2025-09-18 10:29:57,139 - INFO - Domain Default found not creating 2025-09-18 10:29:57,280 - INFO - Project ctest-TestvDNSBasic0-19768892 not found, creating it 2025-09-18 10:29:57,711 - INFO - Created Project:ctest-TestvDNSBasic0-19768892, ID : f339f416-bb8e-45a8-8e37-7990ea3a8428 2025-09-18 10:29:59,066 - INFO - ================================================================================ 2025-09-18 10:29:59,067 - INFO - STARTING TEST : test_vdns_ping_same_vn 2025-09-18 10:29:59,067 - 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-09-18 10:29:59,194 - DEBUG - Nothing to compare xmpp stats {'10.0.0.254': {'10.20.0.17': '0'}} with 2025-09-18 10:29:59,194 - INFO - Initial checks done. Running the testcase now 2025-09-18 10:29:59,194 - INFO - 2025-09-18 10:29:59,869 - INFO - Using existing project ['default-domain', 'ctest-TestvDNSBasic0-19768892'](f339f416-bb8e-45a8-8e37-7990ea3a8428) 2025-09-18 10:29:59,923 - DEBUG - Creating VDNS : ctest-vdns1-52181661 2025-09-18 10:30:00,127 - DEBUG - VDNS: ['default-domain', 'ctest-vdns1-52181661'] created succesfully 2025-09-18 10:30:00,128 - DEBUG - Verify VDNS entry is shown in control node 2025-09-18 10:30:00,133 - DEBUG - Verify VDNS entry is shown in the API server 2025-09-18 10:30:00,134 - DEBUG - Requesting: http://10.0.0.254:8082/domains 2025-09-18 10:30:00,425 - DEBUG - Requesting: http://10.0.0.254:8082/domains 2025-09-18 10:30:00,454 - DEBUG - Requesting: http://10.0.0.254:8082/domain/cefcf260-3738-4f46-b0c5-1a5d683f924c 2025-09-18 10:30:00,468 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-DNS/cfcf69ea-c60d-461d-8cc4-15557ab4baac 2025-09-18 10:30:01,053 - DEBUG - Response for create_network : {'network': {'id': '1fb37747-7012-4f47-94bf-3a18f58fd13b', 'name': 'ctest-vn1-vdns-60677901', 'tenant_id': 'f339f416bb8e45a88e377990ea3a8428', 'project_id': 'f339f416bb8e45a88e377990ea3a8428', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestvDNSBasic0-19768892', 'ctest-vn1-vdns-60677901'], 'port_security_enabled': True, 'description': ''}} 2025-09-18 10:30:01,248 - DEBUG - Response for create_subnet : {'subnet': {'id': '2d9ac365-1d9b-4294-8f0f-a8e42b7f9214', 'name': '', 'tenant_id': 'f339f416bb8e45a88e377990ea3a8428', 'network_id': '1fb37747-7012-4f47-94bf-3a18f58fd13b', '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': 'f339f416bb8e45a88e377990ea3a8428'}} 2025-09-18 10:30:01,271 - INFO - Created VN ctest-vn1-vdns-60677901 2025-09-18 10:30:01,324 - DEBUG - VN ctest-vn1-vdns-60677901 UUID is 1fb37747-7012-4f47-94bf-3a18f58fd13b 2025-09-18 10:30:01,609 - DEBUG - Services list from nova: [, , ] 2025-09-18 10:30:02,654 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4136-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4136-1) 2025-09-18 10:30:02,654 - INFO - Waiting for VM ctest-vm1-test-28567112 to be up.. 2025-09-18 10:30:02,693 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-09-18 10:30:07,746 - DEBUG - VM is in ERROR state now 2025-09-18 10:30:07,746 - ERROR - VM in error state. 2025-09-18 10:30:07,746 - ERROR - VM is not in ACTIVE state 2025-09-18 10:30:07,824 - ERROR - Fatal Nova Exception while getting VM detail 2025-09-18 10:30:07,824 - DEBUG - None 2025-09-18 10:30:07,825 - INFO - Skip interface_detach for VM ctest-vm1-test-28567112 in state ERROR 2025-09-18 10:30:07,826 - INFO - Deleting VM ctest-vm1-test-28567112 2025-09-18 10:30:07,826 - ERROR - VM ctest-vm1-test-28567112 has failed to come up 2025-09-18 10:30:07,826 - ERROR - Fault seen in nova show is: {'code': 500, 'created': '2025-09-18T10:30:03Z', 'message': "Failure running os_vif plugin plug method: Failed to plug VIF VIFGeneric(active=True,address=02:10:f2:af:5d:10,has_traffic_filtering=True,id=10f2af5d-1039-474a-a8a1-7b91ba592c09,network=Network(1fb37747-7012-4f47-94bf-3a18f58fd13b),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=10f2af5d-1039-474a-a8a1-7b91ba592c09\', \'--instance_uuid=6a390642-00eb-4772-9f75-b13113bc4183\', \'--vn_uuid=1fb37747-7012-4f47-94bf-3a18f58fd13b\', \'--vm_project_uuid=f339f416bb8e45a88e377990ea3a8428\', \'--ip_address=10.10.10.3\', \'--ipv6_address=None\', \'--vm_name=ctest-vm1-test-28567112\', \'--mac=02:10:f2:af:5d:10\', \'--tap_name=tap10f2af5d-10\', \'--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:10:f2:af:5d:10,has_traffic_filtering=True,id=10f2af5d-1039-474a-a8a1-7b91ba592c09,network=Network(1fb37747-7012-4f47-94bf-3a18f58fd13b),plugin=\'vrouter\',port_profile=,preserve_on_delete=False,vif_name=\'tap10f2af5d-10\'). Got error: Error during the call to vrouter-port-control: (\'vrouter-port-control\', \'--oper=add\', \'--uuid=10f2af5d-1039-474a-a8a1-7b91ba592c09\', \'--instance_uuid=6a390642-00eb-4772-9f75-b13113bc4183\', \'--vn_uuid=1fb37747-7012-4f47-94bf-3a18f58fd13b\', \'--vm_project_uuid=f339f416bb8e45a88e377990ea3a8428\', \'--ip_address=10.10.10.3\', \'--ipv6_address=None\', \'--vm_name=ctest-vm1-test-28567112\', \'--mac=02:10:f2:af:5d:10\', \'--tap_name=tap10f2af5d-10\', \'--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:10:f2:af:5d:10,has_traffic_filtering=True,id=10f2af5d-1039-474a-a8a1-7b91ba592c09,network=Network(1fb37747-7012-4f47-94bf-3a18f58fd13b),plugin=\'vrouter\',port_profile=,preserve_on_delete=False,vif_name=\'tap10f2af5d-10\'). Got error: Error during the call to vrouter-port-control: (\'vrouter-port-control\', \'--oper=add\', \'--uuid=10f2af5d-1039-474a-a8a1-7b91ba592c09\', \'--instance_uuid=6a390642-00eb-4772-9f75-b13113bc4183\', \'--vn_uuid=1fb37747-7012-4f47-94bf-3a18f58fd13b\', \'--vm_project_uuid=f339f416bb8e45a88e377990ea3a8428\', \'--ip_address=10.10.10.3\', \'--ipv6_address=None\', \'--vm_name=ctest-vm1-test-28567112\', \'--mac=02:10:f2:af:5d:10\', \'--tap_name=tap10f2af5d-10\', \'--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 6a390642-00eb-4772-9f75-b13113bc4183 was re-scheduled: Failure running os_vif plugin plug method: Failed to plug VIF VIFGeneric(active=True,address=02:10:f2:af:5d:10,has_traffic_filtering=True,id=10f2af5d-1039-474a-a8a1-7b91ba592c09,network=Network(1fb37747-7012-4f47-94bf-3a18f58fd13b),plugin=\'vrouter\',port_profile=,preserve_on_delete=False,vif_name=\'tap10f2af5d-10\'). Got error: Error during the call to vrouter-port-control: (\'vrouter-port-control\', \'--oper=add\', \'--uuid=10f2af5d-1039-474a-a8a1-7b91ba592c09\', \'--instance_uuid=6a390642-00eb-4772-9f75-b13113bc4183\', \'--vn_uuid=1fb37747-7012-4f47-94bf-3a18f58fd13b\', \'--vm_project_uuid=f339f416bb8e45a88e377990ea3a8428\', \'--ip_address=10.10.10.3\', \'--ipv6_address=None\', \'--vm_name=ctest-vm1-test-28567112\', \'--mac=02:10:f2:af:5d:10\', \'--tap_name=tap10f2af5d-10\', \'--port_type=NovaVMPort\', \'--tx_vlan_id=-1\', \'--rx_vlan_id=-1\')\n'} 2025-09-18 10:30:07,826 - INFO - Deleting VN ctest-vn1-vdns-60677901 2025-09-18 10:30:07,922 - DEBUG - Response for deleting network () 2025-09-18 10:30:07,950 - DEBUG - Deleting VDNS Entry: ctest-vdns1-52181661 2025-09-18 10:30:08,027 - INFO - VDNS info: ['default-domain', 'ctest-vdns1-52181661'] deleted successfully. 2025-09-18 10:30:08,028 - DEBUG - Requesting: http://10.0.0.254:8082/domains 2025-09-18 10:30:08,033 - DEBUG - Requesting: http://10.0.0.254:8082/domain/cefcf260-3738-4f46-b0c5-1a5d683f924c 2025-09-18 10:30:08,045 - DEBUG - VDNS information not found in API server 2025-09-18 10:30:08,045 - INFO - VDNS information ctest-vdns1-52181661 removed from the API Server 2025-09-18 10:30:08,049 - INFO - VDNS information ctest-vdns1-52181661 removed in the Control node 2025-09-18 10:30:08,050 - DEBUG - Skipping the deletion of Project ['default-domain', 'ctest-TestvDNSBasic0-19768892'] 2025-09-18 10:30:08,169 - ERROR - AssertionError Python 3.9.21: /usr/bin/python3 Thu Sep 18 10:30:07 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-28567112': } vm_name = 'ctest-vm1-test-28567112' ].wait_till_vm_is_up undefined AssertionError: VM ctest-vm1-test-28567112 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-28567112 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-28567112 failed to come up 2025-09-18 10:30:08,175 - DEBUG - No XMPP flaps were noticed during the test 2025-09-18 10:30:08,175 - INFO -
2025-09-18 10:30:08,175 - INFO - END TEST : test_vdns_ping_same_vn : FAILED[0:00:09]
2025-09-18 10:30:08,175 - INFO - -------------------------------------------------------------------------------- 2025-09-18 10:30:08,855 - INFO - Deleted project: ctest-TestvDNSBasic0-19768892, ID : f339f416-bb8e-45a8-8e37-7990ea3a8428