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 301706 Details for
Bug 441588
[IPv6-DoD] openswan IKEv2 crashes when interoperating with racoon2
[?]
New
Simple Search
Advanced Search
My Links
Browse
Requests
Reports
Current State
Search
Tabular reports
Graphical reports
Duplicates
Other Reports
User Changes
Plotly Reports
Bug Status
Bug Severity
Non-Defaults
|
Product Dashboard
Help
Page Help!
Bug Writing Guidelines
What's new
Browser Support Policy
5.0.4.rh83 Release notes
FAQ
Guides index
User guide
Web Services
Contact
Legal
This site requires JavaScript to be enabled to function correctly, please enable it.
Logs from eal5 (initiator)
eal5.log (text/plain), 62.36 KB, created by
IBM Bug Proxy
on 2008-04-08 20:35:07 UTC
(
hide
)
Description:
Logs from eal5 (initiator)
Filename:
MIME Type:
Creator:
IBM Bug Proxy
Created:
2008-04-08 20:35:07 UTC
Size:
62.36 KB
patch
obsolete
> >Apr 7 19:46:45 eal5 ipsec__plutorun: Starting Pluto subsystem... >Apr 7 19:46:45 eal5 pluto[15610]: Starting Pluto (Openswan Version 2.6.09; Vendor ID OEZD~MT}mAnl) pid:15610 >Apr 7 19:46:45 eal5 pluto[15610]: Setting NAT-Traversal port-4500 floating to on >Apr 7 19:46:45 eal5 pluto[15610]: port floating activation criteria nat_t=1/port_float=1 >Apr 7 19:46:45 eal5 pluto[15610]: including NAT-Traversal patch (Version 0.6c) >Apr 7 19:46:45 eal5 pluto[15610]: | opening /dev/urandom >Apr 7 19:46:45 eal5 pluto[15610]: using /dev/urandom as source of random entropy >Apr 7 19:46:45 eal5 pluto[15610]: | inserting event EVENT_REINIT_SECRET, timeout in 3600 seconds >Apr 7 19:46:45 eal5 pluto[15610]: | inserting event EVENT_PENDING_PHASE2, timeout in 120 seconds >Apr 7 19:46:45 eal5 pluto[15610]: ike_alg_register_enc(): Activating OAKLEY_TWOFISH_CBC_SSH: Ok (ret=0) >Apr 7 19:46:45 eal5 pluto[15610]: ike_alg_register_enc(): Activating OAKLEY_TWOFISH_CBC: Ok (ret=0) >Apr 7 19:46:45 eal5 pluto[15610]: ike_alg_register_enc(): Activating OAKLEY_SERPENT_CBC: Ok (ret=0) >Apr 7 19:46:45 eal5 pluto[15610]: ike_alg_register_enc(): Activating OAKLEY_AES_CBC: Ok (ret=0) >Apr 7 19:46:45 eal5 pluto[15610]: ike_alg_register_enc(): Activating OAKLEY_BLOWFISH_CBC: Ok (ret=0) >Apr 7 19:46:45 eal5 pluto[15610]: ike_alg_register_hash(): Activating OAKLEY_SHA2_512: Ok (ret=0) >Apr 7 19:46:45 eal5 pluto[15610]: ike_alg_register_hash(): Activating OAKLEY_SHA2_256: Ok (ret=0) >Apr 7 19:46:45 eal5 pluto[15610]: starting up 3 cryptographic helpers >Apr 7 19:46:45 eal5 pluto[15610]: started helper pid=15620 (fd:7) >Apr 7 19:46:45 eal5 pluto[15620]: | opening /dev/urandom >Apr 7 19:46:45 eal5 pluto[15622]: | opening /dev/urandom >Apr 7 19:46:45 eal5 pluto[15610]: started helper pid=15622 (fd:8) >Apr 7 19:46:45 eal5 pluto[15620]: using /dev/urandom as source of random entropy >Apr 7 19:46:45 eal5 pluto[15622]: using /dev/urandom as source of random entropy >Apr 7 19:46:45 eal5 pluto[15623]: | opening /dev/urandom >Apr 7 19:46:45 eal5 pluto[15610]: started helper pid=15623 (fd:9) >Apr 7 19:46:45 eal5 pluto[15620]: ! helper 0 waiting on fd: 8 >Apr 7 19:46:45 eal5 pluto[15622]: ! helper 1 waiting on fd: 9 >Apr 7 19:46:45 eal5 pluto[15623]: using /dev/urandom as source of random entropy >Apr 7 19:46:45 eal5 pluto[15610]: Using Linux 2.6 IPsec interface code on 2.6.18-86.el5 (experimental code) >Apr 7 19:46:45 eal5 pluto[15623]: ! helper 2 waiting on fd: 10 >Apr 7 19:46:45 eal5 pluto[15610]: | process 15610 listening for PF_KEY_V2 on file descriptor 12 >Apr 7 19:46:45 eal5 pluto[15610]: | finish_pfkey_msg: SADB_REGISTER message 1 for AH >Apr 7 19:46:45 eal5 pluto[15610]: | 02 07 00 02 02 00 00 00 01 00 00 00 fa 3c 00 00 >Apr 7 19:46:45 eal5 pluto[15610]: | pfkey_get: SADB_REGISTER message 1 >Apr 7 19:46:45 eal5 pluto[15610]: | AH registered with kernel. >Apr 7 19:46:45 eal5 pluto[15610]: | finish_pfkey_msg: SADB_REGISTER message 2 for ESP >Apr 7 19:46:45 eal5 pluto[15610]: | 02 07 00 03 02 00 00 00 02 00 00 00 fa 3c 00 00 >Apr 7 19:46:46 eal5 pluto[15610]: | pfkey_get: SADB_REGISTER message 2 >Apr 7 19:46:46 eal5 pluto[15610]: | alg_init():memset(0x31eec0, 0, 2016) memset(0x31f6a0, 0, 2048) >Apr 7 19:46:46 eal5 pluto[15610]: | kernel_alg_register_pfkey(): SADB_SATYPE_ESP: sadb_msg_len=19 sadb_supported_len=56 >Apr 7 19:46:46 eal5 pluto[15610]: | kernel_alg_add():satype=3, exttype=14, alg_id=251 >Apr 7 19:46:46 eal5 pluto[15610]: | kernel_alg_register_pfkey(): SADB_SATYPE_ESP: alg[0], exttype=14, satype=3, alg_id=251, alg_ivlen=0, alg_minbits=0, alg_maxbits=0, res=0, ret=1 >Apr 7 19:46:46 eal5 pluto[15610]: | kernel_alg_add():satype=3, exttype=14, alg_id=2 >Apr 7 19:46:46 eal5 pluto[15610]: | kernel_alg_register_pfkey(): SADB_SATYPE_ESP: alg[1], exttype=14, satype=3, alg_id=2, alg_ivlen=0, alg_minbits=128, alg_maxbits=128, res=0, ret=1 >Apr 7 19:46:46 eal5 pluto[15610]: | kernel_alg_add():satype=3, exttype=14, alg_id=3 >Apr 7 19:46:46 eal5 pluto[15610]: | kernel_alg_register_pfkey(): SADB_SATYPE_ESP: alg[2], exttype=14, satype=3, alg_id=3, alg_ivlen=0, alg_minbits=160, alg_maxbits=160, res=0, ret=1 >Apr 7 19:46:46 eal5 pluto[15610]: | kernel_alg_add():satype=3, exttype=14, alg_id=5 >Apr 7 19:46:46 eal5 pluto[15610]: | kernel_alg_register_pfkey(): SADB_SATYPE_ESP: alg[3], exttype=14, satype=3, alg_id=5, alg_ivlen=0, alg_minbits=256, alg_maxbits=256, res=0, ret=1 >Apr 7 19:46:46 eal5 pluto[15610]: | kernel_alg_add():satype=3, exttype=14, alg_id=8 >Apr 7 19:46:46 eal5 pluto[15610]: | kernel_alg_register_pfkey(): SADB_SATYPE_ESP: alg[4], exttype=14, satype=3, alg_id=8, alg_ivlen=0, alg_minbits=160, alg_maxbits=160, res=0, ret=1 >Apr 7 19:46:46 eal5 pluto[15610]: | kernel_alg_add():satype=3, exttype=14, alg_id=9 >Apr 7 19:46:46 eal5 pluto[15610]: | kernel_alg_register_pfkey(): SADB_SATYPE_ESP: alg[5], exttype=14, satype=3, alg_id=9, alg_ivlen=0, alg_minbits=128, alg_maxbits=128, res=0, ret=1 >Apr 7 19:46:46 eal5 pluto[15610]: | kernel_alg_register_pfkey(): SADB_SATYPE_ESP: sadb_msg_len=19 sadb_supported_len=80 >Apr 7 19:46:46 eal5 pluto[15610]: | kernel_alg_add():satype=3, exttype=15, alg_id=11 >Apr 7 19:46:46 eal5 pluto[15610]: | kernel_alg_register_pfkey(): SADB_SATYPE_ESP: alg[6], exttype=15, satype=3, alg_id=11, alg_ivlen=0, alg_minbits=0, alg_maxbits=0, res=0, ret=1 >Apr 7 19:46:46 eal5 pluto[15610]: | kernel_alg_add():satype=3, exttype=15, alg_id=2 >Apr 7 19:46:46 eal5 pluto[15610]: | kernel_alg_register_pfkey(): SADB_SATYPE_ESP: alg[7], exttype=15, satype=3, alg_id=2, alg_ivlen=8, alg_minbits=64, alg_maxbits=64, res=0, ret=1 >Apr 7 19:46:46 eal5 pluto[15610]: | kernel_alg_add():satype=3, exttype=15, alg_id=3 >Apr 7 19:46:46 eal5 pluto[15610]: | kernel_alg_register_pfkey(): SADB_SATYPE_ESP: alg[8], exttype=15, satype=3, alg_id=3, alg_ivlen=8, alg_minbits=192, alg_maxbits=192, res=0, ret=1 >Apr 7 19:46:46 eal5 pluto[15610]: | kernel_alg_add():satype=3, exttype=15, alg_id=6 >Apr 7 19:46:46 eal5 pluto[15610]: | kernel_alg_register_pfkey(): SADB_SATYPE_ESP: alg[9], exttype=15, satype=3, alg_id=6, alg_ivlen=8, alg_minbits=40, alg_maxbits=128, res=0, ret=1 >Apr 7 19:46:46 eal5 pluto[15610]: | kernel_alg_add():satype=3, exttype=15, alg_id=7 >Apr 7 19:46:46 eal5 pluto[15610]: | kernel_alg_register_pfkey(): SADB_SATYPE_ESP: alg[10], exttype=15, satype=3, alg_id=7, alg_ivlen=8, alg_minbits=40, alg_maxbits=448, res=0, ret=1 >Apr 7 19:46:46 eal5 pluto[15610]: | kernel_alg_add():satype=3, exttype=15, alg_id=12 >Apr 7 19:46:46 eal5 pluto[15610]: | kernel_alg_register_pfkey(): SADB_SATYPE_ESP: alg[11], exttype=15, satype=3, alg_id=12, alg_ivlen=8, alg_minbits=128, alg_maxbits=256, res=0, ret=1 >Apr 7 19:46:46 eal5 pluto[15610]: | kernel_alg_add():satype=3, exttype=15, alg_id=252 >Apr 7 19:46:46 eal5 pluto[15610]: | kernel_alg_register_pfkey(): SADB_SATYPE_ESP: alg[12], exttype=15, satype=3, alg_id=252, alg_ivlen=8, alg_minbits=128, alg_maxbits=256, res=0, ret=1 >Apr 7 19:46:46 eal5 pluto[15610]: | kernel_alg_add():satype=3, exttype=15, alg_id=253 >Apr 7 19:46:46 eal5 pluto[15610]: | kernel_alg_register_pfkey(): SADB_SATYPE_ESP: alg[13], exttype=15, satype=3, alg_id=253, alg_ivlen=8, alg_minbits=128, alg_maxbits=256, res=0, ret=1 >Apr 7 19:46:46 eal5 pluto[15610]: | kernel_alg_add():satype=3, exttype=15, alg_id=13 >Apr 7 19:46:46 eal5 pluto[15610]: | kernel_alg_register_pfkey(): SADB_SATYPE_ESP: alg[14], exttype=15, satype=3, alg_id=13, alg_ivlen=8, alg_minbits=128, alg_maxbits=256, res=0, ret=1 >Apr 7 19:46:46 eal5 pluto[15610]: | ESP registered with kernel. >Apr 7 19:46:46 eal5 pluto[15610]: | finish_pfkey_msg: SADB_REGISTER message 3 for IPCOMP >Apr 7 19:46:46 eal5 pluto[15610]: | 02 07 00 09 02 00 00 00 03 00 00 00 fa 3c 00 00 >Apr 7 19:46:46 eal5 pluto[15610]: | pfkey_get: SADB_REGISTER message 3 >Apr 7 19:46:46 eal5 pluto[15610]: | IPCOMP registered with kernel. >Apr 7 19:46:46 eal5 pluto[15610]: Could not change to directory '/etc/ipsec.d/cacerts': / >Apr 7 19:46:46 eal5 pluto[15610]: Could not change to directory '/etc/ipsec.d/aacerts': / >Apr 7 19:46:47 eal5 pluto[15610]: Could not change to directory '/etc/ipsec.d/ocspcerts': / >Apr 7 19:46:47 eal5 pluto[15610]: Could not change to directory '/etc/ipsec.d/crls' >Apr 7 19:46:47 eal5 pluto[15610]: | inserting event EVENT_LOG_DAILY, timeout in 15193 seconds >Apr 7 19:46:47 eal5 pluto[15610]: | next event EVENT_PENDING_PHASE2 in 118 seconds >Apr 7 19:46:47 eal5 pluto[15610]: | >Apr 7 19:46:47 eal5 pluto[15610]: | *received whack message >Apr 7 19:46:47 eal5 pluto[15610]: | Added new connection openswan-racoon2 with policy PSK+ENCRYPT+TUNNEL+PFS+!IKEv1+IKEv2ALLOW+IKEv2Init >Apr 7 19:46:47 eal5 pluto[15610]: | counting wild cards for 9.3.190.198 is 0 >Apr 7 19:46:47 eal5 pluto[15610]: | counting wild cards for 9.47.67.138 is 0 >Apr 7 19:46:47 eal5 pluto[15610]: added connection description "openswan-racoon2" >Apr 7 19:46:47 eal5 pluto[15610]: | 9.3.190.198<9.3.190.198>[S=C]...9.47.67.138<9.47.67.138>[S=C] >Apr 7 19:46:47 eal5 pluto[15610]: | ike_life: 3600s; ipsec_life: 28800s; rekey_margin: 540s; rekey_fuzz: 100%; keyingtries: 3; policy: PSK+ENCRYPT+TUNNEL+PFS+!IKEv1+IKEv2ALLOW+IKEv2Init >Apr 7 19:46:47 eal5 pluto[15610]: | * processed 0 messages from cryptographic helpers >Apr 7 19:46:47 eal5 pluto[15610]: | next event EVENT_PENDING_PHASE2 in 118 seconds >Apr 7 19:46:47 eal5 pluto[15610]: | >Apr 7 19:46:47 eal5 pluto[15610]: | *received whack message >Apr 7 19:46:47 eal5 pluto[15610]: | alg_info_parse_str() ealg_buf=aes aalg_buf=sha1eklen=128 aklen=0 >Apr 7 19:46:47 eal5 pluto[15610]: | enum_search_prefix () calling enum_search(0x3054a0, "OAKLEY_AES") >Apr 7 19:46:47 eal5 pluto[15610]: | enum_search_ppfixi () calling enum_search(0x3054a0, "OAKLEY_AES_CBC") >Apr 7 19:46:47 eal5 pluto[15610]: | parser_alg_info_add() ealg_getbyname("aes")=7 >Apr 7 19:46:47 eal5 pluto[15610]: | enum_search_prefix () calling enum_search(0x3054b0, "OAKLEY_SHA1") >Apr 7 19:46:47 eal5 pluto[15610]: | parser_alg_info_add() aalg_getbyname("sha1")=2 >Apr 7 19:46:47 eal5 pluto[15610]: | enum_search_prefix () calling enum_search(0x3054c0, "OAKLEY_GROUP_MODP2048") >Apr 7 19:46:47 eal5 pluto[15610]: | parser_alg_info_add() modp_getbyname("modp2048")=14 >Apr 7 19:46:47 eal5 pluto[15610]: | __alg_info_ike_add() ealg=7 aalg=2 modp_id=14, cnt=1 >Apr 7 19:46:47 eal5 pluto[15610]: | Added new connection openswan-strongswan-ppc with policy PSK+ENCRYPT+TUNNEL+PFS+!IKEv1+IKEv2ALLOW+IKEv2Init >Apr 7 19:46:47 eal5 pluto[15610]: | from whack: got --esp=aes128-sha1 >Apr 7 19:46:47 eal5 pluto[15610]: | alg_info_parse_str() ealg_buf=aes aalg_buf=sha1eklen=128 aklen=0 >Apr 7 19:46:47 eal5 pluto[15610]: | enum_search_prefix () calling enum_search(0x305430, "ESP_AES") >Apr 7 19:46:47 eal5 pluto[15610]: | parser_alg_info_add() ealg_getbyname("aes")=12 >Apr 7 19:46:47 eal5 pluto[15610]: | enum_search_prefix () calling enum_search(0x302328, "AUTH_ALGORITHM_HMAC_SHA1") >Apr 7 19:46:47 eal5 pluto[15610]: | parser_alg_info_add() aalg_getbyname("sha1")=2 >Apr 7 19:46:47 eal5 pluto[15610]: | __alg_info_esp_add() ealg=12 aalg=2 cnt=1 >Apr 7 19:46:47 eal5 pluto[15610]: | esp string values: AES(12)_128-SHA1(2); flags=-strict >Apr 7 19:46:47 eal5 pluto[15610]: | ike (phase1) algorihtm values: AES_CBC(7)_128-SHA1(2)-MODP2048(14); flags=-strict >Apr 7 19:46:47 eal5 pluto[15610]: | counting wild cards for 9.3.190.198 is 0 >Apr 7 19:46:47 eal5 pluto[15610]: | counting wild cards for 9.3.192.206 is 0 >Apr 7 19:46:47 eal5 pluto[15610]: | alg_info_addref() alg_info->ref_cnt=1 >Apr 7 19:46:48 eal5 pluto[15610]: | alg_info_addref() alg_info->ref_cnt=1 >Apr 7 19:46:48 eal5 pluto[15610]: | alg_info_addref() alg_info->ref_cnt=2 >Apr 7 19:46:48 eal5 pluto[15610]: | alg_info_addref() alg_info->ref_cnt=2 >Apr 7 19:46:48 eal5 pluto[15610]: added connection description "openswan-strongswan-ppc" >Apr 7 19:46:48 eal5 pluto[15610]: | 9.3.190.198<9.3.190.198>[S=C]...9.3.192.206<9.3.192.206>[S=C] >Apr 7 19:46:48 eal5 pluto[15610]: | ike_life: 3600s; ipsec_life: 28800s; rekey_margin: 540s; rekey_fuzz: 100%; keyingtries: 3; policy: PSK+ENCRYPT+TUNNEL+PFS+!IKEv1+IKEv2ALLOW+IKEv2Init >Apr 7 19:46:48 eal5 pluto[15610]: | * processed 0 messages from cryptographic helpers >Apr 7 19:46:48 eal5 pluto[15610]: | next event EVENT_PENDING_PHASE2 in 117 seconds >Apr 7 19:46:48 eal5 pluto[15610]: | >Apr 7 19:46:48 eal5 pluto[15610]: | *received whack message >Apr 7 19:46:48 eal5 pluto[15610]: | Added new connection openswan-strongswan-i386 with policy PSK+ENCRYPT+TUNNEL+PFS+!IKEv1+IKEv2ALLOW+IKEv2Init >Apr 7 19:46:48 eal5 pluto[15610]: | counting wild cards for 9.3.190.198 is 0 >Apr 7 19:46:48 eal5 pluto[15610]: | counting wild cards for 9.47.66.68 is 0 >Apr 7 19:46:48 eal5 pluto[15610]: added connection description "openswan-strongswan-i386" >Apr 7 19:46:48 eal5 pluto[15610]: | 9.3.190.198<9.3.190.198>[S=C]...9.47.66.68<9.47.66.68>[S=C] >Apr 7 19:46:48 eal5 pluto[15610]: | ike_life: 3600s; ipsec_life: 28800s; rekey_margin: 540s; rekey_fuzz: 100%; keyingtries: 3; policy: PSK+ENCRYPT+TUNNEL+PFS+!IKEv1+IKEv2ALLOW+IKEv2Init >Apr 7 19:46:48 eal5 pluto[15610]: | * processed 0 messages from cryptographic helpers >Apr 7 19:46:48 eal5 pluto[15610]: | next event EVENT_PENDING_PHASE2 in 117 seconds >Apr 7 19:46:48 eal5 pluto[15610]: | >Apr 7 19:46:48 eal5 pluto[15610]: | *received whack message >Apr 7 19:46:48 eal5 pluto[15610]: | alg_info_parse_str() ealg_buf=3des aalg_buf=sha1eklen=0 aklen=0 >Apr 7 19:46:48 eal5 pluto[15610]: | enum_search_prefix () calling enum_search(0x3054a0, "OAKLEY_3DES") >Apr 7 19:46:48 eal5 pluto[15610]: | enum_search_ppfixi () calling enum_search(0x3054a0, "OAKLEY_3DES_CBC") >Apr 7 19:46:48 eal5 pluto[15610]: | parser_alg_info_add() ealg_getbyname("3des")=5 >Apr 7 19:46:48 eal5 pluto[15610]: | enum_search_prefix () calling enum_search(0x3054b0, "OAKLEY_SHA1") >Apr 7 19:46:48 eal5 pluto[15610]: | parser_alg_info_add() aalg_getbyname("sha1")=2 >Apr 7 19:46:48 eal5 pluto[15610]: | enum_search_prefix () calling enum_search(0x3054c0, "OAKLEY_GROUP_MODP1024") >Apr 7 19:46:48 eal5 pluto[15610]: | parser_alg_info_add() modp_getbyname("modp1024")=2 >Apr 7 19:46:48 eal5 pluto[15610]: | __alg_info_ike_add() ealg=5 aalg=2 modp_id=2, cnt=1 >Apr 7 19:46:48 eal5 pluto[15610]: | Added new connection i386-ppc with policy PSK+ENCRYPT+TUNNEL+PFS+!IKEv1+IKEv2ALLOW+IKEv2Init >Apr 7 19:46:48 eal5 pluto[15610]: | ike (phase1) algorihtm values: 3DES_CBC(5)_000-SHA1(2)-MODP1024(2); flags=-strict >Apr 7 19:46:48 eal5 pluto[15610]: | counting wild cards for 9.3.190.198 is 0 >Apr 7 19:46:48 eal5 pluto[15610]: | counting wild cards for 9.3.192.210 is 0 >Apr 7 19:46:48 eal5 pluto[15610]: | alg_info_addref() alg_info->ref_cnt=1 >Apr 7 19:46:48 eal5 pluto[15610]: | alg_info_addref() alg_info->ref_cnt=2 >Apr 7 19:46:48 eal5 pluto[15610]: added connection description "i386-ppc" >Apr 7 19:46:48 eal5 pluto[15610]: | 9.3.190.198<9.3.190.198>[S=C]...9.3.192.210<9.3.192.210>[S=C] >Apr 7 19:46:49 eal5 pluto[15610]: | ike_life: 3600s; ipsec_life: 28800s; rekey_margin: 540s; rekey_fuzz: 100%; keyingtries: 3; policy: PSK+ENCRYPT+TUNNEL+PFS+!IKEv1+IKEv2ALLOW+IKEv2Init >Apr 7 19:46:49 eal5 pluto[15610]: | * processed 0 messages from cryptographic helpers >Apr 7 19:46:49 eal5 pluto[15610]: | next event EVENT_PENDING_PHASE2 in 116 seconds >Apr 7 19:46:49 eal5 pluto[15610]: | >Apr 7 19:46:49 eal5 pluto[15610]: | *received whack message >Apr 7 19:46:49 eal5 pluto[15610]: | Added new connection i386-x86_64 with policy PSK+ENCRYPT+TUNNEL+PFS+!IKEv1+IKEv2ALLOW+IKEv2Init >Apr 7 19:46:49 eal5 pluto[15610]: | counting wild cards for 9.3.190.198 is 0 >Apr 7 19:46:49 eal5 pluto[15610]: | counting wild cards for 9.47.66.195 is 0 >Apr 7 19:46:49 eal5 pluto[15610]: added connection description "i386-x86_64" >Apr 7 19:46:49 eal5 pluto[15610]: | 9.3.190.198<9.3.190.198>[S=C]...9.47.66.195<9.47.66.195>[S=C] >Apr 7 19:46:49 eal5 pluto[15610]: | ike_life: 3600s; ipsec_life: 28800s; rekey_margin: 540s; rekey_fuzz: 100%; keyingtries: 3; policy: PSK+ENCRYPT+TUNNEL+PFS+!IKEv1+IKEv2ALLOW+IKEv2Init >Apr 7 19:46:49 eal5 pluto[15610]: | * processed 0 messages from cryptographic helpers >Apr 7 19:46:49 eal5 pluto[15610]: | next event EVENT_PENDING_PHASE2 in 116 seconds >Apr 7 19:46:49 eal5 pluto[15610]: | >Apr 7 19:46:49 eal5 pluto[15610]: | *received whack message >Apr 7 19:46:49 eal5 pluto[15610]: listening for IKE messages >Apr 7 19:46:49 eal5 pluto[15610]: | found lo with address 127.0.0.1 >Apr 7 19:46:49 eal5 pluto[15610]: | found eth0 with address 9.3.190.198 >Apr 7 19:46:49 eal5 pluto[15610]: adding interface eth0/eth0 9.3.190.198:500 >Apr 7 19:46:49 eal5 pluto[15610]: adding interface eth0/eth0 9.3.190.198:4500 >Apr 7 19:46:49 eal5 pluto[15610]: adding interface lo/lo 127.0.0.1:500 >Apr 7 19:46:49 eal5 pluto[15610]: adding interface lo/lo 127.0.0.1:4500 >Apr 7 19:46:49 eal5 pluto[15610]: | found lo with address 0000:0000:0000:0000:0000:0000:0000:0001 >Apr 7 19:46:49 eal5 pluto[15610]: adding interface lo/lo ::1:500 >Apr 7 19:46:49 eal5 pluto[15610]: | connect_to_host_pair: 9.3.190.198:500 9.47.66.195:500 -> hp:none >Apr 7 19:46:49 eal5 pluto[15610]: | find_host_pair: comparing to 9.3.190.198:500 9.47.66.195:500 >Apr 7 19:46:49 eal5 pluto[15610]: | connect_to_host_pair: 9.3.190.198:500 9.3.192.210:500 -> hp:none >Apr 7 19:46:49 eal5 pluto[15610]: | find_host_pair: comparing to 9.3.190.198:500 9.3.192.210:500 >Apr 7 19:46:49 eal5 pluto[15610]: | find_host_pair: comparing to 9.3.190.198:500 9.47.66.195:500 >Apr 7 19:46:49 eal5 pluto[15610]: | connect_to_host_pair: 9.3.190.198:500 9.47.66.68:500 -> hp:none >Apr 7 19:46:49 eal5 pluto[15610]: | find_host_pair: comparing to 9.3.190.198:500 9.47.66.68:500 >Apr 7 19:46:49 eal5 pluto[15610]: | find_host_pair: comparing to 9.3.190.198:500 9.3.192.210:500 >Apr 7 19:46:49 eal5 pluto[15610]: | find_host_pair: comparing to 9.3.190.198:500 9.47.66.195:500 >Apr 7 19:46:49 eal5 pluto[15610]: | connect_to_host_pair: 9.3.190.198:500 9.3.192.206:500 -> hp:none >Apr 7 19:46:49 eal5 pluto[15610]: | find_host_pair: comparing to 9.3.190.198:500 9.3.192.206:500 >Apr 7 19:46:49 eal5 pluto[15610]: | find_host_pair: comparing to 9.3.190.198:500 9.47.66.68:500 >Apr 7 19:46:49 eal5 pluto[15610]: | find_host_pair: comparing to 9.3.190.198:500 9.3.192.210:500 >Apr 7 19:46:49 eal5 pluto[15610]: | find_host_pair: comparing to 9.3.190.198:500 9.47.66.195:500 >Apr 7 19:46:50 eal5 pluto[15610]: | connect_to_host_pair: 9.3.190.198:500 9.47.67.138:500 -> hp:none >Apr 7 19:46:50 eal5 pluto[15610]: loading secrets from "/etc/ipsec.secrets" >Apr 7 19:46:50 eal5 pluto[15610]: | id type added to secret(0x9c86398) 1: 9.3.190.198 >Apr 7 19:46:50 eal5 pluto[15610]: | id type added to secret(0x9c86398) 1: 9.3.192.206 >Apr 7 19:46:50 eal5 pluto[15610]: | id type added to secret(0x9c86398) 1: 9.3.192.210 >Apr 7 19:46:50 eal5 pluto[15610]: | id type added to secret(0x9c86398) 1: 9.47.67.138 >Apr 7 19:46:50 eal5 pluto[15610]: | Processing PSK at line 1: passed >Apr 7 19:46:50 eal5 pluto[15610]: | * processed 0 messages from cryptographic helpers >Apr 7 19:46:50 eal5 pluto[15610]: | next event EVENT_PENDING_PHASE2 in 115 seconds >Apr 7 19:47:41 eal5 pluto[15610]: | >Apr 7 19:47:41 eal5 pluto[15610]: | *received whack message >Apr 7 19:47:41 eal5 pluto[15610]: | processing connection openswan-racoon2 >Apr 7 19:47:41 eal5 pluto[15610]: | empty esp_info, returning defaults >Apr 7 19:47:41 eal5 pluto[15610]: | creating state object #1 at 0x9c864e0 >Apr 7 19:47:41 eal5 pluto[15610]: | processing connection openswan-racoon2 >Apr 7 19:47:41 eal5 pluto[15610]: | ICOOKIE: 54 b8 6c cf f5 63 de f5 >Apr 7 19:47:41 eal5 pluto[15610]: | RCOOKIE: 00 00 00 00 00 00 00 00 >Apr 7 19:47:41 eal5 pluto[15610]: | state hash entry 20 >Apr 7 19:47:41 eal5 pluto[15610]: | inserting state object #1 on chain 20 >Apr 7 19:47:41 eal5 pluto[15610]: | inserting event EVENT_SO_DISCARD, timeout in 0 seconds for #1 >Apr 7 19:47:41 eal5 pluto[15610]: | processing connection openswan-racoon2 >Apr 7 19:47:41 eal5 pluto[15610]: | Queuing pending Quick Mode with 9.47.67.138 "openswan-racoon2" >Apr 7 19:47:41 eal5 pluto[15610]: "openswan-racoon2" #1: initiating v2 parent SA >Apr 7 19:47:41 eal5 pluto[15610]: | no IKE algorithms for this connection >Apr 7 19:47:41 eal5 pluto[15610]: | 1: w->pcw_dead: 0 w->pcw_work: 0 cnt: 3 >Apr 7 19:47:41 eal5 pluto[15610]: | asking helper 1 to do build_kenonce op on seq: 1 (len=2668, pcw_work=1) >Apr 7 19:47:41 eal5 pluto[15610]: | crypto helper write of request: cnt=2668<wlen=2668. >Apr 7 19:47:41 eal5 pluto[15622]: ! helper 1 read 2664+4/2668 bytesfd: 9 >Apr 7 19:47:41 eal5 pluto[15610]: | inserting event EVENT_CRYPTO_FAILED, timeout in 300 seconds for #1 >Apr 7 19:47:41 eal5 pluto[15622]: ! helper 1 doing build_kenonce op id: 1 >Apr 7 19:47:41 eal5 pluto[15610]: | * processed 0 messages from cryptographic helpers >Apr 7 19:47:41 eal5 pluto[15622]: ! Local DH secret: >Apr 7 19:47:41 eal5 pluto[15610]: | next event EVENT_PENDING_PHASE2 in 64 seconds >Apr 7 19:47:41 eal5 pluto[15622]: ! 0d a6 af d2 11 eb 60 f7 03 5b 0d d0 36 d6 8d 9f >Apr 7 19:47:41 eal5 pluto[15622]: ! 56 23 64 0c f1 54 ea b5 45 fb fa 59 e3 26 58 ba >Apr 7 19:47:41 eal5 pluto[15622]: ! Public DH value sent: >Apr 7 19:47:41 eal5 pluto[15622]: ! 6d 40 1e 4c 90 f2 06 9e 19 22 d9 dc c6 99 8c e4 >Apr 7 19:47:41 eal5 pluto[15622]: ! 60 fb 75 7e 82 0c be 52 b4 72 9c 2d 93 51 76 30 >Apr 7 19:47:41 eal5 pluto[15622]: ! 03 68 93 a9 ab 65 81 d8 38 68 7a 3e 4b 18 fc 07 >Apr 7 19:47:41 eal5 pluto[15622]: ! 07 85 f0 62 e8 3f 00 3b d4 4f 02 8b 7e 3f 29 b9 >Apr 7 19:47:41 eal5 pluto[15622]: ! 0b 56 a1 3a 76 2c 0e 99 a6 73 74 97 e3 d4 9c c8 >Apr 7 19:47:41 eal5 pluto[15622]: ! 5d 0e 14 47 5c 04 d2 8d f6 de 73 7d 41 25 6a 66 >Apr 7 19:47:41 eal5 pluto[15622]: ! 6d 90 00 2b 89 72 ef 98 ed 66 35 5a 55 a9 91 8d >Apr 7 19:47:41 eal5 pluto[15622]: ! 24 50 51 b0 16 56 66 40 31 26 eb 08 48 f5 d6 1e >Apr 7 19:47:41 eal5 pluto[15622]: ! c4 f5 6a c0 fe 4d e2 5a 8f b0 cf d1 10 73 c2 cf >Apr 7 19:47:41 eal5 pluto[15622]: ! 56 7d da d2 3f fc 51 0d ce a9 22 9a ec 92 ec 3f >Apr 7 19:47:41 eal5 pluto[15622]: ! 77 b9 a3 26 5f b3 3d 6c 57 f7 be 77 d9 76 b2 a2 >Apr 7 19:47:41 eal5 pluto[15622]: ! 89 d8 e0 17 0f d5 33 f0 6a fb c7 d2 50 6c a2 5c >Apr 7 19:47:41 eal5 pluto[15622]: ! Generated nonce: >Apr 7 19:47:41 eal5 pluto[15622]: ! 3e 4d e2 e6 bd 82 a5 a6 05 da 10 74 5d 47 08 92 >Apr 7 19:47:41 eal5 pluto[15610]: | >Apr 7 19:47:41 eal5 pluto[15610]: | helper 1 has finished work (cnt now 1) >Apr 7 19:47:41 eal5 pluto[15610]: | helper 1 replies to id: q#1 >Apr 7 19:47:41 eal5 pluto[15610]: | calling callback function 0x2549c0 >Apr 7 19:47:42 eal5 pluto[15610]: | ikev2 parent outI1: calculated ke+nonce, sending I1 >Apr 7 19:47:42 eal5 pluto[15610]: | processing connection openswan-racoon2 >Apr 7 19:47:42 eal5 pluto[15610]: | **emit ISAKMP Message: >Apr 7 19:47:42 eal5 pluto[15610]: | initiator cookie: >Apr 7 19:47:42 eal5 pluto[15610]: | 54 b8 6c cf f5 63 de f5 >Apr 7 19:47:42 eal5 pluto[15610]: | responder cookie: >Apr 7 19:47:42 eal5 pluto[15610]: | 00 00 00 00 00 00 00 00 >Apr 7 19:47:42 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_v2SA >Apr 7 19:47:42 eal5 pluto[15610]: | ISAKMP version: IKEv2 version 2.0 (rfc4306) >Apr 7 19:47:42 eal5 pluto[15610]: | exchange type: ISAKMP_v2_SA_INIT >Apr 7 19:47:42 eal5 pluto[15610]: | flags: ISAKMP_FLAG_INIT >Apr 7 19:47:42 eal5 pluto[15610]: | message ID: 00 00 00 00 >Apr 7 19:47:42 eal5 pluto[15610]: | ***emit IKEv2 Security Association Payload: >Apr 7 19:47:42 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_v2KE >Apr 7 19:47:42 eal5 pluto[15610]: | critical bit: Payload-Critical >Apr 7 19:47:42 eal5 pluto[15610]: | ****emit IKEv2 Proposal Substructure Payload: >Apr 7 19:47:42 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_P >Apr 7 19:47:42 eal5 pluto[15610]: | prop #: 1 >Apr 7 19:47:42 eal5 pluto[15610]: | proto ID: 1 >Apr 7 19:47:42 eal5 pluto[15610]: | spi size: 0 >Apr 7 19:47:42 eal5 pluto[15610]: | # transforms: 4 >Apr 7 19:47:42 eal5 pluto[15610]: | *****emit IKEv2 Transform Substructure Payload: >Apr 7 19:47:42 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_T >Apr 7 19:47:42 eal5 pluto[15610]: | transform type: 1 >Apr 7 19:47:42 eal5 pluto[15610]: | transform ID: 12 >Apr 7 19:47:42 eal5 pluto[15610]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Apr 7 19:47:42 eal5 pluto[15610]: | *****emit IKEv2 Transform Substructure Payload: >Apr 7 19:47:42 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_T >Apr 7 19:47:42 eal5 pluto[15610]: | transform type: 3 >Apr 7 19:47:42 eal5 pluto[15610]: | transform ID: 2 >Apr 7 19:47:42 eal5 pluto[15610]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Apr 7 19:47:42 eal5 pluto[15610]: | *****emit IKEv2 Transform Substructure Payload: >Apr 7 19:47:42 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_T >Apr 7 19:47:42 eal5 pluto[15610]: | transform type: 2 >Apr 7 19:47:42 eal5 pluto[15610]: | transform ID: 2 >Apr 7 19:47:42 eal5 pluto[15610]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Apr 7 19:47:42 eal5 pluto[15610]: | *****emit IKEv2 Transform Substructure Payload: >Apr 7 19:47:42 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_NONE >Apr 7 19:47:42 eal5 pluto[15610]: | transform type: 4 >Apr 7 19:47:43 eal5 pluto[15610]: | transform ID: 5 >Apr 7 19:47:43 eal5 pluto[15610]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Apr 7 19:47:43 eal5 pluto[15610]: | emitting length of IKEv2 Proposal Substructure Payload: 40 >Apr 7 19:47:43 eal5 pluto[15610]: | ****emit IKEv2 Proposal Substructure Payload: >Apr 7 19:47:43 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_P >Apr 7 19:47:43 eal5 pluto[15610]: | prop #: 2 >Apr 7 19:47:43 eal5 pluto[15610]: | proto ID: 1 >Apr 7 19:47:43 eal5 pluto[15610]: | spi size: 0 >Apr 7 19:47:43 eal5 pluto[15610]: | # transforms: 4 >Apr 7 19:47:43 eal5 pluto[15610]: | *****emit IKEv2 Transform Substructure Payload: >Apr 7 19:47:43 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_T >Apr 7 19:47:43 eal5 pluto[15610]: | transform type: 1 >Apr 7 19:47:43 eal5 pluto[15610]: | transform ID: 12 >Apr 7 19:47:43 eal5 pluto[15610]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Apr 7 19:47:43 eal5 pluto[15610]: | *****emit IKEv2 Transform Substructure Payload: >Apr 7 19:47:43 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_T >Apr 7 19:47:43 eal5 pluto[15610]: | transform type: 3 >Apr 7 19:47:43 eal5 pluto[15610]: | transform ID: 2 >Apr 7 19:47:43 eal5 pluto[15610]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Apr 7 19:47:43 eal5 pluto[15610]: | *****emit IKEv2 Transform Substructure Payload: >Apr 7 19:47:43 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_T >Apr 7 19:47:43 eal5 pluto[15610]: | transform type: 2 >Apr 7 19:47:43 eal5 pluto[15610]: | transform ID: 1 >Apr 7 19:47:43 eal5 pluto[15610]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Apr 7 19:47:43 eal5 pluto[15610]: | *****emit IKEv2 Transform Substructure Payload: >Apr 7 19:47:43 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_NONE >Apr 7 19:47:43 eal5 pluto[15610]: | transform type: 4 >Apr 7 19:47:43 eal5 pluto[15610]: | transform ID: 5 >Apr 7 19:47:43 eal5 pluto[15610]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Apr 7 19:47:43 eal5 pluto[15610]: | emitting length of IKEv2 Proposal Substructure Payload: 40 >Apr 7 19:47:43 eal5 pluto[15610]: | ****emit IKEv2 Proposal Substructure Payload: >Apr 7 19:47:43 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_P >Apr 7 19:47:43 eal5 pluto[15610]: | prop #: 3 >Apr 7 19:47:43 eal5 pluto[15610]: | proto ID: 1 >Apr 7 19:47:43 eal5 pluto[15610]: | spi size: 0 >Apr 7 19:47:43 eal5 pluto[15610]: | # transforms: 4 >Apr 7 19:47:43 eal5 pluto[15610]: | *****emit IKEv2 Transform Substructure Payload: >Apr 7 19:47:43 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_T >Apr 7 19:47:44 eal5 pluto[15610]: | transform type: 1 >Apr 7 19:47:44 eal5 pluto[15610]: | transform ID: 3 >Apr 7 19:47:44 eal5 pluto[15610]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Apr 7 19:47:44 eal5 pluto[15610]: | *****emit IKEv2 Transform Substructure Payload: >Apr 7 19:47:44 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_T >Apr 7 19:47:44 eal5 pluto[15610]: | transform type: 3 >Apr 7 19:47:44 eal5 pluto[15610]: | transform ID: 2 >Apr 7 19:47:44 eal5 pluto[15610]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Apr 7 19:47:44 eal5 pluto[15610]: | *****emit IKEv2 Transform Substructure Payload: >Apr 7 19:47:44 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_T >Apr 7 19:47:44 eal5 pluto[15610]: | transform type: 2 >Apr 7 19:47:44 eal5 pluto[15610]: | transform ID: 2 >Apr 7 19:47:44 eal5 pluto[15610]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Apr 7 19:47:44 eal5 pluto[15610]: | *****emit IKEv2 Transform Substructure Payload: >Apr 7 19:47:44 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_NONE >Apr 7 19:47:44 eal5 pluto[15610]: | transform type: 4 >Apr 7 19:47:44 eal5 pluto[15610]: | transform ID: 5 >Apr 7 19:47:44 eal5 pluto[15610]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Apr 7 19:47:44 eal5 pluto[15610]: | emitting length of IKEv2 Proposal Substructure Payload: 40 >Apr 7 19:47:44 eal5 pluto[15610]: | ****emit IKEv2 Proposal Substructure Payload: >Apr 7 19:47:44 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_P >Apr 7 19:47:44 eal5 pluto[15610]: | prop #: 4 >Apr 7 19:47:44 eal5 pluto[15610]: | proto ID: 1 >Apr 7 19:47:44 eal5 pluto[15610]: | spi size: 0 >Apr 7 19:47:44 eal5 pluto[15610]: | # transforms: 4 >Apr 7 19:47:44 eal5 pluto[15610]: | *****emit IKEv2 Transform Substructure Payload: >Apr 7 19:47:44 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_T >Apr 7 19:47:44 eal5 pluto[15610]: | transform type: 1 >Apr 7 19:47:44 eal5 pluto[15610]: | transform ID: 3 >Apr 7 19:47:44 eal5 pluto[15610]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Apr 7 19:47:44 eal5 pluto[15610]: | *****emit IKEv2 Transform Substructure Payload: >Apr 7 19:47:44 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_T >Apr 7 19:47:44 eal5 pluto[15610]: | transform type: 3 >Apr 7 19:47:44 eal5 pluto[15610]: | transform ID: 2 >Apr 7 19:47:44 eal5 pluto[15610]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Apr 7 19:47:44 eal5 pluto[15610]: | *****emit IKEv2 Transform Substructure Payload: >Apr 7 19:47:44 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_T >Apr 7 19:47:44 eal5 pluto[15610]: | transform type: 2 >Apr 7 19:47:45 eal5 pluto[15610]: | transform ID: 1 >Apr 7 19:47:45 eal5 pluto[15610]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Apr 7 19:47:45 eal5 pluto[15610]: | *****emit IKEv2 Transform Substructure Payload: >Apr 7 19:47:45 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_NONE >Apr 7 19:47:45 eal5 pluto[15610]: | transform type: 4 >Apr 7 19:47:45 eal5 pluto[15610]: | transform ID: 5 >Apr 7 19:47:45 eal5 pluto[15610]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Apr 7 19:47:45 eal5 pluto[15610]: | emitting length of IKEv2 Proposal Substructure Payload: 40 >Apr 7 19:47:45 eal5 pluto[15610]: | ****emit IKEv2 Proposal Substructure Payload: >Apr 7 19:47:45 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_P >Apr 7 19:47:45 eal5 pluto[15610]: | prop #: 5 >Apr 7 19:47:45 eal5 pluto[15610]: | proto ID: 1 >Apr 7 19:47:45 eal5 pluto[15610]: | spi size: 0 >Apr 7 19:47:45 eal5 pluto[15610]: | # transforms: 4 >Apr 7 19:47:45 eal5 pluto[15610]: | *****emit IKEv2 Transform Substructure Payload: >Apr 7 19:47:45 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_T >Apr 7 19:47:45 eal5 pluto[15610]: | transform type: 1 >Apr 7 19:47:45 eal5 pluto[15610]: | transform ID: 3 >Apr 7 19:47:45 eal5 pluto[15610]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Apr 7 19:47:45 eal5 pluto[15610]: | *****emit IKEv2 Transform Substructure Payload: >Apr 7 19:47:45 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_T >Apr 7 19:47:45 eal5 pluto[15610]: | transform type: 3 >Apr 7 19:47:45 eal5 pluto[15610]: | transform ID: 2 >Apr 7 19:47:45 eal5 pluto[15610]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Apr 7 19:47:45 eal5 pluto[15610]: | *****emit IKEv2 Transform Substructure Payload: >Apr 7 19:47:45 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_T >Apr 7 19:47:45 eal5 pluto[15610]: | transform type: 2 >Apr 7 19:47:45 eal5 pluto[15610]: | transform ID: 2 >Apr 7 19:47:45 eal5 pluto[15610]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Apr 7 19:47:45 eal5 pluto[15610]: | *****emit IKEv2 Transform Substructure Payload: >Apr 7 19:47:45 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_NONE >Apr 7 19:47:45 eal5 pluto[15610]: | transform type: 4 >Apr 7 19:47:45 eal5 pluto[15610]: | transform ID: 2 >Apr 7 19:47:45 eal5 pluto[15610]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Apr 7 19:47:45 eal5 pluto[15610]: | emitting length of IKEv2 Proposal Substructure Payload: 40 >Apr 7 19:47:45 eal5 pluto[15610]: | ****emit IKEv2 Proposal Substructure Payload: >Apr 7 19:47:45 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_P >Apr 7 19:47:45 eal5 pluto[15610]: | prop #: 6 >Apr 7 19:47:45 eal5 pluto[15610]: | proto ID: 1 >Apr 7 19:47:45 eal5 pluto[15610]: | spi size: 0 >Apr 7 19:47:46 eal5 pluto[15610]: | # transforms: 4 >Apr 7 19:47:46 eal5 pluto[15610]: | *****emit IKEv2 Transform Substructure Payload: >Apr 7 19:47:46 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_T >Apr 7 19:47:46 eal5 pluto[15610]: | transform type: 1 >Apr 7 19:47:46 eal5 pluto[15610]: | transform ID: 3 >Apr 7 19:47:46 eal5 pluto[15610]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Apr 7 19:47:46 eal5 pluto[15610]: | *****emit IKEv2 Transform Substructure Payload: >Apr 7 19:47:46 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_T >Apr 7 19:47:46 eal5 pluto[15610]: | transform type: 3 >Apr 7 19:47:46 eal5 pluto[15610]: | transform ID: 2 >Apr 7 19:47:46 eal5 pluto[15610]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Apr 7 19:47:46 eal5 pluto[15610]: | *****emit IKEv2 Transform Substructure Payload: >Apr 7 19:47:46 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_T >Apr 7 19:47:46 eal5 pluto[15610]: | transform type: 2 >Apr 7 19:47:46 eal5 pluto[15610]: | transform ID: 1 >Apr 7 19:47:46 eal5 pluto[15610]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Apr 7 19:47:46 eal5 pluto[15610]: | *****emit IKEv2 Transform Substructure Payload: >Apr 7 19:47:46 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_NONE >Apr 7 19:47:46 eal5 pluto[15610]: | transform type: 4 >Apr 7 19:47:46 eal5 pluto[15610]: | transform ID: 2 >Apr 7 19:47:46 eal5 pluto[15610]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Apr 7 19:47:46 eal5 pluto[15610]: | emitting length of IKEv2 Proposal Substructure Payload: 40 >Apr 7 19:47:46 eal5 pluto[15610]: | ****emit IKEv2 Proposal Substructure Payload: >Apr 7 19:47:46 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_P >Apr 7 19:47:46 eal5 pluto[15610]: | prop #: 7 >Apr 7 19:47:46 eal5 pluto[15610]: | proto ID: 1 >Apr 7 19:47:46 eal5 pluto[15610]: | spi size: 0 >Apr 7 19:47:46 eal5 pluto[15610]: | # transforms: 4 >Apr 7 19:47:46 eal5 pluto[15610]: | *****emit IKEv2 Transform Substructure Payload: >Apr 7 19:47:46 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_T >Apr 7 19:47:46 eal5 pluto[15610]: | transform type: 1 >Apr 7 19:47:46 eal5 pluto[15610]: | transform ID: 12 >Apr 7 19:47:46 eal5 pluto[15610]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Apr 7 19:47:46 eal5 pluto[15610]: | *****emit IKEv2 Transform Substructure Payload: >Apr 7 19:47:46 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_T >Apr 7 19:47:46 eal5 pluto[15610]: | transform type: 3 >Apr 7 19:47:46 eal5 pluto[15610]: | transform ID: 2 >Apr 7 19:47:46 eal5 pluto[15610]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Apr 7 19:47:46 eal5 pluto[15610]: | *****emit IKEv2 Transform Substructure Payload: >Apr 7 19:47:47 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_T >Apr 7 19:47:47 eal5 pluto[15610]: | transform type: 2 >Apr 7 19:47:47 eal5 pluto[15610]: | transform ID: 2 >Apr 7 19:47:47 eal5 pluto[15610]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Apr 7 19:47:47 eal5 pluto[15610]: | *****emit IKEv2 Transform Substructure Payload: >Apr 7 19:47:47 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_NONE >Apr 7 19:47:47 eal5 pluto[15610]: | transform type: 4 >Apr 7 19:47:47 eal5 pluto[15610]: | transform ID: 14 >Apr 7 19:47:47 eal5 pluto[15610]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Apr 7 19:47:47 eal5 pluto[15610]: | emitting length of IKEv2 Proposal Substructure Payload: 40 >Apr 7 19:47:47 eal5 pluto[15610]: | ****emit IKEv2 Proposal Substructure Payload: >Apr 7 19:47:47 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_P >Apr 7 19:47:47 eal5 pluto[15610]: | prop #: 8 >Apr 7 19:47:47 eal5 pluto[15610]: | proto ID: 1 >Apr 7 19:47:47 eal5 pluto[15610]: | spi size: 0 >Apr 7 19:47:47 eal5 pluto[15610]: | # transforms: 4 >Apr 7 19:47:47 eal5 pluto[15610]: | *****emit IKEv2 Transform Substructure Payload: >Apr 7 19:47:47 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_T >Apr 7 19:47:47 eal5 pluto[15610]: | transform type: 1 >Apr 7 19:47:47 eal5 pluto[15610]: | transform ID: 12 >Apr 7 19:47:47 eal5 pluto[15610]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Apr 7 19:47:47 eal5 pluto[15610]: | *****emit IKEv2 Transform Substructure Payload: >Apr 7 19:47:47 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_T >Apr 7 19:47:47 eal5 pluto[15610]: | transform type: 3 >Apr 7 19:47:47 eal5 pluto[15610]: | transform ID: 2 >Apr 7 19:47:47 eal5 pluto[15610]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Apr 7 19:47:47 eal5 pluto[15610]: | *****emit IKEv2 Transform Substructure Payload: >Apr 7 19:47:47 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_T >Apr 7 19:47:47 eal5 pluto[15610]: | transform type: 2 >Apr 7 19:47:47 eal5 pluto[15610]: | transform ID: 1 >Apr 7 19:47:47 eal5 pluto[15610]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Apr 7 19:47:47 eal5 pluto[15610]: | *****emit IKEv2 Transform Substructure Payload: >Apr 7 19:47:47 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_NONE >Apr 7 19:47:47 eal5 pluto[15610]: | transform type: 4 >Apr 7 19:47:47 eal5 pluto[15610]: | transform ID: 14 >Apr 7 19:47:47 eal5 pluto[15610]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Apr 7 19:47:47 eal5 pluto[15610]: | emitting length of IKEv2 Proposal Substructure Payload: 40 >Apr 7 19:47:47 eal5 pluto[15610]: | ****emit IKEv2 Proposal Substructure Payload: >Apr 7 19:47:47 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_P >Apr 7 19:47:47 eal5 pluto[15610]: | prop #: 9 >Apr 7 19:47:48 eal5 pluto[15610]: | proto ID: 1 >Apr 7 19:47:48 eal5 pluto[15610]: | spi size: 0 >Apr 7 19:47:48 eal5 pluto[15610]: | # transforms: 4 >Apr 7 19:47:48 eal5 pluto[15610]: | *****emit IKEv2 Transform Substructure Payload: >Apr 7 19:47:48 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_T >Apr 7 19:47:48 eal5 pluto[15610]: | transform type: 1 >Apr 7 19:47:48 eal5 pluto[15610]: | transform ID: 3 >Apr 7 19:47:48 eal5 pluto[15610]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Apr 7 19:47:48 eal5 pluto[15610]: | *****emit IKEv2 Transform Substructure Payload: >Apr 7 19:47:48 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_T >Apr 7 19:47:48 eal5 pluto[15610]: | transform type: 3 >Apr 7 19:47:48 eal5 pluto[15610]: | transform ID: 2 >Apr 7 19:47:48 eal5 pluto[15610]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Apr 7 19:47:48 eal5 pluto[15610]: | *****emit IKEv2 Transform Substructure Payload: >Apr 7 19:47:48 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_T >Apr 7 19:47:48 eal5 pluto[15610]: | transform type: 2 >Apr 7 19:47:48 eal5 pluto[15610]: | transform ID: 2 >Apr 7 19:47:48 eal5 pluto[15610]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Apr 7 19:47:48 eal5 pluto[15610]: | *****emit IKEv2 Transform Substructure Payload: >Apr 7 19:47:48 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_NONE >Apr 7 19:47:48 eal5 pluto[15610]: | transform type: 4 >Apr 7 19:47:48 eal5 pluto[15610]: | transform ID: 14 >Apr 7 19:47:48 eal5 pluto[15610]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Apr 7 19:47:48 eal5 pluto[15610]: | emitting length of IKEv2 Proposal Substructure Payload: 40 >Apr 7 19:47:48 eal5 pluto[15610]: | ****emit IKEv2 Proposal Substructure Payload: >Apr 7 19:47:48 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_NONE >Apr 7 19:47:48 eal5 pluto[15610]: | prop #: 10 >Apr 7 19:47:48 eal5 pluto[15610]: | proto ID: 1 >Apr 7 19:47:48 eal5 pluto[15610]: | spi size: 0 >Apr 7 19:47:48 eal5 pluto[15610]: | # transforms: 4 >Apr 7 19:47:48 eal5 pluto[15610]: | *****emit IKEv2 Transform Substructure Payload: >Apr 7 19:47:48 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_T >Apr 7 19:47:48 eal5 pluto[15610]: | transform type: 1 >Apr 7 19:47:48 eal5 pluto[15610]: | transform ID: 3 >Apr 7 19:47:48 eal5 pluto[15610]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Apr 7 19:47:48 eal5 pluto[15610]: | *****emit IKEv2 Transform Substructure Payload: >Apr 7 19:47:48 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_T >Apr 7 19:47:48 eal5 pluto[15610]: | transform type: 3 >Apr 7 19:47:48 eal5 pluto[15610]: | transform ID: 2 >Apr 7 19:47:49 eal5 pluto[15610]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Apr 7 19:47:49 eal5 pluto[15610]: | *****emit IKEv2 Transform Substructure Payload: >Apr 7 19:47:49 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_T >Apr 7 19:47:49 eal5 pluto[15610]: | transform type: 2 >Apr 7 19:47:49 eal5 pluto[15610]: | transform ID: 1 >Apr 7 19:47:49 eal5 pluto[15610]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Apr 7 19:47:49 eal5 pluto[15610]: | *****emit IKEv2 Transform Substructure Payload: >Apr 7 19:47:49 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_NONE >Apr 7 19:47:49 eal5 pluto[15610]: | transform type: 4 >Apr 7 19:47:49 eal5 pluto[15610]: | transform ID: 14 >Apr 7 19:47:49 eal5 pluto[15610]: | emitting length of IKEv2 Transform Substructure Payload: 8 >Apr 7 19:47:49 eal5 pluto[15610]: | emitting length of IKEv2 Proposal Substructure Payload: 40 >Apr 7 19:47:49 eal5 pluto[15610]: | emitting length of IKEv2 Security Association Payload: 404 >Apr 7 19:47:49 eal5 pluto[15610]: | ***emit IKEv2 Key Exchange Payload: >Apr 7 19:47:49 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_v2Ni >Apr 7 19:47:49 eal5 pluto[15610]: | transform type: 5 >Apr 7 19:47:49 eal5 pluto[15610]: | emitting 192 raw bytes of ikev2 g^x into IKEv2 Key Exchange Payload >Apr 7 19:47:49 eal5 pluto[15610]: | ikev2 g^x 6d 40 1e 4c 90 f2 06 9e 19 22 d9 dc c6 99 8c e4 >Apr 7 19:47:49 eal5 pluto[15610]: | ikev2 g^x 60 fb 75 7e 82 0c be 52 b4 72 9c 2d 93 51 76 30 >Apr 7 19:47:49 eal5 pluto[15610]: | ikev2 g^x 03 68 93 a9 ab 65 81 d8 38 68 7a 3e 4b 18 fc 07 >Apr 7 19:47:49 eal5 pluto[15610]: | ikev2 g^x 07 85 f0 62 e8 3f 00 3b d4 4f 02 8b 7e 3f 29 b9 >Apr 7 19:47:49 eal5 pluto[15610]: | ikev2 g^x 0b 56 a1 3a 76 2c 0e 99 a6 73 74 97 e3 d4 9c c8 >Apr 7 19:47:49 eal5 pluto[15610]: | ikev2 g^x 5d 0e 14 47 5c 04 d2 8d f6 de 73 7d 41 25 6a 66 >Apr 7 19:47:49 eal5 pluto[15610]: | ikev2 g^x 6d 90 00 2b 89 72 ef 98 ed 66 35 5a 55 a9 91 8d >Apr 7 19:47:49 eal5 pluto[15610]: | ikev2 g^x 24 50 51 b0 16 56 66 40 31 26 eb 08 48 f5 d6 1e >Apr 7 19:47:49 eal5 pluto[15610]: | ikev2 g^x c4 f5 6a c0 fe 4d e2 5a 8f b0 cf d1 10 73 c2 cf >Apr 7 19:47:49 eal5 pluto[15610]: | ikev2 g^x 56 7d da d2 3f fc 51 0d ce a9 22 9a ec 92 ec 3f >Apr 7 19:47:49 eal5 pluto[15610]: | ikev2 g^x 77 b9 a3 26 5f b3 3d 6c 57 f7 be 77 d9 76 b2 a2 >Apr 7 19:47:49 eal5 pluto[15610]: | ikev2 g^x 89 d8 e0 17 0f d5 33 f0 6a fb c7 d2 50 6c a2 5c >Apr 7 19:47:49 eal5 pluto[15610]: | emitting length of IKEv2 Key Exchange Payload: 200 >Apr 7 19:47:49 eal5 pluto[15610]: | ***emit IKEv2 Nonce Payload: >Apr 7 19:47:49 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_v2V >Apr 7 19:47:49 eal5 pluto[15610]: | critical bit: Payload-Critical >Apr 7 19:47:49 eal5 pluto[15610]: | emitting 16 raw bytes of IKEv2 nonce into IKEv2 Nonce Payload >Apr 7 19:47:49 eal5 pluto[15610]: | IKEv2 nonce 3e 4d e2 e6 bd 82 a5 a6 05 da 10 74 5d 47 08 92 >Apr 7 19:47:49 eal5 pluto[15610]: | emitting length of IKEv2 Nonce Payload: 20 >Apr 7 19:47:49 eal5 pluto[15610]: | ***emit ISAKMP Vendor ID Payload: >Apr 7 19:47:49 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_NONE >Apr 7 19:47:49 eal5 pluto[15610]: | emitting 12 raw bytes of Vendor ID into ISAKMP Vendor ID Payload >Apr 7 19:47:50 eal5 pluto[15610]: | Vendor ID 4f 45 5a 44 7e 4d 54 7d 6d 41 6e 6c >Apr 7 19:47:50 eal5 pluto[15610]: | emitting length of ISAKMP Vendor ID Payload: 16 >Apr 7 19:47:50 eal5 pluto[15610]: | emitting length of ISAKMP Message: 668 >Apr 7 19:47:50 eal5 pluto[15610]: | sending 668 bytes for ikev2_parent_outI1_common through eth0:500 to 9.47.67.138:500 (using #1) >Apr 7 19:47:50 eal5 pluto[15610]: | 54 b8 6c cf f5 63 de f5 00 00 00 00 00 00 00 00 >Apr 7 19:47:50 eal5 pluto[15610]: | 21 20 22 08 00 00 00 00 00 00 02 9c 22 80 01 94 >Apr 7 19:47:50 eal5 pluto[15610]: | 02 00 00 28 01 01 00 04 03 00 00 08 01 00 00 0c >Apr 7 19:47:50 eal5 pluto[15610]: | 03 00 00 08 03 00 00 02 03 00 00 08 02 00 00 02 >Apr 7 19:47:50 eal5 pluto[15610]: | 00 00 00 08 04 00 00 05 02 00 00 28 02 01 00 04 >Apr 7 19:47:50 eal5 pluto[15610]: | 03 00 00 08 01 00 00 0c 03 00 00 08 03 00 00 02 >Apr 7 19:47:50 eal5 pluto[15610]: | 03 00 00 08 02 00 00 01 00 00 00 08 04 00 00 05 >Apr 7 19:47:50 eal5 pluto[15610]: | 02 00 00 28 03 01 00 04 03 00 00 08 01 00 00 03 >Apr 7 19:47:50 eal5 pluto[15610]: | 03 00 00 08 03 00 00 02 03 00 00 08 02 00 00 02 >Apr 7 19:47:50 eal5 pluto[15610]: | 00 00 00 08 04 00 00 05 02 00 00 28 04 01 00 04 >Apr 7 19:47:50 eal5 pluto[15610]: | 03 00 00 08 01 00 00 03 03 00 00 08 03 00 00 02 >Apr 7 19:47:50 eal5 pluto[15610]: | 03 00 00 08 02 00 00 01 00 00 00 08 04 00 00 05 >Apr 7 19:47:50 eal5 pluto[15610]: | 02 00 00 28 05 01 00 04 03 00 00 08 01 00 00 03 >Apr 7 19:47:50 eal5 pluto[15610]: | 03 00 00 08 03 00 00 02 03 00 00 08 02 00 00 02 >Apr 7 19:47:50 eal5 pluto[15610]: | 00 00 00 08 04 00 00 02 02 00 00 28 06 01 00 04 >Apr 7 19:47:50 eal5 pluto[15610]: | 03 00 00 08 01 00 00 03 03 00 00 08 03 00 00 02 >Apr 7 19:47:50 eal5 pluto[15610]: | 03 00 00 08 02 00 00 01 00 00 00 08 04 00 00 02 >Apr 7 19:47:50 eal5 pluto[15610]: | 02 00 00 28 07 01 00 04 03 00 00 08 01 00 00 0c >Apr 7 19:47:50 eal5 pluto[15610]: | 03 00 00 08 03 00 00 02 03 00 00 08 02 00 00 02 >Apr 7 19:47:50 eal5 pluto[15610]: | 00 00 00 08 04 00 00 0e 02 00 00 28 08 01 00 04 >Apr 7 19:47:50 eal5 pluto[15610]: | 03 00 00 08 01 00 00 0c 03 00 00 08 03 00 00 02 >Apr 7 19:47:50 eal5 pluto[15610]: | 03 00 00 08 02 00 00 01 00 00 00 08 04 00 00 0e >Apr 7 19:47:50 eal5 pluto[15610]: | 02 00 00 28 09 01 00 04 03 00 00 08 01 00 00 03 >Apr 7 19:47:50 eal5 pluto[15610]: | 03 00 00 08 03 00 00 02 03 00 00 08 02 00 00 02 >Apr 7 19:47:50 eal5 pluto[15610]: | 00 00 00 08 04 00 00 0e 00 00 00 28 0a 01 00 04 >Apr 7 19:47:50 eal5 pluto[15610]: | 03 00 00 08 01 00 00 03 03 00 00 08 03 00 00 02 >Apr 7 19:47:50 eal5 pluto[15610]: | 03 00 00 08 02 00 00 01 00 00 00 08 04 00 00 0e >Apr 7 19:47:50 eal5 pluto[15610]: | 28 00 00 c8 00 05 00 00 6d 40 1e 4c 90 f2 06 9e >Apr 7 19:47:50 eal5 pluto[15610]: | 19 22 d9 dc c6 99 8c e4 60 fb 75 7e 82 0c be 52 >Apr 7 19:47:50 eal5 pluto[15610]: | b4 72 9c 2d 93 51 76 30 03 68 93 a9 ab 65 81 d8 >Apr 7 19:47:50 eal5 pluto[15610]: | 38 68 7a 3e 4b 18 fc 07 07 85 f0 62 e8 3f 00 3b >Apr 7 19:47:50 eal5 pluto[15610]: | d4 4f 02 8b 7e 3f 29 b9 0b 56 a1 3a 76 2c 0e 99 >Apr 7 19:47:50 eal5 pluto[15610]: | a6 73 74 97 e3 d4 9c c8 5d 0e 14 47 5c 04 d2 8d >Apr 7 19:47:50 eal5 pluto[15610]: | f6 de 73 7d 41 25 6a 66 6d 90 00 2b 89 72 ef 98 >Apr 7 19:47:50 eal5 pluto[15610]: | ed 66 35 5a 55 a9 91 8d 24 50 51 b0 16 56 66 40 >Apr 7 19:47:51 eal5 pluto[15610]: | 31 26 eb 08 48 f5 d6 1e c4 f5 6a c0 fe 4d e2 5a >Apr 7 19:47:51 eal5 pluto[15610]: | 8f b0 cf d1 10 73 c2 cf 56 7d da d2 3f fc 51 0d >Apr 7 19:47:51 eal5 pluto[15610]: | ce a9 22 9a ec 92 ec 3f 77 b9 a3 26 5f b3 3d 6c >Apr 7 19:47:51 eal5 pluto[15610]: | 57 f7 be 77 d9 76 b2 a2 89 d8 e0 17 0f d5 33 f0 >Apr 7 19:47:51 eal5 pluto[15610]: | 6a fb c7 d2 50 6c a2 5c 2b 80 00 14 3e 4d e2 e6 >Apr 7 19:47:51 eal5 pluto[15610]: | bd 82 a5 a6 05 da 10 74 5d 47 08 92 00 00 00 10 >Apr 7 19:47:51 eal5 pluto[15610]: | 4f 45 5a 44 7e 4d 54 7d 6d 41 6e 6c >Apr 7 19:47:51 eal5 pluto[15610]: | inserting event EVENT_RETRANSMIT, timeout in 10 seconds for #1 >Apr 7 19:47:51 eal5 pluto[15610]: | complete v2 state transition with STF_OK >Apr 7 19:47:51 eal5 pluto[15610]: "openswan-racoon2" #1: transition from state STATE_IKEv2_START to state STATE_PARENT_I1 >Apr 7 19:47:51 eal5 pluto[15610]: "openswan-racoon2" #1: STATE_PARENT_I1: sent v2I1, expected v2R1 >Apr 7 19:47:51 eal5 pluto[15610]: | * processed 1 messages from cryptographic helpers >Apr 7 19:47:51 eal5 pluto[15610]: | next event EVENT_PENDING_PHASE2 in 54 seconds >Apr 7 19:47:51 eal5 pluto[15610]: | >Apr 7 19:47:51 eal5 pluto[15610]: | *received 38 bytes from 9.47.67.138:500 on eth0 (port=500) >Apr 7 19:47:51 eal5 pluto[15610]: | 54 b8 6c cf f5 63 de f5 00 00 00 00 00 00 00 00 >Apr 7 19:47:51 eal5 pluto[15610]: | 29 20 22 20 00 00 00 00 00 00 00 26 00 00 00 0a >Apr 7 19:47:51 eal5 pluto[15610]: | 00 00 00 11 00 02 >Apr 7 19:47:51 eal5 pluto[15610]: | **parse ISAKMP Message: >Apr 7 19:47:51 eal5 pluto[15610]: | initiator cookie: >Apr 7 19:47:51 eal5 pluto[15610]: | 54 b8 6c cf f5 63 de f5 >Apr 7 19:47:51 eal5 pluto[15610]: | responder cookie: >Apr 7 19:47:51 eal5 pluto[15610]: | 00 00 00 00 00 00 00 00 >Apr 7 19:47:51 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_v2N >Apr 7 19:47:51 eal5 pluto[15610]: | ISAKMP version: IKEv2 version 2.0 (rfc4306) >Apr 7 19:47:51 eal5 pluto[15610]: | exchange type: ISAKMP_v2_SA_INIT >Apr 7 19:47:51 eal5 pluto[15610]: | flags: ISAKMP_FLAG_RESPONSE >Apr 7 19:47:51 eal5 pluto[15610]: | message ID: 00 00 00 00 >Apr 7 19:47:51 eal5 pluto[15610]: | length: 38 >Apr 7 19:47:51 eal5 pluto[15610]: | processing version=2.0 packet with exchange type=ISAKMP_v2_SA_INIT (34) >Apr 7 19:47:51 eal5 pluto[15610]: | ICOOKIE: 54 b8 6c cf f5 63 de f5 >Apr 7 19:47:51 eal5 pluto[15610]: | RCOOKIE: 00 00 00 00 00 00 00 00 >Apr 7 19:47:51 eal5 pluto[15610]: | state hash entry 20 >Apr 7 19:47:51 eal5 pluto[15610]: | v2 peer and cookies match on #1 >Apr 7 19:47:51 eal5 pluto[15610]: | v2 state object #1 found, in STATE_PARENT_I1 >Apr 7 19:47:51 eal5 pluto[15610]: | ***parse IKEv2 Notify Payload: >Apr 7 19:47:51 eal5 pluto[15610]: | next payload type: ISAKMP_NEXT_NONE >Apr 7 19:47:51 eal5 pluto[15610]: | critical bit: Payload-Non-Critical >Apr 7 19:47:51 eal5 pluto[15610]: | length: 10 >Apr 7 19:47:52 eal5 pluto[15610]: | Protocol ID: PROTO_RESERVED >Apr 7 19:47:52 eal5 pluto[15610]: | SPI size: 0 >Apr 7 19:47:52 eal5 pluto[15610]: | Notify Message Type: 17?? >Apr 7 19:47:52 eal5 pluto[15610]: | processing payload: ISAKMP_NEXT_v2N (len=10) >Apr 7 19:47:52 eal5 pluto[15610]: | ikev2 parent inR1: calculating g^{xy} in order to send I2 >Apr 7 19:47:52 eal5 pluto[15610]: packet from 9.47.67.138:500: ASSERTION FAILED at /usr/src/redhat/BUILD/openswan-2.6.09/programs/pluto/ipsec_doi.c:222: pbs != NULL >Apr 7 19:47:52 eal5 pluto[15610]: packet from 9.47.67.138:500: using kernel interface: netkey >Apr 7 19:47:52 eal5 pluto[15610]: packet from 9.47.67.138:500: interface lo/lo ::1 >Apr 7 19:47:52 eal5 pluto[15610]: packet from 9.47.67.138:500: interface lo/lo 127.0.0.1 >Apr 7 19:47:52 eal5 pluto[15610]: packet from 9.47.67.138:500: interface lo/lo 127.0.0.1 >Apr 7 19:47:52 eal5 pluto[15610]: packet from 9.47.67.138:500: interface eth0/eth0 9.3.190.198 >Apr 7 19:47:52 eal5 pluto[15610]: packet from 9.47.67.138:500: interface eth0/eth0 9.3.190.198 >Apr 7 19:47:52 eal5 pluto[15610]: packet from 9.47.67.138:500: %myid = (none) >Apr 7 19:47:52 eal5 pluto[15610]: packet from 9.47.67.138:500: debug raw+crypt+parsing+emitting+control+lifecycle+klips+dns+oppo+controlmore+pfkey+nattraversal+x509 >Apr 7 19:47:52 eal5 pluto[15610]: packet from 9.47.67.138:500: >Apr 7 19:47:52 eal5 pluto[15610]: packet from 9.47.67.138:500: algorithm ESP encrypt: id=2, name=ESP_DES, ivlen=8, keysizemin=64, keysizemax=64 >Apr 7 19:47:52 eal5 pluto[15610]: packet from 9.47.67.138:500: algorithm ESP encrypt: id=3, name=ESP_3DES, ivlen=8, keysizemin=192, keysizemax=192 >Apr 7 19:47:52 eal5 pluto[15610]: packet from 9.47.67.138:500: algorithm ESP encrypt: id=6, name=ESP_CAST, ivlen=8, keysizemin=40, keysizemax=128 >Apr 7 19:47:52 eal5 pluto[15610]: packet from 9.47.67.138:500: algorithm ESP encrypt: id=7, name=ESP_BLOWFISH, ivlen=8, keysizemin=40, keysizemax=448 >Apr 7 19:47:52 eal5 pluto[15610]: packet from 9.47.67.138:500: algorithm ESP encrypt: id=11, name=ESP_NULL, ivlen=0, keysizemin=0, keysizemax=0 >Apr 7 19:47:52 eal5 pluto[15610]: packet from 9.47.67.138:500: algorithm ESP encrypt: id=12, name=ESP_AES, ivlen=8, keysizemin=128, keysizemax=256 >Apr 7 19:47:52 eal5 pluto[15610]: packet from 9.47.67.138:500: algorithm ESP encrypt: id=13, name=(null), ivlen=8, keysizemin=128, keysizemax=256 >Apr 7 19:47:52 eal5 pluto[15610]: packet from 9.47.67.138:500: algorithm ESP encrypt: id=252, name=ESP_SERPENT, ivlen=8, keysizemin=128, keysizemax=256 >Apr 7 19:47:52 eal5 pluto[15610]: packet from 9.47.67.138:500: algorithm ESP encrypt: id=253, name=ESP_TWOFISH, ivlen=8, keysizemin=128, keysizemax=256 >Apr 7 19:47:52 eal5 pluto[15610]: packet from 9.47.67.138:500: algorithm ESP auth attr: id=1, name=AUTH_ALGORITHM_HMAC_MD5, keysizemin=128, keysizemax=128 >Apr 7 19:47:52 eal5 pluto[15610]: packet from 9.47.67.138:500: algorithm ESP auth attr: id=2, name=AUTH_ALGORITHM_HMAC_SHA1, keysizemin=160, keysizemax=160 >Apr 7 19:47:52 eal5 pluto[15610]: packet from 9.47.67.138:500: algorithm ESP auth attr: id=5, name=AUTH_ALGORITHM_HMAC_SHA2_256, keysizemin=256, keysizemax=256 >Apr 7 19:47:52 eal5 pluto[15610]: packet from 9.47.67.138:500: algorithm ESP auth attr: id=8, name=AUTH_ALGORITHM_HMAC_RIPEMD, keysizemin=160, keysizemax=160 >Apr 7 19:47:52 eal5 pluto[15610]: packet from 9.47.67.138:500: algorithm ESP auth attr: id=9, name=AUTH_ALGORITHM_AES_CBC, keysizemin=128, keysizemax=128 >Apr 7 19:47:52 eal5 pluto[15610]: packet from 9.47.67.138:500: algorithm ESP auth attr: id=251, name=(null), keysizemin=0, keysizemax=0 >Apr 7 19:47:52 eal5 pluto[15610]: packet from 9.47.67.138:500: >Apr 7 19:47:52 eal5 pluto[15610]: packet from 9.47.67.138:500: algorithm IKE encrypt: id=3, name=OAKLEY_BLOWFISH_CBC, blocksize=8, keydeflen=128 >Apr 7 19:47:52 eal5 pluto[15610]: packet from 9.47.67.138:500: algorithm IKE encrypt: id=5, name=OAKLEY_3DES_CBC, blocksize=8, keydeflen=192 >Apr 7 19:47:52 eal5 pluto[15610]: packet from 9.47.67.138:500: algorithm IKE encrypt: id=7, name=OAKLEY_AES_CBC, blocksize=16, keydeflen=128 >Apr 7 19:47:52 eal5 pluto[15610]: packet from 9.47.67.138:500: algorithm IKE encrypt: id=65004, name=OAKLEY_SERPENT_CBC, blocksize=16, keydeflen=128 >Apr 7 19:47:52 eal5 pluto[15610]: packet from 9.47.67.138:500: algorithm IKE encrypt: id=65005, name=OAKLEY_TWOFISH_CBC, blocksize=16, keydeflen=128 >Apr 7 19:47:52 eal5 pluto[15610]: packet from 9.47.67.138:500: algorithm IKE encrypt: id=65289, name=OAKLEY_TWOFISH_CBC_SSH, blocksize=16, keydeflen=128 >Apr 7 19:47:52 eal5 pluto[15610]: packet from 9.47.67.138:500: algorithm IKE hash: id=1, name=OAKLEY_MD5, hashsize=16 >Apr 7 19:47:53 eal5 pluto[15610]: packet from 9.47.67.138:500: algorithm IKE hash: id=2, name=OAKLEY_SHA1, hashsize=20 >Apr 7 19:47:53 eal5 pluto[15610]: packet from 9.47.67.138:500: algorithm IKE hash: id=4, name=OAKLEY_SHA2_256, hashsize=32 >Apr 7 19:47:53 eal5 pluto[15610]: packet from 9.47.67.138:500: algorithm IKE hash: id=6, name=OAKLEY_SHA2_512, hashsize=64 >Apr 7 19:47:53 eal5 pluto[15610]: packet from 9.47.67.138:500: algorithm IKE dh group: id=2, name=OAKLEY_GROUP_MODP1024, bits=1024 >Apr 7 19:47:53 eal5 pluto[15610]: packet from 9.47.67.138:500: algorithm IKE dh group: id=5, name=OAKLEY_GROUP_MODP1536, bits=1536 >Apr 7 19:47:53 eal5 pluto[15610]: packet from 9.47.67.138:500: algorithm IKE dh group: id=14, name=OAKLEY_GROUP_MODP2048, bits=2048 >Apr 7 19:47:53 eal5 pluto[15610]: packet from 9.47.67.138:500: algorithm IKE dh group: id=15, name=OAKLEY_GROUP_MODP3072, bits=3072 >Apr 7 19:47:53 eal5 pluto[15610]: packet from 9.47.67.138:500: algorithm IKE dh group: id=16, name=OAKLEY_GROUP_MODP4096, bits=4096 >Apr 7 19:47:53 eal5 pluto[15610]: packet from 9.47.67.138:500: algorithm IKE dh group: id=17, name=OAKLEY_GROUP_MODP6144, bits=6144 >Apr 7 19:47:53 eal5 pluto[15610]: packet from 9.47.67.138:500: algorithm IKE dh group: id=18, name=OAKLEY_GROUP_MODP8192, bits=8192 >Apr 7 19:47:53 eal5 pluto[15610]: packet from 9.47.67.138:500: >Apr 7 19:47:53 eal5 pluto[15610]: packet from 9.47.67.138:500: stats db_ops: {curr_cnt, total_cnt, maxsz} :context={0,0,0} trans={0,0,0} attrs={0,0,0} >Apr 7 19:47:53 eal5 pluto[15610]: packet from 9.47.67.138:500: >Apr 7 19:47:53 eal5 pluto[15610]: packet from 9.47.67.138:500: "i386-ppc": 9.3.190.198<9.3.190.198>[S=C]...9.3.192.210<9.3.192.210>[S=C]; unrouted; eroute owner: #0 >Apr 7 19:47:53 eal5 pluto[15610]: packet from 9.47.67.138:500: "i386-ppc": myip=unset; hisip=unset; >Apr 7 19:47:53 eal5 pluto[15610]: packet from 9.47.67.138:500: "i386-ppc": ike_life: 3600s; ipsec_life: 28800s; rekey_margin: 540s; rekey_fuzz: 100%; keyingtries: 3 >Apr 7 19:47:53 eal5 pluto[15610]: packet from 9.47.67.138:500: "i386-ppc": policy: PSK+ENCRYPT+TUNNEL+PFS+!IKEv1+IKEv2ALLOW+IKEv2Init+lKOD+rKOD; prio: 32,32; interface: eth0; >Apr 7 19:47:53 eal5 pluto[15610]: packet from 9.47.67.138:500: "i386-ppc": newest ISAKMP SA: #0; newest IPsec SA: #0; >Apr 7 19:47:53 eal5 pluto[15610]: packet from 9.47.67.138:500: "i386-ppc": IKE algorithms wanted: 3DES_CBC(5)_000-SHA1(2)-MODP1024(2); flags=-strict >Apr 7 19:47:53 eal5 pluto[15610]: packet from 9.47.67.138:500: "i386-ppc": IKE algorithms found: 3DES_CBC(5)_192-SHA1(2)_160-2, >Apr 7 19:47:53 eal5 pluto[15610]: packet from 9.47.67.138:500: "i386-x86_64": 9.3.190.198<9.3.190.198>[S=C]...9.47.66.195<9.47.66.195>[S=C]; unrouted; eroute owner: #0 >Apr 7 19:47:53 eal5 pluto[15610]: packet from 9.47.67.138:500: "i386-x86_64": myip=unset; hisip=unset; >Apr 7 19:47:53 eal5 pluto[15610]: packet from 9.47.67.138:500: "i386-x86_64": ike_life: 3600s; ipsec_life: 28800s; rekey_margin: 540s; rekey_fuzz: 100%; keyingtries: 3 >Apr 7 19:47:53 eal5 pluto[15610]: packet from 9.47.67.138:500: "i386-x86_64": policy: PSK+ENCRYPT+TUNNEL+PFS+!IKEv1+IKEv2ALLOW+IKEv2Init+lKOD+rKOD; prio: 32,32; interface: eth0; >Apr 7 19:47:53 eal5 pluto[15610]: packet from 9.47.67.138:500: "i386-x86_64": newest ISAKMP SA: #0; newest IPsec SA: #0; >Apr 7 19:47:53 eal5 pluto[15610]: packet from 9.47.67.138:500: "openswan-racoon2": 9.3.190.198<9.3.190.198>[S=C]...9.47.67.138<9.47.67.138>[S=C]; unrouted; eroute owner: #0 >Apr 7 19:47:53 eal5 pluto[15610]: packet from 9.47.67.138:500: "openswan-racoon2": myip=unset; hisip=unset; >Apr 7 19:47:53 eal5 pluto[15610]: packet from 9.47.67.138:500: "openswan-racoon2": ike_life: 3600s; ipsec_life: 28800s; rekey_margin: 540s; rekey_fuzz: 100%; keyingtries: 3 >Apr 7 19:47:53 eal5 pluto[15610]: packet from 9.47.67.138:500: "openswan-racoon2": policy: PSK+ENCRYPT+TUNNEL+PFS+UP+!IKEv1+IKEv2ALLOW+IKEv2Init+lKOD+rKOD; prio: 32,32; interface: eth0; >Apr 7 19:47:53 eal5 pluto[15610]: packet from 9.47.67.138:500: "openswan-racoon2": newest ISAKMP SA: #0; newest IPsec SA: #0; >Apr 7 19:47:53 eal5 pluto[15610]: packet from 9.47.67.138:500: "openswan-strongswan-i386": 9.3.190.198<9.3.190.198>[S=C]...9.47.66.68<9.47.66.68>[S=C]; unrouted; eroute owner: #0 >Apr 7 19:47:53 eal5 pluto[15610]: packet from 9.47.67.138:500: "openswan-strongswan-i386": myip=unset; hisip=unset; >Apr 7 19:47:53 eal5 pluto[15610]: packet from 9.47.67.138:500: "openswan-strongswan-i386": ike_life: 3600s; ipsec_life: 28800s; rekey_margin: 540s; rekey_fuzz: 100%; keyingtries: 3 >Apr 7 19:47:53 eal5 pluto[15610]: packet from 9.47.67.138:500: "openswan-strongswan-i386": policy: PSK+ENCRYPT+TUNNEL+PFS+!IKEv1+IKEv2ALLOW+IKEv2Init+lKOD+rKOD; prio: 32,32; interface: eth0; >Apr 7 19:47:53 eal5 pluto[15610]: packet from 9.47.67.138:500: "openswan-strongswan-i386": newest ISAKMP SA: #0; newest IPsec SA: #0; >Apr 7 19:47:53 eal5 pluto[15610]: packet from 9.47.67.138:500: "openswan-strongswan-ppc": 9.3.190.198<9.3.190.198>[S=C]...9.3.192.206<9.3.192.206>[S=C]; unrouted; eroute owner: #0 >Apr 7 19:47:53 eal5 pluto[15610]: packet from 9.47.67.138:500: "openswan-strongswan-ppc": myip=unset; hisip=unset; >Apr 7 19:47:53 eal5 pluto[15610]: packet from 9.47.67.138:500: "openswan-strongswan-ppc": ike_life: 3600s; ipsec_life: 28800s; rekey_margin: 540s; rekey_fuzz: 100%; keyingtries: 3 >Apr 7 19:47:54 eal5 pluto[15610]: packet from 9.47.67.138:500: "openswan-strongswan-ppc": policy: PSK+ENCRYPT+TUNNEL+PFS+!IKEv1+IKEv2ALLOW+IKEv2Init+lKOD+rKOD; prio: 32,32; interface: eth0; >Apr 7 19:47:54 eal5 pluto[15610]: packet from 9.47.67.138:500: "openswan-strongswan-ppc": newest ISAKMP SA: #0; newest IPsec SA: #0; >Apr 7 19:47:54 eal5 pluto[15610]: packet from 9.47.67.138:500: "openswan-strongswan-ppc": IKE algorithms wanted: AES_CBC(7)_128-SHA1(2)-MODP2048(14); flags=-strict >Apr 7 19:47:54 eal5 pluto[15610]: packet from 9.47.67.138:500: "openswan-strongswan-ppc": IKE algorithms found: AES_CBC(7)_128-SHA1(2)_160-14, >Apr 7 19:47:54 eal5 pluto[15610]: packet from 9.47.67.138:500: "openswan-strongswan-ppc": ESP algorithms wanted: AES(12)_128-SHA1(2); flags=-strict >Apr 7 19:47:54 eal5 pluto[15610]: | kernel_alg_esp_enc_ok(12,0): alg_id=12, alg_ivlen=8, alg_minbits=128, alg_maxbits=256, res=0, ret=1 >Apr 7 19:47:54 eal5 pluto[15610]: | kernel_alg_esp_auth_keylen(auth=2, sadb_aalg=3): a_keylen=20 >Apr 7 19:47:54 eal5 pluto[15610]: packet from 9.47.67.138:500: "openswan-strongswan-ppc": ESP algorithms loaded: AES(12)_128-SHA1(2)_160 >Apr 7 19:47:54 eal5 pluto[15610]: packet from 9.47.67.138:500: >Apr 7 19:47:54 eal5 pluto[15610]: packet from 9.47.67.138:500: #1: "openswan-racoon2":500 STATE_PARENT_I1 (sent v2I1, expected v2R1); none in -1s; nodpd; idle; import:admin initiate >Apr 7 19:47:54 eal5 pluto[15610]: packet from 9.47.67.138:500:
<b>You cannot view the attachment while viewing its details because your browser does not support IFRAMEs. <a href="attachment.cgi?id=301706">View the attachment on a separate page</a>.</b>
View Attachment As Raw
Actions:
View
Attachments on
bug 441588
:
301705
| 301706 |
301707
|
301708