test#show cry isa sa dst src state conn-id slot status A.B.C.254 X.Y.Z.152 AG_INIT_EXCH 8 0 ACTIVE A.B.C.254 X.Y.Z.152 MM_NO_STATE 7 0 ACTIVE (deleted) test#show cry ses Crypto session current status Interface: FastEthernet4 Session status: DOWN-NEGOTIATING Peer: A.B.C.254 port 500 IKE SA: local X.Y.Z.152/500 remote A.B.C.254/500 Inactive IKE SA: local X.Y.Z.152/500 remote A.B.C.254/500 Inactive test#show cry ips cli ez Easy VPN Remote Phase: 4 Tunnel name : testVPN Inside interface list: BVI1, Outside interface: FastEthernet4 Current State: READY Last Event: CONNECT Save Password: Disallowed Current EzVPN Peer:A.B.C.254 test#show run Building configuration... Current configuration : 3926 bytes ! version 12.3 no service pad service tcp-keepalives-in service tcp-keepalives-out service timestamps debug datetime msec localtime show-timezone service timestamps log datetime msec localtime show-timezone service password-encryption service sequence-numbers ! hostname test ! boot-start-marker boot-end-marker ! logging buffered 51200 debugging logging console critical ! aaa new-model ! ! aaa authentication login local_authen local aaa authorization exec local_author local aaa session-id common ip subnet-zero no ip source-route ip cef ip dhcp excluded-address 172.17.16.33 ! ip dhcp pool sdm-pool1 import all network 172.17.16.32 255.255.255.224 default-router 172.17.16.33 ! ! ip tcp synwait-time 10 no ip bootp server ip domain name yourdomain.com ip ssh time-out 60 ip ssh authentication-retries 2 ip ddns update method sdm_ddns1 DDNS both ! no ftp-server write-enable ! ! ! ! crypto isakmp keepalive 30 periodic ! crypto ipsec security-association lifetime seconds 86400 ! crypto ipsec client ezvpn testVPN connect auto group testVPN key *** mode network-extension peer A.B.C.254 acl 111 xauth userid mode interactive ! bridge irb ! ! interface Null0 no ip unreachables ! interface FastEthernet0 no ip address no cdp enable ! interface FastEthernet1 no ip address no cdp enable ! interface FastEthernet2 no ip address no cdp enable ! interface FastEthernet3 no ip address no cdp enable ! interface FastEthernet4 description $ETH-WAN$$FW_OUTSIDE$$ES_WAN$ ip address dhcp client-id FastEthernet4 no ip redirects no ip unreachables no ip proxy-arp ip nat outside ip virtual-reassembly ip route-cache flow duplex auto speed auto no cdp enable crypto ipsec client ezvpn testVPN ! interface Dot11Radio0 no ip address ! ssid YMS-SP authentication open ! speed basic-1.0 basic-2.0 basic-5.5 6.0 9.0 basic-11.0 12.0 18.0 24.0 36.0 48.0 54.0 station-role root no cdp enable bridge-group 1 bridge-group 1 spanning-disabled ! interface Vlan1 description $ETH-SW-LAUNCH$$INTF-INFO-HWIC 4ESW$$FW_INSIDE$ no ip address no ip proxy-arp bridge-group 1 ! interface BVI1 description $ES_LAN$$FW_INSIDE$ ip address 172.17.16.33 255.255.255.224 no ip redirects no ip unreachables no ip proxy-arp ip nat inside ip virtual-reassembly ip route-cache flow ip tcp adjust-mss 1452 crypto ipsec client ezvpn testVPN inside ! ip classless ! ip http server ip http authentication local ip http secure-server ip http timeout-policy idle 5 life 86400 requests 10000 ip nat inside source route-map SDM_RMAP_1 interface FastEthernet4 overload ! logging trap debugging access-list 1 remark INSIDE_IF=BVI1 access-list 1 remark SDM_ACL Category=2 access-list 1 permit 172.17.16.32 0.0.0.31 access-list 111 remark SDM_ACL Category=4 access-list 111 permit ip 172.17.16.32 0.0.0.31 172.17.41.0 0.0.0.255 log access-list 125 remark SDM_ACL Category=2 access-list 125 deny ip 172.17.16.32 0.0.0.31 172.17.41.0 0.0.0.255 access-list 125 permit ip 172.17.16.32 0.0.0.31 any no cdp run route-map SDM_RMAP_1 permit 1 match ip address 125 ! ! control-plane ! bridge 1 protocol ieee bridge 1 route ip ! test# test#show log 001485: *Feb 22 20:01:33.331 ASAS: EZVPN(testVPN): ezvpn_reset 001486: *Feb 22 20:01:33.331 ASAS: EZVPN(testVPN): New State: CONNECT_REQUIRED 001487: *Feb 22 20:01:33.335 ASAS: EZVPN(testVPN): Current State: CONNECT_REQUIRED 001488: *Feb 22 20:01:33.335 ASAS: EZVPN(testVPN): Event: CONNECT 001489: *Feb 22 20:01:33.335 ASAS: EZVPN(testVPN): ezvpn_connect_request 001490: *Feb 22 20:01:33.335 ASAS: EZVPN(testVPN): Found valid peer A.B.C.254 001491: *Feb 22 20:01:33.335 ASAS: EZVPN(testVPN): Added PSK for address A.B.C.254 001492: *Feb 22 20:01:33.335 ASAS: EZVPN(testVPN): New State: READY 001493: *Feb 22 20:01:33.339 ASAS: ISAKMP:(0:12:HW:2):SA is still budding. Attached new ipsec request to it. (local X.Y.Z.152, remote A.B.C. 254) 001494: *Feb 22 20:01:39.747 ASAS: ISAKMP:(0:12:HW:2): retransmitting phase 1 AG_INIT_EXCH... 001495: *Feb 22 20:01:39.747 ASAS: ISAKMP:(0:12:HW:2):incrementing error counter on sa: retransmit phase 1 001496: *Feb 22 20:01:39.747 ASAS: ISAKMP:(0:12:HW:2): retransmitting phase 1 AG_INIT_EXCH 001497: *Feb 22 20:01:39.747 ASAS: ISAKMP:(0:12:HW:2): sending packet to A.B.C.254 my_port 500 peer_port 500 (I) AG_INIT_EXCH 001498: *Feb 22 20:01:49.747 ASAS: ISAKMP:(0:12:HW:2): retransmitting phase 1 AG_INIT_EXCH... 001499: *Feb 22 20:01:49.747 ASAS: ISAKMP:(0:12:HW:2):incrementing error counter on sa: retransmit phase 1 001500: *Feb 22 20:01:49.747 ASAS: ISAKMP:(0:12:HW:2): retransmitting phase 1 AG_INIT_EXCH 001501: *Feb 22 20:01:49.747 ASAS: ISAKMP:(0:12:HW:2): sending packet to A.B.C.254 my_port 500 peer_port 500 (I) AG_INIT_EXCH 001502: *Feb 22 20:01:59.747 ASAS: ISAKMP:(0:12:HW:2): retransmitting phase 1 AG_INIT_EXCH... 001503: *Feb 22 20:01:59.747 ASAS: ISAKMP:(0:12:HW:2):incrementing error counter on sa: retransmit phase 1 001504: *Feb 22 20:01:59.747 ASAS: ISAKMP:(0:12:HW:2): retransmitting phase 1 AG_INIT_EXCH 001505: *Feb 22 20:01:59.747 ASAS: ISAKMP:(0:12:HW:2): sending packet to A.B.C.254 my_port 500 peer_port 500 (I) AG_INIT_EXCH 001506: *Feb 22 20:02:09.747 ASAS: ISAKMP:(0:12:HW:2): retransmitting phase 1 AG_INIT_EXCH... 001507: *Feb 22 20:02:09.747 ASAS: ISAKMP:(0:12:HW:2):incrementing error counter on sa: retransmit phase 1 001508: *Feb 22 20:02:09.747 ASAS: ISAKMP:(0:12:HW:2): retransmitting phase 1 AG_INIT_EXCH 001509: *Feb 22 20:02:09.747 ASAS: ISAKMP:(0:12:HW:2): sending packet to A.B.C.254 my_port 500 peer_port 500 (I) AG_INIT_EXCH 001510: *Feb 22 20:02:19.711 ASAS: ISAKMP:(0:11:HW:2):purging SA., sa=82A47EC4, delme=82A47EC4 001511: *Feb 22 20:02:19.747 ASAS: ISAKMP:(0:12:HW:2): retransmitting phase 1 AG_INIT_EXCH... 001512: *Feb 22 20:02:19.747 ASAS: ISAKMP:(0:12:HW:2):peer does not do paranoid keepalives. 001513: *Feb 22 20:02:19.747 ASAS: ISAKMP:(0:12:HW:2):deleting SA reason "Death by retransmission P1" state (I) AG_INIT_EXCH (peer A.B.C.254) 001514: *Feb 22 20:02:19.747 ASAS: EZVPN(testVPN): Current State: READY 001515: *Feb 22 20:02:19.747 ASAS: EZVPN(testVPN): Event: CONNECT_NEXT_PEER 001516: *Feb 22 20:02:19.747 ASAS: EZVPN(testVPN): ezvpn_close 001517: *Feb 22 20:02:19.747 ASAS: EZVPN(testVPN): Deleted PSK for address A.B.C.254 001518: *Feb 22 20:02:19.747 ASAS: EZVPN(testVPN): New active peer is A.B.C.254 001519: *Feb 22 20:02:19.747 ASAS: EZVPN(testVPN): Attempting to connect to peer A.B.C.254 001520: *Feb 22 20:02:19.747 ASAS: EZVPN(testVPN): New State: CONNECT_REQUIRED 001521: *Feb 22 20:02:19.747 ASAS: ISAKMP:(0:12:HW:2):deleting SA reason "Death by retransmission P1" state (I) AG_INIT_EXCH (peer A.B.C.254) 001522: *Feb 22 20:02:19.747 ASAS: ISAKMP:(0:12:HW:2):Input = IKE_MESG_INTERNAL, IKE_PHASE1_DEL 001523: *Feb 22 20:02:19.747 ASAS: ISAKMP:(0:12:HW:2):Old State = IKE_I_AM1 New State = IKE_DEST_SA 001524: *Feb 22 20:02:19.747 ASAS: EZVPN(testVPN): Current State: CONNECT_REQUIRED 001525: *Feb 22 20:02:19.747 ASAS: EZVPN(testVPN): Event: CONNECT 001526: *Feb 22 20:02:19.747 ASAS: EZVPN(testVPN): ezvpn_connect_request 001527: *Feb 22 20:02:19.751 ASAS: EZVPN(testVPN): Found valid peer A.B.C.254 001528: *Feb 22 20:02:19.751 ASAS: EZVPN(testVPN): Added PSK for address A.B.C.254 001529: *Feb 22 20:02:19.751 ASAS: EZVPN(testVPN): New State: READY 001530: *Feb 22 20:02:19.751 ASAS: ISAKMP: local port 500, remote port 500 001531: *Feb 22 20:02:19.751 ASAS: ISAKMP: Find a dup sa in the avl tree during calling isadb_insert sa = 829A6F60 001532: *Feb 22 20:02:19.751 ASAS: ISAKMP:(0:0:N/A:0): client mode configured. 001533: *Feb 22 20:02:19.751 ASAS: ISAKMP:(0:0:N/A:0): constructed NAT-T vendor-07 ID 001534: *Feb 22 20:02:19.751 ASAS: ISAKMP:(0:0:N/A:0): constructed NAT-T vendor-03 ID 001535: *Feb 22 20:02:19.751 ASAS: ISAKMP:(0:0:N/A:0): constructed NAT-T vendor-02 ID 001536: *Feb 22 20:02:19.755 ASAS: EZVPN(testVPN): Current State: READY 001537: *Feb 22 20:02:19.755 ASAS: EZVPN(testVPN): Event: CONN_DOWN 001538: *Feb 22 20:02:19.755 ASAS: EZVPN(testVPN): ezvpn_close 001539: *Feb 22 20:02:19.755 ASAS: EZVPN(testVPN): Deleted PSK for address A.B.C.254 001540: *Feb 22 20:02:19.755 ASAS: EZVPN(testVPN): New State: CONNECT_REQUIRED 001541: *Feb 22 20:02:19.755 ASAS: EZVPN(testVPN): Current State: CONNECT_REQUIRED 001542: *Feb 22 20:02:19.755 ASAS: EZVPN(testVPN): Event: CONNECT 001543: *Feb 22 20:02:19.755 ASAS: EZVPN(testVPN): ezvpn_connect_request 001544: *Feb 22 20:02:19.755 ASAS: EZVPN(testVPN): Found valid peer A.B.C.254 001545: *Feb 22 20:02:19.755 ASAS: EZVPN(testVPN): Added PSK for address A.B.C.254 001546: *Feb 22 20:02:19.755 ASAS: EZVPN(testVPN): New State: READY 001547: *Feb 22 20:02:19.783 ASAS: ISAKMP:(0:13:HW:2):SA is doing pre-shared key authentication plus XAUTH using id type ID_KEY_ID 001548: *Feb 22 20:02:19.783 ASAS: ISAKMP (0:268435469): ID payload next-payload : 13 type : 11 group id : testVPN protocol : 17 port : 0 length : 17 001549: *Feb 22 20:02:19.783 ASAS: ISAKMP:(0:13:HW:2):Total payload length: 17 001550: *Feb 22 20:02:19.783 ASAS: ISAKMP:(0:13:HW:2):Input = IKE_MESG_FROM_IPSEC, IKE_SA_REQ_AM 001551: *Feb 22 20:02:19.783 ASAS: ISAKMP:(0:13:HW:2):Old State = IKE_READY New State = IKE_I_AM1 001552: *Feb 22 20:02:19.783 ASAS: ISAKMP:(0:13:HW:2): beginning Aggressive Mode exchange 001553: *Feb 22 20:02:19.783 ASAS: ISAKMP:(0:13:HW:2): sending packet to A.B.C.254 my_port 500 peer_port 500 (I) AG_INIT_EXCH 001554: *Feb 22 20:02:19.783 ASAS: ISAKMP:(0:13:HW:2):SA is still budding. Attached new ipsec request to it. (local X.Y.Z.152, remote A.B.C. 254) 001555: *Feb 22 20:02:29.783 ASAS: ISAKMP:(0:13:HW:2): retransmitting phase 1 AG_INIT_EXCH... 001556: *Feb 22 20:02:29.783 ASAS: ISAKMP:(0:13:HW:2):incrementing error counter on sa: retransmit phase 1 001557: *Feb 22 20:02:29.783 ASAS: ISAKMP:(0:13:HW:2): retransmitting phase 1 AG_INIT_EXCH 001558: *Feb 22 20:02:29.783 ASAS: ISAKMP:(0:13:HW:2): sending packet to A.B.C.254 my_port 500 peer_port 500 (I) AG_INIT_EXCH 001559: *Feb 22 20:02:39.783 ASAS: ISAKMP:(0:13:HW:2): retransmitting phase 1 AG_INIT_EXCH... 001560: *Feb 22 20:02:39.783 ASAS: ISAKMP:(0:13:HW:2):incrementing error counter on sa: retransmit phase 1 001561: *Feb 22 20:02:39.783 ASAS: ISAKMP:(0:13:HW:2): retransmitting phase 1 AG_INIT_EXCH 001562: *Feb 22 20:02:39.783 ASAS: ISAKMP:(0:13:HW:2): sending packet to A.B.C.254 my_port 500 peer_port 500 (I) AG_INIT_EXCH 001563: *Feb 22 20:02:49.783 ASAS: ISAKMP:(0:13:HW:2): retransmitting phase 1 AG_INIT_EXCH... 001564: *Feb 22 20:02:49.783 ASAS: ISAKMP:(0:13:HW:2):incrementing error counter on sa: retransmit phase 1 001565: *Feb 22 20:02:49.783 ASAS: ISAKMP:(0:13:HW:2): retransmitting phase 1 AG_INIT_EXCH 001566: *Feb 22 20:02:49.783 ASAS: ISAKMP:(0:13:HW:2): sending packet to A.B.C.254 my_port 500 peer_port 500 (I) AG_INIT_EXCH 001567: *Feb 22 20:02:59.783 ASAS: ISAKMP:(0:13:HW:2): retransmitting phase 1 AG_INIT_EXCH... 001568: *Feb 22 20:02:59.783 ASAS: ISAKMP:(0:13:HW:2):incrementing error counter on sa: retransmit phase 1 001569: *Feb 22 20:02:59.783 ASAS: ISAKMP:(0:13:HW:2): retransmitting phase 1 AG_INIT_EXCH 001570: *Feb 22 20:02:59.783 ASAS: ISAKMP:(0:13:HW:2): sending packet to A.B.C.254 my_port 500 peer_port 500 (I) AG_INIT_EXCH 001571: *Feb 22 20:03:09.783 ASAS: ISAKMP:(0:13:HW:2): retransmitting phase 1 AG_INIT_EXCH... 001572: *Feb 22 20:03:09.783 ASAS: ISAKMP:(0:13:HW:2):incrementing error counter on sa: retransmit phase 1 001573: *Feb 22 20:03:09.783 ASAS: ISAKMP:(0:13:HW:2): retransmitting phase 1 AG_INIT_EXCH 001574: *Feb 22 20:03:09.783 ASAS: ISAKMP:(0:13:HW:2): sending packet to A.B.C.254 my_port 500 peer_port 500 (I) AG_INIT_EXCH 001575: *Feb 22 20:05:45.607 ASAS: EZVPN(testVPN): Current State: READY 001576: *Feb 22 20:05:45.607 ASAS: EZVPN(testVPN): Event: RESET 001577: *Feb 22 20:05:45.607 ASAS: EZVPN(testVPN): New active peer is A.B.C.254 001578: *Feb 22 20:05:45.607 ASAS: EZVPN(testVPN): ezvpn_close 001579: *Feb 22 20:05:45.607 ASAS: EZVPN(testVPN): Deleted PSK for address A.B.C.254 001580: *Feb 22 20:05:45.611 ASAS: EZVPN(testVPN): ezvpn_reset 001581: *Feb 22 20:05:45.611 ASAS: EZVPN(testVPN): New State: CONNECT_REQUIRED 001582: *Feb 22 20:05:45.611 ASAS: EZVPN(testVPN): Current State: CONNECT_REQUIRED 001583: *Feb 22 20:05:45.611 ASAS: EZVPN(testVPN): Event: CONNECT 001584: *Feb 22 20:05:45.611 ASAS: EZVPN(testVPN): ezvpn_connect_request 001585: *Feb 22 20:05:45.615 ASAS: EZVPN(testVPN): Found valid peer A.B.C.254 001586: *Feb 22 20:05:45.615 ASAS: EZVPN(testVPN): Added PSK for address A.B.C.254 001587: *Feb 22 20:05:45.615 ASAS: EZVPN(testVPN): New State: READY 001588: *Feb 22 20:05:45.615 ASAS: ISAKMP:(0:16:HW:2):SA is still budding. Attached new ipsec request to it. (local X.Y.Z.152, remote A.B.C. 254) 001589: *Feb 22 20:05:49.879 ASAS: ISAKMP:(0:16:HW:2): retransmitting phase 1 AG_INIT_EXCH... 001590: *Feb 22 20:05:49.879 ASAS: ISAKMP:(0:16:HW:2):incrementing error counter on sa: retransmit phase 1 001591: *Feb 22 20:05:49.879 ASAS: ISAKMP:(0:16:HW:2): retransmitting phase 1 AG_INIT_EXCH 001592: *Feb 22 20:05:49.879 ASAS: ISAKMP:(0:16:HW:2): sending packet to A.B.C.254 my_port 500 peer_port 500 (I) AG_INIT_EXCH 001593: *Feb 22 20:05:50.319 ASAS: EZVPN(testVPN): Current State: READY 001594: *Feb 22 20:05:50.319 ASAS: EZVPN(testVPN): Event: RESET 001595: *Feb 22 20:05:50.319 ASAS: EZVPN(testVPN): New active peer is A.B.C.254 001596: *Feb 22 20:05:50.319 ASAS: EZVPN(testVPN): ezvpn_close 001597: *Feb 22 20:05:50.319 ASAS: EZVPN(testVPN): Deleted PSK for address A.B.C.254 001598: *Feb 22 20:05:50.319 ASAS: EZVPN(testVPN): ezvpn_reset 001599: *Feb 22 20:05:50.319 ASAS: EZVPN(testVPN): New State: CONNECT_REQUIRED 001600: *Feb 22 20:05:50.323 ASAS: EZVPN(testVPN): Current State: CONNECT_REQUIRED 001601: *Feb 22 20:05:50.323 ASAS: EZVPN(testVPN): Event: CONNECT 001602: *Feb 22 20:05:50.323 ASAS: EZVPN(testVPN): ezvpn_connect_request 001603: *Feb 22 20:05:50.323 ASAS: EZVPN(testVPN): Found valid peer A.B.C.254 001604: *Feb 22 20:05:50.323 ASAS: EZVPN(testVPN): Added PSK for address A.B.C.254 001605: *Feb 22 20:05:50.323 ASAS: EZVPN(testVPN): New State: READY 001606: *Feb 22 20:05:50.323 ASAS: ISAKMP:(0:16:HW:2):SA is still budding. Attached new ipsec request to it. (local X.Y.Z.152, remote A.B.C. 254) 001607: *Feb 22 20:05:55.023 ASAS: EZVPN(testVPN): Current State: READY 001608: *Feb 22 20:05:55.023 ASAS: EZVPN(testVPN): Event: RESET 001609: *Feb 22 20:05:55.023 ASAS: EZVPN(testVPN): New active peer is A.B.C.254 001610: *Feb 22 20:05:55.023 ASAS: EZVPN(testVPN): ezvpn_close 001611: *Feb 22 20:05:55.023 ASAS: EZVPN(testVPN): Deleted PSK for address A.B.C.254 001612: *Feb 22 20:05:55.023 ASAS: EZVPN(testVPN): ezvpn_reset 001613: *Feb 22 20:05:55.023 ASAS: EZVPN(testVPN): New State: CONNECT_REQUIRED 001614: *Feb 22 20:05:55.027 ASAS: EZVPN(testVPN): Current State: CONNECT_REQUIRED 001615: *Feb 22 20:05:55.027 ASAS: EZVPN(testVPN): Event: CONNECT 001616: *Feb 22 20:05:55.027 ASAS: EZVPN(testVPN): ezvpn_connect_request 001617: *Feb 22 20:05:55.027 ASAS: EZVPN(testVPN): Found valid peer A.B.C.254 001618: *Feb 22 20:05:55.027 ASAS: EZVPN(testVPN): Added PSK for address A.B.C.254 001619: *Feb 22 20:05:55.027 ASAS: EZVPN(testVPN): New State: READY 001620: *Feb 22 20:05:55.027 ASAS: ISAKMP:(0:16:HW:2):SA is still budding. Attached new ipsec request to it. (local X.Y.Z.152, remote A.B.C. 254) 001621: *Feb 22 20:05:59.723 ASAS: EZVPN(testVPN): Current State: READY 001622: *Feb 22 20:05:59.723 ASAS: EZVPN(testVPN): Event: RESET 001623: *Feb 22 20:05:59.723 ASAS: EZVPN(testVPN): New active peer is A.B.C.254 001624: *Feb 22 20:05:59.879 ASAS: ISAKMP:(0:16:HW:2): retransmitting phase 1 AG_INIT_EXCH... 001625: *Feb 22 20:05:59.879 ASAS: ISAKMP:(0:16:HW:2):incrementing error counter on sa: retransmit phase 1 001626: *Feb 22 20:05:59.879 ASAS: ISAKMP:(0:16:HW:2): retransmitting phase 1 AG_INIT_EXCH 001627: *Feb 22 20:05:59.879 ASAS: ISAKMP:(0:16:HW:2): sending packet to A.B.C.254 my_port 500 peer_port 500 (I) AG_INIT_EXCH 001628: *Feb 22 20:06:00.319 ASAS: EZVPN(testVPN): ezvpn_close 001629: *Feb 22 20:06:00.319 ASAS: EZVPN(testVPN): Deleted PSK for address A.B.C.254 001630: *Feb 22 20:06:00.319 ASAS: EZVPN(testVPN): ezvpn_reset 001631: *Feb 22 20:06:00.319 ASAS: EZVPN(testVPN): New State: CONNECT_REQUIRED 001632: *Feb 22 20:06:00.319 ASAS: EZVPN(testVPN): Current State: CONNECT_REQUIRED 001633: *Feb 22 20:06:00.319 ASAS: EZVPN(testVPN): Event: CONNECT 001634: *Feb 22 20:06:00.319 ASAS: EZVPN(testVPN): ezvpn_connect_request 001635: *Feb 22 20:06:00.319 ASAS: EZVPN(testVPN): Found valid peer A.B.C.254 001636: *Feb 22 20:06:00.319 ASAS: EZVPN(testVPN): Added PSK for address A.B.C.254 001637: *Feb 22 20:06:00.319 ASAS: EZVPN(testVPN): New State: READY 001638: *Feb 22 20:06:00.319 ASAS: ISAKMP:(0:16:HW:2):SA is still budding. Attached new ipsec request to it. (local X.Y.Z.152, remote A.B.C. 254) 001639: *Feb 22 20:06:04.431 ASAS: EZVPN(testVPN): Current State: READY 001640: *Feb 22 20:06:04.431 ASAS: EZVPN(testVPN): Event: RESET 001641: *Feb 22 20:06:04.431 ASAS: EZVPN(testVPN): New active peer is A.B.C.254 001642: *Feb 22 20:06:04.431 ASAS: EZVPN(testVPN): ezvpn_close 001643: *Feb 22 20:06:04.431 ASAS: EZVPN(testVPN): Deleted PSK for address A.B.C.254 001644: *Feb 22 20:06:04.431 ASAS: EZVPN(testVPN): ezvpn_reset 001645: *Feb 22 20:06:04.431 ASAS: EZVPN(testVPN): New State: CONNECT_REQUIRED 001646: *Feb 22 20:06:04.435 ASAS: EZVPN(testVPN): Current State: CONNECT_REQUIRED 001647: *Feb 22 20:06:04.435 ASAS: EZVPN(testVPN): Event: CONNECT 001648: *Feb 22 20:06:04.435 ASAS: EZVPN(testVPN): ezvpn_connect_request 001649: *Feb 22 20:06:04.435 ASAS: EZVPN(testVPN): Found valid peer A.B.C.254 001650: *Feb 22 20:06:04.435 ASAS: EZVPN(testVPN): Added PSK for address A.B.C.254 001651: *Feb 22 20:06:04.435 ASAS: EZVPN(testVPN): New State: READY 001652: *Feb 22 20:06:04.435 ASAS: ISAKMP:(0:16:HW:2):SA is still budding. Attached new ipsec request to it. (local X.Y.Z.152, remote A.B.C. 254) 001653: *Feb 22 20:06:09.879 ASAS: ISAKMP:(0:16:HW:2): retransmitting phase 1 AG_INIT_EXCH... 001654: *Feb 22 20:06:09.879 ASAS: ISAKMP:(0:16:HW:2):incrementing error counter on sa: retransmit phase 1 001655: *Feb 22 20:06:09.879 ASAS: ISAKMP:(0:16:HW:2): retransmitting phase 1 AG_INIT_EXCH 001656: *Feb 22 20:06:09.879 ASAS: ISAKMP:(0:16:HW:2): sending packet to A.B.C.254 my_port 500 peer_port 500 (I) AG_INIT_EXCH 001657: *Feb 22 20:06:19.847 ASAS: ISAKMP:(0:15:HW:2):purging SA., sa=826F63B4, delme=826F63B4 001658: *Feb 22 20:06:19.879 ASAS: ISAKMP:(0:16:HW:2): retransmitting phase 1 AG_INIT_EXCH... 001659: *Feb 22 20:06:19.879 ASAS: ISAKMP:(0:16:HW:2):peer does not do paranoid keepalives. 001660: *Feb 22 20:06:19.879 ASAS: ISAKMP:(0:16:HW:2):deleting SA reason "Death by retransmission P1" state (I) AG_INIT_EXCH (peer A.B.C.254) 001661: *Feb 22 20:06:19.879 ASAS: EZVPN(testVPN): Current State: READY 001662: *Feb 22 20:06:19.879 ASAS: EZVPN(testVPN): Event: CONNECT_NEXT_PEER 001663: *Feb 22 20:06:19.879 ASAS: EZVPN(testVPN): ezvpn_close 001664: *Feb 22 20:06:19.879 ASAS: EZVPN(testVPN): Deleted PSK for address A.B.C.254 001665: *Feb 22 20:06:19.879 ASAS: EZVPN(testVPN): New active peer is A.B.C.254 001666: *Feb 22 20:06:19.879 ASAS: EZVPN(testVPN): Attempting to connect to peer A.B.C.254 001667: *Feb 22 20:06:19.879 ASAS: EZVPN(testVPN): New State: CONNECT_REQUIRED 001668: *Feb 22 20:06:19.879 ASAS: ISAKMP:(0:16:HW:2):deleting SA reason "Death by retransmission P1" state (I) AG_INIT_EXCH (peer A.B.C.254) 001669: *Feb 22 20:06:19.879 ASAS: ISAKMP:(0:16:HW:2):Input = IKE_MESG_INTERNAL, IKE_PHASE1_DEL 001670: *Feb 22 20:06:19.879 ASAS: ISAKMP:(0:16:HW:2):Old State = IKE_I_AM1 New State = IKE_DEST_SA 001671: *Feb 22 20:06:19.879 ASAS: EZVPN(testVPN): Current State: CONNECT_REQUIRED 001672: *Feb 22 20:06:19.879 ASAS: EZVPN(testVPN): Event: CONNECT 001673: *Feb 22 20:06:19.879 ASAS: EZVPN(testVPN): ezvpn_connect_request 001674: *Feb 22 20:06:19.883 ASAS: EZVPN(testVPN): Found valid peer A.B.C.254 001675: *Feb 22 20:06:19.883 ASAS: EZVPN(testVPN): Added PSK for address A.B.C.254 001676: *Feb 22 20:06:19.883 ASAS: EZVPN(testVPN): New State: READY 001677: *Feb 22 20:06:19.883 ASAS: ISAKMP: local port 500, remote port 500 001678: *Feb 22 20:06:19.883 ASAS: ISAKMP: Find a dup sa in the avl tree during calling isadb_insert sa = 82A88650 001679: *Feb 22 20:06:19.883 ASAS: ISAKMP:(0:0:N/A:0): client mode configured. 001680: *Feb 22 20:06:19.883 ASAS: ISAKMP:(0:0:N/A:0): constructed NAT-T vendor-07 ID 001681: *Feb 22 20:06:19.883 ASAS: ISAKMP:(0:0:N/A:0): constructed NAT-T vendor-03 ID 001682: *Feb 22 20:06:19.883 ASAS: ISAKMP:(0:0:N/A:0): constructed NAT-T vendor-02 ID 001683: *Feb 22 20:06:19.887 ASAS: EZVPN(testVPN): Current State: READY 001684: *Feb 22 20:06:19.887 ASAS: EZVPN(testVPN): Event: CONN_DOWN 001685: *Feb 22 20:06:19.887 ASAS: EZVPN(testVPN): ezvpn_close 001686: *Feb 22 20:06:19.887 ASAS: EZVPN(testVPN): Deleted PSK for address A.B.C.254 001687: *Feb 22 20:06:19.887 ASAS: EZVPN(testVPN): New State: CONNECT_REQUIRED 001688: *Feb 22 20:06:19.887 ASAS: EZVPN(testVPN): Current State: CONNECT_REQUIRED 001689: *Feb 22 20:06:19.887 ASAS: EZVPN(testVPN): Event: CONNECT 001690: *Feb 22 20:06:19.887 ASAS: EZVPN(testVPN): ezvpn_connect_request 001691: *Feb 22 20:06:19.887 ASAS: EZVPN(testVPN): Found valid peer A.B.C.254 001692: *Feb 22 20:06:19.887 ASAS: EZVPN(testVPN): Added PSK for address A.B.C.254 001693: *Feb 22 20:06:19.887 ASAS: EZVPN(testVPN): New State: READY 001694: *Feb 22 20:06:19.915 ASAS: ISAKMP:(0:17:HW:2):SA is doing pre-shared key authentication plus XAUTH using id type ID_KEY_ID 001695: *Feb 22 20:06:19.915 ASAS: ISAKMP (0:268435473): ID payload next-payload : 13 type : 11 group id : testVPN protocol : 17 port : 0 length : 17 001696: *Feb 22 20:06:19.915 ASAS: ISAKMP:(0:17:HW:2):Total payload length: 17 001697: *Feb 22 20:06:19.915 ASAS: ISAKMP:(0:17:HW:2):Input = IKE_MESG_FROM_IPSEC, IKE_SA_REQ_AM 001698: *Feb 22 20:06:19.915 ASAS: ISAKMP:(0:17:HW:2):Old State = IKE_READY New State = IKE_I_AM1 001699: *Feb 22 20:06:19.915 ASAS: ISAKMP:(0:17:HW:2): beginning Aggressive Mode exchange 001700: *Feb 22 20:06:19.915 ASAS: ISAKMP:(0:17:HW:2): sending packet to A.B.C.254 my_port 500 peer_port 500 (I) AG_INIT_EXCH 001701: *Feb 22 20:06:19.915 ASAS: ISAKMP:(0:17:HW:2):SA is still budding. Attached new ipsec request to it. (local X.Y.Z.152, remote A.B.C. 254) 001702: *Feb 22 20:06:29.915 ASAS: ISAKMP:(0:17:HW:2): retransmitting phase 1 AG_INIT_EXCH... 001703: *Feb 22 20:06:29.915 ASAS: ISAKMP:(0:17:HW:2):incrementing error counter on sa: retransmit phase 1 001704: *Feb 22 20:06:29.915 ASAS: ISAKMP:(0:17:HW:2): retransmitting phase 1 AG_INIT_EXCH 001705: *Feb 22 20:06:29.915 ASAS: ISAKMP:(0:17:HW:2): sending packet to A.B.C.254 my_port 500 peer_port 500 (I) AG_INIT_EXCH 001706: *Feb 22 20:06:39.915 ASAS: ISAKMP:(0:17:HW:2): retransmitting phase 1 AG_INIT_EXCH... 001707: *Feb 22 20:06:39.915 ASAS: ISAKMP:(0:17:HW:2):incrementing error counter on sa: retransmit phase 1 001708: *Feb 22 20:06:39.915 ASAS: ISAKMP:(0:17:HW:2): retransmitting phase 1 AG_INIT_EXCH 001709: *Feb 22 20:06:39.915 ASAS: ISAKMP:(0:17:HW:2): sending packet to A.B.C.254 my_port 500 peer_port 500 (I) AG_INIT_EXCH 001710: *Feb 22 20:06:49.915 ASAS: ISAKMP:(0:17:HW:2): retransmitting phase 1 AG_INIT_EXCH... 001711: *Feb 22 20:06:49.915 ASAS: ISAKMP:(0:17:HW:2):incrementing error counter on sa: retransmit phase 1 001712: *Feb 22 20:06:49.915 ASAS: ISAKMP:(0:17:HW:2): retransmitting phase 1 AG_INIT_EXCH 001713: *Feb 22 20:06:49.915 ASAS: ISAKMP:(0:17:HW:2): sending packet to A.B.C.254 my_port 500 peer_port 500 (I) AG_INIT_EXCH 001714: *Feb 22 20:06:59.915 ASAS: ISAKMP:(0:17:HW:2): retransmitting phase 1 AG_INIT_EXCH... 001715: *Feb 22 20:06:59.915 ASAS: ISAKMP:(0:17:HW:2):incrementing error counter on sa: retransmit phase 1 001716: *Feb 22 20:06:59.915 ASAS: ISAKMP:(0:17:HW:2): retransmitting phase 1 AG_INIT_EXCH 001717: *Feb 22 20:06:59.915 ASAS: ISAKMP:(0:17:HW:2): sending packet to A.B.C.254 my_port 500 peer_port 500 (I) AG_INIT_EXCH 001718: *Feb 22 20:07:09.915 ASAS: ISAKMP:(0:17:HW:2): retransmitting phase 1 AG_INIT_EXCH... 001719: *Feb 22 20:07:09.915 ASAS: ISAKMP:(0:17:HW:2):incrementing error counter on sa: retransmit phase 1 001720: *Feb 22 20:07:09.915 ASAS: ISAKMP:(0:17:HW:2): retransmitting phase 1 AG_INIT_EXCH 001721: *Feb 22 20:07:09.915 ASAS: ISAKMP:(0:17:HW:2): sending packet to A.B.C.254 my_port 500 peer_port 500 (I) AG_INIT_EXCH 001722: *Feb 22 20:07:19.879 ASAS: ISAKMP:(0:16:HW:2):purging SA., sa=82A47EC4, delme=82A47EC4 001723: *Feb 22 20:07:19.887 ASAS: EZVPN(testVPN): Current State: READY 001724: *Feb 22 20:07:19.887 ASAS: EZVPN(testVPN): Event: RESET 001725: *Feb 22 20:07:19.887 ASAS: EZVPN(testVPN): New active peer is A.B.C.254 001726: *Feb 22 20:07:19.887 ASAS: EZVPN(testVPN): ezvpn_close 001727: *Feb 22 20:07:19.887 ASAS: EZVPN(testVPN): Deleted PSK for address A.B.C.254 001728: *Feb 22 20:07:19.887 ASAS: EZVPN(testVPN): ezvpn_reset 001729: *Feb 22 20:07:19.887 ASAS: EZVPN(testVPN): New State: CONNECT_REQUIRED 001730: *Feb 22 20:07:19.887 ASAS: EZVPN(testVPN): Current State: CONNECT_REQUIRED 001731: *Feb 22 20:07:19.887 ASAS: EZVPN(testVPN): Event: CONNECT 001732: *Feb 22 20:07:19.887 ASAS: EZVPN(testVPN): ezvpn_connect_request 001733: *Feb 22 20:07:19.887 ASAS: EZVPN(testVPN): Found valid peer A.B.C.254 001734: *Feb 22 20:07:19.887 ASAS: EZVPN(testVPN): Added PSK for address A.B.C.254 001735: *Feb 22 20:07:19.887 ASAS: EZVPN(testVPN): New State: READY 001736: *Feb 22 20:07:19.887 ASAS: ISAKMP:(0:17:HW:2):SA is still budding. Attached new ipsec request to it. (local X.Y.Z.152, remote A.B.C. 254) 001737: *Feb 22 20:07:19.915 ASAS: ISAKMP:(0:17:HW:2): retransmitting phase 1 AG_INIT_EXCH... 001738: *Feb 22 20:07:19.915 ASAS: ISAKMP:(0:17:HW:2):peer does not do paranoid keepalives. 001739: *Feb 22 20:07:19.915 ASAS: ISAKMP:(0:17:HW:2):deleting SA reason "Death by retransmission P1" state (I) AG_INIT_EXCH (peer A.B.C.254) 001740: *Feb 22 20:07:19.915 ASAS: EZVPN(testVPN): Current State: READY 001741: *Feb 22 20:07:19.915 ASAS: EZVPN(testVPN): Event: CONNECT_NEXT_PEER 001742: *Feb 22 20:07:19.915 ASAS: EZVPN(testVPN): ezvpn_close 001743: *Feb 22 20:07:19.915 ASAS: EZVPN(testVPN): Deleted PSK for address A.B.C.254 001744: *Feb 22 20:07:19.915 ASAS: EZVPN(testVPN): New active peer is A.B.C.254 001745: *Feb 22 20:07:19.915 ASAS: EZVPN(testVPN): Attempting to connect to peer A.B.C.254 001746: *Feb 22 20:07:19.915 ASAS: EZVPN(testVPN): New State: CONNECT_REQUIRED 001747: *Feb 22 20:07:19.915 ASAS: ISAKMP:(0:17:HW:2):deleting SA reason "Death by retransmission P1" state (I) AG_INIT_EXCH (peer A.B.C.254) 001748: *Feb 22 20:07:19.915 ASAS: ISAKMP:(0:17:HW:2):Input = IKE_MESG_INTERNAL, IKE_PHASE1_DEL 001749: *Feb 22 20:07:19.915 ASAS: ISAKMP:(0:17:HW:2):Old State = IKE_I_AM1 New State = IKE_DEST_SA 001750: *Feb 22 20:07:19.915 ASAS: EZVPN(testVPN): Current State: CONNECT_REQUIRED 001751: *Feb 22 20:07:19.915 ASAS: EZVPN(testVPN): Event: CONNECT 001752: *Feb 22 20:07:19.915 ASAS: EZVPN(testVPN): ezvpn_connect_request 001753: *Feb 22 20:07:19.919 ASAS: EZVPN(testVPN): Found valid peer A.B.C.254 001754: *Feb 22 20:07:19.919 ASAS: EZVPN(testVPN): Added PSK for address A.B.C.254 001755: *Feb 22 20:07:19.919 ASAS: EZVPN(testVPN): New State: READY 001756: *Feb 22 20:07:19.919 ASAS: ISAKMP: local port 500, remote port 500 001757: *Feb 22 20:07:19.919 ASAS: ISAKMP: Find a dup sa in the avl tree during calling isadb_insert sa = 826F63B4 001758: *Feb 22 20:07:19.919 ASAS: ISAKMP:(0:0:N/A:0): client mode configured. 001759: *Feb 22 20:07:19.919 ASAS: ISAKMP:(0:0:N/A:0): constructed NAT-T vendor-07 ID 001760: *Feb 22 20:07:19.919 ASAS: ISAKMP:(0:0:N/A:0): constructed NAT-T vendor-03 ID 001761: *Feb 22 20:07:19.919 ASAS: ISAKMP:(0:0:N/A:0): constructed NAT-T vendor-02 ID 001762: *Feb 22 20:07:19.923 ASAS: EZVPN(testVPN): Current State: READY 001763: *Feb 22 20:07:19.923 ASAS: EZVPN(testVPN): Event: CONN_DOWN 001764: *Feb 22 20:07:19.923 ASAS: EZVPN(testVPN): ezvpn_close 001765: *Feb 22 20:07:19.923 ASAS: EZVPN(testVPN): Deleted PSK for address A.B.C.254 001766: *Feb 22 20:07:19.923 ASAS: EZVPN(testVPN): New State: CONNECT_REQUIRED 001767: *Feb 22 20:07:19.923 ASAS: EZVPN(testVPN): Current State: CONNECT_REQUIRED 001768: *Feb 22 20:07:19.923 ASAS: EZVPN(testVPN): Event: CONNECT 001769: *Feb 22 20:07:19.923 ASAS: EZVPN(testVPN): ezvpn_connect_request 001770: *Feb 22 20:07:19.923 ASAS: EZVPN(testVPN): Found valid peer A.B.C.254 001771: *Feb 22 20:07:19.923 ASAS: EZVPN(testVPN): Added PSK for address A.B.C.254 001772: *Feb 22 20:07:19.923 ASAS: EZVPN(testVPN): New State: READY 001773: *Feb 22 20:07:19.951 ASAS: ISAKMP:(0:18:HW:2):SA is doing pre-shared key authentication plus XAUTH using id type ID_KEY_ID 001774: *Feb 22 20:07:19.951 ASAS: ISAKMP (0:268435474): ID payload next-payload : 13 type : 11 group id : testVPN protocol : 17 port : 0 length : 17 001775: *Feb 22 20:07:19.951 ASAS: ISAKMP:(0:18:HW:2):Total payload length: 17 001776: *Feb 22 20:07:19.951 ASAS: ISAKMP:(0:18:HW:2):Input = IKE_MESG_FROM_IPSEC, IKE_SA_REQ_AM 001777: *Feb 22 20:07:19.951 ASAS: ISAKMP:(0:18:HW:2):Old State = IKE_READY New State = IKE_I_AM1 001778: *Feb 22 20:07:19.951 ASAS: ISAKMP:(0:18:HW:2): beginning Aggressive Mode exchange 001779: *Feb 22 20:07:19.951 ASAS: ISAKMP:(0:18:HW:2): sending packet to A.B.C.254 my_port 500 peer_port 500 (I) AG_INIT_EXCH 001780: *Feb 22 20:07:19.951 ASAS: ISAKMP:(0:18:HW:2):SA is still budding. Attached new ipsec request to it. (local X.Y.Z.152, remote A.B.C. 254) 001781: *Feb 22 20:07:29.951 ASAS: ISAKMP:(0:18:HW:2): retransmitting phase 1 AG_INIT_EXCH... 001782: *Feb 22 20:07:29.951 ASAS: ISAKMP:(0:18:HW:2):incrementing error counter on sa: retransmit phase 1 001783: *Feb 22 20:07:29.951 ASAS: ISAKMP:(0:18:HW:2): retransmitting phase 1 AG_INIT_EXCH 001784: *Feb 22 20:07:29.951 ASAS: ISAKMP:(0:18:HW:2): sending packet to A.B.C.254 my_port 500 peer_port 500 (I) AG_INIT_EXCH 001785: *Feb 22 20:07:39.951 ASAS: ISAKMP:(0:18:HW:2): retransmitting phase 1 AG_INIT_EXCH... 001786: *Feb 22 20:07:39.951 ASAS: ISAKMP:(0:18:HW:2):incrementing error counter on sa: retransmit phase 1 001787: *Feb 22 20:07:39.951 ASAS: ISAKMP:(0:18:HW:2): retransmitting phase 1 AG_INIT_EXCH 001788: *Feb 22 20:07:39.951 ASAS: ISAKMP:(0:18:HW:2): sending packet to A.B.C.254 my_port 500 peer_port 500 (I) AG_INIT_EXCH 001789: *Feb 22 20:09:54.211 ASAS: EZVPN(testVPN): Current State: READY 001790: *Feb 22 20:09:54.211 ASAS: EZVPN(testVPN): Event: RESET 001791: *Feb 22 20:09:54.211 ASAS: EZVPN(testVPN): New active peer is A.B.C.254 001792: *Feb 22 20:09:54.211 ASAS: EZVPN(testVPN): ezvpn_close 001793: *Feb 22 20:09:54.211 ASAS: EZVPN(testVPN): Deleted PSK for address A.B.C.254 001794: *Feb 22 20:09:54.211 ASAS: EZVPN(testVPN): ezvpn_reset 001795: *Feb 22 20:09:54.211 ASAS: EZVPN(testVPN): New State: CONNECT_REQUIRED 001796: *Feb 22 20:09:54.215 ASAS: EZVPN(testVPN): Current State: CONNECT_REQUIRED 001797: *Feb 22 20:09:54.215 ASAS: EZVPN(testVPN): Event: CONNECT 001798: *Feb 22 20:09:54.215 ASAS: EZVPN(testVPN): ezvpn_connect_request 001799: *Feb 22 20:09:54.215 ASAS: EZVPN(testVPN): Found valid peer A.B.C.254 001800: *Feb 22 20:09:54.215 ASAS: EZVPN(testVPN): Added PSK for address A.B.C.254 001801: *Feb 22 20:09:54.215 ASAS: EZVPN(testVPN): New State: READY 001802: *Feb 22 20:09:54.215 ASAS: ISAKMP:(0:20:HW:2):SA is still budding. Attached new ipsec request to it. (local X.Y.Z.152, remote A.B.C. 254) 001803: *Feb 22 20:09:58.919 ASAS: EZVPN(testVPN): Current State: READY 001804: *Feb 22 20:09:58.919 ASAS: EZVPN(testVPN): Event: RESET 001805: *Feb 22 20:09:58.919 ASAS: EZVPN(testVPN): New active peer is A.B.C.254 001806: *Feb 22 20:09:58.919 ASAS: EZVPN(testVPN): ezvpn_close 001807: *Feb 22 20:09:58.919 ASAS: EZVPN(testVPN): Deleted PSK for address A.B.C.254 001808: *Feb 22 20:09:58.919 ASAS: EZVPN(testVPN): ezvpn_reset 001809: *Feb 22 20:09:58.919 ASAS: EZVPN(testVPN): New State: CONNECT_REQUIRED 001810: *Feb 22 20:09:58.923 ASAS: EZVPN(testVPN): Current State: CONNECT_REQUIRED 001811: *Feb 22 20:09:58.923 ASAS: EZVPN(testVPN): Event: CONNECT 001812: *Feb 22 20:09:58.923 ASAS: EZVPN(testVPN): ezvpn_connect_request 001813: *Feb 22 20:09:58.923 ASAS: EZVPN(testVPN): Found valid peer A.B.C.254 001814: *Feb 22 20:09:58.923 ASAS: EZVPN(testVPN): Added PSK for address A.B.C.254 001815: *Feb 22 20:09:58.923 ASAS: EZVPN(testVPN): New State: READY 001816: *Feb 22 20:09:58.923 ASAS: ISAKMP:(0:20:HW:2):SA is still budding. Attached new ipsec request to it. (local X.Y.Z.152, remote A.B.C. 254) 001817: *Feb 22 20:10:00.015 ASAS: ISAKMP:(0:20:HW:2): retransmitting phase 1 AG_INIT_EXCH... 001818: *Feb 22 20:10:00.015 ASAS: ISAKMP:(0:20:HW:2):incrementing error counter on sa: retransmit phase 1 001819: *Feb 22 20:10:00.015 ASAS: ISAKMP:(0:20:HW:2): retransmitting phase 1 AG_INIT_EXCH 001820: *Feb 22 20:10:00.015 ASAS: ISAKMP:(0:20:HW:2): sending packet to A.B.C.254 my_port 500 peer_port 500 (I) AG_INIT_EXCH 001821: *Feb 22 20:10:03.727 ASAS: EZVPN(testVPN): Current State: READY 001822: *Feb 22 20:10:03.727 ASAS: EZVPN(testVPN): Event: RESET 001823: *Feb 22 20:10:03.727 ASAS: EZVPN(testVPN): New active peer is A.B.C.254 001824: *Feb 22 20:10:04.319 ASAS: EZVPN(testVPN): ezvpn_close 001825: *Feb 22 20:10:04.319 ASAS: EZVPN(testVPN): Deleted PSK for address A.B.C.254 001826: *Feb 22 20:10:04.319 ASAS: EZVPN(testVPN): ezvpn_reset 001827: *Feb 22 20:10:04.319 ASAS: EZVPN(testVPN): New State: CONNECT_REQUIRED 001828: *Feb 22 20:10:04.319 ASAS: EZVPN(testVPN): Current State: CONNECT_REQUIRED 001829: *Feb 22 20:10:04.319 ASAS: EZVPN(testVPN): Event: CONNECT 001830: *Feb 22 20:10:04.319 ASAS: EZVPN(testVPN): ezvpn_connect_request 001831: *Feb 22 20:10:04.319 ASAS: EZVPN(testVPN): Found valid peer A.B.C.254 001832: *Feb 22 20:10:04.319 ASAS: EZVPN(testVPN): Added PSK for address A.B.C.254 001833: *Feb 22 20:10:04.319 ASAS: EZVPN(testVPN): New State: READY 001834: *Feb 22 20:10:04.323 ASAS: ISAKMP:(0:20:HW:2):SA is still budding. Attached new ipsec request to it. (local X.Y.Z.152, remote A.B.C. 254) 001835: *Feb 22 20:10:08.431 ASAS: EZVPN(testVPN): Current State: READY 001836: *Feb 22 20:10:08.431 ASAS: EZVPN(testVPN): Event: RESET 001837: *Feb 22 20:10:08.431 ASAS: EZVPN(testVPN): New active peer is A.B.C.254 001838: *Feb 22 20:10:08.431 ASAS: EZVPN(testVPN): ezvpn_close 001839: *Feb 22 20:10:08.431 ASAS: EZVPN(testVPN): Deleted PSK for address A.B.C.254 001840: *Feb 22 20:10:08.431 ASAS: EZVPN(testVPN): ezvpn_reset 001841: *Feb 22 20:10:08.431 ASAS: EZVPN(testVPN): New State: CONNECT_REQUIRED 001842: *Feb 22 20:10:08.435 ASAS: EZVPN(testVPN): Current State: CONNECT_REQUIRED 001843: *Feb 22 20:10:08.435 ASAS: EZVPN(testVPN): Event: CONNECT 001844: *Feb 22 20:10:08.435 ASAS: EZVPN(testVPN): ezvpn_connect_request 001845: *Feb 22 20:10:08.435 ASAS: EZVPN(testVPN): Found valid peer A.B.C.254 001846: *Feb 22 20:10:08.435 ASAS: EZVPN(testVPN): Added PSK for address A.B.C.254 001847: *Feb 22 20:10:08.435 ASAS: EZVPN(testVPN): New State: READY 001848: *Feb 22 20:10:08.439 ASAS: ISAKMP:(0:20:HW:2):SA is still budding. Attached new ipsec request to it. (local X.Y.Z.152, remote A.B.C. 254) 001849: *Feb 22 20:10:10.015 ASAS: ISAKMP:(0:20:HW:2): retransmitting phase 1 AG_INIT_EXCH... 001850: *Feb 22 20:10:10.015 ASAS: ISAKMP:(0:20:HW:2):incrementing error counter on sa: retransmit phase 1 001851: *Feb 22 20:10:10.015 ASAS: ISAKMP:(0:20:HW:2): retransmitting phase 1 AG_INIT_EXCH 001852: *Feb 22 20:10:10.015 ASAS: ISAKMP:(0:20:HW:2): sending packet to A.B.C.254 my_port 500 peer_port 500 (I) AG_INIT_EXCH 001853: *Feb 22 20:10:13.343 ASAS: EZVPN(testVPN): Current State: READY 001854: *Feb 22 20:10:13.343 ASAS: EZVPN(testVPN): Event: RESET 001855: *Feb 22 20:10:13.343 ASAS: EZVPN(testVPN): New active peer is A.B.C.254 001856: *Feb 22 20:10:13.347 ASAS: EZVPN(testVPN): ezvpn_close 001857: *Feb 22 20:10:13.347 ASAS: EZVPN(testVPN): Deleted PSK for address A.B.C.254 001858: *Feb 22 20:10:13.347 ASAS: EZVPN(testVPN): ezvpn_reset 001859: *Feb 22 20:10:13.347 ASAS: EZVPN(testVPN): New State: CONNECT_REQUIRED 001860: *Feb 22 20:10:13.351 ASAS: EZVPN(testVPN): Current State: CONNECT_REQUIRED 001861: *Feb 22 20:10:13.351 ASAS: EZVPN(testVPN): Event: CONNECT 001862: *Feb 22 20:10:13.351 ASAS: EZVPN(testVPN): ezvpn_connect_request 001863: *Feb 22 20:10:13.351 ASAS: EZVPN(testVPN): Found valid peer A.B.C.254 001864: *Feb 22 20:10:13.351 ASAS: EZVPN(testVPN): Added PSK for address A.B.C.254 001865: *Feb 22 20:10:13.351 ASAS: EZVPN(testVPN): New State: READY 001866: *Feb 22 20:10:13.351 ASAS: ISAKMP:(0:20:HW:2):SA is still budding. Attached new ipsec request to it. (local X.Y.Z.152, remote A.B.C. 254) 001867: *Feb 22 20:10:19.983 ASAS: ISAKMP:(0:19:HW:2):purging SA., sa=82A43824, delme=82A43824 001868: *Feb 22 20:10:20.015 ASAS: ISAKMP:(0:20:HW:2): retransmitting phase 1 AG_INIT_EXCH... 001869: *Feb 22 20:10:20.015 ASAS: ISAKMP:(0:20:HW:2):peer does not do paranoid keepalives. 001870: *Feb 22 20:10:20.015 ASAS: ISAKMP:(0:20:HW:2):deleting SA reason "Death by retransmission P1" state (I) AG_INIT_EXCH (peer A.B.C.254) 001871: *Feb 22 20:10:20.015 ASAS: EZVPN(testVPN): Current State: READY 001872: *Feb 22 20:10:20.015 ASAS: EZVPN(testVPN): Event: CONNECT_NEXT_PEER 001873: *Feb 22 20:10:20.015 ASAS: EZVPN(testVPN): ezvpn_close 001874: *Feb 22 20:10:20.015 ASAS: EZVPN(testVPN): Deleted PSK for address A.B.C.254 001875: *Feb 22 20:10:20.015 ASAS: EZVPN(testVPN): New active peer is A.B.C.254 001876: *Feb 22 20:10:20.015 ASAS: EZVPN(testVPN): Attempting to connect to peer A.B.C.254 001877: *Feb 22 20:10:20.015 ASAS: EZVPN(testVPN): New State: CONNECT_REQUIRED 001878: *Feb 22 20:10:20.015 ASAS: ISAKMP:(0:20:HW:2):deleting SA reason "Death by retransmission P1" state (I) AG_INIT_EXCH (peer A.B.C.254) 001879: *Feb 22 20:10:20.015 ASAS: ISAKMP:(0:20:HW:2):Input = IKE_MESG_INTERNAL, IKE_PHASE1_DEL 001880: *Feb 22 20:10:20.015 ASAS: ISAKMP:(0:20:HW:2):Old State = IKE_I_AM1 New State = IKE_DEST_SA 001881: *Feb 22 20:10:20.015 ASAS: EZVPN(testVPN): Current State: CONNECT_REQUIRED 001882: *Feb 22 20:10:20.015 ASAS: EZVPN(testVPN): Event: CONNECT 001883: *Feb 22 20:10:20.015 ASAS: EZVPN(testVPN): ezvpn_connect_request 001884: *Feb 22 20:10:20.019 ASAS: EZVPN(testVPN): Found valid peer A.B.C.254 001885: *Feb 22 20:10:20.019 ASAS: EZVPN(testVPN): Added PSK for address A.B.C.254 001886: *Feb 22 20:10:20.019 ASAS: EZVPN(testVPN): New State: READY 001887: *Feb 22 20:10:20.019 ASAS: ISAKMP: local port 500, remote port 500 001888: *Feb 22 20:10:20.019 ASAS: ISAKMP: Find a dup sa in the avl tree during calling isadb_insert sa = 82A4D298 001889: *Feb 22 20:10:20.019 ASAS: ISAKMP:(0:0:N/A:0): client mode configured. 001890: *Feb 22 20:10:20.019 ASAS: ISAKMP:(0:0:N/A:0): constructed NAT-T vendor-07 ID 001891: *Feb 22 20:10:20.019 ASAS: ISAKMP:(0:0:N/A:0): constructed NAT-T vendor-03 ID 001892: *Feb 22 20:10:20.019 ASAS: ISAKMP:(0:0:N/A:0): constructed NAT-T vendor-02 ID 001893: *Feb 22 20:10:20.023 ASAS: EZVPN(testVPN): Current State: READY 001894: *Feb 22 20:10:20.023 ASAS: EZVPN(testVPN): Event: CONN_DOWN 001895: *Feb 22 20:10:20.023 ASAS: EZVPN(testVPN): ezvpn_close 001896: *Feb 22 20:10:20.023 ASAS: EZVPN(testVPN): Deleted PSK for address A.B.C.254 001897: *Feb 22 20:10:20.023 ASAS: EZVPN(testVPN): New State: CONNECT_REQUIRED 001898: *Feb 22 20:10:20.023 ASAS: EZVPN(testVPN): Current State: CONNECT_REQUIRED 001899: *Feb 22 20:10:20.023 ASAS: EZVPN(testVPN): Event: CONNECT 001900: *Feb 22 20:10:20.023 ASAS: EZVPN(testVPN): ezvpn_connect_request 001901: *Feb 22 20:10:20.023 ASAS: EZVPN(testVPN): Found valid peer A.B.C.254 001902: *Feb 22 20:10:20.023 ASAS: EZVPN(testVPN): Added PSK for address A.B.C.254 001903: *Feb 22 20:10:20.023 ASAS: EZVPN(testVPN): New State: READY 001904: *Feb 22 20:10:20.051 ASAS: ISAKMP:(0:1:HW:2):SA is doing pre-shared key authentication plus XAUTH using id type ID_KEY_ID 001905: *Feb 22 20:10:20.051 ASAS: ISAKMP (0:268435457): ID payload next-payload : 13 type : 11 group id : testVPN protocol : 17 port : 0 length : 17 001906: *Feb 22 20:10:20.051 ASAS: ISAKMP:(0:1:HW:2):Total payload length: 17 001907: *Feb 22 20:10:20.051 ASAS: ISAKMP:(0:1:HW:2):Input = IKE_MESG_FROM_IPSEC, IKE_SA_REQ_AM 001908: *Feb 22 20:10:20.051 ASAS: ISAKMP:(0:1:HW:2):Old State = IKE_READY New State = IKE_I_AM1 001909: *Feb 22 20:10:20.051 ASAS: ISAKMP:(0:1:HW:2): beginning Aggressive Mode exchange 001910: *Feb 22 20:10:20.051 ASAS: ISAKMP:(0:1:HW:2): sending packet to A.B.C.254 my_port 500 peer_port 500 (I) AG_INIT_EXCH 001911: *Feb 22 20:10:20.051 ASAS: ISAKMP:(0:1:HW:2):SA is still budding. Attached new ipsec request to it. (local X.Y.Z.152, remote A.B.C.2 54) 001912: *Feb 22 20:10:30.051 ASAS: ISAKMP:(0:1:HW:2): retransmitting phase 1 AG_INIT_EXCH... 001913: *Feb 22 20:10:30.051 ASAS: ISAKMP:(0:1:HW:2):incrementing error counter on sa: retransmit phase 1 001914: *Feb 22 20:10:30.051 ASAS: ISAKMP:(0:1:HW:2): retransmitting phase 1 AG_INIT_EXCH 001915: *Feb 22 20:10:30.051 ASAS: ISAKMP:(0:1:HW:2): sending packet to A.B.C.254 my_port 500 peer_port 500 (I) AG_INIT_EXCH 001916: *Feb 22 20:10:40.051 ASAS: ISAKMP:(0:1:HW:2): retransmitting phase 1 AG_INIT_EXCH... 001917: *Feb 22 20:10:40.051 ASAS: ISAKMP:(0:1:HW:2):incrementing error counter on sa: retransmit phase 1 001918: *Feb 22 20:10:40.051 ASAS: ISAKMP:(0:1:HW:2): retransmitting phase 1 AG_INIT_EXCH 001919: *Feb 22 20:10:40.051 ASAS: ISAKMP:(0:1:HW:2): sending packet to A.B.C.254 my_port 500 peer_port 500 (I) AG_INIT_EXCH 001920: *Feb 22 20:10:50.051 ASAS: ISAKMP:(0:1:HW:2): retransmitting phase 1 AG_INIT_EXCH... 001921: *Feb 22 20:10:50.051 ASAS: ISAKMP:(0:1:HW:2):incrementing error counter on sa: retransmit phase 1 001922: *Feb 22 20:10:50.051 ASAS: ISAKMP:(0:1:HW:2): retransmitting phase 1 AG_INIT_EXCH 001923: *Feb 22 20:10:50.051 ASAS: ISAKMP:(0:1:HW:2): sending packet to A.B.C.254 my_port 500 peer_port 500 (I) AG_INIT_EXCH 001924: *Feb 22 20:11:00.051 ASAS: ISAKMP:(0:1:HW:2): retransmitting phase 1 AG_INIT_EXCH... 001925: *Feb 22 20:11:00.051 ASAS: ISAKMP:(0:1:HW:2):incrementing error counter on sa: retransmit phase 1 001926: *Feb 22 20:11:00.051 ASAS: ISAKMP:(0:1:HW:2): retransmitting phase 1 AG_INIT_EXCH 001927: *Feb 22 20:11:00.051 ASAS: ISAKMP:(0:1:HW:2): sending packet to A.B.C.254 my_port 500 peer_port 500 (I) AG_INIT_EXCH 001928: *Feb 22 20:11:10.051 ASAS: ISAKMP:(0:1:HW:2): retransmitting phase 1 AG_INIT_EXCH... 001929: *Feb 22 20:11:10.051 ASAS: ISAKMP:(0:1:HW:2):incrementing error counter on sa: retransmit phase 1 001930: *Feb 22 20:11:10.051 ASAS: ISAKMP:(0:1:HW:2): retransmitting phase 1 AG_INIT_EXCH 001931: *Feb 22 20:11:10.051 ASAS: ISAKMP:(0:1:HW:2): sending packet to A.B.C.254 my_port 500 peer_port 500 (I) AG_INIT_EXCH 001932: *Feb 22 20:11:20.015 ASAS: ISAKMP:(0:20:HW:2):purging SA., sa=82A47EC4, delme=82A47EC4 001933: *Feb 22 20:11:20.023 ASAS: EZVPN(testVPN): Current State: READY 001934: *Feb 22 20:11:20.023 ASAS: EZVPN(testVPN): Event: RESET 001935: *Feb 22 20:11:20.023 ASAS: EZVPN(testVPN): New active peer is A.B.C.254 001936: *Feb 22 20:11:20.023 ASAS: EZVPN(testVPN): ezvpn_close 001937: *Feb 22 20:11:20.023 ASAS: EZVPN(testVPN): Deleted PSK for address A.B.C.254 001938: *Feb 22 20:11:20.023 ASAS: EZVPN(testVPN): ezvpn_reset 001939: *Feb 22 20:11:20.023 ASAS: EZVPN(testVPN): New State: CONNECT_REQUIRED 001940: *Feb 22 20:11:20.023 ASAS: EZVPN(testVPN): Current State: CONNECT_REQUIRED 001941: *Feb 22 20:11:20.023 ASAS: EZVPN(testVPN): Event: CONNECT 001942: *Feb 22 20:11:20.023 ASAS: EZVPN(testVPN): ezvpn_connect_request 001943: *Feb 22 20:11:20.023 ASAS: EZVPN(testVPN): Found valid peer A.B.C.254 001944: *Feb 22 20:11:20.023 ASAS: EZVPN(testVPN): Added PSK for address A.B.C.254 001945: *Feb 22 20:11:20.023 ASAS: EZVPN(testVPN): New State: READY 001946: *Feb 22 20:11:20.023 ASAS: ISAKMP:(0:1:HW:2):SA is still budding. Attached new ipsec request to it. (local X.Y.Z.152, remote A.B.C.2 54) 001947: *Feb 22 20:11:20.051 ASAS: ISAKMP:(0:1:HW:2): retransmitting phase 1 AG_INIT_EXCH... 001948: *Feb 22 20:11:20.051 ASAS: ISAKMP:(0:1:HW:2):peer does not do paranoid keepalives. 001949: *Feb 22 20:11:20.051 ASAS: ISAKMP:(0:1:HW:2):deleting SA reason "Death by retransmission P1" state (I) AG_INIT_EXCH (peer A.B.C.254) 001950: *Feb 22 20:11:20.051 ASAS: EZVPN(testVPN): Current State: READY 001951: *Feb 22 20:11:20.051 ASAS: EZVPN(testVPN): Event: CONNECT_NEXT_PEER 001952: *Feb 22 20:11:20.051 ASAS: EZVPN(testVPN): ezvpn_close 001953: *Feb 22 20:11:20.051 ASAS: EZVPN(testVPN): Deleted PSK for address A.B.C.254 001954: *Feb 22 20:11:20.051 ASAS: EZVPN(testVPN): New active peer is A.B.C.254 001955: *Feb 22 20:11:20.051 ASAS: EZVPN(testVPN): Attempting to connect to peer A.B.C.254 001956: *Feb 22 20:11:20.051 ASAS: EZVPN(testVPN): New State: CONNECT_REQUIRED 001957: *Feb 22 20:11:20.051 ASAS: ISAKMP:(0:1:HW:2):deleting SA reason "Death by retransmission P1" state (I) AG_INIT_EXCH (peer A.B.C.254) 001958: *Feb 22 20:11:20.051 ASAS: ISAKMP:(0:1:HW:2):Input = IKE_MESG_INTERNAL, IKE_PHASE1_DEL 001959: *Feb 22 20:11:20.051 ASAS: ISAKMP:(0:1:HW:2):Old State = IKE_I_AM1 New State = IKE_DEST_SA 001960: *Feb 22 20:11:20.051 ASAS: EZVPN(testVPN): Current State: CONNECT_REQUIRED 001961: *Feb 22 20:11:20.051 ASAS: EZVPN(testVPN): Event: CONNECT 001962: *Feb 22 20:11:20.051 ASAS: EZVPN(testVPN): ezvpn_connect_request 001963: *Feb 22 20:11:20.055 ASAS: EZVPN(testVPN): Found valid peer A.B.C.254 001964: *Feb 22 20:11:20.055 ASAS: EZVPN(testVPN): Added PSK for address A.B.C.254 001965: *Feb 22 20:11:20.055 ASAS: EZVPN(testVPN): New State: READY 001966: *Feb 22 20:11:20.055 ASAS: ISAKMP: local port 500, remote port 500 001967: *Feb 22 20:11:20.055 ASAS: ISAKMP: Find a dup sa in the avl tree during calling isadb_insert sa = 82A43824 001968: *Feb 22 20:11:20.055 ASAS: ISAKMP:(0:0:N/A:0): client mode configured. 001969: *Feb 22 20:11:20.055 ASAS: ISAKMP:(0:0:N/A:0): constructed NAT-T vendor-07 ID 001970: *Feb 22 20:11:20.055 ASAS: ISAKMP:(0:0:N/A:0): constructed NAT-T vendor-03 ID 001971: *Feb 22 20:11:20.055 ASAS: ISAKMP:(0:0:N/A:0): constructed NAT-T vendor-02 ID 001972: *Feb 22 20:11:20.059 ASAS: EZVPN(testVPN): Current State: READY 001973: *Feb 22 20:11:20.059 ASAS: EZVPN(testVPN): Event: CONN_DOWN 001974: *Feb 22 20:11:20.059 ASAS: EZVPN(testVPN): ezvpn_close 001975: *Feb 22 20:11:20.059 ASAS: EZVPN(testVPN): Deleted PSK for address A.B.C.254 001976: *Feb 22 20:11:20.059 ASAS: EZVPN(testVPN): New State: CONNECT_REQUIRED 001977: *Feb 22 20:11:20.059 ASAS: EZVPN(testVPN): Current State: CONNECT_REQUIRED 001978: *Feb 22 20:11:20.059 ASAS: EZVPN(testVPN): Event: CONNECT 001979: *Feb 22 20:11:20.059 ASAS: EZVPN(testVPN): ezvpn_connect_request 001980: *Feb 22 20:11:20.059 ASAS: EZVPN(testVPN): Found valid peer A.B.C.254 001981: *Feb 22 20:11:20.059 ASAS: EZVPN(testVPN): Added PSK for address A.B.C.254 001982: *Feb 22 20:11:20.059 ASAS: EZVPN(testVPN): New State: READY 001983: *Feb 22 20:11:20.087 ASAS: ISAKMP:(0:2:HW:2):SA is doing pre-shared key authentication plus XAUTH using id type ID_KEY_ID 001984: *Feb 22 20:11:20.087 ASAS: ISAKMP (0:268435458): ID payload next-payload : 13 type : 11 group id : testVPN protocol : 17 port : 0 length : 17 001985: *Feb 22 20:11:20.087 ASAS: ISAKMP:(0:2:HW:2):Total payload length: 17 001986: *Feb 22 20:11:20.087 ASAS: ISAKMP:(0:2:HW:2):Input = IKE_MESG_FROM_IPSEC, IKE_SA_REQ_AM 001987: *Feb 22 20:11:20.087 ASAS: ISAKMP:(0:2:HW:2):Old State = IKE_READY New State = IKE_I_AM1 001988: *Feb 22 20:11:20.087 ASAS: ISAKMP:(0:2:HW:2): beginning Aggressive Mode exchange 001989: *Feb 22 20:11:20.087 ASAS: ISAKMP:(0:2:HW:2): sending packet to A.B.C.254 my_port 500 peer_port 500 (I) AG_INIT_EXCH 001990: *Feb 22 20:11:20.087 ASAS: ISAKMP:(0:2:HW:2):SA is still budding. Attached new ipsec request to it. (local X.Y.Z.152, remote A.B.C.2 54) 001991: *Feb 22 20:11:30.087 ASAS: ISAKMP:(0:2:HW:2): retransmitting phase 1 AG_INIT_EXCH... 001992: *Feb 22 20:11:30.087 ASAS: ISAKMP:(0:2:HW:2):incrementing error counter on sa: retransmit phase 1 001993: *Feb 22 20:11:30.087 ASAS: ISAKMP:(0:2:HW:2): retransmitting phase 1 AG_INIT_EXCH 001994: *Feb 22 20:11:30.087 ASAS: ISAKMP:(0:2:HW:2): sending packet to A.B.C.254 my_port 500 peer_port 500 (I) AG_INIT_EXCH 001995: *Feb 22 20:11:40.087 ASAS: ISAKMP:(0:2:HW:2): retransmitting phase 1 AG_INIT_EXCH... 001996: *Feb 22 20:11:40.087 ASAS: ISAKMP:(0:2:HW:2):incrementing error counter on sa: retransmit phase 1 001997: *Feb 22 20:11:40.087 ASAS: ISAKMP:(0:2:HW:2): retransmitting phase 1 AG_INIT_EXCH 001998: *Feb 22 20:11:40.087 ASAS: ISAKMP:(0:2:HW:2): sending packet to A.B.C.254 my_port 500 peer_port 500 (I) AG_INIT_EXCH 001999: *Feb 22 20:11:50.086 ASAS: ISAKMP:(0:2:HW:2): retransmitting phase 1 AG_INIT_EXCH... 002000: *Feb 22 20:11:50.086 ASAS: ISAKMP:(0:2:HW:2):incrementing error counter on sa: retransmit phase 1 002001: *Feb 22 20:11:50.086 ASAS: ISAKMP:(0:2:HW:2): retransmitting phase 1 AG_INIT_EXCH 002002: *Feb 22 20:11:50.086 ASAS: ISAKMP:(0:2:HW:2): sending packet to A.B.C.254 my_port 500 peer_port 500 (I) AG_INIT_EXCH test#