is in ACTIVE state now
2025-08-10 21:04:21,082 - DEBUG - VM ctest-VM31-74406965 launched on Node an-jenkins-deploy-platform-ansible-os-3790-1
2025-08-10 21:04:21,166 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine/8f57a4b5-c9c1-43ed-b699-57047e38c231
2025-08-10 21:04:21,176 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine/8f57a4b5-c9c1-43ed-b699-57047e38c231
2025-08-10 21:04:21,184 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine-interface/1da2dd95-36f8-45dc-b621-dab0ed6904f1
2025-08-10 21:04:21,193 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine/8f57a4b5-c9c1-43ed-b699-57047e38c231
2025-08-10 21:04:21,200 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine-interface/1da2dd95-36f8-45dc-b621-dab0ed6904f1
2025-08-10 21:04:21,210 - DEBUG - Requesting: http://10.0.0.49:8082/instance-ip/bee06043-c5f1-4d2c-a170-3cc30ca9eb48
2025-08-10 21:04:21,219 - DEBUG - Verifying in api server 10.0.0.49
2025-08-10 21:04:21,220 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine/8f57a4b5-c9c1-43ed-b699-57047e38c231
2025-08-10 21:04:21,228 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine-interface/1da2dd95-36f8-45dc-b621-dab0ed6904f1
2025-08-10 21:04:21,240 - DEBUG - Requesting: http://10.0.0.49:8082/instance-ip/bee06043-c5f1-4d2c-a170-3cc30ca9eb48
2025-08-10 21:04:21,247 - INFO - VM ctest-VM31-74406965 verfication in all API Servers passed
2025-08-10 21:04:21,264 - DEBUG - VM ctest-VM31-74406965 Tap interface: {'index': '4', 'name': 'tap1da2dd95-36', 'uuid': '1da2dd95-36f8-45dc-b621-dab0ed6904f1', 'vrf_name': 'default-domain:ctest-TestPolicyAcl-36559010:ctest-VN3-41493751:ctest-VN3-41493751', '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-36559010:ctest-VN3-41493751', 'vm_uuid': '8f57a4b5-c9c1-43ed-b699-57047e38c231', 'vm_name': 'ctest-VM31-74406965', 'ip_addr': '199.16.214.67', 'mac_addr': '02:1d:a2:dd:95:36', '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-36559010:1da2dd95-36f8-45dc-b621-dab0ed6904f1', 'sg_uuid_list': ['164f5d01-fbc4-4a48-a1bc-9381ff6567c6'], 'static_route_list': None, 'vm_project_uuid': 'fcda956e-5f8a-4b8d-9f5c-49463798dc45', 'admin_state': 'Enabled', 'flow_key_idx': '41', '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-08-10 21:04:21,264 - DEBUG - Agent 10.0.0.254 vrf name: default-domain:ctest-TestPolicyAcl-36559010:ctest-VN3-41493751:ctest-VN3-41493751
2025-08-10 21:04:21,270 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAcl-36559010:ctest-VN3-41493751:ctest-VN3-41493751', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-TestPolicyAcl-36559010:ctest-VN3-41493751', 'table_label': '-1', 'vxlan_id': '13', 'evpnindex': '3', 'brindex': '3', 'mplsindex': '0', 'RD': '10.20.0.193:3', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]}
2025-08-10 21:04:21,399 - DEBUG - VM ctest-VM31-74406965 : Tap interface tap1da2dd95-36 is set to Active
2025-08-10 21:04:21,399 - DEBUG - Tap interface tap1da2dd95-36 detail : {'index': '4', 'name': 'tap1da2dd95-36', 'uuid': '1da2dd95-36f8-45dc-b621-dab0ed6904f1', 'vrf_name': 'default-domain:ctest-TestPolicyAcl-36559010:ctest-VN3-41493751:ctest-VN3-41493751', '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-36559010:ctest-VN3-41493751', 'vm_uuid': '8f57a4b5-c9c1-43ed-b699-57047e38c231', 'vm_name': 'ctest-VM31-74406965', 'ip_addr': '199.16.214.67', 'mac_addr': '02:1d:a2:dd:95:36', '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-36559010:1da2dd95-36f8-45dc-b621-dab0ed6904f1', 'sg_uuid_list': ['164f5d01-fbc4-4a48-a1bc-9381ff6567c6'], 'static_route_list': None, 'vm_project_uuid': 'fcda956e-5f8a-4b8d-9f5c-49463798dc45', 'admin_state': 'Enabled', 'flow_key_idx': '41', '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-08-10 21:04:21,556 - DEBUG - Starting Layer 2 verification in Agent
2025-08-10 21:04:21,571 - DEBUG - Layer 2 path is seen for VM MAC 02:1d:a2:dd:95:36 in agent 10.0.0.254
2025-08-10 21:04:21,571 - DEBUG - Active layer 2 route in agent is present for VMI tap1da2dd95-36
2025-08-10 21:04:21,571 - DEBUG - L2 label(36) matches bw route table and interface table
2025-08-10 21:04:21,583 - DEBUG - VN default-domain:ctest-TestPolicyAcl-36559010:ctest-VN3-41493751 is found in Agent of node 10.0.0.254
2025-08-10 21:04:21,766 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAcl-36559010:ctest-VN3-41493751 is consistent in agent 10.0.0.254
2025-08-10 21:04:21,766 - DEBUG - Route for VM IP ['199.16.214.67'] is consistent in agent 10.0.0.254
2025-08-10 21:04:21,766 - DEBUG - VN default-domain:ctest-TestPolicyAcl-36559010:ctest-VN3-41493751 verification for VM ctest-VM31-74406965 in Agent 10.0.0.254 passed
2025-08-10 21:04:21,915 - DEBUG - Starting all layer 2 verification in agent 10.0.0.254
2025-08-10 21:04:21,931 - DEBUG - Route for VM MAC 02:1d:a2:dd:95:36 is consistent in agent 10.0.0.254
2025-08-10 21:04:23,154 - 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.61 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.467 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 = 0.467/3.040/5.613/2.573 ms')
2025-08-10 21:04:23,154 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-74406965 passed
2025-08-10 21:04:23,154 - INFO - VM ctest-VM31-74406965 verifications in Compute nodes passed
2025-08-10 21:04:23,412 - DEBUG - Validated VM route 199.16.214.67 in vrouter of 10.0.0.254
2025-08-10 21:04:23,415 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0
2025-08-10 21:04:23,415 - INFO - Validated routes of VM ctest-VM31-74406965 in all vrouters
2025-08-10 21:04:24,017 - DEBUG - Starting all layer2 verification in 10.0.0.48 Control Node
2025-08-10 21:04:24,185 - DEBUG - Layer2 route found for VM MAC 02:1d:a2:dd:95:36 in Control-node 10.0.0.48
2025-08-10 21:04:24,185 - DEBUG - L2 Label for VM ctest-VM31-74406965 same between Control-node 10.0.0.48 and Agent, Expected: 36, Seen: 36
2025-08-10 21:04:24,185 - DEBUG - Starting all layer2 verification in 10.0.0.49 Control Node
2025-08-10 21:04:24,354 - DEBUG - Layer2 route found for VM MAC 02:1d:a2:dd:95:36 in Control-node 10.0.0.49
2025-08-10 21:04:24,354 - DEBUG - L2 Label for VM ctest-VM31-74406965 same between Control-node 10.0.0.49 and Agent, Expected: 36, Seen: 36
2025-08-10 21:04:24,354 - INFO - Verification in Control-nodes for VM ctest-VM31-74406965 passed
2025-08-10 21:04:24,354 - DEBUG - Verifying the vm in opserver
2025-08-10 21:04:24,354 - DEBUG - Verifying in collector 10.0.0.49 ...
2025-08-10 21:04:24,354 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-machine/8f57a4b5-c9c1-43ed-b699-57047e38c231?flat
2025-08-10 21:04:24,370 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-36559010:1da2dd95-36f8-45dc-b621-dab0ed6904f1?flat
2025-08-10 21:04:24,388 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-36559010:1da2dd95-36f8-45dc-b621-dab0ed6904f1?flat
2025-08-10 21:04:24,406 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-machine/8f57a4b5-c9c1-43ed-b699-57047e38c231?flat
2025-08-10 21:04:24,419 - DEBUG - Verifying vm in vn uve
2025-08-10 21:04:24,419 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-36559010:1da2dd95-36f8-45dc-b621-dab0ed6904f1?flat
2025-08-10 21:04:24,437 - DEBUG - VM uve shows interface as {'gateway': '199.16.214.65', 'vm_name': 'ctest-VM31-74406965', 'vn_uuid': 'cd4e07f6-b451-41c3-9d5b-941f68642c30', 'vm_uuid': '8f57a4b5-c9c1-43ed-b699-57047e38c231', 'is_health_check_active': True, '__T': 1754859841461256, 'port_mirror_enabled': False, 'l2_active': True, 'active': True, 'tx_vlan': 65535, 'rx_vlan': 65535, 'fixed_ip4_list': ['199.16.214.67'], 'virtual_network': 'default-domain:ctest-TestPolicyAcl-36559010:ctest-VN3-41493751', 'admin_state': True, 'uuid': '1da2dd95-36f8-45dc-b621-dab0ed6904f1', 'label': 32, 'vhostuser_mode': 0, 'ip6_active': False, 'mac_address': '02:1d:a2:dd:95:36', 'ip4_active': True, 'ip_address': '199.16.214.67', 'ip6_address': '::'}
2025-08-10 21:04:24,437 - DEBUG - VM uve shows ip address as ['199.16.214.67', '::']
2025-08-10 21:04:24,437 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAcl-36559010:ctest-VN3-41493751
2025-08-10 21:04:24,437 - INFO - Verifying through opserver in 10.0.0.48
2025-08-10 21:04:24,437 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-36559010:ctest-VN3-41493751?flat
2025-08-10 21:04:24,456 - DEBUG - expected vm list 8f57a4b5-c9c1-43ed-b699-57047e38c231
2025-08-10 21:04:24,456 - DEBUG - Extracted vm list ['8f57a4b5-c9c1-43ed-b699-57047e38c231']
2025-08-10 21:04:24,456 - INFO - VM 8f57a4b5-c9c1-43ed-b699-57047e38c231 is present in default-domain:ctest-TestPolicyAcl-36559010:ctest-VN3-41493751
2025-08-10 21:04:24,456 - DEBUG - Verifying vm in vrouter uve
2025-08-10 21:04:24,456 - DEBUG - Getting info from collector 10.0.0.49..
2025-08-10 21:04:24,457 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-machine/8f57a4b5-c9c1-43ed-b699-57047e38c231?flat
2025-08-10 21:04:24,470 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-3790-1']
2025-08-10 21:04:24,470 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3790-1:Compute:contrail-vrouter-agent:0?flat
2025-08-10 21:04:24,506 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3790-1?flat
2025-08-10 21:04:24,536 - DEBUG - VM 8f57a4b5-c9c1-43ed-b699-57047e38c231 is present in vrouter an-jenkins-deploy-platform-ansible-os-3790-1 uve
2025-08-10 21:04:24,536 - DEBUG - Validated that VM ctest-VM31-74406965 is in Vrouter an-jenkins-deploy-platform-ansible-os-3790-1 UVE
2025-08-10 21:04:24,536 - DEBUG - Verifying vm tap interface/vn in vrouter uve
2025-08-10 21:04:24,552 - DEBUG - Expected tap interface of VM uuid 8f57a4b5-c9c1-43ed-b699-57047e38c231 is default-domain:ctest-TestPolicyAcl-36559010:1da2dd95-36f8-45dc-b621-dab0ed6904f1
2025-08-10 21:04:24,553 - DEBUG - Expected VN of VM uuid 8f57a4b5-c9c1-43ed-b699-57047e38c231 is default-domain:ctest-TestPolicyAcl-36559010:ctest-VN3-41493751
2025-08-10 21:04:24,553 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3790-1:Compute:contrail-vrouter-agent:0?flat
2025-08-10 21:04:24,590 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3790-1?flat
2025-08-10 21:04:24,622 - DEBUG - VM 8f57a4b5-c9c1-43ed-b699-57047e38c231 is present in vrouter an-jenkins-deploy-platform-ansible-os-3790-1 uve
2025-08-10 21:04:24,622 - INFO - tap interface default-domain:ctest-TestPolicyAcl-36559010:1da2dd95-36f8-45dc-b621-dab0ed6904f1 of vm 8f57a4b5-c9c1-43ed-b699-57047e38c231 is present in vrouter an-jenkins-deploy-platform-ansible-os-3790-1 uve
2025-08-10 21:04:24,622 - DEBUG - Connected network default-domain:ctest-TestPolicyAcl-36559010:ctest-VN3-41493751 of vm 8f57a4b5-c9c1-43ed-b699-57047e38c231 is present in vrouter an-jenkins-deploy-platform-ansible-os-3790-1 uve
2025-08-10 21:04:24,622 - DEBUG - Verifying the 8f57a4b5-c9c1-43ed-b699-57047e38c231 virtual network link through opserver 10.0.0.48
2025-08-10 21:04:24,622 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-machines
2025-08-10 21:04:24,633 - DEBUG - VM link and name as {'name': '8f57a4b5-c9c1-43ed-b699-57047e38c231', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-machine/8f57a4b5-c9c1-43ed-b699-57047e38c231?flat'}
2025-08-10 21:04:24,633 - DEBUG - Verifying the 8f57a4b5-c9c1-43ed-b699-57047e38c231 virtual network link through opserver 10.0.0.38
2025-08-10 21:04:24,633 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines
2025-08-10 21:04:24,644 - DEBUG - VM link and name as {'name': '8f57a4b5-c9c1-43ed-b699-57047e38c231', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-machine/8f57a4b5-c9c1-43ed-b699-57047e38c231?flat'}
2025-08-10 21:04:24,644 - DEBUG - Verifying the 8f57a4b5-c9c1-43ed-b699-57047e38c231 virtual network link through opserver 10.0.0.49
2025-08-10 21:04:24,644 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-machines
2025-08-10 21:04:24,654 - DEBUG - VM link and name as {'name': '8f57a4b5-c9c1-43ed-b699-57047e38c231', 'href': 'http://10.0.0.49:8081/analytics/uves/virtual-machine/8f57a4b5-c9c1-43ed-b699-57047e38c231?flat'}
2025-08-10 21:04:24,654 - INFO - VM ctest-VM31-74406965 validations in Opserver passed
2025-08-10 21:04:24,654 - INFO - Waiting for VM ctest-VM11-64957988 to be up..
2025-08-10 21:04:24,655 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-10 21:04:24,655 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine/4f96f7ae-2039-49ec-82ab-8ff754e1827d
2025-08-10 21:04:24,661 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine-interface/830b5787-6070-4d76-956e-8a35f0658dcf
2025-08-10 21:04:25,864 - 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=10.3 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.65 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.654/6.493/10.333/3.839 ms')
2025-08-10 21:04:25,864 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-64957988 passed
2025-08-10 21:04:25,975 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-10 21:04:25,975 - DEBUG - Waiting to SSH to VM ctest-VM11-64957988, IP 165.134.132.3, Port 22
2025-08-10 21:04:26,043 - 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-08-10 21:04:26,111 - DEBUG - VM ctest-VM11-64957988 is NOT ready for SSH connections, VM status: ACTIVE
2025-08-10 21:04:31,112 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-10 21:04:31,112 - DEBUG - Waiting to SSH to VM ctest-VM11-64957988, IP 165.134.132.3, Port 22
2025-08-10 21:04:31,168 - 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-08-10 21:04:31,239 - DEBUG - VM ctest-VM11-64957988 is NOT ready for SSH connections, VM status: ACTIVE
2025-08-10 21:04:36,239 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-10 21:04:36,239 - DEBUG - Waiting to SSH to VM ctest-VM11-64957988, IP 165.134.132.3, Port 22
2025-08-10 21:04:36,411 - DEBUG - VM ctest-VM11-64957988 is ready for SSH connections
2025-08-10 21:04:36,411 - INFO - Waiting for VM ctest-VM21-51779521 to be up..
2025-08-10 21:04:36,411 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-10 21:04:36,411 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine/74624b3f-a58d-4379-835b-3bb521a244db
2025-08-10 21:04:36,420 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine-interface/a9f92149-1835-47fc-befe-1e3b0b804059
2025-08-10 21:04:37,618 - 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.93 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.404 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.404/1.164/1.925/0.760 ms')
2025-08-10 21:04:37,618 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-51779521 passed
2025-08-10 21:04:37,727 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-10 21:04:37,727 - DEBUG - Waiting to SSH to VM ctest-VM21-51779521, IP 76.67.231.67, Port 22
2025-08-10 21:04:37,878 - DEBUG - VM ctest-VM21-51779521 is ready for SSH connections
2025-08-10 21:04:37,878 - INFO - Waiting for VM ctest-VM31-74406965 to be up..
2025-08-10 21:04:37,878 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-10 21:04:37,878 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine/8f57a4b5-c9c1-43ed-b699-57047e38c231
2025-08-10 21:04:37,886 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine-interface/1da2dd95-36f8-45dc-b621-dab0ed6904f1
2025-08-10 21:04:39,102 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=3.82 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=1.33 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 1.334/2.575/3.816/1.241 ms')
2025-08-10 21:04:39,102 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-74406965 passed
2025-08-10 21:04:39,211 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-10 21:04:39,211 - DEBUG - Waiting to SSH to VM ctest-VM31-74406965, IP 199.16.214.67, Port 22
2025-08-10 21:04:39,383 - DEBUG - VM ctest-VM31-74406965 is ready for SSH connections
2025-08-10 21:04:39,383 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 76.67.231.67, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.254, gateway password: c0ntrail123
2025-08-10 21:04:39,383 - DEBUG - ping -s 56 -c 3 -W 1 76.67.231.67
2025-08-10 21:05:26,780 - DEBUG - PING 76.67.231.67 (76.67.231.67) 56(84) bytes of data.
64 bytes from 76.67.231.67: icmp_req=1 ttl=63 time=7.41 ms
64 bytes from 76.67.231.67: icmp_req=2 ttl=63 time=0.827 ms
64 bytes from 76.67.231.67: icmp_req=3 ttl=63 time=0.720 ms
--- 76.67.231.67 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 0.720/2.986/7.412/3.130 ms
2025-08-10 21:05:26,780 - INFO - Ping to IP 76.67.231.67 from VM ctest-VM11-64957988 passed
2025-08-10 21:05:26,780 - INFO - Test with src as VN and dst as policy PASSED
2025-08-10 21:05:26,780 - INFO - Deleting VM ctest-VM31-74406965
2025-08-10 21:05:26,838 - DEBUG - Verifying in api server 10.0.0.49
2025-08-10 21:05:26,838 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine/8f57a4b5-c9c1-43ed-b699-57047e38c231
2025-08-10 21:05:26,847 - DEBUG - VM ID 8f57a4b5-c9c1-43ed-b699-57047e38c231 of VM ctest-VM31-74406965 is still found in API Server
2025-08-10 21:05:28,847 - DEBUG - Verifying in api server 10.0.0.49
2025-08-10 21:05:28,847 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine/8f57a4b5-c9c1-43ed-b699-57047e38c231
2025-08-10 21:05:28,854 - DEBUG - Response Code: 404
2025-08-10 21:05:28,854 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine/8f57a4b5-c9c1-43ed-b699-57047e38c231
2025-08-10 21:05:28,860 - DEBUG - Response Code: 404
2025-08-10 21:05:28,860 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine/8f57a4b5-c9c1-43ed-b699-57047e38c231
2025-08-10 21:05:28,865 - DEBUG - Response Code: 404
2025-08-10 21:05:28,865 - INFO - VM ctest-VM31-74406965 is fully removed in API-Server
2025-08-10 21:05:29,015 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2025-08-10 21:05:29,053 - DEBUG - Validated that vrouter 10.0.0.254 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-36559010:ctest-VN3-41493751
2025-08-10 21:05:29,063 - DEBUG - Validated that vrouter 10.0.0.50 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-36559010:ctest-VN3-41493751
2025-08-10 21:05:29,064 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAcl-36559010:ctest-VN3-41493751
2025-08-10 21:05:29,064 - INFO - VM ctest-VM31-74406965 is removed in Compute, and routes are removed in all compute nodes
2025-08-10 21:05:29,332 - INFO - Routes for VM ctest-VM31-74406965 is removed in all control-nodes
2025-08-10 21:05:29,333 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3790-1:Compute:contrail-vrouter-agent:0?flat
2025-08-10 21:05:29,391 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3790-1?flat
2025-08-10 21:05:29,446 - DEBUG - VM 8f57a4b5-c9c1-43ed-b699-57047e38c231 is still present in vrouter an-jenkins-deploy-platform-ansible-os-3790-1 uve
2025-08-10 21:05:29,446 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-10 21:05:29,446 - DEBUG - interface for vm 8f57a4b5-c9c1-43ed-b699-57047e38c231 deleted from vrouter uve ...
2025-08-10 21:05:29,446 - DEBUG - Deleted interface not in error interface list ...
2025-08-10 21:05:32,446 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3790-1:Compute:contrail-vrouter-agent:0?flat
2025-08-10 21:05:32,485 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3790-1?flat
2025-08-10 21:05:32,520 - DEBUG - VM 8f57a4b5-c9c1-43ed-b699-57047e38c231 is not present in vrouter an-jenkins-deploy-platform-ansible-os-3790-1 uve
2025-08-10 21:05:32,520 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-10 21:05:32,520 - DEBUG - interface for vm 8f57a4b5-c9c1-43ed-b699-57047e38c231 deleted from vrouter uve ...
2025-08-10 21:05:32,520 - DEBUG - Deleted interface not in error interface list ...
2025-08-10 21:05:32,520 - DEBUG - Validated that VM 8f57a4b5-c9c1-43ed-b699-57047e38c231 is removed in Vrouter UVE
2025-08-10 21:05:32,520 - DEBUG - Verifying through opserver in 10.0.0.48
2025-08-10 21:05:32,520 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-36559010:ctest-VN3-41493751?flat
2025-08-10 21:05:32,538 - DEBUG - 8f57a4b5-c9c1-43ed-b699-57047e38c231 BM not in default-domain:ctest-TestPolicyAcl-36559010:ctest-VN3-41493751 uve
2025-08-10 21:05:32,538 - DEBUG - Validated that VM 8f57a4b5-c9c1-43ed-b699-57047e38c231 is not present in the VN UVE
2025-08-10 21:05:32,538 - DEBUG - Verifying the 8f57a4b5-c9c1-43ed-b699-57047e38c231 virtual network link through opserver 10.0.0.48
2025-08-10 21:05:32,538 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-machines
2025-08-10 21:05:32,548 - INFO - 8f57a4b5-c9c1-43ed-b699-57047e38c231 vm uve deleted from opserver
2025-08-10 21:05:32,548 - DEBUG - Verifying the 8f57a4b5-c9c1-43ed-b699-57047e38c231 virtual network link through opserver 10.0.0.38
2025-08-10 21:05:32,548 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines
2025-08-10 21:05:32,558 - INFO - 8f57a4b5-c9c1-43ed-b699-57047e38c231 vm uve deleted from opserver
2025-08-10 21:05:32,558 - DEBUG - Verifying the 8f57a4b5-c9c1-43ed-b699-57047e38c231 virtual network link through opserver 10.0.0.49
2025-08-10 21:05:32,558 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-machines
2025-08-10 21:05:32,571 - INFO - 8f57a4b5-c9c1-43ed-b699-57047e38c231 vm uve deleted from opserver
2025-08-10 21:05:32,571 - INFO - Validated that VM 8f57a4b5-c9c1-43ed-b699-57047e38c231 is removed from Opserver
2025-08-10 21:05:32,571 - INFO - Deleting VM ctest-VM21-51779521
2025-08-10 21:05:32,635 - DEBUG - Verifying in api server 10.0.0.49
2025-08-10 21:05:32,635 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine/74624b3f-a58d-4379-835b-3bb521a244db
2025-08-10 21:05:32,643 - DEBUG - VM ID 74624b3f-a58d-4379-835b-3bb521a244db of VM ctest-VM21-51779521 is still found in API Server
2025-08-10 21:05:34,643 - DEBUG - Verifying in api server 10.0.0.49
2025-08-10 21:05:34,644 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine/74624b3f-a58d-4379-835b-3bb521a244db
2025-08-10 21:05:34,650 - DEBUG - Response Code: 404
2025-08-10 21:05:34,650 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine/74624b3f-a58d-4379-835b-3bb521a244db
2025-08-10 21:05:34,655 - DEBUG - Response Code: 404
2025-08-10 21:05:34,656 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine/74624b3f-a58d-4379-835b-3bb521a244db
2025-08-10 21:05:34,660 - DEBUG - Response Code: 404
2025-08-10 21:05:34,661 - INFO - VM ctest-VM21-51779521 is fully removed in API-Server
2025-08-10 21:05:34,819 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2025-08-10 21:05:34,856 - DEBUG - Validated that vrouter 10.0.0.254 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-36559010:ctest-VN2-41735252
2025-08-10 21:05:34,865 - DEBUG - Validated that vrouter 10.0.0.50 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-36559010:ctest-VN2-41735252
2025-08-10 21:05:34,865 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAcl-36559010:ctest-VN2-41735252
2025-08-10 21:05:34,865 - INFO - VM ctest-VM21-51779521 is removed in Compute, and routes are removed in all compute nodes
2025-08-10 21:05:35,103 - INFO - Routes for VM ctest-VM21-51779521 is removed in all control-nodes
2025-08-10 21:05:35,103 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3790-2:Compute:contrail-vrouter-agent:0?flat
2025-08-10 21:05:35,145 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3790-2?flat
2025-08-10 21:05:35,178 - DEBUG - VM 74624b3f-a58d-4379-835b-3bb521a244db is still present in vrouter an-jenkins-deploy-platform-ansible-os-3790-2 uve
2025-08-10 21:05:35,178 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-10 21:05:35,178 - DEBUG - interface for vm 74624b3f-a58d-4379-835b-3bb521a244db deleted from vrouter uve ...
2025-08-10 21:05:35,178 - DEBUG - Deleted interface not in error interface list ...
2025-08-10 21:05:38,178 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3790-2:Compute:contrail-vrouter-agent:0?flat
2025-08-10 21:05:38,218 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3790-2?flat
2025-08-10 21:05:38,252 - DEBUG - VM 74624b3f-a58d-4379-835b-3bb521a244db is still present in vrouter an-jenkins-deploy-platform-ansible-os-3790-2 uve
2025-08-10 21:05:38,252 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-10 21:05:38,252 - DEBUG - interface for vm 74624b3f-a58d-4379-835b-3bb521a244db deleted from vrouter uve ...
2025-08-10 21:05:38,252 - DEBUG - Deleted interface not in error interface list ...
2025-08-10 21:05:41,253 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3790-2:Compute:contrail-vrouter-agent:0?flat
2025-08-10 21:05:41,298 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3790-2?flat
2025-08-10 21:05:41,335 - DEBUG - VM 74624b3f-a58d-4379-835b-3bb521a244db is still present in vrouter an-jenkins-deploy-platform-ansible-os-3790-2 uve
2025-08-10 21:05:41,335 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-10 21:05:41,335 - DEBUG - interface for vm 74624b3f-a58d-4379-835b-3bb521a244db deleted from vrouter uve ...
2025-08-10 21:05:41,335 - DEBUG - Deleted interface not in error interface list ...
2025-08-10 21:05:44,336 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3790-2:Compute:contrail-vrouter-agent:0?flat
2025-08-10 21:05:44,379 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3790-2?flat
2025-08-10 21:05:44,413 - DEBUG - VM 74624b3f-a58d-4379-835b-3bb521a244db is still present in vrouter an-jenkins-deploy-platform-ansible-os-3790-2 uve
2025-08-10 21:05:44,413 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-10 21:05:44,413 - DEBUG - interface for vm 74624b3f-a58d-4379-835b-3bb521a244db deleted from vrouter uve ...
2025-08-10 21:05:44,413 - DEBUG - Deleted interface not in error interface list ...
2025-08-10 21:05:47,413 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3790-2:Compute:contrail-vrouter-agent:0?flat
2025-08-10 21:05:47,457 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3790-2?flat
2025-08-10 21:05:47,491 - DEBUG - VM 74624b3f-a58d-4379-835b-3bb521a244db is still present in vrouter an-jenkins-deploy-platform-ansible-os-3790-2 uve
2025-08-10 21:05:47,491 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-10 21:05:47,491 - DEBUG - interface for vm 74624b3f-a58d-4379-835b-3bb521a244db deleted from vrouter uve ...
2025-08-10 21:05:47,491 - DEBUG - Deleted interface not in error interface list ...
2025-08-10 21:05:50,492 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3790-2:Compute:contrail-vrouter-agent:0?flat
2025-08-10 21:05:50,532 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3790-2?flat
2025-08-10 21:05:50,566 - DEBUG - VM 74624b3f-a58d-4379-835b-3bb521a244db is still present in vrouter an-jenkins-deploy-platform-ansible-os-3790-2 uve
2025-08-10 21:05:50,566 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-10 21:05:50,566 - DEBUG - interface for vm 74624b3f-a58d-4379-835b-3bb521a244db deleted from vrouter uve ...
2025-08-10 21:05:50,566 - DEBUG - Deleted interface not in error interface list ...
2025-08-10 21:05:53,567 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3790-2:Compute:contrail-vrouter-agent:0?flat
2025-08-10 21:05:53,611 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3790-2?flat
2025-08-10 21:05:53,646 - DEBUG - VM 74624b3f-a58d-4379-835b-3bb521a244db is still present in vrouter an-jenkins-deploy-platform-ansible-os-3790-2 uve
2025-08-10 21:05:53,646 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-10 21:05:53,646 - DEBUG - interface for vm 74624b3f-a58d-4379-835b-3bb521a244db deleted from vrouter uve ...
2025-08-10 21:05:53,646 - DEBUG - Deleted interface not in error interface list ...
2025-08-10 21:05:56,647 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3790-2:Compute:contrail-vrouter-agent:0?flat
2025-08-10 21:05:56,688 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3790-2?flat
2025-08-10 21:05:56,723 - DEBUG - VM 74624b3f-a58d-4379-835b-3bb521a244db is still present in vrouter an-jenkins-deploy-platform-ansible-os-3790-2 uve
2025-08-10 21:05:56,723 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-10 21:05:56,723 - DEBUG - interface for vm 74624b3f-a58d-4379-835b-3bb521a244db deleted from vrouter uve ...
2025-08-10 21:05:56,723 - DEBUG - Deleted interface not in error interface list ...
2025-08-10 21:05:59,724 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3790-2:Compute:contrail-vrouter-agent:0?flat
2025-08-10 21:05:59,774 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3790-2?flat
2025-08-10 21:05:59,821 - DEBUG - VM 74624b3f-a58d-4379-835b-3bb521a244db is still present in vrouter an-jenkins-deploy-platform-ansible-os-3790-2 uve
2025-08-10 21:05:59,822 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-10 21:05:59,822 - DEBUG - interface for vm 74624b3f-a58d-4379-835b-3bb521a244db deleted from vrouter uve ...
2025-08-10 21:05:59,822 - DEBUG - Deleted interface not in error interface list ...
2025-08-10 21:06:02,822 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3790-2:Compute:contrail-vrouter-agent:0?flat
2025-08-10 21:06:02,862 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3790-2?flat
2025-08-10 21:06:02,895 - DEBUG - VM 74624b3f-a58d-4379-835b-3bb521a244db is not present in vrouter an-jenkins-deploy-platform-ansible-os-3790-2 uve
2025-08-10 21:06:02,895 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-10 21:06:02,895 - DEBUG - interface for vm 74624b3f-a58d-4379-835b-3bb521a244db deleted from vrouter uve ...
2025-08-10 21:06:02,895 - DEBUG - Deleted interface not in error interface list ...
2025-08-10 21:06:02,895 - DEBUG - Validated that VM 74624b3f-a58d-4379-835b-3bb521a244db is removed in Vrouter UVE
2025-08-10 21:06:02,895 - DEBUG - Verifying through opserver in 10.0.0.48
2025-08-10 21:06:02,895 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-36559010:ctest-VN2-41735252?flat
2025-08-10 21:06:02,912 - DEBUG - 74624b3f-a58d-4379-835b-3bb521a244db BM not in default-domain:ctest-TestPolicyAcl-36559010:ctest-VN2-41735252 uve
2025-08-10 21:06:02,912 - DEBUG - Validated that VM 74624b3f-a58d-4379-835b-3bb521a244db is not present in the VN UVE
2025-08-10 21:06:02,912 - DEBUG - Verifying the 74624b3f-a58d-4379-835b-3bb521a244db virtual network link through opserver 10.0.0.48
2025-08-10 21:06:02,912 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-machines
2025-08-10 21:06:02,924 - INFO - 74624b3f-a58d-4379-835b-3bb521a244db vm uve deleted from opserver
2025-08-10 21:06:02,924 - DEBUG - Verifying the 74624b3f-a58d-4379-835b-3bb521a244db virtual network link through opserver 10.0.0.38
2025-08-10 21:06:02,924 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines
2025-08-10 21:06:02,934 - INFO - 74624b3f-a58d-4379-835b-3bb521a244db vm uve deleted from opserver
2025-08-10 21:06:02,934 - DEBUG - Verifying the 74624b3f-a58d-4379-835b-3bb521a244db virtual network link through opserver 10.0.0.49
2025-08-10 21:06:02,934 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-machines
2025-08-10 21:06:02,945 - INFO - 74624b3f-a58d-4379-835b-3bb521a244db vm uve deleted from opserver
2025-08-10 21:06:02,945 - INFO - Validated that VM 74624b3f-a58d-4379-835b-3bb521a244db is removed from Opserver
2025-08-10 21:06:02,945 - INFO - Deleting VM ctest-VM11-64957988
2025-08-10 21:06:03,003 - DEBUG - Verifying in api server 10.0.0.49
2025-08-10 21:06:03,003 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine/4f96f7ae-2039-49ec-82ab-8ff754e1827d
2025-08-10 21:06:03,010 - DEBUG - VM ID 4f96f7ae-2039-49ec-82ab-8ff754e1827d of VM ctest-VM11-64957988 is still found in API Server
2025-08-10 21:06:05,011 - DEBUG - Verifying in api server 10.0.0.49
2025-08-10 21:06:05,012 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine/4f96f7ae-2039-49ec-82ab-8ff754e1827d
2025-08-10 21:06:05,018 - DEBUG - Response Code: 404
2025-08-10 21:06:05,019 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine/4f96f7ae-2039-49ec-82ab-8ff754e1827d
2025-08-10 21:06:05,024 - DEBUG - Response Code: 404
2025-08-10 21:06:05,024 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine/4f96f7ae-2039-49ec-82ab-8ff754e1827d
2025-08-10 21:06:05,029 - DEBUG - Response Code: 404
2025-08-10 21:06:05,029 - INFO - VM ctest-VM11-64957988 is fully removed in API-Server
2025-08-10 21:06:05,179 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2025-08-10 21:06:05,215 - DEBUG - Validated that vrouter 10.0.0.254 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-36559010:ctest-VN1-18349554
2025-08-10 21:06:05,225 - DEBUG - Validated that vrouter 10.0.0.50 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-36559010:ctest-VN1-18349554
2025-08-10 21:06:05,225 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAcl-36559010:ctest-VN1-18349554
2025-08-10 21:06:05,225 - INFO - VM ctest-VM11-64957988 is removed in Compute, and routes are removed in all compute nodes
2025-08-10 21:06:05,453 - INFO - Routes for VM ctest-VM11-64957988 is removed in all control-nodes
2025-08-10 21:06:05,453 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3790-1:Compute:contrail-vrouter-agent:0?flat
2025-08-10 21:06:05,490 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3790-1?flat
2025-08-10 21:06:05,525 - DEBUG - VM 4f96f7ae-2039-49ec-82ab-8ff754e1827d is still present in vrouter an-jenkins-deploy-platform-ansible-os-3790-1 uve
2025-08-10 21:06:05,525 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-10 21:06:05,525 - DEBUG - interface for vm 4f96f7ae-2039-49ec-82ab-8ff754e1827d deleted from vrouter uve ...
2025-08-10 21:06:05,525 - DEBUG - Deleted interface not in error interface list ...
2025-08-10 21:06:08,526 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3790-1:Compute:contrail-vrouter-agent:0?flat
2025-08-10 21:06:08,567 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3790-1?flat
2025-08-10 21:06:08,600 - DEBUG - VM 4f96f7ae-2039-49ec-82ab-8ff754e1827d is still present in vrouter an-jenkins-deploy-platform-ansible-os-3790-1 uve
2025-08-10 21:06:08,600 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-10 21:06:08,600 - DEBUG - interface for vm 4f96f7ae-2039-49ec-82ab-8ff754e1827d deleted from vrouter uve ...
2025-08-10 21:06:08,600 - DEBUG - Deleted interface not in error interface list ...
2025-08-10 21:06:11,600 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3790-1:Compute:contrail-vrouter-agent:0?flat
2025-08-10 21:06:11,646 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3790-1?flat
2025-08-10 21:06:11,681 - DEBUG - VM 4f96f7ae-2039-49ec-82ab-8ff754e1827d is still present in vrouter an-jenkins-deploy-platform-ansible-os-3790-1 uve
2025-08-10 21:06:11,681 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-10 21:06:11,681 - DEBUG - interface for vm 4f96f7ae-2039-49ec-82ab-8ff754e1827d deleted from vrouter uve ...
2025-08-10 21:06:11,682 - DEBUG - Deleted interface not in error interface list ...
2025-08-10 21:06:14,682 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3790-1:Compute:contrail-vrouter-agent:0?flat
2025-08-10 21:06:14,721 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3790-1?flat
2025-08-10 21:06:14,754 - DEBUG - VM 4f96f7ae-2039-49ec-82ab-8ff754e1827d is still present in vrouter an-jenkins-deploy-platform-ansible-os-3790-1 uve
2025-08-10 21:06:14,754 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-10 21:06:14,754 - DEBUG - interface for vm 4f96f7ae-2039-49ec-82ab-8ff754e1827d deleted from vrouter uve ...
2025-08-10 21:06:14,754 - DEBUG - Deleted interface not in error interface list ...
2025-08-10 21:06:17,754 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3790-1:Compute:contrail-vrouter-agent:0?flat
2025-08-10 21:06:17,803 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3790-1?flat
2025-08-10 21:06:17,837 - DEBUG - VM 4f96f7ae-2039-49ec-82ab-8ff754e1827d is still present in vrouter an-jenkins-deploy-platform-ansible-os-3790-1 uve
2025-08-10 21:06:17,837 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-10 21:06:17,837 - DEBUG - interface for vm 4f96f7ae-2039-49ec-82ab-8ff754e1827d deleted from vrouter uve ...
2025-08-10 21:06:17,837 - DEBUG - Deleted interface not in error interface list ...
2025-08-10 21:06:20,838 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3790-1:Compute:contrail-vrouter-agent:0?flat
2025-08-10 21:06:20,877 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3790-1?flat
2025-08-10 21:06:20,911 - DEBUG - VM 4f96f7ae-2039-49ec-82ab-8ff754e1827d is still present in vrouter an-jenkins-deploy-platform-ansible-os-3790-1 uve
2025-08-10 21:06:20,911 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-10 21:06:20,911 - DEBUG - interface for vm 4f96f7ae-2039-49ec-82ab-8ff754e1827d deleted from vrouter uve ...
2025-08-10 21:06:20,911 - DEBUG - Deleted interface not in error interface list ...
2025-08-10 21:06:23,913 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3790-1:Compute:contrail-vrouter-agent:0?flat
2025-08-10 21:06:23,952 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3790-1?flat
2025-08-10 21:06:23,982 - DEBUG - VM 4f96f7ae-2039-49ec-82ab-8ff754e1827d is still present in vrouter an-jenkins-deploy-platform-ansible-os-3790-1 uve
2025-08-10 21:06:23,982 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-10 21:06:23,983 - DEBUG - interface for vm 4f96f7ae-2039-49ec-82ab-8ff754e1827d deleted from vrouter uve ...
2025-08-10 21:06:23,983 - DEBUG - Deleted interface not in error interface list ...
2025-08-10 21:06:26,984 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3790-1:Compute:contrail-vrouter-agent:0?flat
2025-08-10 21:06:27,021 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3790-1?flat
2025-08-10 21:06:27,052 - DEBUG - VM 4f96f7ae-2039-49ec-82ab-8ff754e1827d is still present in vrouter an-jenkins-deploy-platform-ansible-os-3790-1 uve
2025-08-10 21:06:27,052 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-10 21:06:27,052 - DEBUG - interface for vm 4f96f7ae-2039-49ec-82ab-8ff754e1827d deleted from vrouter uve ...
2025-08-10 21:06:27,052 - DEBUG - Deleted interface not in error interface list ...
2025-08-10 21:06:30,053 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3790-1:Compute:contrail-vrouter-agent:0?flat
2025-08-10 21:06:30,092 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3790-1?flat
2025-08-10 21:06:30,132 - DEBUG - VM 4f96f7ae-2039-49ec-82ab-8ff754e1827d is still present in vrouter an-jenkins-deploy-platform-ansible-os-3790-1 uve
2025-08-10 21:06:30,132 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-10 21:06:30,132 - DEBUG - interface for vm 4f96f7ae-2039-49ec-82ab-8ff754e1827d deleted from vrouter uve ...
2025-08-10 21:06:30,132 - DEBUG - Deleted interface not in error interface list ...
2025-08-10 21:06:33,133 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-3790-1:Compute:contrail-vrouter-agent:0?flat
2025-08-10 21:06:33,171 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-3790-1?flat
2025-08-10 21:06:33,202 - DEBUG - VM 4f96f7ae-2039-49ec-82ab-8ff754e1827d is not present in vrouter an-jenkins-deploy-platform-ansible-os-3790-1 uve
2025-08-10 21:06:33,202 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-08-10 21:06:33,202 - DEBUG - interface for vm 4f96f7ae-2039-49ec-82ab-8ff754e1827d deleted from vrouter uve ...
2025-08-10 21:06:33,202 - DEBUG - Deleted interface not in error interface list ...
2025-08-10 21:06:33,202 - DEBUG - Validated that VM 4f96f7ae-2039-49ec-82ab-8ff754e1827d is removed in Vrouter UVE
2025-08-10 21:06:33,203 - DEBUG - Verifying through opserver in 10.0.0.48
2025-08-10 21:06:33,203 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-36559010:ctest-VN1-18349554?flat
2025-08-10 21:06:33,220 - DEBUG - 4f96f7ae-2039-49ec-82ab-8ff754e1827d BM not in default-domain:ctest-TestPolicyAcl-36559010:ctest-VN1-18349554 uve
2025-08-10 21:06:33,220 - DEBUG - Validated that VM 4f96f7ae-2039-49ec-82ab-8ff754e1827d is not present in the VN UVE
2025-08-10 21:06:33,220 - DEBUG - Verifying the 4f96f7ae-2039-49ec-82ab-8ff754e1827d virtual network link through opserver 10.0.0.48
2025-08-10 21:06:33,220 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-machines
2025-08-10 21:06:33,230 - DEBUG - No links retuned for 4f96f7ae-2039-49ec-82ab-8ff754e1827d
2025-08-10 21:06:33,230 - INFO - 4f96f7ae-2039-49ec-82ab-8ff754e1827d vm uve deleted from opserver
2025-08-10 21:06:33,230 - DEBUG - Verifying the 4f96f7ae-2039-49ec-82ab-8ff754e1827d virtual network link through opserver 10.0.0.38
2025-08-10 21:06:33,230 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines
2025-08-10 21:06:33,240 - DEBUG - No links retuned for 4f96f7ae-2039-49ec-82ab-8ff754e1827d
2025-08-10 21:06:33,241 - INFO - 4f96f7ae-2039-49ec-82ab-8ff754e1827d vm uve deleted from opserver
2025-08-10 21:06:33,241 - DEBUG - Verifying the 4f96f7ae-2039-49ec-82ab-8ff754e1827d virtual network link through opserver 10.0.0.49
2025-08-10 21:06:33,241 - DEBUG - Requesting: http://10.0.0.49:8081/analytics/uves/virtual-machines
2025-08-10 21:06:33,251 - DEBUG - No links retuned for 4f96f7ae-2039-49ec-82ab-8ff754e1827d
2025-08-10 21:06:33,251 - INFO - 4f96f7ae-2039-49ec-82ab-8ff754e1827d vm uve deleted from opserver
2025-08-10 21:06:33,251 - INFO - Validated that VM 4f96f7ae-2039-49ec-82ab-8ff754e1827d is removed from Opserver
2025-08-10 21:06:33,252 - DEBUG - Detaching the Policy for VN :ctest-VN2-41735252
2025-08-10 21:06:33,252 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-10 21:06:33,257 - DEBUG - Requesting: http://10.0.0.48:8082/domain/f4633f28-8e52-4cb4-8182-938022088c32
2025-08-10 21:06:33,267 - DEBUG - Requesting: http://10.0.0.48:8082/project/fcda956e-5f8a-4b8d-9f5c-49463798dc45
2025-08-10 21:06:33,357 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/c8859abb-ff5d-4143-9d66-2566b1636c74
2025-08-10 21:06:33,373 - DEBUG - Requesting: http://10.0.0.48:8082/network-policy/244a7ff6-cd14-4b51-b982-35270aae6f22
2025-08-10 21:06:33,509 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAcl-36559010', 'ctest-policy21-08134165']] from ctest-VN2-41735252
2025-08-10 21:06:33,509 - DEBUG - Detaching the Policy for VN :ctest-VN1-18349554
2025-08-10 21:06:33,509 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-10 21:06:33,516 - DEBUG - Requesting: http://10.0.0.48:8082/domain/f4633f28-8e52-4cb4-8182-938022088c32
2025-08-10 21:06:33,570 - DEBUG - Requesting: http://10.0.0.48:8082/project/fcda956e-5f8a-4b8d-9f5c-49463798dc45
2025-08-10 21:06:33,648 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/30631910-a5d0-4094-b74d-329824350fa3
2025-08-10 21:06:33,820 - INFO - Detached Policy from ctest-VN1-18349554
2025-08-10 21:06:33,852 - INFO - Deleted policy ctest-policy13-29374450
2025-08-10 21:06:33,888 - INFO - Deleted policy ctest-policy21-08134165
2025-08-10 21:06:33,919 - INFO - Deleted policy ctest-policy12-34371746
2025-08-10 21:06:33,919 - INFO - Deleting VN ctest-VN3-41493751
2025-08-10 21:06:34,064 - DEBUG - Response for deleting network ()
2025-08-10 21:06:34,064 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/e279d9b5-8da4-42d3-b99f-54f46c20767c
2025-08-10 21:06:34,069 - DEBUG - Response Code: 404
2025-08-10 21:06:34,070 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-10 21:06:34,075 - DEBUG - Requesting: http://10.0.0.48:8082/domain/f4633f28-8e52-4cb4-8182-938022088c32
2025-08-10 21:06:34,087 - DEBUG - Requesting: http://10.0.0.48:8082/project/fcda956e-5f8a-4b8d-9f5c-49463798dc45
2025-08-10 21:06:34,173 - INFO - Validated that VN ctest-VN3-41493751 is not found in API Server
2025-08-10 21:06:34,185 - DEBUG - VN ctest-VN3-41493751 is not present in Agent 10.0.0.254
2025-08-10 21:06:34,196 - DEBUG - VN ctest-VN3-41493751 is not present in Agent 10.0.0.50
2025-08-10 21:06:34,196 - INFO - Validated that VN ctest-VN3-41493751 is not in any agent
2025-08-10 21:06:34,240 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN3-41493751 info
2025-08-10 21:06:34,241 - INFO - Deleting VN ctest-VN2-41735252
2025-08-10 21:06:34,402 - DEBUG - Response for deleting network ()
2025-08-10 21:06:34,402 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/9e50c831-9670-4ea2-b7ae-78dcb943d7c5
2025-08-10 21:06:34,415 - DEBUG - Response Code: 404
2025-08-10 21:06:34,415 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-10 21:06:34,423 - DEBUG - Requesting: http://10.0.0.48:8082/domain/f4633f28-8e52-4cb4-8182-938022088c32
2025-08-10 21:06:34,477 - DEBUG - Requesting: http://10.0.0.48:8082/project/fcda956e-5f8a-4b8d-9f5c-49463798dc45
2025-08-10 21:06:34,558 - INFO - Validated that VN ctest-VN2-41735252 is not found in API Server
2025-08-10 21:06:34,568 - DEBUG - VN ctest-VN2-41735252 is not present in Agent 10.0.0.254
2025-08-10 21:06:34,578 - DEBUG - VN ctest-VN2-41735252 is not present in Agent 10.0.0.50
2025-08-10 21:06:34,578 - INFO - Validated that VN ctest-VN2-41735252 is not in any agent
2025-08-10 21:06:34,609 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN2-41735252 info
2025-08-10 21:06:34,609 - INFO - Deleting VN ctest-VN1-18349554
2025-08-10 21:06:34,746 - DEBUG - Response for deleting network ()
2025-08-10 21:06:34,746 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/f1d43254-b2c6-4b3e-a2bf-c12ba8950b33
2025-08-10 21:06:34,752 - DEBUG - Response Code: 404
2025-08-10 21:06:34,752 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-10 21:06:34,762 - DEBUG - Requesting: http://10.0.0.48:8082/domain/f4633f28-8e52-4cb4-8182-938022088c32
2025-08-10 21:06:34,777 - DEBUG - Requesting: http://10.0.0.48:8082/project/fcda956e-5f8a-4b8d-9f5c-49463798dc45
2025-08-10 21:06:34,861 - INFO - Validated that VN ctest-VN1-18349554 is not found in API Server
2025-08-10 21:06:34,872 - DEBUG - VN ctest-VN1-18349554 is not present in Agent 10.0.0.254
2025-08-10 21:06:34,884 - DEBUG - VN ctest-VN1-18349554 is not present in Agent 10.0.0.50
2025-08-10 21:06:34,884 - INFO - Validated that VN ctest-VN1-18349554 is not in any agent
2025-08-10 21:06:34,923 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN1-18349554 info
2025-08-10 21:06:35,008 - INFO - IPAM: ipam3 is not found in API Server
2025-08-10 21:06:35,028 - INFO - IPAM:ipam3 is not found in control node
2025-08-10 21:06:35,176 - INFO - IPAM: ipam2 is not found in API Server
2025-08-10 21:06:35,197 - INFO - IPAM:ipam2 is not found in control node
2025-08-10 21:06:35,328 - INFO - IPAM: ipam1 is not found in API Server
2025-08-10 21:06:35,348 - INFO - IPAM:ipam1 is not found in control node
2025-08-10 21:06:36,586 - DEBUG - No XMPP flaps were noticed during the test
2025-08-10 21:06:36,586 - INFO - END TEST : test_policy_inheritance_src_vn_dst_pol : PASSED[0:03:14]
2025-08-10 21:06:36,586 - INFO - --------------------------------------------------------------------------------
2025-08-10 21:06:37,311 - INFO - Deleted project: ctest-TestPolicyAcl-36559010, ID : fcda956e-5f8a-4b8d-9f5c-49463798dc45