is in ACTIVE state now
2025-10-30 03:49:48,638 - INFO - VM name : mltf_vm
2025-10-30 03:49:48,734 - DEBUG - VM mltf_vm ID is a6285d30-516c-464c-8979-5e6d70f4ffc8
2025-10-30 03:49:48,734 - DEBUG - VM mltf_vm launched on Node an-jenkins-deploy-platform-ansible-os-4535-2
2025-10-30 03:49:48,902 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine/a6285d30-516c-464c-8979-5e6d70f4ffc8
2025-10-30 03:49:48,912 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine-interface/686e1602-7416-42e7-94aa-3eec1401f1dc
2025-10-30 03:49:48,922 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine-interface/82586a26-5905-4ba5-bc6d-5913f197d13d
2025-10-30 03:49:50,102 - 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.87 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=0.489 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.489/1.177/1.866/0.688 ms')
2025-10-30 03:49:50,102 - INFO - Ping to Metadata IP 169.254.0.5 of VM mltf_vm passed
2025-10-30 03:49:50,174 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-30 03:49:50,174 - DEBUG - Waiting to SSH to VM mltf_vm, IP 155.223.36.69, Port 22
2025-10-30 03:49:50,344 - DEBUG - VM mltf_vm is ready for SSH connections
2025-10-30 03:49:50,418 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-30 03:49:50,418 - DEBUG - Waiting to SSH to VM mltf_vm, IP 155.223.36.69, Port 22
2025-10-30 03:49:50,588 - DEBUG - VM mltf_vm is ready for SSH connections
2025-10-30 03:49:50,590 - INFO - ================================================================================
2025-10-30 03:49:50,591 - INFO - STARTING TEST : test_verify_session_sampling_teardown
2025-10-30 03:49:50,591 - 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-30 03:49:51,874 - DEBUG - Skipping xmpp flap check
2025-10-30 03:49:51,874 - INFO - Initial checks done. Running the testcase now
2025-10-30 03:49:51,874 - INFO -
2025-10-30 03:49:51,996 - INFO - Setting flow export rate: 100
2025-10-30 03:49:52,045 - 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-30 03:49:52,363 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': 'f39b6b6f-ca3f-4d7b-9d30-29226bc65585', 'name': 'ctest-vn1-69893290', 'tenant_id': '7f5f4f841c0c426e93986021e6c8e34c', 'project_id': '7f5f4f841c0c426e93986021e6c8e34c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['c6b490c3-8b55-475e-b832-45db42aa840c'], 'fq_name': ['default-domain', 'ctest-AnalyticsTestSanityWithResource-55359781', 'ctest-vn1-69893290'], 'policys': [['default-domain', 'ctest-AnalyticsTestSanityWithResource-55359781', 'policy1']], 'subnet_ipam': [{'subnet_cidr': '155.223.36.64/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}}
2025-10-30 03:49:52,571 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '0588e724-d7c2-42c3-8e13-c13de7197892', 'name': 'ctest-vn2-21304413', 'tenant_id': '7f5f4f841c0c426e93986021e6c8e34c', 'project_id': '7f5f4f841c0c426e93986021e6c8e34c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['f809c7d7-7675-4ad6-badb-6bc323208bde'], 'fq_name': ['default-domain', 'ctest-AnalyticsTestSanityWithResource-55359781', 'ctest-vn2-21304413'], 'policys': [['default-domain', 'ctest-AnalyticsTestSanityWithResource-55359781', 'policy1']], 'subnet_ipam': [{'subnet_cidr': '91.228.167.0/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}}
2025-10-30 03:49:52,673 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:49:52,680 - DEBUG - Requesting: http://10.0.0.242:8082/domain/2be6ea5e-665d-4a78-a8ec-f940f567f06f
2025-10-30 03:49:52,728 - DEBUG - Requesting: http://10.0.0.242:8082/project/7f5f4f84-1c0c-426e-9398-6021e6c8e34c
2025-10-30 03:49:52,848 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/f39b6b6f-ca3f-4d7b-9d30-29226bc65585
2025-10-30 03:49:52,883 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/f39b6b6f-ca3f-4d7b-9d30-29226bc65585
2025-10-30 03:49:52,894 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/c4d871dc-f456-4056-9f3c-0bc99af963e0
2025-10-30 03:49:52,908 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/c4d871dc-f456-4056-9f3c-0bc99af963e0
2025-10-30 03:49:52,916 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/edb762b6-52cb-40e6-9f77-51ea72470805
2025-10-30 03:49:52,935 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-30 03:49:52,935 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/f39b6b6f-ca3f-4d7b-9d30-29226bc65585
2025-10-30 03:49:52,954 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/c4d871dc-f456-4056-9f3c-0bc99af963e0
2025-10-30 03:49:52,974 - INFO - Verified VN network id 13 for VN f39b6b6f-ca3f-4d7b-9d30-29226bc65585
2025-10-30 03:49:52,974 - INFO - Verifications in API Server for VN ctest-vn1-69893290 passed
2025-10-30 03:49:52,974 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/f39b6b6f-ca3f-4d7b-9d30-29226bc65585
2025-10-30 03:49:52,984 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/c4d871dc-f456-4056-9f3c-0bc99af963e0
2025-10-30 03:49:52,993 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/c4d871dc-f456-4056-9f3c-0bc99af963e0
2025-10-30 03:49:53,002 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/edb762b6-52cb-40e6-9f77-51ea72470805
2025-10-30 03:49:53,027 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn1-69893290', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-55359781', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17553742100485328251', 'uuid-lslong': '11326598290650584453'}, 'enable': 'true', 'created': '2025-10-30T03:48:30', 'last-modified': '2025-10-30T03:49:52', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.700783'}
2025-10-30 03:49:53,038 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-30 03:49:53,044 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn1-69893290', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-55359781', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17553742100485328251', 'uuid-lslong': '11326598290650584453'}, 'enable': 'true', 'created': '2025-10-30T03:48:30', 'last-modified': '2025-10-30T03:49:52', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.714918'}
2025-10-30 03:49:53,055 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-30 03:49:53,069 - DEBUG - Control-node 10.0.0.240 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn1-69893290', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-55359781', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17553742100485328251', 'uuid-lslong': '11326598290650584453'}, 'enable': 'true', 'created': '2025-10-30T03:48:30', 'last-modified': '2025-10-30T03:49:52', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.737917'}
2025-10-30 03:49:53,078 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-30 03:49:53,078 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn1-69893290 passed
2025-10-30 03:49:53,078 - DEBUG - ====Verifying policy data for ctest-vn1-69893290 in API_Server ======
2025-10-30 03:49:53,078 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:49:53,087 - DEBUG - Requesting: http://10.0.0.242:8082/domain/2be6ea5e-665d-4a78-a8ec-f940f567f06f
2025-10-30 03:49:53,101 - DEBUG - Requesting: http://10.0.0.242:8082/project/7f5f4f84-1c0c-426e-9398-6021e6c8e34c
2025-10-30 03:49:53,197 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/f39b6b6f-ca3f-4d7b-9d30-29226bc65585
2025-10-30 03:49:53,208 - DEBUG - ==>Verifying data for policy with id: 125c2c6a-6e49-4cec-ab16-9541fd4d1733, fqn: ['default-domain', 'ctest-AnalyticsTestSanityWithResource-55359781', 'policy1']
2025-10-30 03:49:53,209 - INFO - VN ctest-vn1-69893290 Policy verification: verify_vn_policy_in_api_server, status: True
2025-10-30 03:49:53,209 - DEBUG - Verifying the vn in opserver
2025-10-30 03:49:53,209 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn1-69893290 virtual network link through opserver 10.0.0.242
2025-10-30 03:49:53,209 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2025-10-30 03:49:53,221 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn1-69893290', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn1-69893290?flat'}
2025-10-30 03:49:53,222 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn1-69893290 virtual network link through opserver 10.0.0.254
2025-10-30 03:49:53,222 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-30 03:49:53,234 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn1-69893290', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn1-69893290?flat'}
2025-10-30 03:49:53,234 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn1-69893290 virtual network link through opserver 10.0.0.240
2025-10-30 03:49:53,235 - DEBUG - Requesting: http://10.0.0.240:8081/analytics/uves/virtual-networks
2025-10-30 03:49:53,248 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn1-69893290', 'href': 'http://10.0.0.240:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn1-69893290?flat'}
2025-10-30 03:49:53,248 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn1-69893290 is found in opserver
2025-10-30 03:49:53,259 - DEBUG - VRF ids for VN ctest-vn1-69893290: {'10.0.0.65': '2'}
2025-10-30 03:49:53,260 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:49:53,265 - DEBUG - Requesting: http://10.0.0.242:8082/domain/2be6ea5e-665d-4a78-a8ec-f940f567f06f
2025-10-30 03:49:53,279 - DEBUG - Requesting: http://10.0.0.242:8082/project/7f5f4f84-1c0c-426e-9398-6021e6c8e34c
2025-10-30 03:49:53,371 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/0588e724-d7c2-42c3-8e13-c13de7197892
2025-10-30 03:49:53,384 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/0588e724-d7c2-42c3-8e13-c13de7197892
2025-10-30 03:49:53,394 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/ae5a9b94-a726-4906-a138-6cc6df40433e
2025-10-30 03:49:53,402 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/ae5a9b94-a726-4906-a138-6cc6df40433e
2025-10-30 03:49:53,411 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/9f7ab4f3-6b69-4b1f-9227-6dafc6a9ac10
2025-10-30 03:49:53,418 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-30 03:49:53,418 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/0588e724-d7c2-42c3-8e13-c13de7197892
2025-10-30 03:49:53,430 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/ae5a9b94-a726-4906-a138-6cc6df40433e
2025-10-30 03:49:53,448 - INFO - Verified VN network id 14 for VN 0588e724-d7c2-42c3-8e13-c13de7197892
2025-10-30 03:49:53,448 - INFO - Verifications in API Server for VN ctest-vn2-21304413 passed
2025-10-30 03:49:53,448 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/0588e724-d7c2-42c3-8e13-c13de7197892
2025-10-30 03:49:53,466 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/ae5a9b94-a726-4906-a138-6cc6df40433e
2025-10-30 03:49:53,475 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/ae5a9b94-a726-4906-a138-6cc6df40433e
2025-10-30 03:49:53,484 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/9f7ab4f3-6b69-4b1f-9227-6dafc6a9ac10
2025-10-30 03:49:53,497 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn2-21304413', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-55359781', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '398822712446960323', 'uuid-lslong': '10237738849557641362'}, 'enable': 'true', 'created': '2025-10-30T03:49:06', 'last-modified': '2025-10-30T03:49:52', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.961763'}
2025-10-30 03:49:53,506 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-30 03:49:53,515 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn2-21304413', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-55359781', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '398822712446960323', 'uuid-lslong': '10237738849557641362'}, 'enable': 'true', 'created': '2025-10-30T03:49:06', 'last-modified': '2025-10-30T03:49:52', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.977684'}
2025-10-30 03:49:53,522 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-30 03:49:53,528 - DEBUG - Control-node 10.0.0.240 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn2-21304413', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-55359781', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '398822712446960323', 'uuid-lslong': '10237738849557641362'}, 'enable': 'true', 'created': '2025-10-30T03:49:06', 'last-modified': '2025-10-30T03:49:52', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.992972'}
2025-10-30 03:49:53,534 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-30 03:49:53,534 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn2-21304413 passed
2025-10-30 03:49:53,534 - DEBUG - ====Verifying policy data for ctest-vn2-21304413 in API_Server ======
2025-10-30 03:49:53,534 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:49:53,542 - DEBUG - Requesting: http://10.0.0.242:8082/domain/2be6ea5e-665d-4a78-a8ec-f940f567f06f
2025-10-30 03:49:53,553 - DEBUG - Requesting: http://10.0.0.242:8082/project/7f5f4f84-1c0c-426e-9398-6021e6c8e34c
2025-10-30 03:49:53,648 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/0588e724-d7c2-42c3-8e13-c13de7197892
2025-10-30 03:49:53,661 - DEBUG - ==>Verifying data for policy with id: 125c2c6a-6e49-4cec-ab16-9541fd4d1733, fqn: ['default-domain', 'ctest-AnalyticsTestSanityWithResource-55359781', 'policy1']
2025-10-30 03:49:53,661 - INFO - VN ctest-vn2-21304413 Policy verification: verify_vn_policy_in_api_server, status: True
2025-10-30 03:49:53,661 - DEBUG - Verifying the vn in opserver
2025-10-30 03:49:53,661 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn2-21304413 virtual network link through opserver 10.0.0.242
2025-10-30 03:49:53,661 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2025-10-30 03:49:53,679 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn2-21304413', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn2-21304413?flat'}
2025-10-30 03:49:53,679 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn2-21304413 virtual network link through opserver 10.0.0.254
2025-10-30 03:49:53,679 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-30 03:49:53,693 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn2-21304413', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn2-21304413?flat'}
2025-10-30 03:49:53,693 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn2-21304413 virtual network link through opserver 10.0.0.240
2025-10-30 03:49:53,694 - DEBUG - Requesting: http://10.0.0.240:8081/analytics/uves/virtual-networks
2025-10-30 03:49:53,707 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn2-21304413', 'href': 'http://10.0.0.240:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn2-21304413?flat'}
2025-10-30 03:49:53,707 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn2-21304413 is found in opserver
2025-10-30 03:49:53,718 - DEBUG - VRF ids for VN ctest-vn2-21304413: {'10.0.0.129': '2', '10.0.0.65': '3'}
2025-10-30 03:49:53,718 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:49:53,723 - DEBUG - Requesting: http://10.0.0.242:8082/domain/2be6ea5e-665d-4a78-a8ec-f940f567f06f
2025-10-30 03:49:53,743 - DEBUG - Requesting: http://10.0.0.242:8082/project/7f5f4f84-1c0c-426e-9398-6021e6c8e34c
2025-10-30 03:49:53,838 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/f39b6b6f-ca3f-4d7b-9d30-29226bc65585
2025-10-30 03:49:53,848 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/f39b6b6f-ca3f-4d7b-9d30-29226bc65585
2025-10-30 03:49:53,859 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/c4d871dc-f456-4056-9f3c-0bc99af963e0
2025-10-30 03:49:53,867 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/c4d871dc-f456-4056-9f3c-0bc99af963e0
2025-10-30 03:49:53,876 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/edb762b6-52cb-40e6-9f77-51ea72470805
2025-10-30 03:49:53,883 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-30 03:49:53,883 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/f39b6b6f-ca3f-4d7b-9d30-29226bc65585
2025-10-30 03:49:53,892 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/c4d871dc-f456-4056-9f3c-0bc99af963e0
2025-10-30 03:49:53,907 - INFO - Verified VN network id 13 for VN f39b6b6f-ca3f-4d7b-9d30-29226bc65585
2025-10-30 03:49:53,907 - INFO - Verifications in API Server for VN ctest-vn1-69893290 passed
2025-10-30 03:49:53,907 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/f39b6b6f-ca3f-4d7b-9d30-29226bc65585
2025-10-30 03:49:53,918 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/c4d871dc-f456-4056-9f3c-0bc99af963e0
2025-10-30 03:49:53,925 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/c4d871dc-f456-4056-9f3c-0bc99af963e0
2025-10-30 03:49:53,938 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/edb762b6-52cb-40e6-9f77-51ea72470805
2025-10-30 03:49:53,951 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn1-69893290', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-55359781', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17553742100485328251', 'uuid-lslong': '11326598290650584453'}, 'enable': 'true', 'created': '2025-10-30T03:48:30', 'last-modified': '2025-10-30T03:49:52', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.624119'}
2025-10-30 03:49:53,957 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-30 03:49:53,964 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn1-69893290', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-55359781', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17553742100485328251', 'uuid-lslong': '11326598290650584453'}, 'enable': 'true', 'created': '2025-10-30T03:48:30', 'last-modified': '2025-10-30T03:49:52', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.635498'}
2025-10-30 03:49:53,972 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-30 03:49:53,979 - DEBUG - Control-node 10.0.0.240 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn1-69893290', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-55359781', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17553742100485328251', 'uuid-lslong': '11326598290650584453'}, 'enable': 'true', 'created': '2025-10-30T03:48:30', 'last-modified': '2025-10-30T03:49:52', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.648169'}
2025-10-30 03:49:53,984 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-30 03:49:53,984 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn1-69893290 passed
2025-10-30 03:49:53,985 - DEBUG - ====Verifying policy data for ctest-vn1-69893290 in API_Server ======
2025-10-30 03:49:53,985 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:49:53,991 - DEBUG - Requesting: http://10.0.0.242:8082/domain/2be6ea5e-665d-4a78-a8ec-f940f567f06f
2025-10-30 03:49:54,005 - DEBUG - Requesting: http://10.0.0.242:8082/project/7f5f4f84-1c0c-426e-9398-6021e6c8e34c
2025-10-30 03:49:54,110 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/f39b6b6f-ca3f-4d7b-9d30-29226bc65585
2025-10-30 03:49:54,121 - DEBUG - ==>Verifying data for policy with id: 125c2c6a-6e49-4cec-ab16-9541fd4d1733, fqn: ['default-domain', 'ctest-AnalyticsTestSanityWithResource-55359781', 'policy1']
2025-10-30 03:49:54,121 - INFO - VN ctest-vn1-69893290 Policy verification: verify_vn_policy_in_api_server, status: True
2025-10-30 03:49:54,121 - DEBUG - Verifying the vn in opserver
2025-10-30 03:49:54,121 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn1-69893290 virtual network link through opserver 10.0.0.242
2025-10-30 03:49:54,121 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2025-10-30 03:49:54,135 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn1-69893290', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn1-69893290?flat'}
2025-10-30 03:49:54,135 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn1-69893290 virtual network link through opserver 10.0.0.254
2025-10-30 03:49:54,135 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-30 03:49:54,147 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn1-69893290', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn1-69893290?flat'}
2025-10-30 03:49:54,147 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn1-69893290 virtual network link through opserver 10.0.0.240
2025-10-30 03:49:54,147 - DEBUG - Requesting: http://10.0.0.240:8081/analytics/uves/virtual-networks
2025-10-30 03:49:54,160 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn1-69893290', 'href': 'http://10.0.0.240:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn1-69893290?flat'}
2025-10-30 03:49:54,160 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn1-69893290 is found in opserver
2025-10-30 03:49:54,172 - DEBUG - VRF ids for VN ctest-vn1-69893290: {'10.0.0.65': '2'}
2025-10-30 03:49:54,172 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:49:54,178 - DEBUG - Requesting: http://10.0.0.242:8082/domain/2be6ea5e-665d-4a78-a8ec-f940f567f06f
2025-10-30 03:49:54,189 - DEBUG - Requesting: http://10.0.0.242:8082/project/7f5f4f84-1c0c-426e-9398-6021e6c8e34c
2025-10-30 03:49:54,281 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/0588e724-d7c2-42c3-8e13-c13de7197892
2025-10-30 03:49:54,291 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/0588e724-d7c2-42c3-8e13-c13de7197892
2025-10-30 03:49:54,304 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/ae5a9b94-a726-4906-a138-6cc6df40433e
2025-10-30 03:49:54,313 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/ae5a9b94-a726-4906-a138-6cc6df40433e
2025-10-30 03:49:54,321 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/9f7ab4f3-6b69-4b1f-9227-6dafc6a9ac10
2025-10-30 03:49:54,328 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-30 03:49:54,328 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/0588e724-d7c2-42c3-8e13-c13de7197892
2025-10-30 03:49:54,338 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/ae5a9b94-a726-4906-a138-6cc6df40433e
2025-10-30 03:49:54,354 - INFO - Verified VN network id 14 for VN 0588e724-d7c2-42c3-8e13-c13de7197892
2025-10-30 03:49:54,354 - INFO - Verifications in API Server for VN ctest-vn2-21304413 passed
2025-10-30 03:49:54,354 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/0588e724-d7c2-42c3-8e13-c13de7197892
2025-10-30 03:49:54,365 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/ae5a9b94-a726-4906-a138-6cc6df40433e
2025-10-30 03:49:54,373 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/ae5a9b94-a726-4906-a138-6cc6df40433e
2025-10-30 03:49:54,384 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/9f7ab4f3-6b69-4b1f-9227-6dafc6a9ac10
2025-10-30 03:49:54,398 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn2-21304413', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-55359781', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '398822712446960323', 'uuid-lslong': '10237738849557641362'}, 'enable': 'true', 'created': '2025-10-30T03:49:06', 'last-modified': '2025-10-30T03:49:52', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.863998'}
2025-10-30 03:49:54,407 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-30 03:49:54,413 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn2-21304413', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-55359781', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '398822712446960323', 'uuid-lslong': '10237738849557641362'}, 'enable': 'true', 'created': '2025-10-30T03:49:06', 'last-modified': '2025-10-30T03:49:52', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.875427'}
2025-10-30 03:49:54,420 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-30 03:49:54,425 - DEBUG - Control-node 10.0.0.240 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn2-21304413', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-55359781', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '398822712446960323', 'uuid-lslong': '10237738849557641362'}, 'enable': 'true', 'created': '2025-10-30T03:49:06', 'last-modified': '2025-10-30T03:49:52', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.890766'}
2025-10-30 03:49:54,431 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-30 03:49:54,431 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn2-21304413 passed
2025-10-30 03:49:54,432 - DEBUG - ====Verifying policy data for ctest-vn2-21304413 in API_Server ======
2025-10-30 03:49:54,432 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:49:54,438 - DEBUG - Requesting: http://10.0.0.242:8082/domain/2be6ea5e-665d-4a78-a8ec-f940f567f06f
2025-10-30 03:49:54,451 - DEBUG - Requesting: http://10.0.0.242:8082/project/7f5f4f84-1c0c-426e-9398-6021e6c8e34c
2025-10-30 03:49:54,544 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/0588e724-d7c2-42c3-8e13-c13de7197892
2025-10-30 03:49:54,556 - DEBUG - ==>Verifying data for policy with id: 125c2c6a-6e49-4cec-ab16-9541fd4d1733, fqn: ['default-domain', 'ctest-AnalyticsTestSanityWithResource-55359781', 'policy1']
2025-10-30 03:49:54,556 - INFO - VN ctest-vn2-21304413 Policy verification: verify_vn_policy_in_api_server, status: True
2025-10-30 03:49:54,556 - DEBUG - Verifying the vn in opserver
2025-10-30 03:49:54,556 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn2-21304413 virtual network link through opserver 10.0.0.242
2025-10-30 03:49:54,556 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2025-10-30 03:49:54,569 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn2-21304413', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn2-21304413?flat'}
2025-10-30 03:49:54,569 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn2-21304413 virtual network link through opserver 10.0.0.254
2025-10-30 03:49:54,569 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-30 03:49:54,582 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn2-21304413', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn2-21304413?flat'}
2025-10-30 03:49:54,582 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn2-21304413 virtual network link through opserver 10.0.0.240
2025-10-30 03:49:54,582 - DEBUG - Requesting: http://10.0.0.240:8081/analytics/uves/virtual-networks
2025-10-30 03:49:54,596 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn2-21304413', 'href': 'http://10.0.0.240:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn2-21304413?flat'}
2025-10-30 03:49:54,596 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn2-21304413 is found in opserver
2025-10-30 03:49:54,616 - DEBUG - VRF ids for VN ctest-vn2-21304413: {'10.0.0.129': '2', '10.0.0.65': '3'}
2025-10-30 03:49:54,616 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:49:54,627 - DEBUG - Requesting: http://10.0.0.242:8082/domain/2be6ea5e-665d-4a78-a8ec-f940f567f06f
2025-10-30 03:49:54,643 - DEBUG - Requesting: http://10.0.0.242:8082/project/7f5f4f84-1c0c-426e-9398-6021e6c8e34c
2025-10-30 03:49:54,742 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/4d44802b-1692-4f39-8089-3b8bfe208eba
2025-10-30 03:49:54,752 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/4d44802b-1692-4f39-8089-3b8bfe208eba
2025-10-30 03:49:54,768 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/3fd6f33c-95fc-41bf-a297-7194da8b9ded
2025-10-30 03:49:54,778 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/3fd6f33c-95fc-41bf-a297-7194da8b9ded
2025-10-30 03:49:54,786 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/f66b227c-b4e4-4937-9849-bb8d71be9dc9
2025-10-30 03:49:54,792 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-30 03:49:54,792 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/4d44802b-1692-4f39-8089-3b8bfe208eba
2025-10-30 03:49:54,801 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/3fd6f33c-95fc-41bf-a297-7194da8b9ded
2025-10-30 03:49:54,817 - INFO - Verified VN network id 15 for VN 4d44802b-1692-4f39-8089-3b8bfe208eba
2025-10-30 03:49:54,817 - INFO - Verifications in API Server for VN ctest-fip_vn-85891286 passed
2025-10-30 03:49:54,818 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/4d44802b-1692-4f39-8089-3b8bfe208eba
2025-10-30 03:49:54,832 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/3fd6f33c-95fc-41bf-a297-7194da8b9ded
2025-10-30 03:49:54,841 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/3fd6f33c-95fc-41bf-a297-7194da8b9ded
2025-10-30 03:49:54,850 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/f66b227c-b4e4-4937-9849-bb8d71be9dc9
2025-10-30 03:49:54,864 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-fip_vn-85891286', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-55359781', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5567715961887412025', 'uuid-lslong': '9261999581114175162'}, 'enable': 'true', 'created': '2025-10-30T03:49:06', 'last-modified': '2025-10-30T03:49:07', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:47.675245'}
2025-10-30 03:49:54,869 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-30 03:49:54,874 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-fip_vn-85891286', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-55359781', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5567715961887412025', 'uuid-lslong': '9261999581114175162'}, 'enable': 'true', 'created': '2025-10-30T03:49:06', 'last-modified': '2025-10-30T03:49:07', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:47.689798'}
2025-10-30 03:49:54,880 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-30 03:49:54,885 - DEBUG - Control-node 10.0.0.240 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-fip_vn-85891286', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-55359781', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5567715961887412025', 'uuid-lslong': '9261999581114175162'}, 'enable': 'true', 'created': '2025-10-30T03:49:06', 'last-modified': '2025-10-30T03:49:07', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:47.697174'}
2025-10-30 03:49:54,891 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-30 03:49:54,891 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-fip_vn-85891286 passed
2025-10-30 03:49:54,891 - DEBUG - ====Verifying policy data for ctest-fip_vn-85891286 in API_Server ======
2025-10-30 03:49:54,891 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:49:54,898 - DEBUG - Requesting: http://10.0.0.242:8082/domain/2be6ea5e-665d-4a78-a8ec-f940f567f06f
2025-10-30 03:49:54,909 - DEBUG - Requesting: http://10.0.0.242:8082/project/7f5f4f84-1c0c-426e-9398-6021e6c8e34c
2025-10-30 03:49:55,006 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/4d44802b-1692-4f39-8089-3b8bfe208eba
2025-10-30 03:49:55,016 - DEBUG - =>VN ctest-fip_vn-85891286 has no policy to be verified
2025-10-30 03:49:55,016 - DEBUG - Verifying the vn in opserver
2025-10-30 03:49:55,016 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-fip_vn-85891286 virtual network link through opserver 10.0.0.242
2025-10-30 03:49:55,016 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2025-10-30 03:49:55,027 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-fip_vn-85891286', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-fip_vn-85891286?flat'}
2025-10-30 03:49:55,027 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-fip_vn-85891286 virtual network link through opserver 10.0.0.254
2025-10-30 03:49:55,027 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-30 03:49:55,040 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-fip_vn-85891286', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-fip_vn-85891286?flat'}
2025-10-30 03:49:55,040 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-fip_vn-85891286 virtual network link through opserver 10.0.0.240
2025-10-30 03:49:55,040 - DEBUG - Requesting: http://10.0.0.240:8081/analytics/uves/virtual-networks
2025-10-30 03:49:55,054 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-fip_vn-85891286', 'href': 'http://10.0.0.240:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-fip_vn-85891286?flat'}
2025-10-30 03:49:55,054 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-fip_vn-85891286 is found in opserver
2025-10-30 03:49:55,066 - DEBUG - VRF ids for VN ctest-fip_vn-85891286: {'10.0.0.129': '3'}
2025-10-30 03:49:55,066 - INFO - start time= 1761796195066638
2025-10-30 03:49:55,066 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 91.228.167.3, host_string: cirros@169.254.0.3, password: gocubsgogateway: ubuntu@10.0.0.65, gateway password: c0ntrail123
2025-10-30 03:49:55,066 - DEBUG - ping -s 56 -c 3 -W 1 91.228.167.3
2025-10-30 03:49:58,940 - DEBUG - PING 91.228.167.3 (91.228.167.3): 56 data bytes
64 bytes from 91.228.167.3: seq=0 ttl=63 time=5.623 ms
64 bytes from 91.228.167.3: seq=1 ttl=63 time=1.396 ms
64 bytes from 91.228.167.3: seq=2 ttl=63 time=1.438 ms
--- 91.228.167.3 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 1.396/2.819/5.623 ms
2025-10-30 03:49:58,940 - INFO - Ping to IP 91.228.167.3 from VM ctest-vn1_vm1-39802516 passed
2025-10-30 03:50:08,941 - INFO - Verify session samples and teardown pkts
2025-10-30 03:50:08,941 - INFO - Verifying SessionSeriesTable through opserver 10.0.0.242
2025-10-30 03:50:08,945 - DEBUG - Posting: http://10.0.0.242:8081/analytics/query, payload {'table': 'SessionSeriesTable', 'start_time': 1761796195066638, 'end_time': 1761796208945099, 'select_fields': ['T'], 'where': [[{'name': 'vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn1-69893290', 'op': 1, 'value2': None}, {'name': 'remote_vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn2-21304413', 'op': 1, 'value2': None}, {'name': 'protocol', 'value': '1', 'op': 1, 'value2': None}]], 'session_type': 'client'}
2025-10-30 03:50:09,965 - DEBUG - [{'T': 1761796196979686}, {'T': 1761796197979751}, {'T': 1761796198979832}]
2025-10-30 03:50:09,965 - DEBUG - Posting: http://10.0.0.242:8081/analytics/query, payload {'table': 'SessionSeriesTable', 'start_time': 1761796195066638, 'end_time': 1761796209965484, 'select_fields': ['T'], 'where': [[{'name': 'vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn2-21304413', 'op': 1, 'value2': None}, {'name': 'remote_vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn1-69893290', 'op': 1, 'value2': None}, {'name': 'protocol', 'value': '1', 'op': 1, 'value2': None}]], 'session_type': 'server'}
2025-10-30 03:50:10,986 - DEBUG - [{'T': 1761796197443502}, {'T': 1761796198443583}, {'T': 1761796199443651}]
2025-10-30 03:50:10,987 - DEBUG - Posting: http://10.0.0.242:8081/analytics/query, payload {'table': 'SessionSeriesTable', 'start_time': 1761796195066638, 'end_time': 1761796210987037, 'select_fields': ['vn', 'remote_vn', 'sample_count'], 'where': [[{'name': 'vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn1-69893290', 'op': 1, 'value2': None}, {'name': 'remote_vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn2-21304413', 'op': 1, 'value2': None}, {'name': 'protocol', 'value': '1', 'op': 1, 'value2': None}]], 'session_type': 'client'}
2025-10-30 03:50:12,008 - DEBUG - [{'remote_vn': 'default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn2-21304413', 'vn': 'default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn1-69893290', 'sample_count': 3}]
2025-10-30 03:50:12,008 - INFO - wait for the flows to get expire
2025-10-30 03:53:32,009 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4535-2:Compute:contrail-vrouter-agent:0?flat
2025-10-30 03:53:32,045 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4535-2?flat
2025-10-30 03:53:32,076 - DEBUG - Posting: http://10.0.0.242:8081/analytics/query, payload {'table': 'SessionRecordTable', 'start_time': 1761796195066638, 'end_time': 1761796412076895, 'select_fields': ['vn', 'remote_vn', 'forward_teardown_pkts', 'reverse_teardown_pkts'], 'where': [[{'name': 'vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn1-69893290', 'op': 1, 'value2': None}, {'name': 'remote_vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn2-21304413', 'op': 1, 'value2': None}, {'name': 'protocol', 'value': '1', 'op': 1, 'value2': None}]], 'session_type': 'client'}
2025-10-30 03:53:33,103 - DEBUG - [{'forward_flow_uuid': '1f5719e9-58c5-443c-b4f9-2383393a3e0f', 'remote_vn': 'default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn2-21304413', 'reverse_flow_uuid': '75c76f82-ad00-4225-acec-ea42817077d2', 'vn': 'default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn1-69893290', 'forward_teardown_pkts': 3, 'reverse_teardown_pkts': 3}]
2025-10-30 03:53:33,103 - DEBUG - Posting: http://10.0.0.242:8081/analytics/query, payload {'table': 'SessionSeriesTable', 'start_time': 1761796195066638, 'end_time': 1761796413103236, 'select_fields': ['vn', 'remote_vn', 'sample_count'], 'where': [[{'name': 'vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn1-69893290', 'op': 1, 'value2': None}, {'name': 'remote_vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn2-21304413', 'op': 1, 'value2': None}, {'name': 'protocol', 'value': '1', 'op': 1, 'value2': None}]], 'session_type': 'client'}
2025-10-30 03:53:34,125 - DEBUG - Posting: http://10.0.0.242:8081/analytics/query, payload {'table': 'SessionSeriesTable', 'start_time': 1761796195066638, 'end_time': 1761796414125302, 'select_fields': ['vn', 'remote_vn', 'sample_count'], 'where': [[{'name': 'vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn2-21304413', 'op': 1, 'value2': None}, {'name': 'remote_vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn1-69893290', 'op': 1, 'value2': None}, {'name': 'protocol', 'value': '1', 'op': 1, 'value2': None}]], 'session_type': 'server'}
2025-10-30 03:53:35,146 - DEBUG - [{'remote_vn': 'default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn1-69893290', 'vn': 'default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn2-21304413', 'sample_count': 4}]
2025-10-30 03:53:35,621 - INFO - Deleted policy policy1
2025-10-30 03:53:35,745 - INFO - Setting flow export rate: None
2025-10-30 03:53:37,066 - DEBUG - Skipping xmpp flap check
2025-10-30 03:53:37,066 - INFO - END TEST : test_verify_session_sampling_teardown : PASSED[0:03:47]
2025-10-30 03:53:37,066 - INFO - --------------------------------------------------------------------------------
2025-10-30 03:53:37,068 - INFO - Deleting VM mltf_vm
2025-10-30 03:53:37,146 - INFO - Deleting VM ctest-fvn_vm1-50963986
2025-10-30 03:53:37,243 - INFO - Deleting VM ctest-vn2_vm2-61386846
2025-10-30 03:53:37,323 - INFO - Deleting VN ctest-fip_vn-85891286
2025-10-30 03:53:37,370 - DEBUG - VN 4d44802b-1692-4f39-8089-3b8bfe208eba still in use: Unable to complete operation on network 4d44802b-1692-4f39-8089-3b8bfe208eba. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-93c1b872-2000-4c16-bb40-586fd983a249']
2025-10-30 03:53:37,371 - WARNING - Deleting VN ctest-fip_vn-85891286 failed..Will retry
2025-10-30 03:53:39,554 - DEBUG - Response for deleting network ()
2025-10-30 03:53:39,554 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/3fd6f33c-95fc-41bf-a297-7194da8b9ded
2025-10-30 03:53:39,583 - DEBUG - Response Code: 404
2025-10-30 03:53:39,583 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:53:39,591 - DEBUG - Requesting: http://10.0.0.242:8082/domain/2be6ea5e-665d-4a78-a8ec-f940f567f06f
2025-10-30 03:53:39,607 - DEBUG - Requesting: http://10.0.0.242:8082/project/7f5f4f84-1c0c-426e-9398-6021e6c8e34c
2025-10-30 03:53:39,696 - INFO - Validated that VN ctest-fip_vn-85891286 is not found in API Server
2025-10-30 03:53:39,708 - DEBUG - VN ctest-fip_vn-85891286 is not present in Agent 10.0.0.129
2025-10-30 03:53:39,721 - DEBUG - VN ctest-fip_vn-85891286 is not present in Agent 10.0.0.65
2025-10-30 03:53:39,721 - INFO - Validated that VN ctest-fip_vn-85891286 is not in any agent
2025-10-30 03:53:39,726 - DEBUG - VRF 3 is not seen in agent 10.0.0.129
2025-10-30 03:53:39,732 - DEBUG - Vrouter 10.0.0.129 does not have vrf 3 for VN ctest-fip_vn-85891286
2025-10-30 03:53:39,732 - INFO - Validated that all vrouters do not have the route table for VN default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-fip_vn-85891286
2025-10-30 03:53:39,752 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-fip_vn-85891286 info
2025-10-30 03:53:39,752 - INFO - Deleting VN ctest-vn2-21304413
2025-10-30 03:53:39,894 - DEBUG - Response for deleting network ()
2025-10-30 03:53:39,894 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/ae5a9b94-a726-4906-a138-6cc6df40433e
2025-10-30 03:53:39,901 - DEBUG - Response Code: 404
2025-10-30 03:53:39,901 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:53:39,906 - DEBUG - Requesting: http://10.0.0.242:8082/domain/2be6ea5e-665d-4a78-a8ec-f940f567f06f
2025-10-30 03:53:39,962 - DEBUG - Requesting: http://10.0.0.242:8082/project/7f5f4f84-1c0c-426e-9398-6021e6c8e34c
2025-10-30 03:53:40,067 - INFO - Validated that VN ctest-vn2-21304413 is not found in API Server
2025-10-30 03:53:40,079 - DEBUG - VN ctest-vn2-21304413 is not present in Agent 10.0.0.129
2025-10-30 03:53:40,089 - DEBUG - VN ctest-vn2-21304413 is not present in Agent 10.0.0.65
2025-10-30 03:53:40,089 - INFO - Validated that VN ctest-vn2-21304413 is not in any agent
2025-10-30 03:53:40,094 - DEBUG - VRF 2 is not seen in agent 10.0.0.129
2025-10-30 03:53:40,099 - DEBUG - Vrouter 10.0.0.129 does not have vrf 2 for VN ctest-vn2-21304413
2025-10-30 03:53:40,104 - DEBUG - VRF 3 is not seen in agent 10.0.0.65
2025-10-30 03:53:40,109 - DEBUG - Vrouter 10.0.0.65 does not have vrf 3 for VN ctest-vn2-21304413
2025-10-30 03:53:40,109 - INFO - Validated that all vrouters do not have the route table for VN default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn2-21304413
2025-10-30 03:53:40,133 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn2-21304413 info
2025-10-30 03:53:40,133 - INFO - Deleting VM ctest-vn1_vm2-56222863
2025-10-30 03:53:40,230 - INFO - Deleting VM ctest-vn1_vm1-39802516
2025-10-30 03:53:40,309 - INFO - Deleting VN ctest-vn1-69893290
2025-10-30 03:53:40,355 - DEBUG - VN f39b6b6f-ca3f-4d7b-9d30-29226bc65585 still in use: Unable to complete operation on network f39b6b6f-ca3f-4d7b-9d30-29226bc65585. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-9c0a7d26-46f0-4551-8e9f-9680fea3b9b2']
2025-10-30 03:53:40,355 - WARNING - Deleting VN ctest-vn1-69893290 failed..Will retry
2025-10-30 03:53:42,522 - DEBUG - Response for deleting network ()
2025-10-30 03:53:42,522 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/c4d871dc-f456-4056-9f3c-0bc99af963e0
2025-10-30 03:53:42,534 - DEBUG - Response Code: 404
2025-10-30 03:53:42,534 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2025-10-30 03:53:42,546 - DEBUG - Requesting: http://10.0.0.242:8082/domain/2be6ea5e-665d-4a78-a8ec-f940f567f06f
2025-10-30 03:53:42,558 - DEBUG - Requesting: http://10.0.0.242:8082/project/7f5f4f84-1c0c-426e-9398-6021e6c8e34c
2025-10-30 03:53:42,664 - INFO - Validated that VN ctest-vn1-69893290 is not found in API Server
2025-10-30 03:53:42,676 - DEBUG - VN ctest-vn1-69893290 is not present in Agent 10.0.0.129
2025-10-30 03:53:42,687 - DEBUG - VN ctest-vn1-69893290 is not present in Agent 10.0.0.65
2025-10-30 03:53:42,687 - INFO - Validated that VN ctest-vn1-69893290 is not in any agent
2025-10-30 03:53:42,694 - DEBUG - VRF 2 is not seen in agent 10.0.0.65
2025-10-30 03:53:42,701 - DEBUG - Vrouter 10.0.0.65 does not have vrf 2 for VN ctest-vn1-69893290
2025-10-30 03:53:42,701 - INFO - Validated that all vrouters do not have the route table for VN default-domain:ctest-AnalyticsTestSanityWithResource-55359781:ctest-vn1-69893290
2025-10-30 03:53:42,726 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn1-69893290 info
2025-10-30 03:53:43,558 - INFO - Deleted project: ctest-AnalyticsTestSanityWithResource-55359781, ID : 7f5f4f84-1c0c-426e-9398-6021e6c8e34c