2026-04-02 02:40:16,384 - INFO - Domain Default found not creating
2026-04-02 02:40:16,557 - INFO - Project ctest-TestPolicyAclIpv4v6-60337406 not found, creating it
2026-04-02 02:40:17,199 - INFO - Created Project:ctest-TestPolicyAclIpv4v6-60337406, ID : 2062099b-383c-4bc6-80f8-0875eb4a8dd6
2026-04-02 02:40:18,751 - INFO - ================================================================================
2026-04-02 02:40:18,751 - INFO - STARTING TEST : test_policy_inheritance_src_pol_dst_any
2026-04-02 02:40:20,037 - DEBUG - Nothing to compare xmpp stats {'10.0.0.50': {'10.20.0.254': '0', '10.20.0.249': '0'}, '10.0.0.128': {'10.20.0.249': '0', '10.20.0.254': '0'}} with
2026-04-02 02:40:20,037 - INFO - Initial checks done. Running the testcase now
2026-04-02 02:40:20,037 - INFO -
2026-04-02 02:40:20,037 - INFO - ================================================================================
2026-04-02 02:40:20,037 - INFO - STARTING TEST : test_policy_inheritance_src_pol_dst_any
2026-04-02 02:40:20,037 - INFO - TEST DESCRIPTION : Test cases to test policy inheritance
2026-04-02 02:40:21,339 - DEBUG - Nothing to compare xmpp stats {'10.0.0.50': {'10.20.0.254': '0', '10.20.0.249': '0'}, '10.0.0.128': {'10.20.0.249': '0', '10.20.0.254': '0'}} with
2026-04-02 02:40:21,339 - INFO - Initial checks done. Running the testcase now
2026-04-02 02:40:21,339 - INFO -
2026-04-02 02:40:22,661 - DEBUG - Requesting: http://10.0.0.73:8082/domains
2026-04-02 02:40:22,970 - DEBUG - Requesting: http://10.0.0.73:8082/domains
2026-04-02 02:40:23,002 - DEBUG - Requesting: http://10.0.0.73:8082/domain/2c17115e-1a6e-47b0-9bfc-9e380916d58b
2026-04-02 02:40:23,017 - DEBUG - Requesting: http://10.0.0.73:8082/project/2062099b-383c-4bc6-80f8-0875eb4a8dd6
2026-04-02 02:40:23,120 - DEBUG - Requesting: http://10.0.0.73:8082/network-ipam/11fe8855-2a16-4ed7-90ee-907fa994a0e2
2026-04-02 02:40:23,130 - INFO - Verifications in API Server for IPAM: ipam1 passed
2026-04-02 02:40:23,136 - DEBUG - Control-node 10.0.0.73 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-60337406:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-60337406', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1296623642088984279', 'uuid-lslong': '10443443463899095266'}, 'enable': 'true', 'created': '2026-04-02T02:40:21', 'last-modified': '2026-04-02T02:40:21', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.184947'}
2026-04-02 02:40:23,142 - DEBUG - Control-node 10.0.0.133 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-60337406:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-60337406', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1296623642088984279', 'uuid-lslong': '10443443463899095266'}, 'enable': 'true', 'created': '2026-04-02T02:40:21', 'last-modified': '2026-04-02T02:40:21', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.189905'}
2026-04-02 02:40:23,148 - DEBUG - Control-node 10.0.0.48 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-60337406:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-60337406', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1296623642088984279', 'uuid-lslong': '10443443463899095266'}, 'enable': 'true', 'created': '2026-04-02T02:40:21', 'last-modified': '2026-04-02T02:40:21', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.195084'}
2026-04-02 02:40:23,148 - INFO - Verifications in Control node for IPAM: ipam1 passed
2026-04-02 02:40:23,148 - DEBUG - Requesting: http://10.0.0.73:8082/domains
2026-04-02 02:40:23,157 - DEBUG - Requesting: http://10.0.0.73:8082/domain/2c17115e-1a6e-47b0-9bfc-9e380916d58b
2026-04-02 02:40:23,169 - DEBUG - Requesting: http://10.0.0.73:8082/project/2062099b-383c-4bc6-80f8-0875eb4a8dd6
2026-04-02 02:40:23,267 - DEBUG - Requesting: http://10.0.0.73:8082/network-ipam/6a2ede74-4668-4aab-b51c-c2cffa125bae
2026-04-02 02:40:23,276 - INFO - Verifications in API Server for IPAM: ipam2 passed
2026-04-02 02:40:23,283 - DEBUG - Control-node 10.0.0.73 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-60337406:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-60337406', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7651297407927863979', 'uuid-lslong': '13050520018722380718'}, 'enable': 'true', 'created': '2026-04-02T02:40:22', 'last-modified': '2026-04-02T02:40:22', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.988477'}
2026-04-02 02:40:23,289 - DEBUG - Control-node 10.0.0.133 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-60337406:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-60337406', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7651297407927863979', 'uuid-lslong': '13050520018722380718'}, 'enable': 'true', 'created': '2026-04-02T02:40:22', 'last-modified': '2026-04-02T02:40:22', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.002279'}
2026-04-02 02:40:23,295 - DEBUG - Control-node 10.0.0.48 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-60337406:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-60337406', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7651297407927863979', 'uuid-lslong': '13050520018722380718'}, 'enable': 'true', 'created': '2026-04-02T02:40:22', 'last-modified': '2026-04-02T02:40:22', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.007854'}
2026-04-02 02:40:23,295 - INFO - Verifications in Control node for IPAM: ipam2 passed
2026-04-02 02:40:23,295 - DEBUG - Requesting: http://10.0.0.73:8082/domains
2026-04-02 02:40:23,302 - DEBUG - Requesting: http://10.0.0.73:8082/domain/2c17115e-1a6e-47b0-9bfc-9e380916d58b
2026-04-02 02:40:23,314 - DEBUG - Requesting: http://10.0.0.73:8082/project/2062099b-383c-4bc6-80f8-0875eb4a8dd6
2026-04-02 02:40:23,411 - DEBUG - Requesting: http://10.0.0.73:8082/network-ipam/5eb136d4-388a-4d93-afc4-83f1ec6799ed
2026-04-02 02:40:23,420 - INFO - Verifications in API Server for IPAM: ipam3 passed
2026-04-02 02:40:23,430 - DEBUG - Control-node 10.0.0.73 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-60337406:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-60337406', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6823295195552566675', 'uuid-lslong': '12665393127149246957'}, 'enable': 'true', 'created': '2026-04-02T02:40:22', 'last-modified': '2026-04-02T02:40:22', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.792980'}
2026-04-02 02:40:23,437 - DEBUG - Control-node 10.0.0.133 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-60337406:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-60337406', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6823295195552566675', 'uuid-lslong': '12665393127149246957'}, 'enable': 'true', 'created': '2026-04-02T02:40:22', 'last-modified': '2026-04-02T02:40:22', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.807412'}
2026-04-02 02:40:23,443 - DEBUG - Control-node 10.0.0.48 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-60337406:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-60337406', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6823295195552566675', 'uuid-lslong': '12665393127149246957'}, 'enable': 'true', 'created': '2026-04-02T02:40:22', 'last-modified': '2026-04-02T02:40:22', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.808152'}
2026-04-02 02:40:23,443 - INFO - Verifications in Control node for IPAM: ipam3 passed
2026-04-02 02:40:23,765 - DEBUG - Response for create_network : {'network': {'id': '495b3b80-973a-4b33-8588-451ce3b87b31', 'name': 'ctest-VN1-97689077', 'tenant_id': '2062099b383c4bc680f80875eb4a8dd6', 'project_id': '2062099b383c4bc680f80875eb4a8dd6', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-60337406', 'ctest-VN1-97689077'], 'port_security_enabled': True, 'description': ''}}
2026-04-02 02:40:24,067 - DEBUG - Response for create_subnet : {'subnet': {'id': '78ecd33b-6e74-4420-87bf-5cdd0a8f8312', 'name': '', 'tenant_id': '2062099b383c4bc680f80875eb4a8dd6', 'network_id': '495b3b80-973a-4b33-8588-451ce3b87b31', 'ip_version': 4, 'cidr': '4.37.60.64/26', 'allocation_pools': [{'start': '4.37.60.66', 'end': '4.37.60.126'}], 'gateway_ip': '4.37.60.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '4.37.60.66', 'tags': [], 'project_id': '2062099b383c4bc680f80875eb4a8dd6'}}
2026-04-02 02:40:24,260 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd729b2cc-0588-4076-b00d-f27bf7f6697c', 'name': '', 'tenant_id': '2062099b383c4bc680f80875eb4a8dd6', 'network_id': '495b3b80-973a-4b33-8588-451ce3b87b31', 'ip_version': 6, 'cidr': '24b8:397d:9e98:cdd0:a8c8:2f93::/96', 'allocation_pools': [{'start': '24b8:397d:9e98:cdd0:a8c8:2f93:0:2', 'end': '24b8:397d:9e98:cdd0:a8c8:2f93:ffff:fffe'}], 'gateway_ip': '24b8:397d:9e98:cdd0:a8c8:2f93:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '24b8:397d:9e98:cdd0:a8c8:2f93:0:2', 'tags': [], 'project_id': '2062099b383c4bc680f80875eb4a8dd6'}}
2026-04-02 02:40:24,295 - INFO - Created VN ctest-VN1-97689077
2026-04-02 02:40:24,311 - DEBUG - VN ctest-VN1-97689077 UUID is 495b3b80-973a-4b33-8588-451ce3b87b31
2026-04-02 02:40:24,521 - DEBUG - Fetched VN: default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077(495b3b80-973a-4b33-8588-451ce3b87b31) with subnets ['4.37.60.64/26', '24b8:397d:9e98:cdd0:a8c8:2f93::/96']
2026-04-02 02:40:24,807 - DEBUG - Response for create_network : {'network': {'id': '736d8dd1-4869-4a5f-9672-e3b95ab514b0', 'name': 'ctest-VN2-24772300', 'tenant_id': '2062099b383c4bc680f80875eb4a8dd6', 'project_id': '2062099b383c4bc680f80875eb4a8dd6', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-60337406', 'ctest-VN2-24772300'], 'port_security_enabled': True, 'description': ''}}
2026-04-02 02:40:25,080 - DEBUG - Response for create_subnet : {'subnet': {'id': 'bb529708-37e4-4fe6-95ce-1543a101f2d1', 'name': '', 'tenant_id': '2062099b383c4bc680f80875eb4a8dd6', 'network_id': '736d8dd1-4869-4a5f-9672-e3b95ab514b0', 'ip_version': 4, 'cidr': '11.206.39.128/26', 'allocation_pools': [{'start': '11.206.39.130', 'end': '11.206.39.190'}], 'gateway_ip': '11.206.39.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '11.206.39.130', 'tags': [], 'project_id': '2062099b383c4bc680f80875eb4a8dd6'}}
2026-04-02 02:40:25,269 - DEBUG - Response for create_subnet : {'subnet': {'id': '6b139d9a-7994-4659-bb51-32279586660c', 'name': '', 'tenant_id': '2062099b383c4bc680f80875eb4a8dd6', 'network_id': '736d8dd1-4869-4a5f-9672-e3b95ab514b0', 'ip_version': 6, 'cidr': '2052:b0e3:92c2:eb3b:64f2:bbfd::/96', 'allocation_pools': [{'start': '2052:b0e3:92c2:eb3b:64f2:bbfd:0:2', 'end': '2052:b0e3:92c2:eb3b:64f2:bbfd:ffff:fffe'}], 'gateway_ip': '2052:b0e3:92c2:eb3b:64f2:bbfd:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '2052:b0e3:92c2:eb3b:64f2:bbfd:0:2', 'tags': [], 'project_id': '2062099b383c4bc680f80875eb4a8dd6'}}
2026-04-02 02:40:25,294 - INFO - Created VN ctest-VN2-24772300
2026-04-02 02:40:25,307 - DEBUG - VN ctest-VN2-24772300 UUID is 736d8dd1-4869-4a5f-9672-e3b95ab514b0
2026-04-02 02:40:25,719 - DEBUG - Response for create_network : {'network': {'id': 'a8f11a51-8267-48ee-b995-64c569ccbb27', 'name': 'ctest-VN3-67864215', 'tenant_id': '2062099b383c4bc680f80875eb4a8dd6', 'project_id': '2062099b383c4bc680f80875eb4a8dd6', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-60337406', 'ctest-VN3-67864215'], 'port_security_enabled': True, 'description': ''}}
2026-04-02 02:40:25,998 - DEBUG - Response for create_subnet : {'subnet': {'id': '9caa487e-915e-4aa4-81b3-c837ba67f7e7', 'name': '', 'tenant_id': '2062099b383c4bc680f80875eb4a8dd6', 'network_id': 'a8f11a51-8267-48ee-b995-64c569ccbb27', 'ip_version': 4, 'cidr': '112.160.211.128/26', 'allocation_pools': [{'start': '112.160.211.130', 'end': '112.160.211.190'}], 'gateway_ip': '112.160.211.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '112.160.211.130', 'tags': [], 'project_id': '2062099b383c4bc680f80875eb4a8dd6'}}
2026-04-02 02:40:26,249 - DEBUG - Response for create_subnet : {'subnet': {'id': '6c7e2ebe-7408-43d9-82f1-8ea59b7d4325', 'name': '', 'tenant_id': '2062099b383c4bc680f80875eb4a8dd6', 'network_id': 'a8f11a51-8267-48ee-b995-64c569ccbb27', 'ip_version': 6, 'cidr': '3219:f069:c763:f1a3:8624:dff0::/96', 'allocation_pools': [{'start': '3219:f069:c763:f1a3:8624:dff0:0:2', 'end': '3219:f069:c763:f1a3:8624:dff0:ffff:fffe'}], 'gateway_ip': '3219:f069:c763:f1a3:8624:dff0:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '3219:f069:c763:f1a3:8624:dff0:0:2', 'tags': [], 'project_id': '2062099b383c4bc680f80875eb4a8dd6'}}
2026-04-02 02:40:26,273 - INFO - Created VN ctest-VN3-67864215
2026-04-02 02:40:26,285 - DEBUG - VN ctest-VN3-67864215 UUID is a8f11a51-8267-48ee-b995-64c569ccbb27
2026-04-02 02:40:26,393 - DEBUG - Requesting: http://10.0.0.73:8082/domains
2026-04-02 02:40:26,400 - DEBUG - Requesting: http://10.0.0.73:8082/domain/2c17115e-1a6e-47b0-9bfc-9e380916d58b
2026-04-02 02:40:26,409 - DEBUG - Requesting: http://10.0.0.73:8082/project/2062099b-383c-4bc6-80f8-0875eb4a8dd6
2026-04-02 02:40:26,498 - DEBUG - Requesting: http://10.0.0.73:8082/virtual-network/495b3b80-973a-4b33-8588-451ce3b87b31
2026-04-02 02:40:26,508 - DEBUG - Requesting: http://10.0.0.73:8082/virtual-network/495b3b80-973a-4b33-8588-451ce3b87b31
2026-04-02 02:40:26,517 - DEBUG - Requesting: http://10.0.0.73:8082/routing-instance/8f3b9ada-5dde-4c78-8867-d80e1d25751a
2026-04-02 02:40:26,525 - DEBUG - Requesting: http://10.0.0.73:8082/routing-instance/8f3b9ada-5dde-4c78-8867-d80e1d25751a
2026-04-02 02:40:26,531 - DEBUG - Requesting: http://10.0.0.73:8082/route-target/6d469753-7bfb-4b1d-96fb-83618b9fc5d1
2026-04-02 02:40:26,537 - DEBUG - Route Targets: ['target:64512:8000004']
2026-04-02 02:40:26,537 - DEBUG - Requesting: http://10.0.0.73:8082/virtual-network/495b3b80-973a-4b33-8588-451ce3b87b31
2026-04-02 02:40:26,546 - DEBUG - Requesting: http://10.0.0.73:8082/routing-instance/8f3b9ada-5dde-4c78-8867-d80e1d25751a
2026-04-02 02:40:26,562 - INFO - Verified VN network id 10 for VN 495b3b80-973a-4b33-8588-451ce3b87b31
2026-04-02 02:40:26,562 - INFO - Verifications in API Server for VN ctest-VN1-97689077 passed
2026-04-02 02:40:26,562 - DEBUG - Requesting: http://10.0.0.73:8082/virtual-network/495b3b80-973a-4b33-8588-451ce3b87b31
2026-04-02 02:40:26,571 - DEBUG - Requesting: http://10.0.0.73:8082/routing-instance/8f3b9ada-5dde-4c78-8867-d80e1d25751a
2026-04-02 02:40:26,579 - DEBUG - Requesting: http://10.0.0.73:8082/routing-instance/8f3b9ada-5dde-4c78-8867-d80e1d25751a
2026-04-02 02:40:26,588 - DEBUG - Requesting: http://10.0.0.73:8082/route-target/6d469753-7bfb-4b1d-96fb-83618b9fc5d1
2026-04-02 02:40:26,598 - DEBUG - Control-node 10.0.0.73 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-60337406', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5285884011128441651', 'uuid-lslong': '9622016594258983729'}, 'enable': 'true', 'created': '2026-04-02T02:40:23', 'last-modified': '2026-04-02T02:40:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.392765'}
2026-04-02 02:40:26,603 - DEBUG - Route Targets: ['target:64512:8000004']
2026-04-02 02:40:26,608 - DEBUG - Control-node 10.0.0.133 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-60337406', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5285884011128441651', 'uuid-lslong': '9622016594258983729'}, 'enable': 'true', 'created': '2026-04-02T02:40:23', 'last-modified': '2026-04-02T02:40:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.402659'}
2026-04-02 02:40:26,614 - DEBUG - Route Targets: ['target:64512:8000004']
2026-04-02 02:40:26,619 - DEBUG - Control-node 10.0.0.48 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-60337406', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5285884011128441651', 'uuid-lslong': '9622016594258983729'}, 'enable': 'true', 'created': '2026-04-02T02:40:23', 'last-modified': '2026-04-02T02:40:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.413182'}
2026-04-02 02:40:26,625 - DEBUG - Route Targets: ['target:64512:8000004']
2026-04-02 02:40:26,625 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN1-97689077 passed
2026-04-02 02:40:26,626 - DEBUG - ====Verifying policy data for ctest-VN1-97689077 in API_Server ======
2026-04-02 02:40:26,626 - DEBUG - Requesting: http://10.0.0.73:8082/domains
2026-04-02 02:40:26,633 - DEBUG - Requesting: http://10.0.0.73:8082/domain/2c17115e-1a6e-47b0-9bfc-9e380916d58b
2026-04-02 02:40:26,644 - DEBUG - Requesting: http://10.0.0.73:8082/project/2062099b-383c-4bc6-80f8-0875eb4a8dd6
2026-04-02 02:40:26,737 - DEBUG - Requesting: http://10.0.0.73:8082/virtual-network/495b3b80-973a-4b33-8588-451ce3b87b31
2026-04-02 02:40:26,748 - DEBUG - =>VN ctest-VN1-97689077 has no policy to be verified
2026-04-02 02:40:26,748 - DEBUG - Verifying the vn in opserver
2026-04-02 02:40:26,748 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077 virtual network link through opserver 10.0.0.73
2026-04-02 02:40:26,749 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-networks
2026-04-02 02:40:27,039 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-networks
2026-04-02 02:40:27,092 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077', 'href': 'http://10.0.0.73:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077?flat'}
2026-04-02 02:40:27,093 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077 virtual network link through opserver 10.0.0.133
2026-04-02 02:40:27,093 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/virtual-networks
2026-04-02 02:40:27,385 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/virtual-networks
2026-04-02 02:40:27,445 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077', 'href': 'http://10.0.0.133:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077?flat'}
2026-04-02 02:40:27,445 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077 virtual network link through opserver 10.0.0.48
2026-04-02 02:40:27,445 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2026-04-02 02:40:27,735 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2026-04-02 02:40:27,804 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077?flat'}
2026-04-02 02:40:27,804 - INFO - Validated that VN default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077 is found in opserver
2026-04-02 02:40:27,825 - DEBUG - Do not have enough data to verify VN in agent
2026-04-02 02:40:27,835 - DEBUG - VRF ids for VN ctest-VN1-97689077: {}
2026-04-02 02:40:27,835 - DEBUG - Requesting: http://10.0.0.73:8082/domains
2026-04-02 02:40:27,841 - DEBUG - Requesting: http://10.0.0.73:8082/domain/2c17115e-1a6e-47b0-9bfc-9e380916d58b
2026-04-02 02:40:27,852 - DEBUG - Requesting: http://10.0.0.73:8082/project/2062099b-383c-4bc6-80f8-0875eb4a8dd6
2026-04-02 02:40:27,939 - DEBUG - Requesting: http://10.0.0.73:8082/virtual-network/736d8dd1-4869-4a5f-9672-e3b95ab514b0
2026-04-02 02:40:27,950 - DEBUG - Requesting: http://10.0.0.73:8082/virtual-network/736d8dd1-4869-4a5f-9672-e3b95ab514b0
2026-04-02 02:40:27,958 - DEBUG - Requesting: http://10.0.0.73:8082/routing-instance/2a60654a-58a7-47ad-a4c3-484ad4fdfd2b
2026-04-02 02:40:27,964 - DEBUG - Requesting: http://10.0.0.73:8082/routing-instance/2a60654a-58a7-47ad-a4c3-484ad4fdfd2b
2026-04-02 02:40:27,970 - DEBUG - Requesting: http://10.0.0.73:8082/route-target/6d668e51-93ed-4d50-b7d4-dd827d207f4c
2026-04-02 02:40:27,976 - DEBUG - Route Targets: ['target:64512:8000005']
2026-04-02 02:40:27,976 - DEBUG - Requesting: http://10.0.0.73:8082/virtual-network/736d8dd1-4869-4a5f-9672-e3b95ab514b0
2026-04-02 02:40:27,984 - DEBUG - Requesting: http://10.0.0.73:8082/routing-instance/2a60654a-58a7-47ad-a4c3-484ad4fdfd2b
2026-04-02 02:40:28,000 - INFO - Verified VN network id 11 for VN 736d8dd1-4869-4a5f-9672-e3b95ab514b0
2026-04-02 02:40:28,000 - INFO - Verifications in API Server for VN ctest-VN2-24772300 passed
2026-04-02 02:40:28,000 - DEBUG - Requesting: http://10.0.0.73:8082/virtual-network/736d8dd1-4869-4a5f-9672-e3b95ab514b0
2026-04-02 02:40:28,008 - DEBUG - Requesting: http://10.0.0.73:8082/routing-instance/2a60654a-58a7-47ad-a4c3-484ad4fdfd2b
2026-04-02 02:40:28,014 - DEBUG - Requesting: http://10.0.0.73:8082/routing-instance/2a60654a-58a7-47ad-a4c3-484ad4fdfd2b
2026-04-02 02:40:28,020 - DEBUG - Requesting: http://10.0.0.73:8082/route-target/6d668e51-93ed-4d50-b7d4-dd827d207f4c
2026-04-02 02:40:28,031 - DEBUG - Control-node 10.0.0.73 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN2-24772300', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-60337406', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8317460016825715295', 'uuid-lslong': '10840977638264476848'}, 'enable': 'true', 'created': '2026-04-02T02:40:24', 'last-modified': '2026-04-02T02:40:25', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.808990'}
2026-04-02 02:40:28,037 - DEBUG - Route Targets: ['target:64512:8000005']
2026-04-02 02:40:28,042 - DEBUG - Control-node 10.0.0.133 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN2-24772300', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-60337406', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8317460016825715295', 'uuid-lslong': '10840977638264476848'}, 'enable': 'true', 'created': '2026-04-02T02:40:24', 'last-modified': '2026-04-02T02:40:25', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.830351'}
2026-04-02 02:40:28,047 - DEBUG - Route Targets: ['target:64512:8000005']
2026-04-02 02:40:28,052 - DEBUG - Control-node 10.0.0.48 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN2-24772300', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-60337406', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8317460016825715295', 'uuid-lslong': '10840977638264476848'}, 'enable': 'true', 'created': '2026-04-02T02:40:24', 'last-modified': '2026-04-02T02:40:25', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.840708'}
2026-04-02 02:40:28,058 - DEBUG - Route Targets: ['target:64512:8000005']
2026-04-02 02:40:28,058 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN2-24772300 passed
2026-04-02 02:40:28,059 - DEBUG - ====Verifying policy data for ctest-VN2-24772300 in API_Server ======
2026-04-02 02:40:28,059 - DEBUG - Requesting: http://10.0.0.73:8082/domains
2026-04-02 02:40:28,064 - DEBUG - Requesting: http://10.0.0.73:8082/domain/2c17115e-1a6e-47b0-9bfc-9e380916d58b
2026-04-02 02:40:28,075 - DEBUG - Requesting: http://10.0.0.73:8082/project/2062099b-383c-4bc6-80f8-0875eb4a8dd6
2026-04-02 02:40:28,179 - DEBUG - Requesting: http://10.0.0.73:8082/virtual-network/736d8dd1-4869-4a5f-9672-e3b95ab514b0
2026-04-02 02:40:28,189 - DEBUG - =>VN ctest-VN2-24772300 has no policy to be verified
2026-04-02 02:40:28,189 - DEBUG - Verifying the vn in opserver
2026-04-02 02:40:28,189 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN2-24772300 virtual network link through opserver 10.0.0.73
2026-04-02 02:40:28,189 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-networks
2026-04-02 02:40:28,256 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN2-24772300', 'href': 'http://10.0.0.73:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN2-24772300?flat'}
2026-04-02 02:40:28,256 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN2-24772300 virtual network link through opserver 10.0.0.133
2026-04-02 02:40:28,256 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/virtual-networks
2026-04-02 02:40:28,318 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN2-24772300', 'href': 'http://10.0.0.133:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN2-24772300?flat'}
2026-04-02 02:40:28,318 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN2-24772300 virtual network link through opserver 10.0.0.48
2026-04-02 02:40:28,318 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2026-04-02 02:40:28,375 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN2-24772300', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN2-24772300?flat'}
2026-04-02 02:40:28,375 - INFO - Validated that VN default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN2-24772300 is found in opserver
2026-04-02 02:40:28,397 - DEBUG - Do not have enough data to verify VN in agent
2026-04-02 02:40:28,407 - DEBUG - VRF ids for VN ctest-VN2-24772300: {}
2026-04-02 02:40:28,407 - DEBUG - Requesting: http://10.0.0.73:8082/domains
2026-04-02 02:40:28,413 - DEBUG - Requesting: http://10.0.0.73:8082/domain/2c17115e-1a6e-47b0-9bfc-9e380916d58b
2026-04-02 02:40:28,425 - DEBUG - Requesting: http://10.0.0.73:8082/project/2062099b-383c-4bc6-80f8-0875eb4a8dd6
2026-04-02 02:40:28,525 - DEBUG - Requesting: http://10.0.0.73:8082/virtual-network/a8f11a51-8267-48ee-b995-64c569ccbb27
2026-04-02 02:40:28,537 - DEBUG - Requesting: http://10.0.0.73:8082/virtual-network/a8f11a51-8267-48ee-b995-64c569ccbb27
2026-04-02 02:40:28,545 - DEBUG - Requesting: http://10.0.0.73:8082/routing-instance/100984c2-8b3b-42b6-a8e7-f111b198e157
2026-04-02 02:40:28,554 - DEBUG - Requesting: http://10.0.0.73:8082/routing-instance/100984c2-8b3b-42b6-a8e7-f111b198e157
2026-04-02 02:40:28,561 - DEBUG - Requesting: http://10.0.0.73:8082/route-target/ea5b6741-f6e4-492a-900c-49766116cbe2
2026-04-02 02:40:28,571 - DEBUG - Route Targets: ['target:64512:8000006']
2026-04-02 02:40:28,571 - DEBUG - Requesting: http://10.0.0.73:8082/virtual-network/a8f11a51-8267-48ee-b995-64c569ccbb27
2026-04-02 02:40:28,580 - DEBUG - Requesting: http://10.0.0.73:8082/routing-instance/100984c2-8b3b-42b6-a8e7-f111b198e157
2026-04-02 02:40:28,607 - INFO - Verified VN network id 12 for VN a8f11a51-8267-48ee-b995-64c569ccbb27
2026-04-02 02:40:28,607 - INFO - Verifications in API Server for VN ctest-VN3-67864215 passed
2026-04-02 02:40:28,607 - DEBUG - Requesting: http://10.0.0.73:8082/virtual-network/a8f11a51-8267-48ee-b995-64c569ccbb27
2026-04-02 02:40:28,616 - DEBUG - Requesting: http://10.0.0.73:8082/routing-instance/100984c2-8b3b-42b6-a8e7-f111b198e157
2026-04-02 02:40:28,624 - DEBUG - Requesting: http://10.0.0.73:8082/routing-instance/100984c2-8b3b-42b6-a8e7-f111b198e157
2026-04-02 02:40:28,630 - DEBUG - Requesting: http://10.0.0.73:8082/route-target/ea5b6741-f6e4-492a-900c-49766116cbe2
2026-04-02 02:40:28,641 - DEBUG - Control-node 10.0.0.73 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN3-67864215', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-60337406', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12173540205141641454', 'uuid-lslong': '13372705467592915751'}, 'enable': 'true', 'created': '2026-04-02T02:40:25', 'last-modified': '2026-04-02T02:40:26', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.452602'}
2026-04-02 02:40:28,646 - DEBUG - Route Targets: ['target:64512:8000006']
2026-04-02 02:40:28,651 - DEBUG - Control-node 10.0.0.133 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN3-67864215', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-60337406', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12173540205141641454', 'uuid-lslong': '13372705467592915751'}, 'enable': 'true', 'created': '2026-04-02T02:40:25', 'last-modified': '2026-04-02T02:40:26', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.456644'}
2026-04-02 02:40:28,657 - DEBUG - Route Targets: ['target:64512:8000006']
2026-04-02 02:40:28,663 - DEBUG - Control-node 10.0.0.48 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN3-67864215', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-60337406', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12173540205141641454', 'uuid-lslong': '13372705467592915751'}, 'enable': 'true', 'created': '2026-04-02T02:40:25', 'last-modified': '2026-04-02T02:40:26', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.467611'}
2026-04-02 02:40:28,669 - DEBUG - Route Targets: ['target:64512:8000006']
2026-04-02 02:40:28,669 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN3-67864215 passed
2026-04-02 02:40:28,669 - DEBUG - ====Verifying policy data for ctest-VN3-67864215 in API_Server ======
2026-04-02 02:40:28,669 - DEBUG - Requesting: http://10.0.0.73:8082/domains
2026-04-02 02:40:28,675 - DEBUG - Requesting: http://10.0.0.73:8082/domain/2c17115e-1a6e-47b0-9bfc-9e380916d58b
2026-04-02 02:40:28,685 - DEBUG - Requesting: http://10.0.0.73:8082/project/2062099b-383c-4bc6-80f8-0875eb4a8dd6
2026-04-02 02:40:28,778 - DEBUG - Requesting: http://10.0.0.73:8082/virtual-network/a8f11a51-8267-48ee-b995-64c569ccbb27
2026-04-02 02:40:28,787 - DEBUG - =>VN ctest-VN3-67864215 has no policy to be verified
2026-04-02 02:40:28,787 - DEBUG - Verifying the vn in opserver
2026-04-02 02:40:28,787 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN3-67864215 virtual network link through opserver 10.0.0.73
2026-04-02 02:40:28,787 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-networks
2026-04-02 02:40:28,848 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN3-67864215', 'href': 'http://10.0.0.73:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN3-67864215?flat'}
2026-04-02 02:40:28,848 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN3-67864215 virtual network link through opserver 10.0.0.133
2026-04-02 02:40:28,848 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/virtual-networks
2026-04-02 02:40:28,905 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN3-67864215', 'href': 'http://10.0.0.133:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN3-67864215?flat'}
2026-04-02 02:40:28,905 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN3-67864215 virtual network link through opserver 10.0.0.48
2026-04-02 02:40:28,905 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2026-04-02 02:40:28,961 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN3-67864215', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN3-67864215?flat'}
2026-04-02 02:40:28,961 - INFO - Validated that VN default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN3-67864215 is found in opserver
2026-04-02 02:40:28,983 - DEBUG - Do not have enough data to verify VN in agent
2026-04-02 02:40:28,992 - DEBUG - VRF ids for VN ctest-VN3-67864215: {}
2026-04-02 02:40:28,998 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077, 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-60337406:ctest-VN3-67864215, 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]
2026-04-02 02:40:29,281 - DEBUG - No source network defined
2026-04-02 02:40:29,281 - DEBUG - No source network defined
2026-04-02 02:40:29,282 - 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-60337406:ctest-policy13-04312563, 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-60337406:ctest-policy13-04312563, 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]
2026-04-02 02:40:29,549 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN2-24772300, 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-60337406:ctest-VN1-97689077, 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-60337406:ctest-VN2-24772300, 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-60337406:ctest-VN1-97689077, 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]
2026-04-02 02:40:29,794 - DEBUG - Requesting: http://10.0.0.73:8082/domains
2026-04-02 02:40:29,800 - DEBUG - Requesting: http://10.0.0.73:8082/domain/2c17115e-1a6e-47b0-9bfc-9e380916d58b
2026-04-02 02:40:29,811 - DEBUG - Requesting: http://10.0.0.73:8082/project/2062099b-383c-4bc6-80f8-0875eb4a8dd6
2026-04-02 02:40:29,903 - DEBUG - Requesting: http://10.0.0.73:8082/virtual-network/495b3b80-973a-4b33-8588-451ce3b87b31
2026-04-02 02:40:29,912 - DEBUG - Setup step: Associating the policy to VN'
2026-04-02 02:40:30,013 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '495b3b80-973a-4b33-8588-451ce3b87b31', 'name': 'ctest-VN1-97689077', 'tenant_id': '2062099b383c4bc680f80875eb4a8dd6', 'project_id': '2062099b383c4bc680f80875eb4a8dd6', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['78ecd33b-6e74-4420-87bf-5cdd0a8f8312', 'd729b2cc-0588-4076-b00d-f27bf7f6697c'], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-60337406', 'ctest-VN1-97689077'], 'policys': [['default-domain', 'ctest-TestPolicyAclIpv4v6-60337406', 'ctest-policy12-01072489'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-60337406', 'ctest-policy13-04312563']], 'subnet_ipam': [{'subnet_cidr': '4.37.60.64/26', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-60337406', 'ipam1']}, {'subnet_cidr': '24b8:397d:9e98:cdd0:a8c8:2f93::/96', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-60337406', 'ipam1']}], 'port_security_enabled': True, 'description': ''}}
2026-04-02 02:40:30,184 - DEBUG - Associated Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-60337406', 'ctest-policy12-01072489'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-60337406', 'ctest-policy13-04312563']] to ctest-VN1-97689077
2026-04-02 02:40:30,185 - DEBUG - Requesting: http://10.0.0.73:8082/domains
2026-04-02 02:40:30,190 - DEBUG - Requesting: http://10.0.0.73:8082/domain/2c17115e-1a6e-47b0-9bfc-9e380916d58b
2026-04-02 02:40:30,201 - DEBUG - Requesting: http://10.0.0.73:8082/project/2062099b-383c-4bc6-80f8-0875eb4a8dd6
2026-04-02 02:40:30,292 - DEBUG - Requesting: http://10.0.0.73:8082/virtual-network/736d8dd1-4869-4a5f-9672-e3b95ab514b0
2026-04-02 02:40:30,303 - DEBUG - Setup step: Associating the policy to VN'
2026-04-02 02:40:30,408 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '736d8dd1-4869-4a5f-9672-e3b95ab514b0', 'name': 'ctest-VN2-24772300', 'tenant_id': '2062099b383c4bc680f80875eb4a8dd6', 'project_id': '2062099b383c4bc680f80875eb4a8dd6', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['bb529708-37e4-4fe6-95ce-1543a101f2d1', '6b139d9a-7994-4659-bb51-32279586660c'], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-60337406', 'ctest-VN2-24772300'], 'policys': [['default-domain', 'ctest-TestPolicyAclIpv4v6-60337406', 'ctest-policy21-99084139']], 'subnet_ipam': [{'subnet_cidr': '11.206.39.128/26', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-60337406', 'ipam2']}, {'subnet_cidr': '2052:b0e3:92c2:eb3b:64f2:bbfd::/96', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-60337406', 'ipam2']}], 'port_security_enabled': True, 'description': ''}}
2026-04-02 02:40:30,532 - DEBUG - Associated Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-60337406', 'ctest-policy21-99084139']] to ctest-VN2-24772300
2026-04-02 02:40:30,628 - DEBUG - Services list from nova: [, , , ]
2026-04-02 02:40:31,765 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5575-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5575-2)
2026-04-02 02:40:33,218 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5575-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5575-1)
2026-04-02 02:40:34,549 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5575-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5575-2)
2026-04-02 02:40:34,645 - DEBUG - VM is in ACTIVE state now
2026-04-02 02:40:34,646 - INFO - VM name : ctest-VM11-35965779
2026-04-02 02:40:34,732 - DEBUG - VM ctest-VM11-35965779 ID is 16f34cb9-56ae-4bbd-8f29-5c4886c24fac
2026-04-02 02:40:34,818 - DEBUG - VM is in ACTIVE state now
2026-04-02 02:40:34,849 - DEBUG - VM ctest-VM11-35965779 launched on Node an-jenkins-deploy-platform-ansible-os-5575-2
2026-04-02 02:40:34,971 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/16f34cb9-56ae-4bbd-8f29-5c4886c24fac
2026-04-02 02:40:35,344 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/16f34cb9-56ae-4bbd-8f29-5c4886c24fac
2026-04-02 02:40:35,397 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/16f34cb9-56ae-4bbd-8f29-5c4886c24fac
2026-04-02 02:40:35,406 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine-interface/5392b43c-f971-43ab-a11a-3ab8fcb81f21
2026-04-02 02:40:35,416 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/16f34cb9-56ae-4bbd-8f29-5c4886c24fac
2026-04-02 02:40:35,424 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine-interface/5392b43c-f971-43ab-a11a-3ab8fcb81f21
2026-04-02 02:40:35,439 - DEBUG - Requesting: http://10.0.0.48:8082/instance-ip/2dc5f96b-42ac-41a7-a676-6eeb4307ad08
2026-04-02 02:40:35,460 - DEBUG - Requesting: http://10.0.0.48:8082/instance-ip/e86eb7c2-07a7-4bb7-abbd-e44e55e6ebd4
2026-04-02 02:40:35,473 - DEBUG - Verifying in api server 10.0.0.48
2026-04-02 02:40:35,473 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/16f34cb9-56ae-4bbd-8f29-5c4886c24fac
2026-04-02 02:40:35,495 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine-interface/5392b43c-f971-43ab-a11a-3ab8fcb81f21
2026-04-02 02:40:35,505 - DEBUG - Requesting: http://10.0.0.48:8082/instance-ip/2dc5f96b-42ac-41a7-a676-6eeb4307ad08
2026-04-02 02:40:35,514 - DEBUG - Requesting: http://10.0.0.48:8082/instance-ip/e86eb7c2-07a7-4bb7-abbd-e44e55e6ebd4
2026-04-02 02:40:35,522 - INFO - VM ctest-VM11-35965779 verfication in all API Servers passed
2026-04-02 02:40:35,540 - DEBUG - VM ctest-VM11-35965779 Tap interface: {'index': '3', 'name': 'tap5392b43c-f9', 'uuid': '5392b43c-f971-43ab-a11a-3ab8fcb81f21', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077:ctest-VN1-97689077', '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': '10', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077', 'vm_uuid': '16f34cb9-56ae-4bbd-8f29-5c4886c24fac', 'vm_name': 'ctest-VM11-35965779', 'ip_addr': '4.37.60.67', 'mac_addr': '02:53:92:b4:3c:f9', '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-60337406:5392b43c-f971-43ab-a11a-3ab8fcb81f21', 'sg_uuid_list': ['77fedd23-5b21-4288-b2b7-690f8fde5b83'], 'static_route_list': None, 'vm_project_uuid': '2062099b-383c-4bc6-80f8-0875eb4a8dd6', 'admin_state': 'Enabled', 'flow_key_idx': '31', 'allowed_address_pair_list': None, 'ip6_addr': '24b8:397d:9e98:cdd0:a8c8:2f93: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': []}
2026-04-02 02:40:35,540 - DEBUG - Agent 10.0.0.128 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077:ctest-VN1-97689077
2026-04-02 02:40:35,547 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077:ctest-VN1-97689077', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077', 'table_label': '-1', 'vxlan_id': '10', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.14:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]}
2026-04-02 02:40:35,645 - DEBUG - VM ctest-VM11-35965779 : Tap interface tap5392b43c-f9 is set to Active
2026-04-02 02:40:35,645 - DEBUG - Tap interface tap5392b43c-f9 detail : {'index': '3', 'name': 'tap5392b43c-f9', 'uuid': '5392b43c-f971-43ab-a11a-3ab8fcb81f21', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077:ctest-VN1-97689077', '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': '10', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077', 'vm_uuid': '16f34cb9-56ae-4bbd-8f29-5c4886c24fac', 'vm_name': 'ctest-VM11-35965779', 'ip_addr': '4.37.60.67', 'mac_addr': '02:53:92:b4:3c:f9', '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-60337406:5392b43c-f971-43ab-a11a-3ab8fcb81f21', 'sg_uuid_list': ['77fedd23-5b21-4288-b2b7-690f8fde5b83'], 'static_route_list': None, 'vm_project_uuid': '2062099b-383c-4bc6-80f8-0875eb4a8dd6', 'admin_state': 'Enabled', 'flow_key_idx': '31', 'allowed_address_pair_list': None, 'ip6_addr': '24b8:397d:9e98:cdd0:a8c8:2f93: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': []}
2026-04-02 02:40:35,754 - DEBUG - Starting Layer 2 verification in Agent
2026-04-02 02:40:35,763 - DEBUG - Layer 2 path is seen for VM MAC 02:53:92:b4:3c:f9 in agent 10.0.0.128
2026-04-02 02:40:35,764 - DEBUG - Active layer 2 route in agent is present for VMI tap5392b43c-f9
2026-04-02 02:40:35,764 - DEBUG - L2 label(29) matches bw route table and interface table
2026-04-02 02:40:35,790 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077 is found in Agent of node 10.0.0.128
2026-04-02 02:40:35,904 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077 is consistent in agent 10.0.0.128
2026-04-02 02:40:35,904 - DEBUG - Route for VM IP ['4.37.60.67', '24b8:397d:9e98:cdd0:a8c8:2f93:0:3'] is consistent in agent 10.0.0.128
2026-04-02 02:40:35,904 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077 verification for VM ctest-VM11-35965779 in Agent 10.0.0.128 passed
2026-04-02 02:40:36,022 - DEBUG - Starting all layer 2 verification in agent 10.0.0.128
2026-04-02 02:40:36,124 - DEBUG - Route for VM MAC 02:53:92:b4:3c:f9 is consistent in agent 10.0.0.128
2026-04-02 02:40:39,272 - 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')
2026-04-02 02:40:39,272 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-35965779 failed!
2026-04-02 02:40:39,285 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077 is 4.37.60.65 and allocation pool is NOT set
2026-04-02 02:40:39,286 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077 is 24b8:397d:9e98:cdd0:a8c8:2f93:0:1 and allocation pool is NOT set
2026-04-02 02:40:43,371 - 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 1026ms')
2026-04-02 02:40:43,371 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-35965779 failed!
2026-04-02 02:40:43,388 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077 is 4.37.60.65 and allocation pool is NOT set
2026-04-02 02:40:43,388 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077 is 24b8:397d:9e98:cdd0:a8c8:2f93:0:1 and allocation pool is NOT set
2026-04-02 02:40:47,473 - 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')
2026-04-02 02:40:47,473 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-35965779 failed!
2026-04-02 02:40:47,489 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077 is 4.37.60.65 and allocation pool is NOT set
2026-04-02 02:40:47,489 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077 is 24b8:397d:9e98:cdd0:a8c8:2f93:0:1 and allocation pool is NOT set
2026-04-02 02:40:51,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 1011ms')
2026-04-02 02:40:51,561 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-35965779 failed!
2026-04-02 02:40:51,575 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077 is 4.37.60.65 and allocation pool is NOT set
2026-04-02 02:40:51,575 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077 is 24b8:397d:9e98:cdd0:a8c8:2f93:0:1 and allocation pool is NOT set
2026-04-02 02:40:55,660 - 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 1024ms')
2026-04-02 02:40:55,660 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-35965779 failed!
2026-04-02 02:40:55,674 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077 is 4.37.60.65 and allocation pool is NOT set
2026-04-02 02:40:55,674 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077 is 24b8:397d:9e98:cdd0:a8c8:2f93:0:1 and allocation pool is NOT set
2026-04-02 02:40:59,756 - 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')
2026-04-02 02:40:59,756 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-35965779 failed!
2026-04-02 02:40:59,770 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077 is 4.37.60.65 and allocation pool is NOT set
2026-04-02 02:40:59,770 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077 is 24b8:397d:9e98:cdd0:a8c8:2f93:0:1 and allocation pool is NOT set
2026-04-02 02:41:03,850 - 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')
2026-04-02 02:41:03,851 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-35965779 failed!
2026-04-02 02:41:03,869 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077 is 4.37.60.65 and allocation pool is NOT set
2026-04-02 02:41:03,869 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077 is 24b8:397d:9e98:cdd0:a8c8:2f93:0:1 and allocation pool is NOT set
2026-04-02 02:41:07,947 - 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')
2026-04-02 02:41:07,947 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-35965779 failed!
2026-04-02 02:41:07,962 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077 is 4.37.60.65 and allocation pool is NOT set
2026-04-02 02:41:07,963 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077 is 24b8:397d:9e98:cdd0:a8c8:2f93:0:1 and allocation pool is NOT set
2026-04-02 02:41:12,041 - 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')
2026-04-02 02:41:12,041 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-35965779 failed!
2026-04-02 02:41:12,058 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077 is 4.37.60.65 and allocation pool is NOT set
2026-04-02 02:41:12,058 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077 is 24b8:397d:9e98:cdd0:a8c8:2f93:0:1 and allocation pool is NOT set
2026-04-02 02:41:16,142 - 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 1016ms')
2026-04-02 02:41:16,142 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-35965779 failed!
2026-04-02 02:41:16,156 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077 is 4.37.60.65 and allocation pool is NOT set
2026-04-02 02:41:16,156 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077 is 24b8:397d:9e98:cdd0:a8c8:2f93:0:1 and allocation pool is NOT set
2026-04-02 02:41:20,239 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=5.38 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 1 received, 50% packet loss, time 1023ms\r\nrtt min/avg/max/mdev = 5.377/5.377/5.377/0.000 ms')
2026-04-02 02:41:20,239 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-35965779 passed
2026-04-02 02:41:20,239 - INFO - VM ctest-VM11-35965779 verifications in Compute nodes passed
2026-04-02 02:41:20,506 - DEBUG - Validated VM route 4.37.60.67 in vrouter of 10.0.0.128
2026-04-02 02:41:20,514 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0
2026-04-02 02:41:20,514 - INFO - Validated routes of VM ctest-VM11-35965779 in all vrouters
2026-04-02 02:41:20,953 - DEBUG - Starting all layer2 verification in 10.0.0.73 Control Node
2026-04-02 02:41:21,077 - DEBUG - Layer2 route found for VM MAC 02:53:92:b4:3c:f9 in Control-node 10.0.0.73
2026-04-02 02:41:21,077 - DEBUG - L2 Label for VM ctest-VM11-35965779 same between Control-node 10.0.0.73 and Agent, Expected: 29, Seen: 29
2026-04-02 02:41:21,192 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.73, not supported
2026-04-02 02:41:21,192 - DEBUG - Starting all layer2 verification in 10.0.0.48 Control Node
2026-04-02 02:41:21,327 - DEBUG - Layer2 route found for VM MAC 02:53:92:b4:3c:f9 in Control-node 10.0.0.48
2026-04-02 02:41:21,327 - DEBUG - L2 Label for VM ctest-VM11-35965779 same between Control-node 10.0.0.48 and Agent, Expected: 29, Seen: 29
2026-04-02 02:41:21,446 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.48, not supported
2026-04-02 02:41:21,446 - INFO - Verification in Control-nodes for VM ctest-VM11-35965779 passed
2026-04-02 02:41:21,446 - DEBUG - Verifying the vm in opserver
2026-04-02 02:41:21,446 - DEBUG - Verifying in collector 10.0.0.48 ...
2026-04-02 02:41:21,446 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-machine/16f34cb9-56ae-4bbd-8f29-5c4886c24fac?flat
2026-04-02 02:41:21,462 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-60337406:5392b43c-f971-43ab-a11a-3ab8fcb81f21?flat
2026-04-02 02:41:21,479 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-60337406:5392b43c-f971-43ab-a11a-3ab8fcb81f21?flat
2026-04-02 02:41:21,496 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-60337406:5392b43c-f971-43ab-a11a-3ab8fcb81f21?flat
2026-04-02 02:41:21,513 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-machine/16f34cb9-56ae-4bbd-8f29-5c4886c24fac?flat
2026-04-02 02:41:21,529 - DEBUG - Verifying vm in vn uve
2026-04-02 02:41:21,529 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-60337406:5392b43c-f971-43ab-a11a-3ab8fcb81f21?flat
2026-04-02 02:41:21,546 - DEBUG - VM uve shows interface as {'mac_address': '02:53:92:b4:3c:f9', 'fixed_ip4_list': ['4.37.60.67'], 'gateway': '4.37.60.65', 'ip6_active': True, 'vm_uuid': '16f34cb9-56ae-4bbd-8f29-5c4886c24fac', 'active': True, '__T': 1775097641674040, 'fixed_ip6_list': ['24b8:397d:9e98:cdd0:a8c8:2f93:0:3'], 'tx_vlan': 65535, 'l2_active': True, 'ip6_address': '24b8:397d:9e98:cdd0:a8c8:2f93:0:3', 'ip4_active': True, 'rx_vlan': 65535, 'label': 25, 'vhostuser_mode': 0, 'vm_name': 'ctest-VM11-35965779', 'uuid': '5392b43c-f971-43ab-a11a-3ab8fcb81f21', 'is_health_check_active': True, 'ip_address': '4.37.60.67', 'admin_state': True, 'vn_uuid': '495b3b80-973a-4b33-8588-451ce3b87b31', 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077', 'port_mirror_enabled': False}
2026-04-02 02:41:21,546 - DEBUG - VM uve shows ip address as ['4.37.60.67', '24b8:397d:9e98:cdd0:a8c8:2f93:0:3']
2026-04-02 02:41:21,546 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077
2026-04-02 02:41:21,546 - INFO - Verifying through opserver in 10.0.0.73
2026-04-02 02:41:21,546 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077?flat
2026-04-02 02:41:21,566 - DEBUG - expected vm list 16f34cb9-56ae-4bbd-8f29-5c4886c24fac
2026-04-02 02:41:21,566 - DEBUG - Extracted vm list ['16f34cb9-56ae-4bbd-8f29-5c4886c24fac']
2026-04-02 02:41:21,566 - INFO - VM 16f34cb9-56ae-4bbd-8f29-5c4886c24fac is present in default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077
2026-04-02 02:41:21,566 - DEBUG - Verifying vm in vrouter uve
2026-04-02 02:41:21,566 - DEBUG - Getting info from collector 10.0.0.48..
2026-04-02 02:41:21,566 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-machine/16f34cb9-56ae-4bbd-8f29-5c4886c24fac?flat
2026-04-02 02:41:21,582 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-5575-2']
2026-04-02 02:41:21,582 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5575-2:Compute:contrail-vrouter-agent:0?flat
2026-04-02 02:41:21,614 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5575-2?flat
2026-04-02 02:41:21,643 - DEBUG - VM 16f34cb9-56ae-4bbd-8f29-5c4886c24fac is present in vrouter an-jenkins-deploy-platform-ansible-os-5575-2 uve
2026-04-02 02:41:21,643 - DEBUG - Validated that VM ctest-VM11-35965779 is in Vrouter an-jenkins-deploy-platform-ansible-os-5575-2 UVE
2026-04-02 02:41:21,643 - DEBUG - Verifying vm tap interface/vn in vrouter uve
2026-04-02 02:41:21,649 - DEBUG - Expected tap interface of VM uuid 16f34cb9-56ae-4bbd-8f29-5c4886c24fac is default-domain:ctest-TestPolicyAclIpv4v6-60337406:5392b43c-f971-43ab-a11a-3ab8fcb81f21
2026-04-02 02:41:21,649 - DEBUG - Expected VN of VM uuid 16f34cb9-56ae-4bbd-8f29-5c4886c24fac is default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077
2026-04-02 02:41:21,649 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5575-2:Compute:contrail-vrouter-agent:0?flat
2026-04-02 02:41:21,681 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5575-2?flat
2026-04-02 02:41:21,710 - DEBUG - VM 16f34cb9-56ae-4bbd-8f29-5c4886c24fac is present in vrouter an-jenkins-deploy-platform-ansible-os-5575-2 uve
2026-04-02 02:41:21,710 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-60337406:5392b43c-f971-43ab-a11a-3ab8fcb81f21 of vm 16f34cb9-56ae-4bbd-8f29-5c4886c24fac is present in vrouter an-jenkins-deploy-platform-ansible-os-5575-2 uve
2026-04-02 02:41:21,710 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077 of vm 16f34cb9-56ae-4bbd-8f29-5c4886c24fac is present in vrouter an-jenkins-deploy-platform-ansible-os-5575-2 uve
2026-04-02 02:41:21,710 - DEBUG - Verifying the 16f34cb9-56ae-4bbd-8f29-5c4886c24fac virtual network link through opserver 10.0.0.73
2026-04-02 02:41:21,710 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-machines
2026-04-02 02:41:21,721 - DEBUG - VM link and name as {'name': '16f34cb9-56ae-4bbd-8f29-5c4886c24fac', 'href': 'http://10.0.0.73:8081/analytics/uves/virtual-machine/16f34cb9-56ae-4bbd-8f29-5c4886c24fac?flat'}
2026-04-02 02:41:21,721 - DEBUG - Verifying the 16f34cb9-56ae-4bbd-8f29-5c4886c24fac virtual network link through opserver 10.0.0.133
2026-04-02 02:41:21,721 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/virtual-machines
2026-04-02 02:41:21,733 - DEBUG - VM link and name as {'name': '16f34cb9-56ae-4bbd-8f29-5c4886c24fac', 'href': 'http://10.0.0.133:8081/analytics/uves/virtual-machine/16f34cb9-56ae-4bbd-8f29-5c4886c24fac?flat'}
2026-04-02 02:41:21,733 - DEBUG - Verifying the 16f34cb9-56ae-4bbd-8f29-5c4886c24fac virtual network link through opserver 10.0.0.48
2026-04-02 02:41:21,733 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-machines
2026-04-02 02:41:21,750 - DEBUG - VM link and name as {'name': '16f34cb9-56ae-4bbd-8f29-5c4886c24fac', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-machine/16f34cb9-56ae-4bbd-8f29-5c4886c24fac?flat'}
2026-04-02 02:41:21,750 - INFO - VM ctest-VM11-35965779 validations in Opserver passed
2026-04-02 02:41:21,825 - DEBUG - VM is in ACTIVE state now
2026-04-02 02:41:21,826 - INFO - VM name : ctest-VM21-88314227
2026-04-02 02:41:21,900 - DEBUG - VM ctest-VM21-88314227 ID is 03205e54-f6db-410e-bea8-c25620fbe315
2026-04-02 02:41:21,978 - DEBUG - VM is in ACTIVE state now
2026-04-02 02:41:21,978 - DEBUG - VM ctest-VM21-88314227 launched on Node an-jenkins-deploy-platform-ansible-os-5575-1
2026-04-02 02:41:22,056 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/03205e54-f6db-410e-bea8-c25620fbe315
2026-04-02 02:41:22,070 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/03205e54-f6db-410e-bea8-c25620fbe315
2026-04-02 02:41:22,077 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine-interface/b4a94480-05d3-4ffe-862e-65b9d8e8fb51
2026-04-02 02:41:22,087 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/03205e54-f6db-410e-bea8-c25620fbe315
2026-04-02 02:41:22,095 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine-interface/b4a94480-05d3-4ffe-862e-65b9d8e8fb51
2026-04-02 02:41:22,104 - DEBUG - Requesting: http://10.0.0.48:8082/instance-ip/09d37ba4-cd40-4b4d-b628-2f2a664a3c6c
2026-04-02 02:41:22,122 - DEBUG - Requesting: http://10.0.0.48:8082/instance-ip/a71a51a8-bc1f-4c71-8796-2bf8cde6b716
2026-04-02 02:41:22,148 - DEBUG - Verifying in api server 10.0.0.48
2026-04-02 02:41:22,148 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/03205e54-f6db-410e-bea8-c25620fbe315
2026-04-02 02:41:22,157 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine-interface/b4a94480-05d3-4ffe-862e-65b9d8e8fb51
2026-04-02 02:41:22,167 - DEBUG - Requesting: http://10.0.0.48:8082/instance-ip/09d37ba4-cd40-4b4d-b628-2f2a664a3c6c
2026-04-02 02:41:22,175 - DEBUG - Requesting: http://10.0.0.48:8082/instance-ip/a71a51a8-bc1f-4c71-8796-2bf8cde6b716
2026-04-02 02:41:22,183 - INFO - VM ctest-VM21-88314227 verfication in all API Servers passed
2026-04-02 02:41:22,203 - DEBUG - VM ctest-VM21-88314227 Tap interface: {'index': '3', 'name': 'tapb4a94480-05', 'uuid': 'b4a94480-05d3-4ffe-862e-65b9d8e8fb51', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN2-24772300:ctest-VN2-24772300', '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': '11', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN2-24772300', 'vm_uuid': '03205e54-f6db-410e-bea8-c25620fbe315', 'vm_name': 'ctest-VM21-88314227', 'ip_addr': '11.206.39.131', 'mac_addr': '02:b4:a9:44:80:05', '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-60337406:b4a94480-05d3-4ffe-862e-65b9d8e8fb51', 'sg_uuid_list': ['77fedd23-5b21-4288-b2b7-690f8fde5b83'], 'static_route_list': None, 'vm_project_uuid': '2062099b-383c-4bc6-80f8-0875eb4a8dd6', 'admin_state': 'Enabled', 'flow_key_idx': '31', 'allowed_address_pair_list': None, 'ip6_addr': '2052:b0e3:92c2:eb3b:64f2:bbfd: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': []}
2026-04-02 02:41:22,203 - DEBUG - Agent 10.0.0.50 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN2-24772300:ctest-VN2-24772300
2026-04-02 02:41:22,209 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN2-24772300:ctest-VN2-24772300', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN2-24772300', 'table_label': '-1', 'vxlan_id': '11', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.241:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]}
2026-04-02 02:41:22,302 - DEBUG - VM ctest-VM21-88314227 : Tap interface tapb4a94480-05 is set to Active
2026-04-02 02:41:22,302 - DEBUG - Tap interface tapb4a94480-05 detail : {'index': '3', 'name': 'tapb4a94480-05', 'uuid': 'b4a94480-05d3-4ffe-862e-65b9d8e8fb51', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN2-24772300:ctest-VN2-24772300', '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': '11', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN2-24772300', 'vm_uuid': '03205e54-f6db-410e-bea8-c25620fbe315', 'vm_name': 'ctest-VM21-88314227', 'ip_addr': '11.206.39.131', 'mac_addr': '02:b4:a9:44:80:05', '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-60337406:b4a94480-05d3-4ffe-862e-65b9d8e8fb51', 'sg_uuid_list': ['77fedd23-5b21-4288-b2b7-690f8fde5b83'], 'static_route_list': None, 'vm_project_uuid': '2062099b-383c-4bc6-80f8-0875eb4a8dd6', 'admin_state': 'Enabled', 'flow_key_idx': '31', 'allowed_address_pair_list': None, 'ip6_addr': '2052:b0e3:92c2:eb3b:64f2:bbfd: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': []}
2026-04-02 02:41:22,415 - DEBUG - Starting Layer 2 verification in Agent
2026-04-02 02:41:22,427 - DEBUG - Layer 2 path is seen for VM MAC 02:b4:a9:44:80:05 in agent 10.0.0.50
2026-04-02 02:41:22,427 - DEBUG - Active layer 2 route in agent is present for VMI tapb4a94480-05
2026-04-02 02:41:22,428 - DEBUG - L2 label(29) matches bw route table and interface table
2026-04-02 02:41:22,437 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN2-24772300 is found in Agent of node 10.0.0.50
2026-04-02 02:41:22,586 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN2-24772300 is consistent in agent 10.0.0.50
2026-04-02 02:41:22,586 - DEBUG - Route for VM IP ['11.206.39.131', '2052:b0e3:92c2:eb3b:64f2:bbfd:0:3'] is consistent in agent 10.0.0.50
2026-04-02 02:41:22,586 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN2-24772300 verification for VM ctest-VM21-88314227 in Agent 10.0.0.50 passed
2026-04-02 02:41:22,699 - DEBUG - Starting all layer 2 verification in agent 10.0.0.50
2026-04-02 02:41:22,711 - DEBUG - Route for VM MAC 02:b4:a9:44:80:05 is consistent in agent 10.0.0.50
2026-04-02 02:41:23,901 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=7.86 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=4.71 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 = 4.712/6.285/7.858/1.573 ms')
2026-04-02 02:41:23,901 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-88314227 passed
2026-04-02 02:41:23,901 - INFO - VM ctest-VM21-88314227 verifications in Compute nodes passed
2026-04-02 02:41:24,146 - DEBUG - Validated VM route 11.206.39.131 in vrouter of 10.0.0.50
2026-04-02 02:41:24,153 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0
2026-04-02 02:41:24,153 - INFO - Validated routes of VM ctest-VM21-88314227 in all vrouters
2026-04-02 02:41:24,569 - DEBUG - Starting all layer2 verification in 10.0.0.73 Control Node
2026-04-02 02:41:24,698 - DEBUG - Layer2 route found for VM MAC 02:b4:a9:44:80:05 in Control-node 10.0.0.73
2026-04-02 02:41:24,698 - DEBUG - L2 Label for VM ctest-VM21-88314227 same between Control-node 10.0.0.73 and Agent, Expected: 29, Seen: 29
2026-04-02 02:41:24,810 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.73, not supported
2026-04-02 02:41:24,810 - DEBUG - Starting all layer2 verification in 10.0.0.48 Control Node
2026-04-02 02:41:24,940 - DEBUG - Layer2 route found for VM MAC 02:b4:a9:44:80:05 in Control-node 10.0.0.48
2026-04-02 02:41:24,940 - DEBUG - L2 Label for VM ctest-VM21-88314227 same between Control-node 10.0.0.48 and Agent, Expected: 29, Seen: 29
2026-04-02 02:41:25,059 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.48, not supported
2026-04-02 02:41:25,059 - INFO - Verification in Control-nodes for VM ctest-VM21-88314227 passed
2026-04-02 02:41:25,059 - DEBUG - Verifying the vm in opserver
2026-04-02 02:41:25,059 - DEBUG - Verifying in collector 10.0.0.48 ...
2026-04-02 02:41:25,059 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-machine/03205e54-f6db-410e-bea8-c25620fbe315?flat
2026-04-02 02:41:25,075 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-60337406:b4a94480-05d3-4ffe-862e-65b9d8e8fb51?flat
2026-04-02 02:41:25,093 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-60337406:b4a94480-05d3-4ffe-862e-65b9d8e8fb51?flat
2026-04-02 02:41:25,111 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-60337406:b4a94480-05d3-4ffe-862e-65b9d8e8fb51?flat
2026-04-02 02:41:25,129 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-machine/03205e54-f6db-410e-bea8-c25620fbe315?flat
2026-04-02 02:41:25,146 - DEBUG - Verifying vm in vn uve
2026-04-02 02:41:25,146 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-60337406:b4a94480-05d3-4ffe-862e-65b9d8e8fb51?flat
2026-04-02 02:41:25,164 - DEBUG - VM uve shows interface as {'mac_address': '02:b4:a9:44:80:05', 'fixed_ip4_list': ['11.206.39.131'], 'gateway': '11.206.39.129', 'ip6_active': True, 'vm_uuid': '03205e54-f6db-410e-bea8-c25620fbe315', 'active': True, '__T': 1775097642645329, 'fixed_ip6_list': ['2052:b0e3:92c2:eb3b:64f2:bbfd:0:3'], 'tx_vlan': 65535, 'l2_active': True, 'ip6_address': '2052:b0e3:92c2:eb3b:64f2:bbfd:0:3', 'ip4_active': True, 'rx_vlan': 65535, 'label': 25, 'vhostuser_mode': 0, 'vm_name': 'ctest-VM21-88314227', 'uuid': 'b4a94480-05d3-4ffe-862e-65b9d8e8fb51', 'is_health_check_active': True, 'ip_address': '11.206.39.131', 'admin_state': True, 'vn_uuid': '736d8dd1-4869-4a5f-9672-e3b95ab514b0', 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN2-24772300', 'port_mirror_enabled': False}
2026-04-02 02:41:25,164 - DEBUG - VM uve shows ip address as ['11.206.39.131', '2052:b0e3:92c2:eb3b:64f2:bbfd:0:3']
2026-04-02 02:41:25,164 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN2-24772300
2026-04-02 02:41:25,164 - INFO - Verifying through opserver in 10.0.0.73
2026-04-02 02:41:25,164 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN2-24772300?flat
2026-04-02 02:41:25,183 - DEBUG - expected vm list 03205e54-f6db-410e-bea8-c25620fbe315
2026-04-02 02:41:25,183 - DEBUG - Extracted vm list ['03205e54-f6db-410e-bea8-c25620fbe315']
2026-04-02 02:41:25,183 - INFO - VM 03205e54-f6db-410e-bea8-c25620fbe315 is present in default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN2-24772300
2026-04-02 02:41:25,183 - DEBUG - Verifying vm in vrouter uve
2026-04-02 02:41:25,183 - DEBUG - Getting info from collector 10.0.0.48..
2026-04-02 02:41:25,183 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-machine/03205e54-f6db-410e-bea8-c25620fbe315?flat
2026-04-02 02:41:25,201 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-5575-1']
2026-04-02 02:41:25,201 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5575-1:Compute:contrail-vrouter-agent:0?flat
2026-04-02 02:41:25,232 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5575-1?flat
2026-04-02 02:41:25,264 - DEBUG - VM 03205e54-f6db-410e-bea8-c25620fbe315 is present in vrouter an-jenkins-deploy-platform-ansible-os-5575-1 uve
2026-04-02 02:41:25,264 - DEBUG - Validated that VM ctest-VM21-88314227 is in Vrouter an-jenkins-deploy-platform-ansible-os-5575-1 UVE
2026-04-02 02:41:25,264 - DEBUG - Verifying vm tap interface/vn in vrouter uve
2026-04-02 02:41:25,273 - DEBUG - Expected tap interface of VM uuid 03205e54-f6db-410e-bea8-c25620fbe315 is default-domain:ctest-TestPolicyAclIpv4v6-60337406:b4a94480-05d3-4ffe-862e-65b9d8e8fb51
2026-04-02 02:41:25,274 - DEBUG - Expected VN of VM uuid 03205e54-f6db-410e-bea8-c25620fbe315 is default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN2-24772300
2026-04-02 02:41:25,274 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5575-1:Compute:contrail-vrouter-agent:0?flat
2026-04-02 02:41:25,308 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5575-1?flat
2026-04-02 02:41:25,336 - DEBUG - VM 03205e54-f6db-410e-bea8-c25620fbe315 is present in vrouter an-jenkins-deploy-platform-ansible-os-5575-1 uve
2026-04-02 02:41:25,336 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-60337406:b4a94480-05d3-4ffe-862e-65b9d8e8fb51 of vm 03205e54-f6db-410e-bea8-c25620fbe315 is present in vrouter an-jenkins-deploy-platform-ansible-os-5575-1 uve
2026-04-02 02:41:25,336 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN2-24772300 of vm 03205e54-f6db-410e-bea8-c25620fbe315 is present in vrouter an-jenkins-deploy-platform-ansible-os-5575-1 uve
2026-04-02 02:41:25,336 - DEBUG - Verifying the 03205e54-f6db-410e-bea8-c25620fbe315 virtual network link through opserver 10.0.0.73
2026-04-02 02:41:25,336 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-machines
2026-04-02 02:41:25,347 - DEBUG - VM link and name as {'name': '03205e54-f6db-410e-bea8-c25620fbe315', 'href': 'http://10.0.0.73:8081/analytics/uves/virtual-machine/03205e54-f6db-410e-bea8-c25620fbe315?flat'}
2026-04-02 02:41:25,347 - DEBUG - Verifying the 03205e54-f6db-410e-bea8-c25620fbe315 virtual network link through opserver 10.0.0.133
2026-04-02 02:41:25,347 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/virtual-machines
2026-04-02 02:41:25,359 - DEBUG - VM link and name as {'name': '03205e54-f6db-410e-bea8-c25620fbe315', 'href': 'http://10.0.0.133:8081/analytics/uves/virtual-machine/03205e54-f6db-410e-bea8-c25620fbe315?flat'}
2026-04-02 02:41:25,359 - DEBUG - Verifying the 03205e54-f6db-410e-bea8-c25620fbe315 virtual network link through opserver 10.0.0.48
2026-04-02 02:41:25,359 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-machines
2026-04-02 02:41:25,372 - DEBUG - VM link and name as {'name': '03205e54-f6db-410e-bea8-c25620fbe315', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-machine/03205e54-f6db-410e-bea8-c25620fbe315?flat'}
2026-04-02 02:41:25,373 - INFO - VM ctest-VM21-88314227 validations in Opserver passed
2026-04-02 02:41:25,466 - DEBUG - VM is in ACTIVE state now
2026-04-02 02:41:25,466 - INFO - VM name : ctest-VM31-47476507
2026-04-02 02:41:25,540 - DEBUG - VM ctest-VM31-47476507 ID is 360c5000-6fb6-4281-b610-21217345fefc
2026-04-02 02:41:25,614 - DEBUG - VM is in ACTIVE state now
2026-04-02 02:41:25,614 - DEBUG - VM ctest-VM31-47476507 launched on Node an-jenkins-deploy-platform-ansible-os-5575-2
2026-04-02 02:41:25,695 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/360c5000-6fb6-4281-b610-21217345fefc
2026-04-02 02:41:25,705 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/360c5000-6fb6-4281-b610-21217345fefc
2026-04-02 02:41:25,712 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine-interface/2853c84a-4531-4202-8417-5544eafd6e74
2026-04-02 02:41:25,722 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/360c5000-6fb6-4281-b610-21217345fefc
2026-04-02 02:41:25,730 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine-interface/2853c84a-4531-4202-8417-5544eafd6e74
2026-04-02 02:41:25,741 - DEBUG - Requesting: http://10.0.0.48:8082/instance-ip/6eef3b13-07d3-4051-b596-9de9fd9738bd
2026-04-02 02:41:25,753 - DEBUG - Requesting: http://10.0.0.48:8082/instance-ip/90fac57c-aa41-4df2-85dc-3591372914a6
2026-04-02 02:41:25,762 - DEBUG - Verifying in api server 10.0.0.48
2026-04-02 02:41:25,762 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/360c5000-6fb6-4281-b610-21217345fefc
2026-04-02 02:41:25,770 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine-interface/2853c84a-4531-4202-8417-5544eafd6e74
2026-04-02 02:41:25,785 - DEBUG - Requesting: http://10.0.0.48:8082/instance-ip/6eef3b13-07d3-4051-b596-9de9fd9738bd
2026-04-02 02:41:25,802 - DEBUG - Requesting: http://10.0.0.48:8082/instance-ip/90fac57c-aa41-4df2-85dc-3591372914a6
2026-04-02 02:41:25,816 - INFO - VM ctest-VM31-47476507 verfication in all API Servers passed
2026-04-02 02:41:25,831 - DEBUG - VM ctest-VM31-47476507 Tap interface: {'index': '4', 'name': 'tap2853c84a-45', 'uuid': '2853c84a-4531-4202-8417-5544eafd6e74', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN3-67864215:ctest-VN3-67864215', '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': '12', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN3-67864215', 'vm_uuid': '360c5000-6fb6-4281-b610-21217345fefc', 'vm_name': 'ctest-VM31-47476507', 'ip_addr': '112.160.211.131', 'mac_addr': '02:28:53:c8:4a:45', '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-60337406:2853c84a-4531-4202-8417-5544eafd6e74', 'sg_uuid_list': ['77fedd23-5b21-4288-b2b7-690f8fde5b83'], 'static_route_list': None, 'vm_project_uuid': '2062099b-383c-4bc6-80f8-0875eb4a8dd6', 'admin_state': 'Enabled', 'flow_key_idx': '39', 'allowed_address_pair_list': None, 'ip6_addr': '3219:f069:c763:f1a3:8624:dff0: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': []}
2026-04-02 02:41:25,831 - DEBUG - Agent 10.0.0.128 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN3-67864215:ctest-VN3-67864215
2026-04-02 02:41:25,836 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN3-67864215:ctest-VN3-67864215', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN3-67864215', 'table_label': '-1', 'vxlan_id': '12', 'evpnindex': '3', 'brindex': '3', 'mplsindex': '0', 'RD': '10.20.0.14:3', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]}
2026-04-02 02:41:25,923 - DEBUG - VM ctest-VM31-47476507 : Tap interface tap2853c84a-45 is set to Active
2026-04-02 02:41:25,924 - DEBUG - Tap interface tap2853c84a-45 detail : {'index': '4', 'name': 'tap2853c84a-45', 'uuid': '2853c84a-4531-4202-8417-5544eafd6e74', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN3-67864215:ctest-VN3-67864215', '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': '12', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN3-67864215', 'vm_uuid': '360c5000-6fb6-4281-b610-21217345fefc', 'vm_name': 'ctest-VM31-47476507', 'ip_addr': '112.160.211.131', 'mac_addr': '02:28:53:c8:4a:45', '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-60337406:2853c84a-4531-4202-8417-5544eafd6e74', 'sg_uuid_list': ['77fedd23-5b21-4288-b2b7-690f8fde5b83'], 'static_route_list': None, 'vm_project_uuid': '2062099b-383c-4bc6-80f8-0875eb4a8dd6', 'admin_state': 'Enabled', 'flow_key_idx': '39', 'allowed_address_pair_list': None, 'ip6_addr': '3219:f069:c763:f1a3:8624:dff0: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': []}
2026-04-02 02:41:26,039 - DEBUG - Starting Layer 2 verification in Agent
2026-04-02 02:41:26,049 - DEBUG - Layer 2 path is seen for VM MAC 02:28:53:c8:4a:45 in agent 10.0.0.128
2026-04-02 02:41:26,049 - DEBUG - Active layer 2 route in agent is present for VMI tap2853c84a-45
2026-04-02 02:41:26,049 - DEBUG - L2 label(36) matches bw route table and interface table
2026-04-02 02:41:26,059 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN3-67864215 is found in Agent of node 10.0.0.128
2026-04-02 02:41:26,213 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN3-67864215 is consistent in agent 10.0.0.128
2026-04-02 02:41:26,213 - DEBUG - Route for VM IP ['3219:f069:c763:f1a3:8624:dff0:0:3', '112.160.211.131'] is consistent in agent 10.0.0.128
2026-04-02 02:41:26,214 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN3-67864215 verification for VM ctest-VM31-47476507 in Agent 10.0.0.128 passed
2026-04-02 02:41:26,334 - DEBUG - Starting all layer 2 verification in agent 10.0.0.128
2026-04-02 02:41:26,342 - DEBUG - Route for VM MAC 02:28:53:c8:4a:45 is consistent in agent 10.0.0.128
2026-04-02 02:41:27,514 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=7.51 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=1.14 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 1.136/4.323/7.511/3.187 ms')
2026-04-02 02:41:27,514 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-47476507 passed
2026-04-02 02:41:27,514 - INFO - VM ctest-VM31-47476507 verifications in Compute nodes passed
2026-04-02 02:41:27,739 - DEBUG - Validated VM route 112.160.211.131 in vrouter of 10.0.0.128
2026-04-02 02:41:27,743 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0
2026-04-02 02:41:27,743 - INFO - Validated routes of VM ctest-VM31-47476507 in all vrouters
2026-04-02 02:41:28,239 - DEBUG - Starting all layer2 verification in 10.0.0.73 Control Node
2026-04-02 02:41:28,354 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.73, not supported
2026-04-02 02:41:28,480 - DEBUG - Layer2 route found for VM MAC 02:28:53:c8:4a:45 in Control-node 10.0.0.73
2026-04-02 02:41:28,480 - DEBUG - L2 Label for VM ctest-VM31-47476507 same between Control-node 10.0.0.73 and Agent, Expected: 36, Seen: 36
2026-04-02 02:41:28,480 - DEBUG - Starting all layer2 verification in 10.0.0.48 Control Node
2026-04-02 02:41:28,601 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.48, not supported
2026-04-02 02:41:28,732 - DEBUG - Layer2 route found for VM MAC 02:28:53:c8:4a:45 in Control-node 10.0.0.48
2026-04-02 02:41:28,732 - DEBUG - L2 Label for VM ctest-VM31-47476507 same between Control-node 10.0.0.48 and Agent, Expected: 36, Seen: 36
2026-04-02 02:41:28,732 - INFO - Verification in Control-nodes for VM ctest-VM31-47476507 passed
2026-04-02 02:41:28,733 - DEBUG - Verifying the vm in opserver
2026-04-02 02:41:28,733 - DEBUG - Verifying in collector 10.0.0.48 ...
2026-04-02 02:41:28,733 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-machine/360c5000-6fb6-4281-b610-21217345fefc?flat
2026-04-02 02:41:28,749 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-60337406:2853c84a-4531-4202-8417-5544eafd6e74?flat
2026-04-02 02:41:28,774 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-60337406:2853c84a-4531-4202-8417-5544eafd6e74?flat
2026-04-02 02:41:28,794 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-60337406:2853c84a-4531-4202-8417-5544eafd6e74?flat
2026-04-02 02:41:28,811 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-machine/360c5000-6fb6-4281-b610-21217345fefc?flat
2026-04-02 02:41:28,826 - DEBUG - Verifying vm in vn uve
2026-04-02 02:41:28,826 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-60337406:2853c84a-4531-4202-8417-5544eafd6e74?flat
2026-04-02 02:41:28,843 - DEBUG - VM uve shows interface as {'mac_address': '02:28:53:c8:4a:45', 'fixed_ip4_list': ['112.160.211.131'], 'gateway': '112.160.211.129', 'ip6_active': True, 'vm_uuid': '360c5000-6fb6-4281-b610-21217345fefc', 'active': True, '__T': 1775097641674003, 'fixed_ip6_list': ['3219:f069:c763:f1a3:8624:dff0:0:3'], 'tx_vlan': 65535, 'l2_active': True, 'ip6_address': '3219:f069:c763:f1a3:8624:dff0:0:3', 'ip4_active': True, 'rx_vlan': 65535, 'label': 32, 'vhostuser_mode': 0, 'vm_name': 'ctest-VM31-47476507', 'uuid': '2853c84a-4531-4202-8417-5544eafd6e74', 'is_health_check_active': True, 'ip_address': '112.160.211.131', 'admin_state': True, 'vn_uuid': 'a8f11a51-8267-48ee-b995-64c569ccbb27', 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN3-67864215', 'port_mirror_enabled': False}
2026-04-02 02:41:28,843 - DEBUG - VM uve shows ip address as ['112.160.211.131', '3219:f069:c763:f1a3:8624:dff0:0:3']
2026-04-02 02:41:28,843 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN3-67864215
2026-04-02 02:41:28,843 - INFO - Verifying through opserver in 10.0.0.73
2026-04-02 02:41:28,843 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN3-67864215?flat
2026-04-02 02:41:28,868 - DEBUG - expected vm list 360c5000-6fb6-4281-b610-21217345fefc
2026-04-02 02:41:28,868 - DEBUG - Extracted vm list ['360c5000-6fb6-4281-b610-21217345fefc']
2026-04-02 02:41:28,868 - INFO - VM 360c5000-6fb6-4281-b610-21217345fefc is present in default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN3-67864215
2026-04-02 02:41:28,868 - DEBUG - Verifying vm in vrouter uve
2026-04-02 02:41:28,868 - DEBUG - Getting info from collector 10.0.0.48..
2026-04-02 02:41:28,868 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-machine/360c5000-6fb6-4281-b610-21217345fefc?flat
2026-04-02 02:41:28,885 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-5575-2']
2026-04-02 02:41:28,885 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5575-2:Compute:contrail-vrouter-agent:0?flat
2026-04-02 02:41:28,919 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5575-2?flat
2026-04-02 02:41:28,950 - DEBUG - VM 360c5000-6fb6-4281-b610-21217345fefc is present in vrouter an-jenkins-deploy-platform-ansible-os-5575-2 uve
2026-04-02 02:41:28,950 - DEBUG - Validated that VM ctest-VM31-47476507 is in Vrouter an-jenkins-deploy-platform-ansible-os-5575-2 UVE
2026-04-02 02:41:28,950 - DEBUG - Verifying vm tap interface/vn in vrouter uve
2026-04-02 02:41:28,956 - DEBUG - Expected tap interface of VM uuid 360c5000-6fb6-4281-b610-21217345fefc is default-domain:ctest-TestPolicyAclIpv4v6-60337406:2853c84a-4531-4202-8417-5544eafd6e74
2026-04-02 02:41:28,956 - DEBUG - Expected VN of VM uuid 360c5000-6fb6-4281-b610-21217345fefc is default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN3-67864215
2026-04-02 02:41:28,956 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5575-2:Compute:contrail-vrouter-agent:0?flat
2026-04-02 02:41:28,999 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5575-2?flat
2026-04-02 02:41:29,029 - DEBUG - VM 360c5000-6fb6-4281-b610-21217345fefc is present in vrouter an-jenkins-deploy-platform-ansible-os-5575-2 uve
2026-04-02 02:41:29,029 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-60337406:2853c84a-4531-4202-8417-5544eafd6e74 of vm 360c5000-6fb6-4281-b610-21217345fefc is present in vrouter an-jenkins-deploy-platform-ansible-os-5575-2 uve
2026-04-02 02:41:29,029 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN3-67864215 of vm 360c5000-6fb6-4281-b610-21217345fefc is present in vrouter an-jenkins-deploy-platform-ansible-os-5575-2 uve
2026-04-02 02:41:29,029 - DEBUG - Verifying the 360c5000-6fb6-4281-b610-21217345fefc virtual network link through opserver 10.0.0.73
2026-04-02 02:41:29,029 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-machines
2026-04-02 02:41:29,046 - DEBUG - VM link and name as {'name': '360c5000-6fb6-4281-b610-21217345fefc', 'href': 'http://10.0.0.73:8081/analytics/uves/virtual-machine/360c5000-6fb6-4281-b610-21217345fefc?flat'}
2026-04-02 02:41:29,046 - DEBUG - Verifying the 360c5000-6fb6-4281-b610-21217345fefc virtual network link through opserver 10.0.0.133
2026-04-02 02:41:29,046 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/virtual-machines
2026-04-02 02:41:29,060 - DEBUG - VM link and name as {'name': '360c5000-6fb6-4281-b610-21217345fefc', 'href': 'http://10.0.0.133:8081/analytics/uves/virtual-machine/360c5000-6fb6-4281-b610-21217345fefc?flat'}
2026-04-02 02:41:29,060 - DEBUG - Verifying the 360c5000-6fb6-4281-b610-21217345fefc virtual network link through opserver 10.0.0.48
2026-04-02 02:41:29,060 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-machines
2026-04-02 02:41:29,077 - DEBUG - VM link and name as {'name': '360c5000-6fb6-4281-b610-21217345fefc', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-machine/360c5000-6fb6-4281-b610-21217345fefc?flat'}
2026-04-02 02:41:29,077 - INFO - VM ctest-VM31-47476507 validations in Opserver passed
2026-04-02 02:41:29,077 - INFO - Waiting for VM ctest-VM11-35965779 to be up..
2026-04-02 02:41:29,078 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-02 02:41:29,078 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/16f34cb9-56ae-4bbd-8f29-5c4886c24fac
2026-04-02 02:41:29,086 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine-interface/5392b43c-f971-43ab-a11a-3ab8fcb81f21
2026-04-02 02:41:30,249 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=5.15 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.68 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 = 2.675/3.913/5.152/1.238 ms')
2026-04-02 02:41:30,249 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-35965779 passed
2026-04-02 02:41:30,327 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-02 02:41:30,327 - DEBUG - Waiting to SSH to VM ctest-VM11-35965779, IP 4.37.60.67, Port 22
2026-04-02 02:41:30,395 - 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': ''}
2026-04-02 02:41:30,476 - DEBUG - VM ctest-VM11-35965779 is NOT ready for SSH connections, VM status: ACTIVE
2026-04-02 02:41:35,477 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-02 02:41:35,477 - DEBUG - Waiting to SSH to VM ctest-VM11-35965779, IP 4.37.60.67, Port 22
2026-04-02 02:41:35,532 - 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': ''}
2026-04-02 02:41:35,611 - DEBUG - VM ctest-VM11-35965779 is NOT ready for SSH connections, VM status: ACTIVE
2026-04-02 02:41:40,612 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-02 02:41:40,613 - DEBUG - Waiting to SSH to VM ctest-VM11-35965779, IP 4.37.60.67, Port 22
2026-04-02 02:41:40,779 - DEBUG - VM ctest-VM11-35965779 is ready for SSH connections
2026-04-02 02:41:40,780 - INFO - Waiting for VM ctest-VM21-88314227 to be up..
2026-04-02 02:41:40,780 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-02 02:41:40,780 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/03205e54-f6db-410e-bea8-c25620fbe315
2026-04-02 02:41:40,789 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine-interface/b4a94480-05d3-4ffe-862e-65b9d8e8fb51
2026-04-02 02:41:41,949 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=5.00 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.88 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 2.875/3.938/5.001/1.063 ms')
2026-04-02 02:41:41,949 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-88314227 passed
2026-04-02 02:41:42,023 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-02 02:41:42,023 - DEBUG - Waiting to SSH to VM ctest-VM21-88314227, IP 11.206.39.131, Port 22
2026-04-02 02:41:42,091 - 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': ''}
2026-04-02 02:41:42,184 - DEBUG - VM ctest-VM21-88314227 is NOT ready for SSH connections, VM status: ACTIVE
2026-04-02 02:41:47,185 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-02 02:41:47,186 - DEBUG - Waiting to SSH to VM ctest-VM21-88314227, IP 11.206.39.131, Port 22
2026-04-02 02:41:47,357 - DEBUG - VM ctest-VM21-88314227 is ready for SSH connections
2026-04-02 02:41:47,357 - INFO - Waiting for VM ctest-VM31-47476507 to be up..
2026-04-02 02:41:47,358 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-02 02:41:47,358 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/360c5000-6fb6-4281-b610-21217345fefc
2026-04-02 02:41:47,367 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine-interface/2853c84a-4531-4202-8417-5544eafd6e74
2026-04-02 02:41:48,524 - 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=3.79 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=1.36 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 1.358/2.576/3.794/1.218 ms')
2026-04-02 02:41:48,524 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-47476507 passed
2026-04-02 02:41:48,611 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-02 02:41:48,611 - DEBUG - Waiting to SSH to VM ctest-VM31-47476507, IP 112.160.211.131, Port 22
2026-04-02 02:41:48,779 - DEBUG - VM ctest-VM31-47476507 is ready for SSH connections
2026-04-02 02:41:48,779 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 11.206.39.131, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.128, gateway password: c0ntrail123
2026-04-02 02:41:48,779 - DEBUG - ping -s 56 -c 3 -W 1 11.206.39.131
2026-04-02 02:42:06,537 - DEBUG - PING 11.206.39.131 (11.206.39.131) 56(84) bytes of data.
64 bytes from 11.206.39.131: icmp_req=1 ttl=63 time=9.54 ms
64 bytes from 11.206.39.131: icmp_req=2 ttl=63 time=1.32 ms
64 bytes from 11.206.39.131: icmp_req=3 ttl=63 time=2.14 ms
--- 11.206.39.131 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 1.323/4.338/9.546/3.697 ms
2026-04-02 02:42:06,537 - INFO - Ping to IP 11.206.39.131 from VM ctest-VM11-35965779 passed
2026-04-02 02:42:06,537 - DEBUG - Running remote_cmd, Cmd : ping6 -s 56 -c 3 -W 1 2052:b0e3:92c2:eb3b:64f2:bbfd:0:3, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.128, gateway password: c0ntrail123
2026-04-02 02:42:06,537 - DEBUG - ping6 -s 56 -c 3 -W 1 2052:b0e3:92c2:eb3b:64f2:bbfd:0:3
2026-04-02 02:42:09,079 - DEBUG - PING 2052:b0e3:92c2:eb3b:64f2:bbfd:0:3(2052:b0e3:92c2:eb3b:64f2:bbfd:0:3) 56 data bytes
64 bytes from 2052:b0e3:92c2:eb3b:64f2:bbfd:0:3: icmp_seq=1 ttl=64 time=7.59 ms
64 bytes from 2052:b0e3:92c2:eb3b:64f2:bbfd:0:3: icmp_seq=2 ttl=64 time=1.34 ms
64 bytes from 2052:b0e3:92c2:eb3b:64f2:bbfd:0:3: icmp_seq=3 ttl=64 time=1.23 ms
--- 2052:b0e3:92c2:eb3b:64f2:bbfd:0:3 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 1.236/3.391/7.598/2.975 ms
2026-04-02 02:42:09,080 - INFO - Ping to IP 2052:b0e3:92c2:eb3b:64f2:bbfd:0:3 from VM ctest-VM11-35965779 passed
2026-04-02 02:42:09,080 - INFO - Test with src as policy and dst as any PASSED
2026-04-02 02:42:09,080 - INFO - Deleting VM ctest-VM31-47476507
2026-04-02 02:42:09,149 - DEBUG - Verifying in api server 10.0.0.48
2026-04-02 02:42:09,149 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/360c5000-6fb6-4281-b610-21217345fefc
2026-04-02 02:42:09,159 - DEBUG - VM ID 360c5000-6fb6-4281-b610-21217345fefc of VM ctest-VM31-47476507 is still found in API Server
2026-04-02 02:42:11,159 - DEBUG - Verifying in api server 10.0.0.48
2026-04-02 02:42:11,160 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/360c5000-6fb6-4281-b610-21217345fefc
2026-04-02 02:42:11,166 - DEBUG - Response Code: 404
2026-04-02 02:42:11,166 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/360c5000-6fb6-4281-b610-21217345fefc
2026-04-02 02:42:11,172 - DEBUG - Response Code: 404
2026-04-02 02:42:11,172 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/360c5000-6fb6-4281-b610-21217345fefc
2026-04-02 02:42:11,177 - DEBUG - Response Code: 404
2026-04-02 02:42:11,178 - INFO - VM ctest-VM31-47476507 is fully removed in API-Server
2026-04-02 02:42:11,329 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2026-04-02 02:42:11,371 - DEBUG - Validated that vrouter 10.0.0.50 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN3-67864215
2026-04-02 02:42:11,380 - DEBUG - Validated that vrouter 10.0.0.128 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN3-67864215
2026-04-02 02:42:11,380 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN3-67864215
2026-04-02 02:42:11,380 - INFO - VM ctest-VM31-47476507 is removed in Compute, and routes are removed in all compute nodes
2026-04-02 02:42:11,610 - INFO - Routes for VM ctest-VM31-47476507 is removed in all control-nodes
2026-04-02 02:42:11,610 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5575-2:Compute:contrail-vrouter-agent:0?flat
2026-04-02 02:42:11,645 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5575-2?flat
2026-04-02 02:42:11,680 - DEBUG - VM 360c5000-6fb6-4281-b610-21217345fefc is still present in vrouter an-jenkins-deploy-platform-ansible-os-5575-2 uve
2026-04-02 02:42:11,680 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-02 02:42:11,680 - DEBUG - interface for vm 360c5000-6fb6-4281-b610-21217345fefc deleted from vrouter uve ...
2026-04-02 02:42:11,680 - DEBUG - Deleted interface not in error interface list ...
2026-04-02 02:42:14,681 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5575-2:Compute:contrail-vrouter-agent:0?flat
2026-04-02 02:42:14,715 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5575-2?flat
2026-04-02 02:42:14,743 - DEBUG - VM 360c5000-6fb6-4281-b610-21217345fefc is not present in vrouter an-jenkins-deploy-platform-ansible-os-5575-2 uve
2026-04-02 02:42:14,743 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-02 02:42:14,743 - DEBUG - interface for vm 360c5000-6fb6-4281-b610-21217345fefc deleted from vrouter uve ...
2026-04-02 02:42:14,743 - DEBUG - Deleted interface not in error interface list ...
2026-04-02 02:42:14,743 - DEBUG - Validated that VM 360c5000-6fb6-4281-b610-21217345fefc is removed in Vrouter UVE
2026-04-02 02:42:14,743 - DEBUG - Verifying through opserver in 10.0.0.73
2026-04-02 02:42:14,743 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN3-67864215?flat
2026-04-02 02:42:14,760 - DEBUG - 360c5000-6fb6-4281-b610-21217345fefc BM not in default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN3-67864215 uve
2026-04-02 02:42:14,760 - DEBUG - Validated that VM 360c5000-6fb6-4281-b610-21217345fefc is not present in the VN UVE
2026-04-02 02:42:14,760 - DEBUG - Verifying the 360c5000-6fb6-4281-b610-21217345fefc virtual network link through opserver 10.0.0.73
2026-04-02 02:42:14,760 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-machines
2026-04-02 02:42:14,772 - INFO - 360c5000-6fb6-4281-b610-21217345fefc vm uve deleted from opserver
2026-04-02 02:42:14,772 - DEBUG - Verifying the 360c5000-6fb6-4281-b610-21217345fefc virtual network link through opserver 10.0.0.133
2026-04-02 02:42:14,772 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/virtual-machines
2026-04-02 02:42:14,784 - INFO - 360c5000-6fb6-4281-b610-21217345fefc vm uve deleted from opserver
2026-04-02 02:42:14,785 - DEBUG - Verifying the 360c5000-6fb6-4281-b610-21217345fefc virtual network link through opserver 10.0.0.48
2026-04-02 02:42:14,785 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-machines
2026-04-02 02:42:14,797 - INFO - 360c5000-6fb6-4281-b610-21217345fefc vm uve deleted from opserver
2026-04-02 02:42:14,797 - INFO - Validated that VM 360c5000-6fb6-4281-b610-21217345fefc is removed from Opserver
2026-04-02 02:42:14,797 - INFO - Deleting VM ctest-VM21-88314227
2026-04-02 02:42:14,867 - DEBUG - Verifying in api server 10.0.0.48
2026-04-02 02:42:14,867 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/03205e54-f6db-410e-bea8-c25620fbe315
2026-04-02 02:42:14,876 - DEBUG - VM ID 03205e54-f6db-410e-bea8-c25620fbe315 of VM ctest-VM21-88314227 is still found in API Server
2026-04-02 02:42:16,876 - DEBUG - Verifying in api server 10.0.0.48
2026-04-02 02:42:16,876 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/03205e54-f6db-410e-bea8-c25620fbe315
2026-04-02 02:42:16,884 - DEBUG - Response Code: 404
2026-04-02 02:42:16,884 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/03205e54-f6db-410e-bea8-c25620fbe315
2026-04-02 02:42:16,890 - DEBUG - Response Code: 404
2026-04-02 02:42:16,890 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/03205e54-f6db-410e-bea8-c25620fbe315
2026-04-02 02:42:16,895 - DEBUG - Response Code: 404
2026-04-02 02:42:16,896 - INFO - VM ctest-VM21-88314227 is fully removed in API-Server
2026-04-02 02:42:17,049 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2026-04-02 02:42:17,087 - DEBUG - Validated that vrouter 10.0.0.50 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN2-24772300
2026-04-02 02:42:17,096 - DEBUG - Validated that vrouter 10.0.0.128 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN2-24772300
2026-04-02 02:42:17,096 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN2-24772300
2026-04-02 02:42:17,096 - INFO - VM ctest-VM21-88314227 is removed in Compute, and routes are removed in all compute nodes
2026-04-02 02:42:17,324 - INFO - Routes for VM ctest-VM21-88314227 is removed in all control-nodes
2026-04-02 02:42:17,324 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5575-1:Compute:contrail-vrouter-agent:0?flat
2026-04-02 02:42:17,357 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5575-1?flat
2026-04-02 02:42:17,386 - DEBUG - VM 03205e54-f6db-410e-bea8-c25620fbe315 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5575-1 uve
2026-04-02 02:42:17,386 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-02 02:42:17,386 - DEBUG - interface for vm 03205e54-f6db-410e-bea8-c25620fbe315 deleted from vrouter uve ...
2026-04-02 02:42:17,386 - DEBUG - Deleted interface not in error interface list ...
2026-04-02 02:42:20,387 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5575-1:Compute:contrail-vrouter-agent:0?flat
2026-04-02 02:42:20,419 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5575-1?flat
2026-04-02 02:42:20,451 - DEBUG - VM 03205e54-f6db-410e-bea8-c25620fbe315 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5575-1 uve
2026-04-02 02:42:20,451 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-02 02:42:20,451 - DEBUG - interface for vm 03205e54-f6db-410e-bea8-c25620fbe315 deleted from vrouter uve ...
2026-04-02 02:42:20,451 - DEBUG - Deleted interface not in error interface list ...
2026-04-02 02:42:23,452 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5575-1:Compute:contrail-vrouter-agent:0?flat
2026-04-02 02:42:23,493 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5575-1?flat
2026-04-02 02:42:23,522 - DEBUG - VM 03205e54-f6db-410e-bea8-c25620fbe315 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5575-1 uve
2026-04-02 02:42:23,522 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-02 02:42:23,522 - DEBUG - interface for vm 03205e54-f6db-410e-bea8-c25620fbe315 deleted from vrouter uve ...
2026-04-02 02:42:23,522 - DEBUG - Deleted interface not in error interface list ...
2026-04-02 02:42:26,523 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5575-1:Compute:contrail-vrouter-agent:0?flat
2026-04-02 02:42:26,556 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5575-1?flat
2026-04-02 02:42:26,586 - DEBUG - VM 03205e54-f6db-410e-bea8-c25620fbe315 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5575-1 uve
2026-04-02 02:42:26,586 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-02 02:42:26,586 - DEBUG - interface for vm 03205e54-f6db-410e-bea8-c25620fbe315 deleted from vrouter uve ...
2026-04-02 02:42:26,586 - DEBUG - Deleted interface not in error interface list ...
2026-04-02 02:42:29,587 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5575-1:Compute:contrail-vrouter-agent:0?flat
2026-04-02 02:42:29,620 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5575-1?flat
2026-04-02 02:42:29,649 - DEBUG - VM 03205e54-f6db-410e-bea8-c25620fbe315 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5575-1 uve
2026-04-02 02:42:29,649 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-02 02:42:29,649 - DEBUG - interface for vm 03205e54-f6db-410e-bea8-c25620fbe315 deleted from vrouter uve ...
2026-04-02 02:42:29,649 - DEBUG - Deleted interface not in error interface list ...
2026-04-02 02:42:32,649 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5575-1:Compute:contrail-vrouter-agent:0?flat
2026-04-02 02:42:32,682 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5575-1?flat
2026-04-02 02:42:32,710 - DEBUG - VM 03205e54-f6db-410e-bea8-c25620fbe315 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5575-1 uve
2026-04-02 02:42:32,710 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-02 02:42:32,710 - DEBUG - interface for vm 03205e54-f6db-410e-bea8-c25620fbe315 deleted from vrouter uve ...
2026-04-02 02:42:32,710 - DEBUG - Deleted interface not in error interface list ...
2026-04-02 02:42:35,711 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5575-1:Compute:contrail-vrouter-agent:0?flat
2026-04-02 02:42:35,749 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5575-1?flat
2026-04-02 02:42:35,778 - DEBUG - VM 03205e54-f6db-410e-bea8-c25620fbe315 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5575-1 uve
2026-04-02 02:42:35,778 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-02 02:42:35,778 - DEBUG - interface for vm 03205e54-f6db-410e-bea8-c25620fbe315 deleted from vrouter uve ...
2026-04-02 02:42:35,778 - DEBUG - Deleted interface not in error interface list ...
2026-04-02 02:42:38,778 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5575-1:Compute:contrail-vrouter-agent:0?flat
2026-04-02 02:42:38,810 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5575-1?flat
2026-04-02 02:42:38,837 - DEBUG - VM 03205e54-f6db-410e-bea8-c25620fbe315 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5575-1 uve
2026-04-02 02:42:38,837 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-02 02:42:38,837 - DEBUG - interface for vm 03205e54-f6db-410e-bea8-c25620fbe315 deleted from vrouter uve ...
2026-04-02 02:42:38,838 - DEBUG - Deleted interface not in error interface list ...
2026-04-02 02:42:41,838 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5575-1:Compute:contrail-vrouter-agent:0?flat
2026-04-02 02:42:41,877 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5575-1?flat
2026-04-02 02:42:41,906 - DEBUG - VM 03205e54-f6db-410e-bea8-c25620fbe315 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5575-1 uve
2026-04-02 02:42:41,906 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-02 02:42:41,906 - DEBUG - interface for vm 03205e54-f6db-410e-bea8-c25620fbe315 deleted from vrouter uve ...
2026-04-02 02:42:41,906 - DEBUG - Deleted interface not in error interface list ...
2026-04-02 02:42:44,907 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5575-1:Compute:contrail-vrouter-agent:0?flat
2026-04-02 02:42:44,953 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5575-1?flat
2026-04-02 02:42:44,984 - DEBUG - VM 03205e54-f6db-410e-bea8-c25620fbe315 is not present in vrouter an-jenkins-deploy-platform-ansible-os-5575-1 uve
2026-04-02 02:42:44,984 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-02 02:42:44,984 - DEBUG - interface for vm 03205e54-f6db-410e-bea8-c25620fbe315 deleted from vrouter uve ...
2026-04-02 02:42:44,984 - DEBUG - Deleted interface not in error interface list ...
2026-04-02 02:42:44,984 - DEBUG - Validated that VM 03205e54-f6db-410e-bea8-c25620fbe315 is removed in Vrouter UVE
2026-04-02 02:42:44,984 - DEBUG - Verifying through opserver in 10.0.0.73
2026-04-02 02:42:44,984 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN2-24772300?flat
2026-04-02 02:42:45,002 - DEBUG - 03205e54-f6db-410e-bea8-c25620fbe315 BM not in default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN2-24772300 uve
2026-04-02 02:42:45,002 - DEBUG - Validated that VM 03205e54-f6db-410e-bea8-c25620fbe315 is not present in the VN UVE
2026-04-02 02:42:45,002 - DEBUG - Verifying the 03205e54-f6db-410e-bea8-c25620fbe315 virtual network link through opserver 10.0.0.73
2026-04-02 02:42:45,002 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-machines
2026-04-02 02:42:45,013 - INFO - 03205e54-f6db-410e-bea8-c25620fbe315 vm uve deleted from opserver
2026-04-02 02:42:45,013 - DEBUG - Verifying the 03205e54-f6db-410e-bea8-c25620fbe315 virtual network link through opserver 10.0.0.133
2026-04-02 02:42:45,013 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/virtual-machines
2026-04-02 02:42:45,026 - INFO - 03205e54-f6db-410e-bea8-c25620fbe315 vm uve deleted from opserver
2026-04-02 02:42:45,026 - DEBUG - Verifying the 03205e54-f6db-410e-bea8-c25620fbe315 virtual network link through opserver 10.0.0.48
2026-04-02 02:42:45,026 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-machines
2026-04-02 02:42:45,039 - INFO - 03205e54-f6db-410e-bea8-c25620fbe315 vm uve deleted from opserver
2026-04-02 02:42:45,039 - INFO - Validated that VM 03205e54-f6db-410e-bea8-c25620fbe315 is removed from Opserver
2026-04-02 02:42:45,039 - INFO - Deleting VM ctest-VM11-35965779
2026-04-02 02:42:45,104 - DEBUG - Verifying in api server 10.0.0.48
2026-04-02 02:42:45,105 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/16f34cb9-56ae-4bbd-8f29-5c4886c24fac
2026-04-02 02:42:45,113 - DEBUG - VM ID 16f34cb9-56ae-4bbd-8f29-5c4886c24fac of VM ctest-VM11-35965779 is still found in API Server
2026-04-02 02:42:47,113 - DEBUG - Verifying in api server 10.0.0.48
2026-04-02 02:42:47,114 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/16f34cb9-56ae-4bbd-8f29-5c4886c24fac
2026-04-02 02:42:47,120 - DEBUG - Response Code: 404
2026-04-02 02:42:47,121 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/16f34cb9-56ae-4bbd-8f29-5c4886c24fac
2026-04-02 02:42:47,127 - DEBUG - Response Code: 404
2026-04-02 02:42:47,127 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/16f34cb9-56ae-4bbd-8f29-5c4886c24fac
2026-04-02 02:42:47,132 - DEBUG - Response Code: 404
2026-04-02 02:42:47,133 - INFO - VM ctest-VM11-35965779 is fully removed in API-Server
2026-04-02 02:42:47,290 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2026-04-02 02:42:47,330 - DEBUG - Validated that vrouter 10.0.0.50 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077
2026-04-02 02:42:47,339 - DEBUG - Validated that vrouter 10.0.0.128 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077
2026-04-02 02:42:47,339 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077
2026-04-02 02:42:47,339 - INFO - VM ctest-VM11-35965779 is removed in Compute, and routes are removed in all compute nodes
2026-04-02 02:42:47,531 - INFO - Routes for VM ctest-VM11-35965779 is removed in all control-nodes
2026-04-02 02:42:47,531 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5575-2:Compute:contrail-vrouter-agent:0?flat
2026-04-02 02:42:47,566 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5575-2?flat
2026-04-02 02:42:47,597 - DEBUG - VM 16f34cb9-56ae-4bbd-8f29-5c4886c24fac is still present in vrouter an-jenkins-deploy-platform-ansible-os-5575-2 uve
2026-04-02 02:42:47,597 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-02 02:42:47,597 - DEBUG - interface for vm 16f34cb9-56ae-4bbd-8f29-5c4886c24fac deleted from vrouter uve ...
2026-04-02 02:42:47,597 - DEBUG - Deleted interface not in error interface list ...
2026-04-02 02:42:50,598 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5575-2:Compute:contrail-vrouter-agent:0?flat
2026-04-02 02:42:50,634 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5575-2?flat
2026-04-02 02:42:50,668 - DEBUG - VM 16f34cb9-56ae-4bbd-8f29-5c4886c24fac is still present in vrouter an-jenkins-deploy-platform-ansible-os-5575-2 uve
2026-04-02 02:42:50,668 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-02 02:42:50,668 - DEBUG - interface for vm 16f34cb9-56ae-4bbd-8f29-5c4886c24fac deleted from vrouter uve ...
2026-04-02 02:42:50,668 - DEBUG - Deleted interface not in error interface list ...
2026-04-02 02:42:53,668 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5575-2:Compute:contrail-vrouter-agent:0?flat
2026-04-02 02:42:53,709 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5575-2?flat
2026-04-02 02:42:53,738 - DEBUG - VM 16f34cb9-56ae-4bbd-8f29-5c4886c24fac is still present in vrouter an-jenkins-deploy-platform-ansible-os-5575-2 uve
2026-04-02 02:42:53,738 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-02 02:42:53,738 - DEBUG - interface for vm 16f34cb9-56ae-4bbd-8f29-5c4886c24fac deleted from vrouter uve ...
2026-04-02 02:42:53,738 - DEBUG - Deleted interface not in error interface list ...
2026-04-02 02:42:56,739 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5575-2:Compute:contrail-vrouter-agent:0?flat
2026-04-02 02:42:56,774 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5575-2?flat
2026-04-02 02:42:56,805 - DEBUG - VM 16f34cb9-56ae-4bbd-8f29-5c4886c24fac is still present in vrouter an-jenkins-deploy-platform-ansible-os-5575-2 uve
2026-04-02 02:42:56,805 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-02 02:42:56,806 - DEBUG - interface for vm 16f34cb9-56ae-4bbd-8f29-5c4886c24fac deleted from vrouter uve ...
2026-04-02 02:42:56,806 - DEBUG - Deleted interface not in error interface list ...
2026-04-02 02:42:59,806 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5575-2:Compute:contrail-vrouter-agent:0?flat
2026-04-02 02:42:59,838 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5575-2?flat
2026-04-02 02:42:59,868 - DEBUG - VM 16f34cb9-56ae-4bbd-8f29-5c4886c24fac is still present in vrouter an-jenkins-deploy-platform-ansible-os-5575-2 uve
2026-04-02 02:42:59,868 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-02 02:42:59,868 - DEBUG - interface for vm 16f34cb9-56ae-4bbd-8f29-5c4886c24fac deleted from vrouter uve ...
2026-04-02 02:42:59,868 - DEBUG - Deleted interface not in error interface list ...
2026-04-02 02:43:02,869 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5575-2:Compute:contrail-vrouter-agent:0?flat
2026-04-02 02:43:02,906 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5575-2?flat
2026-04-02 02:43:02,937 - DEBUG - VM 16f34cb9-56ae-4bbd-8f29-5c4886c24fac is still present in vrouter an-jenkins-deploy-platform-ansible-os-5575-2 uve
2026-04-02 02:43:02,937 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-02 02:43:02,937 - DEBUG - interface for vm 16f34cb9-56ae-4bbd-8f29-5c4886c24fac deleted from vrouter uve ...
2026-04-02 02:43:02,937 - DEBUG - Deleted interface not in error interface list ...
2026-04-02 02:43:05,938 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5575-2:Compute:contrail-vrouter-agent:0?flat
2026-04-02 02:43:05,970 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5575-2?flat
2026-04-02 02:43:05,999 - DEBUG - VM 16f34cb9-56ae-4bbd-8f29-5c4886c24fac is still present in vrouter an-jenkins-deploy-platform-ansible-os-5575-2 uve
2026-04-02 02:43:05,999 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-02 02:43:05,999 - DEBUG - interface for vm 16f34cb9-56ae-4bbd-8f29-5c4886c24fac deleted from vrouter uve ...
2026-04-02 02:43:05,999 - DEBUG - Deleted interface not in error interface list ...
2026-04-02 02:43:09,000 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5575-2:Compute:contrail-vrouter-agent:0?flat
2026-04-02 02:43:09,040 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5575-2?flat
2026-04-02 02:43:09,069 - DEBUG - VM 16f34cb9-56ae-4bbd-8f29-5c4886c24fac is still present in vrouter an-jenkins-deploy-platform-ansible-os-5575-2 uve
2026-04-02 02:43:09,069 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-02 02:43:09,069 - DEBUG - interface for vm 16f34cb9-56ae-4bbd-8f29-5c4886c24fac deleted from vrouter uve ...
2026-04-02 02:43:09,069 - DEBUG - Deleted interface not in error interface list ...
2026-04-02 02:43:12,070 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5575-2:Compute:contrail-vrouter-agent:0?flat
2026-04-02 02:43:12,107 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5575-2?flat
2026-04-02 02:43:12,138 - DEBUG - VM 16f34cb9-56ae-4bbd-8f29-5c4886c24fac is not present in vrouter an-jenkins-deploy-platform-ansible-os-5575-2 uve
2026-04-02 02:43:12,138 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-02 02:43:12,138 - DEBUG - interface for vm 16f34cb9-56ae-4bbd-8f29-5c4886c24fac deleted from vrouter uve ...
2026-04-02 02:43:12,138 - DEBUG - Deleted interface not in error interface list ...
2026-04-02 02:43:12,138 - DEBUG - Validated that VM 16f34cb9-56ae-4bbd-8f29-5c4886c24fac is removed in Vrouter UVE
2026-04-02 02:43:12,138 - DEBUG - Verifying through opserver in 10.0.0.73
2026-04-02 02:43:12,138 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077?flat
2026-04-02 02:43:12,157 - DEBUG - 16f34cb9-56ae-4bbd-8f29-5c4886c24fac BM not in default-domain:ctest-TestPolicyAclIpv4v6-60337406:ctest-VN1-97689077 uve
2026-04-02 02:43:12,157 - DEBUG - Validated that VM 16f34cb9-56ae-4bbd-8f29-5c4886c24fac is not present in the VN UVE
2026-04-02 02:43:12,157 - DEBUG - Verifying the 16f34cb9-56ae-4bbd-8f29-5c4886c24fac virtual network link through opserver 10.0.0.73
2026-04-02 02:43:12,157 - DEBUG - Requesting: http://10.0.0.73:8081/analytics/uves/virtual-machines
2026-04-02 02:43:12,168 - DEBUG - No links retuned for 16f34cb9-56ae-4bbd-8f29-5c4886c24fac
2026-04-02 02:43:12,168 - INFO - 16f34cb9-56ae-4bbd-8f29-5c4886c24fac vm uve deleted from opserver
2026-04-02 02:43:12,168 - DEBUG - Verifying the 16f34cb9-56ae-4bbd-8f29-5c4886c24fac virtual network link through opserver 10.0.0.133
2026-04-02 02:43:12,168 - DEBUG - Requesting: http://10.0.0.133:8081/analytics/uves/virtual-machines
2026-04-02 02:43:12,181 - DEBUG - No links retuned for 16f34cb9-56ae-4bbd-8f29-5c4886c24fac
2026-04-02 02:43:12,181 - INFO - 16f34cb9-56ae-4bbd-8f29-5c4886c24fac vm uve deleted from opserver
2026-04-02 02:43:12,181 - DEBUG - Verifying the 16f34cb9-56ae-4bbd-8f29-5c4886c24fac virtual network link through opserver 10.0.0.48
2026-04-02 02:43:12,181 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-machines
2026-04-02 02:43:12,196 - DEBUG - No links retuned for 16f34cb9-56ae-4bbd-8f29-5c4886c24fac
2026-04-02 02:43:12,197 - INFO - 16f34cb9-56ae-4bbd-8f29-5c4886c24fac vm uve deleted from opserver
2026-04-02 02:43:12,197 - INFO - Validated that VM 16f34cb9-56ae-4bbd-8f29-5c4886c24fac is removed from Opserver
2026-04-02 02:43:12,197 - DEBUG - Detaching the Policy for VN :ctest-VN2-24772300
2026-04-02 02:43:12,197 - DEBUG - Requesting: http://10.0.0.73:8082/domains
2026-04-02 02:43:12,203 - DEBUG - Requesting: http://10.0.0.73:8082/domain/2c17115e-1a6e-47b0-9bfc-9e380916d58b
2026-04-02 02:43:12,242 - DEBUG - Requesting: http://10.0.0.73:8082/project/2062099b-383c-4bc6-80f8-0875eb4a8dd6
2026-04-02 02:43:12,357 - DEBUG - Requesting: http://10.0.0.73:8082/virtual-network/736d8dd1-4869-4a5f-9672-e3b95ab514b0
2026-04-02 02:43:12,384 - DEBUG - Requesting: http://10.0.0.73:8082/network-policy/6d517f32-1106-4226-b4bd-05a80ea0ce06
2026-04-02 02:43:12,645 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-60337406', 'ctest-policy21-99084139']] from ctest-VN2-24772300
2026-04-02 02:43:12,645 - DEBUG - Detaching the Policy for VN :ctest-VN1-97689077
2026-04-02 02:43:12,645 - DEBUG - Requesting: http://10.0.0.73:8082/domains
2026-04-02 02:43:12,653 - DEBUG - Requesting: http://10.0.0.73:8082/domain/2c17115e-1a6e-47b0-9bfc-9e380916d58b
2026-04-02 02:43:12,670 - DEBUG - Requesting: http://10.0.0.73:8082/project/2062099b-383c-4bc6-80f8-0875eb4a8dd6
2026-04-02 02:43:12,764 - DEBUG - Requesting: http://10.0.0.73:8082/virtual-network/495b3b80-973a-4b33-8588-451ce3b87b31
2026-04-02 02:43:12,785 - DEBUG - Requesting: http://10.0.0.73:8082/network-policy/c457bda7-a1ec-4435-88d0-925cced33569
2026-04-02 02:43:12,796 - DEBUG - Requesting: http://10.0.0.73:8082/network-policy/4e81d1cf-4446-4ae2-9b5a-5c53b8097b10
2026-04-02 02:43:12,948 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-60337406', 'ctest-policy12-01072489'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-60337406', 'ctest-policy13-04312563']] from ctest-VN1-97689077
2026-04-02 02:43:12,986 - INFO - Deleted policy ctest-policy21-99084139
2026-04-02 02:43:13,037 - INFO - Deleted policy ctest-policy12-01072489
2026-04-02 02:43:13,091 - INFO - Deleted policy ctest-policy13-04312563
2026-04-02 02:43:13,091 - INFO - Deleting VN ctest-VN3-67864215
2026-04-02 02:43:13,423 - DEBUG - Response for deleting network ()
2026-04-02 02:43:13,423 - DEBUG - Requesting: http://10.0.0.73:8082/routing-instance/100984c2-8b3b-42b6-a8e7-f111b198e157
2026-04-02 02:43:13,429 - DEBUG - Response Code: 404
2026-04-02 02:43:13,429 - DEBUG - Requesting: http://10.0.0.73:8082/domains
2026-04-02 02:43:13,434 - DEBUG - Requesting: http://10.0.0.73:8082/domain/2c17115e-1a6e-47b0-9bfc-9e380916d58b
2026-04-02 02:43:13,445 - DEBUG - Requesting: http://10.0.0.73:8082/project/2062099b-383c-4bc6-80f8-0875eb4a8dd6
2026-04-02 02:43:13,551 - INFO - Validated that VN ctest-VN3-67864215 is not found in API Server
2026-04-02 02:43:13,565 - DEBUG - VN ctest-VN3-67864215 is not present in Agent 10.0.0.50
2026-04-02 02:43:13,576 - DEBUG - VN ctest-VN3-67864215 is not present in Agent 10.0.0.128
2026-04-02 02:43:13,576 - INFO - Validated that VN ctest-VN3-67864215 is not in any agent
2026-04-02 02:43:13,621 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN3-67864215 info
2026-04-02 02:43:13,621 - INFO - Deleting VN ctest-VN2-24772300
2026-04-02 02:43:13,822 - DEBUG - Response for deleting network ()
2026-04-02 02:43:13,822 - DEBUG - Requesting: http://10.0.0.73:8082/routing-instance/2a60654a-58a7-47ad-a4c3-484ad4fdfd2b
2026-04-02 02:43:13,833 - DEBUG - Response Code: 404
2026-04-02 02:43:13,833 - DEBUG - Requesting: http://10.0.0.73:8082/domains
2026-04-02 02:43:13,840 - DEBUG - Requesting: http://10.0.0.73:8082/domain/2c17115e-1a6e-47b0-9bfc-9e380916d58b
2026-04-02 02:43:13,851 - DEBUG - Requesting: http://10.0.0.73:8082/project/2062099b-383c-4bc6-80f8-0875eb4a8dd6
2026-04-02 02:43:13,934 - INFO - Validated that VN ctest-VN2-24772300 is not found in API Server
2026-04-02 02:43:13,947 - DEBUG - VN ctest-VN2-24772300 is not present in Agent 10.0.0.50
2026-04-02 02:43:13,956 - DEBUG - VN ctest-VN2-24772300 is not present in Agent 10.0.0.128
2026-04-02 02:43:13,956 - INFO - Validated that VN ctest-VN2-24772300 is not in any agent
2026-04-02 02:43:13,990 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN2-24772300 info
2026-04-02 02:43:13,990 - INFO - Deleting VN ctest-VN1-97689077
2026-04-02 02:43:14,177 - DEBUG - Response for deleting network ()
2026-04-02 02:43:14,181 - DEBUG - Requesting: http://10.0.0.73:8082/routing-instance/8f3b9ada-5dde-4c78-8867-d80e1d25751a
2026-04-02 02:43:14,192 - DEBUG - Response Code: 404
2026-04-02 02:43:14,192 - DEBUG - Requesting: http://10.0.0.73:8082/domains
2026-04-02 02:43:14,200 - DEBUG - Requesting: http://10.0.0.73:8082/domain/2c17115e-1a6e-47b0-9bfc-9e380916d58b
2026-04-02 02:43:14,254 - DEBUG - Requesting: http://10.0.0.73:8082/project/2062099b-383c-4bc6-80f8-0875eb4a8dd6
2026-04-02 02:43:14,338 - INFO - Validated that VN ctest-VN1-97689077 is not found in API Server
2026-04-02 02:43:14,349 - DEBUG - VN ctest-VN1-97689077 is not present in Agent 10.0.0.50
2026-04-02 02:43:14,358 - DEBUG - VN ctest-VN1-97689077 is not present in Agent 10.0.0.128
2026-04-02 02:43:14,359 - INFO - Validated that VN ctest-VN1-97689077 is not in any agent
2026-04-02 02:43:14,393 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN1-97689077 info
2026-04-02 02:43:14,485 - INFO - IPAM: ipam3 is not found in API Server
2026-04-02 02:43:14,514 - INFO - IPAM:ipam3 is not found in control node
2026-04-02 02:43:14,657 - INFO - IPAM: ipam2 is not found in API Server
2026-04-02 02:43:14,678 - INFO - IPAM:ipam2 is not found in control node
2026-04-02 02:43:14,849 - INFO - IPAM: ipam1 is not found in API Server
2026-04-02 02:43:14,872 - INFO - IPAM:ipam1 is not found in control node
2026-04-02 02:43:16,152 - DEBUG - No XMPP flaps were noticed during the test
2026-04-02 02:43:16,153 - INFO - END TEST : test_policy_inheritance_src_pol_dst_any : PASSED[0:02:56]
2026-04-02 02:43:16,153 - INFO - --------------------------------------------------------------------------------
2026-04-02 02:43:17,424 - DEBUG - No XMPP flaps were noticed during the test
2026-04-02 02:43:17,424 - INFO - END TEST : test_policy_inheritance_src_pol_dst_any : PASSED[0:02:59]
2026-04-02 02:43:17,424 - INFO - --------------------------------------------------------------------------------
2026-04-02 02:43:18,155 - INFO - Deleted project: ctest-TestPolicyAclIpv4v6-60337406, ID : 2062099b-383c-4bc6-80f8-0875eb4a8dd6