May 27 01:49:58 01:49:58.013501:CID-0:RT:<10.10.10.1/37596->10.10.10.2/33434;17> matched filter f0: May 27 01:49:58 01:49:58.013501:CID-0:RT:packet [40] ipid = 37597, @430814d2 May 27 01:49:58 01:49:58.013501:CID-0:RT:---- flow_process_pkt: (thd 1): flow_ctxt type 0, common flag 0x0, mbuf 0x43081280, rtbl_idx = 6 May 27 01:49:58 01:49:58.013501:CID-0:RT: flow process pak fast ifl 79 in_ifp lt-0/0/0.101 May 27 01:49:58 01:49:58.013501:CID-0:RT: find flow: table 0x47cb0c78, hash 29041(0xffff), sa 10.10.10.1, da 10.10.10.2, sp 37596, dp 33434, proto 17, tok 24585 May 27 01:49:58 01:49:58.013501:CID-0:RT:check self-traffic on lt-0/0/0.101, in_tunnel 0x0 May 27 01:49:58 01:49:58.013501:CID-0:RT:retcode: 0x4 May 27 01:49:58 01:49:58.013501:CID-0:RT:pak_for_self : proto 17, dst port 33434, action 0x4 May 27 01:49:58 01:49:58.013501:CID-0:RT: flow_first_create_session May 27 01:49:58 01:49:58.013501:CID-0:RT:(flow_first_create_session) usp_tagged set session as mng session May 27 01:49:58 01:49:58.013501:CID-0:RT:Installing pending sess (3811) in ager May 27 01:49:58 01:49:58.013501:CID-0:RT:First path alloc and instl pending session, natp=0x4ad19210, id=3811 May 27 01:49:58 01:49:58.013501:CID-0:RT: flow_first_in_dst_nat: in , out dst_adr 10.10.10.2, sp 37596, dp 33434 May 27 01:49:58 01:49:58.013501:CID-0:RT: chose interface lt-0/0/0.101 as incoming nat if. May 27 01:49:58 01:49:58.013501:CID-0:RT:flow_first_rule_dst_xlate: DST no-xlate: 0.0.0.0(0) to 10.10.10.2(33434) May 27 01:49:58 01:49:58.013501:CID-0:RT:flow_first_routing: vr_id 6, call flow_route_lookup(): src_ip 10.10.10.1, x_dst_ip 10.10.10.2, in ifp lt-0/0/0.101, out ifp N/A sp 37596, dp 33434, ip_proto 17, tos 0 May 27 01:49:58 01:49:58.013501:CID-0:RT:Doing DESTINATION addr route-lookup May 27 01:49:58 01:49:58.013501:CID-0:RT:flow_rt_lkup in VR-id: 6 May 27 01:49:58 01:49:58.013501:CID-0:RT:flow_rt_lkup: Found route entry 0x0x4d386130,nh id 0x22c, out if 0x0 May 27 01:49:58 01:49:58.013501:CID-0:RT:flow_rt_lkup: get NH in local route May 27 01:49:58 01:49:58.013501:CID-0:RT:flow_rt_lkup: nh word 0xfffb0006 May 27 01:49:58 01:49:58.013501:CID-0:RT:flow_ipv4_rt_lkup success 10.10.10.2, iifl 0x4f, oifl 0x0 May 27 01:49:58 01:49:58.013501:CID-0:RT: routed (x_dst_ip 10.10.10.2) from TR2 (lt-0/0/0.101 in 0) to .local..6, Next-hop: 10.10.10.2 May 27 01:49:58 01:49:58.013501:CID-0:RT:flow_first_policy_search: policy search from zone TR2-> zone junos-host (0x0,0x92dc829a,0x829a) May 27 01:49:58 01:49:58.013501:CID-0:RT: app 0, timeout 60s, curr ageout 60s May 27 01:49:58 01:49:58.013501:CID-0:RT: permitted by policy self-traffic-policy(1) May 27 01:49:58 01:49:58.013501:CID-0:RT: packet passed, Permitted by policy. May 27 01:49:58 01:49:58.013501:CID-0:RT:flow_first_src_xlate: nat_src_xlated: False, nat_src_xlate_failed: False May 27 01:49:58 01:49:58.013501:CID-0:RT:flow_first_src_xlate: src nat returns status: 0, rule/pool id: 0/0, pst_nat: False. May 27 01:49:58 01:49:58.013501:CID-0:RT: dip id = 0/0, 10.10.10.1/37596->10.10.10.1/37596 protocol 0 May 27 01:49:58 01:49:58.013501:CID-0:RT: choose interface .local..6 as outgoing phy if May 27 01:49:58 01:49:58.013501:CID-0:RT:is_loop_pak: No loop: ifp doesnt match .local..6 vs looked-up: lt-0/0/0.101, addr: 10.10.10.2, rtt_idx: 6, addr_type:0x3 May 27 01:49:58 01:49:58.013501:CID-0:RT:-jsf : Alloc sess plugin info for session 12884905699 May 27 01:49:58 01:49:58.013501:CID-0:RT:[JSF]Normal interest check. regd plugins 18, enabled impl mask 0x0 May 27 01:49:58 01:49:58.013501:CID-0:RT:-jsf int check: plugin id 2, svc_req 0x0, impl mask 0x0. rc 4 May 27 01:49:58 01:49:58.013501:CID-0:RT:-jsf int check: plugin id 3, svc_req 0x0, impl mask 0x0. rc 4 May 27 01:49:58 01:49:58.013501:CID-0:RT:-jsf int check: plugin id 5, svc_req 0x0, impl mask 0x0. rc 4 May 27 01:49:58 01:49:58.013501:CID-0:RT:-jsf int check: plugin id 6, svc_req 0x0, impl mask 0x0. rc 4 May 27 01:49:58 01:49:58.013501:CID-0:RT:-jsf int check: plugin id 7, svc_req 0x0, impl mask 0x0. rc 4 May 27 01:49:58 01:49:58.013501:CID-0:RT:-jsf int check: plugin id 8, svc_req 0x0, impl mask 0x0. rc 4 May 27 01:49:58 01:49:58.013501:CID-0:RT:-jsf int check: plugin id 14, svc_req 0x0, impl mask 0x0. rc 4 May 27 01:49:58 01:49:58.013501:CID-0:RT:+++++++++++jsf_test_plugin_data_evh: 3 May 27 01:49:58 01:49:58.013501:CID-0:RT:-jsf int check: plugin id 15, svc_req 0x0, impl mask 0x0. rc 4 May 27 01:49:58 01:49:58.013501:CID-0:RT:-jsf int check: plugin id 21, svc_req 0x0, impl mask 0x0. rc 4 May 27 01:49:58 01:49:58.013501:CID-0:RT:-jsf int check: plugin id 22, svc_req 0x0, impl mask 0x0. rc 4 May 27 01:49:58 01:49:58.013501:CID-0:RT:-jsf int check: plugin id 25, svc_req 0x0, impl mask 0x0. rc 4 May 27 01:49:58 01:49:58.013501:CID-0:RT:-jsf int check: plugin id 26, svc_req 0x0, impl mask 0x0. rc 2 May 27 01:49:58 01:49:58.013501:CID-0:RT:-jsf int check: plugin id 27, svc_req 0x0, impl mask 0x0. rc 4 May 27 01:49:58 01:49:58.013501:CID-0:RT:[JSF]Plugins(0x0, count 0) enabled for session = 1268272252, impli mask(0x3), post_nat cnt 3811 svc req(0x0) May 27 01:49:58 01:49:58.013501:CID-0:RT:-jsf : no plugin interested for session 12884905699, free sess plugin info May 27 01:49:58 01:49:58.013501:CID-0:RT:flow_first_service_lookup(): natp(0x4ad19210): app_id, 0(0). May 27 01:49:58 01:49:58.013501:CID-0:RT: service lookup identified service 0. May 27 01:49:58 01:49:58.013501:CID-0:RT: flow_first_final_check: in , out <.local..6> May 27 01:49:58 01:49:58.013501:CID-0:RT:In flow_first_complete_session May 27 01:49:58 01:49:58.013501:CID-0:RT:flow_first_complete_session, pak_ptr: 0x47d31cd8, nsp: 0x4ad19210, in_tunnel: 0x0 May 27 01:49:58 01:49:58.013501:CID-0:RT:construct v4 vector for nsp2 May 27 01:49:58 01:49:58.013501:CID-0:RT: existing vector list 0-45885660. May 27 01:49:58 01:49:58.013501:CID-0:RT: Session (id:3811) created for first pak 0 May 27 01:49:58 01:49:58.013501:CID-0:RT:first pak processing successful May 27 01:49:58 01:49:58.013501:CID-0:RT: flow_first_install_session======> 0x4ad19210 May 27 01:49:58 01:49:58.013501:CID-0:RT: nsp 0x4ad19210, nsp2 0x4ad19290 May 27 01:49:58 01:49:58.013501:CID-0:RT: make_nsp_ready_no_resolve() May 27 01:49:58 01:49:58.013501:CID-0:RT:make_nsp_ready_no_resolve: Route Lookup for dest route. Src_ip 10.10.10.2 Dst_ip 10.10.10.1 protocal 17 if_id 1268272252 May 27 01:49:58 01:49:58.013501:CID-0:RT:flow_rt_lkup in VR-id: 6 May 27 01:49:58 01:49:58.013501:CID-0:RT:flow_rt_lkup: Found route entry 0x0x4d385fb0,nh id 0x25d, out if 0x4f May 27 01:49:58 01:49:58.013501:CID-0:RT:flow_rt_lkup: nh word 0xb0010 May 27 01:49:58 01:49:58.013501:CID-0:RT:flow_ipv4_rt_lkup success 10.10.10.1, iifl 0x4f, oifl 0x4f May 27 01:49:58 01:49:58.013501:CID-0:RT: route lookup: dest-ip 10.10.10.1 orig ifp lt-0/0/0.101 output_ifp lt-0/0/0.101 orig-zone 9 out-zone 9 vsd 0 May 27 01:49:58 01:49:58.013501:CID-0:RT: route to 10.10.10.1 May 27 01:49:58 01:49:58.013501:CID-0:RT:no need update ha May 27 01:49:58 01:49:58.013501:CID-0:RT:Installing c2s NP session wing May 27 01:49:58 01:49:58.013501:CID-0:RT: flow_spu_install_np_session: FLOW STUB May 27 01:49:58 01:49:58.013501:CID-0:RT:Installing s2c NP session wing May 27 01:49:58 01:49:58.013501:CID-0:RT: flow_spu_install_np_session: FLOW STUB May 27 01:49:58 01:49:58.013501:CID-0:RT:updating pending sess (3811) in ager May 27 01:49:58 01:49:58.013501:CID-0:RT:first path session installation succeeded May 27 01:49:58 01:49:58.013501:CID-0:RT: flow got session. May 27 01:49:58 01:49:58.013501:CID-0:RT: flow fast tcp/udp session id 3811 May 27 01:49:58 01:49:58.013501:CID-0:RT: vector bits 0x0 vector 0x45885660 May 27 01:49:58 01:49:58.013501:CID-0:RT:insert usp tag for vpn apps May 27 01:49:58 01:49:58.013501:CID-0:RT:mbuf 0x43081280, exit nh 0xfffb0006 May 27 01:49:58 01:49:58.013501:CID-0:RT: ----- flow_process_pkt rc 0x0 (fp rc 0) May 27 01:49:58 01:49:58.017142:CID-0:RT:<10.10.10.2/1->10.10.10.1/1;1> matched filter f0: May 27 01:49:58 01:49:58.017142:CID-0:RT:packet [56] ipid = 53914, @43080bd2 May 27 01:49:58 01:49:58.017142:CID-0:RT:---- flow_process_pkt: (thd 1): flow_ctxt type 0, common flag 0x0, mbuf 0x43080980, rtbl_idx = 5 May 27 01:49:58 01:49:58.017142:CID-0:RT: flow process pak fast ifl 85 in_ifp lt-0/0/0.100 May 27 01:49:58 01:49:58.017142:CID-0:RT: lt-0/0/0.100:10.10.10.2->10.10.10.1, icmp, (3/3) May 27 01:49:58 01:49:58.017142:CID-0:RT: find flow: table 0x47cb0c78, hash 1077(0xffff), sa 10.10.10.2, da 10.10.10.1, sp 33434, dp 37596, proto 17, tok 20488 May 27 01:49:58 01:49:58.017142:CID-0:RT: packet dropped, no session found for embedded icmp pak May 27 01:49:58 01:49:58.017142:CID-0:RT: flow find session returns error. May 27 01:49:58 01:49:58.017142:CID-0:RT: ----- flow_process_pkt rc 0x7 (fp rc -1)