
|  
| 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
| next event EVENT_PENDING_DDNS in 60 seconds
|  
| *received whack message
| * processed 0 messages from cryptographic helpers 
| next event EVENT_PENDING_DDNS in 56 seconds
| next event EVENT_PENDING_DDNS in 56 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 49 seconds
| next event EVENT_PENDING_DDNS in 49 seconds
|  
| *received 120 bytes from 10.29.3.225:500 on eth2 (port=500)
|   34 3b dd c9  48 17 1c b2  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:
|   34 3b dd c9  48 17 1c b2
|    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: comparing to 10.29.3.195:500 10.29.3.225:500 
| find_host_pair_conn (find_host_connection2): 10.29.3.195:500 10.29.3.225:500 -> hp:site_192.168.1.0_24-192.168.123.0_24 
| find_host_connection returns site_192.168.1.0_24-192.168.123.0_24
| creating state object #5 at 0x17f0b0
| processing connection site_192.168.1.0_24-192.168.123.0_24
| ICOOKIE:  34 3b dd c9  48 17 1c b2
| RCOOKIE:  d0 7f be a1  53 fe 72 99
| state hash entry 28
| inserting state object #5 on chain 28
| inserting event EVENT_SO_DISCARD, timeout in 0 seconds for #5
| event added at head of queue
"site_192.168.1.0_24-192.168.123.0_24" #5: responding to Main Mode
| **emit ISAKMP Message:
|    initiator cookie:
|   34 3b dd c9  48 17 1c b2
|    responder cookie:
|   d0 7f be a1  53 fe 72 99
|    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
| ****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: 1
|    [1 is OAKLEY_GROUP_MODP768]
| ******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
| ****emit IPsec DOI SIT:
|    IPsec DOI SIT: SIT_IDENTITY_ONLY
| ****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
| emitting 28 raw bytes of attributes into ISAKMP Transform Payload (ISAKMP)
| attributes  80 0b 00 01  80 0c 70 80  80 01 00 07  80 02 00 02
| attributes  80 03 00 01  80 04 00 01  80 0e 01 00
| 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
| sender checking NAT-t: 0 and 0
| emitting length of ISAKMP Message: 120
| complete state transition with STF_OK
"site_192.168.1.0_24-192.168.123.0_24" #5: transition from state STATE_MAIN_R0 to state STATE_MAIN_R1
| deleting event for #5
| sending reply packet to 10.29.3.225:500 (from port 500)
| sending 120 bytes for STATE_MAIN_R0 through eth2:500 to 10.29.3.225:500 (using #5)
|   34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
|   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
| inserting event EVENT_RETRANSMIT, timeout in 10 seconds for #5
| event added at head of queue
"site_192.168.1.0_24-192.168.123.0_24" #5: STATE_MAIN_R1: sent MR1, expecting MI2
| modecfg pull: noquirk policy:push not-client
| phase 1 is done, looking for phase 2 to unpend
| * processed 0 messages from cryptographic helpers 
| next event EVENT_RETRANSMIT in 10 seconds for #5
| next event EVENT_RETRANSMIT in 10 seconds for #5
|  
| *received 148 bytes from 10.29.3.225:500 on eth2 (port=500)
|   34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
|   04 10 02 00  00 00 00 00  00 00 00 94  0a 00 00 64
|   ea 17 b3 bb  95 d5 80 95  be ea 17 d0  5c 48 c8 ce
|   57 90 44 b7  33 e0 79 86  72 11 13 bc  87 ca 5f 51
|   1d e2 d2 4f  d9 3a 90 c6  03 bb 90 94  20 6e 89 a7
|   ef 3e 2b e8  3b 19 37 a7  f6 c3 39 b6  87 c1 17 57
|   01 96 a7 ea  aa f3 4e e6  04 67 7a f7  f7 23 eb 59
|   b7 0a 5e 30  ff 3c fa 0f  db 8e 56 0f  a7 46 7a fd
|   00 00 00 14  89 9d ab c8  cd 0f a1 ca  28 56 30 2e
|   29 bb 92 1e
| **parse ISAKMP Message:
|    initiator cookie:
|   34 3b dd c9  48 17 1c b2
|    responder cookie:
|   d0 7f be a1  53 fe 72 99
|    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: 148
|  processing version=1.0 packet with exchange type=ISAKMP_XCHG_IDPROT (2)
| ICOOKIE:  34 3b dd c9  48 17 1c b2
| RCOOKIE:  d0 7f be a1  53 fe 72 99
| state hash entry 28
| v1 peer and cookies match on #5, provided msgid 00000000 vs 00000000
| v1 state object #5 found, in STATE_MAIN_R1
| 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: 100
| got payload 0x400(ISAKMP_NEXT_NONCE) needed: 0x400 opt: 0x102080
| ***parse ISAKMP Nonce Payload:
|    next payload type: ISAKMP_NEXT_NONE
|    length: 20
| DH public value received:
|   ea 17 b3 bb  95 d5 80 95  be ea 17 d0  5c 48 c8 ce
|   57 90 44 b7  33 e0 79 86  72 11 13 bc  87 ca 5f 51
|   1d e2 d2 4f  d9 3a 90 c6  03 bb 90 94  20 6e 89 a7
|   ef 3e 2b e8  3b 19 37 a7  f6 c3 39 b6  87 c1 17 57
|   01 96 a7 ea  aa f3 4e e6  04 67 7a f7  f7 23 eb 59
|   b7 0a 5e 30  ff 3c fa 0f  db 8e 56 0f  a7 46 7a fd
| inI2: 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: 9 (len=2668, pcw_work=1)
! helper 0 read 2664+4/2668 bytesfd: 7
! helper 0 doing build_kenonce op id: 9
! Local DH secret:
!   ee 98 a7 3e  39 13 0e 3b  24 49 7a 1f  90 c7 16 10
!   e2 e4 86 5c  27 5d 02 05  02 75 88 1a  02 d2 f6 7d
! Public DH value sent:
!   a1 15 c7 bd  47 ad 5e 74  5d ea 09 13  75 00 e0 63
!   cd fd a9 b6  d1 7b 58 76  aa 0e 75 49  21 a3 f4 2d
!   bf 91 b0 15  2a 67 9f bc  c0 2b e5 ee  a3 29 e7 86
!   77 c9 0a df  35 8c 05 2e  78 d3 1f 04  1c a8 4d 37
!   d1 e5 f0 a7  9c 12 56 ae  9f db 41 db  f9 e6 c7 bb
!   27 e8 0e de  d8 1c cc 91  3c 47 bd f7  c9 15 f4 20
! Generated nonce:
!   97 64 a5 2f  ef c0 34 45  6a 2a f6 e7  9c cb cc cc
| crypto helper write of request: cnt=2668<wlen=2668.  
| deleting event for #5
| inserting event EVENT_CRYPTO_FAILED, timeout in 300 seconds for #5
| 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 38 seconds
| next event EVENT_PENDING_DDNS in 38 seconds
|  
| helper 0 has finished work (cnt now 1)
| helper 0 replies to id: q#9
| calling callback function 0x330dc
| main inI2_outR2: calculated ke+nonce, sending R2
| processing connection site_192.168.1.0_24-192.168.123.0_24
| **emit ISAKMP Message:
|    initiator cookie:
|   34 3b dd c9  48 17 1c b2
|    responder cookie:
|   d0 7f be a1  53 fe 72 99
|    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 96 raw bytes of keyex value into ISAKMP Key Exchange Payload
| keyex value  a1 15 c7 bd  47 ad 5e 74  5d ea 09 13  75 00 e0 63
| keyex value  cd fd a9 b6  d1 7b 58 76  aa 0e 75 49  21 a3 f4 2d
| keyex value  bf 91 b0 15  2a 67 9f bc  c0 2b e5 ee  a3 29 e7 86
| keyex value  77 c9 0a df  35 8c 05 2e  78 d3 1f 04  1c a8 4d 37
| keyex value  d1 e5 f0 a7  9c 12 56 ae  9f db 41 db  f9 e6 c7 bb
| keyex value  27 e8 0e de  d8 1c cc 91  3c 47 bd f7  c9 15 f4 20
| emitting length of ISAKMP Key Exchange Payload: 100
| ***emit ISAKMP Nonce Payload:
|    next payload type: ISAKMP_NEXT_NONE
| emitting 16 raw bytes of Nr into ISAKMP Nonce Payload
| Nr  97 64 a5 2f  ef c0 34 45  6a 2a f6 e7  9c cb cc cc
| emitting length of ISAKMP Nonce Payload: 20
| emitting length of ISAKMP Message: 148
| main inI2_outR2: starting async DH calculation (group=1)
| 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: 1 len: 2668 
| 0: w->pcw_dead: 0 w->pcw_work: 0 cnt: 1
| asking helper 0 to do compute dh+iv op on seq: 10 (len=2668, pcw_work=1)
! helper 0 read 2664+4/2668 bytesfd: 7
! helper 0 doing compute dh+iv op id: 10
! peer's g:   ea 17 b3 bb  95 d5 80 95  be ea 17 d0  5c 48 c8 ce
! peer's g:   57 90 44 b7  33 e0 79 86  72 11 13 bc  87 ca 5f 51
! peer's g:   1d e2 d2 4f  d9 3a 90 c6  03 bb 90 94  20 6e 89 a7
! peer's g:   ef 3e 2b e8  3b 19 37 a7  f6 c3 39 b6  87 c1 17 57
! peer's g:   01 96 a7 ea  aa f3 4e e6  04 67 7a f7  f7 23 eb 59
! peer's g:   b7 0a 5e 30  ff 3c fa 0f  db 8e 56 0f  a7 46 7a fd
! long term secret:   ee 98 a7 3e  39 13 0e 3b  24 49 7a 1f  90 c7 16 10
! long term secret:   e2 e4 86 5c  27 5d 02 05  02 75 88 1a  02 d2 f6 7d
! calc_dh_shared(): time elapsed (OAKLEY_GROUP_MODP768): 9310 usec
! DH shared-secret:
!   08 6c 83 ce  22 72 24 10  e8 e0 fd 0d  22 dc 45 9d
!   0d 4c fe 84  9b c9 06 dd  99 ea 1c b2  9b 8b 9e 78
!   23 d0 61 e5  3d 8a 7a 0f  50 fa 66 cb  1a c9 1a 00
!   1a 82 86 08  3b de e6 ad  d0 38 df 68  3a ce 6a 04
!   30 a9 09 c5  63 6c d4 52  36 6d fe fc  cf 8d f3 d6
!   09 46 7e 4c  a0 b0 87 4f  09 e3 06 f8  e3 8b a6 8c
! Skey inputs (PSK+NI+NR)
! ni:   89 9d ab c8  cd 0f a1 ca  28 56 30 2e  29 bb 92 1e
! nr:   97 64 a5 2f  ef c0 34 45  6a 2a f6 e7  9c cb cc cc
! keyid:   14 93 31 e3  82 70 dc 15  fb e5 05 0d  b5 b2 65 b9
! keyid:   df 1c 9b 89
! NSS: end of key computation 
! DH_i:  ea 17 b3 bb  95 d5 80 95  be ea 17 d0  5c 48 c8 ce
! DH_i:  57 90 44 b7  33 e0 79 86  72 11 13 bc  87 ca 5f 51
! DH_i:  1d e2 d2 4f  d9 3a 90 c6  03 bb 90 94  20 6e 89 a7
! DH_i:  ef 3e 2b e8  3b 19 37 a7  f6 c3 39 b6  87 c1 17 57
! DH_i:  01 96 a7 ea  aa f3 4e e6  04 67 7a f7  f7 23 eb 59
! DH_i:  b7 0a 5e 30  ff 3c fa 0f  db 8e 56 0f  a7 46 7a fd
! DH_r:  a1 15 c7 bd  47 ad 5e 74  5d ea 09 13  75 00 e0 63
! DH_r:  cd fd a9 b6  d1 7b 58 76  aa 0e 75 49  21 a3 f4 2d
! DH_r:  bf 91 b0 15  2a 67 9f bc  c0 2b e5 ee  a3 29 e7 86
! DH_r:  77 c9 0a df  35 8c 05 2e  78 d3 1f 04  1c a8 4d 37
! DH_r:  d1 e5 f0 a7  9c 12 56 ae  9f db 41 db  f9 e6 c7 bb
! DH_r:  27 e8 0e de  d8 1c cc 91  3c 47 bd f7  c9 15 f4 20
! end of IV generation 
! Skeyid:    14 93 31 e3  82 70 dc 15  fb e5 05 0d  b5 b2 65 b9
! Skeyid:    df 1c 9b 89
! Skeyid_d:  7e 60 52 f9  e9 3b d8 45  7f 2c 93 66  21 2c 00 07
! Skeyid_d:  49 0f a4 f7
! Skeyid_a:  9d 5a 1b aa  c8 10 3e e1  00 13 38 e9  2d d7 a6 61
! Skeyid_a:  ea b3 c2 8e
! Skeyid_e:  18 d3 e7 0d  5f 1f de ef  93 08 28 25  69 95 93 cd
! Skeyid_e:  09 95 3b ae
! enc key:  f1 8d ff 42  b6 25 f8 ee  1e a0 1b ba  83 c5 ce 5a
! enc key:  02 97 d4 d2  5d 35 32 98  ef ea c8 81  16 af be 4d
! IV:  d6 52 5e 18  a5 fc 05 3c  47 94 b6 16  84 57 97 cf
! IV:  72 6f 21 60
| crypto helper write of request: cnt=2668<wlen=2668.  
| deleting event for #5
| inserting event EVENT_CRYPTO_FAILED, timeout in 300 seconds for #5
| event added after event EVENT_SHUNT_SCAN
| started dh_secretiv, returned: stf=STF_SUSPEND 
| complete state transition with STF_OK
"site_192.168.1.0_24-192.168.123.0_24" #5: transition from state STATE_MAIN_R1 to state STATE_MAIN_R2
| deleting event for #5
| sending reply packet to 10.29.3.225:500 (from port 500)
| sending 148 bytes for STATE_MAIN_R1 through eth2:500 to 10.29.3.225:500 (using #5)
|   34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
|   04 10 02 00  00 00 00 00  00 00 00 94  0a 00 00 64
|   a1 15 c7 bd  47 ad 5e 74  5d ea 09 13  75 00 e0 63
|   cd fd a9 b6  d1 7b 58 76  aa 0e 75 49  21 a3 f4 2d
|   bf 91 b0 15  2a 67 9f bc  c0 2b e5 ee  a3 29 e7 86
|   77 c9 0a df  35 8c 05 2e  78 d3 1f 04  1c a8 4d 37
|   d1 e5 f0 a7  9c 12 56 ae  9f db 41 db  f9 e6 c7 bb
|   27 e8 0e de  d8 1c cc 91  3c 47 bd f7  c9 15 f4 20
|   00 00 00 14  97 64 a5 2f  ef c0 34 45  6a 2a f6 e7
|   9c cb cc cc
| inserting event EVENT_RETRANSMIT, timeout in 10 seconds for #5
| event added at head of queue
"site_192.168.1.0_24-192.168.123.0_24" #5: STATE_MAIN_R2: sent MR2, expecting MI3
| 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 #5
| next event EVENT_RETRANSMIT in 10 seconds for #5
|  
| helper 0 has finished work (cnt now 1)
| helper 0 replies to id: q#10
| calling callback function 0x33648
| main inI2_outR2: calculated DH finished
| processing connection site_192.168.1.0_24-192.168.123.0_24
| * processed 1 messages from cryptographic helpers 
| next event EVENT_RETRANSMIT in 10 seconds for #5
| next event EVENT_RETRANSMIT in 10 seconds for #5
|  
| *received 76 bytes from 10.29.3.225:500 on eth2 (port=500)
|   34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
|   05 10 02 01  00 00 00 00  00 00 00 4c  f2 4f 1c 9e
|   f2 3c ce bf  79 e3 ea 8f  4c b3 29 46  e0 7b 50 e4
|   28 90 72 88  7c 71 2c a2  32 e0 c8 57  5d 47 55 d0
|   24 c0 0e 1d  00 2f 2f 8e  22 37 76 20
| **parse ISAKMP Message:
|    initiator cookie:
|   34 3b dd c9  48 17 1c b2
|    responder cookie:
|   d0 7f be a1  53 fe 72 99
|    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:  34 3b dd c9  48 17 1c b2
| RCOOKIE:  d0 7f be a1  53 fe 72 99
| state hash entry 28
| v1 peer and cookies match on #5, provided msgid 00000000 vs 00000000
| v1 state object #5 found, in STATE_MAIN_R2
| 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  00 00 00 18
|   ce d1 87 a9  28 ba 50 94  07 5a aa 57  29 c0 65 67
|   a0 da 2f 27  00 00 00 00  00 00 00 00  00 00 00 00
| next IV:  5d 47 55 d0  24 c0 0e 1d  00 2f 2f 8e  22 37 76 20
| 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  00 00 00 18  ce d1 87 a9
| got payload 0x100(ISAKMP_NEXT_HASH) needed: 0x100 opt: 0x2080
| ***parse ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_NONE
|    length: 24
| removing 12 bytes of padding
"site_192.168.1.0_24-192.168.123.0_24" #5: Main mode peer ID is ID_IPV4_ADDR: '10.29.3.225'
| refine_connection: starting with site_192.168.1.0_24-192.168.123.0_24
| 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)
|    match_id a=10.29.3.225
|             b=10.29.3.225
|    results  matched
|   trusted_ca called with a=(empty) b=(empty)
| refine_connection: checking site_192.168.1.0_24-192.168.123.0_24 against site_192.168.1.0_24-192.168.123.0_24, best=(none) with match=1(id=1/ca=1/reqca=1)
| refine_connection: checked site_192.168.1.0_24-192.168.123.0_24 against site_192.168.1.0_24-192.168.123.0_24, now for see if best
| 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)
| offered CA: '%none'
| hashing 52 bytes of SA
| authentication succeeded
| 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)
| **emit ISAKMP Message:
|    initiator cookie:
|   34 3b dd c9  48 17 1c b2
|    responder cookie:
|   d0 7f be a1  53 fe 72 99
|    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
| ***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_VID
| emitting 20 raw bytes of HASH_R into ISAKMP Hash Payload
| HASH_R  ac b4 18 72  45 97 b2 ce  b3 04 1b 00  6e 4b 3e 47
| HASH_R  7b 50 b5 7d
| emitting length of ISAKMP Hash Payload: 24
| out_vendorid(): sending [CAN-IKEv2]
| ***emit ISAKMP Vendor ID Payload:
|    next payload type: ISAKMP_NEXT_NONE
| emitting 5 raw bytes of V_ID into ISAKMP Vendor ID Payload
| V_ID  49 4b 45 76  32
| emitting length of ISAKMP Vendor ID Payload: 9
| encrypting:
|   08 00 00 0c  01 00 00 00  0a 1d 03 c3  0d 00 00 18
|   ac b4 18 72  45 97 b2 ce  b3 04 1b 00  6e 4b 3e 47
|   7b 50 b5 7d  00 00 00 09  49 4b 45 76  32
| IV:
|   5d 47 55 d0  24 c0 0e 1d  00 2f 2f 8e  22 37 76 20
| unpadded size is: 45
| emitting 3 zero bytes of encryption padding into ISAKMP Message
| encrypting 48 using OAKLEY_AES_CBC
| next IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| emitting length of ISAKMP Message: 76
| last encrypted block of Phase 1:
|   fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| complete state transition with STF_OK
"site_192.168.1.0_24-192.168.123.0_24" #5: transition from state STATE_MAIN_R2 to state STATE_MAIN_R3
| deleting event for #5
| sending reply packet to 10.29.3.225:500 (from port 500)
| sending 76 bytes for STATE_MAIN_R2 through eth2:500 to 10.29.3.225:500 (using #5)
|   34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
|   05 10 02 01  00 00 00 00  00 00 00 4c  f4 cb c2 c8
|   4e 16 eb e4  e4 ee 77 1c  fb aa 0c dd  10 6e 83 15
|   32 33 e5 19  91 e2 15 fa  13 53 c3 46  fd 12 4e 7d
|   1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| inserting event EVENT_SA_EXPIRE, timeout in 28800 seconds for #5
| event added after event EVENT_LOG_DAILY
"site_192.168.1.0_24-192.168.123.0_24" #5: STATE_MAIN_R3: sent MR3, ISAKMP SA established {auth=OAKLEY_PRESHARED_KEY cipher=aes_256 prf=oakley_sha group=modp768}
| ICOOKIE:  34 3b dd c9  48 17 1c b2
| RCOOKIE:  d0 7f be a1  53 fe 72 99
| state hash entry 28
| v1 peer and cookies match on #5, provided msgid 00000000 vs 00000000
| v1 state object #5 found, in STATE_MAIN_R3
"site_192.168.1.0_24-192.168.123.0_24" #5: Dead Peer Detection (RFC 3706): enabled
| state: 5 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 #5
| event added at head of queue
| modecfg pull: noquirk policy:push not-client
| phase 1 is done, looking for phase 2 to unpend
| * processed 0 messages from cryptographic helpers 
| next event EVENT_DPD in 10 seconds for #5
| next event EVENT_DPD in 10 seconds for #5
|  
| *received 268 bytes from 10.29.3.225:500 on eth2 (port=500)
|   34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
|   08 10 20 01  35 61 c8 bc  00 00 01 0c  61 69 43 4a
|   f5 18 94 6d  2f 5b d4 cb  52 cf e8 74  96 67 13 1a
|   9b 7a bf 6a  69 5d c9 21  19 cc 0e a8  9a b1 c0 c3
|   37 fb de 88  6e 7f 9d 65  df 2a cb 12  12 91 77 a1
|   97 f0 59 e1  f1 73 86 70  f8 17 72 04  c7 f3 06 21
|   15 49 d8 4c  58 3e 8d 2e  93 52 a4 42  e1 5f e9 9c
|   73 02 ca d8  c6 59 23 50  b7 c2 d3 af  e9 51 4d fd
|   43 4f 48 3c  05 57 53 49  25 52 90 77  20 3f 43 8b
|   75 00 98 0e  a2 c5 66 28  ff 5f 65 5b  fb 1b 1f c5
|   8d c5 e7 87  7a 20 a7 6b  11 47 bc db  6f 79 fb 0a
|   77 e8 b2 08  a4 36 7f 62  89 40 3d 1c  b8 cc 52 9a
|   d1 87 c5 5d  57 5f 1e 51  6a f4 36 ec  e4 c7 a8 80
|   69 e4 3a 99  74 f2 8f fd  15 00 04 4e  fa a3 38 83
|   e9 dc 02 30  00 d1 7f ed  3e dc d3 11  e9 54 94 de
|   42 14 34 87  2f 9f 11 94  28 6e cd 2e  76 f8 b6 d2
|   cb b1 7f c7  66 4a 9b b6  c9 11 fc e7
| **parse ISAKMP Message:
|    initiator cookie:
|   34 3b dd c9  48 17 1c b2
|    responder cookie:
|   d0 7f be a1  53 fe 72 99
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_QUICK
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  35 61 c8 bc
|    length: 268
|  processing version=1.0 packet with exchange type=ISAKMP_XCHG_QUICK (32)
| ICOOKIE:  34 3b dd c9  48 17 1c b2
| RCOOKIE:  d0 7f be a1  53 fe 72 99
| state hash entry 28
| v1 peer and cookies match on #5, provided msgid 3561c8bc vs 00000000
| v1 state object not found
| ICOOKIE:  34 3b dd c9  48 17 1c b2
| RCOOKIE:  d0 7f be a1  53 fe 72 99
| state hash entry 28
| v1 peer and cookies match on #5, provided msgid 00000000 vs 00000000
| v1 state object #5 found, in STATE_MAIN_R3
| processing connection site_192.168.1.0_24-192.168.123.0_24
| last Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| current Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| computed Phase 2 IV:
|   f4 42 66 f6  ef c4 3c ac  d1 c4 06 ae  61 e5 5a 09
|   54 13 ff 51
| received encrypted packet from 10.29.3.225:500
| decrypting 240 bytes using algorithm OAKLEY_AES_CBC
| decrypted:
|   01 00 00 18  dd a5 42 00  0e a9 00 22  78 ab 50 bd
|   57 09 fc 7b  16 7a 55 ee  0a 00 00 3c  00 00 00 01
|   00 00 00 01  00 00 00 30  00 03 04 01  84 46 2a 0a
|   00 00 00 24  00 0c 00 00  80 03 00 01  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  67 ba 77 8a  08 d3 40 0b
|   5f 57 06 34  7a 32 5d a4  05 00 00 64  7c 3a 2c 8f
|   8a 15 b9 8f  26 be 33 52  e2 aa 8a 43  91 0a d1 10
|   08 af ed 60  1e f0 eb 45  66 bb f2 a7  c4 fe 5a f6
|   3c 0e 12 4c  73 3d f6 18  6d e1 49 8e  c5 81 6d 69
|   85 70 80 19  9a 2f 30 09  56 a3 0e 2b  b3 dd 95 0a
|   c7 0f e9 84  67 8a f2 6a  b4 75 e3 0c  70 23 80 b9
|   aa 0d 7c f5  e5 73 2a 86  52 ad 02 2f  05 00 00 10
|   04 00 00 00  c0 a8 7b 00  ff ff ff 00  00 00 00 10
|   04 00 00 00  c0 a8 01 00  ff ff ff 00  00 00 00 00
| next IV:  76 f8 b6 d2  cb b1 7f c7  66 4a 9b b6  c9 11 fc e7
| 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: 100
| 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 7b 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 01 00  ff ff ff 00  00 00 00 00  00 00 01 11
| removing 4 bytes of padding
| HASH(1) computed:
|   dd a5 42 00  0e a9 00 22  78 ab 50 bd  57 09 fc 7b
|   16 7a 55 ee
| peer client is subnet 192.168.123.0/24
| peer client protocol/port is 0/0
| our client is subnet 192.168.1.0/24
| our client protocol/port is 0/0
"site_192.168.1.0_24-192.168.123.0_24" #5: the peer proposed: 192.168.1.0/24:0/0 -> 192.168.123.0/24:0/0
| find_client_connection starting with site_192.168.1.0_24-192.168.123.0_24
|   looking for 192.168.1.0/24:0/0 -> 192.168.123.0/24:0/0
|   concrete checking against sr#0 192.168.1.0/24 -> 192.168.123.0/24
|    match_id a=10.29.3.225
|             b=10.29.3.225
|    results  matched
|   trusted_ca called with a=(empty) b=(empty)
|   fc_try trying site_192.168.1.0_24-192.168.123.0_24:192.168.1.0/24:0/0 -> 192.168.123.0/24:0/0 vs site_192.168.1.0_24-192.168.123.0_24:192.168.1.0/24:0/0 -> 192.168.123.0/24:0/0
|   fc_try concluding with site_192.168.1.0_24-192.168.123.0_24 [128]
|   fc_try site_192.168.1.0_24-192.168.123.0_24 gives site_192.168.1.0_24-192.168.123.0_24
|   concluding with d = site_192.168.1.0_24-192.168.123.0_24
| client wildcard: no  port wildcard: no  virtual: no
| duplicating state object #5
| creating state object #6 at 0x17fa68
| processing connection site_192.168.1.0_24-192.168.123.0_24
| ICOOKIE:  34 3b dd c9  48 17 1c b2
| RCOOKIE:  d0 7f be a1  53 fe 72 99
| state hash entry 28
| inserting state object #6 on chain 28
| inserting event EVENT_SO_DISCARD, timeout in 0 seconds for #6
| event added at head of queue
| ****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 0a
| *****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: 1
|    [1 is OAKLEY_GROUP_MODP768]
| ******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:
|   7c 3a 2c 8f  8a 15 b9 8f  26 be 33 52  e2 aa 8a 43
|   91 0a d1 10  08 af ed 60  1e f0 eb 45  66 bb f2 a7
|   c4 fe 5a f6  3c 0e 12 4c  73 3d f6 18  6d e1 49 8e
|   c5 81 6d 69  85 70 80 19  9a 2f 30 09  56 a3 0e 2b
|   b3 dd 95 0a  c7 0f e9 84  67 8a f2 6a  b4 75 e3 0c
|   70 23 80 b9  aa 0d 7c f5  e5 73 2a 86  52 ad 02 2f
| 0: w->pcw_dead: 0 w->pcw_work: 0 cnt: 1
| asking helper 0 to do build_kenonce op on seq: 11 (len=2668, pcw_work=1)
! helper 0 read 2664+4/2668 bytesfd: 7
! helper 0 doing build_kenonce op id: 11
| crypto helper write of request: cnt=2668<wlen=2668.  
| deleting event for #6
| inserting event EVENT_CRYPTO_FAILED, timeout in 300 seconds for #6
| 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 #5
| next event EVENT_DPD in 10 seconds for #5
! Local DH secret:
!   e6 36 04 a1  d1 ce e4 f9  3a 07 75 b4  f2 eb d9 7e
!   da 30 e7 32  e5 d6 91 2d  82 f1 56 46  72 89 4f 25
! Public DH value sent:
!   a5 a4 4d 1d  3a c5 6b e4  3c ae 03 0d  1c 8a 37 88
!   1d a8 cf cf  26 9a 58 d4  a0 86 37 cc  2f 16 30 91
!   2b 24 02 85  39 34 91 c9  f6 0b 04 08  5c f2 50 72
!   d1 7e 3c 9b  50 7c e8 7d  87 e3 cd 35  51 ee f1 9e
!   ad 8d e2 ac  7b 5d 6f eb  b0 9a f7 01  ae e8 06 c8
!   2c 5b 2f 46  ce fb c5 1b  ed 5f c1 27  db 9c 58 3b
! Generated nonce:
!   45 39 67 90  53 dd 86 fc  7c d0 e0 3d  fe e1 46 3c
|  
| helper 0 has finished work (cnt now 1)
| helper 0 replies to id: q#11
| calling callback function 0x3cda0
| quick inI1_outR1: calculated ke+nonce, calculating DH
| processing connection site_192.168.1.0_24-192.168.123.0_24
| 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: 12 (len=2668, pcw_work=1)
| crypto helper write of request: cnt=2668<wlen=2668.  
| deleting event for #6
| inserting event EVENT_CRYPTO_FAILED, timeout in 300 seconds for #6
| event added after event EVENT_SHUNT_SCAN
| * processed 1 messages from cryptographic helpers 
| next event EVENT_DPD in 10 seconds for #5
| next event EVENT_DPD in 10 seconds for #5
! helper 0 read 2664+4/2668 bytesfd: 7
! helper 0 doing compute dh(p2) op id: 12
! peer's g:   7c 3a 2c 8f  8a 15 b9 8f  26 be 33 52  e2 aa 8a 43
! peer's g:   91 0a d1 10  08 af ed 60  1e f0 eb 45  66 bb f2 a7
! peer's g:   c4 fe 5a f6  3c 0e 12 4c  73 3d f6 18  6d e1 49 8e
! peer's g:   c5 81 6d 69  85 70 80 19  9a 2f 30 09  56 a3 0e 2b
! peer's g:   b3 dd 95 0a  c7 0f e9 84  67 8a f2 6a  b4 75 e3 0c
! peer's g:   70 23 80 b9  aa 0d 7c f5  e5 73 2a 86  52 ad 02 2f
! calc_dh_shared(): time elapsed (OAKLEY_GROUP_MODP768): 9390 usec
! DH shared-secret:
!   58 0c b2 5a  ab 6c dd 80  3d c8 3a f1  9e 48 24 90
!   2d dd d3 ba  c9 f1 75 76  f9 db 23 50  20 a6 fc dd
!   4e e8 1d 86  34 04 c8 5e  45 00 8a ac  9c 86 e0 3e
!   99 79 ac 40  01 5d 60 c6  10 b7 58 3e  4e cb bf 5e
!   f5 79 a5 e4  a5 6a 45 3d  5a eb 9b 34  aa 20 28 d0
!   8c 42 7f 9d  2c 19 5f ce  d6 7a 7a 4f  78 2c 0d d5
|  
| helper 0 has finished work (cnt now 1)
| helper 0 replies to id: q#12
| calling callback function 0x3d178
| quick inI1_outR1: calculated DH, sending R1
| processing connection site_192.168.1.0_24-192.168.123.0_24
| **emit ISAKMP Message:
|    initiator cookie:
|   34 3b dd c9  48 17 1c b2
|    responder cookie:
|   d0 7f be a1  53 fe 72 99
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_QUICK
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  35 61 c8 bc
| ***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
| ***emit ISAKMP Security Association Payload:
|    next payload type: ISAKMP_NEXT_NONCE
|    DOI: ISAKMP_DOI_IPSEC
| ****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 0a
| *****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: 1
|    [1 is OAKLEY_GROUP_MODP768]
| ******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
| ****emit IPsec DOI SIT:
|    IPsec DOI SIT: SIT_IDENTITY_ONLY
| ****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 3e
| emitting 4 raw bytes of SPI into ISAKMP Proposal Payload
| SPI  23 90 0d 3e
| *****emit ISAKMP Transform Payload (ESP):
|    next payload type: ISAKMP_NEXT_NONE
|    transform number: 0
|    transform ID: ESP_AES
| emitting 28 raw bytes of attributes into ISAKMP Transform Payload (ESP)
| attributes  80 03 00 01  80 04 00 01  80 01 00 01  00 02 00 04
| attributes  00 01 51 80  80 05 00 01  80 06 01 00
| emitting length of ISAKMP Transform Payload (ESP): 36
| emitting length of ISAKMP Proposal Payload: 48
| emitting length of ISAKMP Security Association Payload: 60
"site_192.168.1.0_24-192.168.123.0_24" #6: responding to Quick Mode proposal {msgid:3561c8bc}
"site_192.168.1.0_24-192.168.123.0_24" #6:     us: 192.168.1.0/24===10.29.3.195<10.29.3.195>[+S=C]
"site_192.168.1.0_24-192.168.123.0_24" #6:   them: 10.29.3.225<10.29.3.225>[+S=C]===192.168.123.0/24
| ***emit ISAKMP Nonce Payload:
|    next payload type: ISAKMP_NEXT_KE
| emitting 16 raw bytes of Nr into ISAKMP Nonce Payload
| Nr  45 39 67 90  53 dd 86 fc  7c d0 e0 3d  fe e1 46 3c
| emitting length of ISAKMP Nonce Payload: 20
| ***emit ISAKMP Key Exchange Payload:
|    next payload type: ISAKMP_NEXT_ID
| emitting 96 raw bytes of keyex value into ISAKMP Key Exchange Payload
| keyex value  a5 a4 4d 1d  3a c5 6b e4  3c ae 03 0d  1c 8a 37 88
| keyex value  1d a8 cf cf  26 9a 58 d4  a0 86 37 cc  2f 16 30 91
| keyex value  2b 24 02 85  39 34 91 c9  f6 0b 04 08  5c f2 50 72
| keyex value  d1 7e 3c 9b  50 7c e8 7d  87 e3 cd 35  51 ee f1 9e
| keyex value  ad 8d e2 ac  7b 5d 6f eb  b0 9a f7 01  ae e8 06 c8
| keyex value  2c 5b 2f 46  ce fb c5 1b  ed 5f c1 27  db 9c 58 3b
| emitting length of ISAKMP Key Exchange Payload: 100
| emitting 16 raw bytes of IDci into ISAKMP Message
| IDci  05 00 00 10  04 00 00 00  c0 a8 7b 00  ff ff ff 00
| emitting 16 raw bytes of IDcr into ISAKMP Message
| IDcr  00 00 00 10  04 00 00 00  c0 a8 01 00  ff ff ff 00
| NAT-OA: 0 tunnel: 0  
| HASH(2) computed:
|   0c f2 17 a8  b2 61 0c ec  a6 5f ae c9  30 a0 30 78
|   c9 32 26 48
| compute_proto_keymat:needed_len (after ESP enc)=32
| compute_proto_keymat:needed_len (after ESP auth)=48
| ESP KEYMAT 
|   KEYMAT computed:
|   c3 1f cb cc  ca 4f d0 dc  ee ea 60 e5  45 7d ec dd
|   92 6b e2 f1  ad 1d a2 17  1a 25 d8 11  6d 66 fb 4e
|   2c 68 d6 d9  56 6f 08 6e  87 a0 94 d7  eb 91 88 4b
|   Peer KEYMAT computed:
|   5f 32 48 bd  86 11 73 93  a8 94 d5 51  c6 46 ba cc
|   bd f2 45 6a  4b 4f d9 df  2e 0f 90 6d  31 f2 c8 90
|   b9 ce 24 ef  d3 2a 4e 95  14 b2 fd f7  f8 5d 5a 87
| route owner of "site_192.168.1.0_24-192.168.123.0_24" unrouted: NULL
| install_inbound_ipsec_sa() checking if we can route
| 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)
|    routing is easy, or has resolvable near-conflict
| checking if this is a replacement state
|   st=0x17fa68 ost=(nil) st->serialno=#6 ost->serialno=#0 
| installing outgoing SA now as refhim=0
| pfkey_lib_debug:pfkey_msg_hdr_build: 
| pfkey_lib_debug:pfkey_msg_hdr_build: on_entry &pfkey_ext=0p0xbec894e8 pfkey_ext=0p0xbec8a5d4 *pfkey_ext=0p(nil). 
| pfkey_lib_debug:pfkey_msg_hdr_build: on_exit &pfkey_ext=0p0xbec894e8 pfkey_ext=0p0xbec8a5d4 *pfkey_ext=0p0x1800a0. 
| pfkey_lib_debug:pfkey_sa_build: spi=00001005 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=0p0x1802c8 allocated 88 bytes, &(extensions[0])=0p0xbec8a5d4 
| 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=29, 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 0p0x1802d8 with parser pfkey_sa_parse. 
| pfkey_lib_debug:pfkey_sa_parse: successfully found len=3 exttype=1(security-association) spi=00001005 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 0p0x1802f0 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 0p0x180308 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 29 for Add SA tun.1005@10.29.3.225
|   02 03 00 09  00 0b 00 00  00 00 00 1d  00 00 06 09
|   00 03 00 01  00 00 10 05  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 29
| 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=29, 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 0p0xbec895e4 with parser pfkey_sa_parse. 
| pfkey_lib_debug:pfkey_sa_parse: successfully found len=3 exttype=1(security-association) spi=00001005 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 0p0xbec895fc 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 0p0xbec89614 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 0p0xbec8962c 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=66
| recorded ref=66 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:  5f 32 48 bd  86 11 73 93  a8 94 d5 51  c6 46 ba cc
| esp enckey:  bd f2 45 6a  4b 4f d9 df  2e 0f 90 6d  31 f2 c8 90
| esp authkey:  b9 ce 24 ef  d3 2a 4e 95  14 b2 fd f7  f8 5d 5a 87
| pfkey_lib_debug:pfkey_msg_hdr_build: 
| pfkey_lib_debug:pfkey_msg_hdr_build: on_entry &pfkey_ext=0p0xbec894e8 pfkey_ext=0p0xbec8a5d4 *pfkey_ext=0p(nil). 
| pfkey_lib_debug:pfkey_msg_hdr_build: on_exit &pfkey_ext=0p0xbec894e8 pfkey_ext=0p0xbec8a5d4 *pfkey_ext=0p0x1800a0. 
| pfkey_lib_debug:pfkey_sa_build: spi=84462a0a 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=0p0x180318 allocated 152 bytes, &(extensions[0])=0p0xbec8a5d4 
| 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=30, 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 0p0x180328 with parser pfkey_sa_parse. 
| pfkey_lib_debug:pfkey_sa_parse: successfully found len=3 exttype=1(security-association) spi=84462a0a 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 0p0x180340 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 0p0x180358 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 0p0x180370 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 0p0x180388 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 30 for Add SA esp.84462a0a@10.29.3.225
|   02 03 00 03  00 13 00 00  00 00 00 1e  00 00 06 09
|   00 03 00 01  84 46 2a 0a  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
|   b9 ce 24 ef  d3 2a 4e 95  14 b2 fd f7  f8 5d 5a 87
|   00 05 00 09  01 00 00 00  5f 32 48 bd  86 11 73 93
|   a8 94 d5 51  c6 46 ba cc  bd f2 45 6a  4b 4f d9 df
|   2e 0f 90 6d  31 f2 c8 90
| pfkey_get: K_SADB_ADD message 30
| 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=30, 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 0p0xbec895e4 with parser pfkey_sa_parse. 
| pfkey_lib_debug:pfkey_sa_parse: successfully found len=3 exttype=1(security-association) spi=84462a0a 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 0p0xbec895fc 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 0p0xbec89614 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 0p0xbec8962c 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.1005@10.29.3.225 (ref=66) and unk0.84462a0a@10.29.3.225 (ref=67)
| pfkey_lib_debug:pfkey_msg_hdr_build: 
| pfkey_lib_debug:pfkey_msg_hdr_build: on_entry &pfkey_ext=0p0xbec8a580 pfkey_ext=0p0xbec8a5e4 *pfkey_ext=0p(nil). 
| pfkey_lib_debug:pfkey_msg_hdr_build: on_exit &pfkey_ext=0p0xbec8a580 pfkey_ext=0p0xbec8a5e4 *pfkey_ext=0p0x1800a0. 
| pfkey_lib_debug:pfkey_sa_build: spi=00001005 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=84462a0a 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=0p0x1802f8 allocated 120 bytes, &(extensions[0])=0p0xbec8a5e4 
| 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=31, 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 0p0x180308 with parser pfkey_sa_parse. 
| pfkey_lib_debug:pfkey_sa_parse: successfully found len=3 exttype=1(security-association) spi=00001005 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 0p0x180320 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 0p0x180338 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 0p0x180340 with parser pfkey_sa_parse. 
| pfkey_lib_debug:pfkey_sa_parse: successfully found len=3 exttype=19(X-security-association) spi=84462a0a 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 0p0x180358 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 31 for group unk0.1005@10.29.3.225
|   02 0d 00 09  00 0f 00 00  00 00 00 1f  00 00 06 09
|   00 03 00 01  00 00 10 05  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 0a
|   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 31
| 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) 
| outgoing SA has refhim=66
| pfkey_lib_debug:pfkey_msg_hdr_build: 
| pfkey_lib_debug:pfkey_msg_hdr_build: on_entry &pfkey_ext=0p0xbec894e8 pfkey_ext=0p0xbec8a5d4 *pfkey_ext=0p(nil). 
| pfkey_lib_debug:pfkey_msg_hdr_build: on_exit &pfkey_ext=0p0xbec894e8 pfkey_ext=0p0xbec8a5d4 *pfkey_ext=0p0x1800a0. 
| pfkey_lib_debug:pfkey_sa_build: spi=00001006 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=0p0x1802e0 allocated 104 bytes, &(extensions[0])=0p0xbec8a5d4 
| 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=32, 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 0p0x1802f0 with parser pfkey_sa_parse. 
| pfkey_lib_debug:pfkey_sa_parse: successfully found len=3 exttype=1(security-association) spi=00001006 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 0p0x180308 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 0p0x180320 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 0p0x180338 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 32 for Add SA tun.1006@10.29.3.225
|   02 03 00 09  00 0d 00 00  00 00 00 20  00 00 06 09
|   00 03 00 01  00 00 10 06  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 42  00 01 ad 31
| pfkey_get: K_SADB_ADD message 32
| 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=32, 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 0p0xbec895e4 with parser pfkey_sa_parse. 
| pfkey_lib_debug:pfkey_sa_parse: successfully found len=3 exttype=1(security-association) spi=00001006 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 0p0xbec895fc 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 0p0xbec89614 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 0p0xbec8962c 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=68
| 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:  c3 1f cb cc  ca 4f d0 dc  ee ea 60 e5  45 7d ec dd
| esp enckey:  92 6b e2 f1  ad 1d a2 17  1a 25 d8 11  6d 66 fb 4e
| esp authkey:  2c 68 d6 d9  56 6f 08 6e  87 a0 94 d7  eb 91 88 4b
| pfkey_lib_debug:pfkey_msg_hdr_build: 
| pfkey_lib_debug:pfkey_msg_hdr_build: on_entry &pfkey_ext=0p0xbec894e8 pfkey_ext=0p0xbec8a5d4 *pfkey_ext=0p(nil). 
| pfkey_lib_debug:pfkey_msg_hdr_build: on_exit &pfkey_ext=0p0xbec894e8 pfkey_ext=0p0xbec8a5d4 *pfkey_ext=0p0x1800a0. 
| pfkey_lib_debug:pfkey_sa_build: spi=23900d3e 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=0p0x180330 allocated 168 bytes, &(extensions[0])=0p0xbec8a5d4 
| 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=33, 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 0p0x180340 with parser pfkey_sa_parse. 
| pfkey_lib_debug:pfkey_sa_parse: successfully found len=3 exttype=1(security-association) spi=23900d3e 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 0p0x180358 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 0p0x180370 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 0p0x180388 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 0p0x1803a0 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 0p0x1803c8 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 33 for Add SA esp.23900d3e@10.29.3.195
|   02 03 00 03  00 15 00 00  00 00 00 21  00 00 06 09
|   00 03 00 01  23 90 0d 3e  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
|   2c 68 d6 d9  56 6f 08 6e  87 a0 94 d7  eb 91 88 4b
|   00 05 00 09  01 00 00 00  c3 1f cb cc  ca 4f d0 dc
|   ee ea 60 e5  45 7d ec dd  92 6b e2 f1  ad 1d a2 17
|   1a 25 d8 11  6d 66 fb 4e  00 02 00 20  00 00 00 00
|   00 00 00 42  00 00 00 00
| pfkey_get: K_SADB_ADD message 33
| 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=33, 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 0p0xbec895e4 with parser pfkey_sa_parse. 
| pfkey_lib_debug:pfkey_sa_parse: successfully found len=3 exttype=1(security-association) spi=23900d3e 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 0p0xbec895fc 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 0p0xbec89614 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 0p0xbec8962c 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.1006@10.29.3.195 (raw_eroute)
| pfkey_lib_debug:pfkey_msg_hdr_build: 
| pfkey_lib_debug:pfkey_msg_hdr_build: on_entry &pfkey_ext=0p0xbec8a3a4 pfkey_ext=0p0xbec8a488 *pfkey_ext=0p(nil). 
| pfkey_lib_debug:pfkey_msg_hdr_build: on_exit &pfkey_ext=0p0xbec8a3a4 pfkey_ext=0p0xbec8a488 *pfkey_ext=0p0x1800a0. 
| pfkey_lib_debug:pfkey_sa_build: spi=00001006 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=0p0x180348 allocated 184 bytes, &(extensions[0])=0p0xbec8a488 
| 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=34, 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 0p0x180358 with parser pfkey_sa_parse. 
| pfkey_lib_debug:pfkey_sa_parse: successfully found len=3 exttype=1(security-association) spi=00001006 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 0p0x180370 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 0p0x180388 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 0p0x1803a0 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 0p0x1803b8 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 0p0x1803d0 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 0p0x1803e8 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 34 for flow tun.1006@10.29.3.195
|   02 0e 00 09  00 17 00 00  00 00 00 22  00 00 06 09
|   00 03 00 01  00 00 10 06  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 34
| 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.1006@10.29.3.195 (ref=68) and unk0.23900d3e@10.29.3.195 (ref=69)
| pfkey_lib_debug:pfkey_msg_hdr_build: 
| pfkey_lib_debug:pfkey_msg_hdr_build: on_entry &pfkey_ext=0p0xbec8a580 pfkey_ext=0p0xbec8a5e4 *pfkey_ext=0p(nil). 
| pfkey_lib_debug:pfkey_msg_hdr_build: on_exit &pfkey_ext=0p0xbec8a580 pfkey_ext=0p0xbec8a5e4 *pfkey_ext=0p0x1800a0. 
| pfkey_lib_debug:pfkey_sa_build: spi=00001006 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=23900d3e 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=0p0x1802f8 allocated 120 bytes, &(extensions[0])=0p0xbec8a5e4 
| 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=35, 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 0p0x180308 with parser pfkey_sa_parse. 
| pfkey_lib_debug:pfkey_sa_parse: successfully found len=3 exttype=1(security-association) spi=00001006 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 0p0x180320 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 0p0x180338 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 0p0x180340 with parser pfkey_sa_parse. 
| pfkey_lib_debug:pfkey_sa_parse: successfully found len=3 exttype=19(X-security-association) spi=23900d3e 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 0p0x180358 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 35 for group unk0.1006@10.29.3.195
|   02 0d 00 09  00 0f 00 00  00 00 00 23  00 00 06 09
|   00 03 00 01  00 00 10 06  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 3e
|   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 35
| 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) 
| encrypting:
|   01 00 00 18  0c f2 17 a8  b2 61 0c ec  a6 5f ae c9
|   30 a0 30 78  c9 32 26 48  0a 00 00 3c  00 00 00 01
|   00 00 00 01  00 00 00 30  00 03 04 01  23 90 0d 3e
|   00 00 00 24  00 0c 00 00  80 03 00 01  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  45 39 67 90  53 dd 86 fc
|   7c d0 e0 3d  fe e1 46 3c  05 00 00 64  a5 a4 4d 1d
|   3a c5 6b e4  3c ae 03 0d  1c 8a 37 88  1d a8 cf cf
|   26 9a 58 d4  a0 86 37 cc  2f 16 30 91  2b 24 02 85
|   39 34 91 c9  f6 0b 04 08  5c f2 50 72  d1 7e 3c 9b
|   50 7c e8 7d  87 e3 cd 35  51 ee f1 9e  ad 8d e2 ac
|   7b 5d 6f eb  b0 9a f7 01  ae e8 06 c8  2c 5b 2f 46
|   ce fb c5 1b  ed 5f c1 27  db 9c 58 3b  05 00 00 10
|   04 00 00 00  c0 a8 7b 00  ff ff ff 00  00 00 00 10
|   04 00 00 00  c0 a8 01 00  ff ff ff 00
| IV:
|   76 f8 b6 d2  cb b1 7f c7  66 4a 9b b6  c9 11 fc e7
| unpadded size is: 236
| emitting 4 zero bytes of encryption padding into ISAKMP Message
| encrypting 240 using OAKLEY_AES_CBC
| next IV:  07 99 b0 15  6e f7 b1 61  f8 21 24 ef  d2 e7 f1 37
| emitting length of ISAKMP Message: 268
| finished processing quick inI1
| complete state transition with STF_OK
"site_192.168.1.0_24-192.168.123.0_24" #6: transition from state STATE_QUICK_R0 to state STATE_QUICK_R1
| deleting event for #6
| sending reply packet to 10.29.3.225:500 (from port 500)
| sending 268 bytes for STATE_QUICK_R0 through eth2:500 to 10.29.3.225:500 (using #6)
|   34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
|   08 10 20 01  35 61 c8 bc  00 00 01 0c  d9 7c 25 b5
|   90 d7 11 e4  4d 2e 9d 9e  f6 9f 1d 86  92 4c ca 6b
|   71 e9 23 8f  58 af 4b 57  58 6d 19 c2  b5 2d 1e d4
|   ed 1a 33 01  a0 6f 84 43  4b fc 3e a6  19 b4 73 75
|   d7 ab 6d f2  9b 73 14 b0  b1 83 3b ba  0d fa 4c 9b
|   7c cb ae d1  8c fb 57 bf  b5 05 27 e4  48 5a 95 88
|   18 5c 85 c3  64 c7 bb d7  44 fc da 88  82 62 71 4f
|   65 63 59 9b  22 8f 79 bb  d9 a7 a9 f5  12 d6 24 ff
|   4e ab 8f df  4c f5 1f 19  45 f2 87 c4  28 3f bb ff
|   25 91 22 a9  a4 be 2a 04  e0 a0 27 39  1e 03 43 ab
|   12 0b 56 c6  b4 a2 3e f3  a1 66 b2 da  32 3e 35 c9
|   f7 12 a4 d7  eb 62 32 8a  47 63 af 04  91 66 a8 a6
|   f5 c0 8c 58  a2 3b 50 48  03 ad 97 1f  ef cc 93 66
|   e6 36 b6 b1  b1 f4 55 ba  bd e8 c6 0c  06 16 b3 bc
|   74 32 e6 b7  3f 0f 27 58  5d a5 e6 84  07 99 b0 15
|   6e f7 b1 61  f8 21 24 ef  d2 e7 f1 37
| inserting event EVENT_RETRANSMIT, timeout in 10 seconds for #6
| event added at head of queue
"site_192.168.1.0_24-192.168.123.0_24" #6: STATE_QUICK_R1: sent QR1, inbound IPsec SA installed, expecting QI2
| 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 #6
| next event EVENT_RETRANSMIT in 10 seconds for #6
|  
| *received 60 bytes from 10.29.3.225:500 on eth2 (port=500)
|   34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
|   08 10 20 01  35 61 c8 bc  00 00 00 3c  ca 40 b1 6d
|   71 ca a0 86  7c c7 39 8d  6f 14 de 4f  7f 21 14 b2
|   c6 7c eb be  b5 2d 0b d6  bf 34 bb d2
| **parse ISAKMP Message:
|    initiator cookie:
|   34 3b dd c9  48 17 1c b2
|    responder cookie:
|   d0 7f be a1  53 fe 72 99
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_QUICK
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  35 61 c8 bc
|    length: 60
|  processing version=1.0 packet with exchange type=ISAKMP_XCHG_QUICK (32)
| ICOOKIE:  34 3b dd c9  48 17 1c b2
| RCOOKIE:  d0 7f be a1  53 fe 72 99
| state hash entry 28
| v1 peer and cookies match on #6, provided msgid 3561c8bc vs 3561c8bc
| v1 state object #6 found, in STATE_QUICK_R1
| processing connection site_192.168.1.0_24-192.168.123.0_24
| received encrypted packet from 10.29.3.225:500
| decrypting 32 bytes using algorithm OAKLEY_AES_CBC
| decrypted:
|   00 00 00 18  86 f6 d2 15  7c f1 63 ca  61 36 be 46
|   16 04 48 9b  8b 1e 75 2d  00 00 00 00  00 00 00 00
| next IV:  7f 21 14 b2  c6 7c eb be  b5 2d 0b d6  bf 34 bb d2
| got payload 0x100(ISAKMP_NEXT_HASH) needed: 0x100 opt: 0x0
| ***parse ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_NONE
|    length: 24
| removing 8 bytes of padding
| HASH(3) computed:  86 f6 d2 15  7c f1 63 ca  61 36 be 46  16 04 48 9b
| HASH(3) computed:  8b 1e 75 2d
| install_ipsec_sa() for #6: outbound only
| 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)
| sr for #6: 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: 6
| eroute_connection add eroute 192.168.1.0/24:0 --0-> 192.168.123.0/24:0 => tun.1005@10.29.3.225 (raw_eroute)
| pfkey_lib_debug:pfkey_msg_hdr_build: 
| pfkey_lib_debug:pfkey_msg_hdr_build: on_entry &pfkey_ext=0p0xbec8d060 pfkey_ext=0p0xbec8d144 *pfkey_ext=0p(nil). 
| pfkey_lib_debug:pfkey_msg_hdr_build: on_exit &pfkey_ext=0p0xbec8d060 pfkey_ext=0p0xbec8d144 *pfkey_ext=0p0x17f888. 
| pfkey_lib_debug:pfkey_sa_build: spi=00001005 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=0p0x1802a8 allocated 184 bytes, &(extensions[0])=0p0xbec8d144 
| 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=36, 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 0p0x1802b8 with parser pfkey_sa_parse. 
| pfkey_lib_debug:pfkey_sa_parse: successfully found len=3 exttype=1(security-association) spi=00001005 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 0p0x1802d0 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 0p0x1802e8 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 0p0x180300 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 0p0x180318 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 0p0x180330 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 0p0x180348 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 36 for flow tun.1005@10.29.3.225
|   02 0e 00 09  00 17 00 00  00 00 00 24  00 00 06 09
|   00 03 00 01  00 00 10 05  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 36
| 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+IKEv2ALLOW' PLUTO_XAUTH_USERNAME=''  /ramfs/ipsec/bin/_updown
| popen(): cmd is 713 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+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+IKEv2ALLOW' PLUTO_XAUTH_USERNAME=''  /ramfs/ipsec/bin/_updown
| popen(): cmd is 718 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+IKEv2ALLOW' PLUTO_XAUTH_USERNAME=''  /ramfs/ipsec/bin/_updown:
| 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+IKEv2ALLOW' PLUTO_XAUTH_USERNAME=''  /ramfs/ipsec/bin/_updown
| popen(): cmd is 716 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+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 #6 (was #0) (newest_ipsec_sa=#0)
| inI2: instance site_192.168.1.0_24-192.168.123.0_24[0], setting newest_ipsec_sa to #6 (was #0) (spd.eroute=#6)
| ICOOKIE:  34 3b dd c9  48 17 1c b2
| RCOOKIE:  d0 7f be a1  53 fe 72 99
| state hash entry 28
| v1 peer and cookies match on #6, provided msgid 00000000 vs 3561c8bc
| v1 peer and cookies match on #5, provided msgid 00000000 vs 00000000
| v1 state object #5 found, in STATE_MAIN_R3
"site_192.168.1.0_24-192.168.123.0_24" #6: Dead Peer Detection (RFC 3706): enabled
| state: 6 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 #6
| event added at head of queue
| state: 5 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" #6: transition from state STATE_QUICK_R1 to state STATE_QUICK_R2
| deleting event for #6
| inserting event EVENT_SA_EXPIRE, timeout in 86400 seconds for #6
| event added after event EVENT_SA_EXPIRE for #5
"site_192.168.1.0_24-192.168.123.0_24" #6: STATE_QUICK_R2: IPsec SA established tunnel mode {ESP=>0x84462a0a <0x23900d3e 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 0 messages from cryptographic helpers 
| next event EVENT_DPD in 10 seconds for #6
| next event EVENT_DPD in 10 seconds for #6
|  
| *received 92 bytes from 10.29.3.225:500 on eth2 (port=500)
|   34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
|   08 10 05 01  f1 08 63 e0  00 00 00 5c  56 e0 3a 06
|   22 5a d9 bb  dd 7f 5b 09  70 fb 1e 6c  6a e0 19 ea
|   ab ad 8e b3  92 76 a6 71  52 67 b1 d6  d3 fd d4 f3
|   42 d9 03 08  f5 ad 35 81  82 71 18 c2  5c d6 99 b7
|   b7 26 d8 63  58 59 e9 e9  7c e5 e1 8a
| **parse ISAKMP Message:
|    initiator cookie:
|   34 3b dd c9  48 17 1c b2
|    responder cookie:
|   d0 7f be a1  53 fe 72 99
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  f1 08 63 e0
|    length: 92
|  processing version=1.0 packet with exchange type=ISAKMP_XCHG_INFO (5)
| ICOOKIE:  34 3b dd c9  48 17 1c b2
| RCOOKIE:  d0 7f be a1  53 fe 72 99
| state hash entry 28
| peer and cookies match on #6, provided msgid 00000000 vs 3561c8bc/00000000
| peer and cookies match on #5, provided msgid 00000000 vs 00000000/00000000
| p15 state object #5 found, in STATE_MAIN_R3
| processing connection site_192.168.1.0_24-192.168.123.0_24
| last Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| current Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| computed Phase 2 IV:
|   2e b9 24 1b  a5 3f c8 11  30 59 e4 44  eb 26 78 5c
|   c5 22 8f a5
| received encrypted packet from 10.29.3.225:500
| decrypting 64 bytes using algorithm OAKLEY_AES_CBC
| decrypted:
|   0b 00 00 18  89 e1 ae 91  db 97 09 46  59 6d 20 9d
|   fa 76 fd c7  f7 55 dc a4  00 00 00 20  00 00 00 01
|   01 10 8d 28  34 3b dd c9  48 17 1c b2  d0 7f be a1
|   53 fe 72 99  00 00 23 3a  00 00 00 00  00 00 00 00
| next IV:  5c d6 99 b7  b7 26 d8 63  58 59 e9 e9  7c e5 e1 8a
| 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:  34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
| info:  00 00 23 3a
| processing informational R_U_THERE (36136)
| **emit ISAKMP Message:
|    initiator cookie:
|   34 3b dd c9  48 17 1c b2
|    responder cookie:
|   d0 7f be a1  53 fe 72 99
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  de de de 15
| ***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  34 3b dd c9  48 17 1c b2
| emitting 8 raw bytes of notify rcookie into ISAKMP Notification Payload
| notify rcookie  d0 7f be a1  53 fe 72 99
| emitting 4 raw bytes of notify data into ISAKMP Notification Payload
| notify data  00 00 23 3a
| emitting length of ISAKMP Notification Payload: 32
| HASH computed:
|   a3 3a 9a 1b  5c d3 8a 70  6c 1d be c7  9d 78 a5 8f
|   8f 99 6b a1
| last Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| current Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| computed Phase 2 IV:
|   b2 3f ce 3b  8e 26 13 8f  92 62 65 73  c9 d0 db a6
|   f7 7f f9 78
| encrypting:
|   0b 00 00 18  a3 3a 9a 1b  5c d3 8a 70  6c 1d be c7
|   9d 78 a5 8f  8f 99 6b a1  00 00 00 20  00 00 00 01
|   01 10 8d 29  34 3b dd c9  48 17 1c b2  d0 7f be a1
|   53 fe 72 99  00 00 23 3a
| IV:
|   b2 3f ce 3b  8e 26 13 8f  92 62 65 73  c9 d0 db a6
|   f7 7f f9 78
| unpadded size is: 56
| emitting 8 zero bytes of encryption padding into ISAKMP Message
| encrypting 64 using OAKLEY_AES_CBC
| next IV:  63 26 14 31  30 1d a1 6b  62 ca c3 0e  c4 72 22 1d
| emitting length of ISAKMP Message: 92
| sending 92 bytes for ISAKMP notify through eth2:500 to 10.29.3.225:500 (using #5)
|   34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
|   08 10 05 01  de de de 15  00 00 00 5c  b6 27 f5 df
|   97 14 14 e6  43 2b e2 9a  8d 9b e2 0d  b6 9b 4e b1
|   dc 76 88 23  c4 37 33 4b  2d ae c4 cb  1f ba 71 52
|   d1 c8 a8 65  ad ab df 64  56 50 89 21  63 26 14 31
|   30 1d a1 6b  62 ca c3 0e  c4 72 22 1d
| complete state transition with STF_IGNORE
| * processed 0 messages from cryptographic helpers 
| next event EVENT_DPD in 0 seconds for #6
| *time to handle event
| handling event EVENT_DPD
| event after this is EVENT_PENDING_DDNS in 28 seconds
| processing connection site_192.168.1.0_24-192.168.123.0_24
| inserting event EVENT_DPD, timeout in 10 seconds for #6
| event added at head of queue
| next event EVENT_DPD in 10 seconds for #6
|  
| *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_DPD in 7 seconds for #6
| next event EVENT_DPD in 7 seconds for #6
|  
| next event EVENT_DPD in 0 seconds for #6
| *time to handle event
| handling event EVENT_DPD
| event after this is EVENT_PENDING_DDNS in 18 seconds
| processing connection site_192.168.1.0_24-192.168.123.0_24
| inserting event EVENT_DPD, timeout in 10 seconds for #6
| event added at head of queue
| state: 5 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 #5
| event added after event EVENT_DPD for #6
| **emit ISAKMP Message:
|    initiator cookie:
|   34 3b dd c9  48 17 1c b2
|    responder cookie:
|   d0 7f be a1  53 fe 72 99
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  6f cb ff 5e
| ***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  34 3b dd c9  48 17 1c b2
| emitting 8 raw bytes of notify rcookie into ISAKMP Notification Payload
| notify rcookie  d0 7f be a1  53 fe 72 99
| emitting 4 raw bytes of notify data into ISAKMP Notification Payload
| notify data  00 00 08 2a
| emitting length of ISAKMP Notification Payload: 32
| HASH computed:
|   94 70 bd 52  79 bc 86 57  6f 15 0f 77  0a 69 d3 e4
|   a5 a3 0c cd
| last Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| current Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| computed Phase 2 IV:
|   1d b0 9b 06  2c 28 53 67  1d 03 4c 86  b0 75 8f 2a
|   13 ad 2a b2
| encrypting:
|   0b 00 00 18  94 70 bd 52  79 bc 86 57  6f 15 0f 77
|   0a 69 d3 e4  a5 a3 0c cd  00 00 00 20  00 00 00 01
|   01 10 8d 28  34 3b dd c9  48 17 1c b2  d0 7f be a1
|   53 fe 72 99  00 00 08 2a
| IV:
|   1d b0 9b 06  2c 28 53 67  1d 03 4c 86  b0 75 8f 2a
|   13 ad 2a b2
| unpadded size is: 56
| emitting 8 zero bytes of encryption padding into ISAKMP Message
| encrypting 64 using OAKLEY_AES_CBC
| next IV:  1b 45 21 40  18 68 c9 7c  a7 13 d1 61  54 65 d5 1b
| emitting length of ISAKMP Message: 92
| sending 92 bytes for ISAKMP notify through eth2:500 to 10.29.3.225:500 (using #5)
|   34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
|   08 10 05 01  6f cb ff 5e  00 00 00 5c  b2 d0 fd e0
|   b6 30 27 cc  9c 58 fa 3e  53 85 c7 06  f4 eb 0d b6
|   c1 e3 e2 43  80 0e 77 7d  8e 8f 8b 94  de be 8d f6
|   79 5d cb 82  b2 47 73 65  24 d9 78 b9  1b 45 21 40
|   18 68 c9 7c  a7 13 d1 61  54 65 d5 1b
| next event EVENT_DPD in 10 seconds for #6
|  
| *received 92 bytes from 10.29.3.225:500 on eth2 (port=500)
|   34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
|   08 10 05 01  df dd 3d 6b  00 00 00 5c  d8 60 4f 12
|   e4 42 3a da  5c 0c e5 a0  1e c7 e6 f8  24 77 85 7c
|   a0 d1 4a b7  4e 2b f4 b3  61 63 06 b3  24 9e 7a 16
|   97 68 79 fa  13 e8 8d 1e  fb ec 85 dc  0f d0 6f ef
|   15 8a 4b 8d  c7 2c 09 25  86 92 14 61
| **parse ISAKMP Message:
|    initiator cookie:
|   34 3b dd c9  48 17 1c b2
|    responder cookie:
|   d0 7f be a1  53 fe 72 99
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  df dd 3d 6b
|    length: 92
|  processing version=1.0 packet with exchange type=ISAKMP_XCHG_INFO (5)
| ICOOKIE:  34 3b dd c9  48 17 1c b2
| RCOOKIE:  d0 7f be a1  53 fe 72 99
| state hash entry 28
| peer and cookies match on #6, provided msgid 00000000 vs 3561c8bc/00000000
| peer and cookies match on #5, provided msgid 00000000 vs 00000000/00000000
| p15 state object #5 found, in STATE_MAIN_R3
| processing connection site_192.168.1.0_24-192.168.123.0_24
| last Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| current Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| computed Phase 2 IV:
|   32 8b 19 fb  55 27 c4 98  14 83 62 eb  69 07 f3 80
|   36 ac e5 9e
| received encrypted packet from 10.29.3.225:500
| decrypting 64 bytes using algorithm OAKLEY_AES_CBC
| decrypted:
|   0b 00 00 18  f9 25 1b cb  7a 2c 56 0f  c8 41 52 c8
|   c6 da c7 c3  2b 57 72 40  00 00 00 20  00 00 00 01
|   01 10 8d 29  34 3b dd c9  48 17 1c b2  d0 7f be a1
|   53 fe 72 99  00 00 08 2a  00 00 00 00  00 00 00 00
| next IV:  0f d0 6f ef  15 8a 4b 8d  c7 2c 09 25  86 92 14 61
| 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:  34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
| info:  00 00 08 2a
| processing informational R_U_THERE_ACK (36137)
| state: 5 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 #6
| next event EVENT_DPD in 10 seconds for #6
|  
| next event EVENT_DPD in 0 seconds for #6
| *time to handle event
| handling event EVENT_DPD
| event after this is EVENT_PENDING_DDNS in 8 seconds
| processing connection site_192.168.1.0_24-192.168.123.0_24
| inserting event EVENT_DPD, timeout in 10 seconds for #6
| event added after event EVENT_PENDING_DDNS
| state: 5 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 #5
| event added after event EVENT_DPD for #6
| **emit ISAKMP Message:
|    initiator cookie:
|   34 3b dd c9  48 17 1c b2
|    responder cookie:
|   d0 7f be a1  53 fe 72 99
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  e4 2f 49 d3
| ***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  34 3b dd c9  48 17 1c b2
| emitting 8 raw bytes of notify rcookie into ISAKMP Notification Payload
| notify rcookie  d0 7f be a1  53 fe 72 99
| emitting 4 raw bytes of notify data into ISAKMP Notification Payload
| notify data  00 00 08 2b
| emitting length of ISAKMP Notification Payload: 32
| HASH computed:
|   d4 fa ac f9  f7 5c ac 2a  e3 d4 f8 41  b0 66 62 d5
|   06 0d 3c 45
| last Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| current Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| computed Phase 2 IV:
|   9a 92 82 67  d5 c0 57 b7  7b b8 e1 37  5a c5 a9 3a
|   73 a3 17 28
| encrypting:
|   0b 00 00 18  d4 fa ac f9  f7 5c ac 2a  e3 d4 f8 41
|   b0 66 62 d5  06 0d 3c 45  00 00 00 20  00 00 00 01
|   01 10 8d 28  34 3b dd c9  48 17 1c b2  d0 7f be a1
|   53 fe 72 99  00 00 08 2b
| IV:
|   9a 92 82 67  d5 c0 57 b7  7b b8 e1 37  5a c5 a9 3a
|   73 a3 17 28
| unpadded size is: 56
| emitting 8 zero bytes of encryption padding into ISAKMP Message
| encrypting 64 using OAKLEY_AES_CBC
| next IV:  8a b0 d0 ec  9a 13 ab 06  8c 95 fd 26  e7 4a cd a6
| emitting length of ISAKMP Message: 92
| sending 92 bytes for ISAKMP notify through eth2:500 to 10.29.3.225:500 (using #5)
|   34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
|   08 10 05 01  e4 2f 49 d3  00 00 00 5c  5c f2 4e 18
|   4a 34 7c 65  7a 6e 89 1b  1b ba 2d 4f  14 97 ed ad
|   8e 59 82 e5  dc 44 8e 20  be f9 6e d0  6b a0 c2 1f
|   33 0c 32 56  ca e2 03 04  bd 51 80 bf  8a b0 d0 ec
|   9a 13 ab 06  8c 95 fd 26  e7 4a cd a6
| next event EVENT_PENDING_DDNS in 8 seconds
|  
| *received 92 bytes from 10.29.3.225:500 on eth2 (port=500)
|   34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
|   08 10 05 01  36 66 82 3d  00 00 00 5c  cf 17 cf 93
|   ca 6a 09 62  ba 0d de 8f  ac 38 75 c7  6c f9 01 23
|   0d 71 2d e0  5b b9 d5 71  67 a4 80 5e  37 e7 32 70
|   fd 05 a4 f8  a0 58 8e ac  fd 6a 19 00  ff 5a 12 26
|   eb 64 8a 8e  9a dd 83 ce  8e 96 fc ae
| **parse ISAKMP Message:
|    initiator cookie:
|   34 3b dd c9  48 17 1c b2
|    responder cookie:
|   d0 7f be a1  53 fe 72 99
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  36 66 82 3d
|    length: 92
|  processing version=1.0 packet with exchange type=ISAKMP_XCHG_INFO (5)
| ICOOKIE:  34 3b dd c9  48 17 1c b2
| RCOOKIE:  d0 7f be a1  53 fe 72 99
| state hash entry 28
| peer and cookies match on #6, provided msgid 00000000 vs 3561c8bc/00000000
| peer and cookies match on #5, provided msgid 00000000 vs 00000000/00000000
| p15 state object #5 found, in STATE_MAIN_R3
| processing connection site_192.168.1.0_24-192.168.123.0_24
| last Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| current Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| computed Phase 2 IV:
|   fc 41 f8 35  af 00 bc 26  b4 1d 63 78  8d 7d 93 54
|   4d 59 35 0c
| received encrypted packet from 10.29.3.225:500
| decrypting 64 bytes using algorithm OAKLEY_AES_CBC
| decrypted:
|   0b 00 00 18  fe 9c 81 ab  db 77 90 e6  52 2d 33 66
|   32 2f 10 bc  4d cf ff 21  00 00 00 20  00 00 00 01
|   01 10 8d 28  34 3b dd c9  48 17 1c b2  d0 7f be a1
|   53 fe 72 99  00 00 23 3b  00 00 00 00  00 00 00 00
| next IV:  ff 5a 12 26  eb 64 8a 8e  9a dd 83 ce  8e 96 fc ae
| 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:  34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
| info:  00 00 23 3b
| processing informational R_U_THERE (36136)
| **emit ISAKMP Message:
|    initiator cookie:
|   34 3b dd c9  48 17 1c b2
|    responder cookie:
|   d0 7f be a1  53 fe 72 99
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  06 76 2a 1c
| ***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  34 3b dd c9  48 17 1c b2
| emitting 8 raw bytes of notify rcookie into ISAKMP Notification Payload
| notify rcookie  d0 7f be a1  53 fe 72 99
| emitting 4 raw bytes of notify data into ISAKMP Notification Payload
| notify data  00 00 23 3b
| emitting length of ISAKMP Notification Payload: 32
| HASH computed:
|   15 a8 55 cc  a5 6e cc d1  9e 27 3b 64  0c 4b 79 35
|   87 96 76 de
| last Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| current Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| computed Phase 2 IV:
|   9e e8 a4 44  da 70 c6 36  1d 7e 47 7f  79 99 67 a1
|   a2 3d 94 d3
| encrypting:
|   0b 00 00 18  15 a8 55 cc  a5 6e cc d1  9e 27 3b 64
|   0c 4b 79 35  87 96 76 de  00 00 00 20  00 00 00 01
|   01 10 8d 29  34 3b dd c9  48 17 1c b2  d0 7f be a1
|   53 fe 72 99  00 00 23 3b
| IV:
|   9e e8 a4 44  da 70 c6 36  1d 7e 47 7f  79 99 67 a1
|   a2 3d 94 d3
| unpadded size is: 56
| emitting 8 zero bytes of encryption padding into ISAKMP Message
| encrypting 64 using OAKLEY_AES_CBC
| next IV:  bc fd 33 fc  84 46 28 3e  f6 6f c1 d3  95 f3 29 14
| emitting length of ISAKMP Message: 92
| sending 92 bytes for ISAKMP notify through eth2:500 to 10.29.3.225:500 (using #5)
|   34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
|   08 10 05 01  06 76 2a 1c  00 00 00 5c  28 ac 79 da
|   e4 8a 47 5a  20 38 84 ae  94 98 67 83  21 0d cb 7c
|   5a db 02 27  41 e5 99 e5  f7 ec dc 22  83 8d 35 86
|   c5 1b 6f bc  95 b7 d3 aa  86 e4 42 35  bc fd 33 fc
|   84 46 28 3e  f6 6f c1 d3  95 f3 29 14
| state: 5 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 8 seconds
| next event EVENT_PENDING_DDNS in 8 seconds
|  
| *received 92 bytes from 10.29.3.225:500 on eth2 (port=500)
|   34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
|   08 10 05 01  f9 8e 81 d0  00 00 00 5c  41 50 d5 95
|   e1 87 b2 bb  7f 65 d0 51  8c 64 38 d3  ab 3e ad 51
|   f5 09 cd f0  43 6d 5a cc  ac 37 7f 27  e8 49 49 c2
|   98 f1 19 aa  1d 9a 29 b9  f0 24 77 3c  2b 1c 8c ba
|   5b 1d 49 ed  a6 0f 25 c3  14 74 0e bb
| **parse ISAKMP Message:
|    initiator cookie:
|   34 3b dd c9  48 17 1c b2
|    responder cookie:
|   d0 7f be a1  53 fe 72 99
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  f9 8e 81 d0
|    length: 92
|  processing version=1.0 packet with exchange type=ISAKMP_XCHG_INFO (5)
| ICOOKIE:  34 3b dd c9  48 17 1c b2
| RCOOKIE:  d0 7f be a1  53 fe 72 99
| state hash entry 28
| peer and cookies match on #6, provided msgid 00000000 vs 3561c8bc/00000000
| peer and cookies match on #5, provided msgid 00000000 vs 00000000/00000000
| p15 state object #5 found, in STATE_MAIN_R3
| processing connection site_192.168.1.0_24-192.168.123.0_24
| last Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| current Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| computed Phase 2 IV:
|   34 4b f3 98  0b 47 46 7f  af 99 26 ba  38 b1 71 d8
|   ef 70 d2 68
| received encrypted packet from 10.29.3.225:500
| decrypting 64 bytes using algorithm OAKLEY_AES_CBC
| decrypted:
|   0b 00 00 18  d1 fc 35 c5  02 31 be bb  ec 34 b2 bc
|   98 1f 25 db  6d 17 c6 73  00 00 00 20  00 00 00 01
|   01 10 8d 29  34 3b dd c9  48 17 1c b2  d0 7f be a1
|   53 fe 72 99  00 00 08 2b  00 00 00 00  00 00 00 00
| next IV:  2b 1c 8c ba  5b 1d 49 ed  a6 0f 25 c3  14 74 0e bb
| 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:  34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
| info:  00 00 08 2b
| 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 8 seconds
| next event EVENT_PENDING_DDNS in 8 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 2 seconds
| inserting event EVENT_PENDING_DDNS, timeout in 60 seconds
| event added after event EVENT_DPD for #6
| next event EVENT_DPD in 2 seconds for #6
|  
| *received 92 bytes from 10.29.3.225:500 on eth2 (port=500)
|   34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
|   08 10 05 01  38 1e 38 a1  00 00 00 5c  08 d2 42 d1
|   69 80 c8 2e  c0 6c 8e 29  1c 95 5d 6b  d1 4c e4 76
|   c0 39 87 f0  c4 a9 5e 56  d6 a6 ff 82  81 fd f2 ce
|   64 90 d1 06  d4 fc 2a a4  e0 da 78 85  e6 5b 66 4b
|   40 1c 6c e7  20 81 33 16  1f f2 c1 40
| **parse ISAKMP Message:
|    initiator cookie:
|   34 3b dd c9  48 17 1c b2
|    responder cookie:
|   d0 7f be a1  53 fe 72 99
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  38 1e 38 a1
|    length: 92
|  processing version=1.0 packet with exchange type=ISAKMP_XCHG_INFO (5)
| ICOOKIE:  34 3b dd c9  48 17 1c b2
| RCOOKIE:  d0 7f be a1  53 fe 72 99
| state hash entry 28
| peer and cookies match on #6, provided msgid 00000000 vs 3561c8bc/00000000
| peer and cookies match on #5, provided msgid 00000000 vs 00000000/00000000
| p15 state object #5 found, in STATE_MAIN_R3
| processing connection site_192.168.1.0_24-192.168.123.0_24
| last Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| current Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| computed Phase 2 IV:
|   92 ee 52 8d  85 21 6a e1  b5 bc 3d 85  77 d8 64 f8
|   c1 05 bb b4
| received encrypted packet from 10.29.3.225:500
| decrypting 64 bytes using algorithm OAKLEY_AES_CBC
| decrypted:
|   0b 00 00 18  9c a5 50 67  1c 03 d5 eb  54 57 5b 53
|   59 67 76 c3  62 f3 37 c2  00 00 00 20  00 00 00 01
|   01 10 8d 28  34 3b dd c9  48 17 1c b2  d0 7f be a1
|   53 fe 72 99  00 00 23 3c  00 00 00 00  00 00 00 00
| next IV:  e6 5b 66 4b  40 1c 6c e7  20 81 33 16  1f f2 c1 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:  34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
| info:  00 00 23 3c
| processing informational R_U_THERE (36136)
| **emit ISAKMP Message:
|    initiator cookie:
|   34 3b dd c9  48 17 1c b2
|    responder cookie:
|   d0 7f be a1  53 fe 72 99
|    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 bb 94 da
| ***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  34 3b dd c9  48 17 1c b2
| emitting 8 raw bytes of notify rcookie into ISAKMP Notification Payload
| notify rcookie  d0 7f be a1  53 fe 72 99
| emitting 4 raw bytes of notify data into ISAKMP Notification Payload
| notify data  00 00 23 3c
| emitting length of ISAKMP Notification Payload: 32
| HASH computed:
|   61 bd a9 cf  8b 37 25 06  01 d0 3b 5d  d1 94 08 8d
|   bd 94 21 6e
| last Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| current Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| computed Phase 2 IV:
|   ae 53 ee 66  ad 9b 26 f1  e3 63 62 f6  1e 93 5a 99
|   a3 c5 bb e2
| encrypting:
|   0b 00 00 18  61 bd a9 cf  8b 37 25 06  01 d0 3b 5d
|   d1 94 08 8d  bd 94 21 6e  00 00 00 20  00 00 00 01
|   01 10 8d 29  34 3b dd c9  48 17 1c b2  d0 7f be a1
|   53 fe 72 99  00 00 23 3c
| IV:
|   ae 53 ee 66  ad 9b 26 f1  e3 63 62 f6  1e 93 5a 99
|   a3 c5 bb e2
| unpadded size is: 56
| emitting 8 zero bytes of encryption padding into ISAKMP Message
| encrypting 64 using OAKLEY_AES_CBC
| next IV:  31 63 bb 00  be 6b 37 7b  b4 ab 6e 67  6f 23 1c e4
| emitting length of ISAKMP Message: 92
| sending 92 bytes for ISAKMP notify through eth2:500 to 10.29.3.225:500 (using #5)
|   34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
|   08 10 05 01  6a bb 94 da  00 00 00 5c  d7 54 31 0b
|   6c 9f af 25  36 22 79 46  c7 21 5c b2  a9 d7 eb d9
|   d1 af 63 ca  95 f3 34 b6  47 a0 bd d8  9d 0a cc 83
|   60 9e c6 05  13 2c c6 ef  7f 68 c8 cb  31 63 bb 00
|   be 6b 37 7b  b4 ab 6e 67  6f 23 1c e4
| complete state transition with STF_IGNORE
| * processed 0 messages from cryptographic helpers 
| next event EVENT_DPD in 0 seconds for #6
| *time to handle event
| handling event EVENT_DPD
| event after this is EVENT_PENDING_DDNS in 58 seconds
| processing connection site_192.168.1.0_24-192.168.123.0_24
| inserting event EVENT_DPD, timeout in 10 seconds for #6
| event added at head of queue
| next event EVENT_DPD in 10 seconds for #6
|  
| next event EVENT_DPD in 0 seconds for #6
| *time to handle event
| handling event EVENT_DPD
| event after this is EVENT_PENDING_DDNS in 48 seconds
| processing connection site_192.168.1.0_24-192.168.123.0_24
| inserting event EVENT_DPD, timeout in 10 seconds for #6
| event added at head of queue
| state: 5 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 #5
| event added after event EVENT_DPD for #6
| **emit ISAKMP Message:
|    initiator cookie:
|   34 3b dd c9  48 17 1c b2
|    responder cookie:
|   d0 7f be a1  53 fe 72 99
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  ff b1 57 84
| ***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  34 3b dd c9  48 17 1c b2
| emitting 8 raw bytes of notify rcookie into ISAKMP Notification Payload
| notify rcookie  d0 7f be a1  53 fe 72 99
| emitting 4 raw bytes of notify data into ISAKMP Notification Payload
| notify data  00 00 08 2c
| emitting length of ISAKMP Notification Payload: 32
| HASH computed:
|   d6 c0 ee 84  33 0d 8b 42  70 aa 90 04  6e 16 3c 54
|   89 74 d5 cb
| last Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| current Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| computed Phase 2 IV:
|   90 f5 05 64  d9 b8 2f 9d  d5 3e 2c 9c  af 0d 67 31
|   16 f5 0c b5
| encrypting:
|   0b 00 00 18  d6 c0 ee 84  33 0d 8b 42  70 aa 90 04
|   6e 16 3c 54  89 74 d5 cb  00 00 00 20  00 00 00 01
|   01 10 8d 28  34 3b dd c9  48 17 1c b2  d0 7f be a1
|   53 fe 72 99  00 00 08 2c
| IV:
|   90 f5 05 64  d9 b8 2f 9d  d5 3e 2c 9c  af 0d 67 31
|   16 f5 0c b5
| unpadded size is: 56
| emitting 8 zero bytes of encryption padding into ISAKMP Message
| encrypting 64 using OAKLEY_AES_CBC
| next IV:  ed f8 f8 7a  c4 f1 60 f1  d9 ca 40 7a  f6 e2 9c 39
| emitting length of ISAKMP Message: 92
| sending 92 bytes for ISAKMP notify through eth2:500 to 10.29.3.225:500 (using #5)
|   34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
|   08 10 05 01  ff b1 57 84  00 00 00 5c  3e e8 7b 60
|   0b fd 0a 0e  3c 51 f7 32  26 c1 02 cc  9e fb 78 c5
|   85 b2 70 13  ae be 9f aa  e0 34 12 63  ac d7 93 2f
|   85 42 22 76  60 e7 1d 58  96 d1 27 50  ed f8 f8 7a
|   c4 f1 60 f1  d9 ca 40 7a  f6 e2 9c 39
| next event EVENT_DPD in 10 seconds for #6
|  
| *received 92 bytes from 10.29.3.225:500 on eth2 (port=500)
|   34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
|   08 10 05 01  e9 cf 00 72  00 00 00 5c  1d 54 42 df
|   c6 3a 24 22  3a 99 28 43  b4 df 34 63  4f 45 27 9e
|   22 e4 21 92  71 7f cb 20  d9 34 16 37  21 4e 75 74
|   35 f3 54 0f  7d 94 fa 2e  c9 5a e2 68  5f e5 14 5f
|   2e 71 29 88  e8 cc 4b a3  cb 59 b9 6f
| **parse ISAKMP Message:
|    initiator cookie:
|   34 3b dd c9  48 17 1c b2
|    responder cookie:
|   d0 7f be a1  53 fe 72 99
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  e9 cf 00 72
|    length: 92
|  processing version=1.0 packet with exchange type=ISAKMP_XCHG_INFO (5)
| ICOOKIE:  34 3b dd c9  48 17 1c b2
| RCOOKIE:  d0 7f be a1  53 fe 72 99
| state hash entry 28
| peer and cookies match on #6, provided msgid 00000000 vs 3561c8bc/00000000
| peer and cookies match on #5, provided msgid 00000000 vs 00000000/00000000
| p15 state object #5 found, in STATE_MAIN_R3
| processing connection site_192.168.1.0_24-192.168.123.0_24
| last Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| current Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| computed Phase 2 IV:
|   ff 68 81 12  3b e1 1a 32  cb 45 04 4f  69 61 b2 df
|   6a 71 8b 00
| received encrypted packet from 10.29.3.225:500
| decrypting 64 bytes using algorithm OAKLEY_AES_CBC
| decrypted:
|   0b 00 00 18  4b 76 f9 2e  00 f3 5f f1  32 52 b2 fc
|   69 dc 3f 7c  3f e4 58 f6  00 00 00 20  00 00 00 01
|   01 10 8d 29  34 3b dd c9  48 17 1c b2  d0 7f be a1
|   53 fe 72 99  00 00 08 2c  00 00 00 00  00 00 00 00
| next IV:  5f e5 14 5f  2e 71 29 88  e8 cc 4b a3  cb 59 b9 6f
| 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:  34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
| info:  00 00 08 2c
| processing informational R_U_THERE_ACK (36137)
| state: 5 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 #6
| next event EVENT_DPD in 10 seconds for #6
|  
| next event EVENT_DPD in 0 seconds for #6
| *time to handle event
| handling event EVENT_DPD
| event after this is EVENT_PENDING_DDNS in 38 seconds
| processing connection site_192.168.1.0_24-192.168.123.0_24
| inserting event EVENT_DPD, timeout in 10 seconds for #6
| event added at head of queue
| state: 5 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 #5
| event added after event EVENT_DPD for #6
| **emit ISAKMP Message:
|    initiator cookie:
|   34 3b dd c9  48 17 1c b2
|    responder cookie:
|   d0 7f be a1  53 fe 72 99
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  a5 5e 40 14
| ***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  34 3b dd c9  48 17 1c b2
| emitting 8 raw bytes of notify rcookie into ISAKMP Notification Payload
| notify rcookie  d0 7f be a1  53 fe 72 99
| emitting 4 raw bytes of notify data into ISAKMP Notification Payload
| notify data  00 00 08 2d
| emitting length of ISAKMP Notification Payload: 32
| HASH computed:
|   27 27 28 8f  1a 11 89 c0  4b 9e 15 85  a8 37 87 52
|   7e cc af fc
| last Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| current Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| computed Phase 2 IV:
|   5f 57 7f 0b  93 64 d4 a1  28 11 1d 93  33 19 65 a9
|   67 0c ab 59
| encrypting:
|   0b 00 00 18  27 27 28 8f  1a 11 89 c0  4b 9e 15 85
|   a8 37 87 52  7e cc af fc  00 00 00 20  00 00 00 01
|   01 10 8d 28  34 3b dd c9  48 17 1c b2  d0 7f be a1
|   53 fe 72 99  00 00 08 2d
| IV:
|   5f 57 7f 0b  93 64 d4 a1  28 11 1d 93  33 19 65 a9
|   67 0c ab 59
| unpadded size is: 56
| emitting 8 zero bytes of encryption padding into ISAKMP Message
| encrypting 64 using OAKLEY_AES_CBC
| next IV:  c6 ef 66 5d  36 f9 b8 b7  60 fb 23 87  57 7e 42 ea
| emitting length of ISAKMP Message: 92
| sending 92 bytes for ISAKMP notify through eth2:500 to 10.29.3.225:500 (using #5)
|   34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
|   08 10 05 01  a5 5e 40 14  00 00 00 5c  b3 8e 64 44
|   f4 a9 7a 0c  29 07 24 35  34 e6 4c 64  26 36 6f 27
|   39 22 ca 02  1b 82 6d c3  3e 43 76 b6  46 b9 01 2b
|   40 83 9a ba  4a e1 7c f6  e7 81 c5 de  c6 ef 66 5d
|   36 f9 b8 b7  60 fb 23 87  57 7e 42 ea
| next event EVENT_DPD in 10 seconds for #6
|  
| *received 92 bytes from 10.29.3.225:500 on eth2 (port=500)
|   34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
|   08 10 05 01  58 94 5b 5d  00 00 00 5c  9a cd 97 48
|   6f 96 cf 8d  29 d2 c7 8d  7a 44 89 ea  9d a6 00 af
|   83 29 37 fe  3d c7 d4 d5  ac a6 9e ea  9f 56 61 b1
|   4c db 0a 69  5c 16 df 9a  8a eb c5 6e  3d 15 b0 99
|   3d 94 1d 35  dc 9a e5 c6  65 16 f7 5a
| **parse ISAKMP Message:
|    initiator cookie:
|   34 3b dd c9  48 17 1c b2
|    responder cookie:
|   d0 7f be a1  53 fe 72 99
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  58 94 5b 5d
|    length: 92
|  processing version=1.0 packet with exchange type=ISAKMP_XCHG_INFO (5)
| ICOOKIE:  34 3b dd c9  48 17 1c b2
| RCOOKIE:  d0 7f be a1  53 fe 72 99
| state hash entry 28
| peer and cookies match on #6, provided msgid 00000000 vs 3561c8bc/00000000
| peer and cookies match on #5, provided msgid 00000000 vs 00000000/00000000
| p15 state object #5 found, in STATE_MAIN_R3
| processing connection site_192.168.1.0_24-192.168.123.0_24
| last Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| current Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| computed Phase 2 IV:
|   dc 5e 75 d7  8f fe 17 20  18 4f f6 e2  75 6c 56 5e
|   2a 1f a9 79
| received encrypted packet from 10.29.3.225:500
| decrypting 64 bytes using algorithm OAKLEY_AES_CBC
| decrypted:
|   0b 00 00 18  f0 b9 4f 81  fe 3e 77 aa  78 32 4a 33
|   84 6e 83 62  28 b4 22 c9  00 00 00 20  00 00 00 01
|   01 10 8d 28  34 3b dd c9  48 17 1c b2  d0 7f be a1
|   53 fe 72 99  00 00 23 3d  00 00 00 00  00 00 00 00
| next IV:  3d 15 b0 99  3d 94 1d 35  dc 9a e5 c6  65 16 f7 5a
| 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:  34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
| info:  00 00 23 3d
| processing informational R_U_THERE (36136)
| **emit ISAKMP Message:
|    initiator cookie:
|   34 3b dd c9  48 17 1c b2
|    responder cookie:
|   d0 7f be a1  53 fe 72 99
|    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 1e d2 58
| ***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  34 3b dd c9  48 17 1c b2
| emitting 8 raw bytes of notify rcookie into ISAKMP Notification Payload
| notify rcookie  d0 7f be a1  53 fe 72 99
| emitting 4 raw bytes of notify data into ISAKMP Notification Payload
| notify data  00 00 23 3d
| emitting length of ISAKMP Notification Payload: 32
| HASH computed:
|   3b 62 5e 65  4e 66 ee fc  33 2b cb 2a  73 f8 35 fd
|   ec 60 51 3a
| last Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| current Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| computed Phase 2 IV:
|   20 e1 ed 2e  2f 69 e4 56  79 89 27 1d  18 a4 82 db
|   e2 a2 71 ec
| encrypting:
|   0b 00 00 18  3b 62 5e 65  4e 66 ee fc  33 2b cb 2a
|   73 f8 35 fd  ec 60 51 3a  00 00 00 20  00 00 00 01
|   01 10 8d 29  34 3b dd c9  48 17 1c b2  d0 7f be a1
|   53 fe 72 99  00 00 23 3d
| IV:
|   20 e1 ed 2e  2f 69 e4 56  79 89 27 1d  18 a4 82 db
|   e2 a2 71 ec
| unpadded size is: 56
| emitting 8 zero bytes of encryption padding into ISAKMP Message
| encrypting 64 using OAKLEY_AES_CBC
| next IV:  33 8a e8 ad  69 2b 20 68  3e c9 74 83  57 b0 76 ce
| emitting length of ISAKMP Message: 92
| sending 92 bytes for ISAKMP notify through eth2:500 to 10.29.3.225:500 (using #5)
|   34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
|   08 10 05 01  28 1e d2 58  00 00 00 5c  7b 36 95 57
|   93 d1 52 84  43 8f cd 40  03 ca e2 03  53 8c 86 46
|   03 d9 2b a8  44 50 0c 8e  0f 4b 4c 38  81 e2 68 b0
|   91 ad 11 46  3a e0 3e 1c  f2 60 e7 49  33 8a e8 ad
|   69 2b 20 68  3e c9 74 83  57 b0 76 ce
| state: 5 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 #6
| next event EVENT_DPD in 10 seconds for #6
|  
| *received 92 bytes from 10.29.3.225:500 on eth2 (port=500)
|   34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
|   08 10 05 01  7c 7f 1a 8b  00 00 00 5c  24 dc 8c fb
|   63 c9 7f 1a  45 62 0a 9f  f4 62 19 40  34 e4 21 c4
|   09 d8 66 e4  a4 5e 9f 9d  5b d0 11 1b  aa 1d 7f 1b
|   3c c9 e5 2d  de 3b 45 47  4b 13 04 a3  1b 02 7b 8d
|   79 79 d1 7b  f3 a6 a3 77  69 9a b2 85
| **parse ISAKMP Message:
|    initiator cookie:
|   34 3b dd c9  48 17 1c b2
|    responder cookie:
|   d0 7f be a1  53 fe 72 99
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  7c 7f 1a 8b
|    length: 92
|  processing version=1.0 packet with exchange type=ISAKMP_XCHG_INFO (5)
| ICOOKIE:  34 3b dd c9  48 17 1c b2
| RCOOKIE:  d0 7f be a1  53 fe 72 99
| state hash entry 28
| peer and cookies match on #6, provided msgid 00000000 vs 3561c8bc/00000000
| peer and cookies match on #5, provided msgid 00000000 vs 00000000/00000000
| p15 state object #5 found, in STATE_MAIN_R3
| processing connection site_192.168.1.0_24-192.168.123.0_24
| last Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| current Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| computed Phase 2 IV:
|   ed e1 b8 0f  f6 94 36 eb  7b e1 b6 dd  57 fc 2a de
|   aa 94 16 b8
| received encrypted packet from 10.29.3.225:500
| decrypting 64 bytes using algorithm OAKLEY_AES_CBC
| decrypted:
|   0b 00 00 18  eb 99 e5 7f  c0 be 4a c4  c3 3e 30 0f
|   c5 20 a0 d0  f5 2c bb cc  00 00 00 20  00 00 00 01
|   01 10 8d 29  34 3b dd c9  48 17 1c b2  d0 7f be a1
|   53 fe 72 99  00 00 08 2d  00 00 00 00  00 00 00 00
| next IV:  1b 02 7b 8d  79 79 d1 7b  f3 a6 a3 77  69 9a b2 85
| 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:  34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
| info:  00 00 08 2d
| 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 #6
| next event EVENT_DPD in 10 seconds for #6
|  
| *received 92 bytes from 10.29.3.225:500 on eth2 (port=500)
|   34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
|   08 10 05 01  f9 2b 17 16  00 00 00 5c  4e 2e b3 c0
|   30 c7 92 47  4c 6e f9 7d  50 03 89 c5  c0 24 49 9d
|   1d 61 4b cb  11 3b 12 a7  85 d8 66 60  c9 c3 e1 99
|   98 c9 f0 40  3e e1 a6 23  24 fa e7 bf  84 a2 05 4c
|   03 58 13 72  f3 be 1b b2  d4 f6 0a 9a
| **parse ISAKMP Message:
|    initiator cookie:
|   34 3b dd c9  48 17 1c b2
|    responder cookie:
|   d0 7f be a1  53 fe 72 99
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  f9 2b 17 16
|    length: 92
|  processing version=1.0 packet with exchange type=ISAKMP_XCHG_INFO (5)
| ICOOKIE:  34 3b dd c9  48 17 1c b2
| RCOOKIE:  d0 7f be a1  53 fe 72 99
| state hash entry 28
| peer and cookies match on #6, provided msgid 00000000 vs 3561c8bc/00000000
| peer and cookies match on #5, provided msgid 00000000 vs 00000000/00000000
| p15 state object #5 found, in STATE_MAIN_R3
| processing connection site_192.168.1.0_24-192.168.123.0_24
| last Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| current Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| computed Phase 2 IV:
|   68 d0 8d f7  f1 d4 fb 8f  c7 d9 b4 c4  d8 40 23 4c
|   e8 50 d8 40
| received encrypted packet from 10.29.3.225:500
| decrypting 64 bytes using algorithm OAKLEY_AES_CBC
| decrypted:
|   0b 00 00 18  ad 65 9b e6  36 fa d4 20  f6 dd 02 80
|   97 b6 aa 66  40 69 ab 59  00 00 00 20  00 00 00 01
|   01 10 8d 28  34 3b dd c9  48 17 1c b2  d0 7f be a1
|   53 fe 72 99  00 00 23 3e  00 00 00 00  00 00 00 00
| next IV:  84 a2 05 4c  03 58 13 72  f3 be 1b b2  d4 f6 0a 9a
| 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:  34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
| info:  00 00 23 3e
| processing informational R_U_THERE (36136)
| **emit ISAKMP Message:
|    initiator cookie:
|   34 3b dd c9  48 17 1c b2
|    responder cookie:
|   d0 7f be a1  53 fe 72 99
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  5b 7a c5 9f
| ***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  34 3b dd c9  48 17 1c b2
| emitting 8 raw bytes of notify rcookie into ISAKMP Notification Payload
| notify rcookie  d0 7f be a1  53 fe 72 99
| emitting 4 raw bytes of notify data into ISAKMP Notification Payload
| notify data  00 00 23 3e
| emitting length of ISAKMP Notification Payload: 32
| HASH computed:
|   d5 c3 d0 6f  c9 3e 82 df  87 5f 7f 57  8a 54 ab 00
|   12 a1 cd 66
| last Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| current Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| computed Phase 2 IV:
|   0f 0b 62 f1  8a 40 21 b6  38 aa f4 34  1d bf 64 d1
|   74 da d4 01
| encrypting:
|   0b 00 00 18  d5 c3 d0 6f  c9 3e 82 df  87 5f 7f 57
|   8a 54 ab 00  12 a1 cd 66  00 00 00 20  00 00 00 01
|   01 10 8d 29  34 3b dd c9  48 17 1c b2  d0 7f be a1
|   53 fe 72 99  00 00 23 3e
| IV:
|   0f 0b 62 f1  8a 40 21 b6  38 aa f4 34  1d bf 64 d1
|   74 da d4 01
| unpadded size is: 56
| emitting 8 zero bytes of encryption padding into ISAKMP Message
| encrypting 64 using OAKLEY_AES_CBC
| next IV:  59 15 8c 99  5a e5 ba a5  f1 a9 84 2e  fa 2f 3f be
| emitting length of ISAKMP Message: 92
| sending 92 bytes for ISAKMP notify through eth2:500 to 10.29.3.225:500 (using #5)
|   34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
|   08 10 05 01  5b 7a c5 9f  00 00 00 5c  8e 1a d6 a6
|   22 74 ad cf  f6 13 1b 79  d5 98 31 74  f7 22 16 f7
|   b7 2f a2 40  51 68 ff 4f  f5 e6 7d 45  a1 0e 42 27
|   8a 06 c8 4c  ac c5 6a ed  3d f6 d1 74  59 15 8c 99
|   5a e5 ba a5  f1 a9 84 2e  fa 2f 3f be
| complete state transition with STF_IGNORE
| * processed 0 messages from cryptographic helpers 
| next event EVENT_DPD in 0 seconds for #6
| *time to handle event
| handling event EVENT_DPD
| event after this is EVENT_PENDING_DDNS in 28 seconds
| processing connection site_192.168.1.0_24-192.168.123.0_24
| inserting event EVENT_DPD, timeout in 10 seconds for #6
| event added at head of queue
| next event EVENT_DPD in 10 seconds for #6
|  
| next event EVENT_DPD in 0 seconds for #6
| *time to handle event
| handling event EVENT_DPD
| event after this is EVENT_PENDING_DDNS in 18 seconds
| processing connection site_192.168.1.0_24-192.168.123.0_24
| inserting event EVENT_DPD, timeout in 10 seconds for #6
| event added at head of queue
| state: 5 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 #5
| event added after event EVENT_DPD for #6
| **emit ISAKMP Message:
|    initiator cookie:
|   34 3b dd c9  48 17 1c b2
|    responder cookie:
|   d0 7f be a1  53 fe 72 99
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  be 97 c5 9a
| ***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  34 3b dd c9  48 17 1c b2
| emitting 8 raw bytes of notify rcookie into ISAKMP Notification Payload
| notify rcookie  d0 7f be a1  53 fe 72 99
| emitting 4 raw bytes of notify data into ISAKMP Notification Payload
| notify data  00 00 08 2e
| emitting length of ISAKMP Notification Payload: 32
| HASH computed:
|   c6 00 ac 0c  9c 66 3e 21  65 79 fd 89  a0 ed 70 9e
|   8f d0 be 42
| last Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| current Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| computed Phase 2 IV:
|   cb 40 f8 e3  a5 b3 1f 61  06 56 8f c4  d8 82 9e 9a
|   b1 ea de 12
| encrypting:
|   0b 00 00 18  c6 00 ac 0c  9c 66 3e 21  65 79 fd 89
|   a0 ed 70 9e  8f d0 be 42  00 00 00 20  00 00 00 01
|   01 10 8d 28  34 3b dd c9  48 17 1c b2  d0 7f be a1
|   53 fe 72 99  00 00 08 2e
| IV:
|   cb 40 f8 e3  a5 b3 1f 61  06 56 8f c4  d8 82 9e 9a
|   b1 ea de 12
| unpadded size is: 56
| emitting 8 zero bytes of encryption padding into ISAKMP Message
| encrypting 64 using OAKLEY_AES_CBC
| next IV:  94 e8 f4 ae  23 2c f6 b0  63 98 b5 c1  75 c1 06 a3
| emitting length of ISAKMP Message: 92
| sending 92 bytes for ISAKMP notify through eth2:500 to 10.29.3.225:500 (using #5)
|   34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
|   08 10 05 01  be 97 c5 9a  00 00 00 5c  c1 16 f2 e9
|   d0 6e 9a fb  14 56 d7 29  22 a0 1c fa  3d 49 9a f0
|   81 d3 cf ab  d8 b7 d7 35  74 2f 32 06  4c 78 09 1d
|   6e 75 61 cd  de 07 d6 31  b6 59 f1 ec  94 e8 f4 ae
|   23 2c f6 b0  63 98 b5 c1  75 c1 06 a3
| next event EVENT_DPD in 10 seconds for #6
|  
| *received 92 bytes from 10.29.3.225:500 on eth2 (port=500)
|   34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
|   08 10 05 01  09 52 39 28  00 00 00 5c  c1 45 20 42
|   87 e3 4f de  2c de b3 fa  03 23 6c 55  16 01 86 b3
|   d5 80 fe d1  5c d7 8a df  c7 bb 7a 85  d3 6a 44 fd
|   f3 1f 11 4a  b6 9c 13 d5  aa 25 66 e1  f6 73 a3 2a
|   c8 c4 c1 50  85 52 79 18  4a d2 c2 8b
| **parse ISAKMP Message:
|    initiator cookie:
|   34 3b dd c9  48 17 1c b2
|    responder cookie:
|   d0 7f be a1  53 fe 72 99
|    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 52 39 28
|    length: 92
|  processing version=1.0 packet with exchange type=ISAKMP_XCHG_INFO (5)
| ICOOKIE:  34 3b dd c9  48 17 1c b2
| RCOOKIE:  d0 7f be a1  53 fe 72 99
| state hash entry 28
| peer and cookies match on #6, provided msgid 00000000 vs 3561c8bc/00000000
| peer and cookies match on #5, provided msgid 00000000 vs 00000000/00000000
| p15 state object #5 found, in STATE_MAIN_R3
| processing connection site_192.168.1.0_24-192.168.123.0_24
| last Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| current Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| computed Phase 2 IV:
|   d5 b5 57 fa  be 34 b3 c2  0d 48 01 89  35 a1 b2 16
|   49 b9 98 ab
| received encrypted packet from 10.29.3.225:500
| decrypting 64 bytes using algorithm OAKLEY_AES_CBC
| decrypted:
|   0b 00 00 18  45 c3 d9 12  4c 28 f5 90  15 76 93 60
|   e1 51 ee 31  e7 e5 a3 22  00 00 00 20  00 00 00 01
|   01 10 8d 28  34 3b dd c9  48 17 1c b2  d0 7f be a1
|   53 fe 72 99  00 00 23 3f  00 00 00 00  00 00 00 00
| next IV:  f6 73 a3 2a  c8 c4 c1 50  85 52 79 18  4a d2 c2 8b
| 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:  34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
| info:  00 00 23 3f
| processing informational R_U_THERE (36136)
| **emit ISAKMP Message:
|    initiator cookie:
|   34 3b dd c9  48 17 1c b2
|    responder cookie:
|   d0 7f be a1  53 fe 72 99
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  27 e3 1e 83
| ***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  34 3b dd c9  48 17 1c b2
| emitting 8 raw bytes of notify rcookie into ISAKMP Notification Payload
| notify rcookie  d0 7f be a1  53 fe 72 99
| emitting 4 raw bytes of notify data into ISAKMP Notification Payload
| notify data  00 00 23 3f
| emitting length of ISAKMP Notification Payload: 32
| HASH computed:
|   68 9f c1 5f  b0 51 c2 2c  5c cb f9 8d  6f 30 19 ab
|   11 b1 7c 71
| last Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| current Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| computed Phase 2 IV:
|   f5 e2 48 b2  08 1c f7 94  94 97 5f cc  79 6a 9c a8
|   5a a1 47 8e
| encrypting:
|   0b 00 00 18  68 9f c1 5f  b0 51 c2 2c  5c cb f9 8d
|   6f 30 19 ab  11 b1 7c 71  00 00 00 20  00 00 00 01
|   01 10 8d 29  34 3b dd c9  48 17 1c b2  d0 7f be a1
|   53 fe 72 99  00 00 23 3f
| IV:
|   f5 e2 48 b2  08 1c f7 94  94 97 5f cc  79 6a 9c a8
|   5a a1 47 8e
| unpadded size is: 56
| emitting 8 zero bytes of encryption padding into ISAKMP Message
| encrypting 64 using OAKLEY_AES_CBC
| next IV:  e4 b1 5e 70  35 08 32 02  92 b5 b2 05  af 99 63 ba
| emitting length of ISAKMP Message: 92
| sending 92 bytes for ISAKMP notify through eth2:500 to 10.29.3.225:500 (using #5)
|   34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
|   08 10 05 01  27 e3 1e 83  00 00 00 5c  e8 1b 62 2c
|   9b e0 95 23  2c 85 2e 4c  3e 95 06 56  c9 6c 68 c9
|   6f 75 e9 5e  01 2b 55 2f  e2 b7 98 69  e9 7f 40 29
|   73 3a 80 22  bb 18 17 fc  0f 1d 21 28  e4 b1 5e 70
|   35 08 32 02  92 b5 b2 05  af 99 63 ba
| state: 5 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 #6
| next event EVENT_DPD in 10 seconds for #6
|  
| *received 92 bytes from 10.29.3.225:500 on eth2 (port=500)
|   34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
|   08 10 05 01  f9 46 e7 7b  00 00 00 5c  a3 dc 83 1c
|   a8 70 c4 6c  50 67 30 cc  e4 c6 43 57  01 6d 8e 19
|   5c 8b fe 10  c6 db a7 ae  13 ee 1c 8e  1f 6b 81 6a
|   48 9d 2a 58  5b 77 0f 03  79 4c 1f 0b  90 42 b6 88
|   2d 60 cd 9a  41 3e 47 16  27 22 ce 7c
| **parse ISAKMP Message:
|    initiator cookie:
|   34 3b dd c9  48 17 1c b2
|    responder cookie:
|   d0 7f be a1  53 fe 72 99
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  f9 46 e7 7b
|    length: 92
|  processing version=1.0 packet with exchange type=ISAKMP_XCHG_INFO (5)
| ICOOKIE:  34 3b dd c9  48 17 1c b2
| RCOOKIE:  d0 7f be a1  53 fe 72 99
| state hash entry 28
| peer and cookies match on #6, provided msgid 00000000 vs 3561c8bc/00000000
| peer and cookies match on #5, provided msgid 00000000 vs 00000000/00000000
| p15 state object #5 found, in STATE_MAIN_R3
| processing connection site_192.168.1.0_24-192.168.123.0_24
| last Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| current Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| computed Phase 2 IV:
|   c5 05 01 8f  df 57 d1 be  38 5c 2e c3  a9 58 77 bc
|   d7 90 e8 f6
| received encrypted packet from 10.29.3.225:500
| decrypting 64 bytes using algorithm OAKLEY_AES_CBC
| decrypted:
|   0b 00 00 18  1c fd 43 7c  de 00 56 8d  a4 3e a6 d1
|   f6 ea b4 9d  7d 21 f4 2e  00 00 00 20  00 00 00 01
|   01 10 8d 29  34 3b dd c9  48 17 1c b2  d0 7f be a1
|   53 fe 72 99  00 00 08 2e  00 00 00 00  00 00 00 00
| next IV:  90 42 b6 88  2d 60 cd 9a  41 3e 47 16  27 22 ce 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:  34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
| info:  00 00 08 2e
| 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 #6
| next event EVENT_DPD in 10 seconds for #6
|  
| *received 92 bytes from 10.29.3.225:500 on eth2 (port=500)
|   34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
|   08 10 05 01  53 2e bd b9  00 00 00 5c  75 f5 1c ac
|   29 7e 80 05  e6 e6 b4 af  8b 53 80 df  f5 9b 7a d9
|   42 4b 92 b6  08 46 dd a7  d5 bc 99 fe  7e 19 f6 38
|   22 27 b6 6b  ea 9f 18 b4  e3 b6 49 03  00 5b d2 d7
|   92 c3 b3 8c  3b 3d 45 7a  8d 8b 50 0f
| **parse ISAKMP Message:
|    initiator cookie:
|   34 3b dd c9  48 17 1c b2
|    responder cookie:
|   d0 7f be a1  53 fe 72 99
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  53 2e bd b9
|    length: 92
|  processing version=1.0 packet with exchange type=ISAKMP_XCHG_INFO (5)
| ICOOKIE:  34 3b dd c9  48 17 1c b2
| RCOOKIE:  d0 7f be a1  53 fe 72 99
| state hash entry 28
| peer and cookies match on #6, provided msgid 00000000 vs 3561c8bc/00000000
| peer and cookies match on #5, provided msgid 00000000 vs 00000000/00000000
| p15 state object #5 found, in STATE_MAIN_R3
| processing connection site_192.168.1.0_24-192.168.123.0_24
| last Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| current Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| computed Phase 2 IV:
|   94 cc 57 b7  0c ba d0 63  65 2f ea 1d  c6 aa 3a ee
|   31 75 3b 32
| received encrypted packet from 10.29.3.225:500
| decrypting 64 bytes using algorithm OAKLEY_AES_CBC
| decrypted:
|   0b 00 00 18  cc 37 41 bd  37 08 2a 17  00 4e 7a 49
|   5f 2e 33 7a  26 60 9e e5  00 00 00 20  00 00 00 01
|   01 10 8d 28  34 3b dd c9  48 17 1c b2  d0 7f be a1
|   53 fe 72 99  00 00 23 40  00 00 00 00  00 00 00 00
| next IV:  00 5b d2 d7  92 c3 b3 8c  3b 3d 45 7a  8d 8b 50 0f
| 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:  34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
| info:  00 00 23 40
| processing informational R_U_THERE (36136)
| **emit ISAKMP Message:
|    initiator cookie:
|   34 3b dd c9  48 17 1c b2
|    responder cookie:
|   d0 7f be a1  53 fe 72 99
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  b0 42 ba 82
| ***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  34 3b dd c9  48 17 1c b2
| emitting 8 raw bytes of notify rcookie into ISAKMP Notification Payload
| notify rcookie  d0 7f be a1  53 fe 72 99
| emitting 4 raw bytes of notify data into ISAKMP Notification Payload
| notify data  00 00 23 40
| emitting length of ISAKMP Notification Payload: 32
| HASH computed:
|   3e 1c 8f 3f  60 c1 22 de  86 19 bf a8  31 f1 7e b0
|   5c 58 89 c1
| last Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| current Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| computed Phase 2 IV:
|   6f 83 15 41  48 21 92 ce  8e 6a 09 e4  9d 5b 5f df
|   1c 58 49 20
| encrypting:
|   0b 00 00 18  3e 1c 8f 3f  60 c1 22 de  86 19 bf a8
|   31 f1 7e b0  5c 58 89 c1  00 00 00 20  00 00 00 01
|   01 10 8d 29  34 3b dd c9  48 17 1c b2  d0 7f be a1
|   53 fe 72 99  00 00 23 40
| IV:
|   6f 83 15 41  48 21 92 ce  8e 6a 09 e4  9d 5b 5f df
|   1c 58 49 20
| unpadded size is: 56
| emitting 8 zero bytes of encryption padding into ISAKMP Message
| encrypting 64 using OAKLEY_AES_CBC
| next IV:  66 82 30 cd  ff 1f fe 05  7f 58 37 13  d2 71 76 b9
| emitting length of ISAKMP Message: 92
| sending 92 bytes for ISAKMP notify through eth2:500 to 10.29.3.225:500 (using #5)
|   34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
|   08 10 05 01  b0 42 ba 82  00 00 00 5c  c7 19 d9 d4
|   c9 b2 1c 84  38 55 36 cc  13 74 04 ad  96 6e cc f3
|   8b 3c 0a 80  80 1a e9 46  1d 25 fb c3  71 e3 0b 4c
|   c7 3a ed 8b  d6 4e ed a0  11 59 01 67  66 82 30 cd
|   ff 1f fe 05  7f 58 37 13  d2 71 76 b9
| complete state transition with STF_IGNORE
| * processed 0 messages from cryptographic helpers 
| next event EVENT_DPD in 0 seconds for #6
| *time to handle event
| handling event EVENT_DPD
| event after this is EVENT_PENDING_DDNS in 8 seconds
| processing connection site_192.168.1.0_24-192.168.123.0_24
| inserting event EVENT_DPD, timeout in 10 seconds for #6
| event added after event EVENT_SHUNT_SCAN
| next event EVENT_PENDING_DDNS in 8 seconds
|  
| next event EVENT_PENDING_DDNS in 0 seconds
| *time to handle event
| handling event EVENT_PENDING_DDNS
| event after this is EVENT_PENDING_PHASE2 in 0 seconds
| inserting event EVENT_PENDING_DDNS, timeout in 60 seconds
| event added after event EVENT_DPD for #6
| handling event EVENT_PENDING_PHASE2
| event after this is EVENT_SHUNT_SCAN in 0 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
| handling event EVENT_SHUNT_SCAN
| event after this is EVENT_DPD in 2 seconds
| inserting event EVENT_SHUNT_SCAN, timeout in 120 seconds
| event added after event EVENT_PENDING_DDNS
| scanning for shunt eroutes
| next event EVENT_DPD in 2 seconds for #6
|  
| *received 92 bytes from 10.29.3.225:500 on eth2 (port=500)
|   34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
|   08 10 05 01  7c 9f ea 9e  00 00 00 5c  bb 0a 90 80
|   63 50 26 89  23 5d 88 1d  96 02 7a 70  6a 7d 39 ea
|   64 3d 51 2d  5d 6d 2f da  4b fe 8a b0  dc 51 b4 e8
|   b9 7a 95 e6  bf 6b 53 02  e7 d7 51 0b  5c 59 8a aa
|   8e 28 da c5  09 5f cc ef  c0 1e 50 26
| **parse ISAKMP Message:
|    initiator cookie:
|   34 3b dd c9  48 17 1c b2
|    responder cookie:
|   d0 7f be a1  53 fe 72 99
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  7c 9f ea 9e
|    length: 92
|  processing version=1.0 packet with exchange type=ISAKMP_XCHG_INFO (5)
| ICOOKIE:  34 3b dd c9  48 17 1c b2
| RCOOKIE:  d0 7f be a1  53 fe 72 99
| state hash entry 28
| peer and cookies match on #6, provided msgid 00000000 vs 3561c8bc/00000000
| peer and cookies match on #5, provided msgid 00000000 vs 00000000/00000000
| p15 state object #5 found, in STATE_MAIN_R3
| processing connection site_192.168.1.0_24-192.168.123.0_24
| last Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| current Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| computed Phase 2 IV:
|   15 db a7 d3  5c 4b 92 4d  98 a3 c5 87  dd 1e af f3
|   cc 51 bb cc
| received encrypted packet from 10.29.3.225:500
| decrypting 64 bytes using algorithm OAKLEY_AES_CBC
| decrypted:
|   0b 00 00 18  c1 72 5d f3  2b 03 be 31  bf 74 43 a9
|   a2 5c ba 06  1d 36 19 fa  00 00 00 20  00 00 00 01
|   01 10 8d 28  34 3b dd c9  48 17 1c b2  d0 7f be a1
|   53 fe 72 99  00 00 23 41  00 00 00 00  00 00 00 00
| next IV:  5c 59 8a aa  8e 28 da c5  09 5f cc ef  c0 1e 50 26
| 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:  34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
| info:  00 00 23 41
| processing informational R_U_THERE (36136)
| **emit ISAKMP Message:
|    initiator cookie:
|   34 3b dd c9  48 17 1c b2
|    responder cookie:
|   d0 7f be a1  53 fe 72 99
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  7a 50 60 49
| ***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  34 3b dd c9  48 17 1c b2
| emitting 8 raw bytes of notify rcookie into ISAKMP Notification Payload
| notify rcookie  d0 7f be a1  53 fe 72 99
| emitting 4 raw bytes of notify data into ISAKMP Notification Payload
| notify data  00 00 23 41
| emitting length of ISAKMP Notification Payload: 32
| HASH computed:
|   da ce e5 be  6f 14 9c 22  b9 11 bc ba  49 e1 b5 eb
|   42 eb 95 49
| last Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| current Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| computed Phase 2 IV:
|   e2 0a a1 07  42 72 19 aa  37 ef 9d 66  ee 31 13 fc
|   17 24 10 9c
| encrypting:
|   0b 00 00 18  da ce e5 be  6f 14 9c 22  b9 11 bc ba
|   49 e1 b5 eb  42 eb 95 49  00 00 00 20  00 00 00 01
|   01 10 8d 29  34 3b dd c9  48 17 1c b2  d0 7f be a1
|   53 fe 72 99  00 00 23 41
| IV:
|   e2 0a a1 07  42 72 19 aa  37 ef 9d 66  ee 31 13 fc
|   17 24 10 9c
| unpadded size is: 56
| emitting 8 zero bytes of encryption padding into ISAKMP Message
| encrypting 64 using OAKLEY_AES_CBC
| next IV:  33 38 aa 75  3a 54 f3 5e  dc d8 1f ef  34 af 6e 30
| emitting length of ISAKMP Message: 92
| sending 92 bytes for ISAKMP notify through eth2:500 to 10.29.3.225:500 (using #5)
|   34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
|   08 10 05 01  7a 50 60 49  00 00 00 5c  aa 7f 49 52
|   26 b9 51 1a  65 ac cc 02  21 45 01 a3  f1 7b 55 94
|   e5 9f c2 1d  14 4b e3 4f  92 ca 7a 7a  0e 67 70 d1
|   b7 25 f4 92  e3 fe 3e 7c  d1 4f 3e ff  33 38 aa 75
|   3a 54 f3 5e  dc d8 1f ef  34 af 6e 30
| complete state transition with STF_IGNORE
| * processed 0 messages from cryptographic helpers 
| next event EVENT_DPD in 0 seconds for #6
| *time to handle event
| handling event EVENT_DPD
| event after this is EVENT_PENDING_DDNS in 58 seconds
| processing connection site_192.168.1.0_24-192.168.123.0_24
| inserting event EVENT_DPD, timeout in 10 seconds for #6
| event added at head of queue
| next event EVENT_DPD in 10 seconds for #6
|  
| next event EVENT_DPD in 0 seconds for #6
| *time to handle event
| handling event EVENT_DPD
| event after this is EVENT_PENDING_DDNS in 48 seconds
| processing connection site_192.168.1.0_24-192.168.123.0_24
| inserting event EVENT_DPD, timeout in 10 seconds for #6
| event added at head of queue
| state: 5 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 #5
| event added after event EVENT_DPD for #6
| **emit ISAKMP Message:
|    initiator cookie:
|   34 3b dd c9  48 17 1c b2
|    responder cookie:
|   d0 7f be a1  53 fe 72 99
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  a4 e9 ef 61
| ***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  34 3b dd c9  48 17 1c b2
| emitting 8 raw bytes of notify rcookie into ISAKMP Notification Payload
| notify rcookie  d0 7f be a1  53 fe 72 99
| emitting 4 raw bytes of notify data into ISAKMP Notification Payload
| notify data  00 00 08 2f
| emitting length of ISAKMP Notification Payload: 32
| HASH computed:
|   b9 ca 4f 2e  78 73 cf df  dc 1b 5f 9a  46 be 97 39
|   87 c3 df a0
| last Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| current Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| computed Phase 2 IV:
|   a7 61 9d d9  6b 68 bd 7e  74 ad de f9  85 c4 0f 23
|   1e 60 76 5c
| encrypting:
|   0b 00 00 18  b9 ca 4f 2e  78 73 cf df  dc 1b 5f 9a
|   46 be 97 39  87 c3 df a0  00 00 00 20  00 00 00 01
|   01 10 8d 28  34 3b dd c9  48 17 1c b2  d0 7f be a1
|   53 fe 72 99  00 00 08 2f
| IV:
|   a7 61 9d d9  6b 68 bd 7e  74 ad de f9  85 c4 0f 23
|   1e 60 76 5c
| unpadded size is: 56
| emitting 8 zero bytes of encryption padding into ISAKMP Message
| encrypting 64 using OAKLEY_AES_CBC
| next IV:  09 99 3a f3  71 00 82 a9  bf de a8 65  9f ba 0c 39
| emitting length of ISAKMP Message: 92
| sending 92 bytes for ISAKMP notify through eth2:500 to 10.29.3.225:500 (using #5)
|   34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
|   08 10 05 01  a4 e9 ef 61  00 00 00 5c  76 86 ea a8
|   35 e4 fc cc  e4 80 54 eb  26 f8 dc 24  70 dc 07 1d
|   db 32 08 3e  2e 18 b1 09  10 03 aa df  15 35 af 6b
|   11 a6 b4 17  16 a3 54 b9  07 e9 24 30  09 99 3a f3
|   71 00 82 a9  bf de a8 65  9f ba 0c 39
| next event EVENT_DPD in 10 seconds for #6
|  
| *received 92 bytes from 10.29.3.225:500 on eth2 (port=500)
|   34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
|   08 10 05 01  bc 58 bb 22  00 00 00 5c  4f 2e 81 66
|   9c a4 34 a9  d7 58 bb 1f  5d 21 d7 3c  e1 af 67 36
|   61 ff ab 7d  b2 0e 40 ad  9f 43 10 a4  11 d1 53 91
|   c2 7e c8 30  06 39 35 29  b7 37 f4 af  eb 83 d8 9e
|   1d 97 8f ea  2f 1f 80 d8  de ee 08 5b
| **parse ISAKMP Message:
|    initiator cookie:
|   34 3b dd c9  48 17 1c b2
|    responder cookie:
|   d0 7f be a1  53 fe 72 99
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  bc 58 bb 22
|    length: 92
|  processing version=1.0 packet with exchange type=ISAKMP_XCHG_INFO (5)
| ICOOKIE:  34 3b dd c9  48 17 1c b2
| RCOOKIE:  d0 7f be a1  53 fe 72 99
| state hash entry 28
| peer and cookies match on #6, provided msgid 00000000 vs 3561c8bc/00000000
| peer and cookies match on #5, provided msgid 00000000 vs 00000000/00000000
| p15 state object #5 found, in STATE_MAIN_R3
| processing connection site_192.168.1.0_24-192.168.123.0_24
| last Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| current Phase 1 IV:  fd 12 4e 7d  1b 3a 78 5d  71 97 01 97  b3 fb 25 b2
| computed Phase 2 IV:
|   56 70 a0 ab  f7 aa 40 d0  01 e3 ef 85  53 11 4d 2c
|   16 0b ef 32
| received encrypted packet from 10.29.3.225:500
| decrypting 64 bytes using algorithm OAKLEY_AES_CBC
| decrypted:
|   0b 00 00 18  3b 66 b5 b0  71 98 9b a7  28 63 a4 1e
|   67 f4 8d 34  0a 98 01 1f  00 00 00 20  00 00 00 01
|   01 10 8d 29  34 3b dd c9  48 17 1c b2  d0 7f be a1
|   53 fe 72 99  00 00 08 2f  00 00 00 00  00 00 00 00
| next IV:  eb 83 d8 9e  1d 97 8f ea  2f 1f 80 d8  de ee 08 5b
| 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:  34 3b dd c9  48 17 1c b2  d0 7f be a1  53 fe 72 99
| info:  00 00 08 2f
| processing informational R_U_THERE_ACK (36137)
| state: 5 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 #6
| next event EVENT_DPD in 10 seconds for #6
