
|  
| *received 120 bytes from 10.29.3.225:500 on eth2 (port=500)
|   9f ab 23 9b  4e 62 6a 86  00 00 00 00  00 00 00 00
|   01 10 02 00  00 00 00 00  00 00 00 78  0d 00 00 38
|   00 00 00 01  00 00 00 01  00 00 00 2c  00 01 00 01
|   00 00 00 24  00 01 00 00  80 0b 00 01  80 0c 70 80
|   80 01 00 07  80 02 00 02  80 03 00 01  80 04 00 01
|   80 0e 01 00  0d 00 00 10  4f 45 55 7d  60 68 41 6e
|   77 73 74 78  00 00 00 14  af ca d7 13  68 a1 f1 c9
|   6b 86 96 fc  77 57 01 00
| **parse ISAKMP Message:
|    initiator cookie:
|   9f ab 23 9b  4e 62 6a 86
|    responder cookie:
|   00 00 00 00  00 00 00 00
|    next payload type: ISAKMP_NEXT_SA
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_IDPROT
|    flags: none
|    message ID:  00 00 00 00
|    length: 120
|  processing version=1.0 packet with exchange type=ISAKMP_XCHG_IDPROT (2)
| got payload 0x2(ISAKMP_NEXT_SA) needed: 0x2 opt: 0x2080
| ***parse ISAKMP Security Association Payload:
|    next payload type: ISAKMP_NEXT_VID
|    length: 56
|    DOI: ISAKMP_DOI_IPSEC
| got payload 0x2000(ISAKMP_NEXT_VID) needed: 0x0 opt: 0x2080
| ***parse ISAKMP Vendor ID Payload:
|    next payload type: ISAKMP_NEXT_VID
|    length: 16
| got payload 0x2000(ISAKMP_NEXT_VID) needed: 0x0 opt: 0x2080
| ***parse ISAKMP Vendor ID Payload:
|    next payload type: ISAKMP_NEXT_NONE
|    length: 20
packet from 10.29.3.225:500: received Vendor ID payload [Openswan (this version) 2.6.24 ]
packet from 10.29.3.225:500: received Vendor ID payload [Dead Peer Detection]
| find_host_connection2 called from main_inI1_outR1, me=10.29.3.195:500 him=10.29.3.225:500 policy=none
| find_host_pair_conn (find_host_connection2): 10.29.3.195:500 10.29.3.225:500 -> hp:none 
| find_host_connection returns empty
| ****parse IPsec DOI SIT:
|    IPsec DOI SIT: SIT_IDENTITY_ONLY
| ****parse ISAKMP Proposal Payload:
|    next payload type: ISAKMP_NEXT_NONE
|    length: 44
|    proposal number: 0
|    protocol ID: PROTO_ISAKMP
|    SPI size: 0
|    number of transforms: 1
| *****parse ISAKMP Transform Payload (ISAKMP):
|    next payload type: ISAKMP_NEXT_NONE
|    length: 36
|    transform number: 0
|    transform ID: KEY_IKE
| ******parse ISAKMP Oakley attribute:
|    af+type: OAKLEY_LIFE_TYPE
|    length/value: 1
| ******parse ISAKMP Oakley attribute:
|    af+type: OAKLEY_LIFE_DURATION
|    length/value: 28800
| ******parse ISAKMP Oakley attribute:
|    af+type: OAKLEY_ENCRYPTION_ALGORITHM
|    length/value: 7
| ******parse ISAKMP Oakley attribute:
|    af+type: OAKLEY_HASH_ALGORITHM
|    length/value: 2
| ******parse ISAKMP Oakley attribute:
|    af+type: OAKLEY_AUTHENTICATION_METHOD
|    length/value: 1
| ******parse ISAKMP Oakley attribute:
|    af+type: OAKLEY_GROUP_DESCRIPTION
|    length/value: 1
| ******parse ISAKMP Oakley attribute:
|    af+type: OAKLEY_KEY_LENGTH
|    length/value: 256
| find_host_connection2 called from main_inI1_outR1, me=10.29.3.195:500 him=%any:500 policy=PSK
| find_host_pair_conn (find_host_connection2): 10.29.3.195:500 %any:500 -> hp:none 
| searching for connection with policy = PSK
| find_host_connection returns empty
packet from 10.29.3.225:500: initial Main Mode message received on 10.29.3.195:500 but no connection has been authorized with policy=PSK
| complete state transition with STF_IGNORE
| * processed 0 messages from cryptographic helpers 
| next event EVENT_PENDING_DDNS in 53 seconds
| next event EVENT_PENDING_DDNS in 53 seconds
|  
| *received whack message
| * processed 0 messages from cryptographic helpers 
| next event EVENT_PENDING_DDNS in 49 seconds
| next event EVENT_PENDING_DDNS in 49 seconds
|  
| *received whack message
| alg_info_parse_str() ealg_buf=AES aalg_buf=SHA1eklen=256  aklen=0
| enum_search_prefix () calling enum_search(0x1435e4, "OAKLEY_AES")
| enum_search_ppfixi () calling enum_search(0x1435e4, "OAKLEY_AES_CBC")
| parser_alg_info_add() ealg_getbyname("AES")=7
| enum_search_prefix () calling enum_search(0x143690, "OAKLEY_SHA1")
| parser_alg_info_add() aalg_getbyname("SHA1")=2
| enum_search_prefix () calling enum_search(0x143a4c, "OAKLEY_GROUP_MODP1536")
| parser_alg_info_add() modp_getbyname("MODP1536")=5
| __alg_info_ike_add() ealg=7 aalg=2 modp_id=5, cnt=1
| Added new connection site_192.168.1.0_24-192.168.123.0_24 with policy PSK+ENCRYPT+TUNNEL+PFS+DONTREKEY+IKEv2ALLOW
| from whack: got --esp=AES256-MD5-96
| alg_info_parse_str() ealg_buf=AES aalg_buf=MD5eklen=256  aklen=96
| enum_search_prefix () calling enum_search(0x142530, "ESP_AES")
| parser_alg_info_add() ealg_getbyname("AES")=12
| enum_search_prefix () calling enum_search(0x143000, "AUTH_ALGORITHM_HMAC_MD5")
| parser_alg_info_add() aalg_getbyname("MD5")=1
| __alg_info_esp_add() ealg=12 aalg=1 cnt=1
| esp string values: AES(12)_256-MD5(1); flags=-strict
| ike (phase1) algorihtm values: AES_CBC(7)_256-SHA1(2)-MODP1536(5); flags=-strict
| counting wild cards for 10.29.3.195 is 0
| counting wild cards for 10.29.3.225 is 0
| alg_info_addref() alg_info->ref_cnt=1
| alg_info_addref() alg_info->ref_cnt=1
| alg_info_addref() alg_info->ref_cnt=2
| alg_info_addref() alg_info->ref_cnt=2
| connect_to_host_pair: 10.29.3.195:500 10.29.3.225:500 -> hp:none 
added connection description "site_192.168.1.0_24-192.168.123.0_24"
| 192.168.1.0/24===10.29.3.195<10.29.3.195>[+S=C]...10.29.3.225<10.29.3.225>[+S=C]===192.168.123.0/24
| ike_life: 28800s; ipsec_life: 86400s; rekey_margin: 540s; rekey_fuzz: 100%; keyingtries: 0; policy: PSK+ENCRYPT+TUNNEL+PFS+DONTREKEY+IKEv2ALLOW
| * processed 0 messages from cryptographic helpers 
| next event EVENT_PENDING_DDNS in 8 seconds
| next event EVENT_PENDING_DDNS in 8 seconds
|  
| *received whack message
| kernel_alg_esp_enc_ok(12,0): alg_id=12, alg_ivlen=128, alg_minbits=128, alg_maxbits=256, res=0, ret=1
| * processed 0 messages from cryptographic helpers 
| next event EVENT_PENDING_DDNS in 5 seconds
| next event EVENT_PENDING_DDNS in 5 seconds
|  
| next event EVENT_PENDING_DDNS in 0 seconds
| *time to handle event
| handling event EVENT_PENDING_DDNS
| event after this is EVENT_SHUNT_SCAN in 0 seconds
| inserting event EVENT_PENDING_DDNS, timeout in 60 seconds
| event added after event EVENT_PENDING_PHASE2
| handling event EVENT_SHUNT_SCAN
| event after this is EVENT_PENDING_PHASE2 in 0 seconds
| inserting event EVENT_SHUNT_SCAN, timeout in 120 seconds
| event added after event EVENT_PENDING_DDNS
| scanning for shunt eroutes
| handling event EVENT_PENDING_PHASE2
| event after this is EVENT_PENDING_DDNS in 60 seconds
| inserting event EVENT_PENDING_PHASE2, timeout in 120 seconds
| event added after event EVENT_PENDING_DDNS
| pending review: connection "site_192.168.1.0_24-192.168.123.0_24" was not up, skipped
| next event EVENT_PENDING_DDNS in 60 seconds
|  
| *received whack message
| processing connection site_192.168.1.0_24-192.168.123.0_24
| kernel_alg_db_new() initial trans_cnt=6
| kernel_alg_db_new() will return p_new->protoid=3, p_new->trans_cnt=1
| kernel_alg_db_new()     trans[0]: transid=12, attr_cnt=2, attrs[0].type=5, attrs[0].val=1
| returning new proposal from esp_info
| creating state object #7 at 0x17fbc8
| processing connection site_192.168.1.0_24-192.168.123.0_24
| ICOOKIE:  56 6b ba 6c  9a 8f df ce
| RCOOKIE:  00 00 00 00  00 00 00 00
| state hash entry 3
| inserting state object #7 on chain 3
| inserting event EVENT_SO_DISCARD, timeout in 0 seconds for #7
| event added at head of queue
| processing connection site_192.168.1.0_24-192.168.123.0_24
| Queuing pending Quick Mode with 10.29.3.225 "site_192.168.1.0_24-192.168.123.0_24"
"site_192.168.1.0_24-192.168.123.0_24" #7: initiating Main Mode
| **emit ISAKMP Message:
|    initiator cookie:
|   56 6b ba 6c  9a 8f df ce
|    responder cookie:
|   00 00 00 00  00 00 00 00
|    next payload type: ISAKMP_NEXT_SA
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_IDPROT
|    flags: none
|    message ID:  00 00 00 00
| ***emit ISAKMP Security Association Payload:
|    next payload type: ISAKMP_NEXT_VID
|    DOI: ISAKMP_DOI_IPSEC
| ****emit IPsec DOI SIT:
|    IPsec DOI SIT: SIT_IDENTITY_ONLY
| out_sa pcn: 0 has 1 valid proposals
| out_sa pcn: 0 pn: 0<1 valid_count: 1 trans_cnt: 1
| ****emit ISAKMP Proposal Payload:
|    next payload type: ISAKMP_NEXT_NONE
|    proposal number: 0
|    protocol ID: PROTO_ISAKMP
|    SPI size: 0
|    number of transforms: 1
| *****emit ISAKMP Transform Payload (ISAKMP):
|    next payload type: ISAKMP_NEXT_NONE
|    transform number: 0
|    transform ID: KEY_IKE
| ******emit ISAKMP Oakley attribute:
|    af+type: OAKLEY_LIFE_TYPE
|    length/value: 1
|     [1 is OAKLEY_LIFE_SECONDS]
| ******emit ISAKMP Oakley attribute:
|    af+type: OAKLEY_LIFE_DURATION
|    length/value: 28800
| ******emit ISAKMP Oakley attribute:
|    af+type: OAKLEY_ENCRYPTION_ALGORITHM
|    length/value: 7
|     [7 is OAKLEY_AES_CBC]
| ******emit ISAKMP Oakley attribute:
|    af+type: OAKLEY_HASH_ALGORITHM
|    length/value: 2
|     [2 is OAKLEY_SHA1]
| ******emit ISAKMP Oakley attribute:
|    af+type: OAKLEY_AUTHENTICATION_METHOD
|    length/value: 1
|     [1 is OAKLEY_PRESHARED_KEY]
| ******emit ISAKMP Oakley attribute:
|    af+type: OAKLEY_GROUP_DESCRIPTION
|    length/value: 5
|     [5 is OAKLEY_GROUP_MODP1536]
| ******emit ISAKMP Oakley attribute:
|    af+type: OAKLEY_KEY_LENGTH
|    length/value: 256
| emitting length of ISAKMP Transform Payload (ISAKMP): 36
| emitting length of ISAKMP Proposal Payload: 44
| emitting length of ISAKMP Security Association Payload: 56
| ***emit ISAKMP Vendor ID Payload:
|    next payload type: ISAKMP_NEXT_VID
| emitting 12 raw bytes of Vendor ID into ISAKMP Vendor ID Payload
| Vendor ID  4f 45 55 7d  60 68 41 6e  77 73 74 78
| emitting length of ISAKMP Vendor ID Payload: 16
| out_vendorid(): sending [Dead Peer Detection]
| ***emit ISAKMP Vendor ID Payload:
|    next payload type: ISAKMP_NEXT_NONE
| emitting 16 raw bytes of V_ID into ISAKMP Vendor ID Payload
| V_ID  af ca d7 13  68 a1 f1 c9  6b 86 96 fc  77 57 01 00
| emitting length of ISAKMP Vendor ID Payload: 20
| nat traversal enabled: 0
| emitting length of ISAKMP Message: 120
| sending 120 bytes for main_outI1 through eth2:500 to 10.29.3.225:500 (using #7)
|   56 6b ba 6c  9a 8f df ce  00 00 00 00  00 00 00 00
|   01 10 02 00  00 00 00 00  00 00 00 78  0d 00 00 38
|   00 00 00 01  00 00 00 01  00 00 00 2c  00 01 00 01
|   00 00 00 24  00 01 00 00  80 0b 00 01  80 0c 70 80
|   80 01 00 07  80 02 00 02  80 03 00 01  80 04 00 05
|   80 0e 01 00  0d 00 00 10  4f 45 55 7d  60 68 41 6e
|   77 73 74 78  00 00 00 14  af ca d7 13  68 a1 f1 c9
|   6b 86 96 fc  77 57 01 00
| deleting event for #7
| inserting event EVENT_RETRANSMIT, timeout in 10 seconds for #7
| event added at head of queue
| * processed 0 messages from cryptographic helpers 
| next event EVENT_RETRANSMIT in 10 seconds for #7
| next event EVENT_RETRANSMIT in 10 seconds for #7
|  
| *received 120 bytes from 10.29.3.225:500 on eth2 (port=500)
|   56 6b ba 6c  9a 8f df ce  a0 d7 07 80  4d 46 90 b6
|   01 10 02 00  00 00 00 00  00 00 00 78  0d 00 00 38
|   00 00 00 01  00 00 00 01  00 00 00 2c  00 01 00 01
|   00 00 00 24  00 01 00 00  80 0b 00 01  80 0c 70 80
|   80 01 00 07  80 02 00 02  80 03 00 01  80 04 00 05
|   80 0e 01 00  0d 00 00 10  4f 45 55 7d  60 68 41 6e
|   77 73 74 78  00 00 00 14  af ca d7 13  68 a1 f1 c9
|   6b 86 96 fc  77 57 01 00
| **parse ISAKMP Message:
|    initiator cookie:
|   56 6b ba 6c  9a 8f df ce
|    responder cookie:
|   a0 d7 07 80  4d 46 90 b6
|    next payload type: ISAKMP_NEXT_SA
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_IDPROT
|    flags: none
|    message ID:  00 00 00 00
|    length: 120
|  processing version=1.0 packet with exchange type=ISAKMP_XCHG_IDPROT (2)
| ICOOKIE:  56 6b ba 6c  9a 8f df ce
| RCOOKIE:  a0 d7 07 80  4d 46 90 b6
| state hash entry 18
| v1 state object not found
| ICOOKIE:  56 6b ba 6c  9a 8f df ce
| RCOOKIE:  00 00 00 00  00 00 00 00
| state hash entry 3
| v1 peer and cookies match on #7, provided msgid 00000000 vs 00000000
| v1 state object #7 found, in STATE_MAIN_I1
| processing connection site_192.168.1.0_24-192.168.123.0_24
| got payload 0x2(ISAKMP_NEXT_SA) needed: 0x2 opt: 0x2080
| ***parse ISAKMP Security Association Payload:
|    next payload type: ISAKMP_NEXT_VID
|    length: 56
|    DOI: ISAKMP_DOI_IPSEC
| got payload 0x2000(ISAKMP_NEXT_VID) needed: 0x0 opt: 0x2080
| ***parse ISAKMP Vendor ID Payload:
|    next payload type: ISAKMP_NEXT_VID
|    length: 16
| got payload 0x2000(ISAKMP_NEXT_VID) needed: 0x0 opt: 0x2080
| ***parse ISAKMP Vendor ID Payload:
|    next payload type: ISAKMP_NEXT_NONE
|    length: 20
"site_192.168.1.0_24-192.168.123.0_24" #7: received Vendor ID payload [Openswan (this version) 2.6.24 ]
"site_192.168.1.0_24-192.168.123.0_24" #7: received Vendor ID payload [Dead Peer Detection]
| ****parse IPsec DOI SIT:
|    IPsec DOI SIT: SIT_IDENTITY_ONLY
| ****parse ISAKMP Proposal Payload:
|    next payload type: ISAKMP_NEXT_NONE
|    length: 44
|    proposal number: 0
|    protocol ID: PROTO_ISAKMP
|    SPI size: 0
|    number of transforms: 1
| *****parse ISAKMP Transform Payload (ISAKMP):
|    next payload type: ISAKMP_NEXT_NONE
|    length: 36
|    transform number: 0
|    transform ID: KEY_IKE
| ******parse ISAKMP Oakley attribute:
|    af+type: OAKLEY_LIFE_TYPE
|    length/value: 1
|    [1 is OAKLEY_LIFE_SECONDS]
| ******parse ISAKMP Oakley attribute:
|    af+type: OAKLEY_LIFE_DURATION
|    length/value: 28800
| ******parse ISAKMP Oakley attribute:
|    af+type: OAKLEY_ENCRYPTION_ALGORITHM
|    length/value: 7
|    [7 is OAKLEY_AES_CBC]
| ike_alg_enc_ok(ealg=7,key_len=0): blocksize=16, keyminlen=128, keydeflen=128, keymaxlen=256, ret=1
| ******parse ISAKMP Oakley attribute:
|    af+type: OAKLEY_HASH_ALGORITHM
|    length/value: 2
|    [2 is OAKLEY_SHA1]
| ******parse ISAKMP Oakley attribute:
|    af+type: OAKLEY_AUTHENTICATION_METHOD
|    length/value: 1
|    [1 is OAKLEY_PRESHARED_KEY]
| started looking for secret for 10.29.3.195->10.29.3.225 of kind PPK_PSK
| actually looking for secret for 10.29.3.195->10.29.3.225 of kind PPK_PSK
| line 1: key type PPK_PSK(10.29.3.195) to type PPK_PSK 
| 1: compared key 10.29.3.225 to 10.29.3.195 / 10.29.3.225 -> 4
| 2: compared key 10.29.3.195 to 10.29.3.195 / 10.29.3.225 -> 12
| line 1: match=12 
| best_match 0>12 best=0x17bdf8 (line=1)
| concluding with best_match=12 best=0x17bdf8 (lineno=1)
| ******parse ISAKMP Oakley attribute:
|    af+type: OAKLEY_GROUP_DESCRIPTION
|    length/value: 5
|    [5 is OAKLEY_GROUP_MODP1536]
| ******parse ISAKMP Oakley attribute:
|    af+type: OAKLEY_KEY_LENGTH
|    length/value: 256
| ike_alg_enc_ok(ealg=7,key_len=256): blocksize=16, keyminlen=128, keydeflen=128, keymaxlen=256, ret=1
| Oakley Transform 0 accepted
| sender checking NAT-t: 0 and 0
| 0: w->pcw_dead: 0 w->pcw_work: 0 cnt: 1
| asking helper 0 to do build_kenonce op on seq: 13 (len=2668, pcw_work=1)
! helper 0 read 2664+4/2668 bytesfd: 7
! helper 0 doing build_kenonce op id: 13
| crypto helper write of request: cnt=2668<wlen=2668.  
| deleting event for #7
| inserting event EVENT_CRYPTO_FAILED, timeout in 300 seconds for #7
| event added after event EVENT_SHUNT_SCAN
| complete state transition with STF_SUSPEND
| * processed 0 messages from cryptographic helpers 
| next event EVENT_PENDING_DDNS in 57 seconds
| next event EVENT_PENDING_DDNS in 57 seconds
! Local DH secret:
!   e7 a1 19 d0  1c 5b a7 ea  27 d7 91 7e  98 4e 66 0c
!   73 12 dd 1a  0d e6 33 79  29 5c 13 bd  41 63 b1 71
! Public DH value sent:
!   72 44 63 4f  d6 ed e6 54  3e 5b 2e 66  45 22 21 c9
!   8a 48 74 f7  9a 90 94 a8  b1 6c ff 94  de af 40 16
!   60 9f 86 9b  cc d5 ef db  05 4c 57 3e  4f dd 55 07
!   6b 49 3e 4f  8e 5d 86 f3  43 01 03 91  61 96 52 bb
!   8f 72 af 69  c8 5f 8b 96  97 75 74 5b  c8 e1 68 7a
!   97 f1 6a 45  05 e1 c4 10  1e d8 4b ce  39 9f 49 9e
!   f1 14 dd 97  be 57 9e 8a  5f 9d 4c b9  bd 97 c1 9a
!   8e a3 1f 3b  df db ad d9  5e ca 96 6a  54 36 d9 b9
!   dd 90 4f 61  cd 30 ba ce  ad 2e 2b e7  6d e9 5e d3
!   e4 30 bc 1c  31 5d 82 15  5a f3 1e db  3c c0 c4 91
!   b8 0a 37 36  46 a6 66 a7  ad 90 d1 0f  2d 39 26 71
!   ce e0 41 94  60 ac aa 1a  d5 82 79 ac  97 0f 70 c6
! Generated nonce:
!   43 9d 69 17  77 d6 22 9f  68 f2 5f f6  a6 c5 b9 47
|  
| helper 0 has finished work (cnt now 1)
| helper 0 replies to id: q#13
| calling callback function 0x328a0
| main inR1_outI2: calculated ke+nonce, sending I2
| processing connection site_192.168.1.0_24-192.168.123.0_24
| **emit ISAKMP Message:
|    initiator cookie:
|   56 6b ba 6c  9a 8f df ce
|    responder cookie:
|   a0 d7 07 80  4d 46 90 b6
|    next payload type: ISAKMP_NEXT_KE
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_IDPROT
|    flags: none
|    message ID:  00 00 00 00
| ***emit ISAKMP Key Exchange Payload:
|    next payload type: ISAKMP_NEXT_NONCE
| emitting 192 raw bytes of keyex value into ISAKMP Key Exchange Payload
| keyex value  72 44 63 4f  d6 ed e6 54  3e 5b 2e 66  45 22 21 c9
| keyex value  8a 48 74 f7  9a 90 94 a8  b1 6c ff 94  de af 40 16
| keyex value  60 9f 86 9b  cc d5 ef db  05 4c 57 3e  4f dd 55 07
| keyex value  6b 49 3e 4f  8e 5d 86 f3  43 01 03 91  61 96 52 bb
| keyex value  8f 72 af 69  c8 5f 8b 96  97 75 74 5b  c8 e1 68 7a
| keyex value  97 f1 6a 45  05 e1 c4 10  1e d8 4b ce  39 9f 49 9e
| keyex value  f1 14 dd 97  be 57 9e 8a  5f 9d 4c b9  bd 97 c1 9a
| keyex value  8e a3 1f 3b  df db ad d9  5e ca 96 6a  54 36 d9 b9
| keyex value  dd 90 4f 61  cd 30 ba ce  ad 2e 2b e7  6d e9 5e d3
| keyex value  e4 30 bc 1c  31 5d 82 15  5a f3 1e db  3c c0 c4 91
| keyex value  b8 0a 37 36  46 a6 66 a7  ad 90 d1 0f  2d 39 26 71
| keyex value  ce e0 41 94  60 ac aa 1a  d5 82 79 ac  97 0f 70 c6
| emitting length of ISAKMP Key Exchange Payload: 196
| ***emit ISAKMP Nonce Payload:
|    next payload type: ISAKMP_NEXT_NONE
| emitting 16 raw bytes of Ni into ISAKMP Nonce Payload
| Ni  43 9d 69 17  77 d6 22 9f  68 f2 5f f6  a6 c5 b9 47
| emitting length of ISAKMP Nonce Payload: 20
| emitting length of ISAKMP Message: 244
| ICOOKIE:  56 6b ba 6c  9a 8f df ce
| RCOOKIE:  00 00 00 00  00 00 00 00
| state hash entry 3
| ICOOKIE:  56 6b ba 6c  9a 8f df ce
| RCOOKIE:  a0 d7 07 80  4d 46 90 b6
| state hash entry 18
| inserting state object #7 on chain 18
| complete state transition with STF_OK
"site_192.168.1.0_24-192.168.123.0_24" #7: transition from state STATE_MAIN_I1 to state STATE_MAIN_I2
| deleting event for #7
| sending reply packet to 10.29.3.225:500 (from port 500)
| sending 244 bytes for STATE_MAIN_I1 through eth2:500 to 10.29.3.225:500 (using #7)
|   56 6b ba 6c  9a 8f df ce  a0 d7 07 80  4d 46 90 b6
|   04 10 02 00  00 00 00 00  00 00 00 f4  0a 00 00 c4
|   72 44 63 4f  d6 ed e6 54  3e 5b 2e 66  45 22 21 c9
|   8a 48 74 f7  9a 90 94 a8  b1 6c ff 94  de af 40 16
|   60 9f 86 9b  cc d5 ef db  05 4c 57 3e  4f dd 55 07
|   6b 49 3e 4f  8e 5d 86 f3  43 01 03 91  61 96 52 bb
|   8f 72 af 69  c8 5f 8b 96  97 75 74 5b  c8 e1 68 7a
|   97 f1 6a 45  05 e1 c4 10  1e d8 4b ce  39 9f 49 9e
|   f1 14 dd 97  be 57 9e 8a  5f 9d 4c b9  bd 97 c1 9a
|   8e a3 1f 3b  df db ad d9  5e ca 96 6a  54 36 d9 b9
|   dd 90 4f 61  cd 30 ba ce  ad 2e 2b e7  6d e9 5e d3
|   e4 30 bc 1c  31 5d 82 15  5a f3 1e db  3c c0 c4 91
|   b8 0a 37 36  46 a6 66 a7  ad 90 d1 0f  2d 39 26 71
|   ce e0 41 94  60 ac aa 1a  d5 82 79 ac  97 0f 70 c6
|   00 00 00 14  43 9d 69 17  77 d6 22 9f  68 f2 5f f6
|   a6 c5 b9 47
| inserting event EVENT_RETRANSMIT, timeout in 10 seconds for #7
| event added at head of queue
"site_192.168.1.0_24-192.168.123.0_24" #7: STATE_MAIN_I2: sent MI2, expecting MR2
| modecfg pull: noquirk policy:push not-client
| phase 1 is done, looking for phase 2 to unpend
| * processed 1 messages from cryptographic helpers 
| next event EVENT_RETRANSMIT in 10 seconds for #7
| next event EVENT_RETRANSMIT in 10 seconds for #7
|  
| *received 244 bytes from 10.29.3.225:500 on eth2 (port=500)
|   56 6b ba 6c  9a 8f df ce  a0 d7 07 80  4d 46 90 b6
|   04 10 02 00  00 00 00 00  00 00 00 f4  0a 00 00 c4
|   35 8f 0e e5  58 28 31 ae  b9 d5 49 e6  8c 6f b5 33
|   3a e2 1f a0  c0 88 f7 27  88 14 1f 17  b1 6a 2d be
|   17 e4 7a 73  17 73 83 65  11 fd 2f 21  2d 1b 63 21
|   4b c1 cb 7f  d4 3a 9a 11  7e c7 1a 18  17 16 9e 54
|   71 1a b8 71  84 8a 2b fe  eb 0d 80 76  df c1 39 7c
|   db be 6d 58  77 a7 01 0a  1e 14 e9 ae  ec 72 18 9b
|   67 42 c7 5a  02 4d 6f 86  32 bf 48 26  38 24 bb a1
|   05 13 ae 25  4c 01 d6 4a  69 ed 51 f2  32 18 45 aa
|   20 82 b7 ea  f7 5d 02 ce  3d 7e 36 1e  8f 5d ca e2
|   a4 2b 67 42  af 5a 60 a4  03 48 df 0f  79 d5 6f ab
|   62 6a 32 80  f4 a7 d5 16  23 21 f5 88  b9 2f 22 89
|   09 66 3b 91  07 67 0d 83  d5 85 d6 5e  56 09 c3 43
|   00 00 00 14  51 40 53 72  38 40 e6 b3  5c d7 87 44
|   ea 3c c0 7d
| **parse ISAKMP Message:
|    initiator cookie:
|   56 6b ba 6c  9a 8f df ce
|    responder cookie:
|   a0 d7 07 80  4d 46 90 b6
|    next payload type: ISAKMP_NEXT_KE
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_IDPROT
|    flags: none
|    message ID:  00 00 00 00
|    length: 244
|  processing version=1.0 packet with exchange type=ISAKMP_XCHG_IDPROT (2)
| ICOOKIE:  56 6b ba 6c  9a 8f df ce
| RCOOKIE:  a0 d7 07 80  4d 46 90 b6
| state hash entry 18
| v1 peer and cookies match on #7, provided msgid 00000000 vs 00000000
| v1 state object #7 found, in STATE_MAIN_I2
| processing connection site_192.168.1.0_24-192.168.123.0_24
| got payload 0x10(ISAKMP_NEXT_KE) needed: 0x410 opt: 0x102080
| ***parse ISAKMP Key Exchange Payload:
|    next payload type: ISAKMP_NEXT_NONCE
|    length: 196
| got payload 0x400(ISAKMP_NEXT_NONCE) needed: 0x400 opt: 0x102080
| ***parse ISAKMP Nonce Payload:
|    next payload type: ISAKMP_NEXT_NONE
|    length: 20
| **emit ISAKMP Message:
|    initiator cookie:
|   56 6b ba 6c  9a 8f df ce
|    responder cookie:
|   a0 d7 07 80  4d 46 90 b6
|    next payload type: ISAKMP_NEXT_ID
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_IDPROT
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  00 00 00 00
| DH public value received:
|   35 8f 0e e5  58 28 31 ae  b9 d5 49 e6  8c 6f b5 33
|   3a e2 1f a0  c0 88 f7 27  88 14 1f 17  b1 6a 2d be
|   17 e4 7a 73  17 73 83 65  11 fd 2f 21  2d 1b 63 21
|   4b c1 cb 7f  d4 3a 9a 11  7e c7 1a 18  17 16 9e 54
|   71 1a b8 71  84 8a 2b fe  eb 0d 80 76  df c1 39 7c
|   db be 6d 58  77 a7 01 0a  1e 14 e9 ae  ec 72 18 9b
|   67 42 c7 5a  02 4d 6f 86  32 bf 48 26  38 24 bb a1
|   05 13 ae 25  4c 01 d6 4a  69 ed 51 f2  32 18 45 aa
|   20 82 b7 ea  f7 5d 02 ce  3d 7e 36 1e  8f 5d ca e2
|   a4 2b 67 42  af 5a 60 a4  03 48 df 0f  79 d5 6f ab
|   62 6a 32 80  f4 a7 d5 16  23 21 f5 88  b9 2f 22 89
|   09 66 3b 91  07 67 0d 83  d5 85 d6 5e  56 09 c3 43
| started looking for secret for 10.29.3.195->10.29.3.225 of kind PPK_PSK
| actually looking for secret for 10.29.3.195->10.29.3.225 of kind PPK_PSK
| line 1: key type PPK_PSK(10.29.3.195) to type PPK_PSK 
| 1: compared key 10.29.3.225 to 10.29.3.195 / 10.29.3.225 -> 4
| 2: compared key 10.29.3.195 to 10.29.3.195 / 10.29.3.225 -> 12
| line 1: match=12 
| best_match 0>12 best=0x17bdf8 (line=1)
| concluding with best_match=12 best=0x17bdf8 (lineno=1)
| parent1 type: 7 group: 5 len: 2668 
| 0: w->pcw_dead: 0 w->pcw_work: 0 cnt: 1
| asking helper 0 to do compute dh+iv op on seq: 14 (len=2668, pcw_work=1)
! helper 0 read 2664+4/2668 bytesfd: 7
! helper 0 doing compute dh+iv op id: 14
! peer's g:   35 8f 0e e5  58 28 31 ae  b9 d5 49 e6  8c 6f b5 33
! peer's g:   3a e2 1f a0  c0 88 f7 27  88 14 1f 17  b1 6a 2d be
! peer's g:   17 e4 7a 73  17 73 83 65  11 fd 2f 21  2d 1b 63 21
! peer's g:   4b c1 cb 7f  d4 3a 9a 11  7e c7 1a 18  17 16 9e 54
! peer's g:   71 1a b8 71  84 8a 2b fe  eb 0d 80 76  df c1 39 7c
! peer's g:   db be 6d 58  77 a7 01 0a  1e 14 e9 ae  ec 72 18 9b
! peer's g:   67 42 c7 5a  02 4d 6f 86  32 bf 48 26  38 24 bb a1
! peer's g:   05 13 ae 25  4c 01 d6 4a  69 ed 51 f2  32 18 45 aa
! peer's g:   20 82 b7 ea  f7 5d 02 ce  3d 7e 36 1e  8f 5d ca e2
! peer's g:   a4 2b 67 42  af 5a 60 a4  03 48 df 0f  79 d5 6f ab
! peer's g:   62 6a 32 80  f4 a7 d5 16  23 21 f5 88  b9 2f 22 89
! peer's g:   09 66 3b 91  07 67 0d 83  d5 85 d6 5e  56 09 c3 43
! long term secret:   e7 a1 19 d0  1c 5b a7 ea  27 d7 91 7e  98 4e 66 0c
! long term secret:   73 12 dd 1a  0d e6 33 79  29 5c 13 bd  41 63 b1 71
| crypto helper write of request: cnt=2668<wlen=2668.  
| deleting event for #7
| inserting event EVENT_CRYPTO_FAILED, timeout in 300 seconds for #7
| event added after event EVENT_SHUNT_SCAN
| complete state transition with STF_SUSPEND
| * processed 0 messages from cryptographic helpers 
| next event EVENT_PENDING_DDNS in 57 seconds
| next event EVENT_PENDING_DDNS in 57 seconds
! calc_dh_shared(): time elapsed (OAKLEY_GROUP_MODP1536): 161589 usec
! DH shared-secret:
!   68 42 c9 1a  37 bc 83 0c  3b b3 84 44  3d 6f 51 ea
!   01 86 df 02  cb 07 a8 cb  89 d4 09 46  a2 e6 4c 88
!   0a a9 4f 00  6d b5 0c 43  68 03 f2 c8  0f f7 b6 5f
!   61 f1 b2 ee  ac 82 17 f7  f6 29 09 1b  48 78 25 1b
!   53 73 d4 13  8a e1 71 c0  51 3b cc 83  5c 32 62 b2
!   89 ea 9a 13  75 20 ee 01  33 ec af 41  07 6d 62 08
!   fe fe b1 69  13 95 05 c7  3a f9 6d 0e  53 14 27 37
!   0e 60 91 d7  b6 60 09 3a  5a 40 12 f9  53 b7 1b f4
!   c0 7c 25 c3  ae d5 cc d3  a4 8c cd be  17 85 43 b0
!   b1 3f e2 ab  d8 41 ba e1  f8 0d 06 a6  9d 0c 1b 3c
!   3c 59 cb 57  c0 3c dc 3d  37 c9 5d 78  16 c0 c6 0b
!   3c 66 de 5a  bc 06 e9 e8  fe 75 fe 65  ca 6e 8a 0a
! Skey inputs (PSK+NI+NR)
! ni:   43 9d 69 17  77 d6 22 9f  68 f2 5f f6  a6 c5 b9 47
! nr:   51 40 53 72  38 40 e6 b3  5c d7 87 44  ea 3c c0 7d
! keyid:   c9 16 1b ab  d2 96 55 56  fc 9c fe 67  7d be 82 8b
! keyid:   bc 76 e3 47
! NSS: end of key computation 
! DH_i:  72 44 63 4f  d6 ed e6 54  3e 5b 2e 66  45 22 21 c9
! DH_i:  8a 48 74 f7  9a 90 94 a8  b1 6c ff 94  de af 40 16
! DH_i:  60 9f 86 9b  cc d5 ef db  05 4c 57 3e  4f dd 55 07
! DH_i:  6b 49 3e 4f  8e 5d 86 f3  43 01 03 91  61 96 52 bb
! DH_i:  8f 72 af 69  c8 5f 8b 96  97 75 74 5b  c8 e1 68 7a
! DH_i:  97 f1 6a 45  05 e1 c4 10  1e d8 4b ce  39 9f 49 9e
! DH_i:  f1 14 dd 97  be 57 9e 8a  5f 9d 4c b9  bd 97 c1 9a
! DH_i:  8e a3 1f 3b  df db ad d9  5e ca 96 6a  54 36 d9 b9
! DH_i:  dd 90 4f 61  cd 30 ba ce  ad 2e 2b e7  6d e9 5e d3
! DH_i:  e4 30 bc 1c  31 5d 82 15  5a f3 1e db  3c c0 c4 91
! DH_i:  b8 0a 37 36  46 a6 66 a7  ad 90 d1 0f  2d 39 26 71
! DH_i:  ce e0 41 94  60 ac aa 1a  d5 82 79 ac  97 0f 70 c6
! DH_r:  35 8f 0e e5  58 28 31 ae  b9 d5 49 e6  8c 6f b5 33
! DH_r:  3a e2 1f a0  c0 88 f7 27  88 14 1f 17  b1 6a 2d be
! DH_r:  17 e4 7a 73  17 73 83 65  11 fd 2f 21  2d 1b 63 21
! DH_r:  4b c1 cb 7f  d4 3a 9a 11  7e c7 1a 18  17 16 9e 54
! DH_r:  71 1a b8 71  84 8a 2b fe  eb 0d 80 76  df c1 39 7c
! DH_r:  db be 6d 58  77 a7 01 0a  1e 14 e9 ae  ec 72 18 9b
! DH_r:  67 42 c7 5a  02 4d 6f 86  32 bf 48 26  38 24 bb a1
! DH_r:  05 13 ae 25  4c 01 d6 4a  69 ed 51 f2  32 18 45 aa
! DH_r:  20 82 b7 ea  f7 5d 02 ce  3d 7e 36 1e  8f 5d ca e2
! DH_r:  a4 2b 67 42  af 5a 60 a4  03 48 df 0f  79 d5 6f ab
! DH_r:  62 6a 32 80  f4 a7 d5 16  23 21 f5 88  b9 2f 22 89
! DH_r:  09 66 3b 91  07 67 0d 83  d5 85 d6 5e  56 09 c3 43
! end of IV generation 
! Skeyid:    c9 16 1b ab  d2 96 55 56  fc 9c fe 67  7d be 82 8b
! Skeyid:    bc 76 e3 47
! Skeyid_d:  38 57 7e 68  43 ea 0f 42  89 75 bf 6f  08 8f c3 b4
! Skeyid_d:  4e b8 1b 61
! Skeyid_a:  4d 82 43 a7  15 53 f9 11  c4 44 95 60  9a e4 a8 c2
! Skeyid_a:  a9 e7 f4 ba
! Skeyid_e:  f4 40 23 4f  3f c5 27 c4  10 81 d9 7a  ef a6 3e 04
! Skeyid_e:  a8 b5 56 f4
! enc key:  68 9a fe 69  3c fe 6f 80  0d d9 13 31  ce 46 63 fc
! enc key:  a9 dc 0a 87  87 b2 0d c1  27 e7 d2 61  51 71 36 2f
! IV:  50 37 6a 42  51 b8 83 3f  d1 98 c1 bc  05 dc cf 2d
! IV:  08 31 26 dd
|  
| helper 0 has finished work (cnt now 1)
| helper 0 replies to id: q#14
| calling callback function 0x3478c
| main inR2_outI3: calculated DH, sending R1
| processing connection site_192.168.1.0_24-192.168.123.0_24
| thinking about whether to send my certificate:
|   I have RSA key: OAKLEY_PRESHARED_KEY cert.type: CERT_NONE 
|   sendcert: CERT_ALWAYSSEND and I did not get a certificate request 
|   so do not send cert.
| I did not send a certificate because digital signatures are not being used. (PSK)
|  I am not sending a certificate request
| ***emit ISAKMP Identification Payload (IPsec DOI):
|    next payload type: ISAKMP_NEXT_HASH
|    ID type: ID_IPV4_ADDR
|    Protocol ID: 0
|    port: 0
| emitting 4 raw bytes of my identity into ISAKMP Identification Payload (IPsec DOI)
| my identity  0a 1d 03 c3
| emitting length of ISAKMP Identification Payload (IPsec DOI): 12
| hashing 52 bytes of SA
| ***emit ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_NONE
| emitting 20 raw bytes of HASH_I into ISAKMP Hash Payload
| HASH_I  33 24 20 c2  11 8a e3 2f  b8 11 a6 b3  3b 18 7b b3
| HASH_I  45 38 93 79
| emitting length of ISAKMP Hash Payload: 24
| encrypting:
|   08 00 00 0c  01 00 00 00  0a 1d 03 c3  00 00 00 18
|   33 24 20 c2  11 8a e3 2f  b8 11 a6 b3  3b 18 7b b3
|   45 38 93 79
| IV:
|   50 37 6a 42  51 b8 83 3f  d1 98 c1 bc  05 dc cf 2d
|   08 31 26 dd
| unpadded size is: 36
| emitting 12 zero bytes of encryption padding into ISAKMP Message
| encrypting 48 using OAKLEY_AES_CBC
| next IV:  f4 1c f2 92  33 65 66 57  5b 60 f1 1f  24 3c 10 ec
| emitting length of ISAKMP Message: 76
| complete state transition with STF_OK
"site_192.168.1.0_24-192.168.123.0_24" #7: transition from state STATE_MAIN_I2 to state STATE_MAIN_I3
| deleting event for #7
| sending reply packet to 10.29.3.225:500 (from port 500)
| sending 76 bytes for STATE_MAIN_I2 through eth2:500 to 10.29.3.225:500 (using #7)
|   56 6b ba 6c  9a 8f df ce  a0 d7 07 80  4d 46 90 b6
|   05 10 02 01  00 00 00 00  00 00 00 4c  d0 87 45 f3
|   dd a7 7c 0d  e9 d8 6e 57  c0 43 a5 b7  56 64 cb a2
|   6d 34 63 5b  a8 1a b6 b1  59 6a fe e4  f4 1c f2 92
|   33 65 66 57  5b 60 f1 1f  24 3c 10 ec
| inserting event EVENT_RETRANSMIT, timeout in 10 seconds for #7
| event added at head of queue
"site_192.168.1.0_24-192.168.123.0_24" #7: STATE_MAIN_I3: sent MI3, expecting MR3
| modecfg pull: noquirk policy:push not-client
| phase 1 is done, looking for phase 2 to unpend
| * processed 1 messages from cryptographic helpers 
| next event EVENT_RETRANSMIT in 10 seconds for #7
| next event EVENT_RETRANSMIT in 10 seconds for #7
|  
| *received 76 bytes from 10.29.3.225:500 on eth2 (port=500)
|   56 6b ba 6c  9a 8f df ce  a0 d7 07 80  4d 46 90 b6
|   05 10 02 01  00 00 00 00  00 00 00 4c  47 0e 19 6d
|   0f 33 1a a2  72 26 3d ce  86 0b f7 1f  98 4b 14 c1
|   37 ce b7 79  e8 99 16 34  2a 26 7b 66  ce 3c 12 ec
|   95 8b b4 5f  fa 61 e4 fb  00 40 55 3c
| **parse ISAKMP Message:
|    initiator cookie:
|   56 6b ba 6c  9a 8f df ce
|    responder cookie:
|   a0 d7 07 80  4d 46 90 b6
|    next payload type: ISAKMP_NEXT_ID
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_IDPROT
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  00 00 00 00
|    length: 76
|  processing version=1.0 packet with exchange type=ISAKMP_XCHG_IDPROT (2)
| ICOOKIE:  56 6b ba 6c  9a 8f df ce
| RCOOKIE:  a0 d7 07 80  4d 46 90 b6
| state hash entry 18
| v1 peer and cookies match on #7, provided msgid 00000000 vs 00000000
| v1 state object #7 found, in STATE_MAIN_I3
| processing connection site_192.168.1.0_24-192.168.123.0_24
| received encrypted packet from 10.29.3.225:500
| decrypting 48 bytes using algorithm OAKLEY_AES_CBC
| decrypted:
|   08 00 00 0c  01 00 00 00  0a 1d 03 e1  0d 00 00 18
|   ed 3c 64 98  04 76 74 bb  e2 6c a4 89  f4 1a 0b 5a
|   56 7f e8 46  00 00 00 09  49 4b 45 76  32 00 00 00
| next IV:  ce 3c 12 ec  95 8b b4 5f  fa 61 e4 fb  00 40 55 3c
| got payload 0x20(ISAKMP_NEXT_ID) needed: 0x120 opt: 0x2080
| ***parse ISAKMP Identification Payload:
|    next payload type: ISAKMP_NEXT_HASH
|    length: 12
|    ID type: ID_IPV4_ADDR
|    DOI specific A: 0
|    DOI specific B: 0
|      obj:   0a 1d 03 e1  0d 00 00 18  ed 3c 64 98
| got payload 0x100(ISAKMP_NEXT_HASH) needed: 0x100 opt: 0x2080
| ***parse ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_VID
|    length: 24
| got payload 0x2000(ISAKMP_NEXT_VID) needed: 0x0 opt: 0x2080
| ***parse ISAKMP Vendor ID Payload:
|    next payload type: ISAKMP_NEXT_NONE
|    length: 9
| removing 3 bytes of padding
"site_192.168.1.0_24-192.168.123.0_24" #7: received Vendor ID payload [CAN-IKEv2]
"site_192.168.1.0_24-192.168.123.0_24" #7: Main mode peer ID is ID_IPV4_ADDR: '10.29.3.225'
| hashing 52 bytes of SA
| authentication succeeded
| complete state transition with STF_OK
"site_192.168.1.0_24-192.168.123.0_24" #7: transition from state STATE_MAIN_I3 to state STATE_MAIN_I4
| deleting event for #7
| inserting event EVENT_SA_REPLACE_IF_USED, timeout in 28027 seconds for #7
| event added after event EVENT_LOG_DAILY
"site_192.168.1.0_24-192.168.123.0_24" #7: STATE_MAIN_I4: ISAKMP SA established {auth=OAKLEY_PRESHARED_KEY cipher=aes_256 prf=oakley_sha group=modp1536}
| ICOOKIE:  56 6b ba 6c  9a 8f df ce
| RCOOKIE:  a0 d7 07 80  4d 46 90 b6
| state hash entry 18
| v1 peer and cookies match on #7, provided msgid 00000000 vs 00000000
| v1 state object #7 found, in STATE_MAIN_I4
"site_192.168.1.0_24-192.168.123.0_24" #7: Dead Peer Detection (RFC 3706): enabled
| state: 7 requesting event none to be deleted by /home/mix/work/dsa3600-ipv6/fruitfarm/product/MB120/work/buildroot/build_arm/openswan-2.6.24/programs/pluto/dpd.c:159
| inserting event EVENT_DPD, timeout in 10 seconds for #7
| event added at head of queue
| modecfg pull: noquirk policy:push not-client
| phase 1 is done, looking for phase 2 to unpend
| unqueuing pending Quick Mode with 10.29.3.225 "site_192.168.1.0_24-192.168.123.0_24" import:admin initiate
| duplicating state object #7
| creating state object #8 at 0x1805d0
| processing connection site_192.168.1.0_24-192.168.123.0_24
| ICOOKIE:  56 6b ba 6c  9a 8f df ce
| RCOOKIE:  a0 d7 07 80  4d 46 90 b6
| state hash entry 18
| inserting state object #8 on chain 18
| inserting event EVENT_SO_DISCARD, timeout in 0 seconds for #8
| event added at head of queue
| kernel_alg_esp_enc_ok(12,0): alg_id=12, alg_ivlen=128, alg_minbits=128, alg_maxbits=256, res=0, ret=1
"site_192.168.1.0_24-192.168.123.0_24" #8: initiating Quick Mode PSK+ENCRYPT+TUNNEL+PFS+DONTREKEY+UP+IKEv2ALLOW {using isakmp#7 msgid:590fb497 proposal=AES(12)_256-MD5(1)_096 pfsgroup=OAKLEY_GROUP_MODP1536}
| 0: w->pcw_dead: 0 w->pcw_work: 0 cnt: 1
| asking helper 0 to do build_kenonce op on seq: 15 (len=2668, pcw_work=1)
| crypto helper write of request: cnt=2668<wlen=2668.  
| deleting event for #8
| inserting event EVENT_CRYPTO_FAILED, timeout in 300 seconds for #8
| event added after event EVENT_SHUNT_SCAN
| * processed 0 messages from cryptographic helpers 
| next event EVENT_DPD in 10 seconds for #7
| next event EVENT_DPD in 10 seconds for #7
! helper 0 read 2664+4/2668 bytesfd: 7
! helper 0 doing build_kenonce op id: 15
! Local DH secret:
!   74 61 09 f5  53 ae bf c3  9b 43 26 91  f6 b7 77 20
!   05 a5 f7 e4  57 88 84 bb  7c b9 99 e6  1d 53 71 5a
! Public DH value sent:
!   fa e4 5a 3b  b0 b4 f9 07  13 6d ea 15  a1 23 57 79
!   56 99 ad b1  dc 15 c9 dd  ec 4d 4f b6  6c d7 65 fe
!   33 a2 78 0e  d1 ef 8c 34  6e f6 40 67  8a 04 85 fc
!   cf 3c f7 9c  75 23 a9 42  93 4d 6e 31  95 34 2e ea
!   70 c5 f0 7a  f1 93 5c be  00 ec 54 1e  04 3a 66 bb
!   8c b2 9c 5d  b7 a3 0d cf  c2 1a 42 f1  0d 29 cf a3
!   9c 32 a0 1c  53 ba 7c b0  e8 a2 bc 5f  39 e3 86 95
!   d1 1d e8 ce  2c d1 dd 85  28 b1 10 73  90 34 1d e3
!   c0 29 43 36  77 73 06 35  41 15 bd 6d  24 b4 c1 e0
!   22 55 7c 4f  05 b7 b8 68  9a fc e1 ef  c4 71 67 7e
!   c0 30 cd 2a  d2 f8 22 96  8a 99 d5 fd  e2 ea fc 35
!   b9 a2 cb e1  64 a4 32 51  97 eb 69 bd  7b 21 dd 6b
! Generated nonce:
!   8e 0b cc e4  ff 06 70 c5  29 04 fd 11  66 e7 02 28
|  
| helper 0 has finished work (cnt now 1)
| helper 0 replies to id: q#15
| calling callback function 0x39bec
| quick outI1: calculated ke+nonce, sending I1
| processing connection site_192.168.1.0_24-192.168.123.0_24
| **emit ISAKMP Message:
|    initiator cookie:
|   56 6b ba 6c  9a 8f df ce
|    responder cookie:
|   a0 d7 07 80  4d 46 90 b6
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_QUICK
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  59 0f b4 97
| ***emit ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_SA
| emitting 20 zero bytes of HASH into ISAKMP Hash Payload
| emitting length of ISAKMP Hash Payload: 24
| kernel_alg_db_new() initial trans_cnt=6
| kernel_alg_db_new() will return p_new->protoid=3, p_new->trans_cnt=1
| kernel_alg_db_new()     trans[0]: transid=12, attr_cnt=2, attrs[0].type=5, attrs[0].val=1
| returning new proposal from esp_info
| ***emit ISAKMP Security Association Payload:
|    next payload type: ISAKMP_NEXT_NONCE
|    DOI: ISAKMP_DOI_IPSEC
| ****emit IPsec DOI SIT:
|    IPsec DOI SIT: SIT_IDENTITY_ONLY
| out_sa pcn: 0 has 1 valid proposals
| out_sa pcn: 0 pn: 0<1 valid_count: 1 trans_cnt: 1
| ****emit ISAKMP Proposal Payload:
|    next payload type: ISAKMP_NEXT_NONE
|    proposal number: 0
|    protocol ID: PROTO_IPSEC_ESP
|    SPI size: 4
|    number of transforms: 1
| generate SPI:  23 90 0d 3f
| emitting 4 raw bytes of SPI into ISAKMP Proposal Payload
| SPI  23 90 0d 3f
| *****emit ISAKMP Transform Payload (ESP):
|    next payload type: ISAKMP_NEXT_NONE
|    transform number: 0
|    transform ID: ESP_AES
| ******emit ISAKMP IPsec DOI attribute:
|    af+type: GROUP_DESCRIPTION
|    length/value: 5
|     [5 is OAKLEY_GROUP_MODP1536]
| ******emit ISAKMP IPsec DOI attribute:
|    af+type: ENCAPSULATION_MODE
|    length/value: 1
|     [1 is ENCAPSULATION_MODE_TUNNEL]
| ******emit ISAKMP IPsec DOI attribute:
|    af+type: SA_LIFE_TYPE
|    length/value: 1
|     [1 is SA_LIFE_TYPE_SECONDS]
| ******emit ISAKMP IPsec DOI attribute:
|    af+type: SA_LIFE_DURATION (variable length)
| emitting 4 raw bytes of long attribute value into ISAKMP IPsec DOI attribute
| long attribute value
|   00 01 51 80
| emitting length of ISAKMP IPsec DOI attribute: 4
| ******emit ISAKMP IPsec DOI attribute:
|    af+type: AUTH_ALGORITHM
|    length/value: 1
|     [1 is AUTH_ALGORITHM_HMAC_MD5]
| ******emit ISAKMP IPsec DOI attribute:
|    af+type: KEY_LENGTH
|    length/value: 256
| emitting length of ISAKMP Transform Payload (ESP): 36
| emitting length of ISAKMP Proposal Payload: 48
| emitting length of ISAKMP Security Association Payload: 60
| ***emit ISAKMP Nonce Payload:
|    next payload type: ISAKMP_NEXT_KE
| emitting 16 raw bytes of Ni into ISAKMP Nonce Payload
| Ni  8e 0b cc e4  ff 06 70 c5  29 04 fd 11  66 e7 02 28
| emitting length of ISAKMP Nonce Payload: 20
| ***emit ISAKMP Key Exchange Payload:
|    next payload type: ISAKMP_NEXT_ID
| emitting 192 raw bytes of keyex value into ISAKMP Key Exchange Payload
| keyex value  fa e4 5a 3b  b0 b4 f9 07  13 6d ea 15  a1 23 57 79
| keyex value  56 99 ad b1  dc 15 c9 dd  ec 4d 4f b6  6c d7 65 fe
| keyex value  33 a2 78 0e  d1 ef 8c 34  6e f6 40 67  8a 04 85 fc
| keyex value  cf 3c f7 9c  75 23 a9 42  93 4d 6e 31  95 34 2e ea
| keyex value  70 c5 f0 7a  f1 93 5c be  00 ec 54 1e  04 3a 66 bb
| keyex value  8c b2 9c 5d  b7 a3 0d cf  c2 1a 42 f1  0d 29 cf a3
| keyex value  9c 32 a0 1c  53 ba 7c b0  e8 a2 bc 5f  39 e3 86 95
| keyex value  d1 1d e8 ce  2c d1 dd 85  28 b1 10 73  90 34 1d e3
| keyex value  c0 29 43 36  77 73 06 35  41 15 bd 6d  24 b4 c1 e0
| keyex value  22 55 7c 4f  05 b7 b8 68  9a fc e1 ef  c4 71 67 7e
| keyex value  c0 30 cd 2a  d2 f8 22 96  8a 99 d5 fd  e2 ea fc 35
| keyex value  b9 a2 cb e1  64 a4 32 51  97 eb 69 bd  7b 21 dd 6b
| emitting length of ISAKMP Key Exchange Payload: 196
| ***emit ISAKMP Identification Payload (IPsec DOI):
|    next payload type: ISAKMP_NEXT_ID
|    ID type: ID_IPV4_ADDR_SUBNET
|    Protocol ID: 0
|    port: 0
| emitting 4 raw bytes of client network into ISAKMP Identification Payload (IPsec DOI)
| client network  c0 a8 01 00
| emitting 4 raw bytes of client mask into ISAKMP Identification Payload (IPsec DOI)
| client mask  ff ff ff 00
| emitting length of ISAKMP Identification Payload (IPsec DOI): 16
| ***emit ISAKMP Identification Payload (IPsec DOI):
|    next payload type: ISAKMP_NEXT_NONE
|    ID type: ID_IPV4_ADDR_SUBNET
|    Protocol ID: 0
|    port: 0
| emitting 4 raw bytes of client network into ISAKMP Identification Payload (IPsec DOI)
| client network  c0 a8 7b 00
| emitting 4 raw bytes of client mask into ISAKMP Identification Payload (IPsec DOI)
| client mask  ff ff ff 00
| emitting length of ISAKMP Identification Payload (IPsec DOI): 16
| HASH(1) computed:
|   4a 7a 5e 52  b6 f5 f4 e4  64 6d aa 20  9a 2c 26 51
|   57 f6 b0 fe
| last Phase 1 IV:  ce 3c 12 ec  95 8b b4 5f  fa 61 e4 fb  00 40 55 3c
| current Phase 1 IV:  ce 3c 12 ec  95 8b b4 5f  fa 61 e4 fb  00 40 55 3c
| computed Phase 2 IV:
|   06 ca c2 86  61 b7 6b b9  fa fa ab b5  c4 b9 0e 7f
|   66 20 68 42
| encrypting:
|   01 00 00 18  4a 7a 5e 52  b6 f5 f4 e4  64 6d aa 20
|   9a 2c 26 51  57 f6 b0 fe  0a 00 00 3c  00 00 00 01
|   00 00 00 01  00 00 00 30  00 03 04 01  23 90 0d 3f
|   00 00 00 24  00 0c 00 00  80 03 00 05  80 04 00 01
|   80 01 00 01  00 02 00 04  00 01 51 80  80 05 00 01
|   80 06 01 00  04 00 00 14  8e 0b cc e4  ff 06 70 c5
|   29 04 fd 11  66 e7 02 28  05 00 00 c4  fa e4 5a 3b
|   b0 b4 f9 07  13 6d ea 15  a1 23 57 79  56 99 ad b1
|   dc 15 c9 dd  ec 4d 4f b6  6c d7 65 fe  33 a2 78 0e
|   d1 ef 8c 34  6e f6 40 67  8a 04 85 fc  cf 3c f7 9c
|   75 23 a9 42  93 4d 6e 31  95 34 2e ea  70 c5 f0 7a
|   f1 93 5c be  00 ec 54 1e  04 3a 66 bb  8c b2 9c 5d
|   b7 a3 0d cf  c2 1a 42 f1  0d 29 cf a3  9c 32 a0 1c
|   53 ba 7c b0  e8 a2 bc 5f  39 e3 86 95  d1 1d e8 ce
|   2c d1 dd 85  28 b1 10 73  90 34 1d e3  c0 29 43 36
|   77 73 06 35  41 15 bd 6d  24 b4 c1 e0  22 55 7c 4f
|   05 b7 b8 68  9a fc e1 ef  c4 71 67 7e  c0 30 cd 2a
|   d2 f8 22 96  8a 99 d5 fd  e2 ea fc 35  b9 a2 cb e1
|   64 a4 32 51  97 eb 69 bd  7b 21 dd 6b  05 00 00 10
|   04 00 00 00  c0 a8 01 00  ff ff ff 00  00 00 00 10
|   04 00 00 00  c0 a8 7b 00  ff ff ff 00
| IV:
|   06 ca c2 86  61 b7 6b b9  fa fa ab b5  c4 b9 0e 7f
|   66 20 68 42
| unpadded size is: 332
| emitting 4 zero bytes of encryption padding into ISAKMP Message
| encrypting 336 using OAKLEY_AES_CBC
| next IV:  0d 37 3e 01  3a bb 9d 7e  33 68 d8 4e  3f 79 27 32
| emitting length of ISAKMP Message: 364
| sending 364 bytes for quick_outI1 through eth2:500 to 10.29.3.225:500 (using #8)
|   56 6b ba 6c  9a 8f df ce  a0 d7 07 80  4d 46 90 b6
|   08 10 20 01  59 0f b4 97  00 00 01 6c  22 84 e9 3d
|   bd 22 1f 84  ad 70 6b 89  44 ef d6 e6  1d 26 9a 47
|   6d 2e a6 00  1f 31 87 11  e5 4e e1 2f  28 77 d9 8e
|   d7 6c b4 b3  4f 34 3f 9d  e1 6d 8d bc  dc a8 12 48
|   10 d8 25 6e  6c f5 b2 dc  40 51 d5 26  f6 be e8 ab
|   af 71 14 a6  4b 9c 80 99  9e a5 7b 07  83 04 84 d8
|   4e 5b ad a8  93 61 f9 df  d5 8f 68 89  ab 0f f9 7f
|   48 e6 04 d9  a2 fc ea 76  cb 38 0e 01  d6 79 c7 6f
|   e4 c1 97 8f  37 be 1d b7  fe 54 43 76  92 41 41 7b
|   d0 02 d5 24  fb f2 73 ea  a7 7f fc 39  7d bd 92 25
|   3a 91 5a 79  06 21 62 74  e6 7d 35 80  b3 71 5d 57
|   2b b4 48 8b  94 b5 ab 5d  28 04 61 02  6b c5 ea c2
|   a9 62 1b 9b  58 67 0b a5  f9 32 17 e5  33 cd e7 81
|   41 c7 36 a2  1b cd 1c 2e  8b 0b a0 3e  ec 5e 42 68
|   66 4d b7 57  d8 64 bc a5  6f 05 d0 37  7e 10 87 70
|   7b 8e 71 d3  00 bf f3 47  76 40 53 41  fa fd 8e 35
|   7d 53 87 b5  dc 80 e8 9e  ab 16 6f 27  a0 f0 f5 5b
|   b0 cb 03 93  74 a8 cf 49  f4 71 ad 92  6b d9 c8 4a
|   4f 40 09 42  94 e8 8b 53  38 1f 61 7f  f5 b4 d7 3b
|   31 a8 e1 51  62 87 5a bf  1f 13 b6 16  0a db 7b 39
|   69 92 f8 f6  da 10 ea 69  11 e8 8e 66  0d 37 3e 01
|   3a bb 9d 7e  33 68 d8 4e  3f 79 27 32
| deleting event for #8
| inserting event EVENT_RETRANSMIT, timeout in 10 seconds for #8
| event added at head of queue
| * processed 1 messages from cryptographic helpers 
| next event EVENT_RETRANSMIT in 10 seconds for #8
| next event EVENT_RETRANSMIT in 10 seconds for #8
|  
| *received 364 bytes from 10.29.3.225:500 on eth2 (port=500)
|   56 6b ba 6c  9a 8f df ce  a0 d7 07 80  4d 46 90 b6
|   08 10 20 01  59 0f b4 97  00 00 01 6c  fe c4 d5 1b
|   6d f7 0a 81  ee e3 90 1c  19 20 17 d5  85 32 93 2b
|   d9 22 89 20  30 0c 10 b4  dc 0a 74 a7  28 ec 9d 36
|   7e 7e 38 89  70 29 b1 34  f1 4b a4 29  cf 5f 50 8e
|   e0 27 16 79  1c 5f e4 6d  12 b1 60 ba  44 01 31 f5
|   57 ac 37 35  03 d4 bd 9f  00 93 d9 36  67 f9 47 be
|   fc 5b ac d3  95 ca 96 86  82 bb 99 5d  5e d6 2c 6c
|   56 08 2b c6  75 f5 40 35  9a 2a 2e 25  ba 2f aa c4
|   91 31 c9 cf  7f d9 6f a4  56 06 47 5b  64 18 37 e5
|   52 10 cf da  cb 49 0c 6c  70 e8 b5 24  11 42 22 6a
|   ef fc ce 57  3e db 0f 8d  43 b5 6a a8  9a 6f 24 3a
|   92 02 0a a9  17 d8 f6 7f  b8 a2 e6 d8  72 7f f1 2b
|   65 9d cd 48  65 27 22 54  05 ba 26 e7  1d d7 03 98
|   b0 8c 76 43  27 2d 40 18  b5 bc a4 28  c3 29 8e 0d
|   ab d9 b1 66  43 3e 9b cc  62 aa 2f 1a  88 2a 10 05
|   4f 67 9c dd  89 14 f0 f7  73 e6 9b 47  20 3f 87 68
|   62 1c 70 87  f0 04 14 66  b2 26 27 02  7d af 4f 5b
|   5c 56 36 ac  83 37 3d 56  63 4d 60 6d  31 b3 9b 28
|   12 d5 7d cf  cd 86 f8 e6  bf a2 6d 60  a4 5e 53 a0
|   b7 61 fb eb  60 d6 80 41  8d a8 7d e2  12 e1 43 75
|   95 aa 90 4a  e0 45 42 c2  1d a2 71 fb  8b f6 cb 3b
|   34 9a 0f d7  05 97 83 87  ee 2d 58 9b
| **parse ISAKMP Message:
|    initiator cookie:
|   56 6b ba 6c  9a 8f df ce
|    responder cookie:
|   a0 d7 07 80  4d 46 90 b6
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_QUICK
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  59 0f b4 97
|    length: 364
|  processing version=1.0 packet with exchange type=ISAKMP_XCHG_QUICK (32)
| ICOOKIE:  56 6b ba 6c  9a 8f df ce
| RCOOKIE:  a0 d7 07 80  4d 46 90 b6
| state hash entry 18
| v1 peer and cookies match on #8, provided msgid 590fb497 vs 590fb497
| v1 state object #8 found, in STATE_QUICK_I1
| processing connection site_192.168.1.0_24-192.168.123.0_24
| received encrypted packet from 10.29.3.225:500
| decrypting 336 bytes using algorithm OAKLEY_AES_CBC
| decrypted:
|   01 00 00 18  13 62 83 a0  11 60 99 30  ac fa 68 c2
|   a0 6f 4a 12  41 49 53 de  0a 00 00 3c  00 00 00 01
|   00 00 00 01  00 00 00 30  00 03 04 01  84 46 2a 0b
|   00 00 00 24  00 0c 00 00  80 03 00 05  80 04 00 01
|   80 01 00 01  00 02 00 04  00 01 51 80  80 05 00 01
|   80 06 01 00  04 00 00 14  79 87 4f f4  75 ee b2 ce
|   05 12 b8 27  a9 9d 2f f5  05 00 00 c4  bd ed da 13
|   ae 3f 49 59  95 83 94 bf  d2 44 e4 9a  17 20 34 35
|   f1 1b 26 e3  60 e1 cc f7  4f bb d6 a9  d4 a5 9e 4d
|   f5 e3 73 9f  ab f5 cb bc  1b 44 ac 26  ba 92 36 df
|   de aa 47 2b  38 46 68 4f  3c 32 08 b6  d3 df fd 8b
|   84 35 d3 14  b0 1c 8a f7  a2 df ec ce  18 11 87 bf
|   7b 48 ab f2  0a 7d 75 f5  ea 33 e9 ae  4d 42 1d 68
|   fb 2c b7 16  eb 94 df 0e  ea 45 ba 95  7c 7c b8 a0
|   3c 7b a7 87  5a 75 b6 54  57 bf f6 a3  a7 67 bb 41
|   fc dc 43 74  86 8d ce 45  fc d3 75 79  61 dd ce 3d
|   d9 ff c6 75  64 01 e9 9b  37 8e 54 47  99 5b cb d8
|   08 52 31 df  07 2f 02 c8  14 2b dc 09  53 f8 a6 dd
|   51 56 de 76  c0 10 75 da  e8 86 cf ca  05 00 00 10
|   04 00 00 00  c0 a8 01 00  ff ff ff 00  00 00 00 10
|   04 00 00 00  c0 a8 7b 00  ff ff ff 00  00 00 00 00
| next IV:  8b f6 cb 3b  34 9a 0f d7  05 97 83 87  ee 2d 58 9b
| got payload 0x100(ISAKMP_NEXT_HASH) needed: 0x502 opt: 0x200030
| ***parse ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_SA
|    length: 24
| got payload 0x2(ISAKMP_NEXT_SA) needed: 0x402 opt: 0x200030
| ***parse ISAKMP Security Association Payload:
|    next payload type: ISAKMP_NEXT_NONCE
|    length: 60
|    DOI: ISAKMP_DOI_IPSEC
| got payload 0x400(ISAKMP_NEXT_NONCE) needed: 0x400 opt: 0x200030
| ***parse ISAKMP Nonce Payload:
|    next payload type: ISAKMP_NEXT_KE
|    length: 20
| got payload 0x10(ISAKMP_NEXT_KE) needed: 0x0 opt: 0x200030
| ***parse ISAKMP Key Exchange Payload:
|    next payload type: ISAKMP_NEXT_ID
|    length: 196
| got payload 0x20(ISAKMP_NEXT_ID) needed: 0x0 opt: 0x200030
| ***parse ISAKMP Identification Payload (IPsec DOI):
|    next payload type: ISAKMP_NEXT_ID
|    length: 16
|    ID type: ID_IPV4_ADDR_SUBNET
|    Protocol ID: 0
|    port: 0
|      obj:   c0 a8 01 00  ff ff ff 00  00 00 00 10  04 00 00 00
| got payload 0x20(ISAKMP_NEXT_ID) needed: 0x0 opt: 0x200030
| ***parse ISAKMP Identification Payload (IPsec DOI):
|    next payload type: ISAKMP_NEXT_NONE
|    length: 16
|    ID type: ID_IPV4_ADDR_SUBNET
|    Protocol ID: 0
|    port: 0
|      obj:   c0 a8 7b 00  ff ff ff 00  00 00 00 00  00 00 01 71
| removing 4 bytes of padding
| **emit ISAKMP Message:
|    initiator cookie:
|   56 6b ba 6c  9a 8f df ce
|    responder cookie:
|   a0 d7 07 80  4d 46 90 b6
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_QUICK
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  59 0f b4 97
| HASH(2) computed:
|   13 62 83 a0  11 60 99 30  ac fa 68 c2  a0 6f 4a 12
|   41 49 53 de
| ****parse IPsec DOI SIT:
|    IPsec DOI SIT: SIT_IDENTITY_ONLY
| ****parse ISAKMP Proposal Payload:
|    next payload type: ISAKMP_NEXT_NONE
|    length: 48
|    proposal number: 0
|    protocol ID: PROTO_IPSEC_ESP
|    SPI size: 4
|    number of transforms: 1
| parsing 4 raw bytes of ISAKMP Proposal Payload into SPI
| SPI  84 46 2a 0b
| *****parse ISAKMP Transform Payload (ESP):
|    next payload type: ISAKMP_NEXT_NONE
|    length: 36
|    transform number: 0
|    transform ID: ESP_AES
| ******parse ISAKMP IPsec DOI attribute:
|    af+type: GROUP_DESCRIPTION
|    length/value: 5
|    [5 is OAKLEY_GROUP_MODP1536]
| ******parse ISAKMP IPsec DOI attribute:
|    af+type: ENCAPSULATION_MODE
|    length/value: 1
|    [1 is ENCAPSULATION_MODE_TUNNEL]
| ******parse ISAKMP IPsec DOI attribute:
|    af+type: SA_LIFE_TYPE
|    length/value: 1
|    [1 is SA_LIFE_TYPE_SECONDS]
| ******parse ISAKMP IPsec DOI attribute:
|    af+type: SA_LIFE_DURATION (variable length)
|    length/value: 4
|    long duration: 86400
| ******parse ISAKMP IPsec DOI attribute:
|    af+type: AUTH_ALGORITHM
|    length/value: 1
|    [1 is AUTH_ALGORITHM_HMAC_MD5]
| ******parse ISAKMP IPsec DOI attribute:
|    af+type: KEY_LENGTH
|    length/value: 256
| kernel_alg_esp_enc_ok(12,256): alg_id=12, alg_ivlen=128, alg_minbits=128, alg_maxbits=256, res=0, ret=1
| DH public value received:
|   bd ed da 13  ae 3f 49 59  95 83 94 bf  d2 44 e4 9a
|   17 20 34 35  f1 1b 26 e3  60 e1 cc f7  4f bb d6 a9
|   d4 a5 9e 4d  f5 e3 73 9f  ab f5 cb bc  1b 44 ac 26
|   ba 92 36 df  de aa 47 2b  38 46 68 4f  3c 32 08 b6
|   d3 df fd 8b  84 35 d3 14  b0 1c 8a f7  a2 df ec ce
|   18 11 87 bf  7b 48 ab f2  0a 7d 75 f5  ea 33 e9 ae
|   4d 42 1d 68  fb 2c b7 16  eb 94 df 0e  ea 45 ba 95
|   7c 7c b8 a0  3c 7b a7 87  5a 75 b6 54  57 bf f6 a3
|   a7 67 bb 41  fc dc 43 74  86 8d ce 45  fc d3 75 79
|   61 dd ce 3d  d9 ff c6 75  64 01 e9 9b  37 8e 54 47
|   99 5b cb d8  08 52 31 df  07 2f 02 c8  14 2b dc 09
|   53 f8 a6 dd  51 56 de 76  c0 10 75 da  e8 86 cf ca
| started looking for secret for 10.29.3.195->10.29.3.225 of kind PPK_PSK
| actually looking for secret for 10.29.3.195->10.29.3.225 of kind PPK_PSK
| line 1: key type PPK_PSK(10.29.3.195) to type PPK_PSK 
| 1: compared key 10.29.3.225 to 10.29.3.195 / 10.29.3.225 -> 4
| 2: compared key 10.29.3.195 to 10.29.3.195 / 10.29.3.225 -> 12
| line 1: match=12 
| best_match 0>12 best=0x17bdf8 (line=1)
| concluding with best_match=12 best=0x17bdf8 (lineno=1)
| 0: w->pcw_dead: 0 w->pcw_work: 0 cnt: 1
| asking helper 0 to do compute dh(p2) op on seq: 16 (len=2668, pcw_work=1)
| crypto helper write of request: cnt=2668<wlen=2668.  
| deleting event for #8
| inserting event EVENT_CRYPTO_FAILED, timeout in 300 seconds for #8
| event added after event EVENT_SHUNT_SCAN
| complete state transition with STF_SUSPEND
| * processed 0 messages from cryptographic helpers 
| next event EVENT_DPD in 10 seconds for #7
| next event EVENT_DPD in 10 seconds for #7
! helper 0 read 2664+4/2668 bytesfd: 7
! helper 0 doing compute dh(p2) op id: 16
! peer's g:   bd ed da 13  ae 3f 49 59  95 83 94 bf  d2 44 e4 9a
! peer's g:   17 20 34 35  f1 1b 26 e3  60 e1 cc f7  4f bb d6 a9
! peer's g:   d4 a5 9e 4d  f5 e3 73 9f  ab f5 cb bc  1b 44 ac 26
! peer's g:   ba 92 36 df  de aa 47 2b  38 46 68 4f  3c 32 08 b6
! peer's g:   d3 df fd 8b  84 35 d3 14  b0 1c 8a f7  a2 df ec ce
! peer's g:   18 11 87 bf  7b 48 ab f2  0a 7d 75 f5  ea 33 e9 ae
! peer's g:   4d 42 1d 68  fb 2c b7 16  eb 94 df 0e  ea 45 ba 95
! peer's g:   7c 7c b8 a0  3c 7b a7 87  5a 75 b6 54  57 bf f6 a3
! peer's g:   a7 67 bb 41  fc dc 43 74  86 8d ce 45  fc d3 75 79
! peer's g:   61 dd ce 3d  d9 ff c6 75  64 01 e9 9b  37 8e 54 47
! peer's g:   99 5b cb d8  08 52 31 df  07 2f 02 c8  14 2b dc 09
! peer's g:   53 f8 a6 dd  51 56 de 76  c0 10 75 da  e8 86 cf ca
! calc_dh_shared(): time elapsed (OAKLEY_GROUP_MODP1536): 27802 usec
! DH shared-secret:
!   a4 2f 0b 70  a7 00 fa 14  7a 45 41 7e  39 6f d0 c6
!   e7 e7 bf 3b  73 32 58 b1  15 3d 90 ea  7d 82 68 b7
!   b2 10 6e af  11 65 34 4f  05 e2 7f ca  c8 44 52 30
!   51 dc c2 61  b5 12 d4 02  96 f4 cc 7b  d6 19 3b 2b
!   f8 27 44 0b  36 26 fa 0c  e5 b9 f9 32  fc d5 1c c1
!   e3 2c f2 4b  94 05 7b f6  c7 82 c6 7c  a7 14 d8 12
!   30 de 5f 35  2e bb 65 e8  7f 46 13 70  31 82 2e 74
!   3a 87 be 28  29 3e 22 45  3b f1 f2 f6  78 0f e6 5a
!   33 38 21 1e  e9 34 88 18  b6 0d 42 fb  0d 93 c5 55
!   1d c2 c2 7c  c6 81 2e b0  2a 05 a1 26  55 56 c6 49
!   16 0e ba 99  34 c7 7a 2f  38 c2 45 1a  ee b2 5e a0
!   53 e9 08 06  45 8b 37 df  9d 55 df b3  c0 b5 4f 3a
|  
| helper 0 has finished work (cnt now 1)
| helper 0 replies to id: q#16
| calling callback function 0x3dedc
| quick inI1_outR1: calculated ke+nonce, calculating DH
| processing connection site_192.168.1.0_24-192.168.123.0_24
| our client is subnet 192.168.1.0/24
| our client protocol/port is 0/0
| peer client is subnet 192.168.123.0/24
| peer client protocol/port is 0/0
| ***emit ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_NONE
| emitting 20 zero bytes of HASH into ISAKMP Hash Payload
| emitting length of ISAKMP Hash Payload: 24
| HASH(3) computed:  ca b9 b9 a5  d6 b9 3f e7  a1 50 79 37  7d 47 48 f4
| HASH(3) computed:  b2 d8 97 02
| compute_proto_keymat:needed_len (after ESP enc)=32
| compute_proto_keymat:needed_len (after ESP auth)=48
| ESP KEYMAT 
|   KEYMAT computed:
|   ab 1a 24 4c  d7 1d 8d e6  2b 8d a7 f4  0f 66 6e 20
|   79 5e 78 48  da 67 b2 0e  74 d8 1d 44  ee 41 ed cc
|   32 ab 0a e5  4b d9 39 62  82 ea c7 47  34 23 0d 00
|   Peer KEYMAT computed:
|   cd 1f 42 75  59 f7 af 16  46 59 87 5e  9c 7e e7 09
|   68 a2 37 98  09 0f e5 30  83 9f 51 31  09 85 09 5f
|   e5 f8 8e b3  66 0d 2e 5c  3a 10 4f 55  fd d2 b0 7b
| install_ipsec_sa() for #8: inbound and outbound
| route owner of "site_192.168.1.0_24-192.168.123.0_24" unrouted: NULL; eroute owner: NULL
| could_route called for site_192.168.1.0_24-192.168.123.0_24 (kind=CK_PERMANENT)
| pfkey_lib_debug:pfkey_msg_hdr_build: 
| pfkey_lib_debug:pfkey_msg_hdr_build: on_entry &pfkey_ext=0p0xbec897ac pfkey_ext=0p0xbec8a898 *pfkey_ext=0p(nil). 
| pfkey_lib_debug:pfkey_msg_hdr_build: on_exit &pfkey_ext=0p0xbec897ac pfkey_ext=0p0xbec8a898 *pfkey_ext=0p0x180368. 
| pfkey_lib_debug:pfkey_sa_build: spi=00001007 replay=0 sa_state=1 auth=0 encrypt=0 flags=0 
| pfkey_lib_debug:pfkey_address_build: exttype=5 proto=0 prefixlen=0 
| pfkey_lib_debug:pfkey_address_build: found address family AF_INET. 
| pfkey_lib_debug:pfkey_address_build: found address=10.29.3.195:0. 
| pfkey_lib_debug:pfkey_address_build: successful created len: 3. 
| pfkey_lib_debug:pfkey_address_build: exttype=6 proto=0 prefixlen=0 
| pfkey_lib_debug:pfkey_address_build: found address family AF_INET. 
| pfkey_lib_debug:pfkey_address_build: found address=10.29.3.225:0. 
| pfkey_lib_debug:pfkey_address_build: successful created len: 3. 
| pfkey_lib_debug:pfkey_msg_build: extentions[0] needs 16 bytes 
| pfkey_lib_debug:pfkey_msg_build: extentions[1] needs 24 bytes 
| pfkey_lib_debug:pfkey_msg_build: extentions[5] needs 24 bytes 
| pfkey_lib_debug:pfkey_msg_build: extentions[6] needs 24 bytes 
| pfkey_lib_debug:pfkey_msg_build: pfkey_msg=0p0x17dfb0 allocated 88 bytes, &(extensions[0])=0p0xbec8a898 
| pfkey_lib_debug:pfkey_msg_build: copying 24 bytes from extensions[1] (type=1) 
| pfkey_lib_debug:pfkey_msg_build: copying 24 bytes from extensions[5] (type=5) 
| pfkey_lib_debug:pfkey_msg_build: copying 24 bytes from extensions[6] (type=6) 
| pfkey_lib_debug:pfkey_msg_parse: parsing message ver=2, type=3(add), errno=0, satype=9(IPIP), len=11, res=0, seq=41, pid=1545. 
| pfkey_lib_debug:pfkey_msg_parse: satype 9(IPIP) conversion to proto gives 4 for msg_type 3(add). 
| pfkey_lib_debug:pfkey_msg_parse: remain=9 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=1(security-association) remain=9. 
| pfkey_lib_debug:pfkey_msg_parse: remain=9 ext_type=1(security-association) ext_len=3 parsing ext 0p0x17dfc0 with parser pfkey_sa_parse. 
| pfkey_lib_debug:pfkey_sa_parse: successfully found len=3 exttype=1(security-association) spi=00001007 replay=0 state=1 auth=0 encrypt=0 flags=0 ref=0. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 1(security-association) parsed. 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=5(source-address) remain=6. 
| pfkey_lib_debug:pfkey_msg_parse: remain=6 ext_type=5(source-address) ext_len=3 parsing ext 0p0x17dfd8 with parser pfkey_address_parse. 
| pfkey_lib_debug:pfkey_address_parse: found exttype=5(source-address) family=2(AF_INET) address=10.29.3.195 proto=0 port=0. 
| pfkey_lib_debug:pfkey_address_parse: successful. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 5(source-address) parsed. 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=6(destination-address) remain=3. 
| pfkey_lib_debug:pfkey_msg_parse: remain=3 ext_type=6(destination-address) ext_len=3 parsing ext 0p0x17dff0 with parser pfkey_address_parse. 
| pfkey_lib_debug:pfkey_address_parse: found exttype=6(destination-address) family=2(AF_INET) address=10.29.3.225 proto=0 port=0. 
| pfkey_lib_debug:pfkey_address_parse: successful. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 6(destination-address) parsed. 
| finish_pfkey_msg: K_SADB_ADD message 41 for Add SA tun.1007@10.29.3.225
|   02 03 00 09  00 0b 00 00  00 00 00 29  00 00 06 09
|   00 03 00 01  00 00 10 07  00 01 00 00  00 00 00 00
|   00 00 00 00  00 00 00 00  00 03 00 05  00 00 00 00
|   00 02 00 00  0a 1d 03 c3  00 00 00 00  00 00 00 00
|   00 03 00 06  00 00 00 00  00 02 00 00  0a 1d 03 e1
|   00 00 00 00  00 00 00 00
| pfkey_get: K_SADB_ADD message 41
| pfkey_lib_debug:pfkey_extensions_free:Free extention 0 (16) 
| pfkey_lib_debug:pfkey_extensions_free:Free extention 1 (24) 
| pfkey_lib_debug:pfkey_extensions_free:Free extention 5 (24) 
| pfkey_lib_debug:pfkey_extensions_free:Free extention 6 (24) 
| pfkey_lib_debug:pfkey_msg_parse: parsing message ver=2, type=3(add), errno=0, satype=9(IPIP), len=13, res=0, seq=41, pid=1545. 
| pfkey_lib_debug:pfkey_msg_parse: satype 9(IPIP) conversion to proto gives 4 for msg_type 3(add). 
| pfkey_lib_debug:pfkey_msg_parse: remain=11 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=1(security-association) remain=11. 
| pfkey_lib_debug:pfkey_msg_parse: remain=11 ext_type=1(security-association) ext_len=3 parsing ext 0p0xbec898a8 with parser pfkey_sa_parse. 
| pfkey_lib_debug:pfkey_sa_parse: successfully found len=3 exttype=1(security-association) spi=00001007 replay=0 state=1 auth=0 encrypt=0 flags=0 ref=0. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 1(security-association) parsed. 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=5(source-address) remain=8. 
| pfkey_lib_debug:pfkey_msg_parse: remain=8 ext_type=5(source-address) ext_len=3 parsing ext 0p0xbec898c0 with parser pfkey_address_parse. 
| pfkey_lib_debug:pfkey_address_parse: found exttype=5(source-address) family=2(AF_INET) address=10.29.3.195 proto=0 port=0. 
| pfkey_lib_debug:pfkey_address_parse: successful. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 5(source-address) parsed. 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=6(destination-address) remain=5. 
| pfkey_lib_debug:pfkey_msg_parse: remain=5 ext_type=6(destination-address) ext_len=3 parsing ext 0p0xbec898d8 with parser pfkey_address_parse. 
| pfkey_lib_debug:pfkey_address_parse: found exttype=6(destination-address) family=2(AF_INET) address=10.29.3.225 proto=0 port=0. 
| pfkey_lib_debug:pfkey_address_parse: successful. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 6(destination-address) parsed. 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=32(X-saref) remain=2. 
| pfkey_lib_debug:pfkey_msg_parse: remain=2 ext_type=32(X-saref) ext_len=2 parsing ext 0p0xbec898f0 with parser pfkey_x_ext_saref_parse. 
| pfkey_lib_debug:pfkey_x_saref_parse: 
| pfkey_lib_debug:pfkey_msg_parse: Extension 32(X-saref) parsed. 
| added tunnel with ref=70
| recorded ref=70 as refhim
| looking for alg with transid: 12 keylen: 256 auth: 1 
| checking transid: 11 keylen: 0 auth: 1 
| checking transid: 11 keylen: 0 auth: 2 
| checking transid: 2 keylen: 8 auth: 0 
| checking transid: 2 keylen: 8 auth: 1 
| checking transid: 2 keylen: 8 auth: 2 
| checking transid: 3 keylen: 24 auth: 0 
| checking transid: 3 keylen: 24 auth: 1 
| checking transid: 3 keylen: 24 auth: 2 
| checking transid: 12 keylen: 16 auth: 0 
| checking transid: 12 keylen: 16 auth: 1 
| checking transid: 12 keylen: 16 auth: 2 
| kernel_alg_esp_info():transid=12, auth=1, ei=0x1670e8, enckeylen=32, authkeylen=16, encryptalg=12, authalg=2
| esp enckey:  cd 1f 42 75  59 f7 af 16  46 59 87 5e  9c 7e e7 09
| esp enckey:  68 a2 37 98  09 0f e5 30  83 9f 51 31  09 85 09 5f
| esp authkey:  e5 f8 8e b3  66 0d 2e 5c  3a 10 4f 55  fd d2 b0 7b
| pfkey_lib_debug:pfkey_msg_hdr_build: 
| pfkey_lib_debug:pfkey_msg_hdr_build: on_entry &pfkey_ext=0p0xbec897ac pfkey_ext=0p0xbec8a898 *pfkey_ext=0p(nil). 
| pfkey_lib_debug:pfkey_msg_hdr_build: on_exit &pfkey_ext=0p0xbec897ac pfkey_ext=0p0xbec8a898 *pfkey_ext=0p0x180368. 
| pfkey_lib_debug:pfkey_sa_build: spi=84462a0b replay=64 sa_state=1 auth=2 encrypt=12 flags=0 
| pfkey_lib_debug:pfkey_address_build: exttype=5 proto=0 prefixlen=0 
| pfkey_lib_debug:pfkey_address_build: found address family AF_INET. 
| pfkey_lib_debug:pfkey_address_build: found address=10.29.3.195:0. 
| pfkey_lib_debug:pfkey_address_build: successful created len: 3. 
| pfkey_lib_debug:pfkey_address_build: exttype=6 proto=0 prefixlen=0 
| pfkey_lib_debug:pfkey_address_build: found address family AF_INET. 
| pfkey_lib_debug:pfkey_address_build: found address=10.29.3.225:0. 
| pfkey_lib_debug:pfkey_address_build: successful created len: 3. 
| pfkey_lib_debug:pfkey_key_build: 
| pfkey_lib_debug:pfkey_key_build: 
| pfkey_lib_debug:pfkey_msg_build: extentions[0] needs 16 bytes 
| pfkey_lib_debug:pfkey_msg_build: extentions[1] needs 24 bytes 
| pfkey_lib_debug:pfkey_msg_build: extentions[5] needs 24 bytes 
| pfkey_lib_debug:pfkey_msg_build: extentions[6] needs 24 bytes 
| pfkey_lib_debug:pfkey_msg_build: extentions[8] needs 24 bytes 
| pfkey_lib_debug:pfkey_msg_build: extentions[9] needs 40 bytes 
| pfkey_lib_debug:pfkey_msg_build: pfkey_msg=0p0x1811f0 allocated 152 bytes, &(extensions[0])=0p0xbec8a898 
| pfkey_lib_debug:pfkey_msg_build: copying 24 bytes from extensions[1] (type=1) 
| pfkey_lib_debug:pfkey_msg_build: copying 24 bytes from extensions[5] (type=5) 
| pfkey_lib_debug:pfkey_msg_build: copying 24 bytes from extensions[6] (type=6) 
| pfkey_lib_debug:pfkey_msg_build: copying 24 bytes from extensions[8] (type=8) 
| pfkey_lib_debug:pfkey_msg_build: copying 40 bytes from extensions[9] (type=9) 
| pfkey_lib_debug:pfkey_msg_parse: parsing message ver=2, type=3(add), errno=0, satype=3(ESP), len=19, res=0, seq=42, pid=1545. 
| pfkey_lib_debug:pfkey_msg_parse: satype 3(ESP) conversion to proto gives 50 for msg_type 3(add). 
| pfkey_lib_debug:pfkey_msg_parse: remain=17 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=1(security-association) remain=17. 
| pfkey_lib_debug:pfkey_msg_parse: remain=17 ext_type=1(security-association) ext_len=3 parsing ext 0p0x181200 with parser pfkey_sa_parse. 
| pfkey_lib_debug:pfkey_sa_parse: successfully found len=3 exttype=1(security-association) spi=84462a0b replay=64 state=1 auth=2 encrypt=12 flags=0 ref=0. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 1(security-association) parsed. 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=5(source-address) remain=14. 
| pfkey_lib_debug:pfkey_msg_parse: remain=14 ext_type=5(source-address) ext_len=3 parsing ext 0p0x181218 with parser pfkey_address_parse. 
| pfkey_lib_debug:pfkey_address_parse: found exttype=5(source-address) family=2(AF_INET) address=10.29.3.195 proto=0 port=0. 
| pfkey_lib_debug:pfkey_address_parse: successful. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 5(source-address) parsed. 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=6(destination-address) remain=11. 
| pfkey_lib_debug:pfkey_msg_parse: remain=11 ext_type=6(destination-address) ext_len=3 parsing ext 0p0x181230 with parser pfkey_address_parse. 
| pfkey_lib_debug:pfkey_address_parse: found exttype=6(destination-address) family=2(AF_INET) address=10.29.3.225 proto=0 port=0. 
| pfkey_lib_debug:pfkey_address_parse: successful. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 6(destination-address) parsed. 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=8(authentication-key) remain=8. 
| pfkey_lib_debug:pfkey_msg_parse: remain=8 ext_type=8(authentication-key) ext_len=3 parsing ext 0p0x181248 with parser pfkey_key_parse. 
| pfkey_lib_debug:pfkey_key_parse: success, found len=3 exttype=8(authentication-key) bits=128 reserved=0. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 8(authentication-key) parsed. 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=9(cipher-key) remain=5. 
| pfkey_lib_debug:pfkey_msg_parse: remain=5 ext_type=9(cipher-key) ext_len=5 parsing ext 0p0x181260 with parser pfkey_key_parse. 
| pfkey_lib_debug:pfkey_key_parse: success, found len=5 exttype=9(cipher-key) bits=256 reserved=0. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 9(cipher-key) parsed. 
| finish_pfkey_msg: K_SADB_ADD message 42 for Add SA esp.84462a0b@10.29.3.225
|   02 03 00 03  00 13 00 00  00 00 00 2a  00 00 06 09
|   00 03 00 01  84 46 2a 0b  40 01 02 0c  00 00 00 00
|   00 00 00 00  00 00 00 00  00 03 00 05  00 00 00 00
|   00 02 00 00  0a 1d 03 c3  00 00 00 00  00 00 00 00
|   00 03 00 06  00 00 00 00  00 02 00 00  0a 1d 03 e1
|   00 00 00 00  00 00 00 00  00 03 00 08  00 80 00 00
|   e5 f8 8e b3  66 0d 2e 5c  3a 10 4f 55  fd d2 b0 7b
|   00 05 00 09  01 00 00 00  cd 1f 42 75  59 f7 af 16
|   46 59 87 5e  9c 7e e7 09  68 a2 37 98  09 0f e5 30
|   83 9f 51 31  09 85 09 5f
| pfkey_get: K_SADB_ADD message 42
| pfkey_lib_debug:pfkey_extensions_free:Free extention 0 (16) 
| pfkey_lib_debug:pfkey_extensions_free:Free extention 1 (24) 
| pfkey_lib_debug:pfkey_extensions_free:Free extention 5 (24) 
| pfkey_lib_debug:pfkey_extensions_free:Free extention 6 (24) 
| pfkey_lib_debug:pfkey_extensions_free:Free extention 8 (24) 
| pfkey_lib_debug:pfkey_extensions_free:Free extention 9 (40) 
| pfkey_lib_debug:pfkey_msg_parse: parsing message ver=2, type=3(add), errno=0, satype=3(ESP), len=13, res=0, seq=42, pid=1545. 
| pfkey_lib_debug:pfkey_msg_parse: satype 3(ESP) conversion to proto gives 50 for msg_type 3(add). 
| pfkey_lib_debug:pfkey_msg_parse: remain=11 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=1(security-association) remain=11. 
| pfkey_lib_debug:pfkey_msg_parse: remain=11 ext_type=1(security-association) ext_len=3 parsing ext 0p0xbec898a8 with parser pfkey_sa_parse. 
| pfkey_lib_debug:pfkey_sa_parse: successfully found len=3 exttype=1(security-association) spi=84462a0b replay=64 state=1 auth=2 encrypt=12 flags=0 ref=0. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 1(security-association) parsed. 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=5(source-address) remain=8. 
| pfkey_lib_debug:pfkey_msg_parse: remain=8 ext_type=5(source-address) ext_len=3 parsing ext 0p0xbec898c0 with parser pfkey_address_parse. 
| pfkey_lib_debug:pfkey_address_parse: found exttype=5(source-address) family=2(AF_INET) address=10.29.3.195 proto=0 port=0. 
| pfkey_lib_debug:pfkey_address_parse: successful. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 5(source-address) parsed. 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=6(destination-address) remain=5. 
| pfkey_lib_debug:pfkey_msg_parse: remain=5 ext_type=6(destination-address) ext_len=3 parsing ext 0p0xbec898d8 with parser pfkey_address_parse. 
| pfkey_lib_debug:pfkey_address_parse: found exttype=6(destination-address) family=2(AF_INET) address=10.29.3.225 proto=0 port=0. 
| pfkey_lib_debug:pfkey_address_parse: successful. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 6(destination-address) parsed. 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=32(X-saref) remain=2. 
| pfkey_lib_debug:pfkey_msg_parse: remain=2 ext_type=32(X-saref) ext_len=2 parsing ext 0p0xbec898f0 with parser pfkey_x_ext_saref_parse. 
| pfkey_lib_debug:pfkey_x_saref_parse: 
| pfkey_lib_debug:pfkey_msg_parse: Extension 32(X-saref) parsed. 
| grouping unk0.1007@10.29.3.225 (ref=70) and unk0.84462a0b@10.29.3.225 (ref=71)
| pfkey_lib_debug:pfkey_msg_hdr_build: 
| pfkey_lib_debug:pfkey_msg_hdr_build: on_entry &pfkey_ext=0p0xbec8a844 pfkey_ext=0p0xbec8a8a8 *pfkey_ext=0p(nil). 
| pfkey_lib_debug:pfkey_msg_hdr_build: on_exit &pfkey_ext=0p0xbec8a844 pfkey_ext=0p0xbec8a8a8 *pfkey_ext=0p0x1802f8. 
| pfkey_lib_debug:pfkey_sa_build: spi=00001007 replay=0 sa_state=0 auth=0 encrypt=0 flags=0 
| pfkey_lib_debug:pfkey_address_build: exttype=6 proto=0 prefixlen=0 
| pfkey_lib_debug:pfkey_address_build: found address family AF_INET. 
| pfkey_lib_debug:pfkey_address_build: found address=10.29.3.225:0. 
| pfkey_lib_debug:pfkey_address_build: successful created len: 3. 
| pfkey_lib_debug:pfkey_x_satype_build: 
| pfkey_lib_debug:pfkey_sa_build: spi=84462a0b replay=0 sa_state=0 auth=0 encrypt=0 flags=0 
| pfkey_lib_debug:pfkey_address_build: exttype=20 proto=0 prefixlen=0 
| pfkey_lib_debug:pfkey_address_build: found address family AF_INET. 
| pfkey_lib_debug:pfkey_address_build: found address=10.29.3.225:0. 
| pfkey_lib_debug:pfkey_address_build: successful created len: 3. 
| pfkey_lib_debug:pfkey_msg_build: extentions[0] needs 16 bytes 
| pfkey_lib_debug:pfkey_msg_build: extentions[1] needs 24 bytes 
| pfkey_lib_debug:pfkey_msg_build: extentions[6] needs 24 bytes 
| pfkey_lib_debug:pfkey_msg_build: extentions[18] needs 8 bytes 
| pfkey_lib_debug:pfkey_msg_build: extentions[19] needs 24 bytes 
| pfkey_lib_debug:pfkey_msg_build: extentions[20] needs 24 bytes 
| pfkey_lib_debug:pfkey_msg_build: pfkey_msg=0p0x1811f0 allocated 120 bytes, &(extensions[0])=0p0xbec8a8a8 
| pfkey_lib_debug:pfkey_msg_build: copying 24 bytes from extensions[1] (type=1) 
| pfkey_lib_debug:pfkey_msg_build: copying 24 bytes from extensions[6] (type=6) 
| pfkey_lib_debug:pfkey_msg_build: copying 8 bytes from extensions[18] (type=18) 
| pfkey_lib_debug:pfkey_msg_build: copying 24 bytes from extensions[19] (type=19) 
| pfkey_lib_debug:pfkey_msg_build: copying 24 bytes from extensions[20] (type=20) 
| pfkey_lib_debug:pfkey_msg_parse: parsing message ver=2, type=13(x-groupsa), errno=0, satype=9(IPIP), len=15, res=0, seq=43, pid=1545. 
| pfkey_lib_debug:pfkey_msg_parse: satype 9(IPIP) conversion to proto gives 4 for msg_type 13(x-groupsa). 
| pfkey_lib_debug:pfkey_msg_parse: remain=13 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=1(security-association) remain=13. 
| pfkey_lib_debug:pfkey_msg_parse: remain=13 ext_type=1(security-association) ext_len=3 parsing ext 0p0x181200 with parser pfkey_sa_parse. 
| pfkey_lib_debug:pfkey_sa_parse: successfully found len=3 exttype=1(security-association) spi=00001007 replay=0 state=0 auth=0 encrypt=0 flags=0 ref=0. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 1(security-association) parsed. 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=6(destination-address) remain=10. 
| pfkey_lib_debug:pfkey_msg_parse: remain=10 ext_type=6(destination-address) ext_len=3 parsing ext 0p0x181218 with parser pfkey_address_parse. 
| pfkey_lib_debug:pfkey_address_parse: found exttype=6(destination-address) family=2(AF_INET) address=10.29.3.225 proto=0 port=0. 
| pfkey_lib_debug:pfkey_address_parse: successful. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 6(destination-address) parsed. 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=18(X-satype2) remain=7. 
| pfkey_lib_debug:pfkey_msg_parse: remain=7 ext_type=18(X-satype2) ext_len=1 parsing ext 0p0x181230 with parser pfkey_x_satype_parse. 
| pfkey_lib_debug:pfkey_x_satype_parse: enter 
| pfkey_lib_debug:pfkey_x_satype_parse: len=1 ext=18(X-satype2) satype=3(ESP) res=0,0,0. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 18(X-satype2) parsed. 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=19(X-security-association) remain=6. 
| pfkey_lib_debug:pfkey_msg_parse: remain=6 ext_type=19(X-security-association) ext_len=3 parsing ext 0p0x181238 with parser pfkey_sa_parse. 
| pfkey_lib_debug:pfkey_sa_parse: successfully found len=3 exttype=19(X-security-association) spi=84462a0b replay=0 state=0 auth=0 encrypt=0 flags=0 ref=0. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 19(X-security-association) parsed. 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=20(X-destination-address2) remain=3. 
| pfkey_lib_debug:pfkey_msg_parse: remain=3 ext_type=20(X-destination-address2) ext_len=3 parsing ext 0p0x181250 with parser pfkey_address_parse. 
| pfkey_lib_debug:pfkey_address_parse: found exttype=20(X-destination-address2) family=2(AF_INET) address=10.29.3.225 proto=0 port=0. 
| pfkey_lib_debug:pfkey_address_parse: successful. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 20(X-destination-address2) parsed. 
| finish_pfkey_msg: K_SADB_X_GRPSA message 43 for group unk0.1007@10.29.3.225
|   02 0d 00 09  00 0f 00 00  00 00 00 2b  00 00 06 09
|   00 03 00 01  00 00 10 07  00 00 00 00  00 00 00 00
|   00 00 00 00  00 00 00 00  00 03 00 06  00 00 00 00
|   00 02 00 00  0a 1d 03 e1  00 00 00 00  00 00 00 00
|   00 01 00 12  03 00 00 00  00 03 00 13  84 46 2a 0b
|   00 00 00 00  00 00 00 00  00 00 00 00  00 00 00 00
|   00 03 00 14  00 00 00 00  00 02 00 00  0a 1d 03 e1
|   00 00 00 00  00 00 00 00
| pfkey_get: K_SADB_X_GRPSA message 43
| pfkey_lib_debug:pfkey_extensions_free:Free extention 0 (16) 
| pfkey_lib_debug:pfkey_extensions_free:Free extention 1 (24) 
| pfkey_lib_debug:pfkey_extensions_free:Free extention 6 (24) 
| pfkey_lib_debug:pfkey_extensions_free:Free extention 18 (8) 
| pfkey_lib_debug:pfkey_extensions_free:Free extention 19 (24) 
| pfkey_lib_debug:pfkey_extensions_free:Free extention 20 (24) 
| set up outoing SA, ref=0/70
| pfkey_lib_debug:pfkey_msg_hdr_build: 
| pfkey_lib_debug:pfkey_msg_hdr_build: on_entry &pfkey_ext=0p0xbec897ac pfkey_ext=0p0xbec8a898 *pfkey_ext=0p(nil). 
| pfkey_lib_debug:pfkey_msg_hdr_build: on_exit &pfkey_ext=0p0xbec897ac pfkey_ext=0p0xbec8a898 *pfkey_ext=0p0x180368. 
| pfkey_lib_debug:pfkey_sa_build: spi=00001008 replay=0 sa_state=1 auth=0 encrypt=0 flags=0 
| pfkey_lib_debug:pfkey_address_build: exttype=5 proto=0 prefixlen=0 
| pfkey_lib_debug:pfkey_address_build: found address family AF_INET. 
| pfkey_lib_debug:pfkey_address_build: found address=10.29.3.225:0. 
| pfkey_lib_debug:pfkey_address_build: successful created len: 3. 
| pfkey_lib_debug:pfkey_address_build: exttype=6 proto=0 prefixlen=0 
| pfkey_lib_debug:pfkey_address_build: found address family AF_INET. 
| pfkey_lib_debug:pfkey_address_build: found address=10.29.3.195:0. 
| pfkey_lib_debug:pfkey_address_build: successful created len: 3. 
| pfkey_lib_debug:pfkey_msg_build: extentions[0] needs 16 bytes 
| pfkey_lib_debug:pfkey_msg_build: extentions[1] needs 24 bytes 
| pfkey_lib_debug:pfkey_msg_build: extentions[5] needs 24 bytes 
| pfkey_lib_debug:pfkey_msg_build: extentions[6] needs 24 bytes 
| pfkey_lib_debug:pfkey_msg_build: extentions[32] needs 16 bytes 
| pfkey_lib_debug:pfkey_msg_build: pfkey_msg=0p0x17dfb0 allocated 104 bytes, &(extensions[0])=0p0xbec8a898 
| pfkey_lib_debug:pfkey_msg_build: copying 24 bytes from extensions[1] (type=1) 
| pfkey_lib_debug:pfkey_msg_build: copying 24 bytes from extensions[5] (type=5) 
| pfkey_lib_debug:pfkey_msg_build: copying 24 bytes from extensions[6] (type=6) 
| pfkey_lib_debug:pfkey_msg_build: copying 16 bytes from extensions[32] (type=32) 
| pfkey_lib_debug:pfkey_msg_parse: parsing message ver=2, type=3(add), errno=0, satype=9(IPIP), len=13, res=0, seq=44, pid=1545. 
| pfkey_lib_debug:pfkey_msg_parse: satype 9(IPIP) conversion to proto gives 4 for msg_type 3(add). 
| pfkey_lib_debug:pfkey_msg_parse: remain=11 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=1(security-association) remain=11. 
| pfkey_lib_debug:pfkey_msg_parse: remain=11 ext_type=1(security-association) ext_len=3 parsing ext 0p0x17dfc0 with parser pfkey_sa_parse. 
| pfkey_lib_debug:pfkey_sa_parse: successfully found len=3 exttype=1(security-association) spi=00001008 replay=0 state=1 auth=0 encrypt=0 flags=0 ref=0. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 1(security-association) parsed. 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=5(source-address) remain=8. 
| pfkey_lib_debug:pfkey_msg_parse: remain=8 ext_type=5(source-address) ext_len=3 parsing ext 0p0x17dfd8 with parser pfkey_address_parse. 
| pfkey_lib_debug:pfkey_address_parse: found exttype=5(source-address) family=2(AF_INET) address=10.29.3.225 proto=0 port=0. 
| pfkey_lib_debug:pfkey_address_parse: successful. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 5(source-address) parsed. 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=6(destination-address) remain=5. 
| pfkey_lib_debug:pfkey_msg_parse: remain=5 ext_type=6(destination-address) ext_len=3 parsing ext 0p0x17dff0 with parser pfkey_address_parse. 
| pfkey_lib_debug:pfkey_address_parse: found exttype=6(destination-address) family=2(AF_INET) address=10.29.3.195 proto=0 port=0. 
| pfkey_lib_debug:pfkey_address_parse: successful. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 6(destination-address) parsed. 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=32(X-saref) remain=2. 
| pfkey_lib_debug:pfkey_msg_parse: remain=2 ext_type=32(X-saref) ext_len=2 parsing ext 0p0x17e008 with parser pfkey_x_ext_saref_parse. 
| pfkey_lib_debug:pfkey_x_saref_parse: 
| pfkey_lib_debug:pfkey_msg_parse: Extension 32(X-saref) parsed. 
| finish_pfkey_msg: K_SADB_ADD message 44 for Add SA tun.1008@10.29.3.225
|   02 03 00 09  00 0d 00 00  00 00 00 2c  00 00 06 09
|   00 03 00 01  00 00 10 08  00 01 00 00  00 00 00 00
|   00 00 00 00  00 00 00 00  00 03 00 05  00 00 00 00
|   00 02 00 00  0a 1d 03 e1  00 00 00 00  00 00 00 00
|   00 03 00 06  00 00 00 00  00 02 00 00  0a 1d 03 c3
|   00 00 00 00  00 00 00 00  00 02 00 20  00 00 00 00
|   00 00 00 46  00 00 00 00
| pfkey_get: K_SADB_ADD message 44
| pfkey_lib_debug:pfkey_extensions_free:Free extention 0 (16) 
| pfkey_lib_debug:pfkey_extensions_free:Free extention 1 (24) 
| pfkey_lib_debug:pfkey_extensions_free:Free extention 5 (24) 
| pfkey_lib_debug:pfkey_extensions_free:Free extention 6 (24) 
| pfkey_lib_debug:pfkey_extensions_free:Free extention 32 (16) 
| pfkey_lib_debug:pfkey_msg_parse: parsing message ver=2, type=3(add), errno=0, satype=9(IPIP), len=13, res=0, seq=44, pid=1545. 
| pfkey_lib_debug:pfkey_msg_parse: satype 9(IPIP) conversion to proto gives 4 for msg_type 3(add). 
| pfkey_lib_debug:pfkey_msg_parse: remain=11 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=1(security-association) remain=11. 
| pfkey_lib_debug:pfkey_msg_parse: remain=11 ext_type=1(security-association) ext_len=3 parsing ext 0p0xbec898a8 with parser pfkey_sa_parse. 
| pfkey_lib_debug:pfkey_sa_parse: successfully found len=3 exttype=1(security-association) spi=00001008 replay=0 state=1 auth=0 encrypt=0 flags=1 ref=0. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 1(security-association) parsed. 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=5(source-address) remain=8. 
| pfkey_lib_debug:pfkey_msg_parse: remain=8 ext_type=5(source-address) ext_len=3 parsing ext 0p0xbec898c0 with parser pfkey_address_parse. 
| pfkey_lib_debug:pfkey_address_parse: found exttype=5(source-address) family=2(AF_INET) address=10.29.3.225 proto=0 port=0. 
| pfkey_lib_debug:pfkey_address_parse: successful. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 5(source-address) parsed. 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=6(destination-address) remain=5. 
| pfkey_lib_debug:pfkey_msg_parse: remain=5 ext_type=6(destination-address) ext_len=3 parsing ext 0p0xbec898d8 with parser pfkey_address_parse. 
| pfkey_lib_debug:pfkey_address_parse: found exttype=6(destination-address) family=2(AF_INET) address=10.29.3.195 proto=0 port=0. 
| pfkey_lib_debug:pfkey_address_parse: successful. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 6(destination-address) parsed. 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=32(X-saref) remain=2. 
| pfkey_lib_debug:pfkey_msg_parse: remain=2 ext_type=32(X-saref) ext_len=2 parsing ext 0p0xbec898f0 with parser pfkey_x_ext_saref_parse. 
| pfkey_lib_debug:pfkey_x_saref_parse: 
| pfkey_lib_debug:pfkey_msg_parse: Extension 32(X-saref) parsed. 
| added tunnel with ref=72
| looking for alg with transid: 12 keylen: 256 auth: 1 
| checking transid: 11 keylen: 0 auth: 1 
| checking transid: 11 keylen: 0 auth: 2 
| checking transid: 2 keylen: 8 auth: 0 
| checking transid: 2 keylen: 8 auth: 1 
| checking transid: 2 keylen: 8 auth: 2 
| checking transid: 3 keylen: 24 auth: 0 
| checking transid: 3 keylen: 24 auth: 1 
| checking transid: 3 keylen: 24 auth: 2 
| checking transid: 12 keylen: 16 auth: 0 
| checking transid: 12 keylen: 16 auth: 1 
| checking transid: 12 keylen: 16 auth: 2 
| kernel_alg_esp_info():transid=12, auth=1, ei=0x1670e8, enckeylen=32, authkeylen=16, encryptalg=12, authalg=2
| esp enckey:  ab 1a 24 4c  d7 1d 8d e6  2b 8d a7 f4  0f 66 6e 20
| esp enckey:  79 5e 78 48  da 67 b2 0e  74 d8 1d 44  ee 41 ed cc
| esp authkey:  32 ab 0a e5  4b d9 39 62  82 ea c7 47  34 23 0d 00
| pfkey_lib_debug:pfkey_msg_hdr_build: 
| pfkey_lib_debug:pfkey_msg_hdr_build: on_entry &pfkey_ext=0p0xbec897ac pfkey_ext=0p0xbec8a898 *pfkey_ext=0p(nil). 
| pfkey_lib_debug:pfkey_msg_hdr_build: on_exit &pfkey_ext=0p0xbec897ac pfkey_ext=0p0xbec8a898 *pfkey_ext=0p0x1802f8. 
| pfkey_lib_debug:pfkey_sa_build: spi=23900d3f replay=64 sa_state=1 auth=2 encrypt=12 flags=0 
| pfkey_lib_debug:pfkey_address_build: exttype=5 proto=0 prefixlen=0 
| pfkey_lib_debug:pfkey_address_build: found address family AF_INET. 
| pfkey_lib_debug:pfkey_address_build: found address=10.29.3.225:0. 
| pfkey_lib_debug:pfkey_address_build: successful created len: 3. 
| pfkey_lib_debug:pfkey_address_build: exttype=6 proto=0 prefixlen=0 
| pfkey_lib_debug:pfkey_address_build: found address family AF_INET. 
| pfkey_lib_debug:pfkey_address_build: found address=10.29.3.195:0. 
| pfkey_lib_debug:pfkey_address_build: successful created len: 3. 
| pfkey_lib_debug:pfkey_key_build: 
| pfkey_lib_debug:pfkey_key_build: 
| pfkey_lib_debug:pfkey_msg_build: extentions[0] needs 16 bytes 
| pfkey_lib_debug:pfkey_msg_build: extentions[1] needs 24 bytes 
| pfkey_lib_debug:pfkey_msg_build: extentions[5] needs 24 bytes 
| pfkey_lib_debug:pfkey_msg_build: extentions[6] needs 24 bytes 
| pfkey_lib_debug:pfkey_msg_build: extentions[8] needs 24 bytes 
| pfkey_lib_debug:pfkey_msg_build: extentions[9] needs 40 bytes 
| pfkey_lib_debug:pfkey_msg_build: extentions[32] needs 16 bytes 
| pfkey_lib_debug:pfkey_msg_build: pfkey_msg=0p0x1811f0 allocated 168 bytes, &(extensions[0])=0p0xbec8a898 
| pfkey_lib_debug:pfkey_msg_build: copying 24 bytes from extensions[1] (type=1) 
| pfkey_lib_debug:pfkey_msg_build: copying 24 bytes from extensions[5] (type=5) 
| pfkey_lib_debug:pfkey_msg_build: copying 24 bytes from extensions[6] (type=6) 
| pfkey_lib_debug:pfkey_msg_build: copying 24 bytes from extensions[8] (type=8) 
| pfkey_lib_debug:pfkey_msg_build: copying 40 bytes from extensions[9] (type=9) 
| pfkey_lib_debug:pfkey_msg_build: copying 16 bytes from extensions[32] (type=32) 
| pfkey_lib_debug:pfkey_msg_parse: parsing message ver=2, type=3(add), errno=0, satype=3(ESP), len=21, res=0, seq=45, pid=1545. 
| pfkey_lib_debug:pfkey_msg_parse: satype 3(ESP) conversion to proto gives 50 for msg_type 3(add). 
| pfkey_lib_debug:pfkey_msg_parse: remain=19 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=1(security-association) remain=19. 
| pfkey_lib_debug:pfkey_msg_parse: remain=19 ext_type=1(security-association) ext_len=3 parsing ext 0p0x181200 with parser pfkey_sa_parse. 
| pfkey_lib_debug:pfkey_sa_parse: successfully found len=3 exttype=1(security-association) spi=23900d3f replay=64 state=1 auth=2 encrypt=12 flags=0 ref=0. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 1(security-association) parsed. 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=5(source-address) remain=16. 
| pfkey_lib_debug:pfkey_msg_parse: remain=16 ext_type=5(source-address) ext_len=3 parsing ext 0p0x181218 with parser pfkey_address_parse. 
| pfkey_lib_debug:pfkey_address_parse: found exttype=5(source-address) family=2(AF_INET) address=10.29.3.225 proto=0 port=0. 
| pfkey_lib_debug:pfkey_address_parse: successful. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 5(source-address) parsed. 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=6(destination-address) remain=13. 
| pfkey_lib_debug:pfkey_msg_parse: remain=13 ext_type=6(destination-address) ext_len=3 parsing ext 0p0x181230 with parser pfkey_address_parse. 
| pfkey_lib_debug:pfkey_address_parse: found exttype=6(destination-address) family=2(AF_INET) address=10.29.3.195 proto=0 port=0. 
| pfkey_lib_debug:pfkey_address_parse: successful. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 6(destination-address) parsed. 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=8(authentication-key) remain=10. 
| pfkey_lib_debug:pfkey_msg_parse: remain=10 ext_type=8(authentication-key) ext_len=3 parsing ext 0p0x181248 with parser pfkey_key_parse. 
| pfkey_lib_debug:pfkey_key_parse: success, found len=3 exttype=8(authentication-key) bits=128 reserved=0. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 8(authentication-key) parsed. 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=9(cipher-key) remain=7. 
| pfkey_lib_debug:pfkey_msg_parse: remain=7 ext_type=9(cipher-key) ext_len=5 parsing ext 0p0x181260 with parser pfkey_key_parse. 
| pfkey_lib_debug:pfkey_key_parse: success, found len=5 exttype=9(cipher-key) bits=256 reserved=0. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 9(cipher-key) parsed. 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=32(X-saref) remain=2. 
| pfkey_lib_debug:pfkey_msg_parse: remain=2 ext_type=32(X-saref) ext_len=2 parsing ext 0p0x181288 with parser pfkey_x_ext_saref_parse. 
| pfkey_lib_debug:pfkey_x_saref_parse: 
| pfkey_lib_debug:pfkey_msg_parse: Extension 32(X-saref) parsed. 
| finish_pfkey_msg: K_SADB_ADD message 45 for Add SA esp.23900d3f@10.29.3.195
|   02 03 00 03  00 15 00 00  00 00 00 2d  00 00 06 09
|   00 03 00 01  23 90 0d 3f  40 01 02 0c  00 00 00 00
|   00 00 00 00  00 00 00 00  00 03 00 05  00 00 00 00
|   00 02 00 00  0a 1d 03 e1  00 00 00 00  00 00 00 00
|   00 03 00 06  00 00 00 00  00 02 00 00  0a 1d 03 c3
|   00 00 00 00  00 00 00 00  00 03 00 08  00 80 00 00
|   32 ab 0a e5  4b d9 39 62  82 ea c7 47  34 23 0d 00
|   00 05 00 09  01 00 00 00  ab 1a 24 4c  d7 1d 8d e6
|   2b 8d a7 f4  0f 66 6e 20  79 5e 78 48  da 67 b2 0e
|   74 d8 1d 44  ee 41 ed cc  00 02 00 20  00 00 00 00
|   00 00 00 46  00 00 00 00
| pfkey_get: K_SADB_ADD message 45
| pfkey_lib_debug:pfkey_extensions_free:Free extention 0 (16) 
| pfkey_lib_debug:pfkey_extensions_free:Free extention 1 (24) 
| pfkey_lib_debug:pfkey_extensions_free:Free extention 5 (24) 
| pfkey_lib_debug:pfkey_extensions_free:Free extention 6 (24) 
| pfkey_lib_debug:pfkey_extensions_free:Free extention 8 (24) 
| pfkey_lib_debug:pfkey_extensions_free:Free extention 9 (40) 
| pfkey_lib_debug:pfkey_extensions_free:Free extention 32 (16) 
| pfkey_lib_debug:pfkey_msg_parse: parsing message ver=2, type=3(add), errno=0, satype=3(ESP), len=13, res=0, seq=45, pid=1545. 
| pfkey_lib_debug:pfkey_msg_parse: satype 3(ESP) conversion to proto gives 50 for msg_type 3(add). 
| pfkey_lib_debug:pfkey_msg_parse: remain=11 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=1(security-association) remain=11. 
| pfkey_lib_debug:pfkey_msg_parse: remain=11 ext_type=1(security-association) ext_len=3 parsing ext 0p0xbec898a8 with parser pfkey_sa_parse. 
| pfkey_lib_debug:pfkey_sa_parse: successfully found len=3 exttype=1(security-association) spi=23900d3f replay=64 state=1 auth=2 encrypt=12 flags=1 ref=0. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 1(security-association) parsed. 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=5(source-address) remain=8. 
| pfkey_lib_debug:pfkey_msg_parse: remain=8 ext_type=5(source-address) ext_len=3 parsing ext 0p0xbec898c0 with parser pfkey_address_parse. 
| pfkey_lib_debug:pfkey_address_parse: found exttype=5(source-address) family=2(AF_INET) address=10.29.3.225 proto=0 port=0. 
| pfkey_lib_debug:pfkey_address_parse: successful. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 5(source-address) parsed. 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=6(destination-address) remain=5. 
| pfkey_lib_debug:pfkey_msg_parse: remain=5 ext_type=6(destination-address) ext_len=3 parsing ext 0p0xbec898d8 with parser pfkey_address_parse. 
| pfkey_lib_debug:pfkey_address_parse: found exttype=6(destination-address) family=2(AF_INET) address=10.29.3.195 proto=0 port=0. 
| pfkey_lib_debug:pfkey_address_parse: successful. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 6(destination-address) parsed. 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=32(X-saref) remain=2. 
| pfkey_lib_debug:pfkey_msg_parse: remain=2 ext_type=32(X-saref) ext_len=2 parsing ext 0p0xbec898f0 with parser pfkey_x_ext_saref_parse. 
| pfkey_lib_debug:pfkey_x_saref_parse: 
| pfkey_lib_debug:pfkey_msg_parse: Extension 32(X-saref) parsed. 
| add inbound eroute 192.168.123.0/24:0 --0-> 192.168.1.0/24:0 => tun.1008@10.29.3.195 (raw_eroute)
| pfkey_lib_debug:pfkey_msg_hdr_build: 
| pfkey_lib_debug:pfkey_msg_hdr_build: on_entry &pfkey_ext=0p0xbec8a668 pfkey_ext=0p0xbec8a74c *pfkey_ext=0p(nil). 
| pfkey_lib_debug:pfkey_msg_hdr_build: on_exit &pfkey_ext=0p0xbec8a668 pfkey_ext=0p0xbec8a74c *pfkey_ext=0p0x180368. 
| pfkey_lib_debug:pfkey_sa_build: spi=00001008 replay=0 sa_state=0 auth=0 encrypt=0 flags=8 
| pfkey_lib_debug:pfkey_address_build: exttype=5 proto=0 prefixlen=0 
| pfkey_lib_debug:pfkey_address_build: found address family AF_INET. 
| pfkey_lib_debug:pfkey_address_build: found address=10.29.3.225:0. 
| pfkey_lib_debug:pfkey_address_build: successful created len: 3. 
| pfkey_lib_debug:pfkey_address_build: exttype=6 proto=0 prefixlen=0 
| pfkey_lib_debug:pfkey_address_build: found address family AF_INET. 
| pfkey_lib_debug:pfkey_address_build: found address=10.29.3.195:0. 
| pfkey_lib_debug:pfkey_address_build: successful created len: 3. 
| pfkey_lib_debug:pfkey_address_build: exttype=21 proto=0 prefixlen=0 
| pfkey_lib_debug:pfkey_address_build: found address family AF_INET. 
| pfkey_lib_debug:pfkey_address_build: found address=192.168.123.0:0. 
| pfkey_lib_debug:pfkey_address_build: successful created len: 3. 
| pfkey_lib_debug:pfkey_address_build: exttype=22 proto=0 prefixlen=0 
| pfkey_lib_debug:pfkey_address_build: found address family AF_INET. 
| pfkey_lib_debug:pfkey_address_build: found address=192.168.1.0:0. 
| pfkey_lib_debug:pfkey_address_build: successful created len: 3. 
| pfkey_lib_debug:pfkey_address_build: exttype=23 proto=0 prefixlen=0 
| pfkey_lib_debug:pfkey_address_build: found address family AF_INET. 
| pfkey_lib_debug:pfkey_address_build: found address=255.255.255.0:0. 
| pfkey_lib_debug:pfkey_address_build: successful created len: 3. 
| pfkey_lib_debug:pfkey_address_build: exttype=24 proto=0 prefixlen=0 
| pfkey_lib_debug:pfkey_address_build: found address family AF_INET. 
| pfkey_lib_debug:pfkey_address_build: found address=255.255.255.0:0. 
| pfkey_lib_debug:pfkey_address_build: successful created len: 3. 
| pfkey_lib_debug:pfkey_msg_build: extentions[0] needs 16 bytes 
| pfkey_lib_debug:pfkey_msg_build: extentions[1] needs 24 bytes 
| pfkey_lib_debug:pfkey_msg_build: extentions[5] needs 24 bytes 
| pfkey_lib_debug:pfkey_msg_build: extentions[6] needs 24 bytes 
| pfkey_lib_debug:pfkey_msg_build: extentions[21] needs 24 bytes 
| pfkey_lib_debug:pfkey_msg_build: extentions[22] needs 24 bytes 
| pfkey_lib_debug:pfkey_msg_build: extentions[23] needs 24 bytes 
| pfkey_lib_debug:pfkey_msg_build: extentions[24] needs 24 bytes 
| pfkey_lib_debug:pfkey_msg_build: pfkey_msg=0p0x1811f0 allocated 184 bytes, &(extensions[0])=0p0xbec8a74c 
| pfkey_lib_debug:pfkey_msg_build: copying 24 bytes from extensions[1] (type=1) 
| pfkey_lib_debug:pfkey_msg_build: copying 24 bytes from extensions[5] (type=5) 
| pfkey_lib_debug:pfkey_msg_build: copying 24 bytes from extensions[6] (type=6) 
| pfkey_lib_debug:pfkey_msg_build: copying 24 bytes from extensions[21] (type=21) 
| pfkey_lib_debug:pfkey_msg_build: copying 24 bytes from extensions[22] (type=22) 
| pfkey_lib_debug:pfkey_msg_build: copying 24 bytes from extensions[23] (type=23) 
| pfkey_lib_debug:pfkey_msg_build: copying 24 bytes from extensions[24] (type=24) 
| pfkey_lib_debug:pfkey_msg_parse: parsing message ver=2, type=14(x-addflow(eroute)), errno=0, satype=9(IPIP), len=23, res=0, seq=46, pid=1545. 
| pfkey_lib_debug:pfkey_msg_parse: satype 9(IPIP) conversion to proto gives 4 for msg_type 14(x-addflow(eroute)). 
| pfkey_lib_debug:pfkey_msg_parse: remain=21 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=1(security-association) remain=21. 
| pfkey_lib_debug:pfkey_msg_parse: remain=21 ext_type=1(security-association) ext_len=3 parsing ext 0p0x181200 with parser pfkey_sa_parse. 
| pfkey_lib_debug:pfkey_sa_parse: successfully found len=3 exttype=1(security-association) spi=00001008 replay=0 state=0 auth=0 encrypt=0 flags=8 ref=0. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 1(security-association) parsed. 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=5(source-address) remain=18. 
| pfkey_lib_debug:pfkey_msg_parse: remain=18 ext_type=5(source-address) ext_len=3 parsing ext 0p0x181218 with parser pfkey_address_parse. 
| pfkey_lib_debug:pfkey_address_parse: found exttype=5(source-address) family=2(AF_INET) address=10.29.3.225 proto=0 port=0. 
| pfkey_lib_debug:pfkey_address_parse: successful. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 5(source-address) parsed. 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=6(destination-address) remain=15. 
| pfkey_lib_debug:pfkey_msg_parse: remain=15 ext_type=6(destination-address) ext_len=3 parsing ext 0p0x181230 with parser pfkey_address_parse. 
| pfkey_lib_debug:pfkey_address_parse: found exttype=6(destination-address) family=2(AF_INET) address=10.29.3.195 proto=0 port=0. 
| pfkey_lib_debug:pfkey_address_parse: successful. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 6(destination-address) parsed. 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=21(X-source-flow-address) remain=12. 
| pfkey_lib_debug:pfkey_msg_parse: remain=12 ext_type=21(X-source-flow-address) ext_len=3 parsing ext 0p0x181248 with parser pfkey_address_parse. 
| pfkey_lib_debug:pfkey_address_parse: found exttype=21(X-source-flow-address) family=2(AF_INET) address=192.168.123.0 proto=0 port=0. 
| pfkey_lib_debug:pfkey_address_parse: successful. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 21(X-source-flow-address) parsed. 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=22(X-dest-flow-address) remain=9. 
| pfkey_lib_debug:pfkey_msg_parse: remain=9 ext_type=22(X-dest-flow-address) ext_len=3 parsing ext 0p0x181260 with parser pfkey_address_parse. 
| pfkey_lib_debug:pfkey_address_parse: found exttype=22(X-dest-flow-address) family=2(AF_INET) address=192.168.1.0 proto=0 port=0. 
| pfkey_lib_debug:pfkey_address_parse: successful. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 22(X-dest-flow-address) parsed. 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=23(X-source-mask) remain=6. 
| pfkey_lib_debug:pfkey_msg_parse: remain=6 ext_type=23(X-source-mask) ext_len=3 parsing ext 0p0x181278 with parser pfkey_address_parse. 
| pfkey_lib_debug:pfkey_address_parse: found exttype=23(X-source-mask) family=2(AF_INET) address=255.255.255.0 proto=0 port=0. 
| pfkey_lib_debug:pfkey_address_parse: successful. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 23(X-source-mask) parsed. 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=24(X-dest-mask) remain=3. 
| pfkey_lib_debug:pfkey_msg_parse: remain=3 ext_type=24(X-dest-mask) ext_len=3 parsing ext 0p0x181290 with parser pfkey_address_parse. 
| pfkey_lib_debug:pfkey_address_parse: found exttype=24(X-dest-mask) family=2(AF_INET) address=255.255.255.0 proto=0 port=0. 
| pfkey_lib_debug:pfkey_address_parse: successful. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 24(X-dest-mask) parsed. 
| finish_pfkey_msg: K_SADB_X_ADDFLOW message 46 for flow tun.1008@10.29.3.195
|   02 0e 00 09  00 17 00 00  00 00 00 2e  00 00 06 09
|   00 03 00 01  00 00 10 08  00 00 00 00  00 00 00 08
|   00 00 00 00  00 00 00 00  00 03 00 05  00 00 00 00
|   00 02 00 00  0a 1d 03 e1  00 00 00 00  00 00 00 00
|   00 03 00 06  00 00 00 00  00 02 00 00  0a 1d 03 c3
|   00 00 00 00  00 00 00 00  00 03 00 15  00 00 00 00
|   00 02 00 00  c0 a8 7b 00  00 00 00 00  00 00 00 00
|   00 03 00 16  00 00 00 00  00 02 00 00  c0 a8 01 00
|   00 00 00 00  00 00 00 00  00 03 00 17  00 00 00 00
|   00 02 00 00  ff ff ff 00  00 00 00 00  00 00 00 00
|   00 03 00 18  00 00 00 00  00 02 00 00  ff ff ff 00
|   00 00 00 00  00 00 00 00
| pfkey_get: K_SADB_X_ADDFLOW message 46
| pfkey_lib_debug:pfkey_extensions_free:Free extention 0 (16) 
| pfkey_lib_debug:pfkey_extensions_free:Free extention 1 (24) 
| pfkey_lib_debug:pfkey_extensions_free:Free extention 5 (24) 
| pfkey_lib_debug:pfkey_extensions_free:Free extention 6 (24) 
| pfkey_lib_debug:pfkey_extensions_free:Free extention 21 (24) 
| pfkey_lib_debug:pfkey_extensions_free:Free extention 22 (24) 
| pfkey_lib_debug:pfkey_extensions_free:Free extention 23 (24) 
| pfkey_lib_debug:pfkey_extensions_free:Free extention 24 (24) 
| raw_eroute result=1 
| grouping unk0.1008@10.29.3.195 (ref=72) and unk0.23900d3f@10.29.3.195 (ref=73)
| pfkey_lib_debug:pfkey_msg_hdr_build: 
| pfkey_lib_debug:pfkey_msg_hdr_build: on_entry &pfkey_ext=0p0xbec8a844 pfkey_ext=0p0xbec8a8a8 *pfkey_ext=0p(nil). 
| pfkey_lib_debug:pfkey_msg_hdr_build: on_exit &pfkey_ext=0p0xbec8a844 pfkey_ext=0p0xbec8a8a8 *pfkey_ext=0p0x1802f8. 
| pfkey_lib_debug:pfkey_sa_build: spi=00001008 replay=0 sa_state=0 auth=0 encrypt=0 flags=0 
| pfkey_lib_debug:pfkey_address_build: exttype=6 proto=0 prefixlen=0 
| pfkey_lib_debug:pfkey_address_build: found address family AF_INET. 
| pfkey_lib_debug:pfkey_address_build: found address=10.29.3.195:0. 
| pfkey_lib_debug:pfkey_address_build: successful created len: 3. 
| pfkey_lib_debug:pfkey_x_satype_build: 
| pfkey_lib_debug:pfkey_sa_build: spi=23900d3f replay=0 sa_state=0 auth=0 encrypt=0 flags=0 
| pfkey_lib_debug:pfkey_address_build: exttype=20 proto=0 prefixlen=0 
| pfkey_lib_debug:pfkey_address_build: found address family AF_INET. 
| pfkey_lib_debug:pfkey_address_build: found address=10.29.3.195:0. 
| pfkey_lib_debug:pfkey_address_build: successful created len: 3. 
| pfkey_lib_debug:pfkey_msg_build: extentions[0] needs 16 bytes 
| pfkey_lib_debug:pfkey_msg_build: extentions[1] needs 24 bytes 
| pfkey_lib_debug:pfkey_msg_build: extentions[6] needs 24 bytes 
| pfkey_lib_debug:pfkey_msg_build: extentions[18] needs 8 bytes 
| pfkey_lib_debug:pfkey_msg_build: extentions[19] needs 24 bytes 
| pfkey_lib_debug:pfkey_msg_build: extentions[20] needs 24 bytes 
| pfkey_lib_debug:pfkey_msg_build: pfkey_msg=0p0x1811f0 allocated 120 bytes, &(extensions[0])=0p0xbec8a8a8 
| pfkey_lib_debug:pfkey_msg_build: copying 24 bytes from extensions[1] (type=1) 
| pfkey_lib_debug:pfkey_msg_build: copying 24 bytes from extensions[6] (type=6) 
| pfkey_lib_debug:pfkey_msg_build: copying 8 bytes from extensions[18] (type=18) 
| pfkey_lib_debug:pfkey_msg_build: copying 24 bytes from extensions[19] (type=19) 
| pfkey_lib_debug:pfkey_msg_build: copying 24 bytes from extensions[20] (type=20) 
| pfkey_lib_debug:pfkey_msg_parse: parsing message ver=2, type=13(x-groupsa), errno=0, satype=9(IPIP), len=15, res=0, seq=47, pid=1545. 
| pfkey_lib_debug:pfkey_msg_parse: satype 9(IPIP) conversion to proto gives 4 for msg_type 13(x-groupsa). 
| pfkey_lib_debug:pfkey_msg_parse: remain=13 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=1(security-association) remain=13. 
| pfkey_lib_debug:pfkey_msg_parse: remain=13 ext_type=1(security-association) ext_len=3 parsing ext 0p0x181200 with parser pfkey_sa_parse. 
| pfkey_lib_debug:pfkey_sa_parse: successfully found len=3 exttype=1(security-association) spi=00001008 replay=0 state=0 auth=0 encrypt=0 flags=0 ref=0. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 1(security-association) parsed. 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=6(destination-address) remain=10. 
| pfkey_lib_debug:pfkey_msg_parse: remain=10 ext_type=6(destination-address) ext_len=3 parsing ext 0p0x181218 with parser pfkey_address_parse. 
| pfkey_lib_debug:pfkey_address_parse: found exttype=6(destination-address) family=2(AF_INET) address=10.29.3.195 proto=0 port=0. 
| pfkey_lib_debug:pfkey_address_parse: successful. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 6(destination-address) parsed. 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=18(X-satype2) remain=7. 
| pfkey_lib_debug:pfkey_msg_parse: remain=7 ext_type=18(X-satype2) ext_len=1 parsing ext 0p0x181230 with parser pfkey_x_satype_parse. 
| pfkey_lib_debug:pfkey_x_satype_parse: enter 
| pfkey_lib_debug:pfkey_x_satype_parse: len=1 ext=18(X-satype2) satype=3(ESP) res=0,0,0. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 18(X-satype2) parsed. 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=19(X-security-association) remain=6. 
| pfkey_lib_debug:pfkey_msg_parse: remain=6 ext_type=19(X-security-association) ext_len=3 parsing ext 0p0x181238 with parser pfkey_sa_parse. 
| pfkey_lib_debug:pfkey_sa_parse: successfully found len=3 exttype=19(X-security-association) spi=23900d3f replay=0 state=0 auth=0 encrypt=0 flags=0 ref=0. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 19(X-security-association) parsed. 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=20(X-destination-address2) remain=3. 
| pfkey_lib_debug:pfkey_msg_parse: remain=3 ext_type=20(X-destination-address2) ext_len=3 parsing ext 0p0x181250 with parser pfkey_address_parse. 
| pfkey_lib_debug:pfkey_address_parse: found exttype=20(X-destination-address2) family=2(AF_INET) address=10.29.3.195 proto=0 port=0. 
| pfkey_lib_debug:pfkey_address_parse: successful. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 20(X-destination-address2) parsed. 
| finish_pfkey_msg: K_SADB_X_GRPSA message 47 for group unk0.1008@10.29.3.195
|   02 0d 00 09  00 0f 00 00  00 00 00 2f  00 00 06 09
|   00 03 00 01  00 00 10 08  00 00 00 00  00 00 00 00
|   00 00 00 00  00 00 00 00  00 03 00 06  00 00 00 00
|   00 02 00 00  0a 1d 03 c3  00 00 00 00  00 00 00 00
|   00 01 00 12  03 00 00 00  00 03 00 13  23 90 0d 3f
|   00 00 00 00  00 00 00 00  00 00 00 00  00 00 00 00
|   00 03 00 14  00 00 00 00  00 02 00 00  0a 1d 03 c3
|   00 00 00 00  00 00 00 00
| pfkey_get: K_SADB_X_GRPSA message 47
| pfkey_lib_debug:pfkey_extensions_free:Free extention 0 (16) 
| pfkey_lib_debug:pfkey_extensions_free:Free extention 1 (24) 
| pfkey_lib_debug:pfkey_extensions_free:Free extention 6 (24) 
| pfkey_lib_debug:pfkey_extensions_free:Free extention 18 (8) 
| pfkey_lib_debug:pfkey_extensions_free:Free extention 19 (24) 
| pfkey_lib_debug:pfkey_extensions_free:Free extention 20 (24) 
| set up incoming SA, ref=72/70
| sr for #8: unrouted
| route owner of "site_192.168.1.0_24-192.168.123.0_24" unrouted: NULL; eroute owner: NULL
| route_and_eroute with c: site_192.168.1.0_24-192.168.123.0_24 (next: none) ero:null esr:{(nil)} ro:null rosr:{(nil)} and state: 8
| eroute_connection add eroute 192.168.1.0/24:0 --0-> 192.168.123.0/24:0 => tun.1007@10.29.3.225 (raw_eroute)
| pfkey_lib_debug:pfkey_msg_hdr_build: 
| pfkey_lib_debug:pfkey_msg_hdr_build: on_entry &pfkey_ext=0p0xbec8a704 pfkey_ext=0p0xbec8a7e8 *pfkey_ext=0p(nil). 
| pfkey_lib_debug:pfkey_msg_hdr_build: on_exit &pfkey_ext=0p0xbec8a704 pfkey_ext=0p0xbec8a7e8 *pfkey_ext=0p0x180368. 
| pfkey_lib_debug:pfkey_sa_build: spi=00001007 replay=0 sa_state=0 auth=0 encrypt=0 flags=0 
| pfkey_lib_debug:pfkey_address_build: exttype=5 proto=0 prefixlen=0 
| pfkey_lib_debug:pfkey_address_build: found address family AF_INET. 
| pfkey_lib_debug:pfkey_address_build: found address=10.29.3.195:0. 
| pfkey_lib_debug:pfkey_address_build: successful created len: 3. 
| pfkey_lib_debug:pfkey_address_build: exttype=6 proto=0 prefixlen=0 
| pfkey_lib_debug:pfkey_address_build: found address family AF_INET. 
| pfkey_lib_debug:pfkey_address_build: found address=10.29.3.225:0. 
| pfkey_lib_debug:pfkey_address_build: successful created len: 3. 
| pfkey_lib_debug:pfkey_address_build: exttype=21 proto=0 prefixlen=0 
| pfkey_lib_debug:pfkey_address_build: found address family AF_INET. 
| pfkey_lib_debug:pfkey_address_build: found address=192.168.1.0:0. 
| pfkey_lib_debug:pfkey_address_build: successful created len: 3. 
| pfkey_lib_debug:pfkey_address_build: exttype=22 proto=0 prefixlen=0 
| pfkey_lib_debug:pfkey_address_build: found address family AF_INET. 
| pfkey_lib_debug:pfkey_address_build: found address=192.168.123.0:0. 
| pfkey_lib_debug:pfkey_address_build: successful created len: 3. 
| pfkey_lib_debug:pfkey_address_build: exttype=23 proto=0 prefixlen=0 
| pfkey_lib_debug:pfkey_address_build: found address family AF_INET. 
| pfkey_lib_debug:pfkey_address_build: found address=255.255.255.0:0. 
| pfkey_lib_debug:pfkey_address_build: successful created len: 3. 
| pfkey_lib_debug:pfkey_address_build: exttype=24 proto=0 prefixlen=0 
| pfkey_lib_debug:pfkey_address_build: found address family AF_INET. 
| pfkey_lib_debug:pfkey_address_build: found address=255.255.255.0:0. 
| pfkey_lib_debug:pfkey_address_build: successful created len: 3. 
| pfkey_lib_debug:pfkey_msg_build: extentions[0] needs 16 bytes 
| pfkey_lib_debug:pfkey_msg_build: extentions[1] needs 24 bytes 
| pfkey_lib_debug:pfkey_msg_build: extentions[5] needs 24 bytes 
| pfkey_lib_debug:pfkey_msg_build: extentions[6] needs 24 bytes 
| pfkey_lib_debug:pfkey_msg_build: extentions[21] needs 24 bytes 
| pfkey_lib_debug:pfkey_msg_build: extentions[22] needs 24 bytes 
| pfkey_lib_debug:pfkey_msg_build: extentions[23] needs 24 bytes 
| pfkey_lib_debug:pfkey_msg_build: extentions[24] needs 24 bytes 
| pfkey_lib_debug:pfkey_msg_build: pfkey_msg=0p0x1811f0 allocated 184 bytes, &(extensions[0])=0p0xbec8a7e8 
| pfkey_lib_debug:pfkey_msg_build: copying 24 bytes from extensions[1] (type=1) 
| pfkey_lib_debug:pfkey_msg_build: copying 24 bytes from extensions[5] (type=5) 
| pfkey_lib_debug:pfkey_msg_build: copying 24 bytes from extensions[6] (type=6) 
| pfkey_lib_debug:pfkey_msg_build: copying 24 bytes from extensions[21] (type=21) 
| pfkey_lib_debug:pfkey_msg_build: copying 24 bytes from extensions[22] (type=22) 
| pfkey_lib_debug:pfkey_msg_build: copying 24 bytes from extensions[23] (type=23) 
| pfkey_lib_debug:pfkey_msg_build: copying 24 bytes from extensions[24] (type=24) 
| pfkey_lib_debug:pfkey_msg_parse: parsing message ver=2, type=14(x-addflow(eroute)), errno=0, satype=9(IPIP), len=23, res=0, seq=48, pid=1545. 
| pfkey_lib_debug:pfkey_msg_parse: satype 9(IPIP) conversion to proto gives 4 for msg_type 14(x-addflow(eroute)). 
| pfkey_lib_debug:pfkey_msg_parse: remain=21 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=1(security-association) remain=21. 
| pfkey_lib_debug:pfkey_msg_parse: remain=21 ext_type=1(security-association) ext_len=3 parsing ext 0p0x181200 with parser pfkey_sa_parse. 
| pfkey_lib_debug:pfkey_sa_parse: successfully found len=3 exttype=1(security-association) spi=00001007 replay=0 state=0 auth=0 encrypt=0 flags=0 ref=0. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 1(security-association) parsed. 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=5(source-address) remain=18. 
| pfkey_lib_debug:pfkey_msg_parse: remain=18 ext_type=5(source-address) ext_len=3 parsing ext 0p0x181218 with parser pfkey_address_parse. 
| pfkey_lib_debug:pfkey_address_parse: found exttype=5(source-address) family=2(AF_INET) address=10.29.3.195 proto=0 port=0. 
| pfkey_lib_debug:pfkey_address_parse: successful. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 5(source-address) parsed. 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=6(destination-address) remain=15. 
| pfkey_lib_debug:pfkey_msg_parse: remain=15 ext_type=6(destination-address) ext_len=3 parsing ext 0p0x181230 with parser pfkey_address_parse. 
| pfkey_lib_debug:pfkey_address_parse: found exttype=6(destination-address) family=2(AF_INET) address=10.29.3.225 proto=0 port=0. 
| pfkey_lib_debug:pfkey_address_parse: successful. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 6(destination-address) parsed. 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=21(X-source-flow-address) remain=12. 
| pfkey_lib_debug:pfkey_msg_parse: remain=12 ext_type=21(X-source-flow-address) ext_len=3 parsing ext 0p0x181248 with parser pfkey_address_parse. 
| pfkey_lib_debug:pfkey_address_parse: found exttype=21(X-source-flow-address) family=2(AF_INET) address=192.168.1.0 proto=0 port=0. 
| pfkey_lib_debug:pfkey_address_parse: successful. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 21(X-source-flow-address) parsed. 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=22(X-dest-flow-address) remain=9. 
| pfkey_lib_debug:pfkey_msg_parse: remain=9 ext_type=22(X-dest-flow-address) ext_len=3 parsing ext 0p0x181260 with parser pfkey_address_parse. 
| pfkey_lib_debug:pfkey_address_parse: found exttype=22(X-dest-flow-address) family=2(AF_INET) address=192.168.123.0 proto=0 port=0. 
| pfkey_lib_debug:pfkey_address_parse: successful. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 22(X-dest-flow-address) parsed. 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=23(X-source-mask) remain=6. 
| pfkey_lib_debug:pfkey_msg_parse: remain=6 ext_type=23(X-source-mask) ext_len=3 parsing ext 0p0x181278 with parser pfkey_address_parse. 
| pfkey_lib_debug:pfkey_address_parse: found exttype=23(X-source-mask) family=2(AF_INET) address=255.255.255.0 proto=0 port=0. 
| pfkey_lib_debug:pfkey_address_parse: successful. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 23(X-source-mask) parsed. 
| pfkey_lib_debug:pfkey_msg_parse: parsing ext type=24(X-dest-mask) remain=3. 
| pfkey_lib_debug:pfkey_msg_parse: remain=3 ext_type=24(X-dest-mask) ext_len=3 parsing ext 0p0x181290 with parser pfkey_address_parse. 
| pfkey_lib_debug:pfkey_address_parse: found exttype=24(X-dest-mask) family=2(AF_INET) address=255.255.255.0 proto=0 port=0. 
| pfkey_lib_debug:pfkey_address_parse: successful. 
| pfkey_lib_debug:pfkey_msg_parse: Extension 24(X-dest-mask) parsed. 
| finish_pfkey_msg: K_SADB_X_ADDFLOW message 48 for flow tun.1007@10.29.3.225
|   02 0e 00 09  00 17 00 00  00 00 00 30  00 00 06 09
|   00 03 00 01  00 00 10 07  00 00 00 00  00 00 00 00
|   00 00 00 00  00 00 00 00  00 03 00 05  00 00 00 00
|   00 02 00 00  0a 1d 03 c3  00 00 00 00  00 00 00 00
|   00 03 00 06  00 00 00 00  00 02 00 00  0a 1d 03 e1
|   00 00 00 00  00 00 00 00  00 03 00 15  00 00 00 00
|   00 02 00 00  c0 a8 01 00  00 00 00 00  00 00 00 00
|   00 03 00 16  00 00 00 00  00 02 00 00  c0 a8 7b 00
|   00 00 00 00  00 00 00 00  00 03 00 17  00 00 00 00
|   00 02 00 00  ff ff ff 00  00 00 00 00  00 00 00 00
|   00 03 00 18  00 00 00 00  00 02 00 00  ff ff ff 00
|   00 00 00 00  00 00 00 00
| pfkey_get: K_SADB_X_ADDFLOW message 48
| pfkey_lib_debug:pfkey_extensions_free:Free extention 0 (16) 
| pfkey_lib_debug:pfkey_extensions_free:Free extention 1 (24) 
| pfkey_lib_debug:pfkey_extensions_free:Free extention 5 (24) 
| pfkey_lib_debug:pfkey_extensions_free:Free extention 6 (24) 
| pfkey_lib_debug:pfkey_extensions_free:Free extention 21 (24) 
| pfkey_lib_debug:pfkey_extensions_free:Free extention 22 (24) 
| pfkey_lib_debug:pfkey_extensions_free:Free extention 23 (24) 
| pfkey_lib_debug:pfkey_extensions_free:Free extention 24 (24) 
| raw_eroute result=1 
| command executing up-client
| executing up-client: 2>&1 PLUTO_VERB='up-client' PLUTO_VERSION='2.0' PLUTO_CONNECTION='site_192.168.1.0_24-192.168.123.0_24' PLUTO_INTERFACE='ipsec1' PLUTO_NEXT_HOP='10.29.3.225' PLUTO_ME='10.29.3.195' PLUTO_MY_ID='10.29.3.195' PLUTO_MY_CLIENT='192.168.1.0/24' PLUTO_MY_CLIENT_NET='192.168.1.0' PLUTO_MY_CLIENT_MASK='255.255.255.0' PLUTO_MY_PORT='0' PLUTO_MY_PROTOCOL='0' PLUTO_PEER='10.29.3.225' PLUTO_PEER_ID='10.29.3.225' PLUTO_PEER_CLIENT='192.168.123.0/24' PLUTO_PEER_CLIENT_NET='192.168.123.0' PLUTO_PEER_CLIENT_MASK='255.255.255.0' PLUTO_PEER_PORT='0' PLUTO_PEER_PROTOCOL='0' PLUTO_PEER_CA='' PLUTO_STACK='klips'  PLUTO_CONN_POLICY='PSK+ENCRYPT+TUNNEL+PFS+DONTREKEY+UP+IKEv2ALLOW' PLUTO_XAUTH_USERNAME=''  /ramfs/ipsec/bin/_updown
| popen(): cmd is 716 chars long
| cmd(   0):2>&1 PLUTO_VERB='up-client' PLUTO_VERSION='2.0' PLUTO_CONNECTION='site_192.168.1:
| cmd(  80):.0_24-192.168.123.0_24' PLUTO_INTERFACE='ipsec1' PLUTO_NEXT_HOP='10.29.3.225' PL:
| cmd( 160):UTO_ME='10.29.3.195' PLUTO_MY_ID='10.29.3.195' PLUTO_MY_CLIENT='192.168.1.0/24' :
| cmd( 240):PLUTO_MY_CLIENT_NET='192.168.1.0' PLUTO_MY_CLIENT_MASK='255.255.255.0' PLUTO_MY_:
| cmd( 320):PORT='0' PLUTO_MY_PROTOCOL='0' PLUTO_PEER='10.29.3.225' PLUTO_PEER_ID='10.29.3.2:
| cmd( 400):25' PLUTO_PEER_CLIENT='192.168.123.0/24' PLUTO_PEER_CLIENT_NET='192.168.123.0' P:
| cmd( 480):LUTO_PEER_CLIENT_MASK='255.255.255.0' PLUTO_PEER_PORT='0' PLUTO_PEER_PROTOCOL='0:
| cmd( 560):' PLUTO_PEER_CA='' PLUTO_STACK='klips'  PLUTO_CONN_POLICY='PSK+ENCRYPT+TUNNEL+PF:
| cmd( 640):S+DONTREKEY+UP+IKEv2ALLOW' PLUTO_XAUTH_USERNAME=''  /ramfs/ipsec/bin/_updown:
| route_and_eroute: firewall_notified: true
| command executing prepare-client
| executing prepare-client: 2>&1 PLUTO_VERB='prepare-client' PLUTO_VERSION='2.0' PLUTO_CONNECTION='site_192.168.1.0_24-192.168.123.0_24' PLUTO_INTERFACE='ipsec1' PLUTO_NEXT_HOP='10.29.3.225' PLUTO_ME='10.29.3.195' PLUTO_MY_ID='10.29.3.195' PLUTO_MY_CLIENT='192.168.1.0/24' PLUTO_MY_CLIENT_NET='192.168.1.0' PLUTO_MY_CLIENT_MASK='255.255.255.0' PLUTO_MY_PORT='0' PLUTO_MY_PROTOCOL='0' PLUTO_PEER='10.29.3.225' PLUTO_PEER_ID='10.29.3.225' PLUTO_PEER_CLIENT='192.168.123.0/24' PLUTO_PEER_CLIENT_NET='192.168.123.0' PLUTO_PEER_CLIENT_MASK='255.255.255.0' PLUTO_PEER_PORT='0' PLUTO_PEER_PROTOCOL='0' PLUTO_PEER_CA='' PLUTO_STACK='klips'  PLUTO_CONN_POLICY='PSK+ENCRYPT+TUNNEL+PFS+DONTREKEY+UP+IKEv2ALLOW' PLUTO_XAUTH_USERNAME=''  /ramfs/ipsec/bin/_updown
| popen(): cmd is 721 chars long
| cmd(   0):2>&1 PLUTO_VERB='prepare-client' PLUTO_VERSION='2.0' PLUTO_CONNECTION='site_192.:
| cmd(  80):168.1.0_24-192.168.123.0_24' PLUTO_INTERFACE='ipsec1' PLUTO_NEXT_HOP='10.29.3.22:
| cmd( 160):5' PLUTO_ME='10.29.3.195' PLUTO_MY_ID='10.29.3.195' PLUTO_MY_CLIENT='192.168.1.0:
| cmd( 240):/24' PLUTO_MY_CLIENT_NET='192.168.1.0' PLUTO_MY_CLIENT_MASK='255.255.255.0' PLUT:
| cmd( 320):O_MY_PORT='0' PLUTO_MY_PROTOCOL='0' PLUTO_PEER='10.29.3.225' PLUTO_PEER_ID='10.2:
| cmd( 400):9.3.225' PLUTO_PEER_CLIENT='192.168.123.0/24' PLUTO_PEER_CLIENT_NET='192.168.123:
| cmd( 480):.0' PLUTO_PEER_CLIENT_MASK='255.255.255.0' PLUTO_PEER_PORT='0' PLUTO_PEER_PROTOC:
| cmd( 560):OL='0' PLUTO_PEER_CA='' PLUTO_STACK='klips'  PLUTO_CONN_POLICY='PSK+ENCRYPT+TUNN:
| cmd( 640):EL+PFS+DONTREKEY+UP+IKEv2ALLOW' PLUTO_XAUTH_USERNAME=''  /ramfs/ipsec/bin/_updow:
| cmd( 720):n:
| command executing route-client
| executing route-client: 2>&1 PLUTO_VERB='route-client' PLUTO_VERSION='2.0' PLUTO_CONNECTION='site_192.168.1.0_24-192.168.123.0_24' PLUTO_INTERFACE='ipsec1' PLUTO_NEXT_HOP='10.29.3.225' PLUTO_ME='10.29.3.195' PLUTO_MY_ID='10.29.3.195' PLUTO_MY_CLIENT='192.168.1.0/24' PLUTO_MY_CLIENT_NET='192.168.1.0' PLUTO_MY_CLIENT_MASK='255.255.255.0' PLUTO_MY_PORT='0' PLUTO_MY_PROTOCOL='0' PLUTO_PEER='10.29.3.225' PLUTO_PEER_ID='10.29.3.225' PLUTO_PEER_CLIENT='192.168.123.0/24' PLUTO_PEER_CLIENT_NET='192.168.123.0' PLUTO_PEER_CLIENT_MASK='255.255.255.0' PLUTO_PEER_PORT='0' PLUTO_PEER_PROTOCOL='0' PLUTO_PEER_CA='' PLUTO_STACK='klips'  PLUTO_CONN_POLICY='PSK+ENCRYPT+TUNNEL+PFS+DONTREKEY+UP+IKEv2ALLOW' PLUTO_XAUTH_USERNAME=''  /ramfs/ipsec/bin/_updown
| popen(): cmd is 719 chars long
| cmd(   0):2>&1 PLUTO_VERB='route-client' PLUTO_VERSION='2.0' PLUTO_CONNECTION='site_192.16:
| cmd(  80):8.1.0_24-192.168.123.0_24' PLUTO_INTERFACE='ipsec1' PLUTO_NEXT_HOP='10.29.3.225':
| cmd( 160): PLUTO_ME='10.29.3.195' PLUTO_MY_ID='10.29.3.195' PLUTO_MY_CLIENT='192.168.1.0/2:
| cmd( 240):4' PLUTO_MY_CLIENT_NET='192.168.1.0' PLUTO_MY_CLIENT_MASK='255.255.255.0' PLUTO_:
| cmd( 320):MY_PORT='0' PLUTO_MY_PROTOCOL='0' PLUTO_PEER='10.29.3.225' PLUTO_PEER_ID='10.29.:
| cmd( 400):3.225' PLUTO_PEER_CLIENT='192.168.123.0/24' PLUTO_PEER_CLIENT_NET='192.168.123.0:
| cmd( 480):' PLUTO_PEER_CLIENT_MASK='255.255.255.0' PLUTO_PEER_PORT='0' PLUTO_PEER_PROTOCOL:
| cmd( 560):='0' PLUTO_PEER_CA='' PLUTO_STACK='klips'  PLUTO_CONN_POLICY='PSK+ENCRYPT+TUNNEL:
| cmd( 640):+PFS+DONTREKEY+UP+IKEv2ALLOW' PLUTO_XAUTH_USERNAME=''  /ramfs/ipsec/bin/_updown:
| route_and_eroute: instance "site_192.168.1.0_24-192.168.123.0_24", setting eroute_owner {spd=0x17b4f4,sr=0x17b4f4} to #8 (was #0) (newest_ipsec_sa=#0)
| encrypting:
|   00 00 00 18  ca b9 b9 a5  d6 b9 3f e7  a1 50 79 37
|   7d 47 48 f4  b2 d8 97 02
| IV:
|   8b f6 cb 3b  34 9a 0f d7  05 97 83 87  ee 2d 58 9b
| unpadded size is: 24
| emitting 8 zero bytes of encryption padding into ISAKMP Message
| encrypting 32 using OAKLEY_AES_CBC
| next IV:  43 f1 9c b8  bd 44 68 17  fe c0 6e 2a  8d 7c d7 0f
| emitting length of ISAKMP Message: 60
| inR1_outI2: instance site_192.168.1.0_24-192.168.123.0_24[0], setting newest_ipsec_sa to #8 (was #0) (spd.eroute=#8)
| ICOOKIE:  56 6b ba 6c  9a 8f df ce
| RCOOKIE:  a0 d7 07 80  4d 46 90 b6
| state hash entry 18
| v1 peer and cookies match on #8, provided msgid 00000000 vs 590fb497
| v1 peer and cookies match on #7, provided msgid 00000000 vs 00000000
| v1 state object #7 found, in STATE_MAIN_I4
"site_192.168.1.0_24-192.168.123.0_24" #8: Dead Peer Detection (RFC 3706): enabled
| state: 8 requesting event none to be deleted by /home/mix/work/dsa3600-ipv6/fruitfarm/product/MB120/work/buildroot/build_arm/openswan-2.6.24/programs/pluto/dpd.c:159
| inserting event EVENT_DPD, timeout in 10 seconds for #8
| event added at head of queue
| state: 7 requesting event EVENT_DPD to be deleted by /home/mix/work/dsa3600-ipv6/fruitfarm/product/MB120/work/buildroot/build_arm/openswan-2.6.24/programs/pluto/dpd.c:171
| complete state transition with STF_OK
"site_192.168.1.0_24-192.168.123.0_24" #8: transition from state STATE_QUICK_I1 to state STATE_QUICK_I2
| deleting event for #8
| sending reply packet to 10.29.3.225:500 (from port 500)
| sending 60 bytes for STATE_QUICK_I1 through eth2:500 to 10.29.3.225:500 (using #8)
|   56 6b ba 6c  9a 8f df ce  a0 d7 07 80  4d 46 90 b6
|   08 10 20 01  59 0f b4 97  00 00 00 3c  85 e0 6e 8a
|   8e 07 5b 9e  e4 2b fe d8  a1 99 19 7f  43 f1 9c b8
|   bd 44 68 17  fe c0 6e 2a  8d 7c d7 0f
| inserting event EVENT_SA_REPLACE_IF_USED, timeout in 85842 seconds for #8
| event added after event EVENT_SA_REPLACE_IF_USED for #7
"site_192.168.1.0_24-192.168.123.0_24" #8: STATE_QUICK_I2: sent QI2, IPsec SA established tunnel mode {ESP=>0x84462a0b <0x23900d3f xfrm=AES_256-HMAC_MD5 NATOA=none NATD=none DPD=enabled}
| modecfg pull: noquirk policy:push not-client
| phase 1 is done, looking for phase 2 to unpend
| * processed 1 messages from cryptographic helpers 
| next event EVENT_DPD in 10 seconds for #8
| next event EVENT_DPD in 10 seconds for #8
|  
| next event EVENT_DPD in 0 seconds for #8
| *time to handle event
| handling event EVENT_DPD
| event after this is EVENT_PENDING_DDNS in 47 seconds
| processing connection site_192.168.1.0_24-192.168.123.0_24
| inserting event EVENT_DPD, timeout in 10 seconds for #8
| event added at head of queue
| state: 7 requesting event none to be deleted by /home/mix/work/dsa3600-ipv6/fruitfarm/product/MB120/work/buildroot/build_arm/openswan-2.6.24/programs/pluto/dpd.c:192
| inserting event EVENT_DPD_TIMEOUT, timeout in 15 seconds for #7
| event added after event EVENT_DPD for #8
| **emit ISAKMP Message:
|    initiator cookie:
|   56 6b ba 6c  9a 8f df ce
|    responder cookie:
|   a0 d7 07 80  4d 46 90 b6
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  28 cd a2 b1
| ***emit ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_N
| emitting 20 zero bytes of HASH into ISAKMP Hash Payload
| emitting length of ISAKMP Hash Payload: 24
| ***emit ISAKMP Notification Payload:
|    next payload type: ISAKMP_NEXT_NONE
|    DOI: ISAKMP_DOI_IPSEC
|    protocol ID: 1
|    SPI size: 16
|    Notify Message Type: R_U_THERE
| emitting 8 raw bytes of notify icookie into ISAKMP Notification Payload
| notify icookie  56 6b ba 6c  9a 8f df ce
| emitting 8 raw bytes of notify rcookie into ISAKMP Notification Payload
| notify rcookie  a0 d7 07 80  4d 46 90 b6
| emitting 4 raw bytes of notify data into ISAKMP Notification Payload
| notify data  00 00 2b 99
| emitting length of ISAKMP Notification Payload: 32
| HASH computed:
|   39 26 8b 7f  49 6d 92 39  07 3f f7 f7  43 44 46 73
|   34 e1 e0 f1
| last Phase 1 IV:  ce 3c 12 ec  95 8b b4 5f  fa 61 e4 fb  00 40 55 3c
| current Phase 1 IV:  ce 3c 12 ec  95 8b b4 5f  fa 61 e4 fb  00 40 55 3c
| computed Phase 2 IV:
|   05 7a 89 32  2a 62 8c 62  ea f5 7f e7  25 55 e5 ab
|   2d 5e 96 68
| encrypting:
|   0b 00 00 18  39 26 8b 7f  49 6d 92 39  07 3f f7 f7
|   43 44 46 73  34 e1 e0 f1  00 00 00 20  00 00 00 01
|   01 10 8d 28  56 6b ba 6c  9a 8f df ce  a0 d7 07 80
|   4d 46 90 b6  00 00 2b 99
| IV:
|   05 7a 89 32  2a 62 8c 62  ea f5 7f e7  25 55 e5 ab
|   2d 5e 96 68
| unpadded size is: 56
| emitting 8 zero bytes of encryption padding into ISAKMP Message
| encrypting 64 using OAKLEY_AES_CBC
| next IV:  80 07 fa 2e  21 39 7e ea  10 02 38 a5  83 21 87 fa
| emitting length of ISAKMP Message: 92
| sending 92 bytes for ISAKMP notify through eth2:500 to 10.29.3.225:500 (using #7)
|   56 6b ba 6c  9a 8f df ce  a0 d7 07 80  4d 46 90 b6
|   08 10 05 01  28 cd a2 b1  00 00 00 5c  af 33 79 74
|   46 70 aa 9a  24 9a 7f ab  ff 12 d7 64  3d 57 eb 4e
|   54 a8 82 09  63 da 56 c0  66 88 9c 62  b6 ae 21 4d
|   b0 fe 28 52  94 e6 2e 79  36 86 0a 08  80 07 fa 2e
|   21 39 7e ea  10 02 38 a5  83 21 87 fa
| next event EVENT_DPD in 10 seconds for #8
|  
| *received 92 bytes from 10.29.3.225:500 on eth2 (port=500)
|   56 6b ba 6c  9a 8f df ce  a0 d7 07 80  4d 46 90 b6
|   08 10 05 01  94 57 9a f2  00 00 00 5c  c5 51 d2 28
|   f1 3b 2b e1  7f 31 3f b5  e5 c5 d3 18  55 37 28 15
|   2f 57 2f 1f  b6 c3 ca ec  07 59 bc c8  e5 e7 46 ce
|   0d e6 b5 b6  e5 03 33 f4  77 0d f8 61  4e be 3a 04
|   a5 7b 8d d8  14 da fe 6c  17 12 72 13
| **parse ISAKMP Message:
|    initiator cookie:
|   56 6b ba 6c  9a 8f df ce
|    responder cookie:
|   a0 d7 07 80  4d 46 90 b6
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  94 57 9a f2
|    length: 92
|  processing version=1.0 packet with exchange type=ISAKMP_XCHG_INFO (5)
| ICOOKIE:  56 6b ba 6c  9a 8f df ce
| RCOOKIE:  a0 d7 07 80  4d 46 90 b6
| state hash entry 18
| peer and cookies match on #8, provided msgid 00000000 vs 590fb497/00000000
| peer and cookies match on #7, provided msgid 00000000 vs 00000000/00000000
| p15 state object #7 found, in STATE_MAIN_I4
| processing connection site_192.168.1.0_24-192.168.123.0_24
| last Phase 1 IV:  ce 3c 12 ec  95 8b b4 5f  fa 61 e4 fb  00 40 55 3c
| current Phase 1 IV:  ce 3c 12 ec  95 8b b4 5f  fa 61 e4 fb  00 40 55 3c
| computed Phase 2 IV:
|   4d 2d d9 ed  71 dc d6 fa  8c 26 66 4e  7f 99 e2 f3
|   09 1e 0e 2d
| received encrypted packet from 10.29.3.225:500
| decrypting 64 bytes using algorithm OAKLEY_AES_CBC
| decrypted:
|   0b 00 00 18  34 07 74 96  94 34 2e cc  f8 9d 77 dc
|   7a 52 e0 6c  f6 ad 52 b6  00 00 00 20  00 00 00 01
|   01 10 8d 29  56 6b ba 6c  9a 8f df ce  a0 d7 07 80
|   4d 46 90 b6  00 00 2b 99  00 00 00 00  00 00 00 00
| next IV:  4e be 3a 04  a5 7b 8d d8  14 da fe 6c  17 12 72 13
| got payload 0x100(ISAKMP_NEXT_HASH) needed: 0x100 opt: 0x0
| ***parse ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_N
|    length: 24
| got payload 0x800(ISAKMP_NEXT_N) needed: 0x0 opt: 0x0
| ***parse ISAKMP Notification Payload:
|    next payload type: ISAKMP_NEXT_NONE
|    length: 32
|    DOI: ISAKMP_DOI_IPSEC
|    protocol ID: 1
|    SPI size: 16
|    Notify Message Type: R_U_THERE_ACK
| removing 8 bytes of padding
| info:  56 6b ba 6c  9a 8f df ce  a0 d7 07 80  4d 46 90 b6
| info:  00 00 2b 99
| processing informational R_U_THERE_ACK (36137)
| state: 7 requesting event EVENT_DPD_TIMEOUT to be deleted by /home/mix/work/dsa3600-ipv6/fruitfarm/product/MB120/work/buildroot/build_arm/openswan-2.6.24/programs/pluto/dpd.c:514
| complete state transition with STF_IGNORE
| * processed 0 messages from cryptographic helpers 
| next event EVENT_DPD in 10 seconds for #8
| next event EVENT_DPD in 10 seconds for #8
|  
| *received 92 bytes from 10.29.3.225:500 on eth2 (port=500)
|   56 6b ba 6c  9a 8f df ce  a0 d7 07 80  4d 46 90 b6
|   08 10 05 01  ac f6 a1 9d  00 00 00 5c  41 a4 28 f6
|   ac f1 7b 68  75 b9 ec 0a  d5 94 84 b8  dd 86 08 82
|   33 7f f5 e1  1c 24 6f 42  35 b7 1b 58  8e 7f e0 1e
|   42 1b f7 cc  7d 7d 28 2e  36 c4 31 7a  2c e5 c7 a5
|   66 e5 4a ef  c6 ed 7e 6a  3d fb d1 40
| **parse ISAKMP Message:
|    initiator cookie:
|   56 6b ba 6c  9a 8f df ce
|    responder cookie:
|   a0 d7 07 80  4d 46 90 b6
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  ac f6 a1 9d
|    length: 92
|  processing version=1.0 packet with exchange type=ISAKMP_XCHG_INFO (5)
| ICOOKIE:  56 6b ba 6c  9a 8f df ce
| RCOOKIE:  a0 d7 07 80  4d 46 90 b6
| state hash entry 18
| peer and cookies match on #8, provided msgid 00000000 vs 590fb497/00000000
| peer and cookies match on #7, provided msgid 00000000 vs 00000000/00000000
| p15 state object #7 found, in STATE_MAIN_I4
| processing connection site_192.168.1.0_24-192.168.123.0_24
| last Phase 1 IV:  ce 3c 12 ec  95 8b b4 5f  fa 61 e4 fb  00 40 55 3c
| current Phase 1 IV:  ce 3c 12 ec  95 8b b4 5f  fa 61 e4 fb  00 40 55 3c
| computed Phase 2 IV:
|   d0 ad 7e 98  1b 98 79 ad  5f 27 31 ab  9b 66 38 61
|   e4 db e5 66
| received encrypted packet from 10.29.3.225:500
| decrypting 64 bytes using algorithm OAKLEY_AES_CBC
| decrypted:
|   0b 00 00 18  b9 22 53 17  97 ee 9b 58  ea d2 91 8a
|   f0 63 bc 01  a4 a1 42 6d  00 00 00 20  00 00 00 01
|   01 10 8d 28  56 6b ba 6c  9a 8f df ce  a0 d7 07 80
|   4d 46 90 b6  00 00 74 d1  00 00 00 00  00 00 00 00
| next IV:  2c e5 c7 a5  66 e5 4a ef  c6 ed 7e 6a  3d fb d1 40
| got payload 0x100(ISAKMP_NEXT_HASH) needed: 0x100 opt: 0x0
| ***parse ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_N
|    length: 24
| got payload 0x800(ISAKMP_NEXT_N) needed: 0x0 opt: 0x0
| ***parse ISAKMP Notification Payload:
|    next payload type: ISAKMP_NEXT_NONE
|    length: 32
|    DOI: ISAKMP_DOI_IPSEC
|    protocol ID: 1
|    SPI size: 16
|    Notify Message Type: R_U_THERE
| removing 8 bytes of padding
| info:  56 6b ba 6c  9a 8f df ce  a0 d7 07 80  4d 46 90 b6
| info:  00 00 74 d1
| processing informational R_U_THERE (36136)
| **emit ISAKMP Message:
|    initiator cookie:
|   56 6b ba 6c  9a 8f df ce
|    responder cookie:
|   a0 d7 07 80  4d 46 90 b6
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  09 6e d1 fb
| ***emit ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_N
| emitting 20 zero bytes of HASH into ISAKMP Hash Payload
| emitting length of ISAKMP Hash Payload: 24
| ***emit ISAKMP Notification Payload:
|    next payload type: ISAKMP_NEXT_NONE
|    DOI: ISAKMP_DOI_IPSEC
|    protocol ID: 1
|    SPI size: 16
|    Notify Message Type: R_U_THERE_ACK
| emitting 8 raw bytes of notify icookie into ISAKMP Notification Payload
| notify icookie  56 6b ba 6c  9a 8f df ce
| emitting 8 raw bytes of notify rcookie into ISAKMP Notification Payload
| notify rcookie  a0 d7 07 80  4d 46 90 b6
| emitting 4 raw bytes of notify data into ISAKMP Notification Payload
| notify data  00 00 74 d1
| emitting length of ISAKMP Notification Payload: 32
| HASH computed:
|   f3 0e 4d aa  de 02 49 c9  5b 56 13 45  c1 5a cb 63
|   1a 8f a9 35
| last Phase 1 IV:  ce 3c 12 ec  95 8b b4 5f  fa 61 e4 fb  00 40 55 3c
| current Phase 1 IV:  ce 3c 12 ec  95 8b b4 5f  fa 61 e4 fb  00 40 55 3c
| computed Phase 2 IV:
|   1b 25 d1 cc  4f b8 d3 e3  99 d0 d3 03  f5 23 f5 98
|   21 e5 44 00
| encrypting:
|   0b 00 00 18  f3 0e 4d aa  de 02 49 c9  5b 56 13 45
|   c1 5a cb 63  1a 8f a9 35  00 00 00 20  00 00 00 01
|   01 10 8d 29  56 6b ba 6c  9a 8f df ce  a0 d7 07 80
|   4d 46 90 b6  00 00 74 d1
| IV:
|   1b 25 d1 cc  4f b8 d3 e3  99 d0 d3 03  f5 23 f5 98
|   21 e5 44 00
| unpadded size is: 56
| emitting 8 zero bytes of encryption padding into ISAKMP Message
| encrypting 64 using OAKLEY_AES_CBC
| next IV:  e5 2b a1 a3  de c5 aa 6c  8a 0a a5 19  23 13 29 4f
| emitting length of ISAKMP Message: 92
| sending 92 bytes for ISAKMP notify through eth2:500 to 10.29.3.225:500 (using #7)
|   56 6b ba 6c  9a 8f df ce  a0 d7 07 80  4d 46 90 b6
|   08 10 05 01  09 6e d1 fb  00 00 00 5c  82 b9 43 1c
|   2d 5e 4f c9  be 7f 0f ac  b1 59 f7 bc  74 eb 9e c9
|   1a d7 5a 9b  71 9d 94 77  9f 65 ec f2  89 bf d6 68
|   57 99 f4 c8  5a 31 91 c3  cf ed 35 7e  e5 2b a1 a3
|   de c5 aa 6c  8a 0a a5 19  23 13 29 4f
| complete state transition with STF_IGNORE
| * processed 0 messages from cryptographic helpers 
| next event EVENT_DPD in 0 seconds for #8
| *time to handle event
| handling event EVENT_DPD
| event after this is EVENT_PENDING_DDNS in 37 seconds
| processing connection site_192.168.1.0_24-192.168.123.0_24
| inserting event EVENT_DPD, timeout in 10 seconds for #8
| event added at head of queue
| next event EVENT_DPD in 10 seconds for #8
|  
| next event EVENT_DPD in 0 seconds for #8
| *time to handle event
| handling event EVENT_DPD
| event after this is EVENT_PENDING_DDNS in 27 seconds
| processing connection site_192.168.1.0_24-192.168.123.0_24
| inserting event EVENT_DPD, timeout in 10 seconds for #8
| event added at head of queue
| state: 7 requesting event none to be deleted by /home/mix/work/dsa3600-ipv6/fruitfarm/product/MB120/work/buildroot/build_arm/openswan-2.6.24/programs/pluto/dpd.c:192
| inserting event EVENT_DPD_TIMEOUT, timeout in 15 seconds for #7
| event added after event EVENT_DPD for #8
| **emit ISAKMP Message:
|    initiator cookie:
|   56 6b ba 6c  9a 8f df ce
|    responder cookie:
|   a0 d7 07 80  4d 46 90 b6
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  c4 80 7a 5f
| ***emit ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_N
| emitting 20 zero bytes of HASH into ISAKMP Hash Payload
| emitting length of ISAKMP Hash Payload: 24
| ***emit ISAKMP Notification Payload:
|    next payload type: ISAKMP_NEXT_NONE
|    DOI: ISAKMP_DOI_IPSEC
|    protocol ID: 1
|    SPI size: 16
|    Notify Message Type: R_U_THERE
| emitting 8 raw bytes of notify icookie into ISAKMP Notification Payload
| notify icookie  56 6b ba 6c  9a 8f df ce
| emitting 8 raw bytes of notify rcookie into ISAKMP Notification Payload
| notify rcookie  a0 d7 07 80  4d 46 90 b6
| emitting 4 raw bytes of notify data into ISAKMP Notification Payload
| notify data  00 00 2b 9a
| emitting length of ISAKMP Notification Payload: 32
| HASH computed:
|   71 19 58 9e  71 d9 75 16  9a 7a 8b 7d  e0 bb 53 7d
|   14 9a a4 a0
| last Phase 1 IV:  ce 3c 12 ec  95 8b b4 5f  fa 61 e4 fb  00 40 55 3c
| current Phase 1 IV:  ce 3c 12 ec  95 8b b4 5f  fa 61 e4 fb  00 40 55 3c
| computed Phase 2 IV:
|   8a ab 41 40  14 9f 30 e7  91 06 c4 b2  0a e0 82 11
|   07 bf c3 0d
| encrypting:
|   0b 00 00 18  71 19 58 9e  71 d9 75 16  9a 7a 8b 7d
|   e0 bb 53 7d  14 9a a4 a0  00 00 00 20  00 00 00 01
|   01 10 8d 28  56 6b ba 6c  9a 8f df ce  a0 d7 07 80
|   4d 46 90 b6  00 00 2b 9a
| IV:
|   8a ab 41 40  14 9f 30 e7  91 06 c4 b2  0a e0 82 11
|   07 bf c3 0d
| unpadded size is: 56
| emitting 8 zero bytes of encryption padding into ISAKMP Message
| encrypting 64 using OAKLEY_AES_CBC
| next IV:  65 71 a3 ed  20 48 f4 56  f2 73 31 50  21 79 1a 48
| emitting length of ISAKMP Message: 92
| sending 92 bytes for ISAKMP notify through eth2:500 to 10.29.3.225:500 (using #7)
|   56 6b ba 6c  9a 8f df ce  a0 d7 07 80  4d 46 90 b6
|   08 10 05 01  c4 80 7a 5f  00 00 00 5c  7d 63 19 6f
|   e3 86 27 ef  22 c3 b0 d8  2a 56 5d 27  93 59 12 75
|   80 1e 4d 94  b8 51 1f a6  f7 5a 17 29  61 c8 13 d7
|   bd b5 b5 14  49 1c 45 d9  e0 8c f6 aa  65 71 a3 ed
|   20 48 f4 56  f2 73 31 50  21 79 1a 48
| next event EVENT_DPD in 10 seconds for #8
|  
| *received 92 bytes from 10.29.3.225:500 on eth2 (port=500)
|   56 6b ba 6c  9a 8f df ce  a0 d7 07 80  4d 46 90 b6
|   08 10 05 01  1e 47 35 a6  00 00 00 5c  ea 56 86 db
|   a4 f1 4c c9  3f 75 cc 1e  a4 c3 28 d6  ae 8f 98 ac
|   bd f7 87 f6  1a ff f2 84  0c 09 3f c0  5d 7d a1 b2
|   d9 a6 6f d7  88 a2 12 43  8c 74 72 d3  e6 97 e8 3e
|   eb ad 8e 13  2e d4 a6 4b  c1 6e 23 7c
| **parse ISAKMP Message:
|    initiator cookie:
|   56 6b ba 6c  9a 8f df ce
|    responder cookie:
|   a0 d7 07 80  4d 46 90 b6
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  1e 47 35 a6
|    length: 92
|  processing version=1.0 packet with exchange type=ISAKMP_XCHG_INFO (5)
| ICOOKIE:  56 6b ba 6c  9a 8f df ce
| RCOOKIE:  a0 d7 07 80  4d 46 90 b6
| state hash entry 18
| peer and cookies match on #8, provided msgid 00000000 vs 590fb497/00000000
| peer and cookies match on #7, provided msgid 00000000 vs 00000000/00000000
| p15 state object #7 found, in STATE_MAIN_I4
| processing connection site_192.168.1.0_24-192.168.123.0_24
| last Phase 1 IV:  ce 3c 12 ec  95 8b b4 5f  fa 61 e4 fb  00 40 55 3c
| current Phase 1 IV:  ce 3c 12 ec  95 8b b4 5f  fa 61 e4 fb  00 40 55 3c
| computed Phase 2 IV:
|   25 01 00 5e  b3 d4 bc b4  e9 14 a5 5f  20 49 09 a8
|   3a c8 af 85
| received encrypted packet from 10.29.3.225:500
| decrypting 64 bytes using algorithm OAKLEY_AES_CBC
| decrypted:
|   0b 00 00 18  f0 c3 c9 4f  36 05 92 89  ec 19 18 e0
|   dc 1a 50 ee  80 94 4e 15  00 00 00 20  00 00 00 01
|   01 10 8d 29  56 6b ba 6c  9a 8f df ce  a0 d7 07 80
|   4d 46 90 b6  00 00 2b 9a  00 00 00 00  00 00 00 00
| next IV:  e6 97 e8 3e  eb ad 8e 13  2e d4 a6 4b  c1 6e 23 7c
| got payload 0x100(ISAKMP_NEXT_HASH) needed: 0x100 opt: 0x0
| ***parse ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_N
|    length: 24
| got payload 0x800(ISAKMP_NEXT_N) needed: 0x0 opt: 0x0
| ***parse ISAKMP Notification Payload:
|    next payload type: ISAKMP_NEXT_NONE
|    length: 32
|    DOI: ISAKMP_DOI_IPSEC
|    protocol ID: 1
|    SPI size: 16
|    Notify Message Type: R_U_THERE_ACK
| removing 8 bytes of padding
| info:  56 6b ba 6c  9a 8f df ce  a0 d7 07 80  4d 46 90 b6
| info:  00 00 2b 9a
| processing informational R_U_THERE_ACK (36137)
| state: 7 requesting event EVENT_DPD_TIMEOUT to be deleted by /home/mix/work/dsa3600-ipv6/fruitfarm/product/MB120/work/buildroot/build_arm/openswan-2.6.24/programs/pluto/dpd.c:514
| complete state transition with STF_IGNORE
| * processed 0 messages from cryptographic helpers 
| next event EVENT_DPD in 10 seconds for #8
| next event EVENT_DPD in 10 seconds for #8
|  
| next event EVENT_DPD in 0 seconds for #8
| *time to handle event
| handling event EVENT_DPD
| event after this is EVENT_PENDING_DDNS in 17 seconds
| processing connection site_192.168.1.0_24-192.168.123.0_24
| inserting event EVENT_DPD, timeout in 10 seconds for #8
| event added at head of queue
| state: 7 requesting event none to be deleted by /home/mix/work/dsa3600-ipv6/fruitfarm/product/MB120/work/buildroot/build_arm/openswan-2.6.24/programs/pluto/dpd.c:192
| inserting event EVENT_DPD_TIMEOUT, timeout in 15 seconds for #7
| event added after event EVENT_DPD for #8
| **emit ISAKMP Message:
|    initiator cookie:
|   56 6b ba 6c  9a 8f df ce
|    responder cookie:
|   a0 d7 07 80  4d 46 90 b6
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  a2 a7 f4 50
| ***emit ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_N
| emitting 20 zero bytes of HASH into ISAKMP Hash Payload
| emitting length of ISAKMP Hash Payload: 24
| ***emit ISAKMP Notification Payload:
|    next payload type: ISAKMP_NEXT_NONE
|    DOI: ISAKMP_DOI_IPSEC
|    protocol ID: 1
|    SPI size: 16
|    Notify Message Type: R_U_THERE
| emitting 8 raw bytes of notify icookie into ISAKMP Notification Payload
| notify icookie  56 6b ba 6c  9a 8f df ce
| emitting 8 raw bytes of notify rcookie into ISAKMP Notification Payload
| notify rcookie  a0 d7 07 80  4d 46 90 b6
| emitting 4 raw bytes of notify data into ISAKMP Notification Payload
| notify data  00 00 2b 9b
| emitting length of ISAKMP Notification Payload: 32
| HASH computed:
|   ee 8f 5d b9  63 54 bf 2b  0d ca 3f ed  ff 65 66 91
|   b6 82 e9 c1
| last Phase 1 IV:  ce 3c 12 ec  95 8b b4 5f  fa 61 e4 fb  00 40 55 3c
| current Phase 1 IV:  ce 3c 12 ec  95 8b b4 5f  fa 61 e4 fb  00 40 55 3c
| computed Phase 2 IV:
|   7f 3c c2 ea  7c a5 ab 89  a1 3e 47 0d  7e cb 76 36
|   49 d2 33 4d
| encrypting:
|   0b 00 00 18  ee 8f 5d b9  63 54 bf 2b  0d ca 3f ed
|   ff 65 66 91  b6 82 e9 c1  00 00 00 20  00 00 00 01
|   01 10 8d 28  56 6b ba 6c  9a 8f df ce  a0 d7 07 80
|   4d 46 90 b6  00 00 2b 9b
| IV:
|   7f 3c c2 ea  7c a5 ab 89  a1 3e 47 0d  7e cb 76 36
|   49 d2 33 4d
| unpadded size is: 56
| emitting 8 zero bytes of encryption padding into ISAKMP Message
| encrypting 64 using OAKLEY_AES_CBC
| next IV:  e7 b0 1c 5f  25 af 64 e5  42 89 cf 08  66 c0 55 a4
| emitting length of ISAKMP Message: 92
| sending 92 bytes for ISAKMP notify through eth2:500 to 10.29.3.225:500 (using #7)
|   56 6b ba 6c  9a 8f df ce  a0 d7 07 80  4d 46 90 b6
|   08 10 05 01  a2 a7 f4 50  00 00 00 5c  b6 c4 95 bd
|   36 2b d4 27  44 5a 23 3f  f5 85 ca f2  1f 03 8f c1
|   28 db d2 0c  76 13 6d 75  f1 56 b5 93  33 21 06 c0
|   7c 21 59 58  50 58 02 6b  de a8 53 7f  e7 b0 1c 5f
|   25 af 64 e5  42 89 cf 08  66 c0 55 a4
| next event EVENT_DPD in 10 seconds for #8
|  
| *received 92 bytes from 10.29.3.225:500 on eth2 (port=500)
|   56 6b ba 6c  9a 8f df ce  a0 d7 07 80  4d 46 90 b6
|   08 10 05 01  6a 36 c7 f4  00 00 00 5c  1a 25 cc 60
|   2e 31 47 71  73 d7 8d 00  d4 5f b8 bc  bd 75 bc 1d
|   23 59 f2 75  a3 10 a2 73  1a d5 a9 87  7a db 7d 2b
|   1e 2d 22 7a  90 e2 ae 60  be b5 23 f1  ab ad 69 a2
|   32 35 64 6b  da dc c2 0f  c1 4c 68 cd
| **parse ISAKMP Message:
|    initiator cookie:
|   56 6b ba 6c  9a 8f df ce
|    responder cookie:
|   a0 d7 07 80  4d 46 90 b6
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  6a 36 c7 f4
|    length: 92
|  processing version=1.0 packet with exchange type=ISAKMP_XCHG_INFO (5)
| ICOOKIE:  56 6b ba 6c  9a 8f df ce
| RCOOKIE:  a0 d7 07 80  4d 46 90 b6
| state hash entry 18
| peer and cookies match on #8, provided msgid 00000000 vs 590fb497/00000000
| peer and cookies match on #7, provided msgid 00000000 vs 00000000/00000000
| p15 state object #7 found, in STATE_MAIN_I4
| processing connection site_192.168.1.0_24-192.168.123.0_24
| last Phase 1 IV:  ce 3c 12 ec  95 8b b4 5f  fa 61 e4 fb  00 40 55 3c
| current Phase 1 IV:  ce 3c 12 ec  95 8b b4 5f  fa 61 e4 fb  00 40 55 3c
| computed Phase 2 IV:
|   7c 39 53 a1  6a ec a6 b2  84 72 a8 c0  de 6c db 6b
|   76 dc 22 8d
| received encrypted packet from 10.29.3.225:500
| decrypting 64 bytes using algorithm OAKLEY_AES_CBC
| decrypted:
|   0b 00 00 18  25 94 b2 c4  bb 8f f7 fa  15 ad 15 0f
|   10 ec e0 35  76 07 13 89  00 00 00 20  00 00 00 01
|   01 10 8d 28  56 6b ba 6c  9a 8f df ce  a0 d7 07 80
|   4d 46 90 b6  00 00 74 d2  00 00 00 00  00 00 00 00
| next IV:  ab ad 69 a2  32 35 64 6b  da dc c2 0f  c1 4c 68 cd
| got payload 0x100(ISAKMP_NEXT_HASH) needed: 0x100 opt: 0x0
| ***parse ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_N
|    length: 24
| got payload 0x800(ISAKMP_NEXT_N) needed: 0x0 opt: 0x0
| ***parse ISAKMP Notification Payload:
|    next payload type: ISAKMP_NEXT_NONE
|    length: 32
|    DOI: ISAKMP_DOI_IPSEC
|    protocol ID: 1
|    SPI size: 16
|    Notify Message Type: R_U_THERE
| removing 8 bytes of padding
| info:  56 6b ba 6c  9a 8f df ce  a0 d7 07 80  4d 46 90 b6
| info:  00 00 74 d2
| processing informational R_U_THERE (36136)
| **emit ISAKMP Message:
|    initiator cookie:
|   56 6b ba 6c  9a 8f df ce
|    responder cookie:
|   a0 d7 07 80  4d 46 90 b6
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  fe 54 c8 a8
| ***emit ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_N
| emitting 20 zero bytes of HASH into ISAKMP Hash Payload
| emitting length of ISAKMP Hash Payload: 24
| ***emit ISAKMP Notification Payload:
|    next payload type: ISAKMP_NEXT_NONE
|    DOI: ISAKMP_DOI_IPSEC
|    protocol ID: 1
|    SPI size: 16
|    Notify Message Type: R_U_THERE_ACK
| emitting 8 raw bytes of notify icookie into ISAKMP Notification Payload
| notify icookie  56 6b ba 6c  9a 8f df ce
| emitting 8 raw bytes of notify rcookie into ISAKMP Notification Payload
| notify rcookie  a0 d7 07 80  4d 46 90 b6
| emitting 4 raw bytes of notify data into ISAKMP Notification Payload
| notify data  00 00 74 d2
| emitting length of ISAKMP Notification Payload: 32
| HASH computed:
|   d2 da 6a aa  d5 b3 cd f7  d8 dc d3 8d  52 c5 7a d6
|   ee fd f0 2e
| last Phase 1 IV:  ce 3c 12 ec  95 8b b4 5f  fa 61 e4 fb  00 40 55 3c
| current Phase 1 IV:  ce 3c 12 ec  95 8b b4 5f  fa 61 e4 fb  00 40 55 3c
| computed Phase 2 IV:
|   1a 3e 53 ba  18 94 83 ed  1c 82 80 fe  ac 6d 4a cb
|   cb a6 5a 4c
| encrypting:
|   0b 00 00 18  d2 da 6a aa  d5 b3 cd f7  d8 dc d3 8d
|   52 c5 7a d6  ee fd f0 2e  00 00 00 20  00 00 00 01
|   01 10 8d 29  56 6b ba 6c  9a 8f df ce  a0 d7 07 80
|   4d 46 90 b6  00 00 74 d2
| IV:
|   1a 3e 53 ba  18 94 83 ed  1c 82 80 fe  ac 6d 4a cb
|   cb a6 5a 4c
| unpadded size is: 56
| emitting 8 zero bytes of encryption padding into ISAKMP Message
| encrypting 64 using OAKLEY_AES_CBC
| next IV:  9c cc 5d c0  e3 4b 57 85  68 87 0e 39  f6 5a 24 12
| emitting length of ISAKMP Message: 92
| sending 92 bytes for ISAKMP notify through eth2:500 to 10.29.3.225:500 (using #7)
|   56 6b ba 6c  9a 8f df ce  a0 d7 07 80  4d 46 90 b6
|   08 10 05 01  fe 54 c8 a8  00 00 00 5c  81 e9 22 5b
|   c1 64 99 b1  f7 06 06 a0  95 ea e3 25  85 84 36 ba
|   79 e4 12 b2  b8 d1 15 d1  0d f0 fd 9c  7f 90 3d cb
|   78 63 80 ec  60 21 64 c3  21 f3 a7 f7  9c cc 5d c0
|   e3 4b 57 85  68 87 0e 39  f6 5a 24 12
| state: 7 requesting event EVENT_DPD_TIMEOUT to be deleted by /home/mix/work/dsa3600-ipv6/fruitfarm/product/MB120/work/buildroot/build_arm/openswan-2.6.24/programs/pluto/dpd.c:437
| complete state transition with STF_IGNORE
| * processed 0 messages from cryptographic helpers 
| next event EVENT_DPD in 10 seconds for #8
| next event EVENT_DPD in 10 seconds for #8
|  
| *received 92 bytes from 10.29.3.225:500 on eth2 (port=500)
|   56 6b ba 6c  9a 8f df ce  a0 d7 07 80  4d 46 90 b6
|   08 10 05 01  cb da d4 26  00 00 00 5c  02 c8 2a 48
|   5a e2 3e 2e  6e 26 f1 82  ef 70 d7 c4  4b 51 0a f5
|   f5 e7 3f d9  41 d2 31 db  89 c6 cd 4e  9a 83 f0 e0
|   a1 4d f5 63  f2 e6 0a a5  5e 32 ef bc  da 6e 66 cc
|   b7 09 5d ce  01 16 63 1c  23 b9 52 3a
| **parse ISAKMP Message:
|    initiator cookie:
|   56 6b ba 6c  9a 8f df ce
|    responder cookie:
|   a0 d7 07 80  4d 46 90 b6
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  cb da d4 26
|    length: 92
|  processing version=1.0 packet with exchange type=ISAKMP_XCHG_INFO (5)
| ICOOKIE:  56 6b ba 6c  9a 8f df ce
| RCOOKIE:  a0 d7 07 80  4d 46 90 b6
| state hash entry 18
| peer and cookies match on #8, provided msgid 00000000 vs 590fb497/00000000
| peer and cookies match on #7, provided msgid 00000000 vs 00000000/00000000
| p15 state object #7 found, in STATE_MAIN_I4
| processing connection site_192.168.1.0_24-192.168.123.0_24
| last Phase 1 IV:  ce 3c 12 ec  95 8b b4 5f  fa 61 e4 fb  00 40 55 3c
| current Phase 1 IV:  ce 3c 12 ec  95 8b b4 5f  fa 61 e4 fb  00 40 55 3c
| computed Phase 2 IV:
|   d3 24 d0 ef  6c 62 fd 03  d9 a6 49 f5  ad 6f ad 8d
|   29 8e 67 b8
| received encrypted packet from 10.29.3.225:500
| decrypting 64 bytes using algorithm OAKLEY_AES_CBC
| decrypted:
|   0b 00 00 18  b4 ba dd a7  cd 79 e9 a1  04 20 ba 96
|   fb ea ed 63  77 ba 24 5f  00 00 00 20  00 00 00 01
|   01 10 8d 29  56 6b ba 6c  9a 8f df ce  a0 d7 07 80
|   4d 46 90 b6  00 00 2b 9b  00 00 00 00  00 00 00 00
| next IV:  da 6e 66 cc  b7 09 5d ce  01 16 63 1c  23 b9 52 3a
| got payload 0x100(ISAKMP_NEXT_HASH) needed: 0x100 opt: 0x0
| ***parse ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_N
|    length: 24
| got payload 0x800(ISAKMP_NEXT_N) needed: 0x0 opt: 0x0
| ***parse ISAKMP Notification Payload:
|    next payload type: ISAKMP_NEXT_NONE
|    length: 32
|    DOI: ISAKMP_DOI_IPSEC
|    protocol ID: 1
|    SPI size: 16
|    Notify Message Type: R_U_THERE_ACK
| removing 8 bytes of padding
| info:  56 6b ba 6c  9a 8f df ce  a0 d7 07 80  4d 46 90 b6
| info:  00 00 2b 9b
| processing informational R_U_THERE_ACK (36137)
| complete state transition with STF_IGNORE
| * processed 0 messages from cryptographic helpers 
| next event EVENT_DPD in 10 seconds for #8
| next event EVENT_DPD in 10 seconds for #8
|  
| next event EVENT_DPD in 0 seconds for #8
| *time to handle event
| handling event EVENT_DPD
| event after this is EVENT_PENDING_DDNS in 7 seconds
| processing connection site_192.168.1.0_24-192.168.123.0_24
| inserting event EVENT_DPD, timeout in 10 seconds for #8
| event added after event EVENT_PENDING_DDNS
| state: 7 requesting event none to be deleted by /home/mix/work/dsa3600-ipv6/fruitfarm/product/MB120/work/buildroot/build_arm/openswan-2.6.24/programs/pluto/dpd.c:192
| inserting event EVENT_DPD_TIMEOUT, timeout in 15 seconds for #7
| event added after event EVENT_DPD for #8
| **emit ISAKMP Message:
|    initiator cookie:
|   56 6b ba 6c  9a 8f df ce
|    responder cookie:
|   a0 d7 07 80  4d 46 90 b6
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  dd 76 4a 1e
| ***emit ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_N
| emitting 20 zero bytes of HASH into ISAKMP Hash Payload
| emitting length of ISAKMP Hash Payload: 24
| ***emit ISAKMP Notification Payload:
|    next payload type: ISAKMP_NEXT_NONE
|    DOI: ISAKMP_DOI_IPSEC
|    protocol ID: 1
|    SPI size: 16
|    Notify Message Type: R_U_THERE
| emitting 8 raw bytes of notify icookie into ISAKMP Notification Payload
| notify icookie  56 6b ba 6c  9a 8f df ce
| emitting 8 raw bytes of notify rcookie into ISAKMP Notification Payload
| notify rcookie  a0 d7 07 80  4d 46 90 b6
| emitting 4 raw bytes of notify data into ISAKMP Notification Payload
| notify data  00 00 2b 9c
| emitting length of ISAKMP Notification Payload: 32
| HASH computed:
|   c7 e6 62 15  ca cb ac 31  2f d2 32 fd  71 59 4a f7
|   c1 aa 13 ba
| last Phase 1 IV:  ce 3c 12 ec  95 8b b4 5f  fa 61 e4 fb  00 40 55 3c
| current Phase 1 IV:  ce 3c 12 ec  95 8b b4 5f  fa 61 e4 fb  00 40 55 3c
| computed Phase 2 IV:
|   db bf 62 9a  90 d6 d4 7b  91 32 2d e4  0c 1c 98 ad
|   11 cc 8e 6c
| encrypting:
|   0b 00 00 18  c7 e6 62 15  ca cb ac 31  2f d2 32 fd
|   71 59 4a f7  c1 aa 13 ba  00 00 00 20  00 00 00 01
|   01 10 8d 28  56 6b ba 6c  9a 8f df ce  a0 d7 07 80
|   4d 46 90 b6  00 00 2b 9c
| IV:
|   db bf 62 9a  90 d6 d4 7b  91 32 2d e4  0c 1c 98 ad
|   11 cc 8e 6c
| unpadded size is: 56
| emitting 8 zero bytes of encryption padding into ISAKMP Message
| encrypting 64 using OAKLEY_AES_CBC
| next IV:  4c 5c b6 2f  eb 46 3f 67  6b e0 90 23  93 ad 16 48
| emitting length of ISAKMP Message: 92
| sending 92 bytes for ISAKMP notify through eth2:500 to 10.29.3.225:500 (using #7)
|   56 6b ba 6c  9a 8f df ce  a0 d7 07 80  4d 46 90 b6
|   08 10 05 01  dd 76 4a 1e  00 00 00 5c  c7 8d 47 11
|   c5 1d fc 10  56 ab a4 f2  5a 44 f7 3b  05 12 df fa
|   b9 48 31 f4  37 ef b8 58  9f 5f 2b dd  e6 09 18 c8
|   7e 9c 71 aa  a6 e7 02 99  fe 8e 7d 5b  4c 5c b6 2f
|   eb 46 3f 67  6b e0 90 23  93 ad 16 48
| next event EVENT_PENDING_DDNS in 7 seconds
|  
| *received 92 bytes from 10.29.3.225:500 on eth2 (port=500)
|   56 6b ba 6c  9a 8f df ce  a0 d7 07 80  4d 46 90 b6
|   08 10 05 01  b5 96 8c 05  00 00 00 5c  7a a2 0d 32
|   c2 18 79 d3  a9 32 1e de  dd 5f fb 30  9d 9e 8d 39
|   ad de 54 e7  4c 85 61 63  64 b0 78 dd  56 2a 01 61
|   7d bd 8c 97  ed 9b 53 04  bc 81 47 92  ca d4 ec 4a
|   60 68 d9 27  21 4c 08 38  1c ca d3 15
| **parse ISAKMP Message:
|    initiator cookie:
|   56 6b ba 6c  9a 8f df ce
|    responder cookie:
|   a0 d7 07 80  4d 46 90 b6
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  b5 96 8c 05
|    length: 92
|  processing version=1.0 packet with exchange type=ISAKMP_XCHG_INFO (5)
| ICOOKIE:  56 6b ba 6c  9a 8f df ce
| RCOOKIE:  a0 d7 07 80  4d 46 90 b6
| state hash entry 18
| peer and cookies match on #8, provided msgid 00000000 vs 590fb497/00000000
| peer and cookies match on #7, provided msgid 00000000 vs 00000000/00000000
| p15 state object #7 found, in STATE_MAIN_I4
| processing connection site_192.168.1.0_24-192.168.123.0_24
| last Phase 1 IV:  ce 3c 12 ec  95 8b b4 5f  fa 61 e4 fb  00 40 55 3c
| current Phase 1 IV:  ce 3c 12 ec  95 8b b4 5f  fa 61 e4 fb  00 40 55 3c
| computed Phase 2 IV:
|   1a 52 4f 12  e5 7a ae 2e  d6 62 c8 78  e9 b7 a1 ff
|   b1 cd 70 77
| received encrypted packet from 10.29.3.225:500
| decrypting 64 bytes using algorithm OAKLEY_AES_CBC
| decrypted:
|   0b 00 00 18  57 d3 b6 53  ff 69 3c 35  ad a6 a3 e8
|   27 20 4d 86  c5 f9 54 44  00 00 00 20  00 00 00 01
|   01 10 8d 28  56 6b ba 6c  9a 8f df ce  a0 d7 07 80
|   4d 46 90 b6  00 00 74 d3  00 00 00 00  00 00 00 00
| next IV:  ca d4 ec 4a  60 68 d9 27  21 4c 08 38  1c ca d3 15
| got payload 0x100(ISAKMP_NEXT_HASH) needed: 0x100 opt: 0x0
| ***parse ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_N
|    length: 24
| got payload 0x800(ISAKMP_NEXT_N) needed: 0x0 opt: 0x0
| ***parse ISAKMP Notification Payload:
|    next payload type: ISAKMP_NEXT_NONE
|    length: 32
|    DOI: ISAKMP_DOI_IPSEC
|    protocol ID: 1
|    SPI size: 16
|    Notify Message Type: R_U_THERE
| removing 8 bytes of padding
| info:  56 6b ba 6c  9a 8f df ce  a0 d7 07 80  4d 46 90 b6
| info:  00 00 74 d3
| processing informational R_U_THERE (36136)
| **emit ISAKMP Message:
|    initiator cookie:
|   56 6b ba 6c  9a 8f df ce
|    responder cookie:
|   a0 d7 07 80  4d 46 90 b6
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  2a 9a a3 7e
| ***emit ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_N
| emitting 20 zero bytes of HASH into ISAKMP Hash Payload
| emitting length of ISAKMP Hash Payload: 24
| ***emit ISAKMP Notification Payload:
|    next payload type: ISAKMP_NEXT_NONE
|    DOI: ISAKMP_DOI_IPSEC
|    protocol ID: 1
|    SPI size: 16
|    Notify Message Type: R_U_THERE_ACK
| emitting 8 raw bytes of notify icookie into ISAKMP Notification Payload
| notify icookie  56 6b ba 6c  9a 8f df ce
| emitting 8 raw bytes of notify rcookie into ISAKMP Notification Payload
| notify rcookie  a0 d7 07 80  4d 46 90 b6
| emitting 4 raw bytes of notify data into ISAKMP Notification Payload
| notify data  00 00 74 d3
| emitting length of ISAKMP Notification Payload: 32
| HASH computed:
|   e4 24 5d ba  ae 0b 70 14  4e da 80 ef  7e f1 13 39
|   5f 82 a5 c8
| last Phase 1 IV:  ce 3c 12 ec  95 8b b4 5f  fa 61 e4 fb  00 40 55 3c
| current Phase 1 IV:  ce 3c 12 ec  95 8b b4 5f  fa 61 e4 fb  00 40 55 3c
| computed Phase 2 IV:
|   e1 7d e3 c6  cd 2f 67 78  ef 44 76 8c  9d 38 2a 20
|   b7 b6 17 13
| encrypting:
|   0b 00 00 18  e4 24 5d ba  ae 0b 70 14  4e da 80 ef
|   7e f1 13 39  5f 82 a5 c8  00 00 00 20  00 00 00 01
|   01 10 8d 29  56 6b ba 6c  9a 8f df ce  a0 d7 07 80
|   4d 46 90 b6  00 00 74 d3
| IV:
|   e1 7d e3 c6  cd 2f 67 78  ef 44 76 8c  9d 38 2a 20
|   b7 b6 17 13
| unpadded size is: 56
| emitting 8 zero bytes of encryption padding into ISAKMP Message
| encrypting 64 using OAKLEY_AES_CBC
| next IV:  92 b1 09 ff  57 2a 74 05  94 71 b8 45  8a 03 c2 4a
| emitting length of ISAKMP Message: 92
| sending 92 bytes for ISAKMP notify through eth2:500 to 10.29.3.225:500 (using #7)
|   56 6b ba 6c  9a 8f df ce  a0 d7 07 80  4d 46 90 b6
|   08 10 05 01  2a 9a a3 7e  00 00 00 5c  37 96 b2 4e
|   8c e5 8a 62  64 dd a9 79  1a 65 43 83  b1 6f 71 7b
|   ec 6a ac bb  41 f8 b3 42  0b 82 6a 81  d3 4b 5c f2
|   c9 7a 35 83  22 50 e7 d6  c5 84 09 30  92 b1 09 ff
|   57 2a 74 05  94 71 b8 45  8a 03 c2 4a
| state: 7 requesting event EVENT_DPD_TIMEOUT to be deleted by /home/mix/work/dsa3600-ipv6/fruitfarm/product/MB120/work/buildroot/build_arm/openswan-2.6.24/programs/pluto/dpd.c:437
| complete state transition with STF_IGNORE
| * processed 0 messages from cryptographic helpers 
| next event EVENT_PENDING_DDNS in 7 seconds
| next event EVENT_PENDING_DDNS in 7 seconds
|  
| *received 92 bytes from 10.29.3.225:500 on eth2 (port=500)
|   56 6b ba 6c  9a 8f df ce  a0 d7 07 80  4d 46 90 b6
|   08 10 05 01  60 57 b5 ff  00 00 00 5c  70 5e a8 93
|   72 2d 53 f8  46 eb ab 9b  f1 2d b5 22  62 3e 52 b0
|   cc 77 ca 48  fe 38 7a ce  0e 5c dd 7c  94 fc f0 22
|   93 b3 1f 0e  54 45 40 ec  0a a0 b7 d7  e2 7d e0 69
|   d7 7a c9 7c  0f 25 1b 07  7f 2a 4d 67
| **parse ISAKMP Message:
|    initiator cookie:
|   56 6b ba 6c  9a 8f df ce
|    responder cookie:
|   a0 d7 07 80  4d 46 90 b6
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  60 57 b5 ff
|    length: 92
|  processing version=1.0 packet with exchange type=ISAKMP_XCHG_INFO (5)
| ICOOKIE:  56 6b ba 6c  9a 8f df ce
| RCOOKIE:  a0 d7 07 80  4d 46 90 b6
| state hash entry 18
| peer and cookies match on #8, provided msgid 00000000 vs 590fb497/00000000
| peer and cookies match on #7, provided msgid 00000000 vs 00000000/00000000
| p15 state object #7 found, in STATE_MAIN_I4
| processing connection site_192.168.1.0_24-192.168.123.0_24
| last Phase 1 IV:  ce 3c 12 ec  95 8b b4 5f  fa 61 e4 fb  00 40 55 3c
| current Phase 1 IV:  ce 3c 12 ec  95 8b b4 5f  fa 61 e4 fb  00 40 55 3c
| computed Phase 2 IV:
|   55 47 5d 31  bf 69 9c 6f  82 17 dc c1  56 89 35 08
|   d7 a0 83 c6
| received encrypted packet from 10.29.3.225:500
| decrypting 64 bytes using algorithm OAKLEY_AES_CBC
| decrypted:
|   0b 00 00 18  75 17 bc d4  55 20 dd 52  16 07 17 32
|   4d 72 07 54  96 b5 43 89  00 00 00 20  00 00 00 01
|   01 10 8d 29  56 6b ba 6c  9a 8f df ce  a0 d7 07 80
|   4d 46 90 b6  00 00 2b 9c  00 00 00 00  00 00 00 00
| next IV:  e2 7d e0 69  d7 7a c9 7c  0f 25 1b 07  7f 2a 4d 67
| got payload 0x100(ISAKMP_NEXT_HASH) needed: 0x100 opt: 0x0
| ***parse ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_N
|    length: 24
| got payload 0x800(ISAKMP_NEXT_N) needed: 0x0 opt: 0x0
| ***parse ISAKMP Notification Payload:
|    next payload type: ISAKMP_NEXT_NONE
|    length: 32
|    DOI: ISAKMP_DOI_IPSEC
|    protocol ID: 1
|    SPI size: 16
|    Notify Message Type: R_U_THERE_ACK
| removing 8 bytes of padding
| info:  56 6b ba 6c  9a 8f df ce  a0 d7 07 80  4d 46 90 b6
| info:  00 00 2b 9c
| processing informational R_U_THERE_ACK (36137)
| complete state transition with STF_IGNORE
| * processed 0 messages from cryptographic helpers 
| next event EVENT_PENDING_DDNS in 7 seconds
| next event EVENT_PENDING_DDNS in 7 seconds
|  
| next event EVENT_PENDING_DDNS in 0 seconds
| *time to handle event
| handling event EVENT_PENDING_DDNS
| event after this is EVENT_DPD in 3 seconds
| inserting event EVENT_PENDING_DDNS, timeout in 60 seconds
| event added after event EVENT_DPD for #8
| next event EVENT_DPD in 3 seconds for #8
|  
| *received 92 bytes from 10.29.3.225:500 on eth2 (port=500)
|   56 6b ba 6c  9a 8f df ce  a0 d7 07 80  4d 46 90 b6
|   08 10 05 01  8b 16 c6 04  00 00 00 5c  ee 61 14 b9
|   43 13 aa f2  9d 3d 9f 08  44 6d af 56  fa 06 cb 2e
|   7f 40 5b cb  94 f9 88 be  ff 52 6a 92  73 f2 17 5f
|   3c 9b cb aa  f8 cb 93 4b  c8 af 8e 81  1a e4 d2 4d
|   94 02 e1 9c  2b 3a 3a 0b  72 5d c2 37
| **parse ISAKMP Message:
|    initiator cookie:
|   56 6b ba 6c  9a 8f df ce
|    responder cookie:
|   a0 d7 07 80  4d 46 90 b6
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  8b 16 c6 04
|    length: 92
|  processing version=1.0 packet with exchange type=ISAKMP_XCHG_INFO (5)
| ICOOKIE:  56 6b ba 6c  9a 8f df ce
| RCOOKIE:  a0 d7 07 80  4d 46 90 b6
| state hash entry 18
| peer and cookies match on #8, provided msgid 00000000 vs 590fb497/00000000
| peer and cookies match on #7, provided msgid 00000000 vs 00000000/00000000
| p15 state object #7 found, in STATE_MAIN_I4
| processing connection site_192.168.1.0_24-192.168.123.0_24
| last Phase 1 IV:  ce 3c 12 ec  95 8b b4 5f  fa 61 e4 fb  00 40 55 3c
| current Phase 1 IV:  ce 3c 12 ec  95 8b b4 5f  fa 61 e4 fb  00 40 55 3c
| computed Phase 2 IV:
|   bc 37 a3 fb  23 27 06 36  6b dd cb 4a  ab 90 91 53
|   40 d8 3a 87
| received encrypted packet from 10.29.3.225:500
| decrypting 64 bytes using algorithm OAKLEY_AES_CBC
| decrypted:
|   0b 00 00 18  a2 17 a6 86  90 07 8e e6  6e 22 69 c1
|   bc d7 d7 53  db 7d e0 a3  00 00 00 20  00 00 00 01
|   01 10 8d 28  56 6b ba 6c  9a 8f df ce  a0 d7 07 80
|   4d 46 90 b6  00 00 74 d4  00 00 00 00  00 00 00 00
| next IV:  1a e4 d2 4d  94 02 e1 9c  2b 3a 3a 0b  72 5d c2 37
| got payload 0x100(ISAKMP_NEXT_HASH) needed: 0x100 opt: 0x0
| ***parse ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_N
|    length: 24
| got payload 0x800(ISAKMP_NEXT_N) needed: 0x0 opt: 0x0
| ***parse ISAKMP Notification Payload:
|    next payload type: ISAKMP_NEXT_NONE
|    length: 32
|    DOI: ISAKMP_DOI_IPSEC
|    protocol ID: 1
|    SPI size: 16
|    Notify Message Type: R_U_THERE
| removing 8 bytes of padding
| info:  56 6b ba 6c  9a 8f df ce  a0 d7 07 80  4d 46 90 b6
| info:  00 00 74 d4
| processing informational R_U_THERE (36136)
| **emit ISAKMP Message:
|    initiator cookie:
|   56 6b ba 6c  9a 8f df ce
|    responder cookie:
|   a0 d7 07 80  4d 46 90 b6
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  da 6c e3 d2
| ***emit ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_N
| emitting 20 zero bytes of HASH into ISAKMP Hash Payload
| emitting length of ISAKMP Hash Payload: 24
| ***emit ISAKMP Notification Payload:
|    next payload type: ISAKMP_NEXT_NONE
|    DOI: ISAKMP_DOI_IPSEC
|    protocol ID: 1
|    SPI size: 16
|    Notify Message Type: R_U_THERE_ACK
| emitting 8 raw bytes of notify icookie into ISAKMP Notification Payload
| notify icookie  56 6b ba 6c  9a 8f df ce
| emitting 8 raw bytes of notify rcookie into ISAKMP Notification Payload
| notify rcookie  a0 d7 07 80  4d 46 90 b6
| emitting 4 raw bytes of notify data into ISAKMP Notification Payload
| notify data  00 00 74 d4
| emitting length of ISAKMP Notification Payload: 32
| HASH computed:
|   6d 19 73 7a  07 6d 0f e9  f5 38 67 84  b8 fc 51 ba
|   c3 a2 e0 49
| last Phase 1 IV:  ce 3c 12 ec  95 8b b4 5f  fa 61 e4 fb  00 40 55 3c
| current Phase 1 IV:  ce 3c 12 ec  95 8b b4 5f  fa 61 e4 fb  00 40 55 3c
| computed Phase 2 IV:
|   75 76 d8 eb  26 f4 02 5c  a2 59 fa ca  56 66 6c 8e
|   79 18 03 d7
| encrypting:
|   0b 00 00 18  6d 19 73 7a  07 6d 0f e9  f5 38 67 84
|   b8 fc 51 ba  c3 a2 e0 49  00 00 00 20  00 00 00 01
|   01 10 8d 29  56 6b ba 6c  9a 8f df ce  a0 d7 07 80
|   4d 46 90 b6  00 00 74 d4
| IV:
|   75 76 d8 eb  26 f4 02 5c  a2 59 fa ca  56 66 6c 8e
|   79 18 03 d7
| unpadded size is: 56
| emitting 8 zero bytes of encryption padding into ISAKMP Message
| encrypting 64 using OAKLEY_AES_CBC
| next IV:  15 a7 9a d8  6f 1b 7a 8a  a0 d2 70 46  e9 2e f7 e0
| emitting length of ISAKMP Message: 92
| sending 92 bytes for ISAKMP notify through eth2:500 to 10.29.3.225:500 (using #7)
|   56 6b ba 6c  9a 8f df ce  a0 d7 07 80  4d 46 90 b6
|   08 10 05 01  da 6c e3 d2  00 00 00 5c  25 64 17 44
|   14 ea 20 99  ed fd a5 0e  f6 55 b3 e8  00 f6 00 e9
|   91 3f 52 6f  a1 a1 73 b7  4f c8 5b 33  4d ce ef 02
|   b3 b2 ca 7b  76 96 a1 11  83 66 bb 4b  15 a7 9a d8
|   6f 1b 7a 8a  a0 d2 70 46  e9 2e f7 e0
| complete state transition with STF_IGNORE
| * processed 0 messages from cryptographic helpers 
| next event EVENT_DPD in 0 seconds for #8
| *time to handle event
| handling event EVENT_DPD
| event after this is EVENT_PENDING_DDNS in 57 seconds
| processing connection site_192.168.1.0_24-192.168.123.0_24
| inserting event EVENT_DPD, timeout in 10 seconds for #8
| event added at head of queue
| next event EVENT_DPD in 10 seconds for #8
