2026-04-20 02:39:10,137 - INFO - Domain Default found not creating 2026-04-20 02:39:10,307 - INFO - Project ctest-TestPolicyAclIpv4v6-81143902 not found, creating it 2026-04-20 02:39:10,813 - INFO - Created Project:ctest-TestPolicyAclIpv4v6-81143902, ID : 74fa0b95-9fe9-45ff-8c77-4ba128239b24 2026-04-20 02:39:12,298 - INFO - ================================================================================ 2026-04-20 02:39:12,299 - INFO - STARTING TEST : test_policy_inheritance_src_pol_dst_any 2026-04-20 02:39:13,581 - DEBUG - Nothing to compare xmpp stats {'10.0.0.240': {'10.20.0.129': '0', '10.20.0.25': '0'}, '10.0.0.65': {'10.20.0.25': '0', '10.20.0.129': '0'}} with 2026-04-20 02:39:13,581 - INFO - Initial checks done. Running the testcase now 2026-04-20 02:39:13,581 - INFO - 2026-04-20 02:39:13,581 - INFO - ================================================================================ 2026-04-20 02:39:13,581 - INFO - STARTING TEST : test_policy_inheritance_src_pol_dst_any 2026-04-20 02:39:13,581 - INFO - TEST DESCRIPTION : Test cases to test policy inheritance 2026-04-20 02:39:14,844 - DEBUG - Nothing to compare xmpp stats {'10.0.0.240': {'10.20.0.129': '0', '10.20.0.25': '0'}, '10.0.0.65': {'10.20.0.25': '0', '10.20.0.129': '0'}} with 2026-04-20 02:39:14,844 - INFO - Initial checks done. Running the testcase now 2026-04-20 02:39:14,844 - INFO - 2026-04-20 02:39:16,212 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2026-04-20 02:39:16,524 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2026-04-20 02:39:16,562 - DEBUG - Requesting: http://10.0.0.38:8082/domain/c2b9cdf0-ff50-48aa-bc48-322f89577022 2026-04-20 02:39:16,577 - DEBUG - Requesting: http://10.0.0.38:8082/project/74fa0b95-9fe9-45ff-8c77-4ba128239b24 2026-04-20 02:39:16,674 - DEBUG - Requesting: http://10.0.0.38:8082/network-ipam/78bb5601-05fa-4cac-9507-eea92fcd563f 2026-04-20 02:39:16,683 - INFO - Verifications in API Server for IPAM: ipam1 passed 2026-04-20 02:39:16,690 - DEBUG - Control-node 10.0.0.38 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-81143902:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-81143902', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8699641667591490732', 'uuid-lslong': '10738814246907106879'}, 'enable': 'true', 'created': '2026-04-20T02:39:15', 'last-modified': '2026-04-20T02:39:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.205964'} 2026-04-20 02:39:16,698 - DEBUG - Control-node 10.0.0.27 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-81143902:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-81143902', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8699641667591490732', 'uuid-lslong': '10738814246907106879'}, 'enable': 'true', 'created': '2026-04-20T02:39:15', 'last-modified': '2026-04-20T02:39:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.215221'} 2026-04-20 02:39:16,705 - DEBUG - Control-node 10.0.0.23 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-81143902:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-81143902', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8699641667591490732', 'uuid-lslong': '10738814246907106879'}, 'enable': 'true', 'created': '2026-04-20T02:39:15', 'last-modified': '2026-04-20T02:39:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.219145'} 2026-04-20 02:39:16,705 - INFO - Verifications in Control node for IPAM: ipam1 passed 2026-04-20 02:39:16,705 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2026-04-20 02:39:16,711 - DEBUG - Requesting: http://10.0.0.38:8082/domain/c2b9cdf0-ff50-48aa-bc48-322f89577022 2026-04-20 02:39:16,723 - DEBUG - Requesting: http://10.0.0.38:8082/project/74fa0b95-9fe9-45ff-8c77-4ba128239b24 2026-04-20 02:39:16,818 - DEBUG - Requesting: http://10.0.0.38:8082/network-ipam/fb1b355f-0a44-4d3a-9210-ed7b7a7f91c7 2026-04-20 02:39:16,828 - INFO - Verifications in API Server for IPAM: ipam2 passed 2026-04-20 02:39:16,833 - DEBUG - Control-node 10.0.0.38 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-81143902:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-81143902', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '18094114610201513274', 'uuid-lslong': '10525173443756790215'}, 'enable': 'true', 'created': '2026-04-20T02:39:15', 'last-modified': '2026-04-20T02:39:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.980189'} 2026-04-20 02:39:16,839 - DEBUG - Control-node 10.0.0.27 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-81143902:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-81143902', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '18094114610201513274', 'uuid-lslong': '10525173443756790215'}, 'enable': 'true', 'created': '2026-04-20T02:39:15', 'last-modified': '2026-04-20T02:39:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.985766'} 2026-04-20 02:39:16,843 - DEBUG - Control-node 10.0.0.23 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-81143902:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-81143902', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '18094114610201513274', 'uuid-lslong': '10525173443756790215'}, 'enable': 'true', 'created': '2026-04-20T02:39:15', 'last-modified': '2026-04-20T02:39:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.988390'} 2026-04-20 02:39:16,843 - INFO - Verifications in Control node for IPAM: ipam2 passed 2026-04-20 02:39:16,843 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2026-04-20 02:39:16,857 - DEBUG - Requesting: http://10.0.0.38:8082/domain/c2b9cdf0-ff50-48aa-bc48-322f89577022 2026-04-20 02:39:16,869 - DEBUG - Requesting: http://10.0.0.38:8082/project/74fa0b95-9fe9-45ff-8c77-4ba128239b24 2026-04-20 02:39:16,978 - DEBUG - Requesting: http://10.0.0.38:8082/network-ipam/65d00412-dfbc-4266-bd50-bb6304f75bcb 2026-04-20 02:39:16,993 - INFO - Verifications in API Server for IPAM: ipam3 passed 2026-04-20 02:39:17,001 - DEBUG - Control-node 10.0.0.38 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-81143902:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-81143902', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7336368272096117350', 'uuid-lslong': '13641609305264708555'}, 'enable': 'true', 'created': '2026-04-20T02:39:15', 'last-modified': '2026-04-20T02:39:16', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.814523'} 2026-04-20 02:39:17,009 - DEBUG - Control-node 10.0.0.27 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-81143902:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-81143902', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7336368272096117350', 'uuid-lslong': '13641609305264708555'}, 'enable': 'true', 'created': '2026-04-20T02:39:15', 'last-modified': '2026-04-20T02:39:16', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.824008'} 2026-04-20 02:39:17,014 - DEBUG - Control-node 10.0.0.23 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-81143902:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-81143902', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7336368272096117350', 'uuid-lslong': '13641609305264708555'}, 'enable': 'true', 'created': '2026-04-20T02:39:15', 'last-modified': '2026-04-20T02:39:16', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.827598'} 2026-04-20 02:39:17,014 - INFO - Verifications in Control node for IPAM: ipam3 passed 2026-04-20 02:39:17,347 - DEBUG - Response for create_network : {'network': {'id': '07a9315c-1913-42fa-8f3d-3a76f2077130', 'name': 'ctest-VN1-62804697', 'tenant_id': '74fa0b959fe945ff8c774ba128239b24', 'project_id': '74fa0b959fe945ff8c774ba128239b24', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-81143902', 'ctest-VN1-62804697'], 'port_security_enabled': True, 'description': ''}} 2026-04-20 02:39:17,634 - DEBUG - Response for create_subnet : {'subnet': {'id': 'dd6d701e-730b-44ea-bcf2-9732558c24f3', 'name': '', 'tenant_id': '74fa0b959fe945ff8c774ba128239b24', 'network_id': '07a9315c-1913-42fa-8f3d-3a76f2077130', 'ip_version': 4, 'cidr': '16.62.119.128/26', 'allocation_pools': [{'start': '16.62.119.130', 'end': '16.62.119.190'}], 'gateway_ip': '16.62.119.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '16.62.119.130', 'tags': [], 'project_id': '74fa0b959fe945ff8c774ba128239b24'}} 2026-04-20 02:39:17,855 - DEBUG - Response for create_subnet : {'subnet': {'id': '4a3ed2af-208c-4899-b47a-188445084fd2', 'name': '', 'tenant_id': '74fa0b959fe945ff8c774ba128239b24', 'network_id': '07a9315c-1913-42fa-8f3d-3a76f2077130', 'ip_version': 6, 'cidr': '31cd:e74:1cd1:bf4e:7872:db94::/96', 'allocation_pools': [{'start': '31cd:e74:1cd1:bf4e:7872:db94:0:2', 'end': '31cd:e74:1cd1:bf4e:7872:db94:ffff:fffe'}], 'gateway_ip': '31cd:e74:1cd1:bf4e:7872:db94:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '31cd:e74:1cd1:bf4e:7872:db94:0:2', 'tags': [], 'project_id': '74fa0b959fe945ff8c774ba128239b24'}} 2026-04-20 02:39:17,881 - INFO - Created VN ctest-VN1-62804697 2026-04-20 02:39:17,894 - DEBUG - VN ctest-VN1-62804697 UUID is 07a9315c-1913-42fa-8f3d-3a76f2077130 2026-04-20 02:39:18,076 - DEBUG - Fetched VN: default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697(07a9315c-1913-42fa-8f3d-3a76f2077130) with subnets ['16.62.119.128/26', '31cd:e74:1cd1:bf4e:7872:db94::/96'] 2026-04-20 02:39:18,388 - DEBUG - Response for create_network : {'network': {'id': 'b1d22b67-b338-4f45-9248-f60cdae21814', 'name': 'ctest-VN2-94744319', 'tenant_id': '74fa0b959fe945ff8c774ba128239b24', 'project_id': '74fa0b959fe945ff8c774ba128239b24', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-81143902', 'ctest-VN2-94744319'], 'port_security_enabled': True, 'description': ''}} 2026-04-20 02:39:18,622 - DEBUG - Response for create_subnet : {'subnet': {'id': '4716dd85-592e-4787-a4ca-6c45b79fc8e4', 'name': '', 'tenant_id': '74fa0b959fe945ff8c774ba128239b24', 'network_id': 'b1d22b67-b338-4f45-9248-f60cdae21814', 'ip_version': 4, 'cidr': '68.130.99.0/26', 'allocation_pools': [{'start': '68.130.99.2', 'end': '68.130.99.62'}], 'gateway_ip': '68.130.99.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '68.130.99.2', 'tags': [], 'project_id': '74fa0b959fe945ff8c774ba128239b24'}} 2026-04-20 02:39:18,862 - DEBUG - Response for create_subnet : {'subnet': {'id': 'a375d621-d8bd-4d4e-a738-54a18a9fde37', 'name': '', 'tenant_id': '74fa0b959fe945ff8c774ba128239b24', 'network_id': 'b1d22b67-b338-4f45-9248-f60cdae21814', 'ip_version': 6, 'cidr': '3282:eca2:2a1c:751:e0f:9b19::/96', 'allocation_pools': [{'start': '3282:eca2:2a1c:751:e0f:9b19:0:2', 'end': '3282:eca2:2a1c:751:e0f:9b19:ffff:fffe'}], 'gateway_ip': '3282:eca2:2a1c:751:e0f:9b19:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '3282:eca2:2a1c:751:e0f:9b19:0:2', 'tags': [], 'project_id': '74fa0b959fe945ff8c774ba128239b24'}} 2026-04-20 02:39:18,889 - INFO - Created VN ctest-VN2-94744319 2026-04-20 02:39:18,898 - DEBUG - VN ctest-VN2-94744319 UUID is b1d22b67-b338-4f45-9248-f60cdae21814 2026-04-20 02:39:19,245 - DEBUG - Response for create_network : {'network': {'id': 'd7b8362b-9de4-4e7d-8b55-c452b172952e', 'name': 'ctest-VN3-05460423', 'tenant_id': '74fa0b959fe945ff8c774ba128239b24', 'project_id': '74fa0b959fe945ff8c774ba128239b24', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-81143902', 'ctest-VN3-05460423'], 'port_security_enabled': True, 'description': ''}} 2026-04-20 02:39:19,542 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e94c3b85-068e-48cc-9fc0-bf40af38221c', 'name': '', 'tenant_id': '74fa0b959fe945ff8c774ba128239b24', 'network_id': 'd7b8362b-9de4-4e7d-8b55-c452b172952e', 'ip_version': 4, 'cidr': '164.20.211.0/26', 'allocation_pools': [{'start': '164.20.211.2', 'end': '164.20.211.62'}], 'gateway_ip': '164.20.211.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '164.20.211.2', 'tags': [], 'project_id': '74fa0b959fe945ff8c774ba128239b24'}} 2026-04-20 02:39:19,802 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e8816f78-40f6-419b-952d-269659477541', 'name': '', 'tenant_id': '74fa0b959fe945ff8c774ba128239b24', 'network_id': 'd7b8362b-9de4-4e7d-8b55-c452b172952e', 'ip_version': 6, 'cidr': '214a:48c0:2f1e:7a12:5c18:d0b4::/96', 'allocation_pools': [{'start': '214a:48c0:2f1e:7a12:5c18:d0b4:0:2', 'end': '214a:48c0:2f1e:7a12:5c18:d0b4:ffff:fffe'}], 'gateway_ip': '214a:48c0:2f1e:7a12:5c18:d0b4:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '214a:48c0:2f1e:7a12:5c18:d0b4:0:2', 'tags': [], 'project_id': '74fa0b959fe945ff8c774ba128239b24'}} 2026-04-20 02:39:19,825 - INFO - Created VN ctest-VN3-05460423 2026-04-20 02:39:19,835 - DEBUG - VN ctest-VN3-05460423 UUID is d7b8362b-9de4-4e7d-8b55-c452b172952e 2026-04-20 02:39:19,937 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2026-04-20 02:39:19,943 - DEBUG - Requesting: http://10.0.0.38:8082/domain/c2b9cdf0-ff50-48aa-bc48-322f89577022 2026-04-20 02:39:19,953 - DEBUG - Requesting: http://10.0.0.38:8082/project/74fa0b95-9fe9-45ff-8c77-4ba128239b24 2026-04-20 02:39:20,044 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/07a9315c-1913-42fa-8f3d-3a76f2077130 2026-04-20 02:39:20,057 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/07a9315c-1913-42fa-8f3d-3a76f2077130 2026-04-20 02:39:20,068 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/d6cafa62-2493-4744-9216-9b845c45af88 2026-04-20 02:39:20,075 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/d6cafa62-2493-4744-9216-9b845c45af88 2026-04-20 02:39:20,082 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/e455d943-26f5-4931-9b49-2447bb530eb7 2026-04-20 02:39:20,089 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-04-20 02:39:20,089 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/07a9315c-1913-42fa-8f3d-3a76f2077130 2026-04-20 02:39:20,098 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/d6cafa62-2493-4744-9216-9b845c45af88 2026-04-20 02:39:20,114 - INFO - Verified VN network id 15 for VN 07a9315c-1913-42fa-8f3d-3a76f2077130 2026-04-20 02:39:20,114 - INFO - Verifications in API Server for VN ctest-VN1-62804697 passed 2026-04-20 02:39:20,114 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/07a9315c-1913-42fa-8f3d-3a76f2077130 2026-04-20 02:39:20,127 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/d6cafa62-2493-4744-9216-9b845c45af88 2026-04-20 02:39:20,133 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/d6cafa62-2493-4744-9216-9b845c45af88 2026-04-20 02:39:20,139 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/e455d943-26f5-4931-9b49-2447bb530eb7 2026-04-20 02:39:20,151 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-81143902', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '552026700957041402', 'uuid-lslong': '10321470203544170800'}, 'enable': 'true', 'created': '2026-04-20T02:39:17', 'last-modified': '2026-04-20T02:39:17', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.369779'} 2026-04-20 02:39:20,156 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-04-20 02:39:20,161 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-81143902', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '552026700957041402', 'uuid-lslong': '10321470203544170800'}, 'enable': 'true', 'created': '2026-04-20T02:39:17', 'last-modified': '2026-04-20T02:39:17', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.376174'} 2026-04-20 02:39:20,167 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-04-20 02:39:20,172 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-81143902', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '552026700957041402', 'uuid-lslong': '10321470203544170800'}, 'enable': 'true', 'created': '2026-04-20T02:39:17', 'last-modified': '2026-04-20T02:39:17', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.389235'} 2026-04-20 02:39:20,176 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-04-20 02:39:20,176 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN1-62804697 passed 2026-04-20 02:39:20,177 - DEBUG - ====Verifying policy data for ctest-VN1-62804697 in API_Server ====== 2026-04-20 02:39:20,177 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2026-04-20 02:39:20,183 - DEBUG - Requesting: http://10.0.0.38:8082/domain/c2b9cdf0-ff50-48aa-bc48-322f89577022 2026-04-20 02:39:20,193 - DEBUG - Requesting: http://10.0.0.38:8082/project/74fa0b95-9fe9-45ff-8c77-4ba128239b24 2026-04-20 02:39:20,280 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/07a9315c-1913-42fa-8f3d-3a76f2077130 2026-04-20 02:39:20,290 - DEBUG - =>VN ctest-VN1-62804697 has no policy to be verified 2026-04-20 02:39:20,290 - DEBUG - Verifying the vn in opserver 2026-04-20 02:39:20,290 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697 virtual network link through opserver 10.0.0.38 2026-04-20 02:39:20,290 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2026-04-20 02:39:20,586 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2026-04-20 02:39:20,648 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697?flat'} 2026-04-20 02:39:20,648 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697 virtual network link through opserver 10.0.0.27 2026-04-20 02:39:20,648 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks 2026-04-20 02:39:20,957 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks 2026-04-20 02:39:21,022 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697?flat'} 2026-04-20 02:39:21,022 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697 virtual network link through opserver 10.0.0.23 2026-04-20 02:39:21,022 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks 2026-04-20 02:39:21,327 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks 2026-04-20 02:39:21,384 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697?flat'} 2026-04-20 02:39:21,384 - INFO - Validated that VN default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697 is found in opserver 2026-04-20 02:39:21,405 - DEBUG - Do not have enough data to verify VN in agent 2026-04-20 02:39:21,414 - DEBUG - VRF ids for VN ctest-VN1-62804697: {} 2026-04-20 02:39:21,414 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2026-04-20 02:39:21,419 - DEBUG - Requesting: http://10.0.0.38:8082/domain/c2b9cdf0-ff50-48aa-bc48-322f89577022 2026-04-20 02:39:21,430 - DEBUG - Requesting: http://10.0.0.38:8082/project/74fa0b95-9fe9-45ff-8c77-4ba128239b24 2026-04-20 02:39:21,516 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/b1d22b67-b338-4f45-9248-f60cdae21814 2026-04-20 02:39:21,527 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/b1d22b67-b338-4f45-9248-f60cdae21814 2026-04-20 02:39:21,538 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/078e0bdf-99b0-480a-821c-b2fdfabddf1a 2026-04-20 02:39:21,545 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/078e0bdf-99b0-480a-821c-b2fdfabddf1a 2026-04-20 02:39:21,551 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/d175695b-5d9d-4d57-9197-98ab71b3c4e6 2026-04-20 02:39:21,557 - DEBUG - Route Targets: ['target:64512:8000006'] 2026-04-20 02:39:21,557 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/b1d22b67-b338-4f45-9248-f60cdae21814 2026-04-20 02:39:21,565 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/078e0bdf-99b0-480a-821c-b2fdfabddf1a 2026-04-20 02:39:21,580 - INFO - Verified VN network id 16 for VN b1d22b67-b338-4f45-9248-f60cdae21814 2026-04-20 02:39:21,580 - INFO - Verifications in API Server for VN ctest-VN2-94744319 passed 2026-04-20 02:39:21,580 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/b1d22b67-b338-4f45-9248-f60cdae21814 2026-04-20 02:39:21,590 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/078e0bdf-99b0-480a-821c-b2fdfabddf1a 2026-04-20 02:39:21,596 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/078e0bdf-99b0-480a-821c-b2fdfabddf1a 2026-04-20 02:39:21,602 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/d175695b-5d9d-4d57-9197-98ab71b3c4e6 2026-04-20 02:39:21,613 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN2-94744319', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-81143902', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12813351614210920261', 'uuid-lslong': '10540945462932936724'}, 'enable': 'true', 'created': '2026-04-20T02:39:18', 'last-modified': '2026-04-20T02:39:18', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.816046'} 2026-04-20 02:39:21,618 - DEBUG - Route Targets: ['target:64512:8000006'] 2026-04-20 02:39:21,624 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN2-94744319', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-81143902', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12813351614210920261', 'uuid-lslong': '10540945462932936724'}, 'enable': 'true', 'created': '2026-04-20T02:39:18', 'last-modified': '2026-04-20T02:39:18', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.825444'} 2026-04-20 02:39:21,630 - DEBUG - Route Targets: ['target:64512:8000006'] 2026-04-20 02:39:21,635 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN2-94744319', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-81143902', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12813351614210920261', 'uuid-lslong': '10540945462932936724'}, 'enable': 'true', 'created': '2026-04-20T02:39:18', 'last-modified': '2026-04-20T02:39:18', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.826421'} 2026-04-20 02:39:21,639 - DEBUG - Route Targets: ['target:64512:8000006'] 2026-04-20 02:39:21,640 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN2-94744319 passed 2026-04-20 02:39:21,640 - DEBUG - ====Verifying policy data for ctest-VN2-94744319 in API_Server ====== 2026-04-20 02:39:21,640 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2026-04-20 02:39:21,645 - DEBUG - Requesting: http://10.0.0.38:8082/domain/c2b9cdf0-ff50-48aa-bc48-322f89577022 2026-04-20 02:39:21,655 - DEBUG - Requesting: http://10.0.0.38:8082/project/74fa0b95-9fe9-45ff-8c77-4ba128239b24 2026-04-20 02:39:21,739 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/b1d22b67-b338-4f45-9248-f60cdae21814 2026-04-20 02:39:21,749 - DEBUG - =>VN ctest-VN2-94744319 has no policy to be verified 2026-04-20 02:39:21,749 - DEBUG - Verifying the vn in opserver 2026-04-20 02:39:21,749 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN2-94744319 virtual network link through opserver 10.0.0.38 2026-04-20 02:39:21,749 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2026-04-20 02:39:21,805 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN2-94744319', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN2-94744319?flat'} 2026-04-20 02:39:21,806 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN2-94744319 virtual network link through opserver 10.0.0.27 2026-04-20 02:39:21,806 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks 2026-04-20 02:39:21,859 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN2-94744319', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN2-94744319?flat'} 2026-04-20 02:39:21,859 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN2-94744319 virtual network link through opserver 10.0.0.23 2026-04-20 02:39:21,859 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks 2026-04-20 02:39:21,915 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN2-94744319', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN2-94744319?flat'} 2026-04-20 02:39:21,915 - INFO - Validated that VN default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN2-94744319 is found in opserver 2026-04-20 02:39:21,937 - DEBUG - Do not have enough data to verify VN in agent 2026-04-20 02:39:21,950 - DEBUG - VRF ids for VN ctest-VN2-94744319: {} 2026-04-20 02:39:21,950 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2026-04-20 02:39:21,957 - DEBUG - Requesting: http://10.0.0.38:8082/domain/c2b9cdf0-ff50-48aa-bc48-322f89577022 2026-04-20 02:39:21,969 - DEBUG - Requesting: http://10.0.0.38:8082/project/74fa0b95-9fe9-45ff-8c77-4ba128239b24 2026-04-20 02:39:22,065 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/d7b8362b-9de4-4e7d-8b55-c452b172952e 2026-04-20 02:39:22,077 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/d7b8362b-9de4-4e7d-8b55-c452b172952e 2026-04-20 02:39:22,087 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/a27da738-fed2-473c-ad20-91a2964c8ca6 2026-04-20 02:39:22,094 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/a27da738-fed2-473c-ad20-91a2964c8ca6 2026-04-20 02:39:22,103 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/996cb4a5-c8e5-4b8a-a5b8-bc497bfcff1d 2026-04-20 02:39:22,110 - DEBUG - Route Targets: ['target:64512:8000007'] 2026-04-20 02:39:22,110 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/d7b8362b-9de4-4e7d-8b55-c452b172952e 2026-04-20 02:39:22,120 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/a27da738-fed2-473c-ad20-91a2964c8ca6 2026-04-20 02:39:22,134 - INFO - Verified VN network id 17 for VN d7b8362b-9de4-4e7d-8b55-c452b172952e 2026-04-20 02:39:22,134 - INFO - Verifications in API Server for VN ctest-VN3-05460423 passed 2026-04-20 02:39:22,134 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/d7b8362b-9de4-4e7d-8b55-c452b172952e 2026-04-20 02:39:22,144 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/a27da738-fed2-473c-ad20-91a2964c8ca6 2026-04-20 02:39:22,150 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/a27da738-fed2-473c-ad20-91a2964c8ca6 2026-04-20 02:39:22,156 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/996cb4a5-c8e5-4b8a-a5b8-bc497bfcff1d 2026-04-20 02:39:22,168 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN3-05460423', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-81143902', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15544233674829745789', 'uuid-lslong': '10040146803735827758'}, 'enable': 'true', 'created': '2026-04-20T02:39:19', 'last-modified': '2026-04-20T02:39:19', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.418463'} 2026-04-20 02:39:22,173 - DEBUG - Route Targets: ['target:64512:8000007'] 2026-04-20 02:39:22,179 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN3-05460423', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-81143902', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15544233674829745789', 'uuid-lslong': '10040146803735827758'}, 'enable': 'true', 'created': '2026-04-20T02:39:19', 'last-modified': '2026-04-20T02:39:19', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.435962'} 2026-04-20 02:39:22,186 - DEBUG - Route Targets: ['target:64512:8000007'] 2026-04-20 02:39:22,191 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN3-05460423', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-81143902', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15544233674829745789', 'uuid-lslong': '10040146803735827758'}, 'enable': 'true', 'created': '2026-04-20T02:39:19', 'last-modified': '2026-04-20T02:39:19', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.449207'} 2026-04-20 02:39:22,195 - DEBUG - Route Targets: ['target:64512:8000007'] 2026-04-20 02:39:22,196 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN3-05460423 passed 2026-04-20 02:39:22,196 - DEBUG - ====Verifying policy data for ctest-VN3-05460423 in API_Server ====== 2026-04-20 02:39:22,196 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2026-04-20 02:39:22,201 - DEBUG - Requesting: http://10.0.0.38:8082/domain/c2b9cdf0-ff50-48aa-bc48-322f89577022 2026-04-20 02:39:22,224 - DEBUG - Requesting: http://10.0.0.38:8082/project/74fa0b95-9fe9-45ff-8c77-4ba128239b24 2026-04-20 02:39:22,312 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/d7b8362b-9de4-4e7d-8b55-c452b172952e 2026-04-20 02:39:22,321 - DEBUG - =>VN ctest-VN3-05460423 has no policy to be verified 2026-04-20 02:39:22,321 - DEBUG - Verifying the vn in opserver 2026-04-20 02:39:22,322 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN3-05460423 virtual network link through opserver 10.0.0.38 2026-04-20 02:39:22,322 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2026-04-20 02:39:22,374 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN3-05460423', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN3-05460423?flat'} 2026-04-20 02:39:22,374 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN3-05460423 virtual network link through opserver 10.0.0.27 2026-04-20 02:39:22,374 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks 2026-04-20 02:39:22,428 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN3-05460423', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN3-05460423?flat'} 2026-04-20 02:39:22,428 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN3-05460423 virtual network link through opserver 10.0.0.23 2026-04-20 02:39:22,428 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks 2026-04-20 02:39:22,488 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN3-05460423', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN3-05460423?flat'} 2026-04-20 02:39:22,488 - INFO - Validated that VN default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN3-05460423 is found in opserver 2026-04-20 02:39:22,509 - DEBUG - Do not have enough data to verify VN in agent 2026-04-20 02:39:22,519 - DEBUG - VRF ids for VN ctest-VN3-05460423: {} 2026-04-20 02:39:22,525 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697, 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-81143902:ctest-VN3-05460423, 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] 2026-04-20 02:39:22,792 - DEBUG - No source network defined 2026-04-20 02:39:22,792 - DEBUG - No source network defined 2026-04-20 02:39:22,792 - 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-81143902:ctest-policy13-41579547, 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-81143902:ctest-policy13-41579547, subnet_list = []], src_ports = [start_port = -1, end_port = -1], application = [], dst_addresses = [subnet = None, virtual_network = any, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None] 2026-04-20 02:39:23,052 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN2-94744319, 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-81143902:ctest-VN1-62804697, 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-81143902:ctest-VN2-94744319, 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-81143902:ctest-VN1-62804697, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None] 2026-04-20 02:39:23,259 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2026-04-20 02:39:23,265 - DEBUG - Requesting: http://10.0.0.38:8082/domain/c2b9cdf0-ff50-48aa-bc48-322f89577022 2026-04-20 02:39:23,275 - DEBUG - Requesting: http://10.0.0.38:8082/project/74fa0b95-9fe9-45ff-8c77-4ba128239b24 2026-04-20 02:39:23,370 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/07a9315c-1913-42fa-8f3d-3a76f2077130 2026-04-20 02:39:23,379 - DEBUG - Setup step: Associating the policy to VN' 2026-04-20 02:39:23,484 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '07a9315c-1913-42fa-8f3d-3a76f2077130', 'name': 'ctest-VN1-62804697', 'tenant_id': '74fa0b959fe945ff8c774ba128239b24', 'project_id': '74fa0b959fe945ff8c774ba128239b24', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['dd6d701e-730b-44ea-bcf2-9732558c24f3', '4a3ed2af-208c-4899-b47a-188445084fd2'], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-81143902', 'ctest-VN1-62804697'], 'policys': [['default-domain', 'ctest-TestPolicyAclIpv4v6-81143902', 'ctest-policy12-58614454'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-81143902', 'ctest-policy13-41579547']], 'subnet_ipam': [{'subnet_cidr': '16.62.119.128/26', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-81143902', 'ipam1']}, {'subnet_cidr': '31cd:e74:1cd1:bf4e:7872:db94::/96', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-81143902', 'ipam1']}], 'port_security_enabled': True, 'description': ''}} 2026-04-20 02:39:23,655 - DEBUG - Associated Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-81143902', 'ctest-policy12-58614454'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-81143902', 'ctest-policy13-41579547']] to ctest-VN1-62804697 2026-04-20 02:39:23,655 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2026-04-20 02:39:23,662 - DEBUG - Requesting: http://10.0.0.38:8082/domain/c2b9cdf0-ff50-48aa-bc48-322f89577022 2026-04-20 02:39:23,672 - DEBUG - Requesting: http://10.0.0.38:8082/project/74fa0b95-9fe9-45ff-8c77-4ba128239b24 2026-04-20 02:39:23,758 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/b1d22b67-b338-4f45-9248-f60cdae21814 2026-04-20 02:39:23,768 - DEBUG - Setup step: Associating the policy to VN' 2026-04-20 02:39:23,866 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': 'b1d22b67-b338-4f45-9248-f60cdae21814', 'name': 'ctest-VN2-94744319', 'tenant_id': '74fa0b959fe945ff8c774ba128239b24', 'project_id': '74fa0b959fe945ff8c774ba128239b24', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['4716dd85-592e-4787-a4ca-6c45b79fc8e4', 'a375d621-d8bd-4d4e-a738-54a18a9fde37'], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-81143902', 'ctest-VN2-94744319'], 'policys': [['default-domain', 'ctest-TestPolicyAclIpv4v6-81143902', 'ctest-policy21-41340521']], 'subnet_ipam': [{'subnet_cidr': '68.130.99.0/26', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-81143902', 'ipam2']}, {'subnet_cidr': '3282:eca2:2a1c:751:e0f:9b19::/96', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-81143902', 'ipam2']}], 'port_security_enabled': True, 'description': ''}} 2026-04-20 02:39:23,982 - DEBUG - Associated Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-81143902', 'ctest-policy21-41340521']] to ctest-VN2-94744319 2026-04-20 02:39:24,106 - DEBUG - Services list from nova: [, , , ] 2026-04-20 02:39:25,246 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5644-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5644-2) 2026-04-20 02:39:26,520 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5644-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5644-1) 2026-04-20 02:39:28,154 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5644-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5644-2) 2026-04-20 02:39:28,244 - DEBUG - VM is in ACTIVE state now 2026-04-20 02:39:28,244 - INFO - VM name : ctest-VM11-34665054 2026-04-20 02:39:28,337 - DEBUG - VM ctest-VM11-34665054 ID is 98978850-b161-4b4d-bd30-ffef4ec36db5 2026-04-20 02:39:28,423 - DEBUG - VM is in ACTIVE state now 2026-04-20 02:39:28,445 - DEBUG - VM ctest-VM11-34665054 launched on Node an-jenkins-deploy-platform-ansible-os-5644-2 2026-04-20 02:39:28,550 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/98978850-b161-4b4d-bd30-ffef4ec36db5 2026-04-20 02:39:28,938 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/98978850-b161-4b4d-bd30-ffef4ec36db5 2026-04-20 02:39:28,979 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/98978850-b161-4b4d-bd30-ffef4ec36db5 2026-04-20 02:39:28,985 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/524ed27d-38c1-45f6-8462-8cfa03eb78c6 2026-04-20 02:39:28,994 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/98978850-b161-4b4d-bd30-ffef4ec36db5 2026-04-20 02:39:29,015 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/524ed27d-38c1-45f6-8462-8cfa03eb78c6 2026-04-20 02:39:29,027 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/2c45346d-8c72-481b-8e4e-d7a2e5bc2580 2026-04-20 02:39:29,036 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/b06a2e2a-1c04-4d5c-b116-0d2232490734 2026-04-20 02:39:29,065 - DEBUG - Verifying in api server 10.0.0.23 2026-04-20 02:39:29,065 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/98978850-b161-4b4d-bd30-ffef4ec36db5 2026-04-20 02:39:29,074 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/524ed27d-38c1-45f6-8462-8cfa03eb78c6 2026-04-20 02:39:29,086 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/2c45346d-8c72-481b-8e4e-d7a2e5bc2580 2026-04-20 02:39:29,093 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/b06a2e2a-1c04-4d5c-b116-0d2232490734 2026-04-20 02:39:29,100 - INFO - VM ctest-VM11-34665054 verfication in all API Servers passed 2026-04-20 02:39:29,117 - DEBUG - VM ctest-VM11-34665054 Tap interface: {'index': '3', 'name': 'tap524ed27d-38', 'uuid': '524ed27d-38c1-45f6-8462-8cfa03eb78c6', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697:ctest-VN1-62804697', '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-81143902:ctest-VN1-62804697', 'vm_uuid': '98978850-b161-4b4d-bd30-ffef4ec36db5', 'vm_name': 'ctest-VM11-34665054', 'ip_addr': '16.62.119.131', 'mac_addr': '02:52:4e:d2:7d:38', '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-81143902:524ed27d-38c1-45f6-8462-8cfa03eb78c6', 'sg_uuid_list': ['7fd151e5-0417-468b-9cb3-df7b0c2a8a4e'], 'static_route_list': None, 'vm_project_uuid': '74fa0b95-9fe9-45ff-8c77-4ba128239b24', 'admin_state': 'Enabled', 'flow_key_idx': '31', 'allowed_address_pair_list': None, 'ip6_addr': '31cd:e74:1cd1:bf4e:7872:db94: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': []} 2026-04-20 02:39:29,117 - DEBUG - Agent 10.0.0.65 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697:ctest-VN1-62804697 2026-04-20 02:39:29,123 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697:ctest-VN1-62804697', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697', 'table_label': '-1', 'vxlan_id': '15', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.192:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2026-04-20 02:39:29,216 - DEBUG - VM ctest-VM11-34665054 : Tap interface tap524ed27d-38 is set to Active 2026-04-20 02:39:29,216 - DEBUG - Tap interface tap524ed27d-38 detail : {'index': '3', 'name': 'tap524ed27d-38', 'uuid': '524ed27d-38c1-45f6-8462-8cfa03eb78c6', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697:ctest-VN1-62804697', '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-81143902:ctest-VN1-62804697', 'vm_uuid': '98978850-b161-4b4d-bd30-ffef4ec36db5', 'vm_name': 'ctest-VM11-34665054', 'ip_addr': '16.62.119.131', 'mac_addr': '02:52:4e:d2:7d:38', '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-81143902:524ed27d-38c1-45f6-8462-8cfa03eb78c6', 'sg_uuid_list': ['7fd151e5-0417-468b-9cb3-df7b0c2a8a4e'], 'static_route_list': None, 'vm_project_uuid': '74fa0b95-9fe9-45ff-8c77-4ba128239b24', 'admin_state': 'Enabled', 'flow_key_idx': '31', 'allowed_address_pair_list': None, 'ip6_addr': '31cd:e74:1cd1:bf4e:7872:db94: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': []} 2026-04-20 02:39:29,330 - DEBUG - Starting Layer 2 verification in Agent 2026-04-20 02:39:29,343 - DEBUG - Layer 2 path is seen for VM MAC 02:52:4e:d2:7d:38 in agent 10.0.0.65 2026-04-20 02:39:29,344 - DEBUG - Active layer 2 route in agent is present for VMI tap524ed27d-38 2026-04-20 02:39:29,344 - DEBUG - L2 label(29) matches bw route table and interface table 2026-04-20 02:39:29,357 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697 is found in Agent of node 10.0.0.65 2026-04-20 02:39:29,510 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697 is consistent in agent 10.0.0.65 2026-04-20 02:39:29,510 - DEBUG - Route for VM IP ['16.62.119.131', '31cd:e74:1cd1:bf4e:7872:db94:0:3'] is consistent in agent 10.0.0.65 2026-04-20 02:39:29,510 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697 verification for VM ctest-VM11-34665054 in Agent 10.0.0.65 passed 2026-04-20 02:39:29,622 - DEBUG - Starting all layer 2 verification in agent 10.0.0.65 2026-04-20 02:39:29,648 - DEBUG - Route for VM MAC 02:52:4e:d2:7d:38 is consistent in agent 10.0.0.65 2026-04-20 02:39:32,855 - 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 1032ms') 2026-04-20 02:39:32,855 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-34665054 failed! 2026-04-20 02:39:32,866 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697 is 16.62.119.129 and allocation pool is NOT set 2026-04-20 02:39:32,866 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697 is 31cd:e74:1cd1:bf4e:7872:db94:0:1 and allocation pool is NOT set 2026-04-20 02:39:36,957 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1025ms') 2026-04-20 02:39:36,957 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-34665054 failed! 2026-04-20 02:39:36,974 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697 is 16.62.119.129 and allocation pool is NOT set 2026-04-20 02:39:36,974 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697 is 31cd:e74:1cd1:bf4e:7872:db94:0:1 and allocation pool is NOT set 2026-04-20 02:39:41,052 - 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') 2026-04-20 02:39:41,052 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-34665054 failed! 2026-04-20 02:39:41,068 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697 is 16.62.119.129 and allocation pool is NOT set 2026-04-20 02:39:41,068 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697 is 31cd:e74:1cd1:bf4e:7872:db94:0:1 and allocation pool is NOT set 2026-04-20 02:39:45,143 - 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') 2026-04-20 02:39:45,143 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-34665054 failed! 2026-04-20 02:39:45,159 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697 is 16.62.119.129 and allocation pool is NOT set 2026-04-20 02:39:45,159 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697 is 31cd:e74:1cd1:bf4e:7872:db94:0:1 and allocation pool is NOT set 2026-04-20 02:39:49,243 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1018ms') 2026-04-20 02:39:49,243 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-34665054 failed! 2026-04-20 02:39:49,257 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697 is 16.62.119.129 and allocation pool is NOT set 2026-04-20 02:39:49,257 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697 is 31cd:e74:1cd1:bf4e:7872:db94:0:1 and allocation pool is NOT set 2026-04-20 02:39:53,340 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms') 2026-04-20 02:39:53,340 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-34665054 failed! 2026-04-20 02:39:53,355 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697 is 16.62.119.129 and allocation pool is NOT set 2026-04-20 02:39:53,355 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697 is 31cd:e74:1cd1:bf4e:7872:db94:0:1 and allocation pool is NOT set 2026-04-20 02:39:57,436 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1015ms') 2026-04-20 02:39:57,436 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-34665054 failed! 2026-04-20 02:39:57,450 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697 is 16.62.119.129 and allocation pool is NOT set 2026-04-20 02:39:57,450 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697 is 31cd:e74:1cd1:bf4e:7872:db94:0:1 and allocation pool is NOT set 2026-04-20 02:40:01,527 - 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') 2026-04-20 02:40:01,527 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-34665054 failed! 2026-04-20 02:40:01,539 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697 is 16.62.119.129 and allocation pool is NOT set 2026-04-20 02:40:01,540 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697 is 31cd:e74:1cd1:bf4e:7872:db94:0:1 and allocation pool is NOT set 2026-04-20 02:40:05,626 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1025ms') 2026-04-20 02:40:05,626 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-34665054 failed! 2026-04-20 02:40:05,639 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697 is 16.62.119.129 and allocation pool is NOT set 2026-04-20 02:40:05,639 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697 is 31cd:e74:1cd1:bf4e:7872:db94:0:1 and allocation pool is NOT set 2026-04-20 02:40:09,726 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms') 2026-04-20 02:40:09,726 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-34665054 failed! 2026-04-20 02:40:09,739 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697 is 16.62.119.129 and allocation pool is NOT set 2026-04-20 02:40:09,739 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697 is 31cd:e74:1cd1:bf4e:7872:db94:0:1 and allocation pool is NOT set 2026-04-20 02:40:13,816 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1018ms') 2026-04-20 02:40:13,816 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-34665054 failed! 2026-04-20 02:40:13,829 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697 is 16.62.119.129 and allocation pool is NOT set 2026-04-20 02:40:13,830 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697 is 31cd:e74:1cd1:bf4e:7872:db94:0:1 and allocation pool is NOT set 2026-04-20 02:40:15,897 - 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.30 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=3.41 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 3.408/5.856/8.304/2.448 ms') 2026-04-20 02:40:15,897 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-34665054 passed 2026-04-20 02:40:15,897 - INFO - VM ctest-VM11-34665054 verifications in Compute nodes passed 2026-04-20 02:40:16,156 - DEBUG - Validated VM route 16.62.119.131 in vrouter of 10.0.0.65 2026-04-20 02:40:16,163 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0 2026-04-20 02:40:16,163 - INFO - Validated routes of VM ctest-VM11-34665054 in all vrouters 2026-04-20 02:40:16,624 - DEBUG - Starting all layer2 verification in 10.0.0.38 Control Node 2026-04-20 02:40:16,756 - DEBUG - Layer2 route found for VM MAC 02:52:4e:d2:7d:38 in Control-node 10.0.0.38 2026-04-20 02:40:16,756 - DEBUG - L2 Label for VM ctest-VM11-34665054 same between Control-node 10.0.0.38 and Agent, Expected: 29, Seen: 29 2026-04-20 02:40:16,869 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.38, not supported 2026-04-20 02:40:16,869 - DEBUG - Starting all layer2 verification in 10.0.0.23 Control Node 2026-04-20 02:40:16,990 - DEBUG - Layer2 route found for VM MAC 02:52:4e:d2:7d:38 in Control-node 10.0.0.23 2026-04-20 02:40:16,991 - DEBUG - L2 Label for VM ctest-VM11-34665054 same between Control-node 10.0.0.23 and Agent, Expected: 29, Seen: 29 2026-04-20 02:40:17,101 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.23, not supported 2026-04-20 02:40:17,101 - INFO - Verification in Control-nodes for VM ctest-VM11-34665054 passed 2026-04-20 02:40:17,101 - DEBUG - Verifying the vm in opserver 2026-04-20 02:40:17,101 - DEBUG - Verifying in collector 10.0.0.23 ... 2026-04-20 02:40:17,101 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine/98978850-b161-4b4d-bd30-ffef4ec36db5?flat 2026-04-20 02:40:17,115 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-81143902:524ed27d-38c1-45f6-8462-8cfa03eb78c6?flat 2026-04-20 02:40:17,136 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-81143902:524ed27d-38c1-45f6-8462-8cfa03eb78c6?flat 2026-04-20 02:40:17,155 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-81143902:524ed27d-38c1-45f6-8462-8cfa03eb78c6?flat 2026-04-20 02:40:17,176 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine/98978850-b161-4b4d-bd30-ffef4ec36db5?flat 2026-04-20 02:40:17,193 - DEBUG - Verifying vm in vn uve 2026-04-20 02:40:17,193 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-81143902:524ed27d-38c1-45f6-8462-8cfa03eb78c6?flat 2026-04-20 02:40:17,211 - DEBUG - VM uve shows interface as {'fixed_ip4_list': ['16.62.119.131'], 'tx_vlan': 65535, 'gateway': '16.62.119.129', '__T': 1776652773225939, 'ip4_active': True, 'ip_address': '16.62.119.131', 'vn_uuid': '07a9315c-1913-42fa-8f3d-3a76f2077130', 'ip6_address': '31cd:e74:1cd1:bf4e:7872:db94:0:3', 'is_health_check_active': True, 'admin_state': True, 'label': 25, 'active': True, 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697', 'vhostuser_mode': 0, 'rx_vlan': 65535, 'uuid': '524ed27d-38c1-45f6-8462-8cfa03eb78c6', 'fixed_ip6_list': ['31cd:e74:1cd1:bf4e:7872:db94:0:3'], 'ip6_active': True, 'port_mirror_enabled': False, 'l2_active': True, 'vm_name': 'ctest-VM11-34665054', 'mac_address': '02:52:4e:d2:7d:38', 'vm_uuid': '98978850-b161-4b4d-bd30-ffef4ec36db5'} 2026-04-20 02:40:17,211 - DEBUG - VM uve shows ip address as ['16.62.119.131', '31cd:e74:1cd1:bf4e:7872:db94:0:3'] 2026-04-20 02:40:17,211 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697 2026-04-20 02:40:17,211 - INFO - Verifying through opserver in 10.0.0.38 2026-04-20 02:40:17,211 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697?flat 2026-04-20 02:40:17,241 - DEBUG - expected vm list 98978850-b161-4b4d-bd30-ffef4ec36db5 2026-04-20 02:40:17,241 - DEBUG - Extracted vm list ['98978850-b161-4b4d-bd30-ffef4ec36db5'] 2026-04-20 02:40:17,241 - INFO - VM 98978850-b161-4b4d-bd30-ffef4ec36db5 is present in default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697 2026-04-20 02:40:17,241 - DEBUG - Verifying vm in vrouter uve 2026-04-20 02:40:17,241 - DEBUG - Getting info from collector 10.0.0.23.. 2026-04-20 02:40:17,241 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine/98978850-b161-4b4d-bd30-ffef4ec36db5?flat 2026-04-20 02:40:17,255 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-5644-2'] 2026-04-20 02:40:17,255 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5644-2:Compute:contrail-vrouter-agent:0?flat 2026-04-20 02:40:17,289 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5644-2?flat 2026-04-20 02:40:17,320 - DEBUG - VM 98978850-b161-4b4d-bd30-ffef4ec36db5 is present in vrouter an-jenkins-deploy-platform-ansible-os-5644-2 uve 2026-04-20 02:40:17,320 - DEBUG - Validated that VM ctest-VM11-34665054 is in Vrouter an-jenkins-deploy-platform-ansible-os-5644-2 UVE 2026-04-20 02:40:17,320 - DEBUG - Verifying vm tap interface/vn in vrouter uve 2026-04-20 02:40:17,327 - DEBUG - Expected tap interface of VM uuid 98978850-b161-4b4d-bd30-ffef4ec36db5 is default-domain:ctest-TestPolicyAclIpv4v6-81143902:524ed27d-38c1-45f6-8462-8cfa03eb78c6 2026-04-20 02:40:17,327 - DEBUG - Expected VN of VM uuid 98978850-b161-4b4d-bd30-ffef4ec36db5 is default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697 2026-04-20 02:40:17,327 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5644-2:Compute:contrail-vrouter-agent:0?flat 2026-04-20 02:40:17,366 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5644-2?flat 2026-04-20 02:40:17,396 - DEBUG - VM 98978850-b161-4b4d-bd30-ffef4ec36db5 is present in vrouter an-jenkins-deploy-platform-ansible-os-5644-2 uve 2026-04-20 02:40:17,396 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-81143902:524ed27d-38c1-45f6-8462-8cfa03eb78c6 of vm 98978850-b161-4b4d-bd30-ffef4ec36db5 is present in vrouter an-jenkins-deploy-platform-ansible-os-5644-2 uve 2026-04-20 02:40:17,396 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697 of vm 98978850-b161-4b4d-bd30-ffef4ec36db5 is present in vrouter an-jenkins-deploy-platform-ansible-os-5644-2 uve 2026-04-20 02:40:17,396 - DEBUG - Verifying the 98978850-b161-4b4d-bd30-ffef4ec36db5 virtual network link through opserver 10.0.0.38 2026-04-20 02:40:17,397 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines 2026-04-20 02:40:17,412 - DEBUG - VM link and name as {'name': '98978850-b161-4b4d-bd30-ffef4ec36db5', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-machine/98978850-b161-4b4d-bd30-ffef4ec36db5?flat'} 2026-04-20 02:40:17,412 - DEBUG - Verifying the 98978850-b161-4b4d-bd30-ffef4ec36db5 virtual network link through opserver 10.0.0.27 2026-04-20 02:40:17,412 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines 2026-04-20 02:40:17,424 - DEBUG - VM link and name as {'name': '98978850-b161-4b4d-bd30-ffef4ec36db5', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-machine/98978850-b161-4b4d-bd30-ffef4ec36db5?flat'} 2026-04-20 02:40:17,424 - DEBUG - Verifying the 98978850-b161-4b4d-bd30-ffef4ec36db5 virtual network link through opserver 10.0.0.23 2026-04-20 02:40:17,424 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machines 2026-04-20 02:40:17,435 - DEBUG - VM link and name as {'name': '98978850-b161-4b4d-bd30-ffef4ec36db5', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-machine/98978850-b161-4b4d-bd30-ffef4ec36db5?flat'} 2026-04-20 02:40:17,435 - INFO - VM ctest-VM11-34665054 validations in Opserver passed 2026-04-20 02:40:17,526 - DEBUG - VM is in ACTIVE state now 2026-04-20 02:40:17,526 - INFO - VM name : ctest-VM21-63716180 2026-04-20 02:40:17,626 - DEBUG - VM ctest-VM21-63716180 ID is 7bce5e96-a95e-4278-82c6-1310c52ee6e6 2026-04-20 02:40:17,716 - DEBUG - VM is in ACTIVE state now 2026-04-20 02:40:17,716 - DEBUG - VM ctest-VM21-63716180 launched on Node an-jenkins-deploy-platform-ansible-os-5644-1 2026-04-20 02:40:17,817 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/7bce5e96-a95e-4278-82c6-1310c52ee6e6 2026-04-20 02:40:17,830 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/7bce5e96-a95e-4278-82c6-1310c52ee6e6 2026-04-20 02:40:17,837 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/9fe5dc82-eb76-4a49-9cc6-0c9883bbba2c 2026-04-20 02:40:17,847 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/7bce5e96-a95e-4278-82c6-1310c52ee6e6 2026-04-20 02:40:17,855 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/9fe5dc82-eb76-4a49-9cc6-0c9883bbba2c 2026-04-20 02:40:17,867 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/53b45a7b-81bb-4879-9f35-f0c3401d6557 2026-04-20 02:40:17,878 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/731e2677-1e6c-4fcb-b3aa-6d2c418f6cd7 2026-04-20 02:40:17,890 - DEBUG - Verifying in api server 10.0.0.23 2026-04-20 02:40:17,890 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/7bce5e96-a95e-4278-82c6-1310c52ee6e6 2026-04-20 02:40:17,896 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/9fe5dc82-eb76-4a49-9cc6-0c9883bbba2c 2026-04-20 02:40:17,905 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/53b45a7b-81bb-4879-9f35-f0c3401d6557 2026-04-20 02:40:17,913 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/731e2677-1e6c-4fcb-b3aa-6d2c418f6cd7 2026-04-20 02:40:17,919 - INFO - VM ctest-VM21-63716180 verfication in all API Servers passed 2026-04-20 02:40:17,939 - DEBUG - VM ctest-VM21-63716180 Tap interface: {'index': '3', 'name': 'tap9fe5dc82-eb', 'uuid': '9fe5dc82-eb76-4a49-9cc6-0c9883bbba2c', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN2-94744319:ctest-VN2-94744319', '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-81143902:ctest-VN2-94744319', 'vm_uuid': '7bce5e96-a95e-4278-82c6-1310c52ee6e6', 'vm_name': 'ctest-VM21-63716180', 'ip_addr': '68.130.99.3', 'mac_addr': '02:9f:e5:dc:82:eb', '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-81143902:9fe5dc82-eb76-4a49-9cc6-0c9883bbba2c', 'sg_uuid_list': ['7fd151e5-0417-468b-9cb3-df7b0c2a8a4e'], 'static_route_list': None, 'vm_project_uuid': '74fa0b95-9fe9-45ff-8c77-4ba128239b24', 'admin_state': 'Enabled', 'flow_key_idx': '31', 'allowed_address_pair_list': None, 'ip6_addr': '3282:eca2:2a1c:751:e0f:9b19: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': []} 2026-04-20 02:40:17,939 - DEBUG - Agent 10.0.0.240 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN2-94744319:ctest-VN2-94744319 2026-04-20 02:40:17,948 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN2-94744319:ctest-VN2-94744319', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN2-94744319', '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'}]} 2026-04-20 02:40:18,040 - DEBUG - VM ctest-VM21-63716180 : Tap interface tap9fe5dc82-eb is set to Active 2026-04-20 02:40:18,040 - DEBUG - Tap interface tap9fe5dc82-eb detail : {'index': '3', 'name': 'tap9fe5dc82-eb', 'uuid': '9fe5dc82-eb76-4a49-9cc6-0c9883bbba2c', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN2-94744319:ctest-VN2-94744319', '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-81143902:ctest-VN2-94744319', 'vm_uuid': '7bce5e96-a95e-4278-82c6-1310c52ee6e6', 'vm_name': 'ctest-VM21-63716180', 'ip_addr': '68.130.99.3', 'mac_addr': '02:9f:e5:dc:82:eb', '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-81143902:9fe5dc82-eb76-4a49-9cc6-0c9883bbba2c', 'sg_uuid_list': ['7fd151e5-0417-468b-9cb3-df7b0c2a8a4e'], 'static_route_list': None, 'vm_project_uuid': '74fa0b95-9fe9-45ff-8c77-4ba128239b24', 'admin_state': 'Enabled', 'flow_key_idx': '31', 'allowed_address_pair_list': None, 'ip6_addr': '3282:eca2:2a1c:751:e0f:9b19: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': []} 2026-04-20 02:40:18,162 - DEBUG - Starting Layer 2 verification in Agent 2026-04-20 02:40:18,176 - DEBUG - Layer 2 path is seen for VM MAC 02:9f:e5:dc:82:eb in agent 10.0.0.240 2026-04-20 02:40:18,176 - DEBUG - Active layer 2 route in agent is present for VMI tap9fe5dc82-eb 2026-04-20 02:40:18,176 - DEBUG - L2 label(29) matches bw route table and interface table 2026-04-20 02:40:18,184 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN2-94744319 is found in Agent of node 10.0.0.240 2026-04-20 02:40:18,335 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN2-94744319 is consistent in agent 10.0.0.240 2026-04-20 02:40:18,335 - DEBUG - Route for VM IP ['68.130.99.3', '3282:eca2:2a1c:751:e0f:9b19:0:3'] is consistent in agent 10.0.0.240 2026-04-20 02:40:18,335 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN2-94744319 verification for VM ctest-VM21-63716180 in Agent 10.0.0.240 passed 2026-04-20 02:40:18,450 - DEBUG - Starting all layer 2 verification in agent 10.0.0.240 2026-04-20 02:40:18,464 - DEBUG - Route for VM MAC 02:9f:e5:dc:82:eb is consistent in agent 10.0.0.240 2026-04-20 02:40:19,665 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=2.87 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=8.00 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 2.869/5.433/7.998/2.564 ms') 2026-04-20 02:40:19,665 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-63716180 passed 2026-04-20 02:40:19,665 - INFO - VM ctest-VM21-63716180 verifications in Compute nodes passed 2026-04-20 02:40:19,898 - DEBUG - Validated VM route 68.130.99.3 in vrouter of 10.0.0.240 2026-04-20 02:40:19,905 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0 2026-04-20 02:40:19,905 - INFO - Validated routes of VM ctest-VM21-63716180 in all vrouters 2026-04-20 02:40:20,330 - DEBUG - Starting all layer2 verification in 10.0.0.38 Control Node 2026-04-20 02:40:20,455 - DEBUG - Layer2 route found for VM MAC 02:9f:e5:dc:82:eb in Control-node 10.0.0.38 2026-04-20 02:40:20,455 - DEBUG - L2 Label for VM ctest-VM21-63716180 same between Control-node 10.0.0.38 and Agent, Expected: 29, Seen: 29 2026-04-20 02:40:20,569 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.38, not supported 2026-04-20 02:40:20,569 - DEBUG - Starting all layer2 verification in 10.0.0.23 Control Node 2026-04-20 02:40:20,690 - DEBUG - Layer2 route found for VM MAC 02:9f:e5:dc:82:eb in Control-node 10.0.0.23 2026-04-20 02:40:20,690 - DEBUG - L2 Label for VM ctest-VM21-63716180 same between Control-node 10.0.0.23 and Agent, Expected: 29, Seen: 29 2026-04-20 02:40:20,800 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.23, not supported 2026-04-20 02:40:20,800 - INFO - Verification in Control-nodes for VM ctest-VM21-63716180 passed 2026-04-20 02:40:20,800 - DEBUG - Verifying the vm in opserver 2026-04-20 02:40:20,800 - DEBUG - Verifying in collector 10.0.0.23 ... 2026-04-20 02:40:20,800 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine/7bce5e96-a95e-4278-82c6-1310c52ee6e6?flat 2026-04-20 02:40:20,813 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-81143902:9fe5dc82-eb76-4a49-9cc6-0c9883bbba2c?flat 2026-04-20 02:40:20,832 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-81143902:9fe5dc82-eb76-4a49-9cc6-0c9883bbba2c?flat 2026-04-20 02:40:20,850 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-81143902:9fe5dc82-eb76-4a49-9cc6-0c9883bbba2c?flat 2026-04-20 02:40:20,866 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine/7bce5e96-a95e-4278-82c6-1310c52ee6e6?flat 2026-04-20 02:40:20,880 - DEBUG - Verifying vm in vn uve 2026-04-20 02:40:20,880 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-81143902:9fe5dc82-eb76-4a49-9cc6-0c9883bbba2c?flat 2026-04-20 02:40:20,899 - DEBUG - VM uve shows interface as {'ip6_active': True, 'rx_vlan': 65535, 'label': 25, 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN2-94744319', 'fixed_ip6_list': ['3282:eca2:2a1c:751:e0f:9b19:0:3'], '__T': 1776652774639303, 'vhostuser_mode': 0, 'is_health_check_active': True, 'admin_state': True, 'gateway': '68.130.99.1', 'vm_name': 'ctest-VM21-63716180', 'fixed_ip4_list': ['68.130.99.3'], 'ip4_active': True, 'active': True, 'tx_vlan': 65535, 'vm_uuid': '7bce5e96-a95e-4278-82c6-1310c52ee6e6', 'ip6_address': '3282:eca2:2a1c:751:e0f:9b19:0:3', 'mac_address': '02:9f:e5:dc:82:eb', 'ip_address': '68.130.99.3', 'port_mirror_enabled': False, 'vn_uuid': 'b1d22b67-b338-4f45-9248-f60cdae21814', 'l2_active': True, 'uuid': '9fe5dc82-eb76-4a49-9cc6-0c9883bbba2c'} 2026-04-20 02:40:20,899 - DEBUG - VM uve shows ip address as ['68.130.99.3', '3282:eca2:2a1c:751:e0f:9b19:0:3'] 2026-04-20 02:40:20,899 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN2-94744319 2026-04-20 02:40:20,899 - INFO - Verifying through opserver in 10.0.0.38 2026-04-20 02:40:20,899 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN2-94744319?flat 2026-04-20 02:40:20,929 - DEBUG - expected vm list 7bce5e96-a95e-4278-82c6-1310c52ee6e6 2026-04-20 02:40:20,929 - DEBUG - Extracted vm list ['7bce5e96-a95e-4278-82c6-1310c52ee6e6'] 2026-04-20 02:40:20,929 - INFO - VM 7bce5e96-a95e-4278-82c6-1310c52ee6e6 is present in default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN2-94744319 2026-04-20 02:40:20,929 - DEBUG - Verifying vm in vrouter uve 2026-04-20 02:40:20,929 - DEBUG - Getting info from collector 10.0.0.23.. 2026-04-20 02:40:20,929 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine/7bce5e96-a95e-4278-82c6-1310c52ee6e6?flat 2026-04-20 02:40:20,945 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-5644-1'] 2026-04-20 02:40:20,945 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5644-1:Compute:contrail-vrouter-agent:0?flat 2026-04-20 02:40:20,984 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5644-1?flat 2026-04-20 02:40:21,012 - DEBUG - VM 7bce5e96-a95e-4278-82c6-1310c52ee6e6 is present in vrouter an-jenkins-deploy-platform-ansible-os-5644-1 uve 2026-04-20 02:40:21,012 - DEBUG - Validated that VM ctest-VM21-63716180 is in Vrouter an-jenkins-deploy-platform-ansible-os-5644-1 UVE 2026-04-20 02:40:21,012 - DEBUG - Verifying vm tap interface/vn in vrouter uve 2026-04-20 02:40:21,021 - DEBUG - Expected tap interface of VM uuid 7bce5e96-a95e-4278-82c6-1310c52ee6e6 is default-domain:ctest-TestPolicyAclIpv4v6-81143902:9fe5dc82-eb76-4a49-9cc6-0c9883bbba2c 2026-04-20 02:40:21,021 - DEBUG - Expected VN of VM uuid 7bce5e96-a95e-4278-82c6-1310c52ee6e6 is default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN2-94744319 2026-04-20 02:40:21,021 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5644-1:Compute:contrail-vrouter-agent:0?flat 2026-04-20 02:40:21,052 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5644-1?flat 2026-04-20 02:40:21,081 - DEBUG - VM 7bce5e96-a95e-4278-82c6-1310c52ee6e6 is present in vrouter an-jenkins-deploy-platform-ansible-os-5644-1 uve 2026-04-20 02:40:21,081 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-81143902:9fe5dc82-eb76-4a49-9cc6-0c9883bbba2c of vm 7bce5e96-a95e-4278-82c6-1310c52ee6e6 is present in vrouter an-jenkins-deploy-platform-ansible-os-5644-1 uve 2026-04-20 02:40:21,081 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN2-94744319 of vm 7bce5e96-a95e-4278-82c6-1310c52ee6e6 is present in vrouter an-jenkins-deploy-platform-ansible-os-5644-1 uve 2026-04-20 02:40:21,081 - DEBUG - Verifying the 7bce5e96-a95e-4278-82c6-1310c52ee6e6 virtual network link through opserver 10.0.0.38 2026-04-20 02:40:21,081 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines 2026-04-20 02:40:21,093 - DEBUG - VM link and name as {'name': '7bce5e96-a95e-4278-82c6-1310c52ee6e6', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-machine/7bce5e96-a95e-4278-82c6-1310c52ee6e6?flat'} 2026-04-20 02:40:21,093 - DEBUG - Verifying the 7bce5e96-a95e-4278-82c6-1310c52ee6e6 virtual network link through opserver 10.0.0.27 2026-04-20 02:40:21,093 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines 2026-04-20 02:40:21,107 - DEBUG - VM link and name as {'name': '7bce5e96-a95e-4278-82c6-1310c52ee6e6', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-machine/7bce5e96-a95e-4278-82c6-1310c52ee6e6?flat'} 2026-04-20 02:40:21,107 - DEBUG - Verifying the 7bce5e96-a95e-4278-82c6-1310c52ee6e6 virtual network link through opserver 10.0.0.23 2026-04-20 02:40:21,107 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machines 2026-04-20 02:40:21,123 - DEBUG - VM link and name as {'name': '7bce5e96-a95e-4278-82c6-1310c52ee6e6', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-machine/7bce5e96-a95e-4278-82c6-1310c52ee6e6?flat'} 2026-04-20 02:40:21,123 - INFO - VM ctest-VM21-63716180 validations in Opserver passed 2026-04-20 02:40:21,206 - DEBUG - VM is in ACTIVE state now 2026-04-20 02:40:21,206 - INFO - VM name : ctest-VM31-12784100 2026-04-20 02:40:21,287 - DEBUG - VM ctest-VM31-12784100 ID is c98c3fa0-2e4f-4699-a49e-7822c5698f08 2026-04-20 02:40:21,368 - DEBUG - VM is in ACTIVE state now 2026-04-20 02:40:21,368 - DEBUG - VM ctest-VM31-12784100 launched on Node an-jenkins-deploy-platform-ansible-os-5644-2 2026-04-20 02:40:21,446 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/c98c3fa0-2e4f-4699-a49e-7822c5698f08 2026-04-20 02:40:21,456 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/c98c3fa0-2e4f-4699-a49e-7822c5698f08 2026-04-20 02:40:21,463 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/24462df5-735c-4e8e-afc6-2ac52bdad787 2026-04-20 02:40:21,472 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/c98c3fa0-2e4f-4699-a49e-7822c5698f08 2026-04-20 02:40:21,478 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/24462df5-735c-4e8e-afc6-2ac52bdad787 2026-04-20 02:40:21,487 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/7a683100-527c-42d7-bafd-3f731510d2fe 2026-04-20 02:40:21,496 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/f2ef0ab6-4f9b-43c3-baeb-8b72679395bd 2026-04-20 02:40:21,503 - DEBUG - Verifying in api server 10.0.0.23 2026-04-20 02:40:21,504 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/c98c3fa0-2e4f-4699-a49e-7822c5698f08 2026-04-20 02:40:21,510 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/24462df5-735c-4e8e-afc6-2ac52bdad787 2026-04-20 02:40:21,518 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/7a683100-527c-42d7-bafd-3f731510d2fe 2026-04-20 02:40:21,525 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/f2ef0ab6-4f9b-43c3-baeb-8b72679395bd 2026-04-20 02:40:21,532 - INFO - VM ctest-VM31-12784100 verfication in all API Servers passed 2026-04-20 02:40:21,548 - DEBUG - VM ctest-VM31-12784100 Tap interface: {'index': '4', 'name': 'tap24462df5-73', 'uuid': '24462df5-735c-4e8e-afc6-2ac52bdad787', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN3-05460423:ctest-VN3-05460423', '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-81143902:ctest-VN3-05460423', 'vm_uuid': 'c98c3fa0-2e4f-4699-a49e-7822c5698f08', 'vm_name': 'ctest-VM31-12784100', 'ip_addr': '164.20.211.3', 'mac_addr': '02:24:46:2d:f5:73', '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-81143902:24462df5-735c-4e8e-afc6-2ac52bdad787', 'sg_uuid_list': ['7fd151e5-0417-468b-9cb3-df7b0c2a8a4e'], 'static_route_list': None, 'vm_project_uuid': '74fa0b95-9fe9-45ff-8c77-4ba128239b24', 'admin_state': 'Enabled', 'flow_key_idx': '41', 'allowed_address_pair_list': None, 'ip6_addr': '214a:48c0:2f1e:7a12:5c18:d0b4: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': []} 2026-04-20 02:40:21,549 - DEBUG - Agent 10.0.0.65 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN3-05460423:ctest-VN3-05460423 2026-04-20 02:40:21,553 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN3-05460423:ctest-VN3-05460423', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN3-05460423', 'table_label': '-1', 'vxlan_id': '17', 'evpnindex': '3', 'brindex': '3', 'mplsindex': '0', 'RD': '10.20.0.192:3', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2026-04-20 02:40:21,638 - DEBUG - VM ctest-VM31-12784100 : Tap interface tap24462df5-73 is set to Active 2026-04-20 02:40:21,638 - DEBUG - Tap interface tap24462df5-73 detail : {'index': '4', 'name': 'tap24462df5-73', 'uuid': '24462df5-735c-4e8e-afc6-2ac52bdad787', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN3-05460423:ctest-VN3-05460423', '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-81143902:ctest-VN3-05460423', 'vm_uuid': 'c98c3fa0-2e4f-4699-a49e-7822c5698f08', 'vm_name': 'ctest-VM31-12784100', 'ip_addr': '164.20.211.3', 'mac_addr': '02:24:46:2d:f5:73', '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-81143902:24462df5-735c-4e8e-afc6-2ac52bdad787', 'sg_uuid_list': ['7fd151e5-0417-468b-9cb3-df7b0c2a8a4e'], 'static_route_list': None, 'vm_project_uuid': '74fa0b95-9fe9-45ff-8c77-4ba128239b24', 'admin_state': 'Enabled', 'flow_key_idx': '41', 'allowed_address_pair_list': None, 'ip6_addr': '214a:48c0:2f1e:7a12:5c18:d0b4: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': []} 2026-04-20 02:40:21,758 - DEBUG - Starting Layer 2 verification in Agent 2026-04-20 02:40:21,767 - DEBUG - Layer 2 path is seen for VM MAC 02:24:46:2d:f5:73 in agent 10.0.0.65 2026-04-20 02:40:21,767 - DEBUG - Active layer 2 route in agent is present for VMI tap24462df5-73 2026-04-20 02:40:21,767 - DEBUG - L2 label(36) matches bw route table and interface table 2026-04-20 02:40:21,780 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN3-05460423 is found in Agent of node 10.0.0.65 2026-04-20 02:40:21,917 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN3-05460423 is consistent in agent 10.0.0.65 2026-04-20 02:40:21,917 - DEBUG - Route for VM IP ['164.20.211.3', '214a:48c0:2f1e:7a12:5c18:d0b4:0:3'] is consistent in agent 10.0.0.65 2026-04-20 02:40:21,917 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN3-05460423 verification for VM ctest-VM31-12784100 in Agent 10.0.0.65 passed 2026-04-20 02:40:22,028 - DEBUG - Starting all layer 2 verification in agent 10.0.0.65 2026-04-20 02:40:22,042 - DEBUG - Route for VM MAC 02:24:46:2d:f5:73 is consistent in agent 10.0.0.65 2026-04-20 02:40:23,209 - 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.30 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=1.17 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.165/2.234/3.304/1.069 ms') 2026-04-20 02:40:23,209 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-12784100 passed 2026-04-20 02:40:23,209 - INFO - VM ctest-VM31-12784100 verifications in Compute nodes passed 2026-04-20 02:40:23,406 - DEBUG - Validated VM route 164.20.211.3 in vrouter of 10.0.0.65 2026-04-20 02:40:23,412 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0 2026-04-20 02:40:23,412 - INFO - Validated routes of VM ctest-VM31-12784100 in all vrouters 2026-04-20 02:40:23,908 - DEBUG - Starting all layer2 verification in 10.0.0.38 Control Node 2026-04-20 02:40:24,035 - DEBUG - Layer2 route found for VM MAC 02:24:46:2d:f5:73 in Control-node 10.0.0.38 2026-04-20 02:40:24,035 - DEBUG - L2 Label for VM ctest-VM31-12784100 same between Control-node 10.0.0.38 and Agent, Expected: 36, Seen: 36 2026-04-20 02:40:24,150 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.38, not supported 2026-04-20 02:40:24,151 - DEBUG - Starting all layer2 verification in 10.0.0.23 Control Node 2026-04-20 02:40:24,277 - DEBUG - Layer2 route found for VM MAC 02:24:46:2d:f5:73 in Control-node 10.0.0.23 2026-04-20 02:40:24,277 - DEBUG - L2 Label for VM ctest-VM31-12784100 same between Control-node 10.0.0.23 and Agent, Expected: 36, Seen: 36 2026-04-20 02:40:24,389 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.23, not supported 2026-04-20 02:40:24,389 - INFO - Verification in Control-nodes for VM ctest-VM31-12784100 passed 2026-04-20 02:40:24,389 - DEBUG - Verifying the vm in opserver 2026-04-20 02:40:24,389 - DEBUG - Verifying in collector 10.0.0.23 ... 2026-04-20 02:40:24,389 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine/c98c3fa0-2e4f-4699-a49e-7822c5698f08?flat 2026-04-20 02:40:24,404 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-81143902:24462df5-735c-4e8e-afc6-2ac52bdad787?flat 2026-04-20 02:40:24,421 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-81143902:24462df5-735c-4e8e-afc6-2ac52bdad787?flat 2026-04-20 02:40:24,437 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-81143902:24462df5-735c-4e8e-afc6-2ac52bdad787?flat 2026-04-20 02:40:24,454 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine/c98c3fa0-2e4f-4699-a49e-7822c5698f08?flat 2026-04-20 02:40:24,468 - DEBUG - Verifying vm in vn uve 2026-04-20 02:40:24,468 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-81143902:24462df5-735c-4e8e-afc6-2ac52bdad787?flat 2026-04-20 02:40:24,485 - DEBUG - VM uve shows interface as {'fixed_ip4_list': ['164.20.211.3'], 'tx_vlan': 65535, 'gateway': '164.20.211.1', '__T': 1776652773225915, 'ip4_active': True, 'ip_address': '164.20.211.3', 'vn_uuid': 'd7b8362b-9de4-4e7d-8b55-c452b172952e', 'ip6_address': '214a:48c0:2f1e:7a12:5c18:d0b4:0:3', 'is_health_check_active': True, 'admin_state': True, 'label': 32, 'active': True, 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN3-05460423', 'vhostuser_mode': 0, 'rx_vlan': 65535, 'uuid': '24462df5-735c-4e8e-afc6-2ac52bdad787', 'fixed_ip6_list': ['214a:48c0:2f1e:7a12:5c18:d0b4:0:3'], 'ip6_active': True, 'port_mirror_enabled': False, 'l2_active': True, 'vm_name': 'ctest-VM31-12784100', 'mac_address': '02:24:46:2d:f5:73', 'vm_uuid': 'c98c3fa0-2e4f-4699-a49e-7822c5698f08'} 2026-04-20 02:40:24,485 - DEBUG - VM uve shows ip address as ['164.20.211.3', '214a:48c0:2f1e:7a12:5c18:d0b4:0:3'] 2026-04-20 02:40:24,485 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN3-05460423 2026-04-20 02:40:24,485 - INFO - Verifying through opserver in 10.0.0.38 2026-04-20 02:40:24,485 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN3-05460423?flat 2026-04-20 02:40:24,504 - DEBUG - expected vm list c98c3fa0-2e4f-4699-a49e-7822c5698f08 2026-04-20 02:40:24,504 - DEBUG - Extracted vm list ['c98c3fa0-2e4f-4699-a49e-7822c5698f08'] 2026-04-20 02:40:24,504 - INFO - VM c98c3fa0-2e4f-4699-a49e-7822c5698f08 is present in default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN3-05460423 2026-04-20 02:40:24,504 - DEBUG - Verifying vm in vrouter uve 2026-04-20 02:40:24,504 - DEBUG - Getting info from collector 10.0.0.23.. 2026-04-20 02:40:24,504 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine/c98c3fa0-2e4f-4699-a49e-7822c5698f08?flat 2026-04-20 02:40:24,518 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-5644-2'] 2026-04-20 02:40:24,518 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5644-2:Compute:contrail-vrouter-agent:0?flat 2026-04-20 02:40:24,549 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5644-2?flat 2026-04-20 02:40:24,576 - DEBUG - VM c98c3fa0-2e4f-4699-a49e-7822c5698f08 is present in vrouter an-jenkins-deploy-platform-ansible-os-5644-2 uve 2026-04-20 02:40:24,576 - DEBUG - Validated that VM ctest-VM31-12784100 is in Vrouter an-jenkins-deploy-platform-ansible-os-5644-2 UVE 2026-04-20 02:40:24,576 - DEBUG - Verifying vm tap interface/vn in vrouter uve 2026-04-20 02:40:24,584 - DEBUG - Expected tap interface of VM uuid c98c3fa0-2e4f-4699-a49e-7822c5698f08 is default-domain:ctest-TestPolicyAclIpv4v6-81143902:24462df5-735c-4e8e-afc6-2ac52bdad787 2026-04-20 02:40:24,584 - DEBUG - Expected VN of VM uuid c98c3fa0-2e4f-4699-a49e-7822c5698f08 is default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN3-05460423 2026-04-20 02:40:24,584 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5644-2:Compute:contrail-vrouter-agent:0?flat 2026-04-20 02:40:24,617 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5644-2?flat 2026-04-20 02:40:24,647 - DEBUG - VM c98c3fa0-2e4f-4699-a49e-7822c5698f08 is present in vrouter an-jenkins-deploy-platform-ansible-os-5644-2 uve 2026-04-20 02:40:24,647 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-81143902:24462df5-735c-4e8e-afc6-2ac52bdad787 of vm c98c3fa0-2e4f-4699-a49e-7822c5698f08 is present in vrouter an-jenkins-deploy-platform-ansible-os-5644-2 uve 2026-04-20 02:40:24,647 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN3-05460423 of vm c98c3fa0-2e4f-4699-a49e-7822c5698f08 is present in vrouter an-jenkins-deploy-platform-ansible-os-5644-2 uve 2026-04-20 02:40:24,647 - DEBUG - Verifying the c98c3fa0-2e4f-4699-a49e-7822c5698f08 virtual network link through opserver 10.0.0.38 2026-04-20 02:40:24,647 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines 2026-04-20 02:40:24,660 - DEBUG - VM link and name as {'name': 'c98c3fa0-2e4f-4699-a49e-7822c5698f08', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-machine/c98c3fa0-2e4f-4699-a49e-7822c5698f08?flat'} 2026-04-20 02:40:24,660 - DEBUG - Verifying the c98c3fa0-2e4f-4699-a49e-7822c5698f08 virtual network link through opserver 10.0.0.27 2026-04-20 02:40:24,660 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines 2026-04-20 02:40:24,677 - DEBUG - VM link and name as {'name': 'c98c3fa0-2e4f-4699-a49e-7822c5698f08', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-machine/c98c3fa0-2e4f-4699-a49e-7822c5698f08?flat'} 2026-04-20 02:40:24,677 - DEBUG - Verifying the c98c3fa0-2e4f-4699-a49e-7822c5698f08 virtual network link through opserver 10.0.0.23 2026-04-20 02:40:24,677 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machines 2026-04-20 02:40:24,692 - DEBUG - VM link and name as {'name': 'c98c3fa0-2e4f-4699-a49e-7822c5698f08', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-machine/c98c3fa0-2e4f-4699-a49e-7822c5698f08?flat'} 2026-04-20 02:40:24,692 - INFO - VM ctest-VM31-12784100 validations in Opserver passed 2026-04-20 02:40:24,693 - INFO - Waiting for VM ctest-VM11-34665054 to be up.. 2026-04-20 02:40:24,693 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-20 02:40:24,693 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/98978850-b161-4b4d-bd30-ffef4ec36db5 2026-04-20 02:40:24,702 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/524ed27d-38c1-45f6-8462-8cfa03eb78c6 2026-04-20 02:40:25,868 - 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.97 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.543 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.543/2.256/3.970/1.713 ms') 2026-04-20 02:40:25,868 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-34665054 passed 2026-04-20 02:40:25,936 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-20 02:40:25,936 - DEBUG - Waiting to SSH to VM ctest-VM11-34665054, IP 16.62.119.131, Port 22 2026-04-20 02:40:26,007 - 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': ''} 2026-04-20 02:40:26,093 - DEBUG - VM ctest-VM11-34665054 is NOT ready for SSH connections, VM status: ACTIVE 2026-04-20 02:40:31,093 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-20 02:40:31,093 - DEBUG - Waiting to SSH to VM ctest-VM11-34665054, IP 16.62.119.131, Port 22 2026-04-20 02:40:31,159 - 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': ''} 2026-04-20 02:40:31,241 - DEBUG - VM ctest-VM11-34665054 is NOT ready for SSH connections, VM status: ACTIVE 2026-04-20 02:40:36,242 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-20 02:40:36,242 - DEBUG - Waiting to SSH to VM ctest-VM11-34665054, IP 16.62.119.131, Port 22 2026-04-20 02:40:36,418 - DEBUG - VM ctest-VM11-34665054 is ready for SSH connections 2026-04-20 02:40:36,418 - INFO - Waiting for VM ctest-VM21-63716180 to be up.. 2026-04-20 02:40:36,419 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-20 02:40:36,419 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/7bce5e96-a95e-4278-82c6-1310c52ee6e6 2026-04-20 02:40:36,427 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/9fe5dc82-eb76-4a49-9cc6-0c9883bbba2c 2026-04-20 02:40:37,617 - 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=1.90 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.666 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.666/1.282/1.898/0.616 ms') 2026-04-20 02:40:37,617 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-63716180 passed 2026-04-20 02:40:37,690 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-20 02:40:37,690 - DEBUG - Waiting to SSH to VM ctest-VM21-63716180, IP 68.130.99.3, Port 22 2026-04-20 02:40:37,860 - DEBUG - VM ctest-VM21-63716180 is ready for SSH connections 2026-04-20 02:40:37,860 - INFO - Waiting for VM ctest-VM31-12784100 to be up.. 2026-04-20 02:40:37,861 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-20 02:40:37,861 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/c98c3fa0-2e4f-4699-a49e-7822c5698f08 2026-04-20 02:40:37,869 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/24462df5-735c-4e8e-afc6-2ac52bdad787 2026-04-20 02:40:39,038 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=2.80 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=3.03 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 2.801/2.917/3.033/0.116 ms') 2026-04-20 02:40:39,038 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-12784100 passed 2026-04-20 02:40:39,105 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-20 02:40:39,105 - DEBUG - Waiting to SSH to VM ctest-VM31-12784100, IP 164.20.211.3, Port 22 2026-04-20 02:40:39,274 - DEBUG - VM ctest-VM31-12784100 is ready for SSH connections 2026-04-20 02:40:39,274 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 68.130.99.3, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.65, gateway password: c0ntrail123 2026-04-20 02:40:39,275 - DEBUG - ping -s 56 -c 3 -W 1 68.130.99.3 2026-04-20 02:41:02,579 - DEBUG - PING 68.130.99.3 (68.130.99.3) 56(84) bytes of data. 64 bytes from 68.130.99.3: icmp_req=1 ttl=63 time=11.4 ms 64 bytes from 68.130.99.3: icmp_req=2 ttl=63 time=1.30 ms 64 bytes from 68.130.99.3: icmp_req=3 ttl=63 time=1.31 ms --- 68.130.99.3 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2003ms rtt min/avg/max/mdev = 1.305/4.694/11.461/4.785 ms 2026-04-20 02:41:02,579 - INFO - Ping to IP 68.130.99.3 from VM ctest-VM11-34665054 passed 2026-04-20 02:41:02,579 - DEBUG - Running remote_cmd, Cmd : ping6 -s 56 -c 3 -W 1 3282:eca2:2a1c:751:e0f:9b19:0:3, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.65, gateway password: c0ntrail123 2026-04-20 02:41:02,579 - DEBUG - ping6 -s 56 -c 3 -W 1 3282:eca2:2a1c:751:e0f:9b19:0:3 2026-04-20 02:41:05,089 - DEBUG - PING 3282:eca2:2a1c:751:e0f:9b19:0:3(3282:eca2:2a1c:751:e0f:9b19:0:3) 56 data bytes 64 bytes from 3282:eca2:2a1c:751:e0f:9b19:0:3: icmp_seq=1 ttl=64 time=7.95 ms 64 bytes from 3282:eca2:2a1c:751:e0f:9b19:0:3: icmp_seq=2 ttl=64 time=1.41 ms 64 bytes from 3282:eca2:2a1c:751:e0f:9b19:0:3: icmp_seq=3 ttl=64 time=1.36 ms --- 3282:eca2:2a1c:751:e0f:9b19:0:3 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2003ms rtt min/avg/max/mdev = 1.365/3.575/7.950/3.093 ms 2026-04-20 02:41:05,089 - INFO - Ping to IP 3282:eca2:2a1c:751:e0f:9b19:0:3 from VM ctest-VM11-34665054 passed 2026-04-20 02:41:05,090 - INFO - Test with src as policy and dst as any PASSED 2026-04-20 02:41:05,090 - INFO - Deleting VM ctest-VM31-12784100 2026-04-20 02:41:05,156 - DEBUG - Verifying in api server 10.0.0.23 2026-04-20 02:41:05,156 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/c98c3fa0-2e4f-4699-a49e-7822c5698f08 2026-04-20 02:41:05,165 - DEBUG - VM ID c98c3fa0-2e4f-4699-a49e-7822c5698f08 of VM ctest-VM31-12784100 is still found in API Server 2026-04-20 02:41:07,166 - DEBUG - Verifying in api server 10.0.0.23 2026-04-20 02:41:07,166 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/c98c3fa0-2e4f-4699-a49e-7822c5698f08 2026-04-20 02:41:07,173 - DEBUG - Response Code: 404 2026-04-20 02:41:07,173 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/c98c3fa0-2e4f-4699-a49e-7822c5698f08 2026-04-20 02:41:07,179 - DEBUG - Response Code: 404 2026-04-20 02:41:07,179 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/c98c3fa0-2e4f-4699-a49e-7822c5698f08 2026-04-20 02:41:07,184 - DEBUG - Response Code: 404 2026-04-20 02:41:07,184 - INFO - VM ctest-VM31-12784100 is fully removed in API-Server 2026-04-20 02:41:07,344 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found 2026-04-20 02:41:07,387 - DEBUG - Validated that vrouter 10.0.0.240 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN3-05460423 2026-04-20 02:41:07,396 - DEBUG - Validated that vrouter 10.0.0.65 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN3-05460423 2026-04-20 02:41:07,396 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN3-05460423 2026-04-20 02:41:07,396 - INFO - VM ctest-VM31-12784100 is removed in Compute, and routes are removed in all compute nodes 2026-04-20 02:41:07,625 - INFO - Routes for VM ctest-VM31-12784100 is removed in all control-nodes 2026-04-20 02:41:07,625 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5644-2:Compute:contrail-vrouter-agent:0?flat 2026-04-20 02:41:07,654 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5644-2?flat 2026-04-20 02:41:07,680 - DEBUG - VM c98c3fa0-2e4f-4699-a49e-7822c5698f08 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5644-2 uve 2026-04-20 02:41:07,680 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-04-20 02:41:07,681 - DEBUG - interface for vm c98c3fa0-2e4f-4699-a49e-7822c5698f08 deleted from vrouter uve ... 2026-04-20 02:41:07,681 - DEBUG - Deleted interface not in error interface list ... 2026-04-20 02:41:10,682 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5644-2:Compute:contrail-vrouter-agent:0?flat 2026-04-20 02:41:10,715 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5644-2?flat 2026-04-20 02:41:10,742 - DEBUG - VM c98c3fa0-2e4f-4699-a49e-7822c5698f08 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5644-2 uve 2026-04-20 02:41:10,742 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-04-20 02:41:10,742 - DEBUG - interface for vm c98c3fa0-2e4f-4699-a49e-7822c5698f08 deleted from vrouter uve ... 2026-04-20 02:41:10,742 - DEBUG - Deleted interface not in error interface list ... 2026-04-20 02:41:13,742 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5644-2:Compute:contrail-vrouter-agent:0?flat 2026-04-20 02:41:13,775 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5644-2?flat 2026-04-20 02:41:13,806 - DEBUG - VM c98c3fa0-2e4f-4699-a49e-7822c5698f08 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5644-2 uve 2026-04-20 02:41:13,806 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-04-20 02:41:13,806 - DEBUG - interface for vm c98c3fa0-2e4f-4699-a49e-7822c5698f08 deleted from vrouter uve ... 2026-04-20 02:41:13,806 - DEBUG - Deleted interface not in error interface list ... 2026-04-20 02:41:16,807 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5644-2:Compute:contrail-vrouter-agent:0?flat 2026-04-20 02:41:16,842 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5644-2?flat 2026-04-20 02:41:16,880 - DEBUG - VM c98c3fa0-2e4f-4699-a49e-7822c5698f08 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5644-2 uve 2026-04-20 02:41:16,880 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-04-20 02:41:16,880 - DEBUG - interface for vm c98c3fa0-2e4f-4699-a49e-7822c5698f08 deleted from vrouter uve ... 2026-04-20 02:41:16,880 - DEBUG - Deleted interface not in error interface list ... 2026-04-20 02:41:19,883 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5644-2:Compute:contrail-vrouter-agent:0?flat 2026-04-20 02:41:19,922 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5644-2?flat 2026-04-20 02:41:19,955 - DEBUG - VM c98c3fa0-2e4f-4699-a49e-7822c5698f08 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5644-2 uve 2026-04-20 02:41:19,955 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-04-20 02:41:19,955 - DEBUG - interface for vm c98c3fa0-2e4f-4699-a49e-7822c5698f08 deleted from vrouter uve ... 2026-04-20 02:41:19,956 - DEBUG - Deleted interface not in error interface list ... 2026-04-20 02:41:22,957 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5644-2:Compute:contrail-vrouter-agent:0?flat 2026-04-20 02:41:22,987 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5644-2?flat 2026-04-20 02:41:23,013 - DEBUG - VM c98c3fa0-2e4f-4699-a49e-7822c5698f08 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5644-2 uve 2026-04-20 02:41:23,013 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-04-20 02:41:23,013 - DEBUG - interface for vm c98c3fa0-2e4f-4699-a49e-7822c5698f08 deleted from vrouter uve ... 2026-04-20 02:41:23,013 - DEBUG - Deleted interface not in error interface list ... 2026-04-20 02:41:26,014 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5644-2:Compute:contrail-vrouter-agent:0?flat 2026-04-20 02:41:26,054 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5644-2?flat 2026-04-20 02:41:26,086 - DEBUG - VM c98c3fa0-2e4f-4699-a49e-7822c5698f08 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5644-2 uve 2026-04-20 02:41:26,086 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-04-20 02:41:26,086 - DEBUG - interface for vm c98c3fa0-2e4f-4699-a49e-7822c5698f08 deleted from vrouter uve ... 2026-04-20 02:41:26,086 - DEBUG - Deleted interface not in error interface list ... 2026-04-20 02:41:29,086 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5644-2:Compute:contrail-vrouter-agent:0?flat 2026-04-20 02:41:29,119 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5644-2?flat 2026-04-20 02:41:29,145 - DEBUG - VM c98c3fa0-2e4f-4699-a49e-7822c5698f08 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5644-2 uve 2026-04-20 02:41:29,145 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-04-20 02:41:29,145 - DEBUG - interface for vm c98c3fa0-2e4f-4699-a49e-7822c5698f08 deleted from vrouter uve ... 2026-04-20 02:41:29,145 - DEBUG - Deleted interface not in error interface list ... 2026-04-20 02:41:32,146 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5644-2:Compute:contrail-vrouter-agent:0?flat 2026-04-20 02:41:32,176 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5644-2?flat 2026-04-20 02:41:32,203 - DEBUG - VM c98c3fa0-2e4f-4699-a49e-7822c5698f08 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5644-2 uve 2026-04-20 02:41:32,203 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-04-20 02:41:32,203 - DEBUG - interface for vm c98c3fa0-2e4f-4699-a49e-7822c5698f08 deleted from vrouter uve ... 2026-04-20 02:41:32,203 - DEBUG - Deleted interface not in error interface list ... 2026-04-20 02:41:35,204 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5644-2:Compute:contrail-vrouter-agent:0?flat 2026-04-20 02:41:35,239 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5644-2?flat 2026-04-20 02:41:35,265 - DEBUG - VM c98c3fa0-2e4f-4699-a49e-7822c5698f08 is not present in vrouter an-jenkins-deploy-platform-ansible-os-5644-2 uve 2026-04-20 02:41:35,265 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-04-20 02:41:35,265 - DEBUG - interface for vm c98c3fa0-2e4f-4699-a49e-7822c5698f08 deleted from vrouter uve ... 2026-04-20 02:41:35,265 - DEBUG - Deleted interface not in error interface list ... 2026-04-20 02:41:35,265 - DEBUG - Validated that VM c98c3fa0-2e4f-4699-a49e-7822c5698f08 is removed in Vrouter UVE 2026-04-20 02:41:35,265 - DEBUG - Verifying through opserver in 10.0.0.38 2026-04-20 02:41:35,265 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN3-05460423?flat 2026-04-20 02:41:35,283 - DEBUG - c98c3fa0-2e4f-4699-a49e-7822c5698f08 BM not in default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN3-05460423 uve 2026-04-20 02:41:35,283 - DEBUG - Validated that VM c98c3fa0-2e4f-4699-a49e-7822c5698f08 is not present in the VN UVE 2026-04-20 02:41:35,283 - DEBUG - Verifying the c98c3fa0-2e4f-4699-a49e-7822c5698f08 virtual network link through opserver 10.0.0.38 2026-04-20 02:41:35,283 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines 2026-04-20 02:41:35,293 - INFO - c98c3fa0-2e4f-4699-a49e-7822c5698f08 vm uve deleted from opserver 2026-04-20 02:41:35,293 - DEBUG - Verifying the c98c3fa0-2e4f-4699-a49e-7822c5698f08 virtual network link through opserver 10.0.0.27 2026-04-20 02:41:35,293 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines 2026-04-20 02:41:35,305 - INFO - c98c3fa0-2e4f-4699-a49e-7822c5698f08 vm uve deleted from opserver 2026-04-20 02:41:35,305 - DEBUG - Verifying the c98c3fa0-2e4f-4699-a49e-7822c5698f08 virtual network link through opserver 10.0.0.23 2026-04-20 02:41:35,305 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machines 2026-04-20 02:41:35,315 - INFO - c98c3fa0-2e4f-4699-a49e-7822c5698f08 vm uve deleted from opserver 2026-04-20 02:41:35,315 - INFO - Validated that VM c98c3fa0-2e4f-4699-a49e-7822c5698f08 is removed from Opserver 2026-04-20 02:41:35,316 - INFO - Deleting VM ctest-VM21-63716180 2026-04-20 02:41:35,383 - DEBUG - Verifying in api server 10.0.0.23 2026-04-20 02:41:35,383 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/7bce5e96-a95e-4278-82c6-1310c52ee6e6 2026-04-20 02:41:35,390 - DEBUG - VM ID 7bce5e96-a95e-4278-82c6-1310c52ee6e6 of VM ctest-VM21-63716180 is still found in API Server 2026-04-20 02:41:37,391 - DEBUG - Verifying in api server 10.0.0.23 2026-04-20 02:41:37,391 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/7bce5e96-a95e-4278-82c6-1310c52ee6e6 2026-04-20 02:41:37,397 - DEBUG - Response Code: 404 2026-04-20 02:41:37,397 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/7bce5e96-a95e-4278-82c6-1310c52ee6e6 2026-04-20 02:41:37,402 - DEBUG - Response Code: 404 2026-04-20 02:41:37,402 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/7bce5e96-a95e-4278-82c6-1310c52ee6e6 2026-04-20 02:41:37,408 - DEBUG - Response Code: 404 2026-04-20 02:41:37,408 - INFO - VM ctest-VM21-63716180 is fully removed in API-Server 2026-04-20 02:41:37,570 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found 2026-04-20 02:41:37,620 - DEBUG - Validated that vrouter 10.0.0.240 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN2-94744319 2026-04-20 02:41:37,630 - DEBUG - Validated that vrouter 10.0.0.65 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN2-94744319 2026-04-20 02:41:37,630 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN2-94744319 2026-04-20 02:41:37,630 - INFO - VM ctest-VM21-63716180 is removed in Compute, and routes are removed in all compute nodes 2026-04-20 02:41:37,854 - INFO - Routes for VM ctest-VM21-63716180 is removed in all control-nodes 2026-04-20 02:41:37,854 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5644-1:Compute:contrail-vrouter-agent:0?flat 2026-04-20 02:41:37,886 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5644-1?flat 2026-04-20 02:41:37,913 - DEBUG - VM 7bce5e96-a95e-4278-82c6-1310c52ee6e6 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5644-1 uve 2026-04-20 02:41:37,913 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-04-20 02:41:37,913 - DEBUG - interface for vm 7bce5e96-a95e-4278-82c6-1310c52ee6e6 deleted from vrouter uve ... 2026-04-20 02:41:37,913 - DEBUG - Deleted interface not in error interface list ... 2026-04-20 02:41:40,913 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5644-1:Compute:contrail-vrouter-agent:0?flat 2026-04-20 02:41:40,945 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5644-1?flat 2026-04-20 02:41:40,974 - DEBUG - VM 7bce5e96-a95e-4278-82c6-1310c52ee6e6 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5644-1 uve 2026-04-20 02:41:40,974 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-04-20 02:41:40,974 - DEBUG - interface for vm 7bce5e96-a95e-4278-82c6-1310c52ee6e6 deleted from vrouter uve ... 2026-04-20 02:41:40,974 - DEBUG - Deleted interface not in error interface list ... 2026-04-20 02:41:43,975 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5644-1:Compute:contrail-vrouter-agent:0?flat 2026-04-20 02:41:44,009 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5644-1?flat 2026-04-20 02:41:44,045 - DEBUG - VM 7bce5e96-a95e-4278-82c6-1310c52ee6e6 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5644-1 uve 2026-04-20 02:41:44,045 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-04-20 02:41:44,045 - DEBUG - interface for vm 7bce5e96-a95e-4278-82c6-1310c52ee6e6 deleted from vrouter uve ... 2026-04-20 02:41:44,045 - DEBUG - Deleted interface not in error interface list ... 2026-04-20 02:41:47,046 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5644-1:Compute:contrail-vrouter-agent:0?flat 2026-04-20 02:41:47,080 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5644-1?flat 2026-04-20 02:41:47,110 - DEBUG - VM 7bce5e96-a95e-4278-82c6-1310c52ee6e6 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5644-1 uve 2026-04-20 02:41:47,110 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-04-20 02:41:47,110 - DEBUG - interface for vm 7bce5e96-a95e-4278-82c6-1310c52ee6e6 deleted from vrouter uve ... 2026-04-20 02:41:47,110 - DEBUG - Deleted interface not in error interface list ... 2026-04-20 02:41:50,111 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5644-1:Compute:contrail-vrouter-agent:0?flat 2026-04-20 02:41:50,146 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5644-1?flat 2026-04-20 02:41:50,174 - DEBUG - VM 7bce5e96-a95e-4278-82c6-1310c52ee6e6 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5644-1 uve 2026-04-20 02:41:50,174 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-04-20 02:41:50,174 - DEBUG - interface for vm 7bce5e96-a95e-4278-82c6-1310c52ee6e6 deleted from vrouter uve ... 2026-04-20 02:41:50,174 - DEBUG - Deleted interface not in error interface list ... 2026-04-20 02:41:53,175 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5644-1:Compute:contrail-vrouter-agent:0?flat 2026-04-20 02:41:53,216 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5644-1?flat 2026-04-20 02:41:53,248 - DEBUG - VM 7bce5e96-a95e-4278-82c6-1310c52ee6e6 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5644-1 uve 2026-04-20 02:41:53,249 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-04-20 02:41:53,249 - DEBUG - interface for vm 7bce5e96-a95e-4278-82c6-1310c52ee6e6 deleted from vrouter uve ... 2026-04-20 02:41:53,249 - DEBUG - Deleted interface not in error interface list ... 2026-04-20 02:41:56,249 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5644-1:Compute:contrail-vrouter-agent:0?flat 2026-04-20 02:41:56,285 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5644-1?flat 2026-04-20 02:41:56,313 - DEBUG - VM 7bce5e96-a95e-4278-82c6-1310c52ee6e6 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5644-1 uve 2026-04-20 02:41:56,313 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-04-20 02:41:56,313 - DEBUG - interface for vm 7bce5e96-a95e-4278-82c6-1310c52ee6e6 deleted from vrouter uve ... 2026-04-20 02:41:56,313 - DEBUG - Deleted interface not in error interface list ... 2026-04-20 02:41:59,314 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5644-1:Compute:contrail-vrouter-agent:0?flat 2026-04-20 02:41:59,352 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5644-1?flat 2026-04-20 02:41:59,387 - DEBUG - VM 7bce5e96-a95e-4278-82c6-1310c52ee6e6 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5644-1 uve 2026-04-20 02:41:59,387 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-04-20 02:41:59,387 - DEBUG - interface for vm 7bce5e96-a95e-4278-82c6-1310c52ee6e6 deleted from vrouter uve ... 2026-04-20 02:41:59,387 - DEBUG - Deleted interface not in error interface list ... 2026-04-20 02:42:02,389 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5644-1:Compute:contrail-vrouter-agent:0?flat 2026-04-20 02:42:02,423 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5644-1?flat 2026-04-20 02:42:02,454 - DEBUG - VM 7bce5e96-a95e-4278-82c6-1310c52ee6e6 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5644-1 uve 2026-04-20 02:42:02,454 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-04-20 02:42:02,455 - DEBUG - interface for vm 7bce5e96-a95e-4278-82c6-1310c52ee6e6 deleted from vrouter uve ... 2026-04-20 02:42:02,455 - DEBUG - Deleted interface not in error interface list ... 2026-04-20 02:42:05,455 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5644-1:Compute:contrail-vrouter-agent:0?flat 2026-04-20 02:42:05,496 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5644-1?flat 2026-04-20 02:42:05,528 - DEBUG - VM 7bce5e96-a95e-4278-82c6-1310c52ee6e6 is not present in vrouter an-jenkins-deploy-platform-ansible-os-5644-1 uve 2026-04-20 02:42:05,528 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-04-20 02:42:05,528 - DEBUG - interface for vm 7bce5e96-a95e-4278-82c6-1310c52ee6e6 deleted from vrouter uve ... 2026-04-20 02:42:05,528 - DEBUG - Deleted interface not in error interface list ... 2026-04-20 02:42:05,528 - DEBUG - Validated that VM 7bce5e96-a95e-4278-82c6-1310c52ee6e6 is removed in Vrouter UVE 2026-04-20 02:42:05,528 - DEBUG - Verifying through opserver in 10.0.0.38 2026-04-20 02:42:05,528 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN2-94744319?flat 2026-04-20 02:42:05,547 - DEBUG - 7bce5e96-a95e-4278-82c6-1310c52ee6e6 BM not in default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN2-94744319 uve 2026-04-20 02:42:05,547 - DEBUG - Validated that VM 7bce5e96-a95e-4278-82c6-1310c52ee6e6 is not present in the VN UVE 2026-04-20 02:42:05,547 - DEBUG - Verifying the 7bce5e96-a95e-4278-82c6-1310c52ee6e6 virtual network link through opserver 10.0.0.38 2026-04-20 02:42:05,547 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines 2026-04-20 02:42:05,560 - INFO - 7bce5e96-a95e-4278-82c6-1310c52ee6e6 vm uve deleted from opserver 2026-04-20 02:42:05,560 - DEBUG - Verifying the 7bce5e96-a95e-4278-82c6-1310c52ee6e6 virtual network link through opserver 10.0.0.27 2026-04-20 02:42:05,560 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines 2026-04-20 02:42:05,573 - INFO - 7bce5e96-a95e-4278-82c6-1310c52ee6e6 vm uve deleted from opserver 2026-04-20 02:42:05,573 - DEBUG - Verifying the 7bce5e96-a95e-4278-82c6-1310c52ee6e6 virtual network link through opserver 10.0.0.23 2026-04-20 02:42:05,574 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machines 2026-04-20 02:42:05,586 - INFO - 7bce5e96-a95e-4278-82c6-1310c52ee6e6 vm uve deleted from opserver 2026-04-20 02:42:05,586 - INFO - Validated that VM 7bce5e96-a95e-4278-82c6-1310c52ee6e6 is removed from Opserver 2026-04-20 02:42:05,586 - INFO - Deleting VM ctest-VM11-34665054 2026-04-20 02:42:05,674 - DEBUG - Verifying in api server 10.0.0.23 2026-04-20 02:42:05,674 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/98978850-b161-4b4d-bd30-ffef4ec36db5 2026-04-20 02:42:05,683 - DEBUG - VM ID 98978850-b161-4b4d-bd30-ffef4ec36db5 of VM ctest-VM11-34665054 is still found in API Server 2026-04-20 02:42:07,684 - DEBUG - Verifying in api server 10.0.0.23 2026-04-20 02:42:07,684 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/98978850-b161-4b4d-bd30-ffef4ec36db5 2026-04-20 02:42:07,690 - DEBUG - Response Code: 404 2026-04-20 02:42:07,690 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/98978850-b161-4b4d-bd30-ffef4ec36db5 2026-04-20 02:42:07,696 - DEBUG - Response Code: 404 2026-04-20 02:42:07,696 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/98978850-b161-4b4d-bd30-ffef4ec36db5 2026-04-20 02:42:07,701 - DEBUG - Response Code: 404 2026-04-20 02:42:07,701 - INFO - VM ctest-VM11-34665054 is fully removed in API-Server 2026-04-20 02:42:07,855 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found 2026-04-20 02:42:07,905 - DEBUG - Validated that vrouter 10.0.0.240 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697 2026-04-20 02:42:07,915 - DEBUG - Validated that vrouter 10.0.0.65 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697 2026-04-20 02:42:07,915 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697 2026-04-20 02:42:07,915 - INFO - VM ctest-VM11-34665054 is removed in Compute, and routes are removed in all compute nodes 2026-04-20 02:42:08,101 - INFO - Routes for VM ctest-VM11-34665054 is removed in all control-nodes 2026-04-20 02:42:08,101 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5644-2:Compute:contrail-vrouter-agent:0?flat 2026-04-20 02:42:08,132 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5644-2?flat 2026-04-20 02:42:08,159 - DEBUG - VM 98978850-b161-4b4d-bd30-ffef4ec36db5 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5644-2 uve 2026-04-20 02:42:08,159 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-04-20 02:42:08,159 - DEBUG - interface for vm 98978850-b161-4b4d-bd30-ffef4ec36db5 deleted from vrouter uve ... 2026-04-20 02:42:08,159 - DEBUG - Deleted interface not in error interface list ... 2026-04-20 02:42:11,160 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5644-2:Compute:contrail-vrouter-agent:0?flat 2026-04-20 02:42:11,202 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5644-2?flat 2026-04-20 02:42:11,234 - DEBUG - VM 98978850-b161-4b4d-bd30-ffef4ec36db5 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5644-2 uve 2026-04-20 02:42:11,234 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-04-20 02:42:11,235 - DEBUG - interface for vm 98978850-b161-4b4d-bd30-ffef4ec36db5 deleted from vrouter uve ... 2026-04-20 02:42:11,235 - DEBUG - Deleted interface not in error interface list ... 2026-04-20 02:42:14,236 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5644-2:Compute:contrail-vrouter-agent:0?flat 2026-04-20 02:42:14,266 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5644-2?flat 2026-04-20 02:42:14,297 - DEBUG - VM 98978850-b161-4b4d-bd30-ffef4ec36db5 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5644-2 uve 2026-04-20 02:42:14,297 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-04-20 02:42:14,297 - DEBUG - interface for vm 98978850-b161-4b4d-bd30-ffef4ec36db5 deleted from vrouter uve ... 2026-04-20 02:42:14,297 - DEBUG - Deleted interface not in error interface list ... 2026-04-20 02:42:17,298 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5644-2:Compute:contrail-vrouter-agent:0?flat 2026-04-20 02:42:17,330 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5644-2?flat 2026-04-20 02:42:17,362 - DEBUG - VM 98978850-b161-4b4d-bd30-ffef4ec36db5 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5644-2 uve 2026-04-20 02:42:17,362 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-04-20 02:42:17,362 - DEBUG - interface for vm 98978850-b161-4b4d-bd30-ffef4ec36db5 deleted from vrouter uve ... 2026-04-20 02:42:17,362 - DEBUG - Deleted interface not in error interface list ... 2026-04-20 02:42:20,363 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5644-2:Compute:contrail-vrouter-agent:0?flat 2026-04-20 02:42:20,394 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5644-2?flat 2026-04-20 02:42:20,420 - DEBUG - VM 98978850-b161-4b4d-bd30-ffef4ec36db5 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5644-2 uve 2026-04-20 02:42:20,420 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-04-20 02:42:20,420 - DEBUG - interface for vm 98978850-b161-4b4d-bd30-ffef4ec36db5 deleted from vrouter uve ... 2026-04-20 02:42:20,420 - DEBUG - Deleted interface not in error interface list ... 2026-04-20 02:42:23,421 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5644-2:Compute:contrail-vrouter-agent:0?flat 2026-04-20 02:42:23,459 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5644-2?flat 2026-04-20 02:42:23,486 - DEBUG - VM 98978850-b161-4b4d-bd30-ffef4ec36db5 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5644-2 uve 2026-04-20 02:42:23,486 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-04-20 02:42:23,486 - DEBUG - interface for vm 98978850-b161-4b4d-bd30-ffef4ec36db5 deleted from vrouter uve ... 2026-04-20 02:42:23,486 - DEBUG - Deleted interface not in error interface list ... 2026-04-20 02:42:26,487 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5644-2:Compute:contrail-vrouter-agent:0?flat 2026-04-20 02:42:26,517 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5644-2?flat 2026-04-20 02:42:26,543 - DEBUG - VM 98978850-b161-4b4d-bd30-ffef4ec36db5 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5644-2 uve 2026-04-20 02:42:26,543 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-04-20 02:42:26,543 - DEBUG - interface for vm 98978850-b161-4b4d-bd30-ffef4ec36db5 deleted from vrouter uve ... 2026-04-20 02:42:26,543 - DEBUG - Deleted interface not in error interface list ... 2026-04-20 02:42:29,544 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5644-2:Compute:contrail-vrouter-agent:0?flat 2026-04-20 02:42:29,577 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5644-2?flat 2026-04-20 02:42:29,603 - DEBUG - VM 98978850-b161-4b4d-bd30-ffef4ec36db5 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5644-2 uve 2026-04-20 02:42:29,603 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-04-20 02:42:29,603 - DEBUG - interface for vm 98978850-b161-4b4d-bd30-ffef4ec36db5 deleted from vrouter uve ... 2026-04-20 02:42:29,603 - DEBUG - Deleted interface not in error interface list ... 2026-04-20 02:42:32,604 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5644-2:Compute:contrail-vrouter-agent:0?flat 2026-04-20 02:42:32,636 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5644-2?flat 2026-04-20 02:42:32,668 - DEBUG - VM 98978850-b161-4b4d-bd30-ffef4ec36db5 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5644-2 uve 2026-04-20 02:42:32,668 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-04-20 02:42:32,668 - DEBUG - interface for vm 98978850-b161-4b4d-bd30-ffef4ec36db5 deleted from vrouter uve ... 2026-04-20 02:42:32,668 - DEBUG - Deleted interface not in error interface list ... 2026-04-20 02:42:35,668 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5644-2:Compute:contrail-vrouter-agent:0?flat 2026-04-20 02:42:35,698 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5644-2?flat 2026-04-20 02:42:35,723 - DEBUG - VM 98978850-b161-4b4d-bd30-ffef4ec36db5 is not present in vrouter an-jenkins-deploy-platform-ansible-os-5644-2 uve 2026-04-20 02:42:35,723 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-04-20 02:42:35,723 - DEBUG - interface for vm 98978850-b161-4b4d-bd30-ffef4ec36db5 deleted from vrouter uve ... 2026-04-20 02:42:35,723 - DEBUG - Deleted interface not in error interface list ... 2026-04-20 02:42:35,723 - DEBUG - Validated that VM 98978850-b161-4b4d-bd30-ffef4ec36db5 is removed in Vrouter UVE 2026-04-20 02:42:35,724 - DEBUG - Verifying through opserver in 10.0.0.38 2026-04-20 02:42:35,724 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697?flat 2026-04-20 02:42:35,743 - DEBUG - 98978850-b161-4b4d-bd30-ffef4ec36db5 BM not in default-domain:ctest-TestPolicyAclIpv4v6-81143902:ctest-VN1-62804697 uve 2026-04-20 02:42:35,743 - DEBUG - Validated that VM 98978850-b161-4b4d-bd30-ffef4ec36db5 is not present in the VN UVE 2026-04-20 02:42:35,743 - DEBUG - Verifying the 98978850-b161-4b4d-bd30-ffef4ec36db5 virtual network link through opserver 10.0.0.38 2026-04-20 02:42:35,743 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines 2026-04-20 02:42:35,754 - DEBUG - No links retuned for 98978850-b161-4b4d-bd30-ffef4ec36db5 2026-04-20 02:42:35,754 - INFO - 98978850-b161-4b4d-bd30-ffef4ec36db5 vm uve deleted from opserver 2026-04-20 02:42:35,754 - DEBUG - Verifying the 98978850-b161-4b4d-bd30-ffef4ec36db5 virtual network link through opserver 10.0.0.27 2026-04-20 02:42:35,754 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines 2026-04-20 02:42:35,768 - DEBUG - No links retuned for 98978850-b161-4b4d-bd30-ffef4ec36db5 2026-04-20 02:42:35,768 - INFO - 98978850-b161-4b4d-bd30-ffef4ec36db5 vm uve deleted from opserver 2026-04-20 02:42:35,768 - DEBUG - Verifying the 98978850-b161-4b4d-bd30-ffef4ec36db5 virtual network link through opserver 10.0.0.23 2026-04-20 02:42:35,768 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machines 2026-04-20 02:42:35,781 - DEBUG - No links retuned for 98978850-b161-4b4d-bd30-ffef4ec36db5 2026-04-20 02:42:35,781 - INFO - 98978850-b161-4b4d-bd30-ffef4ec36db5 vm uve deleted from opserver 2026-04-20 02:42:35,782 - INFO - Validated that VM 98978850-b161-4b4d-bd30-ffef4ec36db5 is removed from Opserver 2026-04-20 02:42:35,782 - DEBUG - Detaching the Policy for VN :ctest-VN2-94744319 2026-04-20 02:42:35,782 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2026-04-20 02:42:35,788 - DEBUG - Requesting: http://10.0.0.38:8082/domain/c2b9cdf0-ff50-48aa-bc48-322f89577022 2026-04-20 02:42:35,830 - DEBUG - Requesting: http://10.0.0.38:8082/project/74fa0b95-9fe9-45ff-8c77-4ba128239b24 2026-04-20 02:42:35,933 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/b1d22b67-b338-4f45-9248-f60cdae21814 2026-04-20 02:42:35,945 - DEBUG - Requesting: http://10.0.0.38:8082/network-policy/73287d27-d8a0-43c5-ad31-046eb0e9fbcc 2026-04-20 02:42:36,107 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-81143902', 'ctest-policy21-41340521']] from ctest-VN2-94744319 2026-04-20 02:42:36,108 - DEBUG - Detaching the Policy for VN :ctest-VN1-62804697 2026-04-20 02:42:36,108 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2026-04-20 02:42:36,114 - DEBUG - Requesting: http://10.0.0.38:8082/domain/c2b9cdf0-ff50-48aa-bc48-322f89577022 2026-04-20 02:42:36,131 - DEBUG - Requesting: http://10.0.0.38:8082/project/74fa0b95-9fe9-45ff-8c77-4ba128239b24 2026-04-20 02:42:36,231 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/07a9315c-1913-42fa-8f3d-3a76f2077130 2026-04-20 02:42:36,244 - DEBUG - Requesting: http://10.0.0.38:8082/network-policy/2f195aa5-8943-4e91-aceb-6da0691385d9 2026-04-20 02:42:36,262 - DEBUG - Requesting: http://10.0.0.38:8082/network-policy/a0f58364-28d5-41f9-ab7e-58e6f9d9a2b2 2026-04-20 02:42:36,476 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-81143902', 'ctest-policy12-58614454'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-81143902', 'ctest-policy13-41579547']] from ctest-VN1-62804697 2026-04-20 02:42:36,546 - INFO - Deleted policy ctest-policy21-41340521 2026-04-20 02:42:36,601 - INFO - Deleted policy ctest-policy12-58614454 2026-04-20 02:42:36,641 - INFO - Deleted policy ctest-policy13-41579547 2026-04-20 02:42:36,641 - INFO - Deleting VN ctest-VN3-05460423 2026-04-20 02:42:36,824 - DEBUG - Response for deleting network () 2026-04-20 02:42:36,824 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/a27da738-fed2-473c-ad20-91a2964c8ca6 2026-04-20 02:42:36,831 - DEBUG - Response Code: 404 2026-04-20 02:42:36,831 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2026-04-20 02:42:36,841 - DEBUG - Requesting: http://10.0.0.38:8082/domain/c2b9cdf0-ff50-48aa-bc48-322f89577022 2026-04-20 02:42:36,856 - DEBUG - Requesting: http://10.0.0.38:8082/project/74fa0b95-9fe9-45ff-8c77-4ba128239b24 2026-04-20 02:42:36,954 - INFO - Validated that VN ctest-VN3-05460423 is not found in API Server 2026-04-20 02:42:36,975 - DEBUG - VN ctest-VN3-05460423 is not present in Agent 10.0.0.240 2026-04-20 02:42:36,989 - DEBUG - VN ctest-VN3-05460423 is not present in Agent 10.0.0.65 2026-04-20 02:42:36,989 - INFO - Validated that VN ctest-VN3-05460423 is not in any agent 2026-04-20 02:42:37,025 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN3-05460423 info 2026-04-20 02:42:37,025 - INFO - Deleting VN ctest-VN2-94744319 2026-04-20 02:42:37,213 - DEBUG - Response for deleting network () 2026-04-20 02:42:37,213 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/078e0bdf-99b0-480a-821c-b2fdfabddf1a 2026-04-20 02:42:37,221 - DEBUG - Response Code: 404 2026-04-20 02:42:37,221 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2026-04-20 02:42:37,241 - DEBUG - Requesting: http://10.0.0.38:8082/domain/c2b9cdf0-ff50-48aa-bc48-322f89577022 2026-04-20 02:42:37,258 - DEBUG - Requesting: http://10.0.0.38:8082/project/74fa0b95-9fe9-45ff-8c77-4ba128239b24 2026-04-20 02:42:37,351 - INFO - Validated that VN ctest-VN2-94744319 is not found in API Server 2026-04-20 02:42:37,364 - DEBUG - VN ctest-VN2-94744319 is not present in Agent 10.0.0.240 2026-04-20 02:42:37,381 - DEBUG - VN ctest-VN2-94744319 is not present in Agent 10.0.0.65 2026-04-20 02:42:37,381 - INFO - Validated that VN ctest-VN2-94744319 is not in any agent 2026-04-20 02:42:37,413 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN2-94744319 info 2026-04-20 02:42:37,413 - INFO - Deleting VN ctest-VN1-62804697 2026-04-20 02:42:37,603 - DEBUG - Response for deleting network () 2026-04-20 02:42:37,603 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/d6cafa62-2493-4744-9216-9b845c45af88 2026-04-20 02:42:37,608 - DEBUG - Response Code: 404 2026-04-20 02:42:37,608 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2026-04-20 02:42:37,613 - DEBUG - Requesting: http://10.0.0.38:8082/domain/c2b9cdf0-ff50-48aa-bc48-322f89577022 2026-04-20 02:42:37,666 - DEBUG - Requesting: http://10.0.0.38:8082/project/74fa0b95-9fe9-45ff-8c77-4ba128239b24 2026-04-20 02:42:37,768 - INFO - Validated that VN ctest-VN1-62804697 is not found in API Server 2026-04-20 02:42:37,779 - DEBUG - VN ctest-VN1-62804697 is not present in Agent 10.0.0.240 2026-04-20 02:42:37,789 - DEBUG - VN ctest-VN1-62804697 is not present in Agent 10.0.0.65 2026-04-20 02:42:37,789 - INFO - Validated that VN ctest-VN1-62804697 is not in any agent 2026-04-20 02:42:37,819 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN1-62804697 info 2026-04-20 02:42:37,904 - INFO - IPAM: ipam3 is not found in API Server 2026-04-20 02:42:37,923 - INFO - IPAM:ipam3 is not found in control node 2026-04-20 02:42:38,060 - INFO - IPAM: ipam2 is not found in API Server 2026-04-20 02:42:38,082 - INFO - IPAM:ipam2 is not found in control node 2026-04-20 02:42:38,216 - INFO - IPAM: ipam1 is not found in API Server 2026-04-20 02:42:38,239 - INFO - IPAM:ipam1 is not found in control node 2026-04-20 02:42:39,509 - ERROR - XMPP status is {'10.0.0.240': {'10.20.0.129': '0', '10.20.0.25': '0'}, '10.0.0.65': {'10.20.0.25': '0', '10.20.0.129': '1'}}, Expected: {'10.0.0.240': {'10.20.0.129': '0', '10.20.0.25': '0'}, '10.0.0.65': {'10.20.0.25': '0', '10.20.0.129': '0'}} 2026-04-20 02:42:39,509 - INFO -
2026-04-20 02:42:39,509 - INFO - END TEST : test_policy_inheritance_src_pol_dst_any : FAILED[0:03:26]
2026-04-20 02:42:39,509 - INFO - -------------------------------------------------------------------------------- 2026-04-20 02:42:40,760 - ERROR - TestFailed Python 3.9.25: /usr/bin/python3 Mon Apr 20 02:42:39 2026 A problem occurred in a Python script. Here is the sequence of function calls leading up to the error, in the order they occurred. /contrail-test/tcutils/wrappers.py in wrapper(self=, *args=(), **kwargs={}) 78 log.info('Initial checks done. Running the testcase now') 79 log.info('') 80 result = function(self, *args, **kwargs) 81 if self.inputs.upgrade: 82 pid = os.getpid() result = None function = self = args = () kwargs = {} /contrail-test/scripts/policy/test_policy_acl.py in test_policy_inheritance_src_pol_dst_any(self=) 1451 @attr(type=['sanity']) 1452 @tcutils.wrappers.preposttest_wrapper 1453 def test_policy_inheritance_src_pol_dst_any(self): 1454 super(TestPolicyAclIpv4v6, self).test_policy_inheritance_src_pol_dst_any() 1455 builtinsuper = global TestPolicyAclIpv4v6 = self = ).test_policy_inheritance_src_pol_dst_any = > /contrail-test/tcutils/wrappers.py in wrapper(self=, *args=(), **kwargs={}) 174 if 'ci_image' in list(os.environ.keys()): 175 os.environ['stop_execution_flag'] = 'set' 176 raise TestFailed("\n ".join(errmsg)) 177 elif testskip: 178 log.info('') global TestFailed = "\n ".join undefined errmsg = [] TestFailed: __cause__ = None __class__ = __context__ = None __delattr__ = __dict__ = {} __dir__ = __doc__ = None __eq__ = __format__ = __ge__ = __getattribute__ = __gt__ = __hash__ = __init__ = __init_subclass__ = __le__ = __lt__ = __module__ = 'tcutils.cores' __ne__ = __new__ = __reduce__ = __reduce_ex__ = __repr__ = __setattr__ = __setstate__ = __sizeof__ = __str__ = __subclasshook__ = __suppress_context__ = False __traceback__ = __weakref__ = None args = ('',) with_traceback = The above is a description of an error in a Python program. Here is the original traceback: Traceback (most recent call last): File "/contrail-test/tcutils/wrappers.py", line 80, in wrapper result = function(self, *args, **kwargs) File "/contrail-test/scripts/policy/test_policy_acl.py", line 1454, in test_policy_inheritance_src_pol_dst_any super(TestPolicyAclIpv4v6, self).test_policy_inheritance_src_pol_dst_any() File "/contrail-test/tcutils/wrappers.py", line 176, in wrapper raise TestFailed("\n ".join(errmsg)) tcutils.cores.TestFailed 2026-04-20 02:42:40,772 - ERROR - XMPP status is {'10.0.0.240': {'10.20.0.129': '0', '10.20.0.25': '0'}, '10.0.0.65': {'10.20.0.25': '0', '10.20.0.129': '1'}}, Expected: {'10.0.0.240': {'10.20.0.129': '0', '10.20.0.25': '0'}, '10.0.0.65': {'10.20.0.25': '0', '10.20.0.129': '0'}} 2026-04-20 02:42:40,772 - INFO -
2026-04-20 02:42:40,772 - INFO - END TEST : test_policy_inheritance_src_pol_dst_any : FAILED[0:03:28]
2026-04-20 02:42:40,772 - INFO - -------------------------------------------------------------------------------- 2026-04-20 02:42:41,527 - INFO - Deleted project: ctest-TestPolicyAclIpv4v6-81143902, ID : 74fa0b95-9fe9-45ff-8c77-4ba128239b24