is in ACTIVE state now
2025-08-11 16:34:14,040 - DEBUG - VM ctest-VM31-87465893 launched on Node an-jenkins-deploy-platform-ansible-os-3816-2
2025-08-11 16:34:14,111 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/5e28617c-04fa-4253-ad5b-42c5971f55b5
2025-08-11 16:34:14,121 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/5e28617c-04fa-4253-ad5b-42c5971f55b5
2025-08-11 16:34:14,129 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine-interface/fc79895d-fad3-45fd-b08e-4d25bbd29021
2025-08-11 16:34:14,139 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/5e28617c-04fa-4253-ad5b-42c5971f55b5
2025-08-11 16:34:14,147 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine-interface/fc79895d-fad3-45fd-b08e-4d25bbd29021
2025-08-11 16:34:14,156 - DEBUG - Requesting: http://10.0.0.19:8082/instance-ip/a1d9e637-4dde-4797-8147-3af458d72b3c
2025-08-11 16:34:14,165 - DEBUG - Requesting: http://10.0.0.19:8082/instance-ip/b8af1b6f-0ee4-4d51-9c8b-b4e1b96325fb
2025-08-11 16:34:14,174 - DEBUG - Verifying in api server 10.0.0.19
2025-08-11 16:34:14,174 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/5e28617c-04fa-4253-ad5b-42c5971f55b5
2025-08-11 16:34:14,180 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine-interface/fc79895d-fad3-45fd-b08e-4d25bbd29021
2025-08-11 16:34:14,189 - DEBUG - Requesting: http://10.0.0.19:8082/instance-ip/a1d9e637-4dde-4797-8147-3af458d72b3c
2025-08-11 16:34:14,197 - DEBUG - Requesting: http://10.0.0.19:8082/instance-ip/b8af1b6f-0ee4-4d51-9c8b-b4e1b96325fb
2025-08-11 16:34:14,206 - INFO - VM ctest-VM31-87465893 verfication in all API Servers passed
2025-08-11 16:34:14,225 - DEBUG - VM ctest-VM31-87465893 Tap interface: {'index': '4', 'name': 'tapfc79895d-fa', 'uuid': 'fc79895d-fad3-45fd-b08e-4d25bbd29021', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-27062661:ctest-VN3-39300788:ctest-VN3-39300788', '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': '31', 'l2_label': '35', 'vxlan_id': '14', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-27062661:ctest-VN3-39300788', 'vm_uuid': '5e28617c-04fa-4253-ad5b-42c5971f55b5', 'vm_name': 'ctest-VM31-87465893', 'ip_addr': '135.152.208.195', 'mac_addr': '02:fc:79:89:5d:fa', '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-27062661:fc79895d-fad3-45fd-b08e-4d25bbd29021', 'sg_uuid_list': ['1b8211c4-8863-461c-9a83-65b4a168ebd0'], 'static_route_list': None, 'vm_project_uuid': '35b7f555-174a-492a-b612-a83eaa9b00b7', 'admin_state': 'Enabled', 'flow_key_idx': '38', 'allowed_address_pair_list': None, 'ip6_addr': '28cd:f623:3ede:980c:7f5a:ccbc:0:3', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []}
2025-08-11 16:34:14,225 - DEBUG - Agent 10.0.0.249 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-27062661:ctest-VN3-39300788:ctest-VN3-39300788
2025-08-11 16:34:14,231 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-27062661:ctest-VN3-39300788:ctest-VN3-39300788', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-27062661:ctest-VN3-39300788', 'table_label': '-1', 'vxlan_id': '14', 'evpnindex': '3', 'brindex': '3', 'mplsindex': '0', 'RD': '10.20.0.200:3', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]}
2025-08-11 16:34:14,357 - DEBUG - VM ctest-VM31-87465893 : Tap interface tapfc79895d-fa is set to Active
2025-08-11 16:34:14,357 - DEBUG - Tap interface tapfc79895d-fa detail : {'index': '4', 'name': 'tapfc79895d-fa', 'uuid': 'fc79895d-fad3-45fd-b08e-4d25bbd29021', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-27062661:ctest-VN3-39300788:ctest-VN3-39300788', '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': '31', 'l2_label': '35', 'vxlan_id': '14', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-27062661:ctest-VN3-39300788', 'vm_uuid': '5e28617c-04fa-4253-ad5b-42c5971f55b5', 'vm_name': 'ctest-VM31-87465893', 'ip_addr': '135.152.208.195', 'mac_addr': '02:fc:79:89:5d:fa', '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-27062661:fc79895d-fad3-45fd-b08e-4d25bbd29021', 'sg_uuid_list': ['1b8211c4-8863-461c-9a83-65b4a168ebd0'], 'static_route_list': None, 'vm_project_uuid': '35b7f555-174a-492a-b612-a83eaa9b00b7', 'admin_state': 'Enabled', 'flow_key_idx': '38', 'allowed_address_pair_list': None, 'ip6_addr': '28cd:f623:3ede:980c:7f5a:ccbc:0:3', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []}
2025-08-11 16:34:14,510 - DEBUG - Starting Layer 2 verification in Agent
2025-08-11 16:34:14,520 - DEBUG - Layer 2 path is seen for VM MAC 02:fc:79:89:5d:fa in agent 10.0.0.249
2025-08-11 16:34:14,521 - DEBUG - Active layer 2 route in agent is present for VMI tapfc79895d-fa
2025-08-11 16:34:14,521 - DEBUG - L2 label(35) matches bw route table and interface table
2025-08-11 16:34:14,531 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-27062661:ctest-VN3-39300788 is found in Agent of node 10.0.0.249
2025-08-11 16:34:14,717 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-27062661:ctest-VN3-39300788 is consistent in agent 10.0.0.249
2025-08-11 16:34:14,717 - DEBUG - Route for VM IP ['28cd:f623:3ede:980c:7f5a:ccbc:0:3', '135.152.208.195'] is consistent in agent 10.0.0.249
2025-08-11 16:34:14,717 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-27062661:ctest-VN3-39300788 verification for VM ctest-VM31-87465893 in Agent 10.0.0.249 passed
2025-08-11 16:34:14,875 - DEBUG - Starting all layer 2 verification in agent 10.0.0.249
2025-08-11 16:34:14,885 - DEBUG - Route for VM MAC 02:fc:79:89:5d:fa is consistent in agent 10.0.0.249
2025-08-11 16:34:16,107 - 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.88 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=2.94 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 2.942/5.411/7.881/2.469 ms')
2025-08-11 16:34:16,107 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-87465893 passed
2025-08-11 16:34:16,107 - INFO - VM ctest-VM31-87465893 verifications in Compute nodes passed
2025-08-11 16:34:16,368 - DEBUG - Validated VM route 135.152.208.195 in vrouter of 10.0.0.249
2025-08-11 16:34:16,371 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0
2025-08-11 16:34:16,371 - INFO - Validated routes of VM ctest-VM31-87465893 in all vrouters
2025-08-11 16:34:16,975 - DEBUG - Starting all layer2 verification in 10.0.0.19 Control Node
2025-08-11 16:34:17,151 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.19, not supported
2025-08-11 16:34:17,317 - DEBUG - Layer2 route found for VM MAC 02:fc:79:89:5d:fa in Control-node 10.0.0.19
2025-08-11 16:34:17,317 - DEBUG - L2 Label for VM ctest-VM31-87465893 same between Control-node 10.0.0.19 and Agent, Expected: 35, Seen: 35
2025-08-11 16:34:17,317 - DEBUG - Starting all layer2 verification in 10.0.0.27 Control Node
2025-08-11 16:34:17,474 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.27, not supported
2025-08-11 16:34:17,637 - DEBUG - Layer2 route found for VM MAC 02:fc:79:89:5d:fa in Control-node 10.0.0.27
2025-08-11 16:34:17,637 - DEBUG - L2 Label for VM ctest-VM31-87465893 same between Control-node 10.0.0.27 and Agent, Expected: 35, Seen: 35
2025-08-11 16:34:17,637 - INFO - Verification in Control-nodes for VM ctest-VM31-87465893 passed
2025-08-11 16:34:17,638 - DEBUG - Verifying the vm in opserver
2025-08-11 16:34:17,638 - DEBUG - Verifying in collector 10.0.0.19 ...
2025-08-11 16:34:17,638 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machine/5e28617c-04fa-4253-ad5b-42c5971f55b5?flat
2025-08-11 16:34:17,654 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-27062661:fc79895d-fad3-45fd-b08e-4d25bbd29021?flat
2025-08-11 16:34:17,677 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-27062661:fc79895d-fad3-45fd-b08e-4d25bbd29021?flat
2025-08-11 16:34:17,704 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-27062661:fc79895d-fad3-45fd-b08e-4d25bbd29021?flat
2025-08-11 16:34:17,728 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machine/5e28617c-04fa-4253-ad5b-42c5971f55b5?flat
2025-08-11 16:34:17,744 - DEBUG - Verifying vm in vn uve
2025-08-11 16:34:17,744 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-27062661:fc79895d-fad3-45fd-b08e-4d25bbd29021?flat
2025-08-11 16:34:17,769 - DEBUG - VM uve shows interface as {'fixed_ip6_list': ['28cd:f623:3ede:980c:7f5a:ccbc:0:3'], 'ip6_address': '28cd:f623:3ede:980c:7f5a:ccbc:0:3', 'fixed_ip4_list': ['135.152.208.195'], 'ip6_active': True, 'vhostuser_mode': 0, 'tx_vlan': 65535, 'gateway': '135.152.208.193', 'port_mirror_enabled': False, 'vm_name': 'ctest-VM31-87465893', 'mac_address': '02:fc:79:89:5d:fa', '__T': 1754930015319427, 'vn_uuid': '730675a4-7d15-4cd9-a95a-924af549cb2e', 'ip4_active': True, 'label': 31, 'uuid': 'fc79895d-fad3-45fd-b08e-4d25bbd29021', 'l2_active': True, 'ip_address': '135.152.208.195', 'active': True, 'rx_vlan': 65535, 'is_health_check_active': True, 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-27062661:ctest-VN3-39300788', 'vm_uuid': '5e28617c-04fa-4253-ad5b-42c5971f55b5', 'admin_state': True}
2025-08-11 16:34:17,769 - DEBUG - VM uve shows ip address as ['135.152.208.195', '28cd:f623:3ede:980c:7f5a:ccbc:0:3']
2025-08-11 16:34:17,769 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-27062661:ctest-VN3-39300788
2025-08-11 16:34:17,769 - INFO - Verifying through opserver in 10.0.0.27
2025-08-11 16:34:17,770 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-27062661:ctest-VN3-39300788?flat
2025-08-11 16:34:17,803 - DEBUG - expected vm list 5e28617c-04fa-4253-ad5b-42c5971f55b5
2025-08-11 16:34:17,803 - DEBUG - Extracted vm list ['5e28617c-04fa-4253-ad5b-42c5971f55b5']
2025-08-11 16:34:17,803 - INFO - VM 5e28617c-04fa-4253-ad5b-42c5971f55b5 is present in default-domain:ctest-TestPolicyAclIpv4v6-27062661:ctest-VN3-39300788
2025-08-11 16:34:17,803 - DEBUG - Verifying vm in vrouter uve
2025-08-11 16:34:17,803 - DEBUG - Getting info from collector 10.0.0.19..
2025-08-11 16:34:17,803 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machine/5e28617c-04fa-4253-ad5b-42c5971f55b5?flat
2025-08-11 16:34:17,818 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-3816-2']
2025-08-11 16:34:17,819 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3816-2:Compute:contrail-vrouter-agent:0?flat
2025-08-11 16:34:17,871 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3816-2?flat
2025-08-11 16:34:17,908 - DEBUG - VM 5e28617c-04fa-4253-ad5b-42c5971f55b5 is present in vrouter an-jenkins-deploy-platform-ansible-os-3816-2 uve
2025-08-11 16:34:17,908 - DEBUG - Validated that VM ctest-VM31-87465893 is in Vrouter an-jenkins-deploy-platform-ansible-os-3816-2 UVE
2025-08-11 16:34:17,908 - DEBUG - Verifying vm tap interface/vn in vrouter uve
2025-08-11 16:34:17,933 - DEBUG - Expected tap interface of VM uuid 5e28617c-04fa-4253-ad5b-42c5971f55b5 is default-domain:ctest-TestPolicyAclIpv4v6-27062661:fc79895d-fad3-45fd-b08e-4d25bbd29021
2025-08-11 16:34:17,933 - DEBUG - Expected VN of VM uuid 5e28617c-04fa-4253-ad5b-42c5971f55b5 is default-domain:ctest-TestPolicyAclIpv4v6-27062661:ctest-VN3-39300788
2025-08-11 16:34:17,933 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3816-2:Compute:contrail-vrouter-agent:0?flat
2025-08-11 16:34:17,985 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3816-2?flat
2025-08-11 16:34:18,026 - DEBUG - VM 5e28617c-04fa-4253-ad5b-42c5971f55b5 is present in vrouter an-jenkins-deploy-platform-ansible-os-3816-2 uve
2025-08-11 16:34:18,026 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-27062661:fc79895d-fad3-45fd-b08e-4d25bbd29021 of vm 5e28617c-04fa-4253-ad5b-42c5971f55b5 is present in vrouter an-jenkins-deploy-platform-ansible-os-3816-2 uve
2025-08-11 16:34:18,026 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-27062661:ctest-VN3-39300788 of vm 5e28617c-04fa-4253-ad5b-42c5971f55b5 is present in vrouter an-jenkins-deploy-platform-ansible-os-3816-2 uve
2025-08-11 16:34:18,026 - DEBUG - Verifying the 5e28617c-04fa-4253-ad5b-42c5971f55b5 virtual network link through opserver 10.0.0.27
2025-08-11 16:34:18,026 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines
2025-08-11 16:34:18,038 - DEBUG - VM link and name as {'name': '5e28617c-04fa-4253-ad5b-42c5971f55b5', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-machine/5e28617c-04fa-4253-ad5b-42c5971f55b5?flat'}
2025-08-11 16:34:18,038 - DEBUG - Verifying the 5e28617c-04fa-4253-ad5b-42c5971f55b5 virtual network link through opserver 10.0.0.23
2025-08-11 16:34:18,038 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machines
2025-08-11 16:34:18,050 - DEBUG - VM link and name as {'name': '5e28617c-04fa-4253-ad5b-42c5971f55b5', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-machine/5e28617c-04fa-4253-ad5b-42c5971f55b5?flat'}
2025-08-11 16:34:18,051 - DEBUG - Verifying the 5e28617c-04fa-4253-ad5b-42c5971f55b5 virtual network link through opserver 10.0.0.19
2025-08-11 16:34:18,051 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machines
2025-08-11 16:34:18,063 - DEBUG - VM link and name as {'name': '5e28617c-04fa-4253-ad5b-42c5971f55b5', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-machine/5e28617c-04fa-4253-ad5b-42c5971f55b5?flat'}
2025-08-11 16:34:18,063 - INFO - VM ctest-VM31-87465893 validations in Opserver passed
2025-08-11 16:34:18,063 - INFO - Waiting for VM ctest-VM11-37784736 to be up..
2025-08-11 16:34:18,063 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 16:34:18,063 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/c833e88c-db29-4383-80fc-0a9bce010782
2025-08-11 16:34:18,072 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine-interface/0d6d1331-7714-430d-abda-fe8b9b6363a2
2025-08-11 16:34:19,291 - 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.32 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.22 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.216/2.267/3.319/1.051 ms')
2025-08-11 16:34:19,291 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-37784736 passed
2025-08-11 16:34:19,402 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 16:34:19,403 - DEBUG - Waiting to SSH to VM ctest-VM11-37784736, IP 163.246.49.3, Port 22
2025-08-11 16:34:19,461 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-08-11 16:34:19,531 - DEBUG - VM ctest-VM11-37784736 is NOT ready for SSH connections, VM status: ACTIVE
2025-08-11 16:34:24,532 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 16:34:24,533 - DEBUG - Waiting to SSH to VM ctest-VM11-37784736, IP 163.246.49.3, Port 22
2025-08-11 16:34:24,600 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-08-11 16:34:24,674 - DEBUG - VM ctest-VM11-37784736 is NOT ready for SSH connections, VM status: ACTIVE
2025-08-11 16:34:29,674 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 16:34:29,675 - DEBUG - Waiting to SSH to VM ctest-VM11-37784736, IP 163.246.49.3, Port 22
2025-08-11 16:34:29,852 - DEBUG - VM ctest-VM11-37784736 is ready for SSH connections
2025-08-11 16:34:29,852 - INFO - Waiting for VM ctest-VM21-57230893 to be up..
2025-08-11 16:34:29,852 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 16:34:29,852 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/660932ec-94ab-4e8b-a61f-3a8bea88b726
2025-08-11 16:34:29,862 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine-interface/2bca1269-948f-4335-b65f-4f3d566a6116
2025-08-11 16:34:31,064 - 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=1.42 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.91 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.420/1.666/1.913/0.246 ms')
2025-08-11 16:34:31,064 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-57230893 passed
2025-08-11 16:34:31,174 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 16:34:31,175 - DEBUG - Waiting to SSH to VM ctest-VM21-57230893, IP 78.126.160.3, Port 22
2025-08-11 16:34:31,330 - DEBUG - VM ctest-VM21-57230893 is ready for SSH connections
2025-08-11 16:34:31,330 - INFO - Waiting for VM ctest-VM31-87465893 to be up..
2025-08-11 16:34:31,330 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 16:34:31,330 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/5e28617c-04fa-4253-ad5b-42c5971f55b5
2025-08-11 16:34:31,339 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine-interface/fc79895d-fad3-45fd-b08e-4d25bbd29021
2025-08-11 16:34:32,544 - 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.94 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=1.38 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 1.379/2.160/2.942/0.781 ms')
2025-08-11 16:34:32,545 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-87465893 passed
2025-08-11 16:34:32,660 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 16:34:32,660 - DEBUG - Waiting to SSH to VM ctest-VM31-87465893, IP 135.152.208.195, Port 22
2025-08-11 16:34:32,828 - DEBUG - VM ctest-VM31-87465893 is ready for SSH connections
2025-08-11 16:34:32,829 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 78.126.160.3, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.249, gateway password: c0ntrail123
2025-08-11 16:34:32,829 - DEBUG - ping -s 56 -c 3 -W 1 78.126.160.3
2025-08-11 16:34:51,369 - DEBUG - PING 78.126.160.3 (78.126.160.3) 56(84) bytes of data.
64 bytes from 78.126.160.3: icmp_req=1 ttl=63 time=8.03 ms
64 bytes from 78.126.160.3: icmp_req=2 ttl=63 time=1.21 ms
64 bytes from 78.126.160.3: icmp_req=3 ttl=63 time=1.07 ms
--- 78.126.160.3 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 1.076/3.443/8.039/3.250 ms
2025-08-11 16:34:51,370 - INFO - Ping to IP 78.126.160.3 from VM ctest-VM11-37784736 passed
2025-08-11 16:34:51,370 - DEBUG - Running remote_cmd, Cmd : ping6 -s 56 -c 3 -W 1 2736:456d:a2ac:4aa7:e1f0:ea96:0:3, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.249, gateway password: c0ntrail123
2025-08-11 16:34:51,370 - DEBUG - ping6 -s 56 -c 3 -W 1 2736:456d:a2ac:4aa7:e1f0:ea96:0:3
2025-08-11 16:34:53,851 - DEBUG - PING 2736:456d:a2ac:4aa7:e1f0:ea96:0:3(2736:456d:a2ac:4aa7:e1f0:ea96:0:3) 56 data bytes
64 bytes from 2736:456d:a2ac:4aa7:e1f0:ea96:0:3: icmp_seq=1 ttl=64 time=6.52 ms
64 bytes from 2736:456d:a2ac:4aa7:e1f0:ea96:0:3: icmp_seq=2 ttl=64 time=1.25 ms
64 bytes from 2736:456d:a2ac:4aa7:e1f0:ea96:0:3: icmp_seq=3 ttl=64 time=0.870 ms
--- 2736:456d:a2ac:4aa7:e1f0:ea96:0:3 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 0.870/2.881/6.520/2.577 ms
2025-08-11 16:34:53,852 - INFO - Ping to IP 2736:456d:a2ac:4aa7:e1f0:ea96:0:3 from VM ctest-VM11-37784736 passed
2025-08-11 16:34:53,852 - INFO - Test with src as policy and dst as any PASSED
2025-08-11 16:34:53,852 - INFO - Deleting VM ctest-VM31-87465893
2025-08-11 16:34:53,916 - DEBUG - Verifying in api server 10.0.0.19
2025-08-11 16:34:53,916 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/5e28617c-04fa-4253-ad5b-42c5971f55b5
2025-08-11 16:34:53,925 - DEBUG - VM ID 5e28617c-04fa-4253-ad5b-42c5971f55b5 of VM ctest-VM31-87465893 is still found in API Server
2025-08-11 16:34:55,926 - DEBUG - Verifying in api server 10.0.0.19
2025-08-11 16:34:55,926 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/5e28617c-04fa-4253-ad5b-42c5971f55b5
2025-08-11 16:34:55,933 - DEBUG - Response Code: 404
2025-08-11 16:34:55,933 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/5e28617c-04fa-4253-ad5b-42c5971f55b5
2025-08-11 16:34:55,938 - DEBUG - Response Code: 404
2025-08-11 16:34:55,938 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/5e28617c-04fa-4253-ad5b-42c5971f55b5
2025-08-11 16:34:55,945 - DEBUG - Response Code: 404
2025-08-11 16:34:55,945 - INFO - VM ctest-VM31-87465893 is fully removed in API-Server
2025-08-11 16:34:56,102 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2025-08-11 16:34:56,151 - DEBUG - Validated that vrouter 10.0.0.141 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-27062661:ctest-VN3-39300788
2025-08-11 16:34:56,164 - DEBUG - Validated that vrouter 10.0.0.249 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-27062661:ctest-VN3-39300788
2025-08-11 16:34:56,164 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-27062661:ctest-VN3-39300788
2025-08-11 16:34:56,164 - INFO - VM ctest-VM31-87465893 is removed in Compute, and routes are removed in all compute nodes
2025-08-11 16:34:56,440 - INFO - Routes for VM ctest-VM31-87465893 is removed in all control-nodes
2025-08-11 16:34:56,440 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3816-2:Compute:contrail-vrouter-agent:0?flat
2025-08-11 16:34:56,480 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3816-2?flat
2025-08-11 16:34:56,514 - DEBUG - VM 5e28617c-04fa-4253-ad5b-42c5971f55b5 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3816-2 uve
2025-08-11 16:34:56,514 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-11 16:34:56,514 - DEBUG - interface for vm 5e28617c-04fa-4253-ad5b-42c5971f55b5 deleted from vrouter uve ...
2025-08-11 16:34:56,514 - DEBUG - Deleted interface not in error interface list ...
2025-08-11 16:34:59,515 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3816-2:Compute:contrail-vrouter-agent:0?flat
2025-08-11 16:34:59,556 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3816-2?flat
2025-08-11 16:34:59,591 - DEBUG - VM 5e28617c-04fa-4253-ad5b-42c5971f55b5 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3816-2 uve
2025-08-11 16:34:59,591 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-11 16:34:59,592 - DEBUG - interface for vm 5e28617c-04fa-4253-ad5b-42c5971f55b5 deleted from vrouter uve ...
2025-08-11 16:34:59,592 - DEBUG - Deleted interface not in error interface list ...
2025-08-11 16:35:02,592 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3816-2:Compute:contrail-vrouter-agent:0?flat
2025-08-11 16:35:02,633 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3816-2?flat
2025-08-11 16:35:02,669 - DEBUG - VM 5e28617c-04fa-4253-ad5b-42c5971f55b5 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3816-2 uve
2025-08-11 16:35:02,669 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-11 16:35:02,670 - DEBUG - interface for vm 5e28617c-04fa-4253-ad5b-42c5971f55b5 deleted from vrouter uve ...
2025-08-11 16:35:02,670 - DEBUG - Deleted interface not in error interface list ...
2025-08-11 16:35:05,670 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3816-2:Compute:contrail-vrouter-agent:0?flat
2025-08-11 16:35:05,712 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3816-2?flat
2025-08-11 16:35:05,749 - DEBUG - VM 5e28617c-04fa-4253-ad5b-42c5971f55b5 is not present in vrouter an-jenkins-deploy-platform-ansible-os-3816-2 uve
2025-08-11 16:35:05,750 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-11 16:35:05,750 - DEBUG - interface for vm 5e28617c-04fa-4253-ad5b-42c5971f55b5 deleted from vrouter uve ...
2025-08-11 16:35:05,750 - DEBUG - Deleted interface not in error interface list ...
2025-08-11 16:35:05,750 - DEBUG - Validated that VM 5e28617c-04fa-4253-ad5b-42c5971f55b5 is removed in Vrouter UVE
2025-08-11 16:35:05,750 - DEBUG - Verifying through opserver in 10.0.0.27
2025-08-11 16:35:05,750 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-27062661:ctest-VN3-39300788?flat
2025-08-11 16:35:05,770 - DEBUG - 5e28617c-04fa-4253-ad5b-42c5971f55b5 BM not in default-domain:ctest-TestPolicyAclIpv4v6-27062661:ctest-VN3-39300788 uve
2025-08-11 16:35:05,770 - DEBUG - Validated that VM 5e28617c-04fa-4253-ad5b-42c5971f55b5 is not present in the VN UVE
2025-08-11 16:35:05,770 - DEBUG - Verifying the 5e28617c-04fa-4253-ad5b-42c5971f55b5 virtual network link through opserver 10.0.0.27
2025-08-11 16:35:05,770 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines
2025-08-11 16:35:05,781 - INFO - 5e28617c-04fa-4253-ad5b-42c5971f55b5 vm uve deleted from opserver
2025-08-11 16:35:05,781 - DEBUG - Verifying the 5e28617c-04fa-4253-ad5b-42c5971f55b5 virtual network link through opserver 10.0.0.23
2025-08-11 16:35:05,781 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machines
2025-08-11 16:35:05,793 - INFO - 5e28617c-04fa-4253-ad5b-42c5971f55b5 vm uve deleted from opserver
2025-08-11 16:35:05,793 - DEBUG - Verifying the 5e28617c-04fa-4253-ad5b-42c5971f55b5 virtual network link through opserver 10.0.0.19
2025-08-11 16:35:05,793 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machines
2025-08-11 16:35:05,804 - INFO - 5e28617c-04fa-4253-ad5b-42c5971f55b5 vm uve deleted from opserver
2025-08-11 16:35:05,805 - INFO - Validated that VM 5e28617c-04fa-4253-ad5b-42c5971f55b5 is removed from Opserver
2025-08-11 16:35:05,805 - INFO - Deleting VM ctest-VM21-57230893
2025-08-11 16:35:05,870 - DEBUG - Verifying in api server 10.0.0.19
2025-08-11 16:35:05,870 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/660932ec-94ab-4e8b-a61f-3a8bea88b726
2025-08-11 16:35:05,879 - DEBUG - VM ID 660932ec-94ab-4e8b-a61f-3a8bea88b726 of VM ctest-VM21-57230893 is still found in API Server
2025-08-11 16:35:07,880 - DEBUG - Verifying in api server 10.0.0.19
2025-08-11 16:35:07,880 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/660932ec-94ab-4e8b-a61f-3a8bea88b726
2025-08-11 16:35:07,887 - DEBUG - Response Code: 404
2025-08-11 16:35:07,887 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/660932ec-94ab-4e8b-a61f-3a8bea88b726
2025-08-11 16:35:07,893 - DEBUG - Response Code: 404
2025-08-11 16:35:07,893 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/660932ec-94ab-4e8b-a61f-3a8bea88b726
2025-08-11 16:35:07,899 - DEBUG - Response Code: 404
2025-08-11 16:35:07,899 - INFO - VM ctest-VM21-57230893 is fully removed in API-Server
2025-08-11 16:35:08,053 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2025-08-11 16:35:08,095 - DEBUG - Validated that vrouter 10.0.0.141 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-27062661:ctest-VN2-34690797
2025-08-11 16:35:08,105 - DEBUG - Validated that vrouter 10.0.0.249 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-27062661:ctest-VN2-34690797
2025-08-11 16:35:08,105 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-27062661:ctest-VN2-34690797
2025-08-11 16:35:08,105 - INFO - VM ctest-VM21-57230893 is removed in Compute, and routes are removed in all compute nodes
2025-08-11 16:35:08,364 - INFO - Routes for VM ctest-VM21-57230893 is removed in all control-nodes
2025-08-11 16:35:08,364 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3816-1:Compute:contrail-vrouter-agent:0?flat
2025-08-11 16:35:08,403 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3816-1?flat
2025-08-11 16:35:08,439 - DEBUG - VM 660932ec-94ab-4e8b-a61f-3a8bea88b726 is not present in vrouter an-jenkins-deploy-platform-ansible-os-3816-1 uve
2025-08-11 16:35:08,439 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-11 16:35:08,440 - DEBUG - interface for vm 660932ec-94ab-4e8b-a61f-3a8bea88b726 deleted from vrouter uve ...
2025-08-11 16:35:08,440 - DEBUG - Deleted interface not in error interface list ...
2025-08-11 16:35:08,440 - DEBUG - Validated that VM 660932ec-94ab-4e8b-a61f-3a8bea88b726 is removed in Vrouter UVE
2025-08-11 16:35:08,440 - DEBUG - Verifying through opserver in 10.0.0.27
2025-08-11 16:35:08,440 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-27062661:ctest-VN2-34690797?flat
2025-08-11 16:35:08,458 - DEBUG - 660932ec-94ab-4e8b-a61f-3a8bea88b726 BM not in default-domain:ctest-TestPolicyAclIpv4v6-27062661:ctest-VN2-34690797 uve
2025-08-11 16:35:08,459 - DEBUG - Validated that VM 660932ec-94ab-4e8b-a61f-3a8bea88b726 is not present in the VN UVE
2025-08-11 16:35:08,459 - DEBUG - Verifying the 660932ec-94ab-4e8b-a61f-3a8bea88b726 virtual network link through opserver 10.0.0.27
2025-08-11 16:35:08,459 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines
2025-08-11 16:35:08,469 - INFO - 660932ec-94ab-4e8b-a61f-3a8bea88b726 vm uve deleted from opserver
2025-08-11 16:35:08,469 - DEBUG - Verifying the 660932ec-94ab-4e8b-a61f-3a8bea88b726 virtual network link through opserver 10.0.0.23
2025-08-11 16:35:08,469 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machines
2025-08-11 16:35:08,483 - INFO - 660932ec-94ab-4e8b-a61f-3a8bea88b726 vm uve deleted from opserver
2025-08-11 16:35:08,483 - DEBUG - Verifying the 660932ec-94ab-4e8b-a61f-3a8bea88b726 virtual network link through opserver 10.0.0.19
2025-08-11 16:35:08,483 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machines
2025-08-11 16:35:08,494 - INFO - 660932ec-94ab-4e8b-a61f-3a8bea88b726 vm uve deleted from opserver
2025-08-11 16:35:08,494 - INFO - Validated that VM 660932ec-94ab-4e8b-a61f-3a8bea88b726 is removed from Opserver
2025-08-11 16:35:08,495 - INFO - Deleting VM ctest-VM11-37784736
2025-08-11 16:35:08,551 - DEBUG - Verifying in api server 10.0.0.19
2025-08-11 16:35:08,551 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/c833e88c-db29-4383-80fc-0a9bce010782
2025-08-11 16:35:08,559 - DEBUG - VM ID c833e88c-db29-4383-80fc-0a9bce010782 of VM ctest-VM11-37784736 is still found in API Server
2025-08-11 16:35:10,559 - DEBUG - Verifying in api server 10.0.0.19
2025-08-11 16:35:10,560 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/c833e88c-db29-4383-80fc-0a9bce010782
2025-08-11 16:35:10,566 - DEBUG - Response Code: 404
2025-08-11 16:35:10,566 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/c833e88c-db29-4383-80fc-0a9bce010782
2025-08-11 16:35:10,572 - DEBUG - Response Code: 404
2025-08-11 16:35:10,572 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/c833e88c-db29-4383-80fc-0a9bce010782
2025-08-11 16:35:10,578 - DEBUG - Response Code: 404
2025-08-11 16:35:10,579 - INFO - VM ctest-VM11-37784736 is fully removed in API-Server
2025-08-11 16:35:10,730 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2025-08-11 16:35:10,772 - DEBUG - Validated that vrouter 10.0.0.141 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-27062661:ctest-VN1-03648172
2025-08-11 16:35:10,784 - DEBUG - Validated that vrouter 10.0.0.249 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-27062661:ctest-VN1-03648172
2025-08-11 16:35:10,784 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-27062661:ctest-VN1-03648172
2025-08-11 16:35:10,784 - INFO - VM ctest-VM11-37784736 is removed in Compute, and routes are removed in all compute nodes
2025-08-11 16:35:11,014 - INFO - Routes for VM ctest-VM11-37784736 is removed in all control-nodes
2025-08-11 16:35:11,014 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3816-2:Compute:contrail-vrouter-agent:0?flat
2025-08-11 16:35:11,057 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3816-2?flat
2025-08-11 16:35:11,092 - DEBUG - VM c833e88c-db29-4383-80fc-0a9bce010782 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3816-2 uve
2025-08-11 16:35:11,092 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-11 16:35:11,092 - DEBUG - interface for vm c833e88c-db29-4383-80fc-0a9bce010782 deleted from vrouter uve ...
2025-08-11 16:35:11,092 - DEBUG - Deleted interface not in error interface list ...
2025-08-11 16:35:14,092 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3816-2:Compute:contrail-vrouter-agent:0?flat
2025-08-11 16:35:14,134 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3816-2?flat
2025-08-11 16:35:14,169 - DEBUG - VM c833e88c-db29-4383-80fc-0a9bce010782 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3816-2 uve
2025-08-11 16:35:14,169 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-11 16:35:14,169 - DEBUG - interface for vm c833e88c-db29-4383-80fc-0a9bce010782 deleted from vrouter uve ...
2025-08-11 16:35:14,169 - DEBUG - Deleted interface not in error interface list ...
2025-08-11 16:35:17,170 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3816-2:Compute:contrail-vrouter-agent:0?flat
2025-08-11 16:35:17,219 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3816-2?flat
2025-08-11 16:35:17,258 - DEBUG - VM c833e88c-db29-4383-80fc-0a9bce010782 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3816-2 uve
2025-08-11 16:35:17,258 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-11 16:35:17,258 - DEBUG - interface for vm c833e88c-db29-4383-80fc-0a9bce010782 deleted from vrouter uve ...
2025-08-11 16:35:17,258 - DEBUG - Deleted interface not in error interface list ...
2025-08-11 16:35:20,259 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3816-2:Compute:contrail-vrouter-agent:0?flat
2025-08-11 16:35:20,300 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3816-2?flat
2025-08-11 16:35:20,334 - DEBUG - VM c833e88c-db29-4383-80fc-0a9bce010782 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3816-2 uve
2025-08-11 16:35:20,334 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-11 16:35:20,334 - DEBUG - interface for vm c833e88c-db29-4383-80fc-0a9bce010782 deleted from vrouter uve ...
2025-08-11 16:35:20,335 - DEBUG - Deleted interface not in error interface list ...
2025-08-11 16:35:23,335 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3816-2:Compute:contrail-vrouter-agent:0?flat
2025-08-11 16:35:23,377 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3816-2?flat
2025-08-11 16:35:23,410 - DEBUG - VM c833e88c-db29-4383-80fc-0a9bce010782 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3816-2 uve
2025-08-11 16:35:23,410 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-11 16:35:23,410 - DEBUG - interface for vm c833e88c-db29-4383-80fc-0a9bce010782 deleted from vrouter uve ...
2025-08-11 16:35:23,410 - DEBUG - Deleted interface not in error interface list ...
2025-08-11 16:35:26,411 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3816-2:Compute:contrail-vrouter-agent:0?flat
2025-08-11 16:35:26,452 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3816-2?flat
2025-08-11 16:35:26,493 - DEBUG - VM c833e88c-db29-4383-80fc-0a9bce010782 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3816-2 uve
2025-08-11 16:35:26,493 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-11 16:35:26,493 - DEBUG - interface for vm c833e88c-db29-4383-80fc-0a9bce010782 deleted from vrouter uve ...
2025-08-11 16:35:26,493 - DEBUG - Deleted interface not in error interface list ...
2025-08-11 16:35:29,494 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3816-2:Compute:contrail-vrouter-agent:0?flat
2025-08-11 16:35:29,534 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3816-2?flat
2025-08-11 16:35:29,568 - DEBUG - VM c833e88c-db29-4383-80fc-0a9bce010782 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3816-2 uve
2025-08-11 16:35:29,568 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-11 16:35:29,568 - DEBUG - interface for vm c833e88c-db29-4383-80fc-0a9bce010782 deleted from vrouter uve ...
2025-08-11 16:35:29,568 - DEBUG - Deleted interface not in error interface list ...
2025-08-11 16:35:32,568 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3816-2:Compute:contrail-vrouter-agent:0?flat
2025-08-11 16:35:32,611 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3816-2?flat
2025-08-11 16:35:32,646 - DEBUG - VM c833e88c-db29-4383-80fc-0a9bce010782 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3816-2 uve
2025-08-11 16:35:32,646 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-11 16:35:32,646 - DEBUG - interface for vm c833e88c-db29-4383-80fc-0a9bce010782 deleted from vrouter uve ...
2025-08-11 16:35:32,646 - DEBUG - Deleted interface not in error interface list ...
2025-08-11 16:35:35,646 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3816-2:Compute:contrail-vrouter-agent:0?flat
2025-08-11 16:35:35,688 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3816-2?flat
2025-08-11 16:35:35,722 - DEBUG - VM c833e88c-db29-4383-80fc-0a9bce010782 is not present in vrouter an-jenkins-deploy-platform-ansible-os-3816-2 uve
2025-08-11 16:35:35,722 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-11 16:35:35,722 - DEBUG - interface for vm c833e88c-db29-4383-80fc-0a9bce010782 deleted from vrouter uve ...
2025-08-11 16:35:35,722 - DEBUG - Deleted interface not in error interface list ...
2025-08-11 16:35:35,722 - DEBUG - Validated that VM c833e88c-db29-4383-80fc-0a9bce010782 is removed in Vrouter UVE
2025-08-11 16:35:35,722 - DEBUG - Verifying through opserver in 10.0.0.27
2025-08-11 16:35:35,722 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-27062661:ctest-VN1-03648172?flat
2025-08-11 16:35:35,741 - DEBUG - c833e88c-db29-4383-80fc-0a9bce010782 BM not in default-domain:ctest-TestPolicyAclIpv4v6-27062661:ctest-VN1-03648172 uve
2025-08-11 16:35:35,741 - DEBUG - Validated that VM c833e88c-db29-4383-80fc-0a9bce010782 is not present in the VN UVE
2025-08-11 16:35:35,741 - DEBUG - Verifying the c833e88c-db29-4383-80fc-0a9bce010782 virtual network link through opserver 10.0.0.27
2025-08-11 16:35:35,741 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines
2025-08-11 16:35:35,754 - DEBUG - No links retuned for c833e88c-db29-4383-80fc-0a9bce010782
2025-08-11 16:35:35,754 - INFO - c833e88c-db29-4383-80fc-0a9bce010782 vm uve deleted from opserver
2025-08-11 16:35:35,754 - DEBUG - Verifying the c833e88c-db29-4383-80fc-0a9bce010782 virtual network link through opserver 10.0.0.23
2025-08-11 16:35:35,754 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machines
2025-08-11 16:35:35,765 - DEBUG - No links retuned for c833e88c-db29-4383-80fc-0a9bce010782
2025-08-11 16:35:35,765 - INFO - c833e88c-db29-4383-80fc-0a9bce010782 vm uve deleted from opserver
2025-08-11 16:35:35,765 - DEBUG - Verifying the c833e88c-db29-4383-80fc-0a9bce010782 virtual network link through opserver 10.0.0.19
2025-08-11 16:35:35,765 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machines
2025-08-11 16:35:35,777 - DEBUG - No links retuned for c833e88c-db29-4383-80fc-0a9bce010782
2025-08-11 16:35:35,777 - INFO - c833e88c-db29-4383-80fc-0a9bce010782 vm uve deleted from opserver
2025-08-11 16:35:35,777 - INFO - Validated that VM c833e88c-db29-4383-80fc-0a9bce010782 is removed from Opserver
2025-08-11 16:35:35,777 - DEBUG - Detaching the Policy for VN :ctest-VN2-34690797
2025-08-11 16:35:35,777 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-11 16:35:35,782 - DEBUG - Requesting: http://10.0.0.27:8082/domain/4bc87c47-8b22-47dc-8747-cffafaf5f39a
2025-08-11 16:35:35,793 - DEBUG - Requesting: http://10.0.0.27:8082/project/35b7f555-174a-492a-b612-a83eaa9b00b7
2025-08-11 16:35:35,880 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/a04230a5-b383-4fca-8321-19d0aef305f0
2025-08-11 16:35:35,893 - DEBUG - Requesting: http://10.0.0.27:8082/network-policy/a30f9824-77e7-4123-9117-68183e9b9307
2025-08-11 16:35:36,092 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-27062661', 'ctest-policy21-68811902']] from ctest-VN2-34690797
2025-08-11 16:35:36,092 - DEBUG - Detaching the Policy for VN :ctest-VN1-03648172
2025-08-11 16:35:36,093 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-11 16:35:36,098 - DEBUG - Requesting: http://10.0.0.27:8082/domain/4bc87c47-8b22-47dc-8747-cffafaf5f39a
2025-08-11 16:35:36,109 - DEBUG - Requesting: http://10.0.0.27:8082/project/35b7f555-174a-492a-b612-a83eaa9b00b7
2025-08-11 16:35:36,197 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/0ffdacad-757a-4900-a830-42f54965ca1d
2025-08-11 16:35:36,211 - DEBUG - Requesting: http://10.0.0.27:8082/network-policy/06b8d360-e5bd-4094-86db-624bb8e829fe
2025-08-11 16:35:36,220 - DEBUG - Requesting: http://10.0.0.27:8082/network-policy/33dee8ae-a265-47e2-a636-4f2e8e89e8be
2025-08-11 16:35:36,420 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-27062661', 'ctest-policy12-81330609'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-27062661', 'ctest-policy13-44412942']] from ctest-VN1-03648172
2025-08-11 16:35:36,462 - INFO - Deleted policy ctest-policy21-68811902
2025-08-11 16:35:36,518 - INFO - Deleted policy ctest-policy12-81330609
2025-08-11 16:35:36,564 - INFO - Deleted policy ctest-policy13-44412942
2025-08-11 16:35:36,564 - INFO - Deleting VN ctest-VN3-39300788
2025-08-11 16:35:36,763 - DEBUG - Response for deleting network ()
2025-08-11 16:35:36,763 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/ac1788de-65ba-4ad3-b37a-6f4d832fe513
2025-08-11 16:35:36,769 - DEBUG - Response Code: 404
2025-08-11 16:35:36,769 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-11 16:35:36,775 - DEBUG - Requesting: http://10.0.0.27:8082/domain/4bc87c47-8b22-47dc-8747-cffafaf5f39a
2025-08-11 16:35:36,787 - DEBUG - Requesting: http://10.0.0.27:8082/project/35b7f555-174a-492a-b612-a83eaa9b00b7
2025-08-11 16:35:36,874 - INFO - Validated that VN ctest-VN3-39300788 is not found in API Server
2025-08-11 16:35:36,886 - DEBUG - VN ctest-VN3-39300788 is not present in Agent 10.0.0.141
2025-08-11 16:35:36,896 - DEBUG - VN ctest-VN3-39300788 is not present in Agent 10.0.0.249
2025-08-11 16:35:36,896 - INFO - Validated that VN ctest-VN3-39300788 is not in any agent
2025-08-11 16:35:36,927 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN3-39300788 info
2025-08-11 16:35:36,928 - INFO - Deleting VN ctest-VN2-34690797
2025-08-11 16:35:37,084 - DEBUG - Response for deleting network ()
2025-08-11 16:35:37,084 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/d11f203c-c4fd-4dcf-a8da-98e33d5f1c6f
2025-08-11 16:35:37,090 - DEBUG - Response Code: 404
2025-08-11 16:35:37,090 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-11 16:35:37,097 - DEBUG - Requesting: http://10.0.0.27:8082/domain/4bc87c47-8b22-47dc-8747-cffafaf5f39a
2025-08-11 16:35:37,108 - DEBUG - Requesting: http://10.0.0.27:8082/project/35b7f555-174a-492a-b612-a83eaa9b00b7
2025-08-11 16:35:37,203 - INFO - Validated that VN ctest-VN2-34690797 is not found in API Server
2025-08-11 16:35:37,214 - DEBUG - VN ctest-VN2-34690797 is not present in Agent 10.0.0.141
2025-08-11 16:35:37,226 - DEBUG - VN ctest-VN2-34690797 is not present in Agent 10.0.0.249
2025-08-11 16:35:37,226 - INFO - Validated that VN ctest-VN2-34690797 is not in any agent
2025-08-11 16:35:37,261 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN2-34690797 info
2025-08-11 16:35:37,262 - INFO - Deleting VN ctest-VN1-03648172
2025-08-11 16:35:37,435 - DEBUG - Response for deleting network ()
2025-08-11 16:35:37,435 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/8092b7a4-73b7-4e9e-851e-e0ce9f0f823c
2025-08-11 16:35:37,446 - DEBUG - Response Code: 404
2025-08-11 16:35:37,446 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2025-08-11 16:35:37,454 - DEBUG - Requesting: http://10.0.0.27:8082/domain/4bc87c47-8b22-47dc-8747-cffafaf5f39a
2025-08-11 16:35:37,507 - DEBUG - Requesting: http://10.0.0.27:8082/project/35b7f555-174a-492a-b612-a83eaa9b00b7
2025-08-11 16:35:37,591 - INFO - Validated that VN ctest-VN1-03648172 is not found in API Server
2025-08-11 16:35:37,608 - DEBUG - VN ctest-VN1-03648172 is not present in Agent 10.0.0.141
2025-08-11 16:35:37,620 - DEBUG - VN ctest-VN1-03648172 is not present in Agent 10.0.0.249
2025-08-11 16:35:37,620 - INFO - Validated that VN ctest-VN1-03648172 is not in any agent
2025-08-11 16:35:37,653 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN1-03648172 info
2025-08-11 16:35:37,742 - INFO - IPAM: ipam3 is not found in API Server
2025-08-11 16:35:37,765 - INFO - IPAM:ipam3 is not found in control node
2025-08-11 16:35:37,898 - INFO - IPAM: ipam2 is not found in API Server
2025-08-11 16:35:37,921 - INFO - IPAM:ipam2 is not found in control node
2025-08-11 16:35:38,054 - INFO - IPAM: ipam1 is not found in API Server
2025-08-11 16:35:38,082 - INFO - IPAM:ipam1 is not found in control node
2025-08-11 16:35:39,336 - DEBUG - No XMPP flaps were noticed during the test
2025-08-11 16:35:39,336 - INFO - END TEST : test_policy_inheritance_src_pol_dst_any : PASSED[0:02:26]
2025-08-11 16:35:39,336 - INFO - --------------------------------------------------------------------------------
2025-08-11 16:35:40,579 - DEBUG - No XMPP flaps were noticed during the test
2025-08-11 16:35:40,579 - INFO - END TEST : test_policy_inheritance_src_pol_dst_any : PASSED[0:02:28]
2025-08-11 16:35:40,579 - INFO - --------------------------------------------------------------------------------
2025-08-11 16:35:41,278 - INFO - Deleted project: ctest-TestPolicyAclIpv4v6-27062661, ID : 35b7f555-174a-492a-b612-a83eaa9b00b7