Guest User!

You are not Sophos Staff.

This discussion has been locked.
You can no longer post new replies to this discussion. If you have a question you can start a new discussion

IPSEC Connection random disconnect

Hello,

maybe someone can help with a problem I have got.

We have a IPSEC Connection from one office to the other.
On both sides a Software Version is installed.

On side still has an old Astaro V7.
The other side has the latest version Sophos V9.

The IPSEC tunnel itself works fine, routes are ok and so on. But the IPSEC Connection gets disconnected randomly. Mostly this happens every 2 to 4 hours. But can work with just one disconnect during a work day. Having no disconnect at a day never occured so far. 
When the disconnect occurs it takes about 3 to 5 minutes until the tunnel is established again.

Policy on both sides is the same:
IKE:
AES 128
MD5
lifetime 7800
DH group 5 MODP 1536
IPSEC:
AES 128
MD5
lifetime 3600
PFS group 5 MODP 1536

strict policy & compression off

The Astaro V7 is responding, the Sophos V9 is initiating the connection.
For now we are using a preshared key and not a certificate for the connection.

Before the Policy was AES 256. I changed that to AES 128. The results are the same. 
Other things I haven't tried yet as I also don't know where to exactly start changing things.

Maybe or hopefully someone else can help or has had a similar problem?

Thanks for your answers & suggestions in advance.


This thread was automatically locked due to age.
Parents
  • Log Astaro V7:


    2015:07:08-10:21:44 astaro pluto[15614]: updown: called /sbin/ip route del 192.168.5.0/24 dev ipsec0 src 192.168.20.100 proto 42 (0)
    2015:07:08-10:21:44 astaro pluto[15614]: | next event EVENT_RETRANSMIT in 11 seconds for #4250
    2015:07:08-10:21:44 astaro pluto[15614]: | 
    2015:07:08-10:21:44 astaro pluto[15614]: | *received 76 bytes from xx9.193.***.***:500 on eth1
    2015:07:08-10:21:44 astaro pluto[15614]: | ICOOKIE:  93 80 9d ba  95 32 e1 e9
    2015:07:08-10:21:44 astaro pluto[15614]: | RCOOKIE:  bc ae 45 8b  70 f2 a3 86
    2015:07:08-10:21:44 astaro pluto[15614]: | peer:  6d c1 f5 71
    2015:07:08-10:21:44 astaro pluto[15614]: | state hash entry 8
    2015:07:08-10:21:44 astaro pluto[15614]: | state object #4195 found, in STATE_MAIN_R3
    2015:07:08-10:21:44 astaro pluto[15614]: | ICOOKIE:  93 80 9d ba  95 32 e1 e9
    2015:07:08-10:21:44 astaro pluto[15614]: | RCOOKIE:  bc ae 45 8b  70 f2 a3 86
    2015:07:08-10:21:44 astaro pluto[15614]: | peer:  6d c1 f5 71
    2015:07:08-10:21:44 astaro pluto[15614]: | state hash entry 8
    2015:07:08-10:21:44 astaro pluto[15614]: | state object #4195 found, in STATE_MAIN_R3
    2015:07:08-10:21:44 astaro pluto[15614]: "S_REF_mSfPQBmhut_7"[7] xx9.193.***.*** #4195: received Delete SA payload: deleting ISAKMP State #4195
    2015:07:08-10:21:44 astaro pluto[15614]: | next event EVENT_RETRANSMIT in 11 seconds for #4250
    2015:07:08-10:21:55 astaro pluto[15614]: | 
    2015:07:08-10:21:55 astaro pluto[15614]: | *time to handle event
    2015:07:08-10:21:55 astaro pluto[15614]: | event after this is EVENT_SHUNT_SCAN in 19 seconds
    2015:07:08-10:21:55 astaro pluto[15614]: | handling event EVENT_RETRANSMIT for 58.240.166.234 "S_REF_mSfPQBmhut_11" #4250
    2015:07:08-10:21:55 astaro pluto[15614]: | inserting event EVENT_RETRANSMIT, timeout in 40 seconds for #4250
    2015:07:08-10:21:55 astaro pluto[15614]: | next event EVENT_SHUNT_SCAN in 19 seconds
    2015:07:08-10:22:15 astaro pluto[15614]: | 
    2015:07:08-10:22:15 astaro pluto[15614]: | *time to handle event
    2015:07:08-10:22:15 astaro pluto[15614]: | event after this is EVENT_RETRANSMIT in 20 seconds
    2015:07:08-10:22:15 astaro pluto[15614]: | inserting event EVENT_SHUNT_SCAN, timeout in 120 seconds
    2015:07:08-10:22:15 astaro pluto[15614]: | scanning for shunt eroutes
    2015:07:08-10:22:15 astaro pluto[15614]: | next event EVENT_RETRANSMIT in 20 seconds for #4250
    2015:07:08-10:22:35 astaro pluto[15614]: | 
    2015:07:08-10:22:35 astaro pluto[15614]: | *time to handle event
    2015:07:08-10:22:35 astaro pluto[15614]: | event after this is EVENT_SHUNT_SCAN in 100 seconds
    2015:07:08-10:22:35 astaro pluto[15614]: | handling event EVENT_RETRANSMIT for 58.240.166.234 "S_REF_mSfPQBmhut_11" #4250
    2015:07:08-10:22:35 astaro pluto[15614]: "S_REF_mSfPQBmhut_11"[613] 58.240.166.234 #4250: max number of retransmissions (2) reached STATE_MAIN_R2
    2015:07:08-10:22:35 astaro pluto[15614]: | ICOOKIE:  a4 28 c2 96  22 53 b0 7a
    2015:07:08-10:22:35 astaro pluto[15614]: | RCOOKIE:  2c 38 8d ce  46 d3 8b 54
    2015:07:08-10:22:35 astaro pluto[15614]: | peer:  3a f0 a6 ea
    2015:07:08-10:22:35 astaro pluto[15614]: | state hash entry 16
    2015:07:08-10:22:35 astaro pluto[15614]: "S_REF_mSfPQBmhut_11"[613] 58.240.166.234: deleting connection "S_REF_mSfPQBmhut_11" instance with peer 58.240.166.234 {isakmp=#0/ipsec=#0}
    2015:07:08-10:22:35 astaro pluto[15614]: | next event EVENT_SHUNT_SCAN in 100 seconds
    2015:07:08-10:23:21 astaro pluto[15614]: | 
    2015:07:08-10:23:21 astaro pluto[15614]: | *received 104 bytes from 58.240.166.234:500 on eth1
    2015:07:08-10:23:21 astaro pluto[15614]: packet from 58.240.166.234:500: received Vendor ID payload [Dead Peer Detection]
    2015:07:08-10:23:21 astaro pluto[15614]: | preparse_isakmp_policy: peer requests PSK authentication
    2015:07:08-10:23:21 astaro pluto[15614]: | instantiated "S_REF_mSfPQBmhut_11" for 58.240.166.234
    2015:07:08-10:23:21 astaro pluto[15614]: | creating state object #4251 at 0x81290c0
    2015:07:08-10:23:21 astaro pluto[15614]: | ICOOKIE:  37 5a 5f ea  7c 9b 54 b4
    2015:07:08-10:23:21 astaro pluto[15614]: | RCOOKIE:  99 29 ad 0d  f5 bc 37 6c
    2015:07:08-10:23:21 astaro pluto[15614]: | peer:  3a f0 a6 ea
    2015:07:08-10:23:21 astaro pluto[15614]: | state hash entry 3
    2015:07:08-10:23:21 astaro pluto[15614]: | inserting event EVENT_SO_DISCARD, timeout in 0 seconds for #4251
    2015:07:08-10:23:21 astaro pluto[15614]: "S_REF_mSfPQBmhut_11"[614] 58.240.166.234 #4251: responding to Main Mode from unknown peer 58.240.166.234
    2015:07:08-10:23:21 astaro pluto[15614]: | inserting event EVENT_RETRANSMIT, timeout in 10 seconds for #4251
    2015:07:08-10:23:21 astaro pluto[15614]: | next event EVENT_RETRANSMIT in 10 seconds for #4251
    2015:07:08-10:23:21 astaro pluto[15614]: | 
    2015:07:08-10:23:21 astaro pluto[15614]: | *received 172 bytes from 58.240.166.234:500 on eth1
    2015:07:08-10:23:21 astaro pluto[15614]: | ICOOKIE:  37 5a 5f ea  7c 9b 54 b4
    2015:07:08-10:23:21 astaro pluto[15614]: | RCOOKIE:  99 29 ad 0d  f5 bc 37 6c
    2015:07:08-10:23:21 astaro pluto[15614]: | peer:  3a f0 a6 ea
    2015:07:08-10:23:21 astaro pluto[15614]: | state hash entry 3
    2015:07:08-10:23:21 astaro pluto[15614]: | state object #4251 found, in STATE_MAIN_R1
    2015:07:08-10:23:21 astaro pluto[15614]: | inserting event EVENT_RETRANSMIT, timeout in 10 seconds for #4251
    2015:07:08-10:23:21 astaro pluto[15614]: | next event EVENT_RETRANSMIT in 10 seconds for #4251
    2015:07:08-10:23:21 astaro pluto[15614]: | 
    2015:07:08-10:23:21 astaro pluto[15614]: | *received 68 bytes from 58.240.166.234:500 on eth1
    2015:07:08-10:23:21 astaro pluto[15614]: | ICOOKIE:  37 5a 5f ea  7c 9b 54 b4
    2015:07:08-10:23:21 astaro pluto[15614]: | RCOOKIE:  99 29 ad 0d  f5 bc 37 6c
    2015:07:08-10:23:21 astaro pluto[15614]: | peer:  3a f0 a6 ea
    2015:07:08-10:23:21 astaro pluto[15614]: | state hash entry 3
    2015:07:08-10:23:21 astaro pluto[15614]: | state object #4251 found, in STATE_MAIN_R2
    2015:07:08-10:23:21 astaro pluto[15614]: "S_REF_mSfPQBmhut_11"[614] 58.240.166.234 #4251: next payload type of ISAKMP Identification Payload has an unknown value: 78
    2015:07:08-10:23:21 astaro pluto[15614]: "S_REF_mSfPQBmhut_11"[614] 58.240.166.234 #4251: probable authentication failure (mismatch of preshared secrets?): malformed payload in packet
    2015:07:08-10:23:21 astaro pluto[15614]: "S_REF_mSfPQBmhut_11"[614] 58.240.166.234 #4251: sending encrypted notification PAYLOAD_MALFORMED to 58.240.166.234:500
    2015:07:08-10:23:21 astaro pluto[15614]: | next event EVENT_RETRANSMIT in 10 seconds for #4251
    2015:07:08-10:23:25 astaro pluto[15614]: | 
    2015:07:08-10:23:25 astaro pluto[15614]: | *received 68 bytes from 58.240.166.234:500 on eth1
    2015:07:08-10:23:25 astaro pluto[15614]: | ICOOKIE:  37 5a 5f ea  7c 9b 54 b4
    2015:07:08-10:23:25 astaro pluto[15614]: | RCOOKIE:  99 29 ad 0d  f5 bc 37 6c
    2015:07:08-10:23:25 astaro pluto[15614]: | peer:  3a f0 a6 ea
    2015:07:08-10:23:25 astaro pluto[15614]: | state hash entry 3
    2015:07:08-10:23:25 astaro pluto[15614]: | state object #4251 found, in STATE_MAIN_R2
    2015:07:08-10:23:25 astaro pluto[15614]: "S_REF_mSfPQBmhut_11"[614] 58.240.166.234 #4251: next payload type of ISAKMP Identification Payload has an unknown value: 78
    2015:07:08-10:23:25 astaro pluto[15614]: "S_REF_mSfPQBmhut_11"[614] 58.240.166.234 #4251: probable authentication failure (mismatch of preshared secrets?): malformed payload in packet
    2015:07:08-10:23:25 astaro pluto[15614]: "S_REF_mSfPQBmhut_11"[614] 58.240.166.234 #4251: sending encrypted notification PAYLOAD_MALFORMED to 58.240.166.234:500
    2015:07:08-10:23:25 astaro pluto[15614]: | next event EVENT_RETRANSMIT in 6 seconds for #4251
    2015:07:08-10:23:29 astaro pluto[15614]: | 
    2015:07:08-10:23:29 astaro pluto[15614]: | *received 68 bytes from 58.240.166.234:500 on eth1
    2015:07:08-10:23:29 astaro pluto[15614]: | ICOOKIE:  37 5a 5f ea  7c 9b 54 b4
    2015:07:08-10:23:29 astaro pluto[15614]: | RCOOKIE:  99 29 ad 0d  f5 bc 37 6c
    2015:07:08-10:23:29 astaro pluto[15614]: | peer:  3a f0 a6 ea
    2015:07:08-10:23:29 astaro pluto[15614]: | state hash entry 3
    2015:07:08-10:23:29 astaro pluto[15614]: | state object #4251 found, in STATE_MAIN_R2
    2015:07:08-10:23:29 astaro pluto[15614]: "S_REF_mSfPQBmhut_11"[614] 58.240.166.234 #4251: next payload type of ISAKMP Identification Payload has an unknown value: 78
    2015:07:08-10:23:29 astaro pluto[15614]: "S_REF_mSfPQBmhut_11"[614] 58.240.166.234 #4251: probable authentication failure (mismatch of preshared secrets?): malformed payload in packet
    2015:07:08-10:23:29 astaro pluto[15614]: "S_REF_mSfPQBmhut_11"[614] 58.240.166.234 #4251: sending encrypted notification PAYLOAD_MALFORMED to 58.240.166.234:500
    2015:07:08-10:23:29 astaro pluto[15614]: | next event EVENT_RETRANSMIT in 2 seconds for #4251
    2015:07:08-10:23:31 astaro pluto[15614]: | 
    2015:07:08-10:23:31 astaro pluto[15614]: | *time to handle event
    2015:07:08-10:23:31 astaro pluto[15614]: | event after this is EVENT_SHUNT_SCAN in 44 seconds
    2015:07:08-10:23:31 astaro pluto[15614]: | handling event EVENT_RETRANSMIT for 58.240.166.234 "S_REF_mSfPQBmhut_11" #4251
    2015:07:08-10:23:31 astaro pluto[15614]: | inserting event EVENT_RETRANSMIT, timeout in 20 seconds for #4251
    2015:07:08-10:23:31 astaro pluto[15614]: | next event EVENT_RETRANSMIT in 20 seconds for #4251
    2015:07:08-10:23:33 astaro pluto[15614]: | 
    2015:07:08-10:23:33 astaro pluto[15614]: | *received 68 bytes from 58.240.166.234:500 on eth1
    2015:07:08-10:23:33 astaro pluto[15614]: | ICOOKIE:  37 5a 5f ea  7c 9b 54 b4
    2015:07:08-10:23:33 astaro pluto[15614]: | RCOOKIE:  99 29 ad 0d  f5 bc 37 6c
    2015:07:08-10:23:33 astaro pluto[15614]: | peer:  3a f0 a6 ea
    2015:07:08-10:23:33 astaro pluto[15614]: | state hash entry 3
    2015:07:08-10:23:33 astaro pluto[15614]: | state object #4251 found, in STATE_MAIN_R2
    2015:07:08-10:23:33 astaro pluto[15614]: "S_REF_mSfPQBmhut_11"[614] 58.240.166.234 #4251: next payload type of ISAKMP Identification Payload has an unknown value: 78
    2015:07:08-10:23:33 astaro pluto[15614]: "S_REF_mSfPQBmhut_11"[614] 58.240.166.234 #4251: probable authentication failure (mismatch of preshared secrets?): malformed payload in packet
    2015:07:08-10:23:33 astaro pluto[15614]: "S_REF_mSfPQBmhut_11"[614] 58.240.166.234 #4251: sending encrypted notification PAYLOAD_MALFORMED to 58.240.166.234:500
    2015:07:08-10:23:33 astaro pluto[15614]: | next event EVENT_RETRANSMIT in 18 seconds for #4251
    2015:07:08-10:23:43 astaro pluto[15614]: | 
    2015:07:08-10:23:43 astaro pluto[15614]: | *received 104 bytes from 58.240.166.234:500 on eth1
    2015:07:08-10:23:43 astaro pluto[15614]: packet from 58.240.166.234:500: received Vendor ID payload [Dead Peer Detection]
    2015:07:08-10:23:43 astaro pluto[15614]: | preparse_isakmp_policy: peer requests PSK authentication
    2015:07:08-10:23:43 astaro pluto[15614]: | creating state object #4252 at 0x8128710
    2015:07:08-10:23:43 astaro pluto[15614]: | ICOOKIE:  0a 66 3e df  8a 75 60 db
    2015:07:08-10:23:43 astaro pluto[15614]: | RCOOKIE:  c0 b3 4b df  8a 4d b7 64
    2015:07:08-10:23:43 astaro pluto[15614]: | peer:  3a f0 a6 ea
    2015:07:08-10:23:43 astaro pluto[15614]: | state hash entry 4
    2015:07:08-10:23:43 astaro pluto[15614]: | inserting event EVENT_SO_DISCARD, timeout in 0 seconds for #4252
    2015:07:08-10:23:43 astaro pluto[15614]: "S_REF_mSfPQBmhut_11"[614] 58.240.166.234 #4252: responding to Main Mode from unknown peer 58.240.166.234
    2015:07:08-10:23:43 astaro pluto[15614]: | inserting event EVENT_RETRANSMIT, timeout in 10 seconds for #4252
    2015:07:08-10:23:43 astaro pluto[15614]: | next event EVENT_RETRANSMIT in 8 seconds for #4251
    2015:07:08-10:23:43 astaro pluto[15614]: | 
    2015:07:08-10:23:43 astaro pluto[15614]: | *received 172 bytes from 58.240.166.234:500 on eth1
    2015:07:08-10:23:43 astaro pluto[15614]: | ICOOKIE:  0a 66 3e df  8a 75 60 db
    2015:07:08-10:23:43 astaro pluto[15614]: | RCOOKIE:  c0 b3 4b df  8a 4d b7 64
    2015:07:08-10:23:43 astaro pluto[15614]: | peer:  3a f0 a6 ea
    2015:07:08-10:23:43 astaro pluto[15614]: | state hash entry 4
    2015:07:08-10:23:43 astaro pluto[15614]: | state object #4252 found, in STATE_MAIN_R1
    2015:07:08-10:23:43 astaro pluto[15614]: | inserting event EVENT_RETRANSMIT, timeout in 10 seconds for #4252
    2015:07:08-10:23:43 astaro pluto[15614]: | next event EVENT_RETRANSMIT in 8 seconds for #4251
    2015:07:08-10:23:43 astaro pluto[15614]: | 
    2015:07:08-10:23:43 astaro pluto[15614]: | *received 68 bytes from 58.240.166.234:500 on eth1
    2015:07:08-10:23:43 astaro pluto[15614]: | ICOOKIE:  0a 66 3e df  8a 75 60 db
    2015:07:08-10:23:43 astaro pluto[15614]: | RCOOKIE:  c0 b3 4b df  8a 4d b7 64
    2015:07:08-10:23:43 astaro pluto[15614]: | peer:  3a f0 a6 ea
    2015:07:08-10:23:43 astaro pluto[15614]: | state hash entry 4
    2015:07:08-10:23:43 astaro pluto[15614]: | state object #4252 found, in STATE_MAIN_R2
    2015:07:08-10:23:43 astaro pluto[15614]: "S_REF_mSfPQBmhut_11"[614] 58.240.166.234 #4252: next payload type of ISAKMP Identification Payload has an unknown value: 194
    2015:07:08-10:23:43 astaro pluto[15614]: "S_REF_mSfPQBmhut_11"[614] 58.240.166.234 #4252: probable authentication failure (mismatch of preshared secrets?): malformed payload in packet
    2015:07:08-10:23:43 astaro pluto[15614]: "S_REF_mSfPQBmhut_11"[614] 58.240.166.234 #4252: sending encrypted notification PAYLOAD_MALFORMED to 58.240.166.234:500
    2015:07:08-10:23:43 astaro pluto[15614]: | next event EVENT_RETRANSMIT in 8 seconds for #4251
    2015:07:08-10:23:47 astaro pluto[15614]: | 
    2015:07:08-10:23:47 astaro pluto[15614]: | *received 68 bytes from 58.240.166.234:500 on eth1
    2015:07:08-10:23:47 astaro pluto[15614]: | ICOOKIE:  0a 66 3e df  8a 75 60 db
    2015:07:08-10:23:47 astaro pluto[15614]: | RCOOKIE:  c0 b3 4b df  8a 4d b7 64
    2015:07:08-10:23:47 astaro pluto[15614]: | peer:  3a f0 a6 ea
    2015:07:08-10:23:47 astaro pluto[15614]: | state hash entry 4
    2015:07:08-10:23:47 astaro pluto[15614]: | state object #4252 found, in STATE_MAIN_R2
    2015:07:08-10:23:47 astaro pluto[15614]: "S_REF_mSfPQBmhut_11"[614] 58.240.166.234 #4252: next payload type of ISAKMP Identification Payload has an unknown value: 194
    2015:07:08-10:23:47 astaro pluto[15614]: "S_REF_mSfPQBmhut_11"[614] 58.240.166.234 #4252: probable authentication failure (mismatch of preshared secrets?): malformed payload in packet
    2015:07:08-10:23:47 astaro pluto[15614]: "S_REF_mSfPQBmhut_11"[614] 58.240.166.234 #4252: sending encrypted notification PAYLOAD_MALFORMED to 58.240.166.234:500
    2015:07:08-10:23:47 astaro pluto[15614]: | next event EVENT_RETRANSMIT in 4 seconds for #4251
    2015:07:08-10:23:51 astaro pluto[15614]: | 
    2015:07:08-10:23:51 astaro pluto[15614]: | *received 68 bytes from 58.240.166.234:500 on eth1
    2015:07:08-10:23:51 astaro pluto[15614]: | ICOOKIE:  0a 66 3e df  8a 75 60 db
    2015:07:08-10:23:51 astaro pluto[15614]: | RCOOKIE:  c0 b3 4b df  8a 4d b7 64
    2015:07:08-10:23:51 astaro pluto[15614]: | peer:  3a f0 a6 ea
    2015:07:08-10:23:51 astaro pluto[15614]: | state hash entry 4
    2015:07:08-10:23:51 astaro pluto[15614]: | state object #4252 found, in STATE_MAIN_R2
    2015:07:08-10:23:51 astaro pluto[15614]: "S_REF_mSfPQBmhut_11"[614] 58.240.166.234 #4252: next payload type of ISAKMP Identification Payload has an unknown value: 194
    2015:07:08-10:23:51 astaro pluto[15614]: "S_REF_mSfPQBmhut_11"[614] 58.240.166.234 #4252: probable authentication failure (mismatch of preshared secrets?): malformed payload in packet
    2015:07:08-10:23:51 astaro pluto[15614]: "S_REF_mSfPQBmhut_11"[614] 58.240.166.234 #4252: sending encrypted notification PAYLOAD_MALFORMED to 58.240.166.234:500
    2015:07:08-10:23:51 astaro pluto[15614]: | next event EVENT_RETRANSMIT in 0 seconds for #4251
    2015:07:08-10:23:51 astaro pluto[15614]: | 
    2015:07:08-10:23:51 astaro pluto[15614]: | *time to handle event
    2015:07:08-10:23:51 astaro pluto[15614]: | event after this is EVENT_RETRANSMIT in 2 seconds
    2015:07:08-10:23:51 astaro pluto[15614]: | handling event EVENT_RETRANSMIT for 58.240.166.234 "S_REF_mSfPQBmhut_11" #4251
    2015:07:08-10:23:51 astaro pluto[15614]: | inserting event EVENT_RETRANSMIT, timeout in 40 seconds for #4251
    2015:07:08-10:23:51 astaro pluto[15614]: | next event EVENT_RETRANSMIT in 2 seconds for #4252
    2015:07:08-10:23:53 astaro pluto[15614]: | 
    2015:07:08-10:23:53 astaro pluto[15614]: | *time to handle event
    2015:07:08-10:23:53 astaro pluto[15614]: | event after this is EVENT_SHUNT_SCAN in 22 seconds
    2015:07:08-10:23:53 astaro pluto[15614]: | handling event EVENT_RETRANSMIT for 58.240.166.234 "S_REF_mSfPQBmhut_11" #4252
    2015:07:08-10:23:53 astaro pluto[15614]: | inserting event EVENT_RETRANSMIT, timeout in 20 seconds for #4252
    2015:07:08-10:23:53 astaro pluto[15614]: | next event EVENT_RETRANSMIT in 20 seconds for #4252
    2015:07:08-10:23:55 astaro pluto[15614]: | 
    2015:07:08-10:23:55 astaro pluto[15614]: | *received 68 bytes from 58.240.166.234:500 on eth1
    2015:07:08-10:23:55 astaro pluto[15614]: | ICOOKIE:  0a 66 3e df  8a 75 60 db
    2015:07:08-10:23:55 astaro pluto[15614]: | RCOOKIE:  c0 b3 4b df  8a 4d b7 64
    2015:07:08-10:23:55 astaro pluto[15614]: | peer:  3a f0 a6 ea
    2015:07:08-10:23:55 astaro pluto[15614]: | state hash entry 4
    2015:07:08-10:23:55 astaro pluto[15614]: | state object #4252 found, in STATE_MAIN_R2
    2015:07:08-10:23:55 astaro pluto[15614]: "S_REF_mSfPQBmhut_11"[614] 58.240.166.234 #4252: next payload type of ISAKMP Identification Payload has an unknown value: 194
    2015:07:08-10:23:55 astaro pluto[15614]: "S_REF_mSfPQBmhut_11"[614] 58.240.166.234 #4252: probable authentication failure (mismatch of preshared secrets?): malformed payload in packet
    2015:07:08-10:23:55 astaro pluto[15614]: "S_REF_mSfPQBmhut_11"[614] 58.240.166.234 #4252: sending encrypted notification PAYLOAD_MALFORMED to 58.240.166.234:500
    2015:07:08-10:23:55 astaro pluto[15614]: | next event EVENT_RETRANSMIT in 18 seconds for #4252
    2015:07:08-10:24:13 astaro pluto[15614]: | 
    2015:07:08-10:24:13 astaro pluto[15614]: | *time to handle event
    2015:07:08-10:24:13 astaro pluto[15614]: | event after this is EVENT_SHUNT_SCAN in 2 seconds
    2015:07:08-10:24:13 astaro pluto[15614]: | handling event EVENT_RETRANSMIT for 58.240.166.234 "S_REF_mSfPQBmhut_11" #4252
    2015:07:08-10:24:13 astaro pluto[15614]: | inserting event EVENT_RETRANSMIT, timeout in 40 seconds for #4252
    2015:07:08-10:24:13 astaro pluto[15614]: | next event EVENT_SHUNT_SCAN in 2 seconds
    2015:07:08-10:24:15 astaro pluto[15614]: | 
    2015:07:08-10:24:15 astaro pluto[15614]: | *time to handle event
    2015:07:08-10:24:15 astaro pluto[15614]: | event after this is EVENT_RETRANSMIT in 16 seconds
    2015:07:08-10:24:15 astaro pluto[15614]: | inserting event EVENT_SHUNT_SCAN, timeout in 120 seconds
    2015:07:08-10:24:15 astaro pluto[15614]: | scanning for shunt eroutes
    2015:07:08-10:24:15 astaro pluto[15614]: | next event EVENT_RETRANSMIT in 16 seconds for #4251
    2015:07:08-10:24:31 astaro pluto[15614]: | 
    2015:07:08-10:24:31 astaro pluto[15614]: | *time to handle event
    2015:07:08-10:24:31 astaro pluto[15614]: | event after this is EVENT_RETRANSMIT in 22 seconds
    2015:07:08-10:24:31 astaro pluto[15614]: | handling event EVENT_RETRANSMIT for 58.240.166.234 "S_REF_mSfPQBmhut_11" #4251
    2015:07:08-10:24:31 astaro pluto[15614]: "S_REF_mSfPQBmhut_11"[614] 58.240.166.234 #4251: max number of retransmissions (2) reached STATE_MAIN_R2
    2015:07:08-10:24:31 astaro pluto[15614]: | ICOOKIE:  37 5a 5f ea  7c 9b 54 b4
    2015:07:08-10:24:31 astaro pluto[15614]: | RCOOKIE:  99 29 ad 0d  f5 bc 37 6c
    2015:07:08-10:24:31 astaro pluto[15614]: | peer:  3a f0 a6 ea
    2015:07:08-10:24:31 astaro pluto[15614]: | state hash entry 3
    2015:07:08-10:24:31 astaro pluto[15614]: | next event EVENT_RETRANSMIT in 22 seconds for #4252
    2015:07:08-10:24:53 astaro pluto[15614]: | 
    2015:07:08-10:24:53 astaro pluto[15614]: | *time to handle event
    2015:07:08-10:24:53 astaro pluto[15614]: | event after this is EVENT_SHUNT_SCAN in 82 seconds
    2015:07:08-10:24:53 astaro pluto[15614]: | handling event EVENT_RETRANSMIT for 58.240.166.234 "S_REF_mSfPQBmhut_11" #4252
    2015:07:08-10:24:53 astaro pluto[15614]: "S_REF_mSfPQBmhut_11"[614] 58.240.166.234 #4252: max number of retransmissions (2) reached STATE_MAIN_R2
    2015:07:08-10:24:53 astaro pluto[15614]: | ICOOKIE:  0a 66 3e df  8a 75 60 db
    2015:07:08-10:24:53 astaro pluto[15614]: | RCOOKIE:  c0 b3 4b df  8a 4d b7 64
    2015:07:08-10:24:53 astaro pluto[15614]: | peer:  3a f0 a6 ea
    2015:07:08-10:24:53 astaro pluto[15614]: | state hash entry 4
    2015:07:08-10:24:53 astaro pluto[15614]: "S_REF_mSfPQBmhut_11"[614] 58.240.166.234: deleting connection "S_REF_mSfPQBmhut_11" instance with peer 58.240.166.234 {isakmp=#0/ipsec=#0}
    2015:07:08-10:24:53 astaro pluto[15614]: | next event EVENT_SHUNT_SCAN in 82 seconds
    2015:07:08-10:25:03 astaro pluto[15614]: | 
    2015:07:08-10:25:03 astaro pluto[15614]: | *received 256 bytes from xx9.193.***.***:500 on eth1
    2015:07:08-10:25:03 astaro pluto[15614]: packet from xx9.193.***.***:500: ignoring Vendor ID payload [882fe56d6fd20dbc2251613b2ebe5beb]
    2015:07:08-10:25:03 astaro pluto[15614]: packet from xx9.193.***.***:500: ignoring Vendor ID payload [Cisco-Unity]
    2015:07:08-10:25:03 astaro pluto[15614]: packet from xx9.193.***.***:500: received Vendor ID payload [XAUTH]
    2015:07:08-10:25:03 astaro pluto[15614]: packet from xx9.193.***.***:500: received Vendor ID payload [Dead Peer Detection]
    2015:07:08-10:25:03 astaro pluto[15614]: packet from xx9.193.***.***:500: ignoring Vendor ID payload [RFC 3947]
    2015:07:08-10:25:03 astaro pluto[15614]: packet from xx9.193.***.***:500: ignoring Vendor ID payload [draft-ietf-ipsec-nat-t-ike-03]
    2015:07:08-10:25:03 astaro pluto[15614]: packet from xx9.193.***.***:500: ignoring Vendor ID payload [draft-ietf-ipsec-nat-t-ike-02]
    2015:07:08-10:25:03 astaro pluto[15614]: packet from xx9.193.***.***:500: ignoring Vendor ID payload [draft-ietf-ipsec-nat-t-ike-02_n]
    2015:07:08-10:25:03 astaro pluto[15614]: packet from xx9.193.***.***:500: ignoring Vendor ID payload [draft-ietf-ipsec-nat-t-ike-00]
    2015:07:08-10:25:03 astaro pluto[15614]: | preparse_isakmp_policy: peer requests PSK authentication
    2015:07:08-10:25:03 astaro pluto[15614]: | creating state object #4253 at 0x811bb48
    2015:07:08-10:25:03 astaro pluto[15614]: | ICOOKIE:  ad 4c a6 a1  eb 97 5e 8a
    2015:07:08-10:25:03 astaro pluto[15614]: | RCOOKIE:  91 14 ec cd  c3 23 b3 fe
    2015:07:08-10:25:03 astaro pluto[15614]: | peer:  6d c1 f5 71
    2015:07:08-10:25:03 astaro pluto[15614]: | state hash entry 5
    2015:07:08-10:25:03 astaro pluto[15614]: | inserting event EVENT_SO_DISCARD, timeout in 0 seconds for #4253
    2015:07:08-10:25:03 astaro pluto[15614]: "S_REF_mSfPQBmhut_7"[7] xx9.193.***.*** #4253: responding to Main Mode from unknown peer xx9.193.***.***
    2015:07:08-10:25:03 astaro pluto[15614]: | inserting event EVENT_RETRANSMIT, timeout in 10 seconds for #4253
    2015:07:08-10:25:03 astaro pluto[15614]: | next event EVENT_RETRANSMIT in 10 seconds for #4253
    2015:07:08-10:25:03 astaro pluto[15614]: | 
    2015:07:08-10:25:03 astaro pluto[15614]: | *received 244 bytes from xx9.193.***.***:500 on eth1
    2015:07:08-10:25:03 astaro pluto[15614]: | ICOOKIE:  ad 4c a6 a1  eb 97 5e 8a
    2015:07:08-10:25:03 astaro pluto[15614]: | RCOOKIE:  91 14 ec cd  c3 23 b3 fe
    2015:07:08-10:25:03 astaro pluto[15614]: | peer:  6d c1 f5 71
    2015:07:08-10:25:03 astaro pluto[15614]: | state hash entry 5
    2015:07:08-10:25:03 astaro pluto[15614]: | state object #4253 found, in STATE_MAIN_R1
    2015:07:08-10:25:03 astaro pluto[15614]: | inserting event EVENT_RETRANSMIT, timeout in 10 seconds for #4253
    2015:07:08-10:25:03 astaro pluto[15614]: | next event EVENT_RETRANSMIT in 10 seconds for #4253
    2015:07:08-10:25:03 astaro pluto[15614]: | 
    2015:07:08-10:25:03 astaro pluto[15614]: | *received 60 bytes from xx9.193.***.***:500 on eth1
    2015:07:08-10:25:03 astaro pluto[15614]: | ICOOKIE:  ad 4c a6 a1  eb 97 5e 8a
    2015:07:08-10:25:03 astaro pluto[15614]: | RCOOKIE:  91 14 ec cd  c3 23 b3 fe
    2015:07:08-10:25:03 astaro pluto[15614]: | peer:  6d c1 f5 71
    2015:07:08-10:25:03 astaro pluto[15614]: | state hash entry 5
    2015:07:08-10:25:03 astaro pluto[15614]: | state object #4253 found, in STATE_MAIN_R2
    2015:07:08-10:25:03 astaro pluto[15614]: "S_REF_mSfPQBmhut_7"[7] xx9.193.***.*** #4253: Peer ID is ID_IPV4_ADDR: 'xx9.193.***.***'
    2015:07:08-10:25:03 astaro pluto[15614]: | peer CA:      '%none'
    2015:07:08-10:25:03 astaro pluto[15614]: | current connection is a full match -- no need to look further
    2015:07:08-10:25:03 astaro pluto[15614]: | offered CA:   '%none'
    2015:07:08-10:25:03 astaro pluto[15614]: | inserting event EVENT_SA_REPLACE, timeout in 7530 seconds for #4253
    2015:07:08-10:25:03 astaro pluto[15614]: "S_REF_mSfPQBmhut_7"[7] xx9.193.***.*** #4253: sent MR3, ISAKMP SA established
    2015:07:08-10:25:03 astaro pluto[15614]: | next event EVENT_SHUNT_SCAN in 72 seconds
    2015:07:08-10:25:03 astaro pluto[15614]: | 
    2015:07:08-10:25:03 astaro pluto[15614]: | *received 364 bytes from xx9.193.***.***:500 on eth1
    2015:07:08-10:25:03 astaro pluto[15614]: | ICOOKIE:  ad 4c a6 a1  eb 97 5e 8a
    2015:07:08-10:25:03 astaro pluto[15614]: | RCOOKIE:  91 14 ec cd  c3 23 b3 fe
    2015:07:08-10:25:03 astaro pluto[15614]: | peer:  6d c1 f5 71
    2015:07:08-10:25:03 astaro pluto[15614]: | state hash entry 5
    2015:07:08-10:25:03 astaro pluto[15614]: | state object not found
    2015:07:08-10:25:03 astaro pluto[15614]: | ICOOKIE:  ad 4c a6 a1  eb 97 5e 8a
    2015:07:08-10:25:03 astaro pluto[15614]: | RCOOKIE:  91 14 ec cd  c3 23 b3 fe
    2015:07:08-10:25:03 astaro pluto[15614]: | peer:  6d c1 f5 71
    2015:07:08-10:25:03 astaro pluto[15614]: | state hash entry 5
    2015:07:08-10:25:03 astaro pluto[15614]: | state object #4253 found, in STATE_MAIN_R3
    2015:07:08-10:25:03 astaro pluto[15614]: | peer client is subnet 172.16.0.0/16
    2015:07:08-10:25:03 astaro pluto[15614]: | peer client protocol/port is 0/0
    2015:07:08-10:25:03 astaro pluto[15614]: | our client is subnet 192.168.28.0/24
    2015:07:08-10:25:03 astaro pluto[15614]: | our client protocol/port is 0/0
    2015:07:08-10:25:03 astaro pluto[15614]: | using connection "S_REF_mSfPQBmhut_8"
    2015:07:08-10:25:03 astaro pluto[15614]: | duplicating state object #4253
    2015:07:08-10:25:03 astaro pluto[15614]: | creating state object #4254 at 0x81290c0
    2015:07:08-10:25:03 astaro pluto[15614]: | ICOOKIE:  ad 4c a6 a1  eb 97 5e 8a
    2015:07:08-10:25:03 astaro pluto[15614]: | RCOOKIE:  91 14 ec cd  c3 23 b3 fe
    2015:07:08-10:25:03 astaro pluto[15614]: | peer:  6d c1 f5 71
    2015:07:08-10:25:03 astaro pluto[15614]: | state hash entry 5
    2015:07:08-10:25:03 astaro pluto[15614]: | inserting event EVENT_SO_DISCARD, timeout in 0 seconds for #4254
    2015:07:08-10:25:03 astaro pluto[15614]: | generate SPI:  33 de 0b 6e
    2015:07:08-10:25:03 astaro pluto[15614]: "S_REF_mSfPQBmhut_8"[7] xx9.193.***.*** #4254: responding to Quick Mode
    2015:07:08-10:25:03 astaro pluto[15614]: | kernel_alg_esp_auth_keylen(auth=1, sadb_aalg=2): a_keylen=16
    2015:07:08-10:25:03 astaro pluto[15614]: | route owner of "S_REF_mSfPQBmhut_8"[7] xx9.193.***.*** erouted: self
    2015:07:08-10:25:03 astaro pluto[15614]: | install_inbound_ipsec_sa() checking if we can route
    2015:07:08-10:25:03 astaro pluto[15614]: | route owner of "S_REF_mSfPQBmhut_8"[7] xx9.193.***.*** erouted: self; eroute owner: self
    2015:07:08-10:25:03 astaro pluto[15614]: | add inbound eroute 172.16.0.0/16:0 -> 192.168.28.0/24:0 => tun.2a15@***.115.***.***:0
    2015:07:08-10:25:03 astaro pluto[15614]: | inserting event EVENT_RETRANSMIT, timeout in 10 seconds for #4254
    2015:07:08-10:25:03 astaro pluto[15614]: | next event EVENT_RETRANSMIT in 10 seconds for #4254
    2015:07:08-10:25:03 astaro pluto[15614]: | 
    2015:07:08-10:25:03 astaro pluto[15614]: | *received 364 bytes from xx9.193.***.***:500 on eth1
    2015:07:08-10:25:03 astaro pluto[15614]: | ICOOKIE:  ad 4c a6 a1  eb 97 5e 8a
    2015:07:08-10:25:03 astaro pluto[15614]: | RCOOKIE:  91 14 ec cd  c3 23 b3 fe
    2015:07:08-10:25:03 astaro pluto[15614]: | peer:  6d c1 f5 71
    2015:07:08-10:25:03 astaro pluto[15614]: | state hash entry 5
    2015:07:08-10:25:03 astaro pluto[15614]: | state object not found
    2015:07:08-10:25:03 astaro pluto[15614]: | ICOOKIE:  ad 4c a6 a1  eb 97 5e 8a
    2015:07:08-10:25:03 astaro pluto[15614]: | RCOOKIE:  91 14 ec cd  c3 23 b3 fe
    2015:07:08-10:25:03 astaro pluto[15614]: | peer:  6d c1 f5 71
    2015:07:08-10:25:03 astaro pluto[15614]: | state hash entry 5
    2015:07:08-10:25:03 astaro pluto[15614]: | state object #4253 found, in STATE_MAIN_R3
    2015:07:08-10:25:03 astaro pluto[15614]: | peer client is subnet 172.16.0.0/16
    2015:07:08-10:25:03 astaro pluto[15614]: | peer client protocol/port is 0/0
    2015:07:08-10:25:03 astaro pluto[15614]: | our client is subnet 192.168.22.0/24
    2015:07:08-10:25:03 astaro pluto[15614]: | our client protocol/port is 0/0
    2015:07:08-10:25:03 astaro pluto[15614]: | using connection "S_REF_mSfPQBmhut_2"
    2015:07:08-10:25:03 astaro pluto[15614]: | duplicating state object #4253
    2015:07:08-10:25:03 astaro pluto[15614]: | creating state object #4255 at 0x8128710
    2015:07:08-10:25:03 astaro pluto[15614]: | ICOOKIE:  ad 4c a6 a1  eb 97 5e 8a
    2015:07:08-10:25:03 astaro pluto[15614]: | RCOOKIE:  91 14 ec cd  c3 23 b3 fe
    2015:07:08-10:25:03 astaro pluto[15614]: | peer:  6d c1 f5 71
    2015:07:08-10:25:03 astaro pluto[15614]: | state hash entry 5
    2015:07:08-10:25:03 astaro pluto[15614]: | inserting event EVENT_SO_DISCARD, timeout in 0 seconds for #4255
    2015:07:08-10:25:03 astaro pluto[15614]: | generate SPI:  33 de 0b 6f
    2015:07:08-10:25:03 astaro pluto[15614]: "S_REF_mSfPQBmhut_2"[7] xx9.193.***.*** #4255: responding to Quick Mode
    2015:07:08-10:25:03 astaro pluto[15614]: | kernel_alg_esp_auth_keylen(auth=1, sadb_aalg=2): a_keylen=16
    2015:07:08-10:25:03 astaro pluto[15614]: | route owner of "S_REF_mSfPQBmhut_2"[7] xx9.193.***.*** erouted: self
    2015:07:08-10:25:03 astaro pluto[15614]: | install_inbound_ipsec_sa() checking if we can route
    2015:07:08-10:25:03 astaro pluto[15614]: | route owner of "S_REF_mSfPQBmhut_2"[7] xx9.193.***.*** erouted: self; eroute owner: self
    2015:07:08-10:25:03 astaro pluto[15614]: | add inbound eroute 172.16.0.0/16:0 -> 192.168.22.0/24:0 => tun.2a16@***.115.***.***:0
    2015:07:08-10:25:03 astaro pluto[15614]: | inserting event EVENT_RETRANSMIT, timeout in 10 seconds for #4255
    2015:07:08-10:25:03 astaro pluto[15614]: | next event EVENT_RETRANSMIT in 10 seconds for #4255
    2015:07:08-10:25:03 astaro pluto[15614]: | 
    2015:07:08-10:25:03 astaro pluto[15614]: | *received 364 bytes from xx9.193.***.***:500 on eth1
    2015:07:08-10:25:03 astaro pluto[15614]: | ICOOKIE:  ad 4c a6 a1  eb 97 5e 8a
    2015:07:08-10:25:03 astaro pluto[15614]: | RCOOKIE:  91 14 ec cd  c3 23 b3 fe
    2015:07:08-10:25:03 astaro pluto[15614]: | peer:  6d c1 f5 71
    2015:07:08-10:25:03 astaro pluto[15614]: | state hash entry 5
    2015:07:08-10:25:03 astaro pluto[15614]: | state object not found
    2015:07:08-10:25:03 astaro pluto[15614]: | ICOOKIE:  ad 4c a6 a1  eb 97 5e 8a
    2015:07:08-10:25:03 astaro pluto[15614]: | RCOOKIE:  91 14 ec cd  c3 23 b3 fe
    2015:07:08-10:25:03 astaro pluto[15614]: | peer:  6d c1 f5 71
    2015:07:08-10:25:03 astaro pluto[15614]: | state hash entry 5
    2015:07:08-10:25:03 astaro pluto[15614]: | state object #4253 found, in STATE_MAIN_R3
    2015:07:08-10:25:03 astaro pluto[15614]: | peer client is subnet 172.16.0.0/16
    2015:07:08-10:25:03 astaro pluto[15614]: | peer client protocol/port is 0/0
    2015:07:08-10:25:03 astaro pluto[15614]: | our client is subnet 192.168.21.0/24
    2015:07:08-10:25:03 astaro pluto[15614]: | our client protocol/port is 0/0
    2015:07:08-10:25:03 astaro pluto[15614]: | using connection "S_REF_mSfPQBmhut_6"
    2015:07:08-10:25:03 astaro pluto[15614]: | duplicating state object #4253
    2015:07:08-10:25:03 astaro pluto[15614]: | creating state object #4256 at 0x8128c50
    2015:07:08-10:25:03 astaro pluto[15614]: | ICOOKIE:  ad 4c a6 a1  eb 97 5e 8a
    2015:07:08-10:25:03 astaro pluto[15614]: | RCOOKIE:  91 14 ec cd  c3 23 b3 fe
    2015:07:08-10:25:03 astaro pluto[15614]: | peer:  6d c1 f5 71
    2015:07:08-10:25:03 astaro pluto[15614]: | state hash entry 5
    2015:07:08-10:25:03 astaro pluto[15614]: | inserting event EVENT_SO_DISCARD, timeout in 0 seconds for #4256
    2015:07:08-10:25:03 astaro pluto[15614]: | generate SPI:  33 de 0b 70
    2015:07:08-10:25:03 astaro pluto[15614]: "S_REF_mSfPQBmhut_6"[7] xx9.193.***.*** #4256: responding to Quick Mode
    2015:07:08-10:25:03 astaro pluto[15614]: | kernel_alg_esp_auth_keylen(auth=1, sadb_aalg=2): a_keylen=16
    2015:07:08-10:25:03 astaro pluto[15614]: | route owner of "S_REF_mSfPQBmhut_6"[7] xx9.193.***.*** erouted: self
    2015:07:08-10:25:03 astaro pluto[15614]: | install_inbound_ipsec_sa() checking if we can route
    2015:07:08-10:25:03 astaro pluto[15614]: | route owner of "S_REF_mSfPQBmhut_6"[7] xx9.193.***.*** erouted: self; eroute owner: self
    2015:07:08-10:25:03 astaro pluto[15614]: | add inbound eroute 172.16.0.0/16:0 -> 192.168.21.0/24:0 => tun.2a17@***.115.***.***:0
    2015:07:08-10:25:03 astaro pluto[15614]: | inserting event EVENT_RETRANSMIT, timeout in 10 seconds for #4256
    2015:07:08-10:25:03 astaro pluto[15614]: | next event EVENT_RETRANSMIT in 10 seconds for #4256
    2015:07:08-10:25:03 astaro pluto[15614]: | 
    2015:07:08-10:25:03 astaro pluto[15614]: | *received 364 bytes from xx9.193.***.***:500 on eth1
    2015:07:08-10:25:03 astaro pluto[15614]: | ICOOKIE:  ad 4c a6 a1  eb 97 5e 8a
    2015:07:08-10:25:03 astaro pluto[15614]: | RCOOKIE:  91 14 ec cd  c3 23 b3 fe
    2015:07:08-10:25:03 astaro pluto[15614]: | peer:  6d c1 f5 71
    2015:07:08-10:25:03 astaro pluto[15614]: | state hash entry 5
    2015:07:08-10:25:03 astaro pluto[15614]: | state object not found
    2015:07:08-10:25:03 astaro pluto[15614]: | ICOOKIE:  ad 4c a6 a1  eb 97 5e 8a
    2015:07:08-10:25:03 astaro pluto[15614]: | RCOOKIE:  91 14 ec cd  c3 23 b3 fe
    2015:07:08-10:25:03 astaro pluto[15614]: | peer:  6d c1 f5 71
    2015:07:08-10:25:03 astaro pluto[15614]: | state hash entry 5
    2015:07:08-10:25:03 astaro pluto[15614]: | state object #4253 found, in STATE_MAIN_R3
    2015:07:08-10:25:03 astaro pluto[15614]: | peer client is subnet 172.16.0.0/16
    2015:07:08-10:25:03 astaro pluto[15614]: | peer client protocol/port is 0/0
    2015:07:08-10:25:03 astaro pluto[15614]: | our client is subnet 192.168.20.0/24
    2015:07:08-10:25:03 astaro pluto[15614]: | our client protocol/port is 0/0
    2015:07:08-10:25:03 astaro pluto[15614]: | using connection "S_REF_mSfPQBmhut_0"
    2015:07:08-10:25:03 astaro pluto[15614]: | duplicating state object #4253
    2015:07:08-10:25:03 astaro pluto[15614]: | creating state object #4257 at 0x81308d0
    2015:07:08-10:25:03 astaro pluto[15614]: | ICOOKIE:  ad 4c a6 a1  eb 97 5e 8a
    2015:07:08-10:25:03 astaro pluto[15614]: | RCOOKIE:  91 14 ec cd  c3 23 b3 fe
    2015:07:08-10:25:03 astaro pluto[15614]: | peer:  6d c1 f5 71
    2015:07:08-10:25:03 astaro pluto[15614]: | state hash entry 5
    2015:07:08-10:25:03 astaro pluto[15614]: | inserting event EVENT_SO_DISCARD, timeout in 0 seconds for #4257
    2015:07:08-10:25:03 astaro pluto[15614]: | generate SPI:  33 de 0b 71
    2015:07:08-10:25:03 astaro pluto[15614]: "S_REF_mSfPQBmhut_0"[7] xx9.193.***.*** #4257: responding to Quick Mode
    2015:07:08-10:25:03 astaro pluto[15614]: | kernel_alg_esp_auth_keylen(auth=1, sadb_aalg=2): a_keylen=16
    2015:07:08-10:25:03 astaro pluto[15614]: | route owner of "S_REF_mSfPQBmhut_0"[7] xx9.193.***.*** erouted: self
    2015:07:08-10:25:03 astaro pluto[15614]: | install_inbound_ipsec_sa() checking if we can route
    2015:07:08-10:25:03 astaro pluto[15614]: | route owner of "S_REF_mSfPQBmhut_0"[7] xx9.193.***.*** erouted: self; eroute owner: self
    2015:07:08-10:25:03 astaro pluto[15614]: | add inbound eroute 172.16.0.0/16:0 -> 192.168.20.0/24:0 => tun.2a18@***.115.***.***:0
    2015:07:08-10:25:03 astaro pluto[15614]: | inserting event EVENT_RETRANSMIT, timeout in 10 seconds for #4257
    2015:07:08-10:25:03 astaro pluto[15614]: | next event EVENT_RETRANSMIT in 10 seconds for #4257
  • Sophos V9 Log:


    2015:07:08-10:08:01 asg pluto[25998]: "S_REF_IpsSitSfipsTunne_6" #762: initiating Quick Mode PSK+ENCRYPT+TUNNEL+PFS+UP to replace #749 {using isakmp#745}
    2015:07:08-10:08:01 asg pluto[25998]: "S_REF_IpsSitSfipsTunne_6" #762: sent QI2, IPsec SA established {ESP=>0x33de0b66 0x33de0b67 0x33de0b68 0x33de0b69 0x33de0b6a 0x33de0b6b 0x33de0b6c 0x33de0b6d 0x33de0b6e 0x33de0b6f 0x33de0b70 0x33de0b71 0x33de0b72 0x33de0b73 0x33de0b74 0x33de0b75 0x33de0b76 0x33de0b77 0x33de0b78 0x33de0b79 
Reply
  • Sophos V9 Log:


    2015:07:08-10:08:01 asg pluto[25998]: "S_REF_IpsSitSfipsTunne_6" #762: initiating Quick Mode PSK+ENCRYPT+TUNNEL+PFS+UP to replace #749 {using isakmp#745}
    2015:07:08-10:08:01 asg pluto[25998]: "S_REF_IpsSitSfipsTunne_6" #762: sent QI2, IPsec SA established {ESP=>0x33de0b66 0x33de0b67 0x33de0b68 0x33de0b69 0x33de0b6a 0x33de0b6b 0x33de0b6c 0x33de0b6d 0x33de0b6e 0x33de0b6f 0x33de0b70 0x33de0b71 0x33de0b72 0x33de0b73 0x33de0b74 0x33de0b75 0x33de0b76 0x33de0b77 0x33de0b78 0x33de0b79 
Children
No Data