is in ACTIVE state now
2025-12-29 02:37:54,385 - DEBUG - VM ctest-VM31-03937237 launched on Node an-jenkins-deploy-platform-ansible-os-4934-1
2025-12-29 02:37:54,461 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/79345271-82ff-4453-8865-6c2173d1b076
2025-12-29 02:37:54,471 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/79345271-82ff-4453-8865-6c2173d1b076
2025-12-29 02:37:54,478 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/a37ed7b4-4962-416e-b3b4-cf3406fbbe16
2025-12-29 02:37:54,486 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/79345271-82ff-4453-8865-6c2173d1b076
2025-12-29 02:37:54,493 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/a37ed7b4-4962-416e-b3b4-cf3406fbbe16
2025-12-29 02:37:54,502 - DEBUG - Requesting: http://10.0.0.27:8082/instance-ip/8bd9167c-4409-41f4-a2b8-5fbf51562fb6
2025-12-29 02:37:54,513 - DEBUG - Verifying in api server 10.0.0.27
2025-12-29 02:37:54,513 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/79345271-82ff-4453-8865-6c2173d1b076
2025-12-29 02:37:54,526 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/a37ed7b4-4962-416e-b3b4-cf3406fbbe16
2025-12-29 02:37:54,536 - DEBUG - Requesting: http://10.0.0.27:8082/instance-ip/8bd9167c-4409-41f4-a2b8-5fbf51562fb6
2025-12-29 02:37:54,544 - INFO - VM ctest-VM31-03937237 verfication in all API Servers passed
2025-12-29 02:37:54,563 - DEBUG - VM ctest-VM31-03937237 Tap interface: {'index': '4', 'name': 'tapa37ed7b4-49', 'uuid': 'a37ed7b4-4962-416e-b3b4-cf3406fbbe16', 'vrf_name': 'default-domain:ctest-TestPolicyAcl-07705896:ctest-VN3-65588404:ctest-VN3-65588404', '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': '13', 'vn_name': 'default-domain:ctest-TestPolicyAcl-07705896:ctest-VN3-65588404', 'vm_uuid': '79345271-82ff-4453-8865-6c2173d1b076', 'vm_name': 'ctest-VM31-03937237', 'ip_addr': '146.218.94.131', 'mac_addr': '02:a3:7e:d7:b4:49', '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-07705896:a37ed7b4-4962-416e-b3b4-cf3406fbbe16', 'sg_uuid_list': ['b5116de1-e99a-439a-be60-e210abce0f7b'], 'static_route_list': None, 'vm_project_uuid': '01f6987e-8737-4ee1-a8a8-bd90d497e4e1', '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': []}
2025-12-29 02:37:54,563 - DEBUG - Agent 10.0.0.56 vrf name: default-domain:ctest-TestPolicyAcl-07705896:ctest-VN3-65588404:ctest-VN3-65588404
2025-12-29 02:37:54,569 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAcl-07705896:ctest-VN3-65588404:ctest-VN3-65588404', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-TestPolicyAcl-07705896:ctest-VN3-65588404', 'table_label': '-1', 'vxlan_id': '13', 'evpnindex': '3', 'brindex': '3', 'mplsindex': '0', 'RD': '10.20.0.241:3', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]}
2025-12-29 02:37:54,653 - DEBUG - VM ctest-VM31-03937237 : Tap interface tapa37ed7b4-49 is set to Active
2025-12-29 02:37:54,653 - DEBUG - Tap interface tapa37ed7b4-49 detail : {'index': '4', 'name': 'tapa37ed7b4-49', 'uuid': 'a37ed7b4-4962-416e-b3b4-cf3406fbbe16', 'vrf_name': 'default-domain:ctest-TestPolicyAcl-07705896:ctest-VN3-65588404:ctest-VN3-65588404', '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': '13', 'vn_name': 'default-domain:ctest-TestPolicyAcl-07705896:ctest-VN3-65588404', 'vm_uuid': '79345271-82ff-4453-8865-6c2173d1b076', 'vm_name': 'ctest-VM31-03937237', 'ip_addr': '146.218.94.131', 'mac_addr': '02:a3:7e:d7:b4:49', '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-07705896:a37ed7b4-4962-416e-b3b4-cf3406fbbe16', 'sg_uuid_list': ['b5116de1-e99a-439a-be60-e210abce0f7b'], 'static_route_list': None, 'vm_project_uuid': '01f6987e-8737-4ee1-a8a8-bd90d497e4e1', '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': []}
2025-12-29 02:37:54,770 - DEBUG - Starting Layer 2 verification in Agent
2025-12-29 02:37:54,781 - DEBUG - Layer 2 path is seen for VM MAC 02:a3:7e:d7:b4:49 in agent 10.0.0.56
2025-12-29 02:37:54,781 - DEBUG - Active layer 2 route in agent is present for VMI tapa37ed7b4-49
2025-12-29 02:37:54,781 - DEBUG - L2 label(36) matches bw route table and interface table
2025-12-29 02:37:54,791 - DEBUG - VN default-domain:ctest-TestPolicyAcl-07705896:ctest-VN3-65588404 is found in Agent of node 10.0.0.56
2025-12-29 02:37:54,934 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAcl-07705896:ctest-VN3-65588404 is consistent in agent 10.0.0.56
2025-12-29 02:37:54,935 - DEBUG - Route for VM IP ['146.218.94.131'] is consistent in agent 10.0.0.56
2025-12-29 02:37:54,935 - DEBUG - VN default-domain:ctest-TestPolicyAcl-07705896:ctest-VN3-65588404 verification for VM ctest-VM31-03937237 in Agent 10.0.0.56 passed
2025-12-29 02:37:55,054 - DEBUG - Starting all layer 2 verification in agent 10.0.0.56
2025-12-29 02:37:55,064 - DEBUG - Route for VM MAC 02:a3:7e:d7:b4:49 is consistent in agent 10.0.0.56
2025-12-29 02:37:56,251 - 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=15.6 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=3.62 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 3.621/9.624/15.628/6.003 ms')
2025-12-29 02:37:56,251 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-03937237 passed
2025-12-29 02:37:56,251 - INFO - VM ctest-VM31-03937237 verifications in Compute nodes passed
2025-12-29 02:37:56,457 - DEBUG - Validated VM route 146.218.94.131 in vrouter of 10.0.0.56
2025-12-29 02:37:56,462 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0
2025-12-29 02:37:56,462 - INFO - Validated routes of VM ctest-VM31-03937237 in all vrouters
2025-12-29 02:37:57,012 - DEBUG - Starting all layer2 verification in 10.0.0.22 Control Node
2025-12-29 02:37:57,138 - DEBUG - Layer2 route found for VM MAC 02:a3:7e:d7:b4:49 in Control-node 10.0.0.22
2025-12-29 02:37:57,139 - DEBUG - L2 Label for VM ctest-VM31-03937237 same between Control-node 10.0.0.22 and Agent, Expected: 36, Seen: 36
2025-12-29 02:37:57,139 - DEBUG - Starting all layer2 verification in 10.0.0.61 Control Node
2025-12-29 02:37:57,261 - DEBUG - Layer2 route found for VM MAC 02:a3:7e:d7:b4:49 in Control-node 10.0.0.61
2025-12-29 02:37:57,261 - DEBUG - L2 Label for VM ctest-VM31-03937237 same between Control-node 10.0.0.61 and Agent, Expected: 36, Seen: 36
2025-12-29 02:37:57,261 - INFO - Verification in Control-nodes for VM ctest-VM31-03937237 passed
2025-12-29 02:37:57,261 - DEBUG - Verifying the vm in opserver
2025-12-29 02:37:57,261 - DEBUG - Verifying in collector 10.0.0.27 ...
2025-12-29 02:37:57,261 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine/79345271-82ff-4453-8865-6c2173d1b076?flat
2025-12-29 02:37:57,280 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-07705896:a37ed7b4-4962-416e-b3b4-cf3406fbbe16?flat
2025-12-29 02:37:57,301 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-07705896:a37ed7b4-4962-416e-b3b4-cf3406fbbe16?flat
2025-12-29 02:37:57,325 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine/79345271-82ff-4453-8865-6c2173d1b076?flat
2025-12-29 02:37:57,343 - DEBUG - Verifying vm in vn uve
2025-12-29 02:37:57,343 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-07705896:a37ed7b4-4962-416e-b3b4-cf3406fbbe16?flat
2025-12-29 02:37:57,364 - DEBUG - VM uve shows interface as {'label': 32, 'uuid': 'a37ed7b4-4962-416e-b3b4-cf3406fbbe16', 'vm_name': 'ctest-VM31-03937237', 'ip_address': '146.218.94.131', 'active': True, 'is_health_check_active': True, 'rx_vlan': 65535, 'admin_state': True, 'vn_uuid': 'a4c5411d-b3f9-4874-9141-d4fd76ea75a7', 'l2_active': True, 'virtual_network': 'default-domain:ctest-TestPolicyAcl-07705896:ctest-VN3-65588404', 'port_mirror_enabled': False, 'ip6_active': False, 'vhostuser_mode': 0, 'ip6_address': '::', 'fixed_ip4_list': ['146.218.94.131'], 'ip4_active': True, 'mac_address': '02:a3:7e:d7:b4:49', 'tx_vlan': 65535, 'vm_uuid': '79345271-82ff-4453-8865-6c2173d1b076', 'gateway': '146.218.94.129', '__T': 1766975840737616}
2025-12-29 02:37:57,364 - DEBUG - VM uve shows ip address as ['146.218.94.131', '::']
2025-12-29 02:37:57,365 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAcl-07705896:ctest-VN3-65588404
2025-12-29 02:37:57,365 - INFO - Verifying through opserver in 10.0.0.61
2025-12-29 02:37:57,365 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-07705896:ctest-VN3-65588404?flat
2025-12-29 02:37:57,394 - DEBUG - expected vm list 79345271-82ff-4453-8865-6c2173d1b076
2025-12-29 02:37:57,394 - DEBUG - Extracted vm list ['79345271-82ff-4453-8865-6c2173d1b076']
2025-12-29 02:37:57,394 - INFO - VM 79345271-82ff-4453-8865-6c2173d1b076 is present in default-domain:ctest-TestPolicyAcl-07705896:ctest-VN3-65588404
2025-12-29 02:37:57,394 - DEBUG - Verifying vm in vrouter uve
2025-12-29 02:37:57,394 - DEBUG - Getting info from collector 10.0.0.27..
2025-12-29 02:37:57,395 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine/79345271-82ff-4453-8865-6c2173d1b076?flat
2025-12-29 02:37:57,413 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-4934-1']
2025-12-29 02:37:57,413 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4934-1:Compute:contrail-vrouter-agent:0?flat
2025-12-29 02:37:57,449 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4934-1?flat
2025-12-29 02:37:57,486 - DEBUG - VM 79345271-82ff-4453-8865-6c2173d1b076 is present in vrouter an-jenkins-deploy-platform-ansible-os-4934-1 uve
2025-12-29 02:37:57,486 - DEBUG - Validated that VM ctest-VM31-03937237 is in Vrouter an-jenkins-deploy-platform-ansible-os-4934-1 UVE
2025-12-29 02:37:57,486 - DEBUG - Verifying vm tap interface/vn in vrouter uve
2025-12-29 02:37:57,493 - DEBUG - Expected tap interface of VM uuid 79345271-82ff-4453-8865-6c2173d1b076 is default-domain:ctest-TestPolicyAcl-07705896:a37ed7b4-4962-416e-b3b4-cf3406fbbe16
2025-12-29 02:37:57,494 - DEBUG - Expected VN of VM uuid 79345271-82ff-4453-8865-6c2173d1b076 is default-domain:ctest-TestPolicyAcl-07705896:ctest-VN3-65588404
2025-12-29 02:37:57,494 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4934-1:Compute:contrail-vrouter-agent:0?flat
2025-12-29 02:37:57,526 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4934-1?flat
2025-12-29 02:37:57,555 - DEBUG - VM 79345271-82ff-4453-8865-6c2173d1b076 is present in vrouter an-jenkins-deploy-platform-ansible-os-4934-1 uve
2025-12-29 02:37:57,555 - INFO - tap interface default-domain:ctest-TestPolicyAcl-07705896:a37ed7b4-4962-416e-b3b4-cf3406fbbe16 of vm 79345271-82ff-4453-8865-6c2173d1b076 is present in vrouter an-jenkins-deploy-platform-ansible-os-4934-1 uve
2025-12-29 02:37:57,555 - DEBUG - Connected network default-domain:ctest-TestPolicyAcl-07705896:ctest-VN3-65588404 of vm 79345271-82ff-4453-8865-6c2173d1b076 is present in vrouter an-jenkins-deploy-platform-ansible-os-4934-1 uve
2025-12-29 02:37:57,555 - DEBUG - Verifying the 79345271-82ff-4453-8865-6c2173d1b076 virtual network link through opserver 10.0.0.61
2025-12-29 02:37:57,555 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/virtual-machines
2025-12-29 02:37:57,568 - DEBUG - VM link and name as {'name': '79345271-82ff-4453-8865-6c2173d1b076', 'href': 'http://10.0.0.61:8081/analytics/uves/virtual-machine/79345271-82ff-4453-8865-6c2173d1b076?flat'}
2025-12-29 02:37:57,568 - DEBUG - Verifying the 79345271-82ff-4453-8865-6c2173d1b076 virtual network link through opserver 10.0.0.22
2025-12-29 02:37:57,568 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machines
2025-12-29 02:37:57,579 - DEBUG - VM link and name as {'name': '79345271-82ff-4453-8865-6c2173d1b076', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-machine/79345271-82ff-4453-8865-6c2173d1b076?flat'}
2025-12-29 02:37:57,579 - DEBUG - Verifying the 79345271-82ff-4453-8865-6c2173d1b076 virtual network link through opserver 10.0.0.27
2025-12-29 02:37:57,579 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines
2025-12-29 02:37:57,593 - DEBUG - VM link and name as {'name': '79345271-82ff-4453-8865-6c2173d1b076', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-machine/79345271-82ff-4453-8865-6c2173d1b076?flat'}
2025-12-29 02:37:57,594 - INFO - VM ctest-VM31-03937237 validations in Opserver passed
2025-12-29 02:37:57,594 - INFO - Waiting for VM ctest-VM11-02664056 to be up..
2025-12-29 02:37:57,594 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-12-29 02:37:57,594 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/763891dc-6b38-444a-89d3-ba0b22b65dee
2025-12-29 02:37:57,602 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/d5a77472-7f32-425d-9ae2-db10f28edaab
2025-12-29 02:37:58,769 - 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.12 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.741 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1000ms\r\nrtt min/avg/max/mdev = 0.741/1.928/3.116/1.187 ms')
2025-12-29 02:37:58,769 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-02664056 passed
2025-12-29 02:37:58,836 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-12-29 02:37:58,836 - DEBUG - Waiting to SSH to VM ctest-VM11-02664056, IP 217.62.112.195, Port 22
2025-12-29 02:37:58,907 - 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-12-29 02:37:58,980 - DEBUG - VM ctest-VM11-02664056 is NOT ready for SSH connections, VM status: ACTIVE
2025-12-29 02:38:03,981 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-12-29 02:38:03,981 - DEBUG - Waiting to SSH to VM ctest-VM11-02664056, IP 217.62.112.195, Port 22
2025-12-29 02:38:04,048 - 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-12-29 02:38:04,135 - DEBUG - VM ctest-VM11-02664056 is NOT ready for SSH connections, VM status: ACTIVE
2025-12-29 02:38:09,135 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-12-29 02:38:09,135 - DEBUG - Waiting to SSH to VM ctest-VM11-02664056, IP 217.62.112.195, Port 22
2025-12-29 02:38:09,203 - 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-12-29 02:38:09,293 - DEBUG - VM ctest-VM11-02664056 is NOT ready for SSH connections, VM status: ACTIVE
2025-12-29 02:38:14,293 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-12-29 02:38:14,293 - DEBUG - Waiting to SSH to VM ctest-VM11-02664056, IP 217.62.112.195, Port 22
2025-12-29 02:38:14,465 - DEBUG - VM ctest-VM11-02664056 is ready for SSH connections
2025-12-29 02:38:14,465 - INFO - Waiting for VM ctest-VM21-13777933 to be up..
2025-12-29 02:38:14,465 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-12-29 02:38:14,465 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/9a537c63-4f27-4d54-8e86-a9c65f15a264
2025-12-29 02:38:14,474 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/58e08760-80e3-484b-a0bc-6bc59f05618c
2025-12-29 02:38:15,628 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=1.16 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.378 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.378/0.766/1.155/0.388 ms')
2025-12-29 02:38:15,628 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-13777933 passed
2025-12-29 02:38:15,698 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-12-29 02:38:15,698 - DEBUG - Waiting to SSH to VM ctest-VM21-13777933, IP 120.49.164.131, Port 22
2025-12-29 02:38:15,864 - DEBUG - VM ctest-VM21-13777933 is ready for SSH connections
2025-12-29 02:38:15,864 - INFO - Waiting for VM ctest-VM31-03937237 to be up..
2025-12-29 02:38:15,864 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-12-29 02:38:15,865 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/79345271-82ff-4453-8865-6c2173d1b076
2025-12-29 02:38:15,873 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/a37ed7b4-4962-416e-b3b4-cf3406fbbe16
2025-12-29 02:38:17,039 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=4.33 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=1.45 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.446/2.886/4.326/1.440 ms')
2025-12-29 02:38:17,039 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-03937237 passed
2025-12-29 02:38:17,109 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-12-29 02:38:17,109 - DEBUG - Waiting to SSH to VM ctest-VM31-03937237, IP 146.218.94.131, Port 22
2025-12-29 02:38:17,279 - DEBUG - VM ctest-VM31-03937237 is ready for SSH connections
2025-12-29 02:38:17,279 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 120.49.164.131, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.56, gateway password: c0ntrail123
2025-12-29 02:38:17,280 - DEBUG - ping -s 56 -c 3 -W 1 120.49.164.131
2025-12-29 02:38:42,744 - DEBUG - PING 120.49.164.131 (120.49.164.131) 56(84) bytes of data.
64 bytes from 120.49.164.131: icmp_req=1 ttl=63 time=6.17 ms
64 bytes from 120.49.164.131: icmp_req=2 ttl=63 time=1.21 ms
64 bytes from 120.49.164.131: icmp_req=3 ttl=63 time=1.15 ms
--- 120.49.164.131 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 1.151/2.847/6.175/2.353 ms
2025-12-29 02:38:42,744 - INFO - Ping to IP 120.49.164.131 from VM ctest-VM11-02664056 passed
2025-12-29 02:38:42,744 - INFO - Test with src as VN and dst as policy PASSED
2025-12-29 02:38:42,744 - INFO - Deleting VM ctest-VM31-03937237
2025-12-29 02:38:42,816 - DEBUG - Verifying in api server 10.0.0.27
2025-12-29 02:38:42,816 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/79345271-82ff-4453-8865-6c2173d1b076
2025-12-29 02:38:42,826 - DEBUG - VM ID 79345271-82ff-4453-8865-6c2173d1b076 of VM ctest-VM31-03937237 is still found in API Server
2025-12-29 02:38:44,826 - DEBUG - Verifying in api server 10.0.0.27
2025-12-29 02:38:44,827 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/79345271-82ff-4453-8865-6c2173d1b076
2025-12-29 02:38:44,833 - DEBUG - Response Code: 404
2025-12-29 02:38:44,833 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/79345271-82ff-4453-8865-6c2173d1b076
2025-12-29 02:38:44,839 - DEBUG - Response Code: 404
2025-12-29 02:38:44,839 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/79345271-82ff-4453-8865-6c2173d1b076
2025-12-29 02:38:44,844 - DEBUG - Response Code: 404
2025-12-29 02:38:44,844 - INFO - VM ctest-VM31-03937237 is fully removed in API-Server
2025-12-29 02:38:44,996 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2025-12-29 02:38:45,033 - DEBUG - Validated that vrouter 10.0.0.56 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-07705896:ctest-VN3-65588404
2025-12-29 02:38:45,042 - DEBUG - Validated that vrouter 10.0.0.240 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-07705896:ctest-VN3-65588404
2025-12-29 02:38:45,042 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAcl-07705896:ctest-VN3-65588404
2025-12-29 02:38:45,042 - INFO - VM ctest-VM31-03937237 is removed in Compute, and routes are removed in all compute nodes
2025-12-29 02:38:45,268 - INFO - Routes for VM ctest-VM31-03937237 is removed in all control-nodes
2025-12-29 02:38:45,268 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4934-1:Compute:contrail-vrouter-agent:0?flat
2025-12-29 02:38:45,301 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4934-1?flat
2025-12-29 02:38:45,330 - DEBUG - VM 79345271-82ff-4453-8865-6c2173d1b076 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4934-1 uve
2025-12-29 02:38:45,330 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-12-29 02:38:45,331 - DEBUG - interface for vm 79345271-82ff-4453-8865-6c2173d1b076 deleted from vrouter uve ...
2025-12-29 02:38:45,331 - DEBUG - Deleted interface not in error interface list ...
2025-12-29 02:38:48,332 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4934-1:Compute:contrail-vrouter-agent:0?flat
2025-12-29 02:38:48,373 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4934-1?flat
2025-12-29 02:38:48,403 - DEBUG - VM 79345271-82ff-4453-8865-6c2173d1b076 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4934-1 uve
2025-12-29 02:38:48,403 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-12-29 02:38:48,403 - DEBUG - interface for vm 79345271-82ff-4453-8865-6c2173d1b076 deleted from vrouter uve ...
2025-12-29 02:38:48,403 - DEBUG - Deleted interface not in error interface list ...
2025-12-29 02:38:51,404 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4934-1:Compute:contrail-vrouter-agent:0?flat
2025-12-29 02:38:51,442 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4934-1?flat
2025-12-29 02:38:51,471 - DEBUG - VM 79345271-82ff-4453-8865-6c2173d1b076 is not present in vrouter an-jenkins-deploy-platform-ansible-os-4934-1 uve
2025-12-29 02:38:51,471 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-12-29 02:38:51,471 - DEBUG - interface for vm 79345271-82ff-4453-8865-6c2173d1b076 deleted from vrouter uve ...
2025-12-29 02:38:51,471 - DEBUG - Deleted interface not in error interface list ...
2025-12-29 02:38:51,471 - DEBUG - Validated that VM 79345271-82ff-4453-8865-6c2173d1b076 is removed in Vrouter UVE
2025-12-29 02:38:51,471 - DEBUG - Verifying through opserver in 10.0.0.61
2025-12-29 02:38:51,471 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-07705896:ctest-VN3-65588404?flat
2025-12-29 02:38:51,491 - DEBUG - 79345271-82ff-4453-8865-6c2173d1b076 BM not in default-domain:ctest-TestPolicyAcl-07705896:ctest-VN3-65588404 uve
2025-12-29 02:38:51,491 - DEBUG - Validated that VM 79345271-82ff-4453-8865-6c2173d1b076 is not present in the VN UVE
2025-12-29 02:38:51,491 - DEBUG - Verifying the 79345271-82ff-4453-8865-6c2173d1b076 virtual network link through opserver 10.0.0.61
2025-12-29 02:38:51,491 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/virtual-machines
2025-12-29 02:38:51,505 - INFO - 79345271-82ff-4453-8865-6c2173d1b076 vm uve deleted from opserver
2025-12-29 02:38:51,505 - DEBUG - Verifying the 79345271-82ff-4453-8865-6c2173d1b076 virtual network link through opserver 10.0.0.22
2025-12-29 02:38:51,505 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machines
2025-12-29 02:38:51,519 - INFO - 79345271-82ff-4453-8865-6c2173d1b076 vm uve deleted from opserver
2025-12-29 02:38:51,519 - DEBUG - Verifying the 79345271-82ff-4453-8865-6c2173d1b076 virtual network link through opserver 10.0.0.27
2025-12-29 02:38:51,519 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines
2025-12-29 02:38:51,531 - INFO - 79345271-82ff-4453-8865-6c2173d1b076 vm uve deleted from opserver
2025-12-29 02:38:51,531 - INFO - Validated that VM 79345271-82ff-4453-8865-6c2173d1b076 is removed from Opserver
2025-12-29 02:38:51,531 - INFO - Deleting VM ctest-VM21-13777933
2025-12-29 02:38:51,618 - DEBUG - Verifying in api server 10.0.0.27
2025-12-29 02:38:51,618 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/9a537c63-4f27-4d54-8e86-a9c65f15a264
2025-12-29 02:38:51,627 - DEBUG - VM ID 9a537c63-4f27-4d54-8e86-a9c65f15a264 of VM ctest-VM21-13777933 is still found in API Server
2025-12-29 02:38:53,627 - DEBUG - Verifying in api server 10.0.0.27
2025-12-29 02:38:53,627 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/9a537c63-4f27-4d54-8e86-a9c65f15a264
2025-12-29 02:38:53,634 - DEBUG - Response Code: 404
2025-12-29 02:38:53,634 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/9a537c63-4f27-4d54-8e86-a9c65f15a264
2025-12-29 02:38:53,639 - DEBUG - Response Code: 404
2025-12-29 02:38:53,640 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/9a537c63-4f27-4d54-8e86-a9c65f15a264
2025-12-29 02:38:53,645 - DEBUG - Response Code: 404
2025-12-29 02:38:53,645 - INFO - VM ctest-VM21-13777933 is fully removed in API-Server
2025-12-29 02:38:53,803 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2025-12-29 02:38:53,842 - DEBUG - Validated that vrouter 10.0.0.56 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-07705896:ctest-VN2-58312156
2025-12-29 02:38:53,853 - DEBUG - Validated that vrouter 10.0.0.240 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-07705896:ctest-VN2-58312156
2025-12-29 02:38:53,853 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAcl-07705896:ctest-VN2-58312156
2025-12-29 02:38:53,853 - INFO - VM ctest-VM21-13777933 is removed in Compute, and routes are removed in all compute nodes
2025-12-29 02:38:54,053 - INFO - Routes for VM ctest-VM21-13777933 is removed in all control-nodes
2025-12-29 02:38:54,053 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4934-2:Compute:contrail-vrouter-agent:0?flat
2025-12-29 02:38:54,084 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4934-2?flat
2025-12-29 02:38:54,109 - DEBUG - VM 9a537c63-4f27-4d54-8e86-a9c65f15a264 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4934-2 uve
2025-12-29 02:38:54,109 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-12-29 02:38:54,110 - DEBUG - interface for vm 9a537c63-4f27-4d54-8e86-a9c65f15a264 deleted from vrouter uve ...
2025-12-29 02:38:54,110 - DEBUG - Deleted interface not in error interface list ...
2025-12-29 02:38:57,111 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4934-2:Compute:contrail-vrouter-agent:0?flat
2025-12-29 02:38:57,145 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4934-2?flat
2025-12-29 02:38:57,173 - DEBUG - VM 9a537c63-4f27-4d54-8e86-a9c65f15a264 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4934-2 uve
2025-12-29 02:38:57,173 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-12-29 02:38:57,173 - DEBUG - interface for vm 9a537c63-4f27-4d54-8e86-a9c65f15a264 deleted from vrouter uve ...
2025-12-29 02:38:57,173 - DEBUG - Deleted interface not in error interface list ...
2025-12-29 02:39:00,174 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4934-2:Compute:contrail-vrouter-agent:0?flat
2025-12-29 02:39:00,214 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4934-2?flat
2025-12-29 02:39:00,251 - DEBUG - VM 9a537c63-4f27-4d54-8e86-a9c65f15a264 is not present in vrouter an-jenkins-deploy-platform-ansible-os-4934-2 uve
2025-12-29 02:39:00,251 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-12-29 02:39:00,251 - DEBUG - interface for vm 9a537c63-4f27-4d54-8e86-a9c65f15a264 deleted from vrouter uve ...
2025-12-29 02:39:00,251 - DEBUG - Deleted interface not in error interface list ...
2025-12-29 02:39:00,251 - DEBUG - Validated that VM 9a537c63-4f27-4d54-8e86-a9c65f15a264 is removed in Vrouter UVE
2025-12-29 02:39:00,251 - DEBUG - Verifying through opserver in 10.0.0.61
2025-12-29 02:39:00,251 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-07705896:ctest-VN2-58312156?flat
2025-12-29 02:39:00,276 - DEBUG - 9a537c63-4f27-4d54-8e86-a9c65f15a264 BM not in default-domain:ctest-TestPolicyAcl-07705896:ctest-VN2-58312156 uve
2025-12-29 02:39:00,276 - DEBUG - Validated that VM 9a537c63-4f27-4d54-8e86-a9c65f15a264 is not present in the VN UVE
2025-12-29 02:39:00,276 - DEBUG - Verifying the 9a537c63-4f27-4d54-8e86-a9c65f15a264 virtual network link through opserver 10.0.0.61
2025-12-29 02:39:00,276 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/virtual-machines
2025-12-29 02:39:00,291 - INFO - 9a537c63-4f27-4d54-8e86-a9c65f15a264 vm uve deleted from opserver
2025-12-29 02:39:00,291 - DEBUG - Verifying the 9a537c63-4f27-4d54-8e86-a9c65f15a264 virtual network link through opserver 10.0.0.22
2025-12-29 02:39:00,292 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machines
2025-12-29 02:39:00,303 - INFO - 9a537c63-4f27-4d54-8e86-a9c65f15a264 vm uve deleted from opserver
2025-12-29 02:39:00,304 - DEBUG - Verifying the 9a537c63-4f27-4d54-8e86-a9c65f15a264 virtual network link through opserver 10.0.0.27
2025-12-29 02:39:00,304 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines
2025-12-29 02:39:00,317 - INFO - 9a537c63-4f27-4d54-8e86-a9c65f15a264 vm uve deleted from opserver
2025-12-29 02:39:00,318 - INFO - Validated that VM 9a537c63-4f27-4d54-8e86-a9c65f15a264 is removed from Opserver
2025-12-29 02:39:00,318 - INFO - Deleting VM ctest-VM11-02664056
2025-12-29 02:39:00,415 - DEBUG - Verifying in api server 10.0.0.27
2025-12-29 02:39:00,415 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/763891dc-6b38-444a-89d3-ba0b22b65dee
2025-12-29 02:39:00,425 - DEBUG - VM ID 763891dc-6b38-444a-89d3-ba0b22b65dee of VM ctest-VM11-02664056 is still found in API Server
2025-12-29 02:39:02,426 - DEBUG - Verifying in api server 10.0.0.27
2025-12-29 02:39:02,426 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/763891dc-6b38-444a-89d3-ba0b22b65dee
2025-12-29 02:39:02,433 - DEBUG - Response Code: 404
2025-12-29 02:39:02,433 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/763891dc-6b38-444a-89d3-ba0b22b65dee
2025-12-29 02:39:02,439 - DEBUG - Response Code: 404
2025-12-29 02:39:02,439 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/763891dc-6b38-444a-89d3-ba0b22b65dee
2025-12-29 02:39:02,445 - DEBUG - Response Code: 404
2025-12-29 02:39:02,445 - INFO - VM ctest-VM11-02664056 is fully removed in API-Server
2025-12-29 02:39:02,600 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2025-12-29 02:39:02,644 - DEBUG - Validated that vrouter 10.0.0.56 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-07705896:ctest-VN1-36184561
2025-12-29 02:39:02,656 - DEBUG - Validated that vrouter 10.0.0.240 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-07705896:ctest-VN1-36184561
2025-12-29 02:39:02,656 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAcl-07705896:ctest-VN1-36184561
2025-12-29 02:39:02,657 - INFO - VM ctest-VM11-02664056 is removed in Compute, and routes are removed in all compute nodes
2025-12-29 02:39:02,864 - INFO - Routes for VM ctest-VM11-02664056 is removed in all control-nodes
2025-12-29 02:39:02,865 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4934-1:Compute:contrail-vrouter-agent:0?flat
2025-12-29 02:39:02,901 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4934-1?flat
2025-12-29 02:39:02,937 - DEBUG - VM 763891dc-6b38-444a-89d3-ba0b22b65dee is still present in vrouter an-jenkins-deploy-platform-ansible-os-4934-1 uve
2025-12-29 02:39:02,937 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-12-29 02:39:02,938 - DEBUG - interface for vm 763891dc-6b38-444a-89d3-ba0b22b65dee deleted from vrouter uve ...
2025-12-29 02:39:02,938 - DEBUG - Deleted interface not in error interface list ...
2025-12-29 02:39:05,938 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4934-1:Compute:contrail-vrouter-agent:0?flat
2025-12-29 02:39:05,970 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4934-1?flat
2025-12-29 02:39:06,007 - DEBUG - VM 763891dc-6b38-444a-89d3-ba0b22b65dee is still present in vrouter an-jenkins-deploy-platform-ansible-os-4934-1 uve
2025-12-29 02:39:06,008 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-12-29 02:39:06,008 - DEBUG - interface for vm 763891dc-6b38-444a-89d3-ba0b22b65dee deleted from vrouter uve ...
2025-12-29 02:39:06,008 - DEBUG - Deleted interface not in error interface list ...
2025-12-29 02:39:09,008 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4934-1:Compute:contrail-vrouter-agent:0?flat
2025-12-29 02:39:09,040 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4934-1?flat
2025-12-29 02:39:09,068 - DEBUG - VM 763891dc-6b38-444a-89d3-ba0b22b65dee is still present in vrouter an-jenkins-deploy-platform-ansible-os-4934-1 uve
2025-12-29 02:39:09,068 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-12-29 02:39:09,068 - DEBUG - interface for vm 763891dc-6b38-444a-89d3-ba0b22b65dee deleted from vrouter uve ...
2025-12-29 02:39:09,068 - DEBUG - Deleted interface not in error interface list ...
2025-12-29 02:39:12,068 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4934-1:Compute:contrail-vrouter-agent:0?flat
2025-12-29 02:39:12,099 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4934-1?flat
2025-12-29 02:39:12,126 - DEBUG - VM 763891dc-6b38-444a-89d3-ba0b22b65dee is still present in vrouter an-jenkins-deploy-platform-ansible-os-4934-1 uve
2025-12-29 02:39:12,126 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-12-29 02:39:12,126 - DEBUG - interface for vm 763891dc-6b38-444a-89d3-ba0b22b65dee deleted from vrouter uve ...
2025-12-29 02:39:12,126 - DEBUG - Deleted interface not in error interface list ...
2025-12-29 02:39:15,126 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4934-1:Compute:contrail-vrouter-agent:0?flat
2025-12-29 02:39:15,160 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4934-1?flat
2025-12-29 02:39:15,190 - DEBUG - VM 763891dc-6b38-444a-89d3-ba0b22b65dee is still present in vrouter an-jenkins-deploy-platform-ansible-os-4934-1 uve
2025-12-29 02:39:15,190 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-12-29 02:39:15,190 - DEBUG - interface for vm 763891dc-6b38-444a-89d3-ba0b22b65dee deleted from vrouter uve ...
2025-12-29 02:39:15,190 - DEBUG - Deleted interface not in error interface list ...
2025-12-29 02:39:18,191 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4934-1:Compute:contrail-vrouter-agent:0?flat
2025-12-29 02:39:18,227 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4934-1?flat
2025-12-29 02:39:18,256 - DEBUG - VM 763891dc-6b38-444a-89d3-ba0b22b65dee is still present in vrouter an-jenkins-deploy-platform-ansible-os-4934-1 uve
2025-12-29 02:39:18,256 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-12-29 02:39:18,256 - DEBUG - interface for vm 763891dc-6b38-444a-89d3-ba0b22b65dee deleted from vrouter uve ...
2025-12-29 02:39:18,256 - DEBUG - Deleted interface not in error interface list ...
2025-12-29 02:39:21,257 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4934-1:Compute:contrail-vrouter-agent:0?flat
2025-12-29 02:39:21,291 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4934-1?flat
2025-12-29 02:39:21,318 - DEBUG - VM 763891dc-6b38-444a-89d3-ba0b22b65dee is not present in vrouter an-jenkins-deploy-platform-ansible-os-4934-1 uve
2025-12-29 02:39:21,318 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-12-29 02:39:21,318 - DEBUG - interface for vm 763891dc-6b38-444a-89d3-ba0b22b65dee deleted from vrouter uve ...
2025-12-29 02:39:21,318 - DEBUG - Deleted interface not in error interface list ...
2025-12-29 02:39:21,318 - DEBUG - Validated that VM 763891dc-6b38-444a-89d3-ba0b22b65dee is removed in Vrouter UVE
2025-12-29 02:39:21,318 - DEBUG - Verifying through opserver in 10.0.0.61
2025-12-29 02:39:21,319 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-07705896:ctest-VN1-36184561?flat
2025-12-29 02:39:21,340 - DEBUG - 763891dc-6b38-444a-89d3-ba0b22b65dee BM not in default-domain:ctest-TestPolicyAcl-07705896:ctest-VN1-36184561 uve
2025-12-29 02:39:21,340 - DEBUG - Validated that VM 763891dc-6b38-444a-89d3-ba0b22b65dee is not present in the VN UVE
2025-12-29 02:39:21,340 - DEBUG - Verifying the 763891dc-6b38-444a-89d3-ba0b22b65dee virtual network link through opserver 10.0.0.61
2025-12-29 02:39:21,340 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/virtual-machines
2025-12-29 02:39:21,352 - DEBUG - No links retuned for 763891dc-6b38-444a-89d3-ba0b22b65dee
2025-12-29 02:39:21,352 - INFO - 763891dc-6b38-444a-89d3-ba0b22b65dee vm uve deleted from opserver
2025-12-29 02:39:21,352 - DEBUG - Verifying the 763891dc-6b38-444a-89d3-ba0b22b65dee virtual network link through opserver 10.0.0.22
2025-12-29 02:39:21,352 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machines
2025-12-29 02:39:21,362 - DEBUG - No links retuned for 763891dc-6b38-444a-89d3-ba0b22b65dee
2025-12-29 02:39:21,362 - INFO - 763891dc-6b38-444a-89d3-ba0b22b65dee vm uve deleted from opserver
2025-12-29 02:39:21,362 - DEBUG - Verifying the 763891dc-6b38-444a-89d3-ba0b22b65dee virtual network link through opserver 10.0.0.27
2025-12-29 02:39:21,363 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines
2025-12-29 02:39:21,374 - DEBUG - No links retuned for 763891dc-6b38-444a-89d3-ba0b22b65dee
2025-12-29 02:39:21,374 - INFO - 763891dc-6b38-444a-89d3-ba0b22b65dee vm uve deleted from opserver
2025-12-29 02:39:21,374 - INFO - Validated that VM 763891dc-6b38-444a-89d3-ba0b22b65dee is removed from Opserver
2025-12-29 02:39:21,375 - DEBUG - Detaching the Policy for VN :ctest-VN2-58312156
2025-12-29 02:39:21,375 - DEBUG - Requesting: http://10.0.0.61:8082/domains
2025-12-29 02:39:21,381 - DEBUG - Requesting: http://10.0.0.61:8082/domain/ea4b09a7-31d2-48cf-9e60-035b93d3d81c
2025-12-29 02:39:21,393 - DEBUG - Requesting: http://10.0.0.61:8082/project/01f6987e-8737-4ee1-a8a8-bd90d497e4e1
2025-12-29 02:39:21,485 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-network/9aca612a-34df-4b71-8310-5b99c955ce55
2025-12-29 02:39:21,509 - DEBUG - Requesting: http://10.0.0.61:8082/network-policy/0f2be485-96e0-4606-83f4-3cd6dc0aeae8
2025-12-29 02:39:21,679 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAcl-07705896', 'ctest-policy21-10945031']] from ctest-VN2-58312156
2025-12-29 02:39:21,679 - DEBUG - Detaching the Policy for VN :ctest-VN1-36184561
2025-12-29 02:39:21,679 - DEBUG - Requesting: http://10.0.0.61:8082/domains
2025-12-29 02:39:21,688 - DEBUG - Requesting: http://10.0.0.61:8082/domain/ea4b09a7-31d2-48cf-9e60-035b93d3d81c
2025-12-29 02:39:21,703 - DEBUG - Requesting: http://10.0.0.61:8082/project/01f6987e-8737-4ee1-a8a8-bd90d497e4e1
2025-12-29 02:39:21,807 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-network/20b3efb7-ec88-4be5-8558-93b2e4a1cbdc
2025-12-29 02:39:21,992 - INFO - Detached Policy from ctest-VN1-36184561
2025-12-29 02:39:22,053 - INFO - Deleted policy ctest-policy13-24405209
2025-12-29 02:39:22,101 - INFO - Deleted policy ctest-policy21-10945031
2025-12-29 02:39:22,142 - INFO - Deleted policy ctest-policy12-53928935
2025-12-29 02:39:22,142 - INFO - Deleting VN ctest-VN3-65588404
2025-12-29 02:39:22,308 - DEBUG - Response for deleting network ()
2025-12-29 02:39:22,309 - DEBUG - Requesting: http://10.0.0.61:8082/routing-instance/f47fc017-1780-4ea8-a8bd-3bd63794273f
2025-12-29 02:39:22,317 - DEBUG - Response Code: 404
2025-12-29 02:39:22,318 - DEBUG - Requesting: http://10.0.0.61:8082/domains
2025-12-29 02:39:22,325 - DEBUG - Requesting: http://10.0.0.61:8082/domain/ea4b09a7-31d2-48cf-9e60-035b93d3d81c
2025-12-29 02:39:22,381 - DEBUG - Requesting: http://10.0.0.61:8082/project/01f6987e-8737-4ee1-a8a8-bd90d497e4e1
2025-12-29 02:39:22,464 - INFO - Validated that VN ctest-VN3-65588404 is not found in API Server
2025-12-29 02:39:22,477 - DEBUG - VN ctest-VN3-65588404 is not present in Agent 10.0.0.56
2025-12-29 02:39:22,487 - DEBUG - VN ctest-VN3-65588404 is not present in Agent 10.0.0.240
2025-12-29 02:39:22,487 - INFO - Validated that VN ctest-VN3-65588404 is not in any agent
2025-12-29 02:39:22,519 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN3-65588404 info
2025-12-29 02:39:22,519 - INFO - Deleting VN ctest-VN2-58312156
2025-12-29 02:39:22,700 - DEBUG - Response for deleting network ()
2025-12-29 02:39:22,700 - DEBUG - Requesting: http://10.0.0.61:8082/routing-instance/4f129dc2-799b-4fac-8750-4d206d600fc0
2025-12-29 02:39:22,705 - DEBUG - Response Code: 404
2025-12-29 02:39:22,706 - DEBUG - Requesting: http://10.0.0.61:8082/domains
2025-12-29 02:39:22,712 - DEBUG - Requesting: http://10.0.0.61:8082/domain/ea4b09a7-31d2-48cf-9e60-035b93d3d81c
2025-12-29 02:39:22,722 - DEBUG - Requesting: http://10.0.0.61:8082/project/01f6987e-8737-4ee1-a8a8-bd90d497e4e1
2025-12-29 02:39:22,810 - INFO - Validated that VN ctest-VN2-58312156 is not found in API Server
2025-12-29 02:39:22,822 - DEBUG - VN ctest-VN2-58312156 is not present in Agent 10.0.0.56
2025-12-29 02:39:22,835 - DEBUG - VN ctest-VN2-58312156 is not present in Agent 10.0.0.240
2025-12-29 02:39:22,835 - INFO - Validated that VN ctest-VN2-58312156 is not in any agent
2025-12-29 02:39:22,864 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN2-58312156 info
2025-12-29 02:39:22,865 - INFO - Deleting VN ctest-VN1-36184561
2025-12-29 02:39:23,037 - DEBUG - Response for deleting network ()
2025-12-29 02:39:23,037 - DEBUG - Requesting: http://10.0.0.61:8082/routing-instance/e0930092-2ade-449e-8084-0247a439bcdc
2025-12-29 02:39:23,043 - DEBUG - Response Code: 404
2025-12-29 02:39:23,044 - DEBUG - Requesting: http://10.0.0.61:8082/domains
2025-12-29 02:39:23,051 - DEBUG - Requesting: http://10.0.0.61:8082/domain/ea4b09a7-31d2-48cf-9e60-035b93d3d81c
2025-12-29 02:39:23,063 - DEBUG - Requesting: http://10.0.0.61:8082/project/01f6987e-8737-4ee1-a8a8-bd90d497e4e1
2025-12-29 02:39:23,148 - INFO - Validated that VN ctest-VN1-36184561 is not found in API Server
2025-12-29 02:39:23,160 - DEBUG - VN ctest-VN1-36184561 is not present in Agent 10.0.0.56
2025-12-29 02:39:23,170 - DEBUG - VN ctest-VN1-36184561 is not present in Agent 10.0.0.240
2025-12-29 02:39:23,170 - INFO - Validated that VN ctest-VN1-36184561 is not in any agent
2025-12-29 02:39:23,202 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN1-36184561 info
2025-12-29 02:39:23,300 - INFO - IPAM: ipam3 is not found in API Server
2025-12-29 02:39:23,322 - INFO - IPAM:ipam3 is not found in control node
2025-12-29 02:39:23,460 - INFO - IPAM: ipam2 is not found in API Server
2025-12-29 02:39:23,487 - INFO - IPAM:ipam2 is not found in control node
2025-12-29 02:39:23,628 - INFO - IPAM: ipam1 is not found in API Server
2025-12-29 02:39:23,651 - INFO - IPAM:ipam1 is not found in control node
2025-12-29 02:39:24,937 - DEBUG - No XMPP flaps were noticed during the test
2025-12-29 02:39:24,937 - INFO - END TEST : test_policy_inheritance_src_vn_dst_pol : PASSED[0:02:41]
2025-12-29 02:39:24,938 - INFO - --------------------------------------------------------------------------------
2025-12-29 02:39:25,716 - INFO - Deleted project: ctest-TestPolicyAcl-07705896, ID : 01f6987e-8737-4ee1-a8a8-bd90d497e4e1