I am in the process of configuring a Cisco 1720 Router to be a VPN server for the Cisco VPN Client software. We intend on using it for our mobile users alone.
I have been able to get the VPN running. The client authenticates through the router to a RADIUS server and phase 2 completes. That's were things go wrong.
The client is unable to access anything on the VPN network, nor can it access the interent. I suspect I need to do something in the ACL 110, but I'm not sure what.
Here is the output of the Client LOG:
Cisco Systems VPN Client Version 5.0.02.0090
Copyright (C) 1998-2007 Cisco Systems, Inc. All Rights Reserved.
Client Type(s): Windows, WinNT
Running on: 5.1.2600 Service Pack 2
1 10:41:49.000 12/28/07 Sev=Warning/3 IKE/0xE3000085
The length, 0, of the Mode Config option, INTERNAL_IPV4_NETMASK, is invalid
2 10:41:51.093 12/28/07 Sev=Warning/2 CVPND/0xE3400013
AddRoute failed to add a route: code 87
Destination 192.168.254.255
Netmask 255.255.255.255
Gateway 172.17.0.1
Interface 172.17.2.36
3 10:41:51.093 12/28/07 Sev=Warning/2 CM/0xA3100024
Unable to add route. Network: c0a8feff, Netmask: ffffffff, Interface: ac110224, Gateway: ac110001.
Debugs on the router have not provided much more information. But here is the log output anyway (The time on the router is incorrect, I will fix that later):
Log Buffer (16000 bytes):
*Mar 2 23:54:59.720: ISAKMP (0:10): Checking IPSec proposal 9
*Mar 2 23:54:59.724: ISAKMP: transform 1, ESP_3DES
*Mar 2 23:54:59.724: ISAKMP: attributes in transform:
*Mar 2 23:54:59.724: ISAKMP: authenticator is HMAC-MD5
*Mar 2 23:54:59.724: ISAKMP: encaps is 61443 (Tunnel-UDP)
*Mar 2 23:54:59.724: ISAKMP: SA life type in seconds
*Mar 2 23:54:59.724: ISAKMP: SA life duration (VPI) of 0x0 0x20 0xC4 0x9B
*Mar 2 23:54:59.724: ISAKMP (0:10): atts are acceptable.
*Mar 2 23:54:59.724: ISAKMP (0:10): Checking IPSec proposal 9
*Mar 2 23:54:59.724: ISAKMP (0:10): transform 1, IPPCP LZS
*Mar 2 23:54:59.728: ISAKMP: attributes in transform:
*Mar 2 23:54:59.728: ISAKMP: encaps is 61443 (Tunnel-UDP)
*Mar 2 23:54:59.728: ISAKMP: SA life type in seconds
*Mar 2 23:54:59.728: ISAKMP: SA life duration (VPI) of 0x0 0x20 0xC4 0x9B
*Mar 2 23:54:59.728: ISAKMP (0:10): atts are acceptable.
*Mar 2 23:54:59.728: IPSEC(validate_proposal_re
quest): proposal part #1,
(key eng. msg.) INBOUND local= 162.40.32.9, remote= 162.40.32.22,
local_proxy= 0.0.0.0/0.0.0.0/0/0 (type=4),
remote_proxy= 172.17.2.36/255.255.255.25
5/0/0 (type=1),
protocol= ESP, transform= esp-3des esp-md5-hmac (Tunnel-UDP),
lifedur= 0s and 0kb,
spi= 0x0(0), conn_id= 0, keysize= 0, flags= 0x400
*Mar 2 23:54:59.732: IPSEC(validate_proposal_re
quest): proposal part #2,
(key eng. msg.) INBOUND local= 162.40.32.9, remote= 162.40.32.22,
local_proxy= 0.0.0.0/0.0.0.0/0/0 (type=4),
remote_proxy= 172.17.2.36/255.255.255.25
5/0/0 (type=1),
protocol= PCP, transform= comp-lzs (Tunnel-UDP),
lifedur= 0s and 0kb,
spi= 0x0(0), conn_id= 0, keysize= 0, flags= 0x400
*Mar 2 23:54:59.732: IPSEC(kei_proxy): head = clientmap, map->ivrf = , kei->ivrf =
*Mar 2 23:54:59.732: IPSEC(validate_transform_p
roposal): transform proposal not supported for identity:
{esp-3des esp-md5-hmac comp-lzs }
*Mar 2 23:54:59.732: ISAKMP (0:10): IPSec policy invalidated proposal
*Mar 2 23:54:59.736: ISAKMP (0:10): Checking IPSec proposal 10
*Mar 2 23:54:59.736: ISAKMP: transform 1, ESP_3DES
*Mar 2 23:54:59.736: ISAKMP: attributes in transform:
*Mar 2 23:54:59.736: ISAKMP: authenticator is HMAC-SHA
*Mar 2 23:54:59.736: ISAKMP: encaps is 61443 (Tunnel-UDP)
*Mar 2 23:54:59.736: ISAKMP: SA life type in seconds
*Mar 2 23:54:59.736: ISAKMP: SA life duration (VPI) of 0x0 0x20 0xC4 0x9B
*Mar 2 23:54:59.736: ISAKMP (0:10): atts are acceptable.
*Mar 2 23:54:59.740: ISAKMP (0:10): Checking IPSec proposal 10
*Mar 2 23:54:59.740: ISAKMP (0:10): transform 1, IPPCP LZS
*Mar 2 23:54:59.740: ISAKMP: attributes in transform:
*Mar 2 23:54:59.740: ISAKMP: encaps is 61443 (Tunnel-UDP)
*Mar 2 23:54:59.740: ISAKMP: SA life type in seconds
*Mar 2 23:54:59.740: ISAKMP: SA life duration (VPI) of 0x0 0x20 0xC4 0x9B
*Mar 2 23:54:59.740: ISAKMP (0:10): atts are acceptable.
*Mar 2 23:54:59.740: IPSEC(validate_proposal_re
quest): proposal part #1,
(key eng. msg.) INBOUND local= 162.40.32.9, remote= 162.40.32.22,
local_proxy= 0.0.0.0/0.0.0.0/0/0 (type=4),
remote_proxy= 172.17.2.36/255.255.255.25
5/0/0 (type=1),
protocol= ESP, transform= esp-3des esp-sha-hmac (Tunnel-UDP),
lifedur= 0s and 0kb,
spi= 0x0(0), conn_id= 0, keysize= 0, flags= 0x400
*Mar 2 23:54:59.744: IPSEC(validate_proposal_re
quest): proposal part #2,
(key eng. msg.) INBOUND local= 162.40.32.9, remote= 162.40.32.22,
local_proxy= 0.0.0.0/0.0.0.0/0/0 (type=4),
remote_proxy= 172.17.2.36/255.255.255.25
5/0/0 (type=1),
protocol= PCP, transform= comp-lzs (Tunnel-UDP),
lifedur= 0s and 0kb,
spi= 0x0(0), conn_id= 0, keysize= 0, flags= 0x400
*Mar 2 23:54:59.744: IPSEC(kei_proxy): head = clientmap, map->ivrf = , kei->ivrf =
*Mar 2 23:54:59.744: IPSEC(validate_transform_p
roposal): transform proposal not supported for identity:
{esp-3des esp-sha-hmac comp-lzs }
*Mar 2 23:54:59.748: ISAKMP (0:10): IPSec policy invalidated proposal
*Mar 2 23:54:59.748: ISAKMP (0:10): Checking IPSec proposal 11
*Mar 2 23:54:59.748: ISAKMP: transform 1, ESP_3DES
*Mar 2 23:54:59.748: ISAKMP: attributes in transform:
*Mar 2 23:54:59.748: ISAKMP: authenticator is HMAC-MD5
*Mar 2 23:54:59.748: ISAKMP: encaps is 61443 (Tunnel-UDP)
*Mar 2 23:54:59.748: ISAKMP: SA life type in seconds
*Mar 2 23:54:59.748: ISAKMP: SA life duration (VPI) of 0x0 0x20 0xC4 0x9B
*Mar 2 23:54:59.752: ISAKMP (0:10): atts are acceptable.
*Mar 2 23:54:59.752: IPSEC(validate_proposal_re
quest): proposal part #1,
(key eng. msg.) INBOUND local= 162.40.32.9, remote= 162.40.32.22,
local_proxy= 0.0.0.0/0.0.0.0/0/0 (type=4),
remote_proxy= 172.17.2.36/255.255.255.25
5/0/0 (type=1),
protocol= ESP, transform= esp-3des esp-md5-hmac (Tunnel-UDP),
lifedur= 0s and 0kb,
spi= 0x0(0), conn_id= 0, keysize= 0, flags= 0x400
*Mar 2 23:54:59.752: IPSEC(kei_proxy): head = clientmap, map->ivrf = , kei->ivrf =
*Mar 2 23:54:59.752: IPSEC(validate_transform_p
roposal): transform proposal not supported for identity:
{esp-3des esp-md5-hmac }
*Mar 2 23:54:59.756: ISAKMP (0:10): IPSec policy invalidated proposal
*Mar 2 23:54:59.756: ISAKMP (0:10): Checking IPSec proposal 12
*Mar 2 23:54:59.756: ISAKMP: transform 1, ESP_3DES
*Mar 2 23:54:59.756: ISAKMP: attributes in transform:
*Mar 2 23:54:59.756: ISAKMP: authenticator is HMAC-SHA
*Mar 2 23:54:59.756: ISAKMP: encaps is 61443 (Tunnel-UDP)
*Mar 2 23:54:59.756: ISAKMP: SA life type in seconds
*Mar 2 23:54:59.756: ISAKMP: SA life duration (VPI) of 0x0 0x20 0xC4 0x9B
*Mar 2 23:54:59.756: ISAKMP (0:10): atts are acceptable.
*Mar 2 23:54:59.760: IPSEC(validate_proposal_re
quest): proposal part #1,
(key eng. msg.) INBOUND local= 162.40.32.9, remote= 162.40.32.22,
local_proxy= 0.0.0.0/0.0.0.0/0/0 (type=4),
remote_proxy= 172.17.2.36/255.255.255.25
5/0/0 (type=1),
protocol= ESP, transform= esp-3des esp-sha-hmac (Tunnel-UDP),
lifedur= 0s and 0kb,
spi= 0x0(0), conn_id= 0, keysize= 0, flags= 0x400
*Mar 2 23:54:59.760: IPSEC(kei_proxy): head = clientmap, map->ivrf = , kei->ivrf =
*Mar 2 23:54:59.760: ISAKMP (0:10): processing NONCE payload. message ID = 914356287
*Mar 2 23:54:59.764: ISAKMP (0:10): processing ID payload. message ID = 914356287
*Mar 2 23:54:59.764: ISAKMP (0:10): processing ID payload. message ID = 914356287
*Mar 2 23:54:59.764: ISAKMP (0:10): asking for 1 spis from ipsec
*Mar 2 23:54:59.764: ISAKMP (0:10): Node 914356287, Input = IKE_MESG_FROM_PEER, IKE_QM_EXCH
*Mar 2 23:54:59.764: ISAKMP (0:10): Old State = IKE_QM_READY New State = IKE_QM_SPI_STARVE
*Mar 2 23:54:59.764: IPSEC(key_engine): got a queue event...
*Mar 2 23:54:59.768: IPSEC(spi_response): getting spi 2473336454 for SA
from 162.40.32.9 to 162.40.32.22 for prot 3
*Mar 2 23:54:59.768: ISAKMP: received ke message (2/1)
*Mar 2 23:55:00.224: ISAKMP: Locking peer struct 0x82212B5C, IPSEC refcount 1 for for stuff_ke
*Mar 2 23:55:00.224: ISAKMP (0:10): Creating IPSec SAs
*Mar 2 23:55:00.224: inbound SA from 162.40.32.22 to 162.40.32.9 (f/i) 0/ 0
(proxy 172.17.2.36 to 0.0.0.0)
*Mar 2 23:55:00.228: has spi 0x936C1E86 and conn_id 2000 and flags 400
*Mar 2 23:55:00.228: lifetime of 2147483 seconds
*Mar 2 23:55:00.228: has client flags 0x10
*Mar 2 23:55:00.228: outbound SA from 162.40.32.9 to 162.40.32.22 (f/i) 0/ 0 (proxy 0.0.0.0 to 172.17.2.36 )
*Mar 2 23:55:00.228: has spi 605580969 and conn_id 2001 and flags 408
*Mar 2 23:55:00.228: lifetime of 2147483 seconds
*Mar 2 23:55:00.228: has client flags 0x10
*Mar 2 23:55:00.232: ISAKMP (0:10): sending packet to 162.40.32.22 my_port 4500 peer_port 2026 (R) QM_IDLE
*Mar 2 23:55:00.232: ISAKMP (0:10): Node 914356287, Input = IKE_MESG_FROM_IPSEC, IKE_SPI_REPLY
*Mar 2 23:55:00.232: ISAKMP (0:10): Old State = IKE_QM_SPI_STARVE New State = IKE_QM_R_QM2
*Mar 2 23:55:00.236: IPSEC(key_engine): got a queue event...
*Mar 2 23:55:00.236: IPSEC(initialize_sas): ,
(key eng. msg.) INBOUND local= 162.40.32.9, remote= 162.40.32.22,
local_proxy= 0.0.0.0/0.0.0.0/0/0 (type=4),
remote_proxy= 172.17.2.36/0.0.0.0/0/0 (type=1),
protocol= ESP, transform= esp-3des esp-sha-hmac (Tunnel-UDP),
lifedur= 2147483s and 0kb,
spi= 0x936C1E86(2473336454), conn_id= 2000, keysize= 0, flags= 0x400
*Mar 2 23:55:00.236: IPSEC(initialize_sas): ,
(key eng. msg.) OUTBOUND local= 162.40.32.9, remote= 162.40.32.22,
local_proxy= 0.0.0.0/0.0.0.0/0/0 (type=4),
remote_proxy= 172.17.2.36/0.0.0.0/0/0 (type=1),
protocol= ESP, transform= esp-3des esp-sha-hmac (Tunnel-UDP),
lifedur= 2147483s and 0kb,
spi= 0x24186EA9(605580969), conn_id= 2001, keysize= 0, flags= 0x408
*Mar 2 23:55:00.240: IPSEC(kei_proxy): head = clientmap, map->ivrf = , kei->ivrf =
*Mar 2 23:55:00.240: IPSEC(add mtree): src 0.0.0.0, dest 172.17.2.36, dest_port 0
*Mar 2 23:55:00.240: IPSEC(create_sa): sa created,
(sa) sa_dest= 162.40.32.9, sa_prot= 50,
sa_spi= 0x936C1E86(2473336454),
sa_trans= esp-3des esp-sha-hmac , sa_conn_id= 2000
*Mar 2 23:55:00.244: IPSEC(create_sa): sa created,
(sa) sa_dest= 162.40.32.22, sa_prot= 50,
sa_spi= 0x24186EA9(605580969),
sa_trans= esp-3des esp-sha-hmac , sa_conn_id= 2001
*Mar 2 23:55:00.248: ISAKMP (0:10): received packet from 162.40.32.22 dport 4500 sport 2026 Global (R) QM_IDLE
*Mar 2 23:55:00.248: ISAKMP (0:10): deleting node 914356287 error FALSE reason "quick mode done (await)"
*Mar 2 23:55:00.248: ISAKMP (0:10): Node 914356287, Input = IKE_MESG_FROM_PEER, IKE_QM_EXCH
*Mar 2 23:55:00.252: ISAKMP (0:10): Old State = IKE_QM_R_QM2 New State = IKE_QM_PHASE2_COMPLETE
*Mar 2 23:55:00.252: IPSEC(key_engine): got a queue event...
*Mar 2 23:55:00.252: IPSEC(key_engine_enable_ou
tbound): rec'd enable notify from ISAKMP
*Mar 2 23:55:00.252: IPSEC(key_engine_enable_ou
tbound): enable SA with spi 605580969/50 for 162.40.32.22
*Mar 2 23:55:09.572: ISAKMP (0:10): received packet from 162.40.32.22 dport 4500 sport 2026 Global (R) QM_IDLE
*Mar 2 23:55:09.572: ISAKMP: set new node 314993000 to QM_IDLE
*Mar 2 23:55:09.576: ISAKMP (0:10): processing HASH payload. message ID = 314993000
*Mar 2 23:55:09.576: ISAKMP (0:10): processing NOTIFY R_U_THERE protocol 1
spi 0, message ID = 314993000, sa = 822B807C
*Mar 2 23:55:09.576: ISAKMP (0:10): deleting node 314993000 error FALSE reason "informational (in) state 1"
*Mar 2 23:55:09.576: ISAKMP (0:10): Input = IKE_MESG_FROM_PEER, IKE_INFO_NOTIFY
*Mar 2 23:55:09.576: ISAKMP (0:10): Old State = IKE_P1_COMPLETE New State = IKE_P1_COMPLETE
*Mar 2 23:55:09.576: ISAKMP (0:10): DPD/R_U_THERE received from peer 162.40.32.22, sequence 0x4ED0CD79
*Mar 2 23:55:09.580: ISAKMP: set new node 1231782143 to QM_IDLE
*Mar 2 23:55:09.580: ISAKMP (0:10): Sending NOTIFY R_U_THERE_ACK protocol 1
spi 2179399104, message ID = 1231782143 seq. no 0x4ED0CD79
*Mar 2 23:55:09.580: ISAKMP (0:10): sending packet to 162.40.32.22 my_port 4500 peer_port 2026 (R) QM_IDLE
*Mar 2 23:55:09.584: ISAKMP (0:10): purging node 1231782143
*Mar 2 23:55:09.584: ISAKMP (0:10): Input = IKE_MESG_FROM_PEER, IKE_MESG_KEEP_ALIVE
*Mar 2 23:55:09.584: ISAKMP (0:10): Old State = IKE_P1_COMPLETE New State = IKE_P1_COMPLETE
*Mar 2 23:55:15.412: ISAKMP (0:10): received packet from 162.40.32.22 dport 4500 sport 2026 Global (R) QM_IDLE
*Mar 2 23:55:15.412: ISAKMP: set new node -1817182271 to QM_IDLE
*Mar 2 23:55:15.416: ISAKMP (0:10): processing HASH payload. message ID = -1817182271
*Mar 2 23:55:15.416: ISAKMP (0:10): processing DELETE payload. message ID = -1817182271
*Mar 2 23:55:15.416: ISAKMP (0:10): peer does not do paranoid keepalives.
*Mar 2 23:55:15.416: ISAKMP (0:10): peer does not do paranoid keepalives.
*Mar 2 23:55:15.416: ISAKMP (0:10): deleting node -1817182271 error FALSE reason "informational (in) state 1"
*Mar 2 23:55:15.416: ISAKMP (0:10): received packet from 162.40.32.22 dport 4500 sport 2026 Global (R) QM_IDLE
*Mar 2 23:55:15.420: ISAKMP: set new node 1044263402 to QM_IDLE
*Mar 2 23:55:15.420: ISAKMP (0:10): processing HASH payload. message ID = 1044263402
*Mar 2 23:55:15.424: ISAKMP:received payload type 18
*Mar 2 23:55:15.424: ISAKMP (0:10): processing DELETE_WITH_REASON payload, message ID = 1044263402, reason: DELETE_BY_USER_COMMAND
*Mar 2 23:55:15.424: ISAKMP (0:10): peer does not do paranoid keepalives.
*Mar 2 23:55:15.424: ISAKMP (0:10): deleting SA reason "P1 delete notify (in)" state (R) QM_IDLE (peer 162.40.32.22) input queue 0
*Mar 2 23:55:15.424: ISAKMP (0:10): deleting node 1044263402 error FALSE reason "informational (in) state 1"
*Mar 2 23:55:15.424: IPSEC(key_engine): got a queue event...
*Mar 2 23:55:15.424: IPSEC(key_engine_delete_sa
s): rec'd delete notify from ISAKMP
*Mar 2 23:55:15.428: IPSEC(key_engine_delete_sa
s): delete SA with spi 605580969/50 for 162.40.32.22
*Mar 2 23:55:15.428: IPSEC(delete_sa): deleting SA,
(sa) sa_dest= 162.40.32.9, sa_prot= 50,
sa_spi= 0x936C1E86(2473336454),
sa_trans= esp-3des esp-sha-hmac , sa_conn_id= 2000
*Mar 2 23:55:15.428: IPSEC(add_sa): have new SAs -- expire existing in 30 sec.,
(sa) sa_dest= 162.40.32.22, sa_prot= 50,
sa_spi= 0x24186EA9(605580969),
sa_trans= esp-3des esp-sha-hmac , sa_conn_id= 2001,
(identity) local= 162.40.32.9, remote= 162.40.32.22,
local_proxy= 0.0.0.0/0.0.0.0/0/0 (type=4),
remote_proxy= 172.17.2.36/255.255.255.25
5/0/0 (type=1)
*Mar 2 23:55:15.432: IPSEC(delete_sa): deleting SA,
(sa) sa_dest= 162.40.32.22, sa_prot= 50,
sa_spi= 0x24186EA9(605580969),
sa_trans= esp-3des esp-sha-hmac , sa_conn_id= 2001
*Mar 2 23:55:15.432: ISAKMP: Unlocking IPSEC struct 0x82212B5C from delete_siblings, count 0
*Mar 2 23:55:15.432: IPSEC(key_engine): got a queue event...
*Mar 2 23:55:15.432: IPSEC(key_engine_delete_sa
s): rec'd delete notify from ISAKMP
*Mar 2 23:55:15.436: ISAKMP (0:10): Input = IKE_MESG_INTERNAL, IKE_PHASE1_DEL
*Mar 2 23:55:15.436: ISAKMP (0:10): Old State = IKE_P1_COMPLETE New State = IKE_DEST_SA
*Mar 2 23:55:15.436: ISAKMP (0:10): deleting SA reason "" state (R) QM_IDLE (peer 162.40.32.22) input queue 0
*Mar 2 23:55:15.436: ISAKMP (0:10): returning address 172.17.2.36 to pool
*Mar 2 23:55:15.440: ISAKMP: Unlocking IKE struct 0x82212B5C for isadb_mark_sa_deleted(), count 0
*Mar 2 23:55:15.440: ISAKMP: returning address 172.17.2.36 to pool
*Mar 2 23:55:15.440: ISAKMP: Deleting peer node by peer_reap for 162.40.32.22: 82212B5C
*Mar 2 23:55:15.440: ISAKMP (0:10): deleting node -1720911968 error FALSE reason ""
*Mar 2 23:55:15.440: ISAKMP (0:10): deleting node 327213268 error FALSE reason ""
*Mar 2 23:55:15.444: ISAKMP (0:10): deleting node -1086291021 error FALSE reason ""
*Mar 2 23:55:15.444: ISAKMP (0:10): deleting node 914356287 error FALSE reason ""
*Mar 2 23:55:15.444: ISAKMP (0:10): deleting node 314993000 error FALSE reason ""
*Mar 2 23:55:15.444: ISAKMP (0:10): deleting node -1817182271 error FALSE reason ""
*Mar 2 23:55:15.444: ISAKMP (0:10): deleting node 1044263402 error FALSE reason ""
*Mar 2 23:55:15.444: ISAKMP (0:10): Input = IKE_MESG_FROM_PEER, IKE_MM_EXCH
*Mar 2 23:55:15.444: ISAKMP (0:10): Old State = IKE_DEST_SA New State = IKE_DEST_SA
*Mar 2 23:56:05.440: ISAKMP (0:10): purging node -1720911968
*Mar 2 23:56:05.444: ISAKMP (0:10): purging node 327213268
*Mar 2 23:56:05.444: ISAKMP (0:10): purging node -1086291021
*Mar 2 23:56:05.444: ISAKMP (0:10): purging node 914356287
*Mar 2 23:56:05.444: ISAKMP (0:10): purging node 314993000
*Mar 2 23:56:05.444: ISAKMP (0:10): purging node -1817182271
*Mar 2 23:56:05.448: ISAKMP (0:10): purging node 1044263402
*Mar 2 23:56:15.444: ISAKMP (0:10): purging SA., sa=822B807C, delme=822B807C
I hope that is enough info. I've lost a lot of hair over "EZVPN"
Start Free Trial