2025-10-10 12:14:15,835 - INFO - Domain Default found not creating 2025-10-10 12:14:16,019 - INFO - Project ctest-TestvDNSBasic0-61357995 not found, creating it 2025-10-10 12:14:16,508 - INFO - Created Project:ctest-TestvDNSBasic0-61357995, ID : 5b929620-1c98-43e6-8fbe-6e69b072f292 2025-10-10 12:14:18,080 - INFO - ================================================================================ 2025-10-10 12:14:18,080 - INFO - STARTING TEST : test_vdns_ping_same_vn 2025-10-10 12:14:18,080 - 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-10 12:14:18,225 - DEBUG - Nothing to compare xmpp stats {'10.0.0.27': {'10.20.0.254': '0'}} with 2025-10-10 12:14:18,225 - INFO - Initial checks done. Running the testcase now 2025-10-10 12:14:18,225 - INFO - 2025-10-10 12:14:18,963 - INFO - Using existing project ['default-domain', 'ctest-TestvDNSBasic0-61357995'](5b929620-1c98-43e6-8fbe-6e69b072f292) 2025-10-10 12:14:19,018 - DEBUG - Creating VDNS : ctest-vdns1-40530739 2025-10-10 12:14:19,222 - DEBUG - VDNS: ['default-domain', 'ctest-vdns1-40530739'] created succesfully 2025-10-10 12:14:19,223 - DEBUG - Verify VDNS entry is shown in control node 2025-10-10 12:14:19,229 - DEBUG - Verify VDNS entry is shown in the API server 2025-10-10 12:14:19,229 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2025-10-10 12:14:19,545 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2025-10-10 12:14:19,578 - DEBUG - Requesting: http://10.0.0.27:8082/domain/9aaeb56d-85ca-447c-89d9-59b0c8370332 2025-10-10 12:14:19,590 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-DNS/f6021525-8f86-4bdf-b694-4c7ac49783ed 2025-10-10 12:14:20,204 - DEBUG - Response for create_network : {'network': {'id': '6449ba27-6d32-4eb6-ad9e-a9a36bffeefd', 'name': 'ctest-vn1-vdns-04704429', 'tenant_id': '5b9296201c9843e68fbe6e69b072f292', 'project_id': '5b9296201c9843e68fbe6e69b072f292', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestvDNSBasic0-61357995', 'ctest-vn1-vdns-04704429'], 'port_security_enabled': True, 'description': ''}} 2025-10-10 12:14:20,437 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd07f9a15-2b1d-4198-88f9-fd25e558f893', 'name': '', 'tenant_id': '5b9296201c9843e68fbe6e69b072f292', 'network_id': '6449ba27-6d32-4eb6-ad9e-a9a36bffeefd', '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': '5b9296201c9843e68fbe6e69b072f292'}} 2025-10-10 12:14:20,459 - INFO - Created VN ctest-vn1-vdns-04704429 2025-10-10 12:14:20,519 - DEBUG - VN ctest-vn1-vdns-04704429 UUID is 6449ba27-6d32-4eb6-ad9e-a9a36bffeefd 2025-10-10 12:14:20,858 - DEBUG - Services list from nova: [, , ] 2025-10-10 12:14:22,038 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4285-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4285-1) 2025-10-10 12:14:22,039 - INFO - Waiting for VM ctest-vm1-test-18818011 to be up.. 2025-10-10 12:14:22,085 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-10-10 12:14:27,158 - DEBUG - VM is in ERROR state now 2025-10-10 12:14:27,158 - ERROR - VM in error state. 2025-10-10 12:14:27,158 - ERROR - VM is not in ACTIVE state 2025-10-10 12:14:27,249 - ERROR - Fatal Nova Exception while getting VM detail 2025-10-10 12:14:27,249 - DEBUG - None 2025-10-10 12:14:27,251 - INFO - Skip interface_detach for VM ctest-vm1-test-18818011 in state ERROR 2025-10-10 12:14:27,251 - INFO - Deleting VM ctest-vm1-test-18818011 2025-10-10 12:14:27,251 - ERROR - VM ctest-vm1-test-18818011 has failed to come up 2025-10-10 12:14:27,251 - ERROR - Fault seen in nova show is: {'code': 500, 'created': '2025-10-10T12:14:23Z', 'message': "Failure running os_vif plugin plug method: Failed to plug VIF VIFGeneric(active=True,address=02:16:c9:95:cc:6d,has_traffic_filtering=True,id=16c995cc-6d49-4958-8b30-d0ea36ce56af,network=Network(6449ba27-6d32-4eb6-ad9e-a9a36bffeefd),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=16c995cc-6d49-4958-8b30-d0ea36ce56af\', \'--instance_uuid=036254e7-cb83-4590-ad66-e135b1710d9f\', \'--vn_uuid=6449ba27-6d32-4eb6-ad9e-a9a36bffeefd\', \'--vm_project_uuid=5b9296201c9843e68fbe6e69b072f292\', \'--ip_address=10.10.10.3\', \'--ipv6_address=None\', \'--vm_name=ctest-vm1-test-18818011\', \'--mac=02:16:c9:95:cc:6d\', \'--tap_name=tap16c995cc-6d\', \'--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:16:c9:95:cc:6d,has_traffic_filtering=True,id=16c995cc-6d49-4958-8b30-d0ea36ce56af,network=Network(6449ba27-6d32-4eb6-ad9e-a9a36bffeefd),plugin=\'vrouter\',port_profile=,preserve_on_delete=False,vif_name=\'tap16c995cc-6d\'). Got error: Error during the call to vrouter-port-control: (\'vrouter-port-control\', \'--oper=add\', \'--uuid=16c995cc-6d49-4958-8b30-d0ea36ce56af\', \'--instance_uuid=036254e7-cb83-4590-ad66-e135b1710d9f\', \'--vn_uuid=6449ba27-6d32-4eb6-ad9e-a9a36bffeefd\', \'--vm_project_uuid=5b9296201c9843e68fbe6e69b072f292\', \'--ip_address=10.10.10.3\', \'--ipv6_address=None\', \'--vm_name=ctest-vm1-test-18818011\', \'--mac=02:16:c9:95:cc:6d\', \'--tap_name=tap16c995cc-6d\', \'--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:16:c9:95:cc:6d,has_traffic_filtering=True,id=16c995cc-6d49-4958-8b30-d0ea36ce56af,network=Network(6449ba27-6d32-4eb6-ad9e-a9a36bffeefd),plugin=\'vrouter\',port_profile=,preserve_on_delete=False,vif_name=\'tap16c995cc-6d\'). Got error: Error during the call to vrouter-port-control: (\'vrouter-port-control\', \'--oper=add\', \'--uuid=16c995cc-6d49-4958-8b30-d0ea36ce56af\', \'--instance_uuid=036254e7-cb83-4590-ad66-e135b1710d9f\', \'--vn_uuid=6449ba27-6d32-4eb6-ad9e-a9a36bffeefd\', \'--vm_project_uuid=5b9296201c9843e68fbe6e69b072f292\', \'--ip_address=10.10.10.3\', \'--ipv6_address=None\', \'--vm_name=ctest-vm1-test-18818011\', \'--mac=02:16:c9:95:cc:6d\', \'--tap_name=tap16c995cc-6d\', \'--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 036254e7-cb83-4590-ad66-e135b1710d9f was re-scheduled: Failure running os_vif plugin plug method: Failed to plug VIF VIFGeneric(active=True,address=02:16:c9:95:cc:6d,has_traffic_filtering=True,id=16c995cc-6d49-4958-8b30-d0ea36ce56af,network=Network(6449ba27-6d32-4eb6-ad9e-a9a36bffeefd),plugin=\'vrouter\',port_profile=,preserve_on_delete=False,vif_name=\'tap16c995cc-6d\'). Got error: Error during the call to vrouter-port-control: (\'vrouter-port-control\', \'--oper=add\', \'--uuid=16c995cc-6d49-4958-8b30-d0ea36ce56af\', \'--instance_uuid=036254e7-cb83-4590-ad66-e135b1710d9f\', \'--vn_uuid=6449ba27-6d32-4eb6-ad9e-a9a36bffeefd\', \'--vm_project_uuid=5b9296201c9843e68fbe6e69b072f292\', \'--ip_address=10.10.10.3\', \'--ipv6_address=None\', \'--vm_name=ctest-vm1-test-18818011\', \'--mac=02:16:c9:95:cc:6d\', \'--tap_name=tap16c995cc-6d\', \'--port_type=NovaVMPort\', \'--tx_vlan_id=-1\', \'--rx_vlan_id=-1\')\n'} 2025-10-10 12:14:27,251 - INFO - Deleting VN ctest-vn1-vdns-04704429 2025-10-10 12:14:27,384 - DEBUG - Response for deleting network () 2025-10-10 12:14:27,429 - DEBUG - Deleting VDNS Entry: ctest-vdns1-40530739 2025-10-10 12:14:27,502 - INFO - VDNS info: ['default-domain', 'ctest-vdns1-40530739'] deleted successfully. 2025-10-10 12:14:27,503 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2025-10-10 12:14:27,512 - DEBUG - Requesting: http://10.0.0.27:8082/domain/9aaeb56d-85ca-447c-89d9-59b0c8370332 2025-10-10 12:14:27,528 - DEBUG - VDNS information not found in API server 2025-10-10 12:14:27,528 - INFO - VDNS information ctest-vdns1-40530739 removed from the API Server 2025-10-10 12:14:27,534 - INFO - VDNS information ctest-vdns1-40530739 removed in the Control node 2025-10-10 12:14:27,534 - DEBUG - Skipping the deletion of Project ['default-domain', 'ctest-TestvDNSBasic0-61357995'] 2025-10-10 12:14:27,660 - ERROR - AssertionError Python 3.9.21: /usr/bin/python3 Fri Oct 10 12:14:27 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-18818011': } vm_name = 'ctest-vm1-test-18818011' ].wait_till_vm_is_up undefined AssertionError: VM ctest-vm1-test-18818011 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-18818011 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-18818011 failed to come up 2025-10-10 12:14:27,667 - DEBUG - No XMPP flaps were noticed during the test 2025-10-10 12:14:27,667 - INFO -
2025-10-10 12:14:27,667 - INFO - END TEST : test_vdns_ping_same_vn : FAILED[0:00:09]
2025-10-10 12:14:27,667 - INFO - -------------------------------------------------------------------------------- 2025-10-10 12:14:28,410 - INFO - Deleted project: ctest-TestvDNSBasic0-61357995, ID : 5b929620-1c98-43e6-8fbe-6e69b072f292