when new flow is created, the session is not getting logged in local agent log file.
session is logged when the flow is deleted after default flow aging timeout of 180 seconds.
In current case, I have 2 VNs connected with policy and 2 VMs on different nodes.
VM1 - 1.1.1.3(nodec62, local logging enabled)
VM2 - 2.2.2.3 (nodec12, local logging disabled)
ping from VM1 to VM2 and check if session is logged on nodec62 in agent log file.
flow is logged at 12:57:40 but no session and after 3 mins at 13:00:40 session and flow both are logged again while flow teardown.
logging data in agent log file on nodec62:
2017-10-12 Thu 12:57:40:254.801 IST nodec62 [Thread 139672507184896, Pid 24594]: [SYS_INFO]: FlowLogDataObject: flowdata= [ [ [ flowuuid = d1792b6a-3d23-4cf5-b3d8-c030142402a0 direction_ing = 1 sourcevn = default-domain:default-project:ip-fabric sourceip = 10.204.217.102 destvn = __UNKNOWN__ destip = 10.204.217.104 protocol = 6 sport = 5900 dport = -28138 tcp_flags = 0 vm = reverse_uuid = 7ac2f979-78cd-417e-ba76-fb21b10de27b setup_time = 1507793238750926 bytes = 28507 packets = 145 diff_bytes = 8672 diff_packets = 4 action = pass sg_rule_uuid = 00000000-0000-0000-0000-000000000001 nw_ace_uuid = 00000000-0000-0000-0000-000000000001 vrouter_ip = 10.204.217.102 other_vrouter_ip = 10.204.217.102 underlay_proto = 0 underlay_source_port = 0 vmi_uuid = 90e74784-e4e7-43c9-a8fa-c4d766ea126b drop_reason = UNKNOWN forward_flow = 1 ], [ flowuuid = f41b4543-0dd6-4c74-92a4-8d1d9ff9552a direction_ing = 0 sourcevn = default-domain:default-project:ip-fabric sourceip = 10.204.217.102 destvn = __UNKNOWN__ destip = 10.204.217.104 protocol = 6 sport = 5900 dport = -28138 tcp_flags = 0 vm = reverse_uuid = b3037d62-5f14-4453-b407-cc268661be10 setup_time = 1507793238750926 bytes = 28507 packets = 145 diff_bytes = 8672 diff_packets = 4 action = pass sg_rule_uuid = 00000000-0000-0000-0000-000000000001 nw_ace_uuid = 00000000-0000-0000-0000-000000000001 vrouter_ip = 10.204.217.102 other_vrouter_ip = 10.204.217.102 underlay_proto = 0 underlay_source_port = 0 vmi_uuid = 90e74784-e4e7-43c9-a8fa-c4d766ea126b drop_reason = UNKNOWN forward_flow = 1 ], [ flowuuid = d838253f-328c-419f-8133-05a94bae802e direction_ing = 0 sourcevn = default-domain:admin:vn2 sourceip = 2.2.2.3 destvn = default-domain:admin:vn1 destip = 1.1.1.3 protocol = 1 sport = 16899 dport = 0 tcp_flags = 0 vm = reverse_uuid = 2012a121-bd1a-4747-b7ef-2efb9384feef setup_time = 1507793260164731 bytes = 84 packets = 1 diff_bytes = 84 diff_packets = 1 action = pass sg_rule_uuid = fd5227ab-f76f-4d4a-8a3d-ed1d08a17dc8 nw_ace_uuid = 96de3f00-085a-4f5e-8233-b6b89ce73b7c vrouter_ip = 10.204.217.102 other_vrouter_ip = 10.204.216.69 underlay_proto = 2 underlay_source_port = 51798 vmi_uuid = bbd364ac-6fed-4723-a457-48616ecad2df drop_reason = UNKNOWN forward_flow = 0 ], [ flowuuid = 2012a121-bd1a-4747-b7ef-2efb9384feef direction_ing = 1 sourcevn = default-domain:admin:vn1 sourceip = 1.1.1.3 destvn = default-domain:admin:vn2 destip = 2.2.2.3 protocol = 1 sport = 16899 dport = 0 tcp_flags = 0 vm = 539ffb3e-8ffd-4820-8009-6f3cdc0521d7 reverse_uuid = d838253f-328c-419f-8133-05a94bae802e setup_time = 1507793260165078 bytes = 98 packets = 1 diff_bytes = 98 diff_packets = 1 action = pass sg_rule_uuid = fd5227ab-f76f-4d4a-8a3d-ed1d08a17dc8 nw_ace_uuid = 96de3f00-085a-4f5e-8233-b6b89ce73b7c vrouter_ip = 10.204.217.102 other_vrouter_ip = 10.204.216.69 underlay_proto = 2 underlay_source_port = 56454 vmi_uuid = bbd364ac-6fed-4723-a457-48616ecad2df drop_reason = UNKNOWN forward_flow = 1 ], ] ]
2017-10-12 Thu 13:00:40:283.087 IST nodec62 [Thread 139672511383296, Pid 24594]: [SYS_INFO]: SessionEndpointObject: session_data= [ [ [ vmi = default-domain:admin:bbd364ac-6fed-4723-a457-48616ecad2df vn = default-domain:admin:vn1 security_policy_rule = 00000000-0000-0000-0000-000000000001 remote_vn = default-domain:admin:vn2 is_client_session = 1 is_si = 0 remote_prefix = vrouter_ip = 10.204.217.102 sess_agg_info= [ [ [ ip = 1.1.1.3 port = 0 protocol = 1 ] [ logged_forward_bytes = 0 logged_forward_pkts = 0 logged_reverse_bytes = 0 logged_reverse_pkts = 0 sessionMap= [ [ [ ip = 2.2.2.3 port = 16899 ] [ forward_flow_info= [ logged_bytes = 0 logged_pkts = 0 flow_uuid = 2012a121-bd1a-4747-b7ef-2efb9384feef setup_time = 1507793260164751 teardown_time = 1507793440282703 action = pass sg_rule_uuid = fd5227ab-f76f-4d4a-8a3d-ed1d08a17dc8 nw_ace_uuid = 96de3f00-085a-4f5e-8233-b6b89ce73b7c drop_reason = 0 ] reverse_flow_info= [ logged_bytes = 0 logged_pkts = 0 flow_uuid = d838253f-328c-419f-8133-05a94bae802e setup_time = 1507793260164751 teardown_time = 1507793440282703 action = pass sg_rule_uuid = fd5227ab-f76f-4d4a-8a3d-ed1d08a17dc8 nw_ace_uuid = 96de3f00-085a-4f5e-8233-b6b89ce73b7c drop_reason = 0 ] vm = 539ffb3e-8ffd-4820-8009-6f3cdc0521d7 other_vrouter_ip = 10.204.216.69 underlay_proto = 2 ], ] ] ], ] ] ], ] ]
2017-10-12 Thu 13:00:40:283.617 IST nodec62 [Thread 139672511383296, Pid 24594]: [SYS_INFO]: FlowLogDataObject: flowdata= [ [ [ flowuuid = d838253f-328c-419f-8133-05a94bae802e direction_ing = 0 sourcevn = default-domain:admin:vn2 sourceip = 2.2.2.3 destvn = default-domain:admin:vn1 destip = 1.1.1.3 protocol = 1 sport = 16899 dport = 0 tcp_flags = 0 vm = reverse_uuid = 2012a121-bd1a-4747-b7ef-2efb9384feef setup_time = 1507793260164731 teardown_time = 1507793440282630 bytes = 84 packets = 1 diff_bytes = 0 diff_packets = 0 action = pass sg_rule_uuid = fd5227ab-f76f-4d4a-8a3d-ed1d08a17dc8 nw_ace_uuid = 96de3f00-085a-4f5e-8233-b6b89ce73b7c vrouter_ip = 10.204.217.102 other_vrouter_ip = 10.204.216.69 underlay_proto = 2 underlay_source_port = 51798 vmi_uuid = bbd364ac-6fed-4723-a457-48616ecad2df drop_reason = UNKNOWN forward_flow = 0 ], [ flowuuid = 2012a121-bd1a-4747-b7ef-2efb9384feef direction_ing = 1 sourcevn = default-domain:admin:vn1 sourceip = 1.1.1.3 destvn = default-domain:admin:vn2 destip = 2.2.2.3 protocol = 1 sport = 16899 dport = 0 tcp_flags = 0 vm = 539ffb3e-8ffd-4820-8009-6f3cdc0521d7 reverse_uuid = d838253f-328c-419f-8133-05a94bae802e setup_time = 1507793260165078 teardown_time = 1507793440282701 bytes = 98 packets = 1 diff_bytes = 0 diff_packets = 0 action = pass sg_rule_uuid = fd5227ab-f76f-4d4a-8a3d-ed1d08a17dc8 nw_ace_uuid = 96de3f00-085a-4f5e-8233-b6b89ce73b7c vrouter_ip = 10.204.217.102 other_vrouter_ip = 10.204.216.69 underlay_proto = 2 underlay_source_port = 56454 vmi_uuid = bbd364ac-6fed-4723-a457-48616ecad2df drop_reason = UNKNOWN forward_flow = 1 ], ] ]
Fixed by commits
https:/
(mainline)
https:/
(mainline)