is in ACTIVE state now
2025-11-03 03:51:07,409 - INFO - VM name : mltf_vm
2025-11-03 03:51:07,512 - DEBUG - VM mltf_vm ID is 650e44e2-b5eb-4fdc-a73f-3acd3dff2a41
2025-11-03 03:51:07,513 - DEBUG - VM mltf_vm launched on Node an-jenkins-deploy-platform-ansible-os-4597-1
2025-11-03 03:51:07,736 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/650e44e2-b5eb-4fdc-a73f-3acd3dff2a41
2025-11-03 03:51:07,747 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/bddd2c6e-3a30-4095-8e2e-92de48efdc7e
2025-11-03 03:51:07,757 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/f8f86c20-1b2c-471b-8d73-98d0167bcf11
2025-11-03 03:51:08,931 - 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.02 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=0.499 ms\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1000ms\r\nrtt min/avg/max/mdev = 0.499/1.258/2.018/0.759 ms')
2025-11-03 03:51:08,932 - INFO - Ping to Metadata IP 169.254.0.5 of VM mltf_vm passed
2025-11-03 03:51:09,000 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-11-03 03:51:09,001 - DEBUG - Waiting to SSH to VM mltf_vm, IP 58.80.185.197, Port 22
2025-11-03 03:51:09,157 - DEBUG - VM mltf_vm is ready for SSH connections
2025-11-03 03:51:09,229 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-11-03 03:51:09,229 - DEBUG - Waiting to SSH to VM mltf_vm, IP 58.80.185.197, Port 22
2025-11-03 03:51:09,395 - DEBUG - VM mltf_vm is ready for SSH connections
2025-11-03 03:51:09,397 - INFO - ================================================================================
2025-11-03 03:51:09,397 - INFO - STARTING TEST : test_verify_session_sampling_teardown
2025-11-03 03:51:09,397 - 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
2025-11-03 03:51:10,685 - DEBUG - Skipping xmpp flap check
2025-11-03 03:51:10,685 - INFO - Initial checks done. Running the testcase now
2025-11-03 03:51:10,686 - INFO -
2025-11-03 03:51:10,811 - INFO - Setting flow export rate: 100
2025-11-03 03:51:10,859 - 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]
2025-11-03 03:51:11,203 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '5b9a7e54-dc32-4946-96fd-d0d1f138a9a8', 'name': 'ctest-vn1-24841418', 'tenant_id': '6f0130e2889f4fdcac5261ed9d15ec1a', 'project_id': '6f0130e2889f4fdcac5261ed9d15ec1a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['f90197f8-18d3-4a9b-b2ea-59b5fe16582c'], 'fq_name': ['default-domain', 'ctest-AnalyticsTestSanityWithResource-15970072', 'ctest-vn1-24841418'], 'policys': [['default-domain', 'ctest-AnalyticsTestSanityWithResource-15970072', 'policy1']], 'subnet_ipam': [{'subnet_cidr': '58.80.185.192/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}}
2025-11-03 03:51:11,404 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '38c8ba9b-1180-4586-845e-8f6833d53ac0', 'name': 'ctest-vn2-20211888', 'tenant_id': '6f0130e2889f4fdcac5261ed9d15ec1a', 'project_id': '6f0130e2889f4fdcac5261ed9d15ec1a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['63f70a93-2f02-4bc2-8a0b-e5a2424afa79'], 'fq_name': ['default-domain', 'ctest-AnalyticsTestSanityWithResource-15970072', 'ctest-vn2-20211888'], 'policys': [['default-domain', 'ctest-AnalyticsTestSanityWithResource-15970072', 'policy1']], 'subnet_ipam': [{'subnet_cidr': '217.184.145.0/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}}
2025-11-03 03:51:11,490 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-11-03 03:51:11,496 - DEBUG - Requesting: http://10.0.0.249:8082/domain/08e054d4-7d42-4fbe-9f71-53fe1c9730a1
2025-11-03 03:51:11,508 - DEBUG - Requesting: http://10.0.0.249:8082/project/6f0130e2-889f-4fdc-ac52-61ed9d15ec1a
2025-11-03 03:51:11,605 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/5b9a7e54-dc32-4946-96fd-d0d1f138a9a8
2025-11-03 03:51:11,622 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/5b9a7e54-dc32-4946-96fd-d0d1f138a9a8
2025-11-03 03:51:11,633 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/e7839d9b-7a78-4232-ade5-4778fd6a86d1
2025-11-03 03:51:11,643 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/e7839d9b-7a78-4232-ade5-4778fd6a86d1
2025-11-03 03:51:11,651 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/612a390b-5f87-44be-8432-73b19607775e
2025-11-03 03:51:11,658 - DEBUG - Route Targets: ['target:64512:8000004']
2025-11-03 03:51:11,658 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/5b9a7e54-dc32-4946-96fd-d0d1f138a9a8
2025-11-03 03:51:11,723 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/e7839d9b-7a78-4232-ade5-4778fd6a86d1
2025-11-03 03:51:11,742 - INFO - Verified VN network id 16 for VN 5b9a7e54-dc32-4946-96fd-d0d1f138a9a8
2025-11-03 03:51:11,742 - INFO - Verifications in API Server for VN ctest-vn1-24841418 passed
2025-11-03 03:51:11,742 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/5b9a7e54-dc32-4946-96fd-d0d1f138a9a8
2025-11-03 03:51:11,753 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/e7839d9b-7a78-4232-ade5-4778fd6a86d1
2025-11-03 03:51:11,763 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/e7839d9b-7a78-4232-ade5-4778fd6a86d1
2025-11-03 03:51:11,772 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/612a390b-5f87-44be-8432-73b19607775e
2025-11-03 03:51:11,785 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn1-24841418', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-15970072', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6600727106801518918', 'uuid-lslong': '10880081874910751144'}, 'enable': 'true', 'created': '2025-11-03T03:49:11', 'last-modified': '2025-11-03T03:51:11', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.618195'}
2025-11-03 03:51:11,812 - DEBUG - Route Targets: ['target:64512:8000004']
2025-11-03 03:51:11,821 - DEBUG - Control-node 10.0.0.61 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn1-24841418', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-15970072', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6600727106801518918', 'uuid-lslong': '10880081874910751144'}, 'enable': 'true', 'created': '2025-11-03T03:49:11', 'last-modified': '2025-11-03T03:51:11', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.653404'}
2025-11-03 03:51:11,829 - DEBUG - Route Targets: ['target:64512:8000004']
2025-11-03 03:51:11,843 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn1-24841418', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-15970072', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6600727106801518918', 'uuid-lslong': '10880081874910751144'}, 'enable': 'true', 'created': '2025-11-03T03:49:11', 'last-modified': '2025-11-03T03:51:11', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.673924'}
2025-11-03 03:51:11,860 - DEBUG - Route Targets: ['target:64512:8000004']
2025-11-03 03:51:11,860 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn1-24841418 passed
2025-11-03 03:51:11,860 - DEBUG - ====Verifying policy data for ctest-vn1-24841418 in API_Server ======
2025-11-03 03:51:11,860 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-11-03 03:51:11,866 - DEBUG - Requesting: http://10.0.0.249:8082/domain/08e054d4-7d42-4fbe-9f71-53fe1c9730a1
2025-11-03 03:51:11,881 - DEBUG - Requesting: http://10.0.0.249:8082/project/6f0130e2-889f-4fdc-ac52-61ed9d15ec1a
2025-11-03 03:51:11,966 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/5b9a7e54-dc32-4946-96fd-d0d1f138a9a8
2025-11-03 03:51:11,984 - DEBUG - ==>Verifying data for policy with id: 9d43a4a7-cef5-423a-8675-2709184abe1d, fqn: ['default-domain', 'ctest-AnalyticsTestSanityWithResource-15970072', 'policy1']
2025-11-03 03:51:11,985 - INFO - VN ctest-vn1-24841418 Policy verification: verify_vn_policy_in_api_server, status: True
2025-11-03 03:51:11,985 - DEBUG - Verifying the vn in opserver
2025-11-03 03:51:11,985 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn1-24841418 virtual network link through opserver 10.0.0.249
2025-11-03 03:51:11,985 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-11-03 03:51:11,997 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn1-24841418', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn1-24841418?flat'}
2025-11-03 03:51:11,997 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn1-24841418 virtual network link through opserver 10.0.0.61
2025-11-03 03:51:11,997 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/virtual-networks
2025-11-03 03:51:12,011 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn1-24841418', 'href': 'http://10.0.0.61:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn1-24841418?flat'}
2025-11-03 03:51:12,011 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn1-24841418 virtual network link through opserver 10.0.0.23
2025-11-03 03:51:12,011 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-11-03 03:51:12,025 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn1-24841418', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn1-24841418?flat'}
2025-11-03 03:51:12,025 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn1-24841418 is found in opserver
2025-11-03 03:51:12,036 - DEBUG - VRF ids for VN ctest-vn1-24841418: {'10.0.0.73': '2'}
2025-11-03 03:51:12,036 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-11-03 03:51:12,043 - DEBUG - Requesting: http://10.0.0.249:8082/domain/08e054d4-7d42-4fbe-9f71-53fe1c9730a1
2025-11-03 03:51:12,054 - DEBUG - Requesting: http://10.0.0.249:8082/project/6f0130e2-889f-4fdc-ac52-61ed9d15ec1a
2025-11-03 03:51:12,149 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/38c8ba9b-1180-4586-845e-8f6833d53ac0
2025-11-03 03:51:12,163 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/38c8ba9b-1180-4586-845e-8f6833d53ac0
2025-11-03 03:51:12,174 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/3894e6d5-acb4-48bf-9361-a799b4a0a48a
2025-11-03 03:51:12,185 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/3894e6d5-acb4-48bf-9361-a799b4a0a48a
2025-11-03 03:51:12,193 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/88954052-100e-45df-b070-54506bd6561a
2025-11-03 03:51:12,199 - DEBUG - Route Targets: ['target:64512:8000006']
2025-11-03 03:51:12,199 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/38c8ba9b-1180-4586-845e-8f6833d53ac0
2025-11-03 03:51:12,208 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/3894e6d5-acb4-48bf-9361-a799b4a0a48a
2025-11-03 03:51:12,227 - INFO - Verified VN network id 17 for VN 38c8ba9b-1180-4586-845e-8f6833d53ac0
2025-11-03 03:51:12,227 - INFO - Verifications in API Server for VN ctest-vn2-20211888 passed
2025-11-03 03:51:12,227 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/38c8ba9b-1180-4586-845e-8f6833d53ac0
2025-11-03 03:51:12,238 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/3894e6d5-acb4-48bf-9361-a799b4a0a48a
2025-11-03 03:51:12,247 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/3894e6d5-acb4-48bf-9361-a799b4a0a48a
2025-11-03 03:51:12,256 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/88954052-100e-45df-b070-54506bd6561a
2025-11-03 03:51:12,267 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn2-20211888', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-15970072', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4091725436642411910', 'uuid-lslong': '9538218738526272192'}, 'enable': 'true', 'created': '2025-11-03T03:49:49', 'last-modified': '2025-11-03T03:51:11', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.906023'}
2025-11-03 03:51:12,277 - DEBUG - Route Targets: ['target:64512:8000006']
2025-11-03 03:51:12,283 - DEBUG - Control-node 10.0.0.61 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn2-20211888', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-15970072', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4091725436642411910', 'uuid-lslong': '9538218738526272192'}, 'enable': 'true', 'created': '2025-11-03T03:49:49', 'last-modified': '2025-11-03T03:51:11', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.925847'}
2025-11-03 03:51:12,290 - DEBUG - Route Targets: ['target:64512:8000006']
2025-11-03 03:51:12,297 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn2-20211888', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-15970072', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4091725436642411910', 'uuid-lslong': '9538218738526272192'}, 'enable': 'true', 'created': '2025-11-03T03:49:49', 'last-modified': '2025-11-03T03:51:11', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.938006'}
2025-11-03 03:51:12,306 - DEBUG - Route Targets: ['target:64512:8000006']
2025-11-03 03:51:12,306 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn2-20211888 passed
2025-11-03 03:51:12,306 - DEBUG - ====Verifying policy data for ctest-vn2-20211888 in API_Server ======
2025-11-03 03:51:12,306 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-11-03 03:51:12,313 - DEBUG - Requesting: http://10.0.0.249:8082/domain/08e054d4-7d42-4fbe-9f71-53fe1c9730a1
2025-11-03 03:51:12,326 - DEBUG - Requesting: http://10.0.0.249:8082/project/6f0130e2-889f-4fdc-ac52-61ed9d15ec1a
2025-11-03 03:51:12,443 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/38c8ba9b-1180-4586-845e-8f6833d53ac0
2025-11-03 03:51:12,457 - DEBUG - ==>Verifying data for policy with id: 9d43a4a7-cef5-423a-8675-2709184abe1d, fqn: ['default-domain', 'ctest-AnalyticsTestSanityWithResource-15970072', 'policy1']
2025-11-03 03:51:12,457 - INFO - VN ctest-vn2-20211888 Policy verification: verify_vn_policy_in_api_server, status: True
2025-11-03 03:51:12,457 - DEBUG - Verifying the vn in opserver
2025-11-03 03:51:12,457 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn2-20211888 virtual network link through opserver 10.0.0.249
2025-11-03 03:51:12,457 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-11-03 03:51:12,473 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn2-20211888', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn2-20211888?flat'}
2025-11-03 03:51:12,473 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn2-20211888 virtual network link through opserver 10.0.0.61
2025-11-03 03:51:12,473 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/virtual-networks
2025-11-03 03:51:12,489 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn2-20211888', 'href': 'http://10.0.0.61:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn2-20211888?flat'}
2025-11-03 03:51:12,489 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn2-20211888 virtual network link through opserver 10.0.0.23
2025-11-03 03:51:12,489 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-11-03 03:51:12,504 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn2-20211888', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn2-20211888?flat'}
2025-11-03 03:51:12,504 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn2-20211888 is found in opserver
2025-11-03 03:51:12,518 - DEBUG - VRF ids for VN ctest-vn2-20211888: {'10.0.0.73': '3', '10.0.0.33': '2'}
2025-11-03 03:51:12,518 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-11-03 03:51:12,524 - DEBUG - Requesting: http://10.0.0.249:8082/domain/08e054d4-7d42-4fbe-9f71-53fe1c9730a1
2025-11-03 03:51:12,535 - DEBUG - Requesting: http://10.0.0.249:8082/project/6f0130e2-889f-4fdc-ac52-61ed9d15ec1a
2025-11-03 03:51:12,631 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/5b9a7e54-dc32-4946-96fd-d0d1f138a9a8
2025-11-03 03:51:12,641 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/5b9a7e54-dc32-4946-96fd-d0d1f138a9a8
2025-11-03 03:51:12,652 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/e7839d9b-7a78-4232-ade5-4778fd6a86d1
2025-11-03 03:51:12,660 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/e7839d9b-7a78-4232-ade5-4778fd6a86d1
2025-11-03 03:51:12,667 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/612a390b-5f87-44be-8432-73b19607775e
2025-11-03 03:51:12,674 - DEBUG - Route Targets: ['target:64512:8000004']
2025-11-03 03:51:12,674 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/5b9a7e54-dc32-4946-96fd-d0d1f138a9a8
2025-11-03 03:51:12,685 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/e7839d9b-7a78-4232-ade5-4778fd6a86d1
2025-11-03 03:51:12,700 - INFO - Verified VN network id 16 for VN 5b9a7e54-dc32-4946-96fd-d0d1f138a9a8
2025-11-03 03:51:12,700 - INFO - Verifications in API Server for VN ctest-vn1-24841418 passed
2025-11-03 03:51:12,700 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/5b9a7e54-dc32-4946-96fd-d0d1f138a9a8
2025-11-03 03:51:12,712 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/e7839d9b-7a78-4232-ade5-4778fd6a86d1
2025-11-03 03:51:12,722 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/e7839d9b-7a78-4232-ade5-4778fd6a86d1
2025-11-03 03:51:12,731 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/612a390b-5f87-44be-8432-73b19607775e
2025-11-03 03:51:12,745 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn1-24841418', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-15970072', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6600727106801518918', 'uuid-lslong': '10880081874910751144'}, 'enable': 'true', 'created': '2025-11-03T03:49:11', 'last-modified': '2025-11-03T03:51:11', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.577936'}
2025-11-03 03:51:12,753 - DEBUG - Route Targets: ['target:64512:8000004']
2025-11-03 03:51:12,760 - DEBUG - Control-node 10.0.0.61 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn1-24841418', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-15970072', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6600727106801518918', 'uuid-lslong': '10880081874910751144'}, 'enable': 'true', 'created': '2025-11-03T03:49:11', 'last-modified': '2025-11-03T03:51:11', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.593473'}
2025-11-03 03:51:12,768 - DEBUG - Route Targets: ['target:64512:8000004']
2025-11-03 03:51:12,773 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn1-24841418', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-15970072', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6600727106801518918', 'uuid-lslong': '10880081874910751144'}, 'enable': 'true', 'created': '2025-11-03T03:49:11', 'last-modified': '2025-11-03T03:51:11', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.607918'}
2025-11-03 03:51:12,778 - DEBUG - Route Targets: ['target:64512:8000004']
2025-11-03 03:51:12,779 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn1-24841418 passed
2025-11-03 03:51:12,779 - DEBUG - ====Verifying policy data for ctest-vn1-24841418 in API_Server ======
2025-11-03 03:51:12,779 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-11-03 03:51:12,785 - DEBUG - Requesting: http://10.0.0.249:8082/domain/08e054d4-7d42-4fbe-9f71-53fe1c9730a1
2025-11-03 03:51:12,795 - DEBUG - Requesting: http://10.0.0.249:8082/project/6f0130e2-889f-4fdc-ac52-61ed9d15ec1a
2025-11-03 03:51:12,886 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/5b9a7e54-dc32-4946-96fd-d0d1f138a9a8
2025-11-03 03:51:12,897 - DEBUG - ==>Verifying data for policy with id: 9d43a4a7-cef5-423a-8675-2709184abe1d, fqn: ['default-domain', 'ctest-AnalyticsTestSanityWithResource-15970072', 'policy1']
2025-11-03 03:51:12,897 - INFO - VN ctest-vn1-24841418 Policy verification: verify_vn_policy_in_api_server, status: True
2025-11-03 03:51:12,897 - DEBUG - Verifying the vn in opserver
2025-11-03 03:51:12,897 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn1-24841418 virtual network link through opserver 10.0.0.249
2025-11-03 03:51:12,897 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-11-03 03:51:12,912 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn1-24841418', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn1-24841418?flat'}
2025-11-03 03:51:12,912 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn1-24841418 virtual network link through opserver 10.0.0.61
2025-11-03 03:51:12,912 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/virtual-networks
2025-11-03 03:51:12,926 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn1-24841418', 'href': 'http://10.0.0.61:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn1-24841418?flat'}
2025-11-03 03:51:12,926 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn1-24841418 virtual network link through opserver 10.0.0.23
2025-11-03 03:51:12,926 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-11-03 03:51:12,940 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn1-24841418', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn1-24841418?flat'}
2025-11-03 03:51:12,940 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn1-24841418 is found in opserver
2025-11-03 03:51:12,954 - DEBUG - VRF ids for VN ctest-vn1-24841418: {'10.0.0.73': '2'}
2025-11-03 03:51:12,955 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-11-03 03:51:12,962 - DEBUG - Requesting: http://10.0.0.249:8082/domain/08e054d4-7d42-4fbe-9f71-53fe1c9730a1
2025-11-03 03:51:12,975 - DEBUG - Requesting: http://10.0.0.249:8082/project/6f0130e2-889f-4fdc-ac52-61ed9d15ec1a
2025-11-03 03:51:13,069 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/38c8ba9b-1180-4586-845e-8f6833d53ac0
2025-11-03 03:51:13,080 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/38c8ba9b-1180-4586-845e-8f6833d53ac0
2025-11-03 03:51:13,091 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/3894e6d5-acb4-48bf-9361-a799b4a0a48a
2025-11-03 03:51:13,100 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/3894e6d5-acb4-48bf-9361-a799b4a0a48a
2025-11-03 03:51:13,109 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/88954052-100e-45df-b070-54506bd6561a
2025-11-03 03:51:13,116 - DEBUG - Route Targets: ['target:64512:8000006']
2025-11-03 03:51:13,116 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/38c8ba9b-1180-4586-845e-8f6833d53ac0
2025-11-03 03:51:13,125 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/3894e6d5-acb4-48bf-9361-a799b4a0a48a
2025-11-03 03:51:13,140 - INFO - Verified VN network id 17 for VN 38c8ba9b-1180-4586-845e-8f6833d53ac0
2025-11-03 03:51:13,141 - INFO - Verifications in API Server for VN ctest-vn2-20211888 passed
2025-11-03 03:51:13,141 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/38c8ba9b-1180-4586-845e-8f6833d53ac0
2025-11-03 03:51:13,153 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/3894e6d5-acb4-48bf-9361-a799b4a0a48a
2025-11-03 03:51:13,162 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/3894e6d5-acb4-48bf-9361-a799b4a0a48a
2025-11-03 03:51:13,169 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/88954052-100e-45df-b070-54506bd6561a
2025-11-03 03:51:13,182 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn2-20211888', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-15970072', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4091725436642411910', 'uuid-lslong': '9538218738526272192'}, 'enable': 'true', 'created': '2025-11-03T03:49:49', 'last-modified': '2025-11-03T03:51:11', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.820211'}
2025-11-03 03:51:13,189 - DEBUG - Route Targets: ['target:64512:8000006']
2025-11-03 03:51:13,196 - DEBUG - Control-node 10.0.0.61 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn2-20211888', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-15970072', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4091725436642411910', 'uuid-lslong': '9538218738526272192'}, 'enable': 'true', 'created': '2025-11-03T03:49:49', 'last-modified': '2025-11-03T03:51:11', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.838525'}
2025-11-03 03:51:13,203 - DEBUG - Route Targets: ['target:64512:8000006']
2025-11-03 03:51:13,209 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn2-20211888', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-15970072', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4091725436642411910', 'uuid-lslong': '9538218738526272192'}, 'enable': 'true', 'created': '2025-11-03T03:49:49', 'last-modified': '2025-11-03T03:51:11', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.850158'}
2025-11-03 03:51:13,215 - DEBUG - Route Targets: ['target:64512:8000006']
2025-11-03 03:51:13,215 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn2-20211888 passed
2025-11-03 03:51:13,215 - DEBUG - ====Verifying policy data for ctest-vn2-20211888 in API_Server ======
2025-11-03 03:51:13,215 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-11-03 03:51:13,221 - DEBUG - Requesting: http://10.0.0.249:8082/domain/08e054d4-7d42-4fbe-9f71-53fe1c9730a1
2025-11-03 03:51:13,233 - DEBUG - Requesting: http://10.0.0.249:8082/project/6f0130e2-889f-4fdc-ac52-61ed9d15ec1a
2025-11-03 03:51:13,326 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/38c8ba9b-1180-4586-845e-8f6833d53ac0
2025-11-03 03:51:13,335 - DEBUG - ==>Verifying data for policy with id: 9d43a4a7-cef5-423a-8675-2709184abe1d, fqn: ['default-domain', 'ctest-AnalyticsTestSanityWithResource-15970072', 'policy1']
2025-11-03 03:51:13,335 - INFO - VN ctest-vn2-20211888 Policy verification: verify_vn_policy_in_api_server, status: True
2025-11-03 03:51:13,335 - DEBUG - Verifying the vn in opserver
2025-11-03 03:51:13,335 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn2-20211888 virtual network link through opserver 10.0.0.249
2025-11-03 03:51:13,335 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-11-03 03:51:13,347 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn2-20211888', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn2-20211888?flat'}
2025-11-03 03:51:13,347 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn2-20211888 virtual network link through opserver 10.0.0.61
2025-11-03 03:51:13,347 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/virtual-networks
2025-11-03 03:51:13,360 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn2-20211888', 'href': 'http://10.0.0.61:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn2-20211888?flat'}
2025-11-03 03:51:13,360 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn2-20211888 virtual network link through opserver 10.0.0.23
2025-11-03 03:51:13,360 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-11-03 03:51:13,373 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn2-20211888', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn2-20211888?flat'}
2025-11-03 03:51:13,373 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn2-20211888 is found in opserver
2025-11-03 03:51:13,386 - DEBUG - VRF ids for VN ctest-vn2-20211888: {'10.0.0.73': '3', '10.0.0.33': '2'}
2025-11-03 03:51:13,387 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-11-03 03:51:13,395 - DEBUG - Requesting: http://10.0.0.249:8082/domain/08e054d4-7d42-4fbe-9f71-53fe1c9730a1
2025-11-03 03:51:13,414 - DEBUG - Requesting: http://10.0.0.249:8082/project/6f0130e2-889f-4fdc-ac52-61ed9d15ec1a
2025-11-03 03:51:13,520 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/fd754c8d-1e2f-4ddb-ae00-b90c1e6fba39
2025-11-03 03:51:13,532 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/fd754c8d-1e2f-4ddb-ae00-b90c1e6fba39
2025-11-03 03:51:13,544 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/d46578bf-5e62-48a1-8e50-aad4fc0013e8
2025-11-03 03:51:13,553 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/d46578bf-5e62-48a1-8e50-aad4fc0013e8
2025-11-03 03:51:13,563 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/d1a13d6d-5481-4dd6-a60a-afee788a2ddb
2025-11-03 03:51:13,570 - DEBUG - Route Targets: ['target:64512:8000007']
2025-11-03 03:51:13,570 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/fd754c8d-1e2f-4ddb-ae00-b90c1e6fba39
2025-11-03 03:51:13,580 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/d46578bf-5e62-48a1-8e50-aad4fc0013e8
2025-11-03 03:51:13,596 - INFO - Verified VN network id 18 for VN fd754c8d-1e2f-4ddb-ae00-b90c1e6fba39
2025-11-03 03:51:13,596 - INFO - Verifications in API Server for VN ctest-fip_vn-88537714 passed
2025-11-03 03:51:13,596 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/fd754c8d-1e2f-4ddb-ae00-b90c1e6fba39
2025-11-03 03:51:13,605 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/d46578bf-5e62-48a1-8e50-aad4fc0013e8
2025-11-03 03:51:13,613 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/d46578bf-5e62-48a1-8e50-aad4fc0013e8
2025-11-03 03:51:13,620 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/d1a13d6d-5481-4dd6-a60a-afee788a2ddb
2025-11-03 03:51:13,632 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-fip_vn-88537714', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-15970072', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '18263588032851430875', 'uuid-lslong': '12538224824300845625'}, 'enable': 'true', 'created': '2025-11-03T03:49:49', 'last-modified': '2025-11-03T03:49:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:01:23.524914'}
2025-11-03 03:51:13,638 - DEBUG - Route Targets: ['target:64512:8000007']
2025-11-03 03:51:13,643 - DEBUG - Control-node 10.0.0.61 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-fip_vn-88537714', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-15970072', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '18263588032851430875', 'uuid-lslong': '12538224824300845625'}, 'enable': 'true', 'created': '2025-11-03T03:49:49', 'last-modified': '2025-11-03T03:49:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:01:23.532802'}
2025-11-03 03:51:13,649 - DEBUG - Route Targets: ['target:64512:8000007']
2025-11-03 03:51:13,657 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-fip_vn-88537714', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-15970072', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '18263588032851430875', 'uuid-lslong': '12538224824300845625'}, 'enable': 'true', 'created': '2025-11-03T03:49:49', 'last-modified': '2025-11-03T03:49:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:01:23.542242'}
2025-11-03 03:51:13,663 - DEBUG - Route Targets: ['target:64512:8000007']
2025-11-03 03:51:13,663 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-fip_vn-88537714 passed
2025-11-03 03:51:13,663 - DEBUG - ====Verifying policy data for ctest-fip_vn-88537714 in API_Server ======
2025-11-03 03:51:13,663 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-11-03 03:51:13,669 - DEBUG - Requesting: http://10.0.0.249:8082/domain/08e054d4-7d42-4fbe-9f71-53fe1c9730a1
2025-11-03 03:51:13,680 - DEBUG - Requesting: http://10.0.0.249:8082/project/6f0130e2-889f-4fdc-ac52-61ed9d15ec1a
2025-11-03 03:51:13,774 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/fd754c8d-1e2f-4ddb-ae00-b90c1e6fba39
2025-11-03 03:51:13,784 - DEBUG - =>VN ctest-fip_vn-88537714 has no policy to be verified
2025-11-03 03:51:13,784 - DEBUG - Verifying the vn in opserver
2025-11-03 03:51:13,784 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-fip_vn-88537714 virtual network link through opserver 10.0.0.249
2025-11-03 03:51:13,784 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-11-03 03:51:13,795 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-fip_vn-88537714', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-fip_vn-88537714?flat'}
2025-11-03 03:51:13,795 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-fip_vn-88537714 virtual network link through opserver 10.0.0.61
2025-11-03 03:51:13,795 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/virtual-networks
2025-11-03 03:51:13,808 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-fip_vn-88537714', 'href': 'http://10.0.0.61:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-fip_vn-88537714?flat'}
2025-11-03 03:51:13,808 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-fip_vn-88537714 virtual network link through opserver 10.0.0.23
2025-11-03 03:51:13,808 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-11-03 03:51:13,821 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-fip_vn-88537714', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-fip_vn-88537714?flat'}
2025-11-03 03:51:13,821 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-fip_vn-88537714 is found in opserver
2025-11-03 03:51:13,832 - DEBUG - VRF ids for VN ctest-fip_vn-88537714: {'10.0.0.33': '3'}
2025-11-03 03:51:13,832 - INFO - start time= 1762141873832418
2025-11-03 03:51:13,832 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 217.184.145.3, host_string: cirros@169.254.0.3, password: gocubsgogateway: ubuntu@10.0.0.73, gateway password: c0ntrail123
2025-11-03 03:51:13,832 - DEBUG - ping -s 56 -c 3 -W 1 217.184.145.3
2025-11-03 03:51:17,466 - DEBUG - PING 217.184.145.3 (217.184.145.3): 56 data bytes
64 bytes from 217.184.145.3: seq=0 ttl=63 time=5.190 ms
64 bytes from 217.184.145.3: seq=1 ttl=63 time=1.898 ms
64 bytes from 217.184.145.3: seq=2 ttl=63 time=1.317 ms
--- 217.184.145.3 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 1.317/2.801/5.190 ms
2025-11-03 03:51:17,466 - INFO - Ping to IP 217.184.145.3 from VM ctest-vn1_vm1-20422891 passed
2025-11-03 03:51:27,467 - INFO - Verify session samples and teardown pkts
2025-11-03 03:51:27,467 - INFO - Verifying SessionSeriesTable through opserver 10.0.0.249
2025-11-03 03:51:27,471 - DEBUG - Posting: http://10.0.0.249:8081/analytics/query, payload {'table': 'SessionSeriesTable', 'start_time': 1762141873832418, 'end_time': 1762141887470959, 'select_fields': ['T'], 'where': [[{'name': 'vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn1-24841418', 'op': 1, 'value2': None}, {'name': 'remote_vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn2-20211888', 'op': 1, 'value2': None}, {'name': 'protocol', 'value': '1', 'op': 1, 'value2': None}]], 'session_type': 'client'}
2025-11-03 03:51:28,491 - DEBUG - [{'T': 1762141875446192}, {'T': 1762141876446250}, {'T': 1762141877446414}]
2025-11-03 03:51:28,491 - DEBUG - Posting: http://10.0.0.249:8081/analytics/query, payload {'table': 'SessionSeriesTable', 'start_time': 1762141873832418, 'end_time': 1762141888491803, 'select_fields': ['T'], 'where': [[{'name': 'vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn2-20211888', 'op': 1, 'value2': None}, {'name': 'remote_vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn1-24841418', 'op': 1, 'value2': None}, {'name': 'protocol', 'value': '1', 'op': 1, 'value2': None}]], 'session_type': 'server'}
2025-11-03 03:51:29,513 - DEBUG - [{'T': 1762141876101793}, {'T': 1762141877101364}, {'T': 1762141878101428}]
2025-11-03 03:51:29,513 - DEBUG - Posting: http://10.0.0.249:8081/analytics/query, payload {'table': 'SessionSeriesTable', 'start_time': 1762141873832418, 'end_time': 1762141889513880, 'select_fields': ['vn', 'remote_vn', 'sample_count'], 'where': [[{'name': 'vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn1-24841418', 'op': 1, 'value2': None}, {'name': 'remote_vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn2-20211888', 'op': 1, 'value2': None}, {'name': 'protocol', 'value': '1', 'op': 1, 'value2': None}]], 'session_type': 'client'}
2025-11-03 03:51:30,532 - DEBUG - [{'remote_vn': 'default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn2-20211888', 'vn': 'default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn1-24841418', 'sample_count': 3}]
2025-11-03 03:51:30,532 - INFO - wait for the flows to get expire
2025-11-03 03:54:50,533 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4597-1:Compute:contrail-vrouter-agent:0?flat
2025-11-03 03:54:50,569 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4597-1?flat
2025-11-03 03:54:50,598 - DEBUG - Posting: http://10.0.0.249:8081/analytics/query, payload {'table': 'SessionRecordTable', 'start_time': 1762141873832418, 'end_time': 1762142090598594, 'select_fields': ['vn', 'remote_vn', 'forward_teardown_pkts', 'reverse_teardown_pkts'], 'where': [[{'name': 'vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn1-24841418', 'op': 1, 'value2': None}, {'name': 'remote_vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn2-20211888', 'op': 1, 'value2': None}, {'name': 'protocol', 'value': '1', 'op': 1, 'value2': None}]], 'session_type': 'client'}
2025-11-03 03:54:51,667 - DEBUG - [{'forward_flow_uuid': '9a43a337-82ed-4e2e-a70d-9002e26707c7', 'remote_vn': 'default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn2-20211888', 'reverse_flow_uuid': '8bc8445c-fdf5-42ad-a293-03e40710f62e', 'vn': 'default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn1-24841418', 'forward_teardown_pkts': 3, 'reverse_teardown_pkts': 3}]
2025-11-03 03:54:51,667 - DEBUG - Posting: http://10.0.0.249:8081/analytics/query, payload {'table': 'SessionSeriesTable', 'start_time': 1762141873832418, 'end_time': 1762142091667891, 'select_fields': ['vn', 'remote_vn', 'sample_count'], 'where': [[{'name': 'vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn1-24841418', 'op': 1, 'value2': None}, {'name': 'remote_vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn2-20211888', 'op': 1, 'value2': None}, {'name': 'protocol', 'value': '1', 'op': 1, 'value2': None}]], 'session_type': 'client'}
2025-11-03 03:54:52,686 - DEBUG - Posting: http://10.0.0.249:8081/analytics/query, payload {'table': 'SessionSeriesTable', 'start_time': 1762141873832418, 'end_time': 1762142092686368, 'select_fields': ['vn', 'remote_vn', 'sample_count'], 'where': [[{'name': 'vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn2-20211888', 'op': 1, 'value2': None}, {'name': 'remote_vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn1-24841418', 'op': 1, 'value2': None}, {'name': 'protocol', 'value': '1', 'op': 1, 'value2': None}]], 'session_type': 'server'}
2025-11-03 03:54:53,707 - DEBUG - [{'remote_vn': 'default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn1-24841418', 'vn': 'default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn2-20211888', 'sample_count': 4}]
2025-11-03 03:54:54,224 - INFO - Deleted policy policy1
2025-11-03 03:54:54,343 - INFO - Setting flow export rate: None
2025-11-03 03:54:55,651 - DEBUG - Skipping xmpp flap check
2025-11-03 03:54:55,651 - INFO - END TEST : test_verify_session_sampling_teardown : PASSED[0:03:46]
2025-11-03 03:54:55,651 - INFO - --------------------------------------------------------------------------------
2025-11-03 03:54:55,653 - INFO - Deleting VM mltf_vm
2025-11-03 03:54:55,727 - INFO - Deleting VM ctest-fvn_vm1-92349819
2025-11-03 03:54:55,804 - INFO - Deleting VM ctest-vn2_vm2-88596927
2025-11-03 03:54:55,867 - INFO - Deleting VN ctest-fip_vn-88537714
2025-11-03 03:54:55,916 - DEBUG - VN fd754c8d-1e2f-4ddb-ae00-b90c1e6fba39 still in use: Unable to complete operation on network fd754c8d-1e2f-4ddb-ae00-b90c1e6fba39. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-59753380-b7d7-46f0-8b85-c9ce47150d88']
2025-11-03 03:54:55,917 - WARNING - Deleting VN ctest-fip_vn-88537714 failed..Will retry
2025-11-03 03:54:58,166 - DEBUG - Response for deleting network ()
2025-11-03 03:54:58,166 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/d46578bf-5e62-48a1-8e50-aad4fc0013e8
2025-11-03 03:54:58,211 - DEBUG - Response Code: 404
2025-11-03 03:54:58,211 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-11-03 03:54:58,216 - DEBUG - Requesting: http://10.0.0.249:8082/domain/08e054d4-7d42-4fbe-9f71-53fe1c9730a1
2025-11-03 03:54:58,227 - DEBUG - Requesting: http://10.0.0.249:8082/project/6f0130e2-889f-4fdc-ac52-61ed9d15ec1a
2025-11-03 03:54:58,331 - INFO - Validated that VN ctest-fip_vn-88537714 is not found in API Server
2025-11-03 03:54:58,342 - DEBUG - VN ctest-fip_vn-88537714 is not present in Agent 10.0.0.73
2025-11-03 03:54:58,352 - DEBUG - VN ctest-fip_vn-88537714 is not present in Agent 10.0.0.33
2025-11-03 03:54:58,352 - INFO - Validated that VN ctest-fip_vn-88537714 is not in any agent
2025-11-03 03:54:58,357 - DEBUG - VRF 3 is not seen in agent 10.0.0.33
2025-11-03 03:54:58,367 - DEBUG - Vrouter 10.0.0.33 does not have vrf 3 for VN ctest-fip_vn-88537714
2025-11-03 03:54:58,367 - INFO - Validated that all vrouters do not have the route table for VN default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-fip_vn-88537714
2025-11-03 03:54:58,393 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-fip_vn-88537714 info
2025-11-03 03:54:58,393 - INFO - Deleting VN ctest-vn2-20211888
2025-11-03 03:54:58,603 - DEBUG - Response for deleting network ()
2025-11-03 03:54:58,603 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/3894e6d5-acb4-48bf-9361-a799b4a0a48a
2025-11-03 03:54:58,609 - DEBUG - Response Code: 404
2025-11-03 03:54:58,609 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-11-03 03:54:58,615 - DEBUG - Requesting: http://10.0.0.249:8082/domain/08e054d4-7d42-4fbe-9f71-53fe1c9730a1
2025-11-03 03:54:58,627 - DEBUG - Requesting: http://10.0.0.249:8082/project/6f0130e2-889f-4fdc-ac52-61ed9d15ec1a
2025-11-03 03:54:58,745 - INFO - Validated that VN ctest-vn2-20211888 is not found in API Server
2025-11-03 03:54:58,758 - DEBUG - VN ctest-vn2-20211888 is not present in Agent 10.0.0.73
2025-11-03 03:54:58,768 - DEBUG - VN ctest-vn2-20211888 is not present in Agent 10.0.0.33
2025-11-03 03:54:58,768 - INFO - Validated that VN ctest-vn2-20211888 is not in any agent
2025-11-03 03:54:58,775 - DEBUG - VRF 3 is not seen in agent 10.0.0.73
2025-11-03 03:54:58,784 - DEBUG - Vrouter 10.0.0.73 does not have vrf 3 for VN ctest-vn2-20211888
2025-11-03 03:54:58,790 - DEBUG - VRF 2 is not seen in agent 10.0.0.33
2025-11-03 03:54:58,795 - DEBUG - Vrouter 10.0.0.33 does not have vrf 2 for VN ctest-vn2-20211888
2025-11-03 03:54:58,795 - INFO - Validated that all vrouters do not have the route table for VN default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn2-20211888
2025-11-03 03:54:58,821 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn2-20211888 info
2025-11-03 03:54:58,822 - INFO - Deleting VM ctest-vn1_vm2-69065421
2025-11-03 03:54:58,907 - INFO - Deleting VM ctest-vn1_vm1-20422891
2025-11-03 03:54:58,986 - INFO - Deleting VN ctest-vn1-24841418
2025-11-03 03:54:59,044 - DEBUG - VN 5b9a7e54-dc32-4946-96fd-d0d1f138a9a8 still in use: Unable to complete operation on network 5b9a7e54-dc32-4946-96fd-d0d1f138a9a8. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-d82e29c9-d747-46fe-998a-87394d9cbf84']
2025-11-03 03:54:59,045 - WARNING - Deleting VN ctest-vn1-24841418 failed..Will retry
2025-11-03 03:55:01,214 - DEBUG - Response for deleting network ()
2025-11-03 03:55:01,214 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/e7839d9b-7a78-4232-ade5-4778fd6a86d1
2025-11-03 03:55:01,220 - DEBUG - Response Code: 404
2025-11-03 03:55:01,220 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-11-03 03:55:01,225 - DEBUG - Requesting: http://10.0.0.249:8082/domain/08e054d4-7d42-4fbe-9f71-53fe1c9730a1
2025-11-03 03:55:01,281 - DEBUG - Requesting: http://10.0.0.249:8082/project/6f0130e2-889f-4fdc-ac52-61ed9d15ec1a
2025-11-03 03:55:01,370 - INFO - Validated that VN ctest-vn1-24841418 is not found in API Server
2025-11-03 03:55:01,380 - DEBUG - VN ctest-vn1-24841418 is not present in Agent 10.0.0.73
2025-11-03 03:55:01,391 - DEBUG - VN ctest-vn1-24841418 is not present in Agent 10.0.0.33
2025-11-03 03:55:01,391 - INFO - Validated that VN ctest-vn1-24841418 is not in any agent
2025-11-03 03:55:01,397 - DEBUG - VRF 2 is not seen in agent 10.0.0.73
2025-11-03 03:55:01,402 - DEBUG - Vrouter 10.0.0.73 does not have vrf 2 for VN ctest-vn1-24841418
2025-11-03 03:55:01,402 - INFO - Validated that all vrouters do not have the route table for VN default-domain:ctest-AnalyticsTestSanityWithResource-15970072:ctest-vn1-24841418
2025-11-03 03:55:01,424 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn1-24841418 info
2025-11-03 03:55:02,177 - INFO - Deleted project: ctest-AnalyticsTestSanityWithResource-15970072, ID : 6f0130e2-889f-4fdc-ac52-61ed9d15ec1a