is in ACTIVE state now
2025-10-29 19:30:51,082 - INFO - VM name : mltf_vm
2025-10-29 19:30:51,269 - DEBUG - VM mltf_vm ID is 1fb6ec1e-bdd1-49b3-a1f4-56a443f9d0e5
2025-10-29 19:30:51,270 - DEBUG - VM mltf_vm launched on Node an-jenkins-deploy-platform-ansible-os-4526-2
2025-10-29 19:30:51,679 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/1fb6ec1e-bdd1-49b3-a1f4-56a443f9d0e5
2025-10-29 19:30:51,691 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/a40fc40d-acd2-4a9c-8f69-4709d3959a3e
2025-10-29 19:30:51,703 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/b6f7f2d6-caf2-4f1e-a506-eaf88efadcac
2025-10-29 19:30:52,897 - 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=1.66 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=0.398 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.398/1.030/1.662/0.632 ms')
2025-10-29 19:30:52,897 - INFO - Ping to Metadata IP 169.254.0.5 of VM mltf_vm passed
2025-10-29 19:30:52,970 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-29 19:30:52,970 - DEBUG - Waiting to SSH to VM mltf_vm, IP 208.104.18.69, Port 22
2025-10-29 19:30:53,137 - DEBUG - VM mltf_vm is ready for SSH connections
2025-10-29 19:30:53,221 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-29 19:30:53,222 - DEBUG - Waiting to SSH to VM mltf_vm, IP 208.104.18.69, Port 22
2025-10-29 19:30:53,376 - DEBUG - VM mltf_vm is ready for SSH connections
2025-10-29 19:30:53,382 - INFO - ================================================================================
2025-10-29 19:30:53,382 - INFO - STARTING TEST : test_verify_session_sampling_teardown
2025-10-29 19:30:53,382 - 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-10-29 19:30:54,780 - DEBUG - Skipping xmpp flap check
2025-10-29 19:30:54,780 - INFO - Initial checks done. Running the testcase now
2025-10-29 19:30:54,780 - INFO -
2025-10-29 19:30:54,921 - INFO - Setting flow export rate: 100
2025-10-29 19:30:54,974 - 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-10-29 19:30:55,405 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '2d854077-15c4-48e0-b782-f3fc08dd6bfc', 'name': 'ctest-vn1-95064950', 'tenant_id': '05363ea9d24d4e5180a2ae2bddc3bafb', 'project_id': '05363ea9d24d4e5180a2ae2bddc3bafb', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['c1d5cefa-6867-44f2-a68e-6c6b038b5f1e'], 'fq_name': ['default-domain', 'ctest-AnalyticsTestSanityWithResource-16339681', 'ctest-vn1-95064950'], 'policys': [['default-domain', 'ctest-AnalyticsTestSanityWithResource-16339681', 'policy1']], 'subnet_ipam': [{'subnet_cidr': '208.104.18.64/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}}
2025-10-29 19:30:55,602 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': 'cb6f402c-3447-4fcb-a3f7-fa15f967e2e1', 'name': 'ctest-vn2-89837037', 'tenant_id': '05363ea9d24d4e5180a2ae2bddc3bafb', 'project_id': '05363ea9d24d4e5180a2ae2bddc3bafb', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['338cb2a3-b181-4ab7-a614-064503678fa5'], 'fq_name': ['default-domain', 'ctest-AnalyticsTestSanityWithResource-16339681', 'ctest-vn2-89837037'], 'policys': [['default-domain', 'ctest-AnalyticsTestSanityWithResource-16339681', 'policy1']], 'subnet_ipam': [{'subnet_cidr': '65.36.178.128/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}}
2025-10-29 19:30:55,685 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 19:30:55,693 - DEBUG - Requesting: http://10.0.0.249:8082/domain/ab5f52cf-59f5-4d8f-a294-1eaf08d80442
2025-10-29 19:30:55,734 - DEBUG - Requesting: http://10.0.0.249:8082/project/05363ea9-d24d-4e51-80a2-ae2bddc3bafb
2025-10-29 19:30:55,859 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/2d854077-15c4-48e0-b782-f3fc08dd6bfc
2025-10-29 19:30:55,878 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/2d854077-15c4-48e0-b782-f3fc08dd6bfc
2025-10-29 19:30:55,892 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/444a0ac5-1f43-4466-9c25-c47868018f95
2025-10-29 19:30:55,904 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/444a0ac5-1f43-4466-9c25-c47868018f95
2025-10-29 19:30:55,915 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/13affdfa-82d1-4251-be12-15430693197d
2025-10-29 19:30:55,924 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-29 19:30:55,925 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/2d854077-15c4-48e0-b782-f3fc08dd6bfc
2025-10-29 19:30:55,958 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/444a0ac5-1f43-4466-9c25-c47868018f95
2025-10-29 19:30:55,979 - INFO - Verified VN network id 11 for VN 2d854077-15c4-48e0-b782-f3fc08dd6bfc
2025-10-29 19:30:55,980 - INFO - Verifications in API Server for VN ctest-vn1-95064950 passed
2025-10-29 19:30:55,980 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/2d854077-15c4-48e0-b782-f3fc08dd6bfc
2025-10-29 19:30:55,990 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/444a0ac5-1f43-4466-9c25-c47868018f95
2025-10-29 19:30:55,997 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/444a0ac5-1f43-4466-9c25-c47868018f95
2025-10-29 19:30:56,013 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/13affdfa-82d1-4251-be12-15430693197d
2025-10-29 19:30:56,027 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn1-95064950', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-16339681', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3280098783819745504', 'uuid-lslong': '13223399719719234556'}, 'enable': 'true', 'created': '2025-10-29T19:29:30', 'last-modified': '2025-10-29T19:30:55', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.670060'}
2025-10-29 19:30:56,032 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-29 19:30:56,039 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn1-95064950', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-16339681', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3280098783819745504', 'uuid-lslong': '13223399719719234556'}, 'enable': 'true', 'created': '2025-10-29T19:29:30', 'last-modified': '2025-10-29T19:30:55', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.681136'}
2025-10-29 19:30:56,046 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-29 19:30:56,051 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn1-95064950', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-16339681', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3280098783819745504', 'uuid-lslong': '13223399719719234556'}, 'enable': 'true', 'created': '2025-10-29T19:29:30', 'last-modified': '2025-10-29T19:30:55', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.689056'}
2025-10-29 19:30:56,062 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-29 19:30:56,063 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn1-95064950 passed
2025-10-29 19:30:56,063 - DEBUG - ====Verifying policy data for ctest-vn1-95064950 in API_Server ======
2025-10-29 19:30:56,063 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 19:30:56,071 - DEBUG - Requesting: http://10.0.0.249:8082/domain/ab5f52cf-59f5-4d8f-a294-1eaf08d80442
2025-10-29 19:30:56,083 - DEBUG - Requesting: http://10.0.0.249:8082/project/05363ea9-d24d-4e51-80a2-ae2bddc3bafb
2025-10-29 19:30:56,185 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/2d854077-15c4-48e0-b782-f3fc08dd6bfc
2025-10-29 19:30:56,196 - DEBUG - ==>Verifying data for policy with id: f960dbac-507c-4eeb-b3f2-a888fea73d47, fqn: ['default-domain', 'ctest-AnalyticsTestSanityWithResource-16339681', 'policy1']
2025-10-29 19:30:56,196 - INFO - VN ctest-vn1-95064950 Policy verification: verify_vn_policy_in_api_server, status: True
2025-10-29 19:30:56,196 - DEBUG - Verifying the vn in opserver
2025-10-29 19:30:56,196 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn1-95064950 virtual network link through opserver 10.0.0.249
2025-10-29 19:30:56,196 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-29 19:30:56,210 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn1-95064950', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn1-95064950?flat'}
2025-10-29 19:30:56,210 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn1-95064950 virtual network link through opserver 10.0.0.19
2025-10-29 19:30:56,210 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-29 19:30:56,230 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn1-95064950', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn1-95064950?flat'}
2025-10-29 19:30:56,230 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn1-95064950 virtual network link through opserver 10.0.0.41
2025-10-29 19:30:56,230 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-29 19:30:56,242 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn1-95064950', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn1-95064950?flat'}
2025-10-29 19:30:56,242 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn1-95064950 is found in opserver
2025-10-29 19:30:56,261 - DEBUG - VRF ids for VN ctest-vn1-95064950: {'10.0.0.27': '2'}
2025-10-29 19:30:56,261 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 19:30:56,268 - DEBUG - Requesting: http://10.0.0.249:8082/domain/ab5f52cf-59f5-4d8f-a294-1eaf08d80442
2025-10-29 19:30:56,281 - DEBUG - Requesting: http://10.0.0.249:8082/project/05363ea9-d24d-4e51-80a2-ae2bddc3bafb
2025-10-29 19:30:56,388 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/cb6f402c-3447-4fcb-a3f7-fa15f967e2e1
2025-10-29 19:30:56,405 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/cb6f402c-3447-4fcb-a3f7-fa15f967e2e1
2025-10-29 19:30:56,417 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/885d0b32-678f-4812-b587-4b093c6c6408
2025-10-29 19:30:56,428 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/885d0b32-678f-4812-b587-4b093c6c6408
2025-10-29 19:30:56,437 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/06a18b18-1059-42bc-b6b7-fedec5cea696
2025-10-29 19:30:56,445 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-29 19:30:56,445 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/cb6f402c-3447-4fcb-a3f7-fa15f967e2e1
2025-10-29 19:30:56,457 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/885d0b32-678f-4812-b587-4b093c6c6408
2025-10-29 19:30:56,475 - INFO - Verified VN network id 12 for VN cb6f402c-3447-4fcb-a3f7-fa15f967e2e1
2025-10-29 19:30:56,476 - INFO - Verifications in API Server for VN ctest-vn2-89837037 passed
2025-10-29 19:30:56,476 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/cb6f402c-3447-4fcb-a3f7-fa15f967e2e1
2025-10-29 19:30:56,490 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/885d0b32-678f-4812-b587-4b093c6c6408
2025-10-29 19:30:56,499 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/885d0b32-678f-4812-b587-4b093c6c6408
2025-10-29 19:30:56,510 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/06a18b18-1059-42bc-b6b7-fedec5cea696
2025-10-29 19:30:56,529 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn2-89837037', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-16339681', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14659005870714081227', 'uuid-lslong': '11815187119715377889'}, 'enable': 'true', 'created': '2025-10-29T19:30:12', 'last-modified': '2025-10-29T19:30:55', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.952545'}
2025-10-29 19:30:56,540 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-29 19:30:56,546 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn2-89837037', 'interests': '0-1', 'advertised': '0-1', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-16339681', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14659005870714081227', 'uuid-lslong': '11815187119715377889'}, 'enable': 'true', 'created': '2025-10-29T19:30:12', 'last-modified': '2025-10-29T19:30:55', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.988467'}
2025-10-29 19:30:56,552 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-29 19:30:56,557 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn2-89837037', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-16339681', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14659005870714081227', 'uuid-lslong': '11815187119715377889'}, 'enable': 'true', 'created': '2025-10-29T19:30:12', 'last-modified': '2025-10-29T19:30:55', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.998750'}
2025-10-29 19:30:56,565 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-29 19:30:56,565 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn2-89837037 passed
2025-10-29 19:30:56,565 - DEBUG - ====Verifying policy data for ctest-vn2-89837037 in API_Server ======
2025-10-29 19:30:56,565 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 19:30:56,573 - DEBUG - Requesting: http://10.0.0.249:8082/domain/ab5f52cf-59f5-4d8f-a294-1eaf08d80442
2025-10-29 19:30:56,588 - DEBUG - Requesting: http://10.0.0.249:8082/project/05363ea9-d24d-4e51-80a2-ae2bddc3bafb
2025-10-29 19:30:56,683 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/cb6f402c-3447-4fcb-a3f7-fa15f967e2e1
2025-10-29 19:30:56,700 - DEBUG - ==>Verifying data for policy with id: f960dbac-507c-4eeb-b3f2-a888fea73d47, fqn: ['default-domain', 'ctest-AnalyticsTestSanityWithResource-16339681', 'policy1']
2025-10-29 19:30:56,700 - INFO - VN ctest-vn2-89837037 Policy verification: verify_vn_policy_in_api_server, status: True
2025-10-29 19:30:56,700 - DEBUG - Verifying the vn in opserver
2025-10-29 19:30:56,700 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn2-89837037 virtual network link through opserver 10.0.0.249
2025-10-29 19:30:56,700 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-29 19:30:56,716 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn2-89837037', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn2-89837037?flat'}
2025-10-29 19:30:56,716 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn2-89837037 virtual network link through opserver 10.0.0.19
2025-10-29 19:30:56,716 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-29 19:30:56,732 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn2-89837037', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn2-89837037?flat'}
2025-10-29 19:30:56,732 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn2-89837037 virtual network link through opserver 10.0.0.41
2025-10-29 19:30:56,732 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-29 19:30:56,745 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn2-89837037', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn2-89837037?flat'}
2025-10-29 19:30:56,746 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn2-89837037 is found in opserver
2025-10-29 19:30:56,762 - DEBUG - VRF ids for VN ctest-vn2-89837037: {'10.0.0.73': '2', '10.0.0.27': '3'}
2025-10-29 19:30:56,762 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 19:30:56,768 - DEBUG - Requesting: http://10.0.0.249:8082/domain/ab5f52cf-59f5-4d8f-a294-1eaf08d80442
2025-10-29 19:30:56,783 - DEBUG - Requesting: http://10.0.0.249:8082/project/05363ea9-d24d-4e51-80a2-ae2bddc3bafb
2025-10-29 19:30:56,892 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/2d854077-15c4-48e0-b782-f3fc08dd6bfc
2025-10-29 19:30:56,909 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/2d854077-15c4-48e0-b782-f3fc08dd6bfc
2025-10-29 19:30:56,922 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/444a0ac5-1f43-4466-9c25-c47868018f95
2025-10-29 19:30:56,935 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/444a0ac5-1f43-4466-9c25-c47868018f95
2025-10-29 19:30:56,944 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/13affdfa-82d1-4251-be12-15430693197d
2025-10-29 19:30:56,953 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-29 19:30:56,953 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/2d854077-15c4-48e0-b782-f3fc08dd6bfc
2025-10-29 19:30:56,963 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/444a0ac5-1f43-4466-9c25-c47868018f95
2025-10-29 19:30:56,981 - INFO - Verified VN network id 11 for VN 2d854077-15c4-48e0-b782-f3fc08dd6bfc
2025-10-29 19:30:56,981 - INFO - Verifications in API Server for VN ctest-vn1-95064950 passed
2025-10-29 19:30:56,982 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/2d854077-15c4-48e0-b782-f3fc08dd6bfc
2025-10-29 19:30:56,995 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/444a0ac5-1f43-4466-9c25-c47868018f95
2025-10-29 19:30:57,004 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/444a0ac5-1f43-4466-9c25-c47868018f95
2025-10-29 19:30:57,014 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/13affdfa-82d1-4251-be12-15430693197d
2025-10-29 19:30:57,033 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn1-95064950', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-16339681', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3280098783819745504', 'uuid-lslong': '13223399719719234556'}, 'enable': 'true', 'created': '2025-10-29T19:29:30', 'last-modified': '2025-10-29T19:30:55', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.673957'}
2025-10-29 19:30:57,039 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-29 19:30:57,048 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn1-95064950', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-16339681', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3280098783819745504', 'uuid-lslong': '13223399719719234556'}, 'enable': 'true', 'created': '2025-10-29T19:29:30', 'last-modified': '2025-10-29T19:30:55', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.688570'}
2025-10-29 19:30:57,058 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-29 19:30:57,067 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn1-95064950', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-16339681', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3280098783819745504', 'uuid-lslong': '13223399719719234556'}, 'enable': 'true', 'created': '2025-10-29T19:29:30', 'last-modified': '2025-10-29T19:30:55', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.702337'}
2025-10-29 19:30:57,073 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-29 19:30:57,073 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn1-95064950 passed
2025-10-29 19:30:57,074 - DEBUG - ====Verifying policy data for ctest-vn1-95064950 in API_Server ======
2025-10-29 19:30:57,074 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 19:30:57,081 - DEBUG - Requesting: http://10.0.0.249:8082/domain/ab5f52cf-59f5-4d8f-a294-1eaf08d80442
2025-10-29 19:30:57,093 - DEBUG - Requesting: http://10.0.0.249:8082/project/05363ea9-d24d-4e51-80a2-ae2bddc3bafb
2025-10-29 19:30:57,186 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/2d854077-15c4-48e0-b782-f3fc08dd6bfc
2025-10-29 19:30:57,197 - DEBUG - ==>Verifying data for policy with id: f960dbac-507c-4eeb-b3f2-a888fea73d47, fqn: ['default-domain', 'ctest-AnalyticsTestSanityWithResource-16339681', 'policy1']
2025-10-29 19:30:57,197 - INFO - VN ctest-vn1-95064950 Policy verification: verify_vn_policy_in_api_server, status: True
2025-10-29 19:30:57,197 - DEBUG - Verifying the vn in opserver
2025-10-29 19:30:57,197 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn1-95064950 virtual network link through opserver 10.0.0.249
2025-10-29 19:30:57,197 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-29 19:30:57,214 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn1-95064950', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn1-95064950?flat'}
2025-10-29 19:30:57,214 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn1-95064950 virtual network link through opserver 10.0.0.19
2025-10-29 19:30:57,214 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-29 19:30:57,228 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn1-95064950', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn1-95064950?flat'}
2025-10-29 19:30:57,228 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn1-95064950 virtual network link through opserver 10.0.0.41
2025-10-29 19:30:57,228 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-29 19:30:57,240 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn1-95064950', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn1-95064950?flat'}
2025-10-29 19:30:57,240 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn1-95064950 is found in opserver
2025-10-29 19:30:57,257 - DEBUG - VRF ids for VN ctest-vn1-95064950: {'10.0.0.27': '2'}
2025-10-29 19:30:57,257 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 19:30:57,263 - DEBUG - Requesting: http://10.0.0.249:8082/domain/ab5f52cf-59f5-4d8f-a294-1eaf08d80442
2025-10-29 19:30:57,274 - DEBUG - Requesting: http://10.0.0.249:8082/project/05363ea9-d24d-4e51-80a2-ae2bddc3bafb
2025-10-29 19:30:57,365 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/cb6f402c-3447-4fcb-a3f7-fa15f967e2e1
2025-10-29 19:30:57,377 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/cb6f402c-3447-4fcb-a3f7-fa15f967e2e1
2025-10-29 19:30:57,387 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/885d0b32-678f-4812-b587-4b093c6c6408
2025-10-29 19:30:57,395 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/885d0b32-678f-4812-b587-4b093c6c6408
2025-10-29 19:30:57,405 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/06a18b18-1059-42bc-b6b7-fedec5cea696
2025-10-29 19:30:57,412 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-29 19:30:57,412 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/cb6f402c-3447-4fcb-a3f7-fa15f967e2e1
2025-10-29 19:30:57,423 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/885d0b32-678f-4812-b587-4b093c6c6408
2025-10-29 19:30:57,438 - INFO - Verified VN network id 12 for VN cb6f402c-3447-4fcb-a3f7-fa15f967e2e1
2025-10-29 19:30:57,438 - INFO - Verifications in API Server for VN ctest-vn2-89837037 passed
2025-10-29 19:30:57,438 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/cb6f402c-3447-4fcb-a3f7-fa15f967e2e1
2025-10-29 19:30:57,450 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/885d0b32-678f-4812-b587-4b093c6c6408
2025-10-29 19:30:57,458 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/885d0b32-678f-4812-b587-4b093c6c6408
2025-10-29 19:30:57,465 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/06a18b18-1059-42bc-b6b7-fedec5cea696
2025-10-29 19:30:57,480 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn2-89837037', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-16339681', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14659005870714081227', 'uuid-lslong': '11815187119715377889'}, 'enable': 'true', 'created': '2025-10-29T19:30:12', 'last-modified': '2025-10-29T19:30:55', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.903084'}
2025-10-29 19:30:57,485 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-29 19:30:57,492 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn2-89837037', 'interests': '0-1', 'advertised': '0-1', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-16339681', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14659005870714081227', 'uuid-lslong': '11815187119715377889'}, 'enable': 'true', 'created': '2025-10-29T19:30:12', 'last-modified': '2025-10-29T19:30:55', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.933720'}
2025-10-29 19:30:57,499 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-29 19:30:57,504 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn2-89837037', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-16339681', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14659005870714081227', 'uuid-lslong': '11815187119715377889'}, 'enable': 'true', 'created': '2025-10-29T19:30:12', 'last-modified': '2025-10-29T19:30:55', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.945314'}
2025-10-29 19:30:57,511 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-29 19:30:57,511 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn2-89837037 passed
2025-10-29 19:30:57,511 - DEBUG - ====Verifying policy data for ctest-vn2-89837037 in API_Server ======
2025-10-29 19:30:57,511 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 19:30:57,518 - DEBUG - Requesting: http://10.0.0.249:8082/domain/ab5f52cf-59f5-4d8f-a294-1eaf08d80442
2025-10-29 19:30:57,532 - DEBUG - Requesting: http://10.0.0.249:8082/project/05363ea9-d24d-4e51-80a2-ae2bddc3bafb
2025-10-29 19:30:57,656 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/cb6f402c-3447-4fcb-a3f7-fa15f967e2e1
2025-10-29 19:30:57,672 - DEBUG - ==>Verifying data for policy with id: f960dbac-507c-4eeb-b3f2-a888fea73d47, fqn: ['default-domain', 'ctest-AnalyticsTestSanityWithResource-16339681', 'policy1']
2025-10-29 19:30:57,672 - INFO - VN ctest-vn2-89837037 Policy verification: verify_vn_policy_in_api_server, status: True
2025-10-29 19:30:57,672 - DEBUG - Verifying the vn in opserver
2025-10-29 19:30:57,672 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn2-89837037 virtual network link through opserver 10.0.0.249
2025-10-29 19:30:57,672 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-29 19:30:57,687 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn2-89837037', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn2-89837037?flat'}
2025-10-29 19:30:57,687 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn2-89837037 virtual network link through opserver 10.0.0.19
2025-10-29 19:30:57,687 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-29 19:30:57,704 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn2-89837037', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn2-89837037?flat'}
2025-10-29 19:30:57,704 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn2-89837037 virtual network link through opserver 10.0.0.41
2025-10-29 19:30:57,704 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-29 19:30:57,717 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn2-89837037', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn2-89837037?flat'}
2025-10-29 19:30:57,717 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn2-89837037 is found in opserver
2025-10-29 19:30:57,734 - DEBUG - VRF ids for VN ctest-vn2-89837037: {'10.0.0.73': '2', '10.0.0.27': '3'}
2025-10-29 19:30:57,735 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 19:30:57,744 - DEBUG - Requesting: http://10.0.0.249:8082/domain/ab5f52cf-59f5-4d8f-a294-1eaf08d80442
2025-10-29 19:30:57,762 - DEBUG - Requesting: http://10.0.0.249:8082/project/05363ea9-d24d-4e51-80a2-ae2bddc3bafb
2025-10-29 19:30:57,882 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/697a0589-71c6-40d0-add1-3722481bba1e
2025-10-29 19:30:57,891 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/697a0589-71c6-40d0-add1-3722481bba1e
2025-10-29 19:30:57,903 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/0028766f-a914-44bc-91d5-dc11e7cc596d
2025-10-29 19:30:57,913 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/0028766f-a914-44bc-91d5-dc11e7cc596d
2025-10-29 19:30:57,925 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/2ebc5ca8-18ce-49d2-afdb-bbb48817ed4f
2025-10-29 19:30:57,931 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-29 19:30:57,932 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/697a0589-71c6-40d0-add1-3722481bba1e
2025-10-29 19:30:57,941 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/0028766f-a914-44bc-91d5-dc11e7cc596d
2025-10-29 19:30:57,956 - INFO - Verified VN network id 13 for VN 697a0589-71c6-40d0-add1-3722481bba1e
2025-10-29 19:30:57,956 - INFO - Verifications in API Server for VN ctest-fip_vn-75629394 passed
2025-10-29 19:30:57,956 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/697a0589-71c6-40d0-add1-3722481bba1e
2025-10-29 19:30:57,970 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/0028766f-a914-44bc-91d5-dc11e7cc596d
2025-10-29 19:30:57,979 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/0028766f-a914-44bc-91d5-dc11e7cc596d
2025-10-29 19:30:57,987 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/2ebc5ca8-18ce-49d2-afdb-bbb48817ed4f
2025-10-29 19:30:58,001 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-fip_vn-75629394', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-16339681', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7600393409018609872', 'uuid-lslong': '12524852659072252446'}, 'enable': 'true', 'created': '2025-10-29T19:30:13', 'last-modified': '2025-10-29T19:30:13', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:44.190168'}
2025-10-29 19:30:58,011 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-29 19:30:58,019 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-fip_vn-75629394', 'interests': '1', 'advertised': '1', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-16339681', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7600393409018609872', 'uuid-lslong': '12524852659072252446'}, 'enable': 'true', 'created': '2025-10-29T19:30:13', 'last-modified': '2025-10-29T19:30:13', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:44.207993'}
2025-10-29 19:30:58,026 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-29 19:30:58,032 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-fip_vn-75629394', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-16339681', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7600393409018609872', 'uuid-lslong': '12524852659072252446'}, 'enable': 'true', 'created': '2025-10-29T19:30:13', 'last-modified': '2025-10-29T19:30:13', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:44.223659'}
2025-10-29 19:30:58,037 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-29 19:30:58,037 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-fip_vn-75629394 passed
2025-10-29 19:30:58,038 - DEBUG - ====Verifying policy data for ctest-fip_vn-75629394 in API_Server ======
2025-10-29 19:30:58,038 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 19:30:58,043 - DEBUG - Requesting: http://10.0.0.249:8082/domain/ab5f52cf-59f5-4d8f-a294-1eaf08d80442
2025-10-29 19:30:58,054 - DEBUG - Requesting: http://10.0.0.249:8082/project/05363ea9-d24d-4e51-80a2-ae2bddc3bafb
2025-10-29 19:30:58,147 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/697a0589-71c6-40d0-add1-3722481bba1e
2025-10-29 19:30:58,157 - DEBUG - =>VN ctest-fip_vn-75629394 has no policy to be verified
2025-10-29 19:30:58,157 - DEBUG - Verifying the vn in opserver
2025-10-29 19:30:58,157 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-fip_vn-75629394 virtual network link through opserver 10.0.0.249
2025-10-29 19:30:58,157 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-29 19:30:58,169 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-fip_vn-75629394', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-fip_vn-75629394?flat'}
2025-10-29 19:30:58,169 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-fip_vn-75629394 virtual network link through opserver 10.0.0.19
2025-10-29 19:30:58,169 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-29 19:30:58,182 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-fip_vn-75629394', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-fip_vn-75629394?flat'}
2025-10-29 19:30:58,182 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-fip_vn-75629394 virtual network link through opserver 10.0.0.41
2025-10-29 19:30:58,182 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-29 19:30:58,195 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-fip_vn-75629394', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-fip_vn-75629394?flat'}
2025-10-29 19:30:58,195 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-fip_vn-75629394 is found in opserver
2025-10-29 19:30:58,209 - DEBUG - VRF ids for VN ctest-fip_vn-75629394: {'10.0.0.73': '3'}
2025-10-29 19:30:58,209 - INFO - start time= 1761766258209284
2025-10-29 19:30:58,209 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 65.36.178.131, host_string: cirros@169.254.0.3, password: gocubsgogateway: ubuntu@10.0.0.27, gateway password: c0ntrail123
2025-10-29 19:30:58,209 - DEBUG - ping -s 56 -c 3 -W 1 65.36.178.131
2025-10-29 19:31:02,144 - DEBUG - PING 65.36.178.131 (65.36.178.131): 56 data bytes
64 bytes from 65.36.178.131: seq=0 ttl=63 time=8.911 ms
64 bytes from 65.36.178.131: seq=1 ttl=63 time=2.148 ms
64 bytes from 65.36.178.131: seq=2 ttl=63 time=1.857 ms
--- 65.36.178.131 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 1.857/4.305/8.911 ms
2025-10-29 19:31:02,144 - INFO - Ping to IP 65.36.178.131 from VM ctest-vn1_vm1-76432152 passed
2025-10-29 19:31:12,145 - INFO - Verify session samples and teardown pkts
2025-10-29 19:31:12,146 - INFO - Verifying SessionSeriesTable through opserver 10.0.0.249
2025-10-29 19:31:12,154 - DEBUG - Posting: http://10.0.0.249:8081/analytics/query, payload {'table': 'SessionSeriesTable', 'start_time': 1761766258209284, 'end_time': 1761766272154370, 'select_fields': ['T'], 'where': [[{'name': 'vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn1-95064950', 'op': 1, 'value2': None}, {'name': 'remote_vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn2-89837037', 'op': 1, 'value2': None}, {'name': 'protocol', 'value': '1', 'op': 1, 'value2': None}]], 'session_type': 'client'}
2025-10-29 19:31:13,200 - DEBUG - [{'T': 1761766260672110}, {'T': 1761766261672190}, {'T': 1761766262672260}]
2025-10-29 19:31:13,201 - DEBUG - Posting: http://10.0.0.249:8081/analytics/query, payload {'table': 'SessionSeriesTable', 'start_time': 1761766258209284, 'end_time': 1761766273200856, 'select_fields': ['T'], 'where': [[{'name': 'vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn2-89837037', 'op': 1, 'value2': None}, {'name': 'remote_vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn1-95064950', 'op': 1, 'value2': None}, {'name': 'protocol', 'value': '1', 'op': 1, 'value2': None}]], 'session_type': 'server'}
2025-10-29 19:31:14,229 - DEBUG - [{'T': 1761766260359717}, {'T': 1761766261359999}, {'T': 1761766262360018}]
2025-10-29 19:31:14,229 - DEBUG - Posting: http://10.0.0.249:8081/analytics/query, payload {'table': 'SessionSeriesTable', 'start_time': 1761766258209284, 'end_time': 1761766274229505, 'select_fields': ['vn', 'remote_vn', 'sample_count'], 'where': [[{'name': 'vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn1-95064950', 'op': 1, 'value2': None}, {'name': 'remote_vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn2-89837037', 'op': 1, 'value2': None}, {'name': 'protocol', 'value': '1', 'op': 1, 'value2': None}]], 'session_type': 'client'}
2025-10-29 19:31:15,265 - DEBUG - [{'remote_vn': 'default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn2-89837037', 'vn': 'default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn1-95064950', 'sample_count': 3}]
2025-10-29 19:31:15,266 - INFO - wait for the flows to get expire
2025-10-29 19:34:35,266 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4526-2:Compute:contrail-vrouter-agent:0?flat
2025-10-29 19:34:35,442 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4526-2?flat
2025-10-29 19:34:35,519 - DEBUG - Posting: http://10.0.0.249:8081/analytics/query, payload {'table': 'SessionRecordTable', 'start_time': 1761766258209284, 'end_time': 1761766475519244, 'select_fields': ['vn', 'remote_vn', 'forward_teardown_pkts', 'reverse_teardown_pkts'], 'where': [[{'name': 'vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn1-95064950', 'op': 1, 'value2': None}, {'name': 'remote_vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn2-89837037', 'op': 1, 'value2': None}, {'name': 'protocol', 'value': '1', 'op': 1, 'value2': None}]], 'session_type': 'client'}
2025-10-29 19:34:36,558 - DEBUG - [{'forward_flow_uuid': 'feeb341b-403c-4e54-96b1-c45df1a84cdd', 'remote_vn': 'default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn2-89837037', 'reverse_flow_uuid': 'e5b77920-c831-4a2c-9783-4681de466dfa', 'vn': 'default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn1-95064950', 'forward_teardown_pkts': 3, 'reverse_teardown_pkts': 3}]
2025-10-29 19:34:36,558 - DEBUG - Posting: http://10.0.0.249:8081/analytics/query, payload {'table': 'SessionSeriesTable', 'start_time': 1761766258209284, 'end_time': 1761766476558810, 'select_fields': ['vn', 'remote_vn', 'sample_count'], 'where': [[{'name': 'vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn1-95064950', 'op': 1, 'value2': None}, {'name': 'remote_vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn2-89837037', 'op': 1, 'value2': None}, {'name': 'protocol', 'value': '1', 'op': 1, 'value2': None}]], 'session_type': 'client'}
2025-10-29 19:34:37,599 - DEBUG - Posting: http://10.0.0.249:8081/analytics/query, payload {'table': 'SessionSeriesTable', 'start_time': 1761766258209284, 'end_time': 1761766477599703, 'select_fields': ['vn', 'remote_vn', 'sample_count'], 'where': [[{'name': 'vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn2-89837037', 'op': 1, 'value2': None}, {'name': 'remote_vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn1-95064950', 'op': 1, 'value2': None}, {'name': 'protocol', 'value': '1', 'op': 1, 'value2': None}]], 'session_type': 'server'}
2025-10-29 19:34:38,657 - DEBUG - [{'remote_vn': 'default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn1-95064950', 'vn': 'default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn2-89837037', 'sample_count': 4}]
2025-10-29 19:34:39,313 - INFO - Deleted policy policy1
2025-10-29 19:34:39,425 - INFO - Setting flow export rate: None
2025-10-29 19:34:40,808 - DEBUG - Skipping xmpp flap check
2025-10-29 19:34:40,808 - INFO - END TEST : test_verify_session_sampling_teardown : PASSED[0:03:47]
2025-10-29 19:34:40,808 - INFO - --------------------------------------------------------------------------------
2025-10-29 19:34:40,813 - INFO - Deleting VM mltf_vm
2025-10-29 19:34:40,923 - INFO - Deleting VM ctest-fvn_vm1-07265663
2025-10-29 19:34:41,042 - INFO - Deleting VM ctest-vn2_vm2-08187596
2025-10-29 19:34:41,139 - INFO - Deleting VN ctest-fip_vn-75629394
2025-10-29 19:34:41,175 - DEBUG - VN 697a0589-71c6-40d0-add1-3722481bba1e still in use: Unable to complete operation on network 697a0589-71c6-40d0-add1-3722481bba1e. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-e99e0a10-6d20-4986-b0a6-abc78f7f6f6d']
2025-10-29 19:34:41,176 - WARNING - Deleting VN ctest-fip_vn-75629394 failed..Will retry
2025-10-29 19:34:43,399 - DEBUG - Response for deleting network ()
2025-10-29 19:34:43,399 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/0028766f-a914-44bc-91d5-dc11e7cc596d
2025-10-29 19:34:43,430 - DEBUG - Response Code: 404
2025-10-29 19:34:43,430 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 19:34:43,437 - DEBUG - Requesting: http://10.0.0.249:8082/domain/ab5f52cf-59f5-4d8f-a294-1eaf08d80442
2025-10-29 19:34:43,447 - DEBUG - Requesting: http://10.0.0.249:8082/project/05363ea9-d24d-4e51-80a2-ae2bddc3bafb
2025-10-29 19:34:43,552 - INFO - Validated that VN ctest-fip_vn-75629394 is not found in API Server
2025-10-29 19:34:43,588 - DEBUG - VN ctest-fip_vn-75629394 is not present in Agent 10.0.0.73
2025-10-29 19:34:43,598 - DEBUG - VN ctest-fip_vn-75629394 is not present in Agent 10.0.0.27
2025-10-29 19:34:43,598 - INFO - Validated that VN ctest-fip_vn-75629394 is not in any agent
2025-10-29 19:34:43,610 - DEBUG - VRF 3 is not seen in agent 10.0.0.73
2025-10-29 19:34:43,621 - DEBUG - Vrouter 10.0.0.73 does not have vrf 3 for VN ctest-fip_vn-75629394
2025-10-29 19:34:43,621 - INFO - Validated that all vrouters do not have the route table for VN default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-fip_vn-75629394
2025-10-29 19:34:43,647 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-fip_vn-75629394 info
2025-10-29 19:34:43,647 - INFO - Deleting VN ctest-vn2-89837037
2025-10-29 19:34:43,871 - DEBUG - Response for deleting network ()
2025-10-29 19:34:43,871 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/885d0b32-678f-4812-b587-4b093c6c6408
2025-10-29 19:34:43,879 - DEBUG - Response Code: 404
2025-10-29 19:34:43,879 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 19:34:43,886 - DEBUG - Requesting: http://10.0.0.249:8082/domain/ab5f52cf-59f5-4d8f-a294-1eaf08d80442
2025-10-29 19:34:43,939 - DEBUG - Requesting: http://10.0.0.249:8082/project/05363ea9-d24d-4e51-80a2-ae2bddc3bafb
2025-10-29 19:34:44,054 - INFO - Validated that VN ctest-vn2-89837037 is not found in API Server
2025-10-29 19:34:44,080 - DEBUG - VN ctest-vn2-89837037 is not present in Agent 10.0.0.73
2025-10-29 19:34:44,089 - DEBUG - VN ctest-vn2-89837037 is not present in Agent 10.0.0.27
2025-10-29 19:34:44,089 - INFO - Validated that VN ctest-vn2-89837037 is not in any agent
2025-10-29 19:34:44,109 - DEBUG - VRF 2 is not seen in agent 10.0.0.73
2025-10-29 19:34:44,124 - DEBUG - Vrouter 10.0.0.73 does not have vrf 2 for VN ctest-vn2-89837037
2025-10-29 19:34:44,130 - DEBUG - VRF 3 is not seen in agent 10.0.0.27
2025-10-29 19:34:44,136 - DEBUG - Vrouter 10.0.0.27 does not have vrf 3 for VN ctest-vn2-89837037
2025-10-29 19:34:44,136 - INFO - Validated that all vrouters do not have the route table for VN default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn2-89837037
2025-10-29 19:34:44,157 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn2-89837037 info
2025-10-29 19:34:44,157 - INFO - Deleting VM ctest-vn1_vm2-51905272
2025-10-29 19:34:44,252 - INFO - Deleting VM ctest-vn1_vm1-76432152
2025-10-29 19:34:44,384 - INFO - Deleting VN ctest-vn1-95064950
2025-10-29 19:34:44,435 - DEBUG - VN 2d854077-15c4-48e0-b782-f3fc08dd6bfc still in use: Unable to complete operation on network 2d854077-15c4-48e0-b782-f3fc08dd6bfc. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-063c3ce8-c48a-4358-916c-4f416a5c7809']
2025-10-29 19:34:44,435 - WARNING - Deleting VN ctest-vn1-95064950 failed..Will retry
2025-10-29 19:34:46,665 - DEBUG - Response for deleting network ()
2025-10-29 19:34:46,665 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/444a0ac5-1f43-4466-9c25-c47868018f95
2025-10-29 19:34:46,673 - DEBUG - Response Code: 404
2025-10-29 19:34:46,673 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-10-29 19:34:46,684 - DEBUG - Requesting: http://10.0.0.249:8082/domain/ab5f52cf-59f5-4d8f-a294-1eaf08d80442
2025-10-29 19:34:46,700 - DEBUG - Requesting: http://10.0.0.249:8082/project/05363ea9-d24d-4e51-80a2-ae2bddc3bafb
2025-10-29 19:34:46,790 - INFO - Validated that VN ctest-vn1-95064950 is not found in API Server
2025-10-29 19:34:46,803 - DEBUG - VN ctest-vn1-95064950 is not present in Agent 10.0.0.73
2025-10-29 19:34:46,813 - DEBUG - VN ctest-vn1-95064950 is not present in Agent 10.0.0.27
2025-10-29 19:34:46,813 - INFO - Validated that VN ctest-vn1-95064950 is not in any agent
2025-10-29 19:34:46,818 - DEBUG - VRF 2 is not seen in agent 10.0.0.27
2025-10-29 19:34:46,824 - DEBUG - Vrouter 10.0.0.27 does not have vrf 2 for VN ctest-vn1-95064950
2025-10-29 19:34:46,824 - INFO - Validated that all vrouters do not have the route table for VN default-domain:ctest-AnalyticsTestSanityWithResource-16339681:ctest-vn1-95064950
2025-10-29 19:34:46,846 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn1-95064950 info
2025-10-29 19:34:47,626 - INFO - Deleted project: ctest-AnalyticsTestSanityWithResource-16339681, ID : 05363ea9-d24d-4e51-80a2-ae2bddc3bafb