2025-10-11 16:57:47,996 - INFO - Domain Default found not creating 2025-10-11 16:57:48,185 - INFO - Project ctest-TestvDNSBasic0-65376417 not found, creating it 2025-10-11 16:57:48,669 - INFO - Created Project:ctest-TestvDNSBasic0-65376417, ID : 45d75aa7-69c4-40ff-9376-88c4acd2ba1c 2025-10-11 16:57:50,119 - INFO - ================================================================================ 2025-10-11 16:57:50,119 - INFO - STARTING TEST : test_vdns_ping_same_vn 2025-10-11 16:57:50,119 - INFO - TEST DESCRIPTION : Test:- Test vdns functionality. On VM launch agent should dynamically update dns records to dns agent 1. Create vDNS server 2. Create IPAM using above vDNS data 3. Create VN using above IPAM and launch 2 VM's within it 4. Ping between these 2 VM's using dns name 5. Try to delete vDNS server which has IPAM back-reference[Negative case] 6. Add CNAME VDNS record for vm1-test and verify we able to ping by alias name Pass criteria: Step 4,5 and 6 should pass Maintainer: cf-test@juniper.net 2025-10-11 16:57:50,249 - DEBUG - Nothing to compare xmpp stats {'10.0.0.254': {'10.20.0.254': '0'}} with 2025-10-11 16:57:50,249 - INFO - Initial checks done. Running the testcase now 2025-10-11 16:57:50,249 - INFO - 2025-10-11 16:57:50,938 - INFO - Using existing project ['default-domain', 'ctest-TestvDNSBasic0-65376417'](45d75aa7-69c4-40ff-9376-88c4acd2ba1c) 2025-10-11 16:57:50,993 - DEBUG - Creating VDNS : ctest-vdns1-63813858 2025-10-11 16:57:51,206 - DEBUG - VDNS: ['default-domain', 'ctest-vdns1-63813858'] created succesfully 2025-10-11 16:57:51,206 - DEBUG - Verify VDNS entry is shown in control node 2025-10-11 16:57:51,212 - DEBUG - Verify VDNS entry is shown in the API server 2025-10-11 16:57:51,213 - DEBUG - Requesting: http://10.0.0.254:8082/domains 2025-10-11 16:57:51,510 - DEBUG - Requesting: http://10.0.0.254:8082/domains 2025-10-11 16:57:51,542 - DEBUG - Requesting: http://10.0.0.254:8082/domain/1c888893-7c1f-46a1-80b1-cf2038627f3c 2025-10-11 16:57:51,553 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-DNS/b44dcdfa-d2cb-4ad9-b2c7-3bdfec629e03 2025-10-11 16:57:52,107 - DEBUG - Response for create_network : {'network': {'id': 'd13caaae-1b6f-4f3d-a986-1e0ff754125b', 'name': 'ctest-vn1-vdns-04263251', 'tenant_id': '45d75aa769c440ff937688c4acd2ba1c', 'project_id': '45d75aa769c440ff937688c4acd2ba1c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestvDNSBasic0-65376417', 'ctest-vn1-vdns-04263251'], 'port_security_enabled': True, 'description': ''}} 2025-10-11 16:57:52,300 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c7f616a0-52b7-40bc-99dd-b5ab2ee9ff4b', 'name': '', 'tenant_id': '45d75aa769c440ff937688c4acd2ba1c', 'network_id': 'd13caaae-1b6f-4f3d-a986-1e0ff754125b', 'ip_version': 4, 'cidr': '10.10.10.0/24', 'allocation_pools': [{'start': '10.10.10.2', 'end': '10.10.10.254'}], 'gateway_ip': '10.10.10.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '10.10.10.2', 'tags': [], 'project_id': '45d75aa769c440ff937688c4acd2ba1c'}} 2025-10-11 16:57:52,318 - INFO - Created VN ctest-vn1-vdns-04263251 2025-10-11 16:57:52,370 - DEBUG - VN ctest-vn1-vdns-04263251 UUID is d13caaae-1b6f-4f3d-a986-1e0ff754125b 2025-10-11 16:57:52,646 - DEBUG - Services list from nova: [, , ] 2025-10-11 16:57:53,687 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4287-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4287-1) 2025-10-11 16:57:53,687 - INFO - Waiting for VM ctest-vm1-test-19314772 to be up.. 2025-10-11 16:57:53,730 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-10-11 16:57:58,790 - DEBUG - VM is in ERROR state now 2025-10-11 16:57:58,790 - ERROR - VM in error state. 2025-10-11 16:57:58,790 - ERROR - VM is not in ACTIVE state 2025-10-11 16:57:58,870 - ERROR - Fatal Nova Exception while getting VM detail 2025-10-11 16:57:58,870 - DEBUG - None 2025-10-11 16:57:58,872 - INFO - Skip interface_detach for VM ctest-vm1-test-19314772 in state ERROR 2025-10-11 16:57:58,872 - INFO - Deleting VM ctest-vm1-test-19314772 2025-10-11 16:57:58,872 - ERROR - VM ctest-vm1-test-19314772 has failed to come up 2025-10-11 16:57:58,872 - ERROR - Fault seen in nova show is: {'code': 500, 'created': '2025-10-11T16:57:55Z', 'message': "Failure running os_vif plugin plug method: Failed to plug VIF VIFGeneric(active=True,address=02:ac:26:4d:65:b4,has_traffic_filtering=True,id=ac264d65-b40a-437a-9d9a-0caa0c13d5f3,network=Network(d13caaae-1b6f-4f3d-a986-1e0ff754125b),plugin='vrouter',port_p", 'details': 'Traceback (most recent call last):\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/os_vif/__init__.py", line 77, in plug\n plugin.plug(vif, instance_info)\n File "/opt/plugin/site-packages/vif_plug_vrouter/vrouter.py", line 271, in plug\n self._vrouter_port_add(instance_info, vif)\n File "/opt/plugin/site-packages/vif_plug_vrouter/vrouter.py", line 263, in _vrouter_port_add\n vhostuser_socket, vhostuser_mode)\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/oslo_privsep/priv_context.py", line 272, in _wrap\n r_call_timeout)\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/oslo_privsep/daemon.py", line 215, in remote_call\n raise exc_type(*result[2])\nvif_plug_vrouter.exception.VrouterPortControlError: Error during the call to vrouter-port-control: (\'vrouter-port-control\', \'--oper=add\', \'--uuid=ac264d65-b40a-437a-9d9a-0caa0c13d5f3\', \'--instance_uuid=750556ea-dea3-4475-b8ff-2a9c0d1bbe2a\', \'--vn_uuid=d13caaae-1b6f-4f3d-a986-1e0ff754125b\', \'--vm_project_uuid=45d75aa769c440ff937688c4acd2ba1c\', \'--ip_address=10.10.10.3\', \'--ipv6_address=None\', \'--vm_name=ctest-vm1-test-19314772\', \'--mac=02:ac:26:4d:65:b4\', \'--tap_name=tapac264d65-b4\', \'--port_type=NovaVMPort\', \'--tx_vlan_id=-1\', \'--rx_vlan_id=-1\')\n\nDuring handling of the above exception, another exception occurred:\n\nTraceback (most recent call last):\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/nova/virt/libvirt/vif.py", line 699, in _plug_os_vif\n os_vif.plug(vif, instance_info)\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/os_vif/__init__.py", line 82, in plug\n raise os_vif.exception.PlugException(vif=vif, err=err)\nos_vif.exception.PlugException: Failed to plug VIF VIFGeneric(active=True,address=02:ac:26:4d:65:b4,has_traffic_filtering=True,id=ac264d65-b40a-437a-9d9a-0caa0c13d5f3,network=Network(d13caaae-1b6f-4f3d-a986-1e0ff754125b),plugin=\'vrouter\',port_profile=,preserve_on_delete=False,vif_name=\'tapac264d65-b4\'). Got error: Error during the call to vrouter-port-control: (\'vrouter-port-control\', \'--oper=add\', \'--uuid=ac264d65-b40a-437a-9d9a-0caa0c13d5f3\', \'--instance_uuid=750556ea-dea3-4475-b8ff-2a9c0d1bbe2a\', \'--vn_uuid=d13caaae-1b6f-4f3d-a986-1e0ff754125b\', \'--vm_project_uuid=45d75aa769c440ff937688c4acd2ba1c\', \'--ip_address=10.10.10.3\', \'--ipv6_address=None\', \'--vm_name=ctest-vm1-test-19314772\', \'--mac=02:ac:26:4d:65:b4\', \'--tap_name=tapac264d65-b4\', \'--port_type=NovaVMPort\', \'--tx_vlan_id=-1\', \'--rx_vlan_id=-1\')\n\nDuring handling of the above exception, another exception occurred:\n\nTraceback (most recent call last):\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/nova/compute/manager.py", line 2521, in _build_and_run_instance\n accel_info=accel_info)\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/nova/virt/libvirt/driver.py", line 4339, in spawn\n cleanup_instance_disks=created_disks)\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/nova/virt/libvirt/driver.py", line 7523, in _create_guest_with_network\n cleanup_instance_disks=cleanup_instance_disks)\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/oslo_utils/excutils.py", line 227, in __exit__\n self.force_reraise()\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/oslo_utils/excutils.py", line 200, in force_reraise\n raise self.value\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/nova/virt/libvirt/driver.py", line 7492, in _create_guest_with_network\n self.plug_vifs(instance, network_info)\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/nova/virt/libvirt/driver.py", line 1386, in plug_vifs\n self.vif_driver.plug(instance, vif)\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/nova/virt/libvirt/vif.py", line 723, in plug\n self._plug_os_vif(instance, vif_obj)\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/nova/virt/libvirt/vif.py", line 703, in _plug_os_vif\n raise exception.InternalError(msg)\nnova.exception.InternalError: Failure running os_vif plugin plug method: Failed to plug VIF VIFGeneric(active=True,address=02:ac:26:4d:65:b4,has_traffic_filtering=True,id=ac264d65-b40a-437a-9d9a-0caa0c13d5f3,network=Network(d13caaae-1b6f-4f3d-a986-1e0ff754125b),plugin=\'vrouter\',port_profile=,preserve_on_delete=False,vif_name=\'tapac264d65-b4\'). Got error: Error during the call to vrouter-port-control: (\'vrouter-port-control\', \'--oper=add\', \'--uuid=ac264d65-b40a-437a-9d9a-0caa0c13d5f3\', \'--instance_uuid=750556ea-dea3-4475-b8ff-2a9c0d1bbe2a\', \'--vn_uuid=d13caaae-1b6f-4f3d-a986-1e0ff754125b\', \'--vm_project_uuid=45d75aa769c440ff937688c4acd2ba1c\', \'--ip_address=10.10.10.3\', \'--ipv6_address=None\', \'--vm_name=ctest-vm1-test-19314772\', \'--mac=02:ac:26:4d:65:b4\', \'--tap_name=tapac264d65-b4\', \'--port_type=NovaVMPort\', \'--tx_vlan_id=-1\', \'--rx_vlan_id=-1\')\n\nDuring handling of the above exception, another exception occurred:\n\nTraceback (most recent call last):\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/nova/compute/manager.py", line 2337, in _do_build_and_run_instance\n filter_properties, request_spec, accel_uuids)\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/nova/compute/manager.py", line 2625, in _build_and_run_instance\n instance_uuid=instance.uuid, reason=str(e))\nnova.exception.RescheduledException: Build of instance 750556ea-dea3-4475-b8ff-2a9c0d1bbe2a was re-scheduled: Failure running os_vif plugin plug method: Failed to plug VIF VIFGeneric(active=True,address=02:ac:26:4d:65:b4,has_traffic_filtering=True,id=ac264d65-b40a-437a-9d9a-0caa0c13d5f3,network=Network(d13caaae-1b6f-4f3d-a986-1e0ff754125b),plugin=\'vrouter\',port_profile=,preserve_on_delete=False,vif_name=\'tapac264d65-b4\'). Got error: Error during the call to vrouter-port-control: (\'vrouter-port-control\', \'--oper=add\', \'--uuid=ac264d65-b40a-437a-9d9a-0caa0c13d5f3\', \'--instance_uuid=750556ea-dea3-4475-b8ff-2a9c0d1bbe2a\', \'--vn_uuid=d13caaae-1b6f-4f3d-a986-1e0ff754125b\', \'--vm_project_uuid=45d75aa769c440ff937688c4acd2ba1c\', \'--ip_address=10.10.10.3\', \'--ipv6_address=None\', \'--vm_name=ctest-vm1-test-19314772\', \'--mac=02:ac:26:4d:65:b4\', \'--tap_name=tapac264d65-b4\', \'--port_type=NovaVMPort\', \'--tx_vlan_id=-1\', \'--rx_vlan_id=-1\')\n'} 2025-10-11 16:57:58,872 - INFO - Deleting VN ctest-vn1-vdns-04263251 2025-10-11 16:57:59,008 - DEBUG - Response for deleting network () 2025-10-11 16:57:59,039 - DEBUG - Deleting VDNS Entry: ctest-vdns1-63813858 2025-10-11 16:57:59,109 - INFO - VDNS info: ['default-domain', 'ctest-vdns1-63813858'] deleted successfully. 2025-10-11 16:57:59,110 - DEBUG - Requesting: http://10.0.0.254:8082/domains 2025-10-11 16:57:59,117 - DEBUG - Requesting: http://10.0.0.254:8082/domain/1c888893-7c1f-46a1-80b1-cf2038627f3c 2025-10-11 16:57:59,128 - DEBUG - VDNS information not found in API server 2025-10-11 16:57:59,128 - INFO - VDNS information ctest-vdns1-63813858 removed from the API Server 2025-10-11 16:57:59,134 - INFO - VDNS information ctest-vdns1-63813858 removed in the Control node 2025-10-11 16:57:59,134 - DEBUG - Skipping the deletion of Project ['default-domain', 'ctest-TestvDNSBasic0-65376417'] 2025-10-11 16:57:59,259 - ERROR - AssertionError Python 3.9.21: /usr/bin/python3 Sat Oct 11 16:57:58 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/scripts/vdns/test_vdns_basic.py in test_vdns_ping_same_vn(self=) 94 vm_fixture[vm_name] = self.useFixture( 95 VMFixture(project_name=self.inputs.project_name, connections=self.connections, vn_obj=vn_quantum_obj, vm_name=vm_name,image_name='ubuntu-traffic')) 96 assert vm_fixture[vm_name].wait_till_vm_is_up(), ('VM %s' 97 ' failed to come up' % (vm_name)) 98 vm_ip = vm_fixture[vm_name].get_vm_ip_from_vm( vm_fixture = {'ctest-vm1-test-19314772': } vm_name = 'ctest-vm1-test-19314772' ].wait_till_vm_is_up undefined AssertionError: VM ctest-vm1-test-19314772 failed to come up __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 = ('VM ctest-vm1-test-19314772 failed to come up',) 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/scripts/vdns/test_vdns_basic.py", line 96, in test_vdns_ping_same_vn assert vm_fixture[vm_name].wait_till_vm_is_up(), ('VM %s' AssertionError: VM ctest-vm1-test-19314772 failed to come up 2025-10-11 16:57:59,266 - DEBUG - No XMPP flaps were noticed during the test 2025-10-11 16:57:59,266 - INFO -
2025-10-11 16:57:59,266 - INFO - END TEST : test_vdns_ping_same_vn : FAILED[0:00:09]
2025-10-11 16:57:59,266 - INFO - -------------------------------------------------------------------------------- 2025-10-11 16:57:59,924 - INFO - Deleted project: ctest-TestvDNSBasic0-65376417, ID : 45d75aa7-69c4-40ff-9376-88c4acd2ba1c