2025-08-18 02:33:52,889 - INFO - Domain Default found not creating
2025-08-18 02:33:53,008 - INFO - Project ctest-TestPolicyAclIpv4v6-51732354 not found, creating it
2025-08-18 02:33:53,528 - INFO - Created Project:ctest-TestPolicyAclIpv4v6-51732354, ID : 1a7c285f-546c-4e71-b173-9828e1a41fa2
2025-08-18 02:33:54,876 - INFO - ================================================================================
2025-08-18 02:33:54,876 - INFO - STARTING TEST : test_policy_inheritance_src_pol_dst_any
2025-08-18 02:33:56,137 - DEBUG - Nothing to compare xmpp stats {'10.0.0.241': {'10.20.0.129': '0', '10.20.0.25': '0'}, '10.0.0.41': {'10.20.0.129': '0', '10.20.0.25': '0'}} with
2025-08-18 02:33:56,137 - INFO - Initial checks done. Running the testcase now
2025-08-18 02:33:56,137 - INFO -
2025-08-18 02:33:56,137 - INFO - ================================================================================
2025-08-18 02:33:56,137 - INFO - STARTING TEST : test_policy_inheritance_src_pol_dst_any
2025-08-18 02:33:56,138 - INFO - TEST DESCRIPTION : Test cases to test policy inheritance
2025-08-18 02:33:57,416 - DEBUG - Nothing to compare xmpp stats {'10.0.0.241': {'10.20.0.129': '0', '10.20.0.25': '0'}, '10.0.0.41': {'10.20.0.129': '0', '10.20.0.25': '0'}} with
2025-08-18 02:33:57,416 - INFO - Initial checks done. Running the testcase now
2025-08-18 02:33:57,416 - INFO -
2025-08-18 02:33:58,702 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-18 02:33:58,991 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-18 02:33:59,018 - DEBUG - Requesting: http://10.0.0.27:8082/domain/edf943fc-0622-4564-8821-4de9bd04e8a3
2025-08-18 02:33:59,033 - DEBUG - Requesting: http://10.0.0.27:8082/project/1a7c285f-546c-4e71-b173-9828e1a41fa2
2025-08-18 02:33:59,135 - DEBUG - Requesting: http://10.0.0.27:8082/network-ipam/80e8354a-657f-4c0e-b6a9-c8c52f7fedee
2025-08-18 02:33:59,143 - INFO - Verifications in API Server for IPAM: ipam1 passed
2025-08-18 02:33:59,149 - DEBUG - Control-node 10.0.0.27 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-51732354:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-51732354', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9288732825098341390', 'uuid-lslong': '13162272135198010862'}, 'enable': 'true', 'created': '2025-08-18T02:33:57', 'last-modified': '2025-08-18T02:33:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.134414'}
2025-08-18 02:33:59,154 - DEBUG - Control-node 10.0.0.38 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-51732354:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-51732354', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9288732825098341390', 'uuid-lslong': '13162272135198010862'}, 'enable': 'true', 'created': '2025-08-18T02:33:57', 'last-modified': '2025-08-18T02:33:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.140527'}
2025-08-18 02:33:59,160 - DEBUG - Control-node 10.0.0.19 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-51732354:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-51732354', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9288732825098341390', 'uuid-lslong': '13162272135198010862'}, 'enable': 'true', 'created': '2025-08-18T02:33:57', 'last-modified': '2025-08-18T02:33:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.145354'}
2025-08-18 02:33:59,160 - INFO - Verifications in Control node for IPAM: ipam1 passed
2025-08-18 02:33:59,160 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-18 02:33:59,167 - DEBUG - Requesting: http://10.0.0.27:8082/domain/edf943fc-0622-4564-8821-4de9bd04e8a3
2025-08-18 02:33:59,180 - DEBUG - Requesting: http://10.0.0.27:8082/project/1a7c285f-546c-4e71-b173-9828e1a41fa2
2025-08-18 02:33:59,281 - DEBUG - Requesting: http://10.0.0.27:8082/network-ipam/505c5e81-e59b-490a-b419-ee77980a9a9f
2025-08-18 02:33:59,289 - INFO - Verifications in API Server for IPAM: ipam2 passed
2025-08-18 02:33:59,295 - DEBUG - Control-node 10.0.0.27 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-51732354:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-51732354', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5790607132887566602', 'uuid-lslong': '12977665998664145567'}, 'enable': 'true', 'created': '2025-08-18T02:33:58', 'last-modified': '2025-08-18T02:33:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.952902'}
2025-08-18 02:33:59,301 - DEBUG - Control-node 10.0.0.38 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-51732354:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-51732354', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5790607132887566602', 'uuid-lslong': '12977665998664145567'}, 'enable': 'true', 'created': '2025-08-18T02:33:58', 'last-modified': '2025-08-18T02:33:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.958811'}
2025-08-18 02:33:59,306 - DEBUG - Control-node 10.0.0.19 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-51732354:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-51732354', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5790607132887566602', 'uuid-lslong': '12977665998664145567'}, 'enable': 'true', 'created': '2025-08-18T02:33:58', 'last-modified': '2025-08-18T02:33:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.962864'}
2025-08-18 02:33:59,306 - INFO - Verifications in Control node for IPAM: ipam2 passed
2025-08-18 02:33:59,306 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-18 02:33:59,311 - DEBUG - Requesting: http://10.0.0.27:8082/domain/edf943fc-0622-4564-8821-4de9bd04e8a3
2025-08-18 02:33:59,322 - DEBUG - Requesting: http://10.0.0.27:8082/project/1a7c285f-546c-4e71-b173-9828e1a41fa2
2025-08-18 02:33:59,417 - DEBUG - Requesting: http://10.0.0.27:8082/network-ipam/506afb81-765c-4daa-9739-0f1d3c1c964c
2025-08-18 02:33:59,424 - INFO - Verifications in API Server for IPAM: ipam3 passed
2025-08-18 02:33:59,431 - DEBUG - Control-node 10.0.0.27 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-51732354:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-51732354', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5794720404020678058', 'uuid-lslong': '10896757391636600396'}, 'enable': 'true', 'created': '2025-08-18T02:33:58', 'last-modified': '2025-08-18T02:33:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.762201'}
2025-08-18 02:33:59,438 - DEBUG - Control-node 10.0.0.38 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-51732354:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-51732354', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5794720404020678058', 'uuid-lslong': '10896757391636600396'}, 'enable': 'true', 'created': '2025-08-18T02:33:58', 'last-modified': '2025-08-18T02:33:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.768231'}
2025-08-18 02:33:59,444 - DEBUG - Control-node 10.0.0.19 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-51732354:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-51732354', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5794720404020678058', 'uuid-lslong': '10896757391636600396'}, 'enable': 'true', 'created': '2025-08-18T02:33:58', 'last-modified': '2025-08-18T02:33:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.775347'}
2025-08-18 02:33:59,444 - INFO - Verifications in Control node for IPAM: ipam3 passed
2025-08-18 02:33:59,713 - DEBUG - Response for create_network : {'network': {'id': '1acc055b-8608-4201-8c77-26ad49e72296', 'name': 'ctest-VN1-00644007', 'tenant_id': '1a7c285f546c4e71b1739828e1a41fa2', 'project_id': '1a7c285f546c4e71b1739828e1a41fa2', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-51732354', 'ctest-VN1-00644007'], 'port_security_enabled': True, 'description': ''}}
2025-08-18 02:33:59,986 - DEBUG - Response for create_subnet : {'subnet': {'id': '13da6143-ac84-47f0-b401-346a5306393a', 'name': '', 'tenant_id': '1a7c285f546c4e71b1739828e1a41fa2', 'network_id': '1acc055b-8608-4201-8c77-26ad49e72296', 'ip_version': 4, 'cidr': '87.21.94.64/26', 'allocation_pools': [{'start': '87.21.94.66', 'end': '87.21.94.126'}], 'gateway_ip': '87.21.94.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '87.21.94.66', 'tags': [], 'project_id': '1a7c285f546c4e71b1739828e1a41fa2'}}
2025-08-18 02:34:00,227 - DEBUG - Response for create_subnet : {'subnet': {'id': '64b34633-3e68-43df-80e6-c581ce87f9e7', 'name': '', 'tenant_id': '1a7c285f546c4e71b1739828e1a41fa2', 'network_id': '1acc055b-8608-4201-8c77-26ad49e72296', 'ip_version': 6, 'cidr': '20c8:8c4c:c87:347:e2dd:f776::/96', 'allocation_pools': [{'start': '20c8:8c4c:c87:347:e2dd:f776:0:2', 'end': '20c8:8c4c:c87:347:e2dd:f776:ffff:fffe'}], 'gateway_ip': '20c8:8c4c:c87:347:e2dd:f776:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '20c8:8c4c:c87:347:e2dd:f776:0:2', 'tags': [], 'project_id': '1a7c285f546c4e71b1739828e1a41fa2'}}
2025-08-18 02:34:00,253 - INFO - Created VN ctest-VN1-00644007
2025-08-18 02:34:00,263 - DEBUG - VN ctest-VN1-00644007 UUID is 1acc055b-8608-4201-8c77-26ad49e72296
2025-08-18 02:34:00,426 - DEBUG - Fetched VN: default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007(1acc055b-8608-4201-8c77-26ad49e72296) with subnets ['87.21.94.64/26', '20c8:8c4c:c87:347:e2dd:f776::/96']
2025-08-18 02:34:00,697 - DEBUG - Response for create_network : {'network': {'id': 'b371400a-1a8e-472d-8cab-0b397d7ee62a', 'name': 'ctest-VN2-43943987', 'tenant_id': '1a7c285f546c4e71b1739828e1a41fa2', 'project_id': '1a7c285f546c4e71b1739828e1a41fa2', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-51732354', 'ctest-VN2-43943987'], 'port_security_enabled': True, 'description': ''}}
2025-08-18 02:34:00,901 - DEBUG - Response for create_subnet : {'subnet': {'id': '444c26e5-c90a-468b-9381-a7cad2882439', 'name': '', 'tenant_id': '1a7c285f546c4e71b1739828e1a41fa2', 'network_id': 'b371400a-1a8e-472d-8cab-0b397d7ee62a', 'ip_version': 4, 'cidr': '184.58.226.192/26', 'allocation_pools': [{'start': '184.58.226.194', 'end': '184.58.226.254'}], 'gateway_ip': '184.58.226.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '184.58.226.194', 'tags': [], 'project_id': '1a7c285f546c4e71b1739828e1a41fa2'}}
2025-08-18 02:34:01,097 - DEBUG - Response for create_subnet : {'subnet': {'id': '51c580a5-a2cc-4c93-ab8b-815a9bacb1c1', 'name': '', 'tenant_id': '1a7c285f546c4e71b1739828e1a41fa2', 'network_id': 'b371400a-1a8e-472d-8cab-0b397d7ee62a', 'ip_version': 6, 'cidr': '3fbf:47ea:757a:5f85:d54c:60d4::/96', 'allocation_pools': [{'start': '3fbf:47ea:757a:5f85:d54c:60d4:0:2', 'end': '3fbf:47ea:757a:5f85:d54c:60d4:ffff:fffe'}], 'gateway_ip': '3fbf:47ea:757a:5f85:d54c:60d4:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '3fbf:47ea:757a:5f85:d54c:60d4:0:2', 'tags': [], 'project_id': '1a7c285f546c4e71b1739828e1a41fa2'}}
2025-08-18 02:34:01,120 - INFO - Created VN ctest-VN2-43943987
2025-08-18 02:34:01,130 - DEBUG - VN ctest-VN2-43943987 UUID is b371400a-1a8e-472d-8cab-0b397d7ee62a
2025-08-18 02:34:01,502 - DEBUG - Response for create_network : {'network': {'id': '6c857428-9277-4830-b593-ef1fab15c2e2', 'name': 'ctest-VN3-64191227', 'tenant_id': '1a7c285f546c4e71b1739828e1a41fa2', 'project_id': '1a7c285f546c4e71b1739828e1a41fa2', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-51732354', 'ctest-VN3-64191227'], 'port_security_enabled': True, 'description': ''}}
2025-08-18 02:34:01,752 - DEBUG - Response for create_subnet : {'subnet': {'id': '6e0d2e3c-2365-422f-87cf-2392eb27bd3a', 'name': '', 'tenant_id': '1a7c285f546c4e71b1739828e1a41fa2', 'network_id': '6c857428-9277-4830-b593-ef1fab15c2e2', 'ip_version': 4, 'cidr': '2.72.19.64/26', 'allocation_pools': [{'start': '2.72.19.66', 'end': '2.72.19.126'}], 'gateway_ip': '2.72.19.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '2.72.19.66', 'tags': [], 'project_id': '1a7c285f546c4e71b1739828e1a41fa2'}}
2025-08-18 02:34:01,962 - DEBUG - Response for create_subnet : {'subnet': {'id': '523614dc-dc25-4355-8258-7790a9fe0a2b', 'name': '', 'tenant_id': '1a7c285f546c4e71b1739828e1a41fa2', 'network_id': '6c857428-9277-4830-b593-ef1fab15c2e2', 'ip_version': 6, 'cidr': '2b4b:1271:a0eb:24db:cda3:e3b8::/96', 'allocation_pools': [{'start': '2b4b:1271:a0eb:24db:cda3:e3b8:0:2', 'end': '2b4b:1271:a0eb:24db:cda3:e3b8:ffff:fffe'}], 'gateway_ip': '2b4b:1271:a0eb:24db:cda3:e3b8:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '2b4b:1271:a0eb:24db:cda3:e3b8:0:2', 'tags': [], 'project_id': '1a7c285f546c4e71b1739828e1a41fa2'}}
2025-08-18 02:34:02,003 - INFO - Created VN ctest-VN3-64191227
2025-08-18 02:34:02,028 - DEBUG - VN ctest-VN3-64191227 UUID is 6c857428-9277-4830-b593-ef1fab15c2e2
2025-08-18 02:34:02,135 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-18 02:34:02,142 - DEBUG - Requesting: http://10.0.0.27:8082/domain/edf943fc-0622-4564-8821-4de9bd04e8a3
2025-08-18 02:34:02,154 - DEBUG - Requesting: http://10.0.0.27:8082/project/1a7c285f-546c-4e71-b173-9828e1a41fa2
2025-08-18 02:34:02,281 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/1acc055b-8608-4201-8c77-26ad49e72296
2025-08-18 02:34:02,294 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/1acc055b-8608-4201-8c77-26ad49e72296
2025-08-18 02:34:02,306 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/cc167e45-6f8a-4ccf-9d11-5e170e46b039
2025-08-18 02:34:02,313 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/cc167e45-6f8a-4ccf-9d11-5e170e46b039
2025-08-18 02:34:02,322 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/9acb6b44-397a-4f07-a077-6e1a1413612c
2025-08-18 02:34:02,331 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-18 02:34:02,331 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/1acc055b-8608-4201-8c77-26ad49e72296
2025-08-18 02:34:02,348 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/cc167e45-6f8a-4ccf-9d11-5e170e46b039
2025-08-18 02:34:02,377 - INFO - Verified VN network id 13 for VN 1acc055b-8608-4201-8c77-26ad49e72296
2025-08-18 02:34:02,377 - INFO - Verifications in API Server for VN ctest-VN1-00644007 passed
2025-08-18 02:34:02,377 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/1acc055b-8608-4201-8c77-26ad49e72296
2025-08-18 02:34:02,390 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/cc167e45-6f8a-4ccf-9d11-5e170e46b039
2025-08-18 02:34:02,398 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/cc167e45-6f8a-4ccf-9d11-5e170e46b039
2025-08-18 02:34:02,411 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/9acb6b44-397a-4f07-a077-6e1a1413612c
2025-08-18 02:34:02,428 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-51732354', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1930924230883951105', 'uuid-lslong': '10121601213249561238'}, 'enable': 'true', 'created': '2025-08-18T02:33:59', 'last-modified': '2025-08-18T02:34:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.253023'}
2025-08-18 02:34:02,437 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-18 02:34:02,444 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-51732354', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1930924230883951105', 'uuid-lslong': '10121601213249561238'}, 'enable': 'true', 'created': '2025-08-18T02:33:59', 'last-modified': '2025-08-18T02:34:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.272594'}
2025-08-18 02:34:02,451 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-18 02:34:02,456 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-51732354', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1930924230883951105', 'uuid-lslong': '10121601213249561238'}, 'enable': 'true', 'created': '2025-08-18T02:33:59', 'last-modified': '2025-08-18T02:34:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.284268'}
2025-08-18 02:34:02,463 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-18 02:34:02,463 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN1-00644007 passed
2025-08-18 02:34:02,463 - DEBUG - ====Verifying policy data for ctest-VN1-00644007 in API_Server ======
2025-08-18 02:34:02,464 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-18 02:34:02,470 - DEBUG - Requesting: http://10.0.0.27:8082/domain/edf943fc-0622-4564-8821-4de9bd04e8a3
2025-08-18 02:34:02,482 - DEBUG - Requesting: http://10.0.0.27:8082/project/1a7c285f-546c-4e71-b173-9828e1a41fa2
2025-08-18 02:34:02,602 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/1acc055b-8608-4201-8c77-26ad49e72296
2025-08-18 02:34:02,615 - DEBUG - =>VN ctest-VN1-00644007 has no policy to be verified
2025-08-18 02:34:02,615 - DEBUG - Verifying the vn in opserver
2025-08-18 02:34:02,615 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007 virtual network link through opserver 10.0.0.27
2025-08-18 02:34:02,615 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-08-18 02:34:02,910 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-08-18 02:34:02,975 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007?flat'}
2025-08-18 02:34:02,975 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007 virtual network link through opserver 10.0.0.38
2025-08-18 02:34:02,976 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-18 02:34:03,269 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-18 02:34:03,322 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007?flat'}
2025-08-18 02:34:03,322 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007 virtual network link through opserver 10.0.0.19
2025-08-18 02:34:03,322 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-08-18 02:34:03,610 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-08-18 02:34:03,661 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007?flat'}
2025-08-18 02:34:03,661 - INFO - Validated that VN default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007 is found in opserver
2025-08-18 02:34:03,682 - DEBUG - Do not have enough data to verify VN in agent
2025-08-18 02:34:03,692 - DEBUG - VRF ids for VN ctest-VN1-00644007: {}
2025-08-18 02:34:03,692 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-18 02:34:03,698 - DEBUG - Requesting: http://10.0.0.27:8082/domain/edf943fc-0622-4564-8821-4de9bd04e8a3
2025-08-18 02:34:03,713 - DEBUG - Requesting: http://10.0.0.27:8082/project/1a7c285f-546c-4e71-b173-9828e1a41fa2
2025-08-18 02:34:03,827 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/b371400a-1a8e-472d-8cab-0b397d7ee62a
2025-08-18 02:34:03,841 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/b371400a-1a8e-472d-8cab-0b397d7ee62a
2025-08-18 02:34:03,853 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/90f8d7ad-0db5-4a70-abe0-3033a587746d
2025-08-18 02:34:03,862 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/90f8d7ad-0db5-4a70-abe0-3033a587746d
2025-08-18 02:34:03,870 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/70e3c550-9ec9-433a-a8a6-087182c105b2
2025-08-18 02:34:03,877 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-18 02:34:03,877 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/b371400a-1a8e-472d-8cab-0b397d7ee62a
2025-08-18 02:34:03,888 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/90f8d7ad-0db5-4a70-abe0-3033a587746d
2025-08-18 02:34:03,904 - INFO - Verified VN network id 14 for VN b371400a-1a8e-472d-8cab-0b397d7ee62a
2025-08-18 02:34:03,904 - INFO - Verifications in API Server for VN ctest-VN2-43943987 passed
2025-08-18 02:34:03,905 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/b371400a-1a8e-472d-8cab-0b397d7ee62a
2025-08-18 02:34:03,916 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/90f8d7ad-0db5-4a70-abe0-3033a587746d
2025-08-18 02:34:03,924 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/90f8d7ad-0db5-4a70-abe0-3033a587746d
2025-08-18 02:34:03,931 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/70e3c550-9ec9-433a-a8a6-087182c105b2
2025-08-18 02:34:03,942 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN2-43943987', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-51732354', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12930186417296787245', 'uuid-lslong': '10136207727873943082'}, 'enable': 'true', 'created': '2025-08-18T02:34:00', 'last-modified': '2025-08-18T02:34:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.889780'}
2025-08-18 02:34:03,950 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-18 02:34:03,961 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN2-43943987', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-51732354', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12930186417296787245', 'uuid-lslong': '10136207727873943082'}, 'enable': 'true', 'created': '2025-08-18T02:34:00', 'last-modified': '2025-08-18T02:34:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.911818'}
2025-08-18 02:34:03,967 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-18 02:34:03,973 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN2-43943987', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-51732354', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12930186417296787245', 'uuid-lslong': '10136207727873943082'}, 'enable': 'true', 'created': '2025-08-18T02:34:00', 'last-modified': '2025-08-18T02:34:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.927031'}
2025-08-18 02:34:03,979 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-18 02:34:03,980 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN2-43943987 passed
2025-08-18 02:34:03,980 - DEBUG - ====Verifying policy data for ctest-VN2-43943987 in API_Server ======
2025-08-18 02:34:03,980 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-18 02:34:03,986 - DEBUG - Requesting: http://10.0.0.27:8082/domain/edf943fc-0622-4564-8821-4de9bd04e8a3
2025-08-18 02:34:03,997 - DEBUG - Requesting: http://10.0.0.27:8082/project/1a7c285f-546c-4e71-b173-9828e1a41fa2
2025-08-18 02:34:04,099 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/b371400a-1a8e-472d-8cab-0b397d7ee62a
2025-08-18 02:34:04,109 - DEBUG - =>VN ctest-VN2-43943987 has no policy to be verified
2025-08-18 02:34:04,109 - DEBUG - Verifying the vn in opserver
2025-08-18 02:34:04,109 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN2-43943987 virtual network link through opserver 10.0.0.27
2025-08-18 02:34:04,109 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-08-18 02:34:04,183 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN2-43943987', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN2-43943987?flat'}
2025-08-18 02:34:04,183 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN2-43943987 virtual network link through opserver 10.0.0.38
2025-08-18 02:34:04,183 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-18 02:34:04,247 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN2-43943987', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN2-43943987?flat'}
2025-08-18 02:34:04,247 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN2-43943987 virtual network link through opserver 10.0.0.19
2025-08-18 02:34:04,247 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-08-18 02:34:04,300 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN2-43943987', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN2-43943987?flat'}
2025-08-18 02:34:04,300 - INFO - Validated that VN default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN2-43943987 is found in opserver
2025-08-18 02:34:04,325 - DEBUG - Do not have enough data to verify VN in agent
2025-08-18 02:34:04,335 - DEBUG - VRF ids for VN ctest-VN2-43943987: {}
2025-08-18 02:34:04,335 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-18 02:34:04,341 - DEBUG - Requesting: http://10.0.0.27:8082/domain/edf943fc-0622-4564-8821-4de9bd04e8a3
2025-08-18 02:34:04,353 - DEBUG - Requesting: http://10.0.0.27:8082/project/1a7c285f-546c-4e71-b173-9828e1a41fa2
2025-08-18 02:34:04,439 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/6c857428-9277-4830-b593-ef1fab15c2e2
2025-08-18 02:34:04,458 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/6c857428-9277-4830-b593-ef1fab15c2e2
2025-08-18 02:34:04,473 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/bda5edf8-960c-4214-84d3-d84b8684275c
2025-08-18 02:34:04,482 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/bda5edf8-960c-4214-84d3-d84b8684275c
2025-08-18 02:34:04,491 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/23ef7000-f16f-4a11-b98f-1dd8f9f2500c
2025-08-18 02:34:04,498 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-18 02:34:04,498 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/6c857428-9277-4830-b593-ef1fab15c2e2
2025-08-18 02:34:04,508 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/bda5edf8-960c-4214-84d3-d84b8684275c
2025-08-18 02:34:04,523 - INFO - Verified VN network id 15 for VN 6c857428-9277-4830-b593-ef1fab15c2e2
2025-08-18 02:34:04,523 - INFO - Verifications in API Server for VN ctest-VN3-64191227 passed
2025-08-18 02:34:04,523 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/6c857428-9277-4830-b593-ef1fab15c2e2
2025-08-18 02:34:04,534 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/bda5edf8-960c-4214-84d3-d84b8684275c
2025-08-18 02:34:04,541 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/bda5edf8-960c-4214-84d3-d84b8684275c
2025-08-18 02:34:04,548 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/23ef7000-f16f-4a11-b98f-1dd8f9f2500c
2025-08-18 02:34:04,562 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN3-64191227', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-51732354', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7819784045603538992', 'uuid-lslong': '13084064261734777570'}, 'enable': 'true', 'created': '2025-08-18T02:34:01', 'last-modified': '2025-08-18T02:34:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.668710'}
2025-08-18 02:34:04,572 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-18 02:34:04,577 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN3-64191227', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-51732354', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7819784045603538992', 'uuid-lslong': '13084064261734777570'}, 'enable': 'true', 'created': '2025-08-18T02:34:01', 'last-modified': '2025-08-18T02:34:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.683546'}
2025-08-18 02:34:04,585 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-18 02:34:04,591 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN3-64191227', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-51732354', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7819784045603538992', 'uuid-lslong': '13084064261734777570'}, 'enable': 'true', 'created': '2025-08-18T02:34:01', 'last-modified': '2025-08-18T02:34:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.695489'}
2025-08-18 02:34:04,598 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-18 02:34:04,598 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN3-64191227 passed
2025-08-18 02:34:04,599 - DEBUG - ====Verifying policy data for ctest-VN3-64191227 in API_Server ======
2025-08-18 02:34:04,599 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-18 02:34:04,605 - DEBUG - Requesting: http://10.0.0.27:8082/domain/edf943fc-0622-4564-8821-4de9bd04e8a3
2025-08-18 02:34:04,616 - DEBUG - Requesting: http://10.0.0.27:8082/project/1a7c285f-546c-4e71-b173-9828e1a41fa2
2025-08-18 02:34:04,714 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/6c857428-9277-4830-b593-ef1fab15c2e2
2025-08-18 02:34:04,725 - DEBUG - =>VN ctest-VN3-64191227 has no policy to be verified
2025-08-18 02:34:04,725 - DEBUG - Verifying the vn in opserver
2025-08-18 02:34:04,725 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN3-64191227 virtual network link through opserver 10.0.0.27
2025-08-18 02:34:04,725 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-08-18 02:34:04,782 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN3-64191227', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN3-64191227?flat'}
2025-08-18 02:34:04,782 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN3-64191227 virtual network link through opserver 10.0.0.38
2025-08-18 02:34:04,782 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-18 02:34:04,829 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN3-64191227', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN3-64191227?flat'}
2025-08-18 02:34:04,829 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN3-64191227 virtual network link through opserver 10.0.0.19
2025-08-18 02:34:04,829 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-08-18 02:34:04,877 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN3-64191227', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN3-64191227?flat'}
2025-08-18 02:34:04,877 - INFO - Validated that VN default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN3-64191227 is found in opserver
2025-08-18 02:34:04,898 - DEBUG - Do not have enough data to verify VN in agent
2025-08-18 02:34:04,908 - DEBUG - VRF ids for VN ctest-VN3-64191227: {}
2025-08-18 02:34:04,914 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007, 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-51732354:ctest-VN3-64191227, 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-08-18 02:34:05,170 - DEBUG - No source network defined
2025-08-18 02:34:05,170 - DEBUG - No source network defined
2025-08-18 02:34:05,170 - 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-51732354:ctest-policy13-54620987, 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-51732354:ctest-policy13-54620987, 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-08-18 02:34:05,430 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN2-43943987, 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-51732354:ctest-VN1-00644007, 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-51732354:ctest-VN2-43943987, 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-51732354:ctest-VN1-00644007, 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-08-18 02:34:05,655 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-18 02:34:05,661 - DEBUG - Requesting: http://10.0.0.27:8082/domain/edf943fc-0622-4564-8821-4de9bd04e8a3
2025-08-18 02:34:05,676 - DEBUG - Requesting: http://10.0.0.27:8082/project/1a7c285f-546c-4e71-b173-9828e1a41fa2
2025-08-18 02:34:05,786 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/1acc055b-8608-4201-8c77-26ad49e72296
2025-08-18 02:34:05,801 - DEBUG - Setup step: Associating the policy to VN'
2025-08-18 02:34:05,941 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '1acc055b-8608-4201-8c77-26ad49e72296', 'name': 'ctest-VN1-00644007', 'tenant_id': '1a7c285f546c4e71b1739828e1a41fa2', 'project_id': '1a7c285f546c4e71b1739828e1a41fa2', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['13da6143-ac84-47f0-b401-346a5306393a', '64b34633-3e68-43df-80e6-c581ce87f9e7'], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-51732354', 'ctest-VN1-00644007'], 'policys': [['default-domain', 'ctest-TestPolicyAclIpv4v6-51732354', 'ctest-policy12-97865531'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-51732354', 'ctest-policy13-54620987']], 'subnet_ipam': [{'subnet_cidr': '87.21.94.64/26', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-51732354', 'ipam1']}, {'subnet_cidr': '20c8:8c4c:c87:347:e2dd:f776::/96', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-51732354', 'ipam1']}], 'port_security_enabled': True, 'description': ''}}
2025-08-18 02:34:06,081 - DEBUG - Associated Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-51732354', 'ctest-policy12-97865531'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-51732354', 'ctest-policy13-54620987']] to ctest-VN1-00644007
2025-08-18 02:34:06,081 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-18 02:34:06,093 - DEBUG - Requesting: http://10.0.0.27:8082/domain/edf943fc-0622-4564-8821-4de9bd04e8a3
2025-08-18 02:34:06,106 - DEBUG - Requesting: http://10.0.0.27:8082/project/1a7c285f-546c-4e71-b173-9828e1a41fa2
2025-08-18 02:34:06,210 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/b371400a-1a8e-472d-8cab-0b397d7ee62a
2025-08-18 02:34:06,220 - DEBUG - Setup step: Associating the policy to VN'
2025-08-18 02:34:06,367 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': 'b371400a-1a8e-472d-8cab-0b397d7ee62a', 'name': 'ctest-VN2-43943987', 'tenant_id': '1a7c285f546c4e71b1739828e1a41fa2', 'project_id': '1a7c285f546c4e71b1739828e1a41fa2', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['444c26e5-c90a-468b-9381-a7cad2882439', '51c580a5-a2cc-4c93-ab8b-815a9bacb1c1'], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-51732354', 'ctest-VN2-43943987'], 'policys': [['default-domain', 'ctest-TestPolicyAclIpv4v6-51732354', 'ctest-policy21-88149205']], 'subnet_ipam': [{'subnet_cidr': '184.58.226.192/26', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-51732354', 'ipam2']}, {'subnet_cidr': '3fbf:47ea:757a:5f85:d54c:60d4::/96', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-51732354', 'ipam2']}], 'port_security_enabled': True, 'description': ''}}
2025-08-18 02:34:06,469 - DEBUG - Associated Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-51732354', 'ctest-policy21-88149205']] to ctest-VN2-43943987
2025-08-18 02:34:06,573 - DEBUG - Services list from nova: [, , , ]
2025-08-18 02:34:07,895 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3870-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3870-1)
2025-08-18 02:34:09,407 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3870-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3870-2)
2025-08-18 02:34:11,171 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3870-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3870-1)
2025-08-18 02:34:11,257 - DEBUG - VM is in ACTIVE state now
2025-08-18 02:34:11,257 - INFO - VM name : ctest-VM11-65139879
2025-08-18 02:34:11,328 - DEBUG - VM ctest-VM11-65139879 ID is a9462524-c599-4390-851d-42a089dbdd88
2025-08-18 02:34:11,421 - DEBUG - VM is in ACTIVE state now
2025-08-18 02:34:11,457 - DEBUG - VM ctest-VM11-65139879 launched on Node an-jenkins-deploy-platform-ansible-os-3870-1
2025-08-18 02:34:11,551 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/a9462524-c599-4390-851d-42a089dbdd88
2025-08-18 02:34:11,985 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/a9462524-c599-4390-851d-42a089dbdd88
2025-08-18 02:34:12,048 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/a9462524-c599-4390-851d-42a089dbdd88
2025-08-18 02:34:12,058 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine-interface/26c78483-1a67-4358-87bc-7885967b2f9d
2025-08-18 02:34:12,073 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/a9462524-c599-4390-851d-42a089dbdd88
2025-08-18 02:34:12,083 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine-interface/26c78483-1a67-4358-87bc-7885967b2f9d
2025-08-18 02:34:12,093 - DEBUG - Requesting: http://10.0.0.19:8082/instance-ip/999d6acd-2d84-447d-9dbd-611c387501e6
2025-08-18 02:34:12,104 - DEBUG - Requesting: http://10.0.0.19:8082/instance-ip/cdb34150-c94a-4a03-8278-ff2885a45101
2025-08-18 02:34:12,114 - DEBUG - Verifying in api server 10.0.0.19
2025-08-18 02:34:12,114 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/a9462524-c599-4390-851d-42a089dbdd88
2025-08-18 02:34:12,120 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine-interface/26c78483-1a67-4358-87bc-7885967b2f9d
2025-08-18 02:34:12,130 - DEBUG - Requesting: http://10.0.0.19:8082/instance-ip/999d6acd-2d84-447d-9dbd-611c387501e6
2025-08-18 02:34:12,136 - DEBUG - Requesting: http://10.0.0.19:8082/instance-ip/cdb34150-c94a-4a03-8278-ff2885a45101
2025-08-18 02:34:12,143 - INFO - VM ctest-VM11-65139879 verfication in all API Servers passed
2025-08-18 02:34:12,165 - DEBUG - VM ctest-VM11-65139879 Tap interface: {'index': '3', 'name': 'tap26c78483-1a', 'uuid': '26c78483-1a67-4358-87bc-7885967b2f9d', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007:ctest-VN1-00644007', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Active', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Enable', 'dns_service': 'Enable', 'type': 'vport', 'label': '25', 'l2_label': '29', 'vxlan_id': '13', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007', 'vm_uuid': 'a9462524-c599-4390-851d-42a089dbdd88', 'vm_name': 'ctest-VM11-65139879', 'ip_addr': '87.21.94.67', 'mac_addr': '02:26:c7:84:83:1a', '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-51732354:26c78483-1a67-4358-87bc-7885967b2f9d', 'sg_uuid_list': ['b6248d65-ea8a-4ed7-8505-d10d099e3731'], 'static_route_list': None, 'vm_project_uuid': '1a7c285f-546c-4e71-b173-9828e1a41fa2', 'admin_state': 'Enabled', 'flow_key_idx': '31', 'allowed_address_pair_list': None, 'ip6_addr': '20c8:8c4c:c87:347:e2dd:f776: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-08-18 02:34:12,165 - DEBUG - Agent 10.0.0.241 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007:ctest-VN1-00644007
2025-08-18 02:34:12,173 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007:ctest-VN1-00644007', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007', 'table_label': '-1', 'vxlan_id': '13', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.18:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]}
2025-08-18 02:34:12,279 - DEBUG - VM ctest-VM11-65139879 : Tap interface tap26c78483-1a is set to Active
2025-08-18 02:34:12,279 - DEBUG - Tap interface tap26c78483-1a detail : {'index': '3', 'name': 'tap26c78483-1a', 'uuid': '26c78483-1a67-4358-87bc-7885967b2f9d', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007:ctest-VN1-00644007', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Active', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Enable', 'dns_service': 'Enable', 'type': 'vport', 'label': '25', 'l2_label': '29', 'vxlan_id': '13', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007', 'vm_uuid': 'a9462524-c599-4390-851d-42a089dbdd88', 'vm_name': 'ctest-VM11-65139879', 'ip_addr': '87.21.94.67', 'mac_addr': '02:26:c7:84:83:1a', '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-51732354:26c78483-1a67-4358-87bc-7885967b2f9d', 'sg_uuid_list': ['b6248d65-ea8a-4ed7-8505-d10d099e3731'], 'static_route_list': None, 'vm_project_uuid': '1a7c285f-546c-4e71-b173-9828e1a41fa2', 'admin_state': 'Enabled', 'flow_key_idx': '31', 'allowed_address_pair_list': None, 'ip6_addr': '20c8:8c4c:c87:347:e2dd:f776: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-08-18 02:34:12,386 - DEBUG - Starting Layer 2 verification in Agent
2025-08-18 02:34:12,410 - DEBUG - Layer 2 path is seen for VM MAC 02:26:c7:84:83:1a in agent 10.0.0.241
2025-08-18 02:34:12,410 - DEBUG - Active layer 2 route in agent is present for VMI tap26c78483-1a
2025-08-18 02:34:12,410 - DEBUG - L2 label(29) matches bw route table and interface table
2025-08-18 02:34:12,423 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007 is found in Agent of node 10.0.0.241
2025-08-18 02:34:12,590 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007 is consistent in agent 10.0.0.241
2025-08-18 02:34:12,590 - DEBUG - Route for VM IP ['20c8:8c4c:c87:347:e2dd:f776:0:3', '87.21.94.67'] is consistent in agent 10.0.0.241
2025-08-18 02:34:12,590 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007 verification for VM ctest-VM11-65139879 in Agent 10.0.0.241 passed
2025-08-18 02:34:12,654 - DEBUG - Starting all layer 2 verification in agent 10.0.0.241
2025-08-18 02:34:12,664 - DEBUG - Route for VM MAC 02:26:c7:84:83:1a is consistent in agent 10.0.0.241
2025-08-18 02:34:15,863 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1020ms')
2025-08-18 02:34:15,863 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-65139879 failed!
2025-08-18 02:34:15,876 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007 is 87.21.94.65 and allocation pool is NOT set
2025-08-18 02:34:15,876 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007 is 20c8:8c4c:c87:347:e2dd:f776:0:1 and allocation pool is NOT set
2025-08-18 02:34:19,955 - 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-08-18 02:34:19,955 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-65139879 failed!
2025-08-18 02:34:19,970 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007 is 87.21.94.65 and allocation pool is NOT set
2025-08-18 02:34:19,970 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007 is 20c8:8c4c:c87:347:e2dd:f776:0:1 and allocation pool is NOT set
2025-08-18 02:34:24,043 - 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-08-18 02:34:24,043 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-65139879 failed!
2025-08-18 02:34:24,056 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007 is 87.21.94.65 and allocation pool is NOT set
2025-08-18 02:34:24,056 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007 is 20c8:8c4c:c87:347:e2dd:f776:0:1 and allocation pool is NOT set
2025-08-18 02:34:28,146 - 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-08-18 02:34:28,146 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-65139879 failed!
2025-08-18 02:34:28,163 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007 is 87.21.94.65 and allocation pool is NOT set
2025-08-18 02:34:28,164 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007 is 20c8:8c4c:c87:347:e2dd:f776:0:1 and allocation pool is NOT set
2025-08-18 02:34:32,235 - 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-08-18 02:34:32,235 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-65139879 failed!
2025-08-18 02:34:32,250 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007 is 87.21.94.65 and allocation pool is NOT set
2025-08-18 02:34:32,250 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007 is 20c8:8c4c:c87:347:e2dd:f776:0:1 and allocation pool is NOT set
2025-08-18 02:34:36,336 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1021ms')
2025-08-18 02:34:36,337 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-65139879 failed!
2025-08-18 02:34:36,395 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007 is 87.21.94.65 and allocation pool is NOT set
2025-08-18 02:34:36,395 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007 is 20c8:8c4c:c87:347:e2dd:f776:0:1 and allocation pool is NOT set
2025-08-18 02:34:40,466 - 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-08-18 02:34:40,466 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-65139879 failed!
2025-08-18 02:34:40,480 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007 is 87.21.94.65 and allocation pool is NOT set
2025-08-18 02:34:40,480 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007 is 20c8:8c4c:c87:347:e2dd:f776:0:1 and allocation pool is NOT set
2025-08-18 02:34:44,561 - 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-08-18 02:34:44,561 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-65139879 failed!
2025-08-18 02:34:44,576 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007 is 87.21.94.65 and allocation pool is NOT set
2025-08-18 02:34:44,577 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007 is 20c8:8c4c:c87:347:e2dd:f776:0:1 and allocation pool is NOT set
2025-08-18 02:34:48,652 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1019ms')
2025-08-18 02:34:48,652 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-65139879 failed!
2025-08-18 02:34:48,669 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007 is 87.21.94.65 and allocation pool is NOT set
2025-08-18 02:34:48,669 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007 is 20c8:8c4c:c87:347:e2dd:f776:0:1 and allocation pool is NOT set
2025-08-18 02:34:50,732 - 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=9.12 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.661 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1000ms\r\nrtt min/avg/max/mdev = 0.661/4.888/9.116/4.227 ms')
2025-08-18 02:34:50,733 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-65139879 passed
2025-08-18 02:34:50,733 - INFO - VM ctest-VM11-65139879 verifications in Compute nodes passed
2025-08-18 02:34:51,034 - DEBUG - Validated VM route 87.21.94.67 in vrouter of 10.0.0.241
2025-08-18 02:34:51,040 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0
2025-08-18 02:34:51,040 - INFO - Validated routes of VM ctest-VM11-65139879 in all vrouters
2025-08-18 02:34:51,449 - DEBUG - Starting all layer2 verification in 10.0.0.27 Control Node
2025-08-18 02:34:51,558 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.27, not supported
2025-08-18 02:34:51,680 - DEBUG - Layer2 route found for VM MAC 02:26:c7:84:83:1a in Control-node 10.0.0.27
2025-08-18 02:34:51,680 - DEBUG - L2 Label for VM ctest-VM11-65139879 same between Control-node 10.0.0.27 and Agent, Expected: 29, Seen: 29
2025-08-18 02:34:51,680 - DEBUG - Starting all layer2 verification in 10.0.0.19 Control Node
2025-08-18 02:34:51,795 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.19, not supported
2025-08-18 02:34:51,913 - DEBUG - Layer2 route found for VM MAC 02:26:c7:84:83:1a in Control-node 10.0.0.19
2025-08-18 02:34:51,913 - DEBUG - L2 Label for VM ctest-VM11-65139879 same between Control-node 10.0.0.19 and Agent, Expected: 29, Seen: 29
2025-08-18 02:34:51,913 - INFO - Verification in Control-nodes for VM ctest-VM11-65139879 passed
2025-08-18 02:34:51,914 - DEBUG - Verifying the vm in opserver
2025-08-18 02:34:51,914 - DEBUG - Verifying in collector 10.0.0.19 ...
2025-08-18 02:34:51,914 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machine/a9462524-c599-4390-851d-42a089dbdd88?flat
2025-08-18 02:34:51,930 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-51732354:26c78483-1a67-4358-87bc-7885967b2f9d?flat
2025-08-18 02:34:51,957 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-51732354:26c78483-1a67-4358-87bc-7885967b2f9d?flat
2025-08-18 02:34:51,982 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-51732354:26c78483-1a67-4358-87bc-7885967b2f9d?flat
2025-08-18 02:34:52,006 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machine/a9462524-c599-4390-851d-42a089dbdd88?flat
2025-08-18 02:34:52,023 - DEBUG - Verifying vm in vn uve
2025-08-18 02:34:52,023 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-51732354:26c78483-1a67-4358-87bc-7885967b2f9d?flat
2025-08-18 02:34:52,042 - DEBUG - VM uve shows interface as {'__T': 1755484457712428, 'fixed_ip6_list': ['20c8:8c4c:c87:347:e2dd:f776:0:3'], 'label': 25, 'active': True, 'tx_vlan': 65535, 'ip6_active': True, 'vn_uuid': '1acc055b-8608-4201-8c77-26ad49e72296', 'vm_uuid': 'a9462524-c599-4390-851d-42a089dbdd88', 'ip6_address': '20c8:8c4c:c87:347:e2dd:f776:0:3', 'ip4_active': True, 'port_mirror_enabled': False, 'rx_vlan': 65535, 'gateway': '87.21.94.65', 'uuid': '26c78483-1a67-4358-87bc-7885967b2f9d', 'vm_name': 'ctest-VM11-65139879', 'admin_state': True, 'ip_address': '87.21.94.67', 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007', 'l2_active': True, 'mac_address': '02:26:c7:84:83:1a', 'fixed_ip4_list': ['87.21.94.67'], 'is_health_check_active': True, 'vhostuser_mode': 0}
2025-08-18 02:34:52,043 - DEBUG - VM uve shows ip address as ['87.21.94.67', '20c8:8c4c:c87:347:e2dd:f776:0:3']
2025-08-18 02:34:52,043 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007
2025-08-18 02:34:52,043 - INFO - Verifying through opserver in 10.0.0.27
2025-08-18 02:34:52,043 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007?flat
2025-08-18 02:34:52,066 - DEBUG - expected vm list a9462524-c599-4390-851d-42a089dbdd88
2025-08-18 02:34:52,066 - DEBUG - Extracted vm list ['a9462524-c599-4390-851d-42a089dbdd88']
2025-08-18 02:34:52,066 - INFO - VM a9462524-c599-4390-851d-42a089dbdd88 is present in default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007
2025-08-18 02:34:52,066 - DEBUG - Verifying vm in vrouter uve
2025-08-18 02:34:52,066 - DEBUG - Getting info from collector 10.0.0.19..
2025-08-18 02:34:52,066 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machine/a9462524-c599-4390-851d-42a089dbdd88?flat
2025-08-18 02:34:52,085 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-3870-1']
2025-08-18 02:34:52,085 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3870-1:Compute:contrail-vrouter-agent:0?flat
2025-08-18 02:34:52,128 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3870-1?flat
2025-08-18 02:34:52,164 - DEBUG - VM a9462524-c599-4390-851d-42a089dbdd88 is present in vrouter an-jenkins-deploy-platform-ansible-os-3870-1 uve
2025-08-18 02:34:52,164 - DEBUG - Validated that VM ctest-VM11-65139879 is in Vrouter an-jenkins-deploy-platform-ansible-os-3870-1 UVE
2025-08-18 02:34:52,164 - DEBUG - Verifying vm tap interface/vn in vrouter uve
2025-08-18 02:34:52,172 - DEBUG - Expected tap interface of VM uuid a9462524-c599-4390-851d-42a089dbdd88 is default-domain:ctest-TestPolicyAclIpv4v6-51732354:26c78483-1a67-4358-87bc-7885967b2f9d
2025-08-18 02:34:52,172 - DEBUG - Expected VN of VM uuid a9462524-c599-4390-851d-42a089dbdd88 is default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007
2025-08-18 02:34:52,172 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3870-1:Compute:contrail-vrouter-agent:0?flat
2025-08-18 02:34:52,215 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3870-1?flat
2025-08-18 02:34:52,250 - DEBUG - VM a9462524-c599-4390-851d-42a089dbdd88 is present in vrouter an-jenkins-deploy-platform-ansible-os-3870-1 uve
2025-08-18 02:34:52,250 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-51732354:26c78483-1a67-4358-87bc-7885967b2f9d of vm a9462524-c599-4390-851d-42a089dbdd88 is present in vrouter an-jenkins-deploy-platform-ansible-os-3870-1 uve
2025-08-18 02:34:52,250 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007 of vm a9462524-c599-4390-851d-42a089dbdd88 is present in vrouter an-jenkins-deploy-platform-ansible-os-3870-1 uve
2025-08-18 02:34:52,250 - DEBUG - Verifying the a9462524-c599-4390-851d-42a089dbdd88 virtual network link through opserver 10.0.0.27
2025-08-18 02:34:52,250 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines
2025-08-18 02:34:52,262 - DEBUG - VM link and name as {'name': 'a9462524-c599-4390-851d-42a089dbdd88', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-machine/a9462524-c599-4390-851d-42a089dbdd88?flat'}
2025-08-18 02:34:52,262 - DEBUG - Verifying the a9462524-c599-4390-851d-42a089dbdd88 virtual network link through opserver 10.0.0.38
2025-08-18 02:34:52,262 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines
2025-08-18 02:34:52,273 - DEBUG - VM link and name as {'name': 'a9462524-c599-4390-851d-42a089dbdd88', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-machine/a9462524-c599-4390-851d-42a089dbdd88?flat'}
2025-08-18 02:34:52,274 - DEBUG - Verifying the a9462524-c599-4390-851d-42a089dbdd88 virtual network link through opserver 10.0.0.19
2025-08-18 02:34:52,274 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machines
2025-08-18 02:34:52,284 - DEBUG - VM link and name as {'name': 'a9462524-c599-4390-851d-42a089dbdd88', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-machine/a9462524-c599-4390-851d-42a089dbdd88?flat'}
2025-08-18 02:34:52,284 - INFO - VM ctest-VM11-65139879 validations in Opserver passed
2025-08-18 02:34:52,357 - DEBUG - VM is in ACTIVE state now
2025-08-18 02:34:52,358 - INFO - VM name : ctest-VM21-25126703
2025-08-18 02:34:52,424 - DEBUG - VM ctest-VM21-25126703 ID is bea8b2e6-1010-42ae-9f48-d320818dc55c
2025-08-18 02:34:52,497 - DEBUG - VM is in ACTIVE state now
2025-08-18 02:34:52,498 - DEBUG - VM ctest-VM21-25126703 launched on Node an-jenkins-deploy-platform-ansible-os-3870-2
2025-08-18 02:34:52,568 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/bea8b2e6-1010-42ae-9f48-d320818dc55c
2025-08-18 02:34:52,577 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/bea8b2e6-1010-42ae-9f48-d320818dc55c
2025-08-18 02:34:52,584 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine-interface/e7a1ba7c-aab2-4186-860d-4090fd6e3ea8
2025-08-18 02:34:52,593 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/bea8b2e6-1010-42ae-9f48-d320818dc55c
2025-08-18 02:34:52,599 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine-interface/e7a1ba7c-aab2-4186-860d-4090fd6e3ea8
2025-08-18 02:34:52,608 - DEBUG - Requesting: http://10.0.0.19:8082/instance-ip/27713907-2e15-4402-b1fa-55d6fa968bf1
2025-08-18 02:34:52,617 - DEBUG - Requesting: http://10.0.0.19:8082/instance-ip/74b3e9c3-578f-4c93-be6f-3f34d9178a86
2025-08-18 02:34:52,626 - DEBUG - Verifying in api server 10.0.0.19
2025-08-18 02:34:52,626 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/bea8b2e6-1010-42ae-9f48-d320818dc55c
2025-08-18 02:34:52,632 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine-interface/e7a1ba7c-aab2-4186-860d-4090fd6e3ea8
2025-08-18 02:34:52,641 - DEBUG - Requesting: http://10.0.0.19:8082/instance-ip/27713907-2e15-4402-b1fa-55d6fa968bf1
2025-08-18 02:34:52,647 - DEBUG - Requesting: http://10.0.0.19:8082/instance-ip/74b3e9c3-578f-4c93-be6f-3f34d9178a86
2025-08-18 02:34:52,654 - INFO - VM ctest-VM21-25126703 verfication in all API Servers passed
2025-08-18 02:34:52,672 - DEBUG - VM ctest-VM21-25126703 Tap interface: {'index': '3', 'name': 'tape7a1ba7c-aa', 'uuid': 'e7a1ba7c-aab2-4186-860d-4090fd6e3ea8', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN2-43943987:ctest-VN2-43943987', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Active', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Enable', 'dns_service': 'Enable', 'type': 'vport', 'label': '25', 'l2_label': '29', 'vxlan_id': '14', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN2-43943987', 'vm_uuid': 'bea8b2e6-1010-42ae-9f48-d320818dc55c', 'vm_name': 'ctest-VM21-25126703', 'ip_addr': '184.58.226.195', 'mac_addr': '02:e7:a1:ba:7c:aa', '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-51732354:e7a1ba7c-aab2-4186-860d-4090fd6e3ea8', 'sg_uuid_list': ['b6248d65-ea8a-4ed7-8505-d10d099e3731'], 'static_route_list': None, 'vm_project_uuid': '1a7c285f-546c-4e71-b173-9828e1a41fa2', 'admin_state': 'Enabled', 'flow_key_idx': '31', 'allowed_address_pair_list': None, 'ip6_addr': '3fbf:47ea:757a:5f85:d54c:60d4: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-08-18 02:34:52,672 - DEBUG - Agent 10.0.0.41 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN2-43943987:ctest-VN2-43943987
2025-08-18 02:34:52,677 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN2-43943987:ctest-VN2-43943987', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN2-43943987', 'table_label': '-1', 'vxlan_id': '14', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.192:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]}
2025-08-18 02:34:52,766 - DEBUG - VM ctest-VM21-25126703 : Tap interface tape7a1ba7c-aa is set to Active
2025-08-18 02:34:52,766 - DEBUG - Tap interface tape7a1ba7c-aa detail : {'index': '3', 'name': 'tape7a1ba7c-aa', 'uuid': 'e7a1ba7c-aab2-4186-860d-4090fd6e3ea8', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN2-43943987:ctest-VN2-43943987', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Active', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Enable', 'dns_service': 'Enable', 'type': 'vport', 'label': '25', 'l2_label': '29', 'vxlan_id': '14', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN2-43943987', 'vm_uuid': 'bea8b2e6-1010-42ae-9f48-d320818dc55c', 'vm_name': 'ctest-VM21-25126703', 'ip_addr': '184.58.226.195', 'mac_addr': '02:e7:a1:ba:7c:aa', '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-51732354:e7a1ba7c-aab2-4186-860d-4090fd6e3ea8', 'sg_uuid_list': ['b6248d65-ea8a-4ed7-8505-d10d099e3731'], 'static_route_list': None, 'vm_project_uuid': '1a7c285f-546c-4e71-b173-9828e1a41fa2', 'admin_state': 'Enabled', 'flow_key_idx': '31', 'allowed_address_pair_list': None, 'ip6_addr': '3fbf:47ea:757a:5f85:d54c:60d4: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-08-18 02:34:52,878 - DEBUG - Starting Layer 2 verification in Agent
2025-08-18 02:34:52,889 - DEBUG - Layer 2 path is seen for VM MAC 02:e7:a1:ba:7c:aa in agent 10.0.0.41
2025-08-18 02:34:52,889 - DEBUG - Active layer 2 route in agent is present for VMI tape7a1ba7c-aa
2025-08-18 02:34:52,889 - DEBUG - L2 label(29) matches bw route table and interface table
2025-08-18 02:34:52,903 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN2-43943987 is found in Agent of node 10.0.0.41
2025-08-18 02:34:53,043 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN2-43943987 is consistent in agent 10.0.0.41
2025-08-18 02:34:53,043 - DEBUG - Route for VM IP ['184.58.226.195', '3fbf:47ea:757a:5f85:d54c:60d4:0:3'] is consistent in agent 10.0.0.41
2025-08-18 02:34:53,043 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN2-43943987 verification for VM ctest-VM21-25126703 in Agent 10.0.0.41 passed
2025-08-18 02:34:53,150 - DEBUG - Starting all layer 2 verification in agent 10.0.0.41
2025-08-18 02:34:53,162 - DEBUG - Route for VM MAC 02:e7:a1:ba:7c:aa is consistent in agent 10.0.0.41
2025-08-18 02:34:54,334 - 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.55 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.957 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.957/2.251/3.545/1.294 ms')
2025-08-18 02:34:54,334 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-25126703 passed
2025-08-18 02:34:54,334 - INFO - VM ctest-VM21-25126703 verifications in Compute nodes passed
2025-08-18 02:34:54,587 - DEBUG - Validated VM route 184.58.226.195 in vrouter of 10.0.0.41
2025-08-18 02:34:54,591 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0
2025-08-18 02:34:54,591 - INFO - Validated routes of VM ctest-VM21-25126703 in all vrouters
2025-08-18 02:34:55,004 - DEBUG - Starting all layer2 verification in 10.0.0.27 Control Node
2025-08-18 02:34:55,125 - DEBUG - Layer2 route found for VM MAC 02:e7:a1:ba:7c:aa in Control-node 10.0.0.27
2025-08-18 02:34:55,126 - DEBUG - L2 Label for VM ctest-VM21-25126703 same between Control-node 10.0.0.27 and Agent, Expected: 29, Seen: 29
2025-08-18 02:34:55,234 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.27, not supported
2025-08-18 02:34:55,234 - DEBUG - Starting all layer2 verification in 10.0.0.19 Control Node
2025-08-18 02:34:55,356 - DEBUG - Layer2 route found for VM MAC 02:e7:a1:ba:7c:aa in Control-node 10.0.0.19
2025-08-18 02:34:55,356 - DEBUG - L2 Label for VM ctest-VM21-25126703 same between Control-node 10.0.0.19 and Agent, Expected: 29, Seen: 29
2025-08-18 02:34:55,474 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.19, not supported
2025-08-18 02:34:55,474 - INFO - Verification in Control-nodes for VM ctest-VM21-25126703 passed
2025-08-18 02:34:55,474 - DEBUG - Verifying the vm in opserver
2025-08-18 02:34:55,474 - DEBUG - Verifying in collector 10.0.0.19 ...
2025-08-18 02:34:55,474 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machine/bea8b2e6-1010-42ae-9f48-d320818dc55c?flat
2025-08-18 02:34:55,489 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-51732354:e7a1ba7c-aab2-4186-860d-4090fd6e3ea8?flat
2025-08-18 02:34:55,509 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-51732354:e7a1ba7c-aab2-4186-860d-4090fd6e3ea8?flat
2025-08-18 02:34:55,529 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-51732354:e7a1ba7c-aab2-4186-860d-4090fd6e3ea8?flat
2025-08-18 02:34:55,549 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machine/bea8b2e6-1010-42ae-9f48-d320818dc55c?flat
2025-08-18 02:34:55,565 - DEBUG - Verifying vm in vn uve
2025-08-18 02:34:55,565 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-51732354:e7a1ba7c-aab2-4186-860d-4090fd6e3ea8?flat
2025-08-18 02:34:55,587 - DEBUG - VM uve shows interface as {'__T': 1755484457777498, 'fixed_ip6_list': ['3fbf:47ea:757a:5f85:d54c:60d4:0:3'], 'label': 25, 'active': True, 'tx_vlan': 65535, 'ip6_active': True, 'vn_uuid': 'b371400a-1a8e-472d-8cab-0b397d7ee62a', 'vm_uuid': 'bea8b2e6-1010-42ae-9f48-d320818dc55c', 'ip6_address': '3fbf:47ea:757a:5f85:d54c:60d4:0:3', 'ip4_active': True, 'port_mirror_enabled': False, 'rx_vlan': 65535, 'gateway': '184.58.226.193', 'uuid': 'e7a1ba7c-aab2-4186-860d-4090fd6e3ea8', 'vm_name': 'ctest-VM21-25126703', 'admin_state': True, 'ip_address': '184.58.226.195', 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN2-43943987', 'l2_active': True, 'mac_address': '02:e7:a1:ba:7c:aa', 'fixed_ip4_list': ['184.58.226.195'], 'is_health_check_active': True, 'vhostuser_mode': 0}
2025-08-18 02:34:55,587 - DEBUG - VM uve shows ip address as ['184.58.226.195', '3fbf:47ea:757a:5f85:d54c:60d4:0:3']
2025-08-18 02:34:55,587 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN2-43943987
2025-08-18 02:34:55,587 - INFO - Verifying through opserver in 10.0.0.27
2025-08-18 02:34:55,587 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN2-43943987?flat
2025-08-18 02:34:55,606 - DEBUG - expected vm list bea8b2e6-1010-42ae-9f48-d320818dc55c
2025-08-18 02:34:55,606 - DEBUG - Extracted vm list ['bea8b2e6-1010-42ae-9f48-d320818dc55c']
2025-08-18 02:34:55,606 - INFO - VM bea8b2e6-1010-42ae-9f48-d320818dc55c is present in default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN2-43943987
2025-08-18 02:34:55,606 - DEBUG - Verifying vm in vrouter uve
2025-08-18 02:34:55,606 - DEBUG - Getting info from collector 10.0.0.19..
2025-08-18 02:34:55,606 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machine/bea8b2e6-1010-42ae-9f48-d320818dc55c?flat
2025-08-18 02:34:55,621 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-3870-2']
2025-08-18 02:34:55,621 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3870-2:Compute:contrail-vrouter-agent:0?flat
2025-08-18 02:34:55,667 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3870-2?flat
2025-08-18 02:34:55,702 - DEBUG - VM bea8b2e6-1010-42ae-9f48-d320818dc55c is present in vrouter an-jenkins-deploy-platform-ansible-os-3870-2 uve
2025-08-18 02:34:55,702 - DEBUG - Validated that VM ctest-VM21-25126703 is in Vrouter an-jenkins-deploy-platform-ansible-os-3870-2 UVE
2025-08-18 02:34:55,702 - DEBUG - Verifying vm tap interface/vn in vrouter uve
2025-08-18 02:34:55,718 - DEBUG - Expected tap interface of VM uuid bea8b2e6-1010-42ae-9f48-d320818dc55c is default-domain:ctest-TestPolicyAclIpv4v6-51732354:e7a1ba7c-aab2-4186-860d-4090fd6e3ea8
2025-08-18 02:34:55,718 - DEBUG - Expected VN of VM uuid bea8b2e6-1010-42ae-9f48-d320818dc55c is default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN2-43943987
2025-08-18 02:34:55,719 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3870-2:Compute:contrail-vrouter-agent:0?flat
2025-08-18 02:34:55,763 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3870-2?flat
2025-08-18 02:34:55,798 - DEBUG - VM bea8b2e6-1010-42ae-9f48-d320818dc55c is present in vrouter an-jenkins-deploy-platform-ansible-os-3870-2 uve
2025-08-18 02:34:55,799 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-51732354:e7a1ba7c-aab2-4186-860d-4090fd6e3ea8 of vm bea8b2e6-1010-42ae-9f48-d320818dc55c is present in vrouter an-jenkins-deploy-platform-ansible-os-3870-2 uve
2025-08-18 02:34:55,799 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN2-43943987 of vm bea8b2e6-1010-42ae-9f48-d320818dc55c is present in vrouter an-jenkins-deploy-platform-ansible-os-3870-2 uve
2025-08-18 02:34:55,799 - DEBUG - Verifying the bea8b2e6-1010-42ae-9f48-d320818dc55c virtual network link through opserver 10.0.0.27
2025-08-18 02:34:55,799 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines
2025-08-18 02:34:55,812 - DEBUG - VM link and name as {'name': 'bea8b2e6-1010-42ae-9f48-d320818dc55c', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-machine/bea8b2e6-1010-42ae-9f48-d320818dc55c?flat'}
2025-08-18 02:34:55,812 - DEBUG - Verifying the bea8b2e6-1010-42ae-9f48-d320818dc55c virtual network link through opserver 10.0.0.38
2025-08-18 02:34:55,812 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines
2025-08-18 02:34:55,823 - DEBUG - VM link and name as {'name': 'bea8b2e6-1010-42ae-9f48-d320818dc55c', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-machine/bea8b2e6-1010-42ae-9f48-d320818dc55c?flat'}
2025-08-18 02:34:55,823 - DEBUG - Verifying the bea8b2e6-1010-42ae-9f48-d320818dc55c virtual network link through opserver 10.0.0.19
2025-08-18 02:34:55,823 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machines
2025-08-18 02:34:55,834 - DEBUG - VM link and name as {'name': 'bea8b2e6-1010-42ae-9f48-d320818dc55c', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-machine/bea8b2e6-1010-42ae-9f48-d320818dc55c?flat'}
2025-08-18 02:34:55,834 - INFO - VM ctest-VM21-25126703 validations in Opserver passed
2025-08-18 02:34:55,900 - DEBUG - VM is in ACTIVE state now
2025-08-18 02:34:55,900 - INFO - VM name : ctest-VM31-38041155
2025-08-18 02:34:55,974 - DEBUG - VM ctest-VM31-38041155 ID is d72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242
2025-08-18 02:34:56,049 - DEBUG - VM is in ACTIVE state now
2025-08-18 02:34:56,049 - DEBUG - VM ctest-VM31-38041155 launched on Node an-jenkins-deploy-platform-ansible-os-3870-1
2025-08-18 02:34:56,125 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/d72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242
2025-08-18 02:34:56,133 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/d72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242
2025-08-18 02:34:56,140 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine-interface/ffc5c9b4-4966-4b7e-8aa4-33c50f2aa08e
2025-08-18 02:34:56,149 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/d72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242
2025-08-18 02:34:56,156 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine-interface/ffc5c9b4-4966-4b7e-8aa4-33c50f2aa08e
2025-08-18 02:34:56,165 - DEBUG - Requesting: http://10.0.0.19:8082/instance-ip/562e79bc-581f-47fd-9c63-2ac67c2c7594
2025-08-18 02:34:56,172 - DEBUG - Requesting: http://10.0.0.19:8082/instance-ip/cd3f2cc0-6a6e-4859-a421-80ce1c11cf9a
2025-08-18 02:34:56,180 - DEBUG - Verifying in api server 10.0.0.19
2025-08-18 02:34:56,181 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/d72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242
2025-08-18 02:34:56,187 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine-interface/ffc5c9b4-4966-4b7e-8aa4-33c50f2aa08e
2025-08-18 02:34:56,196 - DEBUG - Requesting: http://10.0.0.19:8082/instance-ip/562e79bc-581f-47fd-9c63-2ac67c2c7594
2025-08-18 02:34:56,202 - DEBUG - Requesting: http://10.0.0.19:8082/instance-ip/cd3f2cc0-6a6e-4859-a421-80ce1c11cf9a
2025-08-18 02:34:56,209 - INFO - VM ctest-VM31-38041155 verfication in all API Servers passed
2025-08-18 02:34:56,226 - DEBUG - VM ctest-VM31-38041155 Tap interface: {'index': '4', 'name': 'tapffc5c9b4-49', 'uuid': 'ffc5c9b4-4966-4b7e-8aa4-33c50f2aa08e', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN3-64191227:ctest-VN3-64191227', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Active', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Enable', 'dns_service': 'Enable', 'type': 'vport', 'label': '32', 'l2_label': '36', 'vxlan_id': '15', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN3-64191227', 'vm_uuid': 'd72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242', 'vm_name': 'ctest-VM31-38041155', 'ip_addr': '2.72.19.67', 'mac_addr': '02:ff:c5:c9:b4:49', '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-51732354:ffc5c9b4-4966-4b7e-8aa4-33c50f2aa08e', 'sg_uuid_list': ['b6248d65-ea8a-4ed7-8505-d10d099e3731'], 'static_route_list': None, 'vm_project_uuid': '1a7c285f-546c-4e71-b173-9828e1a41fa2', 'admin_state': 'Enabled', 'flow_key_idx': '39', 'allowed_address_pair_list': None, 'ip6_addr': '2b4b:1271:a0eb:24db:cda3:e3b8: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-08-18 02:34:56,226 - DEBUG - Agent 10.0.0.241 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN3-64191227:ctest-VN3-64191227
2025-08-18 02:34:56,232 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN3-64191227:ctest-VN3-64191227', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN3-64191227', 'table_label': '-1', 'vxlan_id': '15', 'evpnindex': '3', 'brindex': '3', 'mplsindex': '0', 'RD': '10.20.0.18:3', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]}
2025-08-18 02:34:56,316 - DEBUG - VM ctest-VM31-38041155 : Tap interface tapffc5c9b4-49 is set to Active
2025-08-18 02:34:56,316 - DEBUG - Tap interface tapffc5c9b4-49 detail : {'index': '4', 'name': 'tapffc5c9b4-49', 'uuid': 'ffc5c9b4-4966-4b7e-8aa4-33c50f2aa08e', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN3-64191227:ctest-VN3-64191227', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Active', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Enable', 'dns_service': 'Enable', 'type': 'vport', 'label': '32', 'l2_label': '36', 'vxlan_id': '15', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN3-64191227', 'vm_uuid': 'd72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242', 'vm_name': 'ctest-VM31-38041155', 'ip_addr': '2.72.19.67', 'mac_addr': '02:ff:c5:c9:b4:49', '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-51732354:ffc5c9b4-4966-4b7e-8aa4-33c50f2aa08e', 'sg_uuid_list': ['b6248d65-ea8a-4ed7-8505-d10d099e3731'], 'static_route_list': None, 'vm_project_uuid': '1a7c285f-546c-4e71-b173-9828e1a41fa2', 'admin_state': 'Enabled', 'flow_key_idx': '39', 'allowed_address_pair_list': None, 'ip6_addr': '2b4b:1271:a0eb:24db:cda3:e3b8: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-08-18 02:34:56,423 - DEBUG - Starting Layer 2 verification in Agent
2025-08-18 02:34:56,434 - DEBUG - Layer 2 path is seen for VM MAC 02:ff:c5:c9:b4:49 in agent 10.0.0.241
2025-08-18 02:34:56,434 - DEBUG - Active layer 2 route in agent is present for VMI tapffc5c9b4-49
2025-08-18 02:34:56,434 - DEBUG - L2 label(36) matches bw route table and interface table
2025-08-18 02:34:56,439 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN3-64191227 is found in Agent of node 10.0.0.241
2025-08-18 02:34:56,576 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN3-64191227 is consistent in agent 10.0.0.241
2025-08-18 02:34:56,576 - DEBUG - Route for VM IP ['2b4b:1271:a0eb:24db:cda3:e3b8:0:3', '2.72.19.67'] is consistent in agent 10.0.0.241
2025-08-18 02:34:56,576 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN3-64191227 verification for VM ctest-VM31-38041155 in Agent 10.0.0.241 passed
2025-08-18 02:34:56,682 - DEBUG - Starting all layer 2 verification in agent 10.0.0.241
2025-08-18 02:34:56,693 - DEBUG - Route for VM MAC 02:ff:c5:c9:b4:49 is consistent in agent 10.0.0.241
2025-08-18 02:34:57,867 - 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=8.14 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=2.98 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.978/5.559/8.140/2.581 ms')
2025-08-18 02:34:57,867 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-38041155 passed
2025-08-18 02:34:57,867 - INFO - VM ctest-VM31-38041155 verifications in Compute nodes passed
2025-08-18 02:34:58,092 - DEBUG - Validated VM route 2.72.19.67 in vrouter of 10.0.0.241
2025-08-18 02:34:58,096 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0
2025-08-18 02:34:58,096 - INFO - Validated routes of VM ctest-VM31-38041155 in all vrouters
2025-08-18 02:34:58,609 - DEBUG - Starting all layer2 verification in 10.0.0.27 Control Node
2025-08-18 02:34:58,722 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.27, not supported
2025-08-18 02:34:58,843 - DEBUG - Layer2 route found for VM MAC 02:ff:c5:c9:b4:49 in Control-node 10.0.0.27
2025-08-18 02:34:58,843 - DEBUG - L2 Label for VM ctest-VM31-38041155 same between Control-node 10.0.0.27 and Agent, Expected: 36, Seen: 36
2025-08-18 02:34:58,843 - DEBUG - Starting all layer2 verification in 10.0.0.19 Control Node
2025-08-18 02:34:58,950 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.19, not supported
2025-08-18 02:34:59,075 - DEBUG - Layer2 route found for VM MAC 02:ff:c5:c9:b4:49 in Control-node 10.0.0.19
2025-08-18 02:34:59,076 - DEBUG - L2 Label for VM ctest-VM31-38041155 same between Control-node 10.0.0.19 and Agent, Expected: 36, Seen: 36
2025-08-18 02:34:59,076 - INFO - Verification in Control-nodes for VM ctest-VM31-38041155 passed
2025-08-18 02:34:59,076 - DEBUG - Verifying the vm in opserver
2025-08-18 02:34:59,076 - DEBUG - Verifying in collector 10.0.0.19 ...
2025-08-18 02:34:59,076 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machine/d72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242?flat
2025-08-18 02:34:59,091 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-51732354:ffc5c9b4-4966-4b7e-8aa4-33c50f2aa08e?flat
2025-08-18 02:34:59,110 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-51732354:ffc5c9b4-4966-4b7e-8aa4-33c50f2aa08e?flat
2025-08-18 02:34:59,130 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-51732354:ffc5c9b4-4966-4b7e-8aa4-33c50f2aa08e?flat
2025-08-18 02:34:59,149 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machine/d72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242?flat
2025-08-18 02:34:59,164 - DEBUG - Verifying vm in vn uve
2025-08-18 02:34:59,164 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-51732354:ffc5c9b4-4966-4b7e-8aa4-33c50f2aa08e?flat
2025-08-18 02:34:59,187 - DEBUG - VM uve shows interface as {'__T': 1755484457712453, 'fixed_ip6_list': ['2b4b:1271:a0eb:24db:cda3:e3b8:0:3'], 'label': 32, 'active': True, 'tx_vlan': 65535, 'ip6_active': True, 'vn_uuid': '6c857428-9277-4830-b593-ef1fab15c2e2', 'vm_uuid': 'd72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242', 'ip6_address': '2b4b:1271:a0eb:24db:cda3:e3b8:0:3', 'ip4_active': True, 'port_mirror_enabled': False, 'rx_vlan': 65535, 'gateway': '2.72.19.65', 'uuid': 'ffc5c9b4-4966-4b7e-8aa4-33c50f2aa08e', 'vm_name': 'ctest-VM31-38041155', 'admin_state': True, 'ip_address': '2.72.19.67', 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN3-64191227', 'l2_active': True, 'mac_address': '02:ff:c5:c9:b4:49', 'fixed_ip4_list': ['2.72.19.67'], 'is_health_check_active': True, 'vhostuser_mode': 0}
2025-08-18 02:34:59,188 - DEBUG - VM uve shows ip address as ['2.72.19.67', '2b4b:1271:a0eb:24db:cda3:e3b8:0:3']
2025-08-18 02:34:59,188 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN3-64191227
2025-08-18 02:34:59,188 - INFO - Verifying through opserver in 10.0.0.27
2025-08-18 02:34:59,188 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN3-64191227?flat
2025-08-18 02:34:59,210 - DEBUG - expected vm list d72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242
2025-08-18 02:34:59,210 - DEBUG - Extracted vm list ['d72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242']
2025-08-18 02:34:59,210 - INFO - VM d72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242 is present in default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN3-64191227
2025-08-18 02:34:59,210 - DEBUG - Verifying vm in vrouter uve
2025-08-18 02:34:59,210 - DEBUG - Getting info from collector 10.0.0.19..
2025-08-18 02:34:59,211 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machine/d72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242?flat
2025-08-18 02:34:59,229 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-3870-1']
2025-08-18 02:34:59,229 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3870-1:Compute:contrail-vrouter-agent:0?flat
2025-08-18 02:34:59,271 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3870-1?flat
2025-08-18 02:34:59,306 - DEBUG - VM d72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242 is present in vrouter an-jenkins-deploy-platform-ansible-os-3870-1 uve
2025-08-18 02:34:59,306 - DEBUG - Validated that VM ctest-VM31-38041155 is in Vrouter an-jenkins-deploy-platform-ansible-os-3870-1 UVE
2025-08-18 02:34:59,306 - DEBUG - Verifying vm tap interface/vn in vrouter uve
2025-08-18 02:34:59,314 - DEBUG - Expected tap interface of VM uuid d72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242 is default-domain:ctest-TestPolicyAclIpv4v6-51732354:ffc5c9b4-4966-4b7e-8aa4-33c50f2aa08e
2025-08-18 02:34:59,314 - DEBUG - Expected VN of VM uuid d72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242 is default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN3-64191227
2025-08-18 02:34:59,314 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3870-1:Compute:contrail-vrouter-agent:0?flat
2025-08-18 02:34:59,355 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3870-1?flat
2025-08-18 02:34:59,391 - DEBUG - VM d72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242 is present in vrouter an-jenkins-deploy-platform-ansible-os-3870-1 uve
2025-08-18 02:34:59,391 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-51732354:ffc5c9b4-4966-4b7e-8aa4-33c50f2aa08e of vm d72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242 is present in vrouter an-jenkins-deploy-platform-ansible-os-3870-1 uve
2025-08-18 02:34:59,391 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN3-64191227 of vm d72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242 is present in vrouter an-jenkins-deploy-platform-ansible-os-3870-1 uve
2025-08-18 02:34:59,391 - DEBUG - Verifying the d72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242 virtual network link through opserver 10.0.0.27
2025-08-18 02:34:59,391 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines
2025-08-18 02:34:59,402 - DEBUG - VM link and name as {'name': 'd72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-machine/d72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242?flat'}
2025-08-18 02:34:59,402 - DEBUG - Verifying the d72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242 virtual network link through opserver 10.0.0.38
2025-08-18 02:34:59,403 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines
2025-08-18 02:34:59,413 - DEBUG - VM link and name as {'name': 'd72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-machine/d72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242?flat'}
2025-08-18 02:34:59,413 - DEBUG - Verifying the d72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242 virtual network link through opserver 10.0.0.19
2025-08-18 02:34:59,413 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machines
2025-08-18 02:34:59,424 - DEBUG - VM link and name as {'name': 'd72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-machine/d72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242?flat'}
2025-08-18 02:34:59,424 - INFO - VM ctest-VM31-38041155 validations in Opserver passed
2025-08-18 02:34:59,424 - INFO - Waiting for VM ctest-VM11-65139879 to be up..
2025-08-18 02:34:59,424 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-18 02:34:59,424 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/a9462524-c599-4390-851d-42a089dbdd88
2025-08-18 02:34:59,432 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine-interface/26c78483-1a67-4358-87bc-7885967b2f9d
2025-08-18 02:35:00,595 - 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.90 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=5.23 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.895/4.062/5.230/1.167 ms')
2025-08-18 02:35:00,595 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-65139879 passed
2025-08-18 02:35:00,665 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-18 02:35:00,665 - DEBUG - Waiting to SSH to VM ctest-VM11-65139879, IP 87.21.94.67, Port 22
2025-08-18 02:35:00,731 - 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-08-18 02:35:00,799 - DEBUG - VM ctest-VM11-65139879 is NOT ready for SSH connections, VM status: ACTIVE
2025-08-18 02:35:05,801 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-18 02:35:05,801 - DEBUG - Waiting to SSH to VM ctest-VM11-65139879, IP 87.21.94.67, Port 22
2025-08-18 02:35:05,871 - 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-08-18 02:35:05,942 - DEBUG - VM ctest-VM11-65139879 is NOT ready for SSH connections, VM status: ACTIVE
2025-08-18 02:35:10,942 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-18 02:35:10,943 - DEBUG - Waiting to SSH to VM ctest-VM11-65139879, IP 87.21.94.67, Port 22
2025-08-18 02:35:11,011 - 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-08-18 02:35:11,080 - DEBUG - VM ctest-VM11-65139879 is NOT ready for SSH connections, VM status: ACTIVE
2025-08-18 02:35:16,081 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-18 02:35:16,081 - DEBUG - Waiting to SSH to VM ctest-VM11-65139879, IP 87.21.94.67, Port 22
2025-08-18 02:35:16,252 - DEBUG - VM ctest-VM11-65139879 is ready for SSH connections
2025-08-18 02:35:16,252 - INFO - Waiting for VM ctest-VM21-25126703 to be up..
2025-08-18 02:35:16,252 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-18 02:35:16,252 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/bea8b2e6-1010-42ae-9f48-d320818dc55c
2025-08-18 02:35:16,260 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine-interface/e7a1ba7c-aab2-4186-860d-4090fd6e3ea8
2025-08-18 02:35:17,418 - 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.58 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.445 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.445/1.514/2.584/1.069 ms')
2025-08-18 02:35:17,419 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-25126703 passed
2025-08-18 02:35:17,486 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-18 02:35:17,486 - DEBUG - Waiting to SSH to VM ctest-VM21-25126703, IP 184.58.226.195, Port 22
2025-08-18 02:35:17,641 - DEBUG - VM ctest-VM21-25126703 is ready for SSH connections
2025-08-18 02:35:17,641 - INFO - Waiting for VM ctest-VM31-38041155 to be up..
2025-08-18 02:35:17,641 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-18 02:35:17,641 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/d72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242
2025-08-18 02:35:17,649 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine-interface/ffc5c9b4-4966-4b7e-8aa4-33c50f2aa08e
2025-08-18 02:35:18,808 - 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=2.06 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.476 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1000ms\r\nrtt min/avg/max/mdev = 0.476/1.269/2.063/0.793 ms')
2025-08-18 02:35:18,808 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-38041155 passed
2025-08-18 02:35:18,873 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-18 02:35:18,873 - DEBUG - Waiting to SSH to VM ctest-VM31-38041155, IP 2.72.19.67, Port 22
2025-08-18 02:35:19,028 - DEBUG - VM ctest-VM31-38041155 is ready for SSH connections
2025-08-18 02:35:19,029 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 184.58.226.195, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.241, gateway password: c0ntrail123
2025-08-18 02:35:19,029 - DEBUG - ping -s 56 -c 3 -W 1 184.58.226.195
2025-08-18 02:35:36,271 - DEBUG - PING 184.58.226.195 (184.58.226.195) 56(84) bytes of data.
64 bytes from 184.58.226.195: icmp_req=1 ttl=63 time=9.20 ms
64 bytes from 184.58.226.195: icmp_req=2 ttl=63 time=1.70 ms
64 bytes from 184.58.226.195: icmp_req=3 ttl=63 time=1.10 ms
--- 184.58.226.195 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 1.100/4.003/9.202/3.684 ms
2025-08-18 02:35:36,272 - INFO - Ping to IP 184.58.226.195 from VM ctest-VM11-65139879 passed
2025-08-18 02:35:36,272 - DEBUG - Running remote_cmd, Cmd : ping6 -s 56 -c 3 -W 1 3fbf:47ea:757a:5f85:d54c:60d4:0:3, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.241, gateway password: c0ntrail123
2025-08-18 02:35:36,272 - DEBUG - ping6 -s 56 -c 3 -W 1 3fbf:47ea:757a:5f85:d54c:60d4:0:3
2025-08-18 02:35:38,778 - DEBUG - PING 3fbf:47ea:757a:5f85:d54c:60d4:0:3(3fbf:47ea:757a:5f85:d54c:60d4:0:3) 56 data bytes
64 bytes from 3fbf:47ea:757a:5f85:d54c:60d4:0:3: icmp_seq=1 ttl=64 time=9.35 ms
64 bytes from 3fbf:47ea:757a:5f85:d54c:60d4:0:3: icmp_seq=2 ttl=64 time=1.53 ms
64 bytes from 3fbf:47ea:757a:5f85:d54c:60d4:0:3: icmp_seq=3 ttl=64 time=1.51 ms
--- 3fbf:47ea:757a:5f85:d54c:60d4:0:3 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2003ms
rtt min/avg/max/mdev = 1.510/4.132/9.355/3.693 ms
2025-08-18 02:35:38,778 - INFO - Ping to IP 3fbf:47ea:757a:5f85:d54c:60d4:0:3 from VM ctest-VM11-65139879 passed
2025-08-18 02:35:38,778 - INFO - Test with src as policy and dst as any PASSED
2025-08-18 02:35:38,778 - INFO - Deleting VM ctest-VM31-38041155
2025-08-18 02:35:38,845 - DEBUG - Verifying in api server 10.0.0.19
2025-08-18 02:35:38,845 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/d72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242
2025-08-18 02:35:38,853 - DEBUG - VM ID d72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242 of VM ctest-VM31-38041155 is still found in API Server
2025-08-18 02:35:40,854 - DEBUG - Verifying in api server 10.0.0.19
2025-08-18 02:35:40,854 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/d72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242
2025-08-18 02:35:40,860 - DEBUG - Response Code: 404
2025-08-18 02:35:40,860 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/d72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242
2025-08-18 02:35:40,866 - DEBUG - Response Code: 404
2025-08-18 02:35:40,866 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/d72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242
2025-08-18 02:35:40,871 - DEBUG - Response Code: 404
2025-08-18 02:35:40,871 - INFO - VM ctest-VM31-38041155 is fully removed in API-Server
2025-08-18 02:35:41,025 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2025-08-18 02:35:41,068 - DEBUG - Validated that vrouter 10.0.0.241 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN3-64191227
2025-08-18 02:35:41,078 - DEBUG - Validated that vrouter 10.0.0.41 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN3-64191227
2025-08-18 02:35:41,078 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN3-64191227
2025-08-18 02:35:41,078 - INFO - VM ctest-VM31-38041155 is removed in Compute, and routes are removed in all compute nodes
2025-08-18 02:35:41,307 - INFO - Routes for VM ctest-VM31-38041155 is removed in all control-nodes
2025-08-18 02:35:41,307 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3870-1:Compute:contrail-vrouter-agent:0?flat
2025-08-18 02:35:41,350 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3870-1?flat
2025-08-18 02:35:41,384 - DEBUG - VM d72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3870-1 uve
2025-08-18 02:35:41,384 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-18 02:35:41,384 - DEBUG - interface for vm d72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242 deleted from vrouter uve ...
2025-08-18 02:35:41,384 - DEBUG - Deleted interface not in error interface list ...
2025-08-18 02:35:44,385 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3870-1:Compute:contrail-vrouter-agent:0?flat
2025-08-18 02:35:44,427 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3870-1?flat
2025-08-18 02:35:44,462 - DEBUG - VM d72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3870-1 uve
2025-08-18 02:35:44,462 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-18 02:35:44,462 - DEBUG - interface for vm d72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242 deleted from vrouter uve ...
2025-08-18 02:35:44,462 - DEBUG - Deleted interface not in error interface list ...
2025-08-18 02:35:47,463 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3870-1:Compute:contrail-vrouter-agent:0?flat
2025-08-18 02:35:47,508 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3870-1?flat
2025-08-18 02:35:47,558 - DEBUG - VM d72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3870-1 uve
2025-08-18 02:35:47,558 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-18 02:35:47,558 - DEBUG - interface for vm d72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242 deleted from vrouter uve ...
2025-08-18 02:35:47,559 - DEBUG - Deleted interface not in error interface list ...
2025-08-18 02:35:50,560 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3870-1:Compute:contrail-vrouter-agent:0?flat
2025-08-18 02:35:50,603 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3870-1?flat
2025-08-18 02:35:50,640 - DEBUG - VM d72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242 is not present in vrouter an-jenkins-deploy-platform-ansible-os-3870-1 uve
2025-08-18 02:35:50,640 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-18 02:35:50,640 - DEBUG - interface for vm d72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242 deleted from vrouter uve ...
2025-08-18 02:35:50,640 - DEBUG - Deleted interface not in error interface list ...
2025-08-18 02:35:50,640 - DEBUG - Validated that VM d72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242 is removed in Vrouter UVE
2025-08-18 02:35:50,640 - DEBUG - Verifying through opserver in 10.0.0.27
2025-08-18 02:35:50,641 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN3-64191227?flat
2025-08-18 02:35:50,659 - DEBUG - d72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242 BM not in default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN3-64191227 uve
2025-08-18 02:35:50,659 - DEBUG - Validated that VM d72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242 is not present in the VN UVE
2025-08-18 02:35:50,660 - DEBUG - Verifying the d72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242 virtual network link through opserver 10.0.0.27
2025-08-18 02:35:50,660 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines
2025-08-18 02:35:50,671 - INFO - d72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242 vm uve deleted from opserver
2025-08-18 02:35:50,671 - DEBUG - Verifying the d72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242 virtual network link through opserver 10.0.0.38
2025-08-18 02:35:50,672 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines
2025-08-18 02:35:50,686 - INFO - d72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242 vm uve deleted from opserver
2025-08-18 02:35:50,686 - DEBUG - Verifying the d72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242 virtual network link through opserver 10.0.0.19
2025-08-18 02:35:50,686 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machines
2025-08-18 02:35:50,698 - INFO - d72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242 vm uve deleted from opserver
2025-08-18 02:35:50,698 - INFO - Validated that VM d72e7ee2-6ac7-4c7b-b7e4-c94e6eacb242 is removed from Opserver
2025-08-18 02:35:50,699 - INFO - Deleting VM ctest-VM21-25126703
2025-08-18 02:35:50,758 - DEBUG - Verifying in api server 10.0.0.19
2025-08-18 02:35:50,758 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/bea8b2e6-1010-42ae-9f48-d320818dc55c
2025-08-18 02:35:50,766 - DEBUG - VM ID bea8b2e6-1010-42ae-9f48-d320818dc55c of VM ctest-VM21-25126703 is still found in API Server
2025-08-18 02:35:52,767 - DEBUG - Verifying in api server 10.0.0.19
2025-08-18 02:35:52,767 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/bea8b2e6-1010-42ae-9f48-d320818dc55c
2025-08-18 02:35:52,774 - DEBUG - Response Code: 404
2025-08-18 02:35:52,774 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/bea8b2e6-1010-42ae-9f48-d320818dc55c
2025-08-18 02:35:52,781 - DEBUG - Response Code: 404
2025-08-18 02:35:52,781 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/bea8b2e6-1010-42ae-9f48-d320818dc55c
2025-08-18 02:35:52,788 - DEBUG - Response Code: 404
2025-08-18 02:35:52,788 - INFO - VM ctest-VM21-25126703 is fully removed in API-Server
2025-08-18 02:35:52,941 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2025-08-18 02:35:52,987 - DEBUG - Validated that vrouter 10.0.0.241 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN2-43943987
2025-08-18 02:35:52,997 - DEBUG - Validated that vrouter 10.0.0.41 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN2-43943987
2025-08-18 02:35:52,997 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN2-43943987
2025-08-18 02:35:52,997 - INFO - VM ctest-VM21-25126703 is removed in Compute, and routes are removed in all compute nodes
2025-08-18 02:35:53,206 - INFO - Routes for VM ctest-VM21-25126703 is removed in all control-nodes
2025-08-18 02:35:53,206 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3870-2:Compute:contrail-vrouter-agent:0?flat
2025-08-18 02:35:53,247 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3870-2?flat
2025-08-18 02:35:53,283 - DEBUG - VM bea8b2e6-1010-42ae-9f48-d320818dc55c is still present in vrouter an-jenkins-deploy-platform-ansible-os-3870-2 uve
2025-08-18 02:35:53,283 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-18 02:35:53,284 - DEBUG - interface for vm bea8b2e6-1010-42ae-9f48-d320818dc55c deleted from vrouter uve ...
2025-08-18 02:35:53,284 - DEBUG - Deleted interface not in error interface list ...
2025-08-18 02:35:56,284 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3870-2:Compute:contrail-vrouter-agent:0?flat
2025-08-18 02:35:56,326 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3870-2?flat
2025-08-18 02:35:56,363 - DEBUG - VM bea8b2e6-1010-42ae-9f48-d320818dc55c is still present in vrouter an-jenkins-deploy-platform-ansible-os-3870-2 uve
2025-08-18 02:35:56,363 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-18 02:35:56,363 - DEBUG - interface for vm bea8b2e6-1010-42ae-9f48-d320818dc55c deleted from vrouter uve ...
2025-08-18 02:35:56,363 - DEBUG - Deleted interface not in error interface list ...
2025-08-18 02:35:59,364 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3870-2:Compute:contrail-vrouter-agent:0?flat
2025-08-18 02:35:59,407 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3870-2?flat
2025-08-18 02:35:59,450 - DEBUG - VM bea8b2e6-1010-42ae-9f48-d320818dc55c is still present in vrouter an-jenkins-deploy-platform-ansible-os-3870-2 uve
2025-08-18 02:35:59,450 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-18 02:35:59,450 - DEBUG - interface for vm bea8b2e6-1010-42ae-9f48-d320818dc55c deleted from vrouter uve ...
2025-08-18 02:35:59,450 - DEBUG - Deleted interface not in error interface list ...
2025-08-18 02:36:02,451 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3870-2:Compute:contrail-vrouter-agent:0?flat
2025-08-18 02:36:02,512 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3870-2?flat
2025-08-18 02:36:02,584 - DEBUG - VM bea8b2e6-1010-42ae-9f48-d320818dc55c is still present in vrouter an-jenkins-deploy-platform-ansible-os-3870-2 uve
2025-08-18 02:36:02,584 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-18 02:36:02,584 - DEBUG - interface for vm bea8b2e6-1010-42ae-9f48-d320818dc55c deleted from vrouter uve ...
2025-08-18 02:36:02,584 - DEBUG - Deleted interface not in error interface list ...
2025-08-18 02:36:05,585 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3870-2:Compute:contrail-vrouter-agent:0?flat
2025-08-18 02:36:05,629 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3870-2?flat
2025-08-18 02:36:05,664 - DEBUG - VM bea8b2e6-1010-42ae-9f48-d320818dc55c is still present in vrouter an-jenkins-deploy-platform-ansible-os-3870-2 uve
2025-08-18 02:36:05,664 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-18 02:36:05,664 - DEBUG - interface for vm bea8b2e6-1010-42ae-9f48-d320818dc55c deleted from vrouter uve ...
2025-08-18 02:36:05,664 - DEBUG - Deleted interface not in error interface list ...
2025-08-18 02:36:08,665 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3870-2:Compute:contrail-vrouter-agent:0?flat
2025-08-18 02:36:08,708 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3870-2?flat
2025-08-18 02:36:08,742 - DEBUG - VM bea8b2e6-1010-42ae-9f48-d320818dc55c is still present in vrouter an-jenkins-deploy-platform-ansible-os-3870-2 uve
2025-08-18 02:36:08,742 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-18 02:36:08,742 - DEBUG - interface for vm bea8b2e6-1010-42ae-9f48-d320818dc55c deleted from vrouter uve ...
2025-08-18 02:36:08,742 - DEBUG - Deleted interface not in error interface list ...
2025-08-18 02:36:11,743 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3870-2:Compute:contrail-vrouter-agent:0?flat
2025-08-18 02:36:11,798 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3870-2?flat
2025-08-18 02:36:11,839 - DEBUG - VM bea8b2e6-1010-42ae-9f48-d320818dc55c is still present in vrouter an-jenkins-deploy-platform-ansible-os-3870-2 uve
2025-08-18 02:36:11,839 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-18 02:36:11,839 - DEBUG - interface for vm bea8b2e6-1010-42ae-9f48-d320818dc55c deleted from vrouter uve ...
2025-08-18 02:36:11,839 - DEBUG - Deleted interface not in error interface list ...
2025-08-18 02:36:14,840 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3870-2:Compute:contrail-vrouter-agent:0?flat
2025-08-18 02:36:14,881 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3870-2?flat
2025-08-18 02:36:14,917 - DEBUG - VM bea8b2e6-1010-42ae-9f48-d320818dc55c is still present in vrouter an-jenkins-deploy-platform-ansible-os-3870-2 uve
2025-08-18 02:36:14,917 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-18 02:36:14,917 - DEBUG - interface for vm bea8b2e6-1010-42ae-9f48-d320818dc55c deleted from vrouter uve ...
2025-08-18 02:36:14,917 - DEBUG - Deleted interface not in error interface list ...
2025-08-18 02:36:17,918 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3870-2:Compute:contrail-vrouter-agent:0?flat
2025-08-18 02:36:17,964 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3870-2?flat
2025-08-18 02:36:18,021 - DEBUG - VM bea8b2e6-1010-42ae-9f48-d320818dc55c is not present in vrouter an-jenkins-deploy-platform-ansible-os-3870-2 uve
2025-08-18 02:36:18,021 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-18 02:36:18,021 - DEBUG - interface for vm bea8b2e6-1010-42ae-9f48-d320818dc55c deleted from vrouter uve ...
2025-08-18 02:36:18,021 - DEBUG - Deleted interface not in error interface list ...
2025-08-18 02:36:18,021 - DEBUG - Validated that VM bea8b2e6-1010-42ae-9f48-d320818dc55c is removed in Vrouter UVE
2025-08-18 02:36:18,021 - DEBUG - Verifying through opserver in 10.0.0.27
2025-08-18 02:36:18,021 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN2-43943987?flat
2025-08-18 02:36:18,039 - DEBUG - bea8b2e6-1010-42ae-9f48-d320818dc55c BM not in default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN2-43943987 uve
2025-08-18 02:36:18,039 - DEBUG - Validated that VM bea8b2e6-1010-42ae-9f48-d320818dc55c is not present in the VN UVE
2025-08-18 02:36:18,040 - DEBUG - Verifying the bea8b2e6-1010-42ae-9f48-d320818dc55c virtual network link through opserver 10.0.0.27
2025-08-18 02:36:18,040 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines
2025-08-18 02:36:18,050 - INFO - bea8b2e6-1010-42ae-9f48-d320818dc55c vm uve deleted from opserver
2025-08-18 02:36:18,050 - DEBUG - Verifying the bea8b2e6-1010-42ae-9f48-d320818dc55c virtual network link through opserver 10.0.0.38
2025-08-18 02:36:18,050 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines
2025-08-18 02:36:18,060 - INFO - bea8b2e6-1010-42ae-9f48-d320818dc55c vm uve deleted from opserver
2025-08-18 02:36:18,060 - DEBUG - Verifying the bea8b2e6-1010-42ae-9f48-d320818dc55c virtual network link through opserver 10.0.0.19
2025-08-18 02:36:18,060 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machines
2025-08-18 02:36:18,072 - INFO - bea8b2e6-1010-42ae-9f48-d320818dc55c vm uve deleted from opserver
2025-08-18 02:36:18,072 - INFO - Validated that VM bea8b2e6-1010-42ae-9f48-d320818dc55c is removed from Opserver
2025-08-18 02:36:18,072 - INFO - Deleting VM ctest-VM11-65139879
2025-08-18 02:36:18,138 - DEBUG - Verifying in api server 10.0.0.19
2025-08-18 02:36:18,138 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/a9462524-c599-4390-851d-42a089dbdd88
2025-08-18 02:36:18,146 - DEBUG - VM ID a9462524-c599-4390-851d-42a089dbdd88 of VM ctest-VM11-65139879 is still found in API Server
2025-08-18 02:36:20,147 - DEBUG - Verifying in api server 10.0.0.19
2025-08-18 02:36:20,147 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/a9462524-c599-4390-851d-42a089dbdd88
2025-08-18 02:36:20,159 - DEBUG - Response Code: 404
2025-08-18 02:36:20,159 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/a9462524-c599-4390-851d-42a089dbdd88
2025-08-18 02:36:20,167 - DEBUG - Response Code: 404
2025-08-18 02:36:20,167 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/a9462524-c599-4390-851d-42a089dbdd88
2025-08-18 02:36:20,172 - DEBUG - Response Code: 404
2025-08-18 02:36:20,172 - INFO - VM ctest-VM11-65139879 is fully removed in API-Server
2025-08-18 02:36:20,326 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2025-08-18 02:36:20,369 - DEBUG - Validated that vrouter 10.0.0.241 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007
2025-08-18 02:36:20,379 - DEBUG - Validated that vrouter 10.0.0.41 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007
2025-08-18 02:36:20,379 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007
2025-08-18 02:36:20,380 - INFO - VM ctest-VM11-65139879 is removed in Compute, and routes are removed in all compute nodes
2025-08-18 02:36:20,572 - INFO - Routes for VM ctest-VM11-65139879 is removed in all control-nodes
2025-08-18 02:36:20,572 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3870-1:Compute:contrail-vrouter-agent:0?flat
2025-08-18 02:36:20,619 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3870-1?flat
2025-08-18 02:36:20,658 - DEBUG - VM a9462524-c599-4390-851d-42a089dbdd88 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3870-1 uve
2025-08-18 02:36:20,658 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-18 02:36:20,659 - DEBUG - interface for vm a9462524-c599-4390-851d-42a089dbdd88 deleted from vrouter uve ...
2025-08-18 02:36:20,659 - DEBUG - Deleted interface not in error interface list ...
2025-08-18 02:36:23,659 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3870-1:Compute:contrail-vrouter-agent:0?flat
2025-08-18 02:36:23,719 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3870-1?flat
2025-08-18 02:36:23,763 - DEBUG - VM a9462524-c599-4390-851d-42a089dbdd88 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3870-1 uve
2025-08-18 02:36:23,763 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-18 02:36:23,763 - DEBUG - interface for vm a9462524-c599-4390-851d-42a089dbdd88 deleted from vrouter uve ...
2025-08-18 02:36:23,763 - DEBUG - Deleted interface not in error interface list ...
2025-08-18 02:36:26,764 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3870-1:Compute:contrail-vrouter-agent:0?flat
2025-08-18 02:36:26,807 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3870-1?flat
2025-08-18 02:36:26,841 - DEBUG - VM a9462524-c599-4390-851d-42a089dbdd88 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3870-1 uve
2025-08-18 02:36:26,841 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-18 02:36:26,841 - DEBUG - interface for vm a9462524-c599-4390-851d-42a089dbdd88 deleted from vrouter uve ...
2025-08-18 02:36:26,841 - DEBUG - Deleted interface not in error interface list ...
2025-08-18 02:36:29,842 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3870-1:Compute:contrail-vrouter-agent:0?flat
2025-08-18 02:36:29,898 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3870-1?flat
2025-08-18 02:36:29,936 - DEBUG - VM a9462524-c599-4390-851d-42a089dbdd88 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3870-1 uve
2025-08-18 02:36:29,936 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-18 02:36:29,936 - DEBUG - interface for vm a9462524-c599-4390-851d-42a089dbdd88 deleted from vrouter uve ...
2025-08-18 02:36:29,936 - DEBUG - Deleted interface not in error interface list ...
2025-08-18 02:36:32,937 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3870-1:Compute:contrail-vrouter-agent:0?flat
2025-08-18 02:36:32,979 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3870-1?flat
2025-08-18 02:36:33,014 - DEBUG - VM a9462524-c599-4390-851d-42a089dbdd88 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3870-1 uve
2025-08-18 02:36:33,014 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-18 02:36:33,014 - DEBUG - interface for vm a9462524-c599-4390-851d-42a089dbdd88 deleted from vrouter uve ...
2025-08-18 02:36:33,014 - DEBUG - Deleted interface not in error interface list ...
2025-08-18 02:36:36,015 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3870-1:Compute:contrail-vrouter-agent:0?flat
2025-08-18 02:36:36,059 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3870-1?flat
2025-08-18 02:36:36,105 - DEBUG - VM a9462524-c599-4390-851d-42a089dbdd88 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3870-1 uve
2025-08-18 02:36:36,105 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-18 02:36:36,105 - DEBUG - interface for vm a9462524-c599-4390-851d-42a089dbdd88 deleted from vrouter uve ...
2025-08-18 02:36:36,105 - DEBUG - Deleted interface not in error interface list ...
2025-08-18 02:36:39,105 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3870-1:Compute:contrail-vrouter-agent:0?flat
2025-08-18 02:36:39,148 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3870-1?flat
2025-08-18 02:36:39,183 - DEBUG - VM a9462524-c599-4390-851d-42a089dbdd88 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3870-1 uve
2025-08-18 02:36:39,183 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-18 02:36:39,184 - DEBUG - interface for vm a9462524-c599-4390-851d-42a089dbdd88 deleted from vrouter uve ...
2025-08-18 02:36:39,184 - DEBUG - Deleted interface not in error interface list ...
2025-08-18 02:36:42,185 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3870-1:Compute:contrail-vrouter-agent:0?flat
2025-08-18 02:36:42,236 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3870-1?flat
2025-08-18 02:36:42,281 - DEBUG - VM a9462524-c599-4390-851d-42a089dbdd88 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3870-1 uve
2025-08-18 02:36:42,281 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-18 02:36:42,281 - DEBUG - interface for vm a9462524-c599-4390-851d-42a089dbdd88 deleted from vrouter uve ...
2025-08-18 02:36:42,281 - DEBUG - Deleted interface not in error interface list ...
2025-08-18 02:36:45,282 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3870-1:Compute:contrail-vrouter-agent:0?flat
2025-08-18 02:36:45,326 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3870-1?flat
2025-08-18 02:36:45,363 - DEBUG - VM a9462524-c599-4390-851d-42a089dbdd88 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3870-1 uve
2025-08-18 02:36:45,363 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-18 02:36:45,363 - DEBUG - interface for vm a9462524-c599-4390-851d-42a089dbdd88 deleted from vrouter uve ...
2025-08-18 02:36:45,363 - DEBUG - Deleted interface not in error interface list ...
2025-08-18 02:36:48,364 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3870-1:Compute:contrail-vrouter-agent:0?flat
2025-08-18 02:36:48,410 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3870-1?flat
2025-08-18 02:36:48,446 - DEBUG - VM a9462524-c599-4390-851d-42a089dbdd88 is not present in vrouter an-jenkins-deploy-platform-ansible-os-3870-1 uve
2025-08-18 02:36:48,446 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-18 02:36:48,446 - DEBUG - interface for vm a9462524-c599-4390-851d-42a089dbdd88 deleted from vrouter uve ...
2025-08-18 02:36:48,446 - DEBUG - Deleted interface not in error interface list ...
2025-08-18 02:36:48,446 - DEBUG - Validated that VM a9462524-c599-4390-851d-42a089dbdd88 is removed in Vrouter UVE
2025-08-18 02:36:48,446 - DEBUG - Verifying through opserver in 10.0.0.27
2025-08-18 02:36:48,446 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007?flat
2025-08-18 02:36:48,465 - DEBUG - a9462524-c599-4390-851d-42a089dbdd88 BM not in default-domain:ctest-TestPolicyAclIpv4v6-51732354:ctest-VN1-00644007 uve
2025-08-18 02:36:48,465 - DEBUG - Validated that VM a9462524-c599-4390-851d-42a089dbdd88 is not present in the VN UVE
2025-08-18 02:36:48,465 - DEBUG - Verifying the a9462524-c599-4390-851d-42a089dbdd88 virtual network link through opserver 10.0.0.27
2025-08-18 02:36:48,465 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines
2025-08-18 02:36:48,478 - DEBUG - No links retuned for a9462524-c599-4390-851d-42a089dbdd88
2025-08-18 02:36:48,478 - INFO - a9462524-c599-4390-851d-42a089dbdd88 vm uve deleted from opserver
2025-08-18 02:36:48,479 - DEBUG - Verifying the a9462524-c599-4390-851d-42a089dbdd88 virtual network link through opserver 10.0.0.38
2025-08-18 02:36:48,479 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines
2025-08-18 02:36:48,496 - DEBUG - No links retuned for a9462524-c599-4390-851d-42a089dbdd88
2025-08-18 02:36:48,496 - INFO - a9462524-c599-4390-851d-42a089dbdd88 vm uve deleted from opserver
2025-08-18 02:36:48,496 - DEBUG - Verifying the a9462524-c599-4390-851d-42a089dbdd88 virtual network link through opserver 10.0.0.19
2025-08-18 02:36:48,496 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machines
2025-08-18 02:36:48,511 - DEBUG - No links retuned for a9462524-c599-4390-851d-42a089dbdd88
2025-08-18 02:36:48,511 - INFO - a9462524-c599-4390-851d-42a089dbdd88 vm uve deleted from opserver
2025-08-18 02:36:48,511 - INFO - Validated that VM a9462524-c599-4390-851d-42a089dbdd88 is removed from Opserver
2025-08-18 02:36:48,512 - DEBUG - Detaching the Policy for VN :ctest-VN2-43943987
2025-08-18 02:36:48,512 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-18 02:36:48,520 - DEBUG - Requesting: http://10.0.0.27:8082/domain/edf943fc-0622-4564-8821-4de9bd04e8a3
2025-08-18 02:36:48,560 - DEBUG - Requesting: http://10.0.0.27:8082/project/1a7c285f-546c-4e71-b173-9828e1a41fa2
2025-08-18 02:36:48,667 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/b371400a-1a8e-472d-8cab-0b397d7ee62a
2025-08-18 02:36:48,681 - DEBUG - Requesting: http://10.0.0.27:8082/network-policy/25a5d830-2186-4bb0-94e7-e9d004774035
2025-08-18 02:36:48,903 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-51732354', 'ctest-policy21-88149205']] from ctest-VN2-43943987
2025-08-18 02:36:48,903 - DEBUG - Detaching the Policy for VN :ctest-VN1-00644007
2025-08-18 02:36:48,903 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-18 02:36:48,913 - DEBUG - Requesting: http://10.0.0.27:8082/domain/edf943fc-0622-4564-8821-4de9bd04e8a3
2025-08-18 02:36:48,925 - DEBUG - Requesting: http://10.0.0.27:8082/project/1a7c285f-546c-4e71-b173-9828e1a41fa2
2025-08-18 02:36:49,018 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/1acc055b-8608-4201-8c77-26ad49e72296
2025-08-18 02:36:49,032 - DEBUG - Requesting: http://10.0.0.27:8082/network-policy/e8186527-db52-4a3b-b7bd-18510248d554
2025-08-18 02:36:49,041 - DEBUG - Requesting: http://10.0.0.27:8082/network-policy/479f165f-d619-4a6e-9e04-9dc70165a1a4
2025-08-18 02:36:49,182 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-51732354', 'ctest-policy12-97865531'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-51732354', 'ctest-policy13-54620987']] from ctest-VN1-00644007
2025-08-18 02:36:49,218 - INFO - Deleted policy ctest-policy21-88149205
2025-08-18 02:36:49,251 - INFO - Deleted policy ctest-policy12-97865531
2025-08-18 02:36:49,288 - INFO - Deleted policy ctest-policy13-54620987
2025-08-18 02:36:49,288 - INFO - Deleting VN ctest-VN3-64191227
2025-08-18 02:36:49,545 - DEBUG - Response for deleting network ()
2025-08-18 02:36:49,546 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/bda5edf8-960c-4214-84d3-d84b8684275c
2025-08-18 02:36:49,555 - DEBUG - Response Code: 404
2025-08-18 02:36:49,556 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-18 02:36:49,567 - DEBUG - Requesting: http://10.0.0.27:8082/domain/edf943fc-0622-4564-8821-4de9bd04e8a3
2025-08-18 02:36:49,579 - DEBUG - Requesting: http://10.0.0.27:8082/project/1a7c285f-546c-4e71-b173-9828e1a41fa2
2025-08-18 02:36:49,661 - INFO - Validated that VN ctest-VN3-64191227 is not found in API Server
2025-08-18 02:36:49,673 - DEBUG - VN ctest-VN3-64191227 is not present in Agent 10.0.0.241
2025-08-18 02:36:49,686 - DEBUG - VN ctest-VN3-64191227 is not present in Agent 10.0.0.41
2025-08-18 02:36:49,687 - INFO - Validated that VN ctest-VN3-64191227 is not in any agent
2025-08-18 02:36:49,718 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN3-64191227 info
2025-08-18 02:36:49,719 - INFO - Deleting VN ctest-VN2-43943987
2025-08-18 02:36:49,929 - DEBUG - Response for deleting network ()
2025-08-18 02:36:49,929 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/90f8d7ad-0db5-4a70-abe0-3033a587746d
2025-08-18 02:36:49,935 - DEBUG - Response Code: 404
2025-08-18 02:36:49,935 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-18 02:36:49,940 - DEBUG - Requesting: http://10.0.0.27:8082/domain/edf943fc-0622-4564-8821-4de9bd04e8a3
2025-08-18 02:36:49,951 - DEBUG - Requesting: http://10.0.0.27:8082/project/1a7c285f-546c-4e71-b173-9828e1a41fa2
2025-08-18 02:36:50,031 - INFO - Validated that VN ctest-VN2-43943987 is not found in API Server
2025-08-18 02:36:50,043 - DEBUG - VN ctest-VN2-43943987 is not present in Agent 10.0.0.241
2025-08-18 02:36:50,054 - DEBUG - VN ctest-VN2-43943987 is not present in Agent 10.0.0.41
2025-08-18 02:36:50,054 - INFO - Validated that VN ctest-VN2-43943987 is not in any agent
2025-08-18 02:36:50,084 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN2-43943987 info
2025-08-18 02:36:50,084 - INFO - Deleting VN ctest-VN1-00644007
2025-08-18 02:36:50,248 - DEBUG - Response for deleting network ()
2025-08-18 02:36:50,248 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/cc167e45-6f8a-4ccf-9d11-5e170e46b039
2025-08-18 02:36:50,254 - DEBUG - Response Code: 404
2025-08-18 02:36:50,255 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-18 02:36:50,260 - DEBUG - Requesting: http://10.0.0.27:8082/domain/edf943fc-0622-4564-8821-4de9bd04e8a3
2025-08-18 02:36:50,311 - DEBUG - Requesting: http://10.0.0.27:8082/project/1a7c285f-546c-4e71-b173-9828e1a41fa2
2025-08-18 02:36:50,404 - INFO - Validated that VN ctest-VN1-00644007 is not found in API Server
2025-08-18 02:36:50,421 - DEBUG - VN ctest-VN1-00644007 is not present in Agent 10.0.0.241
2025-08-18 02:36:50,434 - DEBUG - VN ctest-VN1-00644007 is not present in Agent 10.0.0.41
2025-08-18 02:36:50,434 - INFO - Validated that VN ctest-VN1-00644007 is not in any agent
2025-08-18 02:36:50,465 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN1-00644007 info
2025-08-18 02:36:50,562 - INFO - IPAM: ipam3 is not found in API Server
2025-08-18 02:36:50,587 - INFO - IPAM:ipam3 is not found in control node
2025-08-18 02:36:50,718 - INFO - IPAM: ipam2 is not found in API Server
2025-08-18 02:36:50,740 - INFO - IPAM:ipam2 is not found in control node
2025-08-18 02:36:50,870 - INFO - IPAM: ipam1 is not found in API Server
2025-08-18 02:36:50,892 - INFO - IPAM:ipam1 is not found in control node
2025-08-18 02:36:52,158 - DEBUG - No XMPP flaps were noticed during the test
2025-08-18 02:36:52,158 - INFO - END TEST : test_policy_inheritance_src_pol_dst_any : PASSED[0:02:56]
2025-08-18 02:36:52,158 - INFO - --------------------------------------------------------------------------------
2025-08-18 02:36:53,408 - DEBUG - No XMPP flaps were noticed during the test
2025-08-18 02:36:53,408 - INFO - END TEST : test_policy_inheritance_src_pol_dst_any : PASSED[0:02:59]
2025-08-18 02:36:53,409 - INFO - --------------------------------------------------------------------------------
2025-08-18 02:36:54,102 - INFO - Deleted project: ctest-TestPolicyAclIpv4v6-51732354, ID : 1a7c285f-546c-4e71-b173-9828e1a41fa2