2025-10-08 13:46:51,562 - INFO - Domain Default found not creating 2025-10-08 13:46:51,685 - INFO - Project ctest-TestPolicyAclIpv4v6-85636480 not found, creating it 2025-10-08 13:46:52,212 - INFO - Created Project:ctest-TestPolicyAclIpv4v6-85636480, ID : 0352087d-1036-4b51-8231-7489e95cfc8b 2025-10-08 13:46:53,666 - INFO - ================================================================================ 2025-10-08 13:46:53,666 - INFO - STARTING TEST : test_policy_inheritance_src_pol_dst_any 2025-10-08 13:46:54,941 - DEBUG - Nothing to compare xmpp stats {'10.0.0.130': {'10.20.0.25': '0', '10.20.0.14': '0'}, '10.0.0.131': {'10.20.0.14': '0', '10.20.0.25': '0'}} with 2025-10-08 13:46:54,941 - INFO - Initial checks done. Running the testcase now 2025-10-08 13:46:54,941 - INFO - 2025-10-08 13:46:54,941 - INFO - ================================================================================ 2025-10-08 13:46:54,941 - INFO - STARTING TEST : test_policy_inheritance_src_pol_dst_any 2025-10-08 13:46:54,941 - INFO - TEST DESCRIPTION : Test cases to test policy inheritance 2025-10-08 13:46:56,224 - DEBUG - Nothing to compare xmpp stats {'10.0.0.130': {'10.20.0.25': '0', '10.20.0.14': '0'}, '10.0.0.131': {'10.20.0.14': '0', '10.20.0.25': '0'}} with 2025-10-08 13:46:56,224 - INFO - Initial checks done. Running the testcase now 2025-10-08 13:46:56,225 - INFO - 2025-10-08 13:46:57,519 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2025-10-08 13:46:57,810 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2025-10-08 13:46:57,837 - DEBUG - Requesting: http://10.0.0.33:8082/domain/e655d23d-1695-42cd-a914-8836b4a49568 2025-10-08 13:46:57,853 - DEBUG - Requesting: http://10.0.0.33:8082/project/0352087d-1036-4b51-8231-7489e95cfc8b 2025-10-08 13:46:57,952 - DEBUG - Requesting: http://10.0.0.33:8082/network-ipam/a2c27376-d4d6-48a5-9355-b0e0f8f7d67d 2025-10-08 13:46:57,959 - INFO - Verifications in API Server for IPAM: ipam1 passed 2025-10-08 13:46:57,965 - DEBUG - Control-node 10.0.0.33 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-85636480:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-85636480', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11728063333840341157', 'uuid-lslong': '10616586176891967101'}, 'enable': 'true', 'created': '2025-10-08T13:46:56', 'last-modified': '2025-10-08T13:46:56', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.151906'} 2025-10-08 13:46:57,971 - DEBUG - Control-node 10.0.0.19 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-85636480:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-85636480', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11728063333840341157', 'uuid-lslong': '10616586176891967101'}, 'enable': 'true', 'created': '2025-10-08T13:46:56', 'last-modified': '2025-10-08T13:46:56', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.158088'} 2025-10-08 13:46:57,976 - DEBUG - Control-node 10.0.0.129 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-85636480:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-85636480', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11728063333840341157', 'uuid-lslong': '10616586176891967101'}, 'enable': 'true', 'created': '2025-10-08T13:46:56', 'last-modified': '2025-10-08T13:46:56', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.164693'} 2025-10-08 13:46:57,976 - INFO - Verifications in Control node for IPAM: ipam1 passed 2025-10-08 13:46:57,976 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2025-10-08 13:46:57,982 - DEBUG - Requesting: http://10.0.0.33:8082/domain/e655d23d-1695-42cd-a914-8836b4a49568 2025-10-08 13:46:57,992 - DEBUG - Requesting: http://10.0.0.33:8082/project/0352087d-1036-4b51-8231-7489e95cfc8b 2025-10-08 13:46:58,091 - DEBUG - Requesting: http://10.0.0.33:8082/network-ipam/51ae7d7d-2836-48d7-8e4c-d6b881d3788b 2025-10-08 13:46:58,098 - INFO - Verifications in API Server for IPAM: ipam2 passed 2025-10-08 13:46:58,103 - DEBUG - Control-node 10.0.0.33 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-85636480:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-85636480', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5885779739518847191', 'uuid-lslong': '10253806539556223115'}, 'enable': 'true', 'created': '2025-10-08T13:46:56', 'last-modified': '2025-10-08T13:46:57', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.947683'} 2025-10-08 13:46:58,109 - DEBUG - Control-node 10.0.0.19 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-85636480:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-85636480', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5885779739518847191', 'uuid-lslong': '10253806539556223115'}, 'enable': 'true', 'created': '2025-10-08T13:46:56', 'last-modified': '2025-10-08T13:46:57', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.953699'} 2025-10-08 13:46:58,114 - DEBUG - Control-node 10.0.0.129 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-85636480:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-85636480', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5885779739518847191', 'uuid-lslong': '10253806539556223115'}, 'enable': 'true', 'created': '2025-10-08T13:46:56', 'last-modified': '2025-10-08T13:46:57', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.962924'} 2025-10-08 13:46:58,114 - INFO - Verifications in Control node for IPAM: ipam2 passed 2025-10-08 13:46:58,115 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2025-10-08 13:46:58,121 - DEBUG - Requesting: http://10.0.0.33:8082/domain/e655d23d-1695-42cd-a914-8836b4a49568 2025-10-08 13:46:58,132 - DEBUG - Requesting: http://10.0.0.33:8082/project/0352087d-1036-4b51-8231-7489e95cfc8b 2025-10-08 13:46:58,220 - DEBUG - Requesting: http://10.0.0.33:8082/network-ipam/a4b01ea2-41e0-4a8a-864e-e14981b0b5aa 2025-10-08 13:46:58,230 - INFO - Verifications in API Server for IPAM: ipam3 passed 2025-10-08 13:46:58,235 - DEBUG - Control-node 10.0.0.33 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-85636480:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-85636480', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11867018700360010378', 'uuid-lslong': '9677920355090478506'}, 'enable': 'true', 'created': '2025-10-08T13:46:57', 'last-modified': '2025-10-08T13:46:57', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.742891'} 2025-10-08 13:46:58,241 - DEBUG - Control-node 10.0.0.19 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-85636480:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-85636480', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11867018700360010378', 'uuid-lslong': '9677920355090478506'}, 'enable': 'true', 'created': '2025-10-08T13:46:57', 'last-modified': '2025-10-08T13:46:57', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.749743'} 2025-10-08 13:46:58,246 - DEBUG - Control-node 10.0.0.129 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-85636480:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-85636480', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11867018700360010378', 'uuid-lslong': '9677920355090478506'}, 'enable': 'true', 'created': '2025-10-08T13:46:57', 'last-modified': '2025-10-08T13:46:57', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.756657'} 2025-10-08 13:46:58,246 - INFO - Verifications in Control node for IPAM: ipam3 passed 2025-10-08 13:46:58,591 - DEBUG - Response for create_network : {'network': {'id': '1800800f-6fe1-40ca-a94b-8e1ece74e7d5', 'name': 'ctest-VN1-34668464', 'tenant_id': '0352087d10364b5182317489e95cfc8b', 'project_id': '0352087d10364b5182317489e95cfc8b', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-85636480', 'ctest-VN1-34668464'], 'port_security_enabled': True, 'description': ''}} 2025-10-08 13:46:58,882 - DEBUG - Response for create_subnet : {'subnet': {'id': '61a01806-54e3-41ea-a8c3-4417cac0dd91', 'name': '', 'tenant_id': '0352087d10364b5182317489e95cfc8b', 'network_id': '1800800f-6fe1-40ca-a94b-8e1ece74e7d5', 'ip_version': 4, 'cidr': '195.24.39.128/26', 'allocation_pools': [{'start': '195.24.39.130', 'end': '195.24.39.190'}], 'gateway_ip': '195.24.39.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '195.24.39.130', 'tags': [], 'project_id': '0352087d10364b5182317489e95cfc8b'}} 2025-10-08 13:46:59,114 - DEBUG - Response for create_subnet : {'subnet': {'id': 'a2ec6a76-0830-4073-b6e6-5e28fc10fb76', 'name': '', 'tenant_id': '0352087d10364b5182317489e95cfc8b', 'network_id': '1800800f-6fe1-40ca-a94b-8e1ece74e7d5', 'ip_version': 6, 'cidr': '28e9:2305:a72f:9ee7:4277:9718::/96', 'allocation_pools': [{'start': '28e9:2305:a72f:9ee7:4277:9718:0:2', 'end': '28e9:2305:a72f:9ee7:4277:9718:ffff:fffe'}], 'gateway_ip': '28e9:2305:a72f:9ee7:4277:9718:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '28e9:2305:a72f:9ee7:4277:9718:0:2', 'tags': [], 'project_id': '0352087d10364b5182317489e95cfc8b'}} 2025-10-08 13:46:59,136 - INFO - Created VN ctest-VN1-34668464 2025-10-08 13:46:59,148 - DEBUG - VN ctest-VN1-34668464 UUID is 1800800f-6fe1-40ca-a94b-8e1ece74e7d5 2025-10-08 13:46:59,340 - DEBUG - Fetched VN: default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464(1800800f-6fe1-40ca-a94b-8e1ece74e7d5) with subnets ['195.24.39.128/26', '28e9:2305:a72f:9ee7:4277:9718::/96'] 2025-10-08 13:46:59,631 - DEBUG - Response for create_network : {'network': {'id': '34a41372-63d3-4855-bf37-dd511381f83d', 'name': 'ctest-VN2-29274359', 'tenant_id': '0352087d10364b5182317489e95cfc8b', 'project_id': '0352087d10364b5182317489e95cfc8b', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-85636480', 'ctest-VN2-29274359'], 'port_security_enabled': True, 'description': ''}} 2025-10-08 13:46:59,872 - DEBUG - Response for create_subnet : {'subnet': {'id': '89e9f9e8-99d2-4e7f-8577-06d6d491ed93', 'name': '', 'tenant_id': '0352087d10364b5182317489e95cfc8b', 'network_id': '34a41372-63d3-4855-bf37-dd511381f83d', 'ip_version': 4, 'cidr': '30.182.164.64/26', 'allocation_pools': [{'start': '30.182.164.66', 'end': '30.182.164.126'}], 'gateway_ip': '30.182.164.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '30.182.164.66', 'tags': [], 'project_id': '0352087d10364b5182317489e95cfc8b'}} 2025-10-08 13:47:00,152 - DEBUG - Response for create_subnet : {'subnet': {'id': '02c412af-b804-4bb3-8494-2e831648fc37', 'name': '', 'tenant_id': '0352087d10364b5182317489e95cfc8b', 'network_id': '34a41372-63d3-4855-bf37-dd511381f83d', 'ip_version': 6, 'cidr': '2413:8e07:cdfc:a9fe:cedc:3304::/96', 'allocation_pools': [{'start': '2413:8e07:cdfc:a9fe:cedc:3304:0:2', 'end': '2413:8e07:cdfc:a9fe:cedc:3304:ffff:fffe'}], 'gateway_ip': '2413:8e07:cdfc:a9fe:cedc:3304:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '2413:8e07:cdfc:a9fe:cedc:3304:0:2', 'tags': [], 'project_id': '0352087d10364b5182317489e95cfc8b'}} 2025-10-08 13:47:00,178 - INFO - Created VN ctest-VN2-29274359 2025-10-08 13:47:00,194 - DEBUG - VN ctest-VN2-29274359 UUID is 34a41372-63d3-4855-bf37-dd511381f83d 2025-10-08 13:47:00,594 - DEBUG - Response for create_network : {'network': {'id': '4065ed29-f950-49a5-8d40-da9ebe8726a8', 'name': 'ctest-VN3-51664314', 'tenant_id': '0352087d10364b5182317489e95cfc8b', 'project_id': '0352087d10364b5182317489e95cfc8b', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-85636480', 'ctest-VN3-51664314'], 'port_security_enabled': True, 'description': ''}} 2025-10-08 13:47:00,826 - DEBUG - Response for create_subnet : {'subnet': {'id': '986cc8c6-9d14-412a-9a8c-b3b8dbb005b5', 'name': '', 'tenant_id': '0352087d10364b5182317489e95cfc8b', 'network_id': '4065ed29-f950-49a5-8d40-da9ebe8726a8', 'ip_version': 4, 'cidr': '145.235.220.64/26', 'allocation_pools': [{'start': '145.235.220.66', 'end': '145.235.220.126'}], 'gateway_ip': '145.235.220.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '145.235.220.66', 'tags': [], 'project_id': '0352087d10364b5182317489e95cfc8b'}} 2025-10-08 13:47:01,078 - DEBUG - Response for create_subnet : {'subnet': {'id': 'bd3c0697-2e72-420f-adcf-d1b113831269', 'name': '', 'tenant_id': '0352087d10364b5182317489e95cfc8b', 'network_id': '4065ed29-f950-49a5-8d40-da9ebe8726a8', 'ip_version': 6, 'cidr': '222d:4c3:fb84:306b:c7c4:d60a::/96', 'allocation_pools': [{'start': '222d:4c3:fb84:306b:c7c4:d60a:0:2', 'end': '222d:4c3:fb84:306b:c7c4:d60a:ffff:fffe'}], 'gateway_ip': '222d:4c3:fb84:306b:c7c4:d60a:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '222d:4c3:fb84:306b:c7c4:d60a:0:2', 'tags': [], 'project_id': '0352087d10364b5182317489e95cfc8b'}} 2025-10-08 13:47:01,107 - INFO - Created VN ctest-VN3-51664314 2025-10-08 13:47:01,120 - DEBUG - VN ctest-VN3-51664314 UUID is 4065ed29-f950-49a5-8d40-da9ebe8726a8 2025-10-08 13:47:01,222 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2025-10-08 13:47:01,228 - DEBUG - Requesting: http://10.0.0.33:8082/domain/e655d23d-1695-42cd-a914-8836b4a49568 2025-10-08 13:47:01,239 - DEBUG - Requesting: http://10.0.0.33:8082/project/0352087d-1036-4b51-8231-7489e95cfc8b 2025-10-08 13:47:01,339 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/1800800f-6fe1-40ca-a94b-8e1ece74e7d5 2025-10-08 13:47:01,353 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/1800800f-6fe1-40ca-a94b-8e1ece74e7d5 2025-10-08 13:47:01,364 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/ecf2653f-25f2-46cc-81ae-89f708366842 2025-10-08 13:47:01,372 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/ecf2653f-25f2-46cc-81ae-89f708366842 2025-10-08 13:47:01,379 - DEBUG - Requesting: http://10.0.0.33:8082/route-target/e3ce76f2-57fd-4325-b5dc-6ee44513903f 2025-10-08 13:47:01,386 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-10-08 13:47:01,386 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/1800800f-6fe1-40ca-a94b-8e1ece74e7d5 2025-10-08 13:47:01,396 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/ecf2653f-25f2-46cc-81ae-89f708366842 2025-10-08 13:47:01,415 - INFO - Verified VN network id 14 for VN 1800800f-6fe1-40ca-a94b-8e1ece74e7d5 2025-10-08 13:47:01,415 - INFO - Verifications in API Server for VN ctest-VN1-34668464 passed 2025-10-08 13:47:01,415 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/1800800f-6fe1-40ca-a94b-8e1ece74e7d5 2025-10-08 13:47:01,428 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/ecf2653f-25f2-46cc-81ae-89f708366842 2025-10-08 13:47:01,436 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/ecf2653f-25f2-46cc-81ae-89f708366842 2025-10-08 13:47:01,447 - DEBUG - Requesting: http://10.0.0.33:8082/route-target/e3ce76f2-57fd-4325-b5dc-6ee44513903f 2025-10-08 13:47:01,460 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-85636480', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1729523060700168394', 'uuid-lslong': '12199000278627051477'}, 'enable': 'true', 'created': '2025-10-08T13:46:58', 'last-modified': '2025-10-08T13:46:59', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.418326'} 2025-10-08 13:47:01,467 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-10-08 13:47:01,473 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-85636480', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1729523060700168394', 'uuid-lslong': '12199000278627051477'}, 'enable': 'true', 'created': '2025-10-08T13:46:58', 'last-modified': '2025-10-08T13:46:59', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.431407'} 2025-10-08 13:47:01,479 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-10-08 13:47:01,484 - DEBUG - Control-node 10.0.0.129 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-85636480', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1729523060700168394', 'uuid-lslong': '12199000278627051477'}, 'enable': 'true', 'created': '2025-10-08T13:46:58', 'last-modified': '2025-10-08T13:46:59', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.441744'} 2025-10-08 13:47:01,489 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-10-08 13:47:01,490 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN1-34668464 passed 2025-10-08 13:47:01,490 - DEBUG - ====Verifying policy data for ctest-VN1-34668464 in API_Server ====== 2025-10-08 13:47:01,490 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2025-10-08 13:47:01,496 - DEBUG - Requesting: http://10.0.0.33:8082/domain/e655d23d-1695-42cd-a914-8836b4a49568 2025-10-08 13:47:01,508 - DEBUG - Requesting: http://10.0.0.33:8082/project/0352087d-1036-4b51-8231-7489e95cfc8b 2025-10-08 13:47:01,609 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/1800800f-6fe1-40ca-a94b-8e1ece74e7d5 2025-10-08 13:47:01,620 - DEBUG - =>VN ctest-VN1-34668464 has no policy to be verified 2025-10-08 13:47:01,620 - DEBUG - Verifying the vn in opserver 2025-10-08 13:47:01,620 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464 virtual network link through opserver 10.0.0.33 2025-10-08 13:47:01,621 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks 2025-10-08 13:47:01,908 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks 2025-10-08 13:47:01,959 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464?flat'} 2025-10-08 13:47:01,959 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464 virtual network link through opserver 10.0.0.19 2025-10-08 13:47:01,959 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks 2025-10-08 13:47:02,261 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks 2025-10-08 13:47:02,319 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464?flat'} 2025-10-08 13:47:02,319 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464 virtual network link through opserver 10.0.0.129 2025-10-08 13:47:02,319 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-networks 2025-10-08 13:47:02,620 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-networks 2025-10-08 13:47:02,675 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464', 'href': 'http://10.0.0.129:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464?flat'} 2025-10-08 13:47:02,675 - INFO - Validated that VN default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464 is found in opserver 2025-10-08 13:47:02,696 - DEBUG - Do not have enough data to verify VN in agent 2025-10-08 13:47:02,707 - DEBUG - VRF ids for VN ctest-VN1-34668464: {} 2025-10-08 13:47:02,707 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2025-10-08 13:47:02,713 - DEBUG - Requesting: http://10.0.0.33:8082/domain/e655d23d-1695-42cd-a914-8836b4a49568 2025-10-08 13:47:02,723 - DEBUG - Requesting: http://10.0.0.33:8082/project/0352087d-1036-4b51-8231-7489e95cfc8b 2025-10-08 13:47:02,829 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/34a41372-63d3-4855-bf37-dd511381f83d 2025-10-08 13:47:02,841 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/34a41372-63d3-4855-bf37-dd511381f83d 2025-10-08 13:47:02,850 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/b5dcb65f-ae7e-43c2-8e00-cf3c6bf18a82 2025-10-08 13:47:02,857 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/b5dcb65f-ae7e-43c2-8e00-cf3c6bf18a82 2025-10-08 13:47:02,863 - DEBUG - Requesting: http://10.0.0.33:8082/route-target/fff8f0cd-f660-4748-86ed-c207ccc5aa74 2025-10-08 13:47:02,869 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-10-08 13:47:02,869 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/34a41372-63d3-4855-bf37-dd511381f83d 2025-10-08 13:47:02,877 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/b5dcb65f-ae7e-43c2-8e00-cf3c6bf18a82 2025-10-08 13:47:02,891 - INFO - Verified VN network id 15 for VN 34a41372-63d3-4855-bf37-dd511381f83d 2025-10-08 13:47:02,891 - INFO - Verifications in API Server for VN ctest-VN2-29274359 passed 2025-10-08 13:47:02,891 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/34a41372-63d3-4855-bf37-dd511381f83d 2025-10-08 13:47:02,899 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/b5dcb65f-ae7e-43c2-8e00-cf3c6bf18a82 2025-10-08 13:47:02,906 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/b5dcb65f-ae7e-43c2-8e00-cf3c6bf18a82 2025-10-08 13:47:02,914 - DEBUG - Requesting: http://10.0.0.33:8082/route-target/fff8f0cd-f660-4748-86ed-c207ccc5aa74 2025-10-08 13:47:02,926 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN2-29274359', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-85636480', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3793178168174790741', 'uuid-lslong': '13778724925252696125'}, 'enable': 'true', 'created': '2025-10-08T13:46:59', 'last-modified': '2025-10-08T13:47:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.846457'} 2025-10-08 13:47:02,932 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-10-08 13:47:02,937 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN2-29274359', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-85636480', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3793178168174790741', 'uuid-lslong': '13778724925252696125'}, 'enable': 'true', 'created': '2025-10-08T13:46:59', 'last-modified': '2025-10-08T13:47:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.860152'} 2025-10-08 13:47:02,942 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-10-08 13:47:02,948 - DEBUG - Control-node 10.0.0.129 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN2-29274359', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-85636480', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3793178168174790741', 'uuid-lslong': '13778724925252696125'}, 'enable': 'true', 'created': '2025-10-08T13:46:59', 'last-modified': '2025-10-08T13:47:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.868283'} 2025-10-08 13:47:02,954 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-10-08 13:47:02,954 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN2-29274359 passed 2025-10-08 13:47:02,954 - DEBUG - ====Verifying policy data for ctest-VN2-29274359 in API_Server ====== 2025-10-08 13:47:02,954 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2025-10-08 13:47:02,959 - DEBUG - Requesting: http://10.0.0.33:8082/domain/e655d23d-1695-42cd-a914-8836b4a49568 2025-10-08 13:47:02,969 - DEBUG - Requesting: http://10.0.0.33:8082/project/0352087d-1036-4b51-8231-7489e95cfc8b 2025-10-08 13:47:03,074 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/34a41372-63d3-4855-bf37-dd511381f83d 2025-10-08 13:47:03,085 - DEBUG - =>VN ctest-VN2-29274359 has no policy to be verified 2025-10-08 13:47:03,085 - DEBUG - Verifying the vn in opserver 2025-10-08 13:47:03,085 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN2-29274359 virtual network link through opserver 10.0.0.33 2025-10-08 13:47:03,085 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks 2025-10-08 13:47:03,133 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN2-29274359', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN2-29274359?flat'} 2025-10-08 13:47:03,133 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN2-29274359 virtual network link through opserver 10.0.0.19 2025-10-08 13:47:03,133 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks 2025-10-08 13:47:03,186 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN2-29274359', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN2-29274359?flat'} 2025-10-08 13:47:03,186 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN2-29274359 virtual network link through opserver 10.0.0.129 2025-10-08 13:47:03,186 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-networks 2025-10-08 13:47:03,241 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN2-29274359', 'href': 'http://10.0.0.129:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN2-29274359?flat'} 2025-10-08 13:47:03,241 - INFO - Validated that VN default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN2-29274359 is found in opserver 2025-10-08 13:47:03,270 - DEBUG - Do not have enough data to verify VN in agent 2025-10-08 13:47:03,280 - DEBUG - VRF ids for VN ctest-VN2-29274359: {} 2025-10-08 13:47:03,280 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2025-10-08 13:47:03,287 - DEBUG - Requesting: http://10.0.0.33:8082/domain/e655d23d-1695-42cd-a914-8836b4a49568 2025-10-08 13:47:03,298 - DEBUG - Requesting: http://10.0.0.33:8082/project/0352087d-1036-4b51-8231-7489e95cfc8b 2025-10-08 13:47:03,389 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/4065ed29-f950-49a5-8d40-da9ebe8726a8 2025-10-08 13:47:03,401 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/4065ed29-f950-49a5-8d40-da9ebe8726a8 2025-10-08 13:47:03,410 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/904db1d6-7767-476b-b021-dffa5867d398 2025-10-08 13:47:03,417 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/904db1d6-7767-476b-b021-dffa5867d398 2025-10-08 13:47:03,432 - DEBUG - Requesting: http://10.0.0.33:8082/route-target/50be8d2d-43d0-40d4-84c7-57153581c35b 2025-10-08 13:47:03,438 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-10-08 13:47:03,438 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/4065ed29-f950-49a5-8d40-da9ebe8726a8 2025-10-08 13:47:03,447 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/904db1d6-7767-476b-b021-dffa5867d398 2025-10-08 13:47:03,462 - INFO - Verified VN network id 16 for VN 4065ed29-f950-49a5-8d40-da9ebe8726a8 2025-10-08 13:47:03,462 - INFO - Verifications in API Server for VN ctest-VN3-51664314 passed 2025-10-08 13:47:03,462 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/4065ed29-f950-49a5-8d40-da9ebe8726a8 2025-10-08 13:47:03,471 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/904db1d6-7767-476b-b021-dffa5867d398 2025-10-08 13:47:03,477 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/904db1d6-7767-476b-b021-dffa5867d398 2025-10-08 13:47:03,485 - DEBUG - Requesting: http://10.0.0.33:8082/route-target/50be8d2d-43d0-40d4-84c7-57153581c35b 2025-10-08 13:47:03,495 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN3-51664314', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-85636480', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4640375755607394725', 'uuid-lslong': '10178375533193537192'}, 'enable': 'true', 'created': '2025-10-08T13:47:00', 'last-modified': '2025-10-08T13:47:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.469321'} 2025-10-08 13:47:03,500 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-10-08 13:47:03,505 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN3-51664314', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-85636480', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4640375755607394725', 'uuid-lslong': '10178375533193537192'}, 'enable': 'true', 'created': '2025-10-08T13:47:00', 'last-modified': '2025-10-08T13:47:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.476261'} 2025-10-08 13:47:03,511 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-10-08 13:47:03,519 - DEBUG - Control-node 10.0.0.129 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN3-51664314', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-85636480', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4640375755607394725', 'uuid-lslong': '10178375533193537192'}, 'enable': 'true', 'created': '2025-10-08T13:47:00', 'last-modified': '2025-10-08T13:47:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.490420'} 2025-10-08 13:47:03,527 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-10-08 13:47:03,527 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN3-51664314 passed 2025-10-08 13:47:03,528 - DEBUG - ====Verifying policy data for ctest-VN3-51664314 in API_Server ====== 2025-10-08 13:47:03,528 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2025-10-08 13:47:03,533 - DEBUG - Requesting: http://10.0.0.33:8082/domain/e655d23d-1695-42cd-a914-8836b4a49568 2025-10-08 13:47:03,542 - DEBUG - Requesting: http://10.0.0.33:8082/project/0352087d-1036-4b51-8231-7489e95cfc8b 2025-10-08 13:47:03,639 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/4065ed29-f950-49a5-8d40-da9ebe8726a8 2025-10-08 13:47:03,653 - DEBUG - =>VN ctest-VN3-51664314 has no policy to be verified 2025-10-08 13:47:03,653 - DEBUG - Verifying the vn in opserver 2025-10-08 13:47:03,653 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN3-51664314 virtual network link through opserver 10.0.0.33 2025-10-08 13:47:03,653 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks 2025-10-08 13:47:03,752 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN3-51664314', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN3-51664314?flat'} 2025-10-08 13:47:03,752 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN3-51664314 virtual network link through opserver 10.0.0.19 2025-10-08 13:47:03,752 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks 2025-10-08 13:47:03,817 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN3-51664314', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN3-51664314?flat'} 2025-10-08 13:47:03,817 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN3-51664314 virtual network link through opserver 10.0.0.129 2025-10-08 13:47:03,817 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-networks 2025-10-08 13:47:03,889 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN3-51664314', 'href': 'http://10.0.0.129:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN3-51664314?flat'} 2025-10-08 13:47:03,889 - INFO - Validated that VN default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN3-51664314 is found in opserver 2025-10-08 13:47:03,917 - DEBUG - Do not have enough data to verify VN in agent 2025-10-08 13:47:03,931 - DEBUG - VRF ids for VN ctest-VN3-51664314: {} 2025-10-08 13:47:03,936 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464, 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-85636480:ctest-VN3-51664314, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None] 2025-10-08 13:47:04,239 - DEBUG - No source network defined 2025-10-08 13:47:04,239 - DEBUG - No source network defined 2025-10-08 13:47:04,240 - 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-85636480:ctest-policy13-52627997, 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-85636480:ctest-policy13-52627997, subnet_list = []], src_ports = [start_port = -1, end_port = -1], application = [], dst_addresses = [subnet = None, virtual_network = any, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None] 2025-10-08 13:47:04,523 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN2-29274359, 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-85636480:ctest-VN1-34668464, 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-85636480:ctest-VN2-29274359, 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-85636480:ctest-VN1-34668464, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None] 2025-10-08 13:47:04,760 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2025-10-08 13:47:04,775 - DEBUG - Requesting: http://10.0.0.33:8082/domain/e655d23d-1695-42cd-a914-8836b4a49568 2025-10-08 13:47:04,786 - DEBUG - Requesting: http://10.0.0.33:8082/project/0352087d-1036-4b51-8231-7489e95cfc8b 2025-10-08 13:47:04,885 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/1800800f-6fe1-40ca-a94b-8e1ece74e7d5 2025-10-08 13:47:04,894 - DEBUG - Setup step: Associating the policy to VN' 2025-10-08 13:47:05,060 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '1800800f-6fe1-40ca-a94b-8e1ece74e7d5', 'name': 'ctest-VN1-34668464', 'tenant_id': '0352087d10364b5182317489e95cfc8b', 'project_id': '0352087d10364b5182317489e95cfc8b', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['61a01806-54e3-41ea-a8c3-4417cac0dd91', 'a2ec6a76-0830-4073-b6e6-5e28fc10fb76'], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-85636480', 'ctest-VN1-34668464'], 'policys': [['default-domain', 'ctest-TestPolicyAclIpv4v6-85636480', 'ctest-policy12-42722657'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-85636480', 'ctest-policy13-52627997']], 'subnet_ipam': [{'subnet_cidr': '195.24.39.128/26', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-85636480', 'ipam1']}, {'subnet_cidr': '28e9:2305:a72f:9ee7:4277:9718::/96', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-85636480', 'ipam1']}], 'port_security_enabled': True, 'description': ''}} 2025-10-08 13:47:05,198 - DEBUG - Associated Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-85636480', 'ctest-policy12-42722657'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-85636480', 'ctest-policy13-52627997']] to ctest-VN1-34668464 2025-10-08 13:47:05,198 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2025-10-08 13:47:05,204 - DEBUG - Requesting: http://10.0.0.33:8082/domain/e655d23d-1695-42cd-a914-8836b4a49568 2025-10-08 13:47:05,215 - DEBUG - Requesting: http://10.0.0.33:8082/project/0352087d-1036-4b51-8231-7489e95cfc8b 2025-10-08 13:47:05,300 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/34a41372-63d3-4855-bf37-dd511381f83d 2025-10-08 13:47:05,310 - DEBUG - Setup step: Associating the policy to VN' 2025-10-08 13:47:05,441 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '34a41372-63d3-4855-bf37-dd511381f83d', 'name': 'ctest-VN2-29274359', 'tenant_id': '0352087d10364b5182317489e95cfc8b', 'project_id': '0352087d10364b5182317489e95cfc8b', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['89e9f9e8-99d2-4e7f-8577-06d6d491ed93', '02c412af-b804-4bb3-8494-2e831648fc37'], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-85636480', 'ctest-VN2-29274359'], 'policys': [['default-domain', 'ctest-TestPolicyAclIpv4v6-85636480', 'ctest-policy21-95624195']], 'subnet_ipam': [{'subnet_cidr': '30.182.164.64/26', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-85636480', 'ipam2']}, {'subnet_cidr': '2413:8e07:cdfc:a9fe:cedc:3304::/96', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-85636480', 'ipam2']}], 'port_security_enabled': True, 'description': ''}} 2025-10-08 13:47:05,526 - DEBUG - Associated Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-85636480', 'ctest-policy21-95624195']] to ctest-VN2-29274359 2025-10-08 13:47:05,636 - DEBUG - Services list from nova: [, , , ] 2025-10-08 13:47:06,825 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4234-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4234-2) 2025-10-08 13:47:08,095 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4234-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4234-1) 2025-10-08 13:47:09,511 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4234-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4234-2) 2025-10-08 13:47:09,630 - DEBUG - VM is in ACTIVE state now 2025-10-08 13:47:09,630 - INFO - VM name : ctest-VM11-78413541 2025-10-08 13:47:09,735 - DEBUG - VM ctest-VM11-78413541 ID is 611688e2-f519-4b55-8bce-366e33060015 2025-10-08 13:47:09,820 - DEBUG - VM is in ACTIVE state now 2025-10-08 13:47:09,842 - DEBUG - VM ctest-VM11-78413541 launched on Node an-jenkins-deploy-platform-ansible-os-4234-2 2025-10-08 13:47:09,934 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine/611688e2-f519-4b55-8bce-366e33060015 2025-10-08 13:47:10,339 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine/611688e2-f519-4b55-8bce-366e33060015 2025-10-08 13:47:10,371 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine/611688e2-f519-4b55-8bce-366e33060015 2025-10-08 13:47:10,380 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine-interface/b0f71d43-dbbb-4255-9f43-45c5d57ac026 2025-10-08 13:47:10,390 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine/611688e2-f519-4b55-8bce-366e33060015 2025-10-08 13:47:10,402 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine-interface/b0f71d43-dbbb-4255-9f43-45c5d57ac026 2025-10-08 13:47:10,422 - DEBUG - Requesting: http://10.0.0.129:8082/instance-ip/07a4ddc5-6709-4f07-b6cb-5b17be3608d4 2025-10-08 13:47:10,434 - DEBUG - Requesting: http://10.0.0.129:8082/instance-ip/bbb4f022-3c5f-4b9e-97be-3e9db766a28a 2025-10-08 13:47:10,445 - DEBUG - Verifying in api server 10.0.0.129 2025-10-08 13:47:10,445 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine/611688e2-f519-4b55-8bce-366e33060015 2025-10-08 13:47:10,452 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine-interface/b0f71d43-dbbb-4255-9f43-45c5d57ac026 2025-10-08 13:47:10,461 - DEBUG - Requesting: http://10.0.0.129:8082/instance-ip/07a4ddc5-6709-4f07-b6cb-5b17be3608d4 2025-10-08 13:47:10,468 - DEBUG - Requesting: http://10.0.0.129:8082/instance-ip/bbb4f022-3c5f-4b9e-97be-3e9db766a28a 2025-10-08 13:47:10,476 - INFO - VM ctest-VM11-78413541 verfication in all API Servers passed 2025-10-08 13:47:10,498 - DEBUG - VM ctest-VM11-78413541 Tap interface: {'index': '3', 'name': 'tapb0f71d43-db', 'uuid': 'b0f71d43-dbbb-4255-9f43-45c5d57ac026', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464:ctest-VN1-34668464', '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': '14', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464', 'vm_uuid': '611688e2-f519-4b55-8bce-366e33060015', 'vm_name': 'ctest-VM11-78413541', 'ip_addr': '195.24.39.131', 'mac_addr': '02:b0:f7:1d:43:db', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.3', 'service_vlan_list': None, 'os_ifindex': '50', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestPolicyAclIpv4v6-85636480:b0f71d43-dbbb-4255-9f43-45c5d57ac026', 'sg_uuid_list': ['a1d2a8df-25ad-4ff6-9bac-1b729df06b9b'], 'static_route_list': None, 'vm_project_uuid': '0352087d-1036-4b51-8231-7489e95cfc8b', 'admin_state': 'Enabled', 'flow_key_idx': '31', 'allowed_address_pair_list': None, 'ip6_addr': '28e9:2305:a72f:9ee7:4277:9718:0:3', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []} 2025-10-08 13:47:10,498 - DEBUG - Agent 10.0.0.131 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464:ctest-VN1-34668464 2025-10-08 13:47:10,504 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464:ctest-VN1-34668464', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464', 'table_label': '-1', 'vxlan_id': '14', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.129:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2025-10-08 13:47:10,604 - DEBUG - VM ctest-VM11-78413541 : Tap interface tapb0f71d43-db is set to Active 2025-10-08 13:47:10,604 - DEBUG - Tap interface tapb0f71d43-db detail : {'index': '3', 'name': 'tapb0f71d43-db', 'uuid': 'b0f71d43-dbbb-4255-9f43-45c5d57ac026', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464:ctest-VN1-34668464', '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': '14', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464', 'vm_uuid': '611688e2-f519-4b55-8bce-366e33060015', 'vm_name': 'ctest-VM11-78413541', 'ip_addr': '195.24.39.131', 'mac_addr': '02:b0:f7:1d:43:db', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.3', 'service_vlan_list': None, 'os_ifindex': '50', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestPolicyAclIpv4v6-85636480:b0f71d43-dbbb-4255-9f43-45c5d57ac026', 'sg_uuid_list': ['a1d2a8df-25ad-4ff6-9bac-1b729df06b9b'], 'static_route_list': None, 'vm_project_uuid': '0352087d-1036-4b51-8231-7489e95cfc8b', 'admin_state': 'Enabled', 'flow_key_idx': '31', 'allowed_address_pair_list': None, 'ip6_addr': '28e9:2305:a72f:9ee7:4277:9718:0:3', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []} 2025-10-08 13:47:10,733 - DEBUG - Starting Layer 2 verification in Agent 2025-10-08 13:47:10,744 - DEBUG - Layer 2 path is seen for VM MAC 02:b0:f7:1d:43:db in agent 10.0.0.131 2025-10-08 13:47:10,744 - DEBUG - Active layer 2 route in agent is present for VMI tapb0f71d43-db 2025-10-08 13:47:10,744 - DEBUG - L2 label(29) matches bw route table and interface table 2025-10-08 13:47:10,755 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464 is found in Agent of node 10.0.0.131 2025-10-08 13:47:10,908 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464 is consistent in agent 10.0.0.131 2025-10-08 13:47:10,908 - DEBUG - Route for VM IP ['28e9:2305:a72f:9ee7:4277:9718:0:3', '195.24.39.131'] is consistent in agent 10.0.0.131 2025-10-08 13:47:10,908 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464 verification for VM ctest-VM11-78413541 in Agent 10.0.0.131 passed 2025-10-08 13:47:11,021 - DEBUG - Starting all layer 2 verification in agent 10.0.0.131 2025-10-08 13:47:11,041 - DEBUG - Route for VM MAC 02:b0:f7:1d:43:db is consistent in agent 10.0.0.131 2025-10-08 13:47:14,242 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1019ms') 2025-10-08 13:47:14,242 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-78413541 failed! 2025-10-08 13:47:14,255 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464 is 195.24.39.129 and allocation pool is NOT set 2025-10-08 13:47:14,256 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464 is 28e9:2305:a72f:9ee7:4277:9718:0:1 and allocation pool is NOT set 2025-10-08 13:47:18,340 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1013ms') 2025-10-08 13:47:18,340 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-78413541 failed! 2025-10-08 13:47:18,352 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464 is 195.24.39.129 and allocation pool is NOT set 2025-10-08 13:47:18,352 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464 is 28e9:2305:a72f:9ee7:4277:9718:0:1 and allocation pool is NOT set 2025-10-08 13:47:22,431 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1015ms') 2025-10-08 13:47:22,432 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-78413541 failed! 2025-10-08 13:47:22,488 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464 is 195.24.39.129 and allocation pool is NOT set 2025-10-08 13:47:22,489 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464 is 28e9:2305:a72f:9ee7:4277:9718:0:1 and allocation pool is NOT set 2025-10-08 13:47:26,562 - 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 1010ms') 2025-10-08 13:47:26,562 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-78413541 failed! 2025-10-08 13:47:26,576 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464 is 195.24.39.129 and allocation pool is NOT set 2025-10-08 13:47:26,576 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464 is 28e9:2305:a72f:9ee7:4277:9718:0:1 and allocation pool is NOT set 2025-10-08 13:47:30,653 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms') 2025-10-08 13:47:30,653 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-78413541 failed! 2025-10-08 13:47:30,666 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464 is 195.24.39.129 and allocation pool is NOT set 2025-10-08 13:47:30,666 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464 is 28e9:2305:a72f:9ee7:4277:9718:0:1 and allocation pool is NOT set 2025-10-08 13:47:34,746 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1017ms') 2025-10-08 13:47:34,747 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-78413541 failed! 2025-10-08 13:47:34,760 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464 is 195.24.39.129 and allocation pool is NOT set 2025-10-08 13:47:34,760 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464 is 28e9:2305:a72f:9ee7:4277:9718:0:1 and allocation pool is NOT set 2025-10-08 13:47:38,840 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1022ms') 2025-10-08 13:47:38,840 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-78413541 failed! 2025-10-08 13:47:38,855 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464 is 195.24.39.129 and allocation pool is NOT set 2025-10-08 13:47:38,855 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464 is 28e9:2305:a72f:9ee7:4277:9718:0:1 and allocation pool is NOT set 2025-10-08 13:47:42,934 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1021ms') 2025-10-08 13:47:42,935 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-78413541 failed! 2025-10-08 13:47:42,947 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464 is 195.24.39.129 and allocation pool is NOT set 2025-10-08 13:47:42,947 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464 is 28e9:2305:a72f:9ee7:4277:9718:0:1 and allocation pool is NOT set 2025-10-08 13:47:47,037 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1023ms') 2025-10-08 13:47:47,037 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-78413541 failed! 2025-10-08 13:47:47,054 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464 is 195.24.39.129 and allocation pool is NOT set 2025-10-08 13:47:47,054 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464 is 28e9:2305:a72f:9ee7:4277:9718:0:1 and allocation pool is NOT set 2025-10-08 13:47:51,126 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1013ms') 2025-10-08 13:47:51,127 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-78413541 failed! 2025-10-08 13:47:51,143 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464 is 195.24.39.129 and allocation pool is NOT set 2025-10-08 13:47:51,143 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464 is 28e9:2305:a72f:9ee7:4277:9718:0:1 and allocation pool is NOT set 2025-10-08 13:47:55,229 - 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 1020ms') 2025-10-08 13:47:55,229 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-78413541 failed! 2025-10-08 13:47:55,245 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464 is 195.24.39.129 and allocation pool is NOT set 2025-10-08 13:47:55,245 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464 is 28e9:2305:a72f:9ee7:4277:9718:0:1 and allocation pool is NOT set 2025-10-08 13:47:59,323 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=7.65 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 1 received, 50% packet loss, time 1015ms\r\nrtt min/avg/max/mdev = 7.650/7.650/7.650/0.000 ms') 2025-10-08 13:47:59,323 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-78413541 passed 2025-10-08 13:47:59,323 - INFO - VM ctest-VM11-78413541 verifications in Compute nodes passed 2025-10-08 13:47:59,589 - DEBUG - Validated VM route 195.24.39.131 in vrouter of 10.0.0.131 2025-10-08 13:47:59,601 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0 2025-10-08 13:47:59,601 - INFO - Validated routes of VM ctest-VM11-78413541 in all vrouters 2025-10-08 13:48:00,012 - DEBUG - Starting all layer2 verification in 10.0.0.129 Control Node 2025-10-08 13:48:00,128 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.129, not supported 2025-10-08 13:48:00,253 - DEBUG - Layer2 route found for VM MAC 02:b0:f7:1d:43:db in Control-node 10.0.0.129 2025-10-08 13:48:00,253 - DEBUG - L2 Label for VM ctest-VM11-78413541 same between Control-node 10.0.0.129 and Agent, Expected: 29, Seen: 29 2025-10-08 13:48:00,253 - DEBUG - Starting all layer2 verification in 10.0.0.19 Control Node 2025-10-08 13:48:00,364 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.19, not supported 2025-10-08 13:48:00,488 - DEBUG - Layer2 route found for VM MAC 02:b0:f7:1d:43:db in Control-node 10.0.0.19 2025-10-08 13:48:00,488 - DEBUG - L2 Label for VM ctest-VM11-78413541 same between Control-node 10.0.0.19 and Agent, Expected: 29, Seen: 29 2025-10-08 13:48:00,488 - INFO - Verification in Control-nodes for VM ctest-VM11-78413541 passed 2025-10-08 13:48:00,488 - DEBUG - Verifying the vm in opserver 2025-10-08 13:48:00,488 - DEBUG - Verifying in collector 10.0.0.129 ... 2025-10-08 13:48:00,488 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-machine/611688e2-f519-4b55-8bce-366e33060015?flat 2025-10-08 13:48:00,506 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-85636480:b0f71d43-dbbb-4255-9f43-45c5d57ac026?flat 2025-10-08 13:48:00,530 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-85636480:b0f71d43-dbbb-4255-9f43-45c5d57ac026?flat 2025-10-08 13:48:00,550 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-85636480:b0f71d43-dbbb-4255-9f43-45c5d57ac026?flat 2025-10-08 13:48:00,573 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-machine/611688e2-f519-4b55-8bce-366e33060015?flat 2025-10-08 13:48:00,597 - DEBUG - Verifying vm in vn uve 2025-10-08 13:48:00,597 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-85636480:b0f71d43-dbbb-4255-9f43-45c5d57ac026?flat 2025-10-08 13:48:00,618 - DEBUG - VM uve shows interface as {'is_health_check_active': True, 'admin_state': True, 'l2_active': True, 'ip4_active': True, 'vm_name': 'ctest-VM11-78413541', 'fixed_ip6_list': ['28e9:2305:a72f:9ee7:4277:9718:0:3'], 'label': 25, 'fixed_ip4_list': ['195.24.39.131'], 'ip6_active': True, 'port_mirror_enabled': False, 'vhostuser_mode': 0, 'ip_address': '195.24.39.131', 'mac_address': '02:b0:f7:1d:43:db', 'vm_uuid': '611688e2-f519-4b55-8bce-366e33060015', 'rx_vlan': 65535, 'gateway': '195.24.39.129', 'active': True, 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464', 'vn_uuid': '1800800f-6fe1-40ca-a94b-8e1ece74e7d5', 'uuid': 'b0f71d43-dbbb-4255-9f43-45c5d57ac026', '__T': 1759931233315011, 'tx_vlan': 65535, 'ip6_address': '28e9:2305:a72f:9ee7:4277:9718:0:3'} 2025-10-08 13:48:00,618 - DEBUG - VM uve shows ip address as ['195.24.39.131', '28e9:2305:a72f:9ee7:4277:9718:0:3'] 2025-10-08 13:48:00,618 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464 2025-10-08 13:48:00,618 - INFO - Verifying through opserver in 10.0.0.33 2025-10-08 13:48:00,618 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464?flat 2025-10-08 13:48:00,642 - DEBUG - expected vm list 611688e2-f519-4b55-8bce-366e33060015 2025-10-08 13:48:00,642 - DEBUG - Extracted vm list ['611688e2-f519-4b55-8bce-366e33060015'] 2025-10-08 13:48:00,642 - INFO - VM 611688e2-f519-4b55-8bce-366e33060015 is present in default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464 2025-10-08 13:48:00,642 - DEBUG - Verifying vm in vrouter uve 2025-10-08 13:48:00,642 - DEBUG - Getting info from collector 10.0.0.129.. 2025-10-08 13:48:00,642 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-machine/611688e2-f519-4b55-8bce-366e33060015?flat 2025-10-08 13:48:00,661 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-4234-2'] 2025-10-08 13:48:00,661 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4234-2:Compute:contrail-vrouter-agent:0?flat 2025-10-08 13:48:00,698 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4234-2?flat 2025-10-08 13:48:00,736 - DEBUG - VM 611688e2-f519-4b55-8bce-366e33060015 is present in vrouter an-jenkins-deploy-platform-ansible-os-4234-2 uve 2025-10-08 13:48:00,736 - DEBUG - Validated that VM ctest-VM11-78413541 is in Vrouter an-jenkins-deploy-platform-ansible-os-4234-2 UVE 2025-10-08 13:48:00,736 - DEBUG - Verifying vm tap interface/vn in vrouter uve 2025-10-08 13:48:00,745 - DEBUG - Expected tap interface of VM uuid 611688e2-f519-4b55-8bce-366e33060015 is default-domain:ctest-TestPolicyAclIpv4v6-85636480:b0f71d43-dbbb-4255-9f43-45c5d57ac026 2025-10-08 13:48:00,745 - DEBUG - Expected VN of VM uuid 611688e2-f519-4b55-8bce-366e33060015 is default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464 2025-10-08 13:48:00,746 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4234-2:Compute:contrail-vrouter-agent:0?flat 2025-10-08 13:48:00,781 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4234-2?flat 2025-10-08 13:48:00,815 - DEBUG - VM 611688e2-f519-4b55-8bce-366e33060015 is present in vrouter an-jenkins-deploy-platform-ansible-os-4234-2 uve 2025-10-08 13:48:00,815 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-85636480:b0f71d43-dbbb-4255-9f43-45c5d57ac026 of vm 611688e2-f519-4b55-8bce-366e33060015 is present in vrouter an-jenkins-deploy-platform-ansible-os-4234-2 uve 2025-10-08 13:48:00,815 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464 of vm 611688e2-f519-4b55-8bce-366e33060015 is present in vrouter an-jenkins-deploy-platform-ansible-os-4234-2 uve 2025-10-08 13:48:00,815 - DEBUG - Verifying the 611688e2-f519-4b55-8bce-366e33060015 virtual network link through opserver 10.0.0.33 2025-10-08 13:48:00,815 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machines 2025-10-08 13:48:00,829 - DEBUG - VM link and name as {'name': '611688e2-f519-4b55-8bce-366e33060015', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-machine/611688e2-f519-4b55-8bce-366e33060015?flat'} 2025-10-08 13:48:00,829 - DEBUG - Verifying the 611688e2-f519-4b55-8bce-366e33060015 virtual network link through opserver 10.0.0.19 2025-10-08 13:48:00,829 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machines 2025-10-08 13:48:00,844 - DEBUG - VM link and name as {'name': '611688e2-f519-4b55-8bce-366e33060015', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-machine/611688e2-f519-4b55-8bce-366e33060015?flat'} 2025-10-08 13:48:00,844 - DEBUG - Verifying the 611688e2-f519-4b55-8bce-366e33060015 virtual network link through opserver 10.0.0.129 2025-10-08 13:48:00,844 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-machines 2025-10-08 13:48:00,859 - DEBUG - VM link and name as {'name': '611688e2-f519-4b55-8bce-366e33060015', 'href': 'http://10.0.0.129:8081/analytics/uves/virtual-machine/611688e2-f519-4b55-8bce-366e33060015?flat'} 2025-10-08 13:48:00,859 - INFO - VM ctest-VM11-78413541 validations in Opserver passed 2025-10-08 13:48:00,979 - DEBUG - VM is in ACTIVE state now 2025-10-08 13:48:00,979 - INFO - VM name : ctest-VM21-02067792 2025-10-08 13:48:01,078 - DEBUG - VM ctest-VM21-02067792 ID is 153268f8-3b5c-45da-9e2b-5f907a2b29c9 2025-10-08 13:48:01,174 - DEBUG - VM is in ACTIVE state now 2025-10-08 13:48:01,174 - DEBUG - VM ctest-VM21-02067792 launched on Node an-jenkins-deploy-platform-ansible-os-4234-1 2025-10-08 13:48:01,262 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine/153268f8-3b5c-45da-9e2b-5f907a2b29c9 2025-10-08 13:48:01,271 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine/153268f8-3b5c-45da-9e2b-5f907a2b29c9 2025-10-08 13:48:01,279 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine-interface/78682a7d-3a68-4ffb-9b1d-0ad9d225f32a 2025-10-08 13:48:01,292 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine/153268f8-3b5c-45da-9e2b-5f907a2b29c9 2025-10-08 13:48:01,301 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine-interface/78682a7d-3a68-4ffb-9b1d-0ad9d225f32a 2025-10-08 13:48:01,315 - DEBUG - Requesting: http://10.0.0.129:8082/instance-ip/0793eb9c-057f-4b11-9528-4e28e79fbd71 2025-10-08 13:48:01,328 - DEBUG - Requesting: http://10.0.0.129:8082/instance-ip/406b56c2-fb82-4dca-9e95-df1b6bbef149 2025-10-08 13:48:01,339 - DEBUG - Verifying in api server 10.0.0.129 2025-10-08 13:48:01,339 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine/153268f8-3b5c-45da-9e2b-5f907a2b29c9 2025-10-08 13:48:01,346 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine-interface/78682a7d-3a68-4ffb-9b1d-0ad9d225f32a 2025-10-08 13:48:01,355 - DEBUG - Requesting: http://10.0.0.129:8082/instance-ip/0793eb9c-057f-4b11-9528-4e28e79fbd71 2025-10-08 13:48:01,362 - DEBUG - Requesting: http://10.0.0.129:8082/instance-ip/406b56c2-fb82-4dca-9e95-df1b6bbef149 2025-10-08 13:48:01,370 - INFO - VM ctest-VM21-02067792 verfication in all API Servers passed 2025-10-08 13:48:01,397 - DEBUG - VM ctest-VM21-02067792 Tap interface: {'index': '3', 'name': 'tap78682a7d-3a', 'uuid': '78682a7d-3a68-4ffb-9b1d-0ad9d225f32a', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN2-29274359:ctest-VN2-29274359', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Active', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Enable', 'dns_service': 'Enable', 'type': 'vport', 'label': '25', 'l2_label': '29', 'vxlan_id': '15', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN2-29274359', 'vm_uuid': '153268f8-3b5c-45da-9e2b-5f907a2b29c9', 'vm_name': 'ctest-VM21-02067792', 'ip_addr': '30.182.164.67', 'mac_addr': '02:78:68:2a:7d:3a', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.3', 'service_vlan_list': None, 'os_ifindex': '44', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestPolicyAclIpv4v6-85636480:78682a7d-3a68-4ffb-9b1d-0ad9d225f32a', 'sg_uuid_list': ['a1d2a8df-25ad-4ff6-9bac-1b729df06b9b'], 'static_route_list': None, 'vm_project_uuid': '0352087d-1036-4b51-8231-7489e95cfc8b', 'admin_state': 'Enabled', 'flow_key_idx': '31', 'allowed_address_pair_list': None, 'ip6_addr': '2413:8e07:cdfc:a9fe:cedc:3304:0:3', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []} 2025-10-08 13:48:01,397 - DEBUG - Agent 10.0.0.130 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN2-29274359:ctest-VN2-29274359 2025-10-08 13:48:01,405 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN2-29274359:ctest-VN2-29274359', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN2-29274359', 'table_label': '-1', 'vxlan_id': '15', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.193:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2025-10-08 13:48:01,498 - DEBUG - VM ctest-VM21-02067792 : Tap interface tap78682a7d-3a is set to Active 2025-10-08 13:48:01,498 - DEBUG - Tap interface tap78682a7d-3a detail : {'index': '3', 'name': 'tap78682a7d-3a', 'uuid': '78682a7d-3a68-4ffb-9b1d-0ad9d225f32a', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN2-29274359:ctest-VN2-29274359', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Active', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Enable', 'dns_service': 'Enable', 'type': 'vport', 'label': '25', 'l2_label': '29', 'vxlan_id': '15', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN2-29274359', 'vm_uuid': '153268f8-3b5c-45da-9e2b-5f907a2b29c9', 'vm_name': 'ctest-VM21-02067792', 'ip_addr': '30.182.164.67', 'mac_addr': '02:78:68:2a:7d:3a', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.3', 'service_vlan_list': None, 'os_ifindex': '44', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestPolicyAclIpv4v6-85636480:78682a7d-3a68-4ffb-9b1d-0ad9d225f32a', 'sg_uuid_list': ['a1d2a8df-25ad-4ff6-9bac-1b729df06b9b'], 'static_route_list': None, 'vm_project_uuid': '0352087d-1036-4b51-8231-7489e95cfc8b', 'admin_state': 'Enabled', 'flow_key_idx': '31', 'allowed_address_pair_list': None, 'ip6_addr': '2413:8e07:cdfc:a9fe:cedc:3304:0:3', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []} 2025-10-08 13:48:01,617 - DEBUG - Starting Layer 2 verification in Agent 2025-10-08 13:48:01,630 - DEBUG - Layer 2 path is seen for VM MAC 02:78:68:2a:7d:3a in agent 10.0.0.130 2025-10-08 13:48:01,630 - DEBUG - Active layer 2 route in agent is present for VMI tap78682a7d-3a 2025-10-08 13:48:01,630 - DEBUG - L2 label(29) matches bw route table and interface table 2025-10-08 13:48:01,637 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN2-29274359 is found in Agent of node 10.0.0.130 2025-10-08 13:48:01,803 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN2-29274359 is consistent in agent 10.0.0.130 2025-10-08 13:48:01,803 - DEBUG - Route for VM IP ['30.182.164.67', '2413:8e07:cdfc:a9fe:cedc:3304:0:3'] is consistent in agent 10.0.0.130 2025-10-08 13:48:01,803 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN2-29274359 verification for VM ctest-VM21-02067792 in Agent 10.0.0.130 passed 2025-10-08 13:48:01,918 - DEBUG - Starting all layer 2 verification in agent 10.0.0.130 2025-10-08 13:48:01,931 - DEBUG - Route for VM MAC 02:78:68:2a:7d:3a is consistent in agent 10.0.0.130 2025-10-08 13:48:03,120 - 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=7.66 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.04 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 = 1.036/4.345/7.655/3.309 ms') 2025-10-08 13:48:03,120 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-02067792 passed 2025-10-08 13:48:03,120 - INFO - VM ctest-VM21-02067792 verifications in Compute nodes passed 2025-10-08 13:48:03,403 - DEBUG - Validated VM route 30.182.164.67 in vrouter of 10.0.0.130 2025-10-08 13:48:03,414 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0 2025-10-08 13:48:03,414 - INFO - Validated routes of VM ctest-VM21-02067792 in all vrouters 2025-10-08 13:48:03,867 - DEBUG - Starting all layer2 verification in 10.0.0.129 Control Node 2025-10-08 13:48:03,996 - DEBUG - Layer2 route found for VM MAC 02:78:68:2a:7d:3a in Control-node 10.0.0.129 2025-10-08 13:48:03,997 - DEBUG - L2 Label for VM ctest-VM21-02067792 same between Control-node 10.0.0.129 and Agent, Expected: 29, Seen: 29 2025-10-08 13:48:04,114 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.129, not supported 2025-10-08 13:48:04,114 - DEBUG - Starting all layer2 verification in 10.0.0.19 Control Node 2025-10-08 13:48:04,251 - DEBUG - Layer2 route found for VM MAC 02:78:68:2a:7d:3a in Control-node 10.0.0.19 2025-10-08 13:48:04,252 - DEBUG - L2 Label for VM ctest-VM21-02067792 same between Control-node 10.0.0.19 and Agent, Expected: 29, Seen: 29 2025-10-08 13:48:04,377 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.19, not supported 2025-10-08 13:48:04,377 - INFO - Verification in Control-nodes for VM ctest-VM21-02067792 passed 2025-10-08 13:48:04,377 - DEBUG - Verifying the vm in opserver 2025-10-08 13:48:04,377 - DEBUG - Verifying in collector 10.0.0.129 ... 2025-10-08 13:48:04,377 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-machine/153268f8-3b5c-45da-9e2b-5f907a2b29c9?flat 2025-10-08 13:48:04,393 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-85636480:78682a7d-3a68-4ffb-9b1d-0ad9d225f32a?flat 2025-10-08 13:48:04,411 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-85636480:78682a7d-3a68-4ffb-9b1d-0ad9d225f32a?flat 2025-10-08 13:48:04,428 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-85636480:78682a7d-3a68-4ffb-9b1d-0ad9d225f32a?flat 2025-10-08 13:48:04,446 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-machine/153268f8-3b5c-45da-9e2b-5f907a2b29c9?flat 2025-10-08 13:48:04,461 - DEBUG - Verifying vm in vn uve 2025-10-08 13:48:04,461 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-85636480:78682a7d-3a68-4ffb-9b1d-0ad9d225f32a?flat 2025-10-08 13:48:04,492 - DEBUG - VM uve shows interface as {'is_health_check_active': True, 'admin_state': True, 'l2_active': True, 'ip4_active': True, 'vm_name': 'ctest-VM21-02067792', 'fixed_ip6_list': ['2413:8e07:cdfc:a9fe:cedc:3304:0:3'], 'label': 25, 'fixed_ip4_list': ['30.182.164.67'], 'ip6_active': True, 'port_mirror_enabled': False, 'vhostuser_mode': 0, 'ip_address': '30.182.164.67', 'mac_address': '02:78:68:2a:7d:3a', 'vm_uuid': '153268f8-3b5c-45da-9e2b-5f907a2b29c9', 'rx_vlan': 65535, 'gateway': '30.182.164.65', 'active': True, 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN2-29274359', 'vn_uuid': '34a41372-63d3-4855-bf37-dd511381f83d', 'uuid': '78682a7d-3a68-4ffb-9b1d-0ad9d225f32a', '__T': 1759931232906125, 'tx_vlan': 65535, 'ip6_address': '2413:8e07:cdfc:a9fe:cedc:3304:0:3'} 2025-10-08 13:48:04,492 - DEBUG - VM uve shows ip address as ['30.182.164.67', '2413:8e07:cdfc:a9fe:cedc:3304:0:3'] 2025-10-08 13:48:04,492 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN2-29274359 2025-10-08 13:48:04,492 - INFO - Verifying through opserver in 10.0.0.33 2025-10-08 13:48:04,492 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN2-29274359?flat 2025-10-08 13:48:04,513 - DEBUG - expected vm list 153268f8-3b5c-45da-9e2b-5f907a2b29c9 2025-10-08 13:48:04,513 - DEBUG - Extracted vm list ['153268f8-3b5c-45da-9e2b-5f907a2b29c9'] 2025-10-08 13:48:04,513 - INFO - VM 153268f8-3b5c-45da-9e2b-5f907a2b29c9 is present in default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN2-29274359 2025-10-08 13:48:04,513 - DEBUG - Verifying vm in vrouter uve 2025-10-08 13:48:04,513 - DEBUG - Getting info from collector 10.0.0.129.. 2025-10-08 13:48:04,513 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-machine/153268f8-3b5c-45da-9e2b-5f907a2b29c9?flat 2025-10-08 13:48:04,528 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-4234-1'] 2025-10-08 13:48:04,529 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4234-1:Compute:contrail-vrouter-agent:0?flat 2025-10-08 13:48:04,571 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4234-1?flat 2025-10-08 13:48:04,620 - DEBUG - VM 153268f8-3b5c-45da-9e2b-5f907a2b29c9 is present in vrouter an-jenkins-deploy-platform-ansible-os-4234-1 uve 2025-10-08 13:48:04,620 - DEBUG - Validated that VM ctest-VM21-02067792 is in Vrouter an-jenkins-deploy-platform-ansible-os-4234-1 UVE 2025-10-08 13:48:04,620 - DEBUG - Verifying vm tap interface/vn in vrouter uve 2025-10-08 13:48:04,630 - DEBUG - Expected tap interface of VM uuid 153268f8-3b5c-45da-9e2b-5f907a2b29c9 is default-domain:ctest-TestPolicyAclIpv4v6-85636480:78682a7d-3a68-4ffb-9b1d-0ad9d225f32a 2025-10-08 13:48:04,630 - DEBUG - Expected VN of VM uuid 153268f8-3b5c-45da-9e2b-5f907a2b29c9 is default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN2-29274359 2025-10-08 13:48:04,630 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4234-1:Compute:contrail-vrouter-agent:0?flat 2025-10-08 13:48:04,674 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4234-1?flat 2025-10-08 13:48:04,701 - DEBUG - VM 153268f8-3b5c-45da-9e2b-5f907a2b29c9 is present in vrouter an-jenkins-deploy-platform-ansible-os-4234-1 uve 2025-10-08 13:48:04,701 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-85636480:78682a7d-3a68-4ffb-9b1d-0ad9d225f32a of vm 153268f8-3b5c-45da-9e2b-5f907a2b29c9 is present in vrouter an-jenkins-deploy-platform-ansible-os-4234-1 uve 2025-10-08 13:48:04,701 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN2-29274359 of vm 153268f8-3b5c-45da-9e2b-5f907a2b29c9 is present in vrouter an-jenkins-deploy-platform-ansible-os-4234-1 uve 2025-10-08 13:48:04,701 - DEBUG - Verifying the 153268f8-3b5c-45da-9e2b-5f907a2b29c9 virtual network link through opserver 10.0.0.33 2025-10-08 13:48:04,701 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machines 2025-10-08 13:48:04,714 - DEBUG - VM link and name as {'name': '153268f8-3b5c-45da-9e2b-5f907a2b29c9', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-machine/153268f8-3b5c-45da-9e2b-5f907a2b29c9?flat'} 2025-10-08 13:48:04,714 - DEBUG - Verifying the 153268f8-3b5c-45da-9e2b-5f907a2b29c9 virtual network link through opserver 10.0.0.19 2025-10-08 13:48:04,714 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machines 2025-10-08 13:48:04,727 - DEBUG - VM link and name as {'name': '153268f8-3b5c-45da-9e2b-5f907a2b29c9', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-machine/153268f8-3b5c-45da-9e2b-5f907a2b29c9?flat'} 2025-10-08 13:48:04,727 - DEBUG - Verifying the 153268f8-3b5c-45da-9e2b-5f907a2b29c9 virtual network link through opserver 10.0.0.129 2025-10-08 13:48:04,727 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-machines 2025-10-08 13:48:04,740 - DEBUG - VM link and name as {'name': '153268f8-3b5c-45da-9e2b-5f907a2b29c9', 'href': 'http://10.0.0.129:8081/analytics/uves/virtual-machine/153268f8-3b5c-45da-9e2b-5f907a2b29c9?flat'} 2025-10-08 13:48:04,740 - INFO - VM ctest-VM21-02067792 validations in Opserver passed 2025-10-08 13:48:04,830 - DEBUG - VM is in ACTIVE state now 2025-10-08 13:48:04,830 - INFO - VM name : ctest-VM31-74759815 2025-10-08 13:48:04,925 - DEBUG - VM ctest-VM31-74759815 ID is 5355a5dc-7331-4eb9-b1ba-95f11c7bb780 2025-10-08 13:48:05,007 - DEBUG - VM is in ACTIVE state now 2025-10-08 13:48:05,007 - DEBUG - VM ctest-VM31-74759815 launched on Node an-jenkins-deploy-platform-ansible-os-4234-2 2025-10-08 13:48:05,082 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine/5355a5dc-7331-4eb9-b1ba-95f11c7bb780 2025-10-08 13:48:05,092 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine/5355a5dc-7331-4eb9-b1ba-95f11c7bb780 2025-10-08 13:48:05,100 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine-interface/e158f503-d6f1-4b32-a901-61f3e4ecced9 2025-10-08 13:48:05,109 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine/5355a5dc-7331-4eb9-b1ba-95f11c7bb780 2025-10-08 13:48:05,117 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine-interface/e158f503-d6f1-4b32-a901-61f3e4ecced9 2025-10-08 13:48:05,128 - DEBUG - Requesting: http://10.0.0.129:8082/instance-ip/597e02d2-32f6-404f-82b1-95e5990dbc2f 2025-10-08 13:48:05,139 - DEBUG - Requesting: http://10.0.0.129:8082/instance-ip/5ce1b764-e27d-44b2-9504-1612c882d42c 2025-10-08 13:48:05,150 - DEBUG - Verifying in api server 10.0.0.129 2025-10-08 13:48:05,150 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine/5355a5dc-7331-4eb9-b1ba-95f11c7bb780 2025-10-08 13:48:05,159 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine-interface/e158f503-d6f1-4b32-a901-61f3e4ecced9 2025-10-08 13:48:05,181 - DEBUG - Requesting: http://10.0.0.129:8082/instance-ip/597e02d2-32f6-404f-82b1-95e5990dbc2f 2025-10-08 13:48:05,197 - DEBUG - Requesting: http://10.0.0.129:8082/instance-ip/5ce1b764-e27d-44b2-9504-1612c882d42c 2025-10-08 13:48:05,208 - INFO - VM ctest-VM31-74759815 verfication in all API Servers passed 2025-10-08 13:48:05,231 - DEBUG - VM ctest-VM31-74759815 Tap interface: {'index': '4', 'name': 'tape158f503-d6', 'uuid': 'e158f503-d6f1-4b32-a901-61f3e4ecced9', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN3-51664314:ctest-VN3-51664314', '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': '16', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN3-51664314', 'vm_uuid': '5355a5dc-7331-4eb9-b1ba-95f11c7bb780', 'vm_name': 'ctest-VM31-74759815', 'ip_addr': '145.235.220.67', 'mac_addr': '02:e1:58:f5:03:d6', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.4', 'service_vlan_list': None, 'os_ifindex': '51', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestPolicyAclIpv4v6-85636480:e158f503-d6f1-4b32-a901-61f3e4ecced9', 'sg_uuid_list': ['a1d2a8df-25ad-4ff6-9bac-1b729df06b9b'], 'static_route_list': None, 'vm_project_uuid': '0352087d-1036-4b51-8231-7489e95cfc8b', 'admin_state': 'Enabled', 'flow_key_idx': '39', 'allowed_address_pair_list': None, 'ip6_addr': '222d:4c3:fb84:306b:c7c4:d60a:0:3', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []} 2025-10-08 13:48:05,231 - DEBUG - Agent 10.0.0.131 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN3-51664314:ctest-VN3-51664314 2025-10-08 13:48:05,241 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN3-51664314:ctest-VN3-51664314', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN3-51664314', 'table_label': '-1', 'vxlan_id': '16', 'evpnindex': '3', 'brindex': '3', 'mplsindex': '0', 'RD': '10.20.0.129:3', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2025-10-08 13:48:05,332 - DEBUG - VM ctest-VM31-74759815 : Tap interface tape158f503-d6 is set to Active 2025-10-08 13:48:05,332 - DEBUG - Tap interface tape158f503-d6 detail : {'index': '4', 'name': 'tape158f503-d6', 'uuid': 'e158f503-d6f1-4b32-a901-61f3e4ecced9', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN3-51664314:ctest-VN3-51664314', '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': '16', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN3-51664314', 'vm_uuid': '5355a5dc-7331-4eb9-b1ba-95f11c7bb780', 'vm_name': 'ctest-VM31-74759815', 'ip_addr': '145.235.220.67', 'mac_addr': '02:e1:58:f5:03:d6', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.4', 'service_vlan_list': None, 'os_ifindex': '51', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestPolicyAclIpv4v6-85636480:e158f503-d6f1-4b32-a901-61f3e4ecced9', 'sg_uuid_list': ['a1d2a8df-25ad-4ff6-9bac-1b729df06b9b'], 'static_route_list': None, 'vm_project_uuid': '0352087d-1036-4b51-8231-7489e95cfc8b', 'admin_state': 'Enabled', 'flow_key_idx': '39', 'allowed_address_pair_list': None, 'ip6_addr': '222d:4c3:fb84:306b:c7c4:d60a:0:3', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []} 2025-10-08 13:48:05,443 - DEBUG - Starting Layer 2 verification in Agent 2025-10-08 13:48:05,452 - DEBUG - Layer 2 path is seen for VM MAC 02:e1:58:f5:03:d6 in agent 10.0.0.131 2025-10-08 13:48:05,452 - DEBUG - Active layer 2 route in agent is present for VMI tape158f503-d6 2025-10-08 13:48:05,452 - DEBUG - L2 label(36) matches bw route table and interface table 2025-10-08 13:48:05,471 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN3-51664314 is found in Agent of node 10.0.0.131 2025-10-08 13:48:05,589 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN3-51664314 is consistent in agent 10.0.0.131 2025-10-08 13:48:05,589 - DEBUG - Route for VM IP ['145.235.220.67', '222d:4c3:fb84:306b:c7c4:d60a:0:3'] is consistent in agent 10.0.0.131 2025-10-08 13:48:05,589 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN3-51664314 verification for VM ctest-VM31-74759815 in Agent 10.0.0.131 passed 2025-10-08 13:48:05,703 - DEBUG - Starting all layer 2 verification in agent 10.0.0.131 2025-10-08 13:48:05,748 - DEBUG - Route for VM MAC 02:e1:58:f5:03:d6 is consistent in agent 10.0.0.131 2025-10-08 13:48:08,889 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1012ms') 2025-10-08 13:48:08,889 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-74759815 failed! 2025-10-08 13:48:08,905 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN3-51664314 is 145.235.220.65 and allocation pool is NOT set 2025-10-08 13:48:08,905 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN3-51664314 is 222d:4c3:fb84:306b:c7c4:d60a:0:1 and allocation pool is NOT set 2025-10-08 13:48:10,963 - 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=5.96 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=1.20 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 1.202/3.582/5.962/2.380 ms') 2025-10-08 13:48:10,963 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-74759815 passed 2025-10-08 13:48:10,964 - INFO - VM ctest-VM31-74759815 verifications in Compute nodes passed 2025-10-08 13:48:11,186 - DEBUG - Validated VM route 145.235.220.67 in vrouter of 10.0.0.131 2025-10-08 13:48:11,190 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0 2025-10-08 13:48:11,190 - INFO - Validated routes of VM ctest-VM31-74759815 in all vrouters 2025-10-08 13:48:11,690 - DEBUG - Starting all layer2 verification in 10.0.0.129 Control Node 2025-10-08 13:48:11,824 - DEBUG - Layer2 route found for VM MAC 02:e1:58:f5:03:d6 in Control-node 10.0.0.129 2025-10-08 13:48:11,824 - DEBUG - L2 Label for VM ctest-VM31-74759815 same between Control-node 10.0.0.129 and Agent, Expected: 36, Seen: 36 2025-10-08 13:48:11,940 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.129, not supported 2025-10-08 13:48:11,940 - DEBUG - Starting all layer2 verification in 10.0.0.19 Control Node 2025-10-08 13:48:12,065 - DEBUG - Layer2 route found for VM MAC 02:e1:58:f5:03:d6 in Control-node 10.0.0.19 2025-10-08 13:48:12,065 - DEBUG - L2 Label for VM ctest-VM31-74759815 same between Control-node 10.0.0.19 and Agent, Expected: 36, Seen: 36 2025-10-08 13:48:12,183 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.19, not supported 2025-10-08 13:48:12,183 - INFO - Verification in Control-nodes for VM ctest-VM31-74759815 passed 2025-10-08 13:48:12,183 - DEBUG - Verifying the vm in opserver 2025-10-08 13:48:12,183 - DEBUG - Verifying in collector 10.0.0.129 ... 2025-10-08 13:48:12,183 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-machine/5355a5dc-7331-4eb9-b1ba-95f11c7bb780?flat 2025-10-08 13:48:12,199 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-85636480:e158f503-d6f1-4b32-a901-61f3e4ecced9?flat 2025-10-08 13:48:12,222 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-85636480:e158f503-d6f1-4b32-a901-61f3e4ecced9?flat 2025-10-08 13:48:12,249 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-85636480:e158f503-d6f1-4b32-a901-61f3e4ecced9?flat 2025-10-08 13:48:12,273 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-machine/5355a5dc-7331-4eb9-b1ba-95f11c7bb780?flat 2025-10-08 13:48:12,289 - DEBUG - Verifying vm in vn uve 2025-10-08 13:48:12,289 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-85636480:e158f503-d6f1-4b32-a901-61f3e4ecced9?flat 2025-10-08 13:48:12,310 - DEBUG - VM uve shows interface as {'is_health_check_active': True, 'admin_state': True, 'l2_active': True, 'ip4_active': True, 'vm_name': 'ctest-VM31-74759815', 'fixed_ip6_list': ['222d:4c3:fb84:306b:c7c4:d60a:0:3'], 'label': 32, 'fixed_ip4_list': ['145.235.220.67'], 'ip6_active': True, 'port_mirror_enabled': False, 'vhostuser_mode': 0, 'ip_address': '145.235.220.67', 'mac_address': '02:e1:58:f5:03:d6', 'vm_uuid': '5355a5dc-7331-4eb9-b1ba-95f11c7bb780', 'rx_vlan': 65535, 'gateway': '145.235.220.65', 'active': True, 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN3-51664314', 'vn_uuid': '4065ed29-f950-49a5-8d40-da9ebe8726a8', 'uuid': 'e158f503-d6f1-4b32-a901-61f3e4ecced9', '__T': 1759931233315049, 'tx_vlan': 65535, 'ip6_address': '222d:4c3:fb84:306b:c7c4:d60a:0:3'} 2025-10-08 13:48:12,310 - DEBUG - VM uve shows ip address as ['145.235.220.67', '222d:4c3:fb84:306b:c7c4:d60a:0:3'] 2025-10-08 13:48:12,310 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN3-51664314 2025-10-08 13:48:12,310 - INFO - Verifying through opserver in 10.0.0.33 2025-10-08 13:48:12,310 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN3-51664314?flat 2025-10-08 13:48:12,332 - DEBUG - expected vm list 5355a5dc-7331-4eb9-b1ba-95f11c7bb780 2025-10-08 13:48:12,332 - DEBUG - Extracted vm list ['5355a5dc-7331-4eb9-b1ba-95f11c7bb780'] 2025-10-08 13:48:12,332 - INFO - VM 5355a5dc-7331-4eb9-b1ba-95f11c7bb780 is present in default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN3-51664314 2025-10-08 13:48:12,332 - DEBUG - Verifying vm in vrouter uve 2025-10-08 13:48:12,332 - DEBUG - Getting info from collector 10.0.0.129.. 2025-10-08 13:48:12,332 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-machine/5355a5dc-7331-4eb9-b1ba-95f11c7bb780?flat 2025-10-08 13:48:12,348 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-4234-2'] 2025-10-08 13:48:12,348 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4234-2:Compute:contrail-vrouter-agent:0?flat 2025-10-08 13:48:12,391 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4234-2?flat 2025-10-08 13:48:12,423 - DEBUG - VM 5355a5dc-7331-4eb9-b1ba-95f11c7bb780 is present in vrouter an-jenkins-deploy-platform-ansible-os-4234-2 uve 2025-10-08 13:48:12,423 - DEBUG - Validated that VM ctest-VM31-74759815 is in Vrouter an-jenkins-deploy-platform-ansible-os-4234-2 UVE 2025-10-08 13:48:12,423 - DEBUG - Verifying vm tap interface/vn in vrouter uve 2025-10-08 13:48:12,429 - DEBUG - Expected tap interface of VM uuid 5355a5dc-7331-4eb9-b1ba-95f11c7bb780 is default-domain:ctest-TestPolicyAclIpv4v6-85636480:e158f503-d6f1-4b32-a901-61f3e4ecced9 2025-10-08 13:48:12,429 - DEBUG - Expected VN of VM uuid 5355a5dc-7331-4eb9-b1ba-95f11c7bb780 is default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN3-51664314 2025-10-08 13:48:12,429 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4234-2:Compute:contrail-vrouter-agent:0?flat 2025-10-08 13:48:12,466 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4234-2?flat 2025-10-08 13:48:12,494 - DEBUG - VM 5355a5dc-7331-4eb9-b1ba-95f11c7bb780 is present in vrouter an-jenkins-deploy-platform-ansible-os-4234-2 uve 2025-10-08 13:48:12,494 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-85636480:e158f503-d6f1-4b32-a901-61f3e4ecced9 of vm 5355a5dc-7331-4eb9-b1ba-95f11c7bb780 is present in vrouter an-jenkins-deploy-platform-ansible-os-4234-2 uve 2025-10-08 13:48:12,494 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN3-51664314 of vm 5355a5dc-7331-4eb9-b1ba-95f11c7bb780 is present in vrouter an-jenkins-deploy-platform-ansible-os-4234-2 uve 2025-10-08 13:48:12,494 - DEBUG - Verifying the 5355a5dc-7331-4eb9-b1ba-95f11c7bb780 virtual network link through opserver 10.0.0.33 2025-10-08 13:48:12,495 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machines 2025-10-08 13:48:12,508 - DEBUG - VM link and name as {'name': '5355a5dc-7331-4eb9-b1ba-95f11c7bb780', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-machine/5355a5dc-7331-4eb9-b1ba-95f11c7bb780?flat'} 2025-10-08 13:48:12,508 - DEBUG - Verifying the 5355a5dc-7331-4eb9-b1ba-95f11c7bb780 virtual network link through opserver 10.0.0.19 2025-10-08 13:48:12,508 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machines 2025-10-08 13:48:12,520 - DEBUG - VM link and name as {'name': '5355a5dc-7331-4eb9-b1ba-95f11c7bb780', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-machine/5355a5dc-7331-4eb9-b1ba-95f11c7bb780?flat'} 2025-10-08 13:48:12,520 - DEBUG - Verifying the 5355a5dc-7331-4eb9-b1ba-95f11c7bb780 virtual network link through opserver 10.0.0.129 2025-10-08 13:48:12,520 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-machines 2025-10-08 13:48:12,532 - DEBUG - VM link and name as {'name': '5355a5dc-7331-4eb9-b1ba-95f11c7bb780', 'href': 'http://10.0.0.129:8081/analytics/uves/virtual-machine/5355a5dc-7331-4eb9-b1ba-95f11c7bb780?flat'} 2025-10-08 13:48:12,532 - INFO - VM ctest-VM31-74759815 validations in Opserver passed 2025-10-08 13:48:12,533 - INFO - Waiting for VM ctest-VM11-78413541 to be up.. 2025-10-08 13:48:12,533 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-08 13:48:12,533 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine/611688e2-f519-4b55-8bce-366e33060015 2025-10-08 13:48:12,542 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine-interface/b0f71d43-dbbb-4255-9f43-45c5d57ac026 2025-10-08 13:48:13,720 - 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.58 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.14 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 = 1.144/3.864/6.584/2.720 ms') 2025-10-08 13:48:13,721 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-78413541 passed 2025-10-08 13:48:13,791 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-08 13:48:13,791 - DEBUG - Waiting to SSH to VM ctest-VM11-78413541, IP 195.24.39.131, Port 22 2025-10-08 13:48:13,855 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2025-10-08 13:48:13,928 - DEBUG - VM ctest-VM11-78413541 is NOT ready for SSH connections, VM status: ACTIVE 2025-10-08 13:48:18,929 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-08 13:48:18,929 - DEBUG - Waiting to SSH to VM ctest-VM11-78413541, IP 195.24.39.131, Port 22 2025-10-08 13:48:18,999 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2025-10-08 13:48:19,076 - DEBUG - VM ctest-VM11-78413541 is NOT ready for SSH connections, VM status: ACTIVE 2025-10-08 13:48:24,077 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-08 13:48:24,077 - DEBUG - Waiting to SSH to VM ctest-VM11-78413541, IP 195.24.39.131, Port 22 2025-10-08 13:48:24,243 - DEBUG - VM ctest-VM11-78413541 is ready for SSH connections 2025-10-08 13:48:24,244 - INFO - Waiting for VM ctest-VM21-02067792 to be up.. 2025-10-08 13:48:24,244 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-08 13:48:24,244 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine/153268f8-3b5c-45da-9e2b-5f907a2b29c9 2025-10-08 13:48:24,254 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine-interface/78682a7d-3a68-4ffb-9b1d-0ad9d225f32a 2025-10-08 13:48:25,420 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=5.59 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.784 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.784/3.184/5.585/2.400 ms') 2025-10-08 13:48:25,420 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-02067792 passed 2025-10-08 13:48:25,496 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-08 13:48:25,497 - DEBUG - Waiting to SSH to VM ctest-VM21-02067792, IP 30.182.164.67, Port 22 2025-10-08 13:48:25,668 - DEBUG - VM ctest-VM21-02067792 is ready for SSH connections 2025-10-08 13:48:25,668 - INFO - Waiting for VM ctest-VM31-74759815 to be up.. 2025-10-08 13:48:25,668 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-08 13:48:25,668 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine/5355a5dc-7331-4eb9-b1ba-95f11c7bb780 2025-10-08 13:48:25,676 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine-interface/e158f503-d6f1-4b32-a901-61f3e4ecced9 2025-10-08 13:48:26,832 - 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=1.93 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=1.65 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 1.649/1.788/1.928/0.139 ms') 2025-10-08 13:48:26,833 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-74759815 passed 2025-10-08 13:48:26,936 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-08 13:48:26,936 - DEBUG - Waiting to SSH to VM ctest-VM31-74759815, IP 145.235.220.67, Port 22 2025-10-08 13:48:27,003 - 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': ''} 2025-10-08 13:48:27,087 - DEBUG - VM ctest-VM31-74759815 is NOT ready for SSH connections, VM status: ACTIVE 2025-10-08 13:48:32,088 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-08 13:48:32,088 - DEBUG - Waiting to SSH to VM ctest-VM31-74759815, IP 145.235.220.67, Port 22 2025-10-08 13:48:32,160 - 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': ''} 2025-10-08 13:48:32,236 - DEBUG - VM ctest-VM31-74759815 is NOT ready for SSH connections, VM status: ACTIVE 2025-10-08 13:48:37,237 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-08 13:48:37,237 - DEBUG - Waiting to SSH to VM ctest-VM31-74759815, IP 145.235.220.67, Port 22 2025-10-08 13:48:37,403 - DEBUG - VM ctest-VM31-74759815 is ready for SSH connections 2025-10-08 13:48:37,404 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 30.182.164.67, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.131, gateway password: c0ntrail123 2025-10-08 13:48:37,404 - DEBUG - ping -s 56 -c 3 -W 1 30.182.164.67 2025-10-08 13:48:51,405 - DEBUG - PING 30.182.164.67 (30.182.164.67) 56(84) bytes of data. 64 bytes from 30.182.164.67: icmp_req=1 ttl=63 time=10.1 ms 64 bytes from 30.182.164.67: icmp_req=2 ttl=63 time=2.22 ms 64 bytes from 30.182.164.67: icmp_req=3 ttl=63 time=1.71 ms --- 30.182.164.67 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2007ms rtt min/avg/max/mdev = 1.716/4.687/10.125/3.850 ms 2025-10-08 13:48:51,405 - INFO - Ping to IP 30.182.164.67 from VM ctest-VM11-78413541 passed 2025-10-08 13:48:51,405 - DEBUG - Running remote_cmd, Cmd : ping6 -s 56 -c 3 -W 1 2413:8e07:cdfc:a9fe:cedc:3304:0:3, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.131, gateway password: c0ntrail123 2025-10-08 13:48:51,406 - DEBUG - ping6 -s 56 -c 3 -W 1 2413:8e07:cdfc:a9fe:cedc:3304:0:3 2025-10-08 13:48:54,032 - DEBUG - PING 2413:8e07:cdfc:a9fe:cedc:3304:0:3(2413:8e07:cdfc:a9fe:cedc:3304:0:3) 56 data bytes 64 bytes from 2413:8e07:cdfc:a9fe:cedc:3304:0:3: icmp_seq=1 ttl=64 time=9.40 ms 64 bytes from 2413:8e07:cdfc:a9fe:cedc:3304:0:3: icmp_seq=2 ttl=64 time=1.80 ms 64 bytes from 2413:8e07:cdfc:a9fe:cedc:3304:0:3: icmp_seq=3 ttl=64 time=1.50 ms --- 2413:8e07:cdfc:a9fe:cedc:3304:0:3 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2004ms rtt min/avg/max/mdev = 1.505/4.240/9.409/3.657 ms 2025-10-08 13:48:54,032 - INFO - Ping to IP 2413:8e07:cdfc:a9fe:cedc:3304:0:3 from VM ctest-VM11-78413541 passed 2025-10-08 13:48:54,032 - INFO - Test with src as policy and dst as any PASSED 2025-10-08 13:48:54,033 - INFO - Deleting VM ctest-VM31-74759815 2025-10-08 13:48:54,097 - DEBUG - Verifying in api server 10.0.0.129 2025-10-08 13:48:54,097 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine/5355a5dc-7331-4eb9-b1ba-95f11c7bb780 2025-10-08 13:48:54,106 - DEBUG - VM ID 5355a5dc-7331-4eb9-b1ba-95f11c7bb780 of VM ctest-VM31-74759815 is still found in API Server 2025-10-08 13:48:56,107 - DEBUG - Verifying in api server 10.0.0.129 2025-10-08 13:48:56,107 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine/5355a5dc-7331-4eb9-b1ba-95f11c7bb780 2025-10-08 13:48:56,113 - DEBUG - Response Code: 404 2025-10-08 13:48:56,114 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine/5355a5dc-7331-4eb9-b1ba-95f11c7bb780 2025-10-08 13:48:56,119 - DEBUG - Response Code: 404 2025-10-08 13:48:56,119 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine/5355a5dc-7331-4eb9-b1ba-95f11c7bb780 2025-10-08 13:48:56,124 - DEBUG - Response Code: 404 2025-10-08 13:48:56,124 - INFO - VM ctest-VM31-74759815 is fully removed in API-Server 2025-10-08 13:48:56,279 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found 2025-10-08 13:48:56,327 - DEBUG - Validated that vrouter 10.0.0.130 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN3-51664314 2025-10-08 13:48:56,340 - DEBUG - Validated that vrouter 10.0.0.131 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN3-51664314 2025-10-08 13:48:56,340 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN3-51664314 2025-10-08 13:48:56,340 - INFO - VM ctest-VM31-74759815 is removed in Compute, and routes are removed in all compute nodes 2025-10-08 13:48:56,567 - INFO - Routes for VM ctest-VM31-74759815 is removed in all control-nodes 2025-10-08 13:48:56,568 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4234-2:Compute:contrail-vrouter-agent:0?flat 2025-10-08 13:48:56,603 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4234-2?flat 2025-10-08 13:48:56,638 - DEBUG - VM 5355a5dc-7331-4eb9-b1ba-95f11c7bb780 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4234-2 uve 2025-10-08 13:48:56,638 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-08 13:48:56,638 - DEBUG - interface for vm 5355a5dc-7331-4eb9-b1ba-95f11c7bb780 deleted from vrouter uve ... 2025-10-08 13:48:56,638 - DEBUG - Deleted interface not in error interface list ... 2025-10-08 13:48:59,638 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4234-2:Compute:contrail-vrouter-agent:0?flat 2025-10-08 13:48:59,673 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4234-2?flat 2025-10-08 13:48:59,702 - DEBUG - VM 5355a5dc-7331-4eb9-b1ba-95f11c7bb780 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4234-2 uve 2025-10-08 13:48:59,702 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-08 13:48:59,702 - DEBUG - interface for vm 5355a5dc-7331-4eb9-b1ba-95f11c7bb780 deleted from vrouter uve ... 2025-10-08 13:48:59,702 - DEBUG - Deleted interface not in error interface list ... 2025-10-08 13:49:02,704 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4234-2:Compute:contrail-vrouter-agent:0?flat 2025-10-08 13:49:02,741 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4234-2?flat 2025-10-08 13:49:02,772 - DEBUG - VM 5355a5dc-7331-4eb9-b1ba-95f11c7bb780 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4234-2 uve 2025-10-08 13:49:02,773 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-08 13:49:02,773 - DEBUG - interface for vm 5355a5dc-7331-4eb9-b1ba-95f11c7bb780 deleted from vrouter uve ... 2025-10-08 13:49:02,773 - DEBUG - Deleted interface not in error interface list ... 2025-10-08 13:49:05,773 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4234-2:Compute:contrail-vrouter-agent:0?flat 2025-10-08 13:49:05,817 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4234-2?flat 2025-10-08 13:49:05,853 - DEBUG - VM 5355a5dc-7331-4eb9-b1ba-95f11c7bb780 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4234-2 uve 2025-10-08 13:49:05,853 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-08 13:49:05,854 - DEBUG - interface for vm 5355a5dc-7331-4eb9-b1ba-95f11c7bb780 deleted from vrouter uve ... 2025-10-08 13:49:05,854 - DEBUG - Deleted interface not in error interface list ... 2025-10-08 13:49:08,855 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4234-2:Compute:contrail-vrouter-agent:0?flat 2025-10-08 13:49:08,888 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4234-2?flat 2025-10-08 13:49:08,917 - DEBUG - VM 5355a5dc-7331-4eb9-b1ba-95f11c7bb780 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4234-2 uve 2025-10-08 13:49:08,917 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-08 13:49:08,917 - DEBUG - interface for vm 5355a5dc-7331-4eb9-b1ba-95f11c7bb780 deleted from vrouter uve ... 2025-10-08 13:49:08,917 - DEBUG - Deleted interface not in error interface list ... 2025-10-08 13:49:11,918 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4234-2:Compute:contrail-vrouter-agent:0?flat 2025-10-08 13:49:11,959 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4234-2?flat 2025-10-08 13:49:11,986 - DEBUG - VM 5355a5dc-7331-4eb9-b1ba-95f11c7bb780 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4234-2 uve 2025-10-08 13:49:11,987 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-08 13:49:11,987 - DEBUG - interface for vm 5355a5dc-7331-4eb9-b1ba-95f11c7bb780 deleted from vrouter uve ... 2025-10-08 13:49:11,987 - DEBUG - Deleted interface not in error interface list ... 2025-10-08 13:49:14,988 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4234-2:Compute:contrail-vrouter-agent:0?flat 2025-10-08 13:49:15,028 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4234-2?flat 2025-10-08 13:49:15,067 - DEBUG - VM 5355a5dc-7331-4eb9-b1ba-95f11c7bb780 is not present in vrouter an-jenkins-deploy-platform-ansible-os-4234-2 uve 2025-10-08 13:49:15,067 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-08 13:49:15,067 - DEBUG - interface for vm 5355a5dc-7331-4eb9-b1ba-95f11c7bb780 deleted from vrouter uve ... 2025-10-08 13:49:15,067 - DEBUG - Deleted interface not in error interface list ... 2025-10-08 13:49:15,068 - DEBUG - Validated that VM 5355a5dc-7331-4eb9-b1ba-95f11c7bb780 is removed in Vrouter UVE 2025-10-08 13:49:15,068 - DEBUG - Verifying through opserver in 10.0.0.33 2025-10-08 13:49:15,068 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN3-51664314?flat 2025-10-08 13:49:15,092 - DEBUG - 5355a5dc-7331-4eb9-b1ba-95f11c7bb780 BM not in default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN3-51664314 uve 2025-10-08 13:49:15,092 - DEBUG - Validated that VM 5355a5dc-7331-4eb9-b1ba-95f11c7bb780 is not present in the VN UVE 2025-10-08 13:49:15,092 - DEBUG - Verifying the 5355a5dc-7331-4eb9-b1ba-95f11c7bb780 virtual network link through opserver 10.0.0.33 2025-10-08 13:49:15,092 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machines 2025-10-08 13:49:15,106 - INFO - 5355a5dc-7331-4eb9-b1ba-95f11c7bb780 vm uve deleted from opserver 2025-10-08 13:49:15,106 - DEBUG - Verifying the 5355a5dc-7331-4eb9-b1ba-95f11c7bb780 virtual network link through opserver 10.0.0.19 2025-10-08 13:49:15,106 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machines 2025-10-08 13:49:15,123 - INFO - 5355a5dc-7331-4eb9-b1ba-95f11c7bb780 vm uve deleted from opserver 2025-10-08 13:49:15,123 - DEBUG - Verifying the 5355a5dc-7331-4eb9-b1ba-95f11c7bb780 virtual network link through opserver 10.0.0.129 2025-10-08 13:49:15,123 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-machines 2025-10-08 13:49:15,137 - INFO - 5355a5dc-7331-4eb9-b1ba-95f11c7bb780 vm uve deleted from opserver 2025-10-08 13:49:15,137 - INFO - Validated that VM 5355a5dc-7331-4eb9-b1ba-95f11c7bb780 is removed from Opserver 2025-10-08 13:49:15,137 - INFO - Deleting VM ctest-VM21-02067792 2025-10-08 13:49:15,239 - DEBUG - Verifying in api server 10.0.0.129 2025-10-08 13:49:15,239 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine/153268f8-3b5c-45da-9e2b-5f907a2b29c9 2025-10-08 13:49:15,248 - DEBUG - VM ID 153268f8-3b5c-45da-9e2b-5f907a2b29c9 of VM ctest-VM21-02067792 is still found in API Server 2025-10-08 13:49:17,249 - DEBUG - Verifying in api server 10.0.0.129 2025-10-08 13:49:17,249 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine/153268f8-3b5c-45da-9e2b-5f907a2b29c9 2025-10-08 13:49:17,256 - DEBUG - Response Code: 404 2025-10-08 13:49:17,256 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine/153268f8-3b5c-45da-9e2b-5f907a2b29c9 2025-10-08 13:49:17,261 - DEBUG - Response Code: 404 2025-10-08 13:49:17,262 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine/153268f8-3b5c-45da-9e2b-5f907a2b29c9 2025-10-08 13:49:17,267 - DEBUG - Response Code: 404 2025-10-08 13:49:17,268 - INFO - VM ctest-VM21-02067792 is fully removed in API-Server 2025-10-08 13:49:17,419 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found 2025-10-08 13:49:17,461 - DEBUG - Validated that vrouter 10.0.0.130 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN2-29274359 2025-10-08 13:49:17,470 - DEBUG - Validated that vrouter 10.0.0.131 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN2-29274359 2025-10-08 13:49:17,470 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN2-29274359 2025-10-08 13:49:17,470 - INFO - VM ctest-VM21-02067792 is removed in Compute, and routes are removed in all compute nodes 2025-10-08 13:49:17,679 - INFO - Routes for VM ctest-VM21-02067792 is removed in all control-nodes 2025-10-08 13:49:17,679 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4234-1:Compute:contrail-vrouter-agent:0?flat 2025-10-08 13:49:17,717 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4234-1?flat 2025-10-08 13:49:17,750 - DEBUG - VM 153268f8-3b5c-45da-9e2b-5f907a2b29c9 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4234-1 uve 2025-10-08 13:49:17,750 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-08 13:49:17,750 - DEBUG - interface for vm 153268f8-3b5c-45da-9e2b-5f907a2b29c9 deleted from vrouter uve ... 2025-10-08 13:49:17,750 - DEBUG - Deleted interface not in error interface list ... 2025-10-08 13:49:20,751 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4234-1:Compute:contrail-vrouter-agent:0?flat 2025-10-08 13:49:20,786 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4234-1?flat 2025-10-08 13:49:20,818 - DEBUG - VM 153268f8-3b5c-45da-9e2b-5f907a2b29c9 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4234-1 uve 2025-10-08 13:49:20,818 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-08 13:49:20,818 - DEBUG - interface for vm 153268f8-3b5c-45da-9e2b-5f907a2b29c9 deleted from vrouter uve ... 2025-10-08 13:49:20,818 - DEBUG - Deleted interface not in error interface list ... 2025-10-08 13:49:23,819 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4234-1:Compute:contrail-vrouter-agent:0?flat 2025-10-08 13:49:23,855 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4234-1?flat 2025-10-08 13:49:23,887 - DEBUG - VM 153268f8-3b5c-45da-9e2b-5f907a2b29c9 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4234-1 uve 2025-10-08 13:49:23,887 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-08 13:49:23,887 - DEBUG - interface for vm 153268f8-3b5c-45da-9e2b-5f907a2b29c9 deleted from vrouter uve ... 2025-10-08 13:49:23,887 - DEBUG - Deleted interface not in error interface list ... 2025-10-08 13:49:26,888 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4234-1:Compute:contrail-vrouter-agent:0?flat 2025-10-08 13:49:26,930 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4234-1?flat 2025-10-08 13:49:26,964 - DEBUG - VM 153268f8-3b5c-45da-9e2b-5f907a2b29c9 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4234-1 uve 2025-10-08 13:49:26,964 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-08 13:49:26,964 - DEBUG - interface for vm 153268f8-3b5c-45da-9e2b-5f907a2b29c9 deleted from vrouter uve ... 2025-10-08 13:49:26,964 - DEBUG - Deleted interface not in error interface list ... 2025-10-08 13:49:29,965 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4234-1:Compute:contrail-vrouter-agent:0?flat 2025-10-08 13:49:30,001 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4234-1?flat 2025-10-08 13:49:30,034 - DEBUG - VM 153268f8-3b5c-45da-9e2b-5f907a2b29c9 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4234-1 uve 2025-10-08 13:49:30,034 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-08 13:49:30,034 - DEBUG - interface for vm 153268f8-3b5c-45da-9e2b-5f907a2b29c9 deleted from vrouter uve ... 2025-10-08 13:49:30,034 - DEBUG - Deleted interface not in error interface list ... 2025-10-08 13:49:33,035 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4234-1:Compute:contrail-vrouter-agent:0?flat 2025-10-08 13:49:33,068 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4234-1?flat 2025-10-08 13:49:33,096 - DEBUG - VM 153268f8-3b5c-45da-9e2b-5f907a2b29c9 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4234-1 uve 2025-10-08 13:49:33,096 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-08 13:49:33,096 - DEBUG - interface for vm 153268f8-3b5c-45da-9e2b-5f907a2b29c9 deleted from vrouter uve ... 2025-10-08 13:49:33,096 - DEBUG - Deleted interface not in error interface list ... 2025-10-08 13:49:36,097 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4234-1:Compute:contrail-vrouter-agent:0?flat 2025-10-08 13:49:36,135 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4234-1?flat 2025-10-08 13:49:36,167 - DEBUG - VM 153268f8-3b5c-45da-9e2b-5f907a2b29c9 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4234-1 uve 2025-10-08 13:49:36,167 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-08 13:49:36,167 - DEBUG - interface for vm 153268f8-3b5c-45da-9e2b-5f907a2b29c9 deleted from vrouter uve ... 2025-10-08 13:49:36,168 - DEBUG - Deleted interface not in error interface list ... 2025-10-08 13:49:39,168 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4234-1:Compute:contrail-vrouter-agent:0?flat 2025-10-08 13:49:39,206 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4234-1?flat 2025-10-08 13:49:39,243 - DEBUG - VM 153268f8-3b5c-45da-9e2b-5f907a2b29c9 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4234-1 uve 2025-10-08 13:49:39,243 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-08 13:49:39,243 - DEBUG - interface for vm 153268f8-3b5c-45da-9e2b-5f907a2b29c9 deleted from vrouter uve ... 2025-10-08 13:49:39,243 - DEBUG - Deleted interface not in error interface list ... 2025-10-08 13:49:42,243 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4234-1:Compute:contrail-vrouter-agent:0?flat 2025-10-08 13:49:42,282 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4234-1?flat 2025-10-08 13:49:42,320 - DEBUG - VM 153268f8-3b5c-45da-9e2b-5f907a2b29c9 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4234-1 uve 2025-10-08 13:49:42,320 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-08 13:49:42,320 - DEBUG - interface for vm 153268f8-3b5c-45da-9e2b-5f907a2b29c9 deleted from vrouter uve ... 2025-10-08 13:49:42,320 - DEBUG - Deleted interface not in error interface list ... 2025-10-08 13:49:45,321 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4234-1:Compute:contrail-vrouter-agent:0?flat 2025-10-08 13:49:45,364 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4234-1?flat 2025-10-08 13:49:45,399 - DEBUG - VM 153268f8-3b5c-45da-9e2b-5f907a2b29c9 is not present in vrouter an-jenkins-deploy-platform-ansible-os-4234-1 uve 2025-10-08 13:49:45,399 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-08 13:49:45,399 - DEBUG - interface for vm 153268f8-3b5c-45da-9e2b-5f907a2b29c9 deleted from vrouter uve ... 2025-10-08 13:49:45,399 - DEBUG - Deleted interface not in error interface list ... 2025-10-08 13:49:45,399 - DEBUG - Validated that VM 153268f8-3b5c-45da-9e2b-5f907a2b29c9 is removed in Vrouter UVE 2025-10-08 13:49:45,399 - DEBUG - Verifying through opserver in 10.0.0.33 2025-10-08 13:49:45,400 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN2-29274359?flat 2025-10-08 13:49:45,423 - DEBUG - 153268f8-3b5c-45da-9e2b-5f907a2b29c9 BM not in default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN2-29274359 uve 2025-10-08 13:49:45,423 - DEBUG - Validated that VM 153268f8-3b5c-45da-9e2b-5f907a2b29c9 is not present in the VN UVE 2025-10-08 13:49:45,423 - DEBUG - Verifying the 153268f8-3b5c-45da-9e2b-5f907a2b29c9 virtual network link through opserver 10.0.0.33 2025-10-08 13:49:45,423 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machines 2025-10-08 13:49:45,439 - INFO - 153268f8-3b5c-45da-9e2b-5f907a2b29c9 vm uve deleted from opserver 2025-10-08 13:49:45,439 - DEBUG - Verifying the 153268f8-3b5c-45da-9e2b-5f907a2b29c9 virtual network link through opserver 10.0.0.19 2025-10-08 13:49:45,439 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machines 2025-10-08 13:49:45,451 - INFO - 153268f8-3b5c-45da-9e2b-5f907a2b29c9 vm uve deleted from opserver 2025-10-08 13:49:45,451 - DEBUG - Verifying the 153268f8-3b5c-45da-9e2b-5f907a2b29c9 virtual network link through opserver 10.0.0.129 2025-10-08 13:49:45,451 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-machines 2025-10-08 13:49:45,463 - INFO - 153268f8-3b5c-45da-9e2b-5f907a2b29c9 vm uve deleted from opserver 2025-10-08 13:49:45,463 - INFO - Validated that VM 153268f8-3b5c-45da-9e2b-5f907a2b29c9 is removed from Opserver 2025-10-08 13:49:45,463 - INFO - Deleting VM ctest-VM11-78413541 2025-10-08 13:49:45,530 - DEBUG - Verifying in api server 10.0.0.129 2025-10-08 13:49:45,530 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine/611688e2-f519-4b55-8bce-366e33060015 2025-10-08 13:49:45,538 - DEBUG - VM ID 611688e2-f519-4b55-8bce-366e33060015 of VM ctest-VM11-78413541 is still found in API Server 2025-10-08 13:49:47,539 - DEBUG - Verifying in api server 10.0.0.129 2025-10-08 13:49:47,540 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine/611688e2-f519-4b55-8bce-366e33060015 2025-10-08 13:49:47,546 - DEBUG - Response Code: 404 2025-10-08 13:49:47,546 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine/611688e2-f519-4b55-8bce-366e33060015 2025-10-08 13:49:47,551 - DEBUG - Response Code: 404 2025-10-08 13:49:47,551 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine/611688e2-f519-4b55-8bce-366e33060015 2025-10-08 13:49:47,557 - DEBUG - Response Code: 404 2025-10-08 13:49:47,557 - INFO - VM ctest-VM11-78413541 is fully removed in API-Server 2025-10-08 13:49:47,711 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found 2025-10-08 13:49:47,751 - DEBUG - Validated that vrouter 10.0.0.130 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464 2025-10-08 13:49:47,762 - DEBUG - Validated that vrouter 10.0.0.131 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464 2025-10-08 13:49:47,762 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464 2025-10-08 13:49:47,762 - INFO - VM ctest-VM11-78413541 is removed in Compute, and routes are removed in all compute nodes 2025-10-08 13:49:47,955 - INFO - Routes for VM ctest-VM11-78413541 is removed in all control-nodes 2025-10-08 13:49:47,955 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4234-2:Compute:contrail-vrouter-agent:0?flat 2025-10-08 13:49:47,986 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4234-2?flat 2025-10-08 13:49:48,016 - DEBUG - VM 611688e2-f519-4b55-8bce-366e33060015 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4234-2 uve 2025-10-08 13:49:48,017 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-08 13:49:48,017 - DEBUG - interface for vm 611688e2-f519-4b55-8bce-366e33060015 deleted from vrouter uve ... 2025-10-08 13:49:48,017 - DEBUG - Deleted interface not in error interface list ... 2025-10-08 13:49:51,017 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4234-2:Compute:contrail-vrouter-agent:0?flat 2025-10-08 13:49:51,055 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4234-2?flat 2025-10-08 13:49:51,092 - DEBUG - VM 611688e2-f519-4b55-8bce-366e33060015 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4234-2 uve 2025-10-08 13:49:51,092 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-08 13:49:51,092 - DEBUG - interface for vm 611688e2-f519-4b55-8bce-366e33060015 deleted from vrouter uve ... 2025-10-08 13:49:51,092 - DEBUG - Deleted interface not in error interface list ... 2025-10-08 13:49:54,092 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4234-2:Compute:contrail-vrouter-agent:0?flat 2025-10-08 13:49:54,127 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4234-2?flat 2025-10-08 13:49:54,156 - DEBUG - VM 611688e2-f519-4b55-8bce-366e33060015 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4234-2 uve 2025-10-08 13:49:54,156 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-08 13:49:54,156 - DEBUG - interface for vm 611688e2-f519-4b55-8bce-366e33060015 deleted from vrouter uve ... 2025-10-08 13:49:54,156 - DEBUG - Deleted interface not in error interface list ... 2025-10-08 13:49:57,157 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4234-2:Compute:contrail-vrouter-agent:0?flat 2025-10-08 13:49:57,197 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4234-2?flat 2025-10-08 13:49:57,230 - DEBUG - VM 611688e2-f519-4b55-8bce-366e33060015 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4234-2 uve 2025-10-08 13:49:57,230 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-08 13:49:57,230 - DEBUG - interface for vm 611688e2-f519-4b55-8bce-366e33060015 deleted from vrouter uve ... 2025-10-08 13:49:57,230 - DEBUG - Deleted interface not in error interface list ... 2025-10-08 13:50:00,231 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4234-2:Compute:contrail-vrouter-agent:0?flat 2025-10-08 13:50:00,263 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4234-2?flat 2025-10-08 13:50:00,294 - DEBUG - VM 611688e2-f519-4b55-8bce-366e33060015 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4234-2 uve 2025-10-08 13:50:00,294 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-08 13:50:00,294 - DEBUG - interface for vm 611688e2-f519-4b55-8bce-366e33060015 deleted from vrouter uve ... 2025-10-08 13:50:00,294 - DEBUG - Deleted interface not in error interface list ... 2025-10-08 13:50:03,295 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4234-2:Compute:contrail-vrouter-agent:0?flat 2025-10-08 13:50:03,331 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4234-2?flat 2025-10-08 13:50:03,361 - DEBUG - VM 611688e2-f519-4b55-8bce-366e33060015 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4234-2 uve 2025-10-08 13:50:03,361 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-08 13:50:03,361 - DEBUG - interface for vm 611688e2-f519-4b55-8bce-366e33060015 deleted from vrouter uve ... 2025-10-08 13:50:03,361 - DEBUG - Deleted interface not in error interface list ... 2025-10-08 13:50:06,362 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4234-2:Compute:contrail-vrouter-agent:0?flat 2025-10-08 13:50:06,399 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4234-2?flat 2025-10-08 13:50:06,431 - DEBUG - VM 611688e2-f519-4b55-8bce-366e33060015 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4234-2 uve 2025-10-08 13:50:06,432 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-08 13:50:06,432 - DEBUG - interface for vm 611688e2-f519-4b55-8bce-366e33060015 deleted from vrouter uve ... 2025-10-08 13:50:06,432 - DEBUG - Deleted interface not in error interface list ... 2025-10-08 13:50:09,432 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4234-2:Compute:contrail-vrouter-agent:0?flat 2025-10-08 13:50:09,469 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4234-2?flat 2025-10-08 13:50:09,501 - DEBUG - VM 611688e2-f519-4b55-8bce-366e33060015 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4234-2 uve 2025-10-08 13:50:09,501 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-08 13:50:09,501 - DEBUG - interface for vm 611688e2-f519-4b55-8bce-366e33060015 deleted from vrouter uve ... 2025-10-08 13:50:09,501 - DEBUG - Deleted interface not in error interface list ... 2025-10-08 13:50:12,501 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4234-2:Compute:contrail-vrouter-agent:0?flat 2025-10-08 13:50:12,551 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4234-2?flat 2025-10-08 13:50:12,591 - DEBUG - VM 611688e2-f519-4b55-8bce-366e33060015 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4234-2 uve 2025-10-08 13:50:12,591 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-08 13:50:12,591 - DEBUG - interface for vm 611688e2-f519-4b55-8bce-366e33060015 deleted from vrouter uve ... 2025-10-08 13:50:12,591 - DEBUG - Deleted interface not in error interface list ... 2025-10-08 13:50:15,592 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4234-2:Compute:contrail-vrouter-agent:0?flat 2025-10-08 13:50:15,637 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4234-2?flat 2025-10-08 13:50:15,670 - DEBUG - VM 611688e2-f519-4b55-8bce-366e33060015 is not present in vrouter an-jenkins-deploy-platform-ansible-os-4234-2 uve 2025-10-08 13:50:15,670 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-08 13:50:15,670 - DEBUG - interface for vm 611688e2-f519-4b55-8bce-366e33060015 deleted from vrouter uve ... 2025-10-08 13:50:15,670 - DEBUG - Deleted interface not in error interface list ... 2025-10-08 13:50:15,670 - DEBUG - Validated that VM 611688e2-f519-4b55-8bce-366e33060015 is removed in Vrouter UVE 2025-10-08 13:50:15,670 - DEBUG - Verifying through opserver in 10.0.0.33 2025-10-08 13:50:15,670 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464?flat 2025-10-08 13:50:15,690 - DEBUG - 611688e2-f519-4b55-8bce-366e33060015 BM not in default-domain:ctest-TestPolicyAclIpv4v6-85636480:ctest-VN1-34668464 uve 2025-10-08 13:50:15,691 - DEBUG - Validated that VM 611688e2-f519-4b55-8bce-366e33060015 is not present in the VN UVE 2025-10-08 13:50:15,691 - DEBUG - Verifying the 611688e2-f519-4b55-8bce-366e33060015 virtual network link through opserver 10.0.0.33 2025-10-08 13:50:15,691 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machines 2025-10-08 13:50:15,703 - DEBUG - No links retuned for 611688e2-f519-4b55-8bce-366e33060015 2025-10-08 13:50:15,703 - INFO - 611688e2-f519-4b55-8bce-366e33060015 vm uve deleted from opserver 2025-10-08 13:50:15,704 - DEBUG - Verifying the 611688e2-f519-4b55-8bce-366e33060015 virtual network link through opserver 10.0.0.19 2025-10-08 13:50:15,704 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machines 2025-10-08 13:50:15,716 - DEBUG - No links retuned for 611688e2-f519-4b55-8bce-366e33060015 2025-10-08 13:50:15,716 - INFO - 611688e2-f519-4b55-8bce-366e33060015 vm uve deleted from opserver 2025-10-08 13:50:15,716 - DEBUG - Verifying the 611688e2-f519-4b55-8bce-366e33060015 virtual network link through opserver 10.0.0.129 2025-10-08 13:50:15,716 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-machines 2025-10-08 13:50:15,729 - DEBUG - No links retuned for 611688e2-f519-4b55-8bce-366e33060015 2025-10-08 13:50:15,729 - INFO - 611688e2-f519-4b55-8bce-366e33060015 vm uve deleted from opserver 2025-10-08 13:50:15,729 - INFO - Validated that VM 611688e2-f519-4b55-8bce-366e33060015 is removed from Opserver 2025-10-08 13:50:15,729 - DEBUG - Detaching the Policy for VN :ctest-VN2-29274359 2025-10-08 13:50:15,729 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2025-10-08 13:50:15,736 - DEBUG - Requesting: http://10.0.0.33:8082/domain/e655d23d-1695-42cd-a914-8836b4a49568 2025-10-08 13:50:15,749 - DEBUG - Requesting: http://10.0.0.33:8082/project/0352087d-1036-4b51-8231-7489e95cfc8b 2025-10-08 13:50:15,847 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/34a41372-63d3-4855-bf37-dd511381f83d 2025-10-08 13:50:15,862 - DEBUG - Requesting: http://10.0.0.33:8082/network-policy/917b6342-bb20-47fc-b66d-c78c4ab987bc 2025-10-08 13:50:16,038 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-85636480', 'ctest-policy21-95624195']] from ctest-VN2-29274359 2025-10-08 13:50:16,038 - DEBUG - Detaching the Policy for VN :ctest-VN1-34668464 2025-10-08 13:50:16,038 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2025-10-08 13:50:16,050 - DEBUG - Requesting: http://10.0.0.33:8082/domain/e655d23d-1695-42cd-a914-8836b4a49568 2025-10-08 13:50:16,072 - DEBUG - Requesting: http://10.0.0.33:8082/project/0352087d-1036-4b51-8231-7489e95cfc8b 2025-10-08 13:50:16,160 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/1800800f-6fe1-40ca-a94b-8e1ece74e7d5 2025-10-08 13:50:16,176 - DEBUG - Requesting: http://10.0.0.33:8082/network-policy/d6026dc7-3d32-4f39-81ea-5b85f1bade49 2025-10-08 13:50:16,184 - DEBUG - Requesting: http://10.0.0.33:8082/network-policy/99d17477-cb7a-44e5-9da6-73568f5365c3 2025-10-08 13:50:16,358 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-85636480', 'ctest-policy12-42722657'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-85636480', 'ctest-policy13-52627997']] from ctest-VN1-34668464 2025-10-08 13:50:16,398 - INFO - Deleted policy ctest-policy21-95624195 2025-10-08 13:50:16,438 - INFO - Deleted policy ctest-policy12-42722657 2025-10-08 13:50:16,478 - INFO - Deleted policy ctest-policy13-52627997 2025-10-08 13:50:16,478 - INFO - Deleting VN ctest-VN3-51664314 2025-10-08 13:50:16,653 - DEBUG - Response for deleting network () 2025-10-08 13:50:16,654 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/904db1d6-7767-476b-b021-dffa5867d398 2025-10-08 13:50:16,659 - DEBUG - Response Code: 404 2025-10-08 13:50:16,659 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2025-10-08 13:50:16,674 - DEBUG - Requesting: http://10.0.0.33:8082/domain/e655d23d-1695-42cd-a914-8836b4a49568 2025-10-08 13:50:16,690 - DEBUG - Requesting: http://10.0.0.33:8082/project/0352087d-1036-4b51-8231-7489e95cfc8b 2025-10-08 13:50:16,779 - INFO - Validated that VN ctest-VN3-51664314 is not found in API Server 2025-10-08 13:50:16,795 - DEBUG - VN ctest-VN3-51664314 is not present in Agent 10.0.0.130 2025-10-08 13:50:16,805 - DEBUG - VN ctest-VN3-51664314 is not present in Agent 10.0.0.131 2025-10-08 13:50:16,805 - INFO - Validated that VN ctest-VN3-51664314 is not in any agent 2025-10-08 13:50:16,831 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN3-51664314 info 2025-10-08 13:50:16,831 - INFO - Deleting VN ctest-VN2-29274359 2025-10-08 13:50:17,003 - DEBUG - Response for deleting network () 2025-10-08 13:50:17,003 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/b5dcb65f-ae7e-43c2-8e00-cf3c6bf18a82 2025-10-08 13:50:17,009 - DEBUG - Response Code: 404 2025-10-08 13:50:17,009 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2025-10-08 13:50:17,016 - DEBUG - Requesting: http://10.0.0.33:8082/domain/e655d23d-1695-42cd-a914-8836b4a49568 2025-10-08 13:50:17,027 - DEBUG - Requesting: http://10.0.0.33:8082/project/0352087d-1036-4b51-8231-7489e95cfc8b 2025-10-08 13:50:17,128 - INFO - Validated that VN ctest-VN2-29274359 is not found in API Server 2025-10-08 13:50:17,138 - DEBUG - VN ctest-VN2-29274359 is not present in Agent 10.0.0.130 2025-10-08 13:50:17,147 - DEBUG - VN ctest-VN2-29274359 is not present in Agent 10.0.0.131 2025-10-08 13:50:17,147 - INFO - Validated that VN ctest-VN2-29274359 is not in any agent 2025-10-08 13:50:17,172 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN2-29274359 info 2025-10-08 13:50:17,172 - INFO - Deleting VN ctest-VN1-34668464 2025-10-08 13:50:17,355 - DEBUG - Response for deleting network () 2025-10-08 13:50:17,355 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/ecf2653f-25f2-46cc-81ae-89f708366842 2025-10-08 13:50:17,361 - DEBUG - Response Code: 404 2025-10-08 13:50:17,361 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2025-10-08 13:50:17,367 - DEBUG - Requesting: http://10.0.0.33:8082/domain/e655d23d-1695-42cd-a914-8836b4a49568 2025-10-08 13:50:17,420 - DEBUG - Requesting: http://10.0.0.33:8082/project/0352087d-1036-4b51-8231-7489e95cfc8b 2025-10-08 13:50:17,509 - INFO - Validated that VN ctest-VN1-34668464 is not found in API Server 2025-10-08 13:50:17,520 - DEBUG - VN ctest-VN1-34668464 is not present in Agent 10.0.0.130 2025-10-08 13:50:17,530 - DEBUG - VN ctest-VN1-34668464 is not present in Agent 10.0.0.131 2025-10-08 13:50:17,530 - INFO - Validated that VN ctest-VN1-34668464 is not in any agent 2025-10-08 13:50:17,558 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN1-34668464 info 2025-10-08 13:50:17,643 - INFO - IPAM: ipam3 is not found in API Server 2025-10-08 13:50:17,661 - INFO - IPAM:ipam3 is not found in control node 2025-10-08 13:50:17,791 - INFO - IPAM: ipam2 is not found in API Server 2025-10-08 13:50:17,809 - INFO - IPAM:ipam2 is not found in control node 2025-10-08 13:50:17,935 - INFO - IPAM: ipam1 is not found in API Server 2025-10-08 13:50:17,957 - INFO - IPAM:ipam1 is not found in control node 2025-10-08 13:50:19,243 - DEBUG - No XMPP flaps were noticed during the test
2025-10-08 13:50:19,243 - INFO - END TEST : test_policy_inheritance_src_pol_dst_any : PASSED[0:03:25]
2025-10-08 13:50:19,243 - INFO - -------------------------------------------------------------------------------- 2025-10-08 13:50:20,513 - DEBUG - No XMPP flaps were noticed during the test
2025-10-08 13:50:20,513 - INFO - END TEST : test_policy_inheritance_src_pol_dst_any : PASSED[0:03:27]
2025-10-08 13:50:20,514 - INFO - -------------------------------------------------------------------------------- 2025-10-08 13:50:21,254 - INFO - Deleted project: ctest-TestPolicyAclIpv4v6-85636480, ID : 0352087d-1036-4b51-8231-7489e95cfc8b