
|  
| *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 24 seconds
| next event EVENT_PENDING_DDNS in 24 seconds
|  
| *received 116 bytes from 10.29.3.225:500 on eth2 (port=500)
|   54 e4 4d cf  e8 bd 58 56  00 00 00 00  00 00 00 00
|   01 10 02 00  00 00 00 00  00 00 00 74  0d 00 00 34
|   00 00 00 01  00 00 00 01  00 00 00 28  00 01 00 01
|   00 00 00 20  00 01 00 00  80 0b 00 01  80 0c 70 80
|   80 01 00 05  80 02 00 01  80 03 00 01  80 04 00 05
|   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:
|   54 e4 4d cf  e8 bd 58 56
|    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: 116
|  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: 52
|    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 #12 at 0x181830
| processing connection site_192.168.1.0_24-192.168.123.0_24
| ICOOKIE:  54 e4 4d cf  e8 bd 58 56
| RCOOKIE:  c1 4c bd 20  82 fc 1e e8
| state hash entry 31
| inserting state object #12 on chain 31
| inserting event EVENT_SO_DISCARD, timeout in 0 seconds for #12
| event added at head of queue
"site_192.168.1.0_24-192.168.123.0_24" #12: responding to Main Mode
| **emit ISAKMP Message:
|    initiator cookie:
|   54 e4 4d cf  e8 bd 58 56
|    responder cookie:
|   c1 4c bd 20  82 fc 1e e8
|    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: 40
|    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: 32
|    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: 5
|    [5 is OAKLEY_3DES_CBC]
| ike_alg_enc_ok(ealg=5,key_len=0): blocksize=8, keyminlen=192, keydeflen=192, keymaxlen=192, ret=1
| ******parse ISAKMP Oakley attribute:
|    af+type: OAKLEY_HASH_ALGORITHM
|    length/value: 1
|    [1 is OAKLEY_MD5]
| ******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=0x17bd00 (line=1)
| concluding with best_match=12 best=0x17bd00 (lineno=1)
| ******parse ISAKMP Oakley attribute:
|    af+type: OAKLEY_GROUP_DESCRIPTION
|    length/value: 5
|    [5 is OAKLEY_GROUP_MODP1536]
| 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 24 raw bytes of attributes into ISAKMP Transform Payload (ISAKMP)
| attributes  80 0b 00 01  80 0c 70 80  80 01 00 05  80 02 00 01
| attributes  80 03 00 01  80 04 00 05
| emitting length of ISAKMP Transform Payload (ISAKMP): 32
| emitting length of ISAKMP Proposal Payload: 40
| emitting length of ISAKMP Security Association Payload: 52
| ***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: 116
| complete state transition with STF_OK
"site_192.168.1.0_24-192.168.123.0_24" #12: transition from state STATE_MAIN_R0 to state STATE_MAIN_R1
| deleting event for #12
| sending reply packet to 10.29.3.225:500 (from port 500)
| sending 116 bytes for STATE_MAIN_R0 through eth2:500 to 10.29.3.225:500 (using #12)
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   01 10 02 00  00 00 00 00  00 00 00 74  0d 00 00 34
|   00 00 00 01  00 00 00 01  00 00 00 28  00 01 00 01
|   00 00 00 20  00 01 00 00  80 0b 00 01  80 0c 70 80
|   80 01 00 05  80 02 00 01  80 03 00 01  80 04 00 05
|   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 #12
| event added at head of queue
"site_192.168.1.0_24-192.168.123.0_24" #12: 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 #12
| next event EVENT_RETRANSMIT in 10 seconds for #12
|  
| *received 244 bytes from 10.29.3.225:500 on eth2 (port=500)
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   04 10 02 00  00 00 00 00  00 00 00 f4  0a 00 00 c4
|   03 e2 a3 ee  c8 97 40 94  66 99 f7 dd  f0 5f 5e 92
|   82 50 c7 58  fb d0 96 ec  17 c1 68 ca  99 f8 f7 04
|   4d 50 b0 20  9a bc 7b 07  4a 1a fe 7f  ce c1 87 89
|   02 d2 b8 5c  8a 22 5a 58  19 e7 84 db  3d 2c 6c e8
|   60 13 29 61  e4 b5 cf 18  43 04 72 ce  31 5e a7 1d
|   cc c4 55 ac  4f a0 50 ae  16 98 52 fc  ff 47 5d b5
|   bc ef e4 73  d5 b1 38 11  87 46 59 c1  49 32 b6 bb
|   eb d2 f3 90  4f b6 da 93  1b 63 57 48  2b fa 15 a2
|   87 5d 83 a0  b1 f5 d5 4d  16 06 6a eb  11 f7 e8 9e
|   3f e0 d3 0a  1a 91 8c d2  d1 c2 ab 8b  8d 81 f8 b9
|   4e 05 44 a0  c9 81 83 a9  97 27 5c cd  21 d6 f7 3b
|   31 33 37 c9  c3 68 69 10  f9 81 29 15  a7 5d 20 b8
|   00 00 00 14  e5 38 ae 8a  56 fd 0c 2d  1e a5 80 30
|   18 56 e2 4b
| **parse ISAKMP Message:
|    initiator cookie:
|   54 e4 4d cf  e8 bd 58 56
|    responder cookie:
|   c1 4c bd 20  82 fc 1e e8
|    next payload type: ISAKMP_NEXT_KE
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_IDPROT
|    flags: none
|    message ID:  00 00 00 00
|    length: 244
|  processing version=1.0 packet with exchange type=ISAKMP_XCHG_IDPROT (2)
| ICOOKIE:  54 e4 4d cf  e8 bd 58 56
| RCOOKIE:  c1 4c bd 20  82 fc 1e e8
| state hash entry 31
| v1 peer and cookies match on #12, provided msgid 00000000 vs 00000000
| v1 state object #12 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: 196
| 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:
|   03 e2 a3 ee  c8 97 40 94  66 99 f7 dd  f0 5f 5e 92
|   82 50 c7 58  fb d0 96 ec  17 c1 68 ca  99 f8 f7 04
|   4d 50 b0 20  9a bc 7b 07  4a 1a fe 7f  ce c1 87 89
|   02 d2 b8 5c  8a 22 5a 58  19 e7 84 db  3d 2c 6c e8
|   60 13 29 61  e4 b5 cf 18  43 04 72 ce  31 5e a7 1d
|   cc c4 55 ac  4f a0 50 ae  16 98 52 fc  ff 47 5d b5
|   bc ef e4 73  d5 b1 38 11  87 46 59 c1  49 32 b6 bb
|   eb d2 f3 90  4f b6 da 93  1b 63 57 48  2b fa 15 a2
|   87 5d 83 a0  b1 f5 d5 4d  16 06 6a eb  11 f7 e8 9e
|   3f e0 d3 0a  1a 91 8c d2  d1 c2 ab 8b  8d 81 f8 b9
|   4e 05 44 a0  c9 81 83 a9  97 27 5c cd  21 d6 f7 3b
|   31 33 37 c9  c3 68 69 10  f9 81 29 15  a7 5d 20 b8
| 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: 23 (len=2668, pcw_work=1)
! helper 0 read 2664+4/2668 bytesfd: 7
! helper 0 doing build_kenonce op id: 23
| crypto helper write of request: cnt=2668<wlen=2668.  
| deleting event for #12
| inserting event EVENT_CRYPTO_FAILED, timeout in 300 seconds for #12
| event added after event EVENT_PENDING_PHASE2
| complete state transition with STF_SUSPEND
| * processed 0 messages from cryptographic helpers 
| next event EVENT_PENDING_DDNS in 15 seconds
| next event EVENT_PENDING_DDNS in 15 seconds
! Local DH secret:
!   c4 dd 09 fa  53 93 70 fb  7b e5 a5 95  14 e5 cd 0c
!   04 39 c3 37  cd 02 1a 62  1f 73 7e e8  69 8f 92 8d
! Public DH value sent:
!   1c c7 da 0f  b1 28 07 63  2a d5 a9 9a  39 0e be 86
!   e5 01 fc 71  0f f7 06 62  e3 01 12 8b  d7 cb 14 dd
!   c9 4b a9 e9  b8 43 5e bf  3b c9 53 2b  1b e1 99 44
!   16 83 f3 56  77 e7 3b 40  a3 c8 4f 91  f2 39 6d 20
!   a1 b2 25 16  a9 b8 8a a1  ab 33 68 16  83 5e 0a a1
!   29 98 72 38  81 18 fd de  e5 b2 91 22  55 e8 23 88
!   fb c5 da 32  8f 25 1c bd  54 e3 a9 eb  5f 2a 73 29
!   86 c9 69 40  86 d4 73 1a  35 26 91 71  4f fb 04 89
!   25 7d 62 4e  81 bb b0 04  a0 80 b3 00  23 13 78 0b
!   6c ce a8 2b  70 8b df 14  55 2a 0d cc  91 cc 76 f6
!   8b ff 9e 6b  da 93 18 9c  f9 63 06 77  32 5a 36 6c
!   c6 31 8a 18  09 08 b8 a0  fc f1 e9 a4  e9 f7 58 22
! Generated nonce:
!   43 f9 a4 16  78 2a f2 97  3a 58 c7 0a  3d 78 75 a2
|  
| helper 0 has finished work (cnt now 1)
| helper 0 replies to id: q#23
| 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:
|   54 e4 4d cf  e8 bd 58 56
|    responder cookie:
|   c1 4c bd 20  82 fc 1e e8
|    next payload type: ISAKMP_NEXT_KE
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_IDPROT
|    flags: none
|    message ID:  00 00 00 00
| ***emit ISAKMP Key Exchange Payload:
|    next payload type: ISAKMP_NEXT_NONCE
| emitting 192 raw bytes of keyex value into ISAKMP Key Exchange Payload
| keyex value  1c c7 da 0f  b1 28 07 63  2a d5 a9 9a  39 0e be 86
| keyex value  e5 01 fc 71  0f f7 06 62  e3 01 12 8b  d7 cb 14 dd
| keyex value  c9 4b a9 e9  b8 43 5e bf  3b c9 53 2b  1b e1 99 44
| keyex value  16 83 f3 56  77 e7 3b 40  a3 c8 4f 91  f2 39 6d 20
| keyex value  a1 b2 25 16  a9 b8 8a a1  ab 33 68 16  83 5e 0a a1
| keyex value  29 98 72 38  81 18 fd de  e5 b2 91 22  55 e8 23 88
| keyex value  fb c5 da 32  8f 25 1c bd  54 e3 a9 eb  5f 2a 73 29
| keyex value  86 c9 69 40  86 d4 73 1a  35 26 91 71  4f fb 04 89
| keyex value  25 7d 62 4e  81 bb b0 04  a0 80 b3 00  23 13 78 0b
| keyex value  6c ce a8 2b  70 8b df 14  55 2a 0d cc  91 cc 76 f6
| keyex value  8b ff 9e 6b  da 93 18 9c  f9 63 06 77  32 5a 36 6c
| keyex value  c6 31 8a 18  09 08 b8 a0  fc f1 e9 a4  e9 f7 58 22
| emitting length of ISAKMP Key Exchange Payload: 196
| ***emit ISAKMP Nonce Payload:
|    next payload type: ISAKMP_NEXT_NONE
| emitting 16 raw bytes of Nr into ISAKMP Nonce Payload
| Nr  43 f9 a4 16  78 2a f2 97  3a 58 c7 0a  3d 78 75 a2
| emitting length of ISAKMP Nonce Payload: 20
| emitting length of ISAKMP Message: 244
| main inI2_outR2: starting async DH calculation (group=5)
| 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=0x17bd00 (line=1)
| concluding with best_match=12 best=0x17bd00 (lineno=1)
| parent1 type: 7 group: 5 len: 2668 
| 0: w->pcw_dead: 0 w->pcw_work: 0 cnt: 1
| asking helper 0 to do compute dh+iv op on seq: 24 (len=2668, pcw_work=1)
| crypto helper write of request: cnt=2668<wlen=2668.  
| deleting event for #12
| inserting event EVENT_CRYPTO_FAILED, timeout in 300 seconds for #12
| event added after event EVENT_PENDING_PHASE2
| started dh_secretiv, returned: stf=STF_SUSPEND 
| complete state transition with STF_OK
"site_192.168.1.0_24-192.168.123.0_24" #12: transition from state STATE_MAIN_R1 to state STATE_MAIN_R2
| deleting event for #12
| sending reply packet to 10.29.3.225:500 (from port 500)
| sending 244 bytes for STATE_MAIN_R1 through eth2:500 to 10.29.3.225:500 (using #12)
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   04 10 02 00  00 00 00 00  00 00 00 f4  0a 00 00 c4
|   1c c7 da 0f  b1 28 07 63  2a d5 a9 9a  39 0e be 86
|   e5 01 fc 71  0f f7 06 62  e3 01 12 8b  d7 cb 14 dd
|   c9 4b a9 e9  b8 43 5e bf  3b c9 53 2b  1b e1 99 44
|   16 83 f3 56  77 e7 3b 40  a3 c8 4f 91  f2 39 6d 20
|   a1 b2 25 16  a9 b8 8a a1  ab 33 68 16  83 5e 0a a1
|   29 98 72 38  81 18 fd de  e5 b2 91 22  55 e8 23 88
|   fb c5 da 32  8f 25 1c bd  54 e3 a9 eb  5f 2a 73 29
|   86 c9 69 40  86 d4 73 1a  35 26 91 71  4f fb 04 89
|   25 7d 62 4e  81 bb b0 04  a0 80 b3 00  23 13 78 0b
|   6c ce a8 2b  70 8b df 14  55 2a 0d cc  91 cc 76 f6
|   8b ff 9e 6b  da 93 18 9c  f9 63 06 77  32 5a 36 6c
|   c6 31 8a 18  09 08 b8 a0  fc f1 e9 a4  e9 f7 58 22
|   00 00 00 14  43 f9 a4 16  78 2a f2 97  3a 58 c7 0a
|   3d 78 75 a2
| inserting event EVENT_RETRANSMIT, timeout in 10 seconds for #12
| event added at head of queue
"site_192.168.1.0_24-192.168.123.0_24" #12: 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 #12
| next event EVENT_RETRANSMIT in 10 seconds for #12
! helper 0 read 2664+4/2668 bytesfd: 7
! helper 0 doing compute dh+iv op id: 24
! peer's g:   03 e2 a3 ee  c8 97 40 94  66 99 f7 dd  f0 5f 5e 92
! peer's g:   82 50 c7 58  fb d0 96 ec  17 c1 68 ca  99 f8 f7 04
! peer's g:   4d 50 b0 20  9a bc 7b 07  4a 1a fe 7f  ce c1 87 89
! peer's g:   02 d2 b8 5c  8a 22 5a 58  19 e7 84 db  3d 2c 6c e8
! peer's g:   60 13 29 61  e4 b5 cf 18  43 04 72 ce  31 5e a7 1d
! peer's g:   cc c4 55 ac  4f a0 50 ae  16 98 52 fc  ff 47 5d b5
! peer's g:   bc ef e4 73  d5 b1 38 11  87 46 59 c1  49 32 b6 bb
! peer's g:   eb d2 f3 90  4f b6 da 93  1b 63 57 48  2b fa 15 a2
! peer's g:   87 5d 83 a0  b1 f5 d5 4d  16 06 6a eb  11 f7 e8 9e
! peer's g:   3f e0 d3 0a  1a 91 8c d2  d1 c2 ab 8b  8d 81 f8 b9
! peer's g:   4e 05 44 a0  c9 81 83 a9  97 27 5c cd  21 d6 f7 3b
! peer's g:   31 33 37 c9  c3 68 69 10  f9 81 29 15  a7 5d 20 b8
! long term secret:   c4 dd 09 fa  53 93 70 fb  7b e5 a5 95  14 e5 cd 0c
! long term secret:   04 39 c3 37  cd 02 1a 62  1f 73 7e e8  69 8f 92 8d
! calc_dh_shared(): time elapsed (OAKLEY_GROUP_MODP1536): 28006 usec
! DH shared-secret:
!   d4 e3 ff 81  cb 73 44 b5  76 22 65 0b  ed 3c c3 f1
!   27 da d7 57  5f 4c 66 9e  ab cf b2 31  88 86 ac c6
!   ca 54 73 b9  b5 05 1a 4b  e9 b1 4d b9  f1 37 e8 48
!   ba db 64 21  d9 d3 58 d2  46 d8 0d 4f  7c d6 8d 81
!   05 6c 5f 8b  dc cc 2d d9  84 b5 be 83  5a 70 ee 15
!   7d 77 4f cf  03 9a df ab  c2 48 a7 58  36 75 c3 96
!   cc 1c aa d5  c7 b3 5e 9d  fa 0b b0 3e  91 74 9e b0
!   c6 14 ea f6  38 9a a3 9e  1b 3f 7c 5b  5a e1 cb ff
!   a0 29 cd f5  22 26 9b 54  fb f2 6e 5a  97 d7 12 24
!   75 09 30 70  e1 01 fd d7  fe 41 c9 74  78 a8 64 e6
!   e9 bb 92 7f  83 dc a0 f0  b3 37 24 8d  d9 26 27 7f
!   d2 4d 4e 16  6e c6 99 1e  e9 59 57 a9  e4 73 c2 33
! Skey inputs (PSK+NI+NR)
! ni:   e5 38 ae 8a  56 fd 0c 2d  1e a5 80 30  18 56 e2 4b
! nr:   43 f9 a4 16  78 2a f2 97  3a 58 c7 0a  3d 78 75 a2
! keyid:   c0 22 9a 7d  55 e4 cd bf  3e 05 da de  c5 6d 8d 2d
! NSS: end of key computation 
! DH_i:  03 e2 a3 ee  c8 97 40 94  66 99 f7 dd  f0 5f 5e 92
! DH_i:  82 50 c7 58  fb d0 96 ec  17 c1 68 ca  99 f8 f7 04
! DH_i:  4d 50 b0 20  9a bc 7b 07  4a 1a fe 7f  ce c1 87 89
! DH_i:  02 d2 b8 5c  8a 22 5a 58  19 e7 84 db  3d 2c 6c e8
! DH_i:  60 13 29 61  e4 b5 cf 18  43 04 72 ce  31 5e a7 1d
! DH_i:  cc c4 55 ac  4f a0 50 ae  16 98 52 fc  ff 47 5d b5
! DH_i:  bc ef e4 73  d5 b1 38 11  87 46 59 c1  49 32 b6 bb
! DH_i:  eb d2 f3 90  4f b6 da 93  1b 63 57 48  2b fa 15 a2
! DH_i:  87 5d 83 a0  b1 f5 d5 4d  16 06 6a eb  11 f7 e8 9e
! DH_i:  3f e0 d3 0a  1a 91 8c d2  d1 c2 ab 8b  8d 81 f8 b9
! DH_i:  4e 05 44 a0  c9 81 83 a9  97 27 5c cd  21 d6 f7 3b
! DH_i:  31 33 37 c9  c3 68 69 10  f9 81 29 15  a7 5d 20 b8
! DH_r:  1c c7 da 0f  b1 28 07 63  2a d5 a9 9a  39 0e be 86
! DH_r:  e5 01 fc 71  0f f7 06 62  e3 01 12 8b  d7 cb 14 dd
! DH_r:  c9 4b a9 e9  b8 43 5e bf  3b c9 53 2b  1b e1 99 44
! DH_r:  16 83 f3 56  77 e7 3b 40  a3 c8 4f 91  f2 39 6d 20
! DH_r:  a1 b2 25 16  a9 b8 8a a1  ab 33 68 16  83 5e 0a a1
! DH_r:  29 98 72 38  81 18 fd de  e5 b2 91 22  55 e8 23 88
! DH_r:  fb c5 da 32  8f 25 1c bd  54 e3 a9 eb  5f 2a 73 29
! DH_r:  86 c9 69 40  86 d4 73 1a  35 26 91 71  4f fb 04 89
! DH_r:  25 7d 62 4e  81 bb b0 04  a0 80 b3 00  23 13 78 0b
! DH_r:  6c ce a8 2b  70 8b df 14  55 2a 0d cc  91 cc 76 f6
! DH_r:  8b ff 9e 6b  da 93 18 9c  f9 63 06 77  32 5a 36 6c
! DH_r:  c6 31 8a 18  09 08 b8 a0  fc f1 e9 a4  e9 f7 58 22
! end of IV generation 
! Skeyid:    c0 22 9a 7d  55 e4 cd bf  3e 05 da de  c5 6d 8d 2d
! Skeyid_d:  d8 c5 bd 80  39 1e 25 5f  08 32 45 ed  1a 1e 11 fc
! Skeyid_a:  fb 35 05 8e  67 c9 c6 99  bf d9 c5 ec  f8 1d 31 f6
! Skeyid_e:  5f 68 c3 a2  42 35 ea 20  2b 4c eb 7c  1e 31 f5 b2
! enc key:  65 6f ec 8a  d7 a3 c4 27  60 85 57 c6  04 0d 33 4a
! enc key:  e9 a7 34 87  96 22 56 af
! IV:  b4 e6 46 52  32 fe 8a 15  36 8b d6 1b  cf 7a d4 9e
|  
| helper 0 has finished work (cnt now 1)
| helper 0 replies to id: q#24
| 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 #12
| next event EVENT_RETRANSMIT in 10 seconds for #12
|  
| *received 60 bytes from 10.29.3.225:500 on eth2 (port=500)
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   05 10 02 01  00 00 00 00  00 00 00 3c  fb 5d bb f1
|   82 e3 92 76  74 87 b7 eb  d8 d6 81 81  e4 ee b5 1e
|   c6 19 20 a0  f7 4c 41 88  b8 20 75 2e
| **parse ISAKMP Message:
|    initiator cookie:
|   54 e4 4d cf  e8 bd 58 56
|    responder cookie:
|   c1 4c bd 20  82 fc 1e e8
|    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: 60
|  processing version=1.0 packet with exchange type=ISAKMP_XCHG_IDPROT (2)
| ICOOKIE:  54 e4 4d cf  e8 bd 58 56
| RCOOKIE:  c1 4c bd 20  82 fc 1e e8
| state hash entry 31
| v1 peer and cookies match on #12, provided msgid 00000000 vs 00000000
| v1 state object #12 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 32 bytes using algorithm OAKLEY_3DES_CBC
| decrypted:
|   08 00 00 0c  01 00 00 00  0a 1d 03 e1  00 00 00 14
|   11 5a ec aa  c8 bf c6 4d  58 77 ea ac  2a 81 27 c9
| next IV:  f7 4c 41 88  b8 20 75 2e
| 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 14  11 5a ec aa
| got payload 0x100(ISAKMP_NEXT_HASH) needed: 0x100 opt: 0x2080
| ***parse ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_NONE
|    length: 20
"site_192.168.1.0_24-192.168.123.0_24" #12: 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=0x17bd00 (line=1)
| concluding with best_match=12 best=0x17bd00 (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=0x17bd00 (line=1)
| concluding with best_match=12 best=0x17bd00 (lineno=1)
| offered CA: '%none'
| hashing 48 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:
|   54 e4 4d cf  e8 bd 58 56
|    responder cookie:
|   c1 4c bd 20  82 fc 1e e8
|    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 48 bytes of SA
| ***emit ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_VID
| emitting 16 raw bytes of HASH_R into ISAKMP Hash Payload
| HASH_R  56 67 9c eb  3e 09 3f 87  11 d0 66 e4  24 6e a2 8f
| emitting length of ISAKMP Hash Payload: 20
| 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 14
|   56 67 9c eb  3e 09 3f 87  11 d0 66 e4  24 6e a2 8f
|   00 00 00 09  49 4b 45 76  32
| IV:
|   f7 4c 41 88  b8 20 75 2e
| unpadded size is: 41
| emitting 7 zero bytes of encryption padding into ISAKMP Message
| encrypting 48 using OAKLEY_3DES_CBC
| next IV:  71 a5 d0 d0  8d f4 ce 5d
| emitting length of ISAKMP Message: 76
| last encrypted block of Phase 1:
|   71 a5 d0 d0  8d f4 ce 5d
| complete state transition with STF_OK
"site_192.168.1.0_24-192.168.123.0_24" #12: transition from state STATE_MAIN_R2 to state STATE_MAIN_R3
| deleting event for #12
| 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 #12)
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   05 10 02 01  00 00 00 00  00 00 00 4c  a0 64 47 b5
|   dd 90 09 ee  52 09 88 ab  7d 42 80 27  57 20 13 da
|   b3 0d 89 5f  28 42 36 86  e9 24 0c 11  e7 f1 e8 00
|   46 32 7d e2  71 a5 d0 d0  8d f4 ce 5d
| inserting event EVENT_SA_EXPIRE, timeout in 28800 seconds for #12
| event added after event EVENT_LOG_DAILY
"site_192.168.1.0_24-192.168.123.0_24" #12: STATE_MAIN_R3: sent MR3, ISAKMP SA established {auth=OAKLEY_PRESHARED_KEY cipher=oakley_3des_cbc_192 prf=oakley_md5 group=modp1536}
| ICOOKIE:  54 e4 4d cf  e8 bd 58 56
| RCOOKIE:  c1 4c bd 20  82 fc 1e e8
| state hash entry 31
| v1 peer and cookies match on #12, provided msgid 00000000 vs 00000000
| v1 state object #12 found, in STATE_MAIN_R3
"site_192.168.1.0_24-192.168.123.0_24" #12: Dead Peer Detection (RFC 3706): enabled
| state: 12 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 #12
| 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 #12
| next event EVENT_DPD in 10 seconds for #12
|  
| *received 356 bytes from 10.29.3.225:500 on eth2 (port=500)
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   08 10 20 01  e0 33 b6 f9  00 00 01 64  cc 52 4c 8d
|   10 3f 46 0f  6f da c4 a3  c3 a3 82 d8  2c 44 a0 4e
|   37 86 ea ef  45 31 b7 b5  8b b1 0a b8  b9 c9 e2 f0
|   25 73 5e 70  f3 e0 d4 9b  64 d2 e1 95  b7 7f 58 42
|   50 de b5 d3  97 ed f5 34  ea c2 69 d1  dc 99 fd 19
|   66 e8 4a 8d  85 97 3b e0  5e 2b 18 35  87 68 25 8c
|   02 73 32 96  1a aa 7a 64  84 84 32 cb  a1 84 2c ff
|   3a c9 75 a2  ba aa 5d 79  23 c1 68 a7  af 67 b7 21
|   93 b7 84 5f  7a c5 ab f3  85 f0 a4 a5  f1 21 3e ef
|   0b 4b 93 f9  4e 16 9b f8  e5 d1 b3 ef  4b de 9b 67
|   51 2d 15 e2  d1 cf c2 bc  70 cd 9e e6  6c cb 57 8a
|   56 73 8e bc  05 9e 10 d4  47 0a e5 fd  37 36 45 43
|   44 3e a2 6f  d7 45 33 81  91 ac 76 bd  02 c9 3c a2
|   e6 88 c9 43  87 32 58 0d  a5 1c ff f2  f7 b8 e4 c7
|   c1 3d 12 ee  52 bd 21 18  10 f0 cf 30  bd 65 1b 31
|   89 eb 45 8b  0a 08 2d 42  2d 61 c3 6d  ac 75 f0 e3
|   e9 58 97 8a  1a 06 1b db  c6 e1 d4 17  8e 39 56 0c
|   83 54 ed ff  b7 55 6c 16  c4 c2 06 4a  6c 99 91 27
|   23 ae 89 68  80 1e 14 54  9c e9 e2 35  c1 52 88 82
|   bf 13 4f bf  ec f1 aa 85  bc ca 7c 8d  76 84 a4 58
|   da 7e 5d f3  cd 77 f0 43  89 50 fb ee  2a ee 04 64
|   9b f2 68 08
| **parse ISAKMP Message:
|    initiator cookie:
|   54 e4 4d cf  e8 bd 58 56
|    responder cookie:
|   c1 4c bd 20  82 fc 1e e8
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_QUICK
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  e0 33 b6 f9
|    length: 356
|  processing version=1.0 packet with exchange type=ISAKMP_XCHG_QUICK (32)
| ICOOKIE:  54 e4 4d cf  e8 bd 58 56
| RCOOKIE:  c1 4c bd 20  82 fc 1e e8
| state hash entry 31
| v1 peer and cookies match on #12, provided msgid e033b6f9 vs 00000000
| v1 state object not found
| ICOOKIE:  54 e4 4d cf  e8 bd 58 56
| RCOOKIE:  c1 4c bd 20  82 fc 1e e8
| state hash entry 31
| v1 peer and cookies match on #12, provided msgid 00000000 vs 00000000
| v1 state object #12 found, in STATE_MAIN_R3
| processing connection site_192.168.1.0_24-192.168.123.0_24
| last Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| current Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| computed Phase 2 IV:
|   aa b6 54 69  80 13 21 10  ad 72 ab 7e  b1 6f a8 e6
| received encrypted packet from 10.29.3.225:500
| decrypting 328 bytes using algorithm OAKLEY_3DES_CBC
| decrypted:
|   01 00 00 14  7d 2a 85 6f  f3 b6 82 73  75 45 67 78
|   53 81 3a 5c  0a 00 00 3c  00 00 00 01  00 00 00 01
|   00 00 00 30  00 03 04 01  84 46 2a 0d  00 00 00 24
|   00 0c 00 00  80 03 00 05  80 04 00 01  80 01 00 01
|   00 02 00 04  00 01 51 80  80 05 00 01  80 06 01 00
|   04 00 00 14  5b 29 55 21  c7 45 25 f7  c7 b0 54 7f
|   75 a6 a7 ba  05 00 00 c4  82 02 db c3  9c 9e cf 85
|   25 a1 83 8f  67 79 07 f4  c5 3d 4a 78  d9 69 85 9b
|   86 91 88 61  1e a5 0c e5  d2 e5 20 03  6a 2d 1f 31
|   86 83 32 ef  b2 3f f5 3f  c4 87 1b 04  df 43 dd 60
|   8a 60 db 2c  75 7e 6c 78  c3 e0 85 75  38 e6 9a c6
|   3e 7f 7e 1e  17 b2 0c 2b  09 0a ab 8b  74 2d 2d 28
|   c6 73 62 19  ad 86 e6 b1  08 6d a2 48  d2 9f 96 61
|   46 71 95 e3  56 ad dd 45  be 68 ef de  f5 32 19 b8
|   c1 45 1f 0d  d7 ec d6 fc  d2 0d 2a 92  71 bb a7 f8
|   20 ca 0a 1c  be 64 8b cd  5b 38 cd 8f  38 f6 fd 71
|   06 72 25 a6  01 da 7a b0  39 58 bb 47  09 d4 a1 a8
|   ca f8 8e 88  6b 61 56 b5  f3 64 6b be  aa f3 d4 80
|   fd de 4d a3  71 88 d0 bd  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
| next IV:  2a ee 04 64  9b f2 68 08
| got payload 0x100(ISAKMP_NEXT_HASH) needed: 0x502 opt: 0x200030
| ***parse ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_SA
|    length: 20
| got payload 0x2(ISAKMP_NEXT_SA) needed: 0x402 opt: 0x200030
| ***parse ISAKMP Security Association Payload:
|    next payload type: ISAKMP_NEXT_NONCE
|    length: 60
|    DOI: ISAKMP_DOI_IPSEC
| got payload 0x400(ISAKMP_NEXT_NONCE) needed: 0x400 opt: 0x200030
| ***parse ISAKMP Nonce Payload:
|    next payload type: ISAKMP_NEXT_KE
|    length: 20
| got payload 0x10(ISAKMP_NEXT_KE) needed: 0x0 opt: 0x200030
| ***parse ISAKMP Key Exchange Payload:
|    next payload type: ISAKMP_NEXT_ID
|    length: 196
| got payload 0x20(ISAKMP_NEXT_ID) needed: 0x0 opt: 0x200030
| ***parse ISAKMP Identification Payload (IPsec DOI):
|    next payload type: ISAKMP_NEXT_ID
|    length: 16
|    ID type: ID_IPV4_ADDR_SUBNET
|    Protocol ID: 0
|    port: 0
|      obj:   c0 a8 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 01 69  54 e4 4d cf
| HASH(1) computed:
|   7d 2a 85 6f  f3 b6 82 73  75 45 67 78  53 81 3a 5c
| 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" #12: 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 #12
| creating state object #13 at 0x182330
| processing connection site_192.168.1.0_24-192.168.123.0_24
| ICOOKIE:  54 e4 4d cf  e8 bd 58 56
| RCOOKIE:  c1 4c bd 20  82 fc 1e e8
| state hash entry 31
| inserting state object #13 on chain 31
| inserting event EVENT_SO_DISCARD, timeout in 0 seconds for #13
| 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 0d
| *****parse ISAKMP Transform Payload (ESP):
|    next payload type: ISAKMP_NEXT_NONE
|    length: 36
|    transform number: 0
|    transform ID: ESP_AES
| ******parse ISAKMP IPsec DOI attribute:
|    af+type: GROUP_DESCRIPTION
|    length/value: 5
|    [5 is OAKLEY_GROUP_MODP1536]
| ******parse ISAKMP IPsec DOI attribute:
|    af+type: ENCAPSULATION_MODE
|    length/value: 1
|    [1 is ENCAPSULATION_MODE_TUNNEL]
| ******parse ISAKMP IPsec DOI attribute:
|    af+type: SA_LIFE_TYPE
|    length/value: 1
|    [1 is SA_LIFE_TYPE_SECONDS]
| ******parse ISAKMP IPsec DOI attribute:
|    af+type: SA_LIFE_DURATION (variable length)
|    length/value: 4
|    long duration: 86400
| ******parse ISAKMP IPsec DOI attribute:
|    af+type: AUTH_ALGORITHM
|    length/value: 1
|    [1 is AUTH_ALGORITHM_HMAC_MD5]
| ******parse ISAKMP IPsec DOI attribute:
|    af+type: KEY_LENGTH
|    length/value: 256
| kernel_alg_esp_enc_ok(12,256): alg_id=12, alg_ivlen=128, alg_minbits=128, alg_maxbits=256, res=0, ret=1
| DH public value received:
|   82 02 db c3  9c 9e cf 85  25 a1 83 8f  67 79 07 f4
|   c5 3d 4a 78  d9 69 85 9b  86 91 88 61  1e a5 0c e5
|   d2 e5 20 03  6a 2d 1f 31  86 83 32 ef  b2 3f f5 3f
|   c4 87 1b 04  df 43 dd 60  8a 60 db 2c  75 7e 6c 78
|   c3 e0 85 75  38 e6 9a c6  3e 7f 7e 1e  17 b2 0c 2b
|   09 0a ab 8b  74 2d 2d 28  c6 73 62 19  ad 86 e6 b1
|   08 6d a2 48  d2 9f 96 61  46 71 95 e3  56 ad dd 45
|   be 68 ef de  f5 32 19 b8  c1 45 1f 0d  d7 ec d6 fc
|   d2 0d 2a 92  71 bb a7 f8  20 ca 0a 1c  be 64 8b cd
|   5b 38 cd 8f  38 f6 fd 71  06 72 25 a6  01 da 7a b0
|   39 58 bb 47  09 d4 a1 a8  ca f8 8e 88  6b 61 56 b5
|   f3 64 6b be  aa f3 d4 80  fd de 4d a3  71 88 d0 bd
| 0: w->pcw_dead: 0 w->pcw_work: 0 cnt: 1
| asking helper 0 to do build_kenonce op on seq: 25 (len=2668, pcw_work=1)
| crypto helper write of request: cnt=2668<wlen=2668.  
| deleting event for #13
| inserting event EVENT_CRYPTO_FAILED, timeout in 300 seconds for #13
| event added after event EVENT_PENDING_PHASE2
| complete state transition with STF_SUSPEND
| * processed 0 messages from cryptographic helpers 
| next event EVENT_DPD in 10 seconds for #12
| next event EVENT_DPD in 10 seconds for #12
! helper 0 read 2664+4/2668 bytesfd: 7
! helper 0 doing build_kenonce op id: 25
! Local DH secret:
!   2b c3 67 92  86 fd 8e cf  a3 14 0f ea  a1 2b b0 29
!   f8 cd 39 03  86 f2 f6 84  72 f2 d5 19  04 0d 2b 9d
! Public DH value sent:
!   a2 6d a1 3d  27 dd 77 8c  29 3e d1 c4  a7 13 1b d7
!   b9 5d 13 3a  bd cf 1a 29  ad e0 f3 cd  7f 6f d2 37
!   75 96 55 a2  60 60 66 1f  6c 35 5f 3e  d0 03 4f e6
!   99 03 3d 92  ca fd 97 2b  bb d7 37 19  58 e9 2d 2a
!   ed 9a 85 4e  f9 a8 10 0c  0c 98 56 ec  9f a1 d3 8e
!   4f 19 34 be  81 41 d4 cc  59 b5 71 8c  75 33 da 56
!   75 de 8b cd  1d 3d 20 49  ac 05 9c 62  07 ab 75 27
!   09 52 2c 97  17 5f 25 41  bd 18 1f d2  e2 24 94 77
!   7b e5 cb 72  96 33 d5 99  0c bc aa 8f  80 6f 51 2b
!   3c a7 c2 58  3b 19 b5 cd  66 1d 72 ff  4e 9c 06 94
!   31 7b 63 20  1d a9 8b 73  7d 06 da 6c  79 8c e9 39
!   2e c7 70 1d  e2 a0 1f 6a  a4 f8 c4 76  fb 1e 23 87
! Generated nonce:
!   13 a9 cf 11  c0 a2 71 c3  7a b8 88 39  89 ab ce f7
|  
| helper 0 has finished work (cnt now 1)
| helper 0 replies to id: q#25
| 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=0x17bd00 (line=1)
| concluding with best_match=12 best=0x17bd00 (lineno=1)
| 0: w->pcw_dead: 0 w->pcw_work: 0 cnt: 1
| asking helper 0 to do compute dh(p2) op on seq: 26 (len=2668, pcw_work=1)
| crypto helper write of request: cnt=2668<wlen=2668.  
| deleting event for #13
| inserting event EVENT_CRYPTO_FAILED, timeout in 300 seconds for #13
| event added after event EVENT_PENDING_PHASE2
| * processed 1 messages from cryptographic helpers 
| next event EVENT_DPD in 10 seconds for #12
| next event EVENT_DPD in 10 seconds for #12
! helper 0 read 2664+4/2668 bytesfd: 7
! helper 0 doing compute dh(p2) op id: 26
! peer's g:   82 02 db c3  9c 9e cf 85  25 a1 83 8f  67 79 07 f4
! peer's g:   c5 3d 4a 78  d9 69 85 9b  86 91 88 61  1e a5 0c e5
! peer's g:   d2 e5 20 03  6a 2d 1f 31  86 83 32 ef  b2 3f f5 3f
! peer's g:   c4 87 1b 04  df 43 dd 60  8a 60 db 2c  75 7e 6c 78
! peer's g:   c3 e0 85 75  38 e6 9a c6  3e 7f 7e 1e  17 b2 0c 2b
! peer's g:   09 0a ab 8b  74 2d 2d 28  c6 73 62 19  ad 86 e6 b1
! peer's g:   08 6d a2 48  d2 9f 96 61  46 71 95 e3  56 ad dd 45
! peer's g:   be 68 ef de  f5 32 19 b8  c1 45 1f 0d  d7 ec d6 fc
! peer's g:   d2 0d 2a 92  71 bb a7 f8  20 ca 0a 1c  be 64 8b cd
! peer's g:   5b 38 cd 8f  38 f6 fd 71  06 72 25 a6  01 da 7a b0
! peer's g:   39 58 bb 47  09 d4 a1 a8  ca f8 8e 88  6b 61 56 b5
! peer's g:   f3 64 6b be  aa f3 d4 80  fd de 4d a3  71 88 d0 bd
! calc_dh_shared(): time elapsed (OAKLEY_GROUP_MODP1536): 27328 usec
! DH shared-secret:
!   43 bf bc a2  97 59 98 36  70 ea d3 63  e8 f2 d8 8f
!   60 c2 19 3c  50 68 24 d0  b9 b5 4b c3  64 48 bb 26
!   4a ba 54 bb  ce 27 54 b5  6c 64 e7 d5  cb 00 85 24
!   9c 97 60 2a  02 8b af f7  22 86 24 27  c4 7f e7 3c
!   c9 5e 2a c7  09 30 c6 91  6f 28 1a 13  59 61 98 47
!   cb ea 0f 7f  02 2e 21 8b  6c 8b 55 18  75 28 03 ef
!   08 91 26 9f  08 74 12 69  6d 92 7f 87  25 94 c7 76
!   d1 4b a9 44  03 2a 94 ac  85 41 a2 7f  cc b1 1e 44
!   07 e0 d9 12  b2 e6 f4 c8  66 f5 8b 07  eb 79 2c 4a
!   4a a8 01 0b  21 94 53 9c  e7 ff ff 9f  f9 17 bb a9
!   39 02 2a d7  3e 3e 9d 8c  5e 41 b9 ac  fd 10 b1 1d
!   7b ee 3e e0  e3 b8 eb eb  58 93 3d bb  0e 75 f4 3e
|  
| helper 0 has finished work (cnt now 1)
| helper 0 replies to id: q#26
| 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:
|   54 e4 4d cf  e8 bd 58 56
|    responder cookie:
|   c1 4c bd 20  82 fc 1e e8
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_QUICK
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  e0 33 b6 f9
| ***emit ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_SA
| emitting 16 zero bytes of HASH into ISAKMP Hash Payload
| emitting length of ISAKMP Hash Payload: 20
| ***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 0d
| *****parse ISAKMP Transform Payload (ESP):
|    next payload type: ISAKMP_NEXT_NONE
|    length: 36
|    transform number: 0
|    transform ID: ESP_AES
| ******parse ISAKMP IPsec DOI attribute:
|    af+type: GROUP_DESCRIPTION
|    length/value: 5
|    [5 is OAKLEY_GROUP_MODP1536]
| ******parse ISAKMP IPsec DOI attribute:
|    af+type: ENCAPSULATION_MODE
|    length/value: 1
|    [1 is ENCAPSULATION_MODE_TUNNEL]
| ******parse ISAKMP IPsec DOI attribute:
|    af+type: SA_LIFE_TYPE
|    length/value: 1
|    [1 is SA_LIFE_TYPE_SECONDS]
| ******parse ISAKMP IPsec DOI attribute:
|    af+type: SA_LIFE_DURATION (variable length)
|    length/value: 4
|    long duration: 86400
| ******parse ISAKMP IPsec DOI attribute:
|    af+type: AUTH_ALGORITHM
|    length/value: 1
|    [1 is AUTH_ALGORITHM_HMAC_MD5]
| ******parse ISAKMP IPsec DOI attribute:
|    af+type: KEY_LENGTH
|    length/value: 256
| kernel_alg_esp_enc_ok(12,256): alg_id=12, alg_ivlen=128, alg_minbits=128, alg_maxbits=256, res=0, ret=1
| ****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 41
| emitting 4 raw bytes of SPI into ISAKMP Proposal Payload
| SPI  23 90 0d 41
| *****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 05  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" #13: responding to Quick Mode proposal {msgid:e033b6f9}
"site_192.168.1.0_24-192.168.123.0_24" #13:     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" #13:   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  13 a9 cf 11  c0 a2 71 c3  7a b8 88 39  89 ab ce f7
| emitting length of ISAKMP Nonce Payload: 20
| ***emit ISAKMP Key Exchange Payload:
|    next payload type: ISAKMP_NEXT_ID
| emitting 192 raw bytes of keyex value into ISAKMP Key Exchange Payload
| keyex value  a2 6d a1 3d  27 dd 77 8c  29 3e d1 c4  a7 13 1b d7
| keyex value  b9 5d 13 3a  bd cf 1a 29  ad e0 f3 cd  7f 6f d2 37
| keyex value  75 96 55 a2  60 60 66 1f  6c 35 5f 3e  d0 03 4f e6
| keyex value  99 03 3d 92  ca fd 97 2b  bb d7 37 19  58 e9 2d 2a
| keyex value  ed 9a 85 4e  f9 a8 10 0c  0c 98 56 ec  9f a1 d3 8e
| keyex value  4f 19 34 be  81 41 d4 cc  59 b5 71 8c  75 33 da 56
| keyex value  75 de 8b cd  1d 3d 20 49  ac 05 9c 62  07 ab 75 27
| keyex value  09 52 2c 97  17 5f 25 41  bd 18 1f d2  e2 24 94 77
| keyex value  7b e5 cb 72  96 33 d5 99  0c bc aa 8f  80 6f 51 2b
| keyex value  3c a7 c2 58  3b 19 b5 cd  66 1d 72 ff  4e 9c 06 94
| keyex value  31 7b 63 20  1d a9 8b 73  7d 06 da 6c  79 8c e9 39
| keyex value  2e c7 70 1d  e2 a0 1f 6a  a4 f8 c4 76  fb 1e 23 87
| emitting length of ISAKMP Key Exchange Payload: 196
| 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:
|   3f 92 cc 55  dd ba 09 e6  26 bf 94 2e  2b ca 45 b8
| compute_proto_keymat:needed_len (after ESP enc)=32
| compute_proto_keymat:needed_len (after ESP auth)=48
| ESP KEYMAT 
|   KEYMAT computed:
|   a0 1a 98 d2  2f 23 57 bf  d8 c0 3e c9  55 7e 6b bd
|   a9 4e ab 5b  4a 9a 5b 32  fd 98 d3 cb  74 68 83 5a
|   0c c1 2b 0c  17 4a 1c 22  d5 20 52 c8  2f 15 67 bf
|   Peer KEYMAT computed:
|   23 de ad 5d  d7 81 27 1b  55 d2 70 38  1c a9 57 c6
|   60 e7 28 13  09 e8 6b 68  b3 29 b6 34  fa 72 a8 32
|   0d 22 4e be  c0 01 d0 a9  83 00 75 eb  49 63 67 7d
| 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=0x182330 ost=(nil) st->serialno=#13 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=0p0x17be90. 
| pfkey_lib_debug:pfkey_sa_build: spi=0000100b 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=0p0x182c38 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=65, 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 0p0x182c48 with parser pfkey_sa_parse. 
| pfkey_lib_debug:pfkey_sa_parse: successfully found len=3 exttype=1(security-association) spi=0000100b 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 0p0x182c60 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 0p0x182c78 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 65 for Add SA tun.100b@10.29.3.225
|   02 03 00 09  00 0b 00 00  00 00 00 41  00 00 06 09
|   00 03 00 01  00 00 10 0b  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 65
| 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=65, 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=0000100b 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=78
| recorded ref=78 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:  23 de ad 5d  d7 81 27 1b  55 d2 70 38  1c a9 57 c6
| esp enckey:  60 e7 28 13  09 e8 6b 68  b3 29 b6 34  fa 72 a8 32
| esp authkey:  0d 22 4e be  c0 01 d0 a9  83 00 75 eb  49 63 67 7d
| 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=0p0x17be90. 
| pfkey_lib_debug:pfkey_sa_build: spi=84462a0d 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=0p0x182c88 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=66, 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 0p0x182c98 with parser pfkey_sa_parse. 
| pfkey_lib_debug:pfkey_sa_parse: successfully found len=3 exttype=1(security-association) spi=84462a0d 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 0p0x182cb0 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 0p0x182cc8 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 0p0x182ce0 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 0p0x182cf8 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 66 for Add SA esp.84462a0d@10.29.3.225
|   02 03 00 03  00 13 00 00  00 00 00 42  00 00 06 09
|   00 03 00 01  84 46 2a 0d  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
|   0d 22 4e be  c0 01 d0 a9  83 00 75 eb  49 63 67 7d
|   00 05 00 09  01 00 00 00  23 de ad 5d  d7 81 27 1b
|   55 d2 70 38  1c a9 57 c6  60 e7 28 13  09 e8 6b 68
|   b3 29 b6 34  fa 72 a8 32
| pfkey_get: K_SADB_ADD message 66
| 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=66, 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=84462a0d 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.100b@10.29.3.225 (ref=78) and unk0.84462a0d@10.29.3.225 (ref=79)
| 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=0p0x17be90. 
| pfkey_lib_debug:pfkey_sa_build: spi=0000100b 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=84462a0d 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=0p0x182c58 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=67, 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 0p0x182c68 with parser pfkey_sa_parse. 
| pfkey_lib_debug:pfkey_sa_parse: successfully found len=3 exttype=1(security-association) spi=0000100b 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 0p0x182c80 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 0p0x182c98 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 0p0x182ca0 with parser pfkey_sa_parse. 
| pfkey_lib_debug:pfkey_sa_parse: successfully found len=3 exttype=19(X-security-association) spi=84462a0d 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 0p0x182cb8 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 67 for group unk0.100b@10.29.3.225
|   02 0d 00 09  00 0f 00 00  00 00 00 43  00 00 06 09
|   00 03 00 01  00 00 10 0b  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 0d
|   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 67
| 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=78
| 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=0p0x17be90. 
| pfkey_lib_debug:pfkey_sa_build: spi=0000100c 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=0p0x182c38 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=68, 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 0p0x182c48 with parser pfkey_sa_parse. 
| pfkey_lib_debug:pfkey_sa_parse: successfully found len=3 exttype=1(security-association) spi=0000100c 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 0p0x182c60 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 0p0x182c78 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 0p0x182c90 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 68 for Add SA tun.100c@10.29.3.225
|   02 03 00 09  00 0d 00 00  00 00 00 44  00 00 06 09
|   00 03 00 01  00 00 10 0c  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 4e  40 16 98 c4
| pfkey_get: K_SADB_ADD message 68
| 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=68, 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=0000100c 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=80
| 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:  a0 1a 98 d2  2f 23 57 bf  d8 c0 3e c9  55 7e 6b bd
| esp enckey:  a9 4e ab 5b  4a 9a 5b 32  fd 98 d3 cb  74 68 83 5a
| esp authkey:  0c c1 2b 0c  17 4a 1c 22  d5 20 52 c8  2f 15 67 bf
| 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=0p0x17be90. 
| pfkey_lib_debug:pfkey_sa_build: spi=23900d41 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=0p0x182c88 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=69, 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 0p0x182c98 with parser pfkey_sa_parse. 
| pfkey_lib_debug:pfkey_sa_parse: successfully found len=3 exttype=1(security-association) spi=23900d41 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 0p0x182cb0 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 0p0x182cc8 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 0p0x182ce0 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 0p0x182cf8 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 0p0x182d20 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 69 for Add SA esp.23900d41@10.29.3.195
|   02 03 00 03  00 15 00 00  00 00 00 45  00 00 06 09
|   00 03 00 01  23 90 0d 41  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
|   0c c1 2b 0c  17 4a 1c 22  d5 20 52 c8  2f 15 67 bf
|   00 05 00 09  01 00 00 00  a0 1a 98 d2  2f 23 57 bf
|   d8 c0 3e c9  55 7e 6b bd  a9 4e ab 5b  4a 9a 5b 32
|   fd 98 d3 cb  74 68 83 5a  00 02 00 20  00 00 00 00
|   00 00 00 4e  00 00 00 00
| pfkey_get: K_SADB_ADD message 69
| 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=69, 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=23900d41 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.100c@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=0p0x17be90. 
| pfkey_lib_debug:pfkey_sa_build: spi=0000100c 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=0p0x182cb8 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=70, 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 0p0x182cc8 with parser pfkey_sa_parse. 
| pfkey_lib_debug:pfkey_sa_parse: successfully found len=3 exttype=1(security-association) spi=0000100c 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 0p0x182ce0 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 0p0x182cf8 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 0p0x182d10 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 0p0x182d28 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 0p0x182d40 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 0p0x182d58 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 70 for flow tun.100c@10.29.3.195
|   02 0e 00 09  00 17 00 00  00 00 00 46  00 00 06 09
|   00 03 00 01  00 00 10 0c  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 70
| 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.100c@10.29.3.195 (ref=80) and unk0.23900d41@10.29.3.195 (ref=81)
| 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=0p0x17be90. 
| pfkey_lib_debug:pfkey_sa_build: spi=0000100c 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=23900d41 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=0p0x182c58 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=71, 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 0p0x182c68 with parser pfkey_sa_parse. 
| pfkey_lib_debug:pfkey_sa_parse: successfully found len=3 exttype=1(security-association) spi=0000100c 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 0p0x182c80 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 0p0x182c98 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 0p0x182ca0 with parser pfkey_sa_parse. 
| pfkey_lib_debug:pfkey_sa_parse: successfully found len=3 exttype=19(X-security-association) spi=23900d41 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 0p0x182cb8 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 71 for group unk0.100c@10.29.3.195
|   02 0d 00 09  00 0f 00 00  00 00 00 47  00 00 06 09
|   00 03 00 01  00 00 10 0c  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 41
|   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 71
| 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 14  3f 92 cc 55  dd ba 09 e6  26 bf 94 2e
|   2b ca 45 b8  0a 00 00 3c  00 00 00 01  00 00 00 01
|   00 00 00 30  00 03 04 01  23 90 0d 41  00 00 00 24
|   00 0c 00 00  80 03 00 05  80 04 00 01  80 01 00 01
|   00 02 00 04  00 01 51 80  80 05 00 01  80 06 01 00
|   04 00 00 14  13 a9 cf 11  c0 a2 71 c3  7a b8 88 39
|   89 ab ce f7  05 00 00 c4  a2 6d a1 3d  27 dd 77 8c
|   29 3e d1 c4  a7 13 1b d7  b9 5d 13 3a  bd cf 1a 29
|   ad e0 f3 cd  7f 6f d2 37  75 96 55 a2  60 60 66 1f
|   6c 35 5f 3e  d0 03 4f e6  99 03 3d 92  ca fd 97 2b
|   bb d7 37 19  58 e9 2d 2a  ed 9a 85 4e  f9 a8 10 0c
|   0c 98 56 ec  9f a1 d3 8e  4f 19 34 be  81 41 d4 cc
|   59 b5 71 8c  75 33 da 56  75 de 8b cd  1d 3d 20 49
|   ac 05 9c 62  07 ab 75 27  09 52 2c 97  17 5f 25 41
|   bd 18 1f d2  e2 24 94 77  7b e5 cb 72  96 33 d5 99
|   0c bc aa 8f  80 6f 51 2b  3c a7 c2 58  3b 19 b5 cd
|   66 1d 72 ff  4e 9c 06 94  31 7b 63 20  1d a9 8b 73
|   7d 06 da 6c  79 8c e9 39  2e c7 70 1d  e2 a0 1f 6a
|   a4 f8 c4 76  fb 1e 23 87  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:
|   2a ee 04 64  9b f2 68 08
| unpadded size is: 328
| encrypting 328 using OAKLEY_3DES_CBC
| next IV:  a3 a6 04 9f  a5 9d 1c 65
| emitting length of ISAKMP Message: 356
| finished processing quick inI1
| complete state transition with STF_OK
"site_192.168.1.0_24-192.168.123.0_24" #13: transition from state STATE_QUICK_R0 to state STATE_QUICK_R1
| deleting event for #13
| sending reply packet to 10.29.3.225:500 (from port 500)
| sending 356 bytes for STATE_QUICK_R0 through eth2:500 to 10.29.3.225:500 (using #13)
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   08 10 20 01  e0 33 b6 f9  00 00 01 64  d2 99 27 15
|   a6 aa c8 a6  ea 78 04 16  a4 fa 0b 1d  b0 dc a9 da
|   1f db c3 e6  07 8c 7e e3  f1 a8 c9 af  e0 f5 aa 13
|   10 5f 23 10  50 9c 4e 71  60 95 8c f7  d5 44 3e 13
|   88 f1 70 03  d2 9d 69 c1  80 00 3b a5  5d 71 e2 60
|   6f f0 27 89  ce 68 fa 28  9e 6d f9 aa  9a 98 15 4c
|   9d c2 ca 6e  9e 2e 3b 91  ae 31 d9 24  35 8b c2 d2
|   93 fd 24 bf  44 e1 a5 53  dc 36 c7 97  1c 8c 51 e6
|   8e d4 0c 21  5e 42 fe 4b  24 e5 87 c8  b4 1a fe c8
|   81 7f f3 47  61 fc 97 23  79 ba 10 58  3b 92 53 ba
|   7a 47 9e 1f  f0 f3 1d 49  c4 18 e5 33  de dd 4c 55
|   04 ec e3 a2  cd 73 4d ce  89 eb e7 c3  63 5e a4 7a
|   4c 00 41 05  d8 b6 30 82  bf 01 f8 fd  3e cf 2b ef
|   08 ce df 0a  e0 d3 91 35  04 fc 4b a0  22 6a 48 85
|   74 51 a1 86  f5 77 8b 79  8b 44 05 b2  62 fd 30 2f
|   a8 e5 37 85  24 04 dd 8b  3e 57 8b bf  00 ce f2 00
|   ad a2 6d 6c  2a 91 28 4c  4f 9e 40 af  25 55 4c 31
|   7a fb dd 79  2c bf c3 7d  93 fe f1 a4  70 7f 6f 88
|   14 32 e7 95  b6 d1 2c 86  38 47 69 22  4f df 30 50
|   7b aa ba fa  ce 79 d5 8e  de bf 48 fc  aa 56 24 ee
|   b9 93 a4 6c  d5 22 d2 90  3a 4c 2f 0c  a3 a6 04 9f
|   a5 9d 1c 65
| inserting event EVENT_RETRANSMIT, timeout in 10 seconds for #13
| event added at head of queue
"site_192.168.1.0_24-192.168.123.0_24" #13: 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 #13
| next event EVENT_RETRANSMIT in 10 seconds for #13
|  
| *received 52 bytes from 10.29.3.225:500 on eth2 (port=500)
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   08 10 20 01  e0 33 b6 f9  00 00 00 34  9c 7a 2e 5d
|   fb 4e f9 25  62 ec db 7a  6a f8 e7 50  10 22 b6 5d
|   06 a6 24 ce
| **parse ISAKMP Message:
|    initiator cookie:
|   54 e4 4d cf  e8 bd 58 56
|    responder cookie:
|   c1 4c bd 20  82 fc 1e e8
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_QUICK
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  e0 33 b6 f9
|    length: 52
|  processing version=1.0 packet with exchange type=ISAKMP_XCHG_QUICK (32)
| ICOOKIE:  54 e4 4d cf  e8 bd 58 56
| RCOOKIE:  c1 4c bd 20  82 fc 1e e8
| state hash entry 31
| v1 peer and cookies match on #13, provided msgid e033b6f9 vs e033b6f9
| v1 state object #13 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 24 bytes using algorithm OAKLEY_3DES_CBC
| decrypted:
|   00 00 00 14  dd 7f a3 95  c7 91 fc 8a  8a 85 de f8
|   08 13 8d 9f  00 00 00 00
| next IV:  10 22 b6 5d  06 a6 24 ce
| got payload 0x100(ISAKMP_NEXT_HASH) needed: 0x100 opt: 0x0
| ***parse ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_NONE
|    length: 20
| removing 4 bytes of padding
| HASH(3) computed:  dd 7f a3 95  c7 91 fc 8a  8a 85 de f8  08 13 8d 9f
| install_ipsec_sa() for #13: 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 #13: 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: 13
| eroute_connection add eroute 192.168.1.0/24:0 --0-> 192.168.123.0/24:0 => tun.100b@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=0p0x1829a8. 
| pfkey_lib_debug:pfkey_sa_build: spi=0000100b 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=0p0x182bd8 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=72, 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 0p0x182be8 with parser pfkey_sa_parse. 
| pfkey_lib_debug:pfkey_sa_parse: successfully found len=3 exttype=1(security-association) spi=0000100b 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 0p0x182c00 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 0p0x182c18 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 0p0x182c30 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 0p0x182c48 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 0p0x182c60 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 0p0x182c78 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 72 for flow tun.100b@10.29.3.225
|   02 0e 00 09  00 17 00 00  00 00 00 48  00 00 06 09
|   00 03 00 01  00 00 10 0b  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 72
| 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 #13 (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 #13 (was #0) (spd.eroute=#13)
| ICOOKIE:  54 e4 4d cf  e8 bd 58 56
| RCOOKIE:  c1 4c bd 20  82 fc 1e e8
| state hash entry 31
| v1 peer and cookies match on #13, provided msgid 00000000 vs e033b6f9
| v1 peer and cookies match on #12, provided msgid 00000000 vs 00000000
| v1 state object #12 found, in STATE_MAIN_R3
"site_192.168.1.0_24-192.168.123.0_24" #13: Dead Peer Detection (RFC 3706): enabled
| state: 13 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 #13
| event added at head of queue
| state: 12 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" #13: transition from state STATE_QUICK_R1 to state STATE_QUICK_R2
| deleting event for #13
| inserting event EVENT_SA_EXPIRE, timeout in 86400 seconds for #13
| event added after event EVENT_SA_EXPIRE for #12
"site_192.168.1.0_24-192.168.123.0_24" #13: STATE_QUICK_R2: IPsec SA established tunnel mode {ESP=>0x84462a0d <0x23900d41 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 #13
| next event EVENT_DPD in 10 seconds for #13
|  
| *received 84 bytes from 10.29.3.225:500 on eth2 (port=500)
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   08 10 05 01  98 99 29 8b  00 00 00 54  83 fc a2 8a
|   0d 00 90 d2  af 6f db 09  76 57 d2 90  ab 79 dd 2b
|   68 c4 1b d2  23 5f 6c e5  3e d5 ef e0  fc 92 d8 a5
|   87 54 c6 fb  67 ac 19 59  b3 b4 09 26  75 72 86 1a
|   36 95 cf 23
| **parse ISAKMP Message:
|    initiator cookie:
|   54 e4 4d cf  e8 bd 58 56
|    responder cookie:
|   c1 4c bd 20  82 fc 1e e8
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  98 99 29 8b
|    length: 84
|  processing version=1.0 packet with exchange type=ISAKMP_XCHG_INFO (5)
| ICOOKIE:  54 e4 4d cf  e8 bd 58 56
| RCOOKIE:  c1 4c bd 20  82 fc 1e e8
| state hash entry 31
| peer and cookies match on #13, provided msgid 00000000 vs e033b6f9/00000000
| peer and cookies match on #12, provided msgid 00000000 vs 00000000/00000000
| p15 state object #12 found, in STATE_MAIN_R3
| processing connection site_192.168.1.0_24-192.168.123.0_24
| last Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| current Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| computed Phase 2 IV:
|   16 cf 69 4e  cc 7a 0e 1f  2c d8 e7 c5  e2 f9 fd 9a
| received encrypted packet from 10.29.3.225:500
| decrypting 56 bytes using algorithm OAKLEY_3DES_CBC
| decrypted:
|   0b 00 00 14  e9 9f 5c c2  cc d5 19 02  48 ae e9 77
|   b3 54 5c f3  00 00 00 20  00 00 00 01  01 10 8d 28
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   00 00 33 9b  00 00 00 00
| next IV:  75 72 86 1a  36 95 cf 23
| got payload 0x100(ISAKMP_NEXT_HASH) needed: 0x100 opt: 0x0
| ***parse ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_N
|    length: 20
| 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 4 bytes of padding
| info:  54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
| info:  00 00 33 9b
| processing informational R_U_THERE (36136)
| **emit ISAKMP Message:
|    initiator cookie:
|   54 e4 4d cf  e8 bd 58 56
|    responder cookie:
|   c1 4c bd 20  82 fc 1e e8
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  a3 c2 ba 75
| ***emit ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_N
| emitting 16 zero bytes of HASH into ISAKMP Hash Payload
| emitting length of ISAKMP Hash Payload: 20
| ***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  54 e4 4d cf  e8 bd 58 56
| emitting 8 raw bytes of notify rcookie into ISAKMP Notification Payload
| notify rcookie  c1 4c bd 20  82 fc 1e e8
| emitting 4 raw bytes of notify data into ISAKMP Notification Payload
| notify data  00 00 33 9b
| emitting length of ISAKMP Notification Payload: 32
| HASH computed:
|   1f 84 18 87  94 75 15 44  c1 bd 00 ee  86 c3 9a bc
| last Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| current Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| computed Phase 2 IV:
|   87 49 19 81  34 ec 7c ae  1c 9f 20 d3  94 47 83 ce
| encrypting:
|   0b 00 00 14  1f 84 18 87  94 75 15 44  c1 bd 00 ee
|   86 c3 9a bc  00 00 00 20  00 00 00 01  01 10 8d 29
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   00 00 33 9b
| IV:
|   87 49 19 81  34 ec 7c ae  1c 9f 20 d3  94 47 83 ce
| unpadded size is: 52
| emitting 4 zero bytes of encryption padding into ISAKMP Message
| encrypting 56 using OAKLEY_3DES_CBC
| next IV:  07 76 d2 8b  9a b3 d0 60
| emitting length of ISAKMP Message: 84
| sending 84 bytes for ISAKMP notify through eth2:500 to 10.29.3.225:500 (using #12)
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   08 10 05 01  a3 c2 ba 75  00 00 00 54  50 f8 ab ff
|   37 4c f4 b2  d5 67 1d b1  5d f8 01 eb  ae 88 b7 b4
|   31 98 c7 f7  66 a4 de 36  7c 0a 40 a9  e6 03 39 a6
|   fb ea a6 5b  52 ea 76 26  31 a9 8c b7  07 76 d2 8b
|   9a b3 d0 60
| complete state transition with STF_IGNORE
| * processed 0 messages from cryptographic helpers 
| next event EVENT_DPD in 0 seconds for #13
| *time to handle event
| handling event EVENT_DPD
| event after this is EVENT_PENDING_DDNS in 5 seconds
| processing connection site_192.168.1.0_24-192.168.123.0_24
| inserting event EVENT_DPD, timeout in 10 seconds for #13
| event added after event EVENT_PENDING_PHASE2
| next event EVENT_PENDING_DDNS in 5 seconds
|  
| next event EVENT_PENDING_DDNS in 0 seconds
| *time to handle event
| handling event EVENT_PENDING_DDNS
| event after this is EVENT_SHUNT_SCAN in 0 seconds
| inserting event EVENT_PENDING_DDNS, timeout in 60 seconds
| event added after event EVENT_DPD for #13
| 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_DPD in 5 seconds
| inserting event EVENT_PENDING_PHASE2, timeout in 120 seconds
| event added after event EVENT_PENDING_DDNS
| pending review: connection "site_192.168.1.0_24-192.168.123.0_24" was not up, skipped
| next event EVENT_DPD in 5 seconds for #13
|  
| next event EVENT_DPD in 0 seconds for #13
| *time to handle event
| handling event EVENT_DPD
| event after this is EVENT_PENDING_DDNS in 55 seconds
| processing connection site_192.168.1.0_24-192.168.123.0_24
| inserting event EVENT_DPD, timeout in 10 seconds for #13
| event added at head of queue
| state: 12 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 #12
| event added after event EVENT_DPD for #13
| **emit ISAKMP Message:
|    initiator cookie:
|   54 e4 4d cf  e8 bd 58 56
|    responder cookie:
|   c1 4c bd 20  82 fc 1e e8
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  3b d8 f8 75
| ***emit ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_N
| emitting 16 zero bytes of HASH into ISAKMP Hash Payload
| emitting length of ISAKMP Hash Payload: 20
| ***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  54 e4 4d cf  e8 bd 58 56
| emitting 8 raw bytes of notify rcookie into ISAKMP Notification Payload
| notify rcookie  c1 4c bd 20  82 fc 1e e8
| emitting 4 raw bytes of notify data into ISAKMP Notification Payload
| notify data  00 00 4a ab
| emitting length of ISAKMP Notification Payload: 32
| HASH computed:
|   19 0b 96 33  ab a3 1d 4b  f8 68 2a b4  59 fd ac ef
| last Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| current Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| computed Phase 2 IV:
|   28 ab c8 51  07 49 69 d9  7f 66 a9 d3  2e 16 fc ea
| encrypting:
|   0b 00 00 14  19 0b 96 33  ab a3 1d 4b  f8 68 2a b4
|   59 fd ac ef  00 00 00 20  00 00 00 01  01 10 8d 28
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   00 00 4a ab
| IV:
|   28 ab c8 51  07 49 69 d9  7f 66 a9 d3  2e 16 fc ea
| unpadded size is: 52
| emitting 4 zero bytes of encryption padding into ISAKMP Message
| encrypting 56 using OAKLEY_3DES_CBC
| next IV:  5b 2c 9e 2d  c4 64 5c a7
| emitting length of ISAKMP Message: 84
| sending 84 bytes for ISAKMP notify through eth2:500 to 10.29.3.225:500 (using #12)
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   08 10 05 01  3b d8 f8 75  00 00 00 54  2e 1d 5e 86
|   79 14 a2 a5  45 63 7f 0f  3d a0 98 2e  e9 91 e7 63
|   77 ba 7e 34  f7 71 10 c2  c4 10 9d 80  61 76 02 55
|   eb c0 bd d7  04 f5 f8 7a  a8 44 e8 b8  5b 2c 9e 2d
|   c4 64 5c a7
| next event EVENT_DPD in 10 seconds for #13
|  
| *received 84 bytes from 10.29.3.225:500 on eth2 (port=500)
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   08 10 05 01  ab b4 6c d5  00 00 00 54  0c 9c 1e 48
|   30 8e fb a1  57 ee 1a 20  de dc 05 af  1c 31 13 c1
|   12 16 01 e2  7e 91 39 e2  57 4f 59 90  4f 2d 66 2d
|   9f 61 b8 43  e1 c1 dc 64  c0 eb 9d 46  09 50 1c 09
|   18 03 ff ae
| **parse ISAKMP Message:
|    initiator cookie:
|   54 e4 4d cf  e8 bd 58 56
|    responder cookie:
|   c1 4c bd 20  82 fc 1e e8
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  ab b4 6c d5
|    length: 84
|  processing version=1.0 packet with exchange type=ISAKMP_XCHG_INFO (5)
| ICOOKIE:  54 e4 4d cf  e8 bd 58 56
| RCOOKIE:  c1 4c bd 20  82 fc 1e e8
| state hash entry 31
| peer and cookies match on #13, provided msgid 00000000 vs e033b6f9/00000000
| peer and cookies match on #12, provided msgid 00000000 vs 00000000/00000000
| p15 state object #12 found, in STATE_MAIN_R3
| processing connection site_192.168.1.0_24-192.168.123.0_24
| last Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| current Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| computed Phase 2 IV:
|   f8 7b b9 35  b4 5a 0d 6a  b3 22 d9 1a  42 e3 1e ec
| received encrypted packet from 10.29.3.225:500
| decrypting 56 bytes using algorithm OAKLEY_3DES_CBC
| decrypted:
|   0b 00 00 14  71 92 b8 56  8c 9a 8c d0  82 87 ee 6e
|   86 7c 34 ee  00 00 00 20  00 00 00 01  01 10 8d 28
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   00 00 33 9c  00 00 00 00
| next IV:  09 50 1c 09  18 03 ff ae
| got payload 0x100(ISAKMP_NEXT_HASH) needed: 0x100 opt: 0x0
| ***parse ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_N
|    length: 20
| 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 4 bytes of padding
| info:  54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
| info:  00 00 33 9c
| processing informational R_U_THERE (36136)
| **emit ISAKMP Message:
|    initiator cookie:
|   54 e4 4d cf  e8 bd 58 56
|    responder cookie:
|   c1 4c bd 20  82 fc 1e e8
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  c9 55 1c 9e
| ***emit ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_N
| emitting 16 zero bytes of HASH into ISAKMP Hash Payload
| emitting length of ISAKMP Hash Payload: 20
| ***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  54 e4 4d cf  e8 bd 58 56
| emitting 8 raw bytes of notify rcookie into ISAKMP Notification Payload
| notify rcookie  c1 4c bd 20  82 fc 1e e8
| emitting 4 raw bytes of notify data into ISAKMP Notification Payload
| notify data  00 00 33 9c
| emitting length of ISAKMP Notification Payload: 32
| HASH computed:
|   78 d4 90 49  50 7c 31 a3  51 73 b0 3d  a4 12 5d 4f
| last Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| current Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| computed Phase 2 IV:
|   8d 37 3f 76  a2 93 57 87  a1 ae 5b 28  be 02 e7 87
| encrypting:
|   0b 00 00 14  78 d4 90 49  50 7c 31 a3  51 73 b0 3d
|   a4 12 5d 4f  00 00 00 20  00 00 00 01  01 10 8d 29
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   00 00 33 9c
| IV:
|   8d 37 3f 76  a2 93 57 87  a1 ae 5b 28  be 02 e7 87
| unpadded size is: 52
| emitting 4 zero bytes of encryption padding into ISAKMP Message
| encrypting 56 using OAKLEY_3DES_CBC
| next IV:  e6 d6 4f 20  62 6d 6a 8d
| emitting length of ISAKMP Message: 84
| sending 84 bytes for ISAKMP notify through eth2:500 to 10.29.3.225:500 (using #12)
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   08 10 05 01  c9 55 1c 9e  00 00 00 54  54 de f9 dc
|   4a cd 73 51  7c 78 c7 a5  0d 70 d5 3b  84 ee 0b f3
|   91 0a fe d9  53 23 6b d1  77 b3 dc 45  09 7c 2a d5
|   6b 39 73 f3  f5 0a 2f e6  07 d4 1c 0f  e6 d6 4f 20
|   62 6d 6a 8d
| state: 12 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 #13
| next event EVENT_DPD in 10 seconds for #13
|  
| *received 84 bytes from 10.29.3.225:500 on eth2 (port=500)
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   08 10 05 01  a3 83 16 fe  00 00 00 54  79 87 5b 51
|   7d 99 ae 75  d1 3d 2f f6  d6 aa 0a c3  6a 8b a0 df
|   40 9d 69 07  9b 9b 4f c9  b5 8d a4 8f  a7 2c 17 a5
|   c1 a8 70 be  d3 ab 68 ef  e8 cb c0 01  fc ce a7 2b
|   25 e3 e3 40
| **parse ISAKMP Message:
|    initiator cookie:
|   54 e4 4d cf  e8 bd 58 56
|    responder cookie:
|   c1 4c bd 20  82 fc 1e e8
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  a3 83 16 fe
|    length: 84
|  processing version=1.0 packet with exchange type=ISAKMP_XCHG_INFO (5)
| ICOOKIE:  54 e4 4d cf  e8 bd 58 56
| RCOOKIE:  c1 4c bd 20  82 fc 1e e8
| state hash entry 31
| peer and cookies match on #13, provided msgid 00000000 vs e033b6f9/00000000
| peer and cookies match on #12, provided msgid 00000000 vs 00000000/00000000
| p15 state object #12 found, in STATE_MAIN_R3
| processing connection site_192.168.1.0_24-192.168.123.0_24
| last Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| current Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| computed Phase 2 IV:
|   34 de 0c e0  e1 a2 3f 28  03 7f aa 24  6a d1 54 2f
| received encrypted packet from 10.29.3.225:500
| decrypting 56 bytes using algorithm OAKLEY_3DES_CBC
| decrypted:
|   0b 00 00 14  52 48 c5 0a  30 1d 9c 73  03 d0 db ec
|   ce 01 57 20  00 00 00 20  00 00 00 01  01 10 8d 29
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   00 00 4a ab  00 00 00 00
| next IV:  fc ce a7 2b  25 e3 e3 40
| got payload 0x100(ISAKMP_NEXT_HASH) needed: 0x100 opt: 0x0
| ***parse ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_N
|    length: 20
| 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 4 bytes of padding
| info:  54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
| info:  00 00 4a ab
| 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 #13
| next event EVENT_DPD in 10 seconds for #13
|  
| *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 9 seconds for #13
| next event EVENT_DPD in 9 seconds for #13
|  
| *received 84 bytes from 10.29.3.225:500 on eth2 (port=500)
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   08 10 05 01  4b 67 5b 5a  00 00 00 54  47 31 30 e5
|   86 12 f2 df  10 c3 7d 0a  fc a7 80 33  b0 44 18 28
|   1b 5e d1 f3  f3 71 59 ff  b5 c0 f7 38  20 8b 4a e4
|   89 1c 08 36  df c1 72 ca  7d 56 7f a5  6a 85 79 d4
|   38 34 e2 77
| **parse ISAKMP Message:
|    initiator cookie:
|   54 e4 4d cf  e8 bd 58 56
|    responder cookie:
|   c1 4c bd 20  82 fc 1e e8
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  4b 67 5b 5a
|    length: 84
|  processing version=1.0 packet with exchange type=ISAKMP_XCHG_INFO (5)
| ICOOKIE:  54 e4 4d cf  e8 bd 58 56
| RCOOKIE:  c1 4c bd 20  82 fc 1e e8
| state hash entry 31
| peer and cookies match on #13, provided msgid 00000000 vs e033b6f9/00000000
| peer and cookies match on #12, provided msgid 00000000 vs 00000000/00000000
| p15 state object #12 found, in STATE_MAIN_R3
| processing connection site_192.168.1.0_24-192.168.123.0_24
| last Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| current Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| computed Phase 2 IV:
|   84 2c 18 70  34 69 ac fa  e1 70 03 c3  d6 80 21 ff
| received encrypted packet from 10.29.3.225:500
| decrypting 56 bytes using algorithm OAKLEY_3DES_CBC
| decrypted:
|   0b 00 00 14  7e bc f4 3b  2d 24 56 af  59 4e ce 9b
|   25 1d 8a 8f  00 00 00 20  00 00 00 01  01 10 8d 28
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   00 00 33 9d  00 00 00 00
| next IV:  6a 85 79 d4  38 34 e2 77
| got payload 0x100(ISAKMP_NEXT_HASH) needed: 0x100 opt: 0x0
| ***parse ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_N
|    length: 20
| 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 4 bytes of padding
| info:  54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
| info:  00 00 33 9d
| processing informational R_U_THERE (36136)
| **emit ISAKMP Message:
|    initiator cookie:
|   54 e4 4d cf  e8 bd 58 56
|    responder cookie:
|   c1 4c bd 20  82 fc 1e e8
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  4a 23 2d cb
| ***emit ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_N
| emitting 16 zero bytes of HASH into ISAKMP Hash Payload
| emitting length of ISAKMP Hash Payload: 20
| ***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  54 e4 4d cf  e8 bd 58 56
| emitting 8 raw bytes of notify rcookie into ISAKMP Notification Payload
| notify rcookie  c1 4c bd 20  82 fc 1e e8
| emitting 4 raw bytes of notify data into ISAKMP Notification Payload
| notify data  00 00 33 9d
| emitting length of ISAKMP Notification Payload: 32
| HASH computed:
|   57 22 68 85  8e 9c 52 0d  20 8b 32 79  60 bc f0 81
| last Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| current Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| computed Phase 2 IV:
|   2a d9 ab 9d  6a 8c 89 43  f7 31 f3 de  05 f9 19 4d
| encrypting:
|   0b 00 00 14  57 22 68 85  8e 9c 52 0d  20 8b 32 79
|   60 bc f0 81  00 00 00 20  00 00 00 01  01 10 8d 29
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   00 00 33 9d
| IV:
|   2a d9 ab 9d  6a 8c 89 43  f7 31 f3 de  05 f9 19 4d
| unpadded size is: 52
| emitting 4 zero bytes of encryption padding into ISAKMP Message
| encrypting 56 using OAKLEY_3DES_CBC
| next IV:  49 b9 28 b6  bd 65 5e 31
| emitting length of ISAKMP Message: 84
| sending 84 bytes for ISAKMP notify through eth2:500 to 10.29.3.225:500 (using #12)
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   08 10 05 01  4a 23 2d cb  00 00 00 54  fb 5d 8b 6a
|   37 66 83 95  2c 10 c8 44  49 fc b3 8a  66 bc 5b 35
|   b3 1b a8 99  6e 10 5f 1f  df e2 00 33  81 31 a2 27
|   0e aa 1d 87  70 26 4e 39  20 68 13 7f  49 b9 28 b6
|   bd 65 5e 31
| complete state transition with STF_IGNORE
| * processed 0 messages from cryptographic helpers 
| next event EVENT_DPD in 0 seconds for #13
| *time to handle event
| handling event EVENT_DPD
| event after this is EVENT_PENDING_DDNS in 45 seconds
| processing connection site_192.168.1.0_24-192.168.123.0_24
| inserting event EVENT_DPD, timeout in 10 seconds for #13
| event added at head of queue
| next event EVENT_DPD in 10 seconds for #13
|  
| next event EVENT_DPD in 0 seconds for #13
| *time to handle event
| handling event EVENT_DPD
| event after this is EVENT_PENDING_DDNS in 35 seconds
| processing connection site_192.168.1.0_24-192.168.123.0_24
| inserting event EVENT_DPD, timeout in 10 seconds for #13
| event added at head of queue
| state: 12 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 #12
| event added after event EVENT_DPD for #13
| **emit ISAKMP Message:
|    initiator cookie:
|   54 e4 4d cf  e8 bd 58 56
|    responder cookie:
|   c1 4c bd 20  82 fc 1e e8
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  5d 9e 08 ce
| ***emit ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_N
| emitting 16 zero bytes of HASH into ISAKMP Hash Payload
| emitting length of ISAKMP Hash Payload: 20
| ***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  54 e4 4d cf  e8 bd 58 56
| emitting 8 raw bytes of notify rcookie into ISAKMP Notification Payload
| notify rcookie  c1 4c bd 20  82 fc 1e e8
| emitting 4 raw bytes of notify data into ISAKMP Notification Payload
| notify data  00 00 4a ac
| emitting length of ISAKMP Notification Payload: 32
| HASH computed:
|   df 56 2b ef  7c 90 c7 97  86 28 6f ad  07 65 89 e3
| last Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| current Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| computed Phase 2 IV:
|   4f 49 d3 42  9f 7a 90 b3  90 e5 e8 f9  e2 53 dc 1d
| encrypting:
|   0b 00 00 14  df 56 2b ef  7c 90 c7 97  86 28 6f ad
|   07 65 89 e3  00 00 00 20  00 00 00 01  01 10 8d 28
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   00 00 4a ac
| IV:
|   4f 49 d3 42  9f 7a 90 b3  90 e5 e8 f9  e2 53 dc 1d
| unpadded size is: 52
| emitting 4 zero bytes of encryption padding into ISAKMP Message
| encrypting 56 using OAKLEY_3DES_CBC
| next IV:  d6 e1 86 6d  16 73 fc c6
| emitting length of ISAKMP Message: 84
| sending 84 bytes for ISAKMP notify through eth2:500 to 10.29.3.225:500 (using #12)
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   08 10 05 01  5d 9e 08 ce  00 00 00 54  77 d4 90 f9
|   35 50 20 d5  c4 af 2b 51  dd c8 15 5a  d8 93 cb c7
|   f8 b5 ce 7f  36 35 0a 8d  7f a5 7c 3e  a5 24 06 34
|   bb ab 50 77  67 37 2d 41  b9 50 dc f0  d6 e1 86 6d
|   16 73 fc c6
| next event EVENT_DPD in 10 seconds for #13
|  
| *received 84 bytes from 10.29.3.225:500 on eth2 (port=500)
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   08 10 05 01  2b 7e 44 85  00 00 00 54  9e 0f 4e cd
|   21 3b d6 62  4a b1 64 6a  27 1c 85 a0  48 b3 0a b9
|   23 1b e1 d7  a6 ab db b2  e4 ff d5 ff  c2 b4 e6 82
|   74 eb b4 a4  fd 61 fa 79  3a 52 75 d6  49 35 ef f3
|   35 07 7e c1
| **parse ISAKMP Message:
|    initiator cookie:
|   54 e4 4d cf  e8 bd 58 56
|    responder cookie:
|   c1 4c bd 20  82 fc 1e e8
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  2b 7e 44 85
|    length: 84
|  processing version=1.0 packet with exchange type=ISAKMP_XCHG_INFO (5)
| ICOOKIE:  54 e4 4d cf  e8 bd 58 56
| RCOOKIE:  c1 4c bd 20  82 fc 1e e8
| state hash entry 31
| peer and cookies match on #13, provided msgid 00000000 vs e033b6f9/00000000
| peer and cookies match on #12, provided msgid 00000000 vs 00000000/00000000
| p15 state object #12 found, in STATE_MAIN_R3
| processing connection site_192.168.1.0_24-192.168.123.0_24
| last Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| current Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| computed Phase 2 IV:
|   19 2b 62 e7  14 fa 9d aa  ef da 8c 28  10 8d f1 b7
| received encrypted packet from 10.29.3.225:500
| decrypting 56 bytes using algorithm OAKLEY_3DES_CBC
| decrypted:
|   0b 00 00 14  1d c7 34 d7  c7 9c 94 31  6d 98 fe d2
|   14 ff d8 14  00 00 00 20  00 00 00 01  01 10 8d 28
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   00 00 33 9e  00 00 00 00
| next IV:  49 35 ef f3  35 07 7e c1
| got payload 0x100(ISAKMP_NEXT_HASH) needed: 0x100 opt: 0x0
| ***parse ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_N
|    length: 20
| 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 4 bytes of padding
| info:  54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
| info:  00 00 33 9e
| processing informational R_U_THERE (36136)
| **emit ISAKMP Message:
|    initiator cookie:
|   54 e4 4d cf  e8 bd 58 56
|    responder cookie:
|   c1 4c bd 20  82 fc 1e e8
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  93 78 44 9c
| ***emit ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_N
| emitting 16 zero bytes of HASH into ISAKMP Hash Payload
| emitting length of ISAKMP Hash Payload: 20
| ***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  54 e4 4d cf  e8 bd 58 56
| emitting 8 raw bytes of notify rcookie into ISAKMP Notification Payload
| notify rcookie  c1 4c bd 20  82 fc 1e e8
| emitting 4 raw bytes of notify data into ISAKMP Notification Payload
| notify data  00 00 33 9e
| emitting length of ISAKMP Notification Payload: 32
| HASH computed:
|   4d 8d d4 5c  10 47 2c 80  85 80 bc ca  e1 2a f0 af
| last Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| current Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| computed Phase 2 IV:
|   12 f4 56 ca  c6 95 b5 19  01 89 a4 1b  04 96 1d e4
| encrypting:
|   0b 00 00 14  4d 8d d4 5c  10 47 2c 80  85 80 bc ca
|   e1 2a f0 af  00 00 00 20  00 00 00 01  01 10 8d 29
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   00 00 33 9e
| IV:
|   12 f4 56 ca  c6 95 b5 19  01 89 a4 1b  04 96 1d e4
| unpadded size is: 52
| emitting 4 zero bytes of encryption padding into ISAKMP Message
| encrypting 56 using OAKLEY_3DES_CBC
| next IV:  8f 8a 60 5b  0b 52 85 f9
| emitting length of ISAKMP Message: 84
| sending 84 bytes for ISAKMP notify through eth2:500 to 10.29.3.225:500 (using #12)
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   08 10 05 01  93 78 44 9c  00 00 00 54  ab ec d6 95
|   ac f3 ef 6b  19 af 13 b9  92 30 b2 a6  60 63 b3 50
|   5f 06 a1 f4  82 66 6e e0  20 62 7f 72  c6 2c 4a 65
|   58 7b 85 73  4a fa 60 4e  ef a2 a9 68  8f 8a 60 5b
|   0b 52 85 f9
| state: 12 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 #13
| next event EVENT_DPD in 10 seconds for #13
|  
| *received 84 bytes from 10.29.3.225:500 on eth2 (port=500)
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   08 10 05 01  b2 38 15 96  00 00 00 54  c5 b8 a9 97
|   ff 8e af 79  8d 8d e0 28  ab 7b ee f5  43 cf 82 39
|   b3 cf bc 6c  0a 8f db 46  70 ab 92 fb  93 e6 e6 f5
|   40 69 f4 d0  7c 71 ed 27  2f 99 19 28  7c 87 b8 4f
|   4d 8d 7e 0a
| **parse ISAKMP Message:
|    initiator cookie:
|   54 e4 4d cf  e8 bd 58 56
|    responder cookie:
|   c1 4c bd 20  82 fc 1e e8
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  b2 38 15 96
|    length: 84
|  processing version=1.0 packet with exchange type=ISAKMP_XCHG_INFO (5)
| ICOOKIE:  54 e4 4d cf  e8 bd 58 56
| RCOOKIE:  c1 4c bd 20  82 fc 1e e8
| state hash entry 31
| peer and cookies match on #13, provided msgid 00000000 vs e033b6f9/00000000
| peer and cookies match on #12, provided msgid 00000000 vs 00000000/00000000
| p15 state object #12 found, in STATE_MAIN_R3
| processing connection site_192.168.1.0_24-192.168.123.0_24
| last Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| current Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| computed Phase 2 IV:
|   b0 a4 4a 61  ad 63 c6 58  fd 8b b1 6f  ca f9 bd 13
| received encrypted packet from 10.29.3.225:500
| decrypting 56 bytes using algorithm OAKLEY_3DES_CBC
| decrypted:
|   0b 00 00 14  20 5a 11 79  a6 58 8d be  c4 cd 22 63
|   e6 3a fb 0a  00 00 00 20  00 00 00 01  01 10 8d 29
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   00 00 4a ac  00 00 00 00
| next IV:  7c 87 b8 4f  4d 8d 7e 0a
| got payload 0x100(ISAKMP_NEXT_HASH) needed: 0x100 opt: 0x0
| ***parse ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_N
|    length: 20
| 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 4 bytes of padding
| info:  54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
| info:  00 00 4a ac
| 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 #13
| next event EVENT_DPD in 10 seconds for #13
|  
| next event EVENT_DPD in 0 seconds for #13
| *time to handle event
| handling event EVENT_DPD
| event after this is EVENT_PENDING_DDNS in 25 seconds
| processing connection site_192.168.1.0_24-192.168.123.0_24
| inserting event EVENT_DPD, timeout in 10 seconds for #13
| event added at head of queue
| state: 12 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 #12
| event added after event EVENT_DPD for #13
| **emit ISAKMP Message:
|    initiator cookie:
|   54 e4 4d cf  e8 bd 58 56
|    responder cookie:
|   c1 4c bd 20  82 fc 1e e8
|    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 be 87 f2
| ***emit ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_N
| emitting 16 zero bytes of HASH into ISAKMP Hash Payload
| emitting length of ISAKMP Hash Payload: 20
| ***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  54 e4 4d cf  e8 bd 58 56
| emitting 8 raw bytes of notify rcookie into ISAKMP Notification Payload
| notify rcookie  c1 4c bd 20  82 fc 1e e8
| emitting 4 raw bytes of notify data into ISAKMP Notification Payload
| notify data  00 00 4a ad
| emitting length of ISAKMP Notification Payload: 32
| HASH computed:
|   e2 2d c3 fc  e8 e5 ee 35  80 ef c2 a1  70 03 c8 60
| last Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| current Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| computed Phase 2 IV:
|   7e 59 c3 52  5f 53 93 27  f4 32 4b 71  2c 2c f6 0a
| encrypting:
|   0b 00 00 14  e2 2d c3 fc  e8 e5 ee 35  80 ef c2 a1
|   70 03 c8 60  00 00 00 20  00 00 00 01  01 10 8d 28
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   00 00 4a ad
| IV:
|   7e 59 c3 52  5f 53 93 27  f4 32 4b 71  2c 2c f6 0a
| unpadded size is: 52
| emitting 4 zero bytes of encryption padding into ISAKMP Message
| encrypting 56 using OAKLEY_3DES_CBC
| next IV:  4e 06 47 80  58 e8 1c 12
| emitting length of ISAKMP Message: 84
| sending 84 bytes for ISAKMP notify through eth2:500 to 10.29.3.225:500 (using #12)
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   08 10 05 01  09 be 87 f2  00 00 00 54  1a e0 3b 83
|   ef 97 47 6e  d5 4b 5e 1d  29 a8 7b 9b  43 29 9b 50
|   0d 37 ae 6a  76 d2 47 76  d0 7e 34 e0  64 24 1b ee
|   17 aa 03 8c  c7 99 bb b8  6f 16 48 fd  4e 06 47 80
|   58 e8 1c 12
| next event EVENT_DPD in 10 seconds for #13
|  
| *received 84 bytes from 10.29.3.225:500 on eth2 (port=500)
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   08 10 05 01  23 f7 08 2c  00 00 00 54  f3 97 26 23
|   26 b5 3e 92  aa 3f fe 1d  3f a7 55 c3  cd b4 2a c1
|   97 1d 65 41  73 82 8e aa  39 fd 5a 48  13 58 27 5e
|   63 39 9a 4c  81 4b f3 a9  8b 4e e7 f4  3c 11 bd a4
|   a2 47 8d e9
| **parse ISAKMP Message:
|    initiator cookie:
|   54 e4 4d cf  e8 bd 58 56
|    responder cookie:
|   c1 4c bd 20  82 fc 1e e8
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  23 f7 08 2c
|    length: 84
|  processing version=1.0 packet with exchange type=ISAKMP_XCHG_INFO (5)
| ICOOKIE:  54 e4 4d cf  e8 bd 58 56
| RCOOKIE:  c1 4c bd 20  82 fc 1e e8
| state hash entry 31
| peer and cookies match on #13, provided msgid 00000000 vs e033b6f9/00000000
| peer and cookies match on #12, provided msgid 00000000 vs 00000000/00000000
| p15 state object #12 found, in STATE_MAIN_R3
| processing connection site_192.168.1.0_24-192.168.123.0_24
| last Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| current Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| computed Phase 2 IV:
|   56 ac 09 78  42 f6 39 7b  60 eb 14 db  be e6 4f 94
| received encrypted packet from 10.29.3.225:500
| decrypting 56 bytes using algorithm OAKLEY_3DES_CBC
| decrypted:
|   0b 00 00 14  d4 71 87 f6  6c a6 ae 4b  f3 02 6c 25
|   6e 59 b6 ee  00 00 00 20  00 00 00 01  01 10 8d 28
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   00 00 33 9f  00 00 00 00
| next IV:  3c 11 bd a4  a2 47 8d e9
| got payload 0x100(ISAKMP_NEXT_HASH) needed: 0x100 opt: 0x0
| ***parse ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_N
|    length: 20
| 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 4 bytes of padding
| info:  54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
| info:  00 00 33 9f
| processing informational R_U_THERE (36136)
| **emit ISAKMP Message:
|    initiator cookie:
|   54 e4 4d cf  e8 bd 58 56
|    responder cookie:
|   c1 4c bd 20  82 fc 1e e8
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  98 d1 34 0f
| ***emit ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_N
| emitting 16 zero bytes of HASH into ISAKMP Hash Payload
| emitting length of ISAKMP Hash Payload: 20
| ***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  54 e4 4d cf  e8 bd 58 56
| emitting 8 raw bytes of notify rcookie into ISAKMP Notification Payload
| notify rcookie  c1 4c bd 20  82 fc 1e e8
| emitting 4 raw bytes of notify data into ISAKMP Notification Payload
| notify data  00 00 33 9f
| emitting length of ISAKMP Notification Payload: 32
| HASH computed:
|   f1 ef 4a 37  77 c7 f5 7c  98 20 c8 71  40 2a 8b c0
| last Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| current Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| computed Phase 2 IV:
|   cd d9 06 c8  2e a5 21 8c  01 e2 46 1e  2a 83 2b 7b
| encrypting:
|   0b 00 00 14  f1 ef 4a 37  77 c7 f5 7c  98 20 c8 71
|   40 2a 8b c0  00 00 00 20  00 00 00 01  01 10 8d 29
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   00 00 33 9f
| IV:
|   cd d9 06 c8  2e a5 21 8c  01 e2 46 1e  2a 83 2b 7b
| unpadded size is: 52
| emitting 4 zero bytes of encryption padding into ISAKMP Message
| encrypting 56 using OAKLEY_3DES_CBC
| next IV:  04 36 d6 51  0f 0e ae 22
| emitting length of ISAKMP Message: 84
| sending 84 bytes for ISAKMP notify through eth2:500 to 10.29.3.225:500 (using #12)
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   08 10 05 01  98 d1 34 0f  00 00 00 54  37 9e 6b c5
|   f4 ba ad 06  b7 6f a5 53  5c b8 5f d3  83 ce 79 0d
|   d4 7f 8b 0d  75 bb 07 d7  d1 38 bf ed  0b f7 4d ae
|   b8 04 b8 8d  0a 2d a4 d9  fc 67 b0 44  04 36 d6 51
|   0f 0e ae 22
| state: 12 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 #13
| next event EVENT_DPD in 10 seconds for #13
|  
| *received 84 bytes from 10.29.3.225:500 on eth2 (port=500)
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   08 10 05 01  c3 60 5b 4e  00 00 00 54  33 dd 15 e5
|   4a 0b f3 5e  95 de e6 21  33 89 21 5c  13 a5 11 1e
|   0d bd d5 a7  cc a0 86 9c  c1 3a 7d 62  61 11 56 4c
|   f5 2d 86 bd  90 f9 1d 90  77 bc 49 95  2c 16 05 37
|   13 7f 97 e5
| **parse ISAKMP Message:
|    initiator cookie:
|   54 e4 4d cf  e8 bd 58 56
|    responder cookie:
|   c1 4c bd 20  82 fc 1e e8
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  c3 60 5b 4e
|    length: 84
|  processing version=1.0 packet with exchange type=ISAKMP_XCHG_INFO (5)
| ICOOKIE:  54 e4 4d cf  e8 bd 58 56
| RCOOKIE:  c1 4c bd 20  82 fc 1e e8
| state hash entry 31
| peer and cookies match on #13, provided msgid 00000000 vs e033b6f9/00000000
| peer and cookies match on #12, provided msgid 00000000 vs 00000000/00000000
| p15 state object #12 found, in STATE_MAIN_R3
| processing connection site_192.168.1.0_24-192.168.123.0_24
| last Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| current Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| computed Phase 2 IV:
|   2a 66 61 7c  39 34 19 9a  e3 f5 14 f2  aa f8 7b f8
| received encrypted packet from 10.29.3.225:500
| decrypting 56 bytes using algorithm OAKLEY_3DES_CBC
| decrypted:
|   0b 00 00 14  a3 29 53 92  82 c7 b5 6e  af ae ab f1
|   45 b0 af 95  00 00 00 20  00 00 00 01  01 10 8d 29
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   00 00 4a ad  00 00 00 00
| next IV:  2c 16 05 37  13 7f 97 e5
| got payload 0x100(ISAKMP_NEXT_HASH) needed: 0x100 opt: 0x0
| ***parse ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_N
|    length: 20
| 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 4 bytes of padding
| info:  54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
| info:  00 00 4a ad
| 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 #13
| next event EVENT_DPD in 10 seconds for #13
|  
| next event EVENT_DPD in 0 seconds for #13
| *time to handle event
| handling event EVENT_DPD
| event after this is EVENT_PENDING_DDNS in 15 seconds
| processing connection site_192.168.1.0_24-192.168.123.0_24
| inserting event EVENT_DPD, timeout in 10 seconds for #13
| event added at head of queue
| state: 12 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 #12
| event added after event EVENT_DPD for #13
| **emit ISAKMP Message:
|    initiator cookie:
|   54 e4 4d cf  e8 bd 58 56
|    responder cookie:
|   c1 4c bd 20  82 fc 1e e8
|    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 b8 27 55
| ***emit ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_N
| emitting 16 zero bytes of HASH into ISAKMP Hash Payload
| emitting length of ISAKMP Hash Payload: 20
| ***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  54 e4 4d cf  e8 bd 58 56
| emitting 8 raw bytes of notify rcookie into ISAKMP Notification Payload
| notify rcookie  c1 4c bd 20  82 fc 1e e8
| emitting 4 raw bytes of notify data into ISAKMP Notification Payload
| notify data  00 00 4a ae
| emitting length of ISAKMP Notification Payload: 32
| HASH computed:
|   ef ee f0 04  cf f6 38 e5  2a 27 08 0e  77 d3 fe f5
| last Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| current Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| computed Phase 2 IV:
|   f2 8e 3c 1a  15 ad 04 1a  66 55 25 40  11 33 ee 66
| encrypting:
|   0b 00 00 14  ef ee f0 04  cf f6 38 e5  2a 27 08 0e
|   77 d3 fe f5  00 00 00 20  00 00 00 01  01 10 8d 28
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   00 00 4a ae
| IV:
|   f2 8e 3c 1a  15 ad 04 1a  66 55 25 40  11 33 ee 66
| unpadded size is: 52
| emitting 4 zero bytes of encryption padding into ISAKMP Message
| encrypting 56 using OAKLEY_3DES_CBC
| next IV:  ea 89 cd 1f  68 2a b8 01
| emitting length of ISAKMP Message: 84
| sending 84 bytes for ISAKMP notify through eth2:500 to 10.29.3.225:500 (using #12)
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   08 10 05 01  6f b8 27 55  00 00 00 54  de 06 cd 21
|   d7 fc 47 2f  57 98 28 10  07 da 54 b9  12 3f a2 c6
|   79 6a 93 fc  67 b2 f9 9e  b6 2c fb d7  5f d0 45 5a
|   d0 e3 b8 c5  57 58 18 bc  2c 14 09 7b  ea 89 cd 1f
|   68 2a b8 01
| next event EVENT_DPD in 10 seconds for #13
|  
| *received 84 bytes from 10.29.3.225:500 on eth2 (port=500)
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   08 10 05 01  6f d5 c7 10  00 00 00 54  a9 2e fb d6
|   92 c9 c0 ea  c6 c1 07 ce  ae 38 39 ec  94 e0 d8 37
|   5a 7b ed 3a  06 c1 fc ab  16 1e 8a 85  ec 94 40 c6
|   ca ab 73 76  0f a3 76 41  1e 1e 0a 0d  27 46 fb b2
|   ee b9 7e bc
| **parse ISAKMP Message:
|    initiator cookie:
|   54 e4 4d cf  e8 bd 58 56
|    responder cookie:
|   c1 4c bd 20  82 fc 1e e8
|    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 d5 c7 10
|    length: 84
|  processing version=1.0 packet with exchange type=ISAKMP_XCHG_INFO (5)
| ICOOKIE:  54 e4 4d cf  e8 bd 58 56
| RCOOKIE:  c1 4c bd 20  82 fc 1e e8
| state hash entry 31
| peer and cookies match on #13, provided msgid 00000000 vs e033b6f9/00000000
| peer and cookies match on #12, provided msgid 00000000 vs 00000000/00000000
| p15 state object #12 found, in STATE_MAIN_R3
| processing connection site_192.168.1.0_24-192.168.123.0_24
| last Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| current Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| computed Phase 2 IV:
|   7a b6 0b 5e  77 c4 97 5e  03 3a 27 43  f3 94 74 d2
| received encrypted packet from 10.29.3.225:500
| decrypting 56 bytes using algorithm OAKLEY_3DES_CBC
| decrypted:
|   0b 00 00 14  a6 10 e2 93  f3 cc 33 f4  60 4b e0 a5
|   fe 13 fe 66  00 00 00 20  00 00 00 01  01 10 8d 28
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   00 00 33 a0  00 00 00 00
| next IV:  27 46 fb b2  ee b9 7e bc
| got payload 0x100(ISAKMP_NEXT_HASH) needed: 0x100 opt: 0x0
| ***parse ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_N
|    length: 20
| 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 4 bytes of padding
| info:  54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
| info:  00 00 33 a0
| processing informational R_U_THERE (36136)
| **emit ISAKMP Message:
|    initiator cookie:
|   54 e4 4d cf  e8 bd 58 56
|    responder cookie:
|   c1 4c bd 20  82 fc 1e e8
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  b2 9e 72 91
| ***emit ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_N
| emitting 16 zero bytes of HASH into ISAKMP Hash Payload
| emitting length of ISAKMP Hash Payload: 20
| ***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  54 e4 4d cf  e8 bd 58 56
| emitting 8 raw bytes of notify rcookie into ISAKMP Notification Payload
| notify rcookie  c1 4c bd 20  82 fc 1e e8
| emitting 4 raw bytes of notify data into ISAKMP Notification Payload
| notify data  00 00 33 a0
| emitting length of ISAKMP Notification Payload: 32
| HASH computed:
|   ae a4 c7 d7  35 b1 05 a6  58 e8 2c cb  e7 6c d3 53
| last Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| current Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| computed Phase 2 IV:
|   14 34 23 42  13 c9 21 c8  05 2f e1 39  e9 f4 fe cf
| encrypting:
|   0b 00 00 14  ae a4 c7 d7  35 b1 05 a6  58 e8 2c cb
|   e7 6c d3 53  00 00 00 20  00 00 00 01  01 10 8d 29
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   00 00 33 a0
| IV:
|   14 34 23 42  13 c9 21 c8  05 2f e1 39  e9 f4 fe cf
| unpadded size is: 52
| emitting 4 zero bytes of encryption padding into ISAKMP Message
| encrypting 56 using OAKLEY_3DES_CBC
| next IV:  73 44 87 e8  2d 9e 4f 2f
| emitting length of ISAKMP Message: 84
| sending 84 bytes for ISAKMP notify through eth2:500 to 10.29.3.225:500 (using #12)
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   08 10 05 01  b2 9e 72 91  00 00 00 54  1a 4b 6e 1c
|   7d 07 13 4c  64 18 65 a7  e5 f0 de ce  5c 83 c5 20
|   f6 e9 dd fd  7b 87 ae e6  62 28 f8 a5  ca 09 60 02
|   ff 0e 99 32  9b df f4 24  0c 43 2a 9d  73 44 87 e8
|   2d 9e 4f 2f
| state: 12 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 #13
| next event EVENT_DPD in 10 seconds for #13
|  
| *received 84 bytes from 10.29.3.225:500 on eth2 (port=500)
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   08 10 05 01  02 bc 3f a8  00 00 00 54  be 52 0f ca
|   df cb 36 54  d5 9a fc 50  14 75 24 38  0d f2 fe 8c
|   c0 52 f4 92  16 f2 10 40  48 84 17 23  7c d6 c6 4b
|   0f 7a e4 78  3c 13 61 b6  89 81 a5 c1  b3 68 54 6a
|   62 c6 d5 bb
| **parse ISAKMP Message:
|    initiator cookie:
|   54 e4 4d cf  e8 bd 58 56
|    responder cookie:
|   c1 4c bd 20  82 fc 1e e8
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  02 bc 3f a8
|    length: 84
|  processing version=1.0 packet with exchange type=ISAKMP_XCHG_INFO (5)
| ICOOKIE:  54 e4 4d cf  e8 bd 58 56
| RCOOKIE:  c1 4c bd 20  82 fc 1e e8
| state hash entry 31
| peer and cookies match on #13, provided msgid 00000000 vs e033b6f9/00000000
| peer and cookies match on #12, provided msgid 00000000 vs 00000000/00000000
| p15 state object #12 found, in STATE_MAIN_R3
| processing connection site_192.168.1.0_24-192.168.123.0_24
| last Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| current Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| computed Phase 2 IV:
|   b5 93 a6 5c  a1 74 b4 6d  1e ed 1f 63  66 6d 7b 5b
| received encrypted packet from 10.29.3.225:500
| decrypting 56 bytes using algorithm OAKLEY_3DES_CBC
| decrypted:
|   0b 00 00 14  ba 2a 79 67  1f 15 ff 61  7d 55 5d 2b
|   ca ad be b1  00 00 00 20  00 00 00 01  01 10 8d 29
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   00 00 4a ae  00 00 00 00
| next IV:  b3 68 54 6a  62 c6 d5 bb
| got payload 0x100(ISAKMP_NEXT_HASH) needed: 0x100 opt: 0x0
| ***parse ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_N
|    length: 20
| 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 4 bytes of padding
| info:  54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
| info:  00 00 4a ae
| 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 #13
| next event EVENT_DPD in 10 seconds for #13
|  
| next event EVENT_DPD in 0 seconds for #13
| *time to handle event
| handling event EVENT_DPD
| event after this is EVENT_PENDING_DDNS in 5 seconds
| processing connection site_192.168.1.0_24-192.168.123.0_24
| inserting event EVENT_DPD, timeout in 10 seconds for #13
| event added after event EVENT_PENDING_DDNS
| state: 12 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 #12
| event added after event EVENT_DPD for #13
| **emit ISAKMP Message:
|    initiator cookie:
|   54 e4 4d cf  e8 bd 58 56
|    responder cookie:
|   c1 4c bd 20  82 fc 1e e8
|    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 59 79 ed
| ***emit ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_N
| emitting 16 zero bytes of HASH into ISAKMP Hash Payload
| emitting length of ISAKMP Hash Payload: 20
| ***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  54 e4 4d cf  e8 bd 58 56
| emitting 8 raw bytes of notify rcookie into ISAKMP Notification Payload
| notify rcookie  c1 4c bd 20  82 fc 1e e8
| emitting 4 raw bytes of notify data into ISAKMP Notification Payload
| notify data  00 00 4a af
| emitting length of ISAKMP Notification Payload: 32
| HASH computed:
|   4f 69 cb 6e  a8 b0 71 6e  ce 73 a3 02  b0 70 e4 f5
| last Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| current Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| computed Phase 2 IV:
|   2b c6 5e 5d  6f 2d 79 46  63 a6 f8 23  cb bc ab 4f
| encrypting:
|   0b 00 00 14  4f 69 cb 6e  a8 b0 71 6e  ce 73 a3 02
|   b0 70 e4 f5  00 00 00 20  00 00 00 01  01 10 8d 28
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   00 00 4a af
| IV:
|   2b c6 5e 5d  6f 2d 79 46  63 a6 f8 23  cb bc ab 4f
| unpadded size is: 52
| emitting 4 zero bytes of encryption padding into ISAKMP Message
| encrypting 56 using OAKLEY_3DES_CBC
| next IV:  f5 fa d1 60  18 65 d7 6c
| emitting length of ISAKMP Message: 84
| sending 84 bytes for ISAKMP notify through eth2:500 to 10.29.3.225:500 (using #12)
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   08 10 05 01  09 59 79 ed  00 00 00 54  d4 19 a4 e8
|   c8 5b 55 dc  ee d5 64 9b  c4 b6 e9 f8  67 df a6 33
|   86 81 e4 2a  94 5a fd 5f  26 24 53 f2  c0 9c cb 98
|   5c 59 f6 9e  ac c2 d5 da  aa 4a ad 5d  f5 fa d1 60
|   18 65 d7 6c
| next event EVENT_PENDING_DDNS in 5 seconds
|  
| *received 84 bytes from 10.29.3.225:500 on eth2 (port=500)
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   08 10 05 01  3b dc 5b 74  00 00 00 54  55 8a b5 74
|   75 e9 0c 42  98 c7 8f 8f  5d c2 e7 e9  83 9e 99 0d
|   08 68 03 00  40 a7 79 e6  61 1b 9c 97  03 d0 d2 75
|   07 e3 f2 06  16 e2 83 52  b3 e8 97 08  43 6a d1 43
|   f3 ff de 8b
| **parse ISAKMP Message:
|    initiator cookie:
|   54 e4 4d cf  e8 bd 58 56
|    responder cookie:
|   c1 4c bd 20  82 fc 1e e8
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  3b dc 5b 74
|    length: 84
|  processing version=1.0 packet with exchange type=ISAKMP_XCHG_INFO (5)
| ICOOKIE:  54 e4 4d cf  e8 bd 58 56
| RCOOKIE:  c1 4c bd 20  82 fc 1e e8
| state hash entry 31
| peer and cookies match on #13, provided msgid 00000000 vs e033b6f9/00000000
| peer and cookies match on #12, provided msgid 00000000 vs 00000000/00000000
| p15 state object #12 found, in STATE_MAIN_R3
| processing connection site_192.168.1.0_24-192.168.123.0_24
| last Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| current Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| computed Phase 2 IV:
|   5e 70 fe 6b  8e 3b 41 43  40 9a c6 e6  05 e5 3a c7
| received encrypted packet from 10.29.3.225:500
| decrypting 56 bytes using algorithm OAKLEY_3DES_CBC
| decrypted:
|   0b 00 00 14  1f 15 57 15  35 67 a0 a1  ae f5 de 47
|   0e 04 4e 9f  00 00 00 20  00 00 00 01  01 10 8d 28
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   00 00 33 a1  00 00 00 00
| next IV:  43 6a d1 43  f3 ff de 8b
| got payload 0x100(ISAKMP_NEXT_HASH) needed: 0x100 opt: 0x0
| ***parse ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_N
|    length: 20
| 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 4 bytes of padding
| info:  54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
| info:  00 00 33 a1
| processing informational R_U_THERE (36136)
| **emit ISAKMP Message:
|    initiator cookie:
|   54 e4 4d cf  e8 bd 58 56
|    responder cookie:
|   c1 4c bd 20  82 fc 1e e8
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  73 ee 0d e9
| ***emit ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_N
| emitting 16 zero bytes of HASH into ISAKMP Hash Payload
| emitting length of ISAKMP Hash Payload: 20
| ***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  54 e4 4d cf  e8 bd 58 56
| emitting 8 raw bytes of notify rcookie into ISAKMP Notification Payload
| notify rcookie  c1 4c bd 20  82 fc 1e e8
| emitting 4 raw bytes of notify data into ISAKMP Notification Payload
| notify data  00 00 33 a1
| emitting length of ISAKMP Notification Payload: 32
| HASH computed:
|   e5 e3 02 ca  7f da 30 70  a2 9a f4 93  69 78 54 c4
| last Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| current Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| computed Phase 2 IV:
|   66 80 af 60  ea 6f 8d 5c  3e c6 31 17  46 da 97 b9
| encrypting:
|   0b 00 00 14  e5 e3 02 ca  7f da 30 70  a2 9a f4 93
|   69 78 54 c4  00 00 00 20  00 00 00 01  01 10 8d 29
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   00 00 33 a1
| IV:
|   66 80 af 60  ea 6f 8d 5c  3e c6 31 17  46 da 97 b9
| unpadded size is: 52
| emitting 4 zero bytes of encryption padding into ISAKMP Message
| encrypting 56 using OAKLEY_3DES_CBC
| next IV:  39 7b 2b b4  3d cb e5 c1
| emitting length of ISAKMP Message: 84
| sending 84 bytes for ISAKMP notify through eth2:500 to 10.29.3.225:500 (using #12)
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   08 10 05 01  73 ee 0d e9  00 00 00 54  88 44 91 49
|   f8 e6 b1 af  45 f8 fb e3  38 8e 0b 92  fd b3 87 41
|   5c ed 7b 1a  d0 8c 62 55  0d 85 1c 39  3a 57 7f 14
|   0a 54 64 7e  3a 91 85 f2  4f 78 b9 f8  39 7b 2b b4
|   3d cb e5 c1
| state: 12 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 5 seconds
| next event EVENT_PENDING_DDNS in 5 seconds
|  
| *received 84 bytes from 10.29.3.225:500 on eth2 (port=500)
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   08 10 05 01  e9 fb bf 93  00 00 00 54  02 00 d9 0e
|   ea 85 36 6c  cb 3c 1b 87  f3 f0 65 b1  42 42 ee 8e
|   07 27 20 e8  61 38 1b 70  85 78 e1 f0  b8 1f 9c 22
|   bd 94 d5 b3  73 2c ef 74  8c 92 9b 2c  06 8c d8 be
|   f1 d5 76 d8
| **parse ISAKMP Message:
|    initiator cookie:
|   54 e4 4d cf  e8 bd 58 56
|    responder cookie:
|   c1 4c bd 20  82 fc 1e e8
|    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 fb bf 93
|    length: 84
|  processing version=1.0 packet with exchange type=ISAKMP_XCHG_INFO (5)
| ICOOKIE:  54 e4 4d cf  e8 bd 58 56
| RCOOKIE:  c1 4c bd 20  82 fc 1e e8
| state hash entry 31
| peer and cookies match on #13, provided msgid 00000000 vs e033b6f9/00000000
| peer and cookies match on #12, provided msgid 00000000 vs 00000000/00000000
| p15 state object #12 found, in STATE_MAIN_R3
| processing connection site_192.168.1.0_24-192.168.123.0_24
| last Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| current Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| computed Phase 2 IV:
|   fe fe ca 0c  08 c4 ed 00  e9 8b d0 9a  9a a4 14 fe
| received encrypted packet from 10.29.3.225:500
| decrypting 56 bytes using algorithm OAKLEY_3DES_CBC
| decrypted:
|   0b 00 00 14  cb 70 5e 0f  49 e2 8b 50  f4 3f 28 47
|   99 b5 60 de  00 00 00 20  00 00 00 01  01 10 8d 29
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   00 00 4a af  00 00 00 00
| next IV:  06 8c d8 be  f1 d5 76 d8
| got payload 0x100(ISAKMP_NEXT_HASH) needed: 0x100 opt: 0x0
| ***parse ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_N
|    length: 20
| 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 4 bytes of padding
| info:  54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
| info:  00 00 4a af
| 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 5 seconds
| next event EVENT_PENDING_DDNS in 5 seconds
|  
| next event EVENT_PENDING_DDNS in 0 seconds
| *time to handle event
| handling event EVENT_PENDING_DDNS
| event after this is EVENT_DPD in 5 seconds
| inserting event EVENT_PENDING_DDNS, timeout in 60 seconds
| event added after event EVENT_DPD for #13
| next event EVENT_DPD in 5 seconds for #13
|  
| *received 84 bytes from 10.29.3.225:500 on eth2 (port=500)
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   08 10 05 01  ec 0e 46 60  00 00 00 54  aa 8f 70 45
|   1c 85 e0 7d  2a bf 1f 6d  c5 bf f1 42  23 d1 83 42
|   f1 fb b5 86  fa 14 81 26  f2 4a f1 95  40 af c4 94
|   20 1b 3a c2  ff c9 52 93  42 15 44 ef  14 5d 68 c4
|   7d 0b 29 28
| **parse ISAKMP Message:
|    initiator cookie:
|   54 e4 4d cf  e8 bd 58 56
|    responder cookie:
|   c1 4c bd 20  82 fc 1e e8
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  ec 0e 46 60
|    length: 84
|  processing version=1.0 packet with exchange type=ISAKMP_XCHG_INFO (5)
| ICOOKIE:  54 e4 4d cf  e8 bd 58 56
| RCOOKIE:  c1 4c bd 20  82 fc 1e e8
| state hash entry 31
| peer and cookies match on #13, provided msgid 00000000 vs e033b6f9/00000000
| peer and cookies match on #12, provided msgid 00000000 vs 00000000/00000000
| p15 state object #12 found, in STATE_MAIN_R3
| processing connection site_192.168.1.0_24-192.168.123.0_24
| last Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| current Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| computed Phase 2 IV:
|   a1 57 19 03  ba b3 49 5d  3f b1 0e 3a  bd 39 b2 90
| received encrypted packet from 10.29.3.225:500
| decrypting 56 bytes using algorithm OAKLEY_3DES_CBC
| decrypted:
|   0b 00 00 14  91 90 9e 71  ef b8 a8 d8  70 95 47 2d
|   3d 1d fd 53  00 00 00 20  00 00 00 01  01 10 8d 28
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   00 00 33 a2  00 00 00 00
| next IV:  14 5d 68 c4  7d 0b 29 28
| got payload 0x100(ISAKMP_NEXT_HASH) needed: 0x100 opt: 0x0
| ***parse ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_N
|    length: 20
| 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 4 bytes of padding
| info:  54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
| info:  00 00 33 a2
| processing informational R_U_THERE (36136)
| **emit ISAKMP Message:
|    initiator cookie:
|   54 e4 4d cf  e8 bd 58 56
|    responder cookie:
|   c1 4c bd 20  82 fc 1e e8
|    next payload type: ISAKMP_NEXT_HASH
|    ISAKMP version: ISAKMP Version 1.0 (rfc2407)
|    exchange type: ISAKMP_XCHG_INFO
|    flags: ISAKMP_FLAG_ENCRYPTION
|    message ID:  86 8c de b9
| ***emit ISAKMP Hash Payload:
|    next payload type: ISAKMP_NEXT_N
| emitting 16 zero bytes of HASH into ISAKMP Hash Payload
| emitting length of ISAKMP Hash Payload: 20
| ***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  54 e4 4d cf  e8 bd 58 56
| emitting 8 raw bytes of notify rcookie into ISAKMP Notification Payload
| notify rcookie  c1 4c bd 20  82 fc 1e e8
| emitting 4 raw bytes of notify data into ISAKMP Notification Payload
| notify data  00 00 33 a2
| emitting length of ISAKMP Notification Payload: 32
| HASH computed:
|   91 2f 1f ba  3c a3 40 88  fd e6 39 23  45 c8 1a 53
| last Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| current Phase 1 IV:  71 a5 d0 d0  8d f4 ce 5d
| computed Phase 2 IV:
|   a8 63 51 d5  59 6a 76 e1  14 25 90 2d  71 11 f1 58
| encrypting:
|   0b 00 00 14  91 2f 1f ba  3c a3 40 88  fd e6 39 23
|   45 c8 1a 53  00 00 00 20  00 00 00 01  01 10 8d 29
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   00 00 33 a2
| IV:
|   a8 63 51 d5  59 6a 76 e1  14 25 90 2d  71 11 f1 58
| unpadded size is: 52
| emitting 4 zero bytes of encryption padding into ISAKMP Message
| encrypting 56 using OAKLEY_3DES_CBC
| next IV:  5e 80 35 d0  a3 88 7e 6b
| emitting length of ISAKMP Message: 84
| sending 84 bytes for ISAKMP notify through eth2:500 to 10.29.3.225:500 (using #12)
|   54 e4 4d cf  e8 bd 58 56  c1 4c bd 20  82 fc 1e e8
|   08 10 05 01  86 8c de b9  00 00 00 54  ee ae d6 8b
|   94 f5 22 8c  6e da e3 33  1d e0 a8 75  81 1d da 75
|   30 e5 84 4a  24 c0 10 34  db ad a7 a3  9d 20 5a b1
|   17 9b f2 b5  44 48 9e 66  2b 40 c5 57  5e 80 35 d0
|   a3 88 7e 6b
| complete state transition with STF_IGNORE
| * processed 0 messages from cryptographic helpers 
| next event EVENT_DPD in 0 seconds for #13
| *time to handle event
| handling event EVENT_DPD
| event after this is EVENT_PENDING_DDNS in 55 seconds
| processing connection site_192.168.1.0_24-192.168.123.0_24
| inserting event EVENT_DPD, timeout in 10 seconds for #13
| event added at head of queue
| next event EVENT_DPD in 10 seconds for #13
