May 20 14:29:16.348: IKEv2:(SESSION ID = 52,SA ID = 1):Check for existing IPSEC SA
May 20 14:29:46.206: IKEv2:% Getting preshared key from profile keyring KEY
May 20 14:29:46.206: IKEv2-INTERNAL:% Getting preshared key by address Y.Y.Y.151
May 20 14:29:46.207: IKEv2:% Matched peer block 'VPN'
May 20 14:29:46.207: IKEv2:Searching Policy with fvrf 0, local address B.B.B.148
May 20 14:29:46.207: IKEv2:Found Policy 'POLICY'
May 20 14:29:46.207: IKEv2-INTERNAL:Adding Proposal PROPOSAL to toolkit policy
May 20 14:29:46.207: IKEv2-INTERNAL:(1): Choosing IKE profile PROFILE
May 20 14:29:46.207: IKEv2-INTERNAL:New ikev2 sa request admitted
May 20 14:29:46.208: IKEv2-INTERNAL:Incrementing outgoing negotiating sa count by one
May 20 14:29:46.208: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=0000000000000000 (I) MsgID = 0 CurState: IDLE Event: EV_INIT_SA
May 20 14:29:46.208: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=0000000000000000 (I) MsgID = 0 CurState: I_BLD_INIT Event: EV_GET_IKE_POLICY
May 20 14:29:46.208: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=0000000000000000 (I) MsgID = 0 CurState: I_BLD_INIT Event: EV_SET_POLICY
May 20 14:29:46.208: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):Setting configured policies
May 20 14:29:46.209: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=0000000000000000 (I) MsgID = 0 CurState: I_BLD_INIT Event: EV_CHK_AUTH4PKI
May 20 14:29:46.209: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=0000000000000000 (I) MsgID = 0 CurState: I_BLD_INIT Event: EV_GEN_DH_KEY
May 20 14:29:46.209: IKEv2:(SESSION ID = 52,SA ID = 1):[IKEv2 -> Crypto Engine] Computing DH public key, DH Group 20
May 20 14:29:46.209: IKEv2:(SA ID = 1):[Crypto Engine -> IKEv2] DH key Computation PASSED
May 20 14:29:46.209: IKEv2:(SESSION ID = 52,SA ID = 1):Request queued for computation of DH key
May 20 14:29:46.209: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=0000000000000000 (I) MsgID = 0 CurState: I_BLD_INIT Event: EV_NO_EVENT
May 20 14:29:46.210: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=0000000000000000 (I) MsgID = 0 CurState: I_BLD_INIT Event: EV_OK_RECD_DH_PUBKEY_RESP
May 20 14:29:46.210: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):Action: Action_Null
May 20 14:29:46.210: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=0000000000000000 (I) MsgID = 0 CurState: I_BLD_INIT Event: EV_GET_CONFIG_MODE
May 20 14:29:46.210: IKEv2:IKEv2 initiator - no config data to send in IKE_SA_INIT exch
May 20 14:29:46.210: IKEv2-INTERNAL:No config data to send to toolkit:
May 20 14:29:46.210: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=0000000000000000 (I) MsgID = 0 CurState: I_BLD_INIT Event: EV_BLD_MSG
May 20 14:29:46.210: IKEv2:(SESSION ID = 52,SA ID = 1):Generating IKE_SA_INIT message
May 20 14:29:46.210: IKEv2:(SESSION ID = 52,SA ID = 1):IKE Proposal: 1, SPI size: 0 (initial negotiation),
Num. transforms: 8
   AES-GCM   AES-GCM   SHA512   SHA384   SHA256   DH_GROUP_384_ECP/Group 20   DH_GROUP_256_ECP/Group 19   DH_GROUP_2048_MODP/Group 14
May 20 14:29:46.211: IKEv2-INTERNAL:Construct Vendor Specific Payload: DELETE-REASON
May 20 14:29:46.211: IKEv2-INTERNAL:Construct Vendor Specific Payload: CISCOVPN-REV-02
May 20 14:29:46.211: IKEv2-INTERNAL:Sending DRU Handshake
May 20 14:29:46.211: IKEv2-INTERNAL:(1): Sending custom vendor id : CISCO-DYNAMIC-ROUTE
May 20 14:29:46.212: IKEv2-INTERNAL:Construct Vendor Specific Payload: (CUSTOM)
May 20 14:29:46.212: IKEv2-INTERNAL:Construct Vendor Specific Payload: (CUSTOM)
May 20 14:29:46.212: IKEv2-INTERNAL:Construct Notify Payload: NAT_DETECTION_SOURCE_IP
May 20 14:29:46.212: IKEv2-INTERNAL:Construct Notify Payload: NAT_DETECTION_DESTINATION_IP

May 20 14:29:46.212: IKEv2:(SESSION ID = 52,SA ID = 1):Sending Packet [To Y.Y.Y.151:500/From B.B.B.148:500/VRF i0:f0]
Initiator SPI : 41C05D4DFB445428 - Responder SPI : 0000000000000000 Message id: 0
IKEv2 IKE_SA_INIT Exchange REQUEST
Payload contents:
 SA KE N VID VID VID VID NOTIFY(NAT_DETECTION_SOURCE_IP) NOTIFY(NAT_DETECTION_DESTINATION_IP)

May 20 14:29:46.213: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=0000000000000000 (I) MsgID = 0 CurState: I_BLD_INIT Event: EV_INSERT_SA
May 20 14:29:46.213: IKEv2:(SESSION ID = 52,SA ID = 1):Insert SA
May 20 14:29:46.213: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=0000000000000000 (I) MsgID = 0 CurState: I_WAIT_INIT Event: EV_NO_EVENT
May 20 14:29:46.251: IKEv2-INTERNAL:Got a packet from dispatcher

May 20 14:29:46.251: IKEv2-INTERNAL:Processing an item off the pak queue


May 20 14:29:46.251: IKEv2:(SESSION ID = 52,SA ID = 1):Received Packet [From Y.Y.Y.151:500/To B.B.B.148:500/VRF i0:f0]
Initiator SPI : 41C05D4DFB445428 - Responder SPI : EA777AB94F2C822A Message id: 0
IKEv2 IKE_SA_INIT Exchange RESPONSE
Payload contents:
 SA KE N
May 20 14:29:46.252: IKEv2-INTERNAL:Parse Vendor Specific Payload: CISCO-DELETE-REASON VID
May 20 14:29:46.252: IKEv2-INTERNAL:Parse Vendor Specific Payload: (CUSTOM) VID
May 20 14:29:46.252: IKEv2-INTERNAL:Parse Notify Payload: NAT_DETECTION_SOURCE_IP NOTIFY(NAT_DETECTION_SOURCE_IP)
May 20 14:29:46.253: IKEv2-INTERNAL:Parse Notify Payload: NAT_DETECTION_DESTINATION_IP NOTIFY(NAT_DETECTION_DESTINATION_IP)
May 20 14:29:46.253: IKEv2-INTERNAL:Parse Notify Payload: IKEV2_FRAGMENTATION_SUPPORTED NOTIFY(IKEV2_FRAGMENTATION_SUPPORTED)
May 20 14:29:46.253: IKEv2-INTERNAL:Parse Vendor Specific Payload: FRAGMENTATION VID

May 20 14:29:46.253: IKEv2-INTERNAL:(1): Received custom vendor id : CISCO(COPYRIGHT)
May 20 14:29:46.253: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 0 CurState: I_WAIT_INIT Event: EV_RECV_INIT
May 20 14:29:46.254: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):Processing IKE_SA_INIT message
May 20 14:29:46.254: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 0 CurState: I_PROC_INIT Event: EV_CHK4_NOTIFY
May 20 14:29:46.254: IKEv2:(SESSION ID = 52,SA ID = 1):Processing IKE_SA_INIT message
May 20 14:29:46.254: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 0 CurState: I_PROC_INIT Event: EV_VERIFY_MSG
May 20 14:29:46.254: IKEv2:(SESSION ID = 52,SA ID = 1):Verify SA init message
May 20 14:29:46.254: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 0 CurState: I_PROC_INIT Event: EV_PROC_MSG
May 20 14:29:46.254: IKEv2:(SESSION ID = 52,SA ID = 1):Processing IKE_SA_INIT message
May 20 14:29:46.255: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 0 CurState: I_PROC_INIT Event: EV_DETECT_NAT
May 20 14:29:46.255: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):Process NAT discovery notify
May 20 14:29:46.255: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):Processing nat detect src notify
May 20 14:29:46.255: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):Remote address matched
May 20 14:29:46.255: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):Processing nat detect dst notify
May 20 14:29:46.255: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):Local address matched
May 20 14:29:46.255: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):No NAT found
May 20 14:29:46.255: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 0 CurState: I_PROC_INIT Event: EV_CHK_NAT_T
May 20 14:29:46.256: IKEv2:(SESSION ID = 52,SA ID = 1):Checking NAT discovery
May 20 14:29:46.256: IKEv2:(SESSION ID = 52,SA ID = 1):NAT not found
May 20 14:29:46.256: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 0 CurState: I_PROC_INIT Event: EV_CHK_CONFIG_MODE
May 20 14:29:46.256: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 0 CurState: INIT_DONE Event: EV_GEN_DH_SECRET
May 20 14:29:46.256: IKEv2:(SESSION ID = 52,SA ID = 1):[IKEv2 -> Crypto Engine] Computing DH secret key, DH Group 20
May 20 14:29:46.269: IKEv2:(SA ID = 1):[Crypto Engine -> IKEv2] DH key Computation PASSED
May 20 14:29:46.269: IKEv2:(SESSION ID = 52,SA ID = 1):Request queued for computation of DH secret
May 20 14:29:46.270: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 0 CurState: INIT_DONE Event: EV_NO_EVENT
May 20 14:29:46.270: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 0 CurState: INIT_DONE Event: EV_OK_RECD_DH_SECRET_RESP
May 20 14:29:46.270: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):Action: Action_Null
May 20 14:29:46.270: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 0 CurState: INIT_DONE Event: EV_GEN_SKEYID
May 20 14:29:46.270: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):Generate skeyid
May 20 14:29:46.270: IKEv2:(SA ID = 1):[IKEv2 -> Crypto Engine] Calculate SKEYSEED and create rekeyed IKEv2 SA
May 20 14:29:46.271: IKEv2:(SA ID = 1):[Crypto Engine -> IKEv2] SKEYSEED calculation and creation of rekeyed IKEv2 SA PASSED
May 20 14:29:46.271: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 0 CurState: INIT_DONE Event: EV_DONE
May 20 14:29:46.271: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):Cisco DeleteReason Notify is enabled
May 20 14:29:46.271: IKEv2:(SESSION ID = 52,SA ID = 1):Completed SA init exchange
May 20 14:29:46.271: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 0 CurState: INIT_DONE Event: EV_CHK4_ROLE
May 20 14:29:46.271: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 0 CurState: I_BLD_AUTH Event: EV_GET_CONFIG_MODE
May 20 14:29:46.271: IKEv2-INTERNAL:No config data to send to toolkit:
May 20 14:29:46.272: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 0 CurState: I_BLD_AUTH Event: EV_CHK_EAP
May 20 14:29:46.272: IKEv2:(SESSION ID = 52,SA ID = 1):Check for EAP exchange
May 20 14:29:46.272: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 0 CurState: I_BLD_AUTH Event: EV_GEN_AUTH
May 20 14:29:46.272: IKEv2:(SESSION ID = 52,SA ID = 1):Generate my authentication data
May 20 14:29:46.272: IKEv2:(SESSION ID = 52,SA ID = 1):Use preshared key for id B.B.B.148, key len 12
May 20 14:29:46.272: IKEv2:[IKEv2 -> Crypto Engine] Generate IKEv2 authentication data
May 20 14:29:46.272: IKEv2:[Crypto Engine -> IKEv2] IKEv2 authentication data generation PASSED
May 20 14:29:46.273: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 0 CurState: I_BLD_AUTH Event: EV_CHK_AUTH_TYPE
May 20 14:29:46.273: IKEv2:(SESSION ID = 52,SA ID = 1):Get my authentication method
May 20 14:29:46.273: IKEv2:(SESSION ID = 52,SA ID = 1):My authentication method is 'PSK'
May 20 14:29:46.273: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 0 CurState: I_BLD_AUTH Event: EV_OK_AUTH_GEN
May 20 14:29:46.273: IKEv2:(SESSION ID = 52,SA ID = 1):Check for EAP exchange
May 20 14:29:46.273: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 0 CurState: I_BLD_AUTH Event: EV_SEND_AUTH
May 20 14:29:46.273: IKEv2:(SESSION ID = 52,SA ID = 1):Generating IKE_AUTH message
May 20 14:29:46.273: IKEv2-INTERNAL:Construct Vendor Specific Payload: CISCO-GRANITE
May 20 14:29:46.274: IKEv2:(SESSION ID = 52,SA ID = 1):Constructing IDi payload: 'B.B.B.148' of type 'IPv4 address'
May 20 14:29:46.274: IKEv2:(SESSION ID = 52,SA ID = 1):ESP Proposal: 1, SPI size: 4 (IPSec negotiation),
Num. transforms: 2
   AES-GCM   Don't use ESN
May 20 14:29:46.274: IKEv2-INTERNAL:Construct Notify Payload: INITIAL_CONTACT
May 20 14:29:46.274: IKEv2-INTERNAL:Construct Notify Payload: SET_WINDOW_SIZE
May 20 14:29:46.274: IKEv2-INTERNAL:Construct Notify Payload: ESP_TFC_NO_SUPPORT
May 20 14:29:46.274: IKEv2-INTERNAL:Construct Notify Payload: NON_FIRST_FRAGS
May 20 14:29:46.274: IKEv2:(SESSION ID = 52,SA ID = 1):Building packet for encryption.
Payload contents:
 VID IDi AUTH SA TSi TSr NOTIFY(INITIAL_CONTACT) NOTIFY(SET_WINDOW_SIZE) NOTIFY(ESP_TFC_NO_SUPPORT) NOTIFY(NON_FIRST_FRAGS)

May 20 14:29:46.275: IKEv2:(SESSION ID = 52,SA ID = 1):Sending Packet [To Y.Y.Y.151:500/From B.B.B.148:500/VRF i0:f0]
Initiator SPI : 41C05D4DFB445428 - Responder SPI : EA777AB94F2C822A Message id: 1
IKEv2 IKE_AUTH Exchange REQUEST
Payload contents:
 ENCR

May 20 14:29:46.276: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 1 CurState: I_WAIT_AUTH Event: EV_NO_EVENT
May 20 14:29:46.305: IKEv2-INTERNAL:Got a packet from dispatcher

May 20 14:29:46.305: IKEv2-INTERNAL:Processing an item off the pak queue


May 20 14:29:46.306: IKEv2:(SESSION ID = 52,SA ID = 1):Received Packet [From Y.Y.Y.151:500/To B.B.B.148:500/VRF i0:f0]
Initiator SPI : 41C05D4DFB445428 - Responder SPI : EA777AB94F2C822A Message id: 1
IKEv2 IKE_AUTH Exchange RESPONSE
Payload contents:

May 20 14:29:46.306: IKEv2-INTERNAL:Parse Vendor Specific Payload: (CUSTOM) VID IDr AUTH
May 20 14:29:46.306: IKEv2-INTERNAL:Parse Notify Payload: TS_UNACCEPTABLE NOTIFY(TS_UNACCEPTABLE)

May 20 14:29:46.307: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 1 CurState: I_WAIT_AUTH Event: EV_RECV_AUTH
May 20 14:29:46.307: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):Action: Action_Null
May 20 14:29:46.307: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 1 CurState: I_PROC_AUTH Event: EV_CHK4_NOTIFY
May 20 14:29:46.307: IKEv2:(SESSION ID = 52,SA ID = 1):Process auth response notify
May 20 14:29:46.308: IKEv2-ERROR:(SESSION ID = 52,SA ID = 1):
May 20 14:29:46.308: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 1 CurState: I_PROC_AUTH Event: EV_PROC_MSG
May 20 14:29:46.308: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 1 CurState: I_PROC_AUTH Event: EV_CHK_IF_PEER_CERT_NEEDS_TO_BE_FETCHED_FOR_PROF_SEL
May 20 14:29:46.308: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 1 CurState: I_PROC_AUTH Event: EV_GET_POLICY_BY_PEERID
May 20 14:29:46.309: IKEv2:(SESSION ID = 52,SA ID = 1):Searching policy based on peer's identity 'Y.Y.Y.151' of type 'IPv4 address'
May 20 14:29:46.309: IKEv2:Searching Policy with fvrf 0, local address B.B.B.148
May 20 14:29:46.309: IKEv2:Found Policy 'POLICY'
May 20 14:29:46.309: IKEv2-INTERNAL:Adding Proposal PROPOSAL to toolkit policy
May 20 14:29:46.309: IKEv2-INTERNAL:(SA ID = 1):Using IKEv2 profile 'PROFILE'
May 20 14:29:46.309: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 1 CurState: I_PROC_AUTH Event: EV_VERIFY_POLICY_BY_PEERID
May 20 14:29:46.310: IKEv2:(SESSION ID = 52,SA ID = 1):Verify peer's policy
May 20 14:29:46.310: IKEv2:(SESSION ID = 52,SA ID = 1):Peer's policy verified
May 20 14:29:46.310: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 1 CurState: I_PROC_AUTH Event: EV_CHK_AUTH_TYPE
May 20 14:29:46.310: IKEv2:(SESSION ID = 52,SA ID = 1):Get peer's authentication method
May 20 14:29:46.310: IKEv2:(SESSION ID = 52,SA ID = 1):Peer's authentication method is 'PSK'
May 20 14:29:46.310: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 1 CurState: I_PROC_AUTH Event: EV_GET_PRESHR_KEY
May 20 14:29:46.311: IKEv2:(SESSION ID = 52,SA ID = 1):Get peer's preshared key for Y.Y.Y.151
May 20 14:29:46.311: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 1 CurState: I_PROC_AUTH Event: EV_VERIFY_AUTH
May 20 14:29:46.311: IKEv2:(SESSION ID = 52,SA ID = 1):Verify peer's authentication data
May 20 14:29:46.311: IKEv2:(SESSION ID = 52,SA ID = 1):Use preshared key for id Y.Y.Y.151, key len 12
May 20 14:29:46.311: IKEv2:[IKEv2 -> Crypto Engine] Generate IKEv2 authentication data
May 20 14:29:46.311: IKEv2:[Crypto Engine -> IKEv2] IKEv2 authentication data generation PASSED
May 20 14:29:46.311: IKEv2:(SESSION ID = 52,SA ID = 1):Verification of peer's authenctication data PASSED
May 20 14:29:46.312: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 1 CurState: I_PROC_AUTH Event: EV_CHK_EAP
May 20 14:29:46.312: IKEv2:(SESSION ID = 52,SA ID = 1):Check for EAP exchange
May 20 14:29:46.312: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 1 CurState: I_PROC_AUTH Event: EV_NOTIFY_AUTH_DONE
May 20 14:29:46.312: IKEv2-INTERNAL:AAA group authorization is not configured
May 20 14:29:46.312: IKEv2-INTERNAL:AAA user authorization is not configured
May 20 14:29:46.312: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 1 CurState: I_PROC_AUTH Event: EV_CHK_CONFIG_MODE
May 20 14:29:46.312: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 1 CurState: I_PROC_AUTH Event: EV_CHK_GKM
May 20 14:29:46.313: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 1 CurState: I_PROC_AUTH Event: EV_CHK4_IC
May 20 14:29:46.313: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 1 CurState: I_PROC_AUTH Event: EV_CHK_IKE_ONLY
May 20 14:29:46.313: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 1 CurState: AUTH_DONE Event: EV_OK
May 20 14:29:46.313: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):Action: Action_Null
May 20 14:29:46.313: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 1 CurState: AUTH_DONE Event: EV_CHK_GKM_RETRANS
May 20 14:29:46.313: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 1 CurState: AUTH_DONE Event: EV_PKI_SESH_CLOSE
May 20 14:29:46.314: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):Closing the PKI session
May 20 14:29:46.314: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 1 CurState: AUTH_DONE Event: EV_UPDATE_CAC_STATS
May 20 14:29:46.314: IKEv2-INTERNAL:New ikev2 sa request activated
May 20 14:29:46.314: IKEv2-INTERNAL:Decrement count for outgoing negotiating
May 20 14:29:46.314: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 1 CurState: AUTH_DONE Event: EV_INSERT_IKE
May 20 14:29:46.314: IKEv2:(SESSION ID = 52,SA ID = 1):IKEV2 SA created; inserting SA into database. SA lifetime timer (28800 sec) started
May 20 14:29:46.314: IKEv2:(SESSION ID = 52,SA ID = 1):Session with IKE ID PAIR (Y.Y.Y.151, B.B.B.148) is UP
May 20 14:29:46.315: IKEv2:IKEv2 MIB tunnel started, tunnel index 1
May 20 14:29:46.315: IKEv2-INTERNAL:Store mib index ikev2 1, platform 7015
May 20 14:29:46.315: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 1 CurState: AUTH_DONE Event: EV_CHK_COOP
May 20 14:29:46.315: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 1 CurState: AUTH_DONE Event: EV_CHECK_DUPE
May 20 14:29:46.315: IKEv2:(SESSION ID = 52,SA ID = 1):Checking for duplicate IKEv2 SA
May 20 14:29:46.315: IKEv2:(SESSION ID = 52,SA ID = 1):No duplicate IKEv2 SA found
May 20 14:29:46.315: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 1 CurState: AUTH_DONE Event: EV_CHK4_ROLE
May 20 14:29:46.316: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 1 CurState: AUTH_DONE Event: EV_CHK_GKM
May 20 14:29:46.316: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 1 CurState: AUTH_DONE Event: EV_CHK_DIKE
May 20 14:29:46.316: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 1 CurState: READY Event: EV_CHK_IKE_ONLY
May 20 14:29:46.316: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 1 CurState: READY Event: EV_DEL_SA
May 20 14:29:46.316: IKEv2:(SESSION ID = 52,SA ID = 1):Queuing IKE SA delete request reason: unknown
May 20 14:29:46.317: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 1 CurState: READY Event: EV_FREE_NEG
May 20 14:29:46.317: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):Deleting negotiation context for my message ID: 0x1
May 20 14:29:46.317: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 1 CurState: READY Event: EV_IPSEC_DEL
May 20 14:29:46.318: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):Action: Action_Null
May 20 14:29:46.318: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 1 CurState: INFO_I_BLD_INFO Event: EV_SND_IPSEC_DEL
May 20 14:29:46.318: IKEv2:(SESSION ID = 52,SA ID = 1):Sending DELETE INFO message for IPsec SA [SPI: 0x4219A91D]
May 20 14:29:46.318: IKEv2:(SESSION ID = 52,SA ID = 1):Building packet for encryption.
Payload contents:
 DELETE
May 20 14:29:46.318: IKEv2:(SESSION ID = 52,SA ID = 1):Checking if request will fit in peer window

May 20 14:29:46.319: IKEv2:(SESSION ID = 52,SA ID = 1):Sending Packet [To Y.Y.Y.151:500/From B.B.B.148:500/VRF i0:f0]
Initiator SPI : 41C05D4DFB445428 - Responder SPI : EA777AB94F2C822A Message id: 2
IKEv2 INFORMATIONAL Exchange REQUEST
Payload contents:
 ENCR

May 20 14:29:46.319: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 2 CurState: INFO_I_BLD_INFO Event: EV_CHK4_ACTIVE_IPSEC_SA
May 20 14:29:46.319: IKEv2:(SESSION ID = 52,SA ID = 1):Check for existing IPSEC SA
May 20 14:29:46.320: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 2 CurState: INFO_I_BLD_INFO Event: EV_TERM_CONN
May 20 14:29:46.320: IKEv2:(SESSION ID = 52,SA ID = 1):Delete all IKE SAs
May 20 14:29:46.320: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 2 CurState: INFO_I_WAIT Event: EV_NO_EVENT
May 20 14:29:46.320: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 2 CurState: READY Event: EV_DELETE
May 20 14:29:46.320: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):Action: Action_Null
May 20 14:29:46.320: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 2 CurState: DELETE Event: EV_DELETE
May 20 14:29:46.320: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):Action: Action_Null
May 20 14:29:46.320: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 2 CurState: INFO_I_BLD_INFO Event: EV_SND_SA_DEL
May 20 14:29:46.321: IKEv2:(SESSION ID = 52,SA ID = 1):Sending DELETE INFO message for IKEv2 SA [ISPI: 0x41C05D4DFB445428 RSPI: 0xEA777AB94F2C822A]
May 20 14:29:46.321: IKEv2:(SESSION ID = 52,SA ID = 1):Building packet for encryption.
Payload contents:
 DELETE
May 20 14:29:46.321: IKEv2:(SESSION ID = 52,SA ID = 1):Checking if request will fit in peer window
May 20 14:29:46.321: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):No room in peer window request is throttled: Current Req = 3 Next Req = 2
May 20 14:29:46.321: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 2 CurState: INFO_I_BLD_INFO Event: EV_CHK4_ACTIVE_SA
May 20 14:29:46.322: IKEv2:(SESSION ID = 52,SA ID = 1):Check for existing active SA
May 20 14:29:46.322: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 2 CurState: INFO_I_BLD_INFO Event: EV_STOP_ACCT
May 20 14:29:46.322: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 2 CurState: INFO_I_BLD_INFO Event: EV_TERM_CONN
May 20 14:29:46.322: IKEv2:(SESSION ID = 52,SA ID = 1):Delete all IKE SAs
May 20 14:29:46.322: IKEv2-INTERNAL:(SESSION ID = 52,SA ID = 1):SM Trace-> SA: I_SPI=41C05D4DFB445428 R_SPI=EA777AB94F2C822A (I) MsgID = 2 CurState: INFO_I_WAIT Event: EV_NO_EVENT
May 20 14:29:46.348: IKEv2-INTERNAL:Got a packet from dispatcher