is in ACTIVE state now
2026-08-31 02:56:50,252 - DEBUG - VM ctest-VM31-47461636 launched on Node an-jenkins-deploy-platform-ansible-os-6237-1
2026-08-31 02:56:50,337 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/04beb654-f46a-4736-a40d-b7641ac4a37a
2026-08-31 02:56:50,349 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/04beb654-f46a-4736-a40d-b7641ac4a37a
2026-08-31 02:56:50,359 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/df7564f5-f2b9-46d1-aa78-6f4b4d115ed5
2026-08-31 02:56:50,372 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/04beb654-f46a-4736-a40d-b7641ac4a37a
2026-08-31 02:56:50,381 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/df7564f5-f2b9-46d1-aa78-6f4b4d115ed5
2026-08-31 02:56:50,393 - DEBUG - Requesting: http://10.0.0.38:8082/instance-ip/0229d730-72b9-44a1-987c-a70794f6e5b5
2026-08-31 02:56:50,406 - DEBUG - Requesting: http://10.0.0.38:8082/instance-ip/4879fe22-8cc6-4961-9cee-5cd876fd7813
2026-08-31 02:56:50,419 - DEBUG - Verifying in api server 10.0.0.38
2026-08-31 02:56:50,419 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/04beb654-f46a-4736-a40d-b7641ac4a37a
2026-08-31 02:56:50,431 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/df7564f5-f2b9-46d1-aa78-6f4b4d115ed5
2026-08-31 02:56:50,443 - DEBUG - Requesting: http://10.0.0.38:8082/instance-ip/0229d730-72b9-44a1-987c-a70794f6e5b5
2026-08-31 02:56:50,453 - DEBUG - Requesting: http://10.0.0.38:8082/instance-ip/4879fe22-8cc6-4961-9cee-5cd876fd7813
2026-08-31 02:56:50,465 - INFO - VM ctest-VM31-47461636 verfication in all API Servers passed
2026-08-31 02:56:50,489 - DEBUG - VM ctest-VM31-47461636 Tap interface: {'index': '4', 'name': 'tapdf7564f5-f2', 'uuid': 'df7564f5-f2b9-46d1-aa78-6f4b4d115ed5', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-81840964:ctest-VN3-58658296:ctest-VN3-58658296', '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': '18', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-81840964:ctest-VN3-58658296', 'vm_uuid': '04beb654-f46a-4736-a40d-b7641ac4a37a', 'vm_name': 'ctest-VM31-47461636', 'ip_addr': '192.117.236.3', 'mac_addr': '02:df:75:64:f5:f2', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.4', 'service_vlan_list': None, 'os_ifindex': '37', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestPolicyAclIpv4v6-81840964:df7564f5-f2b9-46d1-aa78-6f4b4d115ed5', 'sg_uuid_list': ['3cdbb1d1-ca62-4502-aeb5-04d9d043d78b'], 'static_route_list': None, 'vm_project_uuid': '9c6025b9-44ce-4bd2-a686-72b8e8276bc1', 'admin_state': 'Enabled', 'flow_key_idx': '29', 'allowed_address_pair_list': None, 'ip6_addr': '25d7:e3e9:4f2d:354d:59e1:b1d5:0:3', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []}
2026-08-31 02:56:50,489 - DEBUG - Agent 10.0.0.186 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-81840964:ctest-VN3-58658296:ctest-VN3-58658296
2026-08-31 02:56:50,495 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-81840964:ctest-VN3-58658296:ctest-VN3-58658296', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-81840964:ctest-VN3-58658296', 'table_label': '-1', 'vxlan_id': '18', 'evpnindex': '3', 'brindex': '3', 'mplsindex': '0', 'RD': '10.20.0.193:3', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]}
2026-08-31 02:56:50,629 - DEBUG - VM ctest-VM31-47461636 : Tap interface tapdf7564f5-f2 is set to Active
2026-08-31 02:56:50,629 - DEBUG - Tap interface tapdf7564f5-f2 detail : {'index': '4', 'name': 'tapdf7564f5-f2', 'uuid': 'df7564f5-f2b9-46d1-aa78-6f4b4d115ed5', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-81840964:ctest-VN3-58658296:ctest-VN3-58658296', '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': '18', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-81840964:ctest-VN3-58658296', 'vm_uuid': '04beb654-f46a-4736-a40d-b7641ac4a37a', 'vm_name': 'ctest-VM31-47461636', 'ip_addr': '192.117.236.3', 'mac_addr': '02:df:75:64:f5:f2', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.4', 'service_vlan_list': None, 'os_ifindex': '37', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestPolicyAclIpv4v6-81840964:df7564f5-f2b9-46d1-aa78-6f4b4d115ed5', 'sg_uuid_list': ['3cdbb1d1-ca62-4502-aeb5-04d9d043d78b'], 'static_route_list': None, 'vm_project_uuid': '9c6025b9-44ce-4bd2-a686-72b8e8276bc1', 'admin_state': 'Enabled', 'flow_key_idx': '29', 'allowed_address_pair_list': None, 'ip6_addr': '25d7:e3e9:4f2d:354d:59e1:b1d5:0:3', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []}
2026-08-31 02:56:50,714 - DEBUG - Starting Layer 2 verification in Agent
2026-08-31 02:56:50,726 - DEBUG - Layer 2 path is seen for VM MAC 02:df:75:64:f5:f2 in agent 10.0.0.186
2026-08-31 02:56:50,726 - DEBUG - Active layer 2 route in agent is present for VMI tapdf7564f5-f2
2026-08-31 02:56:50,726 - DEBUG - L2 label(36) matches bw route table and interface table
2026-08-31 02:56:50,736 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-81840964:ctest-VN3-58658296 is found in Agent of node 10.0.0.186
2026-08-31 02:56:50,856 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-81840964:ctest-VN3-58658296 is consistent in agent 10.0.0.186
2026-08-31 02:56:50,856 - DEBUG - Route for VM IP ['25d7:e3e9:4f2d:354d:59e1:b1d5:0:3', '192.117.236.3'] is consistent in agent 10.0.0.186
2026-08-31 02:56:50,856 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-81840964:ctest-VN3-58658296 verification for VM ctest-VM31-47461636 in Agent 10.0.0.186 passed
2026-08-31 02:56:50,973 - DEBUG - Starting all layer 2 verification in agent 10.0.0.186
2026-08-31 02:56:50,991 - DEBUG - Route for VM MAC 02:df:75:64:f5:f2 is consistent in agent 10.0.0.186
2026-08-31 02:56:52,186 - 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=9.66 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=3.98 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.982/6.819/9.656/2.837 ms')
2026-08-31 02:56:52,186 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-47461636 passed
2026-08-31 02:56:52,186 - INFO - VM ctest-VM31-47461636 verifications in Compute nodes passed
2026-08-31 02:56:52,492 - DEBUG - Validated VM route 192.117.236.3 in vrouter of 10.0.0.186
2026-08-31 02:56:52,498 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0
2026-08-31 02:56:52,498 - INFO - Validated routes of VM ctest-VM31-47461636 in all vrouters
2026-08-31 02:56:53,013 - DEBUG - Starting all layer2 verification in 10.0.0.50 Control Node
2026-08-31 02:56:53,129 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.50, not supported
2026-08-31 02:56:53,256 - DEBUG - Layer2 route found for VM MAC 02:df:75:64:f5:f2 in Control-node 10.0.0.50
2026-08-31 02:56:53,256 - DEBUG - L2 Label for VM ctest-VM31-47461636 same between Control-node 10.0.0.50 and Agent, Expected: 36, Seen: 36
2026-08-31 02:56:53,256 - DEBUG - Starting all layer2 verification in 10.0.0.185 Control Node
2026-08-31 02:56:53,374 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.185, not supported
2026-08-31 02:56:53,504 - DEBUG - Layer2 route found for VM MAC 02:df:75:64:f5:f2 in Control-node 10.0.0.185
2026-08-31 02:56:53,504 - DEBUG - L2 Label for VM ctest-VM31-47461636 same between Control-node 10.0.0.185 and Agent, Expected: 36, Seen: 36
2026-08-31 02:56:53,504 - INFO - Verification in Control-nodes for VM ctest-VM31-47461636 passed
2026-08-31 02:56:53,504 - DEBUG - Verifying the vm in opserver
2026-08-31 02:56:53,504 - DEBUG - Verifying in collector 10.0.0.38 ...
2026-08-31 02:56:53,504 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine/04beb654-f46a-4736-a40d-b7641ac4a37a?flat
2026-08-31 02:56:53,522 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-81840964:df7564f5-f2b9-46d1-aa78-6f4b4d115ed5?flat
2026-08-31 02:56:53,544 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-81840964:df7564f5-f2b9-46d1-aa78-6f4b4d115ed5?flat
2026-08-31 02:56:53,569 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-81840964:df7564f5-f2b9-46d1-aa78-6f4b4d115ed5?flat
2026-08-31 02:56:53,592 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine/04beb654-f46a-4736-a40d-b7641ac4a37a?flat
2026-08-31 02:56:53,611 - DEBUG - Verifying vm in vn uve
2026-08-31 02:56:53,611 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-81840964:df7564f5-f2b9-46d1-aa78-6f4b4d115ed5?flat
2026-08-31 02:56:53,636 - DEBUG - VM uve shows interface as {'ip6_address': '25d7:e3e9:4f2d:354d:59e1:b1d5:0:3', 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-81840964:ctest-VN3-58658296', 'vm_uuid': '04beb654-f46a-4736-a40d-b7641ac4a37a', 'l2_active': True, 'label': 32, 'vm_name': 'ctest-VM31-47461636', 'ip_address': '192.117.236.3', 'ip4_active': True, 'mac_address': '02:df:75:64:f5:f2', 'ip6_active': True, 'rx_vlan': 65535, 'tx_vlan': 65535, 'vhostuser_mode': 0, 'gateway': '192.117.236.1', 'active': True, 'fixed_ip6_list': ['25d7:e3e9:4f2d:354d:59e1:b1d5:0:3'], 'admin_state': True, 'uuid': 'df7564f5-f2b9-46d1-aa78-6f4b4d115ed5', 'vn_uuid': '51bb6a55-037b-4a6b-8779-30ea1a08bdd6', 'port_mirror_enabled': False, 'fixed_ip4_list': ['192.117.236.3'], '__T': 1788144989668262, 'is_health_check_active': True}
2026-08-31 02:56:53,636 - DEBUG - VM uve shows ip address as ['192.117.236.3', '25d7:e3e9:4f2d:354d:59e1:b1d5:0:3']
2026-08-31 02:56:53,636 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-81840964:ctest-VN3-58658296
2026-08-31 02:56:53,636 - INFO - Verifying through opserver in 10.0.0.50
2026-08-31 02:56:53,636 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-81840964:ctest-VN3-58658296?flat
2026-08-31 02:56:53,663 - DEBUG - expected vm list 04beb654-f46a-4736-a40d-b7641ac4a37a
2026-08-31 02:56:53,663 - DEBUG - Extracted vm list ['04beb654-f46a-4736-a40d-b7641ac4a37a']
2026-08-31 02:56:53,663 - INFO - VM 04beb654-f46a-4736-a40d-b7641ac4a37a is present in default-domain:ctest-TestPolicyAclIpv4v6-81840964:ctest-VN3-58658296
2026-08-31 02:56:53,663 - DEBUG - Verifying vm in vrouter uve
2026-08-31 02:56:53,663 - DEBUG - Getting info from collector 10.0.0.38..
2026-08-31 02:56:53,663 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine/04beb654-f46a-4736-a40d-b7641ac4a37a?flat
2026-08-31 02:56:53,680 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-6237-1']
2026-08-31 02:56:53,680 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6237-1:Compute:contrail-vrouter-agent:0?flat
2026-08-31 02:56:53,718 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6237-1?flat
2026-08-31 02:56:53,750 - DEBUG - VM 04beb654-f46a-4736-a40d-b7641ac4a37a is present in vrouter an-jenkins-deploy-platform-ansible-os-6237-1 uve
2026-08-31 02:56:53,750 - DEBUG - Validated that VM ctest-VM31-47461636 is in Vrouter an-jenkins-deploy-platform-ansible-os-6237-1 UVE
2026-08-31 02:56:53,750 - DEBUG - Verifying vm tap interface/vn in vrouter uve
2026-08-31 02:56:53,759 - DEBUG - Expected tap interface of VM uuid 04beb654-f46a-4736-a40d-b7641ac4a37a is default-domain:ctest-TestPolicyAclIpv4v6-81840964:df7564f5-f2b9-46d1-aa78-6f4b4d115ed5
2026-08-31 02:56:53,760 - DEBUG - Expected VN of VM uuid 04beb654-f46a-4736-a40d-b7641ac4a37a is default-domain:ctest-TestPolicyAclIpv4v6-81840964:ctest-VN3-58658296
2026-08-31 02:56:53,760 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6237-1:Compute:contrail-vrouter-agent:0?flat
2026-08-31 02:56:53,794 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6237-1?flat
2026-08-31 02:56:53,825 - DEBUG - VM 04beb654-f46a-4736-a40d-b7641ac4a37a is present in vrouter an-jenkins-deploy-platform-ansible-os-6237-1 uve
2026-08-31 02:56:53,825 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-81840964:df7564f5-f2b9-46d1-aa78-6f4b4d115ed5 of vm 04beb654-f46a-4736-a40d-b7641ac4a37a is present in vrouter an-jenkins-deploy-platform-ansible-os-6237-1 uve
2026-08-31 02:56:53,826 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-81840964:ctest-VN3-58658296 of vm 04beb654-f46a-4736-a40d-b7641ac4a37a is present in vrouter an-jenkins-deploy-platform-ansible-os-6237-1 uve
2026-08-31 02:56:53,826 - DEBUG - Verifying the 04beb654-f46a-4736-a40d-b7641ac4a37a virtual network link through opserver 10.0.0.50
2026-08-31 02:56:53,826 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-machines
2026-08-31 02:56:53,842 - DEBUG - VM link and name as {'name': '04beb654-f46a-4736-a40d-b7641ac4a37a', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-machine/04beb654-f46a-4736-a40d-b7641ac4a37a?flat'}
2026-08-31 02:56:53,842 - DEBUG - Verifying the 04beb654-f46a-4736-a40d-b7641ac4a37a virtual network link through opserver 10.0.0.185
2026-08-31 02:56:53,842 - DEBUG - Requesting: http://10.0.0.185:8081/analytics/uves/virtual-machines
2026-08-31 02:56:53,857 - DEBUG - VM link and name as {'name': '04beb654-f46a-4736-a40d-b7641ac4a37a', 'href': 'http://10.0.0.185:8081/analytics/uves/virtual-machine/04beb654-f46a-4736-a40d-b7641ac4a37a?flat'}
2026-08-31 02:56:53,857 - DEBUG - Verifying the 04beb654-f46a-4736-a40d-b7641ac4a37a virtual network link through opserver 10.0.0.38
2026-08-31 02:56:53,857 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines
2026-08-31 02:56:53,874 - DEBUG - VM link and name as {'name': '04beb654-f46a-4736-a40d-b7641ac4a37a', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-machine/04beb654-f46a-4736-a40d-b7641ac4a37a?flat'}
2026-08-31 02:56:53,874 - INFO - VM ctest-VM31-47461636 validations in Opserver passed
2026-08-31 02:56:53,874 - INFO - Waiting for VM ctest-VM11-21398684 to be up..
2026-08-31 02:56:53,874 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-08-31 02:56:53,874 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/2b5fbbc3-a746-48b9-a9df-84d2f72753ad
2026-08-31 02:56:53,885 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/7f1b660e-3d16-4769-abdb-aa2a1b2db088
2026-08-31 02:56:55,054 - 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=13.0 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.887 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.887/6.965/13.044/6.078 ms')
2026-08-31 02:56:55,055 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-21398684 passed
2026-08-31 02:56:55,128 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-08-31 02:56:55,128 - DEBUG - Waiting to SSH to VM ctest-VM11-21398684, IP 8.27.218.195, Port 22
2026-08-31 02:56:55,194 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-08-31 02:56:55,288 - DEBUG - VM ctest-VM11-21398684 is NOT ready for SSH connections, VM status: ACTIVE
2026-08-31 02:57:00,288 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-08-31 02:57:00,289 - DEBUG - Waiting to SSH to VM ctest-VM11-21398684, IP 8.27.218.195, Port 22
2026-08-31 02:57:00,463 - DEBUG - VM ctest-VM11-21398684 is ready for SSH connections
2026-08-31 02:57:00,463 - INFO - Waiting for VM ctest-VM21-16096974 to be up..
2026-08-31 02:57:00,463 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-08-31 02:57:00,463 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/eaceed45-d18e-47ab-9e8c-a7d406a0fcd6
2026-08-31 02:57:00,474 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/5ea33a25-54f6-4253-b6d5-e39797db816f
2026-08-31 02:57:01,654 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=3.31 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=3.67 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 3.306/3.485/3.665/0.179 ms')
2026-08-31 02:57:01,654 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-16096974 passed
2026-08-31 02:57:01,728 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-08-31 02:57:01,729 - DEBUG - Waiting to SSH to VM ctest-VM21-16096974, IP 88.148.105.195, Port 22
2026-08-31 02:57:01,910 - DEBUG - VM ctest-VM21-16096974 is ready for SSH connections
2026-08-31 02:57:01,910 - INFO - Waiting for VM ctest-VM31-47461636 to be up..
2026-08-31 02:57:01,910 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-08-31 02:57:01,910 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/04beb654-f46a-4736-a40d-b7641ac4a37a
2026-08-31 02:57:01,920 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/df7564f5-f2b9-46d1-aa78-6f4b4d115ed5
2026-08-31 02:57:03,090 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=2.44 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.818 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.818/1.628/2.439/0.810 ms')
2026-08-31 02:57:03,090 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-47461636 passed
2026-08-31 02:57:03,165 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-08-31 02:57:03,166 - DEBUG - Waiting to SSH to VM ctest-VM31-47461636, IP 192.117.236.3, Port 22
2026-08-31 02:57:03,339 - DEBUG - VM ctest-VM31-47461636 is ready for SSH connections
2026-08-31 02:57:03,339 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 88.148.105.195, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.186, gateway password: c0ntrail123
2026-08-31 02:57:03,339 - DEBUG - ping -s 56 -c 3 -W 1 88.148.105.195
2026-08-31 02:57:16,971 - DEBUG - PING 88.148.105.195 (88.148.105.195) 56(84) bytes of data.
64 bytes from 88.148.105.195: icmp_req=1 ttl=63 time=9.74 ms
64 bytes from 88.148.105.195: icmp_req=2 ttl=63 time=1.70 ms
64 bytes from 88.148.105.195: icmp_req=3 ttl=63 time=1.75 ms
--- 88.148.105.195 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2003ms
rtt min/avg/max/mdev = 1.705/4.402/9.746/3.778 ms
2026-08-31 02:57:16,971 - INFO - Ping to IP 88.148.105.195 from VM ctest-VM11-21398684 passed
2026-08-31 02:57:16,971 - DEBUG - Running remote_cmd, Cmd : ping6 -s 56 -c 3 -W 1 24ab:f7d7:89a:b26a:f652:58e:0:3, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.186, gateway password: c0ntrail123
2026-08-31 02:57:16,972 - DEBUG - ping6 -s 56 -c 3 -W 1 24ab:f7d7:89a:b26a:f652:58e:0:3
2026-08-31 02:57:19,489 - DEBUG - PING 24ab:f7d7:89a:b26a:f652:58e:0:3(24ab:f7d7:89a:b26a:f652:58e:0:3) 56 data bytes
64 bytes from 24ab:f7d7:89a:b26a:f652:58e:0:3: icmp_seq=1 ttl=64 time=7.64 ms
64 bytes from 24ab:f7d7:89a:b26a:f652:58e:0:3: icmp_seq=2 ttl=64 time=2.05 ms
64 bytes from 24ab:f7d7:89a:b26a:f652:58e:0:3: icmp_seq=3 ttl=64 time=1.87 ms
--- 24ab:f7d7:89a:b26a:f652:58e:0:3 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2003ms
rtt min/avg/max/mdev = 1.871/3.857/7.649/2.682 ms
2026-08-31 02:57:19,490 - INFO - Ping to IP 24ab:f7d7:89a:b26a:f652:58e:0:3 from VM ctest-VM11-21398684 passed
2026-08-31 02:57:19,490 - INFO - Test with src as policy and dst as any PASSED
2026-08-31 02:57:19,490 - INFO - Deleting VM ctest-VM31-47461636
2026-08-31 02:57:19,594 - DEBUG - Verifying in api server 10.0.0.38
2026-08-31 02:57:19,595 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/04beb654-f46a-4736-a40d-b7641ac4a37a
2026-08-31 02:57:19,607 - DEBUG - VM ID 04beb654-f46a-4736-a40d-b7641ac4a37a of VM ctest-VM31-47461636 is still found in API Server
2026-08-31 02:57:21,607 - DEBUG - Verifying in api server 10.0.0.38
2026-08-31 02:57:21,607 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/04beb654-f46a-4736-a40d-b7641ac4a37a
2026-08-31 02:57:21,619 - DEBUG - VM ID 04beb654-f46a-4736-a40d-b7641ac4a37a of VM ctest-VM31-47461636 is still found in API Server
2026-08-31 02:57:23,620 - DEBUG - Verifying in api server 10.0.0.38
2026-08-31 02:57:23,620 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/04beb654-f46a-4736-a40d-b7641ac4a37a
2026-08-31 02:57:23,628 - DEBUG - Response Code: 404
2026-08-31 02:57:23,628 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/04beb654-f46a-4736-a40d-b7641ac4a37a
2026-08-31 02:57:23,635 - DEBUG - Response Code: 404
2026-08-31 02:57:23,635 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/04beb654-f46a-4736-a40d-b7641ac4a37a
2026-08-31 02:57:23,642 - DEBUG - Response Code: 404
2026-08-31 02:57:23,642 - INFO - VM ctest-VM31-47461636 is fully removed in API-Server
2026-08-31 02:57:23,798 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2026-08-31 02:57:23,848 - DEBUG - Validated that vrouter 10.0.0.186 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-81840964:ctest-VN3-58658296
2026-08-31 02:57:23,861 - DEBUG - Validated that vrouter 10.0.0.176 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-81840964:ctest-VN3-58658296
2026-08-31 02:57:23,861 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-81840964:ctest-VN3-58658296
2026-08-31 02:57:23,862 - INFO - VM ctest-VM31-47461636 is removed in Compute, and routes are removed in all compute nodes
2026-08-31 02:57:24,114 - INFO - Routes for VM ctest-VM31-47461636 is removed in all control-nodes
2026-08-31 02:57:24,114 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6237-1:Compute:contrail-vrouter-agent:0?flat
2026-08-31 02:57:24,153 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6237-1?flat
2026-08-31 02:57:24,186 - DEBUG - VM 04beb654-f46a-4736-a40d-b7641ac4a37a is still present in vrouter an-jenkins-deploy-platform-ansible-os-6237-1 uve
2026-08-31 02:57:24,186 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-08-31 02:57:24,186 - DEBUG - interface for vm 04beb654-f46a-4736-a40d-b7641ac4a37a deleted from vrouter uve ...
2026-08-31 02:57:24,186 - DEBUG - Deleted interface not in error interface list ...
2026-08-31 02:57:27,187 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6237-1:Compute:contrail-vrouter-agent:0?flat
2026-08-31 02:57:27,221 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6237-1?flat
2026-08-31 02:57:27,252 - DEBUG - VM 04beb654-f46a-4736-a40d-b7641ac4a37a is still present in vrouter an-jenkins-deploy-platform-ansible-os-6237-1 uve
2026-08-31 02:57:27,252 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-08-31 02:57:27,252 - DEBUG - interface for vm 04beb654-f46a-4736-a40d-b7641ac4a37a deleted from vrouter uve ...
2026-08-31 02:57:27,252 - DEBUG - Deleted interface not in error interface list ...
2026-08-31 02:57:30,253 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6237-1:Compute:contrail-vrouter-agent:0?flat
2026-08-31 02:57:30,291 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6237-1?flat
2026-08-31 02:57:30,325 - DEBUG - VM 04beb654-f46a-4736-a40d-b7641ac4a37a is not present in vrouter an-jenkins-deploy-platform-ansible-os-6237-1 uve
2026-08-31 02:57:30,326 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-08-31 02:57:30,326 - DEBUG - interface for vm 04beb654-f46a-4736-a40d-b7641ac4a37a deleted from vrouter uve ...
2026-08-31 02:57:30,326 - DEBUG - Deleted interface not in error interface list ...
2026-08-31 02:57:30,326 - DEBUG - Validated that VM 04beb654-f46a-4736-a40d-b7641ac4a37a is removed in Vrouter UVE
2026-08-31 02:57:30,326 - DEBUG - Verifying through opserver in 10.0.0.50
2026-08-31 02:57:30,326 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-81840964:ctest-VN3-58658296?flat
2026-08-31 02:57:30,348 - DEBUG - 04beb654-f46a-4736-a40d-b7641ac4a37a BM not in default-domain:ctest-TestPolicyAclIpv4v6-81840964:ctest-VN3-58658296 uve
2026-08-31 02:57:30,348 - DEBUG - Validated that VM 04beb654-f46a-4736-a40d-b7641ac4a37a is not present in the VN UVE
2026-08-31 02:57:30,348 - DEBUG - Verifying the 04beb654-f46a-4736-a40d-b7641ac4a37a virtual network link through opserver 10.0.0.50
2026-08-31 02:57:30,348 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-machines
2026-08-31 02:57:30,368 - INFO - 04beb654-f46a-4736-a40d-b7641ac4a37a vm uve deleted from opserver
2026-08-31 02:57:30,368 - DEBUG - Verifying the 04beb654-f46a-4736-a40d-b7641ac4a37a virtual network link through opserver 10.0.0.185
2026-08-31 02:57:30,368 - DEBUG - Requesting: http://10.0.0.185:8081/analytics/uves/virtual-machines
2026-08-31 02:57:30,382 - INFO - 04beb654-f46a-4736-a40d-b7641ac4a37a vm uve deleted from opserver
2026-08-31 02:57:30,382 - DEBUG - Verifying the 04beb654-f46a-4736-a40d-b7641ac4a37a virtual network link through opserver 10.0.0.38
2026-08-31 02:57:30,382 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines
2026-08-31 02:57:30,396 - INFO - 04beb654-f46a-4736-a40d-b7641ac4a37a vm uve deleted from opserver
2026-08-31 02:57:30,396 - INFO - Validated that VM 04beb654-f46a-4736-a40d-b7641ac4a37a is removed from Opserver
2026-08-31 02:57:30,397 - INFO - Deleting VM ctest-VM21-16096974
2026-08-31 02:57:30,485 - DEBUG - Verifying in api server 10.0.0.38
2026-08-31 02:57:30,485 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/eaceed45-d18e-47ab-9e8c-a7d406a0fcd6
2026-08-31 02:57:30,494 - DEBUG - VM ID eaceed45-d18e-47ab-9e8c-a7d406a0fcd6 of VM ctest-VM21-16096974 is still found in API Server
2026-08-31 02:57:32,495 - DEBUG - Verifying in api server 10.0.0.38
2026-08-31 02:57:32,495 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/eaceed45-d18e-47ab-9e8c-a7d406a0fcd6
2026-08-31 02:57:32,506 - DEBUG - VM ID eaceed45-d18e-47ab-9e8c-a7d406a0fcd6 of VM ctest-VM21-16096974 is still found in API Server
2026-08-31 02:57:34,506 - DEBUG - Verifying in api server 10.0.0.38
2026-08-31 02:57:34,506 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/eaceed45-d18e-47ab-9e8c-a7d406a0fcd6
2026-08-31 02:57:34,515 - DEBUG - Response Code: 404
2026-08-31 02:57:34,515 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/eaceed45-d18e-47ab-9e8c-a7d406a0fcd6
2026-08-31 02:57:34,524 - DEBUG - Response Code: 404
2026-08-31 02:57:34,524 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/eaceed45-d18e-47ab-9e8c-a7d406a0fcd6
2026-08-31 02:57:34,532 - DEBUG - Response Code: 404
2026-08-31 02:57:34,533 - INFO - VM ctest-VM21-16096974 is fully removed in API-Server
2026-08-31 02:57:34,690 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2026-08-31 02:57:34,751 - DEBUG - Validated that vrouter 10.0.0.186 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-81840964:ctest-VN2-38337471
2026-08-31 02:57:34,762 - DEBUG - Validated that vrouter 10.0.0.176 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-81840964:ctest-VN2-38337471
2026-08-31 02:57:34,762 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-81840964:ctest-VN2-38337471
2026-08-31 02:57:34,762 - INFO - VM ctest-VM21-16096974 is removed in Compute, and routes are removed in all compute nodes
2026-08-31 02:57:35,007 - INFO - Routes for VM ctest-VM21-16096974 is removed in all control-nodes
2026-08-31 02:57:35,007 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6237-2:Compute:contrail-vrouter-agent:0?flat
2026-08-31 02:57:35,044 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6237-2?flat
2026-08-31 02:57:35,079 - DEBUG - VM eaceed45-d18e-47ab-9e8c-a7d406a0fcd6 is not present in vrouter an-jenkins-deploy-platform-ansible-os-6237-2 uve
2026-08-31 02:57:35,079 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-08-31 02:57:35,079 - DEBUG - interface for vm eaceed45-d18e-47ab-9e8c-a7d406a0fcd6 deleted from vrouter uve ...
2026-08-31 02:57:35,079 - DEBUG - Deleted interface not in error interface list ...
2026-08-31 02:57:35,079 - DEBUG - Validated that VM eaceed45-d18e-47ab-9e8c-a7d406a0fcd6 is removed in Vrouter UVE
2026-08-31 02:57:35,079 - DEBUG - Verifying through opserver in 10.0.0.50
2026-08-31 02:57:35,079 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-81840964:ctest-VN2-38337471?flat
2026-08-31 02:57:35,098 - DEBUG - eaceed45-d18e-47ab-9e8c-a7d406a0fcd6 BM not in default-domain:ctest-TestPolicyAclIpv4v6-81840964:ctest-VN2-38337471 uve
2026-08-31 02:57:35,098 - DEBUG - Validated that VM eaceed45-d18e-47ab-9e8c-a7d406a0fcd6 is not present in the VN UVE
2026-08-31 02:57:35,098 - DEBUG - Verifying the eaceed45-d18e-47ab-9e8c-a7d406a0fcd6 virtual network link through opserver 10.0.0.50
2026-08-31 02:57:35,098 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-machines
2026-08-31 02:57:35,111 - INFO - eaceed45-d18e-47ab-9e8c-a7d406a0fcd6 vm uve deleted from opserver
2026-08-31 02:57:35,112 - DEBUG - Verifying the eaceed45-d18e-47ab-9e8c-a7d406a0fcd6 virtual network link through opserver 10.0.0.185
2026-08-31 02:57:35,112 - DEBUG - Requesting: http://10.0.0.185:8081/analytics/uves/virtual-machines
2026-08-31 02:57:35,127 - INFO - eaceed45-d18e-47ab-9e8c-a7d406a0fcd6 vm uve deleted from opserver
2026-08-31 02:57:35,127 - DEBUG - Verifying the eaceed45-d18e-47ab-9e8c-a7d406a0fcd6 virtual network link through opserver 10.0.0.38
2026-08-31 02:57:35,127 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines
2026-08-31 02:57:35,141 - INFO - eaceed45-d18e-47ab-9e8c-a7d406a0fcd6 vm uve deleted from opserver
2026-08-31 02:57:35,141 - INFO - Validated that VM eaceed45-d18e-47ab-9e8c-a7d406a0fcd6 is removed from Opserver
2026-08-31 02:57:35,141 - INFO - Deleting VM ctest-VM11-21398684
2026-08-31 02:57:35,230 - DEBUG - Verifying in api server 10.0.0.38
2026-08-31 02:57:35,230 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/2b5fbbc3-a746-48b9-a9df-84d2f72753ad
2026-08-31 02:57:35,241 - DEBUG - VM ID 2b5fbbc3-a746-48b9-a9df-84d2f72753ad of VM ctest-VM11-21398684 is still found in API Server
2026-08-31 02:57:37,242 - DEBUG - Verifying in api server 10.0.0.38
2026-08-31 02:57:37,243 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/2b5fbbc3-a746-48b9-a9df-84d2f72753ad
2026-08-31 02:57:37,253 - DEBUG - VM ID 2b5fbbc3-a746-48b9-a9df-84d2f72753ad of VM ctest-VM11-21398684 is still found in API Server
2026-08-31 02:57:39,254 - DEBUG - Verifying in api server 10.0.0.38
2026-08-31 02:57:39,254 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/2b5fbbc3-a746-48b9-a9df-84d2f72753ad
2026-08-31 02:57:39,262 - DEBUG - Response Code: 404
2026-08-31 02:57:39,262 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/2b5fbbc3-a746-48b9-a9df-84d2f72753ad
2026-08-31 02:57:39,270 - DEBUG - Response Code: 404
2026-08-31 02:57:39,270 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/2b5fbbc3-a746-48b9-a9df-84d2f72753ad
2026-08-31 02:57:39,278 - DEBUG - Response Code: 404
2026-08-31 02:57:39,278 - INFO - VM ctest-VM11-21398684 is fully removed in API-Server
2026-08-31 02:57:39,430 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2026-08-31 02:57:39,481 - DEBUG - Validated that vrouter 10.0.0.186 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-81840964:ctest-VN1-57260360
2026-08-31 02:57:39,492 - DEBUG - Validated that vrouter 10.0.0.176 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-81840964:ctest-VN1-57260360
2026-08-31 02:57:39,493 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-81840964:ctest-VN1-57260360
2026-08-31 02:57:39,493 - INFO - VM ctest-VM11-21398684 is removed in Compute, and routes are removed in all compute nodes
2026-08-31 02:57:39,697 - INFO - Routes for VM ctest-VM11-21398684 is removed in all control-nodes
2026-08-31 02:57:39,697 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6237-1:Compute:contrail-vrouter-agent:0?flat
2026-08-31 02:57:39,737 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6237-1?flat
2026-08-31 02:57:39,771 - DEBUG - VM 2b5fbbc3-a746-48b9-a9df-84d2f72753ad is still present in vrouter an-jenkins-deploy-platform-ansible-os-6237-1 uve
2026-08-31 02:57:39,771 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-08-31 02:57:39,771 - DEBUG - interface for vm 2b5fbbc3-a746-48b9-a9df-84d2f72753ad deleted from vrouter uve ...
2026-08-31 02:57:39,771 - DEBUG - Deleted interface not in error interface list ...
2026-08-31 02:57:42,771 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6237-1:Compute:contrail-vrouter-agent:0?flat
2026-08-31 02:57:42,809 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6237-1?flat
2026-08-31 02:57:42,846 - DEBUG - VM 2b5fbbc3-a746-48b9-a9df-84d2f72753ad is still present in vrouter an-jenkins-deploy-platform-ansible-os-6237-1 uve
2026-08-31 02:57:42,846 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-08-31 02:57:42,846 - DEBUG - interface for vm 2b5fbbc3-a746-48b9-a9df-84d2f72753ad deleted from vrouter uve ...
2026-08-31 02:57:42,846 - DEBUG - Deleted interface not in error interface list ...
2026-08-31 02:57:45,847 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6237-1:Compute:contrail-vrouter-agent:0?flat
2026-08-31 02:57:45,888 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6237-1?flat
2026-08-31 02:57:45,920 - DEBUG - VM 2b5fbbc3-a746-48b9-a9df-84d2f72753ad is still present in vrouter an-jenkins-deploy-platform-ansible-os-6237-1 uve
2026-08-31 02:57:45,920 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-08-31 02:57:45,920 - DEBUG - interface for vm 2b5fbbc3-a746-48b9-a9df-84d2f72753ad deleted from vrouter uve ...
2026-08-31 02:57:45,920 - DEBUG - Deleted interface not in error interface list ...
2026-08-31 02:57:48,921 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6237-1:Compute:contrail-vrouter-agent:0?flat
2026-08-31 02:57:48,960 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6237-1?flat
2026-08-31 02:57:48,992 - DEBUG - VM 2b5fbbc3-a746-48b9-a9df-84d2f72753ad is still present in vrouter an-jenkins-deploy-platform-ansible-os-6237-1 uve
2026-08-31 02:57:48,992 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-08-31 02:57:48,992 - DEBUG - interface for vm 2b5fbbc3-a746-48b9-a9df-84d2f72753ad deleted from vrouter uve ...
2026-08-31 02:57:48,992 - DEBUG - Deleted interface not in error interface list ...
2026-08-31 02:57:51,992 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6237-1:Compute:contrail-vrouter-agent:0?flat
2026-08-31 02:57:52,035 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6237-1?flat
2026-08-31 02:57:52,085 - DEBUG - VM 2b5fbbc3-a746-48b9-a9df-84d2f72753ad is still present in vrouter an-jenkins-deploy-platform-ansible-os-6237-1 uve
2026-08-31 02:57:52,085 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-08-31 02:57:52,085 - DEBUG - interface for vm 2b5fbbc3-a746-48b9-a9df-84d2f72753ad deleted from vrouter uve ...
2026-08-31 02:57:52,085 - DEBUG - Deleted interface not in error interface list ...
2026-08-31 02:57:55,086 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6237-1:Compute:contrail-vrouter-agent:0?flat
2026-08-31 02:57:55,121 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6237-1?flat
2026-08-31 02:57:55,152 - DEBUG - VM 2b5fbbc3-a746-48b9-a9df-84d2f72753ad is still present in vrouter an-jenkins-deploy-platform-ansible-os-6237-1 uve
2026-08-31 02:57:55,152 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-08-31 02:57:55,152 - DEBUG - interface for vm 2b5fbbc3-a746-48b9-a9df-84d2f72753ad deleted from vrouter uve ...
2026-08-31 02:57:55,152 - DEBUG - Deleted interface not in error interface list ...
2026-08-31 02:57:58,153 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6237-1:Compute:contrail-vrouter-agent:0?flat
2026-08-31 02:57:58,188 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6237-1?flat
2026-08-31 02:57:58,218 - DEBUG - VM 2b5fbbc3-a746-48b9-a9df-84d2f72753ad is still present in vrouter an-jenkins-deploy-platform-ansible-os-6237-1 uve
2026-08-31 02:57:58,218 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-08-31 02:57:58,218 - DEBUG - interface for vm 2b5fbbc3-a746-48b9-a9df-84d2f72753ad deleted from vrouter uve ...
2026-08-31 02:57:58,219 - DEBUG - Deleted interface not in error interface list ...
2026-08-31 02:58:01,220 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6237-1:Compute:contrail-vrouter-agent:0?flat
2026-08-31 02:58:01,253 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6237-1?flat
2026-08-31 02:58:01,283 - DEBUG - VM 2b5fbbc3-a746-48b9-a9df-84d2f72753ad is not present in vrouter an-jenkins-deploy-platform-ansible-os-6237-1 uve
2026-08-31 02:58:01,283 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-08-31 02:58:01,283 - DEBUG - interface for vm 2b5fbbc3-a746-48b9-a9df-84d2f72753ad deleted from vrouter uve ...
2026-08-31 02:58:01,283 - DEBUG - Deleted interface not in error interface list ...
2026-08-31 02:58:01,283 - DEBUG - Validated that VM 2b5fbbc3-a746-48b9-a9df-84d2f72753ad is removed in Vrouter UVE
2026-08-31 02:58:01,283 - DEBUG - Verifying through opserver in 10.0.0.50
2026-08-31 02:58:01,283 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-81840964:ctest-VN1-57260360?flat
2026-08-31 02:58:01,306 - DEBUG - 2b5fbbc3-a746-48b9-a9df-84d2f72753ad BM not in default-domain:ctest-TestPolicyAclIpv4v6-81840964:ctest-VN1-57260360 uve
2026-08-31 02:58:01,306 - DEBUG - Validated that VM 2b5fbbc3-a746-48b9-a9df-84d2f72753ad is not present in the VN UVE
2026-08-31 02:58:01,307 - DEBUG - Verifying the 2b5fbbc3-a746-48b9-a9df-84d2f72753ad virtual network link through opserver 10.0.0.50
2026-08-31 02:58:01,307 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-machines
2026-08-31 02:58:01,320 - INFO - 2b5fbbc3-a746-48b9-a9df-84d2f72753ad vm uve deleted from opserver
2026-08-31 02:58:01,320 - DEBUG - Verifying the 2b5fbbc3-a746-48b9-a9df-84d2f72753ad virtual network link through opserver 10.0.0.185
2026-08-31 02:58:01,320 - DEBUG - Requesting: http://10.0.0.185:8081/analytics/uves/virtual-machines
2026-08-31 02:58:01,335 - INFO - 2b5fbbc3-a746-48b9-a9df-84d2f72753ad vm uve deleted from opserver
2026-08-31 02:58:01,335 - DEBUG - Verifying the 2b5fbbc3-a746-48b9-a9df-84d2f72753ad virtual network link through opserver 10.0.0.38
2026-08-31 02:58:01,335 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines
2026-08-31 02:58:01,350 - INFO - 2b5fbbc3-a746-48b9-a9df-84d2f72753ad vm uve deleted from opserver
2026-08-31 02:58:01,350 - INFO - Validated that VM 2b5fbbc3-a746-48b9-a9df-84d2f72753ad is removed from Opserver
2026-08-31 02:58:01,350 - DEBUG - Detaching the Policy for VN :ctest-VN2-38337471
2026-08-31 02:58:01,350 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-08-31 02:58:01,358 - DEBUG - Requesting: http://10.0.0.50:8082/domain/e2dd173c-f32f-4272-81b1-e77dae6a5597
2026-08-31 02:58:01,377 - DEBUG - Requesting: http://10.0.0.50:8082/project/9c6025b9-44ce-4bd2-a686-72b8e8276bc1
2026-08-31 02:58:01,534 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/4ff43361-cef8-4591-a6ff-c9ae65ba349d
2026-08-31 02:58:01,568 - DEBUG - Requesting: http://10.0.0.50:8082/network-policy/8c0d35e9-ef0c-48ee-8d72-dadaa13c5b6e
2026-08-31 02:58:01,800 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-81840964', 'ctest-policy21-93184226']] from ctest-VN2-38337471
2026-08-31 02:58:01,800 - DEBUG - Detaching the Policy for VN :ctest-VN1-57260360
2026-08-31 02:58:01,800 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-08-31 02:58:01,808 - DEBUG - Requesting: http://10.0.0.50:8082/domain/e2dd173c-f32f-4272-81b1-e77dae6a5597
2026-08-31 02:58:01,833 - DEBUG - Requesting: http://10.0.0.50:8082/project/9c6025b9-44ce-4bd2-a686-72b8e8276bc1
2026-08-31 02:58:01,944 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/0e47b284-8f8f-42a1-87b2-718a6cb7eddc
2026-08-31 02:58:01,961 - DEBUG - Requesting: http://10.0.0.50:8082/network-policy/ad2e5e73-5986-48a7-a602-9ac42b3bdeaf
2026-08-31 02:58:01,971 - DEBUG - Requesting: http://10.0.0.50:8082/network-policy/776531be-be67-4b5b-a0e4-223334dfa051
2026-08-31 02:58:02,222 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-81840964', 'ctest-policy12-64564162'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-81840964', 'ctest-policy13-59278861']] from ctest-VN1-57260360
2026-08-31 02:58:02,306 - INFO - Deleted policy ctest-policy21-93184226
2026-08-31 02:58:02,374 - INFO - Deleted policy ctest-policy12-64564162
2026-08-31 02:58:02,431 - INFO - Deleted policy ctest-policy13-59278861
2026-08-31 02:58:02,431 - INFO - Deleting VN ctest-VN3-58658296
2026-08-31 02:58:02,616 - DEBUG - Response for deleting network ()
2026-08-31 02:58:02,617 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/5f71bdd8-26df-4058-8869-8007ba8e613e
2026-08-31 02:58:02,624 - DEBUG - Response Code: 404
2026-08-31 02:58:02,624 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-08-31 02:58:02,643 - DEBUG - Requesting: http://10.0.0.50:8082/domain/e2dd173c-f32f-4272-81b1-e77dae6a5597
2026-08-31 02:58:02,657 - DEBUG - Requesting: http://10.0.0.50:8082/project/9c6025b9-44ce-4bd2-a686-72b8e8276bc1
2026-08-31 02:58:02,760 - INFO - Validated that VN ctest-VN3-58658296 is not found in API Server
2026-08-31 02:58:02,773 - DEBUG - VN ctest-VN3-58658296 is not present in Agent 10.0.0.186
2026-08-31 02:58:02,787 - DEBUG - VN ctest-VN3-58658296 is not present in Agent 10.0.0.176
2026-08-31 02:58:02,787 - INFO - Validated that VN ctest-VN3-58658296 is not in any agent
2026-08-31 02:58:02,827 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN3-58658296 info
2026-08-31 02:58:02,827 - INFO - Deleting VN ctest-VN2-38337471
2026-08-31 02:58:03,044 - DEBUG - Response for deleting network ()
2026-08-31 02:58:03,045 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/378f0829-a912-48dd-bd7a-9bca99fb9e7f
2026-08-31 02:58:03,056 - DEBUG - Response Code: 404
2026-08-31 02:58:03,056 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-08-31 02:58:03,062 - DEBUG - Requesting: http://10.0.0.50:8082/domain/e2dd173c-f32f-4272-81b1-e77dae6a5597
2026-08-31 02:58:03,075 - DEBUG - Requesting: http://10.0.0.50:8082/project/9c6025b9-44ce-4bd2-a686-72b8e8276bc1
2026-08-31 02:58:03,184 - INFO - Validated that VN ctest-VN2-38337471 is not found in API Server
2026-08-31 02:58:03,199 - DEBUG - VN ctest-VN2-38337471 is not present in Agent 10.0.0.186
2026-08-31 02:58:03,211 - DEBUG - VN ctest-VN2-38337471 is not present in Agent 10.0.0.176
2026-08-31 02:58:03,211 - INFO - Validated that VN ctest-VN2-38337471 is not in any agent
2026-08-31 02:58:03,248 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN2-38337471 info
2026-08-31 02:58:03,248 - INFO - Deleting VN ctest-VN1-57260360
2026-08-31 02:58:03,485 - DEBUG - Response for deleting network ()
2026-08-31 02:58:03,485 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/f144f5e2-8cfa-4c26-88a8-e2dea6fa9788
2026-08-31 02:58:03,496 - DEBUG - Response Code: 404
2026-08-31 02:58:03,496 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-08-31 02:58:03,508 - DEBUG - Requesting: http://10.0.0.50:8082/domain/e2dd173c-f32f-4272-81b1-e77dae6a5597
2026-08-31 02:58:03,525 - DEBUG - Requesting: http://10.0.0.50:8082/project/9c6025b9-44ce-4bd2-a686-72b8e8276bc1
2026-08-31 02:58:03,639 - INFO - Validated that VN ctest-VN1-57260360 is not found in API Server
2026-08-31 02:58:03,654 - DEBUG - VN ctest-VN1-57260360 is not present in Agent 10.0.0.186
2026-08-31 02:58:03,666 - DEBUG - VN ctest-VN1-57260360 is not present in Agent 10.0.0.176
2026-08-31 02:58:03,667 - INFO - Validated that VN ctest-VN1-57260360 is not in any agent
2026-08-31 02:58:03,705 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN1-57260360 info
2026-08-31 02:58:03,814 - INFO - IPAM: ipam3 is not found in API Server
2026-08-31 02:58:03,842 - INFO - IPAM:ipam3 is not found in control node
2026-08-31 02:58:03,990 - INFO - IPAM: ipam2 is not found in API Server
2026-08-31 02:58:04,020 - INFO - IPAM:ipam2 is not found in control node
2026-08-31 02:58:04,170 - INFO - IPAM: ipam1 is not found in API Server
2026-08-31 02:58:04,196 - INFO - IPAM:ipam1 is not found in control node
2026-08-31 02:58:05,495 - DEBUG - No XMPP flaps were noticed during the test
2026-08-31 02:58:05,495 - INFO - END TEST : test_policy_inheritance_src_pol_dst_any : PASSED[0:02:56]
2026-08-31 02:58:05,495 - INFO - --------------------------------------------------------------------------------
2026-08-31 02:58:06,810 - DEBUG - No XMPP flaps were noticed during the test
2026-08-31 02:58:06,810 - INFO - END TEST : test_policy_inheritance_src_pol_dst_any : PASSED[0:02:58]
2026-08-31 02:58:06,810 - INFO - --------------------------------------------------------------------------------
2026-08-31 02:58:07,631 - INFO - Deleted project: ctest-TestPolicyAclIpv4v6-81840964, ID : 9c6025b9-44ce-4bd2-a686-72b8e8276bc1