is in ACTIVE state now
2026-01-15 03:55:54,277 - INFO - VM name : mltf_vm
2026-01-15 03:55:54,379 - DEBUG - VM mltf_vm ID is 9f656940-ccca-4d15-b226-e095bfed082e
2026-01-15 03:55:54,379 - DEBUG - VM mltf_vm launched on Node an-jenkins-deploy-platform-ansible-os-5009-2
2026-01-15 03:55:54,584 - DEBUG - Requesting: http://10.0.0.73:8082/virtual-machine/9f656940-ccca-4d15-b226-e095bfed082e
2026-01-15 03:55:54,597 - DEBUG - Requesting: http://10.0.0.73:8082/virtual-machine-interface/122c71a4-5075-4a5e-9612-7e32534542ef
2026-01-15 03:55:54,612 - DEBUG - Requesting: http://10.0.0.73:8082/virtual-machine-interface/cfbb370e-0bde-4c38-a54b-558f55da0de3
2026-01-15 03:55:55,798 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n64 bytes from 169.254.0.5: icmp_seq=1 ttl=63 time=2.40 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=0.483 ms\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.483/1.439/2.395/0.956 ms')
2026-01-15 03:55:55,798 - INFO - Ping to Metadata IP 169.254.0.5 of VM mltf_vm passed
2026-01-15 03:55:55,872 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-01-15 03:55:55,872 - DEBUG - Waiting to SSH to VM mltf_vm, IP 126.88.85.5, Port 22
2026-01-15 03:55:56,039 - DEBUG - VM mltf_vm is ready for SSH connections
2026-01-15 03:55:56,111 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-01-15 03:55:56,111 - DEBUG - Waiting to SSH to VM mltf_vm, IP 126.88.85.5, Port 22
2026-01-15 03:55:56,270 - DEBUG - VM mltf_vm is ready for SSH connections
2026-01-15 03:55:56,272 - INFO - ================================================================================
2026-01-15 03:55:56,272 - INFO - STARTING TEST : test_verify_session_sampling_teardown
2026-01-15 03:55:56,272 - INFO - TEST DESCRIPTION :
1.query client session samples
2.query server session samples
3.query client session to get number of sessions exported
4.query session record table for teardown bytes/pkts
5.query sample count after teardown on server side
2026-01-15 03:55:57,553 - DEBUG - Skipping xmpp flap check
2026-01-15 03:55:57,553 - INFO - Initial checks done. Running the testcase now
2026-01-15 03:55:57,553 - INFO -
2026-01-15 03:55:57,681 - INFO - Setting flow export rate: 100
2026-01-15 03:55:57,729 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = any, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = -1, end_port = -1], application = [], dst_addresses = [subnet = None, virtual_network = any, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None]
2026-01-15 03:55:58,096 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': 'a78c2766-3036-423a-bf86-48cb3dc1ee57', 'name': 'ctest-vn1-55128658', 'tenant_id': '9541d992ae2e412490b4668cc07c5e2c', 'project_id': '9541d992ae2e412490b4668cc07c5e2c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['5a1a4281-157a-4935-9490-6d9db8bf2dec'], 'fq_name': ['default-domain', 'ctest-AnalyticsTestSanityWithResource-37172580', 'ctest-vn1-55128658'], 'policys': [['default-domain', 'ctest-AnalyticsTestSanityWithResource-37172580', 'policy1']], 'subnet_ipam': [{'subnet_cidr': '126.88.85.0/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}}
2026-01-15 03:55:58,293 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': 'd5c28a77-fb41-4d4d-912c-71b6721b9862', 'name': 'ctest-vn2-92940921', 'tenant_id': '9541d992ae2e412490b4668cc07c5e2c', 'project_id': '9541d992ae2e412490b4668cc07c5e2c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['9732eee3-9c5a-498d-8c34-47a3a91c3c95'], 'fq_name': ['default-domain', 'ctest-AnalyticsTestSanityWithResource-37172580', 'ctest-vn2-92940921'], 'policys': [['default-domain', 'ctest-AnalyticsTestSanityWithResource-37172580', 'policy1']], 'subnet_ipam': [{'subnet_cidr': '70.133.103.64/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}}
2026-01-15 03:55:58,392 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:55:58,399 - DEBUG - Requesting: http://10.0.0.242:8082/domain/17e89162-e11a-43fe-960d-0f3e5cda0a6b
2026-01-15 03:55:58,448 - DEBUG - Requesting: http://10.0.0.242:8082/project/9541d992-ae2e-4124-90b4-668cc07c5e2c
2026-01-15 03:55:58,581 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/a78c2766-3036-423a-bf86-48cb3dc1ee57
2026-01-15 03:55:58,607 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/a78c2766-3036-423a-bf86-48cb3dc1ee57
2026-01-15 03:55:58,657 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/5efbcec9-fbfc-4592-9a39-375954de950c
2026-01-15 03:55:58,670 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/5efbcec9-fbfc-4592-9a39-375954de950c
2026-01-15 03:55:58,680 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/27e64ca9-7b9e-4437-ba8c-27fee3881892
2026-01-15 03:55:58,691 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-15 03:55:58,691 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/a78c2766-3036-423a-bf86-48cb3dc1ee57
2026-01-15 03:55:58,704 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/5efbcec9-fbfc-4592-9a39-375954de950c
2026-01-15 03:55:58,745 - INFO - Verified VN network id 13 for VN a78c2766-3036-423a-bf86-48cb3dc1ee57
2026-01-15 03:55:58,745 - INFO - Verifications in API Server for VN ctest-vn1-55128658 passed
2026-01-15 03:55:58,746 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/a78c2766-3036-423a-bf86-48cb3dc1ee57
2026-01-15 03:55:58,761 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/5efbcec9-fbfc-4592-9a39-375954de950c
2026-01-15 03:55:58,774 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/5efbcec9-fbfc-4592-9a39-375954de950c
2026-01-15 03:55:58,785 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/27e64ca9-7b9e-4437-ba8c-27fee3881892
2026-01-15 03:55:58,801 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn1-55128658', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-37172580', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12073068020922466874', 'uuid-lslong': '13800798145875144279'}, 'enable': 'true', 'created': '2026-01-15T03:54:32', 'last-modified': '2026-01-15T03:55:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.750397'}
2026-01-15 03:55:58,843 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-15 03:55:58,849 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn1-55128658', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-37172580', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12073068020922466874', 'uuid-lslong': '13800798145875144279'}, 'enable': 'true', 'created': '2026-01-15T03:54:32', 'last-modified': '2026-01-15T03:55:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.794867'}
2026-01-15 03:55:58,856 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-15 03:55:58,864 - DEBUG - Control-node 10.0.0.73 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn1-55128658', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-37172580', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12073068020922466874', 'uuid-lslong': '13800798145875144279'}, 'enable': 'true', 'created': '2026-01-15T03:54:32', 'last-modified': '2026-01-15T03:55:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.810269'}
2026-01-15 03:55:58,887 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-15 03:55:58,887 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn1-55128658 passed
2026-01-15 03:55:58,888 - DEBUG - ====Verifying policy data for ctest-vn1-55128658 in API_Server ======
2026-01-15 03:55:58,889 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:55:58,900 - DEBUG - Requesting: http://10.0.0.242:8082/domain/17e89162-e11a-43fe-960d-0f3e5cda0a6b
2026-01-15 03:55:58,916 - DEBUG - Requesting: http://10.0.0.242:8082/project/9541d992-ae2e-4124-90b4-668cc07c5e2c
2026-01-15 03:55:59,039 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/a78c2766-3036-423a-bf86-48cb3dc1ee57
2026-01-15 03:55:59,052 - DEBUG - ==>Verifying data for policy with id: 76578598-3fbd-4847-bf17-f8936421125c, fqn: ['default-domain', 'ctest-AnalyticsTestSanityWithResource-37172580', 'policy1']
2026-01-15 03:55:59,052 - INFO - VN ctest-vn1-55128658 Policy verification: verify_vn_policy_in_api_server, status: True
2026-01-15 03:55:59,052 - DEBUG - Verifying the vn in opserver
2026-01-15 03:55:59,052 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn1-55128658 virtual network link through opserver 10.0.0.242
2026-01-15 03:55:59,052 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2026-01-15 03:55:59,072 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn1-55128658', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn1-55128658?flat'}
2026-01-15 03:55:59,073 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn1-55128658 virtual network link through opserver 10.0.0.33
2026-01-15 03:55:59,073 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2026-01-15 03:55:59,089 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn1-55128658', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn1-55128658?flat'}
2026-01-15 03:55:59,090 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn1-55128658 virtual network link through opserver 10.0.0.73
2026-01-15 03:55:59,090 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-networks
2026-01-15 03:55:59,104 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn1-55128658', 'href': 'http://10.0.0.73:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn1-55128658?flat'}
2026-01-15 03:55:59,104 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn1-55128658 is found in opserver
2026-01-15 03:55:59,118 - DEBUG - VRF ids for VN ctest-vn1-55128658: {'10.0.0.133': '2'}
2026-01-15 03:55:59,118 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:55:59,128 - DEBUG - Requesting: http://10.0.0.242:8082/domain/17e89162-e11a-43fe-960d-0f3e5cda0a6b
2026-01-15 03:55:59,144 - DEBUG - Requesting: http://10.0.0.242:8082/project/9541d992-ae2e-4124-90b4-668cc07c5e2c
2026-01-15 03:55:59,245 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/d5c28a77-fb41-4d4d-912c-71b6721b9862
2026-01-15 03:55:59,263 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/d5c28a77-fb41-4d4d-912c-71b6721b9862
2026-01-15 03:55:59,274 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/0132f355-8a28-4904-be8c-3ab358421e84
2026-01-15 03:55:59,285 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/0132f355-8a28-4904-be8c-3ab358421e84
2026-01-15 03:55:59,294 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/01fd83ea-fc5c-4aa1-b7b5-71033a6321c3
2026-01-15 03:55:59,304 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-15 03:55:59,304 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/d5c28a77-fb41-4d4d-912c-71b6721b9862
2026-01-15 03:55:59,315 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/0132f355-8a28-4904-be8c-3ab358421e84
2026-01-15 03:55:59,335 - INFO - Verified VN network id 14 for VN d5c28a77-fb41-4d4d-912c-71b6721b9862
2026-01-15 03:55:59,335 - INFO - Verifications in API Server for VN ctest-vn2-92940921 passed
2026-01-15 03:55:59,335 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/d5c28a77-fb41-4d4d-912c-71b6721b9862
2026-01-15 03:55:59,347 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/0132f355-8a28-4904-be8c-3ab358421e84
2026-01-15 03:55:59,357 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/0132f355-8a28-4904-be8c-3ab358421e84
2026-01-15 03:55:59,366 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/01fd83ea-fc5c-4aa1-b7b5-71033a6321c3
2026-01-15 03:55:59,384 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn2-92940921', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-37172580', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15403025923481619789', 'uuid-lslong': '10460861062887217250'}, 'enable': 'true', 'created': '2026-01-15T03:55:09', 'last-modified': '2026-01-15T03:55:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.112137'}
2026-01-15 03:55:59,392 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-15 03:55:59,399 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn2-92940921', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-37172580', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15403025923481619789', 'uuid-lslong': '10460861062887217250'}, 'enable': 'true', 'created': '2026-01-15T03:55:09', 'last-modified': '2026-01-15T03:55:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.137336'}
2026-01-15 03:55:59,409 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-15 03:55:59,418 - DEBUG - Control-node 10.0.0.73 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn2-92940921', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-37172580', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15403025923481619789', 'uuid-lslong': '10460861062887217250'}, 'enable': 'true', 'created': '2026-01-15T03:55:09', 'last-modified': '2026-01-15T03:55:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.153151'}
2026-01-15 03:55:59,426 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-15 03:55:59,426 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn2-92940921 passed
2026-01-15 03:55:59,426 - DEBUG - ====Verifying policy data for ctest-vn2-92940921 in API_Server ======
2026-01-15 03:55:59,426 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:55:59,434 - DEBUG - Requesting: http://10.0.0.242:8082/domain/17e89162-e11a-43fe-960d-0f3e5cda0a6b
2026-01-15 03:55:59,450 - DEBUG - Requesting: http://10.0.0.242:8082/project/9541d992-ae2e-4124-90b4-668cc07c5e2c
2026-01-15 03:55:59,566 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/d5c28a77-fb41-4d4d-912c-71b6721b9862
2026-01-15 03:55:59,582 - DEBUG - ==>Verifying data for policy with id: 76578598-3fbd-4847-bf17-f8936421125c, fqn: ['default-domain', 'ctest-AnalyticsTestSanityWithResource-37172580', 'policy1']
2026-01-15 03:55:59,582 - INFO - VN ctest-vn2-92940921 Policy verification: verify_vn_policy_in_api_server, status: True
2026-01-15 03:55:59,582 - DEBUG - Verifying the vn in opserver
2026-01-15 03:55:59,582 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn2-92940921 virtual network link through opserver 10.0.0.242
2026-01-15 03:55:59,582 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2026-01-15 03:55:59,596 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn2-92940921', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn2-92940921?flat'}
2026-01-15 03:55:59,596 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn2-92940921 virtual network link through opserver 10.0.0.33
2026-01-15 03:55:59,596 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2026-01-15 03:55:59,608 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn2-92940921', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn2-92940921?flat'}
2026-01-15 03:55:59,609 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn2-92940921 virtual network link through opserver 10.0.0.73
2026-01-15 03:55:59,609 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-networks
2026-01-15 03:55:59,621 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn2-92940921', 'href': 'http://10.0.0.73:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn2-92940921?flat'}
2026-01-15 03:55:59,621 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn2-92940921 is found in opserver
2026-01-15 03:55:59,633 - DEBUG - VRF ids for VN ctest-vn2-92940921: {'10.0.0.141': '2', '10.0.0.133': '3'}
2026-01-15 03:55:59,633 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:55:59,640 - DEBUG - Requesting: http://10.0.0.242:8082/domain/17e89162-e11a-43fe-960d-0f3e5cda0a6b
2026-01-15 03:55:59,658 - DEBUG - Requesting: http://10.0.0.242:8082/project/9541d992-ae2e-4124-90b4-668cc07c5e2c
2026-01-15 03:55:59,760 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/a78c2766-3036-423a-bf86-48cb3dc1ee57
2026-01-15 03:55:59,775 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/a78c2766-3036-423a-bf86-48cb3dc1ee57
2026-01-15 03:55:59,787 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/5efbcec9-fbfc-4592-9a39-375954de950c
2026-01-15 03:55:59,797 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/5efbcec9-fbfc-4592-9a39-375954de950c
2026-01-15 03:55:59,808 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/27e64ca9-7b9e-4437-ba8c-27fee3881892
2026-01-15 03:55:59,816 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-15 03:55:59,816 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/a78c2766-3036-423a-bf86-48cb3dc1ee57
2026-01-15 03:55:59,827 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/5efbcec9-fbfc-4592-9a39-375954de950c
2026-01-15 03:55:59,846 - INFO - Verified VN network id 13 for VN a78c2766-3036-423a-bf86-48cb3dc1ee57
2026-01-15 03:55:59,846 - INFO - Verifications in API Server for VN ctest-vn1-55128658 passed
2026-01-15 03:55:59,847 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/a78c2766-3036-423a-bf86-48cb3dc1ee57
2026-01-15 03:55:59,862 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/5efbcec9-fbfc-4592-9a39-375954de950c
2026-01-15 03:55:59,872 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/5efbcec9-fbfc-4592-9a39-375954de950c
2026-01-15 03:55:59,882 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/27e64ca9-7b9e-4437-ba8c-27fee3881892
2026-01-15 03:55:59,899 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn1-55128658', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-37172580', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12073068020922466874', 'uuid-lslong': '13800798145875144279'}, 'enable': 'true', 'created': '2026-01-15T03:54:32', 'last-modified': '2026-01-15T03:55:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.848794'}
2026-01-15 03:55:59,906 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-15 03:55:59,913 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn1-55128658', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-37172580', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12073068020922466874', 'uuid-lslong': '13800798145875144279'}, 'enable': 'true', 'created': '2026-01-15T03:54:32', 'last-modified': '2026-01-15T03:55:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.858916'}
2026-01-15 03:55:59,921 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-15 03:55:59,928 - DEBUG - Control-node 10.0.0.73 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn1-55128658', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-37172580', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12073068020922466874', 'uuid-lslong': '13800798145875144279'}, 'enable': 'true', 'created': '2026-01-15T03:54:32', 'last-modified': '2026-01-15T03:55:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.875795'}
2026-01-15 03:55:59,935 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-15 03:55:59,935 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn1-55128658 passed
2026-01-15 03:55:59,935 - DEBUG - ====Verifying policy data for ctest-vn1-55128658 in API_Server ======
2026-01-15 03:55:59,935 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:55:59,942 - DEBUG - Requesting: http://10.0.0.242:8082/domain/17e89162-e11a-43fe-960d-0f3e5cda0a6b
2026-01-15 03:55:59,956 - DEBUG - Requesting: http://10.0.0.242:8082/project/9541d992-ae2e-4124-90b4-668cc07c5e2c
2026-01-15 03:56:00,070 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/a78c2766-3036-423a-bf86-48cb3dc1ee57
2026-01-15 03:56:00,089 - DEBUG - ==>Verifying data for policy with id: 76578598-3fbd-4847-bf17-f8936421125c, fqn: ['default-domain', 'ctest-AnalyticsTestSanityWithResource-37172580', 'policy1']
2026-01-15 03:56:00,089 - INFO - VN ctest-vn1-55128658 Policy verification: verify_vn_policy_in_api_server, status: True
2026-01-15 03:56:00,089 - DEBUG - Verifying the vn in opserver
2026-01-15 03:56:00,089 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn1-55128658 virtual network link through opserver 10.0.0.242
2026-01-15 03:56:00,089 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2026-01-15 03:56:00,107 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn1-55128658', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn1-55128658?flat'}
2026-01-15 03:56:00,107 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn1-55128658 virtual network link through opserver 10.0.0.33
2026-01-15 03:56:00,107 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2026-01-15 03:56:00,123 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn1-55128658', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn1-55128658?flat'}
2026-01-15 03:56:00,123 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn1-55128658 virtual network link through opserver 10.0.0.73
2026-01-15 03:56:00,123 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-networks
2026-01-15 03:56:00,138 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn1-55128658', 'href': 'http://10.0.0.73:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn1-55128658?flat'}
2026-01-15 03:56:00,138 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn1-55128658 is found in opserver
2026-01-15 03:56:00,157 - DEBUG - VRF ids for VN ctest-vn1-55128658: {'10.0.0.133': '2'}
2026-01-15 03:56:00,157 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:56:00,169 - DEBUG - Requesting: http://10.0.0.242:8082/domain/17e89162-e11a-43fe-960d-0f3e5cda0a6b
2026-01-15 03:56:00,186 - DEBUG - Requesting: http://10.0.0.242:8082/project/9541d992-ae2e-4124-90b4-668cc07c5e2c
2026-01-15 03:56:00,303 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/d5c28a77-fb41-4d4d-912c-71b6721b9862
2026-01-15 03:56:00,316 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/d5c28a77-fb41-4d4d-912c-71b6721b9862
2026-01-15 03:56:00,330 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/0132f355-8a28-4904-be8c-3ab358421e84
2026-01-15 03:56:00,343 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/0132f355-8a28-4904-be8c-3ab358421e84
2026-01-15 03:56:00,354 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/01fd83ea-fc5c-4aa1-b7b5-71033a6321c3
2026-01-15 03:56:00,362 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-15 03:56:00,363 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/d5c28a77-fb41-4d4d-912c-71b6721b9862
2026-01-15 03:56:00,375 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/0132f355-8a28-4904-be8c-3ab358421e84
2026-01-15 03:56:00,402 - INFO - Verified VN network id 14 for VN d5c28a77-fb41-4d4d-912c-71b6721b9862
2026-01-15 03:56:00,403 - INFO - Verifications in API Server for VN ctest-vn2-92940921 passed
2026-01-15 03:56:00,403 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/d5c28a77-fb41-4d4d-912c-71b6721b9862
2026-01-15 03:56:00,416 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/0132f355-8a28-4904-be8c-3ab358421e84
2026-01-15 03:56:00,428 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/0132f355-8a28-4904-be8c-3ab358421e84
2026-01-15 03:56:00,439 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/01fd83ea-fc5c-4aa1-b7b5-71033a6321c3
2026-01-15 03:56:00,457 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn2-92940921', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-37172580', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15403025923481619789', 'uuid-lslong': '10460861062887217250'}, 'enable': 'true', 'created': '2026-01-15T03:55:09', 'last-modified': '2026-01-15T03:55:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.185713'}
2026-01-15 03:56:00,467 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-15 03:56:00,478 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn2-92940921', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-37172580', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15403025923481619789', 'uuid-lslong': '10460861062887217250'}, 'enable': 'true', 'created': '2026-01-15T03:55:09', 'last-modified': '2026-01-15T03:55:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.214788'}
2026-01-15 03:56:00,490 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-15 03:56:00,499 - DEBUG - Control-node 10.0.0.73 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn2-92940921', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-37172580', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15403025923481619789', 'uuid-lslong': '10460861062887217250'}, 'enable': 'true', 'created': '2026-01-15T03:55:09', 'last-modified': '2026-01-15T03:55:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.233376'}
2026-01-15 03:56:00,508 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-15 03:56:00,508 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn2-92940921 passed
2026-01-15 03:56:00,508 - DEBUG - ====Verifying policy data for ctest-vn2-92940921 in API_Server ======
2026-01-15 03:56:00,508 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:56:00,517 - DEBUG - Requesting: http://10.0.0.242:8082/domain/17e89162-e11a-43fe-960d-0f3e5cda0a6b
2026-01-15 03:56:00,536 - DEBUG - Requesting: http://10.0.0.242:8082/project/9541d992-ae2e-4124-90b4-668cc07c5e2c
2026-01-15 03:56:00,657 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/d5c28a77-fb41-4d4d-912c-71b6721b9862
2026-01-15 03:56:00,671 - DEBUG - ==>Verifying data for policy with id: 76578598-3fbd-4847-bf17-f8936421125c, fqn: ['default-domain', 'ctest-AnalyticsTestSanityWithResource-37172580', 'policy1']
2026-01-15 03:56:00,672 - INFO - VN ctest-vn2-92940921 Policy verification: verify_vn_policy_in_api_server, status: True
2026-01-15 03:56:00,672 - DEBUG - Verifying the vn in opserver
2026-01-15 03:56:00,672 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn2-92940921 virtual network link through opserver 10.0.0.242
2026-01-15 03:56:00,672 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2026-01-15 03:56:00,687 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn2-92940921', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn2-92940921?flat'}
2026-01-15 03:56:00,687 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn2-92940921 virtual network link through opserver 10.0.0.33
2026-01-15 03:56:00,687 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2026-01-15 03:56:00,704 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn2-92940921', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn2-92940921?flat'}
2026-01-15 03:56:00,704 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn2-92940921 virtual network link through opserver 10.0.0.73
2026-01-15 03:56:00,704 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-networks
2026-01-15 03:56:00,720 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn2-92940921', 'href': 'http://10.0.0.73:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn2-92940921?flat'}
2026-01-15 03:56:00,720 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn2-92940921 is found in opserver
2026-01-15 03:56:00,735 - DEBUG - VRF ids for VN ctest-vn2-92940921: {'10.0.0.141': '2', '10.0.0.133': '3'}
2026-01-15 03:56:00,736 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:56:00,744 - DEBUG - Requesting: http://10.0.0.242:8082/domain/17e89162-e11a-43fe-960d-0f3e5cda0a6b
2026-01-15 03:56:00,762 - DEBUG - Requesting: http://10.0.0.242:8082/project/9541d992-ae2e-4124-90b4-668cc07c5e2c
2026-01-15 03:56:00,899 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/c2abf055-5fb0-4604-9dea-11f6c1df11ef
2026-01-15 03:56:00,919 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/c2abf055-5fb0-4604-9dea-11f6c1df11ef
2026-01-15 03:56:00,934 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/27ca3a99-e3f5-4443-9912-4358d3bd5bad
2026-01-15 03:56:00,949 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/27ca3a99-e3f5-4443-9912-4358d3bd5bad
2026-01-15 03:56:00,967 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/0e97b29a-dd2d-4920-a9b0-67803a2d89fb
2026-01-15 03:56:00,979 - DEBUG - Route Targets: ['target:64512:8000007']
2026-01-15 03:56:00,979 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/c2abf055-5fb0-4604-9dea-11f6c1df11ef
2026-01-15 03:56:00,995 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/27ca3a99-e3f5-4443-9912-4358d3bd5bad
2026-01-15 03:56:01,022 - INFO - Verified VN network id 15 for VN c2abf055-5fb0-4604-9dea-11f6c1df11ef
2026-01-15 03:56:01,022 - INFO - Verifications in API Server for VN ctest-fip_vn-40811695 passed
2026-01-15 03:56:01,022 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/c2abf055-5fb0-4604-9dea-11f6c1df11ef
2026-01-15 03:56:01,040 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/27ca3a99-e3f5-4443-9912-4358d3bd5bad
2026-01-15 03:56:01,055 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/27ca3a99-e3f5-4443-9912-4358d3bd5bad
2026-01-15 03:56:01,074 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/0e97b29a-dd2d-4920-a9b0-67803a2d89fb
2026-01-15 03:56:01,096 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-fip_vn-40811695', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-37172580', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14027569713843815940', 'uuid-lslong': '11378927160017228271'}, 'enable': 'true', 'created': '2026-01-15T03:55:10', 'last-modified': '2026-01-15T03:55:10', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:50.696069'}
2026-01-15 03:56:01,105 - DEBUG - Route Targets: ['target:64512:8000007']
2026-01-15 03:56:01,115 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-fip_vn-40811695', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-37172580', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14027569713843815940', 'uuid-lslong': '11378927160017228271'}, 'enable': 'true', 'created': '2026-01-15T03:55:10', 'last-modified': '2026-01-15T03:55:10', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:50.711667'}
2026-01-15 03:56:01,124 - DEBUG - Route Targets: ['target:64512:8000007']
2026-01-15 03:56:01,134 - DEBUG - Control-node 10.0.0.73 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-fip_vn-40811695', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-37172580', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14027569713843815940', 'uuid-lslong': '11378927160017228271'}, 'enable': 'true', 'created': '2026-01-15T03:55:10', 'last-modified': '2026-01-15T03:55:10', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:50.741841'}
2026-01-15 03:56:01,147 - DEBUG - Route Targets: ['target:64512:8000007']
2026-01-15 03:56:01,148 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-fip_vn-40811695 passed
2026-01-15 03:56:01,148 - DEBUG - ====Verifying policy data for ctest-fip_vn-40811695 in API_Server ======
2026-01-15 03:56:01,148 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:56:01,165 - DEBUG - Requesting: http://10.0.0.242:8082/domain/17e89162-e11a-43fe-960d-0f3e5cda0a6b
2026-01-15 03:56:01,187 - DEBUG - Requesting: http://10.0.0.242:8082/project/9541d992-ae2e-4124-90b4-668cc07c5e2c
2026-01-15 03:56:01,330 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/c2abf055-5fb0-4604-9dea-11f6c1df11ef
2026-01-15 03:56:01,358 - DEBUG - =>VN ctest-fip_vn-40811695 has no policy to be verified
2026-01-15 03:56:01,358 - DEBUG - Verifying the vn in opserver
2026-01-15 03:56:01,358 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-fip_vn-40811695 virtual network link through opserver 10.0.0.242
2026-01-15 03:56:01,358 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2026-01-15 03:56:01,379 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-fip_vn-40811695', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-fip_vn-40811695?flat'}
2026-01-15 03:56:01,380 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-fip_vn-40811695 virtual network link through opserver 10.0.0.33
2026-01-15 03:56:01,380 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2026-01-15 03:56:01,402 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-fip_vn-40811695', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-fip_vn-40811695?flat'}
2026-01-15 03:56:01,402 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-fip_vn-40811695 virtual network link through opserver 10.0.0.73
2026-01-15 03:56:01,402 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-networks
2026-01-15 03:56:01,418 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-fip_vn-40811695', 'href': 'http://10.0.0.73:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-fip_vn-40811695?flat'}
2026-01-15 03:56:01,418 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-fip_vn-40811695 is found in opserver
2026-01-15 03:56:01,434 - DEBUG - VRF ids for VN ctest-fip_vn-40811695: {'10.0.0.141': '3'}
2026-01-15 03:56:01,434 - INFO - start time= 1768449361434591
2026-01-15 03:56:01,434 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 70.133.103.67, host_string: cirros@169.254.0.3, password: gocubsgogateway: ubuntu@10.0.0.133, gateway password: c0ntrail123
2026-01-15 03:56:01,435 - DEBUG - ping -s 56 -c 3 -W 1 70.133.103.67
2026-01-15 03:56:05,302 - DEBUG - PING 70.133.103.67 (70.133.103.67): 56 data bytes
64 bytes from 70.133.103.67: seq=0 ttl=63 time=6.318 ms
64 bytes from 70.133.103.67: seq=1 ttl=63 time=1.450 ms
64 bytes from 70.133.103.67: seq=2 ttl=63 time=1.458 ms
--- 70.133.103.67 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 1.450/3.075/6.318 ms
2026-01-15 03:56:05,302 - INFO - Ping to IP 70.133.103.67 from VM ctest-vn1_vm1-28209967 passed
2026-01-15 03:56:15,304 - INFO - Verify session samples and teardown pkts
2026-01-15 03:56:15,304 - INFO - Verifying SessionSeriesTable through opserver 10.0.0.242
2026-01-15 03:56:15,308 - DEBUG - Posting: http://10.0.0.242:8081/analytics/query, payload {'table': 'SessionSeriesTable', 'start_time': 1768449361434591, 'end_time': 1768449375308625, 'select_fields': ['T'], 'where': [[{'name': 'vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn1-55128658', 'op': 1, 'value2': None}, {'name': 'remote_vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn2-92940921', 'op': 1, 'value2': None}, {'name': 'protocol', 'value': '1', 'op': 1, 'value2': None}]], 'session_type': 'client'}
2026-01-15 03:56:16,335 - DEBUG - [{'T': 1768449364138504}, {'T': 1768449365138565}, {'T': 1768449366138639}]
2026-01-15 03:56:16,335 - DEBUG - Posting: http://10.0.0.242:8081/analytics/query, payload {'table': 'SessionSeriesTable', 'start_time': 1768449361434591, 'end_time': 1768449376335630, 'select_fields': ['T'], 'where': [[{'name': 'vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn2-92940921', 'op': 1, 'value2': None}, {'name': 'remote_vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn1-55128658', 'op': 1, 'value2': None}, {'name': 'protocol', 'value': '1', 'op': 1, 'value2': None}]], 'session_type': 'server'}
2026-01-15 03:56:17,357 - DEBUG - [{'T': 1768449363600113}, {'T': 1768449364600228}, {'T': 1768449365600354}]
2026-01-15 03:56:17,357 - DEBUG - Posting: http://10.0.0.242:8081/analytics/query, payload {'table': 'SessionSeriesTable', 'start_time': 1768449361434591, 'end_time': 1768449377357472, 'select_fields': ['vn', 'remote_vn', 'sample_count'], 'where': [[{'name': 'vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn1-55128658', 'op': 1, 'value2': None}, {'name': 'remote_vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn2-92940921', 'op': 1, 'value2': None}, {'name': 'protocol', 'value': '1', 'op': 1, 'value2': None}]], 'session_type': 'client'}
2026-01-15 03:56:18,381 - DEBUG - [{'remote_vn': 'default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn2-92940921', 'vn': 'default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn1-55128658', 'sample_count': 3}]
2026-01-15 03:56:18,382 - INFO - wait for the flows to get expire
2026-01-15 03:59:38,383 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5009-2:Compute:contrail-vrouter-agent:0?flat
2026-01-15 03:59:38,441 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5009-2?flat
2026-01-15 03:59:38,534 - DEBUG - Posting: http://10.0.0.242:8081/analytics/query, payload {'table': 'SessionRecordTable', 'start_time': 1768449361434591, 'end_time': 1768449578534377, 'select_fields': ['vn', 'remote_vn', 'forward_teardown_pkts', 'reverse_teardown_pkts'], 'where': [[{'name': 'vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn1-55128658', 'op': 1, 'value2': None}, {'name': 'remote_vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn2-92940921', 'op': 1, 'value2': None}, {'name': 'protocol', 'value': '1', 'op': 1, 'value2': None}]], 'session_type': 'client'}
2026-01-15 03:59:39,557 - DEBUG - [{'forward_flow_uuid': '19181fe9-0645-41a7-9064-f3b0c60c3c33', 'remote_vn': 'default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn2-92940921', 'reverse_flow_uuid': '366f933b-fe5f-40d5-894a-151744fbe760', 'vn': 'default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn1-55128658', 'forward_teardown_pkts': 3, 'reverse_teardown_pkts': 3}]
2026-01-15 03:59:39,557 - DEBUG - Posting: http://10.0.0.242:8081/analytics/query, payload {'table': 'SessionSeriesTable', 'start_time': 1768449361434591, 'end_time': 1768449579557568, 'select_fields': ['vn', 'remote_vn', 'sample_count'], 'where': [[{'name': 'vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn1-55128658', 'op': 1, 'value2': None}, {'name': 'remote_vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn2-92940921', 'op': 1, 'value2': None}, {'name': 'protocol', 'value': '1', 'op': 1, 'value2': None}]], 'session_type': 'client'}
2026-01-15 03:59:40,644 - DEBUG - Posting: http://10.0.0.242:8081/analytics/query, payload {'table': 'SessionSeriesTable', 'start_time': 1768449361434591, 'end_time': 1768449580644502, 'select_fields': ['vn', 'remote_vn', 'sample_count'], 'where': [[{'name': 'vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn2-92940921', 'op': 1, 'value2': None}, {'name': 'remote_vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn1-55128658', 'op': 1, 'value2': None}, {'name': 'protocol', 'value': '1', 'op': 1, 'value2': None}]], 'session_type': 'server'}
2026-01-15 03:59:41,675 - DEBUG - [{'remote_vn': 'default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn1-55128658', 'vn': 'default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn2-92940921', 'sample_count': 4}]
2026-01-15 03:59:42,193 - INFO - Deleted policy policy1
2026-01-15 03:59:42,305 - INFO - Setting flow export rate: None
2026-01-15 03:59:43,622 - DEBUG - Skipping xmpp flap check
2026-01-15 03:59:43,622 - INFO - END TEST : test_verify_session_sampling_teardown : PASSED[0:03:47]
2026-01-15 03:59:43,622 - INFO - --------------------------------------------------------------------------------
2026-01-15 03:59:43,624 - INFO - Deleting VM mltf_vm
2026-01-15 03:59:43,716 - INFO - Deleting VM ctest-fvn_vm1-97046100
2026-01-15 03:59:43,807 - INFO - Deleting VM ctest-vn2_vm2-75224998
2026-01-15 03:59:43,891 - INFO - Deleting VN ctest-fip_vn-40811695
2026-01-15 03:59:43,934 - DEBUG - VN c2abf055-5fb0-4604-9dea-11f6c1df11ef still in use: Unable to complete operation on network c2abf055-5fb0-4604-9dea-11f6c1df11ef. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-50fa0c64-9eb6-4d88-8fff-e151a184b339']
2026-01-15 03:59:43,934 - WARNING - Deleting VN ctest-fip_vn-40811695 failed..Will retry
2026-01-15 03:59:46,140 - DEBUG - Response for deleting network ()
2026-01-15 03:59:46,140 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/27ca3a99-e3f5-4443-9912-4358d3bd5bad
2026-01-15 03:59:46,180 - DEBUG - Response Code: 404
2026-01-15 03:59:46,180 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:59:46,188 - DEBUG - Requesting: http://10.0.0.242:8082/domain/17e89162-e11a-43fe-960d-0f3e5cda0a6b
2026-01-15 03:59:46,204 - DEBUG - Requesting: http://10.0.0.242:8082/project/9541d992-ae2e-4124-90b4-668cc07c5e2c
2026-01-15 03:59:46,307 - INFO - Validated that VN ctest-fip_vn-40811695 is not found in API Server
2026-01-15 03:59:46,320 - DEBUG - VN ctest-fip_vn-40811695 is not present in Agent 10.0.0.141
2026-01-15 03:59:46,331 - DEBUG - VN ctest-fip_vn-40811695 is not present in Agent 10.0.0.133
2026-01-15 03:59:46,331 - INFO - Validated that VN ctest-fip_vn-40811695 is not in any agent
2026-01-15 03:59:46,338 - DEBUG - VRF 3 is not seen in agent 10.0.0.141
2026-01-15 03:59:46,344 - DEBUG - Vrouter 10.0.0.141 does not have vrf 3 for VN ctest-fip_vn-40811695
2026-01-15 03:59:46,344 - INFO - Validated that all vrouters do not have the route table for VN default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-fip_vn-40811695
2026-01-15 03:59:46,368 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-fip_vn-40811695 info
2026-01-15 03:59:46,368 - INFO - Deleting VN ctest-vn2-92940921
2026-01-15 03:59:46,538 - DEBUG - Response for deleting network ()
2026-01-15 03:59:46,538 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/0132f355-8a28-4904-be8c-3ab358421e84
2026-01-15 03:59:46,548 - DEBUG - Response Code: 404
2026-01-15 03:59:46,548 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:59:46,557 - DEBUG - Requesting: http://10.0.0.242:8082/domain/17e89162-e11a-43fe-960d-0f3e5cda0a6b
2026-01-15 03:59:46,577 - DEBUG - Requesting: http://10.0.0.242:8082/project/9541d992-ae2e-4124-90b4-668cc07c5e2c
2026-01-15 03:59:46,683 - INFO - Validated that VN ctest-vn2-92940921 is not found in API Server
2026-01-15 03:59:46,698 - DEBUG - VN ctest-vn2-92940921 is not present in Agent 10.0.0.141
2026-01-15 03:59:46,711 - DEBUG - VN ctest-vn2-92940921 is not present in Agent 10.0.0.133
2026-01-15 03:59:46,711 - INFO - Validated that VN ctest-vn2-92940921 is not in any agent
2026-01-15 03:59:46,720 - DEBUG - VRF 2 is not seen in agent 10.0.0.141
2026-01-15 03:59:46,726 - DEBUG - Vrouter 10.0.0.141 does not have vrf 2 for VN ctest-vn2-92940921
2026-01-15 03:59:46,734 - DEBUG - VRF 3 is not seen in agent 10.0.0.133
2026-01-15 03:59:46,739 - DEBUG - Vrouter 10.0.0.133 does not have vrf 3 for VN ctest-vn2-92940921
2026-01-15 03:59:46,740 - INFO - Validated that all vrouters do not have the route table for VN default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn2-92940921
2026-01-15 03:59:46,761 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn2-92940921 info
2026-01-15 03:59:46,761 - INFO - Deleting VM ctest-vn1_vm2-33494245
2026-01-15 03:59:46,848 - INFO - Deleting VM ctest-vn1_vm1-28209967
2026-01-15 03:59:46,934 - INFO - Deleting VN ctest-vn1-55128658
2026-01-15 03:59:46,979 - DEBUG - VN a78c2766-3036-423a-bf86-48cb3dc1ee57 still in use: Unable to complete operation on network a78c2766-3036-423a-bf86-48cb3dc1ee57. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-c33dc939-bf82-4215-9248-fd282ab78b63']
2026-01-15 03:59:46,979 - WARNING - Deleting VN ctest-vn1-55128658 failed..Will retry
2026-01-15 03:59:49,168 - DEBUG - Response for deleting network ()
2026-01-15 03:59:49,168 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/5efbcec9-fbfc-4592-9a39-375954de950c
2026-01-15 03:59:49,175 - DEBUG - Response Code: 404
2026-01-15 03:59:49,175 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-01-15 03:59:49,184 - DEBUG - Requesting: http://10.0.0.242:8082/domain/17e89162-e11a-43fe-960d-0f3e5cda0a6b
2026-01-15 03:59:49,244 - DEBUG - Requesting: http://10.0.0.242:8082/project/9541d992-ae2e-4124-90b4-668cc07c5e2c
2026-01-15 03:59:49,352 - INFO - Validated that VN ctest-vn1-55128658 is not found in API Server
2026-01-15 03:59:49,368 - DEBUG - VN ctest-vn1-55128658 is not present in Agent 10.0.0.141
2026-01-15 03:59:49,380 - DEBUG - VN ctest-vn1-55128658 is not present in Agent 10.0.0.133
2026-01-15 03:59:49,380 - INFO - Validated that VN ctest-vn1-55128658 is not in any agent
2026-01-15 03:59:49,387 - DEBUG - VRF 2 is not seen in agent 10.0.0.133
2026-01-15 03:59:49,392 - DEBUG - Vrouter 10.0.0.133 does not have vrf 2 for VN ctest-vn1-55128658
2026-01-15 03:59:49,393 - INFO - Validated that all vrouters do not have the route table for VN default-domain:ctest-AnalyticsTestSanityWithResource-37172580:ctest-vn1-55128658
2026-01-15 03:59:49,420 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn1-55128658 info
2026-01-15 03:59:50,299 - INFO - Deleted project: ctest-AnalyticsTestSanityWithResource-37172580, ID : 9541d992-ae2e-4124-90b4-668cc07c5e2c