2025-12-22 03:53:11,187 - INFO - Domain Default found not creating 2025-12-22 03:53:11,352 - INFO - Project ctest-TestEvpnCasesRestart-86150708 not found, creating it 2025-12-22 03:53:11,935 - INFO - Created Project:ctest-TestEvpnCasesRestart-86150708, ID : 6c1885ca-0041-4e8f-bf21-427922af510f 2025-12-22 03:53:13,396 - INFO - ================================================================================ 2025-12-22 03:53:13,396 - INFO - STARTING TEST : test_with_vxlan_encap_agent_restart 2025-12-22 03:53:13,396 - INFO - TEST DESCRIPTION : Description:Test agent restart with VXLAN Encap 1. Configure VXLAN as highest priority 2. Configure 2 VM under a VN configured with l2-l3 mode 3. Send L2 traffic and verify communication between vms 4. Restart the contrail-grouter service. 5. Send L2 Traffic and verify communication between vms Pass criteria: Step 3 and 5 should pass Maintainer: chhandak@juniper.net 2025-12-22 03:53:14,677 - DEBUG - Skipping xmpp flap check 2025-12-22 03:53:14,677 - INFO - Initial checks done. Running the testcase now 2025-12-22 03:53:14,677 - INFO - 2025-12-22 03:53:14,678 - INFO - Setting new Encap before continuing 2025-12-22 03:53:14,678 - INFO - Read the existing encap priority 2025-12-22 03:53:15,222 - INFO - Created.UUID is {"global-vrouter-config": {"uuid": "da75cb2e-1a17-423d-8ac0-ca2776e5681b", "href": "http://10.0.0.65:8082/global-vrouter-config/da75cb2e-1a17-423d-8ac0-ca2776e5681b"}}. VXLAN is the highest priority encap 2025-12-22 03:53:15,246 - DEBUG - Services list from nova: [, , , ] 2025-12-22 03:53:15,246 - DEBUG - Hosts: {'nova': ['an-jenkins-deploy-platform-ansible-os-4918-1', 'an-jenkins-deploy-platform-ansible-os-4918-2']} 2025-12-22 03:53:15,617 - DEBUG - Response for create_network : {'network': {'id': '86c7da20-7a27-46f8-8113-f672de5059cd', 'name': 'EVPN-VN1', 'tenant_id': '6c1885ca00414e8fbf21427922af510f', 'project_id': '6c1885ca00414e8fbf21427922af510f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestEvpnCasesRestart-86150708', 'EVPN-VN1'], 'port_security_enabled': True, 'description': ''}} 2025-12-22 03:53:15,935 - DEBUG - Response for create_subnet : {'subnet': {'id': '7766b3ee-321c-4950-857b-22b1669f13c8', 'name': '', 'tenant_id': '6c1885ca00414e8fbf21427922af510f', 'network_id': '86c7da20-7a27-46f8-8113-f672de5059cd', 'ip_version': 4, 'cidr': '11.1.1.0/24', 'allocation_pools': [{'start': '11.1.1.2', 'end': '11.1.1.254'}], 'gateway_ip': '11.1.1.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '11.1.1.2', 'tags': [], 'project_id': '6c1885ca00414e8fbf21427922af510f'}} 2025-12-22 03:53:15,955 - INFO - Created VN EVPN-VN1 2025-12-22 03:53:15,967 - DEBUG - VN EVPN-VN1 UUID is 86c7da20-7a27-46f8-8113-f672de5059cd 2025-12-22 03:53:17,412 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4918-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4918-1) 2025-12-22 03:53:18,590 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4918-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4918-2) 2025-12-22 03:53:18,590 - DEBUG - Requesting: http://10.0.0.23:8082/domains 2025-12-22 03:53:18,933 - DEBUG - Requesting: http://10.0.0.23:8082/domains 2025-12-22 03:53:18,988 - DEBUG - Requesting: http://10.0.0.23:8082/domain/cc89f09a-44c2-47d5-97ff-1101c2b1f822 2025-12-22 03:53:19,056 - DEBUG - Requesting: http://10.0.0.23:8082/project/6c1885ca-0041-4e8f-bf21-427922af510f 2025-12-22 03:53:19,157 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/86c7da20-7a27-46f8-8113-f672de5059cd 2025-12-22 03:53:19,199 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/86c7da20-7a27-46f8-8113-f672de5059cd 2025-12-22 03:53:19,215 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/c7471d81-0aa9-430b-b964-1c50e22ca6a2 2025-12-22 03:53:19,227 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/c7471d81-0aa9-430b-b964-1c50e22ca6a2 2025-12-22 03:53:19,234 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/78ebd4c4-e348-4729-ba5c-934796f67f75 2025-12-22 03:53:19,250 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-12-22 03:53:19,250 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/86c7da20-7a27-46f8-8113-f672de5059cd 2025-12-22 03:53:19,264 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/c7471d81-0aa9-430b-b964-1c50e22ca6a2 2025-12-22 03:53:19,301 - INFO - Verified VN network id 11 for VN 86c7da20-7a27-46f8-8113-f672de5059cd 2025-12-22 03:53:19,301 - INFO - Verifications in API Server for VN EVPN-VN1 passed 2025-12-22 03:53:19,301 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/86c7da20-7a27-46f8-8113-f672de5059cd 2025-12-22 03:53:19,323 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/c7471d81-0aa9-430b-b964-1c50e22ca6a2 2025-12-22 03:53:19,331 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/c7471d81-0aa9-430b-b964-1c50e22ca6a2 2025-12-22 03:53:19,341 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/78ebd4c4-e348-4729-ba5c-934796f67f75 2025-12-22 03:53:19,373 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestEvpnCasesRestart-86150708:EVPN-VN1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestEvpnCasesRestart-86150708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9711970954470967032', 'uuid-lslong': '9301048628666718669'}, 'enable': 'true', 'created': '2025-12-22T03:53:15', 'last-modified': '2025-12-22T03:53:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.500698'} 2025-12-22 03:53:19,379 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-12-22 03:53:19,384 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestEvpnCasesRestart-86150708:EVPN-VN1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestEvpnCasesRestart-86150708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9711970954470967032', 'uuid-lslong': '9301048628666718669'}, 'enable': 'true', 'created': '2025-12-22T03:53:15', 'last-modified': '2025-12-22T03:53:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.508473'} 2025-12-22 03:53:19,394 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-12-22 03:53:19,405 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestEvpnCasesRestart-86150708:EVPN-VN1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestEvpnCasesRestart-86150708', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9711970954470967032', 'uuid-lslong': '9301048628666718669'}, 'enable': 'true', 'created': '2025-12-22T03:53:15', 'last-modified': '2025-12-22T03:53:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.522212'} 2025-12-22 03:53:19,411 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-12-22 03:53:19,411 - INFO - On all control nodes, Config, RI and RT verification for VN EVPN-VN1 passed 2025-12-22 03:53:19,412 - DEBUG - ====Verifying policy data for EVPN-VN1 in API_Server ====== 2025-12-22 03:53:19,412 - DEBUG - Requesting: http://10.0.0.23:8082/domains 2025-12-22 03:53:19,418 - DEBUG - Requesting: http://10.0.0.23:8082/domain/cc89f09a-44c2-47d5-97ff-1101c2b1f822 2025-12-22 03:53:19,428 - DEBUG - Requesting: http://10.0.0.23:8082/project/6c1885ca-0041-4e8f-bf21-427922af510f 2025-12-22 03:53:19,566 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/86c7da20-7a27-46f8-8113-f672de5059cd 2025-12-22 03:53:19,576 - DEBUG - =>VN EVPN-VN1 has no policy to be verified 2025-12-22 03:53:19,576 - DEBUG - Verifying the vn in opserver 2025-12-22 03:53:19,577 - DEBUG - Verifying the default-domain:ctest-TestEvpnCasesRestart-86150708:EVPN-VN1 virtual network link through opserver 10.0.0.23 2025-12-22 03:53:19,577 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks 2025-12-22 03:53:19,927 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks 2025-12-22 03:53:19,984 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestEvpnCasesRestart-86150708:EVPN-VN1', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestEvpnCasesRestart-86150708:EVPN-VN1?flat'} 2025-12-22 03:53:19,984 - DEBUG - Verifying the default-domain:ctest-TestEvpnCasesRestart-86150708:EVPN-VN1 virtual network link through opserver 10.0.0.22 2025-12-22 03:53:19,984 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks 2025-12-22 03:53:20,281 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks 2025-12-22 03:53:20,345 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestEvpnCasesRestart-86150708:EVPN-VN1', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestEvpnCasesRestart-86150708:EVPN-VN1?flat'} 2025-12-22 03:53:20,345 - DEBUG - Verifying the default-domain:ctest-TestEvpnCasesRestart-86150708:EVPN-VN1 virtual network link through opserver 10.0.0.65 2025-12-22 03:53:20,345 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks 2025-12-22 03:53:20,663 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks 2025-12-22 03:53:20,725 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestEvpnCasesRestart-86150708:EVPN-VN1', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestEvpnCasesRestart-86150708:EVPN-VN1?flat'} 2025-12-22 03:53:20,725 - INFO - Validated that VN default-domain:ctest-TestEvpnCasesRestart-86150708:EVPN-VN1 is found in opserver 2025-12-22 03:53:20,736 - DEBUG - VRF ids for VN EVPN-VN1: {'10.0.0.19': '2'} 2025-12-22 03:53:20,807 - DEBUG - VM is in ERROR state now 2025-12-22 03:53:20,807 - WARNING - VM in error state. Asserting... 2025-12-22 03:53:20,814 - INFO - Skip interface_detach for VM EVPN_VN1_VM2 in state BUILD 2025-12-22 03:53:20,814 - INFO - Deleting VM EVPN_VN1_VM2 2025-12-22 03:53:20,858 - ERROR - VM EVPN_VN1_VM2 has failed to come up 2025-12-22 03:53:20,858 - ERROR - Nova failed to get host of the VM 2025-12-22 03:53:20,858 - ERROR - VM EVPN_VN1_VM2 has failed to come up 2025-12-22 03:53:20,858 - ERROR - Nova failed to get host of the VM 2025-12-22 03:53:20,965 - INFO - Skip interface_detach for VM EVPN_VN1_VM1 in state ERROR 2025-12-22 03:53:20,965 - INFO - Deleting VM EVPN_VN1_VM1 2025-12-22 03:53:20,965 - ERROR - VM EVPN_VN1_VM1 has failed to come up 2025-12-22 03:53:20,966 - ERROR - Fault seen in nova show is: {'code': 500, 'created': '2025-12-22T03:53:17Z', 'message': 'No valid host was found. ', 'details': 'Traceback (most recent call last):\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/nova/conductor/manager.py", line 1581, in schedule_and_build_instances\n instance_uuids, return_alternates=True)\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/nova/conductor/manager.py", line 942, in _schedule_instances\n return_alternates=return_alternates)\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/nova/scheduler/client/query.py", line 42, in select_destinations\n instance_uuids, return_objects, return_alternates)\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/nova/scheduler/rpcapi.py", line 160, in select_destinations\n return cctxt.call(ctxt, \'select_destinations\', **msg_args)\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/oslo_messaging/rpc/client.py", line 192, in call\n retry=self.retry, transport_options=self.transport_options)\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/oslo_messaging/transport.py", line 128, in _send\n transport_options=transport_options)\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/oslo_messaging/_drivers/amqpdriver.py", line 691, in send\n transport_options=transport_options)\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/oslo_messaging/_drivers/amqpdriver.py", line 681, in _send\n raise result\nnova.exception_Remote.NoValidHost_Remote: No valid host was found. \nTraceback (most recent call last):\n\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/oslo_messaging/rpc/server.py", line 241, in inner\n return func(*args, **kwargs)\n\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/nova/scheduler/manager.py", line 209, in select_destinations\n raise exception.NoValidHost(reason="")\n\nnova.exception.NoValidHost: No valid host was found. \n\n'} 2025-12-22 03:53:20,966 - INFO - Deleting VN EVPN-VN1 2025-12-22 03:53:21,024 - DEBUG - VN 86c7da20-7a27-46f8-8113-f672de5059cd still in use: Unable to complete operation on network 86c7da20-7a27-46f8-8113-f672de5059cd. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-37751a5a-f056-49c9-8b46-e47bf3983172'] 2025-12-22 03:53:21,024 - WARNING - Deleting VN EVPN-VN1 failed..Will retry 2025-12-22 03:53:23,212 - DEBUG - Response for deleting network () 2025-12-22 03:53:23,212 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/c7471d81-0aa9-430b-b964-1c50e22ca6a2 2025-12-22 03:53:23,222 - DEBUG - Response Code: 404 2025-12-22 03:53:23,222 - DEBUG - Requesting: http://10.0.0.23:8082/domains 2025-12-22 03:53:23,229 - DEBUG - Requesting: http://10.0.0.23:8082/domain/cc89f09a-44c2-47d5-97ff-1101c2b1f822 2025-12-22 03:53:23,242 - DEBUG - Requesting: http://10.0.0.23:8082/project/6c1885ca-0041-4e8f-bf21-427922af510f 2025-12-22 03:53:23,333 - INFO - Validated that VN EVPN-VN1 is not found in API Server 2025-12-22 03:53:23,343 - DEBUG - VN EVPN-VN1 is not present in Agent 10.0.0.130 2025-12-22 03:53:23,353 - DEBUG - VN EVPN-VN1 is not present in Agent 10.0.0.19 2025-12-22 03:53:23,353 - INFO - Validated that VN EVPN-VN1 is not in any agent 2025-12-22 03:53:23,358 - DEBUG - VRF 2 is not seen in agent 10.0.0.19 2025-12-22 03:53:23,365 - DEBUG - Vrouter 10.0.0.19 does not have vrf 2 for VN EVPN-VN1 2025-12-22 03:53:23,365 - INFO - Validated that all vrouters do not have the route table for VN default-domain:ctest-TestEvpnCasesRestart-86150708:EVPN-VN1 2025-12-22 03:53:23,383 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN EVPN-VN1 info 2025-12-22 03:53:24,813 - ERROR - AssertionError Python 3.9.21: /usr/bin/python3 Mon Dec 22 03:53:20 2025 A problem occurred in a Python script. Here is the sequence of function calls leading up to the error, in the order they occurred. /contrail-test/tcutils/wrappers.py in wrapper(self=, *args=(), **kwargs={}) 78 log.info('Initial checks done. Running the testcase now') 79 log.info('') 80 result = function(self, *args, **kwargs) 81 if self.inputs.upgrade: 82 pid = os.getpid() result = None function = self = args = () kwargs = {} /contrail-test/serial_scripts/evpn/test_evpn.py in test_with_vxlan_encap_agent_restart(self=) 353 Maintainer: chhandak@juniper.net 354 ''' 355 verdict=self.verify_epvn_with_agent_restart(encap='vxlan') 356 if not verdict: 357 print("collect logs for bug 1737030") verdict undefined self = self.verify_epvn_with_agent_restart = > encap undefined /contrail-test/serial_scripts/evpn/verify.py in verify_epvn_with_agent_restart(self=, encap='vxlan') 1997 node_name=compute_2)) 1998 assert vn1_fixture.verify_on_setup() 1999 assert vn1_vm1_fixture.verify_on_setup() 2000 assert vn1_vm2_fixture.verify_on_setup() 2001 assert vn1_vm1_fixture.wait_till_vm_is_up() vn1_vm1_fixture = vn1_vm1_fixture.verify_on_setup = > AssertionError: __cause__ = None __class__ = __context__ = None __delattr__ = __dict__ = {} __dir__ = __doc__ = 'Assertion failed.' __eq__ = __format__ = __ge__ = __getattribute__ = __gt__ = __hash__ = __init__ = __init_subclass__ = __le__ = __lt__ = __ne__ = __new__ = __reduce__ = __reduce_ex__ = __repr__ = __setattr__ = __setstate__ = __sizeof__ = __str__ = __subclasshook__ = __suppress_context__ = False __traceback__ = args = () with_traceback = The above is a description of an error in a Python program. Here is the original traceback: Traceback (most recent call last): File "/contrail-test/tcutils/wrappers.py", line 80, in wrapper result = function(self, *args, **kwargs) File "/contrail-test/serial_scripts/evpn/test_evpn.py", line 355, in test_with_vxlan_encap_agent_restart verdict=self.verify_epvn_with_agent_restart(encap='vxlan') File "/contrail-test/serial_scripts/evpn/verify.py", line 1999, in verify_epvn_with_agent_restart assert vn1_vm1_fixture.verify_on_setup() AssertionError 2025-12-22 03:53:24,813 - DEBUG - Skipping xmpp flap check 2025-12-22 03:53:24,813 - INFO -
2025-12-22 03:53:24,813 - INFO - END TEST : test_with_vxlan_encap_agent_restart : FAILED[0:00:11]
2025-12-22 03:53:24,813 - INFO - -------------------------------------------------------------------------------- 2025-12-22 03:53:25,573 - INFO - Deleted project: ctest-TestEvpnCasesRestart-86150708, ID : 6c1885ca-0041-4e8f-bf21-427922af510f