Aug 22 05:43:52 05:43:52.770838:CID-0:RT:<192.168.1.2/4168->8.8.8.8/1;1> matched filter MatchTraffic: Aug 22 05:43:52 05:43:52.770838:CID-0:RT:packet [60] ipid = 17332, @0x4365c41a Aug 22 05:43:52 05:43:52.770838:CID-0:RT:---- flow_process_pkt: (thd 1): flow_ctxt type 15, common flag 0x0, mbuf 0x4365c200, rtbl_idx = 0 Aug 22 05:43:52 05:43:52.770838:CID-0:RT: flow process pak fast ifl 82 in_ifp vlan.0 Aug 22 05:43:52 05:43:52.770838:CID-0:RT: vlan.0:192.168.1.2->8.8.8.8, icmp, (8/0) Aug 22 05:43:52 05:43:52.770838:CID-0:RT: find flow: table 0x4f9a9078, hash 41146(0xffff), sa 192.168.1.2, da 8.8.8.8, sp 4168, dp 1, proto 1, tok 6 Aug 22 05:43:52 05:43:52.770838:CID-0:RT: no session found, start first path. in_tunnel - 0x0, from_cp_flag - 0 Aug 22 05:43:52 05:43:52.770838:CID-0:RT: flow_first_create_session Aug 22 05:43:52 05:43:52.770838:CID-0:RT: flow_first_in_dst_nat: in , out dst_adr 8.8.8.8, sp 4168, dp 1 Aug 22 05:43:52 05:43:52.770838:CID-0:RT: chose interface vlan.0 as incoming nat if. Aug 22 05:43:52 05:43:52.770838:CID-0:RT:nat_get_mapped_or_incoming_dip: no rule configured with lsys id 0 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:nat_get_mapped_or_incoming_dip: diff 0, calls 0 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:nat_dst_xlate_ip_port: src-ip 192.168.1.2, dst-ip 8.8.8.8, dst_port 1 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:nat_dst_rule_match: lsys 0, src-ip 192.168.1.2, dst-ip 8.8.8.8, dst-port 1 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:nat_dst_xlate_ip_port: no rule found... Aug 22 05:43:52 05:43:52.770838:CID-0:RT:flow_first_rule_dst_xlate: DST no-xlate: 0.0.0.0(0) to 8.8.8.8(1) Aug 22 05:43:52 05:43:52.770838:CID-0:RT:flow_first_routing: vr_id 0, call flow_route_lookup(): src_ip 192.168.1.2, x_dst_ip 8.8.8.8, in ifp vlan.0, out ifp N/A sp 4168, dp 1, ip_proto 1, tos 0 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:Doing DESTINATION addr route-lookup Aug 22 05:43:52 05:43:52.770838:CID-0:RT: routed (x_dst_ip 8.8.8.8) from trust (vlan.0 in 0) to pp0.0, Next-hop: 8.8.8.8 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:flow_first_policy_search: policy search from zone trust-> zone untrust (0x0,0x10480001,0x1) Aug 22 05:43:52 05:43:52.770838:CID-0:RT:Policy lkup: vsys 0 zone(6:trust) -> zone(7:untrust) scope:0 Aug 22 05:43:52 05:43:52.770838:CID-0:RT: 192.168.1.2/2048 -> 8.8.8.8/15635 proto 1 Aug 22 05:43:52 05:43:52.770838:CID-0:RT: app 0, timeout 60s, curr ageout 60s Aug 22 05:43:52 05:43:52.770838:CID-0:RT: permitted by policy default-permit(6) Aug 22 05:43:52 05:43:52.770838:CID-0:RT: packet passed, Permitted by policy. Aug 22 05:43:52 05:43:52.770838:CID-0:RT:nat_get_rule_number: type 2, rule counter 0 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:nat_get_hipped_ip: no rule configured Aug 22 05:43:52 05:43:52.770838:CID-0:RT:flow_first_src_xlate: nat_src_xlated: False, nat_src_xlate_failed: False Aug 22 05:43:52 05:43:52.770838:CID-0:RT:nat xlate nsp 192.168.1.2/4168 -> 8.8.8.8/1 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:nat xlate nsp2 8.8.8.8/1 -> 0.0.0.0/4168 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:nat_src_rule_match: lsys 0, src-ip 192.168.1.2, dst-ip 8.8.8.8, dst-port 1 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:nat_proc_sorted_lists: src_min 1073741825, dst_min 1073741825 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:nat_rule_match_ctx_n_port: protocol 1 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:nat_rule_match_ctx_n_port: ctx-type 4, from-id 6, to-id 7 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:nat_src_rule_match: src nat rule found: id 1, pos-id 1, pool id 2 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:flow_first_src_xlate: src nat returns status: 1, rule/pool id: 1/2, pst_nat: False. Aug 22 05:43:52 05:43:52.770838:CID-0:RT:nat xlate nsp 192.168.1.2/4168 -> 8.8.8.8/1 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:nat xlate nsp2 8.8.8.8/1 -> 0.0.0.0/4168 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:nat_get_dip_by_host: pst_nat = False, lsys_id = 0, platform = 1, dip_id = 0x2,dst_port = 1, ptr = 0x4fa2a290, sess_id = 0x0 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:nat_get_dip_by_host:src_ip 192.168.1.2 dst_ip 8.8.8.8 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:nat_get_local_dip_by_host: src nat ip/port is allocated locally. src_port 4168 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:nat_get_dip_by_host_cp: lsys id: 0, src_port 0, dip id: 0x2 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:nat_get_dip_by_host_cp:src_ip 192.168.1.2 dst_ip 8.8.8.8 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:nat_get_dip_by_host_cp: get xlated ip/port (115.64.136.32/17086), xlation flag (1), xlation pool id (0x2) from JSF Aug 22 05:43:52 05:43:52.770838:CID-0:RT: dip id = 2/0, 192.168.1.2/4168->115.64.136.32/17086 protocol 58 Aug 22 05:43:52 05:43:52.770838:CID-0:RT: choose interface pp0.0 as outgoing phy if Aug 22 05:43:52 05:43:52.770838:CID-0:RT:nat_get_mipped_ip: no rule configured for lsys 0 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:is_loop_pak: No loop: on ifp: pp0.0, addr: 8.8.8.8, rtt_idx:0 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:-jsf : Alloc sess plugin info for session 237839 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:[JSF]Normal interest check. regd plugins 19, enabled impl mask 0x0 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:-jsf int check: plugin id 2, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:-jsf int check: plugin id 3, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:-jsf int check: plugin id 5, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:-jsf int check: plugin id 6, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:-jsf int check: plugin id 7, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:-jsf int check: plugin id 8, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:-jsf int check: plugin id 12, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:-jsf int check: plugin id 15, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:+++++++++++jsf_test_plugin_data_evh: 3 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:-jsf int check: plugin id 16, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:-jsf int check: plugin id 22, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:-jsf int check: plugin id 23, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:-jsf int check: plugin id 26, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:-jsf int check: plugin id 27, svc_req 0x0, impl mask 0x0. rc 2 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:-jsf int check: plugin id 28, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:[JSF]Plugins(0x0, count 0) enabled for session = 0, impli mask(0x0), post_nat cnt 237839 svc req(0x0) Aug 22 05:43:52 05:43:52.770838:CID-0:RT:-jsf : no plugin interested for session 237839, free sess plugin info Aug 22 05:43:52 05:43:52.770838:CID-0:RT:flow_first_service_lookup(): natp(0x59ef9570): app_id, 0(0). Aug 22 05:43:52 05:43:52.770838:CID-0:RT: service lookup identified service 0. Aug 22 05:43:52 05:43:52.770838:CID-0:RT: flow_first_final_check: in , out Aug 22 05:43:52 05:43:52.770838:CID-0:RT:flow_first_final_check: flow_set_xlate_vector. Aug 22 05:43:52 05:43:52.770838:CID-0:RT:flow_first_complete_session, pak_ptr: 0x4fa2a290, nsp: 0x59ef9570, in_tunnel: 0x0 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:construct v4 vector for nsp2 Aug 22 05:43:52 05:43:52.770838:CID-0:RT: existing vector list 0x1200-0x48cf5910. Aug 22 05:43:52 05:43:52.770838:CID-0:RT: Session (id:237839) created for first pak 1200 Aug 22 05:43:52 05:43:52.770838:CID-0:RT: flow_first_install_session======> 0x59ef9570 Aug 22 05:43:52 05:43:52.770838:CID-0:RT: nsp 0x59ef9570, nsp2 0x59ef95f0 Aug 22 05:43:52 05:43:52.770838:CID-0:RT: make_nsp_ready_no_resolve() Aug 22 05:43:52 05:43:52.770838:CID-0:RT: route lookup: dest-ip 192.168.1.2 orig ifp vlan.0 output_ifp vlan.0 orig-zone 6 out-zone 6 vsd 0 Aug 22 05:43:52 05:43:52.770838:CID-0:RT: route to 192.168.1.2 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:no need update ha Aug 22 05:43:52 05:43:52.770838:CID-0:RT:Installing c2s NP session wing Aug 22 05:43:52 05:43:52.770838:CID-0:RT:Installing s2c NP session wing Aug 22 05:43:52 05:43:52.770838:CID-0:RT: flow got session. Aug 22 05:43:52 05:43:52.770838:CID-0:RT: flow session id 237839 Aug 22 05:43:52 05:43:52.770838:CID-0:RT: vector bits 0x1200 vector 0x48cf5910 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:flow_xlate_pak Aug 22 05:43:52 05:43:52.770838:CID-0:RT:flow_handle_icmp_xlate Aug 22 05:43:52 05:43:52.770838:CID-0:RT:xlate_icmp_pak Aug 22 05:43:52 05:43:52.770838:CID-0:RT: post addr xlation: 115.64.136.32->8.8.8.8. Aug 22 05:43:52 05:43:52.770838:CID-0:RT: post addr xlation: 115.64.136.32->8.8.8.8. Aug 22 05:43:52 05:43:52.770838:CID-0:RT:skip pre-frag: is_tunnel_if- 0, is_if_mtu_configured- 0 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:mbuf 0x4365c200, exit nh 0xfffa0006 Aug 22 05:43:52 05:43:52.770838:CID-0:RT: ----- flow_process_pkt rc 0x0 (fp rc 0) Aug 22 05:43:52 05:43:52.770838:CID-0:RT:nat_get_mapped_or_incoming_dip: no rule configured with lsys id 0 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:nat_get_mapped_or_incoming_dip: diff 0, calls 0 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:nat_get_rule_number: type 2, rule counter 0 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:nat_get_hipped_ip: no rule configured Aug 22 05:43:52 05:43:52.770838:CID-0:RT:nat xlate nsp 8.8.8.8/1 -> 115.64.136.32/17086 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:nat xlate nsp2 115.64.136.32/17086 -> 0.0.0.0/1 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:nat_src_rule_match: lsys 0, src-ip 8.8.8.8, dst-ip 115.64.136.32, dst-port 17086 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:nat_src_get_dip_id: rule NULL Aug 22 05:43:52 05:43:52.770838:CID-0:RT:nat xlate nsp 8.8.8.8/1 -> 115.64.136.32/17086 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:nat xlate nsp2 115.64.136.32/17086 -> 0.0.0.0/1 Aug 22 05:43:52 05:43:52.770838:CID-0:RT:nat_get_mipped_ip: no rule configured for lsys 0 Aug 22 05:43:53 05:43:52.988730:CID-0:RT:flow_nat_release_dip_by_session_with_natp: sid 237739, src dip id 2, dst dip id 0, pst nat: 0, src pst nat: 0, dst pst nat:0 Aug 22 05:43:53 05:43:52.988730:CID-0:RT:flow_nat_release_dip_by_session_with_natp: Normal dip release: sid 237739, dip id 2, pst dst dip id 0 Aug 22 05:43:53 05:43:52.988730:CID-0:RT:nat_release_dip_by_session: release source nat [lsysid:0]: pst_nat = 0x0, nat_info = 0x59eee4b8, did = 2, pport = 13120, port-num 1flag = 0x0, on platform 1, lsys 0, Aug 22 05:43:53 05:43:52.988730:CID-0:RT:nat_release_dip_by_session:src_ip 192.168.1.2 dip 115.64.136.32 dst_ip 8.8.8.8 Aug 22 05:43:53 05:43:52.988730:CID-0:RT:nat_release_local_dip_by_session: Release source nat [0]: did=2 pport=13120 Aug 22 05:43:53 05:43:52.988730:CID-0:RT:nat_release_local_dip_by_session:src_ip 192.168.1.2 dip 115.64.136.32 dst_ip 8.8.8.8 Aug 22 05:43:53 05:43:52.988730:CID-0:RT:flow_nat_release_dip_by_session_with_natp: sid 237828, src dip id 2, dst dip id 0, pst nat: 0, src pst nat: 0, dst pst nat:0 Aug 22 05:43:53 05:43:52.988730:CID-0:RT:flow_nat_release_dip_by_session_with_natp: Normal dip release: sid 237828, dip id 2, pst dst dip id 0 Aug 22 05:43:53 05:43:52.988730:CID-0:RT:nat_release_dip_by_session: release source nat [lsysid:0]: pst_nat = 0x0, nat_info = 0x59ef8340, did = 2, pport = 25764, port-num 1flag = 0x0, on platform 1, lsys 0, Aug 22 05:43:53 05:43:52.988730:CID-0:RT:nat_release_dip_by_session:src_ip 192.168.1.2 dip 115.64.136.32 dst_ip 37.48.66.130 Aug 22 05:43:53 05:43:52.988730:CID-0:RT:nat_release_local_dip_by_session: Release source nat [0]: did=2 pport=25764 Aug 22 05:43:53 05:43:52.988730:CID-0:RT:nat_release_local_dip_by_session:src_ip 192.168.1.2 dip 115.64.136.32 dst_ip 37.48.66.130 Aug 22 05:43:54 05:43:53.992455:CID-0:RT:nat_get_mapped_or_incoming_dip: no rule configured with lsys id 0 Aug 22 05:43:54 05:43:53.992455:CID-0:RT:nat_get_mapped_or_incoming_dip: diff 0, calls 0 Aug 22 05:43:54 05:43:53.992455:CID-0:RT:nat_dst_xlate_ip_port: src-ip 192.168.1.7, dst-ip 207.46.101.8, dst_port 443 Aug 22 05:43:54 05:43:53.992455:CID-0:RT:nat_dst_rule_match: lsys 0, src-ip 192.168.1.7, dst-ip 207.46.101.8, dst-port 443 Aug 22 05:43:54 05:43:53.992455:CID-0:RT:nat_dst_xlate_ip_port: no rule found... Aug 22 05:43:54 05:43:53.992455:CID-0:RT:nat_get_rule_number: type 2, rule counter 0 Aug 22 05:43:54 05:43:53.992455:CID-0:RT:nat_get_hipped_ip: no rule configured Aug 22 05:43:54 05:43:53.992455:CID-0:RT:nat xlate nsp 192.168.1.7/53075 -> 207.46.101.8/443 Aug 22 05:43:54 05:43:53.992455:CID-0:RT:nat xlate nsp2 207.46.101.8/443 -> 0.0.0.0/53075 Aug 22 05:43:54 05:43:53.992455:CID-0:RT:nat_src_rule_match: lsys 0, src-ip 192.168.1.7, dst-ip 207.46.101.8, dst-port 443 Aug 22 05:43:54 05:43:53.992455:CID-0:RT:nat_proc_sorted_lists: src_min 1073741825, dst_min 1073741825 Aug 22 05:43:54 05:43:53.992455:CID-0:RT:nat_rule_match_ctx_n_port: protocol 6 Aug 22 05:43:54 05:43:53.992455:CID-0:RT:nat_rule_match_ctx_n_port: ctx-type 4, from-id 6, to-id 7 Aug 22 05:43:54 05:43:53.992455:CID-0:RT:nat_src_rule_match: src nat rule found: id 1, pos-id 1, pool id 2 Aug 22 05:43:54 05:43:53.992455:CID-0:RT:nat xlate nsp 192.168.1.7/53075 -> 207.46.101.8/443 Aug 22 05:43:54 05:43:53.992455:CID-0:RT:nat xlate nsp2 207.46.101.8/443 -> 0.0.0.0/53075 Aug 22 05:43:54 05:43:53.992455:CID-0:RT:nat_get_dip_by_host: pst_nat = False, lsys_id = 0, platform = 1, dip_id = 0x2,dst_port = 443, ptr = 0x4fa2a290, sess_id = 0x0 Aug 22 05:43:54 05:43:53.992455:CID-0:RT:nat_get_dip_by_host:src_ip 192.168.1.7 dst_ip 207.46.101.8 Aug 22 05:43:54 05:43:53.992455:CID-0:RT:nat_get_local_dip_by_host: src nat ip/port is allocated locally. src_port 53075 Aug 22 05:43:54 05:43:53.992455:CID-0:RT:nat_get_dip_by_host_cp: lsys id: 0, src_port 0, dip id: 0x2 Aug 22 05:43:54 05:43:53.992455:CID-0:RT:nat_get_dip_by_host_cp:src_ip 192.168.1.7 dst_ip 207.46.101.8 Aug 22 05:43:54 05:43:53.992455:CID-0:RT:nat_get_dip_by_host_cp: get xlated ip/port (115.64.136.32/19953), xlation flag (1), xlation pool id (0x2) from JSF Aug 22 05:43:54 05:43:53.992455:CID-0:RT:nat_get_mipped_ip: no rule configured for lsys 0 Aug 22 05:43:54 05:43:54.013632:CID-0:RT:scan hash table with index 145 Aug 22 05:43:54 05:43:54.013632:CID-0:RT:scan hash table with index 146 Aug 22 05:43:54 05:43:54.013632:CID-0:RT:scan hash table with index 147 Aug 22 05:43:54 05:43:54.013632:CID-0:RT:scan hash table with index 148 Aug 22 05:43:54 05:43:54.013632:CID-0:RT:scan hash table with index 149 Aug 22 05:43:54 05:43:54.013632:CID-0:RT:scan hash table with index 150 Aug 22 05:43:54 05:43:54.013632:CID-0:RT:scan hash table with index 151 Aug 22 05:43:54 05:43:54.013632:CID-0:RT:scan hash table with index 152 Aug 22 05:43:54 05:43:54.013632:CID-0:RT:scan hash table with index 153 Aug 22 05:43:54 05:43:54.013632:CID-0:RT:scan hash table with index 154 Aug 22 05:43:54 05:43:54.013632:CID-0:RT:scan hash table with index 155 Aug 22 05:43:54 05:43:54.013632:CID-0:RT:scan hash table with index 156 Aug 22 05:43:54 05:43:54.013632:CID-0:RT:scan hash table with index 157 Aug 22 05:43:54 05:43:54.013632:CID-0:RT:scan hash table with index 158 Aug 22 05:43:54 05:43:54.013632:CID-0:RT:scan hash table with index 159 Aug 22 05:43:54 05:43:54.013632:CID-0:RT:scan hash table with index 160 Aug 22 05:43:54 05:43:54.036606:CID-0:RT:nat_get_mapped_or_incoming_dip: no rule configured with lsys id 0 Aug 22 05:43:54 05:43:54.036606:CID-0:RT:nat_get_mapped_or_incoming_dip: diff 0, calls 0 Aug 22 05:43:54 05:43:54.036606:CID-0:RT:nat_dst_xlate_ip_port: src-ip 192.168.1.21, dst-ip 255.255.255.255, dst_port 10001 Aug 22 05:43:54 05:43:54.036606:CID-0:RT:nat_dst_rule_match: lsys 0, src-ip 192.168.1.21, dst-ip 255.255.255.255, dst-port 10001 Aug 22 05:43:54 05:43:54.036606:CID-0:RT:nat_dst_xlate_ip_port: no rule found... Aug 22 05:43:54 05:43:54.036606:CID-0:RT:nat_get_mapped_or_incoming_dip: no rule configured with lsys id 0 Aug 22 05:43:54 05:43:54.036606:CID-0:RT:nat_get_mapped_or_incoming_dip: diff 0, calls 0 Aug 22 05:43:54 05:43:54.036606:CID-0:RT:nat_dst_xlate_ip_port: src-ip 192.168.1.21, dst-ip 255.255.255.255, dst_port 10001 Aug 22 05:43:54 05:43:54.036606:CID-0:RT:nat_dst_rule_match: lsys 0, src-ip 192.168.1.21, dst-ip 255.255.255.255, dst-port 10001 Aug 22 05:43:54 05:43:54.036606:CID-0:RT:nat_dst_xlate_ip_port: no rule found... Aug 22 05:43:55 05:43:54.989651:CID-0:RT:flow_nat_release_dip_by_session_with_natp: sid 237836, src dip id 2, dst dip id 0, pst nat: 0, src pst nat: 0, dst pst nat:0 Aug 22 05:43:55 05:43:54.989651:CID-0:RT:flow_nat_release_dip_by_session_with_natp: Normal dip release: sid 237836, dip id 2, pst dst dip id 0 Aug 22 05:43:55 05:43:54.989651:CID-0:RT:nat_release_dip_by_session: release source nat [lsysid:0]: pst_nat = 0x0, nat_info = 0x59ef9180, did = 2, pport = 14910, port-num 1flag = 0x0, on platform 1, lsys 0, Aug 22 05:43:55 05:43:54.989651:CID-0:RT:nat_release_dip_by_session:src_ip 192.168.1.2 dip 115.64.136.32 dst_ip 37.48.66.130 Aug 22 05:43:55 05:43:54.989651:CID-0:RT:nat_release_local_dip_by_session: Release source nat [0]: did=2 pport=14910 Aug 22 05:43:55 05:43:54.989651:CID-0:RT:nat_release_local_dip_by_session:src_ip 192.168.1.2 dip 115.64.136.32 dst_ip 37.48.66.130 Aug 22 05:43:55 05:43:55.341371:CID-0:RT:nat_get_mapped_or_incoming_dip: no rule configured with lsys id 0 Aug 22 05:43:55 05:43:55.341371:CID-0:RT:nat_get_mapped_or_incoming_dip: diff 0, calls 0 Aug 22 05:43:55 05:43:55.341371:CID-0:RT:nat_dst_xlate_ip_port: src-ip 192.168.1.7, dst-ip 207.46.101.8, dst_port 443 Aug 22 05:43:55 05:43:55.341371:CID-0:RT:nat_dst_rule_match: lsys 0, src-ip 192.168.1.7, dst-ip 207.46.101.8, dst-port 443 Aug 22 05:43:55 05:43:55.341371:CID-0:RT:nat_dst_xlate_ip_port: no rule found... Aug 22 05:43:55 05:43:55.341371:CID-0:RT:nat_get_rule_number: type 2, rule counter 0 Aug 22 05:43:55 05:43:55.341371:CID-0:RT:nat_get_hipped_ip: no rule configured Aug 22 05:43:55 05:43:55.341371:CID-0:RT:nat xlate nsp 192.168.1.7/53076 -> 207.46.101.8/443 Aug 22 05:43:55 05:43:55.341371:CID-0:RT:nat xlate nsp2 207.46.101.8/443 -> 0.0.0.0/53076 Aug 22 05:43:55 05:43:55.341371:CID-0:RT:nat_src_rule_match: lsys 0, src-ip 192.168.1.7, dst-ip 207.46.101.8, dst-port 443 Aug 22 05:43:55 05:43:55.341371:CID-0:RT:nat_proc_sorted_lists: src_min 1073741825, dst_min 1073741825 Aug 22 05:43:55 05:43:55.341371:CID-0:RT:nat_rule_match_ctx_n_port: protocol 6 Aug 22 05:43:55 05:43:55.341371:CID-0:RT:nat_rule_match_ctx_n_port: ctx-type 4, from-id 6, to-id 7 Aug 22 05:43:55 05:43:55.341371:CID-0:RT:nat_src_rule_match: src nat rule found: id 1, pos-id 1, pool id 2 Aug 22 05:43:55 05:43:55.341371:CID-0:RT:nat xlate nsp 192.168.1.7/53076 -> 207.46.101.8/443 Aug 22 05:43:55 05:43:55.341371:CID-0:RT:nat xlate nsp2 207.46.101.8/443 -> 0.0.0.0/53076 Aug 22 05:43:55 05:43:55.341371:CID-0:RT:nat_get_dip_by_host: pst_nat = False, lsys_id = 0, platform = 1, dip_id = 0x2,dst_port = 443, ptr = 0x4fa2a290, sess_id = 0x0 Aug 22 05:43:55 05:43:55.341371:CID-0:RT:nat_get_dip_by_host:src_ip 192.168.1.7 dst_ip 207.46.101.8 Aug 22 05:43:55 05:43:55.341371:CID-0:RT:nat_get_local_dip_by_host: src nat ip/port is allocated locally. src_port 53076 Aug 22 05:43:55 05:43:55.341371:CID-0:RT:nat_get_dip_by_host_cp: lsys id: 0, src_port 0, dip id: 0x2 Aug 22 05:43:55 05:43:55.341371:CID-0:RT:nat_get_dip_by_host_cp:src_ip 192.168.1.7 dst_ip 207.46.101.8 Aug 22 05:43:55 05:43:55.341371:CID-0:RT:nat_get_dip_by_host_cp: get xlated ip/port (115.64.136.32/1696), xlation flag (1), xlation pool id (0x2) from JSF Aug 22 05:43:55 05:43:55.341371:CID-0:RT:nat_get_mipped_ip: no rule configured for lsys 0 Aug 22 05:43:57 05:43:56.981016:CID-0:RT:nat_get_mapped_or_incoming_dip: no rule configured with lsys id 0 Aug 22 05:43:57 05:43:56.981016:CID-0:RT:nat_get_mapped_or_incoming_dip: diff 0, calls 0 Aug 22 05:43:57 05:43:56.981016:CID-0:RT:nat_dst_xlate_ip_port: src-ip 192.168.1.13, dst-ip 192.168.1.1, dst_port 53 Aug 22 05:43:57 05:43:56.981016:CID-0:RT:nat_dst_rule_match: lsys 0, src-ip 192.168.1.13, dst-ip 192.168.1.1, dst-port 53 Aug 22 05:43:57 05:43:56.981016:CID-0:RT:nat_dst_xlate_ip_port: no rule found... Aug 22 05:43:57 05:43:56.981016:CID-0:RT:nat_get_rule_number: type 2, rule counter 0 Aug 22 05:43:57 05:43:56.981016:CID-0:RT:nat_get_hipped_ip: no rule configured Aug 22 05:43:57 05:43:56.981016:CID-0:RT:nat xlate nsp 192.168.1.13/60232 -> 192.168.1.1/53 Aug 22 05:43:57 05:43:56.981016:CID-0:RT:nat xlate nsp2 192.168.1.1/53 -> 0.0.0.0/60232 Aug 22 05:43:57 05:43:56.981016:CID-0:RT:nat_src_rule_match: lsys 0, src-ip 192.168.1.13, dst-ip 192.168.1.1, dst-port 53 Aug 22 05:43:57 05:43:56.981016:CID-0:RT:nat_proc_sorted_lists: src_min 1073741825, dst_min 1073741825 Aug 22 05:43:57 05:43:56.981016:CID-0:RT:nat_rule_match_ctx_n_port: protocol 17 Aug 22 05:43:57 05:43:56.981016:CID-0:RT:nat_rule_match_ctx_n_port: ctx-type 4, from-id 6, to-id 2 Aug 22 05:43:57 05:43:56.981016:CID-0:RT:nat_src_get_dip_id: rule NULL Aug 22 05:43:57 05:43:56.981016:CID-0:RT:nat xlate nsp 192.168.1.13/60232 -> 192.168.1.1/53 Aug 22 05:43:57 05:43:56.981016:CID-0:RT:nat xlate nsp2 192.168.1.1/53 -> 0.0.0.0/60232 Aug 22 05:43:57 05:43:56.981016:CID-0:RT:nat_get_mipped_ip: no rule configured for lsys 0 Aug 22 05:43:57 05:43:56.987548:CID-0:RT: Error : get sess plugin info 0x59eef520 Aug 22 05:43:57 05:43:56.987548:CID-0:RT:avt_get_app_name(): Getting appid failed. Aug 22 05:43:57 05:43:56.987548:CID-0:RT: Error : get sess plugin info 0x59eef520 Aug 22 05:43:57 05:43:56.987548:CID-0:RT:jsf sess close notify Aug 22 05:43:57 05:43:56.987548:CID-0:RT:flow_ipv4_del_flow: sess 237749, in hash 32 Aug 22 05:43:57 05:43:56.987548:CID-0:RT:flow_ipv4_del_flow: sess 237749, in hash 32 Aug 22 05:43:57 05:43:56.987548:CID-0:RT:flow_nat_release_dip_by_session_with_natp: sid 237013, src dip id 2, dst dip id 0, pst nat: 0, src pst nat: 0, dst pst nat:0 Aug 22 05:43:57 05:43:56.987548:CID-0:RT:flow_nat_release_dip_by_session_with_natp: Normal dip release: sid 237013, dip id 2, pst dst dip id 0 Aug 22 05:43:57 05:43:56.987548:CID-0:RT:nat_release_dip_by_session: release source nat [lsysid:0]: pst_nat = 0x0, nat_info = 0x59e9d788, did = 2, pport = 9118, port-num 1flag = 0x0, on platform 1, lsys 0, Aug 22 05:43:57 05:43:56.987548:CID-0:RT:nat_release_dip_by_session:src_ip 192.168.1.7 dip 115.64.136.32 dst_ip 207.46.101.8 Aug 22 05:43:57 05:43:56.987548:CID-0:RT:nat_release_local_dip_by_session: Release source nat [0]: did=2 pport=9118 Aug 22 05:43:57 05:43:56.987548:CID-0:RT:nat_release_local_dip_by_session:src_ip 192.168.1.7 dip 115.64.136.32 dst_ip 207.46.101.8 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:<192.168.1.2/4169->8.8.8.8/1;1> matched filter MatchTraffic: Aug 22 05:43:57 05:43:57.771141:CID-0:RT:packet [60] ipid = 17346, @0x4366081a Aug 22 05:43:57 05:43:57.771141:CID-0:RT:---- flow_process_pkt: (thd 1): flow_ctxt type 15, common flag 0x0, mbuf 0x43660600, rtbl_idx = 0 Aug 22 05:43:57 05:43:57.771141:CID-0:RT: flow process pak fast ifl 82 in_ifp vlan.0 Aug 22 05:43:57 05:43:57.771141:CID-0:RT: vlan.0:192.168.1.2->8.8.8.8, icmp, (8/0) Aug 22 05:43:57 05:43:57.771141:CID-0:RT: find flow: table 0x4f9a9078, hash 51146(0xffff), sa 192.168.1.2, da 8.8.8.8, sp 4169, dp 1, proto 1, tok 6 Aug 22 05:43:57 05:43:57.771141:CID-0:RT: no session found, start first path. in_tunnel - 0x0, from_cp_flag - 0 Aug 22 05:43:57 05:43:57.771141:CID-0:RT: flow_first_create_session Aug 22 05:43:57 05:43:57.771141:CID-0:RT: flow_first_in_dst_nat: in , out dst_adr 8.8.8.8, sp 4169, dp 1 Aug 22 05:43:57 05:43:57.771141:CID-0:RT: chose interface vlan.0 as incoming nat if. Aug 22 05:43:57 05:43:57.771141:CID-0:RT:nat_get_mapped_or_incoming_dip: no rule configured with lsys id 0 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:nat_get_mapped_or_incoming_dip: diff 0, calls 0 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:nat_dst_xlate_ip_port: src-ip 192.168.1.2, dst-ip 8.8.8.8, dst_port 1 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:nat_dst_rule_match: lsys 0, src-ip 192.168.1.2, dst-ip 8.8.8.8, dst-port 1 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:nat_dst_xlate_ip_port: no rule found... Aug 22 05:43:57 05:43:57.771141:CID-0:RT:flow_first_rule_dst_xlate: DST no-xlate: 0.0.0.0(0) to 8.8.8.8(1) Aug 22 05:43:57 05:43:57.771141:CID-0:RT:flow_first_routing: vr_id 0, call flow_route_lookup(): src_ip 192.168.1.2, x_dst_ip 8.8.8.8, in ifp vlan.0, out ifp N/A sp 4169, dp 1, ip_proto 1, tos 0 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:Doing DESTINATION addr route-lookup Aug 22 05:43:57 05:43:57.771141:CID-0:RT: routed (x_dst_ip 8.8.8.8) from trust (vlan.0 in 0) to pp0.0, Next-hop: 8.8.8.8 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:flow_first_policy_search: policy search from zone trust-> zone untrust (0x0,0x10490001,0x1) Aug 22 05:43:57 05:43:57.771141:CID-0:RT:Policy lkup: vsys 0 zone(6:trust) -> zone(7:untrust) scope:0 Aug 22 05:43:57 05:43:57.771141:CID-0:RT: 192.168.1.2/2048 -> 8.8.8.8/15634 proto 1 Aug 22 05:43:57 05:43:57.771141:CID-0:RT: app 0, timeout 60s, curr ageout 60s Aug 22 05:43:57 05:43:57.771141:CID-0:RT: permitted by policy default-permit(6) Aug 22 05:43:57 05:43:57.771141:CID-0:RT: packet passed, Permitted by policy. Aug 22 05:43:57 05:43:57.771141:CID-0:RT:nat_get_rule_number: type 2, rule counter 0 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:nat_get_hipped_ip: no rule configured Aug 22 05:43:57 05:43:57.771141:CID-0:RT:flow_first_src_xlate: nat_src_xlated: False, nat_src_xlate_failed: False Aug 22 05:43:57 05:43:57.771141:CID-0:RT:nat xlate nsp 192.168.1.2/4169 -> 8.8.8.8/1 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:nat xlate nsp2 8.8.8.8/1 -> 0.0.0.0/4169 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:nat_src_rule_match: lsys 0, src-ip 192.168.1.2, dst-ip 8.8.8.8, dst-port 1 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:nat_proc_sorted_lists: src_min 1073741825, dst_min 1073741825 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:nat_rule_match_ctx_n_port: protocol 1 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:nat_rule_match_ctx_n_port: ctx-type 4, from-id 6, to-id 7 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:nat_src_rule_match: src nat rule found: id 1, pos-id 1, pool id 2 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:flow_first_src_xlate: src nat returns status: 1, rule/pool id: 1/2, pst_nat: False. Aug 22 05:43:57 05:43:57.771141:CID-0:RT:nat xlate nsp 192.168.1.2/4169 -> 8.8.8.8/1 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:nat xlate nsp2 8.8.8.8/1 -> 0.0.0.0/4169 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:nat_get_dip_by_host: pst_nat = False, lsys_id = 0, platform = 1, dip_id = 0x2,dst_port = 1, ptr = 0x4fa2a290, sess_id = 0x0 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:nat_get_dip_by_host:src_ip 192.168.1.2 dst_ip 8.8.8.8 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:nat_get_local_dip_by_host: src nat ip/port is allocated locally. src_port 4169 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:nat_get_dip_by_host_cp: lsys id: 0, src_port 0, dip id: 0x2 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:nat_get_dip_by_host_cp:src_ip 192.168.1.2 dst_ip 8.8.8.8 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:nat_get_dip_by_host_cp: get xlated ip/port (115.64.136.32/14557), xlation flag (1), xlation pool id (0x2) from JSF Aug 22 05:43:57 05:43:57.771141:CID-0:RT: dip id = 2/0, 192.168.1.2/4169->115.64.136.32/14557 protocol 58 Aug 22 05:43:57 05:43:57.771141:CID-0:RT: choose interface pp0.0 as outgoing phy if Aug 22 05:43:57 05:43:57.771141:CID-0:RT:nat_get_mipped_ip: no rule configured for lsys 0 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:is_loop_pak: No loop: on ifp: pp0.0, addr: 8.8.8.8, rtt_idx:0 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:-jsf : Alloc sess plugin info for session 237846 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:[JSF]Normal interest check. regd plugins 19, enabled impl mask 0x0 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:-jsf int check: plugin id 2, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:-jsf int check: plugin id 3, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:-jsf int check: plugin id 5, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:-jsf int check: plugin id 6, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:-jsf int check: plugin id 7, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:-jsf int check: plugin id 8, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:-jsf int check: plugin id 12, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:-jsf int check: plugin id 15, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:+++++++++++jsf_test_plugin_data_evh: 3 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:-jsf int check: plugin id 16, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:-jsf int check: plugin id 22, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:-jsf int check: plugin id 23, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:-jsf int check: plugin id 26, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:-jsf int check: plugin id 27, svc_req 0x0, impl mask 0x0. rc 2 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:-jsf int check: plugin id 28, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:[JSF]Plugins(0x0, count 0) enabled for session = 0, impli mask(0x0), post_nat cnt 237846 svc req(0x0) Aug 22 05:43:57 05:43:57.771141:CID-0:RT:-jsf : no plugin interested for session 237846, free sess plugin info Aug 22 05:43:57 05:43:57.771141:CID-0:RT:flow_first_service_lookup(): natp(0x59efa1e8): app_id, 0(0). Aug 22 05:43:57 05:43:57.771141:CID-0:RT: service lookup identified service 0. Aug 22 05:43:57 05:43:57.771141:CID-0:RT: flow_first_final_check: in , out Aug 22 05:43:57 05:43:57.771141:CID-0:RT:flow_first_final_check: flow_set_xlate_vector. Aug 22 05:43:57 05:43:57.771141:CID-0:RT:flow_first_complete_session, pak_ptr: 0x4fa2a290, nsp: 0x59efa1e8, in_tunnel: 0x0 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:construct v4 vector for nsp2 Aug 22 05:43:57 05:43:57.771141:CID-0:RT: existing vector list 0x1200-0x48cf5910. Aug 22 05:43:57 05:43:57.771141:CID-0:RT: Session (id:237846) created for first pak 1200 Aug 22 05:43:57 05:43:57.771141:CID-0:RT: flow_first_install_session======> 0x59efa1e8 Aug 22 05:43:57 05:43:57.771141:CID-0:RT: nsp 0x59efa1e8, nsp2 0x59efa268 Aug 22 05:43:57 05:43:57.771141:CID-0:RT: make_nsp_ready_no_resolve() Aug 22 05:43:57 05:43:57.771141:CID-0:RT: route lookup: dest-ip 192.168.1.2 orig ifp vlan.0 output_ifp vlan.0 orig-zone 6 out-zone 6 vsd 0 Aug 22 05:43:57 05:43:57.771141:CID-0:RT: route to 192.168.1.2 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:no need update ha Aug 22 05:43:57 05:43:57.771141:CID-0:RT:Installing c2s NP session wing Aug 22 05:43:57 05:43:57.771141:CID-0:RT:Installing s2c NP session wing Aug 22 05:43:57 05:43:57.771141:CID-0:RT: flow got session. Aug 22 05:43:57 05:43:57.771141:CID-0:RT: flow session id 237846 Aug 22 05:43:57 05:43:57.771141:CID-0:RT: vector bits 0x1200 vector 0x48cf5910 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:flow_xlate_pak Aug 22 05:43:57 05:43:57.771141:CID-0:RT:flow_handle_icmp_xlate Aug 22 05:43:57 05:43:57.771141:CID-0:RT:xlate_icmp_pak Aug 22 05:43:57 05:43:57.771141:CID-0:RT: post addr xlation: 115.64.136.32->8.8.8.8. Aug 22 05:43:57 05:43:57.771141:CID-0:RT: post addr xlation: 115.64.136.32->8.8.8.8. Aug 22 05:43:57 05:43:57.771141:CID-0:RT:skip pre-frag: is_tunnel_if- 0, is_if_mtu_configured- 0 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:mbuf 0x43660600, exit nh 0xfffa0006 Aug 22 05:43:57 05:43:57.771141:CID-0:RT: ----- flow_process_pkt rc 0x0 (fp rc 0) Aug 22 05:43:57 05:43:57.771141:CID-0:RT:nat_get_mapped_or_incoming_dip: no rule configured with lsys id 0 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:nat_get_mapped_or_incoming_dip: diff 0, calls 0 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:nat_get_rule_number: type 2, rule counter 0 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:nat_get_hipped_ip: no rule configured Aug 22 05:43:57 05:43:57.771141:CID-0:RT:nat xlate nsp 8.8.8.8/1 -> 115.64.136.32/14557 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:nat xlate nsp2 115.64.136.32/14557 -> 0.0.0.0/1 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:nat_src_rule_match: lsys 0, src-ip 8.8.8.8, dst-ip 115.64.136.32, dst-port 14557 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:nat_src_get_dip_id: rule NULL Aug 22 05:43:57 05:43:57.771141:CID-0:RT:nat xlate nsp 8.8.8.8/1 -> 115.64.136.32/14557 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:nat xlate nsp2 115.64.136.32/14557 -> 0.0.0.0/1 Aug 22 05:43:57 05:43:57.771141:CID-0:RT:nat_get_mipped_ip: no rule configured for lsys 0 Aug 22 05:43:59 05:43:58.990998:CID-0:RT:flow_nat_release_dip_by_session_with_natp: sid 237749, src dip id 2, dst dip id 0, pst nat: 0, src pst nat: 0, dst pst nat:0 Aug 22 05:43:59 05:43:58.990998:CID-0:RT:flow_nat_release_dip_by_session_with_natp: Normal dip release: sid 237749, dip id 2, pst dst dip id 0 Aug 22 05:43:59 05:43:58.990998:CID-0:RT:nat_release_dip_by_session: release source nat [lsysid:0]: pst_nat = 0x0, nat_info = 0x59eef688, did = 2, pport = 16662, port-num 1flag = 0x0, on platform 1, lsys 0, Aug 22 05:43:59 05:43:58.990998:CID-0:RT:nat_release_dip_by_session:src_ip 192.168.1.2 dip 115.64.136.32 dst_ip 8.8.8.8 Aug 22 05:43:59 05:43:58.990998:CID-0:RT:nat_release_local_dip_by_session: Release source nat [0]: did=2 pport=16662 Aug 22 05:43:59 05:43:58.990998:CID-0:RT:nat_release_local_dip_by_session:src_ip 192.168.1.2 dip 115.64.136.32 dst_ip 8.8.8.8 Aug 22 05:43:59 05:43:58.990998:CID-0:RT:flow_nat_release_dip_by_session_with_natp: sid 237841, src dip id 2, dst dip id 0, pst nat: 0, src pst nat: 0, dst pst nat:0 Aug 22 05:43:59 05:43:58.990998:CID-0:RT:flow_nat_release_dip_by_session_with_natp: Normal dip release: sid 237841, dip id 2, pst dst dip id 0 Aug 22 05:43:59 05:43:58.990998:CID-0:RT:nat_release_dip_by_session: release source nat [lsysid:0]: pst_nat = 0x0, nat_info = 0x59ef9a68, did = 2, pport = 19953, port-num 1flag = 0x0, on platform 1, lsys 0, Aug 22 05:43:59 05:43:58.990998:CID-0:RT:nat_release_dip_by_session:src_ip 192.168.1.7 dip 115.64.136.32 dst_ip 207.46.101.8 Aug 22 05:43:59 05:43:58.990998:CID-0:RT:nat_release_local_dip_by_session: Release source nat [0]: did=2 pport=19953 Aug 22 05:43:59 05:43:58.990998:CID-0:RT:nat_release_local_dip_by_session:src_ip 192.168.1.7 dip 115.64.136.32 dst_ip 207.46.101.8 Aug 22 05:43:59 05:43:58.990998:CID-0:RT:flow_nat_release_dip_by_session_with_natp: sid 236430, src dip id 2, dst dip id 0, pst nat: 0, src pst nat: 0, dst pst nat:0 Aug 22 05:43:59 05:43:58.990998:CID-0:RT:flow_nat_release_dip_by_session_with_natp: Normal dip release: sid 236430, dip id 2, pst dst dip id 0 Aug 22 05:43:59 05:43:58.990998:CID-0:RT:nat_release_dip_by_session: release source nat [lsysid:0]: pst_nat = 0x0, nat_info = 0x59e5c910, did = 2, pport = 25081, port-num 1flag = 0x0, on platform 1, lsys 0, Aug 22 05:43:59 05:43:58.990998:CID-0:RT:nat_release_dip_by_session:src_ip 192.168.1.2 dip 115.64.136.32 dst_ip 216.58.220.142 Aug 22 05:43:59 05:43:58.990998:CID-0:RT:nat_release_local_dip_by_session: Release source nat [0]: did=2 pport=25081 Aug 22 05:43:59 05:43:58.990998:CID-0:RT:nat_release_local_dip_by_session:src_ip 192.168.1.2 dip 115.64.136.32 dst_ip 216.58.220.142 Aug 22 05:44:00 05:44:00.013047:CID-0:RT:scan hash table with index 161 Aug 22 05:44:00 05:44:00.013047:CID-0:RT:scan hash table with index 162 Aug 22 05:44:00 05:44:00.013047:CID-0:RT:scan hash table with index 163 Aug 22 05:44:00 05:44:00.013047:CID-0:RT:scan hash table with index 164 Aug 22 05:44:00 05:44:00.013047:CID-0:RT:scan hash table with index 165 Aug 22 05:44:00 05:44:00.013047:CID-0:RT:scan hash table with index 166 Aug 22 05:44:00 05:44:00.013047:CID-0:RT:scan hash table with index 167 Aug 22 05:44:00 05:44:00.013047:CID-0:RT:scan hash table with index 168 Aug 22 05:44:00 05:44:00.013047:CID-0:RT:scan hash table with index 169 Aug 22 05:44:00 05:44:00.013047:CID-0:RT:scan hash table with index 170 Aug 22 05:44:00 05:44:00.013047:CID-0:RT:scan hash table with index 171 Aug 22 05:44:00 05:44:00.013047:CID-0:RT:scan hash table with index 172 Aug 22 05:44:00 05:44:00.013047:CID-0:RT:scan hash table with index 173 Aug 22 05:44:00 05:44:00.013047:CID-0:RT:scan hash table with index 174 Aug 22 05:44:00 05:44:00.013047:CID-0:RT:scan hash table with index 175 Aug 22 05:44:00 05:44:00.013047:CID-0:RT:scan hash table with index 176 Aug 22 05:44:00 05:44:00.691123:CID-0:RT:nat_get_mapped_or_incoming_dip: no rule configured with lsys id 0 Aug 22 05:44:00 05:44:00.691123:CID-0:RT:nat_get_mapped_or_incoming_dip: diff 0, calls 0 Aug 22 05:44:00 05:44:00.691123:CID-0:RT:nat_dst_xlate_ip_port: src-ip 192.168.1.60, dst-ip 192.168.1.255, dst_port 3052 Aug 22 05:44:00 05:44:00.691123:CID-0:RT:nat_dst_rule_match: lsys 0, src-ip 192.168.1.60, dst-ip 192.168.1.255, dst-port 3052 Aug 22 05:44:00 05:44:00.691123:CID-0:RT:nat_dst_xlate_ip_port: no rule found... Aug 22 05:44:00 05:44:00.691123:CID-0:RT:nat_get_mapped_or_incoming_dip: no rule configured with lsys id 0 Aug 22 05:44:00 05:44:00.691123:CID-0:RT:nat_get_mapped_or_incoming_dip: diff 0, calls 0 Aug 22 05:44:00 05:44:00.691123:CID-0:RT:nat_dst_xlate_ip_port: src-ip 192.168.1.60, dst-ip 192.168.1.255, dst_port 3052 Aug 22 05:44:00 05:44:00.691123:CID-0:RT:nat_dst_rule_match: lsys 0, src-ip 192.168.1.60, dst-ip 192.168.1.255, dst-port 3052 Aug 22 05:44:00 05:44:00.691123:CID-0:RT:nat_dst_xlate_ip_port: no rule found... Aug 22 05:44:01 05:44:00.991893:CID-0:RT: Error : get sess plugin info 0x59ef0360 Aug 22 05:44:01 05:44:00.991893:CID-0:RT:avt_get_app_name(): Getting appid failed. Aug 22 05:44:01 05:44:00.991893:CID-0:RT: Error : get sess plugin info 0x59ef0360 Aug 22 05:44:01 05:44:00.991893:CID-0:RT:jsf sess close notify Aug 22 05:44:01 05:44:00.991893:CID-0:RT:flow_ipv4_del_flow: sess 237757, in hash 32 Aug 22 05:44:01 05:44:00.991893:CID-0:RT:flow_ipv4_del_flow: sess 237757, in hash 32 Aug 22 05:44:01 05:44:01.590920:CID-0:RT:nat_get_mapped_or_incoming_dip: no rule configured with lsys id 0 Aug 22 05:44:01 05:44:01.590920:CID-0:RT:nat_get_mapped_or_incoming_dip: diff 0, calls 0 Aug 22 05:44:01 05:44:01.590920:CID-0:RT:nat_dst_xlate_ip_port: src-ip 209.126.117.235, dst-ip 115.64.136.32, dst_port 5060 Aug 22 05:44:01 05:44:01.590920:CID-0:RT:nat_dst_rule_match: lsys 0, src-ip 209.126.117.235, dst-ip 115.64.136.32, dst-port 5060 Aug 22 05:44:01 05:44:01.590920:CID-0:RT:nat_proc_sorted_lists: src_min 268435457, dst_min 268435457 Aug 22 05:44:01 05:44:01.590920:CID-0:RT:nat_rule_match_ctx_n_port: protocol 17 Aug 22 05:44:01 05:44:01.590920:CID-0:RT:nat_dst_xlate_ip_port: no rule found... Aug 22 05:44:01 05:44:01.590920:CID-0:RT:nat_get_rule_number: type 2, rule counter 0 Aug 22 05:44:01 05:44:01.590920:CID-0:RT:nat_get_hipped_ip: no rule configured Aug 22 05:44:01 05:44:01.590920:CID-0:RT:nat xlate nsp 209.126.117.235/5071 -> 115.64.136.32/5060 Aug 22 05:44:01 05:44:01.590920:CID-0:RT:nat xlate nsp2 115.64.136.32/5060 -> 0.0.0.0/5071 Aug 22 05:44:01 05:44:01.590920:CID-0:RT:nat_src_rule_match: lsys 0, src-ip 209.126.117.235, dst-ip 115.64.136.32, dst-port 5060 Aug 22 05:44:01 05:44:01.590920:CID-0:RT:nat_src_get_dip_id: rule NULL Aug 22 05:44:01 05:44:01.590920:CID-0:RT:nat xlate nsp 209.126.117.235/5071 -> 115.64.136.32/5060 Aug 22 05:44:01 05:44:01.590920:CID-0:RT:nat xlate nsp2 115.64.136.32/5060 -> 0.0.0.0/5071 Aug 22 05:44:01 05:44:01.590920:CID-0:RT:nat_get_mipped_ip: no rule configured for lsys 0 Aug 22 05:44:02 05:44:01.989713:CID-0:RT:nat_get_mapped_or_incoming_dip: no rule configured with lsys id 0 Aug 22 05:44:02 05:44:01.989713:CID-0:RT:nat_get_mapped_or_incoming_dip: diff 0, calls 0 Aug 22 05:44:02 05:44:01.989713:CID-0:RT:nat_dst_xlate_ip_port: src-ip 192.168.1.13, dst-ip 192.168.1.1, dst_port 53 Aug 22 05:44:02 05:44:01.989713:CID-0:RT:nat_dst_rule_match: lsys 0, src-ip 192.168.1.13, dst-ip 192.168.1.1, dst-port 53 Aug 22 05:44:02 05:44:01.989713:CID-0:RT:nat_dst_xlate_ip_port: no rule found... Aug 22 05:44:02 05:44:01.989713:CID-0:RT:nat_get_rule_number: type 2, rule counter 0 Aug 22 05:44:02 05:44:01.989713:CID-0:RT:nat_get_hipped_ip: no rule configured Aug 22 05:44:02 05:44:01.989713:CID-0:RT:nat xlate nsp 192.168.1.13/43698 -> 192.168.1.1/53 Aug 22 05:44:02 05:44:01.989713:CID-0:RT:nat xlate nsp2 192.168.1.1/53 -> 0.0.0.0/43698 Aug 22 05:44:02 05:44:01.989713:CID-0:RT:nat_src_rule_match: lsys 0, src-ip 192.168.1.13, dst-ip 192.168.1.1, dst-port 53 Aug 22 05:44:02 05:44:01.989713:CID-0:RT:nat_proc_sorted_lists: src_min 1073741825, dst_min 1073741825 Aug 22 05:44:02 05:44:01.989713:CID-0:RT:nat_rule_match_ctx_n_port: protocol 17 Aug 22 05:44:02 05:44:01.989713:CID-0:RT:nat_rule_match_ctx_n_port: ctx-type 4, from-id 6, to-id 2 Aug 22 05:44:02 05:44:01.989713:CID-0:RT:nat_src_get_dip_id: rule NULL Aug 22 05:44:02 05:44:01.989713:CID-0:RT:nat xlate nsp 192.168.1.13/43698 -> 192.168.1.1/53 Aug 22 05:44:02 05:44:01.989713:CID-0:RT:nat xlate nsp2 192.168.1.1/53 -> 0.0.0.0/43698 Aug 22 05:44:02 05:44:01.989713:CID-0:RT:nat_get_mipped_ip: no rule configured for lsys 0 Aug 22 05:44:02 05:44:02.602931:CID-0:RT:nat_get_mapped_or_incoming_dip: no rule configured with lsys id 0 Aug 22 05:44:02 05:44:02.602931:CID-0:RT:nat_get_mapped_or_incoming_dip: diff 0, calls 0 Aug 22 05:44:02 05:44:02.602931:CID-0:RT:nat_dst_xlate_ip_port: src-ip 192.168.1.2, dst-ip 37.48.66.130, dst_port 443 Aug 22 05:44:02 05:44:02.602931:CID-0:RT:nat_dst_rule_match: lsys 0, src-ip 192.168.1.2, dst-ip 37.48.66.130, dst-port 443 Aug 22 05:44:02 05:44:02.602931:CID-0:RT:nat_dst_xlate_ip_port: no rule found... Aug 22 05:44:02 05:44:02.602931:CID-0:RT:nat_get_rule_number: type 2, rule counter 0 Aug 22 05:44:02 05:44:02.602931:CID-0:RT:nat_get_hipped_ip: no rule configured Aug 22 05:44:02 05:44:02.602931:CID-0:RT:nat xlate nsp 192.168.1.2/64893 -> 37.48.66.130/443 Aug 22 05:44:02 05:44:02.602931:CID-0:RT:nat xlate nsp2 37.48.66.130/443 -> 0.0.0.0/64893 Aug 22 05:44:02 05:44:02.602931:CID-0:RT:nat_src_rule_match: lsys 0, src-ip 192.168.1.2, dst-ip 37.48.66.130, dst-port 443 Aug 22 05:44:02 05:44:02.602931:CID-0:RT:nat_proc_sorted_lists: src_min 1073741825, dst_min 1073741825 Aug 22 05:44:02 05:44:02.602931:CID-0:RT:nat_rule_match_ctx_n_port: protocol 6 Aug 22 05:44:02 05:44:02.602931:CID-0:RT:nat_rule_match_ctx_n_port: ctx-type 4, from-id 6, to-id 7 Aug 22 05:44:02 05:44:02.602931:CID-0:RT:nat_src_rule_match: src nat rule found: id 1, pos-id 1, pool id 2 Aug 22 05:44:02 05:44:02.602931:CID-0:RT:nat xlate nsp 192.168.1.2/64893 -> 37.48.66.130/443 Aug 22 05:44:02 05:44:02.602931:CID-0:RT:nat xlate nsp2 37.48.66.130/443 -> 0.0.0.0/64893 Aug 22 05:44:02 05:44:02.602931:CID-0:RT:nat_get_dip_by_host: pst_nat = False, lsys_id = 0, platform = 1, dip_id = 0x2,dst_port = 443, ptr = 0x4fa2a290, sess_id = 0x0 Aug 22 05:44:02 05:44:02.602931:CID-0:RT:nat_get_dip_by_host:src_ip 192.168.1.2 dst_ip 37.48.66.130 Aug 22 05:44:02 05:44:02.602931:CID-0:RT:nat_get_local_dip_by_host: src nat ip/port is allocated locally. src_port 64893 Aug 22 05:44:02 05:44:02.602931:CID-0:RT:nat_get_dip_by_host_cp: lsys id: 0, src_port 0, dip id: 0x2 Aug 22 05:44:02 05:44:02.602931:CID-0:RT:nat_get_dip_by_host_cp:src_ip 192.168.1.2 dst_ip 37.48.66.130 Aug 22 05:44:02 05:44:02.602931:CID-0:RT:nat_get_dip_by_host_cp: get xlated ip/port (115.64.136.32/22938), xlation flag (1), xlation pool id (0x2) from JSF Aug 22 05:44:02 05:44:02.602931:CID-0:RT:nat_get_mipped_ip: no rule configured for lsys 0 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:<192.168.1.2/4170->8.8.8.8/1;1> matched filter MatchTraffic: Aug 22 05:44:02 05:44:02.770449:CID-0:RT:packet [60] ipid = 17383, @0x4367649a Aug 22 05:44:02 05:44:02.770449:CID-0:RT:---- flow_process_pkt: (thd 1): flow_ctxt type 15, common flag 0x0, mbuf 0x43676280, rtbl_idx = 0 Aug 22 05:44:02 05:44:02.770449:CID-0:RT: flow process pak fast ifl 82 in_ifp vlan.0 Aug 22 05:44:02 05:44:02.770449:CID-0:RT: vlan.0:192.168.1.2->8.8.8.8, icmp, (8/0) Aug 22 05:44:02 05:44:02.770449:CID-0:RT: find flow: table 0x4f9a9078, hash 59098(0xffff), sa 192.168.1.2, da 8.8.8.8, sp 4170, dp 1, proto 1, tok 6 Aug 22 05:44:02 05:44:02.770449:CID-0:RT: no session found, start first path. in_tunnel - 0x0, from_cp_flag - 0 Aug 22 05:44:02 05:44:02.770449:CID-0:RT: flow_first_create_session Aug 22 05:44:02 05:44:02.770449:CID-0:RT: flow_first_in_dst_nat: in , out dst_adr 8.8.8.8, sp 4170, dp 1 Aug 22 05:44:02 05:44:02.770449:CID-0:RT: chose interface vlan.0 as incoming nat if. Aug 22 05:44:02 05:44:02.770449:CID-0:RT:nat_get_mapped_or_incoming_dip: no rule configured with lsys id 0 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:nat_get_mapped_or_incoming_dip: diff 0, calls 0 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:nat_dst_xlate_ip_port: src-ip 192.168.1.2, dst-ip 8.8.8.8, dst_port 1 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:nat_dst_rule_match: lsys 0, src-ip 192.168.1.2, dst-ip 8.8.8.8, dst-port 1 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:nat_dst_xlate_ip_port: no rule found... Aug 22 05:44:02 05:44:02.770449:CID-0:RT:flow_first_rule_dst_xlate: DST no-xlate: 0.0.0.0(0) to 8.8.8.8(1) Aug 22 05:44:02 05:44:02.770449:CID-0:RT:flow_first_routing: vr_id 0, call flow_route_lookup(): src_ip 192.168.1.2, x_dst_ip 8.8.8.8, in ifp vlan.0, out ifp N/A sp 4170, dp 1, ip_proto 1, tos 0 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:Doing DESTINATION addr route-lookup Aug 22 05:44:02 05:44:02.770449:CID-0:RT: routed (x_dst_ip 8.8.8.8) from trust (vlan.0 in 0) to pp0.0, Next-hop: 8.8.8.8 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:flow_first_policy_search: policy search from zone trust-> zone untrust (0x0,0x104a0001,0x1) Aug 22 05:44:02 05:44:02.770449:CID-0:RT:Policy lkup: vsys 0 zone(6:trust) -> zone(7:untrust) scope:0 Aug 22 05:44:02 05:44:02.770449:CID-0:RT: 192.168.1.2/2048 -> 8.8.8.8/15633 proto 1 Aug 22 05:44:02 05:44:02.770449:CID-0:RT: app 0, timeout 60s, curr ageout 60s Aug 22 05:44:02 05:44:02.770449:CID-0:RT: permitted by policy default-permit(6) Aug 22 05:44:02 05:44:02.770449:CID-0:RT: packet passed, Permitted by policy. Aug 22 05:44:02 05:44:02.770449:CID-0:RT:nat_get_rule_number: type 2, rule counter 0 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:nat_get_hipped_ip: no rule configured Aug 22 05:44:02 05:44:02.770449:CID-0:RT:flow_first_src_xlate: nat_src_xlated: False, nat_src_xlate_failed: False Aug 22 05:44:02 05:44:02.770449:CID-0:RT:nat xlate nsp 192.168.1.2/4170 -> 8.8.8.8/1 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:nat xlate nsp2 8.8.8.8/1 -> 0.0.0.0/4170 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:nat_src_rule_match: lsys 0, src-ip 192.168.1.2, dst-ip 8.8.8.8, dst-port 1 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:nat_proc_sorted_lists: src_min 1073741825, dst_min 1073741825 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:nat_rule_match_ctx_n_port: protocol 1 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:nat_rule_match_ctx_n_port: ctx-type 4, from-id 6, to-id 7 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:nat_src_rule_match: src nat rule found: id 1, pos-id 1, pool id 2 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:flow_first_src_xlate: src nat returns status: 1, rule/pool id: 1/2, pst_nat: False. Aug 22 05:44:02 05:44:02.770449:CID-0:RT:nat xlate nsp 192.168.1.2/4170 -> 8.8.8.8/1 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:nat xlate nsp2 8.8.8.8/1 -> 0.0.0.0/4170 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:nat_get_dip_by_host: pst_nat = False, lsys_id = 0, platform = 1, dip_id = 0x2,dst_port = 1, ptr = 0x4fa2a290, sess_id = 0x0 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:nat_get_dip_by_host:src_ip 192.168.1.2 dst_ip 8.8.8.8 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:nat_get_local_dip_by_host: src nat ip/port is allocated locally. src_port 4170 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:nat_get_dip_by_host_cp: lsys id: 0, src_port 0, dip id: 0x2 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:nat_get_dip_by_host_cp:src_ip 192.168.1.2 dst_ip 8.8.8.8 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:nat_get_dip_by_host_cp: get xlated ip/port (115.64.136.32/16265), xlation flag (1), xlation pool id (0x2) from JSF Aug 22 05:44:02 05:44:02.770449:CID-0:RT: dip id = 2/0, 192.168.1.2/4170->115.64.136.32/16265 protocol 58 Aug 22 05:44:02 05:44:02.770449:CID-0:RT: choose interface pp0.0 as outgoing phy if Aug 22 05:44:02 05:44:02.770449:CID-0:RT:nat_get_mipped_ip: no rule configured for lsys 0 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:is_loop_pak: No loop: on ifp: pp0.0, addr: 8.8.8.8, rtt_idx:0 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:-jsf : Alloc sess plugin info for session 237853 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:[JSF]Normal interest check. regd plugins 19, enabled impl mask 0x0 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:-jsf int check: plugin id 2, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:-jsf int check: plugin id 3, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:-jsf int check: plugin id 5, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:-jsf int check: plugin id 6, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:-jsf int check: plugin id 7, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:-jsf int check: plugin id 8, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:-jsf int check: plugin id 12, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:-jsf int check: plugin id 15, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:+++++++++++jsf_test_plugin_data_evh: 3 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:-jsf int check: plugin id 16, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:-jsf int check: plugin id 22, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:-jsf int check: plugin id 23, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:-jsf int check: plugin id 26, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:-jsf int check: plugin id 27, svc_req 0x0, impl mask 0x0. rc 2 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:-jsf int check: plugin id 28, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:[JSF]Plugins(0x0, count 0) enabled for session = 0, impli mask(0x0), post_nat cnt 237853 svc req(0x0) Aug 22 05:44:02 05:44:02.770449:CID-0:RT:-jsf : no plugin interested for session 237853, free sess plugin info Aug 22 05:44:02 05:44:02.770449:CID-0:RT:flow_first_service_lookup(): natp(0x59efae60): app_id, 0(0). Aug 22 05:44:02 05:44:02.770449:CID-0:RT: service lookup identified service 0. Aug 22 05:44:02 05:44:02.770449:CID-0:RT: flow_first_final_check: in , out Aug 22 05:44:02 05:44:02.770449:CID-0:RT:flow_first_final_check: flow_set_xlate_vector. Aug 22 05:44:02 05:44:02.770449:CID-0:RT:flow_first_complete_session, pak_ptr: 0x4fa2a290, nsp: 0x59efae60, in_tunnel: 0x0 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:construct v4 vector for nsp2 Aug 22 05:44:02 05:44:02.770449:CID-0:RT: existing vector list 0x1200-0x48cf5910. Aug 22 05:44:02 05:44:02.770449:CID-0:RT: Session (id:237853) created for first pak 1200 Aug 22 05:44:02 05:44:02.770449:CID-0:RT: flow_first_install_session======> 0x59efae60 Aug 22 05:44:02 05:44:02.770449:CID-0:RT: nsp 0x59efae60, nsp2 0x59efaee0 Aug 22 05:44:02 05:44:02.770449:CID-0:RT: make_nsp_ready_no_resolve() Aug 22 05:44:02 05:44:02.770449:CID-0:RT: route lookup: dest-ip 192.168.1.2 orig ifp vlan.0 output_ifp vlan.0 orig-zone 6 out-zone 6 vsd 0 Aug 22 05:44:02 05:44:02.770449:CID-0:RT: route to 192.168.1.2 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:no need update ha Aug 22 05:44:02 05:44:02.770449:CID-0:RT:Installing c2s NP session wing Aug 22 05:44:02 05:44:02.770449:CID-0:RT:Installing s2c NP session wing Aug 22 05:44:02 05:44:02.770449:CID-0:RT: flow got session. Aug 22 05:44:02 05:44:02.770449:CID-0:RT: flow session id 237853 Aug 22 05:44:02 05:44:02.770449:CID-0:RT: vector bits 0x1200 vector 0x48cf5910 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:flow_xlate_pak Aug 22 05:44:02 05:44:02.770449:CID-0:RT:flow_handle_icmp_xlate Aug 22 05:44:02 05:44:02.770449:CID-0:RT:xlate_icmp_pak Aug 22 05:44:02 05:44:02.770449:CID-0:RT: post addr xlation: 115.64.136.32->8.8.8.8. Aug 22 05:44:02 05:44:02.770449:CID-0:RT: post addr xlation: 115.64.136.32->8.8.8.8. Aug 22 05:44:02 05:44:02.770449:CID-0:RT:skip pre-frag: is_tunnel_if- 0, is_if_mtu_configured- 0 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:mbuf 0x43676280, exit nh 0xfffa0006 Aug 22 05:44:02 05:44:02.770449:CID-0:RT: ----- flow_process_pkt rc 0x0 (fp rc 0) Aug 22 05:44:02 05:44:02.770449:CID-0:RT:nat_get_mapped_or_incoming_dip: no rule configured with lsys id 0 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:nat_get_mapped_or_incoming_dip: diff 0, calls 0 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:nat_get_rule_number: type 2, rule counter 0 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:nat_get_hipped_ip: no rule configured Aug 22 05:44:02 05:44:02.770449:CID-0:RT:nat xlate nsp 8.8.8.8/1 -> 115.64.136.32/16265 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:nat xlate nsp2 115.64.136.32/16265 -> 0.0.0.0/1 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:nat_src_rule_match: lsys 0, src-ip 8.8.8.8, dst-ip 115.64.136.32, dst-port 16265 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:nat_src_get_dip_id: rule NULL Aug 22 05:44:02 05:44:02.770449:CID-0:RT:nat xlate nsp 8.8.8.8/1 -> 115.64.136.32/16265 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:nat xlate nsp2 115.64.136.32/16265 -> 0.0.0.0/1 Aug 22 05:44:02 05:44:02.770449:CID-0:RT:nat_get_mipped_ip: no rule configured for lsys 0 Aug 22 05:44:02 05:44:02.853422:CID-0:RT:nat_get_mapped_or_incoming_dip: no rule configured with lsys id 0 Aug 22 05:44:02 05:44:02.853422:CID-0:RT:nat_get_mapped_or_incoming_dip: diff 0, calls 0 Aug 22 05:44:02 05:44:02.853422:CID-0:RT:nat_dst_xlate_ip_port: src-ip 192.168.1.2, dst-ip 37.48.66.130, dst_port 443 Aug 22 05:44:02 05:44:02.853422:CID-0:RT:nat_dst_rule_match: lsys 0, src-ip 192.168.1.2, dst-ip 37.48.66.130, dst-port 443 Aug 22 05:44:02 05:44:02.853422:CID-0:RT:nat_dst_xlate_ip_port: no rule found... Aug 22 05:44:02 05:44:02.853422:CID-0:RT:nat_get_rule_number: type 2, rule counter 0 Aug 22 05:44:02 05:44:02.853422:CID-0:RT:nat_get_hipped_ip: no rule configured Aug 22 05:44:02 05:44:02.853422:CID-0:RT:nat xlate nsp 192.168.1.2/64894 -> 37.48.66.130/443 Aug 22 05:44:02 05:44:02.853422:CID-0:RT:nat xlate nsp2 37.48.66.130/443 -> 0.0.0.0/64894 Aug 22 05:44:02 05:44:02.853422:CID-0:RT:nat_src_rule_match: lsys 0, src-ip 192.168.1.2, dst-ip 37.48.66.130, dst-port 443 Aug 22 05:44:02 05:44:02.853422:CID-0:RT:nat_proc_sorted_lists: src_min 1073741825, dst_min 1073741825 Aug 22 05:44:02 05:44:02.853422:CID-0:RT:nat_rule_match_ctx_n_port: protocol 6 Aug 22 05:44:02 05:44:02.853422:CID-0:RT:nat_rule_match_ctx_n_port: ctx-type 4, from-id 6, to-id 7 Aug 22 05:44:02 05:44:02.853422:CID-0:RT:nat_src_rule_match: src nat rule found: id 1, pos-id 1, pool id 2 Aug 22 05:44:02 05:44:02.853422:CID-0:RT:nat xlate nsp 192.168.1.2/64894 -> 37.48.66.130/443 Aug 22 05:44:02 05:44:02.853422:CID-0:RT:nat xlate nsp2 37.48.66.130/443 -> 0.0.0.0/64894 Aug 22 05:44:02 05:44:02.853422:CID-0:RT:nat_get_dip_by_host: pst_nat = False, lsys_id = 0, platform = 1, dip_id = 0x2,dst_port = 443, ptr = 0x4fa2a290, sess_id = 0x0 Aug 22 05:44:02 05:44:02.853422:CID-0:RT:nat_get_dip_by_host:src_ip 192.168.1.2 dst_ip 37.48.66.130 Aug 22 05:44:02 05:44:02.853422:CID-0:RT:nat_get_local_dip_by_host: src nat ip/port is allocated locally. src_port 64894 Aug 22 05:44:02 05:44:02.853422:CID-0:RT:nat_get_dip_by_host_cp: lsys id: 0, src_port 0, dip id: 0x2 Aug 22 05:44:02 05:44:02.853422:CID-0:RT:nat_get_dip_by_host_cp:src_ip 192.168.1.2 dst_ip 37.48.66.130 Aug 22 05:44:02 05:44:02.853422:CID-0:RT:nat_get_dip_by_host_cp: get xlated ip/port (115.64.136.32/13604), xlation flag (1), xlation pool id (0x2) from JSF Aug 22 05:44:02 05:44:02.853422:CID-0:RT:nat_get_mipped_ip: no rule configured for lsys 0 Aug 22 05:44:03 05:44:02.997935:CID-0:RT:flow_nat_release_dip_by_session_with_natp: sid 237757, src dip id 2, dst dip id 0, pst nat: 0, src pst nat: 0, dst pst nat:0 Aug 22 05:44:03 05:44:02.997935:CID-0:RT:flow_nat_release_dip_by_session_with_natp: Normal dip release: sid 237757, dip id 2, pst dst dip id 0 Aug 22 05:44:03 05:44:02.997935:CID-0:RT:nat_release_dip_by_session: release source nat [lsysid:0]: pst_nat = 0x0, nat_info = 0x59ef04c8, did = 2, pport = 11687, port-num 1flag = 0x0, on platform 1, lsys 0, Aug 22 05:44:03 05:44:02.997935:CID-0:RT:nat_release_dip_by_session:src_ip 192.168.1.2 dip 115.64.136.32 dst_ip 8.8.8.8 Aug 22 05:44:03 05:44:02.997935:CID-0:RT:nat_release_local_dip_by_session: Release source nat [0]: did=2 pport=11687 Aug 22 05:44:03 05:44:02.997935:CID-0:RT:nat_release_local_dip_by_session:src_ip 192.168.1.2 dip 115.64.136.32 dst_ip 8.8.8.8 Aug 22 05:44:04 05:44:04.038609:CID-0:RT:nat_get_mapped_or_incoming_dip: no rule configured with lsys id 0 Aug 22 05:44:04 05:44:04.038609:CID-0:RT:nat_get_mapped_or_incoming_dip: diff 0, calls 0 Aug 22 05:44:04 05:44:04.038609:CID-0:RT:nat_dst_xlate_ip_port: src-ip 192.168.1.21, dst-ip 255.255.255.255, dst_port 10001 Aug 22 05:44:04 05:44:04.038609:CID-0:RT:nat_dst_rule_match: lsys 0, src-ip 192.168.1.21, dst-ip 255.255.255.255, dst-port 10001 Aug 22 05:44:04 05:44:04.038609:CID-0:RT:nat_dst_xlate_ip_port: no rule found... Aug 22 05:44:04 05:44:04.038609:CID-0:RT:nat_get_mapped_or_incoming_dip: no rule configured with lsys id 0 Aug 22 05:44:04 05:44:04.038609:CID-0:RT:nat_get_mapped_or_incoming_dip: diff 0, calls 0 Aug 22 05:44:04 05:44:04.038609:CID-0:RT:nat_dst_xlate_ip_port: src-ip 192.168.1.21, dst-ip 255.255.255.255, dst_port 10001 Aug 22 05:44:04 05:44:04.038609:CID-0:RT:nat_dst_rule_match: lsys 0, src-ip 192.168.1.21, dst-ip 255.255.255.255, dst-port 10001 Aug 22 05:44:04 05:44:04.038609:CID-0:RT:nat_dst_xlate_ip_port: no rule found... Aug 22 05:44:06 05:44:06.016070:CID-0:RT:scan hash table with index 177 Aug 22 05:44:06 05:44:06.016070:CID-0:RT:scan hash table with index 178 Aug 22 05:44:06 05:44:06.016070:CID-0:RT:scan hash table with index 179 Aug 22 05:44:06 05:44:06.016070:CID-0:RT:scan hash table with index 180 Aug 22 05:44:06 05:44:06.016070:CID-0:RT:scan hash table with index 181 Aug 22 05:44:06 05:44:06.016070:CID-0:RT:scan hash table with index 182 Aug 22 05:44:06 05:44:06.016070:CID-0:RT:scan hash table with index 183 Aug 22 05:44:06 05:44:06.016070:CID-0:RT:scan hash table with index 184 Aug 22 05:44:06 05:44:06.016070:CID-0:RT:scan hash table with index 185 Aug 22 05:44:06 05:44:06.016070:CID-0:RT:scan hash table with index 186 Aug 22 05:44:06 05:44:06.016070:CID-0:RT:scan hash table with index 187 Aug 22 05:44:06 05:44:06.016070:CID-0:RT:scan hash table with index 188 Aug 22 05:44:06 05:44:06.016070:CID-0:RT:scan hash table with index 189 Aug 22 05:44:06 05:44:06.016070:CID-0:RT:scan hash table with index 190 Aug 22 05:44:06 05:44:06.016070:CID-0:RT:scan hash table with index 191 Aug 22 05:44:06 05:44:06.016070:CID-0:RT:scan hash table with index 192 Aug 22 05:44:07 05:44:06.988536:CID-0:RT: Error : get sess plugin info 0x59ef0fd8 Aug 22 05:44:07 05:44:06.988536:CID-0:RT:avt_get_app_name(): Getting appid failed. Aug 22 05:44:07 05:44:06.988536:CID-0:RT: Error : get sess plugin info 0x59ef0fd8 Aug 22 05:44:07 05:44:06.988536:CID-0:RT:jsf sess close notify Aug 22 05:44:07 05:44:06.988536:CID-0:RT:flow_ipv4_del_flow: sess 237764, in hash 32 Aug 22 05:44:07 05:44:06.988536:CID-0:RT:flow_ipv4_del_flow: sess 237764, in hash 32 Aug 22 05:44:07 05:44:06.988536:CID-0:RT:flow_nat_release_dip_by_session_with_natp: sid 237156, src dip id 2, dst dip id 0, pst nat: 0, src pst nat: 0, dst pst nat:0 Aug 22 05:44:07 05:44:06.988536:CID-0:RT:flow_nat_release_dip_by_session_with_natp: Normal dip release: sid 237156, dip id 2, pst dst dip id 0 Aug 22 05:44:07 05:44:06.988536:CID-0:RT:nat_release_dip_by_session: release source nat [lsysid:0]: pst_nat = 0x0, nat_info = 0x59ead640, did = 2, pport = 2725, port-num 1flag = 0x0, on platform 1, lsys 0, Aug 22 05:44:07 05:44:06.988536:CID-0:RT:nat_release_dip_by_session:src_ip 192.168.1.7 dip 115.64.136.32 dst_ip 66.211.179.149 Aug 22 05:44:07 05:44:06.988536:CID-0:RT:nat_release_local_dip_by_session: Release source nat [0]: did=2 pport=2725 Aug 22 05:44:07 05:44:06.988536:CID-0:RT:nat_release_local_dip_by_session:src_ip 192.168.1.7 dip 115.64.136.32 dst_ip 66.211.179.149 Aug 22 05:44:07 05:44:06.988536:CID-0:RT:flow_nat_release_dip_by_session_with_natp: sid 237831, src dip id 2, dst dip id 0, pst nat: 0, src pst nat: 0, dst pst nat:0 Aug 22 05:44:07 05:44:06.988536:CID-0:RT:flow_nat_release_dip_by_session_with_natp: Normal dip release: sid 237831, dip id 2, pst dst dip id 0 Aug 22 05:44:07 05:44:06.988536:CID-0:RT:nat_release_dip_by_session: release source nat [lsysid:0]: pst_nat = 0x0, nat_info = 0x59ef8898, did = 2, pport = 15512, port-num 1flag = 0x0, on platform 1, lsys 0, Aug 22 05:44:07 05:44:06.988536:CID-0:RT:nat_release_dip_by_session:src_ip 192.168.1.2 dip 115.64.136.32 dst_ip 37.48.66.130 Aug 22 05:44:07 05:44:06.988536:CID-0:RT:nat_release_local_dip_by_session: Release source nat [0]: did=2 pport=15512 Aug 22 05:44:07 05:44:06.988536:CID-0:RT:nat_release_local_dip_by_session:src_ip 192.168.1.2 dip 115.64.136.32 dst_ip 37.48.66.130 Aug 22 05:44:07 05:44:07.000029:CID-0:RT:nat_get_mapped_or_incoming_dip: no rule configured with lsys id 0 Aug 22 05:44:07 05:44:07.000029:CID-0:RT:nat_get_mapped_or_incoming_dip: diff 0, calls 0 Aug 22 05:44:07 05:44:07.000029:CID-0:RT:nat_dst_xlate_ip_port: src-ip 192.168.1.13, dst-ip 192.168.1.1, dst_port 53 Aug 22 05:44:07 05:44:07.000029:CID-0:RT:nat_dst_rule_match: lsys 0, src-ip 192.168.1.13, dst-ip 192.168.1.1, dst-port 53 Aug 22 05:44:07 05:44:07.000029:CID-0:RT:nat_dst_xlate_ip_port: no rule found... Aug 22 05:44:07 05:44:07.000029:CID-0:RT:nat_get_rule_number: type 2, rule counter 0 Aug 22 05:44:07 05:44:07.000029:CID-0:RT:nat_get_hipped_ip: no rule configured Aug 22 05:44:07 05:44:07.000029:CID-0:RT:nat xlate nsp 192.168.1.13/49094 -> 192.168.1.1/53 Aug 22 05:44:07 05:44:07.000029:CID-0:RT:nat xlate nsp2 192.168.1.1/53 -> 0.0.0.0/49094 Aug 22 05:44:07 05:44:07.000029:CID-0:RT:nat_src_rule_match: lsys 0, src-ip 192.168.1.13, dst-ip 192.168.1.1, dst-port 53 Aug 22 05:44:07 05:44:07.000029:CID-0:RT:nat_proc_sorted_lists: src_min 1073741825, dst_min 1073741825 Aug 22 05:44:07 05:44:07.000029:CID-0:RT:nat_rule_match_ctx_n_port: protocol 17 Aug 22 05:44:07 05:44:07.000029:CID-0:RT:nat_rule_match_ctx_n_port: ctx-type 4, from-id 6, to-id 2 Aug 22 05:44:07 05:44:07.000029:CID-0:RT:nat_src_get_dip_id: rule NULL Aug 22 05:44:07 05:44:07.000029:CID-0:RT:nat xlate nsp 192.168.1.13/49094 -> 192.168.1.1/53 Aug 22 05:44:07 05:44:07.000029:CID-0:RT:nat xlate nsp2 192.168.1.1/53 -> 0.0.0.0/49094 Aug 22 05:44:07 05:44:07.000029:CID-0:RT:nat_get_mipped_ip: no rule configured for lsys 0 Aug 22 05:44:07 05:44:07.108167:CID-0:RT:nat_get_mapped_or_incoming_dip: no rule configured with lsys id 0 Aug 22 05:44:07 05:44:07.108167:CID-0:RT:nat_get_mapped_or_incoming_dip: diff 0, calls 0 Aug 22 05:44:07 05:44:07.108167:CID-0:RT:nat_dst_xlate_ip_port: src-ip 192.168.1.7, dst-ip 207.46.11.151, dst_port 443 Aug 22 05:44:07 05:44:07.108167:CID-0:RT:nat_dst_rule_match: lsys 0, src-ip 192.168.1.7, dst-ip 207.46.11.151, dst-port 443 Aug 22 05:44:07 05:44:07.108167:CID-0:RT:nat_dst_xlate_ip_port: no rule found... Aug 22 05:44:07 05:44:07.108167:CID-0:RT:nat_get_rule_number: type 2, rule counter 0 Aug 22 05:44:07 05:44:07.108167:CID-0:RT:nat_get_hipped_ip: no rule configured Aug 22 05:44:07 05:44:07.108167:CID-0:RT:nat xlate nsp 192.168.1.7/57554 -> 207.46.11.151/443 Aug 22 05:44:07 05:44:07.108167:CID-0:RT:nat xlate nsp2 207.46.11.151/443 -> 0.0.0.0/57554 Aug 22 05:44:07 05:44:07.108167:CID-0:RT:nat_src_rule_match: lsys 0, src-ip 192.168.1.7, dst-ip 207.46.11.151, dst-port 443 Aug 22 05:44:07 05:44:07.108167:CID-0:RT:nat_proc_sorted_lists: src_min 1073741825, dst_min 1073741825 Aug 22 05:44:07 05:44:07.108167:CID-0:RT:nat_rule_match_ctx_n_port: protocol 6 Aug 22 05:44:07 05:44:07.108167:CID-0:RT:nat_rule_match_ctx_n_port: ctx-type 4, from-id 6, to-id 7 Aug 22 05:44:07 05:44:07.108167:CID-0:RT:nat_src_rule_match: src nat rule found: id 1, pos-id 1, pool id 2 Aug 22 05:44:07 05:44:07.108167:CID-0:RT:nat xlate nsp 192.168.1.7/57554 -> 207.46.11.151/443 Aug 22 05:44:07 05:44:07.108167:CID-0:RT:nat xlate nsp2 207.46.11.151/443 -> 0.0.0.0/57554 Aug 22 05:44:07 05:44:07.108167:CID-0:RT:nat_get_dip_by_host: pst_nat = False, lsys_id = 0, platform = 1, dip_id = 0x2,dst_port = 443, ptr = 0x4fa2a290, sess_id = 0x0 Aug 22 05:44:07 05:44:07.108167:CID-0:RT:nat_get_dip_by_host:src_ip 192.168.1.7 dst_ip 207.46.11.151 Aug 22 05:44:07 05:44:07.108167:CID-0:RT:nat_get_local_dip_by_host: src nat ip/port is allocated locally. src_port 57554 Aug 22 05:44:07 05:44:07.108167:CID-0:RT:nat_get_dip_by_host_cp: lsys id: 0, src_port 0, dip id: 0x2 Aug 22 05:44:07 05:44:07.108167:CID-0:RT:nat_get_dip_by_host_cp:src_ip 192.168.1.7 dst_ip 207.46.11.151 Aug 22 05:44:07 05:44:07.108167:CID-0:RT:nat_get_dip_by_host_cp: get xlated ip/port (115.64.136.32/14158), xlation flag (1), xlation pool id (0x2) from JSF Aug 22 05:44:07 05:44:07.108167:CID-0:RT:nat_get_mipped_ip: no rule configured for lsys 0 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:<192.168.1.2/4171->8.8.8.8/1;1> matched filter MatchTraffic: Aug 22 05:44:07 05:44:07.770796:CID-0:RT:packet [60] ipid = 17569, @0x43666e1a Aug 22 05:44:07 05:44:07.770796:CID-0:RT:---- flow_process_pkt: (thd 1): flow_ctxt type 15, common flag 0x0, mbuf 0x43666c00, rtbl_idx = 0 Aug 22 05:44:07 05:44:07.770796:CID-0:RT: flow process pak fast ifl 82 in_ifp vlan.0 Aug 22 05:44:07 05:44:07.770796:CID-0:RT: vlan.0:192.168.1.2->8.8.8.8, icmp, (8/0) Aug 22 05:44:07 05:44:07.770796:CID-0:RT: find flow: table 0x4f9a9078, hash 1514(0xffff), sa 192.168.1.2, da 8.8.8.8, sp 4171, dp 1, proto 1, tok 6 Aug 22 05:44:07 05:44:07.770796:CID-0:RT: no session found, start first path. in_tunnel - 0x0, from_cp_flag - 0 Aug 22 05:44:07 05:44:07.770796:CID-0:RT: flow_first_create_session Aug 22 05:44:07 05:44:07.770796:CID-0:RT: flow_first_in_dst_nat: in , out dst_adr 8.8.8.8, sp 4171, dp 1 Aug 22 05:44:07 05:44:07.770796:CID-0:RT: chose interface vlan.0 as incoming nat if. Aug 22 05:44:07 05:44:07.770796:CID-0:RT:nat_get_mapped_or_incoming_dip: no rule configured with lsys id 0 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:nat_get_mapped_or_incoming_dip: diff 0, calls 0 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:nat_dst_xlate_ip_port: src-ip 192.168.1.2, dst-ip 8.8.8.8, dst_port 1 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:nat_dst_rule_match: lsys 0, src-ip 192.168.1.2, dst-ip 8.8.8.8, dst-port 1 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:nat_dst_xlate_ip_port: no rule found... Aug 22 05:44:07 05:44:07.770796:CID-0:RT:flow_first_rule_dst_xlate: DST no-xlate: 0.0.0.0(0) to 8.8.8.8(1) Aug 22 05:44:07 05:44:07.770796:CID-0:RT:flow_first_routing: vr_id 0, call flow_route_lookup(): src_ip 192.168.1.2, x_dst_ip 8.8.8.8, in ifp vlan.0, out ifp N/A sp 4171, dp 1, ip_proto 1, tos 0 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:Doing DESTINATION addr route-lookup Aug 22 05:44:07 05:44:07.770796:CID-0:RT: routed (x_dst_ip 8.8.8.8) from trust (vlan.0 in 0) to pp0.0, Next-hop: 8.8.8.8 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:flow_first_policy_search: policy search from zone trust-> zone untrust (0x0,0x104b0001,0x1) Aug 22 05:44:07 05:44:07.770796:CID-0:RT:Policy lkup: vsys 0 zone(6:trust) -> zone(7:untrust) scope:0 Aug 22 05:44:07 05:44:07.770796:CID-0:RT: 192.168.1.2/2048 -> 8.8.8.8/15632 proto 1 Aug 22 05:44:07 05:44:07.770796:CID-0:RT: app 0, timeout 60s, curr ageout 60s Aug 22 05:44:07 05:44:07.770796:CID-0:RT: permitted by policy default-permit(6) Aug 22 05:44:07 05:44:07.770796:CID-0:RT: packet passed, Permitted by policy. Aug 22 05:44:07 05:44:07.770796:CID-0:RT:nat_get_rule_number: type 2, rule counter 0 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:nat_get_hipped_ip: no rule configured Aug 22 05:44:07 05:44:07.770796:CID-0:RT:flow_first_src_xlate: nat_src_xlated: False, nat_src_xlate_failed: False Aug 22 05:44:07 05:44:07.770796:CID-0:RT:nat xlate nsp 192.168.1.2/4171 -> 8.8.8.8/1 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:nat xlate nsp2 8.8.8.8/1 -> 0.0.0.0/4171 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:nat_src_rule_match: lsys 0, src-ip 192.168.1.2, dst-ip 8.8.8.8, dst-port 1 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:nat_proc_sorted_lists: src_min 1073741825, dst_min 1073741825 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:nat_rule_match_ctx_n_port: protocol 1 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:nat_rule_match_ctx_n_port: ctx-type 4, from-id 6, to-id 7 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:nat_src_rule_match: src nat rule found: id 1, pos-id 1, pool id 2 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:flow_first_src_xlate: src nat returns status: 1, rule/pool id: 1/2, pst_nat: False. Aug 22 05:44:07 05:44:07.770796:CID-0:RT:nat xlate nsp 192.168.1.2/4171 -> 8.8.8.8/1 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:nat xlate nsp2 8.8.8.8/1 -> 0.0.0.0/4171 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:nat_get_dip_by_host: pst_nat = False, lsys_id = 0, platform = 1, dip_id = 0x2,dst_port = 1, ptr = 0x4fa2a290, sess_id = 0x0 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:nat_get_dip_by_host:src_ip 192.168.1.2 dst_ip 8.8.8.8 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:nat_get_local_dip_by_host: src nat ip/port is allocated locally. src_port 4171 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:nat_get_dip_by_host_cp: lsys id: 0, src_port 0, dip id: 0x2 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:nat_get_dip_by_host_cp:src_ip 192.168.1.2 dst_ip 8.8.8.8 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:nat_get_dip_by_host_cp: get xlated ip/port (115.64.136.32/31697), xlation flag (1), xlation pool id (0x2) from JSF Aug 22 05:44:07 05:44:07.770796:CID-0:RT: dip id = 2/0, 192.168.1.2/4171->115.64.136.32/31697 protocol 58 Aug 22 05:44:07 05:44:07.770796:CID-0:RT: choose interface pp0.0 as outgoing phy if Aug 22 05:44:07 05:44:07.770796:CID-0:RT:nat_get_mipped_ip: no rule configured for lsys 0 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:is_loop_pak: No loop: on ifp: pp0.0, addr: 8.8.8.8, rtt_idx:0 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:-jsf : Alloc sess plugin info for session 237860 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:[JSF]Normal interest check. regd plugins 19, enabled impl mask 0x0 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:-jsf int check: plugin id 2, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:-jsf int check: plugin id 3, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:-jsf int check: plugin id 5, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:-jsf int check: plugin id 6, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:-jsf int check: plugin id 7, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:-jsf int check: plugin id 8, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:-jsf int check: plugin id 12, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:-jsf int check: plugin id 15, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:+++++++++++jsf_test_plugin_data_evh: 3 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:-jsf int check: plugin id 16, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:-jsf int check: plugin id 22, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:-jsf int check: plugin id 23, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:-jsf int check: plugin id 26, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:-jsf int check: plugin id 27, svc_req 0x0, impl mask 0x0. rc 2 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:-jsf int check: plugin id 28, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:[JSF]Plugins(0x0, count 0) enabled for session = 0, impli mask(0x0), post_nat cnt 237860 svc req(0x0) Aug 22 05:44:07 05:44:07.770796:CID-0:RT:-jsf : no plugin interested for session 237860, free sess plugin info Aug 22 05:44:07 05:44:07.770796:CID-0:RT:flow_first_service_lookup(): natp(0x59efbad8): app_id, 0(0). Aug 22 05:44:07 05:44:07.770796:CID-0:RT: service lookup identified service 0. Aug 22 05:44:07 05:44:07.770796:CID-0:RT: flow_first_final_check: in , out Aug 22 05:44:07 05:44:07.770796:CID-0:RT:flow_first_final_check: flow_set_xlate_vector. Aug 22 05:44:07 05:44:07.770796:CID-0:RT:flow_first_complete_session, pak_ptr: 0x4fa2a290, nsp: 0x59efbad8, in_tunnel: 0x0 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:construct v4 vector for nsp2 Aug 22 05:44:07 05:44:07.770796:CID-0:RT: existing vector list 0x1200-0x48cf5910. Aug 22 05:44:07 05:44:07.770796:CID-0:RT: Session (id:237860) created for first pak 1200 Aug 22 05:44:07 05:44:07.770796:CID-0:RT: flow_first_install_session======> 0x59efbad8 Aug 22 05:44:07 05:44:07.770796:CID-0:RT: nsp 0x59efbad8, nsp2 0x59efbb58 Aug 22 05:44:07 05:44:07.770796:CID-0:RT: make_nsp_ready_no_resolve() Aug 22 05:44:07 05:44:07.770796:CID-0:RT: route lookup: dest-ip 192.168.1.2 orig ifp vlan.0 output_ifp vlan.0 orig-zone 6 out-zone 6 vsd 0 Aug 22 05:44:07 05:44:07.770796:CID-0:RT: route to 192.168.1.2 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:no need update ha Aug 22 05:44:07 05:44:07.770796:CID-0:RT:Installing c2s NP session wing Aug 22 05:44:07 05:44:07.770796:CID-0:RT:Installing s2c NP session wing Aug 22 05:44:07 05:44:07.770796:CID-0:RT: flow got session. Aug 22 05:44:07 05:44:07.770796:CID-0:RT: flow session id 237860 Aug 22 05:44:07 05:44:07.770796:CID-0:RT: vector bits 0x1200 vector 0x48cf5910 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:flow_xlate_pak Aug 22 05:44:07 05:44:07.770796:CID-0:RT:flow_handle_icmp_xlate Aug 22 05:44:07 05:44:07.770796:CID-0:RT:xlate_icmp_pak Aug 22 05:44:07 05:44:07.770796:CID-0:RT: post addr xlation: 115.64.136.32->8.8.8.8. Aug 22 05:44:07 05:44:07.770796:CID-0:RT: post addr xlation: 115.64.136.32->8.8.8.8. Aug 22 05:44:07 05:44:07.770796:CID-0:RT:skip pre-frag: is_tunnel_if- 0, is_if_mtu_configured- 0 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:mbuf 0x43666c00, exit nh 0xfffa0006 Aug 22 05:44:07 05:44:07.770796:CID-0:RT: ----- flow_process_pkt rc 0x0 (fp rc 0) Aug 22 05:44:07 05:44:07.770796:CID-0:RT:nat_get_mapped_or_incoming_dip: no rule configured with lsys id 0 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:nat_get_mapped_or_incoming_dip: diff 0, calls 0 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:nat_get_rule_number: type 2, rule counter 0 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:nat_get_hipped_ip: no rule configured Aug 22 05:44:07 05:44:07.770796:CID-0:RT:nat xlate nsp 8.8.8.8/1 -> 115.64.136.32/31697 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:nat xlate nsp2 115.64.136.32/31697 -> 0.0.0.0/1 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:nat_src_rule_match: lsys 0, src-ip 8.8.8.8, dst-ip 115.64.136.32, dst-port 31697 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:nat_src_get_dip_id: rule NULL Aug 22 05:44:07 05:44:07.770796:CID-0:RT:nat xlate nsp 8.8.8.8/1 -> 115.64.136.32/31697 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:nat xlate nsp2 115.64.136.32/31697 -> 0.0.0.0/1 Aug 22 05:44:07 05:44:07.770796:CID-0:RT:nat_get_mipped_ip: no rule configured for lsys 0 Aug 22 05:44:08 05:44:08.036397:CID-0:RT:nat_get_mapped_or_incoming_dip: no rule configured with lsys id 0 Aug 22 05:44:08 05:44:08.036397:CID-0:RT:nat_get_mapped_or_incoming_dip: diff 0, calls 0 Aug 22 05:44:08 05:44:08.036397:CID-0:RT:nat_dst_xlate_ip_port: src-ip 192.168.1.2, dst-ip 37.48.66.130, dst_port 443 Aug 22 05:44:08 05:44:08.036397:CID-0:RT:nat_dst_rule_match: lsys 0, src-ip 192.168.1.2, dst-ip 37.48.66.130, dst-port 443 Aug 22 05:44:08 05:44:08.036397:CID-0:RT:nat_dst_xlate_ip_port: no rule found... Aug 22 05:44:08 05:44:08.036397:CID-0:RT:nat_get_rule_number: type 2, rule counter 0 Aug 22 05:44:08 05:44:08.036397:CID-0:RT:nat_get_hipped_ip: no rule configured Aug 22 05:44:08 05:44:08.036397:CID-0:RT:nat xlate nsp 192.168.1.2/64896 -> 37.48.66.130/443 Aug 22 05:44:08 05:44:08.036397:CID-0:RT:nat xlate nsp2 37.48.66.130/443 -> 0.0.0.0/64896 Aug 22 05:44:08 05:44:08.036397:CID-0:RT:nat_src_rule_match: lsys 0, src-ip 192.168.1.2, dst-ip 37.48.66.130, dst-port 443 Aug 22 05:44:08 05:44:08.036397:CID-0:RT:nat_proc_sorted_lists: src_min 1073741825, dst_min 1073741825 Aug 22 05:44:08 05:44:08.036397:CID-0:RT:nat_rule_match_ctx_n_port: protocol 6 Aug 22 05:44:08 05:44:08.036397:CID-0:RT:nat_rule_match_ctx_n_port: ctx-type 4, from-id 6, to-id 7 Aug 22 05:44:08 05:44:08.036397:CID-0:RT:nat_src_rule_match: src nat rule found: id 1, pos-id 1, pool id 2 Aug 22 05:44:08 05:44:08.036397:CID-0:RT:nat xlate nsp 192.168.1.2/64896 -> 37.48.66.130/443 Aug 22 05:44:08 05:44:08.036397:CID-0:RT:nat xlate nsp2 37.48.66.130/443 -> 0.0.0.0/64896 Aug 22 05:44:08 05:44:08.036397:CID-0:RT:nat_get_dip_by_host: pst_nat = False, lsys_id = 0, platform = 1, dip_id = 0x2,dst_port = 443, ptr = 0x4fa2a290, sess_id = 0x0 Aug 22 05:44:08 05:44:08.036397:CID-0:RT:nat_get_dip_by_host:src_ip 192.168.1.2 dst_ip 37.48.66.130 Aug 22 05:44:08 05:44:08.036397:CID-0:RT:nat_get_local_dip_by_host: src nat ip/port is allocated locally. src_port 64896 Aug 22 05:44:08 05:44:08.036397:CID-0:RT:nat_get_dip_by_host_cp: lsys id: 0, src_port 0, dip id: 0x2 Aug 22 05:44:08 05:44:08.036397:CID-0:RT:nat_get_dip_by_host_cp:src_ip 192.168.1.2 dst_ip 37.48.66.130 Aug 22 05:44:08 05:44:08.036397:CID-0:RT:nat_get_dip_by_host_cp: get xlated ip/port (115.64.136.32/9549), xlation flag (1), xlation pool id (0x2) from JSF Aug 22 05:44:08 05:44:08.036397:CID-0:RT:nat_get_mipped_ip: no rule configured for lsys 0 Aug 22 05:44:09 05:44:08.990419:CID-0:RT:flow_nat_release_dip_by_session_with_natp: sid 237764, src dip id 2, dst dip id 0, pst nat: 0, src pst nat: 0, dst pst nat:0 Aug 22 05:44:09 05:44:08.990419:CID-0:RT:flow_nat_release_dip_by_session_with_natp: Normal dip release: sid 237764, dip id 2, pst dst dip id 0 Aug 22 05:44:09 05:44:08.990419:CID-0:RT:nat_release_dip_by_session: release source nat [lsysid:0]: pst_nat = 0x0, nat_info = 0x59ef1140, did = 2, pport = 29585, port-num 1flag = 0x0, on platform 1, lsys 0, Aug 22 05:44:09 05:44:08.990419:CID-0:RT:nat_release_dip_by_session:src_ip 192.168.1.2 dip 115.64.136.32 dst_ip 8.8.8.8 Aug 22 05:44:09 05:44:08.990419:CID-0:RT:nat_release_local_dip_by_session: Release source nat [0]: did=2 pport=29585 Aug 22 05:44:09 05:44:08.990419:CID-0:RT:nat_release_local_dip_by_session:src_ip 192.168.1.2 dip 115.64.136.32 dst_ip 8.8.8.8 Aug 22 05:44:09 05:44:08.990419:CID-0:RT:flow_nat_release_dip_by_session_with_natp: sid 237852, src dip id 2, dst dip id 0, pst nat: 0, src pst nat: 0, dst pst nat:0 Aug 22 05:44:09 05:44:08.990419:CID-0:RT:flow_nat_release_dip_by_session_with_natp: Normal dip release: sid 237852, dip id 2, pst dst dip id 0 Aug 22 05:44:09 05:44:08.990419:CID-0:RT:nat_release_dip_by_session: release source nat [lsysid:0]: pst_nat = 0x0, nat_info = 0x59efae00, did = 2, pport = 22938, port-num 1flag = 0x0, on platform 1, lsys 0, Aug 22 05:44:09 05:44:08.990419:CID-0:RT:nat_release_dip_by_session:src_ip 192.168.1.2 dip 115.64.136.32 dst_ip 37.48.66.130 Aug 22 05:44:09 05:44:08.990419:CID-0:RT:nat_release_local_dip_by_session: Release source nat [0]: did=2 pport=22938 Aug 22 05:44:09 05:44:08.990419:CID-0:RT:nat_release_local_dip_by_session:src_ip 192.168.1.2 dip 115.64.136.32 dst_ip 37.48.66.130 Aug 22 05:44:09 05:44:09.603567:CID-0:RT:nat_get_mapped_or_incoming_dip: no rule configured with lsys id 0 Aug 22 05:44:09 05:44:09.603567:CID-0:RT:nat_get_mapped_or_incoming_dip: diff 0, calls 0 Aug 22 05:44:09 05:44:09.603567:CID-0:RT:nat_dst_xlate_ip_port: src-ip 192.168.1.2, dst-ip 136.147.56.133, dst_port 443 Aug 22 05:44:09 05:44:09.603567:CID-0:RT:nat_dst_rule_match: lsys 0, src-ip 192.168.1.2, dst-ip 136.147.56.133, dst-port 443 Aug 22 05:44:09 05:44:09.603567:CID-0:RT:nat_dst_xlate_ip_port: no rule found... Aug 22 05:44:09 05:44:09.603567:CID-0:RT:nat_get_rule_number: type 2, rule counter 0 Aug 22 05:44:09 05:44:09.603567:CID-0:RT:nat_get_hipped_ip: no rule configured Aug 22 05:44:09 05:44:09.603567:CID-0:RT:nat xlate nsp 192.168.1.2/64897 -> 136.147.56.133/443 Aug 22 05:44:09 05:44:09.603567:CID-0:RT:nat xlate nsp2 136.147.56.133/443 -> 0.0.0.0/64897 Aug 22 05:44:09 05:44:09.603567:CID-0:RT:nat_src_rule_match: lsys 0, src-ip 192.168.1.2, dst-ip 136.147.56.133, dst-port 443 Aug 22 05:44:09 05:44:09.603567:CID-0:RT:nat_proc_sorted_lists: src_min 1073741825, dst_min 1073741825 Aug 22 05:44:09 05:44:09.603567:CID-0:RT:nat_rule_match_ctx_n_port: protocol 6 Aug 22 05:44:09 05:44:09.603567:CID-0:RT:nat_rule_match_ctx_n_port: ctx-type 4, from-id 6, to-id 7 Aug 22 05:44:09 05:44:09.603567:CID-0:RT:nat_src_rule_match: src nat rule found: id 1, pos-id 1, pool id 2 Aug 22 05:44:09 05:44:09.603567:CID-0:RT:nat xlate nsp 192.168.1.2/64897 -> 136.147.56.133/443 Aug 22 05:44:09 05:44:09.603567:CID-0:RT:nat xlate nsp2 136.147.56.133/443 -> 0.0.0.0/64897 Aug 22 05:44:09 05:44:09.603567:CID-0:RT:nat_get_dip_by_host: pst_nat = False, lsys_id = 0, platform = 1, dip_id = 0x2,dst_port = 443, ptr = 0x4fa2a290, sess_id = 0x0 Aug 22 05:44:09 05:44:09.603567:CID-0:RT:nat_get_dip_by_host:src_ip 192.168.1.2 dst_ip 136.147.56.133 Aug 22 05:44:09 05:44:09.603567:CID-0:RT:nat_get_local_dip_by_host: src nat ip/port is allocated locally. src_port 64897 Aug 22 05:44:09 05:44:09.603567:CID-0:RT:nat_get_dip_by_host_cp: lsys id: 0, src_port 0, dip id: 0x2 Aug 22 05:44:09 05:44:09.603567:CID-0:RT:nat_get_dip_by_host_cp:src_ip 192.168.1.2 dst_ip 136.147.56.133 Aug 22 05:44:09 05:44:09.603567:CID-0:RT:nat_get_dip_by_host_cp: get xlated ip/port (115.64.136.32/17952), xlation flag (1), xlation pool id (0x2) from JSF Aug 22 05:44:09 05:44:09.603567:CID-0:RT:nat_get_mipped_ip: no rule configured for lsys 0 Aug 22 05:44:11 05:44:10.990690:CID-0:RT: Error : get sess plugin info 0x59ef1a88 Aug 22 05:44:11 05:44:10.990690:CID-0:RT:avt_get_app_name(): Getting appid failed. Aug 22 05:44:11 05:44:10.990690:CID-0:RT: Error : get sess plugin info 0x59ef1a88 Aug 22 05:44:11 05:44:10.990690:CID-0:RT:jsf sess close notify Aug 22 05:44:11 05:44:10.990690:CID-0:RT:flow_ipv4_del_flow: sess 237770, in hash 32 Aug 22 05:44:11 05:44:10.990690:CID-0:RT:flow_ipv4_del_flow: sess 237770, in hash 32 Aug 22 05:44:11 05:44:10.990690:CID-0:RT:flow_nat_release_dip_by_session_with_natp: sid 237011, src dip id 2, dst dip id 0, pst nat: 0, src pst nat: 0, dst pst nat:0 Aug 22 05:44:11 05:44:10.990690:CID-0:RT:flow_nat_release_dip_by_session_with_natp: Normal dip release: sid 237011, dip id 2, pst dst dip id 0 Aug 22 05:44:11 05:44:10.990690:CID-0:RT:nat_release_dip_by_session: release source nat [lsysid:0]: pst_nat = 0x0, nat_info = 0x59e9d3f8, did = 2, pport = 7146, port-num 1flag = 0x0, on platform 1, lsys 0, Aug 22 05:44:11 05:44:10.990690:CID-0:RT:nat_release_dip_by_session:src_ip 192.168.1.7 dip 115.64.136.32 dst_ip 207.46.11.151 Aug 22 05:44:11 05:44:10.990690:CID-0:RT:nat_release_local_dip_by_session: Release source nat [0]: did=2 pport=7146 Aug 22 05:44:11 05:44:10.990690:CID-0:RT:nat_release_local_dip_by_session:src_ip 192.168.1.7 dip 115.64.136.32 dst_ip 207.46.11.151 Aug 22 05:44:12 05:44:12.009726:CID-0:RT:nat_get_mapped_or_incoming_dip: no rule configured with lsys id 0 Aug 22 05:44:12 05:44:12.009726:CID-0:RT:nat_get_mapped_or_incoming_dip: diff 0, calls 0 Aug 22 05:44:12 05:44:12.009726:CID-0:RT:nat_dst_xlate_ip_port: src-ip 192.168.1.13, dst-ip 192.168.1.1, dst_port 53 Aug 22 05:44:12 05:44:12.009726:CID-0:RT:nat_dst_rule_match: lsys 0, src-ip 192.168.1.13, dst-ip 192.168.1.1, dst-port 53 Aug 22 05:44:12 05:44:12.009726:CID-0:RT:nat_dst_xlate_ip_port: no rule found... Aug 22 05:44:12 05:44:12.009726:CID-0:RT:nat_get_rule_number: type 2, rule counter 0 Aug 22 05:44:12 05:44:12.009726:CID-0:RT:nat_get_hipped_ip: no rule configured Aug 22 05:44:12 05:44:12.009726:CID-0:RT:nat xlate nsp 192.168.1.13/56486 -> 192.168.1.1/53 Aug 22 05:44:12 05:44:12.009726:CID-0:RT:nat xlate nsp2 192.168.1.1/53 -> 0.0.0.0/56486 Aug 22 05:44:12 05:44:12.009726:CID-0:RT:nat_src_rule_match: lsys 0, src-ip 192.168.1.13, dst-ip 192.168.1.1, dst-port 53 Aug 22 05:44:12 05:44:12.009726:CID-0:RT:nat_proc_sorted_lists: src_min 1073741825, dst_min 1073741825 Aug 22 05:44:12 05:44:12.009726:CID-0:RT:nat_rule_match_ctx_n_port: protocol 17 Aug 22 05:44:12 05:44:12.009726:CID-0:RT:nat_rule_match_ctx_n_port: ctx-type 4, from-id 6, to-id 2 Aug 22 05:44:12 05:44:12.009726:CID-0:RT:nat_src_get_dip_id: rule NULL Aug 22 05:44:12 05:44:12.009726:CID-0:RT:nat xlate nsp 192.168.1.13/56486 -> 192.168.1.1/53 Aug 22 05:44:12 05:44:12.009726:CID-0:RT:nat xlate nsp2 192.168.1.1/53 -> 0.0.0.0/56486 Aug 22 05:44:12 05:44:12.009726:CID-0:RT:nat_get_mipped_ip: no rule configured for lsys 0 Aug 22 05:44:12 05:44:12.013390:CID-0:RT:scan hash table with index 193 Aug 22 05:44:12 05:44:12.013390:CID-0:RT:scan hash table with index 194 Aug 22 05:44:12 05:44:12.013390:CID-0:RT:scan hash table with index 195 Aug 22 05:44:12 05:44:12.013390:CID-0:RT:scan hash table with index 196 Aug 22 05:44:12 05:44:12.013390:CID-0:RT:scan hash table with index 197 Aug 22 05:44:12 05:44:12.013390:CID-0:RT:scan hash table with index 198 Aug 22 05:44:12 05:44:12.013390:CID-0:RT:scan hash table with index 199 Aug 22 05:44:12 05:44:12.013390:CID-0:RT:scan hash table with index 200 Aug 22 05:44:12 05:44:12.013390:CID-0:RT:scan hash table with index 201 Aug 22 05:44:12 05:44:12.013390:CID-0:RT:scan hash table with index 202 Aug 22 05:44:12 05:44:12.013390:CID-0:RT:scan hash table with index 203 Aug 22 05:44:12 05:44:12.013390:CID-0:RT:scan hash table with index 204 Aug 22 05:44:12 05:44:12.013390:CID-0:RT:scan hash table with index 205 Aug 22 05:44:12 05:44:12.013390:CID-0:RT:scan hash table with index 206 Aug 22 05:44:12 05:44:12.013390:CID-0:RT:scan hash table with index 207 Aug 22 05:44:12 05:44:12.013390:CID-0:RT:scan hash table with index 208 Aug 22 05:44:12 05:44:12.746499:CID-0:RT:nat_get_mapped_or_incoming_dip: no rule configured with lsys id 0 Aug 22 05:44:12 05:44:12.746499:CID-0:RT:nat_get_mapped_or_incoming_dip: diff 0, calls 0 Aug 22 05:44:12 05:44:12.746499:CID-0:RT:nat_dst_xlate_ip_port: src-ip 128.8.124.7, dst-ip 115.64.136.32, dst_port 8333 Aug 22 05:44:12 05:44:12.746499:CID-0:RT:nat_dst_rule_match: lsys 0, src-ip 128.8.124.7, dst-ip 115.64.136.32, dst-port 8333 Aug 22 05:44:12 05:44:12.746499:CID-0:RT:nat_proc_sorted_lists: src_min 268435457, dst_min 268435457 Aug 22 05:44:12 05:44:12.746499:CID-0:RT:nat_rule_match_ctx_n_port: protocol 6 Aug 22 05:44:12 05:44:12.746499:CID-0:RT:nat_dst_xlate_ip_port: no rule found... Aug 22 05:44:12 05:44:12.771119:CID-0:RT:<192.168.1.2/4172->8.8.8.8/1;1> matched filter MatchTraffic: Aug 22 05:44:12 05:44:12.771119:CID-0:RT:packet [60] ipid = 17601, @0x4368749a Aug 22 05:44:12 05:44:12.771119:CID-0:RT:---- flow_process_pkt: (thd 1): flow_ctxt type 15, common flag 0x0, mbuf 0x43687280, rtbl_idx = 0 Aug 22 05:44:12 05:44:12.771119:CID-0:RT: flow process pak fast ifl 82 in_ifp vlan.0 Aug 22 05:44:12 05:44:12.771119:CID-0:RT: vlan.0:192.168.1.2->8.8.8.8, icmp, (8/0) Aug 22 05:44:12 05:44:12.771119:CID-0:RT: find flow: table 0x4f9a9078, hash 9466(0xffff), sa 192.168.1.2, da 8.8.8.8, sp 4172, dp 1, proto 1, tok 6 Aug 22 05:44:12 05:44:12.771119:CID-0:RT: no session found, start first path. in_tunnel - 0x0, from_cp_flag - 0 Aug 22 05:44:12 05:44:12.771119:CID-0:RT: flow_first_create_session Aug 22 05:44:12 05:44:12.771119:CID-0:RT: flow_first_in_dst_nat: in , out dst_adr 8.8.8.8, sp 4172, dp 1 Aug 22 05:44:12 05:44:12.771119:CID-0:RT: chose interface vlan.0 as incoming nat if. Aug 22 05:44:12 05:44:12.771119:CID-0:RT:nat_get_mapped_or_incoming_dip: no rule configured with lsys id 0 Aug 22 05:44:12 05:44:12.771119:CID-0:RT:nat_get_mapped_or_incoming_dip: diff 0, calls 0 Aug 22 05:44:12 05:44:12.771119:CID-0:RT:nat_dst_xlate_ip_port: src-ip 192.168.1.2, dst-ip 8.8.8.8, dst_port 1 Aug 22 05:44:12 05:44:12.771119:CID-0:RT:nat_dst_rule_match: lsys 0, src-ip 192.168.1.2, dst-ip 8.8.8.8, dst-port 1 Aug 22 05:44:12 05:44:12.771119:CID-0:RT:nat_dst_xlate_ip_port: no rule found... Aug 22 05:44:12 05:44:12.771119:CID-0:RT:flow_first_rule_dst_xlate: DST no-xlate: 0.0.0.0(0) to 8.8.8.8(1) Aug 22 05:44:12 05:44:12.771119:CID-0:RT:flow_first_routing: vr_id 0, call flow_route_lookup(): src_ip 192.168.1.2, x_dst_ip 8.8.8.8, in ifp vlan.0, out ifp N/A sp 4172, dp 1, ip_proto 1, tos 0 Aug 22 05:44:12 05:44:12.771119:CID-0:RT:Doing DESTINATION addr route-lookup Aug 22 05:44:12 05:44:12.771119:CID-0:RT: routed (x_dst_ip 8.8.8.8) from trust (vlan.0 in 0) to pp0.0, Next-hop: 8.8.8.8 Aug 22 05:44:12 05:44:12.771119:CID-0:RT:flow_first_policy_search: policy search from zone trust-> zone untrust (0x0,0x104c0001,0x1) Aug 22 05:44:12 05:44:12.771119:CID-0:RT:Policy lkup: vsys 0 zone(6:trust) -> zone(7:untrust) scope:0 Aug 22 05:44:12 05:44:12.771119:CID-0:RT: 192.168.1.2/2048 -> 8.8.8.8/15631 proto 1 Aug 22 05:44:12 05:44:12.771119:CID-0:RT: app 0, timeout 60s, curr ageout 60s Aug 22 05:44:12 05:44:12.771119:CID-0:RT: permitted by policy default-permit(6) Aug 22 05:44:12 05:44:12.771119:CID-0:RT: packet passed, Permitted by policy. Aug 22 05:44:12 05:44:12.771119:CID-0:RT:nat_get_rule_number: type 2, rule counter 0 Aug 22 05:44:12 05:44:12.771119:CID-0:RT:nat_get_hipped_ip: no rule configured Aug 22 05:44:12 05:44:12.771119:CID-0:RT:flow_first_src_xlate: nat_src_xlated: False, nat_src_xlate_failed: False Aug 22 05:44:12 05:44:12.771119:CID-0:RT:nat xlate nsp 192.168.1.2/4172 -> 8.8.8.8/1 Aug 22 05:44:12 05:44:12.771119:CID-0:RT:nat xlate nsp2 8.8.8.8/1 -> 0.0.0.0/4172 Aug 22 05:44:12 05:44:12.771119:CID-0:RT:nat_src_rule_match: lsys 0, src-ip 192.168.1.2, dst-ip 8.8.8.8, dst-port 1 Aug 22 05:44:12 05:44:12.771119:CID-0:RT:nat_proc_sorted_lists: src_min 1073741825, dst_min 1073741825 Aug 22 05:44:12 05:44:12.771119:CID-0:RT:nat_rule_match_ctx_n_port: protocol 1 Aug 22 05:44:12 05:44:12.771119:CID-0:RT:nat_rule_match_ctx_n_port: ctx-type 4, from-id 6, to-id 7 Aug 22 05:44:12 05:44:12.771119:CID-0:RT:nat_src_rule_match: src nat rule found: id 1, pos-id 1, pool id 2 Aug 22 05:44:12 05:44:12.771119:CID-0:RT:flow_first_src_xlate: src nat returns status: 1, rule/pool id: 1/2, pst_nat: False. Aug 22 05:44:12 05:44:12.771119:CID-0:RT:nat xlate nsp 192.168.1.2/4172 -> 8.8.8.8/1 Aug 22 05:44:12 05:44:12.771119:CID-0:RT:nat xlate nsp2 8.8.8.8/1 -> 0.0.0.0/4172 Aug 22 05:44:12 05:44:12.771119:CID-0:RT:nat_get_dip_by_host: pst_nat = False, lsys_id = 0, platform = 1, dip_id = 0x2,dst_port = 1, ptr = 0x4fa2a290, sess_id = 0x0 Aug 22 05:44:12 05:44:12.771119:CID-0:RT:nat_get_dip_by_host:src_ip 192.168.1.2 dst_ip 8.8.8.8 Aug 22 05:44:12 05:44:12.771119:CID-0:RT:nat_get_local_dip_by_host: src nat ip/port is allocated locally. src_port 4172 Aug 22 05:44:12 05:44:12.771119:CID-0:RT:nat_get_dip_by_host_cp: lsys id: 0, src_port 0, dip id: 0x2 Aug 22 05:44:12 05:44:12.771119:CID-0:RT:nat_get_dip_by_host_cp:src_ip 192.168.1.2 dst_ip 8.8.8.8 Aug 22 05:44:12 05:44:12.771119:CID-0:RT:nat_get_dip_by_host_cp: get xlated ip/port (115.64.136.32/22159), xlation flag (1), xlation pool id (0x2) from JSF Aug 22 05:44:12 05:44:12.771119:CID-0:RT: dip id = 2/0, 192.168.1.2/4172->115.64.136.32/22159 protocol 58 Aug 22 05:44:12 05:44:12.771119:CID-0:RT: choose interface pp0.0 as outgoing phy if Aug 22 05:44:12 05:44:12.771119:CID-0:RT:nat_get_mipped_ip: no rule configured for lsys 0 Aug 22 05:44:12 05:44:12.771119:CID-0:RT:is_loop_pak: No loop: on ifp: pp0.0, addr: 8.8.8.8, rtt_idx:0 Aug 22 05:44:12 05:44:12.771119:CID-0:RT:-jsf : Alloc sess plugin info for session 237866 Aug 22 05:44:12 05:44:12.771119:CID-0:RT:[JSF]Normal interest check. regd plugins 19, enabled impl mask 0x0 Aug 22 05:44:12 05:44:12.771119:CID-0:RT:-jsf int check: plugin id 2, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:44:12 05:44:12.771119:CID-0:RT:-jsf int check: plugin id 3, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:44:12 05:44:12.771119:CID-0:RT:-jsf int check: plugin id 5, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:44:12 05:44:12.771119:CID-0:RT:-jsf int check: plugin id 6, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:44:12 05:44:12.771119:CID-0:RT:-jsf int check: plugin id 7, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:44:12 05:44:12.771119:CID-0:RT:-jsf int check: plugin id 8, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:44:12 05:44:12.771119:CID-0:RT:-jsf int check: plugin id 12, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:44:12 05:44:12.771119:CID-0:RT:-jsf int check: plugin id 15, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:44:12 05:44:12.771119:CID-0:RT:+++++++++++jsf_test_plugin_data_evh: 3 Aug 22 05:44:12 05:44:12.771119:CID-0:RT:-jsf int check: plugin id 16, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:44:12 05:44:12.771119:CID-0:RT:-jsf int check: plugin id 22, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:44:12 05:44:12.771119:CID-0:RT:-jsf int check: plugin id 23, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:44:12 05:44:12.771119:CID-0:RT:-jsf int check: plugin id 26, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:44:12 05:44:12.771119:CID-0:RT:-jsf int check: plugin id 27, svc_req 0x0, impl mask 0x0. rc 2 Aug 22 05:44:12 05:44:12.771119:CID-0:RT:-jsf int check: plugin id 28, svc_req 0x0, impl mask 0x0. rc 4 Aug 22 05:44:12 05:44:12.771119:CID-0:RT:[JSF]Plugins(0x0, count 0) enabled for session = 0, impli mask(0x0), post_nat cnt 237866 svc req(0x0) Aug 22 05:44:12 05:44:12.771119:CID-0:RT:-jsf : no plugin interested for session 237866, free sess plugin info Aug 22 05:44:12 05:44:12.771119:CID-0:RT:flow_first_service_lookup(): natp(0x59efc588): app_id, 0(0). Aug 22 05:44:12 05:44:12.771119:CID-0:RT: service lookup identified service 0. Aug 22 05:44:12 05:44:12.771119:CID-0:RT: flow_first_final_check: in , out Aug 22 05:44:12 05:44:12.771119:CID-0:RT:flow_first_final_check: flow_set_xlate_vector. Aug 22 05:44:12 05:44:12.771119:CID-0:RT:flow_first_complete_session, pak_ptr: 0x4fa2a290, nsp: 0x59efc588, in_tunnel: 0x0 Aug 22 05:44:12 05:44:12.771119:CID-0:RT:construct v4 vector for nsp2 Aug 22 05:44:12 05:44:12.771119:CID-0:RT: existing vector list 0x1200-0x48cf5910. Aug 22 05:44:12 05:44:12.771119:CID-0:RT: Session (id:237866) created for first pak 1200 Aug 22 05:44:12 05:44:12.771119:CID-0:RT: flow_first_install_session======> 0x59efc588 Aug 22 05:44:12 05:44:12.771119:CID-0:RT: nsp 0x59efc588, nsp2 0x59efc608 Aug 22 05:44:12 05:44:12.771119:CID-0:RT: make_nsp_ready_no_resolve() Aug 22 05:44:12 05:44:12.771119:CID-0:RT: route lookup: dest-ip 192.168.1.2 orig ifp vlan.0 output_ifp vlan.0 orig-zone 6 out-zone 6 vsd 0 Aug 22 05:44:12 05:44:12.771119:CID-0:RT: route to 192.168.1.2 Aug 22 05:44:12 05:44:12.771119:CID-0:RT:no need update ha Aug 22 05:44:12 05:44:12.771119:CID-0:RT:Installing c2s NP session wing Aug 22 05:44:12 05:44:12.771119:CID-0:RT:Installing s2c NP session wing Aug 22 05:44:12 05:44:12.771119:CID-0:RT: flow got session. Aug 22 05:44:12 05:44:12.771119:CID-0:RT: flow session id 237866 Aug 22 05:44:12 05:44:12.771119:CID-0:RT: vector bits 0x1200 vector 0x48cf5910 Aug 22 05:44:12 05:44:12.771119:CID-0:RT:flow_xlate_pak Aug 22 05:44:13 05:44:12.771119:CID-0:RT:flow_handle_icmp_xlate Aug 22 05:44:13 05:44:12.771119:CID-0:RT:xlate_icmp_pak Aug 22 05:44:13 05:44:12.771119:CID-0:RT: post addr xlation: 115.64.136.32->8.8.8.8. Aug 22 05:44:13 05:44:12.771119:CID-0:RT: post addr xlation: 115.64.136.32->8.8.8.8. Aug 22 05:44:13 05:44:12.771119:CID-0:RT:skip pre-frag: is_tunnel_if- 0, is_if_mtu_configured- 0 Aug 22 05:44:13 05:44:12.771119:CID-0:RT:mbuf 0x43687280, exit nh 0xfffa0006 Aug 22 05:44:13 05:44:12.771119:CID-0:RT: ----- flow_process_pkt rc 0x0 (fp rc 0) Aug 22 05:44:13 05:44:12.771119:CID-0:RT:nat_get_mapped_or_incoming_dip: no rule configured with lsys id 0 Aug 22 05:44:13 05:44:12.771119:CID-0:RT:nat_get_mapped_or_incoming_dip: diff 0, calls 0 Aug 22 05:44:13 05:44:12.771119:CID-0:RT:nat_get_rule_number: type 2, rule counter 0 Aug 22 05:44:13 05:44:12.771119:CID-0:RT:nat_get_hipped_ip: no rule configured Aug 22 05:44:13 05:44:12.771119:CID-0:RT:nat xlate nsp 8.8.8.8/1 -> 115.64.136.32/22159 Aug 22 05:44:13 05:44:12.771119:CID-0:RT:nat xlate nsp2 115.64.136.32/22159 -> 0.0.0.0/1 Aug 22 05:44:13 05:44:12.771119:CID-0:RT:nat_src_rule_match: lsys 0, src-ip 8.8.8.8, dst-ip 115.64.136.32, dst-port 22159 Aug 22 05:44:13 05:44:12.771119:CID-0:RT:nat_src_get_dip_id: rule NULL Aug 22 05:44:13 05:44:12.771119:CID-0:RT:nat xlate nsp 8.8.8.8/1 -> 115.64.136.32/22159 Aug 22 05:44:13 05:44:12.771119:CID-0:RT:nat xlate nsp2 115.64.136.32/22159 -> 0.0.0.0/1 Aug 22 05:44:13 05:44:12.771119:CID-0:RT:nat_get_mipped_ip: no rule configured for lsys 0 Aug 22 05:44:13 05:44:12.989902:CID-0:RT:flow_nat_release_dip_by_session_with_natp: sid 237770, src dip id 2, dst dip id 0, pst nat: 0, src pst nat: 0, dst pst nat:0 Aug 22 05:44:13 05:44:12.989902:CID-0:RT:flow_nat_release_dip_by_session_with_natp: Normal dip release: sid 237770, dip id 2, pst dst dip id 0 Aug 22 05:44:13 05:44:12.989902:CID-0:RT:nat_release_dip_by_session: release source nat [lsysid:0]: pst_nat = 0x0, nat_info = 0x59ef1bf0, did = 2, pport = 5932, port-num 1flag = 0x0, on platform 1, lsys 0, Aug 22 05:44:13 05:44:12.989902:CID-0:RT:nat_release_dip_by_session:src_ip 192.168.1.2 dip 115.64.136.32 dst_ip 8.8.8.8 Aug 22 05:44:13 05:44:12.989902:CID-0:RT:nat_release_local_dip_by_session: Release source nat [0]: did=2 pport=5932 Aug 22 05:44:13 05:44:12.989902:CID-0:RT:nat_release_local_dip_by_session:src_ip 192.168.1.2 dip 115.64.136.32 dst_ip 8.8.8.8 Aug 22 05:44:13 05:44:12.989902:CID-0:RT:flow_nat_release_dip_by_session_with_natp: sid 237862, src dip id 2, dst dip id 0, pst nat: 0, src pst nat: 0, dst pst nat:0 Aug 22 05:44:13 05:44:12.989902:CID-0:RT:flow_nat_release_dip_by_session_with_natp: Normal dip release: sid 237862, dip id 2, pst dst dip id 0 Aug 22 05:44:13 05:44:12.989902:CID-0:RT:nat_release_dip_by_session: release source nat [lsysid:0]: pst_nat = 0x0, nat_info = 0x59efbfd0, did = 2, pport = 9549, port-num 1flag = 0x0, on platform 1, lsys 0, Aug 22 05:44:13 05:44:12.989902:CID-0:RT:nat_release_dip_by_session:src_ip 192.168.1.2 dip 115.64.136.32 dst_ip 37.48.66.130 Aug 22 05:44:13 05:44:12.989902:CID-0:RT:nat_release_local_dip_by_session: Release source nat [0]: did=2 pport=9549 Aug 22 05:44:13 05:44:12.989902:CID-0:RT:nat_release_local_dip_by_session:src_ip 192.168.1.2 dip 115.64.136.32 dst_ip 37.48.66.130 Aug 22 05:44:13 05:44:12.989902:CID-0:RT:flow_nat_release_dip_by_session_with_natp: sid 237863, src dip id 2, dst dip id 0, pst nat: 0, src pst nat: 0, dst pst nat:0 Aug 22 05:44:13 05:44:12.989902:CID-0:RT:flow_nat_release_dip_by_session_with_natp: Normal dip release: sid 237863, dip id 2, pst dst dip id 0 Aug 22 05:44:13 05:44:12.989902:CID-0:RT:nat_release_dip_by_session: release source nat [lsysid:0]: pst_nat = 0x0, nat_info = 0x59efc198, did = 2, pport = 17952, port-num 1flag = 0x0, on platform 1, lsys 0, Aug 22 05:44:13 05:44:12.989902:CID-0:RT:nat_release_dip_by_session:src_ip 192.168.1.2 dip 115.64.136.32 dst_ip 136.147.56.133 Aug 22 05:44:13 05:44:12.989902:CID-0:RT:nat_release_local_dip_by_session: Release source nat [0]: did=2 pport=17952 Aug 22 05:44:13 05:44:12.989902:CID-0:RT:nat_release_local_dip_by_session:src_ip 192.168.1.2 dip 115.64.136.32 dst_ip 136.147.56.133