is in ACTIVE state now
2025-11-17 02:37:12,651 - DEBUG - VM ctest-VM31-96407689 launched on Node an-jenkins-deploy-platform-ansible-os-4736-1
2025-11-17 02:37:12,749 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/342bbcbe-2a8d-4afa-b32e-2178a67ba2be
2025-11-17 02:37:12,761 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/342bbcbe-2a8d-4afa-b32e-2178a67ba2be
2025-11-17 02:37:12,774 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/1f0b9a0a-885b-4ab5-a390-e0daeb21d5e0
2025-11-17 02:37:12,787 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/342bbcbe-2a8d-4afa-b32e-2178a67ba2be
2025-11-17 02:37:12,797 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/1f0b9a0a-885b-4ab5-a390-e0daeb21d5e0
2025-11-17 02:37:12,810 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/518e7f67-91cd-41b3-90c2-4cf4713c5468
2025-11-17 02:37:12,822 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/73785266-147b-442f-86ae-b2524c3410e5
2025-11-17 02:37:12,832 - DEBUG - Verifying in api server 10.0.0.23
2025-11-17 02:37:12,832 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/342bbcbe-2a8d-4afa-b32e-2178a67ba2be
2025-11-17 02:37:12,840 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/1f0b9a0a-885b-4ab5-a390-e0daeb21d5e0
2025-11-17 02:37:12,853 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/518e7f67-91cd-41b3-90c2-4cf4713c5468
2025-11-17 02:37:12,862 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/73785266-147b-442f-86ae-b2524c3410e5
2025-11-17 02:37:12,872 - INFO - VM ctest-VM31-96407689 verfication in all API Servers passed
2025-11-17 02:37:12,896 - DEBUG - VM ctest-VM31-96407689 Tap interface: {'index': '4', 'name': 'tap1f0b9a0a-88', 'uuid': '1f0b9a0a-885b-4ab5-a390-e0daeb21d5e0', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-10055946:ctest-VN3-40669289:ctest-VN3-40669289', '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-10055946:ctest-VN3-40669289', 'vm_uuid': '342bbcbe-2a8d-4afa-b32e-2178a67ba2be', 'vm_name': 'ctest-VM31-96407689', 'ip_addr': '129.151.152.131', 'mac_addr': '02:1f:0b:9a:0a:88', '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-10055946:1f0b9a0a-885b-4ab5-a390-e0daeb21d5e0', 'sg_uuid_list': ['edfab288-a52b-41e8-95f0-639149a2b8bb'], 'static_route_list': None, 'vm_project_uuid': 'deb0e48c-e743-45d6-a228-d4a0bbfb469f', 'admin_state': 'Enabled', 'flow_key_idx': '38', 'allowed_address_pair_list': None, 'ip6_addr': '3435:8ea4:b6c2:7577:5410:8890: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-11-17 02:37:12,896 - DEBUG - Agent 10.0.0.50 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-10055946:ctest-VN3-40669289:ctest-VN3-40669289
2025-11-17 02:37:12,903 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-10055946:ctest-VN3-40669289:ctest-VN3-40669289', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-10055946:ctest-VN3-40669289', '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-11-17 02:37:13,004 - DEBUG - VM ctest-VM31-96407689 : Tap interface tap1f0b9a0a-88 is set to Active
2025-11-17 02:37:13,004 - DEBUG - Tap interface tap1f0b9a0a-88 detail : {'index': '4', 'name': 'tap1f0b9a0a-88', 'uuid': '1f0b9a0a-885b-4ab5-a390-e0daeb21d5e0', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-10055946:ctest-VN3-40669289:ctest-VN3-40669289', '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-10055946:ctest-VN3-40669289', 'vm_uuid': '342bbcbe-2a8d-4afa-b32e-2178a67ba2be', 'vm_name': 'ctest-VM31-96407689', 'ip_addr': '129.151.152.131', 'mac_addr': '02:1f:0b:9a:0a:88', '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-10055946:1f0b9a0a-885b-4ab5-a390-e0daeb21d5e0', 'sg_uuid_list': ['edfab288-a52b-41e8-95f0-639149a2b8bb'], 'static_route_list': None, 'vm_project_uuid': 'deb0e48c-e743-45d6-a228-d4a0bbfb469f', 'admin_state': 'Enabled', 'flow_key_idx': '38', 'allowed_address_pair_list': None, 'ip6_addr': '3435:8ea4:b6c2:7577:5410:8890: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-11-17 02:37:13,128 - DEBUG - Starting Layer 2 verification in Agent
2025-11-17 02:37:13,143 - DEBUG - Layer 2 path is seen for VM MAC 02:1f:0b:9a:0a:88 in agent 10.0.0.50
2025-11-17 02:37:13,143 - DEBUG - Active layer 2 route in agent is present for VMI tap1f0b9a0a-88
2025-11-17 02:37:13,143 - DEBUG - L2 label(36) matches bw route table and interface table
2025-11-17 02:37:13,152 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-10055946:ctest-VN3-40669289 is found in Agent of node 10.0.0.50
2025-11-17 02:37:13,288 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-10055946:ctest-VN3-40669289 is consistent in agent 10.0.0.50
2025-11-17 02:37:13,288 - DEBUG - Route for VM IP ['3435:8ea4:b6c2:7577:5410:8890:0:3', '129.151.152.131'] is consistent in agent 10.0.0.50
2025-11-17 02:37:13,288 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-10055946:ctest-VN3-40669289 verification for VM ctest-VM31-96407689 in Agent 10.0.0.50 passed
2025-11-17 02:37:13,402 - DEBUG - Starting all layer 2 verification in agent 10.0.0.50
2025-11-17 02:37:13,412 - DEBUG - Route for VM MAC 02:1f:0b:9a:0a:88 is consistent in agent 10.0.0.50
2025-11-17 02:37:14,606 - 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=13.7 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=1.92 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 1.921/7.832/13.743/5.911 ms')
2025-11-17 02:37:14,606 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-96407689 passed
2025-11-17 02:37:14,606 - INFO - VM ctest-VM31-96407689 verifications in Compute nodes passed
2025-11-17 02:37:14,845 - DEBUG - Validated VM route 129.151.152.131 in vrouter of 10.0.0.50
2025-11-17 02:37:14,850 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0
2025-11-17 02:37:14,850 - INFO - Validated routes of VM ctest-VM31-96407689 in all vrouters
2025-11-17 02:37:15,350 - DEBUG - Starting all layer2 verification in 10.0.0.33 Control Node
2025-11-17 02:37:15,462 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.33, not supported
2025-11-17 02:37:15,583 - DEBUG - Layer2 route found for VM MAC 02:1f:0b:9a:0a:88 in Control-node 10.0.0.33
2025-11-17 02:37:15,584 - DEBUG - L2 Label for VM ctest-VM31-96407689 same between Control-node 10.0.0.33 and Agent, Expected: 36, Seen: 36
2025-11-17 02:37:15,584 - DEBUG - Starting all layer2 verification in 10.0.0.22 Control Node
2025-11-17 02:37:15,699 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.22, not supported
2025-11-17 02:37:15,822 - DEBUG - Layer2 route found for VM MAC 02:1f:0b:9a:0a:88 in Control-node 10.0.0.22
2025-11-17 02:37:15,822 - DEBUG - L2 Label for VM ctest-VM31-96407689 same between Control-node 10.0.0.22 and Agent, Expected: 36, Seen: 36
2025-11-17 02:37:15,822 - INFO - Verification in Control-nodes for VM ctest-VM31-96407689 passed
2025-11-17 02:37:15,823 - DEBUG - Verifying the vm in opserver
2025-11-17 02:37:15,823 - DEBUG - Verifying in collector 10.0.0.23 ...
2025-11-17 02:37:15,823 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine/342bbcbe-2a8d-4afa-b32e-2178a67ba2be?flat
2025-11-17 02:37:15,837 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-10055946:1f0b9a0a-885b-4ab5-a390-e0daeb21d5e0?flat
2025-11-17 02:37:15,859 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-10055946:1f0b9a0a-885b-4ab5-a390-e0daeb21d5e0?flat
2025-11-17 02:37:15,878 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-10055946:1f0b9a0a-885b-4ab5-a390-e0daeb21d5e0?flat
2025-11-17 02:37:15,899 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine/342bbcbe-2a8d-4afa-b32e-2178a67ba2be?flat
2025-11-17 02:37:15,913 - DEBUG - Verifying vm in vn uve
2025-11-17 02:37:15,913 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-10055946:1f0b9a0a-885b-4ab5-a390-e0daeb21d5e0?flat
2025-11-17 02:37:15,931 - DEBUG - VM uve shows interface as {'ip6_address': '3435:8ea4:b6c2:7577:5410:8890:0:3', 'rx_vlan': 65535, 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-10055946:ctest-VN3-40669289', 'is_health_check_active': True, 'admin_state': True, 'fixed_ip6_list': ['3435:8ea4:b6c2:7577:5410:8890:0:3'], 'ip4_active': True, 'vm_uuid': '342bbcbe-2a8d-4afa-b32e-2178a67ba2be', 'port_mirror_enabled': False, 'vn_uuid': 'c68cd9ab-f5c9-4335-8ff7-8d0253194450', '__T': 1763346998161967, 'gateway': '129.151.152.129', 'active': True, 'fixed_ip4_list': ['129.151.152.131'], 'tx_vlan': 65535, 'uuid': '1f0b9a0a-885b-4ab5-a390-e0daeb21d5e0', 'vhostuser_mode': 0, 'ip6_active': True, 'l2_active': True, 'vm_name': 'ctest-VM31-96407689', 'label': 32, 'mac_address': '02:1f:0b:9a:0a:88', 'ip_address': '129.151.152.131'}
2025-11-17 02:37:15,931 - DEBUG - VM uve shows ip address as ['129.151.152.131', '3435:8ea4:b6c2:7577:5410:8890:0:3']
2025-11-17 02:37:15,931 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-10055946:ctest-VN3-40669289
2025-11-17 02:37:15,931 - INFO - Verifying through opserver in 10.0.0.22
2025-11-17 02:37:15,931 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-10055946:ctest-VN3-40669289?flat
2025-11-17 02:37:15,951 - DEBUG - expected vm list 342bbcbe-2a8d-4afa-b32e-2178a67ba2be
2025-11-17 02:37:15,951 - DEBUG - Extracted vm list ['342bbcbe-2a8d-4afa-b32e-2178a67ba2be']
2025-11-17 02:37:15,951 - INFO - VM 342bbcbe-2a8d-4afa-b32e-2178a67ba2be is present in default-domain:ctest-TestPolicyAclIpv4v6-10055946:ctest-VN3-40669289
2025-11-17 02:37:15,951 - DEBUG - Verifying vm in vrouter uve
2025-11-17 02:37:15,951 - DEBUG - Getting info from collector 10.0.0.23..
2025-11-17 02:37:15,951 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine/342bbcbe-2a8d-4afa-b32e-2178a67ba2be?flat
2025-11-17 02:37:15,966 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-4736-1']
2025-11-17 02:37:15,966 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4736-1:Compute:contrail-vrouter-agent:0?flat
2025-11-17 02:37:16,000 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4736-1?flat
2025-11-17 02:37:16,030 - DEBUG - VM 342bbcbe-2a8d-4afa-b32e-2178a67ba2be is present in vrouter an-jenkins-deploy-platform-ansible-os-4736-1 uve
2025-11-17 02:37:16,030 - DEBUG - Validated that VM ctest-VM31-96407689 is in Vrouter an-jenkins-deploy-platform-ansible-os-4736-1 UVE
2025-11-17 02:37:16,030 - DEBUG - Verifying vm tap interface/vn in vrouter uve
2025-11-17 02:37:16,038 - DEBUG - Expected tap interface of VM uuid 342bbcbe-2a8d-4afa-b32e-2178a67ba2be is default-domain:ctest-TestPolicyAclIpv4v6-10055946:1f0b9a0a-885b-4ab5-a390-e0daeb21d5e0
2025-11-17 02:37:16,038 - DEBUG - Expected VN of VM uuid 342bbcbe-2a8d-4afa-b32e-2178a67ba2be is default-domain:ctest-TestPolicyAclIpv4v6-10055946:ctest-VN3-40669289
2025-11-17 02:37:16,038 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4736-1:Compute:contrail-vrouter-agent:0?flat
2025-11-17 02:37:16,073 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4736-1?flat
2025-11-17 02:37:16,102 - DEBUG - VM 342bbcbe-2a8d-4afa-b32e-2178a67ba2be is present in vrouter an-jenkins-deploy-platform-ansible-os-4736-1 uve
2025-11-17 02:37:16,102 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-10055946:1f0b9a0a-885b-4ab5-a390-e0daeb21d5e0 of vm 342bbcbe-2a8d-4afa-b32e-2178a67ba2be is present in vrouter an-jenkins-deploy-platform-ansible-os-4736-1 uve
2025-11-17 02:37:16,103 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-10055946:ctest-VN3-40669289 of vm 342bbcbe-2a8d-4afa-b32e-2178a67ba2be is present in vrouter an-jenkins-deploy-platform-ansible-os-4736-1 uve
2025-11-17 02:37:16,103 - DEBUG - Verifying the 342bbcbe-2a8d-4afa-b32e-2178a67ba2be virtual network link through opserver 10.0.0.22
2025-11-17 02:37:16,103 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machines
2025-11-17 02:37:16,115 - DEBUG - VM link and name as {'name': '342bbcbe-2a8d-4afa-b32e-2178a67ba2be', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-machine/342bbcbe-2a8d-4afa-b32e-2178a67ba2be?flat'}
2025-11-17 02:37:16,115 - DEBUG - Verifying the 342bbcbe-2a8d-4afa-b32e-2178a67ba2be virtual network link through opserver 10.0.0.33
2025-11-17 02:37:16,115 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machines
2025-11-17 02:37:16,128 - DEBUG - VM link and name as {'name': '342bbcbe-2a8d-4afa-b32e-2178a67ba2be', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-machine/342bbcbe-2a8d-4afa-b32e-2178a67ba2be?flat'}
2025-11-17 02:37:16,128 - DEBUG - Verifying the 342bbcbe-2a8d-4afa-b32e-2178a67ba2be virtual network link through opserver 10.0.0.23
2025-11-17 02:37:16,128 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machines
2025-11-17 02:37:16,139 - DEBUG - VM link and name as {'name': '342bbcbe-2a8d-4afa-b32e-2178a67ba2be', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-machine/342bbcbe-2a8d-4afa-b32e-2178a67ba2be?flat'}
2025-11-17 02:37:16,139 - INFO - VM ctest-VM31-96407689 validations in Opserver passed
2025-11-17 02:37:16,139 - INFO - Waiting for VM ctest-VM11-98889313 to be up..
2025-11-17 02:37:16,139 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-11-17 02:37:16,139 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/efc4cd6c-62cc-4ea7-bfc9-4f558b04c64b
2025-11-17 02:37:16,148 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/97959b66-b53b-4b95-844c-6a27ae0779a4
2025-11-17 02:37:17,309 - 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.55 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=3.53 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 2.554/3.043/3.533/0.489 ms')
2025-11-17 02:37:17,310 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-98889313 passed
2025-11-17 02:37:17,379 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-11-17 02:37:17,379 - DEBUG - Waiting to SSH to VM ctest-VM11-98889313, IP 175.197.27.131, Port 22
2025-11-17 02:37:17,450 - 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-11-17 02:37:17,535 - DEBUG - VM ctest-VM11-98889313 is NOT ready for SSH connections, VM status: ACTIVE
2025-11-17 02:37:22,536 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-11-17 02:37:22,536 - DEBUG - Waiting to SSH to VM ctest-VM11-98889313, IP 175.197.27.131, Port 22
2025-11-17 02:37:22,602 - 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-11-17 02:37:22,692 - DEBUG - VM ctest-VM11-98889313 is NOT ready for SSH connections, VM status: ACTIVE
2025-11-17 02:37:27,694 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-11-17 02:37:27,694 - DEBUG - Waiting to SSH to VM ctest-VM11-98889313, IP 175.197.27.131, Port 22
2025-11-17 02:37:27,869 - DEBUG - VM ctest-VM11-98889313 is ready for SSH connections
2025-11-17 02:37:27,869 - INFO - Waiting for VM ctest-VM21-75145401 to be up..
2025-11-17 02:37:27,869 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-11-17 02:37:27,869 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/9a7ccb86-4b9c-4f28-bd0f-86e4ee41e66e
2025-11-17 02:37:27,877 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/d8b7c7ab-0428-4c9a-9183-810cbe462519
2025-11-17 02:37:29,032 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=1.60 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.92 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 = 1.604/1.759/1.915/0.155 ms')
2025-11-17 02:37:29,032 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-75145401 passed
2025-11-17 02:37:29,101 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-11-17 02:37:29,101 - DEBUG - Waiting to SSH to VM ctest-VM21-75145401, IP 75.75.217.3, Port 22
2025-11-17 02:37:29,255 - DEBUG - VM ctest-VM21-75145401 is ready for SSH connections
2025-11-17 02:37:29,255 - INFO - Waiting for VM ctest-VM31-96407689 to be up..
2025-11-17 02:37:29,255 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-11-17 02:37:29,255 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/342bbcbe-2a8d-4afa-b32e-2178a67ba2be
2025-11-17 02:37:29,264 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/1f0b9a0a-885b-4ab5-a390-e0daeb21d5e0
2025-11-17 02:37:30,450 - 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=4.30 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=3.86 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 = 3.856/4.076/4.296/0.220 ms')
2025-11-17 02:37:30,451 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-96407689 passed
2025-11-17 02:37:30,522 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-11-17 02:37:30,522 - DEBUG - Waiting to SSH to VM ctest-VM31-96407689, IP 129.151.152.131, Port 22
2025-11-17 02:37:30,580 - DEBUG - Error on ssh to ubuntu@169.254.0.4:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.4/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.4/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.4/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-11-17 02:37:30,675 - DEBUG - VM ctest-VM31-96407689 is NOT ready for SSH connections, VM status: ACTIVE
2025-11-17 02:37:35,677 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-11-17 02:37:35,677 - DEBUG - Waiting to SSH to VM ctest-VM31-96407689, IP 129.151.152.131, Port 22
2025-11-17 02:37:35,857 - DEBUG - VM ctest-VM31-96407689 is ready for SSH connections
2025-11-17 02:37:35,857 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 75.75.217.3, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.50, gateway password: c0ntrail123
2025-11-17 02:37:35,857 - DEBUG - ping -s 56 -c 3 -W 1 75.75.217.3
2025-11-17 02:37:57,978 - DEBUG - PING 75.75.217.3 (75.75.217.3) 56(84) bytes of data.
64 bytes from 75.75.217.3: icmp_req=1 ttl=63 time=7.95 ms
64 bytes from 75.75.217.3: icmp_req=2 ttl=63 time=1.21 ms
64 bytes from 75.75.217.3: icmp_req=3 ttl=63 time=2.19 ms
--- 75.75.217.3 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2005ms
rtt min/avg/max/mdev = 1.213/3.788/7.954/2.973 ms
2025-11-17 02:37:57,978 - INFO - Ping to IP 75.75.217.3 from VM ctest-VM11-98889313 passed
2025-11-17 02:37:57,978 - DEBUG - Running remote_cmd, Cmd : ping6 -s 56 -c 3 -W 1 37c4:ab7e:43a0:5fd8:47bb:40b8:0:3, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.50, gateway password: c0ntrail123
2025-11-17 02:37:57,978 - DEBUG - ping6 -s 56 -c 3 -W 1 37c4:ab7e:43a0:5fd8:47bb:40b8:0:3
2025-11-17 02:38:00,584 - DEBUG - PING 37c4:ab7e:43a0:5fd8:47bb:40b8:0:3(37c4:ab7e:43a0:5fd8:47bb:40b8:0:3) 56 data bytes
64 bytes from 37c4:ab7e:43a0:5fd8:47bb:40b8:0:3: icmp_seq=1 ttl=64 time=8.66 ms
64 bytes from 37c4:ab7e:43a0:5fd8:47bb:40b8:0:3: icmp_seq=2 ttl=64 time=1.30 ms
64 bytes from 37c4:ab7e:43a0:5fd8:47bb:40b8:0:3: icmp_seq=3 ttl=64 time=1.47 ms
--- 37c4:ab7e:43a0:5fd8:47bb:40b8:0:3 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2003ms
rtt min/avg/max/mdev = 1.300/3.813/8.668/3.433 ms
2025-11-17 02:38:00,584 - INFO - Ping to IP 37c4:ab7e:43a0:5fd8:47bb:40b8:0:3 from VM ctest-VM11-98889313 passed
2025-11-17 02:38:00,584 - INFO - Test with src as policy and dst as any PASSED
2025-11-17 02:38:00,584 - INFO - Deleting VM ctest-VM31-96407689
2025-11-17 02:38:00,666 - DEBUG - Verifying in api server 10.0.0.23
2025-11-17 02:38:00,666 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/342bbcbe-2a8d-4afa-b32e-2178a67ba2be
2025-11-17 02:38:00,675 - DEBUG - VM ID 342bbcbe-2a8d-4afa-b32e-2178a67ba2be of VM ctest-VM31-96407689 is still found in API Server
2025-11-17 02:38:02,676 - DEBUG - Verifying in api server 10.0.0.23
2025-11-17 02:38:02,676 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/342bbcbe-2a8d-4afa-b32e-2178a67ba2be
2025-11-17 02:38:02,683 - DEBUG - Response Code: 404
2025-11-17 02:38:02,683 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/342bbcbe-2a8d-4afa-b32e-2178a67ba2be
2025-11-17 02:38:02,691 - DEBUG - Response Code: 404
2025-11-17 02:38:02,691 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/342bbcbe-2a8d-4afa-b32e-2178a67ba2be
2025-11-17 02:38:02,698 - DEBUG - Response Code: 404
2025-11-17 02:38:02,698 - INFO - VM ctest-VM31-96407689 is fully removed in API-Server
2025-11-17 02:38:02,860 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2025-11-17 02:38:02,901 - DEBUG - Validated that vrouter 10.0.0.50 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-10055946:ctest-VN3-40669289
2025-11-17 02:38:02,912 - DEBUG - Validated that vrouter 10.0.0.73 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-10055946:ctest-VN3-40669289
2025-11-17 02:38:02,912 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-10055946:ctest-VN3-40669289
2025-11-17 02:38:02,912 - INFO - VM ctest-VM31-96407689 is removed in Compute, and routes are removed in all compute nodes
2025-11-17 02:38:03,146 - INFO - Routes for VM ctest-VM31-96407689 is removed in all control-nodes
2025-11-17 02:38:03,146 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4736-1:Compute:contrail-vrouter-agent:0?flat
2025-11-17 02:38:03,184 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4736-1?flat
2025-11-17 02:38:03,216 - DEBUG - VM 342bbcbe-2a8d-4afa-b32e-2178a67ba2be is still present in vrouter an-jenkins-deploy-platform-ansible-os-4736-1 uve
2025-11-17 02:38:03,216 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-11-17 02:38:03,216 - DEBUG - interface for vm 342bbcbe-2a8d-4afa-b32e-2178a67ba2be deleted from vrouter uve ...
2025-11-17 02:38:03,216 - DEBUG - Deleted interface not in error interface list ...
2025-11-17 02:38:06,217 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4736-1:Compute:contrail-vrouter-agent:0?flat
2025-11-17 02:38:06,257 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4736-1?flat
2025-11-17 02:38:06,298 - DEBUG - VM 342bbcbe-2a8d-4afa-b32e-2178a67ba2be is still present in vrouter an-jenkins-deploy-platform-ansible-os-4736-1 uve
2025-11-17 02:38:06,298 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-11-17 02:38:06,298 - DEBUG - interface for vm 342bbcbe-2a8d-4afa-b32e-2178a67ba2be deleted from vrouter uve ...
2025-11-17 02:38:06,298 - DEBUG - Deleted interface not in error interface list ...
2025-11-17 02:38:09,298 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4736-1:Compute:contrail-vrouter-agent:0?flat
2025-11-17 02:38:09,337 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4736-1?flat
2025-11-17 02:38:09,368 - DEBUG - VM 342bbcbe-2a8d-4afa-b32e-2178a67ba2be is not present in vrouter an-jenkins-deploy-platform-ansible-os-4736-1 uve
2025-11-17 02:38:09,368 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-11-17 02:38:09,368 - DEBUG - interface for vm 342bbcbe-2a8d-4afa-b32e-2178a67ba2be deleted from vrouter uve ...
2025-11-17 02:38:09,368 - DEBUG - Deleted interface not in error interface list ...
2025-11-17 02:38:09,368 - DEBUG - Validated that VM 342bbcbe-2a8d-4afa-b32e-2178a67ba2be is removed in Vrouter UVE
2025-11-17 02:38:09,369 - DEBUG - Verifying through opserver in 10.0.0.22
2025-11-17 02:38:09,369 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-10055946:ctest-VN3-40669289?flat
2025-11-17 02:38:09,392 - DEBUG - 342bbcbe-2a8d-4afa-b32e-2178a67ba2be BM not in default-domain:ctest-TestPolicyAclIpv4v6-10055946:ctest-VN3-40669289 uve
2025-11-17 02:38:09,392 - DEBUG - Validated that VM 342bbcbe-2a8d-4afa-b32e-2178a67ba2be is not present in the VN UVE
2025-11-17 02:38:09,392 - DEBUG - Verifying the 342bbcbe-2a8d-4afa-b32e-2178a67ba2be virtual network link through opserver 10.0.0.22
2025-11-17 02:38:09,392 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machines
2025-11-17 02:38:09,405 - INFO - 342bbcbe-2a8d-4afa-b32e-2178a67ba2be vm uve deleted from opserver
2025-11-17 02:38:09,405 - DEBUG - Verifying the 342bbcbe-2a8d-4afa-b32e-2178a67ba2be virtual network link through opserver 10.0.0.33
2025-11-17 02:38:09,405 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machines
2025-11-17 02:38:09,419 - INFO - 342bbcbe-2a8d-4afa-b32e-2178a67ba2be vm uve deleted from opserver
2025-11-17 02:38:09,419 - DEBUG - Verifying the 342bbcbe-2a8d-4afa-b32e-2178a67ba2be virtual network link through opserver 10.0.0.23
2025-11-17 02:38:09,419 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machines
2025-11-17 02:38:09,432 - INFO - 342bbcbe-2a8d-4afa-b32e-2178a67ba2be vm uve deleted from opserver
2025-11-17 02:38:09,432 - INFO - Validated that VM 342bbcbe-2a8d-4afa-b32e-2178a67ba2be is removed from Opserver
2025-11-17 02:38:09,432 - INFO - Deleting VM ctest-VM21-75145401
2025-11-17 02:38:09,513 - DEBUG - Verifying in api server 10.0.0.23
2025-11-17 02:38:09,514 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/9a7ccb86-4b9c-4f28-bd0f-86e4ee41e66e
2025-11-17 02:38:09,522 - DEBUG - VM ID 9a7ccb86-4b9c-4f28-bd0f-86e4ee41e66e of VM ctest-VM21-75145401 is still found in API Server
2025-11-17 02:38:11,523 - DEBUG - Verifying in api server 10.0.0.23
2025-11-17 02:38:11,523 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/9a7ccb86-4b9c-4f28-bd0f-86e4ee41e66e
2025-11-17 02:38:11,531 - DEBUG - Response Code: 404
2025-11-17 02:38:11,531 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/9a7ccb86-4b9c-4f28-bd0f-86e4ee41e66e
2025-11-17 02:38:11,538 - DEBUG - Response Code: 404
2025-11-17 02:38:11,539 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/9a7ccb86-4b9c-4f28-bd0f-86e4ee41e66e
2025-11-17 02:38:11,545 - DEBUG - Response Code: 404
2025-11-17 02:38:11,545 - INFO - VM ctest-VM21-75145401 is fully removed in API-Server
2025-11-17 02:38:11,697 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2025-11-17 02:38:11,745 - DEBUG - Validated that vrouter 10.0.0.50 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-10055946:ctest-VN2-88128007
2025-11-17 02:38:11,758 - DEBUG - Validated that vrouter 10.0.0.73 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-10055946:ctest-VN2-88128007
2025-11-17 02:38:11,758 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-10055946:ctest-VN2-88128007
2025-11-17 02:38:11,758 - INFO - VM ctest-VM21-75145401 is removed in Compute, and routes are removed in all compute nodes
2025-11-17 02:38:11,997 - INFO - Routes for VM ctest-VM21-75145401 is removed in all control-nodes
2025-11-17 02:38:11,997 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4736-2:Compute:contrail-vrouter-agent:0?flat
2025-11-17 02:38:12,040 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4736-2?flat
2025-11-17 02:38:12,073 - DEBUG - VM 9a7ccb86-4b9c-4f28-bd0f-86e4ee41e66e is still present in vrouter an-jenkins-deploy-platform-ansible-os-4736-2 uve
2025-11-17 02:38:12,073 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-11-17 02:38:12,073 - DEBUG - interface for vm 9a7ccb86-4b9c-4f28-bd0f-86e4ee41e66e deleted from vrouter uve ...
2025-11-17 02:38:12,074 - DEBUG - Deleted interface not in error interface list ...
2025-11-17 02:38:15,074 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4736-2:Compute:contrail-vrouter-agent:0?flat
2025-11-17 02:38:15,114 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4736-2?flat
2025-11-17 02:38:15,158 - DEBUG - VM 9a7ccb86-4b9c-4f28-bd0f-86e4ee41e66e is not present in vrouter an-jenkins-deploy-platform-ansible-os-4736-2 uve
2025-11-17 02:38:15,158 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-11-17 02:38:15,158 - DEBUG - interface for vm 9a7ccb86-4b9c-4f28-bd0f-86e4ee41e66e deleted from vrouter uve ...
2025-11-17 02:38:15,159 - DEBUG - Deleted interface not in error interface list ...
2025-11-17 02:38:15,159 - DEBUG - Validated that VM 9a7ccb86-4b9c-4f28-bd0f-86e4ee41e66e is removed in Vrouter UVE
2025-11-17 02:38:15,159 - DEBUG - Verifying through opserver in 10.0.0.22
2025-11-17 02:38:15,159 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-10055946:ctest-VN2-88128007?flat
2025-11-17 02:38:15,180 - DEBUG - 9a7ccb86-4b9c-4f28-bd0f-86e4ee41e66e BM not in default-domain:ctest-TestPolicyAclIpv4v6-10055946:ctest-VN2-88128007 uve
2025-11-17 02:38:15,180 - DEBUG - Validated that VM 9a7ccb86-4b9c-4f28-bd0f-86e4ee41e66e is not present in the VN UVE
2025-11-17 02:38:15,180 - DEBUG - Verifying the 9a7ccb86-4b9c-4f28-bd0f-86e4ee41e66e virtual network link through opserver 10.0.0.22
2025-11-17 02:38:15,180 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machines
2025-11-17 02:38:15,195 - INFO - 9a7ccb86-4b9c-4f28-bd0f-86e4ee41e66e vm uve deleted from opserver
2025-11-17 02:38:15,195 - DEBUG - Verifying the 9a7ccb86-4b9c-4f28-bd0f-86e4ee41e66e virtual network link through opserver 10.0.0.33
2025-11-17 02:38:15,195 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machines
2025-11-17 02:38:15,210 - INFO - 9a7ccb86-4b9c-4f28-bd0f-86e4ee41e66e vm uve deleted from opserver
2025-11-17 02:38:15,211 - DEBUG - Verifying the 9a7ccb86-4b9c-4f28-bd0f-86e4ee41e66e virtual network link through opserver 10.0.0.23
2025-11-17 02:38:15,211 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machines
2025-11-17 02:38:15,224 - INFO - 9a7ccb86-4b9c-4f28-bd0f-86e4ee41e66e vm uve deleted from opserver
2025-11-17 02:38:15,224 - INFO - Validated that VM 9a7ccb86-4b9c-4f28-bd0f-86e4ee41e66e is removed from Opserver
2025-11-17 02:38:15,224 - INFO - Deleting VM ctest-VM11-98889313
2025-11-17 02:38:15,333 - DEBUG - Verifying in api server 10.0.0.23
2025-11-17 02:38:15,334 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/efc4cd6c-62cc-4ea7-bfc9-4f558b04c64b
2025-11-17 02:38:15,344 - DEBUG - VM ID efc4cd6c-62cc-4ea7-bfc9-4f558b04c64b of VM ctest-VM11-98889313 is still found in API Server
2025-11-17 02:38:17,345 - DEBUG - Verifying in api server 10.0.0.23
2025-11-17 02:38:17,345 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/efc4cd6c-62cc-4ea7-bfc9-4f558b04c64b
2025-11-17 02:38:17,352 - DEBUG - Response Code: 404
2025-11-17 02:38:17,352 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/efc4cd6c-62cc-4ea7-bfc9-4f558b04c64b
2025-11-17 02:38:17,358 - DEBUG - Response Code: 404
2025-11-17 02:38:17,358 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/efc4cd6c-62cc-4ea7-bfc9-4f558b04c64b
2025-11-17 02:38:17,363 - DEBUG - Response Code: 404
2025-11-17 02:38:17,363 - INFO - VM ctest-VM11-98889313 is fully removed in API-Server
2025-11-17 02:38:17,517 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2025-11-17 02:38:17,559 - DEBUG - Validated that vrouter 10.0.0.50 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-10055946:ctest-VN1-64299741
2025-11-17 02:38:17,568 - DEBUG - Validated that vrouter 10.0.0.73 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-10055946:ctest-VN1-64299741
2025-11-17 02:38:17,568 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-10055946:ctest-VN1-64299741
2025-11-17 02:38:17,568 - INFO - VM ctest-VM11-98889313 is removed in Compute, and routes are removed in all compute nodes
2025-11-17 02:38:17,767 - INFO - Routes for VM ctest-VM11-98889313 is removed in all control-nodes
2025-11-17 02:38:17,768 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4736-1:Compute:contrail-vrouter-agent:0?flat
2025-11-17 02:38:17,804 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4736-1?flat
2025-11-17 02:38:17,839 - DEBUG - VM efc4cd6c-62cc-4ea7-bfc9-4f558b04c64b is still present in vrouter an-jenkins-deploy-platform-ansible-os-4736-1 uve
2025-11-17 02:38:17,839 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-11-17 02:38:17,839 - DEBUG - interface for vm efc4cd6c-62cc-4ea7-bfc9-4f558b04c64b deleted from vrouter uve ...
2025-11-17 02:38:17,839 - DEBUG - Deleted interface not in error interface list ...
2025-11-17 02:38:20,840 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4736-1:Compute:contrail-vrouter-agent:0?flat
2025-11-17 02:38:20,878 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4736-1?flat
2025-11-17 02:38:20,909 - DEBUG - VM efc4cd6c-62cc-4ea7-bfc9-4f558b04c64b is still present in vrouter an-jenkins-deploy-platform-ansible-os-4736-1 uve
2025-11-17 02:38:20,909 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-11-17 02:38:20,910 - DEBUG - interface for vm efc4cd6c-62cc-4ea7-bfc9-4f558b04c64b deleted from vrouter uve ...
2025-11-17 02:38:20,910 - DEBUG - Deleted interface not in error interface list ...
2025-11-17 02:38:23,911 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4736-1:Compute:contrail-vrouter-agent:0?flat
2025-11-17 02:38:23,949 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4736-1?flat
2025-11-17 02:38:23,982 - DEBUG - VM efc4cd6c-62cc-4ea7-bfc9-4f558b04c64b is still present in vrouter an-jenkins-deploy-platform-ansible-os-4736-1 uve
2025-11-17 02:38:23,982 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-11-17 02:38:23,982 - DEBUG - interface for vm efc4cd6c-62cc-4ea7-bfc9-4f558b04c64b deleted from vrouter uve ...
2025-11-17 02:38:23,982 - DEBUG - Deleted interface not in error interface list ...
2025-11-17 02:38:26,983 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4736-1:Compute:contrail-vrouter-agent:0?flat
2025-11-17 02:38:27,017 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4736-1?flat
2025-11-17 02:38:27,048 - DEBUG - VM efc4cd6c-62cc-4ea7-bfc9-4f558b04c64b is still present in vrouter an-jenkins-deploy-platform-ansible-os-4736-1 uve
2025-11-17 02:38:27,048 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-11-17 02:38:27,048 - DEBUG - interface for vm efc4cd6c-62cc-4ea7-bfc9-4f558b04c64b deleted from vrouter uve ...
2025-11-17 02:38:27,048 - DEBUG - Deleted interface not in error interface list ...
2025-11-17 02:38:30,050 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4736-1:Compute:contrail-vrouter-agent:0?flat
2025-11-17 02:38:30,090 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4736-1?flat
2025-11-17 02:38:30,119 - DEBUG - VM efc4cd6c-62cc-4ea7-bfc9-4f558b04c64b is still present in vrouter an-jenkins-deploy-platform-ansible-os-4736-1 uve
2025-11-17 02:38:30,119 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-11-17 02:38:30,119 - DEBUG - interface for vm efc4cd6c-62cc-4ea7-bfc9-4f558b04c64b deleted from vrouter uve ...
2025-11-17 02:38:30,119 - DEBUG - Deleted interface not in error interface list ...
2025-11-17 02:38:33,120 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4736-1:Compute:contrail-vrouter-agent:0?flat
2025-11-17 02:38:33,160 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4736-1?flat
2025-11-17 02:38:33,190 - DEBUG - VM efc4cd6c-62cc-4ea7-bfc9-4f558b04c64b is still present in vrouter an-jenkins-deploy-platform-ansible-os-4736-1 uve
2025-11-17 02:38:33,190 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-11-17 02:38:33,190 - DEBUG - interface for vm efc4cd6c-62cc-4ea7-bfc9-4f558b04c64b deleted from vrouter uve ...
2025-11-17 02:38:33,190 - DEBUG - Deleted interface not in error interface list ...
2025-11-17 02:38:36,190 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4736-1:Compute:contrail-vrouter-agent:0?flat
2025-11-17 02:38:36,225 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4736-1?flat
2025-11-17 02:38:36,254 - DEBUG - VM efc4cd6c-62cc-4ea7-bfc9-4f558b04c64b is still present in vrouter an-jenkins-deploy-platform-ansible-os-4736-1 uve
2025-11-17 02:38:36,254 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-11-17 02:38:36,255 - DEBUG - interface for vm efc4cd6c-62cc-4ea7-bfc9-4f558b04c64b deleted from vrouter uve ...
2025-11-17 02:38:36,255 - DEBUG - Deleted interface not in error interface list ...
2025-11-17 02:38:39,255 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4736-1:Compute:contrail-vrouter-agent:0?flat
2025-11-17 02:38:39,291 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4736-1?flat
2025-11-17 02:38:39,321 - DEBUG - VM efc4cd6c-62cc-4ea7-bfc9-4f558b04c64b is not present in vrouter an-jenkins-deploy-platform-ansible-os-4736-1 uve
2025-11-17 02:38:39,321 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-11-17 02:38:39,321 - DEBUG - interface for vm efc4cd6c-62cc-4ea7-bfc9-4f558b04c64b deleted from vrouter uve ...
2025-11-17 02:38:39,321 - DEBUG - Deleted interface not in error interface list ...
2025-11-17 02:38:39,321 - DEBUG - Validated that VM efc4cd6c-62cc-4ea7-bfc9-4f558b04c64b is removed in Vrouter UVE
2025-11-17 02:38:39,321 - DEBUG - Verifying through opserver in 10.0.0.22
2025-11-17 02:38:39,321 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-10055946:ctest-VN1-64299741?flat
2025-11-17 02:38:39,341 - DEBUG - efc4cd6c-62cc-4ea7-bfc9-4f558b04c64b BM not in default-domain:ctest-TestPolicyAclIpv4v6-10055946:ctest-VN1-64299741 uve
2025-11-17 02:38:39,341 - DEBUG - Validated that VM efc4cd6c-62cc-4ea7-bfc9-4f558b04c64b is not present in the VN UVE
2025-11-17 02:38:39,341 - DEBUG - Verifying the efc4cd6c-62cc-4ea7-bfc9-4f558b04c64b virtual network link through opserver 10.0.0.22
2025-11-17 02:38:39,341 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machines
2025-11-17 02:38:39,354 - DEBUG - No links retuned for efc4cd6c-62cc-4ea7-bfc9-4f558b04c64b
2025-11-17 02:38:39,354 - INFO - efc4cd6c-62cc-4ea7-bfc9-4f558b04c64b vm uve deleted from opserver
2025-11-17 02:38:39,354 - DEBUG - Verifying the efc4cd6c-62cc-4ea7-bfc9-4f558b04c64b virtual network link through opserver 10.0.0.33
2025-11-17 02:38:39,355 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machines
2025-11-17 02:38:39,368 - DEBUG - No links retuned for efc4cd6c-62cc-4ea7-bfc9-4f558b04c64b
2025-11-17 02:38:39,368 - INFO - efc4cd6c-62cc-4ea7-bfc9-4f558b04c64b vm uve deleted from opserver
2025-11-17 02:38:39,368 - DEBUG - Verifying the efc4cd6c-62cc-4ea7-bfc9-4f558b04c64b virtual network link through opserver 10.0.0.23
2025-11-17 02:38:39,368 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machines
2025-11-17 02:38:39,379 - DEBUG - No links retuned for efc4cd6c-62cc-4ea7-bfc9-4f558b04c64b
2025-11-17 02:38:39,379 - INFO - efc4cd6c-62cc-4ea7-bfc9-4f558b04c64b vm uve deleted from opserver
2025-11-17 02:38:39,379 - INFO - Validated that VM efc4cd6c-62cc-4ea7-bfc9-4f558b04c64b is removed from Opserver
2025-11-17 02:38:39,379 - DEBUG - Detaching the Policy for VN :ctest-VN2-88128007
2025-11-17 02:38:39,379 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-17 02:38:39,385 - DEBUG - Requesting: http://10.0.0.22:8082/domain/b575f39c-ab2e-404a-877c-00e9b4192b03
2025-11-17 02:38:39,397 - DEBUG - Requesting: http://10.0.0.22:8082/project/deb0e48c-e743-45d6-a228-d4a0bbfb469f
2025-11-17 02:38:39,503 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/3c209f55-53c0-4a8d-a7cb-067cd2faf05d
2025-11-17 02:38:39,520 - DEBUG - Requesting: http://10.0.0.22:8082/network-policy/2f27ccd7-954d-48a9-befc-101ce546cefa
2025-11-17 02:38:39,713 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-10055946', 'ctest-policy21-32004754']] from ctest-VN2-88128007
2025-11-17 02:38:39,713 - DEBUG - Detaching the Policy for VN :ctest-VN1-64299741
2025-11-17 02:38:39,713 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-17 02:38:39,740 - DEBUG - Requesting: http://10.0.0.22:8082/domain/b575f39c-ab2e-404a-877c-00e9b4192b03
2025-11-17 02:38:39,757 - DEBUG - Requesting: http://10.0.0.22:8082/project/deb0e48c-e743-45d6-a228-d4a0bbfb469f
2025-11-17 02:38:39,855 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/966056b3-3533-4cb5-ba4c-5f92b4f42565
2025-11-17 02:38:39,873 - DEBUG - Requesting: http://10.0.0.22:8082/network-policy/8da58110-4e5c-47e7-8de6-dc81a08bd4a9
2025-11-17 02:38:39,887 - DEBUG - Requesting: http://10.0.0.22:8082/network-policy/44e02c79-4833-490d-93dc-74a7c41e4a7c
2025-11-17 02:38:40,104 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-10055946', 'ctest-policy12-46453043'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-10055946', 'ctest-policy13-72109498']] from ctest-VN1-64299741
2025-11-17 02:38:40,186 - INFO - Deleted policy ctest-policy21-32004754
2025-11-17 02:38:40,248 - INFO - Deleted policy ctest-policy12-46453043
2025-11-17 02:38:40,299 - INFO - Deleted policy ctest-policy13-72109498
2025-11-17 02:38:40,300 - INFO - Deleting VN ctest-VN3-40669289
2025-11-17 02:38:40,508 - DEBUG - Response for deleting network ()
2025-11-17 02:38:40,508 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/5fd791e8-44e0-48c2-a9a9-ef46391d856a
2025-11-17 02:38:40,517 - DEBUG - Response Code: 404
2025-11-17 02:38:40,517 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-17 02:38:40,525 - DEBUG - Requesting: http://10.0.0.22:8082/domain/b575f39c-ab2e-404a-877c-00e9b4192b03
2025-11-17 02:38:40,539 - DEBUG - Requesting: http://10.0.0.22:8082/project/deb0e48c-e743-45d6-a228-d4a0bbfb469f
2025-11-17 02:38:40,656 - INFO - Validated that VN ctest-VN3-40669289 is not found in API Server
2025-11-17 02:38:40,668 - DEBUG - VN ctest-VN3-40669289 is not present in Agent 10.0.0.50
2025-11-17 02:38:40,681 - DEBUG - VN ctest-VN3-40669289 is not present in Agent 10.0.0.73
2025-11-17 02:38:40,681 - INFO - Validated that VN ctest-VN3-40669289 is not in any agent
2025-11-17 02:38:40,709 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN3-40669289 info
2025-11-17 02:38:40,710 - INFO - Deleting VN ctest-VN2-88128007
2025-11-17 02:38:40,961 - DEBUG - Response for deleting network ()
2025-11-17 02:38:40,961 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/f2500e93-3299-436a-89ab-8bec5a01ca5a
2025-11-17 02:38:40,971 - DEBUG - Response Code: 404
2025-11-17 02:38:40,971 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-17 02:38:40,982 - DEBUG - Requesting: http://10.0.0.22:8082/domain/b575f39c-ab2e-404a-877c-00e9b4192b03
2025-11-17 02:38:40,994 - DEBUG - Requesting: http://10.0.0.22:8082/project/deb0e48c-e743-45d6-a228-d4a0bbfb469f
2025-11-17 02:38:41,095 - INFO - Validated that VN ctest-VN2-88128007 is not found in API Server
2025-11-17 02:38:41,108 - DEBUG - VN ctest-VN2-88128007 is not present in Agent 10.0.0.50
2025-11-17 02:38:41,121 - DEBUG - VN ctest-VN2-88128007 is not present in Agent 10.0.0.73
2025-11-17 02:38:41,121 - INFO - Validated that VN ctest-VN2-88128007 is not in any agent
2025-11-17 02:38:41,154 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN2-88128007 info
2025-11-17 02:38:41,154 - INFO - Deleting VN ctest-VN1-64299741
2025-11-17 02:38:41,359 - DEBUG - Response for deleting network ()
2025-11-17 02:38:41,359 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/d5adf67e-8b1f-46eb-af49-08095c2a2e92
2025-11-17 02:38:41,367 - DEBUG - Response Code: 404
2025-11-17 02:38:41,367 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-17 02:38:41,374 - DEBUG - Requesting: http://10.0.0.22:8082/domain/b575f39c-ab2e-404a-877c-00e9b4192b03
2025-11-17 02:38:41,389 - DEBUG - Requesting: http://10.0.0.22:8082/project/deb0e48c-e743-45d6-a228-d4a0bbfb469f
2025-11-17 02:38:41,480 - INFO - Validated that VN ctest-VN1-64299741 is not found in API Server
2025-11-17 02:38:41,493 - DEBUG - VN ctest-VN1-64299741 is not present in Agent 10.0.0.50
2025-11-17 02:38:41,506 - DEBUG - VN ctest-VN1-64299741 is not present in Agent 10.0.0.73
2025-11-17 02:38:41,506 - INFO - Validated that VN ctest-VN1-64299741 is not in any agent
2025-11-17 02:38:41,538 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN1-64299741 info
2025-11-17 02:38:41,629 - INFO - IPAM: ipam3 is not found in API Server
2025-11-17 02:38:41,650 - INFO - IPAM:ipam3 is not found in control node
2025-11-17 02:38:41,781 - INFO - IPAM: ipam2 is not found in API Server
2025-11-17 02:38:41,804 - INFO - IPAM:ipam2 is not found in control node
2025-11-17 02:38:41,949 - INFO - IPAM: ipam1 is not found in API Server
2025-11-17 02:38:41,972 - INFO - IPAM:ipam1 is not found in control node
2025-11-17 02:38:43,251 - DEBUG - No XMPP flaps were noticed during the test
2025-11-17 02:38:43,251 - INFO - END TEST : test_policy_inheritance_src_pol_dst_any : PASSED[0:02:54]
2025-11-17 02:38:43,251 - INFO - --------------------------------------------------------------------------------
2025-11-17 02:38:44,547 - DEBUG - No XMPP flaps were noticed during the test
2025-11-17 02:38:44,547 - INFO - END TEST : test_policy_inheritance_src_pol_dst_any : PASSED[0:02:56]
2025-11-17 02:38:44,547 - INFO - --------------------------------------------------------------------------------
2025-11-17 02:38:45,363 - INFO - Deleted project: ctest-TestPolicyAclIpv4v6-10055946, ID : deb0e48c-e743-45d6-a228-d4a0bbfb469f