2026-03-26 03:50:20,059 - INFO - Domain Default found not creating 2026-03-26 03:50:20,251 - INFO - Project ctest-AnalyticsTestSanityWithResource-43814714 not found, creating it 2026-03-26 03:50:20,865 - INFO - Created Project:ctest-AnalyticsTestSanityWithResource-43814714, ID : 6750c5b7-55c0-491a-ab64-6801c7b0a322 2026-03-26 03:50:23,539 - DEBUG - Response for create_network : {'network': {'id': 'aef1774d-96ae-45ae-9f34-35d4ea182f71', 'name': 'ctest-vn1-06975742', 'tenant_id': '6750c5b755c0491aab646801c7b0a322', 'project_id': '6750c5b755c0491aab646801c7b0a322', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-AnalyticsTestSanityWithResource-43814714', 'ctest-vn1-06975742'], 'port_security_enabled': True, 'description': ''}} 2026-03-26 03:50:23,890 - DEBUG - Response for create_subnet : {'subnet': {'id': '93f15ef7-39f0-452f-9d0d-5cb2a37233fc', 'name': '', 'tenant_id': '6750c5b755c0491aab646801c7b0a322', 'network_id': 'aef1774d-96ae-45ae-9f34-35d4ea182f71', 'ip_version': 4, 'cidr': '73.139.205.128/26', 'allocation_pools': [{'start': '73.139.205.130', 'end': '73.139.205.190'}], 'gateway_ip': '73.139.205.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '73.139.205.130', 'tags': [], 'project_id': '6750c5b755c0491aab646801c7b0a322'}} 2026-03-26 03:50:23,916 - INFO - Created VN ctest-vn1-06975742 2026-03-26 03:50:23,929 - DEBUG - VN ctest-vn1-06975742 UUID is aef1774d-96ae-45ae-9f34-35d4ea182f71 2026-03-26 03:50:24,032 - DEBUG - Services list from nova: [, , , ] 2026-03-26 03:50:24,032 - DEBUG - Hosts: {'nova': ['an-jenkins-deploy-platform-ansible-os-5545-2', 'an-jenkins-deploy-platform-ansible-os-5545-1']} 2026-03-26 03:50:25,433 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5545-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5545-2) 2026-03-26 03:50:27,189 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5545-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5545-2) 2026-03-26 03:50:27,189 - DEBUG - Requesting: http://10.0.0.240:8082/domains 2026-03-26 03:50:27,623 - DEBUG - Requesting: http://10.0.0.240:8082/domains 2026-03-26 03:50:27,679 - DEBUG - Requesting: http://10.0.0.240:8082/domain/b07ef41d-f358-4169-a694-2c6c20158c87 2026-03-26 03:50:27,720 - DEBUG - Requesting: http://10.0.0.240:8082/project/6750c5b7-55c0-491a-ab64-6801c7b0a322 2026-03-26 03:50:27,873 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-network/aef1774d-96ae-45ae-9f34-35d4ea182f71 2026-03-26 03:50:27,889 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-network/aef1774d-96ae-45ae-9f34-35d4ea182f71 2026-03-26 03:50:27,904 - DEBUG - Requesting: http://10.0.0.240:8082/routing-instance/9c293f6a-7510-4e5a-b127-9b395a302887 2026-03-26 03:50:27,917 - DEBUG - Requesting: http://10.0.0.240:8082/routing-instance/9c293f6a-7510-4e5a-b127-9b395a302887 2026-03-26 03:50:27,928 - DEBUG - Requesting: http://10.0.0.240:8082/route-target/caa4c2b5-d1f7-4c88-9e33-4566d4c465b0 2026-03-26 03:50:27,937 - DEBUG - Route Targets: ['target:64512:8000007'] 2026-03-26 03:50:27,937 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-network/aef1774d-96ae-45ae-9f34-35d4ea182f71 2026-03-26 03:50:27,953 - DEBUG - Requesting: http://10.0.0.240:8082/routing-instance/9c293f6a-7510-4e5a-b127-9b395a302887 2026-03-26 03:50:27,978 - INFO - Verified VN network id 18 for VN aef1774d-96ae-45ae-9f34-35d4ea182f71 2026-03-26 03:50:27,978 - INFO - Verifications in API Server for VN ctest-vn1-06975742 passed 2026-03-26 03:50:27,978 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-network/aef1774d-96ae-45ae-9f34-35d4ea182f71 2026-03-26 03:50:28,050 - DEBUG - Requesting: http://10.0.0.240:8082/routing-instance/9c293f6a-7510-4e5a-b127-9b395a302887 2026-03-26 03:50:28,073 - DEBUG - Requesting: http://10.0.0.240:8082/routing-instance/9c293f6a-7510-4e5a-b127-9b395a302887 2026-03-26 03:50:28,085 - DEBUG - Requesting: http://10.0.0.240:8082/route-target/caa4c2b5-d1f7-4c88-9e33-4566d4c465b0 2026-03-26 03:50:28,102 - DEBUG - Control-node 10.0.0.240 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-43814714', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12605988007110919598', 'uuid-lslong': '11471853339396288369'}, 'enable': 'true', 'created': '2026-03-26T03:50:23', 'last-modified': '2026-03-26T03:50:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:04.259364'} 2026-03-26 03:50:28,114 - DEBUG - Route Targets: ['target:64512:8000007'] 2026-03-26 03:50:28,127 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-43814714', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12605988007110919598', 'uuid-lslong': '11471853339396288369'}, 'enable': 'true', 'created': '2026-03-26T03:50:23', 'last-modified': '2026-03-26T03:50:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:04.286957'} 2026-03-26 03:50:28,134 - DEBUG - Route Targets: ['target:64512:8000007'] 2026-03-26 03:50:28,143 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-43814714', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12605988007110919598', 'uuid-lslong': '11471853339396288369'}, 'enable': 'true', 'created': '2026-03-26T03:50:23', 'last-modified': '2026-03-26T03:50:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:04.300045'} 2026-03-26 03:50:28,151 - DEBUG - Route Targets: ['target:64512:8000007'] 2026-03-26 03:50:28,151 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn1-06975742 passed 2026-03-26 03:50:28,151 - DEBUG - ====Verifying policy data for ctest-vn1-06975742 in API_Server ====== 2026-03-26 03:50:28,151 - DEBUG - Requesting: http://10.0.0.240:8082/domains 2026-03-26 03:50:28,158 - DEBUG - Requesting: http://10.0.0.240:8082/domain/b07ef41d-f358-4169-a694-2c6c20158c87 2026-03-26 03:50:28,171 - DEBUG - Requesting: http://10.0.0.240:8082/project/6750c5b7-55c0-491a-ab64-6801c7b0a322 2026-03-26 03:50:28,261 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-network/aef1774d-96ae-45ae-9f34-35d4ea182f71 2026-03-26 03:50:28,288 - DEBUG - =>VN ctest-vn1-06975742 has no policy to be verified 2026-03-26 03:50:28,288 - DEBUG - Verifying the vn in opserver 2026-03-26 03:50:28,288 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742 virtual network link through opserver 10.0.0.240 2026-03-26 03:50:28,288 - DEBUG - Requesting: http://10.0.0.240:8081/analytics/uves/virtual-networks 2026-03-26 03:50:28,649 - DEBUG - Requesting: http://10.0.0.240:8081/analytics/uves/virtual-networks 2026-03-26 03:50:28,725 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742', 'href': 'http://10.0.0.240:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742?flat'} 2026-03-26 03:50:28,725 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742 virtual network link through opserver 10.0.0.22 2026-03-26 03:50:28,726 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks 2026-03-26 03:50:29,078 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks 2026-03-26 03:50:29,163 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742?flat'} 2026-03-26 03:50:29,163 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742 virtual network link through opserver 10.0.0.65 2026-03-26 03:50:29,163 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks 2026-03-26 03:50:29,519 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks 2026-03-26 03:50:29,598 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742?flat'} 2026-03-26 03:50:29,598 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742 is found in opserver 2026-03-26 03:50:29,610 - DEBUG - VRF ids for VN ctest-vn1-06975742: {'10.0.0.23': '4'} 2026-03-26 03:50:29,610 - INFO - Waiting for VM ctest-vn1_vm1-82499764 to be up.. 2026-03-26 03:50:29,711 - DEBUG - VM is in ACTIVE state now 2026-03-26 03:50:29,711 - INFO - VM name : ctest-vn1_vm1-82499764 2026-03-26 03:50:29,812 - DEBUG - VM ctest-vn1_vm1-82499764 ID is 8cfabd1e-bea2-4e0d-b4ff-be3eff8de118 2026-03-26 03:50:29,840 - DEBUG - VM ctest-vn1_vm1-82499764 launched on Node an-jenkins-deploy-platform-ansible-os-5545-2 2026-03-26 03:50:29,941 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/8cfabd1e-bea2-4e0d-b4ff-be3eff8de118 2026-03-26 03:50:30,286 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/8cfabd1e-bea2-4e0d-b4ff-be3eff8de118 2026-03-26 03:50:30,330 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine-interface/0fb71b31-38ab-4990-b2a9-60aed79f04d0 2026-03-26 03:50:33,514 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1003ms') 2026-03-26 03:50:33,514 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-vn1_vm1-82499764 failed! 2026-03-26 03:50:33,530 - DEBUG - Gateway for vn default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742 is 73.139.205.129 and allocation pool is NOT set 2026-03-26 03:50:37,608 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1019ms') 2026-03-26 03:50:37,609 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-vn1_vm1-82499764 failed! 2026-03-26 03:50:37,623 - DEBUG - Gateway for vn default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742 is 73.139.205.129 and allocation pool is NOT set 2026-03-26 03:50:41,701 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1013ms') 2026-03-26 03:50:41,701 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-vn1_vm1-82499764 failed! 2026-03-26 03:50:41,715 - DEBUG - Gateway for vn default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742 is 73.139.205.129 and allocation pool is NOT set 2026-03-26 03:50:45,803 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1023ms') 2026-03-26 03:50:45,803 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-vn1_vm1-82499764 failed! 2026-03-26 03:50:45,820 - DEBUG - Gateway for vn default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742 is 73.139.205.129 and allocation pool is NOT set 2026-03-26 03:50:47,889 - 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=14.9 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=2.02 ms\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 2.021/8.439/14.858/6.418 ms') 2026-03-26 03:50:47,889 - INFO - Ping to Metadata IP 169.254.0.5 of VM ctest-vn1_vm1-82499764 passed 2026-03-26 03:50:47,965 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-26 03:50:47,965 - DEBUG - Waiting to SSH to VM ctest-vn1_vm1-82499764, IP 73.139.205.131, Port 22 2026-03-26 03:50:48,037 - DEBUG - Error on ssh to cirros@169.254.0.5:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.5/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.5/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.5/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-03-26 03:50:48,138 - DEBUG - VM ctest-vn1_vm1-82499764 is NOT ready for SSH connections, VM status: ACTIVE 2026-03-26 03:50:53,138 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-26 03:50:53,138 - DEBUG - Waiting to SSH to VM ctest-vn1_vm1-82499764, IP 73.139.205.131, Port 22 2026-03-26 03:50:53,194 - DEBUG - Error on ssh to cirros@169.254.0.5:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.5/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.5/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.5/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-03-26 03:50:53,291 - DEBUG - VM ctest-vn1_vm1-82499764 is NOT ready for SSH connections, VM status: ACTIVE 2026-03-26 03:50:58,293 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-26 03:50:58,293 - DEBUG - Waiting to SSH to VM ctest-vn1_vm1-82499764, IP 73.139.205.131, Port 22 2026-03-26 03:50:58,453 - DEBUG - VM ctest-vn1_vm1-82499764 is ready for SSH connections 2026-03-26 03:50:58,454 - INFO - Waiting for VM ctest-vn1_vm2-03732048 to be up.. 2026-03-26 03:50:58,541 - DEBUG - VM is in ACTIVE state now 2026-03-26 03:50:58,542 - INFO - VM name : ctest-vn1_vm2-03732048 2026-03-26 03:50:58,623 - DEBUG - VM ctest-vn1_vm2-03732048 ID is f8098a8d-e5ba-4982-a822-d81ce78c57da 2026-03-26 03:50:58,623 - DEBUG - VM ctest-vn1_vm2-03732048 launched on Node an-jenkins-deploy-platform-ansible-os-5545-2 2026-03-26 03:50:58,702 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/f8098a8d-e5ba-4982-a822-d81ce78c57da 2026-03-26 03:50:58,713 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine-interface/c0f6e8c5-1ce2-4972-9e28-eceef08ee691 2026-03-26 03:50:59,893 - DEBUG - (True, 'PING 169.254.0.6 (169.254.0.6) 56(84) bytes of data.\r\n64 bytes from 169.254.0.6: icmp_seq=1 ttl=63 time=4.47 ms\r\n64 bytes from 169.254.0.6: icmp_seq=2 ttl=63 time=1.46 ms\r\n\r\n--- 169.254.0.6 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 1.456/2.961/4.467/1.505 ms') 2026-03-26 03:50:59,893 - INFO - Ping to Metadata IP 169.254.0.6 of VM ctest-vn1_vm2-03732048 passed 2026-03-26 03:50:59,965 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-26 03:50:59,965 - DEBUG - Waiting to SSH to VM ctest-vn1_vm2-03732048, IP 73.139.205.132, Port 22 2026-03-26 03:51:00,122 - DEBUG - VM ctest-vn1_vm2-03732048 is ready for SSH connections 2026-03-26 03:51:00,459 - DEBUG - Response for create_network : {'network': {'id': '967ae200-ff4d-438e-9a34-da703d772557', 'name': 'ctest-vn2-04268916', 'tenant_id': '6750c5b755c0491aab646801c7b0a322', 'project_id': '6750c5b755c0491aab646801c7b0a322', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-AnalyticsTestSanityWithResource-43814714', 'ctest-vn2-04268916'], 'port_security_enabled': True, 'description': ''}} 2026-03-26 03:51:00,770 - DEBUG - Response for create_subnet : {'subnet': {'id': '98b66c46-a3a5-45eb-b9e1-25f51e4cb10a', 'name': '', 'tenant_id': '6750c5b755c0491aab646801c7b0a322', 'network_id': '967ae200-ff4d-438e-9a34-da703d772557', 'ip_version': 4, 'cidr': '63.236.149.0/26', 'allocation_pools': [{'start': '63.236.149.2', 'end': '63.236.149.62'}], 'gateway_ip': '63.236.149.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '63.236.149.2', 'tags': [], 'project_id': '6750c5b755c0491aab646801c7b0a322'}} 2026-03-26 03:51:00,796 - INFO - Created VN ctest-vn2-04268916 2026-03-26 03:51:00,811 - DEBUG - VN ctest-vn2-04268916 UUID is 967ae200-ff4d-438e-9a34-da703d772557 2026-03-26 03:51:01,166 - DEBUG - Response for create_network : {'network': {'id': '8e66bfe9-84c5-45a3-a4a3-6f0187c17f1c', 'name': 'ctest-fip_vn-96706071', 'tenant_id': '6750c5b755c0491aab646801c7b0a322', 'project_id': '6750c5b755c0491aab646801c7b0a322', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-AnalyticsTestSanityWithResource-43814714', 'ctest-fip_vn-96706071'], 'port_security_enabled': True, 'description': ''}} 2026-03-26 03:51:01,500 - DEBUG - Response for create_subnet : {'subnet': {'id': 'aed11d15-8557-4819-8a00-90c1dd1d1c38', 'name': '', 'tenant_id': '6750c5b755c0491aab646801c7b0a322', 'network_id': '8e66bfe9-84c5-45a3-a4a3-6f0187c17f1c', 'ip_version': 4, 'cidr': '162.76.121.0/26', 'allocation_pools': [{'start': '162.76.121.2', 'end': '162.76.121.62'}], 'gateway_ip': '162.76.121.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '162.76.121.2', 'tags': [], 'project_id': '6750c5b755c0491aab646801c7b0a322'}} 2026-03-26 03:51:01,527 - INFO - Created VN ctest-fip_vn-96706071 2026-03-26 03:51:01,540 - DEBUG - VN ctest-fip_vn-96706071 UUID is 8e66bfe9-84c5-45a3-a4a3-6f0187c17f1c 2026-03-26 03:51:01,623 - DEBUG - Hosts: {'nova': ['an-jenkins-deploy-platform-ansible-os-5545-2', 'an-jenkins-deploy-platform-ansible-os-5545-1']} 2026-03-26 03:51:03,152 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5545-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5545-1) 2026-03-26 03:51:04,864 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5545-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5545-1) 2026-03-26 03:51:06,925 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5545-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5545-2) 2026-03-26 03:51:06,925 - DEBUG - Requesting: http://10.0.0.240:8082/domains 2026-03-26 03:51:06,938 - DEBUG - Requesting: http://10.0.0.240:8082/domain/b07ef41d-f358-4169-a694-2c6c20158c87 2026-03-26 03:51:06,957 - DEBUG - Requesting: http://10.0.0.240:8082/project/6750c5b7-55c0-491a-ab64-6801c7b0a322 2026-03-26 03:51:07,058 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-network/aef1774d-96ae-45ae-9f34-35d4ea182f71 2026-03-26 03:51:07,085 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-network/aef1774d-96ae-45ae-9f34-35d4ea182f71 2026-03-26 03:51:07,096 - DEBUG - Requesting: http://10.0.0.240:8082/routing-instance/9c293f6a-7510-4e5a-b127-9b395a302887 2026-03-26 03:51:07,103 - DEBUG - Requesting: http://10.0.0.240:8082/routing-instance/9c293f6a-7510-4e5a-b127-9b395a302887 2026-03-26 03:51:07,111 - DEBUG - Requesting: http://10.0.0.240:8082/route-target/caa4c2b5-d1f7-4c88-9e33-4566d4c465b0 2026-03-26 03:51:07,126 - DEBUG - Route Targets: ['target:64512:8000007'] 2026-03-26 03:51:07,126 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-network/aef1774d-96ae-45ae-9f34-35d4ea182f71 2026-03-26 03:51:07,137 - DEBUG - Requesting: http://10.0.0.240:8082/routing-instance/9c293f6a-7510-4e5a-b127-9b395a302887 2026-03-26 03:51:07,161 - INFO - Verified VN network id 18 for VN aef1774d-96ae-45ae-9f34-35d4ea182f71 2026-03-26 03:51:07,161 - INFO - Verifications in API Server for VN ctest-vn1-06975742 passed 2026-03-26 03:51:07,161 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-network/aef1774d-96ae-45ae-9f34-35d4ea182f71 2026-03-26 03:51:07,174 - DEBUG - Requesting: http://10.0.0.240:8082/routing-instance/9c293f6a-7510-4e5a-b127-9b395a302887 2026-03-26 03:51:07,184 - DEBUG - Requesting: http://10.0.0.240:8082/routing-instance/9c293f6a-7510-4e5a-b127-9b395a302887 2026-03-26 03:51:07,203 - DEBUG - Requesting: http://10.0.0.240:8082/route-target/caa4c2b5-d1f7-4c88-9e33-4566d4c465b0 2026-03-26 03:51:07,218 - DEBUG - Control-node 10.0.0.240 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-43814714', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12605988007110919598', 'uuid-lslong': '11471853339396288369'}, 'enable': 'true', 'created': '2026-03-26T03:50:23', 'last-modified': '2026-03-26T03:50:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:43.376433'} 2026-03-26 03:51:07,224 - DEBUG - Route Targets: ['target:64512:8000007'] 2026-03-26 03:51:07,231 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-43814714', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12605988007110919598', 'uuid-lslong': '11471853339396288369'}, 'enable': 'true', 'created': '2026-03-26T03:50:23', 'last-modified': '2026-03-26T03:50:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:43.395250'} 2026-03-26 03:51:07,240 - DEBUG - Route Targets: ['target:64512:8000007'] 2026-03-26 03:51:07,247 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-43814714', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12605988007110919598', 'uuid-lslong': '11471853339396288369'}, 'enable': 'true', 'created': '2026-03-26T03:50:23', 'last-modified': '2026-03-26T03:50:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:43.405358'} 2026-03-26 03:51:07,254 - DEBUG - Route Targets: ['target:64512:8000007'] 2026-03-26 03:51:07,254 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn1-06975742 passed 2026-03-26 03:51:07,254 - DEBUG - ====Verifying policy data for ctest-vn1-06975742 in API_Server ====== 2026-03-26 03:51:07,254 - DEBUG - Requesting: http://10.0.0.240:8082/domains 2026-03-26 03:51:07,261 - DEBUG - Requesting: http://10.0.0.240:8082/domain/b07ef41d-f358-4169-a694-2c6c20158c87 2026-03-26 03:51:07,274 - DEBUG - Requesting: http://10.0.0.240:8082/project/6750c5b7-55c0-491a-ab64-6801c7b0a322 2026-03-26 03:51:07,365 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-network/aef1774d-96ae-45ae-9f34-35d4ea182f71 2026-03-26 03:51:07,407 - DEBUG - =>VN ctest-vn1-06975742 has no policy to be verified 2026-03-26 03:51:07,407 - DEBUG - Verifying the vn in opserver 2026-03-26 03:51:07,407 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742 virtual network link through opserver 10.0.0.240 2026-03-26 03:51:07,407 - DEBUG - Requesting: http://10.0.0.240:8081/analytics/uves/virtual-networks 2026-03-26 03:51:07,552 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742', 'href': 'http://10.0.0.240:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742?flat'} 2026-03-26 03:51:07,552 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742 virtual network link through opserver 10.0.0.22 2026-03-26 03:51:07,552 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks 2026-03-26 03:51:07,690 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742?flat'} 2026-03-26 03:51:07,690 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742 virtual network link through opserver 10.0.0.65 2026-03-26 03:51:07,690 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks 2026-03-26 03:51:07,852 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742?flat'} 2026-03-26 03:51:07,852 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742 is found in opserver 2026-03-26 03:51:07,863 - DEBUG - VRF ids for VN ctest-vn1-06975742: {'10.0.0.23': '4'} 2026-03-26 03:51:07,863 - DEBUG - Requesting: http://10.0.0.240:8082/domains 2026-03-26 03:51:07,870 - DEBUG - Requesting: http://10.0.0.240:8082/domain/b07ef41d-f358-4169-a694-2c6c20158c87 2026-03-26 03:51:07,895 - DEBUG - Requesting: http://10.0.0.240:8082/project/6750c5b7-55c0-491a-ab64-6801c7b0a322 2026-03-26 03:51:08,012 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-network/967ae200-ff4d-438e-9a34-da703d772557 2026-03-26 03:51:08,027 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-network/967ae200-ff4d-438e-9a34-da703d772557 2026-03-26 03:51:08,046 - DEBUG - Requesting: http://10.0.0.240:8082/routing-instance/728f4a39-0d37-4260-a03c-c4585266a0e8 2026-03-26 03:51:08,056 - DEBUG - Requesting: http://10.0.0.240:8082/routing-instance/728f4a39-0d37-4260-a03c-c4585266a0e8 2026-03-26 03:51:08,073 - DEBUG - Requesting: http://10.0.0.240:8082/route-target/eff36213-bd83-44cd-b5de-f0e1517ca2a9 2026-03-26 03:51:08,099 - DEBUG - Route Targets: ['target:64512:8000008'] 2026-03-26 03:51:08,099 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-network/967ae200-ff4d-438e-9a34-da703d772557 2026-03-26 03:51:08,137 - DEBUG - Requesting: http://10.0.0.240:8082/routing-instance/728f4a39-0d37-4260-a03c-c4585266a0e8 2026-03-26 03:51:08,168 - INFO - Verified VN network id 19 for VN 967ae200-ff4d-438e-9a34-da703d772557 2026-03-26 03:51:08,168 - INFO - Verifications in API Server for VN ctest-vn2-04268916 passed 2026-03-26 03:51:08,168 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-network/967ae200-ff4d-438e-9a34-da703d772557 2026-03-26 03:51:08,185 - DEBUG - Requesting: http://10.0.0.240:8082/routing-instance/728f4a39-0d37-4260-a03c-c4585266a0e8 2026-03-26 03:51:08,201 - DEBUG - Requesting: http://10.0.0.240:8082/routing-instance/728f4a39-0d37-4260-a03c-c4585266a0e8 2026-03-26 03:51:08,212 - DEBUG - Requesting: http://10.0.0.240:8082/route-target/eff36213-bd83-44cd-b5de-f0e1517ca2a9 2026-03-26 03:51:08,226 - DEBUG - Control-node 10.0.0.240 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn2-04268916', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-43814714', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10843227546759021454', 'uuid-lslong': '11111746356232267095'}, 'enable': 'true', 'created': '2026-03-26T03:51:00', 'last-modified': '2026-03-26T03:51:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:07.552591'} 2026-03-26 03:51:08,232 - DEBUG - Route Targets: ['target:64512:8000008'] 2026-03-26 03:51:08,237 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn2-04268916', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-43814714', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10843227546759021454', 'uuid-lslong': '11111746356232267095'}, 'enable': 'true', 'created': '2026-03-26T03:51:00', 'last-modified': '2026-03-26T03:51:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:07.554192'} 2026-03-26 03:51:08,248 - DEBUG - Route Targets: ['target:64512:8000008'] 2026-03-26 03:51:08,256 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn2-04268916', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-43814714', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10843227546759021454', 'uuid-lslong': '11111746356232267095'}, 'enable': 'true', 'created': '2026-03-26T03:51:00', 'last-modified': '2026-03-26T03:51:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:07.582989'} 2026-03-26 03:51:08,271 - DEBUG - Route Targets: ['target:64512:8000008'] 2026-03-26 03:51:08,271 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn2-04268916 passed 2026-03-26 03:51:08,271 - DEBUG - ====Verifying policy data for ctest-vn2-04268916 in API_Server ====== 2026-03-26 03:51:08,271 - DEBUG - Requesting: http://10.0.0.240:8082/domains 2026-03-26 03:51:08,279 - DEBUG - Requesting: http://10.0.0.240:8082/domain/b07ef41d-f358-4169-a694-2c6c20158c87 2026-03-26 03:51:08,293 - DEBUG - Requesting: http://10.0.0.240:8082/project/6750c5b7-55c0-491a-ab64-6801c7b0a322 2026-03-26 03:51:08,391 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-network/967ae200-ff4d-438e-9a34-da703d772557 2026-03-26 03:51:08,402 - DEBUG - =>VN ctest-vn2-04268916 has no policy to be verified 2026-03-26 03:51:08,402 - DEBUG - Verifying the vn in opserver 2026-03-26 03:51:08,402 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn2-04268916 virtual network link through opserver 10.0.0.240 2026-03-26 03:51:08,402 - DEBUG - Requesting: http://10.0.0.240:8081/analytics/uves/virtual-networks 2026-03-26 03:51:08,417 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn2-04268916', 'href': 'http://10.0.0.240:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn2-04268916?flat'} 2026-03-26 03:51:08,417 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn2-04268916 virtual network link through opserver 10.0.0.22 2026-03-26 03:51:08,417 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks 2026-03-26 03:51:08,531 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn2-04268916', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn2-04268916?flat'} 2026-03-26 03:51:08,531 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn2-04268916 virtual network link through opserver 10.0.0.65 2026-03-26 03:51:08,531 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks 2026-03-26 03:51:08,545 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn2-04268916', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn2-04268916?flat'} 2026-03-26 03:51:08,545 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn2-04268916 is found in opserver 2026-03-26 03:51:08,561 - DEBUG - VRF ids for VN ctest-vn2-04268916: {'10.0.0.38': '4'} 2026-03-26 03:51:08,561 - DEBUG - Requesting: http://10.0.0.240:8082/domains 2026-03-26 03:51:08,566 - DEBUG - Requesting: http://10.0.0.240:8082/domain/b07ef41d-f358-4169-a694-2c6c20158c87 2026-03-26 03:51:08,587 - DEBUG - Requesting: http://10.0.0.240:8082/project/6750c5b7-55c0-491a-ab64-6801c7b0a322 2026-03-26 03:51:08,697 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-network/8e66bfe9-84c5-45a3-a4a3-6f0187c17f1c 2026-03-26 03:51:08,740 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-network/8e66bfe9-84c5-45a3-a4a3-6f0187c17f1c 2026-03-26 03:51:08,768 - DEBUG - Requesting: http://10.0.0.240:8082/routing-instance/21d2c5e3-674e-4818-bd71-7f3a9d3a59a9 2026-03-26 03:51:08,784 - DEBUG - Requesting: http://10.0.0.240:8082/routing-instance/21d2c5e3-674e-4818-bd71-7f3a9d3a59a9 2026-03-26 03:51:08,796 - DEBUG - Requesting: http://10.0.0.240:8082/route-target/3e7d6696-58da-427e-a944-92d9aca0e4c5 2026-03-26 03:51:08,805 - DEBUG - Route Targets: ['target:64512:8000009'] 2026-03-26 03:51:08,805 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-network/8e66bfe9-84c5-45a3-a4a3-6f0187c17f1c 2026-03-26 03:51:08,817 - DEBUG - Requesting: http://10.0.0.240:8082/routing-instance/21d2c5e3-674e-4818-bd71-7f3a9d3a59a9 2026-03-26 03:51:08,840 - INFO - Verified VN network id 20 for VN 8e66bfe9-84c5-45a3-a4a3-6f0187c17f1c 2026-03-26 03:51:08,841 - INFO - Verifications in API Server for VN ctest-fip_vn-96706071 passed 2026-03-26 03:51:08,841 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-network/8e66bfe9-84c5-45a3-a4a3-6f0187c17f1c 2026-03-26 03:51:08,853 - DEBUG - Requesting: http://10.0.0.240:8082/routing-instance/21d2c5e3-674e-4818-bd71-7f3a9d3a59a9 2026-03-26 03:51:08,862 - DEBUG - Requesting: http://10.0.0.240:8082/routing-instance/21d2c5e3-674e-4818-bd71-7f3a9d3a59a9 2026-03-26 03:51:08,870 - DEBUG - Requesting: http://10.0.0.240:8082/route-target/3e7d6696-58da-427e-a944-92d9aca0e4c5 2026-03-26 03:51:08,886 - DEBUG - Control-node 10.0.0.240 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-fip_vn-96706071', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-43814714', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10261099810686059939', 'uuid-lslong': '11863447895787274012'}, 'enable': 'true', 'created': '2026-03-26T03:51:01', 'last-modified': '2026-03-26T03:51:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:07.504790'} 2026-03-26 03:51:08,893 - DEBUG - Route Targets: ['target:64512:8000009'] 2026-03-26 03:51:08,899 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-fip_vn-96706071', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-43814714', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10261099810686059939', 'uuid-lslong': '11863447895787274012'}, 'enable': 'true', 'created': '2026-03-26T03:51:01', 'last-modified': '2026-03-26T03:51:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:07.517178'} 2026-03-26 03:51:08,908 - DEBUG - Route Targets: ['target:64512:8000009'] 2026-03-26 03:51:08,914 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-fip_vn-96706071', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-43814714', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10261099810686059939', 'uuid-lslong': '11863447895787274012'}, 'enable': 'true', 'created': '2026-03-26T03:51:01', 'last-modified': '2026-03-26T03:51:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:07.533678'} 2026-03-26 03:51:08,921 - DEBUG - Route Targets: ['target:64512:8000009'] 2026-03-26 03:51:08,921 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-fip_vn-96706071 passed 2026-03-26 03:51:08,921 - DEBUG - ====Verifying policy data for ctest-fip_vn-96706071 in API_Server ====== 2026-03-26 03:51:08,921 - DEBUG - Requesting: http://10.0.0.240:8082/domains 2026-03-26 03:51:08,928 - DEBUG - Requesting: http://10.0.0.240:8082/domain/b07ef41d-f358-4169-a694-2c6c20158c87 2026-03-26 03:51:08,944 - DEBUG - Requesting: http://10.0.0.240:8082/project/6750c5b7-55c0-491a-ab64-6801c7b0a322 2026-03-26 03:51:09,067 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-network/8e66bfe9-84c5-45a3-a4a3-6f0187c17f1c 2026-03-26 03:51:09,084 - DEBUG - =>VN ctest-fip_vn-96706071 has no policy to be verified 2026-03-26 03:51:09,084 - DEBUG - Verifying the vn in opserver 2026-03-26 03:51:09,085 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-fip_vn-96706071 virtual network link through opserver 10.0.0.240 2026-03-26 03:51:09,085 - DEBUG - Requesting: http://10.0.0.240:8081/analytics/uves/virtual-networks 2026-03-26 03:51:09,193 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-fip_vn-96706071', 'href': 'http://10.0.0.240:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-fip_vn-96706071?flat'} 2026-03-26 03:51:09,193 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-fip_vn-96706071 virtual network link through opserver 10.0.0.22 2026-03-26 03:51:09,193 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks 2026-03-26 03:51:09,207 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-fip_vn-96706071', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-fip_vn-96706071?flat'} 2026-03-26 03:51:09,207 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-fip_vn-96706071 virtual network link through opserver 10.0.0.65 2026-03-26 03:51:09,207 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks 2026-03-26 03:51:09,279 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-fip_vn-96706071', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-fip_vn-96706071?flat'} 2026-03-26 03:51:09,279 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-fip_vn-96706071 is found in opserver 2026-03-26 03:51:09,292 - DEBUG - VRF ids for VN ctest-fip_vn-96706071: {'10.0.0.38': '5'} 2026-03-26 03:51:09,293 - INFO - Waiting for VM ctest-fvn_vm1-17817684 to be up.. 2026-03-26 03:51:09,400 - DEBUG - VM is in ACTIVE state now 2026-03-26 03:51:09,400 - INFO - VM name : ctest-fvn_vm1-17817684 2026-03-26 03:51:09,488 - DEBUG - VM ctest-fvn_vm1-17817684 ID is 0873e170-e00b-4de7-b90a-534cfaae7169 2026-03-26 03:51:09,489 - DEBUG - VM ctest-fvn_vm1-17817684 launched on Node an-jenkins-deploy-platform-ansible-os-5545-1 2026-03-26 03:51:09,591 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/0873e170-e00b-4de7-b90a-534cfaae7169 2026-03-26 03:51:09,601 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine-interface/cf064ef1-5358-47b6-960d-61118c6e9b1a 2026-03-26 03:51:12,803 - DEBUG - (True, 'PING 169.254.0.6 (169.254.0.6) 56(84) bytes of data.\r\n\r\n--- 169.254.0.6 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1021ms') 2026-03-26 03:51:12,803 - DEBUG - Ping to Metadata IP 169.254.0.6 of VM ctest-fvn_vm1-17817684 failed! 2026-03-26 03:51:12,818 - DEBUG - Gateway for vn default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-fip_vn-96706071 is 162.76.121.1 and allocation pool is NOT set 2026-03-26 03:51:16,906 - DEBUG - (True, 'PING 169.254.0.6 (169.254.0.6) 56(84) bytes of data.\r\n\r\n--- 169.254.0.6 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1021ms') 2026-03-26 03:51:16,906 - DEBUG - Ping to Metadata IP 169.254.0.6 of VM ctest-fvn_vm1-17817684 failed! 2026-03-26 03:51:16,920 - DEBUG - Gateway for vn default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-fip_vn-96706071 is 162.76.121.1 and allocation pool is NOT set 2026-03-26 03:51:20,999 - DEBUG - (True, 'PING 169.254.0.6 (169.254.0.6) 56(84) bytes of data.\r\n\r\n--- 169.254.0.6 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1013ms') 2026-03-26 03:51:20,999 - DEBUG - Ping to Metadata IP 169.254.0.6 of VM ctest-fvn_vm1-17817684 failed! 2026-03-26 03:51:21,013 - DEBUG - Gateway for vn default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-fip_vn-96706071 is 162.76.121.1 and allocation pool is NOT set 2026-03-26 03:51:25,090 - DEBUG - (True, 'PING 169.254.0.6 (169.254.0.6) 56(84) bytes of data.\r\n\r\n--- 169.254.0.6 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1018ms') 2026-03-26 03:51:25,090 - DEBUG - Ping to Metadata IP 169.254.0.6 of VM ctest-fvn_vm1-17817684 failed! 2026-03-26 03:51:25,107 - DEBUG - Gateway for vn default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-fip_vn-96706071 is 162.76.121.1 and allocation pool is NOT set 2026-03-26 03:51:29,189 - DEBUG - (True, 'PING 169.254.0.6 (169.254.0.6) 56(84) bytes of data.\r\n64 bytes from 169.254.0.6: icmp_seq=2 ttl=63 time=4.62 ms\r\n\r\n--- 169.254.0.6 ping statistics ---\r\n2 packets transmitted, 1 received, 50% packet loss, time 1023ms\r\nrtt min/avg/max/mdev = 4.623/4.623/4.623/0.000 ms') 2026-03-26 03:51:29,190 - INFO - Ping to Metadata IP 169.254.0.6 of VM ctest-fvn_vm1-17817684 passed 2026-03-26 03:51:29,265 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-26 03:51:29,265 - DEBUG - Waiting to SSH to VM ctest-fvn_vm1-17817684, IP 162.76.121.3, Port 22 2026-03-26 03:51:29,333 - DEBUG - Error on ssh to cirros@169.254.0.6:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.6/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.6/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.6/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-03-26 03:51:29,443 - DEBUG - VM ctest-fvn_vm1-17817684 is NOT ready for SSH connections, VM status: ACTIVE 2026-03-26 03:51:34,444 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-26 03:51:34,444 - DEBUG - Waiting to SSH to VM ctest-fvn_vm1-17817684, IP 162.76.121.3, Port 22 2026-03-26 03:51:34,614 - DEBUG - VM ctest-fvn_vm1-17817684 is ready for SSH connections 2026-03-26 03:51:34,614 - INFO - Waiting for VM ctest-vn2_vm2-96321041 to be up.. 2026-03-26 03:51:34,706 - DEBUG - VM is in ACTIVE state now 2026-03-26 03:51:34,706 - INFO - VM name : ctest-vn2_vm2-96321041 2026-03-26 03:51:34,799 - DEBUG - VM ctest-vn2_vm2-96321041 ID is 57b29268-c434-4d37-b57b-a6d245709985 2026-03-26 03:51:34,800 - DEBUG - VM ctest-vn2_vm2-96321041 launched on Node an-jenkins-deploy-platform-ansible-os-5545-1 2026-03-26 03:51:34,903 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/57b29268-c434-4d37-b57b-a6d245709985 2026-03-26 03:51:34,919 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine-interface/d77ad2dc-65d9-470d-ae3c-2004bee7ec7d 2026-03-26 03:51:36,096 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n64 bytes from 169.254.0.5: icmp_seq=1 ttl=63 time=2.81 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=0.554 ms\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.554/1.680/2.807/1.126 ms') 2026-03-26 03:51:36,097 - INFO - Ping to Metadata IP 169.254.0.5 of VM ctest-vn2_vm2-96321041 passed 2026-03-26 03:51:36,175 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-26 03:51:36,175 - DEBUG - Waiting to SSH to VM ctest-vn2_vm2-96321041, IP 63.236.149.3, Port 22 2026-03-26 03:51:36,354 - DEBUG - VM ctest-vn2_vm2-96321041 is ready for SSH connections 2026-03-26 03:51:36,354 - INFO - Waiting for VM mltf_vm to be up.. 2026-03-26 03:51:36,445 - DEBUG - VM is in ACTIVE state now 2026-03-26 03:51:36,445 - INFO - VM name : mltf_vm 2026-03-26 03:51:36,557 - DEBUG - VM mltf_vm ID is aa5ca56b-13dd-46e5-bf18-4a57c83e7a69 2026-03-26 03:51:36,557 - DEBUG - VM mltf_vm launched on Node an-jenkins-deploy-platform-ansible-os-5545-2 2026-03-26 03:51:36,744 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/aa5ca56b-13dd-46e5-bf18-4a57c83e7a69 2026-03-26 03:51:36,757 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine-interface/11ddc53e-f637-4577-861d-e63414ea18cb 2026-03-26 03:51:36,771 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine-interface/f6f8c5f2-d246-4874-a668-f5b1b2738f32 2026-03-26 03:51:37,957 - DEBUG - (True, 'PING 169.254.0.7 (169.254.0.7) 56(84) bytes of data.\r\n64 bytes from 169.254.0.7: icmp_seq=1 ttl=63 time=2.51 ms\r\n64 bytes from 169.254.0.7: icmp_seq=2 ttl=63 time=0.427 ms\r\n\r\n--- 169.254.0.7 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.427/1.467/2.507/1.040 ms') 2026-03-26 03:51:37,957 - INFO - Ping to Metadata IP 169.254.0.7 of VM mltf_vm passed 2026-03-26 03:51:38,028 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-26 03:51:38,028 - DEBUG - Waiting to SSH to VM mltf_vm, IP 73.139.205.133, Port 22 2026-03-26 03:51:38,196 - DEBUG - VM mltf_vm is ready for SSH connections 2026-03-26 03:51:38,263 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-26 03:51:38,263 - DEBUG - Waiting to SSH to VM mltf_vm, IP 73.139.205.133, Port 22 2026-03-26 03:51:38,430 - DEBUG - VM mltf_vm is ready for SSH connections 2026-03-26 03:51:38,432 - INFO - ================================================================================ 2026-03-26 03:51:38,432 - INFO - STARTING TEST : test_verify_session_sampling_teardown 2026-03-26 03:51:38,432 - INFO - TEST DESCRIPTION : 1.query client session samples 2.query server session samples 3.query client session to get number of sessions exported 4.query session record table for teardown bytes/pkts 5.query sample count after teardown on server side 2026-03-26 03:51:39,686 - DEBUG - Skipping xmpp flap check 2026-03-26 03:51:39,686 - INFO - Initial checks done. Running the testcase now 2026-03-26 03:51:39,686 - INFO - 2026-03-26 03:51:39,823 - INFO - Setting flow export rate: 100 2026-03-26 03:51:39,876 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = any, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = -1, end_port = -1], application = [], dst_addresses = [subnet = None, virtual_network = any, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None] 2026-03-26 03:51:40,215 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': 'aef1774d-96ae-45ae-9f34-35d4ea182f71', 'name': 'ctest-vn1-06975742', 'tenant_id': '6750c5b755c0491aab646801c7b0a322', 'project_id': '6750c5b755c0491aab646801c7b0a322', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['93f15ef7-39f0-452f-9d0d-5cb2a37233fc'], 'fq_name': ['default-domain', 'ctest-AnalyticsTestSanityWithResource-43814714', 'ctest-vn1-06975742'], 'policys': [['default-domain', 'ctest-AnalyticsTestSanityWithResource-43814714', 'policy1']], 'subnet_ipam': [{'subnet_cidr': '73.139.205.128/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}} 2026-03-26 03:51:40,431 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '967ae200-ff4d-438e-9a34-da703d772557', 'name': 'ctest-vn2-04268916', 'tenant_id': '6750c5b755c0491aab646801c7b0a322', 'project_id': '6750c5b755c0491aab646801c7b0a322', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['98b66c46-a3a5-45eb-b9e1-25f51e4cb10a'], 'fq_name': ['default-domain', 'ctest-AnalyticsTestSanityWithResource-43814714', 'ctest-vn2-04268916'], 'policys': [['default-domain', 'ctest-AnalyticsTestSanityWithResource-43814714', 'policy1']], 'subnet_ipam': [{'subnet_cidr': '63.236.149.0/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}} 2026-03-26 03:51:40,522 - DEBUG - Requesting: http://10.0.0.240:8082/domains 2026-03-26 03:51:40,529 - DEBUG - Requesting: http://10.0.0.240:8082/domain/b07ef41d-f358-4169-a694-2c6c20158c87 2026-03-26 03:51:40,543 - DEBUG - Requesting: http://10.0.0.240:8082/project/6750c5b7-55c0-491a-ab64-6801c7b0a322 2026-03-26 03:51:40,670 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-network/aef1774d-96ae-45ae-9f34-35d4ea182f71 2026-03-26 03:51:40,719 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-network/aef1774d-96ae-45ae-9f34-35d4ea182f71 2026-03-26 03:51:40,730 - DEBUG - Requesting: http://10.0.0.240:8082/routing-instance/9c293f6a-7510-4e5a-b127-9b395a302887 2026-03-26 03:51:40,753 - DEBUG - Requesting: http://10.0.0.240:8082/routing-instance/9c293f6a-7510-4e5a-b127-9b395a302887 2026-03-26 03:51:40,765 - DEBUG - Requesting: http://10.0.0.240:8082/route-target/caa4c2b5-d1f7-4c88-9e33-4566d4c465b0 2026-03-26 03:51:40,775 - DEBUG - Route Targets: ['target:64512:8000007'] 2026-03-26 03:51:40,775 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-network/aef1774d-96ae-45ae-9f34-35d4ea182f71 2026-03-26 03:51:40,802 - DEBUG - Requesting: http://10.0.0.240:8082/routing-instance/9c293f6a-7510-4e5a-b127-9b395a302887 2026-03-26 03:51:40,822 - INFO - Verified VN network id 18 for VN aef1774d-96ae-45ae-9f34-35d4ea182f71 2026-03-26 03:51:40,822 - INFO - Verifications in API Server for VN ctest-vn1-06975742 passed 2026-03-26 03:51:40,823 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-network/aef1774d-96ae-45ae-9f34-35d4ea182f71 2026-03-26 03:51:40,851 - DEBUG - Requesting: http://10.0.0.240:8082/routing-instance/9c293f6a-7510-4e5a-b127-9b395a302887 2026-03-26 03:51:40,869 - DEBUG - Requesting: http://10.0.0.240:8082/routing-instance/9c293f6a-7510-4e5a-b127-9b395a302887 2026-03-26 03:51:40,909 - DEBUG - Requesting: http://10.0.0.240:8082/route-target/caa4c2b5-d1f7-4c88-9e33-4566d4c465b0 2026-03-26 03:51:40,926 - DEBUG - Control-node 10.0.0.240 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-43814714', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12605988007110919598', 'uuid-lslong': '11471853339396288369'}, 'enable': 'true', 'created': '2026-03-26T03:50:23', 'last-modified': '2026-03-26T03:51:40', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.740750'} 2026-03-26 03:51:40,939 - DEBUG - Route Targets: ['target:64512:8000007'] 2026-03-26 03:51:40,949 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-43814714', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12605988007110919598', 'uuid-lslong': '11471853339396288369'}, 'enable': 'true', 'created': '2026-03-26T03:50:23', 'last-modified': '2026-03-26T03:51:40', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.765587'} 2026-03-26 03:51:40,956 - DEBUG - Route Targets: ['target:64512:8000007'] 2026-03-26 03:51:40,963 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-43814714', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12605988007110919598', 'uuid-lslong': '11471853339396288369'}, 'enable': 'true', 'created': '2026-03-26T03:50:23', 'last-modified': '2026-03-26T03:51:40', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.775106'} 2026-03-26 03:51:40,971 - DEBUG - Route Targets: ['target:64512:8000007'] 2026-03-26 03:51:40,971 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn1-06975742 passed 2026-03-26 03:51:40,971 - DEBUG - ====Verifying policy data for ctest-vn1-06975742 in API_Server ====== 2026-03-26 03:51:40,971 - DEBUG - Requesting: http://10.0.0.240:8082/domains 2026-03-26 03:51:40,985 - DEBUG - Requesting: http://10.0.0.240:8082/domain/b07ef41d-f358-4169-a694-2c6c20158c87 2026-03-26 03:51:40,997 - DEBUG - Requesting: http://10.0.0.240:8082/project/6750c5b7-55c0-491a-ab64-6801c7b0a322 2026-03-26 03:51:41,089 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-network/aef1774d-96ae-45ae-9f34-35d4ea182f71 2026-03-26 03:51:41,100 - DEBUG - ==>Verifying data for policy with id: 24990294-9da8-4a17-8c92-ebb9daf4d09e, fqn: ['default-domain', 'ctest-AnalyticsTestSanityWithResource-43814714', 'policy1'] 2026-03-26 03:51:41,100 - INFO - VN ctest-vn1-06975742 Policy verification: verify_vn_policy_in_api_server, status: True 2026-03-26 03:51:41,100 - DEBUG - Verifying the vn in opserver 2026-03-26 03:51:41,100 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742 virtual network link through opserver 10.0.0.240 2026-03-26 03:51:41,100 - DEBUG - Requesting: http://10.0.0.240:8081/analytics/uves/virtual-networks 2026-03-26 03:51:41,124 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742', 'href': 'http://10.0.0.240:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742?flat'} 2026-03-26 03:51:41,124 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742 virtual network link through opserver 10.0.0.22 2026-03-26 03:51:41,124 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks 2026-03-26 03:51:41,139 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742?flat'} 2026-03-26 03:51:41,140 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742 virtual network link through opserver 10.0.0.65 2026-03-26 03:51:41,140 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks 2026-03-26 03:51:41,154 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742?flat'} 2026-03-26 03:51:41,154 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742 is found in opserver 2026-03-26 03:51:41,168 - DEBUG - VRF ids for VN ctest-vn1-06975742: {'10.0.0.23': '4'} 2026-03-26 03:51:41,169 - DEBUG - Requesting: http://10.0.0.240:8082/domains 2026-03-26 03:51:41,178 - DEBUG - Requesting: http://10.0.0.240:8082/domain/b07ef41d-f358-4169-a694-2c6c20158c87 2026-03-26 03:51:41,197 - DEBUG - Requesting: http://10.0.0.240:8082/project/6750c5b7-55c0-491a-ab64-6801c7b0a322 2026-03-26 03:51:41,319 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-network/967ae200-ff4d-438e-9a34-da703d772557 2026-03-26 03:51:41,338 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-network/967ae200-ff4d-438e-9a34-da703d772557 2026-03-26 03:51:41,355 - DEBUG - Requesting: http://10.0.0.240:8082/routing-instance/728f4a39-0d37-4260-a03c-c4585266a0e8 2026-03-26 03:51:41,371 - DEBUG - Requesting: http://10.0.0.240:8082/routing-instance/728f4a39-0d37-4260-a03c-c4585266a0e8 2026-03-26 03:51:41,382 - DEBUG - Requesting: http://10.0.0.240:8082/route-target/eff36213-bd83-44cd-b5de-f0e1517ca2a9 2026-03-26 03:51:41,391 - DEBUG - Route Targets: ['target:64512:8000008'] 2026-03-26 03:51:41,391 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-network/967ae200-ff4d-438e-9a34-da703d772557 2026-03-26 03:51:41,403 - DEBUG - Requesting: http://10.0.0.240:8082/routing-instance/728f4a39-0d37-4260-a03c-c4585266a0e8 2026-03-26 03:51:41,422 - INFO - Verified VN network id 19 for VN 967ae200-ff4d-438e-9a34-da703d772557 2026-03-26 03:51:41,422 - INFO - Verifications in API Server for VN ctest-vn2-04268916 passed 2026-03-26 03:51:41,423 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-network/967ae200-ff4d-438e-9a34-da703d772557 2026-03-26 03:51:41,437 - DEBUG - Requesting: http://10.0.0.240:8082/routing-instance/728f4a39-0d37-4260-a03c-c4585266a0e8 2026-03-26 03:51:41,448 - DEBUG - Requesting: http://10.0.0.240:8082/routing-instance/728f4a39-0d37-4260-a03c-c4585266a0e8 2026-03-26 03:51:41,459 - DEBUG - Requesting: http://10.0.0.240:8082/route-target/eff36213-bd83-44cd-b5de-f0e1517ca2a9 2026-03-26 03:51:41,475 - DEBUG - Control-node 10.0.0.240 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn2-04268916', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-43814714', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10843227546759021454', 'uuid-lslong': '11111746356232267095'}, 'enable': 'true', 'created': '2026-03-26T03:51:00', 'last-modified': '2026-03-26T03:51:40', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.098098'} 2026-03-26 03:51:41,484 - DEBUG - Route Targets: ['target:64512:8000008'] 2026-03-26 03:51:41,494 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn2-04268916', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-43814714', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10843227546759021454', 'uuid-lslong': '11111746356232267095'}, 'enable': 'true', 'created': '2026-03-26T03:51:00', 'last-modified': '2026-03-26T03:51:40', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.111961'} 2026-03-26 03:51:41,504 - DEBUG - Route Targets: ['target:64512:8000008'] 2026-03-26 03:51:41,512 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn2-04268916', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-43814714', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10843227546759021454', 'uuid-lslong': '11111746356232267095'}, 'enable': 'true', 'created': '2026-03-26T03:51:00', 'last-modified': '2026-03-26T03:51:40', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.133252'} 2026-03-26 03:51:41,519 - DEBUG - Route Targets: ['target:64512:8000008'] 2026-03-26 03:51:41,519 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn2-04268916 passed 2026-03-26 03:51:41,520 - DEBUG - ====Verifying policy data for ctest-vn2-04268916 in API_Server ====== 2026-03-26 03:51:41,520 - DEBUG - Requesting: http://10.0.0.240:8082/domains 2026-03-26 03:51:41,526 - DEBUG - Requesting: http://10.0.0.240:8082/domain/b07ef41d-f358-4169-a694-2c6c20158c87 2026-03-26 03:51:41,545 - DEBUG - Requesting: http://10.0.0.240:8082/project/6750c5b7-55c0-491a-ab64-6801c7b0a322 2026-03-26 03:51:41,663 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-network/967ae200-ff4d-438e-9a34-da703d772557 2026-03-26 03:51:41,675 - DEBUG - ==>Verifying data for policy with id: 24990294-9da8-4a17-8c92-ebb9daf4d09e, fqn: ['default-domain', 'ctest-AnalyticsTestSanityWithResource-43814714', 'policy1'] 2026-03-26 03:51:41,675 - INFO - VN ctest-vn2-04268916 Policy verification: verify_vn_policy_in_api_server, status: True 2026-03-26 03:51:41,675 - DEBUG - Verifying the vn in opserver 2026-03-26 03:51:41,676 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn2-04268916 virtual network link through opserver 10.0.0.240 2026-03-26 03:51:41,676 - DEBUG - Requesting: http://10.0.0.240:8081/analytics/uves/virtual-networks 2026-03-26 03:51:41,692 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn2-04268916', 'href': 'http://10.0.0.240:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn2-04268916?flat'} 2026-03-26 03:51:41,692 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn2-04268916 virtual network link through opserver 10.0.0.22 2026-03-26 03:51:41,692 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks 2026-03-26 03:51:41,711 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn2-04268916', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn2-04268916?flat'} 2026-03-26 03:51:41,711 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn2-04268916 virtual network link through opserver 10.0.0.65 2026-03-26 03:51:41,711 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks 2026-03-26 03:51:41,728 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn2-04268916', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn2-04268916?flat'} 2026-03-26 03:51:41,728 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn2-04268916 is found in opserver 2026-03-26 03:51:41,739 - DEBUG - VRF ids for VN ctest-vn2-04268916: {'10.0.0.38': '4', '10.0.0.23': '5'} 2026-03-26 03:51:41,740 - DEBUG - Requesting: http://10.0.0.240:8082/domains 2026-03-26 03:51:41,746 - DEBUG - Requesting: http://10.0.0.240:8082/domain/b07ef41d-f358-4169-a694-2c6c20158c87 2026-03-26 03:51:41,761 - DEBUG - Requesting: http://10.0.0.240:8082/project/6750c5b7-55c0-491a-ab64-6801c7b0a322 2026-03-26 03:51:41,860 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-network/aef1774d-96ae-45ae-9f34-35d4ea182f71 2026-03-26 03:51:41,872 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-network/aef1774d-96ae-45ae-9f34-35d4ea182f71 2026-03-26 03:51:41,883 - DEBUG - Requesting: http://10.0.0.240:8082/routing-instance/9c293f6a-7510-4e5a-b127-9b395a302887 2026-03-26 03:51:41,891 - DEBUG - Requesting: http://10.0.0.240:8082/routing-instance/9c293f6a-7510-4e5a-b127-9b395a302887 2026-03-26 03:51:41,901 - DEBUG - Requesting: http://10.0.0.240:8082/route-target/caa4c2b5-d1f7-4c88-9e33-4566d4c465b0 2026-03-26 03:51:41,915 - DEBUG - Route Targets: ['target:64512:8000007'] 2026-03-26 03:51:41,915 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-network/aef1774d-96ae-45ae-9f34-35d4ea182f71 2026-03-26 03:51:41,926 - DEBUG - Requesting: http://10.0.0.240:8082/routing-instance/9c293f6a-7510-4e5a-b127-9b395a302887 2026-03-26 03:51:41,948 - INFO - Verified VN network id 18 for VN aef1774d-96ae-45ae-9f34-35d4ea182f71 2026-03-26 03:51:41,948 - INFO - Verifications in API Server for VN ctest-vn1-06975742 passed 2026-03-26 03:51:41,948 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-network/aef1774d-96ae-45ae-9f34-35d4ea182f71 2026-03-26 03:51:41,962 - DEBUG - Requesting: http://10.0.0.240:8082/routing-instance/9c293f6a-7510-4e5a-b127-9b395a302887 2026-03-26 03:51:41,975 - DEBUG - Requesting: http://10.0.0.240:8082/routing-instance/9c293f6a-7510-4e5a-b127-9b395a302887 2026-03-26 03:51:41,985 - DEBUG - Requesting: http://10.0.0.240:8082/route-target/caa4c2b5-d1f7-4c88-9e33-4566d4c465b0 2026-03-26 03:51:42,001 - DEBUG - Control-node 10.0.0.240 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-43814714', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12605988007110919598', 'uuid-lslong': '11471853339396288369'}, 'enable': 'true', 'created': '2026-03-26T03:50:23', 'last-modified': '2026-03-26T03:51:40', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.814362'} 2026-03-26 03:51:42,012 - DEBUG - Route Targets: ['target:64512:8000007'] 2026-03-26 03:51:42,019 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-43814714', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12605988007110919598', 'uuid-lslong': '11471853339396288369'}, 'enable': 'true', 'created': '2026-03-26T03:50:23', 'last-modified': '2026-03-26T03:51:40', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.836120'} 2026-03-26 03:51:42,024 - DEBUG - Route Targets: ['target:64512:8000007'] 2026-03-26 03:51:42,031 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-43814714', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12605988007110919598', 'uuid-lslong': '11471853339396288369'}, 'enable': 'true', 'created': '2026-03-26T03:50:23', 'last-modified': '2026-03-26T03:51:40', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.842041'} 2026-03-26 03:51:42,038 - DEBUG - Route Targets: ['target:64512:8000007'] 2026-03-26 03:51:42,038 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn1-06975742 passed 2026-03-26 03:51:42,038 - DEBUG - ====Verifying policy data for ctest-vn1-06975742 in API_Server ====== 2026-03-26 03:51:42,038 - DEBUG - Requesting: http://10.0.0.240:8082/domains 2026-03-26 03:51:42,044 - DEBUG - Requesting: http://10.0.0.240:8082/domain/b07ef41d-f358-4169-a694-2c6c20158c87 2026-03-26 03:51:42,057 - DEBUG - Requesting: http://10.0.0.240:8082/project/6750c5b7-55c0-491a-ab64-6801c7b0a322 2026-03-26 03:51:42,151 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-network/aef1774d-96ae-45ae-9f34-35d4ea182f71 2026-03-26 03:51:42,165 - DEBUG - ==>Verifying data for policy with id: 24990294-9da8-4a17-8c92-ebb9daf4d09e, fqn: ['default-domain', 'ctest-AnalyticsTestSanityWithResource-43814714', 'policy1'] 2026-03-26 03:51:42,165 - INFO - VN ctest-vn1-06975742 Policy verification: verify_vn_policy_in_api_server, status: True 2026-03-26 03:51:42,166 - DEBUG - Verifying the vn in opserver 2026-03-26 03:51:42,166 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742 virtual network link through opserver 10.0.0.240 2026-03-26 03:51:42,166 - DEBUG - Requesting: http://10.0.0.240:8081/analytics/uves/virtual-networks 2026-03-26 03:51:42,183 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742', 'href': 'http://10.0.0.240:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742?flat'} 2026-03-26 03:51:42,183 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742 virtual network link through opserver 10.0.0.22 2026-03-26 03:51:42,183 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks 2026-03-26 03:51:42,197 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742?flat'} 2026-03-26 03:51:42,197 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742 virtual network link through opserver 10.0.0.65 2026-03-26 03:51:42,197 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks 2026-03-26 03:51:42,209 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742?flat'} 2026-03-26 03:51:42,209 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742 is found in opserver 2026-03-26 03:51:42,220 - DEBUG - VRF ids for VN ctest-vn1-06975742: {'10.0.0.23': '4'} 2026-03-26 03:51:42,220 - DEBUG - Requesting: http://10.0.0.240:8082/domains 2026-03-26 03:51:42,227 - DEBUG - Requesting: http://10.0.0.240:8082/domain/b07ef41d-f358-4169-a694-2c6c20158c87 2026-03-26 03:51:42,239 - DEBUG - Requesting: http://10.0.0.240:8082/project/6750c5b7-55c0-491a-ab64-6801c7b0a322 2026-03-26 03:51:42,334 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-network/967ae200-ff4d-438e-9a34-da703d772557 2026-03-26 03:51:42,345 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-network/967ae200-ff4d-438e-9a34-da703d772557 2026-03-26 03:51:42,356 - DEBUG - Requesting: http://10.0.0.240:8082/routing-instance/728f4a39-0d37-4260-a03c-c4585266a0e8 2026-03-26 03:51:42,365 - DEBUG - Requesting: http://10.0.0.240:8082/routing-instance/728f4a39-0d37-4260-a03c-c4585266a0e8 2026-03-26 03:51:42,373 - DEBUG - Requesting: http://10.0.0.240:8082/route-target/eff36213-bd83-44cd-b5de-f0e1517ca2a9 2026-03-26 03:51:42,381 - DEBUG - Route Targets: ['target:64512:8000008'] 2026-03-26 03:51:42,381 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-network/967ae200-ff4d-438e-9a34-da703d772557 2026-03-26 03:51:42,392 - DEBUG - Requesting: http://10.0.0.240:8082/routing-instance/728f4a39-0d37-4260-a03c-c4585266a0e8 2026-03-26 03:51:42,408 - INFO - Verified VN network id 19 for VN 967ae200-ff4d-438e-9a34-da703d772557 2026-03-26 03:51:42,409 - INFO - Verifications in API Server for VN ctest-vn2-04268916 passed 2026-03-26 03:51:42,409 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-network/967ae200-ff4d-438e-9a34-da703d772557 2026-03-26 03:51:42,431 - DEBUG - Requesting: http://10.0.0.240:8082/routing-instance/728f4a39-0d37-4260-a03c-c4585266a0e8 2026-03-26 03:51:42,439 - DEBUG - Requesting: http://10.0.0.240:8082/routing-instance/728f4a39-0d37-4260-a03c-c4585266a0e8 2026-03-26 03:51:42,448 - DEBUG - Requesting: http://10.0.0.240:8082/route-target/eff36213-bd83-44cd-b5de-f0e1517ca2a9 2026-03-26 03:51:42,463 - DEBUG - Control-node 10.0.0.240 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn2-04268916', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-43814714', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10843227546759021454', 'uuid-lslong': '11111746356232267095'}, 'enable': 'true', 'created': '2026-03-26T03:51:00', 'last-modified': '2026-03-26T03:51:40', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.085949'} 2026-03-26 03:51:42,473 - DEBUG - Route Targets: ['target:64512:8000008'] 2026-03-26 03:51:42,481 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn2-04268916', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-43814714', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10843227546759021454', 'uuid-lslong': '11111746356232267095'}, 'enable': 'true', 'created': '2026-03-26T03:51:00', 'last-modified': '2026-03-26T03:51:40', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.101141'} 2026-03-26 03:51:42,491 - DEBUG - Route Targets: ['target:64512:8000008'] 2026-03-26 03:51:42,497 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn2-04268916', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-43814714', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10843227546759021454', 'uuid-lslong': '11111746356232267095'}, 'enable': 'true', 'created': '2026-03-26T03:51:00', 'last-modified': '2026-03-26T03:51:40', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.119057'} 2026-03-26 03:51:42,504 - DEBUG - Route Targets: ['target:64512:8000008'] 2026-03-26 03:51:42,504 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn2-04268916 passed 2026-03-26 03:51:42,504 - DEBUG - ====Verifying policy data for ctest-vn2-04268916 in API_Server ====== 2026-03-26 03:51:42,505 - DEBUG - Requesting: http://10.0.0.240:8082/domains 2026-03-26 03:51:42,512 - DEBUG - Requesting: http://10.0.0.240:8082/domain/b07ef41d-f358-4169-a694-2c6c20158c87 2026-03-26 03:51:42,526 - DEBUG - Requesting: http://10.0.0.240:8082/project/6750c5b7-55c0-491a-ab64-6801c7b0a322 2026-03-26 03:51:42,629 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-network/967ae200-ff4d-438e-9a34-da703d772557 2026-03-26 03:51:42,644 - DEBUG - ==>Verifying data for policy with id: 24990294-9da8-4a17-8c92-ebb9daf4d09e, fqn: ['default-domain', 'ctest-AnalyticsTestSanityWithResource-43814714', 'policy1'] 2026-03-26 03:51:42,644 - INFO - VN ctest-vn2-04268916 Policy verification: verify_vn_policy_in_api_server, status: True 2026-03-26 03:51:42,644 - DEBUG - Verifying the vn in opserver 2026-03-26 03:51:42,644 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn2-04268916 virtual network link through opserver 10.0.0.240 2026-03-26 03:51:42,644 - DEBUG - Requesting: http://10.0.0.240:8081/analytics/uves/virtual-networks 2026-03-26 03:51:42,661 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn2-04268916', 'href': 'http://10.0.0.240:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn2-04268916?flat'} 2026-03-26 03:51:42,662 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn2-04268916 virtual network link through opserver 10.0.0.22 2026-03-26 03:51:42,662 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks 2026-03-26 03:51:42,676 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn2-04268916', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn2-04268916?flat'} 2026-03-26 03:51:42,676 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn2-04268916 virtual network link through opserver 10.0.0.65 2026-03-26 03:51:42,676 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks 2026-03-26 03:51:42,688 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn2-04268916', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn2-04268916?flat'} 2026-03-26 03:51:42,688 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn2-04268916 is found in opserver 2026-03-26 03:51:42,700 - DEBUG - VRF ids for VN ctest-vn2-04268916: {'10.0.0.38': '4', '10.0.0.23': '5'} 2026-03-26 03:51:42,700 - DEBUG - Requesting: http://10.0.0.240:8082/domains 2026-03-26 03:51:42,706 - DEBUG - Requesting: http://10.0.0.240:8082/domain/b07ef41d-f358-4169-a694-2c6c20158c87 2026-03-26 03:51:42,721 - DEBUG - Requesting: http://10.0.0.240:8082/project/6750c5b7-55c0-491a-ab64-6801c7b0a322 2026-03-26 03:51:42,842 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-network/8e66bfe9-84c5-45a3-a4a3-6f0187c17f1c 2026-03-26 03:51:42,854 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-network/8e66bfe9-84c5-45a3-a4a3-6f0187c17f1c 2026-03-26 03:51:42,869 - DEBUG - Requesting: http://10.0.0.240:8082/routing-instance/21d2c5e3-674e-4818-bd71-7f3a9d3a59a9 2026-03-26 03:51:42,878 - DEBUG - Requesting: http://10.0.0.240:8082/routing-instance/21d2c5e3-674e-4818-bd71-7f3a9d3a59a9 2026-03-26 03:51:42,887 - DEBUG - Requesting: http://10.0.0.240:8082/route-target/3e7d6696-58da-427e-a944-92d9aca0e4c5 2026-03-26 03:51:42,897 - DEBUG - Route Targets: ['target:64512:8000009'] 2026-03-26 03:51:42,897 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-network/8e66bfe9-84c5-45a3-a4a3-6f0187c17f1c 2026-03-26 03:51:42,908 - DEBUG - Requesting: http://10.0.0.240:8082/routing-instance/21d2c5e3-674e-4818-bd71-7f3a9d3a59a9 2026-03-26 03:51:42,929 - INFO - Verified VN network id 20 for VN 8e66bfe9-84c5-45a3-a4a3-6f0187c17f1c 2026-03-26 03:51:42,929 - INFO - Verifications in API Server for VN ctest-fip_vn-96706071 passed 2026-03-26 03:51:42,929 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-network/8e66bfe9-84c5-45a3-a4a3-6f0187c17f1c 2026-03-26 03:51:42,945 - DEBUG - Requesting: http://10.0.0.240:8082/routing-instance/21d2c5e3-674e-4818-bd71-7f3a9d3a59a9 2026-03-26 03:51:42,958 - DEBUG - Requesting: http://10.0.0.240:8082/routing-instance/21d2c5e3-674e-4818-bd71-7f3a9d3a59a9 2026-03-26 03:51:42,965 - DEBUG - Requesting: http://10.0.0.240:8082/route-target/3e7d6696-58da-427e-a944-92d9aca0e4c5 2026-03-26 03:51:42,984 - DEBUG - Control-node 10.0.0.240 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-fip_vn-96706071', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-43814714', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10261099810686059939', 'uuid-lslong': '11863447895787274012'}, 'enable': 'true', 'created': '2026-03-26T03:51:01', 'last-modified': '2026-03-26T03:51:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:41.598488'} 2026-03-26 03:51:42,998 - DEBUG - Route Targets: ['target:64512:8000009'] 2026-03-26 03:51:43,003 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-fip_vn-96706071', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-43814714', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10261099810686059939', 'uuid-lslong': '11863447895787274012'}, 'enable': 'true', 'created': '2026-03-26T03:51:01', 'last-modified': '2026-03-26T03:51:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:41.621425'} 2026-03-26 03:51:43,009 - DEBUG - Route Targets: ['target:64512:8000009'] 2026-03-26 03:51:43,020 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-fip_vn-96706071', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-43814714', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10261099810686059939', 'uuid-lslong': '11863447895787274012'}, 'enable': 'true', 'created': '2026-03-26T03:51:01', 'last-modified': '2026-03-26T03:51:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:41.634838'} 2026-03-26 03:51:43,028 - DEBUG - Route Targets: ['target:64512:8000009'] 2026-03-26 03:51:43,028 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-fip_vn-96706071 passed 2026-03-26 03:51:43,028 - DEBUG - ====Verifying policy data for ctest-fip_vn-96706071 in API_Server ====== 2026-03-26 03:51:43,028 - DEBUG - Requesting: http://10.0.0.240:8082/domains 2026-03-26 03:51:43,034 - DEBUG - Requesting: http://10.0.0.240:8082/domain/b07ef41d-f358-4169-a694-2c6c20158c87 2026-03-26 03:51:43,049 - DEBUG - Requesting: http://10.0.0.240:8082/project/6750c5b7-55c0-491a-ab64-6801c7b0a322 2026-03-26 03:51:43,159 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-network/8e66bfe9-84c5-45a3-a4a3-6f0187c17f1c 2026-03-26 03:51:43,179 - DEBUG - =>VN ctest-fip_vn-96706071 has no policy to be verified 2026-03-26 03:51:43,179 - DEBUG - Verifying the vn in opserver 2026-03-26 03:51:43,179 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-fip_vn-96706071 virtual network link through opserver 10.0.0.240 2026-03-26 03:51:43,179 - DEBUG - Requesting: http://10.0.0.240:8081/analytics/uves/virtual-networks 2026-03-26 03:51:43,192 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-fip_vn-96706071', 'href': 'http://10.0.0.240:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-fip_vn-96706071?flat'} 2026-03-26 03:51:43,192 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-fip_vn-96706071 virtual network link through opserver 10.0.0.22 2026-03-26 03:51:43,192 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks 2026-03-26 03:51:43,207 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-fip_vn-96706071', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-fip_vn-96706071?flat'} 2026-03-26 03:51:43,207 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-fip_vn-96706071 virtual network link through opserver 10.0.0.65 2026-03-26 03:51:43,207 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks 2026-03-26 03:51:43,221 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-fip_vn-96706071', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-fip_vn-96706071?flat'} 2026-03-26 03:51:43,221 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-fip_vn-96706071 is found in opserver 2026-03-26 03:51:43,232 - DEBUG - VRF ids for VN ctest-fip_vn-96706071: {'10.0.0.38': '5'} 2026-03-26 03:51:43,232 - INFO - start time= 1774497103232838 2026-03-26 03:51:43,233 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 63.236.149.3, host_string: cirros@169.254.0.5, password: gocubsgogateway: ubuntu@10.0.0.23, gateway password: c0ntrail123 2026-03-26 03:51:43,233 - DEBUG - ping -s 56 -c 3 -W 1 63.236.149.3 2026-03-26 03:51:46,912 - DEBUG - PING 63.236.149.3 (63.236.149.3): 56 data bytes 64 bytes from 63.236.149.3: seq=0 ttl=63 time=4.452 ms 64 bytes from 63.236.149.3: seq=1 ttl=63 time=1.533 ms 64 bytes from 63.236.149.3: seq=2 ttl=63 time=1.164 ms --- 63.236.149.3 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.164/2.383/4.452 ms 2026-03-26 03:51:46,913 - INFO - Ping to IP 63.236.149.3 from VM ctest-vn1_vm1-82499764 passed 2026-03-26 03:51:56,913 - INFO - Verify session samples and teardown pkts 2026-03-26 03:51:56,914 - INFO - Verifying SessionSeriesTable through opserver 10.0.0.240 2026-03-26 03:51:56,917 - DEBUG - Posting: http://10.0.0.240:8081/analytics/query, payload {'table': 'SessionSeriesTable', 'start_time': 1774497103232838, 'end_time': 1774497116917852, 'select_fields': ['T'], 'where': [[{'name': 'vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742', 'op': 1, 'value2': None}, {'name': 'remote_vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn2-04268916', 'op': 1, 'value2': None}, {'name': 'protocol', 'value': '1', 'op': 1, 'value2': None}]], 'session_type': 'client'} 2026-03-26 03:51:57,943 - DEBUG - [{'T': 1774497105678092}, {'T': 1774497106678159}, {'T': 1774497107678211}] 2026-03-26 03:51:57,943 - DEBUG - Posting: http://10.0.0.240:8081/analytics/query, payload {'table': 'SessionSeriesTable', 'start_time': 1774497103232838, 'end_time': 1774497117943756, 'select_fields': ['T'], 'where': [[{'name': 'vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn2-04268916', 'op': 1, 'value2': None}, {'name': 'remote_vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742', 'op': 1, 'value2': None}, {'name': 'protocol', 'value': '1', 'op': 1, 'value2': None}]], 'session_type': 'server'} 2026-03-26 03:51:58,966 - DEBUG - [{'T': 1774497105705275}, {'T': 1774497106705343}, {'T': 1774497107705419}] 2026-03-26 03:51:58,967 - DEBUG - Posting: http://10.0.0.240:8081/analytics/query, payload {'table': 'SessionSeriesTable', 'start_time': 1774497103232838, 'end_time': 1774497118967058, 'select_fields': ['vn', 'remote_vn', 'sample_count'], 'where': [[{'name': 'vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742', 'op': 1, 'value2': None}, {'name': 'remote_vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn2-04268916', 'op': 1, 'value2': None}, {'name': 'protocol', 'value': '1', 'op': 1, 'value2': None}]], 'session_type': 'client'} 2026-03-26 03:51:59,992 - DEBUG - [{'remote_vn': 'default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn2-04268916', 'vn': 'default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742', 'sample_count': 3}] 2026-03-26 03:51:59,992 - INFO - wait for the flows to get expire 2026-03-26 03:55:19,993 - DEBUG - Requesting: http://10.0.0.240:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5545-2:Compute:contrail-vrouter-agent:0?flat 2026-03-26 03:55:20,033 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5545-2?flat 2026-03-26 03:55:20,429 - INFO - Deleted policy policy1 2026-03-26 03:55:20,547 - INFO - Setting flow export rate: None 2026-03-26 03:55:21,835 - ERROR - AssertionError Python 3.9.25: /usr/bin/python3 Thu Mar 26 03:55:20 2026 A problem occurred in a Python script. Here is the sequence of function calls leading up to the error, in the order they occurred. /contrail-test/tcutils/wrappers.py in wrapper(self=, *args=(), **kwargs={}) 78 log.info('Initial checks done. Running the testcase now') 79 log.info('') 80 result = function(self, *args, **kwargs) 81 if self.inputs.upgrade: 82 pid = os.getpid() result = None function = self = args = () kwargs = {} /contrail-test/serial_scripts/analytics/test_analytics_resource.py in test_verify_session_sampling_teardown(self=) 1281 src_vn = self.res.vn1_fixture.vn_fq_name 1282 dst_vn = self.res.vn2_fixture.vn_fq_name 1283 result = self.verify_session_sampling_teardown(start_time, src_vn, dst_vn) 1284 1285 assert result,'Failed to get expected number of samples' result = True self = self.verify_session_sampling_teardown = > start_time = '1774497103232838' src_vn = 'default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742' dst_vn = 'default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn2-04268916' /contrail-test/serial_scripts/analytics/base.py in verify_session_sampling_teardown(self=, start_time='1774497103232838', src_vn='default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742', dst_vn='default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn2-04268916') 486 flow_record = self.analytics_obj.get_flows_vrouter_uve( 487 vrouter=vm_host) 488 assert not flow_record,'flows not got deleted even after 240 sec' 489 490 res = self.analytics_obj.ops_inspect[ip].post_query( flow_record = 12 AssertionError: flows not got deleted even after 240 sec __cause__ = None __class__ = __context__ = None __delattr__ = __dict__ = {} __dir__ = __doc__ = 'Assertion failed.' __eq__ = __format__ = __ge__ = __getattribute__ = __gt__ = __hash__ = __init__ = __init_subclass__ = __le__ = __lt__ = __ne__ = __new__ = __reduce__ = __reduce_ex__ = __repr__ = __setattr__ = __setstate__ = __sizeof__ = __str__ = __subclasshook__ = __suppress_context__ = False __traceback__ = args = ('flows not got deleted even after 240 sec',) with_traceback = The above is a description of an error in a Python program. Here is the original traceback: Traceback (most recent call last): File "/contrail-test/tcutils/wrappers.py", line 80, in wrapper result = function(self, *args, **kwargs) File "/contrail-test/serial_scripts/analytics/test_analytics_resource.py", line 1283, in test_verify_session_sampling_teardown result = self.verify_session_sampling_teardown(start_time, src_vn, dst_vn) File "/contrail-test/serial_scripts/analytics/base.py", line 488, in verify_session_sampling_teardown assert not flow_record,'flows not got deleted even after 240 sec' AssertionError: flows not got deleted even after 240 sec 2026-03-26 03:55:21,835 - DEBUG - Skipping xmpp flap check 2026-03-26 03:55:21,835 - INFO -

2026-03-26 03:55:21,835 - INFO - END TEST : test_verify_session_sampling_teardown : FAILED[0:03:43]

2026-03-26 03:55:21,835 - INFO - -------------------------------------------------------------------------------- 2026-03-26 03:55:21,837 - INFO - Deleting VM mltf_vm 2026-03-26 03:55:21,914 - INFO - Deleting VM ctest-fvn_vm1-17817684 2026-03-26 03:55:22,029 - INFO - Deleting VM ctest-vn2_vm2-96321041 2026-03-26 03:55:22,097 - INFO - Deleting VN ctest-fip_vn-96706071 2026-03-26 03:55:22,137 - DEBUG - VN 8e66bfe9-84c5-45a3-a4a3-6f0187c17f1c still in use: Unable to complete operation on network 8e66bfe9-84c5-45a3-a4a3-6f0187c17f1c. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-66e9dd65-7e71-4b14-9f37-4746d8aa974c'] 2026-03-26 03:55:22,138 - WARNING - Deleting VN ctest-fip_vn-96706071 failed..Will retry 2026-03-26 03:55:24,351 - DEBUG - Response for deleting network () 2026-03-26 03:55:24,351 - DEBUG - Requesting: http://10.0.0.240:8082/routing-instance/21d2c5e3-674e-4818-bd71-7f3a9d3a59a9 2026-03-26 03:55:24,359 - DEBUG - Response Code: 404 2026-03-26 03:55:24,359 - DEBUG - Requesting: http://10.0.0.240:8082/domains 2026-03-26 03:55:24,367 - DEBUG - Requesting: http://10.0.0.240:8082/domain/b07ef41d-f358-4169-a694-2c6c20158c87 2026-03-26 03:55:24,425 - DEBUG - Requesting: http://10.0.0.240:8082/project/6750c5b7-55c0-491a-ab64-6801c7b0a322 2026-03-26 03:55:24,530 - INFO - Validated that VN ctest-fip_vn-96706071 is not found in API Server 2026-03-26 03:55:24,543 - DEBUG - VN ctest-fip_vn-96706071 is not present in Agent 10.0.0.38 2026-03-26 03:55:24,555 - DEBUG - VN ctest-fip_vn-96706071 is not present in Agent 10.0.0.23 2026-03-26 03:55:24,555 - INFO - Validated that VN ctest-fip_vn-96706071 is not in any agent 2026-03-26 03:55:24,561 - DEBUG - VRF 5 is not seen in agent 10.0.0.38 2026-03-26 03:55:24,567 - DEBUG - Vrouter 10.0.0.38 does not have vrf 5 for VN ctest-fip_vn-96706071 2026-03-26 03:55:24,567 - INFO - Validated that all vrouters do not have the route table for VN default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-fip_vn-96706071 2026-03-26 03:55:24,589 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-fip_vn-96706071 info 2026-03-26 03:55:24,589 - INFO - Deleting VN ctest-vn2-04268916 2026-03-26 03:55:24,752 - DEBUG - Response for deleting network () 2026-03-26 03:55:24,752 - DEBUG - Requesting: http://10.0.0.240:8082/routing-instance/728f4a39-0d37-4260-a03c-c4585266a0e8 2026-03-26 03:55:24,766 - DEBUG - Response Code: 404 2026-03-26 03:55:24,767 - DEBUG - Requesting: http://10.0.0.240:8082/domains 2026-03-26 03:55:24,780 - DEBUG - Requesting: http://10.0.0.240:8082/domain/b07ef41d-f358-4169-a694-2c6c20158c87 2026-03-26 03:55:24,810 - DEBUG - Requesting: http://10.0.0.240:8082/project/6750c5b7-55c0-491a-ab64-6801c7b0a322 2026-03-26 03:55:24,917 - INFO - Validated that VN ctest-vn2-04268916 is not found in API Server 2026-03-26 03:55:24,928 - DEBUG - VN ctest-vn2-04268916 is not present in Agent 10.0.0.38 2026-03-26 03:55:24,937 - DEBUG - VN ctest-vn2-04268916 is not present in Agent 10.0.0.23 2026-03-26 03:55:24,937 - INFO - Validated that VN ctest-vn2-04268916 is not in any agent 2026-03-26 03:55:24,944 - DEBUG - VRF 4 is not seen in agent 10.0.0.38 2026-03-26 03:55:24,949 - DEBUG - Vrouter 10.0.0.38 does not have vrf 4 for VN ctest-vn2-04268916 2026-03-26 03:55:24,954 - DEBUG - VRF 5 is not seen in agent 10.0.0.23 2026-03-26 03:55:24,959 - DEBUG - Vrouter 10.0.0.23 does not have vrf 5 for VN ctest-vn2-04268916 2026-03-26 03:55:24,960 - INFO - Validated that all vrouters do not have the route table for VN default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn2-04268916 2026-03-26 03:55:24,982 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn2-04268916 info 2026-03-26 03:55:24,983 - INFO - Deleting VM ctest-vn1_vm2-03732048 2026-03-26 03:55:25,066 - INFO - Deleting VM ctest-vn1_vm1-82499764 2026-03-26 03:55:25,147 - INFO - Deleting VN ctest-vn1-06975742 2026-03-26 03:55:25,194 - DEBUG - VN aef1774d-96ae-45ae-9f34-35d4ea182f71 still in use: Unable to complete operation on network aef1774d-96ae-45ae-9f34-35d4ea182f71. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-b9fd4696-43c4-4954-acad-09010766b225'] 2026-03-26 03:55:25,194 - WARNING - Deleting VN ctest-vn1-06975742 failed..Will retry 2026-03-26 03:55:27,381 - DEBUG - Response for deleting network () 2026-03-26 03:55:27,381 - DEBUG - Requesting: http://10.0.0.240:8082/routing-instance/9c293f6a-7510-4e5a-b127-9b395a302887 2026-03-26 03:55:27,424 - DEBUG - Response Code: 404 2026-03-26 03:55:27,424 - DEBUG - Requesting: http://10.0.0.240:8082/domains 2026-03-26 03:55:27,430 - DEBUG - Requesting: http://10.0.0.240:8082/domain/b07ef41d-f358-4169-a694-2c6c20158c87 2026-03-26 03:55:27,446 - DEBUG - Requesting: http://10.0.0.240:8082/project/6750c5b7-55c0-491a-ab64-6801c7b0a322 2026-03-26 03:55:27,536 - INFO - Validated that VN ctest-vn1-06975742 is not found in API Server 2026-03-26 03:55:27,546 - DEBUG - VN ctest-vn1-06975742 is not present in Agent 10.0.0.38 2026-03-26 03:55:27,556 - DEBUG - VN ctest-vn1-06975742 is not present in Agent 10.0.0.23 2026-03-26 03:55:27,556 - INFO - Validated that VN ctest-vn1-06975742 is not in any agent 2026-03-26 03:55:27,560 - DEBUG - VRF 4 is not seen in agent 10.0.0.23 2026-03-26 03:55:27,566 - DEBUG - Vrouter 10.0.0.23 does not have vrf 4 for VN ctest-vn1-06975742 2026-03-26 03:55:27,566 - INFO - Validated that all vrouters do not have the route table for VN default-domain:ctest-AnalyticsTestSanityWithResource-43814714:ctest-vn1-06975742 2026-03-26 03:55:27,586 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn1-06975742 info 2026-03-26 03:55:28,422 - INFO - Deleted project: ctest-AnalyticsTestSanityWithResource-43814714, ID : 6750c5b7-55c0-491a-ab64-6801c7b0a322