Attachment 299492 Details for Bug 438826 – /var/log/secure from initiator (eal5)

/var/log/secure from initiator (eal5)

eal5_init-tim-hv4_resp.eal5.log (text/plain), 40.34 KB, created by IBM Bug Proxy on 2008-03-28 16:49:19 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: IBM Bug Proxy

Created: 2008-03-28 16:49:19 UTC

Size: 40.34 KB

patch

obsolete

>
>Mar 28 11:47:55 eal5 ipsec__plutorun: Starting Pluto subsystem...
>Mar 28 11:47:55 eal5 pluto[5451]: Starting Pluto (Openswan Version 2.6.09; Vendor ID OEZD~MT}mAnl) pid:5451
>Mar 28 11:47:55 eal5 pluto[5451]: Setting NAT-Traversal port-4500 floating to on
>Mar 28 11:47:55 eal5 pluto[5451]:    port floating activation criteria nat_t=1/port_float=1
>Mar 28 11:47:55 eal5 pluto[5451]:    including NAT-Traversal patch (Version 0.6c)
>Mar 28 11:47:55 eal5 pluto[5451]: | opening /dev/urandom
>Mar 28 11:47:55 eal5 pluto[5451]: using /dev/urandom as source of random entropy
>Mar 28 11:47:55 eal5 pluto[5451]: | inserting event EVENT_REINIT_SECRET, timeout in 3600 seconds
>Mar 28 11:47:55 eal5 pluto[5451]: | inserting event EVENT_PENDING_PHASE2, timeout in 120 seconds
>Mar 28 11:47:55 eal5 pluto[5451]: ike_alg_register_enc(): Activating OAKLEY_TWOFISH_CBC_SSH: Ok (ret=0)
>Mar 28 11:47:55 eal5 pluto[5451]: ike_alg_register_enc(): Activating OAKLEY_TWOFISH_CBC: Ok (ret=0)
>Mar 28 11:47:55 eal5 pluto[5451]: ike_alg_register_enc(): Activating OAKLEY_SERPENT_CBC: Ok (ret=0)
>Mar 28 11:47:55 eal5 pluto[5451]: ike_alg_register_enc(): Activating OAKLEY_AES_CBC: Ok (ret=0)
>Mar 28 11:47:56 eal5 pluto[5451]: ike_alg_register_enc(): Activating OAKLEY_BLOWFISH_CBC: Ok (ret=0)
>Mar 28 11:47:56 eal5 pluto[5451]: ike_alg_register_hash(): Activating OAKLEY_SHA2_512: Ok (ret=0)
>Mar 28 11:47:56 eal5 pluto[5451]: ike_alg_register_hash(): Activating OAKLEY_SHA2_256: Ok (ret=0)
>Mar 28 11:47:56 eal5 pluto[5451]: starting up 3 cryptographic helpers
>Mar 28 11:47:56 eal5 pluto[5462]: | opening /dev/urandom
>Mar 28 11:47:56 eal5 pluto[5451]: started helper pid=5462 (fd:7)
>Mar 28 11:47:56 eal5 pluto[5462]: using /dev/urandom as source of random entropy
>Mar 28 11:47:56 eal5 pluto[5464]: | opening /dev/urandom
>Mar 28 11:47:56 eal5 pluto[5451]: started helper pid=5464 (fd:8)
>Mar 28 11:47:56 eal5 pluto[5462]: ! helper 0 waiting on fd: 8
>Mar 28 11:47:56 eal5 pluto[5464]: using /dev/urandom as source of random entropy
>Mar 28 11:47:56 eal5 pluto[5451]: started helper pid=5465 (fd:9)
>Mar 28 11:47:56 eal5 pluto[5465]: | opening /dev/urandom
>Mar 28 11:47:56 eal5 pluto[5464]: ! helper 1 waiting on fd: 9
>Mar 28 11:47:56 eal5 pluto[5451]: Using Linux 2.6 IPsec interface code on 2.6.18-86.el5 (experimental code)
>Mar 28 11:47:56 eal5 pluto[5465]: using /dev/urandom as source of random entropy
>Mar 28 11:47:56 eal5 pluto[5451]: | process 5451 listening for PF_KEY_V2 on file descriptor 12
>Mar 28 11:47:56 eal5 pluto[5465]: ! helper 2 waiting on fd: 10
>Mar 28 11:47:56 eal5 pluto[5451]: | finish_pfkey_msg: SADB_REGISTER message 1 for AH 
>Mar 28 11:47:56 eal5 pluto[5451]: |   02 07 00 02  02 00 00 00  01 00 00 00  4b 15 00 00
>Mar 28 11:47:56 eal5 pluto[5451]: | pfkey_get: SADB_REGISTER message 1
>Mar 28 11:47:56 eal5 pluto[5451]: | AH registered with kernel.
>Mar 28 11:47:56 eal5 pluto[5451]: | finish_pfkey_msg: SADB_REGISTER message 2 for ESP 
>Mar 28 11:47:56 eal5 pluto[5451]: |   02 07 00 03  02 00 00 00  02 00 00 00  4b 15 00 00
>Mar 28 11:47:56 eal5 pluto[5451]: | pfkey_get: SADB_REGISTER message 2
>Mar 28 11:47:56 eal5 pluto[5451]: | alg_init():memset(0x5c7ec0, 0, 2016) memset(0x5c86a0, 0, 2048) 
>Mar 28 11:47:56 eal5 pluto[5451]: | kernel_alg_register_pfkey(): SADB_SATYPE_ESP: sadb_msg_len=19 sadb_supported_len=56
>Mar 28 11:47:56 eal5 pluto[5451]: | kernel_alg_add():satype=3, exttype=14, alg_id=251
>Mar 28 11:47:56 eal5 pluto[5451]: | 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 11:47:56 eal5 pluto[5451]: | kernel_alg_add():satype=3, exttype=14, alg_id=2
>Mar 28 11:47:57 eal5 pluto[5451]: | 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 11:47:57 eal5 pluto[5451]: | kernel_alg_add():satype=3, exttype=14, alg_id=3
>Mar 28 11:47:57 eal5 pluto[5451]: | 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 11:47:57 eal5 pluto[5451]: | kernel_alg_add():satype=3, exttype=14, alg_id=5
>Mar 28 11:47:57 eal5 pluto[5451]: | 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 11:47:57 eal5 pluto[5451]: | kernel_alg_add():satype=3, exttype=14, alg_id=8
>Mar 28 11:47:57 eal5 pluto[5451]: | 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 11:47:57 eal5 pluto[5451]: | kernel_alg_add():satype=3, exttype=14, alg_id=9
>Mar 28 11:47:57 eal5 pluto[5451]: | 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 11:47:57 eal5 pluto[5451]: | kernel_alg_register_pfkey(): SADB_SATYPE_ESP: sadb_msg_len=19 sadb_supported_len=80
>Mar 28 11:47:57 eal5 pluto[5451]: | kernel_alg_add():satype=3, exttype=15, alg_id=11
>Mar 28 11:47:57 eal5 pluto[5451]: | 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 11:47:57 eal5 pluto[5451]: | kernel_alg_add():satype=3, exttype=15, alg_id=2
>Mar 28 11:47:57 eal5 pluto[5451]: | 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 11:47:57 eal5 pluto[5451]: | kernel_alg_add():satype=3, exttype=15, alg_id=3
>Mar 28 11:47:57 eal5 pluto[5451]: | 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 11:47:57 eal5 pluto[5451]: | kernel_alg_add():satype=3, exttype=15, alg_id=6
>Mar 28 11:47:57 eal5 pluto[5451]: | 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 11:47:57 eal5 pluto[5451]: | kernel_alg_add():satype=3, exttype=15, alg_id=7
>Mar 28 11:47:57 eal5 pluto[5451]: | 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 11:47:57 eal5 pluto[5451]: | kernel_alg_add():satype=3, exttype=15, alg_id=12
>Mar 28 11:47:57 eal5 pluto[5451]: | 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 11:47:57 eal5 pluto[5451]: | kernel_alg_add():satype=3, exttype=15, alg_id=252
>Mar 28 11:47:57 eal5 pluto[5451]: | 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 11:47:57 eal5 pluto[5451]: | kernel_alg_add():satype=3, exttype=15, alg_id=253
>Mar 28 11:47:57 eal5 pluto[5451]: | 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 11:47:57 eal5 pluto[5451]: | kernel_alg_add():satype=3, exttype=15, alg_id=13
>Mar 28 11:47:57 eal5 pluto[5451]: | 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 11:47:57 eal5 pluto[5451]: | ESP registered with kernel.
>Mar 28 11:47:57 eal5 pluto[5451]: | finish_pfkey_msg: SADB_REGISTER message 3 for IPCOMP 
>Mar 28 11:47:57 eal5 pluto[5451]: |   02 07 00 09  02 00 00 00  03 00 00 00  4b 15 00 00
>Mar 28 11:47:57 eal5 pluto[5451]: | pfkey_get: SADB_REGISTER message 3
>Mar 28 11:47:57 eal5 pluto[5451]: | IPCOMP registered with kernel.
>Mar 28 11:47:57 eal5 pluto[5451]: Could not change to directory '/etc/ipsec.d/cacerts': /
>Mar 28 11:47:57 eal5 pluto[5451]: Could not change to directory '/etc/ipsec.d/aacerts': /
>Mar 28 11:47:57 eal5 pluto[5451]: Could not change to directory '/etc/ipsec.d/ocspcerts': /
>Mar 28 11:47:57 eal5 pluto[5451]: Could not change to directory '/etc/ipsec.d/crls'
>Mar 28 11:47:58 eal5 pluto[5451]: | inserting event EVENT_LOG_DAILY, timeout in 43922 seconds
>Mar 28 11:47:58 eal5 pluto[5451]: | next event EVENT_PENDING_PHASE2 in 117 seconds
>Mar 28 11:47:58 eal5 pluto[5451]: |  
>Mar 28 11:47:58 eal5 pluto[5451]: | *received whack message
>Mar 28 11:47:58 eal5 pluto[5451]: | Added new connection i386-ppc64 with policy PSK+ENCRYPT+TUNNEL+PFS+!IKEv1+IKEv2ALLOW+IKEv2Init
>Mar 28 11:47:58 eal5 pluto[5451]: | counting wild cards for 9.3.190.198 is 0
>Mar 28 11:47:58 eal5 pluto[5451]: | counting wild cards for 9.3.192.210 is 0
>Mar 28 11:47:58 eal5 pluto[5451]: added connection description "i386-ppc64"
>Mar 28 11:47:58 eal5 pluto[5451]: | 9.3.190.198<9.3.190.198>[S=C]...9.3.192.210<9.3.192.210>[S=C]
>Mar 28 11:47:58 eal5 pluto[5451]: | ike_life: 3600s; ipsec_life: 28800s; rekey_margin: 540s; rekey_fuzz: 100%; keyingtries: 3; policy: PSK+ENCRYPT+TUNNEL+PFS+!IKEv1+IKEv2ALLOW+IKEv2Init
>Mar 28 11:47:58 eal5 pluto[5451]: | * processed 0 messages from cryptographic helpers 
>Mar 28 11:47:58 eal5 pluto[5451]: | next event EVENT_PENDING_PHASE2 in 117 seconds
>Mar 28 11:47:58 eal5 pluto[5451]: |  
>Mar 28 11:47:58 eal5 pluto[5451]: | *received whack message
>Mar 28 11:47:58 eal5 pluto[5451]: listening for IKE messages
>Mar 28 11:47:58 eal5 pluto[5451]: | found lo with address 127.0.0.1
>Mar 28 11:47:58 eal5 pluto[5451]: | found eth0 with address 9.3.190.198
>Mar 28 11:47:58 eal5 pluto[5451]: adding interface eth0/eth0 9.3.190.198:500
>Mar 28 11:47:58 eal5 pluto[5451]: adding interface eth0/eth0 9.3.190.198:4500
>Mar 28 11:47:58 eal5 pluto[5451]: adding interface lo/lo 127.0.0.1:500
>Mar 28 11:47:58 eal5 pluto[5451]: adding interface lo/lo 127.0.0.1:4500
>Mar 28 11:47:58 eal5 pluto[5451]: | found lo with address 0000:0000:0000:0000:0000:0000:0000:0001
>Mar 28 11:47:58 eal5 pluto[5451]: adding interface lo/lo ::1:500
>Mar 28 11:47:58 eal5 pluto[5451]: | connect_to_host_pair: 9.3.190.198:500 9.3.192.210:500 -> hp:none 
>Mar 28 11:47:58 eal5 pluto[5451]: loading secrets from "/etc/ipsec.secrets"
>Mar 28 11:47:58 eal5 pluto[5451]: | id type added to secret(0x8a0ee70) 1: 9.3.190.198
>Mar 28 11:47:58 eal5 pluto[5451]: | id type added to secret(0x8a0ee70) 1: 9.3.192.210
>Mar 28 11:47:58 eal5 pluto[5451]: | Processing PSK at line 1: passed
>Mar 28 11:47:58 eal5 pluto[5451]: | * processed 0 messages from cryptographic helpers 
>Mar 28 11:47:58 eal5 pluto[5451]: | next event EVENT_PENDING_PHASE2 in 117 seconds
>Mar 28 11:48:08 eal5 pluto[5451]: |  
>Mar 28 11:48:08 eal5 pluto[5451]: | *received whack message
>Mar 28 11:48:08 eal5 pluto[5451]: | processing connection i386-ppc64
>Mar 28 11:48:08 eal5 pluto[5451]: | empty esp_info, returning defaults
>Mar 28 11:48:08 eal5 pluto[5451]: | creating state object #1 at 0x8a0f958
>Mar 28 11:48:08 eal5 pluto[5451]: | processing connection i386-ppc64
>Mar 28 11:48:08 eal5 pluto[5451]: | ICOOKIE:  05 be fe 21  e5 62 02 f0
>Mar 28 11:48:08 eal5 pluto[5451]: | RCOOKIE:  00 00 00 00  00 00 00 00
>Mar 28 11:48:08 eal5 pluto[5451]: | state hash entry 23
>Mar 28 11:48:08 eal5 pluto[5451]: | inserting state object #1 on chain 23
>Mar 28 11:48:08 eal5 pluto[5451]: | inserting event EVENT_SO_DISCARD, timeout in 0 seconds for #1
>Mar 28 11:48:08 eal5 pluto[5451]: | processing connection i386-ppc64
>Mar 28 11:48:08 eal5 pluto[5451]: | Queuing pending Quick Mode with 9.3.192.210 "i386-ppc64"
>Mar 28 11:48:08 eal5 pluto[5451]: "i386-ppc64" #1: initiating v2 parent SA
>Mar 28 11:48:08 eal5 pluto[5451]: | no IKE algorithms for this connection 
>Mar 28 11:48:08 eal5 pluto[5451]: | 1: w->pcw_dead: 0 w->pcw_work: 0 cnt: 3
>Mar 28 11:48:08 eal5 pluto[5451]: | asking helper 1 to do build_kenonce op on seq: 1 (len=2668, pcw_work=1)
>Mar 28 11:48:08 eal5 pluto[5451]: | crypto helper write of request: cnt=2668<wlen=2668.  
>Mar 28 11:48:08 eal5 pluto[5464]: ! helper 1 read 2664+4/2668 bytesfd: 9
>Mar 28 11:48:08 eal5 pluto[5451]: | inserting event EVENT_CRYPTO_FAILED, timeout in 300 seconds for #1
>Mar 28 11:48:08 eal5 pluto[5464]: ! helper 1 doing build_kenonce op id: 1
>Mar 28 11:48:08 eal5 pluto[5451]: | * processed 0 messages from cryptographic helpers 
>Mar 28 11:48:08 eal5 pluto[5464]: ! Local DH secret:
>Mar 28 11:48:08 eal5 pluto[5451]: | next event EVENT_PENDING_PHASE2 in 107 seconds
>Mar 28 11:48:08 eal5 pluto[5464]: !   f3 22 d5 f3  44 04 60 2e  82 24 5c ee  41 64 17 1a
>Mar 28 11:48:08 eal5 pluto[5464]: !   da 48 8f f2  e3 ef d5 a4  ba 48 34 cd  dc 95 bd af
>Mar 28 11:48:08 eal5 pluto[5464]: ! Public DH value sent:
>Mar 28 11:48:08 eal5 pluto[5464]: !   0d c7 79 95  3a 73 6c 81  e3 12 64 f6  d0 55 61 c1
>Mar 28 11:48:08 eal5 pluto[5464]: !   2a ae d5 da  af 26 39 b7  aa be 1b eb  5f 0b c7 46
>Mar 28 11:48:08 eal5 pluto[5464]: !   51 ad 8b 29  47 0f 33 09  94 98 72 2d  fd b9 0e 4e
>Mar 28 11:48:09 eal5 pluto[5464]: !   7a ef 5a ce  16 a8 57 61  68 d9 48 90  d2 53 cc 0d
>Mar 28 11:48:09 eal5 pluto[5464]: !   0c 01 41 fe  b3 e7 9d 5a  ab 75 b5 66  9e 2d 2a fc
>Mar 28 11:48:09 eal5 pluto[5464]: !   4b 76 cc 76  67 50 4a 1e  92 5b 69 ae  e7 57 bc 85
>Mar 28 11:48:09 eal5 pluto[5464]: !   f1 88 b7 9b  32 0d 09 df  31 72 1d d7  c7 26 92 a5
>Mar 28 11:48:09 eal5 pluto[5464]: !   e4 06 8d b9  78 f0 68 75  d5 00 bd 93  55 ec 63 34
>Mar 28 11:48:09 eal5 pluto[5464]: !   d7 3d 7b fc  3e cb 32 e0  3a f3 dc 8c  f8 33 f0 e7
>Mar 28 11:48:09 eal5 pluto[5464]: !   bf df 8e 9b  33 89 ba 80  b3 1e 4b 64  61 51 b5 e4
>Mar 28 11:48:09 eal5 pluto[5464]: !   25 87 4b 08  7e 3a a1 da  a6 83 da 46  31 a1 1a 31
>Mar 28 11:48:09 eal5 pluto[5464]: !   de c5 75 a4  23 b2 99 b1  61 44 34 ee  78 0b 40 8b
>Mar 28 11:48:09 eal5 pluto[5464]: ! Generated nonce:
>Mar 28 11:48:09 eal5 pluto[5464]: !   4f 56 94 e7  61 09 80 3d  c9 f7 f9 fe  27 01 d7 73
>Mar 28 11:48:09 eal5 pluto[5451]: |  
>Mar 28 11:48:09 eal5 pluto[5451]: | helper 1 has finished work (cnt now 1)
>Mar 28 11:48:09 eal5 pluto[5451]: | helper 1 replies to id: q#1
>Mar 28 11:48:09 eal5 pluto[5451]: | calling callback function 0x4fd960
>Mar 28 11:48:09 eal5 pluto[5451]: | ikev2 parent outI1: calculated ke+nonce, sending I1
>Mar 28 11:48:09 eal5 pluto[5451]: | processing connection i386-ppc64
>Mar 28 11:48:09 eal5 pluto[5451]: | **emit ISAKMP Message:
>Mar 28 11:48:09 eal5 pluto[5451]: |    initiator cookie:
>Mar 28 11:48:09 eal5 pluto[5451]: |   05 be fe 21  e5 62 02 f0
>Mar 28 11:48:09 eal5 pluto[5451]: |    responder cookie:
>Mar 28 11:48:09 eal5 pluto[5451]: |   00 00 00 00  00 00 00 00
>Mar 28 11:48:09 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_v2SA
>Mar 28 11:48:09 eal5 pluto[5451]: |    ISAKMP version: IKEv2 version 2.0 (rfc4306)
>Mar 28 11:48:09 eal5 pluto[5451]: |    exchange type: ISAKMP_v2_SA_INIT
>Mar 28 11:48:09 eal5 pluto[5451]: |    flags: ISAKMP_FLAG_INIT
>Mar 28 11:48:09 eal5 pluto[5451]: |    message ID:  00 00 00 00
>Mar 28 11:48:09 eal5 pluto[5451]: | ***emit IKEv2 Security Association Payload:
>Mar 28 11:48:09 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_v2KE
>Mar 28 11:48:09 eal5 pluto[5451]: |    critical bit: Payload-Critical
>Mar 28 11:48:09 eal5 pluto[5451]: | ****emit IKEv2 Proposal Substructure Payload:
>Mar 28 11:48:09 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_P
>Mar 28 11:48:09 eal5 pluto[5451]: |    prop #: 1
>Mar 28 11:48:09 eal5 pluto[5451]: |    proto ID: 1
>Mar 28 11:48:09 eal5 pluto[5451]: |    spi size: 0
>Mar 28 11:48:09 eal5 pluto[5451]: |    # transforms: 4
>Mar 28 11:48:09 eal5 pluto[5451]: | *****emit IKEv2 Transform Substructure Payload:
>Mar 28 11:48:09 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_T
>Mar 28 11:48:09 eal5 pluto[5451]: |    transform type: 1
>Mar 28 11:48:09 eal5 pluto[5451]: |    transform ID: 12
>Mar 28 11:48:10 eal5 pluto[5451]: | emitting length of IKEv2 Transform Substructure Payload: 8
>Mar 28 11:48:10 eal5 pluto[5451]: | *****emit IKEv2 Transform Substructure Payload:
>Mar 28 11:48:10 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_T
>Mar 28 11:48:10 eal5 pluto[5451]: |    transform type: 3
>Mar 28 11:48:10 eal5 pluto[5451]: |    transform ID: 2
>Mar 28 11:48:10 eal5 pluto[5451]: | emitting length of IKEv2 Transform Substructure Payload: 8
>Mar 28 11:48:10 eal5 pluto[5451]: | *****emit IKEv2 Transform Substructure Payload:
>Mar 28 11:48:10 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_T
>Mar 28 11:48:10 eal5 pluto[5451]: |    transform type: 2
>Mar 28 11:48:10 eal5 pluto[5451]: |    transform ID: 2
>Mar 28 11:48:10 eal5 pluto[5451]: | emitting length of IKEv2 Transform Substructure Payload: 8
>Mar 28 11:48:10 eal5 pluto[5451]: | *****emit IKEv2 Transform Substructure Payload:
>Mar 28 11:48:10 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_NONE
>Mar 28 11:48:10 eal5 pluto[5451]: |    transform type: 4
>Mar 28 11:48:10 eal5 pluto[5451]: |    transform ID: 5
>Mar 28 11:48:10 eal5 pluto[5451]: | emitting length of IKEv2 Transform Substructure Payload: 8
>Mar 28 11:48:10 eal5 pluto[5451]: | emitting length of IKEv2 Proposal Substructure Payload: 40
>Mar 28 11:48:10 eal5 pluto[5451]: | ****emit IKEv2 Proposal Substructure Payload:
>Mar 28 11:48:10 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_P
>Mar 28 11:48:10 eal5 pluto[5451]: |    prop #: 2
>Mar 28 11:48:10 eal5 pluto[5451]: |    proto ID: 1
>Mar 28 11:48:10 eal5 pluto[5451]: |    spi size: 0
>Mar 28 11:48:10 eal5 pluto[5451]: |    # transforms: 4
>Mar 28 11:48:10 eal5 pluto[5451]: | *****emit IKEv2 Transform Substructure Payload:
>Mar 28 11:48:10 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_T
>Mar 28 11:48:10 eal5 pluto[5451]: |    transform type: 1
>Mar 28 11:48:10 eal5 pluto[5451]: |    transform ID: 12
>Mar 28 11:48:10 eal5 pluto[5451]: | emitting length of IKEv2 Transform Substructure Payload: 8
>Mar 28 11:48:10 eal5 pluto[5451]: | *****emit IKEv2 Transform Substructure Payload:
>Mar 28 11:48:10 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_T
>Mar 28 11:48:10 eal5 pluto[5451]: |    transform type: 3
>Mar 28 11:48:10 eal5 pluto[5451]: |    transform ID: 2
>Mar 28 11:48:10 eal5 pluto[5451]: | emitting length of IKEv2 Transform Substructure Payload: 8
>Mar 28 11:48:10 eal5 pluto[5451]: | *****emit IKEv2 Transform Substructure Payload:
>Mar 28 11:48:10 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_T
>Mar 28 11:48:10 eal5 pluto[5451]: |    transform type: 2
>Mar 28 11:48:10 eal5 pluto[5451]: |    transform ID: 1
>Mar 28 11:48:10 eal5 pluto[5451]: | emitting length of IKEv2 Transform Substructure Payload: 8
>Mar 28 11:48:11 eal5 pluto[5451]: | *****emit IKEv2 Transform Substructure Payload:
>Mar 28 11:48:11 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_NONE
>Mar 28 11:48:11 eal5 pluto[5451]: |    transform type: 4
>Mar 28 11:48:11 eal5 pluto[5451]: |    transform ID: 5
>Mar 28 11:48:11 eal5 pluto[5451]: | emitting length of IKEv2 Transform Substructure Payload: 8
>Mar 28 11:48:11 eal5 pluto[5451]: | emitting length of IKEv2 Proposal Substructure Payload: 40
>Mar 28 11:48:11 eal5 pluto[5451]: | ****emit IKEv2 Proposal Substructure Payload:
>Mar 28 11:48:11 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_P
>Mar 28 11:48:11 eal5 pluto[5451]: |    prop #: 3
>Mar 28 11:48:11 eal5 pluto[5451]: |    proto ID: 1
>Mar 28 11:48:11 eal5 pluto[5451]: |    spi size: 0
>Mar 28 11:48:11 eal5 pluto[5451]: |    # transforms: 4
>Mar 28 11:48:11 eal5 pluto[5451]: | *****emit IKEv2 Transform Substructure Payload:
>Mar 28 11:48:11 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_T
>Mar 28 11:48:11 eal5 pluto[5451]: |    transform type: 1
>Mar 28 11:48:11 eal5 pluto[5451]: |    transform ID: 3
>Mar 28 11:48:11 eal5 pluto[5451]: | emitting length of IKEv2 Transform Substructure Payload: 8
>Mar 28 11:48:11 eal5 pluto[5451]: | *****emit IKEv2 Transform Substructure Payload:
>Mar 28 11:48:11 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_T
>Mar 28 11:48:11 eal5 pluto[5451]: |    transform type: 3
>Mar 28 11:48:11 eal5 pluto[5451]: |    transform ID: 2
>Mar 28 11:48:11 eal5 pluto[5451]: | emitting length of IKEv2 Transform Substructure Payload: 8
>Mar 28 11:48:11 eal5 pluto[5451]: | *****emit IKEv2 Transform Substructure Payload:
>Mar 28 11:48:11 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_T
>Mar 28 11:48:11 eal5 pluto[5451]: |    transform type: 2
>Mar 28 11:48:11 eal5 pluto[5451]: |    transform ID: 2
>Mar 28 11:48:11 eal5 pluto[5451]: | emitting length of IKEv2 Transform Substructure Payload: 8
>Mar 28 11:48:11 eal5 pluto[5451]: | *****emit IKEv2 Transform Substructure Payload:
>Mar 28 11:48:11 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_NONE
>Mar 28 11:48:11 eal5 pluto[5451]: |    transform type: 4
>Mar 28 11:48:11 eal5 pluto[5451]: |    transform ID: 5
>Mar 28 11:48:11 eal5 pluto[5451]: | emitting length of IKEv2 Transform Substructure Payload: 8
>Mar 28 11:48:11 eal5 pluto[5451]: | emitting length of IKEv2 Proposal Substructure Payload: 40
>Mar 28 11:48:11 eal5 pluto[5451]: | ****emit IKEv2 Proposal Substructure Payload:
>Mar 28 11:48:11 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_P
>Mar 28 11:48:11 eal5 pluto[5451]: |    prop #: 4
>Mar 28 11:48:11 eal5 pluto[5451]: |    proto ID: 1
>Mar 28 11:48:11 eal5 pluto[5451]: |    spi size: 0
>Mar 28 11:48:11 eal5 pluto[5451]: |    # transforms: 4
>Mar 28 11:48:12 eal5 pluto[5451]: | *****emit IKEv2 Transform Substructure Payload:
>Mar 28 11:48:12 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_T
>Mar 28 11:48:12 eal5 pluto[5451]: |    transform type: 1
>Mar 28 11:48:12 eal5 pluto[5451]: |    transform ID: 3
>Mar 28 11:48:12 eal5 pluto[5451]: | emitting length of IKEv2 Transform Substructure Payload: 8
>Mar 28 11:48:12 eal5 pluto[5451]: | *****emit IKEv2 Transform Substructure Payload:
>Mar 28 11:48:12 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_T
>Mar 28 11:48:12 eal5 pluto[5451]: |    transform type: 3
>Mar 28 11:48:12 eal5 pluto[5451]: |    transform ID: 2
>Mar 28 11:48:12 eal5 pluto[5451]: | emitting length of IKEv2 Transform Substructure Payload: 8
>Mar 28 11:48:12 eal5 pluto[5451]: | *****emit IKEv2 Transform Substructure Payload:
>Mar 28 11:48:12 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_T
>Mar 28 11:48:12 eal5 pluto[5451]: |    transform type: 2
>Mar 28 11:48:12 eal5 pluto[5451]: |    transform ID: 1
>Mar 28 11:48:12 eal5 pluto[5451]: | emitting length of IKEv2 Transform Substructure Payload: 8
>Mar 28 11:48:12 eal5 pluto[5451]: | *****emit IKEv2 Transform Substructure Payload:
>Mar 28 11:48:12 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_NONE
>Mar 28 11:48:12 eal5 pluto[5451]: |    transform type: 4
>Mar 28 11:48:12 eal5 pluto[5451]: |    transform ID: 5
>Mar 28 11:48:12 eal5 pluto[5451]: | emitting length of IKEv2 Transform Substructure Payload: 8
>Mar 28 11:48:12 eal5 pluto[5451]: | emitting length of IKEv2 Proposal Substructure Payload: 40
>Mar 28 11:48:12 eal5 pluto[5451]: | ****emit IKEv2 Proposal Substructure Payload:
>Mar 28 11:48:12 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_P
>Mar 28 11:48:12 eal5 pluto[5451]: |    prop #: 5
>Mar 28 11:48:12 eal5 pluto[5451]: |    proto ID: 1
>Mar 28 11:48:12 eal5 pluto[5451]: |    spi size: 0
>Mar 28 11:48:12 eal5 pluto[5451]: |    # transforms: 4
>Mar 28 11:48:12 eal5 pluto[5451]: | *****emit IKEv2 Transform Substructure Payload:
>Mar 28 11:48:12 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_T
>Mar 28 11:48:12 eal5 pluto[5451]: |    transform type: 1
>Mar 28 11:48:12 eal5 pluto[5451]: |    transform ID: 3
>Mar 28 11:48:12 eal5 pluto[5451]: | emitting length of IKEv2 Transform Substructure Payload: 8
>Mar 28 11:48:12 eal5 pluto[5451]: | *****emit IKEv2 Transform Substructure Payload:
>Mar 28 11:48:12 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_T
>Mar 28 11:48:12 eal5 pluto[5451]: |    transform type: 3
>Mar 28 11:48:12 eal5 pluto[5451]: |    transform ID: 2
>Mar 28 11:48:12 eal5 pluto[5451]: | emitting length of IKEv2 Transform Substructure Payload: 8
>Mar 28 11:48:12 eal5 pluto[5451]: | *****emit IKEv2 Transform Substructure Payload:
>Mar 28 11:48:12 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_T
>Mar 28 11:48:13 eal5 pluto[5451]: |    transform type: 2
>Mar 28 11:48:13 eal5 pluto[5451]: |    transform ID: 2
>Mar 28 11:48:13 eal5 pluto[5451]: | emitting length of IKEv2 Transform Substructure Payload: 8
>Mar 28 11:48:13 eal5 pluto[5451]: | *****emit IKEv2 Transform Substructure Payload:
>Mar 28 11:48:13 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_NONE
>Mar 28 11:48:13 eal5 pluto[5451]: |    transform type: 4
>Mar 28 11:48:13 eal5 pluto[5451]: |    transform ID: 2
>Mar 28 11:48:13 eal5 pluto[5451]: | emitting length of IKEv2 Transform Substructure Payload: 8
>Mar 28 11:48:13 eal5 pluto[5451]: | emitting length of IKEv2 Proposal Substructure Payload: 40
>Mar 28 11:48:13 eal5 pluto[5451]: | ****emit IKEv2 Proposal Substructure Payload:
>Mar 28 11:48:13 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_P
>Mar 28 11:48:13 eal5 pluto[5451]: |    prop #: 6
>Mar 28 11:48:13 eal5 pluto[5451]: |    proto ID: 1
>Mar 28 11:48:13 eal5 pluto[5451]: |    spi size: 0
>Mar 28 11:48:13 eal5 pluto[5451]: |    # transforms: 4
>Mar 28 11:48:13 eal5 pluto[5451]: | *****emit IKEv2 Transform Substructure Payload:
>Mar 28 11:48:13 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_T
>Mar 28 11:48:13 eal5 pluto[5451]: |    transform type: 1
>Mar 28 11:48:13 eal5 pluto[5451]: |    transform ID: 3
>Mar 28 11:48:13 eal5 pluto[5451]: | emitting length of IKEv2 Transform Substructure Payload: 8
>Mar 28 11:48:13 eal5 pluto[5451]: | *****emit IKEv2 Transform Substructure Payload:
>Mar 28 11:48:13 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_T
>Mar 28 11:48:13 eal5 pluto[5451]: |    transform type: 3
>Mar 28 11:48:13 eal5 pluto[5451]: |    transform ID: 2
>Mar 28 11:48:13 eal5 pluto[5451]: | emitting length of IKEv2 Transform Substructure Payload: 8
>Mar 28 11:48:13 eal5 pluto[5451]: | *****emit IKEv2 Transform Substructure Payload:
>Mar 28 11:48:13 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_T
>Mar 28 11:48:13 eal5 pluto[5451]: |    transform type: 2
>Mar 28 11:48:13 eal5 pluto[5451]: |    transform ID: 1
>Mar 28 11:48:13 eal5 pluto[5451]: | emitting length of IKEv2 Transform Substructure Payload: 8
>Mar 28 11:48:13 eal5 pluto[5451]: | *****emit IKEv2 Transform Substructure Payload:
>Mar 28 11:48:13 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_NONE
>Mar 28 11:48:13 eal5 pluto[5451]: |    transform type: 4
>Mar 28 11:48:13 eal5 pluto[5451]: |    transform ID: 2
>Mar 28 11:48:13 eal5 pluto[5451]: | emitting length of IKEv2 Transform Substructure Payload: 8
>Mar 28 11:48:13 eal5 pluto[5451]: | emitting length of IKEv2 Proposal Substructure Payload: 40
>Mar 28 11:48:13 eal5 pluto[5451]: | ****emit IKEv2 Proposal Substructure Payload:
>Mar 28 11:48:13 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_P
>Mar 28 11:48:14 eal5 pluto[5451]: |    prop #: 7
>Mar 28 11:48:14 eal5 pluto[5451]: |    proto ID: 1
>Mar 28 11:48:14 eal5 pluto[5451]: |    spi size: 0
>Mar 28 11:48:14 eal5 pluto[5451]: |    # transforms: 4
>Mar 28 11:48:14 eal5 pluto[5451]: | *****emit IKEv2 Transform Substructure Payload:
>Mar 28 11:48:14 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_T
>Mar 28 11:48:14 eal5 pluto[5451]: |    transform type: 1
>Mar 28 11:48:14 eal5 pluto[5451]: |    transform ID: 12
>Mar 28 11:48:14 eal5 pluto[5451]: | emitting length of IKEv2 Transform Substructure Payload: 8
>Mar 28 11:48:14 eal5 pluto[5451]: | *****emit IKEv2 Transform Substructure Payload:
>Mar 28 11:48:14 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_T
>Mar 28 11:48:14 eal5 pluto[5451]: |    transform type: 3
>Mar 28 11:48:14 eal5 pluto[5451]: |    transform ID: 2
>Mar 28 11:48:14 eal5 pluto[5451]: | emitting length of IKEv2 Transform Substructure Payload: 8
>Mar 28 11:48:14 eal5 pluto[5451]: | *****emit IKEv2 Transform Substructure Payload:
>Mar 28 11:48:14 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_T
>Mar 28 11:48:14 eal5 pluto[5451]: |    transform type: 2
>Mar 28 11:48:14 eal5 pluto[5451]: |    transform ID: 2
>Mar 28 11:48:14 eal5 pluto[5451]: | emitting length of IKEv2 Transform Substructure Payload: 8
>Mar 28 11:48:14 eal5 pluto[5451]: | *****emit IKEv2 Transform Substructure Payload:
>Mar 28 11:48:14 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_NONE
>Mar 28 11:48:14 eal5 pluto[5451]: |    transform type: 4
>Mar 28 11:48:14 eal5 pluto[5451]: |    transform ID: 14
>Mar 28 11:48:14 eal5 pluto[5451]: | emitting length of IKEv2 Transform Substructure Payload: 8
>Mar 28 11:48:14 eal5 pluto[5451]: | emitting length of IKEv2 Proposal Substructure Payload: 40
>Mar 28 11:48:14 eal5 pluto[5451]: | ****emit IKEv2 Proposal Substructure Payload:
>Mar 28 11:48:14 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_P
>Mar 28 11:48:14 eal5 pluto[5451]: |    prop #: 8
>Mar 28 11:48:14 eal5 pluto[5451]: |    proto ID: 1
>Mar 28 11:48:14 eal5 pluto[5451]: |    spi size: 0
>Mar 28 11:48:14 eal5 pluto[5451]: |    # transforms: 4
>Mar 28 11:48:14 eal5 pluto[5451]: | *****emit IKEv2 Transform Substructure Payload:
>Mar 28 11:48:14 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_T
>Mar 28 11:48:14 eal5 pluto[5451]: |    transform type: 1
>Mar 28 11:48:14 eal5 pluto[5451]: |    transform ID: 12
>Mar 28 11:48:14 eal5 pluto[5451]: | emitting length of IKEv2 Transform Substructure Payload: 8
>Mar 28 11:48:14 eal5 pluto[5451]: | *****emit IKEv2 Transform Substructure Payload:
>Mar 28 11:48:14 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_T
>Mar 28 11:48:15 eal5 pluto[5451]: |    transform type: 3
>Mar 28 11:48:15 eal5 pluto[5451]: |    transform ID: 2
>Mar 28 11:48:15 eal5 pluto[5451]: | emitting length of IKEv2 Transform Substructure Payload: 8
>Mar 28 11:48:15 eal5 pluto[5451]: | *****emit IKEv2 Transform Substructure Payload:
>Mar 28 11:48:15 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_T
>Mar 28 11:48:15 eal5 pluto[5451]: |    transform type: 2
>Mar 28 11:48:15 eal5 pluto[5451]: |    transform ID: 1
>Mar 28 11:48:15 eal5 pluto[5451]: | emitting length of IKEv2 Transform Substructure Payload: 8
>Mar 28 11:48:15 eal5 pluto[5451]: | *****emit IKEv2 Transform Substructure Payload:
>Mar 28 11:48:15 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_NONE
>Mar 28 11:48:15 eal5 pluto[5451]: |    transform type: 4
>Mar 28 11:48:15 eal5 pluto[5451]: |    transform ID: 14
>Mar 28 11:48:15 eal5 pluto[5451]: | emitting length of IKEv2 Transform Substructure Payload: 8
>Mar 28 11:48:15 eal5 pluto[5451]: | emitting length of IKEv2 Proposal Substructure Payload: 40
>Mar 28 11:48:15 eal5 pluto[5451]: | ****emit IKEv2 Proposal Substructure Payload:
>Mar 28 11:48:15 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_P
>Mar 28 11:48:15 eal5 pluto[5451]: |    prop #: 9
>Mar 28 11:48:15 eal5 pluto[5451]: |    proto ID: 1
>Mar 28 11:48:15 eal5 pluto[5451]: |    spi size: 0
>Mar 28 11:48:15 eal5 pluto[5451]: |    # transforms: 4
>Mar 28 11:48:15 eal5 pluto[5451]: | *****emit IKEv2 Transform Substructure Payload:
>Mar 28 11:48:15 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_T
>Mar 28 11:48:15 eal5 pluto[5451]: |    transform type: 1
>Mar 28 11:48:15 eal5 pluto[5451]: |    transform ID: 3
>Mar 28 11:48:15 eal5 pluto[5451]: | emitting length of IKEv2 Transform Substructure Payload: 8
>Mar 28 11:48:15 eal5 pluto[5451]: | *****emit IKEv2 Transform Substructure Payload:
>Mar 28 11:48:15 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_T
>Mar 28 11:48:15 eal5 pluto[5451]: |    transform type: 3
>Mar 28 11:48:15 eal5 pluto[5451]: |    transform ID: 2
>Mar 28 11:48:15 eal5 pluto[5451]: | emitting length of IKEv2 Transform Substructure Payload: 8
>Mar 28 11:48:15 eal5 pluto[5451]: | *****emit IKEv2 Transform Substructure Payload:
>Mar 28 11:48:15 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_T
>Mar 28 11:48:15 eal5 pluto[5451]: |    transform type: 2
>Mar 28 11:48:15 eal5 pluto[5451]: |    transform ID: 2
>Mar 28 11:48:15 eal5 pluto[5451]: | emitting length of IKEv2 Transform Substructure Payload: 8
>Mar 28 11:48:15 eal5 pluto[5451]: | *****emit IKEv2 Transform Substructure Payload:
>Mar 28 11:48:15 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_NONE
>Mar 28 11:48:16 eal5 pluto[5451]: |    transform type: 4
>Mar 28 11:48:16 eal5 pluto[5451]: |    transform ID: 14
>Mar 28 11:48:16 eal5 pluto[5451]: | emitting length of IKEv2 Transform Substructure Payload: 8
>Mar 28 11:48:16 eal5 pluto[5451]: | emitting length of IKEv2 Proposal Substructure Payload: 40
>Mar 28 11:48:16 eal5 pluto[5451]: | ****emit IKEv2 Proposal Substructure Payload:
>Mar 28 11:48:16 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_NONE
>Mar 28 11:48:16 eal5 pluto[5451]: |    prop #: 10
>Mar 28 11:48:16 eal5 pluto[5451]: |    proto ID: 1
>Mar 28 11:48:16 eal5 pluto[5451]: |    spi size: 0
>Mar 28 11:48:16 eal5 pluto[5451]: |    # transforms: 4
>Mar 28 11:48:16 eal5 pluto[5451]: | *****emit IKEv2 Transform Substructure Payload:
>Mar 28 11:48:16 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_T
>Mar 28 11:48:16 eal5 pluto[5451]: |    transform type: 1
>Mar 28 11:48:16 eal5 pluto[5451]: |    transform ID: 3
>Mar 28 11:48:16 eal5 pluto[5451]: | emitting length of IKEv2 Transform Substructure Payload: 8
>Mar 28 11:48:16 eal5 pluto[5451]: | *****emit IKEv2 Transform Substructure Payload:
>Mar 28 11:48:16 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_T
>Mar 28 11:48:16 eal5 pluto[5451]: |    transform type: 3
>Mar 28 11:48:16 eal5 pluto[5451]: |    transform ID: 2
>Mar 28 11:48:16 eal5 pluto[5451]: | emitting length of IKEv2 Transform Substructure Payload: 8
>Mar 28 11:48:16 eal5 pluto[5451]: | *****emit IKEv2 Transform Substructure Payload:
>Mar 28 11:48:16 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_T
>Mar 28 11:48:16 eal5 pluto[5451]: |    transform type: 2
>Mar 28 11:48:16 eal5 pluto[5451]: |    transform ID: 1
>Mar 28 11:48:16 eal5 pluto[5451]: | emitting length of IKEv2 Transform Substructure Payload: 8
>Mar 28 11:48:16 eal5 pluto[5451]: | *****emit IKEv2 Transform Substructure Payload:
>Mar 28 11:48:16 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_NONE
>Mar 28 11:48:16 eal5 pluto[5451]: |    transform type: 4
>Mar 28 11:48:16 eal5 pluto[5451]: |    transform ID: 14
>Mar 28 11:48:16 eal5 pluto[5451]: | emitting length of IKEv2 Transform Substructure Payload: 8
>Mar 28 11:48:16 eal5 pluto[5451]: | emitting length of IKEv2 Proposal Substructure Payload: 40
>Mar 28 11:48:16 eal5 pluto[5451]: | emitting length of IKEv2 Security Association Payload: 404
>Mar 28 11:48:16 eal5 pluto[5451]: | ***emit IKEv2 Key Exchange Payload:
>Mar 28 11:48:16 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_v2Ni
>Mar 28 11:48:16 eal5 pluto[5451]: |    transform type: 5
>Mar 28 11:48:16 eal5 pluto[5451]: | emitting 192 raw bytes of ikev2 g^x into IKEv2 Key Exchange Payload
>Mar 28 11:48:16 eal5 pluto[5451]: | ikev2 g^x  0d c7 79 95  3a 73 6c 81  e3 12 64 f6  d0 55 61 c1
>Mar 28 11:48:16 eal5 pluto[5451]: | ikev2 g^x  2a ae d5 da  af 26 39 b7  aa be 1b eb  5f 0b c7 46
>Mar 28 11:48:16 eal5 pluto[5451]: | ikev2 g^x  51 ad 8b 29  47 0f 33 09  94 98 72 2d  fd b9 0e 4e
>Mar 28 11:48:17 eal5 pluto[5451]: | ikev2 g^x  7a ef 5a ce  16 a8 57 61  68 d9 48 90  d2 53 cc 0d
>Mar 28 11:48:17 eal5 pluto[5451]: | ikev2 g^x  0c 01 41 fe  b3 e7 9d 5a  ab 75 b5 66  9e 2d 2a fc
>Mar 28 11:48:17 eal5 pluto[5451]: | ikev2 g^x  4b 76 cc 76  67 50 4a 1e  92 5b 69 ae  e7 57 bc 85
>Mar 28 11:48:17 eal5 pluto[5451]: | ikev2 g^x  f1 88 b7 9b  32 0d 09 df  31 72 1d d7  c7 26 92 a5
>Mar 28 11:48:17 eal5 pluto[5451]: | ikev2 g^x  e4 06 8d b9  78 f0 68 75  d5 00 bd 93  55 ec 63 34
>Mar 28 11:48:17 eal5 pluto[5451]: | ikev2 g^x  d7 3d 7b fc  3e cb 32 e0  3a f3 dc 8c  f8 33 f0 e7
>Mar 28 11:48:17 eal5 pluto[5451]: | ikev2 g^x  bf df 8e 9b  33 89 ba 80  b3 1e 4b 64  61 51 b5 e4
>Mar 28 11:48:17 eal5 pluto[5451]: | ikev2 g^x  25 87 4b 08  7e 3a a1 da  a6 83 da 46  31 a1 1a 31
>Mar 28 11:48:17 eal5 pluto[5451]: | ikev2 g^x  de c5 75 a4  23 b2 99 b1  61 44 34 ee  78 0b 40 8b
>Mar 28 11:48:17 eal5 pluto[5451]: | emitting length of IKEv2 Key Exchange Payload: 200
>Mar 28 11:48:17 eal5 pluto[5451]: | ***emit IKEv2 Nonce Payload:
>Mar 28 11:48:17 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_v2V
>Mar 28 11:48:17 eal5 pluto[5451]: |    critical bit: Payload-Critical
>Mar 28 11:48:17 eal5 pluto[5451]: | emitting 16 raw bytes of IKEv2 nonce into IKEv2 Nonce Payload
>Mar 28 11:48:17 eal5 pluto[5451]: | IKEv2 nonce  4f 56 94 e7  61 09 80 3d  c9 f7 f9 fe  27 01 d7 73
>Mar 28 11:48:17 eal5 pluto[5451]: | emitting length of IKEv2 Nonce Payload: 20
>Mar 28 11:48:17 eal5 pluto[5451]: | ***emit ISAKMP Vendor ID Payload:
>Mar 28 11:48:17 eal5 pluto[5451]: |    next payload type: ISAKMP_NEXT_NONE
>Mar 28 11:48:17 eal5 pluto[5451]: | emitting 12 raw bytes of Vendor ID into ISAKMP Vendor ID Payload
>Mar 28 11:48:17 eal5 pluto[5451]: | Vendor ID  4f 45 5a 44  7e 4d 54 7d  6d 41 6e 6c
>Mar 28 11:48:17 eal5 pluto[5451]: | emitting length of ISAKMP Vendor ID Payload: 16
>Mar 28 11:48:17 eal5 pluto[5451]: | emitting length of ISAKMP Message: 668
>Mar 28 11:48:17 eal5 pluto[5451]: | sending 668 bytes for ikev2_parent_outI1_common through eth0:500 to 9.3.192.210:500 (using #1)
>Mar 28 11:48:17 eal5 pluto[5451]: |   05 be fe 21  e5 62 02 f0  00 00 00 00  00 00 00 00
>Mar 28 11:48:17 eal5 pluto[5451]: |   21 20 22 08  00 00 00 00  00 00 02 9c  22 80 01 94
>Mar 28 11:48:17 eal5 pluto[5451]: |   02 00 00 28  01 01 00 04  03 00 00 08  01 00 00 0c
>Mar 28 11:48:17 eal5 pluto[5451]: |   03 00 00 08  03 00 00 02  03 00 00 08  02 00 00 02
>Mar 28 11:48:17 eal5 pluto[5451]: |   00 00 00 08  04 00 00 05  02 00 00 28  02 01 00 04
>Mar 28 11:48:17 eal5 pluto[5451]: |   03 00 00 08  01 00 00 0c  03 00 00 08  03 00 00 02
>Mar 28 11:48:17 eal5 pluto[5451]: |   03 00 00 08  02 00 00 01  00 00 00 08  04 00 00 05
>Mar 28 11:48:17 eal5 pluto[5451]: |   02 00 00 28  03 01 00 04  03 00 00 08  01 00 00 03
>Mar 28 11:48:17 eal5 pluto[5451]: |   03 00 00 08  03 00 00 02  03 00 00 08  02 00 00 02
>Mar 28 11:48:17 eal5 pluto[5451]: |   00 00 00 08  04 00 00 05  02 00 00 28  04 01 00 04
>Mar 28 11:48:17 eal5 pluto[5451]: |   03 00 00 08  01 00 00 03  03 00 00 08  03 00 00 02
>Mar 28 11:48:17 eal5 pluto[5451]: |   03 00 00 08  02 00 00 01  00 00 00 08  04 00 00 05
>Mar 28 11:48:17 eal5 pluto[5451]: |   02 00 00 28  05 01 00 04  03 00 00 08  01 00 00 03
>Mar 28 11:48:17 eal5 pluto[5451]: |   03 00 00 08  03 00 00 02  03 00 00 08  02 00 00 02
>Mar 28 11:48:17 eal5 pluto[5451]: |   00 00 00 08  04 00 00 02  02 00 00 28  06 01 00 04
>Mar 28 11:48:18 eal5 pluto[5451]: |   03 00 00 08  01 00 00 03  03 00 00 08  03 00 00 02
>Mar 28 11:48:18 eal5 pluto[5451]: |   03 00 00 08  02 00 00 01  00 00 00 08  04 00 00 02
>Mar 28 11:48:18 eal5 pluto[5451]: |   02 00 00 28  07 01 00 04  03 00 00 08  01 00 00 0c
>Mar 28 11:48:18 eal5 pluto[5451]: |   03 00 00 08  03 00 00 02  03 00 00 08  02 00 00 02
>Mar 28 11:48:18 eal5 pluto[5451]: |   00 00 00 08  04 00 00 0e  02 00 00 28  08 01 00 04
>Mar 28 11:48:18 eal5 pluto[5451]: |   03 00 00 08  01 00 00 0c  03 00 00 08  03 00 00 02
>Mar 28 11:48:18 eal5 pluto[5451]: |   03 00 00 08  02 00 00 01  00 00 00 08  04 00 00 0e
>Mar 28 11:48:18 eal5 pluto[5451]: |   02 00 00 28  09 01 00 04  03 00 00 08  01 00 00 03
>Mar 28 11:48:18 eal5 pluto[5451]: |   03 00 00 08  03 00 00 02  03 00 00 08  02 00 00 02
>Mar 28 11:48:18 eal5 pluto[5451]: |   00 00 00 08  04 00 00 0e  00 00 00 28  0a 01 00 04
>Mar 28 11:48:18 eal5 pluto[5451]: |   03 00 00 08  01 00 00 03  03 00 00 08  03 00 00 02
>Mar 28 11:48:18 eal5 pluto[5451]: |   03 00 00 08  02 00 00 01  00 00 00 08  04 00 00 0e
>Mar 28 11:48:18 eal5 pluto[5451]: |   28 00 00 c8  00 05 00 00  0d c7 79 95  3a 73 6c 81
>Mar 28 11:48:18 eal5 pluto[5451]: |   e3 12 64 f6  d0 55 61 c1  2a ae d5 da  af 26 39 b7
>Mar 28 11:48:18 eal5 pluto[5451]: |   aa be 1b eb  5f 0b c7 46  51 ad 8b 29  47 0f 33 09
>Mar 28 11:48:18 eal5 pluto[5451]: |   94 98 72 2d  fd b9 0e 4e  7a ef 5a ce  16 a8 57 61
>Mar 28 11:48:18 eal5 pluto[5451]: |   68 d9 48 90  d2 53 cc 0d  0c 01 41 fe  b3 e7 9d 5a
>Mar 28 11:48:18 eal5 pluto[5451]: |   ab 75 b5 66  9e 2d 2a fc  4b 76 cc 76  67 50 4a 1e
>Mar 28 11:48:18 eal5 pluto[5451]: |   92 5b 69 ae  e7 57 bc 85  f1 88 b7 9b  32 0d 09 df
>Mar 28 11:48:18 eal5 pluto[5451]: |   31 72 1d d7  c7 26 92 a5  e4 06 8d b9  78 f0 68 75
>Mar 28 11:48:18 eal5 pluto[5451]: |   d5 00 bd 93  55 ec 63 34  d7 3d 7b fc  3e cb 32 e0
>Mar 28 11:48:18 eal5 pluto[5451]: |   3a f3 dc 8c  f8 33 f0 e7  bf df 8e 9b  33 89 ba 80
>Mar 28 11:48:18 eal5 pluto[5451]: |   b3 1e 4b 64  61 51 b5 e4  25 87 4b 08  7e 3a a1 da
>Mar 28 11:48:18 eal5 pluto[5451]: |   a6 83 da 46  31 a1 1a 31  de c5 75 a4  23 b2 99 b1
>Mar 28 11:48:18 eal5 pluto[5451]: |   61 44 34 ee  78 0b 40 8b  2b 80 00 14  4f 56 94 e7
>Mar 28 11:48:18 eal5 pluto[5451]: |   61 09 80 3d  c9 f7 f9 fe  27 01 d7 73  00 00 00 10
>Mar 28 11:48:18 eal5 pluto[5451]: |   4f 45 5a 44  7e 4d 54 7d  6d 41 6e 6c
>Mar 28 11:48:18 eal5 pluto[5451]: | inserting event EVENT_RETRANSMIT, timeout in 10 seconds for #1
>Mar 28 11:48:18 eal5 pluto[5451]: | complete v2 state transition with STF_OK
>Mar 28 11:48:18 eal5 pluto[5451]: "i386-ppc64" #1: transition from state STATE_IKEv2_START to state STATE_PARENT_I1
>Mar 28 11:48:18 eal5 pluto[5451]: "i386-ppc64" #1: STATE_PARENT_I1: sent v2I1, expected v2R1
>Mar 28 11:48:18 eal5 pluto[5451]: | * processed 1 messages from cryptographic helpers 
>Mar 28 11:48:18 eal5 pluto[5451]: | next event EVENT_PENDING_PHASE2 in 97 seconds

Actions: View

Attachments on bug 438826: 299030 | 299031 | 299289 | 299290 | 299492 | 299493 | 299510 | 300144 | 300178 | 300179