2025-12-04 02:49:28,971 - INFO - Domain Default found not creating 2025-12-04 02:49:29,158 - INFO - Project ctest-TestPolicyAclIpv4v6-96935984 not found, creating it 2025-12-04 02:49:29,748 - INFO - Created Project:ctest-TestPolicyAclIpv4v6-96935984, ID : 426d4939-0709-4be3-abee-cc1bc4ff250d 2025-12-04 02:49:31,475 - INFO - ================================================================================ 2025-12-04 02:49:31,475 - INFO - STARTING TEST : test_policy_inheritance_src_pol_dst_any 2025-12-04 02:49:32,759 - DEBUG - Nothing to compare xmpp stats {'10.0.0.22': {'10.20.0.209': '0', '10.20.0.254': '0'}, '10.0.0.130': {'10.20.0.192': '0', '10.20.0.209': '0'}} with 2025-12-04 02:49:32,759 - INFO - Initial checks done. Running the testcase now 2025-12-04 02:49:32,759 - INFO - 2025-12-04 02:49:32,760 - INFO - ================================================================================ 2025-12-04 02:49:32,761 - INFO - STARTING TEST : test_policy_inheritance_src_pol_dst_any 2025-12-04 02:49:32,761 - INFO - TEST DESCRIPTION : Test cases to test policy inheritance 2025-12-04 02:49:34,068 - DEBUG - Nothing to compare xmpp stats {'10.0.0.22': {'10.20.0.209': '0', '10.20.0.254': '0'}, '10.0.0.130': {'10.20.0.192': '0', '10.20.0.209': '0'}} with 2025-12-04 02:49:34,069 - INFO - Initial checks done. Running the testcase now 2025-12-04 02:49:34,069 - INFO - 2025-12-04 02:49:35,401 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2025-12-04 02:49:35,739 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2025-12-04 02:49:35,772 - DEBUG - Requesting: http://10.0.0.27:8082/domain/77f3106b-db9e-49b5-893e-09cac574b890 2025-12-04 02:49:35,788 - DEBUG - Requesting: http://10.0.0.27:8082/project/426d4939-0709-4be3-abee-cc1bc4ff250d 2025-12-04 02:49:35,887 - DEBUG - Requesting: http://10.0.0.27:8082/network-ipam/6c5e5845-e834-4b9f-8e65-584f29a23d09 2025-12-04 02:49:35,894 - INFO - Verifications in API Server for IPAM: ipam1 passed 2025-12-04 02:49:35,900 - DEBUG - Control-node 10.0.0.27 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-96935984:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-96935984', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7808775861178747807', 'uuid-lslong': '10260704423057702153'}, 'enable': 'true', 'created': '2025-12-04T02:49:34', 'last-modified': '2025-12-04T02:49:34', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.222708'} 2025-12-04 02:49:35,906 - DEBUG - Control-node 10.0.0.33 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-96935984:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-96935984', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7808775861178747807', 'uuid-lslong': '10260704423057702153'}, 'enable': 'true', 'created': '2025-12-04T02:49:34', 'last-modified': '2025-12-04T02:49:34', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.225624'} 2025-12-04 02:49:35,911 - DEBUG - Control-node 10.0.0.23 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-96935984:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-96935984', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7808775861178747807', 'uuid-lslong': '10260704423057702153'}, 'enable': 'true', 'created': '2025-12-04T02:49:34', 'last-modified': '2025-12-04T02:49:34', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.233156'} 2025-12-04 02:49:35,911 - INFO - Verifications in Control node for IPAM: ipam1 passed 2025-12-04 02:49:35,911 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2025-12-04 02:49:35,917 - DEBUG - Requesting: http://10.0.0.27:8082/domain/77f3106b-db9e-49b5-893e-09cac574b890 2025-12-04 02:49:35,928 - DEBUG - Requesting: http://10.0.0.27:8082/project/426d4939-0709-4be3-abee-cc1bc4ff250d 2025-12-04 02:49:36,026 - DEBUG - Requesting: http://10.0.0.27:8082/network-ipam/33a33ed6-dcc5-40e9-ac87-6045895c0abd 2025-12-04 02:49:36,033 - INFO - Verifications in API Server for IPAM: ipam2 passed 2025-12-04 02:49:36,039 - DEBUG - Control-node 10.0.0.27 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-96935984:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-96935984', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3720886809685999849', 'uuid-lslong': '12432011148153064125'}, 'enable': 'true', 'created': '2025-12-04T02:49:34', 'last-modified': '2025-12-04T02:49:34', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.026720'} 2025-12-04 02:49:36,045 - DEBUG - Control-node 10.0.0.33 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-96935984:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-96935984', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3720886809685999849', 'uuid-lslong': '12432011148153064125'}, 'enable': 'true', 'created': '2025-12-04T02:49:34', 'last-modified': '2025-12-04T02:49:34', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.032614'} 2025-12-04 02:49:36,051 - DEBUG - Control-node 10.0.0.23 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-96935984:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-96935984', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3720886809685999849', 'uuid-lslong': '12432011148153064125'}, 'enable': 'true', 'created': '2025-12-04T02:49:34', 'last-modified': '2025-12-04T02:49:34', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.033514'} 2025-12-04 02:49:36,051 - INFO - Verifications in Control node for IPAM: ipam2 passed 2025-12-04 02:49:36,051 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2025-12-04 02:49:36,057 - DEBUG - Requesting: http://10.0.0.27:8082/domain/77f3106b-db9e-49b5-893e-09cac574b890 2025-12-04 02:49:36,070 - DEBUG - Requesting: http://10.0.0.27:8082/project/426d4939-0709-4be3-abee-cc1bc4ff250d 2025-12-04 02:49:36,166 - DEBUG - Requesting: http://10.0.0.27:8082/network-ipam/02a05780-3f5a-4061-b3a0-462de44dc378 2025-12-04 02:49:36,176 - INFO - Verifications in API Server for IPAM: ipam3 passed 2025-12-04 02:49:36,182 - DEBUG - Control-node 10.0.0.27 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-96935984:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-96935984', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '189247392679870561', 'uuid-lslong': '12943422491980579704'}, 'enable': 'true', 'created': '2025-12-04T02:49:35', 'last-modified': '2025-12-04T02:49:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.811950'} 2025-12-04 02:49:36,188 - DEBUG - Control-node 10.0.0.33 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-96935984:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-96935984', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '189247392679870561', 'uuid-lslong': '12943422491980579704'}, 'enable': 'true', 'created': '2025-12-04T02:49:35', 'last-modified': '2025-12-04T02:49:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.816661'} 2025-12-04 02:49:36,193 - DEBUG - Control-node 10.0.0.23 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-96935984:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-96935984', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '189247392679870561', 'uuid-lslong': '12943422491980579704'}, 'enable': 'true', 'created': '2025-12-04T02:49:35', 'last-modified': '2025-12-04T02:49:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.820738'} 2025-12-04 02:49:36,194 - INFO - Verifications in Control node for IPAM: ipam3 passed 2025-12-04 02:49:36,539 - DEBUG - Response for create_network : {'network': {'id': '8e73c634-f829-4cbc-9fa4-0a17a2c22823', 'name': 'ctest-VN1-90331990', 'tenant_id': '426d493907094be3abeecc1bc4ff250d', 'project_id': '426d493907094be3abeecc1bc4ff250d', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-96935984', 'ctest-VN1-90331990'], 'port_security_enabled': True, 'description': ''}} 2025-12-04 02:49:36,836 - DEBUG - Response for create_subnet : {'subnet': {'id': '1798c5f0-8555-4c27-93f5-5c6decd7a9d2', 'name': '', 'tenant_id': '426d493907094be3abeecc1bc4ff250d', 'network_id': '8e73c634-f829-4cbc-9fa4-0a17a2c22823', 'ip_version': 4, 'cidr': '122.130.96.192/26', 'allocation_pools': [{'start': '122.130.96.194', 'end': '122.130.96.254'}], 'gateway_ip': '122.130.96.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '122.130.96.194', 'tags': [], 'project_id': '426d493907094be3abeecc1bc4ff250d'}} 2025-12-04 02:49:37,070 - DEBUG - Response for create_subnet : {'subnet': {'id': '87c467f3-724c-45da-892a-9bdbc1b82111', 'name': '', 'tenant_id': '426d493907094be3abeecc1bc4ff250d', 'network_id': '8e73c634-f829-4cbc-9fa4-0a17a2c22823', 'ip_version': 6, 'cidr': '3502:888d:93:bbf6:d290:49e6::/96', 'allocation_pools': [{'start': '3502:888d:93:bbf6:d290:49e6:0:2', 'end': '3502:888d:93:bbf6:d290:49e6:ffff:fffe'}], 'gateway_ip': '3502:888d:93:bbf6:d290:49e6:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '3502:888d:93:bbf6:d290:49e6:0:2', 'tags': [], 'project_id': '426d493907094be3abeecc1bc4ff250d'}} 2025-12-04 02:49:37,097 - INFO - Created VN ctest-VN1-90331990 2025-12-04 02:49:37,108 - DEBUG - VN ctest-VN1-90331990 UUID is 8e73c634-f829-4cbc-9fa4-0a17a2c22823 2025-12-04 02:49:37,301 - DEBUG - Fetched VN: default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990(8e73c634-f829-4cbc-9fa4-0a17a2c22823) with subnets ['122.130.96.192/26', '3502:888d:93:bbf6:d290:49e6::/96'] 2025-12-04 02:49:37,795 - DEBUG - Response for create_network : {'network': {'id': '582a0f66-d2a7-4feb-a8da-af1256d2ad04', 'name': 'ctest-VN2-21473336', 'tenant_id': '426d493907094be3abeecc1bc4ff250d', 'project_id': '426d493907094be3abeecc1bc4ff250d', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-96935984', 'ctest-VN2-21473336'], 'port_security_enabled': True, 'description': ''}} 2025-12-04 02:49:38,071 - DEBUG - Response for create_subnet : {'subnet': {'id': '96b2f936-4ea6-4e1f-82a5-22d140fee0aa', 'name': '', 'tenant_id': '426d493907094be3abeecc1bc4ff250d', 'network_id': '582a0f66-d2a7-4feb-a8da-af1256d2ad04', 'ip_version': 4, 'cidr': '121.143.55.0/26', 'allocation_pools': [{'start': '121.143.55.2', 'end': '121.143.55.62'}], 'gateway_ip': '121.143.55.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '121.143.55.2', 'tags': [], 'project_id': '426d493907094be3abeecc1bc4ff250d'}} 2025-12-04 02:49:38,316 - DEBUG - Response for create_subnet : {'subnet': {'id': '8fd2737e-9b2c-414a-914f-4c9eea7b808a', 'name': '', 'tenant_id': '426d493907094be3abeecc1bc4ff250d', 'network_id': '582a0f66-d2a7-4feb-a8da-af1256d2ad04', 'ip_version': 6, 'cidr': '361d:c888:f70b:ad59:26d1:3ac0::/96', 'allocation_pools': [{'start': '361d:c888:f70b:ad59:26d1:3ac0:0:2', 'end': '361d:c888:f70b:ad59:26d1:3ac0:ffff:fffe'}], 'gateway_ip': '361d:c888:f70b:ad59:26d1:3ac0:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '361d:c888:f70b:ad59:26d1:3ac0:0:2', 'tags': [], 'project_id': '426d493907094be3abeecc1bc4ff250d'}} 2025-12-04 02:49:38,341 - INFO - Created VN ctest-VN2-21473336 2025-12-04 02:49:38,355 - DEBUG - VN ctest-VN2-21473336 UUID is 582a0f66-d2a7-4feb-a8da-af1256d2ad04 2025-12-04 02:49:38,741 - DEBUG - Response for create_network : {'network': {'id': '0fdb1540-ce92-4935-b844-40af3d92b8f7', 'name': 'ctest-VN3-22910592', 'tenant_id': '426d493907094be3abeecc1bc4ff250d', 'project_id': '426d493907094be3abeecc1bc4ff250d', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-96935984', 'ctest-VN3-22910592'], 'port_security_enabled': True, 'description': ''}} 2025-12-04 02:49:38,974 - DEBUG - Response for create_subnet : {'subnet': {'id': '92b705bb-5d53-4daf-adf8-599e4dc080b9', 'name': '', 'tenant_id': '426d493907094be3abeecc1bc4ff250d', 'network_id': '0fdb1540-ce92-4935-b844-40af3d92b8f7', 'ip_version': 4, 'cidr': '90.99.158.128/26', 'allocation_pools': [{'start': '90.99.158.130', 'end': '90.99.158.190'}], 'gateway_ip': '90.99.158.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '90.99.158.130', 'tags': [], 'project_id': '426d493907094be3abeecc1bc4ff250d'}} 2025-12-04 02:49:39,240 - DEBUG - Response for create_subnet : {'subnet': {'id': '57124a4d-fe45-4530-9094-3390db793c0a', 'name': '', 'tenant_id': '426d493907094be3abeecc1bc4ff250d', 'network_id': '0fdb1540-ce92-4935-b844-40af3d92b8f7', 'ip_version': 6, 'cidr': '360a:b183:6b03:6b1e:b5b8:76f6::/96', 'allocation_pools': [{'start': '360a:b183:6b03:6b1e:b5b8:76f6:0:2', 'end': '360a:b183:6b03:6b1e:b5b8:76f6:ffff:fffe'}], 'gateway_ip': '360a:b183:6b03:6b1e:b5b8:76f6:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '360a:b183:6b03:6b1e:b5b8:76f6:0:2', 'tags': [], 'project_id': '426d493907094be3abeecc1bc4ff250d'}} 2025-12-04 02:49:39,264 - INFO - Created VN ctest-VN3-22910592 2025-12-04 02:49:39,275 - DEBUG - VN ctest-VN3-22910592 UUID is 0fdb1540-ce92-4935-b844-40af3d92b8f7 2025-12-04 02:49:39,377 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2025-12-04 02:49:39,383 - DEBUG - Requesting: http://10.0.0.27:8082/domain/77f3106b-db9e-49b5-893e-09cac574b890 2025-12-04 02:49:39,395 - DEBUG - Requesting: http://10.0.0.27:8082/project/426d4939-0709-4be3-abee-cc1bc4ff250d 2025-12-04 02:49:39,490 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/8e73c634-f829-4cbc-9fa4-0a17a2c22823 2025-12-04 02:49:39,502 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/8e73c634-f829-4cbc-9fa4-0a17a2c22823 2025-12-04 02:49:39,513 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/5b500866-2828-497b-b93f-97d4b2e4c7a7 2025-12-04 02:49:39,520 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/5b500866-2828-497b-b93f-97d4b2e4c7a7 2025-12-04 02:49:39,528 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/3227702c-1d97-4f80-9573-500236f0f904 2025-12-04 02:49:39,535 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-12-04 02:49:39,535 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/8e73c634-f829-4cbc-9fa4-0a17a2c22823 2025-12-04 02:49:39,544 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/5b500866-2828-497b-b93f-97d4b2e4c7a7 2025-12-04 02:49:39,560 - INFO - Verified VN network id 13 for VN 8e73c634-f829-4cbc-9fa4-0a17a2c22823 2025-12-04 02:49:39,560 - INFO - Verifications in API Server for VN ctest-VN1-90331990 passed 2025-12-04 02:49:39,561 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/8e73c634-f829-4cbc-9fa4-0a17a2c22823 2025-12-04 02:49:39,571 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/5b500866-2828-497b-b93f-97d4b2e4c7a7 2025-12-04 02:49:39,578 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/5b500866-2828-497b-b93f-97d4b2e4c7a7 2025-12-04 02:49:39,586 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/3227702c-1d97-4f80-9573-500236f0f904 2025-12-04 02:49:39,598 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-96935984', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10264765906511547580', 'uuid-lslong': '11503330444842248227'}, 'enable': 'true', 'created': '2025-12-04T02:49:36', 'last-modified': '2025-12-04T02:49:36', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.599105'} 2025-12-04 02:49:39,604 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-12-04 02:49:39,610 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-96935984', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10264765906511547580', 'uuid-lslong': '11503330444842248227'}, 'enable': 'true', 'created': '2025-12-04T02:49:36', 'last-modified': '2025-12-04T02:49:36', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.611469'} 2025-12-04 02:49:39,616 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-12-04 02:49:39,621 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-96935984', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10264765906511547580', 'uuid-lslong': '11503330444842248227'}, 'enable': 'true', 'created': '2025-12-04T02:49:36', 'last-modified': '2025-12-04T02:49:36', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.618706'} 2025-12-04 02:49:39,626 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-12-04 02:49:39,626 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN1-90331990 passed 2025-12-04 02:49:39,627 - DEBUG - ====Verifying policy data for ctest-VN1-90331990 in API_Server ====== 2025-12-04 02:49:39,627 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2025-12-04 02:49:39,632 - DEBUG - Requesting: http://10.0.0.27:8082/domain/77f3106b-db9e-49b5-893e-09cac574b890 2025-12-04 02:49:39,643 - DEBUG - Requesting: http://10.0.0.27:8082/project/426d4939-0709-4be3-abee-cc1bc4ff250d 2025-12-04 02:49:39,734 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/8e73c634-f829-4cbc-9fa4-0a17a2c22823 2025-12-04 02:49:39,744 - DEBUG - =>VN ctest-VN1-90331990 has no policy to be verified 2025-12-04 02:49:39,744 - DEBUG - Verifying the vn in opserver 2025-12-04 02:49:39,744 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990 virtual network link through opserver 10.0.0.27 2025-12-04 02:49:39,744 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks 2025-12-04 02:49:40,039 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks 2025-12-04 02:49:40,104 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990?flat'} 2025-12-04 02:49:40,104 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990 virtual network link through opserver 10.0.0.33 2025-12-04 02:49:40,104 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks 2025-12-04 02:49:40,404 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks 2025-12-04 02:49:40,477 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990?flat'} 2025-12-04 02:49:40,477 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990 virtual network link through opserver 10.0.0.23 2025-12-04 02:49:40,477 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks 2025-12-04 02:49:40,786 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks 2025-12-04 02:49:40,849 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990?flat'} 2025-12-04 02:49:40,849 - INFO - Validated that VN default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990 is found in opserver 2025-12-04 02:49:40,871 - DEBUG - Do not have enough data to verify VN in agent 2025-12-04 02:49:40,882 - DEBUG - VRF ids for VN ctest-VN1-90331990: {} 2025-12-04 02:49:40,882 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2025-12-04 02:49:40,887 - DEBUG - Requesting: http://10.0.0.27:8082/domain/77f3106b-db9e-49b5-893e-09cac574b890 2025-12-04 02:49:40,898 - DEBUG - Requesting: http://10.0.0.27:8082/project/426d4939-0709-4be3-abee-cc1bc4ff250d 2025-12-04 02:49:40,989 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/582a0f66-d2a7-4feb-a8da-af1256d2ad04 2025-12-04 02:49:41,002 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/582a0f66-d2a7-4feb-a8da-af1256d2ad04 2025-12-04 02:49:41,012 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/b9de6e35-ff41-4625-8ddf-f524f29a6f8e 2025-12-04 02:49:41,021 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/b9de6e35-ff41-4625-8ddf-f524f29a6f8e 2025-12-04 02:49:41,028 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/eab5f7bb-adc5-4c1e-ab00-0a3944e1a016 2025-12-04 02:49:41,034 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-12-04 02:49:41,034 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/582a0f66-d2a7-4feb-a8da-af1256d2ad04 2025-12-04 02:49:41,044 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/b9de6e35-ff41-4625-8ddf-f524f29a6f8e 2025-12-04 02:49:41,060 - INFO - Verified VN network id 14 for VN 582a0f66-d2a7-4feb-a8da-af1256d2ad04 2025-12-04 02:49:41,060 - INFO - Verifications in API Server for VN ctest-VN2-21473336 passed 2025-12-04 02:49:41,060 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/582a0f66-d2a7-4feb-a8da-af1256d2ad04 2025-12-04 02:49:41,071 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/b9de6e35-ff41-4625-8ddf-f524f29a6f8e 2025-12-04 02:49:41,079 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/b9de6e35-ff41-4625-8ddf-f524f29a6f8e 2025-12-04 02:49:41,086 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/eab5f7bb-adc5-4c1e-ab00-0a3944e1a016 2025-12-04 02:49:41,100 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN2-21473336', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-96935984', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6352907158654767083', 'uuid-lslong': '12167229836595735812'}, 'enable': 'true', 'created': '2025-12-04T02:49:37', 'last-modified': '2025-12-04T02:49:38', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.837918'} 2025-12-04 02:49:41,106 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-12-04 02:49:41,112 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN2-21473336', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-96935984', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6352907158654767083', 'uuid-lslong': '12167229836595735812'}, 'enable': 'true', 'created': '2025-12-04T02:49:37', 'last-modified': '2025-12-04T02:49:38', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.849734'} 2025-12-04 02:49:41,118 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-12-04 02:49:41,124 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN2-21473336', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-96935984', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6352907158654767083', 'uuid-lslong': '12167229836595735812'}, 'enable': 'true', 'created': '2025-12-04T02:49:37', 'last-modified': '2025-12-04T02:49:38', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.863183'} 2025-12-04 02:49:41,129 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-12-04 02:49:41,129 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN2-21473336 passed 2025-12-04 02:49:41,129 - DEBUG - ====Verifying policy data for ctest-VN2-21473336 in API_Server ====== 2025-12-04 02:49:41,129 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2025-12-04 02:49:41,136 - DEBUG - Requesting: http://10.0.0.27:8082/domain/77f3106b-db9e-49b5-893e-09cac574b890 2025-12-04 02:49:41,147 - DEBUG - Requesting: http://10.0.0.27:8082/project/426d4939-0709-4be3-abee-cc1bc4ff250d 2025-12-04 02:49:41,243 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/582a0f66-d2a7-4feb-a8da-af1256d2ad04 2025-12-04 02:49:41,257 - DEBUG - =>VN ctest-VN2-21473336 has no policy to be verified 2025-12-04 02:49:41,257 - DEBUG - Verifying the vn in opserver 2025-12-04 02:49:41,257 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN2-21473336 virtual network link through opserver 10.0.0.27 2025-12-04 02:49:41,257 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks 2025-12-04 02:49:41,329 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN2-21473336', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN2-21473336?flat'} 2025-12-04 02:49:41,329 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN2-21473336 virtual network link through opserver 10.0.0.33 2025-12-04 02:49:41,329 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks 2025-12-04 02:49:41,399 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN2-21473336', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN2-21473336?flat'} 2025-12-04 02:49:41,399 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN2-21473336 virtual network link through opserver 10.0.0.23 2025-12-04 02:49:41,399 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks 2025-12-04 02:49:41,469 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN2-21473336', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN2-21473336?flat'} 2025-12-04 02:49:41,469 - INFO - Validated that VN default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN2-21473336 is found in opserver 2025-12-04 02:49:41,494 - DEBUG - Do not have enough data to verify VN in agent 2025-12-04 02:49:41,505 - DEBUG - VRF ids for VN ctest-VN2-21473336: {} 2025-12-04 02:49:41,505 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2025-12-04 02:49:41,511 - DEBUG - Requesting: http://10.0.0.27:8082/domain/77f3106b-db9e-49b5-893e-09cac574b890 2025-12-04 02:49:41,524 - DEBUG - Requesting: http://10.0.0.27:8082/project/426d4939-0709-4be3-abee-cc1bc4ff250d 2025-12-04 02:49:41,625 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/0fdb1540-ce92-4935-b844-40af3d92b8f7 2025-12-04 02:49:41,638 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/0fdb1540-ce92-4935-b844-40af3d92b8f7 2025-12-04 02:49:41,650 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/5b9c1ca6-635c-4064-a920-d3559a8ec092 2025-12-04 02:49:41,663 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/5b9c1ca6-635c-4064-a920-d3559a8ec092 2025-12-04 02:49:41,672 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/e1679fc1-c879-474f-afce-714fb17b924f 2025-12-04 02:49:41,681 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-12-04 02:49:41,681 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/0fdb1540-ce92-4935-b844-40af3d92b8f7 2025-12-04 02:49:41,692 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/5b9c1ca6-635c-4064-a920-d3559a8ec092 2025-12-04 02:49:41,711 - INFO - Verified VN network id 15 for VN 0fdb1540-ce92-4935-b844-40af3d92b8f7 2025-12-04 02:49:41,711 - INFO - Verifications in API Server for VN ctest-VN3-22910592 passed 2025-12-04 02:49:41,711 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/0fdb1540-ce92-4935-b844-40af3d92b8f7 2025-12-04 02:49:41,723 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/5b9c1ca6-635c-4064-a920-d3559a8ec092 2025-12-04 02:49:41,733 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/5b9c1ca6-635c-4064-a920-d3559a8ec092 2025-12-04 02:49:41,743 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/e1679fc1-c879-474f-afce-714fb17b924f 2025-12-04 02:49:41,758 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN3-22910592', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-96935984', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1142530298556336437', 'uuid-lslong': '13277808722791545079'}, 'enable': 'true', 'created': '2025-12-04T02:49:38', 'last-modified': '2025-12-04T02:49:39', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.587675'} 2025-12-04 02:49:41,765 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-12-04 02:49:41,772 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN3-22910592', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-96935984', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1142530298556336437', 'uuid-lslong': '13277808722791545079'}, 'enable': 'true', 'created': '2025-12-04T02:49:38', 'last-modified': '2025-12-04T02:49:39', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.607663'} 2025-12-04 02:49:41,779 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-12-04 02:49:41,786 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN3-22910592', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-96935984', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1142530298556336437', 'uuid-lslong': '13277808722791545079'}, 'enable': 'true', 'created': '2025-12-04T02:49:38', 'last-modified': '2025-12-04T02:49:39', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.613784'} 2025-12-04 02:49:41,796 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-12-04 02:49:41,796 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN3-22910592 passed 2025-12-04 02:49:41,797 - DEBUG - ====Verifying policy data for ctest-VN3-22910592 in API_Server ====== 2025-12-04 02:49:41,797 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2025-12-04 02:49:41,804 - DEBUG - Requesting: http://10.0.0.27:8082/domain/77f3106b-db9e-49b5-893e-09cac574b890 2025-12-04 02:49:41,817 - DEBUG - Requesting: http://10.0.0.27:8082/project/426d4939-0709-4be3-abee-cc1bc4ff250d 2025-12-04 02:49:41,928 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/0fdb1540-ce92-4935-b844-40af3d92b8f7 2025-12-04 02:49:41,961 - DEBUG - =>VN ctest-VN3-22910592 has no policy to be verified 2025-12-04 02:49:41,961 - DEBUG - Verifying the vn in opserver 2025-12-04 02:49:41,961 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN3-22910592 virtual network link through opserver 10.0.0.27 2025-12-04 02:49:41,961 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks 2025-12-04 02:49:42,046 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN3-22910592', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN3-22910592?flat'} 2025-12-04 02:49:42,046 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN3-22910592 virtual network link through opserver 10.0.0.33 2025-12-04 02:49:42,046 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks 2025-12-04 02:49:42,131 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN3-22910592', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN3-22910592?flat'} 2025-12-04 02:49:42,131 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN3-22910592 virtual network link through opserver 10.0.0.23 2025-12-04 02:49:42,131 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks 2025-12-04 02:49:42,207 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN3-22910592', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN3-22910592?flat'} 2025-12-04 02:49:42,207 - INFO - Validated that VN default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN3-22910592 is found in opserver 2025-12-04 02:49:42,236 - DEBUG - Do not have enough data to verify VN in agent 2025-12-04 02:49:42,249 - DEBUG - VRF ids for VN ctest-VN3-22910592: {} 2025-12-04 02:49:42,255 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990, 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-96935984:ctest-VN3-22910592, 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-12-04 02:49:42,525 - DEBUG - No source network defined 2025-12-04 02:49:42,525 - DEBUG - No source network defined 2025-12-04 02:49:42,526 - 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-96935984:ctest-policy13-06389220, 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-96935984:ctest-policy13-06389220, 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-12-04 02:49:42,801 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN2-21473336, 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-96935984:ctest-VN1-90331990, 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-96935984:ctest-VN2-21473336, 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-96935984:ctest-VN1-90331990, 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-12-04 02:49:43,034 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2025-12-04 02:49:43,040 - DEBUG - Requesting: http://10.0.0.27:8082/domain/77f3106b-db9e-49b5-893e-09cac574b890 2025-12-04 02:49:43,053 - DEBUG - Requesting: http://10.0.0.27:8082/project/426d4939-0709-4be3-abee-cc1bc4ff250d 2025-12-04 02:49:43,184 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/8e73c634-f829-4cbc-9fa4-0a17a2c22823 2025-12-04 02:49:43,196 - DEBUG - Setup step: Associating the policy to VN' 2025-12-04 02:49:43,350 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '8e73c634-f829-4cbc-9fa4-0a17a2c22823', 'name': 'ctest-VN1-90331990', 'tenant_id': '426d493907094be3abeecc1bc4ff250d', 'project_id': '426d493907094be3abeecc1bc4ff250d', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['1798c5f0-8555-4c27-93f5-5c6decd7a9d2', '87c467f3-724c-45da-892a-9bdbc1b82111'], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-96935984', 'ctest-VN1-90331990'], 'policys': [['default-domain', 'ctest-TestPolicyAclIpv4v6-96935984', 'ctest-policy12-83993246'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-96935984', 'ctest-policy13-06389220']], 'subnet_ipam': [{'subnet_cidr': '122.130.96.192/26', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-96935984', 'ipam1']}, {'subnet_cidr': '3502:888d:93:bbf6:d290:49e6::/96', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-96935984', 'ipam1']}], 'port_security_enabled': True, 'description': ''}} 2025-12-04 02:49:43,540 - DEBUG - Associated Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-96935984', 'ctest-policy12-83993246'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-96935984', 'ctest-policy13-06389220']] to ctest-VN1-90331990 2025-12-04 02:49:43,540 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2025-12-04 02:49:43,551 - DEBUG - Requesting: http://10.0.0.27:8082/domain/77f3106b-db9e-49b5-893e-09cac574b890 2025-12-04 02:49:43,571 - DEBUG - Requesting: http://10.0.0.27:8082/project/426d4939-0709-4be3-abee-cc1bc4ff250d 2025-12-04 02:49:43,671 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/582a0f66-d2a7-4feb-a8da-af1256d2ad04 2025-12-04 02:49:43,684 - DEBUG - Setup step: Associating the policy to VN' 2025-12-04 02:49:43,812 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '582a0f66-d2a7-4feb-a8da-af1256d2ad04', 'name': 'ctest-VN2-21473336', 'tenant_id': '426d493907094be3abeecc1bc4ff250d', 'project_id': '426d493907094be3abeecc1bc4ff250d', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['96b2f936-4ea6-4e1f-82a5-22d140fee0aa', '8fd2737e-9b2c-414a-914f-4c9eea7b808a'], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-96935984', 'ctest-VN2-21473336'], 'policys': [['default-domain', 'ctest-TestPolicyAclIpv4v6-96935984', 'ctest-policy21-60317777']], 'subnet_ipam': [{'subnet_cidr': '121.143.55.0/26', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-96935984', 'ipam2']}, {'subnet_cidr': '361d:c888:f70b:ad59:26d1:3ac0::/96', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-96935984', 'ipam2']}], 'port_security_enabled': True, 'description': ''}} 2025-12-04 02:49:43,903 - DEBUG - Associated Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-96935984', 'ctest-policy21-60317777']] to ctest-VN2-21473336 2025-12-04 02:49:44,025 - DEBUG - Services list from nova: [, , , ] 2025-12-04 02:49:45,162 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4840-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4840-1) 2025-12-04 02:49:46,732 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4840-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4840-2) 2025-12-04 02:49:48,408 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4840-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4840-1) 2025-12-04 02:49:48,503 - DEBUG - VM is in ACTIVE state now 2025-12-04 02:49:48,503 - INFO - VM name : ctest-VM11-22036895 2025-12-04 02:49:48,604 - DEBUG - VM ctest-VM11-22036895 ID is 8c984f7b-bb91-497f-aeea-084af478d710 2025-12-04 02:49:48,694 - DEBUG - VM is in ACTIVE state now 2025-12-04 02:49:48,724 - DEBUG - VM ctest-VM11-22036895 launched on Node an-jenkins-deploy-platform-ansible-os-4840-1 2025-12-04 02:49:48,898 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/8c984f7b-bb91-497f-aeea-084af478d710 2025-12-04 02:49:49,408 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/8c984f7b-bb91-497f-aeea-084af478d710 2025-12-04 02:49:49,464 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/8c984f7b-bb91-497f-aeea-084af478d710 2025-12-04 02:49:49,479 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/30dec441-23d5-4f1a-bb7c-75622fd744a7 2025-12-04 02:49:49,496 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/8c984f7b-bb91-497f-aeea-084af478d710 2025-12-04 02:49:49,507 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/30dec441-23d5-4f1a-bb7c-75622fd744a7 2025-12-04 02:49:49,517 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/15166322-2a5a-4fe6-a6e9-dcbdd2d23f6d 2025-12-04 02:49:49,528 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/53af9982-3d5b-48e8-9f39-0fd84b7aff33 2025-12-04 02:49:49,540 - DEBUG - Verifying in api server 10.0.0.23 2025-12-04 02:49:49,540 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/8c984f7b-bb91-497f-aeea-084af478d710 2025-12-04 02:49:49,548 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/30dec441-23d5-4f1a-bb7c-75622fd744a7 2025-12-04 02:49:49,560 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/15166322-2a5a-4fe6-a6e9-dcbdd2d23f6d 2025-12-04 02:49:49,568 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/53af9982-3d5b-48e8-9f39-0fd84b7aff33 2025-12-04 02:49:49,580 - INFO - VM ctest-VM11-22036895 verfication in all API Servers passed 2025-12-04 02:49:49,603 - DEBUG - VM ctest-VM11-22036895 Tap interface: {'index': '3', 'name': 'tap30dec441-23', 'uuid': '30dec441-23d5-4f1a-bb7c-75622fd744a7', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990:ctest-VN1-90331990', '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': '13', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990', 'vm_uuid': '8c984f7b-bb91-497f-aeea-084af478d710', 'vm_name': 'ctest-VM11-22036895', 'ip_addr': '122.130.96.195', 'mac_addr': '02:30:de:c4:41:23', '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-96935984:30dec441-23d5-4f1a-bb7c-75622fd744a7', 'sg_uuid_list': ['736181b7-1d3c-4673-bd91-86e490268178'], 'static_route_list': None, 'vm_project_uuid': '426d4939-0709-4be3-abee-cc1bc4ff250d', 'admin_state': 'Enabled', 'flow_key_idx': '30', 'allowed_address_pair_list': None, 'ip6_addr': '3502:888d:93:bbf6:d290:49e6: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-12-04 02:49:49,604 - DEBUG - Agent 10.0.0.22 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990:ctest-VN1-90331990 2025-12-04 02:49:49,610 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990:ctest-VN1-90331990', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990', 'table_label': '-1', 'vxlan_id': '13', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.201:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2025-12-04 02:49:49,723 - DEBUG - VM ctest-VM11-22036895 : Tap interface tap30dec441-23 is set to Active 2025-12-04 02:49:49,723 - DEBUG - Tap interface tap30dec441-23 detail : {'index': '3', 'name': 'tap30dec441-23', 'uuid': '30dec441-23d5-4f1a-bb7c-75622fd744a7', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990:ctest-VN1-90331990', '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': '13', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990', 'vm_uuid': '8c984f7b-bb91-497f-aeea-084af478d710', 'vm_name': 'ctest-VM11-22036895', 'ip_addr': '122.130.96.195', 'mac_addr': '02:30:de:c4:41:23', '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-96935984:30dec441-23d5-4f1a-bb7c-75622fd744a7', 'sg_uuid_list': ['736181b7-1d3c-4673-bd91-86e490268178'], 'static_route_list': None, 'vm_project_uuid': '426d4939-0709-4be3-abee-cc1bc4ff250d', 'admin_state': 'Enabled', 'flow_key_idx': '30', 'allowed_address_pair_list': None, 'ip6_addr': '3502:888d:93:bbf6:d290:49e6: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-12-04 02:49:49,789 - DEBUG - Starting Layer 2 verification in Agent 2025-12-04 02:49:49,810 - DEBUG - Layer 2 path is seen for VM MAC 02:30:de:c4:41:23 in agent 10.0.0.22 2025-12-04 02:49:49,810 - DEBUG - Active layer 2 route in agent is present for VMI tap30dec441-23 2025-12-04 02:49:49,810 - DEBUG - L2 label(29) matches bw route table and interface table 2025-12-04 02:49:49,822 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990 is found in Agent of node 10.0.0.22 2025-12-04 02:49:49,953 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990 is consistent in agent 10.0.0.22 2025-12-04 02:49:49,954 - DEBUG - Route for VM IP ['3502:888d:93:bbf6:d290:49e6:0:3', '122.130.96.195'] is consistent in agent 10.0.0.22 2025-12-04 02:49:49,954 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990 verification for VM ctest-VM11-22036895 in Agent 10.0.0.22 passed 2025-12-04 02:49:50,026 - DEBUG - Starting all layer 2 verification in agent 10.0.0.22 2025-12-04 02:49:50,039 - DEBUG - Route for VM MAC 02:30:de:c4:41:23 is consistent in agent 10.0.0.22 2025-12-04 02:49:53,247 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1027ms') 2025-12-04 02:49:53,247 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-22036895 failed! 2025-12-04 02:49:53,264 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990 is 122.130.96.193 and allocation pool is NOT set 2025-12-04 02:49:53,264 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990 is 3502:888d:93:bbf6:d290:49e6:0:1 and allocation pool is NOT set 2025-12-04 02:49:57,347 - 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-12-04 02:49:57,347 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-22036895 failed! 2025-12-04 02:49:57,364 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990 is 122.130.96.193 and allocation pool is NOT set 2025-12-04 02:49:57,364 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990 is 3502:888d:93:bbf6:d290:49e6:0:1 and allocation pool is NOT set 2025-12-04 02:50:01,446 - 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-12-04 02:50:01,447 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-22036895 failed! 2025-12-04 02:50:01,464 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990 is 122.130.96.193 and allocation pool is NOT set 2025-12-04 02:50:01,465 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990 is 3502:888d:93:bbf6:d290:49e6:0:1 and allocation pool is NOT set 2025-12-04 02:50:05,542 - 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-12-04 02:50:05,542 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-22036895 failed! 2025-12-04 02:50:05,557 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990 is 122.130.96.193 and allocation pool is NOT set 2025-12-04 02:50:05,557 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990 is 3502:888d:93:bbf6:d290:49e6:0:1 and allocation pool is NOT set 2025-12-04 02:50:09,635 - 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-12-04 02:50:09,635 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-22036895 failed! 2025-12-04 02:50:09,650 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990 is 122.130.96.193 and allocation pool is NOT set 2025-12-04 02:50:09,650 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990 is 3502:888d:93:bbf6:d290:49e6:0:1 and allocation pool is NOT set 2025-12-04 02:50:13,725 - 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-12-04 02:50:13,725 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-22036895 failed! 2025-12-04 02:50:13,740 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990 is 122.130.96.193 and allocation pool is NOT set 2025-12-04 02:50:13,740 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990 is 3502:888d:93:bbf6:d290:49e6:0:1 and allocation pool is NOT set 2025-12-04 02:50:17,829 - 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-12-04 02:50:17,829 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-22036895 failed! 2025-12-04 02:50:17,843 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990 is 122.130.96.193 and allocation pool is NOT set 2025-12-04 02:50:17,843 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990 is 3502:888d:93:bbf6:d290:49e6:0:1 and allocation pool is NOT set 2025-12-04 02:50:21,930 - 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-12-04 02:50:21,930 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-22036895 failed! 2025-12-04 02:50:21,944 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990 is 122.130.96.193 and allocation pool is NOT set 2025-12-04 02:50:21,945 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990 is 3502:888d:93:bbf6:d290:49e6:0:1 and allocation pool is NOT set 2025-12-04 02:50:26,019 - 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 1009ms') 2025-12-04 02:50:26,019 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-22036895 failed! 2025-12-04 02:50:26,034 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990 is 122.130.96.193 and allocation pool is NOT set 2025-12-04 02:50:26,035 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990 is 3502:888d:93:bbf6:d290:49e6:0:1 and allocation pool is NOT set 2025-12-04 02:50:30,122 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1018ms') 2025-12-04 02:50:30,122 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-22036895 failed! 2025-12-04 02:50:30,139 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990 is 122.130.96.193 and allocation pool is NOT set 2025-12-04 02:50:30,139 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990 is 3502:888d:93:bbf6:d290:49e6:0:1 and allocation pool is NOT set 2025-12-04 02:50:34,208 - 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 1009ms') 2025-12-04 02:50:34,208 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-22036895 failed! 2025-12-04 02:50:34,224 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990 is 122.130.96.193 and allocation pool is NOT set 2025-12-04 02:50:34,225 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990 is 3502:888d:93:bbf6:d290:49e6:0:1 and allocation pool is NOT set 2025-12-04 02:50:38,304 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1018ms') 2025-12-04 02:50:38,305 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-22036895 failed! 2025-12-04 02:50:38,319 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990 is 122.130.96.193 and allocation pool is NOT set 2025-12-04 02:50:38,320 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990 is 3502:888d:93:bbf6:d290:49e6:0:1 and allocation pool is NOT set 2025-12-04 02:50:40,385 - 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=10.7 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.16 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 2.160/6.429/10.698/4.269 ms') 2025-12-04 02:50:40,385 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-22036895 passed 2025-12-04 02:50:40,385 - INFO - VM ctest-VM11-22036895 verifications in Compute nodes passed 2025-12-04 02:50:40,682 - DEBUG - Validated VM route 122.130.96.195 in vrouter of 10.0.0.22 2025-12-04 02:50:40,690 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0 2025-12-04 02:50:40,690 - INFO - Validated routes of VM ctest-VM11-22036895 in all vrouters 2025-12-04 02:50:41,142 - DEBUG - Starting all layer2 verification in 10.0.0.27 Control Node 2025-12-04 02:50:41,251 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.27, not supported 2025-12-04 02:50:41,370 - DEBUG - Layer2 route found for VM MAC 02:30:de:c4:41:23 in Control-node 10.0.0.27 2025-12-04 02:50:41,370 - DEBUG - L2 Label for VM ctest-VM11-22036895 same between Control-node 10.0.0.27 and Agent, Expected: 29, Seen: 29 2025-12-04 02:50:41,370 - DEBUG - Starting all layer2 verification in 10.0.0.33 Control Node 2025-12-04 02:50:41,487 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.33, not supported 2025-12-04 02:50:41,616 - DEBUG - Layer2 route found for VM MAC 02:30:de:c4:41:23 in Control-node 10.0.0.33 2025-12-04 02:50:41,616 - DEBUG - L2 Label for VM ctest-VM11-22036895 same between Control-node 10.0.0.33 and Agent, Expected: 29, Seen: 29 2025-12-04 02:50:41,616 - DEBUG - Starting all layer2 verification in 10.0.0.23 Control Node 2025-12-04 02:50:41,731 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.23, not supported 2025-12-04 02:50:41,857 - DEBUG - Layer2 route found for VM MAC 02:30:de:c4:41:23 in Control-node 10.0.0.23 2025-12-04 02:50:41,857 - DEBUG - L2 Label for VM ctest-VM11-22036895 same between Control-node 10.0.0.23 and Agent, Expected: 29, Seen: 29 2025-12-04 02:50:41,857 - INFO - Verification in Control-nodes for VM ctest-VM11-22036895 passed 2025-12-04 02:50:41,857 - DEBUG - Verifying the vm in opserver 2025-12-04 02:50:41,857 - DEBUG - Verifying in collector 10.0.0.23 ... 2025-12-04 02:50:41,857 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine/8c984f7b-bb91-497f-aeea-084af478d710?flat 2025-12-04 02:50:41,873 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-96935984:30dec441-23d5-4f1a-bb7c-75622fd744a7?flat 2025-12-04 02:50:41,897 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-96935984:30dec441-23d5-4f1a-bb7c-75622fd744a7?flat 2025-12-04 02:50:41,917 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-96935984:30dec441-23d5-4f1a-bb7c-75622fd744a7?flat 2025-12-04 02:50:41,939 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine/8c984f7b-bb91-497f-aeea-084af478d710?flat 2025-12-04 02:50:41,955 - DEBUG - Verifying vm in vn uve 2025-12-04 02:50:41,955 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-96935984:30dec441-23d5-4f1a-bb7c-75622fd744a7?flat 2025-12-04 02:50:41,978 - DEBUG - VM uve shows interface as {'uuid': '30dec441-23d5-4f1a-bb7c-75622fd744a7', 'mac_address': '02:30:de:c4:41:23', 'l2_active': True, 'vhostuser_mode': 0, 'port_mirror_enabled': False, 'ip6_address': '3502:888d:93:bbf6:d290:49e6:0:3', 'fixed_ip4_list': ['122.130.96.195'], 'vn_uuid': '8e73c634-f829-4cbc-9fa4-0a17a2c22823', 'vm_name': 'ctest-VM11-22036895', 'fixed_ip6_list': ['3502:888d:93:bbf6:d290:49e6:0:3'], 'tx_vlan': 65535, 'active': True, 'vm_uuid': '8c984f7b-bb91-497f-aeea-084af478d710', 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990', 'ip_address': '122.130.96.195', 'ip6_active': True, 'gateway': '122.130.96.193', '__T': 1764816592100292, 'ip4_active': True, 'rx_vlan': 65535, 'label': 25, 'is_health_check_active': True, 'admin_state': True} 2025-12-04 02:50:41,978 - DEBUG - VM uve shows ip address as ['122.130.96.195', '3502:888d:93:bbf6:d290:49e6:0:3'] 2025-12-04 02:50:41,978 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990 2025-12-04 02:50:41,978 - INFO - Verifying through opserver in 10.0.0.27 2025-12-04 02:50:41,978 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990?flat 2025-12-04 02:50:42,008 - DEBUG - expected vm list 8c984f7b-bb91-497f-aeea-084af478d710 2025-12-04 02:50:42,008 - DEBUG - Extracted vm list ['8c984f7b-bb91-497f-aeea-084af478d710'] 2025-12-04 02:50:42,008 - INFO - VM 8c984f7b-bb91-497f-aeea-084af478d710 is present in default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990 2025-12-04 02:50:42,009 - DEBUG - Verifying vm in vrouter uve 2025-12-04 02:50:42,009 - DEBUG - Getting info from collector 10.0.0.23.. 2025-12-04 02:50:42,009 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine/8c984f7b-bb91-497f-aeea-084af478d710?flat 2025-12-04 02:50:42,026 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-4840-1'] 2025-12-04 02:50:42,026 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4840-1:Compute:contrail-vrouter-agent:0?flat 2025-12-04 02:50:42,062 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4840-1?flat 2025-12-04 02:50:42,104 - DEBUG - VM 8c984f7b-bb91-497f-aeea-084af478d710 is present in vrouter an-jenkins-deploy-platform-ansible-os-4840-1 uve 2025-12-04 02:50:42,104 - DEBUG - Validated that VM ctest-VM11-22036895 is in Vrouter an-jenkins-deploy-platform-ansible-os-4840-1 UVE 2025-12-04 02:50:42,104 - DEBUG - Verifying vm tap interface/vn in vrouter uve 2025-12-04 02:50:42,112 - DEBUG - Expected tap interface of VM uuid 8c984f7b-bb91-497f-aeea-084af478d710 is default-domain:ctest-TestPolicyAclIpv4v6-96935984:30dec441-23d5-4f1a-bb7c-75622fd744a7 2025-12-04 02:50:42,112 - DEBUG - Expected VN of VM uuid 8c984f7b-bb91-497f-aeea-084af478d710 is default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990 2025-12-04 02:50:42,112 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4840-1:Compute:contrail-vrouter-agent:0?flat 2025-12-04 02:50:42,153 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4840-1?flat 2025-12-04 02:50:42,189 - DEBUG - VM 8c984f7b-bb91-497f-aeea-084af478d710 is present in vrouter an-jenkins-deploy-platform-ansible-os-4840-1 uve 2025-12-04 02:50:42,189 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-96935984:30dec441-23d5-4f1a-bb7c-75622fd744a7 of vm 8c984f7b-bb91-497f-aeea-084af478d710 is present in vrouter an-jenkins-deploy-platform-ansible-os-4840-1 uve 2025-12-04 02:50:42,189 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990 of vm 8c984f7b-bb91-497f-aeea-084af478d710 is present in vrouter an-jenkins-deploy-platform-ansible-os-4840-1 uve 2025-12-04 02:50:42,189 - DEBUG - Verifying the 8c984f7b-bb91-497f-aeea-084af478d710 virtual network link through opserver 10.0.0.27 2025-12-04 02:50:42,189 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines 2025-12-04 02:50:42,207 - DEBUG - VM link and name as {'name': '8c984f7b-bb91-497f-aeea-084af478d710', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-machine/8c984f7b-bb91-497f-aeea-084af478d710?flat'} 2025-12-04 02:50:42,207 - DEBUG - Verifying the 8c984f7b-bb91-497f-aeea-084af478d710 virtual network link through opserver 10.0.0.33 2025-12-04 02:50:42,207 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machines 2025-12-04 02:50:42,222 - DEBUG - VM link and name as {'name': '8c984f7b-bb91-497f-aeea-084af478d710', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-machine/8c984f7b-bb91-497f-aeea-084af478d710?flat'} 2025-12-04 02:50:42,222 - DEBUG - Verifying the 8c984f7b-bb91-497f-aeea-084af478d710 virtual network link through opserver 10.0.0.23 2025-12-04 02:50:42,222 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machines 2025-12-04 02:50:42,236 - DEBUG - VM link and name as {'name': '8c984f7b-bb91-497f-aeea-084af478d710', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-machine/8c984f7b-bb91-497f-aeea-084af478d710?flat'} 2025-12-04 02:50:42,236 - INFO - VM ctest-VM11-22036895 validations in Opserver passed 2025-12-04 02:50:42,329 - DEBUG - VM is in ACTIVE state now 2025-12-04 02:50:42,329 - INFO - VM name : ctest-VM21-71358464 2025-12-04 02:50:42,430 - DEBUG - VM ctest-VM21-71358464 ID is 7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 2025-12-04 02:50:42,520 - DEBUG - VM is in ACTIVE state now 2025-12-04 02:50:42,520 - DEBUG - VM ctest-VM21-71358464 launched on Node an-jenkins-deploy-platform-ansible-os-4840-2 2025-12-04 02:50:42,607 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 2025-12-04 02:50:42,619 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 2025-12-04 02:50:42,628 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/2c811150-d82b-49e9-a09a-0cc9370d65e1 2025-12-04 02:50:42,640 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 2025-12-04 02:50:42,649 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/2c811150-d82b-49e9-a09a-0cc9370d65e1 2025-12-04 02:50:42,660 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/35ab7652-48fc-4d1d-b1c2-d1fd301dff2c 2025-12-04 02:50:42,671 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/43b13d91-9fcb-4c8a-afb9-4d39b12459af 2025-12-04 02:50:42,681 - DEBUG - Verifying in api server 10.0.0.23 2025-12-04 02:50:42,681 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 2025-12-04 02:50:42,692 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/2c811150-d82b-49e9-a09a-0cc9370d65e1 2025-12-04 02:50:42,703 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/35ab7652-48fc-4d1d-b1c2-d1fd301dff2c 2025-12-04 02:50:42,712 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/43b13d91-9fcb-4c8a-afb9-4d39b12459af 2025-12-04 02:50:42,723 - INFO - VM ctest-VM21-71358464 verfication in all API Servers passed 2025-12-04 02:50:42,748 - DEBUG - VM ctest-VM21-71358464 Tap interface: {'index': '3', 'name': 'tap2c811150-d8', 'uuid': '2c811150-d82b-49e9-a09a-0cc9370d65e1', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN2-21473336:ctest-VN2-21473336', '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-96935984:ctest-VN2-21473336', 'vm_uuid': '7f23b5c5-47a9-4b16-82f8-1c3531ea84b1', 'vm_name': 'ctest-VM21-71358464', 'ip_addr': '121.143.55.3', 'mac_addr': '02:2c:81:11:50:d8', '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-96935984:2c811150-d82b-49e9-a09a-0cc9370d65e1', 'sg_uuid_list': ['736181b7-1d3c-4673-bd91-86e490268178'], 'static_route_list': None, 'vm_project_uuid': '426d4939-0709-4be3-abee-cc1bc4ff250d', 'admin_state': 'Enabled', 'flow_key_idx': '29', 'allowed_address_pair_list': None, 'ip6_addr': '361d:c888:f70b:ad59:26d1:3ac0: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-12-04 02:50:42,748 - DEBUG - Agent 10.0.0.130 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN2-21473336:ctest-VN2-21473336 2025-12-04 02:50:42,756 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN2-21473336:ctest-VN2-21473336', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN2-21473336', 'table_label': '-1', 'vxlan_id': '14', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.252:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2025-12-04 02:50:42,854 - DEBUG - VM ctest-VM21-71358464 : Tap interface tap2c811150-d8 is set to Active 2025-12-04 02:50:42,854 - DEBUG - Tap interface tap2c811150-d8 detail : {'index': '3', 'name': 'tap2c811150-d8', 'uuid': '2c811150-d82b-49e9-a09a-0cc9370d65e1', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN2-21473336:ctest-VN2-21473336', '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-96935984:ctest-VN2-21473336', 'vm_uuid': '7f23b5c5-47a9-4b16-82f8-1c3531ea84b1', 'vm_name': 'ctest-VM21-71358464', 'ip_addr': '121.143.55.3', 'mac_addr': '02:2c:81:11:50:d8', '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-96935984:2c811150-d82b-49e9-a09a-0cc9370d65e1', 'sg_uuid_list': ['736181b7-1d3c-4673-bd91-86e490268178'], 'static_route_list': None, 'vm_project_uuid': '426d4939-0709-4be3-abee-cc1bc4ff250d', 'admin_state': 'Enabled', 'flow_key_idx': '29', 'allowed_address_pair_list': None, 'ip6_addr': '361d:c888:f70b:ad59:26d1:3ac0: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-12-04 02:50:42,975 - DEBUG - Starting Layer 2 verification in Agent 2025-12-04 02:50:42,994 - DEBUG - Layer 2 path is seen for VM MAC 02:2c:81:11:50:d8 in agent 10.0.0.130 2025-12-04 02:50:42,995 - DEBUG - Active layer 2 route in agent is present for VMI tap2c811150-d8 2025-12-04 02:50:42,995 - DEBUG - L2 label(29) matches bw route table and interface table 2025-12-04 02:50:43,016 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN2-21473336 is found in Agent of node 10.0.0.130 2025-12-04 02:50:43,130 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN2-21473336 is consistent in agent 10.0.0.130 2025-12-04 02:50:43,131 - DEBUG - Route for VM IP ['361d:c888:f70b:ad59:26d1:3ac0:0:3', '121.143.55.3'] is consistent in agent 10.0.0.130 2025-12-04 02:50:43,131 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN2-21473336 verification for VM ctest-VM21-71358464 in Agent 10.0.0.130 passed 2025-12-04 02:50:43,246 - DEBUG - Starting all layer 2 verification in agent 10.0.0.130 2025-12-04 02:50:43,258 - DEBUG - Route for VM MAC 02:2c:81:11:50:d8 is consistent in agent 10.0.0.130 2025-12-04 02:50:44,445 - 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.36 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.01 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 1.006/4.182/7.358/3.176 ms') 2025-12-04 02:50:44,445 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-71358464 passed 2025-12-04 02:50:44,445 - INFO - VM ctest-VM21-71358464 verifications in Compute nodes passed 2025-12-04 02:50:44,700 - DEBUG - Validated VM route 121.143.55.3 in vrouter of 10.0.0.130 2025-12-04 02:50:44,709 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0 2025-12-04 02:50:44,709 - INFO - Validated routes of VM ctest-VM21-71358464 in all vrouters 2025-12-04 02:50:45,148 - DEBUG - Starting all layer2 verification in 10.0.0.27 Control Node 2025-12-04 02:50:45,264 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.27, not supported 2025-12-04 02:50:45,393 - DEBUG - Layer2 route found for VM MAC 02:2c:81:11:50:d8 in Control-node 10.0.0.27 2025-12-04 02:50:45,393 - DEBUG - L2 Label for VM ctest-VM21-71358464 same between Control-node 10.0.0.27 and Agent, Expected: 29, Seen: 29 2025-12-04 02:50:45,393 - DEBUG - Starting all layer2 verification in 10.0.0.33 Control Node 2025-12-04 02:50:45,506 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.33, not supported 2025-12-04 02:50:45,630 - DEBUG - Layer2 route found for VM MAC 02:2c:81:11:50:d8 in Control-node 10.0.0.33 2025-12-04 02:50:45,630 - DEBUG - L2 Label for VM ctest-VM21-71358464 same between Control-node 10.0.0.33 and Agent, Expected: 29, Seen: 29 2025-12-04 02:50:45,630 - DEBUG - Starting all layer2 verification in 10.0.0.23 Control Node 2025-12-04 02:50:45,746 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.23, not supported 2025-12-04 02:50:45,864 - DEBUG - Layer2 route found for VM MAC 02:2c:81:11:50:d8 in Control-node 10.0.0.23 2025-12-04 02:50:45,865 - DEBUG - L2 Label for VM ctest-VM21-71358464 same between Control-node 10.0.0.23 and Agent, Expected: 29, Seen: 29 2025-12-04 02:50:45,865 - INFO - Verification in Control-nodes for VM ctest-VM21-71358464 passed 2025-12-04 02:50:45,865 - DEBUG - Verifying the vm in opserver 2025-12-04 02:50:45,865 - DEBUG - Verifying in collector 10.0.0.23 ... 2025-12-04 02:50:45,865 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine/7f23b5c5-47a9-4b16-82f8-1c3531ea84b1?flat 2025-12-04 02:50:45,881 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-96935984:2c811150-d82b-49e9-a09a-0cc9370d65e1?flat 2025-12-04 02:50:45,899 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-96935984:2c811150-d82b-49e9-a09a-0cc9370d65e1?flat 2025-12-04 02:50:45,916 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-96935984:2c811150-d82b-49e9-a09a-0cc9370d65e1?flat 2025-12-04 02:50:45,935 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine/7f23b5c5-47a9-4b16-82f8-1c3531ea84b1?flat 2025-12-04 02:50:45,949 - DEBUG - Verifying vm in vn uve 2025-12-04 02:50:45,949 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-96935984:2c811150-d82b-49e9-a09a-0cc9370d65e1?flat 2025-12-04 02:50:45,966 - DEBUG - VM uve shows interface as {'active': True, 'fixed_ip6_list': ['361d:c888:f70b:ad59:26d1:3ac0:0:3'], 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN2-21473336', 'ip6_address': '361d:c888:f70b:ad59:26d1:3ac0:0:3', 'fixed_ip4_list': ['121.143.55.3'], 'admin_state': True, 'port_mirror_enabled': False, 'is_health_check_active': True, 'tx_vlan': 65535, 'gateway': '121.143.55.1', 'uuid': '2c811150-d82b-49e9-a09a-0cc9370d65e1', 'ip_address': '121.143.55.3', '__T': 1764816593961901, 'ip6_active': True, 'mac_address': '02:2c:81:11:50:d8', 'vhostuser_mode': 0, 'vm_uuid': '7f23b5c5-47a9-4b16-82f8-1c3531ea84b1', 'l2_active': True, 'ip4_active': True, 'rx_vlan': 65535, 'vn_uuid': '582a0f66-d2a7-4feb-a8da-af1256d2ad04', 'label': 25, 'vm_name': 'ctest-VM21-71358464'} 2025-12-04 02:50:45,966 - DEBUG - VM uve shows ip address as ['121.143.55.3', '361d:c888:f70b:ad59:26d1:3ac0:0:3'] 2025-12-04 02:50:45,966 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN2-21473336 2025-12-04 02:50:45,966 - INFO - Verifying through opserver in 10.0.0.27 2025-12-04 02:50:45,966 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN2-21473336?flat 2025-12-04 02:50:45,985 - DEBUG - expected vm list 7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 2025-12-04 02:50:45,985 - DEBUG - Extracted vm list ['7f23b5c5-47a9-4b16-82f8-1c3531ea84b1'] 2025-12-04 02:50:45,985 - INFO - VM 7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 is present in default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN2-21473336 2025-12-04 02:50:45,986 - DEBUG - Verifying vm in vrouter uve 2025-12-04 02:50:45,986 - DEBUG - Getting info from collector 10.0.0.23.. 2025-12-04 02:50:45,986 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine/7f23b5c5-47a9-4b16-82f8-1c3531ea84b1?flat 2025-12-04 02:50:46,001 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-4840-2'] 2025-12-04 02:50:46,001 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4840-2:Compute:contrail-vrouter-agent:0?flat 2025-12-04 02:50:46,034 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4840-2?flat 2025-12-04 02:50:46,063 - DEBUG - VM 7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 is present in vrouter an-jenkins-deploy-platform-ansible-os-4840-2 uve 2025-12-04 02:50:46,063 - DEBUG - Validated that VM ctest-VM21-71358464 is in Vrouter an-jenkins-deploy-platform-ansible-os-4840-2 UVE 2025-12-04 02:50:46,063 - DEBUG - Verifying vm tap interface/vn in vrouter uve 2025-12-04 02:50:46,077 - DEBUG - Expected tap interface of VM uuid 7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 is default-domain:ctest-TestPolicyAclIpv4v6-96935984:2c811150-d82b-49e9-a09a-0cc9370d65e1 2025-12-04 02:50:46,077 - DEBUG - Expected VN of VM uuid 7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 is default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN2-21473336 2025-12-04 02:50:46,078 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4840-2:Compute:contrail-vrouter-agent:0?flat 2025-12-04 02:50:46,122 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4840-2?flat 2025-12-04 02:50:46,148 - DEBUG - VM 7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 is present in vrouter an-jenkins-deploy-platform-ansible-os-4840-2 uve 2025-12-04 02:50:46,148 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-96935984:2c811150-d82b-49e9-a09a-0cc9370d65e1 of vm 7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 is present in vrouter an-jenkins-deploy-platform-ansible-os-4840-2 uve 2025-12-04 02:50:46,148 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN2-21473336 of vm 7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 is present in vrouter an-jenkins-deploy-platform-ansible-os-4840-2 uve 2025-12-04 02:50:46,148 - DEBUG - Verifying the 7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 virtual network link through opserver 10.0.0.27 2025-12-04 02:50:46,148 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines 2025-12-04 02:50:46,162 - DEBUG - VM link and name as {'name': '7f23b5c5-47a9-4b16-82f8-1c3531ea84b1', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-machine/7f23b5c5-47a9-4b16-82f8-1c3531ea84b1?flat'} 2025-12-04 02:50:46,162 - DEBUG - Verifying the 7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 virtual network link through opserver 10.0.0.33 2025-12-04 02:50:46,162 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machines 2025-12-04 02:50:46,175 - DEBUG - VM link and name as {'name': '7f23b5c5-47a9-4b16-82f8-1c3531ea84b1', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-machine/7f23b5c5-47a9-4b16-82f8-1c3531ea84b1?flat'} 2025-12-04 02:50:46,175 - DEBUG - Verifying the 7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 virtual network link through opserver 10.0.0.23 2025-12-04 02:50:46,175 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machines 2025-12-04 02:50:46,188 - DEBUG - VM link and name as {'name': '7f23b5c5-47a9-4b16-82f8-1c3531ea84b1', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-machine/7f23b5c5-47a9-4b16-82f8-1c3531ea84b1?flat'} 2025-12-04 02:50:46,188 - INFO - VM ctest-VM21-71358464 validations in Opserver passed 2025-12-04 02:50:46,275 - DEBUG - VM is in ACTIVE state now 2025-12-04 02:50:46,275 - INFO - VM name : ctest-VM31-71740250 2025-12-04 02:50:46,362 - DEBUG - VM ctest-VM31-71740250 ID is cd151108-4858-4ae5-9d04-7bad9059f85a 2025-12-04 02:50:46,449 - DEBUG - VM is in ACTIVE state now 2025-12-04 02:50:46,449 - DEBUG - VM ctest-VM31-71740250 launched on Node an-jenkins-deploy-platform-ansible-os-4840-1 2025-12-04 02:50:46,531 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/cd151108-4858-4ae5-9d04-7bad9059f85a 2025-12-04 02:50:46,540 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/cd151108-4858-4ae5-9d04-7bad9059f85a 2025-12-04 02:50:46,547 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/24e94464-ced1-45ec-b520-32e8e5a9115e 2025-12-04 02:50:46,558 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/cd151108-4858-4ae5-9d04-7bad9059f85a 2025-12-04 02:50:46,565 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/24e94464-ced1-45ec-b520-32e8e5a9115e 2025-12-04 02:50:46,593 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/05a2676d-8f81-4d90-a1ee-ce38d3942a2e 2025-12-04 02:50:46,610 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/1443d125-41c0-42f5-bed4-04cb3a21c6ea 2025-12-04 02:50:46,627 - DEBUG - Verifying in api server 10.0.0.23 2025-12-04 02:50:46,627 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/cd151108-4858-4ae5-9d04-7bad9059f85a 2025-12-04 02:50:46,637 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/24e94464-ced1-45ec-b520-32e8e5a9115e 2025-12-04 02:50:46,654 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/05a2676d-8f81-4d90-a1ee-ce38d3942a2e 2025-12-04 02:50:46,664 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/1443d125-41c0-42f5-bed4-04cb3a21c6ea 2025-12-04 02:50:46,673 - INFO - VM ctest-VM31-71740250 verfication in all API Servers passed 2025-12-04 02:50:46,696 - DEBUG - VM ctest-VM31-71740250 Tap interface: {'index': '4', 'name': 'tap24e94464-ce', 'uuid': '24e94464-ced1-45ec-b520-32e8e5a9115e', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN3-22910592:ctest-VN3-22910592', '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': '15', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN3-22910592', 'vm_uuid': 'cd151108-4858-4ae5-9d04-7bad9059f85a', 'vm_name': 'ctest-VM31-71740250', 'ip_addr': '90.99.158.131', 'mac_addr': '02:24:e9:44:64:ce', '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-96935984:24e94464-ced1-45ec-b520-32e8e5a9115e', 'sg_uuid_list': ['736181b7-1d3c-4673-bd91-86e490268178'], 'static_route_list': None, 'vm_project_uuid': '426d4939-0709-4be3-abee-cc1bc4ff250d', 'admin_state': 'Enabled', 'flow_key_idx': '40', 'allowed_address_pair_list': None, 'ip6_addr': '360a:b183:6b03:6b1e:b5b8:76f6: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-12-04 02:50:46,696 - DEBUG - Agent 10.0.0.22 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN3-22910592:ctest-VN3-22910592 2025-12-04 02:50:46,703 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN3-22910592:ctest-VN3-22910592', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN3-22910592', 'table_label': '-1', 'vxlan_id': '15', 'evpnindex': '3', 'brindex': '3', 'mplsindex': '0', 'RD': '10.20.0.201:3', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2025-12-04 02:50:46,815 - DEBUG - VM ctest-VM31-71740250 : Tap interface tap24e94464-ce is set to Active 2025-12-04 02:50:46,815 - DEBUG - Tap interface tap24e94464-ce detail : {'index': '4', 'name': 'tap24e94464-ce', 'uuid': '24e94464-ced1-45ec-b520-32e8e5a9115e', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN3-22910592:ctest-VN3-22910592', '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': '15', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN3-22910592', 'vm_uuid': 'cd151108-4858-4ae5-9d04-7bad9059f85a', 'vm_name': 'ctest-VM31-71740250', 'ip_addr': '90.99.158.131', 'mac_addr': '02:24:e9:44:64:ce', '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-96935984:24e94464-ced1-45ec-b520-32e8e5a9115e', 'sg_uuid_list': ['736181b7-1d3c-4673-bd91-86e490268178'], 'static_route_list': None, 'vm_project_uuid': '426d4939-0709-4be3-abee-cc1bc4ff250d', 'admin_state': 'Enabled', 'flow_key_idx': '40', 'allowed_address_pair_list': None, 'ip6_addr': '360a:b183:6b03:6b1e:b5b8:76f6: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-12-04 02:50:46,925 - DEBUG - Starting Layer 2 verification in Agent 2025-12-04 02:50:46,936 - DEBUG - Layer 2 path is seen for VM MAC 02:24:e9:44:64:ce in agent 10.0.0.22 2025-12-04 02:50:46,936 - DEBUG - Active layer 2 route in agent is present for VMI tap24e94464-ce 2025-12-04 02:50:46,936 - DEBUG - L2 label(36) matches bw route table and interface table 2025-12-04 02:50:46,946 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN3-22910592 is found in Agent of node 10.0.0.22 2025-12-04 02:50:47,093 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN3-22910592 is consistent in agent 10.0.0.22 2025-12-04 02:50:47,093 - DEBUG - Route for VM IP ['90.99.158.131', '360a:b183:6b03:6b1e:b5b8:76f6:0:3'] is consistent in agent 10.0.0.22 2025-12-04 02:50:47,093 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN3-22910592 verification for VM ctest-VM31-71740250 in Agent 10.0.0.22 passed 2025-12-04 02:50:47,206 - DEBUG - Starting all layer 2 verification in agent 10.0.0.22 2025-12-04 02:50:47,218 - DEBUG - Route for VM MAC 02:24:e9:44:64:ce is consistent in agent 10.0.0.22 2025-12-04 02:50:48,420 - 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=27.4 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.786 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.786/14.113/27.441/13.327 ms') 2025-12-04 02:50:48,420 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-71740250 passed 2025-12-04 02:50:48,420 - INFO - VM ctest-VM31-71740250 verifications in Compute nodes passed 2025-12-04 02:50:48,668 - DEBUG - Validated VM route 90.99.158.131 in vrouter of 10.0.0.22 2025-12-04 02:50:48,673 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0 2025-12-04 02:50:48,673 - INFO - Validated routes of VM ctest-VM31-71740250 in all vrouters 2025-12-04 02:50:49,165 - DEBUG - Starting all layer2 verification in 10.0.0.33 Control Node 2025-12-04 02:50:49,290 - DEBUG - Layer2 route found for VM MAC 02:24:e9:44:64:ce in Control-node 10.0.0.33 2025-12-04 02:50:49,290 - DEBUG - L2 Label for VM ctest-VM31-71740250 same between Control-node 10.0.0.33 and Agent, Expected: 36, Seen: 36 2025-12-04 02:50:49,402 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.33, not supported 2025-12-04 02:50:49,402 - DEBUG - Starting all layer2 verification in 10.0.0.27 Control Node 2025-12-04 02:50:49,524 - DEBUG - Layer2 route found for VM MAC 02:24:e9:44:64:ce in Control-node 10.0.0.27 2025-12-04 02:50:49,524 - DEBUG - L2 Label for VM ctest-VM31-71740250 same between Control-node 10.0.0.27 and Agent, Expected: 36, Seen: 36 2025-12-04 02:50:49,638 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.27, not supported 2025-12-04 02:50:49,638 - INFO - Verification in Control-nodes for VM ctest-VM31-71740250 passed 2025-12-04 02:50:49,638 - DEBUG - Verifying the vm in opserver 2025-12-04 02:50:49,638 - DEBUG - Verifying in collector 10.0.0.23 ... 2025-12-04 02:50:49,638 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine/cd151108-4858-4ae5-9d04-7bad9059f85a?flat 2025-12-04 02:50:49,653 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-96935984:24e94464-ced1-45ec-b520-32e8e5a9115e?flat 2025-12-04 02:50:49,676 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-96935984:24e94464-ced1-45ec-b520-32e8e5a9115e?flat 2025-12-04 02:50:49,695 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-96935984:24e94464-ced1-45ec-b520-32e8e5a9115e?flat 2025-12-04 02:50:49,718 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine/cd151108-4858-4ae5-9d04-7bad9059f85a?flat 2025-12-04 02:50:49,736 - DEBUG - Verifying vm in vn uve 2025-12-04 02:50:49,736 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-96935984:24e94464-ced1-45ec-b520-32e8e5a9115e?flat 2025-12-04 02:50:49,755 - DEBUG - VM uve shows interface as {'uuid': '24e94464-ced1-45ec-b520-32e8e5a9115e', 'mac_address': '02:24:e9:44:64:ce', 'l2_active': True, 'vhostuser_mode': 0, 'port_mirror_enabled': False, 'ip6_address': '360a:b183:6b03:6b1e:b5b8:76f6:0:3', 'fixed_ip4_list': ['90.99.158.131'], 'vn_uuid': '0fdb1540-ce92-4935-b844-40af3d92b8f7', 'vm_name': 'ctest-VM31-71740250', 'fixed_ip6_list': ['360a:b183:6b03:6b1e:b5b8:76f6:0:3'], 'tx_vlan': 65535, 'active': True, 'vm_uuid': 'cd151108-4858-4ae5-9d04-7bad9059f85a', 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN3-22910592', 'ip_address': '90.99.158.131', 'ip6_active': True, 'gateway': '90.99.158.129', '__T': 1764816592100256, 'ip4_active': True, 'rx_vlan': 65535, 'label': 32, 'is_health_check_active': True, 'admin_state': True} 2025-12-04 02:50:49,755 - DEBUG - VM uve shows ip address as ['90.99.158.131', '360a:b183:6b03:6b1e:b5b8:76f6:0:3'] 2025-12-04 02:50:49,755 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN3-22910592 2025-12-04 02:50:49,755 - INFO - Verifying through opserver in 10.0.0.27 2025-12-04 02:50:49,755 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN3-22910592?flat 2025-12-04 02:50:49,777 - DEBUG - expected vm list cd151108-4858-4ae5-9d04-7bad9059f85a 2025-12-04 02:50:49,777 - DEBUG - Extracted vm list ['cd151108-4858-4ae5-9d04-7bad9059f85a'] 2025-12-04 02:50:49,777 - INFO - VM cd151108-4858-4ae5-9d04-7bad9059f85a is present in default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN3-22910592 2025-12-04 02:50:49,777 - DEBUG - Verifying vm in vrouter uve 2025-12-04 02:50:49,778 - DEBUG - Getting info from collector 10.0.0.23.. 2025-12-04 02:50:49,778 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine/cd151108-4858-4ae5-9d04-7bad9059f85a?flat 2025-12-04 02:50:49,791 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-4840-1'] 2025-12-04 02:50:49,791 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4840-1:Compute:contrail-vrouter-agent:0?flat 2025-12-04 02:50:49,827 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4840-1?flat 2025-12-04 02:50:49,858 - DEBUG - VM cd151108-4858-4ae5-9d04-7bad9059f85a is present in vrouter an-jenkins-deploy-platform-ansible-os-4840-1 uve 2025-12-04 02:50:49,858 - DEBUG - Validated that VM ctest-VM31-71740250 is in Vrouter an-jenkins-deploy-platform-ansible-os-4840-1 UVE 2025-12-04 02:50:49,858 - DEBUG - Verifying vm tap interface/vn in vrouter uve 2025-12-04 02:50:49,867 - DEBUG - Expected tap interface of VM uuid cd151108-4858-4ae5-9d04-7bad9059f85a is default-domain:ctest-TestPolicyAclIpv4v6-96935984:24e94464-ced1-45ec-b520-32e8e5a9115e 2025-12-04 02:50:49,867 - DEBUG - Expected VN of VM uuid cd151108-4858-4ae5-9d04-7bad9059f85a is default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN3-22910592 2025-12-04 02:50:49,868 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4840-1:Compute:contrail-vrouter-agent:0?flat 2025-12-04 02:50:49,906 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4840-1?flat 2025-12-04 02:50:49,939 - DEBUG - VM cd151108-4858-4ae5-9d04-7bad9059f85a is present in vrouter an-jenkins-deploy-platform-ansible-os-4840-1 uve 2025-12-04 02:50:49,939 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-96935984:24e94464-ced1-45ec-b520-32e8e5a9115e of vm cd151108-4858-4ae5-9d04-7bad9059f85a is present in vrouter an-jenkins-deploy-platform-ansible-os-4840-1 uve 2025-12-04 02:50:49,939 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN3-22910592 of vm cd151108-4858-4ae5-9d04-7bad9059f85a is present in vrouter an-jenkins-deploy-platform-ansible-os-4840-1 uve 2025-12-04 02:50:49,939 - DEBUG - Verifying the cd151108-4858-4ae5-9d04-7bad9059f85a virtual network link through opserver 10.0.0.27 2025-12-04 02:50:49,939 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines 2025-12-04 02:50:49,953 - DEBUG - VM link and name as {'name': 'cd151108-4858-4ae5-9d04-7bad9059f85a', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-machine/cd151108-4858-4ae5-9d04-7bad9059f85a?flat'} 2025-12-04 02:50:49,953 - DEBUG - Verifying the cd151108-4858-4ae5-9d04-7bad9059f85a virtual network link through opserver 10.0.0.33 2025-12-04 02:50:49,953 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machines 2025-12-04 02:50:49,967 - DEBUG - VM link and name as {'name': 'cd151108-4858-4ae5-9d04-7bad9059f85a', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-machine/cd151108-4858-4ae5-9d04-7bad9059f85a?flat'} 2025-12-04 02:50:49,967 - DEBUG - Verifying the cd151108-4858-4ae5-9d04-7bad9059f85a virtual network link through opserver 10.0.0.23 2025-12-04 02:50:49,967 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machines 2025-12-04 02:50:49,979 - DEBUG - VM link and name as {'name': 'cd151108-4858-4ae5-9d04-7bad9059f85a', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-machine/cd151108-4858-4ae5-9d04-7bad9059f85a?flat'} 2025-12-04 02:50:49,979 - INFO - VM ctest-VM31-71740250 validations in Opserver passed 2025-12-04 02:50:49,979 - INFO - Waiting for VM ctest-VM11-22036895 to be up.. 2025-12-04 02:50:49,979 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-04 02:50:49,979 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/8c984f7b-bb91-497f-aeea-084af478d710 2025-12-04 02:50:49,987 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/30dec441-23d5-4f1a-bb7c-75622fd744a7 2025-12-04 02:50:51,165 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=2.52 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=11.2 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 2.522/6.849/11.177/4.327 ms') 2025-12-04 02:50:51,166 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-22036895 passed 2025-12-04 02:50:51,234 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-04 02:50:51,234 - DEBUG - Waiting to SSH to VM ctest-VM11-22036895, IP 122.130.96.195, Port 22 2025-12-04 02:50:51,303 - 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-12-04 02:50:51,399 - DEBUG - VM ctest-VM11-22036895 is NOT ready for SSH connections, VM status: ACTIVE 2025-12-04 02:50:56,399 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-04 02:50:56,399 - DEBUG - Waiting to SSH to VM ctest-VM11-22036895, IP 122.130.96.195, Port 22 2025-12-04 02:50:56,466 - 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-12-04 02:50:56,567 - DEBUG - VM ctest-VM11-22036895 is NOT ready for SSH connections, VM status: ACTIVE 2025-12-04 02:51:01,568 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-04 02:51:01,568 - DEBUG - Waiting to SSH to VM ctest-VM11-22036895, IP 122.130.96.195, Port 22 2025-12-04 02:51:01,624 - 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-12-04 02:51:01,712 - DEBUG - VM ctest-VM11-22036895 is NOT ready for SSH connections, VM status: ACTIVE 2025-12-04 02:51:06,713 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-04 02:51:06,713 - DEBUG - Waiting to SSH to VM ctest-VM11-22036895, IP 122.130.96.195, Port 22 2025-12-04 02:51:06,883 - DEBUG - VM ctest-VM11-22036895 is ready for SSH connections 2025-12-04 02:51:06,883 - INFO - Waiting for VM ctest-VM21-71358464 to be up.. 2025-12-04 02:51:06,883 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-04 02:51:06,883 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 2025-12-04 02:51:06,893 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/2c811150-d82b-49e9-a09a-0cc9370d65e1 2025-12-04 02:51:08,075 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=3.34 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.51 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 2.514/2.925/3.336/0.411 ms') 2025-12-04 02:51:08,075 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-71358464 passed 2025-12-04 02:51:08,142 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-04 02:51:08,142 - DEBUG - Waiting to SSH to VM ctest-VM21-71358464, IP 121.143.55.3, Port 22 2025-12-04 02:51:08,319 - DEBUG - VM ctest-VM21-71358464 is ready for SSH connections 2025-12-04 02:51:08,319 - INFO - Waiting for VM ctest-VM31-71740250 to be up.. 2025-12-04 02:51:08,320 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-04 02:51:08,320 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/cd151108-4858-4ae5-9d04-7bad9059f85a 2025-12-04 02:51:08,328 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/24e94464-ced1-45ec-b520-32e8e5a9115e 2025-12-04 02:51:09,500 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=4.34 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.596 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1003ms\r\nrtt min/avg/max/mdev = 0.596/2.469/4.343/1.873 ms') 2025-12-04 02:51:09,501 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-71740250 passed 2025-12-04 02:51:09,572 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-04 02:51:09,572 - DEBUG - Waiting to SSH to VM ctest-VM31-71740250, IP 90.99.158.131, Port 22 2025-12-04 02:51:09,738 - DEBUG - VM ctest-VM31-71740250 is ready for SSH connections 2025-12-04 02:51:09,738 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 121.143.55.3, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.22, gateway password: c0ntrail123 2025-12-04 02:51:09,738 - DEBUG - ping -s 56 -c 3 -W 1 121.143.55.3 2025-12-04 02:51:32,811 - DEBUG - PING 121.143.55.3 (121.143.55.3) 56(84) bytes of data. 64 bytes from 121.143.55.3: icmp_req=1 ttl=63 time=8.38 ms 64 bytes from 121.143.55.3: icmp_req=2 ttl=63 time=1.39 ms 64 bytes from 121.143.55.3: icmp_req=3 ttl=63 time=1.18 ms --- 121.143.55.3 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2003ms rtt min/avg/max/mdev = 1.184/3.653/8.386/3.348 ms 2025-12-04 02:51:32,811 - INFO - Ping to IP 121.143.55.3 from VM ctest-VM11-22036895 passed 2025-12-04 02:51:32,811 - DEBUG - Running remote_cmd, Cmd : ping6 -s 56 -c 3 -W 1 361d:c888:f70b:ad59:26d1:3ac0:0:3, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.22, gateway password: c0ntrail123 2025-12-04 02:51:32,811 - DEBUG - ping6 -s 56 -c 3 -W 1 361d:c888:f70b:ad59:26d1:3ac0:0:3 2025-12-04 02:51:35,412 - DEBUG - PING 361d:c888:f70b:ad59:26d1:3ac0:0:3(361d:c888:f70b:ad59:26d1:3ac0:0:3) 56 data bytes 64 bytes from 361d:c888:f70b:ad59:26d1:3ac0:0:3: icmp_seq=1 ttl=64 time=9.96 ms 64 bytes from 361d:c888:f70b:ad59:26d1:3ac0:0:3: icmp_seq=2 ttl=64 time=1.59 ms 64 bytes from 361d:c888:f70b:ad59:26d1:3ac0:0:3: icmp_seq=3 ttl=64 time=1.77 ms --- 361d:c888:f70b:ad59:26d1:3ac0:0:3 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2004ms rtt min/avg/max/mdev = 1.592/4.443/9.964/3.904 ms 2025-12-04 02:51:35,412 - INFO - Ping to IP 361d:c888:f70b:ad59:26d1:3ac0:0:3 from VM ctest-VM11-22036895 passed 2025-12-04 02:51:35,412 - INFO - Test with src as policy and dst as any PASSED 2025-12-04 02:51:35,412 - INFO - Deleting VM ctest-VM31-71740250 2025-12-04 02:51:35,491 - DEBUG - Verifying in api server 10.0.0.23 2025-12-04 02:51:35,491 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/cd151108-4858-4ae5-9d04-7bad9059f85a 2025-12-04 02:51:35,499 - DEBUG - VM ID cd151108-4858-4ae5-9d04-7bad9059f85a of VM ctest-VM31-71740250 is still found in API Server 2025-12-04 02:51:37,500 - DEBUG - Verifying in api server 10.0.0.23 2025-12-04 02:51:37,501 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/cd151108-4858-4ae5-9d04-7bad9059f85a 2025-12-04 02:51:37,507 - DEBUG - Response Code: 404 2025-12-04 02:51:37,507 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/cd151108-4858-4ae5-9d04-7bad9059f85a 2025-12-04 02:51:37,513 - DEBUG - Response Code: 404 2025-12-04 02:51:37,513 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/cd151108-4858-4ae5-9d04-7bad9059f85a 2025-12-04 02:51:37,518 - DEBUG - Response Code: 404 2025-12-04 02:51:37,518 - INFO - VM ctest-VM31-71740250 is fully removed in API-Server 2025-12-04 02:51:37,672 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found 2025-12-04 02:51:37,718 - DEBUG - Validated that vrouter 10.0.0.22 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN3-22910592 2025-12-04 02:51:37,731 - DEBUG - Validated that vrouter 10.0.0.130 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN3-22910592 2025-12-04 02:51:37,731 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN3-22910592 2025-12-04 02:51:37,731 - INFO - VM ctest-VM31-71740250 is removed in Compute, and routes are removed in all compute nodes 2025-12-04 02:51:37,963 - INFO - Routes for VM ctest-VM31-71740250 is removed in all control-nodes 2025-12-04 02:51:37,963 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4840-1:Compute:contrail-vrouter-agent:0?flat 2025-12-04 02:51:37,997 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4840-1?flat 2025-12-04 02:51:38,027 - DEBUG - VM cd151108-4858-4ae5-9d04-7bad9059f85a is still present in vrouter an-jenkins-deploy-platform-ansible-os-4840-1 uve 2025-12-04 02:51:38,027 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-12-04 02:51:38,027 - DEBUG - interface for vm cd151108-4858-4ae5-9d04-7bad9059f85a deleted from vrouter uve ... 2025-12-04 02:51:38,027 - DEBUG - Deleted interface not in error interface list ... 2025-12-04 02:51:41,028 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4840-1:Compute:contrail-vrouter-agent:0?flat 2025-12-04 02:51:41,065 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4840-1?flat 2025-12-04 02:51:41,097 - DEBUG - VM cd151108-4858-4ae5-9d04-7bad9059f85a is still present in vrouter an-jenkins-deploy-platform-ansible-os-4840-1 uve 2025-12-04 02:51:41,097 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-12-04 02:51:41,097 - DEBUG - interface for vm cd151108-4858-4ae5-9d04-7bad9059f85a deleted from vrouter uve ... 2025-12-04 02:51:41,097 - DEBUG - Deleted interface not in error interface list ... 2025-12-04 02:51:44,098 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4840-1:Compute:contrail-vrouter-agent:0?flat 2025-12-04 02:51:44,136 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4840-1?flat 2025-12-04 02:51:44,169 - DEBUG - VM cd151108-4858-4ae5-9d04-7bad9059f85a is still present in vrouter an-jenkins-deploy-platform-ansible-os-4840-1 uve 2025-12-04 02:51:44,169 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-12-04 02:51:44,169 - DEBUG - interface for vm cd151108-4858-4ae5-9d04-7bad9059f85a deleted from vrouter uve ... 2025-12-04 02:51:44,169 - DEBUG - Deleted interface not in error interface list ... 2025-12-04 02:51:47,170 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4840-1:Compute:contrail-vrouter-agent:0?flat 2025-12-04 02:51:47,209 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4840-1?flat 2025-12-04 02:51:47,240 - DEBUG - VM cd151108-4858-4ae5-9d04-7bad9059f85a is still present in vrouter an-jenkins-deploy-platform-ansible-os-4840-1 uve 2025-12-04 02:51:47,240 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-12-04 02:51:47,240 - DEBUG - interface for vm cd151108-4858-4ae5-9d04-7bad9059f85a deleted from vrouter uve ... 2025-12-04 02:51:47,240 - DEBUG - Deleted interface not in error interface list ... 2025-12-04 02:51:50,241 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4840-1:Compute:contrail-vrouter-agent:0?flat 2025-12-04 02:51:50,279 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4840-1?flat 2025-12-04 02:51:50,311 - DEBUG - VM cd151108-4858-4ae5-9d04-7bad9059f85a is still present in vrouter an-jenkins-deploy-platform-ansible-os-4840-1 uve 2025-12-04 02:51:50,311 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-12-04 02:51:50,311 - DEBUG - interface for vm cd151108-4858-4ae5-9d04-7bad9059f85a deleted from vrouter uve ... 2025-12-04 02:51:50,311 - DEBUG - Deleted interface not in error interface list ... 2025-12-04 02:51:53,312 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4840-1:Compute:contrail-vrouter-agent:0?flat 2025-12-04 02:51:53,356 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4840-1?flat 2025-12-04 02:51:53,396 - DEBUG - VM cd151108-4858-4ae5-9d04-7bad9059f85a is not present in vrouter an-jenkins-deploy-platform-ansible-os-4840-1 uve 2025-12-04 02:51:53,396 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-12-04 02:51:53,396 - DEBUG - interface for vm cd151108-4858-4ae5-9d04-7bad9059f85a deleted from vrouter uve ... 2025-12-04 02:51:53,396 - DEBUG - Deleted interface not in error interface list ... 2025-12-04 02:51:53,396 - DEBUG - Validated that VM cd151108-4858-4ae5-9d04-7bad9059f85a is removed in Vrouter UVE 2025-12-04 02:51:53,396 - DEBUG - Verifying through opserver in 10.0.0.27 2025-12-04 02:51:53,396 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN3-22910592?flat 2025-12-04 02:51:53,418 - DEBUG - cd151108-4858-4ae5-9d04-7bad9059f85a BM not in default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN3-22910592 uve 2025-12-04 02:51:53,418 - DEBUG - Validated that VM cd151108-4858-4ae5-9d04-7bad9059f85a is not present in the VN UVE 2025-12-04 02:51:53,418 - DEBUG - Verifying the cd151108-4858-4ae5-9d04-7bad9059f85a virtual network link through opserver 10.0.0.27 2025-12-04 02:51:53,418 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines 2025-12-04 02:51:53,431 - INFO - cd151108-4858-4ae5-9d04-7bad9059f85a vm uve deleted from opserver 2025-12-04 02:51:53,431 - DEBUG - Verifying the cd151108-4858-4ae5-9d04-7bad9059f85a virtual network link through opserver 10.0.0.33 2025-12-04 02:51:53,431 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machines 2025-12-04 02:51:53,445 - INFO - cd151108-4858-4ae5-9d04-7bad9059f85a vm uve deleted from opserver 2025-12-04 02:51:53,445 - DEBUG - Verifying the cd151108-4858-4ae5-9d04-7bad9059f85a virtual network link through opserver 10.0.0.23 2025-12-04 02:51:53,445 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machines 2025-12-04 02:51:53,462 - INFO - cd151108-4858-4ae5-9d04-7bad9059f85a vm uve deleted from opserver 2025-12-04 02:51:53,462 - INFO - Validated that VM cd151108-4858-4ae5-9d04-7bad9059f85a is removed from Opserver 2025-12-04 02:51:53,462 - INFO - Deleting VM ctest-VM21-71358464 2025-12-04 02:51:53,548 - DEBUG - Verifying in api server 10.0.0.23 2025-12-04 02:51:53,548 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 2025-12-04 02:51:53,558 - DEBUG - VM ID 7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 of VM ctest-VM21-71358464 is still found in API Server 2025-12-04 02:51:55,558 - DEBUG - Verifying in api server 10.0.0.23 2025-12-04 02:51:55,558 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 2025-12-04 02:51:55,566 - DEBUG - Response Code: 404 2025-12-04 02:51:55,566 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 2025-12-04 02:51:55,572 - DEBUG - Response Code: 404 2025-12-04 02:51:55,573 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 2025-12-04 02:51:55,578 - DEBUG - Response Code: 404 2025-12-04 02:51:55,578 - INFO - VM ctest-VM21-71358464 is fully removed in API-Server 2025-12-04 02:51:55,733 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found 2025-12-04 02:51:55,786 - DEBUG - Validated that vrouter 10.0.0.22 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN2-21473336 2025-12-04 02:51:55,800 - DEBUG - Validated that vrouter 10.0.0.130 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN2-21473336 2025-12-04 02:51:55,800 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN2-21473336 2025-12-04 02:51:55,800 - INFO - VM ctest-VM21-71358464 is removed in Compute, and routes are removed in all compute nodes 2025-12-04 02:51:56,036 - INFO - Routes for VM ctest-VM21-71358464 is removed in all control-nodes 2025-12-04 02:51:56,036 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4840-2:Compute:contrail-vrouter-agent:0?flat 2025-12-04 02:51:56,074 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4840-2?flat 2025-12-04 02:51:56,112 - DEBUG - VM 7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4840-2 uve 2025-12-04 02:51:56,112 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-12-04 02:51:56,112 - DEBUG - interface for vm 7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 deleted from vrouter uve ... 2025-12-04 02:51:59,113 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4840-2:Compute:contrail-vrouter-agent:0?flat 2025-12-04 02:51:59,148 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4840-2?flat 2025-12-04 02:51:59,178 - DEBUG - VM 7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4840-2 uve 2025-12-04 02:51:59,178 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-12-04 02:51:59,178 - DEBUG - interface for vm 7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 deleted from vrouter uve ... 2025-12-04 02:52:02,178 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4840-2:Compute:contrail-vrouter-agent:0?flat 2025-12-04 02:52:02,212 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4840-2?flat 2025-12-04 02:52:02,241 - DEBUG - VM 7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4840-2 uve 2025-12-04 02:52:02,241 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-12-04 02:52:02,241 - DEBUG - interface for vm 7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 deleted from vrouter uve ... 2025-12-04 02:52:05,242 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4840-2:Compute:contrail-vrouter-agent:0?flat 2025-12-04 02:52:05,275 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4840-2?flat 2025-12-04 02:52:05,317 - DEBUG - VM 7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4840-2 uve 2025-12-04 02:52:05,317 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-12-04 02:52:05,317 - DEBUG - interface for vm 7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 deleted from vrouter uve ... 2025-12-04 02:52:08,318 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4840-2:Compute:contrail-vrouter-agent:0?flat 2025-12-04 02:52:08,352 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4840-2?flat 2025-12-04 02:52:08,386 - DEBUG - VM 7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4840-2 uve 2025-12-04 02:52:08,386 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-12-04 02:52:08,386 - DEBUG - interface for vm 7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 deleted from vrouter uve ... 2025-12-04 02:52:11,387 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4840-2:Compute:contrail-vrouter-agent:0?flat 2025-12-04 02:52:11,436 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4840-2?flat 2025-12-04 02:52:11,473 - DEBUG - VM 7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4840-2 uve 2025-12-04 02:52:11,473 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-12-04 02:52:11,473 - DEBUG - interface for vm 7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 deleted from vrouter uve ... 2025-12-04 02:52:14,474 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4840-2:Compute:contrail-vrouter-agent:0?flat 2025-12-04 02:52:14,511 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4840-2?flat 2025-12-04 02:52:14,545 - DEBUG - VM 7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4840-2 uve 2025-12-04 02:52:14,545 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-12-04 02:52:14,545 - DEBUG - interface for vm 7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 deleted from vrouter uve ... 2025-12-04 02:52:17,546 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4840-2:Compute:contrail-vrouter-agent:0?flat 2025-12-04 02:52:17,580 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4840-2?flat 2025-12-04 02:52:17,610 - DEBUG - VM 7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4840-2 uve 2025-12-04 02:52:17,610 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-12-04 02:52:17,611 - DEBUG - interface for vm 7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 deleted from vrouter uve ... 2025-12-04 02:52:20,611 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4840-2:Compute:contrail-vrouter-agent:0?flat 2025-12-04 02:52:20,645 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4840-2?flat 2025-12-04 02:52:20,678 - DEBUG - VM 7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4840-2 uve 2025-12-04 02:52:20,678 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-12-04 02:52:20,678 - DEBUG - interface for vm 7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 deleted from vrouter uve ... 2025-12-04 02:52:23,678 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4840-2:Compute:contrail-vrouter-agent:0?flat 2025-12-04 02:52:23,717 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4840-2?flat 2025-12-04 02:52:23,749 - DEBUG - VM 7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4840-2 uve 2025-12-04 02:52:23,749 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-12-04 02:52:23,749 - DEBUG - interface for vm 7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 deleted from vrouter uve ... 2025-12-04 02:52:26,750 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4840-2:Compute:contrail-vrouter-agent:0?flat 2025-12-04 02:52:26,784 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4840-2?flat 2025-12-04 02:52:26,813 - DEBUG - VM 7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 is not present in vrouter an-jenkins-deploy-platform-ansible-os-4840-2 uve 2025-12-04 02:52:26,813 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-12-04 02:52:26,813 - DEBUG - interface for vm 7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 deleted from vrouter uve ... 2025-12-04 02:52:26,813 - DEBUG - Deleted interface not in error interface list ... 2025-12-04 02:52:26,813 - DEBUG - Validated that VM 7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 is removed in Vrouter UVE 2025-12-04 02:52:26,813 - DEBUG - Verifying through opserver in 10.0.0.27 2025-12-04 02:52:26,813 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN2-21473336?flat 2025-12-04 02:52:26,832 - DEBUG - 7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 BM not in default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN2-21473336 uve 2025-12-04 02:52:26,832 - DEBUG - Validated that VM 7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 is not present in the VN UVE 2025-12-04 02:52:26,832 - DEBUG - Verifying the 7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 virtual network link through opserver 10.0.0.27 2025-12-04 02:52:26,832 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines 2025-12-04 02:52:26,845 - INFO - 7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 vm uve deleted from opserver 2025-12-04 02:52:26,845 - DEBUG - Verifying the 7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 virtual network link through opserver 10.0.0.33 2025-12-04 02:52:26,845 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machines 2025-12-04 02:52:26,858 - INFO - 7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 vm uve deleted from opserver 2025-12-04 02:52:26,858 - DEBUG - Verifying the 7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 virtual network link through opserver 10.0.0.23 2025-12-04 02:52:26,858 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machines 2025-12-04 02:52:26,874 - INFO - 7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 vm uve deleted from opserver 2025-12-04 02:52:26,874 - INFO - Validated that VM 7f23b5c5-47a9-4b16-82f8-1c3531ea84b1 is removed from Opserver 2025-12-04 02:52:26,874 - INFO - Deleting VM ctest-VM11-22036895 2025-12-04 02:52:26,946 - DEBUG - Verifying in api server 10.0.0.23 2025-12-04 02:52:26,946 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/8c984f7b-bb91-497f-aeea-084af478d710 2025-12-04 02:52:26,955 - DEBUG - VM ID 8c984f7b-bb91-497f-aeea-084af478d710 of VM ctest-VM11-22036895 is still found in API Server 2025-12-04 02:52:28,956 - DEBUG - Verifying in api server 10.0.0.23 2025-12-04 02:52:28,956 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/8c984f7b-bb91-497f-aeea-084af478d710 2025-12-04 02:52:28,963 - DEBUG - Response Code: 404 2025-12-04 02:52:28,963 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/8c984f7b-bb91-497f-aeea-084af478d710 2025-12-04 02:52:28,969 - DEBUG - Response Code: 404 2025-12-04 02:52:28,969 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/8c984f7b-bb91-497f-aeea-084af478d710 2025-12-04 02:52:28,974 - DEBUG - Response Code: 404 2025-12-04 02:52:28,974 - INFO - VM ctest-VM11-22036895 is fully removed in API-Server 2025-12-04 02:52:29,136 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found 2025-12-04 02:52:29,180 - DEBUG - Validated that vrouter 10.0.0.22 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990 2025-12-04 02:52:29,191 - DEBUG - Validated that vrouter 10.0.0.130 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990 2025-12-04 02:52:29,191 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990 2025-12-04 02:52:29,192 - INFO - VM ctest-VM11-22036895 is removed in Compute, and routes are removed in all compute nodes 2025-12-04 02:52:29,380 - INFO - Routes for VM ctest-VM11-22036895 is removed in all control-nodes 2025-12-04 02:52:29,380 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4840-1:Compute:contrail-vrouter-agent:0?flat 2025-12-04 02:52:29,424 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4840-1?flat 2025-12-04 02:52:29,455 - DEBUG - VM 8c984f7b-bb91-497f-aeea-084af478d710 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4840-1 uve 2025-12-04 02:52:29,455 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-12-04 02:52:29,456 - DEBUG - interface for vm 8c984f7b-bb91-497f-aeea-084af478d710 deleted from vrouter uve ... 2025-12-04 02:52:29,456 - DEBUG - Deleted interface not in error interface list ... 2025-12-04 02:52:32,456 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4840-1:Compute:contrail-vrouter-agent:0?flat 2025-12-04 02:52:32,490 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4840-1?flat 2025-12-04 02:52:32,521 - DEBUG - VM 8c984f7b-bb91-497f-aeea-084af478d710 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4840-1 uve 2025-12-04 02:52:32,521 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-12-04 02:52:32,521 - DEBUG - interface for vm 8c984f7b-bb91-497f-aeea-084af478d710 deleted from vrouter uve ... 2025-12-04 02:52:32,521 - DEBUG - Deleted interface not in error interface list ... 2025-12-04 02:52:35,522 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4840-1:Compute:contrail-vrouter-agent:0?flat 2025-12-04 02:52:35,562 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4840-1?flat 2025-12-04 02:52:35,593 - DEBUG - VM 8c984f7b-bb91-497f-aeea-084af478d710 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4840-1 uve 2025-12-04 02:52:35,594 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-12-04 02:52:35,594 - DEBUG - interface for vm 8c984f7b-bb91-497f-aeea-084af478d710 deleted from vrouter uve ... 2025-12-04 02:52:35,594 - DEBUG - Deleted interface not in error interface list ... 2025-12-04 02:52:38,594 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4840-1:Compute:contrail-vrouter-agent:0?flat 2025-12-04 02:52:38,639 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4840-1?flat 2025-12-04 02:52:38,675 - DEBUG - VM 8c984f7b-bb91-497f-aeea-084af478d710 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4840-1 uve 2025-12-04 02:52:38,675 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-12-04 02:52:38,675 - DEBUG - interface for vm 8c984f7b-bb91-497f-aeea-084af478d710 deleted from vrouter uve ... 2025-12-04 02:52:38,675 - DEBUG - Deleted interface not in error interface list ... 2025-12-04 02:52:41,676 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4840-1:Compute:contrail-vrouter-agent:0?flat 2025-12-04 02:52:41,713 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4840-1?flat 2025-12-04 02:52:41,745 - DEBUG - VM 8c984f7b-bb91-497f-aeea-084af478d710 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4840-1 uve 2025-12-04 02:52:41,745 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-12-04 02:52:41,745 - DEBUG - interface for vm 8c984f7b-bb91-497f-aeea-084af478d710 deleted from vrouter uve ... 2025-12-04 02:52:41,745 - DEBUG - Deleted interface not in error interface list ... 2025-12-04 02:52:44,746 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4840-1:Compute:contrail-vrouter-agent:0?flat 2025-12-04 02:52:44,781 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4840-1?flat 2025-12-04 02:52:44,813 - DEBUG - VM 8c984f7b-bb91-497f-aeea-084af478d710 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4840-1 uve 2025-12-04 02:52:44,813 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-12-04 02:52:44,813 - DEBUG - interface for vm 8c984f7b-bb91-497f-aeea-084af478d710 deleted from vrouter uve ... 2025-12-04 02:52:44,813 - DEBUG - Deleted interface not in error interface list ... 2025-12-04 02:52:47,814 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4840-1:Compute:contrail-vrouter-agent:0?flat 2025-12-04 02:52:47,851 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4840-1?flat 2025-12-04 02:52:47,882 - DEBUG - VM 8c984f7b-bb91-497f-aeea-084af478d710 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4840-1 uve 2025-12-04 02:52:47,882 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-12-04 02:52:47,882 - DEBUG - interface for vm 8c984f7b-bb91-497f-aeea-084af478d710 deleted from vrouter uve ... 2025-12-04 02:52:47,882 - DEBUG - Deleted interface not in error interface list ... 2025-12-04 02:52:50,882 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4840-1:Compute:contrail-vrouter-agent:0?flat 2025-12-04 02:52:50,921 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4840-1?flat 2025-12-04 02:52:50,953 - DEBUG - VM 8c984f7b-bb91-497f-aeea-084af478d710 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4840-1 uve 2025-12-04 02:52:50,953 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-12-04 02:52:50,953 - DEBUG - interface for vm 8c984f7b-bb91-497f-aeea-084af478d710 deleted from vrouter uve ... 2025-12-04 02:52:50,953 - DEBUG - Deleted interface not in error interface list ... 2025-12-04 02:52:53,954 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4840-1:Compute:contrail-vrouter-agent:0?flat 2025-12-04 02:52:54,000 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4840-1?flat 2025-12-04 02:52:54,048 - DEBUG - VM 8c984f7b-bb91-497f-aeea-084af478d710 is not present in vrouter an-jenkins-deploy-platform-ansible-os-4840-1 uve 2025-12-04 02:52:54,048 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-12-04 02:52:54,048 - DEBUG - interface for vm 8c984f7b-bb91-497f-aeea-084af478d710 deleted from vrouter uve ... 2025-12-04 02:52:54,048 - DEBUG - Deleted interface not in error interface list ... 2025-12-04 02:52:54,048 - DEBUG - Validated that VM 8c984f7b-bb91-497f-aeea-084af478d710 is removed in Vrouter UVE 2025-12-04 02:52:54,048 - DEBUG - Verifying through opserver in 10.0.0.27 2025-12-04 02:52:54,048 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990?flat 2025-12-04 02:52:54,070 - DEBUG - 8c984f7b-bb91-497f-aeea-084af478d710 BM not in default-domain:ctest-TestPolicyAclIpv4v6-96935984:ctest-VN1-90331990 uve 2025-12-04 02:52:54,070 - DEBUG - Validated that VM 8c984f7b-bb91-497f-aeea-084af478d710 is not present in the VN UVE 2025-12-04 02:52:54,070 - DEBUG - Verifying the 8c984f7b-bb91-497f-aeea-084af478d710 virtual network link through opserver 10.0.0.27 2025-12-04 02:52:54,070 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines 2025-12-04 02:52:54,084 - DEBUG - No links retuned for 8c984f7b-bb91-497f-aeea-084af478d710 2025-12-04 02:52:54,084 - INFO - 8c984f7b-bb91-497f-aeea-084af478d710 vm uve deleted from opserver 2025-12-04 02:52:54,084 - DEBUG - Verifying the 8c984f7b-bb91-497f-aeea-084af478d710 virtual network link through opserver 10.0.0.33 2025-12-04 02:52:54,084 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machines 2025-12-04 02:52:54,096 - DEBUG - No links retuned for 8c984f7b-bb91-497f-aeea-084af478d710 2025-12-04 02:52:54,096 - INFO - 8c984f7b-bb91-497f-aeea-084af478d710 vm uve deleted from opserver 2025-12-04 02:52:54,096 - DEBUG - Verifying the 8c984f7b-bb91-497f-aeea-084af478d710 virtual network link through opserver 10.0.0.23 2025-12-04 02:52:54,096 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machines 2025-12-04 02:52:54,109 - DEBUG - No links retuned for 8c984f7b-bb91-497f-aeea-084af478d710 2025-12-04 02:52:54,109 - INFO - 8c984f7b-bb91-497f-aeea-084af478d710 vm uve deleted from opserver 2025-12-04 02:52:54,109 - INFO - Validated that VM 8c984f7b-bb91-497f-aeea-084af478d710 is removed from Opserver 2025-12-04 02:52:54,109 - DEBUG - Detaching the Policy for VN :ctest-VN2-21473336 2025-12-04 02:52:54,109 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2025-12-04 02:52:54,116 - DEBUG - Requesting: http://10.0.0.27:8082/domain/77f3106b-db9e-49b5-893e-09cac574b890 2025-12-04 02:52:54,161 - DEBUG - Requesting: http://10.0.0.27:8082/project/426d4939-0709-4be3-abee-cc1bc4ff250d 2025-12-04 02:52:54,279 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/582a0f66-d2a7-4feb-a8da-af1256d2ad04 2025-12-04 02:52:54,298 - DEBUG - Requesting: http://10.0.0.27:8082/network-policy/3288a3c5-c90c-46fb-a7c9-9577e1cfeef2 2025-12-04 02:52:54,516 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-96935984', 'ctest-policy21-60317777']] from ctest-VN2-21473336 2025-12-04 02:52:54,517 - DEBUG - Detaching the Policy for VN :ctest-VN1-90331990 2025-12-04 02:52:54,517 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2025-12-04 02:52:54,530 - DEBUG - Requesting: http://10.0.0.27:8082/domain/77f3106b-db9e-49b5-893e-09cac574b890 2025-12-04 02:52:54,558 - DEBUG - Requesting: http://10.0.0.27:8082/project/426d4939-0709-4be3-abee-cc1bc4ff250d 2025-12-04 02:52:54,649 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/8e73c634-f829-4cbc-9fa4-0a17a2c22823 2025-12-04 02:52:54,679 - DEBUG - Requesting: http://10.0.0.27:8082/network-policy/ef55e3b8-7934-4986-ba9d-c098e34c6280 2025-12-04 02:52:54,689 - DEBUG - Requesting: http://10.0.0.27:8082/network-policy/67c42c25-e0a7-4e82-ae30-551e6bfc03e9 2025-12-04 02:52:54,901 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-96935984', 'ctest-policy12-83993246'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-96935984', 'ctest-policy13-06389220']] from ctest-VN1-90331990 2025-12-04 02:52:54,949 - INFO - Deleted policy ctest-policy21-60317777 2025-12-04 02:52:54,996 - INFO - Deleted policy ctest-policy12-83993246 2025-12-04 02:52:55,048 - INFO - Deleted policy ctest-policy13-06389220 2025-12-04 02:52:55,048 - INFO - Deleting VN ctest-VN3-22910592 2025-12-04 02:52:55,281 - DEBUG - Response for deleting network () 2025-12-04 02:52:55,281 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/5b9c1ca6-635c-4064-a920-d3559a8ec092 2025-12-04 02:52:55,288 - DEBUG - Response Code: 404 2025-12-04 02:52:55,288 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2025-12-04 02:52:55,295 - DEBUG - Requesting: http://10.0.0.27:8082/domain/77f3106b-db9e-49b5-893e-09cac574b890 2025-12-04 02:52:55,324 - DEBUG - Requesting: http://10.0.0.27:8082/project/426d4939-0709-4be3-abee-cc1bc4ff250d 2025-12-04 02:52:55,462 - INFO - Validated that VN ctest-VN3-22910592 is not found in API Server 2025-12-04 02:52:55,477 - DEBUG - VN ctest-VN3-22910592 is not present in Agent 10.0.0.22 2025-12-04 02:52:55,490 - DEBUG - VN ctest-VN3-22910592 is not present in Agent 10.0.0.130 2025-12-04 02:52:55,490 - INFO - Validated that VN ctest-VN3-22910592 is not in any agent 2025-12-04 02:52:55,536 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN3-22910592 info 2025-12-04 02:52:55,536 - INFO - Deleting VN ctest-VN2-21473336 2025-12-04 02:52:55,739 - DEBUG - Response for deleting network () 2025-12-04 02:52:55,740 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/b9de6e35-ff41-4625-8ddf-f524f29a6f8e 2025-12-04 02:52:55,749 - DEBUG - Response Code: 404 2025-12-04 02:52:55,749 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2025-12-04 02:52:55,756 - DEBUG - Requesting: http://10.0.0.27:8082/domain/77f3106b-db9e-49b5-893e-09cac574b890 2025-12-04 02:52:55,771 - DEBUG - Requesting: http://10.0.0.27:8082/project/426d4939-0709-4be3-abee-cc1bc4ff250d 2025-12-04 02:52:55,894 - INFO - Validated that VN ctest-VN2-21473336 is not found in API Server 2025-12-04 02:52:55,913 - DEBUG - VN ctest-VN2-21473336 is not present in Agent 10.0.0.22 2025-12-04 02:52:55,929 - DEBUG - VN ctest-VN2-21473336 is not present in Agent 10.0.0.130 2025-12-04 02:52:55,929 - INFO - Validated that VN ctest-VN2-21473336 is not in any agent 2025-12-04 02:52:55,976 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN2-21473336 info 2025-12-04 02:52:55,978 - INFO - Deleting VN ctest-VN1-90331990 2025-12-04 02:52:56,264 - DEBUG - Response for deleting network () 2025-12-04 02:52:56,264 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/5b500866-2828-497b-b93f-97d4b2e4c7a7 2025-12-04 02:52:56,271 - DEBUG - Response Code: 404 2025-12-04 02:52:56,272 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2025-12-04 02:52:56,279 - DEBUG - Requesting: http://10.0.0.27:8082/domain/77f3106b-db9e-49b5-893e-09cac574b890 2025-12-04 02:52:56,337 - DEBUG - Requesting: http://10.0.0.27:8082/project/426d4939-0709-4be3-abee-cc1bc4ff250d 2025-12-04 02:52:56,443 - INFO - Validated that VN ctest-VN1-90331990 is not found in API Server 2025-12-04 02:52:56,454 - DEBUG - VN ctest-VN1-90331990 is not present in Agent 10.0.0.22 2025-12-04 02:52:56,466 - DEBUG - VN ctest-VN1-90331990 is not present in Agent 10.0.0.130 2025-12-04 02:52:56,466 - INFO - Validated that VN ctest-VN1-90331990 is not in any agent 2025-12-04 02:52:56,503 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN1-90331990 info 2025-12-04 02:52:56,597 - INFO - IPAM: ipam3 is not found in API Server 2025-12-04 02:52:56,619 - INFO - IPAM:ipam3 is not found in control node 2025-12-04 02:52:56,757 - INFO - IPAM: ipam2 is not found in API Server 2025-12-04 02:52:56,783 - INFO - IPAM:ipam2 is not found in control node 2025-12-04 02:52:56,941 - INFO - IPAM: ipam1 is not found in API Server 2025-12-04 02:52:56,963 - INFO - IPAM:ipam1 is not found in control node 2025-12-04 02:52:58,250 - DEBUG - No XMPP flaps were noticed during the test
2025-12-04 02:52:58,250 - INFO - END TEST : test_policy_inheritance_src_pol_dst_any : PASSED[0:03:26]
2025-12-04 02:52:58,250 - INFO - -------------------------------------------------------------------------------- 2025-12-04 02:52:59,530 - DEBUG - No XMPP flaps were noticed during the test
2025-12-04 02:52:59,530 - INFO - END TEST : test_policy_inheritance_src_pol_dst_any : PASSED[0:03:28]
2025-12-04 02:52:59,530 - INFO - -------------------------------------------------------------------------------- 2025-12-04 02:53:00,267 - INFO - Deleted project: ctest-TestPolicyAclIpv4v6-96935984, ID : 426d4939-0709-4be3-abee-cc1bc4ff250d