is in ACTIVE state now
2025-09-08 04:09:50,452 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-09-08 04:09:50,452 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/3d7c313f-271e-48ea-8a9f-08b36a44e151
2025-09-08 04:09:50,461 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/cafd761b-b081-4c6c-81bf-e9473813faf3
2025-09-08 04:09:50,472 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/3d7c313f-271e-48ea-8a9f-08b36a44e151
2025-09-08 04:09:50,481 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/cafd761b-b081-4c6c-81bf-e9473813faf3
2025-09-08 04:09:50,491 - DEBUG - Requesting: http://10.0.0.38:8082/instance-ip/2e462ab2-4070-4372-a513-f78d2cbbdbc3
2025-09-08 04:09:50,499 - DEBUG - Verifying in api server 10.0.0.38
2025-09-08 04:09:50,499 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/3d7c313f-271e-48ea-8a9f-08b36a44e151
2025-09-08 04:09:50,507 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/cafd761b-b081-4c6c-81bf-e9473813faf3
2025-09-08 04:09:50,518 - DEBUG - Requesting: http://10.0.0.38:8082/instance-ip/2e462ab2-4070-4372-a513-f78d2cbbdbc3
2025-09-08 04:09:50,527 - INFO - VM EVPN_VN1_VM2 verfication in all API Servers passed
2025-09-08 04:09:50,564 - DEBUG - VM EVPN_VN1_VM2 Tap interface: {'index': '3', 'name': 'tapcafd761b-b0', 'uuid': 'cafd761b-b081-4c6c-81bf-e9473813faf3', 'vrf_name': 'default-domain:ctest-TestEvpnCasesRestart-37762297:EVPN-VN1:EVPN-VN1', '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': '22', 'l2_label': '26', 'vxlan_id': '14', 'vn_name': 'default-domain:ctest-TestEvpnCasesRestart-37762297:EVPN-VN1', 'vm_uuid': '3d7c313f-271e-48ea-8a9f-08b36a44e151', 'vm_name': 'EVPN_VN1_VM2', 'ip_addr': '11.1.1.4', 'mac_addr': '02:ca:fd:76:1b:b0', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.3', 'service_vlan_list': None, 'os_ifindex': '114', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestEvpnCasesRestart-37762297:cafd761b-b081-4c6c-81bf-e9473813faf3', 'sg_uuid_list': ['a4f2890d-a0fe-45cf-9afb-55c55a618784'], 'static_route_list': None, 'vm_project_uuid': '9b46b284-c926-4d8b-84a2-d55a42b9f16e', 'admin_state': 'Enabled', 'flow_key_idx': '23', '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-09-08 04:09:50,564 - DEBUG - Agent 10.0.0.23 vrf name: default-domain:ctest-TestEvpnCasesRestart-37762297:EVPN-VN1:EVPN-VN1
2025-09-08 04:09:50,574 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestEvpnCasesRestart-37762297:EVPN-VN1:EVPN-VN1', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestEvpnCasesRestart-37762297:EVPN-VN1', 'table_label': '-1', 'vxlan_id': '14', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.192:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]}
2025-09-08 04:09:50,668 - DEBUG - VM EVPN_VN1_VM2 : Tap interface tapcafd761b-b0 is set to Active
2025-09-08 04:09:50,668 - DEBUG - Tap interface tapcafd761b-b0 detail : {'index': '3', 'name': 'tapcafd761b-b0', 'uuid': 'cafd761b-b081-4c6c-81bf-e9473813faf3', 'vrf_name': 'default-domain:ctest-TestEvpnCasesRestart-37762297:EVPN-VN1:EVPN-VN1', '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': '22', 'l2_label': '26', 'vxlan_id': '14', 'vn_name': 'default-domain:ctest-TestEvpnCasesRestart-37762297:EVPN-VN1', 'vm_uuid': '3d7c313f-271e-48ea-8a9f-08b36a44e151', 'vm_name': 'EVPN_VN1_VM2', 'ip_addr': '11.1.1.4', 'mac_addr': '02:ca:fd:76:1b:b0', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.3', 'service_vlan_list': None, 'os_ifindex': '114', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestEvpnCasesRestart-37762297:cafd761b-b081-4c6c-81bf-e9473813faf3', 'sg_uuid_list': ['a4f2890d-a0fe-45cf-9afb-55c55a618784'], 'static_route_list': None, 'vm_project_uuid': '9b46b284-c926-4d8b-84a2-d55a42b9f16e', 'admin_state': 'Enabled', 'flow_key_idx': '23', '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-09-08 04:09:50,789 - DEBUG - Starting Layer 2 verification in Agent
2025-09-08 04:09:50,805 - DEBUG - Layer 2 path is seen for VM MAC 02:ca:fd:76:1b:b0 in agent 10.0.0.23
2025-09-08 04:09:50,805 - DEBUG - Active layer 2 route in agent is present for VMI tapcafd761b-b0
2025-09-08 04:09:50,805 - DEBUG - vxlan_id (14) matches bw route table and interface table
2025-09-08 04:09:50,818 - DEBUG - VN default-domain:ctest-TestEvpnCasesRestart-37762297:EVPN-VN1 is found in Agent of node 10.0.0.65
2025-09-08 04:09:50,927 - DEBUG - VRF IDs of VN default-domain:ctest-TestEvpnCasesRestart-37762297:EVPN-VN1 is consistent in agent 10.0.0.65
2025-09-08 04:09:50,927 - DEBUG - Route for VM IP ['11.1.1.4'] is consistent in agent 10.0.0.65
2025-09-08 04:09:50,927 - DEBUG - VN default-domain:ctest-TestEvpnCasesRestart-37762297:EVPN-VN1 verification for VM EVPN_VN1_VM2 in Agent 10.0.0.65 passed
2025-09-08 04:09:51,041 - DEBUG - Starting all layer 2 verification in agent 10.0.0.65
2025-09-08 04:09:51,059 - DEBUG - Route for VM MAC 02:ca:fd:76:1b:b0 is consistent in agent 10.0.0.65
2025-09-08 04:09:51,072 - DEBUG - VN default-domain:ctest-TestEvpnCasesRestart-37762297:EVPN-VN1 is found in Agent of node 10.0.0.23
2025-09-08 04:09:51,174 - DEBUG - VRF IDs of VN default-domain:ctest-TestEvpnCasesRestart-37762297:EVPN-VN1 is consistent in agent 10.0.0.23
2025-09-08 04:09:51,175 - DEBUG - Route for VM IP ['11.1.1.4'] is consistent in agent 10.0.0.23
2025-09-08 04:09:51,175 - DEBUG - VN default-domain:ctest-TestEvpnCasesRestart-37762297:EVPN-VN1 verification for VM EVPN_VN1_VM2 in Agent 10.0.0.23 passed
2025-09-08 04:09:51,289 - DEBUG - Starting all layer 2 verification in agent 10.0.0.23
2025-09-08 04:09:51,308 - DEBUG - Route for VM MAC 02:ca:fd:76:1b:b0 is consistent in agent 10.0.0.23
2025-09-08 04:09:52,506 - 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=11.9 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.01 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 1.011/6.471/11.931/5.460 ms')
2025-09-08 04:09:52,506 - INFO - Ping to Metadata IP 169.254.0.3 of VM EVPN_VN1_VM2 passed
2025-09-08 04:09:52,506 - INFO - VM EVPN_VN1_VM2 verifications in Compute nodes passed
2025-09-08 04:09:52,780 - DEBUG - Validated VM route 11.1.1.4 in vrouter of 10.0.0.65
2025-09-08 04:09:52,788 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: 10.20.0.192, 22; Got 10.20.0.192,22
2025-09-08 04:09:52,788 - DEBUG - Validated VM route 11.1.1.4 in vrouter of 10.0.0.65
2025-09-08 04:09:52,912 - DEBUG - Validated VM route 11.1.1.4 in vrouter of 10.0.0.23
2025-09-08 04:09:52,919 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0
2025-09-08 04:09:52,919 - INFO - Validated routes of VM EVPN_VN1_VM2 in all vrouters
2025-09-08 04:09:53,444 - DEBUG - Starting all layer2 verification in 10.0.0.27 Control Node
2025-09-08 04:09:53,574 - DEBUG - Layer2 route found for VM MAC 02:ca:fd:76:1b:b0 in Control-node 10.0.0.27
2025-09-08 04:09:53,574 - DEBUG - L2 Label for VM EVPN_VN1_VM2 same between Control-node 10.0.0.27 and Agent, Expected: 14, Seen: 14
2025-09-08 04:09:53,574 - DEBUG - Starting all layer2 verification in 10.0.0.41 Control Node
2025-09-08 04:09:53,705 - DEBUG - Layer2 route found for VM MAC 02:ca:fd:76:1b:b0 in Control-node 10.0.0.41
2025-09-08 04:09:53,705 - DEBUG - L2 Label for VM EVPN_VN1_VM2 same between Control-node 10.0.0.41 and Agent, Expected: 14, Seen: 14
2025-09-08 04:09:53,705 - DEBUG - Starting all layer2 verification in 10.0.0.38 Control Node
2025-09-08 04:09:53,837 - DEBUG - Layer2 route found for VM MAC 02:ca:fd:76:1b:b0 in Control-node 10.0.0.38
2025-09-08 04:09:53,837 - DEBUG - L2 Label for VM EVPN_VN1_VM2 same between Control-node 10.0.0.38 and Agent, Expected: 14, Seen: 14
2025-09-08 04:09:53,837 - INFO - Verification in Control-nodes for VM EVPN_VN1_VM2 passed
2025-09-08 04:09:53,837 - DEBUG - Verifying the vm in opserver
2025-09-08 04:09:53,837 - DEBUG - Verifying in collector 10.0.0.38 ...
2025-09-08 04:09:53,837 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine/3d7c313f-271e-48ea-8a9f-08b36a44e151?flat
2025-09-08 04:09:53,854 - DEBUG - Failed to get VM EVPN_VN1_VM2, ID 3d7c313f-271e-48ea-8a9f-08b36a44e151 info from Opserver
2025-09-08 04:09:55,854 - DEBUG - Verifying the vm in opserver
2025-09-08 04:09:55,854 - DEBUG - Verifying in collector 10.0.0.38 ...
2025-09-08 04:09:55,854 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine/3d7c313f-271e-48ea-8a9f-08b36a44e151?flat
2025-09-08 04:09:55,870 - DEBUG - Failed to get VM EVPN_VN1_VM2, ID 3d7c313f-271e-48ea-8a9f-08b36a44e151 info from Opserver
2025-09-08 04:09:57,871 - DEBUG - Verifying the vm in opserver
2025-09-08 04:09:57,871 - DEBUG - Verifying in collector 10.0.0.38 ...
2025-09-08 04:09:57,871 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine/3d7c313f-271e-48ea-8a9f-08b36a44e151?flat
2025-09-08 04:09:57,888 - DEBUG - Failed to get VM EVPN_VN1_VM2, ID 3d7c313f-271e-48ea-8a9f-08b36a44e151 info from Opserver
2025-09-08 04:09:59,889 - DEBUG - Verifying the vm in opserver
2025-09-08 04:09:59,889 - DEBUG - Verifying in collector 10.0.0.38 ...
2025-09-08 04:09:59,889 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine/3d7c313f-271e-48ea-8a9f-08b36a44e151?flat
2025-09-08 04:09:59,907 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestEvpnCasesRestart-37762297:cafd761b-b081-4c6c-81bf-e9473813faf3?flat
2025-09-08 04:09:59,929 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestEvpnCasesRestart-37762297:cafd761b-b081-4c6c-81bf-e9473813faf3?flat
2025-09-08 04:09:59,950 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine/3d7c313f-271e-48ea-8a9f-08b36a44e151?flat
2025-09-08 04:09:59,967 - DEBUG - Verifying vm in vn uve
2025-09-08 04:09:59,967 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestEvpnCasesRestart-37762297:cafd761b-b081-4c6c-81bf-e9473813faf3?flat
2025-09-08 04:09:59,988 - DEBUG - VM uve shows interface as {'ip_address': '11.1.1.4', 'tx_vlan': 65535, 'gateway': '11.1.1.1', 'l2_active': True, 'ip4_active': True, 'vn_uuid': 'f8f813b6-6659-4491-8b0b-d782b16a5055', 'port_mirror_enabled': False, 'admin_state': True, 'vm_name': 'EVPN_VN1_VM2', '__T': 1757304598169159, 'active': True, 'vm_uuid': '3d7c313f-271e-48ea-8a9f-08b36a44e151', 'label': 22, 'fixed_ip4_list': ['11.1.1.4'], 'ip6_active': False, 'rx_vlan': 65535, 'mac_address': '02:ca:fd:76:1b:b0', 'uuid': 'cafd761b-b081-4c6c-81bf-e9473813faf3', 'is_health_check_active': True, 'vhostuser_mode': 0, 'virtual_network': 'default-domain:ctest-TestEvpnCasesRestart-37762297:EVPN-VN1', 'ip6_address': '::'}
2025-09-08 04:09:59,988 - DEBUG - VM uve shows ip address as ['11.1.1.4', '::']
2025-09-08 04:09:59,988 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestEvpnCasesRestart-37762297:EVPN-VN1
2025-09-08 04:09:59,988 - INFO - Verifying through opserver in 10.0.0.41
2025-09-08 04:09:59,988 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestEvpnCasesRestart-37762297:EVPN-VN1?flat
2025-09-08 04:10:00,025 - DEBUG - expected vm list 3d7c313f-271e-48ea-8a9f-08b36a44e151
2025-09-08 04:10:00,025 - DEBUG - Extracted vm list ['3d7c313f-271e-48ea-8a9f-08b36a44e151']
2025-09-08 04:10:00,025 - INFO - VM 3d7c313f-271e-48ea-8a9f-08b36a44e151 is present in default-domain:ctest-TestEvpnCasesRestart-37762297:EVPN-VN1
2025-09-08 04:10:00,025 - DEBUG - Verifying vm in vrouter uve
2025-09-08 04:10:00,025 - DEBUG - Getting info from collector 10.0.0.38..
2025-09-08 04:10:00,025 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine/3d7c313f-271e-48ea-8a9f-08b36a44e151?flat
2025-09-08 04:10:00,046 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-4041-2']
2025-09-08 04:10:00,046 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4041-2:Compute:contrail-vrouter-agent:0?flat
2025-09-08 04:10:00,088 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4041-2?flat
2025-09-08 04:10:00,121 - DEBUG - VM 3d7c313f-271e-48ea-8a9f-08b36a44e151 is present in vrouter an-jenkins-deploy-platform-ansible-os-4041-2 uve
2025-09-08 04:10:00,121 - DEBUG - Validated that VM EVPN_VN1_VM2 is in Vrouter an-jenkins-deploy-platform-ansible-os-4041-2 UVE
2025-09-08 04:10:00,121 - DEBUG - Verifying vm tap interface/vn in vrouter uve
2025-09-08 04:10:00,136 - DEBUG - Expected tap interface of VM uuid 3d7c313f-271e-48ea-8a9f-08b36a44e151 is default-domain:ctest-TestEvpnCasesRestart-37762297:cafd761b-b081-4c6c-81bf-e9473813faf3
2025-09-08 04:10:00,136 - DEBUG - Expected VN of VM uuid 3d7c313f-271e-48ea-8a9f-08b36a44e151 is default-domain:ctest-TestEvpnCasesRestart-37762297:EVPN-VN1
2025-09-08 04:10:00,136 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4041-2:Compute:contrail-vrouter-agent:0?flat
2025-09-08 04:10:00,170 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4041-2?flat
2025-09-08 04:10:00,198 - DEBUG - VM 3d7c313f-271e-48ea-8a9f-08b36a44e151 is present in vrouter an-jenkins-deploy-platform-ansible-os-4041-2 uve
2025-09-08 04:10:00,198 - INFO - tap interface default-domain:ctest-TestEvpnCasesRestart-37762297:cafd761b-b081-4c6c-81bf-e9473813faf3 of vm 3d7c313f-271e-48ea-8a9f-08b36a44e151 is present in vrouter an-jenkins-deploy-platform-ansible-os-4041-2 uve
2025-09-08 04:10:00,198 - DEBUG - Connected network default-domain:ctest-TestEvpnCasesRestart-37762297:EVPN-VN1 of vm 3d7c313f-271e-48ea-8a9f-08b36a44e151 is present in vrouter an-jenkins-deploy-platform-ansible-os-4041-2 uve
2025-09-08 04:10:00,198 - DEBUG - Verifying the 3d7c313f-271e-48ea-8a9f-08b36a44e151 virtual network link through opserver 10.0.0.41
2025-09-08 04:10:00,199 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machines
2025-09-08 04:10:00,213 - DEBUG - VM link and name as {'name': '3d7c313f-271e-48ea-8a9f-08b36a44e151', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-machine/3d7c313f-271e-48ea-8a9f-08b36a44e151?flat'}
2025-09-08 04:10:00,213 - DEBUG - Verifying the 3d7c313f-271e-48ea-8a9f-08b36a44e151 virtual network link through opserver 10.0.0.27
2025-09-08 04:10:00,213 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines
2025-09-08 04:10:00,227 - DEBUG - VM link and name as {'name': '3d7c313f-271e-48ea-8a9f-08b36a44e151', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-machine/3d7c313f-271e-48ea-8a9f-08b36a44e151?flat'}
2025-09-08 04:10:00,227 - DEBUG - Verifying the 3d7c313f-271e-48ea-8a9f-08b36a44e151 virtual network link through opserver 10.0.0.38
2025-09-08 04:10:00,228 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines
2025-09-08 04:10:00,242 - DEBUG - VM link and name as {'name': '3d7c313f-271e-48ea-8a9f-08b36a44e151', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-machine/3d7c313f-271e-48ea-8a9f-08b36a44e151?flat'}
2025-09-08 04:10:00,242 - INFO - VM EVPN_VN1_VM2 validations in Opserver passed
2025-09-08 04:10:00,372 - DEBUG - Executing command: sudo tcpdump -nni tapcafd761b-b0 -U ether src 02:29:bc:05:dc:c1 -w /tmp/tapcafd761b-b0_ctest-random-32220654.pcap
2025-09-08 04:10:00,501 - INFO - waiting to get tcpdump started
2025-09-08 04:10:21,653 - 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.14 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.552 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.552/1.847/3.142/1.295 ms')
2025-09-08 04:10:21,653 - INFO - Ping to Metadata IP 169.254.0.3 of VM EVPN_VN1_VM1 passed
2025-09-08 04:10:21,914 - DEBUG - file transfer done ['/tmp/program.py']
2025-09-08 04:10:21,914 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-09-08 04:10:21,914 - DEBUG - Copied file /tmp/tmpfv0io80p/program.py to 11.1.1.3
2025-09-08 04:10:23,085 - 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=2.70 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.02 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 1.015/1.855/2.696/0.840 ms')
2025-09-08 04:10:23,085 - INFO - Ping to Metadata IP 169.254.0.3 of VM EVPN_VN1_VM1 passed
2025-09-08 04:10:23,085 - DEBUG - Running remote_cmd, Cmd : python /tmp/program.py, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.65, gateway password: c0ntrail123
2025-09-08 04:10:23,085 - DEBUG - python /tmp/program.py
2025-09-08 04:10:25,001 - DEBUG - WARNING: No route found for IPv6 destination :: (no default route?)
..........
Sent 10 packets.
2025-09-08 04:10:25,002 - DEBUG - Executing command: sudo tcpdump -nnr /tmp/tapcafd761b-b0_ctest-random-32220654.pcap ether host 02:ca:fd:76:1b:b0 | grep -c length
2025-09-08 04:10:25,045 - DEBUG - STDOUT: 0
2025-09-08 04:10:25,045 - DEBUG - STDERR: reading from file /tmp/tapcafd761b-b0_ctest-random-32220654.pcap, link-type EN10MB (Ethernet), snapshot length 262144
2025-09-08 04:10:25,045 - WARNING - 0 packets are found in tcpdump output file /tmp/tapcafd761b-b0_ctest-random-32220654.pcap but expected 10
2025-09-08 04:10:27,046 - DEBUG - Executing command: sudo tcpdump -nnr /tmp/tapcafd761b-b0_ctest-random-32220654.pcap ether host 02:ca:fd:76:1b:b0 | grep -c length
2025-09-08 04:10:27,087 - DEBUG - STDOUT: 0
2025-09-08 04:10:27,087 - DEBUG - STDERR: reading from file /tmp/tapcafd761b-b0_ctest-random-32220654.pcap, link-type EN10MB (Ethernet), snapshot length 262144
2025-09-08 04:10:27,087 - WARNING - 0 packets are found in tcpdump output file /tmp/tapcafd761b-b0_ctest-random-32220654.pcap but expected 10
2025-09-08 04:10:29,088 - DEBUG - Executing command: sudo tcpdump -nnr /tmp/tapcafd761b-b0_ctest-random-32220654.pcap ether host 02:ca:fd:76:1b:b0 | grep -c length
2025-09-08 04:10:29,128 - DEBUG - STDOUT: 0
2025-09-08 04:10:29,128 - DEBUG - STDERR: reading from file /tmp/tapcafd761b-b0_ctest-random-32220654.pcap, link-type EN10MB (Ethernet), snapshot length 262144
2025-09-08 04:10:29,128 - WARNING - 0 packets are found in tcpdump output file /tmp/tapcafd761b-b0_ctest-random-32220654.pcap but expected 10
2025-09-08 04:10:31,129 - DEBUG - Executing command: sudo tcpdump -nnr /tmp/tapcafd761b-b0_ctest-random-32220654.pcap ether host 02:ca:fd:76:1b:b0 | grep -c length
2025-09-08 04:10:31,169 - DEBUG - STDOUT: 0
2025-09-08 04:10:31,169 - DEBUG - STDERR: reading from file /tmp/tapcafd761b-b0_ctest-random-32220654.pcap, link-type EN10MB (Ethernet), snapshot length 262144
2025-09-08 04:10:31,169 - WARNING - 0 packets are found in tcpdump output file /tmp/tapcafd761b-b0_ctest-random-32220654.pcap but expected 10
2025-09-08 04:10:33,170 - DEBUG - Executing command: sudo tcpdump -nnr /tmp/tapcafd761b-b0_ctest-random-32220654.pcap ether host 02:ca:fd:76:1b:b0 | grep -c length
2025-09-08 04:10:33,214 - DEBUG - STDOUT: 0
2025-09-08 04:10:33,214 - DEBUG - STDERR: reading from file /tmp/tapcafd761b-b0_ctest-random-32220654.pcap, link-type EN10MB (Ethernet), snapshot length 262144
2025-09-08 04:10:33,214 - WARNING - 0 packets are found in tcpdump output file /tmp/tapcafd761b-b0_ctest-random-32220654.pcap but expected 10
2025-09-08 04:10:35,215 - DEBUG - Executing command: sudo tcpdump -nnr /tmp/tapcafd761b-b0_ctest-random-32220654.pcap ether host 02:ca:fd:76:1b:b0 | grep -c length
2025-09-08 04:10:35,255 - DEBUG - STDOUT: 0
2025-09-08 04:10:35,255 - DEBUG - STDERR: reading from file /tmp/tapcafd761b-b0_ctest-random-32220654.pcap, link-type EN10MB (Ethernet), snapshot length 262144
2025-09-08 04:10:35,255 - WARNING - 0 packets are found in tcpdump output file /tmp/tapcafd761b-b0_ctest-random-32220654.pcap but expected 10
2025-09-08 04:10:37,256 - DEBUG - Executing command: sudo tcpdump -nnr /tmp/tapcafd761b-b0_ctest-random-32220654.pcap ether host 02:ca:fd:76:1b:b0 | grep -c length
2025-09-08 04:10:37,277 - DEBUG - STDOUT: 0
2025-09-08 04:10:37,278 - DEBUG - STDERR: reading from file /tmp/tapcafd761b-b0_ctest-random-32220654.pcap, link-type EN10MB (Ethernet), snapshot length 262144
2025-09-08 04:10:37,278 - WARNING - 0 packets are found in tcpdump output file /tmp/tapcafd761b-b0_ctest-random-32220654.pcap but expected 10
2025-09-08 04:10:37,278 - INFO - Checking the communication between 2 VM after vrouter restart
2025-09-08 04:10:37,278 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/3d7c313f-271e-48ea-8a9f-08b36a44e151
2025-09-08 04:10:37,289 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/cafd761b-b081-4c6c-81bf-e9473813faf3
2025-09-08 04:10:37,301 - DEBUG - Requesting: http://10.0.0.38:8082/instance-ip/2e462ab2-4070-4372-a513-f78d2cbbdbc3
2025-09-08 04:10:38,478 - 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.36 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.972 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.972/2.164/3.357/1.192 ms')
2025-09-08 04:10:38,478 - INFO - Ping to Metadata IP 169.254.0.3 of VM EVPN_VN1_VM1 passed
2025-09-08 04:10:38,479 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 11.1.1.4, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.65, gateway password: c0ntrail123
2025-09-08 04:10:38,479 - DEBUG - ping -s 56 -c 3 -W 1 11.1.1.4
2025-09-08 04:10:41,144 - DEBUG - PING 11.1.1.4 (11.1.1.4) 56(84) bytes of data.
64 bytes from 11.1.1.4: icmp_req=1 ttl=64 time=21.7 ms
64 bytes from 11.1.1.4: icmp_req=2 ttl=64 time=2.00 ms
64 bytes from 11.1.1.4: icmp_req=3 ttl=64 time=2.00 ms
--- 11.1.1.4 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 2.001/8.587/21.753/9.309 ms
2025-09-08 04:10:41,144 - INFO - Ping to IP 11.1.1.4 from VM EVPN_VN1_VM1 passed
2025-09-08 04:10:41,144 - INFO - Deleting VM EVPN_VN1_VM2
2025-09-08 04:10:41,217 - DEBUG - Verifying in api server 10.0.0.38
2025-09-08 04:10:41,217 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/3d7c313f-271e-48ea-8a9f-08b36a44e151
2025-09-08 04:10:41,229 - DEBUG - VM ID 3d7c313f-271e-48ea-8a9f-08b36a44e151 of VM EVPN_VN1_VM2 is still found in API Server
2025-09-08 04:10:43,229 - DEBUG - Verifying in api server 10.0.0.38
2025-09-08 04:10:43,229 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/3d7c313f-271e-48ea-8a9f-08b36a44e151
2025-09-08 04:10:43,237 - DEBUG - Response Code: 404
2025-09-08 04:10:43,237 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/3d7c313f-271e-48ea-8a9f-08b36a44e151
2025-09-08 04:10:43,245 - DEBUG - Response Code: 404
2025-09-08 04:10:43,245 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/3d7c313f-271e-48ea-8a9f-08b36a44e151
2025-09-08 04:10:43,255 - DEBUG - Response Code: 404
2025-09-08 04:10:43,255 - INFO - VM EVPN_VN1_VM2 is fully removed in API-Server
2025-09-08 04:10:44,132 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2025-09-08 04:10:44,156 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/3d7c313f-271e-48ea-8a9f-08b36a44e151
2025-09-08 04:10:44,163 - DEBUG - Response Code: 404
2025-09-08 04:10:45,164 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/3d7c313f-271e-48ea-8a9f-08b36a44e151
2025-09-08 04:10:45,173 - DEBUG - Response Code: 404
2025-09-08 04:10:46,174 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/3d7c313f-271e-48ea-8a9f-08b36a44e151
2025-09-08 04:10:46,184 - DEBUG - Response Code: 404
2025-09-08 04:10:47,186 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/3d7c313f-271e-48ea-8a9f-08b36a44e151
2025-09-08 04:10:47,193 - DEBUG - Response Code: 404
2025-09-08 04:10:48,194 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/3d7c313f-271e-48ea-8a9f-08b36a44e151
2025-09-08 04:10:48,202 - DEBUG - Response Code: 404
2025-09-08 04:10:49,203 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/3d7c313f-271e-48ea-8a9f-08b36a44e151
2025-09-08 04:10:49,211 - DEBUG - Response Code: 404
2025-09-08 04:10:49,211 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/3d7c313f-271e-48ea-8a9f-08b36a44e151
2025-09-08 04:10:49,218 - DEBUG - Response Code: 404
2025-09-08 04:10:50,219 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/3d7c313f-271e-48ea-8a9f-08b36a44e151
2025-09-08 04:10:50,228 - DEBUG - Response Code: 404
2025-09-08 04:10:51,229 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/3d7c313f-271e-48ea-8a9f-08b36a44e151
2025-09-08 04:10:51,236 - DEBUG - Response Code: 404
2025-09-08 04:10:52,238 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/3d7c313f-271e-48ea-8a9f-08b36a44e151
2025-09-08 04:10:52,245 - DEBUG - Response Code: 404
2025-09-08 04:10:53,246 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/3d7c313f-271e-48ea-8a9f-08b36a44e151
2025-09-08 04:10:53,255 - DEBUG - Response Code: 404
2025-09-08 04:10:54,256 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/3d7c313f-271e-48ea-8a9f-08b36a44e151
2025-09-08 04:10:54,264 - DEBUG - Response Code: 404
2025-09-08 04:10:54,314 - DEBUG - Validated that vrouter 10.0.0.65 does not have VMs route for VN default-domain:ctest-TestEvpnCasesRestart-37762297:EVPN-VN1
2025-09-08 04:10:54,314 - DEBUG - Validated that vrouter 10.0.0.23 does not have VMs route for VN default-domain:ctest-TestEvpnCasesRestart-37762297:EVPN-VN1
2025-09-08 04:10:54,314 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestEvpnCasesRestart-37762297:EVPN-VN1
2025-09-08 04:10:54,314 - INFO - VM EVPN_VN1_VM2 is removed in Compute, and routes are removed in all compute nodes
2025-09-08 04:10:54,563 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/3d7c313f-271e-48ea-8a9f-08b36a44e151
2025-09-08 04:10:54,569 - DEBUG - Response Code: 404
2025-09-08 04:10:55,571 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/3d7c313f-271e-48ea-8a9f-08b36a44e151
2025-09-08 04:10:55,580 - DEBUG - Response Code: 404
2025-09-08 04:10:56,580 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/3d7c313f-271e-48ea-8a9f-08b36a44e151
2025-09-08 04:10:56,588 - DEBUG - Response Code: 404
2025-09-08 04:10:57,589 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/3d7c313f-271e-48ea-8a9f-08b36a44e151
2025-09-08 04:10:57,597 - DEBUG - Response Code: 404
2025-09-08 04:10:58,597 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/3d7c313f-271e-48ea-8a9f-08b36a44e151
2025-09-08 04:10:58,605 - DEBUG - Response Code: 404
2025-09-08 04:10:59,606 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/3d7c313f-271e-48ea-8a9f-08b36a44e151
2025-09-08 04:10:59,615 - DEBUG - Response Code: 404
2025-09-08 04:10:59,615 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/3d7c313f-271e-48ea-8a9f-08b36a44e151
2025-09-08 04:10:59,622 - DEBUG - Response Code: 404
2025-09-08 04:11:00,622 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/3d7c313f-271e-48ea-8a9f-08b36a44e151
2025-09-08 04:11:00,632 - DEBUG - Response Code: 404
2025-09-08 04:11:01,633 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/3d7c313f-271e-48ea-8a9f-08b36a44e151
2025-09-08 04:11:01,641 - DEBUG - Response Code: 404
2025-09-08 04:11:02,641 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/3d7c313f-271e-48ea-8a9f-08b36a44e151
2025-09-08 04:11:02,651 - DEBUG - Response Code: 404
2025-09-08 04:11:03,652 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/3d7c313f-271e-48ea-8a9f-08b36a44e151
2025-09-08 04:11:03,660 - DEBUG - Response Code: 404
2025-09-08 04:11:04,661 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/3d7c313f-271e-48ea-8a9f-08b36a44e151
2025-09-08 04:11:04,668 - DEBUG - Response Code: 404
2025-09-08 04:11:04,669 - INFO - Routes for VM EVPN_VN1_VM2 is removed in all control-nodes
2025-09-08 04:11:04,669 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4041-2:Compute:contrail-vrouter-agent:0?flat
2025-09-08 04:11:04,717 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4041-2?flat
2025-09-08 04:11:04,759 - DEBUG - VM 3d7c313f-271e-48ea-8a9f-08b36a44e151 is not present in vrouter an-jenkins-deploy-platform-ansible-os-4041-2 uve
2025-09-08 04:11:04,759 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-09-08 04:11:04,759 - DEBUG - interface for vm 3d7c313f-271e-48ea-8a9f-08b36a44e151 deleted from vrouter uve ...
2025-09-08 04:11:04,760 - DEBUG - Deleted interface not in error interface list ...
2025-09-08 04:11:04,760 - DEBUG - Validated that VM 3d7c313f-271e-48ea-8a9f-08b36a44e151 is removed in Vrouter UVE
2025-09-08 04:11:04,760 - DEBUG - Verifying through opserver in 10.0.0.41
2025-09-08 04:11:04,760 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestEvpnCasesRestart-37762297:EVPN-VN1?flat
2025-09-08 04:11:04,786 - DEBUG - 3d7c313f-271e-48ea-8a9f-08b36a44e151 BM not in default-domain:ctest-TestEvpnCasesRestart-37762297:EVPN-VN1 uve
2025-09-08 04:11:04,786 - DEBUG - Validated that VM 3d7c313f-271e-48ea-8a9f-08b36a44e151 is not present in the VN UVE
2025-09-08 04:11:04,786 - DEBUG - Verifying the 3d7c313f-271e-48ea-8a9f-08b36a44e151 virtual network link through opserver 10.0.0.41
2025-09-08 04:11:04,786 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machines
2025-09-08 04:11:04,799 - INFO - 3d7c313f-271e-48ea-8a9f-08b36a44e151 vm uve deleted from opserver
2025-09-08 04:11:04,799 - DEBUG - Verifying the 3d7c313f-271e-48ea-8a9f-08b36a44e151 virtual network link through opserver 10.0.0.27
2025-09-08 04:11:04,799 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines
2025-09-08 04:11:04,814 - INFO - 3d7c313f-271e-48ea-8a9f-08b36a44e151 vm uve deleted from opserver
2025-09-08 04:11:04,814 - DEBUG - Verifying the 3d7c313f-271e-48ea-8a9f-08b36a44e151 virtual network link through opserver 10.0.0.38
2025-09-08 04:11:04,814 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines
2025-09-08 04:11:04,829 - INFO - 3d7c313f-271e-48ea-8a9f-08b36a44e151 vm uve deleted from opserver
2025-09-08 04:11:04,829 - INFO - Validated that VM 3d7c313f-271e-48ea-8a9f-08b36a44e151 is removed from Opserver
2025-09-08 04:11:04,829 - INFO - Deleting VM EVPN_VN1_VM1
2025-09-08 04:11:04,902 - DEBUG - Verifying in api server 10.0.0.38
2025-09-08 04:11:04,903 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/850d819d-74c7-455f-ad2c-b4420ba24ed8
2025-09-08 04:11:04,912 - DEBUG - VM ID 850d819d-74c7-455f-ad2c-b4420ba24ed8 of VM EVPN_VN1_VM1 is still found in API Server
2025-09-08 04:11:06,913 - DEBUG - Verifying in api server 10.0.0.38
2025-09-08 04:11:06,913 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/850d819d-74c7-455f-ad2c-b4420ba24ed8
2025-09-08 04:11:06,920 - DEBUG - Response Code: 404
2025-09-08 04:11:06,921 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/850d819d-74c7-455f-ad2c-b4420ba24ed8
2025-09-08 04:11:06,928 - DEBUG - Response Code: 404
2025-09-08 04:11:06,928 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/850d819d-74c7-455f-ad2c-b4420ba24ed8
2025-09-08 04:11:06,934 - DEBUG - Response Code: 404
2025-09-08 04:11:06,935 - INFO - VM EVPN_VN1_VM1 is fully removed in API-Server
2025-09-08 04:11:07,086 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2025-09-08 04:11:07,112 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/850d819d-74c7-455f-ad2c-b4420ba24ed8
2025-09-08 04:11:07,119 - DEBUG - Response Code: 404
2025-09-08 04:11:08,120 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/850d819d-74c7-455f-ad2c-b4420ba24ed8
2025-09-08 04:11:08,128 - DEBUG - Response Code: 404
2025-09-08 04:11:09,129 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/850d819d-74c7-455f-ad2c-b4420ba24ed8
2025-09-08 04:11:09,137 - DEBUG - Response Code: 404
2025-09-08 04:11:10,138 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/850d819d-74c7-455f-ad2c-b4420ba24ed8
2025-09-08 04:11:10,146 - DEBUG - Response Code: 404
2025-09-08 04:11:11,147 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/850d819d-74c7-455f-ad2c-b4420ba24ed8
2025-09-08 04:11:11,157 - DEBUG - Response Code: 404
2025-09-08 04:11:12,158 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/850d819d-74c7-455f-ad2c-b4420ba24ed8
2025-09-08 04:11:12,166 - DEBUG - Response Code: 404
2025-09-08 04:11:12,167 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/850d819d-74c7-455f-ad2c-b4420ba24ed8
2025-09-08 04:11:12,174 - DEBUG - Response Code: 404
2025-09-08 04:11:13,175 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/850d819d-74c7-455f-ad2c-b4420ba24ed8
2025-09-08 04:11:13,183 - DEBUG - Response Code: 404
2025-09-08 04:11:14,184 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/850d819d-74c7-455f-ad2c-b4420ba24ed8
2025-09-08 04:11:14,192 - DEBUG - Response Code: 404
2025-09-08 04:11:15,192 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/850d819d-74c7-455f-ad2c-b4420ba24ed8
2025-09-08 04:11:15,200 - DEBUG - Response Code: 404
2025-09-08 04:11:16,201 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/850d819d-74c7-455f-ad2c-b4420ba24ed8
2025-09-08 04:11:16,209 - DEBUG - Response Code: 404
2025-09-08 04:11:17,210 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/850d819d-74c7-455f-ad2c-b4420ba24ed8
2025-09-08 04:11:17,217 - DEBUG - Response Code: 404
2025-09-08 04:11:17,259 - DEBUG - Validated that vrouter 10.0.0.65 does not have VMs route for VN default-domain:ctest-TestEvpnCasesRestart-37762297:EVPN-VN1
2025-09-08 04:11:17,283 - DEBUG - Validated that vrouter 10.0.0.23 does not have VMs route for VN default-domain:ctest-TestEvpnCasesRestart-37762297:EVPN-VN1
2025-09-08 04:11:17,283 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestEvpnCasesRestart-37762297:EVPN-VN1
2025-09-08 04:11:17,283 - INFO - VM EVPN_VN1_VM1 is removed in Compute, and routes are removed in all compute nodes
2025-09-08 04:11:17,518 - INFO - Routes for VM EVPN_VN1_VM1 is removed in all control-nodes
2025-09-08 04:11:17,518 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4041-1:Compute:contrail-vrouter-agent:0?flat
2025-09-08 04:11:17,555 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4041-1?flat
2025-09-08 04:11:17,590 - DEBUG - VM 850d819d-74c7-455f-ad2c-b4420ba24ed8 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4041-1 uve
2025-09-08 04:11:17,590 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-09-08 04:11:17,591 - DEBUG - interface for vm 850d819d-74c7-455f-ad2c-b4420ba24ed8 deleted from vrouter uve ...
2025-09-08 04:11:17,591 - DEBUG - Deleted interface not in error interface list ...
2025-09-08 04:11:20,592 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4041-1:Compute:contrail-vrouter-agent:0?flat
2025-09-08 04:11:20,634 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4041-1?flat
2025-09-08 04:11:20,668 - DEBUG - VM 850d819d-74c7-455f-ad2c-b4420ba24ed8 is not present in vrouter an-jenkins-deploy-platform-ansible-os-4041-1 uve
2025-09-08 04:11:20,668 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-09-08 04:11:20,668 - DEBUG - interface for vm 850d819d-74c7-455f-ad2c-b4420ba24ed8 deleted from vrouter uve ...
2025-09-08 04:11:20,668 - DEBUG - Deleted interface not in error interface list ...
2025-09-08 04:11:20,668 - DEBUG - Validated that VM 850d819d-74c7-455f-ad2c-b4420ba24ed8 is removed in Vrouter UVE
2025-09-08 04:11:20,668 - DEBUG - Verifying through opserver in 10.0.0.41
2025-09-08 04:11:20,668 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestEvpnCasesRestart-37762297:EVPN-VN1?flat
2025-09-08 04:11:20,688 - DEBUG - 850d819d-74c7-455f-ad2c-b4420ba24ed8 BM not in default-domain:ctest-TestEvpnCasesRestart-37762297:EVPN-VN1 uve
2025-09-08 04:11:20,688 - DEBUG - Validated that VM 850d819d-74c7-455f-ad2c-b4420ba24ed8 is not present in the VN UVE
2025-09-08 04:11:20,688 - DEBUG - Verifying the 850d819d-74c7-455f-ad2c-b4420ba24ed8 virtual network link through opserver 10.0.0.41
2025-09-08 04:11:20,688 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machines
2025-09-08 04:11:20,702 - INFO - 850d819d-74c7-455f-ad2c-b4420ba24ed8 vm uve deleted from opserver
2025-09-08 04:11:20,702 - DEBUG - Verifying the 850d819d-74c7-455f-ad2c-b4420ba24ed8 virtual network link through opserver 10.0.0.27
2025-09-08 04:11:20,703 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines
2025-09-08 04:11:20,719 - INFO - 850d819d-74c7-455f-ad2c-b4420ba24ed8 vm uve deleted from opserver
2025-09-08 04:11:20,720 - DEBUG - Verifying the 850d819d-74c7-455f-ad2c-b4420ba24ed8 virtual network link through opserver 10.0.0.38
2025-09-08 04:11:20,720 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines
2025-09-08 04:11:20,739 - INFO - 850d819d-74c7-455f-ad2c-b4420ba24ed8 vm uve deleted from opserver
2025-09-08 04:11:20,739 - INFO - Validated that VM 850d819d-74c7-455f-ad2c-b4420ba24ed8 is removed from Opserver
2025-09-08 04:11:20,740 - INFO - Deleting VN EVPN-VN1
2025-09-08 04:11:20,914 - DEBUG - Response for deleting network ()
2025-09-08 04:11:20,914 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/c127363f-4929-41ff-ae10-41701fed8b65
2025-09-08 04:11:20,920 - DEBUG - Response Code: 404
2025-09-08 04:11:20,921 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-09-08 04:11:20,929 - DEBUG - Requesting: http://10.0.0.41:8082/domain/729830a9-0e71-496c-9bfd-570f28a4effe
2025-09-08 04:11:20,991 - DEBUG - Requesting: http://10.0.0.41:8082/project/9b46b284-c926-4d8b-84a2-d55a42b9f16e
2025-09-08 04:11:21,090 - INFO - Validated that VN EVPN-VN1 is not found in API Server
2025-09-08 04:11:21,111 - DEBUG - VN EVPN-VN1 is not present in Agent 10.0.0.65
2025-09-08 04:11:21,136 - DEBUG - VN EVPN-VN1 is not present in Agent 10.0.0.23
2025-09-08 04:11:21,136 - INFO - Validated that VN EVPN-VN1 is not in any agent
2025-09-08 04:11:21,148 - DEBUG - VRF 2 is not seen in agent 10.0.0.65
2025-09-08 04:11:21,162 - DEBUG - Vrouter 10.0.0.65 does not have vrf 2 for VN EVPN-VN1
2025-09-08 04:11:21,173 - DEBUG - VRF 2 is not seen in agent 10.0.0.23
2025-09-08 04:11:21,184 - DEBUG - Vrouter 10.0.0.23 does not have vrf 2 for VN EVPN-VN1
2025-09-08 04:11:21,184 - INFO - Validated that all vrouters do not have the route table for VN default-domain:ctest-TestEvpnCasesRestart-37762297:EVPN-VN1
2025-09-08 04:11:21,206 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN EVPN-VN1 info
2025-09-08 04:11:23,827 - DEBUG - Skipping xmpp flap check
2025-09-08 04:11:23,827 - INFO - END TEST : test_with_vxlan_encap_agent_restart : PASSED[0:04:40]
2025-09-08 04:11:23,827 - INFO - --------------------------------------------------------------------------------
2025-09-08 04:11:24,692 - INFO - Deleted project: ctest-TestEvpnCasesRestart-37762297, ID : 9b46b284-c926-4d8b-84a2-d55a42b9f16e