is in ACTIVE state now
2025-10-16 02:43:21,029 - DEBUG - VM ctest-VM31-91737918 launched on Node an-jenkins-deploy-platform-ansible-os-4339-1
2025-10-16 02:43:21,112 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb
2025-10-16 02:43:21,121 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb
2025-10-16 02:43:21,127 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/66e74cce-64ec-4b7f-800b-ad8210d436cc
2025-10-16 02:43:21,136 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb
2025-10-16 02:43:21,142 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/66e74cce-64ec-4b7f-800b-ad8210d436cc
2025-10-16 02:43:21,151 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/4f5612e8-42d4-48fe-aec2-5b6a37358d9e
2025-10-16 02:43:21,160 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/def7b601-93d6-4d60-be2f-968fcc7d42af
2025-10-16 02:43:21,169 - DEBUG - Verifying in api server 10.0.0.23
2025-10-16 02:43:21,169 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb
2025-10-16 02:43:21,175 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/66e74cce-64ec-4b7f-800b-ad8210d436cc
2025-10-16 02:43:21,184 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/4f5612e8-42d4-48fe-aec2-5b6a37358d9e
2025-10-16 02:43:21,190 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/def7b601-93d6-4d60-be2f-968fcc7d42af
2025-10-16 02:43:21,197 - INFO - VM ctest-VM31-91737918 verfication in all API Servers passed
2025-10-16 02:43:21,214 - DEBUG - VM ctest-VM31-91737918 Tap interface: {'index': '4', 'name': 'tap66e74cce-64', 'uuid': '66e74cce-64ec-4b7f-800b-ad8210d436cc', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-95923021:ctest-VN3-45899492:ctest-VN3-45899492', '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': '13', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-95923021:ctest-VN3-45899492', 'vm_uuid': '8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb', 'vm_name': 'ctest-VM31-91737918', 'ip_addr': '14.48.215.195', 'mac_addr': '02:66:e7:4c:ce:64', '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-95923021:66e74cce-64ec-4b7f-800b-ad8210d436cc', 'sg_uuid_list': ['f4cb548e-b217-4f19-b936-c9a8f191629d'], 'static_route_list': None, 'vm_project_uuid': 'b5880306-5b16-497f-8bb1-1dabc03de537', 'admin_state': 'Enabled', 'flow_key_idx': '41', 'allowed_address_pair_list': None, 'ip6_addr': '36a0:4bc1:7b9c:6928:231:7d3c: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-10-16 02:43:21,214 - DEBUG - Agent 10.0.0.38 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-95923021:ctest-VN3-45899492:ctest-VN3-45899492
2025-10-16 02:43:21,220 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-95923021:ctest-VN3-45899492:ctest-VN3-45899492', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-95923021:ctest-VN3-45899492', 'table_label': '-1', 'vxlan_id': '13', 'evpnindex': '3', 'brindex': '3', 'mplsindex': '0', 'RD': '10.20.0.145:3', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]}
2025-10-16 02:43:21,309 - DEBUG - VM ctest-VM31-91737918 : Tap interface tap66e74cce-64 is set to Active
2025-10-16 02:43:21,309 - DEBUG - Tap interface tap66e74cce-64 detail : {'index': '4', 'name': 'tap66e74cce-64', 'uuid': '66e74cce-64ec-4b7f-800b-ad8210d436cc', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-95923021:ctest-VN3-45899492:ctest-VN3-45899492', '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': '13', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-95923021:ctest-VN3-45899492', 'vm_uuid': '8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb', 'vm_name': 'ctest-VM31-91737918', 'ip_addr': '14.48.215.195', 'mac_addr': '02:66:e7:4c:ce:64', '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-95923021:66e74cce-64ec-4b7f-800b-ad8210d436cc', 'sg_uuid_list': ['f4cb548e-b217-4f19-b936-c9a8f191629d'], 'static_route_list': None, 'vm_project_uuid': 'b5880306-5b16-497f-8bb1-1dabc03de537', 'admin_state': 'Enabled', 'flow_key_idx': '41', 'allowed_address_pair_list': None, 'ip6_addr': '36a0:4bc1:7b9c:6928:231:7d3c: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-10-16 02:43:21,419 - DEBUG - Starting Layer 2 verification in Agent
2025-10-16 02:43:21,433 - DEBUG - Layer 2 path is seen for VM MAC 02:66:e7:4c:ce:64 in agent 10.0.0.38
2025-10-16 02:43:21,433 - DEBUG - Active layer 2 route in agent is present for VMI tap66e74cce-64
2025-10-16 02:43:21,433 - DEBUG - L2 label(36) matches bw route table and interface table
2025-10-16 02:43:21,445 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-95923021:ctest-VN3-45899492 is found in Agent of node 10.0.0.38
2025-10-16 02:43:21,584 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-95923021:ctest-VN3-45899492 is consistent in agent 10.0.0.38
2025-10-16 02:43:21,584 - DEBUG - Route for VM IP ['14.48.215.195', '36a0:4bc1:7b9c:6928:231:7d3c:0:3'] is consistent in agent 10.0.0.38
2025-10-16 02:43:21,584 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-95923021:ctest-VN3-45899492 verification for VM ctest-VM31-91737918 in Agent 10.0.0.38 passed
2025-10-16 02:43:21,690 - DEBUG - Starting all layer 2 verification in agent 10.0.0.38
2025-10-16 02:43:21,703 - DEBUG - Route for VM MAC 02:66:e7:4c:ce:64 is consistent in agent 10.0.0.38
2025-10-16 02:43:22,877 - 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=6.09 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.687 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.687/3.390/6.094/2.703 ms')
2025-10-16 02:43:22,878 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-91737918 passed
2025-10-16 02:43:22,878 - INFO - VM ctest-VM31-91737918 verifications in Compute nodes passed
2025-10-16 02:43:23,075 - DEBUG - Validated VM route 14.48.215.195 in vrouter of 10.0.0.38
2025-10-16 02:43:23,079 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0
2025-10-16 02:43:23,079 - INFO - Validated routes of VM ctest-VM31-91737918 in all vrouters
2025-10-16 02:43:23,597 - DEBUG - Starting all layer2 verification in 10.0.0.23 Control Node
2025-10-16 02:43:23,725 - DEBUG - Layer2 route found for VM MAC 02:66:e7:4c:ce:64 in Control-node 10.0.0.23
2025-10-16 02:43:23,725 - DEBUG - L2 Label for VM ctest-VM31-91737918 same between Control-node 10.0.0.23 and Agent, Expected: 36, Seen: 36
2025-10-16 02:43:23,838 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.23, not supported
2025-10-16 02:43:23,838 - DEBUG - Starting all layer2 verification in 10.0.0.19 Control Node
2025-10-16 02:43:23,961 - DEBUG - Layer2 route found for VM MAC 02:66:e7:4c:ce:64 in Control-node 10.0.0.19
2025-10-16 02:43:23,961 - DEBUG - L2 Label for VM ctest-VM31-91737918 same between Control-node 10.0.0.19 and Agent, Expected: 36, Seen: 36
2025-10-16 02:43:24,071 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.19, not supported
2025-10-16 02:43:24,071 - INFO - Verification in Control-nodes for VM ctest-VM31-91737918 passed
2025-10-16 02:43:24,071 - DEBUG - Verifying the vm in opserver
2025-10-16 02:43:24,071 - DEBUG - Verifying in collector 10.0.0.23 ...
2025-10-16 02:43:24,071 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine/8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb?flat
2025-10-16 02:43:24,086 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-95923021:66e74cce-64ec-4b7f-800b-ad8210d436cc?flat
2025-10-16 02:43:24,103 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-95923021:66e74cce-64ec-4b7f-800b-ad8210d436cc?flat
2025-10-16 02:43:24,120 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-95923021:66e74cce-64ec-4b7f-800b-ad8210d436cc?flat
2025-10-16 02:43:24,137 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine/8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb?flat
2025-10-16 02:43:24,151 - DEBUG - Verifying vm in vn uve
2025-10-16 02:43:24,151 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-95923021:66e74cce-64ec-4b7f-800b-ad8210d436cc?flat
2025-10-16 02:43:24,168 - DEBUG - VM uve shows interface as {'mac_address': '02:66:e7:4c:ce:64', 'l2_active': True, 'vm_name': 'ctest-VM31-91737918', 'admin_state': True, 'label': 32, 'vn_uuid': '96235444-8eab-4201-8ffd-114fd9ac60fe', 'uuid': '66e74cce-64ec-4b7f-800b-ad8210d436cc', 'ip4_active': True, 'port_mirror_enabled': False, 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-95923021:ctest-VN3-45899492', 'is_health_check_active': True, 'ip_address': '14.48.215.195', 'rx_vlan': 65535, 'tx_vlan': 65535, 'active': True, 'ip6_address': '36a0:4bc1:7b9c:6928:231:7d3c:0:3', '__T': 1760582556686045, 'fixed_ip6_list': ['36a0:4bc1:7b9c:6928:231:7d3c:0:3'], 'ip6_active': True, 'gateway': '14.48.215.193', 'vhostuser_mode': 0, 'vm_uuid': '8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb', 'fixed_ip4_list': ['14.48.215.195']}
2025-10-16 02:43:24,168 - DEBUG - VM uve shows ip address as ['14.48.215.195', '36a0:4bc1:7b9c:6928:231:7d3c:0:3']
2025-10-16 02:43:24,168 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-95923021:ctest-VN3-45899492
2025-10-16 02:43:24,168 - INFO - Verifying through opserver in 10.0.0.19
2025-10-16 02:43:24,169 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-95923021:ctest-VN3-45899492?flat
2025-10-16 02:43:24,189 - DEBUG - expected vm list 8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb
2025-10-16 02:43:24,189 - DEBUG - Extracted vm list ['8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb']
2025-10-16 02:43:24,189 - INFO - VM 8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb is present in default-domain:ctest-TestPolicyAclIpv4v6-95923021:ctest-VN3-45899492
2025-10-16 02:43:24,189 - DEBUG - Verifying vm in vrouter uve
2025-10-16 02:43:24,189 - DEBUG - Getting info from collector 10.0.0.23..
2025-10-16 02:43:24,189 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine/8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb?flat
2025-10-16 02:43:24,204 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-4339-1']
2025-10-16 02:43:24,204 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4339-1:Compute:contrail-vrouter-agent:0?flat
2025-10-16 02:43:24,241 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4339-1?flat
2025-10-16 02:43:24,278 - DEBUG - VM 8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb is present in vrouter an-jenkins-deploy-platform-ansible-os-4339-1 uve
2025-10-16 02:43:24,278 - DEBUG - Validated that VM ctest-VM31-91737918 is in Vrouter an-jenkins-deploy-platform-ansible-os-4339-1 UVE
2025-10-16 02:43:24,278 - DEBUG - Verifying vm tap interface/vn in vrouter uve
2025-10-16 02:43:24,285 - DEBUG - Expected tap interface of VM uuid 8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb is default-domain:ctest-TestPolicyAclIpv4v6-95923021:66e74cce-64ec-4b7f-800b-ad8210d436cc
2025-10-16 02:43:24,285 - DEBUG - Expected VN of VM uuid 8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb is default-domain:ctest-TestPolicyAclIpv4v6-95923021:ctest-VN3-45899492
2025-10-16 02:43:24,285 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4339-1:Compute:contrail-vrouter-agent:0?flat
2025-10-16 02:43:24,333 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4339-1?flat
2025-10-16 02:43:24,369 - DEBUG - VM 8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb is present in vrouter an-jenkins-deploy-platform-ansible-os-4339-1 uve
2025-10-16 02:43:24,369 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-95923021:66e74cce-64ec-4b7f-800b-ad8210d436cc of vm 8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb is present in vrouter an-jenkins-deploy-platform-ansible-os-4339-1 uve
2025-10-16 02:43:24,369 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-95923021:ctest-VN3-45899492 of vm 8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb is present in vrouter an-jenkins-deploy-platform-ansible-os-4339-1 uve
2025-10-16 02:43:24,369 - DEBUG - Verifying the 8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb virtual network link through opserver 10.0.0.19
2025-10-16 02:43:24,369 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machines
2025-10-16 02:43:24,382 - DEBUG - VM link and name as {'name': '8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-machine/8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb?flat'}
2025-10-16 02:43:24,382 - DEBUG - Verifying the 8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb virtual network link through opserver 10.0.0.57
2025-10-16 02:43:24,382 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-machines
2025-10-16 02:43:24,395 - DEBUG - VM link and name as {'name': '8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb', 'href': 'http://10.0.0.57:8081/analytics/uves/virtual-machine/8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb?flat'}
2025-10-16 02:43:24,395 - DEBUG - Verifying the 8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb virtual network link through opserver 10.0.0.23
2025-10-16 02:43:24,395 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machines
2025-10-16 02:43:24,409 - DEBUG - VM link and name as {'name': '8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-machine/8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb?flat'}
2025-10-16 02:43:24,409 - INFO - VM ctest-VM31-91737918 validations in Opserver passed
2025-10-16 02:43:24,409 - INFO - Waiting for VM ctest-VM11-86144317 to be up..
2025-10-16 02:43:24,409 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-16 02:43:24,409 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/b16f0684-001f-4dd3-baaf-c623730a8209
2025-10-16 02:43:24,417 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/46e6cc02-0936-4943-ad52-490df7b17a85
2025-10-16 02:43:25,581 - 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.24 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.550 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.550/1.896/3.243/1.346 ms')
2025-10-16 02:43:25,582 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-86144317 passed
2025-10-16 02:43:25,654 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-16 02:43:25,655 - DEBUG - Waiting to SSH to VM ctest-VM11-86144317, IP 106.216.15.195, Port 22
2025-10-16 02:43:25,723 - 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-10-16 02:43:25,796 - DEBUG - VM ctest-VM11-86144317 is NOT ready for SSH connections, VM status: ACTIVE
2025-10-16 02:43:30,797 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-16 02:43:30,797 - DEBUG - Waiting to SSH to VM ctest-VM11-86144317, IP 106.216.15.195, Port 22
2025-10-16 02:43:30,862 - 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-10-16 02:43:30,973 - DEBUG - VM ctest-VM11-86144317 is NOT ready for SSH connections, VM status: ACTIVE
2025-10-16 02:43:35,974 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-16 02:43:35,974 - DEBUG - Waiting to SSH to VM ctest-VM11-86144317, IP 106.216.15.195, Port 22
2025-10-16 02:43:36,143 - DEBUG - VM ctest-VM11-86144317 is ready for SSH connections
2025-10-16 02:43:36,143 - INFO - Waiting for VM ctest-VM21-84537542 to be up..
2025-10-16 02:43:36,143 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-16 02:43:36,143 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/b3f0712e-19e9-4107-9921-b8375f553732
2025-10-16 02:43:36,151 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/5b2630d1-16ad-48aa-8368-316c26ed26dd
2025-10-16 02:43:37,314 - 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=4.12 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.50 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 1.501/2.809/4.117/1.308 ms')
2025-10-16 02:43:37,314 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-84537542 passed
2025-10-16 02:43:37,383 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-16 02:43:37,383 - DEBUG - Waiting to SSH to VM ctest-VM21-84537542, IP 206.55.141.131, Port 22
2025-10-16 02:43:37,451 - 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-10-16 02:43:37,528 - DEBUG - VM ctest-VM21-84537542 is NOT ready for SSH connections, VM status: ACTIVE
2025-10-16 02:43:42,529 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-16 02:43:42,530 - DEBUG - Waiting to SSH to VM ctest-VM21-84537542, IP 206.55.141.131, Port 22
2025-10-16 02:43:42,595 - 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-10-16 02:43:42,672 - DEBUG - VM ctest-VM21-84537542 is NOT ready for SSH connections, VM status: ACTIVE
2025-10-16 02:43:47,674 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-16 02:43:47,674 - DEBUG - Waiting to SSH to VM ctest-VM21-84537542, IP 206.55.141.131, Port 22
2025-10-16 02:43:47,843 - DEBUG - VM ctest-VM21-84537542 is ready for SSH connections
2025-10-16 02:43:47,843 - INFO - Waiting for VM ctest-VM31-91737918 to be up..
2025-10-16 02:43:47,843 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-16 02:43:47,843 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb
2025-10-16 02:43:47,853 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/66e74cce-64ec-4b7f-800b-ad8210d436cc
2025-10-16 02:43:49,005 - 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=3.02 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=1.44 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 = 1.441/2.230/3.019/0.789 ms')
2025-10-16 02:43:49,005 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-91737918 passed
2025-10-16 02:43:49,070 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-16 02:43:49,070 - DEBUG - Waiting to SSH to VM ctest-VM31-91737918, IP 14.48.215.195, Port 22
2025-10-16 02:43:49,228 - DEBUG - VM ctest-VM31-91737918 is ready for SSH connections
2025-10-16 02:43:49,228 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 206.55.141.131, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.38, gateway password: c0ntrail123
2025-10-16 02:43:49,228 - DEBUG - ping -s 56 -c 3 -W 1 206.55.141.131
2025-10-16 02:43:58,383 - DEBUG - PING 206.55.141.131 (206.55.141.131) 56(84) bytes of data.
64 bytes from 206.55.141.131: icmp_req=1 ttl=63 time=9.01 ms
64 bytes from 206.55.141.131: icmp_req=2 ttl=63 time=3.30 ms
64 bytes from 206.55.141.131: icmp_req=3 ttl=63 time=1.59 ms
--- 206.55.141.131 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 1.591/4.635/9.015/3.174 ms
2025-10-16 02:43:58,383 - INFO - Ping to IP 206.55.141.131 from VM ctest-VM11-86144317 passed
2025-10-16 02:43:58,384 - DEBUG - Running remote_cmd, Cmd : ping6 -s 56 -c 3 -W 1 3ad1:f6f8:b568:21b:a11a:37fb:0:3, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.38, gateway password: c0ntrail123
2025-10-16 02:43:58,384 - DEBUG - ping6 -s 56 -c 3 -W 1 3ad1:f6f8:b568:21b:a11a:37fb:0:3
2025-10-16 02:44:00,890 - DEBUG - PING 3ad1:f6f8:b568:21b:a11a:37fb:0:3(3ad1:f6f8:b568:21b:a11a:37fb:0:3) 56 data bytes
64 bytes from 3ad1:f6f8:b568:21b:a11a:37fb:0:3: icmp_seq=1 ttl=64 time=9.25 ms
64 bytes from 3ad1:f6f8:b568:21b:a11a:37fb:0:3: icmp_seq=2 ttl=64 time=1.26 ms
64 bytes from 3ad1:f6f8:b568:21b:a11a:37fb:0:3: icmp_seq=3 ttl=64 time=3.19 ms
--- 3ad1:f6f8:b568:21b:a11a:37fb:0:3 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2003ms
rtt min/avg/max/mdev = 1.267/4.569/9.250/3.401 ms
2025-10-16 02:44:00,891 - INFO - Ping to IP 3ad1:f6f8:b568:21b:a11a:37fb:0:3 from VM ctest-VM11-86144317 passed
2025-10-16 02:44:00,891 - INFO - Test with src as policy and dst as any PASSED
2025-10-16 02:44:00,891 - INFO - Deleting VM ctest-VM31-91737918
2025-10-16 02:44:00,962 - DEBUG - Verifying in api server 10.0.0.23
2025-10-16 02:44:00,962 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb
2025-10-16 02:44:00,975 - DEBUG - VM ID 8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb of VM ctest-VM31-91737918 is still found in API Server
2025-10-16 02:44:02,976 - DEBUG - Verifying in api server 10.0.0.23
2025-10-16 02:44:02,976 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb
2025-10-16 02:44:02,985 - DEBUG - Response Code: 404
2025-10-16 02:44:02,985 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb
2025-10-16 02:44:02,990 - DEBUG - Response Code: 404
2025-10-16 02:44:02,990 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb
2025-10-16 02:44:02,996 - DEBUG - Response Code: 404
2025-10-16 02:44:02,996 - INFO - VM ctest-VM31-91737918 is fully removed in API-Server
2025-10-16 02:44:03,146 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2025-10-16 02:44:03,215 - DEBUG - Validated that vrouter 10.0.0.38 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-95923021:ctest-VN3-45899492
2025-10-16 02:44:03,232 - DEBUG - Validated that vrouter 10.0.0.129 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-95923021:ctest-VN3-45899492
2025-10-16 02:44:03,232 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-95923021:ctest-VN3-45899492
2025-10-16 02:44:03,232 - INFO - VM ctest-VM31-91737918 is removed in Compute, and routes are removed in all compute nodes
2025-10-16 02:44:03,459 - INFO - Routes for VM ctest-VM31-91737918 is removed in all control-nodes
2025-10-16 02:44:03,459 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4339-1:Compute:contrail-vrouter-agent:0?flat
2025-10-16 02:44:03,494 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4339-1?flat
2025-10-16 02:44:03,524 - DEBUG - VM 8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb is still present in vrouter an-jenkins-deploy-platform-ansible-os-4339-1 uve
2025-10-16 02:44:03,524 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-10-16 02:44:03,524 - DEBUG - interface for vm 8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb deleted from vrouter uve ...
2025-10-16 02:44:03,524 - DEBUG - Deleted interface not in error interface list ...
2025-10-16 02:44:06,525 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4339-1:Compute:contrail-vrouter-agent:0?flat
2025-10-16 02:44:06,570 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4339-1?flat
2025-10-16 02:44:06,604 - DEBUG - VM 8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb is still present in vrouter an-jenkins-deploy-platform-ansible-os-4339-1 uve
2025-10-16 02:44:06,604 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-10-16 02:44:06,604 - DEBUG - interface for vm 8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb deleted from vrouter uve ...
2025-10-16 02:44:06,604 - DEBUG - Deleted interface not in error interface list ...
2025-10-16 02:44:09,606 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4339-1:Compute:contrail-vrouter-agent:0?flat
2025-10-16 02:44:09,657 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4339-1?flat
2025-10-16 02:44:09,688 - DEBUG - VM 8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb is not present in vrouter an-jenkins-deploy-platform-ansible-os-4339-1 uve
2025-10-16 02:44:09,688 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-10-16 02:44:09,688 - DEBUG - interface for vm 8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb deleted from vrouter uve ...
2025-10-16 02:44:09,688 - DEBUG - Deleted interface not in error interface list ...
2025-10-16 02:44:09,688 - DEBUG - Validated that VM 8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb is removed in Vrouter UVE
2025-10-16 02:44:09,688 - DEBUG - Verifying through opserver in 10.0.0.19
2025-10-16 02:44:09,688 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-95923021:ctest-VN3-45899492?flat
2025-10-16 02:44:09,714 - DEBUG - 8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb BM not in default-domain:ctest-TestPolicyAclIpv4v6-95923021:ctest-VN3-45899492 uve
2025-10-16 02:44:09,714 - DEBUG - Validated that VM 8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb is not present in the VN UVE
2025-10-16 02:44:09,714 - DEBUG - Verifying the 8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb virtual network link through opserver 10.0.0.19
2025-10-16 02:44:09,714 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machines
2025-10-16 02:44:09,727 - INFO - 8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb vm uve deleted from opserver
2025-10-16 02:44:09,727 - DEBUG - Verifying the 8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb virtual network link through opserver 10.0.0.57
2025-10-16 02:44:09,727 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-machines
2025-10-16 02:44:09,740 - INFO - 8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb vm uve deleted from opserver
2025-10-16 02:44:09,740 - DEBUG - Verifying the 8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb virtual network link through opserver 10.0.0.23
2025-10-16 02:44:09,740 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machines
2025-10-16 02:44:09,757 - INFO - 8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb vm uve deleted from opserver
2025-10-16 02:44:09,757 - INFO - Validated that VM 8b506dfd-e676-4ef4-bfaf-bd8ab49fd6fb is removed from Opserver
2025-10-16 02:44:09,757 - INFO - Deleting VM ctest-VM21-84537542
2025-10-16 02:44:09,830 - DEBUG - Verifying in api server 10.0.0.23
2025-10-16 02:44:09,830 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/b3f0712e-19e9-4107-9921-b8375f553732
2025-10-16 02:44:09,839 - DEBUG - VM ID b3f0712e-19e9-4107-9921-b8375f553732 of VM ctest-VM21-84537542 is still found in API Server
2025-10-16 02:44:11,839 - DEBUG - Verifying in api server 10.0.0.23
2025-10-16 02:44:11,839 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/b3f0712e-19e9-4107-9921-b8375f553732
2025-10-16 02:44:11,846 - DEBUG - Response Code: 404
2025-10-16 02:44:11,846 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/b3f0712e-19e9-4107-9921-b8375f553732
2025-10-16 02:44:11,851 - DEBUG - Response Code: 404
2025-10-16 02:44:11,851 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/b3f0712e-19e9-4107-9921-b8375f553732
2025-10-16 02:44:11,856 - DEBUG - Response Code: 404
2025-10-16 02:44:11,856 - INFO - VM ctest-VM21-84537542 is fully removed in API-Server
2025-10-16 02:44:12,010 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2025-10-16 02:44:12,050 - DEBUG - Validated that vrouter 10.0.0.38 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-95923021:ctest-VN2-64934170
2025-10-16 02:44:12,060 - DEBUG - Validated that vrouter 10.0.0.129 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-95923021:ctest-VN2-64934170
2025-10-16 02:44:12,060 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-95923021:ctest-VN2-64934170
2025-10-16 02:44:12,061 - INFO - VM ctest-VM21-84537542 is removed in Compute, and routes are removed in all compute nodes
2025-10-16 02:44:12,280 - INFO - Routes for VM ctest-VM21-84537542 is removed in all control-nodes
2025-10-16 02:44:12,280 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4339-2:Compute:contrail-vrouter-agent:0?flat
2025-10-16 02:44:12,317 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4339-2?flat
2025-10-16 02:44:12,350 - DEBUG - VM b3f0712e-19e9-4107-9921-b8375f553732 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4339-2 uve
2025-10-16 02:44:12,350 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-10-16 02:44:12,350 - DEBUG - interface for vm b3f0712e-19e9-4107-9921-b8375f553732 deleted from vrouter uve ...
2025-10-16 02:44:12,350 - DEBUG - Deleted interface not in error interface list ...
2025-10-16 02:44:15,351 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4339-2:Compute:contrail-vrouter-agent:0?flat
2025-10-16 02:44:15,390 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4339-2?flat
2025-10-16 02:44:15,421 - DEBUG - VM b3f0712e-19e9-4107-9921-b8375f553732 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4339-2 uve
2025-10-16 02:44:15,421 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-10-16 02:44:15,422 - DEBUG - interface for vm b3f0712e-19e9-4107-9921-b8375f553732 deleted from vrouter uve ...
2025-10-16 02:44:15,422 - DEBUG - Deleted interface not in error interface list ...
2025-10-16 02:44:18,422 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4339-2:Compute:contrail-vrouter-agent:0?flat
2025-10-16 02:44:18,454 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4339-2?flat
2025-10-16 02:44:18,481 - DEBUG - VM b3f0712e-19e9-4107-9921-b8375f553732 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4339-2 uve
2025-10-16 02:44:18,481 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-10-16 02:44:18,481 - DEBUG - interface for vm b3f0712e-19e9-4107-9921-b8375f553732 deleted from vrouter uve ...
2025-10-16 02:44:18,481 - DEBUG - Deleted interface not in error interface list ...
2025-10-16 02:44:21,482 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4339-2:Compute:contrail-vrouter-agent:0?flat
2025-10-16 02:44:21,514 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4339-2?flat
2025-10-16 02:44:21,542 - DEBUG - VM b3f0712e-19e9-4107-9921-b8375f553732 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4339-2 uve
2025-10-16 02:44:21,542 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-10-16 02:44:21,542 - DEBUG - interface for vm b3f0712e-19e9-4107-9921-b8375f553732 deleted from vrouter uve ...
2025-10-16 02:44:21,542 - DEBUG - Deleted interface not in error interface list ...
2025-10-16 02:44:24,542 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4339-2:Compute:contrail-vrouter-agent:0?flat
2025-10-16 02:44:24,573 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4339-2?flat
2025-10-16 02:44:24,601 - DEBUG - VM b3f0712e-19e9-4107-9921-b8375f553732 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4339-2 uve
2025-10-16 02:44:24,601 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-10-16 02:44:24,601 - DEBUG - interface for vm b3f0712e-19e9-4107-9921-b8375f553732 deleted from vrouter uve ...
2025-10-16 02:44:24,601 - DEBUG - Deleted interface not in error interface list ...
2025-10-16 02:44:27,602 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4339-2:Compute:contrail-vrouter-agent:0?flat
2025-10-16 02:44:27,632 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4339-2?flat
2025-10-16 02:44:27,658 - DEBUG - VM b3f0712e-19e9-4107-9921-b8375f553732 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4339-2 uve
2025-10-16 02:44:27,658 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-10-16 02:44:27,658 - DEBUG - interface for vm b3f0712e-19e9-4107-9921-b8375f553732 deleted from vrouter uve ...
2025-10-16 02:44:27,658 - DEBUG - Deleted interface not in error interface list ...
2025-10-16 02:44:30,659 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4339-2:Compute:contrail-vrouter-agent:0?flat
2025-10-16 02:44:30,694 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4339-2?flat
2025-10-16 02:44:30,720 - DEBUG - VM b3f0712e-19e9-4107-9921-b8375f553732 is not present in vrouter an-jenkins-deploy-platform-ansible-os-4339-2 uve
2025-10-16 02:44:30,720 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-10-16 02:44:30,720 - DEBUG - interface for vm b3f0712e-19e9-4107-9921-b8375f553732 deleted from vrouter uve ...
2025-10-16 02:44:30,720 - DEBUG - Deleted interface not in error interface list ...
2025-10-16 02:44:30,720 - DEBUG - Validated that VM b3f0712e-19e9-4107-9921-b8375f553732 is removed in Vrouter UVE
2025-10-16 02:44:30,720 - DEBUG - Verifying through opserver in 10.0.0.19
2025-10-16 02:44:30,720 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-95923021:ctest-VN2-64934170?flat
2025-10-16 02:44:30,743 - DEBUG - b3f0712e-19e9-4107-9921-b8375f553732 BM not in default-domain:ctest-TestPolicyAclIpv4v6-95923021:ctest-VN2-64934170 uve
2025-10-16 02:44:30,743 - DEBUG - Validated that VM b3f0712e-19e9-4107-9921-b8375f553732 is not present in the VN UVE
2025-10-16 02:44:30,743 - DEBUG - Verifying the b3f0712e-19e9-4107-9921-b8375f553732 virtual network link through opserver 10.0.0.19
2025-10-16 02:44:30,743 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machines
2025-10-16 02:44:30,754 - INFO - b3f0712e-19e9-4107-9921-b8375f553732 vm uve deleted from opserver
2025-10-16 02:44:30,754 - DEBUG - Verifying the b3f0712e-19e9-4107-9921-b8375f553732 virtual network link through opserver 10.0.0.57
2025-10-16 02:44:30,754 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-machines
2025-10-16 02:44:30,765 - INFO - b3f0712e-19e9-4107-9921-b8375f553732 vm uve deleted from opserver
2025-10-16 02:44:30,765 - DEBUG - Verifying the b3f0712e-19e9-4107-9921-b8375f553732 virtual network link through opserver 10.0.0.23
2025-10-16 02:44:30,765 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machines
2025-10-16 02:44:30,777 - INFO - b3f0712e-19e9-4107-9921-b8375f553732 vm uve deleted from opserver
2025-10-16 02:44:30,777 - INFO - Validated that VM b3f0712e-19e9-4107-9921-b8375f553732 is removed from Opserver
2025-10-16 02:44:30,777 - INFO - Deleting VM ctest-VM11-86144317
2025-10-16 02:44:30,842 - DEBUG - Verifying in api server 10.0.0.23
2025-10-16 02:44:30,842 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/b16f0684-001f-4dd3-baaf-c623730a8209
2025-10-16 02:44:30,850 - DEBUG - VM ID b16f0684-001f-4dd3-baaf-c623730a8209 of VM ctest-VM11-86144317 is still found in API Server
2025-10-16 02:44:32,851 - DEBUG - Verifying in api server 10.0.0.23
2025-10-16 02:44:32,851 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/b16f0684-001f-4dd3-baaf-c623730a8209
2025-10-16 02:44:32,857 - DEBUG - Response Code: 404
2025-10-16 02:44:32,858 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/b16f0684-001f-4dd3-baaf-c623730a8209
2025-10-16 02:44:32,863 - DEBUG - Response Code: 404
2025-10-16 02:44:32,863 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/b16f0684-001f-4dd3-baaf-c623730a8209
2025-10-16 02:44:32,868 - DEBUG - Response Code: 404
2025-10-16 02:44:32,868 - INFO - VM ctest-VM11-86144317 is fully removed in API-Server
2025-10-16 02:44:33,029 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2025-10-16 02:44:33,070 - DEBUG - Validated that vrouter 10.0.0.38 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-95923021:ctest-VN1-64897415
2025-10-16 02:44:33,080 - DEBUG - Validated that vrouter 10.0.0.129 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-95923021:ctest-VN1-64897415
2025-10-16 02:44:33,080 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-95923021:ctest-VN1-64897415
2025-10-16 02:44:33,080 - INFO - VM ctest-VM11-86144317 is removed in Compute, and routes are removed in all compute nodes
2025-10-16 02:44:33,271 - INFO - Routes for VM ctest-VM11-86144317 is removed in all control-nodes
2025-10-16 02:44:33,272 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4339-1:Compute:contrail-vrouter-agent:0?flat
2025-10-16 02:44:33,303 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4339-1?flat
2025-10-16 02:44:33,332 - DEBUG - VM b16f0684-001f-4dd3-baaf-c623730a8209 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4339-1 uve
2025-10-16 02:44:33,332 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-10-16 02:44:33,332 - DEBUG - interface for vm b16f0684-001f-4dd3-baaf-c623730a8209 deleted from vrouter uve ...
2025-10-16 02:44:33,332 - DEBUG - Deleted interface not in error interface list ...
2025-10-16 02:44:36,332 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4339-1:Compute:contrail-vrouter-agent:0?flat
2025-10-16 02:44:36,366 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4339-1?flat
2025-10-16 02:44:36,395 - DEBUG - VM b16f0684-001f-4dd3-baaf-c623730a8209 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4339-1 uve
2025-10-16 02:44:36,395 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-10-16 02:44:36,395 - DEBUG - interface for vm b16f0684-001f-4dd3-baaf-c623730a8209 deleted from vrouter uve ...
2025-10-16 02:44:36,395 - DEBUG - Deleted interface not in error interface list ...
2025-10-16 02:44:39,396 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4339-1:Compute:contrail-vrouter-agent:0?flat
2025-10-16 02:44:39,434 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4339-1?flat
2025-10-16 02:44:39,462 - DEBUG - VM b16f0684-001f-4dd3-baaf-c623730a8209 is not present in vrouter an-jenkins-deploy-platform-ansible-os-4339-1 uve
2025-10-16 02:44:39,462 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-10-16 02:44:39,462 - DEBUG - interface for vm b16f0684-001f-4dd3-baaf-c623730a8209 deleted from vrouter uve ...
2025-10-16 02:44:39,462 - DEBUG - Deleted interface not in error interface list ...
2025-10-16 02:44:39,462 - DEBUG - Validated that VM b16f0684-001f-4dd3-baaf-c623730a8209 is removed in Vrouter UVE
2025-10-16 02:44:39,462 - DEBUG - Verifying through opserver in 10.0.0.19
2025-10-16 02:44:39,463 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-95923021:ctest-VN1-64897415?flat
2025-10-16 02:44:39,480 - DEBUG - b16f0684-001f-4dd3-baaf-c623730a8209 BM not in default-domain:ctest-TestPolicyAclIpv4v6-95923021:ctest-VN1-64897415 uve
2025-10-16 02:44:39,480 - DEBUG - Validated that VM b16f0684-001f-4dd3-baaf-c623730a8209 is not present in the VN UVE
2025-10-16 02:44:39,480 - DEBUG - Verifying the b16f0684-001f-4dd3-baaf-c623730a8209 virtual network link through opserver 10.0.0.19
2025-10-16 02:44:39,480 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machines
2025-10-16 02:44:39,492 - DEBUG - No links retuned for b16f0684-001f-4dd3-baaf-c623730a8209
2025-10-16 02:44:39,492 - INFO - b16f0684-001f-4dd3-baaf-c623730a8209 vm uve deleted from opserver
2025-10-16 02:44:39,492 - DEBUG - Verifying the b16f0684-001f-4dd3-baaf-c623730a8209 virtual network link through opserver 10.0.0.57
2025-10-16 02:44:39,492 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-machines
2025-10-16 02:44:39,504 - DEBUG - No links retuned for b16f0684-001f-4dd3-baaf-c623730a8209
2025-10-16 02:44:39,504 - INFO - b16f0684-001f-4dd3-baaf-c623730a8209 vm uve deleted from opserver
2025-10-16 02:44:39,504 - DEBUG - Verifying the b16f0684-001f-4dd3-baaf-c623730a8209 virtual network link through opserver 10.0.0.23
2025-10-16 02:44:39,504 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machines
2025-10-16 02:44:39,515 - DEBUG - No links retuned for b16f0684-001f-4dd3-baaf-c623730a8209
2025-10-16 02:44:39,515 - INFO - b16f0684-001f-4dd3-baaf-c623730a8209 vm uve deleted from opserver
2025-10-16 02:44:39,515 - INFO - Validated that VM b16f0684-001f-4dd3-baaf-c623730a8209 is removed from Opserver
2025-10-16 02:44:39,515 - DEBUG - Detaching the Policy for VN :ctest-VN2-64934170
2025-10-16 02:44:39,515 - DEBUG - Requesting: http://10.0.0.19:8082/domains
2025-10-16 02:44:39,520 - DEBUG - Requesting: http://10.0.0.19:8082/domain/446ea2e6-9d96-4973-a55a-3f633ea4d0f9
2025-10-16 02:44:39,531 - DEBUG - Requesting: http://10.0.0.19:8082/project/b5880306-5b16-497f-8bb1-1dabc03de537
2025-10-16 02:44:39,627 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/059ff9ce-7ae6-4353-9c53-10c55f5fb89d
2025-10-16 02:44:39,643 - DEBUG - Requesting: http://10.0.0.19:8082/network-policy/269ce87d-1419-48f1-9855-fdf1d1ab6ed9
2025-10-16 02:44:39,855 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-95923021', 'ctest-policy21-91661877']] from ctest-VN2-64934170
2025-10-16 02:44:39,855 - DEBUG - Detaching the Policy for VN :ctest-VN1-64897415
2025-10-16 02:44:39,855 - DEBUG - Requesting: http://10.0.0.19:8082/domains
2025-10-16 02:44:39,862 - DEBUG - Requesting: http://10.0.0.19:8082/domain/446ea2e6-9d96-4973-a55a-3f633ea4d0f9
2025-10-16 02:44:39,874 - DEBUG - Requesting: http://10.0.0.19:8082/project/b5880306-5b16-497f-8bb1-1dabc03de537
2025-10-16 02:44:39,964 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/f9a7b21d-ddfe-49c3-b307-aeaf897dbb86
2025-10-16 02:44:39,978 - DEBUG - Requesting: http://10.0.0.19:8082/network-policy/c08fa097-2775-4980-9485-e272a31451fb
2025-10-16 02:44:39,989 - DEBUG - Requesting: http://10.0.0.19:8082/network-policy/f3aaecc4-2915-4940-b85d-36dbe29da70c
2025-10-16 02:44:40,169 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-95923021', 'ctest-policy12-78680102'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-95923021', 'ctest-policy13-92208806']] from ctest-VN1-64897415
2025-10-16 02:44:40,251 - INFO - Deleted policy ctest-policy21-91661877
2025-10-16 02:44:40,318 - INFO - Deleted policy ctest-policy12-78680102
2025-10-16 02:44:40,367 - INFO - Deleted policy ctest-policy13-92208806
2025-10-16 02:44:40,367 - INFO - Deleting VN ctest-VN3-45899492
2025-10-16 02:44:40,595 - DEBUG - Response for deleting network ()
2025-10-16 02:44:40,595 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/c94ccfcc-9558-4db8-82d8-6c8f39a2f792
2025-10-16 02:44:40,601 - DEBUG - Response Code: 404
2025-10-16 02:44:40,601 - DEBUG - Requesting: http://10.0.0.19:8082/domains
2025-10-16 02:44:40,607 - DEBUG - Requesting: http://10.0.0.19:8082/domain/446ea2e6-9d96-4973-a55a-3f633ea4d0f9
2025-10-16 02:44:40,664 - DEBUG - Requesting: http://10.0.0.19:8082/project/b5880306-5b16-497f-8bb1-1dabc03de537
2025-10-16 02:44:40,782 - INFO - Validated that VN ctest-VN3-45899492 is not found in API Server
2025-10-16 02:44:40,795 - DEBUG - VN ctest-VN3-45899492 is not present in Agent 10.0.0.38
2025-10-16 02:44:40,816 - DEBUG - VN ctest-VN3-45899492 is not present in Agent 10.0.0.129
2025-10-16 02:44:40,816 - INFO - Validated that VN ctest-VN3-45899492 is not in any agent
2025-10-16 02:44:40,849 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN3-45899492 info
2025-10-16 02:44:40,849 - INFO - Deleting VN ctest-VN2-64934170
2025-10-16 02:44:41,049 - DEBUG - Response for deleting network ()
2025-10-16 02:44:41,049 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/386eb90e-7076-4bbf-80b3-a59dc6c91bdb
2025-10-16 02:44:41,059 - DEBUG - Response Code: 404
2025-10-16 02:44:41,059 - DEBUG - Requesting: http://10.0.0.19:8082/domains
2025-10-16 02:44:41,074 - DEBUG - Requesting: http://10.0.0.19:8082/domain/446ea2e6-9d96-4973-a55a-3f633ea4d0f9
2025-10-16 02:44:41,085 - DEBUG - Requesting: http://10.0.0.19:8082/project/b5880306-5b16-497f-8bb1-1dabc03de537
2025-10-16 02:44:41,166 - INFO - Validated that VN ctest-VN2-64934170 is not found in API Server
2025-10-16 02:44:41,178 - DEBUG - VN ctest-VN2-64934170 is not present in Agent 10.0.0.38
2025-10-16 02:44:41,191 - DEBUG - VN ctest-VN2-64934170 is not present in Agent 10.0.0.129
2025-10-16 02:44:41,191 - INFO - Validated that VN ctest-VN2-64934170 is not in any agent
2025-10-16 02:44:41,233 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN2-64934170 info
2025-10-16 02:44:41,233 - INFO - Deleting VN ctest-VN1-64897415
2025-10-16 02:44:41,413 - DEBUG - Response for deleting network ()
2025-10-16 02:44:41,413 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/ac928673-e478-498e-b3b9-ab4ba6e9bc75
2025-10-16 02:44:41,419 - DEBUG - Response Code: 404
2025-10-16 02:44:41,419 - DEBUG - Requesting: http://10.0.0.19:8082/domains
2025-10-16 02:44:41,426 - DEBUG - Requesting: http://10.0.0.19:8082/domain/446ea2e6-9d96-4973-a55a-3f633ea4d0f9
2025-10-16 02:44:41,454 - DEBUG - Requesting: http://10.0.0.19:8082/project/b5880306-5b16-497f-8bb1-1dabc03de537
2025-10-16 02:44:41,554 - INFO - Validated that VN ctest-VN1-64897415 is not found in API Server
2025-10-16 02:44:41,568 - DEBUG - VN ctest-VN1-64897415 is not present in Agent 10.0.0.38
2025-10-16 02:44:41,582 - DEBUG - VN ctest-VN1-64897415 is not present in Agent 10.0.0.129
2025-10-16 02:44:41,582 - INFO - Validated that VN ctest-VN1-64897415 is not in any agent
2025-10-16 02:44:41,613 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN1-64897415 info
2025-10-16 02:44:41,714 - INFO - IPAM: ipam3 is not found in API Server
2025-10-16 02:44:41,738 - INFO - IPAM:ipam3 is not found in control node
2025-10-16 02:44:41,890 - INFO - IPAM: ipam2 is not found in API Server
2025-10-16 02:44:41,916 - INFO - IPAM:ipam2 is not found in control node
2025-10-16 02:44:42,070 - INFO - IPAM: ipam1 is not found in API Server
2025-10-16 02:44:42,101 - INFO - IPAM:ipam1 is not found in control node
2025-10-16 02:44:43,381 - DEBUG - No XMPP flaps were noticed during the test
2025-10-16 02:44:43,381 - INFO - END TEST : test_policy_inheritance_src_pol_dst_any : PASSED[0:02:26]
2025-10-16 02:44:43,381 - INFO - --------------------------------------------------------------------------------
2025-10-16 02:44:44,653 - DEBUG - No XMPP flaps were noticed during the test
2025-10-16 02:44:44,653 - INFO - END TEST : test_policy_inheritance_src_pol_dst_any : PASSED[0:02:28]
2025-10-16 02:44:44,653 - INFO - --------------------------------------------------------------------------------
2025-10-16 02:44:45,380 - INFO - Deleted project: ctest-TestPolicyAclIpv4v6-95923021, ID : b5880306-5b16-497f-8bb1-1dabc03de537