is in ACTIVE state now
2026-04-03 17:14:30,774 - DEBUG - VM ctest-VM31-65155674 launched on Node an-jenkins-deploy-platform-ansible-os-5589-2
2026-04-03 17:14:30,868 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/889d290c-197a-4736-8774-c54f791fda73
2026-04-03 17:14:30,878 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/889d290c-197a-4736-8774-c54f791fda73
2026-04-03 17:14:30,886 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/829fdd81-8c63-48dd-9880-826bf461628f
2026-04-03 17:14:30,898 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/889d290c-197a-4736-8774-c54f791fda73
2026-04-03 17:14:30,907 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/829fdd81-8c63-48dd-9880-826bf461628f
2026-04-03 17:14:30,919 - DEBUG - Requesting: http://10.0.0.141:8082/instance-ip/23583b81-fb2c-4bf8-9e96-ab9d07b93b48
2026-04-03 17:14:30,930 - DEBUG - Requesting: http://10.0.0.141:8082/instance-ip/b4bd935b-9376-4b43-bcf0-d4dc977c8b65
2026-04-03 17:14:30,940 - DEBUG - Verifying in api server 10.0.0.141
2026-04-03 17:14:30,940 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/889d290c-197a-4736-8774-c54f791fda73
2026-04-03 17:14:30,947 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/829fdd81-8c63-48dd-9880-826bf461628f
2026-04-03 17:14:30,958 - DEBUG - Requesting: http://10.0.0.141:8082/instance-ip/23583b81-fb2c-4bf8-9e96-ab9d07b93b48
2026-04-03 17:14:30,966 - DEBUG - Requesting: http://10.0.0.141:8082/instance-ip/b4bd935b-9376-4b43-bcf0-d4dc977c8b65
2026-04-03 17:14:30,976 - INFO - VM ctest-VM31-65155674 verfication in all API Servers passed
2026-04-03 17:14:30,992 - DEBUG - VM ctest-VM31-65155674 Tap interface: {'index': '4', 'name': 'tap829fdd81-8c', 'uuid': '829fdd81-8c63-48dd-9880-826bf461628f', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-77086446:ctest-VN3-70141270:ctest-VN3-70141270', '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': '14', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-77086446:ctest-VN3-70141270', 'vm_uuid': '889d290c-197a-4736-8774-c54f791fda73', 'vm_name': 'ctest-VM31-65155674', 'ip_addr': '51.117.139.3', 'mac_addr': '02:82:9f:dd:81:8c', '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-77086446:829fdd81-8c63-48dd-9880-826bf461628f', 'sg_uuid_list': ['7c1b26c8-53de-4649-8ecb-dc8ccc70aaed'], 'static_route_list': None, 'vm_project_uuid': '9455923f-170a-4a14-8a91-6c66862c188a', 'admin_state': 'Enabled', 'flow_key_idx': '39', 'allowed_address_pair_list': None, 'ip6_addr': '3765:567c:6cde:669e:1018:c057:0:3', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []}
2026-04-03 17:14:30,992 - DEBUG - Agent 10.0.0.240 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-77086446:ctest-VN3-70141270:ctest-VN3-70141270
2026-04-03 17:14:30,996 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-77086446:ctest-VN3-70141270:ctest-VN3-70141270', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-77086446:ctest-VN3-70141270', 'table_label': '-1', 'vxlan_id': '14', '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-04-03 17:14:31,082 - DEBUG - VM ctest-VM31-65155674 : Tap interface tap829fdd81-8c is set to Active
2026-04-03 17:14:31,082 - DEBUG - Tap interface tap829fdd81-8c detail : {'index': '4', 'name': 'tap829fdd81-8c', 'uuid': '829fdd81-8c63-48dd-9880-826bf461628f', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-77086446:ctest-VN3-70141270:ctest-VN3-70141270', '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': '14', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-77086446:ctest-VN3-70141270', 'vm_uuid': '889d290c-197a-4736-8774-c54f791fda73', 'vm_name': 'ctest-VM31-65155674', 'ip_addr': '51.117.139.3', 'mac_addr': '02:82:9f:dd:81:8c', '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-77086446:829fdd81-8c63-48dd-9880-826bf461628f', 'sg_uuid_list': ['7c1b26c8-53de-4649-8ecb-dc8ccc70aaed'], 'static_route_list': None, 'vm_project_uuid': '9455923f-170a-4a14-8a91-6c66862c188a', 'admin_state': 'Enabled', 'flow_key_idx': '39', 'allowed_address_pair_list': None, 'ip6_addr': '3765:567c:6cde:669e:1018:c057:0:3', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []}
2026-04-03 17:14:31,200 - DEBUG - Starting Layer 2 verification in Agent
2026-04-03 17:14:31,215 - DEBUG - Layer 2 path is seen for VM MAC 02:82:9f:dd:81:8c in agent 10.0.0.240
2026-04-03 17:14:31,215 - DEBUG - Active layer 2 route in agent is present for VMI tap829fdd81-8c
2026-04-03 17:14:31,215 - DEBUG - L2 label(36) matches bw route table and interface table
2026-04-03 17:14:31,226 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-77086446:ctest-VN3-70141270 is found in Agent of node 10.0.0.240
2026-04-03 17:14:31,374 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-77086446:ctest-VN3-70141270 is consistent in agent 10.0.0.240
2026-04-03 17:14:31,374 - DEBUG - Route for VM IP ['3765:567c:6cde:669e:1018:c057:0:3', '51.117.139.3'] is consistent in agent 10.0.0.240
2026-04-03 17:14:31,374 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-77086446:ctest-VN3-70141270 verification for VM ctest-VM31-65155674 in Agent 10.0.0.240 passed
2026-04-03 17:14:31,488 - DEBUG - Starting all layer 2 verification in agent 10.0.0.240
2026-04-03 17:14:31,496 - DEBUG - Route for VM MAC 02:82:9f:dd:81:8c is consistent in agent 10.0.0.240
2026-04-03 17:14:32,679 - 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=6.70 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=1.97 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 1.967/4.332/6.697/2.365 ms')
2026-04-03 17:14:32,679 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-65155674 passed
2026-04-03 17:14:32,679 - INFO - VM ctest-VM31-65155674 verifications in Compute nodes passed
2026-04-03 17:14:32,882 - DEBUG - Validated VM route 51.117.139.3 in vrouter of 10.0.0.240
2026-04-03 17:14:32,890 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0
2026-04-03 17:14:32,890 - INFO - Validated routes of VM ctest-VM31-65155674 in all vrouters
2026-04-03 17:14:33,399 - DEBUG - Starting all layer2 verification in 10.0.0.38 Control Node
2026-04-03 17:14:33,517 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.38, not supported
2026-04-03 17:14:33,640 - DEBUG - Layer2 route found for VM MAC 02:82:9f:dd:81:8c in Control-node 10.0.0.38
2026-04-03 17:14:33,640 - DEBUG - L2 Label for VM ctest-VM31-65155674 same between Control-node 10.0.0.38 and Agent, Expected: 36, Seen: 36
2026-04-03 17:14:33,640 - DEBUG - Starting all layer2 verification in 10.0.0.141 Control Node
2026-04-03 17:14:33,752 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.141, not supported
2026-04-03 17:14:33,880 - DEBUG - Layer2 route found for VM MAC 02:82:9f:dd:81:8c in Control-node 10.0.0.141
2026-04-03 17:14:33,880 - DEBUG - L2 Label for VM ctest-VM31-65155674 same between Control-node 10.0.0.141 and Agent, Expected: 36, Seen: 36
2026-04-03 17:14:33,880 - INFO - Verification in Control-nodes for VM ctest-VM31-65155674 passed
2026-04-03 17:14:33,880 - DEBUG - Verifying the vm in opserver
2026-04-03 17:14:33,880 - DEBUG - Verifying in collector 10.0.0.141 ...
2026-04-03 17:14:33,880 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-machine/889d290c-197a-4736-8774-c54f791fda73?flat
2026-04-03 17:14:33,895 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-77086446:829fdd81-8c63-48dd-9880-826bf461628f?flat
2026-04-03 17:14:33,913 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-77086446:829fdd81-8c63-48dd-9880-826bf461628f?flat
2026-04-03 17:14:33,930 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-77086446:829fdd81-8c63-48dd-9880-826bf461628f?flat
2026-04-03 17:14:33,950 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-machine/889d290c-197a-4736-8774-c54f791fda73?flat
2026-04-03 17:14:33,966 - DEBUG - Verifying vm in vn uve
2026-04-03 17:14:33,966 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-77086446:829fdd81-8c63-48dd-9880-826bf461628f?flat
2026-04-03 17:14:33,984 - DEBUG - VM uve shows interface as {'tx_vlan': 65535, 'fixed_ip6_list': ['3765:567c:6cde:669e:1018:c057:0:3'], 'fixed_ip4_list': ['51.117.139.3'], 'active': True, 'ip_address': '51.117.139.3', 'uuid': '829fdd81-8c63-48dd-9880-826bf461628f', 'mac_address': '02:82:9f:dd:81:8c', 'ip6_active': True, 'vhostuser_mode': 0, 'vm_name': 'ctest-VM31-65155674', 'is_health_check_active': True, 'rx_vlan': 65535, 'ip4_active': True, 'vm_uuid': '889d290c-197a-4736-8774-c54f791fda73', 'gateway': '51.117.139.1', 'vn_uuid': '2e3f5bea-7cae-4989-8522-6bf90d38b9e3', 'l2_active': True, 'label': 32, 'ip6_address': '3765:567c:6cde:669e:1018:c057:0:3', 'port_mirror_enabled': False, 'admin_state': True, '__T': 1775236428043612, 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-77086446:ctest-VN3-70141270'}
2026-04-03 17:14:33,985 - DEBUG - VM uve shows ip address as ['51.117.139.3', '3765:567c:6cde:669e:1018:c057:0:3']
2026-04-03 17:14:33,985 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-77086446:ctest-VN3-70141270
2026-04-03 17:14:33,985 - INFO - Verifying through opserver in 10.0.0.22
2026-04-03 17:14:33,985 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-77086446:ctest-VN3-70141270?flat
2026-04-03 17:14:34,006 - DEBUG - expected vm list 889d290c-197a-4736-8774-c54f791fda73
2026-04-03 17:14:34,006 - DEBUG - Extracted vm list ['889d290c-197a-4736-8774-c54f791fda73']
2026-04-03 17:14:34,006 - INFO - VM 889d290c-197a-4736-8774-c54f791fda73 is present in default-domain:ctest-TestPolicyAclIpv4v6-77086446:ctest-VN3-70141270
2026-04-03 17:14:34,006 - DEBUG - Verifying vm in vrouter uve
2026-04-03 17:14:34,006 - DEBUG - Getting info from collector 10.0.0.141..
2026-04-03 17:14:34,006 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-machine/889d290c-197a-4736-8774-c54f791fda73?flat
2026-04-03 17:14:34,024 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-5589-2']
2026-04-03 17:14:34,024 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5589-2:Compute:contrail-vrouter-agent:0?flat
2026-04-03 17:14:34,055 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5589-2?flat
2026-04-03 17:14:34,082 - DEBUG - VM 889d290c-197a-4736-8774-c54f791fda73 is present in vrouter an-jenkins-deploy-platform-ansible-os-5589-2 uve
2026-04-03 17:14:34,082 - DEBUG - Validated that VM ctest-VM31-65155674 is in Vrouter an-jenkins-deploy-platform-ansible-os-5589-2 UVE
2026-04-03 17:14:34,083 - DEBUG - Verifying vm tap interface/vn in vrouter uve
2026-04-03 17:14:34,089 - DEBUG - Expected tap interface of VM uuid 889d290c-197a-4736-8774-c54f791fda73 is default-domain:ctest-TestPolicyAclIpv4v6-77086446:829fdd81-8c63-48dd-9880-826bf461628f
2026-04-03 17:14:34,090 - DEBUG - Expected VN of VM uuid 889d290c-197a-4736-8774-c54f791fda73 is default-domain:ctest-TestPolicyAclIpv4v6-77086446:ctest-VN3-70141270
2026-04-03 17:14:34,090 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5589-2:Compute:contrail-vrouter-agent:0?flat
2026-04-03 17:14:34,121 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5589-2?flat
2026-04-03 17:14:34,148 - DEBUG - VM 889d290c-197a-4736-8774-c54f791fda73 is present in vrouter an-jenkins-deploy-platform-ansible-os-5589-2 uve
2026-04-03 17:14:34,148 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-77086446:829fdd81-8c63-48dd-9880-826bf461628f of vm 889d290c-197a-4736-8774-c54f791fda73 is present in vrouter an-jenkins-deploy-platform-ansible-os-5589-2 uve
2026-04-03 17:14:34,148 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-77086446:ctest-VN3-70141270 of vm 889d290c-197a-4736-8774-c54f791fda73 is present in vrouter an-jenkins-deploy-platform-ansible-os-5589-2 uve
2026-04-03 17:14:34,148 - DEBUG - Verifying the 889d290c-197a-4736-8774-c54f791fda73 virtual network link through opserver 10.0.0.22
2026-04-03 17:14:34,148 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machines
2026-04-03 17:14:34,158 - DEBUG - VM link and name as {'name': '889d290c-197a-4736-8774-c54f791fda73', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-machine/889d290c-197a-4736-8774-c54f791fda73?flat'}
2026-04-03 17:14:34,158 - DEBUG - Verifying the 889d290c-197a-4736-8774-c54f791fda73 virtual network link through opserver 10.0.0.38
2026-04-03 17:14:34,159 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines
2026-04-03 17:14:34,170 - DEBUG - VM link and name as {'name': '889d290c-197a-4736-8774-c54f791fda73', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-machine/889d290c-197a-4736-8774-c54f791fda73?flat'}
2026-04-03 17:14:34,170 - DEBUG - Verifying the 889d290c-197a-4736-8774-c54f791fda73 virtual network link through opserver 10.0.0.141
2026-04-03 17:14:34,170 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-machines
2026-04-03 17:14:34,183 - DEBUG - VM link and name as {'name': '889d290c-197a-4736-8774-c54f791fda73', 'href': 'http://10.0.0.141:8081/analytics/uves/virtual-machine/889d290c-197a-4736-8774-c54f791fda73?flat'}
2026-04-03 17:14:34,183 - INFO - VM ctest-VM31-65155674 validations in Opserver passed
2026-04-03 17:14:34,183 - INFO - Waiting for VM ctest-VM11-39346586 to be up..
2026-04-03 17:14:34,183 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-03 17:14:34,183 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/2d95a307-975d-4900-a094-28f8139f0321
2026-04-03 17:14:34,191 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/10647ce1-15e6-402b-bd4c-e84ea9f320f5
2026-04-03 17:14:35,353 - 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=0.687 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.687/1.818/2.950/1.131 ms')
2026-04-03 17:14:35,354 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-39346586 passed
2026-04-03 17:14:35,425 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-03 17:14:35,426 - DEBUG - Waiting to SSH to VM ctest-VM11-39346586, IP 32.40.171.195, Port 22
2026-04-03 17:14:35,480 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-04-03 17:14:35,559 - DEBUG - VM ctest-VM11-39346586 is NOT ready for SSH connections, VM status: ACTIVE
2026-04-03 17:14:40,559 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-03 17:14:40,560 - DEBUG - Waiting to SSH to VM ctest-VM11-39346586, IP 32.40.171.195, Port 22
2026-04-03 17:14:40,626 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-04-03 17:14:40,713 - DEBUG - VM ctest-VM11-39346586 is NOT ready for SSH connections, VM status: ACTIVE
2026-04-03 17:14:45,714 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-03 17:14:45,714 - DEBUG - Waiting to SSH to VM ctest-VM11-39346586, IP 32.40.171.195, Port 22
2026-04-03 17:14:45,890 - DEBUG - VM ctest-VM11-39346586 is ready for SSH connections
2026-04-03 17:14:45,890 - INFO - Waiting for VM ctest-VM21-63583778 to be up..
2026-04-03 17:14:45,890 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-03 17:14:45,890 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/fd5b9b60-b6ed-45cd-8bbc-fd7ac5cbb06b
2026-04-03 17:14:45,899 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/63fe3b20-05fe-4d81-a767-45cc11299d88
2026-04-03 17:14:47,059 - 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.18 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.270/2.224/3.179/0.954 ms')
2026-04-03 17:14:47,059 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-63583778 passed
2026-04-03 17:14:47,136 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-03 17:14:47,136 - DEBUG - Waiting to SSH to VM ctest-VM21-63583778, IP 185.217.230.195, Port 22
2026-04-03 17:14:47,205 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-04-03 17:14:47,289 - DEBUG - VM ctest-VM21-63583778 is NOT ready for SSH connections, VM status: ACTIVE
2026-04-03 17:14:52,290 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-03 17:14:52,290 - DEBUG - Waiting to SSH to VM ctest-VM21-63583778, IP 185.217.230.195, Port 22
2026-04-03 17:14:52,464 - DEBUG - VM ctest-VM21-63583778 is ready for SSH connections
2026-04-03 17:14:52,464 - INFO - Waiting for VM ctest-VM31-65155674 to be up..
2026-04-03 17:14:52,464 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-03 17:14:52,464 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/889d290c-197a-4736-8774-c54f791fda73
2026-04-03 17:14:52,473 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/829fdd81-8c63-48dd-9880-826bf461628f
2026-04-03 17:14:53,625 - 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.83 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.803 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.803/1.816/2.830/1.013 ms')
2026-04-03 17:14:53,625 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-65155674 passed
2026-04-03 17:14:53,692 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-03 17:14:53,692 - DEBUG - Waiting to SSH to VM ctest-VM31-65155674, IP 51.117.139.3, Port 22
2026-04-03 17:14:53,858 - DEBUG - VM ctest-VM31-65155674 is ready for SSH connections
2026-04-03 17:14:53,858 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 185.217.230.195, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.240, gateway password: c0ntrail123
2026-04-03 17:14:53,858 - DEBUG - ping -s 56 -c 3 -W 1 185.217.230.195
2026-04-03 17:15:07,275 - DEBUG - PING 185.217.230.195 (185.217.230.195) 56(84) bytes of data.
64 bytes from 185.217.230.195: icmp_req=1 ttl=63 time=7.55 ms
64 bytes from 185.217.230.195: icmp_req=2 ttl=63 time=1.05 ms
64 bytes from 185.217.230.195: icmp_req=3 ttl=63 time=1.88 ms
--- 185.217.230.195 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2005ms
rtt min/avg/max/mdev = 1.051/3.496/7.558/2.892 ms
2026-04-03 17:15:07,275 - INFO - Ping to IP 185.217.230.195 from VM ctest-VM11-39346586 passed
2026-04-03 17:15:07,275 - DEBUG - Running remote_cmd, Cmd : ping6 -s 56 -c 3 -W 1 3267:1d54:46de:3e17:f2c4:9a6f:0:3, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.240, gateway password: c0ntrail123
2026-04-03 17:15:07,276 - DEBUG - ping6 -s 56 -c 3 -W 1 3267:1d54:46de:3e17:f2c4:9a6f:0:3
2026-04-03 17:15:09,791 - DEBUG - PING 3267:1d54:46de:3e17:f2c4:9a6f:0:3(3267:1d54:46de:3e17:f2c4:9a6f:0:3) 56 data bytes
64 bytes from 3267:1d54:46de:3e17:f2c4:9a6f:0:3: icmp_seq=1 ttl=64 time=8.00 ms
64 bytes from 3267:1d54:46de:3e17:f2c4:9a6f:0:3: icmp_seq=2 ttl=64 time=3.44 ms
64 bytes from 3267:1d54:46de:3e17:f2c4:9a6f:0:3: icmp_seq=3 ttl=64 time=1.33 ms
--- 3267:1d54:46de:3e17:f2c4:9a6f:0:3 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 1.330/4.261/8.006/2.785 ms
2026-04-03 17:15:09,791 - INFO - Ping to IP 3267:1d54:46de:3e17:f2c4:9a6f:0:3 from VM ctest-VM11-39346586 passed
2026-04-03 17:15:09,792 - INFO - Test with src as policy and dst as any PASSED
2026-04-03 17:15:09,792 - INFO - Deleting VM ctest-VM31-65155674
2026-04-03 17:15:09,856 - DEBUG - Verifying in api server 10.0.0.141
2026-04-03 17:15:09,856 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/889d290c-197a-4736-8774-c54f791fda73
2026-04-03 17:15:09,865 - DEBUG - VM ID 889d290c-197a-4736-8774-c54f791fda73 of VM ctest-VM31-65155674 is still found in API Server
2026-04-03 17:15:11,866 - DEBUG - Verifying in api server 10.0.0.141
2026-04-03 17:15:11,866 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/889d290c-197a-4736-8774-c54f791fda73
2026-04-03 17:15:11,873 - DEBUG - Response Code: 404
2026-04-03 17:15:11,873 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/889d290c-197a-4736-8774-c54f791fda73
2026-04-03 17:15:11,879 - DEBUG - Response Code: 404
2026-04-03 17:15:11,879 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/889d290c-197a-4736-8774-c54f791fda73
2026-04-03 17:15:11,886 - DEBUG - Response Code: 404
2026-04-03 17:15:11,886 - INFO - VM ctest-VM31-65155674 is fully removed in API-Server
2026-04-03 17:15:12,049 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2026-04-03 17:15:12,109 - DEBUG - Validated that vrouter 10.0.0.19 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-77086446:ctest-VN3-70141270
2026-04-03 17:15:12,121 - DEBUG - Validated that vrouter 10.0.0.240 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-77086446:ctest-VN3-70141270
2026-04-03 17:15:12,121 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-77086446:ctest-VN3-70141270
2026-04-03 17:15:12,121 - INFO - VM ctest-VM31-65155674 is removed in Compute, and routes are removed in all compute nodes
2026-04-03 17:15:12,348 - INFO - Routes for VM ctest-VM31-65155674 is removed in all control-nodes
2026-04-03 17:15:12,349 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5589-2:Compute:contrail-vrouter-agent:0?flat
2026-04-03 17:15:12,387 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5589-2?flat
2026-04-03 17:15:12,416 - DEBUG - VM 889d290c-197a-4736-8774-c54f791fda73 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5589-2 uve
2026-04-03 17:15:12,417 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-03 17:15:12,417 - DEBUG - interface for vm 889d290c-197a-4736-8774-c54f791fda73 deleted from vrouter uve ...
2026-04-03 17:15:12,417 - DEBUG - Deleted interface not in error interface list ...
2026-04-03 17:15:15,417 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5589-2:Compute:contrail-vrouter-agent:0?flat
2026-04-03 17:15:15,452 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5589-2?flat
2026-04-03 17:15:15,480 - DEBUG - VM 889d290c-197a-4736-8774-c54f791fda73 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5589-2 uve
2026-04-03 17:15:15,480 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-03 17:15:15,480 - DEBUG - interface for vm 889d290c-197a-4736-8774-c54f791fda73 deleted from vrouter uve ...
2026-04-03 17:15:15,480 - DEBUG - Deleted interface not in error interface list ...
2026-04-03 17:15:18,481 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5589-2:Compute:contrail-vrouter-agent:0?flat
2026-04-03 17:15:18,513 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5589-2?flat
2026-04-03 17:15:18,541 - DEBUG - VM 889d290c-197a-4736-8774-c54f791fda73 is not present in vrouter an-jenkins-deploy-platform-ansible-os-5589-2 uve
2026-04-03 17:15:18,541 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-03 17:15:18,541 - DEBUG - interface for vm 889d290c-197a-4736-8774-c54f791fda73 deleted from vrouter uve ...
2026-04-03 17:15:18,541 - DEBUG - Deleted interface not in error interface list ...
2026-04-03 17:15:18,541 - DEBUG - Validated that VM 889d290c-197a-4736-8774-c54f791fda73 is removed in Vrouter UVE
2026-04-03 17:15:18,541 - DEBUG - Verifying through opserver in 10.0.0.22
2026-04-03 17:15:18,541 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-77086446:ctest-VN3-70141270?flat
2026-04-03 17:15:18,564 - DEBUG - 889d290c-197a-4736-8774-c54f791fda73 BM not in default-domain:ctest-TestPolicyAclIpv4v6-77086446:ctest-VN3-70141270 uve
2026-04-03 17:15:18,564 - DEBUG - Validated that VM 889d290c-197a-4736-8774-c54f791fda73 is not present in the VN UVE
2026-04-03 17:15:18,564 - DEBUG - Verifying the 889d290c-197a-4736-8774-c54f791fda73 virtual network link through opserver 10.0.0.22
2026-04-03 17:15:18,564 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machines
2026-04-03 17:15:18,576 - INFO - 889d290c-197a-4736-8774-c54f791fda73 vm uve deleted from opserver
2026-04-03 17:15:18,576 - DEBUG - Verifying the 889d290c-197a-4736-8774-c54f791fda73 virtual network link through opserver 10.0.0.38
2026-04-03 17:15:18,576 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines
2026-04-03 17:15:18,587 - INFO - 889d290c-197a-4736-8774-c54f791fda73 vm uve deleted from opserver
2026-04-03 17:15:18,587 - DEBUG - Verifying the 889d290c-197a-4736-8774-c54f791fda73 virtual network link through opserver 10.0.0.141
2026-04-03 17:15:18,587 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-machines
2026-04-03 17:15:18,600 - INFO - 889d290c-197a-4736-8774-c54f791fda73 vm uve deleted from opserver
2026-04-03 17:15:18,600 - INFO - Validated that VM 889d290c-197a-4736-8774-c54f791fda73 is removed from Opserver
2026-04-03 17:15:18,601 - INFO - Deleting VM ctest-VM21-63583778
2026-04-03 17:15:18,669 - DEBUG - Verifying in api server 10.0.0.141
2026-04-03 17:15:18,669 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/fd5b9b60-b6ed-45cd-8bbc-fd7ac5cbb06b
2026-04-03 17:15:18,679 - DEBUG - VM ID fd5b9b60-b6ed-45cd-8bbc-fd7ac5cbb06b of VM ctest-VM21-63583778 is still found in API Server
2026-04-03 17:15:20,680 - DEBUG - Verifying in api server 10.0.0.141
2026-04-03 17:15:20,680 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/fd5b9b60-b6ed-45cd-8bbc-fd7ac5cbb06b
2026-04-03 17:15:20,688 - DEBUG - Response Code: 404
2026-04-03 17:15:20,688 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/fd5b9b60-b6ed-45cd-8bbc-fd7ac5cbb06b
2026-04-03 17:15:20,694 - DEBUG - Response Code: 404
2026-04-03 17:15:20,695 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/fd5b9b60-b6ed-45cd-8bbc-fd7ac5cbb06b
2026-04-03 17:15:20,700 - DEBUG - Response Code: 404
2026-04-03 17:15:20,701 - INFO - VM ctest-VM21-63583778 is fully removed in API-Server
2026-04-03 17:15:20,851 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2026-04-03 17:15:20,898 - DEBUG - Validated that vrouter 10.0.0.19 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-77086446:ctest-VN2-62474021
2026-04-03 17:15:20,912 - DEBUG - Validated that vrouter 10.0.0.240 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-77086446:ctest-VN2-62474021
2026-04-03 17:15:20,912 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-77086446:ctest-VN2-62474021
2026-04-03 17:15:20,912 - INFO - VM ctest-VM21-63583778 is removed in Compute, and routes are removed in all compute nodes
2026-04-03 17:15:21,134 - INFO - Routes for VM ctest-VM21-63583778 is removed in all control-nodes
2026-04-03 17:15:21,134 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5589-1:Compute:contrail-vrouter-agent:0?flat
2026-04-03 17:15:21,166 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5589-1?flat
2026-04-03 17:15:21,193 - DEBUG - VM fd5b9b60-b6ed-45cd-8bbc-fd7ac5cbb06b is not present in vrouter an-jenkins-deploy-platform-ansible-os-5589-1 uve
2026-04-03 17:15:21,193 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-03 17:15:21,193 - DEBUG - interface for vm fd5b9b60-b6ed-45cd-8bbc-fd7ac5cbb06b deleted from vrouter uve ...
2026-04-03 17:15:21,193 - DEBUG - Deleted interface not in error interface list ...
2026-04-03 17:15:21,193 - DEBUG - Validated that VM fd5b9b60-b6ed-45cd-8bbc-fd7ac5cbb06b is removed in Vrouter UVE
2026-04-03 17:15:21,193 - DEBUG - Verifying through opserver in 10.0.0.22
2026-04-03 17:15:21,193 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-77086446:ctest-VN2-62474021?flat
2026-04-03 17:15:21,209 - DEBUG - fd5b9b60-b6ed-45cd-8bbc-fd7ac5cbb06b BM not in default-domain:ctest-TestPolicyAclIpv4v6-77086446:ctest-VN2-62474021 uve
2026-04-03 17:15:21,209 - DEBUG - Validated that VM fd5b9b60-b6ed-45cd-8bbc-fd7ac5cbb06b is not present in the VN UVE
2026-04-03 17:15:21,209 - DEBUG - Verifying the fd5b9b60-b6ed-45cd-8bbc-fd7ac5cbb06b virtual network link through opserver 10.0.0.22
2026-04-03 17:15:21,210 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machines
2026-04-03 17:15:21,221 - INFO - fd5b9b60-b6ed-45cd-8bbc-fd7ac5cbb06b vm uve deleted from opserver
2026-04-03 17:15:21,221 - DEBUG - Verifying the fd5b9b60-b6ed-45cd-8bbc-fd7ac5cbb06b virtual network link through opserver 10.0.0.38
2026-04-03 17:15:21,221 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines
2026-04-03 17:15:21,231 - INFO - fd5b9b60-b6ed-45cd-8bbc-fd7ac5cbb06b vm uve deleted from opserver
2026-04-03 17:15:21,231 - DEBUG - Verifying the fd5b9b60-b6ed-45cd-8bbc-fd7ac5cbb06b virtual network link through opserver 10.0.0.141
2026-04-03 17:15:21,231 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-machines
2026-04-03 17:15:21,243 - INFO - fd5b9b60-b6ed-45cd-8bbc-fd7ac5cbb06b vm uve deleted from opserver
2026-04-03 17:15:21,243 - INFO - Validated that VM fd5b9b60-b6ed-45cd-8bbc-fd7ac5cbb06b is removed from Opserver
2026-04-03 17:15:21,243 - INFO - Deleting VM ctest-VM11-39346586
2026-04-03 17:15:21,306 - DEBUG - Verifying in api server 10.0.0.141
2026-04-03 17:15:21,306 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/2d95a307-975d-4900-a094-28f8139f0321
2026-04-03 17:15:21,315 - DEBUG - VM ID 2d95a307-975d-4900-a094-28f8139f0321 of VM ctest-VM11-39346586 is still found in API Server
2026-04-03 17:15:23,315 - DEBUG - Verifying in api server 10.0.0.141
2026-04-03 17:15:23,315 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/2d95a307-975d-4900-a094-28f8139f0321
2026-04-03 17:15:23,322 - DEBUG - Response Code: 404
2026-04-03 17:15:23,322 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/2d95a307-975d-4900-a094-28f8139f0321
2026-04-03 17:15:23,328 - DEBUG - Response Code: 404
2026-04-03 17:15:23,328 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/2d95a307-975d-4900-a094-28f8139f0321
2026-04-03 17:15:23,333 - DEBUG - Response Code: 404
2026-04-03 17:15:23,333 - INFO - VM ctest-VM11-39346586 is fully removed in API-Server
2026-04-03 17:15:23,491 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2026-04-03 17:15:23,538 - DEBUG - Validated that vrouter 10.0.0.19 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-77086446:ctest-VN1-76953987
2026-04-03 17:15:23,550 - DEBUG - Validated that vrouter 10.0.0.240 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-77086446:ctest-VN1-76953987
2026-04-03 17:15:23,550 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-77086446:ctest-VN1-76953987
2026-04-03 17:15:23,550 - INFO - VM ctest-VM11-39346586 is removed in Compute, and routes are removed in all compute nodes
2026-04-03 17:15:23,748 - INFO - Routes for VM ctest-VM11-39346586 is removed in all control-nodes
2026-04-03 17:15:23,749 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5589-2:Compute:contrail-vrouter-agent:0?flat
2026-04-03 17:15:23,782 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5589-2?flat
2026-04-03 17:15:23,817 - DEBUG - VM 2d95a307-975d-4900-a094-28f8139f0321 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5589-2 uve
2026-04-03 17:15:23,817 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-03 17:15:23,817 - DEBUG - interface for vm 2d95a307-975d-4900-a094-28f8139f0321 deleted from vrouter uve ...
2026-04-03 17:15:23,817 - DEBUG - Deleted interface not in error interface list ...
2026-04-03 17:15:26,818 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5589-2:Compute:contrail-vrouter-agent:0?flat
2026-04-03 17:15:26,853 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5589-2?flat
2026-04-03 17:15:26,885 - DEBUG - VM 2d95a307-975d-4900-a094-28f8139f0321 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5589-2 uve
2026-04-03 17:15:26,885 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-03 17:15:26,885 - DEBUG - interface for vm 2d95a307-975d-4900-a094-28f8139f0321 deleted from vrouter uve ...
2026-04-03 17:15:26,885 - DEBUG - Deleted interface not in error interface list ...
2026-04-03 17:15:29,886 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5589-2:Compute:contrail-vrouter-agent:0?flat
2026-04-03 17:15:29,918 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5589-2?flat
2026-04-03 17:15:29,945 - DEBUG - VM 2d95a307-975d-4900-a094-28f8139f0321 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5589-2 uve
2026-04-03 17:15:29,945 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-03 17:15:29,945 - DEBUG - interface for vm 2d95a307-975d-4900-a094-28f8139f0321 deleted from vrouter uve ...
2026-04-03 17:15:29,945 - DEBUG - Deleted interface not in error interface list ...
2026-04-03 17:15:32,946 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5589-2:Compute:contrail-vrouter-agent:0?flat
2026-04-03 17:15:32,979 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5589-2?flat
2026-04-03 17:15:33,006 - DEBUG - VM 2d95a307-975d-4900-a094-28f8139f0321 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5589-2 uve
2026-04-03 17:15:33,006 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-03 17:15:33,006 - DEBUG - interface for vm 2d95a307-975d-4900-a094-28f8139f0321 deleted from vrouter uve ...
2026-04-03 17:15:33,006 - DEBUG - Deleted interface not in error interface list ...
2026-04-03 17:15:36,007 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5589-2:Compute:contrail-vrouter-agent:0?flat
2026-04-03 17:15:36,042 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5589-2?flat
2026-04-03 17:15:36,077 - DEBUG - VM 2d95a307-975d-4900-a094-28f8139f0321 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5589-2 uve
2026-04-03 17:15:36,078 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-03 17:15:36,078 - DEBUG - interface for vm 2d95a307-975d-4900-a094-28f8139f0321 deleted from vrouter uve ...
2026-04-03 17:15:36,078 - DEBUG - Deleted interface not in error interface list ...
2026-04-03 17:15:39,078 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5589-2:Compute:contrail-vrouter-agent:0?flat
2026-04-03 17:15:39,111 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5589-2?flat
2026-04-03 17:15:39,138 - DEBUG - VM 2d95a307-975d-4900-a094-28f8139f0321 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5589-2 uve
2026-04-03 17:15:39,138 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-03 17:15:39,138 - DEBUG - interface for vm 2d95a307-975d-4900-a094-28f8139f0321 deleted from vrouter uve ...
2026-04-03 17:15:39,139 - DEBUG - Deleted interface not in error interface list ...
2026-04-03 17:15:42,140 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5589-2:Compute:contrail-vrouter-agent:0?flat
2026-04-03 17:15:42,181 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5589-2?flat
2026-04-03 17:15:42,214 - DEBUG - VM 2d95a307-975d-4900-a094-28f8139f0321 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5589-2 uve
2026-04-03 17:15:42,214 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-03 17:15:42,214 - DEBUG - interface for vm 2d95a307-975d-4900-a094-28f8139f0321 deleted from vrouter uve ...
2026-04-03 17:15:42,214 - DEBUG - Deleted interface not in error interface list ...
2026-04-03 17:15:45,215 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5589-2:Compute:contrail-vrouter-agent:0?flat
2026-04-03 17:15:45,248 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5589-2?flat
2026-04-03 17:15:45,285 - DEBUG - VM 2d95a307-975d-4900-a094-28f8139f0321 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5589-2 uve
2026-04-03 17:15:45,285 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-03 17:15:45,285 - DEBUG - interface for vm 2d95a307-975d-4900-a094-28f8139f0321 deleted from vrouter uve ...
2026-04-03 17:15:45,285 - DEBUG - Deleted interface not in error interface list ...
2026-04-03 17:15:48,286 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5589-2:Compute:contrail-vrouter-agent:0?flat
2026-04-03 17:15:48,321 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5589-2?flat
2026-04-03 17:15:48,348 - DEBUG - VM 2d95a307-975d-4900-a094-28f8139f0321 is not present in vrouter an-jenkins-deploy-platform-ansible-os-5589-2 uve
2026-04-03 17:15:48,348 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-03 17:15:48,348 - DEBUG - interface for vm 2d95a307-975d-4900-a094-28f8139f0321 deleted from vrouter uve ...
2026-04-03 17:15:48,348 - DEBUG - Deleted interface not in error interface list ...
2026-04-03 17:15:48,348 - DEBUG - Validated that VM 2d95a307-975d-4900-a094-28f8139f0321 is removed in Vrouter UVE
2026-04-03 17:15:48,348 - DEBUG - Verifying through opserver in 10.0.0.22
2026-04-03 17:15:48,349 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-77086446:ctest-VN1-76953987?flat
2026-04-03 17:15:48,365 - DEBUG - 2d95a307-975d-4900-a094-28f8139f0321 BM not in default-domain:ctest-TestPolicyAclIpv4v6-77086446:ctest-VN1-76953987 uve
2026-04-03 17:15:48,365 - DEBUG - Validated that VM 2d95a307-975d-4900-a094-28f8139f0321 is not present in the VN UVE
2026-04-03 17:15:48,365 - DEBUG - Verifying the 2d95a307-975d-4900-a094-28f8139f0321 virtual network link through opserver 10.0.0.22
2026-04-03 17:15:48,365 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machines
2026-04-03 17:15:48,376 - DEBUG - No links retuned for 2d95a307-975d-4900-a094-28f8139f0321
2026-04-03 17:15:48,376 - INFO - 2d95a307-975d-4900-a094-28f8139f0321 vm uve deleted from opserver
2026-04-03 17:15:48,376 - DEBUG - Verifying the 2d95a307-975d-4900-a094-28f8139f0321 virtual network link through opserver 10.0.0.38
2026-04-03 17:15:48,376 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines
2026-04-03 17:15:48,393 - DEBUG - No links retuned for 2d95a307-975d-4900-a094-28f8139f0321
2026-04-03 17:15:48,393 - INFO - 2d95a307-975d-4900-a094-28f8139f0321 vm uve deleted from opserver
2026-04-03 17:15:48,393 - DEBUG - Verifying the 2d95a307-975d-4900-a094-28f8139f0321 virtual network link through opserver 10.0.0.141
2026-04-03 17:15:48,393 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-machines
2026-04-03 17:15:48,408 - DEBUG - No links retuned for 2d95a307-975d-4900-a094-28f8139f0321
2026-04-03 17:15:48,408 - INFO - 2d95a307-975d-4900-a094-28f8139f0321 vm uve deleted from opserver
2026-04-03 17:15:48,408 - INFO - Validated that VM 2d95a307-975d-4900-a094-28f8139f0321 is removed from Opserver
2026-04-03 17:15:48,409 - DEBUG - Detaching the Policy for VN :ctest-VN2-62474021
2026-04-03 17:15:48,410 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2026-04-03 17:15:48,421 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3366d1ce-0fb9-4373-a4f2-6bb9715d4849
2026-04-03 17:15:48,447 - DEBUG - Requesting: http://10.0.0.22:8082/project/9455923f-170a-4a14-8a91-6c66862c188a
2026-04-03 17:15:48,548 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/961755f9-0ad2-44ca-8421-d725b7651c61
2026-04-03 17:15:48,563 - DEBUG - Requesting: http://10.0.0.22:8082/network-policy/aa6010ce-893e-42c6-b1c4-20bd1b557f1c
2026-04-03 17:15:48,752 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-77086446', 'ctest-policy21-65854753']] from ctest-VN2-62474021
2026-04-03 17:15:48,752 - DEBUG - Detaching the Policy for VN :ctest-VN1-76953987
2026-04-03 17:15:48,752 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2026-04-03 17:15:48,766 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3366d1ce-0fb9-4373-a4f2-6bb9715d4849
2026-04-03 17:15:48,781 - DEBUG - Requesting: http://10.0.0.22:8082/project/9455923f-170a-4a14-8a91-6c66862c188a
2026-04-03 17:15:48,880 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/edc389d3-78b0-41a7-8afc-06ca486a0d3d
2026-04-03 17:15:48,896 - DEBUG - Requesting: http://10.0.0.22:8082/network-policy/5545f2e8-b990-417b-b6bb-70075f46aa06
2026-04-03 17:15:48,905 - DEBUG - Requesting: http://10.0.0.22:8082/network-policy/5c159d1a-8aef-4a67-902c-62fa1a296554
2026-04-03 17:15:49,067 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-77086446', 'ctest-policy12-50510355'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-77086446', 'ctest-policy13-99876847']] from ctest-VN1-76953987
2026-04-03 17:15:49,164 - INFO - Deleted policy ctest-policy21-65854753
2026-04-03 17:15:49,235 - INFO - Deleted policy ctest-policy12-50510355
2026-04-03 17:15:49,287 - INFO - Deleted policy ctest-policy13-99876847
2026-04-03 17:15:49,287 - INFO - Deleting VN ctest-VN3-70141270
2026-04-03 17:15:49,464 - DEBUG - Response for deleting network ()
2026-04-03 17:15:49,464 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/164f421e-6c99-49de-ba4c-f7b39b815ded
2026-04-03 17:15:49,470 - DEBUG - Response Code: 404
2026-04-03 17:15:49,471 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2026-04-03 17:15:49,477 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3366d1ce-0fb9-4373-a4f2-6bb9715d4849
2026-04-03 17:15:49,491 - DEBUG - Requesting: http://10.0.0.22:8082/project/9455923f-170a-4a14-8a91-6c66862c188a
2026-04-03 17:15:49,579 - INFO - Validated that VN ctest-VN3-70141270 is not found in API Server
2026-04-03 17:15:49,590 - DEBUG - VN ctest-VN3-70141270 is not present in Agent 10.0.0.19
2026-04-03 17:15:49,599 - DEBUG - VN ctest-VN3-70141270 is not present in Agent 10.0.0.240
2026-04-03 17:15:49,599 - INFO - Validated that VN ctest-VN3-70141270 is not in any agent
2026-04-03 17:15:49,628 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN3-70141270 info
2026-04-03 17:15:49,628 - INFO - Deleting VN ctest-VN2-62474021
2026-04-03 17:15:49,799 - DEBUG - Response for deleting network ()
2026-04-03 17:15:49,799 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/aa4b54d8-bbdd-4b72-8d06-90e7815b6c06
2026-04-03 17:15:49,813 - DEBUG - Response Code: 404
2026-04-03 17:15:49,813 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2026-04-03 17:15:49,822 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3366d1ce-0fb9-4373-a4f2-6bb9715d4849
2026-04-03 17:15:49,880 - DEBUG - Requesting: http://10.0.0.22:8082/project/9455923f-170a-4a14-8a91-6c66862c188a
2026-04-03 17:15:49,990 - INFO - Validated that VN ctest-VN2-62474021 is not found in API Server
2026-04-03 17:15:50,003 - DEBUG - VN ctest-VN2-62474021 is not present in Agent 10.0.0.19
2026-04-03 17:15:50,013 - DEBUG - VN ctest-VN2-62474021 is not present in Agent 10.0.0.240
2026-04-03 17:15:50,013 - INFO - Validated that VN ctest-VN2-62474021 is not in any agent
2026-04-03 17:15:50,042 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN2-62474021 info
2026-04-03 17:15:50,043 - INFO - Deleting VN ctest-VN1-76953987
2026-04-03 17:15:50,266 - DEBUG - Response for deleting network ()
2026-04-03 17:15:50,266 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/85076585-d1b1-4ddc-9ed7-8bb0855bd5c6
2026-04-03 17:15:50,276 - DEBUG - Response Code: 404
2026-04-03 17:15:50,276 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2026-04-03 17:15:50,285 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3366d1ce-0fb9-4373-a4f2-6bb9715d4849
2026-04-03 17:15:50,301 - DEBUG - Requesting: http://10.0.0.22:8082/project/9455923f-170a-4a14-8a91-6c66862c188a
2026-04-03 17:15:50,405 - INFO - Validated that VN ctest-VN1-76953987 is not found in API Server
2026-04-03 17:15:50,419 - DEBUG - VN ctest-VN1-76953987 is not present in Agent 10.0.0.19
2026-04-03 17:15:50,430 - DEBUG - VN ctest-VN1-76953987 is not present in Agent 10.0.0.240
2026-04-03 17:15:50,430 - INFO - Validated that VN ctest-VN1-76953987 is not in any agent
2026-04-03 17:15:50,460 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN1-76953987 info
2026-04-03 17:15:50,567 - INFO - IPAM: ipam3 is not found in API Server
2026-04-03 17:15:50,591 - INFO - IPAM:ipam3 is not found in control node
2026-04-03 17:15:50,739 - INFO - IPAM: ipam2 is not found in API Server
2026-04-03 17:15:50,765 - INFO - IPAM:ipam2 is not found in control node
2026-04-03 17:15:50,915 - INFO - IPAM: ipam1 is not found in API Server
2026-04-03 17:15:50,934 - INFO - IPAM:ipam1 is not found in control node
2026-04-03 17:15:52,198 - DEBUG - No XMPP flaps were noticed during the test
2026-04-03 17:15:52,198 - INFO - END TEST : test_policy_inheritance_src_pol_dst_any : PASSED[0:02:26]
2026-04-03 17:15:52,198 - INFO - --------------------------------------------------------------------------------
2026-04-03 17:15:53,464 - DEBUG - No XMPP flaps were noticed during the test
2026-04-03 17:15:53,464 - INFO - END TEST : test_policy_inheritance_src_pol_dst_any : PASSED[0:02:28]
2026-04-03 17:15:53,464 - INFO - --------------------------------------------------------------------------------
2026-04-03 17:15:54,309 - INFO - Deleted project: ctest-TestPolicyAclIpv4v6-77086446, ID : 9455923f-170a-4a14-8a91-6c66862c188a