2025-08-07 03:51:43,262 - INFO - Domain Default found not creating
2025-08-07 03:51:43,433 - INFO - Project ctest-AnalyticsTestSanityWithResource-52543708 not found, creating it
2025-08-07 03:51:44,069 - INFO - Created Project:ctest-AnalyticsTestSanityWithResource-52543708, ID : e750dce5-6593-495f-a498-7395bff3b2dd
2025-08-07 03:51:46,626 - DEBUG - Response for create_network : {'network': {'id': '86dfcaa9-5861-472a-be9d-3232f6e3d340', 'name': 'ctest-vn1-76278277', 'tenant_id': 'e750dce56593495fa4987395bff3b2dd', 'project_id': 'e750dce56593495fa4987395bff3b2dd', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-AnalyticsTestSanityWithResource-52543708', 'ctest-vn1-76278277'], 'port_security_enabled': True, 'description': ''}}
2025-08-07 03:51:46,919 - DEBUG - Response for create_subnet : {'subnet': {'id': 'eaf77d94-f3e1-4e0c-bac8-101ee189b394', 'name': '', 'tenant_id': 'e750dce56593495fa4987395bff3b2dd', 'network_id': '86dfcaa9-5861-472a-be9d-3232f6e3d340', 'ip_version': 4, 'cidr': '5.15.152.192/26', 'allocation_pools': [{'start': '5.15.152.194', 'end': '5.15.152.254'}], 'gateway_ip': '5.15.152.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '5.15.152.194', 'tags': [], 'project_id': 'e750dce56593495fa4987395bff3b2dd'}}
2025-08-07 03:51:46,951 - INFO - Created VN ctest-vn1-76278277
2025-08-07 03:51:46,968 - DEBUG - VN ctest-vn1-76278277 UUID is 86dfcaa9-5861-472a-be9d-3232f6e3d340
2025-08-07 03:51:47,102 - DEBUG - Services list from nova: [, , , ]
2025-08-07 03:51:47,102 - DEBUG - Hosts: {'nova': ['an-jenkins-deploy-platform-ansible-os-3767-2', 'an-jenkins-deploy-platform-ansible-os-3767-1']}
2025-08-07 03:51:48,448 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3767-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3767-2)
2025-08-07 03:51:49,963 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3767-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3767-2)
2025-08-07 03:51:49,963 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:51:50,291 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:51:50,338 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:51:50,363 - DEBUG - Requesting: http://10.0.0.56:8082/project/e750dce5-6593-495f-a498-7395bff3b2dd
2025-08-07 03:51:50,520 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/86dfcaa9-5861-472a-be9d-3232f6e3d340
2025-08-07 03:51:50,537 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/86dfcaa9-5861-472a-be9d-3232f6e3d340
2025-08-07 03:51:50,548 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e70612e3-8778-48cd-badf-80905eabdc5c
2025-08-07 03:51:50,560 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e70612e3-8778-48cd-badf-80905eabdc5c
2025-08-07 03:51:50,568 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/8810dbb5-1519-48dd-8bad-fa535a465593
2025-08-07 03:51:50,597 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-07 03:51:50,597 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/86dfcaa9-5861-472a-be9d-3232f6e3d340
2025-08-07 03:51:50,620 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e70612e3-8778-48cd-badf-80905eabdc5c
2025-08-07 03:51:50,649 - INFO - Verified VN network id 12 for VN 86dfcaa9-5861-472a-be9d-3232f6e3d340
2025-08-07 03:51:50,650 - INFO - Verifications in API Server for VN ctest-vn1-76278277 passed
2025-08-07 03:51:50,650 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/86dfcaa9-5861-472a-be9d-3232f6e3d340
2025-08-07 03:51:50,689 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e70612e3-8778-48cd-badf-80905eabdc5c
2025-08-07 03:51:50,702 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e70612e3-8778-48cd-badf-80905eabdc5c
2025-08-07 03:51:50,720 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/8810dbb5-1519-48dd-8bad-fa535a465593
2025-08-07 03:51:50,740 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9718709349569873706', 'uuid-lslong': '13735189633021760320'}, 'enable': 'true', 'created': '2025-08-07T03:51:46', 'last-modified': '2025-08-07T03:51:46', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.897424'}
2025-08-07 03:51:50,753 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-07 03:51:50,773 - DEBUG - Control-node 10.0.0.130 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9718709349569873706', 'uuid-lslong': '13735189633021760320'}, 'enable': 'true', 'created': '2025-08-07T03:51:46', 'last-modified': '2025-08-07T03:51:46', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.929207'}
2025-08-07 03:51:50,791 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-07 03:51:50,798 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9718709349569873706', 'uuid-lslong': '13735189633021760320'}, 'enable': 'true', 'created': '2025-08-07T03:51:46', 'last-modified': '2025-08-07T03:51:46', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.950339'}
2025-08-07 03:51:50,807 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-07 03:51:50,807 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn1-76278277 passed
2025-08-07 03:51:50,807 - DEBUG - ====Verifying policy data for ctest-vn1-76278277 in API_Server ======
2025-08-07 03:51:50,808 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:51:50,821 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:51:50,842 - DEBUG - Requesting: http://10.0.0.56:8082/project/e750dce5-6593-495f-a498-7395bff3b2dd
2025-08-07 03:51:50,950 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/86dfcaa9-5861-472a-be9d-3232f6e3d340
2025-08-07 03:51:50,978 - DEBUG - =>VN ctest-vn1-76278277 has no policy to be verified
2025-08-07 03:51:50,978 - DEBUG - Verifying the vn in opserver
2025-08-07 03:51:50,978 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277 virtual network link through opserver 10.0.0.56
2025-08-07 03:51:50,978 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-08-07 03:51:51,352 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-08-07 03:51:51,422 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277?flat'}
2025-08-07 03:51:51,422 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277 virtual network link through opserver 10.0.0.130
2025-08-07 03:51:51,422 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-networks
2025-08-07 03:51:51,737 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-networks
2025-08-07 03:51:51,803 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'href': 'http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277?flat'}
2025-08-07 03:51:51,803 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277 virtual network link through opserver 10.0.0.249
2025-08-07 03:51:51,804 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-08-07 03:51:52,132 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-08-07 03:51:52,206 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277?flat'}
2025-08-07 03:51:52,207 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277 is found in opserver
2025-08-07 03:51:52,221 - DEBUG - VRF ids for VN ctest-vn1-76278277: {'10.0.0.61': '2'}
2025-08-07 03:51:52,221 - INFO - Waiting for VM ctest-vn1_vm1-14989368 to be up..
2025-08-07 03:51:52,317 - DEBUG - VM is in ACTIVE state now
2025-08-07 03:51:52,317 - INFO - VM name : ctest-vn1_vm1-14989368
2025-08-07 03:51:52,417 - DEBUG - VM ctest-vn1_vm1-14989368 ID is a0055b1f-a79e-496d-9969-9f68d0ecc36b
2025-08-07 03:51:52,450 - DEBUG - VM ctest-vn1_vm1-14989368 launched on Node an-jenkins-deploy-platform-ansible-os-3767-2
2025-08-07 03:51:52,554 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/a0055b1f-a79e-496d-9969-9f68d0ecc36b
2025-08-07 03:51:52,887 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/a0055b1f-a79e-496d-9969-9f68d0ecc36b
2025-08-07 03:51:52,928 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/9df64ffa-0ba6-43ec-a6ec-0605b6bf6f8d
2025-08-07 03:51:56,193 - 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 1025ms')
2025-08-07 03:51:56,193 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1-14989368 failed!
2025-08-07 03:51:56,214 - DEBUG - Gateway for vn default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277 is 5.15.152.193 and allocation pool is NOT set
2025-08-07 03:52:00,286 - 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 1011ms')
2025-08-07 03:52:00,286 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1-14989368 failed!
2025-08-07 03:52:00,305 - DEBUG - Gateway for vn default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277 is 5.15.152.193 and allocation pool is NOT set
2025-08-07 03:52:04,383 - 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-07 03:52:04,383 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1-14989368 failed!
2025-08-07 03:52:04,402 - DEBUG - Gateway for vn default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277 is 5.15.152.193 and allocation pool is NOT set
2025-08-07 03:52:08,477 - 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 1014ms')
2025-08-07 03:52:08,477 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1-14989368 failed!
2025-08-07 03:52:08,496 - DEBUG - Gateway for vn default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277 is 5.15.152.193 and allocation pool is NOT set
2025-08-07 03:52:10,557 - 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=4.62 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.401 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.401/2.512/4.624/2.111 ms')
2025-08-07 03:52:10,558 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1-14989368 passed
2025-08-07 03:52:10,684 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-07 03:52:10,684 - DEBUG - Waiting to SSH to VM ctest-vn1_vm1-14989368, IP 5.15.152.195, Port 22
2025-08-07 03:52:10,752 - 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-07 03:52:10,844 - DEBUG - VM ctest-vn1_vm1-14989368 is NOT ready for SSH connections, VM status: ACTIVE
2025-08-07 03:52:15,845 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-07 03:52:15,845 - DEBUG - Waiting to SSH to VM ctest-vn1_vm1-14989368, IP 5.15.152.195, Port 22
2025-08-07 03:52:16,005 - DEBUG - VM ctest-vn1_vm1-14989368 is ready for SSH connections
2025-08-07 03:52:16,006 - INFO - Waiting for VM ctest-vn1_vm2-91156958 to be up..
2025-08-07 03:52:16,103 - DEBUG - VM is in ACTIVE state now
2025-08-07 03:52:16,103 - INFO - VM name : ctest-vn1_vm2-91156958
2025-08-07 03:52:16,202 - DEBUG - VM ctest-vn1_vm2-91156958 ID is 0ad3f731-717d-4ac8-b75b-a90b3db70dfd
2025-08-07 03:52:16,202 - DEBUG - VM ctest-vn1_vm2-91156958 launched on Node an-jenkins-deploy-platform-ansible-os-3767-2
2025-08-07 03:52:16,306 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/0ad3f731-717d-4ac8-b75b-a90b3db70dfd
2025-08-07 03:52:16,318 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/5bd467ee-5a69-4c59-ab65-c06c87e7cc36
2025-08-07 03:52:17,545 - 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=3.75 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=3.62 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 = 3.621/3.685/3.749/0.064 ms')
2025-08-07 03:52:17,545 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-vn1_vm2-91156958 passed
2025-08-07 03:52:17,670 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-07 03:52:17,670 - DEBUG - Waiting to SSH to VM ctest-vn1_vm2-91156958, IP 5.15.152.196, Port 22
2025-08-07 03:52:17,736 - 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-07 03:52:17,842 - DEBUG - VM ctest-vn1_vm2-91156958 is NOT ready for SSH connections, VM status: ACTIVE
2025-08-07 03:52:22,843 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-07 03:52:22,843 - DEBUG - Waiting to SSH to VM ctest-vn1_vm2-91156958, IP 5.15.152.196, Port 22
2025-08-07 03:52:23,016 - DEBUG - VM ctest-vn1_vm2-91156958 is ready for SSH connections
2025-08-07 03:52:23,378 - DEBUG - Response for create_network : {'network': {'id': '162e47c8-8920-4611-b29b-be9a65dd870c', 'name': 'ctest-vn2-75786057', 'tenant_id': 'e750dce56593495fa4987395bff3b2dd', 'project_id': 'e750dce56593495fa4987395bff3b2dd', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-AnalyticsTestSanityWithResource-52543708', 'ctest-vn2-75786057'], 'port_security_enabled': True, 'description': ''}}
2025-08-07 03:52:23,685 - DEBUG - Response for create_subnet : {'subnet': {'id': '495c77ec-a577-446f-866c-99fd951841eb', 'name': '', 'tenant_id': 'e750dce56593495fa4987395bff3b2dd', 'network_id': '162e47c8-8920-4611-b29b-be9a65dd870c', 'ip_version': 4, 'cidr': '3.231.40.64/26', 'allocation_pools': [{'start': '3.231.40.66', 'end': '3.231.40.126'}], 'gateway_ip': '3.231.40.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '3.231.40.66', 'tags': [], 'project_id': 'e750dce56593495fa4987395bff3b2dd'}}
2025-08-07 03:52:23,720 - INFO - Created VN ctest-vn2-75786057
2025-08-07 03:52:23,742 - DEBUG - VN ctest-vn2-75786057 UUID is 162e47c8-8920-4611-b29b-be9a65dd870c
2025-08-07 03:52:24,266 - DEBUG - Response for create_network : {'network': {'id': '2e571d31-de3d-4b1a-8f57-2e1cdffe401c', 'name': 'ctest-fip_vn-23932101', 'tenant_id': 'e750dce56593495fa4987395bff3b2dd', 'project_id': 'e750dce56593495fa4987395bff3b2dd', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-AnalyticsTestSanityWithResource-52543708', 'ctest-fip_vn-23932101'], 'port_security_enabled': True, 'description': ''}}
2025-08-07 03:52:24,577 - DEBUG - Response for create_subnet : {'subnet': {'id': '74d3728d-7388-464f-9648-a3aabfe148bd', 'name': '', 'tenant_id': 'e750dce56593495fa4987395bff3b2dd', 'network_id': '2e571d31-de3d-4b1a-8f57-2e1cdffe401c', 'ip_version': 4, 'cidr': '134.218.181.192/26', 'allocation_pools': [{'start': '134.218.181.194', 'end': '134.218.181.254'}], 'gateway_ip': '134.218.181.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '134.218.181.194', 'tags': [], 'project_id': 'e750dce56593495fa4987395bff3b2dd'}}
2025-08-07 03:52:24,604 - INFO - Created VN ctest-fip_vn-23932101
2025-08-07 03:52:24,631 - DEBUG - VN ctest-fip_vn-23932101 UUID is 2e571d31-de3d-4b1a-8f57-2e1cdffe401c
2025-08-07 03:52:24,743 - DEBUG - Hosts: {'nova': ['an-jenkins-deploy-platform-ansible-os-3767-2', 'an-jenkins-deploy-platform-ansible-os-3767-1']}
2025-08-07 03:52:26,244 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3767-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3767-1)
2025-08-07 03:52:28,050 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3767-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3767-1)
2025-08-07 03:52:29,953 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3767-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3767-2)
2025-08-07 03:52:29,954 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:52:29,962 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:52:29,988 - DEBUG - Requesting: http://10.0.0.56:8082/project/e750dce5-6593-495f-a498-7395bff3b2dd
2025-08-07 03:52:30,093 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/86dfcaa9-5861-472a-be9d-3232f6e3d340
2025-08-07 03:52:30,109 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/86dfcaa9-5861-472a-be9d-3232f6e3d340
2025-08-07 03:52:30,122 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e70612e3-8778-48cd-badf-80905eabdc5c
2025-08-07 03:52:30,133 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e70612e3-8778-48cd-badf-80905eabdc5c
2025-08-07 03:52:30,146 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/8810dbb5-1519-48dd-8bad-fa535a465593
2025-08-07 03:52:30,164 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-07 03:52:30,164 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/86dfcaa9-5861-472a-be9d-3232f6e3d340
2025-08-07 03:52:30,179 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e70612e3-8778-48cd-badf-80905eabdc5c
2025-08-07 03:52:30,202 - INFO - Verified VN network id 12 for VN 86dfcaa9-5861-472a-be9d-3232f6e3d340
2025-08-07 03:52:30,203 - INFO - Verifications in API Server for VN ctest-vn1-76278277 passed
2025-08-07 03:52:30,203 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/86dfcaa9-5861-472a-be9d-3232f6e3d340
2025-08-07 03:52:30,225 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e70612e3-8778-48cd-badf-80905eabdc5c
2025-08-07 03:52:30,237 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e70612e3-8778-48cd-badf-80905eabdc5c
2025-08-07 03:52:30,251 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/8810dbb5-1519-48dd-8bad-fa535a465593
2025-08-07 03:52:30,277 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9718709349569873706', 'uuid-lslong': '13735189633021760320'}, 'enable': 'true', 'created': '2025-08-07T03:51:46', 'last-modified': '2025-08-07T03:51:46', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:43.429322'}
2025-08-07 03:52:30,295 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-07 03:52:30,315 - DEBUG - Control-node 10.0.0.130 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9718709349569873706', 'uuid-lslong': '13735189633021760320'}, 'enable': 'true', 'created': '2025-08-07T03:51:46', 'last-modified': '2025-08-07T03:51:46', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:43.472713'}
2025-08-07 03:52:30,327 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-07 03:52:30,339 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9718709349569873706', 'uuid-lslong': '13735189633021760320'}, 'enable': 'true', 'created': '2025-08-07T03:51:46', 'last-modified': '2025-08-07T03:51:46', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:43.490892'}
2025-08-07 03:52:30,347 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-07 03:52:30,347 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn1-76278277 passed
2025-08-07 03:52:30,348 - DEBUG - ====Verifying policy data for ctest-vn1-76278277 in API_Server ======
2025-08-07 03:52:30,348 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:52:30,358 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:52:30,377 - DEBUG - Requesting: http://10.0.0.56:8082/project/e750dce5-6593-495f-a498-7395bff3b2dd
2025-08-07 03:52:30,510 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/86dfcaa9-5861-472a-be9d-3232f6e3d340
2025-08-07 03:52:30,527 - DEBUG - =>VN ctest-vn1-76278277 has no policy to be verified
2025-08-07 03:52:30,527 - DEBUG - Verifying the vn in opserver
2025-08-07 03:52:30,528 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277 virtual network link through opserver 10.0.0.56
2025-08-07 03:52:30,528 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-08-07 03:52:30,613 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277?flat'}
2025-08-07 03:52:30,613 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277 virtual network link through opserver 10.0.0.130
2025-08-07 03:52:30,614 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-networks
2025-08-07 03:52:30,778 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'href': 'http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277?flat'}
2025-08-07 03:52:30,778 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277 virtual network link through opserver 10.0.0.249
2025-08-07 03:52:30,778 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-08-07 03:52:30,907 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277?flat'}
2025-08-07 03:52:30,907 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277 is found in opserver
2025-08-07 03:52:30,925 - DEBUG - VRF ids for VN ctest-vn1-76278277: {'10.0.0.61': '2'}
2025-08-07 03:52:30,925 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:52:30,932 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:52:30,985 - DEBUG - Requesting: http://10.0.0.56:8082/project/e750dce5-6593-495f-a498-7395bff3b2dd
2025-08-07 03:52:31,065 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/162e47c8-8920-4611-b29b-be9a65dd870c
2025-08-07 03:52:31,085 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/162e47c8-8920-4611-b29b-be9a65dd870c
2025-08-07 03:52:31,103 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6d42434f-997b-4f8a-b65d-895b112183df
2025-08-07 03:52:31,120 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6d42434f-997b-4f8a-b65d-895b112183df
2025-08-07 03:52:31,153 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/5576ea6c-9c3a-49fa-82a7-e16d5b7dd38b
2025-08-07 03:52:31,166 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-07 03:52:31,166 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/162e47c8-8920-4611-b29b-be9a65dd870c
2025-08-07 03:52:31,186 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6d42434f-997b-4f8a-b65d-895b112183df
2025-08-07 03:52:31,224 - INFO - Verified VN network id 13 for VN 162e47c8-8920-4611-b29b-be9a65dd870c
2025-08-07 03:52:31,225 - INFO - Verifications in API Server for VN ctest-vn2-75786057 passed
2025-08-07 03:52:31,225 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/162e47c8-8920-4611-b29b-be9a65dd870c
2025-08-07 03:52:31,262 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6d42434f-997b-4f8a-b65d-895b112183df
2025-08-07 03:52:31,285 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6d42434f-997b-4f8a-b65d-895b112183df
2025-08-07 03:52:31,315 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/5576ea6c-9c3a-49fa-82a7-e16d5b7dd38b
2025-08-07 03:52:31,348 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1598293844382729745', 'uuid-lslong': '12870089930484582156'}, 'enable': 'true', 'created': '2025-08-07T03:52:23', 'last-modified': '2025-08-07T03:52:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:07.741038'}
2025-08-07 03:52:31,359 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-07 03:52:31,388 - DEBUG - Control-node 10.0.0.130 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1598293844382729745', 'uuid-lslong': '12870089930484582156'}, 'enable': 'true', 'created': '2025-08-07T03:52:23', 'last-modified': '2025-08-07T03:52:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:07.768508'}
2025-08-07 03:52:31,399 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-07 03:52:31,407 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1598293844382729745', 'uuid-lslong': '12870089930484582156'}, 'enable': 'true', 'created': '2025-08-07T03:52:23', 'last-modified': '2025-08-07T03:52:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:07.796460'}
2025-08-07 03:52:31,416 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-07 03:52:31,416 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn2-75786057 passed
2025-08-07 03:52:31,416 - DEBUG - ====Verifying policy data for ctest-vn2-75786057 in API_Server ======
2025-08-07 03:52:31,416 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:52:31,426 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:52:31,443 - DEBUG - Requesting: http://10.0.0.56:8082/project/e750dce5-6593-495f-a498-7395bff3b2dd
2025-08-07 03:52:31,551 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/162e47c8-8920-4611-b29b-be9a65dd870c
2025-08-07 03:52:31,589 - DEBUG - =>VN ctest-vn2-75786057 has no policy to be verified
2025-08-07 03:52:31,590 - DEBUG - Verifying the vn in opserver
2025-08-07 03:52:31,590 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057 virtual network link through opserver 10.0.0.56
2025-08-07 03:52:31,590 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-08-07 03:52:31,630 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057?flat'}
2025-08-07 03:52:31,630 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057 virtual network link through opserver 10.0.0.130
2025-08-07 03:52:31,630 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-networks
2025-08-07 03:52:31,766 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'href': 'http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057?flat'}
2025-08-07 03:52:31,766 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057 virtual network link through opserver 10.0.0.249
2025-08-07 03:52:31,766 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-08-07 03:52:31,791 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057?flat'}
2025-08-07 03:52:31,791 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057 is found in opserver
2025-08-07 03:52:31,814 - DEBUG - VRF ids for VN ctest-vn2-75786057: {'10.0.0.41': '2'}
2025-08-07 03:52:31,814 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:52:31,836 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:52:31,868 - DEBUG - Requesting: http://10.0.0.56:8082/project/e750dce5-6593-495f-a498-7395bff3b2dd
2025-08-07 03:52:31,971 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/2e571d31-de3d-4b1a-8f57-2e1cdffe401c
2025-08-07 03:52:31,991 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/2e571d31-de3d-4b1a-8f57-2e1cdffe401c
2025-08-07 03:52:32,008 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/a103d4df-1e11-4145-920a-baa03016b822
2025-08-07 03:52:32,027 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/a103d4df-1e11-4145-920a-baa03016b822
2025-08-07 03:52:32,044 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/47dce227-58af-481e-9897-d2c61de06498
2025-08-07 03:52:32,058 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-07 03:52:32,058 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/2e571d31-de3d-4b1a-8f57-2e1cdffe401c
2025-08-07 03:52:32,076 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/a103d4df-1e11-4145-920a-baa03016b822
2025-08-07 03:52:32,111 - INFO - Verified VN network id 14 for VN 2e571d31-de3d-4b1a-8f57-2e1cdffe401c
2025-08-07 03:52:32,111 - INFO - Verifications in API Server for VN ctest-fip_vn-23932101 passed
2025-08-07 03:52:32,112 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/2e571d31-de3d-4b1a-8f57-2e1cdffe401c
2025-08-07 03:52:32,130 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/a103d4df-1e11-4145-920a-baa03016b822
2025-08-07 03:52:32,153 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/a103d4df-1e11-4145-920a-baa03016b822
2025-08-07 03:52:32,178 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/47dce227-58af-481e-9897-d2c61de06498
2025-08-07 03:52:32,195 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3339169748737674010', 'uuid-lslong': '10328774971949465628'}, 'enable': 'true', 'created': '2025-08-07T03:52:24', 'last-modified': '2025-08-07T03:52:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:07.691749'}
2025-08-07 03:52:32,207 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-07 03:52:32,214 - DEBUG - Control-node 10.0.0.130 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3339169748737674010', 'uuid-lslong': '10328774971949465628'}, 'enable': 'true', 'created': '2025-08-07T03:52:24', 'last-modified': '2025-08-07T03:52:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:07.711760'}
2025-08-07 03:52:32,223 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-07 03:52:32,235 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3339169748737674010', 'uuid-lslong': '10328774971949465628'}, 'enable': 'true', 'created': '2025-08-07T03:52:24', 'last-modified': '2025-08-07T03:52:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:07.735776'}
2025-08-07 03:52:32,245 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-07 03:52:32,245 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-fip_vn-23932101 passed
2025-08-07 03:52:32,246 - DEBUG - ====Verifying policy data for ctest-fip_vn-23932101 in API_Server ======
2025-08-07 03:52:32,246 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:52:32,253 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:52:32,277 - DEBUG - Requesting: http://10.0.0.56:8082/project/e750dce5-6593-495f-a498-7395bff3b2dd
2025-08-07 03:52:32,382 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/2e571d31-de3d-4b1a-8f57-2e1cdffe401c
2025-08-07 03:52:32,397 - DEBUG - =>VN ctest-fip_vn-23932101 has no policy to be verified
2025-08-07 03:52:32,398 - DEBUG - Verifying the vn in opserver
2025-08-07 03:52:32,398 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101 virtual network link through opserver 10.0.0.56
2025-08-07 03:52:32,398 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-08-07 03:52:32,463 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101?flat'}
2025-08-07 03:52:32,464 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101 virtual network link through opserver 10.0.0.130
2025-08-07 03:52:32,464 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-networks
2025-08-07 03:52:32,484 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101', 'href': 'http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101?flat'}
2025-08-07 03:52:32,484 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101 virtual network link through opserver 10.0.0.249
2025-08-07 03:52:32,484 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-08-07 03:52:32,557 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101?flat'}
2025-08-07 03:52:32,557 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101 is found in opserver
2025-08-07 03:52:32,575 - DEBUG - VRF ids for VN ctest-fip_vn-23932101: {'10.0.0.41': '3'}
2025-08-07 03:52:32,575 - INFO - Waiting for VM ctest-fvn_vm1-00268763 to be up..
2025-08-07 03:52:32,675 - DEBUG - VM is in ACTIVE state now
2025-08-07 03:52:32,675 - INFO - VM name : ctest-fvn_vm1-00268763
2025-08-07 03:52:32,765 - DEBUG - VM ctest-fvn_vm1-00268763 ID is 0212b60c-0e7e-4423-b203-6e95461e9538
2025-08-07 03:52:32,766 - DEBUG - VM ctest-fvn_vm1-00268763 launched on Node an-jenkins-deploy-platform-ansible-os-3767-1
2025-08-07 03:52:32,868 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/0212b60c-0e7e-4423-b203-6e95461e9538
2025-08-07 03:52:32,881 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/f4511102-a2bd-4bc4-a055-340656d02fc1
2025-08-07 03:52:36,125 - 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 1013ms')
2025-08-07 03:52:36,126 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-fvn_vm1-00268763 failed!
2025-08-07 03:52:36,146 - DEBUG - Gateway for vn default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101 is 134.218.181.193 and allocation pool is NOT set
2025-08-07 03:52:40,228 - 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 1009ms')
2025-08-07 03:52:40,229 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-fvn_vm1-00268763 failed!
2025-08-07 03:52:40,249 - DEBUG - Gateway for vn default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101 is 134.218.181.193 and allocation pool is NOT set
2025-08-07 03:52:44,320 - 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-07 03:52:44,320 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-fvn_vm1-00268763 failed!
2025-08-07 03:52:44,339 - DEBUG - Gateway for vn default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101 is 134.218.181.193 and allocation pool is NOT set
2025-08-07 03:52:48,425 - 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 1018ms')
2025-08-07 03:52:48,425 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-fvn_vm1-00268763 failed!
2025-08-07 03:52:48,443 - DEBUG - Gateway for vn default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101 is 134.218.181.193 and allocation pool is NOT set
2025-08-07 03:52:52,545 - 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 1032ms')
2025-08-07 03:52:52,546 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-fvn_vm1-00268763 failed!
2025-08-07 03:52:52,565 - DEBUG - Gateway for vn default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101 is 134.218.181.193 and allocation pool is NOT set
2025-08-07 03:52:56,639 - 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=2 ttl=63 time=10.4 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 1 received, 50% packet loss, time 1012ms\r\nrtt min/avg/max/mdev = 10.430/10.430/10.430/0.000 ms')
2025-08-07 03:52:56,639 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-fvn_vm1-00268763 passed
2025-08-07 03:52:56,762 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-07 03:52:56,762 - DEBUG - Waiting to SSH to VM ctest-fvn_vm1-00268763, IP 134.218.181.195, Port 22
2025-08-07 03:52:56,833 - 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-07 03:52:56,937 - DEBUG - VM ctest-fvn_vm1-00268763 is NOT ready for SSH connections, VM status: ACTIVE
2025-08-07 03:53:01,938 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-07 03:53:01,938 - DEBUG - Waiting to SSH to VM ctest-fvn_vm1-00268763, IP 134.218.181.195, Port 22
2025-08-07 03:53:02,005 - 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-07 03:53:02,114 - DEBUG - VM ctest-fvn_vm1-00268763 is NOT ready for SSH connections, VM status: ACTIVE
2025-08-07 03:53:07,115 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-07 03:53:07,115 - DEBUG - Waiting to SSH to VM ctest-fvn_vm1-00268763, IP 134.218.181.195, Port 22
2025-08-07 03:53:07,185 - 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-07 03:53:07,289 - DEBUG - VM ctest-fvn_vm1-00268763 is NOT ready for SSH connections, VM status: ACTIVE
2025-08-07 03:53:12,290 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-07 03:53:12,290 - DEBUG - Waiting to SSH to VM ctest-fvn_vm1-00268763, IP 134.218.181.195, Port 22
2025-08-07 03:53:12,466 - DEBUG - VM ctest-fvn_vm1-00268763 is ready for SSH connections
2025-08-07 03:53:12,466 - INFO - Waiting for VM ctest-vn2_vm2-53196154 to be up..
2025-08-07 03:53:12,565 - DEBUG - VM is in ACTIVE state now
2025-08-07 03:53:12,565 - INFO - VM name : ctest-vn2_vm2-53196154
2025-08-07 03:53:12,669 - DEBUG - VM ctest-vn2_vm2-53196154 ID is e5f365bf-837e-4d12-8d0f-a86308c62c26
2025-08-07 03:53:12,669 - DEBUG - VM ctest-vn2_vm2-53196154 launched on Node an-jenkins-deploy-platform-ansible-os-3767-1
2025-08-07 03:53:12,773 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/e5f365bf-837e-4d12-8d0f-a86308c62c26
2025-08-07 03:53:12,787 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/b06e7060-98e6-4f45-863a-5b9a3f275180
2025-08-07 03:53:14,020 - 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=3.04 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.577 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.577/1.808/3.040/1.231 ms')
2025-08-07 03:53:14,021 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn2_vm2-53196154 passed
2025-08-07 03:53:14,141 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-07 03:53:14,141 - DEBUG - Waiting to SSH to VM ctest-vn2_vm2-53196154, IP 3.231.40.67, Port 22
2025-08-07 03:53:14,317 - DEBUG - VM ctest-vn2_vm2-53196154 is ready for SSH connections
2025-08-07 03:53:14,317 - INFO - Waiting for VM mltf_vm to be up..
2025-08-07 03:53:14,426 - DEBUG - VM is in ACTIVE state now
2025-08-07 03:53:14,426 - INFO - VM name : mltf_vm
2025-08-07 03:53:14,523 - DEBUG - VM mltf_vm ID is 2bab141d-e75d-47b5-8816-0de295324e17
2025-08-07 03:53:14,523 - DEBUG - VM mltf_vm launched on Node an-jenkins-deploy-platform-ansible-os-3767-2
2025-08-07 03:53:14,730 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/2bab141d-e75d-47b5-8816-0de295324e17
2025-08-07 03:53:14,744 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/71a61b61-1128-484f-85aa-1c465327d849
2025-08-07 03:53:14,757 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/b3616dfa-ceb6-43b7-bfd9-a3605378adf9
2025-08-07 03:53:15,999 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n64 bytes from 169.254.0.5: icmp_seq=1 ttl=63 time=2.51 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=0.372 ms\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.372/1.441/2.510/1.069 ms')
2025-08-07 03:53:15,999 - INFO - Ping to Metadata IP 169.254.0.5 of VM mltf_vm passed
2025-08-07 03:53:16,120 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-07 03:53:16,120 - DEBUG - Waiting to SSH to VM mltf_vm, IP 5.15.152.197, Port 22
2025-08-07 03:53:16,288 - DEBUG - VM mltf_vm is ready for SSH connections
2025-08-07 03:53:16,410 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-07 03:53:16,410 - DEBUG - Waiting to SSH to VM mltf_vm, IP 5.15.152.197, Port 22
2025-08-07 03:53:16,580 - DEBUG - VM mltf_vm is ready for SSH connections
2025-08-07 03:53:16,582 - INFO - ================================================================================
2025-08-07 03:53:16,582 - INFO - STARTING TEST : test_verify_session_record_table_inter_vn
2025-08-07 03:53:16,582 - INFO - TEST DESCRIPTION : Verify session record table ,generated stats between different vns
1.query and verify number of client session records
2.query and verify number of server session records
3.query with local_ip server_port protocol
4.query with server_port local_ip filter by server_port
5.query with client_port remote_ip filter by client_port
Total we get three record limit by 2
6.query with sort_fields
2025-08-07 03:53:17,861 - DEBUG - Skipping xmpp flap check
2025-08-07 03:53:17,861 - INFO - Initial checks done. Running the testcase now
2025-08-07 03:53:17,861 - INFO -
2025-08-07 03:53:18,086 - INFO - Setting flow export rate: 100
2025-08-07 03:53:18,138 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = udp, 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-07 03:53:18,514 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '86dfcaa9-5861-472a-be9d-3232f6e3d340', 'name': 'ctest-vn1-76278277', 'tenant_id': 'e750dce56593495fa4987395bff3b2dd', 'project_id': 'e750dce56593495fa4987395bff3b2dd', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['eaf77d94-f3e1-4e0c-bac8-101ee189b394'], 'fq_name': ['default-domain', 'ctest-AnalyticsTestSanityWithResource-52543708', 'ctest-vn1-76278277'], 'policys': [['default-domain', 'ctest-AnalyticsTestSanityWithResource-52543708', 'policy1']], 'subnet_ipam': [{'subnet_cidr': '5.15.152.192/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}}
2025-08-07 03:53:18,760 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '162e47c8-8920-4611-b29b-be9a65dd870c', 'name': 'ctest-vn2-75786057', 'tenant_id': 'e750dce56593495fa4987395bff3b2dd', 'project_id': 'e750dce56593495fa4987395bff3b2dd', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['495c77ec-a577-446f-866c-99fd951841eb'], 'fq_name': ['default-domain', 'ctest-AnalyticsTestSanityWithResource-52543708', 'ctest-vn2-75786057'], 'policys': [['default-domain', 'ctest-AnalyticsTestSanityWithResource-52543708', 'policy1']], 'subnet_ipam': [{'subnet_cidr': '3.231.40.64/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}}
2025-08-07 03:53:18,883 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:53:18,893 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:53:18,909 - DEBUG - Requesting: http://10.0.0.56:8082/project/e750dce5-6593-495f-a498-7395bff3b2dd
2025-08-07 03:53:19,051 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/86dfcaa9-5861-472a-be9d-3232f6e3d340
2025-08-07 03:53:19,086 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/86dfcaa9-5861-472a-be9d-3232f6e3d340
2025-08-07 03:53:19,103 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e70612e3-8778-48cd-badf-80905eabdc5c
2025-08-07 03:53:19,118 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e70612e3-8778-48cd-badf-80905eabdc5c
2025-08-07 03:53:19,129 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/8810dbb5-1519-48dd-8bad-fa535a465593
2025-08-07 03:53:19,137 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-07 03:53:19,137 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/86dfcaa9-5861-472a-be9d-3232f6e3d340
2025-08-07 03:53:19,150 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e70612e3-8778-48cd-badf-80905eabdc5c
2025-08-07 03:53:19,205 - INFO - Verified VN network id 12 for VN 86dfcaa9-5861-472a-be9d-3232f6e3d340
2025-08-07 03:53:19,205 - INFO - Verifications in API Server for VN ctest-vn1-76278277 passed
2025-08-07 03:53:19,205 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/86dfcaa9-5861-472a-be9d-3232f6e3d340
2025-08-07 03:53:19,239 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e70612e3-8778-48cd-badf-80905eabdc5c
2025-08-07 03:53:19,264 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e70612e3-8778-48cd-badf-80905eabdc5c
2025-08-07 03:53:19,286 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/8810dbb5-1519-48dd-8bad-fa535a465593
2025-08-07 03:53:19,308 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9718709349569873706', 'uuid-lslong': '13735189633021760320'}, 'enable': 'true', 'created': '2025-08-07T03:51:46', 'last-modified': '2025-08-07T03:53:18', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.846036'}
2025-08-07 03:53:19,318 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-07 03:53:19,326 - DEBUG - Control-node 10.0.0.130 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9718709349569873706', 'uuid-lslong': '13735189633021760320'}, 'enable': 'true', 'created': '2025-08-07T03:51:46', 'last-modified': '2025-08-07T03:53:18', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.862943'}
2025-08-07 03:53:19,336 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-07 03:53:19,347 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9718709349569873706', 'uuid-lslong': '13735189633021760320'}, 'enable': 'true', 'created': '2025-08-07T03:51:46', 'last-modified': '2025-08-07T03:53:18', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.878939'}
2025-08-07 03:53:19,355 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-07 03:53:19,355 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn1-76278277 passed
2025-08-07 03:53:19,356 - DEBUG - ====Verifying policy data for ctest-vn1-76278277 in API_Server ======
2025-08-07 03:53:19,356 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:53:19,365 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:53:19,380 - DEBUG - Requesting: http://10.0.0.56:8082/project/e750dce5-6593-495f-a498-7395bff3b2dd
2025-08-07 03:53:19,492 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/86dfcaa9-5861-472a-be9d-3232f6e3d340
2025-08-07 03:53:19,518 - DEBUG - ==>Verifying data for policy with id: db96fc92-6d1f-4436-87da-01935a75fda5, fqn: ['default-domain', 'ctest-AnalyticsTestSanityWithResource-52543708', 'policy1']
2025-08-07 03:53:19,518 - INFO - VN ctest-vn1-76278277 Policy verification: verify_vn_policy_in_api_server, status: True
2025-08-07 03:53:19,518 - DEBUG - Verifying the vn in opserver
2025-08-07 03:53:19,518 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277 virtual network link through opserver 10.0.0.56
2025-08-07 03:53:19,519 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-08-07 03:53:19,541 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277?flat'}
2025-08-07 03:53:19,541 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277 virtual network link through opserver 10.0.0.130
2025-08-07 03:53:19,541 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-networks
2025-08-07 03:53:19,559 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'href': 'http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277?flat'}
2025-08-07 03:53:19,559 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277 virtual network link through opserver 10.0.0.249
2025-08-07 03:53:19,559 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-08-07 03:53:19,575 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277?flat'}
2025-08-07 03:53:19,575 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277 is found in opserver
2025-08-07 03:53:19,588 - DEBUG - VRF ids for VN ctest-vn1-76278277: {'10.0.0.61': '2'}
2025-08-07 03:53:19,588 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:53:19,597 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:53:19,613 - DEBUG - Requesting: http://10.0.0.56:8082/project/e750dce5-6593-495f-a498-7395bff3b2dd
2025-08-07 03:53:19,726 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/162e47c8-8920-4611-b29b-be9a65dd870c
2025-08-07 03:53:19,753 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/162e47c8-8920-4611-b29b-be9a65dd870c
2025-08-07 03:53:19,771 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6d42434f-997b-4f8a-b65d-895b112183df
2025-08-07 03:53:19,785 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6d42434f-997b-4f8a-b65d-895b112183df
2025-08-07 03:53:19,798 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/5576ea6c-9c3a-49fa-82a7-e16d5b7dd38b
2025-08-07 03:53:19,809 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-07 03:53:19,809 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/162e47c8-8920-4611-b29b-be9a65dd870c
2025-08-07 03:53:19,824 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6d42434f-997b-4f8a-b65d-895b112183df
2025-08-07 03:53:19,849 - INFO - Verified VN network id 13 for VN 162e47c8-8920-4611-b29b-be9a65dd870c
2025-08-07 03:53:19,849 - INFO - Verifications in API Server for VN ctest-vn2-75786057 passed
2025-08-07 03:53:19,849 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/162e47c8-8920-4611-b29b-be9a65dd870c
2025-08-07 03:53:19,872 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6d42434f-997b-4f8a-b65d-895b112183df
2025-08-07 03:53:19,892 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6d42434f-997b-4f8a-b65d-895b112183df
2025-08-07 03:53:19,902 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/5576ea6c-9c3a-49fa-82a7-e16d5b7dd38b
2025-08-07 03:53:19,920 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1598293844382729745', 'uuid-lslong': '12870089930484582156'}, 'enable': 'true', 'created': '2025-08-07T03:52:23', 'last-modified': '2025-08-07T03:53:18', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.197205'}
2025-08-07 03:53:19,929 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-07 03:53:19,937 - DEBUG - Control-node 10.0.0.130 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1598293844382729745', 'uuid-lslong': '12870089930484582156'}, 'enable': 'true', 'created': '2025-08-07T03:52:23', 'last-modified': '2025-08-07T03:53:18', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.217936'}
2025-08-07 03:53:19,947 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-07 03:53:19,955 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1598293844382729745', 'uuid-lslong': '12870089930484582156'}, 'enable': 'true', 'created': '2025-08-07T03:52:23', 'last-modified': '2025-08-07T03:53:18', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.239524'}
2025-08-07 03:53:19,963 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-07 03:53:19,963 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn2-75786057 passed
2025-08-07 03:53:19,963 - DEBUG - ====Verifying policy data for ctest-vn2-75786057 in API_Server ======
2025-08-07 03:53:19,963 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:53:19,971 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:53:19,985 - DEBUG - Requesting: http://10.0.0.56:8082/project/e750dce5-6593-495f-a498-7395bff3b2dd
2025-08-07 03:53:20,097 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/162e47c8-8920-4611-b29b-be9a65dd870c
2025-08-07 03:53:20,116 - DEBUG - ==>Verifying data for policy with id: db96fc92-6d1f-4436-87da-01935a75fda5, fqn: ['default-domain', 'ctest-AnalyticsTestSanityWithResource-52543708', 'policy1']
2025-08-07 03:53:20,116 - INFO - VN ctest-vn2-75786057 Policy verification: verify_vn_policy_in_api_server, status: True
2025-08-07 03:53:20,116 - DEBUG - Verifying the vn in opserver
2025-08-07 03:53:20,116 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057 virtual network link through opserver 10.0.0.56
2025-08-07 03:53:20,116 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-08-07 03:53:20,136 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057?flat'}
2025-08-07 03:53:20,136 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057 virtual network link through opserver 10.0.0.130
2025-08-07 03:53:20,136 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-networks
2025-08-07 03:53:20,156 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'href': 'http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057?flat'}
2025-08-07 03:53:20,156 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057 virtual network link through opserver 10.0.0.249
2025-08-07 03:53:20,156 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-08-07 03:53:20,174 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057?flat'}
2025-08-07 03:53:20,175 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057 is found in opserver
2025-08-07 03:53:20,190 - DEBUG - VRF ids for VN ctest-vn2-75786057: {'10.0.0.41': '2', '10.0.0.61': '3'}
2025-08-07 03:53:20,190 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:53:20,197 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:53:20,212 - DEBUG - Requesting: http://10.0.0.56:8082/project/e750dce5-6593-495f-a498-7395bff3b2dd
2025-08-07 03:53:20,311 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/86dfcaa9-5861-472a-be9d-3232f6e3d340
2025-08-07 03:53:20,327 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/86dfcaa9-5861-472a-be9d-3232f6e3d340
2025-08-07 03:53:20,344 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e70612e3-8778-48cd-badf-80905eabdc5c
2025-08-07 03:53:20,355 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e70612e3-8778-48cd-badf-80905eabdc5c
2025-08-07 03:53:20,365 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/8810dbb5-1519-48dd-8bad-fa535a465593
2025-08-07 03:53:20,376 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-07 03:53:20,377 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/86dfcaa9-5861-472a-be9d-3232f6e3d340
2025-08-07 03:53:20,389 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e70612e3-8778-48cd-badf-80905eabdc5c
2025-08-07 03:53:20,413 - INFO - Verified VN network id 12 for VN 86dfcaa9-5861-472a-be9d-3232f6e3d340
2025-08-07 03:53:20,414 - INFO - Verifications in API Server for VN ctest-vn1-76278277 passed
2025-08-07 03:53:20,414 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/86dfcaa9-5861-472a-be9d-3232f6e3d340
2025-08-07 03:53:20,429 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e70612e3-8778-48cd-badf-80905eabdc5c
2025-08-07 03:53:20,445 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e70612e3-8778-48cd-badf-80905eabdc5c
2025-08-07 03:53:20,458 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/8810dbb5-1519-48dd-8bad-fa535a465593
2025-08-07 03:53:20,479 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9718709349569873706', 'uuid-lslong': '13735189633021760320'}, 'enable': 'true', 'created': '2025-08-07T03:51:46', 'last-modified': '2025-08-07T03:53:18', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.014880'}
2025-08-07 03:53:20,490 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-07 03:53:20,497 - DEBUG - Control-node 10.0.0.130 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9718709349569873706', 'uuid-lslong': '13735189633021760320'}, 'enable': 'true', 'created': '2025-08-07T03:51:46', 'last-modified': '2025-08-07T03:53:18', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.034498'}
2025-08-07 03:53:20,506 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-07 03:53:20,513 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9718709349569873706', 'uuid-lslong': '13735189633021760320'}, 'enable': 'true', 'created': '2025-08-07T03:51:46', 'last-modified': '2025-08-07T03:53:18', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.044707'}
2025-08-07 03:53:20,521 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-07 03:53:20,521 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn1-76278277 passed
2025-08-07 03:53:20,521 - DEBUG - ====Verifying policy data for ctest-vn1-76278277 in API_Server ======
2025-08-07 03:53:20,521 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:53:20,529 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:53:20,547 - DEBUG - Requesting: http://10.0.0.56:8082/project/e750dce5-6593-495f-a498-7395bff3b2dd
2025-08-07 03:53:20,659 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/86dfcaa9-5861-472a-be9d-3232f6e3d340
2025-08-07 03:53:20,673 - DEBUG - ==>Verifying data for policy with id: db96fc92-6d1f-4436-87da-01935a75fda5, fqn: ['default-domain', 'ctest-AnalyticsTestSanityWithResource-52543708', 'policy1']
2025-08-07 03:53:20,673 - INFO - VN ctest-vn1-76278277 Policy verification: verify_vn_policy_in_api_server, status: True
2025-08-07 03:53:20,674 - DEBUG - Verifying the vn in opserver
2025-08-07 03:53:20,674 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277 virtual network link through opserver 10.0.0.56
2025-08-07 03:53:20,674 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-08-07 03:53:20,692 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277?flat'}
2025-08-07 03:53:20,692 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277 virtual network link through opserver 10.0.0.130
2025-08-07 03:53:20,693 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-networks
2025-08-07 03:53:20,710 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'href': 'http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277?flat'}
2025-08-07 03:53:20,710 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277 virtual network link through opserver 10.0.0.249
2025-08-07 03:53:20,710 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-08-07 03:53:20,728 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277?flat'}
2025-08-07 03:53:20,728 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277 is found in opserver
2025-08-07 03:53:20,744 - DEBUG - VRF ids for VN ctest-vn1-76278277: {'10.0.0.61': '2'}
2025-08-07 03:53:20,745 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:53:20,754 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:53:20,769 - DEBUG - Requesting: http://10.0.0.56:8082/project/e750dce5-6593-495f-a498-7395bff3b2dd
2025-08-07 03:53:20,869 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/162e47c8-8920-4611-b29b-be9a65dd870c
2025-08-07 03:53:20,885 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/162e47c8-8920-4611-b29b-be9a65dd870c
2025-08-07 03:53:20,901 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6d42434f-997b-4f8a-b65d-895b112183df
2025-08-07 03:53:20,912 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6d42434f-997b-4f8a-b65d-895b112183df
2025-08-07 03:53:20,923 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/5576ea6c-9c3a-49fa-82a7-e16d5b7dd38b
2025-08-07 03:53:20,932 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-07 03:53:20,933 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/162e47c8-8920-4611-b29b-be9a65dd870c
2025-08-07 03:53:20,945 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6d42434f-997b-4f8a-b65d-895b112183df
2025-08-07 03:53:20,966 - INFO - Verified VN network id 13 for VN 162e47c8-8920-4611-b29b-be9a65dd870c
2025-08-07 03:53:20,966 - INFO - Verifications in API Server for VN ctest-vn2-75786057 passed
2025-08-07 03:53:20,966 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/162e47c8-8920-4611-b29b-be9a65dd870c
2025-08-07 03:53:20,980 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6d42434f-997b-4f8a-b65d-895b112183df
2025-08-07 03:53:20,991 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6d42434f-997b-4f8a-b65d-895b112183df
2025-08-07 03:53:21,002 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/5576ea6c-9c3a-49fa-82a7-e16d5b7dd38b
2025-08-07 03:53:21,020 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1598293844382729745', 'uuid-lslong': '12870089930484582156'}, 'enable': 'true', 'created': '2025-08-07T03:52:23', 'last-modified': '2025-08-07T03:53:18', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.298670'}
2025-08-07 03:53:21,029 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-07 03:53:21,036 - DEBUG - Control-node 10.0.0.130 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1598293844382729745', 'uuid-lslong': '12870089930484582156'}, 'enable': 'true', 'created': '2025-08-07T03:52:23', 'last-modified': '2025-08-07T03:53:18', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.317114'}
2025-08-07 03:53:21,044 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-07 03:53:21,052 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1598293844382729745', 'uuid-lslong': '12870089930484582156'}, 'enable': 'true', 'created': '2025-08-07T03:52:23', 'last-modified': '2025-08-07T03:53:18', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.336874'}
2025-08-07 03:53:21,060 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-07 03:53:21,060 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn2-75786057 passed
2025-08-07 03:53:21,060 - DEBUG - ====Verifying policy data for ctest-vn2-75786057 in API_Server ======
2025-08-07 03:53:21,060 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:53:21,068 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:53:21,082 - DEBUG - Requesting: http://10.0.0.56:8082/project/e750dce5-6593-495f-a498-7395bff3b2dd
2025-08-07 03:53:21,215 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/162e47c8-8920-4611-b29b-be9a65dd870c
2025-08-07 03:53:21,232 - DEBUG - ==>Verifying data for policy with id: db96fc92-6d1f-4436-87da-01935a75fda5, fqn: ['default-domain', 'ctest-AnalyticsTestSanityWithResource-52543708', 'policy1']
2025-08-07 03:53:21,232 - INFO - VN ctest-vn2-75786057 Policy verification: verify_vn_policy_in_api_server, status: True
2025-08-07 03:53:21,232 - DEBUG - Verifying the vn in opserver
2025-08-07 03:53:21,232 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057 virtual network link through opserver 10.0.0.56
2025-08-07 03:53:21,232 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-08-07 03:53:21,250 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057?flat'}
2025-08-07 03:53:21,250 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057 virtual network link through opserver 10.0.0.130
2025-08-07 03:53:21,250 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-networks
2025-08-07 03:53:21,269 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'href': 'http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057?flat'}
2025-08-07 03:53:21,269 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057 virtual network link through opserver 10.0.0.249
2025-08-07 03:53:21,269 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-08-07 03:53:21,286 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057?flat'}
2025-08-07 03:53:21,286 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057 is found in opserver
2025-08-07 03:53:21,302 - DEBUG - VRF ids for VN ctest-vn2-75786057: {'10.0.0.41': '2', '10.0.0.61': '3'}
2025-08-07 03:53:21,302 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:53:21,309 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:53:21,324 - DEBUG - Requesting: http://10.0.0.56:8082/project/e750dce5-6593-495f-a498-7395bff3b2dd
2025-08-07 03:53:21,421 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/2e571d31-de3d-4b1a-8f57-2e1cdffe401c
2025-08-07 03:53:21,435 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/2e571d31-de3d-4b1a-8f57-2e1cdffe401c
2025-08-07 03:53:21,448 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/a103d4df-1e11-4145-920a-baa03016b822
2025-08-07 03:53:21,457 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/a103d4df-1e11-4145-920a-baa03016b822
2025-08-07 03:53:21,469 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/47dce227-58af-481e-9897-d2c61de06498
2025-08-07 03:53:21,478 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-07 03:53:21,478 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/2e571d31-de3d-4b1a-8f57-2e1cdffe401c
2025-08-07 03:53:21,492 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/a103d4df-1e11-4145-920a-baa03016b822
2025-08-07 03:53:21,514 - INFO - Verified VN network id 14 for VN 2e571d31-de3d-4b1a-8f57-2e1cdffe401c
2025-08-07 03:53:21,514 - INFO - Verifications in API Server for VN ctest-fip_vn-23932101 passed
2025-08-07 03:53:21,515 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/2e571d31-de3d-4b1a-8f57-2e1cdffe401c
2025-08-07 03:53:21,529 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/a103d4df-1e11-4145-920a-baa03016b822
2025-08-07 03:53:21,540 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/a103d4df-1e11-4145-920a-baa03016b822
2025-08-07 03:53:21,548 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/47dce227-58af-481e-9897-d2c61de06498
2025-08-07 03:53:21,566 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3339169748737674010', 'uuid-lslong': '10328774971949465628'}, 'enable': 'true', 'created': '2025-08-07T03:52:24', 'last-modified': '2025-08-07T03:52:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:57.062359'}
2025-08-07 03:53:21,574 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-07 03:53:21,583 - DEBUG - Control-node 10.0.0.130 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3339169748737674010', 'uuid-lslong': '10328774971949465628'}, 'enable': 'true', 'created': '2025-08-07T03:52:24', 'last-modified': '2025-08-07T03:52:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:57.080113'}
2025-08-07 03:53:21,591 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-07 03:53:21,598 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3339169748737674010', 'uuid-lslong': '10328774971949465628'}, 'enable': 'true', 'created': '2025-08-07T03:52:24', 'last-modified': '2025-08-07T03:52:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:57.100813'}
2025-08-07 03:53:21,606 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-07 03:53:21,606 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-fip_vn-23932101 passed
2025-08-07 03:53:21,607 - DEBUG - ====Verifying policy data for ctest-fip_vn-23932101 in API_Server ======
2025-08-07 03:53:21,607 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:53:21,618 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:53:21,632 - DEBUG - Requesting: http://10.0.0.56:8082/project/e750dce5-6593-495f-a498-7395bff3b2dd
2025-08-07 03:53:21,733 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/2e571d31-de3d-4b1a-8f57-2e1cdffe401c
2025-08-07 03:53:21,747 - DEBUG - =>VN ctest-fip_vn-23932101 has no policy to be verified
2025-08-07 03:53:21,747 - DEBUG - Verifying the vn in opserver
2025-08-07 03:53:21,747 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101 virtual network link through opserver 10.0.0.56
2025-08-07 03:53:21,747 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-08-07 03:53:21,763 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101?flat'}
2025-08-07 03:53:21,763 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101 virtual network link through opserver 10.0.0.130
2025-08-07 03:53:21,763 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-networks
2025-08-07 03:53:21,783 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101', 'href': 'http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101?flat'}
2025-08-07 03:53:21,783 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101 virtual network link through opserver 10.0.0.249
2025-08-07 03:53:21,783 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-08-07 03:53:21,802 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101?flat'}
2025-08-07 03:53:21,802 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101 is found in opserver
2025-08-07 03:53:21,818 - DEBUG - VRF ids for VN ctest-fip_vn-23932101: {'10.0.0.41': '3'}
2025-08-07 03:53:21,818 - INFO - start time= 1754538801818825
2025-08-07 03:53:21,818 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-07 03:53:25,632 - DEBUG - file copy failed retrying
2025-08-07 03:53:29,053 - DEBUG - file copy failed retrying
2025-08-07 03:53:32,512 - DEBUG - file copy failed retrying
2025-08-07 03:53:35,920 - DEBUG - file copy failed retrying
2025-08-07 03:53:35,921 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-07 03:53:35,921 - DEBUG - Copied file /contrail-test/tcutils/traffic_utils/../udpechoserver.py to 3.231.40.67
2025-08-07 03:53:35,921 - DEBUG - Running remote_cmd, Cmd : python /tmp/udpechoserver.py --start_port 9000 --end_port 9000 --pid_file /tmp/server-ctest-random-65702372.pid --stats_file /tmp/server-ctest-random-65702372.stats 0<&- &> /tmp/server-ctest-random-65702372.log, host_string: cirros@169.254.0.3, password: gocubsgogateway: ubuntu@10.0.0.41, gateway password: c0ntrail123
2025-08-07 03:53:35,921 - DEBUG - nohup python /tmp/udpechoserver.py --start_port 9000 --end_port 9000 --pid_file /tmp/server-ctest-random-65702372.pid --stats_file /tmp/server-ctest-random-65702372.stats 0<&- &> /tmp/server-ctest-random-65702372.log &
2025-08-07 03:53:36,563 - DEBUG - None
2025-08-07 03:53:39,976 - DEBUG - file copy failed retrying
2025-08-07 03:53:43,357 - DEBUG - file copy failed retrying
2025-08-07 03:53:46,740 - DEBUG - file copy failed retrying
2025-08-07 03:53:50,120 - DEBUG - file copy failed retrying
2025-08-07 03:53:50,120 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-07 03:53:50,121 - DEBUG - Copied file /contrail-test/tcutils/traffic_utils/../udpechoclient.py to 5.15.152.195
2025-08-07 03:53:50,121 - DEBUG - Running remote_cmd, Cmd : python /tmp/udpechoclient.py --servers 3.231.40.67 --sport 8000 --dports 9000 --retry --count 100 --pid_file /tmp/client-ctest-random-65702372.pid --stats_file /tmp/client-ctest-random-65702372.stats 0<&- &> /tmp/client-ctest-random-65702372.log, host_string: cirros@169.254.0.3, password: gocubsgogateway: ubuntu@10.0.0.61, gateway password: c0ntrail123
2025-08-07 03:53:50,121 - DEBUG - nohup python /tmp/udpechoclient.py --servers 3.231.40.67 --sport 8000 --dports 9000 --retry --count 100 --pid_file /tmp/client-ctest-random-65702372.pid --stats_file /tmp/client-ctest-random-65702372.stats 0<&- &> /tmp/client-ctest-random-65702372.log &
2025-08-07 03:53:50,664 - DEBUG - None
2025-08-07 03:53:50,664 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-07 03:53:50,664 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-07 03:53:50,665 - DEBUG - File /contrail-test/tcutils/traffic_utils/../udpechoserver.py already copied to 3.231.40.67
2025-08-07 03:53:50,665 - DEBUG - Running remote_cmd, Cmd : python /tmp/udpechoserver.py --start_port 9001 --end_port 9001 --pid_file /tmp/server-ctest-random-50615025.pid --stats_file /tmp/server-ctest-random-50615025.stats 0<&- &> /tmp/server-ctest-random-50615025.log, host_string: cirros@169.254.0.3, password: gocubsgogateway: ubuntu@10.0.0.41, gateway password: c0ntrail123
2025-08-07 03:53:50,665 - DEBUG - nohup python /tmp/udpechoserver.py --start_port 9001 --end_port 9001 --pid_file /tmp/server-ctest-random-50615025.pid --stats_file /tmp/server-ctest-random-50615025.stats 0<&- &> /tmp/server-ctest-random-50615025.log &
2025-08-07 03:53:51,250 - DEBUG - None
2025-08-07 03:53:51,250 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-07 03:53:51,250 - DEBUG - File /contrail-test/tcutils/traffic_utils/../udpechoclient.py already copied to 5.15.152.195
2025-08-07 03:53:51,251 - DEBUG - Running remote_cmd, Cmd : python /tmp/udpechoclient.py --servers 3.231.40.67 --sport 8000 --dports 9001 --retry --count 100 --pid_file /tmp/client-ctest-random-50615025.pid --stats_file /tmp/client-ctest-random-50615025.stats 0<&- &> /tmp/client-ctest-random-50615025.log, host_string: cirros@169.254.0.3, password: gocubsgogateway: ubuntu@10.0.0.61, gateway password: c0ntrail123
2025-08-07 03:53:51,251 - DEBUG - nohup python /tmp/udpechoclient.py --servers 3.231.40.67 --sport 8000 --dports 9001 --retry --count 100 --pid_file /tmp/client-ctest-random-50615025.pid --stats_file /tmp/client-ctest-random-50615025.stats 0<&- &> /tmp/client-ctest-random-50615025.log &
2025-08-07 03:53:51,481 - DEBUG - None
2025-08-07 03:53:51,481 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-07 03:53:51,482 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-07 03:53:51,482 - DEBUG - File /contrail-test/tcutils/traffic_utils/../udpechoserver.py already copied to 3.231.40.67
2025-08-07 03:53:51,482 - DEBUG - Running remote_cmd, Cmd : python /tmp/udpechoserver.py --start_port 9003 --end_port 9003 --pid_file /tmp/server-ctest-random-82518695.pid --stats_file /tmp/server-ctest-random-82518695.stats 0<&- &> /tmp/server-ctest-random-82518695.log, host_string: cirros@169.254.0.3, password: gocubsgogateway: ubuntu@10.0.0.41, gateway password: c0ntrail123
2025-08-07 03:53:51,482 - DEBUG - nohup python /tmp/udpechoserver.py --start_port 9003 --end_port 9003 --pid_file /tmp/server-ctest-random-82518695.pid --stats_file /tmp/server-ctest-random-82518695.stats 0<&- &> /tmp/server-ctest-random-82518695.log &
2025-08-07 03:53:51,735 - DEBUG - None
2025-08-07 03:53:51,736 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-07 03:53:51,736 - DEBUG - File /contrail-test/tcutils/traffic_utils/../udpechoclient.py already copied to 5.15.152.195
2025-08-07 03:53:51,736 - DEBUG - Running remote_cmd, Cmd : python /tmp/udpechoclient.py --servers 3.231.40.67 --sport 8000 --dports 9003 --retry --count 100 --pid_file /tmp/client-ctest-random-82518695.pid --stats_file /tmp/client-ctest-random-82518695.stats 0<&- &> /tmp/client-ctest-random-82518695.log, host_string: cirros@169.254.0.3, password: gocubsgogateway: ubuntu@10.0.0.61, gateway password: c0ntrail123
2025-08-07 03:53:51,736 - DEBUG - nohup python /tmp/udpechoclient.py --servers 3.231.40.67 --sport 8000 --dports 9003 --retry --count 100 --pid_file /tmp/client-ctest-random-82518695.pid --stats_file /tmp/client-ctest-random-82518695.stats 0<&- &> /tmp/client-ctest-random-82518695.log &
2025-08-07 03:53:51,975 - DEBUG - None
2025-08-07 03:54:01,975 - DEBUG - Running remote_cmd, Cmd : kill $(cat /tmp/client-ctest-random-65702372.pid); sync; cat /tmp/client-ctest-random-65702372.stats, host_string: cirros@169.254.0.3, password: gocubsgogateway: ubuntu@10.0.0.61, gateway password: c0ntrail123
2025-08-07 03:54:01,975 - DEBUG - kill $(cat /tmp/client-ctest-random-65702372.pid); sync; cat /tmp/client-ctest-random-65702372.stats
2025-08-07 03:54:02,318 - DEBUG - cat: can't open '/tmp/client-ctest-random-65702372.pid': No such file or directory
sh: you need to specify whom to kill
cat: can't open '/tmp/client-ctest-random-65702372.stats': No such file or directory
2025-08-07 03:54:02,319 - DEBUG - Running remote_cmd, Cmd : kill $(cat /tmp/server-ctest-random-65702372.pid); sync; cat /tmp/server-ctest-random-65702372.stats, host_string: cirros@169.254.0.3, password: gocubsgogateway: ubuntu@10.0.0.41, gateway password: c0ntrail123
2025-08-07 03:54:02,319 - DEBUG - kill $(cat /tmp/server-ctest-random-65702372.pid); sync; cat /tmp/server-ctest-random-65702372.stats
2025-08-07 03:54:02,701 - DEBUG - cat: can't open '/tmp/server-ctest-random-65702372.pid': No such file or directory
sh: you need to specify whom to kill
cat: can't open '/tmp/server-ctest-random-65702372.stats': No such file or directory
2025-08-07 03:54:02,702 - INFO - Client - Sent: 0, Received: 0
2025-08-07 03:54:02,702 - INFO - Server - Sent: 0, Received: 0
2025-08-07 03:54:03,344 - INFO - Deleted policy policy1
2025-08-07 03:54:03,526 - INFO - Setting flow export rate: None
2025-08-07 03:54:06,413 - ERROR - AssertionError
Python 3.6.8: /usr/bin/python3
Thu Aug 7 03:54:02 2025
A problem occurred in a Python script. Here is the sequence of
function calls leading up to the error, in the order they occurred.
/contrail-test/tcutils/wrappers.py in wrapper(self=, *args=(), **kwargs={})
78 log.info('Initial checks done. Running the testcase now')
79 log.info('')
80 result = function(self, *args, **kwargs)
81 if self.inputs.upgrade:
82 pid = os.getpid()
result = None
function =
self =
args = ()
kwargs = {}
/contrail-test/serial_scripts/analytics/test_analytics_resource.py in test_verify_session_record_table_inter_vn(self=)
1373 start_time = self.analytics_obj.getstarttime(vm_node_ip)
1374 self.logger.info("start time= %s" % (start_time))
1375 self.setup_and_create_streams(self.res.vn1_vm1_fixture, self.res.vn2_vm2_fixture)
1376 time.sleep(100)
1377 src_vn = self.res.vn1_fixture.vn_fq_name
self =
self.setup_and_create_streams = >
self.res =
self.res.vn1_vm1_fixture =
self.res.vn2_vm2_fixture =
/contrail-test/serial_scripts/analytics/base.py in setup_and_create_streams(self=, src_vm=, dst_vm=, sport=8000, dport=9003, count=100)
168 time.sleep(10)
169 for traffic_obj in traffic_objs:
170 self.stop_traffic(traffic_obj)
171
172 #end setup_create_streams
self =
self.stop_traffic = >
traffic_obj =
/contrail-test/common/base.py in stop_traffic(self=, traffic_obj=, expectation=True, unidirection=False, partial=False)
796 assert False, "No drops observed in packets %s"%msg
797 elif expectation:
798 assert sent and recv, "Packets not %s"%msg
799 if recv*100/float(sent) < 90:
800 assert False, "Packets not %s"%msg
sent = 0
recv = 0
msg = 'transferred between 5.15.152.195 and 3.231.40.67, proto udp sport 8000 and dport 9000'
AssertionError: Packets not transferred between 5.15.152.195 and 3.231.40.67, proto udp sport 8000 and dport 9000
__cause__ = None
__class__ =
__context__ = None
__delattr__ =
__dict__ = {}
__dir__ =
__doc__ = 'Assertion failed.'
__eq__ =
__format__ =
__ge__ =
__getattribute__ =
__gt__ =
__hash__ =
__init__ =
__init_subclass__ =
__le__ =
__lt__ =
__ne__ =
__new__ =
__reduce__ =
__reduce_ex__ =
__repr__ =
__setattr__ =
__setstate__ =
__sizeof__ =
__str__ =
__subclasshook__ =
__suppress_context__ = False
__traceback__ =
args = ('Packets not transferred between 5.15.152.195 and 3.231.40.67, proto udp sport 8000 and dport 9000',)
with_traceback =
The above is a description of an error in a Python program. Here is
the original traceback:
Traceback (most recent call last):
File "/contrail-test/tcutils/wrappers.py", line 80, in wrapper
result = function(self, *args, **kwargs)
File "/contrail-test/serial_scripts/analytics/test_analytics_resource.py", line 1375, in test_verify_session_record_table_inter_vn
self.setup_and_create_streams(self.res.vn1_vm1_fixture, self.res.vn2_vm2_fixture)
File "/contrail-test/serial_scripts/analytics/base.py", line 170, in setup_and_create_streams
self.stop_traffic(traffic_obj)
File "/contrail-test/common/base.py", line 798, in stop_traffic
assert sent and recv, "Packets not %s"%msg
AssertionError: Packets not transferred between 5.15.152.195 and 3.231.40.67, proto udp sport 8000 and dport 9000
2025-08-07 03:54:06,414 - DEBUG - Skipping xmpp flap check
2025-08-07 03:54:06,414 - INFO -
2025-08-07 03:54:06,414 - INFO - END TEST : test_verify_session_record_table_inter_vn : FAILED[0:00:50]
2025-08-07 03:54:06,414 - INFO - --------------------------------------------------------------------------------
2025-08-07 03:54:06,419 - INFO - ================================================================================
2025-08-07 03:54:06,419 - INFO - STARTING TEST : test_verify_session_sampling_teardown
2025-08-07 03:54:06,419 - 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-07 03:54:07,693 - DEBUG - Skipping xmpp flap check
2025-08-07 03:54:07,693 - INFO - Initial checks done. Running the testcase now
2025-08-07 03:54:07,693 - INFO -
2025-08-07 03:54:07,910 - INFO - Setting flow export rate: 100
2025-08-07 03:54:07,962 - 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-07 03:54:08,343 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '86dfcaa9-5861-472a-be9d-3232f6e3d340', 'name': 'ctest-vn1-76278277', 'tenant_id': 'e750dce56593495fa4987395bff3b2dd', 'project_id': 'e750dce56593495fa4987395bff3b2dd', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['eaf77d94-f3e1-4e0c-bac8-101ee189b394'], 'fq_name': ['default-domain', 'ctest-AnalyticsTestSanityWithResource-52543708', 'ctest-vn1-76278277'], 'policys': [['default-domain', 'ctest-AnalyticsTestSanityWithResource-52543708', 'policy1']], 'subnet_ipam': [{'subnet_cidr': '5.15.152.192/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}}
2025-08-07 03:54:08,594 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '162e47c8-8920-4611-b29b-be9a65dd870c', 'name': 'ctest-vn2-75786057', 'tenant_id': 'e750dce56593495fa4987395bff3b2dd', 'project_id': 'e750dce56593495fa4987395bff3b2dd', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['495c77ec-a577-446f-866c-99fd951841eb'], 'fq_name': ['default-domain', 'ctest-AnalyticsTestSanityWithResource-52543708', 'ctest-vn2-75786057'], 'policys': [['default-domain', 'ctest-AnalyticsTestSanityWithResource-52543708', 'policy1']], 'subnet_ipam': [{'subnet_cidr': '3.231.40.64/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}}
2025-08-07 03:54:08,743 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:54:08,758 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:54:08,780 - DEBUG - Requesting: http://10.0.0.56:8082/project/e750dce5-6593-495f-a498-7395bff3b2dd
2025-08-07 03:54:08,885 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/86dfcaa9-5861-472a-be9d-3232f6e3d340
2025-08-07 03:54:08,906 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/86dfcaa9-5861-472a-be9d-3232f6e3d340
2025-08-07 03:54:08,920 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e70612e3-8778-48cd-badf-80905eabdc5c
2025-08-07 03:54:08,933 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e70612e3-8778-48cd-badf-80905eabdc5c
2025-08-07 03:54:08,944 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/8810dbb5-1519-48dd-8bad-fa535a465593
2025-08-07 03:54:08,954 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-07 03:54:08,954 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/86dfcaa9-5861-472a-be9d-3232f6e3d340
2025-08-07 03:54:08,966 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e70612e3-8778-48cd-badf-80905eabdc5c
2025-08-07 03:54:08,990 - INFO - Verified VN network id 12 for VN 86dfcaa9-5861-472a-be9d-3232f6e3d340
2025-08-07 03:54:08,990 - INFO - Verifications in API Server for VN ctest-vn1-76278277 passed
2025-08-07 03:54:08,990 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/86dfcaa9-5861-472a-be9d-3232f6e3d340
2025-08-07 03:54:09,008 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e70612e3-8778-48cd-badf-80905eabdc5c
2025-08-07 03:54:09,020 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e70612e3-8778-48cd-badf-80905eabdc5c
2025-08-07 03:54:09,030 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/8810dbb5-1519-48dd-8bad-fa535a465593
2025-08-07 03:54:09,047 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9718709349569873706', 'uuid-lslong': '13735189633021760320'}, 'enable': 'true', 'created': '2025-08-07T03:51:46', 'last-modified': '2025-08-07T03:54:08', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.740844'}
2025-08-07 03:54:09,055 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-07 03:54:09,062 - DEBUG - Control-node 10.0.0.130 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9718709349569873706', 'uuid-lslong': '13735189633021760320'}, 'enable': 'true', 'created': '2025-08-07T03:51:46', 'last-modified': '2025-08-07T03:54:08', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.754675'}
2025-08-07 03:54:09,073 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-07 03:54:09,081 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9718709349569873706', 'uuid-lslong': '13735189633021760320'}, 'enable': 'true', 'created': '2025-08-07T03:51:46', 'last-modified': '2025-08-07T03:54:08', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.795780'}
2025-08-07 03:54:09,089 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-07 03:54:09,089 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn1-76278277 passed
2025-08-07 03:54:09,089 - DEBUG - ====Verifying policy data for ctest-vn1-76278277 in API_Server ======
2025-08-07 03:54:09,089 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:54:09,097 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:54:09,113 - DEBUG - Requesting: http://10.0.0.56:8082/project/e750dce5-6593-495f-a498-7395bff3b2dd
2025-08-07 03:54:09,255 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/86dfcaa9-5861-472a-be9d-3232f6e3d340
2025-08-07 03:54:09,276 - DEBUG - ==>Verifying data for policy with id: 849b108b-411e-4ad9-8ac0-e71a8a3af6a1, fqn: ['default-domain', 'ctest-AnalyticsTestSanityWithResource-52543708', 'policy1']
2025-08-07 03:54:09,276 - INFO - VN ctest-vn1-76278277 Policy verification: verify_vn_policy_in_api_server, status: True
2025-08-07 03:54:09,276 - DEBUG - Verifying the vn in opserver
2025-08-07 03:54:09,277 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277 virtual network link through opserver 10.0.0.56
2025-08-07 03:54:09,277 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-08-07 03:54:09,295 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277?flat'}
2025-08-07 03:54:09,295 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277 virtual network link through opserver 10.0.0.130
2025-08-07 03:54:09,295 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-networks
2025-08-07 03:54:09,310 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'href': 'http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277?flat'}
2025-08-07 03:54:09,310 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277 virtual network link through opserver 10.0.0.249
2025-08-07 03:54:09,310 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-08-07 03:54:09,328 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277?flat'}
2025-08-07 03:54:09,328 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277 is found in opserver
2025-08-07 03:54:09,345 - DEBUG - VRF ids for VN ctest-vn1-76278277: {'10.0.0.61': '2'}
2025-08-07 03:54:09,345 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:54:09,355 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:54:09,371 - DEBUG - Requesting: http://10.0.0.56:8082/project/e750dce5-6593-495f-a498-7395bff3b2dd
2025-08-07 03:54:09,495 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/162e47c8-8920-4611-b29b-be9a65dd870c
2025-08-07 03:54:09,514 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/162e47c8-8920-4611-b29b-be9a65dd870c
2025-08-07 03:54:09,527 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6d42434f-997b-4f8a-b65d-895b112183df
2025-08-07 03:54:09,538 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6d42434f-997b-4f8a-b65d-895b112183df
2025-08-07 03:54:09,551 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/5576ea6c-9c3a-49fa-82a7-e16d5b7dd38b
2025-08-07 03:54:09,561 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-07 03:54:09,561 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/162e47c8-8920-4611-b29b-be9a65dd870c
2025-08-07 03:54:09,577 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6d42434f-997b-4f8a-b65d-895b112183df
2025-08-07 03:54:09,603 - INFO - Verified VN network id 13 for VN 162e47c8-8920-4611-b29b-be9a65dd870c
2025-08-07 03:54:09,603 - INFO - Verifications in API Server for VN ctest-vn2-75786057 passed
2025-08-07 03:54:09,603 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/162e47c8-8920-4611-b29b-be9a65dd870c
2025-08-07 03:54:09,619 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6d42434f-997b-4f8a-b65d-895b112183df
2025-08-07 03:54:09,631 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6d42434f-997b-4f8a-b65d-895b112183df
2025-08-07 03:54:09,642 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/5576ea6c-9c3a-49fa-82a7-e16d5b7dd38b
2025-08-07 03:54:09,660 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1598293844382729745', 'uuid-lslong': '12870089930484582156'}, 'enable': 'true', 'created': '2025-08-07T03:52:23', 'last-modified': '2025-08-07T03:54:08', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.104667'}
2025-08-07 03:54:09,670 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-07 03:54:09,677 - DEBUG - Control-node 10.0.0.130 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1598293844382729745', 'uuid-lslong': '12870089930484582156'}, 'enable': 'true', 'created': '2025-08-07T03:52:23', 'last-modified': '2025-08-07T03:54:08', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.123408'}
2025-08-07 03:54:09,688 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-07 03:54:09,696 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1598293844382729745', 'uuid-lslong': '12870089930484582156'}, 'enable': 'true', 'created': '2025-08-07T03:52:23', 'last-modified': '2025-08-07T03:54:08', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.148379'}
2025-08-07 03:54:09,705 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-07 03:54:09,705 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn2-75786057 passed
2025-08-07 03:54:09,705 - DEBUG - ====Verifying policy data for ctest-vn2-75786057 in API_Server ======
2025-08-07 03:54:09,706 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:54:09,714 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:54:09,729 - DEBUG - Requesting: http://10.0.0.56:8082/project/e750dce5-6593-495f-a498-7395bff3b2dd
2025-08-07 03:54:09,842 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/162e47c8-8920-4611-b29b-be9a65dd870c
2025-08-07 03:54:09,856 - DEBUG - ==>Verifying data for policy with id: 849b108b-411e-4ad9-8ac0-e71a8a3af6a1, fqn: ['default-domain', 'ctest-AnalyticsTestSanityWithResource-52543708', 'policy1']
2025-08-07 03:54:09,856 - INFO - VN ctest-vn2-75786057 Policy verification: verify_vn_policy_in_api_server, status: True
2025-08-07 03:54:09,856 - DEBUG - Verifying the vn in opserver
2025-08-07 03:54:09,856 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057 virtual network link through opserver 10.0.0.56
2025-08-07 03:54:09,856 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-08-07 03:54:09,874 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057?flat'}
2025-08-07 03:54:09,874 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057 virtual network link through opserver 10.0.0.130
2025-08-07 03:54:09,874 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-networks
2025-08-07 03:54:09,893 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'href': 'http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057?flat'}
2025-08-07 03:54:09,893 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057 virtual network link through opserver 10.0.0.249
2025-08-07 03:54:09,893 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-08-07 03:54:09,910 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057?flat'}
2025-08-07 03:54:09,910 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057 is found in opserver
2025-08-07 03:54:09,925 - DEBUG - VRF ids for VN ctest-vn2-75786057: {'10.0.0.41': '2', '10.0.0.61': '3'}
2025-08-07 03:54:09,925 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:54:09,941 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:54:09,961 - DEBUG - Requesting: http://10.0.0.56:8082/project/e750dce5-6593-495f-a498-7395bff3b2dd
2025-08-07 03:54:10,075 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/86dfcaa9-5861-472a-be9d-3232f6e3d340
2025-08-07 03:54:10,089 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/86dfcaa9-5861-472a-be9d-3232f6e3d340
2025-08-07 03:54:10,107 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e70612e3-8778-48cd-badf-80905eabdc5c
2025-08-07 03:54:10,119 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e70612e3-8778-48cd-badf-80905eabdc5c
2025-08-07 03:54:10,132 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/8810dbb5-1519-48dd-8bad-fa535a465593
2025-08-07 03:54:10,142 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-07 03:54:10,142 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/86dfcaa9-5861-472a-be9d-3232f6e3d340
2025-08-07 03:54:10,155 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e70612e3-8778-48cd-badf-80905eabdc5c
2025-08-07 03:54:10,179 - INFO - Verified VN network id 12 for VN 86dfcaa9-5861-472a-be9d-3232f6e3d340
2025-08-07 03:54:10,179 - INFO - Verifications in API Server for VN ctest-vn1-76278277 passed
2025-08-07 03:54:10,180 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/86dfcaa9-5861-472a-be9d-3232f6e3d340
2025-08-07 03:54:10,206 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e70612e3-8778-48cd-badf-80905eabdc5c
2025-08-07 03:54:10,221 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e70612e3-8778-48cd-badf-80905eabdc5c
2025-08-07 03:54:10,235 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/8810dbb5-1519-48dd-8bad-fa535a465593
2025-08-07 03:54:10,254 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9718709349569873706', 'uuid-lslong': '13735189633021760320'}, 'enable': 'true', 'created': '2025-08-07T03:51:46', 'last-modified': '2025-08-07T03:54:08', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.946645'}
2025-08-07 03:54:10,263 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-07 03:54:10,271 - DEBUG - Control-node 10.0.0.130 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9718709349569873706', 'uuid-lslong': '13735189633021760320'}, 'enable': 'true', 'created': '2025-08-07T03:51:46', 'last-modified': '2025-08-07T03:54:08', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.963818'}
2025-08-07 03:54:10,281 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-07 03:54:10,290 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9718709349569873706', 'uuid-lslong': '13735189633021760320'}, 'enable': 'true', 'created': '2025-08-07T03:51:46', 'last-modified': '2025-08-07T03:54:08', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.003991'}
2025-08-07 03:54:10,299 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-07 03:54:10,299 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn1-76278277 passed
2025-08-07 03:54:10,299 - DEBUG - ====Verifying policy data for ctest-vn1-76278277 in API_Server ======
2025-08-07 03:54:10,299 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:54:10,307 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:54:10,325 - DEBUG - Requesting: http://10.0.0.56:8082/project/e750dce5-6593-495f-a498-7395bff3b2dd
2025-08-07 03:54:10,431 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/86dfcaa9-5861-472a-be9d-3232f6e3d340
2025-08-07 03:54:10,445 - DEBUG - ==>Verifying data for policy with id: 849b108b-411e-4ad9-8ac0-e71a8a3af6a1, fqn: ['default-domain', 'ctest-AnalyticsTestSanityWithResource-52543708', 'policy1']
2025-08-07 03:54:10,446 - INFO - VN ctest-vn1-76278277 Policy verification: verify_vn_policy_in_api_server, status: True
2025-08-07 03:54:10,446 - DEBUG - Verifying the vn in opserver
2025-08-07 03:54:10,446 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277 virtual network link through opserver 10.0.0.56
2025-08-07 03:54:10,446 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-08-07 03:54:10,462 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277?flat'}
2025-08-07 03:54:10,462 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277 virtual network link through opserver 10.0.0.130
2025-08-07 03:54:10,462 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-networks
2025-08-07 03:54:10,485 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'href': 'http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277?flat'}
2025-08-07 03:54:10,485 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277 virtual network link through opserver 10.0.0.249
2025-08-07 03:54:10,485 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-08-07 03:54:10,502 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277?flat'}
2025-08-07 03:54:10,502 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277 is found in opserver
2025-08-07 03:54:10,517 - DEBUG - VRF ids for VN ctest-vn1-76278277: {'10.0.0.61': '2'}
2025-08-07 03:54:10,517 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:54:10,525 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:54:10,540 - DEBUG - Requesting: http://10.0.0.56:8082/project/e750dce5-6593-495f-a498-7395bff3b2dd
2025-08-07 03:54:10,644 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/162e47c8-8920-4611-b29b-be9a65dd870c
2025-08-07 03:54:10,659 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/162e47c8-8920-4611-b29b-be9a65dd870c
2025-08-07 03:54:10,679 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6d42434f-997b-4f8a-b65d-895b112183df
2025-08-07 03:54:10,691 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6d42434f-997b-4f8a-b65d-895b112183df
2025-08-07 03:54:10,703 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/5576ea6c-9c3a-49fa-82a7-e16d5b7dd38b
2025-08-07 03:54:10,712 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-07 03:54:10,712 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/162e47c8-8920-4611-b29b-be9a65dd870c
2025-08-07 03:54:10,728 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6d42434f-997b-4f8a-b65d-895b112183df
2025-08-07 03:54:10,752 - INFO - Verified VN network id 13 for VN 162e47c8-8920-4611-b29b-be9a65dd870c
2025-08-07 03:54:10,752 - INFO - Verifications in API Server for VN ctest-vn2-75786057 passed
2025-08-07 03:54:10,753 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/162e47c8-8920-4611-b29b-be9a65dd870c
2025-08-07 03:54:10,768 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6d42434f-997b-4f8a-b65d-895b112183df
2025-08-07 03:54:10,781 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6d42434f-997b-4f8a-b65d-895b112183df
2025-08-07 03:54:10,796 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/5576ea6c-9c3a-49fa-82a7-e16d5b7dd38b
2025-08-07 03:54:10,818 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1598293844382729745', 'uuid-lslong': '12870089930484582156'}, 'enable': 'true', 'created': '2025-08-07T03:52:23', 'last-modified': '2025-08-07T03:54:08', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.260483'}
2025-08-07 03:54:10,829 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-07 03:54:10,837 - DEBUG - Control-node 10.0.0.130 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1598293844382729745', 'uuid-lslong': '12870089930484582156'}, 'enable': 'true', 'created': '2025-08-07T03:52:23', 'last-modified': '2025-08-07T03:54:08', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.282909'}
2025-08-07 03:54:10,847 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-07 03:54:10,856 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1598293844382729745', 'uuid-lslong': '12870089930484582156'}, 'enable': 'true', 'created': '2025-08-07T03:52:23', 'last-modified': '2025-08-07T03:54:08', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.307942'}
2025-08-07 03:54:10,866 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-07 03:54:10,866 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn2-75786057 passed
2025-08-07 03:54:10,867 - DEBUG - ====Verifying policy data for ctest-vn2-75786057 in API_Server ======
2025-08-07 03:54:10,867 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:54:10,875 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:54:10,892 - DEBUG - Requesting: http://10.0.0.56:8082/project/e750dce5-6593-495f-a498-7395bff3b2dd
2025-08-07 03:54:10,999 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/162e47c8-8920-4611-b29b-be9a65dd870c
2025-08-07 03:54:11,021 - DEBUG - ==>Verifying data for policy with id: 849b108b-411e-4ad9-8ac0-e71a8a3af6a1, fqn: ['default-domain', 'ctest-AnalyticsTestSanityWithResource-52543708', 'policy1']
2025-08-07 03:54:11,021 - INFO - VN ctest-vn2-75786057 Policy verification: verify_vn_policy_in_api_server, status: True
2025-08-07 03:54:11,021 - DEBUG - Verifying the vn in opserver
2025-08-07 03:54:11,021 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057 virtual network link through opserver 10.0.0.56
2025-08-07 03:54:11,021 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-08-07 03:54:11,040 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057?flat'}
2025-08-07 03:54:11,040 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057 virtual network link through opserver 10.0.0.130
2025-08-07 03:54:11,040 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-networks
2025-08-07 03:54:11,058 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'href': 'http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057?flat'}
2025-08-07 03:54:11,058 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057 virtual network link through opserver 10.0.0.249
2025-08-07 03:54:11,058 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-08-07 03:54:11,075 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057?flat'}
2025-08-07 03:54:11,075 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057 is found in opserver
2025-08-07 03:54:11,090 - DEBUG - VRF ids for VN ctest-vn2-75786057: {'10.0.0.41': '2', '10.0.0.61': '3'}
2025-08-07 03:54:11,090 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:54:11,099 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:54:11,114 - DEBUG - Requesting: http://10.0.0.56:8082/project/e750dce5-6593-495f-a498-7395bff3b2dd
2025-08-07 03:54:11,232 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/2e571d31-de3d-4b1a-8f57-2e1cdffe401c
2025-08-07 03:54:11,249 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/2e571d31-de3d-4b1a-8f57-2e1cdffe401c
2025-08-07 03:54:11,262 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/a103d4df-1e11-4145-920a-baa03016b822
2025-08-07 03:54:11,277 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/a103d4df-1e11-4145-920a-baa03016b822
2025-08-07 03:54:11,291 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/47dce227-58af-481e-9897-d2c61de06498
2025-08-07 03:54:11,301 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-07 03:54:11,301 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/2e571d31-de3d-4b1a-8f57-2e1cdffe401c
2025-08-07 03:54:11,314 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/a103d4df-1e11-4145-920a-baa03016b822
2025-08-07 03:54:11,338 - INFO - Verified VN network id 14 for VN 2e571d31-de3d-4b1a-8f57-2e1cdffe401c
2025-08-07 03:54:11,338 - INFO - Verifications in API Server for VN ctest-fip_vn-23932101 passed
2025-08-07 03:54:11,338 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/2e571d31-de3d-4b1a-8f57-2e1cdffe401c
2025-08-07 03:54:11,351 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/a103d4df-1e11-4145-920a-baa03016b822
2025-08-07 03:54:11,362 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/a103d4df-1e11-4145-920a-baa03016b822
2025-08-07 03:54:11,373 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/47dce227-58af-481e-9897-d2c61de06498
2025-08-07 03:54:11,391 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3339169748737674010', 'uuid-lslong': '10328774971949465628'}, 'enable': 'true', 'created': '2025-08-07T03:52:24', 'last-modified': '2025-08-07T03:52:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:01:46.888011'}
2025-08-07 03:54:11,400 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-07 03:54:11,409 - DEBUG - Control-node 10.0.0.130 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3339169748737674010', 'uuid-lslong': '10328774971949465628'}, 'enable': 'true', 'created': '2025-08-07T03:52:24', 'last-modified': '2025-08-07T03:52:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:01:46.906420'}
2025-08-07 03:54:11,420 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-07 03:54:11,429 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3339169748737674010', 'uuid-lslong': '10328774971949465628'}, 'enable': 'true', 'created': '2025-08-07T03:52:24', 'last-modified': '2025-08-07T03:52:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:01:46.931358'}
2025-08-07 03:54:11,439 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-07 03:54:11,439 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-fip_vn-23932101 passed
2025-08-07 03:54:11,439 - DEBUG - ====Verifying policy data for ctest-fip_vn-23932101 in API_Server ======
2025-08-07 03:54:11,439 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:54:11,448 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:54:11,464 - DEBUG - Requesting: http://10.0.0.56:8082/project/e750dce5-6593-495f-a498-7395bff3b2dd
2025-08-07 03:54:11,576 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/2e571d31-de3d-4b1a-8f57-2e1cdffe401c
2025-08-07 03:54:11,589 - DEBUG - =>VN ctest-fip_vn-23932101 has no policy to be verified
2025-08-07 03:54:11,590 - DEBUG - Verifying the vn in opserver
2025-08-07 03:54:11,590 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101 virtual network link through opserver 10.0.0.56
2025-08-07 03:54:11,590 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-08-07 03:54:11,607 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101?flat'}
2025-08-07 03:54:11,607 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101 virtual network link through opserver 10.0.0.130
2025-08-07 03:54:11,607 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-networks
2025-08-07 03:54:11,626 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101', 'href': 'http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101?flat'}
2025-08-07 03:54:11,626 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101 virtual network link through opserver 10.0.0.249
2025-08-07 03:54:11,626 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-08-07 03:54:11,642 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101?flat'}
2025-08-07 03:54:11,642 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101 is found in opserver
2025-08-07 03:54:11,659 - DEBUG - VRF ids for VN ctest-fip_vn-23932101: {'10.0.0.41': '3'}
2025-08-07 03:54:11,659 - INFO - start time= 1754538851659256
2025-08-07 03:54:11,659 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 3.231.40.67, host_string: cirros@169.254.0.3, password: gocubsgogateway: ubuntu@10.0.0.61, gateway password: c0ntrail123
2025-08-07 03:54:11,659 - DEBUG - ping -s 56 -c 3 -W 1 3.231.40.67
2025-08-07 03:54:13,934 - DEBUG - PING 3.231.40.67 (3.231.40.67): 56 data bytes
64 bytes from 3.231.40.67: seq=0 ttl=63 time=6.029 ms
64 bytes from 3.231.40.67: seq=1 ttl=63 time=1.666 ms
64 bytes from 3.231.40.67: seq=2 ttl=63 time=1.514 ms
--- 3.231.40.67 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 1.514/3.069/6.029 ms
2025-08-07 03:54:13,934 - INFO - Ping to IP 3.231.40.67 from VM ctest-vn1_vm1-14989368 passed
2025-08-07 03:54:23,935 - INFO - Verify session samples and teardown pkts
2025-08-07 03:54:23,935 - INFO - Verifying SessionSeriesTable through opserver 10.0.0.56
2025-08-07 03:54:23,940 - DEBUG - Posting: http://10.0.0.56:8081/analytics/query, payload {'table': 'SessionSeriesTable', 'start_time': 1754538851659256, 'end_time': 1754538863940591, 'select_fields': ['T'], 'where': [[{'name': 'vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'op': 1, 'value2': None}, {'name': 'remote_vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'op': 1, 'value2': None}, {'name': 'protocol', 'value': '1', 'op': 1, 'value2': None}]], 'session_type': 'client'}
2025-08-07 03:54:24,970 - DEBUG - [{'T': 1754538852091349}, {'T': 1754538853091416}, {'T': 1754538854091496}]
2025-08-07 03:54:24,970 - DEBUG - Posting: http://10.0.0.56:8081/analytics/query, payload {'table': 'SessionSeriesTable', 'start_time': 1754538851659256, 'end_time': 1754538864970482, 'select_fields': ['T'], 'where': [[{'name': 'vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'op': 1, 'value2': None}, {'name': 'remote_vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'op': 1, 'value2': None}, {'name': 'protocol', 'value': '1', 'op': 1, 'value2': None}]], 'session_type': 'server'}
2025-08-07 03:54:25,998 - DEBUG - [{'T': 1754538852645326}, {'T': 1754538853645469}, {'T': 1754538854645549}]
2025-08-07 03:54:25,998 - DEBUG - Posting: http://10.0.0.56:8081/analytics/query, payload {'table': 'SessionSeriesTable', 'start_time': 1754538851659256, 'end_time': 1754538865998212, 'select_fields': ['vn', 'remote_vn', 'sample_count'], 'where': [[{'name': 'vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'op': 1, 'value2': None}, {'name': 'remote_vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'op': 1, 'value2': None}, {'name': 'protocol', 'value': '1', 'op': 1, 'value2': None}]], 'session_type': 'client'}
2025-08-07 03:54:27,024 - DEBUG - [{'remote_vn': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'vn': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'sample_count': 3}]
2025-08-07 03:54:27,024 - INFO - wait for the flows to get expire
2025-08-07 03:57:47,025 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3767-2:Compute:contrail-vrouter-agent:0?flat
2025-08-07 03:57:47,131 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3767-2?flat
2025-08-07 03:57:47,226 - DEBUG - Posting: http://10.0.0.56:8081/analytics/query, payload {'table': 'SessionRecordTable', 'start_time': 1754538851659256, 'end_time': 1754539067226691, 'select_fields': ['vn', 'remote_vn', 'forward_teardown_pkts', 'reverse_teardown_pkts'], 'where': [[{'name': 'vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'op': 1, 'value2': None}, {'name': 'remote_vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'op': 1, 'value2': None}, {'name': 'protocol', 'value': '1', 'op': 1, 'value2': None}]], 'session_type': 'client'}
2025-08-07 03:57:48,306 - DEBUG - [{'forward_flow_uuid': '3305216b-a1a1-4204-9d82-eef454e711d6', 'remote_vn': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'reverse_flow_uuid': 'd8b97c09-61bd-4ae8-8108-2548b3643cb6', 'vn': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'forward_teardown_pkts': 3, 'reverse_teardown_pkts': 3}]
2025-08-07 03:57:48,306 - DEBUG - Posting: http://10.0.0.56:8081/analytics/query, payload {'table': 'SessionSeriesTable', 'start_time': 1754538851659256, 'end_time': 1754539068306424, 'select_fields': ['vn', 'remote_vn', 'sample_count'], 'where': [[{'name': 'vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'op': 1, 'value2': None}, {'name': 'remote_vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'op': 1, 'value2': None}, {'name': 'protocol', 'value': '1', 'op': 1, 'value2': None}]], 'session_type': 'client'}
2025-08-07 03:57:49,392 - DEBUG - Posting: http://10.0.0.56:8081/analytics/query, payload {'table': 'SessionSeriesTable', 'start_time': 1754538851659256, 'end_time': 1754539069392763, 'select_fields': ['vn', 'remote_vn', 'sample_count'], 'where': [[{'name': 'vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'op': 1, 'value2': None}, {'name': 'remote_vn', 'value': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'op': 1, 'value2': None}, {'name': 'protocol', 'value': '1', 'op': 1, 'value2': None}]], 'session_type': 'server'}
2025-08-07 03:57:50,418 - DEBUG - [{'remote_vn': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'vn': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'sample_count': 4}]
2025-08-07 03:57:51,037 - INFO - Deleted policy policy1
2025-08-07 03:57:51,214 - INFO - Setting flow export rate: None
2025-08-07 03:57:52,521 - DEBUG - Skipping xmpp flap check
2025-08-07 03:57:52,522 - INFO - END TEST : test_verify_session_sampling_teardown : PASSED[0:03:46]
2025-08-07 03:57:52,522 - INFO - --------------------------------------------------------------------------------
2025-08-07 03:57:52,525 - INFO - ================================================================================
2025-08-07 03:57:52,526 - INFO - STARTING TEST : test_verify_session_series_table_inter_vn
2025-08-07 03:57:52,526 - INFO - TEST DESCRIPTION : Verify session series table ,generated stats between different vns
1.query client session samples
2.query for server ports
3.sort results by server_port column
4.verify granularity with T=10
5.verify sampled bytes
6.verify logged bytes
7.query and verify filter by action
2025-08-07 03:57:53,814 - DEBUG - Skipping xmpp flap check
2025-08-07 03:57:53,814 - INFO - Initial checks done. Running the testcase now
2025-08-07 03:57:53,814 - INFO -
2025-08-07 03:57:54,034 - INFO - Setting flow export rate: 100
2025-08-07 03:57:54,082 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = udp, 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-07 03:57:54,499 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '86dfcaa9-5861-472a-be9d-3232f6e3d340', 'name': 'ctest-vn1-76278277', 'tenant_id': 'e750dce56593495fa4987395bff3b2dd', 'project_id': 'e750dce56593495fa4987395bff3b2dd', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['eaf77d94-f3e1-4e0c-bac8-101ee189b394'], 'fq_name': ['default-domain', 'ctest-AnalyticsTestSanityWithResource-52543708', 'ctest-vn1-76278277'], 'policys': [['default-domain', 'ctest-AnalyticsTestSanityWithResource-52543708', 'policy1']], 'subnet_ipam': [{'subnet_cidr': '5.15.152.192/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}}
2025-08-07 03:57:54,748 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '162e47c8-8920-4611-b29b-be9a65dd870c', 'name': 'ctest-vn2-75786057', 'tenant_id': 'e750dce56593495fa4987395bff3b2dd', 'project_id': 'e750dce56593495fa4987395bff3b2dd', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['495c77ec-a577-446f-866c-99fd951841eb'], 'fq_name': ['default-domain', 'ctest-AnalyticsTestSanityWithResource-52543708', 'ctest-vn2-75786057'], 'policys': [['default-domain', 'ctest-AnalyticsTestSanityWithResource-52543708', 'policy1']], 'subnet_ipam': [{'subnet_cidr': '3.231.40.64/26', 'ipam_fq_name': ['default-domain', 'default-project', 'default-network-ipam']}], 'port_security_enabled': True, 'description': ''}}
2025-08-07 03:57:54,913 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:57:54,957 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:57:55,036 - DEBUG - Requesting: http://10.0.0.56:8082/project/e750dce5-6593-495f-a498-7395bff3b2dd
2025-08-07 03:57:55,162 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/86dfcaa9-5861-472a-be9d-3232f6e3d340
2025-08-07 03:57:55,196 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/86dfcaa9-5861-472a-be9d-3232f6e3d340
2025-08-07 03:57:55,225 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e70612e3-8778-48cd-badf-80905eabdc5c
2025-08-07 03:57:55,243 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e70612e3-8778-48cd-badf-80905eabdc5c
2025-08-07 03:57:55,267 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/8810dbb5-1519-48dd-8bad-fa535a465593
2025-08-07 03:57:55,288 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-07 03:57:55,288 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/86dfcaa9-5861-472a-be9d-3232f6e3d340
2025-08-07 03:57:55,304 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e70612e3-8778-48cd-badf-80905eabdc5c
2025-08-07 03:57:55,335 - INFO - Verified VN network id 12 for VN 86dfcaa9-5861-472a-be9d-3232f6e3d340
2025-08-07 03:57:55,336 - INFO - Verifications in API Server for VN ctest-vn1-76278277 passed
2025-08-07 03:57:55,336 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/86dfcaa9-5861-472a-be9d-3232f6e3d340
2025-08-07 03:57:55,352 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e70612e3-8778-48cd-badf-80905eabdc5c
2025-08-07 03:57:55,364 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e70612e3-8778-48cd-badf-80905eabdc5c
2025-08-07 03:57:55,381 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/8810dbb5-1519-48dd-8bad-fa535a465593
2025-08-07 03:57:55,407 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9718709349569873706', 'uuid-lslong': '13735189633021760320'}, 'enable': 'true', 'created': '2025-08-07T03:51:46', 'last-modified': '2025-08-07T03:57:54', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.959420'}
2025-08-07 03:57:55,416 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-07 03:57:55,423 - DEBUG - Control-node 10.0.0.130 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9718709349569873706', 'uuid-lslong': '13735189633021760320'}, 'enable': 'true', 'created': '2025-08-07T03:51:46', 'last-modified': '2025-08-07T03:57:54', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.981253'}
2025-08-07 03:57:55,432 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-07 03:57:55,440 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9718709349569873706', 'uuid-lslong': '13735189633021760320'}, 'enable': 'true', 'created': '2025-08-07T03:51:46', 'last-modified': '2025-08-07T03:57:54', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.998847'}
2025-08-07 03:57:55,449 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-07 03:57:55,449 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn1-76278277 passed
2025-08-07 03:57:55,450 - DEBUG - ====Verifying policy data for ctest-vn1-76278277 in API_Server ======
2025-08-07 03:57:55,450 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:57:55,457 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:57:55,474 - DEBUG - Requesting: http://10.0.0.56:8082/project/e750dce5-6593-495f-a498-7395bff3b2dd
2025-08-07 03:57:55,593 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/86dfcaa9-5861-472a-be9d-3232f6e3d340
2025-08-07 03:57:55,608 - DEBUG - ==>Verifying data for policy with id: 6544adc3-0f57-469c-89db-fefcc4c59643, fqn: ['default-domain', 'ctest-AnalyticsTestSanityWithResource-52543708', 'policy1']
2025-08-07 03:57:55,608 - INFO - VN ctest-vn1-76278277 Policy verification: verify_vn_policy_in_api_server, status: True
2025-08-07 03:57:55,608 - DEBUG - Verifying the vn in opserver
2025-08-07 03:57:55,608 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277 virtual network link through opserver 10.0.0.56
2025-08-07 03:57:55,608 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-08-07 03:57:55,625 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277?flat'}
2025-08-07 03:57:55,625 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277 virtual network link through opserver 10.0.0.130
2025-08-07 03:57:55,625 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-networks
2025-08-07 03:57:55,695 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'href': 'http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277?flat'}
2025-08-07 03:57:55,695 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277 virtual network link through opserver 10.0.0.249
2025-08-07 03:57:55,695 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-08-07 03:57:55,764 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277?flat'}
2025-08-07 03:57:55,764 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277 is found in opserver
2025-08-07 03:57:55,779 - DEBUG - VRF ids for VN ctest-vn1-76278277: {'10.0.0.61': '2'}
2025-08-07 03:57:55,779 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:57:55,787 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:57:55,803 - DEBUG - Requesting: http://10.0.0.56:8082/project/e750dce5-6593-495f-a498-7395bff3b2dd
2025-08-07 03:57:55,911 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/162e47c8-8920-4611-b29b-be9a65dd870c
2025-08-07 03:57:55,933 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/162e47c8-8920-4611-b29b-be9a65dd870c
2025-08-07 03:57:55,949 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6d42434f-997b-4f8a-b65d-895b112183df
2025-08-07 03:57:55,961 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6d42434f-997b-4f8a-b65d-895b112183df
2025-08-07 03:57:55,973 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/5576ea6c-9c3a-49fa-82a7-e16d5b7dd38b
2025-08-07 03:57:55,985 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-07 03:57:55,985 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/162e47c8-8920-4611-b29b-be9a65dd870c
2025-08-07 03:57:56,007 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6d42434f-997b-4f8a-b65d-895b112183df
2025-08-07 03:57:56,031 - INFO - Verified VN network id 13 for VN 162e47c8-8920-4611-b29b-be9a65dd870c
2025-08-07 03:57:56,031 - INFO - Verifications in API Server for VN ctest-vn2-75786057 passed
2025-08-07 03:57:56,031 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/162e47c8-8920-4611-b29b-be9a65dd870c
2025-08-07 03:57:56,051 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6d42434f-997b-4f8a-b65d-895b112183df
2025-08-07 03:57:56,070 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6d42434f-997b-4f8a-b65d-895b112183df
2025-08-07 03:57:56,080 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/5576ea6c-9c3a-49fa-82a7-e16d5b7dd38b
2025-08-07 03:57:56,098 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1598293844382729745', 'uuid-lslong': '12870089930484582156'}, 'enable': 'true', 'created': '2025-08-07T03:52:23', 'last-modified': '2025-08-07T03:57:54', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.387551'}
2025-08-07 03:57:56,107 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-07 03:57:56,114 - DEBUG - Control-node 10.0.0.130 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1598293844382729745', 'uuid-lslong': '12870089930484582156'}, 'enable': 'true', 'created': '2025-08-07T03:52:23', 'last-modified': '2025-08-07T03:57:54', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.407623'}
2025-08-07 03:57:56,126 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-07 03:57:56,132 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1598293844382729745', 'uuid-lslong': '12870089930484582156'}, 'enable': 'true', 'created': '2025-08-07T03:52:23', 'last-modified': '2025-08-07T03:57:54', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.430098'}
2025-08-07 03:57:56,141 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-07 03:57:56,141 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn2-75786057 passed
2025-08-07 03:57:56,141 - DEBUG - ====Verifying policy data for ctest-vn2-75786057 in API_Server ======
2025-08-07 03:57:56,141 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:57:56,149 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:57:56,163 - DEBUG - Requesting: http://10.0.0.56:8082/project/e750dce5-6593-495f-a498-7395bff3b2dd
2025-08-07 03:57:56,282 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/162e47c8-8920-4611-b29b-be9a65dd870c
2025-08-07 03:57:56,295 - DEBUG - ==>Verifying data for policy with id: 6544adc3-0f57-469c-89db-fefcc4c59643, fqn: ['default-domain', 'ctest-AnalyticsTestSanityWithResource-52543708', 'policy1']
2025-08-07 03:57:56,295 - INFO - VN ctest-vn2-75786057 Policy verification: verify_vn_policy_in_api_server, status: True
2025-08-07 03:57:56,296 - DEBUG - Verifying the vn in opserver
2025-08-07 03:57:56,296 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057 virtual network link through opserver 10.0.0.56
2025-08-07 03:57:56,296 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-08-07 03:57:56,312 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057?flat'}
2025-08-07 03:57:56,312 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057 virtual network link through opserver 10.0.0.130
2025-08-07 03:57:56,312 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-networks
2025-08-07 03:57:56,378 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'href': 'http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057?flat'}
2025-08-07 03:57:56,378 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057 virtual network link through opserver 10.0.0.249
2025-08-07 03:57:56,378 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-08-07 03:57:56,441 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057?flat'}
2025-08-07 03:57:56,441 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057 is found in opserver
2025-08-07 03:57:56,456 - DEBUG - VRF ids for VN ctest-vn2-75786057: {'10.0.0.41': '2', '10.0.0.61': '3'}
2025-08-07 03:57:56,456 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:57:56,463 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:57:56,478 - DEBUG - Requesting: http://10.0.0.56:8082/project/e750dce5-6593-495f-a498-7395bff3b2dd
2025-08-07 03:57:56,589 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/86dfcaa9-5861-472a-be9d-3232f6e3d340
2025-08-07 03:57:56,609 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/86dfcaa9-5861-472a-be9d-3232f6e3d340
2025-08-07 03:57:56,629 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e70612e3-8778-48cd-badf-80905eabdc5c
2025-08-07 03:57:56,641 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e70612e3-8778-48cd-badf-80905eabdc5c
2025-08-07 03:57:56,651 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/8810dbb5-1519-48dd-8bad-fa535a465593
2025-08-07 03:57:56,660 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-07 03:57:56,660 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/86dfcaa9-5861-472a-be9d-3232f6e3d340
2025-08-07 03:57:56,674 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e70612e3-8778-48cd-badf-80905eabdc5c
2025-08-07 03:57:56,696 - INFO - Verified VN network id 12 for VN 86dfcaa9-5861-472a-be9d-3232f6e3d340
2025-08-07 03:57:56,696 - INFO - Verifications in API Server for VN ctest-vn1-76278277 passed
2025-08-07 03:57:56,697 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/86dfcaa9-5861-472a-be9d-3232f6e3d340
2025-08-07 03:57:56,712 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e70612e3-8778-48cd-badf-80905eabdc5c
2025-08-07 03:57:56,725 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e70612e3-8778-48cd-badf-80905eabdc5c
2025-08-07 03:57:56,737 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/8810dbb5-1519-48dd-8bad-fa535a465593
2025-08-07 03:57:56,758 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9718709349569873706', 'uuid-lslong': '13735189633021760320'}, 'enable': 'true', 'created': '2025-08-07T03:51:46', 'last-modified': '2025-08-07T03:57:54', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.318351'}
2025-08-07 03:57:56,773 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-07 03:57:56,782 - DEBUG - Control-node 10.0.0.130 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9718709349569873706', 'uuid-lslong': '13735189633021760320'}, 'enable': 'true', 'created': '2025-08-07T03:51:46', 'last-modified': '2025-08-07T03:57:54', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.338458'}
2025-08-07 03:57:56,790 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-07 03:57:56,798 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9718709349569873706', 'uuid-lslong': '13735189633021760320'}, 'enable': 'true', 'created': '2025-08-07T03:51:46', 'last-modified': '2025-08-07T03:57:54', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.356705'}
2025-08-07 03:57:56,806 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-07 03:57:56,806 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn1-76278277 passed
2025-08-07 03:57:56,806 - DEBUG - ====Verifying policy data for ctest-vn1-76278277 in API_Server ======
2025-08-07 03:57:56,807 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:57:56,814 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:57:56,831 - DEBUG - Requesting: http://10.0.0.56:8082/project/e750dce5-6593-495f-a498-7395bff3b2dd
2025-08-07 03:57:56,947 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/86dfcaa9-5861-472a-be9d-3232f6e3d340
2025-08-07 03:57:56,964 - DEBUG - ==>Verifying data for policy with id: 6544adc3-0f57-469c-89db-fefcc4c59643, fqn: ['default-domain', 'ctest-AnalyticsTestSanityWithResource-52543708', 'policy1']
2025-08-07 03:57:56,964 - INFO - VN ctest-vn1-76278277 Policy verification: verify_vn_policy_in_api_server, status: True
2025-08-07 03:57:56,965 - DEBUG - Verifying the vn in opserver
2025-08-07 03:57:56,965 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277 virtual network link through opserver 10.0.0.56
2025-08-07 03:57:56,965 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-08-07 03:57:56,984 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277?flat'}
2025-08-07 03:57:56,984 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277 virtual network link through opserver 10.0.0.130
2025-08-07 03:57:56,984 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-networks
2025-08-07 03:57:57,005 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'href': 'http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277?flat'}
2025-08-07 03:57:57,005 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277 virtual network link through opserver 10.0.0.249
2025-08-07 03:57:57,005 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-08-07 03:57:57,071 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277?flat'}
2025-08-07 03:57:57,071 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277 is found in opserver
2025-08-07 03:57:57,086 - DEBUG - VRF ids for VN ctest-vn1-76278277: {'10.0.0.61': '2'}
2025-08-07 03:57:57,086 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:57:57,095 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:57:57,111 - DEBUG - Requesting: http://10.0.0.56:8082/project/e750dce5-6593-495f-a498-7395bff3b2dd
2025-08-07 03:57:57,213 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/162e47c8-8920-4611-b29b-be9a65dd870c
2025-08-07 03:57:57,235 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/162e47c8-8920-4611-b29b-be9a65dd870c
2025-08-07 03:57:57,250 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6d42434f-997b-4f8a-b65d-895b112183df
2025-08-07 03:57:57,262 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6d42434f-997b-4f8a-b65d-895b112183df
2025-08-07 03:57:57,279 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/5576ea6c-9c3a-49fa-82a7-e16d5b7dd38b
2025-08-07 03:57:57,291 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-07 03:57:57,291 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/162e47c8-8920-4611-b29b-be9a65dd870c
2025-08-07 03:57:57,308 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6d42434f-997b-4f8a-b65d-895b112183df
2025-08-07 03:57:57,334 - INFO - Verified VN network id 13 for VN 162e47c8-8920-4611-b29b-be9a65dd870c
2025-08-07 03:57:57,334 - INFO - Verifications in API Server for VN ctest-vn2-75786057 passed
2025-08-07 03:57:57,335 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/162e47c8-8920-4611-b29b-be9a65dd870c
2025-08-07 03:57:57,350 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6d42434f-997b-4f8a-b65d-895b112183df
2025-08-07 03:57:57,361 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6d42434f-997b-4f8a-b65d-895b112183df
2025-08-07 03:57:57,373 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/5576ea6c-9c3a-49fa-82a7-e16d5b7dd38b
2025-08-07 03:57:57,394 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1598293844382729745', 'uuid-lslong': '12870089930484582156'}, 'enable': 'true', 'created': '2025-08-07T03:52:23', 'last-modified': '2025-08-07T03:57:54', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.680873'}
2025-08-07 03:57:57,404 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-07 03:57:57,412 - DEBUG - Control-node 10.0.0.130 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1598293844382729745', 'uuid-lslong': '12870089930484582156'}, 'enable': 'true', 'created': '2025-08-07T03:52:23', 'last-modified': '2025-08-07T03:57:54', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.706072'}
2025-08-07 03:57:57,422 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-07 03:57:57,431 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1598293844382729745', 'uuid-lslong': '12870089930484582156'}, 'enable': 'true', 'created': '2025-08-07T03:52:23', 'last-modified': '2025-08-07T03:57:54', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.727682'}
2025-08-07 03:57:57,441 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-07 03:57:57,441 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn2-75786057 passed
2025-08-07 03:57:57,441 - DEBUG - ====Verifying policy data for ctest-vn2-75786057 in API_Server ======
2025-08-07 03:57:57,441 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:57:57,450 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:57:57,470 - DEBUG - Requesting: http://10.0.0.56:8082/project/e750dce5-6593-495f-a498-7395bff3b2dd
2025-08-07 03:57:57,580 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/162e47c8-8920-4611-b29b-be9a65dd870c
2025-08-07 03:57:57,602 - DEBUG - ==>Verifying data for policy with id: 6544adc3-0f57-469c-89db-fefcc4c59643, fqn: ['default-domain', 'ctest-AnalyticsTestSanityWithResource-52543708', 'policy1']
2025-08-07 03:57:57,602 - INFO - VN ctest-vn2-75786057 Policy verification: verify_vn_policy_in_api_server, status: True
2025-08-07 03:57:57,602 - DEBUG - Verifying the vn in opserver
2025-08-07 03:57:57,602 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057 virtual network link through opserver 10.0.0.56
2025-08-07 03:57:57,602 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-08-07 03:57:57,619 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057?flat'}
2025-08-07 03:57:57,619 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057 virtual network link through opserver 10.0.0.130
2025-08-07 03:57:57,619 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-networks
2025-08-07 03:57:57,639 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'href': 'http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057?flat'}
2025-08-07 03:57:57,639 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057 virtual network link through opserver 10.0.0.249
2025-08-07 03:57:57,639 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-08-07 03:57:57,657 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057?flat'}
2025-08-07 03:57:57,657 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057 is found in opserver
2025-08-07 03:57:57,671 - DEBUG - VRF ids for VN ctest-vn2-75786057: {'10.0.0.41': '2', '10.0.0.61': '3'}
2025-08-07 03:57:57,671 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:57:57,679 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:57:57,695 - DEBUG - Requesting: http://10.0.0.56:8082/project/e750dce5-6593-495f-a498-7395bff3b2dd
2025-08-07 03:57:57,824 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/2e571d31-de3d-4b1a-8f57-2e1cdffe401c
2025-08-07 03:57:57,840 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/2e571d31-de3d-4b1a-8f57-2e1cdffe401c
2025-08-07 03:57:57,856 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/a103d4df-1e11-4145-920a-baa03016b822
2025-08-07 03:57:57,882 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/a103d4df-1e11-4145-920a-baa03016b822
2025-08-07 03:57:57,893 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/47dce227-58af-481e-9897-d2c61de06498
2025-08-07 03:57:57,906 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-07 03:57:57,906 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/2e571d31-de3d-4b1a-8f57-2e1cdffe401c
2025-08-07 03:57:57,922 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/a103d4df-1e11-4145-920a-baa03016b822
2025-08-07 03:57:57,946 - INFO - Verified VN network id 14 for VN 2e571d31-de3d-4b1a-8f57-2e1cdffe401c
2025-08-07 03:57:57,947 - INFO - Verifications in API Server for VN ctest-fip_vn-23932101 passed
2025-08-07 03:57:57,947 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/2e571d31-de3d-4b1a-8f57-2e1cdffe401c
2025-08-07 03:57:57,963 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/a103d4df-1e11-4145-920a-baa03016b822
2025-08-07 03:57:57,979 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/a103d4df-1e11-4145-920a-baa03016b822
2025-08-07 03:57:57,992 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/47dce227-58af-481e-9897-d2c61de06498
2025-08-07 03:57:58,011 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3339169748737674010', 'uuid-lslong': '10328774971949465628'}, 'enable': 'true', 'created': '2025-08-07T03:52:24', 'last-modified': '2025-08-07T03:52:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:05:33.508200'}
2025-08-07 03:57:58,021 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-07 03:57:58,029 - DEBUG - Control-node 10.0.0.130 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3339169748737674010', 'uuid-lslong': '10328774971949465628'}, 'enable': 'true', 'created': '2025-08-07T03:52:24', 'last-modified': '2025-08-07T03:52:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:05:33.525931'}
2025-08-07 03:57:58,039 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-07 03:57:58,047 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-AnalyticsTestSanityWithResource-52543708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3339169748737674010', 'uuid-lslong': '10328774971949465628'}, 'enable': 'true', 'created': '2025-08-07T03:52:24', 'last-modified': '2025-08-07T03:52:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:05:33.549407'}
2025-08-07 03:57:58,056 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-07 03:57:58,056 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-fip_vn-23932101 passed
2025-08-07 03:57:58,056 - DEBUG - ====Verifying policy data for ctest-fip_vn-23932101 in API_Server ======
2025-08-07 03:57:58,056 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:57:58,065 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:57:58,081 - DEBUG - Requesting: http://10.0.0.56:8082/project/e750dce5-6593-495f-a498-7395bff3b2dd
2025-08-07 03:57:58,195 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/2e571d31-de3d-4b1a-8f57-2e1cdffe401c
2025-08-07 03:57:58,210 - DEBUG - =>VN ctest-fip_vn-23932101 has no policy to be verified
2025-08-07 03:57:58,210 - DEBUG - Verifying the vn in opserver
2025-08-07 03:57:58,210 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101 virtual network link through opserver 10.0.0.56
2025-08-07 03:57:58,210 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-08-07 03:57:58,227 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101?flat'}
2025-08-07 03:57:58,227 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101 virtual network link through opserver 10.0.0.130
2025-08-07 03:57:58,227 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-networks
2025-08-07 03:57:58,244 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101', 'href': 'http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101?flat'}
2025-08-07 03:57:58,244 - DEBUG - Verifying the default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101 virtual network link through opserver 10.0.0.249
2025-08-07 03:57:58,244 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-08-07 03:57:58,260 - DEBUG - vn link and name as {'name': 'default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101?flat'}
2025-08-07 03:57:58,260 - INFO - Validated that VN default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101 is found in opserver
2025-08-07 03:57:58,277 - DEBUG - VRF ids for VN ctest-fip_vn-23932101: {'10.0.0.41': '3'}
2025-08-07 03:57:58,277 - INFO - start time= 1754539078277677
2025-08-07 03:57:58,277 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-07 03:57:58,278 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-07 03:57:58,278 - DEBUG - File /contrail-test/tcutils/traffic_utils/../udpechoserver.py already copied to 3.231.40.67
2025-08-07 03:57:58,278 - DEBUG - Running remote_cmd, Cmd : python /tmp/udpechoserver.py --start_port 9000 --end_port 9000 --pid_file /tmp/server-ctest-random-36848789.pid --stats_file /tmp/server-ctest-random-36848789.stats 0<&- &> /tmp/server-ctest-random-36848789.log, host_string: cirros@169.254.0.3, password: gocubsgogateway: ubuntu@10.0.0.41, gateway password: c0ntrail123
2025-08-07 03:57:58,278 - DEBUG - nohup python /tmp/udpechoserver.py --start_port 9000 --end_port 9000 --pid_file /tmp/server-ctest-random-36848789.pid --stats_file /tmp/server-ctest-random-36848789.stats 0<&- &> /tmp/server-ctest-random-36848789.log &
2025-08-07 03:57:58,521 - DEBUG - None
2025-08-07 03:57:58,521 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-07 03:57:58,521 - DEBUG - File /contrail-test/tcutils/traffic_utils/../udpechoclient.py already copied to 5.15.152.195
2025-08-07 03:57:58,521 - DEBUG - Running remote_cmd, Cmd : python /tmp/udpechoclient.py --servers 3.231.40.67 --sport 8000 --dports 9000 --retry --count 100 --pid_file /tmp/client-ctest-random-36848789.pid --stats_file /tmp/client-ctest-random-36848789.stats 0<&- &> /tmp/client-ctest-random-36848789.log, host_string: cirros@169.254.0.3, password: gocubsgogateway: ubuntu@10.0.0.61, gateway password: c0ntrail123
2025-08-07 03:57:58,521 - DEBUG - nohup python /tmp/udpechoclient.py --servers 3.231.40.67 --sport 8000 --dports 9000 --retry --count 100 --pid_file /tmp/client-ctest-random-36848789.pid --stats_file /tmp/client-ctest-random-36848789.stats 0<&- &> /tmp/client-ctest-random-36848789.log &
2025-08-07 03:57:58,752 - DEBUG - None
2025-08-07 03:57:58,752 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-07 03:57:58,752 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-07 03:57:58,752 - DEBUG - File /contrail-test/tcutils/traffic_utils/../udpechoserver.py already copied to 3.231.40.67
2025-08-07 03:57:58,753 - DEBUG - Running remote_cmd, Cmd : python /tmp/udpechoserver.py --start_port 9001 --end_port 9001 --pid_file /tmp/server-ctest-random-07768473.pid --stats_file /tmp/server-ctest-random-07768473.stats 0<&- &> /tmp/server-ctest-random-07768473.log, host_string: cirros@169.254.0.3, password: gocubsgogateway: ubuntu@10.0.0.41, gateway password: c0ntrail123
2025-08-07 03:57:58,753 - DEBUG - nohup python /tmp/udpechoserver.py --start_port 9001 --end_port 9001 --pid_file /tmp/server-ctest-random-07768473.pid --stats_file /tmp/server-ctest-random-07768473.stats 0<&- &> /tmp/server-ctest-random-07768473.log &
2025-08-07 03:57:58,992 - DEBUG - None
2025-08-07 03:57:58,992 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-07 03:57:58,993 - DEBUG - File /contrail-test/tcutils/traffic_utils/../udpechoclient.py already copied to 5.15.152.195
2025-08-07 03:57:58,993 - DEBUG - Running remote_cmd, Cmd : python /tmp/udpechoclient.py --servers 3.231.40.67 --sport 8000 --dports 9001 --retry --count 100 --pid_file /tmp/client-ctest-random-07768473.pid --stats_file /tmp/client-ctest-random-07768473.stats 0<&- &> /tmp/client-ctest-random-07768473.log, host_string: cirros@169.254.0.3, password: gocubsgogateway: ubuntu@10.0.0.61, gateway password: c0ntrail123
2025-08-07 03:57:58,993 - DEBUG - nohup python /tmp/udpechoclient.py --servers 3.231.40.67 --sport 8000 --dports 9001 --retry --count 100 --pid_file /tmp/client-ctest-random-07768473.pid --stats_file /tmp/client-ctest-random-07768473.stats 0<&- &> /tmp/client-ctest-random-07768473.log &
2025-08-07 03:57:59,219 - DEBUG - None
2025-08-07 03:57:59,219 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-07 03:57:59,219 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-07 03:57:59,219 - DEBUG - File /contrail-test/tcutils/traffic_utils/../udpechoserver.py already copied to 3.231.40.67
2025-08-07 03:57:59,219 - DEBUG - Running remote_cmd, Cmd : python /tmp/udpechoserver.py --start_port 9003 --end_port 9003 --pid_file /tmp/server-ctest-random-80148230.pid --stats_file /tmp/server-ctest-random-80148230.stats 0<&- &> /tmp/server-ctest-random-80148230.log, host_string: cirros@169.254.0.3, password: gocubsgogateway: ubuntu@10.0.0.41, gateway password: c0ntrail123
2025-08-07 03:57:59,219 - DEBUG - nohup python /tmp/udpechoserver.py --start_port 9003 --end_port 9003 --pid_file /tmp/server-ctest-random-80148230.pid --stats_file /tmp/server-ctest-random-80148230.stats 0<&- &> /tmp/server-ctest-random-80148230.log &
2025-08-07 03:57:59,483 - DEBUG - None
2025-08-07 03:57:59,483 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-07 03:57:59,483 - DEBUG - File /contrail-test/tcutils/traffic_utils/../udpechoclient.py already copied to 5.15.152.195
2025-08-07 03:57:59,483 - DEBUG - Running remote_cmd, Cmd : python /tmp/udpechoclient.py --servers 3.231.40.67 --sport 8000 --dports 9003 --retry --count 100 --pid_file /tmp/client-ctest-random-80148230.pid --stats_file /tmp/client-ctest-random-80148230.stats 0<&- &> /tmp/client-ctest-random-80148230.log, host_string: cirros@169.254.0.3, password: gocubsgogateway: ubuntu@10.0.0.61, gateway password: c0ntrail123
2025-08-07 03:57:59,483 - DEBUG - nohup python /tmp/udpechoclient.py --servers 3.231.40.67 --sport 8000 --dports 9003 --retry --count 100 --pid_file /tmp/client-ctest-random-80148230.pid --stats_file /tmp/client-ctest-random-80148230.stats 0<&- &> /tmp/client-ctest-random-80148230.log &
2025-08-07 03:57:59,715 - DEBUG - None
2025-08-07 03:58:09,715 - DEBUG - Running remote_cmd, Cmd : kill $(cat /tmp/client-ctest-random-36848789.pid); sync; cat /tmp/client-ctest-random-36848789.stats, host_string: cirros@169.254.0.3, password: gocubsgogateway: ubuntu@10.0.0.61, gateway password: c0ntrail123
2025-08-07 03:58:09,715 - DEBUG - kill $(cat /tmp/client-ctest-random-36848789.pid); sync; cat /tmp/client-ctest-random-36848789.stats
2025-08-07 03:58:10,031 - DEBUG - cat: can't open '/tmp/client-ctest-random-36848789.pid': No such file or directory
sh: you need to specify whom to kill
cat: can't open '/tmp/client-ctest-random-36848789.stats': No such file or directory
2025-08-07 03:58:10,031 - DEBUG - Running remote_cmd, Cmd : kill $(cat /tmp/server-ctest-random-36848789.pid); sync; cat /tmp/server-ctest-random-36848789.stats, host_string: cirros@169.254.0.3, password: gocubsgogateway: ubuntu@10.0.0.41, gateway password: c0ntrail123
2025-08-07 03:58:10,031 - DEBUG - kill $(cat /tmp/server-ctest-random-36848789.pid); sync; cat /tmp/server-ctest-random-36848789.stats
2025-08-07 03:58:10,376 - DEBUG - cat: can't open '/tmp/server-ctest-random-36848789.pid': No such file or directory
sh: you need to specify whom to kill
cat: can't open '/tmp/server-ctest-random-36848789.stats': No such file or directory
2025-08-07 03:58:10,376 - INFO - Client - Sent: 0, Received: 0
2025-08-07 03:58:10,376 - INFO - Server - Sent: 0, Received: 0
2025-08-07 03:58:10,928 - INFO - Deleted policy policy1
2025-08-07 03:58:11,114 - INFO - Setting flow export rate: None
2025-08-07 03:58:12,377 - ERROR - AssertionError
Python 3.6.8: /usr/bin/python3
Thu Aug 7 03:58:10 2025
A problem occurred in a Python script. Here is the sequence of
function calls leading up to the error, in the order they occurred.
/contrail-test/tcutils/wrappers.py in wrapper(self=, *args=(), **kwargs={})
78 log.info('Initial checks done. Running the testcase now')
79 log.info('')
80 result = function(self, *args, **kwargs)
81 if self.inputs.upgrade:
82 pid = os.getpid()
result = None
function =
self =
args = ()
kwargs = {}
/contrail-test/serial_scripts/analytics/test_analytics_resource.py in test_verify_session_series_table_inter_vn(self=)
1336 start_time = self.analytics_obj.getstarttime(vm_node_ip)
1337 self.logger.info("start time= %s" % (start_time))
1338 self.setup_and_create_streams(self.res.vn1_vm1_fixture, self.res.vn2_vm2_fixture)
1339 src_vn = self.res.vn1_fixture.vn_fq_name
1340 dst_vn = self.res.vn2_fixture.vn_fq_name
self =
self.setup_and_create_streams = >
self.res =
self.res.vn1_vm1_fixture =
self.res.vn2_vm2_fixture =
/contrail-test/serial_scripts/analytics/base.py in setup_and_create_streams(self=, src_vm=, dst_vm=, sport=8000, dport=9003, count=100)
168 time.sleep(10)
169 for traffic_obj in traffic_objs:
170 self.stop_traffic(traffic_obj)
171
172 #end setup_create_streams
self =
self.stop_traffic = >
traffic_obj =
/contrail-test/common/base.py in stop_traffic(self=, traffic_obj=, expectation=True, unidirection=False, partial=False)
796 assert False, "No drops observed in packets %s"%msg
797 elif expectation:
798 assert sent and recv, "Packets not %s"%msg
799 if recv*100/float(sent) < 90:
800 assert False, "Packets not %s"%msg
sent = 0
recv = 0
msg = 'transferred between 5.15.152.195 and 3.231.40.67, proto udp sport 8000 and dport 9000'
AssertionError: Packets not transferred between 5.15.152.195 and 3.231.40.67, proto udp sport 8000 and dport 9000
__cause__ = None
__class__ =
__context__ = None
__delattr__ =
__dict__ = {}
__dir__ =
__doc__ = 'Assertion failed.'
__eq__ =
__format__ =
__ge__ =
__getattribute__ =
__gt__ =
__hash__ =
__init__ =
__init_subclass__ =
__le__ =
__lt__ =
__ne__ =
__new__ =
__reduce__ =
__reduce_ex__ =
__repr__ =
__setattr__ =
__setstate__ =
__sizeof__ =
__str__ =
__subclasshook__ =
__suppress_context__ = False
__traceback__ =
args = ('Packets not transferred between 5.15.152.195 and 3.231.40.67, proto udp sport 8000 and dport 9000',)
with_traceback =
The above is a description of an error in a Python program. Here is
the original traceback:
Traceback (most recent call last):
File "/contrail-test/tcutils/wrappers.py", line 80, in wrapper
result = function(self, *args, **kwargs)
File "/contrail-test/serial_scripts/analytics/test_analytics_resource.py", line 1338, in test_verify_session_series_table_inter_vn
self.setup_and_create_streams(self.res.vn1_vm1_fixture, self.res.vn2_vm2_fixture)
File "/contrail-test/serial_scripts/analytics/base.py", line 170, in setup_and_create_streams
self.stop_traffic(traffic_obj)
File "/contrail-test/common/base.py", line 798, in stop_traffic
assert sent and recv, "Packets not %s"%msg
AssertionError: Packets not transferred between 5.15.152.195 and 3.231.40.67, proto udp sport 8000 and dport 9000
2025-08-07 03:58:12,377 - DEBUG - Skipping xmpp flap check
2025-08-07 03:58:12,377 - INFO -
2025-08-07 03:58:12,378 - INFO - END TEST : test_verify_session_series_table_inter_vn : FAILED[0:00:20]
2025-08-07 03:58:12,378 - INFO - --------------------------------------------------------------------------------
2025-08-07 03:58:12,381 - INFO - Deleting VM mltf_vm
2025-08-07 03:58:12,471 - INFO - Deleting VM ctest-fvn_vm1-00268763
2025-08-07 03:58:12,557 - INFO - Deleting VM ctest-vn2_vm2-53196154
2025-08-07 03:58:12,632 - INFO - Deleting VN ctest-fip_vn-23932101
2025-08-07 03:58:12,694 - DEBUG - VN 2e571d31-de3d-4b1a-8f57-2e1cdffe401c still in use: Unable to complete operation on network 2e571d31-de3d-4b1a-8f57-2e1cdffe401c. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-d26c10f2-df1f-4c7c-a470-da49c6c20ea8']
2025-08-07 03:58:12,694 - WARNING - Deleting VN ctest-fip_vn-23932101 failed..Will retry
2025-08-07 03:58:14,886 - DEBUG - Response for deleting network ()
2025-08-07 03:58:14,886 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/a103d4df-1e11-4145-920a-baa03016b822
2025-08-07 03:58:14,893 - DEBUG - Response Code: 404
2025-08-07 03:58:14,893 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:58:14,910 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:58:14,928 - DEBUG - Requesting: http://10.0.0.56:8082/project/e750dce5-6593-495f-a498-7395bff3b2dd
2025-08-07 03:58:15,044 - INFO - Validated that VN ctest-fip_vn-23932101 is not found in API Server
2025-08-07 03:58:15,057 - DEBUG - VN ctest-fip_vn-23932101 is not present in Agent 10.0.0.41
2025-08-07 03:58:15,074 - DEBUG - VN ctest-fip_vn-23932101 is not present in Agent 10.0.0.61
2025-08-07 03:58:15,074 - INFO - Validated that VN ctest-fip_vn-23932101 is not in any agent
2025-08-07 03:58:15,082 - DEBUG - VRF 3 is not seen in agent 10.0.0.41
2025-08-07 03:58:15,090 - DEBUG - Vrouter 10.0.0.41 does not have vrf 3 for VN ctest-fip_vn-23932101
2025-08-07 03:58:15,090 - INFO - Validated that all vrouters do not have the route table for VN default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-fip_vn-23932101
2025-08-07 03:58:15,117 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-fip_vn-23932101 info
2025-08-07 03:58:15,117 - INFO - Deleting VN ctest-vn2-75786057
2025-08-07 03:58:15,302 - DEBUG - Response for deleting network ()
2025-08-07 03:58:15,302 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6d42434f-997b-4f8a-b65d-895b112183df
2025-08-07 03:58:15,319 - DEBUG - Response Code: 404
2025-08-07 03:58:15,320 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:58:15,327 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:58:15,344 - DEBUG - Requesting: http://10.0.0.56:8082/project/e750dce5-6593-495f-a498-7395bff3b2dd
2025-08-07 03:58:15,447 - INFO - Validated that VN ctest-vn2-75786057 is not found in API Server
2025-08-07 03:58:15,460 - DEBUG - VN ctest-vn2-75786057 is not present in Agent 10.0.0.41
2025-08-07 03:58:15,474 - DEBUG - VN ctest-vn2-75786057 is not present in Agent 10.0.0.61
2025-08-07 03:58:15,474 - INFO - Validated that VN ctest-vn2-75786057 is not in any agent
2025-08-07 03:58:15,481 - DEBUG - VRF 2 is not seen in agent 10.0.0.41
2025-08-07 03:58:15,487 - DEBUG - Vrouter 10.0.0.41 does not have vrf 2 for VN ctest-vn2-75786057
2025-08-07 03:58:15,494 - DEBUG - VRF 3 is not seen in agent 10.0.0.61
2025-08-07 03:58:15,501 - DEBUG - Vrouter 10.0.0.61 does not have vrf 3 for VN ctest-vn2-75786057
2025-08-07 03:58:15,501 - INFO - Validated that all vrouters do not have the route table for VN default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn2-75786057
2025-08-07 03:58:15,529 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn2-75786057 info
2025-08-07 03:58:15,529 - INFO - Deleting VM ctest-vn1_vm2-91156958
2025-08-07 03:58:15,630 - INFO - Deleting VM ctest-vn1_vm1-14989368
2025-08-07 03:58:15,708 - INFO - Deleting VN ctest-vn1-76278277
2025-08-07 03:58:15,764 - DEBUG - VN 86dfcaa9-5861-472a-be9d-3232f6e3d340 still in use: Unable to complete operation on network 86dfcaa9-5861-472a-be9d-3232f6e3d340. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-4ae0090c-93be-438b-b30c-1da792ec6abd']
2025-08-07 03:58:15,765 - WARNING - Deleting VN ctest-vn1-76278277 failed..Will retry
2025-08-07 03:58:18,001 - DEBUG - Response for deleting network ()
2025-08-07 03:58:18,002 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e70612e3-8778-48cd-badf-80905eabdc5c
2025-08-07 03:58:18,008 - DEBUG - Response Code: 404
2025-08-07 03:58:18,008 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:58:18,028 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:58:18,048 - DEBUG - Requesting: http://10.0.0.56:8082/project/e750dce5-6593-495f-a498-7395bff3b2dd
2025-08-07 03:58:18,155 - INFO - Validated that VN ctest-vn1-76278277 is not found in API Server
2025-08-07 03:58:18,175 - DEBUG - VN ctest-vn1-76278277 is not present in Agent 10.0.0.41
2025-08-07 03:58:18,190 - DEBUG - VN ctest-vn1-76278277 is not present in Agent 10.0.0.61
2025-08-07 03:58:18,190 - INFO - Validated that VN ctest-vn1-76278277 is not in any agent
2025-08-07 03:58:18,197 - DEBUG - VRF 2 is not seen in agent 10.0.0.61
2025-08-07 03:58:18,203 - DEBUG - Vrouter 10.0.0.61 does not have vrf 2 for VN ctest-vn1-76278277
2025-08-07 03:58:18,203 - INFO - Validated that all vrouters do not have the route table for VN default-domain:ctest-AnalyticsTestSanityWithResource-52543708:ctest-vn1-76278277
2025-08-07 03:58:18,230 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn1-76278277 info
2025-08-07 03:58:19,384 - INFO - Deleted project: ctest-AnalyticsTestSanityWithResource-52543708, ID : e750dce5-6593-495f-a498-7395bff3b2dd