yahoo-eng-team team mailing list archive

Thread
Date
[Bug 1457061] [NEW] Requested operation is not valid: cpu affinity is not supported

To: yahoo-eng-team@xxxxxxxxxxxxxxxxxxx
From: Pradip Rawat <pradipkumarr@xxxxxxxxxx>
Date: Wed, 20 May 2015 14:09:05 -0000
Reply-to: Bug 1457061 <1457061@xxxxxxxxxxxxxxxxxx>
Sender: bounces@xxxxxxxxxxxxx
Public bug reported:

Hi everyone,
I am installing  openstack (kilo) using official documentation. but while creating instance it's returning error "No valid host was found. There are not enough hosts available". i am using vm for installing openstack.

nova-compute.conf
------------------------------
[DEFAULT]
compute_driver=libvirt.LibvirtDriver
[libvirt]
virt_type = qemu

nova-compute.log
----------------------------
2015-05-20 18:07:56.806 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Auditing locally available compute resources for node controller
2015-05-20 18:07:56.979 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Total usable vcpus: 8, total allocated vcpus: 0
2015-05-20 18:07:56.979 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Final resource view: name=controller phys_ram=10000MB used_ram=1024MB phys_disk=94GB used_disk=1GB total_vcpus=8 used_vcpus=0 pci_stats=<nova.pci.stats.PciDeviceStats object at 0x7f9bc3758c50>
2015-05-20 18:07:57.001 8723 INFO nova.scheduler.client.report [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Compute_service record updated for ('controller', 'controller')
2015-05-20 18:07:57.002 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Compute_service record updated for controller:controller
2015-05-20 18:08:17.052 8723 INFO nova.compute.manager [req-ae0aae7e-73db-4ba2-a3ae-b62b0a51b314 f670019404f547918a0e932a6d641fd4 d91d46e009bc43d281a6144e9a5be516 - - -] [instance: 91c11a0a-29d8-4d0c-8d32-55c8bfa828d6] Terminating instance
2015-05-20 18:08:17.056 8723 INFO nova.virt.libvirt.driver [-] [instance: 91c11a0a-29d8-4d0c-8d32-55c8bfa828d6] During wait destroy, instance disappeared.
2015-05-20 18:08:17.501 8723 INFO nova.virt.libvirt.driver [req-ae0aae7e-73db-4ba2-a3ae-b62b0a51b314 f670019404f547918a0e932a6d641fd4 d91d46e009bc43d281a6144e9a5be516 - - -] [instance: 91c11a0a-29d8-4d0c-8d32-55c8bfa828d6] Deletion of /var/lib/nova/instances/91c11a0a-29d8-4d0c-8d32-55c8bfa828d6_del complete
2015-05-20 18:08:17.915 8723 INFO nova.scheduler.client.report [req-ae0aae7e-73db-4ba2-a3ae-b62b0a51b314 f670019404f547918a0e932a6d641fd4 d91d46e009bc43d281a6144e9a5be516 - - -] Compute_service record updated for ('controller', 'controller')
2015-05-20 18:08:32.440 8723 INFO nova.compute.manager [req-3ae2f763-45e8-4898-bed1-faf34ddae612 f670019404f547918a0e932a6d641fd4 d91d46e009bc43d281a6144e9a5be516 - - -] [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4] Starting instance...
2015-05-20 18:08:32.513 8723 INFO nova.compute.claims [-] [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4] Attempting claim: memory 512 MB, disk 1 GB
2015-05-20 18:08:32.513 8723 INFO nova.compute.claims [-] [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4] Total memory: 10000 MB, used: 512.00 MB
2015-05-20 18:08:32.514 8723 INFO nova.compute.claims [-] [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4] memory limit: 15000.00 MB, free: 14488.00 MB
2015-05-20 18:08:32.514 8723 INFO nova.compute.claims [-] [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4] Total disk: 94 GB, used: 0.00 GB
2015-05-20 18:08:32.514 8723 INFO nova.compute.claims [-] [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4] disk limit not specified, defaulting to unlimited
2015-05-20 18:08:32.536 8723 INFO nova.compute.claims [-] [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4] Claim successful
2015-05-20 18:08:32.629 8723 INFO nova.scheduler.client.report [-] Compute_service record updated for ('controller', 'controller')
2015-05-20 18:08:32.749 8723 INFO nova.scheduler.client.report [-] Compute_service record updated for ('controller', 'controller')
2015-05-20 18:08:32.916 8723 INFO nova.virt.libvirt.driver [req-8a0e86ca-bac8-4de7-a850-8f9f196ccbd0 - - - - -] [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4] Creating image
2015-05-20 18:08:33.104 8723 INFO nova.scheduler.client.report [-] Compute_service record updated for ('controller', 'controller')
2015-05-20 18:08:33.244 8723 INFO nova.virt.disk.vfs.api [req-8a0e86ca-bac8-4de7-a850-8f9f196ccbd0 - - - - -] Unable to import guestfs, falling back to VFSLocalFS
2015-05-20 18:08:36.522 8723 ERROR nova.virt.libvirt.driver [req-8a0e86ca-bac8-4de7-a850-8f9f196ccbd0 - - - - -] Error launching a defined domain with XML: <domain type='qemu'>
  <name>instance-00000007</name>
  <uuid>291a21ab-644b-444c-9d8b-0fe08d46e6f4</uuid>
  <metadata>
    <nova:instance xmlns:nova="http://openstack.org/xmlns/libvirt/nova/1.0";>
      <nova:package version="2015.1.0"/>
      <nova:name>vm1</nova:name>
      <nova:creationTime>2015-05-20 12:38:33</nova:creationTime>
      <nova:flavor name="m1.tiny">
        <nova:memory>512</nova:memory>
        <nova:disk>1</nova:disk>
        <nova:swap>0</nova:swap>
        <nova:ephemeral>0</nova:ephemeral>
        <nova:vcpus>1</nova:vcpus>
      </nova:flavor>
      <nova:owner>
        <nova:user uuid="f670019404f547918a0e932a6d641fd4">demo</nova:user>
        <nova:project uuid="d91d46e009bc43d281a6144e9a5be516">demo</nova:project>
      </nova:owner>
      <nova:root type="image" uuid="80c65f8e-1812-4638-a3a1-cf2e6964d2ed"/>
    </nova:instance>
  </metadata>
  <memory unit='KiB'>524288</memory>
  <currentMemory unit='KiB'>524288</currentMemory>
  <vcpu placement='static' cpuset='0-7'>1</vcpu>
  <cputune>
    <shares>1024</shares>
  </cputune>
  <sysinfo type='smbios'>
    <system>
      <entry name='manufacturer'>OpenStack Foundation</entry>
      <entry name='product'>OpenStack Nova</entry>
      <entry name='version'>2015.1.0</entry>
      <entry name='serial'>564d5c8c-7514-5f69-6bd1-c4989f7e5f02</entry>
      <entry name='uuid'>291a21ab-644b-444c-9d8b-0fe08d46e6f4</entry>
    </system>
  </sysinfo>
  <os>
    <type arch='x86_64' machine='pc-i440fx-utopic'>hvm</type>
    <boot dev='hd'/>
    <smbios mode='sysinfo'/>
  </os>
  <features>
    <acpi/>
    <apic/>
  </features>
  <cpu mode='host-model'>
    <model fallback='allow'/>
    <topology sockets='1' cores='1' threads='1'/>
  </cpu>
  <clock offset='utc'/>
  <on_poweroff>destroy</on_poweroff>
  <on_reboot>restart</on_reboot>
  <on_crash>destroy</on_crash>
  <devices>
    <emulator>/usr/bin/qemu-system-x86_64</emulator>
    <disk type='file' device='disk'>
      <driver name='qemu' type='qcow2' cache='none'/>
      <source file='/var/lib/nova/instances/291a21ab-644b-444c-9d8b-0fe08d46e6f4/disk'/>
      <target dev='vda' bus='virtio'/>
      <address type='pci' domain='0x0000' bus='0x00' slot='0x04' function='0x0'/>
    </disk>
    <controller type='usb' index='0'>
      <address type='pci' domain='0x0000' bus='0x00' slot='0x01' function='0x2'/>
    </controller>
    <controller type='pci' index='0' model='pci-root'/>
    <interface type='bridge'>
      <mac address='fa:16:3e:db:e8:42'/>
      <source bridge='qbr278130e3-c5'/>
      <target dev='tap278130e3-c5'/>
      <model type='virtio'/>
      <driver name='qemu'/>
      <address type='pci' domain='0x0000' bus='0x00' slot='0x03' function='0x0'/>
    </interface>
    <serial type='file'>
      <source path='/var/lib/nova/instances/291a21ab-644b-444c-9d8b-0fe08d46e6f4/console.log'/>
      <target port='0'/>
    </serial>
    <serial type='pty'>
      <target port='1'/>
    </serial>
    <console type='file'>
      <source path='/var/lib/nova/instances/291a21ab-644b-444c-9d8b-0fe08d46e6f4/console.log'/>
      <target type='serial' port='0'/>
    </console>
    <input type='tablet' bus='usb'/>
    <input type='mouse' bus='ps2'/>
    <input type='keyboard' bus='ps2'/>
    <graphics type='vnc' port='-1' autoport='yes' listen='0.0.0.0' keymap='en-us'>
      <listen type='address' address='0.0.0.0'/>
    </graphics>
    <video>
      <model type='cirrus' vram='16384' heads='1'/>
      <address type='pci' domain='0x0000' bus='0x00' slot='0x02' function='0x0'/>
    </video>
    <memballoon model='virtio'>
      <address type='pci' domain='0x0000' bus='0x00' slot='0x05' function='0x0'/>
      <stats period='10'/>
    </memballoon>
  </devices>
</domain>

2015-05-20 18:08:36.523 8723 ERROR nova.compute.manager [req-8a0e86ca-bac8-4de7-a850-8f9f196ccbd0 - - - - -] [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4] Instance failed to spawn
2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4] Traceback (most recent call last):
2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4]   File "/usr/lib/python2.7/dist-packages/nova/compute/manager.py", line 2442, in _build_resources
2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4]     yield resources
2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4]   File "/usr/lib/python2.7/dist-packages/nova/compute/manager.py", line 2314, in _build_and_run_instance
2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4]     block_device_info=block_device_info)
2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4]   File "/usr/lib/python2.7/dist-packages/nova/virt/libvirt/driver.py", line 2354, in spawn
2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4]     block_device_info=block_device_info)
2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4]   File "/usr/lib/python2.7/dist-packages/nova/virt/libvirt/driver.py", line 4391, in _create_domain_and_network
2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4]     power_on=power_on)
2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4]   File "/usr/lib/python2.7/dist-packages/nova/virt/libvirt/driver.py", line 4322, in _create_domain
2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4]     LOG.error(err)
2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4]   File "/usr/lib/python2.7/dist-packages/oslo_utils/excutils.py", line 85, in __exit__
2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4]     six.reraise(self.type_, self.value, self.tb)
2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4]   File "/usr/lib/python2.7/dist-packages/nova/virt/libvirt/driver.py", line 4312, in _create_domain
2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4]     domain.createWithFlags(launch_flags)
2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4]   File "/usr/lib/python2.7/dist-packages/eventlet/tpool.py", line 183, in doit
2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4]     result = proxy_call(self._autowrap, f, *args, **kwargs)
2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4]   File "/usr/lib/python2.7/dist-packages/eventlet/tpool.py", line 141, in proxy_call
2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4]     rv = execute(f, *args, **kwargs)
2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4]   File "/usr/lib/python2.7/dist-packages/eventlet/tpool.py", line 122, in execute
2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4]     six.reraise(c, e, tb)
2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4]   File "/usr/lib/python2.7/dist-packages/eventlet/tpool.py", line 80, in tworker
2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4]     rv = meth(*args, **kwargs)
2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4]   File "/usr/lib/python2.7/dist-packages/libvirt.py", line 896, in createWithFlags
2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4]     if ret == -1: raise libvirtError ('virDomainCreateWithFlags() failed', dom=self)
2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4] libvirtError: Requested operation is not valid: cpu affinity is not supported
2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4] 
2015-05-20 18:08:36.527 8723 INFO nova.compute.manager [req-3ae2f763-45e8-4898-bed1-faf34ddae612 f670019404f547918a0e932a6d641fd4 d91d46e009bc43d281a6144e9a5be516 - - -] [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4] Terminating instance
2015-05-20 18:08:36.533 8723 INFO nova.virt.libvirt.driver [-] [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4] Instance destroyed successfully.
2015-05-20 18:08:36.544 8723 INFO nova.virt.libvirt.driver [req-8a0e86ca-bac8-4de7-a850-8f9f196ccbd0 - - - - -] [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4] Deleting instance files /var/lib/nova/instances/291a21ab-644b-444c-9d8b-0fe08d46e6f4_del
2015-05-20 18:08:36.545 8723 INFO nova.virt.libvirt.driver [req-8a0e86ca-bac8-4de7-a850-8f9f196ccbd0 - - - - -] [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4] Deletion of /var/lib/nova/instances/291a21ab-644b-444c-9d8b-0fe08d46e6f4_del complete
2015-05-20 18:08:36.666 8723 INFO nova.scheduler.client.report [req-8a0e86ca-bac8-4de7-a850-8f9f196ccbd0 - - - - -] Compute_service record updated for ('controller', 'controller')
2015-05-20 18:08:56.890 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Auditing locally available compute resources for node controller
2015-05-20 18:08:57.071 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Total usable vcpus: 8, total allocated vcpus: 0
2015-05-20 18:08:57.072 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Final resource view: name=controller phys_ram=10000MB used_ram=1024MB phys_disk=94GB used_disk=1GB total_vcpus=8 used_vcpus=0 pci_stats=<nova.pci.stats.PciDeviceStats object at 0x7f9bc3758c50>
2015-05-20 18:08:57.096 8723 INFO nova.scheduler.client.report [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Compute_service record updated for ('controller', 'controller')
2015-05-20 18:08:57.097 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Compute_service record updated for controller:controller
2015-05-20 18:09:56.813 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Auditing locally available compute resources for node controller
2015-05-20 18:09:56.985 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Total usable vcpus: 8, total allocated vcpus: 0
2015-05-20 18:09:56.985 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Final resource view: name=controller phys_ram=10000MB used_ram=1024MB phys_disk=94GB used_disk=1GB total_vcpus=8 used_vcpus=0 pci_stats=<nova.pci.stats.PciDeviceStats object at 0x7f9bc3758c50>
2015-05-20 18:09:57.009 8723 INFO nova.scheduler.client.report [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Compute_service record updated for ('controller', 'controller')
2015-05-20 18:09:57.009 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Compute_service record updated for controller:controller
2015-05-20 18:10:57.843 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Auditing locally available compute resources for node controller
2015-05-20 18:10:58.012 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Total usable vcpus: 8, total allocated vcpus: 0
2015-05-20 18:10:58.013 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Final resource view: name=controller phys_ram=10000MB used_ram=1024MB phys_disk=94GB used_disk=1GB total_vcpus=8 used_vcpus=0 pci_stats=<nova.pci.stats.PciDeviceStats object at 0x7f9bc3758c50>
2015-05-20 18:10:58.033 8723 INFO nova.scheduler.client.report [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Compute_service record updated for ('controller', 'controller')
2015-05-20 18:10:58.034 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Compute_service record updated for controller:controller
2015-05-20 18:11:59.677 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Auditing locally available compute resources for node controller
2015-05-20 18:11:59.852 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Total usable vcpus: 8, total allocated vcpus: 0
2015-05-20 18:11:59.853 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Final resource view: name=controller phys_ram=10000MB used_ram=1024MB phys_disk=94GB used_disk=1GB total_vcpus=8 used_vcpus=0 pci_stats=<nova.pci.stats.PciDeviceStats object at 0x7f9bc3758c50>
2015-05-20 18:11:59.874 8723 INFO nova.scheduler.client.report [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Compute_service record updated for ('controller', 'controller')
2015-05-20 18:11:59.874 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Compute_service record updated for controller:controller
2015-05-20 18:13:01.646 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Auditing locally available compute resources for node controller
2015-05-20 18:13:01.818 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Total usable vcpus: 8, total allocated vcpus: 0
2015-05-20 18:13:01.819 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Final resource view: name=controller phys_ram=10000MB used_ram=1024MB phys_disk=94GB used_disk=1GB total_vcpus=8 used_vcpus=0 pci_stats=<nova.pci.stats.PciDeviceStats object at 0x7f9bc3758c50>
2015-05-20 18:13:01.838 8723 INFO nova.scheduler.client.report [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Compute_service record updated for ('controller', 'controller')
2015-05-20 18:13:01.839 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Compute_service record updated for controller:controller
2015-05-20 18:14:03.676 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Auditing locally available compute resources for node controller
2015-05-20 18:14:03.847 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Total usable vcpus: 8, total allocated vcpus: 0
2015-05-20 18:14:03.848 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Final resource view: name=controller phys_ram=10000MB used_ram=1024MB phys_disk=94GB used_disk=1GB total_vcpus=8 used_vcpus=0 pci_stats=<nova.pci.stats.PciDeviceStats object at 0x7f9bc3758c50>
2015-05-20 18:14:03.871 8723 INFO nova.scheduler.client.report [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Compute_service record updated for ('controller', 'controller')
2015-05-20 18:14:03.871 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Compute_service record updated for controller:controller
2015-05-20 18:15:03.646 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Auditing locally available compute resources for node controller
2015-05-20 18:15:03.815 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Total usable vcpus: 8, total allocated vcpus: 0
2015-05-20 18:15:03.816 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Final resource view: name=controller phys_ram=10000MB used_ram=1024MB phys_disk=94GB used_disk=1GB total_vcpus=8 used_vcpus=0 pci_stats=<nova.pci.stats.PciDeviceStats object at 0x7f9bc3758c50>
2015-05-20 18:15:03.838 8723 INFO nova.scheduler.client.report [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Compute_service record updated for ('controller', 'controller')
2015-05-20 18:15:03.839 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Compute_service record updated for controller:controller
2015-05-20 18:15:55.645 8723 INFO nova.compute.manager [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Updating bandwidth usage cache
2015-05-20 18:15:55.675 8723 WARNING nova.compute.manager [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Bandwidth usage not supported by hypervisor.
2015-05-20 18:16:05.646 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Auditing locally available compute resources for node controller
2015-05-20 18:16:05.815 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Total usable vcpus: 8, total allocated vcpus: 0
2015-05-20 18:16:05.815 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Final resource view: name=controller phys_ram=10000MB used_ram=1024MB phys_disk=94GB used_disk=1GB total_vcpus=8 used_vcpus=0 pci_stats=<nova.pci.stats.PciDeviceStats object at 0x7f9bc3758c50>
2015-05-20 18:16:05.836 8723 INFO nova.scheduler.client.report [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Compute_service record updated for ('controller', 'controller')
2015-05-20 18:16:05.836 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Compute_service record updated for controller:controller
2015-05-20 18:16:16.208 8723 WARNING nova.compute.manager [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] While synchronizing instance power states, found 1 instances in the database and 0 instances on the hypervisor.

** Affects: nova
     Importance: Undecided
         Status: New

-- 
You received this bug notification because you are a member of Yahoo!
Engineering Team, which is subscribed to OpenStack Compute (nova).
https://bugs.launchpad.net/bugs/1457061

Title:
  Requested operation is not valid: cpu affinity is not supported

Status in OpenStack Compute (Nova):
  New

Bug description:
  Hi everyone,
  I am installing  openstack (kilo) using official documentation. but while creating instance it's returning error "No valid host was found. There are not enough hosts available". i am using vm for installing openstack.

  nova-compute.conf
  ------------------------------
  [DEFAULT]
  compute_driver=libvirt.LibvirtDriver
  [libvirt]
  virt_type = qemu

  nova-compute.log
  ----------------------------
  2015-05-20 18:07:56.806 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Auditing locally available compute resources for node controller
  2015-05-20 18:07:56.979 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Total usable vcpus: 8, total allocated vcpus: 0
  2015-05-20 18:07:56.979 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Final resource view: name=controller phys_ram=10000MB used_ram=1024MB phys_disk=94GB used_disk=1GB total_vcpus=8 used_vcpus=0 pci_stats=<nova.pci.stats.PciDeviceStats object at 0x7f9bc3758c50>
  2015-05-20 18:07:57.001 8723 INFO nova.scheduler.client.report [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Compute_service record updated for ('controller', 'controller')
  2015-05-20 18:07:57.002 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Compute_service record updated for controller:controller
  2015-05-20 18:08:17.052 8723 INFO nova.compute.manager [req-ae0aae7e-73db-4ba2-a3ae-b62b0a51b314 f670019404f547918a0e932a6d641fd4 d91d46e009bc43d281a6144e9a5be516 - - -] [instance: 91c11a0a-29d8-4d0c-8d32-55c8bfa828d6] Terminating instance
  2015-05-20 18:08:17.056 8723 INFO nova.virt.libvirt.driver [-] [instance: 91c11a0a-29d8-4d0c-8d32-55c8bfa828d6] During wait destroy, instance disappeared.
  2015-05-20 18:08:17.501 8723 INFO nova.virt.libvirt.driver [req-ae0aae7e-73db-4ba2-a3ae-b62b0a51b314 f670019404f547918a0e932a6d641fd4 d91d46e009bc43d281a6144e9a5be516 - - -] [instance: 91c11a0a-29d8-4d0c-8d32-55c8bfa828d6] Deletion of /var/lib/nova/instances/91c11a0a-29d8-4d0c-8d32-55c8bfa828d6_del complete
  2015-05-20 18:08:17.915 8723 INFO nova.scheduler.client.report [req-ae0aae7e-73db-4ba2-a3ae-b62b0a51b314 f670019404f547918a0e932a6d641fd4 d91d46e009bc43d281a6144e9a5be516 - - -] Compute_service record updated for ('controller', 'controller')
  2015-05-20 18:08:32.440 8723 INFO nova.compute.manager [req-3ae2f763-45e8-4898-bed1-faf34ddae612 f670019404f547918a0e932a6d641fd4 d91d46e009bc43d281a6144e9a5be516 - - -] [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4] Starting instance...
  2015-05-20 18:08:32.513 8723 INFO nova.compute.claims [-] [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4] Attempting claim: memory 512 MB, disk 1 GB
  2015-05-20 18:08:32.513 8723 INFO nova.compute.claims [-] [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4] Total memory: 10000 MB, used: 512.00 MB
  2015-05-20 18:08:32.514 8723 INFO nova.compute.claims [-] [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4] memory limit: 15000.00 MB, free: 14488.00 MB
  2015-05-20 18:08:32.514 8723 INFO nova.compute.claims [-] [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4] Total disk: 94 GB, used: 0.00 GB
  2015-05-20 18:08:32.514 8723 INFO nova.compute.claims [-] [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4] disk limit not specified, defaulting to unlimited
  2015-05-20 18:08:32.536 8723 INFO nova.compute.claims [-] [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4] Claim successful
  2015-05-20 18:08:32.629 8723 INFO nova.scheduler.client.report [-] Compute_service record updated for ('controller', 'controller')
  2015-05-20 18:08:32.749 8723 INFO nova.scheduler.client.report [-] Compute_service record updated for ('controller', 'controller')
  2015-05-20 18:08:32.916 8723 INFO nova.virt.libvirt.driver [req-8a0e86ca-bac8-4de7-a850-8f9f196ccbd0 - - - - -] [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4] Creating image
  2015-05-20 18:08:33.104 8723 INFO nova.scheduler.client.report [-] Compute_service record updated for ('controller', 'controller')
  2015-05-20 18:08:33.244 8723 INFO nova.virt.disk.vfs.api [req-8a0e86ca-bac8-4de7-a850-8f9f196ccbd0 - - - - -] Unable to import guestfs, falling back to VFSLocalFS
  2015-05-20 18:08:36.522 8723 ERROR nova.virt.libvirt.driver [req-8a0e86ca-bac8-4de7-a850-8f9f196ccbd0 - - - - -] Error launching a defined domain with XML: <domain type='qemu'>
    <name>instance-00000007</name>
    <uuid>291a21ab-644b-444c-9d8b-0fe08d46e6f4</uuid>
    <metadata>
      <nova:instance xmlns:nova="http://openstack.org/xmlns/libvirt/nova/1.0";>
        <nova:package version="2015.1.0"/>
        <nova:name>vm1</nova:name>
        <nova:creationTime>2015-05-20 12:38:33</nova:creationTime>
        <nova:flavor name="m1.tiny">
          <nova:memory>512</nova:memory>
          <nova:disk>1</nova:disk>
          <nova:swap>0</nova:swap>
          <nova:ephemeral>0</nova:ephemeral>
          <nova:vcpus>1</nova:vcpus>
        </nova:flavor>
        <nova:owner>
          <nova:user uuid="f670019404f547918a0e932a6d641fd4">demo</nova:user>
          <nova:project uuid="d91d46e009bc43d281a6144e9a5be516">demo</nova:project>
        </nova:owner>
        <nova:root type="image" uuid="80c65f8e-1812-4638-a3a1-cf2e6964d2ed"/>
      </nova:instance>
    </metadata>
    <memory unit='KiB'>524288</memory>
    <currentMemory unit='KiB'>524288</currentMemory>
    <vcpu placement='static' cpuset='0-7'>1</vcpu>
    <cputune>
      <shares>1024</shares>
    </cputune>
    <sysinfo type='smbios'>
      <system>
        <entry name='manufacturer'>OpenStack Foundation</entry>
        <entry name='product'>OpenStack Nova</entry>
        <entry name='version'>2015.1.0</entry>
        <entry name='serial'>564d5c8c-7514-5f69-6bd1-c4989f7e5f02</entry>
        <entry name='uuid'>291a21ab-644b-444c-9d8b-0fe08d46e6f4</entry>
      </system>
    </sysinfo>
    <os>
      <type arch='x86_64' machine='pc-i440fx-utopic'>hvm</type>
      <boot dev='hd'/>
      <smbios mode='sysinfo'/>
    </os>
    <features>
      <acpi/>
      <apic/>
    </features>
    <cpu mode='host-model'>
      <model fallback='allow'/>
      <topology sockets='1' cores='1' threads='1'/>
    </cpu>
    <clock offset='utc'/>
    <on_poweroff>destroy</on_poweroff>
    <on_reboot>restart</on_reboot>
    <on_crash>destroy</on_crash>
    <devices>
      <emulator>/usr/bin/qemu-system-x86_64</emulator>
      <disk type='file' device='disk'>
        <driver name='qemu' type='qcow2' cache='none'/>
        <source file='/var/lib/nova/instances/291a21ab-644b-444c-9d8b-0fe08d46e6f4/disk'/>
        <target dev='vda' bus='virtio'/>
        <address type='pci' domain='0x0000' bus='0x00' slot='0x04' function='0x0'/>
      </disk>
      <controller type='usb' index='0'>
        <address type='pci' domain='0x0000' bus='0x00' slot='0x01' function='0x2'/>
      </controller>
      <controller type='pci' index='0' model='pci-root'/>
      <interface type='bridge'>
        <mac address='fa:16:3e:db:e8:42'/>
        <source bridge='qbr278130e3-c5'/>
        <target dev='tap278130e3-c5'/>
        <model type='virtio'/>
        <driver name='qemu'/>
        <address type='pci' domain='0x0000' bus='0x00' slot='0x03' function='0x0'/>
      </interface>
      <serial type='file'>
        <source path='/var/lib/nova/instances/291a21ab-644b-444c-9d8b-0fe08d46e6f4/console.log'/>
        <target port='0'/>
      </serial>
      <serial type='pty'>
        <target port='1'/>
      </serial>
      <console type='file'>
        <source path='/var/lib/nova/instances/291a21ab-644b-444c-9d8b-0fe08d46e6f4/console.log'/>
        <target type='serial' port='0'/>
      </console>
      <input type='tablet' bus='usb'/>
      <input type='mouse' bus='ps2'/>
      <input type='keyboard' bus='ps2'/>
      <graphics type='vnc' port='-1' autoport='yes' listen='0.0.0.0' keymap='en-us'>
        <listen type='address' address='0.0.0.0'/>
      </graphics>
      <video>
        <model type='cirrus' vram='16384' heads='1'/>
        <address type='pci' domain='0x0000' bus='0x00' slot='0x02' function='0x0'/>
      </video>
      <memballoon model='virtio'>
        <address type='pci' domain='0x0000' bus='0x00' slot='0x05' function='0x0'/>
        <stats period='10'/>
      </memballoon>
    </devices>
  </domain>

  2015-05-20 18:08:36.523 8723 ERROR nova.compute.manager [req-8a0e86ca-bac8-4de7-a850-8f9f196ccbd0 - - - - -] [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4] Instance failed to spawn
  2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4] Traceback (most recent call last):
  2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4]   File "/usr/lib/python2.7/dist-packages/nova/compute/manager.py", line 2442, in _build_resources
  2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4]     yield resources
  2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4]   File "/usr/lib/python2.7/dist-packages/nova/compute/manager.py", line 2314, in _build_and_run_instance
  2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4]     block_device_info=block_device_info)
  2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4]   File "/usr/lib/python2.7/dist-packages/nova/virt/libvirt/driver.py", line 2354, in spawn
  2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4]     block_device_info=block_device_info)
  2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4]   File "/usr/lib/python2.7/dist-packages/nova/virt/libvirt/driver.py", line 4391, in _create_domain_and_network
  2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4]     power_on=power_on)
  2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4]   File "/usr/lib/python2.7/dist-packages/nova/virt/libvirt/driver.py", line 4322, in _create_domain
  2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4]     LOG.error(err)
  2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4]   File "/usr/lib/python2.7/dist-packages/oslo_utils/excutils.py", line 85, in __exit__
  2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4]     six.reraise(self.type_, self.value, self.tb)
  2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4]   File "/usr/lib/python2.7/dist-packages/nova/virt/libvirt/driver.py", line 4312, in _create_domain
  2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4]     domain.createWithFlags(launch_flags)
  2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4]   File "/usr/lib/python2.7/dist-packages/eventlet/tpool.py", line 183, in doit
  2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4]     result = proxy_call(self._autowrap, f, *args, **kwargs)
  2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4]   File "/usr/lib/python2.7/dist-packages/eventlet/tpool.py", line 141, in proxy_call
  2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4]     rv = execute(f, *args, **kwargs)
  2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4]   File "/usr/lib/python2.7/dist-packages/eventlet/tpool.py", line 122, in execute
  2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4]     six.reraise(c, e, tb)
  2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4]   File "/usr/lib/python2.7/dist-packages/eventlet/tpool.py", line 80, in tworker
  2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4]     rv = meth(*args, **kwargs)
  2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4]   File "/usr/lib/python2.7/dist-packages/libvirt.py", line 896, in createWithFlags
  2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4]     if ret == -1: raise libvirtError ('virDomainCreateWithFlags() failed', dom=self)
  2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4] libvirtError: Requested operation is not valid: cpu affinity is not supported
  2015-05-20 18:08:36.523 8723 TRACE nova.compute.manager [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4] 
  2015-05-20 18:08:36.527 8723 INFO nova.compute.manager [req-3ae2f763-45e8-4898-bed1-faf34ddae612 f670019404f547918a0e932a6d641fd4 d91d46e009bc43d281a6144e9a5be516 - - -] [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4] Terminating instance
  2015-05-20 18:08:36.533 8723 INFO nova.virt.libvirt.driver [-] [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4] Instance destroyed successfully.
  2015-05-20 18:08:36.544 8723 INFO nova.virt.libvirt.driver [req-8a0e86ca-bac8-4de7-a850-8f9f196ccbd0 - - - - -] [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4] Deleting instance files /var/lib/nova/instances/291a21ab-644b-444c-9d8b-0fe08d46e6f4_del
  2015-05-20 18:08:36.545 8723 INFO nova.virt.libvirt.driver [req-8a0e86ca-bac8-4de7-a850-8f9f196ccbd0 - - - - -] [instance: 291a21ab-644b-444c-9d8b-0fe08d46e6f4] Deletion of /var/lib/nova/instances/291a21ab-644b-444c-9d8b-0fe08d46e6f4_del complete
  2015-05-20 18:08:36.666 8723 INFO nova.scheduler.client.report [req-8a0e86ca-bac8-4de7-a850-8f9f196ccbd0 - - - - -] Compute_service record updated for ('controller', 'controller')
  2015-05-20 18:08:56.890 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Auditing locally available compute resources for node controller
  2015-05-20 18:08:57.071 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Total usable vcpus: 8, total allocated vcpus: 0
  2015-05-20 18:08:57.072 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Final resource view: name=controller phys_ram=10000MB used_ram=1024MB phys_disk=94GB used_disk=1GB total_vcpus=8 used_vcpus=0 pci_stats=<nova.pci.stats.PciDeviceStats object at 0x7f9bc3758c50>
  2015-05-20 18:08:57.096 8723 INFO nova.scheduler.client.report [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Compute_service record updated for ('controller', 'controller')
  2015-05-20 18:08:57.097 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Compute_service record updated for controller:controller
  2015-05-20 18:09:56.813 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Auditing locally available compute resources for node controller
  2015-05-20 18:09:56.985 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Total usable vcpus: 8, total allocated vcpus: 0
  2015-05-20 18:09:56.985 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Final resource view: name=controller phys_ram=10000MB used_ram=1024MB phys_disk=94GB used_disk=1GB total_vcpus=8 used_vcpus=0 pci_stats=<nova.pci.stats.PciDeviceStats object at 0x7f9bc3758c50>
  2015-05-20 18:09:57.009 8723 INFO nova.scheduler.client.report [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Compute_service record updated for ('controller', 'controller')
  2015-05-20 18:09:57.009 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Compute_service record updated for controller:controller
  2015-05-20 18:10:57.843 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Auditing locally available compute resources for node controller
  2015-05-20 18:10:58.012 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Total usable vcpus: 8, total allocated vcpus: 0
  2015-05-20 18:10:58.013 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Final resource view: name=controller phys_ram=10000MB used_ram=1024MB phys_disk=94GB used_disk=1GB total_vcpus=8 used_vcpus=0 pci_stats=<nova.pci.stats.PciDeviceStats object at 0x7f9bc3758c50>
  2015-05-20 18:10:58.033 8723 INFO nova.scheduler.client.report [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Compute_service record updated for ('controller', 'controller')
  2015-05-20 18:10:58.034 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Compute_service record updated for controller:controller
  2015-05-20 18:11:59.677 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Auditing locally available compute resources for node controller
  2015-05-20 18:11:59.852 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Total usable vcpus: 8, total allocated vcpus: 0
  2015-05-20 18:11:59.853 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Final resource view: name=controller phys_ram=10000MB used_ram=1024MB phys_disk=94GB used_disk=1GB total_vcpus=8 used_vcpus=0 pci_stats=<nova.pci.stats.PciDeviceStats object at 0x7f9bc3758c50>
  2015-05-20 18:11:59.874 8723 INFO nova.scheduler.client.report [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Compute_service record updated for ('controller', 'controller')
  2015-05-20 18:11:59.874 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Compute_service record updated for controller:controller
  2015-05-20 18:13:01.646 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Auditing locally available compute resources for node controller
  2015-05-20 18:13:01.818 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Total usable vcpus: 8, total allocated vcpus: 0
  2015-05-20 18:13:01.819 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Final resource view: name=controller phys_ram=10000MB used_ram=1024MB phys_disk=94GB used_disk=1GB total_vcpus=8 used_vcpus=0 pci_stats=<nova.pci.stats.PciDeviceStats object at 0x7f9bc3758c50>
  2015-05-20 18:13:01.838 8723 INFO nova.scheduler.client.report [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Compute_service record updated for ('controller', 'controller')
  2015-05-20 18:13:01.839 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Compute_service record updated for controller:controller
  2015-05-20 18:14:03.676 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Auditing locally available compute resources for node controller
  2015-05-20 18:14:03.847 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Total usable vcpus: 8, total allocated vcpus: 0
  2015-05-20 18:14:03.848 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Final resource view: name=controller phys_ram=10000MB used_ram=1024MB phys_disk=94GB used_disk=1GB total_vcpus=8 used_vcpus=0 pci_stats=<nova.pci.stats.PciDeviceStats object at 0x7f9bc3758c50>
  2015-05-20 18:14:03.871 8723 INFO nova.scheduler.client.report [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Compute_service record updated for ('controller', 'controller')
  2015-05-20 18:14:03.871 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Compute_service record updated for controller:controller
  2015-05-20 18:15:03.646 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Auditing locally available compute resources for node controller
  2015-05-20 18:15:03.815 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Total usable vcpus: 8, total allocated vcpus: 0
  2015-05-20 18:15:03.816 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Final resource view: name=controller phys_ram=10000MB used_ram=1024MB phys_disk=94GB used_disk=1GB total_vcpus=8 used_vcpus=0 pci_stats=<nova.pci.stats.PciDeviceStats object at 0x7f9bc3758c50>
  2015-05-20 18:15:03.838 8723 INFO nova.scheduler.client.report [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Compute_service record updated for ('controller', 'controller')
  2015-05-20 18:15:03.839 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Compute_service record updated for controller:controller
  2015-05-20 18:15:55.645 8723 INFO nova.compute.manager [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Updating bandwidth usage cache
  2015-05-20 18:15:55.675 8723 WARNING nova.compute.manager [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Bandwidth usage not supported by hypervisor.
  2015-05-20 18:16:05.646 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Auditing locally available compute resources for node controller
  2015-05-20 18:16:05.815 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Total usable vcpus: 8, total allocated vcpus: 0
  2015-05-20 18:16:05.815 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Final resource view: name=controller phys_ram=10000MB used_ram=1024MB phys_disk=94GB used_disk=1GB total_vcpus=8 used_vcpus=0 pci_stats=<nova.pci.stats.PciDeviceStats object at 0x7f9bc3758c50>
  2015-05-20 18:16:05.836 8723 INFO nova.scheduler.client.report [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Compute_service record updated for ('controller', 'controller')
  2015-05-20 18:16:05.836 8723 INFO nova.compute.resource_tracker [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] Compute_service record updated for controller:controller
  2015-05-20 18:16:16.208 8723 WARNING nova.compute.manager [req-930b6e92-971b-4317-b2df-d4be4af39b89 - - - - -] While synchronizing instance power states, found 1 instances in the database and 0 instances on the hypervisor.

To manage notifications about this bug go to:
https://bugs.launchpad.net/nova/+bug/1457061/+subscriptions
Follow ups

[Bug 1457061] [NEW] Requested operation is not valid: cpu affinity is not supported
From: Pradip Rawat, 2015-05-20
References

[Bug 1457061] [NEW] Requested operation is not valid: cpu affinity is not supported
From: Pradip Rawat, 2015-05-20