2026-06-03 17:46:18,835 - INFO - Domain Default found not creating
2026-06-03 17:46:19,055 - INFO - Project ctest-TestPolicyAclIpv4v6-35807875 not found, creating it
2026-06-03 17:46:19,670 - INFO - Created Project:ctest-TestPolicyAclIpv4v6-35807875, ID : 7ed12a2e-484d-4264-82e2-9cbae0a87532
2026-06-03 17:46:21,279 - INFO - ================================================================================
2026-06-03 17:46:21,279 - INFO - STARTING TEST : test_policy_inheritance_src_pol_dst_any
2026-06-03 17:46:22,567 - DEBUG - Nothing to compare xmpp stats {'10.0.0.22': {'10.20.0.25': '0', '10.20.0.17': '0'}, '10.0.0.19': {'10.20.0.25': '0', '10.20.0.17': '0'}} with
2026-06-03 17:46:22,567 - INFO - Initial checks done. Running the testcase now
2026-06-03 17:46:22,567 - INFO -
2026-06-03 17:46:22,567 - INFO - ================================================================================
2026-06-03 17:46:22,567 - INFO - STARTING TEST : test_policy_inheritance_src_pol_dst_any
2026-06-03 17:46:22,567 - INFO - TEST DESCRIPTION : Test cases to test policy inheritance
2026-06-03 17:46:23,896 - DEBUG - Nothing to compare xmpp stats {'10.0.0.22': {'10.20.0.25': '0', '10.20.0.17': '0'}, '10.0.0.19': {'10.20.0.25': '0', '10.20.0.17': '0'}} with
2026-06-03 17:46:23,897 - INFO - Initial checks done. Running the testcase now
2026-06-03 17:46:23,897 - INFO -
2026-06-03 17:46:25,249 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-06-03 17:46:25,549 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-06-03 17:46:25,590 - DEBUG - Requesting: http://10.0.0.50:8082/domain/cfce58c2-bdea-4e41-80d8-e31e3dad5e9f
2026-06-03 17:46:25,608 - DEBUG - Requesting: http://10.0.0.50:8082/project/7ed12a2e-484d-4264-82e2-9cbae0a87532
2026-06-03 17:46:25,714 - DEBUG - Requesting: http://10.0.0.50:8082/network-ipam/3491293e-b47a-473e-b8fd-41305b8a938c
2026-06-03 17:46:25,722 - INFO - Verifications in API Server for IPAM: ipam1 passed
2026-06-03 17:46:25,730 - DEBUG - Control-node 10.0.0.50 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-35807875:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-35807875', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3787854110887921470', 'uuid-lslong': '13329882148036580236'}, 'enable': 'true', 'created': '2026-06-03T17:46:24', 'last-modified': '2026-06-03T17:46:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.216773'}
2026-06-03 17:46:25,737 - DEBUG - Control-node 10.0.0.33 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-35807875:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-35807875', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3787854110887921470', 'uuid-lslong': '13329882148036580236'}, 'enable': 'true', 'created': '2026-06-03T17:46:24', 'last-modified': '2026-06-03T17:46:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.226884'}
2026-06-03 17:46:25,745 - DEBUG - Control-node 10.0.0.249 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-35807875:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-35807875', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3787854110887921470', 'uuid-lslong': '13329882148036580236'}, 'enable': 'true', 'created': '2026-06-03T17:46:24', 'last-modified': '2026-06-03T17:46:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.231829'}
2026-06-03 17:46:25,745 - INFO - Verifications in Control node for IPAM: ipam1 passed
2026-06-03 17:46:25,745 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-06-03 17:46:25,754 - DEBUG - Requesting: http://10.0.0.50:8082/domain/cfce58c2-bdea-4e41-80d8-e31e3dad5e9f
2026-06-03 17:46:25,769 - DEBUG - Requesting: http://10.0.0.50:8082/project/7ed12a2e-484d-4264-82e2-9cbae0a87532
2026-06-03 17:46:25,886 - DEBUG - Requesting: http://10.0.0.50:8082/network-ipam/29b39610-eec6-40e6-8933-fab94c40e25b
2026-06-03 17:46:25,893 - INFO - Verifications in API Server for IPAM: ipam2 passed
2026-06-03 17:46:25,901 - DEBUG - Control-node 10.0.0.50 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-35807875:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-35807875', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3004910375855866086', 'uuid-lslong': '9886521280763585115'}, 'enable': 'true', 'created': '2026-06-03T17:46:24', 'last-modified': '2026-06-03T17:46:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.025308'}
2026-06-03 17:46:25,906 - DEBUG - Control-node 10.0.0.33 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-35807875:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-35807875', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3004910375855866086', 'uuid-lslong': '9886521280763585115'}, 'enable': 'true', 'created': '2026-06-03T17:46:24', 'last-modified': '2026-06-03T17:46:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.028892'}
2026-06-03 17:46:25,912 - DEBUG - Control-node 10.0.0.249 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-35807875:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-35807875', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3004910375855866086', 'uuid-lslong': '9886521280763585115'}, 'enable': 'true', 'created': '2026-06-03T17:46:24', 'last-modified': '2026-06-03T17:46:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.034207'}
2026-06-03 17:46:25,912 - INFO - Verifications in Control node for IPAM: ipam2 passed
2026-06-03 17:46:25,912 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-06-03 17:46:25,920 - DEBUG - Requesting: http://10.0.0.50:8082/domain/cfce58c2-bdea-4e41-80d8-e31e3dad5e9f
2026-06-03 17:46:25,934 - DEBUG - Requesting: http://10.0.0.50:8082/project/7ed12a2e-484d-4264-82e2-9cbae0a87532
2026-06-03 17:46:26,045 - DEBUG - Requesting: http://10.0.0.50:8082/network-ipam/7fd08c2e-8638-41f2-b75e-751aded6f214
2026-06-03 17:46:26,057 - INFO - Verifications in API Server for IPAM: ipam3 passed
2026-06-03 17:46:26,062 - DEBUG - Control-node 10.0.0.50 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-35807875:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-35807875', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9210015369420882418', 'uuid-lslong': '13213127115019842068'}, 'enable': 'true', 'created': '2026-06-03T17:46:24', 'last-modified': '2026-06-03T17:46:25', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.838267'}
2026-06-03 17:46:26,069 - DEBUG - Control-node 10.0.0.33 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-35807875:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-35807875', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9210015369420882418', 'uuid-lslong': '13213127115019842068'}, 'enable': 'true', 'created': '2026-06-03T17:46:24', 'last-modified': '2026-06-03T17:46:25', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.842045'}
2026-06-03 17:46:26,075 - DEBUG - Control-node 10.0.0.249 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-35807875:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-35807875', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9210015369420882418', 'uuid-lslong': '13213127115019842068'}, 'enable': 'true', 'created': '2026-06-03T17:46:24', 'last-modified': '2026-06-03T17:46:25', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.849627'}
2026-06-03 17:46:26,075 - INFO - Verifications in Control node for IPAM: ipam3 passed
2026-06-03 17:46:26,457 - DEBUG - Response for create_network : {'network': {'id': '2451254e-ac88-417e-b09a-dbe9443a3cbb', 'name': 'ctest-VN1-59419945', 'tenant_id': '7ed12a2e484d426482e29cbae0a87532', 'project_id': '7ed12a2e484d426482e29cbae0a87532', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-35807875', 'ctest-VN1-59419945'], 'port_security_enabled': True, 'description': ''}}
2026-06-03 17:46:26,769 - DEBUG - Response for create_subnet : {'subnet': {'id': '07801c59-d08c-40e7-997c-b71557ab8afe', 'name': '', 'tenant_id': '7ed12a2e484d426482e29cbae0a87532', 'network_id': '2451254e-ac88-417e-b09a-dbe9443a3cbb', 'ip_version': 4, 'cidr': '123.11.242.64/26', 'allocation_pools': [{'start': '123.11.242.66', 'end': '123.11.242.126'}], 'gateway_ip': '123.11.242.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '123.11.242.66', 'tags': [], 'project_id': '7ed12a2e484d426482e29cbae0a87532'}}
2026-06-03 17:46:27,009 - DEBUG - Response for create_subnet : {'subnet': {'id': '9be791d6-e19c-4adc-b882-4dd0627c0bae', 'name': '', 'tenant_id': '7ed12a2e484d426482e29cbae0a87532', 'network_id': '2451254e-ac88-417e-b09a-dbe9443a3cbb', 'ip_version': 6, 'cidr': '2347:ffec:7d02:386:9159:f5f6::/96', 'allocation_pools': [{'start': '2347:ffec:7d02:386:9159:f5f6:0:2', 'end': '2347:ffec:7d02:386:9159:f5f6:ffff:fffe'}], 'gateway_ip': '2347:ffec:7d02:386:9159:f5f6:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '2347:ffec:7d02:386:9159:f5f6:0:2', 'tags': [], 'project_id': '7ed12a2e484d426482e29cbae0a87532'}}
2026-06-03 17:46:27,042 - INFO - Created VN ctest-VN1-59419945
2026-06-03 17:46:27,057 - DEBUG - VN ctest-VN1-59419945 UUID is 2451254e-ac88-417e-b09a-dbe9443a3cbb
2026-06-03 17:46:27,269 - DEBUG - Fetched VN: default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945(2451254e-ac88-417e-b09a-dbe9443a3cbb) with subnets ['123.11.242.64/26', '2347:ffec:7d02:386:9159:f5f6::/96']
2026-06-03 17:46:27,634 - DEBUG - Response for create_network : {'network': {'id': '603e4114-1c86-44d4-958c-122e0e77d8a4', 'name': 'ctest-VN2-64579439', 'tenant_id': '7ed12a2e484d426482e29cbae0a87532', 'project_id': '7ed12a2e484d426482e29cbae0a87532', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-35807875', 'ctest-VN2-64579439'], 'port_security_enabled': True, 'description': ''}}
2026-06-03 17:46:27,901 - DEBUG - Response for create_subnet : {'subnet': {'id': 'aff175d3-c9d5-4eac-9cf0-04a8919e48cc', 'name': '', 'tenant_id': '7ed12a2e484d426482e29cbae0a87532', 'network_id': '603e4114-1c86-44d4-958c-122e0e77d8a4', 'ip_version': 4, 'cidr': '102.136.97.64/26', 'allocation_pools': [{'start': '102.136.97.66', 'end': '102.136.97.126'}], 'gateway_ip': '102.136.97.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '102.136.97.66', 'tags': [], 'project_id': '7ed12a2e484d426482e29cbae0a87532'}}
2026-06-03 17:46:28,090 - DEBUG - Response for create_subnet : {'subnet': {'id': '789280eb-f3a9-4aec-b1a9-120e4f0b87ad', 'name': '', 'tenant_id': '7ed12a2e484d426482e29cbae0a87532', 'network_id': '603e4114-1c86-44d4-958c-122e0e77d8a4', 'ip_version': 6, 'cidr': '2bfd:cad2:cd91:67de:5a40:7dc7::/96', 'allocation_pools': [{'start': '2bfd:cad2:cd91:67de:5a40:7dc7:0:2', 'end': '2bfd:cad2:cd91:67de:5a40:7dc7:ffff:fffe'}], 'gateway_ip': '2bfd:cad2:cd91:67de:5a40:7dc7:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '2bfd:cad2:cd91:67de:5a40:7dc7:0:2', 'tags': [], 'project_id': '7ed12a2e484d426482e29cbae0a87532'}}
2026-06-03 17:46:28,121 - INFO - Created VN ctest-VN2-64579439
2026-06-03 17:46:28,133 - DEBUG - VN ctest-VN2-64579439 UUID is 603e4114-1c86-44d4-958c-122e0e77d8a4
2026-06-03 17:46:28,561 - DEBUG - Response for create_network : {'network': {'id': '2352a398-1364-4bb7-aaa8-bc458e584e30', 'name': 'ctest-VN3-88266431', 'tenant_id': '7ed12a2e484d426482e29cbae0a87532', 'project_id': '7ed12a2e484d426482e29cbae0a87532', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-35807875', 'ctest-VN3-88266431'], 'port_security_enabled': True, 'description': ''}}
2026-06-03 17:46:28,829 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e769e13e-d855-425f-9b6e-e6494fdabe3a', 'name': '', 'tenant_id': '7ed12a2e484d426482e29cbae0a87532', 'network_id': '2352a398-1364-4bb7-aaa8-bc458e584e30', 'ip_version': 4, 'cidr': '174.199.34.192/26', 'allocation_pools': [{'start': '174.199.34.194', 'end': '174.199.34.254'}], 'gateway_ip': '174.199.34.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '174.199.34.194', 'tags': [], 'project_id': '7ed12a2e484d426482e29cbae0a87532'}}
2026-06-03 17:46:29,087 - DEBUG - Response for create_subnet : {'subnet': {'id': '3a821aef-b44a-47b3-9782-6508a562edd8', 'name': '', 'tenant_id': '7ed12a2e484d426482e29cbae0a87532', 'network_id': '2352a398-1364-4bb7-aaa8-bc458e584e30', 'ip_version': 6, 'cidr': '3b16:7:cf4f:6ec6:5c31:78c3::/96', 'allocation_pools': [{'start': '3b16:7:cf4f:6ec6:5c31:78c3:0:2', 'end': '3b16:7:cf4f:6ec6:5c31:78c3:ffff:fffe'}], 'gateway_ip': '3b16:7:cf4f:6ec6:5c31:78c3:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '3b16:7:cf4f:6ec6:5c31:78c3:0:2', 'tags': [], 'project_id': '7ed12a2e484d426482e29cbae0a87532'}}
2026-06-03 17:46:29,118 - INFO - Created VN ctest-VN3-88266431
2026-06-03 17:46:29,130 - DEBUG - VN ctest-VN3-88266431 UUID is 2352a398-1364-4bb7-aaa8-bc458e584e30
2026-06-03 17:46:29,241 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-06-03 17:46:29,248 - DEBUG - Requesting: http://10.0.0.50:8082/domain/cfce58c2-bdea-4e41-80d8-e31e3dad5e9f
2026-06-03 17:46:29,261 - DEBUG - Requesting: http://10.0.0.50:8082/project/7ed12a2e-484d-4264-82e2-9cbae0a87532
2026-06-03 17:46:29,359 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/2451254e-ac88-417e-b09a-dbe9443a3cbb
2026-06-03 17:46:29,376 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/2451254e-ac88-417e-b09a-dbe9443a3cbb
2026-06-03 17:46:29,387 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/4ea162db-8767-4f41-b713-bcf2eba352a1
2026-06-03 17:46:29,395 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/4ea162db-8767-4f41-b713-bcf2eba352a1
2026-06-03 17:46:29,404 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/1dad30dd-f61e-4ef8-b877-7bb268fc9144
2026-06-03 17:46:29,412 - DEBUG - Route Targets: ['target:64512:8000004']
2026-06-03 17:46:29,413 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/2451254e-ac88-417e-b09a-dbe9443a3cbb
2026-06-03 17:46:29,425 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/4ea162db-8767-4f41-b713-bcf2eba352a1
2026-06-03 17:46:29,445 - INFO - Verified VN network id 14 for VN 2451254e-ac88-417e-b09a-dbe9443a3cbb
2026-06-03 17:46:29,445 - INFO - Verifications in API Server for VN ctest-VN1-59419945 passed
2026-06-03 17:46:29,445 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/2451254e-ac88-417e-b09a-dbe9443a3cbb
2026-06-03 17:46:29,458 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/4ea162db-8767-4f41-b713-bcf2eba352a1
2026-06-03 17:46:29,467 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/4ea162db-8767-4f41-b713-bcf2eba352a1
2026-06-03 17:46:29,476 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/1dad30dd-f61e-4ef8-b877-7bb268fc9144
2026-06-03 17:46:29,489 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-35807875', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2616913878311256446', 'uuid-lslong': '12725725492007287995'}, 'enable': 'true', 'created': '2026-06-03T17:46:26', 'last-modified': '2026-06-03T17:46:26', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.524972'}
2026-06-03 17:46:29,495 - DEBUG - Route Targets: ['target:64512:8000004']
2026-06-03 17:46:29,501 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-35807875', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2616913878311256446', 'uuid-lslong': '12725725492007287995'}, 'enable': 'true', 'created': '2026-06-03T17:46:26', 'last-modified': '2026-06-03T17:46:26', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.539171'}
2026-06-03 17:46:29,510 - DEBUG - Route Targets: ['target:64512:8000004']
2026-06-03 17:46:29,516 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-35807875', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2616913878311256446', 'uuid-lslong': '12725725492007287995'}, 'enable': 'true', 'created': '2026-06-03T17:46:26', 'last-modified': '2026-06-03T17:46:26', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.554459'}
2026-06-03 17:46:29,523 - DEBUG - Route Targets: ['target:64512:8000004']
2026-06-03 17:46:29,523 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN1-59419945 passed
2026-06-03 17:46:29,523 - DEBUG - ====Verifying policy data for ctest-VN1-59419945 in API_Server ======
2026-06-03 17:46:29,523 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-06-03 17:46:29,532 - DEBUG - Requesting: http://10.0.0.50:8082/domain/cfce58c2-bdea-4e41-80d8-e31e3dad5e9f
2026-06-03 17:46:29,547 - DEBUG - Requesting: http://10.0.0.50:8082/project/7ed12a2e-484d-4264-82e2-9cbae0a87532
2026-06-03 17:46:29,650 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/2451254e-ac88-417e-b09a-dbe9443a3cbb
2026-06-03 17:46:29,660 - DEBUG - =>VN ctest-VN1-59419945 has no policy to be verified
2026-06-03 17:46:29,660 - DEBUG - Verifying the vn in opserver
2026-06-03 17:46:29,660 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945 virtual network link through opserver 10.0.0.50
2026-06-03 17:46:29,661 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2026-06-03 17:46:29,959 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2026-06-03 17:46:30,034 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945?flat'}
2026-06-03 17:46:30,034 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945 virtual network link through opserver 10.0.0.33
2026-06-03 17:46:30,034 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2026-06-03 17:46:30,332 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2026-06-03 17:46:30,409 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945?flat'}
2026-06-03 17:46:30,409 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945 virtual network link through opserver 10.0.0.249
2026-06-03 17:46:30,409 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2026-06-03 17:46:30,703 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2026-06-03 17:46:30,777 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945?flat'}
2026-06-03 17:46:30,777 - INFO - Validated that VN default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945 is found in opserver
2026-06-03 17:46:30,812 - DEBUG - Do not have enough data to verify VN in agent
2026-06-03 17:46:30,825 - DEBUG - VRF ids for VN ctest-VN1-59419945: {}
2026-06-03 17:46:30,825 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-06-03 17:46:30,832 - DEBUG - Requesting: http://10.0.0.50:8082/domain/cfce58c2-bdea-4e41-80d8-e31e3dad5e9f
2026-06-03 17:46:30,844 - DEBUG - Requesting: http://10.0.0.50:8082/project/7ed12a2e-484d-4264-82e2-9cbae0a87532
2026-06-03 17:46:30,949 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/603e4114-1c86-44d4-958c-122e0e77d8a4
2026-06-03 17:46:30,964 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/603e4114-1c86-44d4-958c-122e0e77d8a4
2026-06-03 17:46:30,977 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/28417804-6fcd-42b3-b4a6-ccc373ba12f1
2026-06-03 17:46:30,986 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/28417804-6fcd-42b3-b4a6-ccc373ba12f1
2026-06-03 17:46:30,994 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/bd6824d0-18d3-42e1-8735-95a4eec49ca8
2026-06-03 17:46:31,003 - DEBUG - Route Targets: ['target:64512:8000006']
2026-06-03 17:46:31,004 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/603e4114-1c86-44d4-958c-122e0e77d8a4
2026-06-03 17:46:31,014 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/28417804-6fcd-42b3-b4a6-ccc373ba12f1
2026-06-03 17:46:31,032 - INFO - Verified VN network id 15 for VN 603e4114-1c86-44d4-958c-122e0e77d8a4
2026-06-03 17:46:31,032 - INFO - Verifications in API Server for VN ctest-VN2-64579439 passed
2026-06-03 17:46:31,032 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/603e4114-1c86-44d4-958c-122e0e77d8a4
2026-06-03 17:46:31,047 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/28417804-6fcd-42b3-b4a6-ccc373ba12f1
2026-06-03 17:46:31,056 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/28417804-6fcd-42b3-b4a6-ccc373ba12f1
2026-06-03 17:46:31,066 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/bd6824d0-18d3-42e1-8735-95a4eec49ca8
2026-06-03 17:46:31,079 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN2-64579439', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-35807875', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6935052030830855380', 'uuid-lslong': '10776007997411285156'}, 'enable': 'true', 'created': '2026-06-03T17:46:27', 'last-modified': '2026-06-03T17:46:28', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.048603'}
2026-06-03 17:46:31,087 - DEBUG - Route Targets: ['target:64512:8000006']
2026-06-03 17:46:31,094 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN2-64579439', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-35807875', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6935052030830855380', 'uuid-lslong': '10776007997411285156'}, 'enable': 'true', 'created': '2026-06-03T17:46:27', 'last-modified': '2026-06-03T17:46:28', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.054687'}
2026-06-03 17:46:31,102 - DEBUG - Route Targets: ['target:64512:8000006']
2026-06-03 17:46:31,108 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN2-64579439', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-35807875', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6935052030830855380', 'uuid-lslong': '10776007997411285156'}, 'enable': 'true', 'created': '2026-06-03T17:46:27', 'last-modified': '2026-06-03T17:46:28', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.071762'}
2026-06-03 17:46:31,114 - DEBUG - Route Targets: ['target:64512:8000006']
2026-06-03 17:46:31,114 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN2-64579439 passed
2026-06-03 17:46:31,115 - DEBUG - ====Verifying policy data for ctest-VN2-64579439 in API_Server ======
2026-06-03 17:46:31,115 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-06-03 17:46:31,121 - DEBUG - Requesting: http://10.0.0.50:8082/domain/cfce58c2-bdea-4e41-80d8-e31e3dad5e9f
2026-06-03 17:46:31,137 - DEBUG - Requesting: http://10.0.0.50:8082/project/7ed12a2e-484d-4264-82e2-9cbae0a87532
2026-06-03 17:46:31,241 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/603e4114-1c86-44d4-958c-122e0e77d8a4
2026-06-03 17:46:31,253 - DEBUG - =>VN ctest-VN2-64579439 has no policy to be verified
2026-06-03 17:46:31,253 - DEBUG - Verifying the vn in opserver
2026-06-03 17:46:31,253 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN2-64579439 virtual network link through opserver 10.0.0.50
2026-06-03 17:46:31,253 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2026-06-03 17:46:31,341 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN2-64579439', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN2-64579439?flat'}
2026-06-03 17:46:31,341 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN2-64579439 virtual network link through opserver 10.0.0.33
2026-06-03 17:46:31,341 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2026-06-03 17:46:31,420 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN2-64579439', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN2-64579439?flat'}
2026-06-03 17:46:31,420 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN2-64579439 virtual network link through opserver 10.0.0.249
2026-06-03 17:46:31,420 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2026-06-03 17:46:31,488 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN2-64579439', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN2-64579439?flat'}
2026-06-03 17:46:31,488 - INFO - Validated that VN default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN2-64579439 is found in opserver
2026-06-03 17:46:31,517 - DEBUG - Do not have enough data to verify VN in agent
2026-06-03 17:46:31,530 - DEBUG - VRF ids for VN ctest-VN2-64579439: {}
2026-06-03 17:46:31,530 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-06-03 17:46:31,536 - DEBUG - Requesting: http://10.0.0.50:8082/domain/cfce58c2-bdea-4e41-80d8-e31e3dad5e9f
2026-06-03 17:46:31,548 - DEBUG - Requesting: http://10.0.0.50:8082/project/7ed12a2e-484d-4264-82e2-9cbae0a87532
2026-06-03 17:46:31,651 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/2352a398-1364-4bb7-aaa8-bc458e584e30
2026-06-03 17:46:31,663 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/2352a398-1364-4bb7-aaa8-bc458e584e30
2026-06-03 17:46:31,674 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/6f8143c5-3a04-42d7-a029-d0ff31ddc461
2026-06-03 17:46:31,684 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/6f8143c5-3a04-42d7-a029-d0ff31ddc461
2026-06-03 17:46:31,692 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/1e9743c0-c6b4-43d6-835a-2dee4c9cceb4
2026-06-03 17:46:31,700 - DEBUG - Route Targets: ['target:64512:8000007']
2026-06-03 17:46:31,700 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/2352a398-1364-4bb7-aaa8-bc458e584e30
2026-06-03 17:46:31,710 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/6f8143c5-3a04-42d7-a029-d0ff31ddc461
2026-06-03 17:46:31,727 - INFO - Verified VN network id 16 for VN 2352a398-1364-4bb7-aaa8-bc458e584e30
2026-06-03 17:46:31,727 - INFO - Verifications in API Server for VN ctest-VN3-88266431 passed
2026-06-03 17:46:31,727 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/2352a398-1364-4bb7-aaa8-bc458e584e30
2026-06-03 17:46:31,738 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/6f8143c5-3a04-42d7-a029-d0ff31ddc461
2026-06-03 17:46:31,745 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/6f8143c5-3a04-42d7-a029-d0ff31ddc461
2026-06-03 17:46:31,752 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/1e9743c0-c6b4-43d6-835a-2dee4c9cceb4
2026-06-03 17:46:31,764 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN3-88266431', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-35807875', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2545276612973448119', 'uuid-lslong': '12297285789462056496'}, 'enable': 'true', 'created': '2026-06-03T17:46:28', 'last-modified': '2026-06-03T17:46:29', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.715379'}
2026-06-03 17:46:31,770 - DEBUG - Route Targets: ['target:64512:8000007']
2026-06-03 17:46:31,776 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN3-88266431', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-35807875', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2545276612973448119', 'uuid-lslong': '12297285789462056496'}, 'enable': 'true', 'created': '2026-06-03T17:46:28', 'last-modified': '2026-06-03T17:46:29', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.732492'}
2026-06-03 17:46:31,783 - DEBUG - Route Targets: ['target:64512:8000007']
2026-06-03 17:46:31,791 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN3-88266431', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-35807875', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2545276612973448119', 'uuid-lslong': '12297285789462056496'}, 'enable': 'true', 'created': '2026-06-03T17:46:28', 'last-modified': '2026-06-03T17:46:29', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.751874'}
2026-06-03 17:46:31,798 - DEBUG - Route Targets: ['target:64512:8000007']
2026-06-03 17:46:31,798 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN3-88266431 passed
2026-06-03 17:46:31,798 - DEBUG - ====Verifying policy data for ctest-VN3-88266431 in API_Server ======
2026-06-03 17:46:31,798 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-06-03 17:46:31,805 - DEBUG - Requesting: http://10.0.0.50:8082/domain/cfce58c2-bdea-4e41-80d8-e31e3dad5e9f
2026-06-03 17:46:31,818 - DEBUG - Requesting: http://10.0.0.50:8082/project/7ed12a2e-484d-4264-82e2-9cbae0a87532
2026-06-03 17:46:31,940 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/2352a398-1364-4bb7-aaa8-bc458e584e30
2026-06-03 17:46:31,960 - DEBUG - =>VN ctest-VN3-88266431 has no policy to be verified
2026-06-03 17:46:31,960 - DEBUG - Verifying the vn in opserver
2026-06-03 17:46:31,960 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN3-88266431 virtual network link through opserver 10.0.0.50
2026-06-03 17:46:31,960 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2026-06-03 17:46:32,027 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN3-88266431', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN3-88266431?flat'}
2026-06-03 17:46:32,027 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN3-88266431 virtual network link through opserver 10.0.0.33
2026-06-03 17:46:32,027 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2026-06-03 17:46:32,091 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN3-88266431', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN3-88266431?flat'}
2026-06-03 17:46:32,091 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN3-88266431 virtual network link through opserver 10.0.0.249
2026-06-03 17:46:32,091 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2026-06-03 17:46:32,153 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN3-88266431', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN3-88266431?flat'}
2026-06-03 17:46:32,153 - INFO - Validated that VN default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN3-88266431 is found in opserver
2026-06-03 17:46:32,176 - DEBUG - Do not have enough data to verify VN in agent
2026-06-03 17:46:32,189 - DEBUG - VRF ids for VN ctest-VN3-88266431: {}
2026-06-03 17:46:32,196 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945, 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-35807875:ctest-VN3-88266431, 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-06-03 17:46:32,485 - DEBUG - No source network defined
2026-06-03 17:46:32,485 - DEBUG - No source network defined
2026-06-03 17:46:32,485 - 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-35807875:ctest-policy13-73676923, 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-35807875:ctest-policy13-73676923, 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-06-03 17:46:32,781 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN2-64579439, 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-35807875:ctest-VN1-59419945, 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-35807875:ctest-VN2-64579439, 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-35807875:ctest-VN1-59419945, 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-06-03 17:46:33,011 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-06-03 17:46:33,018 - DEBUG - Requesting: http://10.0.0.50:8082/domain/cfce58c2-bdea-4e41-80d8-e31e3dad5e9f
2026-06-03 17:46:33,030 - DEBUG - Requesting: http://10.0.0.50:8082/project/7ed12a2e-484d-4264-82e2-9cbae0a87532
2026-06-03 17:46:33,138 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/2451254e-ac88-417e-b09a-dbe9443a3cbb
2026-06-03 17:46:33,150 - DEBUG - Setup step: Associating the policy to VN'
2026-06-03 17:46:33,294 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '2451254e-ac88-417e-b09a-dbe9443a3cbb', 'name': 'ctest-VN1-59419945', 'tenant_id': '7ed12a2e484d426482e29cbae0a87532', 'project_id': '7ed12a2e484d426482e29cbae0a87532', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['07801c59-d08c-40e7-997c-b71557ab8afe', '9be791d6-e19c-4adc-b882-4dd0627c0bae'], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-35807875', 'ctest-VN1-59419945'], 'policys': [['default-domain', 'ctest-TestPolicyAclIpv4v6-35807875', 'ctest-policy12-59289002'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-35807875', 'ctest-policy13-73676923']], 'subnet_ipam': [{'subnet_cidr': '123.11.242.64/26', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-35807875', 'ipam1']}, {'subnet_cidr': '2347:ffec:7d02:386:9159:f5f6::/96', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-35807875', 'ipam1']}], 'port_security_enabled': True, 'description': ''}}
2026-06-03 17:46:33,444 - DEBUG - Associated Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-35807875', 'ctest-policy12-59289002'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-35807875', 'ctest-policy13-73676923']] to ctest-VN1-59419945
2026-06-03 17:46:33,445 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-06-03 17:46:33,451 - DEBUG - Requesting: http://10.0.0.50:8082/domain/cfce58c2-bdea-4e41-80d8-e31e3dad5e9f
2026-06-03 17:46:33,462 - DEBUG - Requesting: http://10.0.0.50:8082/project/7ed12a2e-484d-4264-82e2-9cbae0a87532
2026-06-03 17:46:33,563 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/603e4114-1c86-44d4-958c-122e0e77d8a4
2026-06-03 17:46:33,574 - DEBUG - Setup step: Associating the policy to VN'
2026-06-03 17:46:33,715 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '603e4114-1c86-44d4-958c-122e0e77d8a4', 'name': 'ctest-VN2-64579439', 'tenant_id': '7ed12a2e484d426482e29cbae0a87532', 'project_id': '7ed12a2e484d426482e29cbae0a87532', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['aff175d3-c9d5-4eac-9cf0-04a8919e48cc', '789280eb-f3a9-4aec-b1a9-120e4f0b87ad'], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-35807875', 'ctest-VN2-64579439'], 'policys': [['default-domain', 'ctest-TestPolicyAclIpv4v6-35807875', 'ctest-policy21-50350804']], 'subnet_ipam': [{'subnet_cidr': '102.136.97.64/26', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-35807875', 'ipam2']}, {'subnet_cidr': '2bfd:cad2:cd91:67de:5a40:7dc7::/96', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-35807875', 'ipam2']}], 'port_security_enabled': True, 'description': ''}}
2026-06-03 17:46:33,845 - DEBUG - Associated Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-35807875', 'ctest-policy21-50350804']] to ctest-VN2-64579439
2026-06-03 17:46:33,954 - DEBUG - Services list from nova: [, , , ]
2026-06-03 17:46:35,206 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5883-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5883-1)
2026-06-03 17:46:36,770 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5883-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5883-2)
2026-06-03 17:46:38,610 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5883-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5883-1)
2026-06-03 17:46:38,712 - DEBUG - VM is in ACTIVE state now
2026-06-03 17:46:38,712 - INFO - VM name : ctest-VM11-54109248
2026-06-03 17:46:38,814 - DEBUG - VM ctest-VM11-54109248 ID is 0e068fd9-967e-4efa-a7a7-335d354b9310
2026-06-03 17:46:38,903 - DEBUG - VM is in ACTIVE state now
2026-06-03 17:46:38,927 - DEBUG - VM ctest-VM11-54109248 launched on Node an-jenkins-deploy-platform-ansible-os-5883-1
2026-06-03 17:46:39,067 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/0e068fd9-967e-4efa-a7a7-335d354b9310
2026-06-03 17:46:39,530 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/0e068fd9-967e-4efa-a7a7-335d354b9310
2026-06-03 17:46:39,608 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/0e068fd9-967e-4efa-a7a7-335d354b9310
2026-06-03 17:46:39,616 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/da8e7a3a-3ab3-442f-a72e-65a9eed0aea4
2026-06-03 17:46:39,627 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/0e068fd9-967e-4efa-a7a7-335d354b9310
2026-06-03 17:46:39,634 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/da8e7a3a-3ab3-442f-a72e-65a9eed0aea4
2026-06-03 17:46:39,644 - DEBUG - Requesting: http://10.0.0.249:8082/instance-ip/070da308-e891-4584-aa73-292b248cf648
2026-06-03 17:46:39,654 - DEBUG - Requesting: http://10.0.0.249:8082/instance-ip/3157f661-db9c-4659-a6e6-342bd774e7e0
2026-06-03 17:46:39,663 - DEBUG - Verifying in api server 10.0.0.249
2026-06-03 17:46:39,663 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/0e068fd9-967e-4efa-a7a7-335d354b9310
2026-06-03 17:46:39,671 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/da8e7a3a-3ab3-442f-a72e-65a9eed0aea4
2026-06-03 17:46:39,682 - DEBUG - Requesting: http://10.0.0.249:8082/instance-ip/070da308-e891-4584-aa73-292b248cf648
2026-06-03 17:46:39,693 - DEBUG - Requesting: http://10.0.0.249:8082/instance-ip/3157f661-db9c-4659-a6e6-342bd774e7e0
2026-06-03 17:46:39,703 - INFO - VM ctest-VM11-54109248 verfication in all API Servers passed
2026-06-03 17:46:39,727 - DEBUG - VM ctest-VM11-54109248 Tap interface: {'index': '3', 'name': 'tapda8e7a3a-3a', 'uuid': 'da8e7a3a-3ab3-442f-a72e-65a9eed0aea4', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945:ctest-VN1-59419945', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Active', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Enable', 'dns_service': 'Enable', 'type': 'vport', 'label': '25', 'l2_label': '29', 'vxlan_id': '14', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945', 'vm_uuid': '0e068fd9-967e-4efa-a7a7-335d354b9310', 'vm_name': 'ctest-VM11-54109248', 'ip_addr': '123.11.242.67', 'mac_addr': '02:da:8e:7a:3a:3a', '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-35807875:da8e7a3a-3ab3-442f-a72e-65a9eed0aea4', 'sg_uuid_list': ['f995fd0c-4904-4ec5-9b31-7f39c10da0d6'], 'static_route_list': None, 'vm_project_uuid': '7ed12a2e-484d-4264-82e2-9cbae0a87532', 'admin_state': 'Enabled', 'flow_key_idx': '31', 'allowed_address_pair_list': None, 'ip6_addr': '2347:ffec:7d02:386:9159:f5f6: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-06-03 17:46:39,727 - DEBUG - Agent 10.0.0.22 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945:ctest-VN1-59419945
2026-06-03 17:46:39,739 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945:ctest-VN1-59419945', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945', 'table_label': '-1', 'vxlan_id': '14', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.193:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]}
2026-06-03 17:46:39,849 - DEBUG - VM ctest-VM11-54109248 : Tap interface tapda8e7a3a-3a is set to Active
2026-06-03 17:46:39,849 - DEBUG - Tap interface tapda8e7a3a-3a detail : {'index': '3', 'name': 'tapda8e7a3a-3a', 'uuid': 'da8e7a3a-3ab3-442f-a72e-65a9eed0aea4', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945:ctest-VN1-59419945', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Active', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Enable', 'dns_service': 'Enable', 'type': 'vport', 'label': '25', 'l2_label': '29', 'vxlan_id': '14', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945', 'vm_uuid': '0e068fd9-967e-4efa-a7a7-335d354b9310', 'vm_name': 'ctest-VM11-54109248', 'ip_addr': '123.11.242.67', 'mac_addr': '02:da:8e:7a:3a:3a', '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-35807875:da8e7a3a-3ab3-442f-a72e-65a9eed0aea4', 'sg_uuid_list': ['f995fd0c-4904-4ec5-9b31-7f39c10da0d6'], 'static_route_list': None, 'vm_project_uuid': '7ed12a2e-484d-4264-82e2-9cbae0a87532', 'admin_state': 'Enabled', 'flow_key_idx': '31', 'allowed_address_pair_list': None, 'ip6_addr': '2347:ffec:7d02:386:9159:f5f6: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-06-03 17:46:39,963 - DEBUG - Starting Layer 2 verification in Agent
2026-06-03 17:46:39,981 - DEBUG - Layer 2 path is seen for VM MAC 02:da:8e:7a:3a:3a in agent 10.0.0.22
2026-06-03 17:46:39,982 - DEBUG - Active layer 2 route in agent is present for VMI tapda8e7a3a-3a
2026-06-03 17:46:39,982 - DEBUG - L2 label(29) matches bw route table and interface table
2026-06-03 17:46:39,995 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945 is found in Agent of node 10.0.0.22
2026-06-03 17:46:40,193 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945 is consistent in agent 10.0.0.22
2026-06-03 17:46:40,193 - DEBUG - Route for VM IP ['123.11.242.67', '2347:ffec:7d02:386:9159:f5f6:0:3'] is consistent in agent 10.0.0.22
2026-06-03 17:46:40,193 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945 verification for VM ctest-VM11-54109248 in Agent 10.0.0.22 passed
2026-06-03 17:46:40,267 - DEBUG - Starting all layer 2 verification in agent 10.0.0.22
2026-06-03 17:46:40,278 - DEBUG - Route for VM MAC 02:da:8e:7a:3a:3a is consistent in agent 10.0.0.22
2026-06-03 17:46:43,499 - 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 1032ms')
2026-06-03 17:46:43,499 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-54109248 failed!
2026-06-03 17:46:43,514 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945 is 123.11.242.65 and allocation pool is NOT set
2026-06-03 17:46:43,514 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945 is 2347:ffec:7d02:386:9159:f5f6:0:1 and allocation pool is NOT set
2026-06-03 17:46:47,598 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1017ms')
2026-06-03 17:46:47,598 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-54109248 failed!
2026-06-03 17:46:47,616 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945 is 123.11.242.65 and allocation pool is NOT set
2026-06-03 17:46:47,616 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945 is 2347:ffec:7d02:386:9159:f5f6:0:1 and allocation pool is NOT set
2026-06-03 17:46:51,686 - 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-06-03 17:46:51,686 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-54109248 failed!
2026-06-03 17:46:51,702 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945 is 123.11.242.65 and allocation pool is NOT set
2026-06-03 17:46:51,702 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945 is 2347:ffec:7d02:386:9159:f5f6:0:1 and allocation pool is NOT set
2026-06-03 17:46:55,795 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1023ms')
2026-06-03 17:46:55,795 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-54109248 failed!
2026-06-03 17:46:55,855 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945 is 123.11.242.65 and allocation pool is NOT set
2026-06-03 17:46:55,855 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945 is 2347:ffec:7d02:386:9159:f5f6:0:1 and allocation pool is NOT set
2026-06-03 17:46:59,955 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1032ms')
2026-06-03 17:46:59,955 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-54109248 failed!
2026-06-03 17:46:59,973 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945 is 123.11.242.65 and allocation pool is NOT set
2026-06-03 17:46:59,973 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945 is 2347:ffec:7d02:386:9159:f5f6:0:1 and allocation pool is NOT set
2026-06-03 17:47:04,049 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1010ms')
2026-06-03 17:47:04,049 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-54109248 failed!
2026-06-03 17:47:04,065 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945 is 123.11.242.65 and allocation pool is NOT set
2026-06-03 17:47:04,065 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945 is 2347:ffec:7d02:386:9159:f5f6:0:1 and allocation pool is NOT set
2026-06-03 17:47:08,135 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms')
2026-06-03 17:47:08,135 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-54109248 failed!
2026-06-03 17:47:08,150 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945 is 123.11.242.65 and allocation pool is NOT set
2026-06-03 17:47:08,150 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945 is 2347:ffec:7d02:386:9159:f5f6:0:1 and allocation pool is NOT set
2026-06-03 17:47:12,236 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1027ms')
2026-06-03 17:47:12,236 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-54109248 failed!
2026-06-03 17:47:12,295 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945 is 123.11.242.65 and allocation pool is NOT set
2026-06-03 17:47:12,296 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945 is 2347:ffec:7d02:386:9159:f5f6:0:1 and allocation pool is NOT set
2026-06-03 17:47:16,360 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1007ms')
2026-06-03 17:47:16,360 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-54109248 failed!
2026-06-03 17:47:16,381 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945 is 123.11.242.65 and allocation pool is NOT set
2026-06-03 17:47:16,381 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945 is 2347:ffec:7d02:386:9159:f5f6:0:1 and allocation pool is NOT set
2026-06-03 17:47:20,457 - 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-06-03 17:47:20,458 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-54109248 failed!
2026-06-03 17:47:20,473 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945 is 123.11.242.65 and allocation pool is NOT set
2026-06-03 17:47:20,473 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945 is 2347:ffec:7d02:386:9159:f5f6:0:1 and allocation pool is NOT set
2026-06-03 17:47:24,551 - 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-06-03 17:47:24,552 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-54109248 failed!
2026-06-03 17:47:24,570 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945 is 123.11.242.65 and allocation pool is NOT set
2026-06-03 17:47:24,570 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945 is 2347:ffec:7d02:386:9159:f5f6:0:1 and allocation pool is NOT set
2026-06-03 17:47:26,641 - 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.55 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=3.78 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 3.778/4.665/5.553/0.887 ms')
2026-06-03 17:47:26,641 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-54109248 passed
2026-06-03 17:47:26,641 - INFO - VM ctest-VM11-54109248 verifications in Compute nodes passed
2026-06-03 17:47:26,954 - DEBUG - Validated VM route 123.11.242.67 in vrouter of 10.0.0.22
2026-06-03 17:47:26,961 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0
2026-06-03 17:47:26,961 - INFO - Validated routes of VM ctest-VM11-54109248 in all vrouters
2026-06-03 17:47:27,431 - DEBUG - Starting all layer2 verification in 10.0.0.50 Control Node
2026-06-03 17:47:27,569 - DEBUG - Layer2 route found for VM MAC 02:da:8e:7a:3a:3a in Control-node 10.0.0.50
2026-06-03 17:47:27,569 - DEBUG - L2 Label for VM ctest-VM11-54109248 same between Control-node 10.0.0.50 and Agent, Expected: 29, Seen: 29
2026-06-03 17:47:27,693 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.50, not supported
2026-06-03 17:47:27,693 - DEBUG - Starting all layer2 verification in 10.0.0.249 Control Node
2026-06-03 17:47:27,822 - DEBUG - Layer2 route found for VM MAC 02:da:8e:7a:3a:3a in Control-node 10.0.0.249
2026-06-03 17:47:27,822 - DEBUG - L2 Label for VM ctest-VM11-54109248 same between Control-node 10.0.0.249 and Agent, Expected: 29, Seen: 29
2026-06-03 17:47:27,938 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.249, not supported
2026-06-03 17:47:27,938 - INFO - Verification in Control-nodes for VM ctest-VM11-54109248 passed
2026-06-03 17:47:27,939 - DEBUG - Verifying the vm in opserver
2026-06-03 17:47:27,939 - DEBUG - Verifying in collector 10.0.0.249 ...
2026-06-03 17:47:27,939 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machine/0e068fd9-967e-4efa-a7a7-335d354b9310?flat
2026-06-03 17:47:27,956 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-35807875:da8e7a3a-3ab3-442f-a72e-65a9eed0aea4?flat
2026-06-03 17:47:27,978 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-35807875:da8e7a3a-3ab3-442f-a72e-65a9eed0aea4?flat
2026-06-03 17:47:28,002 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-35807875:da8e7a3a-3ab3-442f-a72e-65a9eed0aea4?flat
2026-06-03 17:47:28,023 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machine/0e068fd9-967e-4efa-a7a7-335d354b9310?flat
2026-06-03 17:47:28,039 - DEBUG - Verifying vm in vn uve
2026-06-03 17:47:28,039 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-35807875:da8e7a3a-3ab3-442f-a72e-65a9eed0aea4?flat
2026-06-03 17:47:28,064 - DEBUG - VM uve shows interface as {'admin_state': True, 'active': True, '__T': 1780508801096945, 'ip6_address': '2347:ffec:7d02:386:9159:f5f6:0:3', 'is_health_check_active': True, 'tx_vlan': 65535, 'ip_address': '123.11.242.67', 'mac_address': '02:da:8e:7a:3a:3a', 'fixed_ip4_list': ['123.11.242.67'], 'vm_name': 'ctest-VM11-54109248', 'port_mirror_enabled': False, 'uuid': 'da8e7a3a-3ab3-442f-a72e-65a9eed0aea4', 'vn_uuid': '2451254e-ac88-417e-b09a-dbe9443a3cbb', 'label': 25, 'fixed_ip6_list': ['2347:ffec:7d02:386:9159:f5f6:0:3'], 'ip6_active': True, 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945', 'l2_active': True, 'vm_uuid': '0e068fd9-967e-4efa-a7a7-335d354b9310', 'rx_vlan': 65535, 'gateway': '123.11.242.65', 'vhostuser_mode': 0, 'ip4_active': True}
2026-06-03 17:47:28,064 - DEBUG - VM uve shows ip address as ['123.11.242.67', '2347:ffec:7d02:386:9159:f5f6:0:3']
2026-06-03 17:47:28,064 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945
2026-06-03 17:47:28,064 - INFO - Verifying through opserver in 10.0.0.50
2026-06-03 17:47:28,064 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945?flat
2026-06-03 17:47:28,091 - DEBUG - expected vm list 0e068fd9-967e-4efa-a7a7-335d354b9310
2026-06-03 17:47:28,091 - DEBUG - Extracted vm list ['0e068fd9-967e-4efa-a7a7-335d354b9310']
2026-06-03 17:47:28,091 - INFO - VM 0e068fd9-967e-4efa-a7a7-335d354b9310 is present in default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945
2026-06-03 17:47:28,091 - DEBUG - Verifying vm in vrouter uve
2026-06-03 17:47:28,091 - DEBUG - Getting info from collector 10.0.0.249..
2026-06-03 17:47:28,091 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machine/0e068fd9-967e-4efa-a7a7-335d354b9310?flat
2026-06-03 17:47:28,109 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-5883-1']
2026-06-03 17:47:28,109 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5883-1:Compute:contrail-vrouter-agent:0?flat
2026-06-03 17:47:28,151 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5883-1?flat
2026-06-03 17:47:28,186 - DEBUG - VM 0e068fd9-967e-4efa-a7a7-335d354b9310 is present in vrouter an-jenkins-deploy-platform-ansible-os-5883-1 uve
2026-06-03 17:47:28,186 - DEBUG - Validated that VM ctest-VM11-54109248 is in Vrouter an-jenkins-deploy-platform-ansible-os-5883-1 UVE
2026-06-03 17:47:28,186 - DEBUG - Verifying vm tap interface/vn in vrouter uve
2026-06-03 17:47:28,193 - DEBUG - Expected tap interface of VM uuid 0e068fd9-967e-4efa-a7a7-335d354b9310 is default-domain:ctest-TestPolicyAclIpv4v6-35807875:da8e7a3a-3ab3-442f-a72e-65a9eed0aea4
2026-06-03 17:47:28,194 - DEBUG - Expected VN of VM uuid 0e068fd9-967e-4efa-a7a7-335d354b9310 is default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945
2026-06-03 17:47:28,194 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5883-1:Compute:contrail-vrouter-agent:0?flat
2026-06-03 17:47:28,228 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5883-1?flat
2026-06-03 17:47:28,258 - DEBUG - VM 0e068fd9-967e-4efa-a7a7-335d354b9310 is present in vrouter an-jenkins-deploy-platform-ansible-os-5883-1 uve
2026-06-03 17:47:28,258 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-35807875:da8e7a3a-3ab3-442f-a72e-65a9eed0aea4 of vm 0e068fd9-967e-4efa-a7a7-335d354b9310 is present in vrouter an-jenkins-deploy-platform-ansible-os-5883-1 uve
2026-06-03 17:47:28,258 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945 of vm 0e068fd9-967e-4efa-a7a7-335d354b9310 is present in vrouter an-jenkins-deploy-platform-ansible-os-5883-1 uve
2026-06-03 17:47:28,258 - DEBUG - Verifying the 0e068fd9-967e-4efa-a7a7-335d354b9310 virtual network link through opserver 10.0.0.50
2026-06-03 17:47:28,258 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-machines
2026-06-03 17:47:28,273 - DEBUG - VM link and name as {'name': '0e068fd9-967e-4efa-a7a7-335d354b9310', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-machine/0e068fd9-967e-4efa-a7a7-335d354b9310?flat'}
2026-06-03 17:47:28,273 - DEBUG - Verifying the 0e068fd9-967e-4efa-a7a7-335d354b9310 virtual network link through opserver 10.0.0.33
2026-06-03 17:47:28,273 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machines
2026-06-03 17:47:28,288 - DEBUG - VM link and name as {'name': '0e068fd9-967e-4efa-a7a7-335d354b9310', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-machine/0e068fd9-967e-4efa-a7a7-335d354b9310?flat'}
2026-06-03 17:47:28,289 - DEBUG - Verifying the 0e068fd9-967e-4efa-a7a7-335d354b9310 virtual network link through opserver 10.0.0.249
2026-06-03 17:47:28,289 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machines
2026-06-03 17:47:28,303 - DEBUG - VM link and name as {'name': '0e068fd9-967e-4efa-a7a7-335d354b9310', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-machine/0e068fd9-967e-4efa-a7a7-335d354b9310?flat'}
2026-06-03 17:47:28,303 - INFO - VM ctest-VM11-54109248 validations in Opserver passed
2026-06-03 17:47:28,400 - DEBUG - VM is in ACTIVE state now
2026-06-03 17:47:28,400 - INFO - VM name : ctest-VM21-46961354
2026-06-03 17:47:28,494 - DEBUG - VM ctest-VM21-46961354 ID is efe53321-e365-41fa-8d53-6770c443ee30
2026-06-03 17:47:28,588 - DEBUG - VM is in ACTIVE state now
2026-06-03 17:47:28,589 - DEBUG - VM ctest-VM21-46961354 launched on Node an-jenkins-deploy-platform-ansible-os-5883-2
2026-06-03 17:47:28,700 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/efe53321-e365-41fa-8d53-6770c443ee30
2026-06-03 17:47:28,716 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/efe53321-e365-41fa-8d53-6770c443ee30
2026-06-03 17:47:28,726 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/28c2e56a-2f1d-47d8-9021-1b13ae5644c7
2026-06-03 17:47:28,740 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/efe53321-e365-41fa-8d53-6770c443ee30
2026-06-03 17:47:28,752 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/28c2e56a-2f1d-47d8-9021-1b13ae5644c7
2026-06-03 17:47:28,764 - DEBUG - Requesting: http://10.0.0.249:8082/instance-ip/54504d8d-3e3b-4d3b-b3ca-be8d08cce65d
2026-06-03 17:47:28,774 - DEBUG - Requesting: http://10.0.0.249:8082/instance-ip/e1bea578-389e-4fef-834e-e9f29d194cb5
2026-06-03 17:47:28,787 - DEBUG - Verifying in api server 10.0.0.249
2026-06-03 17:47:28,787 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/efe53321-e365-41fa-8d53-6770c443ee30
2026-06-03 17:47:28,795 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/28c2e56a-2f1d-47d8-9021-1b13ae5644c7
2026-06-03 17:47:28,806 - DEBUG - Requesting: http://10.0.0.249:8082/instance-ip/54504d8d-3e3b-4d3b-b3ca-be8d08cce65d
2026-06-03 17:47:28,814 - DEBUG - Requesting: http://10.0.0.249:8082/instance-ip/e1bea578-389e-4fef-834e-e9f29d194cb5
2026-06-03 17:47:28,822 - INFO - VM ctest-VM21-46961354 verfication in all API Servers passed
2026-06-03 17:47:28,844 - DEBUG - VM ctest-VM21-46961354 Tap interface: {'index': '3', 'name': 'tap28c2e56a-2f', 'uuid': '28c2e56a-2f1d-47d8-9021-1b13ae5644c7', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN2-64579439:ctest-VN2-64579439', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Active', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Enable', 'dns_service': 'Enable', 'type': 'vport', 'label': '25', 'l2_label': '29', 'vxlan_id': '15', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN2-64579439', 'vm_uuid': 'efe53321-e365-41fa-8d53-6770c443ee30', 'vm_name': 'ctest-VM21-46961354', 'ip_addr': '102.136.97.67', 'mac_addr': '02:28:c2:e5:6a:2f', '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-35807875:28c2e56a-2f1d-47d8-9021-1b13ae5644c7', 'sg_uuid_list': ['f995fd0c-4904-4ec5-9b31-7f39c10da0d6'], 'static_route_list': None, 'vm_project_uuid': '7ed12a2e-484d-4264-82e2-9cbae0a87532', 'admin_state': 'Enabled', 'flow_key_idx': '31', 'allowed_address_pair_list': None, 'ip6_addr': '2bfd:cad2:cd91:67de:5a40:7dc7: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-06-03 17:47:28,844 - DEBUG - Agent 10.0.0.19 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN2-64579439:ctest-VN2-64579439
2026-06-03 17:47:28,853 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN2-64579439:ctest-VN2-64579439', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN2-64579439', 'table_label': '-1', 'vxlan_id': '15', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.129:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]}
2026-06-03 17:47:29,004 - DEBUG - VM ctest-VM21-46961354 : Tap interface tap28c2e56a-2f is set to Active
2026-06-03 17:47:29,004 - DEBUG - Tap interface tap28c2e56a-2f detail : {'index': '3', 'name': 'tap28c2e56a-2f', 'uuid': '28c2e56a-2f1d-47d8-9021-1b13ae5644c7', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN2-64579439:ctest-VN2-64579439', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Active', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Enable', 'dns_service': 'Enable', 'type': 'vport', 'label': '25', 'l2_label': '29', 'vxlan_id': '15', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN2-64579439', 'vm_uuid': 'efe53321-e365-41fa-8d53-6770c443ee30', 'vm_name': 'ctest-VM21-46961354', 'ip_addr': '102.136.97.67', 'mac_addr': '02:28:c2:e5:6a:2f', '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-35807875:28c2e56a-2f1d-47d8-9021-1b13ae5644c7', 'sg_uuid_list': ['f995fd0c-4904-4ec5-9b31-7f39c10da0d6'], 'static_route_list': None, 'vm_project_uuid': '7ed12a2e-484d-4264-82e2-9cbae0a87532', 'admin_state': 'Enabled', 'flow_key_idx': '31', 'allowed_address_pair_list': None, 'ip6_addr': '2bfd:cad2:cd91:67de:5a40:7dc7: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-06-03 17:47:29,117 - DEBUG - Starting Layer 2 verification in Agent
2026-06-03 17:47:29,131 - DEBUG - Layer 2 path is seen for VM MAC 02:28:c2:e5:6a:2f in agent 10.0.0.19
2026-06-03 17:47:29,131 - DEBUG - Active layer 2 route in agent is present for VMI tap28c2e56a-2f
2026-06-03 17:47:29,131 - DEBUG - L2 label(29) matches bw route table and interface table
2026-06-03 17:47:29,148 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN2-64579439 is found in Agent of node 10.0.0.19
2026-06-03 17:47:29,300 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN2-64579439 is consistent in agent 10.0.0.19
2026-06-03 17:47:29,301 - DEBUG - Route for VM IP ['102.136.97.67', '2bfd:cad2:cd91:67de:5a40:7dc7:0:3'] is consistent in agent 10.0.0.19
2026-06-03 17:47:29,301 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN2-64579439 verification for VM ctest-VM21-46961354 in Agent 10.0.0.19 passed
2026-06-03 17:47:29,415 - DEBUG - Starting all layer 2 verification in agent 10.0.0.19
2026-06-03 17:47:29,427 - DEBUG - Route for VM MAC 02:28:c2:e5:6a:2f is consistent in agent 10.0.0.19
2026-06-03 17:47:30,623 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=3.54 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.87 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.872/3.203/3.535/0.331 ms')
2026-06-03 17:47:30,624 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-46961354 passed
2026-06-03 17:47:30,624 - INFO - VM ctest-VM21-46961354 verifications in Compute nodes passed
2026-06-03 17:47:30,872 - DEBUG - Validated VM route 102.136.97.67 in vrouter of 10.0.0.19
2026-06-03 17:47:30,879 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0
2026-06-03 17:47:30,879 - INFO - Validated routes of VM ctest-VM21-46961354 in all vrouters
2026-06-03 17:47:31,321 - DEBUG - Starting all layer2 verification in 10.0.0.50 Control Node
2026-06-03 17:47:31,455 - DEBUG - Layer2 route found for VM MAC 02:28:c2:e5:6a:2f in Control-node 10.0.0.50
2026-06-03 17:47:31,455 - DEBUG - L2 Label for VM ctest-VM21-46961354 same between Control-node 10.0.0.50 and Agent, Expected: 29, Seen: 29
2026-06-03 17:47:31,571 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.50, not supported
2026-06-03 17:47:31,571 - DEBUG - Starting all layer2 verification in 10.0.0.249 Control Node
2026-06-03 17:47:31,694 - DEBUG - Layer2 route found for VM MAC 02:28:c2:e5:6a:2f in Control-node 10.0.0.249
2026-06-03 17:47:31,694 - DEBUG - L2 Label for VM ctest-VM21-46961354 same between Control-node 10.0.0.249 and Agent, Expected: 29, Seen: 29
2026-06-03 17:47:31,821 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.249, not supported
2026-06-03 17:47:31,821 - INFO - Verification in Control-nodes for VM ctest-VM21-46961354 passed
2026-06-03 17:47:31,821 - DEBUG - Verifying the vm in opserver
2026-06-03 17:47:31,821 - DEBUG - Verifying in collector 10.0.0.249 ...
2026-06-03 17:47:31,821 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machine/efe53321-e365-41fa-8d53-6770c443ee30?flat
2026-06-03 17:47:31,842 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-35807875:28c2e56a-2f1d-47d8-9021-1b13ae5644c7?flat
2026-06-03 17:47:31,866 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-35807875:28c2e56a-2f1d-47d8-9021-1b13ae5644c7?flat
2026-06-03 17:47:31,889 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-35807875:28c2e56a-2f1d-47d8-9021-1b13ae5644c7?flat
2026-06-03 17:47:31,911 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machine/efe53321-e365-41fa-8d53-6770c443ee30?flat
2026-06-03 17:47:31,935 - DEBUG - Verifying vm in vn uve
2026-06-03 17:47:31,935 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-35807875:28c2e56a-2f1d-47d8-9021-1b13ae5644c7?flat
2026-06-03 17:47:31,964 - DEBUG - VM uve shows interface as {'admin_state': True, 'active': True, '__T': 1780508801594953, 'ip6_address': '2bfd:cad2:cd91:67de:5a40:7dc7:0:3', 'is_health_check_active': True, 'tx_vlan': 65535, 'ip_address': '102.136.97.67', 'mac_address': '02:28:c2:e5:6a:2f', 'fixed_ip4_list': ['102.136.97.67'], 'vm_name': 'ctest-VM21-46961354', 'port_mirror_enabled': False, 'uuid': '28c2e56a-2f1d-47d8-9021-1b13ae5644c7', 'vn_uuid': '603e4114-1c86-44d4-958c-122e0e77d8a4', 'label': 25, 'fixed_ip6_list': ['2bfd:cad2:cd91:67de:5a40:7dc7:0:3'], 'ip6_active': True, 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN2-64579439', 'l2_active': True, 'vm_uuid': 'efe53321-e365-41fa-8d53-6770c443ee30', 'rx_vlan': 65535, 'gateway': '102.136.97.65', 'vhostuser_mode': 0, 'ip4_active': True}
2026-06-03 17:47:31,964 - DEBUG - VM uve shows ip address as ['102.136.97.67', '2bfd:cad2:cd91:67de:5a40:7dc7:0:3']
2026-06-03 17:47:31,964 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN2-64579439
2026-06-03 17:47:31,964 - INFO - Verifying through opserver in 10.0.0.50
2026-06-03 17:47:31,964 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN2-64579439?flat
2026-06-03 17:47:31,986 - DEBUG - expected vm list efe53321-e365-41fa-8d53-6770c443ee30
2026-06-03 17:47:31,986 - DEBUG - Extracted vm list ['efe53321-e365-41fa-8d53-6770c443ee30']
2026-06-03 17:47:31,986 - INFO - VM efe53321-e365-41fa-8d53-6770c443ee30 is present in default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN2-64579439
2026-06-03 17:47:31,986 - DEBUG - Verifying vm in vrouter uve
2026-06-03 17:47:31,986 - DEBUG - Getting info from collector 10.0.0.249..
2026-06-03 17:47:31,986 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machine/efe53321-e365-41fa-8d53-6770c443ee30?flat
2026-06-03 17:47:32,002 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-5883-2']
2026-06-03 17:47:32,002 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5883-2:Compute:contrail-vrouter-agent:0?flat
2026-06-03 17:47:32,042 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5883-2?flat
2026-06-03 17:47:32,076 - DEBUG - VM efe53321-e365-41fa-8d53-6770c443ee30 is present in vrouter an-jenkins-deploy-platform-ansible-os-5883-2 uve
2026-06-03 17:47:32,076 - DEBUG - Validated that VM ctest-VM21-46961354 is in Vrouter an-jenkins-deploy-platform-ansible-os-5883-2 UVE
2026-06-03 17:47:32,076 - DEBUG - Verifying vm tap interface/vn in vrouter uve
2026-06-03 17:47:32,084 - DEBUG - Expected tap interface of VM uuid efe53321-e365-41fa-8d53-6770c443ee30 is default-domain:ctest-TestPolicyAclIpv4v6-35807875:28c2e56a-2f1d-47d8-9021-1b13ae5644c7
2026-06-03 17:47:32,084 - DEBUG - Expected VN of VM uuid efe53321-e365-41fa-8d53-6770c443ee30 is default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN2-64579439
2026-06-03 17:47:32,084 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5883-2:Compute:contrail-vrouter-agent:0?flat
2026-06-03 17:47:32,117 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5883-2?flat
2026-06-03 17:47:32,152 - DEBUG - VM efe53321-e365-41fa-8d53-6770c443ee30 is present in vrouter an-jenkins-deploy-platform-ansible-os-5883-2 uve
2026-06-03 17:47:32,152 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-35807875:28c2e56a-2f1d-47d8-9021-1b13ae5644c7 of vm efe53321-e365-41fa-8d53-6770c443ee30 is present in vrouter an-jenkins-deploy-platform-ansible-os-5883-2 uve
2026-06-03 17:47:32,152 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN2-64579439 of vm efe53321-e365-41fa-8d53-6770c443ee30 is present in vrouter an-jenkins-deploy-platform-ansible-os-5883-2 uve
2026-06-03 17:47:32,152 - DEBUG - Verifying the efe53321-e365-41fa-8d53-6770c443ee30 virtual network link through opserver 10.0.0.50
2026-06-03 17:47:32,152 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-machines
2026-06-03 17:47:32,169 - DEBUG - VM link and name as {'name': 'efe53321-e365-41fa-8d53-6770c443ee30', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-machine/efe53321-e365-41fa-8d53-6770c443ee30?flat'}
2026-06-03 17:47:32,169 - DEBUG - Verifying the efe53321-e365-41fa-8d53-6770c443ee30 virtual network link through opserver 10.0.0.33
2026-06-03 17:47:32,169 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machines
2026-06-03 17:47:32,183 - DEBUG - VM link and name as {'name': 'efe53321-e365-41fa-8d53-6770c443ee30', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-machine/efe53321-e365-41fa-8d53-6770c443ee30?flat'}
2026-06-03 17:47:32,183 - DEBUG - Verifying the efe53321-e365-41fa-8d53-6770c443ee30 virtual network link through opserver 10.0.0.249
2026-06-03 17:47:32,183 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machines
2026-06-03 17:47:32,198 - DEBUG - VM link and name as {'name': 'efe53321-e365-41fa-8d53-6770c443ee30', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-machine/efe53321-e365-41fa-8d53-6770c443ee30?flat'}
2026-06-03 17:47:32,198 - INFO - VM ctest-VM21-46961354 validations in Opserver passed
2026-06-03 17:47:32,291 - DEBUG - VM is in ACTIVE state now
2026-06-03 17:47:32,291 - INFO - VM name : ctest-VM31-34946210
2026-06-03 17:47:32,386 - DEBUG - VM ctest-VM31-34946210 ID is 71d9a7f7-ed48-4208-a9c2-b0082400a0b5
2026-06-03 17:47:32,492 - DEBUG - VM is in ACTIVE state now
2026-06-03 17:47:32,492 - DEBUG - VM ctest-VM31-34946210 launched on Node an-jenkins-deploy-platform-ansible-os-5883-1
2026-06-03 17:47:32,590 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/71d9a7f7-ed48-4208-a9c2-b0082400a0b5
2026-06-03 17:47:32,604 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/71d9a7f7-ed48-4208-a9c2-b0082400a0b5
2026-06-03 17:47:32,615 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/44e67dea-18d1-485b-8995-581be4943dbe
2026-06-03 17:47:32,627 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/71d9a7f7-ed48-4208-a9c2-b0082400a0b5
2026-06-03 17:47:32,637 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/44e67dea-18d1-485b-8995-581be4943dbe
2026-06-03 17:47:32,651 - DEBUG - Requesting: http://10.0.0.249:8082/instance-ip/3822968d-c27e-467c-85e1-7d8bcabc4b90
2026-06-03 17:47:32,666 - DEBUG - Requesting: http://10.0.0.249:8082/instance-ip/6c574ba7-d514-426e-9e30-dfe006bd5fc3
2026-06-03 17:47:32,678 - DEBUG - Verifying in api server 10.0.0.249
2026-06-03 17:47:32,678 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/71d9a7f7-ed48-4208-a9c2-b0082400a0b5
2026-06-03 17:47:32,687 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/44e67dea-18d1-485b-8995-581be4943dbe
2026-06-03 17:47:32,701 - DEBUG - Requesting: http://10.0.0.249:8082/instance-ip/3822968d-c27e-467c-85e1-7d8bcabc4b90
2026-06-03 17:47:32,711 - DEBUG - Requesting: http://10.0.0.249:8082/instance-ip/6c574ba7-d514-426e-9e30-dfe006bd5fc3
2026-06-03 17:47:32,722 - INFO - VM ctest-VM31-34946210 verfication in all API Servers passed
2026-06-03 17:47:32,741 - DEBUG - VM ctest-VM31-34946210 Tap interface: {'index': '4', 'name': 'tap44e67dea-18', 'uuid': '44e67dea-18d1-485b-8995-581be4943dbe', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN3-88266431:ctest-VN3-88266431', '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': '16', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN3-88266431', 'vm_uuid': '71d9a7f7-ed48-4208-a9c2-b0082400a0b5', 'vm_name': 'ctest-VM31-34946210', 'ip_addr': '174.199.34.195', 'mac_addr': '02:44:e6:7d:ea:18', '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-35807875:44e67dea-18d1-485b-8995-581be4943dbe', 'sg_uuid_list': ['f995fd0c-4904-4ec5-9b31-7f39c10da0d6'], 'static_route_list': None, 'vm_project_uuid': '7ed12a2e-484d-4264-82e2-9cbae0a87532', 'admin_state': 'Enabled', 'flow_key_idx': '41', 'allowed_address_pair_list': None, 'ip6_addr': '3b16:7:cf4f:6ec6:5c31:78c3: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-06-03 17:47:32,741 - DEBUG - Agent 10.0.0.22 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN3-88266431:ctest-VN3-88266431
2026-06-03 17:47:32,747 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN3-88266431:ctest-VN3-88266431', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN3-88266431', 'table_label': '-1', 'vxlan_id': '16', 'evpnindex': '3', 'brindex': '3', 'mplsindex': '0', 'RD': '10.20.0.193:3', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]}
2026-06-03 17:47:32,845 - DEBUG - VM ctest-VM31-34946210 : Tap interface tap44e67dea-18 is set to Active
2026-06-03 17:47:32,845 - DEBUG - Tap interface tap44e67dea-18 detail : {'index': '4', 'name': 'tap44e67dea-18', 'uuid': '44e67dea-18d1-485b-8995-581be4943dbe', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN3-88266431:ctest-VN3-88266431', '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': '16', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN3-88266431', 'vm_uuid': '71d9a7f7-ed48-4208-a9c2-b0082400a0b5', 'vm_name': 'ctest-VM31-34946210', 'ip_addr': '174.199.34.195', 'mac_addr': '02:44:e6:7d:ea:18', '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-35807875:44e67dea-18d1-485b-8995-581be4943dbe', 'sg_uuid_list': ['f995fd0c-4904-4ec5-9b31-7f39c10da0d6'], 'static_route_list': None, 'vm_project_uuid': '7ed12a2e-484d-4264-82e2-9cbae0a87532', 'admin_state': 'Enabled', 'flow_key_idx': '41', 'allowed_address_pair_list': None, 'ip6_addr': '3b16:7:cf4f:6ec6:5c31:78c3: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-06-03 17:47:32,969 - DEBUG - Starting Layer 2 verification in Agent
2026-06-03 17:47:32,981 - DEBUG - Layer 2 path is seen for VM MAC 02:44:e6:7d:ea:18 in agent 10.0.0.22
2026-06-03 17:47:32,982 - DEBUG - Active layer 2 route in agent is present for VMI tap44e67dea-18
2026-06-03 17:47:32,982 - DEBUG - L2 label(36) matches bw route table and interface table
2026-06-03 17:47:32,993 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN3-88266431 is found in Agent of node 10.0.0.22
2026-06-03 17:47:33,142 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN3-88266431 is consistent in agent 10.0.0.22
2026-06-03 17:47:33,142 - DEBUG - Route for VM IP ['3b16:7:cf4f:6ec6:5c31:78c3:0:3', '174.199.34.195'] is consistent in agent 10.0.0.22
2026-06-03 17:47:33,142 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN3-88266431 verification for VM ctest-VM31-34946210 in Agent 10.0.0.22 passed
2026-06-03 17:47:33,263 - DEBUG - Starting all layer 2 verification in agent 10.0.0.22
2026-06-03 17:47:33,273 - DEBUG - Route for VM MAC 02:44:e6:7d:ea:18 is consistent in agent 10.0.0.22
2026-06-03 17:47:34,481 - 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.85 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=5.78 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 5.777/6.812/7.847/1.035 ms')
2026-06-03 17:47:34,481 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-34946210 passed
2026-06-03 17:47:34,481 - INFO - VM ctest-VM31-34946210 verifications in Compute nodes passed
2026-06-03 17:47:34,737 - DEBUG - Validated VM route 174.199.34.195 in vrouter of 10.0.0.22
2026-06-03 17:47:34,744 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0
2026-06-03 17:47:34,745 - INFO - Validated routes of VM ctest-VM31-34946210 in all vrouters
2026-06-03 17:47:35,273 - DEBUG - Starting all layer2 verification in 10.0.0.50 Control Node
2026-06-03 17:47:35,394 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.50, not supported
2026-06-03 17:47:35,531 - DEBUG - Layer2 route found for VM MAC 02:44:e6:7d:ea:18 in Control-node 10.0.0.50
2026-06-03 17:47:35,532 - DEBUG - L2 Label for VM ctest-VM31-34946210 same between Control-node 10.0.0.50 and Agent, Expected: 36, Seen: 36
2026-06-03 17:47:35,532 - DEBUG - Starting all layer2 verification in 10.0.0.249 Control Node
2026-06-03 17:47:35,656 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.249, not supported
2026-06-03 17:47:35,789 - DEBUG - Layer2 route found for VM MAC 02:44:e6:7d:ea:18 in Control-node 10.0.0.249
2026-06-03 17:47:35,789 - DEBUG - L2 Label for VM ctest-VM31-34946210 same between Control-node 10.0.0.249 and Agent, Expected: 36, Seen: 36
2026-06-03 17:47:35,789 - INFO - Verification in Control-nodes for VM ctest-VM31-34946210 passed
2026-06-03 17:47:35,789 - DEBUG - Verifying the vm in opserver
2026-06-03 17:47:35,790 - DEBUG - Verifying in collector 10.0.0.249 ...
2026-06-03 17:47:35,790 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machine/71d9a7f7-ed48-4208-a9c2-b0082400a0b5?flat
2026-06-03 17:47:35,811 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-35807875:44e67dea-18d1-485b-8995-581be4943dbe?flat
2026-06-03 17:47:35,835 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-35807875:44e67dea-18d1-485b-8995-581be4943dbe?flat
2026-06-03 17:47:35,859 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-35807875:44e67dea-18d1-485b-8995-581be4943dbe?flat
2026-06-03 17:47:35,880 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machine/71d9a7f7-ed48-4208-a9c2-b0082400a0b5?flat
2026-06-03 17:47:35,899 - DEBUG - Verifying vm in vn uve
2026-06-03 17:47:35,899 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-35807875:44e67dea-18d1-485b-8995-581be4943dbe?flat
2026-06-03 17:47:35,921 - DEBUG - VM uve shows interface as {'admin_state': True, 'active': True, '__T': 1780508801096920, 'ip6_address': '3b16:7:cf4f:6ec6:5c31:78c3:0:3', 'is_health_check_active': True, 'tx_vlan': 65535, 'ip_address': '174.199.34.195', 'mac_address': '02:44:e6:7d:ea:18', 'fixed_ip4_list': ['174.199.34.195'], 'vm_name': 'ctest-VM31-34946210', 'port_mirror_enabled': False, 'uuid': '44e67dea-18d1-485b-8995-581be4943dbe', 'vn_uuid': '2352a398-1364-4bb7-aaa8-bc458e584e30', 'label': 32, 'fixed_ip6_list': ['3b16:7:cf4f:6ec6:5c31:78c3:0:3'], 'ip6_active': True, 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN3-88266431', 'l2_active': True, 'vm_uuid': '71d9a7f7-ed48-4208-a9c2-b0082400a0b5', 'rx_vlan': 65535, 'gateway': '174.199.34.193', 'vhostuser_mode': 0, 'ip4_active': True}
2026-06-03 17:47:35,922 - DEBUG - VM uve shows ip address as ['174.199.34.195', '3b16:7:cf4f:6ec6:5c31:78c3:0:3']
2026-06-03 17:47:35,922 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN3-88266431
2026-06-03 17:47:35,922 - INFO - Verifying through opserver in 10.0.0.50
2026-06-03 17:47:35,922 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN3-88266431?flat
2026-06-03 17:47:35,952 - DEBUG - expected vm list 71d9a7f7-ed48-4208-a9c2-b0082400a0b5
2026-06-03 17:47:35,952 - DEBUG - Extracted vm list ['71d9a7f7-ed48-4208-a9c2-b0082400a0b5']
2026-06-03 17:47:35,952 - INFO - VM 71d9a7f7-ed48-4208-a9c2-b0082400a0b5 is present in default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN3-88266431
2026-06-03 17:47:35,955 - DEBUG - Verifying vm in vrouter uve
2026-06-03 17:47:35,955 - DEBUG - Getting info from collector 10.0.0.249..
2026-06-03 17:47:35,955 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machine/71d9a7f7-ed48-4208-a9c2-b0082400a0b5?flat
2026-06-03 17:47:35,977 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-5883-1']
2026-06-03 17:47:35,977 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5883-1:Compute:contrail-vrouter-agent:0?flat
2026-06-03 17:47:36,017 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5883-1?flat
2026-06-03 17:47:36,050 - DEBUG - VM 71d9a7f7-ed48-4208-a9c2-b0082400a0b5 is present in vrouter an-jenkins-deploy-platform-ansible-os-5883-1 uve
2026-06-03 17:47:36,050 - DEBUG - Validated that VM ctest-VM31-34946210 is in Vrouter an-jenkins-deploy-platform-ansible-os-5883-1 UVE
2026-06-03 17:47:36,050 - DEBUG - Verifying vm tap interface/vn in vrouter uve
2026-06-03 17:47:36,059 - DEBUG - Expected tap interface of VM uuid 71d9a7f7-ed48-4208-a9c2-b0082400a0b5 is default-domain:ctest-TestPolicyAclIpv4v6-35807875:44e67dea-18d1-485b-8995-581be4943dbe
2026-06-03 17:47:36,059 - DEBUG - Expected VN of VM uuid 71d9a7f7-ed48-4208-a9c2-b0082400a0b5 is default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN3-88266431
2026-06-03 17:47:36,059 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5883-1:Compute:contrail-vrouter-agent:0?flat
2026-06-03 17:47:36,117 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5883-1?flat
2026-06-03 17:47:36,160 - DEBUG - VM 71d9a7f7-ed48-4208-a9c2-b0082400a0b5 is present in vrouter an-jenkins-deploy-platform-ansible-os-5883-1 uve
2026-06-03 17:47:36,160 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-35807875:44e67dea-18d1-485b-8995-581be4943dbe of vm 71d9a7f7-ed48-4208-a9c2-b0082400a0b5 is present in vrouter an-jenkins-deploy-platform-ansible-os-5883-1 uve
2026-06-03 17:47:36,160 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN3-88266431 of vm 71d9a7f7-ed48-4208-a9c2-b0082400a0b5 is present in vrouter an-jenkins-deploy-platform-ansible-os-5883-1 uve
2026-06-03 17:47:36,160 - DEBUG - Verifying the 71d9a7f7-ed48-4208-a9c2-b0082400a0b5 virtual network link through opserver 10.0.0.50
2026-06-03 17:47:36,160 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-machines
2026-06-03 17:47:36,173 - DEBUG - VM link and name as {'name': '71d9a7f7-ed48-4208-a9c2-b0082400a0b5', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-machine/71d9a7f7-ed48-4208-a9c2-b0082400a0b5?flat'}
2026-06-03 17:47:36,174 - DEBUG - Verifying the 71d9a7f7-ed48-4208-a9c2-b0082400a0b5 virtual network link through opserver 10.0.0.33
2026-06-03 17:47:36,174 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machines
2026-06-03 17:47:36,188 - DEBUG - VM link and name as {'name': '71d9a7f7-ed48-4208-a9c2-b0082400a0b5', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-machine/71d9a7f7-ed48-4208-a9c2-b0082400a0b5?flat'}
2026-06-03 17:47:36,188 - DEBUG - Verifying the 71d9a7f7-ed48-4208-a9c2-b0082400a0b5 virtual network link through opserver 10.0.0.249
2026-06-03 17:47:36,188 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machines
2026-06-03 17:47:36,204 - DEBUG - VM link and name as {'name': '71d9a7f7-ed48-4208-a9c2-b0082400a0b5', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-machine/71d9a7f7-ed48-4208-a9c2-b0082400a0b5?flat'}
2026-06-03 17:47:36,204 - INFO - VM ctest-VM31-34946210 validations in Opserver passed
2026-06-03 17:47:36,204 - INFO - Waiting for VM ctest-VM11-54109248 to be up..
2026-06-03 17:47:36,204 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-03 17:47:36,204 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/0e068fd9-967e-4efa-a7a7-335d354b9310
2026-06-03 17:47:36,221 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/da8e7a3a-3ab3-442f-a72e-65a9eed0aea4
2026-06-03 17:47:37,408 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=2.95 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=11.4 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.954/7.152/11.351/4.198 ms')
2026-06-03 17:47:37,409 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-54109248 passed
2026-06-03 17:47:37,483 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-03 17:47:37,483 - DEBUG - Waiting to SSH to VM ctest-VM11-54109248, IP 123.11.242.67, Port 22
2026-06-03 17:47:37,551 - 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-06-03 17:47:37,641 - DEBUG - VM ctest-VM11-54109248 is NOT ready for SSH connections, VM status: ACTIVE
2026-06-03 17:47:42,641 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-03 17:47:42,642 - DEBUG - Waiting to SSH to VM ctest-VM11-54109248, IP 123.11.242.67, Port 22
2026-06-03 17:47:42,707 - 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-06-03 17:47:42,819 - DEBUG - VM ctest-VM11-54109248 is NOT ready for SSH connections, VM status: ACTIVE
2026-06-03 17:47:47,820 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-03 17:47:47,820 - DEBUG - Waiting to SSH to VM ctest-VM11-54109248, IP 123.11.242.67, Port 22
2026-06-03 17:47:47,987 - DEBUG - VM ctest-VM11-54109248 is ready for SSH connections
2026-06-03 17:47:47,987 - INFO - Waiting for VM ctest-VM21-46961354 to be up..
2026-06-03 17:47:47,988 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-03 17:47:47,988 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/efe53321-e365-41fa-8d53-6770c443ee30
2026-06-03 17:47:47,997 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/28c2e56a-2f1d-47d8-9021-1b13ae5644c7
2026-06-03 17:47:49,166 - 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=4.06 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.27 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 1.266/2.662/4.058/1.396 ms')
2026-06-03 17:47:49,166 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-46961354 passed
2026-06-03 17:47:49,238 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-03 17:47:49,238 - DEBUG - Waiting to SSH to VM ctest-VM21-46961354, IP 102.136.97.67, Port 22
2026-06-03 17:47:49,413 - DEBUG - VM ctest-VM21-46961354 is ready for SSH connections
2026-06-03 17:47:49,413 - INFO - Waiting for VM ctest-VM31-34946210 to be up..
2026-06-03 17:47:49,413 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-03 17:47:49,413 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/71d9a7f7-ed48-4208-a9c2-b0082400a0b5
2026-06-03 17:47:49,421 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/44e67dea-18d1-485b-8995-581be4943dbe
2026-06-03 17:47:50,597 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=2.96 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=3.33 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1003ms\r\nrtt min/avg/max/mdev = 2.961/3.143/3.325/0.182 ms')
2026-06-03 17:47:50,597 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-34946210 passed
2026-06-03 17:47:50,667 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-03 17:47:50,667 - DEBUG - Waiting to SSH to VM ctest-VM31-34946210, IP 174.199.34.195, Port 22
2026-06-03 17:47:50,735 - DEBUG - Error on ssh to ubuntu@169.254.0.4:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.4/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.4/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.4/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-06-03 17:47:50,820 - DEBUG - VM ctest-VM31-34946210 is NOT ready for SSH connections, VM status: ACTIVE
2026-06-03 17:47:55,820 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-03 17:47:55,821 - DEBUG - Waiting to SSH to VM ctest-VM31-34946210, IP 174.199.34.195, Port 22
2026-06-03 17:47:55,977 - DEBUG - VM ctest-VM31-34946210 is ready for SSH connections
2026-06-03 17:47:55,977 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 102.136.97.67, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.22, gateway password: c0ntrail123
2026-06-03 17:47:55,978 - DEBUG - ping -s 56 -c 3 -W 1 102.136.97.67
2026-06-03 17:48:14,227 - DEBUG - PING 102.136.97.67 (102.136.97.67) 56(84) bytes of data.
64 bytes from 102.136.97.67: icmp_req=1 ttl=63 time=10.1 ms
64 bytes from 102.136.97.67: icmp_req=2 ttl=63 time=1.48 ms
64 bytes from 102.136.97.67: icmp_req=3 ttl=63 time=1.37 ms
--- 102.136.97.67 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 1.379/4.335/10.145/4.108 ms
2026-06-03 17:48:14,227 - INFO - Ping to IP 102.136.97.67 from VM ctest-VM11-54109248 passed
2026-06-03 17:48:14,227 - DEBUG - Running remote_cmd, Cmd : ping6 -s 56 -c 3 -W 1 2bfd:cad2:cd91:67de:5a40:7dc7:0:3, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.22, gateway password: c0ntrail123
2026-06-03 17:48:14,227 - DEBUG - ping6 -s 56 -c 3 -W 1 2bfd:cad2:cd91:67de:5a40:7dc7:0:3
2026-06-03 17:48:16,824 - DEBUG - PING 2bfd:cad2:cd91:67de:5a40:7dc7:0:3(2bfd:cad2:cd91:67de:5a40:7dc7:0:3) 56 data bytes
64 bytes from 2bfd:cad2:cd91:67de:5a40:7dc7:0:3: icmp_seq=1 ttl=64 time=9.97 ms
64 bytes from 2bfd:cad2:cd91:67de:5a40:7dc7:0:3: icmp_seq=2 ttl=64 time=1.95 ms
64 bytes from 2bfd:cad2:cd91:67de:5a40:7dc7:0:3: icmp_seq=3 ttl=64 time=1.26 ms
--- 2bfd:cad2:cd91:67de:5a40:7dc7:0:3 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2005ms
rtt min/avg/max/mdev = 1.268/4.400/9.978/3.954 ms
2026-06-03 17:48:16,824 - INFO - Ping to IP 2bfd:cad2:cd91:67de:5a40:7dc7:0:3 from VM ctest-VM11-54109248 passed
2026-06-03 17:48:16,824 - INFO - Test with src as policy and dst as any PASSED
2026-06-03 17:48:16,824 - INFO - Deleting VM ctest-VM31-34946210
2026-06-03 17:48:16,921 - DEBUG - Verifying in api server 10.0.0.249
2026-06-03 17:48:16,921 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/71d9a7f7-ed48-4208-a9c2-b0082400a0b5
2026-06-03 17:48:16,931 - DEBUG - VM ID 71d9a7f7-ed48-4208-a9c2-b0082400a0b5 of VM ctest-VM31-34946210 is still found in API Server
2026-06-03 17:48:18,932 - DEBUG - Verifying in api server 10.0.0.249
2026-06-03 17:48:18,932 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/71d9a7f7-ed48-4208-a9c2-b0082400a0b5
2026-06-03 17:48:18,939 - DEBUG - Response Code: 404
2026-06-03 17:48:18,940 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/71d9a7f7-ed48-4208-a9c2-b0082400a0b5
2026-06-03 17:48:18,946 - DEBUG - Response Code: 404
2026-06-03 17:48:18,946 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/71d9a7f7-ed48-4208-a9c2-b0082400a0b5
2026-06-03 17:48:18,952 - DEBUG - Response Code: 404
2026-06-03 17:48:18,953 - INFO - VM ctest-VM31-34946210 is fully removed in API-Server
2026-06-03 17:48:19,113 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2026-06-03 17:48:19,162 - DEBUG - Validated that vrouter 10.0.0.22 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN3-88266431
2026-06-03 17:48:19,174 - DEBUG - Validated that vrouter 10.0.0.19 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN3-88266431
2026-06-03 17:48:19,174 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN3-88266431
2026-06-03 17:48:19,174 - INFO - VM ctest-VM31-34946210 is removed in Compute, and routes are removed in all compute nodes
2026-06-03 17:48:19,412 - INFO - Routes for VM ctest-VM31-34946210 is removed in all control-nodes
2026-06-03 17:48:19,412 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5883-1:Compute:contrail-vrouter-agent:0?flat
2026-06-03 17:48:19,457 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5883-1?flat
2026-06-03 17:48:19,490 - DEBUG - VM 71d9a7f7-ed48-4208-a9c2-b0082400a0b5 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5883-1 uve
2026-06-03 17:48:19,490 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-06-03 17:48:19,490 - DEBUG - interface for vm 71d9a7f7-ed48-4208-a9c2-b0082400a0b5 deleted from vrouter uve ...
2026-06-03 17:48:19,490 - DEBUG - Deleted interface not in error interface list ...
2026-06-03 17:48:22,491 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5883-1:Compute:contrail-vrouter-agent:0?flat
2026-06-03 17:48:22,525 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5883-1?flat
2026-06-03 17:48:22,554 - DEBUG - VM 71d9a7f7-ed48-4208-a9c2-b0082400a0b5 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5883-1 uve
2026-06-03 17:48:22,554 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-06-03 17:48:22,554 - DEBUG - interface for vm 71d9a7f7-ed48-4208-a9c2-b0082400a0b5 deleted from vrouter uve ...
2026-06-03 17:48:22,554 - DEBUG - Deleted interface not in error interface list ...
2026-06-03 17:48:25,555 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5883-1:Compute:contrail-vrouter-agent:0?flat
2026-06-03 17:48:25,593 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5883-1?flat
2026-06-03 17:48:25,624 - DEBUG - VM 71d9a7f7-ed48-4208-a9c2-b0082400a0b5 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5883-1 uve
2026-06-03 17:48:25,624 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-06-03 17:48:25,624 - DEBUG - interface for vm 71d9a7f7-ed48-4208-a9c2-b0082400a0b5 deleted from vrouter uve ...
2026-06-03 17:48:25,624 - DEBUG - Deleted interface not in error interface list ...
2026-06-03 17:48:28,625 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5883-1:Compute:contrail-vrouter-agent:0?flat
2026-06-03 17:48:28,657 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5883-1?flat
2026-06-03 17:48:28,689 - DEBUG - VM 71d9a7f7-ed48-4208-a9c2-b0082400a0b5 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5883-1 uve
2026-06-03 17:48:28,690 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-06-03 17:48:28,690 - DEBUG - interface for vm 71d9a7f7-ed48-4208-a9c2-b0082400a0b5 deleted from vrouter uve ...
2026-06-03 17:48:28,690 - DEBUG - Deleted interface not in error interface list ...
2026-06-03 17:48:31,691 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5883-1:Compute:contrail-vrouter-agent:0?flat
2026-06-03 17:48:31,749 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5883-1?flat
2026-06-03 17:48:31,782 - DEBUG - VM 71d9a7f7-ed48-4208-a9c2-b0082400a0b5 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5883-1 uve
2026-06-03 17:48:31,782 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-06-03 17:48:31,782 - DEBUG - interface for vm 71d9a7f7-ed48-4208-a9c2-b0082400a0b5 deleted from vrouter uve ...
2026-06-03 17:48:31,782 - DEBUG - Deleted interface not in error interface list ...
2026-06-03 17:48:34,784 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5883-1:Compute:contrail-vrouter-agent:0?flat
2026-06-03 17:48:34,820 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5883-1?flat
2026-06-03 17:48:34,857 - DEBUG - VM 71d9a7f7-ed48-4208-a9c2-b0082400a0b5 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5883-1 uve
2026-06-03 17:48:34,857 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-06-03 17:48:34,857 - DEBUG - interface for vm 71d9a7f7-ed48-4208-a9c2-b0082400a0b5 deleted from vrouter uve ...
2026-06-03 17:48:34,857 - DEBUG - Deleted interface not in error interface list ...
2026-06-03 17:48:37,858 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5883-1:Compute:contrail-vrouter-agent:0?flat
2026-06-03 17:48:37,893 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5883-1?flat
2026-06-03 17:48:37,921 - DEBUG - VM 71d9a7f7-ed48-4208-a9c2-b0082400a0b5 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5883-1 uve
2026-06-03 17:48:37,921 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-06-03 17:48:37,921 - DEBUG - interface for vm 71d9a7f7-ed48-4208-a9c2-b0082400a0b5 deleted from vrouter uve ...
2026-06-03 17:48:37,921 - DEBUG - Deleted interface not in error interface list ...
2026-06-03 17:48:40,922 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5883-1:Compute:contrail-vrouter-agent:0?flat
2026-06-03 17:48:40,961 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5883-1?flat
2026-06-03 17:48:40,991 - DEBUG - VM 71d9a7f7-ed48-4208-a9c2-b0082400a0b5 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5883-1 uve
2026-06-03 17:48:40,992 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-06-03 17:48:40,992 - DEBUG - interface for vm 71d9a7f7-ed48-4208-a9c2-b0082400a0b5 deleted from vrouter uve ...
2026-06-03 17:48:40,992 - DEBUG - Deleted interface not in error interface list ...
2026-06-03 17:48:43,992 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5883-1:Compute:contrail-vrouter-agent:0?flat
2026-06-03 17:48:44,025 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5883-1?flat
2026-06-03 17:48:44,057 - DEBUG - VM 71d9a7f7-ed48-4208-a9c2-b0082400a0b5 is not present in vrouter an-jenkins-deploy-platform-ansible-os-5883-1 uve
2026-06-03 17:48:44,057 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-06-03 17:48:44,057 - DEBUG - interface for vm 71d9a7f7-ed48-4208-a9c2-b0082400a0b5 deleted from vrouter uve ...
2026-06-03 17:48:44,057 - DEBUG - Deleted interface not in error interface list ...
2026-06-03 17:48:44,057 - DEBUG - Validated that VM 71d9a7f7-ed48-4208-a9c2-b0082400a0b5 is removed in Vrouter UVE
2026-06-03 17:48:44,058 - DEBUG - Verifying through opserver in 10.0.0.50
2026-06-03 17:48:44,058 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN3-88266431?flat
2026-06-03 17:48:44,079 - DEBUG - 71d9a7f7-ed48-4208-a9c2-b0082400a0b5 BM not in default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN3-88266431 uve
2026-06-03 17:48:44,079 - DEBUG - Validated that VM 71d9a7f7-ed48-4208-a9c2-b0082400a0b5 is not present in the VN UVE
2026-06-03 17:48:44,079 - DEBUG - Verifying the 71d9a7f7-ed48-4208-a9c2-b0082400a0b5 virtual network link through opserver 10.0.0.50
2026-06-03 17:48:44,079 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-machines
2026-06-03 17:48:44,093 - INFO - 71d9a7f7-ed48-4208-a9c2-b0082400a0b5 vm uve deleted from opserver
2026-06-03 17:48:44,093 - DEBUG - Verifying the 71d9a7f7-ed48-4208-a9c2-b0082400a0b5 virtual network link through opserver 10.0.0.33
2026-06-03 17:48:44,093 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machines
2026-06-03 17:48:44,108 - INFO - 71d9a7f7-ed48-4208-a9c2-b0082400a0b5 vm uve deleted from opserver
2026-06-03 17:48:44,108 - DEBUG - Verifying the 71d9a7f7-ed48-4208-a9c2-b0082400a0b5 virtual network link through opserver 10.0.0.249
2026-06-03 17:48:44,108 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machines
2026-06-03 17:48:44,123 - INFO - 71d9a7f7-ed48-4208-a9c2-b0082400a0b5 vm uve deleted from opserver
2026-06-03 17:48:44,123 - INFO - Validated that VM 71d9a7f7-ed48-4208-a9c2-b0082400a0b5 is removed from Opserver
2026-06-03 17:48:44,123 - INFO - Deleting VM ctest-VM21-46961354
2026-06-03 17:48:44,203 - DEBUG - Verifying in api server 10.0.0.249
2026-06-03 17:48:44,203 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/efe53321-e365-41fa-8d53-6770c443ee30
2026-06-03 17:48:44,212 - DEBUG - VM ID efe53321-e365-41fa-8d53-6770c443ee30 of VM ctest-VM21-46961354 is still found in API Server
2026-06-03 17:48:46,213 - DEBUG - Verifying in api server 10.0.0.249
2026-06-03 17:48:46,213 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/efe53321-e365-41fa-8d53-6770c443ee30
2026-06-03 17:48:46,221 - DEBUG - Response Code: 404
2026-06-03 17:48:46,222 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/efe53321-e365-41fa-8d53-6770c443ee30
2026-06-03 17:48:46,230 - DEBUG - Response Code: 404
2026-06-03 17:48:46,231 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/efe53321-e365-41fa-8d53-6770c443ee30
2026-06-03 17:48:46,242 - DEBUG - Response Code: 404
2026-06-03 17:48:46,242 - INFO - VM ctest-VM21-46961354 is fully removed in API-Server
2026-06-03 17:48:46,411 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2026-06-03 17:48:46,469 - DEBUG - Validated that vrouter 10.0.0.22 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN2-64579439
2026-06-03 17:48:46,483 - DEBUG - Validated that vrouter 10.0.0.19 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN2-64579439
2026-06-03 17:48:46,483 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN2-64579439
2026-06-03 17:48:46,483 - INFO - VM ctest-VM21-46961354 is removed in Compute, and routes are removed in all compute nodes
2026-06-03 17:48:46,737 - INFO - Routes for VM ctest-VM21-46961354 is removed in all control-nodes
2026-06-03 17:48:46,737 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5883-2:Compute:contrail-vrouter-agent:0?flat
2026-06-03 17:48:46,776 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5883-2?flat
2026-06-03 17:48:46,809 - DEBUG - VM efe53321-e365-41fa-8d53-6770c443ee30 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5883-2 uve
2026-06-03 17:48:46,809 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-06-03 17:48:46,809 - DEBUG - interface for vm efe53321-e365-41fa-8d53-6770c443ee30 deleted from vrouter uve ...
2026-06-03 17:48:46,809 - DEBUG - Deleted interface not in error interface list ...
2026-06-03 17:48:49,810 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5883-2:Compute:contrail-vrouter-agent:0?flat
2026-06-03 17:48:49,850 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5883-2?flat
2026-06-03 17:48:49,892 - DEBUG - VM efe53321-e365-41fa-8d53-6770c443ee30 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5883-2 uve
2026-06-03 17:48:49,892 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-06-03 17:48:49,892 - DEBUG - interface for vm efe53321-e365-41fa-8d53-6770c443ee30 deleted from vrouter uve ...
2026-06-03 17:48:49,892 - DEBUG - Deleted interface not in error interface list ...
2026-06-03 17:48:52,892 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5883-2:Compute:contrail-vrouter-agent:0?flat
2026-06-03 17:48:52,932 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5883-2?flat
2026-06-03 17:48:52,963 - DEBUG - VM efe53321-e365-41fa-8d53-6770c443ee30 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5883-2 uve
2026-06-03 17:48:52,963 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-06-03 17:48:52,963 - DEBUG - interface for vm efe53321-e365-41fa-8d53-6770c443ee30 deleted from vrouter uve ...
2026-06-03 17:48:52,963 - DEBUG - Deleted interface not in error interface list ...
2026-06-03 17:48:55,964 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5883-2:Compute:contrail-vrouter-agent:0?flat
2026-06-03 17:48:55,999 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5883-2?flat
2026-06-03 17:48:56,031 - DEBUG - VM efe53321-e365-41fa-8d53-6770c443ee30 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5883-2 uve
2026-06-03 17:48:56,031 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-06-03 17:48:56,031 - DEBUG - interface for vm efe53321-e365-41fa-8d53-6770c443ee30 deleted from vrouter uve ...
2026-06-03 17:48:56,031 - DEBUG - Deleted interface not in error interface list ...
2026-06-03 17:48:59,031 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5883-2:Compute:contrail-vrouter-agent:0?flat
2026-06-03 17:48:59,065 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5883-2?flat
2026-06-03 17:48:59,093 - DEBUG - VM efe53321-e365-41fa-8d53-6770c443ee30 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5883-2 uve
2026-06-03 17:48:59,093 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-06-03 17:48:59,093 - DEBUG - interface for vm efe53321-e365-41fa-8d53-6770c443ee30 deleted from vrouter uve ...
2026-06-03 17:48:59,093 - DEBUG - Deleted interface not in error interface list ...
2026-06-03 17:49:02,095 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5883-2:Compute:contrail-vrouter-agent:0?flat
2026-06-03 17:49:02,147 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5883-2?flat
2026-06-03 17:49:02,186 - DEBUG - VM efe53321-e365-41fa-8d53-6770c443ee30 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5883-2 uve
2026-06-03 17:49:02,186 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-06-03 17:49:02,186 - DEBUG - interface for vm efe53321-e365-41fa-8d53-6770c443ee30 deleted from vrouter uve ...
2026-06-03 17:49:02,186 - DEBUG - Deleted interface not in error interface list ...
2026-06-03 17:49:05,187 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5883-2:Compute:contrail-vrouter-agent:0?flat
2026-06-03 17:49:05,235 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5883-2?flat
2026-06-03 17:49:05,280 - DEBUG - VM efe53321-e365-41fa-8d53-6770c443ee30 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5883-2 uve
2026-06-03 17:49:05,280 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-06-03 17:49:05,280 - DEBUG - interface for vm efe53321-e365-41fa-8d53-6770c443ee30 deleted from vrouter uve ...
2026-06-03 17:49:05,280 - DEBUG - Deleted interface not in error interface list ...
2026-06-03 17:49:08,282 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5883-2:Compute:contrail-vrouter-agent:0?flat
2026-06-03 17:49:08,329 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5883-2?flat
2026-06-03 17:49:08,363 - DEBUG - VM efe53321-e365-41fa-8d53-6770c443ee30 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5883-2 uve
2026-06-03 17:49:08,363 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-06-03 17:49:08,363 - DEBUG - interface for vm efe53321-e365-41fa-8d53-6770c443ee30 deleted from vrouter uve ...
2026-06-03 17:49:08,363 - DEBUG - Deleted interface not in error interface list ...
2026-06-03 17:49:11,364 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5883-2:Compute:contrail-vrouter-agent:0?flat
2026-06-03 17:49:11,406 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5883-2?flat
2026-06-03 17:49:11,438 - DEBUG - VM efe53321-e365-41fa-8d53-6770c443ee30 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5883-2 uve
2026-06-03 17:49:11,438 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-06-03 17:49:11,438 - DEBUG - interface for vm efe53321-e365-41fa-8d53-6770c443ee30 deleted from vrouter uve ...
2026-06-03 17:49:11,438 - DEBUG - Deleted interface not in error interface list ...
2026-06-03 17:49:14,438 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5883-2:Compute:contrail-vrouter-agent:0?flat
2026-06-03 17:49:14,472 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5883-2?flat
2026-06-03 17:49:14,506 - DEBUG - VM efe53321-e365-41fa-8d53-6770c443ee30 is not present in vrouter an-jenkins-deploy-platform-ansible-os-5883-2 uve
2026-06-03 17:49:14,506 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-06-03 17:49:14,506 - DEBUG - interface for vm efe53321-e365-41fa-8d53-6770c443ee30 deleted from vrouter uve ...
2026-06-03 17:49:14,506 - DEBUG - Deleted interface not in error interface list ...
2026-06-03 17:49:14,506 - DEBUG - Validated that VM efe53321-e365-41fa-8d53-6770c443ee30 is removed in Vrouter UVE
2026-06-03 17:49:14,506 - DEBUG - Verifying through opserver in 10.0.0.50
2026-06-03 17:49:14,506 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN2-64579439?flat
2026-06-03 17:49:14,533 - DEBUG - efe53321-e365-41fa-8d53-6770c443ee30 BM not in default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN2-64579439 uve
2026-06-03 17:49:14,533 - DEBUG - Validated that VM efe53321-e365-41fa-8d53-6770c443ee30 is not present in the VN UVE
2026-06-03 17:49:14,533 - DEBUG - Verifying the efe53321-e365-41fa-8d53-6770c443ee30 virtual network link through opserver 10.0.0.50
2026-06-03 17:49:14,533 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-machines
2026-06-03 17:49:14,551 - INFO - efe53321-e365-41fa-8d53-6770c443ee30 vm uve deleted from opserver
2026-06-03 17:49:14,551 - DEBUG - Verifying the efe53321-e365-41fa-8d53-6770c443ee30 virtual network link through opserver 10.0.0.33
2026-06-03 17:49:14,551 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machines
2026-06-03 17:49:14,568 - INFO - efe53321-e365-41fa-8d53-6770c443ee30 vm uve deleted from opserver
2026-06-03 17:49:14,568 - DEBUG - Verifying the efe53321-e365-41fa-8d53-6770c443ee30 virtual network link through opserver 10.0.0.249
2026-06-03 17:49:14,568 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machines
2026-06-03 17:49:14,582 - INFO - efe53321-e365-41fa-8d53-6770c443ee30 vm uve deleted from opserver
2026-06-03 17:49:14,582 - INFO - Validated that VM efe53321-e365-41fa-8d53-6770c443ee30 is removed from Opserver
2026-06-03 17:49:14,582 - INFO - Deleting VM ctest-VM11-54109248
2026-06-03 17:49:14,661 - DEBUG - Verifying in api server 10.0.0.249
2026-06-03 17:49:14,661 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/0e068fd9-967e-4efa-a7a7-335d354b9310
2026-06-03 17:49:14,670 - DEBUG - VM ID 0e068fd9-967e-4efa-a7a7-335d354b9310 of VM ctest-VM11-54109248 is still found in API Server
2026-06-03 17:49:16,670 - DEBUG - Verifying in api server 10.0.0.249
2026-06-03 17:49:16,671 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/0e068fd9-967e-4efa-a7a7-335d354b9310
2026-06-03 17:49:16,678 - DEBUG - Response Code: 404
2026-06-03 17:49:16,678 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/0e068fd9-967e-4efa-a7a7-335d354b9310
2026-06-03 17:49:16,686 - DEBUG - Response Code: 404
2026-06-03 17:49:16,687 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/0e068fd9-967e-4efa-a7a7-335d354b9310
2026-06-03 17:49:16,693 - DEBUG - Response Code: 404
2026-06-03 17:49:16,693 - INFO - VM ctest-VM11-54109248 is fully removed in API-Server
2026-06-03 17:49:16,866 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2026-06-03 17:49:16,932 - DEBUG - Validated that vrouter 10.0.0.22 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945
2026-06-03 17:49:16,946 - DEBUG - Validated that vrouter 10.0.0.19 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945
2026-06-03 17:49:16,946 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945
2026-06-03 17:49:16,946 - INFO - VM ctest-VM11-54109248 is removed in Compute, and routes are removed in all compute nodes
2026-06-03 17:49:17,158 - INFO - Routes for VM ctest-VM11-54109248 is removed in all control-nodes
2026-06-03 17:49:17,158 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5883-1:Compute:contrail-vrouter-agent:0?flat
2026-06-03 17:49:17,206 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5883-1?flat
2026-06-03 17:49:17,252 - DEBUG - VM 0e068fd9-967e-4efa-a7a7-335d354b9310 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5883-1 uve
2026-06-03 17:49:17,252 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-06-03 17:49:17,252 - DEBUG - interface for vm 0e068fd9-967e-4efa-a7a7-335d354b9310 deleted from vrouter uve ...
2026-06-03 17:49:17,252 - DEBUG - Deleted interface not in error interface list ...
2026-06-03 17:49:20,253 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5883-1:Compute:contrail-vrouter-agent:0?flat
2026-06-03 17:49:20,297 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5883-1?flat
2026-06-03 17:49:20,329 - DEBUG - VM 0e068fd9-967e-4efa-a7a7-335d354b9310 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5883-1 uve
2026-06-03 17:49:20,329 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-06-03 17:49:20,329 - DEBUG - interface for vm 0e068fd9-967e-4efa-a7a7-335d354b9310 deleted from vrouter uve ...
2026-06-03 17:49:20,329 - DEBUG - Deleted interface not in error interface list ...
2026-06-03 17:49:23,329 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5883-1:Compute:contrail-vrouter-agent:0?flat
2026-06-03 17:49:23,369 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5883-1?flat
2026-06-03 17:49:23,401 - DEBUG - VM 0e068fd9-967e-4efa-a7a7-335d354b9310 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5883-1 uve
2026-06-03 17:49:23,401 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-06-03 17:49:23,401 - DEBUG - interface for vm 0e068fd9-967e-4efa-a7a7-335d354b9310 deleted from vrouter uve ...
2026-06-03 17:49:23,401 - DEBUG - Deleted interface not in error interface list ...
2026-06-03 17:49:26,402 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5883-1:Compute:contrail-vrouter-agent:0?flat
2026-06-03 17:49:26,438 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5883-1?flat
2026-06-03 17:49:26,468 - DEBUG - VM 0e068fd9-967e-4efa-a7a7-335d354b9310 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5883-1 uve
2026-06-03 17:49:26,468 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-06-03 17:49:26,468 - DEBUG - interface for vm 0e068fd9-967e-4efa-a7a7-335d354b9310 deleted from vrouter uve ...
2026-06-03 17:49:26,468 - DEBUG - Deleted interface not in error interface list ...
2026-06-03 17:49:29,469 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5883-1:Compute:contrail-vrouter-agent:0?flat
2026-06-03 17:49:29,509 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5883-1?flat
2026-06-03 17:49:29,545 - DEBUG - VM 0e068fd9-967e-4efa-a7a7-335d354b9310 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5883-1 uve
2026-06-03 17:49:29,545 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-06-03 17:49:29,545 - DEBUG - interface for vm 0e068fd9-967e-4efa-a7a7-335d354b9310 deleted from vrouter uve ...
2026-06-03 17:49:29,545 - DEBUG - Deleted interface not in error interface list ...
2026-06-03 17:49:32,546 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5883-1:Compute:contrail-vrouter-agent:0?flat
2026-06-03 17:49:32,595 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5883-1?flat
2026-06-03 17:49:32,631 - DEBUG - VM 0e068fd9-967e-4efa-a7a7-335d354b9310 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5883-1 uve
2026-06-03 17:49:32,631 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-06-03 17:49:32,631 - DEBUG - interface for vm 0e068fd9-967e-4efa-a7a7-335d354b9310 deleted from vrouter uve ...
2026-06-03 17:49:32,631 - DEBUG - Deleted interface not in error interface list ...
2026-06-03 17:49:35,632 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5883-1:Compute:contrail-vrouter-agent:0?flat
2026-06-03 17:49:35,673 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5883-1?flat
2026-06-03 17:49:35,707 - DEBUG - VM 0e068fd9-967e-4efa-a7a7-335d354b9310 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5883-1 uve
2026-06-03 17:49:35,707 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-06-03 17:49:35,707 - DEBUG - interface for vm 0e068fd9-967e-4efa-a7a7-335d354b9310 deleted from vrouter uve ...
2026-06-03 17:49:35,707 - DEBUG - Deleted interface not in error interface list ...
2026-06-03 17:49:38,708 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5883-1:Compute:contrail-vrouter-agent:0?flat
2026-06-03 17:49:38,743 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5883-1?flat
2026-06-03 17:49:38,770 - DEBUG - VM 0e068fd9-967e-4efa-a7a7-335d354b9310 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5883-1 uve
2026-06-03 17:49:38,770 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-06-03 17:49:38,770 - DEBUG - interface for vm 0e068fd9-967e-4efa-a7a7-335d354b9310 deleted from vrouter uve ...
2026-06-03 17:49:38,770 - DEBUG - Deleted interface not in error interface list ...
2026-06-03 17:49:41,771 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5883-1:Compute:contrail-vrouter-agent:0?flat
2026-06-03 17:49:41,805 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5883-1?flat
2026-06-03 17:49:41,841 - DEBUG - VM 0e068fd9-967e-4efa-a7a7-335d354b9310 is not present in vrouter an-jenkins-deploy-platform-ansible-os-5883-1 uve
2026-06-03 17:49:41,841 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-06-03 17:49:41,841 - DEBUG - interface for vm 0e068fd9-967e-4efa-a7a7-335d354b9310 deleted from vrouter uve ...
2026-06-03 17:49:41,841 - DEBUG - Deleted interface not in error interface list ...
2026-06-03 17:49:41,841 - DEBUG - Validated that VM 0e068fd9-967e-4efa-a7a7-335d354b9310 is removed in Vrouter UVE
2026-06-03 17:49:41,841 - DEBUG - Verifying through opserver in 10.0.0.50
2026-06-03 17:49:41,841 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945?flat
2026-06-03 17:49:41,860 - DEBUG - 0e068fd9-967e-4efa-a7a7-335d354b9310 BM not in default-domain:ctest-TestPolicyAclIpv4v6-35807875:ctest-VN1-59419945 uve
2026-06-03 17:49:41,860 - DEBUG - Validated that VM 0e068fd9-967e-4efa-a7a7-335d354b9310 is not present in the VN UVE
2026-06-03 17:49:41,860 - DEBUG - Verifying the 0e068fd9-967e-4efa-a7a7-335d354b9310 virtual network link through opserver 10.0.0.50
2026-06-03 17:49:41,860 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-machines
2026-06-03 17:49:41,873 - DEBUG - No links retuned for 0e068fd9-967e-4efa-a7a7-335d354b9310
2026-06-03 17:49:41,873 - INFO - 0e068fd9-967e-4efa-a7a7-335d354b9310 vm uve deleted from opserver
2026-06-03 17:49:41,873 - DEBUG - Verifying the 0e068fd9-967e-4efa-a7a7-335d354b9310 virtual network link through opserver 10.0.0.33
2026-06-03 17:49:41,873 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machines
2026-06-03 17:49:41,888 - DEBUG - No links retuned for 0e068fd9-967e-4efa-a7a7-335d354b9310
2026-06-03 17:49:41,888 - INFO - 0e068fd9-967e-4efa-a7a7-335d354b9310 vm uve deleted from opserver
2026-06-03 17:49:41,888 - DEBUG - Verifying the 0e068fd9-967e-4efa-a7a7-335d354b9310 virtual network link through opserver 10.0.0.249
2026-06-03 17:49:41,888 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machines
2026-06-03 17:49:41,905 - DEBUG - No links retuned for 0e068fd9-967e-4efa-a7a7-335d354b9310
2026-06-03 17:49:41,905 - INFO - 0e068fd9-967e-4efa-a7a7-335d354b9310 vm uve deleted from opserver
2026-06-03 17:49:41,906 - INFO - Validated that VM 0e068fd9-967e-4efa-a7a7-335d354b9310 is removed from Opserver
2026-06-03 17:49:41,906 - DEBUG - Detaching the Policy for VN :ctest-VN2-64579439
2026-06-03 17:49:41,906 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-06-03 17:49:41,911 - DEBUG - Requesting: http://10.0.0.50:8082/domain/cfce58c2-bdea-4e41-80d8-e31e3dad5e9f
2026-06-03 17:49:41,963 - DEBUG - Requesting: http://10.0.0.50:8082/project/7ed12a2e-484d-4264-82e2-9cbae0a87532
2026-06-03 17:49:42,070 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/603e4114-1c86-44d4-958c-122e0e77d8a4
2026-06-03 17:49:42,088 - DEBUG - Requesting: http://10.0.0.50:8082/network-policy/add88398-7166-4cd5-9da5-5765617d2ed9
2026-06-03 17:49:42,310 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-35807875', 'ctest-policy21-50350804']] from ctest-VN2-64579439
2026-06-03 17:49:42,310 - DEBUG - Detaching the Policy for VN :ctest-VN1-59419945
2026-06-03 17:49:42,310 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-06-03 17:49:42,322 - DEBUG - Requesting: http://10.0.0.50:8082/domain/cfce58c2-bdea-4e41-80d8-e31e3dad5e9f
2026-06-03 17:49:42,354 - DEBUG - Requesting: http://10.0.0.50:8082/project/7ed12a2e-484d-4264-82e2-9cbae0a87532
2026-06-03 17:49:42,474 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/2451254e-ac88-417e-b09a-dbe9443a3cbb
2026-06-03 17:49:42,495 - DEBUG - Requesting: http://10.0.0.50:8082/network-policy/4af78ab9-6034-4335-a07f-c7c63e70bcdc
2026-06-03 17:49:42,506 - DEBUG - Requesting: http://10.0.0.50:8082/network-policy/056669e7-087f-4054-b8cc-b5609565114f
2026-06-03 17:49:42,714 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-35807875', 'ctest-policy12-59289002'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-35807875', 'ctest-policy13-73676923']] from ctest-VN1-59419945
2026-06-03 17:49:42,756 - INFO - Deleted policy ctest-policy21-50350804
2026-06-03 17:49:42,808 - INFO - Deleted policy ctest-policy12-59289002
2026-06-03 17:49:42,861 - INFO - Deleted policy ctest-policy13-73676923
2026-06-03 17:49:42,861 - INFO - Deleting VN ctest-VN3-88266431
2026-06-03 17:49:43,135 - DEBUG - Response for deleting network ()
2026-06-03 17:49:43,136 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/6f8143c5-3a04-42d7-a029-d0ff31ddc461
2026-06-03 17:49:43,141 - DEBUG - Response Code: 404
2026-06-03 17:49:43,142 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-06-03 17:49:43,152 - DEBUG - Requesting: http://10.0.0.50:8082/domain/cfce58c2-bdea-4e41-80d8-e31e3dad5e9f
2026-06-03 17:49:43,165 - DEBUG - Requesting: http://10.0.0.50:8082/project/7ed12a2e-484d-4264-82e2-9cbae0a87532
2026-06-03 17:49:43,270 - INFO - Validated that VN ctest-VN3-88266431 is not found in API Server
2026-06-03 17:49:43,283 - DEBUG - VN ctest-VN3-88266431 is not present in Agent 10.0.0.22
2026-06-03 17:49:43,296 - DEBUG - VN ctest-VN3-88266431 is not present in Agent 10.0.0.19
2026-06-03 17:49:43,296 - INFO - Validated that VN ctest-VN3-88266431 is not in any agent
2026-06-03 17:49:43,332 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN3-88266431 info
2026-06-03 17:49:43,332 - INFO - Deleting VN ctest-VN2-64579439
2026-06-03 17:49:43,540 - DEBUG - Response for deleting network ()
2026-06-03 17:49:43,541 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/28417804-6fcd-42b3-b4a6-ccc373ba12f1
2026-06-03 17:49:43,555 - DEBUG - Response Code: 404
2026-06-03 17:49:43,555 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-06-03 17:49:43,564 - DEBUG - Requesting: http://10.0.0.50:8082/domain/cfce58c2-bdea-4e41-80d8-e31e3dad5e9f
2026-06-03 17:49:43,586 - DEBUG - Requesting: http://10.0.0.50:8082/project/7ed12a2e-484d-4264-82e2-9cbae0a87532
2026-06-03 17:49:43,678 - INFO - Validated that VN ctest-VN2-64579439 is not found in API Server
2026-06-03 17:49:43,691 - DEBUG - VN ctest-VN2-64579439 is not present in Agent 10.0.0.22
2026-06-03 17:49:43,703 - DEBUG - VN ctest-VN2-64579439 is not present in Agent 10.0.0.19
2026-06-03 17:49:43,703 - INFO - Validated that VN ctest-VN2-64579439 is not in any agent
2026-06-03 17:49:43,739 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN2-64579439 info
2026-06-03 17:49:43,739 - INFO - Deleting VN ctest-VN1-59419945
2026-06-03 17:49:43,958 - DEBUG - Response for deleting network ()
2026-06-03 17:49:43,958 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/4ea162db-8767-4f41-b713-bcf2eba352a1
2026-06-03 17:49:43,964 - DEBUG - Response Code: 404
2026-06-03 17:49:43,964 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-06-03 17:49:43,982 - DEBUG - Requesting: http://10.0.0.50:8082/domain/cfce58c2-bdea-4e41-80d8-e31e3dad5e9f
2026-06-03 17:49:44,004 - DEBUG - Requesting: http://10.0.0.50:8082/project/7ed12a2e-484d-4264-82e2-9cbae0a87532
2026-06-03 17:49:44,111 - INFO - Validated that VN ctest-VN1-59419945 is not found in API Server
2026-06-03 17:49:44,125 - DEBUG - VN ctest-VN1-59419945 is not present in Agent 10.0.0.22
2026-06-03 17:49:44,141 - DEBUG - VN ctest-VN1-59419945 is not present in Agent 10.0.0.19
2026-06-03 17:49:44,141 - INFO - Validated that VN ctest-VN1-59419945 is not in any agent
2026-06-03 17:49:44,192 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN1-59419945 info
2026-06-03 17:49:44,305 - INFO - IPAM: ipam3 is not found in API Server
2026-06-03 17:49:44,332 - INFO - IPAM:ipam3 is not found in control node
2026-06-03 17:49:44,477 - INFO - IPAM: ipam2 is not found in API Server
2026-06-03 17:49:44,504 - INFO - IPAM:ipam2 is not found in control node
2026-06-03 17:49:44,657 - INFO - IPAM: ipam1 is not found in API Server
2026-06-03 17:49:44,680 - INFO - IPAM:ipam1 is not found in control node
2026-06-03 17:49:45,969 - DEBUG - No XMPP flaps were noticed during the test
2026-06-03 17:49:45,969 - INFO - END TEST : test_policy_inheritance_src_pol_dst_any : PASSED[0:03:23]
2026-06-03 17:49:45,969 - INFO - --------------------------------------------------------------------------------
2026-06-03 17:49:47,259 - DEBUG - No XMPP flaps were noticed during the test
2026-06-03 17:49:47,259 - INFO - END TEST : test_policy_inheritance_src_pol_dst_any : PASSED[0:03:26]
2026-06-03 17:49:47,259 - INFO - --------------------------------------------------------------------------------
2026-06-03 17:49:48,077 - INFO - Deleted project: ctest-TestPolicyAclIpv4v6-35807875, ID : 7ed12a2e-484d-4264-82e2-9cbae0a87532