is in ACTIVE state now
2025-08-07 18:41:25,831 - DEBUG - VM ctest-VM31-42135686 launched on Node an-jenkins-deploy-platform-ansible-os-3775-2
2025-08-07 18:41:25,926 - DEBUG - Requesting: http://10.0.0.241:8082/virtual-machine/2b2ac87f-b7af-4cfb-956b-f19f0ef0d768
2025-08-07 18:41:25,942 - DEBUG - Requesting: http://10.0.0.241:8082/virtual-machine/2b2ac87f-b7af-4cfb-956b-f19f0ef0d768
2025-08-07 18:41:25,951 - DEBUG - Requesting: http://10.0.0.241:8082/virtual-machine-interface/9633ad65-c796-4aff-8d8b-aab6e369fb76
2025-08-07 18:41:25,964 - DEBUG - Requesting: http://10.0.0.241:8082/virtual-machine/2b2ac87f-b7af-4cfb-956b-f19f0ef0d768
2025-08-07 18:41:25,974 - DEBUG - Requesting: http://10.0.0.241:8082/virtual-machine-interface/9633ad65-c796-4aff-8d8b-aab6e369fb76
2025-08-07 18:41:25,988 - DEBUG - Requesting: http://10.0.0.241:8082/instance-ip/020c7d1a-4c89-45c1-b4ce-f95a37b21a89
2025-08-07 18:41:26,000 - DEBUG - Requesting: http://10.0.0.241:8082/instance-ip/947d55bf-4a70-4a4e-ae0a-a95369c41013
2025-08-07 18:41:26,015 - DEBUG - Verifying in api server 10.0.0.241
2025-08-07 18:41:26,015 - DEBUG - Requesting: http://10.0.0.241:8082/virtual-machine/2b2ac87f-b7af-4cfb-956b-f19f0ef0d768
2025-08-07 18:41:26,027 - DEBUG - Requesting: http://10.0.0.241:8082/virtual-machine-interface/9633ad65-c796-4aff-8d8b-aab6e369fb76
2025-08-07 18:41:26,045 - DEBUG - Requesting: http://10.0.0.241:8082/instance-ip/020c7d1a-4c89-45c1-b4ce-f95a37b21a89
2025-08-07 18:41:26,060 - DEBUG - Requesting: http://10.0.0.241:8082/instance-ip/947d55bf-4a70-4a4e-ae0a-a95369c41013
2025-08-07 18:41:26,070 - INFO - VM ctest-VM31-42135686 verfication in all API Servers passed
2025-08-07 18:41:26,104 - DEBUG - VM ctest-VM31-42135686 Tap interface: {'index': '4', 'name': 'tap9633ad65-c7', 'uuid': '9633ad65-c796-4aff-8d8b-aab6e369fb76', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-15412084:ctest-VN3-91424494:ctest-VN3-91424494', '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': '17', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-15412084:ctest-VN3-91424494', 'vm_uuid': '2b2ac87f-b7af-4cfb-956b-f19f0ef0d768', 'vm_name': 'ctest-VM31-42135686', 'ip_addr': '88.116.90.131', 'mac_addr': '02:96:33:ad:65:c7', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.4', 'service_vlan_list': None, 'os_ifindex': '57', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestPolicyAclIpv4v6-15412084:9633ad65-c796-4aff-8d8b-aab6e369fb76', 'sg_uuid_list': ['34bd83ee-ca58-4711-9334-bc5b35c1f20f'], 'static_route_list': None, 'vm_project_uuid': '9105f2cf-b2d4-4265-ba6c-5031da10c1bc', 'admin_state': 'Enabled', 'flow_key_idx': '41', 'allowed_address_pair_list': None, 'ip6_addr': '3f5a:d73:8d40:e58f:1dd6:e846: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-07 18:41:26,104 - DEBUG - Agent 10.0.0.242 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-15412084:ctest-VN3-91424494:ctest-VN3-91424494
2025-08-07 18:41:26,113 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-15412084:ctest-VN3-91424494:ctest-VN3-91424494', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-15412084:ctest-VN3-91424494', 'table_label': '-1', 'vxlan_id': '17', '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-08-07 18:41:26,270 - DEBUG - VM ctest-VM31-42135686 : Tap interface tap9633ad65-c7 is set to Active
2025-08-07 18:41:26,270 - DEBUG - Tap interface tap9633ad65-c7 detail : {'index': '4', 'name': 'tap9633ad65-c7', 'uuid': '9633ad65-c796-4aff-8d8b-aab6e369fb76', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-15412084:ctest-VN3-91424494:ctest-VN3-91424494', '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': '17', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-15412084:ctest-VN3-91424494', 'vm_uuid': '2b2ac87f-b7af-4cfb-956b-f19f0ef0d768', 'vm_name': 'ctest-VM31-42135686', 'ip_addr': '88.116.90.131', 'mac_addr': '02:96:33:ad:65:c7', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.4', 'service_vlan_list': None, 'os_ifindex': '57', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestPolicyAclIpv4v6-15412084:9633ad65-c796-4aff-8d8b-aab6e369fb76', 'sg_uuid_list': ['34bd83ee-ca58-4711-9334-bc5b35c1f20f'], 'static_route_list': None, 'vm_project_uuid': '9105f2cf-b2d4-4265-ba6c-5031da10c1bc', 'admin_state': 'Enabled', 'flow_key_idx': '41', 'allowed_address_pair_list': None, 'ip6_addr': '3f5a:d73:8d40:e58f:1dd6:e846: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-07 18:41:26,444 - DEBUG - Starting Layer 2 verification in Agent
2025-08-07 18:41:26,457 - DEBUG - Layer 2 path is seen for VM MAC 02:96:33:ad:65:c7 in agent 10.0.0.242
2025-08-07 18:41:26,457 - DEBUG - Active layer 2 route in agent is present for VMI tap9633ad65-c7
2025-08-07 18:41:26,457 - DEBUG - L2 label(36) matches bw route table and interface table
2025-08-07 18:41:26,493 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-15412084:ctest-VN3-91424494 is found in Agent of node 10.0.0.242
2025-08-07 18:41:26,665 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-15412084:ctest-VN3-91424494 is consistent in agent 10.0.0.242
2025-08-07 18:41:26,665 - DEBUG - Route for VM IP ['88.116.90.131', '3f5a:d73:8d40:e58f:1dd6:e846:0:3'] is consistent in agent 10.0.0.242
2025-08-07 18:41:26,665 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-15412084:ctest-VN3-91424494 verification for VM ctest-VM31-42135686 in Agent 10.0.0.242 passed
2025-08-07 18:41:26,791 - DEBUG - Starting all layer 2 verification in agent 10.0.0.242
2025-08-07 18:41:26,802 - DEBUG - Route for VM MAC 02:96:33:ad:65:c7 is consistent in agent 10.0.0.242
2025-08-07 18:41:28,037 - 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.41 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.949 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.949/4.181/7.414/3.232 ms')
2025-08-07 18:41:28,037 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-42135686 passed
2025-08-07 18:41:28,038 - INFO - VM ctest-VM31-42135686 verifications in Compute nodes passed
2025-08-07 18:41:28,369 - DEBUG - Validated VM route 88.116.90.131 in vrouter of 10.0.0.242
2025-08-07 18:41:28,374 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0
2025-08-07 18:41:28,375 - INFO - Validated routes of VM ctest-VM31-42135686 in all vrouters
2025-08-07 18:41:29,033 - DEBUG - Starting all layer2 verification in 10.0.0.19 Control Node
2025-08-07 18:41:29,211 - DEBUG - Layer2 route found for VM MAC 02:96:33:ad:65:c7 in Control-node 10.0.0.19
2025-08-07 18:41:29,211 - DEBUG - L2 Label for VM ctest-VM31-42135686 same between Control-node 10.0.0.19 and Agent, Expected: 36, Seen: 36
2025-08-07 18:41:29,381 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.19, not supported
2025-08-07 18:41:29,381 - DEBUG - Starting all layer2 verification in 10.0.0.241 Control Node
2025-08-07 18:41:29,564 - DEBUG - Layer2 route found for VM MAC 02:96:33:ad:65:c7 in Control-node 10.0.0.241
2025-08-07 18:41:29,564 - DEBUG - L2 Label for VM ctest-VM31-42135686 same between Control-node 10.0.0.241 and Agent, Expected: 36, Seen: 36
2025-08-07 18:41:29,732 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.241, not supported
2025-08-07 18:41:29,732 - INFO - Verification in Control-nodes for VM ctest-VM31-42135686 passed
2025-08-07 18:41:29,732 - DEBUG - Verifying the vm in opserver
2025-08-07 18:41:29,733 - DEBUG - Verifying in collector 10.0.0.241 ...
2025-08-07 18:41:29,733 - DEBUG - Requesting: http://10.0.0.241:8081/analytics/uves/virtual-machine/2b2ac87f-b7af-4cfb-956b-f19f0ef0d768?flat
2025-08-07 18:41:29,757 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-15412084:9633ad65-c796-4aff-8d8b-aab6e369fb76?flat
2025-08-07 18:41:29,781 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-15412084:9633ad65-c796-4aff-8d8b-aab6e369fb76?flat
2025-08-07 18:41:29,809 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-15412084:9633ad65-c796-4aff-8d8b-aab6e369fb76?flat
2025-08-07 18:41:29,840 - DEBUG - Requesting: http://10.0.0.241:8081/analytics/uves/virtual-machine/2b2ac87f-b7af-4cfb-956b-f19f0ef0d768?flat
2025-08-07 18:41:29,868 - DEBUG - Verifying vm in vn uve
2025-08-07 18:41:29,868 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-15412084:9633ad65-c796-4aff-8d8b-aab6e369fb76?flat
2025-08-07 18:41:29,892 - DEBUG - VM uve shows interface as {'vhostuser_mode': 0, 'l2_active': True, 'vn_uuid': '61c06e6a-921e-49fe-a1d8-e36e2dc9ab21', '__T': 1754592028006456, 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-15412084:ctest-VN3-91424494', 'port_mirror_enabled': False, 'admin_state': True, 'fixed_ip6_list': ['3f5a:d73:8d40:e58f:1dd6:e846:0:3'], 'mac_address': '02:96:33:ad:65:c7', 'vm_uuid': '2b2ac87f-b7af-4cfb-956b-f19f0ef0d768', 'rx_vlan': 65535, 'vm_name': 'ctest-VM31-42135686', 'label': 32, 'ip_address': '88.116.90.131', 'uuid': '9633ad65-c796-4aff-8d8b-aab6e369fb76', 'fixed_ip4_list': ['88.116.90.131'], 'active': True, 'tx_vlan': 65535, 'is_health_check_active': True, 'ip6_address': '3f5a:d73:8d40:e58f:1dd6:e846:0:3', 'gateway': '88.116.90.129', 'ip4_active': True, 'ip6_active': True}
2025-08-07 18:41:29,892 - DEBUG - VM uve shows ip address as ['88.116.90.131', '3f5a:d73:8d40:e58f:1dd6:e846:0:3']
2025-08-07 18:41:29,892 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-15412084:ctest-VN3-91424494
2025-08-07 18:41:29,892 - INFO - Verifying through opserver in 10.0.0.19
2025-08-07 18:41:29,893 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-15412084:ctest-VN3-91424494?flat
2025-08-07 18:41:29,924 - DEBUG - expected vm list 2b2ac87f-b7af-4cfb-956b-f19f0ef0d768
2025-08-07 18:41:29,924 - DEBUG - Extracted vm list ['2b2ac87f-b7af-4cfb-956b-f19f0ef0d768']
2025-08-07 18:41:29,924 - INFO - VM 2b2ac87f-b7af-4cfb-956b-f19f0ef0d768 is present in default-domain:ctest-TestPolicyAclIpv4v6-15412084:ctest-VN3-91424494
2025-08-07 18:41:29,924 - DEBUG - Verifying vm in vrouter uve
2025-08-07 18:41:29,924 - DEBUG - Getting info from collector 10.0.0.241..
2025-08-07 18:41:29,924 - DEBUG - Requesting: http://10.0.0.241:8081/analytics/uves/virtual-machine/2b2ac87f-b7af-4cfb-956b-f19f0ef0d768?flat
2025-08-07 18:41:29,945 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-3775-2']
2025-08-07 18:41:29,945 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3775-2:Compute:contrail-vrouter-agent:0?flat
2025-08-07 18:41:30,004 - DEBUG - Requesting: http://10.0.0.241:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3775-2?flat
2025-08-07 18:41:30,049 - DEBUG - VM 2b2ac87f-b7af-4cfb-956b-f19f0ef0d768 is present in vrouter an-jenkins-deploy-platform-ansible-os-3775-2 uve
2025-08-07 18:41:30,049 - DEBUG - Validated that VM ctest-VM31-42135686 is in Vrouter an-jenkins-deploy-platform-ansible-os-3775-2 UVE
2025-08-07 18:41:30,049 - DEBUG - Verifying vm tap interface/vn in vrouter uve
2025-08-07 18:41:30,058 - DEBUG - Expected tap interface of VM uuid 2b2ac87f-b7af-4cfb-956b-f19f0ef0d768 is default-domain:ctest-TestPolicyAclIpv4v6-15412084:9633ad65-c796-4aff-8d8b-aab6e369fb76
2025-08-07 18:41:30,059 - DEBUG - Expected VN of VM uuid 2b2ac87f-b7af-4cfb-956b-f19f0ef0d768 is default-domain:ctest-TestPolicyAclIpv4v6-15412084:ctest-VN3-91424494
2025-08-07 18:41:30,059 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3775-2:Compute:contrail-vrouter-agent:0?flat
2025-08-07 18:41:30,108 - DEBUG - Requesting: http://10.0.0.241:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3775-2?flat
2025-08-07 18:41:30,147 - DEBUG - VM 2b2ac87f-b7af-4cfb-956b-f19f0ef0d768 is present in vrouter an-jenkins-deploy-platform-ansible-os-3775-2 uve
2025-08-07 18:41:30,147 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-15412084:9633ad65-c796-4aff-8d8b-aab6e369fb76 of vm 2b2ac87f-b7af-4cfb-956b-f19f0ef0d768 is present in vrouter an-jenkins-deploy-platform-ansible-os-3775-2 uve
2025-08-07 18:41:30,147 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-15412084:ctest-VN3-91424494 of vm 2b2ac87f-b7af-4cfb-956b-f19f0ef0d768 is present in vrouter an-jenkins-deploy-platform-ansible-os-3775-2 uve
2025-08-07 18:41:30,147 - DEBUG - Verifying the 2b2ac87f-b7af-4cfb-956b-f19f0ef0d768 virtual network link through opserver 10.0.0.19
2025-08-07 18:41:30,147 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machines
2025-08-07 18:41:30,163 - DEBUG - VM link and name as {'name': '2b2ac87f-b7af-4cfb-956b-f19f0ef0d768', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-machine/2b2ac87f-b7af-4cfb-956b-f19f0ef0d768?flat'}
2025-08-07 18:41:30,163 - DEBUG - Verifying the 2b2ac87f-b7af-4cfb-956b-f19f0ef0d768 virtual network link through opserver 10.0.0.50
2025-08-07 18:41:30,163 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-machines
2025-08-07 18:41:30,178 - DEBUG - VM link and name as {'name': '2b2ac87f-b7af-4cfb-956b-f19f0ef0d768', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-machine/2b2ac87f-b7af-4cfb-956b-f19f0ef0d768?flat'}
2025-08-07 18:41:30,178 - DEBUG - Verifying the 2b2ac87f-b7af-4cfb-956b-f19f0ef0d768 virtual network link through opserver 10.0.0.241
2025-08-07 18:41:30,178 - DEBUG - Requesting: http://10.0.0.241:8081/analytics/uves/virtual-machines
2025-08-07 18:41:30,195 - DEBUG - VM link and name as {'name': '2b2ac87f-b7af-4cfb-956b-f19f0ef0d768', 'href': 'http://10.0.0.241:8081/analytics/uves/virtual-machine/2b2ac87f-b7af-4cfb-956b-f19f0ef0d768?flat'}
2025-08-07 18:41:30,195 - INFO - VM ctest-VM31-42135686 validations in Opserver passed
2025-08-07 18:41:30,195 - INFO - Waiting for VM ctest-VM11-57552863 to be up..
2025-08-07 18:41:30,195 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-07 18:41:30,195 - DEBUG - Requesting: http://10.0.0.241:8082/virtual-machine/ab2d173f-56e8-432b-b5a4-5d8cf2afc205
2025-08-07 18:41:30,207 - DEBUG - Requesting: http://10.0.0.241:8082/virtual-machine-interface/f93c603e-51ac-423a-b114-6c55d1bc50b4
2025-08-07 18:41:31,433 - 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.6 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.965 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.965/6.263/11.561/5.298 ms')
2025-08-07 18:41:31,433 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-57552863 passed
2025-08-07 18:41:31,553 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-07 18:41:31,553 - DEBUG - Waiting to SSH to VM ctest-VM11-57552863, IP 201.2.65.131, Port 22
2025-08-07 18:41:31,626 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-08-07 18:41:31,732 - DEBUG - VM ctest-VM11-57552863 is NOT ready for SSH connections, VM status: ACTIVE
2025-08-07 18:41:36,732 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-07 18:41:36,733 - DEBUG - Waiting to SSH to VM ctest-VM11-57552863, IP 201.2.65.131, Port 22
2025-08-07 18:41:36,792 - 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-07 18:41:36,895 - DEBUG - VM ctest-VM11-57552863 is NOT ready for SSH connections, VM status: ACTIVE
2025-08-07 18:41:41,896 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-07 18:41:41,896 - DEBUG - Waiting to SSH to VM ctest-VM11-57552863, IP 201.2.65.131, Port 22
2025-08-07 18:41:41,962 - 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-07 18:41:42,053 - DEBUG - VM ctest-VM11-57552863 is NOT ready for SSH connections, VM status: ACTIVE
2025-08-07 18:41:47,054 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-07 18:41:47,054 - DEBUG - Waiting to SSH to VM ctest-VM11-57552863, IP 201.2.65.131, Port 22
2025-08-07 18:41:47,226 - DEBUG - VM ctest-VM11-57552863 is ready for SSH connections
2025-08-07 18:41:47,226 - INFO - Waiting for VM ctest-VM21-61353087 to be up..
2025-08-07 18:41:47,226 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-07 18:41:47,226 - DEBUG - Requesting: http://10.0.0.241:8082/virtual-machine/633586ca-aab1-4c91-9c4b-eb6b2b80950e
2025-08-07 18:41:47,237 - DEBUG - Requesting: http://10.0.0.241:8082/virtual-machine-interface/28259fa0-a633-409b-82bc-3d6897c92f58
2025-08-07 18:41:48,492 - 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=6.39 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.870 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.870/3.628/6.386/2.758 ms')
2025-08-07 18:41:48,492 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-61353087 passed
2025-08-07 18:41:48,618 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-07 18:41:48,618 - DEBUG - Waiting to SSH to VM ctest-VM21-61353087, IP 70.205.40.131, Port 22
2025-08-07 18:41:48,810 - DEBUG - VM ctest-VM21-61353087 is ready for SSH connections
2025-08-07 18:41:48,810 - INFO - Waiting for VM ctest-VM31-42135686 to be up..
2025-08-07 18:41:48,810 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-07 18:41:48,810 - DEBUG - Requesting: http://10.0.0.241:8082/virtual-machine/2b2ac87f-b7af-4cfb-956b-f19f0ef0d768
2025-08-07 18:41:48,821 - DEBUG - Requesting: http://10.0.0.241:8082/virtual-machine-interface/9633ad65-c796-4aff-8d8b-aab6e369fb76
2025-08-07 18:41:50,048 - 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=5.22 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.403 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.403/2.811/5.220/2.408 ms')
2025-08-07 18:41:50,048 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-42135686 passed
2025-08-07 18:41:50,202 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-07 18:41:50,202 - DEBUG - Waiting to SSH to VM ctest-VM31-42135686, IP 88.116.90.131, Port 22
2025-08-07 18:41:50,371 - DEBUG - VM ctest-VM31-42135686 is ready for SSH connections
2025-08-07 18:41:50,371 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 70.205.40.131, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.242, gateway password: c0ntrail123
2025-08-07 18:41:50,372 - DEBUG - ping -s 56 -c 3 -W 1 70.205.40.131
2025-08-07 18:42:14,086 - DEBUG - PING 70.205.40.131 (70.205.40.131) 56(84) bytes of data.
64 bytes from 70.205.40.131: icmp_req=1 ttl=63 time=13.1 ms
64 bytes from 70.205.40.131: icmp_req=2 ttl=63 time=1.96 ms
64 bytes from 70.205.40.131: icmp_req=3 ttl=63 time=1.87 ms
--- 70.205.40.131 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 1.871/5.659/13.146/5.294 ms
2025-08-07 18:42:14,087 - INFO - Ping to IP 70.205.40.131 from VM ctest-VM11-57552863 passed
2025-08-07 18:42:14,087 - DEBUG - Running remote_cmd, Cmd : ping6 -s 56 -c 3 -W 1 2848:7e53:9db6:1349:5946:c201:0:3, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.242, gateway password: c0ntrail123
2025-08-07 18:42:14,087 - DEBUG - ping6 -s 56 -c 3 -W 1 2848:7e53:9db6:1349:5946:c201:0:3
2025-08-07 18:42:16,699 - DEBUG - PING 2848:7e53:9db6:1349:5946:c201:0:3(2848:7e53:9db6:1349:5946:c201:0:3) 56 data bytes
64 bytes from 2848:7e53:9db6:1349:5946:c201:0:3: icmp_seq=1 ttl=64 time=15.7 ms
64 bytes from 2848:7e53:9db6:1349:5946:c201:0:3: icmp_seq=2 ttl=64 time=2.06 ms
64 bytes from 2848:7e53:9db6:1349:5946:c201:0:3: icmp_seq=3 ttl=64 time=1.91 ms
--- 2848:7e53:9db6:1349:5946:c201:0:3 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2005ms
rtt min/avg/max/mdev = 1.914/6.564/15.717/6.472 ms
2025-08-07 18:42:16,699 - INFO - Ping to IP 2848:7e53:9db6:1349:5946:c201:0:3 from VM ctest-VM11-57552863 passed
2025-08-07 18:42:16,699 - INFO - Test with src as policy and dst as any PASSED
2025-08-07 18:42:16,699 - INFO - Deleting VM ctest-VM31-42135686
2025-08-07 18:42:16,786 - DEBUG - Verifying in api server 10.0.0.241
2025-08-07 18:42:16,786 - DEBUG - Requesting: http://10.0.0.241:8082/virtual-machine/2b2ac87f-b7af-4cfb-956b-f19f0ef0d768
2025-08-07 18:42:16,798 - DEBUG - VM ID 2b2ac87f-b7af-4cfb-956b-f19f0ef0d768 of VM ctest-VM31-42135686 is still found in API Server
2025-08-07 18:42:18,799 - DEBUG - Verifying in api server 10.0.0.241
2025-08-07 18:42:18,799 - DEBUG - Requesting: http://10.0.0.241:8082/virtual-machine/2b2ac87f-b7af-4cfb-956b-f19f0ef0d768
2025-08-07 18:42:18,809 - DEBUG - Response Code: 404
2025-08-07 18:42:18,809 - DEBUG - Requesting: http://10.0.0.241:8082/virtual-machine/2b2ac87f-b7af-4cfb-956b-f19f0ef0d768
2025-08-07 18:42:18,819 - DEBUG - Response Code: 404
2025-08-07 18:42:18,819 - DEBUG - Requesting: http://10.0.0.241:8082/virtual-machine/2b2ac87f-b7af-4cfb-956b-f19f0ef0d768
2025-08-07 18:42:18,828 - DEBUG - Response Code: 404
2025-08-07 18:42:18,828 - INFO - VM ctest-VM31-42135686 is fully removed in API-Server
2025-08-07 18:42:18,987 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2025-08-07 18:42:19,066 - DEBUG - Validated that vrouter 10.0.0.65 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-15412084:ctest-VN3-91424494
2025-08-07 18:42:19,096 - DEBUG - Validated that vrouter 10.0.0.242 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-15412084:ctest-VN3-91424494
2025-08-07 18:42:19,096 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-15412084:ctest-VN3-91424494
2025-08-07 18:42:19,096 - INFO - VM ctest-VM31-42135686 is removed in Compute, and routes are removed in all compute nodes
2025-08-07 18:42:19,391 - INFO - Routes for VM ctest-VM31-42135686 is removed in all control-nodes
2025-08-07 18:42:19,391 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3775-2:Compute:contrail-vrouter-agent:0?flat
2025-08-07 18:42:19,437 - DEBUG - Requesting: http://10.0.0.241:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3775-2?flat
2025-08-07 18:42:19,483 - DEBUG - VM 2b2ac87f-b7af-4cfb-956b-f19f0ef0d768 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3775-2 uve
2025-08-07 18:42:19,483 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-07 18:42:19,483 - DEBUG - interface for vm 2b2ac87f-b7af-4cfb-956b-f19f0ef0d768 deleted from vrouter uve ...
2025-08-07 18:42:19,483 - DEBUG - Deleted interface not in error interface list ...
2025-08-07 18:42:22,484 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3775-2:Compute:contrail-vrouter-agent:0?flat
2025-08-07 18:42:22,531 - DEBUG - Requesting: http://10.0.0.241:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3775-2?flat
2025-08-07 18:42:22,572 - DEBUG - VM 2b2ac87f-b7af-4cfb-956b-f19f0ef0d768 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3775-2 uve
2025-08-07 18:42:22,572 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-07 18:42:22,572 - DEBUG - interface for vm 2b2ac87f-b7af-4cfb-956b-f19f0ef0d768 deleted from vrouter uve ...
2025-08-07 18:42:22,572 - DEBUG - Deleted interface not in error interface list ...
2025-08-07 18:42:25,573 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3775-2:Compute:contrail-vrouter-agent:0?flat
2025-08-07 18:42:25,619 - DEBUG - Requesting: http://10.0.0.241:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3775-2?flat
2025-08-07 18:42:25,663 - DEBUG - VM 2b2ac87f-b7af-4cfb-956b-f19f0ef0d768 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3775-2 uve
2025-08-07 18:42:25,663 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-07 18:42:25,663 - DEBUG - interface for vm 2b2ac87f-b7af-4cfb-956b-f19f0ef0d768 deleted from vrouter uve ...
2025-08-07 18:42:25,663 - DEBUG - Deleted interface not in error interface list ...
2025-08-07 18:42:28,664 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3775-2:Compute:contrail-vrouter-agent:0?flat
2025-08-07 18:42:28,717 - DEBUG - Requesting: http://10.0.0.241:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3775-2?flat
2025-08-07 18:42:28,758 - DEBUG - VM 2b2ac87f-b7af-4cfb-956b-f19f0ef0d768 is not present in vrouter an-jenkins-deploy-platform-ansible-os-3775-2 uve
2025-08-07 18:42:28,758 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-07 18:42:28,758 - DEBUG - interface for vm 2b2ac87f-b7af-4cfb-956b-f19f0ef0d768 deleted from vrouter uve ...
2025-08-07 18:42:28,758 - DEBUG - Deleted interface not in error interface list ...
2025-08-07 18:42:28,759 - DEBUG - Validated that VM 2b2ac87f-b7af-4cfb-956b-f19f0ef0d768 is removed in Vrouter UVE
2025-08-07 18:42:28,759 - DEBUG - Verifying through opserver in 10.0.0.19
2025-08-07 18:42:28,759 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-15412084:ctest-VN3-91424494?flat
2025-08-07 18:42:28,782 - DEBUG - 2b2ac87f-b7af-4cfb-956b-f19f0ef0d768 BM not in default-domain:ctest-TestPolicyAclIpv4v6-15412084:ctest-VN3-91424494 uve
2025-08-07 18:42:28,782 - DEBUG - Validated that VM 2b2ac87f-b7af-4cfb-956b-f19f0ef0d768 is not present in the VN UVE
2025-08-07 18:42:28,782 - DEBUG - Verifying the 2b2ac87f-b7af-4cfb-956b-f19f0ef0d768 virtual network link through opserver 10.0.0.19
2025-08-07 18:42:28,782 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machines
2025-08-07 18:42:28,798 - INFO - 2b2ac87f-b7af-4cfb-956b-f19f0ef0d768 vm uve deleted from opserver
2025-08-07 18:42:28,798 - DEBUG - Verifying the 2b2ac87f-b7af-4cfb-956b-f19f0ef0d768 virtual network link through opserver 10.0.0.50
2025-08-07 18:42:28,798 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-machines
2025-08-07 18:42:28,823 - INFO - 2b2ac87f-b7af-4cfb-956b-f19f0ef0d768 vm uve deleted from opserver
2025-08-07 18:42:28,823 - DEBUG - Verifying the 2b2ac87f-b7af-4cfb-956b-f19f0ef0d768 virtual network link through opserver 10.0.0.241
2025-08-07 18:42:28,823 - DEBUG - Requesting: http://10.0.0.241:8081/analytics/uves/virtual-machines
2025-08-07 18:42:28,850 - INFO - 2b2ac87f-b7af-4cfb-956b-f19f0ef0d768 vm uve deleted from opserver
2025-08-07 18:42:28,850 - INFO - Validated that VM 2b2ac87f-b7af-4cfb-956b-f19f0ef0d768 is removed from Opserver
2025-08-07 18:42:28,850 - INFO - Deleting VM ctest-VM21-61353087
2025-08-07 18:42:28,930 - DEBUG - Verifying in api server 10.0.0.241
2025-08-07 18:42:28,930 - DEBUG - Requesting: http://10.0.0.241:8082/virtual-machine/633586ca-aab1-4c91-9c4b-eb6b2b80950e
2025-08-07 18:42:28,943 - DEBUG - VM ID 633586ca-aab1-4c91-9c4b-eb6b2b80950e of VM ctest-VM21-61353087 is still found in API Server
2025-08-07 18:42:30,943 - DEBUG - Verifying in api server 10.0.0.241
2025-08-07 18:42:30,944 - DEBUG - Requesting: http://10.0.0.241:8082/virtual-machine/633586ca-aab1-4c91-9c4b-eb6b2b80950e
2025-08-07 18:42:30,952 - DEBUG - Response Code: 404
2025-08-07 18:42:30,952 - DEBUG - Requesting: http://10.0.0.241:8082/virtual-machine/633586ca-aab1-4c91-9c4b-eb6b2b80950e
2025-08-07 18:42:30,958 - DEBUG - Response Code: 404
2025-08-07 18:42:30,959 - DEBUG - Requesting: http://10.0.0.241:8082/virtual-machine/633586ca-aab1-4c91-9c4b-eb6b2b80950e
2025-08-07 18:42:30,971 - DEBUG - Response Code: 404
2025-08-07 18:42:30,971 - INFO - VM ctest-VM21-61353087 is fully removed in API-Server
2025-08-07 18:42:31,127 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2025-08-07 18:42:31,219 - DEBUG - Validated that vrouter 10.0.0.65 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-15412084:ctest-VN2-63110633
2025-08-07 18:42:31,237 - DEBUG - Validated that vrouter 10.0.0.242 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-15412084:ctest-VN2-63110633
2025-08-07 18:42:31,237 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-15412084:ctest-VN2-63110633
2025-08-07 18:42:31,238 - INFO - VM ctest-VM21-61353087 is removed in Compute, and routes are removed in all compute nodes
2025-08-07 18:42:31,530 - INFO - Routes for VM ctest-VM21-61353087 is removed in all control-nodes
2025-08-07 18:42:31,530 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3775-1:Compute:contrail-vrouter-agent:0?flat
2025-08-07 18:42:31,582 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3775-1?flat
2025-08-07 18:42:31,623 - DEBUG - VM 633586ca-aab1-4c91-9c4b-eb6b2b80950e is not present in vrouter an-jenkins-deploy-platform-ansible-os-3775-1 uve
2025-08-07 18:42:31,623 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-07 18:42:31,623 - DEBUG - interface for vm 633586ca-aab1-4c91-9c4b-eb6b2b80950e deleted from vrouter uve ...
2025-08-07 18:42:31,623 - DEBUG - Deleted interface not in error interface list ...
2025-08-07 18:42:31,623 - DEBUG - Validated that VM 633586ca-aab1-4c91-9c4b-eb6b2b80950e is removed in Vrouter UVE
2025-08-07 18:42:31,623 - DEBUG - Verifying through opserver in 10.0.0.19
2025-08-07 18:42:31,623 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-15412084:ctest-VN2-63110633?flat
2025-08-07 18:42:31,651 - DEBUG - 633586ca-aab1-4c91-9c4b-eb6b2b80950e BM not in default-domain:ctest-TestPolicyAclIpv4v6-15412084:ctest-VN2-63110633 uve
2025-08-07 18:42:31,651 - DEBUG - Validated that VM 633586ca-aab1-4c91-9c4b-eb6b2b80950e is not present in the VN UVE
2025-08-07 18:42:31,651 - DEBUG - Verifying the 633586ca-aab1-4c91-9c4b-eb6b2b80950e virtual network link through opserver 10.0.0.19
2025-08-07 18:42:31,651 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machines
2025-08-07 18:42:31,666 - INFO - 633586ca-aab1-4c91-9c4b-eb6b2b80950e vm uve deleted from opserver
2025-08-07 18:42:31,666 - DEBUG - Verifying the 633586ca-aab1-4c91-9c4b-eb6b2b80950e virtual network link through opserver 10.0.0.50
2025-08-07 18:42:31,667 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-machines
2025-08-07 18:42:31,681 - INFO - 633586ca-aab1-4c91-9c4b-eb6b2b80950e vm uve deleted from opserver
2025-08-07 18:42:31,681 - DEBUG - Verifying the 633586ca-aab1-4c91-9c4b-eb6b2b80950e virtual network link through opserver 10.0.0.241
2025-08-07 18:42:31,682 - DEBUG - Requesting: http://10.0.0.241:8081/analytics/uves/virtual-machines
2025-08-07 18:42:31,697 - INFO - 633586ca-aab1-4c91-9c4b-eb6b2b80950e vm uve deleted from opserver
2025-08-07 18:42:31,697 - INFO - Validated that VM 633586ca-aab1-4c91-9c4b-eb6b2b80950e is removed from Opserver
2025-08-07 18:42:31,697 - INFO - Deleting VM ctest-VM11-57552863
2025-08-07 18:42:31,799 - DEBUG - Verifying in api server 10.0.0.241
2025-08-07 18:42:31,799 - DEBUG - Requesting: http://10.0.0.241:8082/virtual-machine/ab2d173f-56e8-432b-b5a4-5d8cf2afc205
2025-08-07 18:42:31,809 - DEBUG - VM ID ab2d173f-56e8-432b-b5a4-5d8cf2afc205 of VM ctest-VM11-57552863 is still found in API Server
2025-08-07 18:42:33,810 - DEBUG - Verifying in api server 10.0.0.241
2025-08-07 18:42:33,810 - DEBUG - Requesting: http://10.0.0.241:8082/virtual-machine/ab2d173f-56e8-432b-b5a4-5d8cf2afc205
2025-08-07 18:42:33,819 - DEBUG - Response Code: 404
2025-08-07 18:42:33,819 - DEBUG - Requesting: http://10.0.0.241:8082/virtual-machine/ab2d173f-56e8-432b-b5a4-5d8cf2afc205
2025-08-07 18:42:33,829 - DEBUG - Response Code: 404
2025-08-07 18:42:33,829 - DEBUG - Requesting: http://10.0.0.241:8082/virtual-machine/ab2d173f-56e8-432b-b5a4-5d8cf2afc205
2025-08-07 18:42:33,836 - DEBUG - Response Code: 404
2025-08-07 18:42:33,837 - INFO - VM ctest-VM11-57552863 is fully removed in API-Server
2025-08-07 18:42:33,991 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2025-08-07 18:42:34,052 - DEBUG - Validated that vrouter 10.0.0.65 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-15412084:ctest-VN1-66555417
2025-08-07 18:42:34,076 - DEBUG - Validated that vrouter 10.0.0.242 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-15412084:ctest-VN1-66555417
2025-08-07 18:42:34,076 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-15412084:ctest-VN1-66555417
2025-08-07 18:42:34,076 - INFO - VM ctest-VM11-57552863 is removed in Compute, and routes are removed in all compute nodes
2025-08-07 18:42:34,333 - INFO - Routes for VM ctest-VM11-57552863 is removed in all control-nodes
2025-08-07 18:42:34,333 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3775-2:Compute:contrail-vrouter-agent:0?flat
2025-08-07 18:42:34,378 - DEBUG - Requesting: http://10.0.0.241:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3775-2?flat
2025-08-07 18:42:34,415 - DEBUG - VM ab2d173f-56e8-432b-b5a4-5d8cf2afc205 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3775-2 uve
2025-08-07 18:42:34,415 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-07 18:42:34,415 - DEBUG - interface for vm ab2d173f-56e8-432b-b5a4-5d8cf2afc205 deleted from vrouter uve ...
2025-08-07 18:42:34,415 - DEBUG - Deleted interface not in error interface list ...
2025-08-07 18:42:37,416 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3775-2:Compute:contrail-vrouter-agent:0?flat
2025-08-07 18:42:37,463 - DEBUG - Requesting: http://10.0.0.241:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3775-2?flat
2025-08-07 18:42:37,502 - DEBUG - VM ab2d173f-56e8-432b-b5a4-5d8cf2afc205 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3775-2 uve
2025-08-07 18:42:37,502 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-07 18:42:37,502 - DEBUG - interface for vm ab2d173f-56e8-432b-b5a4-5d8cf2afc205 deleted from vrouter uve ...
2025-08-07 18:42:37,502 - DEBUG - Deleted interface not in error interface list ...
2025-08-07 18:42:40,504 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3775-2:Compute:contrail-vrouter-agent:0?flat
2025-08-07 18:42:40,547 - DEBUG - Requesting: http://10.0.0.241:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3775-2?flat
2025-08-07 18:42:40,585 - DEBUG - VM ab2d173f-56e8-432b-b5a4-5d8cf2afc205 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3775-2 uve
2025-08-07 18:42:40,585 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-07 18:42:40,585 - DEBUG - interface for vm ab2d173f-56e8-432b-b5a4-5d8cf2afc205 deleted from vrouter uve ...
2025-08-07 18:42:40,585 - DEBUG - Deleted interface not in error interface list ...
2025-08-07 18:42:43,586 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3775-2:Compute:contrail-vrouter-agent:0?flat
2025-08-07 18:42:43,641 - DEBUG - Requesting: http://10.0.0.241:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3775-2?flat
2025-08-07 18:42:43,688 - DEBUG - VM ab2d173f-56e8-432b-b5a4-5d8cf2afc205 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3775-2 uve
2025-08-07 18:42:43,688 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-07 18:42:43,688 - DEBUG - interface for vm ab2d173f-56e8-432b-b5a4-5d8cf2afc205 deleted from vrouter uve ...
2025-08-07 18:42:43,688 - DEBUG - Deleted interface not in error interface list ...
2025-08-07 18:42:46,689 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3775-2:Compute:contrail-vrouter-agent:0?flat
2025-08-07 18:42:46,733 - DEBUG - Requesting: http://10.0.0.241:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3775-2?flat
2025-08-07 18:42:46,777 - DEBUG - VM ab2d173f-56e8-432b-b5a4-5d8cf2afc205 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3775-2 uve
2025-08-07 18:42:46,778 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-07 18:42:46,778 - DEBUG - interface for vm ab2d173f-56e8-432b-b5a4-5d8cf2afc205 deleted from vrouter uve ...
2025-08-07 18:42:46,778 - DEBUG - Deleted interface not in error interface list ...
2025-08-07 18:42:49,779 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3775-2:Compute:contrail-vrouter-agent:0?flat
2025-08-07 18:42:49,827 - DEBUG - Requesting: http://10.0.0.241:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3775-2?flat
2025-08-07 18:42:49,868 - DEBUG - VM ab2d173f-56e8-432b-b5a4-5d8cf2afc205 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3775-2 uve
2025-08-07 18:42:49,868 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-07 18:42:49,868 - DEBUG - interface for vm ab2d173f-56e8-432b-b5a4-5d8cf2afc205 deleted from vrouter uve ...
2025-08-07 18:42:49,868 - DEBUG - Deleted interface not in error interface list ...
2025-08-07 18:42:52,869 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3775-2:Compute:contrail-vrouter-agent:0?flat
2025-08-07 18:42:52,914 - DEBUG - Requesting: http://10.0.0.241:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3775-2?flat
2025-08-07 18:42:52,954 - DEBUG - VM ab2d173f-56e8-432b-b5a4-5d8cf2afc205 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3775-2 uve
2025-08-07 18:42:52,954 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-07 18:42:52,954 - DEBUG - interface for vm ab2d173f-56e8-432b-b5a4-5d8cf2afc205 deleted from vrouter uve ...
2025-08-07 18:42:52,954 - DEBUG - Deleted interface not in error interface list ...
2025-08-07 18:42:55,955 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3775-2:Compute:contrail-vrouter-agent:0?flat
2025-08-07 18:42:56,036 - DEBUG - Requesting: http://10.0.0.241:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3775-2?flat
2025-08-07 18:42:56,075 - DEBUG - VM ab2d173f-56e8-432b-b5a4-5d8cf2afc205 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3775-2 uve
2025-08-07 18:42:56,075 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-07 18:42:56,075 - DEBUG - interface for vm ab2d173f-56e8-432b-b5a4-5d8cf2afc205 deleted from vrouter uve ...
2025-08-07 18:42:56,075 - DEBUG - Deleted interface not in error interface list ...
2025-08-07 18:42:59,076 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3775-2:Compute:contrail-vrouter-agent:0?flat
2025-08-07 18:42:59,123 - DEBUG - Requesting: http://10.0.0.241:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3775-2?flat
2025-08-07 18:42:59,163 - DEBUG - VM ab2d173f-56e8-432b-b5a4-5d8cf2afc205 is not present in vrouter an-jenkins-deploy-platform-ansible-os-3775-2 uve
2025-08-07 18:42:59,163 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-07 18:42:59,163 - DEBUG - interface for vm ab2d173f-56e8-432b-b5a4-5d8cf2afc205 deleted from vrouter uve ...
2025-08-07 18:42:59,163 - DEBUG - Deleted interface not in error interface list ...
2025-08-07 18:42:59,163 - DEBUG - Validated that VM ab2d173f-56e8-432b-b5a4-5d8cf2afc205 is removed in Vrouter UVE
2025-08-07 18:42:59,163 - DEBUG - Verifying through opserver in 10.0.0.19
2025-08-07 18:42:59,163 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-15412084:ctest-VN1-66555417?flat
2025-08-07 18:42:59,186 - DEBUG - ab2d173f-56e8-432b-b5a4-5d8cf2afc205 BM not in default-domain:ctest-TestPolicyAclIpv4v6-15412084:ctest-VN1-66555417 uve
2025-08-07 18:42:59,186 - DEBUG - Validated that VM ab2d173f-56e8-432b-b5a4-5d8cf2afc205 is not present in the VN UVE
2025-08-07 18:42:59,186 - DEBUG - Verifying the ab2d173f-56e8-432b-b5a4-5d8cf2afc205 virtual network link through opserver 10.0.0.19
2025-08-07 18:42:59,186 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machines
2025-08-07 18:42:59,201 - DEBUG - No links retuned for ab2d173f-56e8-432b-b5a4-5d8cf2afc205
2025-08-07 18:42:59,201 - INFO - ab2d173f-56e8-432b-b5a4-5d8cf2afc205 vm uve deleted from opserver
2025-08-07 18:42:59,201 - DEBUG - Verifying the ab2d173f-56e8-432b-b5a4-5d8cf2afc205 virtual network link through opserver 10.0.0.50
2025-08-07 18:42:59,201 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-machines
2025-08-07 18:42:59,218 - DEBUG - No links retuned for ab2d173f-56e8-432b-b5a4-5d8cf2afc205
2025-08-07 18:42:59,218 - INFO - ab2d173f-56e8-432b-b5a4-5d8cf2afc205 vm uve deleted from opserver
2025-08-07 18:42:59,219 - DEBUG - Verifying the ab2d173f-56e8-432b-b5a4-5d8cf2afc205 virtual network link through opserver 10.0.0.241
2025-08-07 18:42:59,219 - DEBUG - Requesting: http://10.0.0.241:8081/analytics/uves/virtual-machines
2025-08-07 18:42:59,234 - DEBUG - No links retuned for ab2d173f-56e8-432b-b5a4-5d8cf2afc205
2025-08-07 18:42:59,234 - INFO - ab2d173f-56e8-432b-b5a4-5d8cf2afc205 vm uve deleted from opserver
2025-08-07 18:42:59,234 - INFO - Validated that VM ab2d173f-56e8-432b-b5a4-5d8cf2afc205 is removed from Opserver
2025-08-07 18:42:59,234 - DEBUG - Detaching the Policy for VN :ctest-VN2-63110633
2025-08-07 18:42:59,234 - DEBUG - Requesting: http://10.0.0.19:8082/domains
2025-08-07 18:42:59,243 - DEBUG - Requesting: http://10.0.0.19:8082/domain/e058ca0e-02ca-4506-8888-1a040ca13521
2025-08-07 18:42:59,264 - DEBUG - Requesting: http://10.0.0.19:8082/project/9105f2cf-b2d4-4265-ba6c-5031da10c1bc
2025-08-07 18:42:59,388 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/29fc3409-f996-4717-b9e7-ae65129d6b8d
2025-08-07 18:42:59,414 - DEBUG - Requesting: http://10.0.0.19:8082/network-policy/05099f1b-1461-4864-af9c-35ae3276631d
2025-08-07 18:42:59,700 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-15412084', 'ctest-policy21-86130260']] from ctest-VN2-63110633
2025-08-07 18:42:59,701 - DEBUG - Detaching the Policy for VN :ctest-VN1-66555417
2025-08-07 18:42:59,701 - DEBUG - Requesting: http://10.0.0.19:8082/domains
2025-08-07 18:42:59,715 - DEBUG - Requesting: http://10.0.0.19:8082/domain/e058ca0e-02ca-4506-8888-1a040ca13521
2025-08-07 18:42:59,735 - DEBUG - Requesting: http://10.0.0.19:8082/project/9105f2cf-b2d4-4265-ba6c-5031da10c1bc
2025-08-07 18:42:59,841 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/c97ff62b-f754-4e28-aeb5-74195bb7490f
2025-08-07 18:42:59,875 - DEBUG - Requesting: http://10.0.0.19:8082/network-policy/92bf4204-46ea-4f64-9e96-cb9bb96a814c
2025-08-07 18:42:59,886 - DEBUG - Requesting: http://10.0.0.19:8082/network-policy/762e10ee-e52e-4168-a667-9d681aa1a338
2025-08-07 18:43:00,163 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-15412084', 'ctest-policy12-08826368'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-15412084', 'ctest-policy13-51762643']] from ctest-VN1-66555417
2025-08-07 18:43:00,214 - INFO - Deleted policy ctest-policy21-86130260
2025-08-07 18:43:00,271 - INFO - Deleted policy ctest-policy12-08826368
2025-08-07 18:43:00,342 - INFO - Deleted policy ctest-policy13-51762643
2025-08-07 18:43:00,343 - INFO - Deleting VN ctest-VN3-91424494
2025-08-07 18:43:00,683 - DEBUG - Response for deleting network ()
2025-08-07 18:43:00,683 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/1dd828f7-eb63-4738-9321-3d53f2f79902
2025-08-07 18:43:00,692 - DEBUG - Response Code: 404
2025-08-07 18:43:00,693 - DEBUG - Requesting: http://10.0.0.19:8082/domains
2025-08-07 18:43:00,713 - DEBUG - Requesting: http://10.0.0.19:8082/domain/e058ca0e-02ca-4506-8888-1a040ca13521
2025-08-07 18:43:00,735 - DEBUG - Requesting: http://10.0.0.19:8082/project/9105f2cf-b2d4-4265-ba6c-5031da10c1bc
2025-08-07 18:43:00,849 - INFO - Validated that VN ctest-VN3-91424494 is not found in API Server
2025-08-07 18:43:00,882 - DEBUG - VN ctest-VN3-91424494 is not present in Agent 10.0.0.65
2025-08-07 18:43:00,907 - DEBUG - VN ctest-VN3-91424494 is not present in Agent 10.0.0.242
2025-08-07 18:43:00,907 - INFO - Validated that VN ctest-VN3-91424494 is not in any agent
2025-08-07 18:43:00,957 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN3-91424494 info
2025-08-07 18:43:00,957 - INFO - Deleting VN ctest-VN2-63110633
2025-08-07 18:43:01,275 - DEBUG - Response for deleting network ()
2025-08-07 18:43:01,275 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/c054a410-fd95-4a68-905c-325bb3838711
2025-08-07 18:43:01,283 - DEBUG - Response Code: 404
2025-08-07 18:43:01,283 - DEBUG - Requesting: http://10.0.0.19:8082/domains
2025-08-07 18:43:01,294 - DEBUG - Requesting: http://10.0.0.19:8082/domain/e058ca0e-02ca-4506-8888-1a040ca13521
2025-08-07 18:43:01,349 - DEBUG - Requesting: http://10.0.0.19:8082/project/9105f2cf-b2d4-4265-ba6c-5031da10c1bc
2025-08-07 18:43:01,493 - INFO - Validated that VN ctest-VN2-63110633 is not found in API Server
2025-08-07 18:43:01,524 - DEBUG - VN ctest-VN2-63110633 is not present in Agent 10.0.0.65
2025-08-07 18:43:01,538 - DEBUG - VN ctest-VN2-63110633 is not present in Agent 10.0.0.242
2025-08-07 18:43:01,538 - INFO - Validated that VN ctest-VN2-63110633 is not in any agent
2025-08-07 18:43:01,584 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN2-63110633 info
2025-08-07 18:43:01,584 - INFO - Deleting VN ctest-VN1-66555417
2025-08-07 18:43:01,833 - DEBUG - Response for deleting network ()
2025-08-07 18:43:01,833 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/940d0c89-af89-4713-ac3e-2b92d0ab0214
2025-08-07 18:43:01,843 - DEBUG - Response Code: 404
2025-08-07 18:43:01,843 - DEBUG - Requesting: http://10.0.0.19:8082/domains
2025-08-07 18:43:01,869 - DEBUG - Requesting: http://10.0.0.19:8082/domain/e058ca0e-02ca-4506-8888-1a040ca13521
2025-08-07 18:43:01,890 - DEBUG - Requesting: http://10.0.0.19:8082/project/9105f2cf-b2d4-4265-ba6c-5031da10c1bc
2025-08-07 18:43:02,018 - INFO - Validated that VN ctest-VN1-66555417 is not found in API Server
2025-08-07 18:43:02,038 - DEBUG - VN ctest-VN1-66555417 is not present in Agent 10.0.0.65
2025-08-07 18:43:02,056 - DEBUG - VN ctest-VN1-66555417 is not present in Agent 10.0.0.242
2025-08-07 18:43:02,056 - INFO - Validated that VN ctest-VN1-66555417 is not in any agent
2025-08-07 18:43:02,116 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN1-66555417 info
2025-08-07 18:43:02,287 - INFO - IPAM: ipam3 is not found in API Server
2025-08-07 18:43:02,330 - INFO - IPAM:ipam3 is not found in control node
2025-08-07 18:43:02,455 - INFO - IPAM: ipam2 is not found in API Server
2025-08-07 18:43:02,501 - INFO - IPAM:ipam2 is not found in control node
2025-08-07 18:43:02,618 - INFO - IPAM: ipam1 is not found in API Server
2025-08-07 18:43:02,659 - INFO - IPAM:ipam1 is not found in control node
2025-08-07 18:43:04,016 - DEBUG - No XMPP flaps were noticed during the test
2025-08-07 18:43:04,016 - INFO - END TEST : test_policy_inheritance_src_pol_dst_any : PASSED[0:02:56]
2025-08-07 18:43:04,016 - INFO - --------------------------------------------------------------------------------
2025-08-07 18:43:05,365 - DEBUG - No XMPP flaps were noticed during the test
2025-08-07 18:43:05,365 - INFO - END TEST : test_policy_inheritance_src_pol_dst_any : PASSED[0:02:58]
2025-08-07 18:43:05,365 - INFO - --------------------------------------------------------------------------------
2025-08-07 18:43:06,242 - INFO - Deleted project: ctest-TestPolicyAclIpv4v6-15412084, ID : 9105f2cf-b2d4-4265-ba6c-5031da10c1bc