2025-10-31 15:16:09,052 - INFO - Domain Default found not creating 2025-10-31 15:16:09,193 - INFO - Project ctest-TestPolicyAclIpv4v6-76625063 not found, creating it 2025-10-31 15:16:09,739 - INFO - Created Project:ctest-TestPolicyAclIpv4v6-76625063, ID : c7507b1f-4309-4180-9b1d-6c0668cbf321 2025-10-31 15:16:11,165 - INFO - ================================================================================ 2025-10-31 15:16:11,165 - INFO - STARTING TEST : test_policy_inheritance_src_pol_dst_any 2025-10-31 15:16:12,459 - DEBUG - Nothing to compare xmpp stats {'10.0.0.19': {'10.20.0.25': '0', '10.20.0.14': '0'}, '10.0.0.22': {'10.20.0.25': '0', '10.20.0.193': '0'}} with 2025-10-31 15:16:12,459 - INFO - Initial checks done. Running the testcase now 2025-10-31 15:16:12,460 - INFO - 2025-10-31 15:16:12,460 - INFO - ================================================================================ 2025-10-31 15:16:12,460 - INFO - STARTING TEST : test_policy_inheritance_src_pol_dst_any 2025-10-31 15:16:12,460 - INFO - TEST DESCRIPTION : Test cases to test policy inheritance 2025-10-31 15:16:13,751 - DEBUG - Nothing to compare xmpp stats {'10.0.0.19': {'10.20.0.25': '0', '10.20.0.14': '0'}, '10.0.0.22': {'10.20.0.25': '0', '10.20.0.193': '0'}} with 2025-10-31 15:16:13,751 - INFO - Initial checks done. Running the testcase now 2025-10-31 15:16:13,751 - INFO - 2025-10-31 15:16:15,071 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-10-31 15:16:15,365 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-10-31 15:16:15,393 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69 2025-10-31 15:16:15,408 - DEBUG - Requesting: http://10.0.0.38:8082/project/c7507b1f-4309-4180-9b1d-6c0668cbf321 2025-10-31 15:16:15,510 - DEBUG - Requesting: http://10.0.0.38:8082/network-ipam/256c6cbe-a2f1-4fe5-9860-b5a0f5afe5b1 2025-10-31 15:16:15,517 - INFO - Verifications in API Server for IPAM: ipam1 passed 2025-10-31 15:16:15,522 - DEBUG - Control-node 10.0.0.38 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-76625063:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-76625063', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2696649842921394149', 'uuid-lslong': '10979975594450609585'}, 'enable': 'true', 'created': '2025-10-31T15:16:14', 'last-modified': '2025-10-31T15:16:14', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.173284'} 2025-10-31 15:16:15,528 - DEBUG - Control-node 10.0.0.249 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-76625063:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-76625063', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2696649842921394149', 'uuid-lslong': '10979975594450609585'}, 'enable': 'true', 'created': '2025-10-31T15:16:14', 'last-modified': '2025-10-31T15:16:14', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.176122'} 2025-10-31 15:16:15,535 - DEBUG - Control-node 10.0.0.65 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-76625063:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-76625063', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2696649842921394149', 'uuid-lslong': '10979975594450609585'}, 'enable': 'true', 'created': '2025-10-31T15:16:14', 'last-modified': '2025-10-31T15:16:14', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.191519'} 2025-10-31 15:16:15,535 - INFO - Verifications in Control node for IPAM: ipam1 passed 2025-10-31 15:16:15,535 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-10-31 15:16:15,543 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69 2025-10-31 15:16:15,553 - DEBUG - Requesting: http://10.0.0.38:8082/project/c7507b1f-4309-4180-9b1d-6c0668cbf321 2025-10-31 15:16:15,674 - DEBUG - Requesting: http://10.0.0.38:8082/network-ipam/8fed7380-7449-47d6-a2e7-8e2a395ccb22 2025-10-31 15:16:15,682 - INFO - Verifications in API Server for IPAM: ipam2 passed 2025-10-31 15:16:15,688 - DEBUG - Control-node 10.0.0.38 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-76625063:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-76625063', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10371072512448088022', 'uuid-lslong': '11738507265766640418'}, 'enable': 'true', 'created': '2025-10-31T15:16:14', 'last-modified': '2025-10-31T15:16:14', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.000983'} 2025-10-31 15:16:15,696 - DEBUG - Control-node 10.0.0.249 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-76625063:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-76625063', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10371072512448088022', 'uuid-lslong': '11738507265766640418'}, 'enable': 'true', 'created': '2025-10-31T15:16:14', 'last-modified': '2025-10-31T15:16:14', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.006925'} 2025-10-31 15:16:15,701 - DEBUG - Control-node 10.0.0.65 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-76625063:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-76625063', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10371072512448088022', 'uuid-lslong': '11738507265766640418'}, 'enable': 'true', 'created': '2025-10-31T15:16:14', 'last-modified': '2025-10-31T15:16:14', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.015346'} 2025-10-31 15:16:15,701 - INFO - Verifications in Control node for IPAM: ipam2 passed 2025-10-31 15:16:15,701 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-10-31 15:16:15,706 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69 2025-10-31 15:16:15,719 - DEBUG - Requesting: http://10.0.0.38:8082/project/c7507b1f-4309-4180-9b1d-6c0668cbf321 2025-10-31 15:16:15,810 - DEBUG - Requesting: http://10.0.0.38:8082/network-ipam/378cb34f-0286-4587-8ce0-64784e4c456e 2025-10-31 15:16:15,819 - INFO - Verifications in API Server for IPAM: ipam3 passed 2025-10-31 15:16:15,826 - DEBUG - Control-node 10.0.0.38 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-76625063:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-76625063', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4002771320751670663', 'uuid-lslong': '10151224027965572462'}, 'enable': 'true', 'created': '2025-10-31T15:16:14', 'last-modified': '2025-10-31T15:16:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.788592'} 2025-10-31 15:16:15,831 - DEBUG - Control-node 10.0.0.249 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-76625063:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-76625063', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4002771320751670663', 'uuid-lslong': '10151224027965572462'}, 'enable': 'true', 'created': '2025-10-31T15:16:14', 'last-modified': '2025-10-31T15:16:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.796393'} 2025-10-31 15:16:15,836 - DEBUG - Control-node 10.0.0.65 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-76625063:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-76625063', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4002771320751670663', 'uuid-lslong': '10151224027965572462'}, 'enable': 'true', 'created': '2025-10-31T15:16:14', 'last-modified': '2025-10-31T15:16:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.796550'} 2025-10-31 15:16:15,836 - INFO - Verifications in Control node for IPAM: ipam3 passed 2025-10-31 15:16:16,184 - DEBUG - Response for create_network : {'network': {'id': '0f0f7495-035b-4110-8464-0d4f9caad6a8', 'name': 'ctest-VN1-67879207', 'tenant_id': 'c7507b1f430941809b1d6c0668cbf321', 'project_id': 'c7507b1f430941809b1d6c0668cbf321', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-76625063', 'ctest-VN1-67879207'], 'port_security_enabled': True, 'description': ''}} 2025-10-31 15:16:16,486 - DEBUG - Response for create_subnet : {'subnet': {'id': '706924f6-b7b3-4ce4-a373-8e44382697c1', 'name': '', 'tenant_id': 'c7507b1f430941809b1d6c0668cbf321', 'network_id': '0f0f7495-035b-4110-8464-0d4f9caad6a8', 'ip_version': 4, 'cidr': '222.153.98.128/26', 'allocation_pools': [{'start': '222.153.98.130', 'end': '222.153.98.190'}], 'gateway_ip': '222.153.98.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '222.153.98.130', 'tags': [], 'project_id': 'c7507b1f430941809b1d6c0668cbf321'}} 2025-10-31 15:16:16,709 - DEBUG - Response for create_subnet : {'subnet': {'id': '6d1d03f0-1e76-4cce-8aa4-d902658ac939', 'name': '', 'tenant_id': 'c7507b1f430941809b1d6c0668cbf321', 'network_id': '0f0f7495-035b-4110-8464-0d4f9caad6a8', 'ip_version': 6, 'cidr': '22e8:9812:b7d8:da18:974b:6c8e::/96', 'allocation_pools': [{'start': '22e8:9812:b7d8:da18:974b:6c8e:0:2', 'end': '22e8:9812:b7d8:da18:974b:6c8e:ffff:fffe'}], 'gateway_ip': '22e8:9812:b7d8:da18:974b:6c8e:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '22e8:9812:b7d8:da18:974b:6c8e:0:2', 'tags': [], 'project_id': 'c7507b1f430941809b1d6c0668cbf321'}} 2025-10-31 15:16:16,742 - INFO - Created VN ctest-VN1-67879207 2025-10-31 15:16:16,755 - DEBUG - VN ctest-VN1-67879207 UUID is 0f0f7495-035b-4110-8464-0d4f9caad6a8 2025-10-31 15:16:16,939 - DEBUG - Fetched VN: default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207(0f0f7495-035b-4110-8464-0d4f9caad6a8) with subnets ['222.153.98.128/26', '22e8:9812:b7d8:da18:974b:6c8e::/96'] 2025-10-31 15:16:17,264 - DEBUG - Response for create_network : {'network': {'id': '01aa3232-f541-4ea1-82fe-baeba5b9b56a', 'name': 'ctest-VN2-17371718', 'tenant_id': 'c7507b1f430941809b1d6c0668cbf321', 'project_id': 'c7507b1f430941809b1d6c0668cbf321', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-76625063', 'ctest-VN2-17371718'], 'port_security_enabled': True, 'description': ''}} 2025-10-31 15:16:17,516 - DEBUG - Response for create_subnet : {'subnet': {'id': '47523288-f1db-4dc1-b671-8318f2839a90', 'name': '', 'tenant_id': 'c7507b1f430941809b1d6c0668cbf321', 'network_id': '01aa3232-f541-4ea1-82fe-baeba5b9b56a', 'ip_version': 4, 'cidr': '105.164.1.64/26', 'allocation_pools': [{'start': '105.164.1.66', 'end': '105.164.1.126'}], 'gateway_ip': '105.164.1.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '105.164.1.66', 'tags': [], 'project_id': 'c7507b1f430941809b1d6c0668cbf321'}} 2025-10-31 15:16:17,781 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c9fdec9f-d5ad-4bc3-bea0-f6548e4a744d', 'name': '', 'tenant_id': 'c7507b1f430941809b1d6c0668cbf321', 'network_id': '01aa3232-f541-4ea1-82fe-baeba5b9b56a', 'ip_version': 6, 'cidr': '30c4:b2aa:94a1:2bdf:835a:6d38::/96', 'allocation_pools': [{'start': '30c4:b2aa:94a1:2bdf:835a:6d38:0:2', 'end': '30c4:b2aa:94a1:2bdf:835a:6d38:ffff:fffe'}], 'gateway_ip': '30c4:b2aa:94a1:2bdf:835a:6d38:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '30c4:b2aa:94a1:2bdf:835a:6d38:0:2', 'tags': [], 'project_id': 'c7507b1f430941809b1d6c0668cbf321'}} 2025-10-31 15:16:17,812 - INFO - Created VN ctest-VN2-17371718 2025-10-31 15:16:17,825 - DEBUG - VN ctest-VN2-17371718 UUID is 01aa3232-f541-4ea1-82fe-baeba5b9b56a 2025-10-31 15:16:18,252 - DEBUG - Response for create_network : {'network': {'id': 'c0ad0d4f-553f-4f24-b947-c1a3142e5ff2', 'name': 'ctest-VN3-65695210', 'tenant_id': 'c7507b1f430941809b1d6c0668cbf321', 'project_id': 'c7507b1f430941809b1d6c0668cbf321', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-76625063', 'ctest-VN3-65695210'], 'port_security_enabled': True, 'description': ''}} 2025-10-31 15:16:18,508 - DEBUG - Response for create_subnet : {'subnet': {'id': '26b7e8a4-5aa7-4617-af54-4fae5498d9f9', 'name': '', 'tenant_id': 'c7507b1f430941809b1d6c0668cbf321', 'network_id': 'c0ad0d4f-553f-4f24-b947-c1a3142e5ff2', 'ip_version': 4, 'cidr': '104.70.19.192/26', 'allocation_pools': [{'start': '104.70.19.194', 'end': '104.70.19.254'}], 'gateway_ip': '104.70.19.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '104.70.19.194', 'tags': [], 'project_id': 'c7507b1f430941809b1d6c0668cbf321'}} 2025-10-31 15:16:18,704 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ce33395c-1073-43e1-957c-bdfe9638d991', 'name': '', 'tenant_id': 'c7507b1f430941809b1d6c0668cbf321', 'network_id': 'c0ad0d4f-553f-4f24-b947-c1a3142e5ff2', 'ip_version': 6, 'cidr': '3526:d21f:d3eb:9e5c:9282:36ae::/96', 'allocation_pools': [{'start': '3526:d21f:d3eb:9e5c:9282:36ae:0:2', 'end': '3526:d21f:d3eb:9e5c:9282:36ae:ffff:fffe'}], 'gateway_ip': '3526:d21f:d3eb:9e5c:9282:36ae:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '3526:d21f:d3eb:9e5c:9282:36ae:0:2', 'tags': [], 'project_id': 'c7507b1f430941809b1d6c0668cbf321'}} 2025-10-31 15:16:18,737 - INFO - Created VN ctest-VN3-65695210 2025-10-31 15:16:18,750 - DEBUG - VN ctest-VN3-65695210 UUID is c0ad0d4f-553f-4f24-b947-c1a3142e5ff2 2025-10-31 15:16:18,850 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-10-31 15:16:18,855 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69 2025-10-31 15:16:18,865 - DEBUG - Requesting: http://10.0.0.38:8082/project/c7507b1f-4309-4180-9b1d-6c0668cbf321 2025-10-31 15:16:18,954 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/0f0f7495-035b-4110-8464-0d4f9caad6a8 2025-10-31 15:16:18,968 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/0f0f7495-035b-4110-8464-0d4f9caad6a8 2025-10-31 15:16:18,978 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/ff9d01f5-ebf9-423f-b8fb-69eb648c3212 2025-10-31 15:16:18,985 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/ff9d01f5-ebf9-423f-b8fb-69eb648c3212 2025-10-31 15:16:18,993 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/f97b3e1d-fb0f-4be6-9415-02fd0837b9cb 2025-10-31 15:16:19,000 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-10-31 15:16:19,000 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/0f0f7495-035b-4110-8464-0d4f9caad6a8 2025-10-31 15:16:19,010 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/ff9d01f5-ebf9-423f-b8fb-69eb648c3212 2025-10-31 15:16:19,032 - INFO - Verified VN network id 15 for VN 0f0f7495-035b-4110-8464-0d4f9caad6a8 2025-10-31 15:16:19,033 - INFO - Verifications in API Server for VN ctest-VN1-67879207 passed 2025-10-31 15:16:19,033 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/0f0f7495-035b-4110-8464-0d4f9caad6a8 2025-10-31 15:16:19,046 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/ff9d01f5-ebf9-423f-b8fb-69eb648c3212 2025-10-31 15:16:19,055 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/ff9d01f5-ebf9-423f-b8fb-69eb648c3212 2025-10-31 15:16:19,063 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/f97b3e1d-fb0f-4be6-9415-02fd0837b9cb 2025-10-31 15:16:19,077 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-76625063', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1085214218574840080', 'uuid-lslong': '9539764546259572392'}, 'enable': 'true', 'created': '2025-10-31T15:16:16', 'last-modified': '2025-10-31T15:16:16', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.425656'} 2025-10-31 15:16:19,083 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-10-31 15:16:19,089 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-76625063', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1085214218574840080', 'uuid-lslong': '9539764546259572392'}, 'enable': 'true', 'created': '2025-10-31T15:16:16', 'last-modified': '2025-10-31T15:16:16', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.436473'} 2025-10-31 15:16:19,095 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-10-31 15:16:19,100 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-76625063', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1085214218574840080', 'uuid-lslong': '9539764546259572392'}, 'enable': 'true', 'created': '2025-10-31T15:16:16', 'last-modified': '2025-10-31T15:16:16', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.451587'} 2025-10-31 15:16:19,106 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-10-31 15:16:19,106 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN1-67879207 passed 2025-10-31 15:16:19,106 - DEBUG - ====Verifying policy data for ctest-VN1-67879207 in API_Server ====== 2025-10-31 15:16:19,106 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-10-31 15:16:19,114 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69 2025-10-31 15:16:19,125 - DEBUG - Requesting: http://10.0.0.38:8082/project/c7507b1f-4309-4180-9b1d-6c0668cbf321 2025-10-31 15:16:19,219 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/0f0f7495-035b-4110-8464-0d4f9caad6a8 2025-10-31 15:16:19,232 - DEBUG - =>VN ctest-VN1-67879207 has no policy to be verified 2025-10-31 15:16:19,232 - DEBUG - Verifying the vn in opserver 2025-10-31 15:16:19,232 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207 virtual network link through opserver 10.0.0.38 2025-10-31 15:16:19,232 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2025-10-31 15:16:19,524 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2025-10-31 15:16:19,579 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207?flat'} 2025-10-31 15:16:19,579 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207 virtual network link through opserver 10.0.0.249 2025-10-31 15:16:19,579 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks 2025-10-31 15:16:19,880 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks 2025-10-31 15:16:19,941 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207?flat'} 2025-10-31 15:16:19,941 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207 virtual network link through opserver 10.0.0.65 2025-10-31 15:16:19,941 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks 2025-10-31 15:16:20,236 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks 2025-10-31 15:16:20,295 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207?flat'} 2025-10-31 15:16:20,295 - INFO - Validated that VN default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207 is found in opserver 2025-10-31 15:16:20,316 - DEBUG - Do not have enough data to verify VN in agent 2025-10-31 15:16:20,340 - DEBUG - VRF ids for VN ctest-VN1-67879207: {} 2025-10-31 15:16:20,340 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-10-31 15:16:20,346 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69 2025-10-31 15:16:20,357 - DEBUG - Requesting: http://10.0.0.38:8082/project/c7507b1f-4309-4180-9b1d-6c0668cbf321 2025-10-31 15:16:20,453 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/01aa3232-f541-4ea1-82fe-baeba5b9b56a 2025-10-31 15:16:20,470 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/01aa3232-f541-4ea1-82fe-baeba5b9b56a 2025-10-31 15:16:20,481 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/2e22dae1-25c5-4abe-ba49-bee6e961767c 2025-10-31 15:16:20,489 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/2e22dae1-25c5-4abe-ba49-bee6e961767c 2025-10-31 15:16:20,497 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/9926a867-332c-4468-9d58-6c18ca4937b3 2025-10-31 15:16:20,505 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-10-31 15:16:20,505 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/01aa3232-f541-4ea1-82fe-baeba5b9b56a 2025-10-31 15:16:20,514 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/2e22dae1-25c5-4abe-ba49-bee6e961767c 2025-10-31 15:16:20,528 - INFO - Verified VN network id 16 for VN 01aa3232-f541-4ea1-82fe-baeba5b9b56a 2025-10-31 15:16:20,528 - INFO - Verifications in API Server for VN ctest-VN2-17371718 passed 2025-10-31 15:16:20,528 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/01aa3232-f541-4ea1-82fe-baeba5b9b56a 2025-10-31 15:16:20,539 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/2e22dae1-25c5-4abe-ba49-bee6e961767c 2025-10-31 15:16:20,546 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/2e22dae1-25c5-4abe-ba49-bee6e961767c 2025-10-31 15:16:20,552 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/9926a867-332c-4468-9d58-6c18ca4937b3 2025-10-31 15:16:20,566 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN2-17371718', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-76625063', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '119963534523190945', 'uuid-lslong': '9439187390275630442'}, 'enable': 'true', 'created': '2025-10-31T15:16:17', 'last-modified': '2025-10-31T15:16:17', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.855382'} 2025-10-31 15:16:20,571 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-10-31 15:16:20,578 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN2-17371718', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-76625063', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '119963534523190945', 'uuid-lslong': '9439187390275630442'}, 'enable': 'true', 'created': '2025-10-31T15:16:17', 'last-modified': '2025-10-31T15:16:17', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.871254'} 2025-10-31 15:16:20,584 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-10-31 15:16:20,590 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN2-17371718', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-76625063', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '119963534523190945', 'uuid-lslong': '9439187390275630442'}, 'enable': 'true', 'created': '2025-10-31T15:16:17', 'last-modified': '2025-10-31T15:16:17', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.879570'} 2025-10-31 15:16:20,596 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-10-31 15:16:20,596 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN2-17371718 passed 2025-10-31 15:16:20,596 - DEBUG - ====Verifying policy data for ctest-VN2-17371718 in API_Server ====== 2025-10-31 15:16:20,596 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-10-31 15:16:20,602 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69 2025-10-31 15:16:20,615 - DEBUG - Requesting: http://10.0.0.38:8082/project/c7507b1f-4309-4180-9b1d-6c0668cbf321 2025-10-31 15:16:20,712 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/01aa3232-f541-4ea1-82fe-baeba5b9b56a 2025-10-31 15:16:20,725 - DEBUG - =>VN ctest-VN2-17371718 has no policy to be verified 2025-10-31 15:16:20,725 - DEBUG - Verifying the vn in opserver 2025-10-31 15:16:20,725 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN2-17371718 virtual network link through opserver 10.0.0.38 2025-10-31 15:16:20,725 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2025-10-31 15:16:20,780 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN2-17371718', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN2-17371718?flat'} 2025-10-31 15:16:20,780 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN2-17371718 virtual network link through opserver 10.0.0.249 2025-10-31 15:16:20,780 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks 2025-10-31 15:16:20,837 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN2-17371718', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN2-17371718?flat'} 2025-10-31 15:16:20,837 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN2-17371718 virtual network link through opserver 10.0.0.65 2025-10-31 15:16:20,837 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks 2025-10-31 15:16:20,895 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN2-17371718', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN2-17371718?flat'} 2025-10-31 15:16:20,895 - INFO - Validated that VN default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN2-17371718 is found in opserver 2025-10-31 15:16:20,915 - DEBUG - Do not have enough data to verify VN in agent 2025-10-31 15:16:20,925 - DEBUG - VRF ids for VN ctest-VN2-17371718: {} 2025-10-31 15:16:20,925 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-10-31 15:16:20,933 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69 2025-10-31 15:16:20,946 - DEBUG - Requesting: http://10.0.0.38:8082/project/c7507b1f-4309-4180-9b1d-6c0668cbf321 2025-10-31 15:16:21,046 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/c0ad0d4f-553f-4f24-b947-c1a3142e5ff2 2025-10-31 15:16:21,061 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/c0ad0d4f-553f-4f24-b947-c1a3142e5ff2 2025-10-31 15:16:21,073 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/38dd2e17-33a1-4cd7-8f48-4f3bbf1e2aba 2025-10-31 15:16:21,081 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/38dd2e17-33a1-4cd7-8f48-4f3bbf1e2aba 2025-10-31 15:16:21,089 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/ce1d5041-c1fe-45a9-a9e4-73f66fdbbfdd 2025-10-31 15:16:21,096 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-10-31 15:16:21,096 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/c0ad0d4f-553f-4f24-b947-c1a3142e5ff2 2025-10-31 15:16:21,107 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/38dd2e17-33a1-4cd7-8f48-4f3bbf1e2aba 2025-10-31 15:16:21,123 - INFO - Verified VN network id 17 for VN c0ad0d4f-553f-4f24-b947-c1a3142e5ff2 2025-10-31 15:16:21,123 - INFO - Verifications in API Server for VN ctest-VN3-65695210 passed 2025-10-31 15:16:21,123 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/c0ad0d4f-553f-4f24-b947-c1a3142e5ff2 2025-10-31 15:16:21,134 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/38dd2e17-33a1-4cd7-8f48-4f3bbf1e2aba 2025-10-31 15:16:21,141 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/38dd2e17-33a1-4cd7-8f48-4f3bbf1e2aba 2025-10-31 15:16:21,149 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/ce1d5041-c1fe-45a9-a9e4-73f66fdbbfdd 2025-10-31 15:16:21,161 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN3-65695210', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-76625063', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13883767860636897060', 'uuid-lslong': '13350852526525538290'}, 'enable': 'true', 'created': '2025-10-31T15:16:18', 'last-modified': '2025-10-31T15:16:18', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.515985'} 2025-10-31 15:16:21,166 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-10-31 15:16:21,172 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN3-65695210', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-76625063', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13883767860636897060', 'uuid-lslong': '13350852526525538290'}, 'enable': 'true', 'created': '2025-10-31T15:16:18', 'last-modified': '2025-10-31T15:16:18', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.525167'} 2025-10-31 15:16:21,178 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-10-31 15:16:21,184 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN3-65695210', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-76625063', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13883767860636897060', 'uuid-lslong': '13350852526525538290'}, 'enable': 'true', 'created': '2025-10-31T15:16:18', 'last-modified': '2025-10-31T15:16:18', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.535657'} 2025-10-31 15:16:21,191 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-10-31 15:16:21,191 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN3-65695210 passed 2025-10-31 15:16:21,192 - DEBUG - ====Verifying policy data for ctest-VN3-65695210 in API_Server ====== 2025-10-31 15:16:21,192 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-10-31 15:16:21,198 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69 2025-10-31 15:16:21,212 - DEBUG - Requesting: http://10.0.0.38:8082/project/c7507b1f-4309-4180-9b1d-6c0668cbf321 2025-10-31 15:16:21,307 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/c0ad0d4f-553f-4f24-b947-c1a3142e5ff2 2025-10-31 15:16:21,317 - DEBUG - =>VN ctest-VN3-65695210 has no policy to be verified 2025-10-31 15:16:21,317 - DEBUG - Verifying the vn in opserver 2025-10-31 15:16:21,317 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN3-65695210 virtual network link through opserver 10.0.0.38 2025-10-31 15:16:21,317 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2025-10-31 15:16:21,381 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN3-65695210', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN3-65695210?flat'} 2025-10-31 15:16:21,381 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN3-65695210 virtual network link through opserver 10.0.0.249 2025-10-31 15:16:21,381 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks 2025-10-31 15:16:21,436 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN3-65695210', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN3-65695210?flat'} 2025-10-31 15:16:21,436 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN3-65695210 virtual network link through opserver 10.0.0.65 2025-10-31 15:16:21,436 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks 2025-10-31 15:16:21,491 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN3-65695210', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN3-65695210?flat'} 2025-10-31 15:16:21,491 - INFO - Validated that VN default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN3-65695210 is found in opserver 2025-10-31 15:16:21,517 - DEBUG - Do not have enough data to verify VN in agent 2025-10-31 15:16:21,528 - DEBUG - VRF ids for VN ctest-VN3-65695210: {} 2025-10-31 15:16:21,535 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207, 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-76625063:ctest-VN3-65695210, 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-10-31 15:16:21,823 - DEBUG - No source network defined 2025-10-31 15:16:21,823 - DEBUG - No source network defined 2025-10-31 15:16:21,823 - 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-76625063:ctest-policy13-23982635, 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-76625063:ctest-policy13-23982635, subnet_list = []], src_ports = [start_port = -1, end_port = -1], application = [], dst_addresses = [subnet = None, virtual_network = any, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None] 2025-10-31 15:16:22,108 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN2-17371718, 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-76625063:ctest-VN1-67879207, 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-76625063:ctest-VN2-17371718, 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-76625063:ctest-VN1-67879207, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None] 2025-10-31 15:16:22,332 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-10-31 15:16:22,338 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69 2025-10-31 15:16:22,348 - DEBUG - Requesting: http://10.0.0.38:8082/project/c7507b1f-4309-4180-9b1d-6c0668cbf321 2025-10-31 15:16:22,455 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/0f0f7495-035b-4110-8464-0d4f9caad6a8 2025-10-31 15:16:22,464 - DEBUG - Setup step: Associating the policy to VN' 2025-10-31 15:16:22,610 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '0f0f7495-035b-4110-8464-0d4f9caad6a8', 'name': 'ctest-VN1-67879207', 'tenant_id': 'c7507b1f430941809b1d6c0668cbf321', 'project_id': 'c7507b1f430941809b1d6c0668cbf321', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['706924f6-b7b3-4ce4-a373-8e44382697c1', '6d1d03f0-1e76-4cce-8aa4-d902658ac939'], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-76625063', 'ctest-VN1-67879207'], 'policys': [['default-domain', 'ctest-TestPolicyAclIpv4v6-76625063', 'ctest-policy12-78128838'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-76625063', 'ctest-policy13-23982635']], 'subnet_ipam': [{'subnet_cidr': '222.153.98.128/26', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-76625063', 'ipam1']}, {'subnet_cidr': '22e8:9812:b7d8:da18:974b:6c8e::/96', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-76625063', 'ipam1']}], 'port_security_enabled': True, 'description': ''}} 2025-10-31 15:16:22,777 - DEBUG - Associated Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-76625063', 'ctest-policy12-78128838'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-76625063', 'ctest-policy13-23982635']] to ctest-VN1-67879207 2025-10-31 15:16:22,777 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-10-31 15:16:22,783 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69 2025-10-31 15:16:22,794 - DEBUG - Requesting: http://10.0.0.38:8082/project/c7507b1f-4309-4180-9b1d-6c0668cbf321 2025-10-31 15:16:22,911 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/01aa3232-f541-4ea1-82fe-baeba5b9b56a 2025-10-31 15:16:22,921 - DEBUG - Setup step: Associating the policy to VN' 2025-10-31 15:16:23,062 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '01aa3232-f541-4ea1-82fe-baeba5b9b56a', 'name': 'ctest-VN2-17371718', 'tenant_id': 'c7507b1f430941809b1d6c0668cbf321', 'project_id': 'c7507b1f430941809b1d6c0668cbf321', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['47523288-f1db-4dc1-b671-8318f2839a90', 'c9fdec9f-d5ad-4bc3-bea0-f6548e4a744d'], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-76625063', 'ctest-VN2-17371718'], 'policys': [['default-domain', 'ctest-TestPolicyAclIpv4v6-76625063', 'ctest-policy21-68570719']], 'subnet_ipam': [{'subnet_cidr': '105.164.1.64/26', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-76625063', 'ipam2']}, {'subnet_cidr': '30c4:b2aa:94a1:2bdf:835a:6d38::/96', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-76625063', 'ipam2']}], 'port_security_enabled': True, 'description': ''}} 2025-10-31 15:16:23,166 - DEBUG - Associated Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-76625063', 'ctest-policy21-68570719']] to ctest-VN2-17371718 2025-10-31 15:16:23,276 - DEBUG - Services list from nova: [, , , ] 2025-10-31 15:16:24,416 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4577-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4577-1) 2025-10-31 15:16:25,744 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4577-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4577-2) 2025-10-31 15:16:27,283 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4577-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4577-1) 2025-10-31 15:16:27,390 - DEBUG - VM is in ACTIVE state now 2025-10-31 15:16:27,390 - INFO - VM name : ctest-VM11-11011959 2025-10-31 15:16:27,477 - DEBUG - VM ctest-VM11-11011959 ID is 7e61e8dd-0198-433f-9df4-b756fad52c0f 2025-10-31 15:16:27,568 - DEBUG - VM is in ACTIVE state now 2025-10-31 15:16:27,598 - DEBUG - VM ctest-VM11-11011959 launched on Node an-jenkins-deploy-platform-ansible-os-4577-1 2025-10-31 15:16:27,697 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/7e61e8dd-0198-433f-9df4-b756fad52c0f 2025-10-31 15:16:28,080 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/7e61e8dd-0198-433f-9df4-b756fad52c0f 2025-10-31 15:16:28,134 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/7e61e8dd-0198-433f-9df4-b756fad52c0f 2025-10-31 15:16:28,142 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine-interface/ee074cc6-e147-4218-85a2-4ab0ab0397b6 2025-10-31 15:16:28,157 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/7e61e8dd-0198-433f-9df4-b756fad52c0f 2025-10-31 15:16:28,175 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine-interface/ee074cc6-e147-4218-85a2-4ab0ab0397b6 2025-10-31 15:16:28,184 - DEBUG - Requesting: http://10.0.0.65:8082/instance-ip/d450132e-b602-4f80-9f9e-10e3261719be 2025-10-31 15:16:28,209 - DEBUG - Requesting: http://10.0.0.65:8082/instance-ip/d671ae31-f0df-43e2-bd77-62c65f9efa75 2025-10-31 15:16:28,232 - DEBUG - Verifying in api server 10.0.0.65 2025-10-31 15:16:28,232 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/7e61e8dd-0198-433f-9df4-b756fad52c0f 2025-10-31 15:16:28,240 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine-interface/ee074cc6-e147-4218-85a2-4ab0ab0397b6 2025-10-31 15:16:28,253 - DEBUG - Requesting: http://10.0.0.65:8082/instance-ip/d450132e-b602-4f80-9f9e-10e3261719be 2025-10-31 15:16:28,261 - DEBUG - Requesting: http://10.0.0.65:8082/instance-ip/d671ae31-f0df-43e2-bd77-62c65f9efa75 2025-10-31 15:16:28,269 - INFO - VM ctest-VM11-11011959 verfication in all API Servers passed 2025-10-31 15:16:28,291 - DEBUG - VM ctest-VM11-11011959 Tap interface: {'index': '3', 'name': 'tapee074cc6-e1', 'uuid': 'ee074cc6-e147-4218-85a2-4ab0ab0397b6', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207:ctest-VN1-67879207', '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-76625063:ctest-VN1-67879207', 'vm_uuid': '7e61e8dd-0198-433f-9df4-b756fad52c0f', 'vm_name': 'ctest-VM11-11011959', 'ip_addr': '222.153.98.131', 'mac_addr': '02:ee:07:4c:c6:e1', '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-76625063:ee074cc6-e147-4218-85a2-4ab0ab0397b6', 'sg_uuid_list': ['5d99eec9-a822-477e-9506-c795efe21d2a'], 'static_route_list': None, 'vm_project_uuid': 'c7507b1f-4309-4180-9b1d-6c0668cbf321', 'admin_state': 'Enabled', 'flow_key_idx': '30', 'allowed_address_pair_list': None, 'ip6_addr': '22e8:9812:b7d8:da18:974b:6c8e: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-10-31 15:16:28,291 - DEBUG - Agent 10.0.0.19 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207:ctest-VN1-67879207 2025-10-31 15:16:28,297 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207:ctest-VN1-67879207', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207', 'table_label': '-1', 'vxlan_id': '15', '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-10-31 15:16:28,396 - DEBUG - VM ctest-VM11-11011959 : Tap interface tapee074cc6-e1 is set to Active 2025-10-31 15:16:28,396 - DEBUG - Tap interface tapee074cc6-e1 detail : {'index': '3', 'name': 'tapee074cc6-e1', 'uuid': 'ee074cc6-e147-4218-85a2-4ab0ab0397b6', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207:ctest-VN1-67879207', '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-76625063:ctest-VN1-67879207', 'vm_uuid': '7e61e8dd-0198-433f-9df4-b756fad52c0f', 'vm_name': 'ctest-VM11-11011959', 'ip_addr': '222.153.98.131', 'mac_addr': '02:ee:07:4c:c6:e1', '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-76625063:ee074cc6-e147-4218-85a2-4ab0ab0397b6', 'sg_uuid_list': ['5d99eec9-a822-477e-9506-c795efe21d2a'], 'static_route_list': None, 'vm_project_uuid': 'c7507b1f-4309-4180-9b1d-6c0668cbf321', 'admin_state': 'Enabled', 'flow_key_idx': '30', 'allowed_address_pair_list': None, 'ip6_addr': '22e8:9812:b7d8:da18:974b:6c8e: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-10-31 15:16:28,511 - DEBUG - Starting Layer 2 verification in Agent 2025-10-31 15:16:28,527 - DEBUG - Layer 2 path is seen for VM MAC 02:ee:07:4c:c6:e1 in agent 10.0.0.19 2025-10-31 15:16:28,527 - DEBUG - Active layer 2 route in agent is present for VMI tapee074cc6-e1 2025-10-31 15:16:28,527 - DEBUG - L2 label(29) matches bw route table and interface table 2025-10-31 15:16:28,534 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207 is found in Agent of node 10.0.0.19 2025-10-31 15:16:28,683 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207 is consistent in agent 10.0.0.19 2025-10-31 15:16:28,683 - DEBUG - Route for VM IP ['222.153.98.131', '22e8:9812:b7d8:da18:974b:6c8e:0:3'] is consistent in agent 10.0.0.19 2025-10-31 15:16:28,683 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207 verification for VM ctest-VM11-11011959 in Agent 10.0.0.19 passed 2025-10-31 15:16:28,806 - DEBUG - Starting all layer 2 verification in agent 10.0.0.19 2025-10-31 15:16:28,834 - DEBUG - Route for VM MAC 02:ee:07:4c:c6:e1 is consistent in agent 10.0.0.19 2025-10-31 15:16:32,044 - 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 1021ms') 2025-10-31 15:16:32,044 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-11011959 failed! 2025-10-31 15:16:32,058 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207 is 222.153.98.129 and allocation pool is NOT set 2025-10-31 15:16:32,058 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207 is 22e8:9812:b7d8:da18:974b:6c8e:0:1 and allocation pool is NOT set 2025-10-31 15:16:36,137 - 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 1013ms') 2025-10-31 15:16:36,137 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-11011959 failed! 2025-10-31 15:16:36,152 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207 is 222.153.98.129 and allocation pool is NOT set 2025-10-31 15:16:36,152 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207 is 22e8:9812:b7d8:da18:974b:6c8e:0:1 and allocation pool is NOT set 2025-10-31 15:16:40,240 - 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-10-31 15:16:40,240 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-11011959 failed! 2025-10-31 15:16:40,254 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207 is 222.153.98.129 and allocation pool is NOT set 2025-10-31 15:16:40,254 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207 is 22e8:9812:b7d8:da18:974b:6c8e:0:1 and allocation pool is NOT set 2025-10-31 15:16:44,326 - 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 1012ms') 2025-10-31 15:16:44,326 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-11011959 failed! 2025-10-31 15:16:44,341 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207 is 222.153.98.129 and allocation pool is NOT set 2025-10-31 15:16:44,341 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207 is 22e8:9812:b7d8:da18:974b:6c8e:0:1 and allocation pool is NOT set 2025-10-31 15:16:48,425 - 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 1028ms') 2025-10-31 15:16:48,425 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-11011959 failed! 2025-10-31 15:16:48,441 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207 is 222.153.98.129 and allocation pool is NOT set 2025-10-31 15:16:48,441 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207 is 22e8:9812:b7d8:da18:974b:6c8e:0:1 and allocation pool is NOT set 2025-10-31 15:16:52,520 - 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-10-31 15:16:52,520 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-11011959 failed! 2025-10-31 15:16:52,535 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207 is 222.153.98.129 and allocation pool is NOT set 2025-10-31 15:16:52,535 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207 is 22e8:9812:b7d8:da18:974b:6c8e:0:1 and allocation pool is NOT set 2025-10-31 15:16:56,614 - 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-10-31 15:16:56,614 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-11011959 failed! 2025-10-31 15:16:56,630 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207 is 222.153.98.129 and allocation pool is NOT set 2025-10-31 15:16:56,631 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207 is 22e8:9812:b7d8:da18:974b:6c8e:0:1 and allocation pool is NOT set 2025-10-31 15:17:00,709 - 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 1017ms') 2025-10-31 15:17:00,709 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-11011959 failed! 2025-10-31 15:17:00,724 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207 is 222.153.98.129 and allocation pool is NOT set 2025-10-31 15:17:00,724 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207 is 22e8:9812:b7d8:da18:974b:6c8e:0:1 and allocation pool is NOT set 2025-10-31 15:17:04,806 - 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-10-31 15:17:04,806 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-11011959 failed! 2025-10-31 15:17:04,819 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207 is 222.153.98.129 and allocation pool is NOT set 2025-10-31 15:17:04,820 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207 is 22e8:9812:b7d8:da18:974b:6c8e:0:1 and allocation pool is NOT set 2025-10-31 15:17:08,899 - 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-10-31 15:17:08,899 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-11011959 failed! 2025-10-31 15:17:08,956 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207 is 222.153.98.129 and allocation pool is NOT set 2025-10-31 15:17:08,956 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207 is 22e8:9812:b7d8:da18:974b:6c8e:0:1 and allocation pool is NOT set 2025-10-31 15:17:13,033 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=10.2 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 1 received, 50% packet loss, time 1019ms\r\nrtt min/avg/max/mdev = 10.199/10.199/10.199/0.000 ms') 2025-10-31 15:17:13,033 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-11011959 passed 2025-10-31 15:17:13,033 - INFO - VM ctest-VM11-11011959 verifications in Compute nodes passed 2025-10-31 15:17:13,273 - DEBUG - Validated VM route 222.153.98.131 in vrouter of 10.0.0.19 2025-10-31 15:17:13,280 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0 2025-10-31 15:17:13,280 - INFO - Validated routes of VM ctest-VM11-11011959 in all vrouters 2025-10-31 15:17:13,726 - DEBUG - Starting all layer2 verification in 10.0.0.38 Control Node 2025-10-31 15:17:13,857 - DEBUG - Layer2 route found for VM MAC 02:ee:07:4c:c6:e1 in Control-node 10.0.0.38 2025-10-31 15:17:13,857 - DEBUG - L2 Label for VM ctest-VM11-11011959 same between Control-node 10.0.0.38 and Agent, Expected: 29, Seen: 29 2025-10-31 15:17:13,975 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.38, not supported 2025-10-31 15:17:13,975 - DEBUG - Starting all layer2 verification in 10.0.0.65 Control Node 2025-10-31 15:17:14,104 - DEBUG - Layer2 route found for VM MAC 02:ee:07:4c:c6:e1 in Control-node 10.0.0.65 2025-10-31 15:17:14,104 - DEBUG - L2 Label for VM ctest-VM11-11011959 same between Control-node 10.0.0.65 and Agent, Expected: 29, Seen: 29 2025-10-31 15:17:14,226 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.65, not supported 2025-10-31 15:17:14,226 - DEBUG - Starting all layer2 verification in 10.0.0.249 Control Node 2025-10-31 15:17:14,352 - DEBUG - Layer2 route found for VM MAC 02:ee:07:4c:c6:e1 in Control-node 10.0.0.249 2025-10-31 15:17:14,352 - DEBUG - L2 Label for VM ctest-VM11-11011959 same between Control-node 10.0.0.249 and Agent, Expected: 29, Seen: 29 2025-10-31 15:17:14,466 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.249, not supported 2025-10-31 15:17:14,466 - INFO - Verification in Control-nodes for VM ctest-VM11-11011959 passed 2025-10-31 15:17:14,466 - DEBUG - Verifying the vm in opserver 2025-10-31 15:17:14,466 - DEBUG - Verifying in collector 10.0.0.65 ... 2025-10-31 15:17:14,466 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-machine/7e61e8dd-0198-433f-9df4-b756fad52c0f?flat 2025-10-31 15:17:14,486 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-76625063:ee074cc6-e147-4218-85a2-4ab0ab0397b6?flat 2025-10-31 15:17:14,504 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-76625063:ee074cc6-e147-4218-85a2-4ab0ab0397b6?flat 2025-10-31 15:17:14,523 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-76625063:ee074cc6-e147-4218-85a2-4ab0ab0397b6?flat 2025-10-31 15:17:14,540 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-machine/7e61e8dd-0198-433f-9df4-b756fad52c0f?flat 2025-10-31 15:17:14,556 - DEBUG - Verifying vm in vn uve 2025-10-31 15:17:14,556 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-76625063:ee074cc6-e147-4218-85a2-4ab0ab0397b6?flat 2025-10-31 15:17:14,573 - DEBUG - VM uve shows interface as {'uuid': 'ee074cc6-e147-4218-85a2-4ab0ab0397b6', 'label': 25, 'mac_address': '02:ee:07:4c:c6:e1', 'ip_address': '222.153.98.131', 'is_health_check_active': True, 'ip4_active': True, 'fixed_ip4_list': ['222.153.98.131'], 'rx_vlan': 65535, 'ip6_active': True, 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207', '__T': 1761923792337030, 'admin_state': True, 'fixed_ip6_list': ['22e8:9812:b7d8:da18:974b:6c8e:0:3'], 'l2_active': True, 'port_mirror_enabled': False, 'vhostuser_mode': 0, 'vm_uuid': '7e61e8dd-0198-433f-9df4-b756fad52c0f', 'vn_uuid': '0f0f7495-035b-4110-8464-0d4f9caad6a8', 'ip6_address': '22e8:9812:b7d8:da18:974b:6c8e:0:3', 'tx_vlan': 65535, 'active': True, 'gateway': '222.153.98.129', 'vm_name': 'ctest-VM11-11011959'} 2025-10-31 15:17:14,573 - DEBUG - VM uve shows ip address as ['222.153.98.131', '22e8:9812:b7d8:da18:974b:6c8e:0:3'] 2025-10-31 15:17:14,573 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207 2025-10-31 15:17:14,573 - INFO - Verifying through opserver in 10.0.0.38 2025-10-31 15:17:14,573 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207?flat 2025-10-31 15:17:14,595 - DEBUG - expected vm list 7e61e8dd-0198-433f-9df4-b756fad52c0f 2025-10-31 15:17:14,595 - DEBUG - Extracted vm list ['7e61e8dd-0198-433f-9df4-b756fad52c0f'] 2025-10-31 15:17:14,595 - INFO - VM 7e61e8dd-0198-433f-9df4-b756fad52c0f is present in default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207 2025-10-31 15:17:14,595 - DEBUG - Verifying vm in vrouter uve 2025-10-31 15:17:14,595 - DEBUG - Getting info from collector 10.0.0.65.. 2025-10-31 15:17:14,595 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-machine/7e61e8dd-0198-433f-9df4-b756fad52c0f?flat 2025-10-31 15:17:14,613 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-4577-1'] 2025-10-31 15:17:14,613 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4577-1:Compute:contrail-vrouter-agent:0?flat 2025-10-31 15:17:14,647 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4577-1?flat 2025-10-31 15:17:14,683 - DEBUG - VM 7e61e8dd-0198-433f-9df4-b756fad52c0f is present in vrouter an-jenkins-deploy-platform-ansible-os-4577-1 uve 2025-10-31 15:17:14,683 - DEBUG - Validated that VM ctest-VM11-11011959 is in Vrouter an-jenkins-deploy-platform-ansible-os-4577-1 UVE 2025-10-31 15:17:14,683 - DEBUG - Verifying vm tap interface/vn in vrouter uve 2025-10-31 15:17:14,689 - DEBUG - Expected tap interface of VM uuid 7e61e8dd-0198-433f-9df4-b756fad52c0f is default-domain:ctest-TestPolicyAclIpv4v6-76625063:ee074cc6-e147-4218-85a2-4ab0ab0397b6 2025-10-31 15:17:14,690 - DEBUG - Expected VN of VM uuid 7e61e8dd-0198-433f-9df4-b756fad52c0f is default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207 2025-10-31 15:17:14,690 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4577-1:Compute:contrail-vrouter-agent:0?flat 2025-10-31 15:17:14,722 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4577-1?flat 2025-10-31 15:17:14,754 - DEBUG - VM 7e61e8dd-0198-433f-9df4-b756fad52c0f is present in vrouter an-jenkins-deploy-platform-ansible-os-4577-1 uve 2025-10-31 15:17:14,754 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-76625063:ee074cc6-e147-4218-85a2-4ab0ab0397b6 of vm 7e61e8dd-0198-433f-9df4-b756fad52c0f is present in vrouter an-jenkins-deploy-platform-ansible-os-4577-1 uve 2025-10-31 15:17:14,754 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207 of vm 7e61e8dd-0198-433f-9df4-b756fad52c0f is present in vrouter an-jenkins-deploy-platform-ansible-os-4577-1 uve 2025-10-31 15:17:14,754 - DEBUG - Verifying the 7e61e8dd-0198-433f-9df4-b756fad52c0f virtual network link through opserver 10.0.0.38 2025-10-31 15:17:14,754 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines 2025-10-31 15:17:14,766 - DEBUG - VM link and name as {'name': '7e61e8dd-0198-433f-9df4-b756fad52c0f', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-machine/7e61e8dd-0198-433f-9df4-b756fad52c0f?flat'} 2025-10-31 15:17:14,767 - DEBUG - Verifying the 7e61e8dd-0198-433f-9df4-b756fad52c0f virtual network link through opserver 10.0.0.249 2025-10-31 15:17:14,767 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machines 2025-10-31 15:17:14,780 - DEBUG - VM link and name as {'name': '7e61e8dd-0198-433f-9df4-b756fad52c0f', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-machine/7e61e8dd-0198-433f-9df4-b756fad52c0f?flat'} 2025-10-31 15:17:14,780 - DEBUG - Verifying the 7e61e8dd-0198-433f-9df4-b756fad52c0f virtual network link through opserver 10.0.0.65 2025-10-31 15:17:14,780 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-machines 2025-10-31 15:17:14,795 - DEBUG - VM link and name as {'name': '7e61e8dd-0198-433f-9df4-b756fad52c0f', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-machine/7e61e8dd-0198-433f-9df4-b756fad52c0f?flat'} 2025-10-31 15:17:14,795 - INFO - VM ctest-VM11-11011959 validations in Opserver passed 2025-10-31 15:17:14,882 - DEBUG - VM is in ACTIVE state now 2025-10-31 15:17:14,882 - INFO - VM name : ctest-VM21-59838410 2025-10-31 15:17:14,972 - DEBUG - VM ctest-VM21-59838410 ID is 124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a 2025-10-31 15:17:15,052 - DEBUG - VM is in ACTIVE state now 2025-10-31 15:17:15,052 - DEBUG - VM ctest-VM21-59838410 launched on Node an-jenkins-deploy-platform-ansible-os-4577-2 2025-10-31 15:17:15,144 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a 2025-10-31 15:17:15,160 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a 2025-10-31 15:17:15,170 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine-interface/3bd4e14f-43f6-4b19-8595-229304684e4c 2025-10-31 15:17:15,183 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a 2025-10-31 15:17:15,192 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine-interface/3bd4e14f-43f6-4b19-8595-229304684e4c 2025-10-31 15:17:15,205 - DEBUG - Requesting: http://10.0.0.65:8082/instance-ip/54e69e5a-d12c-46c4-a6d7-419d872dca42 2025-10-31 15:17:15,215 - DEBUG - Requesting: http://10.0.0.65:8082/instance-ip/6505edd6-cf6a-4565-ab2d-1c99b71b5b1f 2025-10-31 15:17:15,227 - DEBUG - Verifying in api server 10.0.0.65 2025-10-31 15:17:15,227 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a 2025-10-31 15:17:15,235 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine-interface/3bd4e14f-43f6-4b19-8595-229304684e4c 2025-10-31 15:17:15,247 - DEBUG - Requesting: http://10.0.0.65:8082/instance-ip/54e69e5a-d12c-46c4-a6d7-419d872dca42 2025-10-31 15:17:15,255 - DEBUG - Requesting: http://10.0.0.65:8082/instance-ip/6505edd6-cf6a-4565-ab2d-1c99b71b5b1f 2025-10-31 15:17:15,264 - INFO - VM ctest-VM21-59838410 verfication in all API Servers passed 2025-10-31 15:17:15,289 - DEBUG - VM ctest-VM21-59838410 Tap interface: {'index': '3', 'name': 'tap3bd4e14f-43', 'uuid': '3bd4e14f-43f6-4b19-8595-229304684e4c', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN2-17371718:ctest-VN2-17371718', '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-76625063:ctest-VN2-17371718', 'vm_uuid': '124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a', 'vm_name': 'ctest-VM21-59838410', 'ip_addr': '105.164.1.67', 'mac_addr': '02:3b:d4:e1:4f:43', '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-76625063:3bd4e14f-43f6-4b19-8595-229304684e4c', 'sg_uuid_list': ['5d99eec9-a822-477e-9506-c795efe21d2a'], 'static_route_list': None, 'vm_project_uuid': 'c7507b1f-4309-4180-9b1d-6c0668cbf321', 'admin_state': 'Enabled', 'flow_key_idx': '31', 'allowed_address_pair_list': None, 'ip6_addr': '30c4:b2aa:94a1:2bdf:835a:6d38: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-10-31 15:17:15,289 - DEBUG - Agent 10.0.0.22 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN2-17371718:ctest-VN2-17371718 2025-10-31 15:17:15,297 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN2-17371718:ctest-VN2-17371718', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN2-17371718', 'table_label': '-1', 'vxlan_id': '16', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.18:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2025-10-31 15:17:15,405 - DEBUG - VM ctest-VM21-59838410 : Tap interface tap3bd4e14f-43 is set to Active 2025-10-31 15:17:15,405 - DEBUG - Tap interface tap3bd4e14f-43 detail : {'index': '3', 'name': 'tap3bd4e14f-43', 'uuid': '3bd4e14f-43f6-4b19-8595-229304684e4c', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN2-17371718:ctest-VN2-17371718', '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-76625063:ctest-VN2-17371718', 'vm_uuid': '124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a', 'vm_name': 'ctest-VM21-59838410', 'ip_addr': '105.164.1.67', 'mac_addr': '02:3b:d4:e1:4f:43', '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-76625063:3bd4e14f-43f6-4b19-8595-229304684e4c', 'sg_uuid_list': ['5d99eec9-a822-477e-9506-c795efe21d2a'], 'static_route_list': None, 'vm_project_uuid': 'c7507b1f-4309-4180-9b1d-6c0668cbf321', 'admin_state': 'Enabled', 'flow_key_idx': '31', 'allowed_address_pair_list': None, 'ip6_addr': '30c4:b2aa:94a1:2bdf:835a:6d38: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-10-31 15:17:15,519 - DEBUG - Starting Layer 2 verification in Agent 2025-10-31 15:17:15,532 - DEBUG - Layer 2 path is seen for VM MAC 02:3b:d4:e1:4f:43 in agent 10.0.0.22 2025-10-31 15:17:15,532 - DEBUG - Active layer 2 route in agent is present for VMI tap3bd4e14f-43 2025-10-31 15:17:15,532 - DEBUG - L2 label(29) matches bw route table and interface table 2025-10-31 15:17:15,545 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN2-17371718 is found in Agent of node 10.0.0.22 2025-10-31 15:17:15,704 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN2-17371718 is consistent in agent 10.0.0.22 2025-10-31 15:17:15,704 - DEBUG - Route for VM IP ['30c4:b2aa:94a1:2bdf:835a:6d38:0:3', '105.164.1.67'] is consistent in agent 10.0.0.22 2025-10-31 15:17:15,704 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN2-17371718 verification for VM ctest-VM21-59838410 in Agent 10.0.0.22 passed 2025-10-31 15:17:15,816 - DEBUG - Starting all layer 2 verification in agent 10.0.0.22 2025-10-31 15:17:15,828 - DEBUG - Route for VM MAC 02:3b:d4:e1:4f:43 is consistent in agent 10.0.0.22 2025-10-31 15:17:17,004 - 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=8.79 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.894 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1000ms\r\nrtt min/avg/max/mdev = 0.894/4.840/8.787/3.946 ms') 2025-10-31 15:17:17,004 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-59838410 passed 2025-10-31 15:17:17,004 - INFO - VM ctest-VM21-59838410 verifications in Compute nodes passed 2025-10-31 15:17:17,258 - DEBUG - Validated VM route 105.164.1.67 in vrouter of 10.0.0.22 2025-10-31 15:17:17,265 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0 2025-10-31 15:17:17,265 - INFO - Validated routes of VM ctest-VM21-59838410 in all vrouters 2025-10-31 15:17:17,730 - DEBUG - Starting all layer2 verification in 10.0.0.38 Control Node 2025-10-31 15:17:17,844 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.38, not supported 2025-10-31 15:17:17,973 - DEBUG - Layer2 route found for VM MAC 02:3b:d4:e1:4f:43 in Control-node 10.0.0.38 2025-10-31 15:17:17,974 - DEBUG - L2 Label for VM ctest-VM21-59838410 same between Control-node 10.0.0.38 and Agent, Expected: 29, Seen: 29 2025-10-31 15:17:17,974 - DEBUG - Starting all layer2 verification in 10.0.0.65 Control Node 2025-10-31 15:17:18,093 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.65, not supported 2025-10-31 15:17:18,219 - DEBUG - Layer2 route found for VM MAC 02:3b:d4:e1:4f:43 in Control-node 10.0.0.65 2025-10-31 15:17:18,219 - DEBUG - L2 Label for VM ctest-VM21-59838410 same between Control-node 10.0.0.65 and Agent, Expected: 29, Seen: 29 2025-10-31 15:17:18,219 - DEBUG - Starting all layer2 verification in 10.0.0.249 Control Node 2025-10-31 15:17:18,337 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.249, not supported 2025-10-31 15:17:18,476 - DEBUG - Layer2 route found for VM MAC 02:3b:d4:e1:4f:43 in Control-node 10.0.0.249 2025-10-31 15:17:18,476 - DEBUG - L2 Label for VM ctest-VM21-59838410 same between Control-node 10.0.0.249 and Agent, Expected: 29, Seen: 29 2025-10-31 15:17:18,476 - INFO - Verification in Control-nodes for VM ctest-VM21-59838410 passed 2025-10-31 15:17:18,476 - DEBUG - Verifying the vm in opserver 2025-10-31 15:17:18,476 - DEBUG - Verifying in collector 10.0.0.65 ... 2025-10-31 15:17:18,477 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-machine/124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a?flat 2025-10-31 15:17:18,493 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-76625063:3bd4e14f-43f6-4b19-8595-229304684e4c?flat 2025-10-31 15:17:18,514 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-76625063:3bd4e14f-43f6-4b19-8595-229304684e4c?flat 2025-10-31 15:17:18,532 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-76625063:3bd4e14f-43f6-4b19-8595-229304684e4c?flat 2025-10-31 15:17:18,555 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-machine/124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a?flat 2025-10-31 15:17:18,572 - DEBUG - Verifying vm in vn uve 2025-10-31 15:17:18,573 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-76625063:3bd4e14f-43f6-4b19-8595-229304684e4c?flat 2025-10-31 15:17:18,594 - DEBUG - VM uve shows interface as {'uuid': '3bd4e14f-43f6-4b19-8595-229304684e4c', 'label': 25, 'mac_address': '02:3b:d4:e1:4f:43', 'ip_address': '105.164.1.67', 'is_health_check_active': True, 'ip4_active': True, 'fixed_ip4_list': ['105.164.1.67'], 'rx_vlan': 65535, 'ip6_active': True, 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN2-17371718', '__T': 1761923815874567, 'admin_state': True, 'fixed_ip6_list': ['30c4:b2aa:94a1:2bdf:835a:6d38:0:3'], 'l2_active': True, 'port_mirror_enabled': False, 'vhostuser_mode': 0, 'vm_uuid': '124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a', 'vn_uuid': '01aa3232-f541-4ea1-82fe-baeba5b9b56a', 'ip6_address': '30c4:b2aa:94a1:2bdf:835a:6d38:0:3', 'tx_vlan': 65535, 'active': True, 'gateway': '105.164.1.65', 'vm_name': 'ctest-VM21-59838410'} 2025-10-31 15:17:18,594 - DEBUG - VM uve shows ip address as ['105.164.1.67', '30c4:b2aa:94a1:2bdf:835a:6d38:0:3'] 2025-10-31 15:17:18,594 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN2-17371718 2025-10-31 15:17:18,594 - INFO - Verifying through opserver in 10.0.0.38 2025-10-31 15:17:18,594 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN2-17371718?flat 2025-10-31 15:17:18,615 - DEBUG - expected vm list 124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a 2025-10-31 15:17:18,615 - DEBUG - Extracted vm list ['124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a'] 2025-10-31 15:17:18,615 - INFO - VM 124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a is present in default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN2-17371718 2025-10-31 15:17:18,616 - DEBUG - Verifying vm in vrouter uve 2025-10-31 15:17:18,616 - DEBUG - Getting info from collector 10.0.0.65.. 2025-10-31 15:17:18,616 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-machine/124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a?flat 2025-10-31 15:17:18,630 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-4577-2'] 2025-10-31 15:17:18,630 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4577-2:Compute:contrail-vrouter-agent:0?flat 2025-10-31 15:17:18,663 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4577-2?flat 2025-10-31 15:17:18,696 - DEBUG - VM 124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a is present in vrouter an-jenkins-deploy-platform-ansible-os-4577-2 uve 2025-10-31 15:17:18,696 - DEBUG - Validated that VM ctest-VM21-59838410 is in Vrouter an-jenkins-deploy-platform-ansible-os-4577-2 UVE 2025-10-31 15:17:18,696 - DEBUG - Verifying vm tap interface/vn in vrouter uve 2025-10-31 15:17:18,704 - DEBUG - Expected tap interface of VM uuid 124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a is default-domain:ctest-TestPolicyAclIpv4v6-76625063:3bd4e14f-43f6-4b19-8595-229304684e4c 2025-10-31 15:17:18,704 - DEBUG - Expected VN of VM uuid 124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a is default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN2-17371718 2025-10-31 15:17:18,704 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4577-2:Compute:contrail-vrouter-agent:0?flat 2025-10-31 15:17:18,743 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4577-2?flat 2025-10-31 15:17:18,775 - DEBUG - VM 124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a is present in vrouter an-jenkins-deploy-platform-ansible-os-4577-2 uve 2025-10-31 15:17:18,775 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-76625063:3bd4e14f-43f6-4b19-8595-229304684e4c of vm 124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a is present in vrouter an-jenkins-deploy-platform-ansible-os-4577-2 uve 2025-10-31 15:17:18,775 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN2-17371718 of vm 124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a is present in vrouter an-jenkins-deploy-platform-ansible-os-4577-2 uve 2025-10-31 15:17:18,775 - DEBUG - Verifying the 124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a virtual network link through opserver 10.0.0.38 2025-10-31 15:17:18,775 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines 2025-10-31 15:17:18,788 - DEBUG - VM link and name as {'name': '124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-machine/124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a?flat'} 2025-10-31 15:17:18,788 - DEBUG - Verifying the 124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a virtual network link through opserver 10.0.0.249 2025-10-31 15:17:18,788 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machines 2025-10-31 15:17:18,801 - DEBUG - VM link and name as {'name': '124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-machine/124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a?flat'} 2025-10-31 15:17:18,801 - DEBUG - Verifying the 124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a virtual network link through opserver 10.0.0.65 2025-10-31 15:17:18,801 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-machines 2025-10-31 15:17:18,813 - DEBUG - VM link and name as {'name': '124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-machine/124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a?flat'} 2025-10-31 15:17:18,813 - INFO - VM ctest-VM21-59838410 validations in Opserver passed 2025-10-31 15:17:18,894 - DEBUG - VM is in ACTIVE state now 2025-10-31 15:17:18,894 - INFO - VM name : ctest-VM31-26571588 2025-10-31 15:17:18,980 - DEBUG - VM ctest-VM31-26571588 ID is a48418d6-6cfe-409b-8910-60230b717356 2025-10-31 15:17:19,060 - DEBUG - VM is in ACTIVE state now 2025-10-31 15:17:19,060 - DEBUG - VM ctest-VM31-26571588 launched on Node an-jenkins-deploy-platform-ansible-os-4577-1 2025-10-31 15:17:19,142 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/a48418d6-6cfe-409b-8910-60230b717356 2025-10-31 15:17:19,153 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/a48418d6-6cfe-409b-8910-60230b717356 2025-10-31 15:17:19,160 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine-interface/c461f496-e730-4ffe-bf65-8b5d1090202f 2025-10-31 15:17:19,172 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/a48418d6-6cfe-409b-8910-60230b717356 2025-10-31 15:17:19,182 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine-interface/c461f496-e730-4ffe-bf65-8b5d1090202f 2025-10-31 15:17:19,196 - DEBUG - Requesting: http://10.0.0.65:8082/instance-ip/1ca7fb13-8558-4b75-9d46-ae11d33ba702 2025-10-31 15:17:19,211 - DEBUG - Requesting: http://10.0.0.65:8082/instance-ip/24213015-9fa7-452c-b280-48194bf2df9e 2025-10-31 15:17:19,221 - DEBUG - Verifying in api server 10.0.0.65 2025-10-31 15:17:19,222 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/a48418d6-6cfe-409b-8910-60230b717356 2025-10-31 15:17:19,228 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine-interface/c461f496-e730-4ffe-bf65-8b5d1090202f 2025-10-31 15:17:19,238 - DEBUG - Requesting: http://10.0.0.65:8082/instance-ip/1ca7fb13-8558-4b75-9d46-ae11d33ba702 2025-10-31 15:17:19,249 - DEBUG - Requesting: http://10.0.0.65:8082/instance-ip/24213015-9fa7-452c-b280-48194bf2df9e 2025-10-31 15:17:19,258 - INFO - VM ctest-VM31-26571588 verfication in all API Servers passed 2025-10-31 15:17:19,274 - DEBUG - VM ctest-VM31-26571588 Tap interface: {'index': '4', 'name': 'tapc461f496-e7', 'uuid': 'c461f496-e730-4ffe-bf65-8b5d1090202f', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN3-65695210:ctest-VN3-65695210', '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': '32', 'l2_label': '36', 'vxlan_id': '17', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN3-65695210', 'vm_uuid': 'a48418d6-6cfe-409b-8910-60230b717356', 'vm_name': 'ctest-VM31-26571588', 'ip_addr': '104.70.19.195', 'mac_addr': '02:c4:61:f4:96:e7', '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-76625063:c461f496-e730-4ffe-bf65-8b5d1090202f', 'sg_uuid_list': ['5d99eec9-a822-477e-9506-c795efe21d2a'], 'static_route_list': None, 'vm_project_uuid': 'c7507b1f-4309-4180-9b1d-6c0668cbf321', 'admin_state': 'Enabled', 'flow_key_idx': '40', 'allowed_address_pair_list': None, 'ip6_addr': '3526:d21f:d3eb:9e5c:9282:36ae: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-10-31 15:17:19,275 - DEBUG - Agent 10.0.0.19 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN3-65695210:ctest-VN3-65695210 2025-10-31 15:17:19,279 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN3-65695210:ctest-VN3-65695210', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN3-65695210', 'table_label': '-1', 'vxlan_id': '17', 'evpnindex': '3', 'brindex': '3', 'mplsindex': '0', 'RD': '10.20.0.129:3', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2025-10-31 15:17:19,364 - DEBUG - VM ctest-VM31-26571588 : Tap interface tapc461f496-e7 is set to Active 2025-10-31 15:17:19,364 - DEBUG - Tap interface tapc461f496-e7 detail : {'index': '4', 'name': 'tapc461f496-e7', 'uuid': 'c461f496-e730-4ffe-bf65-8b5d1090202f', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN3-65695210:ctest-VN3-65695210', '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': '32', 'l2_label': '36', 'vxlan_id': '17', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN3-65695210', 'vm_uuid': 'a48418d6-6cfe-409b-8910-60230b717356', 'vm_name': 'ctest-VM31-26571588', 'ip_addr': '104.70.19.195', 'mac_addr': '02:c4:61:f4:96:e7', '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-76625063:c461f496-e730-4ffe-bf65-8b5d1090202f', 'sg_uuid_list': ['5d99eec9-a822-477e-9506-c795efe21d2a'], 'static_route_list': None, 'vm_project_uuid': 'c7507b1f-4309-4180-9b1d-6c0668cbf321', 'admin_state': 'Enabled', 'flow_key_idx': '40', 'allowed_address_pair_list': None, 'ip6_addr': '3526:d21f:d3eb:9e5c:9282:36ae: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-10-31 15:17:19,476 - DEBUG - Starting Layer 2 verification in Agent 2025-10-31 15:17:19,485 - DEBUG - Layer 2 path is seen for VM MAC 02:c4:61:f4:96:e7 in agent 10.0.0.19 2025-10-31 15:17:19,485 - DEBUG - Active layer 2 route in agent is present for VMI tapc461f496-e7 2025-10-31 15:17:19,485 - DEBUG - L2 label(36) matches bw route table and interface table 2025-10-31 15:17:19,491 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN3-65695210 is found in Agent of node 10.0.0.19 2025-10-31 15:17:19,631 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN3-65695210 is consistent in agent 10.0.0.19 2025-10-31 15:17:19,631 - DEBUG - Route for VM IP ['104.70.19.195', '3526:d21f:d3eb:9e5c:9282:36ae:0:3'] is consistent in agent 10.0.0.19 2025-10-31 15:17:19,631 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN3-65695210 verification for VM ctest-VM31-26571588 in Agent 10.0.0.19 passed 2025-10-31 15:17:19,749 - DEBUG - Starting all layer 2 verification in agent 10.0.0.19 2025-10-31 15:17:19,758 - DEBUG - Route for VM MAC 02:c4:61:f4:96:e7 is consistent in agent 10.0.0.19 2025-10-31 15:17:20,941 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=3.15 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=1.47 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 1.469/2.311/3.154/0.842 ms') 2025-10-31 15:17:20,941 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-26571588 passed 2025-10-31 15:17:20,941 - INFO - VM ctest-VM31-26571588 verifications in Compute nodes passed 2025-10-31 15:17:21,142 - DEBUG - Validated VM route 104.70.19.195 in vrouter of 10.0.0.19 2025-10-31 15:17:21,146 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0 2025-10-31 15:17:21,146 - INFO - Validated routes of VM ctest-VM31-26571588 in all vrouters 2025-10-31 15:17:21,650 - DEBUG - Starting all layer2 verification in 10.0.0.38 Control Node 2025-10-31 15:17:21,773 - DEBUG - Layer2 route found for VM MAC 02:c4:61:f4:96:e7 in Control-node 10.0.0.38 2025-10-31 15:17:21,773 - DEBUG - L2 Label for VM ctest-VM31-26571588 same between Control-node 10.0.0.38 and Agent, Expected: 36, Seen: 36 2025-10-31 15:17:21,888 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.38, not supported 2025-10-31 15:17:21,888 - DEBUG - Starting all layer2 verification in 10.0.0.249 Control Node 2025-10-31 15:17:22,012 - DEBUG - Layer2 route found for VM MAC 02:c4:61:f4:96:e7 in Control-node 10.0.0.249 2025-10-31 15:17:22,012 - DEBUG - L2 Label for VM ctest-VM31-26571588 same between Control-node 10.0.0.249 and Agent, Expected: 36, Seen: 36 2025-10-31 15:17:22,131 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.249, not supported 2025-10-31 15:17:22,131 - INFO - Verification in Control-nodes for VM ctest-VM31-26571588 passed 2025-10-31 15:17:22,131 - DEBUG - Verifying the vm in opserver 2025-10-31 15:17:22,131 - DEBUG - Verifying in collector 10.0.0.65 ... 2025-10-31 15:17:22,131 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-machine/a48418d6-6cfe-409b-8910-60230b717356?flat 2025-10-31 15:17:22,146 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-76625063:c461f496-e730-4ffe-bf65-8b5d1090202f?flat 2025-10-31 15:17:22,171 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-76625063:c461f496-e730-4ffe-bf65-8b5d1090202f?flat 2025-10-31 15:17:22,189 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-76625063:c461f496-e730-4ffe-bf65-8b5d1090202f?flat 2025-10-31 15:17:22,204 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-machine/a48418d6-6cfe-409b-8910-60230b717356?flat 2025-10-31 15:17:22,219 - DEBUG - Verifying vm in vn uve 2025-10-31 15:17:22,219 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-76625063:c461f496-e730-4ffe-bf65-8b5d1090202f?flat 2025-10-31 15:17:22,239 - DEBUG - VM uve shows interface as {'uuid': 'c461f496-e730-4ffe-bf65-8b5d1090202f', 'label': 32, 'mac_address': '02:c4:61:f4:96:e7', 'ip_address': '104.70.19.195', 'is_health_check_active': True, 'ip4_active': True, 'fixed_ip4_list': ['104.70.19.195'], 'rx_vlan': 65535, 'ip6_active': True, 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN3-65695210', '__T': 1761923792337009, 'admin_state': True, 'fixed_ip6_list': ['3526:d21f:d3eb:9e5c:9282:36ae:0:3'], 'l2_active': True, 'port_mirror_enabled': False, 'vhostuser_mode': 0, 'vm_uuid': 'a48418d6-6cfe-409b-8910-60230b717356', 'vn_uuid': 'c0ad0d4f-553f-4f24-b947-c1a3142e5ff2', 'ip6_address': '3526:d21f:d3eb:9e5c:9282:36ae:0:3', 'tx_vlan': 65535, 'active': True, 'gateway': '104.70.19.193', 'vm_name': 'ctest-VM31-26571588'} 2025-10-31 15:17:22,239 - DEBUG - VM uve shows ip address as ['104.70.19.195', '3526:d21f:d3eb:9e5c:9282:36ae:0:3'] 2025-10-31 15:17:22,239 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN3-65695210 2025-10-31 15:17:22,239 - INFO - Verifying through opserver in 10.0.0.38 2025-10-31 15:17:22,240 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN3-65695210?flat 2025-10-31 15:17:22,261 - DEBUG - expected vm list a48418d6-6cfe-409b-8910-60230b717356 2025-10-31 15:17:22,261 - DEBUG - Extracted vm list ['a48418d6-6cfe-409b-8910-60230b717356'] 2025-10-31 15:17:22,261 - INFO - VM a48418d6-6cfe-409b-8910-60230b717356 is present in default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN3-65695210 2025-10-31 15:17:22,261 - DEBUG - Verifying vm in vrouter uve 2025-10-31 15:17:22,261 - DEBUG - Getting info from collector 10.0.0.65.. 2025-10-31 15:17:22,261 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-machine/a48418d6-6cfe-409b-8910-60230b717356?flat 2025-10-31 15:17:22,276 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-4577-1'] 2025-10-31 15:17:22,276 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4577-1:Compute:contrail-vrouter-agent:0?flat 2025-10-31 15:17:22,310 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4577-1?flat 2025-10-31 15:17:22,350 - DEBUG - VM a48418d6-6cfe-409b-8910-60230b717356 is present in vrouter an-jenkins-deploy-platform-ansible-os-4577-1 uve 2025-10-31 15:17:22,350 - DEBUG - Validated that VM ctest-VM31-26571588 is in Vrouter an-jenkins-deploy-platform-ansible-os-4577-1 UVE 2025-10-31 15:17:22,350 - DEBUG - Verifying vm tap interface/vn in vrouter uve 2025-10-31 15:17:22,358 - DEBUG - Expected tap interface of VM uuid a48418d6-6cfe-409b-8910-60230b717356 is default-domain:ctest-TestPolicyAclIpv4v6-76625063:c461f496-e730-4ffe-bf65-8b5d1090202f 2025-10-31 15:17:22,358 - DEBUG - Expected VN of VM uuid a48418d6-6cfe-409b-8910-60230b717356 is default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN3-65695210 2025-10-31 15:17:22,358 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4577-1:Compute:contrail-vrouter-agent:0?flat 2025-10-31 15:17:22,396 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4577-1?flat 2025-10-31 15:17:22,429 - DEBUG - VM a48418d6-6cfe-409b-8910-60230b717356 is present in vrouter an-jenkins-deploy-platform-ansible-os-4577-1 uve 2025-10-31 15:17:22,430 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-76625063:c461f496-e730-4ffe-bf65-8b5d1090202f of vm a48418d6-6cfe-409b-8910-60230b717356 is present in vrouter an-jenkins-deploy-platform-ansible-os-4577-1 uve 2025-10-31 15:17:22,430 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN3-65695210 of vm a48418d6-6cfe-409b-8910-60230b717356 is present in vrouter an-jenkins-deploy-platform-ansible-os-4577-1 uve 2025-10-31 15:17:22,430 - DEBUG - Verifying the a48418d6-6cfe-409b-8910-60230b717356 virtual network link through opserver 10.0.0.38 2025-10-31 15:17:22,430 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines 2025-10-31 15:17:22,450 - DEBUG - VM link and name as {'name': 'a48418d6-6cfe-409b-8910-60230b717356', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-machine/a48418d6-6cfe-409b-8910-60230b717356?flat'} 2025-10-31 15:17:22,450 - DEBUG - Verifying the a48418d6-6cfe-409b-8910-60230b717356 virtual network link through opserver 10.0.0.249 2025-10-31 15:17:22,450 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machines 2025-10-31 15:17:22,472 - DEBUG - VM link and name as {'name': 'a48418d6-6cfe-409b-8910-60230b717356', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-machine/a48418d6-6cfe-409b-8910-60230b717356?flat'} 2025-10-31 15:17:22,472 - DEBUG - Verifying the a48418d6-6cfe-409b-8910-60230b717356 virtual network link through opserver 10.0.0.65 2025-10-31 15:17:22,472 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-machines 2025-10-31 15:17:22,487 - DEBUG - VM link and name as {'name': 'a48418d6-6cfe-409b-8910-60230b717356', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-machine/a48418d6-6cfe-409b-8910-60230b717356?flat'} 2025-10-31 15:17:22,488 - INFO - VM ctest-VM31-26571588 validations in Opserver passed 2025-10-31 15:17:22,488 - INFO - Waiting for VM ctest-VM11-11011959 to be up.. 2025-10-31 15:17:22,488 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-31 15:17:22,488 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/7e61e8dd-0198-433f-9df4-b756fad52c0f 2025-10-31 15:17:22,496 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine-interface/ee074cc6-e147-4218-85a2-4ab0ab0397b6 2025-10-31 15:17:23,653 - 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=11.5 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.481 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.481/5.984/11.487/5.503 ms') 2025-10-31 15:17:23,653 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-11011959 passed 2025-10-31 15:17:23,775 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-31 15:17:23,776 - DEBUG - Waiting to SSH to VM ctest-VM11-11011959, IP 222.153.98.131, Port 22 2025-10-31 15:17:23,843 - 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-10-31 15:17:23,935 - DEBUG - VM ctest-VM11-11011959 is NOT ready for SSH connections, VM status: ACTIVE 2025-10-31 15:17:28,935 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-31 15:17:28,936 - DEBUG - Waiting to SSH to VM ctest-VM11-11011959, IP 222.153.98.131, Port 22 2025-10-31 15:17:29,003 - 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-10-31 15:17:29,081 - DEBUG - VM ctest-VM11-11011959 is NOT ready for SSH connections, VM status: ACTIVE 2025-10-31 15:17:34,082 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-31 15:17:34,082 - DEBUG - Waiting to SSH to VM ctest-VM11-11011959, IP 222.153.98.131, Port 22 2025-10-31 15:17:34,247 - DEBUG - VM ctest-VM11-11011959 is ready for SSH connections 2025-10-31 15:17:34,247 - INFO - Waiting for VM ctest-VM21-59838410 to be up.. 2025-10-31 15:17:34,247 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-31 15:17:34,247 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a 2025-10-31 15:17:34,258 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine-interface/3bd4e14f-43f6-4b19-8595-229304684e4c 2025-10-31 15:17:35,444 - 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.59 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.56 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.561/3.074/3.588/0.513 ms') 2025-10-31 15:17:35,445 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-59838410 passed 2025-10-31 15:17:35,519 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-31 15:17:35,519 - DEBUG - Waiting to SSH to VM ctest-VM21-59838410, IP 105.164.1.67, Port 22 2025-10-31 15:17:35,586 - 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-10-31 15:17:35,676 - DEBUG - VM ctest-VM21-59838410 is NOT ready for SSH connections, VM status: ACTIVE 2025-10-31 15:17:40,677 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-31 15:17:40,677 - DEBUG - Waiting to SSH to VM ctest-VM21-59838410, IP 105.164.1.67, Port 22 2025-10-31 15:17:40,849 - DEBUG - VM ctest-VM21-59838410 is ready for SSH connections 2025-10-31 15:17:40,849 - INFO - Waiting for VM ctest-VM31-26571588 to be up.. 2025-10-31 15:17:40,849 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-31 15:17:40,850 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/a48418d6-6cfe-409b-8910-60230b717356 2025-10-31 15:17:40,858 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine-interface/c461f496-e730-4ffe-bf65-8b5d1090202f 2025-10-31 15:17:42,019 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=3.01 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.521 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.521/1.764/3.008/1.243 ms') 2025-10-31 15:17:42,019 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-26571588 passed 2025-10-31 15:17:42,091 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-31 15:17:42,091 - DEBUG - Waiting to SSH to VM ctest-VM31-26571588, IP 104.70.19.195, Port 22 2025-10-31 15:17:42,267 - DEBUG - VM ctest-VM31-26571588 is ready for SSH connections 2025-10-31 15:17:42,267 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 105.164.1.67, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.19, gateway password: c0ntrail123 2025-10-31 15:17:42,267 - DEBUG - ping -s 56 -c 3 -W 1 105.164.1.67 2025-10-31 15:17:55,778 - DEBUG - PING 105.164.1.67 (105.164.1.67) 56(84) bytes of data. 64 bytes from 105.164.1.67: icmp_req=1 ttl=63 time=21.6 ms 64 bytes from 105.164.1.67: icmp_req=2 ttl=63 time=1.96 ms 64 bytes from 105.164.1.67: icmp_req=3 ttl=63 time=3.26 ms --- 105.164.1.67 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2004ms rtt min/avg/max/mdev = 1.965/8.951/21.626/8.978 ms 2025-10-31 15:17:55,778 - INFO - Ping to IP 105.164.1.67 from VM ctest-VM11-11011959 passed 2025-10-31 15:17:55,779 - DEBUG - Running remote_cmd, Cmd : ping6 -s 56 -c 3 -W 1 30c4:b2aa:94a1:2bdf:835a:6d38:0:3, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.19, gateway password: c0ntrail123 2025-10-31 15:17:55,779 - DEBUG - ping6 -s 56 -c 3 -W 1 30c4:b2aa:94a1:2bdf:835a:6d38:0:3 2025-10-31 15:17:58,266 - DEBUG - PING 30c4:b2aa:94a1:2bdf:835a:6d38:0:3(30c4:b2aa:94a1:2bdf:835a:6d38:0:3) 56 data bytes 64 bytes from 30c4:b2aa:94a1:2bdf:835a:6d38:0:3: icmp_seq=1 ttl=64 time=7.80 ms 64 bytes from 30c4:b2aa:94a1:2bdf:835a:6d38:0:3: icmp_seq=2 ttl=64 time=3.07 ms 64 bytes from 30c4:b2aa:94a1:2bdf:835a:6d38:0:3: icmp_seq=3 ttl=64 time=1.65 ms --- 30c4:b2aa:94a1:2bdf:835a:6d38:0:3 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2004ms rtt min/avg/max/mdev = 1.659/4.176/7.800/2.627 ms 2025-10-31 15:17:58,266 - INFO - Ping to IP 30c4:b2aa:94a1:2bdf:835a:6d38:0:3 from VM ctest-VM11-11011959 passed 2025-10-31 15:17:58,267 - INFO - Test with src as policy and dst as any PASSED 2025-10-31 15:17:58,267 - INFO - Deleting VM ctest-VM31-26571588 2025-10-31 15:17:58,332 - DEBUG - Verifying in api server 10.0.0.65 2025-10-31 15:17:58,332 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/a48418d6-6cfe-409b-8910-60230b717356 2025-10-31 15:17:58,342 - DEBUG - VM ID a48418d6-6cfe-409b-8910-60230b717356 of VM ctest-VM31-26571588 is still found in API Server 2025-10-31 15:18:00,342 - DEBUG - Verifying in api server 10.0.0.65 2025-10-31 15:18:00,342 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/a48418d6-6cfe-409b-8910-60230b717356 2025-10-31 15:18:00,350 - DEBUG - Response Code: 404 2025-10-31 15:18:00,350 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/a48418d6-6cfe-409b-8910-60230b717356 2025-10-31 15:18:00,356 - DEBUG - Response Code: 404 2025-10-31 15:18:00,356 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/a48418d6-6cfe-409b-8910-60230b717356 2025-10-31 15:18:00,361 - DEBUG - Response Code: 404 2025-10-31 15:18:00,361 - INFO - VM ctest-VM31-26571588 is fully removed in API-Server 2025-10-31 15:18:00,518 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found 2025-10-31 15:18:00,569 - DEBUG - Validated that vrouter 10.0.0.19 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN3-65695210 2025-10-31 15:18:00,581 - DEBUG - Validated that vrouter 10.0.0.22 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN3-65695210 2025-10-31 15:18:00,581 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN3-65695210 2025-10-31 15:18:00,581 - INFO - VM ctest-VM31-26571588 is removed in Compute, and routes are removed in all compute nodes 2025-10-31 15:18:00,835 - INFO - Routes for VM ctest-VM31-26571588 is removed in all control-nodes 2025-10-31 15:18:00,835 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4577-1:Compute:contrail-vrouter-agent:0?flat 2025-10-31 15:18:00,873 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4577-1?flat 2025-10-31 15:18:00,908 - DEBUG - VM a48418d6-6cfe-409b-8910-60230b717356 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4577-1 uve 2025-10-31 15:18:00,908 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-31 15:18:00,908 - DEBUG - interface for vm a48418d6-6cfe-409b-8910-60230b717356 deleted from vrouter uve ... 2025-10-31 15:18:00,908 - DEBUG - Deleted interface not in error interface list ... 2025-10-31 15:18:03,909 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4577-1:Compute:contrail-vrouter-agent:0?flat 2025-10-31 15:18:03,944 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4577-1?flat 2025-10-31 15:18:03,977 - DEBUG - VM a48418d6-6cfe-409b-8910-60230b717356 is not present in vrouter an-jenkins-deploy-platform-ansible-os-4577-1 uve 2025-10-31 15:18:03,977 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-31 15:18:03,977 - DEBUG - interface for vm a48418d6-6cfe-409b-8910-60230b717356 deleted from vrouter uve ... 2025-10-31 15:18:03,977 - DEBUG - Deleted interface not in error interface list ... 2025-10-31 15:18:03,977 - DEBUG - Validated that VM a48418d6-6cfe-409b-8910-60230b717356 is removed in Vrouter UVE 2025-10-31 15:18:03,977 - DEBUG - Verifying through opserver in 10.0.0.38 2025-10-31 15:18:03,977 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN3-65695210?flat 2025-10-31 15:18:03,996 - DEBUG - a48418d6-6cfe-409b-8910-60230b717356 BM not in default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN3-65695210 uve 2025-10-31 15:18:03,996 - DEBUG - Validated that VM a48418d6-6cfe-409b-8910-60230b717356 is not present in the VN UVE 2025-10-31 15:18:03,996 - DEBUG - Verifying the a48418d6-6cfe-409b-8910-60230b717356 virtual network link through opserver 10.0.0.38 2025-10-31 15:18:03,996 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines 2025-10-31 15:18:04,009 - INFO - a48418d6-6cfe-409b-8910-60230b717356 vm uve deleted from opserver 2025-10-31 15:18:04,009 - DEBUG - Verifying the a48418d6-6cfe-409b-8910-60230b717356 virtual network link through opserver 10.0.0.249 2025-10-31 15:18:04,009 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machines 2025-10-31 15:18:04,025 - INFO - a48418d6-6cfe-409b-8910-60230b717356 vm uve deleted from opserver 2025-10-31 15:18:04,026 - DEBUG - Verifying the a48418d6-6cfe-409b-8910-60230b717356 virtual network link through opserver 10.0.0.65 2025-10-31 15:18:04,026 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-machines 2025-10-31 15:18:04,038 - INFO - a48418d6-6cfe-409b-8910-60230b717356 vm uve deleted from opserver 2025-10-31 15:18:04,038 - INFO - Validated that VM a48418d6-6cfe-409b-8910-60230b717356 is removed from Opserver 2025-10-31 15:18:04,038 - INFO - Deleting VM ctest-VM21-59838410 2025-10-31 15:18:04,115 - DEBUG - Verifying in api server 10.0.0.65 2025-10-31 15:18:04,115 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a 2025-10-31 15:18:04,125 - DEBUG - VM ID 124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a of VM ctest-VM21-59838410 is still found in API Server 2025-10-31 15:18:06,125 - DEBUG - Verifying in api server 10.0.0.65 2025-10-31 15:18:06,125 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a 2025-10-31 15:18:06,132 - DEBUG - Response Code: 404 2025-10-31 15:18:06,132 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a 2025-10-31 15:18:06,144 - DEBUG - Response Code: 404 2025-10-31 15:18:06,144 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a 2025-10-31 15:18:06,155 - DEBUG - Response Code: 404 2025-10-31 15:18:06,155 - INFO - VM ctest-VM21-59838410 is fully removed in API-Server 2025-10-31 15:18:06,310 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found 2025-10-31 15:18:06,357 - DEBUG - Validated that vrouter 10.0.0.19 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN2-17371718 2025-10-31 15:18:06,368 - DEBUG - Validated that vrouter 10.0.0.22 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN2-17371718 2025-10-31 15:18:06,368 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN2-17371718 2025-10-31 15:18:06,368 - INFO - VM ctest-VM21-59838410 is removed in Compute, and routes are removed in all compute nodes 2025-10-31 15:18:06,597 - INFO - Routes for VM ctest-VM21-59838410 is removed in all control-nodes 2025-10-31 15:18:06,597 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4577-2:Compute:contrail-vrouter-agent:0?flat 2025-10-31 15:18:06,633 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4577-2?flat 2025-10-31 15:18:06,664 - DEBUG - VM 124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a is still present in vrouter an-jenkins-deploy-platform-ansible-os-4577-2 uve 2025-10-31 15:18:06,664 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-31 15:18:06,664 - DEBUG - interface for vm 124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a deleted from vrouter uve ... 2025-10-31 15:18:06,664 - DEBUG - Deleted interface not in error interface list ... 2025-10-31 15:18:09,664 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4577-2:Compute:contrail-vrouter-agent:0?flat 2025-10-31 15:18:09,698 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4577-2?flat 2025-10-31 15:18:09,730 - DEBUG - VM 124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a is still present in vrouter an-jenkins-deploy-platform-ansible-os-4577-2 uve 2025-10-31 15:18:09,731 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-31 15:18:09,731 - DEBUG - interface for vm 124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a deleted from vrouter uve ... 2025-10-31 15:18:09,731 - DEBUG - Deleted interface not in error interface list ... 2025-10-31 15:18:12,731 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4577-2:Compute:contrail-vrouter-agent:0?flat 2025-10-31 15:18:12,767 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4577-2?flat 2025-10-31 15:18:12,803 - DEBUG - VM 124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a is still present in vrouter an-jenkins-deploy-platform-ansible-os-4577-2 uve 2025-10-31 15:18:12,803 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-31 15:18:12,803 - DEBUG - interface for vm 124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a deleted from vrouter uve ... 2025-10-31 15:18:12,803 - DEBUG - Deleted interface not in error interface list ... 2025-10-31 15:18:15,804 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4577-2:Compute:contrail-vrouter-agent:0?flat 2025-10-31 15:18:15,839 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4577-2?flat 2025-10-31 15:18:15,873 - DEBUG - VM 124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a is still present in vrouter an-jenkins-deploy-platform-ansible-os-4577-2 uve 2025-10-31 15:18:15,873 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-31 15:18:15,873 - DEBUG - interface for vm 124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a deleted from vrouter uve ... 2025-10-31 15:18:15,873 - DEBUG - Deleted interface not in error interface list ... 2025-10-31 15:18:18,874 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4577-2:Compute:contrail-vrouter-agent:0?flat 2025-10-31 15:18:18,923 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4577-2?flat 2025-10-31 15:18:18,960 - DEBUG - VM 124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a is still present in vrouter an-jenkins-deploy-platform-ansible-os-4577-2 uve 2025-10-31 15:18:18,960 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-31 15:18:18,960 - DEBUG - interface for vm 124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a deleted from vrouter uve ... 2025-10-31 15:18:18,960 - DEBUG - Deleted interface not in error interface list ... 2025-10-31 15:18:21,960 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4577-2:Compute:contrail-vrouter-agent:0?flat 2025-10-31 15:18:21,993 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4577-2?flat 2025-10-31 15:18:22,025 - DEBUG - VM 124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a is still present in vrouter an-jenkins-deploy-platform-ansible-os-4577-2 uve 2025-10-31 15:18:22,025 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-31 15:18:22,025 - DEBUG - interface for vm 124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a deleted from vrouter uve ... 2025-10-31 15:18:22,025 - DEBUG - Deleted interface not in error interface list ... 2025-10-31 15:18:25,026 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4577-2:Compute:contrail-vrouter-agent:0?flat 2025-10-31 15:18:25,060 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4577-2?flat 2025-10-31 15:18:25,093 - DEBUG - VM 124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a is still present in vrouter an-jenkins-deploy-platform-ansible-os-4577-2 uve 2025-10-31 15:18:25,093 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-31 15:18:25,093 - DEBUG - interface for vm 124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a deleted from vrouter uve ... 2025-10-31 15:18:25,093 - DEBUG - Deleted interface not in error interface list ... 2025-10-31 15:18:28,093 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4577-2:Compute:contrail-vrouter-agent:0?flat 2025-10-31 15:18:28,130 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4577-2?flat 2025-10-31 15:18:28,163 - DEBUG - VM 124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a is not present in vrouter an-jenkins-deploy-platform-ansible-os-4577-2 uve 2025-10-31 15:18:28,163 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-31 15:18:28,163 - DEBUG - interface for vm 124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a deleted from vrouter uve ... 2025-10-31 15:18:28,163 - DEBUG - Deleted interface not in error interface list ... 2025-10-31 15:18:28,163 - DEBUG - Validated that VM 124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a is removed in Vrouter UVE 2025-10-31 15:18:28,163 - DEBUG - Verifying through opserver in 10.0.0.38 2025-10-31 15:18:28,163 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN2-17371718?flat 2025-10-31 15:18:28,179 - DEBUG - 124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a BM not in default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN2-17371718 uve 2025-10-31 15:18:28,179 - DEBUG - Validated that VM 124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a is not present in the VN UVE 2025-10-31 15:18:28,179 - DEBUG - Verifying the 124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a virtual network link through opserver 10.0.0.38 2025-10-31 15:18:28,179 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines 2025-10-31 15:18:28,192 - INFO - 124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a vm uve deleted from opserver 2025-10-31 15:18:28,192 - DEBUG - Verifying the 124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a virtual network link through opserver 10.0.0.249 2025-10-31 15:18:28,192 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machines 2025-10-31 15:18:28,206 - INFO - 124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a vm uve deleted from opserver 2025-10-31 15:18:28,207 - DEBUG - Verifying the 124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a virtual network link through opserver 10.0.0.65 2025-10-31 15:18:28,207 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-machines 2025-10-31 15:18:28,221 - INFO - 124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a vm uve deleted from opserver 2025-10-31 15:18:28,221 - INFO - Validated that VM 124a64ef-a4ca-4f4e-8d93-98cee5cf6d0a is removed from Opserver 2025-10-31 15:18:28,221 - INFO - Deleting VM ctest-VM11-11011959 2025-10-31 15:18:28,284 - DEBUG - Verifying in api server 10.0.0.65 2025-10-31 15:18:28,284 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/7e61e8dd-0198-433f-9df4-b756fad52c0f 2025-10-31 15:18:28,294 - DEBUG - VM ID 7e61e8dd-0198-433f-9df4-b756fad52c0f of VM ctest-VM11-11011959 is still found in API Server 2025-10-31 15:18:30,296 - DEBUG - Verifying in api server 10.0.0.65 2025-10-31 15:18:30,296 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/7e61e8dd-0198-433f-9df4-b756fad52c0f 2025-10-31 15:18:30,302 - DEBUG - Response Code: 404 2025-10-31 15:18:30,303 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/7e61e8dd-0198-433f-9df4-b756fad52c0f 2025-10-31 15:18:30,309 - DEBUG - Response Code: 404 2025-10-31 15:18:30,309 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/7e61e8dd-0198-433f-9df4-b756fad52c0f 2025-10-31 15:18:30,316 - DEBUG - Response Code: 404 2025-10-31 15:18:30,316 - INFO - VM ctest-VM11-11011959 is fully removed in API-Server 2025-10-31 15:18:30,479 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found 2025-10-31 15:18:30,532 - DEBUG - Validated that vrouter 10.0.0.19 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207 2025-10-31 15:18:30,544 - DEBUG - Validated that vrouter 10.0.0.22 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207 2025-10-31 15:18:30,544 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207 2025-10-31 15:18:30,544 - INFO - VM ctest-VM11-11011959 is removed in Compute, and routes are removed in all compute nodes 2025-10-31 15:18:30,753 - INFO - Routes for VM ctest-VM11-11011959 is removed in all control-nodes 2025-10-31 15:18:30,753 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4577-1:Compute:contrail-vrouter-agent:0?flat 2025-10-31 15:18:30,801 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4577-1?flat 2025-10-31 15:18:30,840 - DEBUG - VM 7e61e8dd-0198-433f-9df4-b756fad52c0f is still present in vrouter an-jenkins-deploy-platform-ansible-os-4577-1 uve 2025-10-31 15:18:30,840 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-31 15:18:30,840 - DEBUG - interface for vm 7e61e8dd-0198-433f-9df4-b756fad52c0f deleted from vrouter uve ... 2025-10-31 15:18:30,840 - DEBUG - Deleted interface not in error interface list ... 2025-10-31 15:18:33,841 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4577-1:Compute:contrail-vrouter-agent:0?flat 2025-10-31 15:18:33,875 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4577-1?flat 2025-10-31 15:18:33,909 - DEBUG - VM 7e61e8dd-0198-433f-9df4-b756fad52c0f is not present in vrouter an-jenkins-deploy-platform-ansible-os-4577-1 uve 2025-10-31 15:18:33,909 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-31 15:18:33,909 - DEBUG - interface for vm 7e61e8dd-0198-433f-9df4-b756fad52c0f deleted from vrouter uve ... 2025-10-31 15:18:33,909 - DEBUG - Deleted interface not in error interface list ... 2025-10-31 15:18:33,909 - DEBUG - Validated that VM 7e61e8dd-0198-433f-9df4-b756fad52c0f is removed in Vrouter UVE 2025-10-31 15:18:33,909 - DEBUG - Verifying through opserver in 10.0.0.38 2025-10-31 15:18:33,909 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207?flat 2025-10-31 15:18:33,927 - DEBUG - 7e61e8dd-0198-433f-9df4-b756fad52c0f BM not in default-domain:ctest-TestPolicyAclIpv4v6-76625063:ctest-VN1-67879207 uve 2025-10-31 15:18:33,928 - DEBUG - Validated that VM 7e61e8dd-0198-433f-9df4-b756fad52c0f is not present in the VN UVE 2025-10-31 15:18:33,928 - DEBUG - Verifying the 7e61e8dd-0198-433f-9df4-b756fad52c0f virtual network link through opserver 10.0.0.38 2025-10-31 15:18:33,928 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines 2025-10-31 15:18:33,942 - DEBUG - No links retuned for 7e61e8dd-0198-433f-9df4-b756fad52c0f 2025-10-31 15:18:33,942 - INFO - 7e61e8dd-0198-433f-9df4-b756fad52c0f vm uve deleted from opserver 2025-10-31 15:18:33,942 - DEBUG - Verifying the 7e61e8dd-0198-433f-9df4-b756fad52c0f virtual network link through opserver 10.0.0.249 2025-10-31 15:18:33,942 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machines 2025-10-31 15:18:33,957 - DEBUG - No links retuned for 7e61e8dd-0198-433f-9df4-b756fad52c0f 2025-10-31 15:18:33,957 - INFO - 7e61e8dd-0198-433f-9df4-b756fad52c0f vm uve deleted from opserver 2025-10-31 15:18:33,957 - DEBUG - Verifying the 7e61e8dd-0198-433f-9df4-b756fad52c0f virtual network link through opserver 10.0.0.65 2025-10-31 15:18:33,957 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-machines 2025-10-31 15:18:33,970 - DEBUG - No links retuned for 7e61e8dd-0198-433f-9df4-b756fad52c0f 2025-10-31 15:18:33,970 - INFO - 7e61e8dd-0198-433f-9df4-b756fad52c0f vm uve deleted from opserver 2025-10-31 15:18:33,970 - INFO - Validated that VM 7e61e8dd-0198-433f-9df4-b756fad52c0f is removed from Opserver 2025-10-31 15:18:33,970 - DEBUG - Detaching the Policy for VN :ctest-VN2-17371718 2025-10-31 15:18:33,970 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-10-31 15:18:33,989 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69 2025-10-31 15:18:34,003 - DEBUG - Requesting: http://10.0.0.38:8082/project/c7507b1f-4309-4180-9b1d-6c0668cbf321 2025-10-31 15:18:34,094 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/01aa3232-f541-4ea1-82fe-baeba5b9b56a 2025-10-31 15:18:34,117 - DEBUG - Requesting: http://10.0.0.38:8082/network-policy/1517f0a2-c2e5-4687-be99-f81e74b5303d 2025-10-31 15:18:34,322 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-76625063', 'ctest-policy21-68570719']] from ctest-VN2-17371718 2025-10-31 15:18:34,322 - DEBUG - Detaching the Policy for VN :ctest-VN1-67879207 2025-10-31 15:18:34,322 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-10-31 15:18:34,332 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69 2025-10-31 15:18:34,347 - DEBUG - Requesting: http://10.0.0.38:8082/project/c7507b1f-4309-4180-9b1d-6c0668cbf321 2025-10-31 15:18:34,447 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/0f0f7495-035b-4110-8464-0d4f9caad6a8 2025-10-31 15:18:34,462 - DEBUG - Requesting: http://10.0.0.38:8082/network-policy/a63b39c7-ce8a-4fa0-aa0b-635b71b66d00 2025-10-31 15:18:34,474 - DEBUG - Requesting: http://10.0.0.38:8082/network-policy/12300a5f-f15b-4c80-a72f-3ea7a6793c8b 2025-10-31 15:18:34,685 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-76625063', 'ctest-policy12-78128838'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-76625063', 'ctest-policy13-23982635']] from ctest-VN1-67879207 2025-10-31 15:18:34,753 - INFO - Deleted policy ctest-policy21-68570719 2025-10-31 15:18:34,819 - INFO - Deleted policy ctest-policy12-78128838 2025-10-31 15:18:34,854 - INFO - Deleted policy ctest-policy13-23982635 2025-10-31 15:18:34,855 - INFO - Deleting VN ctest-VN3-65695210 2025-10-31 15:18:35,091 - DEBUG - Response for deleting network () 2025-10-31 15:18:35,091 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/38dd2e17-33a1-4cd7-8f48-4f3bbf1e2aba 2025-10-31 15:18:35,099 - DEBUG - Response Code: 404 2025-10-31 15:18:35,099 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-10-31 15:18:35,108 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69 2025-10-31 15:18:35,121 - DEBUG - Requesting: http://10.0.0.38:8082/project/c7507b1f-4309-4180-9b1d-6c0668cbf321 2025-10-31 15:18:35,205 - INFO - Validated that VN ctest-VN3-65695210 is not found in API Server 2025-10-31 15:18:35,215 - DEBUG - VN ctest-VN3-65695210 is not present in Agent 10.0.0.19 2025-10-31 15:18:35,229 - DEBUG - VN ctest-VN3-65695210 is not present in Agent 10.0.0.22 2025-10-31 15:18:35,229 - INFO - Validated that VN ctest-VN3-65695210 is not in any agent 2025-10-31 15:18:35,260 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN3-65695210 info 2025-10-31 15:18:35,260 - INFO - Deleting VN ctest-VN2-17371718 2025-10-31 15:18:35,455 - DEBUG - Response for deleting network () 2025-10-31 15:18:35,455 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/2e22dae1-25c5-4abe-ba49-bee6e961767c 2025-10-31 15:18:35,470 - DEBUG - Response Code: 404 2025-10-31 15:18:35,471 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-10-31 15:18:35,480 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69 2025-10-31 15:18:35,536 - DEBUG - Requesting: http://10.0.0.38:8082/project/c7507b1f-4309-4180-9b1d-6c0668cbf321 2025-10-31 15:18:35,638 - INFO - Validated that VN ctest-VN2-17371718 is not found in API Server 2025-10-31 15:18:35,649 - DEBUG - VN ctest-VN2-17371718 is not present in Agent 10.0.0.19 2025-10-31 15:18:35,660 - DEBUG - VN ctest-VN2-17371718 is not present in Agent 10.0.0.22 2025-10-31 15:18:35,660 - INFO - Validated that VN ctest-VN2-17371718 is not in any agent 2025-10-31 15:18:35,693 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN2-17371718 info 2025-10-31 15:18:35,694 - INFO - Deleting VN ctest-VN1-67879207 2025-10-31 15:18:35,930 - DEBUG - Response for deleting network () 2025-10-31 15:18:35,930 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/ff9d01f5-ebf9-423f-b8fb-69eb648c3212 2025-10-31 15:18:35,936 - DEBUG - Response Code: 404 2025-10-31 15:18:35,936 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-10-31 15:18:35,943 - DEBUG - Requesting: http://10.0.0.38:8082/domain/84461aec-f8f2-4c85-873b-ef5843450b69 2025-10-31 15:18:35,954 - DEBUG - Requesting: http://10.0.0.38:8082/project/c7507b1f-4309-4180-9b1d-6c0668cbf321 2025-10-31 15:18:36,042 - INFO - Validated that VN ctest-VN1-67879207 is not found in API Server 2025-10-31 15:18:36,055 - DEBUG - VN ctest-VN1-67879207 is not present in Agent 10.0.0.19 2025-10-31 15:18:36,067 - DEBUG - VN ctest-VN1-67879207 is not present in Agent 10.0.0.22 2025-10-31 15:18:36,067 - INFO - Validated that VN ctest-VN1-67879207 is not in any agent 2025-10-31 15:18:36,102 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN1-67879207 info 2025-10-31 15:18:36,202 - INFO - IPAM: ipam3 is not found in API Server 2025-10-31 15:18:36,227 - INFO - IPAM:ipam3 is not found in control node 2025-10-31 15:18:36,354 - INFO - IPAM: ipam2 is not found in API Server 2025-10-31 15:18:36,377 - INFO - IPAM:ipam2 is not found in control node 2025-10-31 15:18:36,534 - INFO - IPAM: ipam1 is not found in API Server 2025-10-31 15:18:36,566 - INFO - IPAM:ipam1 is not found in control node 2025-10-31 15:18:37,875 - DEBUG - No XMPP flaps were noticed during the test

2025-10-31 15:18:37,875 - INFO - END TEST : test_policy_inheritance_src_pol_dst_any : PASSED[0:02:25]

2025-10-31 15:18:37,875 - INFO - -------------------------------------------------------------------------------- 2025-10-31 15:18:39,207 - DEBUG - No XMPP flaps were noticed during the test

2025-10-31 15:18:39,207 - INFO - END TEST : test_policy_inheritance_src_pol_dst_any : PASSED[0:02:28]

2025-10-31 15:18:39,207 - INFO - -------------------------------------------------------------------------------- 2025-10-31 15:18:40,033 - INFO - Deleted project: ctest-TestPolicyAclIpv4v6-76625063, ID : c7507b1f-4309-4180-9b1d-6c0668cbf321