2025-09-04 02:35:43,124 - INFO - Domain Default found not creating 2025-09-04 02:35:43,270 - INFO - Project ctest-TestPolicyAclIpv4v6-16254764 not found, creating it 2025-09-04 02:35:43,847 - INFO - Created Project:ctest-TestPolicyAclIpv4v6-16254764, ID : d3251d2d-fd0b-4ab3-a150-7787310badda 2025-09-04 02:35:46,108 - INFO - ================================================================================ 2025-09-04 02:35:46,108 - INFO - STARTING TEST : test_policy_inheritance_src_pol_dst_any 2025-09-04 02:35:47,385 - DEBUG - Nothing to compare xmpp stats {'10.0.0.241': {'10.20.0.25': '0', '10.20.0.254': '0'}, '10.0.0.242': {'10.20.0.25': '0', '10.20.0.254': '0'}} with 2025-09-04 02:35:47,386 - INFO - Initial checks done. Running the testcase now 2025-09-04 02:35:47,386 - INFO - 2025-09-04 02:35:47,386 - INFO - ================================================================================ 2025-09-04 02:35:47,386 - INFO - STARTING TEST : test_policy_inheritance_src_pol_dst_any 2025-09-04 02:35:47,386 - INFO - TEST DESCRIPTION : Test cases to test policy inheritance 2025-09-04 02:35:48,673 - DEBUG - Nothing to compare xmpp stats {'10.0.0.241': {'10.20.0.25': '0', '10.20.0.254': '0'}, '10.0.0.242': {'10.20.0.25': '0', '10.20.0.254': '0'}} with 2025-09-04 02:35:48,673 - INFO - Initial checks done. Running the testcase now 2025-09-04 02:35:48,673 - INFO - 2025-09-04 02:35:49,972 - DEBUG - Requesting: http://10.0.0.57:8082/domains 2025-09-04 02:35:50,270 - DEBUG - Requesting: http://10.0.0.57:8082/domains 2025-09-04 02:35:50,299 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c 2025-09-04 02:35:50,314 - DEBUG - Requesting: http://10.0.0.57:8082/project/d3251d2d-fd0b-4ab3-a150-7787310badda 2025-09-04 02:35:50,418 - DEBUG - Requesting: http://10.0.0.57:8082/network-ipam/095f811c-c45f-4a3a-8827-55a60a31ec10 2025-09-04 02:35:50,425 - INFO - Verifications in API Server for IPAM: ipam1 passed 2025-09-04 02:35:50,431 - DEBUG - Control-node 10.0.0.57 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-16254764:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-16254764', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '675400429682510394', 'uuid-lslong': '9810904484873890832'}, 'enable': 'true', 'created': '2025-09-04T02:35:49', 'last-modified': '2025-09-04T02:35:49', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.141214'} 2025-09-04 02:35:50,436 - DEBUG - Control-node 10.0.0.38 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-16254764:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-16254764', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '675400429682510394', 'uuid-lslong': '9810904484873890832'}, 'enable': 'true', 'created': '2025-09-04T02:35:49', 'last-modified': '2025-09-04T02:35:49', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.152560'} 2025-09-04 02:35:50,441 - DEBUG - Control-node 10.0.0.131 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-16254764:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-16254764', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '675400429682510394', 'uuid-lslong': '9810904484873890832'}, 'enable': 'true', 'created': '2025-09-04T02:35:49', 'last-modified': '2025-09-04T02:35:49', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.160239'} 2025-09-04 02:35:50,441 - INFO - Verifications in Control node for IPAM: ipam1 passed 2025-09-04 02:35:50,442 - DEBUG - Requesting: http://10.0.0.57:8082/domains 2025-09-04 02:35:50,448 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c 2025-09-04 02:35:50,458 - DEBUG - Requesting: http://10.0.0.57:8082/project/d3251d2d-fd0b-4ab3-a150-7787310badda 2025-09-04 02:35:50,558 - DEBUG - Requesting: http://10.0.0.57:8082/network-ipam/ecb9f552-de5e-4983-9f38-3530e601fd39 2025-09-04 02:35:50,565 - INFO - Verifications in API Server for IPAM: ipam2 passed 2025-09-04 02:35:50,573 - DEBUG - Control-node 10.0.0.57 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-16254764:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-16254764', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17057934799909308803', 'uuid-lslong': '11472978534859930937'}, 'enable': 'true', 'created': '2025-09-04T02:35:49', 'last-modified': '2025-09-04T02:35:49', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.956894'} 2025-09-04 02:35:50,580 - DEBUG - Control-node 10.0.0.38 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-16254764:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-16254764', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17057934799909308803', 'uuid-lslong': '11472978534859930937'}, 'enable': 'true', 'created': '2025-09-04T02:35:49', 'last-modified': '2025-09-04T02:35:49', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.965722'} 2025-09-04 02:35:50,585 - DEBUG - Control-node 10.0.0.131 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-16254764:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-16254764', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17057934799909308803', 'uuid-lslong': '11472978534859930937'}, 'enable': 'true', 'created': '2025-09-04T02:35:49', 'last-modified': '2025-09-04T02:35:49', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.973180'} 2025-09-04 02:35:50,585 - INFO - Verifications in Control node for IPAM: ipam2 passed 2025-09-04 02:35:50,585 - DEBUG - Requesting: http://10.0.0.57:8082/domains 2025-09-04 02:35:50,592 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c 2025-09-04 02:35:50,604 - DEBUG - Requesting: http://10.0.0.57:8082/project/d3251d2d-fd0b-4ab3-a150-7787310badda 2025-09-04 02:35:50,715 - DEBUG - Requesting: http://10.0.0.57:8082/network-ipam/04ed59d1-3c3b-4fe1-9da4-ac84ba0c8ad1 2025-09-04 02:35:50,725 - INFO - Verifications in API Server for IPAM: ipam3 passed 2025-09-04 02:35:50,730 - DEBUG - Control-node 10.0.0.57 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-16254764:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-16254764', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '355038700825694177', 'uuid-lslong': '11359393846192278225'}, 'enable': 'true', 'created': '2025-09-04T02:35:49', 'last-modified': '2025-09-04T02:35:49', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.786970'} 2025-09-04 02:35:50,737 - DEBUG - Control-node 10.0.0.38 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-16254764:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-16254764', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '355038700825694177', 'uuid-lslong': '11359393846192278225'}, 'enable': 'true', 'created': '2025-09-04T02:35:49', 'last-modified': '2025-09-04T02:35:49', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.796152'} 2025-09-04 02:35:50,743 - DEBUG - Control-node 10.0.0.131 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-16254764:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-16254764', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '355038700825694177', 'uuid-lslong': '11359393846192278225'}, 'enable': 'true', 'created': '2025-09-04T02:35:49', 'last-modified': '2025-09-04T02:35:49', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.800317'} 2025-09-04 02:35:50,743 - INFO - Verifications in Control node for IPAM: ipam3 passed 2025-09-04 02:35:51,060 - DEBUG - Response for create_network : {'network': {'id': '45e670b4-74da-4bad-8b96-e4fd63fbd04b', 'name': 'ctest-VN1-43502880', 'tenant_id': 'd3251d2dfd0b4ab3a1507787310badda', 'project_id': 'd3251d2dfd0b4ab3a1507787310badda', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-16254764', 'ctest-VN1-43502880'], 'port_security_enabled': True, 'description': ''}} 2025-09-04 02:35:51,352 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ca5ed9b8-f76d-41aa-8e83-bb8a5ea9b065', 'name': '', 'tenant_id': 'd3251d2dfd0b4ab3a1507787310badda', 'network_id': '45e670b4-74da-4bad-8b96-e4fd63fbd04b', 'ip_version': 4, 'cidr': '90.102.68.64/26', 'allocation_pools': [{'start': '90.102.68.66', 'end': '90.102.68.126'}], 'gateway_ip': '90.102.68.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '90.102.68.66', 'tags': [], 'project_id': 'd3251d2dfd0b4ab3a1507787310badda'}} 2025-09-04 02:35:51,617 - DEBUG - Response for create_subnet : {'subnet': {'id': '2082a9c1-272e-448d-b9f1-621c3efb75d6', 'name': '', 'tenant_id': 'd3251d2dfd0b4ab3a1507787310badda', 'network_id': '45e670b4-74da-4bad-8b96-e4fd63fbd04b', 'ip_version': 6, 'cidr': '3c35:c7cf:f33a:1ab:a6f4:f819::/96', 'allocation_pools': [{'start': '3c35:c7cf:f33a:1ab:a6f4:f819:0:2', 'end': '3c35:c7cf:f33a:1ab:a6f4:f819:ffff:fffe'}], 'gateway_ip': '3c35:c7cf:f33a:1ab:a6f4:f819:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '3c35:c7cf:f33a:1ab:a6f4:f819:0:2', 'tags': [], 'project_id': 'd3251d2dfd0b4ab3a1507787310badda'}} 2025-09-04 02:35:51,656 - INFO - Created VN ctest-VN1-43502880 2025-09-04 02:35:51,677 - DEBUG - VN ctest-VN1-43502880 UUID is 45e670b4-74da-4bad-8b96-e4fd63fbd04b 2025-09-04 02:35:51,856 - DEBUG - Fetched VN: default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880(45e670b4-74da-4bad-8b96-e4fd63fbd04b) with subnets ['90.102.68.64/26', '3c35:c7cf:f33a:1ab:a6f4:f819::/96'] 2025-09-04 02:35:52,168 - DEBUG - Response for create_network : {'network': {'id': '551df278-2efc-45d4-abf6-e8c67939be2e', 'name': 'ctest-VN2-33211179', 'tenant_id': 'd3251d2dfd0b4ab3a1507787310badda', 'project_id': 'd3251d2dfd0b4ab3a1507787310badda', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-16254764', 'ctest-VN2-33211179'], 'port_security_enabled': True, 'description': ''}} 2025-09-04 02:35:52,379 - DEBUG - Response for create_subnet : {'subnet': {'id': '63397140-1751-4ca7-a91a-1777228e846b', 'name': '', 'tenant_id': 'd3251d2dfd0b4ab3a1507787310badda', 'network_id': '551df278-2efc-45d4-abf6-e8c67939be2e', 'ip_version': 4, 'cidr': '17.248.79.192/26', 'allocation_pools': [{'start': '17.248.79.194', 'end': '17.248.79.254'}], 'gateway_ip': '17.248.79.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '17.248.79.194', 'tags': [], 'project_id': 'd3251d2dfd0b4ab3a1507787310badda'}} 2025-09-04 02:35:52,603 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ab8ddebb-9907-4375-a58e-65fc066731df', 'name': '', 'tenant_id': 'd3251d2dfd0b4ab3a1507787310badda', 'network_id': '551df278-2efc-45d4-abf6-e8c67939be2e', 'ip_version': 6, 'cidr': '26c6:c916:397b:383c:b814:8755::/96', 'allocation_pools': [{'start': '26c6:c916:397b:383c:b814:8755:0:2', 'end': '26c6:c916:397b:383c:b814:8755:ffff:fffe'}], 'gateway_ip': '26c6:c916:397b:383c:b814:8755:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '26c6:c916:397b:383c:b814:8755:0:2', 'tags': [], 'project_id': 'd3251d2dfd0b4ab3a1507787310badda'}} 2025-09-04 02:35:52,638 - INFO - Created VN ctest-VN2-33211179 2025-09-04 02:35:52,649 - DEBUG - VN ctest-VN2-33211179 UUID is 551df278-2efc-45d4-abf6-e8c67939be2e 2025-09-04 02:35:53,093 - DEBUG - Response for create_network : {'network': {'id': '9294bf9a-f0e9-4b57-afa5-f1c553b6e34c', 'name': 'ctest-VN3-74964432', 'tenant_id': 'd3251d2dfd0b4ab3a1507787310badda', 'project_id': 'd3251d2dfd0b4ab3a1507787310badda', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-16254764', 'ctest-VN3-74964432'], 'port_security_enabled': True, 'description': ''}} 2025-09-04 02:35:53,337 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd16651d8-3920-4036-8055-0d37557af31e', 'name': '', 'tenant_id': 'd3251d2dfd0b4ab3a1507787310badda', 'network_id': '9294bf9a-f0e9-4b57-afa5-f1c553b6e34c', 'ip_version': 4, 'cidr': '15.188.138.192/26', 'allocation_pools': [{'start': '15.188.138.194', 'end': '15.188.138.254'}], 'gateway_ip': '15.188.138.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '15.188.138.194', 'tags': [], 'project_id': 'd3251d2dfd0b4ab3a1507787310badda'}} 2025-09-04 02:35:53,584 - DEBUG - Response for create_subnet : {'subnet': {'id': '2c2266df-483c-4e44-8d6d-ad73e84b3ad4', 'name': '', 'tenant_id': 'd3251d2dfd0b4ab3a1507787310badda', 'network_id': '9294bf9a-f0e9-4b57-afa5-f1c553b6e34c', 'ip_version': 6, 'cidr': '3507:8c49:5a5:216c:e813:6430::/96', 'allocation_pools': [{'start': '3507:8c49:5a5:216c:e813:6430:0:2', 'end': '3507:8c49:5a5:216c:e813:6430:ffff:fffe'}], 'gateway_ip': '3507:8c49:5a5:216c:e813:6430:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '3507:8c49:5a5:216c:e813:6430:0:2', 'tags': [], 'project_id': 'd3251d2dfd0b4ab3a1507787310badda'}} 2025-09-04 02:35:53,614 - INFO - Created VN ctest-VN3-74964432 2025-09-04 02:35:53,625 - DEBUG - VN ctest-VN3-74964432 UUID is 9294bf9a-f0e9-4b57-afa5-f1c553b6e34c 2025-09-04 02:35:53,728 - DEBUG - Requesting: http://10.0.0.57:8082/domains 2025-09-04 02:35:53,734 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c 2025-09-04 02:35:53,747 - DEBUG - Requesting: http://10.0.0.57:8082/project/d3251d2d-fd0b-4ab3-a150-7787310badda 2025-09-04 02:35:53,876 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/45e670b4-74da-4bad-8b96-e4fd63fbd04b 2025-09-04 02:35:53,893 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/45e670b4-74da-4bad-8b96-e4fd63fbd04b 2025-09-04 02:35:53,905 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/a4772aca-5c1b-4dec-a166-2dc17503f03d 2025-09-04 02:35:53,916 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/a4772aca-5c1b-4dec-a166-2dc17503f03d 2025-09-04 02:35:53,928 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/e76f4842-0dc5-450f-8f26-23ab23f1ab21 2025-09-04 02:35:53,935 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-09-04 02:35:53,935 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/45e670b4-74da-4bad-8b96-e4fd63fbd04b 2025-09-04 02:35:53,947 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/a4772aca-5c1b-4dec-a166-2dc17503f03d 2025-09-04 02:35:53,964 - INFO - Verified VN network id 13 for VN 45e670b4-74da-4bad-8b96-e4fd63fbd04b 2025-09-04 02:35:53,964 - INFO - Verifications in API Server for VN ctest-VN1-43502880 passed 2025-09-04 02:35:53,964 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/45e670b4-74da-4bad-8b96-e4fd63fbd04b 2025-09-04 02:35:53,974 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/a4772aca-5c1b-4dec-a166-2dc17503f03d 2025-09-04 02:35:53,981 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/a4772aca-5c1b-4dec-a166-2dc17503f03d 2025-09-04 02:35:53,988 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/e76f4842-0dc5-450f-8f26-23ab23f1ab21 2025-09-04 02:35:54,000 - DEBUG - Control-node 10.0.0.57 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-16254764', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5036837153617365933', 'uuid-lslong': '10058478594733887563'}, 'enable': 'true', 'created': '2025-09-04T02:35:50', 'last-modified': '2025-09-04T02:35:51', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.462830'} 2025-09-04 02:35:54,006 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-09-04 02:35:54,012 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-16254764', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5036837153617365933', 'uuid-lslong': '10058478594733887563'}, 'enable': 'true', 'created': '2025-09-04T02:35:50', 'last-modified': '2025-09-04T02:35:51', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.467987'} 2025-09-04 02:35:54,018 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-09-04 02:35:54,024 - DEBUG - Control-node 10.0.0.131 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-16254764', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5036837153617365933', 'uuid-lslong': '10058478594733887563'}, 'enable': 'true', 'created': '2025-09-04T02:35:50', 'last-modified': '2025-09-04T02:35:51', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.489121'} 2025-09-04 02:35:54,035 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-09-04 02:35:54,035 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN1-43502880 passed 2025-09-04 02:35:54,036 - DEBUG - ====Verifying policy data for ctest-VN1-43502880 in API_Server ====== 2025-09-04 02:35:54,036 - DEBUG - Requesting: http://10.0.0.57:8082/domains 2025-09-04 02:35:54,042 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c 2025-09-04 02:35:54,056 - DEBUG - Requesting: http://10.0.0.57:8082/project/d3251d2d-fd0b-4ab3-a150-7787310badda 2025-09-04 02:35:54,156 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/45e670b4-74da-4bad-8b96-e4fd63fbd04b 2025-09-04 02:35:54,181 - DEBUG - =>VN ctest-VN1-43502880 has no policy to be verified 2025-09-04 02:35:54,181 - DEBUG - Verifying the vn in opserver 2025-09-04 02:35:54,181 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880 virtual network link through opserver 10.0.0.57 2025-09-04 02:35:54,181 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-networks 2025-09-04 02:35:54,490 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-networks 2025-09-04 02:35:54,554 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880', 'href': 'http://10.0.0.57:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880?flat'} 2025-09-04 02:35:54,554 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880 virtual network link through opserver 10.0.0.38 2025-09-04 02:35:54,554 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2025-09-04 02:35:54,866 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2025-09-04 02:35:54,930 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880?flat'} 2025-09-04 02:35:54,930 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880 virtual network link through opserver 10.0.0.131 2025-09-04 02:35:54,930 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-networks 2025-09-04 02:35:55,239 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-networks 2025-09-04 02:35:55,298 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880', 'href': 'http://10.0.0.131:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880?flat'} 2025-09-04 02:35:55,298 - INFO - Validated that VN default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880 is found in opserver 2025-09-04 02:35:55,324 - DEBUG - Do not have enough data to verify VN in agent 2025-09-04 02:35:55,333 - DEBUG - VRF ids for VN ctest-VN1-43502880: {} 2025-09-04 02:35:55,333 - DEBUG - Requesting: http://10.0.0.57:8082/domains 2025-09-04 02:35:55,341 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c 2025-09-04 02:35:55,355 - DEBUG - Requesting: http://10.0.0.57:8082/project/d3251d2d-fd0b-4ab3-a150-7787310badda 2025-09-04 02:35:55,454 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/551df278-2efc-45d4-abf6-e8c67939be2e 2025-09-04 02:35:55,469 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/551df278-2efc-45d4-abf6-e8c67939be2e 2025-09-04 02:35:55,479 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/131071cf-1fbd-48d5-86b8-6cb14d9b8dff 2025-09-04 02:35:55,488 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/131071cf-1fbd-48d5-86b8-6cb14d9b8dff 2025-09-04 02:35:55,496 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/4fae22f9-51c4-444b-a1b9-f0d1d783cbfc 2025-09-04 02:35:55,506 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-09-04 02:35:55,507 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/551df278-2efc-45d4-abf6-e8c67939be2e 2025-09-04 02:35:55,516 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/131071cf-1fbd-48d5-86b8-6cb14d9b8dff 2025-09-04 02:35:55,530 - INFO - Verified VN network id 14 for VN 551df278-2efc-45d4-abf6-e8c67939be2e 2025-09-04 02:35:55,530 - INFO - Verifications in API Server for VN ctest-VN2-33211179 passed 2025-09-04 02:35:55,530 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/551df278-2efc-45d4-abf6-e8c67939be2e 2025-09-04 02:35:55,540 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/131071cf-1fbd-48d5-86b8-6cb14d9b8dff 2025-09-04 02:35:55,546 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/131071cf-1fbd-48d5-86b8-6cb14d9b8dff 2025-09-04 02:35:55,553 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/4fae22f9-51c4-444b-a1b9-f0d1d783cbfc 2025-09-04 02:35:55,566 - DEBUG - Control-node 10.0.0.57 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN2-33211179', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-16254764', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6133324865546765780', 'uuid-lslong': '12391347363891494446'}, 'enable': 'true', 'created': '2025-09-04T02:35:52', 'last-modified': '2025-09-04T02:35:52', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.021292'} 2025-09-04 02:35:55,576 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-09-04 02:35:55,581 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN2-33211179', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-16254764', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6133324865546765780', 'uuid-lslong': '12391347363891494446'}, 'enable': 'true', 'created': '2025-09-04T02:35:52', 'last-modified': '2025-09-04T02:35:52', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.031683'} 2025-09-04 02:35:55,587 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-09-04 02:35:55,593 - DEBUG - Control-node 10.0.0.131 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN2-33211179', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-16254764', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6133324865546765780', 'uuid-lslong': '12391347363891494446'}, 'enable': 'true', 'created': '2025-09-04T02:35:52', 'last-modified': '2025-09-04T02:35:52', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.049001'} 2025-09-04 02:35:55,599 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-09-04 02:35:55,599 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN2-33211179 passed 2025-09-04 02:35:55,599 - DEBUG - ====Verifying policy data for ctest-VN2-33211179 in API_Server ====== 2025-09-04 02:35:55,600 - DEBUG - Requesting: http://10.0.0.57:8082/domains 2025-09-04 02:35:55,605 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c 2025-09-04 02:35:55,616 - DEBUG - Requesting: http://10.0.0.57:8082/project/d3251d2d-fd0b-4ab3-a150-7787310badda 2025-09-04 02:35:55,716 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/551df278-2efc-45d4-abf6-e8c67939be2e 2025-09-04 02:35:55,727 - DEBUG - =>VN ctest-VN2-33211179 has no policy to be verified 2025-09-04 02:35:55,727 - DEBUG - Verifying the vn in opserver 2025-09-04 02:35:55,727 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN2-33211179 virtual network link through opserver 10.0.0.57 2025-09-04 02:35:55,727 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-networks 2025-09-04 02:35:55,785 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN2-33211179', 'href': 'http://10.0.0.57:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN2-33211179?flat'} 2025-09-04 02:35:55,785 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN2-33211179 virtual network link through opserver 10.0.0.38 2025-09-04 02:35:55,785 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2025-09-04 02:35:55,855 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN2-33211179', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN2-33211179?flat'} 2025-09-04 02:35:55,855 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN2-33211179 virtual network link through opserver 10.0.0.131 2025-09-04 02:35:55,855 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-networks 2025-09-04 02:35:55,906 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN2-33211179', 'href': 'http://10.0.0.131:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN2-33211179?flat'} 2025-09-04 02:35:55,906 - INFO - Validated that VN default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN2-33211179 is found in opserver 2025-09-04 02:35:55,928 - DEBUG - Do not have enough data to verify VN in agent 2025-09-04 02:35:55,942 - DEBUG - VRF ids for VN ctest-VN2-33211179: {} 2025-09-04 02:35:55,942 - DEBUG - Requesting: http://10.0.0.57:8082/domains 2025-09-04 02:35:55,949 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c 2025-09-04 02:35:55,960 - DEBUG - Requesting: http://10.0.0.57:8082/project/d3251d2d-fd0b-4ab3-a150-7787310badda 2025-09-04 02:35:56,056 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/9294bf9a-f0e9-4b57-afa5-f1c553b6e34c 2025-09-04 02:35:56,068 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/9294bf9a-f0e9-4b57-afa5-f1c553b6e34c 2025-09-04 02:35:56,077 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/6b71721a-06c9-4525-94b7-9cd6174c25c1 2025-09-04 02:35:56,087 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/6b71721a-06c9-4525-94b7-9cd6174c25c1 2025-09-04 02:35:56,093 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/f5d6cde0-7fe1-422a-9890-a42a9c0f3cdd 2025-09-04 02:35:56,101 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-09-04 02:35:56,101 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/9294bf9a-f0e9-4b57-afa5-f1c553b6e34c 2025-09-04 02:35:56,112 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/6b71721a-06c9-4525-94b7-9cd6174c25c1 2025-09-04 02:35:56,130 - INFO - Verified VN network id 15 for VN 9294bf9a-f0e9-4b57-afa5-f1c553b6e34c 2025-09-04 02:35:56,130 - INFO - Verifications in API Server for VN ctest-VN3-74964432 passed 2025-09-04 02:35:56,130 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/9294bf9a-f0e9-4b57-afa5-f1c553b6e34c 2025-09-04 02:35:56,141 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/6b71721a-06c9-4525-94b7-9cd6174c25c1 2025-09-04 02:35:56,151 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/6b71721a-06c9-4525-94b7-9cd6174c25c1 2025-09-04 02:35:56,159 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/f5d6cde0-7fe1-422a-9890-a42a9c0f3cdd 2025-09-04 02:35:56,173 - DEBUG - Control-node 10.0.0.57 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN3-74964432', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-16254764', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10562277698278345559', 'uuid-lslong': '12656788157609993036'}, 'enable': 'true', 'created': '2025-09-04T02:35:52', 'last-modified': '2025-09-04T02:35:53', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.648559'} 2025-09-04 02:35:56,180 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-09-04 02:35:56,186 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN3-74964432', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-16254764', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10562277698278345559', 'uuid-lslong': '12656788157609993036'}, 'enable': 'true', 'created': '2025-09-04T02:35:52', 'last-modified': '2025-09-04T02:35:53', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.684553'} 2025-09-04 02:35:56,192 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-09-04 02:35:56,199 - DEBUG - Control-node 10.0.0.131 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN3-74964432', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-16254764', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10562277698278345559', 'uuid-lslong': '12656788157609993036'}, 'enable': 'true', 'created': '2025-09-04T02:35:52', 'last-modified': '2025-09-04T02:35:53', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.689747'} 2025-09-04 02:35:56,206 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-09-04 02:35:56,206 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN3-74964432 passed 2025-09-04 02:35:56,207 - DEBUG - ====Verifying policy data for ctest-VN3-74964432 in API_Server ====== 2025-09-04 02:35:56,207 - DEBUG - Requesting: http://10.0.0.57:8082/domains 2025-09-04 02:35:56,215 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c 2025-09-04 02:35:56,228 - DEBUG - Requesting: http://10.0.0.57:8082/project/d3251d2d-fd0b-4ab3-a150-7787310badda 2025-09-04 02:35:56,330 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/9294bf9a-f0e9-4b57-afa5-f1c553b6e34c 2025-09-04 02:35:56,341 - DEBUG - =>VN ctest-VN3-74964432 has no policy to be verified 2025-09-04 02:35:56,341 - DEBUG - Verifying the vn in opserver 2025-09-04 02:35:56,341 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN3-74964432 virtual network link through opserver 10.0.0.57 2025-09-04 02:35:56,341 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-networks 2025-09-04 02:35:56,400 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN3-74964432', 'href': 'http://10.0.0.57:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN3-74964432?flat'} 2025-09-04 02:35:56,400 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN3-74964432 virtual network link through opserver 10.0.0.38 2025-09-04 02:35:56,400 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2025-09-04 02:35:56,458 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN3-74964432', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN3-74964432?flat'} 2025-09-04 02:35:56,458 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN3-74964432 virtual network link through opserver 10.0.0.131 2025-09-04 02:35:56,458 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-networks 2025-09-04 02:35:56,518 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN3-74964432', 'href': 'http://10.0.0.131:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN3-74964432?flat'} 2025-09-04 02:35:56,518 - INFO - Validated that VN default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN3-74964432 is found in opserver 2025-09-04 02:35:56,539 - DEBUG - Do not have enough data to verify VN in agent 2025-09-04 02:35:56,549 - DEBUG - VRF ids for VN ctest-VN3-74964432: {} 2025-09-04 02:35:56,555 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880, 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-16254764:ctest-VN3-74964432, 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-09-04 02:35:56,824 - DEBUG - No source network defined 2025-09-04 02:35:56,824 - DEBUG - No source network defined 2025-09-04 02:35:56,825 - 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-16254764:ctest-policy13-65349389, 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-16254764:ctest-policy13-65349389, 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-09-04 02:35:57,109 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN2-33211179, 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-16254764:ctest-VN1-43502880, 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-16254764:ctest-VN2-33211179, 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-16254764:ctest-VN1-43502880, 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-09-04 02:35:57,331 - DEBUG - Requesting: http://10.0.0.57:8082/domains 2025-09-04 02:35:57,338 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c 2025-09-04 02:35:57,352 - DEBUG - Requesting: http://10.0.0.57:8082/project/d3251d2d-fd0b-4ab3-a150-7787310badda 2025-09-04 02:35:57,458 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/45e670b4-74da-4bad-8b96-e4fd63fbd04b 2025-09-04 02:35:57,468 - DEBUG - Setup step: Associating the policy to VN' 2025-09-04 02:35:57,596 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '45e670b4-74da-4bad-8b96-e4fd63fbd04b', 'name': 'ctest-VN1-43502880', 'tenant_id': 'd3251d2dfd0b4ab3a1507787310badda', 'project_id': 'd3251d2dfd0b4ab3a1507787310badda', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['ca5ed9b8-f76d-41aa-8e83-bb8a5ea9b065', '2082a9c1-272e-448d-b9f1-621c3efb75d6'], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-16254764', 'ctest-VN1-43502880'], 'policys': [['default-domain', 'ctest-TestPolicyAclIpv4v6-16254764', 'ctest-policy12-46474637'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-16254764', 'ctest-policy13-65349389']], 'subnet_ipam': [{'subnet_cidr': '90.102.68.64/26', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-16254764', 'ipam1']}, {'subnet_cidr': '3c35:c7cf:f33a:1ab:a6f4:f819::/96', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-16254764', 'ipam1']}], 'port_security_enabled': True, 'description': ''}} 2025-09-04 02:35:57,767 - DEBUG - Associated Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-16254764', 'ctest-policy12-46474637'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-16254764', 'ctest-policy13-65349389']] to ctest-VN1-43502880 2025-09-04 02:35:57,767 - DEBUG - Requesting: http://10.0.0.57:8082/domains 2025-09-04 02:35:57,774 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c 2025-09-04 02:35:57,794 - DEBUG - Requesting: http://10.0.0.57:8082/project/d3251d2d-fd0b-4ab3-a150-7787310badda 2025-09-04 02:35:57,885 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/551df278-2efc-45d4-abf6-e8c67939be2e 2025-09-04 02:35:57,899 - DEBUG - Setup step: Associating the policy to VN' 2025-09-04 02:35:58,033 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '551df278-2efc-45d4-abf6-e8c67939be2e', 'name': 'ctest-VN2-33211179', 'tenant_id': 'd3251d2dfd0b4ab3a1507787310badda', 'project_id': 'd3251d2dfd0b4ab3a1507787310badda', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['63397140-1751-4ca7-a91a-1777228e846b', 'ab8ddebb-9907-4375-a58e-65fc066731df'], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-16254764', 'ctest-VN2-33211179'], 'policys': [['default-domain', 'ctest-TestPolicyAclIpv4v6-16254764', 'ctest-policy21-78735191']], 'subnet_ipam': [{'subnet_cidr': '17.248.79.192/26', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-16254764', 'ipam2']}, {'subnet_cidr': '26c6:c916:397b:383c:b814:8755::/96', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-16254764', 'ipam2']}], 'port_security_enabled': True, 'description': ''}} 2025-09-04 02:35:58,151 - DEBUG - Associated Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-16254764', 'ctest-policy21-78735191']] to ctest-VN2-33211179 2025-09-04 02:35:58,272 - DEBUG - Services list from nova: [, , , ] 2025-09-04 02:35:59,378 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4012-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4012-1) 2025-09-04 02:36:00,817 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4012-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4012-2) 2025-09-04 02:36:02,264 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4012-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4012-1) 2025-09-04 02:36:02,354 - DEBUG - VM is in ACTIVE state now 2025-09-04 02:36:02,355 - INFO - VM name : ctest-VM11-48649270 2025-09-04 02:36:02,441 - DEBUG - VM ctest-VM11-48649270 ID is ab248664-787f-4511-9f0a-aa540af76c20 2025-09-04 02:36:02,524 - DEBUG - VM is in ACTIVE state now 2025-09-04 02:36:02,551 - DEBUG - VM ctest-VM11-48649270 launched on Node an-jenkins-deploy-platform-ansible-os-4012-1 2025-09-04 02:36:02,652 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/ab248664-787f-4511-9f0a-aa540af76c20 2025-09-04 02:36:03,141 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/ab248664-787f-4511-9f0a-aa540af76c20 2025-09-04 02:36:03,222 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/ab248664-787f-4511-9f0a-aa540af76c20 2025-09-04 02:36:03,235 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine-interface/b0230ea8-e2ba-40de-9149-aae3cd65a6ed 2025-09-04 02:36:03,274 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/ab248664-787f-4511-9f0a-aa540af76c20 2025-09-04 02:36:03,288 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine-interface/b0230ea8-e2ba-40de-9149-aae3cd65a6ed 2025-09-04 02:36:03,306 - DEBUG - Requesting: http://10.0.0.131:8082/instance-ip/8653381e-ae46-4574-b2af-4d830df1dcda 2025-09-04 02:36:03,332 - DEBUG - Requesting: http://10.0.0.131:8082/instance-ip/b7be1d16-d0ac-4f5c-85f5-5cb200bed052 2025-09-04 02:36:03,346 - DEBUG - Verifying in api server 10.0.0.131 2025-09-04 02:36:03,347 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/ab248664-787f-4511-9f0a-aa540af76c20 2025-09-04 02:36:03,358 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine-interface/b0230ea8-e2ba-40de-9149-aae3cd65a6ed 2025-09-04 02:36:03,370 - DEBUG - Requesting: http://10.0.0.131:8082/instance-ip/8653381e-ae46-4574-b2af-4d830df1dcda 2025-09-04 02:36:03,381 - DEBUG - Requesting: http://10.0.0.131:8082/instance-ip/b7be1d16-d0ac-4f5c-85f5-5cb200bed052 2025-09-04 02:36:03,395 - INFO - VM ctest-VM11-48649270 verfication in all API Servers passed 2025-09-04 02:36:03,417 - DEBUG - VM ctest-VM11-48649270 Tap interface: {'index': '3', 'name': 'tapb0230ea8-e2', 'uuid': 'b0230ea8-e2ba-40de-9149-aae3cd65a6ed', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880:ctest-VN1-43502880', '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-16254764:ctest-VN1-43502880', 'vm_uuid': 'ab248664-787f-4511-9f0a-aa540af76c20', 'vm_name': 'ctest-VM11-48649270', 'ip_addr': '90.102.68.67', 'mac_addr': '02:b0:23:0e:a8:e2', '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-16254764:b0230ea8-e2ba-40de-9149-aae3cd65a6ed', 'sg_uuid_list': ['d58b8dc0-ad69-4b43-b89b-01f480041fd0'], 'static_route_list': None, 'vm_project_uuid': 'd3251d2d-fd0b-4ab3-a150-7787310badda', 'admin_state': 'Enabled', 'flow_key_idx': '30', 'allowed_address_pair_list': None, 'ip6_addr': '3c35:c7cf:f33a:1ab:a6f4:f819: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-09-04 02:36:03,417 - DEBUG - Agent 10.0.0.241 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880:ctest-VN1-43502880 2025-09-04 02:36:03,423 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880:ctest-VN1-43502880', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880', 'table_label': '-1', 'vxlan_id': '13', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.18:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2025-09-04 02:36:03,518 - DEBUG - VM ctest-VM11-48649270 : Tap interface tapb0230ea8-e2 is set to Active 2025-09-04 02:36:03,518 - DEBUG - Tap interface tapb0230ea8-e2 detail : {'index': '3', 'name': 'tapb0230ea8-e2', 'uuid': 'b0230ea8-e2ba-40de-9149-aae3cd65a6ed', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880:ctest-VN1-43502880', '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-16254764:ctest-VN1-43502880', 'vm_uuid': 'ab248664-787f-4511-9f0a-aa540af76c20', 'vm_name': 'ctest-VM11-48649270', 'ip_addr': '90.102.68.67', 'mac_addr': '02:b0:23:0e:a8:e2', '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-16254764:b0230ea8-e2ba-40de-9149-aae3cd65a6ed', 'sg_uuid_list': ['d58b8dc0-ad69-4b43-b89b-01f480041fd0'], 'static_route_list': None, 'vm_project_uuid': 'd3251d2d-fd0b-4ab3-a150-7787310badda', 'admin_state': 'Enabled', 'flow_key_idx': '30', 'allowed_address_pair_list': None, 'ip6_addr': '3c35:c7cf:f33a:1ab:a6f4:f819: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-09-04 02:36:03,638 - DEBUG - Starting Layer 2 verification in Agent 2025-09-04 02:36:03,649 - DEBUG - Layer 2 path is seen for VM MAC 02:b0:23:0e:a8:e2 in agent 10.0.0.241 2025-09-04 02:36:03,649 - DEBUG - Active layer 2 route in agent is present for VMI tapb0230ea8-e2 2025-09-04 02:36:03,649 - DEBUG - L2 label(29) matches bw route table and interface table 2025-09-04 02:36:03,668 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880 is found in Agent of node 10.0.0.241 2025-09-04 02:36:03,822 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880 is consistent in agent 10.0.0.241 2025-09-04 02:36:03,822 - DEBUG - Route for VM IP ['90.102.68.67', '3c35:c7cf:f33a:1ab:a6f4:f819:0:3'] is consistent in agent 10.0.0.241 2025-09-04 02:36:03,822 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880 verification for VM ctest-VM11-48649270 in Agent 10.0.0.241 passed 2025-09-04 02:36:03,893 - DEBUG - Starting all layer 2 verification in agent 10.0.0.241 2025-09-04 02:36:03,906 - DEBUG - Route for VM MAC 02:b0:23:0e:a8:e2 is consistent in agent 10.0.0.241 2025-09-04 02:36:07,117 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1020ms') 2025-09-04 02:36:07,117 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-48649270 failed! 2025-09-04 02:36:07,133 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880 is 90.102.68.65 and allocation pool is NOT set 2025-09-04 02:36:07,133 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880 is 3c35:c7cf:f33a:1ab:a6f4:f819:0:1 and allocation pool is NOT set 2025-09-04 02:36:11,212 - 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-09-04 02:36:11,213 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-48649270 failed! 2025-09-04 02:36:11,228 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880 is 90.102.68.65 and allocation pool is NOT set 2025-09-04 02:36:11,228 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880 is 3c35:c7cf:f33a:1ab:a6f4:f819:0:1 and allocation pool is NOT set 2025-09-04 02:36:15,309 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1013ms') 2025-09-04 02:36:15,309 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-48649270 failed! 2025-09-04 02:36:15,323 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880 is 90.102.68.65 and allocation pool is NOT set 2025-09-04 02:36:15,324 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880 is 3c35:c7cf:f33a:1ab:a6f4:f819:0:1 and allocation pool is NOT set 2025-09-04 02:36:19,409 - 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-09-04 02:36:19,409 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-48649270 failed! 2025-09-04 02:36:19,423 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880 is 90.102.68.65 and allocation pool is NOT set 2025-09-04 02:36:19,424 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880 is 3c35:c7cf:f33a:1ab:a6f4:f819:0:1 and allocation pool is NOT set 2025-09-04 02:36:23,502 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1013ms') 2025-09-04 02:36:23,503 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-48649270 failed! 2025-09-04 02:36:23,519 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880 is 90.102.68.65 and allocation pool is NOT set 2025-09-04 02:36:23,520 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880 is 3c35:c7cf:f33a:1ab:a6f4:f819:0:1 and allocation pool is NOT set 2025-09-04 02:36:27,597 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1019ms') 2025-09-04 02:36:27,598 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-48649270 failed! 2025-09-04 02:36:27,616 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880 is 90.102.68.65 and allocation pool is NOT set 2025-09-04 02:36:27,616 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880 is 3c35:c7cf:f33a:1ab:a6f4:f819:0:1 and allocation pool is NOT set 2025-09-04 02:36:31,688 - 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 1011ms') 2025-09-04 02:36:31,688 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-48649270 failed! 2025-09-04 02:36:31,705 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880 is 90.102.68.65 and allocation pool is NOT set 2025-09-04 02:36:31,705 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880 is 3c35:c7cf:f33a:1ab:a6f4:f819:0:1 and allocation pool is NOT set 2025-09-04 02:36:35,791 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1019ms') 2025-09-04 02:36:35,791 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-48649270 failed! 2025-09-04 02:36:35,805 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880 is 90.102.68.65 and allocation pool is NOT set 2025-09-04 02:36:35,805 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880 is 3c35:c7cf:f33a:1ab:a6f4:f819:0:1 and allocation pool is NOT set 2025-09-04 02:36:39,876 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1013ms') 2025-09-04 02:36:39,876 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-48649270 failed! 2025-09-04 02:36:39,891 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880 is 90.102.68.65 and allocation pool is NOT set 2025-09-04 02:36:39,891 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880 is 3c35:c7cf:f33a:1ab:a6f4:f819:0:1 and allocation pool is NOT set 2025-09-04 02:36:43,973 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1023ms') 2025-09-04 02:36:43,973 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-48649270 failed! 2025-09-04 02:36:43,988 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880 is 90.102.68.65 and allocation pool is NOT set 2025-09-04 02:36:43,988 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880 is 3c35:c7cf:f33a:1ab:a6f4:f819:0:1 and allocation pool is NOT set 2025-09-04 02:36:48,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=2 ttl=63 time=6.70 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 1 received, 50% packet loss, time 1022ms\r\nrtt min/avg/max/mdev = 6.699/6.699/6.699/0.000 ms') 2025-09-04 02:36:48,076 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-48649270 passed 2025-09-04 02:36:48,076 - INFO - VM ctest-VM11-48649270 verifications in Compute nodes passed 2025-09-04 02:36:48,314 - DEBUG - Validated VM route 90.102.68.67 in vrouter of 10.0.0.241 2025-09-04 02:36:48,322 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0 2025-09-04 02:36:48,322 - INFO - Validated routes of VM ctest-VM11-48649270 in all vrouters 2025-09-04 02:36:48,746 - DEBUG - Starting all layer2 verification in 10.0.0.57 Control Node 2025-09-04 02:36:48,868 - DEBUG - Layer2 route found for VM MAC 02:b0:23:0e:a8:e2 in Control-node 10.0.0.57 2025-09-04 02:36:48,868 - DEBUG - L2 Label for VM ctest-VM11-48649270 same between Control-node 10.0.0.57 and Agent, Expected: 29, Seen: 29 2025-09-04 02:36:48,977 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.57, not supported 2025-09-04 02:36:48,977 - DEBUG - Starting all layer2 verification in 10.0.0.38 Control Node 2025-09-04 02:36:49,097 - DEBUG - Layer2 route found for VM MAC 02:b0:23:0e:a8:e2 in Control-node 10.0.0.38 2025-09-04 02:36:49,097 - DEBUG - L2 Label for VM ctest-VM11-48649270 same between Control-node 10.0.0.38 and Agent, Expected: 29, Seen: 29 2025-09-04 02:36:49,205 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.38, not supported 2025-09-04 02:36:49,205 - INFO - Verification in Control-nodes for VM ctest-VM11-48649270 passed 2025-09-04 02:36:49,205 - DEBUG - Verifying the vm in opserver 2025-09-04 02:36:49,205 - DEBUG - Verifying in collector 10.0.0.131 ... 2025-09-04 02:36:49,205 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-machine/ab248664-787f-4511-9f0a-aa540af76c20?flat 2025-09-04 02:36:49,220 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-16254764:b0230ea8-e2ba-40de-9149-aae3cd65a6ed?flat 2025-09-04 02:36:49,242 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-16254764:b0230ea8-e2ba-40de-9149-aae3cd65a6ed?flat 2025-09-04 02:36:49,263 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-16254764:b0230ea8-e2ba-40de-9149-aae3cd65a6ed?flat 2025-09-04 02:36:49,283 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-machine/ab248664-787f-4511-9f0a-aa540af76c20?flat 2025-09-04 02:36:49,299 - DEBUG - Verifying vm in vn uve 2025-09-04 02:36:49,300 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-16254764:b0230ea8-e2ba-40de-9149-aae3cd65a6ed?flat 2025-09-04 02:36:49,320 - DEBUG - VM uve shows interface as {'ip4_active': True, 'gateway': '90.102.68.65', 'vm_name': 'ctest-VM11-48649270', 'vm_uuid': 'ab248664-787f-4511-9f0a-aa540af76c20', 'uuid': 'b0230ea8-e2ba-40de-9149-aae3cd65a6ed', 'label': 25, 'fixed_ip6_list': ['3c35:c7cf:f33a:1ab:a6f4:f819:0:3'], 'rx_vlan': 65535, '__T': 1756953366970514, 'tx_vlan': 65535, 'fixed_ip4_list': ['90.102.68.67'], 'port_mirror_enabled': False, 'mac_address': '02:b0:23:0e:a8:e2', 'vn_uuid': '45e670b4-74da-4bad-8b96-e4fd63fbd04b', 'vhostuser_mode': 0, 'ip_address': '90.102.68.67', 'l2_active': True, 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880', 'ip6_active': True, 'admin_state': True, 'is_health_check_active': True, 'ip6_address': '3c35:c7cf:f33a:1ab:a6f4:f819:0:3', 'active': True} 2025-09-04 02:36:49,320 - DEBUG - VM uve shows ip address as ['90.102.68.67', '3c35:c7cf:f33a:1ab:a6f4:f819:0:3'] 2025-09-04 02:36:49,320 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880 2025-09-04 02:36:49,320 - INFO - Verifying through opserver in 10.0.0.57 2025-09-04 02:36:49,321 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880?flat 2025-09-04 02:36:49,344 - DEBUG - expected vm list ab248664-787f-4511-9f0a-aa540af76c20 2025-09-04 02:36:49,344 - DEBUG - Extracted vm list ['ab248664-787f-4511-9f0a-aa540af76c20'] 2025-09-04 02:36:49,344 - INFO - VM ab248664-787f-4511-9f0a-aa540af76c20 is present in default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880 2025-09-04 02:36:49,344 - DEBUG - Verifying vm in vrouter uve 2025-09-04 02:36:49,344 - DEBUG - Getting info from collector 10.0.0.131.. 2025-09-04 02:36:49,344 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-machine/ab248664-787f-4511-9f0a-aa540af76c20?flat 2025-09-04 02:36:49,359 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-4012-1'] 2025-09-04 02:36:49,359 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4012-1:Compute:contrail-vrouter-agent:0?flat 2025-09-04 02:36:49,395 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4012-1?flat 2025-09-04 02:36:49,423 - DEBUG - VM ab248664-787f-4511-9f0a-aa540af76c20 is present in vrouter an-jenkins-deploy-platform-ansible-os-4012-1 uve 2025-09-04 02:36:49,423 - DEBUG - Validated that VM ctest-VM11-48649270 is in Vrouter an-jenkins-deploy-platform-ansible-os-4012-1 UVE 2025-09-04 02:36:49,423 - DEBUG - Verifying vm tap interface/vn in vrouter uve 2025-09-04 02:36:49,430 - DEBUG - Expected tap interface of VM uuid ab248664-787f-4511-9f0a-aa540af76c20 is default-domain:ctest-TestPolicyAclIpv4v6-16254764:b0230ea8-e2ba-40de-9149-aae3cd65a6ed 2025-09-04 02:36:49,430 - DEBUG - Expected VN of VM uuid ab248664-787f-4511-9f0a-aa540af76c20 is default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880 2025-09-04 02:36:49,430 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4012-1:Compute:contrail-vrouter-agent:0?flat 2025-09-04 02:36:49,466 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4012-1?flat 2025-09-04 02:36:49,494 - DEBUG - VM ab248664-787f-4511-9f0a-aa540af76c20 is present in vrouter an-jenkins-deploy-platform-ansible-os-4012-1 uve 2025-09-04 02:36:49,494 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-16254764:b0230ea8-e2ba-40de-9149-aae3cd65a6ed of vm ab248664-787f-4511-9f0a-aa540af76c20 is present in vrouter an-jenkins-deploy-platform-ansible-os-4012-1 uve 2025-09-04 02:36:49,494 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880 of vm ab248664-787f-4511-9f0a-aa540af76c20 is present in vrouter an-jenkins-deploy-platform-ansible-os-4012-1 uve 2025-09-04 02:36:49,494 - DEBUG - Verifying the ab248664-787f-4511-9f0a-aa540af76c20 virtual network link through opserver 10.0.0.57 2025-09-04 02:36:49,494 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-machines 2025-09-04 02:36:49,505 - DEBUG - VM link and name as {'name': 'ab248664-787f-4511-9f0a-aa540af76c20', 'href': 'http://10.0.0.57:8081/analytics/uves/virtual-machine/ab248664-787f-4511-9f0a-aa540af76c20?flat'} 2025-09-04 02:36:49,505 - DEBUG - Verifying the ab248664-787f-4511-9f0a-aa540af76c20 virtual network link through opserver 10.0.0.38 2025-09-04 02:36:49,505 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines 2025-09-04 02:36:49,517 - DEBUG - VM link and name as {'name': 'ab248664-787f-4511-9f0a-aa540af76c20', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-machine/ab248664-787f-4511-9f0a-aa540af76c20?flat'} 2025-09-04 02:36:49,517 - DEBUG - Verifying the ab248664-787f-4511-9f0a-aa540af76c20 virtual network link through opserver 10.0.0.131 2025-09-04 02:36:49,517 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-machines 2025-09-04 02:36:49,528 - DEBUG - VM link and name as {'name': 'ab248664-787f-4511-9f0a-aa540af76c20', 'href': 'http://10.0.0.131:8081/analytics/uves/virtual-machine/ab248664-787f-4511-9f0a-aa540af76c20?flat'} 2025-09-04 02:36:49,528 - INFO - VM ctest-VM11-48649270 validations in Opserver passed 2025-09-04 02:36:49,613 - DEBUG - VM is in ACTIVE state now 2025-09-04 02:36:49,613 - INFO - VM name : ctest-VM21-04387429 2025-09-04 02:36:49,701 - DEBUG - VM ctest-VM21-04387429 ID is df74802d-7321-489f-a302-7515d5ac2254 2025-09-04 02:36:49,796 - DEBUG - VM is in ACTIVE state now 2025-09-04 02:36:49,796 - DEBUG - VM ctest-VM21-04387429 launched on Node an-jenkins-deploy-platform-ansible-os-4012-2 2025-09-04 02:36:49,882 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/df74802d-7321-489f-a302-7515d5ac2254 2025-09-04 02:36:49,897 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/df74802d-7321-489f-a302-7515d5ac2254 2025-09-04 02:36:49,905 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine-interface/64fe77e0-9b1b-4ae6-8e95-504a476c93aa 2025-09-04 02:36:49,916 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/df74802d-7321-489f-a302-7515d5ac2254 2025-09-04 02:36:49,924 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine-interface/64fe77e0-9b1b-4ae6-8e95-504a476c93aa 2025-09-04 02:36:49,933 - DEBUG - Requesting: http://10.0.0.131:8082/instance-ip/4f1fea96-c23e-4dcf-817c-a31723968a4c 2025-09-04 02:36:49,942 - DEBUG - Requesting: http://10.0.0.131:8082/instance-ip/f7068bd6-fdab-44d4-9a09-eb33f983523f 2025-09-04 02:36:49,950 - DEBUG - Verifying in api server 10.0.0.131 2025-09-04 02:36:49,950 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/df74802d-7321-489f-a302-7515d5ac2254 2025-09-04 02:36:49,957 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine-interface/64fe77e0-9b1b-4ae6-8e95-504a476c93aa 2025-09-04 02:36:49,969 - DEBUG - Requesting: http://10.0.0.131:8082/instance-ip/4f1fea96-c23e-4dcf-817c-a31723968a4c 2025-09-04 02:36:49,976 - DEBUG - Requesting: http://10.0.0.131:8082/instance-ip/f7068bd6-fdab-44d4-9a09-eb33f983523f 2025-09-04 02:36:49,986 - INFO - VM ctest-VM21-04387429 verfication in all API Servers passed 2025-09-04 02:36:50,005 - DEBUG - VM ctest-VM21-04387429 Tap interface: {'index': '3', 'name': 'tap64fe77e0-9b', 'uuid': '64fe77e0-9b1b-4ae6-8e95-504a476c93aa', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN2-33211179:ctest-VN2-33211179', '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-16254764:ctest-VN2-33211179', 'vm_uuid': 'df74802d-7321-489f-a302-7515d5ac2254', 'vm_name': 'ctest-VM21-04387429', 'ip_addr': '17.248.79.195', 'mac_addr': '02:64:fe:77:e0:9b', '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-16254764:64fe77e0-9b1b-4ae6-8e95-504a476c93aa', 'sg_uuid_list': ['d58b8dc0-ad69-4b43-b89b-01f480041fd0'], 'static_route_list': None, 'vm_project_uuid': 'd3251d2d-fd0b-4ab3-a150-7787310badda', 'admin_state': 'Enabled', 'flow_key_idx': '30', 'allowed_address_pair_list': None, 'ip6_addr': '26c6:c916:397b:383c:b814:8755: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-09-04 02:36:50,005 - DEBUG - Agent 10.0.0.242 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN2-33211179:ctest-VN2-33211179 2025-09-04 02:36:50,011 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN2-33211179:ctest-VN2-33211179', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN2-33211179', 'table_label': '-1', 'vxlan_id': '14', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.192:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2025-09-04 02:36:50,111 - DEBUG - VM ctest-VM21-04387429 : Tap interface tap64fe77e0-9b is set to Active 2025-09-04 02:36:50,112 - DEBUG - Tap interface tap64fe77e0-9b detail : {'index': '3', 'name': 'tap64fe77e0-9b', 'uuid': '64fe77e0-9b1b-4ae6-8e95-504a476c93aa', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN2-33211179:ctest-VN2-33211179', '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-16254764:ctest-VN2-33211179', 'vm_uuid': 'df74802d-7321-489f-a302-7515d5ac2254', 'vm_name': 'ctest-VM21-04387429', 'ip_addr': '17.248.79.195', 'mac_addr': '02:64:fe:77:e0:9b', '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-16254764:64fe77e0-9b1b-4ae6-8e95-504a476c93aa', 'sg_uuid_list': ['d58b8dc0-ad69-4b43-b89b-01f480041fd0'], 'static_route_list': None, 'vm_project_uuid': 'd3251d2d-fd0b-4ab3-a150-7787310badda', 'admin_state': 'Enabled', 'flow_key_idx': '30', 'allowed_address_pair_list': None, 'ip6_addr': '26c6:c916:397b:383c:b814:8755: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-09-04 02:36:50,231 - DEBUG - Starting Layer 2 verification in Agent 2025-09-04 02:36:50,244 - DEBUG - Layer 2 path is seen for VM MAC 02:64:fe:77:e0:9b in agent 10.0.0.242 2025-09-04 02:36:50,244 - DEBUG - Active layer 2 route in agent is present for VMI tap64fe77e0-9b 2025-09-04 02:36:50,244 - DEBUG - L2 label(29) matches bw route table and interface table 2025-09-04 02:36:50,255 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN2-33211179 is found in Agent of node 10.0.0.242 2025-09-04 02:36:50,402 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN2-33211179 is consistent in agent 10.0.0.242 2025-09-04 02:36:50,402 - DEBUG - Route for VM IP ['17.248.79.195', '26c6:c916:397b:383c:b814:8755:0:3'] is consistent in agent 10.0.0.242 2025-09-04 02:36:50,402 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN2-33211179 verification for VM ctest-VM21-04387429 in Agent 10.0.0.242 passed 2025-09-04 02:36:50,515 - DEBUG - Starting all layer 2 verification in agent 10.0.0.242 2025-09-04 02:36:50,526 - DEBUG - Route for VM MAC 02:64:fe:77:e0:9b is consistent in agent 10.0.0.242 2025-09-04 02:36:51,697 - 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=3.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 = 3.340/3.423/3.506/0.083 ms') 2025-09-04 02:36:51,697 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-04387429 passed 2025-09-04 02:36:51,697 - INFO - VM ctest-VM21-04387429 verifications in Compute nodes passed 2025-09-04 02:36:51,935 - DEBUG - Validated VM route 17.248.79.195 in vrouter of 10.0.0.242 2025-09-04 02:36:51,942 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0 2025-09-04 02:36:51,942 - INFO - Validated routes of VM ctest-VM21-04387429 in all vrouters 2025-09-04 02:36:52,403 - DEBUG - Starting all layer2 verification in 10.0.0.57 Control Node 2025-09-04 02:36:52,530 - DEBUG - Layer2 route found for VM MAC 02:64:fe:77:e0:9b in Control-node 10.0.0.57 2025-09-04 02:36:52,530 - DEBUG - L2 Label for VM ctest-VM21-04387429 same between Control-node 10.0.0.57 and Agent, Expected: 29, Seen: 29 2025-09-04 02:36:52,637 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.57, not supported 2025-09-04 02:36:52,637 - DEBUG - Starting all layer2 verification in 10.0.0.38 Control Node 2025-09-04 02:36:52,759 - DEBUG - Layer2 route found for VM MAC 02:64:fe:77:e0:9b in Control-node 10.0.0.38 2025-09-04 02:36:52,759 - DEBUG - L2 Label for VM ctest-VM21-04387429 same between Control-node 10.0.0.38 and Agent, Expected: 29, Seen: 29 2025-09-04 02:36:52,874 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.38, not supported 2025-09-04 02:36:52,874 - INFO - Verification in Control-nodes for VM ctest-VM21-04387429 passed 2025-09-04 02:36:52,874 - DEBUG - Verifying the vm in opserver 2025-09-04 02:36:52,874 - DEBUG - Verifying in collector 10.0.0.131 ... 2025-09-04 02:36:52,874 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-machine/df74802d-7321-489f-a302-7515d5ac2254?flat 2025-09-04 02:36:52,895 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-16254764:64fe77e0-9b1b-4ae6-8e95-504a476c93aa?flat 2025-09-04 02:36:52,914 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-16254764:64fe77e0-9b1b-4ae6-8e95-504a476c93aa?flat 2025-09-04 02:36:52,933 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-16254764:64fe77e0-9b1b-4ae6-8e95-504a476c93aa?flat 2025-09-04 02:36:52,952 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-machine/df74802d-7321-489f-a302-7515d5ac2254?flat 2025-09-04 02:36:52,971 - DEBUG - Verifying vm in vn uve 2025-09-04 02:36:52,971 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-16254764:64fe77e0-9b1b-4ae6-8e95-504a476c93aa?flat 2025-09-04 02:36:52,989 - DEBUG - VM uve shows interface as {'ip4_active': True, 'admin_state': True, 'is_health_check_active': True, 'mac_address': '02:64:fe:77:e0:9b', 'uuid': '64fe77e0-9b1b-4ae6-8e95-504a476c93aa', 'label': 25, 'active': True, 'vn_uuid': '551df278-2efc-45d4-abf6-e8c67939be2e', 'ip6_address': '26c6:c916:397b:383c:b814:8755:0:3', 'rx_vlan': 65535, '__T': 1756953387361486, 'fixed_ip6_list': ['26c6:c916:397b:383c:b814:8755:0:3'], 'l2_active': True, 'ip6_active': True, 'ip_address': '17.248.79.195', 'vm_uuid': 'df74802d-7321-489f-a302-7515d5ac2254', 'tx_vlan': 65535, 'port_mirror_enabled': False, 'gateway': '17.248.79.193', 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN2-33211179', 'vhostuser_mode': 0, 'fixed_ip4_list': ['17.248.79.195'], 'vm_name': 'ctest-VM21-04387429'} 2025-09-04 02:36:52,989 - DEBUG - VM uve shows ip address as ['17.248.79.195', '26c6:c916:397b:383c:b814:8755:0:3'] 2025-09-04 02:36:52,989 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN2-33211179 2025-09-04 02:36:52,989 - INFO - Verifying through opserver in 10.0.0.57 2025-09-04 02:36:52,990 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN2-33211179?flat 2025-09-04 02:36:53,013 - DEBUG - expected vm list df74802d-7321-489f-a302-7515d5ac2254 2025-09-04 02:36:53,013 - DEBUG - Extracted vm list ['df74802d-7321-489f-a302-7515d5ac2254'] 2025-09-04 02:36:53,013 - INFO - VM df74802d-7321-489f-a302-7515d5ac2254 is present in default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN2-33211179 2025-09-04 02:36:53,013 - DEBUG - Verifying vm in vrouter uve 2025-09-04 02:36:53,013 - DEBUG - Getting info from collector 10.0.0.131.. 2025-09-04 02:36:53,013 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-machine/df74802d-7321-489f-a302-7515d5ac2254?flat 2025-09-04 02:36:53,029 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-4012-2'] 2025-09-04 02:36:53,029 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4012-2:Compute:contrail-vrouter-agent:0?flat 2025-09-04 02:36:53,066 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4012-2?flat 2025-09-04 02:36:53,105 - DEBUG - VM df74802d-7321-489f-a302-7515d5ac2254 is present in vrouter an-jenkins-deploy-platform-ansible-os-4012-2 uve 2025-09-04 02:36:53,105 - DEBUG - Validated that VM ctest-VM21-04387429 is in Vrouter an-jenkins-deploy-platform-ansible-os-4012-2 UVE 2025-09-04 02:36:53,105 - DEBUG - Verifying vm tap interface/vn in vrouter uve 2025-09-04 02:36:53,118 - DEBUG - Expected tap interface of VM uuid df74802d-7321-489f-a302-7515d5ac2254 is default-domain:ctest-TestPolicyAclIpv4v6-16254764:64fe77e0-9b1b-4ae6-8e95-504a476c93aa 2025-09-04 02:36:53,118 - DEBUG - Expected VN of VM uuid df74802d-7321-489f-a302-7515d5ac2254 is default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN2-33211179 2025-09-04 02:36:53,118 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4012-2:Compute:contrail-vrouter-agent:0?flat 2025-09-04 02:36:53,174 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4012-2?flat 2025-09-04 02:36:53,208 - DEBUG - VM df74802d-7321-489f-a302-7515d5ac2254 is present in vrouter an-jenkins-deploy-platform-ansible-os-4012-2 uve 2025-09-04 02:36:53,208 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-16254764:64fe77e0-9b1b-4ae6-8e95-504a476c93aa of vm df74802d-7321-489f-a302-7515d5ac2254 is present in vrouter an-jenkins-deploy-platform-ansible-os-4012-2 uve 2025-09-04 02:36:53,208 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN2-33211179 of vm df74802d-7321-489f-a302-7515d5ac2254 is present in vrouter an-jenkins-deploy-platform-ansible-os-4012-2 uve 2025-09-04 02:36:53,208 - DEBUG - Verifying the df74802d-7321-489f-a302-7515d5ac2254 virtual network link through opserver 10.0.0.57 2025-09-04 02:36:53,208 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-machines 2025-09-04 02:36:53,221 - DEBUG - VM link and name as {'name': 'df74802d-7321-489f-a302-7515d5ac2254', 'href': 'http://10.0.0.57:8081/analytics/uves/virtual-machine/df74802d-7321-489f-a302-7515d5ac2254?flat'} 2025-09-04 02:36:53,221 - DEBUG - Verifying the df74802d-7321-489f-a302-7515d5ac2254 virtual network link through opserver 10.0.0.38 2025-09-04 02:36:53,221 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines 2025-09-04 02:36:53,236 - DEBUG - VM link and name as {'name': 'df74802d-7321-489f-a302-7515d5ac2254', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-machine/df74802d-7321-489f-a302-7515d5ac2254?flat'} 2025-09-04 02:36:53,236 - DEBUG - Verifying the df74802d-7321-489f-a302-7515d5ac2254 virtual network link through opserver 10.0.0.131 2025-09-04 02:36:53,236 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-machines 2025-09-04 02:36:53,252 - DEBUG - VM link and name as {'name': 'df74802d-7321-489f-a302-7515d5ac2254', 'href': 'http://10.0.0.131:8081/analytics/uves/virtual-machine/df74802d-7321-489f-a302-7515d5ac2254?flat'} 2025-09-04 02:36:53,252 - INFO - VM ctest-VM21-04387429 validations in Opserver passed 2025-09-04 02:36:53,407 - DEBUG - VM is in ACTIVE state now 2025-09-04 02:36:53,408 - INFO - VM name : ctest-VM31-37257243 2025-09-04 02:36:53,497 - DEBUG - VM ctest-VM31-37257243 ID is fcd32582-9884-49d3-8847-2e3a62efbe38 2025-09-04 02:36:53,583 - DEBUG - VM is in ACTIVE state now 2025-09-04 02:36:53,583 - DEBUG - VM ctest-VM31-37257243 launched on Node an-jenkins-deploy-platform-ansible-os-4012-1 2025-09-04 02:36:53,676 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/fcd32582-9884-49d3-8847-2e3a62efbe38 2025-09-04 02:36:53,686 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/fcd32582-9884-49d3-8847-2e3a62efbe38 2025-09-04 02:36:53,693 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine-interface/87d9dfe1-e43e-4e8a-889e-5bee8c73a5a4 2025-09-04 02:36:53,702 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/fcd32582-9884-49d3-8847-2e3a62efbe38 2025-09-04 02:36:53,709 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine-interface/87d9dfe1-e43e-4e8a-889e-5bee8c73a5a4 2025-09-04 02:36:53,720 - DEBUG - Requesting: http://10.0.0.131:8082/instance-ip/a26bddff-ea92-4c67-85ce-5264dab14773 2025-09-04 02:36:53,729 - DEBUG - Requesting: http://10.0.0.131:8082/instance-ip/d75a3b6e-4a7c-4be8-aec4-32a56f96fb6b 2025-09-04 02:36:53,737 - DEBUG - Verifying in api server 10.0.0.131 2025-09-04 02:36:53,737 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/fcd32582-9884-49d3-8847-2e3a62efbe38 2025-09-04 02:36:53,744 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine-interface/87d9dfe1-e43e-4e8a-889e-5bee8c73a5a4 2025-09-04 02:36:53,752 - DEBUG - Requesting: http://10.0.0.131:8082/instance-ip/a26bddff-ea92-4c67-85ce-5264dab14773 2025-09-04 02:36:53,759 - DEBUG - Requesting: http://10.0.0.131:8082/instance-ip/d75a3b6e-4a7c-4be8-aec4-32a56f96fb6b 2025-09-04 02:36:53,767 - INFO - VM ctest-VM31-37257243 verfication in all API Servers passed 2025-09-04 02:36:53,787 - DEBUG - VM ctest-VM31-37257243 Tap interface: {'index': '4', 'name': 'tap87d9dfe1-e4', 'uuid': '87d9dfe1-e43e-4e8a-889e-5bee8c73a5a4', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN3-74964432:ctest-VN3-74964432', '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-16254764:ctest-VN3-74964432', 'vm_uuid': 'fcd32582-9884-49d3-8847-2e3a62efbe38', 'vm_name': 'ctest-VM31-37257243', 'ip_addr': '15.188.138.195', 'mac_addr': '02:87:d9:df:e1:e4', '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-16254764:87d9dfe1-e43e-4e8a-889e-5bee8c73a5a4', 'sg_uuid_list': ['d58b8dc0-ad69-4b43-b89b-01f480041fd0'], 'static_route_list': None, 'vm_project_uuid': 'd3251d2d-fd0b-4ab3-a150-7787310badda', 'admin_state': 'Enabled', 'flow_key_idx': '40', 'allowed_address_pair_list': None, 'ip6_addr': '3507:8c49:5a5:216c:e813:6430: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-09-04 02:36:53,787 - DEBUG - Agent 10.0.0.241 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN3-74964432:ctest-VN3-74964432 2025-09-04 02:36:53,794 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN3-74964432:ctest-VN3-74964432', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN3-74964432', 'table_label': '-1', 'vxlan_id': '15', 'evpnindex': '3', 'brindex': '3', 'mplsindex': '0', 'RD': '10.20.0.18:3', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2025-09-04 02:36:53,882 - DEBUG - VM ctest-VM31-37257243 : Tap interface tap87d9dfe1-e4 is set to Active 2025-09-04 02:36:53,882 - DEBUG - Tap interface tap87d9dfe1-e4 detail : {'index': '4', 'name': 'tap87d9dfe1-e4', 'uuid': '87d9dfe1-e43e-4e8a-889e-5bee8c73a5a4', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN3-74964432:ctest-VN3-74964432', '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-16254764:ctest-VN3-74964432', 'vm_uuid': 'fcd32582-9884-49d3-8847-2e3a62efbe38', 'vm_name': 'ctest-VM31-37257243', 'ip_addr': '15.188.138.195', 'mac_addr': '02:87:d9:df:e1:e4', '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-16254764:87d9dfe1-e43e-4e8a-889e-5bee8c73a5a4', 'sg_uuid_list': ['d58b8dc0-ad69-4b43-b89b-01f480041fd0'], 'static_route_list': None, 'vm_project_uuid': 'd3251d2d-fd0b-4ab3-a150-7787310badda', 'admin_state': 'Enabled', 'flow_key_idx': '40', 'allowed_address_pair_list': None, 'ip6_addr': '3507:8c49:5a5:216c:e813:6430: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-09-04 02:36:54,029 - DEBUG - Starting Layer 2 verification in Agent 2025-09-04 02:36:54,039 - DEBUG - Layer 2 path is seen for VM MAC 02:87:d9:df:e1:e4 in agent 10.0.0.241 2025-09-04 02:36:54,039 - DEBUG - Active layer 2 route in agent is present for VMI tap87d9dfe1-e4 2025-09-04 02:36:54,039 - DEBUG - L2 label(36) matches bw route table and interface table 2025-09-04 02:36:54,047 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN3-74964432 is found in Agent of node 10.0.0.241 2025-09-04 02:36:54,210 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN3-74964432 is consistent in agent 10.0.0.241 2025-09-04 02:36:54,210 - DEBUG - Route for VM IP ['15.188.138.195', '3507:8c49:5a5:216c:e813:6430:0:3'] is consistent in agent 10.0.0.241 2025-09-04 02:36:54,210 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN3-74964432 verification for VM ctest-VM31-37257243 in Agent 10.0.0.241 passed 2025-09-04 02:36:54,326 - DEBUG - Starting all layer 2 verification in agent 10.0.0.241 2025-09-04 02:36:54,338 - DEBUG - Route for VM MAC 02:87:d9:df:e1:e4 is consistent in agent 10.0.0.241 2025-09-04 02:36:55,526 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=5.44 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=3.48 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 3.482/4.463/5.444/0.981 ms') 2025-09-04 02:36:55,526 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-37257243 passed 2025-09-04 02:36:55,526 - INFO - VM ctest-VM31-37257243 verifications in Compute nodes passed 2025-09-04 02:36:55,802 - DEBUG - Validated VM route 15.188.138.195 in vrouter of 10.0.0.241 2025-09-04 02:36:55,811 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0 2025-09-04 02:36:55,811 - INFO - Validated routes of VM ctest-VM31-37257243 in all vrouters 2025-09-04 02:36:56,332 - DEBUG - Starting all layer2 verification in 10.0.0.57 Control Node 2025-09-04 02:36:56,464 - DEBUG - Layer2 route found for VM MAC 02:87:d9:df:e1:e4 in Control-node 10.0.0.57 2025-09-04 02:36:56,464 - DEBUG - L2 Label for VM ctest-VM31-37257243 same between Control-node 10.0.0.57 and Agent, Expected: 36, Seen: 36 2025-09-04 02:36:56,585 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.57, not supported 2025-09-04 02:36:56,585 - DEBUG - Starting all layer2 verification in 10.0.0.38 Control Node 2025-09-04 02:36:56,719 - DEBUG - Layer2 route found for VM MAC 02:87:d9:df:e1:e4 in Control-node 10.0.0.38 2025-09-04 02:36:56,719 - DEBUG - L2 Label for VM ctest-VM31-37257243 same between Control-node 10.0.0.38 and Agent, Expected: 36, Seen: 36 2025-09-04 02:36:56,833 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.38, not supported 2025-09-04 02:36:56,833 - INFO - Verification in Control-nodes for VM ctest-VM31-37257243 passed 2025-09-04 02:36:56,833 - DEBUG - Verifying the vm in opserver 2025-09-04 02:36:56,833 - DEBUG - Verifying in collector 10.0.0.131 ... 2025-09-04 02:36:56,833 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-machine/fcd32582-9884-49d3-8847-2e3a62efbe38?flat 2025-09-04 02:36:56,858 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-16254764:87d9dfe1-e43e-4e8a-889e-5bee8c73a5a4?flat 2025-09-04 02:36:56,876 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-16254764:87d9dfe1-e43e-4e8a-889e-5bee8c73a5a4?flat 2025-09-04 02:36:56,893 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-16254764:87d9dfe1-e43e-4e8a-889e-5bee8c73a5a4?flat 2025-09-04 02:36:56,912 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-machine/fcd32582-9884-49d3-8847-2e3a62efbe38?flat 2025-09-04 02:36:56,927 - DEBUG - Verifying vm in vn uve 2025-09-04 02:36:56,927 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-16254764:87d9dfe1-e43e-4e8a-889e-5bee8c73a5a4?flat 2025-09-04 02:36:56,946 - DEBUG - VM uve shows interface as {'ip4_active': True, 'gateway': '15.188.138.193', 'vm_name': 'ctest-VM31-37257243', 'vm_uuid': 'fcd32582-9884-49d3-8847-2e3a62efbe38', 'uuid': '87d9dfe1-e43e-4e8a-889e-5bee8c73a5a4', 'label': 32, 'fixed_ip6_list': ['3507:8c49:5a5:216c:e813:6430:0:3'], 'rx_vlan': 65535, '__T': 1756953366970484, 'tx_vlan': 65535, 'fixed_ip4_list': ['15.188.138.195'], 'port_mirror_enabled': False, 'mac_address': '02:87:d9:df:e1:e4', 'vn_uuid': '9294bf9a-f0e9-4b57-afa5-f1c553b6e34c', 'vhostuser_mode': 0, 'ip_address': '15.188.138.195', 'l2_active': True, 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN3-74964432', 'ip6_active': True, 'admin_state': True, 'is_health_check_active': True, 'ip6_address': '3507:8c49:5a5:216c:e813:6430:0:3', 'active': True} 2025-09-04 02:36:56,946 - DEBUG - VM uve shows ip address as ['15.188.138.195', '3507:8c49:5a5:216c:e813:6430:0:3'] 2025-09-04 02:36:56,946 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN3-74964432 2025-09-04 02:36:56,946 - INFO - Verifying through opserver in 10.0.0.57 2025-09-04 02:36:56,947 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN3-74964432?flat 2025-09-04 02:36:56,972 - DEBUG - expected vm list fcd32582-9884-49d3-8847-2e3a62efbe38 2025-09-04 02:36:56,972 - DEBUG - Extracted vm list ['fcd32582-9884-49d3-8847-2e3a62efbe38'] 2025-09-04 02:36:56,972 - INFO - VM fcd32582-9884-49d3-8847-2e3a62efbe38 is present in default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN3-74964432 2025-09-04 02:36:56,972 - DEBUG - Verifying vm in vrouter uve 2025-09-04 02:36:56,972 - DEBUG - Getting info from collector 10.0.0.131.. 2025-09-04 02:36:56,972 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-machine/fcd32582-9884-49d3-8847-2e3a62efbe38?flat 2025-09-04 02:36:56,989 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-4012-1'] 2025-09-04 02:36:56,989 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4012-1:Compute:contrail-vrouter-agent:0?flat 2025-09-04 02:36:57,022 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4012-1?flat 2025-09-04 02:36:57,051 - DEBUG - VM fcd32582-9884-49d3-8847-2e3a62efbe38 is present in vrouter an-jenkins-deploy-platform-ansible-os-4012-1 uve 2025-09-04 02:36:57,052 - DEBUG - Validated that VM ctest-VM31-37257243 is in Vrouter an-jenkins-deploy-platform-ansible-os-4012-1 UVE 2025-09-04 02:36:57,052 - DEBUG - Verifying vm tap interface/vn in vrouter uve 2025-09-04 02:36:57,059 - DEBUG - Expected tap interface of VM uuid fcd32582-9884-49d3-8847-2e3a62efbe38 is default-domain:ctest-TestPolicyAclIpv4v6-16254764:87d9dfe1-e43e-4e8a-889e-5bee8c73a5a4 2025-09-04 02:36:57,059 - DEBUG - Expected VN of VM uuid fcd32582-9884-49d3-8847-2e3a62efbe38 is default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN3-74964432 2025-09-04 02:36:57,059 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4012-1:Compute:contrail-vrouter-agent:0?flat 2025-09-04 02:36:57,093 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4012-1?flat 2025-09-04 02:36:57,124 - DEBUG - VM fcd32582-9884-49d3-8847-2e3a62efbe38 is present in vrouter an-jenkins-deploy-platform-ansible-os-4012-1 uve 2025-09-04 02:36:57,124 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-16254764:87d9dfe1-e43e-4e8a-889e-5bee8c73a5a4 of vm fcd32582-9884-49d3-8847-2e3a62efbe38 is present in vrouter an-jenkins-deploy-platform-ansible-os-4012-1 uve 2025-09-04 02:36:57,124 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN3-74964432 of vm fcd32582-9884-49d3-8847-2e3a62efbe38 is present in vrouter an-jenkins-deploy-platform-ansible-os-4012-1 uve 2025-09-04 02:36:57,124 - DEBUG - Verifying the fcd32582-9884-49d3-8847-2e3a62efbe38 virtual network link through opserver 10.0.0.57 2025-09-04 02:36:57,124 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-machines 2025-09-04 02:36:57,136 - DEBUG - VM link and name as {'name': 'fcd32582-9884-49d3-8847-2e3a62efbe38', 'href': 'http://10.0.0.57:8081/analytics/uves/virtual-machine/fcd32582-9884-49d3-8847-2e3a62efbe38?flat'} 2025-09-04 02:36:57,136 - DEBUG - Verifying the fcd32582-9884-49d3-8847-2e3a62efbe38 virtual network link through opserver 10.0.0.38 2025-09-04 02:36:57,136 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines 2025-09-04 02:36:57,148 - DEBUG - VM link and name as {'name': 'fcd32582-9884-49d3-8847-2e3a62efbe38', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-machine/fcd32582-9884-49d3-8847-2e3a62efbe38?flat'} 2025-09-04 02:36:57,148 - DEBUG - Verifying the fcd32582-9884-49d3-8847-2e3a62efbe38 virtual network link through opserver 10.0.0.131 2025-09-04 02:36:57,148 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-machines 2025-09-04 02:36:57,161 - DEBUG - VM link and name as {'name': 'fcd32582-9884-49d3-8847-2e3a62efbe38', 'href': 'http://10.0.0.131:8081/analytics/uves/virtual-machine/fcd32582-9884-49d3-8847-2e3a62efbe38?flat'} 2025-09-04 02:36:57,162 - INFO - VM ctest-VM31-37257243 validations in Opserver passed 2025-09-04 02:36:57,162 - INFO - Waiting for VM ctest-VM11-48649270 to be up.. 2025-09-04 02:36:57,162 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-04 02:36:57,162 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/ab248664-787f-4511-9f0a-aa540af76c20 2025-09-04 02:36:57,169 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine-interface/b0230ea8-e2ba-40de-9149-aae3cd65a6ed 2025-09-04 02:36:58,338 - 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.48 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.25 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 1.249/2.362/3.475/1.113 ms') 2025-09-04 02:36:58,338 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-48649270 passed 2025-09-04 02:36:58,411 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-04 02:36:58,411 - DEBUG - Waiting to SSH to VM ctest-VM11-48649270, IP 90.102.68.67, Port 22 2025-09-04 02:36:58,468 - 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-09-04 02:36:58,556 - DEBUG - VM ctest-VM11-48649270 is NOT ready for SSH connections, VM status: ACTIVE 2025-09-04 02:37:03,557 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-04 02:37:03,557 - DEBUG - Waiting to SSH to VM ctest-VM11-48649270, IP 90.102.68.67, Port 22 2025-09-04 02:37:03,626 - 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-09-04 02:37:03,723 - DEBUG - VM ctest-VM11-48649270 is NOT ready for SSH connections, VM status: ACTIVE 2025-09-04 02:37:08,724 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-04 02:37:08,724 - DEBUG - Waiting to SSH to VM ctest-VM11-48649270, IP 90.102.68.67, Port 22 2025-09-04 02:37:08,879 - DEBUG - VM ctest-VM11-48649270 is ready for SSH connections 2025-09-04 02:37:08,879 - INFO - Waiting for VM ctest-VM21-04387429 to be up.. 2025-09-04 02:37:08,879 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-04 02:37:08,879 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/df74802d-7321-489f-a302-7515d5ac2254 2025-09-04 02:37:08,887 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine-interface/64fe77e0-9b1b-4ae6-8e95-504a476c93aa 2025-09-04 02:37:10,047 - 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.62 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.822 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.822/1.721/2.620/0.899 ms') 2025-09-04 02:37:10,047 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-04387429 passed 2025-09-04 02:37:10,118 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-04 02:37:10,118 - DEBUG - Waiting to SSH to VM ctest-VM21-04387429, IP 17.248.79.195, Port 22 2025-09-04 02:37:10,286 - DEBUG - VM ctest-VM21-04387429 is ready for SSH connections 2025-09-04 02:37:10,286 - INFO - Waiting for VM ctest-VM31-37257243 to be up.. 2025-09-04 02:37:10,286 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-04 02:37:10,286 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/fcd32582-9884-49d3-8847-2e3a62efbe38 2025-09-04 02:37:10,296 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine-interface/87d9dfe1-e43e-4e8a-889e-5bee8c73a5a4 2025-09-04 02:37:11,460 - 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.27 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.434 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.434/2.351/4.268/1.917 ms') 2025-09-04 02:37:11,460 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-37257243 passed 2025-09-04 02:37:11,530 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-04 02:37:11,531 - DEBUG - Waiting to SSH to VM ctest-VM31-37257243, IP 15.188.138.195, Port 22 2025-09-04 02:37:11,701 - DEBUG - VM ctest-VM31-37257243 is ready for SSH connections 2025-09-04 02:37:11,702 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 17.248.79.195, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.241, gateway password: c0ntrail123 2025-09-04 02:37:11,702 - DEBUG - ping -s 56 -c 3 -W 1 17.248.79.195 2025-09-04 02:37:35,266 - DEBUG - PING 17.248.79.195 (17.248.79.195) 56(84) bytes of data. 64 bytes from 17.248.79.195: icmp_req=1 ttl=63 time=10.1 ms 64 bytes from 17.248.79.195: icmp_req=2 ttl=63 time=1.54 ms 64 bytes from 17.248.79.195: icmp_req=3 ttl=63 time=2.96 ms --- 17.248.79.195 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2004ms rtt min/avg/max/mdev = 1.548/4.887/10.146/3.764 ms 2025-09-04 02:37:35,266 - INFO - Ping to IP 17.248.79.195 from VM ctest-VM11-48649270 passed 2025-09-04 02:37:35,267 - DEBUG - Running remote_cmd, Cmd : ping6 -s 56 -c 3 -W 1 26c6:c916:397b:383c:b814:8755:0:3, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.241, gateway password: c0ntrail123 2025-09-04 02:37:35,267 - DEBUG - ping6 -s 56 -c 3 -W 1 26c6:c916:397b:383c:b814:8755:0:3 2025-09-04 02:37:37,799 - DEBUG - PING 26c6:c916:397b:383c:b814:8755:0:3(26c6:c916:397b:383c:b814:8755:0:3) 56 data bytes 64 bytes from 26c6:c916:397b:383c:b814:8755:0:3: icmp_seq=1 ttl=64 time=9.70 ms 64 bytes from 26c6:c916:397b:383c:b814:8755:0:3: icmp_seq=2 ttl=64 time=3.12 ms 64 bytes from 26c6:c916:397b:383c:b814:8755:0:3: icmp_seq=3 ttl=64 time=1.28 ms --- 26c6:c916:397b:383c:b814:8755:0:3 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2003ms rtt min/avg/max/mdev = 1.281/4.701/9.700/3.614 ms 2025-09-04 02:37:37,799 - INFO - Ping to IP 26c6:c916:397b:383c:b814:8755:0:3 from VM ctest-VM11-48649270 passed 2025-09-04 02:37:37,799 - INFO - Test with src as policy and dst as any PASSED 2025-09-04 02:37:37,799 - INFO - Deleting VM ctest-VM31-37257243 2025-09-04 02:37:37,872 - DEBUG - Verifying in api server 10.0.0.131 2025-09-04 02:37:37,872 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/fcd32582-9884-49d3-8847-2e3a62efbe38 2025-09-04 02:37:37,881 - DEBUG - VM ID fcd32582-9884-49d3-8847-2e3a62efbe38 of VM ctest-VM31-37257243 is still found in API Server 2025-09-04 02:37:39,882 - DEBUG - Verifying in api server 10.0.0.131 2025-09-04 02:37:39,882 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/fcd32582-9884-49d3-8847-2e3a62efbe38 2025-09-04 02:37:39,889 - DEBUG - Response Code: 404 2025-09-04 02:37:39,889 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/fcd32582-9884-49d3-8847-2e3a62efbe38 2025-09-04 02:37:39,895 - DEBUG - Response Code: 404 2025-09-04 02:37:39,895 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/fcd32582-9884-49d3-8847-2e3a62efbe38 2025-09-04 02:37:39,902 - DEBUG - Response Code: 404 2025-09-04 02:37:39,902 - INFO - VM ctest-VM31-37257243 is fully removed in API-Server 2025-09-04 02:37:40,060 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found 2025-09-04 02:37:40,104 - DEBUG - Validated that vrouter 10.0.0.241 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN3-74964432 2025-09-04 02:37:40,114 - DEBUG - Validated that vrouter 10.0.0.242 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN3-74964432 2025-09-04 02:37:40,114 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN3-74964432 2025-09-04 02:37:40,114 - INFO - VM ctest-VM31-37257243 is removed in Compute, and routes are removed in all compute nodes 2025-09-04 02:37:40,352 - INFO - Routes for VM ctest-VM31-37257243 is removed in all control-nodes 2025-09-04 02:37:40,352 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4012-1:Compute:contrail-vrouter-agent:0?flat 2025-09-04 02:37:40,391 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4012-1?flat 2025-09-04 02:37:40,422 - DEBUG - VM fcd32582-9884-49d3-8847-2e3a62efbe38 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4012-1 uve 2025-09-04 02:37:40,422 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-09-04 02:37:40,422 - DEBUG - interface for vm fcd32582-9884-49d3-8847-2e3a62efbe38 deleted from vrouter uve ... 2025-09-04 02:37:40,422 - DEBUG - Deleted interface not in error interface list ... 2025-09-04 02:37:43,423 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4012-1:Compute:contrail-vrouter-agent:0?flat 2025-09-04 02:37:43,462 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4012-1?flat 2025-09-04 02:37:43,494 - DEBUG - VM fcd32582-9884-49d3-8847-2e3a62efbe38 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4012-1 uve 2025-09-04 02:37:43,494 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-09-04 02:37:43,494 - DEBUG - interface for vm fcd32582-9884-49d3-8847-2e3a62efbe38 deleted from vrouter uve ... 2025-09-04 02:37:43,494 - DEBUG - Deleted interface not in error interface list ... 2025-09-04 02:37:46,494 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4012-1:Compute:contrail-vrouter-agent:0?flat 2025-09-04 02:37:46,540 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4012-1?flat 2025-09-04 02:37:46,579 - DEBUG - VM fcd32582-9884-49d3-8847-2e3a62efbe38 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4012-1 uve 2025-09-04 02:37:46,579 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-09-04 02:37:46,579 - DEBUG - interface for vm fcd32582-9884-49d3-8847-2e3a62efbe38 deleted from vrouter uve ... 2025-09-04 02:37:46,579 - DEBUG - Deleted interface not in error interface list ... 2025-09-04 02:37:49,580 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4012-1:Compute:contrail-vrouter-agent:0?flat 2025-09-04 02:37:49,620 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4012-1?flat 2025-09-04 02:37:49,651 - DEBUG - VM fcd32582-9884-49d3-8847-2e3a62efbe38 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4012-1 uve 2025-09-04 02:37:49,651 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-09-04 02:37:49,651 - DEBUG - interface for vm fcd32582-9884-49d3-8847-2e3a62efbe38 deleted from vrouter uve ... 2025-09-04 02:37:49,651 - DEBUG - Deleted interface not in error interface list ... 2025-09-04 02:37:52,652 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4012-1:Compute:contrail-vrouter-agent:0?flat 2025-09-04 02:37:52,687 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4012-1?flat 2025-09-04 02:37:52,718 - DEBUG - VM fcd32582-9884-49d3-8847-2e3a62efbe38 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4012-1 uve 2025-09-04 02:37:52,718 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-09-04 02:37:52,718 - DEBUG - interface for vm fcd32582-9884-49d3-8847-2e3a62efbe38 deleted from vrouter uve ... 2025-09-04 02:37:52,718 - DEBUG - Deleted interface not in error interface list ... 2025-09-04 02:37:55,719 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4012-1:Compute:contrail-vrouter-agent:0?flat 2025-09-04 02:37:55,763 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4012-1?flat 2025-09-04 02:37:55,804 - DEBUG - VM fcd32582-9884-49d3-8847-2e3a62efbe38 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4012-1 uve 2025-09-04 02:37:55,804 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-09-04 02:37:55,804 - DEBUG - interface for vm fcd32582-9884-49d3-8847-2e3a62efbe38 deleted from vrouter uve ... 2025-09-04 02:37:55,804 - DEBUG - Deleted interface not in error interface list ... 2025-09-04 02:37:58,805 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4012-1:Compute:contrail-vrouter-agent:0?flat 2025-09-04 02:37:58,840 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4012-1?flat 2025-09-04 02:37:58,869 - DEBUG - VM fcd32582-9884-49d3-8847-2e3a62efbe38 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4012-1 uve 2025-09-04 02:37:58,869 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-09-04 02:37:58,869 - DEBUG - interface for vm fcd32582-9884-49d3-8847-2e3a62efbe38 deleted from vrouter uve ... 2025-09-04 02:37:58,869 - DEBUG - Deleted interface not in error interface list ... 2025-09-04 02:38:01,870 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4012-1:Compute:contrail-vrouter-agent:0?flat 2025-09-04 02:38:01,934 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4012-1?flat 2025-09-04 02:38:01,976 - DEBUG - VM fcd32582-9884-49d3-8847-2e3a62efbe38 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4012-1 uve 2025-09-04 02:38:01,976 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-09-04 02:38:01,976 - DEBUG - interface for vm fcd32582-9884-49d3-8847-2e3a62efbe38 deleted from vrouter uve ... 2025-09-04 02:38:01,976 - DEBUG - Deleted interface not in error interface list ... 2025-09-04 02:38:04,977 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4012-1:Compute:contrail-vrouter-agent:0?flat 2025-09-04 02:38:05,013 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4012-1?flat 2025-09-04 02:38:05,045 - DEBUG - VM fcd32582-9884-49d3-8847-2e3a62efbe38 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4012-1 uve 2025-09-04 02:38:05,045 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-09-04 02:38:05,045 - DEBUG - interface for vm fcd32582-9884-49d3-8847-2e3a62efbe38 deleted from vrouter uve ... 2025-09-04 02:38:05,045 - DEBUG - Deleted interface not in error interface list ... 2025-09-04 02:38:08,045 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4012-1:Compute:contrail-vrouter-agent:0?flat 2025-09-04 02:38:08,083 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4012-1?flat 2025-09-04 02:38:08,113 - DEBUG - VM fcd32582-9884-49d3-8847-2e3a62efbe38 is not present in vrouter an-jenkins-deploy-platform-ansible-os-4012-1 uve 2025-09-04 02:38:08,113 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-09-04 02:38:08,114 - DEBUG - interface for vm fcd32582-9884-49d3-8847-2e3a62efbe38 deleted from vrouter uve ... 2025-09-04 02:38:08,114 - DEBUG - Deleted interface not in error interface list ... 2025-09-04 02:38:08,114 - DEBUG - Validated that VM fcd32582-9884-49d3-8847-2e3a62efbe38 is removed in Vrouter UVE 2025-09-04 02:38:08,114 - DEBUG - Verifying through opserver in 10.0.0.57 2025-09-04 02:38:08,114 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN3-74964432?flat 2025-09-04 02:38:08,133 - DEBUG - fcd32582-9884-49d3-8847-2e3a62efbe38 BM not in default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN3-74964432 uve 2025-09-04 02:38:08,133 - DEBUG - Validated that VM fcd32582-9884-49d3-8847-2e3a62efbe38 is not present in the VN UVE 2025-09-04 02:38:08,133 - DEBUG - Verifying the fcd32582-9884-49d3-8847-2e3a62efbe38 virtual network link through opserver 10.0.0.57 2025-09-04 02:38:08,133 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-machines 2025-09-04 02:38:08,145 - INFO - fcd32582-9884-49d3-8847-2e3a62efbe38 vm uve deleted from opserver 2025-09-04 02:38:08,145 - DEBUG - Verifying the fcd32582-9884-49d3-8847-2e3a62efbe38 virtual network link through opserver 10.0.0.38 2025-09-04 02:38:08,145 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines 2025-09-04 02:38:08,159 - INFO - fcd32582-9884-49d3-8847-2e3a62efbe38 vm uve deleted from opserver 2025-09-04 02:38:08,160 - DEBUG - Verifying the fcd32582-9884-49d3-8847-2e3a62efbe38 virtual network link through opserver 10.0.0.131 2025-09-04 02:38:08,160 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-machines 2025-09-04 02:38:08,170 - INFO - fcd32582-9884-49d3-8847-2e3a62efbe38 vm uve deleted from opserver 2025-09-04 02:38:08,170 - INFO - Validated that VM fcd32582-9884-49d3-8847-2e3a62efbe38 is removed from Opserver 2025-09-04 02:38:08,171 - INFO - Deleting VM ctest-VM21-04387429 2025-09-04 02:38:08,248 - DEBUG - Verifying in api server 10.0.0.131 2025-09-04 02:38:08,248 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/df74802d-7321-489f-a302-7515d5ac2254 2025-09-04 02:38:08,256 - DEBUG - VM ID df74802d-7321-489f-a302-7515d5ac2254 of VM ctest-VM21-04387429 is still found in API Server 2025-09-04 02:38:10,257 - DEBUG - Verifying in api server 10.0.0.131 2025-09-04 02:38:10,257 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/df74802d-7321-489f-a302-7515d5ac2254 2025-09-04 02:38:10,264 - DEBUG - Response Code: 404 2025-09-04 02:38:10,264 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/df74802d-7321-489f-a302-7515d5ac2254 2025-09-04 02:38:10,270 - DEBUG - Response Code: 404 2025-09-04 02:38:10,271 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/df74802d-7321-489f-a302-7515d5ac2254 2025-09-04 02:38:10,276 - DEBUG - Response Code: 404 2025-09-04 02:38:10,276 - INFO - VM ctest-VM21-04387429 is fully removed in API-Server 2025-09-04 02:38:10,428 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found 2025-09-04 02:38:10,470 - DEBUG - Validated that vrouter 10.0.0.241 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN2-33211179 2025-09-04 02:38:10,483 - DEBUG - Validated that vrouter 10.0.0.242 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN2-33211179 2025-09-04 02:38:10,483 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN2-33211179 2025-09-04 02:38:10,483 - INFO - VM ctest-VM21-04387429 is removed in Compute, and routes are removed in all compute nodes 2025-09-04 02:38:10,712 - INFO - Routes for VM ctest-VM21-04387429 is removed in all control-nodes 2025-09-04 02:38:10,712 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4012-2:Compute:contrail-vrouter-agent:0?flat 2025-09-04 02:38:10,747 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4012-2?flat 2025-09-04 02:38:10,780 - DEBUG - VM df74802d-7321-489f-a302-7515d5ac2254 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4012-2 uve 2025-09-04 02:38:10,780 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-09-04 02:38:10,780 - DEBUG - interface for vm df74802d-7321-489f-a302-7515d5ac2254 deleted from vrouter uve ... 2025-09-04 02:38:10,780 - DEBUG - Deleted interface not in error interface list ... 2025-09-04 02:38:13,781 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4012-2:Compute:contrail-vrouter-agent:0?flat 2025-09-04 02:38:13,818 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4012-2?flat 2025-09-04 02:38:13,851 - DEBUG - VM df74802d-7321-489f-a302-7515d5ac2254 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4012-2 uve 2025-09-04 02:38:13,851 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-09-04 02:38:13,851 - DEBUG - interface for vm df74802d-7321-489f-a302-7515d5ac2254 deleted from vrouter uve ... 2025-09-04 02:38:13,851 - DEBUG - Deleted interface not in error interface list ... 2025-09-04 02:38:16,852 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4012-2:Compute:contrail-vrouter-agent:0?flat 2025-09-04 02:38:16,892 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4012-2?flat 2025-09-04 02:38:16,928 - DEBUG - VM df74802d-7321-489f-a302-7515d5ac2254 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4012-2 uve 2025-09-04 02:38:16,928 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-09-04 02:38:16,928 - DEBUG - interface for vm df74802d-7321-489f-a302-7515d5ac2254 deleted from vrouter uve ... 2025-09-04 02:38:16,928 - DEBUG - Deleted interface not in error interface list ... 2025-09-04 02:38:19,929 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4012-2:Compute:contrail-vrouter-agent:0?flat 2025-09-04 02:38:19,966 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4012-2?flat 2025-09-04 02:38:19,996 - DEBUG - VM df74802d-7321-489f-a302-7515d5ac2254 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4012-2 uve 2025-09-04 02:38:19,996 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-09-04 02:38:19,996 - DEBUG - interface for vm df74802d-7321-489f-a302-7515d5ac2254 deleted from vrouter uve ... 2025-09-04 02:38:19,996 - DEBUG - Deleted interface not in error interface list ... 2025-09-04 02:38:22,997 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4012-2:Compute:contrail-vrouter-agent:0?flat 2025-09-04 02:38:23,033 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4012-2?flat 2025-09-04 02:38:23,076 - DEBUG - VM df74802d-7321-489f-a302-7515d5ac2254 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4012-2 uve 2025-09-04 02:38:23,076 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-09-04 02:38:23,076 - DEBUG - interface for vm df74802d-7321-489f-a302-7515d5ac2254 deleted from vrouter uve ... 2025-09-04 02:38:23,076 - DEBUG - Deleted interface not in error interface list ... 2025-09-04 02:38:26,077 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4012-2:Compute:contrail-vrouter-agent:0?flat 2025-09-04 02:38:26,110 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4012-2?flat 2025-09-04 02:38:26,142 - DEBUG - VM df74802d-7321-489f-a302-7515d5ac2254 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4012-2 uve 2025-09-04 02:38:26,142 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-09-04 02:38:26,142 - DEBUG - interface for vm df74802d-7321-489f-a302-7515d5ac2254 deleted from vrouter uve ... 2025-09-04 02:38:26,142 - DEBUG - Deleted interface not in error interface list ... 2025-09-04 02:38:29,143 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4012-2:Compute:contrail-vrouter-agent:0?flat 2025-09-04 02:38:29,176 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4012-2?flat 2025-09-04 02:38:29,210 - DEBUG - VM df74802d-7321-489f-a302-7515d5ac2254 is not present in vrouter an-jenkins-deploy-platform-ansible-os-4012-2 uve 2025-09-04 02:38:29,210 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-09-04 02:38:29,210 - DEBUG - interface for vm df74802d-7321-489f-a302-7515d5ac2254 deleted from vrouter uve ... 2025-09-04 02:38:29,210 - DEBUG - Deleted interface not in error interface list ... 2025-09-04 02:38:29,210 - DEBUG - Validated that VM df74802d-7321-489f-a302-7515d5ac2254 is removed in Vrouter UVE 2025-09-04 02:38:29,210 - DEBUG - Verifying through opserver in 10.0.0.57 2025-09-04 02:38:29,210 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN2-33211179?flat 2025-09-04 02:38:29,232 - DEBUG - df74802d-7321-489f-a302-7515d5ac2254 BM not in default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN2-33211179 uve 2025-09-04 02:38:29,232 - DEBUG - Validated that VM df74802d-7321-489f-a302-7515d5ac2254 is not present in the VN UVE 2025-09-04 02:38:29,232 - DEBUG - Verifying the df74802d-7321-489f-a302-7515d5ac2254 virtual network link through opserver 10.0.0.57 2025-09-04 02:38:29,232 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-machines 2025-09-04 02:38:29,242 - INFO - df74802d-7321-489f-a302-7515d5ac2254 vm uve deleted from opserver 2025-09-04 02:38:29,242 - DEBUG - Verifying the df74802d-7321-489f-a302-7515d5ac2254 virtual network link through opserver 10.0.0.38 2025-09-04 02:38:29,243 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines 2025-09-04 02:38:29,255 - INFO - df74802d-7321-489f-a302-7515d5ac2254 vm uve deleted from opserver 2025-09-04 02:38:29,255 - DEBUG - Verifying the df74802d-7321-489f-a302-7515d5ac2254 virtual network link through opserver 10.0.0.131 2025-09-04 02:38:29,255 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-machines 2025-09-04 02:38:29,268 - INFO - df74802d-7321-489f-a302-7515d5ac2254 vm uve deleted from opserver 2025-09-04 02:38:29,268 - INFO - Validated that VM df74802d-7321-489f-a302-7515d5ac2254 is removed from Opserver 2025-09-04 02:38:29,268 - INFO - Deleting VM ctest-VM11-48649270 2025-09-04 02:38:29,342 - DEBUG - Verifying in api server 10.0.0.131 2025-09-04 02:38:29,343 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/ab248664-787f-4511-9f0a-aa540af76c20 2025-09-04 02:38:29,351 - DEBUG - VM ID ab248664-787f-4511-9f0a-aa540af76c20 of VM ctest-VM11-48649270 is still found in API Server 2025-09-04 02:38:31,352 - DEBUG - Verifying in api server 10.0.0.131 2025-09-04 02:38:31,352 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/ab248664-787f-4511-9f0a-aa540af76c20 2025-09-04 02:38:31,359 - DEBUG - Response Code: 404 2025-09-04 02:38:31,360 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/ab248664-787f-4511-9f0a-aa540af76c20 2025-09-04 02:38:31,367 - DEBUG - Response Code: 404 2025-09-04 02:38:31,367 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/ab248664-787f-4511-9f0a-aa540af76c20 2025-09-04 02:38:31,374 - DEBUG - Response Code: 404 2025-09-04 02:38:31,374 - INFO - VM ctest-VM11-48649270 is fully removed in API-Server 2025-09-04 02:38:31,528 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found 2025-09-04 02:38:31,568 - DEBUG - Validated that vrouter 10.0.0.241 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880 2025-09-04 02:38:31,577 - DEBUG - Validated that vrouter 10.0.0.242 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880 2025-09-04 02:38:31,578 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880 2025-09-04 02:38:31,578 - INFO - VM ctest-VM11-48649270 is removed in Compute, and routes are removed in all compute nodes 2025-09-04 02:38:31,773 - INFO - Routes for VM ctest-VM11-48649270 is removed in all control-nodes 2025-09-04 02:38:31,773 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4012-1:Compute:contrail-vrouter-agent:0?flat 2025-09-04 02:38:31,809 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4012-1?flat 2025-09-04 02:38:31,837 - DEBUG - VM ab248664-787f-4511-9f0a-aa540af76c20 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4012-1 uve 2025-09-04 02:38:31,837 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-09-04 02:38:31,838 - DEBUG - interface for vm ab248664-787f-4511-9f0a-aa540af76c20 deleted from vrouter uve ... 2025-09-04 02:38:31,838 - DEBUG - Deleted interface not in error interface list ... 2025-09-04 02:38:34,839 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4012-1:Compute:contrail-vrouter-agent:0?flat 2025-09-04 02:38:34,885 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4012-1?flat 2025-09-04 02:38:34,916 - DEBUG - VM ab248664-787f-4511-9f0a-aa540af76c20 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4012-1 uve 2025-09-04 02:38:34,916 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-09-04 02:38:34,916 - DEBUG - interface for vm ab248664-787f-4511-9f0a-aa540af76c20 deleted from vrouter uve ... 2025-09-04 02:38:34,916 - DEBUG - Deleted interface not in error interface list ... 2025-09-04 02:38:37,916 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4012-1:Compute:contrail-vrouter-agent:0?flat 2025-09-04 02:38:37,955 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4012-1?flat 2025-09-04 02:38:37,985 - DEBUG - VM ab248664-787f-4511-9f0a-aa540af76c20 is not present in vrouter an-jenkins-deploy-platform-ansible-os-4012-1 uve 2025-09-04 02:38:37,985 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-09-04 02:38:37,985 - DEBUG - interface for vm ab248664-787f-4511-9f0a-aa540af76c20 deleted from vrouter uve ... 2025-09-04 02:38:37,985 - DEBUG - Deleted interface not in error interface list ... 2025-09-04 02:38:37,985 - DEBUG - Validated that VM ab248664-787f-4511-9f0a-aa540af76c20 is removed in Vrouter UVE 2025-09-04 02:38:37,985 - DEBUG - Verifying through opserver in 10.0.0.57 2025-09-04 02:38:37,985 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880?flat 2025-09-04 02:38:38,005 - DEBUG - ab248664-787f-4511-9f0a-aa540af76c20 BM not in default-domain:ctest-TestPolicyAclIpv4v6-16254764:ctest-VN1-43502880 uve 2025-09-04 02:38:38,005 - DEBUG - Validated that VM ab248664-787f-4511-9f0a-aa540af76c20 is not present in the VN UVE 2025-09-04 02:38:38,005 - DEBUG - Verifying the ab248664-787f-4511-9f0a-aa540af76c20 virtual network link through opserver 10.0.0.57 2025-09-04 02:38:38,005 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-machines 2025-09-04 02:38:38,016 - DEBUG - No links retuned for ab248664-787f-4511-9f0a-aa540af76c20 2025-09-04 02:38:38,016 - INFO - ab248664-787f-4511-9f0a-aa540af76c20 vm uve deleted from opserver 2025-09-04 02:38:38,017 - DEBUG - Verifying the ab248664-787f-4511-9f0a-aa540af76c20 virtual network link through opserver 10.0.0.38 2025-09-04 02:38:38,017 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines 2025-09-04 02:38:38,029 - DEBUG - No links retuned for ab248664-787f-4511-9f0a-aa540af76c20 2025-09-04 02:38:38,029 - INFO - ab248664-787f-4511-9f0a-aa540af76c20 vm uve deleted from opserver 2025-09-04 02:38:38,030 - DEBUG - Verifying the ab248664-787f-4511-9f0a-aa540af76c20 virtual network link through opserver 10.0.0.131 2025-09-04 02:38:38,030 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-machines 2025-09-04 02:38:38,043 - DEBUG - No links retuned for ab248664-787f-4511-9f0a-aa540af76c20 2025-09-04 02:38:38,043 - INFO - ab248664-787f-4511-9f0a-aa540af76c20 vm uve deleted from opserver 2025-09-04 02:38:38,043 - INFO - Validated that VM ab248664-787f-4511-9f0a-aa540af76c20 is removed from Opserver 2025-09-04 02:38:38,043 - DEBUG - Detaching the Policy for VN :ctest-VN2-33211179 2025-09-04 02:38:38,043 - DEBUG - Requesting: http://10.0.0.57:8082/domains 2025-09-04 02:38:38,050 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c 2025-09-04 02:38:38,064 - DEBUG - Requesting: http://10.0.0.57:8082/project/d3251d2d-fd0b-4ab3-a150-7787310badda 2025-09-04 02:38:38,166 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/551df278-2efc-45d4-abf6-e8c67939be2e 2025-09-04 02:38:38,183 - DEBUG - Requesting: http://10.0.0.57:8082/network-policy/4c0b58fe-e1b4-40bc-bbd7-02502610344f 2025-09-04 02:38:38,389 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-16254764', 'ctest-policy21-78735191']] from ctest-VN2-33211179 2025-09-04 02:38:38,389 - DEBUG - Detaching the Policy for VN :ctest-VN1-43502880 2025-09-04 02:38:38,389 - DEBUG - Requesting: http://10.0.0.57:8082/domains 2025-09-04 02:38:38,397 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c 2025-09-04 02:38:38,411 - DEBUG - Requesting: http://10.0.0.57:8082/project/d3251d2d-fd0b-4ab3-a150-7787310badda 2025-09-04 02:38:38,504 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/45e670b4-74da-4bad-8b96-e4fd63fbd04b 2025-09-04 02:38:38,523 - DEBUG - Requesting: http://10.0.0.57:8082/network-policy/d613370c-d073-4808-9cd7-7cdf9660e58c 2025-09-04 02:38:38,537 - DEBUG - Requesting: http://10.0.0.57:8082/network-policy/5650a2f7-b0d7-49be-8e69-099f9288c293 2025-09-04 02:38:38,762 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-16254764', 'ctest-policy12-46474637'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-16254764', 'ctest-policy13-65349389']] from ctest-VN1-43502880 2025-09-04 02:38:38,826 - INFO - Deleted policy ctest-policy21-78735191 2025-09-04 02:38:38,864 - INFO - Deleted policy ctest-policy12-46474637 2025-09-04 02:38:38,902 - INFO - Deleted policy ctest-policy13-65349389 2025-09-04 02:38:38,903 - INFO - Deleting VN ctest-VN3-74964432 2025-09-04 02:38:39,088 - DEBUG - Response for deleting network () 2025-09-04 02:38:39,088 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/6b71721a-06c9-4525-94b7-9cd6174c25c1 2025-09-04 02:38:39,094 - DEBUG - Response Code: 404 2025-09-04 02:38:39,094 - DEBUG - Requesting: http://10.0.0.57:8082/domains 2025-09-04 02:38:39,101 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c 2025-09-04 02:38:39,154 - DEBUG - Requesting: http://10.0.0.57:8082/project/d3251d2d-fd0b-4ab3-a150-7787310badda 2025-09-04 02:38:39,250 - INFO - Validated that VN ctest-VN3-74964432 is not found in API Server 2025-09-04 02:38:39,261 - DEBUG - VN ctest-VN3-74964432 is not present in Agent 10.0.0.241 2025-09-04 02:38:39,271 - DEBUG - VN ctest-VN3-74964432 is not present in Agent 10.0.0.242 2025-09-04 02:38:39,271 - INFO - Validated that VN ctest-VN3-74964432 is not in any agent 2025-09-04 02:38:39,302 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN3-74964432 info 2025-09-04 02:38:39,302 - INFO - Deleting VN ctest-VN2-33211179 2025-09-04 02:38:39,523 - DEBUG - Response for deleting network () 2025-09-04 02:38:39,523 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/131071cf-1fbd-48d5-86b8-6cb14d9b8dff 2025-09-04 02:38:39,529 - DEBUG - Response Code: 404 2025-09-04 02:38:39,530 - DEBUG - Requesting: http://10.0.0.57:8082/domains 2025-09-04 02:38:39,535 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c 2025-09-04 02:38:39,546 - DEBUG - Requesting: http://10.0.0.57:8082/project/d3251d2d-fd0b-4ab3-a150-7787310badda 2025-09-04 02:38:39,664 - INFO - Validated that VN ctest-VN2-33211179 is not found in API Server 2025-09-04 02:38:39,675 - DEBUG - VN ctest-VN2-33211179 is not present in Agent 10.0.0.241 2025-09-04 02:38:39,685 - DEBUG - VN ctest-VN2-33211179 is not present in Agent 10.0.0.242 2025-09-04 02:38:39,685 - INFO - Validated that VN ctest-VN2-33211179 is not in any agent 2025-09-04 02:38:39,723 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN2-33211179 info 2025-09-04 02:38:39,724 - INFO - Deleting VN ctest-VN1-43502880 2025-09-04 02:38:39,912 - DEBUG - Response for deleting network () 2025-09-04 02:38:39,912 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/a4772aca-5c1b-4dec-a166-2dc17503f03d 2025-09-04 02:38:39,920 - DEBUG - Response Code: 404 2025-09-04 02:38:39,920 - DEBUG - Requesting: http://10.0.0.57:8082/domains 2025-09-04 02:38:39,925 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c 2025-09-04 02:38:39,936 - DEBUG - Requesting: http://10.0.0.57:8082/project/d3251d2d-fd0b-4ab3-a150-7787310badda 2025-09-04 02:38:40,036 - INFO - Validated that VN ctest-VN1-43502880 is not found in API Server 2025-09-04 02:38:40,047 - DEBUG - VN ctest-VN1-43502880 is not present in Agent 10.0.0.241 2025-09-04 02:38:40,057 - DEBUG - VN ctest-VN1-43502880 is not present in Agent 10.0.0.242 2025-09-04 02:38:40,057 - INFO - Validated that VN ctest-VN1-43502880 is not in any agent 2025-09-04 02:38:40,090 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN1-43502880 info 2025-09-04 02:38:40,196 - INFO - IPAM: ipam3 is not found in API Server 2025-09-04 02:38:40,220 - INFO - IPAM:ipam3 is not found in control node 2025-09-04 02:38:40,360 - INFO - IPAM: ipam2 is not found in API Server 2025-09-04 02:38:40,380 - INFO - IPAM:ipam2 is not found in control node 2025-09-04 02:38:40,512 - INFO - IPAM: ipam1 is not found in API Server 2025-09-04 02:38:40,538 - INFO - IPAM:ipam1 is not found in control node 2025-09-04 02:38:41,820 - DEBUG - No XMPP flaps were noticed during the test

2025-09-04 02:38:41,820 - INFO - END TEST : test_policy_inheritance_src_pol_dst_any : PASSED[0:02:54]

2025-09-04 02:38:41,820 - INFO - -------------------------------------------------------------------------------- 2025-09-04 02:38:43,098 - DEBUG - No XMPP flaps were noticed during the test

2025-09-04 02:38:43,098 - INFO - END TEST : test_policy_inheritance_src_pol_dst_any : PASSED[0:02:57]

2025-09-04 02:38:43,098 - INFO - -------------------------------------------------------------------------------- 2025-09-04 02:38:43,815 - INFO - Deleted project: ctest-TestPolicyAclIpv4v6-16254764, ID : d3251d2d-fd0b-4ab3-a150-7787310badda