Ask Your Question

Revision history [back]

click to hide/show revision 1
initial version

Openstack Controller Mitaka and HyperV Compute node

Hi All,

I have a setup 1. Centos 7.3 with Openstack Mitaka as a (Controller) 2. Windows Server 2012 R2 with Cloudbase HyperVNovaComputeMitaka as a (Compute Node)

When I run instance into the compute node the creation is ok but after spawning the new instance I got this error (Instance failed network setup after 1 attempt(s)).

Here's the sample nova-compute log 2017-01-05 16:56:19.539 3924 INFO oslo_service.periodic_task [-] Skipping periodic task _periodic_update_dns because its interval is negative 2017-01-05 16:56:19.555 3924 INFO nova.virt.driver [-] Loading compute driver 'nova.virt.hyperv.driver.HyperVDriver' 2017-01-05 16:56:19.946 3924 INFO nova.service [-] Starting compute node (version 13.0.0) 2017-01-05 16:56:20.414 3924 WARNING nova.compute.monitors [req-394f6878-b577-4986-8f71-e9d04d97b516 - - - - -] Excluding nova.compute.monitors.cpu monitor virt_driver. Not in the list of enabled monitors (CONF.compute_monitors). 2017-01-05 16:56:20.430 3924 INFO nova.compute.resource_tracker [req-394f6878-b577-4986-8f71-e9d04d97b516 - - - - -] Auditing locally available compute resources for node HYPERV-M16 2017-01-05 16:56:20.617 3924 INFO nova.compute.resource_tracker [req-394f6878-b577-4986-8f71-e9d04d97b516 - - - - -] Total usable vcpus: 8, total allocated vcpus: 0 2017-01-05 16:56:20.617 3924 INFO nova.compute.resource_tracker [req-394f6878-b577-4986-8f71-e9d04d97b516 - - - - -] Final resource view: name=HYPERV-M16 phys_ram=32765MB used_ram=512MB phys_disk=546GB used_disk=0GB total_vcpus=8 used_vcpus=0 pci_stats=[] 2017-01-05 16:56:20.648 3924 INFO nova.compute.resource_tracker [req-394f6878-b577-4986-8f71-e9d04d97b516 - - - - -] Compute_service record updated for HYPERV-M16:HYPERV-M16 2017-01-05 16:56:50.792 3924 WARNING nova.compute.manager [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] While synchronizing instance power states, found 0 instances in the database and 3 instances on the hypervisor. 2017-01-05 16:57:04.786 3924 INFO nova.compute.claims [req-c3d00da0-0f9c-409d-9b22-022228a4c7f9 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] Attempting claim: memory 2024 MB, disk 10 GB, vcpus 3 CPU 2017-01-05 16:57:04.801 3924 INFO nova.compute.claims [req-c3d00da0-0f9c-409d-9b22-022228a4c7f9 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] Total memory: 32765 MB, used: 512.00 MB 2017-01-05 16:57:04.816 3924 INFO nova.compute.claims [req-c3d00da0-0f9c-409d-9b22-022228a4c7f9 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] memory limit: 49147.50 MB, free: 48635.50 MB 2017-01-05 16:57:04.816 3924 INFO nova.compute.claims [req-c3d00da0-0f9c-409d-9b22-022228a4c7f9 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] Total disk: 546 GB, used: 0.00 GB 2017-01-05 16:57:04.816 3924 INFO nova.compute.claims [req-c3d00da0-0f9c-409d-9b22-022228a4c7f9 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] disk limit not specified, defaulting to unlimited 2017-01-05 16:57:04.816 3924 INFO nova.compute.claims [req-c3d00da0-0f9c-409d-9b22-022228a4c7f9 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] Total vcpu: 8 VCPU, used: 0.00 VCPU 2017-01-05 16:57:04.816 3924 INFO nova.compute.claims [req-c3d00da0-0f9c-409d-9b22-022228a4c7f9 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] vcpu limit: 128.00 VCPU, free: 128.00 VCPU 2017-01-05 16:57:04.848 3924 INFO nova.compute.claims [req-c3d00da0-0f9c-409d-9b22-022228a4c7f9 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] Claim successful 2017-01-05 16:57:05.489 3924 INFO nova.virt.hyperv.vmops [req-c3d00da0-0f9c-409d-9b22-022228a4c7f9 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] Spawning new instance 2017-01-05 16:57:06.431 3924 ERROR nova.compute.manager [req-c3d00da0-0f9c-409d-9b22-022228a4c7f9 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] Instance failed network setup after 1 attempt(s) 2017-01-05 16:57:06.431 3924 ERROR nova.compute.manager Traceback (most recent call last): 2017-01-05 16:57:06.431 3924 ERROR nova.compute.manager File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\compute\manager.py", line 1570, in _allocate_network_async 2017-01-05 16:57:06.431 3924 ERROR nova.compute.manager bind_host_id=bind_host_id) 2017-01-05 16:57:06.431 3924 ERROR nova.compute.manager File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\network\neutronv2\api.py", line 666, in allocate_for_instance 2017-01-05 16:57:06.431 3924 ERROR nova.compute.manager self._delete_ports(neutron, instance, created_port_ids) 2017-01-05 16:57:06.431 3924 ERROR nova.compute.manager File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\oslo_utils\excutils.py", line 220, in __exit__ 2017-01-05 16:57:06.431 3924 ERROR nova.compute.manager self.force_reraise() 2017-01-05 16:57:06.431 3924 ERROR nova.compute.manager File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\oslo_utils\excutils.py", line 196, in force_reraise 2017-01-05 16:57:06.431 3924 ERROR nova.compute.manager six.reraise(self.type_, self.value, self.tb) 2017-01-05 16:57:06.431 3924 ERROR nova.compute.manager File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\network\neutronv2\api.py", line 655, in allocate_for_instance 2017-01-05 16:57:06.431 3924 ERROR nova.compute.manager security_group_ids, available_macs, dhcp_opts) 2017-01-05 16:57:06.431 3924 ERROR nova.compute.manager File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\network\neutronv2\api.py", line 259, in _create_port 2017-01-05 16:57:06.431 3924 ERROR nova.compute.manager raise exception.PortBindingFailed(port_id=port_id) 2017-01-05 16:57:06.431 3924 ERROR nova.compute.manager PortBindingFailed: Binding failed for port ebe6bfd0-fd45-43b1-bf57-c15641decdb7, please check neutron logs for more information. 2017-01-05 16:57:06.431 3924 ERROR nova.compute.manager 2017-01-05 16:57:19.331 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Auditing locally available compute resources for node HYPERV-M16 2017-01-05 16:57:19.503 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Total usable vcpus: 8, total allocated vcpus: 3 2017-01-05 16:57:19.503 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Final resource view: name=HYPERV-M16 phys_ram=32765MB used_ram=2536MB phys_disk=546GB used_disk=10GB total_vcpus=8 used_vcpus=3 pci_stats=[] 2017-01-05 16:57:19.565 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Compute_service record updated for HYPERV-M16:HYPERV-M16 2017-01-05 16:58:19.385 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Auditing locally available compute resources for node HYPERV-M16 2017-01-05 16:58:19.891 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Total usable vcpus: 8, total allocated vcpus: 3 2017-01-05 16:58:19.891 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Final resource view: name=HYPERV-M16 phys_ram=32765MB used_ram=2536MB phys_disk=546GB used_disk=10GB total_vcpus=8 used_vcpus=3 pci_stats=[] 2017-01-05 16:58:19.970 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Compute_service record updated for HYPERV-M16:HYPERV-M16 2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [req-c3d00da0-0f9c-409d-9b22-022228a4c7f9 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] Instance failed to spawn 2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] Traceback (most recent call last): 2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\compute\manager.py", line 2218, in _build_resources 2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] yield resources 2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\compute\manager.py", line 2064, in _build_and_run_instance 2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] block_device_info=block_device_info) 2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\virt\hyperv\driver.py", line 66, in wrapper 2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] six.reraise(raised_exception, exc, exc_info[2]) 2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\virt\hyperv\driver.py", line 50, in wrapper 2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] return function(args, *kwargs) 2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\virt\hyperv\driver.py", line 142, in spawn 2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] admin_password, network_info, block_device_info) 2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\virt\hyperv\vmops.py", line 115, in wrapper 2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] return function(self, args, *kwds) 2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\virt\hyperv\vmops.py", line 277, in spawn 2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] root_vhd_path = self._create_root_vhd(context, instance) 2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\virt\hyperv\vmops.py", line 190, in _create_root_vhd 2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] base_vhd_path = self._imagecache.get_cached_image(context, instance) 2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\virt\hyperv\imagecache.py", line 121, in get_cached_image 2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] vhd_path = fetch_image_if_not_existing() 2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\oslo_concurrency\lockutils.py", line 271, in inner 2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] return f(args, *kwargs) 2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\virt\hyperv\imagecache.py", line 117, in fetch_image_if_not_existing 2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] self._pathutils.remove(base_vhd_path) 2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\oslo_utils\excutils.py", line 220, in __exit__ 2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] self.force_reraise() 2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\oslo_utils\excutils.py", line 196, in force_reraise 2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] six.reraise(self.type_, self.value, self.tb) 2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\virt\hyperv\imagecache.py", line 111, in fetch_image_if_not_existing 2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] format_ext = self._vhdutils.get_vhd_format(base_vhd_path) 2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\os_win\utils\storage\virtdisk\vhdutils.py", line 153, in get_vhd_format 2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] _("Could not retrieve VHD format: %s") % vhd_path) 2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] NovaException: Could not retrieve VHD format: D:\OpenStack\Instances_base\96d9b017-220c-494b-97a8-1e10d968980b 2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] 2017-01-05 16:58:39.084 3924 INFO nova.compute.manager [req-c3d00da0-0f9c-409d-9b22-022228a4c7f9 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] Terminating instance 2017-01-05 16:58:39.115 3924 INFO nova.virt.hyperv.vmops [req-c3d00da0-0f9c-409d-9b22-022228a4c7f9 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] Got request to destroy instance 2017-01-05 16:58:39.177 3924 INFO nova.compute.manager [req-c3d00da0-0f9c-409d-9b22-022228a4c7f9 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] Took 0.06 seconds to destroy the instance on the hypervisor. 2017-01-05 16:58:39.395 3924 INFO nova.compute.manager [req-c3d00da0-0f9c-409d-9b22-022228a4c7f9 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] Took 0.20 seconds to deallocate network for instance. 2017-01-05 16:59:19.359 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Auditing locally available compute resources for node HYPERV-M16 2017-01-05 16:59:19.469 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Total usable vcpus: 8, total allocated vcpus: 0 2017-01-05 16:59:19.484 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Final resource view: name=HYPERV-M16 phys_ram=32765MB used_ram=512MB phys_disk=546GB used_disk=0GB total_vcpus=8 used_vcpus=0 pci_stats=[] 2017-01-05 16:59:19.516 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Compute_service record updated for HYPERV-M16:HYPERV-M16 2017-01-05 17:00:21.338 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Auditing locally available compute resources for node HYPERV-M16 2017-01-05 17:00:21.463 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Total usable vcpus: 8, total allocated vcpus: 0 2017-01-05 17:00:21.463 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Final resource view: name=HYPERV-M16 phys_ram=32765MB used_ram=512MB phys_disk=546GB used_disk=0GB total_vcpus=8 used_vcpus=0 pci_stats=[] 2017-01-05 17:00:21.509 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Compute_service record updated for HYPERV-M16:HYPERV-M16 2017-01-05 17:01:22.332 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Auditing locally available compute resources for node HYPERV-M16 2017-01-05 17:01:22.457 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Total usable vcpus: 8, total allocated vcpus: 0 2017-01-05 17:01:22.457 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Final resource view: name=HYPERV-M16 phys_ram=32765MB used_ram=512MB phys_disk=546GB used_disk=0GB total_vcpus=8 used_vcpus=0 pci_stats=[] 2017-01-05 17:01:22.505 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Compute_service record updated for HYPERV-M16:HYPERV-M16 2017-01-05 17:02:24.316 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Auditing locally available compute resources for node HYPERV-M16 2017-01-05 17:02:24.441 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Total usable vcpus: 8, total allocated vcpus: 0 2017-01-05 17:02:24.441 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Final resource view: name=HYPERV-M16 phys_ram=32765MB used_ram=512MB phys_disk=546GB used_disk=0GB total_vcpus=8 used_vcpus=0 pci_stats=[] 2017-01-05 17:02:24.489 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Compute_service record updated for HYPERV-M16:HYPERV-M16 2017-01-05 17:03:24.302 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Auditing locally available compute resources for node HYPERV-M16 2017-01-05 17:03:24.443 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Total usable vcpus: 8, total allocated vcpus: 0 2017-01-05 17:03:24.443 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Final resource view: name=HYPERV-M16 phys_ram=32765MB used_ram=512MB phys_disk=546GB used_disk=0GB total_vcpus=8 used_vcpus=0 pci_stats=[] 2017-01-05 17:03:24.474 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Compute_service record updated for HYPERV-M16:HYPERV-M16 2017-01-05 17:04:25.309 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Auditing locally available compute resources for node HYPERV-M16 2017-01-05 17:04:25.448 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Total usable vcpus: 8, total allocated vcpus: 0 2017-01-05 17:04:25.464 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Final resource view: name=HYPERV-M16 phys_ram=32765MB used_ram=512MB phys_disk=546GB used_disk=0GB total_vcpus=8 used_vcpus=0 pci_stats=[] 2017-01-05 17:04:25.512 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Compute_service record updated for HYPERV-M16:HYPERV-M16 2017-01-05 17:05:11.891 3924 INFO nova.compute.claims [req-d2ee5ae5-8cc2-4038-94f0-bd8f94f817c3 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] Attempting claim: memory 2024 MB, disk 10 GB, vcpus 3 CPU 2017-01-05 17:05:11.891 3924 INFO nova.compute.claims [req-d2ee5ae5-8cc2-4038-94f0-bd8f94f817c3 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] Total memory: 32765 MB, used: 512.00 MB 2017-01-05 17:05:11.891 3924 INFO nova.compute.claims [req-d2ee5ae5-8cc2-4038-94f0-bd8f94f817c3 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] memory limit: 49147.50 MB, free: 48635.50 MB 2017-01-05 17:05:11.891 3924 INFO nova.compute.claims [req-d2ee5ae5-8cc2-4038-94f0-bd8f94f817c3 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] Total disk: 546 GB, used: 0.00 GB 2017-01-05 17:05:11.891 3924 INFO nova.compute.claims [req-d2ee5ae5-8cc2-4038-94f0-bd8f94f817c3 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] disk limit not specified, defaulting to unlimited 2017-01-05 17:05:11.891 3924 INFO nova.compute.claims [req-d2ee5ae5-8cc2-4038-94f0-bd8f94f817c3 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] Total vcpu: 8 VCPU, used: 0.00 VCPU 2017-01-05 17:05:11.891 3924 INFO nova.compute.claims [req-d2ee5ae5-8cc2-4038-94f0-bd8f94f817c3 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] vcpu limit: 128.00 VCPU, free: 128.00 VCPU 2017-01-05 17:05:11.923 3924 INFO nova.compute.claims [req-d2ee5ae5-8cc2-4038-94f0-bd8f94f817c3 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] Claim successful 2017-01-05 17:05:13.000 3924 INFO nova.virt.hyperv.vmops [req-d2ee5ae5-8cc2-4038-94f0-bd8f94f817c3 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] Spawning new instance 2017-01-05 17:05:13.974 3924 ERROR nova.compute.manager [req-d2ee5ae5-8cc2-4038-94f0-bd8f94f817c3 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] Instance failed network setup after 1 attempt(s) 2017-01-05 17:05:13.974 3924 ERROR nova.compute.manager Traceback (most recent call last): 2017-01-05 17:05:13.974 3924 ERROR nova.compute.manager File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\compute\manager.py", line 1570, in _allocate_network_async 2017-01-05 17:05:13.974 3924 ERROR nova.compute.manager bind_host_id=bind_host_id) 2017-01-05 17:05:13.974 3924 ERROR nova.compute.manager File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\network\neutronv2\api.py", line 666, in allocate_for_instance 2017-01-05 17:05:13.974 3924 ERROR nova.compute.manager self._delete_ports(neutron, instance, created_port_ids) 2017-01-05 17:05:13.974 3924 ERROR nova.compute.manager File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\oslo_utils\excutils.py", line 220, in __exit__ 2017-01-05 17:05:13.974 3924 ERROR nova.compute.manager self.force_reraise() 2017-01-05 17:05:13.974 3924 ERROR nova.compute.manager File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\oslo_utils\excutils.py", line 196, in force_reraise 2017-01-05 17:05:13.974 3924 ERROR nova.compute.manager six.reraise(self.type_, self.value, self.tb) 2017-01-05 17:05:13.974 3924 ERROR nova.compute.manager File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\network\neutronv2\api.py", line 655, in allocate_for_instance 2017-01-05 17:05:13.974 3924 ERROR nova.compute.manager security_group_ids, available_macs, dhcp_opts) 2017-01-05 17:05:13.974 3924 ERROR nova.compute.manager File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\network\neutronv2\api.py", line 259, in _create_port 2017-01-05 17:05:13.974 3924 ERROR nova.compute.manager raise exception.PortBindingFailed(port_id=port_id) 2017-01-05 17:05:13.974 3924 ERROR nova.compute.manager PortBindingFailed: Binding failed for port 8dbec645-e5e8-476a-a191-253d3ab323eb, please check neutron logs for more information. 2017-01-05 17:05:13.974 3924 ERROR nova.compute.manager 2017-01-05 17:05:26.359 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Auditing locally available compute resources for node HYPERV-M16 2017-01-05 17:05:26.816 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Total usable vcpus: 8, total allocated vcpus: 3 2017-01-05 17:05:26.816 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Final resource view: name=HYPERV-M16 phys_ram=32765MB used_ram=2536MB phys_disk=546GB used_disk=10GB total_vcpus=8 used_vcpus=3 pci_stats=[] 2017-01-05 17:05:26.878 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Compute_service record updated for HYPERV-M16:HYPERV-M16 2017-01-05 17:06:19.359 3924 INFO nova.compute.manager [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Updating bandwidth usage cache 2017-01-05 17:06:19.438 3924 INFO nova.compute.manager [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Bandwidth usage not supported by hypervisor. 2017-01-05 17:06:27.371 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Auditing locally available compute resources for node HYPERV-M16 2017-01-05 17:06:27.558 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Total usable vcpus: 8, total allocated vcpus: 3 2017-01-05 17:06:27.558 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Final resource view: name=HYPERV-M16 phys_ram=32765MB used_ram=2536MB phys_disk=546GB used_disk=10GB total_vcpus=8 used_vcpus=3 pci_stats=[] 2017-01-05 17:06:27.637 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Compute_service record updated for HYPERV-M16:HYPERV-M16 2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [req-d2ee5ae5-8cc2-4038-94f0-bd8f94f817c3 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] Instance failed to spawn 2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] Traceback (most recent call last): 2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\compute\manager.py", line 2218, in _build_resources 2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] yield resources 2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\compute\manager.py", line 2064, in _build_and_run_instance 2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] block_device_info=block_device_info) 2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\virt\hyperv\driver.py", line 66, in wrapper 2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] six.reraise(raised_exception, exc, exc_info[2]) 2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\virt\hyperv\driver.py", line 50, in wrapper 2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] return function(args, *kwargs) 2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\virt\hyperv\driver.py", line 142, in spawn 2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] admin_password, network_info, block_device_info) 2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\virt\hyperv\vmops.py", line 115, in wrapper 2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] return function(self, args, *kwds) 2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\virt\hyperv\vmops.py", line 277, in spawn 2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] root_vhd_path = self._create_root_vhd(context, instance) 2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\virt\hyperv\vmops.py", line 190, in _create_root_vhd 2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] base_vhd_path = self._imagecache.get_cached_image(context, instance) 2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\virt\hyperv\imagecache.py", line 121, in get_cached_image 2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] vhd_path = fetch_image_if_not_existing() 2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\oslo_concurrency\lockutils.py", line 271, in inner 2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] return f(args, *kwargs) 2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\virt\hyperv\imagecache.py", line 117, in fetch_image_if_not_existing 2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] self._pathutils.remove(base_vhd_path) 2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\oslo_utils\excutils.py", line 220, in __exit__ 2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] self.force_reraise() 2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\oslo_utils\excutils.py", line 196, in force_reraise 2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] six.reraise(self.type_, self.value, self.tb) 2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\virt\hyperv\imagecache.py", line 111, in fetch_image_if_not_existing 2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] format_ext = self._vhdutils.get_vhd_format(base_vhd_path) 2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\os_win\utils\storage\virtdisk\vhdutils.py", line 153, in get_vhd_format 2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] _("Could not retrieve VHD format: %s") % vhd_path) 2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] NovaException: Could not retrieve VHD format: D:\OpenStack\Instances_base\96d9b017-220c-494b-97a8-1e10d968980b 2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] 2017-01-05 17:06:38.506 3924 INFO nova.compute.manager [req-d2ee5ae5-8cc2-4038-94f0-bd8f94f817c3 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] Terminating instance 2017-01-05 17:06:38.538 3924 INFO nova.virt.hyperv.vmops [req-d2ee5ae5-8cc2-4038-94f0-bd8f94f817c3 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] Got request to destroy instance 2017-01-05 17:06:38.601 3924 INFO nova.compute.manager [req-d2ee5ae5-8cc2-4038-94f0-bd8f94f817c3 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] Took 0.06 seconds to destroy the instance on the hypervisor. 2017-01-05 17:06:38.819 3924 INFO nova.compute.manager [req-d2ee5ae5-8cc2-4038-94f0-bd8f94f817c3 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] Took 0.20 seconds to deallocate network for instance. 2017-01-05 17:06:50.904 3924 WARNING nova.compute.manager [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] While synchronizing instance power states, found 0 instances in the database and 3 instances on the hypervisor. 2017-01-05 17:07:28.309 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Auditing locally available compute resources for node HYPERV-M16 2017-01-05 17:07:28.417 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Total usable vcpus: 8, total allocated vcpus: 0 2017-01-05 17:07:28.417 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Final resource view: name=HYPERV-M16 phys_ram=32765MB used_ram=512MB phys_disk=546GB used_disk=0GB total_vcpus=8 used_vcpus=0 pci_stats=[] 2017-01-05 17:07:28.464 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Compute_service record updated for HYPERV-M16:HYPERV-M16 2017-01-05 17:08:29.305 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Auditing locally available compute resources for node HYPERV-M16 2017-01-05 17:08:29.446 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Total usable vcpus: 8, total allocated vcpus: 0 2017-01-05 17:08:29.446 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Final resource view: name=HYPERV-M16 phys_ram=32765MB used_ram=512MB phys_disk=546GB used_disk=0GB total_vcpus=8 used_vcpus=0 pci_stats=[] 2017-01-05 17:08:29.492 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Compute_service record updated for HYPERV-M16:HYPERV-M16 2017-01-05 17:09:29.315 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Auditing locally available compute resources for node HYPERV-M16 2017-01-05 17:09:29.424 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Total usable vcpus: 8, total allocated vcpus: 0 2017-01-05 17:09:29.424 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Final resource view: name=HYPERV-M16 phys_ram=32765MB used_ram=512MB phys_disk=546GB used_disk=0GB total_vcpus=8 used_vcpus=0 pci_stats=[] 2017-01-05 17:09:29.471 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Compute_service record updated for HYPERV-M16:HYPERV-M16 2017-01-05 17:10:29.328 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Auditing locally available compute resources for node HYPERV-M16 2017-01-05 17:10:29.453 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Total usable vcpus: 8, total allocated vcpus: 0 2017-01-05 17:10:29.470 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Final resource view: name=HYPERV-M16 phys_ram=32765MB used_ram=512MB phys_disk=546GB used_disk=0GB total_vcpus=8 used_vcpus=0 pci_stats=[] 2017-01-05 17:10:29.500 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Compute_service record updated for HYPERV-M16:HYPERV-M16 2017-01-05 17:11:31.339 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Auditing locally available compute resources for node HYPERV-M16 2017-01-05 17:11:31.464 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Total usable vcpus: 8, total allocated vcpus: 0 2017-01-05 17:11:31.464 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Final resource view: name=HYPERV-M16 phys_ram=32765MB used_ram=512MB phys_disk=546GB used_disk=0GB total_vcpus=8 used_vcpus=0 pci_stats=[] 2017-01-05 17:11:31.496 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Compute_service record updated for HYPERV-M16:HYPERV-M16 2017-01-05 17:12:31.306 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Auditing locally available compute resources for node HYPERV-M16 2017-01-05 17:12:31.401 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Total usable vcpus: 8, total allocated vcpus: 0 2017-01-05 17:12:31.401 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Final resource view: name=HYPERV-M16 phys_ram=32765MB used_ram=512MB phys_disk=546GB used_disk=0GB total_vcpus=8 used_vcpus=0 pci_stats=[] 2017-01-05 17:12:31.572 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Compute_service record updated for HYPERV-M16:HYPERV-M16 2017-01-05 17:13:32.306 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Auditing locally available compute resources for node HYPERV-M16 2017-01-05 17:13:32.447 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Total usable vcpus: 8, total allocated vcpus: 0 2017-01-05 17:13:32.447 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Final resource view: name=HYPERV-M16 phys_ram=32765MB used_ram=512MB phys_disk=546GB used_disk=0GB total_vcpus=8 used_vcpus=0 pci_stats=[] 2017-01-05 17:13:32.494 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Compute_service record updated for HYPERV-M16:HYPERV-M16 2017-01-05 17:14:33.351 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Auditing locally available compute resources for node HYPERV-M16 2017-01-05 17:14:33.492 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Total usable vcpus: 8, total allocated vcpus: 0 2017-01-05 17:14:33.492 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Final resource view: name=HYPERV-M16 phys_ram=32765MB used_ram=512MB phys_disk=546GB used_disk=0GB total_vcpus=8 used_vcpus=0 pci_stats=[] 2017-01-05 17:14:33.539 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Compute_service record updated for HYPERV-M16:HYPERV-M16 2017-01-05 17:15:34.331 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Auditing locally available compute resources for node HYPERV-M16 2017-01-05 17:15:34.486 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Total usable vcpus: 8, total allocated vcpus: 0 2017-01-05 17:15:34.486 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Final resource view: name=HYPERV-M16 phys_ram=32765MB used_ram=512MB phys_disk=546GB used_disk=0GB total_vcpus=8 used_vcpus=0 pci_stats=[] 2017-01-05 17:15:34.533 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Compute_service record updated for HYPERV-M16:HYPERV-M16

Openstack Controller Mitaka and HyperV Compute node

Hi All,

I have a setup 1. Centos 7.3 with Openstack Mitaka as a (Controller) 2. Windows Server 2012 R2 with Cloudbase HyperVNovaComputeMitaka as a (Compute Node)

When I run instance into the compute node the creation is ok but after spawning the new instance I got this error (Instance failed network setup after 1 attempt(s)).

Here's the sample nova-compute log log

2017-01-05 16:56:19.539 3924 INFO oslo_service.periodic_task [-] Skipping periodic task _periodic_update_dns because its interval is negative
2017-01-05 16:56:19.555 3924 INFO nova.virt.driver [-] Loading compute driver 'nova.virt.hyperv.driver.HyperVDriver'
2017-01-05 16:56:19.946 3924 INFO nova.service [-] Starting compute node (version 13.0.0)
2017-01-05 16:56:20.414 3924 WARNING nova.compute.monitors [req-394f6878-b577-4986-8f71-e9d04d97b516 - - - - -] Excluding nova.compute.monitors.cpu monitor virt_driver. Not in the list of enabled monitors (CONF.compute_monitors).
2017-01-05 16:56:20.430 3924 INFO nova.compute.resource_tracker [req-394f6878-b577-4986-8f71-e9d04d97b516 - - - - -] Auditing locally available compute resources for node HYPERV-M16
2017-01-05 16:56:20.617 3924 INFO nova.compute.resource_tracker [req-394f6878-b577-4986-8f71-e9d04d97b516 - - - - -] Total usable vcpus: 8, total allocated vcpus: 0
2017-01-05 16:56:20.617 3924 INFO nova.compute.resource_tracker [req-394f6878-b577-4986-8f71-e9d04d97b516 - - - - -] Final resource view: name=HYPERV-M16 phys_ram=32765MB used_ram=512MB phys_disk=546GB used_disk=0GB total_vcpus=8 used_vcpus=0 pci_stats=[]
2017-01-05 16:56:20.648 3924 INFO nova.compute.resource_tracker [req-394f6878-b577-4986-8f71-e9d04d97b516 - - - - -] Compute_service record updated for HYPERV-M16:HYPERV-M16
2017-01-05 16:56:50.792 3924 WARNING nova.compute.manager [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] While synchronizing instance power states, found 0 instances in the database and 3 instances on the hypervisor.
2017-01-05 16:57:04.786 3924 INFO nova.compute.claims [req-c3d00da0-0f9c-409d-9b22-022228a4c7f9 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] Attempting claim: memory 2024 MB, disk 10 GB, vcpus 3 CPU
2017-01-05 16:57:04.801 3924 INFO nova.compute.claims [req-c3d00da0-0f9c-409d-9b22-022228a4c7f9 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] Total memory: 32765 MB, used: 512.00 MB
2017-01-05 16:57:04.816 3924 INFO nova.compute.claims [req-c3d00da0-0f9c-409d-9b22-022228a4c7f9 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] memory limit: 49147.50 MB, free: 48635.50 MB
2017-01-05 16:57:04.816 3924 INFO nova.compute.claims [req-c3d00da0-0f9c-409d-9b22-022228a4c7f9 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] Total disk: 546 GB, used: 0.00 GB
2017-01-05 16:57:04.816 3924 INFO nova.compute.claims [req-c3d00da0-0f9c-409d-9b22-022228a4c7f9 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] disk limit not specified, defaulting to unlimited
2017-01-05 16:57:04.816 3924 INFO nova.compute.claims [req-c3d00da0-0f9c-409d-9b22-022228a4c7f9 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] Total vcpu: 8 VCPU, used: 0.00 VCPU
2017-01-05 16:57:04.816 3924 INFO nova.compute.claims [req-c3d00da0-0f9c-409d-9b22-022228a4c7f9 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] vcpu limit: 128.00 VCPU, free: 128.00 VCPU
2017-01-05 16:57:04.848 3924 INFO nova.compute.claims [req-c3d00da0-0f9c-409d-9b22-022228a4c7f9 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] Claim successful
2017-01-05 16:57:05.489 3924 INFO nova.virt.hyperv.vmops [req-c3d00da0-0f9c-409d-9b22-022228a4c7f9 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] Spawning new instance
2017-01-05 16:57:06.431 3924 ERROR nova.compute.manager [req-c3d00da0-0f9c-409d-9b22-022228a4c7f9 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] Instance failed network setup after 1 attempt(s)
2017-01-05 16:57:06.431 3924 ERROR nova.compute.manager Traceback (most recent call last):
2017-01-05 16:57:06.431 3924 ERROR nova.compute.manager   File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\compute\manager.py", line 1570, in _allocate_network_async
2017-01-05 16:57:06.431 3924 ERROR nova.compute.manager     bind_host_id=bind_host_id)
2017-01-05 16:57:06.431 3924 ERROR nova.compute.manager   File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\network\neutronv2\api.py", line 666, in allocate_for_instance
2017-01-05 16:57:06.431 3924 ERROR nova.compute.manager     self._delete_ports(neutron, instance, created_port_ids)
2017-01-05 16:57:06.431 3924 ERROR nova.compute.manager   File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\oslo_utils\excutils.py", line 220, in __exit__
2017-01-05 16:57:06.431 3924 ERROR nova.compute.manager     self.force_reraise()
2017-01-05 16:57:06.431 3924 ERROR nova.compute.manager   File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\oslo_utils\excutils.py", line 196, in force_reraise
2017-01-05 16:57:06.431 3924 ERROR nova.compute.manager     six.reraise(self.type_, self.value, self.tb)
2017-01-05 16:57:06.431 3924 ERROR nova.compute.manager   File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\network\neutronv2\api.py", line 655, in allocate_for_instance
2017-01-05 16:57:06.431 3924 ERROR nova.compute.manager     security_group_ids, available_macs, dhcp_opts)
2017-01-05 16:57:06.431 3924 ERROR nova.compute.manager   File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\network\neutronv2\api.py", line 259, in _create_port
2017-01-05 16:57:06.431 3924 ERROR nova.compute.manager     raise exception.PortBindingFailed(port_id=port_id)
2017-01-05 16:57:06.431 3924 ERROR nova.compute.manager PortBindingFailed: Binding failed for port ebe6bfd0-fd45-43b1-bf57-c15641decdb7, please check neutron logs for more information.
2017-01-05 16:57:06.431 3924 ERROR nova.compute.manager 
2017-01-05 16:57:19.331 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Auditing locally available compute resources for node HYPERV-M16
2017-01-05 16:57:19.503 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Total usable vcpus: 8, total allocated vcpus: 3
2017-01-05 16:57:19.503 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Final resource view: name=HYPERV-M16 phys_ram=32765MB used_ram=2536MB phys_disk=546GB used_disk=10GB total_vcpus=8 used_vcpus=3 pci_stats=[]
2017-01-05 16:57:19.565 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Compute_service record updated for HYPERV-M16:HYPERV-M16
2017-01-05 16:58:19.385 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Auditing locally available compute resources for node HYPERV-M16
2017-01-05 16:58:19.891 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Total usable vcpus: 8, total allocated vcpus: 3
2017-01-05 16:58:19.891 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Final resource view: name=HYPERV-M16 phys_ram=32765MB used_ram=2536MB phys_disk=546GB used_disk=10GB total_vcpus=8 used_vcpus=3 pci_stats=[]
2017-01-05 16:58:19.970 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Compute_service record updated for HYPERV-M16:HYPERV-M16
2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [req-c3d00da0-0f9c-409d-9b22-022228a4c7f9 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] Instance failed to spawn
2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] Traceback (most recent call last):
2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1]   File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\compute\manager.py", line 2218, in _build_resources
2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1]     yield resources
2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1]   File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\compute\manager.py", line 2064, in _build_and_run_instance
2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1]     block_device_info=block_device_info)
2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1]   File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\virt\hyperv\driver.py", line 66, in wrapper
2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1]     six.reraise(raised_exception, exc, exc_info[2])
2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1]   File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\virt\hyperv\driver.py", line 50, in wrapper
2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1]     return function(args, *kwargs)
function(*args, **kwargs)
2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1]   File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\virt\hyperv\driver.py", line 142, in spawn
2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1]     admin_password, network_info, block_device_info)
2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1]   File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\virt\hyperv\vmops.py", line 115, in wrapper
2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1]     return function(self, args, *kwds)
*args, **kwds)
2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1]   File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\virt\hyperv\vmops.py", line 277, in spawn
2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1]     root_vhd_path = self._create_root_vhd(context, instance)
2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1]   File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\virt\hyperv\vmops.py", line 190, in _create_root_vhd
2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1]     base_vhd_path = self._imagecache.get_cached_image(context, instance)
2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1]   File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\virt\hyperv\imagecache.py", line 121, in get_cached_image
2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1]     vhd_path = fetch_image_if_not_existing()
2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1]   File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\oslo_concurrency\lockutils.py", line 271, in inner
2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1]     return f(args, *kwargs)
f(*args, **kwargs)
2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1]   File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\virt\hyperv\imagecache.py", line 117, in fetch_image_if_not_existing
2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1]     self._pathutils.remove(base_vhd_path)
2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1]   File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\oslo_utils\excutils.py", line 220, in __exit__
2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1]     self.force_reraise()
2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1]   File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\oslo_utils\excutils.py", line 196, in force_reraise
2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1]     six.reraise(self.type_, self.value, self.tb)
2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1]   File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\virt\hyperv\imagecache.py", line 111, in fetch_image_if_not_existing
2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1]     format_ext = self._vhdutils.get_vhd_format(base_vhd_path)
2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1]   File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\os_win\utils\storage\virtdisk\vhdutils.py", line 153, in get_vhd_format
2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1]     _("Could not retrieve VHD format: %s") % vhd_path)
2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] NovaException: Could not retrieve VHD format: D:\OpenStack\Instances_base\96d9b017-220c-494b-97a8-1e10d968980b
D:\OpenStack\Instances\_base\96d9b017-220c-494b-97a8-1e10d968980b
2017-01-05 16:58:39.036 3924 ERROR nova.compute.manager [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] 
2017-01-05 16:58:39.084 3924 INFO nova.compute.manager [req-c3d00da0-0f9c-409d-9b22-022228a4c7f9 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] Terminating instance
2017-01-05 16:58:39.115 3924 INFO nova.virt.hyperv.vmops [req-c3d00da0-0f9c-409d-9b22-022228a4c7f9 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] Got request to destroy instance
2017-01-05 16:58:39.177 3924 INFO nova.compute.manager [req-c3d00da0-0f9c-409d-9b22-022228a4c7f9 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] Took 0.06 seconds to destroy the instance on the hypervisor.
2017-01-05 16:58:39.395 3924 INFO nova.compute.manager [req-c3d00da0-0f9c-409d-9b22-022228a4c7f9 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: f1b4292c-acd9-4158-8852-b61e4c5650a1] Took 0.20 seconds to deallocate network for instance.
2017-01-05 16:59:19.359 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Auditing locally available compute resources for node HYPERV-M16
2017-01-05 16:59:19.469 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Total usable vcpus: 8, total allocated vcpus: 0
2017-01-05 16:59:19.484 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Final resource view: name=HYPERV-M16 phys_ram=32765MB used_ram=512MB phys_disk=546GB used_disk=0GB total_vcpus=8 used_vcpus=0 pci_stats=[]
2017-01-05 16:59:19.516 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Compute_service record updated for HYPERV-M16:HYPERV-M16
2017-01-05 17:00:21.338 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Auditing locally available compute resources for node HYPERV-M16
2017-01-05 17:00:21.463 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Total usable vcpus: 8, total allocated vcpus: 0
2017-01-05 17:00:21.463 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Final resource view: name=HYPERV-M16 phys_ram=32765MB used_ram=512MB phys_disk=546GB used_disk=0GB total_vcpus=8 used_vcpus=0 pci_stats=[]
2017-01-05 17:00:21.509 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Compute_service record updated for HYPERV-M16:HYPERV-M16
2017-01-05 17:01:22.332 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Auditing locally available compute resources for node HYPERV-M16
2017-01-05 17:01:22.457 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Total usable vcpus: 8, total allocated vcpus: 0
2017-01-05 17:01:22.457 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Final resource view: name=HYPERV-M16 phys_ram=32765MB used_ram=512MB phys_disk=546GB used_disk=0GB total_vcpus=8 used_vcpus=0 pci_stats=[]
2017-01-05 17:01:22.505 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Compute_service record updated for HYPERV-M16:HYPERV-M16
2017-01-05 17:02:24.316 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Auditing locally available compute resources for node HYPERV-M16
2017-01-05 17:02:24.441 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Total usable vcpus: 8, total allocated vcpus: 0
2017-01-05 17:02:24.441 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Final resource view: name=HYPERV-M16 phys_ram=32765MB used_ram=512MB phys_disk=546GB used_disk=0GB total_vcpus=8 used_vcpus=0 pci_stats=[]
2017-01-05 17:02:24.489 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Compute_service record updated for HYPERV-M16:HYPERV-M16
2017-01-05 17:03:24.302 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Auditing locally available compute resources for node HYPERV-M16
2017-01-05 17:03:24.443 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Total usable vcpus: 8, total allocated vcpus: 0
2017-01-05 17:03:24.443 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Final resource view: name=HYPERV-M16 phys_ram=32765MB used_ram=512MB phys_disk=546GB used_disk=0GB total_vcpus=8 used_vcpus=0 pci_stats=[]
2017-01-05 17:03:24.474 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Compute_service record updated for HYPERV-M16:HYPERV-M16
2017-01-05 17:04:25.309 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Auditing locally available compute resources for node HYPERV-M16
2017-01-05 17:04:25.448 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Total usable vcpus: 8, total allocated vcpus: 0
2017-01-05 17:04:25.464 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Final resource view: name=HYPERV-M16 phys_ram=32765MB used_ram=512MB phys_disk=546GB used_disk=0GB total_vcpus=8 used_vcpus=0 pci_stats=[]
2017-01-05 17:04:25.512 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Compute_service record updated for HYPERV-M16:HYPERV-M16
2017-01-05 17:05:11.891 3924 INFO nova.compute.claims [req-d2ee5ae5-8cc2-4038-94f0-bd8f94f817c3 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] Attempting claim: memory 2024 MB, disk 10 GB, vcpus 3 CPU
2017-01-05 17:05:11.891 3924 INFO nova.compute.claims [req-d2ee5ae5-8cc2-4038-94f0-bd8f94f817c3 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] Total memory: 32765 MB, used: 512.00 MB
2017-01-05 17:05:11.891 3924 INFO nova.compute.claims [req-d2ee5ae5-8cc2-4038-94f0-bd8f94f817c3 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] memory limit: 49147.50 MB, free: 48635.50 MB
2017-01-05 17:05:11.891 3924 INFO nova.compute.claims [req-d2ee5ae5-8cc2-4038-94f0-bd8f94f817c3 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] Total disk: 546 GB, used: 0.00 GB
2017-01-05 17:05:11.891 3924 INFO nova.compute.claims [req-d2ee5ae5-8cc2-4038-94f0-bd8f94f817c3 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] disk limit not specified, defaulting to unlimited
2017-01-05 17:05:11.891 3924 INFO nova.compute.claims [req-d2ee5ae5-8cc2-4038-94f0-bd8f94f817c3 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] Total vcpu: 8 VCPU, used: 0.00 VCPU
2017-01-05 17:05:11.891 3924 INFO nova.compute.claims [req-d2ee5ae5-8cc2-4038-94f0-bd8f94f817c3 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] vcpu limit: 128.00 VCPU, free: 128.00 VCPU
2017-01-05 17:05:11.923 3924 INFO nova.compute.claims [req-d2ee5ae5-8cc2-4038-94f0-bd8f94f817c3 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] Claim successful
2017-01-05 17:05:13.000 3924 INFO nova.virt.hyperv.vmops [req-d2ee5ae5-8cc2-4038-94f0-bd8f94f817c3 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] Spawning new instance
2017-01-05 17:05:13.974 3924 ERROR nova.compute.manager [req-d2ee5ae5-8cc2-4038-94f0-bd8f94f817c3 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] Instance failed network setup after 1 attempt(s)
2017-01-05 17:05:13.974 3924 ERROR nova.compute.manager Traceback (most recent call last):
2017-01-05 17:05:13.974 3924 ERROR nova.compute.manager   File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\compute\manager.py", line 1570, in _allocate_network_async
2017-01-05 17:05:13.974 3924 ERROR nova.compute.manager     bind_host_id=bind_host_id)
2017-01-05 17:05:13.974 3924 ERROR nova.compute.manager   File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\network\neutronv2\api.py", line 666, in allocate_for_instance
2017-01-05 17:05:13.974 3924 ERROR nova.compute.manager     self._delete_ports(neutron, instance, created_port_ids)
2017-01-05 17:05:13.974 3924 ERROR nova.compute.manager   File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\oslo_utils\excutils.py", line 220, in __exit__
2017-01-05 17:05:13.974 3924 ERROR nova.compute.manager     self.force_reraise()
2017-01-05 17:05:13.974 3924 ERROR nova.compute.manager   File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\oslo_utils\excutils.py", line 196, in force_reraise
2017-01-05 17:05:13.974 3924 ERROR nova.compute.manager     six.reraise(self.type_, self.value, self.tb)
2017-01-05 17:05:13.974 3924 ERROR nova.compute.manager   File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\network\neutronv2\api.py", line 655, in allocate_for_instance
2017-01-05 17:05:13.974 3924 ERROR nova.compute.manager     security_group_ids, available_macs, dhcp_opts)
2017-01-05 17:05:13.974 3924 ERROR nova.compute.manager   File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\network\neutronv2\api.py", line 259, in _create_port
2017-01-05 17:05:13.974 3924 ERROR nova.compute.manager     raise exception.PortBindingFailed(port_id=port_id)
2017-01-05 17:05:13.974 3924 ERROR nova.compute.manager PortBindingFailed: Binding failed for port 8dbec645-e5e8-476a-a191-253d3ab323eb, please check neutron logs for more information.
2017-01-05 17:05:13.974 3924 ERROR nova.compute.manager 
2017-01-05 17:05:26.359 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Auditing locally available compute resources for node HYPERV-M16
2017-01-05 17:05:26.816 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Total usable vcpus: 8, total allocated vcpus: 3
2017-01-05 17:05:26.816 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Final resource view: name=HYPERV-M16 phys_ram=32765MB used_ram=2536MB phys_disk=546GB used_disk=10GB total_vcpus=8 used_vcpus=3 pci_stats=[]
2017-01-05 17:05:26.878 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Compute_service record updated for HYPERV-M16:HYPERV-M16
2017-01-05 17:06:19.359 3924 INFO nova.compute.manager [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Updating bandwidth usage cache
2017-01-05 17:06:19.438 3924 INFO nova.compute.manager [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Bandwidth usage not supported by hypervisor.
2017-01-05 17:06:27.371 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Auditing locally available compute resources for node HYPERV-M16
2017-01-05 17:06:27.558 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Total usable vcpus: 8, total allocated vcpus: 3
2017-01-05 17:06:27.558 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Final resource view: name=HYPERV-M16 phys_ram=32765MB used_ram=2536MB phys_disk=546GB used_disk=10GB total_vcpus=8 used_vcpus=3 pci_stats=[]
2017-01-05 17:06:27.637 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Compute_service record updated for HYPERV-M16:HYPERV-M16
2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [req-d2ee5ae5-8cc2-4038-94f0-bd8f94f817c3 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] Instance failed to spawn
2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] Traceback (most recent call last):
2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f]   File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\compute\manager.py", line 2218, in _build_resources
2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f]     yield resources
2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f]   File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\compute\manager.py", line 2064, in _build_and_run_instance
2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f]     block_device_info=block_device_info)
2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f]   File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\virt\hyperv\driver.py", line 66, in wrapper
2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f]     six.reraise(raised_exception, exc, exc_info[2])
2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f]   File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\virt\hyperv\driver.py", line 50, in wrapper
2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f]     return function(args, *kwargs)
function(*args, **kwargs)
2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f]   File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\virt\hyperv\driver.py", line 142, in spawn
2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f]     admin_password, network_info, block_device_info)
2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f]   File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\virt\hyperv\vmops.py", line 115, in wrapper
2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f]     return function(self, args, *kwds)
*args, **kwds)
2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f]   File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\virt\hyperv\vmops.py", line 277, in spawn
2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f]     root_vhd_path = self._create_root_vhd(context, instance)
2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f]   File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\virt\hyperv\vmops.py", line 190, in _create_root_vhd
2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f]     base_vhd_path = self._imagecache.get_cached_image(context, instance)
2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f]   File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\virt\hyperv\imagecache.py", line 121, in get_cached_image
2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f]     vhd_path = fetch_image_if_not_existing()
2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f]   File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\oslo_concurrency\lockutils.py", line 271, in inner
2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f]     return f(args, *kwargs)
f(*args, **kwargs)
2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f]   File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\virt\hyperv\imagecache.py", line 117, in fetch_image_if_not_existing
2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f]     self._pathutils.remove(base_vhd_path)
2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f]   File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\oslo_utils\excutils.py", line 220, in __exit__
2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f]     self.force_reraise()
2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f]   File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\oslo_utils\excutils.py", line 196, in force_reraise
2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f]     six.reraise(self.type_, self.value, self.tb)
2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f]   File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\nova\virt\hyperv\imagecache.py", line 111, in fetch_image_if_not_existing
2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f]     format_ext = self._vhdutils.get_vhd_format(base_vhd_path)
2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f]   File "C:\Program Files\Cloudbase Solutions\OpenStack\Nova\Python27\lib\site-packages\os_win\utils\storage\virtdisk\vhdutils.py", line 153, in get_vhd_format
2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f]     _("Could not retrieve VHD format: %s") % vhd_path)
2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] NovaException: Could not retrieve VHD format: D:\OpenStack\Instances_base\96d9b017-220c-494b-97a8-1e10d968980b
D:\OpenStack\Instances\_base\96d9b017-220c-494b-97a8-1e10d968980b
2017-01-05 17:06:38.474 3924 ERROR nova.compute.manager [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] 
2017-01-05 17:06:38.506 3924 INFO nova.compute.manager [req-d2ee5ae5-8cc2-4038-94f0-bd8f94f817c3 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] Terminating instance
2017-01-05 17:06:38.538 3924 INFO nova.virt.hyperv.vmops [req-d2ee5ae5-8cc2-4038-94f0-bd8f94f817c3 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] Got request to destroy instance
2017-01-05 17:06:38.601 3924 INFO nova.compute.manager [req-d2ee5ae5-8cc2-4038-94f0-bd8f94f817c3 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] Took 0.06 seconds to destroy the instance on the hypervisor.
2017-01-05 17:06:38.819 3924 INFO nova.compute.manager [req-d2ee5ae5-8cc2-4038-94f0-bd8f94f817c3 a133f23d27604a4781acbaad108882b2 4c118ae015e84365b28786efbe85a9db - - -] [instance: 0c7122ea-a7d8-4543-8a99-13cd47c93e3f] Took 0.20 seconds to deallocate network for instance.
2017-01-05 17:06:50.904 3924 WARNING nova.compute.manager [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] While synchronizing instance power states, found 0 instances in the database and 3 instances on the hypervisor.
2017-01-05 17:07:28.309 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Auditing locally available compute resources for node HYPERV-M16
2017-01-05 17:07:28.417 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Total usable vcpus: 8, total allocated vcpus: 0
2017-01-05 17:07:28.417 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Final resource view: name=HYPERV-M16 phys_ram=32765MB used_ram=512MB phys_disk=546GB used_disk=0GB total_vcpus=8 used_vcpus=0 pci_stats=[]
2017-01-05 17:07:28.464 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Compute_service record updated for HYPERV-M16:HYPERV-M16
2017-01-05 17:08:29.305 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Auditing locally available compute resources for node HYPERV-M16
2017-01-05 17:08:29.446 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Total usable vcpus: 8, total allocated vcpus: 0
2017-01-05 17:08:29.446 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Final resource view: name=HYPERV-M16 phys_ram=32765MB used_ram=512MB phys_disk=546GB used_disk=0GB total_vcpus=8 used_vcpus=0 pci_stats=[]
2017-01-05 17:08:29.492 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Compute_service record updated for HYPERV-M16:HYPERV-M16
2017-01-05 17:09:29.315 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Auditing locally available compute resources for node HYPERV-M16
2017-01-05 17:09:29.424 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Total usable vcpus: 8, total allocated vcpus: 0
2017-01-05 17:09:29.424 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Final resource view: name=HYPERV-M16 phys_ram=32765MB used_ram=512MB phys_disk=546GB used_disk=0GB total_vcpus=8 used_vcpus=0 pci_stats=[]
2017-01-05 17:09:29.471 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Compute_service record updated for HYPERV-M16:HYPERV-M16
2017-01-05 17:10:29.328 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Auditing locally available compute resources for node HYPERV-M16
2017-01-05 17:10:29.453 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Total usable vcpus: 8, total allocated vcpus: 0
2017-01-05 17:10:29.470 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Final resource view: name=HYPERV-M16 phys_ram=32765MB used_ram=512MB phys_disk=546GB used_disk=0GB total_vcpus=8 used_vcpus=0 pci_stats=[]
2017-01-05 17:10:29.500 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Compute_service record updated for HYPERV-M16:HYPERV-M16
2017-01-05 17:11:31.339 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Auditing locally available compute resources for node HYPERV-M16
2017-01-05 17:11:31.464 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Total usable vcpus: 8, total allocated vcpus: 0
2017-01-05 17:11:31.464 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Final resource view: name=HYPERV-M16 phys_ram=32765MB used_ram=512MB phys_disk=546GB used_disk=0GB total_vcpus=8 used_vcpus=0 pci_stats=[]
2017-01-05 17:11:31.496 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Compute_service record updated for HYPERV-M16:HYPERV-M16
2017-01-05 17:12:31.306 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Auditing locally available compute resources for node HYPERV-M16
2017-01-05 17:12:31.401 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Total usable vcpus: 8, total allocated vcpus: 0
2017-01-05 17:12:31.401 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Final resource view: name=HYPERV-M16 phys_ram=32765MB used_ram=512MB phys_disk=546GB used_disk=0GB total_vcpus=8 used_vcpus=0 pci_stats=[]
2017-01-05 17:12:31.572 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Compute_service record updated for HYPERV-M16:HYPERV-M16
2017-01-05 17:13:32.306 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Auditing locally available compute resources for node HYPERV-M16
2017-01-05 17:13:32.447 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Total usable vcpus: 8, total allocated vcpus: 0
2017-01-05 17:13:32.447 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Final resource view: name=HYPERV-M16 phys_ram=32765MB used_ram=512MB phys_disk=546GB used_disk=0GB total_vcpus=8 used_vcpus=0 pci_stats=[]
2017-01-05 17:13:32.494 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Compute_service record updated for HYPERV-M16:HYPERV-M16
2017-01-05 17:14:33.351 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Auditing locally available compute resources for node HYPERV-M16
2017-01-05 17:14:33.492 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Total usable vcpus: 8, total allocated vcpus: 0
2017-01-05 17:14:33.492 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Final resource view: name=HYPERV-M16 phys_ram=32765MB used_ram=512MB phys_disk=546GB used_disk=0GB total_vcpus=8 used_vcpus=0 pci_stats=[]
2017-01-05 17:14:33.539 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Compute_service record updated for HYPERV-M16:HYPERV-M16
2017-01-05 17:15:34.331 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Auditing locally available compute resources for node HYPERV-M16
2017-01-05 17:15:34.486 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Total usable vcpus: 8, total allocated vcpus: 0
2017-01-05 17:15:34.486 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Final resource view: name=HYPERV-M16 phys_ram=32765MB used_ram=512MB phys_disk=546GB used_disk=0GB total_vcpus=8 used_vcpus=0 pci_stats=[]
2017-01-05 17:15:34.533 3924 INFO nova.compute.resource_tracker [req-b81baed6-31f5-458b-8401-4cee9509403d - - - - -] Compute_service record updated for HYPERV-M16:HYPERV-M16

HYPERV-M16:HYPERV-M16