2025-08-11 07:32:14,345 - INFO - Domain Default found not creating
2025-08-11 07:32:14,473 - INFO - Project ctest-AnalyticsTestSanityWithResource-83596126 not found, creating it
2025-08-11 07:32:14,945 - INFO - Created Project:ctest-AnalyticsTestSanityWithResource-83596126, ID : 5d5019ab-6bc5-494e-9e85-dd10a95c5237
2025-08-11 07:32:16,860 - DEBUG - Response for create_network : {'network': {'id': 'b0b15f92-0e11-4186-b6a4-8adacd2291a0', 'name': 'ctest-vn1-32168765', 'tenant_id': '5d5019ab6bc5494e9e85dd10a95c5237', 'project_id': '5d5019ab6bc5494e9e85dd10a95c5237', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-AnalyticsTestSanityWithResource-83596126', 'ctest-vn1-32168765'], 'port_security_enabled': True, 'description': ''}}
2025-08-11 07:32:17,125 - DEBUG - Response for create_subnet : {'subnet': {'id': '09855c6a-f709-44a3-a4a5-4ffbe2b1b0a7', 'name': '', 'tenant_id': '5d5019ab6bc5494e9e85dd10a95c5237', 'network_id': 'b0b15f92-0e11-4186-b6a4-8adacd2291a0', 'ip_version': 4, 'cidr': '98.152.189.0/26', 'allocation_pools': [{'start': '98.152.189.2', 'end': '98.152.189.62'}], 'gateway_ip': '98.152.189.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '98.152.189.2', 'tags': [], 'project_id': '5d5019ab6bc5494e9e85dd10a95c5237'}}
2025-08-11 07:32:17,144 - INFO - Created VN ctest-vn1-32168765
2025-08-11 07:32:17,154 - DEBUG - VN ctest-vn1-32168765 UUID is b0b15f92-0e11-4186-b6a4-8adacd2291a0
2025-08-11 07:32:17,238 - DEBUG - Services list from nova: [, , , ]
2025-08-11 07:32:17,238 - DEBUG - Hosts: {'nova': ['an-jenkins-deploy-platform-ansible-os-3798-2', 'an-jenkins-deploy-platform-ansible-os-3798-1']}
2025-08-11 07:32:18,221 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3798-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3798-2)
2025-08-11 07:32:19,495 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3798-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3798-2)
2025-08-11 07:32:19,496 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:32:19,808 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:32:19,849 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:32:19,864 - DEBUG - Requesting: http://10.0.0.254:8082/project/5d5019ab-6bc5-494e-9e85-dd10a95c5237
2025-08-11 07:32:19,971 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/b0b15f92-0e11-4186-b6a4-8adacd2291a0
2025-08-11 07:32:19,991 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/b0b15f92-0e11-4186-b6a4-8adacd2291a0
2025-08-11 07:32:20,006 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/f58a86ef-86ab-4c14-b56c-cf014d36f2b1
2025-08-11 07:32:20,027 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/f58a86ef-86ab-4c14-b56c-cf014d36f2b1
2025-08-11 07:32:20,040 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/3ee71e43-3155-452b-b85c-4f8b44e496aa
2025-08-11 07:32:20,049 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 07:32:20,049 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/b0b15f92-0e11-4186-b6a4-8adacd2291a0
2025-08-11 07:32:20,071 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/f58a86ef-86ab-4c14-b56c-cf014d36f2b1
2025-08-11 07:32:20,087 - INFO - Verified VN network id 14 for VN b0b15f92-0e11-4186-b6a4-8adacd2291a0
2025-08-11 07:32:20,087 - INFO - Verifications in API Server for VN ctest-vn1-32168765 passed
2025-08-11 07:32:20,087 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/b0b15f92-0e11-4186-b6a4-8adacd2291a0
2025-08-11 07:32:20,097 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/f58a86ef-86ab-4c14-b56c-cf014d36f2b1
2025-08-11 07:32:20,105 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/f58a86ef-86ab-4c14-b56c-cf014d36f2b1
2025-08-11 07:32:20,117 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/3ee71e43-3155-452b-b85c-4f8b44e496aa
2025-08-11 07:32:20,131 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-83596126', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12732062702458978694', 'uuid-lslong': '13160796683432530336'}, 'enable': 'true', 'created': '2025-08-11T07:32:16', 'last-modified': '2025-08-11T07:32:17', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.051110'}
2025-08-11 07:32:20,141 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 07:32:20,147 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-83596126', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12732062702458978694', 'uuid-lslong': '13160796683432530336'}, 'enable': 'true', 'created': '2025-08-11T07:32:16', 'last-modified': '2025-08-11T07:32:17', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.068598'}
2025-08-11 07:32:20,156 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 07:32:20,163 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-83596126', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12732062702458978694', 'uuid-lslong': '13160796683432530336'}, 'enable': 'true', 'created': '2025-08-11T07:32:16', 'last-modified': '2025-08-11T07:32:17', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.086170'}
2025-08-11 07:32:20,171 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 07:32:20,171 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn1-32168765 passed
2025-08-11 07:32:20,172 - DEBUG - ====Verifying policy data for ctest-vn1-32168765 in API_Server ======
2025-08-11 07:32:20,172 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:32:20,179 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:32:20,226 - DEBUG - Requesting: http://10.0.0.254:8082/project/5d5019ab-6bc5-494e-9e85-dd10a95c5237
2025-08-11 07:32:20,336 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/b0b15f92-0e11-4186-b6a4-8adacd2291a0
2025-08-11 07:32:20,349 - DEBUG - =>VN ctest-vn1-32168765 has no policy to be verified
2025-08-11 07:32:20,349 - DEBUG - Verifying the vn in opserver
2025-08-11 07:32:20,349 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765 virtual network link through opserver 10.0.0.254
2025-08-11 07:32:20,349 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-11 07:32:20,647 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-11 07:32:20,699 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765?flat'}
2025-08-11 07:32:20,699 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765 virtual network link through opserver 10.0.0.38
2025-08-11 07:32:20,699 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-11 07:32:20,992 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-11 07:32:21,039 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765?flat'}
2025-08-11 07:32:21,039 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765 virtual network link through opserver 10.0.0.50
2025-08-11 07:32:21,039 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-08-11 07:32:21,329 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-08-11 07:32:21,380 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765?flat'}
2025-08-11 07:32:21,380 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765 is found in opserver
2025-08-11 07:32:21,391 - DEBUG - VRF ids for VN ctest-vn1-32168765: {'10.0.0.57': '2'}
2025-08-11 07:32:21,391 - INFO - Waiting for VM ctest-vn1_vm1-60071817 to be up..
2025-08-11 07:32:21,465 - DEBUG - VM is in ACTIVE state now
2025-08-11 07:32:21,465 - INFO - VM name : ctest-vn1_vm1-60071817
2025-08-11 07:32:21,535 - DEBUG - VM ctest-vn1_vm1-60071817 ID is 21751f92-ea9b-4101-aa71-01e726c8ec06
2025-08-11 07:32:21,557 - DEBUG - VM ctest-vn1_vm1-60071817 launched on Node an-jenkins-deploy-platform-ansible-os-3798-2
2025-08-11 07:32:21,635 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/21751f92-ea9b-4101-aa71-01e726c8ec06
2025-08-11 07:32:21,943 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/21751f92-ea9b-4101-aa71-01e726c8ec06
2025-08-11 07:32:21,971 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/00859916-e700-48ab-9bc3-dcad874570e9
2025-08-11 07:32:25,179 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1004ms')
2025-08-11 07:32:25,179 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1-60071817 failed!
2025-08-11 07:32:25,192 - DEBUG - Gateway for vn default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765 is 98.152.189.1 and allocation pool is NOT set
2025-08-11 07:32:29,273 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1018ms')
2025-08-11 07:32:29,273 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1-60071817 failed!
2025-08-11 07:32:29,287 - DEBUG - Gateway for vn default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765 is 98.152.189.1 and allocation pool is NOT set
2025-08-11 07:32:33,370 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1019ms')
2025-08-11 07:32:33,370 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1-60071817 failed!
2025-08-11 07:32:33,384 - DEBUG - Gateway for vn default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765 is 98.152.189.1 and allocation pool is NOT set
2025-08-11 07:32:37,461 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=9.01 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 1 received, 50% packet loss, time 1019ms\r\nrtt min/avg/max/mdev = 9.009/9.009/9.009/0.000 ms')
2025-08-11 07:32:37,461 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1-60071817 passed
2025-08-11 07:32:37,576 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 07:32:37,577 - DEBUG - Waiting to SSH to VM ctest-vn1_vm1-60071817, IP 98.152.189.3, Port 22
2025-08-11 07:32:37,642 - DEBUG - Error on ssh to cirros@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-08-11 07:32:37,727 - DEBUG - VM ctest-vn1_vm1-60071817 is NOT ready for SSH connections, VM status: ACTIVE
2025-08-11 07:32:42,727 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 07:32:42,728 - DEBUG - Waiting to SSH to VM ctest-vn1_vm1-60071817, IP 98.152.189.3, Port 22
2025-08-11 07:32:42,888 - DEBUG - VM ctest-vn1_vm1-60071817 is ready for SSH connections
2025-08-11 07:32:42,888 - INFO - Waiting for VM ctest-vn1_vm2-74713373 to be up..
2025-08-11 07:32:42,956 - DEBUG - VM is in ACTIVE state now
2025-08-11 07:32:42,956 - INFO - VM name : ctest-vn1_vm2-74713373
2025-08-11 07:32:43,021 - DEBUG - VM ctest-vn1_vm2-74713373 ID is bc791c5a-72aa-4da6-8f18-06d90c17b7b8
2025-08-11 07:32:43,021 - DEBUG - VM ctest-vn1_vm2-74713373 launched on Node an-jenkins-deploy-platform-ansible-os-3798-2
2025-08-11 07:32:43,095 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/bc791c5a-72aa-4da6-8f18-06d90c17b7b8
2025-08-11 07:32:43,105 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/1b0341e3-29c6-4aa3-9f67-4ac29b56cfda
2025-08-11 07:32:44,302 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=2.95 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.526 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.526/1.739/2.952/1.213 ms')
2025-08-11 07:32:44,302 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-vn1_vm2-74713373 passed
2025-08-11 07:32:44,413 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 07:32:44,413 - DEBUG - Waiting to SSH to VM ctest-vn1_vm2-74713373, IP 98.152.189.4, Port 22
2025-08-11 07:32:44,479 - DEBUG - Error on ssh to cirros@169.254.0.4:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.4/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.4/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.4/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-08-11 07:32:44,546 - DEBUG - VM ctest-vn1_vm2-74713373 is NOT ready for SSH connections, VM status: ACTIVE
2025-08-11 07:32:49,547 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 07:32:49,548 - DEBUG - Waiting to SSH to VM ctest-vn1_vm2-74713373, IP 98.152.189.4, Port 22
2025-08-11 07:32:49,708 - DEBUG - VM ctest-vn1_vm2-74713373 is ready for SSH connections
2025-08-11 07:32:49,929 - DEBUG - Response for create_network : {'network': {'id': 'cfe1d1fb-e6bf-48c1-bf36-9299770a2424', 'name': 'ctest-vn2-82351956', 'tenant_id': '5d5019ab6bc5494e9e85dd10a95c5237', 'project_id': '5d5019ab6bc5494e9e85dd10a95c5237', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-AnalyticsTestSanityWithResource-83596126', 'ctest-vn2-82351956'], 'port_security_enabled': True, 'description': ''}}
2025-08-11 07:32:50,159 - DEBUG - Response for create_subnet : {'subnet': {'id': 'cb637cfd-55b7-4810-afe6-7625a8c8f9e2', 'name': '', 'tenant_id': '5d5019ab6bc5494e9e85dd10a95c5237', 'network_id': 'cfe1d1fb-e6bf-48c1-bf36-9299770a2424', 'ip_version': 4, 'cidr': '198.224.253.192/26', 'allocation_pools': [{'start': '198.224.253.194', 'end': '198.224.253.254'}], 'gateway_ip': '198.224.253.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '198.224.253.194', 'tags': [], 'project_id': '5d5019ab6bc5494e9e85dd10a95c5237'}}
2025-08-11 07:32:50,178 - INFO - Created VN ctest-vn2-82351956
2025-08-11 07:32:50,189 - DEBUG - VN ctest-vn2-82351956 UUID is cfe1d1fb-e6bf-48c1-bf36-9299770a2424
2025-08-11 07:32:50,554 - DEBUG - Response for create_network : {'network': {'id': '84189f46-2adf-4c94-a122-89a144c877e6', 'name': 'ctest-fip_vn-54233459', 'tenant_id': '5d5019ab6bc5494e9e85dd10a95c5237', 'project_id': '5d5019ab6bc5494e9e85dd10a95c5237', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-AnalyticsTestSanityWithResource-83596126', 'ctest-fip_vn-54233459'], 'port_security_enabled': True, 'description': ''}}
2025-08-11 07:32:50,726 - DEBUG - Response for create_subnet : {'subnet': {'id': '521653c4-4c7b-4839-9d4f-b68436bd5e97', 'name': '', 'tenant_id': '5d5019ab6bc5494e9e85dd10a95c5237', 'network_id': '84189f46-2adf-4c94-a122-89a144c877e6', 'ip_version': 4, 'cidr': '128.101.196.0/26', 'allocation_pools': [{'start': '128.101.196.2', 'end': '128.101.196.62'}], 'gateway_ip': '128.101.196.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '128.101.196.2', 'tags': [], 'project_id': '5d5019ab6bc5494e9e85dd10a95c5237'}}
2025-08-11 07:32:50,748 - INFO - Created VN ctest-fip_vn-54233459
2025-08-11 07:32:50,761 - DEBUG - VN ctest-fip_vn-54233459 UUID is 84189f46-2adf-4c94-a122-89a144c877e6
2025-08-11 07:32:50,828 - DEBUG - Hosts: {'nova': ['an-jenkins-deploy-platform-ansible-os-3798-2', 'an-jenkins-deploy-platform-ansible-os-3798-1']}
2025-08-11 07:32:51,918 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3798-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3798-1)
2025-08-11 07:32:53,291 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3798-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3798-1)
2025-08-11 07:32:54,715 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3798-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3798-2)
2025-08-11 07:32:54,715 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:32:54,721 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:32:54,735 - DEBUG - Requesting: http://10.0.0.254:8082/project/5d5019ab-6bc5-494e-9e85-dd10a95c5237
2025-08-11 07:32:54,825 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/b0b15f92-0e11-4186-b6a4-8adacd2291a0
2025-08-11 07:32:54,837 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/b0b15f92-0e11-4186-b6a4-8adacd2291a0
2025-08-11 07:32:54,847 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/f58a86ef-86ab-4c14-b56c-cf014d36f2b1
2025-08-11 07:32:54,854 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/f58a86ef-86ab-4c14-b56c-cf014d36f2b1
2025-08-11 07:32:54,862 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/3ee71e43-3155-452b-b85c-4f8b44e496aa
2025-08-11 07:32:54,870 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 07:32:54,870 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/b0b15f92-0e11-4186-b6a4-8adacd2291a0
2025-08-11 07:32:54,880 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/f58a86ef-86ab-4c14-b56c-cf014d36f2b1
2025-08-11 07:32:54,899 - INFO - Verified VN network id 14 for VN b0b15f92-0e11-4186-b6a4-8adacd2291a0
2025-08-11 07:32:54,899 - INFO - Verifications in API Server for VN ctest-vn1-32168765 passed
2025-08-11 07:32:54,899 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/b0b15f92-0e11-4186-b6a4-8adacd2291a0
2025-08-11 07:32:54,911 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/f58a86ef-86ab-4c14-b56c-cf014d36f2b1
2025-08-11 07:32:54,920 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/f58a86ef-86ab-4c14-b56c-cf014d36f2b1
2025-08-11 07:32:54,928 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/3ee71e43-3155-452b-b85c-4f8b44e496aa
2025-08-11 07:32:54,950 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-83596126', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12732062702458978694', 'uuid-lslong': '13160796683432530336'}, 'enable': 'true', 'created': '2025-08-11T07:32:16', 'last-modified': '2025-08-11T07:32:17', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:37.868736'}
2025-08-11 07:32:54,959 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 07:32:54,965 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-83596126', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12732062702458978694', 'uuid-lslong': '13160796683432530336'}, 'enable': 'true', 'created': '2025-08-11T07:32:16', 'last-modified': '2025-08-11T07:32:17', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:37.887229'}
2025-08-11 07:32:54,972 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 07:32:54,978 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-83596126', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12732062702458978694', 'uuid-lslong': '13160796683432530336'}, 'enable': 'true', 'created': '2025-08-11T07:32:16', 'last-modified': '2025-08-11T07:32:17', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:37.901318'}
2025-08-11 07:32:54,984 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 07:32:54,984 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn1-32168765 passed
2025-08-11 07:32:54,984 - DEBUG - ====Verifying policy data for ctest-vn1-32168765 in API_Server ======
2025-08-11 07:32:54,984 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:32:54,990 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:32:55,005 - DEBUG - Requesting: http://10.0.0.254:8082/project/5d5019ab-6bc5-494e-9e85-dd10a95c5237
2025-08-11 07:32:55,052 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/b0b15f92-0e11-4186-b6a4-8adacd2291a0
2025-08-11 07:32:55,068 - DEBUG - =>VN ctest-vn1-32168765 has no policy to be verified
2025-08-11 07:32:55,068 - DEBUG - Verifying the vn in opserver
2025-08-11 07:32:55,068 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765 virtual network link through opserver 10.0.0.254
2025-08-11 07:32:55,068 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-11 07:32:55,150 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765?flat'}
2025-08-11 07:32:55,150 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765 virtual network link through opserver 10.0.0.38
2025-08-11 07:32:55,150 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-11 07:32:55,208 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765?flat'}
2025-08-11 07:32:55,208 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765 virtual network link through opserver 10.0.0.50
2025-08-11 07:32:55,208 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-08-11 07:32:55,301 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765?flat'}
2025-08-11 07:32:55,301 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765 is found in opserver
2025-08-11 07:32:55,320 - DEBUG - VRF ids for VN ctest-vn1-32168765: {'10.0.0.57': '2'}
2025-08-11 07:32:55,320 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:32:55,331 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:32:55,346 - DEBUG - Requesting: http://10.0.0.254:8082/project/5d5019ab-6bc5-494e-9e85-dd10a95c5237
2025-08-11 07:32:55,437 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/cfe1d1fb-e6bf-48c1-bf36-9299770a2424
2025-08-11 07:32:55,458 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/cfe1d1fb-e6bf-48c1-bf36-9299770a2424
2025-08-11 07:32:55,468 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/ac9be2cf-1f03-4624-a91f-4edae62e16a6
2025-08-11 07:32:55,479 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/ac9be2cf-1f03-4624-a91f-4edae62e16a6
2025-08-11 07:32:55,488 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/7418610e-fec3-4a27-876d-9621c1e6550e
2025-08-11 07:32:55,500 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-11 07:32:55,500 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/cfe1d1fb-e6bf-48c1-bf36-9299770a2424
2025-08-11 07:32:55,538 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/ac9be2cf-1f03-4624-a91f-4edae62e16a6
2025-08-11 07:32:55,577 - INFO - Verified VN network id 15 for VN cfe1d1fb-e6bf-48c1-bf36-9299770a2424
2025-08-11 07:32:55,577 - INFO - Verifications in API Server for VN ctest-vn2-82351956 passed
2025-08-11 07:32:55,578 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/cfe1d1fb-e6bf-48c1-bf36-9299770a2424
2025-08-11 07:32:55,606 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/ac9be2cf-1f03-4624-a91f-4edae62e16a6
2025-08-11 07:32:55,615 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/ac9be2cf-1f03-4624-a91f-4edae62e16a6
2025-08-11 07:32:55,625 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/7418610e-fec3-4a27-876d-9621c1e6550e
2025-08-11 07:32:55,639 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn2-82351956', 'interests': '1', 'advertised': '1', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-83596126', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14979484715449272513', 'uuid-lslong': '13778361297811416100'}, 'enable': 'true', 'created': '2025-08-11T07:32:49', 'last-modified': '2025-08-11T07:32:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:05.528421'}
2025-08-11 07:32:55,651 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-11 07:32:55,662 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn2-82351956', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-83596126', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14979484715449272513', 'uuid-lslong': '13778361297811416100'}, 'enable': 'true', 'created': '2025-08-11T07:32:49', 'last-modified': '2025-08-11T07:32:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:05.553187'}
2025-08-11 07:32:55,668 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-11 07:32:55,674 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn2-82351956', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-83596126', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14979484715449272513', 'uuid-lslong': '13778361297811416100'}, 'enable': 'true', 'created': '2025-08-11T07:32:49', 'last-modified': '2025-08-11T07:32:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:05.563587'}
2025-08-11 07:32:55,682 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-11 07:32:55,682 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn2-82351956 passed
2025-08-11 07:32:55,683 - DEBUG - ====Verifying policy data for ctest-vn2-82351956 in API_Server ======
2025-08-11 07:32:55,683 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:32:55,691 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:32:55,704 - DEBUG - Requesting: http://10.0.0.254:8082/project/5d5019ab-6bc5-494e-9e85-dd10a95c5237
2025-08-11 07:32:55,821 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/cfe1d1fb-e6bf-48c1-bf36-9299770a2424
2025-08-11 07:32:55,838 - DEBUG - =>VN ctest-vn2-82351956 has no policy to be verified
2025-08-11 07:32:55,838 - DEBUG - Verifying the vn in opserver
2025-08-11 07:32:55,838 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn2-82351956 virtual network link through opserver 10.0.0.254
2025-08-11 07:32:55,838 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-11 07:32:55,853 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn2-82351956', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn2-82351956?flat'}
2025-08-11 07:32:55,853 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn2-82351956 virtual network link through opserver 10.0.0.38
2025-08-11 07:32:55,853 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-11 07:32:55,865 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn2-82351956', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn2-82351956?flat'}
2025-08-11 07:32:55,865 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn2-82351956 virtual network link through opserver 10.0.0.50
2025-08-11 07:32:55,865 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-08-11 07:32:55,962 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn2-82351956', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn2-82351956?flat'}
2025-08-11 07:32:55,962 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn2-82351956 is found in opserver
2025-08-11 07:32:55,974 - DEBUG - VRF ids for VN ctest-vn2-82351956: {'10.0.0.49': '2'}
2025-08-11 07:32:55,974 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:32:55,979 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:32:55,990 - DEBUG - Requesting: http://10.0.0.254:8082/project/5d5019ab-6bc5-494e-9e85-dd10a95c5237
2025-08-11 07:32:56,077 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/84189f46-2adf-4c94-a122-89a144c877e6
2025-08-11 07:32:56,090 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/84189f46-2adf-4c94-a122-89a144c877e6
2025-08-11 07:32:56,106 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/c2ed8ab8-6698-44c2-8508-e04bad1b0451
2025-08-11 07:32:56,113 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/c2ed8ab8-6698-44c2-8508-e04bad1b0451
2025-08-11 07:32:56,120 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/56317a72-bc33-4795-bed3-4144f6aff5fb
2025-08-11 07:32:56,128 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-11 07:32:56,128 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/84189f46-2adf-4c94-a122-89a144c877e6
2025-08-11 07:32:56,137 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/c2ed8ab8-6698-44c2-8508-e04bad1b0451
2025-08-11 07:32:56,151 - INFO - Verified VN network id 16 for VN 84189f46-2adf-4c94-a122-89a144c877e6
2025-08-11 07:32:56,152 - INFO - Verifications in API Server for VN ctest-fip_vn-54233459 passed
2025-08-11 07:32:56,152 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/84189f46-2adf-4c94-a122-89a144c877e6
2025-08-11 07:32:56,164 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/c2ed8ab8-6698-44c2-8508-e04bad1b0451
2025-08-11 07:32:56,171 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/c2ed8ab8-6698-44c2-8508-e04bad1b0451
2025-08-11 07:32:56,183 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/56317a72-bc33-4795-bed3-4144f6aff5fb
2025-08-11 07:32:56,201 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-fip_vn-54233459', 'interests': '1', 'advertised': '1', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-83596126', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9518532936163347604', 'uuid-lslong': '11610994115051288550'}, 'enable': 'true', 'created': '2025-08-11T07:32:50', 'last-modified': '2025-08-11T07:32:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:05.519036'}
2025-08-11 07:32:56,207 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-11 07:32:56,213 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-fip_vn-54233459', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-83596126', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9518532936163347604', 'uuid-lslong': '11610994115051288550'}, 'enable': 'true', 'created': '2025-08-11T07:32:50', 'last-modified': '2025-08-11T07:32:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:05.532897'}
2025-08-11 07:32:56,218 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-11 07:32:56,224 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-fip_vn-54233459', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-83596126', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9518532936163347604', 'uuid-lslong': '11610994115051288550'}, 'enable': 'true', 'created': '2025-08-11T07:32:50', 'last-modified': '2025-08-11T07:32:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:05.545792'}
2025-08-11 07:32:56,230 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-11 07:32:56,230 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-fip_vn-54233459 passed
2025-08-11 07:32:56,230 - DEBUG - ====Verifying policy data for ctest-fip_vn-54233459 in API_Server ======
2025-08-11 07:32:56,230 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:32:56,236 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:32:56,247 - DEBUG - Requesting: http://10.0.0.254:8082/project/5d5019ab-6bc5-494e-9e85-dd10a95c5237
2025-08-11 07:32:56,331 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/84189f46-2adf-4c94-a122-89a144c877e6
2025-08-11 07:32:56,342 - DEBUG - =>VN ctest-fip_vn-54233459 has no policy to be verified
2025-08-11 07:32:56,342 - DEBUG - Verifying the vn in opserver
2025-08-11 07:32:56,342 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-fip_vn-54233459 virtual network link through opserver 10.0.0.254
2025-08-11 07:32:56,342 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-11 07:32:56,396 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-fip_vn-54233459', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-fip_vn-54233459?flat'}
2025-08-11 07:32:56,396 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-fip_vn-54233459 virtual network link through opserver 10.0.0.38
2025-08-11 07:32:56,396 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-11 07:32:56,442 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-fip_vn-54233459', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-fip_vn-54233459?flat'}
2025-08-11 07:32:56,442 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-fip_vn-54233459 virtual network link through opserver 10.0.0.50
2025-08-11 07:32:56,442 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-08-11 07:32:56,455 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-fip_vn-54233459', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-fip_vn-54233459?flat'}
2025-08-11 07:32:56,455 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-fip_vn-54233459 is found in opserver
2025-08-11 07:32:56,468 - DEBUG - VRF ids for VN ctest-fip_vn-54233459: {'10.0.0.49': '3'}
2025-08-11 07:32:56,468 - INFO - Waiting for VM ctest-fvn_vm1-09894592 to be up..
2025-08-11 07:32:56,547 - DEBUG - VM is in ACTIVE state now
2025-08-11 07:32:56,547 - INFO - VM name : ctest-fvn_vm1-09894592
2025-08-11 07:32:56,613 - DEBUG - VM ctest-fvn_vm1-09894592 ID is 4108ac21-8269-47db-b88b-fad0f87f54a1
2025-08-11 07:32:56,613 - DEBUG - VM ctest-fvn_vm1-09894592 launched on Node an-jenkins-deploy-platform-ansible-os-3798-1
2025-08-11 07:32:56,682 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/4108ac21-8269-47db-b88b-fad0f87f54a1
2025-08-11 07:32:56,690 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/e188784b-5aef-49f6-8ba6-26c865b94ac0
2025-08-11 07:32:59,908 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1010ms')
2025-08-11 07:32:59,908 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-fvn_vm1-09894592 failed!
2025-08-11 07:32:59,922 - DEBUG - Gateway for vn default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-fip_vn-54233459 is 128.101.196.1 and allocation pool is NOT set
2025-08-11 07:33:03,997 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1019ms')
2025-08-11 07:33:03,997 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-fvn_vm1-09894592 failed!
2025-08-11 07:33:04,011 - DEBUG - Gateway for vn default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-fip_vn-54233459 is 128.101.196.1 and allocation pool is NOT set
2025-08-11 07:33:08,091 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1028ms')
2025-08-11 07:33:08,091 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-fvn_vm1-09894592 failed!
2025-08-11 07:33:08,105 - DEBUG - Gateway for vn default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-fip_vn-54233459 is 128.101.196.1 and allocation pool is NOT set
2025-08-11 07:33:12,192 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1031ms')
2025-08-11 07:33:12,192 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-fvn_vm1-09894592 failed!
2025-08-11 07:33:12,205 - DEBUG - Gateway for vn default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-fip_vn-54233459 is 128.101.196.1 and allocation pool is NOT set
2025-08-11 07:33:14,266 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=8.23 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=1.77 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 1.769/4.997/8.225/3.228 ms')
2025-08-11 07:33:14,266 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-fvn_vm1-09894592 passed
2025-08-11 07:33:14,376 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 07:33:14,376 - DEBUG - Waiting to SSH to VM ctest-fvn_vm1-09894592, IP 128.101.196.3, Port 22
2025-08-11 07:33:14,434 - DEBUG - Error on ssh to cirros@169.254.0.4:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.4/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.4/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.4/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-08-11 07:33:14,503 - DEBUG - VM ctest-fvn_vm1-09894592 is NOT ready for SSH connections, VM status: ACTIVE
2025-08-11 07:33:19,504 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 07:33:19,504 - DEBUG - Waiting to SSH to VM ctest-fvn_vm1-09894592, IP 128.101.196.3, Port 22
2025-08-11 07:33:19,558 - DEBUG - Error on ssh to cirros@169.254.0.4:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.4/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.4/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.4/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-08-11 07:33:19,627 - DEBUG - VM ctest-fvn_vm1-09894592 is NOT ready for SSH connections, VM status: ACTIVE
2025-08-11 07:33:24,627 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 07:33:24,628 - DEBUG - Waiting to SSH to VM ctest-fvn_vm1-09894592, IP 128.101.196.3, Port 22
2025-08-11 07:33:24,696 - DEBUG - Error on ssh to cirros@169.254.0.4:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.4/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.4/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.4/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-08-11 07:33:24,765 - DEBUG - VM ctest-fvn_vm1-09894592 is NOT ready for SSH connections, VM status: ACTIVE
2025-08-11 07:33:29,766 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 07:33:29,766 - DEBUG - Waiting to SSH to VM ctest-fvn_vm1-09894592, IP 128.101.196.3, Port 22
2025-08-11 07:33:29,931 - DEBUG - VM ctest-fvn_vm1-09894592 is ready for SSH connections
2025-08-11 07:33:29,932 - INFO - Waiting for VM ctest-vn2_vm2-95457406 to be up..
2025-08-11 07:33:30,000 - DEBUG - VM is in ACTIVE state now
2025-08-11 07:33:30,000 - INFO - VM name : ctest-vn2_vm2-95457406
2025-08-11 07:33:30,070 - DEBUG - VM ctest-vn2_vm2-95457406 ID is 33263273-d545-48e6-b34e-31f8f260109b
2025-08-11 07:33:30,070 - DEBUG - VM ctest-vn2_vm2-95457406 launched on Node an-jenkins-deploy-platform-ansible-os-3798-1
2025-08-11 07:33:30,149 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/33263273-d545-48e6-b34e-31f8f260109b
2025-08-11 07:33:30,158 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/86c5abdb-feb1-4647-99c0-4294a3bcc916
2025-08-11 07:33:31,351 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=2.07 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.278 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.278/1.173/2.068/0.895 ms')
2025-08-11 07:33:31,352 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn2_vm2-95457406 passed
2025-08-11 07:33:31,468 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 07:33:31,468 - DEBUG - Waiting to SSH to VM ctest-vn2_vm2-95457406, IP 198.224.253.195, Port 22
2025-08-11 07:33:31,633 - DEBUG - VM ctest-vn2_vm2-95457406 is ready for SSH connections
2025-08-11 07:33:31,633 - INFO - Waiting for VM mltf_vm to be up..
2025-08-11 07:33:31,711 - DEBUG - VM is in ACTIVE state now
2025-08-11 07:33:31,711 - INFO - VM name : mltf_vm
2025-08-11 07:33:31,790 - DEBUG - VM mltf_vm ID is 2be8d6d2-558a-4470-885f-3e9ef2ac48f7
2025-08-11 07:33:31,790 - DEBUG - VM mltf_vm launched on Node an-jenkins-deploy-platform-ansible-os-3798-2
2025-08-11 07:33:31,934 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/2be8d6d2-558a-4470-885f-3e9ef2ac48f7
2025-08-11 07:33:31,943 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/bc472d1b-72ad-44e6-9d42-5075114f5998
2025-08-11 07:33:31,953 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/dc669a38-8d8f-4661-814a-78e393efafad
2025-08-11 07:33:33,160 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n64 bytes from 169.254.0.5: icmp_seq=1 ttl=63 time=1.51 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=0.433 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.433/0.972/1.511/0.539 ms')
2025-08-11 07:33:33,160 - INFO - Ping to Metadata IP 169.254.0.5 of VM mltf_vm passed
2025-08-11 07:33:33,268 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 07:33:33,268 - DEBUG - Waiting to SSH to VM mltf_vm, IP 98.152.189.5, Port 22
2025-08-11 07:33:33,428 - DEBUG - VM mltf_vm is ready for SSH connections
2025-08-11 07:33:33,537 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 07:33:33,537 - DEBUG - Waiting to SSH to VM mltf_vm, IP 98.152.189.5, Port 22
2025-08-11 07:33:33,706 - DEBUG - VM mltf_vm is ready for SSH connections
2025-08-11 07:33:33,708 - INFO - ================================================================================
2025-08-11 07:33:33,709 - INFO - STARTING TEST : test_verify_session_sampling_teardown
2025-08-11 07:33:33,709 - INFO - TEST DESCRIPTION :
1.query client session samples
2.query server session samples
3.query client session to get number of sessions exported
4.query session record table for teardown bytes/pkts
5.query sample count after teardown on server side
2025-08-11 07:33:34,956 - DEBUG - Skipping xmpp flap check
2025-08-11 07:33:34,957 - INFO - Initial checks done. Running the testcase now
2025-08-11 07:33:34,957 - INFO -
2025-08-11 07:33:35,164 - INFO - Setting flow export rate: 100
2025-08-11 07:33:35,213 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = any, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = -1, end_port = -1], application = [], dst_addresses = [subnet = None, virtual_network = any, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None]
2025-08-11 07:33:35,520 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': 'b0b15f92-0e11-4186-b6a4-8adacd2291a0', 'name': 'ctest-vn1-32168765', 'tenant_id': '5d5019ab6bc5494e9e85dd10a95c5237', 'project_id': '5d5019ab6bc5494e9e85dd10a95c5237', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['09855c6a-f709-44a3-a4a5-4ffbe2b1b0a7'], 'fq_name': ['default-domain', 'ctest-AnalyticsTestSanityWithResource-83596126', 'ctest-vn1-32168765'], 'policys': [['default-domain', 'ctest-AnalyticsTestSanityWithResource-83596126', 'policy1']], 'subnet_ipam': [{'subnet_cidr': '98.152.189.0/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}}
2025-08-11 07:33:35,686 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': 'cfe1d1fb-e6bf-48c1-bf36-9299770a2424', 'name': 'ctest-vn2-82351956', 'tenant_id': '5d5019ab6bc5494e9e85dd10a95c5237', 'project_id': '5d5019ab6bc5494e9e85dd10a95c5237', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['cb637cfd-55b7-4810-afe6-7625a8c8f9e2'], 'fq_name': ['default-domain', 'ctest-AnalyticsTestSanityWithResource-83596126', 'ctest-vn2-82351956'], 'policys': [['default-domain', 'ctest-AnalyticsTestSanityWithResource-83596126', 'policy1']], 'subnet_ipam': [{'subnet_cidr': '198.224.253.192/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}}
2025-08-11 07:33:35,770 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:33:35,776 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:33:35,787 - DEBUG - Requesting: http://10.0.0.254:8082/project/5d5019ab-6bc5-494e-9e85-dd10a95c5237
2025-08-11 07:33:35,879 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/b0b15f92-0e11-4186-b6a4-8adacd2291a0
2025-08-11 07:33:35,903 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/b0b15f92-0e11-4186-b6a4-8adacd2291a0
2025-08-11 07:33:35,918 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/f58a86ef-86ab-4c14-b56c-cf014d36f2b1
2025-08-11 07:33:35,931 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/f58a86ef-86ab-4c14-b56c-cf014d36f2b1
2025-08-11 07:33:35,942 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/3ee71e43-3155-452b-b85c-4f8b44e496aa
2025-08-11 07:33:35,952 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 07:33:35,952 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/b0b15f92-0e11-4186-b6a4-8adacd2291a0
2025-08-11 07:33:35,994 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/f58a86ef-86ab-4c14-b56c-cf014d36f2b1
2025-08-11 07:33:36,014 - INFO - Verified VN network id 14 for VN b0b15f92-0e11-4186-b6a4-8adacd2291a0
2025-08-11 07:33:36,015 - INFO - Verifications in API Server for VN ctest-vn1-32168765 passed
2025-08-11 07:33:36,015 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/b0b15f92-0e11-4186-b6a4-8adacd2291a0
2025-08-11 07:33:36,030 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/f58a86ef-86ab-4c14-b56c-cf014d36f2b1
2025-08-11 07:33:36,037 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/f58a86ef-86ab-4c14-b56c-cf014d36f2b1
2025-08-11 07:33:36,070 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/3ee71e43-3155-452b-b85c-4f8b44e496aa
2025-08-11 07:33:36,125 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-83596126', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12732062702458978694', 'uuid-lslong': '13160796683432530336'}, 'enable': 'true', 'created': '2025-08-11T07:32:16', 'last-modified': '2025-08-11T07:33:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.623824'}
2025-08-11 07:33:36,162 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 07:33:36,169 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-83596126', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12732062702458978694', 'uuid-lslong': '13160796683432530336'}, 'enable': 'true', 'created': '2025-08-11T07:32:16', 'last-modified': '2025-08-11T07:33:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.671000'}
2025-08-11 07:33:36,175 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 07:33:36,184 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-83596126', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12732062702458978694', 'uuid-lslong': '13160796683432530336'}, 'enable': 'true', 'created': '2025-08-11T07:32:16', 'last-modified': '2025-08-11T07:33:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.687241'}
2025-08-11 07:33:36,190 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 07:33:36,190 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn1-32168765 passed
2025-08-11 07:33:36,190 - DEBUG - ====Verifying policy data for ctest-vn1-32168765 in API_Server ======
2025-08-11 07:33:36,190 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:33:36,196 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:33:36,208 - DEBUG - Requesting: http://10.0.0.254:8082/project/5d5019ab-6bc5-494e-9e85-dd10a95c5237
2025-08-11 07:33:36,297 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/b0b15f92-0e11-4186-b6a4-8adacd2291a0
2025-08-11 07:33:36,307 - DEBUG - ==>Verifying data for policy with id: deb51bfd-619e-412d-a6b1-b94c60dccb83, fqn: ['default-domain', 'ctest-AnalyticsTestSanityWithResource-83596126', 'policy1']
2025-08-11 07:33:36,307 - INFO - VN ctest-vn1-32168765 Policy verification: verify_vn_policy_in_api_server, status: True
2025-08-11 07:33:36,308 - DEBUG - Verifying the vn in opserver
2025-08-11 07:33:36,308 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765 virtual network link through opserver 10.0.0.254
2025-08-11 07:33:36,308 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-11 07:33:36,320 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765?flat'}
2025-08-11 07:33:36,320 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765 virtual network link through opserver 10.0.0.38
2025-08-11 07:33:36,320 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-11 07:33:36,332 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765?flat'}
2025-08-11 07:33:36,333 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765 virtual network link through opserver 10.0.0.50
2025-08-11 07:33:36,333 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-08-11 07:33:36,346 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765?flat'}
2025-08-11 07:33:36,346 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765 is found in opserver
2025-08-11 07:33:36,356 - DEBUG - VRF ids for VN ctest-vn1-32168765: {'10.0.0.57': '2'}
2025-08-11 07:33:36,356 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:33:36,362 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:33:36,373 - DEBUG - Requesting: http://10.0.0.254:8082/project/5d5019ab-6bc5-494e-9e85-dd10a95c5237
2025-08-11 07:33:36,465 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/cfe1d1fb-e6bf-48c1-bf36-9299770a2424
2025-08-11 07:33:36,482 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/cfe1d1fb-e6bf-48c1-bf36-9299770a2424
2025-08-11 07:33:36,494 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/ac9be2cf-1f03-4624-a91f-4edae62e16a6
2025-08-11 07:33:36,504 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/ac9be2cf-1f03-4624-a91f-4edae62e16a6
2025-08-11 07:33:36,512 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/7418610e-fec3-4a27-876d-9621c1e6550e
2025-08-11 07:33:36,518 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-11 07:33:36,518 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/cfe1d1fb-e6bf-48c1-bf36-9299770a2424
2025-08-11 07:33:36,528 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/ac9be2cf-1f03-4624-a91f-4edae62e16a6
2025-08-11 07:33:36,549 - INFO - Verified VN network id 15 for VN cfe1d1fb-e6bf-48c1-bf36-9299770a2424
2025-08-11 07:33:36,549 - INFO - Verifications in API Server for VN ctest-vn2-82351956 passed
2025-08-11 07:33:36,549 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/cfe1d1fb-e6bf-48c1-bf36-9299770a2424
2025-08-11 07:33:36,560 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/ac9be2cf-1f03-4624-a91f-4edae62e16a6
2025-08-11 07:33:36,569 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/ac9be2cf-1f03-4624-a91f-4edae62e16a6
2025-08-11 07:33:36,577 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/7418610e-fec3-4a27-876d-9621c1e6550e
2025-08-11 07:33:36,589 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn2-82351956', 'interests': '0-1', 'advertised': '0-1', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-83596126', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14979484715449272513', 'uuid-lslong': '13778361297811416100'}, 'enable': 'true', 'created': '2025-08-11T07:32:49', 'last-modified': '2025-08-11T07:33:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.928492'}
2025-08-11 07:33:36,597 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-11 07:33:36,602 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn2-82351956', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-83596126', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14979484715449272513', 'uuid-lslong': '13778361297811416100'}, 'enable': 'true', 'created': '2025-08-11T07:32:49', 'last-modified': '2025-08-11T07:33:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.941569'}
2025-08-11 07:33:36,609 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-11 07:33:36,614 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn2-82351956', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-83596126', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14979484715449272513', 'uuid-lslong': '13778361297811416100'}, 'enable': 'true', 'created': '2025-08-11T07:32:49', 'last-modified': '2025-08-11T07:33:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.952711'}
2025-08-11 07:33:36,621 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-11 07:33:36,621 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn2-82351956 passed
2025-08-11 07:33:36,621 - DEBUG - ====Verifying policy data for ctest-vn2-82351956 in API_Server ======
2025-08-11 07:33:36,621 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:33:36,627 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:33:36,639 - DEBUG - Requesting: http://10.0.0.254:8082/project/5d5019ab-6bc5-494e-9e85-dd10a95c5237
2025-08-11 07:33:36,732 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/cfe1d1fb-e6bf-48c1-bf36-9299770a2424
2025-08-11 07:33:36,742 - DEBUG - ==>Verifying data for policy with id: deb51bfd-619e-412d-a6b1-b94c60dccb83, fqn: ['default-domain', 'ctest-AnalyticsTestSanityWithResource-83596126', 'policy1']
2025-08-11 07:33:36,742 - INFO - VN ctest-vn2-82351956 Policy verification: verify_vn_policy_in_api_server, status: True
2025-08-11 07:33:36,742 - DEBUG - Verifying the vn in opserver
2025-08-11 07:33:36,742 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn2-82351956 virtual network link through opserver 10.0.0.254
2025-08-11 07:33:36,742 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-11 07:33:36,754 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn2-82351956', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn2-82351956?flat'}
2025-08-11 07:33:36,754 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn2-82351956 virtual network link through opserver 10.0.0.38
2025-08-11 07:33:36,754 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-11 07:33:36,766 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn2-82351956', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn2-82351956?flat'}
2025-08-11 07:33:36,766 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn2-82351956 virtual network link through opserver 10.0.0.50
2025-08-11 07:33:36,766 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-08-11 07:33:36,777 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn2-82351956', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn2-82351956?flat'}
2025-08-11 07:33:36,777 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn2-82351956 is found in opserver
2025-08-11 07:33:36,789 - DEBUG - VRF ids for VN ctest-vn2-82351956: {'10.0.0.49': '2', '10.0.0.57': '3'}
2025-08-11 07:33:36,789 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:33:36,794 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:33:36,805 - DEBUG - Requesting: http://10.0.0.254:8082/project/5d5019ab-6bc5-494e-9e85-dd10a95c5237
2025-08-11 07:33:36,900 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/b0b15f92-0e11-4186-b6a4-8adacd2291a0
2025-08-11 07:33:36,912 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/b0b15f92-0e11-4186-b6a4-8adacd2291a0
2025-08-11 07:33:36,922 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/f58a86ef-86ab-4c14-b56c-cf014d36f2b1
2025-08-11 07:33:36,932 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/f58a86ef-86ab-4c14-b56c-cf014d36f2b1
2025-08-11 07:33:36,939 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/3ee71e43-3155-452b-b85c-4f8b44e496aa
2025-08-11 07:33:36,946 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 07:33:36,946 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/b0b15f92-0e11-4186-b6a4-8adacd2291a0
2025-08-11 07:33:36,956 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/f58a86ef-86ab-4c14-b56c-cf014d36f2b1
2025-08-11 07:33:36,971 - INFO - Verified VN network id 14 for VN b0b15f92-0e11-4186-b6a4-8adacd2291a0
2025-08-11 07:33:36,971 - INFO - Verifications in API Server for VN ctest-vn1-32168765 passed
2025-08-11 07:33:36,971 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/b0b15f92-0e11-4186-b6a4-8adacd2291a0
2025-08-11 07:33:36,982 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/f58a86ef-86ab-4c14-b56c-cf014d36f2b1
2025-08-11 07:33:36,991 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/f58a86ef-86ab-4c14-b56c-cf014d36f2b1
2025-08-11 07:33:36,999 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/3ee71e43-3155-452b-b85c-4f8b44e496aa
2025-08-11 07:33:37,012 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-83596126', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12732062702458978694', 'uuid-lslong': '13160796683432530336'}, 'enable': 'true', 'created': '2025-08-11T07:32:16', 'last-modified': '2025-08-11T07:33:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.513203'}
2025-08-11 07:33:37,019 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 07:33:37,025 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-83596126', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12732062702458978694', 'uuid-lslong': '13160796683432530336'}, 'enable': 'true', 'created': '2025-08-11T07:32:16', 'last-modified': '2025-08-11T07:33:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.526741'}
2025-08-11 07:33:37,031 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 07:33:37,037 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-83596126', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12732062702458978694', 'uuid-lslong': '13160796683432530336'}, 'enable': 'true', 'created': '2025-08-11T07:32:16', 'last-modified': '2025-08-11T07:33:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.539978'}
2025-08-11 07:33:37,043 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 07:33:37,043 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn1-32168765 passed
2025-08-11 07:33:37,043 - DEBUG - ====Verifying policy data for ctest-vn1-32168765 in API_Server ======
2025-08-11 07:33:37,043 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:33:37,049 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:33:37,061 - DEBUG - Requesting: http://10.0.0.254:8082/project/5d5019ab-6bc5-494e-9e85-dd10a95c5237
2025-08-11 07:33:37,152 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/b0b15f92-0e11-4186-b6a4-8adacd2291a0
2025-08-11 07:33:37,165 - DEBUG - ==>Verifying data for policy with id: deb51bfd-619e-412d-a6b1-b94c60dccb83, fqn: ['default-domain', 'ctest-AnalyticsTestSanityWithResource-83596126', 'policy1']
2025-08-11 07:33:37,165 - INFO - VN ctest-vn1-32168765 Policy verification: verify_vn_policy_in_api_server, status: True
2025-08-11 07:33:37,165 - DEBUG - Verifying the vn in opserver
2025-08-11 07:33:37,165 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765 virtual network link through opserver 10.0.0.254
2025-08-11 07:33:37,166 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-11 07:33:37,177 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765?flat'}
2025-08-11 07:33:37,177 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765 virtual network link through opserver 10.0.0.38
2025-08-11 07:33:37,177 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-11 07:33:37,190 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765?flat'}
2025-08-11 07:33:37,190 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765 virtual network link through opserver 10.0.0.50
2025-08-11 07:33:37,190 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-08-11 07:33:37,202 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765?flat'}
2025-08-11 07:33:37,202 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765 is found in opserver
2025-08-11 07:33:37,213 - DEBUG - VRF ids for VN ctest-vn1-32168765: {'10.0.0.57': '2'}
2025-08-11 07:33:37,214 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:33:37,219 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:33:37,231 - DEBUG - Requesting: http://10.0.0.254:8082/project/5d5019ab-6bc5-494e-9e85-dd10a95c5237
2025-08-11 07:33:37,322 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/cfe1d1fb-e6bf-48c1-bf36-9299770a2424
2025-08-11 07:33:37,334 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/cfe1d1fb-e6bf-48c1-bf36-9299770a2424
2025-08-11 07:33:37,343 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/ac9be2cf-1f03-4624-a91f-4edae62e16a6
2025-08-11 07:33:37,351 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/ac9be2cf-1f03-4624-a91f-4edae62e16a6
2025-08-11 07:33:37,358 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/7418610e-fec3-4a27-876d-9621c1e6550e
2025-08-11 07:33:37,364 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-11 07:33:37,364 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/cfe1d1fb-e6bf-48c1-bf36-9299770a2424
2025-08-11 07:33:37,373 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/ac9be2cf-1f03-4624-a91f-4edae62e16a6
2025-08-11 07:33:37,387 - INFO - Verified VN network id 15 for VN cfe1d1fb-e6bf-48c1-bf36-9299770a2424
2025-08-11 07:33:37,387 - INFO - Verifications in API Server for VN ctest-vn2-82351956 passed
2025-08-11 07:33:37,387 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/cfe1d1fb-e6bf-48c1-bf36-9299770a2424
2025-08-11 07:33:37,397 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/ac9be2cf-1f03-4624-a91f-4edae62e16a6
2025-08-11 07:33:37,404 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/ac9be2cf-1f03-4624-a91f-4edae62e16a6
2025-08-11 07:33:37,412 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/7418610e-fec3-4a27-876d-9621c1e6550e
2025-08-11 07:33:37,428 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn2-82351956', 'interests': '0-1', 'advertised': '0-1', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-83596126', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14979484715449272513', 'uuid-lslong': '13778361297811416100'}, 'enable': 'true', 'created': '2025-08-11T07:32:49', 'last-modified': '2025-08-11T07:33:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.765835'}
2025-08-11 07:33:37,438 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-11 07:33:37,444 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn2-82351956', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-83596126', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14979484715449272513', 'uuid-lslong': '13778361297811416100'}, 'enable': 'true', 'created': '2025-08-11T07:32:49', 'last-modified': '2025-08-11T07:33:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.782878'}
2025-08-11 07:33:37,450 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-11 07:33:37,456 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn2-82351956', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-83596126', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14979484715449272513', 'uuid-lslong': '13778361297811416100'}, 'enable': 'true', 'created': '2025-08-11T07:32:49', 'last-modified': '2025-08-11T07:33:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.793792'}
2025-08-11 07:33:37,462 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-11 07:33:37,462 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn2-82351956 passed
2025-08-11 07:33:37,462 - DEBUG - ====Verifying policy data for ctest-vn2-82351956 in API_Server ======
2025-08-11 07:33:37,462 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:33:37,469 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:33:37,480 - DEBUG - Requesting: http://10.0.0.254:8082/project/5d5019ab-6bc5-494e-9e85-dd10a95c5237
2025-08-11 07:33:37,576 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/cfe1d1fb-e6bf-48c1-bf36-9299770a2424
2025-08-11 07:33:37,590 - DEBUG - ==>Verifying data for policy with id: deb51bfd-619e-412d-a6b1-b94c60dccb83, fqn: ['default-domain', 'ctest-AnalyticsTestSanityWithResource-83596126', 'policy1']
2025-08-11 07:33:37,590 - INFO - VN ctest-vn2-82351956 Policy verification: verify_vn_policy_in_api_server, status: True
2025-08-11 07:33:37,591 - DEBUG - Verifying the vn in opserver
2025-08-11 07:33:37,591 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn2-82351956 virtual network link through opserver 10.0.0.254
2025-08-11 07:33:37,591 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-11 07:33:37,603 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn2-82351956', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn2-82351956?flat'}
2025-08-11 07:33:37,603 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn2-82351956 virtual network link through opserver 10.0.0.38
2025-08-11 07:33:37,603 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-11 07:33:37,614 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn2-82351956', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn2-82351956?flat'}
2025-08-11 07:33:37,614 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn2-82351956 virtual network link through opserver 10.0.0.50
2025-08-11 07:33:37,614 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-08-11 07:33:37,627 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn2-82351956', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn2-82351956?flat'}
2025-08-11 07:33:37,627 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn2-82351956 is found in opserver
2025-08-11 07:33:37,638 - DEBUG - VRF ids for VN ctest-vn2-82351956: {'10.0.0.49': '2', '10.0.0.57': '3'}
2025-08-11 07:33:37,638 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:33:37,644 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:33:37,655 - DEBUG - Requesting: http://10.0.0.254:8082/project/5d5019ab-6bc5-494e-9e85-dd10a95c5237
2025-08-11 07:33:37,750 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/84189f46-2adf-4c94-a122-89a144c877e6
2025-08-11 07:33:37,759 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/84189f46-2adf-4c94-a122-89a144c877e6
2025-08-11 07:33:37,768 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/c2ed8ab8-6698-44c2-8508-e04bad1b0451
2025-08-11 07:33:37,774 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/c2ed8ab8-6698-44c2-8508-e04bad1b0451
2025-08-11 07:33:37,781 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/56317a72-bc33-4795-bed3-4144f6aff5fb
2025-08-11 07:33:37,804 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-11 07:33:37,804 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/84189f46-2adf-4c94-a122-89a144c877e6
2025-08-11 07:33:37,814 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/c2ed8ab8-6698-44c2-8508-e04bad1b0451
2025-08-11 07:33:37,832 - INFO - Verified VN network id 16 for VN 84189f46-2adf-4c94-a122-89a144c877e6
2025-08-11 07:33:37,832 - INFO - Verifications in API Server for VN ctest-fip_vn-54233459 passed
2025-08-11 07:33:37,832 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/84189f46-2adf-4c94-a122-89a144c877e6
2025-08-11 07:33:37,844 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/c2ed8ab8-6698-44c2-8508-e04bad1b0451
2025-08-11 07:33:37,855 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/c2ed8ab8-6698-44c2-8508-e04bad1b0451
2025-08-11 07:33:37,863 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/56317a72-bc33-4795-bed3-4144f6aff5fb
2025-08-11 07:33:37,880 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-fip_vn-54233459', 'interests': '1', 'advertised': '1', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-83596126', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9518532936163347604', 'uuid-lslong': '11610994115051288550'}, 'enable': 'true', 'created': '2025-08-11T07:32:50', 'last-modified': '2025-08-11T07:32:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:47.199584'}
2025-08-11 07:33:37,890 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-11 07:33:37,898 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-fip_vn-54233459', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-83596126', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9518532936163347604', 'uuid-lslong': '11610994115051288550'}, 'enable': 'true', 'created': '2025-08-11T07:32:50', 'last-modified': '2025-08-11T07:32:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:47.217593'}
2025-08-11 07:33:37,905 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-11 07:33:37,910 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-fip_vn-54233459', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-83596126', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9518532936163347604', 'uuid-lslong': '11610994115051288550'}, 'enable': 'true', 'created': '2025-08-11T07:32:50', 'last-modified': '2025-08-11T07:32:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:47.232470'}
2025-08-11 07:33:37,917 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-11 07:33:37,917 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-fip_vn-54233459 passed
2025-08-11 07:33:37,918 - DEBUG - ====Verifying policy data for ctest-fip_vn-54233459 in API_Server ======
2025-08-11 07:33:37,918 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:33:37,925 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:33:37,938 - DEBUG - Requesting: http://10.0.0.254:8082/project/5d5019ab-6bc5-494e-9e85-dd10a95c5237
2025-08-11 07:33:38,032 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/84189f46-2adf-4c94-a122-89a144c877e6
2025-08-11 07:33:38,041 - DEBUG - =>VN ctest-fip_vn-54233459 has no policy to be verified
2025-08-11 07:33:38,041 - DEBUG - Verifying the vn in opserver
2025-08-11 07:33:38,041 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-fip_vn-54233459 virtual network link through opserver 10.0.0.254
2025-08-11 07:33:38,041 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-11 07:33:38,053 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-fip_vn-54233459', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-fip_vn-54233459?flat'}
2025-08-11 07:33:38,053 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-fip_vn-54233459 virtual network link through opserver 10.0.0.38
2025-08-11 07:33:38,053 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-11 07:33:38,064 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-fip_vn-54233459', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-fip_vn-54233459?flat'}
2025-08-11 07:33:38,064 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-fip_vn-54233459 virtual network link through opserver 10.0.0.50
2025-08-11 07:33:38,064 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-08-11 07:33:38,075 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-fip_vn-54233459', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-fip_vn-54233459?flat'}
2025-08-11 07:33:38,075 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-fip_vn-54233459 is found in opserver
2025-08-11 07:33:38,086 - DEBUG - VRF ids for VN ctest-fip_vn-54233459: {'10.0.0.49': '3'}
2025-08-11 07:33:38,086 - INFO - start time= 1754897618086351
2025-08-11 07:33:38,086 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 198.224.253.195, host_string: cirros@169.254.0.3, password: gocubsgogateway: ubuntu@10.0.0.57, gateway password: c0ntrail123
2025-08-11 07:33:38,086 - DEBUG - ping -s 56 -c 3 -W 1 198.224.253.195
2025-08-11 07:33:41,875 - DEBUG - PING 198.224.253.195 (198.224.253.195): 56 data bytes
64 bytes from 198.224.253.195: seq=0 ttl=63 time=7.635 ms
64 bytes from 198.224.253.195: seq=1 ttl=63 time=2.529 ms
64 bytes from 198.224.253.195: seq=2 ttl=63 time=1.082 ms
--- 198.224.253.195 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 1.082/3.748/7.635 ms
2025-08-11 07:33:41,875 - INFO - Ping to IP 198.224.253.195 from VM ctest-vn1_vm1-60071817 passed
2025-08-11 07:33:51,877 - INFO - Verify session samples and teardown pkts
2025-08-11 07:33:51,877 - INFO - Verifying SessionSeriesTable through opserver 10.0.0.254
2025-08-11 07:33:51,881 - DEBUG - Posting: http://10.0.0.254:8081/analytics/query, payload {'table': 'SessionSeriesTable', 'start_time': 1754897618086351, 'end_time': 1754897631881108, 'select_fields': ['T'], 'where': [[{'name': 'vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765', 'op': 1, 'value2': None}, {'name': 'remote_vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn2-82351956', 'op': 1, 'value2': None}, {'name': 'protocol', 'value': '1', 'op': 1, 'value2': None}]], 'session_type': 'client'}
2025-08-11 07:33:52,898 - DEBUG - [{'T': 1754897620639320}, {'T': 1754897621639403}, {'T': 1754897622639473}]
2025-08-11 07:33:52,898 - DEBUG - Posting: http://10.0.0.254:8081/analytics/query, payload {'table': 'SessionSeriesTable', 'start_time': 1754897618086351, 'end_time': 1754897632898459, 'select_fields': ['T'], 'where': [[{'name': 'vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn2-82351956', 'op': 1, 'value2': None}, {'name': 'remote_vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765', 'op': 1, 'value2': None}, {'name': 'protocol', 'value': '1', 'op': 1, 'value2': None}]], 'session_type': 'server'}
2025-08-11 07:33:53,917 - DEBUG - [{'T': 1754897620213470}, {'T': 1754897621213523}, {'T': 1754897622213592}]
2025-08-11 07:33:53,917 - DEBUG - Posting: http://10.0.0.254:8081/analytics/query, payload {'table': 'SessionSeriesTable', 'start_time': 1754897618086351, 'end_time': 1754897633917589, 'select_fields': ['vn', 'remote_vn', 'sample_count'], 'where': [[{'name': 'vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765', 'op': 1, 'value2': None}, {'name': 'remote_vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn2-82351956', 'op': 1, 'value2': None}, {'name': 'protocol', 'value': '1', 'op': 1, 'value2': None}]], 'session_type': 'client'}
2025-08-11 07:33:54,935 - DEBUG - [{'remote_vn': 'default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn2-82351956', 'vn': 'default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765', 'sample_count': 3}]
2025-08-11 07:33:54,935 - INFO - wait for the flows to get expire
2025-08-11 07:37:14,936 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3798-2:Compute:contrail-vrouter-agent:0?flat
2025-08-11 07:37:14,978 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3798-2?flat
2025-08-11 07:37:15,012 - DEBUG - Posting: http://10.0.0.254:8081/analytics/query, payload {'table': 'SessionRecordTable', 'start_time': 1754897618086351, 'end_time': 1754897835012136, 'select_fields': ['vn', 'remote_vn', 'forward_teardown_pkts', 'reverse_teardown_pkts'], 'where': [[{'name': 'vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765', 'op': 1, 'value2': None}, {'name': 'remote_vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn2-82351956', 'op': 1, 'value2': None}, {'name': 'protocol', 'value': '1', 'op': 1, 'value2': None}]], 'session_type': 'client'}
2025-08-11 07:37:16,031 - DEBUG - [{'forward_flow_uuid': '62ea8752-016a-4871-8638-dd7318b8c5a7', 'remote_vn': 'default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn2-82351956', 'reverse_flow_uuid': 'f2414020-4ab5-4c76-a37b-6980d21c8c53', 'vn': 'default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765', 'forward_teardown_pkts': 3, 'reverse_teardown_pkts': 3}]
2025-08-11 07:37:16,031 - DEBUG - Posting: http://10.0.0.254:8081/analytics/query, payload {'table': 'SessionSeriesTable', 'start_time': 1754897618086351, 'end_time': 1754897836031908, 'select_fields': ['vn', 'remote_vn', 'sample_count'], 'where': [[{'name': 'vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765', 'op': 1, 'value2': None}, {'name': 'remote_vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn2-82351956', 'op': 1, 'value2': None}, {'name': 'protocol', 'value': '1', 'op': 1, 'value2': None}]], 'session_type': 'client'}
2025-08-11 07:37:17,054 - DEBUG - Posting: http://10.0.0.254:8081/analytics/query, payload {'table': 'SessionSeriesTable', 'start_time': 1754897618086351, 'end_time': 1754897837053964, 'select_fields': ['vn', 'remote_vn', 'sample_count'], 'where': [[{'name': 'vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn2-82351956', 'op': 1, 'value2': None}, {'name': 'remote_vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765', 'op': 1, 'value2': None}, {'name': 'protocol', 'value': '1', 'op': 1, 'value2': None}]], 'session_type': 'server'}
2025-08-11 07:37:18,073 - DEBUG - [{'remote_vn': 'default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765', 'vn': 'default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn2-82351956', 'sample_count': 4}]
2025-08-11 07:37:18,518 - INFO - Deleted policy policy1
2025-08-11 07:37:18,668 - INFO - Setting flow export rate: None
2025-08-11 07:37:19,909 - DEBUG - Skipping xmpp flap check
2025-08-11 07:37:19,909 - INFO - END TEST : test_verify_session_sampling_teardown : PASSED[0:03:46]
2025-08-11 07:37:19,909 - INFO - --------------------------------------------------------------------------------
2025-08-11 07:37:19,910 - INFO - Deleting VM mltf_vm
2025-08-11 07:37:19,968 - INFO - Deleting VM ctest-fvn_vm1-09894592
2025-08-11 07:37:20,023 - INFO - Deleting VM ctest-vn2_vm2-95457406
2025-08-11 07:37:20,080 - INFO - Deleting VN ctest-fip_vn-54233459
2025-08-11 07:37:20,118 - DEBUG - VN 84189f46-2adf-4c94-a122-89a144c877e6 still in use: Unable to complete operation on network 84189f46-2adf-4c94-a122-89a144c877e6. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-3d9e9e0e-27df-4d42-b27b-3dee23db993f']
2025-08-11 07:37:20,118 - WARNING - Deleting VN ctest-fip_vn-54233459 failed..Will retry
2025-08-11 07:37:22,300 - DEBUG - Response for deleting network ()
2025-08-11 07:37:22,300 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/c2ed8ab8-6698-44c2-8508-e04bad1b0451
2025-08-11 07:37:22,325 - DEBUG - Response Code: 404
2025-08-11 07:37:22,325 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:37:22,330 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:37:22,391 - DEBUG - Requesting: http://10.0.0.254:8082/project/5d5019ab-6bc5-494e-9e85-dd10a95c5237
2025-08-11 07:37:22,489 - INFO - Validated that VN ctest-fip_vn-54233459 is not found in API Server
2025-08-11 07:37:22,500 - DEBUG - VN ctest-fip_vn-54233459 is not present in Agent 10.0.0.49
2025-08-11 07:37:22,512 - DEBUG - VN ctest-fip_vn-54233459 is not present in Agent 10.0.0.57
2025-08-11 07:37:22,512 - INFO - Validated that VN ctest-fip_vn-54233459 is not in any agent
2025-08-11 07:37:22,518 - DEBUG - VRF 3 is not seen in agent 10.0.0.49
2025-08-11 07:37:22,523 - DEBUG - Vrouter 10.0.0.49 does not have vrf 3 for VN ctest-fip_vn-54233459
2025-08-11 07:37:22,523 - INFO - Validated that all vrouters do not have the route table for VN default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-fip_vn-54233459
2025-08-11 07:37:22,547 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-fip_vn-54233459 info
2025-08-11 07:37:22,547 - INFO - Deleting VN ctest-vn2-82351956
2025-08-11 07:37:22,711 - DEBUG - Response for deleting network ()
2025-08-11 07:37:22,711 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/ac9be2cf-1f03-4624-a91f-4edae62e16a6
2025-08-11 07:37:22,717 - DEBUG - Response Code: 404
2025-08-11 07:37:22,717 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:37:22,723 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:37:22,735 - DEBUG - Requesting: http://10.0.0.254:8082/project/5d5019ab-6bc5-494e-9e85-dd10a95c5237
2025-08-11 07:37:22,824 - INFO - Validated that VN ctest-vn2-82351956 is not found in API Server
2025-08-11 07:37:22,835 - DEBUG - VN ctest-vn2-82351956 is not present in Agent 10.0.0.49
2025-08-11 07:37:22,845 - DEBUG - VN ctest-vn2-82351956 is not present in Agent 10.0.0.57
2025-08-11 07:37:22,845 - INFO - Validated that VN ctest-vn2-82351956 is not in any agent
2025-08-11 07:37:22,849 - DEBUG - VRF 2 is not seen in agent 10.0.0.49
2025-08-11 07:37:22,854 - DEBUG - Vrouter 10.0.0.49 does not have vrf 2 for VN ctest-vn2-82351956
2025-08-11 07:37:22,860 - DEBUG - VRF 3 is not seen in agent 10.0.0.57
2025-08-11 07:37:22,865 - DEBUG - Vrouter 10.0.0.57 does not have vrf 3 for VN ctest-vn2-82351956
2025-08-11 07:37:22,865 - INFO - Validated that all vrouters do not have the route table for VN default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn2-82351956
2025-08-11 07:37:22,885 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn2-82351956 info
2025-08-11 07:37:22,885 - INFO - Deleting VM ctest-vn1_vm2-74713373
2025-08-11 07:37:22,941 - INFO - Deleting VM ctest-vn1_vm1-60071817
2025-08-11 07:37:22,996 - INFO - Deleting VN ctest-vn1-32168765
2025-08-11 07:37:23,032 - DEBUG - VN b0b15f92-0e11-4186-b6a4-8adacd2291a0 still in use: Unable to complete operation on network b0b15f92-0e11-4186-b6a4-8adacd2291a0. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-dc1489ff-fa74-4e2c-9254-ee4a72f07168']
2025-08-11 07:37:23,033 - WARNING - Deleting VN ctest-vn1-32168765 failed..Will retry
2025-08-11 07:37:25,167 - DEBUG - Response for deleting network ()
2025-08-11 07:37:25,168 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/f58a86ef-86ab-4c14-b56c-cf014d36f2b1
2025-08-11 07:37:25,181 - DEBUG - Response Code: 404
2025-08-11 07:37:25,181 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:37:25,190 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:37:25,250 - DEBUG - Requesting: http://10.0.0.254:8082/project/5d5019ab-6bc5-494e-9e85-dd10a95c5237
2025-08-11 07:37:25,336 - INFO - Validated that VN ctest-vn1-32168765 is not found in API Server
2025-08-11 07:37:25,347 - DEBUG - VN ctest-vn1-32168765 is not present in Agent 10.0.0.49
2025-08-11 07:37:25,358 - DEBUG - VN ctest-vn1-32168765 is not present in Agent 10.0.0.57
2025-08-11 07:37:25,358 - INFO - Validated that VN ctest-vn1-32168765 is not in any agent
2025-08-11 07:37:25,363 - DEBUG - VRF 2 is not seen in agent 10.0.0.57
2025-08-11 07:37:25,369 - DEBUG - Vrouter 10.0.0.57 does not have vrf 2 for VN ctest-vn1-32168765
2025-08-11 07:37:25,369 - INFO - Validated that all vrouters do not have the route table for VN default-domain:ctest-AnalyticsTestSanityWithResource-83596126:ctest-vn1-32168765
2025-08-11 07:37:25,389 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn1-32168765 info
2025-08-11 07:37:26,091 - INFO - Deleted project: ctest-AnalyticsTestSanityWithResource-83596126, ID : 5d5019ab-6bc5-494e-9e85-dd10a95c5237