Aug 27 04:43:34 ike_send_packet: Start, send SA = { 35a83ab2 5cf575b3 - 2cda7b32 f237f6db}, nego = 10, src = x.x.x.x:500, dst = x.x.x.x:500, routing table id = 0
Aug 27 04:43:34 ike_delete_negotiation: Start, SA = { 35a83ab2 5cf575b3 - 2cda7b32 f237f6db}, nego = 10
Aug 27 04:43:34 ike_free_negotiation_info: Start, nego = 10
Aug 27 04:43:34 ike_free_negotiation: Start, nego = 10
Aug 27 04:43:35 ike_remove_callback: Start, delete SA = { 35a83ab2 5cf575b3 - 2cda7b32 f237f6db}, nego = 1
Aug 27 04:43:35 ike_delete_negotiation: Start, SA = { 35a83ab2 5cf575b3 - 2cda7b32 f237f6db}, nego = 1
Aug 27 04:43:35 ike_free_negotiation_qm: Start, nego = 1
Aug 27 04:43:35 ike_free_negotiation: Start, nego = 1
Aug 27 04:43:35 ike_free_id_payload: Start, id type = 4
Aug 27 04:43:35 ike_free_id_payload: Start, id type = 4
Aug 27 04:43:54 ike_remove_callback: Start, delete SA = { 35a83ab2 5cf575b3 - 2cda7b32 f237f6db}, nego = 9
Aug 27 04:43:54 ike_delete_negotiation: Start, SA = { 35a83ab2 5cf575b3 - 2cda7b32 f237f6db}, nego = 9
Aug 27 04:43:54 ike_free_negotiation_qm: Start, nego = 9
Aug 27 04:43:54 ike_free_negotiation: Start, nego = 9
Aug 27 04:43:54 ike_free_id_payload: Start, id type = 4
Aug 27 04:43:54 ike_free_id_payload: Start, id type = 4
Aug 27 04:43:54 ike_get_sa: Start, SA = { 35a83ab2 5cf575b3 - 2cda7b32 f237f6db } / c810c5ff, remote = x.x.x.x:500
Aug 27 04:43:54 ike_sa_find: Found SA = { 35a83ab2 5cf575b3 - 2cda7b32 f237f6db }
Aug 27 04:43:54 ike_alloc_negotiation: Start, SA = { 35a83ab2 5cf575b3 - 2cda7b32 f237f6db}
Aug 27 04:43:54 ike_init_qm_negotiation: Start, initiator = 0, message_id = c810c5ff
Aug 27 04:43:54 ike_decode_packet: Start
Aug 27 04:43:54 ike_decode_packet: Start, SA = { 35a83ab2 5cf575b3 - 2cda7b32 f237f6db} / c810c5ff, nego = 1
Aug 27 04:43:54 ike_decode_payload_sa: Start
Aug 27 04:43:54 ike_decode_payload_t: Start, # trans = 1
Aug 27 04:43:54 ike_decode_payload_t: Start, # trans = 1
Aug 27 04:43:54 ike_st_i_encrypt: Check that packet was encrypted succeeded
Aug 27 04:43:54 ike_st_i_qm_hash_1: Start, hash[0..20] = a6acf7ad d6f1b065 ...
Aug 27 04:43:54 ike_st_i_qm_nonce: Nonce[0..20] = b1b59a73 a948f810 ...
Aug 27 04:43:54 ike_st_i_qm_ke: Ke[0..128] = 8f7f34f3 4a1d36bc ...
Aug 27 04:43:54 ike_st_i_qm_sa_proposals: Start
Aug 27 04:43:54 Phase-2 sa_cfg lookup with local_id=ipv4_subnet(any:0,[0..7]=192.168.200.0/24), remote_id=ipv4_subnet(any:0,[0..7]=192.168.2.0/24)
Aug 27 04:43:54 Phase-2 remote access sa_cfg lookup with local_id=ipv4_subnet(any:0,[0..7]=192.168.200.0/24), remote_id=ipv4_subnet(any:0,[0..7]=192.168.2.0/24)
Aug 27 04:43:54 KMD_INTERNAL_ERROR: Phase2 finish: No sa_cfg found!
Aug 27 04:43:54 KMD_PM_P2_POLICY_LOOKUP_FAILURE: Policy lookup for Phase-2 [responder] failed for p1_local=ipv4(udp:0,[0..3]=x.x.x.x) p1_remote=ipv4(udp:500,[0..3]=x.x.x.x) p2_local=ipv4_subnet(any:0,[0..7]=192.168.200.0/24) p2_remote=ipv4_subnet(any:0,[0..7]=192.168.2.0/24)
Aug 27 04:43:54 KMD_PM_P2_POLICY_LOOKUP_FAILURE: Policy lookup for Phase-2 [responder] failed for p1_local=ipv4(udp:0,[0..3]=x.x.x.x) p1_remote=ipv4(udp:500,[0..3]=x.x.x.x) p2_local=ipv4_subnet(any:0,[0..7]=192.168.200.0/24) p2_remote=ipv4_subnet(any:0,[0..7]=192.168.2.0/24)
Aug 27 04:43:54 ike_qm_sa_reply: Start
Aug 27 04:43:54 x.x.x.x:500 (Responder) <-> x.x.x.x:500 { 35a83ab2 5cf575b3 - 2cda7b32 f237f6db [1] / 0xc810c5ff } QM; Error = No proposal chosen (14)
Aug 27 04:43:54 ike_alloc_negotiation: Start, SA = { 35a83ab2 5cf575b3 - 2cda7b32 f237f6db}
Aug 27 04:43:54 ike_encode_packet: Start, SA = { 0x35a83ab2 5cf575b3 - 2cda7b32 f237f6db } / 97921c54, nego = 9
Aug 27 04:43:54 ike_send_packet: Start, send SA = { 35a83ab2 5cf575b3 - 2cda7b32 f237f6db}, nego = 9, src = x.x.x.x:500, dst = x.x.x.x:500, routing table id = 0
Aug 27 04:43:54 ike_delete_negotiation: Start, SA = { 35a83ab2 5cf575b3 - 2cda7b32 f237f6db}, nego = 9
Aug 27 04:43:54 ike_free_negotiation_info: Start, nego = 9
Aug 27 04:43:54 ike_free_negotiation: Start, nego = 9
Aug 27 04:44:14 ike_remove_callback: Start, delete SA = { 35a83ab2 5cf575b3 - 2cda7b32 f237f6db}, nego = 2
Aug 27 04:44:14 ike_delete_negotiation: Start, SA = { 35a83ab2 5cf575b3 - 2cda7b32 f237f6db}, nego = 2
Aug 27 04:44:14 ike_free_negotiation_qm: Start, nego = 2
Aug 27 04:44:14 ike_free_negotiation: Start, nego = 2
Aug 27 04:44:14 ike_free_id_payload: Start, id type = 4
Aug 27 04:44:14 ike_free_id_payload: Start, id type = 4
Aug 27 04:44:15 ike_get_sa: Start, SA = { 35a83ab2 5cf575b3 - 2cda7b32 f237f6db } / c2dc2bc0, remote = x.x.x.x:500
Aug 27 04:44:15 ike_sa_find: Found SA = { 35a83ab2 5cf575b3 - 2cda7b32 f237f6db }
Aug 27 04:44:15 ike_alloc_negotiation: Start, SA = { 35a83ab2 5cf575b3 - 2cda7b32 f237f6db}
Aug 27 04:44:15 ike_init_qm_negotiation: Start, initiator = 0, message_id = c2dc2bc0
Aug 27 04:44:15 ike_decode_packet: Start
Aug 27 04:44:15 ike_decode_packet: Start, SA = { 35a83ab2 5cf575b3 - 2cda7b32 f237f6db} / c2dc2bc0, nego = 2
Aug 27 04:44:15 ike_decode_payload_sa: Start
Aug 27 04:44:15 ike_decode_payload_t: Start, # trans = 1
Aug 27 04:44:15 ike_decode_payload_t: Start, # trans = 1
Aug 27 04:44:15 ike_st_i_encrypt: Check that packet was encrypted succeeded
Aug 27 04:44:15 ike_st_i_qm_hash_1: Start, hash[0..20] = cb23fb30 ee2d9e25 ...
Aug 27 04:44:15 ike_st_i_qm_nonce: Nonce[0..20] = 1d776afc 8ef1ce4d ...
Aug 27 04:44:15 ike_st_i_qm_ke: Ke[0..128] = 55c3be9d c0e682bf ...
Aug 27 04:44:15 ike_st_i_qm_sa_proposals: Start
Aug 27 04:44:15 Phase-2 sa_cfg lookup with local_id=ipv4_subnet(any:0,[0..7]=192.168.200.0/24), remote_id=ipv4_subnet(any:0,[0..7]=192.168.2.0/24)
Aug 27 04:44:15 Phase-2 remote access sa_cfg lookup with local_id=ipv4_subnet(any:0,[0..7]=192.168.200.0/24), remote_id=ipv4_subnet(any:0,[0..7]=192.168.2.0/24)
Aug 27 04:44:15 KMD_INTERNAL_ERROR: Phase2 finish: No sa_cfg found!
Aug 27 04:44:15 KMD_PM_P2_POLICY_LOOKUP_FAILURE: Policy lookup for Phase-2 [responder] failed for p1_local=ipv4(udp:0,[0..3]=x.x.x.x) p1_remote=ipv4(udp:500,[0..3]=x.x.x.x) p2_local=ipv4_subnet(any:0,[0..7]=192.168.200.0/24) p2_remote=ipv4_subnet(any:0,[0..7]=192.168.2.0/24)
Aug 27 04:44:15 KMD_PM_P2_POLICY_LOOKUP_FAILURE: Policy lookup for Phase-2 [responder] failed for p1_local=ipv4(udp:0,[0..3]=x.x.x.x) p1_remote=ipv4(udp:500,[0..3]=x.x.x.x) p2_local=ipv4_subnet(any:0,[0..7]=192.168.200.0/24) p2_remote=ipv4_subnet(any:0,[0..7]=192.168.2.0/24)
Aug 27 04:44:15 ike_qm_sa_reply: Start
Aug 27 04:44:15 x.x.x.x:500 (Responder) <-> x.x.x.x:500 { 35a83ab2 5cf575b3 - 2cda7b32 f237f6db [2] / 0xc2dc2bc0 } QM; Error = No proposal chosen (14)
Aug 27 04:44:15 ike_alloc_negotiation: Start, SA = { 35a83ab2 5cf575b3 - 2cda7b32 f237f6db}
Aug 27 04:44:15 ike_encode_packet: Start, SA = { 0x35a83ab2 5cf575b3 - 2cda7b32 f237f6db } / c448c089, nego = 9
Aug 27 04:44:15 ike_send_packet: Start, send SA = { 35a83ab2 5cf575b3 - 2cda7b32 f237f6db}, nego = 9, src = x.x.x.x:500, dst = x.x.x.x:500, routing table id = 0
Aug 27 04:44:15 ike_delete_negotiation: Start, SA = { 35a83ab2 5cf575b3 - 2cda7b32 f237f6db}, nego = 9
Aug 27 04:44:15 ike_free_negotiation_info: Start, nego = 9
Aug 27 04:44:15 ike_free_negotiation: Start, nego = 9
Aug 27 04:44:34 ike_remove_callback: Start, delete SA = { 35a83ab2 5cf575b3 - 2cda7b32 f237f6db}, nego = 3
Aug 27 04:44:34 ike_delete_negotiation: Start, SA = { 35a83ab2 5cf575b3 - 2cda7b32 f237f6db}, nego = 3
Aug 27 04:44:34 ike_free_negotiation_qm: Start, nego = 3
Aug 27 04:44:34 ike_free_negotiation: Start, nego = 3
Aug 27 04:44:34 ike_free_id_payload: Start, id type = 4
Aug 27 04:44:34 ike_free_id_payload: Start, id type = 4
Aug 27 04:44:35 ike_get_sa: Start, SA = { 35a83ab2 5cf575b3 - 2cda7b32 f237f6db } / ae04f7ba, remote = x.x.x.x:500
Aug 27 04:44:35 ike_sa_find: Found SA = { 35a83ab2 5cf575b3 - 2cda7b32 f237f6db }
Aug 27 04:44:35 ike_alloc_negotiation: Start, SA = { 35a83ab2 5cf575b3 - 2cda7b32 f237f6db}
Aug 27 04:44:35 ike_init_qm_negotiation: Start, initiator = 0, message_id = ae04f7ba
Aug 27 04:44:35 ike_decode_packet: Start
Aug 27 04:44:35 ike_decode_packet: Start, SA = { 35a83ab2 5cf575b3 - 2cda7b32 f237f6db} / ae04f7ba, nego = 3
Aug 27 04:44:35 ike_decode_payload_sa: Start
Aug 27 04:44:35 ike_decode_payload_t: Start, # trans = 1
Aug 27 04:44:35 ike_decode_payload_t: Start, # trans = 1
Aug 27 04:44:35 ike_st_i_encrypt: Check that packet was encrypted succeeded
Aug 27 04:44:35 ike_st_i_qm_hash_1: Start, hash[0..20] = 82f1f9dd 0d2b5286 ...
Aug 27 04:44:35 ike_st_i_qm_nonce: Nonce[0..20] = d1031d32 9bb57c9c ...
Aug 27 04:44:35 ike_st_i_qm_ke: Ke[0..128] = 0f95d1bc 9ef1ac9a ...
Aug 27 04:44:35 ike_st_i_qm_sa_proposals: Start
Aug 27 04:44:35 Phase-2 sa_cfg lookup with local_id=ipv4_subnet(any:0,[0..7]=192.168.200.0/24), remote_id=ipv4_subnet(any:0,[0..7]=192.168.2.0/24)
Aug 27 04:44:35 Phase-2 remote access sa_cfg lookup with local_id=ipv4_subnet(any:0,[0..7]=192.168.200.0/24), remote_id=ipv4_subnet(any:0,[0..7]=192.168.2.0/24)
Aug 27 04:44:35 KMD_INTERNAL_ERROR: Phase2 finish: No sa_cfg found!
Aug 27 04:44:35 KMD_PM_P2_POLICY_LOOKUP_FAILURE: Policy lookup for Phase-2 [responder] failed for p1_local=ipv4(udp:0,[0..3]=x.x.x.x) p1_remote=ipv4(udp:500,[0..3]=x.x.x.x) p2_local=ipv4_subnet(any:0,[0..7]=192.168.200.0/24) p2_remote=ipv4_subnet(any:0,[0..7]=192.168.2.0/24)
Aug 27 04:44:35 KMD_PM_P2_POLICY_LOOKUP_FAILURE: Policy lookup for Phase-2 [responder] failed for p1_local=ipv4(udp:0,[0..3]=x.x.x.x) p1_remote=ipv4(udp:500,[0..3]=x.x.x.x) p2_local=ipv4_subnet(any:0,[0..7]=192.168.200.0/24) p2_remote=ipv4_subnet(any:0,[0..7]=192.168.2.0/24)
Aug 27 04:44:35 ike_qm_sa_reply: Start
Aug 27 04:44:35 x.x.x.x:500 (Responder) <-> x.x.x.x:500 { 35a83ab2 5cf575b3 - 2cda7b32 f237f6db [3] / 0xae04f7ba } QM; Error = No proposal chosen (14)
Aug 27 04:44:35 ike_alloc_negotiation: Start, SA = { 35a83ab2 5cf575b3 - 2cda7b32 f237f6db}
Aug 27 04:44:35 ike_encode_packet: Start, SA = { 0x35a83ab2 5cf575b3 - 2cda7b32 f237f6db } / ece0f821, nego = 9
Aug 27 04:44:35 ike_send_packet: Start, send SA = { 35a83ab2 5cf575b3 - 2cda7b32 f237f6db}, nego = 9, src = x.x.x.x:500, dst = x.x.x.x:500, routing table id = 0
Aug 27 04:44:35 ike_delete_negotiation: Start, SA = { 35a83ab2 5cf575b3 - 2cda7b32 f237f6db}, nego = 9
Aug 27 04:44:35 ike_free_negotiation_info: Start, nego = 9
Aug 27 04:44:35 ike_free_negotiation: Start, nego = 9