2025-10-15 16:15:53,048 - INFO - Domain Default found not creating 2025-10-15 16:15:53,190 - INFO - Project ctest-TestPolicyAclIpv4v6-76776884 not found, creating it 2025-10-15 16:15:53,753 - INFO - Created Project:ctest-TestPolicyAclIpv4v6-76776884, ID : e94ece4c-105f-43f3-8c60-fac9d2edefe4 2025-10-15 16:15:55,265 - INFO - ================================================================================ 2025-10-15 16:15:55,265 - INFO - STARTING TEST : test_policy_inheritance_src_pol_dst_any 2025-10-15 16:15:56,556 - DEBUG - Nothing to compare xmpp stats {'10.0.0.141': {'10.20.0.5': '0', '10.20.0.18': '0'}, '10.0.0.33': {'10.20.0.18': '0', '10.20.0.252': '0'}} with 2025-10-15 16:15:56,556 - INFO - Initial checks done. Running the testcase now 2025-10-15 16:15:56,557 - INFO - 2025-10-15 16:15:56,557 - INFO - ================================================================================ 2025-10-15 16:15:56,557 - INFO - STARTING TEST : test_policy_inheritance_src_pol_dst_any 2025-10-15 16:15:56,557 - INFO - TEST DESCRIPTION : Test cases to test policy inheritance 2025-10-15 16:15:57,853 - DEBUG - Nothing to compare xmpp stats {'10.0.0.141': {'10.20.0.5': '0', '10.20.0.18': '0'}, '10.0.0.33': {'10.20.0.18': '0', '10.20.0.252': '0'}} with 2025-10-15 16:15:57,853 - INFO - Initial checks done. Running the testcase now 2025-10-15 16:15:57,853 - INFO - 2025-10-15 16:15:59,225 - DEBUG - Requesting: http://10.0.0.130:8082/domains 2025-10-15 16:15:59,514 - DEBUG - Requesting: http://10.0.0.130:8082/domains 2025-10-15 16:15:59,542 - DEBUG - Requesting: http://10.0.0.130:8082/domain/424b027d-9a59-4bc3-a9ea-fd1131d532cb 2025-10-15 16:15:59,557 - DEBUG - Requesting: http://10.0.0.130:8082/project/e94ece4c-105f-43f3-8c60-fac9d2edefe4 2025-10-15 16:15:59,659 - DEBUG - Requesting: http://10.0.0.130:8082/network-ipam/264c5416-c963-4340-a274-cc3864e1cea3 2025-10-15 16:15:59,667 - INFO - Verifications in API Server for IPAM: ipam1 passed 2025-10-15 16:15:59,672 - DEBUG - Control-node 10.0.0.130 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-76776884:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-76776884', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2759673128516010816', 'uuid-lslong': '11706205874025516707'}, 'enable': 'true', 'created': '2025-10-15T16:15:58', 'last-modified': '2025-10-15T16:15:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.183820'} 2025-10-15 16:15:59,678 - DEBUG - Control-node 10.0.0.131 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-76776884:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-76776884', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2759673128516010816', 'uuid-lslong': '11706205874025516707'}, 'enable': 'true', 'created': '2025-10-15T16:15:58', 'last-modified': '2025-10-15T16:15:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.192114'} 2025-10-15 16:15:59,686 - DEBUG - Control-node 10.0.0.137 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-76776884:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-76776884', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2759673128516010816', 'uuid-lslong': '11706205874025516707'}, 'enable': 'true', 'created': '2025-10-15T16:15:58', 'last-modified': '2025-10-15T16:15:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.198792'} 2025-10-15 16:15:59,686 - INFO - Verifications in Control node for IPAM: ipam1 passed 2025-10-15 16:15:59,686 - DEBUG - Requesting: http://10.0.0.130:8082/domains 2025-10-15 16:15:59,693 - DEBUG - Requesting: http://10.0.0.130:8082/domain/424b027d-9a59-4bc3-a9ea-fd1131d532cb 2025-10-15 16:15:59,706 - DEBUG - Requesting: http://10.0.0.130:8082/project/e94ece4c-105f-43f3-8c60-fac9d2edefe4 2025-10-15 16:15:59,794 - DEBUG - Requesting: http://10.0.0.130:8082/network-ipam/3faa8bb7-b150-412d-baa9-8a1b2deea1d1 2025-10-15 16:15:59,803 - INFO - Verifications in API Server for IPAM: ipam2 passed 2025-10-15 16:15:59,811 - DEBUG - Control-node 10.0.0.130 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-76776884:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-76776884', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4587632791500374317', 'uuid-lslong': '13450433611458060753'}, 'enable': 'true', 'created': '2025-10-15T16:15:58', 'last-modified': '2025-10-15T16:15:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.966213'} 2025-10-15 16:15:59,818 - DEBUG - Control-node 10.0.0.131 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-76776884:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-76776884', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4587632791500374317', 'uuid-lslong': '13450433611458060753'}, 'enable': 'true', 'created': '2025-10-15T16:15:58', 'last-modified': '2025-10-15T16:15:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.974722'} 2025-10-15 16:15:59,825 - DEBUG - Control-node 10.0.0.137 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-76776884:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-76776884', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4587632791500374317', 'uuid-lslong': '13450433611458060753'}, 'enable': 'true', 'created': '2025-10-15T16:15:58', 'last-modified': '2025-10-15T16:15:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.981851'} 2025-10-15 16:15:59,826 - INFO - Verifications in Control node for IPAM: ipam2 passed 2025-10-15 16:15:59,826 - DEBUG - Requesting: http://10.0.0.130:8082/domains 2025-10-15 16:15:59,835 - DEBUG - Requesting: http://10.0.0.130:8082/domain/424b027d-9a59-4bc3-a9ea-fd1131d532cb 2025-10-15 16:15:59,849 - DEBUG - Requesting: http://10.0.0.130:8082/project/e94ece4c-105f-43f3-8c60-fac9d2edefe4 2025-10-15 16:15:59,940 - DEBUG - Requesting: http://10.0.0.130:8082/network-ipam/4f2c8319-2be0-498d-9fbf-082fe26b897c 2025-10-15 16:15:59,948 - INFO - Verifications in API Server for IPAM: ipam3 passed 2025-10-15 16:15:59,953 - DEBUG - Control-node 10.0.0.130 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-76776884:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-76776884', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5705078972105116045', 'uuid-lslong': '11510928174337460604'}, 'enable': 'true', 'created': '2025-10-15T16:15:58', 'last-modified': '2025-10-15T16:15:59', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.765124'} 2025-10-15 16:15:59,960 - DEBUG - Control-node 10.0.0.131 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-76776884:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-76776884', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5705078972105116045', 'uuid-lslong': '11510928174337460604'}, 'enable': 'true', 'created': '2025-10-15T16:15:58', 'last-modified': '2025-10-15T16:15:59', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.771588'} 2025-10-15 16:15:59,965 - DEBUG - Control-node 10.0.0.137 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-76776884:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-76776884', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5705078972105116045', 'uuid-lslong': '11510928174337460604'}, 'enable': 'true', 'created': '2025-10-15T16:15:58', 'last-modified': '2025-10-15T16:15:59', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.777792'} 2025-10-15 16:15:59,966 - INFO - Verifications in Control node for IPAM: ipam3 passed 2025-10-15 16:16:00,281 - DEBUG - Response for create_network : {'network': {'id': '46a99359-7cb4-4b9a-a150-5b63321d4279', 'name': 'ctest-VN1-33076858', 'tenant_id': 'e94ece4c105f43f38c60fac9d2edefe4', 'project_id': 'e94ece4c105f43f38c60fac9d2edefe4', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-76776884', 'ctest-VN1-33076858'], 'port_security_enabled': True, 'description': ''}} 2025-10-15 16:16:00,526 - DEBUG - Response for create_subnet : {'subnet': {'id': 'dff7595a-0405-4f7c-a623-731b292382d9', 'name': '', 'tenant_id': 'e94ece4c105f43f38c60fac9d2edefe4', 'network_id': '46a99359-7cb4-4b9a-a150-5b63321d4279', 'ip_version': 4, 'cidr': '176.76.108.64/26', 'allocation_pools': [{'start': '176.76.108.66', 'end': '176.76.108.126'}], 'gateway_ip': '176.76.108.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '176.76.108.66', 'tags': [], 'project_id': 'e94ece4c105f43f38c60fac9d2edefe4'}} 2025-10-15 16:16:00,739 - DEBUG - Response for create_subnet : {'subnet': {'id': '69eef6d4-803c-4daf-8da0-abb81dee6fc8', 'name': '', 'tenant_id': 'e94ece4c105f43f38c60fac9d2edefe4', 'network_id': '46a99359-7cb4-4b9a-a150-5b63321d4279', 'ip_version': 6, 'cidr': '2a8b:8855:cc89:8d84:12d:3f24::/96', 'allocation_pools': [{'start': '2a8b:8855:cc89:8d84:12d:3f24:0:2', 'end': '2a8b:8855:cc89:8d84:12d:3f24:ffff:fffe'}], 'gateway_ip': '2a8b:8855:cc89:8d84:12d:3f24:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '2a8b:8855:cc89:8d84:12d:3f24:0:2', 'tags': [], 'project_id': 'e94ece4c105f43f38c60fac9d2edefe4'}} 2025-10-15 16:16:00,769 - INFO - Created VN ctest-VN1-33076858 2025-10-15 16:16:00,790 - DEBUG - VN ctest-VN1-33076858 UUID is 46a99359-7cb4-4b9a-a150-5b63321d4279 2025-10-15 16:16:00,981 - DEBUG - Fetched VN: default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858(46a99359-7cb4-4b9a-a150-5b63321d4279) with subnets ['176.76.108.64/26', '2a8b:8855:cc89:8d84:12d:3f24::/96'] 2025-10-15 16:16:01,324 - DEBUG - Response for create_network : {'network': {'id': 'd060d0d6-af83-43fa-b9d1-7b85b0171f57', 'name': 'ctest-VN2-28212433', 'tenant_id': 'e94ece4c105f43f38c60fac9d2edefe4', 'project_id': 'e94ece4c105f43f38c60fac9d2edefe4', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-76776884', 'ctest-VN2-28212433'], 'port_security_enabled': True, 'description': ''}} 2025-10-15 16:16:01,532 - DEBUG - Response for create_subnet : {'subnet': {'id': '16b94a4d-d7e7-4f2b-94ed-4b825109cf51', 'name': '', 'tenant_id': 'e94ece4c105f43f38c60fac9d2edefe4', 'network_id': 'd060d0d6-af83-43fa-b9d1-7b85b0171f57', 'ip_version': 4, 'cidr': '200.134.225.192/26', 'allocation_pools': [{'start': '200.134.225.194', 'end': '200.134.225.254'}], 'gateway_ip': '200.134.225.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '200.134.225.194', 'tags': [], 'project_id': 'e94ece4c105f43f38c60fac9d2edefe4'}} 2025-10-15 16:16:01,765 - DEBUG - Response for create_subnet : {'subnet': {'id': 'bd40351c-31fe-407f-b0de-1d065caed2b3', 'name': '', 'tenant_id': 'e94ece4c105f43f38c60fac9d2edefe4', 'network_id': 'd060d0d6-af83-43fa-b9d1-7b85b0171f57', 'ip_version': 6, 'cidr': '2043:131f:7f89:909b:9bd8:1066::/96', 'allocation_pools': [{'start': '2043:131f:7f89:909b:9bd8:1066:0:2', 'end': '2043:131f:7f89:909b:9bd8:1066:ffff:fffe'}], 'gateway_ip': '2043:131f:7f89:909b:9bd8:1066:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '2043:131f:7f89:909b:9bd8:1066:0:2', 'tags': [], 'project_id': 'e94ece4c105f43f38c60fac9d2edefe4'}} 2025-10-15 16:16:01,796 - INFO - Created VN ctest-VN2-28212433 2025-10-15 16:16:01,812 - DEBUG - VN ctest-VN2-28212433 UUID is d060d0d6-af83-43fa-b9d1-7b85b0171f57 2025-10-15 16:16:02,261 - DEBUG - Response for create_network : {'network': {'id': '63414430-ed69-40e0-90d8-9c660ef2cca9', 'name': 'ctest-VN3-49110623', 'tenant_id': 'e94ece4c105f43f38c60fac9d2edefe4', 'project_id': 'e94ece4c105f43f38c60fac9d2edefe4', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-76776884', 'ctest-VN3-49110623'], 'port_security_enabled': True, 'description': ''}} 2025-10-15 16:16:02,486 - DEBUG - Response for create_subnet : {'subnet': {'id': '9868c863-9866-4dcc-bcf9-bc2ec9c60d7d', 'name': '', 'tenant_id': 'e94ece4c105f43f38c60fac9d2edefe4', 'network_id': '63414430-ed69-40e0-90d8-9c660ef2cca9', 'ip_version': 4, 'cidr': '108.69.25.0/26', 'allocation_pools': [{'start': '108.69.25.2', 'end': '108.69.25.62'}], 'gateway_ip': '108.69.25.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '108.69.25.2', 'tags': [], 'project_id': 'e94ece4c105f43f38c60fac9d2edefe4'}} 2025-10-15 16:16:02,662 - DEBUG - Response for create_subnet : {'subnet': {'id': '68ec0920-e14a-4542-b2c5-5fb16a90e6d0', 'name': '', 'tenant_id': 'e94ece4c105f43f38c60fac9d2edefe4', 'network_id': '63414430-ed69-40e0-90d8-9c660ef2cca9', 'ip_version': 6, 'cidr': '2eed:8427:cb15:8815:ea17:1fb9::/96', 'allocation_pools': [{'start': '2eed:8427:cb15:8815:ea17:1fb9:0:2', 'end': '2eed:8427:cb15:8815:ea17:1fb9:ffff:fffe'}], 'gateway_ip': '2eed:8427:cb15:8815:ea17:1fb9:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '2eed:8427:cb15:8815:ea17:1fb9:0:2', 'tags': [], 'project_id': 'e94ece4c105f43f38c60fac9d2edefe4'}} 2025-10-15 16:16:02,691 - INFO - Created VN ctest-VN3-49110623 2025-10-15 16:16:02,705 - DEBUG - VN ctest-VN3-49110623 UUID is 63414430-ed69-40e0-90d8-9c660ef2cca9 2025-10-15 16:16:02,821 - DEBUG - Requesting: http://10.0.0.130:8082/domains 2025-10-15 16:16:02,829 - DEBUG - Requesting: http://10.0.0.130:8082/domain/424b027d-9a59-4bc3-a9ea-fd1131d532cb 2025-10-15 16:16:02,842 - DEBUG - Requesting: http://10.0.0.130:8082/project/e94ece4c-105f-43f3-8c60-fac9d2edefe4 2025-10-15 16:16:02,937 - DEBUG - Requesting: http://10.0.0.130:8082/virtual-network/46a99359-7cb4-4b9a-a150-5b63321d4279 2025-10-15 16:16:02,955 - DEBUG - Requesting: http://10.0.0.130:8082/virtual-network/46a99359-7cb4-4b9a-a150-5b63321d4279 2025-10-15 16:16:02,967 - DEBUG - Requesting: http://10.0.0.130:8082/routing-instance/78a4a5da-4ce7-4da1-8267-496bac8f2dfe 2025-10-15 16:16:02,976 - DEBUG - Requesting: http://10.0.0.130:8082/routing-instance/78a4a5da-4ce7-4da1-8267-496bac8f2dfe 2025-10-15 16:16:02,986 - DEBUG - Requesting: http://10.0.0.130:8082/route-target/00ed673d-cfe1-41d3-b70d-0b60ba3d7fc2 2025-10-15 16:16:02,996 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-10-15 16:16:02,996 - DEBUG - Requesting: http://10.0.0.130:8082/virtual-network/46a99359-7cb4-4b9a-a150-5b63321d4279 2025-10-15 16:16:03,010 - DEBUG - Requesting: http://10.0.0.130:8082/routing-instance/78a4a5da-4ce7-4da1-8267-496bac8f2dfe 2025-10-15 16:16:03,030 - INFO - Verified VN network id 15 for VN 46a99359-7cb4-4b9a-a150-5b63321d4279 2025-10-15 16:16:03,031 - INFO - Verifications in API Server for VN ctest-VN1-33076858 passed 2025-10-15 16:16:03,031 - DEBUG - Requesting: http://10.0.0.130:8082/virtual-network/46a99359-7cb4-4b9a-a150-5b63321d4279 2025-10-15 16:16:03,044 - DEBUG - Requesting: http://10.0.0.130:8082/routing-instance/78a4a5da-4ce7-4da1-8267-496bac8f2dfe 2025-10-15 16:16:03,053 - DEBUG - Requesting: http://10.0.0.130:8082/routing-instance/78a4a5da-4ce7-4da1-8267-496bac8f2dfe 2025-10-15 16:16:03,061 - DEBUG - Requesting: http://10.0.0.130:8082/route-target/00ed673d-cfe1-41d3-b70d-0b60ba3d7fc2 2025-10-15 16:16:03,074 - DEBUG - Control-node 10.0.0.130 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-76776884', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5091762866272619418', 'uuid-lslong': '11623891119843918457'}, 'enable': 'true', 'created': '2025-10-15T16:16:00', 'last-modified': '2025-10-15T16:16:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.392366'} 2025-10-15 16:16:03,081 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-10-15 16:16:03,088 - DEBUG - Control-node 10.0.0.131 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-76776884', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5091762866272619418', 'uuid-lslong': '11623891119843918457'}, 'enable': 'true', 'created': '2025-10-15T16:16:00', 'last-modified': '2025-10-15T16:16:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.409183'} 2025-10-15 16:16:03,095 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-10-15 16:16:03,101 - DEBUG - Control-node 10.0.0.137 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-76776884', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5091762866272619418', 'uuid-lslong': '11623891119843918457'}, 'enable': 'true', 'created': '2025-10-15T16:16:00', 'last-modified': '2025-10-15T16:16:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.421829'} 2025-10-15 16:16:03,107 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-10-15 16:16:03,107 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN1-33076858 passed 2025-10-15 16:16:03,108 - DEBUG - ====Verifying policy data for ctest-VN1-33076858 in API_Server ====== 2025-10-15 16:16:03,108 - DEBUG - Requesting: http://10.0.0.130:8082/domains 2025-10-15 16:16:03,115 - DEBUG - Requesting: http://10.0.0.130:8082/domain/424b027d-9a59-4bc3-a9ea-fd1131d532cb 2025-10-15 16:16:03,126 - DEBUG - Requesting: http://10.0.0.130:8082/project/e94ece4c-105f-43f3-8c60-fac9d2edefe4 2025-10-15 16:16:03,212 - DEBUG - Requesting: http://10.0.0.130:8082/virtual-network/46a99359-7cb4-4b9a-a150-5b63321d4279 2025-10-15 16:16:03,221 - DEBUG - =>VN ctest-VN1-33076858 has no policy to be verified 2025-10-15 16:16:03,221 - DEBUG - Verifying the vn in opserver 2025-10-15 16:16:03,221 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858 virtual network link through opserver 10.0.0.130 2025-10-15 16:16:03,222 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-networks 2025-10-15 16:16:03,514 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-networks 2025-10-15 16:16:03,580 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858', 'href': 'http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858?flat'} 2025-10-15 16:16:03,580 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858 virtual network link through opserver 10.0.0.131 2025-10-15 16:16:03,580 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-networks 2025-10-15 16:16:03,873 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-networks 2025-10-15 16:16:03,929 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858', 'href': 'http://10.0.0.131:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858?flat'} 2025-10-15 16:16:03,929 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858 virtual network link through opserver 10.0.0.137 2025-10-15 16:16:03,930 - DEBUG - Requesting: http://10.0.0.137:8081/analytics/uves/virtual-networks 2025-10-15 16:16:04,221 - DEBUG - Requesting: http://10.0.0.137:8081/analytics/uves/virtual-networks 2025-10-15 16:16:04,279 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858', 'href': 'http://10.0.0.137:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858?flat'} 2025-10-15 16:16:04,279 - INFO - Validated that VN default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858 is found in opserver 2025-10-15 16:16:04,303 - DEBUG - Do not have enough data to verify VN in agent 2025-10-15 16:16:04,315 - DEBUG - VRF ids for VN ctest-VN1-33076858: {} 2025-10-15 16:16:04,315 - DEBUG - Requesting: http://10.0.0.130:8082/domains 2025-10-15 16:16:04,323 - DEBUG - Requesting: http://10.0.0.130:8082/domain/424b027d-9a59-4bc3-a9ea-fd1131d532cb 2025-10-15 16:16:04,342 - DEBUG - Requesting: http://10.0.0.130:8082/project/e94ece4c-105f-43f3-8c60-fac9d2edefe4 2025-10-15 16:16:04,429 - DEBUG - Requesting: http://10.0.0.130:8082/virtual-network/d060d0d6-af83-43fa-b9d1-7b85b0171f57 2025-10-15 16:16:04,440 - DEBUG - Requesting: http://10.0.0.130:8082/virtual-network/d060d0d6-af83-43fa-b9d1-7b85b0171f57 2025-10-15 16:16:04,450 - DEBUG - Requesting: http://10.0.0.130:8082/routing-instance/2d5013f8-e55e-41fd-b4be-1b98c350d159 2025-10-15 16:16:04,457 - DEBUG - Requesting: http://10.0.0.130:8082/routing-instance/2d5013f8-e55e-41fd-b4be-1b98c350d159 2025-10-15 16:16:04,464 - DEBUG - Requesting: http://10.0.0.130:8082/route-target/88fb5693-5ace-482b-98a2-55bc54d2b30f 2025-10-15 16:16:04,471 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-10-15 16:16:04,471 - DEBUG - Requesting: http://10.0.0.130:8082/virtual-network/d060d0d6-af83-43fa-b9d1-7b85b0171f57 2025-10-15 16:16:04,479 - DEBUG - Requesting: http://10.0.0.130:8082/routing-instance/2d5013f8-e55e-41fd-b4be-1b98c350d159 2025-10-15 16:16:04,496 - INFO - Verified VN network id 16 for VN d060d0d6-af83-43fa-b9d1-7b85b0171f57 2025-10-15 16:16:04,496 - INFO - Verifications in API Server for VN ctest-VN2-28212433 passed 2025-10-15 16:16:04,496 - DEBUG - Requesting: http://10.0.0.130:8082/virtual-network/d060d0d6-af83-43fa-b9d1-7b85b0171f57 2025-10-15 16:16:04,508 - DEBUG - Requesting: http://10.0.0.130:8082/routing-instance/2d5013f8-e55e-41fd-b4be-1b98c350d159 2025-10-15 16:16:04,517 - DEBUG - Requesting: http://10.0.0.130:8082/routing-instance/2d5013f8-e55e-41fd-b4be-1b98c350d159 2025-10-15 16:16:04,524 - DEBUG - Requesting: http://10.0.0.130:8082/route-target/88fb5693-5ace-482b-98a2-55bc54d2b30f 2025-10-15 16:16:04,537 - DEBUG - Control-node 10.0.0.130 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN2-28212433', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-76776884', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15015230778139427834', 'uuid-lslong': '13389618981264367447'}, 'enable': 'true', 'created': '2025-10-15T16:16:01', 'last-modified': '2025-10-15T16:16:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.834114'} 2025-10-15 16:16:04,543 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-10-15 16:16:04,549 - DEBUG - Control-node 10.0.0.131 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN2-28212433', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-76776884', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15015230778139427834', 'uuid-lslong': '13389618981264367447'}, 'enable': 'true', 'created': '2025-10-15T16:16:01', 'last-modified': '2025-10-15T16:16:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.847761'} 2025-10-15 16:16:04,558 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-10-15 16:16:04,565 - DEBUG - Control-node 10.0.0.137 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN2-28212433', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-76776884', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15015230778139427834', 'uuid-lslong': '13389618981264367447'}, 'enable': 'true', 'created': '2025-10-15T16:16:01', 'last-modified': '2025-10-15T16:16:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.863553'} 2025-10-15 16:16:04,572 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-10-15 16:16:04,572 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN2-28212433 passed 2025-10-15 16:16:04,572 - DEBUG - ====Verifying policy data for ctest-VN2-28212433 in API_Server ====== 2025-10-15 16:16:04,572 - DEBUG - Requesting: http://10.0.0.130:8082/domains 2025-10-15 16:16:04,578 - DEBUG - Requesting: http://10.0.0.130:8082/domain/424b027d-9a59-4bc3-a9ea-fd1131d532cb 2025-10-15 16:16:04,588 - DEBUG - Requesting: http://10.0.0.130:8082/project/e94ece4c-105f-43f3-8c60-fac9d2edefe4 2025-10-15 16:16:04,676 - DEBUG - Requesting: http://10.0.0.130:8082/virtual-network/d060d0d6-af83-43fa-b9d1-7b85b0171f57 2025-10-15 16:16:04,685 - DEBUG - =>VN ctest-VN2-28212433 has no policy to be verified 2025-10-15 16:16:04,685 - DEBUG - Verifying the vn in opserver 2025-10-15 16:16:04,685 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN2-28212433 virtual network link through opserver 10.0.0.130 2025-10-15 16:16:04,685 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-networks 2025-10-15 16:16:04,741 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN2-28212433', 'href': 'http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN2-28212433?flat'} 2025-10-15 16:16:04,741 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN2-28212433 virtual network link through opserver 10.0.0.131 2025-10-15 16:16:04,741 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-networks 2025-10-15 16:16:04,804 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN2-28212433', 'href': 'http://10.0.0.131:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN2-28212433?flat'} 2025-10-15 16:16:04,804 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN2-28212433 virtual network link through opserver 10.0.0.137 2025-10-15 16:16:04,804 - DEBUG - Requesting: http://10.0.0.137:8081/analytics/uves/virtual-networks 2025-10-15 16:16:04,865 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN2-28212433', 'href': 'http://10.0.0.137:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN2-28212433?flat'} 2025-10-15 16:16:04,865 - INFO - Validated that VN default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN2-28212433 is found in opserver 2025-10-15 16:16:04,885 - DEBUG - Do not have enough data to verify VN in agent 2025-10-15 16:16:04,894 - DEBUG - VRF ids for VN ctest-VN2-28212433: {} 2025-10-15 16:16:04,895 - DEBUG - Requesting: http://10.0.0.130:8082/domains 2025-10-15 16:16:04,901 - DEBUG - Requesting: http://10.0.0.130:8082/domain/424b027d-9a59-4bc3-a9ea-fd1131d532cb 2025-10-15 16:16:04,912 - DEBUG - Requesting: http://10.0.0.130:8082/project/e94ece4c-105f-43f3-8c60-fac9d2edefe4 2025-10-15 16:16:04,997 - DEBUG - Requesting: http://10.0.0.130:8082/virtual-network/63414430-ed69-40e0-90d8-9c660ef2cca9 2025-10-15 16:16:05,009 - DEBUG - Requesting: http://10.0.0.130:8082/virtual-network/63414430-ed69-40e0-90d8-9c660ef2cca9 2025-10-15 16:16:05,019 - DEBUG - Requesting: http://10.0.0.130:8082/routing-instance/e448d3bd-e283-4969-9c3e-3ce92ceabee5 2025-10-15 16:16:05,026 - DEBUG - Requesting: http://10.0.0.130:8082/routing-instance/e448d3bd-e283-4969-9c3e-3ce92ceabee5 2025-10-15 16:16:05,033 - DEBUG - Requesting: http://10.0.0.130:8082/route-target/0e4a4a9e-45c1-4c25-8fce-30990868c887 2025-10-15 16:16:05,040 - DEBUG - Route Targets: ['target:64512:8000007'] 2025-10-15 16:16:05,040 - DEBUG - Requesting: http://10.0.0.130:8082/virtual-network/63414430-ed69-40e0-90d8-9c660ef2cca9 2025-10-15 16:16:05,049 - DEBUG - Requesting: http://10.0.0.130:8082/routing-instance/e448d3bd-e283-4969-9c3e-3ce92ceabee5 2025-10-15 16:16:05,066 - INFO - Verified VN network id 17 for VN 63414430-ed69-40e0-90d8-9c660ef2cca9 2025-10-15 16:16:05,066 - INFO - Verifications in API Server for VN ctest-VN3-49110623 passed 2025-10-15 16:16:05,066 - DEBUG - Requesting: http://10.0.0.130:8082/virtual-network/63414430-ed69-40e0-90d8-9c660ef2cca9 2025-10-15 16:16:05,077 - DEBUG - Requesting: http://10.0.0.130:8082/routing-instance/e448d3bd-e283-4969-9c3e-3ce92ceabee5 2025-10-15 16:16:05,084 - DEBUG - Requesting: http://10.0.0.130:8082/routing-instance/e448d3bd-e283-4969-9c3e-3ce92ceabee5 2025-10-15 16:16:05,091 - DEBUG - Requesting: http://10.0.0.130:8082/route-target/0e4a4a9e-45c1-4c25-8fce-30990868c887 2025-10-15 16:16:05,102 - DEBUG - Control-node 10.0.0.130 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN3-49110623', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-76776884', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7152072660173275360', 'uuid-lslong': '10437264098582514857'}, 'enable': 'true', 'created': '2025-10-15T16:16:02', 'last-modified': '2025-10-15T16:16:02', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.478769'} 2025-10-15 16:16:05,108 - DEBUG - Route Targets: ['target:64512:8000007'] 2025-10-15 16:16:05,117 - DEBUG - Control-node 10.0.0.131 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN3-49110623', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-76776884', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7152072660173275360', 'uuid-lslong': '10437264098582514857'}, 'enable': 'true', 'created': '2025-10-15T16:16:02', 'last-modified': '2025-10-15T16:16:02', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.495488'} 2025-10-15 16:16:05,123 - DEBUG - Route Targets: ['target:64512:8000007'] 2025-10-15 16:16:05,129 - DEBUG - Control-node 10.0.0.137 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN3-49110623', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-76776884', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7152072660173275360', 'uuid-lslong': '10437264098582514857'}, 'enable': 'true', 'created': '2025-10-15T16:16:02', 'last-modified': '2025-10-15T16:16:02', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.506137'} 2025-10-15 16:16:05,135 - DEBUG - Route Targets: ['target:64512:8000007'] 2025-10-15 16:16:05,135 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN3-49110623 passed 2025-10-15 16:16:05,136 - DEBUG - ====Verifying policy data for ctest-VN3-49110623 in API_Server ====== 2025-10-15 16:16:05,136 - DEBUG - Requesting: http://10.0.0.130:8082/domains 2025-10-15 16:16:05,144 - DEBUG - Requesting: http://10.0.0.130:8082/domain/424b027d-9a59-4bc3-a9ea-fd1131d532cb 2025-10-15 16:16:05,155 - DEBUG - Requesting: http://10.0.0.130:8082/project/e94ece4c-105f-43f3-8c60-fac9d2edefe4 2025-10-15 16:16:05,244 - DEBUG - Requesting: http://10.0.0.130:8082/virtual-network/63414430-ed69-40e0-90d8-9c660ef2cca9 2025-10-15 16:16:05,254 - DEBUG - =>VN ctest-VN3-49110623 has no policy to be verified 2025-10-15 16:16:05,254 - DEBUG - Verifying the vn in opserver 2025-10-15 16:16:05,254 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN3-49110623 virtual network link through opserver 10.0.0.130 2025-10-15 16:16:05,254 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-networks 2025-10-15 16:16:05,307 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN3-49110623', 'href': 'http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN3-49110623?flat'} 2025-10-15 16:16:05,307 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN3-49110623 virtual network link through opserver 10.0.0.131 2025-10-15 16:16:05,307 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-networks 2025-10-15 16:16:05,366 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN3-49110623', 'href': 'http://10.0.0.131:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN3-49110623?flat'} 2025-10-15 16:16:05,366 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN3-49110623 virtual network link through opserver 10.0.0.137 2025-10-15 16:16:05,366 - DEBUG - Requesting: http://10.0.0.137:8081/analytics/uves/virtual-networks 2025-10-15 16:16:05,430 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN3-49110623', 'href': 'http://10.0.0.137:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN3-49110623?flat'} 2025-10-15 16:16:05,430 - INFO - Validated that VN default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN3-49110623 is found in opserver 2025-10-15 16:16:05,452 - DEBUG - Do not have enough data to verify VN in agent 2025-10-15 16:16:05,463 - DEBUG - VRF ids for VN ctest-VN3-49110623: {} 2025-10-15 16:16:05,472 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858, 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-76776884:ctest-VN3-49110623, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None] 2025-10-15 16:16:05,789 - DEBUG - No source network defined 2025-10-15 16:16:05,790 - DEBUG - No source network defined 2025-10-15 16:16:05,790 - 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-76776884:ctest-policy13-74755003, 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-76776884:ctest-policy13-74755003, subnet_list = []], src_ports = [start_port = -1, end_port = -1], application = [], dst_addresses = [subnet = None, virtual_network = any, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None] 2025-10-15 16:16:06,093 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN2-28212433, 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-76776884:ctest-VN1-33076858, 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-76776884:ctest-VN2-28212433, 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-76776884:ctest-VN1-33076858, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None] 2025-10-15 16:16:06,350 - DEBUG - Requesting: http://10.0.0.130:8082/domains 2025-10-15 16:16:06,357 - DEBUG - Requesting: http://10.0.0.130:8082/domain/424b027d-9a59-4bc3-a9ea-fd1131d532cb 2025-10-15 16:16:06,369 - DEBUG - Requesting: http://10.0.0.130:8082/project/e94ece4c-105f-43f3-8c60-fac9d2edefe4 2025-10-15 16:16:06,468 - DEBUG - Requesting: http://10.0.0.130:8082/virtual-network/46a99359-7cb4-4b9a-a150-5b63321d4279 2025-10-15 16:16:06,478 - DEBUG - Setup step: Associating the policy to VN' 2025-10-15 16:16:06,619 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '46a99359-7cb4-4b9a-a150-5b63321d4279', 'name': 'ctest-VN1-33076858', 'tenant_id': 'e94ece4c105f43f38c60fac9d2edefe4', 'project_id': 'e94ece4c105f43f38c60fac9d2edefe4', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['dff7595a-0405-4f7c-a623-731b292382d9', '69eef6d4-803c-4daf-8da0-abb81dee6fc8'], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-76776884', 'ctest-VN1-33076858'], 'policys': [['default-domain', 'ctest-TestPolicyAclIpv4v6-76776884', 'ctest-policy12-37178360'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-76776884', 'ctest-policy13-74755003']], 'subnet_ipam': [{'subnet_cidr': '176.76.108.64/26', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-76776884', 'ipam1']}, {'subnet_cidr': '2a8b:8855:cc89:8d84:12d:3f24::/96', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-76776884', 'ipam1']}], 'port_security_enabled': True, 'description': ''}} 2025-10-15 16:16:06,798 - DEBUG - Associated Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-76776884', 'ctest-policy12-37178360'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-76776884', 'ctest-policy13-74755003']] to ctest-VN1-33076858 2025-10-15 16:16:06,798 - DEBUG - Requesting: http://10.0.0.130:8082/domains 2025-10-15 16:16:06,805 - DEBUG - Requesting: http://10.0.0.130:8082/domain/424b027d-9a59-4bc3-a9ea-fd1131d532cb 2025-10-15 16:16:06,816 - DEBUG - Requesting: http://10.0.0.130:8082/project/e94ece4c-105f-43f3-8c60-fac9d2edefe4 2025-10-15 16:16:06,909 - DEBUG - Requesting: http://10.0.0.130:8082/virtual-network/d060d0d6-af83-43fa-b9d1-7b85b0171f57 2025-10-15 16:16:06,921 - DEBUG - Setup step: Associating the policy to VN' 2025-10-15 16:16:07,038 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': 'd060d0d6-af83-43fa-b9d1-7b85b0171f57', 'name': 'ctest-VN2-28212433', 'tenant_id': 'e94ece4c105f43f38c60fac9d2edefe4', 'project_id': 'e94ece4c105f43f38c60fac9d2edefe4', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['16b94a4d-d7e7-4f2b-94ed-4b825109cf51', 'bd40351c-31fe-407f-b0de-1d065caed2b3'], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-76776884', 'ctest-VN2-28212433'], 'policys': [['default-domain', 'ctest-TestPolicyAclIpv4v6-76776884', 'ctest-policy21-63718949']], 'subnet_ipam': [{'subnet_cidr': '200.134.225.192/26', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-76776884', 'ipam2']}, {'subnet_cidr': '2043:131f:7f89:909b:9bd8:1066::/96', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-76776884', 'ipam2']}], 'port_security_enabled': True, 'description': ''}} 2025-10-15 16:16:07,135 - DEBUG - Associated Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-76776884', 'ctest-policy21-63718949']] to ctest-VN2-28212433 2025-10-15 16:16:07,245 - DEBUG - Services list from nova: [, , , ] 2025-10-15 16:16:08,400 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4330-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4330-1) 2025-10-15 16:16:09,563 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4330-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4330-2) 2025-10-15 16:16:10,845 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4330-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4330-1) 2025-10-15 16:16:10,939 - DEBUG - VM is in ACTIVE state now 2025-10-15 16:16:10,940 - INFO - VM name : ctest-VM11-66511174 2025-10-15 16:16:11,043 - DEBUG - VM ctest-VM11-66511174 ID is b90b02e6-70d5-4f83-89a8-500c9eeadaf8 2025-10-15 16:16:11,144 - DEBUG - VM is in ACTIVE state now 2025-10-15 16:16:11,178 - DEBUG - VM ctest-VM11-66511174 launched on Node an-jenkins-deploy-platform-ansible-os-4330-1 2025-10-15 16:16:11,269 - DEBUG - Requesting: http://10.0.0.137:8082/virtual-machine/b90b02e6-70d5-4f83-89a8-500c9eeadaf8 2025-10-15 16:16:11,576 - DEBUG - Requesting: http://10.0.0.137:8082/virtual-machine/b90b02e6-70d5-4f83-89a8-500c9eeadaf8 2025-10-15 16:16:11,614 - DEBUG - Requesting: http://10.0.0.137:8082/virtual-machine/b90b02e6-70d5-4f83-89a8-500c9eeadaf8 2025-10-15 16:16:11,622 - DEBUG - Requesting: http://10.0.0.137:8082/virtual-machine-interface/3135a100-2434-4ec7-894f-64521374b71d 2025-10-15 16:16:11,632 - DEBUG - Requesting: http://10.0.0.137:8082/virtual-machine/b90b02e6-70d5-4f83-89a8-500c9eeadaf8 2025-10-15 16:16:11,641 - DEBUG - Requesting: http://10.0.0.137:8082/virtual-machine-interface/3135a100-2434-4ec7-894f-64521374b71d 2025-10-15 16:16:11,661 - DEBUG - Requesting: http://10.0.0.137:8082/instance-ip/201146fd-c1c3-481c-87ae-8fc0d33b7c1b 2025-10-15 16:16:11,673 - DEBUG - Requesting: http://10.0.0.137:8082/instance-ip/60a87c6a-a22d-42de-b2d9-f2ded55ea14a 2025-10-15 16:16:11,688 - DEBUG - Verifying in api server 10.0.0.137 2025-10-15 16:16:11,688 - DEBUG - Requesting: http://10.0.0.137:8082/virtual-machine/b90b02e6-70d5-4f83-89a8-500c9eeadaf8 2025-10-15 16:16:11,699 - DEBUG - Requesting: http://10.0.0.137:8082/virtual-machine-interface/3135a100-2434-4ec7-894f-64521374b71d 2025-10-15 16:16:11,712 - DEBUG - Requesting: http://10.0.0.137:8082/instance-ip/201146fd-c1c3-481c-87ae-8fc0d33b7c1b 2025-10-15 16:16:11,725 - DEBUG - Requesting: http://10.0.0.137:8082/instance-ip/60a87c6a-a22d-42de-b2d9-f2ded55ea14a 2025-10-15 16:16:11,736 - INFO - VM ctest-VM11-66511174 verfication in all API Servers passed 2025-10-15 16:16:11,754 - DEBUG - VM ctest-VM11-66511174 Tap interface: {'index': '3', 'name': 'tap3135a100-24', 'uuid': '3135a100-2434-4ec7-894f-64521374b71d', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858:ctest-VN1-33076858', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Active', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Enable', 'dns_service': 'Enable', 'type': 'vport', 'label': '25', 'l2_label': '29', 'vxlan_id': '15', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858', 'vm_uuid': 'b90b02e6-70d5-4f83-89a8-500c9eeadaf8', 'vm_name': 'ctest-VM11-66511174', 'ip_addr': '176.76.108.67', 'mac_addr': '02:31:35:a1:00:24', '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-76776884:3135a100-2434-4ec7-894f-64521374b71d', 'sg_uuid_list': ['8d9e6fc0-a38a-4db3-ab76-38cd2d7df747'], 'static_route_list': None, 'vm_project_uuid': 'e94ece4c-105f-43f3-8c60-fac9d2edefe4', 'admin_state': 'Enabled', 'flow_key_idx': '31', 'allowed_address_pair_list': None, 'ip6_addr': '2a8b:8855:cc89:8d84:12d:3f24:0:3', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []} 2025-10-15 16:16:11,754 - DEBUG - Agent 10.0.0.141 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858:ctest-VN1-33076858 2025-10-15 16:16:11,760 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858:ctest-VN1-33076858', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858', 'table_label': '-1', 'vxlan_id': '15', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.14:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2025-10-15 16:16:11,856 - DEBUG - VM ctest-VM11-66511174 : Tap interface tap3135a100-24 is set to Active 2025-10-15 16:16:11,856 - DEBUG - Tap interface tap3135a100-24 detail : {'index': '3', 'name': 'tap3135a100-24', 'uuid': '3135a100-2434-4ec7-894f-64521374b71d', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858:ctest-VN1-33076858', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Active', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Enable', 'dns_service': 'Enable', 'type': 'vport', 'label': '25', 'l2_label': '29', 'vxlan_id': '15', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858', 'vm_uuid': 'b90b02e6-70d5-4f83-89a8-500c9eeadaf8', 'vm_name': 'ctest-VM11-66511174', 'ip_addr': '176.76.108.67', 'mac_addr': '02:31:35:a1:00:24', '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-76776884:3135a100-2434-4ec7-894f-64521374b71d', 'sg_uuid_list': ['8d9e6fc0-a38a-4db3-ab76-38cd2d7df747'], 'static_route_list': None, 'vm_project_uuid': 'e94ece4c-105f-43f3-8c60-fac9d2edefe4', 'admin_state': 'Enabled', 'flow_key_idx': '31', 'allowed_address_pair_list': None, 'ip6_addr': '2a8b:8855:cc89:8d84:12d:3f24:0:3', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []} 2025-10-15 16:16:11,971 - DEBUG - Starting Layer 2 verification in Agent 2025-10-15 16:16:11,986 - DEBUG - Layer 2 path is seen for VM MAC 02:31:35:a1:00:24 in agent 10.0.0.141 2025-10-15 16:16:11,986 - DEBUG - Active layer 2 route in agent is present for VMI tap3135a100-24 2025-10-15 16:16:11,986 - DEBUG - L2 label(29) matches bw route table and interface table 2025-10-15 16:16:12,004 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858 is found in Agent of node 10.0.0.141 2025-10-15 16:16:12,128 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858 is consistent in agent 10.0.0.141 2025-10-15 16:16:12,129 - DEBUG - Route for VM IP ['2a8b:8855:cc89:8d84:12d:3f24:0:3', '176.76.108.67'] is consistent in agent 10.0.0.141 2025-10-15 16:16:12,129 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858 verification for VM ctest-VM11-66511174 in Agent 10.0.0.141 passed 2025-10-15 16:16:12,244 - DEBUG - Starting all layer 2 verification in agent 10.0.0.141 2025-10-15 16:16:12,282 - DEBUG - Route for VM MAC 02:31:35:a1:00:24 is consistent in agent 10.0.0.141 2025-10-15 16:16:15,431 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1005ms') 2025-10-15 16:16:15,431 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-66511174 failed! 2025-10-15 16:16:15,458 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858 is 176.76.108.65 and allocation pool is NOT set 2025-10-15 16:16:15,458 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858 is 2a8b:8855:cc89:8d84:12d:3f24:0:1 and allocation pool is NOT set 2025-10-15 16:16:19,528 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1005ms') 2025-10-15 16:16:19,528 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-66511174 failed! 2025-10-15 16:16:19,543 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858 is 176.76.108.65 and allocation pool is NOT set 2025-10-15 16:16:19,543 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858 is 2a8b:8855:cc89:8d84:12d:3f24:0:1 and allocation pool is NOT set 2025-10-15 16:16:23,631 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1017ms') 2025-10-15 16:16:23,631 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-66511174 failed! 2025-10-15 16:16:23,649 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858 is 176.76.108.65 and allocation pool is NOT set 2025-10-15 16:16:23,649 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858 is 2a8b:8855:cc89:8d84:12d:3f24:0:1 and allocation pool is NOT set 2025-10-15 16:16:27,724 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms') 2025-10-15 16:16:27,724 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-66511174 failed! 2025-10-15 16:16:27,741 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858 is 176.76.108.65 and allocation pool is NOT set 2025-10-15 16:16:27,741 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858 is 2a8b:8855:cc89:8d84:12d:3f24:0:1 and allocation pool is NOT set 2025-10-15 16:16:31,821 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1022ms') 2025-10-15 16:16:31,821 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-66511174 failed! 2025-10-15 16:16:31,837 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858 is 176.76.108.65 and allocation pool is NOT set 2025-10-15 16:16:31,838 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858 is 2a8b:8855:cc89:8d84:12d:3f24:0:1 and allocation pool is NOT set 2025-10-15 16:16:35,921 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1023ms') 2025-10-15 16:16:35,921 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-66511174 failed! 2025-10-15 16:16:35,939 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858 is 176.76.108.65 and allocation pool is NOT set 2025-10-15 16:16:35,939 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858 is 2a8b:8855:cc89:8d84:12d:3f24:0:1 and allocation pool is NOT set 2025-10-15 16:16:40,020 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1015ms') 2025-10-15 16:16:40,021 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-66511174 failed! 2025-10-15 16:16:40,039 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858 is 176.76.108.65 and allocation pool is NOT set 2025-10-15 16:16:40,039 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858 is 2a8b:8855:cc89:8d84:12d:3f24:0:1 and allocation pool is NOT set 2025-10-15 16:16:44,106 - 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 1007ms') 2025-10-15 16:16:44,106 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-66511174 failed! 2025-10-15 16:16:44,121 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858 is 176.76.108.65 and allocation pool is NOT set 2025-10-15 16:16:44,122 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858 is 2a8b:8855:cc89:8d84:12d:3f24:0:1 and allocation pool is NOT set 2025-10-15 16:16:48,199 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1022ms') 2025-10-15 16:16:48,199 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-66511174 failed! 2025-10-15 16:16:48,220 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858 is 176.76.108.65 and allocation pool is NOT set 2025-10-15 16:16:48,220 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858 is 2a8b:8855:cc89:8d84:12d:3f24:0:1 and allocation pool is NOT set 2025-10-15 16:16:52,295 - 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-10-15 16:16:52,296 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-66511174 failed! 2025-10-15 16:16:52,310 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858 is 176.76.108.65 and allocation pool is NOT set 2025-10-15 16:16:52,310 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858 is 2a8b:8855:cc89:8d84:12d:3f24:0:1 and allocation pool is NOT set 2025-10-15 16:16:56,392 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1023ms') 2025-10-15 16:16:56,392 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-66511174 failed! 2025-10-15 16:16:56,409 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858 is 176.76.108.65 and allocation pool is NOT set 2025-10-15 16:16:56,409 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858 is 2a8b:8855:cc89:8d84:12d:3f24:0:1 and allocation pool is NOT set 2025-10-15 16:16:58,480 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=6.49 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.66 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.662/4.074/6.487/2.412 ms') 2025-10-15 16:16:58,480 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-66511174 passed 2025-10-15 16:16:58,480 - INFO - VM ctest-VM11-66511174 verifications in Compute nodes passed 2025-10-15 16:16:58,748 - DEBUG - Validated VM route 176.76.108.67 in vrouter of 10.0.0.141 2025-10-15 16:16:58,755 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0 2025-10-15 16:16:58,755 - INFO - Validated routes of VM ctest-VM11-66511174 in all vrouters 2025-10-15 16:16:59,196 - DEBUG - Starting all layer2 verification in 10.0.0.137 Control Node 2025-10-15 16:16:59,311 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.137, not supported 2025-10-15 16:16:59,448 - DEBUG - Layer2 route found for VM MAC 02:31:35:a1:00:24 in Control-node 10.0.0.137 2025-10-15 16:16:59,448 - DEBUG - L2 Label for VM ctest-VM11-66511174 same between Control-node 10.0.0.137 and Agent, Expected: 29, Seen: 29 2025-10-15 16:16:59,448 - DEBUG - Starting all layer2 verification in 10.0.0.130 Control Node 2025-10-15 16:16:59,576 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.130, not supported 2025-10-15 16:16:59,712 - DEBUG - Layer2 route found for VM MAC 02:31:35:a1:00:24 in Control-node 10.0.0.130 2025-10-15 16:16:59,712 - DEBUG - L2 Label for VM ctest-VM11-66511174 same between Control-node 10.0.0.130 and Agent, Expected: 29, Seen: 29 2025-10-15 16:16:59,712 - DEBUG - Starting all layer2 verification in 10.0.0.131 Control Node 2025-10-15 16:16:59,836 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.131, not supported 2025-10-15 16:16:59,973 - DEBUG - Layer2 route found for VM MAC 02:31:35:a1:00:24 in Control-node 10.0.0.131 2025-10-15 16:16:59,973 - DEBUG - L2 Label for VM ctest-VM11-66511174 same between Control-node 10.0.0.131 and Agent, Expected: 29, Seen: 29 2025-10-15 16:16:59,973 - INFO - Verification in Control-nodes for VM ctest-VM11-66511174 passed 2025-10-15 16:16:59,973 - DEBUG - Verifying the vm in opserver 2025-10-15 16:16:59,973 - DEBUG - Verifying in collector 10.0.0.137 ... 2025-10-15 16:16:59,973 - DEBUG - Requesting: http://10.0.0.137:8081/analytics/uves/virtual-machine/b90b02e6-70d5-4f83-89a8-500c9eeadaf8?flat 2025-10-15 16:16:59,993 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-76776884:3135a100-2434-4ec7-894f-64521374b71d?flat 2025-10-15 16:17:00,021 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-76776884:3135a100-2434-4ec7-894f-64521374b71d?flat 2025-10-15 16:17:00,039 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-76776884:3135a100-2434-4ec7-894f-64521374b71d?flat 2025-10-15 16:17:00,060 - DEBUG - Requesting: http://10.0.0.137:8081/analytics/uves/virtual-machine/b90b02e6-70d5-4f83-89a8-500c9eeadaf8?flat 2025-10-15 16:17:00,080 - DEBUG - Verifying vm in vn uve 2025-10-15 16:17:00,080 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-76776884:3135a100-2434-4ec7-894f-64521374b71d?flat 2025-10-15 16:17:00,100 - DEBUG - VM uve shows interface as {'fixed_ip6_list': ['2a8b:8855:cc89:8d84:12d:3f24:0:3'], 'admin_state': True, 'label': 25, 'ip6_active': True, 'gateway': '176.76.108.65', 'vm_uuid': 'b90b02e6-70d5-4f83-89a8-500c9eeadaf8', 'port_mirror_enabled': False, 'ip_address': '176.76.108.67', '__T': 1760544977349567, 'is_health_check_active': True, 'ip6_address': '2a8b:8855:cc89:8d84:12d:3f24:0:3', 'active': True, 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858', 'tx_vlan': 65535, 'uuid': '3135a100-2434-4ec7-894f-64521374b71d', 'l2_active': True, 'vm_name': 'ctest-VM11-66511174', 'ip4_active': True, 'fixed_ip4_list': ['176.76.108.67'], 'rx_vlan': 65535, 'vhostuser_mode': 0, 'vn_uuid': '46a99359-7cb4-4b9a-a150-5b63321d4279', 'mac_address': '02:31:35:a1:00:24'} 2025-10-15 16:17:00,100 - DEBUG - VM uve shows ip address as ['176.76.108.67', '2a8b:8855:cc89:8d84:12d:3f24:0:3'] 2025-10-15 16:17:00,101 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858 2025-10-15 16:17:00,101 - INFO - Verifying through opserver in 10.0.0.130 2025-10-15 16:17:00,101 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858?flat 2025-10-15 16:17:00,122 - DEBUG - expected vm list b90b02e6-70d5-4f83-89a8-500c9eeadaf8 2025-10-15 16:17:00,122 - DEBUG - Extracted vm list ['b90b02e6-70d5-4f83-89a8-500c9eeadaf8'] 2025-10-15 16:17:00,122 - INFO - VM b90b02e6-70d5-4f83-89a8-500c9eeadaf8 is present in default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858 2025-10-15 16:17:00,122 - DEBUG - Verifying vm in vrouter uve 2025-10-15 16:17:00,123 - DEBUG - Getting info from collector 10.0.0.137.. 2025-10-15 16:17:00,123 - DEBUG - Requesting: http://10.0.0.137:8081/analytics/uves/virtual-machine/b90b02e6-70d5-4f83-89a8-500c9eeadaf8?flat 2025-10-15 16:17:00,142 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-4330-1'] 2025-10-15 16:17:00,142 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4330-1:Compute:contrail-vrouter-agent:0?flat 2025-10-15 16:17:00,177 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4330-1?flat 2025-10-15 16:17:00,212 - DEBUG - VM b90b02e6-70d5-4f83-89a8-500c9eeadaf8 is present in vrouter an-jenkins-deploy-platform-ansible-os-4330-1 uve 2025-10-15 16:17:00,212 - DEBUG - Validated that VM ctest-VM11-66511174 is in Vrouter an-jenkins-deploy-platform-ansible-os-4330-1 UVE 2025-10-15 16:17:00,212 - DEBUG - Verifying vm tap interface/vn in vrouter uve 2025-10-15 16:17:00,221 - DEBUG - Expected tap interface of VM uuid b90b02e6-70d5-4f83-89a8-500c9eeadaf8 is default-domain:ctest-TestPolicyAclIpv4v6-76776884:3135a100-2434-4ec7-894f-64521374b71d 2025-10-15 16:17:00,222 - DEBUG - Expected VN of VM uuid b90b02e6-70d5-4f83-89a8-500c9eeadaf8 is default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858 2025-10-15 16:17:00,222 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4330-1:Compute:contrail-vrouter-agent:0?flat 2025-10-15 16:17:00,256 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4330-1?flat 2025-10-15 16:17:00,290 - DEBUG - VM b90b02e6-70d5-4f83-89a8-500c9eeadaf8 is present in vrouter an-jenkins-deploy-platform-ansible-os-4330-1 uve 2025-10-15 16:17:00,290 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-76776884:3135a100-2434-4ec7-894f-64521374b71d of vm b90b02e6-70d5-4f83-89a8-500c9eeadaf8 is present in vrouter an-jenkins-deploy-platform-ansible-os-4330-1 uve 2025-10-15 16:17:00,290 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858 of vm b90b02e6-70d5-4f83-89a8-500c9eeadaf8 is present in vrouter an-jenkins-deploy-platform-ansible-os-4330-1 uve 2025-10-15 16:17:00,290 - DEBUG - Verifying the b90b02e6-70d5-4f83-89a8-500c9eeadaf8 virtual network link through opserver 10.0.0.130 2025-10-15 16:17:00,290 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-machines 2025-10-15 16:17:00,303 - DEBUG - VM link and name as {'name': 'b90b02e6-70d5-4f83-89a8-500c9eeadaf8', 'href': 'http://10.0.0.130:8081/analytics/uves/virtual-machine/b90b02e6-70d5-4f83-89a8-500c9eeadaf8?flat'} 2025-10-15 16:17:00,303 - DEBUG - Verifying the b90b02e6-70d5-4f83-89a8-500c9eeadaf8 virtual network link through opserver 10.0.0.131 2025-10-15 16:17:00,303 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-machines 2025-10-15 16:17:00,319 - DEBUG - VM link and name as {'name': 'b90b02e6-70d5-4f83-89a8-500c9eeadaf8', 'href': 'http://10.0.0.131:8081/analytics/uves/virtual-machine/b90b02e6-70d5-4f83-89a8-500c9eeadaf8?flat'} 2025-10-15 16:17:00,319 - DEBUG - Verifying the b90b02e6-70d5-4f83-89a8-500c9eeadaf8 virtual network link through opserver 10.0.0.137 2025-10-15 16:17:00,319 - DEBUG - Requesting: http://10.0.0.137:8081/analytics/uves/virtual-machines 2025-10-15 16:17:00,332 - DEBUG - VM link and name as {'name': 'b90b02e6-70d5-4f83-89a8-500c9eeadaf8', 'href': 'http://10.0.0.137:8081/analytics/uves/virtual-machine/b90b02e6-70d5-4f83-89a8-500c9eeadaf8?flat'} 2025-10-15 16:17:00,332 - INFO - VM ctest-VM11-66511174 validations in Opserver passed 2025-10-15 16:17:00,421 - DEBUG - VM is in ACTIVE state now 2025-10-15 16:17:00,421 - INFO - VM name : ctest-VM21-35882190 2025-10-15 16:17:00,503 - DEBUG - VM ctest-VM21-35882190 ID is 05e0d2bc-9a97-49aa-b91a-624962954045 2025-10-15 16:17:00,588 - DEBUG - VM is in ACTIVE state now 2025-10-15 16:17:00,588 - DEBUG - VM ctest-VM21-35882190 launched on Node an-jenkins-deploy-platform-ansible-os-4330-2 2025-10-15 16:17:00,685 - DEBUG - Requesting: http://10.0.0.137:8082/virtual-machine/05e0d2bc-9a97-49aa-b91a-624962954045 2025-10-15 16:17:00,699 - DEBUG - Requesting: http://10.0.0.137:8082/virtual-machine/05e0d2bc-9a97-49aa-b91a-624962954045 2025-10-15 16:17:00,712 - DEBUG - Requesting: http://10.0.0.137:8082/virtual-machine-interface/f97185b1-5ac2-4561-aa31-116e6d5db9f5 2025-10-15 16:17:00,725 - DEBUG - Requesting: http://10.0.0.137:8082/virtual-machine/05e0d2bc-9a97-49aa-b91a-624962954045 2025-10-15 16:17:00,735 - DEBUG - Requesting: http://10.0.0.137:8082/virtual-machine-interface/f97185b1-5ac2-4561-aa31-116e6d5db9f5 2025-10-15 16:17:00,749 - DEBUG - Requesting: http://10.0.0.137:8082/instance-ip/092a2b04-e4f7-45fb-9aae-4d37b6aac3bf 2025-10-15 16:17:00,761 - DEBUG - Requesting: http://10.0.0.137:8082/instance-ip/b196b975-f719-434c-b8b7-824d7aa2d7df 2025-10-15 16:17:00,776 - DEBUG - Verifying in api server 10.0.0.137 2025-10-15 16:17:00,776 - DEBUG - Requesting: http://10.0.0.137:8082/virtual-machine/05e0d2bc-9a97-49aa-b91a-624962954045 2025-10-15 16:17:00,786 - DEBUG - Requesting: http://10.0.0.137:8082/virtual-machine-interface/f97185b1-5ac2-4561-aa31-116e6d5db9f5 2025-10-15 16:17:00,800 - DEBUG - Requesting: http://10.0.0.137:8082/instance-ip/092a2b04-e4f7-45fb-9aae-4d37b6aac3bf 2025-10-15 16:17:00,812 - DEBUG - Requesting: http://10.0.0.137:8082/instance-ip/b196b975-f719-434c-b8b7-824d7aa2d7df 2025-10-15 16:17:00,824 - INFO - VM ctest-VM21-35882190 verfication in all API Servers passed 2025-10-15 16:17:00,871 - DEBUG - VM ctest-VM21-35882190 Tap interface: {'index': '3', 'name': 'tapf97185b1-5a', 'uuid': 'f97185b1-5ac2-4561-aa31-116e6d5db9f5', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN2-28212433:ctest-VN2-28212433', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Active', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Enable', 'dns_service': 'Enable', 'type': 'vport', 'label': '25', 'l2_label': '29', 'vxlan_id': '16', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN2-28212433', 'vm_uuid': '05e0d2bc-9a97-49aa-b91a-624962954045', 'vm_name': 'ctest-VM21-35882190', 'ip_addr': '200.134.225.195', 'mac_addr': '02:f9:71:85:b1:5a', '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-76776884:f97185b1-5ac2-4561-aa31-116e6d5db9f5', 'sg_uuid_list': ['8d9e6fc0-a38a-4db3-ab76-38cd2d7df747'], 'static_route_list': None, 'vm_project_uuid': 'e94ece4c-105f-43f3-8c60-fac9d2edefe4', 'admin_state': 'Enabled', 'flow_key_idx': '30', 'allowed_address_pair_list': None, 'ip6_addr': '2043:131f:7f89:909b:9bd8:1066:0:3', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []} 2025-10-15 16:17:00,871 - DEBUG - Agent 10.0.0.33 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN2-28212433:ctest-VN2-28212433 2025-10-15 16:17:00,878 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN2-28212433:ctest-VN2-28212433', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN2-28212433', 'table_label': '-1', 'vxlan_id': '16', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.249:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2025-10-15 16:17:00,975 - DEBUG - VM ctest-VM21-35882190 : Tap interface tapf97185b1-5a is set to Active 2025-10-15 16:17:00,975 - DEBUG - Tap interface tapf97185b1-5a detail : {'index': '3', 'name': 'tapf97185b1-5a', 'uuid': 'f97185b1-5ac2-4561-aa31-116e6d5db9f5', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN2-28212433:ctest-VN2-28212433', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Active', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Enable', 'dns_service': 'Enable', 'type': 'vport', 'label': '25', 'l2_label': '29', 'vxlan_id': '16', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN2-28212433', 'vm_uuid': '05e0d2bc-9a97-49aa-b91a-624962954045', 'vm_name': 'ctest-VM21-35882190', 'ip_addr': '200.134.225.195', 'mac_addr': '02:f9:71:85:b1:5a', '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-76776884:f97185b1-5ac2-4561-aa31-116e6d5db9f5', 'sg_uuid_list': ['8d9e6fc0-a38a-4db3-ab76-38cd2d7df747'], 'static_route_list': None, 'vm_project_uuid': 'e94ece4c-105f-43f3-8c60-fac9d2edefe4', 'admin_state': 'Enabled', 'flow_key_idx': '30', 'allowed_address_pair_list': None, 'ip6_addr': '2043:131f:7f89:909b:9bd8:1066:0:3', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []} 2025-10-15 16:17:01,094 - DEBUG - Starting Layer 2 verification in Agent 2025-10-15 16:17:01,108 - DEBUG - Layer 2 path is seen for VM MAC 02:f9:71:85:b1:5a in agent 10.0.0.33 2025-10-15 16:17:01,108 - DEBUG - Active layer 2 route in agent is present for VMI tapf97185b1-5a 2025-10-15 16:17:01,108 - DEBUG - L2 label(29) matches bw route table and interface table 2025-10-15 16:17:01,123 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN2-28212433 is found in Agent of node 10.0.0.33 2025-10-15 16:17:01,283 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN2-28212433 is consistent in agent 10.0.0.33 2025-10-15 16:17:01,283 - DEBUG - Route for VM IP ['2043:131f:7f89:909b:9bd8:1066:0:3', '200.134.225.195'] is consistent in agent 10.0.0.33 2025-10-15 16:17:01,283 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN2-28212433 verification for VM ctest-VM21-35882190 in Agent 10.0.0.33 passed 2025-10-15 16:17:01,405 - DEBUG - Starting all layer 2 verification in agent 10.0.0.33 2025-10-15 16:17:01,426 - DEBUG - Route for VM MAC 02:f9:71:85:b1:5a is consistent in agent 10.0.0.33 2025-10-15 16:17:02,613 - 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.50 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.49 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 2.494/2.995/3.496/0.501 ms') 2025-10-15 16:17:02,613 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-35882190 passed 2025-10-15 16:17:02,613 - INFO - VM ctest-VM21-35882190 verifications in Compute nodes passed 2025-10-15 16:17:02,860 - DEBUG - Validated VM route 200.134.225.195 in vrouter of 10.0.0.33 2025-10-15 16:17:02,871 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0 2025-10-15 16:17:02,871 - INFO - Validated routes of VM ctest-VM21-35882190 in all vrouters 2025-10-15 16:17:03,361 - DEBUG - Starting all layer2 verification in 10.0.0.137 Control Node 2025-10-15 16:17:03,482 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.137, not supported 2025-10-15 16:17:03,621 - DEBUG - Layer2 route found for VM MAC 02:f9:71:85:b1:5a in Control-node 10.0.0.137 2025-10-15 16:17:03,622 - DEBUG - L2 Label for VM ctest-VM21-35882190 same between Control-node 10.0.0.137 and Agent, Expected: 29, Seen: 29 2025-10-15 16:17:03,622 - DEBUG - Starting all layer2 verification in 10.0.0.130 Control Node 2025-10-15 16:17:03,741 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.130, not supported 2025-10-15 16:17:03,871 - DEBUG - Layer2 route found for VM MAC 02:f9:71:85:b1:5a in Control-node 10.0.0.130 2025-10-15 16:17:03,871 - DEBUG - L2 Label for VM ctest-VM21-35882190 same between Control-node 10.0.0.130 and Agent, Expected: 29, Seen: 29 2025-10-15 16:17:03,871 - DEBUG - Starting all layer2 verification in 10.0.0.131 Control Node 2025-10-15 16:17:03,994 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.131, not supported 2025-10-15 16:17:04,120 - DEBUG - Layer2 route found for VM MAC 02:f9:71:85:b1:5a in Control-node 10.0.0.131 2025-10-15 16:17:04,120 - DEBUG - L2 Label for VM ctest-VM21-35882190 same between Control-node 10.0.0.131 and Agent, Expected: 29, Seen: 29 2025-10-15 16:17:04,120 - INFO - Verification in Control-nodes for VM ctest-VM21-35882190 passed 2025-10-15 16:17:04,120 - DEBUG - Verifying the vm in opserver 2025-10-15 16:17:04,120 - DEBUG - Verifying in collector 10.0.0.137 ... 2025-10-15 16:17:04,120 - DEBUG - Requesting: http://10.0.0.137:8081/analytics/uves/virtual-machine/05e0d2bc-9a97-49aa-b91a-624962954045?flat 2025-10-15 16:17:04,140 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-76776884:f97185b1-5ac2-4561-aa31-116e6d5db9f5?flat 2025-10-15 16:17:04,161 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-76776884:f97185b1-5ac2-4561-aa31-116e6d5db9f5?flat 2025-10-15 16:17:04,186 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-76776884:f97185b1-5ac2-4561-aa31-116e6d5db9f5?flat 2025-10-15 16:17:04,210 - DEBUG - Requesting: http://10.0.0.137:8081/analytics/uves/virtual-machine/05e0d2bc-9a97-49aa-b91a-624962954045?flat 2025-10-15 16:17:04,233 - DEBUG - Verifying vm in vn uve 2025-10-15 16:17:04,233 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-76776884:f97185b1-5ac2-4561-aa31-116e6d5db9f5?flat 2025-10-15 16:17:04,254 - DEBUG - VM uve shows interface as {'tx_vlan': 65535, 'admin_state': True, 'ip6_active': True, 'is_health_check_active': True, 'rx_vlan': 65535, 'fixed_ip4_list': ['200.134.225.195'], '__T': 1760544999210754, 'mac_address': '02:f9:71:85:b1:5a', 'vn_uuid': 'd060d0d6-af83-43fa-b9d1-7b85b0171f57', 'label': 25, 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN2-28212433', 'ip6_address': '2043:131f:7f89:909b:9bd8:1066:0:3', 'l2_active': True, 'gateway': '200.134.225.193', 'vhostuser_mode': 0, 'vm_uuid': '05e0d2bc-9a97-49aa-b91a-624962954045', 'fixed_ip6_list': ['2043:131f:7f89:909b:9bd8:1066:0:3'], 'vm_name': 'ctest-VM21-35882190', 'uuid': 'f97185b1-5ac2-4561-aa31-116e6d5db9f5', 'ip4_active': True, 'port_mirror_enabled': False, 'ip_address': '200.134.225.195', 'active': True} 2025-10-15 16:17:04,254 - DEBUG - VM uve shows ip address as ['200.134.225.195', '2043:131f:7f89:909b:9bd8:1066:0:3'] 2025-10-15 16:17:04,254 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN2-28212433 2025-10-15 16:17:04,254 - INFO - Verifying through opserver in 10.0.0.130 2025-10-15 16:17:04,254 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN2-28212433?flat 2025-10-15 16:17:04,276 - DEBUG - expected vm list 05e0d2bc-9a97-49aa-b91a-624962954045 2025-10-15 16:17:04,277 - DEBUG - Extracted vm list ['05e0d2bc-9a97-49aa-b91a-624962954045'] 2025-10-15 16:17:04,277 - INFO - VM 05e0d2bc-9a97-49aa-b91a-624962954045 is present in default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN2-28212433 2025-10-15 16:17:04,277 - DEBUG - Verifying vm in vrouter uve 2025-10-15 16:17:04,277 - DEBUG - Getting info from collector 10.0.0.137.. 2025-10-15 16:17:04,277 - DEBUG - Requesting: http://10.0.0.137:8081/analytics/uves/virtual-machine/05e0d2bc-9a97-49aa-b91a-624962954045?flat 2025-10-15 16:17:04,297 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-4330-2'] 2025-10-15 16:17:04,297 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4330-2:Compute:contrail-vrouter-agent:0?flat 2025-10-15 16:17:04,330 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4330-2?flat 2025-10-15 16:17:04,363 - DEBUG - VM 05e0d2bc-9a97-49aa-b91a-624962954045 is present in vrouter an-jenkins-deploy-platform-ansible-os-4330-2 uve 2025-10-15 16:17:04,363 - DEBUG - Validated that VM ctest-VM21-35882190 is in Vrouter an-jenkins-deploy-platform-ansible-os-4330-2 UVE 2025-10-15 16:17:04,363 - DEBUG - Verifying vm tap interface/vn in vrouter uve 2025-10-15 16:17:04,371 - DEBUG - Expected tap interface of VM uuid 05e0d2bc-9a97-49aa-b91a-624962954045 is default-domain:ctest-TestPolicyAclIpv4v6-76776884:f97185b1-5ac2-4561-aa31-116e6d5db9f5 2025-10-15 16:17:04,371 - DEBUG - Expected VN of VM uuid 05e0d2bc-9a97-49aa-b91a-624962954045 is default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN2-28212433 2025-10-15 16:17:04,371 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4330-2:Compute:contrail-vrouter-agent:0?flat 2025-10-15 16:17:04,406 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4330-2?flat 2025-10-15 16:17:04,438 - DEBUG - VM 05e0d2bc-9a97-49aa-b91a-624962954045 is present in vrouter an-jenkins-deploy-platform-ansible-os-4330-2 uve 2025-10-15 16:17:04,438 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-76776884:f97185b1-5ac2-4561-aa31-116e6d5db9f5 of vm 05e0d2bc-9a97-49aa-b91a-624962954045 is present in vrouter an-jenkins-deploy-platform-ansible-os-4330-2 uve 2025-10-15 16:17:04,438 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN2-28212433 of vm 05e0d2bc-9a97-49aa-b91a-624962954045 is present in vrouter an-jenkins-deploy-platform-ansible-os-4330-2 uve 2025-10-15 16:17:04,438 - DEBUG - Verifying the 05e0d2bc-9a97-49aa-b91a-624962954045 virtual network link through opserver 10.0.0.130 2025-10-15 16:17:04,438 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-machines 2025-10-15 16:17:04,452 - DEBUG - VM link and name as {'name': '05e0d2bc-9a97-49aa-b91a-624962954045', 'href': 'http://10.0.0.130:8081/analytics/uves/virtual-machine/05e0d2bc-9a97-49aa-b91a-624962954045?flat'} 2025-10-15 16:17:04,452 - DEBUG - Verifying the 05e0d2bc-9a97-49aa-b91a-624962954045 virtual network link through opserver 10.0.0.131 2025-10-15 16:17:04,452 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-machines 2025-10-15 16:17:04,467 - DEBUG - VM link and name as {'name': '05e0d2bc-9a97-49aa-b91a-624962954045', 'href': 'http://10.0.0.131:8081/analytics/uves/virtual-machine/05e0d2bc-9a97-49aa-b91a-624962954045?flat'} 2025-10-15 16:17:04,467 - DEBUG - Verifying the 05e0d2bc-9a97-49aa-b91a-624962954045 virtual network link through opserver 10.0.0.137 2025-10-15 16:17:04,467 - DEBUG - Requesting: http://10.0.0.137:8081/analytics/uves/virtual-machines 2025-10-15 16:17:04,483 - DEBUG - VM link and name as {'name': '05e0d2bc-9a97-49aa-b91a-624962954045', 'href': 'http://10.0.0.137:8081/analytics/uves/virtual-machine/05e0d2bc-9a97-49aa-b91a-624962954045?flat'} 2025-10-15 16:17:04,483 - INFO - VM ctest-VM21-35882190 validations in Opserver passed 2025-10-15 16:17:04,567 - DEBUG - VM is in ACTIVE state now 2025-10-15 16:17:04,567 - INFO - VM name : ctest-VM31-84878027 2025-10-15 16:17:04,655 - DEBUG - VM ctest-VM31-84878027 ID is 19cb08c3-b2b7-440c-9e50-15c695af75f1 2025-10-15 16:17:04,741 - DEBUG - VM is in ACTIVE state now 2025-10-15 16:17:04,741 - DEBUG - VM ctest-VM31-84878027 launched on Node an-jenkins-deploy-platform-ansible-os-4330-1 2025-10-15 16:17:04,819 - DEBUG - Requesting: http://10.0.0.137:8082/virtual-machine/19cb08c3-b2b7-440c-9e50-15c695af75f1 2025-10-15 16:17:04,833 - DEBUG - Requesting: http://10.0.0.137:8082/virtual-machine/19cb08c3-b2b7-440c-9e50-15c695af75f1 2025-10-15 16:17:04,844 - DEBUG - Requesting: http://10.0.0.137:8082/virtual-machine-interface/de099058-a912-454c-97bd-2cf2cb0df96d 2025-10-15 16:17:04,857 - DEBUG - Requesting: http://10.0.0.137:8082/virtual-machine/19cb08c3-b2b7-440c-9e50-15c695af75f1 2025-10-15 16:17:04,871 - DEBUG - Requesting: http://10.0.0.137:8082/virtual-machine-interface/de099058-a912-454c-97bd-2cf2cb0df96d 2025-10-15 16:17:04,887 - DEBUG - Requesting: http://10.0.0.137:8082/instance-ip/2387e81b-5ebd-4f9f-9529-dc4d134e1f6d 2025-10-15 16:17:04,902 - DEBUG - Requesting: http://10.0.0.137:8082/instance-ip/ed68c270-c6eb-41f2-a99e-9616cfc4e057 2025-10-15 16:17:04,915 - DEBUG - Verifying in api server 10.0.0.137 2025-10-15 16:17:04,915 - DEBUG - Requesting: http://10.0.0.137:8082/virtual-machine/19cb08c3-b2b7-440c-9e50-15c695af75f1 2025-10-15 16:17:04,926 - DEBUG - Requesting: http://10.0.0.137:8082/virtual-machine-interface/de099058-a912-454c-97bd-2cf2cb0df96d 2025-10-15 16:17:04,938 - DEBUG - Requesting: http://10.0.0.137:8082/instance-ip/2387e81b-5ebd-4f9f-9529-dc4d134e1f6d 2025-10-15 16:17:04,948 - DEBUG - Requesting: http://10.0.0.137:8082/instance-ip/ed68c270-c6eb-41f2-a99e-9616cfc4e057 2025-10-15 16:17:04,958 - INFO - VM ctest-VM31-84878027 verfication in all API Servers passed 2025-10-15 16:17:04,977 - DEBUG - VM ctest-VM31-84878027 Tap interface: {'index': '4', 'name': 'tapde099058-a9', 'uuid': 'de099058-a912-454c-97bd-2cf2cb0df96d', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN3-49110623:ctest-VN3-49110623', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Active', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Enable', 'dns_service': 'Enable', 'type': 'vport', 'label': '32', 'l2_label': '36', 'vxlan_id': '17', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN3-49110623', 'vm_uuid': '19cb08c3-b2b7-440c-9e50-15c695af75f1', 'vm_name': 'ctest-VM31-84878027', 'ip_addr': '108.69.25.3', 'mac_addr': '02:de:09:90:58:a9', '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-76776884:de099058-a912-454c-97bd-2cf2cb0df96d', 'sg_uuid_list': ['8d9e6fc0-a38a-4db3-ab76-38cd2d7df747'], 'static_route_list': None, 'vm_project_uuid': 'e94ece4c-105f-43f3-8c60-fac9d2edefe4', 'admin_state': 'Enabled', 'flow_key_idx': '41', 'allowed_address_pair_list': None, 'ip6_addr': '2eed:8427:cb15:8815:ea17:1fb9:0:3', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []} 2025-10-15 16:17:04,977 - DEBUG - Agent 10.0.0.141 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN3-49110623:ctest-VN3-49110623 2025-10-15 16:17:04,982 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN3-49110623:ctest-VN3-49110623', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN3-49110623', 'table_label': '-1', 'vxlan_id': '17', 'evpnindex': '3', 'brindex': '3', 'mplsindex': '0', 'RD': '10.20.0.14:3', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2025-10-15 16:17:05,078 - DEBUG - VM ctest-VM31-84878027 : Tap interface tapde099058-a9 is set to Active 2025-10-15 16:17:05,078 - DEBUG - Tap interface tapde099058-a9 detail : {'index': '4', 'name': 'tapde099058-a9', 'uuid': 'de099058-a912-454c-97bd-2cf2cb0df96d', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN3-49110623:ctest-VN3-49110623', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Active', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Enable', 'dns_service': 'Enable', 'type': 'vport', 'label': '32', 'l2_label': '36', 'vxlan_id': '17', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN3-49110623', 'vm_uuid': '19cb08c3-b2b7-440c-9e50-15c695af75f1', 'vm_name': 'ctest-VM31-84878027', 'ip_addr': '108.69.25.3', 'mac_addr': '02:de:09:90:58:a9', '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-76776884:de099058-a912-454c-97bd-2cf2cb0df96d', 'sg_uuid_list': ['8d9e6fc0-a38a-4db3-ab76-38cd2d7df747'], 'static_route_list': None, 'vm_project_uuid': 'e94ece4c-105f-43f3-8c60-fac9d2edefe4', 'admin_state': 'Enabled', 'flow_key_idx': '41', 'allowed_address_pair_list': None, 'ip6_addr': '2eed:8427:cb15:8815:ea17:1fb9:0:3', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []} 2025-10-15 16:17:05,204 - DEBUG - Starting Layer 2 verification in Agent 2025-10-15 16:17:05,213 - DEBUG - Layer 2 path is seen for VM MAC 02:de:09:90:58:a9 in agent 10.0.0.141 2025-10-15 16:17:05,213 - DEBUG - Active layer 2 route in agent is present for VMI tapde099058-a9 2025-10-15 16:17:05,213 - DEBUG - L2 label(36) matches bw route table and interface table 2025-10-15 16:17:05,218 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN3-49110623 is found in Agent of node 10.0.0.141 2025-10-15 16:17:05,363 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN3-49110623 is consistent in agent 10.0.0.141 2025-10-15 16:17:05,363 - DEBUG - Route for VM IP ['108.69.25.3', '2eed:8427:cb15:8815:ea17:1fb9:0:3'] is consistent in agent 10.0.0.141 2025-10-15 16:17:05,364 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN3-49110623 verification for VM ctest-VM31-84878027 in Agent 10.0.0.141 passed 2025-10-15 16:17:05,487 - DEBUG - Starting all layer 2 verification in agent 10.0.0.141 2025-10-15 16:17:05,498 - DEBUG - Route for VM MAC 02:de:09:90:58:a9 is consistent in agent 10.0.0.141 2025-10-15 16:17:06,701 - 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=7.66 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=1.97 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 1.967/4.813/7.659/2.846 ms') 2025-10-15 16:17:06,702 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-84878027 passed 2025-10-15 16:17:06,702 - INFO - VM ctest-VM31-84878027 verifications in Compute nodes passed 2025-10-15 16:17:06,913 - DEBUG - Validated VM route 108.69.25.3 in vrouter of 10.0.0.141 2025-10-15 16:17:06,917 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0 2025-10-15 16:17:06,917 - INFO - Validated routes of VM ctest-VM31-84878027 in all vrouters 2025-10-15 16:17:07,460 - DEBUG - Starting all layer2 verification in 10.0.0.137 Control Node 2025-10-15 16:17:07,588 - DEBUG - Layer2 route found for VM MAC 02:de:09:90:58:a9 in Control-node 10.0.0.137 2025-10-15 16:17:07,588 - DEBUG - L2 Label for VM ctest-VM31-84878027 same between Control-node 10.0.0.137 and Agent, Expected: 36, Seen: 36 2025-10-15 16:17:07,711 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.137, not supported 2025-10-15 16:17:07,711 - DEBUG - Starting all layer2 verification in 10.0.0.130 Control Node 2025-10-15 16:17:07,849 - DEBUG - Layer2 route found for VM MAC 02:de:09:90:58:a9 in Control-node 10.0.0.130 2025-10-15 16:17:07,850 - DEBUG - L2 Label for VM ctest-VM31-84878027 same between Control-node 10.0.0.130 and Agent, Expected: 36, Seen: 36 2025-10-15 16:17:07,972 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.130, not supported 2025-10-15 16:17:07,972 - INFO - Verification in Control-nodes for VM ctest-VM31-84878027 passed 2025-10-15 16:17:07,972 - DEBUG - Verifying the vm in opserver 2025-10-15 16:17:07,972 - DEBUG - Verifying in collector 10.0.0.137 ... 2025-10-15 16:17:07,973 - DEBUG - Requesting: http://10.0.0.137:8081/analytics/uves/virtual-machine/19cb08c3-b2b7-440c-9e50-15c695af75f1?flat 2025-10-15 16:17:07,991 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-76776884:de099058-a912-454c-97bd-2cf2cb0df96d?flat 2025-10-15 16:17:08,013 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-76776884:de099058-a912-454c-97bd-2cf2cb0df96d?flat 2025-10-15 16:17:08,031 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-76776884:de099058-a912-454c-97bd-2cf2cb0df96d?flat 2025-10-15 16:17:08,052 - DEBUG - Requesting: http://10.0.0.137:8081/analytics/uves/virtual-machine/19cb08c3-b2b7-440c-9e50-15c695af75f1?flat 2025-10-15 16:17:08,070 - DEBUG - Verifying vm in vn uve 2025-10-15 16:17:08,070 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-76776884:de099058-a912-454c-97bd-2cf2cb0df96d?flat 2025-10-15 16:17:08,089 - DEBUG - VM uve shows interface as {'fixed_ip6_list': ['2eed:8427:cb15:8815:ea17:1fb9:0:3'], 'admin_state': True, 'label': 32, 'ip6_active': True, 'gateway': '108.69.25.1', 'vm_uuid': '19cb08c3-b2b7-440c-9e50-15c695af75f1', 'port_mirror_enabled': False, 'ip_address': '108.69.25.3', '__T': 1760544977349598, 'is_health_check_active': True, 'ip6_address': '2eed:8427:cb15:8815:ea17:1fb9:0:3', 'active': True, 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN3-49110623', 'tx_vlan': 65535, 'uuid': 'de099058-a912-454c-97bd-2cf2cb0df96d', 'l2_active': True, 'vm_name': 'ctest-VM31-84878027', 'ip4_active': True, 'fixed_ip4_list': ['108.69.25.3'], 'rx_vlan': 65535, 'vhostuser_mode': 0, 'vn_uuid': '63414430-ed69-40e0-90d8-9c660ef2cca9', 'mac_address': '02:de:09:90:58:a9'} 2025-10-15 16:17:08,089 - DEBUG - VM uve shows ip address as ['108.69.25.3', '2eed:8427:cb15:8815:ea17:1fb9:0:3'] 2025-10-15 16:17:08,089 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN3-49110623 2025-10-15 16:17:08,090 - INFO - Verifying through opserver in 10.0.0.130 2025-10-15 16:17:08,090 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN3-49110623?flat 2025-10-15 16:17:08,113 - DEBUG - expected vm list 19cb08c3-b2b7-440c-9e50-15c695af75f1 2025-10-15 16:17:08,113 - DEBUG - Extracted vm list ['19cb08c3-b2b7-440c-9e50-15c695af75f1'] 2025-10-15 16:17:08,113 - INFO - VM 19cb08c3-b2b7-440c-9e50-15c695af75f1 is present in default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN3-49110623 2025-10-15 16:17:08,113 - DEBUG - Verifying vm in vrouter uve 2025-10-15 16:17:08,113 - DEBUG - Getting info from collector 10.0.0.137.. 2025-10-15 16:17:08,113 - DEBUG - Requesting: http://10.0.0.137:8081/analytics/uves/virtual-machine/19cb08c3-b2b7-440c-9e50-15c695af75f1?flat 2025-10-15 16:17:08,131 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-4330-1'] 2025-10-15 16:17:08,131 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4330-1:Compute:contrail-vrouter-agent:0?flat 2025-10-15 16:17:08,170 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4330-1?flat 2025-10-15 16:17:08,207 - DEBUG - VM 19cb08c3-b2b7-440c-9e50-15c695af75f1 is present in vrouter an-jenkins-deploy-platform-ansible-os-4330-1 uve 2025-10-15 16:17:08,207 - DEBUG - Validated that VM ctest-VM31-84878027 is in Vrouter an-jenkins-deploy-platform-ansible-os-4330-1 UVE 2025-10-15 16:17:08,207 - DEBUG - Verifying vm tap interface/vn in vrouter uve 2025-10-15 16:17:08,214 - DEBUG - Expected tap interface of VM uuid 19cb08c3-b2b7-440c-9e50-15c695af75f1 is default-domain:ctest-TestPolicyAclIpv4v6-76776884:de099058-a912-454c-97bd-2cf2cb0df96d 2025-10-15 16:17:08,214 - DEBUG - Expected VN of VM uuid 19cb08c3-b2b7-440c-9e50-15c695af75f1 is default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN3-49110623 2025-10-15 16:17:08,214 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4330-1:Compute:contrail-vrouter-agent:0?flat 2025-10-15 16:17:08,252 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4330-1?flat 2025-10-15 16:17:08,288 - DEBUG - VM 19cb08c3-b2b7-440c-9e50-15c695af75f1 is present in vrouter an-jenkins-deploy-platform-ansible-os-4330-1 uve 2025-10-15 16:17:08,288 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-76776884:de099058-a912-454c-97bd-2cf2cb0df96d of vm 19cb08c3-b2b7-440c-9e50-15c695af75f1 is present in vrouter an-jenkins-deploy-platform-ansible-os-4330-1 uve 2025-10-15 16:17:08,288 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN3-49110623 of vm 19cb08c3-b2b7-440c-9e50-15c695af75f1 is present in vrouter an-jenkins-deploy-platform-ansible-os-4330-1 uve 2025-10-15 16:17:08,288 - DEBUG - Verifying the 19cb08c3-b2b7-440c-9e50-15c695af75f1 virtual network link through opserver 10.0.0.130 2025-10-15 16:17:08,288 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-machines 2025-10-15 16:17:08,301 - DEBUG - VM link and name as {'name': '19cb08c3-b2b7-440c-9e50-15c695af75f1', 'href': 'http://10.0.0.130:8081/analytics/uves/virtual-machine/19cb08c3-b2b7-440c-9e50-15c695af75f1?flat'} 2025-10-15 16:17:08,301 - DEBUG - Verifying the 19cb08c3-b2b7-440c-9e50-15c695af75f1 virtual network link through opserver 10.0.0.131 2025-10-15 16:17:08,301 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-machines 2025-10-15 16:17:08,318 - DEBUG - VM link and name as {'name': '19cb08c3-b2b7-440c-9e50-15c695af75f1', 'href': 'http://10.0.0.131:8081/analytics/uves/virtual-machine/19cb08c3-b2b7-440c-9e50-15c695af75f1?flat'} 2025-10-15 16:17:08,318 - DEBUG - Verifying the 19cb08c3-b2b7-440c-9e50-15c695af75f1 virtual network link through opserver 10.0.0.137 2025-10-15 16:17:08,318 - DEBUG - Requesting: http://10.0.0.137:8081/analytics/uves/virtual-machines 2025-10-15 16:17:08,333 - DEBUG - VM link and name as {'name': '19cb08c3-b2b7-440c-9e50-15c695af75f1', 'href': 'http://10.0.0.137:8081/analytics/uves/virtual-machine/19cb08c3-b2b7-440c-9e50-15c695af75f1?flat'} 2025-10-15 16:17:08,333 - INFO - VM ctest-VM31-84878027 validations in Opserver passed 2025-10-15 16:17:08,333 - INFO - Waiting for VM ctest-VM11-66511174 to be up.. 2025-10-15 16:17:08,333 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-15 16:17:08,333 - DEBUG - Requesting: http://10.0.0.137:8082/virtual-machine/b90b02e6-70d5-4f83-89a8-500c9eeadaf8 2025-10-15 16:17:08,345 - DEBUG - Requesting: http://10.0.0.137:8082/virtual-machine-interface/3135a100-2434-4ec7-894f-64521374b71d 2025-10-15 16:17:09,523 - 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.14 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.53 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 2.529/2.833/3.138/0.304 ms') 2025-10-15 16:17:09,524 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-66511174 passed 2025-10-15 16:17:09,603 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-15 16:17:09,603 - DEBUG - Waiting to SSH to VM ctest-VM11-66511174, IP 176.76.108.67, Port 22 2025-10-15 16:17:09,671 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2025-10-15 16:17:09,752 - DEBUG - VM ctest-VM11-66511174 is NOT ready for SSH connections, VM status: ACTIVE 2025-10-15 16:17:14,753 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-15 16:17:14,753 - DEBUG - Waiting to SSH to VM ctest-VM11-66511174, IP 176.76.108.67, Port 22 2025-10-15 16:17:14,819 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2025-10-15 16:17:14,916 - DEBUG - VM ctest-VM11-66511174 is NOT ready for SSH connections, VM status: ACTIVE 2025-10-15 16:17:19,918 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-15 16:17:19,918 - DEBUG - Waiting to SSH to VM ctest-VM11-66511174, IP 176.76.108.67, Port 22 2025-10-15 16:17:20,074 - DEBUG - VM ctest-VM11-66511174 is ready for SSH connections 2025-10-15 16:17:20,074 - INFO - Waiting for VM ctest-VM21-35882190 to be up.. 2025-10-15 16:17:20,074 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-15 16:17:20,074 - DEBUG - Requesting: http://10.0.0.137:8082/virtual-machine/05e0d2bc-9a97-49aa-b91a-624962954045 2025-10-15 16:17:20,088 - DEBUG - Requesting: http://10.0.0.137:8082/virtual-machine-interface/f97185b1-5ac2-4561-aa31-116e6d5db9f5 2025-10-15 16:17:21,268 - 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.05 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.53 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 2.045/2.287/2.529/0.242 ms') 2025-10-15 16:17:21,268 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-35882190 passed 2025-10-15 16:17:21,340 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-15 16:17:21,340 - DEBUG - Waiting to SSH to VM ctest-VM21-35882190, IP 200.134.225.195, Port 22 2025-10-15 16:17:21,511 - DEBUG - VM ctest-VM21-35882190 is ready for SSH connections 2025-10-15 16:17:21,511 - INFO - Waiting for VM ctest-VM31-84878027 to be up.. 2025-10-15 16:17:21,511 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-15 16:17:21,511 - DEBUG - Requesting: http://10.0.0.137:8082/virtual-machine/19cb08c3-b2b7-440c-9e50-15c695af75f1 2025-10-15 16:17:21,521 - DEBUG - Requesting: http://10.0.0.137:8082/virtual-machine-interface/de099058-a912-454c-97bd-2cf2cb0df96d 2025-10-15 16:17:22,693 - 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.01 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=2.16 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 2.158/3.081/4.005/0.923 ms') 2025-10-15 16:17:22,693 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-84878027 passed 2025-10-15 16:17:22,768 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-15 16:17:22,768 - DEBUG - Waiting to SSH to VM ctest-VM31-84878027, IP 108.69.25.3, Port 22 2025-10-15 16:17:22,930 - DEBUG - VM ctest-VM31-84878027 is ready for SSH connections 2025-10-15 16:17:22,930 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 200.134.225.195, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.141, gateway password: c0ntrail123 2025-10-15 16:17:22,930 - DEBUG - ping -s 56 -c 3 -W 1 200.134.225.195 2025-10-15 16:17:43,396 - DEBUG - PING 200.134.225.195 (200.134.225.195) 56(84) bytes of data. 64 bytes from 200.134.225.195: icmp_req=1 ttl=63 time=7.52 ms 64 bytes from 200.134.225.195: icmp_req=2 ttl=63 time=1.18 ms 64 bytes from 200.134.225.195: icmp_req=3 ttl=63 time=1.23 ms --- 200.134.225.195 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2004ms rtt min/avg/max/mdev = 1.185/3.315/7.528/2.979 ms 2025-10-15 16:17:43,397 - INFO - Ping to IP 200.134.225.195 from VM ctest-VM11-66511174 passed 2025-10-15 16:17:43,397 - DEBUG - Running remote_cmd, Cmd : ping6 -s 56 -c 3 -W 1 2043:131f:7f89:909b:9bd8:1066:0:3, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.141, gateway password: c0ntrail123 2025-10-15 16:17:43,397 - DEBUG - ping6 -s 56 -c 3 -W 1 2043:131f:7f89:909b:9bd8:1066:0:3 2025-10-15 16:17:45,941 - DEBUG - PING 2043:131f:7f89:909b:9bd8:1066:0:3(2043:131f:7f89:909b:9bd8:1066:0:3) 56 data bytes 64 bytes from 2043:131f:7f89:909b:9bd8:1066:0:3: icmp_seq=1 ttl=64 time=8.61 ms 64 bytes from 2043:131f:7f89:909b:9bd8:1066:0:3: icmp_seq=2 ttl=64 time=1.59 ms 64 bytes from 2043:131f:7f89:909b:9bd8:1066:0:3: icmp_seq=3 ttl=64 time=1.26 ms --- 2043:131f:7f89:909b:9bd8:1066:0:3 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2004ms rtt min/avg/max/mdev = 1.261/3.824/8.618/3.392 ms 2025-10-15 16:17:45,941 - INFO - Ping to IP 2043:131f:7f89:909b:9bd8:1066:0:3 from VM ctest-VM11-66511174 passed 2025-10-15 16:17:45,941 - INFO - Test with src as policy and dst as any PASSED 2025-10-15 16:17:45,941 - INFO - Deleting VM ctest-VM31-84878027 2025-10-15 16:17:46,013 - DEBUG - Verifying in api server 10.0.0.137 2025-10-15 16:17:46,013 - DEBUG - Requesting: http://10.0.0.137:8082/virtual-machine/19cb08c3-b2b7-440c-9e50-15c695af75f1 2025-10-15 16:17:46,024 - DEBUG - VM ID 19cb08c3-b2b7-440c-9e50-15c695af75f1 of VM ctest-VM31-84878027 is still found in API Server 2025-10-15 16:17:48,025 - DEBUG - Verifying in api server 10.0.0.137 2025-10-15 16:17:48,025 - DEBUG - Requesting: http://10.0.0.137:8082/virtual-machine/19cb08c3-b2b7-440c-9e50-15c695af75f1 2025-10-15 16:17:48,034 - DEBUG - Response Code: 404 2025-10-15 16:17:48,034 - DEBUG - Requesting: http://10.0.0.137:8082/virtual-machine/19cb08c3-b2b7-440c-9e50-15c695af75f1 2025-10-15 16:17:48,042 - DEBUG - Response Code: 404 2025-10-15 16:17:48,042 - DEBUG - Requesting: http://10.0.0.137:8082/virtual-machine/19cb08c3-b2b7-440c-9e50-15c695af75f1 2025-10-15 16:17:48,050 - DEBUG - Response Code: 404 2025-10-15 16:17:48,050 - INFO - VM ctest-VM31-84878027 is fully removed in API-Server 2025-10-15 16:17:48,201 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found 2025-10-15 16:17:48,249 - DEBUG - Validated that vrouter 10.0.0.141 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN3-49110623 2025-10-15 16:17:48,261 - DEBUG - Validated that vrouter 10.0.0.33 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN3-49110623 2025-10-15 16:17:48,261 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN3-49110623 2025-10-15 16:17:48,261 - INFO - VM ctest-VM31-84878027 is removed in Compute, and routes are removed in all compute nodes 2025-10-15 16:17:48,510 - INFO - Routes for VM ctest-VM31-84878027 is removed in all control-nodes 2025-10-15 16:17:48,510 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4330-1:Compute:contrail-vrouter-agent:0?flat 2025-10-15 16:17:48,544 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4330-1?flat 2025-10-15 16:17:48,580 - DEBUG - VM 19cb08c3-b2b7-440c-9e50-15c695af75f1 is not present in vrouter an-jenkins-deploy-platform-ansible-os-4330-1 uve 2025-10-15 16:17:48,580 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-15 16:17:48,580 - DEBUG - interface for vm 19cb08c3-b2b7-440c-9e50-15c695af75f1 deleted from vrouter uve ... 2025-10-15 16:17:48,580 - DEBUG - Deleted interface not in error interface list ... 2025-10-15 16:17:48,580 - DEBUG - Validated that VM 19cb08c3-b2b7-440c-9e50-15c695af75f1 is removed in Vrouter UVE 2025-10-15 16:17:48,580 - DEBUG - Verifying through opserver in 10.0.0.130 2025-10-15 16:17:48,580 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN3-49110623?flat 2025-10-15 16:17:48,599 - DEBUG - 19cb08c3-b2b7-440c-9e50-15c695af75f1 BM not in default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN3-49110623 uve 2025-10-15 16:17:48,599 - DEBUG - Validated that VM 19cb08c3-b2b7-440c-9e50-15c695af75f1 is not present in the VN UVE 2025-10-15 16:17:48,599 - DEBUG - Verifying the 19cb08c3-b2b7-440c-9e50-15c695af75f1 virtual network link through opserver 10.0.0.130 2025-10-15 16:17:48,599 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-machines 2025-10-15 16:17:48,610 - INFO - 19cb08c3-b2b7-440c-9e50-15c695af75f1 vm uve deleted from opserver 2025-10-15 16:17:48,611 - DEBUG - Verifying the 19cb08c3-b2b7-440c-9e50-15c695af75f1 virtual network link through opserver 10.0.0.131 2025-10-15 16:17:48,611 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-machines 2025-10-15 16:17:48,625 - INFO - 19cb08c3-b2b7-440c-9e50-15c695af75f1 vm uve deleted from opserver 2025-10-15 16:17:48,625 - DEBUG - Verifying the 19cb08c3-b2b7-440c-9e50-15c695af75f1 virtual network link through opserver 10.0.0.137 2025-10-15 16:17:48,625 - DEBUG - Requesting: http://10.0.0.137:8081/analytics/uves/virtual-machines 2025-10-15 16:17:48,640 - INFO - 19cb08c3-b2b7-440c-9e50-15c695af75f1 vm uve deleted from opserver 2025-10-15 16:17:48,640 - INFO - Validated that VM 19cb08c3-b2b7-440c-9e50-15c695af75f1 is removed from Opserver 2025-10-15 16:17:48,640 - INFO - Deleting VM ctest-VM21-35882190 2025-10-15 16:17:48,701 - DEBUG - Verifying in api server 10.0.0.137 2025-10-15 16:17:48,701 - DEBUG - Requesting: http://10.0.0.137:8082/virtual-machine/05e0d2bc-9a97-49aa-b91a-624962954045 2025-10-15 16:17:48,711 - DEBUG - VM ID 05e0d2bc-9a97-49aa-b91a-624962954045 of VM ctest-VM21-35882190 is still found in API Server 2025-10-15 16:17:50,712 - DEBUG - Verifying in api server 10.0.0.137 2025-10-15 16:17:50,712 - DEBUG - Requesting: http://10.0.0.137:8082/virtual-machine/05e0d2bc-9a97-49aa-b91a-624962954045 2025-10-15 16:17:50,720 - DEBUG - Response Code: 404 2025-10-15 16:17:50,720 - DEBUG - Requesting: http://10.0.0.137:8082/virtual-machine/05e0d2bc-9a97-49aa-b91a-624962954045 2025-10-15 16:17:50,727 - DEBUG - Response Code: 404 2025-10-15 16:17:50,727 - DEBUG - Requesting: http://10.0.0.137:8082/virtual-machine/05e0d2bc-9a97-49aa-b91a-624962954045 2025-10-15 16:17:50,733 - DEBUG - Response Code: 404 2025-10-15 16:17:50,734 - INFO - VM ctest-VM21-35882190 is fully removed in API-Server 2025-10-15 16:17:50,897 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found 2025-10-15 16:17:50,947 - DEBUG - Validated that vrouter 10.0.0.141 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN2-28212433 2025-10-15 16:17:50,960 - DEBUG - Validated that vrouter 10.0.0.33 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN2-28212433 2025-10-15 16:17:50,960 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN2-28212433 2025-10-15 16:17:50,960 - INFO - VM ctest-VM21-35882190 is removed in Compute, and routes are removed in all compute nodes 2025-10-15 16:17:51,199 - INFO - Routes for VM ctest-VM21-35882190 is removed in all control-nodes 2025-10-15 16:17:51,200 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4330-2:Compute:contrail-vrouter-agent:0?flat 2025-10-15 16:17:51,240 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4330-2?flat 2025-10-15 16:17:51,274 - DEBUG - VM 05e0d2bc-9a97-49aa-b91a-624962954045 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4330-2 uve 2025-10-15 16:17:51,274 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-15 16:17:51,274 - DEBUG - interface for vm 05e0d2bc-9a97-49aa-b91a-624962954045 deleted from vrouter uve ... 2025-10-15 16:17:51,274 - DEBUG - Deleted interface not in error interface list ... 2025-10-15 16:17:54,274 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4330-2:Compute:contrail-vrouter-agent:0?flat 2025-10-15 16:17:54,309 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4330-2?flat 2025-10-15 16:17:54,341 - DEBUG - VM 05e0d2bc-9a97-49aa-b91a-624962954045 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4330-2 uve 2025-10-15 16:17:54,341 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-15 16:17:54,341 - DEBUG - interface for vm 05e0d2bc-9a97-49aa-b91a-624962954045 deleted from vrouter uve ... 2025-10-15 16:17:54,341 - DEBUG - Deleted interface not in error interface list ... 2025-10-15 16:17:57,342 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4330-2:Compute:contrail-vrouter-agent:0?flat 2025-10-15 16:17:57,389 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4330-2?flat 2025-10-15 16:17:57,422 - DEBUG - VM 05e0d2bc-9a97-49aa-b91a-624962954045 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4330-2 uve 2025-10-15 16:17:57,422 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-15 16:17:57,422 - DEBUG - interface for vm 05e0d2bc-9a97-49aa-b91a-624962954045 deleted from vrouter uve ... 2025-10-15 16:17:57,422 - DEBUG - Deleted interface not in error interface list ... 2025-10-15 16:18:00,423 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4330-2:Compute:contrail-vrouter-agent:0?flat 2025-10-15 16:18:00,459 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4330-2?flat 2025-10-15 16:18:00,493 - DEBUG - VM 05e0d2bc-9a97-49aa-b91a-624962954045 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4330-2 uve 2025-10-15 16:18:00,493 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-15 16:18:00,493 - DEBUG - interface for vm 05e0d2bc-9a97-49aa-b91a-624962954045 deleted from vrouter uve ... 2025-10-15 16:18:00,493 - DEBUG - Deleted interface not in error interface list ... 2025-10-15 16:18:03,494 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4330-2:Compute:contrail-vrouter-agent:0?flat 2025-10-15 16:18:03,530 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4330-2?flat 2025-10-15 16:18:03,561 - DEBUG - VM 05e0d2bc-9a97-49aa-b91a-624962954045 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4330-2 uve 2025-10-15 16:18:03,561 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-15 16:18:03,561 - DEBUG - interface for vm 05e0d2bc-9a97-49aa-b91a-624962954045 deleted from vrouter uve ... 2025-10-15 16:18:03,561 - DEBUG - Deleted interface not in error interface list ... 2025-10-15 16:18:06,562 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4330-2:Compute:contrail-vrouter-agent:0?flat 2025-10-15 16:18:06,601 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4330-2?flat 2025-10-15 16:18:06,633 - DEBUG - VM 05e0d2bc-9a97-49aa-b91a-624962954045 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4330-2 uve 2025-10-15 16:18:06,633 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-15 16:18:06,633 - DEBUG - interface for vm 05e0d2bc-9a97-49aa-b91a-624962954045 deleted from vrouter uve ... 2025-10-15 16:18:06,633 - DEBUG - Deleted interface not in error interface list ... 2025-10-15 16:18:09,634 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4330-2:Compute:contrail-vrouter-agent:0?flat 2025-10-15 16:18:09,671 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4330-2?flat 2025-10-15 16:18:09,702 - DEBUG - VM 05e0d2bc-9a97-49aa-b91a-624962954045 is not present in vrouter an-jenkins-deploy-platform-ansible-os-4330-2 uve 2025-10-15 16:18:09,702 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-15 16:18:09,702 - DEBUG - interface for vm 05e0d2bc-9a97-49aa-b91a-624962954045 deleted from vrouter uve ... 2025-10-15 16:18:09,702 - DEBUG - Deleted interface not in error interface list ... 2025-10-15 16:18:09,702 - DEBUG - Validated that VM 05e0d2bc-9a97-49aa-b91a-624962954045 is removed in Vrouter UVE 2025-10-15 16:18:09,702 - DEBUG - Verifying through opserver in 10.0.0.130 2025-10-15 16:18:09,702 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN2-28212433?flat 2025-10-15 16:18:09,720 - DEBUG - 05e0d2bc-9a97-49aa-b91a-624962954045 BM not in default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN2-28212433 uve 2025-10-15 16:18:09,720 - DEBUG - Validated that VM 05e0d2bc-9a97-49aa-b91a-624962954045 is not present in the VN UVE 2025-10-15 16:18:09,720 - DEBUG - Verifying the 05e0d2bc-9a97-49aa-b91a-624962954045 virtual network link through opserver 10.0.0.130 2025-10-15 16:18:09,720 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-machines 2025-10-15 16:18:09,732 - INFO - 05e0d2bc-9a97-49aa-b91a-624962954045 vm uve deleted from opserver 2025-10-15 16:18:09,732 - DEBUG - Verifying the 05e0d2bc-9a97-49aa-b91a-624962954045 virtual network link through opserver 10.0.0.131 2025-10-15 16:18:09,732 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-machines 2025-10-15 16:18:09,748 - INFO - 05e0d2bc-9a97-49aa-b91a-624962954045 vm uve deleted from opserver 2025-10-15 16:18:09,748 - DEBUG - Verifying the 05e0d2bc-9a97-49aa-b91a-624962954045 virtual network link through opserver 10.0.0.137 2025-10-15 16:18:09,748 - DEBUG - Requesting: http://10.0.0.137:8081/analytics/uves/virtual-machines 2025-10-15 16:18:09,766 - INFO - 05e0d2bc-9a97-49aa-b91a-624962954045 vm uve deleted from opserver 2025-10-15 16:18:09,766 - INFO - Validated that VM 05e0d2bc-9a97-49aa-b91a-624962954045 is removed from Opserver 2025-10-15 16:18:09,766 - INFO - Deleting VM ctest-VM11-66511174 2025-10-15 16:18:09,828 - DEBUG - Verifying in api server 10.0.0.137 2025-10-15 16:18:09,828 - DEBUG - Requesting: http://10.0.0.137:8082/virtual-machine/b90b02e6-70d5-4f83-89a8-500c9eeadaf8 2025-10-15 16:18:09,838 - DEBUG - VM ID b90b02e6-70d5-4f83-89a8-500c9eeadaf8 of VM ctest-VM11-66511174 is still found in API Server 2025-10-15 16:18:11,839 - DEBUG - Verifying in api server 10.0.0.137 2025-10-15 16:18:11,839 - DEBUG - Requesting: http://10.0.0.137:8082/virtual-machine/b90b02e6-70d5-4f83-89a8-500c9eeadaf8 2025-10-15 16:18:11,848 - DEBUG - Response Code: 404 2025-10-15 16:18:11,848 - DEBUG - Requesting: http://10.0.0.137:8082/virtual-machine/b90b02e6-70d5-4f83-89a8-500c9eeadaf8 2025-10-15 16:18:11,856 - DEBUG - Response Code: 404 2025-10-15 16:18:11,856 - DEBUG - Requesting: http://10.0.0.137:8082/virtual-machine/b90b02e6-70d5-4f83-89a8-500c9eeadaf8 2025-10-15 16:18:11,863 - DEBUG - Response Code: 404 2025-10-15 16:18:11,863 - INFO - VM ctest-VM11-66511174 is fully removed in API-Server 2025-10-15 16:18:12,021 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found 2025-10-15 16:18:12,061 - DEBUG - Validated that vrouter 10.0.0.141 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858 2025-10-15 16:18:12,070 - DEBUG - Validated that vrouter 10.0.0.33 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858 2025-10-15 16:18:12,070 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858 2025-10-15 16:18:12,070 - INFO - VM ctest-VM11-66511174 is removed in Compute, and routes are removed in all compute nodes 2025-10-15 16:18:12,284 - INFO - Routes for VM ctest-VM11-66511174 is removed in all control-nodes 2025-10-15 16:18:12,284 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4330-1:Compute:contrail-vrouter-agent:0?flat 2025-10-15 16:18:12,328 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4330-1?flat 2025-10-15 16:18:12,366 - DEBUG - VM b90b02e6-70d5-4f83-89a8-500c9eeadaf8 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4330-1 uve 2025-10-15 16:18:12,366 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-15 16:18:12,367 - DEBUG - interface for vm b90b02e6-70d5-4f83-89a8-500c9eeadaf8 deleted from vrouter uve ... 2025-10-15 16:18:12,367 - DEBUG - Deleted interface not in error interface list ... 2025-10-15 16:18:15,367 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4330-1:Compute:contrail-vrouter-agent:0?flat 2025-10-15 16:18:15,415 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4330-1?flat 2025-10-15 16:18:15,449 - DEBUG - VM b90b02e6-70d5-4f83-89a8-500c9eeadaf8 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4330-1 uve 2025-10-15 16:18:15,449 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-15 16:18:15,449 - DEBUG - interface for vm b90b02e6-70d5-4f83-89a8-500c9eeadaf8 deleted from vrouter uve ... 2025-10-15 16:18:15,449 - DEBUG - Deleted interface not in error interface list ... 2025-10-15 16:18:18,450 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4330-1:Compute:contrail-vrouter-agent:0?flat 2025-10-15 16:18:18,485 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4330-1?flat 2025-10-15 16:18:18,519 - DEBUG - VM b90b02e6-70d5-4f83-89a8-500c9eeadaf8 is not present in vrouter an-jenkins-deploy-platform-ansible-os-4330-1 uve 2025-10-15 16:18:18,519 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-15 16:18:18,519 - DEBUG - interface for vm b90b02e6-70d5-4f83-89a8-500c9eeadaf8 deleted from vrouter uve ... 2025-10-15 16:18:18,519 - DEBUG - Deleted interface not in error interface list ... 2025-10-15 16:18:18,519 - DEBUG - Validated that VM b90b02e6-70d5-4f83-89a8-500c9eeadaf8 is removed in Vrouter UVE 2025-10-15 16:18:18,519 - DEBUG - Verifying through opserver in 10.0.0.130 2025-10-15 16:18:18,519 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858?flat 2025-10-15 16:18:18,541 - DEBUG - b90b02e6-70d5-4f83-89a8-500c9eeadaf8 BM not in default-domain:ctest-TestPolicyAclIpv4v6-76776884:ctest-VN1-33076858 uve 2025-10-15 16:18:18,541 - DEBUG - Validated that VM b90b02e6-70d5-4f83-89a8-500c9eeadaf8 is not present in the VN UVE 2025-10-15 16:18:18,541 - DEBUG - Verifying the b90b02e6-70d5-4f83-89a8-500c9eeadaf8 virtual network link through opserver 10.0.0.130 2025-10-15 16:18:18,541 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-machines 2025-10-15 16:18:18,554 - DEBUG - No links retuned for b90b02e6-70d5-4f83-89a8-500c9eeadaf8 2025-10-15 16:18:18,554 - INFO - b90b02e6-70d5-4f83-89a8-500c9eeadaf8 vm uve deleted from opserver 2025-10-15 16:18:18,554 - DEBUG - Verifying the b90b02e6-70d5-4f83-89a8-500c9eeadaf8 virtual network link through opserver 10.0.0.131 2025-10-15 16:18:18,554 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-machines 2025-10-15 16:18:18,570 - DEBUG - No links retuned for b90b02e6-70d5-4f83-89a8-500c9eeadaf8 2025-10-15 16:18:18,570 - INFO - b90b02e6-70d5-4f83-89a8-500c9eeadaf8 vm uve deleted from opserver 2025-10-15 16:18:18,570 - DEBUG - Verifying the b90b02e6-70d5-4f83-89a8-500c9eeadaf8 virtual network link through opserver 10.0.0.137 2025-10-15 16:18:18,570 - DEBUG - Requesting: http://10.0.0.137:8081/analytics/uves/virtual-machines 2025-10-15 16:18:18,585 - DEBUG - No links retuned for b90b02e6-70d5-4f83-89a8-500c9eeadaf8 2025-10-15 16:18:18,585 - INFO - b90b02e6-70d5-4f83-89a8-500c9eeadaf8 vm uve deleted from opserver 2025-10-15 16:18:18,585 - INFO - Validated that VM b90b02e6-70d5-4f83-89a8-500c9eeadaf8 is removed from Opserver 2025-10-15 16:18:18,585 - DEBUG - Detaching the Policy for VN :ctest-VN2-28212433 2025-10-15 16:18:18,585 - DEBUG - Requesting: http://10.0.0.130:8082/domains 2025-10-15 16:18:18,594 - DEBUG - Requesting: http://10.0.0.130:8082/domain/424b027d-9a59-4bc3-a9ea-fd1131d532cb 2025-10-15 16:18:18,608 - DEBUG - Requesting: http://10.0.0.130:8082/project/e94ece4c-105f-43f3-8c60-fac9d2edefe4 2025-10-15 16:18:18,699 - DEBUG - Requesting: http://10.0.0.130:8082/virtual-network/d060d0d6-af83-43fa-b9d1-7b85b0171f57 2025-10-15 16:18:18,713 - DEBUG - Requesting: http://10.0.0.130:8082/network-policy/a305d206-b458-4a23-b08e-39baf6f9624b 2025-10-15 16:18:18,886 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-76776884', 'ctest-policy21-63718949']] from ctest-VN2-28212433 2025-10-15 16:18:18,886 - DEBUG - Detaching the Policy for VN :ctest-VN1-33076858 2025-10-15 16:18:18,886 - DEBUG - Requesting: http://10.0.0.130:8082/domains 2025-10-15 16:18:18,892 - DEBUG - Requesting: http://10.0.0.130:8082/domain/424b027d-9a59-4bc3-a9ea-fd1131d532cb 2025-10-15 16:18:18,913 - DEBUG - Requesting: http://10.0.0.130:8082/project/e94ece4c-105f-43f3-8c60-fac9d2edefe4 2025-10-15 16:18:19,000 - DEBUG - Requesting: http://10.0.0.130:8082/virtual-network/46a99359-7cb4-4b9a-a150-5b63321d4279 2025-10-15 16:18:19,017 - DEBUG - Requesting: http://10.0.0.130:8082/network-policy/785fc0cf-cc2d-4128-97cd-7d099f620654 2025-10-15 16:18:19,027 - DEBUG - Requesting: http://10.0.0.130:8082/network-policy/da85f881-15f3-40c9-9b9d-7ede1f5bc50c 2025-10-15 16:18:19,202 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-76776884', 'ctest-policy12-37178360'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-76776884', 'ctest-policy13-74755003']] from ctest-VN1-33076858 2025-10-15 16:18:19,249 - INFO - Deleted policy ctest-policy21-63718949 2025-10-15 16:18:19,287 - INFO - Deleted policy ctest-policy12-37178360 2025-10-15 16:18:19,327 - INFO - Deleted policy ctest-policy13-74755003 2025-10-15 16:18:19,327 - INFO - Deleting VN ctest-VN3-49110623 2025-10-15 16:18:19,518 - DEBUG - Response for deleting network () 2025-10-15 16:18:19,518 - DEBUG - Requesting: http://10.0.0.130:8082/routing-instance/e448d3bd-e283-4969-9c3e-3ce92ceabee5 2025-10-15 16:18:19,526 - DEBUG - Response Code: 404 2025-10-15 16:18:19,526 - DEBUG - Requesting: http://10.0.0.130:8082/domains 2025-10-15 16:18:19,531 - DEBUG - Requesting: http://10.0.0.130:8082/domain/424b027d-9a59-4bc3-a9ea-fd1131d532cb 2025-10-15 16:18:19,542 - DEBUG - Requesting: http://10.0.0.130:8082/project/e94ece4c-105f-43f3-8c60-fac9d2edefe4 2025-10-15 16:18:19,641 - INFO - Validated that VN ctest-VN3-49110623 is not found in API Server 2025-10-15 16:18:19,650 - DEBUG - VN ctest-VN3-49110623 is not present in Agent 10.0.0.141 2025-10-15 16:18:19,661 - DEBUG - VN ctest-VN3-49110623 is not present in Agent 10.0.0.33 2025-10-15 16:18:19,661 - INFO - Validated that VN ctest-VN3-49110623 is not in any agent 2025-10-15 16:18:19,691 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN3-49110623 info 2025-10-15 16:18:19,691 - INFO - Deleting VN ctest-VN2-28212433 2025-10-15 16:18:19,896 - DEBUG - Response for deleting network () 2025-10-15 16:18:19,896 - DEBUG - Requesting: http://10.0.0.130:8082/routing-instance/2d5013f8-e55e-41fd-b4be-1b98c350d159 2025-10-15 16:18:19,902 - DEBUG - Response Code: 404 2025-10-15 16:18:19,902 - DEBUG - Requesting: http://10.0.0.130:8082/domains 2025-10-15 16:18:19,908 - DEBUG - Requesting: http://10.0.0.130:8082/domain/424b027d-9a59-4bc3-a9ea-fd1131d532cb 2025-10-15 16:18:19,921 - DEBUG - Requesting: http://10.0.0.130:8082/project/e94ece4c-105f-43f3-8c60-fac9d2edefe4 2025-10-15 16:18:20,005 - INFO - Validated that VN ctest-VN2-28212433 is not found in API Server 2025-10-15 16:18:20,017 - DEBUG - VN ctest-VN2-28212433 is not present in Agent 10.0.0.141 2025-10-15 16:18:20,027 - DEBUG - VN ctest-VN2-28212433 is not present in Agent 10.0.0.33 2025-10-15 16:18:20,027 - INFO - Validated that VN ctest-VN2-28212433 is not in any agent 2025-10-15 16:18:20,060 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN2-28212433 info 2025-10-15 16:18:20,060 - INFO - Deleting VN ctest-VN1-33076858 2025-10-15 16:18:20,229 - DEBUG - Response for deleting network () 2025-10-15 16:18:20,229 - DEBUG - Requesting: http://10.0.0.130:8082/routing-instance/78a4a5da-4ce7-4da1-8267-496bac8f2dfe 2025-10-15 16:18:20,237 - DEBUG - Response Code: 404 2025-10-15 16:18:20,237 - DEBUG - Requesting: http://10.0.0.130:8082/domains 2025-10-15 16:18:20,243 - DEBUG - Requesting: http://10.0.0.130:8082/domain/424b027d-9a59-4bc3-a9ea-fd1131d532cb 2025-10-15 16:18:20,295 - DEBUG - Requesting: http://10.0.0.130:8082/project/e94ece4c-105f-43f3-8c60-fac9d2edefe4 2025-10-15 16:18:20,384 - INFO - Validated that VN ctest-VN1-33076858 is not found in API Server 2025-10-15 16:18:20,393 - DEBUG - VN ctest-VN1-33076858 is not present in Agent 10.0.0.141 2025-10-15 16:18:20,405 - DEBUG - VN ctest-VN1-33076858 is not present in Agent 10.0.0.33 2025-10-15 16:18:20,405 - INFO - Validated that VN ctest-VN1-33076858 is not in any agent 2025-10-15 16:18:20,436 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN1-33076858 info 2025-10-15 16:18:20,533 - INFO - IPAM: ipam3 is not found in API Server 2025-10-15 16:18:20,555 - INFO - IPAM:ipam3 is not found in control node 2025-10-15 16:18:20,697 - INFO - IPAM: ipam2 is not found in API Server 2025-10-15 16:18:20,720 - INFO - IPAM:ipam2 is not found in control node 2025-10-15 16:18:20,865 - INFO - IPAM: ipam1 is not found in API Server 2025-10-15 16:18:20,887 - INFO - IPAM:ipam1 is not found in control node 2025-10-15 16:18:22,190 - DEBUG - No XMPP flaps were noticed during the test

2025-10-15 16:18:22,190 - INFO - END TEST : test_policy_inheritance_src_pol_dst_any : PASSED[0:02:26]

2025-10-15 16:18:22,190 - INFO - -------------------------------------------------------------------------------- 2025-10-15 16:18:23,459 - DEBUG - No XMPP flaps were noticed during the test

2025-10-15 16:18:23,459 - INFO - END TEST : test_policy_inheritance_src_pol_dst_any : PASSED[0:02:28]

2025-10-15 16:18:23,459 - INFO - -------------------------------------------------------------------------------- 2025-10-15 16:18:24,231 - INFO - Deleted project: ctest-TestPolicyAclIpv4v6-76776884, ID : e94ece4c-105f-43f3-8c60-fac9d2edefe4