show log TEST Oct 12 18:41:20 CS7-HQ-FW02 clear-log[21087]: logfile cleared Oct 12 18:41:50 18:41:50.942520:CID-0:RT:<10.83.30.12/46260->10.83.42.1/80;6,0x0> matched filter TEST: Oct 12 18:41:50 18:41:50.942520:CID-0:RT:packet [60] ipid = 4389, @0x43e73a1c Oct 12 18:41:50 18:41:50.942520:CID-0:RT:---- flow_process_pkt: (thd 1): flow_ctxt type 15, common flag 0x0, mbuf 0x43e73800, rtbl_idx = 7 Oct 12 18:41:50 18:41:50.942520:CID-0:RT: flow process pak fast ifl 78 in_ifp irb.30 Oct 12 18:41:50 18:41:50.942520:CID-0:RT: irb.30:10.83.30.12/46260->10.83.42.1/80, tcp, flag 2 syn Oct 12 18:41:50 18:41:50.942520:CID-0:RT: find flow: table 0x523a0c60, hash 62317(0xffff), sa 10.83.30.12, da 10.83.42.1, sp 46260, dp 80, proto 6, tok 8, conn-tag 0x00000000 Oct 12 18:41:50 18:41:50.942520:CID-0:RT: no session found, start first path. in_tunnel - 0x0, from_cp_flag - 0 Oct 12 18:41:50 18:41:50.942520:CID-0:RT: flow_first_create_session Oct 12 18:41:50 18:41:50.942520:CID-0:RT:Save init hash spu id 0 to nsp and nsp2! Oct 12 18:41:50 18:41:50.942520:CID-0:RT:First path alloc and instl pending session, natp=0x56d27e88, id=59165 Oct 12 18:41:50 18:41:50.942520:CID-0:RT: flow_first_in_dst_nat: in , out dst_adr 10.83.42.1, sp 46260, dp 80 Oct 12 18:41:50 18:41:50.942520:CID-0:RT: chose interface irb.30 as incoming nat if. Oct 12 18:41:50 18:41:50.942520:CID-0:RT:flow_first_rule_dst_xlate: DST no-xlate: 0.0.0.0(0) to 10.83.42.1(80) Oct 12 18:41:50 18:41:50.942520:CID-0:RT:[JSF] Do ingress interest check. regd ingress plugins(1) Oct 12 18:41:50 18:41:50.942520:CID-0:RT:[JSF][0]plugins(0x0) enabled for session = 21474895645 implicit mask(0x0), service request(0x0) Oct 12 18:41:50 18:41:50.942520:CID-0:RT:-jsf : no plugin ingress interested for session 21474895645 Oct 12 18:41:50 18:41:50.942520:CID-0:RT:flow_first_routing: vr_id 7, call flow_route_lookup(): src_ip 10.83.30.12, x_dst_ip 10.83.42.1, in ifp irb.30, out ifp N/A sp 46260, dp 80, ip_proto 6, tos 0 Oct 12 18:41:50 18:41:50.942520:CID-0:RT:Doing DESTINATION addr route-lookup Oct 12 18:41:50 18:41:50.942520:CID-0:RT:flow_ipv4_rt_lkup success 10.83.42.1, iifl 0x4e, oifl 0x4d Oct 12 18:41:50 18:41:50.942520:CID-0:RT: routed (x_dst_ip 10.83.42.1) from INSIDE (irb.30 in 0) to irb.22, Next-hop: 10.83.42.1 Oct 12 18:41:50 18:41:50.942520:CID-0:RT:flow_first_policy_search: policy search from zone INSIDE-> zone SERVERS (0x0,0xb4b40050,0x50) Oct 12 18:41:50 18:41:50.942520:CID-0:RT:Policy lkup: vsys 0 zone(8:INSIDE) -> zone(10:SERVERS) scope:0 Oct 12 18:41:50 18:41:50.942520:CID-0:RT: 10.83.30.12/46260 -> 10.83.42.1/80 proto 6 Oct 12 18:41:50 18:41:50.942520:CID-0:RT: policy has timeout 900 Oct 12 18:41:50 18:41:50.942520:CID-0:RT: app 6, timeout 1800s, curr ageout 20s Oct 12 18:41:50 18:41:50.942520:CID-0:RT: permitted by policy ARM02(11) Oct 12 18:41:50 18:41:50.942520:CID-0:RT: packet passed, Permitted by policy. Oct 12 18:41:50 18:41:50.942520:CID-0:RT:flow_first_src_xlate: nat_src_xlated: False, nat_src_xlate_failed: False Oct 12 18:41:50 18:41:50.942520:CID-0:RT:flow_first_src_xlate: incoming src port is : 46260. Oct 12 18:41:50 18:41:50.942520:CID-0:RT:flow_first_src_xlate: src nat returns status: 0, rule/pool id: 0/0, pst_nat: False. Oct 12 18:41:50 18:41:50.942520:CID-0:RT: dip id = 0/0, 10.83.30.12/46260->10.83.30.12/46260 protocol 0 Oct 12 18:41:50 18:41:50.942520:CID-0:RT: choose interface irb.22(P2P) as outgoing phy if Oct 12 18:41:50 18:41:50.942520:CID-0:RT:is_loop_pak: No loop: on ifp: irb.22, addr: 10.83.42.1, rtt_idx:0 Oct 12 18:41:50 18:41:50.942520:CID-0:RT:[JSF]Normal interest check. regd plugins 35, enabled impl mask 0x0 Oct 12 18:41:50 18:41:50.942520:CID-0:RT:get NULL sess plugin info 0x56d27e88 Oct 12 18:41:50 18:41:50.942520:CID-0:RT:get NULL sess plugin info 0x56d27e88 Oct 12 18:41:50 18:41:50.942520:CID-0:RT:get NULL sess plugin info 0x56d27e88 Oct 12 18:41:50 18:41:50.942520:CID-0:RT:get NULL sess plugin info 0x56d27e88 Oct 12 18:41:50 18:41:50.942520:CID-0:RT:get NULL sess plugin info 0x56d27e88 Oct 12 18:41:50 18:41:50.942520:CID-0:RT:get NULL sess plugin info 0x56d27e88 Oct 12 18:41:50 18:41:50.942520:CID-0:RT:+++++++++++jsf_test_plugin_data_evh: 3 Oct 12 18:41:50 18:41:50.942520:CID-0:RT:get NULL sess plugin info 0x56d27e88 Oct 12 18:41:50 18:41:50.942520:CID-0:RT:get NULL sess plugin info 0x56d27e88 Oct 12 18:41:50 18:41:50.942520:CID-0:RT:[JSF]Plugins(0x0, count 0) enabled for session = 21474895645, impli mask(0x0), post_nat cnt 0 svc req(0x0) Oct 12 18:41:50 18:41:50.942520:CID-0:RT:-jsf : no plugin interested for session 21474895645, free sess plugin info Oct 12 18:41:50 18:41:50.942520:CID-0:RT: service lookup identified service 6. Oct 12 18:41:50 18:41:50.942520:CID-0:RT: flow_first_final_check: in , out Oct 12 18:41:50 18:41:50.942520:CID-0:RT:In flow_first_complete_session Oct 12 18:41:50 18:41:50.942520:CID-0:RT:flow_first_complete_session, pak_ptr: 0x50e25170, nsp: 0x56d27e88, in_tunnel: 0x0 Oct 12 18:41:50 18:41:50.942520:CID-0:RT:construct v4 vector for nsp2 and nsp Oct 12 18:41:50 18:41:50.942520:CID-0:RT: existing vector list 0x2-0x4b1e2d48. Oct 12 18:41:50 18:41:50.942520:CID-0:RT: existing vector list 0x2-0x4b1e2d48. Oct 12 18:41:50 18:41:50.942520:CID-0:RT: Session (id:59165) created for first pak 2 Oct 12 18:41:50 18:41:50.942520:CID-0:RT:first pak processing successful Oct 12 18:41:50 18:41:50.942520:CID-0:RT: flow_first_install_session======> 0x56d27e88 Oct 12 18:41:50 18:41:50.942520:CID-0:RT: nsp 0x56d27e88, nsp2 0x56d27f18 Oct 12 18:41:50 18:41:50.942520:CID-0:RT: make_nsp_ready_no_resolve() Oct 12 18:41:50 18:41:50.942520:CID-0:RT:flow_ipv4_rt_lkup success 10.83.30.12, iifl 0x4e, oifl 0x4e Oct 12 18:41:50 18:41:50.942520:CID-0:RT: route lookup: dest-ip 10.83.30.12 orig ifp irb.30 output_ifp irb.30 orig-zone 8 out-zone 8 vsd 0 Oct 12 18:41:50 18:41:50.942520:CID-0:RT: route to 10.83.30.12 Oct 12 18:41:50 18:41:50.942520:CID-0:RT:no need update ha Oct 12 18:41:50 18:41:50.942520:CID-0:RT:Installing c2s NP session wing Oct 12 18:41:50 18:41:50.942520:CID-0:RT:Installing s2c NP session wing Oct 12 18:41:50 18:41:50.942520:CID-0:RT:first path session installation succeeded Oct 12 18:41:50 18:41:50.942520:CID-0:RT: flow got session. Oct 12 18:41:50 18:41:50.942520:CID-0:RT: flow session id 59165 Oct 12 18:41:50 18:41:50.942520:CID-0:RT: vector bits 0x2 vector 0x4b1e2d48 Oct 12 18:41:50 18:41:50.942520:CID-0:RT:flow_tcp_wsf_update: wsf 6 Oct 12 18:41:50 18:41:50.942520:CID-0:RT: tcp 3way refresh, is_half_open:0, tcp_proxy enabled:0, is_fwauth:0 Oct 12 18:41:50 18:41:50.942520:CID-0:RT:mbuf 0x43e73800, exit nh 0x250010 Oct 12 18:41:50 18:41:50.942520:CID-0:RT: ----- flow_process_pkt rc 0x0 (fp rc 0) Oct 12 18:41:50 18:41:50.943602:CID-0:RT:<10.83.42.1/80->10.83.30.12/46260;6,0x0> matched filter TEST: Oct 12 18:41:50 18:41:50.943602:CID-0:RT:packet [60] ipid = 0, @0x43e2149c Oct 12 18:41:50 18:41:50.943602:CID-0:RT:---- flow_process_pkt: (thd 1): flow_ctxt type 15, common flag 0x0, mbuf 0x43e21280, rtbl_idx = 0 Oct 12 18:41:50 18:41:50.943602:CID-0:RT: flow process pak fast ifl 77 in_ifp irb.22 Oct 12 18:41:50 18:41:50.943602:CID-0:RT: irb.22:10.83.42.1/80->10.83.30.12/46260, tcp, flag 12 syn ack Oct 12 18:41:50 18:41:50.943602:CID-0:RT: find flow: table 0x523a0c60, hash 25914(0xffff), sa 10.83.42.1, da 10.83.30.12, sp 80, dp 46260, proto 6, tok 10, conn-tag 0x00000000 Oct 12 18:41:50 18:41:50.943602:CID-0:RT:Found: session id 0xe71d. sess tok 10 Oct 12 18:41:50 18:41:50.943602:CID-0:RT: flow got session. Oct 12 18:41:50 18:41:50.943602:CID-0:RT: flow session id 59165 Oct 12 18:41:50 18:41:50.943602:CID-0:RT: vector bits 0x2 vector 0x4b1e2d48 Oct 12 18:41:50 18:41:50.943602:CID-0:RT:flow_tcp_wsf_update: wsf 7 Oct 12 18:41:50 18:41:50.943602:CID-0:RT: tcp 3way refresh, is_half_open:0, tcp_proxy enabled:0, is_fwauth:0 Oct 12 18:41:50 18:41:50.943602:CID-0:RT:mbuf 0x43e21280, exit nh 0x2c0010 Oct 12 18:41:50 18:41:50.943602:CID-0:RT: ----- flow_process_pkt rc 0x0 (fp rc 0) Oct 12 18:41:50 18:41:50.945315:CID-0:RT:<10.83.30.12/46260->10.83.42.1/80;6,0x0> matched filter TEST: Oct 12 18:41:50 18:41:50.945315:CID-0:RT:packet [52] ipid = 4390, @0x43de8f9c Oct 12 18:41:50 18:41:50.945315:CID-0:RT:---- flow_process_pkt: (thd 1): flow_ctxt type 15, common flag 0x0, mbuf 0x43de8d80, rtbl_idx = 7 Oct 12 18:41:50 18:41:50.945315:CID-0:RT: flow process pak fast ifl 78 in_ifp irb.30 Oct 12 18:41:50 18:41:50.945315:CID-0:RT: irb.30:10.83.30.12/46260->10.83.42.1/80, tcp, flag 10 Oct 12 18:41:50 18:41:50.945315:CID-0:RT: find flow: table 0x523a0c60, hash 62317(0xffff), sa 10.83.30.12, da 10.83.42.1, sp 46260, dp 80, proto 6, tok 8, conn-tag 0x00000000 Oct 12 18:41:50 18:41:50.945315:CID-0:RT:Found: session id 0xe71d. sess tok 8 Oct 12 18:41:50 18:41:50.945315:CID-0:RT: flow got session. Oct 12 18:41:50 18:41:50.945315:CID-0:RT: flow session id 59165 Oct 12 18:41:50 18:41:50.945315:CID-0:RT: vector bits 0x2 vector 0x4b1e2d48 Oct 12 18:41:50 18:41:50.945315:CID-0:RT: tcp seq check. Oct 12 18:41:50 18:41:50.945315:CID-0:RT: tcp 3way refresh, is_half_open:0, tcp_proxy enabled:0, is_fwauth:0 Oct 12 18:41:50 18:41:50.945315:CID-0:RT: refreshing session Oct 12 18:41:50 18:41:50.945315:CID-0:RT:mbuf 0x43de8d80, exit nh 0x250010 Oct 12 18:41:50 18:41:50.945315:CID-0:RT: ----- flow_process_pkt rc 0x0 (fp rc 0) Oct 12 18:41:50 18:41:50.946161:CID-0:RT:<10.83.30.12/46260->10.83.42.1/80;6,0x0> matched filter TEST: Oct 12 18:41:50 18:41:50.946161:CID-0:RT:packet [360] ipid = 4391, @0x43e08d9c Oct 12 18:41:50 18:41:50.946161:CID-0:RT:---- flow_process_pkt: (thd 1): flow_ctxt type 15, common flag 0x0, mbuf 0x43e08b80, rtbl_idx = 7 Oct 12 18:41:50 18:41:50.946161:CID-0:RT: flow process pak fast ifl 78 in_ifp irb.30 Oct 12 18:41:50 18:41:50.946161:CID-0:RT: irb.30:10.83.30.12/46260->10.83.42.1/80, tcp, flag 18 Oct 12 18:41:50 18:41:50.946161:CID-0:RT: find flow: table 0x523a0c60, hash 62317(0xffff), sa 10.83.30.12, da 10.83.42.1, sp 46260, dp 80, proto 6, tok 8, conn-tag 0x00000000 Oct 12 18:41:50 18:41:50.946161:CID-0:RT:Found: session id 0xe71d. sess tok 8 Oct 12 18:41:50 18:41:50.946161:CID-0:RT: flow got session. Oct 12 18:41:50 18:41:50.946161:CID-0:RT: flow session id 59165 Oct 12 18:41:50 18:41:50.946161:CID-0:RT: vector bits 0x2 vector 0x4b1e2d48 Oct 12 18:41:50 18:41:50.946161:CID-0:RT: tcp seq check. Oct 12 18:41:50 18:41:50.946161:CID-0:RT:mbuf 0x43e08b80, exit nh 0x250010 Oct 12 18:41:50 18:41:50.946161:CID-0:RT: ----- flow_process_pkt rc 0x0 (fp rc 0) Oct 12 18:41:50 18:41:50.946659:CID-0:RT:<10.83.42.1/80->10.83.30.12/46260;6,0x0> matched filter TEST: Oct 12 18:41:50 18:41:50.946659:CID-0:RT:packet [52] ipid = 26022, @0x43e1511c Oct 12 18:41:50 18:41:50.946659:CID-0:RT:---- flow_process_pkt: (thd 1): flow_ctxt type 15, common flag 0x0, mbuf 0x43e14f00, rtbl_idx = 0 Oct 12 18:41:50 18:41:50.946659:CID-0:RT: flow process pak fast ifl 77 in_ifp irb.22 Oct 12 18:41:50 18:41:50.946659:CID-0:RT: irb.22:10.83.42.1/80->10.83.30.12/46260, tcp, flag 10 Oct 12 18:41:50 18:41:50.946659:CID-0:RT: find flow: table 0x523a0c60, hash 25914(0xffff), sa 10.83.42.1, da 10.83.30.12, sp 80, dp 46260, proto 6, tok 10, conn-tag 0x00000000 Oct 12 18:41:50 18:41:50.946659:CID-0:RT:Found: session id 0xe71d. sess tok 10 Oct 12 18:41:50 18:41:50.946659:CID-0:RT: flow got session. Oct 12 18:41:50 18:41:50.946659:CID-0:RT: flow session id 59165 Oct 12 18:41:50 18:41:50.946659:CID-0:RT: vector bits 0x2 vector 0x4b1e2d48 Oct 12 18:41:50 18:41:50.946659:CID-0:RT: tcp seq check. Oct 12 18:41:50 18:41:50.946659:CID-0:RT:mbuf 0x43e14f00, exit nh 0x2c0010 Oct 12 18:41:50 18:41:50.946659:CID-0:RT: ----- flow_process_pkt rc 0x0 (fp rc 0) Oct 12 18:41:50 18:41:50.950380:CID-0:RT:<10.83.42.1/80->10.83.30.12/46260;6,0x0> matched filter TEST: Oct 12 18:41:50 18:41:50.950380:CID-0:RT:packet [599] ipid = 26023, @0x43e3711c Oct 12 18:41:50 18:41:50.950380:CID-0:RT:---- flow_process_pkt: (thd 1): flow_ctxt type 15, common flag 0x0, mbuf 0x43e36f00, rtbl_idx = 0 Oct 12 18:41:50 18:41:50.950380:CID-0:RT: flow process pak fast ifl 77 in_ifp irb.22 Oct 12 18:41:50 18:41:50.950380:CID-0:RT: irb.22:10.83.42.1/80->10.83.30.12/46260, tcp, flag 18 Oct 12 18:41:50 18:41:50.950380:CID-0:RT: find flow: table 0x523a0c60, hash 25914(0xffff), sa 10.83.42.1, da 10.83.30.12, sp 80, dp 46260, proto 6, tok 10, conn-tag 0x00000000 Oct 12 18:41:50 18:41:50.950380:CID-0:RT:Found: session id 0xe71d. sess tok 10 Oct 12 18:41:50 18:41:50.950380:CID-0:RT: flow got session. Oct 12 18:41:50 18:41:50.950380:CID-0:RT: flow session id 59165 Oct 12 18:41:50 18:41:50.950380:CID-0:RT: vector bits 0x2 vector 0x4b1e2d48 Oct 12 18:41:50 18:41:50.950380:CID-0:RT: tcp seq check. Oct 12 18:41:50 18:41:50.950380:CID-0:RT:mbuf 0x43e36f00, exit nh 0x2c0010 Oct 12 18:41:50 18:41:50.950380:CID-0:RT: ----- flow_process_pkt rc 0x0 (fp rc 0) Oct 12 18:41:50 18:41:50.954367:CID-0:RT:<10.83.30.12/46260->10.83.42.1/80;6,0x0> matched filter TEST: Oct 12 18:41:50 18:41:50.954367:CID-0:RT:packet [52] ipid = 4392, @0x43e2589c Oct 12 18:41:50 18:41:50.954367:CID-0:RT:---- flow_process_pkt: (thd 1): flow_ctxt type 15, common flag 0x0, mbuf 0x43e25680, rtbl_idx = 7 Oct 12 18:41:50 18:41:50.954367:CID-0:RT: flow process pak fast ifl 78 in_ifp irb.30 Oct 12 18:41:50 18:41:50.954367:CID-0:RT: irb.30:10.83.30.12/46260->10.83.42.1/80, tcp, flag 10 Oct 12 18:41:50 18:41:50.954367:CID-0:RT: find flow: table 0x523a0c60, hash 62317(0xffff), sa 10.83.30.12, da 10.83.42.1, sp 46260, dp 80, proto 6, tok 8, conn-tag 0x00000000 Oct 12 18:41:50 18:41:50.954367:CID-0:RT:Found: session id 0xe71d. sess tok 8 Oct 12 18:41:50 18:41:50.954367:CID-0:RT: flow got session. Oct 12 18:41:50 18:41:50.954367:CID-0:RT: flow session id 59165 Oct 12 18:41:50 18:41:50.954367:CID-0:RT: vector bits 0x2 vector 0x4b1e2d48 Oct 12 18:41:50 18:41:50.954367:CID-0:RT: tcp seq check. Oct 12 18:41:50 18:41:50.954367:CID-0:RT:mbuf 0x43e25680, exit nh 0x250010 Oct 12 18:41:50 18:41:50.954367:CID-0:RT: ----- flow_process_pkt rc 0x0 (fp rc 0) Oct 12 18:41:51 18:41:51.455603:CID-0:RT:<10.83.30.12/38081->10.83.42.1/80;6,0x0> matched filter TEST: Oct 12 18:41:51 18:41:51.455603:CID-0:RT:packet [282] ipid = 1500, @0x43e4cd9c Oct 12 18:41:51 18:41:51.455603:CID-0:RT:---- flow_process_pkt: (thd 1): flow_ctxt type 15, common flag 0x0, mbuf 0x43e4cb80, rtbl_idx = 7 Oct 12 18:41:51 18:41:51.455603:CID-0:RT: flow process pak fast ifl 78 in_ifp irb.30 Oct 12 18:41:51 18:41:51.455603:CID-0:RT: irb.30:10.83.30.12/38081->10.83.42.1/80, tcp, flag 18 Oct 12 18:41:51 18:41:51.455603:CID-0:RT: find flow: table 0x523a0c60, hash 57021(0xffff), sa 10.83.30.12, da 10.83.42.1, sp 38081, dp 80, proto 6, tok 8, conn-tag 0x00000000 Oct 12 18:41:51 18:41:51.455603:CID-0:RT:Found: session id 0xe5d5. sess tok 8 Oct 12 18:41:51 18:41:51.455603:CID-0:RT: flow got session. Oct 12 18:41:51 18:41:51.455603:CID-0:RT: flow session id 58837 Oct 12 18:41:51 18:41:51.455603:CID-0:RT: refreshing session Oct 12 18:41:51 18:41:51.455603:CID-0:RT: vector bits 0x2 vector 0x4b1e2d48 Oct 12 18:41:51 18:41:51.455603:CID-0:RT: tcp seq check. Oct 12 18:41:51 18:41:51.455603:CID-0:RT:mbuf 0x43e4cb80, exit nh 0x250010 Oct 12 18:41:51 18:41:51.455603:CID-0:RT: ----- flow_process_pkt rc 0x0 (fp rc 0) Oct 12 18:41:51 18:41:51.456179:CID-0:RT:<10.83.42.1/80->10.83.30.12/38081;6,0x0> matched filter TEST: Oct 12 18:41:51 18:41:51.456179:CID-0:RT:packet [40] ipid = 9468, @0x43dfb91c Oct 12 18:41:51 18:41:51.456179:CID-0:RT:---- flow_process_pkt: (thd 1): flow_ctxt type 15, common flag 0x0, mbuf 0x43dfb700, rtbl_idx = 0 Oct 12 18:41:51 18:41:51.456179:CID-0:RT: flow process pak fast ifl 77 in_ifp irb.22 Oct 12 18:41:51 18:41:51.456179:CID-0:RT: irb.22:10.83.42.1/80->10.83.30.12/38081, tcp, flag 4 rst Oct 12 18:41:51 18:41:51.456179:CID-0:RT: find flow: table 0x523a0c60, hash 30987(0xffff), sa 10.83.42.1, da 10.83.30.12, sp 80, dp 38081, proto 6, tok 10, conn-tag 0x00000000 Oct 12 18:41:51 18:41:51.456179:CID-0:RT:Found: session id 0xe5d5. sess tok 10 Oct 12 18:41:51 18:41:51.456179:CID-0:RT: flow got session. Oct 12 18:41:51 18:41:51.456179:CID-0:RT: flow session id 58837 Oct 12 18:41:51 18:41:51.456179:CID-0:RT: vector bits 0x2 vector 0x4b1e2d48 Oct 12 18:41:51 18:41:51.456179:CID-0:RT: tcp seq check. Oct 12 18:41:51 18:41:51.456179:CID-0:RT:mbuf 0x43dfb700, exit nh 0x2c0010 Oct 12 18:41:51 18:41:51.456179:CID-0:RT: ----- flow_process_pkt rc 0x0 (fp rc 0) Oct 12 18:41:51 18:41:51.456820:CID-0:RT:<10.83.30.12/38081->10.83.42.1/80;6,0x0> matched filter TEST: Oct 12 18:41:51 18:41:51.456820:CID-0:RT:packet [52] ipid = 1501, @0x43e2f19c Oct 12 18:41:51 18:41:51.456820:CID-0:RT:---- flow_process_pkt: (thd 1): flow_ctxt type 15, common flag 0x0, mbuf 0x43e2ef80, rtbl_idx = 7 Oct 12 18:41:51 18:41:51.456820:CID-0:RT: flow process pak fast ifl 78 in_ifp irb.30 Oct 12 18:41:51 18:41:51.456820:CID-0:RT: irb.30:10.83.30.12/38081->10.83.42.1/80, tcp, flag 11 fin Oct 12 18:41:51 18:41:51.456820:CID-0:RT: find flow: table 0x523a0c60, hash 57021(0xffff), sa 10.83.30.12, da 10.83.42.1, sp 38081, dp 80, proto 6, tok 8, conn-tag 0x00000000 Oct 12 18:41:51 18:41:51.456820:CID-0:RT:Found: session id 0xe5d5. sess tok 8 Oct 12 18:41:51 18:41:51.456820:CID-0:RT: flow got session. Oct 12 18:41:51 18:41:51.456820:CID-0:RT: flow session id 58837 Oct 12 18:41:51 18:41:51.456820:CID-0:RT: vector bits 0x2 vector 0x4b1e2d48 Oct 12 18:41:51 18:41:51.456820:CID-0:RT: tcp seq check. Oct 12 18:41:51 18:41:51.456820:CID-0:RT:mbuf 0x43e2ef80, exit nh 0x250010 Oct 12 18:41:51 18:41:51.456820:CID-0:RT: ----- flow_process_pkt rc 0x0 (fp rc 0) Oct 12 18:41:51 18:41:51.456820:CID-0:RT:<10.83.30.12/40884->10.83.42.1/80;6,0x0> matched filter TEST: Oct 12 18:41:51 18:41:51.456820:CID-0:RT:packet [60] ipid = 5185, @0x43e7429c Oct 12 18:41:51 18:41:51.456820:CID-0:RT:---- flow_process_pkt: (thd 1): flow_ctxt type 15, common flag 0x0, mbuf 0x43e74080, rtbl_idx = 7 Oct 12 18:41:51 18:41:51.456820:CID-0:RT: flow process pak fast ifl 78 in_ifp irb.30 Oct 12 18:41:51 18:41:51.456820:CID-0:RT: irb.30:10.83.30.12/40884->10.83.42.1/80, tcp, flag 2 syn Oct 12 18:41:51 18:41:51.456820:CID-0:RT: find flow: table 0x523a0c60, hash 50788(0xffff), sa 10.83.30.12, da 10.83.42.1, sp 40884, dp 80, proto 6, tok 8, conn-tag 0x00000000 Oct 12 18:41:51 18:41:51.456820:CID-0:RT: no session found, start first path. in_tunnel - 0x0, from_cp_flag - 0 Oct 12 18:41:51 18:41:51.456820:CID-0:RT: flow_first_create_session Oct 12 18:41:51 18:41:51.456820:CID-0:RT:Save init hash spu id 0 to nsp and nsp2! Oct 12 18:41:51 18:41:51.456820:CID-0:RT:First path alloc and instl pending session, natp=0x56d33f80, id=59260 Oct 12 18:41:51 18:41:51.456820:CID-0:RT: flow_first_in_dst_nat: in , out dst_adr 10.83.42.1, sp 40884, dp 80 Oct 12 18:41:51 18:41:51.456820:CID-0:RT: chose interface irb.30 as incoming nat if. Oct 12 18:41:51 18:41:51.456820:CID-0:RT:flow_first_rule_dst_xlate: DST no-xlate: 0.0.0.0(0) to 10.83.42.1(80) Oct 12 18:41:51 18:41:51.456820:CID-0:RT:[JSF] Do ingress interest check. regd ingress plugins(1) Oct 12 18:41:51 18:41:51.456820:CID-0:RT:[JSF][0]plugins(0x0) enabled for session = 21474895740 implicit mask(0x0), service request(0x0) Oct 12 18:41:51 18:41:51.456820:CID-0:RT:-jsf : no plugin ingress interested for session 21474895740 Oct 12 18:41:51 18:41:51.456820:CID-0:RT:flow_first_routing: vr_id 7, call flow_route_lookup(): src_ip 10.83.30.12, x_dst_ip 10.83.42.1, in ifp irb.30, out ifp N/A sp 40884, dp 80, ip_proto 6, tos 0 Oct 12 18:41:51 18:41:51.456820:CID-0:RT:Doing DESTINATION addr route-lookup Oct 12 18:41:51 18:41:51.456820:CID-0:RT:flow_ipv4_rt_lkup success 10.83.42.1, iifl 0x4e, oifl 0x4d Oct 12 18:41:51 18:41:51.456820:CID-0:RT: routed (x_dst_ip 10.83.42.1) from INSIDE (irb.30 in 0) to irb.22, Next-hop: 10.83.42.1 Oct 12 18:41:51 18:41:51.456820:CID-0:RT:flow_first_policy_search: policy search from zone INSIDE-> zone SERVERS (0x0,0x9fb40050,0x50) Oct 12 18:41:51 18:41:51.456820:CID-0:RT:Policy lkup: vsys 0 zone(8:INSIDE) -> zone(10:SERVERS) scope:0 Oct 12 18:41:51 18:41:51.456820:CID-0:RT: 10.83.30.12/40884 -> 10.83.42.1/80 proto 6 Oct 12 18:41:51 18:41:51.456820:CID-0:RT: policy has timeout 900 Oct 12 18:41:51 18:41:51.456820:CID-0:RT: app 6, timeout 1800s, curr ageout 20s Oct 12 18:41:51 18:41:51.456820:CID-0:RT: permitted by policy ARM02(11) Oct 12 18:41:51 18:41:51.456820:CID-0:RT: packet passed, Permitted by policy. Oct 12 18:41:51 18:41:51.456820:CID-0:RT:flow_first_src_xlate: nat_src_xlated: False, nat_src_xlate_failed: False Oct 12 18:41:51 18:41:51.456820:CID-0:RT:flow_first_src_xlate: incoming src port is : 40884. Oct 12 18:41:51 18:41:51.456820:CID-0:RT:flow_first_src_xlate: src nat returns status: 0, rule/pool id: 0/0, pst_nat: False. Oct 12 18:41:51 18:41:51.456820:CID-0:RT: dip id = 0/0, 10.83.30.12/40884->10.83.30.12/40884 protocol 0 Oct 12 18:41:51 18:41:51.456820:CID-0:RT: choose interface irb.22(P2P) as outgoing phy if Oct 12 18:41:51 18:41:51.456820:CID-0:RT:is_loop_pak: No loop: on ifp: irb.22, addr: 10.83.42.1, rtt_idx:0 Oct 12 18:41:51 18:41:51.456820:CID-0:RT:[JSF]Normal interest check. regd plugins 35, enabled impl mask 0x0 Oct 12 18:41:51 18:41:51.456820:CID-0:RT:get NULL sess plugin info 0x56d33f80 Oct 12 18:41:51 18:41:51.456820:CID-0:RT:get NULL sess plugin info 0x56d33f80 Oct 12 18:41:51 18:41:51.456820:CID-0:RT:get NULL sess plugin info 0x56d33f80 Oct 12 18:41:51 18:41:51.456820:CID-0:RT:get NULL sess plugin info 0x56d33f80 Oct 12 18:41:51 18:41:51.456820:CID-0:RT:get NULL sess plugin info 0x56d33f80 Oct 12 18:41:51 18:41:51.456820:CID-0:RT:get NULL sess plugin info 0x56d33f80 Oct 12 18:41:51 18:41:51.456820:CID-0:RT:+++++++++++jsf_test_plugin_data_evh: 3 Oct 12 18:41:51 18:41:51.456820:CID-0:RT:get NULL sess plugin info 0x56d33f80 Oct 12 18:41:51 18:41:51.456820:CID-0:RT:get NULL sess plugin info 0x56d33f80 Oct 12 18:41:51 18:41:51.456820:CID-0:RT:[JSF]Plugins(0x0, count 0) enabled for session = 21474895740, impli mask(0x0), post_nat cnt 0 svc req(0x0) Oct 12 18:41:51 18:41:51.456820:CID-0:RT:-jsf : no plugin interested for session 21474895740, free sess plugin info Oct 12 18:41:51 18:41:51.456820:CID-0:RT: service lookup identified service 6. Oct 12 18:41:51 18:41:51.456820:CID-0:RT: flow_first_final_check: in , out Oct 12 18:41:51 18:41:51.456820:CID-0:RT:In flow_first_complete_session Oct 12 18:41:51 18:41:51.456820:CID-0:RT:flow_first_complete_session, pak_ptr: 0x50e25170, nsp: 0x56d33f80, in_tunnel: 0x0 Oct 12 18:41:51 18:41:51.456820:CID-0:RT:construct v4 vector for nsp2 and nsp Oct 12 18:41:51 18:41:51.456820:CID-0:RT: existing vector list 0x2-0x4b1e2d48. Oct 12 18:41:51 18:41:51.456820:CID-0:RT: existing vector list 0x2-0x4b1e2d48. Oct 12 18:41:51 18:41:51.456820:CID-0:RT: Session (id:59260) created for first pak 2 Oct 12 18:41:51 18:41:51.456820:CID-0:RT:first pak processing successful Oct 12 18:41:51 18:41:51.456820:CID-0:RT: flow_first_install_session======> 0x56d33f80 Oct 12 18:41:51 18:41:51.456820:CID-0:RT: nsp 0x56d33f80, nsp2 0x56d34010 Oct 12 18:41:51 18:41:51.456820:CID-0:RT: make_nsp_ready_no_resolve() Oct 12 18:41:51 18:41:51.456820:CID-0:RT:flow_ipv4_rt_lkup success 10.83.30.12, iifl 0x4e, oifl 0x4e Oct 12 18:41:51 18:41:51.456820:CID-0:RT: route lookup: dest-ip 10.83.30.12 orig ifp irb.30 output_ifp irb.30 orig-zone 8 out-zone 8 vsd 0 Oct 12 18:41:51 18:41:51.456820:CID-0:RT: route to 10.83.30.12 Oct 12 18:41:51 18:41:51.456820:CID-0:RT:no need update ha Oct 12 18:41:51 18:41:51.456820:CID-0:RT:Installing c2s NP session wing Oct 12 18:41:51 18:41:51.456820:CID-0:RT:Installing s2c NP session wing Oct 12 18:41:51 18:41:51.456820:CID-0:RT:first path session installation succeeded Oct 12 18:41:51 18:41:51.456820:CID-0:RT: flow got session. Oct 12 18:41:51 18:41:51.456820:CID-0:RT: flow session id 59260 Oct 12 18:41:51 18:41:51.456820:CID-0:RT: vector bits 0x2 vector 0x4b1e2d48 Oct 12 18:41:51 18:41:51.456820:CID-0:RT:flow_tcp_wsf_update: wsf 6 Oct 12 18:41:51 18:41:51.456820:CID-0:RT: tcp 3way refresh, is_half_open:0, tcp_proxy enabled:0, is_fwauth:0 Oct 12 18:41:51 18:41:51.456820:CID-0:RT:mbuf 0x43e74080, exit nh 0x250010 Oct 12 18:41:51 18:41:51.456820:CID-0:RT: ----- flow_process_pkt rc 0x0 (fp rc 0) Oct 12 18:41:51 18:41:51.456820:CID-0:RT:<10.83.42.1/80->10.83.30.12/38081;6,0x0> matched filter TEST: Oct 12 18:41:51 18:41:51.456820:CID-0:RT:packet [40] ipid = 9469, @0x43e4459c Oct 12 18:41:51 18:41:51.456820:CID-0:RT:---- flow_process_pkt: (thd 1): flow_ctxt type 15, common flag 0x0, mbuf 0x43e44380, rtbl_idx = 0 Oct 12 18:41:51 18:41:51.456820:CID-0:RT: flow process pak fast ifl 77 in_ifp irb.22 Oct 12 18:41:51 18:41:51.456820:CID-0:RT: irb.22:10.83.42.1/80->10.83.30.12/38081, tcp, flag 4 rst Oct 12 18:41:51 18:41:51.456820:CID-0:RT: find flow: table 0x523a0c60, hash 30987(0xffff), sa 10.83.42.1, da 10.83.30.12, sp 80, dp 38081, proto 6, tok 10, conn-tag 0x00000000 Oct 12 18:41:51 18:41:51.456820:CID-0:RT:Found: session id 0xe5d5. sess tok 10 Oct 12 18:41:51 18:41:51.456820:CID-0:RT: flow got session. Oct 12 18:41:51 18:41:51.456820:CID-0:RT: flow session id 58837 Oct 12 18:41:51 18:41:51.456820:CID-0:RT: vector bits 0x2 vector 0x4b1e2d48 Oct 12 18:41:51 18:41:51.456820:CID-0:RT: tcp seq check. Oct 12 18:41:51 18:41:51.456820:CID-0:RT:mbuf 0x43e44380, exit nh 0x2c0010 Oct 12 18:41:51 18:41:51.456820:CID-0:RT: ----- flow_process_pkt rc 0x0 (fp rc 0) Oct 12 18:41:51 18:41:51.457903:CID-0:RT:<10.83.42.1/80->10.83.30.12/40884;6,0x0> matched filter TEST: Oct 12 18:41:51 18:41:51.457903:CID-0:RT:packet [60] ipid = 0, @0x43dec29c Oct 12 18:41:51 18:41:51.457903:CID-0:RT:---- flow_process_pkt: (thd 1): flow_ctxt type 15, common flag 0x0, mbuf 0x43dec080, rtbl_idx = 0 Oct 12 18:41:51 18:41:51.457903:CID-0:RT: flow process pak fast ifl 77 in_ifp irb.22 Oct 12 18:41:51 18:41:51.457903:CID-0:RT: irb.22:10.83.42.1/80->10.83.30.12/40884, tcp, flag 12 syn ack Oct 12 18:41:51 18:41:51.457903:CID-0:RT: find flow: table 0x523a0c60, hash 32076(0xffff), sa 10.83.42.1, da 10.83.30.12, sp 80, dp 40884, proto 6, tok 10, conn-tag 0x00000000 Oct 12 18:41:51 18:41:51.457903:CID-0:RT:Found: session id 0xe77c. sess tok 10 Oct 12 18:41:51 18:41:51.457903:CID-0:RT: flow got session. Oct 12 18:41:51 18:41:51.457903:CID-0:RT: flow session id 59260 Oct 12 18:41:51 18:41:51.457903:CID-0:RT: vector bits 0x2 vector 0x4b1e2d48 Oct 12 18:41:51 18:41:51.457903:CID-0:RT:flow_tcp_wsf_update: wsf 7 Oct 12 18:41:51 18:41:51.457903:CID-0:RT: tcp 3way refresh, is_half_open:0, tcp_proxy enabled:0, is_fwauth:0 Oct 12 18:41:51 18:41:51.457903:CID-0:RT:mbuf 0x43dec080, exit nh 0x2c0010 Oct 12 18:41:51 18:41:51.457903:CID-0:RT: ----- flow_process_pkt rc 0x0 (fp rc 0) Oct 12 18:41:51 18:41:51.461250:CID-0:RT:<10.83.30.12/40884->10.83.42.1/80;6,0x0> matched filter TEST: Oct 12 18:41:51 18:41:51.461250:CID-0:RT:packet [52] ipid = 5186, @0x43e3139c Oct 12 18:41:51 18:41:51.461250:CID-0:RT:---- flow_process_pkt: (thd 1): flow_ctxt type 15, common flag 0x0, mbuf 0x43e31180, rtbl_idx = 7 Oct 12 18:41:51 18:41:51.461250:CID-0:RT: flow process pak fast ifl 78 in_ifp irb.30 Oct 12 18:41:51 18:41:51.461250:CID-0:RT: irb.30:10.83.30.12/40884->10.83.42.1/80, tcp, flag 10 Oct 12 18:41:51 18:41:51.461250:CID-0:RT: find flow: table 0x523a0c60, hash 50788(0xffff), sa 10.83.30.12, da 10.83.42.1, sp 40884, dp 80, proto 6, tok 8, conn-tag 0x00000000 Oct 12 18:41:51 18:41:51.461250:CID-0:RT:Found: session id 0xe77c. sess tok 8 Oct 12 18:41:51 18:41:51.461250:CID-0:RT: flow got session. Oct 12 18:41:51 18:41:51.461250:CID-0:RT: flow session id 59260 Oct 12 18:41:51 18:41:51.461250:CID-0:RT: vector bits 0x2 vector 0x4b1e2d48 Oct 12 18:41:51 18:41:51.461250:CID-0:RT: tcp seq check. Oct 12 18:41:51 18:41:51.461250:CID-0:RT: tcp 3way refresh, is_half_open:0, tcp_proxy enabled:0, is_fwauth:0 Oct 12 18:41:51 18:41:51.461250:CID-0:RT: refreshing session Oct 12 18:41:51 18:41:51.461250:CID-0:RT:mbuf 0x43e31180, exit nh 0x250010 Oct 12 18:41:51 18:41:51.461250:CID-0:RT: ----- flow_process_pkt rc 0x0 (fp rc 0) Oct 12 18:41:51 18:41:51.461989:CID-0:RT:<10.83.30.12/40884->10.83.42.1/80;6,0x0> matched filter TEST: Oct 12 18:41:51 18:41:51.461989:CID-0:RT:packet [282] ipid = 5187, @0x43e1951c Oct 12 18:41:51 18:41:51.461989:CID-0:RT:---- flow_process_pkt: (thd 1): flow_ctxt type 15, common flag 0x0, mbuf 0x43e19300, rtbl_idx = 7 Oct 12 18:41:51 18:41:51.461989:CID-0:RT: flow process pak fast ifl 78 in_ifp irb.30 Oct 12 18:41:51 18:41:51.461989:CID-0:RT: irb.30:10.83.30.12/40884->10.83.42.1/80, tcp, flag 18 Oct 12 18:41:51 18:41:51.461989:CID-0:RT: find flow: table 0x523a0c60, hash 50788(0xffff), sa 10.83.30.12, da 10.83.42.1, sp 40884, dp 80, proto 6, tok 8, conn-tag 0x00000000 Oct 12 18:41:51 18:41:51.461989:CID-0:RT:Found: session id 0xe77c. sess tok 8 Oct 12 18:41:51 18:41:51.461989:CID-0:RT: flow got session. Oct 12 18:41:51 18:41:51.461989:CID-0:RT: flow session id 59260 Oct 12 18:41:51 18:41:51.461989:CID-0:RT: vector bits 0x2 vector 0x4b1e2d48 Oct 12 18:41:51 18:41:51.461989:CID-0:RT: tcp seq check. Oct 12 18:41:51 18:41:51.461989:CID-0:RT:mbuf 0x43e19300, exit nh 0x250010 Oct 12 18:41:51 18:41:51.461989:CID-0:RT: ----- flow_process_pkt rc 0x0 (fp rc 0) Oct 12 18:41:51 18:41:51.462488:CID-0:RT:<10.83.42.1/80->10.83.30.12/40884;6,0x0> matched filter TEST: Oct 12 18:41:51 18:41:51.462488:CID-0:RT:packet [52] ipid = 62512, @0x43e0da1c Oct 12 18:41:51 18:41:51.462488:CID-0:RT:---- flow_process_pkt: (thd 1): flow_ctxt type 15, common flag 0x0, mbuf 0x43e0d800, rtbl_idx = 0 Oct 12 18:41:51 18:41:51.462488:CID-0:RT: flow process pak fast ifl 77 in_ifp irb.22 Oct 12 18:41:51 18:41:51.462488:CID-0:RT: irb.22:10.83.42.1/80->10.83.30.12/40884, tcp, flag 10 Oct 12 18:41:51 18:41:51.462488:CID-0:RT: find flow: table 0x523a0c60, hash 32076(0xffff), sa 10.83.42.1, da 10.83.30.12, sp 80, dp 40884, proto 6, tok 10, conn-tag 0x00000000 Oct 12 18:41:51 18:41:51.462488:CID-0:RT:Found: session id 0xe77c. sess tok 10 Oct 12 18:41:51 18:41:51.462488:CID-0:RT: flow got session. Oct 12 18:41:51 18:41:51.462488:CID-0:RT: flow session id 59260 Oct 12 18:41:51 18:41:51.462488:CID-0:RT: vector bits 0x2 vector 0x4b1e2d48 Oct 12 18:41:51 18:41:51.462488:CID-0:RT: tcp seq check. Oct 12 18:41:51 18:41:51.462488:CID-0:RT:mbuf 0x43e0d800, exit nh 0x2c0010 Oct 12 18:41:51 18:41:51.462488:CID-0:RT: ----- flow_process_pkt rc 0x0 (fp rc 0) Oct 12 18:41:51 18:41:51.466141:CID-0:RT:<10.83.42.1/80->10.83.30.12/40884;6,0x0> matched filter TEST: Oct 12 18:41:51 18:41:51.466141:CID-0:RT:packet [599] ipid = 62513, @0x43df7d9c Oct 12 18:41:51 18:41:51.466141:CID-0:RT:---- flow_process_pkt: (thd 1): flow_ctxt type 15, common flag 0x0, mbuf 0x43df7b80, rtbl_idx = 0 Oct 12 18:41:51 18:41:51.466141:CID-0:RT: flow process pak fast ifl 77 in_ifp irb.22 Oct 12 18:41:51 18:41:51.466141:CID-0:RT: irb.22:10.83.42.1/80->10.83.30.12/40884, tcp, flag 18 Oct 12 18:41:51 18:41:51.466141:CID-0:RT: find flow: table 0x523a0c60, hash 32076(0xffff), sa 10.83.42.1, da 10.83.30.12, sp 80, dp 40884, proto 6, tok 10, conn-tag 0x00000000 Oct 12 18:41:51 18:41:51.466141:CID-0:RT:Found: session id 0xe77c. sess tok 10 Oct 12 18:41:51 18:41:51.466141:CID-0:RT: flow got session. Oct 12 18:41:51 18:41:51.466141:CID-0:RT: flow session id 59260 Oct 12 18:41:51 18:41:51.466141:CID-0:RT: vector bits 0x2 vector 0x4b1e2d48 Oct 12 18:41:51 18:41:51.466141:CID-0:RT: tcp seq check. Oct 12 18:41:51 18:41:51.466141:CID-0:RT:mbuf 0x43df7b80, exit nh 0x2c0010 Oct 12 18:41:51 18:41:51.466141:CID-0:RT: ----- flow_process_pkt rc 0x0 (fp rc 0) Oct 12 18:41:51 18:41:51.481103:CID-0:RT:<10.83.30.12/40884->10.83.42.1/80;6,0x0> matched filter TEST: Oct 12 18:41:51 18:41:51.481103:CID-0:RT:packet [52] ipid = 5188, @0x43e87d1c Oct 12 18:41:51 18:41:51.481103:CID-0:RT:---- flow_process_pkt: (thd 1): flow_ctxt type 15, common flag 0x0, mbuf 0x43e87b00, rtbl_idx = 7 Oct 12 18:41:51 18:41:51.481103:CID-0:RT: flow process pak fast ifl 78 in_ifp irb.30 Oct 12 18:41:51 18:41:51.481103:CID-0:RT: irb.30:10.83.30.12/40884->10.83.42.1/80, tcp, flag 10 Oct 12 18:41:51 18:41:51.481103:CID-0:RT: find flow: table 0x523a0c60, hash 50788(0xffff), sa 10.83.30.12, da 10.83.42.1, sp 40884, dp 80, proto 6, tok 8, conn-tag 0x00000000 Oct 12 18:41:51 18:41:51.481103:CID-0:RT:Found: session id 0xe77c. sess tok 8 Oct 12 18:41:51 18:41:51.481103:CID-0:RT: flow got session. Oct 12 18:41:51 18:41:51.481103:CID-0:RT: flow session id 59260 Oct 12 18:41:51 18:41:51.481103:CID-0:RT: vector bits 0x2 vector 0x4b1e2d48 Oct 12 18:41:51 18:41:51.481103:CID-0:RT: tcp seq check. Oct 12 18:41:51 18:41:51.481103:CID-0:RT:mbuf 0x43e87b00, exit nh 0x250010 Oct 12 18:41:51 18:41:51.481103:CID-0:RT: ----- flow_process_pkt rc 0x0 (fp rc 0)