2025-08-10 11:23:37,604 - INFO - Domain Default found not creating 2025-08-10 11:23:37,724 - INFO - Project ctest-TestPolicyAclIpv4v6-10181764 not found, creating it 2025-08-10 11:23:38,223 - INFO - Created Project:ctest-TestPolicyAclIpv4v6-10181764, ID : 40762832-e00c-4f09-8da5-c16b2522c8cf 2025-08-10 11:23:39,522 - INFO - ================================================================================ 2025-08-10 11:23:39,522 - INFO - STARTING TEST : test_policy_inheritance_src_pol_dst_any 2025-08-10 11:23:40,786 - DEBUG - Nothing to compare xmpp stats {'10.0.0.65': {'10.20.0.14': '0', '10.20.0.25': '0'}, '10.0.0.56': {'10.20.0.14': '0', '10.20.0.25': '0'}} with 2025-08-10 11:23:40,786 - INFO - Initial checks done. Running the testcase now 2025-08-10 11:23:40,787 - INFO - 2025-08-10 11:23:40,787 - INFO - ================================================================================ 2025-08-10 11:23:40,787 - INFO - STARTING TEST : test_policy_inheritance_src_pol_dst_any 2025-08-10 11:23:40,787 - INFO - TEST DESCRIPTION : Test cases to test policy inheritance 2025-08-10 11:23:42,037 - DEBUG - Nothing to compare xmpp stats {'10.0.0.65': {'10.20.0.14': '0', '10.20.0.25': '0'}, '10.0.0.56': {'10.20.0.14': '0', '10.20.0.25': '0'}} with 2025-08-10 11:23:42,037 - INFO - Initial checks done. Running the testcase now 2025-08-10 11:23:42,037 - INFO - 2025-08-10 11:23:43,280 - DEBUG - Requesting: http://10.0.0.49:8082/domains 2025-08-10 11:23:43,568 - DEBUG - Requesting: http://10.0.0.49:8082/domains 2025-08-10 11:23:43,595 - DEBUG - Requesting: http://10.0.0.49:8082/domain/506b9682-8eb9-488e-ab69-24d332f4d228 2025-08-10 11:23:43,609 - DEBUG - Requesting: http://10.0.0.49:8082/project/40762832-e00c-4f09-8da5-c16b2522c8cf 2025-08-10 11:23:43,722 - DEBUG - Requesting: http://10.0.0.49:8082/network-ipam/d1f5d433-0951-48de-b573-589fd5d44656 2025-08-10 11:23:43,729 - INFO - Verifications in API Server for IPAM: ipam1 passed 2025-08-10 11:23:43,734 - DEBUG - Control-node 10.0.0.49 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-10181764:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-10181764', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15129231838885791966', 'uuid-lslong': '13074891586697184854'}, 'enable': 'true', 'created': '2025-08-10T11:23:42', 'last-modified': '2025-08-10T11:23:42', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.119232'} 2025-08-10 11:23:43,740 - DEBUG - Control-node 10.0.0.254 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-10181764:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-10181764', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15129231838885791966', 'uuid-lslong': '13074891586697184854'}, 'enable': 'true', 'created': '2025-08-10T11:23:42', 'last-modified': '2025-08-10T11:23:42', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.118205'} 2025-08-10 11:23:43,748 - DEBUG - Control-node 10.0.0.57 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-10181764:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-10181764', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15129231838885791966', 'uuid-lslong': '13074891586697184854'}, 'enable': 'true', 'created': '2025-08-10T11:23:42', 'last-modified': '2025-08-10T11:23:42', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.126250'} 2025-08-10 11:23:43,748 - INFO - Verifications in Control node for IPAM: ipam1 passed 2025-08-10 11:23:43,748 - DEBUG - Requesting: http://10.0.0.49:8082/domains 2025-08-10 11:23:43,754 - DEBUG - Requesting: http://10.0.0.49:8082/domain/506b9682-8eb9-488e-ab69-24d332f4d228 2025-08-10 11:23:43,765 - DEBUG - Requesting: http://10.0.0.49:8082/project/40762832-e00c-4f09-8da5-c16b2522c8cf 2025-08-10 11:23:43,859 - DEBUG - Requesting: http://10.0.0.49:8082/network-ipam/e7b58f72-f8f5-4a14-b8ea-c724b7a18c36 2025-08-10 11:23:43,866 - INFO - Verifications in API Server for IPAM: ipam2 passed 2025-08-10 11:23:43,873 - DEBUG - Control-node 10.0.0.49 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-10181764:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-10181764', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16696408917511850516', 'uuid-lslong': '13324681408042601526'}, 'enable': 'true', 'created': '2025-08-10T11:23:42', 'last-modified': '2025-08-10T11:23:42', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.944751'} 2025-08-10 11:23:43,879 - DEBUG - Control-node 10.0.0.254 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-10181764:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-10181764', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16696408917511850516', 'uuid-lslong': '13324681408042601526'}, 'enable': 'true', 'created': '2025-08-10T11:23:42', 'last-modified': '2025-08-10T11:23:42', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.945284'} 2025-08-10 11:23:43,885 - DEBUG - Control-node 10.0.0.57 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-10181764:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-10181764', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16696408917511850516', 'uuid-lslong': '13324681408042601526'}, 'enable': 'true', 'created': '2025-08-10T11:23:42', 'last-modified': '2025-08-10T11:23:42', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.951787'} 2025-08-10 11:23:43,885 - INFO - Verifications in Control node for IPAM: ipam2 passed 2025-08-10 11:23:43,885 - DEBUG - Requesting: http://10.0.0.49:8082/domains 2025-08-10 11:23:43,891 - DEBUG - Requesting: http://10.0.0.49:8082/domain/506b9682-8eb9-488e-ab69-24d332f4d228 2025-08-10 11:23:43,902 - DEBUG - Requesting: http://10.0.0.49:8082/project/40762832-e00c-4f09-8da5-c16b2522c8cf 2025-08-10 11:23:43,995 - DEBUG - Requesting: http://10.0.0.49:8082/network-ipam/2a23aeff-28e5-45b3-8510-95d7d1e387fa 2025-08-10 11:23:44,002 - INFO - Verifications in API Server for IPAM: ipam3 passed 2025-08-10 11:23:44,008 - DEBUG - Control-node 10.0.0.49 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-10181764:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-10181764', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3036462984703854003', 'uuid-lslong': '9588328360843642874'}, 'enable': 'true', 'created': '2025-08-10T11:23:43', 'last-modified': '2025-08-10T11:23:43', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.749106'} 2025-08-10 11:23:44,014 - DEBUG - Control-node 10.0.0.254 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-10181764:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-10181764', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3036462984703854003', 'uuid-lslong': '9588328360843642874'}, 'enable': 'true', 'created': '2025-08-10T11:23:43', 'last-modified': '2025-08-10T11:23:43', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.758215'} 2025-08-10 11:23:44,020 - DEBUG - Control-node 10.0.0.57 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-10181764:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-10181764', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3036462984703854003', 'uuid-lslong': '9588328360843642874'}, 'enable': 'true', 'created': '2025-08-10T11:23:43', 'last-modified': '2025-08-10T11:23:43', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.763437'} 2025-08-10 11:23:44,020 - INFO - Verifications in Control node for IPAM: ipam3 passed 2025-08-10 11:23:44,332 - DEBUG - Response for create_network : {'network': {'id': '618a4142-6b96-4427-9086-78caf70c37c0', 'name': 'ctest-VN1-03874402', 'tenant_id': '40762832e00c4f098da5c16b2522c8cf', 'project_id': '40762832e00c4f098da5c16b2522c8cf', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-10181764', 'ctest-VN1-03874402'], 'port_security_enabled': True, 'description': ''}} 2025-08-10 11:23:44,585 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b23fa5e3-8d4c-4c69-98f8-3a97151a580e', 'name': '', 'tenant_id': '40762832e00c4f098da5c16b2522c8cf', 'network_id': '618a4142-6b96-4427-9086-78caf70c37c0', 'ip_version': 4, 'cidr': '84.134.216.0/26', 'allocation_pools': [{'start': '84.134.216.2', 'end': '84.134.216.62'}], 'gateway_ip': '84.134.216.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '84.134.216.2', 'tags': [], 'project_id': '40762832e00c4f098da5c16b2522c8cf'}} 2025-08-10 11:23:44,837 - DEBUG - Response for create_subnet : {'subnet': {'id': '7452e0e3-44c9-4dc1-a082-c0a6688b6423', 'name': '', 'tenant_id': '40762832e00c4f098da5c16b2522c8cf', 'network_id': '618a4142-6b96-4427-9086-78caf70c37c0', 'ip_version': 6, 'cidr': '2e97:276d:6e93:461f:70ac:83a9::/96', 'allocation_pools': [{'start': '2e97:276d:6e93:461f:70ac:83a9:0:2', 'end': '2e97:276d:6e93:461f:70ac:83a9:ffff:fffe'}], 'gateway_ip': '2e97:276d:6e93:461f:70ac:83a9:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '2e97:276d:6e93:461f:70ac:83a9:0:2', 'tags': [], 'project_id': '40762832e00c4f098da5c16b2522c8cf'}} 2025-08-10 11:23:44,860 - INFO - Created VN ctest-VN1-03874402 2025-08-10 11:23:44,873 - DEBUG - VN ctest-VN1-03874402 UUID is 618a4142-6b96-4427-9086-78caf70c37c0 2025-08-10 11:23:45,016 - DEBUG - Fetched VN: default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402(618a4142-6b96-4427-9086-78caf70c37c0) with subnets ['84.134.216.0/26', '2e97:276d:6e93:461f:70ac:83a9::/96'] 2025-08-10 11:23:45,289 - DEBUG - Response for create_network : {'network': {'id': 'f855bad3-59dc-4255-9e4e-b8e40654a514', 'name': 'ctest-VN2-85407297', 'tenant_id': '40762832e00c4f098da5c16b2522c8cf', 'project_id': '40762832e00c4f098da5c16b2522c8cf', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-10181764', 'ctest-VN2-85407297'], 'port_security_enabled': True, 'description': ''}} 2025-08-10 11:23:45,481 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b4c3afd4-b5c7-450f-b88a-a76726e152d5', 'name': '', 'tenant_id': '40762832e00c4f098da5c16b2522c8cf', 'network_id': 'f855bad3-59dc-4255-9e4e-b8e40654a514', 'ip_version': 4, 'cidr': '28.169.193.64/26', 'allocation_pools': [{'start': '28.169.193.66', 'end': '28.169.193.126'}], 'gateway_ip': '28.169.193.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '28.169.193.66', 'tags': [], 'project_id': '40762832e00c4f098da5c16b2522c8cf'}} 2025-08-10 11:23:45,665 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ff0fcf97-3b65-49c9-a86c-941158c6a523', 'name': '', 'tenant_id': '40762832e00c4f098da5c16b2522c8cf', 'network_id': 'f855bad3-59dc-4255-9e4e-b8e40654a514', 'ip_version': 6, 'cidr': '2012:4ac:c77a:8ac8:4a0d:58b1::/96', 'allocation_pools': [{'start': '2012:4ac:c77a:8ac8:4a0d:58b1:0:2', 'end': '2012:4ac:c77a:8ac8:4a0d:58b1:ffff:fffe'}], 'gateway_ip': '2012:4ac:c77a:8ac8:4a0d:58b1:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '2012:4ac:c77a:8ac8:4a0d:58b1:0:2', 'tags': [], 'project_id': '40762832e00c4f098da5c16b2522c8cf'}} 2025-08-10 11:23:45,686 - INFO - Created VN ctest-VN2-85407297 2025-08-10 11:23:45,696 - DEBUG - VN ctest-VN2-85407297 UUID is f855bad3-59dc-4255-9e4e-b8e40654a514 2025-08-10 11:23:46,001 - DEBUG - Response for create_network : {'network': {'id': '4c60ea21-ab16-4147-9001-3a733b7a002d', 'name': 'ctest-VN3-56262246', 'tenant_id': '40762832e00c4f098da5c16b2522c8cf', 'project_id': '40762832e00c4f098da5c16b2522c8cf', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-10181764', 'ctest-VN3-56262246'], 'port_security_enabled': True, 'description': ''}} 2025-08-10 11:23:46,249 - DEBUG - Response for create_subnet : {'subnet': {'id': '546ba0d4-78b3-43f4-a826-470b2a86f8d0', 'name': '', 'tenant_id': '40762832e00c4f098da5c16b2522c8cf', 'network_id': '4c60ea21-ab16-4147-9001-3a733b7a002d', 'ip_version': 4, 'cidr': '164.190.15.192/26', 'allocation_pools': [{'start': '164.190.15.194', 'end': '164.190.15.254'}], 'gateway_ip': '164.190.15.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '164.190.15.194', 'tags': [], 'project_id': '40762832e00c4f098da5c16b2522c8cf'}} 2025-08-10 11:23:46,418 - DEBUG - Response for create_subnet : {'subnet': {'id': '73316f61-b177-4335-b174-017b8c3b399b', 'name': '', 'tenant_id': '40762832e00c4f098da5c16b2522c8cf', 'network_id': '4c60ea21-ab16-4147-9001-3a733b7a002d', 'ip_version': 6, 'cidr': '215c:11d8:1e6a:97f:b39d:e9f3::/96', 'allocation_pools': [{'start': '215c:11d8:1e6a:97f:b39d:e9f3:0:2', 'end': '215c:11d8:1e6a:97f:b39d:e9f3:ffff:fffe'}], 'gateway_ip': '215c:11d8:1e6a:97f:b39d:e9f3:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '215c:11d8:1e6a:97f:b39d:e9f3:0:2', 'tags': [], 'project_id': '40762832e00c4f098da5c16b2522c8cf'}} 2025-08-10 11:23:46,441 - INFO - Created VN ctest-VN3-56262246 2025-08-10 11:23:46,452 - DEBUG - VN ctest-VN3-56262246 UUID is 4c60ea21-ab16-4147-9001-3a733b7a002d 2025-08-10 11:23:46,534 - DEBUG - Requesting: http://10.0.0.49:8082/domains 2025-08-10 11:23:46,540 - DEBUG - Requesting: http://10.0.0.49:8082/domain/506b9682-8eb9-488e-ab69-24d332f4d228 2025-08-10 11:23:46,550 - DEBUG - Requesting: http://10.0.0.49:8082/project/40762832-e00c-4f09-8da5-c16b2522c8cf 2025-08-10 11:23:46,648 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/618a4142-6b96-4427-9086-78caf70c37c0 2025-08-10 11:23:46,661 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/618a4142-6b96-4427-9086-78caf70c37c0 2025-08-10 11:23:46,670 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/6a7e126e-a054-4930-aece-18b413bdf7ad 2025-08-10 11:23:46,677 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/6a7e126e-a054-4930-aece-18b413bdf7ad 2025-08-10 11:23:46,683 - DEBUG - Requesting: http://10.0.0.49:8082/route-target/114fdc61-4b60-449c-8e28-efa9e9d9daa1 2025-08-10 11:23:46,689 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-08-10 11:23:46,689 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/618a4142-6b96-4427-9086-78caf70c37c0 2025-08-10 11:23:46,698 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/6a7e126e-a054-4930-aece-18b413bdf7ad 2025-08-10 11:23:46,711 - INFO - Verified VN network id 15 for VN 618a4142-6b96-4427-9086-78caf70c37c0 2025-08-10 11:23:46,711 - INFO - Verifications in API Server for VN ctest-VN1-03874402 passed 2025-08-10 11:23:46,711 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/618a4142-6b96-4427-9086-78caf70c37c0 2025-08-10 11:23:46,720 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/6a7e126e-a054-4930-aece-18b413bdf7ad 2025-08-10 11:23:46,726 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/6a7e126e-a054-4930-aece-18b413bdf7ad 2025-08-10 11:23:46,731 - DEBUG - Requesting: http://10.0.0.49:8082/route-target/114fdc61-4b60-449c-8e28-efa9e9d9daa1 2025-08-10 11:23:46,742 - DEBUG - Control-node 10.0.0.49 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-10181764', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7028501921993737255', 'uuid-lslong': '10414144001464350656'}, 'enable': 'true', 'created': '2025-08-10T11:23:44', 'last-modified': '2025-08-10T11:23:44', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.961823'} 2025-08-10 11:23:46,748 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-08-10 11:23:46,753 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-10181764', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7028501921993737255', 'uuid-lslong': '10414144001464350656'}, 'enable': 'true', 'created': '2025-08-10T11:23:44', 'last-modified': '2025-08-10T11:23:44', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.972316'} 2025-08-10 11:23:46,759 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-08-10 11:23:46,765 - DEBUG - Control-node 10.0.0.57 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-10181764', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7028501921993737255', 'uuid-lslong': '10414144001464350656'}, 'enable': 'true', 'created': '2025-08-10T11:23:44', 'last-modified': '2025-08-10T11:23:44', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.980801'} 2025-08-10 11:23:46,771 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-08-10 11:23:46,771 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN1-03874402 passed 2025-08-10 11:23:46,771 - DEBUG - ====Verifying policy data for ctest-VN1-03874402 in API_Server ====== 2025-08-10 11:23:46,771 - DEBUG - Requesting: http://10.0.0.49:8082/domains 2025-08-10 11:23:46,776 - DEBUG - Requesting: http://10.0.0.49:8082/domain/506b9682-8eb9-488e-ab69-24d332f4d228 2025-08-10 11:23:46,786 - DEBUG - Requesting: http://10.0.0.49:8082/project/40762832-e00c-4f09-8da5-c16b2522c8cf 2025-08-10 11:23:46,872 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/618a4142-6b96-4427-9086-78caf70c37c0 2025-08-10 11:23:46,885 - DEBUG - =>VN ctest-VN1-03874402 has no policy to be verified 2025-08-10 11:23:46,885 - DEBUG - Verifying the vn in opserver 2025-08-10 11:23:46,885 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402 virtual network link through opserver 10.0.0.49 2025-08-10 11:23:46,885 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-networks 2025-08-10 11:23:47,170 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-networks 2025-08-10 11:23:47,219 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402', 'href': 'http://10.0.0.49:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402?flat'} 2025-08-10 11:23:47,219 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402 virtual network link through opserver 10.0.0.254 2025-08-10 11:23:47,219 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks 2025-08-10 11:23:47,505 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks 2025-08-10 11:23:47,555 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402?flat'} 2025-08-10 11:23:47,555 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402 virtual network link through opserver 10.0.0.57 2025-08-10 11:23:47,555 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-networks 2025-08-10 11:23:47,839 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-networks 2025-08-10 11:23:47,886 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402', 'href': 'http://10.0.0.57:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402?flat'} 2025-08-10 11:23:47,887 - INFO - Validated that VN default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402 is found in opserver 2025-08-10 11:23:47,907 - DEBUG - Do not have enough data to verify VN in agent 2025-08-10 11:23:47,916 - DEBUG - VRF ids for VN ctest-VN1-03874402: {} 2025-08-10 11:23:47,916 - DEBUG - Requesting: http://10.0.0.49:8082/domains 2025-08-10 11:23:47,922 - DEBUG - Requesting: http://10.0.0.49:8082/domain/506b9682-8eb9-488e-ab69-24d332f4d228 2025-08-10 11:23:47,933 - DEBUG - Requesting: http://10.0.0.49:8082/project/40762832-e00c-4f09-8da5-c16b2522c8cf 2025-08-10 11:23:48,015 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/f855bad3-59dc-4255-9e4e-b8e40654a514 2025-08-10 11:23:48,032 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/f855bad3-59dc-4255-9e4e-b8e40654a514 2025-08-10 11:23:48,041 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/466b2464-d506-40d7-bab8-19036179d750 2025-08-10 11:23:48,048 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/466b2464-d506-40d7-bab8-19036179d750 2025-08-10 11:23:48,055 - DEBUG - Requesting: http://10.0.0.49:8082/route-target/df0a3bbc-3e4a-46e1-a24f-e09772083fee 2025-08-10 11:23:48,061 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-08-10 11:23:48,061 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/f855bad3-59dc-4255-9e4e-b8e40654a514 2025-08-10 11:23:48,070 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/466b2464-d506-40d7-bab8-19036179d750 2025-08-10 11:23:48,083 - INFO - Verified VN network id 16 for VN f855bad3-59dc-4255-9e4e-b8e40654a514 2025-08-10 11:23:48,083 - INFO - Verifications in API Server for VN ctest-VN2-85407297 passed 2025-08-10 11:23:48,083 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/f855bad3-59dc-4255-9e4e-b8e40654a514 2025-08-10 11:23:48,092 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/466b2464-d506-40d7-bab8-19036179d750 2025-08-10 11:23:48,098 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/466b2464-d506-40d7-bab8-19036179d750 2025-08-10 11:23:48,104 - DEBUG - Requesting: http://10.0.0.49:8082/route-target/df0a3bbc-3e4a-46e1-a24f-e09772083fee 2025-08-10 11:23:48,119 - DEBUG - Control-node 10.0.0.49 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN2-85407297', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-10181764', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17894414111335006805', 'uuid-lslong': '11407258195674309908'}, 'enable': 'true', 'created': '2025-08-10T11:23:45', 'last-modified': '2025-08-10T11:23:45', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.500704'} 2025-08-10 11:23:48,128 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-08-10 11:23:48,134 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN2-85407297', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-10181764', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17894414111335006805', 'uuid-lslong': '11407258195674309908'}, 'enable': 'true', 'created': '2025-08-10T11:23:45', 'last-modified': '2025-08-10T11:23:45', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.506517'} 2025-08-10 11:23:48,142 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-08-10 11:23:48,147 - DEBUG - Control-node 10.0.0.57 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN2-85407297', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-10181764', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17894414111335006805', 'uuid-lslong': '11407258195674309908'}, 'enable': 'true', 'created': '2025-08-10T11:23:45', 'last-modified': '2025-08-10T11:23:45', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.520819'} 2025-08-10 11:23:48,153 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-08-10 11:23:48,153 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN2-85407297 passed 2025-08-10 11:23:48,153 - DEBUG - ====Verifying policy data for ctest-VN2-85407297 in API_Server ====== 2025-08-10 11:23:48,153 - DEBUG - Requesting: http://10.0.0.49:8082/domains 2025-08-10 11:23:48,158 - DEBUG - Requesting: http://10.0.0.49:8082/domain/506b9682-8eb9-488e-ab69-24d332f4d228 2025-08-10 11:23:48,168 - DEBUG - Requesting: http://10.0.0.49:8082/project/40762832-e00c-4f09-8da5-c16b2522c8cf 2025-08-10 11:23:48,254 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/f855bad3-59dc-4255-9e4e-b8e40654a514 2025-08-10 11:23:48,264 - DEBUG - =>VN ctest-VN2-85407297 has no policy to be verified 2025-08-10 11:23:48,264 - DEBUG - Verifying the vn in opserver 2025-08-10 11:23:48,264 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN2-85407297 virtual network link through opserver 10.0.0.49 2025-08-10 11:23:48,264 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-networks 2025-08-10 11:23:48,318 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN2-85407297', 'href': 'http://10.0.0.49:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN2-85407297?flat'} 2025-08-10 11:23:48,318 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN2-85407297 virtual network link through opserver 10.0.0.254 2025-08-10 11:23:48,318 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks 2025-08-10 11:23:48,365 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN2-85407297', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN2-85407297?flat'} 2025-08-10 11:23:48,365 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN2-85407297 virtual network link through opserver 10.0.0.57 2025-08-10 11:23:48,365 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-networks 2025-08-10 11:23:48,410 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN2-85407297', 'href': 'http://10.0.0.57:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN2-85407297?flat'} 2025-08-10 11:23:48,410 - INFO - Validated that VN default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN2-85407297 is found in opserver 2025-08-10 11:23:48,430 - DEBUG - Do not have enough data to verify VN in agent 2025-08-10 11:23:48,440 - DEBUG - VRF ids for VN ctest-VN2-85407297: {} 2025-08-10 11:23:48,440 - DEBUG - Requesting: http://10.0.0.49:8082/domains 2025-08-10 11:23:48,445 - DEBUG - Requesting: http://10.0.0.49:8082/domain/506b9682-8eb9-488e-ab69-24d332f4d228 2025-08-10 11:23:48,455 - DEBUG - Requesting: http://10.0.0.49:8082/project/40762832-e00c-4f09-8da5-c16b2522c8cf 2025-08-10 11:23:48,541 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/4c60ea21-ab16-4147-9001-3a733b7a002d 2025-08-10 11:23:48,554 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/4c60ea21-ab16-4147-9001-3a733b7a002d 2025-08-10 11:23:48,564 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/0f19852a-5305-40cc-8ff6-8c463334e360 2025-08-10 11:23:48,581 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/0f19852a-5305-40cc-8ff6-8c463334e360 2025-08-10 11:23:48,593 - DEBUG - Requesting: http://10.0.0.49:8082/route-target/910bce0c-85f2-4b68-858a-07dffa9f6346 2025-08-10 11:23:48,624 - DEBUG - Route Targets: ['target:64512:8000007'] 2025-08-10 11:23:48,624 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/4c60ea21-ab16-4147-9001-3a733b7a002d 2025-08-10 11:23:48,635 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/0f19852a-5305-40cc-8ff6-8c463334e360 2025-08-10 11:23:48,650 - INFO - Verified VN network id 17 for VN 4c60ea21-ab16-4147-9001-3a733b7a002d 2025-08-10 11:23:48,650 - INFO - Verifications in API Server for VN ctest-VN3-56262246 passed 2025-08-10 11:23:48,650 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/4c60ea21-ab16-4147-9001-3a733b7a002d 2025-08-10 11:23:48,671 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/0f19852a-5305-40cc-8ff6-8c463334e360 2025-08-10 11:23:48,678 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/0f19852a-5305-40cc-8ff6-8c463334e360 2025-08-10 11:23:48,685 - DEBUG - Requesting: http://10.0.0.49:8082/route-target/910bce0c-85f2-4b68-858a-07dffa9f6346 2025-08-10 11:23:48,696 - DEBUG - Control-node 10.0.0.49 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN3-56262246', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-10181764', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5503656174971928903', 'uuid-lslong': '10376639283031834669'}, 'enable': 'true', 'created': '2025-08-10T11:23:45', 'last-modified': '2025-08-10T11:23:46', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.324141'} 2025-08-10 11:23:48,702 - DEBUG - Route Targets: ['target:64512:8000007'] 2025-08-10 11:23:48,708 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN3-56262246', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-10181764', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5503656174971928903', 'uuid-lslong': '10376639283031834669'}, 'enable': 'true', 'created': '2025-08-10T11:23:45', 'last-modified': '2025-08-10T11:23:46', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.341511'} 2025-08-10 11:23:48,713 - DEBUG - Route Targets: ['target:64512:8000007'] 2025-08-10 11:23:48,719 - DEBUG - Control-node 10.0.0.57 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN3-56262246', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-10181764', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5503656174971928903', 'uuid-lslong': '10376639283031834669'}, 'enable': 'true', 'created': '2025-08-10T11:23:45', 'last-modified': '2025-08-10T11:23:46', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.353816'} 2025-08-10 11:23:48,726 - DEBUG - Route Targets: ['target:64512:8000007'] 2025-08-10 11:23:48,726 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN3-56262246 passed 2025-08-10 11:23:48,726 - DEBUG - ====Verifying policy data for ctest-VN3-56262246 in API_Server ====== 2025-08-10 11:23:48,726 - DEBUG - Requesting: http://10.0.0.49:8082/domains 2025-08-10 11:23:48,731 - DEBUG - Requesting: http://10.0.0.49:8082/domain/506b9682-8eb9-488e-ab69-24d332f4d228 2025-08-10 11:23:48,749 - DEBUG - Requesting: http://10.0.0.49:8082/project/40762832-e00c-4f09-8da5-c16b2522c8cf 2025-08-10 11:23:48,855 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/4c60ea21-ab16-4147-9001-3a733b7a002d 2025-08-10 11:23:48,868 - DEBUG - =>VN ctest-VN3-56262246 has no policy to be verified 2025-08-10 11:23:48,869 - DEBUG - Verifying the vn in opserver 2025-08-10 11:23:48,869 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN3-56262246 virtual network link through opserver 10.0.0.49 2025-08-10 11:23:48,869 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-networks 2025-08-10 11:23:48,977 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN3-56262246', 'href': 'http://10.0.0.49:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN3-56262246?flat'} 2025-08-10 11:23:48,977 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN3-56262246 virtual network link through opserver 10.0.0.254 2025-08-10 11:23:48,978 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks 2025-08-10 11:23:49,029 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN3-56262246', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN3-56262246?flat'} 2025-08-10 11:23:49,029 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN3-56262246 virtual network link through opserver 10.0.0.57 2025-08-10 11:23:49,029 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-networks 2025-08-10 11:23:49,075 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN3-56262246', 'href': 'http://10.0.0.57:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN3-56262246?flat'} 2025-08-10 11:23:49,075 - INFO - Validated that VN default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN3-56262246 is found in opserver 2025-08-10 11:23:49,096 - DEBUG - Do not have enough data to verify VN in agent 2025-08-10 11:23:49,106 - DEBUG - VRF ids for VN ctest-VN3-56262246: {} 2025-08-10 11:23:49,112 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = -1, end_port = -1], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN3-56262246, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, 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-10 11:23:49,372 - DEBUG - No source network defined 2025-08-10 11:23:49,372 - DEBUG - No source network defined 2025-08-10 11:23:49,372 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = None, security_group = None, network_policy = default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-policy13-34388548, 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, rule_sequence = None, rule_uuid = None, direction = <>, protocol = 58, src_addresses = [subnet = None, virtual_network = None, security_group = None, network_policy = default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-policy13-34388548, 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-10 11:23:49,624 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN2-85407297, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = -1, end_port = -1], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402, 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, rule_sequence = None, rule_uuid = None, direction = <>, protocol = 58, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN2-85407297, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = -1, end_port = -1], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402, 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-10 11:23:49,844 - DEBUG - Requesting: http://10.0.0.49:8082/domains 2025-08-10 11:23:49,850 - DEBUG - Requesting: http://10.0.0.49:8082/domain/506b9682-8eb9-488e-ab69-24d332f4d228 2025-08-10 11:23:49,864 - DEBUG - Requesting: http://10.0.0.49:8082/project/40762832-e00c-4f09-8da5-c16b2522c8cf 2025-08-10 11:23:49,961 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/618a4142-6b96-4427-9086-78caf70c37c0 2025-08-10 11:23:49,971 - DEBUG - Setup step: Associating the policy to VN' 2025-08-10 11:23:50,070 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '618a4142-6b96-4427-9086-78caf70c37c0', 'name': 'ctest-VN1-03874402', 'tenant_id': '40762832e00c4f098da5c16b2522c8cf', 'project_id': '40762832e00c4f098da5c16b2522c8cf', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['b23fa5e3-8d4c-4c69-98f8-3a97151a580e', '7452e0e3-44c9-4dc1-a082-c0a6688b6423'], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-10181764', 'ctest-VN1-03874402'], 'policys': [['default-domain', 'ctest-TestPolicyAclIpv4v6-10181764', 'ctest-policy12-69623423'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-10181764', 'ctest-policy13-34388548']], 'subnet_ipam': [{'subnet_cidr': '84.134.216.0/26', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-10181764', 'ipam1']}, {'subnet_cidr': '2e97:276d:6e93:461f:70ac:83a9::/96', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-10181764', 'ipam1']}], 'port_security_enabled': True, 'description': ''}} 2025-08-10 11:23:50,202 - DEBUG - Associated Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-10181764', 'ctest-policy12-69623423'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-10181764', 'ctest-policy13-34388548']] to ctest-VN1-03874402 2025-08-10 11:23:50,202 - DEBUG - Requesting: http://10.0.0.49:8082/domains 2025-08-10 11:23:50,207 - DEBUG - Requesting: http://10.0.0.49:8082/domain/506b9682-8eb9-488e-ab69-24d332f4d228 2025-08-10 11:23:50,218 - DEBUG - Requesting: http://10.0.0.49:8082/project/40762832-e00c-4f09-8da5-c16b2522c8cf 2025-08-10 11:23:50,301 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/f855bad3-59dc-4255-9e4e-b8e40654a514 2025-08-10 11:23:50,312 - DEBUG - Setup step: Associating the policy to VN' 2025-08-10 11:23:50,429 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': 'f855bad3-59dc-4255-9e4e-b8e40654a514', 'name': 'ctest-VN2-85407297', 'tenant_id': '40762832e00c4f098da5c16b2522c8cf', 'project_id': '40762832e00c4f098da5c16b2522c8cf', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['b4c3afd4-b5c7-450f-b88a-a76726e152d5', 'ff0fcf97-3b65-49c9-a86c-941158c6a523'], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-10181764', 'ctest-VN2-85407297'], 'policys': [['default-domain', 'ctest-TestPolicyAclIpv4v6-10181764', 'ctest-policy21-23656219']], 'subnet_ipam': [{'subnet_cidr': '28.169.193.64/26', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-10181764', 'ipam2']}, {'subnet_cidr': '2012:4ac:c77a:8ac8:4a0d:58b1::/96', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-10181764', 'ipam2']}], 'port_security_enabled': True, 'description': ''}} 2025-08-10 11:23:50,523 - DEBUG - Associated Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-10181764', 'ctest-policy21-23656219']] to ctest-VN2-85407297 2025-08-10 11:23:50,618 - DEBUG - Services list from nova: [, , , ] 2025-08-10 11:23:51,630 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3786-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3786-1) 2025-08-10 11:23:52,922 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3786-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3786-2) 2025-08-10 11:23:54,379 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3786-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3786-1) 2025-08-10 11:23:54,446 - DEBUG - VM is in ACTIVE state now 2025-08-10 11:23:54,446 - INFO - VM name : ctest-VM11-96522352 2025-08-10 11:23:54,520 - DEBUG - VM ctest-VM11-96522352 ID is 64ac911a-84df-41c8-89ab-c79cb3c538e2 2025-08-10 11:23:54,601 - DEBUG - VM is in ACTIVE state now 2025-08-10 11:23:54,626 - DEBUG - VM ctest-VM11-96522352 launched on Node an-jenkins-deploy-platform-ansible-os-3786-1 2025-08-10 11:23:54,709 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-machine/64ac911a-84df-41c8-89ab-c79cb3c538e2 2025-08-10 11:23:55,393 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-machine/64ac911a-84df-41c8-89ab-c79cb3c538e2 2025-08-10 11:23:55,426 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-machine/64ac911a-84df-41c8-89ab-c79cb3c538e2 2025-08-10 11:23:55,437 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-machine-interface/dad1f835-77fb-426e-972c-0e7118653fd6 2025-08-10 11:23:55,450 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-machine/64ac911a-84df-41c8-89ab-c79cb3c538e2 2025-08-10 11:23:55,459 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-machine-interface/dad1f835-77fb-426e-972c-0e7118653fd6 2025-08-10 11:23:55,470 - DEBUG - Requesting: http://10.0.0.57:8082/instance-ip/23de7dc5-b717-4ed3-961d-5f2f095373ab 2025-08-10 11:23:55,486 - DEBUG - Requesting: http://10.0.0.57:8082/instance-ip/f80c5418-d459-412d-bec6-780f258a875a 2025-08-10 11:23:55,495 - DEBUG - Verifying in api server 10.0.0.57 2025-08-10 11:23:55,495 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-machine/64ac911a-84df-41c8-89ab-c79cb3c538e2 2025-08-10 11:23:55,502 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-machine-interface/dad1f835-77fb-426e-972c-0e7118653fd6 2025-08-10 11:23:55,517 - DEBUG - Requesting: http://10.0.0.57:8082/instance-ip/23de7dc5-b717-4ed3-961d-5f2f095373ab 2025-08-10 11:23:55,525 - DEBUG - Requesting: http://10.0.0.57:8082/instance-ip/f80c5418-d459-412d-bec6-780f258a875a 2025-08-10 11:23:55,534 - INFO - VM ctest-VM11-96522352 verfication in all API Servers passed 2025-08-10 11:23:55,561 - DEBUG - VM ctest-VM11-96522352 Tap interface: {'index': '3', 'name': 'tapdad1f835-77', 'uuid': 'dad1f835-77fb-426e-972c-0e7118653fd6', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402:ctest-VN1-03874402', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Active', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Enable', 'dns_service': 'Enable', 'type': 'vport', 'label': '25', 'l2_label': '29', 'vxlan_id': '15', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402', 'vm_uuid': '64ac911a-84df-41c8-89ab-c79cb3c538e2', 'vm_name': 'ctest-VM11-96522352', 'ip_addr': '84.134.216.3', 'mac_addr': '02:da:d1:f8:35:77', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.3', 'service_vlan_list': None, 'os_ifindex': '50', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestPolicyAclIpv4v6-10181764:dad1f835-77fb-426e-972c-0e7118653fd6', 'sg_uuid_list': ['f5384303-bcf4-4632-91a7-9b2d718e131d'], 'static_route_list': None, 'vm_project_uuid': '40762832-e00c-4f09-8da5-c16b2522c8cf', 'admin_state': 'Enabled', 'flow_key_idx': '33', 'allowed_address_pair_list': None, 'ip6_addr': '2e97:276d:6e93:461f:70ac:83a9:0:3', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []} 2025-08-10 11:23:55,561 - DEBUG - Agent 10.0.0.65 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402:ctest-VN1-03874402 2025-08-10 11:23:55,567 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402:ctest-VN1-03874402', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402', 'table_label': '-1', 'vxlan_id': '15', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.193:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2025-08-10 11:23:55,810 - DEBUG - VM ctest-VM11-96522352 : Tap interface tapdad1f835-77 is set to Active 2025-08-10 11:23:55,810 - DEBUG - Tap interface tapdad1f835-77 detail : {'index': '3', 'name': 'tapdad1f835-77', 'uuid': 'dad1f835-77fb-426e-972c-0e7118653fd6', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402:ctest-VN1-03874402', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Active', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Enable', 'dns_service': 'Enable', 'type': 'vport', 'label': '25', 'l2_label': '29', 'vxlan_id': '15', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402', 'vm_uuid': '64ac911a-84df-41c8-89ab-c79cb3c538e2', 'vm_name': 'ctest-VM11-96522352', 'ip_addr': '84.134.216.3', 'mac_addr': '02:da:d1:f8:35:77', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.3', 'service_vlan_list': None, 'os_ifindex': '50', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestPolicyAclIpv4v6-10181764:dad1f835-77fb-426e-972c-0e7118653fd6', 'sg_uuid_list': ['f5384303-bcf4-4632-91a7-9b2d718e131d'], 'static_route_list': None, 'vm_project_uuid': '40762832-e00c-4f09-8da5-c16b2522c8cf', 'admin_state': 'Enabled', 'flow_key_idx': '33', 'allowed_address_pair_list': None, 'ip6_addr': '2e97:276d:6e93:461f:70ac:83a9:0:3', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []} 2025-08-10 11:23:55,920 - DEBUG - Starting Layer 2 verification in Agent 2025-08-10 11:23:55,932 - DEBUG - Layer 2 path is seen for VM MAC 02:da:d1:f8:35:77 in agent 10.0.0.65 2025-08-10 11:23:55,933 - DEBUG - Active layer 2 route in agent is present for VMI tapdad1f835-77 2025-08-10 11:23:55,933 - DEBUG - L2 label(29) matches bw route table and interface table 2025-08-10 11:23:55,940 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402 is found in Agent of node 10.0.0.65 2025-08-10 11:23:56,110 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402 is consistent in agent 10.0.0.65 2025-08-10 11:23:56,110 - DEBUG - Route for VM IP ['84.134.216.3', '2e97:276d:6e93:461f:70ac:83a9:0:3'] is consistent in agent 10.0.0.65 2025-08-10 11:23:56,110 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402 verification for VM ctest-VM11-96522352 in Agent 10.0.0.65 passed 2025-08-10 11:23:56,259 - DEBUG - Starting all layer 2 verification in agent 10.0.0.65 2025-08-10 11:23:56,282 - DEBUG - Route for VM MAC 02:da:d1:f8:35:77 is consistent in agent 10.0.0.65 2025-08-10 11:23:59,514 - 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 1016ms') 2025-08-10 11:23:59,514 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-96522352 failed! 2025-08-10 11:23:59,527 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402 is 84.134.216.1 and allocation pool is NOT set 2025-08-10 11:23:59,527 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402 is 2e97:276d:6e93:461f:70ac:83a9:0:1 and allocation pool is NOT set 2025-08-10 11:24:03,615 - 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-10 11:24:03,615 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-96522352 failed! 2025-08-10 11:24:03,628 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402 is 84.134.216.1 and allocation pool is NOT set 2025-08-10 11:24:03,628 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402 is 2e97:276d:6e93:461f:70ac:83a9:0:1 and allocation pool is NOT set 2025-08-10 11:24:07,715 - 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 1022ms') 2025-08-10 11:24:07,715 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-96522352 failed! 2025-08-10 11:24:07,729 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402 is 84.134.216.1 and allocation pool is NOT set 2025-08-10 11:24:07,730 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402 is 2e97:276d:6e93:461f:70ac:83a9:0:1 and allocation pool is NOT set 2025-08-10 11:24:11,801 - 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 1015ms') 2025-08-10 11:24:11,801 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-96522352 failed! 2025-08-10 11:24:11,814 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402 is 84.134.216.1 and allocation pool is NOT set 2025-08-10 11:24:11,814 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402 is 2e97:276d:6e93:461f:70ac:83a9:0:1 and allocation pool is NOT set 2025-08-10 11:24:15,901 - 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 1027ms') 2025-08-10 11:24:15,901 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-96522352 failed! 2025-08-10 11:24:15,914 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402 is 84.134.216.1 and allocation pool is NOT set 2025-08-10 11:24:15,914 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402 is 2e97:276d:6e93:461f:70ac:83a9:0:1 and allocation pool is NOT set 2025-08-10 11:24:19,991 - 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 1024ms') 2025-08-10 11:24:19,991 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-96522352 failed! 2025-08-10 11:24:20,004 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402 is 84.134.216.1 and allocation pool is NOT set 2025-08-10 11:24:20,004 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402 is 2e97:276d:6e93:461f:70ac:83a9:0:1 and allocation pool is NOT set 2025-08-10 11:24:24,096 - 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 1023ms') 2025-08-10 11:24:24,096 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-96522352 failed! 2025-08-10 11:24:24,152 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402 is 84.134.216.1 and allocation pool is NOT set 2025-08-10 11:24:24,152 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402 is 2e97:276d:6e93:461f:70ac:83a9:0:1 and allocation pool is NOT set 2025-08-10 11:24:28,219 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1004ms') 2025-08-10 11:24:28,219 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-96522352 failed! 2025-08-10 11:24:28,231 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402 is 84.134.216.1 and allocation pool is NOT set 2025-08-10 11:24:28,231 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402 is 2e97:276d:6e93:461f:70ac:83a9:0:1 and allocation pool is NOT set 2025-08-10 11:24:32,312 - 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 1022ms') 2025-08-10 11:24:32,312 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-96522352 failed! 2025-08-10 11:24:32,324 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402 is 84.134.216.1 and allocation pool is NOT set 2025-08-10 11:24:32,324 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402 is 2e97:276d:6e93:461f:70ac:83a9:0:1 and allocation pool is NOT set 2025-08-10 11:24:34,393 - 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=5.61 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=3.73 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1003ms\r\nrtt min/avg/max/mdev = 3.728/4.670/5.612/0.942 ms') 2025-08-10 11:24:34,394 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-96522352 passed 2025-08-10 11:24:34,394 - INFO - VM ctest-VM11-96522352 verifications in Compute nodes passed 2025-08-10 11:24:34,713 - DEBUG - Validated VM route 84.134.216.3 in vrouter of 10.0.0.65 2025-08-10 11:24:34,717 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0 2025-08-10 11:24:34,717 - INFO - Validated routes of VM ctest-VM11-96522352 in all vrouters 2025-08-10 11:24:35,204 - DEBUG - Starting all layer2 verification in 10.0.0.57 Control Node 2025-08-10 11:24:35,366 - DEBUG - Layer2 route found for VM MAC 02:da:d1:f8:35:77 in Control-node 10.0.0.57 2025-08-10 11:24:35,366 - DEBUG - L2 Label for VM ctest-VM11-96522352 same between Control-node 10.0.0.57 and Agent, Expected: 29, Seen: 29 2025-08-10 11:24:35,515 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.57, not supported 2025-08-10 11:24:35,515 - DEBUG - Starting all layer2 verification in 10.0.0.254 Control Node 2025-08-10 11:24:35,675 - DEBUG - Layer2 route found for VM MAC 02:da:d1:f8:35:77 in Control-node 10.0.0.254 2025-08-10 11:24:35,675 - DEBUG - L2 Label for VM ctest-VM11-96522352 same between Control-node 10.0.0.254 and Agent, Expected: 29, Seen: 29 2025-08-10 11:24:35,827 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.254, not supported 2025-08-10 11:24:35,827 - INFO - Verification in Control-nodes for VM ctest-VM11-96522352 passed 2025-08-10 11:24:35,827 - DEBUG - Verifying the vm in opserver 2025-08-10 11:24:35,827 - DEBUG - Verifying in collector 10.0.0.57 ... 2025-08-10 11:24:35,827 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-machine/64ac911a-84df-41c8-89ab-c79cb3c538e2?flat 2025-08-10 11:24:35,842 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-10181764:dad1f835-77fb-426e-972c-0e7118653fd6?flat 2025-08-10 11:24:35,860 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-10181764:dad1f835-77fb-426e-972c-0e7118653fd6?flat 2025-08-10 11:24:35,878 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-10181764:dad1f835-77fb-426e-972c-0e7118653fd6?flat 2025-08-10 11:24:35,895 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-machine/64ac911a-84df-41c8-89ab-c79cb3c538e2?flat 2025-08-10 11:24:35,909 - DEBUG - Verifying vm in vn uve 2025-08-10 11:24:35,909 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-10181764:dad1f835-77fb-426e-972c-0e7118653fd6?flat 2025-08-10 11:24:35,926 - DEBUG - VM uve shows interface as {'tx_vlan': 65535, 'admin_state': True, 'vm_uuid': '64ac911a-84df-41c8-89ab-c79cb3c538e2', 'ip4_active': True, 'l2_active': True, 'gateway': '84.134.216.1', 'port_mirror_enabled': False, 'active': True, 'uuid': 'dad1f835-77fb-426e-972c-0e7118653fd6', 'label': 25, 'rx_vlan': 65535, 'mac_address': '02:da:d1:f8:35:77', 'vm_name': 'ctest-VM11-96522352', 'vhostuser_mode': 0, 'vn_uuid': '618a4142-6b96-4427-9086-78caf70c37c0', 'fixed_ip4_list': ['84.134.216.3'], 'ip_address': '84.134.216.3', 'is_health_check_active': True, 'fixed_ip6_list': ['2e97:276d:6e93:461f:70ac:83a9:0:3'], 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402', 'ip6_address': '2e97:276d:6e93:461f:70ac:83a9:0:3', '__T': 1754825040885105, 'ip6_active': True} 2025-08-10 11:24:35,926 - DEBUG - VM uve shows ip address as ['84.134.216.3', '2e97:276d:6e93:461f:70ac:83a9:0:3'] 2025-08-10 11:24:35,926 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402 2025-08-10 11:24:35,926 - INFO - Verifying through opserver in 10.0.0.49 2025-08-10 11:24:35,926 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402?flat 2025-08-10 11:24:35,949 - DEBUG - expected vm list 64ac911a-84df-41c8-89ab-c79cb3c538e2 2025-08-10 11:24:35,949 - DEBUG - Extracted vm list ['64ac911a-84df-41c8-89ab-c79cb3c538e2'] 2025-08-10 11:24:35,950 - INFO - VM 64ac911a-84df-41c8-89ab-c79cb3c538e2 is present in default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402 2025-08-10 11:24:35,950 - DEBUG - Verifying vm in vrouter uve 2025-08-10 11:24:35,950 - DEBUG - Getting info from collector 10.0.0.57.. 2025-08-10 11:24:35,950 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-machine/64ac911a-84df-41c8-89ab-c79cb3c538e2?flat 2025-08-10 11:24:35,963 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-3786-1'] 2025-08-10 11:24:35,963 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3786-1:Compute:contrail-vrouter-agent:0?flat 2025-08-10 11:24:36,007 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3786-1?flat 2025-08-10 11:24:36,042 - DEBUG - VM 64ac911a-84df-41c8-89ab-c79cb3c538e2 is present in vrouter an-jenkins-deploy-platform-ansible-os-3786-1 uve 2025-08-10 11:24:36,042 - DEBUG - Validated that VM ctest-VM11-96522352 is in Vrouter an-jenkins-deploy-platform-ansible-os-3786-1 UVE 2025-08-10 11:24:36,042 - DEBUG - Verifying vm tap interface/vn in vrouter uve 2025-08-10 11:24:36,049 - DEBUG - Expected tap interface of VM uuid 64ac911a-84df-41c8-89ab-c79cb3c538e2 is default-domain:ctest-TestPolicyAclIpv4v6-10181764:dad1f835-77fb-426e-972c-0e7118653fd6 2025-08-10 11:24:36,049 - DEBUG - Expected VN of VM uuid 64ac911a-84df-41c8-89ab-c79cb3c538e2 is default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402 2025-08-10 11:24:36,049 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3786-1:Compute:contrail-vrouter-agent:0?flat 2025-08-10 11:24:36,087 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3786-1?flat 2025-08-10 11:24:36,119 - DEBUG - VM 64ac911a-84df-41c8-89ab-c79cb3c538e2 is present in vrouter an-jenkins-deploy-platform-ansible-os-3786-1 uve 2025-08-10 11:24:36,119 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-10181764:dad1f835-77fb-426e-972c-0e7118653fd6 of vm 64ac911a-84df-41c8-89ab-c79cb3c538e2 is present in vrouter an-jenkins-deploy-platform-ansible-os-3786-1 uve 2025-08-10 11:24:36,120 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402 of vm 64ac911a-84df-41c8-89ab-c79cb3c538e2 is present in vrouter an-jenkins-deploy-platform-ansible-os-3786-1 uve 2025-08-10 11:24:36,120 - DEBUG - Verifying the 64ac911a-84df-41c8-89ab-c79cb3c538e2 virtual network link through opserver 10.0.0.49 2025-08-10 11:24:36,120 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-machines 2025-08-10 11:24:36,130 - DEBUG - VM link and name as {'name': '64ac911a-84df-41c8-89ab-c79cb3c538e2', 'href': 'http://10.0.0.49:8081/analytics/uves/virtual-machine/64ac911a-84df-41c8-89ab-c79cb3c538e2?flat'} 2025-08-10 11:24:36,130 - DEBUG - Verifying the 64ac911a-84df-41c8-89ab-c79cb3c538e2 virtual network link through opserver 10.0.0.254 2025-08-10 11:24:36,130 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-machines 2025-08-10 11:24:36,139 - DEBUG - VM link and name as {'name': '64ac911a-84df-41c8-89ab-c79cb3c538e2', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-machine/64ac911a-84df-41c8-89ab-c79cb3c538e2?flat'} 2025-08-10 11:24:36,139 - DEBUG - Verifying the 64ac911a-84df-41c8-89ab-c79cb3c538e2 virtual network link through opserver 10.0.0.57 2025-08-10 11:24:36,139 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-machines 2025-08-10 11:24:36,150 - DEBUG - VM link and name as {'name': '64ac911a-84df-41c8-89ab-c79cb3c538e2', 'href': 'http://10.0.0.57:8081/analytics/uves/virtual-machine/64ac911a-84df-41c8-89ab-c79cb3c538e2?flat'} 2025-08-10 11:24:36,150 - INFO - VM ctest-VM11-96522352 validations in Opserver passed 2025-08-10 11:24:36,217 - DEBUG - VM is in ACTIVE state now 2025-08-10 11:24:36,217 - INFO - VM name : ctest-VM21-03287090 2025-08-10 11:24:36,280 - DEBUG - VM ctest-VM21-03287090 ID is 5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 2025-08-10 11:24:36,337 - DEBUG - VM is in ACTIVE state now 2025-08-10 11:24:36,337 - DEBUG - VM ctest-VM21-03287090 launched on Node an-jenkins-deploy-platform-ansible-os-3786-2 2025-08-10 11:24:36,395 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-machine/5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 2025-08-10 11:24:36,402 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-machine/5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 2025-08-10 11:24:36,408 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-machine-interface/d6e46592-ea9d-4a24-8b2d-d3dae0d51ec1 2025-08-10 11:24:36,417 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-machine/5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 2025-08-10 11:24:36,423 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-machine-interface/d6e46592-ea9d-4a24-8b2d-d3dae0d51ec1 2025-08-10 11:24:36,432 - DEBUG - Requesting: http://10.0.0.57:8082/instance-ip/99098b1b-7e58-4f86-997b-0b46aeccf199 2025-08-10 11:24:36,439 - DEBUG - Requesting: http://10.0.0.57:8082/instance-ip/d7478b25-6d14-44ee-a8fe-dbf0349c5a5e 2025-08-10 11:24:36,447 - DEBUG - Verifying in api server 10.0.0.57 2025-08-10 11:24:36,447 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-machine/5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 2025-08-10 11:24:36,453 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-machine-interface/d6e46592-ea9d-4a24-8b2d-d3dae0d51ec1 2025-08-10 11:24:36,461 - DEBUG - Requesting: http://10.0.0.57:8082/instance-ip/99098b1b-7e58-4f86-997b-0b46aeccf199 2025-08-10 11:24:36,468 - DEBUG - Requesting: http://10.0.0.57:8082/instance-ip/d7478b25-6d14-44ee-a8fe-dbf0349c5a5e 2025-08-10 11:24:36,474 - INFO - VM ctest-VM21-03287090 verfication in all API Servers passed 2025-08-10 11:24:36,490 - DEBUG - VM ctest-VM21-03287090 Tap interface: {'index': '3', 'name': 'tapd6e46592-ea', 'uuid': 'd6e46592-ea9d-4a24-8b2d-d3dae0d51ec1', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN2-85407297:ctest-VN2-85407297', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Active', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Enable', 'dns_service': 'Enable', 'type': 'vport', 'label': '25', 'l2_label': '29', 'vxlan_id': '16', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN2-85407297', 'vm_uuid': '5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2', 'vm_name': 'ctest-VM21-03287090', 'ip_addr': '28.169.193.67', 'mac_addr': '02:d6:e4:65:92:ea', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.3', 'service_vlan_list': None, 'os_ifindex': '44', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestPolicyAclIpv4v6-10181764:d6e46592-ea9d-4a24-8b2d-d3dae0d51ec1', 'sg_uuid_list': ['f5384303-bcf4-4632-91a7-9b2d718e131d'], 'static_route_list': None, 'vm_project_uuid': '40762832-e00c-4f09-8da5-c16b2522c8cf', 'admin_state': 'Enabled', 'flow_key_idx': '31', 'allowed_address_pair_list': None, 'ip6_addr': '2012:4ac:c77a:8ac8:4a0d:58b1:0:3', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []} 2025-08-10 11:24:36,490 - DEBUG - Agent 10.0.0.56 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN2-85407297:ctest-VN2-85407297 2025-08-10 11:24:36,496 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN2-85407297:ctest-VN2-85407297', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN2-85407297', 'table_label': '-1', 'vxlan_id': '16', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.129:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2025-08-10 11:24:36,687 - DEBUG - VM ctest-VM21-03287090 : Tap interface tapd6e46592-ea is set to Active 2025-08-10 11:24:36,687 - DEBUG - Tap interface tapd6e46592-ea detail : {'index': '3', 'name': 'tapd6e46592-ea', 'uuid': 'd6e46592-ea9d-4a24-8b2d-d3dae0d51ec1', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN2-85407297:ctest-VN2-85407297', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Active', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Enable', 'dns_service': 'Enable', 'type': 'vport', 'label': '25', 'l2_label': '29', 'vxlan_id': '16', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN2-85407297', 'vm_uuid': '5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2', 'vm_name': 'ctest-VM21-03287090', 'ip_addr': '28.169.193.67', 'mac_addr': '02:d6:e4:65:92:ea', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.3', 'service_vlan_list': None, 'os_ifindex': '44', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestPolicyAclIpv4v6-10181764:d6e46592-ea9d-4a24-8b2d-d3dae0d51ec1', 'sg_uuid_list': ['f5384303-bcf4-4632-91a7-9b2d718e131d'], 'static_route_list': None, 'vm_project_uuid': '40762832-e00c-4f09-8da5-c16b2522c8cf', 'admin_state': 'Enabled', 'flow_key_idx': '31', 'allowed_address_pair_list': None, 'ip6_addr': '2012:4ac:c77a:8ac8:4a0d:58b1:0:3', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []} 2025-08-10 11:24:36,843 - DEBUG - Starting Layer 2 verification in Agent 2025-08-10 11:24:36,860 - DEBUG - Layer 2 path is seen for VM MAC 02:d6:e4:65:92:ea in agent 10.0.0.56 2025-08-10 11:24:36,861 - DEBUG - Active layer 2 route in agent is present for VMI tapd6e46592-ea 2025-08-10 11:24:36,861 - DEBUG - L2 label(29) matches bw route table and interface table 2025-08-10 11:24:36,880 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN2-85407297 is found in Agent of node 10.0.0.56 2025-08-10 11:24:37,041 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN2-85407297 is consistent in agent 10.0.0.56 2025-08-10 11:24:37,041 - DEBUG - Route for VM IP ['28.169.193.67', '2012:4ac:c77a:8ac8:4a0d:58b1:0:3'] is consistent in agent 10.0.0.56 2025-08-10 11:24:37,041 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN2-85407297 verification for VM ctest-VM21-03287090 in Agent 10.0.0.56 passed 2025-08-10 11:24:37,147 - DEBUG - Starting all layer 2 verification in agent 10.0.0.56 2025-08-10 11:24:37,165 - DEBUG - Route for VM MAC 02:d6:e4:65:92:ea is consistent in agent 10.0.0.56 2025-08-10 11:24:38,370 - 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=5.32 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.15 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 = 1.149/3.236/5.324/2.087 ms') 2025-08-10 11:24:38,371 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-03287090 passed 2025-08-10 11:24:38,371 - INFO - VM ctest-VM21-03287090 verifications in Compute nodes passed 2025-08-10 11:24:38,667 - DEBUG - Validated VM route 28.169.193.67 in vrouter of 10.0.0.56 2025-08-10 11:24:38,670 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0 2025-08-10 11:24:38,670 - INFO - Validated routes of VM ctest-VM21-03287090 in all vrouters 2025-08-10 11:24:39,168 - DEBUG - Starting all layer2 verification in 10.0.0.57 Control Node 2025-08-10 11:24:39,331 - DEBUG - Layer2 route found for VM MAC 02:d6:e4:65:92:ea in Control-node 10.0.0.57 2025-08-10 11:24:39,331 - DEBUG - L2 Label for VM ctest-VM21-03287090 same between Control-node 10.0.0.57 and Agent, Expected: 29, Seen: 29 2025-08-10 11:24:39,488 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.57, not supported 2025-08-10 11:24:39,489 - DEBUG - Starting all layer2 verification in 10.0.0.254 Control Node 2025-08-10 11:24:39,652 - DEBUG - Layer2 route found for VM MAC 02:d6:e4:65:92:ea in Control-node 10.0.0.254 2025-08-10 11:24:39,652 - DEBUG - L2 Label for VM ctest-VM21-03287090 same between Control-node 10.0.0.254 and Agent, Expected: 29, Seen: 29 2025-08-10 11:24:39,803 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.254, not supported 2025-08-10 11:24:39,803 - INFO - Verification in Control-nodes for VM ctest-VM21-03287090 passed 2025-08-10 11:24:39,803 - DEBUG - Verifying the vm in opserver 2025-08-10 11:24:39,804 - DEBUG - Verifying in collector 10.0.0.57 ... 2025-08-10 11:24:39,804 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-machine/5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2?flat 2025-08-10 11:24:39,820 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-10181764:d6e46592-ea9d-4a24-8b2d-d3dae0d51ec1?flat 2025-08-10 11:24:39,839 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-10181764:d6e46592-ea9d-4a24-8b2d-d3dae0d51ec1?flat 2025-08-10 11:24:39,859 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-10181764:d6e46592-ea9d-4a24-8b2d-d3dae0d51ec1?flat 2025-08-10 11:24:39,878 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-machine/5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2?flat 2025-08-10 11:24:39,892 - DEBUG - Verifying vm in vn uve 2025-08-10 11:24:39,892 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-10181764:d6e46592-ea9d-4a24-8b2d-d3dae0d51ec1?flat 2025-08-10 11:24:39,912 - DEBUG - VM uve shows interface as {'tx_vlan': 65535, 'admin_state': True, 'vm_uuid': '5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2', 'ip4_active': True, 'l2_active': True, 'gateway': '28.169.193.65', 'port_mirror_enabled': False, 'active': True, 'uuid': 'd6e46592-ea9d-4a24-8b2d-d3dae0d51ec1', 'label': 25, 'rx_vlan': 65535, 'mac_address': '02:d6:e4:65:92:ea', 'vm_name': 'ctest-VM21-03287090', 'vhostuser_mode': 0, 'vn_uuid': 'f855bad3-59dc-4255-9e4e-b8e40654a514', 'fixed_ip4_list': ['28.169.193.67'], 'ip_address': '28.169.193.67', 'is_health_check_active': True, 'fixed_ip6_list': ['2012:4ac:c77a:8ac8:4a0d:58b1:0:3'], 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN2-85407297', 'ip6_address': '2012:4ac:c77a:8ac8:4a0d:58b1:0:3', '__T': 1754825040890492, 'ip6_active': True} 2025-08-10 11:24:39,912 - DEBUG - VM uve shows ip address as ['28.169.193.67', '2012:4ac:c77a:8ac8:4a0d:58b1:0:3'] 2025-08-10 11:24:39,912 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN2-85407297 2025-08-10 11:24:39,912 - INFO - Verifying through opserver in 10.0.0.49 2025-08-10 11:24:39,912 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN2-85407297?flat 2025-08-10 11:24:39,935 - DEBUG - expected vm list 5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 2025-08-10 11:24:39,935 - DEBUG - Extracted vm list ['5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2'] 2025-08-10 11:24:39,935 - INFO - VM 5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 is present in default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN2-85407297 2025-08-10 11:24:39,935 - DEBUG - Verifying vm in vrouter uve 2025-08-10 11:24:39,935 - DEBUG - Getting info from collector 10.0.0.57.. 2025-08-10 11:24:39,935 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-machine/5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2?flat 2025-08-10 11:24:39,949 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-3786-2'] 2025-08-10 11:24:39,949 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3786-2:Compute:contrail-vrouter-agent:0?flat 2025-08-10 11:24:39,986 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3786-2?flat 2025-08-10 11:24:40,018 - DEBUG - VM 5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 is present in vrouter an-jenkins-deploy-platform-ansible-os-3786-2 uve 2025-08-10 11:24:40,019 - DEBUG - Validated that VM ctest-VM21-03287090 is in Vrouter an-jenkins-deploy-platform-ansible-os-3786-2 UVE 2025-08-10 11:24:40,019 - DEBUG - Verifying vm tap interface/vn in vrouter uve 2025-08-10 11:24:40,025 - DEBUG - Expected tap interface of VM uuid 5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 is default-domain:ctest-TestPolicyAclIpv4v6-10181764:d6e46592-ea9d-4a24-8b2d-d3dae0d51ec1 2025-08-10 11:24:40,025 - DEBUG - Expected VN of VM uuid 5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 is default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN2-85407297 2025-08-10 11:24:40,025 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3786-2:Compute:contrail-vrouter-agent:0?flat 2025-08-10 11:24:40,061 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3786-2?flat 2025-08-10 11:24:40,095 - DEBUG - VM 5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 is present in vrouter an-jenkins-deploy-platform-ansible-os-3786-2 uve 2025-08-10 11:24:40,095 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-10181764:d6e46592-ea9d-4a24-8b2d-d3dae0d51ec1 of vm 5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 is present in vrouter an-jenkins-deploy-platform-ansible-os-3786-2 uve 2025-08-10 11:24:40,095 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN2-85407297 of vm 5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 is present in vrouter an-jenkins-deploy-platform-ansible-os-3786-2 uve 2025-08-10 11:24:40,095 - DEBUG - Verifying the 5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 virtual network link through opserver 10.0.0.49 2025-08-10 11:24:40,095 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-machines 2025-08-10 11:24:40,109 - DEBUG - VM link and name as {'name': '5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2', 'href': 'http://10.0.0.49:8081/analytics/uves/virtual-machine/5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2?flat'} 2025-08-10 11:24:40,109 - DEBUG - Verifying the 5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 virtual network link through opserver 10.0.0.254 2025-08-10 11:24:40,109 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-machines 2025-08-10 11:24:40,120 - DEBUG - VM link and name as {'name': '5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-machine/5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2?flat'} 2025-08-10 11:24:40,120 - DEBUG - Verifying the 5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 virtual network link through opserver 10.0.0.57 2025-08-10 11:24:40,120 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-machines 2025-08-10 11:24:40,131 - DEBUG - VM link and name as {'name': '5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2', 'href': 'http://10.0.0.57:8081/analytics/uves/virtual-machine/5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2?flat'} 2025-08-10 11:24:40,131 - INFO - VM ctest-VM21-03287090 validations in Opserver passed 2025-08-10 11:24:40,199 - DEBUG - VM is in ACTIVE state now 2025-08-10 11:24:40,199 - INFO - VM name : ctest-VM31-41058406 2025-08-10 11:24:40,265 - DEBUG - VM ctest-VM31-41058406 ID is c8e1c499-167b-41a1-8452-470be6e06c4c 2025-08-10 11:24:40,328 - DEBUG - VM is in ACTIVE state now 2025-08-10 11:24:40,328 - DEBUG - VM ctest-VM31-41058406 launched on Node an-jenkins-deploy-platform-ansible-os-3786-1 2025-08-10 11:24:40,387 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-machine/c8e1c499-167b-41a1-8452-470be6e06c4c 2025-08-10 11:24:40,396 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-machine/c8e1c499-167b-41a1-8452-470be6e06c4c 2025-08-10 11:24:40,403 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-machine-interface/e8a153d5-f2a6-4c54-b075-f85f7e024171 2025-08-10 11:24:40,412 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-machine/c8e1c499-167b-41a1-8452-470be6e06c4c 2025-08-10 11:24:40,418 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-machine-interface/e8a153d5-f2a6-4c54-b075-f85f7e024171 2025-08-10 11:24:40,427 - DEBUG - Requesting: http://10.0.0.57:8082/instance-ip/0132ef16-979b-451e-ac11-ac069c57ef03 2025-08-10 11:24:40,435 - DEBUG - Requesting: http://10.0.0.57:8082/instance-ip/36d8fc94-21e6-479e-a9a9-27c5f661816d 2025-08-10 11:24:40,443 - DEBUG - Verifying in api server 10.0.0.57 2025-08-10 11:24:40,443 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-machine/c8e1c499-167b-41a1-8452-470be6e06c4c 2025-08-10 11:24:40,450 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-machine-interface/e8a153d5-f2a6-4c54-b075-f85f7e024171 2025-08-10 11:24:40,463 - DEBUG - Requesting: http://10.0.0.57:8082/instance-ip/0132ef16-979b-451e-ac11-ac069c57ef03 2025-08-10 11:24:40,469 - DEBUG - Requesting: http://10.0.0.57:8082/instance-ip/36d8fc94-21e6-479e-a9a9-27c5f661816d 2025-08-10 11:24:40,476 - INFO - VM ctest-VM31-41058406 verfication in all API Servers passed 2025-08-10 11:24:40,493 - DEBUG - VM ctest-VM31-41058406 Tap interface: {'index': '4', 'name': 'tape8a153d5-f2', 'uuid': 'e8a153d5-f2a6-4c54-b075-f85f7e024171', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN3-56262246:ctest-VN3-56262246', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Active', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Enable', 'dns_service': 'Enable', 'type': 'vport', 'label': '31', 'l2_label': '35', 'vxlan_id': '17', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN3-56262246', 'vm_uuid': 'c8e1c499-167b-41a1-8452-470be6e06c4c', 'vm_name': 'ctest-VM31-41058406', 'ip_addr': '164.190.15.195', 'mac_addr': '02:e8:a1:53:d5:f2', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.4', 'service_vlan_list': None, 'os_ifindex': '51', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestPolicyAclIpv4v6-10181764:e8a153d5-f2a6-4c54-b075-f85f7e024171', 'sg_uuid_list': ['f5384303-bcf4-4632-91a7-9b2d718e131d'], 'static_route_list': None, 'vm_project_uuid': '40762832-e00c-4f09-8da5-c16b2522c8cf', 'admin_state': 'Enabled', 'flow_key_idx': '40', 'allowed_address_pair_list': None, 'ip6_addr': '215c:11d8:1e6a:97f:b39d:e9f3:0:3', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []} 2025-08-10 11:24:40,493 - DEBUG - Agent 10.0.0.65 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN3-56262246:ctest-VN3-56262246 2025-08-10 11:24:40,499 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN3-56262246:ctest-VN3-56262246', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN3-56262246', 'table_label': '-1', 'vxlan_id': '17', 'evpnindex': '3', 'brindex': '3', 'mplsindex': '0', 'RD': '10.20.0.193:3', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2025-08-10 11:24:40,628 - DEBUG - VM ctest-VM31-41058406 : Tap interface tape8a153d5-f2 is set to Active 2025-08-10 11:24:40,628 - DEBUG - Tap interface tape8a153d5-f2 detail : {'index': '4', 'name': 'tape8a153d5-f2', 'uuid': 'e8a153d5-f2a6-4c54-b075-f85f7e024171', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN3-56262246:ctest-VN3-56262246', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Active', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Enable', 'dns_service': 'Enable', 'type': 'vport', 'label': '31', 'l2_label': '35', 'vxlan_id': '17', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN3-56262246', 'vm_uuid': 'c8e1c499-167b-41a1-8452-470be6e06c4c', 'vm_name': 'ctest-VM31-41058406', 'ip_addr': '164.190.15.195', 'mac_addr': '02:e8:a1:53:d5:f2', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.4', 'service_vlan_list': None, 'os_ifindex': '51', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestPolicyAclIpv4v6-10181764:e8a153d5-f2a6-4c54-b075-f85f7e024171', 'sg_uuid_list': ['f5384303-bcf4-4632-91a7-9b2d718e131d'], 'static_route_list': None, 'vm_project_uuid': '40762832-e00c-4f09-8da5-c16b2522c8cf', 'admin_state': 'Enabled', 'flow_key_idx': '40', 'allowed_address_pair_list': None, 'ip6_addr': '215c:11d8:1e6a:97f:b39d:e9f3:0:3', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []} 2025-08-10 11:24:40,780 - DEBUG - Starting Layer 2 verification in Agent 2025-08-10 11:24:40,790 - DEBUG - Layer 2 path is seen for VM MAC 02:e8:a1:53:d5:f2 in agent 10.0.0.65 2025-08-10 11:24:40,790 - DEBUG - Active layer 2 route in agent is present for VMI tape8a153d5-f2 2025-08-10 11:24:40,790 - DEBUG - L2 label(35) matches bw route table and interface table 2025-08-10 11:24:40,796 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN3-56262246 is found in Agent of node 10.0.0.65 2025-08-10 11:24:40,978 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN3-56262246 is consistent in agent 10.0.0.65 2025-08-10 11:24:40,978 - DEBUG - Route for VM IP ['215c:11d8:1e6a:97f:b39d:e9f3:0:3', '164.190.15.195'] is consistent in agent 10.0.0.65 2025-08-10 11:24:40,978 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN3-56262246 verification for VM ctest-VM31-41058406 in Agent 10.0.0.65 passed 2025-08-10 11:24:41,132 - DEBUG - Starting all layer 2 verification in agent 10.0.0.65 2025-08-10 11:24:41,142 - DEBUG - Route for VM MAC 02:e8:a1:53:d5:f2 is consistent in agent 10.0.0.65 2025-08-10 11:24:42,363 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=2.88 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=2.29 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 2.290/2.585/2.881/0.295 ms') 2025-08-10 11:24:42,363 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-41058406 passed 2025-08-10 11:24:42,363 - INFO - VM ctest-VM31-41058406 verifications in Compute nodes passed 2025-08-10 11:24:42,642 - DEBUG - Validated VM route 164.190.15.195 in vrouter of 10.0.0.65 2025-08-10 11:24:42,645 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0 2025-08-10 11:24:42,645 - INFO - Validated routes of VM ctest-VM31-41058406 in all vrouters 2025-08-10 11:24:43,213 - DEBUG - Starting all layer2 verification in 10.0.0.57 Control Node 2025-08-10 11:24:43,364 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.57, not supported 2025-08-10 11:24:43,526 - DEBUG - Layer2 route found for VM MAC 02:e8:a1:53:d5:f2 in Control-node 10.0.0.57 2025-08-10 11:24:43,526 - DEBUG - L2 Label for VM ctest-VM31-41058406 same between Control-node 10.0.0.57 and Agent, Expected: 35, Seen: 35 2025-08-10 11:24:43,526 - DEBUG - Starting all layer2 verification in 10.0.0.254 Control Node 2025-08-10 11:24:43,679 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.254, not supported 2025-08-10 11:24:43,840 - DEBUG - Layer2 route found for VM MAC 02:e8:a1:53:d5:f2 in Control-node 10.0.0.254 2025-08-10 11:24:43,840 - DEBUG - L2 Label for VM ctest-VM31-41058406 same between Control-node 10.0.0.254 and Agent, Expected: 35, Seen: 35 2025-08-10 11:24:43,841 - INFO - Verification in Control-nodes for VM ctest-VM31-41058406 passed 2025-08-10 11:24:43,841 - DEBUG - Verifying the vm in opserver 2025-08-10 11:24:43,841 - DEBUG - Verifying in collector 10.0.0.57 ... 2025-08-10 11:24:43,841 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-machine/c8e1c499-167b-41a1-8452-470be6e06c4c?flat 2025-08-10 11:24:43,854 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-10181764:e8a153d5-f2a6-4c54-b075-f85f7e024171?flat 2025-08-10 11:24:43,872 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-10181764:e8a153d5-f2a6-4c54-b075-f85f7e024171?flat 2025-08-10 11:24:43,891 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-10181764:e8a153d5-f2a6-4c54-b075-f85f7e024171?flat 2025-08-10 11:24:43,909 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-machine/c8e1c499-167b-41a1-8452-470be6e06c4c?flat 2025-08-10 11:24:43,923 - DEBUG - Verifying vm in vn uve 2025-08-10 11:24:43,923 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-10181764:e8a153d5-f2a6-4c54-b075-f85f7e024171?flat 2025-08-10 11:24:43,941 - DEBUG - VM uve shows interface as {'tx_vlan': 65535, 'admin_state': True, 'vm_uuid': 'c8e1c499-167b-41a1-8452-470be6e06c4c', 'ip4_active': True, 'l2_active': True, 'gateway': '164.190.15.193', 'port_mirror_enabled': False, 'active': True, 'uuid': 'e8a153d5-f2a6-4c54-b075-f85f7e024171', 'label': 31, 'rx_vlan': 65535, 'mac_address': '02:e8:a1:53:d5:f2', 'vm_name': 'ctest-VM31-41058406', 'vhostuser_mode': 0, 'vn_uuid': '4c60ea21-ab16-4147-9001-3a733b7a002d', 'fixed_ip4_list': ['164.190.15.195'], 'ip_address': '164.190.15.195', 'is_health_check_active': True, 'fixed_ip6_list': ['215c:11d8:1e6a:97f:b39d:e9f3:0:3'], 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN3-56262246', 'ip6_address': '215c:11d8:1e6a:97f:b39d:e9f3:0:3', '__T': 1754825040885129, 'ip6_active': True} 2025-08-10 11:24:43,941 - DEBUG - VM uve shows ip address as ['164.190.15.195', '215c:11d8:1e6a:97f:b39d:e9f3:0:3'] 2025-08-10 11:24:43,942 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN3-56262246 2025-08-10 11:24:43,942 - INFO - Verifying through opserver in 10.0.0.49 2025-08-10 11:24:43,942 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN3-56262246?flat 2025-08-10 11:24:43,962 - DEBUG - expected vm list c8e1c499-167b-41a1-8452-470be6e06c4c 2025-08-10 11:24:43,962 - DEBUG - Extracted vm list ['c8e1c499-167b-41a1-8452-470be6e06c4c'] 2025-08-10 11:24:43,962 - INFO - VM c8e1c499-167b-41a1-8452-470be6e06c4c is present in default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN3-56262246 2025-08-10 11:24:43,962 - DEBUG - Verifying vm in vrouter uve 2025-08-10 11:24:43,962 - DEBUG - Getting info from collector 10.0.0.57.. 2025-08-10 11:24:43,962 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-machine/c8e1c499-167b-41a1-8452-470be6e06c4c?flat 2025-08-10 11:24:43,976 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-3786-1'] 2025-08-10 11:24:43,976 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3786-1:Compute:contrail-vrouter-agent:0?flat 2025-08-10 11:24:44,013 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3786-1?flat 2025-08-10 11:24:44,054 - DEBUG - VM c8e1c499-167b-41a1-8452-470be6e06c4c is present in vrouter an-jenkins-deploy-platform-ansible-os-3786-1 uve 2025-08-10 11:24:44,054 - DEBUG - Validated that VM ctest-VM31-41058406 is in Vrouter an-jenkins-deploy-platform-ansible-os-3786-1 UVE 2025-08-10 11:24:44,054 - DEBUG - Verifying vm tap interface/vn in vrouter uve 2025-08-10 11:24:44,061 - DEBUG - Expected tap interface of VM uuid c8e1c499-167b-41a1-8452-470be6e06c4c is default-domain:ctest-TestPolicyAclIpv4v6-10181764:e8a153d5-f2a6-4c54-b075-f85f7e024171 2025-08-10 11:24:44,061 - DEBUG - Expected VN of VM uuid c8e1c499-167b-41a1-8452-470be6e06c4c is default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN3-56262246 2025-08-10 11:24:44,062 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3786-1:Compute:contrail-vrouter-agent:0?flat 2025-08-10 11:24:44,100 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3786-1?flat 2025-08-10 11:24:44,132 - DEBUG - VM c8e1c499-167b-41a1-8452-470be6e06c4c is present in vrouter an-jenkins-deploy-platform-ansible-os-3786-1 uve 2025-08-10 11:24:44,132 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-10181764:e8a153d5-f2a6-4c54-b075-f85f7e024171 of vm c8e1c499-167b-41a1-8452-470be6e06c4c is present in vrouter an-jenkins-deploy-platform-ansible-os-3786-1 uve 2025-08-10 11:24:44,132 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN3-56262246 of vm c8e1c499-167b-41a1-8452-470be6e06c4c is present in vrouter an-jenkins-deploy-platform-ansible-os-3786-1 uve 2025-08-10 11:24:44,132 - DEBUG - Verifying the c8e1c499-167b-41a1-8452-470be6e06c4c virtual network link through opserver 10.0.0.49 2025-08-10 11:24:44,132 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-machines 2025-08-10 11:24:44,143 - DEBUG - VM link and name as {'name': 'c8e1c499-167b-41a1-8452-470be6e06c4c', 'href': 'http://10.0.0.49:8081/analytics/uves/virtual-machine/c8e1c499-167b-41a1-8452-470be6e06c4c?flat'} 2025-08-10 11:24:44,143 - DEBUG - Verifying the c8e1c499-167b-41a1-8452-470be6e06c4c virtual network link through opserver 10.0.0.254 2025-08-10 11:24:44,143 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-machines 2025-08-10 11:24:44,152 - DEBUG - VM link and name as {'name': 'c8e1c499-167b-41a1-8452-470be6e06c4c', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-machine/c8e1c499-167b-41a1-8452-470be6e06c4c?flat'} 2025-08-10 11:24:44,152 - DEBUG - Verifying the c8e1c499-167b-41a1-8452-470be6e06c4c virtual network link through opserver 10.0.0.57 2025-08-10 11:24:44,152 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-machines 2025-08-10 11:24:44,163 - DEBUG - VM link and name as {'name': 'c8e1c499-167b-41a1-8452-470be6e06c4c', 'href': 'http://10.0.0.57:8081/analytics/uves/virtual-machine/c8e1c499-167b-41a1-8452-470be6e06c4c?flat'} 2025-08-10 11:24:44,163 - INFO - VM ctest-VM31-41058406 validations in Opserver passed 2025-08-10 11:24:44,163 - INFO - Waiting for VM ctest-VM11-96522352 to be up.. 2025-08-10 11:24:44,164 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-10 11:24:44,164 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-machine/64ac911a-84df-41c8-89ab-c79cb3c538e2 2025-08-10 11:24:44,170 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-machine-interface/dad1f835-77fb-426e-972c-0e7118653fd6 2025-08-10 11:24:45,359 - 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.43 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.47 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 = 1.470/2.450/3.431/0.980 ms') 2025-08-10 11:24:45,359 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-96522352 passed 2025-08-10 11:24:45,471 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-10 11:24:45,472 - DEBUG - Waiting to SSH to VM ctest-VM11-96522352, IP 84.134.216.3, Port 22 2025-08-10 11:24:45,539 - DEBUG - Error on ssh to ubuntu@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-10 11:24:45,609 - DEBUG - VM ctest-VM11-96522352 is NOT ready for SSH connections, VM status: ACTIVE 2025-08-10 11:24:50,609 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-10 11:24:50,610 - DEBUG - Waiting to SSH to VM ctest-VM11-96522352, IP 84.134.216.3, Port 22 2025-08-10 11:24:50,675 - DEBUG - Error on ssh to ubuntu@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-10 11:24:50,744 - DEBUG - VM ctest-VM11-96522352 is NOT ready for SSH connections, VM status: ACTIVE 2025-08-10 11:24:55,745 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-10 11:24:55,745 - DEBUG - Waiting to SSH to VM ctest-VM11-96522352, IP 84.134.216.3, Port 22 2025-08-10 11:24:55,915 - DEBUG - VM ctest-VM11-96522352 is ready for SSH connections 2025-08-10 11:24:55,915 - INFO - Waiting for VM ctest-VM21-03287090 to be up.. 2025-08-10 11:24:55,915 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-10 11:24:55,916 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-machine/5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 2025-08-10 11:24:55,924 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-machine-interface/d6e46592-ea9d-4a24-8b2d-d3dae0d51ec1 2025-08-10 11:24:57,127 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=2.66 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.16 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 = 2.161/2.409/2.657/0.248 ms') 2025-08-10 11:24:57,127 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-03287090 passed 2025-08-10 11:24:57,235 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-10 11:24:57,235 - DEBUG - Waiting to SSH to VM ctest-VM21-03287090, IP 28.169.193.67, Port 22 2025-08-10 11:24:57,389 - DEBUG - VM ctest-VM21-03287090 is ready for SSH connections 2025-08-10 11:24:57,389 - INFO - Waiting for VM ctest-VM31-41058406 to be up.. 2025-08-10 11:24:57,389 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-10 11:24:57,390 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-machine/c8e1c499-167b-41a1-8452-470be6e06c4c 2025-08-10 11:24:57,399 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-machine-interface/e8a153d5-f2a6-4c54-b075-f85f7e024171 2025-08-10 11:24:58,603 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=2.92 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=2.17 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 2.172/2.545/2.918/0.373 ms') 2025-08-10 11:24:58,603 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-41058406 passed 2025-08-10 11:24:58,720 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-10 11:24:58,720 - DEBUG - Waiting to SSH to VM ctest-VM31-41058406, IP 164.190.15.195, Port 22 2025-08-10 11:24:58,891 - DEBUG - VM ctest-VM31-41058406 is ready for SSH connections 2025-08-10 11:24:58,892 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 28.169.193.67, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.65, gateway password: c0ntrail123 2025-08-10 11:24:58,892 - DEBUG - ping -s 56 -c 3 -W 1 28.169.193.67 2025-08-10 11:25:15,059 - DEBUG - PING 28.169.193.67 (28.169.193.67) 56(84) bytes of data. 64 bytes from 28.169.193.67: icmp_req=1 ttl=63 time=5.91 ms 64 bytes from 28.169.193.67: icmp_req=2 ttl=63 time=0.883 ms 64 bytes from 28.169.193.67: icmp_req=3 ttl=63 time=0.894 ms --- 28.169.193.67 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2004ms rtt min/avg/max/mdev = 0.883/2.565/5.918/2.370 ms 2025-08-10 11:25:15,060 - INFO - Ping to IP 28.169.193.67 from VM ctest-VM11-96522352 passed 2025-08-10 11:25:15,060 - DEBUG - Running remote_cmd, Cmd : ping6 -s 56 -c 3 -W 1 2012:4ac:c77a:8ac8:4a0d:58b1:0:3, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.65, gateway password: c0ntrail123 2025-08-10 11:25:15,060 - DEBUG - ping6 -s 56 -c 3 -W 1 2012:4ac:c77a:8ac8:4a0d:58b1:0:3 2025-08-10 11:25:17,531 - DEBUG - PING 2012:4ac:c77a:8ac8:4a0d:58b1:0:3(2012:4ac:c77a:8ac8:4a0d:58b1:0:3) 56 data bytes 64 bytes from 2012:4ac:c77a:8ac8:4a0d:58b1:0:3: icmp_seq=1 ttl=64 time=7.84 ms 64 bytes from 2012:4ac:c77a:8ac8:4a0d:58b1:0:3: icmp_seq=2 ttl=64 time=0.864 ms 64 bytes from 2012:4ac:c77a:8ac8:4a0d:58b1:0:3: icmp_seq=3 ttl=64 time=0.875 ms --- 2012:4ac:c77a:8ac8:4a0d:58b1:0:3 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2004ms rtt min/avg/max/mdev = 0.864/3.196/7.849/3.290 ms 2025-08-10 11:25:17,531 - INFO - Ping to IP 2012:4ac:c77a:8ac8:4a0d:58b1:0:3 from VM ctest-VM11-96522352 passed 2025-08-10 11:25:17,531 - INFO - Test with src as policy and dst as any PASSED 2025-08-10 11:25:17,531 - INFO - Deleting VM ctest-VM31-41058406 2025-08-10 11:25:17,589 - DEBUG - Verifying in api server 10.0.0.57 2025-08-10 11:25:17,589 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-machine/c8e1c499-167b-41a1-8452-470be6e06c4c 2025-08-10 11:25:17,597 - DEBUG - VM ID c8e1c499-167b-41a1-8452-470be6e06c4c of VM ctest-VM31-41058406 is still found in API Server 2025-08-10 11:25:19,597 - DEBUG - Verifying in api server 10.0.0.57 2025-08-10 11:25:19,597 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-machine/c8e1c499-167b-41a1-8452-470be6e06c4c 2025-08-10 11:25:19,603 - DEBUG - Response Code: 404 2025-08-10 11:25:19,603 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-machine/c8e1c499-167b-41a1-8452-470be6e06c4c 2025-08-10 11:25:19,608 - DEBUG - Response Code: 404 2025-08-10 11:25:19,608 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-machine/c8e1c499-167b-41a1-8452-470be6e06c4c 2025-08-10 11:25:19,613 - DEBUG - Response Code: 404 2025-08-10 11:25:19,613 - INFO - VM ctest-VM31-41058406 is fully removed in API-Server 2025-08-10 11:25:19,759 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found 2025-08-10 11:25:19,800 - DEBUG - Validated that vrouter 10.0.0.65 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN3-56262246 2025-08-10 11:25:19,809 - DEBUG - Validated that vrouter 10.0.0.56 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN3-56262246 2025-08-10 11:25:19,809 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN3-56262246 2025-08-10 11:25:19,810 - INFO - VM ctest-VM31-41058406 is removed in Compute, and routes are removed in all compute nodes 2025-08-10 11:25:20,084 - INFO - Routes for VM ctest-VM31-41058406 is removed in all control-nodes 2025-08-10 11:25:20,084 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3786-1:Compute:contrail-vrouter-agent:0?flat 2025-08-10 11:25:20,122 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3786-1?flat 2025-08-10 11:25:20,155 - DEBUG - VM c8e1c499-167b-41a1-8452-470be6e06c4c is still present in vrouter an-jenkins-deploy-platform-ansible-os-3786-1 uve 2025-08-10 11:25:20,155 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-08-10 11:25:20,155 - DEBUG - interface for vm c8e1c499-167b-41a1-8452-470be6e06c4c deleted from vrouter uve ... 2025-08-10 11:25:20,155 - DEBUG - Deleted interface not in error interface list ... 2025-08-10 11:25:23,156 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3786-1:Compute:contrail-vrouter-agent:0?flat 2025-08-10 11:25:23,195 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3786-1?flat 2025-08-10 11:25:23,229 - DEBUG - VM c8e1c499-167b-41a1-8452-470be6e06c4c is still present in vrouter an-jenkins-deploy-platform-ansible-os-3786-1 uve 2025-08-10 11:25:23,229 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-08-10 11:25:23,229 - DEBUG - interface for vm c8e1c499-167b-41a1-8452-470be6e06c4c deleted from vrouter uve ... 2025-08-10 11:25:23,229 - DEBUG - Deleted interface not in error interface list ... 2025-08-10 11:25:26,230 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3786-1:Compute:contrail-vrouter-agent:0?flat 2025-08-10 11:25:26,270 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3786-1?flat 2025-08-10 11:25:26,305 - DEBUG - VM c8e1c499-167b-41a1-8452-470be6e06c4c is still present in vrouter an-jenkins-deploy-platform-ansible-os-3786-1 uve 2025-08-10 11:25:26,305 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-08-10 11:25:26,305 - DEBUG - interface for vm c8e1c499-167b-41a1-8452-470be6e06c4c deleted from vrouter uve ... 2025-08-10 11:25:26,305 - DEBUG - Deleted interface not in error interface list ... 2025-08-10 11:25:29,306 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3786-1:Compute:contrail-vrouter-agent:0?flat 2025-08-10 11:25:29,345 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3786-1?flat 2025-08-10 11:25:29,378 - DEBUG - VM c8e1c499-167b-41a1-8452-470be6e06c4c is still present in vrouter an-jenkins-deploy-platform-ansible-os-3786-1 uve 2025-08-10 11:25:29,378 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-08-10 11:25:29,378 - DEBUG - interface for vm c8e1c499-167b-41a1-8452-470be6e06c4c deleted from vrouter uve ... 2025-08-10 11:25:29,378 - DEBUG - Deleted interface not in error interface list ... 2025-08-10 11:25:32,379 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3786-1:Compute:contrail-vrouter-agent:0?flat 2025-08-10 11:25:32,427 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3786-1?flat 2025-08-10 11:25:32,461 - DEBUG - VM c8e1c499-167b-41a1-8452-470be6e06c4c is not present in vrouter an-jenkins-deploy-platform-ansible-os-3786-1 uve 2025-08-10 11:25:32,462 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-08-10 11:25:32,462 - DEBUG - interface for vm c8e1c499-167b-41a1-8452-470be6e06c4c deleted from vrouter uve ... 2025-08-10 11:25:32,462 - DEBUG - Deleted interface not in error interface list ... 2025-08-10 11:25:32,462 - DEBUG - Validated that VM c8e1c499-167b-41a1-8452-470be6e06c4c is removed in Vrouter UVE 2025-08-10 11:25:32,462 - DEBUG - Verifying through opserver in 10.0.0.49 2025-08-10 11:25:32,462 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN3-56262246?flat 2025-08-10 11:25:32,480 - DEBUG - c8e1c499-167b-41a1-8452-470be6e06c4c BM not in default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN3-56262246 uve 2025-08-10 11:25:32,480 - DEBUG - Validated that VM c8e1c499-167b-41a1-8452-470be6e06c4c is not present in the VN UVE 2025-08-10 11:25:32,480 - DEBUG - Verifying the c8e1c499-167b-41a1-8452-470be6e06c4c virtual network link through opserver 10.0.0.49 2025-08-10 11:25:32,480 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-machines 2025-08-10 11:25:32,493 - INFO - c8e1c499-167b-41a1-8452-470be6e06c4c vm uve deleted from opserver 2025-08-10 11:25:32,493 - DEBUG - Verifying the c8e1c499-167b-41a1-8452-470be6e06c4c virtual network link through opserver 10.0.0.254 2025-08-10 11:25:32,493 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-machines 2025-08-10 11:25:32,503 - INFO - c8e1c499-167b-41a1-8452-470be6e06c4c vm uve deleted from opserver 2025-08-10 11:25:32,503 - DEBUG - Verifying the c8e1c499-167b-41a1-8452-470be6e06c4c virtual network link through opserver 10.0.0.57 2025-08-10 11:25:32,504 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-machines 2025-08-10 11:25:32,515 - INFO - c8e1c499-167b-41a1-8452-470be6e06c4c vm uve deleted from opserver 2025-08-10 11:25:32,516 - INFO - Validated that VM c8e1c499-167b-41a1-8452-470be6e06c4c is removed from Opserver 2025-08-10 11:25:32,516 - INFO - Deleting VM ctest-VM21-03287090 2025-08-10 11:25:32,586 - DEBUG - Verifying in api server 10.0.0.57 2025-08-10 11:25:32,586 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-machine/5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 2025-08-10 11:25:32,594 - DEBUG - VM ID 5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 of VM ctest-VM21-03287090 is still found in API Server 2025-08-10 11:25:34,595 - DEBUG - Verifying in api server 10.0.0.57 2025-08-10 11:25:34,595 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-machine/5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 2025-08-10 11:25:34,602 - DEBUG - Response Code: 404 2025-08-10 11:25:34,602 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-machine/5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 2025-08-10 11:25:34,607 - DEBUG - Response Code: 404 2025-08-10 11:25:34,607 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-machine/5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 2025-08-10 11:25:34,612 - DEBUG - Response Code: 404 2025-08-10 11:25:34,612 - INFO - VM ctest-VM21-03287090 is fully removed in API-Server 2025-08-10 11:25:34,759 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found 2025-08-10 11:25:34,798 - DEBUG - Validated that vrouter 10.0.0.65 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN2-85407297 2025-08-10 11:25:34,808 - DEBUG - Validated that vrouter 10.0.0.56 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN2-85407297 2025-08-10 11:25:34,808 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN2-85407297 2025-08-10 11:25:34,808 - INFO - VM ctest-VM21-03287090 is removed in Compute, and routes are removed in all compute nodes 2025-08-10 11:25:35,057 - INFO - Routes for VM ctest-VM21-03287090 is removed in all control-nodes 2025-08-10 11:25:35,057 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3786-2:Compute:contrail-vrouter-agent:0?flat 2025-08-10 11:25:35,095 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3786-2?flat 2025-08-10 11:25:35,128 - DEBUG - VM 5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3786-2 uve 2025-08-10 11:25:35,128 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-08-10 11:25:35,128 - DEBUG - interface for vm 5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 deleted from vrouter uve ... 2025-08-10 11:25:35,128 - DEBUG - Deleted interface not in error interface list ... 2025-08-10 11:25:38,129 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3786-2:Compute:contrail-vrouter-agent:0?flat 2025-08-10 11:25:38,168 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3786-2?flat 2025-08-10 11:25:38,202 - DEBUG - VM 5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3786-2 uve 2025-08-10 11:25:38,202 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-08-10 11:25:38,202 - DEBUG - interface for vm 5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 deleted from vrouter uve ... 2025-08-10 11:25:38,202 - DEBUG - Deleted interface not in error interface list ... 2025-08-10 11:25:41,203 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3786-2:Compute:contrail-vrouter-agent:0?flat 2025-08-10 11:25:41,242 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3786-2?flat 2025-08-10 11:25:41,277 - DEBUG - VM 5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3786-2 uve 2025-08-10 11:25:41,277 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-08-10 11:25:41,277 - DEBUG - interface for vm 5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 deleted from vrouter uve ... 2025-08-10 11:25:41,277 - DEBUG - Deleted interface not in error interface list ... 2025-08-10 11:25:44,278 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3786-2:Compute:contrail-vrouter-agent:0?flat 2025-08-10 11:25:44,321 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3786-2?flat 2025-08-10 11:25:44,354 - DEBUG - VM 5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3786-2 uve 2025-08-10 11:25:44,354 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-08-10 11:25:44,354 - DEBUG - interface for vm 5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 deleted from vrouter uve ... 2025-08-10 11:25:44,354 - DEBUG - Deleted interface not in error interface list ... 2025-08-10 11:25:47,354 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3786-2:Compute:contrail-vrouter-agent:0?flat 2025-08-10 11:25:47,393 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3786-2?flat 2025-08-10 11:25:47,428 - DEBUG - VM 5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3786-2 uve 2025-08-10 11:25:47,428 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-08-10 11:25:47,428 - DEBUG - interface for vm 5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 deleted from vrouter uve ... 2025-08-10 11:25:47,428 - DEBUG - Deleted interface not in error interface list ... 2025-08-10 11:25:50,429 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3786-2:Compute:contrail-vrouter-agent:0?flat 2025-08-10 11:25:50,471 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3786-2?flat 2025-08-10 11:25:50,505 - DEBUG - VM 5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3786-2 uve 2025-08-10 11:25:50,505 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-08-10 11:25:50,505 - DEBUG - interface for vm 5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 deleted from vrouter uve ... 2025-08-10 11:25:50,505 - DEBUG - Deleted interface not in error interface list ... 2025-08-10 11:25:53,506 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3786-2:Compute:contrail-vrouter-agent:0?flat 2025-08-10 11:25:53,545 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3786-2?flat 2025-08-10 11:25:53,578 - DEBUG - VM 5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3786-2 uve 2025-08-10 11:25:53,579 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-08-10 11:25:53,579 - DEBUG - interface for vm 5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 deleted from vrouter uve ... 2025-08-10 11:25:53,579 - DEBUG - Deleted interface not in error interface list ... 2025-08-10 11:25:56,579 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3786-2:Compute:contrail-vrouter-agent:0?flat 2025-08-10 11:25:56,623 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3786-2?flat 2025-08-10 11:25:56,657 - DEBUG - VM 5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3786-2 uve 2025-08-10 11:25:56,657 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-08-10 11:25:56,657 - DEBUG - interface for vm 5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 deleted from vrouter uve ... 2025-08-10 11:25:56,657 - DEBUG - Deleted interface not in error interface list ... 2025-08-10 11:25:59,657 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3786-2:Compute:contrail-vrouter-agent:0?flat 2025-08-10 11:25:59,715 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3786-2?flat 2025-08-10 11:25:59,757 - DEBUG - VM 5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3786-2 uve 2025-08-10 11:25:59,757 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-08-10 11:25:59,757 - DEBUG - interface for vm 5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 deleted from vrouter uve ... 2025-08-10 11:25:59,757 - DEBUG - Deleted interface not in error interface list ... 2025-08-10 11:26:02,758 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3786-2:Compute:contrail-vrouter-agent:0?flat 2025-08-10 11:26:02,798 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3786-2?flat 2025-08-10 11:26:02,831 - DEBUG - VM 5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 is not present in vrouter an-jenkins-deploy-platform-ansible-os-3786-2 uve 2025-08-10 11:26:02,831 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-08-10 11:26:02,831 - DEBUG - interface for vm 5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 deleted from vrouter uve ... 2025-08-10 11:26:02,831 - DEBUG - Deleted interface not in error interface list ... 2025-08-10 11:26:02,831 - DEBUG - Validated that VM 5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 is removed in Vrouter UVE 2025-08-10 11:26:02,831 - DEBUG - Verifying through opserver in 10.0.0.49 2025-08-10 11:26:02,831 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN2-85407297?flat 2025-08-10 11:26:02,848 - DEBUG - 5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 BM not in default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN2-85407297 uve 2025-08-10 11:26:02,848 - DEBUG - Validated that VM 5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 is not present in the VN UVE 2025-08-10 11:26:02,848 - DEBUG - Verifying the 5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 virtual network link through opserver 10.0.0.49 2025-08-10 11:26:02,848 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-machines 2025-08-10 11:26:02,858 - INFO - 5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 vm uve deleted from opserver 2025-08-10 11:26:02,858 - DEBUG - Verifying the 5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 virtual network link through opserver 10.0.0.254 2025-08-10 11:26:02,858 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-machines 2025-08-10 11:26:02,868 - INFO - 5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 vm uve deleted from opserver 2025-08-10 11:26:02,868 - DEBUG - Verifying the 5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 virtual network link through opserver 10.0.0.57 2025-08-10 11:26:02,868 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-machines 2025-08-10 11:26:02,878 - INFO - 5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 vm uve deleted from opserver 2025-08-10 11:26:02,878 - INFO - Validated that VM 5dfa7979-baa4-44ef-9aec-2f7e6c18bfd2 is removed from Opserver 2025-08-10 11:26:02,878 - INFO - Deleting VM ctest-VM11-96522352 2025-08-10 11:26:02,935 - DEBUG - Verifying in api server 10.0.0.57 2025-08-10 11:26:02,936 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-machine/64ac911a-84df-41c8-89ab-c79cb3c538e2 2025-08-10 11:26:02,943 - DEBUG - VM ID 64ac911a-84df-41c8-89ab-c79cb3c538e2 of VM ctest-VM11-96522352 is still found in API Server 2025-08-10 11:26:04,943 - DEBUG - Verifying in api server 10.0.0.57 2025-08-10 11:26:04,943 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-machine/64ac911a-84df-41c8-89ab-c79cb3c538e2 2025-08-10 11:26:04,950 - DEBUG - Response Code: 404 2025-08-10 11:26:04,950 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-machine/64ac911a-84df-41c8-89ab-c79cb3c538e2 2025-08-10 11:26:04,955 - DEBUG - Response Code: 404 2025-08-10 11:26:04,955 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-machine/64ac911a-84df-41c8-89ab-c79cb3c538e2 2025-08-10 11:26:04,960 - DEBUG - Response Code: 404 2025-08-10 11:26:04,960 - INFO - VM ctest-VM11-96522352 is fully removed in API-Server 2025-08-10 11:26:05,111 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found 2025-08-10 11:26:05,153 - DEBUG - Validated that vrouter 10.0.0.65 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402 2025-08-10 11:26:05,164 - DEBUG - Validated that vrouter 10.0.0.56 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402 2025-08-10 11:26:05,164 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402 2025-08-10 11:26:05,164 - INFO - VM ctest-VM11-96522352 is removed in Compute, and routes are removed in all compute nodes 2025-08-10 11:26:05,393 - INFO - Routes for VM ctest-VM11-96522352 is removed in all control-nodes 2025-08-10 11:26:05,393 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3786-1:Compute:contrail-vrouter-agent:0?flat 2025-08-10 11:26:05,436 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3786-1?flat 2025-08-10 11:26:05,469 - DEBUG - VM 64ac911a-84df-41c8-89ab-c79cb3c538e2 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3786-1 uve 2025-08-10 11:26:05,469 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-08-10 11:26:05,470 - DEBUG - interface for vm 64ac911a-84df-41c8-89ab-c79cb3c538e2 deleted from vrouter uve ... 2025-08-10 11:26:05,470 - DEBUG - Deleted interface not in error interface list ... 2025-08-10 11:26:08,470 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3786-1:Compute:contrail-vrouter-agent:0?flat 2025-08-10 11:26:08,510 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3786-1?flat 2025-08-10 11:26:08,543 - DEBUG - VM 64ac911a-84df-41c8-89ab-c79cb3c538e2 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3786-1 uve 2025-08-10 11:26:08,543 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-08-10 11:26:08,543 - DEBUG - interface for vm 64ac911a-84df-41c8-89ab-c79cb3c538e2 deleted from vrouter uve ... 2025-08-10 11:26:08,543 - DEBUG - Deleted interface not in error interface list ... 2025-08-10 11:26:11,544 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3786-1:Compute:contrail-vrouter-agent:0?flat 2025-08-10 11:26:11,582 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3786-1?flat 2025-08-10 11:26:11,617 - DEBUG - VM 64ac911a-84df-41c8-89ab-c79cb3c538e2 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3786-1 uve 2025-08-10 11:26:11,617 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-08-10 11:26:11,617 - DEBUG - interface for vm 64ac911a-84df-41c8-89ab-c79cb3c538e2 deleted from vrouter uve ... 2025-08-10 11:26:11,617 - DEBUG - Deleted interface not in error interface list ... 2025-08-10 11:26:14,618 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3786-1:Compute:contrail-vrouter-agent:0?flat 2025-08-10 11:26:14,657 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3786-1?flat 2025-08-10 11:26:14,694 - DEBUG - VM 64ac911a-84df-41c8-89ab-c79cb3c538e2 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3786-1 uve 2025-08-10 11:26:14,694 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-08-10 11:26:14,694 - DEBUG - interface for vm 64ac911a-84df-41c8-89ab-c79cb3c538e2 deleted from vrouter uve ... 2025-08-10 11:26:14,694 - DEBUG - Deleted interface not in error interface list ... 2025-08-10 11:26:17,694 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3786-1:Compute:contrail-vrouter-agent:0?flat 2025-08-10 11:26:17,733 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3786-1?flat 2025-08-10 11:26:17,766 - DEBUG - VM 64ac911a-84df-41c8-89ab-c79cb3c538e2 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3786-1 uve 2025-08-10 11:26:17,766 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-08-10 11:26:17,766 - DEBUG - interface for vm 64ac911a-84df-41c8-89ab-c79cb3c538e2 deleted from vrouter uve ... 2025-08-10 11:26:17,766 - DEBUG - Deleted interface not in error interface list ... 2025-08-10 11:26:20,767 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3786-1:Compute:contrail-vrouter-agent:0?flat 2025-08-10 11:26:20,806 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3786-1?flat 2025-08-10 11:26:20,840 - DEBUG - VM 64ac911a-84df-41c8-89ab-c79cb3c538e2 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3786-1 uve 2025-08-10 11:26:20,840 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-08-10 11:26:20,840 - DEBUG - interface for vm 64ac911a-84df-41c8-89ab-c79cb3c538e2 deleted from vrouter uve ... 2025-08-10 11:26:20,841 - DEBUG - Deleted interface not in error interface list ... 2025-08-10 11:26:23,842 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3786-1:Compute:contrail-vrouter-agent:0?flat 2025-08-10 11:26:23,881 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3786-1?flat 2025-08-10 11:26:23,918 - DEBUG - VM 64ac911a-84df-41c8-89ab-c79cb3c538e2 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3786-1 uve 2025-08-10 11:26:23,918 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-08-10 11:26:23,918 - DEBUG - interface for vm 64ac911a-84df-41c8-89ab-c79cb3c538e2 deleted from vrouter uve ... 2025-08-10 11:26:23,918 - DEBUG - Deleted interface not in error interface list ... 2025-08-10 11:26:26,919 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3786-1:Compute:contrail-vrouter-agent:0?flat 2025-08-10 11:26:26,959 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3786-1?flat 2025-08-10 11:26:26,993 - DEBUG - VM 64ac911a-84df-41c8-89ab-c79cb3c538e2 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3786-1 uve 2025-08-10 11:26:26,993 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-08-10 11:26:26,993 - DEBUG - interface for vm 64ac911a-84df-41c8-89ab-c79cb3c538e2 deleted from vrouter uve ... 2025-08-10 11:26:26,993 - DEBUG - Deleted interface not in error interface list ... 2025-08-10 11:26:29,994 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3786-1:Compute:contrail-vrouter-agent:0?flat 2025-08-10 11:26:30,033 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3786-1?flat 2025-08-10 11:26:30,067 - DEBUG - VM 64ac911a-84df-41c8-89ab-c79cb3c538e2 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3786-1 uve 2025-08-10 11:26:30,067 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-08-10 11:26:30,067 - DEBUG - interface for vm 64ac911a-84df-41c8-89ab-c79cb3c538e2 deleted from vrouter uve ... 2025-08-10 11:26:30,067 - DEBUG - Deleted interface not in error interface list ... 2025-08-10 11:26:33,068 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3786-1:Compute:contrail-vrouter-agent:0?flat 2025-08-10 11:26:33,106 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3786-1?flat 2025-08-10 11:26:33,140 - DEBUG - VM 64ac911a-84df-41c8-89ab-c79cb3c538e2 is not present in vrouter an-jenkins-deploy-platform-ansible-os-3786-1 uve 2025-08-10 11:26:33,140 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-08-10 11:26:33,140 - DEBUG - interface for vm 64ac911a-84df-41c8-89ab-c79cb3c538e2 deleted from vrouter uve ... 2025-08-10 11:26:33,140 - DEBUG - Deleted interface not in error interface list ... 2025-08-10 11:26:33,140 - DEBUG - Validated that VM 64ac911a-84df-41c8-89ab-c79cb3c538e2 is removed in Vrouter UVE 2025-08-10 11:26:33,140 - DEBUG - Verifying through opserver in 10.0.0.49 2025-08-10 11:26:33,140 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402?flat 2025-08-10 11:26:33,156 - DEBUG - 64ac911a-84df-41c8-89ab-c79cb3c538e2 BM not in default-domain:ctest-TestPolicyAclIpv4v6-10181764:ctest-VN1-03874402 uve 2025-08-10 11:26:33,156 - DEBUG - Validated that VM 64ac911a-84df-41c8-89ab-c79cb3c538e2 is not present in the VN UVE 2025-08-10 11:26:33,156 - DEBUG - Verifying the 64ac911a-84df-41c8-89ab-c79cb3c538e2 virtual network link through opserver 10.0.0.49 2025-08-10 11:26:33,156 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-machines 2025-08-10 11:26:33,166 - DEBUG - No links retuned for 64ac911a-84df-41c8-89ab-c79cb3c538e2 2025-08-10 11:26:33,166 - INFO - 64ac911a-84df-41c8-89ab-c79cb3c538e2 vm uve deleted from opserver 2025-08-10 11:26:33,166 - DEBUG - Verifying the 64ac911a-84df-41c8-89ab-c79cb3c538e2 virtual network link through opserver 10.0.0.254 2025-08-10 11:26:33,166 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-machines 2025-08-10 11:26:33,175 - DEBUG - No links retuned for 64ac911a-84df-41c8-89ab-c79cb3c538e2 2025-08-10 11:26:33,175 - INFO - 64ac911a-84df-41c8-89ab-c79cb3c538e2 vm uve deleted from opserver 2025-08-10 11:26:33,175 - DEBUG - Verifying the 64ac911a-84df-41c8-89ab-c79cb3c538e2 virtual network link through opserver 10.0.0.57 2025-08-10 11:26:33,175 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-machines 2025-08-10 11:26:33,187 - DEBUG - No links retuned for 64ac911a-84df-41c8-89ab-c79cb3c538e2 2025-08-10 11:26:33,187 - INFO - 64ac911a-84df-41c8-89ab-c79cb3c538e2 vm uve deleted from opserver 2025-08-10 11:26:33,187 - INFO - Validated that VM 64ac911a-84df-41c8-89ab-c79cb3c538e2 is removed from Opserver 2025-08-10 11:26:33,187 - DEBUG - Detaching the Policy for VN :ctest-VN2-85407297 2025-08-10 11:26:33,187 - DEBUG - Requesting: http://10.0.0.49:8082/domains 2025-08-10 11:26:33,192 - DEBUG - Requesting: http://10.0.0.49:8082/domain/506b9682-8eb9-488e-ab69-24d332f4d228 2025-08-10 11:26:33,222 - DEBUG - Requesting: http://10.0.0.49:8082/project/40762832-e00c-4f09-8da5-c16b2522c8cf 2025-08-10 11:26:33,312 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/f855bad3-59dc-4255-9e4e-b8e40654a514 2025-08-10 11:26:33,324 - DEBUG - Requesting: http://10.0.0.49:8082/network-policy/3f7fa739-b9b9-435f-9c45-879a09f90e81 2025-08-10 11:26:33,510 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-10181764', 'ctest-policy21-23656219']] from ctest-VN2-85407297 2025-08-10 11:26:33,510 - DEBUG - Detaching the Policy for VN :ctest-VN1-03874402 2025-08-10 11:26:33,510 - DEBUG - Requesting: http://10.0.0.49:8082/domains 2025-08-10 11:26:33,516 - DEBUG - Requesting: http://10.0.0.49:8082/domain/506b9682-8eb9-488e-ab69-24d332f4d228 2025-08-10 11:26:33,527 - DEBUG - Requesting: http://10.0.0.49:8082/project/40762832-e00c-4f09-8da5-c16b2522c8cf 2025-08-10 11:26:33,612 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-network/618a4142-6b96-4427-9086-78caf70c37c0 2025-08-10 11:26:33,628 - DEBUG - Requesting: http://10.0.0.49:8082/network-policy/dff0f510-ccd2-484b-b4e0-8f00b8ed6dea 2025-08-10 11:26:33,637 - DEBUG - Requesting: http://10.0.0.49:8082/network-policy/bb7e7d25-0056-4d84-bca8-573c49d6a374 2025-08-10 11:26:33,787 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-10181764', 'ctest-policy12-69623423'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-10181764', 'ctest-policy13-34388548']] from ctest-VN1-03874402 2025-08-10 11:26:33,822 - INFO - Deleted policy ctest-policy21-23656219 2025-08-10 11:26:33,863 - INFO - Deleted policy ctest-policy12-69623423 2025-08-10 11:26:33,906 - INFO - Deleted policy ctest-policy13-34388548 2025-08-10 11:26:33,906 - INFO - Deleting VN ctest-VN3-56262246 2025-08-10 11:26:34,138 - DEBUG - Response for deleting network () 2025-08-10 11:26:34,138 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/0f19852a-5305-40cc-8ff6-8c463334e360 2025-08-10 11:26:34,147 - DEBUG - Response Code: 404 2025-08-10 11:26:34,147 - DEBUG - Requesting: http://10.0.0.49:8082/domains 2025-08-10 11:26:34,153 - DEBUG - Requesting: http://10.0.0.49:8082/domain/506b9682-8eb9-488e-ab69-24d332f4d228 2025-08-10 11:26:34,165 - DEBUG - Requesting: http://10.0.0.49:8082/project/40762832-e00c-4f09-8da5-c16b2522c8cf 2025-08-10 11:26:34,259 - INFO - Validated that VN ctest-VN3-56262246 is not found in API Server 2025-08-10 11:26:34,271 - DEBUG - VN ctest-VN3-56262246 is not present in Agent 10.0.0.65 2025-08-10 11:26:34,281 - DEBUG - VN ctest-VN3-56262246 is not present in Agent 10.0.0.56 2025-08-10 11:26:34,281 - INFO - Validated that VN ctest-VN3-56262246 is not in any agent 2025-08-10 11:26:34,309 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN3-56262246 info 2025-08-10 11:26:34,309 - INFO - Deleting VN ctest-VN2-85407297 2025-08-10 11:26:34,450 - DEBUG - Response for deleting network () 2025-08-10 11:26:34,450 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/466b2464-d506-40d7-bab8-19036179d750 2025-08-10 11:26:34,459 - DEBUG - Response Code: 404 2025-08-10 11:26:34,460 - DEBUG - Requesting: http://10.0.0.49:8082/domains 2025-08-10 11:26:34,470 - DEBUG - Requesting: http://10.0.0.49:8082/domain/506b9682-8eb9-488e-ab69-24d332f4d228 2025-08-10 11:26:34,483 - DEBUG - Requesting: http://10.0.0.49:8082/project/40762832-e00c-4f09-8da5-c16b2522c8cf 2025-08-10 11:26:34,564 - INFO - Validated that VN ctest-VN2-85407297 is not found in API Server 2025-08-10 11:26:34,576 - DEBUG - VN ctest-VN2-85407297 is not present in Agent 10.0.0.65 2025-08-10 11:26:34,585 - DEBUG - VN ctest-VN2-85407297 is not present in Agent 10.0.0.56 2025-08-10 11:26:34,585 - INFO - Validated that VN ctest-VN2-85407297 is not in any agent 2025-08-10 11:26:34,617 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN2-85407297 info 2025-08-10 11:26:34,617 - INFO - Deleting VN ctest-VN1-03874402 2025-08-10 11:26:34,754 - DEBUG - Response for deleting network () 2025-08-10 11:26:34,754 - DEBUG - Requesting: http://10.0.0.49:8082/routing-instance/6a7e126e-a054-4930-aece-18b413bdf7ad 2025-08-10 11:26:34,764 - DEBUG - Response Code: 404 2025-08-10 11:26:34,764 - DEBUG - Requesting: http://10.0.0.49:8082/domains 2025-08-10 11:26:34,813 - DEBUG - Requesting: http://10.0.0.49:8082/domain/506b9682-8eb9-488e-ab69-24d332f4d228 2025-08-10 11:26:34,824 - DEBUG - Requesting: http://10.0.0.49:8082/project/40762832-e00c-4f09-8da5-c16b2522c8cf 2025-08-10 11:26:34,911 - INFO - Validated that VN ctest-VN1-03874402 is not found in API Server 2025-08-10 11:26:34,922 - DEBUG - VN ctest-VN1-03874402 is not present in Agent 10.0.0.65 2025-08-10 11:26:34,932 - DEBUG - VN ctest-VN1-03874402 is not present in Agent 10.0.0.56 2025-08-10 11:26:34,932 - INFO - Validated that VN ctest-VN1-03874402 is not in any agent 2025-08-10 11:26:34,962 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN1-03874402 info 2025-08-10 11:26:35,044 - INFO - IPAM: ipam3 is not found in API Server 2025-08-10 11:26:35,063 - INFO - IPAM:ipam3 is not found in control node 2025-08-10 11:26:35,192 - INFO - IPAM: ipam2 is not found in API Server 2025-08-10 11:26:35,212 - INFO - IPAM:ipam2 is not found in control node 2025-08-10 11:26:35,336 - INFO - IPAM: ipam1 is not found in API Server 2025-08-10 11:26:35,355 - INFO - IPAM:ipam1 is not found in control node 2025-08-10 11:26:36,613 - DEBUG - No XMPP flaps were noticed during the test

2025-08-10 11:26:36,613 - INFO - END TEST : test_policy_inheritance_src_pol_dst_any : PASSED[0:02:56]

2025-08-10 11:26:36,613 - INFO - -------------------------------------------------------------------------------- 2025-08-10 11:26:37,869 - DEBUG - No XMPP flaps were noticed during the test

2025-08-10 11:26:37,869 - INFO - END TEST : test_policy_inheritance_src_pol_dst_any : PASSED[0:02:58]

2025-08-10 11:26:37,869 - INFO - -------------------------------------------------------------------------------- 2025-08-10 11:26:38,544 - INFO - Deleted project: ctest-TestPolicyAclIpv4v6-10181764, ID : 40762832-e00c-4f09-8da5-c16b2522c8cf