yahoo-eng-team team mailing list archive

Thread
Date
[Bug 1768473] [NEW] nova.tests.functional.regressions.test_bug_1735407.TestParallelEvacuationWithServerGroup failed

To: yahoo-eng-team@xxxxxxxxxxxxxxxxxxx
From: jichenjc <jichenjc@xxxxxxxxxx>
Date: Wed, 02 May 2018 09:20:00 -0000
Reply-to: Bug 1768473 <1768473@xxxxxxxxxxxxxxxxxx>
Sender: bounces@xxxxxxxxxxxxx
Public bug reported:

my change should not related to this error, so might be a timing issue

ft1.1: nova.tests.functional.regressions.test_bug_1735407.TestParallelEvacuationWithServerGroup.test_parallel_evacuate_with_server_group_StringException: pythonlogging:'': {{{
2018-05-02 06:46:15,917 INFO [nova.service] Starting conductor node (version 18.0.0)
2018-05-02 06:46:15,966 INFO [nova.service] Starting scheduler node (version 18.0.0)
2018-05-02 06:46:16,020 INFO [nova.virt.driver] Loading compute driver 'fake.SmallFakeDriver'
2018-05-02 06:46:16,021 INFO [nova.service] Starting compute node (version 18.0.0)
2018-05-02 06:46:16,086 WARNING [nova.compute.manager] No compute node record found for host host1. If this is the first time this service is starting on this host, then you can ignore this warning.
2018-05-02 06:46:16,087 WARNING [nova.compute.monitors] Excluding nova.compute.monitors.cpu monitor virt_driver. Not in the list of enabled monitors (CONF.compute_monitors).
2018-05-02 06:46:16,098 WARNING [nova.compute.resource_tracker] No compute node record for host1:host1
2018-05-02 06:46:16,108 INFO [nova.compute.resource_tracker] Compute node record created for host1:host1 with uuid: 20f37f27-0bd2-4a23-a7b3-d879da021839
2018-05-02 06:46:16,198 INFO [nova.api.openstack.placement.objects.resource_provider] Synced traits from os_traits into API DB: set(['HW_GPU_RESOLUTION_W1024H600', 'HW_NIC_OFFLOAD_RXVLAN', 'HW_GPU_RESOLUTION_W1024H768', 'HW_CPU_AARCH64_SHA3', 'HW_CPU_AARCH64_SHA2', 'HW_CPU_AARCH64_SHA1', 'HW_CPU_X86_TSX', 'HW_GPU_API_OPENCL_V2_0', 'HW_GPU_API_OPENCL_V2_1', 'HW_NIC_SRIOV', 'HW_GPU_RESOLUTION_W1152H864', 'HW_CPU_X86_AVX512F', 'HW_GPU_API_CUDA_V5_3', 'HW_GPU_API_CUDA_V5_2', 'HW_CPU_X86_CLMUL', 'HW_GPU_API_CUDA_V5_0', 'HW_GPU_API_DIRECTX_V12', 'HW_GPU_API_DIRECTX_V10', 'HW_GPU_API_DIRECTX_V11', 'HW_CPU_X86_TBM', 'HW_NIC_OFFLOAD_SCS', 'HW_NIC_OFFLOAD_QINQ', 'HW_GPU_API_OPENCL_V2_2', 'HW_NIC_OFFLOAD_FDF', 'MISC_SHARES_VIA_AGGREGATE', 'HW_CPU_X86_SVM', 'HW_CPU_X86_FMA4', 'HW_CPU_X86_FMA3', 'HW_NIC_OFFLOAD_GRE', 'HW_CPU_AARCH64_ASIMDHP', 'HW_CPU_AARCH64_ATOMICS', 'HW_CPU_X86_MPX', 'HW_NIC_OFFLOAD_UCS', 'HW_CPU_X86_SSE3', 'HW_CPU_X86_SSE2', 'HW_NIC_OFFLOAD_VXLAN', 'HW_GPU_API_CUDA_V6_0', 'HW_GPU_API_CUDA_V6_1', 'HW_GPU_API_CUDA_V6_2', 'HW_NIC_VMDQ', 'HW_CPU_AARCH64_AES', 'HW_GPU_API_DIRECT3D_V12_0', 'HW_CPU_X86_AVX512VL', 'HW_CPU_X86_3DNOW', 'HW_CPU_AARCH64_SM3', 'HW_CPU_AARCH64_ASIMD', 'HW_GPU_API_DIRECT3D_V9_0B', 'HW_NIC_ACCEL_TLS', 'HW_GPU_API_DIRECT3D_V7_0', 'HW_GPU_API_DIRECT3D_V9_0L', 'HW_CPU_X86_AVX512ER', 'HW_GPU_RESOLUTION_W1280H720', 'HW_GPU_API_CUDA_V1_0', 'HW_GPU_API_OPENGL_V4_5', 'HW_NIC_ACCEL_ECC', 'HW_NIC_OFFLOAD_LRO', 'HW_CPU_X86_VMX', 'HW_GPU_API_CUDA_V1_3', 'HW_NIC_OFFLOAD_TXVLAN', 'HW_NIC_SRIOV_QOS_TX', 'HW_GPU_API_CUDA_V2_0', 'HW_GPU_RESOLUTION_W1360H768', 'HW_GPU_MAX_DISPLAY_HEADS_8', 'HW_GPU_API_CUDA_V1_1', 'HW_GPU_MAX_DISPLAY_HEADS_2', 'HW_GPU_MAX_DISPLAY_HEADS_1', 'HW_GPU_MAX_DISPLAY_HEADS_6', 'HW_GPU_MAX_DISPLAY_HEADS_4', 'HW_GPU_RESOLUTION_W2560H1440', 'HW_GPU_RESOLUTION_W2560H1600', 'HW_NIC_DCB_ETS', 'HW_CPU_AARCH64_PMULL', 'HW_GPU_RESOLUTION_W1600H1200', 'HW_CPU_AARCH64_EVTSTRM', 'HW_GPU_RESOLUTION_W640H480', 'HW_GPU_RESOLUTION_W1920H1080', 'HW_GPU_API_DIRECT3D_V10_1', 'HW_GPU_API_DIRECT3D_V10_0', 'HW_CPU_X86_AVX512DQ', 'HW_GPU_API_VULKAN', 'HW_NIC_ACCEL_DIFFIEH', 'HW_CPU_X86_SGX', 'HW_NIC_ACCEL_DEFLATE', 'HW_GPU_API_OPENCL_V1_1', 'HW_GPU_API_OPENCL_V1_0', 'HW_CPU_X86_AVX', 'HW_GPU_API_OPENCL_V1_2', 'HW_GPU_API_DIRECT2D', 'HW_CPU_AARCH64_LRCPC', 'HW_GPU_API_CUDA_V7_0', 'HW_NIC_OFFLOAD_UFO', 'HW_NIC_SRIOV_MULTIQUEUE', 'HW_CPU_X86_BMI2', 'HW_CPU_X86_AVX2', 'HW_GPU_API_OPENGL_V2_1', 'HW_GPU_API_OPENGL_V1_3', 'HW_CPU_X86_AVX512CD', 'HW_GPU_RESOLUTION_W800H600', 'HW_GPU_RESOLUTION_W1366H768', 'HW_NIC_OFFLOAD_TSO', 'HW_NIC_SRIOV_QOS_RX', 'HW_NIC_OFFLOAD_LSO', 'HW_NIC_OFFLOAD_SWITCHDEV', 'HW_NIC_OFFLOAD_SG', 'HW_GPU_API_OPENGL_V1_2', 'STORAGE_DISK_HDD', 'HW_NIC_PROGRAMMABLE_PIPELINE', 'HW_CPU_X86_F16C', 'HW_GPU_API_DIRECT3D_V9_0C', 'HW_CPU_AARCH64_CPUID', 'HW_GPU_API_CUDA_V3_5', 'HW_GPU_API_CUDA_V3_7', 'HW_CPU_X86_AVX512BW', 'HW_CPU_AARCH64_SM4', 'HW_CPU_AARCH64_SHA512', 'HW_NIC_DCB_QCN', 'HW_CPU_X86_BMI', 'HW_GPU_API_DIRECT3D_V11_0', 'HW_GPU_API_DIRECT3D_V11_1', 'HW_NIC_OFFLOAD_L2CRC', 'HW_GPU_API_DIRECT3D_V11_3', 'HW_NIC_ACCEL_RSA', 'HW_NIC_OFFLOAD_RXHASH', 'HW_GPU_RESOLUTION_W1440H900', 'HW_GPU_API_CUDA_V3_2', 'HW_CPU_AARCH64_ASIMDDP', 'HW_CPU_X86_XOP', 'HW_NIC_OFFLOAD_RX', 'HW_GPU_API_DIRECT3D_V9_0', 'HW_NIC_OFFLOAD_TXUDP', 'HW_CPU_AARCH64_SVE', 'HW_NIC_DCB_PFC', 'HW_GPU_API_DIRECT3D_V6_0', 'HW_NIC_ACCEL_SSL', 'HW_NIC_SRIOV_TRUSTED', 'HW_GPU_API_OPENGL_V3_3', 'HW_CPU_AARCH64_FPHP', 'HW_NIC_OFFLOAD_GSO', 'HW_NIC_OFFLOAD_TCS', 'HW_GPU_API_OPENGL_V3_0', 'HW_CPU_X86_AVX512PF', 'HW_CPU_X86_ABM', 'STORAGE_DISK_SSD', 'HW_CPU_X86_SSE41', 'HW_CPU_X86_SSE42', 'HW_GPU_API_CUDA_V2_1', 'HW_GPU_RESOLUTION_W1280H800', 'HW_CPU_X86_ASF', 'HW_CPU_X86_AESNI', 'HW_CPU_X86_SHA', 'HW_GPU_RESOLUTION_W1680H1050', 'HW_GPU_API_DIRECT3D_V11_2', 'HW_NIC_OFFLOAD_GRO', 'HW_GPU_RESOLUTION_W1280H768', 'HW_GPU_API_OPENGL_V4_4', 'HW_NIC_OFFLOAD_RDMA', 'HW_GPU_API_OPENGL_V4_0', 'HW_GPU_API_OPENGL_V4_1', 'HW_GPU_API_OPENGL_V4_2', 'HW_GPU_API_OPENGL_V4_3', 'HW_CPU_AARCH64_FCMA', 'HW_NIC_ACCEL_LZS', 'HW_GPU_RESOLUTION_W1280H1024', 'HW_GPU_RESOLUTION_W1920H1200', 'HW_NIC_OFFLOAD_GENEVE', 'HW_GPU_API_CUDA_V7_1', 'HW_CPU_X86_SSE4A', 'HW_GPU_API_OPENGL_V2_0', 'HW_CPU_X86_MMX', 'HW_GPU_RESOLUTION_W3840H2160', 'HW_NIC_MULTIQUEUE', 'HW_CPU_AARCH64_DCPOP', 'HW_GPU_RESOLUTION_W1600H900', 'HW_GPU_API_DXVA', 'HW_GPU_RESOLUTION_W7680H4320', 'HW_CPU_AARCH64_FP', 'HW_CPU_AARCH64_JSCVT', 'HW_CPU_X86_SSSE3', 'HW_CPU_AARCH64_CRC32', 'HW_GPU_API_CUDA_V1_2', 'HW_GPU_API_OPENGL_V3_2', 'HW_GPU_API_OPENGL_V3_1', 'HW_GPU_API_CUDA_V3_0', 'HW_NIC_ACCEL_IPSEC', 'HW_CPU_AARCH64_ASIMDRDM', 'HW_GPU_RESOLUTION_W320H240', 'HW_NIC_OFFLOAD_TX', 'HW_GPU_API_OPENGL_V1_1', 'HW_GPU_API_DIRECT3D_V8_1', 'HW_GPU_API_DIRECT3D_V8_0', 'HW_GPU_API_OPENGL_V1_5', 'HW_GPU_API_OPENGL_V1_4', 'HW_CPU_X86_SSE'])
2018-05-02 06:46:16,266 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=20f37f27-0bd2-4a23-a7b3-d879da021839" status: 200 len: 26 microversion: 1.14
2018-05-02 06:46:16,286 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "POST /placement/resource_providers" status: 200 len: 828 microversion: 1.20
2018-05-02 06:46:16,288 INFO [nova.scheduler.client.report] [req-23e8001c-fd5d-4a83-b4ba-08a1ecb58fd7] Created resource provider record via placement API for resource provider with UUID 20f37f27-0bd2-4a23-a7b3-d879da021839 and name host1.
2018-05-02 06:46:16,338 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/20f37f27-0bd2-4a23-a7b3-d879da021839/inventories" status: 200 len: 54 microversion: 1.0
2018-05-02 06:46:16,395 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=20f37f27-0bd2-4a23-a7b3-d879da021839" status: 200 len: 854 microversion: 1.14
2018-05-02 06:46:16,426 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/20f37f27-0bd2-4a23-a7b3-d879da021839/inventories" status: 200 len: 54 microversion: 1.0
2018-05-02 06:46:16,439 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/20f37f27-0bd2-4a23-a7b3-d879da021839/aggregates" status: 200 len: 18 microversion: 1.1
2018-05-02 06:46:16,481 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/20f37f27-0bd2-4a23-a7b3-d879da021839/traits" status: 200 len: 49 microversion: 1.6
2018-05-02 06:46:16,506 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/20f37f27-0bd2-4a23-a7b3-d879da021839/inventories" status: 200 len: 54 microversion: 1.0
2018-05-02 06:46:16,536 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "PUT /placement/resource_providers/20f37f27-0bd2-4a23-a7b3-d879da021839/inventories" status: 200 len: 399 microversion: 1.0
2018-05-02 06:46:16,594 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/20f37f27-0bd2-4a23-a7b3-d879da021839/allocations" status: 200 len: 54 microversion: 1.0
2018-05-02 06:46:16,596 INFO [nova.compute.resource_tracker] Final resource view: name=host1 phys_ram=8192MB used_ram=512MB phys_disk=1028GB used_disk=0GB total_vcpus=2 used_vcpus=0 pci_stats=[]
2018-05-02 06:46:16,633 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=20f37f27-0bd2-4a23-a7b3-d879da021839" status: 200 len: 854 microversion: 1.14
2018-05-02 06:46:16,652 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/20f37f27-0bd2-4a23-a7b3-d879da021839/inventories" status: 200 len: 399 microversion: 1.0
2018-05-02 06:46:16,668 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/20f37f27-0bd2-4a23-a7b3-d879da021839/aggregates" status: 200 len: 18 microversion: 1.1
2018-05-02 06:46:16,686 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/20f37f27-0bd2-4a23-a7b3-d879da021839/traits" status: 200 len: 49 microversion: 1.6
2018-05-02 06:46:16,704 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/20f37f27-0bd2-4a23-a7b3-d879da021839/inventories" status: 200 len: 399 microversion: 1.0
2018-05-02 06:46:16,727 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=20f37f27-0bd2-4a23-a7b3-d879da021839" status: 200 len: 854 microversion: 1.14
2018-05-02 06:46:16,746 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/20f37f27-0bd2-4a23-a7b3-d879da021839/inventories" status: 200 len: 399 microversion: 1.0
2018-05-02 06:46:16,761 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/20f37f27-0bd2-4a23-a7b3-d879da021839/aggregates" status: 200 len: 18 microversion: 1.1
2018-05-02 06:46:16,779 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/20f37f27-0bd2-4a23-a7b3-d879da021839/traits" status: 200 len: 49 microversion: 1.6
2018-05-02 06:46:16,798 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/20f37f27-0bd2-4a23-a7b3-d879da021839/inventories" status: 200 len: 399 microversion: 1.0
2018-05-02 06:46:16,811 INFO [nova.virt.driver] Loading compute driver 'fake.SmallFakeDriver'
2018-05-02 06:46:16,812 INFO [nova.service] Starting compute node (version 18.0.0)
2018-05-02 06:46:16,863 WARNING [nova.compute.manager] No compute node record found for host host2. If this is the first time this service is starting on this host, then you can ignore this warning.
2018-05-02 06:46:16,864 WARNING [nova.compute.monitors] Excluding nova.compute.monitors.cpu monitor virt_driver. Not in the list of enabled monitors (CONF.compute_monitors).
2018-05-02 06:46:16,874 WARNING [nova.compute.resource_tracker] No compute node record for host2:host2
2018-05-02 06:46:16,880 INFO [nova.compute.resource_tracker] Compute node record created for host2:host2 with uuid: 6b75720c-cd49-4cef-a051-7dfad4e0a2eb
2018-05-02 06:46:16,921 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=6b75720c-cd49-4cef-a051-7dfad4e0a2eb" status: 200 len: 26 microversion: 1.14
2018-05-02 06:46:16,936 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "POST /placement/resource_providers" status: 200 len: 828 microversion: 1.20
2018-05-02 06:46:16,938 INFO [nova.scheduler.client.report] [req-9ec49ca9-af55-4b76-a8e6-72d2b580b4fc] Created resource provider record via placement API for resource provider with UUID 6b75720c-cd49-4cef-a051-7dfad4e0a2eb and name host2.
2018-05-02 06:46:16,956 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6b75720c-cd49-4cef-a051-7dfad4e0a2eb/inventories" status: 200 len: 54 microversion: 1.0
2018-05-02 06:46:16,979 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=6b75720c-cd49-4cef-a051-7dfad4e0a2eb" status: 200 len: 854 microversion: 1.14
2018-05-02 06:46:17,015 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6b75720c-cd49-4cef-a051-7dfad4e0a2eb/inventories" status: 200 len: 54 microversion: 1.0
2018-05-02 06:46:17,040 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6b75720c-cd49-4cef-a051-7dfad4e0a2eb/aggregates" status: 200 len: 18 microversion: 1.1
2018-05-02 06:46:17,067 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6b75720c-cd49-4cef-a051-7dfad4e0a2eb/traits" status: 200 len: 49 microversion: 1.6
2018-05-02 06:46:17,097 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6b75720c-cd49-4cef-a051-7dfad4e0a2eb/inventories" status: 200 len: 54 microversion: 1.0
2018-05-02 06:46:17,147 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "PUT /placement/resource_providers/6b75720c-cd49-4cef-a051-7dfad4e0a2eb/inventories" status: 200 len: 399 microversion: 1.0
2018-05-02 06:46:17,239 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6b75720c-cd49-4cef-a051-7dfad4e0a2eb/allocations" status: 200 len: 54 microversion: 1.0
2018-05-02 06:46:17,241 INFO [nova.compute.resource_tracker] Final resource view: name=host2 phys_ram=8192MB used_ram=512MB phys_disk=1028GB used_disk=0GB total_vcpus=2 used_vcpus=0 pci_stats=[]
2018-05-02 06:46:17,282 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=6b75720c-cd49-4cef-a051-7dfad4e0a2eb" status: 200 len: 854 microversion: 1.14
2018-05-02 06:46:17,301 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6b75720c-cd49-4cef-a051-7dfad4e0a2eb/inventories" status: 200 len: 399 microversion: 1.0
2018-05-02 06:46:17,317 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6b75720c-cd49-4cef-a051-7dfad4e0a2eb/aggregates" status: 200 len: 18 microversion: 1.1
2018-05-02 06:46:17,335 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6b75720c-cd49-4cef-a051-7dfad4e0a2eb/traits" status: 200 len: 49 microversion: 1.6
2018-05-02 06:46:17,354 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6b75720c-cd49-4cef-a051-7dfad4e0a2eb/inventories" status: 200 len: 399 microversion: 1.0
2018-05-02 06:46:17,377 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=6b75720c-cd49-4cef-a051-7dfad4e0a2eb" status: 200 len: 854 microversion: 1.14
2018-05-02 06:46:17,395 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6b75720c-cd49-4cef-a051-7dfad4e0a2eb/inventories" status: 200 len: 399 microversion: 1.0
2018-05-02 06:46:17,411 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6b75720c-cd49-4cef-a051-7dfad4e0a2eb/aggregates" status: 200 len: 18 microversion: 1.1
2018-05-02 06:46:17,429 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6b75720c-cd49-4cef-a051-7dfad4e0a2eb/traits" status: 200 len: 49 microversion: 1.6
2018-05-02 06:46:17,442 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6b75720c-cd49-4cef-a051-7dfad4e0a2eb/inventories" status: 200 len: 399 microversion: 1.0
2018-05-02 06:46:17,453 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1" status: 204 len: 0 microversion: - time: 0.000773
2018-05-02 06:46:17,782 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1/6f70656e737461636b20342065766572/images/detail" status: 200 len: 5609 microversion: 2.14 time: 0.323356
2018-05-02 06:46:17,854 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1/6f70656e737461636b20342065766572/flavors/detail" status: 200 len: 2747 microversion: 2.14 time: 0.065201
2018-05-02 06:46:18,066 INFO [nova.api.openstack.requestlog] 127.0.0.1 "POST /v2.1/6f70656e737461636b20342065766572/os-server-groups" status: 200 len: 214 microversion: 2.14 time: 0.205434
2018-05-02 06:46:18,416 INFO [nova.api.openstack.requestlog] 127.0.0.1 "POST /v2.1/6f70656e737461636b20342065766572/servers" status: 202 len: 480 microversion: 2.14 time: 0.344958
2018-05-02 06:46:18,583 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1/6f70656e737461636b20342065766572/servers/a474ffac-3b78-4c54-95bd-20f4daced15c" status: 200 len: 1737 microversion: 2.14 time: 0.161672
2018-05-02 06:46:18,701 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/allocation_candidates?limit=1000&resources=DISK_GB%3A1%2CMEMORY_MB%3A512%2CVCPU%3A1" status: 200 len: 677 microversion: 1.21
2018-05-02 06:46:18,807 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/allocations/a474ffac-3b78-4c54-95bd-20f4daced15c" status: 200 len: 19 microversion: 1.0
2018-05-02 06:46:18,854 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "PUT /placement/allocations/a474ffac-3b78-4c54-95bd-20f4daced15c" status: 204 len: 0 microversion: 1.21
2018-05-02 06:46:19,324 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1/6f70656e737461636b20342065766572/servers/a474ffac-3b78-4c54-95bd-20f4daced15c" status: 200 len: 1758 microversion: 2.14 time: 0.231676
2018-05-02 06:46:19,466 INFO [nova.compute.claims] Attempting claim on node host1: memory 512 MB, disk 1 GB, vcpus 1 CPU
2018-05-02 06:46:19,466 INFO [nova.compute.claims] Total memory: 8192 MB, used: 512.00 MB
2018-05-02 06:46:19,466 INFO [nova.compute.claims] memory limit not specified, defaulting to unlimited
2018-05-02 06:46:19,467 INFO [nova.compute.claims] Total disk: 1028 GB, used: 0.00 GB
2018-05-02 06:46:19,467 INFO [nova.compute.claims] disk limit not specified, defaulting to unlimited
2018-05-02 06:46:19,467 INFO [nova.compute.claims] Total vcpu: 2 VCPU, used: 0.00 VCPU
2018-05-02 06:46:19,467 INFO [nova.compute.claims] vcpu limit not specified, defaulting to unlimited
2018-05-02 06:46:19,467 INFO [nova.compute.claims] Claim successful on node host1
2018-05-02 06:46:19,681 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=20f37f27-0bd2-4a23-a7b3-d879da021839" status: 200 len: 854 microversion: 1.14
2018-05-02 06:46:19,700 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/20f37f27-0bd2-4a23-a7b3-d879da021839/inventories" status: 200 len: 399 microversion: 1.0
2018-05-02 06:46:19,716 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/20f37f27-0bd2-4a23-a7b3-d879da021839/aggregates" status: 200 len: 18 microversion: 1.1
2018-05-02 06:46:19,734 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/20f37f27-0bd2-4a23-a7b3-d879da021839/traits" status: 200 len: 49 microversion: 1.6
2018-05-02 06:46:19,751 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/20f37f27-0bd2-4a23-a7b3-d879da021839/inventories" status: 200 len: 399 microversion: 1.0
2018-05-02 06:46:19,776 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=20f37f27-0bd2-4a23-a7b3-d879da021839" status: 200 len: 854 microversion: 1.14
2018-05-02 06:46:19,794 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/20f37f27-0bd2-4a23-a7b3-d879da021839/inventories" status: 200 len: 399 microversion: 1.0
2018-05-02 06:46:19,810 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/20f37f27-0bd2-4a23-a7b3-d879da021839/aggregates" status: 200 len: 18 microversion: 1.1
2018-05-02 06:46:19,849 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/20f37f27-0bd2-4a23-a7b3-d879da021839/traits" status: 200 len: 49 microversion: 1.6
2018-05-02 06:46:19,943 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/20f37f27-0bd2-4a23-a7b3-d879da021839/inventories" status: 200 len: 399 microversion: 1.0
2018-05-02 06:46:20,017 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1/6f70656e737461636b20342065766572/servers/a474ffac-3b78-4c54-95bd-20f4daced15c" status: 200 len: 1812 microversion: 2.14 time: 0.181288
2018-05-02 06:46:20,127 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/allocations/a474ffac-3b78-4c54-95bd-20f4daced15c" status: 200 len: 134 microversion: 1.0
2018-05-02 06:46:20,229 INFO [nova.compute.manager] Took 0.00 seconds to spawn the instance on the hypervisor.
2018-05-02 06:46:20,345 INFO [nova.compute.manager] Took 0.90 seconds to build instance.
2018-05-02 06:46:20,409 INFO [nova.scheduler.host_manager] Received an update from an unknown host 'host1'. Re-created its InstanceList.
2018-05-02 06:46:20,671 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1/6f70656e737461636b20342065766572/servers/a474ffac-3b78-4c54-95bd-20f4daced15c" status: 200 len: 1841 microversion: 2.14 time: 0.148823
2018-05-02 06:46:21,113 INFO [nova.api.openstack.requestlog] 127.0.0.1 "POST /v2.1/6f70656e737461636b20342065766572/servers" status: 202 len: 480 microversion: 2.14 time: 0.435586
2018-05-02 06:46:21,324 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1/6f70656e737461636b20342065766572/servers/d899c9b6-e643-400c-a085-321fd8816bd3" status: 200 len: 1737 microversion: 2.14 time: 0.205507
2018-05-02 06:46:21,428 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/allocation_candidates?limit=1000&resources=DISK_GB%3A1%2CMEMORY_MB%3A512%2CVCPU%3A1" status: 200 len: 679 microversion: 1.21
2018-05-02 06:46:21,531 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/allocations/d899c9b6-e643-400c-a085-321fd8816bd3" status: 200 len: 19 microversion: 1.0
2018-05-02 06:46:21,566 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "PUT /placement/allocations/d899c9b6-e643-400c-a085-321fd8816bd3" status: 204 len: 0 microversion: 1.21
2018-05-02 06:46:21,916 INFO [nova.compute.claims] Attempting claim on node host2: memory 512 MB, disk 1 GB, vcpus 1 CPU
2018-05-02 06:46:21,917 INFO [nova.compute.claims] Total memory: 8192 MB, used: 512.00 MB
2018-05-02 06:46:21,917 INFO [nova.compute.claims] memory limit not specified, defaulting to unlimited
2018-05-02 06:46:21,917 INFO [nova.compute.claims] Total disk: 1028 GB, used: 0.00 GB
2018-05-02 06:46:21,918 INFO [nova.compute.claims] disk limit not specified, defaulting to unlimited
2018-05-02 06:46:21,918 INFO [nova.compute.claims] Total vcpu: 2 VCPU, used: 0.00 VCPU
2018-05-02 06:46:21,918 INFO [nova.compute.claims] vcpu limit not specified, defaulting to unlimited
2018-05-02 06:46:21,919 INFO [nova.compute.claims] Claim successful on node host2
2018-05-02 06:46:22,162 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1/6f70656e737461636b20342065766572/servers/d899c9b6-e643-400c-a085-321fd8816bd3" status: 200 len: 1750 microversion: 2.14 time: 0.300493
2018-05-02 06:46:22,189 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=6b75720c-cd49-4cef-a051-7dfad4e0a2eb" status: 200 len: 854 microversion: 1.14
2018-05-02 06:46:22,208 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6b75720c-cd49-4cef-a051-7dfad4e0a2eb/inventories" status: 200 len: 399 microversion: 1.0
2018-05-02 06:46:22,225 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6b75720c-cd49-4cef-a051-7dfad4e0a2eb/aggregates" status: 200 len: 18 microversion: 1.1
2018-05-02 06:46:22,244 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6b75720c-cd49-4cef-a051-7dfad4e0a2eb/traits" status: 200 len: 49 microversion: 1.6
2018-05-02 06:46:22,281 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6b75720c-cd49-4cef-a051-7dfad4e0a2eb/inventories" status: 200 len: 399 microversion: 1.0
2018-05-02 06:46:22,353 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=6b75720c-cd49-4cef-a051-7dfad4e0a2eb" status: 200 len: 854 microversion: 1.14
2018-05-02 06:46:22,386 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6b75720c-cd49-4cef-a051-7dfad4e0a2eb/inventories" status: 200 len: 399 microversion: 1.0
2018-05-02 06:46:22,397 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6b75720c-cd49-4cef-a051-7dfad4e0a2eb/aggregates" status: 200 len: 18 microversion: 1.1
2018-05-02 06:46:22,411 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6b75720c-cd49-4cef-a051-7dfad4e0a2eb/traits" status: 200 len: 49 microversion: 1.6
2018-05-02 06:46:22,424 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6b75720c-cd49-4cef-a051-7dfad4e0a2eb/inventories" status: 200 len: 399 microversion: 1.0
2018-05-02 06:46:22,554 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/allocations/d899c9b6-e643-400c-a085-321fd8816bd3" status: 200 len: 134 microversion: 1.0
2018-05-02 06:46:22,649 INFO [nova.compute.manager] Took 0.00 seconds to spawn the instance on the hypervisor.
2018-05-02 06:46:22,772 INFO [nova.compute.manager] Took 0.89 seconds to build instance.
2018-05-02 06:46:23,102 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1/6f70656e737461636b20342065766572/servers/d899c9b6-e643-400c-a085-321fd8816bd3" status: 200 len: 1841 microversion: 2.14 time: 0.369750
2018-05-02 06:46:23,129 INFO [nova.scheduler.host_manager] Received an update from an unknown host 'host2'. Re-created its InstanceList.
2018-05-02 06:46:23,164 INFO [nova.api.openstack.requestlog] 127.0.0.1 "PUT /v2.1/6f70656e737461636b20342065766572/os-services/force-down" status: 200 len: 77 microversion: 2.14 time: 0.031380
2018-05-02 06:46:23,219 INFO [nova.api.openstack.requestlog] 127.0.0.1 "PUT /v2.1/6f70656e737461636b20342065766572/os-services/force-down" status: 200 len: 77 microversion: 2.14 time: 0.035129
2018-05-02 06:46:23,229 INFO [nova.virt.driver] Loading compute driver 'fake.SmallFakeDriver'
2018-05-02 06:46:23,230 INFO [nova.service] Starting compute node (version 18.0.0)
2018-05-02 06:46:23,276 WARNING [nova.compute.manager] No compute node record found for host host3. If this is the first time this service is starting on this host, then you can ignore this warning.
2018-05-02 06:46:23,277 WARNING [nova.compute.monitors] Excluding nova.compute.monitors.cpu monitor virt_driver. Not in the list of enabled monitors (CONF.compute_monitors).
2018-05-02 06:46:23,285 WARNING [nova.compute.resource_tracker] No compute node record for host3:host3
2018-05-02 06:46:23,289 INFO [nova.compute.resource_tracker] Compute node record created for host3:host3 with uuid: 6a84d2a4-ae03-48f8-8737-dc695c1c7c31
2018-05-02 06:46:23,330 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=6a84d2a4-ae03-48f8-8737-dc695c1c7c31" status: 200 len: 26 microversion: 1.14
2018-05-02 06:46:23,344 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "POST /placement/resource_providers" status: 200 len: 828 microversion: 1.20
2018-05-02 06:46:23,346 INFO [nova.scheduler.client.report] [req-21ea3b51-1c0f-4b13-bef8-43d8217723d3] Created resource provider record via placement API for resource provider with UUID 6a84d2a4-ae03-48f8-8737-dc695c1c7c31 and name host3.
2018-05-02 06:46:23,363 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/inventories" status: 200 len: 54 microversion: 1.0
2018-05-02 06:46:23,380 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=6a84d2a4-ae03-48f8-8737-dc695c1c7c31" status: 200 len: 854 microversion: 1.14
2018-05-02 06:46:23,392 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/inventories" status: 200 len: 54 microversion: 1.0
2018-05-02 06:46:23,407 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/aggregates" status: 200 len: 18 microversion: 1.1
2018-05-02 06:46:23,425 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/traits" status: 200 len: 49 microversion: 1.6
2018-05-02 06:46:23,446 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/inventories" status: 200 len: 54 microversion: 1.0
2018-05-02 06:46:23,480 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "PUT /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/inventories" status: 200 len: 399 microversion: 1.0
2018-05-02 06:46:23,544 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/allocations" status: 200 len: 54 microversion: 1.0
2018-05-02 06:46:23,546 INFO [nova.compute.resource_tracker] Final resource view: name=host3 phys_ram=8192MB used_ram=512MB phys_disk=1028GB used_disk=0GB total_vcpus=2 used_vcpus=0 pci_stats=[]
2018-05-02 06:46:23,585 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=6a84d2a4-ae03-48f8-8737-dc695c1c7c31" status: 200 len: 854 microversion: 1.14
2018-05-02 06:46:23,605 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/inventories" status: 200 len: 399 microversion: 1.0
2018-05-02 06:46:23,623 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/aggregates" status: 200 len: 18 microversion: 1.1
2018-05-02 06:46:23,642 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/traits" status: 200 len: 49 microversion: 1.6
2018-05-02 06:46:23,661 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/inventories" status: 200 len: 399 microversion: 1.0
2018-05-02 06:46:23,686 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=6a84d2a4-ae03-48f8-8737-dc695c1c7c31" status: 200 len: 854 microversion: 1.14
2018-05-02 06:46:23,705 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/inventories" status: 200 len: 399 microversion: 1.0
2018-05-02 06:46:23,723 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/aggregates" status: 200 len: 18 microversion: 1.1
2018-05-02 06:46:23,741 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/traits" status: 200 len: 49 microversion: 1.6
2018-05-02 06:46:23,767 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/inventories" status: 200 len: 399 microversion: 1.0
2018-05-02 06:46:24,082 INFO [nova.api.openstack.requestlog] 127.0.0.1 "POST /v2.1/6f70656e737461636b20342065766572/servers/a474ffac-3b78-4c54-95bd-20f4daced15c/action" status: 200 len: 0 microversion: 2.14 time: 0.305313
2018-05-02 06:46:24,480 INFO [nova.api.openstack.requestlog] 127.0.0.1 "POST /v2.1/6f70656e737461636b20342065766572/servers/d899c9b6-e643-400c-a085-321fd8816bd3/action" status: 200 len: 0 microversion: 2.14 time: 0.394764
2018-05-02 06:46:24,579 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/allocation_candidates?limit=1000&resources=DISK_GB%3A1%2CMEMORY_MB%3A512%2CVCPU%3A1" status: 200 len: 995 microversion: 1.21
2018-05-02 06:46:24,834 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/allocation_candidates?limit=1000&resources=DISK_GB%3A1%2CMEMORY_MB%3A512%2CVCPU%3A1" status: 200 len: 995 microversion: 1.21
2018-05-02 06:46:24,926 INFO [nova.scheduler.host_manager] Host filter ignoring hosts: host1
2018-05-02 06:46:24,927 WARNING [nova.scheduler.filters.compute_filter] (host2, host2) ram: 7168MB disk: 0MB io_ops: 1 instances: 1 has not been heard from in a while
2018-05-02 06:46:24,969 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/allocations/a474ffac-3b78-4c54-95bd-20f4daced15c" status: 200 len: 134 microversion: 1.0
2018-05-02 06:46:25,061 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "PUT /placement/allocations/a474ffac-3b78-4c54-95bd-20f4daced15c" status: 204 len: 0 microversion: 1.21
2018-05-02 06:46:25,115 INFO [nova.scheduler.host_manager] Host filter ignoring hosts: host2
2018-05-02 06:46:25,116 WARNING [nova.scheduler.filters.compute_filter] (host1, host1) ram: 7168MB disk: 0MB io_ops: 1 instances: 1 has not been heard from in a while
2018-05-02 06:46:25,159 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/allocations/d899c9b6-e643-400c-a085-321fd8816bd3" status: 200 len: 134 microversion: 1.0
2018-05-02 06:46:25,200 INFO [nova.compute.manager] Evacuating instance
2018-05-02 06:46:25,236 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "PUT /placement/allocations/d899c9b6-e643-400c-a085-321fd8816bd3" status: 204 len: 0 microversion: 1.21
2018-05-02 06:46:25,247 INFO [nova.compute.claims] Attempting claim on node host3: memory 512 MB, disk 1 GB, vcpus 1 CPU
2018-05-02 06:46:25,248 INFO [nova.compute.claims] Total memory: 8192 MB, used: 512.00 MB
2018-05-02 06:46:25,248 INFO [nova.compute.claims] memory limit not specified, defaulting to unlimited
2018-05-02 06:46:25,248 INFO [nova.compute.claims] Total disk: 1028 GB, used: 0.00 GB
2018-05-02 06:46:25,248 INFO [nova.compute.claims] disk limit not specified, defaulting to unlimited
2018-05-02 06:46:25,249 INFO [nova.compute.claims] Total vcpu: 2 VCPU, used: 0.00 VCPU
2018-05-02 06:46:25,249 INFO [nova.compute.claims] vcpu limit not specified, defaulting to unlimited
2018-05-02 06:46:25,249 INFO [nova.compute.claims] Claim successful on node host3
2018-05-02 06:46:25,416 INFO [nova.compute.resource_tracker] Updating from migration a474ffac-3b78-4c54-95bd-20f4daced15c
2018-05-02 06:46:25,431 INFO [nova.compute.manager] Evacuating instance
2018-05-02 06:46:25,453 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=6a84d2a4-ae03-48f8-8737-dc695c1c7c31" status: 200 len: 854 microversion: 1.14
2018-05-02 06:46:25,472 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/inventories" status: 200 len: 399 microversion: 1.0
2018-05-02 06:46:25,507 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/aggregates" status: 200 len: 18 microversion: 1.1
2018-05-02 06:46:25,596 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/traits" status: 200 len: 49 microversion: 1.6
2018-05-02 06:46:25,658 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1/6f70656e737461636b20342065766572/servers/a474ffac-3b78-4c54-95bd-20f4daced15c" status: 200 len: 1850 microversion: 2.14 time: 0.163443
2018-05-02 06:46:25,662 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/inventories" status: 200 len: 399 microversion: 1.0
2018-05-02 06:46:25,685 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=6a84d2a4-ae03-48f8-8737-dc695c1c7c31" status: 200 len: 854 microversion: 1.14
2018-05-02 06:46:25,704 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/inventories" status: 200 len: 399 microversion: 1.0
2018-05-02 06:46:25,719 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/aggregates" status: 200 len: 18 microversion: 1.1
2018-05-02 06:46:25,737 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/traits" status: 200 len: 49 microversion: 1.6
2018-05-02 06:46:25,755 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/inventories" status: 200 len: 399 microversion: 1.0
2018-05-02 06:46:25,773 INFO [nova.compute.claims] Attempting claim on node host3: memory 512 MB, disk 1 GB, vcpus 1 CPU
2018-05-02 06:46:25,774 INFO [nova.compute.claims] Total memory: 8192 MB, used: 1024.00 MB
2018-05-02 06:46:25,774 INFO [nova.compute.claims] memory limit not specified, defaulting to unlimited
2018-05-02 06:46:25,774 INFO [nova.compute.claims] Total disk: 1028 GB, used: 1.00 GB
2018-05-02 06:46:25,774 INFO [nova.compute.claims] disk limit not specified, defaulting to unlimited
2018-05-02 06:46:25,775 INFO [nova.compute.claims] Total vcpu: 2 VCPU, used: 1.00 VCPU
2018-05-02 06:46:25,775 INFO [nova.compute.claims] vcpu limit not specified, defaulting to unlimited
2018-05-02 06:46:25,775 INFO [nova.compute.claims] Claim successful on node host3
2018-05-02 06:46:25,894 INFO [nova.compute.resource_tracker] Updating from migration d899c9b6-e643-400c-a085-321fd8816bd3
2018-05-02 06:46:25,931 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=6a84d2a4-ae03-48f8-8737-dc695c1c7c31" status: 200 len: 854 microversion: 1.14
2018-05-02 06:46:25,951 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/inventories" status: 200 len: 399 microversion: 1.0
2018-05-02 06:46:25,968 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/aggregates" status: 200 len: 18 microversion: 1.1
2018-05-02 06:46:25,985 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/traits" status: 200 len: 49 microversion: 1.6
2018-05-02 06:46:26,030 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/inventories" status: 200 len: 399 microversion: 1.0
2018-05-02 06:46:26,067 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=6a84d2a4-ae03-48f8-8737-dc695c1c7c31" status: 200 len: 854 microversion: 1.14
2018-05-02 06:46:26,108 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/inventories" status: 200 len: 399 microversion: 1.0
2018-05-02 06:46:26,125 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/aggregates" status: 200 len: 18 microversion: 1.1
2018-05-02 06:46:26,146 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/traits" status: 200 len: 49 microversion: 1.6
2018-05-02 06:46:26,251 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/inventories" status: 200 len: 399 microversion: 1.0
2018-05-02 06:46:26,403 INFO [nova.compute.manager] disk not on shared storage, evacuating from: 'a2459075-d96c-40d5-893e-577ff92e721c'
2018-05-02 06:46:26,427 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1/6f70656e737461636b20342065766572/servers/a474ffac-3b78-4c54-95bd-20f4daced15c" status: 200 len: 1850 microversion: 2.14 time: 0.231836
2018-05-02 06:46:26,551 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/allocations/d899c9b6-e643-400c-a085-321fd8816bd3" status: 200 len: 251 microversion: 1.0
2018-05-02 06:46:26,971 INFO [nova.compute.manager] disk not on shared storage, evacuating from: 'a2459075-d96c-40d5-893e-577ff92e721c'
2018-05-02 06:46:27,286 INFO [nova.scheduler.host_manager] Received an update from an unknown host 'host3'. Re-created its InstanceList.
2018-05-02 06:46:27,336 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/allocations/a474ffac-3b78-4c54-95bd-20f4daced15c" status: 200 len: 251 microversion: 1.0
2018-05-02 06:46:27,361 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1/6f70656e737461636b20342065766572/servers/a474ffac-3b78-4c54-95bd-20f4daced15c" status: 200 len: 1850 microversion: 2.14 time: 0.379597
2018-05-02 06:46:28,022 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1/6f70656e737461636b20342065766572/servers/a474ffac-3b78-4c54-95bd-20f4daced15c" status: 200 len: 1841 microversion: 2.14 time: 0.155013
2018-05-02 06:46:28,181 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1/6f70656e737461636b20342065766572/servers/d899c9b6-e643-400c-a085-321fd8816bd3" status: 200 len: 1841 microversion: 2.14 time: 0.152980
2018-05-02 06:46:28,225 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1/6f70656e737461636b20342065766572/os-migrations" status: 200 len: 736 microversion: 2.14 time: 0.038190
2018-05-02 06:46:28,265 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1/6f70656e737461636b20342065766572/os-migrations" status: 200 len: 736 microversion: 2.14 time: 0.018824
2018-05-02 06:46:28,766 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1/6f70656e737461636b20342065766572/servers/a474ffac-3b78-4c54-95bd-20f4daced15c" status: 200 len: 1841 microversion: 2.14 time: 0.495266
2018-05-02 06:46:28,865 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1/6f70656e737461636b20342065766572/servers/d899c9b6-e643-400c-a085-321fd8816bd3" status: 200 len: 1841 microversion: 2.14 time: 0.095275
}}}

Traceback (most recent call last):
  File "nova/tests/functional/regressions/test_bug_1735407.py", line 158, in test_parallel_evacuate_with_server_group
    server2['OS-EXT-SRV-ATTR:host'])
  File "/home/zuul/src/git.openstack.org/openstack/nova/.tox/functional/local/lib/python2.7/site-packages/unittest2/case.py", line 845, in assertNotEqual
    raise self.failureException(msg)
AssertionError: u'host3' == u'host3'

** Affects: nova
     Importance: Undecided
     Assignee: jichenjc (jichenjc)
         Status: New

** Changed in: nova
     Assignee: (unassigned) => jichenjc (jichenjc)

-- 
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/1768473

Title:
  nova.tests.functional.regressions.test_bug_1735407.TestParallelEvacuationWithServerGroup
  failed

Status in OpenStack Compute (nova):
  New

Bug description:
  my change should not related to this error, so might be a timing issue

  ft1.1: nova.tests.functional.regressions.test_bug_1735407.TestParallelEvacuationWithServerGroup.test_parallel_evacuate_with_server_group_StringException: pythonlogging:'': {{{
  2018-05-02 06:46:15,917 INFO [nova.service] Starting conductor node (version 18.0.0)
  2018-05-02 06:46:15,966 INFO [nova.service] Starting scheduler node (version 18.0.0)
  2018-05-02 06:46:16,020 INFO [nova.virt.driver] Loading compute driver 'fake.SmallFakeDriver'
  2018-05-02 06:46:16,021 INFO [nova.service] Starting compute node (version 18.0.0)
  2018-05-02 06:46:16,086 WARNING [nova.compute.manager] No compute node record found for host host1. If this is the first time this service is starting on this host, then you can ignore this warning.
  2018-05-02 06:46:16,087 WARNING [nova.compute.monitors] Excluding nova.compute.monitors.cpu monitor virt_driver. Not in the list of enabled monitors (CONF.compute_monitors).
  2018-05-02 06:46:16,098 WARNING [nova.compute.resource_tracker] No compute node record for host1:host1
  2018-05-02 06:46:16,108 INFO [nova.compute.resource_tracker] Compute node record created for host1:host1 with uuid: 20f37f27-0bd2-4a23-a7b3-d879da021839
  2018-05-02 06:46:16,198 INFO [nova.api.openstack.placement.objects.resource_provider] Synced traits from os_traits into API DB: set(['HW_GPU_RESOLUTION_W1024H600', 'HW_NIC_OFFLOAD_RXVLAN', 'HW_GPU_RESOLUTION_W1024H768', 'HW_CPU_AARCH64_SHA3', 'HW_CPU_AARCH64_SHA2', 'HW_CPU_AARCH64_SHA1', 'HW_CPU_X86_TSX', 'HW_GPU_API_OPENCL_V2_0', 'HW_GPU_API_OPENCL_V2_1', 'HW_NIC_SRIOV', 'HW_GPU_RESOLUTION_W1152H864', 'HW_CPU_X86_AVX512F', 'HW_GPU_API_CUDA_V5_3', 'HW_GPU_API_CUDA_V5_2', 'HW_CPU_X86_CLMUL', 'HW_GPU_API_CUDA_V5_0', 'HW_GPU_API_DIRECTX_V12', 'HW_GPU_API_DIRECTX_V10', 'HW_GPU_API_DIRECTX_V11', 'HW_CPU_X86_TBM', 'HW_NIC_OFFLOAD_SCS', 'HW_NIC_OFFLOAD_QINQ', 'HW_GPU_API_OPENCL_V2_2', 'HW_NIC_OFFLOAD_FDF', 'MISC_SHARES_VIA_AGGREGATE', 'HW_CPU_X86_SVM', 'HW_CPU_X86_FMA4', 'HW_CPU_X86_FMA3', 'HW_NIC_OFFLOAD_GRE', 'HW_CPU_AARCH64_ASIMDHP', 'HW_CPU_AARCH64_ATOMICS', 'HW_CPU_X86_MPX', 'HW_NIC_OFFLOAD_UCS', 'HW_CPU_X86_SSE3', 'HW_CPU_X86_SSE2', 'HW_NIC_OFFLOAD_VXLAN', 'HW_GPU_API_CUDA_V6_0', 'HW_GPU_API_CUDA_V6_1', 'HW_GPU_API_CUDA_V6_2', 'HW_NIC_VMDQ', 'HW_CPU_AARCH64_AES', 'HW_GPU_API_DIRECT3D_V12_0', 'HW_CPU_X86_AVX512VL', 'HW_CPU_X86_3DNOW', 'HW_CPU_AARCH64_SM3', 'HW_CPU_AARCH64_ASIMD', 'HW_GPU_API_DIRECT3D_V9_0B', 'HW_NIC_ACCEL_TLS', 'HW_GPU_API_DIRECT3D_V7_0', 'HW_GPU_API_DIRECT3D_V9_0L', 'HW_CPU_X86_AVX512ER', 'HW_GPU_RESOLUTION_W1280H720', 'HW_GPU_API_CUDA_V1_0', 'HW_GPU_API_OPENGL_V4_5', 'HW_NIC_ACCEL_ECC', 'HW_NIC_OFFLOAD_LRO', 'HW_CPU_X86_VMX', 'HW_GPU_API_CUDA_V1_3', 'HW_NIC_OFFLOAD_TXVLAN', 'HW_NIC_SRIOV_QOS_TX', 'HW_GPU_API_CUDA_V2_0', 'HW_GPU_RESOLUTION_W1360H768', 'HW_GPU_MAX_DISPLAY_HEADS_8', 'HW_GPU_API_CUDA_V1_1', 'HW_GPU_MAX_DISPLAY_HEADS_2', 'HW_GPU_MAX_DISPLAY_HEADS_1', 'HW_GPU_MAX_DISPLAY_HEADS_6', 'HW_GPU_MAX_DISPLAY_HEADS_4', 'HW_GPU_RESOLUTION_W2560H1440', 'HW_GPU_RESOLUTION_W2560H1600', 'HW_NIC_DCB_ETS', 'HW_CPU_AARCH64_PMULL', 'HW_GPU_RESOLUTION_W1600H1200', 'HW_CPU_AARCH64_EVTSTRM', 'HW_GPU_RESOLUTION_W640H480', 'HW_GPU_RESOLUTION_W1920H1080', 'HW_GPU_API_DIRECT3D_V10_1', 'HW_GPU_API_DIRECT3D_V10_0', 'HW_CPU_X86_AVX512DQ', 'HW_GPU_API_VULKAN', 'HW_NIC_ACCEL_DIFFIEH', 'HW_CPU_X86_SGX', 'HW_NIC_ACCEL_DEFLATE', 'HW_GPU_API_OPENCL_V1_1', 'HW_GPU_API_OPENCL_V1_0', 'HW_CPU_X86_AVX', 'HW_GPU_API_OPENCL_V1_2', 'HW_GPU_API_DIRECT2D', 'HW_CPU_AARCH64_LRCPC', 'HW_GPU_API_CUDA_V7_0', 'HW_NIC_OFFLOAD_UFO', 'HW_NIC_SRIOV_MULTIQUEUE', 'HW_CPU_X86_BMI2', 'HW_CPU_X86_AVX2', 'HW_GPU_API_OPENGL_V2_1', 'HW_GPU_API_OPENGL_V1_3', 'HW_CPU_X86_AVX512CD', 'HW_GPU_RESOLUTION_W800H600', 'HW_GPU_RESOLUTION_W1366H768', 'HW_NIC_OFFLOAD_TSO', 'HW_NIC_SRIOV_QOS_RX', 'HW_NIC_OFFLOAD_LSO', 'HW_NIC_OFFLOAD_SWITCHDEV', 'HW_NIC_OFFLOAD_SG', 'HW_GPU_API_OPENGL_V1_2', 'STORAGE_DISK_HDD', 'HW_NIC_PROGRAMMABLE_PIPELINE', 'HW_CPU_X86_F16C', 'HW_GPU_API_DIRECT3D_V9_0C', 'HW_CPU_AARCH64_CPUID', 'HW_GPU_API_CUDA_V3_5', 'HW_GPU_API_CUDA_V3_7', 'HW_CPU_X86_AVX512BW', 'HW_CPU_AARCH64_SM4', 'HW_CPU_AARCH64_SHA512', 'HW_NIC_DCB_QCN', 'HW_CPU_X86_BMI', 'HW_GPU_API_DIRECT3D_V11_0', 'HW_GPU_API_DIRECT3D_V11_1', 'HW_NIC_OFFLOAD_L2CRC', 'HW_GPU_API_DIRECT3D_V11_3', 'HW_NIC_ACCEL_RSA', 'HW_NIC_OFFLOAD_RXHASH', 'HW_GPU_RESOLUTION_W1440H900', 'HW_GPU_API_CUDA_V3_2', 'HW_CPU_AARCH64_ASIMDDP', 'HW_CPU_X86_XOP', 'HW_NIC_OFFLOAD_RX', 'HW_GPU_API_DIRECT3D_V9_0', 'HW_NIC_OFFLOAD_TXUDP', 'HW_CPU_AARCH64_SVE', 'HW_NIC_DCB_PFC', 'HW_GPU_API_DIRECT3D_V6_0', 'HW_NIC_ACCEL_SSL', 'HW_NIC_SRIOV_TRUSTED', 'HW_GPU_API_OPENGL_V3_3', 'HW_CPU_AARCH64_FPHP', 'HW_NIC_OFFLOAD_GSO', 'HW_NIC_OFFLOAD_TCS', 'HW_GPU_API_OPENGL_V3_0', 'HW_CPU_X86_AVX512PF', 'HW_CPU_X86_ABM', 'STORAGE_DISK_SSD', 'HW_CPU_X86_SSE41', 'HW_CPU_X86_SSE42', 'HW_GPU_API_CUDA_V2_1', 'HW_GPU_RESOLUTION_W1280H800', 'HW_CPU_X86_ASF', 'HW_CPU_X86_AESNI', 'HW_CPU_X86_SHA', 'HW_GPU_RESOLUTION_W1680H1050', 'HW_GPU_API_DIRECT3D_V11_2', 'HW_NIC_OFFLOAD_GRO', 'HW_GPU_RESOLUTION_W1280H768', 'HW_GPU_API_OPENGL_V4_4', 'HW_NIC_OFFLOAD_RDMA', 'HW_GPU_API_OPENGL_V4_0', 'HW_GPU_API_OPENGL_V4_1', 'HW_GPU_API_OPENGL_V4_2', 'HW_GPU_API_OPENGL_V4_3', 'HW_CPU_AARCH64_FCMA', 'HW_NIC_ACCEL_LZS', 'HW_GPU_RESOLUTION_W1280H1024', 'HW_GPU_RESOLUTION_W1920H1200', 'HW_NIC_OFFLOAD_GENEVE', 'HW_GPU_API_CUDA_V7_1', 'HW_CPU_X86_SSE4A', 'HW_GPU_API_OPENGL_V2_0', 'HW_CPU_X86_MMX', 'HW_GPU_RESOLUTION_W3840H2160', 'HW_NIC_MULTIQUEUE', 'HW_CPU_AARCH64_DCPOP', 'HW_GPU_RESOLUTION_W1600H900', 'HW_GPU_API_DXVA', 'HW_GPU_RESOLUTION_W7680H4320', 'HW_CPU_AARCH64_FP', 'HW_CPU_AARCH64_JSCVT', 'HW_CPU_X86_SSSE3', 'HW_CPU_AARCH64_CRC32', 'HW_GPU_API_CUDA_V1_2', 'HW_GPU_API_OPENGL_V3_2', 'HW_GPU_API_OPENGL_V3_1', 'HW_GPU_API_CUDA_V3_0', 'HW_NIC_ACCEL_IPSEC', 'HW_CPU_AARCH64_ASIMDRDM', 'HW_GPU_RESOLUTION_W320H240', 'HW_NIC_OFFLOAD_TX', 'HW_GPU_API_OPENGL_V1_1', 'HW_GPU_API_DIRECT3D_V8_1', 'HW_GPU_API_DIRECT3D_V8_0', 'HW_GPU_API_OPENGL_V1_5', 'HW_GPU_API_OPENGL_V1_4', 'HW_CPU_X86_SSE'])
  2018-05-02 06:46:16,266 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=20f37f27-0bd2-4a23-a7b3-d879da021839" status: 200 len: 26 microversion: 1.14
  2018-05-02 06:46:16,286 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "POST /placement/resource_providers" status: 200 len: 828 microversion: 1.20
  2018-05-02 06:46:16,288 INFO [nova.scheduler.client.report] [req-23e8001c-fd5d-4a83-b4ba-08a1ecb58fd7] Created resource provider record via placement API for resource provider with UUID 20f37f27-0bd2-4a23-a7b3-d879da021839 and name host1.
  2018-05-02 06:46:16,338 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/20f37f27-0bd2-4a23-a7b3-d879da021839/inventories" status: 200 len: 54 microversion: 1.0
  2018-05-02 06:46:16,395 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=20f37f27-0bd2-4a23-a7b3-d879da021839" status: 200 len: 854 microversion: 1.14
  2018-05-02 06:46:16,426 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/20f37f27-0bd2-4a23-a7b3-d879da021839/inventories" status: 200 len: 54 microversion: 1.0
  2018-05-02 06:46:16,439 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/20f37f27-0bd2-4a23-a7b3-d879da021839/aggregates" status: 200 len: 18 microversion: 1.1
  2018-05-02 06:46:16,481 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/20f37f27-0bd2-4a23-a7b3-d879da021839/traits" status: 200 len: 49 microversion: 1.6
  2018-05-02 06:46:16,506 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/20f37f27-0bd2-4a23-a7b3-d879da021839/inventories" status: 200 len: 54 microversion: 1.0
  2018-05-02 06:46:16,536 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "PUT /placement/resource_providers/20f37f27-0bd2-4a23-a7b3-d879da021839/inventories" status: 200 len: 399 microversion: 1.0
  2018-05-02 06:46:16,594 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/20f37f27-0bd2-4a23-a7b3-d879da021839/allocations" status: 200 len: 54 microversion: 1.0
  2018-05-02 06:46:16,596 INFO [nova.compute.resource_tracker] Final resource view: name=host1 phys_ram=8192MB used_ram=512MB phys_disk=1028GB used_disk=0GB total_vcpus=2 used_vcpus=0 pci_stats=[]
  2018-05-02 06:46:16,633 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=20f37f27-0bd2-4a23-a7b3-d879da021839" status: 200 len: 854 microversion: 1.14
  2018-05-02 06:46:16,652 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/20f37f27-0bd2-4a23-a7b3-d879da021839/inventories" status: 200 len: 399 microversion: 1.0
  2018-05-02 06:46:16,668 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/20f37f27-0bd2-4a23-a7b3-d879da021839/aggregates" status: 200 len: 18 microversion: 1.1
  2018-05-02 06:46:16,686 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/20f37f27-0bd2-4a23-a7b3-d879da021839/traits" status: 200 len: 49 microversion: 1.6
  2018-05-02 06:46:16,704 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/20f37f27-0bd2-4a23-a7b3-d879da021839/inventories" status: 200 len: 399 microversion: 1.0
  2018-05-02 06:46:16,727 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=20f37f27-0bd2-4a23-a7b3-d879da021839" status: 200 len: 854 microversion: 1.14
  2018-05-02 06:46:16,746 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/20f37f27-0bd2-4a23-a7b3-d879da021839/inventories" status: 200 len: 399 microversion: 1.0
  2018-05-02 06:46:16,761 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/20f37f27-0bd2-4a23-a7b3-d879da021839/aggregates" status: 200 len: 18 microversion: 1.1
  2018-05-02 06:46:16,779 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/20f37f27-0bd2-4a23-a7b3-d879da021839/traits" status: 200 len: 49 microversion: 1.6
  2018-05-02 06:46:16,798 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/20f37f27-0bd2-4a23-a7b3-d879da021839/inventories" status: 200 len: 399 microversion: 1.0
  2018-05-02 06:46:16,811 INFO [nova.virt.driver] Loading compute driver 'fake.SmallFakeDriver'
  2018-05-02 06:46:16,812 INFO [nova.service] Starting compute node (version 18.0.0)
  2018-05-02 06:46:16,863 WARNING [nova.compute.manager] No compute node record found for host host2. If this is the first time this service is starting on this host, then you can ignore this warning.
  2018-05-02 06:46:16,864 WARNING [nova.compute.monitors] Excluding nova.compute.monitors.cpu monitor virt_driver. Not in the list of enabled monitors (CONF.compute_monitors).
  2018-05-02 06:46:16,874 WARNING [nova.compute.resource_tracker] No compute node record for host2:host2
  2018-05-02 06:46:16,880 INFO [nova.compute.resource_tracker] Compute node record created for host2:host2 with uuid: 6b75720c-cd49-4cef-a051-7dfad4e0a2eb
  2018-05-02 06:46:16,921 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=6b75720c-cd49-4cef-a051-7dfad4e0a2eb" status: 200 len: 26 microversion: 1.14
  2018-05-02 06:46:16,936 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "POST /placement/resource_providers" status: 200 len: 828 microversion: 1.20
  2018-05-02 06:46:16,938 INFO [nova.scheduler.client.report] [req-9ec49ca9-af55-4b76-a8e6-72d2b580b4fc] Created resource provider record via placement API for resource provider with UUID 6b75720c-cd49-4cef-a051-7dfad4e0a2eb and name host2.
  2018-05-02 06:46:16,956 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6b75720c-cd49-4cef-a051-7dfad4e0a2eb/inventories" status: 200 len: 54 microversion: 1.0
  2018-05-02 06:46:16,979 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=6b75720c-cd49-4cef-a051-7dfad4e0a2eb" status: 200 len: 854 microversion: 1.14
  2018-05-02 06:46:17,015 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6b75720c-cd49-4cef-a051-7dfad4e0a2eb/inventories" status: 200 len: 54 microversion: 1.0
  2018-05-02 06:46:17,040 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6b75720c-cd49-4cef-a051-7dfad4e0a2eb/aggregates" status: 200 len: 18 microversion: 1.1
  2018-05-02 06:46:17,067 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6b75720c-cd49-4cef-a051-7dfad4e0a2eb/traits" status: 200 len: 49 microversion: 1.6
  2018-05-02 06:46:17,097 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6b75720c-cd49-4cef-a051-7dfad4e0a2eb/inventories" status: 200 len: 54 microversion: 1.0
  2018-05-02 06:46:17,147 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "PUT /placement/resource_providers/6b75720c-cd49-4cef-a051-7dfad4e0a2eb/inventories" status: 200 len: 399 microversion: 1.0
  2018-05-02 06:46:17,239 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6b75720c-cd49-4cef-a051-7dfad4e0a2eb/allocations" status: 200 len: 54 microversion: 1.0
  2018-05-02 06:46:17,241 INFO [nova.compute.resource_tracker] Final resource view: name=host2 phys_ram=8192MB used_ram=512MB phys_disk=1028GB used_disk=0GB total_vcpus=2 used_vcpus=0 pci_stats=[]
  2018-05-02 06:46:17,282 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=6b75720c-cd49-4cef-a051-7dfad4e0a2eb" status: 200 len: 854 microversion: 1.14
  2018-05-02 06:46:17,301 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6b75720c-cd49-4cef-a051-7dfad4e0a2eb/inventories" status: 200 len: 399 microversion: 1.0
  2018-05-02 06:46:17,317 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6b75720c-cd49-4cef-a051-7dfad4e0a2eb/aggregates" status: 200 len: 18 microversion: 1.1
  2018-05-02 06:46:17,335 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6b75720c-cd49-4cef-a051-7dfad4e0a2eb/traits" status: 200 len: 49 microversion: 1.6
  2018-05-02 06:46:17,354 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6b75720c-cd49-4cef-a051-7dfad4e0a2eb/inventories" status: 200 len: 399 microversion: 1.0
  2018-05-02 06:46:17,377 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=6b75720c-cd49-4cef-a051-7dfad4e0a2eb" status: 200 len: 854 microversion: 1.14
  2018-05-02 06:46:17,395 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6b75720c-cd49-4cef-a051-7dfad4e0a2eb/inventories" status: 200 len: 399 microversion: 1.0
  2018-05-02 06:46:17,411 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6b75720c-cd49-4cef-a051-7dfad4e0a2eb/aggregates" status: 200 len: 18 microversion: 1.1
  2018-05-02 06:46:17,429 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6b75720c-cd49-4cef-a051-7dfad4e0a2eb/traits" status: 200 len: 49 microversion: 1.6
  2018-05-02 06:46:17,442 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6b75720c-cd49-4cef-a051-7dfad4e0a2eb/inventories" status: 200 len: 399 microversion: 1.0
  2018-05-02 06:46:17,453 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1" status: 204 len: 0 microversion: - time: 0.000773
  2018-05-02 06:46:17,782 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1/6f70656e737461636b20342065766572/images/detail" status: 200 len: 5609 microversion: 2.14 time: 0.323356
  2018-05-02 06:46:17,854 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1/6f70656e737461636b20342065766572/flavors/detail" status: 200 len: 2747 microversion: 2.14 time: 0.065201
  2018-05-02 06:46:18,066 INFO [nova.api.openstack.requestlog] 127.0.0.1 "POST /v2.1/6f70656e737461636b20342065766572/os-server-groups" status: 200 len: 214 microversion: 2.14 time: 0.205434
  2018-05-02 06:46:18,416 INFO [nova.api.openstack.requestlog] 127.0.0.1 "POST /v2.1/6f70656e737461636b20342065766572/servers" status: 202 len: 480 microversion: 2.14 time: 0.344958
  2018-05-02 06:46:18,583 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1/6f70656e737461636b20342065766572/servers/a474ffac-3b78-4c54-95bd-20f4daced15c" status: 200 len: 1737 microversion: 2.14 time: 0.161672
  2018-05-02 06:46:18,701 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/allocation_candidates?limit=1000&resources=DISK_GB%3A1%2CMEMORY_MB%3A512%2CVCPU%3A1" status: 200 len: 677 microversion: 1.21
  2018-05-02 06:46:18,807 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/allocations/a474ffac-3b78-4c54-95bd-20f4daced15c" status: 200 len: 19 microversion: 1.0
  2018-05-02 06:46:18,854 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "PUT /placement/allocations/a474ffac-3b78-4c54-95bd-20f4daced15c" status: 204 len: 0 microversion: 1.21
  2018-05-02 06:46:19,324 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1/6f70656e737461636b20342065766572/servers/a474ffac-3b78-4c54-95bd-20f4daced15c" status: 200 len: 1758 microversion: 2.14 time: 0.231676
  2018-05-02 06:46:19,466 INFO [nova.compute.claims] Attempting claim on node host1: memory 512 MB, disk 1 GB, vcpus 1 CPU
  2018-05-02 06:46:19,466 INFO [nova.compute.claims] Total memory: 8192 MB, used: 512.00 MB
  2018-05-02 06:46:19,466 INFO [nova.compute.claims] memory limit not specified, defaulting to unlimited
  2018-05-02 06:46:19,467 INFO [nova.compute.claims] Total disk: 1028 GB, used: 0.00 GB
  2018-05-02 06:46:19,467 INFO [nova.compute.claims] disk limit not specified, defaulting to unlimited
  2018-05-02 06:46:19,467 INFO [nova.compute.claims] Total vcpu: 2 VCPU, used: 0.00 VCPU
  2018-05-02 06:46:19,467 INFO [nova.compute.claims] vcpu limit not specified, defaulting to unlimited
  2018-05-02 06:46:19,467 INFO [nova.compute.claims] Claim successful on node host1
  2018-05-02 06:46:19,681 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=20f37f27-0bd2-4a23-a7b3-d879da021839" status: 200 len: 854 microversion: 1.14
  2018-05-02 06:46:19,700 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/20f37f27-0bd2-4a23-a7b3-d879da021839/inventories" status: 200 len: 399 microversion: 1.0
  2018-05-02 06:46:19,716 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/20f37f27-0bd2-4a23-a7b3-d879da021839/aggregates" status: 200 len: 18 microversion: 1.1
  2018-05-02 06:46:19,734 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/20f37f27-0bd2-4a23-a7b3-d879da021839/traits" status: 200 len: 49 microversion: 1.6
  2018-05-02 06:46:19,751 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/20f37f27-0bd2-4a23-a7b3-d879da021839/inventories" status: 200 len: 399 microversion: 1.0
  2018-05-02 06:46:19,776 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=20f37f27-0bd2-4a23-a7b3-d879da021839" status: 200 len: 854 microversion: 1.14
  2018-05-02 06:46:19,794 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/20f37f27-0bd2-4a23-a7b3-d879da021839/inventories" status: 200 len: 399 microversion: 1.0
  2018-05-02 06:46:19,810 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/20f37f27-0bd2-4a23-a7b3-d879da021839/aggregates" status: 200 len: 18 microversion: 1.1
  2018-05-02 06:46:19,849 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/20f37f27-0bd2-4a23-a7b3-d879da021839/traits" status: 200 len: 49 microversion: 1.6
  2018-05-02 06:46:19,943 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/20f37f27-0bd2-4a23-a7b3-d879da021839/inventories" status: 200 len: 399 microversion: 1.0
  2018-05-02 06:46:20,017 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1/6f70656e737461636b20342065766572/servers/a474ffac-3b78-4c54-95bd-20f4daced15c" status: 200 len: 1812 microversion: 2.14 time: 0.181288
  2018-05-02 06:46:20,127 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/allocations/a474ffac-3b78-4c54-95bd-20f4daced15c" status: 200 len: 134 microversion: 1.0
  2018-05-02 06:46:20,229 INFO [nova.compute.manager] Took 0.00 seconds to spawn the instance on the hypervisor.
  2018-05-02 06:46:20,345 INFO [nova.compute.manager] Took 0.90 seconds to build instance.
  2018-05-02 06:46:20,409 INFO [nova.scheduler.host_manager] Received an update from an unknown host 'host1'. Re-created its InstanceList.
  2018-05-02 06:46:20,671 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1/6f70656e737461636b20342065766572/servers/a474ffac-3b78-4c54-95bd-20f4daced15c" status: 200 len: 1841 microversion: 2.14 time: 0.148823
  2018-05-02 06:46:21,113 INFO [nova.api.openstack.requestlog] 127.0.0.1 "POST /v2.1/6f70656e737461636b20342065766572/servers" status: 202 len: 480 microversion: 2.14 time: 0.435586
  2018-05-02 06:46:21,324 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1/6f70656e737461636b20342065766572/servers/d899c9b6-e643-400c-a085-321fd8816bd3" status: 200 len: 1737 microversion: 2.14 time: 0.205507
  2018-05-02 06:46:21,428 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/allocation_candidates?limit=1000&resources=DISK_GB%3A1%2CMEMORY_MB%3A512%2CVCPU%3A1" status: 200 len: 679 microversion: 1.21
  2018-05-02 06:46:21,531 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/allocations/d899c9b6-e643-400c-a085-321fd8816bd3" status: 200 len: 19 microversion: 1.0
  2018-05-02 06:46:21,566 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "PUT /placement/allocations/d899c9b6-e643-400c-a085-321fd8816bd3" status: 204 len: 0 microversion: 1.21
  2018-05-02 06:46:21,916 INFO [nova.compute.claims] Attempting claim on node host2: memory 512 MB, disk 1 GB, vcpus 1 CPU
  2018-05-02 06:46:21,917 INFO [nova.compute.claims] Total memory: 8192 MB, used: 512.00 MB
  2018-05-02 06:46:21,917 INFO [nova.compute.claims] memory limit not specified, defaulting to unlimited
  2018-05-02 06:46:21,917 INFO [nova.compute.claims] Total disk: 1028 GB, used: 0.00 GB
  2018-05-02 06:46:21,918 INFO [nova.compute.claims] disk limit not specified, defaulting to unlimited
  2018-05-02 06:46:21,918 INFO [nova.compute.claims] Total vcpu: 2 VCPU, used: 0.00 VCPU
  2018-05-02 06:46:21,918 INFO [nova.compute.claims] vcpu limit not specified, defaulting to unlimited
  2018-05-02 06:46:21,919 INFO [nova.compute.claims] Claim successful on node host2
  2018-05-02 06:46:22,162 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1/6f70656e737461636b20342065766572/servers/d899c9b6-e643-400c-a085-321fd8816bd3" status: 200 len: 1750 microversion: 2.14 time: 0.300493
  2018-05-02 06:46:22,189 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=6b75720c-cd49-4cef-a051-7dfad4e0a2eb" status: 200 len: 854 microversion: 1.14
  2018-05-02 06:46:22,208 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6b75720c-cd49-4cef-a051-7dfad4e0a2eb/inventories" status: 200 len: 399 microversion: 1.0
  2018-05-02 06:46:22,225 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6b75720c-cd49-4cef-a051-7dfad4e0a2eb/aggregates" status: 200 len: 18 microversion: 1.1
  2018-05-02 06:46:22,244 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6b75720c-cd49-4cef-a051-7dfad4e0a2eb/traits" status: 200 len: 49 microversion: 1.6
  2018-05-02 06:46:22,281 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6b75720c-cd49-4cef-a051-7dfad4e0a2eb/inventories" status: 200 len: 399 microversion: 1.0
  2018-05-02 06:46:22,353 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=6b75720c-cd49-4cef-a051-7dfad4e0a2eb" status: 200 len: 854 microversion: 1.14
  2018-05-02 06:46:22,386 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6b75720c-cd49-4cef-a051-7dfad4e0a2eb/inventories" status: 200 len: 399 microversion: 1.0
  2018-05-02 06:46:22,397 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6b75720c-cd49-4cef-a051-7dfad4e0a2eb/aggregates" status: 200 len: 18 microversion: 1.1
  2018-05-02 06:46:22,411 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6b75720c-cd49-4cef-a051-7dfad4e0a2eb/traits" status: 200 len: 49 microversion: 1.6
  2018-05-02 06:46:22,424 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6b75720c-cd49-4cef-a051-7dfad4e0a2eb/inventories" status: 200 len: 399 microversion: 1.0
  2018-05-02 06:46:22,554 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/allocations/d899c9b6-e643-400c-a085-321fd8816bd3" status: 200 len: 134 microversion: 1.0
  2018-05-02 06:46:22,649 INFO [nova.compute.manager] Took 0.00 seconds to spawn the instance on the hypervisor.
  2018-05-02 06:46:22,772 INFO [nova.compute.manager] Took 0.89 seconds to build instance.
  2018-05-02 06:46:23,102 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1/6f70656e737461636b20342065766572/servers/d899c9b6-e643-400c-a085-321fd8816bd3" status: 200 len: 1841 microversion: 2.14 time: 0.369750
  2018-05-02 06:46:23,129 INFO [nova.scheduler.host_manager] Received an update from an unknown host 'host2'. Re-created its InstanceList.
  2018-05-02 06:46:23,164 INFO [nova.api.openstack.requestlog] 127.0.0.1 "PUT /v2.1/6f70656e737461636b20342065766572/os-services/force-down" status: 200 len: 77 microversion: 2.14 time: 0.031380
  2018-05-02 06:46:23,219 INFO [nova.api.openstack.requestlog] 127.0.0.1 "PUT /v2.1/6f70656e737461636b20342065766572/os-services/force-down" status: 200 len: 77 microversion: 2.14 time: 0.035129
  2018-05-02 06:46:23,229 INFO [nova.virt.driver] Loading compute driver 'fake.SmallFakeDriver'
  2018-05-02 06:46:23,230 INFO [nova.service] Starting compute node (version 18.0.0)
  2018-05-02 06:46:23,276 WARNING [nova.compute.manager] No compute node record found for host host3. If this is the first time this service is starting on this host, then you can ignore this warning.
  2018-05-02 06:46:23,277 WARNING [nova.compute.monitors] Excluding nova.compute.monitors.cpu monitor virt_driver. Not in the list of enabled monitors (CONF.compute_monitors).
  2018-05-02 06:46:23,285 WARNING [nova.compute.resource_tracker] No compute node record for host3:host3
  2018-05-02 06:46:23,289 INFO [nova.compute.resource_tracker] Compute node record created for host3:host3 with uuid: 6a84d2a4-ae03-48f8-8737-dc695c1c7c31
  2018-05-02 06:46:23,330 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=6a84d2a4-ae03-48f8-8737-dc695c1c7c31" status: 200 len: 26 microversion: 1.14
  2018-05-02 06:46:23,344 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "POST /placement/resource_providers" status: 200 len: 828 microversion: 1.20
  2018-05-02 06:46:23,346 INFO [nova.scheduler.client.report] [req-21ea3b51-1c0f-4b13-bef8-43d8217723d3] Created resource provider record via placement API for resource provider with UUID 6a84d2a4-ae03-48f8-8737-dc695c1c7c31 and name host3.
  2018-05-02 06:46:23,363 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/inventories" status: 200 len: 54 microversion: 1.0
  2018-05-02 06:46:23,380 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=6a84d2a4-ae03-48f8-8737-dc695c1c7c31" status: 200 len: 854 microversion: 1.14
  2018-05-02 06:46:23,392 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/inventories" status: 200 len: 54 microversion: 1.0
  2018-05-02 06:46:23,407 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/aggregates" status: 200 len: 18 microversion: 1.1
  2018-05-02 06:46:23,425 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/traits" status: 200 len: 49 microversion: 1.6
  2018-05-02 06:46:23,446 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/inventories" status: 200 len: 54 microversion: 1.0
  2018-05-02 06:46:23,480 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "PUT /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/inventories" status: 200 len: 399 microversion: 1.0
  2018-05-02 06:46:23,544 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/allocations" status: 200 len: 54 microversion: 1.0
  2018-05-02 06:46:23,546 INFO [nova.compute.resource_tracker] Final resource view: name=host3 phys_ram=8192MB used_ram=512MB phys_disk=1028GB used_disk=0GB total_vcpus=2 used_vcpus=0 pci_stats=[]
  2018-05-02 06:46:23,585 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=6a84d2a4-ae03-48f8-8737-dc695c1c7c31" status: 200 len: 854 microversion: 1.14
  2018-05-02 06:46:23,605 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/inventories" status: 200 len: 399 microversion: 1.0
  2018-05-02 06:46:23,623 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/aggregates" status: 200 len: 18 microversion: 1.1
  2018-05-02 06:46:23,642 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/traits" status: 200 len: 49 microversion: 1.6
  2018-05-02 06:46:23,661 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/inventories" status: 200 len: 399 microversion: 1.0
  2018-05-02 06:46:23,686 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=6a84d2a4-ae03-48f8-8737-dc695c1c7c31" status: 200 len: 854 microversion: 1.14
  2018-05-02 06:46:23,705 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/inventories" status: 200 len: 399 microversion: 1.0
  2018-05-02 06:46:23,723 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/aggregates" status: 200 len: 18 microversion: 1.1
  2018-05-02 06:46:23,741 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/traits" status: 200 len: 49 microversion: 1.6
  2018-05-02 06:46:23,767 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/inventories" status: 200 len: 399 microversion: 1.0
  2018-05-02 06:46:24,082 INFO [nova.api.openstack.requestlog] 127.0.0.1 "POST /v2.1/6f70656e737461636b20342065766572/servers/a474ffac-3b78-4c54-95bd-20f4daced15c/action" status: 200 len: 0 microversion: 2.14 time: 0.305313
  2018-05-02 06:46:24,480 INFO [nova.api.openstack.requestlog] 127.0.0.1 "POST /v2.1/6f70656e737461636b20342065766572/servers/d899c9b6-e643-400c-a085-321fd8816bd3/action" status: 200 len: 0 microversion: 2.14 time: 0.394764
  2018-05-02 06:46:24,579 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/allocation_candidates?limit=1000&resources=DISK_GB%3A1%2CMEMORY_MB%3A512%2CVCPU%3A1" status: 200 len: 995 microversion: 1.21
  2018-05-02 06:46:24,834 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/allocation_candidates?limit=1000&resources=DISK_GB%3A1%2CMEMORY_MB%3A512%2CVCPU%3A1" status: 200 len: 995 microversion: 1.21
  2018-05-02 06:46:24,926 INFO [nova.scheduler.host_manager] Host filter ignoring hosts: host1
  2018-05-02 06:46:24,927 WARNING [nova.scheduler.filters.compute_filter] (host2, host2) ram: 7168MB disk: 0MB io_ops: 1 instances: 1 has not been heard from in a while
  2018-05-02 06:46:24,969 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/allocations/a474ffac-3b78-4c54-95bd-20f4daced15c" status: 200 len: 134 microversion: 1.0
  2018-05-02 06:46:25,061 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "PUT /placement/allocations/a474ffac-3b78-4c54-95bd-20f4daced15c" status: 204 len: 0 microversion: 1.21
  2018-05-02 06:46:25,115 INFO [nova.scheduler.host_manager] Host filter ignoring hosts: host2
  2018-05-02 06:46:25,116 WARNING [nova.scheduler.filters.compute_filter] (host1, host1) ram: 7168MB disk: 0MB io_ops: 1 instances: 1 has not been heard from in a while
  2018-05-02 06:46:25,159 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/allocations/d899c9b6-e643-400c-a085-321fd8816bd3" status: 200 len: 134 microversion: 1.0
  2018-05-02 06:46:25,200 INFO [nova.compute.manager] Evacuating instance
  2018-05-02 06:46:25,236 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "PUT /placement/allocations/d899c9b6-e643-400c-a085-321fd8816bd3" status: 204 len: 0 microversion: 1.21
  2018-05-02 06:46:25,247 INFO [nova.compute.claims] Attempting claim on node host3: memory 512 MB, disk 1 GB, vcpus 1 CPU
  2018-05-02 06:46:25,248 INFO [nova.compute.claims] Total memory: 8192 MB, used: 512.00 MB
  2018-05-02 06:46:25,248 INFO [nova.compute.claims] memory limit not specified, defaulting to unlimited
  2018-05-02 06:46:25,248 INFO [nova.compute.claims] Total disk: 1028 GB, used: 0.00 GB
  2018-05-02 06:46:25,248 INFO [nova.compute.claims] disk limit not specified, defaulting to unlimited
  2018-05-02 06:46:25,249 INFO [nova.compute.claims] Total vcpu: 2 VCPU, used: 0.00 VCPU
  2018-05-02 06:46:25,249 INFO [nova.compute.claims] vcpu limit not specified, defaulting to unlimited
  2018-05-02 06:46:25,249 INFO [nova.compute.claims] Claim successful on node host3
  2018-05-02 06:46:25,416 INFO [nova.compute.resource_tracker] Updating from migration a474ffac-3b78-4c54-95bd-20f4daced15c
  2018-05-02 06:46:25,431 INFO [nova.compute.manager] Evacuating instance
  2018-05-02 06:46:25,453 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=6a84d2a4-ae03-48f8-8737-dc695c1c7c31" status: 200 len: 854 microversion: 1.14
  2018-05-02 06:46:25,472 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/inventories" status: 200 len: 399 microversion: 1.0
  2018-05-02 06:46:25,507 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/aggregates" status: 200 len: 18 microversion: 1.1
  2018-05-02 06:46:25,596 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/traits" status: 200 len: 49 microversion: 1.6
  2018-05-02 06:46:25,658 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1/6f70656e737461636b20342065766572/servers/a474ffac-3b78-4c54-95bd-20f4daced15c" status: 200 len: 1850 microversion: 2.14 time: 0.163443
  2018-05-02 06:46:25,662 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/inventories" status: 200 len: 399 microversion: 1.0
  2018-05-02 06:46:25,685 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=6a84d2a4-ae03-48f8-8737-dc695c1c7c31" status: 200 len: 854 microversion: 1.14
  2018-05-02 06:46:25,704 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/inventories" status: 200 len: 399 microversion: 1.0
  2018-05-02 06:46:25,719 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/aggregates" status: 200 len: 18 microversion: 1.1
  2018-05-02 06:46:25,737 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/traits" status: 200 len: 49 microversion: 1.6
  2018-05-02 06:46:25,755 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/inventories" status: 200 len: 399 microversion: 1.0
  2018-05-02 06:46:25,773 INFO [nova.compute.claims] Attempting claim on node host3: memory 512 MB, disk 1 GB, vcpus 1 CPU
  2018-05-02 06:46:25,774 INFO [nova.compute.claims] Total memory: 8192 MB, used: 1024.00 MB
  2018-05-02 06:46:25,774 INFO [nova.compute.claims] memory limit not specified, defaulting to unlimited
  2018-05-02 06:46:25,774 INFO [nova.compute.claims] Total disk: 1028 GB, used: 1.00 GB
  2018-05-02 06:46:25,774 INFO [nova.compute.claims] disk limit not specified, defaulting to unlimited
  2018-05-02 06:46:25,775 INFO [nova.compute.claims] Total vcpu: 2 VCPU, used: 1.00 VCPU
  2018-05-02 06:46:25,775 INFO [nova.compute.claims] vcpu limit not specified, defaulting to unlimited
  2018-05-02 06:46:25,775 INFO [nova.compute.claims] Claim successful on node host3
  2018-05-02 06:46:25,894 INFO [nova.compute.resource_tracker] Updating from migration d899c9b6-e643-400c-a085-321fd8816bd3
  2018-05-02 06:46:25,931 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=6a84d2a4-ae03-48f8-8737-dc695c1c7c31" status: 200 len: 854 microversion: 1.14
  2018-05-02 06:46:25,951 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/inventories" status: 200 len: 399 microversion: 1.0
  2018-05-02 06:46:25,968 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/aggregates" status: 200 len: 18 microversion: 1.1
  2018-05-02 06:46:25,985 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/traits" status: 200 len: 49 microversion: 1.6
  2018-05-02 06:46:26,030 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/inventories" status: 200 len: 399 microversion: 1.0
  2018-05-02 06:46:26,067 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=6a84d2a4-ae03-48f8-8737-dc695c1c7c31" status: 200 len: 854 microversion: 1.14
  2018-05-02 06:46:26,108 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/inventories" status: 200 len: 399 microversion: 1.0
  2018-05-02 06:46:26,125 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/aggregates" status: 200 len: 18 microversion: 1.1
  2018-05-02 06:46:26,146 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/traits" status: 200 len: 49 microversion: 1.6
  2018-05-02 06:46:26,251 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/6a84d2a4-ae03-48f8-8737-dc695c1c7c31/inventories" status: 200 len: 399 microversion: 1.0
  2018-05-02 06:46:26,403 INFO [nova.compute.manager] disk not on shared storage, evacuating from: 'a2459075-d96c-40d5-893e-577ff92e721c'
  2018-05-02 06:46:26,427 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1/6f70656e737461636b20342065766572/servers/a474ffac-3b78-4c54-95bd-20f4daced15c" status: 200 len: 1850 microversion: 2.14 time: 0.231836
  2018-05-02 06:46:26,551 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/allocations/d899c9b6-e643-400c-a085-321fd8816bd3" status: 200 len: 251 microversion: 1.0
  2018-05-02 06:46:26,971 INFO [nova.compute.manager] disk not on shared storage, evacuating from: 'a2459075-d96c-40d5-893e-577ff92e721c'
  2018-05-02 06:46:27,286 INFO [nova.scheduler.host_manager] Received an update from an unknown host 'host3'. Re-created its InstanceList.
  2018-05-02 06:46:27,336 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/allocations/a474ffac-3b78-4c54-95bd-20f4daced15c" status: 200 len: 251 microversion: 1.0
  2018-05-02 06:46:27,361 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1/6f70656e737461636b20342065766572/servers/a474ffac-3b78-4c54-95bd-20f4daced15c" status: 200 len: 1850 microversion: 2.14 time: 0.379597
  2018-05-02 06:46:28,022 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1/6f70656e737461636b20342065766572/servers/a474ffac-3b78-4c54-95bd-20f4daced15c" status: 200 len: 1841 microversion: 2.14 time: 0.155013
  2018-05-02 06:46:28,181 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1/6f70656e737461636b20342065766572/servers/d899c9b6-e643-400c-a085-321fd8816bd3" status: 200 len: 1841 microversion: 2.14 time: 0.152980
  2018-05-02 06:46:28,225 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1/6f70656e737461636b20342065766572/os-migrations" status: 200 len: 736 microversion: 2.14 time: 0.038190
  2018-05-02 06:46:28,265 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1/6f70656e737461636b20342065766572/os-migrations" status: 200 len: 736 microversion: 2.14 time: 0.018824
  2018-05-02 06:46:28,766 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1/6f70656e737461636b20342065766572/servers/a474ffac-3b78-4c54-95bd-20f4daced15c" status: 200 len: 1841 microversion: 2.14 time: 0.495266
  2018-05-02 06:46:28,865 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1/6f70656e737461636b20342065766572/servers/d899c9b6-e643-400c-a085-321fd8816bd3" status: 200 len: 1841 microversion: 2.14 time: 0.095275
  }}}

  Traceback (most recent call last):
    File "nova/tests/functional/regressions/test_bug_1735407.py", line 158, in test_parallel_evacuate_with_server_group
      server2['OS-EXT-SRV-ATTR:host'])
    File "/home/zuul/src/git.openstack.org/openstack/nova/.tox/functional/local/lib/python2.7/site-packages/unittest2/case.py", line 845, in assertNotEqual
      raise self.failureException(msg)
  AssertionError: u'host3' == u'host3'

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