is in ACTIVE state now
2025-09-11 02:27:34,259 - DEBUG - VM ctest-VM31-90010509 launched on Node an-jenkins-deploy-platform-ansible-os-4079-1
2025-09-11 02:27:34,345 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine/433a8852-2bbf-43a3-935e-8c1413b601b3
2025-09-11 02:27:34,355 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine/433a8852-2bbf-43a3-935e-8c1413b601b3
2025-09-11 02:27:34,363 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine-interface/7203e3c4-134a-47f0-83db-029d2c66588d
2025-09-11 02:27:34,373 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine/433a8852-2bbf-43a3-935e-8c1413b601b3
2025-09-11 02:27:34,381 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine-interface/7203e3c4-134a-47f0-83db-029d2c66588d
2025-09-11 02:27:34,390 - DEBUG - Requesting: http://10.0.0.49:8082/instance-ip/2d3f1428-8fde-4f65-9275-ea52f729b6bc
2025-09-11 02:27:34,401 - DEBUG - Requesting: http://10.0.0.49:8082/instance-ip/887ebf60-6019-48bc-a0bf-8357401de619
2025-09-11 02:27:34,410 - DEBUG - Verifying in api server 10.0.0.49
2025-09-11 02:27:34,411 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine/433a8852-2bbf-43a3-935e-8c1413b601b3
2025-09-11 02:27:34,418 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine-interface/7203e3c4-134a-47f0-83db-029d2c66588d
2025-09-11 02:27:34,428 - DEBUG - Requesting: http://10.0.0.49:8082/instance-ip/2d3f1428-8fde-4f65-9275-ea52f729b6bc
2025-09-11 02:27:34,436 - DEBUG - Requesting: http://10.0.0.49:8082/instance-ip/887ebf60-6019-48bc-a0bf-8357401de619
2025-09-11 02:27:34,443 - INFO - VM ctest-VM31-90010509 verfication in all API Servers passed
2025-09-11 02:27:34,458 - DEBUG - VM ctest-VM31-90010509 Tap interface: {'index': '4', 'name': 'tap7203e3c4-13', 'uuid': '7203e3c4-134a-47f0-83db-029d2c66588d', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-19387082:ctest-VN3-05819714:ctest-VN3-05819714', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Active', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Enable', 'dns_service': 'Enable', 'type': 'vport', 'label': '32', 'l2_label': '36', 'vxlan_id': '16', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-19387082:ctest-VN3-05819714', 'vm_uuid': '433a8852-2bbf-43a3-935e-8c1413b601b3', 'vm_name': 'ctest-VM31-90010509', 'ip_addr': '126.63.119.131', 'mac_addr': '02:72:03:e3:c4:13', '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-19387082:7203e3c4-134a-47f0-83db-029d2c66588d', 'sg_uuid_list': ['9b0991ee-2466-43f8-888f-d559a5ceb2fe'], 'static_route_list': None, 'vm_project_uuid': '193df22e-f7a6-4e3b-8f3d-d1286d8ca3bd', 'admin_state': 'Enabled', 'flow_key_idx': '40', 'allowed_address_pair_list': None, 'ip6_addr': '3166:c40:ebf9:715:f2a5:e8c9: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-09-11 02:27:34,459 - DEBUG - Agent 10.0.0.254 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-19387082:ctest-VN3-05819714:ctest-VN3-05819714
2025-09-11 02:27:34,463 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-19387082:ctest-VN3-05819714:ctest-VN3-05819714', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-19387082:ctest-VN3-05819714', 'table_label': '-1', 'vxlan_id': '16', 'evpnindex': '3', 'brindex': '3', 'mplsindex': '0', 'RD': '10.20.0.18:3', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]}
2025-09-11 02:27:34,556 - DEBUG - VM ctest-VM31-90010509 : Tap interface tap7203e3c4-13 is set to Active
2025-09-11 02:27:34,556 - DEBUG - Tap interface tap7203e3c4-13 detail : {'index': '4', 'name': 'tap7203e3c4-13', 'uuid': '7203e3c4-134a-47f0-83db-029d2c66588d', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-19387082:ctest-VN3-05819714:ctest-VN3-05819714', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Active', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Enable', 'dns_service': 'Enable', 'type': 'vport', 'label': '32', 'l2_label': '36', 'vxlan_id': '16', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-19387082:ctest-VN3-05819714', 'vm_uuid': '433a8852-2bbf-43a3-935e-8c1413b601b3', 'vm_name': 'ctest-VM31-90010509', 'ip_addr': '126.63.119.131', 'mac_addr': '02:72:03:e3:c4:13', '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-19387082:7203e3c4-134a-47f0-83db-029d2c66588d', 'sg_uuid_list': ['9b0991ee-2466-43f8-888f-d559a5ceb2fe'], 'static_route_list': None, 'vm_project_uuid': '193df22e-f7a6-4e3b-8f3d-d1286d8ca3bd', 'admin_state': 'Enabled', 'flow_key_idx': '40', 'allowed_address_pair_list': None, 'ip6_addr': '3166:c40:ebf9:715:f2a5:e8c9: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-09-11 02:27:34,669 - DEBUG - Starting Layer 2 verification in Agent
2025-09-11 02:27:34,681 - DEBUG - Layer 2 path is seen for VM MAC 02:72:03:e3:c4:13 in agent 10.0.0.254
2025-09-11 02:27:34,681 - DEBUG - Active layer 2 route in agent is present for VMI tap7203e3c4-13
2025-09-11 02:27:34,682 - DEBUG - L2 label(36) matches bw route table and interface table
2025-09-11 02:27:34,688 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-19387082:ctest-VN3-05819714 is found in Agent of node 10.0.0.254
2025-09-11 02:27:34,842 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-19387082:ctest-VN3-05819714 is consistent in agent 10.0.0.254
2025-09-11 02:27:34,842 - DEBUG - Route for VM IP ['3166:c40:ebf9:715:f2a5:e8c9:0:3', '126.63.119.131'] is consistent in agent 10.0.0.254
2025-09-11 02:27:34,842 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-19387082:ctest-VN3-05819714 verification for VM ctest-VM31-90010509 in Agent 10.0.0.254 passed
2025-09-11 02:27:34,952 - DEBUG - Starting all layer 2 verification in agent 10.0.0.254
2025-09-11 02:27:34,967 - DEBUG - Route for VM MAC 02:72:03:e3:c4:13 is consistent in agent 10.0.0.254
2025-09-11 02:27:36,148 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=7.51 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.847 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 = 0.847/4.177/7.508/3.330 ms')
2025-09-11 02:27:36,148 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-90010509 passed
2025-09-11 02:27:36,148 - INFO - VM ctest-VM31-90010509 verifications in Compute nodes passed
2025-09-11 02:27:36,352 - DEBUG - Validated VM route 126.63.119.131 in vrouter of 10.0.0.254
2025-09-11 02:27:36,357 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0
2025-09-11 02:27:36,357 - INFO - Validated routes of VM ctest-VM31-90010509 in all vrouters
2025-09-11 02:27:36,853 - DEBUG - Starting all layer2 verification in 10.0.0.22 Control Node
2025-09-11 02:27:36,965 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.22, not supported
2025-09-11 02:27:37,087 - DEBUG - Layer2 route found for VM MAC 02:72:03:e3:c4:13 in Control-node 10.0.0.22
2025-09-11 02:27:37,087 - DEBUG - L2 Label for VM ctest-VM31-90010509 same between Control-node 10.0.0.22 and Agent, Expected: 36, Seen: 36
2025-09-11 02:27:37,087 - DEBUG - Starting all layer2 verification in 10.0.0.249 Control Node
2025-09-11 02:27:37,202 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.249, not supported
2025-09-11 02:27:37,327 - DEBUG - Layer2 route found for VM MAC 02:72:03:e3:c4:13 in Control-node 10.0.0.249
2025-09-11 02:27:37,327 - DEBUG - L2 Label for VM ctest-VM31-90010509 same between Control-node 10.0.0.249 and Agent, Expected: 36, Seen: 36
2025-09-11 02:27:37,327 - INFO - Verification in Control-nodes for VM ctest-VM31-90010509 passed
2025-09-11 02:27:37,327 - DEBUG - Verifying the vm in opserver
2025-09-11 02:27:37,327 - DEBUG - Verifying in collector 10.0.0.49 ...
2025-09-11 02:27:37,327 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-machine/433a8852-2bbf-43a3-935e-8c1413b601b3?flat
2025-09-11 02:27:37,342 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-19387082:7203e3c4-134a-47f0-83db-029d2c66588d?flat
2025-09-11 02:27:37,360 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-19387082:7203e3c4-134a-47f0-83db-029d2c66588d?flat
2025-09-11 02:27:37,378 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-19387082:7203e3c4-134a-47f0-83db-029d2c66588d?flat
2025-09-11 02:27:37,397 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-machine/433a8852-2bbf-43a3-935e-8c1413b601b3?flat
2025-09-11 02:27:37,415 - DEBUG - Verifying vm in vn uve
2025-09-11 02:27:37,415 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-19387082:7203e3c4-134a-47f0-83db-029d2c66588d?flat
2025-09-11 02:27:37,434 - DEBUG - VM uve shows interface as {'fixed_ip6_list': ['3166:c40:ebf9:715:f2a5:e8c9:0:3'], 'gateway': '126.63.119.129', 'vm_uuid': '433a8852-2bbf-43a3-935e-8c1413b601b3', 'mac_address': '02:72:03:e3:c4:13', '__T': 1757557610033460, 'label': 32, 'is_health_check_active': True, 'vn_uuid': '24a82f12-833e-48a6-b799-fd1b5a72a5c9', 'ip6_address': '3166:c40:ebf9:715:f2a5:e8c9:0:3', 'vm_name': 'ctest-VM31-90010509', 'port_mirror_enabled': False, 'admin_state': True, 'active': True, 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-19387082:ctest-VN3-05819714', 'uuid': '7203e3c4-134a-47f0-83db-029d2c66588d', 'ip4_active': True, 'l2_active': True, 'rx_vlan': 65535, 'ip_address': '126.63.119.131', 'fixed_ip4_list': ['126.63.119.131'], 'vhostuser_mode': 0, 'ip6_active': True, 'tx_vlan': 65535}
2025-09-11 02:27:37,434 - DEBUG - VM uve shows ip address as ['126.63.119.131', '3166:c40:ebf9:715:f2a5:e8c9:0:3']
2025-09-11 02:27:37,434 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-19387082:ctest-VN3-05819714
2025-09-11 02:27:37,434 - INFO - Verifying through opserver in 10.0.0.249
2025-09-11 02:27:37,434 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-19387082:ctest-VN3-05819714?flat
2025-09-11 02:27:37,455 - DEBUG - expected vm list 433a8852-2bbf-43a3-935e-8c1413b601b3
2025-09-11 02:27:37,456 - DEBUG - Extracted vm list ['433a8852-2bbf-43a3-935e-8c1413b601b3']
2025-09-11 02:27:37,456 - INFO - VM 433a8852-2bbf-43a3-935e-8c1413b601b3 is present in default-domain:ctest-TestPolicyAclIpv4v6-19387082:ctest-VN3-05819714
2025-09-11 02:27:37,456 - DEBUG - Verifying vm in vrouter uve
2025-09-11 02:27:37,456 - DEBUG - Getting info from collector 10.0.0.49..
2025-09-11 02:27:37,456 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-machine/433a8852-2bbf-43a3-935e-8c1413b601b3?flat
2025-09-11 02:27:37,474 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-4079-1']
2025-09-11 02:27:37,474 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4079-1:Compute:contrail-vrouter-agent:0?flat
2025-09-11 02:27:37,506 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4079-1?flat
2025-09-11 02:27:37,533 - DEBUG - VM 433a8852-2bbf-43a3-935e-8c1413b601b3 is present in vrouter an-jenkins-deploy-platform-ansible-os-4079-1 uve
2025-09-11 02:27:37,533 - DEBUG - Validated that VM ctest-VM31-90010509 is in Vrouter an-jenkins-deploy-platform-ansible-os-4079-1 UVE
2025-09-11 02:27:37,533 - DEBUG - Verifying vm tap interface/vn in vrouter uve
2025-09-11 02:27:37,540 - DEBUG - Expected tap interface of VM uuid 433a8852-2bbf-43a3-935e-8c1413b601b3 is default-domain:ctest-TestPolicyAclIpv4v6-19387082:7203e3c4-134a-47f0-83db-029d2c66588d
2025-09-11 02:27:37,540 - DEBUG - Expected VN of VM uuid 433a8852-2bbf-43a3-935e-8c1413b601b3 is default-domain:ctest-TestPolicyAclIpv4v6-19387082:ctest-VN3-05819714
2025-09-11 02:27:37,540 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4079-1:Compute:contrail-vrouter-agent:0?flat
2025-09-11 02:27:37,575 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4079-1?flat
2025-09-11 02:27:37,608 - DEBUG - VM 433a8852-2bbf-43a3-935e-8c1413b601b3 is present in vrouter an-jenkins-deploy-platform-ansible-os-4079-1 uve
2025-09-11 02:27:37,608 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-19387082:7203e3c4-134a-47f0-83db-029d2c66588d of vm 433a8852-2bbf-43a3-935e-8c1413b601b3 is present in vrouter an-jenkins-deploy-platform-ansible-os-4079-1 uve
2025-09-11 02:27:37,608 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-19387082:ctest-VN3-05819714 of vm 433a8852-2bbf-43a3-935e-8c1413b601b3 is present in vrouter an-jenkins-deploy-platform-ansible-os-4079-1 uve
2025-09-11 02:27:37,608 - DEBUG - Verifying the 433a8852-2bbf-43a3-935e-8c1413b601b3 virtual network link through opserver 10.0.0.249
2025-09-11 02:27:37,609 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machines
2025-09-11 02:27:37,622 - DEBUG - VM link and name as {'name': '433a8852-2bbf-43a3-935e-8c1413b601b3', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-machine/433a8852-2bbf-43a3-935e-8c1413b601b3?flat'}
2025-09-11 02:27:37,622 - DEBUG - Verifying the 433a8852-2bbf-43a3-935e-8c1413b601b3 virtual network link through opserver 10.0.0.22
2025-09-11 02:27:37,622 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machines
2025-09-11 02:27:37,634 - DEBUG - VM link and name as {'name': '433a8852-2bbf-43a3-935e-8c1413b601b3', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-machine/433a8852-2bbf-43a3-935e-8c1413b601b3?flat'}
2025-09-11 02:27:37,634 - DEBUG - Verifying the 433a8852-2bbf-43a3-935e-8c1413b601b3 virtual network link through opserver 10.0.0.49
2025-09-11 02:27:37,634 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-machines
2025-09-11 02:27:37,649 - DEBUG - VM link and name as {'name': '433a8852-2bbf-43a3-935e-8c1413b601b3', 'href': 'http://10.0.0.49:8081/analytics/uves/virtual-machine/433a8852-2bbf-43a3-935e-8c1413b601b3?flat'}
2025-09-11 02:27:37,649 - INFO - VM ctest-VM31-90010509 validations in Opserver passed
2025-09-11 02:27:37,649 - INFO - Waiting for VM ctest-VM11-40724831 to be up..
2025-09-11 02:27:37,649 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-09-11 02:27:37,649 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine/0ae9d462-44e0-4324-a1ff-2bfb9c479dab
2025-09-11 02:27:37,659 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine-interface/aa77c050-1c30-4377-8be0-413ad58a90b0
2025-09-11 02:27:38,835 - 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=11.8 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=3.83 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 3.825/7.825/11.826/4.000 ms')
2025-09-11 02:27:38,836 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-40724831 passed
2025-09-11 02:27:38,904 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-09-11 02:27:38,904 - DEBUG - Waiting to SSH to VM ctest-VM11-40724831, IP 144.16.110.3, Port 22
2025-09-11 02:27:38,970 - 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-09-11 02:27:39,072 - DEBUG - VM ctest-VM11-40724831 is NOT ready for SSH connections, VM status: ACTIVE
2025-09-11 02:27:44,073 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-09-11 02:27:44,073 - DEBUG - Waiting to SSH to VM ctest-VM11-40724831, IP 144.16.110.3, Port 22
2025-09-11 02:27:44,141 - 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-09-11 02:27:44,218 - DEBUG - VM ctest-VM11-40724831 is NOT ready for SSH connections, VM status: ACTIVE
2025-09-11 02:27:49,219 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-09-11 02:27:49,219 - DEBUG - Waiting to SSH to VM ctest-VM11-40724831, IP 144.16.110.3, Port 22
2025-09-11 02:27:49,375 - DEBUG - VM ctest-VM11-40724831 is ready for SSH connections
2025-09-11 02:27:49,375 - INFO - Waiting for VM ctest-VM21-61097314 to be up..
2025-09-11 02:27:49,375 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-09-11 02:27:49,375 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine/38103ac9-0bbf-47a7-b5ca-3a366cf43b27
2025-09-11 02:27:49,383 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine-interface/cc09baf1-001e-4c2e-8fc8-08c74c634de8
2025-09-11 02:27:50,541 - 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.12 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.444 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 = 0.444/1.283/2.122/0.839 ms')
2025-09-11 02:27:50,541 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-61097314 passed
2025-09-11 02:27:50,607 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-09-11 02:27:50,607 - DEBUG - Waiting to SSH to VM ctest-VM21-61097314, IP 124.28.218.67, Port 22
2025-09-11 02:27:50,777 - DEBUG - VM ctest-VM21-61097314 is ready for SSH connections
2025-09-11 02:27:50,778 - INFO - Waiting for VM ctest-VM31-90010509 to be up..
2025-09-11 02:27:50,778 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-09-11 02:27:50,778 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine/433a8852-2bbf-43a3-935e-8c1413b601b3
2025-09-11 02:27:50,787 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine-interface/7203e3c4-134a-47f0-83db-029d2c66588d
2025-09-11 02:27:51,961 - 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.41 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=2.24 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 2.238/2.321/2.405/0.083 ms')
2025-09-11 02:27:51,961 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-90010509 passed
2025-09-11 02:27:52,028 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-09-11 02:27:52,028 - DEBUG - Waiting to SSH to VM ctest-VM31-90010509, IP 126.63.119.131, Port 22
2025-09-11 02:27:52,197 - DEBUG - VM ctest-VM31-90010509 is ready for SSH connections
2025-09-11 02:27:52,198 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 124.28.218.67, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.254, gateway password: c0ntrail123
2025-09-11 02:27:52,198 - DEBUG - ping -s 56 -c 3 -W 1 124.28.218.67
2025-09-11 02:28:13,118 - DEBUG - PING 124.28.218.67 (124.28.218.67) 56(84) bytes of data.
64 bytes from 124.28.218.67: icmp_req=1 ttl=63 time=6.97 ms
64 bytes from 124.28.218.67: icmp_req=2 ttl=63 time=3.36 ms
64 bytes from 124.28.218.67: icmp_req=3 ttl=63 time=2.35 ms
--- 124.28.218.67 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2003ms
rtt min/avg/max/mdev = 2.354/4.233/6.977/1.984 ms
2025-09-11 02:28:13,118 - INFO - Ping to IP 124.28.218.67 from VM ctest-VM11-40724831 passed
2025-09-11 02:28:13,119 - DEBUG - Running remote_cmd, Cmd : ping6 -s 56 -c 3 -W 1 387c:9d89:c288:9194:44d5:131b:0:3, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.254, gateway password: c0ntrail123
2025-09-11 02:28:13,119 - DEBUG - ping6 -s 56 -c 3 -W 1 387c:9d89:c288:9194:44d5:131b:0:3
2025-09-11 02:28:15,711 - DEBUG - PING 387c:9d89:c288:9194:44d5:131b:0:3(387c:9d89:c288:9194:44d5:131b:0:3) 56 data bytes
64 bytes from 387c:9d89:c288:9194:44d5:131b:0:3: icmp_seq=1 ttl=64 time=8.71 ms
64 bytes from 387c:9d89:c288:9194:44d5:131b:0:3: icmp_seq=2 ttl=64 time=1.76 ms
64 bytes from 387c:9d89:c288:9194:44d5:131b:0:3: icmp_seq=3 ttl=64 time=1.57 ms
--- 387c:9d89:c288:9194:44d5:131b:0:3 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 1.570/4.017/8.713/3.321 ms
2025-09-11 02:28:15,711 - INFO - Ping to IP 387c:9d89:c288:9194:44d5:131b:0:3 from VM ctest-VM11-40724831 passed
2025-09-11 02:28:15,712 - INFO - Test with src as policy and dst as any PASSED
2025-09-11 02:28:15,712 - INFO - Deleting VM ctest-VM31-90010509
2025-09-11 02:28:15,786 - DEBUG - Verifying in api server 10.0.0.49
2025-09-11 02:28:15,786 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine/433a8852-2bbf-43a3-935e-8c1413b601b3
2025-09-11 02:28:15,794 - DEBUG - VM ID 433a8852-2bbf-43a3-935e-8c1413b601b3 of VM ctest-VM31-90010509 is still found in API Server
2025-09-11 02:28:17,794 - DEBUG - Verifying in api server 10.0.0.49
2025-09-11 02:28:17,795 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine/433a8852-2bbf-43a3-935e-8c1413b601b3
2025-09-11 02:28:17,801 - DEBUG - Response Code: 404
2025-09-11 02:28:17,801 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine/433a8852-2bbf-43a3-935e-8c1413b601b3
2025-09-11 02:28:17,807 - DEBUG - Response Code: 404
2025-09-11 02:28:17,807 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine/433a8852-2bbf-43a3-935e-8c1413b601b3
2025-09-11 02:28:17,812 - DEBUG - Response Code: 404
2025-09-11 02:28:17,812 - INFO - VM ctest-VM31-90010509 is fully removed in API-Server
2025-09-11 02:28:17,971 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2025-09-11 02:28:18,023 - DEBUG - Validated that vrouter 10.0.0.254 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-19387082:ctest-VN3-05819714
2025-09-11 02:28:18,034 - DEBUG - Validated that vrouter 10.0.0.19 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-19387082:ctest-VN3-05819714
2025-09-11 02:28:18,034 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-19387082:ctest-VN3-05819714
2025-09-11 02:28:18,034 - INFO - VM ctest-VM31-90010509 is removed in Compute, and routes are removed in all compute nodes
2025-09-11 02:28:18,273 - INFO - Routes for VM ctest-VM31-90010509 is removed in all control-nodes
2025-09-11 02:28:18,274 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4079-1:Compute:contrail-vrouter-agent:0?flat
2025-09-11 02:28:18,304 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4079-1?flat
2025-09-11 02:28:18,335 - DEBUG - VM 433a8852-2bbf-43a3-935e-8c1413b601b3 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4079-1 uve
2025-09-11 02:28:18,335 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-09-11 02:28:18,335 - DEBUG - interface for vm 433a8852-2bbf-43a3-935e-8c1413b601b3 deleted from vrouter uve ...
2025-09-11 02:28:18,335 - DEBUG - Deleted interface not in error interface list ...
2025-09-11 02:28:21,336 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4079-1:Compute:contrail-vrouter-agent:0?flat
2025-09-11 02:28:21,370 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4079-1?flat
2025-09-11 02:28:21,397 - DEBUG - VM 433a8852-2bbf-43a3-935e-8c1413b601b3 is not present in vrouter an-jenkins-deploy-platform-ansible-os-4079-1 uve
2025-09-11 02:28:21,397 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-09-11 02:28:21,397 - DEBUG - interface for vm 433a8852-2bbf-43a3-935e-8c1413b601b3 deleted from vrouter uve ...
2025-09-11 02:28:21,397 - DEBUG - Deleted interface not in error interface list ...
2025-09-11 02:28:21,397 - DEBUG - Validated that VM 433a8852-2bbf-43a3-935e-8c1413b601b3 is removed in Vrouter UVE
2025-09-11 02:28:21,397 - DEBUG - Verifying through opserver in 10.0.0.249
2025-09-11 02:28:21,398 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-19387082:ctest-VN3-05819714?flat
2025-09-11 02:28:21,414 - DEBUG - 433a8852-2bbf-43a3-935e-8c1413b601b3 BM not in default-domain:ctest-TestPolicyAclIpv4v6-19387082:ctest-VN3-05819714 uve
2025-09-11 02:28:21,415 - DEBUG - Validated that VM 433a8852-2bbf-43a3-935e-8c1413b601b3 is not present in the VN UVE
2025-09-11 02:28:21,415 - DEBUG - Verifying the 433a8852-2bbf-43a3-935e-8c1413b601b3 virtual network link through opserver 10.0.0.249
2025-09-11 02:28:21,415 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machines
2025-09-11 02:28:21,425 - INFO - 433a8852-2bbf-43a3-935e-8c1413b601b3 vm uve deleted from opserver
2025-09-11 02:28:21,425 - DEBUG - Verifying the 433a8852-2bbf-43a3-935e-8c1413b601b3 virtual network link through opserver 10.0.0.22
2025-09-11 02:28:21,425 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machines
2025-09-11 02:28:21,435 - INFO - 433a8852-2bbf-43a3-935e-8c1413b601b3 vm uve deleted from opserver
2025-09-11 02:28:21,435 - DEBUG - Verifying the 433a8852-2bbf-43a3-935e-8c1413b601b3 virtual network link through opserver 10.0.0.49
2025-09-11 02:28:21,435 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-machines
2025-09-11 02:28:21,447 - INFO - 433a8852-2bbf-43a3-935e-8c1413b601b3 vm uve deleted from opserver
2025-09-11 02:28:21,447 - INFO - Validated that VM 433a8852-2bbf-43a3-935e-8c1413b601b3 is removed from Opserver
2025-09-11 02:28:21,447 - INFO - Deleting VM ctest-VM21-61097314
2025-09-11 02:28:21,523 - DEBUG - Verifying in api server 10.0.0.49
2025-09-11 02:28:21,523 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine/38103ac9-0bbf-47a7-b5ca-3a366cf43b27
2025-09-11 02:28:21,532 - DEBUG - VM ID 38103ac9-0bbf-47a7-b5ca-3a366cf43b27 of VM ctest-VM21-61097314 is still found in API Server
2025-09-11 02:28:23,533 - DEBUG - Verifying in api server 10.0.0.49
2025-09-11 02:28:23,533 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine/38103ac9-0bbf-47a7-b5ca-3a366cf43b27
2025-09-11 02:28:23,540 - DEBUG - Response Code: 404
2025-09-11 02:28:23,540 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine/38103ac9-0bbf-47a7-b5ca-3a366cf43b27
2025-09-11 02:28:23,545 - DEBUG - Response Code: 404
2025-09-11 02:28:23,545 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine/38103ac9-0bbf-47a7-b5ca-3a366cf43b27
2025-09-11 02:28:23,551 - DEBUG - Response Code: 404
2025-09-11 02:28:23,552 - INFO - VM ctest-VM21-61097314 is fully removed in API-Server
2025-09-11 02:28:23,710 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2025-09-11 02:28:23,756 - DEBUG - Validated that vrouter 10.0.0.254 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-19387082:ctest-VN2-76923013
2025-09-11 02:28:23,766 - DEBUG - Validated that vrouter 10.0.0.19 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-19387082:ctest-VN2-76923013
2025-09-11 02:28:23,766 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-19387082:ctest-VN2-76923013
2025-09-11 02:28:23,766 - INFO - VM ctest-VM21-61097314 is removed in Compute, and routes are removed in all compute nodes
2025-09-11 02:28:24,011 - INFO - Routes for VM ctest-VM21-61097314 is removed in all control-nodes
2025-09-11 02:28:24,012 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4079-2:Compute:contrail-vrouter-agent:0?flat
2025-09-11 02:28:24,047 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4079-2?flat
2025-09-11 02:28:24,076 - DEBUG - VM 38103ac9-0bbf-47a7-b5ca-3a366cf43b27 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4079-2 uve
2025-09-11 02:28:24,076 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-09-11 02:28:24,076 - DEBUG - interface for vm 38103ac9-0bbf-47a7-b5ca-3a366cf43b27 deleted from vrouter uve ...
2025-09-11 02:28:24,076 - DEBUG - Deleted interface not in error interface list ...
2025-09-11 02:28:27,077 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4079-2:Compute:contrail-vrouter-agent:0?flat
2025-09-11 02:28:27,106 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4079-2?flat
2025-09-11 02:28:27,135 - DEBUG - VM 38103ac9-0bbf-47a7-b5ca-3a366cf43b27 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4079-2 uve
2025-09-11 02:28:27,136 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-09-11 02:28:27,136 - DEBUG - interface for vm 38103ac9-0bbf-47a7-b5ca-3a366cf43b27 deleted from vrouter uve ...
2025-09-11 02:28:27,136 - DEBUG - Deleted interface not in error interface list ...
2025-09-11 02:28:30,136 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4079-2:Compute:contrail-vrouter-agent:0?flat
2025-09-11 02:28:30,167 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4079-2?flat
2025-09-11 02:28:30,195 - DEBUG - VM 38103ac9-0bbf-47a7-b5ca-3a366cf43b27 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4079-2 uve
2025-09-11 02:28:30,195 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-09-11 02:28:30,195 - DEBUG - interface for vm 38103ac9-0bbf-47a7-b5ca-3a366cf43b27 deleted from vrouter uve ...
2025-09-11 02:28:30,195 - DEBUG - Deleted interface not in error interface list ...
2025-09-11 02:28:33,196 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4079-2:Compute:contrail-vrouter-agent:0?flat
2025-09-11 02:28:33,230 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4079-2?flat
2025-09-11 02:28:33,290 - DEBUG - VM 38103ac9-0bbf-47a7-b5ca-3a366cf43b27 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4079-2 uve
2025-09-11 02:28:33,290 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-09-11 02:28:33,290 - DEBUG - interface for vm 38103ac9-0bbf-47a7-b5ca-3a366cf43b27 deleted from vrouter uve ...
2025-09-11 02:28:33,290 - DEBUG - Deleted interface not in error interface list ...
2025-09-11 02:28:36,291 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4079-2:Compute:contrail-vrouter-agent:0?flat
2025-09-11 02:28:36,324 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4079-2?flat
2025-09-11 02:28:36,353 - DEBUG - VM 38103ac9-0bbf-47a7-b5ca-3a366cf43b27 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4079-2 uve
2025-09-11 02:28:36,353 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-09-11 02:28:36,353 - DEBUG - interface for vm 38103ac9-0bbf-47a7-b5ca-3a366cf43b27 deleted from vrouter uve ...
2025-09-11 02:28:36,353 - DEBUG - Deleted interface not in error interface list ...
2025-09-11 02:28:39,354 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4079-2:Compute:contrail-vrouter-agent:0?flat
2025-09-11 02:28:39,385 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4079-2?flat
2025-09-11 02:28:39,412 - DEBUG - VM 38103ac9-0bbf-47a7-b5ca-3a366cf43b27 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4079-2 uve
2025-09-11 02:28:39,413 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-09-11 02:28:39,413 - DEBUG - interface for vm 38103ac9-0bbf-47a7-b5ca-3a366cf43b27 deleted from vrouter uve ...
2025-09-11 02:28:39,413 - DEBUG - Deleted interface not in error interface list ...
2025-09-11 02:28:42,414 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4079-2:Compute:contrail-vrouter-agent:0?flat
2025-09-11 02:28:42,447 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4079-2?flat
2025-09-11 02:28:42,474 - DEBUG - VM 38103ac9-0bbf-47a7-b5ca-3a366cf43b27 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4079-2 uve
2025-09-11 02:28:42,474 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-09-11 02:28:42,474 - DEBUG - interface for vm 38103ac9-0bbf-47a7-b5ca-3a366cf43b27 deleted from vrouter uve ...
2025-09-11 02:28:42,474 - DEBUG - Deleted interface not in error interface list ...
2025-09-11 02:28:45,475 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4079-2:Compute:contrail-vrouter-agent:0?flat
2025-09-11 02:28:45,507 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4079-2?flat
2025-09-11 02:28:45,533 - DEBUG - VM 38103ac9-0bbf-47a7-b5ca-3a366cf43b27 is not present in vrouter an-jenkins-deploy-platform-ansible-os-4079-2 uve
2025-09-11 02:28:45,533 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-09-11 02:28:45,533 - DEBUG - interface for vm 38103ac9-0bbf-47a7-b5ca-3a366cf43b27 deleted from vrouter uve ...
2025-09-11 02:28:45,533 - DEBUG - Deleted interface not in error interface list ...
2025-09-11 02:28:45,533 - DEBUG - Validated that VM 38103ac9-0bbf-47a7-b5ca-3a366cf43b27 is removed in Vrouter UVE
2025-09-11 02:28:45,533 - DEBUG - Verifying through opserver in 10.0.0.249
2025-09-11 02:28:45,533 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-19387082:ctest-VN2-76923013?flat
2025-09-11 02:28:45,549 - DEBUG - 38103ac9-0bbf-47a7-b5ca-3a366cf43b27 BM not in default-domain:ctest-TestPolicyAclIpv4v6-19387082:ctest-VN2-76923013 uve
2025-09-11 02:28:45,549 - DEBUG - Validated that VM 38103ac9-0bbf-47a7-b5ca-3a366cf43b27 is not present in the VN UVE
2025-09-11 02:28:45,549 - DEBUG - Verifying the 38103ac9-0bbf-47a7-b5ca-3a366cf43b27 virtual network link through opserver 10.0.0.249
2025-09-11 02:28:45,549 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machines
2025-09-11 02:28:45,561 - INFO - 38103ac9-0bbf-47a7-b5ca-3a366cf43b27 vm uve deleted from opserver
2025-09-11 02:28:45,561 - DEBUG - Verifying the 38103ac9-0bbf-47a7-b5ca-3a366cf43b27 virtual network link through opserver 10.0.0.22
2025-09-11 02:28:45,561 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machines
2025-09-11 02:28:45,573 - INFO - 38103ac9-0bbf-47a7-b5ca-3a366cf43b27 vm uve deleted from opserver
2025-09-11 02:28:45,573 - DEBUG - Verifying the 38103ac9-0bbf-47a7-b5ca-3a366cf43b27 virtual network link through opserver 10.0.0.49
2025-09-11 02:28:45,573 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-machines
2025-09-11 02:28:45,583 - INFO - 38103ac9-0bbf-47a7-b5ca-3a366cf43b27 vm uve deleted from opserver
2025-09-11 02:28:45,584 - INFO - Validated that VM 38103ac9-0bbf-47a7-b5ca-3a366cf43b27 is removed from Opserver
2025-09-11 02:28:45,584 - INFO - Deleting VM ctest-VM11-40724831
2025-09-11 02:28:45,705 - DEBUG - Verifying in api server 10.0.0.49
2025-09-11 02:28:45,706 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine/0ae9d462-44e0-4324-a1ff-2bfb9c479dab
2025-09-11 02:28:45,714 - DEBUG - VM ID 0ae9d462-44e0-4324-a1ff-2bfb9c479dab of VM ctest-VM11-40724831 is still found in API Server
2025-09-11 02:28:47,715 - DEBUG - Verifying in api server 10.0.0.49
2025-09-11 02:28:47,715 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine/0ae9d462-44e0-4324-a1ff-2bfb9c479dab
2025-09-11 02:28:47,721 - DEBUG - Response Code: 404
2025-09-11 02:28:47,721 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine/0ae9d462-44e0-4324-a1ff-2bfb9c479dab
2025-09-11 02:28:47,726 - DEBUG - Response Code: 404
2025-09-11 02:28:47,727 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine/0ae9d462-44e0-4324-a1ff-2bfb9c479dab
2025-09-11 02:28:47,733 - DEBUG - Response Code: 404
2025-09-11 02:28:47,733 - INFO - VM ctest-VM11-40724831 is fully removed in API-Server
2025-09-11 02:28:47,882 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2025-09-11 02:28:47,924 - DEBUG - Validated that vrouter 10.0.0.254 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-19387082:ctest-VN1-50538801
2025-09-11 02:28:47,934 - DEBUG - Validated that vrouter 10.0.0.19 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-19387082:ctest-VN1-50538801
2025-09-11 02:28:47,934 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-19387082:ctest-VN1-50538801
2025-09-11 02:28:47,934 - INFO - VM ctest-VM11-40724831 is removed in Compute, and routes are removed in all compute nodes
2025-09-11 02:28:48,119 - INFO - Routes for VM ctest-VM11-40724831 is removed in all control-nodes
2025-09-11 02:28:48,119 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4079-1:Compute:contrail-vrouter-agent:0?flat
2025-09-11 02:28:48,148 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4079-1?flat
2025-09-11 02:28:48,175 - DEBUG - VM 0ae9d462-44e0-4324-a1ff-2bfb9c479dab is still present in vrouter an-jenkins-deploy-platform-ansible-os-4079-1 uve
2025-09-11 02:28:48,175 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-09-11 02:28:48,175 - DEBUG - interface for vm 0ae9d462-44e0-4324-a1ff-2bfb9c479dab deleted from vrouter uve ...
2025-09-11 02:28:48,175 - DEBUG - Deleted interface not in error interface list ...
2025-09-11 02:28:51,176 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4079-1:Compute:contrail-vrouter-agent:0?flat
2025-09-11 02:28:51,207 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4079-1?flat
2025-09-11 02:28:51,237 - DEBUG - VM 0ae9d462-44e0-4324-a1ff-2bfb9c479dab is not present in vrouter an-jenkins-deploy-platform-ansible-os-4079-1 uve
2025-09-11 02:28:51,237 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-09-11 02:28:51,237 - DEBUG - interface for vm 0ae9d462-44e0-4324-a1ff-2bfb9c479dab deleted from vrouter uve ...
2025-09-11 02:28:51,237 - DEBUG - Deleted interface not in error interface list ...
2025-09-11 02:28:51,237 - DEBUG - Validated that VM 0ae9d462-44e0-4324-a1ff-2bfb9c479dab is removed in Vrouter UVE
2025-09-11 02:28:51,237 - DEBUG - Verifying through opserver in 10.0.0.249
2025-09-11 02:28:51,237 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-19387082:ctest-VN1-50538801?flat
2025-09-11 02:28:51,254 - DEBUG - 0ae9d462-44e0-4324-a1ff-2bfb9c479dab BM not in default-domain:ctest-TestPolicyAclIpv4v6-19387082:ctest-VN1-50538801 uve
2025-09-11 02:28:51,254 - DEBUG - Validated that VM 0ae9d462-44e0-4324-a1ff-2bfb9c479dab is not present in the VN UVE
2025-09-11 02:28:51,254 - DEBUG - Verifying the 0ae9d462-44e0-4324-a1ff-2bfb9c479dab virtual network link through opserver 10.0.0.249
2025-09-11 02:28:51,254 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machines
2025-09-11 02:28:51,265 - DEBUG - No links retuned for 0ae9d462-44e0-4324-a1ff-2bfb9c479dab
2025-09-11 02:28:51,265 - INFO - 0ae9d462-44e0-4324-a1ff-2bfb9c479dab vm uve deleted from opserver
2025-09-11 02:28:51,265 - DEBUG - Verifying the 0ae9d462-44e0-4324-a1ff-2bfb9c479dab virtual network link through opserver 10.0.0.22
2025-09-11 02:28:51,265 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machines
2025-09-11 02:28:51,276 - DEBUG - No links retuned for 0ae9d462-44e0-4324-a1ff-2bfb9c479dab
2025-09-11 02:28:51,276 - INFO - 0ae9d462-44e0-4324-a1ff-2bfb9c479dab vm uve deleted from opserver
2025-09-11 02:28:51,276 - DEBUG - Verifying the 0ae9d462-44e0-4324-a1ff-2bfb9c479dab virtual network link through opserver 10.0.0.49
2025-09-11 02:28:51,276 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-machines
2025-09-11 02:28:51,288 - DEBUG - No links retuned for 0ae9d462-44e0-4324-a1ff-2bfb9c479dab
2025-09-11 02:28:51,288 - INFO - 0ae9d462-44e0-4324-a1ff-2bfb9c479dab vm uve deleted from opserver
2025-09-11 02:28:51,288 - INFO - Validated that VM 0ae9d462-44e0-4324-a1ff-2bfb9c479dab is removed from Opserver
2025-09-11 02:28:51,288 - DEBUG - Detaching the Policy for VN :ctest-VN2-76923013
2025-09-11 02:28:51,288 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-09-11 02:28:51,294 - DEBUG - Requesting: http://10.0.0.249:8082/domain/6cefd47d-baca-4df6-a10c-ec963319da07
2025-09-11 02:28:51,306 - DEBUG - Requesting: http://10.0.0.249:8082/project/193df22e-f7a6-4e3b-8f3d-d1286d8ca3bd
2025-09-11 02:28:51,415 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/5f1617e6-968d-4e14-9113-1dc21b98d2b9
2025-09-11 02:28:51,429 - DEBUG - Requesting: http://10.0.0.249:8082/network-policy/fefca250-3c3a-4224-8e07-22b83bba118a
2025-09-11 02:28:51,607 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-19387082', 'ctest-policy21-05149524']] from ctest-VN2-76923013
2025-09-11 02:28:51,607 - DEBUG - Detaching the Policy for VN :ctest-VN1-50538801
2025-09-11 02:28:51,607 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-09-11 02:28:51,625 - DEBUG - Requesting: http://10.0.0.249:8082/domain/6cefd47d-baca-4df6-a10c-ec963319da07
2025-09-11 02:28:51,640 - DEBUG - Requesting: http://10.0.0.249:8082/project/193df22e-f7a6-4e3b-8f3d-d1286d8ca3bd
2025-09-11 02:28:51,739 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/82a3cc07-8527-447e-b6f8-861cf3fd32a8
2025-09-11 02:28:51,754 - DEBUG - Requesting: http://10.0.0.249:8082/network-policy/d095e956-3f6b-434d-8047-1a178b8dcd84
2025-09-11 02:28:51,763 - DEBUG - Requesting: http://10.0.0.249:8082/network-policy/b9e69a29-00be-4456-83fd-cc768abf3b2e
2025-09-11 02:28:51,928 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-19387082', 'ctest-policy12-80404932'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-19387082', 'ctest-policy13-40914234']] from ctest-VN1-50538801
2025-09-11 02:28:51,997 - INFO - Deleted policy ctest-policy21-05149524
2025-09-11 02:28:52,077 - INFO - Deleted policy ctest-policy12-80404932
2025-09-11 02:28:52,124 - INFO - Deleted policy ctest-policy13-40914234
2025-09-11 02:28:52,124 - INFO - Deleting VN ctest-VN3-05819714
2025-09-11 02:28:52,290 - DEBUG - Response for deleting network ()
2025-09-11 02:28:52,290 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/3bc6b8ce-baaf-40ce-8816-5d98b4165557
2025-09-11 02:28:52,298 - DEBUG - Response Code: 404
2025-09-11 02:28:52,298 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-09-11 02:28:52,305 - DEBUG - Requesting: http://10.0.0.249:8082/domain/6cefd47d-baca-4df6-a10c-ec963319da07
2025-09-11 02:28:52,318 - DEBUG - Requesting: http://10.0.0.249:8082/project/193df22e-f7a6-4e3b-8f3d-d1286d8ca3bd
2025-09-11 02:28:52,409 - INFO - Validated that VN ctest-VN3-05819714 is not found in API Server
2025-09-11 02:28:52,419 - DEBUG - VN ctest-VN3-05819714 is not present in Agent 10.0.0.254
2025-09-11 02:28:52,429 - DEBUG - VN ctest-VN3-05819714 is not present in Agent 10.0.0.19
2025-09-11 02:28:52,429 - INFO - Validated that VN ctest-VN3-05819714 is not in any agent
2025-09-11 02:28:52,459 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN3-05819714 info
2025-09-11 02:28:52,459 - INFO - Deleting VN ctest-VN2-76923013
2025-09-11 02:28:52,629 - DEBUG - Response for deleting network ()
2025-09-11 02:28:52,629 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/3a4046f9-adbb-4eae-b83e-f1da028d6aa9
2025-09-11 02:28:52,639 - DEBUG - Response Code: 404
2025-09-11 02:28:52,639 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-09-11 02:28:52,644 - DEBUG - Requesting: http://10.0.0.249:8082/domain/6cefd47d-baca-4df6-a10c-ec963319da07
2025-09-11 02:28:52,696 - DEBUG - Requesting: http://10.0.0.249:8082/project/193df22e-f7a6-4e3b-8f3d-d1286d8ca3bd
2025-09-11 02:28:52,783 - INFO - Validated that VN ctest-VN2-76923013 is not found in API Server
2025-09-11 02:28:52,794 - DEBUG - VN ctest-VN2-76923013 is not present in Agent 10.0.0.254
2025-09-11 02:28:52,804 - DEBUG - VN ctest-VN2-76923013 is not present in Agent 10.0.0.19
2025-09-11 02:28:52,804 - INFO - Validated that VN ctest-VN2-76923013 is not in any agent
2025-09-11 02:28:52,832 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN2-76923013 info
2025-09-11 02:28:52,833 - INFO - Deleting VN ctest-VN1-50538801
2025-09-11 02:28:53,022 - DEBUG - Response for deleting network ()
2025-09-11 02:28:53,022 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/77b33e0d-e5ab-46d0-b9ca-9114151b2787
2025-09-11 02:28:53,032 - DEBUG - Response Code: 404
2025-09-11 02:28:53,032 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-09-11 02:28:53,037 - DEBUG - Requesting: http://10.0.0.249:8082/domain/6cefd47d-baca-4df6-a10c-ec963319da07
2025-09-11 02:28:53,049 - DEBUG - Requesting: http://10.0.0.249:8082/project/193df22e-f7a6-4e3b-8f3d-d1286d8ca3bd
2025-09-11 02:28:53,137 - INFO - Validated that VN ctest-VN1-50538801 is not found in API Server
2025-09-11 02:28:53,149 - DEBUG - VN ctest-VN1-50538801 is not present in Agent 10.0.0.254
2025-09-11 02:28:53,159 - DEBUG - VN ctest-VN1-50538801 is not present in Agent 10.0.0.19
2025-09-11 02:28:53,160 - INFO - Validated that VN ctest-VN1-50538801 is not in any agent
2025-09-11 02:28:53,190 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN1-50538801 info
2025-09-11 02:28:53,279 - INFO - IPAM: ipam3 is not found in API Server
2025-09-11 02:28:53,301 - INFO - IPAM:ipam3 is not found in control node
2025-09-11 02:28:53,435 - INFO - IPAM: ipam2 is not found in API Server
2025-09-11 02:28:53,455 - INFO - IPAM:ipam2 is not found in control node
2025-09-11 02:28:53,587 - INFO - IPAM: ipam1 is not found in API Server
2025-09-11 02:28:53,606 - INFO - IPAM:ipam1 is not found in control node
2025-09-11 02:28:54,868 - DEBUG - No XMPP flaps were noticed during the test
2025-09-11 02:28:54,868 - INFO - END TEST : test_policy_inheritance_src_pol_dst_any : PASSED[0:02:26]
2025-09-11 02:28:54,869 - INFO - --------------------------------------------------------------------------------
2025-09-11 02:28:56,142 - DEBUG - No XMPP flaps were noticed during the test
2025-09-11 02:28:56,142 - INFO - END TEST : test_policy_inheritance_src_pol_dst_any : PASSED[0:02:29]
2025-09-11 02:28:56,142 - INFO - --------------------------------------------------------------------------------
2025-09-11 02:28:56,895 - INFO - Deleted project: ctest-TestPolicyAclIpv4v6-19387082, ID : 193df22e-f7a6-4e3b-8f3d-d1286d8ca3bd