is in ACTIVE state now
2026-02-02 02:32:29,632 - DEBUG - VM ctest-VM31-72753877 launched on Node an-jenkins-deploy-platform-ansible-os-5167-1
2026-02-02 02:32:29,711 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/c4a47aed-e514-4028-a739-652e3bc30a2c
2026-02-02 02:32:29,720 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/c4a47aed-e514-4028-a739-652e3bc30a2c
2026-02-02 02:32:29,727 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/2f840902-47a4-4682-88e3-01af085b3425
2026-02-02 02:32:29,736 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/c4a47aed-e514-4028-a739-652e3bc30a2c
2026-02-02 02:32:29,743 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/2f840902-47a4-4682-88e3-01af085b3425
2026-02-02 02:32:29,752 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/0c944a1a-4a7d-409c-9bdf-b28a99edf47e
2026-02-02 02:32:29,760 - DEBUG - Verifying in api server 10.0.0.23
2026-02-02 02:32:29,760 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/c4a47aed-e514-4028-a739-652e3bc30a2c
2026-02-02 02:32:29,766 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/2f840902-47a4-4682-88e3-01af085b3425
2026-02-02 02:32:29,774 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/0c944a1a-4a7d-409c-9bdf-b28a99edf47e
2026-02-02 02:32:29,781 - INFO - VM ctest-VM31-72753877 verfication in all API Servers passed
2026-02-02 02:32:29,799 - DEBUG - VM ctest-VM31-72753877 Tap interface: {'index': '4', 'name': 'tap2f840902-47', 'uuid': '2f840902-47a4-4682-88e3-01af085b3425', 'vrf_name': 'default-domain:ctest-TestPolicyAcl-52120613:ctest-VN3-52813967:ctest-VN3-52813967', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Ipv6 Inactive < no-ipv6-addr >', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Disable', 'dns_service': 'Enable', 'type': 'vport', 'label': '32', 'l2_label': '36', 'vxlan_id': '12', 'vn_name': 'default-domain:ctest-TestPolicyAcl-52120613:ctest-VN3-52813967', 'vm_uuid': 'c4a47aed-e514-4028-a739-652e3bc30a2c', 'vm_name': 'ctest-VM31-72753877', 'ip_addr': '118.136.68.131', 'mac_addr': '02:2f:84:09:02:47', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.4', 'service_vlan_list': None, 'os_ifindex': '47', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestPolicyAcl-52120613:2f840902-47a4-4682-88e3-01af085b3425', 'sg_uuid_list': ['2200a030-4241-4e9a-a887-68f317565e80'], 'static_route_list': None, 'vm_project_uuid': 'dadcea4d-c00f-4768-90cd-cc9f76f2f6d0', 'admin_state': 'Enabled', 'flow_key_idx': '40', 'allowed_address_pair_list': None, 'ip6_addr': '::', '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-02-02 02:32:29,799 - DEBUG - Agent 10.0.0.50 vrf name: default-domain:ctest-TestPolicyAcl-52120613:ctest-VN3-52813967:ctest-VN3-52813967
2026-02-02 02:32:29,805 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAcl-52120613:ctest-VN3-52813967:ctest-VN3-52813967', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-TestPolicyAcl-52120613:ctest-VN3-52813967', 'table_label': '-1', 'vxlan_id': '12', 'evpnindex': '3', 'brindex': '3', 'mplsindex': '0', 'RD': '10.20.0.192:3', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]}
2026-02-02 02:32:29,883 - DEBUG - VM ctest-VM31-72753877 : Tap interface tap2f840902-47 is set to Active
2026-02-02 02:32:29,883 - DEBUG - Tap interface tap2f840902-47 detail : {'index': '4', 'name': 'tap2f840902-47', 'uuid': '2f840902-47a4-4682-88e3-01af085b3425', 'vrf_name': 'default-domain:ctest-TestPolicyAcl-52120613:ctest-VN3-52813967:ctest-VN3-52813967', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Ipv6 Inactive < no-ipv6-addr >', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Disable', 'dns_service': 'Enable', 'type': 'vport', 'label': '32', 'l2_label': '36', 'vxlan_id': '12', 'vn_name': 'default-domain:ctest-TestPolicyAcl-52120613:ctest-VN3-52813967', 'vm_uuid': 'c4a47aed-e514-4028-a739-652e3bc30a2c', 'vm_name': 'ctest-VM31-72753877', 'ip_addr': '118.136.68.131', 'mac_addr': '02:2f:84:09:02:47', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.4', 'service_vlan_list': None, 'os_ifindex': '47', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestPolicyAcl-52120613:2f840902-47a4-4682-88e3-01af085b3425', 'sg_uuid_list': ['2200a030-4241-4e9a-a887-68f317565e80'], 'static_route_list': None, 'vm_project_uuid': 'dadcea4d-c00f-4768-90cd-cc9f76f2f6d0', 'admin_state': 'Enabled', 'flow_key_idx': '40', 'allowed_address_pair_list': None, 'ip6_addr': '::', '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-02-02 02:32:29,996 - DEBUG - Starting Layer 2 verification in Agent
2026-02-02 02:32:30,011 - DEBUG - Layer 2 path is seen for VM MAC 02:2f:84:09:02:47 in agent 10.0.0.50
2026-02-02 02:32:30,011 - DEBUG - Active layer 2 route in agent is present for VMI tap2f840902-47
2026-02-02 02:32:30,011 - DEBUG - L2 label(36) matches bw route table and interface table
2026-02-02 02:32:30,017 - DEBUG - VN default-domain:ctest-TestPolicyAcl-52120613:ctest-VN3-52813967 is found in Agent of node 10.0.0.50
2026-02-02 02:32:30,143 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAcl-52120613:ctest-VN3-52813967 is consistent in agent 10.0.0.50
2026-02-02 02:32:30,143 - DEBUG - Route for VM IP ['118.136.68.131'] is consistent in agent 10.0.0.50
2026-02-02 02:32:30,143 - DEBUG - VN default-domain:ctest-TestPolicyAcl-52120613:ctest-VN3-52813967 verification for VM ctest-VM31-72753877 in Agent 10.0.0.50 passed
2026-02-02 02:32:30,253 - DEBUG - Starting all layer 2 verification in agent 10.0.0.50
2026-02-02 02:32:30,263 - DEBUG - Route for VM MAC 02:2f:84:09:02:47 is consistent in agent 10.0.0.50
2026-02-02 02:32:31,446 - 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=14.4 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=4.61 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 = 4.614/9.493/14.373/4.879 ms')
2026-02-02 02:32:31,446 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-72753877 passed
2026-02-02 02:32:31,446 - INFO - VM ctest-VM31-72753877 verifications in Compute nodes passed
2026-02-02 02:32:31,650 - DEBUG - Validated VM route 118.136.68.131 in vrouter of 10.0.0.50
2026-02-02 02:32:31,654 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0
2026-02-02 02:32:31,654 - INFO - Validated routes of VM ctest-VM31-72753877 in all vrouters
2026-02-02 02:32:32,192 - DEBUG - Starting all layer2 verification in 10.0.0.141 Control Node
2026-02-02 02:32:32,325 - DEBUG - Layer2 route found for VM MAC 02:2f:84:09:02:47 in Control-node 10.0.0.141
2026-02-02 02:32:32,325 - DEBUG - L2 Label for VM ctest-VM31-72753877 same between Control-node 10.0.0.141 and Agent, Expected: 36, Seen: 36
2026-02-02 02:32:32,325 - DEBUG - Starting all layer2 verification in 10.0.0.22 Control Node
2026-02-02 02:32:32,452 - DEBUG - Layer2 route found for VM MAC 02:2f:84:09:02:47 in Control-node 10.0.0.22
2026-02-02 02:32:32,452 - DEBUG - L2 Label for VM ctest-VM31-72753877 same between Control-node 10.0.0.22 and Agent, Expected: 36, Seen: 36
2026-02-02 02:32:32,452 - INFO - Verification in Control-nodes for VM ctest-VM31-72753877 passed
2026-02-02 02:32:32,452 - DEBUG - Verifying the vm in opserver
2026-02-02 02:32:32,452 - DEBUG - Verifying in collector 10.0.0.23 ...
2026-02-02 02:32:32,452 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine/c4a47aed-e514-4028-a739-652e3bc30a2c?flat
2026-02-02 02:32:32,466 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-52120613:2f840902-47a4-4682-88e3-01af085b3425?flat
2026-02-02 02:32:32,485 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-52120613:2f840902-47a4-4682-88e3-01af085b3425?flat
2026-02-02 02:32:32,504 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine/c4a47aed-e514-4028-a739-652e3bc30a2c?flat
2026-02-02 02:32:32,526 - DEBUG - Verifying vm in vn uve
2026-02-02 02:32:32,526 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-52120613:2f840902-47a4-4682-88e3-01af085b3425?flat
2026-02-02 02:32:32,550 - DEBUG - VM uve shows interface as {'tx_vlan': 65535, 'active': True, 'admin_state': True, 'label': 32, 'rx_vlan': 65535, 'vn_uuid': '42471fab-6bb3-4a01-b0c8-782857fbeaa0', 'l2_active': True, 'ip6_active': False, '__T': 1769999523822956, 'is_health_check_active': True, 'ip6_address': '::', 'mac_address': '02:2f:84:09:02:47', 'gateway': '118.136.68.129', 'port_mirror_enabled': False, 'ip4_active': True, 'vm_name': 'ctest-VM31-72753877', 'ip_address': '118.136.68.131', 'vm_uuid': 'c4a47aed-e514-4028-a739-652e3bc30a2c', 'virtual_network': 'default-domain:ctest-TestPolicyAcl-52120613:ctest-VN3-52813967', 'vhostuser_mode': 0, 'fixed_ip4_list': ['118.136.68.131'], 'uuid': '2f840902-47a4-4682-88e3-01af085b3425'}
2026-02-02 02:32:32,550 - DEBUG - VM uve shows ip address as ['118.136.68.131', '::']
2026-02-02 02:32:32,550 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAcl-52120613:ctest-VN3-52813967
2026-02-02 02:32:32,550 - INFO - Verifying through opserver in 10.0.0.141
2026-02-02 02:32:32,551 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-52120613:ctest-VN3-52813967?flat
2026-02-02 02:32:32,575 - DEBUG - expected vm list c4a47aed-e514-4028-a739-652e3bc30a2c
2026-02-02 02:32:32,575 - DEBUG - Extracted vm list ['c4a47aed-e514-4028-a739-652e3bc30a2c']
2026-02-02 02:32:32,575 - INFO - VM c4a47aed-e514-4028-a739-652e3bc30a2c is present in default-domain:ctest-TestPolicyAcl-52120613:ctest-VN3-52813967
2026-02-02 02:32:32,575 - DEBUG - Verifying vm in vrouter uve
2026-02-02 02:32:32,575 - DEBUG - Getting info from collector 10.0.0.23..
2026-02-02 02:32:32,575 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine/c4a47aed-e514-4028-a739-652e3bc30a2c?flat
2026-02-02 02:32:32,590 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-5167-1']
2026-02-02 02:32:32,591 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5167-1:Compute:contrail-vrouter-agent:0?flat
2026-02-02 02:32:32,622 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5167-1?flat
2026-02-02 02:32:32,651 - DEBUG - VM c4a47aed-e514-4028-a739-652e3bc30a2c is present in vrouter an-jenkins-deploy-platform-ansible-os-5167-1 uve
2026-02-02 02:32:32,651 - DEBUG - Validated that VM ctest-VM31-72753877 is in Vrouter an-jenkins-deploy-platform-ansible-os-5167-1 UVE
2026-02-02 02:32:32,651 - DEBUG - Verifying vm tap interface/vn in vrouter uve
2026-02-02 02:32:32,659 - DEBUG - Expected tap interface of VM uuid c4a47aed-e514-4028-a739-652e3bc30a2c is default-domain:ctest-TestPolicyAcl-52120613:2f840902-47a4-4682-88e3-01af085b3425
2026-02-02 02:32:32,659 - DEBUG - Expected VN of VM uuid c4a47aed-e514-4028-a739-652e3bc30a2c is default-domain:ctest-TestPolicyAcl-52120613:ctest-VN3-52813967
2026-02-02 02:32:32,659 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5167-1:Compute:contrail-vrouter-agent:0?flat
2026-02-02 02:32:32,692 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5167-1?flat
2026-02-02 02:32:32,719 - DEBUG - VM c4a47aed-e514-4028-a739-652e3bc30a2c is present in vrouter an-jenkins-deploy-platform-ansible-os-5167-1 uve
2026-02-02 02:32:32,719 - INFO - tap interface default-domain:ctest-TestPolicyAcl-52120613:2f840902-47a4-4682-88e3-01af085b3425 of vm c4a47aed-e514-4028-a739-652e3bc30a2c is present in vrouter an-jenkins-deploy-platform-ansible-os-5167-1 uve
2026-02-02 02:32:32,720 - DEBUG - Connected network default-domain:ctest-TestPolicyAcl-52120613:ctest-VN3-52813967 of vm c4a47aed-e514-4028-a739-652e3bc30a2c is present in vrouter an-jenkins-deploy-platform-ansible-os-5167-1 uve
2026-02-02 02:32:32,720 - DEBUG - Verifying the c4a47aed-e514-4028-a739-652e3bc30a2c virtual network link through opserver 10.0.0.141
2026-02-02 02:32:32,720 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-machines
2026-02-02 02:32:32,731 - DEBUG - VM link and name as {'name': 'c4a47aed-e514-4028-a739-652e3bc30a2c', 'href': 'http://10.0.0.141:8081/analytics/uves/virtual-machine/c4a47aed-e514-4028-a739-652e3bc30a2c?flat'}
2026-02-02 02:32:32,731 - DEBUG - Verifying the c4a47aed-e514-4028-a739-652e3bc30a2c virtual network link through opserver 10.0.0.22
2026-02-02 02:32:32,731 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machines
2026-02-02 02:32:32,748 - DEBUG - VM link and name as {'name': 'c4a47aed-e514-4028-a739-652e3bc30a2c', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-machine/c4a47aed-e514-4028-a739-652e3bc30a2c?flat'}
2026-02-02 02:32:32,748 - DEBUG - Verifying the c4a47aed-e514-4028-a739-652e3bc30a2c virtual network link through opserver 10.0.0.23
2026-02-02 02:32:32,748 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machines
2026-02-02 02:32:32,762 - DEBUG - VM link and name as {'name': 'c4a47aed-e514-4028-a739-652e3bc30a2c', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-machine/c4a47aed-e514-4028-a739-652e3bc30a2c?flat'}
2026-02-02 02:32:32,762 - INFO - VM ctest-VM31-72753877 validations in Opserver passed
2026-02-02 02:32:32,763 - INFO - Waiting for VM ctest-VM11-51870209 to be up..
2026-02-02 02:32:32,763 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-02-02 02:32:32,763 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/886c7b4c-aaf7-48df-8dd9-bdd6eee2666f
2026-02-02 02:32:32,772 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/9d4b15d6-766a-4119-8d85-5cf132aa6776
2026-02-02 02:32:33,941 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=6.14 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.25 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 2.252/4.194/6.137/1.942 ms')
2026-02-02 02:32:33,941 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-51870209 passed
2026-02-02 02:32:34,010 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-02-02 02:32:34,010 - DEBUG - Waiting to SSH to VM ctest-VM11-51870209, IP 9.66.139.3, Port 22
2026-02-02 02:32:34,078 - 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-02-02 02:32:34,210 - DEBUG - VM ctest-VM11-51870209 is NOT ready for SSH connections, VM status: ACTIVE
2026-02-02 02:32:39,211 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-02-02 02:32:39,211 - DEBUG - Waiting to SSH to VM ctest-VM11-51870209, IP 9.66.139.3, Port 22
2026-02-02 02:32:39,278 - 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-02-02 02:32:39,364 - DEBUG - VM ctest-VM11-51870209 is NOT ready for SSH connections, VM status: ACTIVE
2026-02-02 02:32:44,365 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-02-02 02:32:44,365 - DEBUG - Waiting to SSH to VM ctest-VM11-51870209, IP 9.66.139.3, Port 22
2026-02-02 02:32:44,539 - DEBUG - VM ctest-VM11-51870209 is ready for SSH connections
2026-02-02 02:32:44,539 - INFO - Waiting for VM ctest-VM21-70139338 to be up..
2026-02-02 02:32:44,539 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-02-02 02:32:44,539 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/2288cb6f-6e2d-465b-af41-29e74435f629
2026-02-02 02:32:44,548 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/8638de3c-8b2b-4e46-b2ba-e4f3a12719dd
2026-02-02 02:32:45,717 - 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.44 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.531 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.531/1.986/3.441/1.455 ms')
2026-02-02 02:32:45,717 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-70139338 passed
2026-02-02 02:32:45,785 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-02-02 02:32:45,785 - DEBUG - Waiting to SSH to VM ctest-VM21-70139338, IP 220.59.102.131, Port 22
2026-02-02 02:32:45,852 - 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-02-02 02:32:45,938 - DEBUG - VM ctest-VM21-70139338 is NOT ready for SSH connections, VM status: ACTIVE
2026-02-02 02:32:50,939 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-02-02 02:32:50,939 - DEBUG - Waiting to SSH to VM ctest-VM21-70139338, IP 220.59.102.131, Port 22
2026-02-02 02:32:51,108 - DEBUG - VM ctest-VM21-70139338 is ready for SSH connections
2026-02-02 02:32:51,109 - INFO - Waiting for VM ctest-VM31-72753877 to be up..
2026-02-02 02:32:51,109 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-02-02 02:32:51,109 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/c4a47aed-e514-4028-a739-652e3bc30a2c
2026-02-02 02:32:51,117 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/2f840902-47a4-4682-88e3-01af085b3425
2026-02-02 02:32:52,284 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=5.02 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=1.64 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.642/3.331/5.020/1.689 ms')
2026-02-02 02:32:52,284 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-72753877 passed
2026-02-02 02:32:52,350 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-02-02 02:32:52,350 - DEBUG - Waiting to SSH to VM ctest-VM31-72753877, IP 118.136.68.131, Port 22
2026-02-02 02:32:52,508 - DEBUG - VM ctest-VM31-72753877 is ready for SSH connections
2026-02-02 02:32:52,508 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 220.59.102.131, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.50, gateway password: c0ntrail123
2026-02-02 02:32:52,508 - DEBUG - ping -s 56 -c 3 -W 1 220.59.102.131
2026-02-02 02:33:11,305 - DEBUG - PING 220.59.102.131 (220.59.102.131) 56(84) bytes of data.
64 bytes from 220.59.102.131: icmp_req=1 ttl=63 time=5.02 ms
64 bytes from 220.59.102.131: icmp_req=2 ttl=63 time=1.24 ms
64 bytes from 220.59.102.131: icmp_req=3 ttl=63 time=3.18 ms
--- 220.59.102.131 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 1.243/3.152/5.027/1.545 ms
2026-02-02 02:33:11,305 - INFO - Ping to IP 220.59.102.131 from VM ctest-VM11-51870209 passed
2026-02-02 02:33:11,305 - INFO - Test with src as VN and dst as policy PASSED
2026-02-02 02:33:11,305 - INFO - Deleting VM ctest-VM31-72753877
2026-02-02 02:33:11,378 - DEBUG - Verifying in api server 10.0.0.23
2026-02-02 02:33:11,378 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/c4a47aed-e514-4028-a739-652e3bc30a2c
2026-02-02 02:33:11,388 - DEBUG - VM ID c4a47aed-e514-4028-a739-652e3bc30a2c of VM ctest-VM31-72753877 is still found in API Server
2026-02-02 02:33:13,388 - DEBUG - Verifying in api server 10.0.0.23
2026-02-02 02:33:13,388 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/c4a47aed-e514-4028-a739-652e3bc30a2c
2026-02-02 02:33:13,395 - DEBUG - Response Code: 404
2026-02-02 02:33:13,395 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/c4a47aed-e514-4028-a739-652e3bc30a2c
2026-02-02 02:33:13,401 - DEBUG - Response Code: 404
2026-02-02 02:33:13,401 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/c4a47aed-e514-4028-a739-652e3bc30a2c
2026-02-02 02:33:13,407 - DEBUG - Response Code: 404
2026-02-02 02:33:13,407 - INFO - VM ctest-VM31-72753877 is fully removed in API-Server
2026-02-02 02:33:13,560 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2026-02-02 02:33:13,597 - DEBUG - Validated that vrouter 10.0.0.50 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-52120613:ctest-VN3-52813967
2026-02-02 02:33:13,608 - DEBUG - Validated that vrouter 10.0.0.65 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-52120613:ctest-VN3-52813967
2026-02-02 02:33:13,608 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAcl-52120613:ctest-VN3-52813967
2026-02-02 02:33:13,608 - INFO - VM ctest-VM31-72753877 is removed in Compute, and routes are removed in all compute nodes
2026-02-02 02:33:13,837 - INFO - Routes for VM ctest-VM31-72753877 is removed in all control-nodes
2026-02-02 02:33:13,837 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5167-1:Compute:contrail-vrouter-agent:0?flat
2026-02-02 02:33:13,868 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5167-1?flat
2026-02-02 02:33:13,896 - DEBUG - VM c4a47aed-e514-4028-a739-652e3bc30a2c is still present in vrouter an-jenkins-deploy-platform-ansible-os-5167-1 uve
2026-02-02 02:33:13,896 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-02-02 02:33:13,896 - DEBUG - interface for vm c4a47aed-e514-4028-a739-652e3bc30a2c deleted from vrouter uve ...
2026-02-02 02:33:13,896 - DEBUG - Deleted interface not in error interface list ...
2026-02-02 02:33:16,896 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5167-1:Compute:contrail-vrouter-agent:0?flat
2026-02-02 02:33:16,929 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5167-1?flat
2026-02-02 02:33:16,957 - DEBUG - VM c4a47aed-e514-4028-a739-652e3bc30a2c is still present in vrouter an-jenkins-deploy-platform-ansible-os-5167-1 uve
2026-02-02 02:33:16,957 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-02-02 02:33:16,957 - DEBUG - interface for vm c4a47aed-e514-4028-a739-652e3bc30a2c deleted from vrouter uve ...
2026-02-02 02:33:16,957 - DEBUG - Deleted interface not in error interface list ...
2026-02-02 02:33:19,958 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5167-1:Compute:contrail-vrouter-agent:0?flat
2026-02-02 02:33:19,996 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5167-1?flat
2026-02-02 02:33:20,023 - DEBUG - VM c4a47aed-e514-4028-a739-652e3bc30a2c is still present in vrouter an-jenkins-deploy-platform-ansible-os-5167-1 uve
2026-02-02 02:33:20,023 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-02-02 02:33:20,024 - DEBUG - interface for vm c4a47aed-e514-4028-a739-652e3bc30a2c deleted from vrouter uve ...
2026-02-02 02:33:20,024 - DEBUG - Deleted interface not in error interface list ...
2026-02-02 02:33:23,024 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5167-1:Compute:contrail-vrouter-agent:0?flat
2026-02-02 02:33:23,055 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5167-1?flat
2026-02-02 02:33:23,080 - DEBUG - VM c4a47aed-e514-4028-a739-652e3bc30a2c is still present in vrouter an-jenkins-deploy-platform-ansible-os-5167-1 uve
2026-02-02 02:33:23,080 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-02-02 02:33:23,081 - DEBUG - interface for vm c4a47aed-e514-4028-a739-652e3bc30a2c deleted from vrouter uve ...
2026-02-02 02:33:23,081 - DEBUG - Deleted interface not in error interface list ...
2026-02-02 02:33:26,081 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5167-1:Compute:contrail-vrouter-agent:0?flat
2026-02-02 02:33:26,115 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5167-1?flat
2026-02-02 02:33:26,143 - DEBUG - VM c4a47aed-e514-4028-a739-652e3bc30a2c is still present in vrouter an-jenkins-deploy-platform-ansible-os-5167-1 uve
2026-02-02 02:33:26,143 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-02-02 02:33:26,143 - DEBUG - interface for vm c4a47aed-e514-4028-a739-652e3bc30a2c deleted from vrouter uve ...
2026-02-02 02:33:26,143 - DEBUG - Deleted interface not in error interface list ...
2026-02-02 02:33:29,144 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5167-1:Compute:contrail-vrouter-agent:0?flat
2026-02-02 02:33:29,175 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5167-1?flat
2026-02-02 02:33:29,200 - DEBUG - VM c4a47aed-e514-4028-a739-652e3bc30a2c is still present in vrouter an-jenkins-deploy-platform-ansible-os-5167-1 uve
2026-02-02 02:33:29,201 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-02-02 02:33:29,201 - DEBUG - interface for vm c4a47aed-e514-4028-a739-652e3bc30a2c deleted from vrouter uve ...
2026-02-02 02:33:29,201 - DEBUG - Deleted interface not in error interface list ...
2026-02-02 02:33:32,201 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5167-1:Compute:contrail-vrouter-agent:0?flat
2026-02-02 02:33:32,241 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5167-1?flat
2026-02-02 02:33:32,270 - DEBUG - VM c4a47aed-e514-4028-a739-652e3bc30a2c is still present in vrouter an-jenkins-deploy-platform-ansible-os-5167-1 uve
2026-02-02 02:33:32,270 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-02-02 02:33:32,270 - DEBUG - interface for vm c4a47aed-e514-4028-a739-652e3bc30a2c deleted from vrouter uve ...
2026-02-02 02:33:32,270 - DEBUG - Deleted interface not in error interface list ...
2026-02-02 02:33:35,271 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5167-1:Compute:contrail-vrouter-agent:0?flat
2026-02-02 02:33:35,301 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5167-1?flat
2026-02-02 02:33:35,327 - DEBUG - VM c4a47aed-e514-4028-a739-652e3bc30a2c is not present in vrouter an-jenkins-deploy-platform-ansible-os-5167-1 uve
2026-02-02 02:33:35,327 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-02-02 02:33:35,327 - DEBUG - interface for vm c4a47aed-e514-4028-a739-652e3bc30a2c deleted from vrouter uve ...
2026-02-02 02:33:35,327 - DEBUG - Deleted interface not in error interface list ...
2026-02-02 02:33:35,327 - DEBUG - Validated that VM c4a47aed-e514-4028-a739-652e3bc30a2c is removed in Vrouter UVE
2026-02-02 02:33:35,327 - DEBUG - Verifying through opserver in 10.0.0.141
2026-02-02 02:33:35,327 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-52120613:ctest-VN3-52813967?flat
2026-02-02 02:33:35,344 - DEBUG - c4a47aed-e514-4028-a739-652e3bc30a2c BM not in default-domain:ctest-TestPolicyAcl-52120613:ctest-VN3-52813967 uve
2026-02-02 02:33:35,344 - DEBUG - Validated that VM c4a47aed-e514-4028-a739-652e3bc30a2c is not present in the VN UVE
2026-02-02 02:33:35,344 - DEBUG - Verifying the c4a47aed-e514-4028-a739-652e3bc30a2c virtual network link through opserver 10.0.0.141
2026-02-02 02:33:35,344 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-machines
2026-02-02 02:33:35,354 - INFO - c4a47aed-e514-4028-a739-652e3bc30a2c vm uve deleted from opserver
2026-02-02 02:33:35,354 - DEBUG - Verifying the c4a47aed-e514-4028-a739-652e3bc30a2c virtual network link through opserver 10.0.0.22
2026-02-02 02:33:35,354 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machines
2026-02-02 02:33:35,365 - INFO - c4a47aed-e514-4028-a739-652e3bc30a2c vm uve deleted from opserver
2026-02-02 02:33:35,365 - DEBUG - Verifying the c4a47aed-e514-4028-a739-652e3bc30a2c virtual network link through opserver 10.0.0.23
2026-02-02 02:33:35,365 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machines
2026-02-02 02:33:35,377 - INFO - c4a47aed-e514-4028-a739-652e3bc30a2c vm uve deleted from opserver
2026-02-02 02:33:35,377 - INFO - Validated that VM c4a47aed-e514-4028-a739-652e3bc30a2c is removed from Opserver
2026-02-02 02:33:35,377 - INFO - Deleting VM ctest-VM21-70139338
2026-02-02 02:33:35,452 - DEBUG - Verifying in api server 10.0.0.23
2026-02-02 02:33:35,452 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/2288cb6f-6e2d-465b-af41-29e74435f629
2026-02-02 02:33:35,461 - DEBUG - VM ID 2288cb6f-6e2d-465b-af41-29e74435f629 of VM ctest-VM21-70139338 is still found in API Server
2026-02-02 02:33:37,462 - DEBUG - Verifying in api server 10.0.0.23
2026-02-02 02:33:37,462 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/2288cb6f-6e2d-465b-af41-29e74435f629
2026-02-02 02:33:37,467 - DEBUG - Response Code: 404
2026-02-02 02:33:37,468 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/2288cb6f-6e2d-465b-af41-29e74435f629
2026-02-02 02:33:37,472 - DEBUG - Response Code: 404
2026-02-02 02:33:37,472 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/2288cb6f-6e2d-465b-af41-29e74435f629
2026-02-02 02:33:37,477 - DEBUG - Response Code: 404
2026-02-02 02:33:37,477 - INFO - VM ctest-VM21-70139338 is fully removed in API-Server
2026-02-02 02:33:37,632 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2026-02-02 02:33:37,669 - DEBUG - Validated that vrouter 10.0.0.50 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-52120613:ctest-VN2-58046328
2026-02-02 02:33:37,678 - DEBUG - Validated that vrouter 10.0.0.65 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-52120613:ctest-VN2-58046328
2026-02-02 02:33:37,678 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAcl-52120613:ctest-VN2-58046328
2026-02-02 02:33:37,678 - INFO - VM ctest-VM21-70139338 is removed in Compute, and routes are removed in all compute nodes
2026-02-02 02:33:37,874 - INFO - Routes for VM ctest-VM21-70139338 is removed in all control-nodes
2026-02-02 02:33:37,875 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5167-2:Compute:contrail-vrouter-agent:0?flat
2026-02-02 02:33:37,910 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5167-2?flat
2026-02-02 02:33:37,940 - DEBUG - VM 2288cb6f-6e2d-465b-af41-29e74435f629 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5167-2 uve
2026-02-02 02:33:37,940 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-02-02 02:33:37,941 - DEBUG - interface for vm 2288cb6f-6e2d-465b-af41-29e74435f629 deleted from vrouter uve ...
2026-02-02 02:33:37,941 - DEBUG - Deleted interface not in error interface list ...
2026-02-02 02:33:40,941 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5167-2:Compute:contrail-vrouter-agent:0?flat
2026-02-02 02:33:40,975 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5167-2?flat
2026-02-02 02:33:41,002 - DEBUG - VM 2288cb6f-6e2d-465b-af41-29e74435f629 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5167-2 uve
2026-02-02 02:33:41,002 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-02-02 02:33:41,002 - DEBUG - interface for vm 2288cb6f-6e2d-465b-af41-29e74435f629 deleted from vrouter uve ...
2026-02-02 02:33:41,002 - DEBUG - Deleted interface not in error interface list ...
2026-02-02 02:33:44,003 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5167-2:Compute:contrail-vrouter-agent:0?flat
2026-02-02 02:33:44,039 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5167-2?flat
2026-02-02 02:33:44,065 - DEBUG - VM 2288cb6f-6e2d-465b-af41-29e74435f629 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5167-2 uve
2026-02-02 02:33:44,065 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-02-02 02:33:44,065 - DEBUG - interface for vm 2288cb6f-6e2d-465b-af41-29e74435f629 deleted from vrouter uve ...
2026-02-02 02:33:44,065 - DEBUG - Deleted interface not in error interface list ...
2026-02-02 02:33:47,066 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5167-2:Compute:contrail-vrouter-agent:0?flat
2026-02-02 02:33:47,110 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5167-2?flat
2026-02-02 02:33:47,137 - DEBUG - VM 2288cb6f-6e2d-465b-af41-29e74435f629 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5167-2 uve
2026-02-02 02:33:47,137 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-02-02 02:33:47,137 - DEBUG - interface for vm 2288cb6f-6e2d-465b-af41-29e74435f629 deleted from vrouter uve ...
2026-02-02 02:33:47,137 - DEBUG - Deleted interface not in error interface list ...
2026-02-02 02:33:50,138 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5167-2:Compute:contrail-vrouter-agent:0?flat
2026-02-02 02:33:50,172 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5167-2?flat
2026-02-02 02:33:50,200 - DEBUG - VM 2288cb6f-6e2d-465b-af41-29e74435f629 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5167-2 uve
2026-02-02 02:33:50,200 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-02-02 02:33:50,200 - DEBUG - interface for vm 2288cb6f-6e2d-465b-af41-29e74435f629 deleted from vrouter uve ...
2026-02-02 02:33:50,200 - DEBUG - Deleted interface not in error interface list ...
2026-02-02 02:33:53,201 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5167-2:Compute:contrail-vrouter-agent:0?flat
2026-02-02 02:33:53,233 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5167-2?flat
2026-02-02 02:33:53,259 - DEBUG - VM 2288cb6f-6e2d-465b-af41-29e74435f629 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5167-2 uve
2026-02-02 02:33:53,260 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-02-02 02:33:53,260 - DEBUG - interface for vm 2288cb6f-6e2d-465b-af41-29e74435f629 deleted from vrouter uve ...
2026-02-02 02:33:53,260 - DEBUG - Deleted interface not in error interface list ...
2026-02-02 02:33:56,260 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5167-2:Compute:contrail-vrouter-agent:0?flat
2026-02-02 02:33:56,301 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5167-2?flat
2026-02-02 02:33:56,331 - DEBUG - VM 2288cb6f-6e2d-465b-af41-29e74435f629 is not present in vrouter an-jenkins-deploy-platform-ansible-os-5167-2 uve
2026-02-02 02:33:56,331 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-02-02 02:33:56,331 - DEBUG - interface for vm 2288cb6f-6e2d-465b-af41-29e74435f629 deleted from vrouter uve ...
2026-02-02 02:33:56,331 - DEBUG - Deleted interface not in error interface list ...
2026-02-02 02:33:56,331 - DEBUG - Validated that VM 2288cb6f-6e2d-465b-af41-29e74435f629 is removed in Vrouter UVE
2026-02-02 02:33:56,331 - DEBUG - Verifying through opserver in 10.0.0.141
2026-02-02 02:33:56,331 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-52120613:ctest-VN2-58046328?flat
2026-02-02 02:33:56,352 - DEBUG - 2288cb6f-6e2d-465b-af41-29e74435f629 BM not in default-domain:ctest-TestPolicyAcl-52120613:ctest-VN2-58046328 uve
2026-02-02 02:33:56,352 - DEBUG - Validated that VM 2288cb6f-6e2d-465b-af41-29e74435f629 is not present in the VN UVE
2026-02-02 02:33:56,352 - DEBUG - Verifying the 2288cb6f-6e2d-465b-af41-29e74435f629 virtual network link through opserver 10.0.0.141
2026-02-02 02:33:56,352 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-machines
2026-02-02 02:33:56,365 - INFO - 2288cb6f-6e2d-465b-af41-29e74435f629 vm uve deleted from opserver
2026-02-02 02:33:56,365 - DEBUG - Verifying the 2288cb6f-6e2d-465b-af41-29e74435f629 virtual network link through opserver 10.0.0.22
2026-02-02 02:33:56,365 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machines
2026-02-02 02:33:56,378 - INFO - 2288cb6f-6e2d-465b-af41-29e74435f629 vm uve deleted from opserver
2026-02-02 02:33:56,378 - DEBUG - Verifying the 2288cb6f-6e2d-465b-af41-29e74435f629 virtual network link through opserver 10.0.0.23
2026-02-02 02:33:56,378 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machines
2026-02-02 02:33:56,389 - INFO - 2288cb6f-6e2d-465b-af41-29e74435f629 vm uve deleted from opserver
2026-02-02 02:33:56,389 - INFO - Validated that VM 2288cb6f-6e2d-465b-af41-29e74435f629 is removed from Opserver
2026-02-02 02:33:56,389 - INFO - Deleting VM ctest-VM11-51870209
2026-02-02 02:33:56,471 - DEBUG - Verifying in api server 10.0.0.23
2026-02-02 02:33:56,471 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/886c7b4c-aaf7-48df-8dd9-bdd6eee2666f
2026-02-02 02:33:56,479 - DEBUG - VM ID 886c7b4c-aaf7-48df-8dd9-bdd6eee2666f of VM ctest-VM11-51870209 is still found in API Server
2026-02-02 02:33:58,480 - DEBUG - Verifying in api server 10.0.0.23
2026-02-02 02:33:58,480 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/886c7b4c-aaf7-48df-8dd9-bdd6eee2666f
2026-02-02 02:33:58,488 - DEBUG - Response Code: 404
2026-02-02 02:33:58,488 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/886c7b4c-aaf7-48df-8dd9-bdd6eee2666f
2026-02-02 02:33:58,494 - DEBUG - Response Code: 404
2026-02-02 02:33:58,494 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/886c7b4c-aaf7-48df-8dd9-bdd6eee2666f
2026-02-02 02:33:58,499 - DEBUG - Response Code: 404
2026-02-02 02:33:58,500 - INFO - VM ctest-VM11-51870209 is fully removed in API-Server
2026-02-02 02:33:58,668 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2026-02-02 02:33:58,708 - DEBUG - Validated that vrouter 10.0.0.50 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-52120613:ctest-VN1-96302591
2026-02-02 02:33:58,718 - DEBUG - Validated that vrouter 10.0.0.65 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-52120613:ctest-VN1-96302591
2026-02-02 02:33:58,718 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAcl-52120613:ctest-VN1-96302591
2026-02-02 02:33:58,718 - INFO - VM ctest-VM11-51870209 is removed in Compute, and routes are removed in all compute nodes
2026-02-02 02:33:58,914 - INFO - Routes for VM ctest-VM11-51870209 is removed in all control-nodes
2026-02-02 02:33:58,914 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5167-1:Compute:contrail-vrouter-agent:0?flat
2026-02-02 02:33:58,946 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5167-1?flat
2026-02-02 02:33:58,977 - DEBUG - VM 886c7b4c-aaf7-48df-8dd9-bdd6eee2666f is still present in vrouter an-jenkins-deploy-platform-ansible-os-5167-1 uve
2026-02-02 02:33:58,977 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-02-02 02:33:58,978 - DEBUG - interface for vm 886c7b4c-aaf7-48df-8dd9-bdd6eee2666f deleted from vrouter uve ...
2026-02-02 02:33:58,978 - DEBUG - Deleted interface not in error interface list ...
2026-02-02 02:34:01,979 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5167-1:Compute:contrail-vrouter-agent:0?flat
2026-02-02 02:34:02,019 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5167-1?flat
2026-02-02 02:34:02,050 - DEBUG - VM 886c7b4c-aaf7-48df-8dd9-bdd6eee2666f is still present in vrouter an-jenkins-deploy-platform-ansible-os-5167-1 uve
2026-02-02 02:34:02,050 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-02-02 02:34:02,050 - DEBUG - interface for vm 886c7b4c-aaf7-48df-8dd9-bdd6eee2666f deleted from vrouter uve ...
2026-02-02 02:34:02,050 - DEBUG - Deleted interface not in error interface list ...
2026-02-02 02:34:05,050 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5167-1:Compute:contrail-vrouter-agent:0?flat
2026-02-02 02:34:05,079 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5167-1?flat
2026-02-02 02:34:05,110 - DEBUG - VM 886c7b4c-aaf7-48df-8dd9-bdd6eee2666f is not present in vrouter an-jenkins-deploy-platform-ansible-os-5167-1 uve
2026-02-02 02:34:05,110 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-02-02 02:34:05,110 - DEBUG - interface for vm 886c7b4c-aaf7-48df-8dd9-bdd6eee2666f deleted from vrouter uve ...
2026-02-02 02:34:05,110 - DEBUG - Deleted interface not in error interface list ...
2026-02-02 02:34:05,110 - DEBUG - Validated that VM 886c7b4c-aaf7-48df-8dd9-bdd6eee2666f is removed in Vrouter UVE
2026-02-02 02:34:05,110 - DEBUG - Verifying through opserver in 10.0.0.141
2026-02-02 02:34:05,110 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-52120613:ctest-VN1-96302591?flat
2026-02-02 02:34:05,133 - DEBUG - 886c7b4c-aaf7-48df-8dd9-bdd6eee2666f BM not in default-domain:ctest-TestPolicyAcl-52120613:ctest-VN1-96302591 uve
2026-02-02 02:34:05,133 - DEBUG - Validated that VM 886c7b4c-aaf7-48df-8dd9-bdd6eee2666f is not present in the VN UVE
2026-02-02 02:34:05,133 - DEBUG - Verifying the 886c7b4c-aaf7-48df-8dd9-bdd6eee2666f virtual network link through opserver 10.0.0.141
2026-02-02 02:34:05,133 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-machines
2026-02-02 02:34:05,144 - DEBUG - No links retuned for 886c7b4c-aaf7-48df-8dd9-bdd6eee2666f
2026-02-02 02:34:05,144 - INFO - 886c7b4c-aaf7-48df-8dd9-bdd6eee2666f vm uve deleted from opserver
2026-02-02 02:34:05,144 - DEBUG - Verifying the 886c7b4c-aaf7-48df-8dd9-bdd6eee2666f virtual network link through opserver 10.0.0.22
2026-02-02 02:34:05,144 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machines
2026-02-02 02:34:05,156 - DEBUG - No links retuned for 886c7b4c-aaf7-48df-8dd9-bdd6eee2666f
2026-02-02 02:34:05,156 - INFO - 886c7b4c-aaf7-48df-8dd9-bdd6eee2666f vm uve deleted from opserver
2026-02-02 02:34:05,156 - DEBUG - Verifying the 886c7b4c-aaf7-48df-8dd9-bdd6eee2666f virtual network link through opserver 10.0.0.23
2026-02-02 02:34:05,156 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machines
2026-02-02 02:34:05,166 - DEBUG - No links retuned for 886c7b4c-aaf7-48df-8dd9-bdd6eee2666f
2026-02-02 02:34:05,166 - INFO - 886c7b4c-aaf7-48df-8dd9-bdd6eee2666f vm uve deleted from opserver
2026-02-02 02:34:05,167 - INFO - Validated that VM 886c7b4c-aaf7-48df-8dd9-bdd6eee2666f is removed from Opserver
2026-02-02 02:34:05,167 - DEBUG - Detaching the Policy for VN :ctest-VN2-58046328
2026-02-02 02:34:05,167 - DEBUG - Requesting: http://10.0.0.141:8082/domains
2026-02-02 02:34:05,172 - DEBUG - Requesting: http://10.0.0.141:8082/domain/4480bafd-5776-4d34-b387-df5887123349
2026-02-02 02:34:05,228 - DEBUG - Requesting: http://10.0.0.141:8082/project/dadcea4d-c00f-4768-90cd-cc9f76f2f6d0
2026-02-02 02:34:05,324 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/c0985b93-9edf-4677-9ef6-496621c9c798
2026-02-02 02:34:05,344 - DEBUG - Requesting: http://10.0.0.141:8082/network-policy/8712634a-cfc4-4a1f-8cf3-ab5d873b6a42
2026-02-02 02:34:05,521 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAcl-52120613', 'ctest-policy21-54811892']] from ctest-VN2-58046328
2026-02-02 02:34:05,521 - DEBUG - Detaching the Policy for VN :ctest-VN1-96302591
2026-02-02 02:34:05,521 - DEBUG - Requesting: http://10.0.0.141:8082/domains
2026-02-02 02:34:05,527 - DEBUG - Requesting: http://10.0.0.141:8082/domain/4480bafd-5776-4d34-b387-df5887123349
2026-02-02 02:34:05,537 - DEBUG - Requesting: http://10.0.0.141:8082/project/dadcea4d-c00f-4768-90cd-cc9f76f2f6d0
2026-02-02 02:34:05,624 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/e7d2f137-eb62-4674-b964-649705d2cf1b
2026-02-02 02:34:05,821 - INFO - Detached Policy from ctest-VN1-96302591
2026-02-02 02:34:05,871 - INFO - Deleted policy ctest-policy13-67755085
2026-02-02 02:34:05,918 - INFO - Deleted policy ctest-policy21-54811892
2026-02-02 02:34:05,954 - INFO - Deleted policy ctest-policy12-13919007
2026-02-02 02:34:05,955 - INFO - Deleting VN ctest-VN3-52813967
2026-02-02 02:34:06,106 - DEBUG - Response for deleting network ()
2026-02-02 02:34:06,106 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/673a929a-6ee0-4a97-b9a2-5a55138b3723
2026-02-02 02:34:06,114 - DEBUG - Response Code: 404
2026-02-02 02:34:06,114 - DEBUG - Requesting: http://10.0.0.141:8082/domains
2026-02-02 02:34:06,130 - DEBUG - Requesting: http://10.0.0.141:8082/domain/4480bafd-5776-4d34-b387-df5887123349
2026-02-02 02:34:06,147 - DEBUG - Requesting: http://10.0.0.141:8082/project/dadcea4d-c00f-4768-90cd-cc9f76f2f6d0
2026-02-02 02:34:06,232 - INFO - Validated that VN ctest-VN3-52813967 is not found in API Server
2026-02-02 02:34:06,244 - DEBUG - VN ctest-VN3-52813967 is not present in Agent 10.0.0.50
2026-02-02 02:34:06,255 - DEBUG - VN ctest-VN3-52813967 is not present in Agent 10.0.0.65
2026-02-02 02:34:06,255 - INFO - Validated that VN ctest-VN3-52813967 is not in any agent
2026-02-02 02:34:06,289 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN3-52813967 info
2026-02-02 02:34:06,289 - INFO - Deleting VN ctest-VN2-58046328
2026-02-02 02:34:06,457 - DEBUG - Response for deleting network ()
2026-02-02 02:34:06,458 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/f15172a1-1f76-456a-bbca-d1e67a1473cf
2026-02-02 02:34:06,463 - DEBUG - Response Code: 404
2026-02-02 02:34:06,463 - DEBUG - Requesting: http://10.0.0.141:8082/domains
2026-02-02 02:34:06,474 - DEBUG - Requesting: http://10.0.0.141:8082/domain/4480bafd-5776-4d34-b387-df5887123349
2026-02-02 02:34:06,490 - DEBUG - Requesting: http://10.0.0.141:8082/project/dadcea4d-c00f-4768-90cd-cc9f76f2f6d0
2026-02-02 02:34:06,573 - INFO - Validated that VN ctest-VN2-58046328 is not found in API Server
2026-02-02 02:34:06,585 - DEBUG - VN ctest-VN2-58046328 is not present in Agent 10.0.0.50
2026-02-02 02:34:06,596 - DEBUG - VN ctest-VN2-58046328 is not present in Agent 10.0.0.65
2026-02-02 02:34:06,596 - INFO - Validated that VN ctest-VN2-58046328 is not in any agent
2026-02-02 02:34:06,624 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN2-58046328 info
2026-02-02 02:34:06,625 - INFO - Deleting VN ctest-VN1-96302591
2026-02-02 02:34:06,775 - DEBUG - Response for deleting network ()
2026-02-02 02:34:06,775 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/1dcce920-5838-41df-8036-75e33654587a
2026-02-02 02:34:06,785 - DEBUG - Response Code: 404
2026-02-02 02:34:06,785 - DEBUG - Requesting: http://10.0.0.141:8082/domains
2026-02-02 02:34:06,792 - DEBUG - Requesting: http://10.0.0.141:8082/domain/4480bafd-5776-4d34-b387-df5887123349
2026-02-02 02:34:06,803 - DEBUG - Requesting: http://10.0.0.141:8082/project/dadcea4d-c00f-4768-90cd-cc9f76f2f6d0
2026-02-02 02:34:06,895 - INFO - Validated that VN ctest-VN1-96302591 is not found in API Server
2026-02-02 02:34:06,907 - DEBUG - VN ctest-VN1-96302591 is not present in Agent 10.0.0.50
2026-02-02 02:34:06,919 - DEBUG - VN ctest-VN1-96302591 is not present in Agent 10.0.0.65
2026-02-02 02:34:06,919 - INFO - Validated that VN ctest-VN1-96302591 is not in any agent
2026-02-02 02:34:06,950 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN1-96302591 info
2026-02-02 02:34:07,033 - INFO - IPAM: ipam3 is not found in API Server
2026-02-02 02:34:07,053 - INFO - IPAM:ipam3 is not found in control node
2026-02-02 02:34:07,185 - INFO - IPAM: ipam2 is not found in API Server
2026-02-02 02:34:07,209 - INFO - IPAM:ipam2 is not found in control node
2026-02-02 02:34:07,349 - INFO - IPAM: ipam1 is not found in API Server
2026-02-02 02:34:07,372 - INFO - IPAM:ipam1 is not found in control node
2026-02-02 02:34:08,632 - DEBUG - No XMPP flaps were noticed during the test
2026-02-02 02:34:08,632 - INFO - END TEST : test_policy_inheritance_src_vn_dst_pol : PASSED[0:02:41]
2026-02-02 02:34:08,632 - INFO - --------------------------------------------------------------------------------
2026-02-02 02:34:09,397 - INFO - Deleted project: ctest-TestPolicyAcl-52120613, ID : dadcea4d-c00f-4768-90cd-cc9f76f2f6d0