2026-08-24 03:00:22,005 - INFO - Domain Default found not creating 2026-08-24 03:00:22,178 - INFO - Project ctest-TestPolicyAclIpv4v6-47405603 not found, creating it 2026-08-24 03:00:22,788 - INFO - Created Project:ctest-TestPolicyAclIpv4v6-47405603, ID : 9dbffe12-ea12-4d8c-a6dc-61d1c04d5594 2026-08-24 03:00:24,563 - INFO - ================================================================================ 2026-08-24 03:00:24,563 - INFO - STARTING TEST : test_policy_inheritance_src_pol_dst_any 2026-08-24 03:00:25,895 - DEBUG - Nothing to compare xmpp stats {'10.0.0.162': {'10.20.0.254': '0', '10.20.0.14': '0'}, '10.0.0.185': {'10.20.0.25': '0', '10.20.0.14': '0'}} with 2026-08-24 03:00:25,895 - INFO - Initial checks done. Running the testcase now 2026-08-24 03:00:25,895 - INFO - 2026-08-24 03:00:25,895 - INFO - ================================================================================ 2026-08-24 03:00:25,895 - INFO - STARTING TEST : test_policy_inheritance_src_pol_dst_any 2026-08-24 03:00:25,895 - INFO - TEST DESCRIPTION : Test cases to test policy inheritance 2026-08-24 03:00:27,183 - DEBUG - Nothing to compare xmpp stats {'10.0.0.162': {'10.20.0.254': '0', '10.20.0.14': '0'}, '10.0.0.185': {'10.20.0.25': '0', '10.20.0.14': '0'}} with 2026-08-24 03:00:27,183 - INFO - Initial checks done. Running the testcase now 2026-08-24 03:00:27,183 - INFO - 2026-08-24 03:00:28,523 - DEBUG - Requesting: http://10.0.0.190:8082/domains 2026-08-24 03:00:28,849 - DEBUG - Requesting: http://10.0.0.190:8082/domains 2026-08-24 03:00:28,880 - DEBUG - Requesting: http://10.0.0.190:8082/domain/a2bfd9a2-e8fd-4333-9d61-3cfdf889946b 2026-08-24 03:00:28,898 - DEBUG - Requesting: http://10.0.0.190:8082/project/9dbffe12-ea12-4d8c-a6dc-61d1c04d5594 2026-08-24 03:00:29,013 - DEBUG - Requesting: http://10.0.0.190:8082/network-ipam/a04eef7b-d6bd-4d84-bf96-86690a0ef20e 2026-08-24 03:00:29,020 - INFO - Verifications in API Server for IPAM: ipam1 passed 2026-08-24 03:00:29,025 - DEBUG - Control-node 10.0.0.190 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-47405603:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-47405603', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11551433409414647172', 'uuid-lslong': '13805369493449273870'}, 'enable': 'true', 'created': '2026-08-24T03:00:27', 'last-modified': '2026-08-24T03:00:27', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.220074'} 2026-08-24 03:00:29,030 - DEBUG - Control-node 10.0.0.50 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-47405603:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-47405603', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11551433409414647172', 'uuid-lslong': '13805369493449273870'}, 'enable': 'true', 'created': '2026-08-24T03:00:27', 'last-modified': '2026-08-24T03:00:27', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.223410'} 2026-08-24 03:00:29,037 - DEBUG - Control-node 10.0.0.191 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-47405603:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-47405603', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11551433409414647172', 'uuid-lslong': '13805369493449273870'}, 'enable': 'true', 'created': '2026-08-24T03:00:27', 'last-modified': '2026-08-24T03:00:27', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.225056'} 2026-08-24 03:00:29,037 - INFO - Verifications in Control node for IPAM: ipam1 passed 2026-08-24 03:00:29,037 - DEBUG - Requesting: http://10.0.0.190:8082/domains 2026-08-24 03:00:29,043 - DEBUG - Requesting: http://10.0.0.190:8082/domain/a2bfd9a2-e8fd-4333-9d61-3cfdf889946b 2026-08-24 03:00:29,054 - DEBUG - Requesting: http://10.0.0.190:8082/project/9dbffe12-ea12-4d8c-a6dc-61d1c04d5594 2026-08-24 03:00:29,154 - DEBUG - Requesting: http://10.0.0.190:8082/network-ipam/b98a0cac-970a-4d3c-8c68-41cb76a51756 2026-08-24 03:00:29,164 - INFO - Verifications in API Server for IPAM: ipam2 passed 2026-08-24 03:00:29,169 - DEBUG - Control-node 10.0.0.190 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-47405603:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-47405603', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13369512379210681660', 'uuid-lslong': '10117408905012516694'}, 'enable': 'true', 'created': '2026-08-24T03:00:27', 'last-modified': '2026-08-24T03:00:28', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.005116'} 2026-08-24 03:00:29,177 - DEBUG - Control-node 10.0.0.50 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-47405603:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-47405603', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13369512379210681660', 'uuid-lslong': '10117408905012516694'}, 'enable': 'true', 'created': '2026-08-24T03:00:27', 'last-modified': '2026-08-24T03:00:28', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.021583'} 2026-08-24 03:00:29,183 - DEBUG - Control-node 10.0.0.191 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-47405603:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-47405603', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13369512379210681660', 'uuid-lslong': '10117408905012516694'}, 'enable': 'true', 'created': '2026-08-24T03:00:27', 'last-modified': '2026-08-24T03:00:28', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.032608'} 2026-08-24 03:00:29,183 - INFO - Verifications in Control node for IPAM: ipam2 passed 2026-08-24 03:00:29,183 - DEBUG - Requesting: http://10.0.0.190:8082/domains 2026-08-24 03:00:29,190 - DEBUG - Requesting: http://10.0.0.190:8082/domain/a2bfd9a2-e8fd-4333-9d61-3cfdf889946b 2026-08-24 03:00:29,203 - DEBUG - Requesting: http://10.0.0.190:8082/project/9dbffe12-ea12-4d8c-a6dc-61d1c04d5594 2026-08-24 03:00:29,315 - DEBUG - Requesting: http://10.0.0.190:8082/network-ipam/95429356-2174-479f-a465-479041cf4ea6 2026-08-24 03:00:29,323 - INFO - Verifications in API Server for IPAM: ipam3 passed 2026-08-24 03:00:29,328 - DEBUG - Control-node 10.0.0.190 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-47405603:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-47405603', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10755320858251904927', 'uuid-lslong': '11845953079772925606'}, 'enable': 'true', 'created': '2026-08-24T03:00:28', 'last-modified': '2026-08-24T03:00:28', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.828450'} 2026-08-24 03:00:29,336 - DEBUG - Control-node 10.0.0.50 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-47405603:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-47405603', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10755320858251904927', 'uuid-lslong': '11845953079772925606'}, 'enable': 'true', 'created': '2026-08-24T03:00:28', 'last-modified': '2026-08-24T03:00:28', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.837850'} 2026-08-24 03:00:29,342 - DEBUG - Control-node 10.0.0.191 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-47405603:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-47405603', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10755320858251904927', 'uuid-lslong': '11845953079772925606'}, 'enable': 'true', 'created': '2026-08-24T03:00:28', 'last-modified': '2026-08-24T03:00:28', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.843516'} 2026-08-24 03:00:29,342 - INFO - Verifications in Control node for IPAM: ipam3 passed 2026-08-24 03:00:29,708 - DEBUG - Response for create_network : {'network': {'id': 'f3a31965-8e9f-4426-b689-9f2cfa018167', 'name': 'ctest-VN1-64192983', 'tenant_id': '9dbffe12ea124d8ca6dc61d1c04d5594', 'project_id': '9dbffe12ea124d8ca6dc61d1c04d5594', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-47405603', 'ctest-VN1-64192983'], 'port_security_enabled': True, 'description': ''}} 2026-08-24 03:00:30,080 - DEBUG - Response for create_subnet : {'subnet': {'id': '78ea7da7-d2b4-448a-b32c-2870009d2e2c', 'name': '', 'tenant_id': '9dbffe12ea124d8ca6dc61d1c04d5594', 'network_id': 'f3a31965-8e9f-4426-b689-9f2cfa018167', 'ip_version': 4, 'cidr': '168.204.208.192/26', 'allocation_pools': [{'start': '168.204.208.194', 'end': '168.204.208.254'}], 'gateway_ip': '168.204.208.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '168.204.208.194', 'tags': [], 'project_id': '9dbffe12ea124d8ca6dc61d1c04d5594'}} 2026-08-24 03:00:30,317 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b9fd8726-48a6-44eb-8360-d4772f47c444', 'name': '', 'tenant_id': '9dbffe12ea124d8ca6dc61d1c04d5594', 'network_id': 'f3a31965-8e9f-4426-b689-9f2cfa018167', 'ip_version': 6, 'cidr': '3ce7:9423:90fd:1de3:97fa:107f::/96', 'allocation_pools': [{'start': '3ce7:9423:90fd:1de3:97fa:107f:0:2', 'end': '3ce7:9423:90fd:1de3:97fa:107f:ffff:fffe'}], 'gateway_ip': '3ce7:9423:90fd:1de3:97fa:107f:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '3ce7:9423:90fd:1de3:97fa:107f:0:2', 'tags': [], 'project_id': '9dbffe12ea124d8ca6dc61d1c04d5594'}} 2026-08-24 03:00:30,351 - INFO - Created VN ctest-VN1-64192983 2026-08-24 03:00:30,366 - DEBUG - VN ctest-VN1-64192983 UUID is f3a31965-8e9f-4426-b689-9f2cfa018167 2026-08-24 03:00:30,568 - DEBUG - Fetched VN: default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983(f3a31965-8e9f-4426-b689-9f2cfa018167) with subnets ['168.204.208.192/26', '3ce7:9423:90fd:1de3:97fa:107f::/96'] 2026-08-24 03:00:30,965 - DEBUG - Response for create_network : {'network': {'id': 'f2fe6f73-367a-49b4-97ad-ff839468e541', 'name': 'ctest-VN2-94781241', 'tenant_id': '9dbffe12ea124d8ca6dc61d1c04d5594', 'project_id': '9dbffe12ea124d8ca6dc61d1c04d5594', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-47405603', 'ctest-VN2-94781241'], 'port_security_enabled': True, 'description': ''}} 2026-08-24 03:00:31,214 - DEBUG - Response for create_subnet : {'subnet': {'id': '3b4396f0-caa8-404c-91e6-1b0d9196da73', 'name': '', 'tenant_id': '9dbffe12ea124d8ca6dc61d1c04d5594', 'network_id': 'f2fe6f73-367a-49b4-97ad-ff839468e541', 'ip_version': 4, 'cidr': '45.247.53.64/26', 'allocation_pools': [{'start': '45.247.53.66', 'end': '45.247.53.126'}], 'gateway_ip': '45.247.53.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '45.247.53.66', 'tags': [], 'project_id': '9dbffe12ea124d8ca6dc61d1c04d5594'}} 2026-08-24 03:00:31,448 - DEBUG - Response for create_subnet : {'subnet': {'id': '85dc5694-4023-481e-b3a2-caa2ab68b6ad', 'name': '', 'tenant_id': '9dbffe12ea124d8ca6dc61d1c04d5594', 'network_id': 'f2fe6f73-367a-49b4-97ad-ff839468e541', 'ip_version': 6, 'cidr': '2ad9:9712:91c9:614a:264:8ff4::/96', 'allocation_pools': [{'start': '2ad9:9712:91c9:614a:264:8ff4:0:2', 'end': '2ad9:9712:91c9:614a:264:8ff4:ffff:fffe'}], 'gateway_ip': '2ad9:9712:91c9:614a:264:8ff4:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '2ad9:9712:91c9:614a:264:8ff4:0:2', 'tags': [], 'project_id': '9dbffe12ea124d8ca6dc61d1c04d5594'}} 2026-08-24 03:00:31,490 - INFO - Created VN ctest-VN2-94781241 2026-08-24 03:00:31,504 - DEBUG - VN ctest-VN2-94781241 UUID is f2fe6f73-367a-49b4-97ad-ff839468e541 2026-08-24 03:00:31,969 - DEBUG - Response for create_network : {'network': {'id': '82cffe03-d686-4148-aac4-60d897bf6bd3', 'name': 'ctest-VN3-81445529', 'tenant_id': '9dbffe12ea124d8ca6dc61d1c04d5594', 'project_id': '9dbffe12ea124d8ca6dc61d1c04d5594', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-47405603', 'ctest-VN3-81445529'], 'port_security_enabled': True, 'description': ''}} 2026-08-24 03:00:32,309 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ee381f49-d963-4080-98ef-3ce98a0fcfef', 'name': '', 'tenant_id': '9dbffe12ea124d8ca6dc61d1c04d5594', 'network_id': '82cffe03-d686-4148-aac4-60d897bf6bd3', 'ip_version': 4, 'cidr': '216.20.205.128/26', 'allocation_pools': [{'start': '216.20.205.130', 'end': '216.20.205.190'}], 'gateway_ip': '216.20.205.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '216.20.205.130', 'tags': [], 'project_id': '9dbffe12ea124d8ca6dc61d1c04d5594'}} 2026-08-24 03:00:32,523 - DEBUG - Response for create_subnet : {'subnet': {'id': 'de79a8a8-f756-48fd-a50b-32dc01a381e7', 'name': '', 'tenant_id': '9dbffe12ea124d8ca6dc61d1c04d5594', 'network_id': '82cffe03-d686-4148-aac4-60d897bf6bd3', 'ip_version': 6, 'cidr': '32e2:4365:2b82:49f5:eed6:a36a::/96', 'allocation_pools': [{'start': '32e2:4365:2b82:49f5:eed6:a36a:0:2', 'end': '32e2:4365:2b82:49f5:eed6:a36a:ffff:fffe'}], 'gateway_ip': '32e2:4365:2b82:49f5:eed6:a36a:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '32e2:4365:2b82:49f5:eed6:a36a:0:2', 'tags': [], 'project_id': '9dbffe12ea124d8ca6dc61d1c04d5594'}} 2026-08-24 03:00:32,549 - INFO - Created VN ctest-VN3-81445529 2026-08-24 03:00:32,564 - DEBUG - VN ctest-VN3-81445529 UUID is 82cffe03-d686-4148-aac4-60d897bf6bd3 2026-08-24 03:00:32,700 - DEBUG - Requesting: http://10.0.0.190:8082/domains 2026-08-24 03:00:32,707 - DEBUG - Requesting: http://10.0.0.190:8082/domain/a2bfd9a2-e8fd-4333-9d61-3cfdf889946b 2026-08-24 03:00:32,722 - DEBUG - Requesting: http://10.0.0.190:8082/project/9dbffe12-ea12-4d8c-a6dc-61d1c04d5594 2026-08-24 03:00:32,824 - DEBUG - Requesting: http://10.0.0.190:8082/virtual-network/f3a31965-8e9f-4426-b689-9f2cfa018167 2026-08-24 03:00:32,855 - DEBUG - Requesting: http://10.0.0.190:8082/virtual-network/f3a31965-8e9f-4426-b689-9f2cfa018167 2026-08-24 03:00:32,868 - DEBUG - Requesting: http://10.0.0.190:8082/routing-instance/327c14ba-4f9f-45e0-b43b-c46e900cfeb9 2026-08-24 03:00:32,884 - DEBUG - Requesting: http://10.0.0.190:8082/routing-instance/327c14ba-4f9f-45e0-b43b-c46e900cfeb9 2026-08-24 03:00:32,901 - DEBUG - Requesting: http://10.0.0.190:8082/route-target/2d51638d-8c7f-4b24-b297-0ea6411aa572 2026-08-24 03:00:32,912 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-08-24 03:00:32,912 - DEBUG - Requesting: http://10.0.0.190:8082/virtual-network/f3a31965-8e9f-4426-b689-9f2cfa018167 2026-08-24 03:00:32,930 - DEBUG - Requesting: http://10.0.0.190:8082/routing-instance/327c14ba-4f9f-45e0-b43b-c46e900cfeb9 2026-08-24 03:00:32,949 - INFO - Verified VN network id 15 for VN f3a31965-8e9f-4426-b689-9f2cfa018167 2026-08-24 03:00:32,949 - INFO - Verifications in API Server for VN ctest-VN1-64192983 passed 2026-08-24 03:00:32,949 - DEBUG - Requesting: http://10.0.0.190:8082/virtual-network/f3a31965-8e9f-4426-b689-9f2cfa018167 2026-08-24 03:00:32,959 - DEBUG - Requesting: http://10.0.0.190:8082/routing-instance/327c14ba-4f9f-45e0-b43b-c46e900cfeb9 2026-08-24 03:00:32,967 - DEBUG - Requesting: http://10.0.0.190:8082/routing-instance/327c14ba-4f9f-45e0-b43b-c46e900cfeb9 2026-08-24 03:00:32,978 - DEBUG - Requesting: http://10.0.0.190:8082/route-target/2d51638d-8c7f-4b24-b297-0ea6411aa572 2026-08-24 03:00:32,991 - DEBUG - Control-node 10.0.0.190 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-47405603', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17555903696395519014', 'uuid-lslong': '13153219202234024295'}, 'enable': 'true', 'created': '2026-08-24T03:00:29', 'last-modified': '2026-08-24T03:00:30', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.743594'} 2026-08-24 03:00:32,996 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-08-24 03:00:33,003 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-47405603', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17555903696395519014', 'uuid-lslong': '13153219202234024295'}, 'enable': 'true', 'created': '2026-08-24T03:00:29', 'last-modified': '2026-08-24T03:00:30', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.754274'} 2026-08-24 03:00:33,011 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-08-24 03:00:33,017 - DEBUG - Control-node 10.0.0.191 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-47405603', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17555903696395519014', 'uuid-lslong': '13153219202234024295'}, 'enable': 'true', 'created': '2026-08-24T03:00:29', 'last-modified': '2026-08-24T03:00:30', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.774874'} 2026-08-24 03:00:33,024 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-08-24 03:00:33,025 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN1-64192983 passed 2026-08-24 03:00:33,025 - DEBUG - ====Verifying policy data for ctest-VN1-64192983 in API_Server ====== 2026-08-24 03:00:33,025 - DEBUG - Requesting: http://10.0.0.190:8082/domains 2026-08-24 03:00:33,034 - DEBUG - Requesting: http://10.0.0.190:8082/domain/a2bfd9a2-e8fd-4333-9d61-3cfdf889946b 2026-08-24 03:00:33,047 - DEBUG - Requesting: http://10.0.0.190:8082/project/9dbffe12-ea12-4d8c-a6dc-61d1c04d5594 2026-08-24 03:00:33,153 - DEBUG - Requesting: http://10.0.0.190:8082/virtual-network/f3a31965-8e9f-4426-b689-9f2cfa018167 2026-08-24 03:00:33,175 - DEBUG - =>VN ctest-VN1-64192983 has no policy to be verified 2026-08-24 03:00:33,175 - DEBUG - Verifying the vn in opserver 2026-08-24 03:00:33,175 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983 virtual network link through opserver 10.0.0.190 2026-08-24 03:00:33,175 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/virtual-networks 2026-08-24 03:00:33,527 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/virtual-networks 2026-08-24 03:00:33,621 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983', 'href': 'http://10.0.0.190:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983?flat'} 2026-08-24 03:00:33,621 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983 virtual network link through opserver 10.0.0.50 2026-08-24 03:00:33,621 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks 2026-08-24 03:00:33,972 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks 2026-08-24 03:00:34,071 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983?flat'} 2026-08-24 03:00:34,071 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983 virtual network link through opserver 10.0.0.191 2026-08-24 03:00:34,071 - DEBUG - Requesting: http://10.0.0.191:8081/analytics/uves/virtual-networks 2026-08-24 03:00:34,444 - DEBUG - Requesting: http://10.0.0.191:8081/analytics/uves/virtual-networks 2026-08-24 03:00:34,504 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983', 'href': 'http://10.0.0.191:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983?flat'} 2026-08-24 03:00:34,504 - INFO - Validated that VN default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983 is found in opserver 2026-08-24 03:00:34,526 - DEBUG - Do not have enough data to verify VN in agent 2026-08-24 03:00:34,536 - DEBUG - VRF ids for VN ctest-VN1-64192983: {} 2026-08-24 03:00:34,536 - DEBUG - Requesting: http://10.0.0.190:8082/domains 2026-08-24 03:00:34,542 - DEBUG - Requesting: http://10.0.0.190:8082/domain/a2bfd9a2-e8fd-4333-9d61-3cfdf889946b 2026-08-24 03:00:34,554 - DEBUG - Requesting: http://10.0.0.190:8082/project/9dbffe12-ea12-4d8c-a6dc-61d1c04d5594 2026-08-24 03:00:34,674 - DEBUG - Requesting: http://10.0.0.190:8082/virtual-network/f2fe6f73-367a-49b4-97ad-ff839468e541 2026-08-24 03:00:34,696 - DEBUG - Requesting: http://10.0.0.190:8082/virtual-network/f2fe6f73-367a-49b4-97ad-ff839468e541 2026-08-24 03:00:34,710 - DEBUG - Requesting: http://10.0.0.190:8082/routing-instance/9d270d93-35b8-4bf5-b0fb-ddd6837dae92 2026-08-24 03:00:34,728 - DEBUG - Requesting: http://10.0.0.190:8082/routing-instance/9d270d93-35b8-4bf5-b0fb-ddd6837dae92 2026-08-24 03:00:34,742 - DEBUG - Requesting: http://10.0.0.190:8082/route-target/b15221c7-4e2a-4779-a97f-34ca6d73cc21 2026-08-24 03:00:34,753 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-08-24 03:00:34,753 - DEBUG - Requesting: http://10.0.0.190:8082/virtual-network/f2fe6f73-367a-49b4-97ad-ff839468e541 2026-08-24 03:00:34,767 - DEBUG - Requesting: http://10.0.0.190:8082/routing-instance/9d270d93-35b8-4bf5-b0fb-ddd6837dae92 2026-08-24 03:00:34,785 - INFO - Verified VN network id 16 for VN f2fe6f73-367a-49b4-97ad-ff839468e541 2026-08-24 03:00:34,785 - INFO - Verifications in API Server for VN ctest-VN2-94781241 passed 2026-08-24 03:00:34,786 - DEBUG - Requesting: http://10.0.0.190:8082/virtual-network/f2fe6f73-367a-49b4-97ad-ff839468e541 2026-08-24 03:00:34,797 - DEBUG - Requesting: http://10.0.0.190:8082/routing-instance/9d270d93-35b8-4bf5-b0fb-ddd6837dae92 2026-08-24 03:00:34,809 - DEBUG - Requesting: http://10.0.0.190:8082/routing-instance/9d270d93-35b8-4bf5-b0fb-ddd6837dae92 2026-08-24 03:00:34,820 - DEBUG - Requesting: http://10.0.0.190:8082/route-target/b15221c7-4e2a-4779-a97f-34ca6d73cc21 2026-08-24 03:00:34,839 - DEBUG - Control-node 10.0.0.190 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN2-94781241', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-47405603', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17509554941888973236', 'uuid-lslong': '10929672811293762881'}, 'enable': 'true', 'created': '2026-08-24T03:00:30', 'last-modified': '2026-08-24T03:00:31', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.447193'} 2026-08-24 03:00:34,852 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-08-24 03:00:34,859 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN2-94781241', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-47405603', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17509554941888973236', 'uuid-lslong': '10929672811293762881'}, 'enable': 'true', 'created': '2026-08-24T03:00:30', 'last-modified': '2026-08-24T03:00:31', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.474780'} 2026-08-24 03:00:34,866 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-08-24 03:00:34,873 - DEBUG - Control-node 10.0.0.191 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN2-94781241', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-47405603', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17509554941888973236', 'uuid-lslong': '10929672811293762881'}, 'enable': 'true', 'created': '2026-08-24T03:00:30', 'last-modified': '2026-08-24T03:00:31', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.480694'} 2026-08-24 03:00:34,881 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-08-24 03:00:34,881 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN2-94781241 passed 2026-08-24 03:00:34,881 - DEBUG - ====Verifying policy data for ctest-VN2-94781241 in API_Server ====== 2026-08-24 03:00:34,882 - DEBUG - Requesting: http://10.0.0.190:8082/domains 2026-08-24 03:00:34,889 - DEBUG - Requesting: http://10.0.0.190:8082/domain/a2bfd9a2-e8fd-4333-9d61-3cfdf889946b 2026-08-24 03:00:34,905 - DEBUG - Requesting: http://10.0.0.190:8082/project/9dbffe12-ea12-4d8c-a6dc-61d1c04d5594 2026-08-24 03:00:35,014 - DEBUG - Requesting: http://10.0.0.190:8082/virtual-network/f2fe6f73-367a-49b4-97ad-ff839468e541 2026-08-24 03:00:35,034 - DEBUG - =>VN ctest-VN2-94781241 has no policy to be verified 2026-08-24 03:00:35,034 - DEBUG - Verifying the vn in opserver 2026-08-24 03:00:35,034 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN2-94781241 virtual network link through opserver 10.0.0.190 2026-08-24 03:00:35,034 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/virtual-networks 2026-08-24 03:00:35,091 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN2-94781241', 'href': 'http://10.0.0.190:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN2-94781241?flat'} 2026-08-24 03:00:35,091 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN2-94781241 virtual network link through opserver 10.0.0.50 2026-08-24 03:00:35,091 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks 2026-08-24 03:00:35,139 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN2-94781241', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN2-94781241?flat'} 2026-08-24 03:00:35,139 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN2-94781241 virtual network link through opserver 10.0.0.191 2026-08-24 03:00:35,139 - DEBUG - Requesting: http://10.0.0.191:8081/analytics/uves/virtual-networks 2026-08-24 03:00:35,200 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN2-94781241', 'href': 'http://10.0.0.191:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN2-94781241?flat'} 2026-08-24 03:00:35,200 - INFO - Validated that VN default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN2-94781241 is found in opserver 2026-08-24 03:00:35,221 - DEBUG - Do not have enough data to verify VN in agent 2026-08-24 03:00:35,229 - DEBUG - VRF ids for VN ctest-VN2-94781241: {} 2026-08-24 03:00:35,229 - DEBUG - Requesting: http://10.0.0.190:8082/domains 2026-08-24 03:00:35,236 - DEBUG - Requesting: http://10.0.0.190:8082/domain/a2bfd9a2-e8fd-4333-9d61-3cfdf889946b 2026-08-24 03:00:35,249 - DEBUG - Requesting: http://10.0.0.190:8082/project/9dbffe12-ea12-4d8c-a6dc-61d1c04d5594 2026-08-24 03:00:35,339 - DEBUG - Requesting: http://10.0.0.190:8082/virtual-network/82cffe03-d686-4148-aac4-60d897bf6bd3 2026-08-24 03:00:35,359 - DEBUG - Requesting: http://10.0.0.190:8082/virtual-network/82cffe03-d686-4148-aac4-60d897bf6bd3 2026-08-24 03:00:35,372 - DEBUG - Requesting: http://10.0.0.190:8082/routing-instance/555aab23-d608-4045-bf2d-f9882c2b9dcf 2026-08-24 03:00:35,382 - DEBUG - Requesting: http://10.0.0.190:8082/routing-instance/555aab23-d608-4045-bf2d-f9882c2b9dcf 2026-08-24 03:00:35,391 - DEBUG - Requesting: http://10.0.0.190:8082/route-target/f889172a-30ac-499a-a89f-e1c6ed2696e1 2026-08-24 03:00:35,399 - DEBUG - Route Targets: ['target:64512:8000007'] 2026-08-24 03:00:35,399 - DEBUG - Requesting: http://10.0.0.190:8082/virtual-network/82cffe03-d686-4148-aac4-60d897bf6bd3 2026-08-24 03:00:35,409 - DEBUG - Requesting: http://10.0.0.190:8082/routing-instance/555aab23-d608-4045-bf2d-f9882c2b9dcf 2026-08-24 03:00:35,425 - INFO - Verified VN network id 17 for VN 82cffe03-d686-4148-aac4-60d897bf6bd3 2026-08-24 03:00:35,425 - INFO - Verifications in API Server for VN ctest-VN3-81445529 passed 2026-08-24 03:00:35,425 - DEBUG - Requesting: http://10.0.0.190:8082/virtual-network/82cffe03-d686-4148-aac4-60d897bf6bd3 2026-08-24 03:00:35,436 - DEBUG - Requesting: http://10.0.0.190:8082/routing-instance/555aab23-d608-4045-bf2d-f9882c2b9dcf 2026-08-24 03:00:35,446 - DEBUG - Requesting: http://10.0.0.190:8082/routing-instance/555aab23-d608-4045-bf2d-f9882c2b9dcf 2026-08-24 03:00:35,454 - DEBUG - Requesting: http://10.0.0.190:8082/route-target/f889172a-30ac-499a-a89f-e1c6ed2696e1 2026-08-24 03:00:35,465 - DEBUG - Control-node 10.0.0.190 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN3-81445529', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-47405603', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9426031837547217224', 'uuid-lslong': '12305066565258144723'}, 'enable': 'true', 'created': '2026-08-24T03:00:31', 'last-modified': '2026-08-24T03:00:32', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.988092'} 2026-08-24 03:00:35,471 - DEBUG - Route Targets: ['target:64512:8000007'] 2026-08-24 03:00:35,476 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN3-81445529', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-47405603', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9426031837547217224', 'uuid-lslong': '12305066565258144723'}, 'enable': 'true', 'created': '2026-08-24T03:00:31', 'last-modified': '2026-08-24T03:00:32', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.002969'} 2026-08-24 03:00:35,483 - DEBUG - Route Targets: ['target:64512:8000007'] 2026-08-24 03:00:35,488 - DEBUG - Control-node 10.0.0.191 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN3-81445529', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-47405603', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9426031837547217224', 'uuid-lslong': '12305066565258144723'}, 'enable': 'true', 'created': '2026-08-24T03:00:31', 'last-modified': '2026-08-24T03:00:32', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.010664'} 2026-08-24 03:00:35,494 - DEBUG - Route Targets: ['target:64512:8000007'] 2026-08-24 03:00:35,494 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN3-81445529 passed 2026-08-24 03:00:35,494 - DEBUG - ====Verifying policy data for ctest-VN3-81445529 in API_Server ====== 2026-08-24 03:00:35,495 - DEBUG - Requesting: http://10.0.0.190:8082/domains 2026-08-24 03:00:35,501 - DEBUG - Requesting: http://10.0.0.190:8082/domain/a2bfd9a2-e8fd-4333-9d61-3cfdf889946b 2026-08-24 03:00:35,514 - DEBUG - Requesting: http://10.0.0.190:8082/project/9dbffe12-ea12-4d8c-a6dc-61d1c04d5594 2026-08-24 03:00:35,626 - DEBUG - Requesting: http://10.0.0.190:8082/virtual-network/82cffe03-d686-4148-aac4-60d897bf6bd3 2026-08-24 03:00:35,637 - DEBUG - =>VN ctest-VN3-81445529 has no policy to be verified 2026-08-24 03:00:35,637 - DEBUG - Verifying the vn in opserver 2026-08-24 03:00:35,637 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN3-81445529 virtual network link through opserver 10.0.0.190 2026-08-24 03:00:35,637 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/virtual-networks 2026-08-24 03:00:35,695 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN3-81445529', 'href': 'http://10.0.0.190:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN3-81445529?flat'} 2026-08-24 03:00:35,695 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN3-81445529 virtual network link through opserver 10.0.0.50 2026-08-24 03:00:35,695 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks 2026-08-24 03:00:35,752 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN3-81445529', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN3-81445529?flat'} 2026-08-24 03:00:35,752 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN3-81445529 virtual network link through opserver 10.0.0.191 2026-08-24 03:00:35,752 - DEBUG - Requesting: http://10.0.0.191:8081/analytics/uves/virtual-networks 2026-08-24 03:00:35,812 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN3-81445529', 'href': 'http://10.0.0.191:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN3-81445529?flat'} 2026-08-24 03:00:35,812 - INFO - Validated that VN default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN3-81445529 is found in opserver 2026-08-24 03:00:35,834 - DEBUG - Do not have enough data to verify VN in agent 2026-08-24 03:00:35,845 - DEBUG - VRF ids for VN ctest-VN3-81445529: {} 2026-08-24 03:00:35,851 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983, 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-47405603:ctest-VN3-81445529, 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-08-24 03:00:36,147 - DEBUG - No source network defined 2026-08-24 03:00:36,147 - DEBUG - No source network defined 2026-08-24 03:00:36,148 - 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-47405603:ctest-policy13-70947070, 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-47405603:ctest-policy13-70947070, 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-08-24 03:00:36,431 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN2-94781241, 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-47405603:ctest-VN1-64192983, 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-47405603:ctest-VN2-94781241, 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-47405603:ctest-VN1-64192983, 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-08-24 03:00:36,649 - DEBUG - Requesting: http://10.0.0.190:8082/domains 2026-08-24 03:00:36,655 - DEBUG - Requesting: http://10.0.0.190:8082/domain/a2bfd9a2-e8fd-4333-9d61-3cfdf889946b 2026-08-24 03:00:36,669 - DEBUG - Requesting: http://10.0.0.190:8082/project/9dbffe12-ea12-4d8c-a6dc-61d1c04d5594 2026-08-24 03:00:36,795 - DEBUG - Requesting: http://10.0.0.190:8082/virtual-network/f3a31965-8e9f-4426-b689-9f2cfa018167 2026-08-24 03:00:36,807 - DEBUG - Setup step: Associating the policy to VN' 2026-08-24 03:00:36,968 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': 'f3a31965-8e9f-4426-b689-9f2cfa018167', 'name': 'ctest-VN1-64192983', 'tenant_id': '9dbffe12ea124d8ca6dc61d1c04d5594', 'project_id': '9dbffe12ea124d8ca6dc61d1c04d5594', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['78ea7da7-d2b4-448a-b32c-2870009d2e2c', 'b9fd8726-48a6-44eb-8360-d4772f47c444'], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-47405603', 'ctest-VN1-64192983'], 'policys': [['default-domain', 'ctest-TestPolicyAclIpv4v6-47405603', 'ctest-policy12-91550977'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-47405603', 'ctest-policy13-70947070']], 'subnet_ipam': [{'subnet_cidr': '168.204.208.192/26', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-47405603', 'ipam1']}, {'subnet_cidr': '3ce7:9423:90fd:1de3:97fa:107f::/96', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-47405603', 'ipam1']}], 'port_security_enabled': True, 'description': ''}} 2026-08-24 03:00:37,143 - DEBUG - Associated Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-47405603', 'ctest-policy12-91550977'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-47405603', 'ctest-policy13-70947070']] to ctest-VN1-64192983 2026-08-24 03:00:37,143 - DEBUG - Requesting: http://10.0.0.190:8082/domains 2026-08-24 03:00:37,153 - DEBUG - Requesting: http://10.0.0.190:8082/domain/a2bfd9a2-e8fd-4333-9d61-3cfdf889946b 2026-08-24 03:00:37,165 - DEBUG - Requesting: http://10.0.0.190:8082/project/9dbffe12-ea12-4d8c-a6dc-61d1c04d5594 2026-08-24 03:00:37,255 - DEBUG - Requesting: http://10.0.0.190:8082/virtual-network/f2fe6f73-367a-49b4-97ad-ff839468e541 2026-08-24 03:00:37,278 - DEBUG - Setup step: Associating the policy to VN' 2026-08-24 03:00:37,424 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': 'f2fe6f73-367a-49b4-97ad-ff839468e541', 'name': 'ctest-VN2-94781241', 'tenant_id': '9dbffe12ea124d8ca6dc61d1c04d5594', 'project_id': '9dbffe12ea124d8ca6dc61d1c04d5594', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['3b4396f0-caa8-404c-91e6-1b0d9196da73', '85dc5694-4023-481e-b3a2-caa2ab68b6ad'], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-47405603', 'ctest-VN2-94781241'], 'policys': [['default-domain', 'ctest-TestPolicyAclIpv4v6-47405603', 'ctest-policy21-47621129']], 'subnet_ipam': [{'subnet_cidr': '45.247.53.64/26', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-47405603', 'ipam2']}, {'subnet_cidr': '2ad9:9712:91c9:614a:264:8ff4::/96', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-47405603', 'ipam2']}], 'port_security_enabled': True, 'description': ''}} 2026-08-24 03:00:37,534 - DEBUG - Associated Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-47405603', 'ctest-policy21-47621129']] to ctest-VN2-94781241 2026-08-24 03:00:37,655 - DEBUG - Services list from nova: [, , , ] 2026-08-24 03:00:38,211 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-6211-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-6211-1) 2026-08-24 03:00:38,819 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-6211-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-6211-2) 2026-08-24 03:00:39,564 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-6211-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-6211-1) 2026-08-24 03:00:39,620 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-24 03:00:44,795 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-24 03:00:49,894 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-24 03:00:54,983 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-24 03:01:00,077 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-24 03:01:05,166 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-24 03:01:10,264 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-24 03:01:15,361 - DEBUG - VM is in ACTIVE state now 2026-08-24 03:01:15,361 - INFO - VM name : ctest-VM11-23808320 2026-08-24 03:01:15,468 - DEBUG - VM ctest-VM11-23808320 ID is 182fb518-f13e-4d2b-9982-ecfcb6dd716f 2026-08-24 03:01:15,572 - DEBUG - VM is in ACTIVE state now 2026-08-24 03:01:15,616 - DEBUG - VM ctest-VM11-23808320 launched on Node an-jenkins-deploy-platform-ansible-os-6211-1 2026-08-24 03:01:15,807 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine/182fb518-f13e-4d2b-9982-ecfcb6dd716f 2026-08-24 03:01:16,138 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine/182fb518-f13e-4d2b-9982-ecfcb6dd716f 2026-08-24 03:01:16,178 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine/182fb518-f13e-4d2b-9982-ecfcb6dd716f 2026-08-24 03:01:16,186 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine-interface/df9ac98c-a1d5-461f-aba5-77000c92b95d 2026-08-24 03:01:16,197 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine/182fb518-f13e-4d2b-9982-ecfcb6dd716f 2026-08-24 03:01:16,205 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine-interface/df9ac98c-a1d5-461f-aba5-77000c92b95d 2026-08-24 03:01:16,215 - DEBUG - Requesting: http://10.0.0.191:8082/instance-ip/41b75df0-1edf-489f-b421-2bdf68914466 2026-08-24 03:01:16,231 - DEBUG - Requesting: http://10.0.0.191:8082/instance-ip/7ad4c8f6-2601-4a72-bb3c-9bc97f4b7a0b 2026-08-24 03:01:16,245 - DEBUG - Verifying in api server 10.0.0.191 2026-08-24 03:01:16,245 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine/182fb518-f13e-4d2b-9982-ecfcb6dd716f 2026-08-24 03:01:16,253 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine-interface/df9ac98c-a1d5-461f-aba5-77000c92b95d 2026-08-24 03:01:16,264 - DEBUG - Requesting: http://10.0.0.191:8082/instance-ip/41b75df0-1edf-489f-b421-2bdf68914466 2026-08-24 03:01:16,272 - DEBUG - Requesting: http://10.0.0.191:8082/instance-ip/7ad4c8f6-2601-4a72-bb3c-9bc97f4b7a0b 2026-08-24 03:01:16,281 - INFO - VM ctest-VM11-23808320 verfication in all API Servers passed 2026-08-24 03:01:16,304 - DEBUG - VM ctest-VM11-23808320 Tap interface: {'index': '3', 'name': 'tapdf9ac98c-a1', 'uuid': 'df9ac98c-a1d5-461f-aba5-77000c92b95d', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983:ctest-VN1-64192983', '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': '27', 'l2_label': '31', 'vxlan_id': '15', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983', 'vm_uuid': '182fb518-f13e-4d2b-9982-ecfcb6dd716f', 'vm_name': 'ctest-VM11-23808320', 'ip_addr': '168.204.208.195', 'mac_addr': '02:df:9a:c9:8c:a1', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.3', 'service_vlan_list': None, 'os_ifindex': '36', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestPolicyAclIpv4v6-47405603:df9ac98c-a1d5-461f-aba5-77000c92b95d', 'sg_uuid_list': ['ca94a9b2-84ef-41c8-bfa6-8678783f1be3'], 'static_route_list': None, 'vm_project_uuid': '9dbffe12-ea12-4d8c-a6dc-61d1c04d5594', 'admin_state': 'Enabled', 'flow_key_idx': '34', 'allowed_address_pair_list': None, 'ip6_addr': '3ce7:9423:90fd:1de3:97fa:107f: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-08-24 03:01:16,304 - DEBUG - Agent 10.0.0.162 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983:ctest-VN1-64192983 2026-08-24 03:01:16,312 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983:ctest-VN1-64192983', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983', 'table_label': '-1', 'vxlan_id': '15', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.193:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2026-08-24 03:01:16,447 - DEBUG - VM ctest-VM11-23808320 : Tap interface tapdf9ac98c-a1 is set to Active 2026-08-24 03:01:16,447 - DEBUG - Tap interface tapdf9ac98c-a1 detail : {'index': '3', 'name': 'tapdf9ac98c-a1', 'uuid': 'df9ac98c-a1d5-461f-aba5-77000c92b95d', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983:ctest-VN1-64192983', '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': '27', 'l2_label': '31', 'vxlan_id': '15', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983', 'vm_uuid': '182fb518-f13e-4d2b-9982-ecfcb6dd716f', 'vm_name': 'ctest-VM11-23808320', 'ip_addr': '168.204.208.195', 'mac_addr': '02:df:9a:c9:8c:a1', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.3', 'service_vlan_list': None, 'os_ifindex': '36', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestPolicyAclIpv4v6-47405603:df9ac98c-a1d5-461f-aba5-77000c92b95d', 'sg_uuid_list': ['ca94a9b2-84ef-41c8-bfa6-8678783f1be3'], 'static_route_list': None, 'vm_project_uuid': '9dbffe12-ea12-4d8c-a6dc-61d1c04d5594', 'admin_state': 'Enabled', 'flow_key_idx': '34', 'allowed_address_pair_list': None, 'ip6_addr': '3ce7:9423:90fd:1de3:97fa:107f: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-08-24 03:01:16,516 - DEBUG - Starting Layer 2 verification in Agent 2026-08-24 03:01:16,527 - DEBUG - Layer 2 path is seen for VM MAC 02:df:9a:c9:8c:a1 in agent 10.0.0.162 2026-08-24 03:01:16,527 - DEBUG - Active layer 2 route in agent is present for VMI tapdf9ac98c-a1 2026-08-24 03:01:16,527 - DEBUG - L2 label(31) matches bw route table and interface table 2026-08-24 03:01:16,555 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983 is found in Agent of node 10.0.0.162 2026-08-24 03:01:16,815 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983 is consistent in agent 10.0.0.162 2026-08-24 03:01:16,815 - DEBUG - Route for VM IP ['3ce7:9423:90fd:1de3:97fa:107f:0:3', '168.204.208.195'] is consistent in agent 10.0.0.162 2026-08-24 03:01:16,815 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983 verification for VM ctest-VM11-23808320 in Agent 10.0.0.162 passed 2026-08-24 03:01:16,884 - DEBUG - Starting all layer 2 verification in agent 10.0.0.162 2026-08-24 03:01:16,921 - DEBUG - Route for VM MAC 02:df:9a:c9:8c:a1 is consistent in agent 10.0.0.162 2026-08-24 03:01:20,121 - 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 1029ms') 2026-08-24 03:01:20,121 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-23808320 failed! 2026-08-24 03:01:20,136 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983 is 168.204.208.193 and allocation pool is NOT set 2026-08-24 03:01:20,136 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983 is 3ce7:9423:90fd:1de3:97fa:107f:0:1 and allocation pool is NOT set 2026-08-24 03:01:24,219 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1006ms') 2026-08-24 03:01:24,219 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-23808320 failed! 2026-08-24 03:01:24,237 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983 is 168.204.208.193 and allocation pool is NOT set 2026-08-24 03:01:24,237 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983 is 3ce7:9423:90fd:1de3:97fa:107f:0:1 and allocation pool is NOT set 2026-08-24 03:01:28,306 - 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 1005ms') 2026-08-24 03:01:28,306 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-23808320 failed! 2026-08-24 03:01:28,322 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983 is 168.204.208.193 and allocation pool is NOT set 2026-08-24 03:01:28,322 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983 is 3ce7:9423:90fd:1de3:97fa:107f:0:1 and allocation pool is NOT set 2026-08-24 03:01:32,403 - 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-08-24 03:01:32,404 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-23808320 failed! 2026-08-24 03:01:32,422 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983 is 168.204.208.193 and allocation pool is NOT set 2026-08-24 03:01:32,423 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983 is 3ce7:9423:90fd:1de3:97fa:107f:0:1 and allocation pool is NOT set 2026-08-24 03:01:36,506 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1016ms') 2026-08-24 03:01:36,507 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-23808320 failed! 2026-08-24 03:01:36,523 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983 is 168.204.208.193 and allocation pool is NOT set 2026-08-24 03:01:36,523 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983 is 3ce7:9423:90fd:1de3:97fa:107f:0:1 and allocation pool is NOT set 2026-08-24 03:01:40,599 - 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-08-24 03:01:40,599 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-23808320 failed! 2026-08-24 03:01:40,616 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983 is 168.204.208.193 and allocation pool is NOT set 2026-08-24 03:01:40,616 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983 is 3ce7:9423:90fd:1de3:97fa:107f:0:1 and allocation pool is NOT set 2026-08-24 03:01:44,694 - 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-08-24 03:01:44,694 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-23808320 failed! 2026-08-24 03:01:44,710 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983 is 168.204.208.193 and allocation pool is NOT set 2026-08-24 03:01:44,710 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983 is 3ce7:9423:90fd:1de3:97fa:107f:0:1 and allocation pool is NOT set 2026-08-24 03:01:48,790 - 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-08-24 03:01:48,790 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-23808320 failed! 2026-08-24 03:01:48,806 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983 is 168.204.208.193 and allocation pool is NOT set 2026-08-24 03:01:48,806 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983 is 3ce7:9423:90fd:1de3:97fa:107f:0:1 and allocation pool is NOT set 2026-08-24 03:01:52,888 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1016ms') 2026-08-24 03:01:52,888 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-23808320 failed! 2026-08-24 03:01:52,904 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983 is 168.204.208.193 and allocation pool is NOT set 2026-08-24 03:01:52,904 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983 is 3ce7:9423:90fd:1de3:97fa:107f:0:1 and allocation pool is NOT set 2026-08-24 03:01:54,977 - 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=31.9 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=3.69 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 3.691/17.791/31.891/14.100 ms') 2026-08-24 03:01:54,977 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-23808320 passed 2026-08-24 03:01:54,977 - INFO - VM ctest-VM11-23808320 verifications in Compute nodes passed 2026-08-24 03:01:55,271 - DEBUG - Validated VM route 168.204.208.195 in vrouter of 10.0.0.162 2026-08-24 03:01:55,281 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0 2026-08-24 03:01:55,281 - INFO - Validated routes of VM ctest-VM11-23808320 in all vrouters 2026-08-24 03:01:55,770 - DEBUG - Starting all layer2 verification in 10.0.0.50 Control Node 2026-08-24 03:01:55,845 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.50, not supported 2026-08-24 03:01:55,970 - DEBUG - Layer2 route found for VM MAC 02:df:9a:c9:8c:a1 in Control-node 10.0.0.50 2026-08-24 03:01:55,970 - DEBUG - L2 Label for VM ctest-VM11-23808320 same between Control-node 10.0.0.50 and Agent, Expected: 31, Seen: 31 2026-08-24 03:01:55,970 - DEBUG - Starting all layer2 verification in 10.0.0.190 Control Node 2026-08-24 03:01:56,089 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.190, not supported 2026-08-24 03:01:56,234 - DEBUG - Layer2 route found for VM MAC 02:df:9a:c9:8c:a1 in Control-node 10.0.0.190 2026-08-24 03:01:56,234 - DEBUG - L2 Label for VM ctest-VM11-23808320 same between Control-node 10.0.0.190 and Agent, Expected: 31, Seen: 31 2026-08-24 03:01:56,234 - DEBUG - Starting all layer2 verification in 10.0.0.191 Control Node 2026-08-24 03:01:56,357 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.191, not supported 2026-08-24 03:01:56,487 - DEBUG - Layer2 route found for VM MAC 02:df:9a:c9:8c:a1 in Control-node 10.0.0.191 2026-08-24 03:01:56,487 - DEBUG - L2 Label for VM ctest-VM11-23808320 same between Control-node 10.0.0.191 and Agent, Expected: 31, Seen: 31 2026-08-24 03:01:56,487 - INFO - Verification in Control-nodes for VM ctest-VM11-23808320 passed 2026-08-24 03:01:56,488 - DEBUG - Verifying the vm in opserver 2026-08-24 03:01:56,488 - DEBUG - Verifying in collector 10.0.0.191 ... 2026-08-24 03:01:56,488 - DEBUG - Requesting: http://10.0.0.191:8081/analytics/uves/virtual-machine/182fb518-f13e-4d2b-9982-ecfcb6dd716f?flat 2026-08-24 03:01:56,509 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-47405603:df9ac98c-a1d5-461f-aba5-77000c92b95d?flat 2026-08-24 03:01:56,534 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-47405603:df9ac98c-a1d5-461f-aba5-77000c92b95d?flat 2026-08-24 03:01:56,559 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-47405603:df9ac98c-a1d5-461f-aba5-77000c92b95d?flat 2026-08-24 03:01:56,583 - DEBUG - Requesting: http://10.0.0.191:8081/analytics/uves/virtual-machine/182fb518-f13e-4d2b-9982-ecfcb6dd716f?flat 2026-08-24 03:01:56,605 - DEBUG - Verifying vm in vn uve 2026-08-24 03:01:56,605 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-47405603:df9ac98c-a1d5-461f-aba5-77000c92b95d?flat 2026-08-24 03:01:56,627 - DEBUG - VM uve shows interface as {'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983', 'active': True, 'label': 27, '__T': 1787540475328612, 'port_mirror_enabled': False, 'admin_state': True, 'ip6_address': '3ce7:9423:90fd:1de3:97fa:107f:0:3', 'fixed_ip4_list': ['168.204.208.195'], 'is_health_check_active': True, 'vm_name': 'ctest-VM11-23808320', 'vn_uuid': 'f3a31965-8e9f-4426-b689-9f2cfa018167', 'ip_address': '168.204.208.195', 'fixed_ip6_list': ['3ce7:9423:90fd:1de3:97fa:107f:0:3'], 'ip4_active': True, 'vhostuser_mode': 0, 'uuid': 'df9ac98c-a1d5-461f-aba5-77000c92b95d', 'gateway': '168.204.208.193', 'tx_vlan': 65535, 'rx_vlan': 65535, 'vm_uuid': '182fb518-f13e-4d2b-9982-ecfcb6dd716f', 'l2_active': True, 'mac_address': '02:df:9a:c9:8c:a1', 'ip6_active': True} 2026-08-24 03:01:56,627 - DEBUG - VM uve shows ip address as ['168.204.208.195', '3ce7:9423:90fd:1de3:97fa:107f:0:3'] 2026-08-24 03:01:56,628 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983 2026-08-24 03:01:56,628 - INFO - Verifying through opserver in 10.0.0.190 2026-08-24 03:01:56,628 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983?flat 2026-08-24 03:01:56,654 - DEBUG - expected vm list 182fb518-f13e-4d2b-9982-ecfcb6dd716f 2026-08-24 03:01:56,654 - DEBUG - Extracted vm list ['182fb518-f13e-4d2b-9982-ecfcb6dd716f'] 2026-08-24 03:01:56,654 - INFO - VM 182fb518-f13e-4d2b-9982-ecfcb6dd716f is present in default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983 2026-08-24 03:01:56,655 - DEBUG - Verifying vm in vrouter uve 2026-08-24 03:01:56,655 - DEBUG - Getting info from collector 10.0.0.191.. 2026-08-24 03:01:56,655 - DEBUG - Requesting: http://10.0.0.191:8081/analytics/uves/virtual-machine/182fb518-f13e-4d2b-9982-ecfcb6dd716f?flat 2026-08-24 03:01:56,675 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-6211-1'] 2026-08-24 03:01:56,675 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6211-1:Compute:contrail-vrouter-agent:0?flat 2026-08-24 03:01:56,717 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6211-1?flat 2026-08-24 03:01:56,756 - DEBUG - VM 182fb518-f13e-4d2b-9982-ecfcb6dd716f is present in vrouter an-jenkins-deploy-platform-ansible-os-6211-1 uve 2026-08-24 03:01:56,756 - DEBUG - Validated that VM ctest-VM11-23808320 is in Vrouter an-jenkins-deploy-platform-ansible-os-6211-1 UVE 2026-08-24 03:01:56,756 - DEBUG - Verifying vm tap interface/vn in vrouter uve 2026-08-24 03:01:56,766 - DEBUG - Expected tap interface of VM uuid 182fb518-f13e-4d2b-9982-ecfcb6dd716f is default-domain:ctest-TestPolicyAclIpv4v6-47405603:df9ac98c-a1d5-461f-aba5-77000c92b95d 2026-08-24 03:01:56,766 - DEBUG - Expected VN of VM uuid 182fb518-f13e-4d2b-9982-ecfcb6dd716f is default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983 2026-08-24 03:01:56,766 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6211-1:Compute:contrail-vrouter-agent:0?flat 2026-08-24 03:01:56,806 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6211-1?flat 2026-08-24 03:01:56,843 - DEBUG - VM 182fb518-f13e-4d2b-9982-ecfcb6dd716f is present in vrouter an-jenkins-deploy-platform-ansible-os-6211-1 uve 2026-08-24 03:01:56,843 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-47405603:df9ac98c-a1d5-461f-aba5-77000c92b95d of vm 182fb518-f13e-4d2b-9982-ecfcb6dd716f is present in vrouter an-jenkins-deploy-platform-ansible-os-6211-1 uve 2026-08-24 03:01:56,843 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983 of vm 182fb518-f13e-4d2b-9982-ecfcb6dd716f is present in vrouter an-jenkins-deploy-platform-ansible-os-6211-1 uve 2026-08-24 03:01:56,843 - DEBUG - Verifying the 182fb518-f13e-4d2b-9982-ecfcb6dd716f virtual network link through opserver 10.0.0.190 2026-08-24 03:01:56,843 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/virtual-machines 2026-08-24 03:01:56,859 - DEBUG - VM link and name as {'name': '182fb518-f13e-4d2b-9982-ecfcb6dd716f', 'href': 'http://10.0.0.190:8081/analytics/uves/virtual-machine/182fb518-f13e-4d2b-9982-ecfcb6dd716f?flat'} 2026-08-24 03:01:56,859 - DEBUG - Verifying the 182fb518-f13e-4d2b-9982-ecfcb6dd716f virtual network link through opserver 10.0.0.50 2026-08-24 03:01:56,859 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-machines 2026-08-24 03:01:56,879 - DEBUG - VM link and name as {'name': '182fb518-f13e-4d2b-9982-ecfcb6dd716f', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-machine/182fb518-f13e-4d2b-9982-ecfcb6dd716f?flat'} 2026-08-24 03:01:56,879 - DEBUG - Verifying the 182fb518-f13e-4d2b-9982-ecfcb6dd716f virtual network link through opserver 10.0.0.191 2026-08-24 03:01:56,879 - DEBUG - Requesting: http://10.0.0.191:8081/analytics/uves/virtual-machines 2026-08-24 03:01:56,893 - DEBUG - VM link and name as {'name': '182fb518-f13e-4d2b-9982-ecfcb6dd716f', 'href': 'http://10.0.0.191:8081/analytics/uves/virtual-machine/182fb518-f13e-4d2b-9982-ecfcb6dd716f?flat'} 2026-08-24 03:01:56,893 - INFO - VM ctest-VM11-23808320 validations in Opserver passed 2026-08-24 03:01:57,004 - DEBUG - VM is in ACTIVE state now 2026-08-24 03:01:57,004 - INFO - VM name : ctest-VM21-71777553 2026-08-24 03:01:57,105 - DEBUG - VM ctest-VM21-71777553 ID is 73ef2b22-258d-4c38-835a-c4135abd68a1 2026-08-24 03:01:57,207 - DEBUG - VM is in ACTIVE state now 2026-08-24 03:01:57,208 - DEBUG - VM ctest-VM21-71777553 launched on Node an-jenkins-deploy-platform-ansible-os-6211-2 2026-08-24 03:01:57,315 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine/73ef2b22-258d-4c38-835a-c4135abd68a1 2026-08-24 03:01:57,327 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine/73ef2b22-258d-4c38-835a-c4135abd68a1 2026-08-24 03:01:57,335 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine-interface/eb6dfbc9-1f25-434d-ab12-a334d9e9cee4 2026-08-24 03:01:57,346 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine/73ef2b22-258d-4c38-835a-c4135abd68a1 2026-08-24 03:01:57,353 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine-interface/eb6dfbc9-1f25-434d-ab12-a334d9e9cee4 2026-08-24 03:01:57,364 - DEBUG - Requesting: http://10.0.0.191:8082/instance-ip/1f4439a7-1c80-4e8f-afaf-d32a47c332ce 2026-08-24 03:01:57,374 - DEBUG - Requesting: http://10.0.0.191:8082/instance-ip/ac0b91f5-6ad6-40c2-809f-49270a914c18 2026-08-24 03:01:57,384 - DEBUG - Verifying in api server 10.0.0.191 2026-08-24 03:01:57,385 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine/73ef2b22-258d-4c38-835a-c4135abd68a1 2026-08-24 03:01:57,393 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine-interface/eb6dfbc9-1f25-434d-ab12-a334d9e9cee4 2026-08-24 03:01:57,406 - DEBUG - Requesting: http://10.0.0.191:8082/instance-ip/1f4439a7-1c80-4e8f-afaf-d32a47c332ce 2026-08-24 03:01:57,420 - DEBUG - Requesting: http://10.0.0.191:8082/instance-ip/ac0b91f5-6ad6-40c2-809f-49270a914c18 2026-08-24 03:01:57,430 - INFO - VM ctest-VM21-71777553 verfication in all API Servers passed 2026-08-24 03:01:57,457 - DEBUG - VM ctest-VM21-71777553 Tap interface: {'index': '3', 'name': 'tapeb6dfbc9-1f', 'uuid': 'eb6dfbc9-1f25-434d-ab12-a334d9e9cee4', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN2-94781241:ctest-VN2-94781241', '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-47405603:ctest-VN2-94781241', 'vm_uuid': '73ef2b22-258d-4c38-835a-c4135abd68a1', 'vm_name': 'ctest-VM21-71777553', 'ip_addr': '45.247.53.67', 'mac_addr': '02:eb:6d:fb:c9:1f', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.3', 'service_vlan_list': None, 'os_ifindex': '30', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestPolicyAclIpv4v6-47405603:eb6dfbc9-1f25-434d-ab12-a334d9e9cee4', 'sg_uuid_list': ['ca94a9b2-84ef-41c8-bfa6-8678783f1be3'], 'static_route_list': None, 'vm_project_uuid': '9dbffe12-ea12-4d8c-a6dc-61d1c04d5594', 'admin_state': 'Enabled', 'flow_key_idx': '31', 'allowed_address_pair_list': None, 'ip6_addr': '2ad9:9712:91c9:614a:264:8ff4: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-08-24 03:01:57,457 - DEBUG - Agent 10.0.0.185 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN2-94781241:ctest-VN2-94781241 2026-08-24 03:01:57,463 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN2-94781241:ctest-VN2-94781241', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN2-94781241', 'table_label': '-1', 'vxlan_id': '16', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.129:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2026-08-24 03:01:57,560 - DEBUG - VM ctest-VM21-71777553 : Tap interface tapeb6dfbc9-1f is set to Active 2026-08-24 03:01:57,560 - DEBUG - Tap interface tapeb6dfbc9-1f detail : {'index': '3', 'name': 'tapeb6dfbc9-1f', 'uuid': 'eb6dfbc9-1f25-434d-ab12-a334d9e9cee4', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN2-94781241:ctest-VN2-94781241', '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-47405603:ctest-VN2-94781241', 'vm_uuid': '73ef2b22-258d-4c38-835a-c4135abd68a1', 'vm_name': 'ctest-VM21-71777553', 'ip_addr': '45.247.53.67', 'mac_addr': '02:eb:6d:fb:c9:1f', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.3', 'service_vlan_list': None, 'os_ifindex': '30', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestPolicyAclIpv4v6-47405603:eb6dfbc9-1f25-434d-ab12-a334d9e9cee4', 'sg_uuid_list': ['ca94a9b2-84ef-41c8-bfa6-8678783f1be3'], 'static_route_list': None, 'vm_project_uuid': '9dbffe12-ea12-4d8c-a6dc-61d1c04d5594', 'admin_state': 'Enabled', 'flow_key_idx': '31', 'allowed_address_pair_list': None, 'ip6_addr': '2ad9:9712:91c9:614a:264:8ff4: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-08-24 03:01:57,681 - DEBUG - Starting Layer 2 verification in Agent 2026-08-24 03:01:57,696 - DEBUG - Layer 2 path is seen for VM MAC 02:eb:6d:fb:c9:1f in agent 10.0.0.185 2026-08-24 03:01:57,697 - DEBUG - Active layer 2 route in agent is present for VMI tapeb6dfbc9-1f 2026-08-24 03:01:57,697 - DEBUG - L2 label(29) matches bw route table and interface table 2026-08-24 03:01:57,713 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN2-94781241 is found in Agent of node 10.0.0.185 2026-08-24 03:01:57,823 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN2-94781241 is consistent in agent 10.0.0.185 2026-08-24 03:01:57,823 - DEBUG - Route for VM IP ['45.247.53.67', '2ad9:9712:91c9:614a:264:8ff4:0:3'] is consistent in agent 10.0.0.185 2026-08-24 03:01:57,823 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN2-94781241 verification for VM ctest-VM21-71777553 in Agent 10.0.0.185 passed 2026-08-24 03:01:57,940 - DEBUG - Starting all layer 2 verification in agent 10.0.0.185 2026-08-24 03:01:57,952 - DEBUG - Route for VM MAC 02:eb:6d:fb:c9:1f is consistent in agent 10.0.0.185 2026-08-24 03:01:59,130 - 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=6.35 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.536 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.536/3.444/6.353/2.908 ms') 2026-08-24 03:01:59,130 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-71777553 passed 2026-08-24 03:01:59,130 - INFO - VM ctest-VM21-71777553 verifications in Compute nodes passed 2026-08-24 03:01:59,387 - DEBUG - Validated VM route 45.247.53.67 in vrouter of 10.0.0.185 2026-08-24 03:01:59,397 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0 2026-08-24 03:01:59,397 - INFO - Validated routes of VM ctest-VM21-71777553 in all vrouters 2026-08-24 03:01:59,900 - DEBUG - Starting all layer2 verification in 10.0.0.50 Control Node 2026-08-24 03:02:00,028 - DEBUG - Layer2 route found for VM MAC 02:eb:6d:fb:c9:1f in Control-node 10.0.0.50 2026-08-24 03:02:00,029 - DEBUG - L2 Label for VM ctest-VM21-71777553 same between Control-node 10.0.0.50 and Agent, Expected: 29, Seen: 29 2026-08-24 03:02:00,146 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.50, not supported 2026-08-24 03:02:00,147 - DEBUG - Starting all layer2 verification in 10.0.0.190 Control Node 2026-08-24 03:02:00,289 - DEBUG - Layer2 route found for VM MAC 02:eb:6d:fb:c9:1f in Control-node 10.0.0.190 2026-08-24 03:02:00,289 - DEBUG - L2 Label for VM ctest-VM21-71777553 same between Control-node 10.0.0.190 and Agent, Expected: 29, Seen: 29 2026-08-24 03:02:00,405 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.190, not supported 2026-08-24 03:02:00,405 - DEBUG - Starting all layer2 verification in 10.0.0.191 Control Node 2026-08-24 03:02:00,530 - DEBUG - Layer2 route found for VM MAC 02:eb:6d:fb:c9:1f in Control-node 10.0.0.191 2026-08-24 03:02:00,530 - DEBUG - L2 Label for VM ctest-VM21-71777553 same between Control-node 10.0.0.191 and Agent, Expected: 29, Seen: 29 2026-08-24 03:02:00,645 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.191, not supported 2026-08-24 03:02:00,645 - INFO - Verification in Control-nodes for VM ctest-VM21-71777553 passed 2026-08-24 03:02:00,645 - DEBUG - Verifying the vm in opserver 2026-08-24 03:02:00,645 - DEBUG - Verifying in collector 10.0.0.191 ... 2026-08-24 03:02:00,646 - DEBUG - Requesting: http://10.0.0.191:8081/analytics/uves/virtual-machine/73ef2b22-258d-4c38-835a-c4135abd68a1?flat 2026-08-24 03:02:00,663 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-47405603:eb6dfbc9-1f25-434d-ab12-a334d9e9cee4?flat 2026-08-24 03:02:00,684 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-47405603:eb6dfbc9-1f25-434d-ab12-a334d9e9cee4?flat 2026-08-24 03:02:00,706 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-47405603:eb6dfbc9-1f25-434d-ab12-a334d9e9cee4?flat 2026-08-24 03:02:00,727 - DEBUG - Requesting: http://10.0.0.191:8081/analytics/uves/virtual-machine/73ef2b22-258d-4c38-835a-c4135abd68a1?flat 2026-08-24 03:02:00,747 - DEBUG - Verifying vm in vn uve 2026-08-24 03:02:00,747 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-47405603:eb6dfbc9-1f25-434d-ab12-a334d9e9cee4?flat 2026-08-24 03:02:00,768 - DEBUG - VM uve shows interface as {'vn_uuid': 'f2fe6f73-367a-49b4-97ad-ff839468e541', 'ip_address': '45.247.53.67', 'gateway': '45.247.53.65', 'mac_address': '02:eb:6d:fb:c9:1f', 'uuid': 'eb6dfbc9-1f25-434d-ab12-a334d9e9cee4', 'ip6_address': '2ad9:9712:91c9:614a:264:8ff4:0:3', 'label': 25, 'tx_vlan': 65535, 'fixed_ip4_list': ['45.247.53.67'], 'port_mirror_enabled': False, 'ip6_active': True, 'vm_name': 'ctest-VM21-71777553', '__T': 1787540467339968, 'vm_uuid': '73ef2b22-258d-4c38-835a-c4135abd68a1', 'vhostuser_mode': 0, 'l2_active': True, 'rx_vlan': 65535, 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN2-94781241', 'admin_state': True, 'is_health_check_active': True, 'active': True, 'ip4_active': True, 'fixed_ip6_list': ['2ad9:9712:91c9:614a:264:8ff4:0:3']} 2026-08-24 03:02:00,768 - DEBUG - VM uve shows ip address as ['45.247.53.67', '2ad9:9712:91c9:614a:264:8ff4:0:3'] 2026-08-24 03:02:00,768 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN2-94781241 2026-08-24 03:02:00,768 - INFO - Verifying through opserver in 10.0.0.190 2026-08-24 03:02:00,769 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN2-94781241?flat 2026-08-24 03:02:00,793 - DEBUG - expected vm list 73ef2b22-258d-4c38-835a-c4135abd68a1 2026-08-24 03:02:00,793 - DEBUG - Extracted vm list ['73ef2b22-258d-4c38-835a-c4135abd68a1'] 2026-08-24 03:02:00,793 - INFO - VM 73ef2b22-258d-4c38-835a-c4135abd68a1 is present in default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN2-94781241 2026-08-24 03:02:00,793 - DEBUG - Verifying vm in vrouter uve 2026-08-24 03:02:00,794 - DEBUG - Getting info from collector 10.0.0.191.. 2026-08-24 03:02:00,794 - DEBUG - Requesting: http://10.0.0.191:8081/analytics/uves/virtual-machine/73ef2b22-258d-4c38-835a-c4135abd68a1?flat 2026-08-24 03:02:00,818 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-6211-2'] 2026-08-24 03:02:00,818 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6211-2:Compute:contrail-vrouter-agent:0?flat 2026-08-24 03:02:00,858 - DEBUG - Requesting: http://10.0.0.191:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6211-2?flat 2026-08-24 03:02:00,891 - DEBUG - VM 73ef2b22-258d-4c38-835a-c4135abd68a1 is present in vrouter an-jenkins-deploy-platform-ansible-os-6211-2 uve 2026-08-24 03:02:00,891 - DEBUG - Validated that VM ctest-VM21-71777553 is in Vrouter an-jenkins-deploy-platform-ansible-os-6211-2 UVE 2026-08-24 03:02:00,891 - DEBUG - Verifying vm tap interface/vn in vrouter uve 2026-08-24 03:02:00,900 - DEBUG - Expected tap interface of VM uuid 73ef2b22-258d-4c38-835a-c4135abd68a1 is default-domain:ctest-TestPolicyAclIpv4v6-47405603:eb6dfbc9-1f25-434d-ab12-a334d9e9cee4 2026-08-24 03:02:00,900 - DEBUG - Expected VN of VM uuid 73ef2b22-258d-4c38-835a-c4135abd68a1 is default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN2-94781241 2026-08-24 03:02:00,900 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6211-2:Compute:contrail-vrouter-agent:0?flat 2026-08-24 03:02:00,937 - DEBUG - Requesting: http://10.0.0.191:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6211-2?flat 2026-08-24 03:02:00,986 - DEBUG - VM 73ef2b22-258d-4c38-835a-c4135abd68a1 is present in vrouter an-jenkins-deploy-platform-ansible-os-6211-2 uve 2026-08-24 03:02:00,986 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-47405603:eb6dfbc9-1f25-434d-ab12-a334d9e9cee4 of vm 73ef2b22-258d-4c38-835a-c4135abd68a1 is present in vrouter an-jenkins-deploy-platform-ansible-os-6211-2 uve 2026-08-24 03:02:00,986 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN2-94781241 of vm 73ef2b22-258d-4c38-835a-c4135abd68a1 is present in vrouter an-jenkins-deploy-platform-ansible-os-6211-2 uve 2026-08-24 03:02:00,987 - DEBUG - Verifying the 73ef2b22-258d-4c38-835a-c4135abd68a1 virtual network link through opserver 10.0.0.190 2026-08-24 03:02:00,987 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/virtual-machines 2026-08-24 03:02:01,004 - DEBUG - VM link and name as {'name': '73ef2b22-258d-4c38-835a-c4135abd68a1', 'href': 'http://10.0.0.190:8081/analytics/uves/virtual-machine/73ef2b22-258d-4c38-835a-c4135abd68a1?flat'} 2026-08-24 03:02:01,004 - DEBUG - Verifying the 73ef2b22-258d-4c38-835a-c4135abd68a1 virtual network link through opserver 10.0.0.50 2026-08-24 03:02:01,004 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-machines 2026-08-24 03:02:01,029 - DEBUG - VM link and name as {'name': '73ef2b22-258d-4c38-835a-c4135abd68a1', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-machine/73ef2b22-258d-4c38-835a-c4135abd68a1?flat'} 2026-08-24 03:02:01,029 - DEBUG - Verifying the 73ef2b22-258d-4c38-835a-c4135abd68a1 virtual network link through opserver 10.0.0.191 2026-08-24 03:02:01,029 - DEBUG - Requesting: http://10.0.0.191:8081/analytics/uves/virtual-machines 2026-08-24 03:02:01,044 - DEBUG - VM link and name as {'name': '73ef2b22-258d-4c38-835a-c4135abd68a1', 'href': 'http://10.0.0.191:8081/analytics/uves/virtual-machine/73ef2b22-258d-4c38-835a-c4135abd68a1?flat'} 2026-08-24 03:02:01,044 - INFO - VM ctest-VM21-71777553 validations in Opserver passed 2026-08-24 03:02:01,165 - DEBUG - VM is in ACTIVE state now 2026-08-24 03:02:01,165 - INFO - VM name : ctest-VM31-87016772 2026-08-24 03:02:01,271 - DEBUG - VM ctest-VM31-87016772 ID is 89271be5-1b3a-45f5-815e-30912b349062 2026-08-24 03:02:01,377 - DEBUG - VM is in ACTIVE state now 2026-08-24 03:02:01,378 - DEBUG - VM ctest-VM31-87016772 launched on Node an-jenkins-deploy-platform-ansible-os-6211-1 2026-08-24 03:02:01,483 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine/89271be5-1b3a-45f5-815e-30912b349062 2026-08-24 03:02:01,509 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine/89271be5-1b3a-45f5-815e-30912b349062 2026-08-24 03:02:01,517 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine-interface/26f81906-b3c7-4bc9-a562-fd9715105214 2026-08-24 03:02:01,528 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine/89271be5-1b3a-45f5-815e-30912b349062 2026-08-24 03:02:01,537 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine-interface/26f81906-b3c7-4bc9-a562-fd9715105214 2026-08-24 03:02:01,548 - DEBUG - Requesting: http://10.0.0.191:8082/instance-ip/12c60734-f6d6-442e-b12c-6c453468d6c8 2026-08-24 03:02:01,563 - DEBUG - Requesting: http://10.0.0.191:8082/instance-ip/529bab58-f8c4-4955-b5ba-3070a691bed8 2026-08-24 03:02:01,577 - DEBUG - Verifying in api server 10.0.0.191 2026-08-24 03:02:01,577 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine/89271be5-1b3a-45f5-815e-30912b349062 2026-08-24 03:02:01,587 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine-interface/26f81906-b3c7-4bc9-a562-fd9715105214 2026-08-24 03:02:01,599 - DEBUG - Requesting: http://10.0.0.191:8082/instance-ip/12c60734-f6d6-442e-b12c-6c453468d6c8 2026-08-24 03:02:01,607 - DEBUG - Requesting: http://10.0.0.191:8082/instance-ip/529bab58-f8c4-4955-b5ba-3070a691bed8 2026-08-24 03:02:01,617 - INFO - VM ctest-VM31-87016772 verfication in all API Servers passed 2026-08-24 03:02:01,679 - DEBUG - VM ctest-VM31-87016772 Tap interface: {'index': '4', 'name': 'tap26f81906-b3', 'uuid': '26f81906-b3c7-4bc9-a562-fd9715105214', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN3-81445529:ctest-VN3-81445529', '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-47405603:ctest-VN3-81445529', 'vm_uuid': '89271be5-1b3a-45f5-815e-30912b349062', 'vm_name': 'ctest-VM31-87016772', 'ip_addr': '216.20.205.131', 'mac_addr': '02:26:f8:19:06:b3', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.4', 'service_vlan_list': None, 'os_ifindex': '37', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestPolicyAclIpv4v6-47405603:26f81906-b3c7-4bc9-a562-fd9715105214', 'sg_uuid_list': ['ca94a9b2-84ef-41c8-bfa6-8678783f1be3'], 'static_route_list': None, 'vm_project_uuid': '9dbffe12-ea12-4d8c-a6dc-61d1c04d5594', 'admin_state': 'Enabled', 'flow_key_idx': '29', 'allowed_address_pair_list': None, 'ip6_addr': '32e2:4365:2b82:49f5:eed6:a36a: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-08-24 03:02:01,680 - DEBUG - Agent 10.0.0.162 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN3-81445529:ctest-VN3-81445529 2026-08-24 03:02:01,707 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN3-81445529:ctest-VN3-81445529', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN3-81445529', 'table_label': '-1', 'vxlan_id': '17', 'evpnindex': '3', 'brindex': '3', 'mplsindex': '0', 'RD': '10.20.0.193:3', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2026-08-24 03:02:01,840 - DEBUG - VM ctest-VM31-87016772 : Tap interface tap26f81906-b3 is set to Active 2026-08-24 03:02:01,840 - DEBUG - Tap interface tap26f81906-b3 detail : {'index': '4', 'name': 'tap26f81906-b3', 'uuid': '26f81906-b3c7-4bc9-a562-fd9715105214', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN3-81445529:ctest-VN3-81445529', '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-47405603:ctest-VN3-81445529', 'vm_uuid': '89271be5-1b3a-45f5-815e-30912b349062', 'vm_name': 'ctest-VM31-87016772', 'ip_addr': '216.20.205.131', 'mac_addr': '02:26:f8:19:06:b3', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.4', 'service_vlan_list': None, 'os_ifindex': '37', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestPolicyAclIpv4v6-47405603:26f81906-b3c7-4bc9-a562-fd9715105214', 'sg_uuid_list': ['ca94a9b2-84ef-41c8-bfa6-8678783f1be3'], 'static_route_list': None, 'vm_project_uuid': '9dbffe12-ea12-4d8c-a6dc-61d1c04d5594', 'admin_state': 'Enabled', 'flow_key_idx': '29', 'allowed_address_pair_list': None, 'ip6_addr': '32e2:4365:2b82:49f5:eed6:a36a: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-08-24 03:02:01,925 - DEBUG - Starting Layer 2 verification in Agent 2026-08-24 03:02:01,955 - DEBUG - Layer 2 path is seen for VM MAC 02:26:f8:19:06:b3 in agent 10.0.0.162 2026-08-24 03:02:01,955 - DEBUG - Active layer 2 route in agent is present for VMI tap26f81906-b3 2026-08-24 03:02:01,955 - DEBUG - L2 label(36) matches bw route table and interface table 2026-08-24 03:02:01,978 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN3-81445529 is found in Agent of node 10.0.0.162 2026-08-24 03:02:02,126 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN3-81445529 is consistent in agent 10.0.0.162 2026-08-24 03:02:02,126 - DEBUG - Route for VM IP ['216.20.205.131', '32e2:4365:2b82:49f5:eed6:a36a:0:3'] is consistent in agent 10.0.0.162 2026-08-24 03:02:02,126 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN3-81445529 verification for VM ctest-VM31-87016772 in Agent 10.0.0.162 passed 2026-08-24 03:02:02,197 - DEBUG - Starting all layer 2 verification in agent 10.0.0.162 2026-08-24 03:02:02,223 - DEBUG - Route for VM MAC 02:26:f8:19:06:b3 is consistent in agent 10.0.0.162 2026-08-24 03:02:03,413 - 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=9.51 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=5.53 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 = 5.529/7.519/9.509/1.990 ms') 2026-08-24 03:02:03,413 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-87016772 passed 2026-08-24 03:02:03,413 - INFO - VM ctest-VM31-87016772 verifications in Compute nodes passed 2026-08-24 03:02:03,683 - DEBUG - Validated VM route 216.20.205.131 in vrouter of 10.0.0.162 2026-08-24 03:02:03,690 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0 2026-08-24 03:02:03,690 - INFO - Validated routes of VM ctest-VM31-87016772 in all vrouters 2026-08-24 03:02:04,229 - DEBUG - Starting all layer2 verification in 10.0.0.50 Control Node 2026-08-24 03:02:04,365 - DEBUG - Layer2 route found for VM MAC 02:26:f8:19:06:b3 in Control-node 10.0.0.50 2026-08-24 03:02:04,366 - DEBUG - L2 Label for VM ctest-VM31-87016772 same between Control-node 10.0.0.50 and Agent, Expected: 36, Seen: 36 2026-08-24 03:02:04,481 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.50, not supported 2026-08-24 03:02:04,481 - DEBUG - Starting all layer2 verification in 10.0.0.190 Control Node 2026-08-24 03:02:04,603 - DEBUG - Layer2 route found for VM MAC 02:26:f8:19:06:b3 in Control-node 10.0.0.190 2026-08-24 03:02:04,604 - DEBUG - L2 Label for VM ctest-VM31-87016772 same between Control-node 10.0.0.190 and Agent, Expected: 36, Seen: 36 2026-08-24 03:02:04,718 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.190, not supported 2026-08-24 03:02:04,718 - INFO - Verification in Control-nodes for VM ctest-VM31-87016772 passed 2026-08-24 03:02:04,719 - DEBUG - Verifying the vm in opserver 2026-08-24 03:02:04,719 - DEBUG - Verifying in collector 10.0.0.191 ... 2026-08-24 03:02:04,719 - DEBUG - Requesting: http://10.0.0.191:8081/analytics/uves/virtual-machine/89271be5-1b3a-45f5-815e-30912b349062?flat 2026-08-24 03:02:04,740 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-47405603:26f81906-b3c7-4bc9-a562-fd9715105214?flat 2026-08-24 03:02:04,762 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-47405603:26f81906-b3c7-4bc9-a562-fd9715105214?flat 2026-08-24 03:02:04,785 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-47405603:26f81906-b3c7-4bc9-a562-fd9715105214?flat 2026-08-24 03:02:04,807 - DEBUG - Requesting: http://10.0.0.191:8081/analytics/uves/virtual-machine/89271be5-1b3a-45f5-815e-30912b349062?flat 2026-08-24 03:02:04,824 - DEBUG - Verifying vm in vn uve 2026-08-24 03:02:04,824 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-47405603:26f81906-b3c7-4bc9-a562-fd9715105214?flat 2026-08-24 03:02:04,850 - DEBUG - VM uve shows interface as {'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN3-81445529', 'active': True, 'label': 32, '__T': 1787540505328668, 'port_mirror_enabled': False, 'admin_state': True, 'ip6_address': '32e2:4365:2b82:49f5:eed6:a36a:0:3', 'fixed_ip4_list': ['216.20.205.131'], 'is_health_check_active': True, 'vm_name': 'ctest-VM31-87016772', 'vn_uuid': '82cffe03-d686-4148-aac4-60d897bf6bd3', 'ip_address': '216.20.205.131', 'fixed_ip6_list': ['32e2:4365:2b82:49f5:eed6:a36a:0:3'], 'ip4_active': True, 'vhostuser_mode': 0, 'uuid': '26f81906-b3c7-4bc9-a562-fd9715105214', 'gateway': '216.20.205.129', 'tx_vlan': 65535, 'rx_vlan': 65535, 'vm_uuid': '89271be5-1b3a-45f5-815e-30912b349062', 'l2_active': True, 'mac_address': '02:26:f8:19:06:b3', 'ip6_active': True} 2026-08-24 03:02:04,850 - DEBUG - VM uve shows ip address as ['216.20.205.131', '32e2:4365:2b82:49f5:eed6:a36a:0:3'] 2026-08-24 03:02:04,850 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN3-81445529 2026-08-24 03:02:04,850 - INFO - Verifying through opserver in 10.0.0.190 2026-08-24 03:02:04,850 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN3-81445529?flat 2026-08-24 03:02:04,887 - DEBUG - expected vm list 89271be5-1b3a-45f5-815e-30912b349062 2026-08-24 03:02:04,887 - DEBUG - Extracted vm list ['89271be5-1b3a-45f5-815e-30912b349062'] 2026-08-24 03:02:04,887 - INFO - VM 89271be5-1b3a-45f5-815e-30912b349062 is present in default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN3-81445529 2026-08-24 03:02:04,887 - DEBUG - Verifying vm in vrouter uve 2026-08-24 03:02:04,887 - DEBUG - Getting info from collector 10.0.0.191.. 2026-08-24 03:02:04,887 - DEBUG - Requesting: http://10.0.0.191:8081/analytics/uves/virtual-machine/89271be5-1b3a-45f5-815e-30912b349062?flat 2026-08-24 03:02:04,906 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-6211-1'] 2026-08-24 03:02:04,906 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6211-1:Compute:contrail-vrouter-agent:0?flat 2026-08-24 03:02:04,947 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6211-1?flat 2026-08-24 03:02:04,983 - DEBUG - VM 89271be5-1b3a-45f5-815e-30912b349062 is present in vrouter an-jenkins-deploy-platform-ansible-os-6211-1 uve 2026-08-24 03:02:04,983 - DEBUG - Validated that VM ctest-VM31-87016772 is in Vrouter an-jenkins-deploy-platform-ansible-os-6211-1 UVE 2026-08-24 03:02:04,983 - DEBUG - Verifying vm tap interface/vn in vrouter uve 2026-08-24 03:02:04,991 - DEBUG - Expected tap interface of VM uuid 89271be5-1b3a-45f5-815e-30912b349062 is default-domain:ctest-TestPolicyAclIpv4v6-47405603:26f81906-b3c7-4bc9-a562-fd9715105214 2026-08-24 03:02:04,991 - DEBUG - Expected VN of VM uuid 89271be5-1b3a-45f5-815e-30912b349062 is default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN3-81445529 2026-08-24 03:02:04,991 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6211-1:Compute:contrail-vrouter-agent:0?flat 2026-08-24 03:02:05,040 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6211-1?flat 2026-08-24 03:02:05,074 - DEBUG - VM 89271be5-1b3a-45f5-815e-30912b349062 is present in vrouter an-jenkins-deploy-platform-ansible-os-6211-1 uve 2026-08-24 03:02:05,074 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-47405603:26f81906-b3c7-4bc9-a562-fd9715105214 of vm 89271be5-1b3a-45f5-815e-30912b349062 is present in vrouter an-jenkins-deploy-platform-ansible-os-6211-1 uve 2026-08-24 03:02:05,074 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN3-81445529 of vm 89271be5-1b3a-45f5-815e-30912b349062 is present in vrouter an-jenkins-deploy-platform-ansible-os-6211-1 uve 2026-08-24 03:02:05,074 - DEBUG - Verifying the 89271be5-1b3a-45f5-815e-30912b349062 virtual network link through opserver 10.0.0.190 2026-08-24 03:02:05,075 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/virtual-machines 2026-08-24 03:02:05,089 - DEBUG - VM link and name as {'name': '89271be5-1b3a-45f5-815e-30912b349062', 'href': 'http://10.0.0.190:8081/analytics/uves/virtual-machine/89271be5-1b3a-45f5-815e-30912b349062?flat'} 2026-08-24 03:02:05,089 - DEBUG - Verifying the 89271be5-1b3a-45f5-815e-30912b349062 virtual network link through opserver 10.0.0.50 2026-08-24 03:02:05,089 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-machines 2026-08-24 03:02:05,103 - DEBUG - VM link and name as {'name': '89271be5-1b3a-45f5-815e-30912b349062', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-machine/89271be5-1b3a-45f5-815e-30912b349062?flat'} 2026-08-24 03:02:05,104 - DEBUG - Verifying the 89271be5-1b3a-45f5-815e-30912b349062 virtual network link through opserver 10.0.0.191 2026-08-24 03:02:05,104 - DEBUG - Requesting: http://10.0.0.191:8081/analytics/uves/virtual-machines 2026-08-24 03:02:05,117 - DEBUG - VM link and name as {'name': '89271be5-1b3a-45f5-815e-30912b349062', 'href': 'http://10.0.0.191:8081/analytics/uves/virtual-machine/89271be5-1b3a-45f5-815e-30912b349062?flat'} 2026-08-24 03:02:05,118 - INFO - VM ctest-VM31-87016772 validations in Opserver passed 2026-08-24 03:02:05,118 - INFO - Waiting for VM ctest-VM11-23808320 to be up.. 2026-08-24 03:02:05,118 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-24 03:02:05,118 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine/182fb518-f13e-4d2b-9982-ecfcb6dd716f 2026-08-24 03:02:05,128 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine-interface/df9ac98c-a1d5-461f-aba5-77000c92b95d 2026-08-24 03:02:06,293 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=4.22 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.51 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.512/3.364/4.216/0.852 ms') 2026-08-24 03:02:06,293 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-23808320 passed 2026-08-24 03:02:06,365 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-24 03:02:06,365 - DEBUG - Waiting to SSH to VM ctest-VM11-23808320, IP 168.204.208.195, Port 22 2026-08-24 03:02:06,531 - DEBUG - VM ctest-VM11-23808320 is ready for SSH connections 2026-08-24 03:02:06,531 - INFO - Waiting for VM ctest-VM21-71777553 to be up.. 2026-08-24 03:02:06,531 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-24 03:02:06,531 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine/73ef2b22-258d-4c38-835a-c4135abd68a1 2026-08-24 03:02:06,541 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine-interface/eb6dfbc9-1f25-434d-ab12-a334d9e9cee4 2026-08-24 03:02:07,717 - 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.86 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.973 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.973/2.418/3.863/1.445 ms') 2026-08-24 03:02:07,717 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-71777553 passed 2026-08-24 03:02:07,797 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-24 03:02:07,797 - DEBUG - Waiting to SSH to VM ctest-VM21-71777553, IP 45.247.53.67, Port 22 2026-08-24 03:02:07,970 - DEBUG - VM ctest-VM21-71777553 is ready for SSH connections 2026-08-24 03:02:07,970 - INFO - Waiting for VM ctest-VM31-87016772 to be up.. 2026-08-24 03:02:07,970 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-24 03:02:07,970 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine/89271be5-1b3a-45f5-815e-30912b349062 2026-08-24 03:02:07,981 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine-interface/26f81906-b3c7-4bc9-a562-fd9715105214 2026-08-24 03:02:09,176 - 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=4.06 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=2.23 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 = 2.229/3.145/4.062/0.916 ms') 2026-08-24 03:02:09,176 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-87016772 passed 2026-08-24 03:02:09,251 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-24 03:02:09,251 - DEBUG - Waiting to SSH to VM ctest-VM31-87016772, IP 216.20.205.131, Port 22 2026-08-24 03:02:09,320 - DEBUG - Error on ssh to ubuntu@169.254.0.4:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.4/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.4/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.4/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-08-24 03:02:09,411 - DEBUG - VM ctest-VM31-87016772 is NOT ready for SSH connections, VM status: ACTIVE 2026-08-24 03:02:14,412 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-24 03:02:14,412 - DEBUG - Waiting to SSH to VM ctest-VM31-87016772, IP 216.20.205.131, Port 22 2026-08-24 03:02:14,479 - DEBUG - Error on ssh to ubuntu@169.254.0.4:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.4/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.4/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.4/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-08-24 03:02:14,578 - DEBUG - VM ctest-VM31-87016772 is NOT ready for SSH connections, VM status: ACTIVE 2026-08-24 03:02:19,579 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-24 03:02:19,579 - DEBUG - Waiting to SSH to VM ctest-VM31-87016772, IP 216.20.205.131, Port 22 2026-08-24 03:02:19,637 - DEBUG - Error on ssh to ubuntu@169.254.0.4:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.4/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.4/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.4/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-08-24 03:02:19,735 - DEBUG - VM ctest-VM31-87016772 is NOT ready for SSH connections, VM status: ACTIVE 2026-08-24 03:02:24,736 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-24 03:02:24,737 - DEBUG - Waiting to SSH to VM ctest-VM31-87016772, IP 216.20.205.131, Port 22 2026-08-24 03:02:24,803 - DEBUG - Error on ssh to ubuntu@169.254.0.4:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.4/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.4/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.4/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-08-24 03:02:24,897 - DEBUG - VM ctest-VM31-87016772 is NOT ready for SSH connections, VM status: ACTIVE 2026-08-24 03:02:29,898 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-24 03:02:29,898 - DEBUG - Waiting to SSH to VM ctest-VM31-87016772, IP 216.20.205.131, Port 22 2026-08-24 03:02:29,967 - DEBUG - Error on ssh to ubuntu@169.254.0.4:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.4/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.4/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.4/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-08-24 03:02:30,060 - DEBUG - VM ctest-VM31-87016772 is NOT ready for SSH connections, VM status: ACTIVE 2026-08-24 03:02:35,061 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-24 03:02:35,062 - DEBUG - Waiting to SSH to VM ctest-VM31-87016772, IP 216.20.205.131, Port 22 2026-08-24 03:02:35,131 - DEBUG - Error on ssh to ubuntu@169.254.0.4:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.4/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.4/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.4/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-08-24 03:02:35,220 - DEBUG - VM ctest-VM31-87016772 is NOT ready for SSH connections, VM status: ACTIVE 2026-08-24 03:02:40,220 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-24 03:02:40,221 - DEBUG - Waiting to SSH to VM ctest-VM31-87016772, IP 216.20.205.131, Port 22 2026-08-24 03:02:40,390 - DEBUG - VM ctest-VM31-87016772 is ready for SSH connections 2026-08-24 03:02:40,391 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 45.247.53.67, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.162, gateway password: c0ntrail123 2026-08-24 03:02:40,391 - DEBUG - ping -s 56 -c 3 -W 1 45.247.53.67 2026-08-24 03:03:22,275 - DEBUG - PING 45.247.53.67 (45.247.53.67) 56(84) bytes of data. 64 bytes from 45.247.53.67: icmp_req=1 ttl=63 time=7.76 ms 64 bytes from 45.247.53.67: icmp_req=2 ttl=63 time=1.54 ms 64 bytes from 45.247.53.67: icmp_req=3 ttl=63 time=1.29 ms --- 45.247.53.67 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2005ms rtt min/avg/max/mdev = 1.290/3.535/7.768/2.995 ms 2026-08-24 03:03:22,275 - INFO - Ping to IP 45.247.53.67 from VM ctest-VM11-23808320 passed 2026-08-24 03:03:22,275 - DEBUG - Running remote_cmd, Cmd : ping6 -s 56 -c 3 -W 1 2ad9:9712:91c9:614a:264:8ff4:0:3, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.162, gateway password: c0ntrail123 2026-08-24 03:03:22,275 - DEBUG - ping6 -s 56 -c 3 -W 1 2ad9:9712:91c9:614a:264:8ff4:0:3 2026-08-24 03:03:24,582 - DEBUG - PING 2ad9:9712:91c9:614a:264:8ff4:0:3(2ad9:9712:91c9:614a:264:8ff4:0:3) 56 data bytes 64 bytes from 2ad9:9712:91c9:614a:264:8ff4:0:3: icmp_seq=1 ttl=64 time=8.57 ms 64 bytes from 2ad9:9712:91c9:614a:264:8ff4:0:3: icmp_seq=2 ttl=64 time=2.33 ms 64 bytes from 2ad9:9712:91c9:614a:264:8ff4:0:3: icmp_seq=3 ttl=64 time=1.50 ms --- 2ad9:9712:91c9:614a:264:8ff4:0:3 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2004ms rtt min/avg/max/mdev = 1.505/4.138/8.572/3.153 ms 2026-08-24 03:03:24,582 - INFO - Ping to IP 2ad9:9712:91c9:614a:264:8ff4:0:3 from VM ctest-VM11-23808320 passed 2026-08-24 03:03:24,582 - INFO - Test with src as policy and dst as any PASSED 2026-08-24 03:03:24,582 - INFO - Deleting VM ctest-VM31-87016772 2026-08-24 03:03:24,657 - DEBUG - Verifying in api server 10.0.0.191 2026-08-24 03:03:24,657 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine/89271be5-1b3a-45f5-815e-30912b349062 2026-08-24 03:03:24,668 - DEBUG - VM ID 89271be5-1b3a-45f5-815e-30912b349062 of VM ctest-VM31-87016772 is still found in API Server 2026-08-24 03:03:26,668 - DEBUG - Verifying in api server 10.0.0.191 2026-08-24 03:03:26,668 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine/89271be5-1b3a-45f5-815e-30912b349062 2026-08-24 03:03:26,678 - DEBUG - VM ID 89271be5-1b3a-45f5-815e-30912b349062 of VM ctest-VM31-87016772 is still found in API Server 2026-08-24 03:03:28,679 - DEBUG - Verifying in api server 10.0.0.191 2026-08-24 03:03:28,679 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine/89271be5-1b3a-45f5-815e-30912b349062 2026-08-24 03:03:28,687 - DEBUG - Response Code: 404 2026-08-24 03:03:28,687 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine/89271be5-1b3a-45f5-815e-30912b349062 2026-08-24 03:03:28,694 - DEBUG - Response Code: 404 2026-08-24 03:03:28,694 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine/89271be5-1b3a-45f5-815e-30912b349062 2026-08-24 03:03:28,700 - DEBUG - Response Code: 404 2026-08-24 03:03:28,700 - INFO - VM ctest-VM31-87016772 is fully removed in API-Server 2026-08-24 03:03:28,864 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found 2026-08-24 03:03:28,916 - DEBUG - Validated that vrouter 10.0.0.162 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN3-81445529 2026-08-24 03:03:28,927 - DEBUG - Validated that vrouter 10.0.0.185 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN3-81445529 2026-08-24 03:03:28,928 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN3-81445529 2026-08-24 03:03:28,928 - INFO - VM ctest-VM31-87016772 is removed in Compute, and routes are removed in all compute nodes 2026-08-24 03:03:29,165 - INFO - Routes for VM ctest-VM31-87016772 is removed in all control-nodes 2026-08-24 03:03:29,166 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6211-1:Compute:contrail-vrouter-agent:0?flat 2026-08-24 03:03:29,207 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6211-1?flat 2026-08-24 03:03:29,239 - DEBUG - VM 89271be5-1b3a-45f5-815e-30912b349062 is still present in vrouter an-jenkins-deploy-platform-ansible-os-6211-1 uve 2026-08-24 03:03:29,239 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-08-24 03:03:29,240 - DEBUG - interface for vm 89271be5-1b3a-45f5-815e-30912b349062 deleted from vrouter uve ... 2026-08-24 03:03:29,240 - DEBUG - Deleted interface not in error interface list ... 2026-08-24 03:03:32,240 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6211-1:Compute:contrail-vrouter-agent:0?flat 2026-08-24 03:03:32,276 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6211-1?flat 2026-08-24 03:03:32,312 - DEBUG - VM 89271be5-1b3a-45f5-815e-30912b349062 is still present in vrouter an-jenkins-deploy-platform-ansible-os-6211-1 uve 2026-08-24 03:03:32,312 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-08-24 03:03:32,312 - DEBUG - interface for vm 89271be5-1b3a-45f5-815e-30912b349062 deleted from vrouter uve ... 2026-08-24 03:03:32,312 - DEBUG - Deleted interface not in error interface list ... 2026-08-24 03:03:35,313 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6211-1:Compute:contrail-vrouter-agent:0?flat 2026-08-24 03:03:35,350 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6211-1?flat 2026-08-24 03:03:35,384 - DEBUG - VM 89271be5-1b3a-45f5-815e-30912b349062 is still present in vrouter an-jenkins-deploy-platform-ansible-os-6211-1 uve 2026-08-24 03:03:35,384 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-08-24 03:03:35,385 - DEBUG - interface for vm 89271be5-1b3a-45f5-815e-30912b349062 deleted from vrouter uve ... 2026-08-24 03:03:35,385 - DEBUG - Deleted interface not in error interface list ... 2026-08-24 03:03:38,386 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6211-1:Compute:contrail-vrouter-agent:0?flat 2026-08-24 03:03:38,431 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6211-1?flat 2026-08-24 03:03:38,466 - DEBUG - VM 89271be5-1b3a-45f5-815e-30912b349062 is still present in vrouter an-jenkins-deploy-platform-ansible-os-6211-1 uve 2026-08-24 03:03:38,466 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-08-24 03:03:38,466 - DEBUG - interface for vm 89271be5-1b3a-45f5-815e-30912b349062 deleted from vrouter uve ... 2026-08-24 03:03:38,467 - DEBUG - Deleted interface not in error interface list ... 2026-08-24 03:03:41,467 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6211-1:Compute:contrail-vrouter-agent:0?flat 2026-08-24 03:03:41,506 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6211-1?flat 2026-08-24 03:03:41,542 - DEBUG - VM 89271be5-1b3a-45f5-815e-30912b349062 is still present in vrouter an-jenkins-deploy-platform-ansible-os-6211-1 uve 2026-08-24 03:03:41,542 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-08-24 03:03:41,542 - DEBUG - interface for vm 89271be5-1b3a-45f5-815e-30912b349062 deleted from vrouter uve ... 2026-08-24 03:03:41,542 - DEBUG - Deleted interface not in error interface list ... 2026-08-24 03:03:44,543 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6211-1:Compute:contrail-vrouter-agent:0?flat 2026-08-24 03:03:44,580 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6211-1?flat 2026-08-24 03:03:44,616 - DEBUG - VM 89271be5-1b3a-45f5-815e-30912b349062 is still present in vrouter an-jenkins-deploy-platform-ansible-os-6211-1 uve 2026-08-24 03:03:44,616 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-08-24 03:03:44,616 - DEBUG - interface for vm 89271be5-1b3a-45f5-815e-30912b349062 deleted from vrouter uve ... 2026-08-24 03:03:44,616 - DEBUG - Deleted interface not in error interface list ... 2026-08-24 03:03:47,616 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6211-1:Compute:contrail-vrouter-agent:0?flat 2026-08-24 03:03:47,662 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6211-1?flat 2026-08-24 03:03:47,700 - DEBUG - VM 89271be5-1b3a-45f5-815e-30912b349062 is not present in vrouter an-jenkins-deploy-platform-ansible-os-6211-1 uve 2026-08-24 03:03:47,700 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-08-24 03:03:47,700 - DEBUG - interface for vm 89271be5-1b3a-45f5-815e-30912b349062 deleted from vrouter uve ... 2026-08-24 03:03:47,700 - DEBUG - Deleted interface not in error interface list ... 2026-08-24 03:03:47,701 - DEBUG - Validated that VM 89271be5-1b3a-45f5-815e-30912b349062 is removed in Vrouter UVE 2026-08-24 03:03:47,701 - DEBUG - Verifying through opserver in 10.0.0.190 2026-08-24 03:03:47,701 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN3-81445529?flat 2026-08-24 03:03:47,730 - DEBUG - 89271be5-1b3a-45f5-815e-30912b349062 BM not in default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN3-81445529 uve 2026-08-24 03:03:47,730 - DEBUG - Validated that VM 89271be5-1b3a-45f5-815e-30912b349062 is not present in the VN UVE 2026-08-24 03:03:47,730 - DEBUG - Verifying the 89271be5-1b3a-45f5-815e-30912b349062 virtual network link through opserver 10.0.0.190 2026-08-24 03:03:47,731 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/virtual-machines 2026-08-24 03:03:47,753 - INFO - 89271be5-1b3a-45f5-815e-30912b349062 vm uve deleted from opserver 2026-08-24 03:03:47,753 - DEBUG - Verifying the 89271be5-1b3a-45f5-815e-30912b349062 virtual network link through opserver 10.0.0.50 2026-08-24 03:03:47,753 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-machines 2026-08-24 03:03:47,768 - INFO - 89271be5-1b3a-45f5-815e-30912b349062 vm uve deleted from opserver 2026-08-24 03:03:47,769 - DEBUG - Verifying the 89271be5-1b3a-45f5-815e-30912b349062 virtual network link through opserver 10.0.0.191 2026-08-24 03:03:47,769 - DEBUG - Requesting: http://10.0.0.191:8081/analytics/uves/virtual-machines 2026-08-24 03:03:47,784 - INFO - 89271be5-1b3a-45f5-815e-30912b349062 vm uve deleted from opserver 2026-08-24 03:03:47,784 - INFO - Validated that VM 89271be5-1b3a-45f5-815e-30912b349062 is removed from Opserver 2026-08-24 03:03:47,784 - INFO - Deleting VM ctest-VM21-71777553 2026-08-24 03:03:47,861 - DEBUG - Verifying in api server 10.0.0.191 2026-08-24 03:03:47,861 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine/73ef2b22-258d-4c38-835a-c4135abd68a1 2026-08-24 03:03:47,870 - DEBUG - VM ID 73ef2b22-258d-4c38-835a-c4135abd68a1 of VM ctest-VM21-71777553 is still found in API Server 2026-08-24 03:03:49,871 - DEBUG - Verifying in api server 10.0.0.191 2026-08-24 03:03:49,871 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine/73ef2b22-258d-4c38-835a-c4135abd68a1 2026-08-24 03:03:49,884 - DEBUG - VM ID 73ef2b22-258d-4c38-835a-c4135abd68a1 of VM ctest-VM21-71777553 is still found in API Server 2026-08-24 03:03:51,884 - DEBUG - Verifying in api server 10.0.0.191 2026-08-24 03:03:51,885 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine/73ef2b22-258d-4c38-835a-c4135abd68a1 2026-08-24 03:03:51,893 - DEBUG - Response Code: 404 2026-08-24 03:03:51,893 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine/73ef2b22-258d-4c38-835a-c4135abd68a1 2026-08-24 03:03:51,900 - DEBUG - Response Code: 404 2026-08-24 03:03:51,900 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine/73ef2b22-258d-4c38-835a-c4135abd68a1 2026-08-24 03:03:51,906 - DEBUG - Response Code: 404 2026-08-24 03:03:51,906 - INFO - VM ctest-VM21-71777553 is fully removed in API-Server 2026-08-24 03:03:52,063 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found 2026-08-24 03:03:52,135 - DEBUG - Validated that vrouter 10.0.0.162 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN2-94781241 2026-08-24 03:03:52,146 - DEBUG - Validated that vrouter 10.0.0.185 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN2-94781241 2026-08-24 03:03:52,146 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN2-94781241 2026-08-24 03:03:52,146 - INFO - VM ctest-VM21-71777553 is removed in Compute, and routes are removed in all compute nodes 2026-08-24 03:03:52,381 - INFO - Routes for VM ctest-VM21-71777553 is removed in all control-nodes 2026-08-24 03:03:52,381 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6211-2:Compute:contrail-vrouter-agent:0?flat 2026-08-24 03:03:52,420 - DEBUG - Requesting: http://10.0.0.191:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6211-2?flat 2026-08-24 03:03:52,447 - DEBUG - VM 73ef2b22-258d-4c38-835a-c4135abd68a1 is still present in vrouter an-jenkins-deploy-platform-ansible-os-6211-2 uve 2026-08-24 03:03:52,447 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-08-24 03:03:52,448 - DEBUG - interface for vm 73ef2b22-258d-4c38-835a-c4135abd68a1 deleted from vrouter uve ... 2026-08-24 03:03:52,448 - DEBUG - Deleted interface not in error interface list ... 2026-08-24 03:03:55,448 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6211-2:Compute:contrail-vrouter-agent:0?flat 2026-08-24 03:03:55,482 - DEBUG - Requesting: http://10.0.0.191:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6211-2?flat 2026-08-24 03:03:55,512 - DEBUG - VM 73ef2b22-258d-4c38-835a-c4135abd68a1 is still present in vrouter an-jenkins-deploy-platform-ansible-os-6211-2 uve 2026-08-24 03:03:55,512 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-08-24 03:03:55,513 - DEBUG - interface for vm 73ef2b22-258d-4c38-835a-c4135abd68a1 deleted from vrouter uve ... 2026-08-24 03:03:55,513 - DEBUG - Deleted interface not in error interface list ... 2026-08-24 03:03:58,513 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6211-2:Compute:contrail-vrouter-agent:0?flat 2026-08-24 03:03:58,552 - DEBUG - Requesting: http://10.0.0.191:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6211-2?flat 2026-08-24 03:03:58,583 - DEBUG - VM 73ef2b22-258d-4c38-835a-c4135abd68a1 is still present in vrouter an-jenkins-deploy-platform-ansible-os-6211-2 uve 2026-08-24 03:03:58,583 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-08-24 03:03:58,583 - DEBUG - interface for vm 73ef2b22-258d-4c38-835a-c4135abd68a1 deleted from vrouter uve ... 2026-08-24 03:03:58,583 - DEBUG - Deleted interface not in error interface list ... 2026-08-24 03:04:01,583 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6211-2:Compute:contrail-vrouter-agent:0?flat 2026-08-24 03:04:01,624 - DEBUG - Requesting: http://10.0.0.191:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6211-2?flat 2026-08-24 03:04:01,659 - DEBUG - VM 73ef2b22-258d-4c38-835a-c4135abd68a1 is still present in vrouter an-jenkins-deploy-platform-ansible-os-6211-2 uve 2026-08-24 03:04:01,659 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-08-24 03:04:01,659 - DEBUG - interface for vm 73ef2b22-258d-4c38-835a-c4135abd68a1 deleted from vrouter uve ... 2026-08-24 03:04:01,659 - DEBUG - Deleted interface not in error interface list ... 2026-08-24 03:04:04,660 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6211-2:Compute:contrail-vrouter-agent:0?flat 2026-08-24 03:04:04,702 - DEBUG - Requesting: http://10.0.0.191:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6211-2?flat 2026-08-24 03:04:04,735 - DEBUG - VM 73ef2b22-258d-4c38-835a-c4135abd68a1 is still present in vrouter an-jenkins-deploy-platform-ansible-os-6211-2 uve 2026-08-24 03:04:04,735 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-08-24 03:04:04,735 - DEBUG - interface for vm 73ef2b22-258d-4c38-835a-c4135abd68a1 deleted from vrouter uve ... 2026-08-24 03:04:04,735 - DEBUG - Deleted interface not in error interface list ... 2026-08-24 03:04:07,736 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6211-2:Compute:contrail-vrouter-agent:0?flat 2026-08-24 03:04:07,778 - DEBUG - Requesting: http://10.0.0.191:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6211-2?flat 2026-08-24 03:04:07,808 - DEBUG - VM 73ef2b22-258d-4c38-835a-c4135abd68a1 is not present in vrouter an-jenkins-deploy-platform-ansible-os-6211-2 uve 2026-08-24 03:04:07,808 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-08-24 03:04:07,808 - DEBUG - interface for vm 73ef2b22-258d-4c38-835a-c4135abd68a1 deleted from vrouter uve ... 2026-08-24 03:04:07,808 - DEBUG - Deleted interface not in error interface list ... 2026-08-24 03:04:07,808 - DEBUG - Validated that VM 73ef2b22-258d-4c38-835a-c4135abd68a1 is removed in Vrouter UVE 2026-08-24 03:04:07,808 - DEBUG - Verifying through opserver in 10.0.0.190 2026-08-24 03:04:07,808 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN2-94781241?flat 2026-08-24 03:04:07,830 - DEBUG - 73ef2b22-258d-4c38-835a-c4135abd68a1 BM not in default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN2-94781241 uve 2026-08-24 03:04:07,830 - DEBUG - Validated that VM 73ef2b22-258d-4c38-835a-c4135abd68a1 is not present in the VN UVE 2026-08-24 03:04:07,830 - DEBUG - Verifying the 73ef2b22-258d-4c38-835a-c4135abd68a1 virtual network link through opserver 10.0.0.190 2026-08-24 03:04:07,830 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/virtual-machines 2026-08-24 03:04:07,848 - INFO - 73ef2b22-258d-4c38-835a-c4135abd68a1 vm uve deleted from opserver 2026-08-24 03:04:07,848 - DEBUG - Verifying the 73ef2b22-258d-4c38-835a-c4135abd68a1 virtual network link through opserver 10.0.0.50 2026-08-24 03:04:07,848 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-machines 2026-08-24 03:04:07,866 - INFO - 73ef2b22-258d-4c38-835a-c4135abd68a1 vm uve deleted from opserver 2026-08-24 03:04:07,866 - DEBUG - Verifying the 73ef2b22-258d-4c38-835a-c4135abd68a1 virtual network link through opserver 10.0.0.191 2026-08-24 03:04:07,866 - DEBUG - Requesting: http://10.0.0.191:8081/analytics/uves/virtual-machines 2026-08-24 03:04:07,883 - INFO - 73ef2b22-258d-4c38-835a-c4135abd68a1 vm uve deleted from opserver 2026-08-24 03:04:07,883 - INFO - Validated that VM 73ef2b22-258d-4c38-835a-c4135abd68a1 is removed from Opserver 2026-08-24 03:04:07,884 - INFO - Deleting VM ctest-VM11-23808320 2026-08-24 03:04:07,972 - DEBUG - Verifying in api server 10.0.0.191 2026-08-24 03:04:07,972 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine/182fb518-f13e-4d2b-9982-ecfcb6dd716f 2026-08-24 03:04:07,986 - DEBUG - VM ID 182fb518-f13e-4d2b-9982-ecfcb6dd716f of VM ctest-VM11-23808320 is still found in API Server 2026-08-24 03:04:09,987 - DEBUG - Verifying in api server 10.0.0.191 2026-08-24 03:04:09,987 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine/182fb518-f13e-4d2b-9982-ecfcb6dd716f 2026-08-24 03:04:09,996 - DEBUG - VM ID 182fb518-f13e-4d2b-9982-ecfcb6dd716f of VM ctest-VM11-23808320 is still found in API Server 2026-08-24 03:04:11,997 - DEBUG - Verifying in api server 10.0.0.191 2026-08-24 03:04:11,997 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine/182fb518-f13e-4d2b-9982-ecfcb6dd716f 2026-08-24 03:04:12,005 - DEBUG - Response Code: 404 2026-08-24 03:04:12,005 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine/182fb518-f13e-4d2b-9982-ecfcb6dd716f 2026-08-24 03:04:12,011 - DEBUG - Response Code: 404 2026-08-24 03:04:12,012 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine/182fb518-f13e-4d2b-9982-ecfcb6dd716f 2026-08-24 03:04:12,018 - DEBUG - Response Code: 404 2026-08-24 03:04:12,018 - INFO - VM ctest-VM11-23808320 is fully removed in API-Server 2026-08-24 03:04:12,193 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found 2026-08-24 03:04:12,278 - DEBUG - Validated that vrouter 10.0.0.162 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983 2026-08-24 03:04:12,290 - DEBUG - Validated that vrouter 10.0.0.185 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983 2026-08-24 03:04:12,290 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983 2026-08-24 03:04:12,290 - INFO - VM ctest-VM11-23808320 is removed in Compute, and routes are removed in all compute nodes 2026-08-24 03:04:12,489 - INFO - Routes for VM ctest-VM11-23808320 is removed in all control-nodes 2026-08-24 03:04:12,489 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6211-1:Compute:contrail-vrouter-agent:0?flat 2026-08-24 03:04:12,528 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6211-1?flat 2026-08-24 03:04:12,563 - DEBUG - VM 182fb518-f13e-4d2b-9982-ecfcb6dd716f is still present in vrouter an-jenkins-deploy-platform-ansible-os-6211-1 uve 2026-08-24 03:04:12,563 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-08-24 03:04:12,564 - DEBUG - interface for vm 182fb518-f13e-4d2b-9982-ecfcb6dd716f deleted from vrouter uve ... 2026-08-24 03:04:12,564 - DEBUG - Deleted interface not in error interface list ... 2026-08-24 03:04:15,565 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6211-1:Compute:contrail-vrouter-agent:0?flat 2026-08-24 03:04:15,603 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6211-1?flat 2026-08-24 03:04:15,646 - DEBUG - VM 182fb518-f13e-4d2b-9982-ecfcb6dd716f is not present in vrouter an-jenkins-deploy-platform-ansible-os-6211-1 uve 2026-08-24 03:04:15,646 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-08-24 03:04:15,646 - DEBUG - interface for vm 182fb518-f13e-4d2b-9982-ecfcb6dd716f deleted from vrouter uve ... 2026-08-24 03:04:15,646 - DEBUG - Deleted interface not in error interface list ... 2026-08-24 03:04:15,646 - DEBUG - Validated that VM 182fb518-f13e-4d2b-9982-ecfcb6dd716f is removed in Vrouter UVE 2026-08-24 03:04:15,646 - DEBUG - Verifying through opserver in 10.0.0.190 2026-08-24 03:04:15,647 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983?flat 2026-08-24 03:04:15,667 - DEBUG - 182fb518-f13e-4d2b-9982-ecfcb6dd716f BM not in default-domain:ctest-TestPolicyAclIpv4v6-47405603:ctest-VN1-64192983 uve 2026-08-24 03:04:15,667 - DEBUG - Validated that VM 182fb518-f13e-4d2b-9982-ecfcb6dd716f is not present in the VN UVE 2026-08-24 03:04:15,667 - DEBUG - Verifying the 182fb518-f13e-4d2b-9982-ecfcb6dd716f virtual network link through opserver 10.0.0.190 2026-08-24 03:04:15,667 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/virtual-machines 2026-08-24 03:04:15,680 - INFO - 182fb518-f13e-4d2b-9982-ecfcb6dd716f vm uve deleted from opserver 2026-08-24 03:04:15,680 - DEBUG - Verifying the 182fb518-f13e-4d2b-9982-ecfcb6dd716f virtual network link through opserver 10.0.0.50 2026-08-24 03:04:15,680 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-machines 2026-08-24 03:04:15,695 - INFO - 182fb518-f13e-4d2b-9982-ecfcb6dd716f vm uve deleted from opserver 2026-08-24 03:04:15,695 - DEBUG - Verifying the 182fb518-f13e-4d2b-9982-ecfcb6dd716f virtual network link through opserver 10.0.0.191 2026-08-24 03:04:15,695 - DEBUG - Requesting: http://10.0.0.191:8081/analytics/uves/virtual-machines 2026-08-24 03:04:15,709 - INFO - 182fb518-f13e-4d2b-9982-ecfcb6dd716f vm uve deleted from opserver 2026-08-24 03:04:15,710 - INFO - Validated that VM 182fb518-f13e-4d2b-9982-ecfcb6dd716f is removed from Opserver 2026-08-24 03:04:15,710 - DEBUG - Detaching the Policy for VN :ctest-VN2-94781241 2026-08-24 03:04:15,710 - DEBUG - Requesting: http://10.0.0.190:8082/domains 2026-08-24 03:04:15,717 - DEBUG - Requesting: http://10.0.0.190:8082/domain/a2bfd9a2-e8fd-4333-9d61-3cfdf889946b 2026-08-24 03:04:15,762 - DEBUG - Requesting: http://10.0.0.190:8082/project/9dbffe12-ea12-4d8c-a6dc-61d1c04d5594 2026-08-24 03:04:15,878 - DEBUG - Requesting: http://10.0.0.190:8082/virtual-network/f2fe6f73-367a-49b4-97ad-ff839468e541 2026-08-24 03:04:15,902 - DEBUG - Requesting: http://10.0.0.190:8082/network-policy/9af5cdba-c2cb-4f4c-a527-324285396b41 2026-08-24 03:04:16,143 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-47405603', 'ctest-policy21-47621129']] from ctest-VN2-94781241 2026-08-24 03:04:16,143 - DEBUG - Detaching the Policy for VN :ctest-VN1-64192983 2026-08-24 03:04:16,143 - DEBUG - Requesting: http://10.0.0.190:8082/domains 2026-08-24 03:04:16,171 - DEBUG - Requesting: http://10.0.0.190:8082/domain/a2bfd9a2-e8fd-4333-9d61-3cfdf889946b 2026-08-24 03:04:16,188 - DEBUG - Requesting: http://10.0.0.190:8082/project/9dbffe12-ea12-4d8c-a6dc-61d1c04d5594 2026-08-24 03:04:16,294 - DEBUG - Requesting: http://10.0.0.190:8082/virtual-network/f3a31965-8e9f-4426-b689-9f2cfa018167 2026-08-24 03:04:16,314 - DEBUG - Requesting: http://10.0.0.190:8082/network-policy/380722af-61f9-4ebb-91d7-2b2265055517 2026-08-24 03:04:16,324 - DEBUG - Requesting: http://10.0.0.190:8082/network-policy/9b8b3c88-ff8d-4c42-8345-658ca0d51d80 2026-08-24 03:04:16,534 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-47405603', 'ctest-policy12-91550977'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-47405603', 'ctest-policy13-70947070']] from ctest-VN1-64192983 2026-08-24 03:04:16,602 - INFO - Deleted policy ctest-policy21-47621129 2026-08-24 03:04:16,668 - INFO - Deleted policy ctest-policy12-91550977 2026-08-24 03:04:16,708 - INFO - Deleted policy ctest-policy13-70947070 2026-08-24 03:04:16,714 - INFO - Deleting VN ctest-VN3-81445529 2026-08-24 03:04:16,931 - DEBUG - Response for deleting network () 2026-08-24 03:04:16,931 - DEBUG - Requesting: http://10.0.0.190:8082/routing-instance/555aab23-d608-4045-bf2d-f9882c2b9dcf 2026-08-24 03:04:16,939 - DEBUG - Response Code: 404 2026-08-24 03:04:16,940 - DEBUG - Requesting: http://10.0.0.190:8082/domains 2026-08-24 03:04:16,953 - DEBUG - Requesting: http://10.0.0.190:8082/domain/a2bfd9a2-e8fd-4333-9d61-3cfdf889946b 2026-08-24 03:04:16,974 - DEBUG - Requesting: http://10.0.0.190:8082/project/9dbffe12-ea12-4d8c-a6dc-61d1c04d5594 2026-08-24 03:04:17,092 - INFO - Validated that VN ctest-VN3-81445529 is not found in API Server 2026-08-24 03:04:17,106 - DEBUG - VN ctest-VN3-81445529 is not present in Agent 10.0.0.162 2026-08-24 03:04:17,117 - DEBUG - VN ctest-VN3-81445529 is not present in Agent 10.0.0.185 2026-08-24 03:04:17,117 - INFO - Validated that VN ctest-VN3-81445529 is not in any agent 2026-08-24 03:04:17,153 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN3-81445529 info 2026-08-24 03:04:17,154 - INFO - Deleting VN ctest-VN2-94781241 2026-08-24 03:04:17,381 - DEBUG - Response for deleting network () 2026-08-24 03:04:17,381 - DEBUG - Requesting: http://10.0.0.190:8082/routing-instance/9d270d93-35b8-4bf5-b0fb-ddd6837dae92 2026-08-24 03:04:17,400 - DEBUG - Response Code: 404 2026-08-24 03:04:17,400 - DEBUG - Requesting: http://10.0.0.190:8082/domains 2026-08-24 03:04:17,407 - DEBUG - Requesting: http://10.0.0.190:8082/domain/a2bfd9a2-e8fd-4333-9d61-3cfdf889946b 2026-08-24 03:04:17,431 - DEBUG - Requesting: http://10.0.0.190:8082/project/9dbffe12-ea12-4d8c-a6dc-61d1c04d5594 2026-08-24 03:04:17,556 - INFO - Validated that VN ctest-VN2-94781241 is not found in API Server 2026-08-24 03:04:17,569 - DEBUG - VN ctest-VN2-94781241 is not present in Agent 10.0.0.162 2026-08-24 03:04:17,590 - DEBUG - VN ctest-VN2-94781241 is not present in Agent 10.0.0.185 2026-08-24 03:04:17,590 - INFO - Validated that VN ctest-VN2-94781241 is not in any agent 2026-08-24 03:04:17,631 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN2-94781241 info 2026-08-24 03:04:17,632 - INFO - Deleting VN ctest-VN1-64192983 2026-08-24 03:04:17,904 - DEBUG - Response for deleting network () 2026-08-24 03:04:17,904 - DEBUG - Requesting: http://10.0.0.190:8082/routing-instance/327c14ba-4f9f-45e0-b43b-c46e900cfeb9 2026-08-24 03:04:17,911 - DEBUG - Response Code: 404 2026-08-24 03:04:17,911 - DEBUG - Requesting: http://10.0.0.190:8082/domains 2026-08-24 03:04:17,919 - DEBUG - Requesting: http://10.0.0.190:8082/domain/a2bfd9a2-e8fd-4333-9d61-3cfdf889946b 2026-08-24 03:04:17,930 - DEBUG - Requesting: http://10.0.0.190:8082/project/9dbffe12-ea12-4d8c-a6dc-61d1c04d5594 2026-08-24 03:04:18,041 - INFO - Validated that VN ctest-VN1-64192983 is not found in API Server 2026-08-24 03:04:18,057 - DEBUG - VN ctest-VN1-64192983 is not present in Agent 10.0.0.162 2026-08-24 03:04:18,068 - DEBUG - VN ctest-VN1-64192983 is not present in Agent 10.0.0.185 2026-08-24 03:04:18,068 - INFO - Validated that VN ctest-VN1-64192983 is not in any agent 2026-08-24 03:04:18,108 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN1-64192983 info 2026-08-24 03:04:18,211 - INFO - IPAM: ipam3 is not found in API Server 2026-08-24 03:04:18,234 - INFO - IPAM:ipam3 is not found in control node 2026-08-24 03:04:18,387 - INFO - IPAM: ipam2 is not found in API Server 2026-08-24 03:04:18,411 - INFO - IPAM:ipam2 is not found in control node 2026-08-24 03:04:18,555 - INFO - IPAM: ipam1 is not found in API Server 2026-08-24 03:04:18,585 - INFO - IPAM:ipam1 is not found in control node 2026-08-24 03:04:19,893 - DEBUG - No XMPP flaps were noticed during the test
2026-08-24 03:04:19,893 - INFO - END TEST : test_policy_inheritance_src_pol_dst_any : PASSED[0:03:54]
2026-08-24 03:04:19,894 - INFO - -------------------------------------------------------------------------------- 2026-08-24 03:04:21,206 - DEBUG - No XMPP flaps were noticed during the test
2026-08-24 03:04:21,206 - INFO - END TEST : test_policy_inheritance_src_pol_dst_any : PASSED[0:03:57]
2026-08-24 03:04:21,206 - INFO - -------------------------------------------------------------------------------- 2026-08-24 03:04:22,011 - INFO - Deleted project: ctest-TestPolicyAclIpv4v6-47405603, ID : 9dbffe12-ea12-4d8c-a6dc-61d1c04d5594