2025-07-22 18:07:00,871 - INFO - Domain Default found not creating 2025-07-22 18:07:01,036 - INFO - Project ctest-TestIpVlanLearning-38127045 not found, creating it 2025-07-22 18:07:01,602 - INFO - Created Project:ctest-TestIpVlanLearning-38127045, ID : a2f228cd-1932-4233-8e2d-c363a8697520 2025-07-22 18:07:03,137 - DEBUG - Services list from nova: [, , , ] 2025-07-22 18:07:03,137 - DEBUG - Hosts: {'nova': ['an-jenkins-deploy-platform-ansible-os-3693-2', 'an-jenkins-deploy-platform-ansible-os-3693-1']} 2025-07-22 18:07:03,138 - DEBUG - Hosts: {'nova': ['an-jenkins-deploy-platform-ansible-os-3693-2', 'an-jenkins-deploy-platform-ansible-os-3693-1']} 2025-07-22 18:07:04,188 - DEBUG - Response for create_network : {'network': {'id': '1dcc52df-9687-42d1-bcfb-037324d8c838', 'name': 'ctest-vn1-51174124', 'tenant_id': 'a2f228cd193242338e2dc363a8697520', 'project_id': 'a2f228cd193242338e2dc363a8697520', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestIpVlanLearning-38127045', 'ctest-vn1-51174124'], 'port_security_enabled': True, 'description': ''}} 2025-07-22 18:07:04,570 - DEBUG - Response for create_subnet : {'subnet': {'id': '35ae2c50-807c-4f2c-aac7-27f78ecc42bb', 'name': '', 'tenant_id': 'a2f228cd193242338e2dc363a8697520', 'network_id': '1dcc52df-9687-42d1-bcfb-037324d8c838', 'ip_version': 4, 'cidr': '199.164.192.0/24', 'allocation_pools': [{'start': '199.164.192.2', 'end': '199.164.192.254'}], 'gateway_ip': '199.164.192.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '199.164.192.2', 'tags': [], 'project_id': 'a2f228cd193242338e2dc363a8697520'}} 2025-07-22 18:07:04,606 - INFO - Created VN ctest-vn1-51174124 2025-07-22 18:07:04,627 - DEBUG - VN ctest-vn1-51174124 UUID is 1dcc52df-9687-42d1-bcfb-037324d8c838 2025-07-22 18:07:05,039 - DEBUG - Response for create_network : {'network': {'id': 'e689889b-e52e-41e8-bba7-111ed792e998', 'name': 'ctest-vn2-14284900', 'tenant_id': 'a2f228cd193242338e2dc363a8697520', 'project_id': 'a2f228cd193242338e2dc363a8697520', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestIpVlanLearning-38127045', 'ctest-vn2-14284900'], 'port_security_enabled': True, 'description': ''}} 2025-07-22 18:07:05,325 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c205154d-f5a9-480d-b81f-24c21912e724', 'name': '', 'tenant_id': 'a2f228cd193242338e2dc363a8697520', 'network_id': 'e689889b-e52e-41e8-bba7-111ed792e998', 'ip_version': 4, 'cidr': '72.184.48.0/24', 'allocation_pools': [{'start': '72.184.48.2', 'end': '72.184.48.254'}], 'gateway_ip': '72.184.48.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '72.184.48.2', 'tags': [], 'project_id': 'a2f228cd193242338e2dc363a8697520'}} 2025-07-22 18:07:05,353 - INFO - Created VN ctest-vn2-14284900 2025-07-22 18:07:05,368 - DEBUG - VN ctest-vn2-14284900 UUID is e689889b-e52e-41e8-bba7-111ed792e998 2025-07-22 18:07:05,466 - DEBUG - Updating mac_ip_learning flag on VN default-domain:ctest-TestIpVlanLearning-38127045:ctest-vn1-51174124 to True 2025-07-22 18:07:05,608 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/1dcc52df-9687-42d1-bcfb-037324d8c838 2025-07-22 18:07:05,926 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/1dcc52df-9687-42d1-bcfb-037324d8c838 2025-07-22 18:07:05,969 - DEBUG - Updating mac_ip_learning flag on VN default-domain:ctest-TestIpVlanLearning-38127045:ctest-vn2-14284900 to True 2025-07-22 18:07:06,092 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/e689889b-e52e-41e8-bba7-111ed792e998 2025-07-22 18:07:06,292 - DEBUG - Image by name crpd-ipvlan not found 2025-07-22 18:07:06,292 - DEBUG - Installing image crpd-ipvlan 2025-07-22 18:07:06,292 - DEBUG - Image info {'name': 'docker-crpd-ipvlan2.qcow2', 'webserver': '', 'location': '/images/crpd/', 'username': 'centos', 'password': 'centos', 'type': 'os', 'flavor': 'contrail_crpd', 'params': '--container-format bare --disk-format qcow2 --property vmware_disktype="sparse" --property vmware_adaptertype="ide"', 'name_docker': 'phusion-baseimage-enablesshd'} 2025-07-22 18:07:06,292 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/crpd//docker-crpd-ipvlan2.qcow2 2025-07-22 18:07:06,293 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/crpd//docker-crpd-ipvlan2.qcow2 2025-07-22 18:07:06,307 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: wget -nv http://nexus.opensdn.io/repository///images/crpd//docker-crpd-ipvlan2.qcow2 -O /contrail-test/images/docker-crpd-ipvlan2.qcow2 2025-07-22 18:07:06,460 - DEBUG - Image has been downloaded to /contrail-test/images/docker-crpd-ipvlan2.qcow2 2025-07-22 18:07:06,460 - DEBUG - Try to create image in user context 2025-07-22 18:07:06,564 - DEBUG - Image has been created in user context 2025-07-22 18:07:06,564 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: rm -f /contrail-test/images/docker-crpd-ipvlan2.qcow2 2025-07-22 18:07:06,577 - DEBUG - Downloaded image has been removed 2025-07-22 18:07:06,591 - DEBUG - Uploaded image status = active 2025-07-22 18:07:07,850 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3693-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3693-1) 2025-07-22 18:07:09,225 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3693-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3693-1) 2025-07-22 18:07:10,414 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3693-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3693-2) 2025-07-22 18:07:11,589 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3693-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3693-2) 2025-07-22 18:07:11,589 - INFO - Waiting for VM ctest-crpd-vm1-04536432 to be up.. 2025-07-22 18:07:11,644 - DEBUG - VM is in ERROR state now 2025-07-22 18:07:11,645 - ERROR - VM in error state. 2025-07-22 18:07:11,645 - ERROR - VM is not in ACTIVE state 2025-07-22 18:07:11,738 - ERROR - Fatal Nova Exception while getting VM detail 2025-07-22 18:07:11,738 - DEBUG - None 2025-07-22 18:07:11,739 - INFO - Skip interface_detach for VM ctest-crpd-vm4-18634607 in state BUILD 2025-07-22 18:07:11,740 - INFO - Deleting VM ctest-crpd-vm4-18634607 2025-07-22 18:07:11,763 - ERROR - VM ctest-crpd-vm4-18634607 has failed to come up 2025-07-22 18:07:11,764 - ERROR - Nova failed to get host of the VM 2025-07-22 18:07:11,764 - ERROR - VM ctest-crpd-vm4-18634607 has failed to come up 2025-07-22 18:07:11,764 - ERROR - Nova failed to get host of the VM 2025-07-22 18:07:11,953 - INFO - Skip interface_detach for VM ctest-crpd-vm3-37084472 in state BUILD 2025-07-22 18:07:11,953 - INFO - Deleting VM ctest-crpd-vm3-37084472 2025-07-22 18:07:11,953 - ERROR - VM ctest-crpd-vm3-37084472 has failed to come up 2025-07-22 18:07:11,953 - ERROR - Nova failed to get host of the VM 2025-07-22 18:07:11,953 - ERROR - VM ctest-crpd-vm3-37084472 has failed to come up 2025-07-22 18:07:11,953 - ERROR - Nova failed to get host of the VM 2025-07-22 18:07:12,115 - INFO - Skip interface_detach for VM ctest-crpd-vm2-84557517 in state ERROR 2025-07-22 18:07:12,116 - INFO - Deleting VM ctest-crpd-vm2-84557517 2025-07-22 18:07:12,116 - ERROR - VM ctest-crpd-vm2-84557517 has failed to come up 2025-07-22 18:07:12,116 - ERROR - Fault seen in nova show is: {'code': 500, 'created': '2025-07-22T18:07:09Z', '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-07-22 18:07:12,122 - INFO - Skip interface_detach for VM ctest-crpd-vm1-04536432 in state ERROR 2025-07-22 18:07:12,122 - INFO - Deleting VM ctest-crpd-vm1-04536432 2025-07-22 18:07:12,122 - ERROR - VM ctest-crpd-vm1-04536432 has failed to come up 2025-07-22 18:07:12,122 - ERROR - Fault seen in nova show is: {'code': 500, 'created': '2025-07-22T18:07:08Z', '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-07-22 18:07:12,123 - INFO - Deleting VN ctest-vn2-14284900 2025-07-22 18:07:12,303 - DEBUG - Response for deleting network () 2025-07-22 18:07:12,303 - INFO - Deleting VN ctest-vn1-51174124 2025-07-22 18:07:12,494 - DEBUG - Response for deleting network () 2025-07-22 18:07:12,496 - DEBUG - Hosts: {'nova': ['an-jenkins-deploy-platform-ansible-os-3693-2', 'an-jenkins-deploy-platform-ansible-os-3693-1']} 2025-07-22 18:07:12,849 - DEBUG - Response for create_network : {'network': {'id': '6afcaf63-0cf9-4375-9268-128119270025', 'name': 'ctest-vn1-86240747', 'tenant_id': 'a2f228cd193242338e2dc363a8697520', 'project_id': 'a2f228cd193242338e2dc363a8697520', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestIpVlanLearning-38127045', 'ctest-vn1-86240747'], 'port_security_enabled': True, 'description': ''}} 2025-07-22 18:07:13,087 - DEBUG - Response for create_subnet : {'subnet': {'id': '631ddd7c-fb4b-40c4-8540-ec7552870891', 'name': '', 'tenant_id': 'a2f228cd193242338e2dc363a8697520', 'network_id': '6afcaf63-0cf9-4375-9268-128119270025', 'ip_version': 4, 'cidr': '75.100.104.0/24', 'allocation_pools': [{'start': '75.100.104.2', 'end': '75.100.104.254'}], 'gateway_ip': '75.100.104.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '75.100.104.2', 'tags': [], 'project_id': 'a2f228cd193242338e2dc363a8697520'}} 2025-07-22 18:07:13,112 - INFO - Created VN ctest-vn1-86240747 2025-07-22 18:07:13,130 - DEBUG - VN ctest-vn1-86240747 UUID is 6afcaf63-0cf9-4375-9268-128119270025 2025-07-22 18:07:13,500 - DEBUG - Response for create_network : {'network': {'id': 'bafaeb1b-8338-481b-acb8-ca37c3466f31', 'name': 'ctest-vn2-26630084', 'tenant_id': 'a2f228cd193242338e2dc363a8697520', 'project_id': 'a2f228cd193242338e2dc363a8697520', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestIpVlanLearning-38127045', 'ctest-vn2-26630084'], 'port_security_enabled': True, 'description': ''}} 2025-07-22 18:07:13,748 - DEBUG - Response for create_subnet : {'subnet': {'id': 'fe7ccf17-2f1b-4917-b863-b7a7809d262f', 'name': '', 'tenant_id': 'a2f228cd193242338e2dc363a8697520', 'network_id': 'bafaeb1b-8338-481b-acb8-ca37c3466f31', 'ip_version': 4, 'cidr': '94.227.57.0/24', 'allocation_pools': [{'start': '94.227.57.2', 'end': '94.227.57.254'}], 'gateway_ip': '94.227.57.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '94.227.57.2', 'tags': [], 'project_id': 'a2f228cd193242338e2dc363a8697520'}} 2025-07-22 18:07:13,772 - INFO - Created VN ctest-vn2-26630084 2025-07-22 18:07:13,787 - DEBUG - VN ctest-vn2-26630084 UUID is bafaeb1b-8338-481b-acb8-ca37c3466f31 2025-07-22 18:07:13,867 - DEBUG - Updating mac_ip_learning flag on VN default-domain:ctest-TestIpVlanLearning-38127045:ctest-vn1-86240747 to True 2025-07-22 18:07:13,988 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/6afcaf63-0cf9-4375-9268-128119270025 2025-07-22 18:07:14,003 - DEBUG - Updating mac_ip_learning flag on VN default-domain:ctest-TestIpVlanLearning-38127045:ctest-vn2-26630084 to True 2025-07-22 18:07:14,124 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/bafaeb1b-8338-481b-acb8-ca37c3466f31 2025-07-22 18:07:15,361 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3693-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3693-1) 2025-07-22 18:07:16,541 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3693-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3693-1) 2025-07-22 18:07:17,707 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3693-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3693-2) 2025-07-22 18:07:18,669 - INFO - Skip interface_detach for VM ctest-crpd-vm3-56097610 in state BUILD 2025-07-22 18:07:18,669 - INFO - Deleting VM ctest-crpd-vm3-56097610 2025-07-22 18:07:18,669 - ERROR - VM ctest-crpd-vm3-56097610 has failed to come up 2025-07-22 18:07:18,669 - ERROR - Nova failed to get host of the VM 2025-07-22 18:07:18,669 - ERROR - VM ctest-crpd-vm3-56097610 has failed to come up 2025-07-22 18:07:18,669 - ERROR - Nova failed to get host of the VM 2025-07-22 18:07:18,822 - INFO - Skip interface_detach for VM ctest-crpd-vm2-64787662 in state BUILD 2025-07-22 18:07:18,822 - INFO - Deleting VM ctest-crpd-vm2-64787662 2025-07-22 18:07:18,822 - ERROR - VM ctest-crpd-vm2-64787662 has failed to come up 2025-07-22 18:07:18,822 - ERROR - Nova failed to get host of the VM 2025-07-22 18:07:18,822 - ERROR - VM ctest-crpd-vm2-64787662 has failed to come up 2025-07-22 18:07:18,822 - ERROR - Nova failed to get host of the VM 2025-07-22 18:07:18,977 - INFO - Skip interface_detach for VM ctest-crpd-vm1-41711357 in state BUILD 2025-07-22 18:07:18,977 - INFO - Deleting VM ctest-crpd-vm1-41711357 2025-07-22 18:07:18,977 - ERROR - VM ctest-crpd-vm1-41711357 has failed to come up 2025-07-22 18:07:18,977 - ERROR - Nova failed to get host of the VM 2025-07-22 18:07:18,977 - ERROR - VM ctest-crpd-vm1-41711357 has failed to come up 2025-07-22 18:07:18,977 - ERROR - Nova failed to get host of the VM 2025-07-22 18:07:19,132 - INFO - Deleting VN ctest-vn2-26630084 2025-07-22 18:07:19,320 - DEBUG - Response for deleting network () 2025-07-22 18:07:19,320 - INFO - Deleting VN ctest-vn1-86240747 2025-07-22 18:07:19,502 - DEBUG - Response for deleting network () 2025-07-22 18:07:20,871 - INFO - Deleted project: ctest-TestIpVlanLearning-38127045, ID : a2f228cd-1932-4233-8e2d-c363a8697520