2010:01:04-20:04:47 acenn pluto[4451]: | 2010:01:04-20:04:47 acenn pluto[4451]: | *time to handle event 2010:01:04-20:04:47 acenn pluto[4451]: | event after this is EVENT_NAT_T_KEEPALIVE in 53 seconds 2010:01:04-20:04:47 acenn pluto[4451]: | ICOOKIE: ac 22 ed 69 4c d7 06 36 2010:01:04-20:04:47 acenn pluto[4451]: | RCOOKIE: c0 f6 9e 06 6f 4e df c9 2010:01:04-20:04:47 acenn pluto[4451]: | peer: c0 a8 02 96 2010:01:04-20:04:47 acenn pluto[4451]: | state hash entry 4 2010:01:04-20:04:47 acenn pluto[4451]: | next event EVENT_NAT_T_KEEPALIVE in 53 seconds 2010:01:04-20:04:52 acenn pluto[4451]: | 2010:01:04-20:04:52 acenn pluto[4451]: | *received 1036 bytes from 192.168.2.150:1144 on eth1 2010:01:04-20:04:52 acenn pluto[4451]: | ICOOKIE: ac 22 ed 69 4c d7 06 36 2010:01:04-20:04:52 acenn pluto[4451]: | RCOOKIE: c0 f6 9e 06 6f 4e df c9 2010:01:04-20:04:52 acenn pluto[4451]: | peer: c0 a8 02 96 2010:01:04-20:04:52 acenn pluto[4451]: | state hash entry 4 2010:01:04-20:04:52 acenn pluto[4451]: | state object not found 2010:01:04-20:04:52 acenn pluto[4451]: | ICOOKIE: ac 22 ed 69 4c d7 06 36 2010:01:04-20:04:52 acenn pluto[4451]: | RCOOKIE: c0 f6 9e 06 6f 4e df c9 2010:01:04-20:04:52 acenn pluto[4451]: | peer: c0 a8 02 96 2010:01:04-20:04:52 acenn pluto[4451]: | state hash entry 4 2010:01:04-20:04:52 acenn pluto[4451]: | state object #3 found, in STATE_MODE_CFG_R1 2010:01:04-20:04:52 acenn pluto[4451]: "D_REF_zfmTEQwktH"[2] 192.168.2.150:1144 #3: Quick Mode I1 message is unacceptable because it uses a previously used Message ID 0x11513fd7 (perhaps this is a duplicated packet) 2010:01:04-20:04:52 acenn pluto[4451]: "D_REF_zfmTEQwktH"[2] 192.168.2.150:1144 #3: sending encrypted notification INVALID_MESSAGE_ID to 192.168.2.150:1144 2010:01:04-20:04:52 acenn pluto[4451]: | next event EVENT_NAT_T_KEEPALIVE in 48 seconds 2010:01:04-20:04:57 acenn pluto[4451]: | 2010:01:04-20:04:57 acenn pluto[4451]: | *received 1036 bytes from 192.168.2.150:1144 on eth1 2010:01:04-20:04:57 acenn pluto[4451]: | ICOOKIE: ac 22 ed 69 4c d7 06 36 2010:01:04-20:04:57 acenn pluto[4451]: | RCOOKIE: c0 f6 9e 06 6f 4e df c9 2010:01:04-20:04:57 acenn pluto[4451]: | peer: c0 a8 02 96 2010:01:04-20:04:57 acenn pluto[4451]: | state hash entry 4 2010:01:04-20:04:57 acenn pluto[4451]: | state object not found 2010:01:04-20:04:57 acenn pluto[4451]: | ICOOKIE: ac 22 ed 69 4c d7 06 36 2010:01:04-20:04:57 acenn pluto[4451]: | RCOOKIE: c0 f6 9e 06 6f 4e df c9 2010:01:04-20:04:57 acenn pluto[4451]: | peer: c0 a8 02 96 2010:01:04-20:04:57 acenn pluto[4451]: | state hash entry 4 2010:01:04-20:04:57 acenn pluto[4451]: | state object #3 found, in STATE_MODE_CFG_R1 2010:01:04-20:04:57 acenn pluto[4451]: "D_REF_zfmTEQwktH"[2] 192.168.2.150:1144 #3: Quick Mode I1 message is unacceptable because it uses a previously used Message ID 0x11513fd7 (perhaps this is a duplicated packet) 2010:01:04-20:04:57 acenn pluto[4451]: "D_REF_zfmTEQwktH"[2] 192.168.2.150:1144 #3: sending encrypted notification INVALID_MESSAGE_ID to 192.168.2.150:1144 2010:01:04-20:04:57 acenn pluto[4451]: | next event EVENT_NAT_T_KEEPALIVE in 43 seconds 2010:01:04-20:04:57 acenn pluto[4451]: | 2010:01:04-20:04:57 acenn pluto[4451]: | *received 92 bytes from 192.168.2.150:1144 on eth1 2010:01:04-20:04:57 acenn pluto[4451]: | ICOOKIE: ac 22 ed 69 4c d7 06 36 2010:01:04-20:04:57 acenn pluto[4451]: | RCOOKIE: c0 f6 9e 06 6f 4e df c9 2010:01:04-20:04:57 acenn pluto[4451]: | peer: c0 a8 02 96 2010:01:04-20:04:57 acenn pluto[4451]: | state hash entry 4 2010:01:04-20:04:57 acenn pluto[4451]: | state object #3 found, in STATE_MODE_CFG_R1 2010:01:04-20:04:57 acenn pluto[4451]: | received DPD notification R_U_THERE with seqno = 801447672 2010:01:04-20:04:57 acenn pluto[4451]: | sent DPD notification R_U_THERE_ACK with seqno = 801447672 2010:01:04-20:04:57 acenn pluto[4451]: | next event EVENT_NAT_T_KEEPALIVE in 43 seconds 2010:01:04-20:05:02 acenn pluto[4451]: | 2010:01:04-20:05:02 acenn pluto[4451]: | *received 1036 bytes from 192.168.2.150:1144 on eth1 2010:01:04-20:05:02 acenn pluto[4451]: | ICOOKIE: ac 22 ed 69 4c d7 06 36 2010:01:04-20:05:02 acenn pluto[4451]: | RCOOKIE: c0 f6 9e 06 6f 4e df c9 2010:01:04-20:05:02 acenn pluto[4451]: | peer: c0 a8 02 96 2010:01:04-20:05:02 acenn pluto[4451]: | state hash entry 4 2010:01:04-20:05:02 acenn pluto[4451]: | state object not found 2010:01:04-20:05:02 acenn pluto[4451]: | ICOOKIE: ac 22 ed 69 4c d7 06 36 2010:01:04-20:05:02 acenn pluto[4451]: | RCOOKIE: c0 f6 9e 06 6f 4e df c9 2010:01:04-20:05:02 acenn pluto[4451]: | peer: c0 a8 02 96 2010:01:04-20:05:02 acenn pluto[4451]: | state hash entry 4 2010:01:04-20:05:02 acenn pluto[4451]: | state object #3 found, in STATE_MODE_CFG_R1 2010:01:04-20:05:02 acenn pluto[4451]: "D_REF_zfmTEQwktH"[2] 192.168.2.150:1144 #3: Quick Mode I1 message is unacceptable because it uses a previously used Message ID 0x11513fd7 (perhaps this is a duplicated packet) 2010:01:04-20:05:02 acenn pluto[4451]: "D_REF_zfmTEQwktH"[2] 192.168.2.150:1144 #3: sending encrypted notification INVALID_MESSAGE_ID to 192.168.2.150:1144 2010:01:04-20:05:02 acenn pluto[4451]: | next event EVENT_NAT_T_KEEPALIVE in 38 seconds 2010:01:04-20:05:07 acenn pluto[4451]: | 2010:01:04-20:05:07 acenn pluto[4451]: | *received 92 bytes from 192.168.2.150:1144 on eth1 2010:01:04-20:05:07 acenn pluto[4451]: | ICOOKIE: ac 22 ed 69 4c d7 06 36 2010:01:04-20:05:07 acenn pluto[4451]: | RCOOKIE: c0 f6 9e 06 6f 4e df c9 2010:01:04-20:05:07 acenn pluto[4451]: | peer: c0 a8 02 96 2010:01:04-20:05:07 acenn pluto[4451]: | state hash entry 4 2010:01:04-20:05:07 acenn pluto[4451]: | state object #3 found, in STATE_MODE_CFG_R1 2010:01:04-20:05:07 acenn pluto[4451]: | received DPD notification R_U_THERE with seqno = 801447673 2010:01:04-20:05:07 acenn pluto[4451]: | sent DPD notification R_U_THERE_ACK with seqno = 801447673 2010:01:04-20:05:07 acenn pluto[4451]: | next event EVENT_NAT_T_KEEPALIVE in 33 seconds 2010:01:04-20:05:07 acenn pluto[4451]: | 2010:01:04-20:05:07 acenn pluto[4451]: | *received 76 bytes from 192.168.2.150:1144 on eth1 2010:01:04-20:05:07 acenn pluto[4451]: | ICOOKIE: ac 22 ed 69 4c d7 06 36 2010:01:04-20:05:07 acenn pluto[4451]: | RCOOKIE: c0 f6 9e 06 6f 4e df c9 2010:01:04-20:05:07 acenn pluto[4451]: | peer: c0 a8 02 96 2010:01:04-20:05:07 acenn pluto[4451]: | state hash entry 4 2010:01:04-20:05:07 acenn pluto[4451]: | state object #3 found, in STATE_MODE_CFG_R1 2010:01:04-20:05:07 acenn pluto[4451]: "D_REF_zfmTEQwktH"[2] 192.168.2.150:1144 #3: ignoring Delete SA payload: PROTO_IPSEC_ESP SA(0x52f92577) not found (maybe expired) 2010:01:04-20:05:07 acenn pluto[4451]: | next event EVENT_NAT_T_KEEPALIVE in 33 seconds 2010:01:04-20:05:17 acenn pluto[4451]: | 2010:01:04-20:05:17 acenn pluto[4451]: | *received 92 bytes from 192.168.2.150:1144 on eth1 2010:01:04-20:05:17 acenn pluto[4451]: | ICOOKIE: ac 22 ed 69 4c d7 06 36 2010:01:04-20:05:17 acenn pluto[4451]: | RCOOKIE: c0 f6 9e 06 6f 4e df c9 2010:01:04-20:05:17 acenn pluto[4451]: | peer: c0 a8 02 96 2010:01:04-20:05:17 acenn pluto[4451]: | state hash entry 4 2010:01:04-20:05:17 acenn pluto[4451]: | state object #3 found, in STATE_MODE_CFG_R1 2010:01:04-20:05:17 acenn pluto[4451]: | received DPD notification R_U_THERE with seqno = 801447674 2010:01:04-20:05:17 acenn pluto[4451]: | sent DPD notification R_U_THERE_ACK with seqno = 801447674 2010:01:04-20:05:17 acenn pluto[4451]: | next event EVENT_NAT_T_KEEPALIVE in 23 seconds 2010:01:04-20:05:27 acenn pluto[4451]: | 2010:01:04-20:05:27 acenn pluto[4451]: | *received 92 bytes from 192.168.2.150:1144 on eth1 2010:01:04-20:05:27 acenn pluto[4451]: | ICOOKIE: ac 22 ed 69 4c d7 06 36 2010:01:04-20:05:27 acenn pluto[4451]: | RCOOKIE: c0 f6 9e 06 6f 4e df c9 2010:01:04-20:05:27 acenn pluto[4451]: | peer: c0 a8 02 96 2010:01:04-20:05:27 acenn pluto[4451]: | state hash entry 4 2010:01:04-20:05:27 acenn pluto[4451]: | state object #3 found, in STATE_MODE_CFG_R1 2010:01:04-20:05:27 acenn pluto[4451]: | received DPD notification R_U_THERE with seqno = 801447675 2010:01:04-20:05:27 acenn pluto[4451]: | sent DPD notification R_U_THERE_ACK with seqno = 801447675 2010:01:04-20:05:27 acenn pluto[4451]: | next event EVENT_NAT_T_KEEPALIVE in 13 seconds 2010:01:04-20:05:37 acenn pluto[4451]: | 2010:01:04-20:05:37 acenn pluto[4451]: | *received 92 bytes from 192.168.2.150:1144 on eth1 2010:01:04-20:05:37 acenn pluto[4451]: | ICOOKIE: ac 22 ed 69 4c d7 06 36 2010:01:04-20:05:37 acenn pluto[4451]: | RCOOKIE: c0 f6 9e 06 6f 4e df c9 2010:01:04-20:05:37 acenn pluto[4451]: | peer: c0 a8 02 96 2010:01:04-20:05:37 acenn pluto[4451]: | state hash entry 4 2010:01:04-20:05:37 acenn pluto[4451]: | state object #3 found, in STATE_MODE_CFG_R1 2010:01:04-20:05:37 acenn pluto[4451]: | ICOOKIE: ac 22 ed 69 4c d7 06 36 2010:01:04-20:05:37 acenn pluto[4451]: | RCOOKIE: c0 f6 9e 06 6f 4e df c9 2010:01:04-20:05:37 acenn pluto[4451]: | peer: c0 a8 02 96 2010:01:04-20:05:37 acenn pluto[4451]: | state hash entry 4 2010:01:04-20:05:37 acenn pluto[4451]: | state object #3 found, in STATE_MODE_CFG_R1 2010:01:04-20:05:37 acenn pluto[4451]: "D_REF_zfmTEQwktH"[2] 192.168.2.150:1144 #3: received Delete SA payload: deleting ISAKMP State #3 2010:01:04-20:05:37 acenn pluto[4451]: | ICOOKIE: ac 22 ed 69 4c d7 06 36 2010:01:04-20:05:37 acenn pluto[4451]: | RCOOKIE: c0 f6 9e 06 6f 4e df c9 2010:01:04-20:05:37 acenn pluto[4451]: | peer: c0 a8 02 96 2010:01:04-20:05:37 acenn pluto[4451]: | state hash entry 4 2010:01:04-20:05:37 acenn pluto[4451]: "D_REF_zfmTEQwktH"[2] 192.168.2.150:1144: deleting connection "D_REF_zfmTEQwktH" instance with peer 192.168.2.150 {isakmp=#0/ipsec=#0} 2010:01:04-20:05:37 acenn pluto[4451]: | next event EVENT_NAT_T_KEEPALIVE in 3 seconds 2010:01:04-20:05:40 acenn pluto[4451]: | 2010:01:04-20:05:40 acenn pluto[4451]: | *time to handle event 2010:01:04-20:05:40 acenn pluto[4451]: | event after this is EVENT_REINIT_SECRET in 1361 seconds 2010:01:04-20:05:40 acenn pluto[4451]: | next event EVENT_REINIT_SECRET in 1361 seconds