============================== Failed 1 tests - output below: ============================== nova.tests.functional.regressions.test_bug_1718512.TestRequestSpecRetryReschedule.test_resize_with_reschedule_then_live_migrate ------------------------------------------------------------------------------------------------------------------------------- Captured stderr: ~~~~~~~~~~~~~~~~ /tmp/nova/.tox/functional/local/lib/python2.7/site-packages/paste/deploy/loadwsgi.py:22: DeprecationWarning: Parameters to load are deprecated. Call .resolve and .require separately. return pkg_resources.EntryPoint.parse("x=" + s).load(False) Captured traceback: ~~~~~~~~~~~~~~~~~~~ Traceback (most recent call last): File "nova/tests/functional/regressions/test_bug_1718512.py", line 150, in test_resize_with_reschedule_then_live_migrate self.assertEqual('completed', migrations[0]['status']) File "/tmp/nova/.tox/functional/local/lib/python2.7/site-packages/testtools/testcase.py", line 411, in assertEqual self.assertThat(observed, matcher, message) File "/tmp/nova/.tox/functional/local/lib/python2.7/site-packages/testtools/testcase.py", line 498, in assertThat raise mismatch_error testtools.matchers._impl.MismatchError: 'completed' != u'running' Captured pythonlogging: ~~~~~~~~~~~~~~~~~~~~~~~ 2018-04-11 16:22:24,202 INFO [248_add_expire_reservations_index] Skipped adding reservations_deleted_expire_idx because an equivalent index already exists. 2018-04-11 16:22:25,881 INFO [nova.service] Starting conductor node (version 17.0.0) 2018-04-11 16:22:25,889 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1" status: 204 len: 0 microversion: - time: 0.000260 2018-04-11 16:22:25,957 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1/6f70656e737461636b20342065766572/images/detail" status: 200 len: 5609 microversion: 2.1 time: 0.066373 2018-04-11 16:22:25,959 INFO [nova.service] Starting consoleauth node (version 17.0.0) 2018-04-11 16:22:25,967 INFO [oslo_service.periodic_task] Skipping periodic task _discover_hosts_in_cells because its interval is negative 2018-04-11 16:22:25,990 INFO [nova.service] Starting scheduler node (version 17.0.0) 2018-04-11 16:22:26,006 INFO [nova.virt.driver] Loading compute driver 'fake.SmallFakeDriver' 2018-04-11 16:22:26,006 INFO [nova.service] Starting compute node (version 17.0.0) 2018-04-11 16:22:26,025 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-04-11 16:22:26,026 WARNING [nova.compute.monitors] Excluding nova.compute.monitors.cpu monitor virt_driver. Not in the list of enabled monitors (CONF.compute_monitors). 2018-04-11 16:22:26,029 WARNING [nova.compute.resource_tracker] No compute node record for host1:host1 2018-04-11 16:22:26,032 INFO [nova.compute.resource_tracker] Compute node record created for host1:host1 with uuid: f8528e77-80cf-4865-b1d3-a4e548159eb7 2018-04-11 16:22:26,050 INFO [nova.api.openstack.placement.objects.resource_provider] Synced traits from os_traits into API DB: set(['HW_GPU_API_OPENGL_V2_1', 'HW_GPU_API_OPENGL_V2_0', 'HW_GPU_RESOLUTION_W1366H768', 'HW_GPU_API_OPENGL_V4_5', 'HW_GPU_API_OPENGL_V4_4', 'HW_GPU_API_VULKAN', 'HW_GPU_API_OPENGL_V4_1', 'HW_GPU_API_OPENGL_V4_0', 'HW_GPU_API_OPENGL_V4_3', 'HW_GPU_API_OPENGL_V4_2', 'HW_GPU_API_DXVA', 'HW_NIC_ACCEL_SSL', 'HW_NIC_OFFLOAD_RDMA', 'HW_NIC_OFFLOAD_QINQ', 'HW_GPU_RESOLUTION_W1024H768', 'HW_NIC_ACCEL_TLS', 'HW_CPU_X86_SSE4A', 'HW_GPU_API_CUDA_V1_3', 'HW_CPU_X86_MMX', 'HW_GPU_API_CUDA_V1_1', 'HW_NIC_OFFLOAD_SG', 'HW_CPU_X86_SHA', 'HW_CPU_X86_SSE', 'HW_GPU_RESOLUTION_W1024H600', 'HW_CPU_X86_AVX512BW', 'HW_GPU_API_CUDA_V5_2', 'HW_GPU_API_CUDA_V5_3', 'HW_GPU_API_CUDA_V5_0', 'HW_GPU_API_CUDA_V3_0', 'HW_GPU_API_CUDA_V3_2', 'HW_NIC_MULTIQUEUE', 'HW_GPU_API_CUDA_V3_5', 'HW_GPU_API_CUDA_V3_7', 'HW_CPU_X86_SSE41', 'HW_GPU_API_CUDA_V7_1', 'HW_CPU_X86_SSE42', 'HW_GPU_RESOLUTION_W1920H1080', 'HW_NIC_OFFLOAD_LRO', 'STORAGE_DISK_SSD', 'HW_CPU_X86_AVX512CD', 'HW_CPU_X86_TSX', 'HW_GPU_RESOLUTION_W1280H800', 'HW_CPU_X86_BMI2', 'HW_GPU_API_OPENGL_V1_1', 'HW_GPU_API_OPENGL_V1_2', 'HW_GPU_API_OPENGL_V1_3', 'HW_GPU_API_OPENGL_V1_4', 'HW_GPU_API_OPENGL_V1_5', 'HW_GPU_RESOLUTION_W1280H768', 'HW_NIC_OFFLOAD_UFO', 'HW_CPU_X86_CLMUL', 'HW_GPU_RESOLUTION_W1680H1050', 'HW_NIC_OFFLOAD_SWITCHDEV', 'HW_GPU_API_DIRECT3D_V10_0', 'HW_GPU_API_DIRECT3D_V10_1', 'HW_CPU_X86_AVX', 'HW_GPU_RESOLUTION_W1920H1200', 'HW_NIC_OFFLOAD_TSO', 'HW_NIC_SRIOV_QOS_RX', 'HW_CPU_X86_AVX2', 'HW_NIC_OFFLOAD_LSO', 'HW_NIC_SRIOV', 'HW_GPU_RESOLUTION_W800H600', 'HW_NIC_DCB_PFC', 'HW_GPU_API_DIRECTX_V12', 'HW_GPU_API_DIRECTX_V11', 'HW_GPU_API_DIRECTX_V10', 'HW_GPU_API_CUDA_V6_1', 'HW_GPU_API_CUDA_V6_0', 'HW_GPU_API_CUDA_V6_2', 'HW_CPU_X86_BMI', 'HW_NIC_SRIOV_QOS_TX', 'HW_GPU_RESOLUTION_W1152H864', 'HW_GPU_API_CUDA_V2_1', 'HW_GPU_API_CUDA_V2_0', 'HW_GPU_RESOLUTION_W2560H1440', 'HW_GPU_RESOLUTION_W2560H1600', 'HW_GPU_API_CUDA_V1_2', 'HW_CPU_X86_TBM', 'HW_CPU_X86_AVX512PF', 'HW_NIC_ACCEL_IPSEC', 'HW_NIC_DCB_ETS', 'HW_GPU_API_CUDA_V1_0', 'HW_NIC_OFFLOAD_TXUDP', 'HW_CPU_X86_FMA4', 'HW_CPU_X86_FMA3', 'HW_NIC_ACCEL_LZS', 'HW_NIC_VMDQ', 'MISC_SHARES_VIA_AGGREGATE', 'HW_CPU_X86_SVM', 'HW_NIC_ACCEL_ECC', 'HW_CPU_X86_SGX', 'HW_GPU_API_DIRECT3D_V11_1', 'HW_GPU_API_DIRECT3D_V11_0', 'HW_GPU_API_DIRECT3D_V11_3', 'HW_GPU_API_DIRECT3D_V11_2', 'HW_CPU_X86_SSSE3', 'HW_CPU_X86_VMX', 'HW_CPU_X86_F16C', 'HW_GPU_RESOLUTION_W1440H900', 'HW_NIC_OFFLOAD_RXVLAN', 'HW_CPU_X86_3DNOW', 'HW_CPU_X86_MPX', 'HW_NIC_OFFLOAD_VXLAN', 'HW_CPU_X86_AVX512VL', 'HW_NIC_OFFLOAD_L2CRC', 'HW_GPU_API_DIRECT2D', 'HW_CPU_X86_AVX512F', 'HW_GPU_API_OPENCL_V1_0', 'HW_GPU_API_OPENCL_V1_1', 'HW_GPU_API_OPENCL_V1_2', 'HW_NIC_OFFLOAD_TXVLAN', 'HW_GPU_API_DIRECT3D_V9_0B', 'HW_GPU_API_DIRECT3D_V9_0C', 'HW_GPU_RESOLUTION_W320H240', 'HW_CPU_X86_XOP', 'HW_GPU_API_OPENGL_V3_1', 'HW_NIC_OFFLOAD_RX', 'HW_GPU_API_DIRECT3D_V9_0L', 'HW_GPU_API_DIRECT3D_V6_0', 'HW_GPU_RESOLUTION_W1280H720', 'HW_GPU_RESOLUTION_W1600H1200', 'HW_NIC_SRIOV_MULTIQUEUE', 'HW_GPU_RESOLUTION_W1280H1024', 'HW_NIC_DCB_QCN', 'HW_GPU_API_DIRECT3D_V12_0', 'HW_GPU_API_DIRECT3D_V7_0', 'HW_GPU_RESOLUTION_W3840H2160', 'HW_NIC_ACCEL_DIFFIEH', 'HW_NIC_OFFLOAD_GRE', 'HW_GPU_API_DIRECT3D_V9_0', 'HW_CPU_X86_AESNI', 'HW_NIC_ACCEL_RSA', 'HW_NIC_OFFLOAD_GRO', 'HW_GPU_API_OPENGL_V3_2', 'HW_GPU_API_OPENGL_V3_3', 'HW_GPU_API_OPENGL_V3_0', 'HW_CPU_X86_AVX512DQ', 'HW_NIC_OFFLOAD_GENEVE', 'STORAGE_DISK_HDD', 'HW_GPU_RESOLUTION_W640H480', 'HW_GPU_RESOLUTION_W1600H900', 'HW_GPU_RESOLUTION_W1360H768', 'HW_NIC_ACCEL_DEFLATE', 'HW_CPU_X86_SSE2', 'HW_CPU_X86_SSE3', 'HW_NIC_OFFLOAD_TCS', 'HW_CPU_X86_ABM', 'HW_NIC_OFFLOAD_GSO', 'HW_NIC_OFFLOAD_SCS', 'HW_GPU_RESOLUTION_W7680H4320', 'HW_NIC_OFFLOAD_FDF', 'HW_NIC_OFFLOAD_TX', 'HW_NIC_PROGRAMMABLE_PIPELINE', 'HW_CPU_X86_ASF', 'HW_GPU_API_OPENCL_V2_2', 'HW_GPU_API_OPENCL_V2_1', 'HW_GPU_API_OPENCL_V2_0', 'HW_NIC_OFFLOAD_RXHASH', 'HW_CPU_X86_AVX512ER', 'HW_NIC_OFFLOAD_UCS', 'HW_GPU_API_CUDA_V7_0', 'HW_GPU_API_DIRECT3D_V8_0', 'HW_GPU_API_DIRECT3D_V8_1']) 2018-04-11 16:22:26,054 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=f8528e77-80cf-4865-b1d3-a4e548159eb7" status: 200 len: 26 microversion: 1.14 2018-04-11 16:22:26,059 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "POST /placement/resource_providers" status: 200 len: 828 microversion: 1.20 2018-04-11 16:22:26,060 INFO [nova.scheduler.client.report] [req-43326ec0-dee9-4736-9547-a53d1387e101] Created resource provider record via placement API for resource provider with UUID f8528e77-80cf-4865-b1d3-a4e548159eb7 and name host1. 2018-04-11 16:22:26,064 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/f8528e77-80cf-4865-b1d3-a4e548159eb7/inventories" status: 200 len: 54 microversion: 1.0 2018-04-11 16:22:26,070 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=f8528e77-80cf-4865-b1d3-a4e548159eb7" status: 200 len: 854 microversion: 1.14 2018-04-11 16:22:26,075 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/f8528e77-80cf-4865-b1d3-a4e548159eb7/inventories" status: 200 len: 54 microversion: 1.0 2018-04-11 16:22:26,080 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/f8528e77-80cf-4865-b1d3-a4e548159eb7/aggregates" status: 200 len: 18 microversion: 1.1 2018-04-11 16:22:26,086 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/f8528e77-80cf-4865-b1d3-a4e548159eb7/traits" status: 200 len: 49 microversion: 1.6 2018-04-11 16:22:26,091 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/f8528e77-80cf-4865-b1d3-a4e548159eb7/inventories" status: 200 len: 54 microversion: 1.0 2018-04-11 16:22:26,098 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "PUT /placement/resource_providers/f8528e77-80cf-4865-b1d3-a4e548159eb7/inventories" status: 200 len: 399 microversion: 1.0 2018-04-11 16:22:26,121 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/f8528e77-80cf-4865-b1d3-a4e548159eb7/allocations" status: 200 len: 54 microversion: 1.0 2018-04-11 16:22:26,122 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-04-11 16:22:26,132 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=f8528e77-80cf-4865-b1d3-a4e548159eb7" status: 200 len: 854 microversion: 1.14 2018-04-11 16:22:26,137 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/f8528e77-80cf-4865-b1d3-a4e548159eb7/inventories" status: 200 len: 399 microversion: 1.0 2018-04-11 16:22:26,142 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/f8528e77-80cf-4865-b1d3-a4e548159eb7/aggregates" status: 200 len: 18 microversion: 1.1 2018-04-11 16:22:26,146 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/f8528e77-80cf-4865-b1d3-a4e548159eb7/traits" status: 200 len: 49 microversion: 1.6 2018-04-11 16:22:26,152 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/f8528e77-80cf-4865-b1d3-a4e548159eb7/inventories" status: 200 len: 399 microversion: 1.0 2018-04-11 16:22:26,159 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=f8528e77-80cf-4865-b1d3-a4e548159eb7" status: 200 len: 854 microversion: 1.14 2018-04-11 16:22:26,164 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/f8528e77-80cf-4865-b1d3-a4e548159eb7/inventories" status: 200 len: 399 microversion: 1.0 2018-04-11 16:22:26,168 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/f8528e77-80cf-4865-b1d3-a4e548159eb7/aggregates" status: 200 len: 18 microversion: 1.1 2018-04-11 16:22:26,173 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/f8528e77-80cf-4865-b1d3-a4e548159eb7/traits" status: 200 len: 49 microversion: 1.6 2018-04-11 16:22:26,178 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/f8528e77-80cf-4865-b1d3-a4e548159eb7/inventories" status: 200 len: 399 microversion: 1.0 2018-04-11 16:22:26,182 INFO [nova.virt.driver] Loading compute driver 'fake.SmallFakeDriver' 2018-04-11 16:22:26,182 INFO [nova.service] Starting compute node (version 17.0.0) 2018-04-11 16:22:26,194 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-04-11 16:22:26,195 WARNING [nova.compute.monitors] Excluding nova.compute.monitors.cpu monitor virt_driver. Not in the list of enabled monitors (CONF.compute_monitors). 2018-04-11 16:22:26,197 WARNING [nova.compute.resource_tracker] No compute node record for host2:host2 2018-04-11 16:22:26,199 INFO [nova.compute.resource_tracker] Compute node record created for host2:host2 with uuid: 77562443-87a3-46c1-b2ad-890582ed7b1e 2018-04-11 16:22:26,210 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=77562443-87a3-46c1-b2ad-890582ed7b1e" status: 200 len: 26 microversion: 1.14 2018-04-11 16:22:26,214 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "POST /placement/resource_providers" status: 200 len: 828 microversion: 1.20 2018-04-11 16:22:26,214 INFO [nova.scheduler.client.report] [req-92588746-0c0c-467c-933c-969c45f8f53d] Created resource provider record via placement API for resource provider with UUID 77562443-87a3-46c1-b2ad-890582ed7b1e and name host2. 2018-04-11 16:22:26,218 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/77562443-87a3-46c1-b2ad-890582ed7b1e/inventories" status: 200 len: 54 microversion: 1.0 2018-04-11 16:22:26,225 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=77562443-87a3-46c1-b2ad-890582ed7b1e" status: 200 len: 854 microversion: 1.14 2018-04-11 16:22:26,230 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/77562443-87a3-46c1-b2ad-890582ed7b1e/inventories" status: 200 len: 54 microversion: 1.0 2018-04-11 16:22:26,234 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/77562443-87a3-46c1-b2ad-890582ed7b1e/aggregates" status: 200 len: 18 microversion: 1.1 2018-04-11 16:22:26,240 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/77562443-87a3-46c1-b2ad-890582ed7b1e/traits" status: 200 len: 49 microversion: 1.6 2018-04-11 16:22:26,245 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/77562443-87a3-46c1-b2ad-890582ed7b1e/inventories" status: 200 len: 54 microversion: 1.0 2018-04-11 16:22:26,252 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "PUT /placement/resource_providers/77562443-87a3-46c1-b2ad-890582ed7b1e/inventories" status: 200 len: 399 microversion: 1.0 2018-04-11 16:22:26,266 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/77562443-87a3-46c1-b2ad-890582ed7b1e/allocations" status: 200 len: 54 microversion: 1.0 2018-04-11 16:22:26,267 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-04-11 16:22:26,277 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=77562443-87a3-46c1-b2ad-890582ed7b1e" status: 200 len: 854 microversion: 1.14 2018-04-11 16:22:26,282 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/77562443-87a3-46c1-b2ad-890582ed7b1e/inventories" status: 200 len: 399 microversion: 1.0 2018-04-11 16:22:26,287 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/77562443-87a3-46c1-b2ad-890582ed7b1e/aggregates" status: 200 len: 18 microversion: 1.1 2018-04-11 16:22:26,292 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/77562443-87a3-46c1-b2ad-890582ed7b1e/traits" status: 200 len: 49 microversion: 1.6 2018-04-11 16:22:26,296 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/77562443-87a3-46c1-b2ad-890582ed7b1e/inventories" status: 200 len: 399 microversion: 1.0 2018-04-11 16:22:26,302 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=77562443-87a3-46c1-b2ad-890582ed7b1e" status: 200 len: 854 microversion: 1.14 2018-04-11 16:22:26,308 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/77562443-87a3-46c1-b2ad-890582ed7b1e/inventories" status: 200 len: 399 microversion: 1.0 2018-04-11 16:22:26,312 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/77562443-87a3-46c1-b2ad-890582ed7b1e/aggregates" status: 200 len: 18 microversion: 1.1 2018-04-11 16:22:26,317 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/77562443-87a3-46c1-b2ad-890582ed7b1e/traits" status: 200 len: 49 microversion: 1.6 2018-04-11 16:22:26,322 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/77562443-87a3-46c1-b2ad-890582ed7b1e/inventories" status: 200 len: 399 microversion: 1.0 2018-04-11 16:22:26,326 INFO [nova.virt.driver] Loading compute driver 'fake.SmallFakeDriver' 2018-04-11 16:22:26,326 INFO [nova.service] Starting compute node (version 17.0.0) 2018-04-11 16:22:26,341 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-04-11 16:22:26,341 WARNING [nova.compute.monitors] Excluding nova.compute.monitors.cpu monitor virt_driver. Not in the list of enabled monitors (CONF.compute_monitors). 2018-04-11 16:22:26,343 WARNING [nova.compute.resource_tracker] No compute node record for host3:host3 2018-04-11 16:22:26,345 INFO [nova.compute.resource_tracker] Compute node record created for host3:host3 with uuid: 2978286e-1551-4150-b04d-59d93f5626ab 2018-04-11 16:22:26,355 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=2978286e-1551-4150-b04d-59d93f5626ab" status: 200 len: 26 microversion: 1.14 2018-04-11 16:22:26,359 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "POST /placement/resource_providers" status: 200 len: 828 microversion: 1.20 2018-04-11 16:22:26,360 INFO [nova.scheduler.client.report] [req-22226c92-34c4-4b99-8f8c-53ba5e7dd55f] Created resource provider record via placement API for resource provider with UUID 2978286e-1551-4150-b04d-59d93f5626ab and name host3. 2018-04-11 16:22:26,364 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/2978286e-1551-4150-b04d-59d93f5626ab/inventories" status: 200 len: 54 microversion: 1.0 2018-04-11 16:22:26,370 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=2978286e-1551-4150-b04d-59d93f5626ab" status: 200 len: 854 microversion: 1.14 2018-04-11 16:22:26,375 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/2978286e-1551-4150-b04d-59d93f5626ab/inventories" status: 200 len: 54 microversion: 1.0 2018-04-11 16:22:26,379 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/2978286e-1551-4150-b04d-59d93f5626ab/aggregates" status: 200 len: 18 microversion: 1.1 2018-04-11 16:22:26,384 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/2978286e-1551-4150-b04d-59d93f5626ab/traits" status: 200 len: 49 microversion: 1.6 2018-04-11 16:22:26,389 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/2978286e-1551-4150-b04d-59d93f5626ab/inventories" status: 200 len: 54 microversion: 1.0 2018-04-11 16:22:26,396 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "PUT /placement/resource_providers/2978286e-1551-4150-b04d-59d93f5626ab/inventories" status: 200 len: 399 microversion: 1.0 2018-04-11 16:22:26,411 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/2978286e-1551-4150-b04d-59d93f5626ab/allocations" status: 200 len: 54 microversion: 1.0 2018-04-11 16:22:26,411 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-04-11 16:22:26,422 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=2978286e-1551-4150-b04d-59d93f5626ab" status: 200 len: 854 microversion: 1.14 2018-04-11 16:22:26,427 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/2978286e-1551-4150-b04d-59d93f5626ab/inventories" status: 200 len: 399 microversion: 1.0 2018-04-11 16:22:26,432 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/2978286e-1551-4150-b04d-59d93f5626ab/aggregates" status: 200 len: 18 microversion: 1.1 2018-04-11 16:22:26,437 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/2978286e-1551-4150-b04d-59d93f5626ab/traits" status: 200 len: 49 microversion: 1.6 2018-04-11 16:22:26,441 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/2978286e-1551-4150-b04d-59d93f5626ab/inventories" status: 200 len: 399 microversion: 1.0 2018-04-11 16:22:26,447 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=2978286e-1551-4150-b04d-59d93f5626ab" status: 200 len: 854 microversion: 1.14 2018-04-11 16:22:26,452 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/2978286e-1551-4150-b04d-59d93f5626ab/inventories" status: 200 len: 399 microversion: 1.0 2018-04-11 16:22:26,457 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/2978286e-1551-4150-b04d-59d93f5626ab/aggregates" status: 200 len: 18 microversion: 1.1 2018-04-11 16:22:26,461 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/2978286e-1551-4150-b04d-59d93f5626ab/traits" status: 200 len: 49 microversion: 1.6 2018-04-11 16:22:26,466 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/2978286e-1551-4150-b04d-59d93f5626ab/inventories" status: 200 len: 399 microversion: 1.0 2018-04-11 16:22:26,480 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1/6f70656e737461636b20342065766572/flavors/detail" status: 200 len: 2873 microversion: 2.60 time: 0.011003 2018-04-11 16:22:26,482 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1" status: 204 len: 0 microversion: - time: 0.000143 2018-04-11 16:22:26,555 INFO [nova.api.openstack.requestlog] 127.0.0.1 "POST /v2.1/6f70656e737461636b20342065766572/servers" status: 202 len: 480 microversion: 2.60 time: 0.072180 2018-04-11 16:22:26,582 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1/6f70656e737461636b20342065766572/servers/d661df8e-f8a0-45ea-9d90-0c55d7947120" status: 200 len: 1835 microversion: 2.60 time: 0.024689 2018-04-11 16:22:26,651 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: 991 microversion: 1.21 2018-04-11 16:22:26,688 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/allocations/d661df8e-f8a0-45ea-9d90-0c55d7947120" status: 200 len: 19 microversion: 1.0 2018-04-11 16:22:26,698 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "PUT /placement/allocations/d661df8e-f8a0-45ea-9d90-0c55d7947120" status: 204 len: 0 microversion: 1.21 2018-04-11 16:22:26,853 INFO [nova.compute.claims] Attempting claim on node host1: memory 512 MB, disk 1 GB, vcpus 1 CPU 2018-04-11 16:22:26,853 INFO [nova.compute.claims] Total memory: 8192 MB, used: 512.00 MB 2018-04-11 16:22:26,853 INFO [nova.compute.claims] memory limit not specified, defaulting to unlimited 2018-04-11 16:22:26,853 INFO [nova.compute.claims] Total disk: 1028 GB, used: 0.00 GB 2018-04-11 16:22:26,853 INFO [nova.compute.claims] disk limit not specified, defaulting to unlimited 2018-04-11 16:22:26,854 INFO [nova.compute.claims] Total vcpu: 2 VCPU, used: 0.00 VCPU 2018-04-11 16:22:26,854 INFO [nova.compute.claims] vcpu limit not specified, defaulting to unlimited 2018-04-11 16:22:26,854 INFO [nova.compute.claims] Claim successful on node host1 2018-04-11 16:22:26,892 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=f8528e77-80cf-4865-b1d3-a4e548159eb7" status: 200 len: 854 microversion: 1.14 2018-04-11 16:22:26,897 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/f8528e77-80cf-4865-b1d3-a4e548159eb7/inventories" status: 200 len: 399 microversion: 1.0 2018-04-11 16:22:26,902 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/f8528e77-80cf-4865-b1d3-a4e548159eb7/aggregates" status: 200 len: 18 microversion: 1.1 2018-04-11 16:22:26,907 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/f8528e77-80cf-4865-b1d3-a4e548159eb7/traits" status: 200 len: 49 microversion: 1.6 2018-04-11 16:22:26,912 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/f8528e77-80cf-4865-b1d3-a4e548159eb7/inventories" status: 200 len: 399 microversion: 1.0 2018-04-11 16:22:26,918 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=f8528e77-80cf-4865-b1d3-a4e548159eb7" status: 200 len: 854 microversion: 1.14 2018-04-11 16:22:26,923 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/f8528e77-80cf-4865-b1d3-a4e548159eb7/inventories" status: 200 len: 399 microversion: 1.0 2018-04-11 16:22:26,927 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/f8528e77-80cf-4865-b1d3-a4e548159eb7/aggregates" status: 200 len: 18 microversion: 1.1 2018-04-11 16:22:26,932 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/f8528e77-80cf-4865-b1d3-a4e548159eb7/traits" status: 200 len: 49 microversion: 1.6 2018-04-11 16:22:26,937 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/f8528e77-80cf-4865-b1d3-a4e548159eb7/inventories" status: 200 len: 399 microversion: 1.0 2018-04-11 16:22:26,993 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/allocations/d661df8e-f8a0-45ea-9d90-0c55d7947120" status: 200 len: 134 microversion: 1.0 2018-04-11 16:22:27,017 INFO [nova.compute.manager] Took 0.00 seconds to spawn the instance on the hypervisor. 2018-04-11 16:22:27,046 INFO [nova.compute.manager] Took 0.20 seconds to build instance. 2018-04-11 16:22:27,131 INFO [nova.scheduler.host_manager] Received an update from an unknown host 'host1'. Re-created its InstanceList. 2018-04-11 16:22:27,159 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1/6f70656e737461636b20342065766572/servers/d661df8e-f8a0-45ea-9d90-0c55d7947120" status: 200 len: 1941 microversion: 2.60 time: 0.071932 2018-04-11 16:22:27,348 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/allocations/d661df8e-f8a0-45ea-9d90-0c55d7947120" status: 200 len: 134 microversion: 1.0 2018-04-11 16:22:27,367 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "POST /placement/allocations" status: 204 len: 0 microversion: 1.13 2018-04-11 16:22:27,409 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/allocation_candidates?limit=1000&resources=DISK_GB%3A20%2CMEMORY_MB%3A2048%2CVCPU%3A1" status: 200 len: 999 microversion: 1.21 2018-04-11 16:22:27,444 INFO [nova.scheduler.host_manager] Host filter ignoring hosts: host1 2018-04-11 16:22:27,448 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/allocations/d661df8e-f8a0-45ea-9d90-0c55d7947120" status: 200 len: 19 microversion: 1.0 2018-04-11 16:22:27,457 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "PUT /placement/allocations/d661df8e-f8a0-45ea-9d90-0c55d7947120" status: 204 len: 0 microversion: 1.21 2018-04-11 16:22:27,476 INFO [nova.api.openstack.requestlog] 127.0.0.1 "POST /v2.1/6f70656e737461636b20342065766572/servers/d661df8e-f8a0-45ea-9d90-0c55d7947120/action" status: 202 len: 0 microversion: 2.60 time: 0.314875 2018-04-11 16:22:27,541 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/allocations/43cf626d-73dd-44d3-a625-91f9bf5dc9f3" status: 200 len: 134 microversion: 1.0 2018-04-11 16:22:27,541 INFO [nova.compute.manager] Swapping old allocation on f8528e77-80cf-4865-b1d3-a4e548159eb7 held by migration 43cf626d-73dd-44d3-a625-91f9bf5dc9f3 for instance 2018-04-11 16:22:27,551 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1/6f70656e737461636b20342065766572/servers/d661df8e-f8a0-45ea-9d90-0c55d7947120" status: 200 len: 1950 microversion: 2.60 time: 0.073405 2018-04-11 16:22:27,559 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "POST /placement/allocations" status: 204 len: 0 microversion: 1.13 2018-04-11 16:22:27,614 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/allocations/d661df8e-f8a0-45ea-9d90-0c55d7947120" status: 200 len: 134 microversion: 1.0 2018-04-11 16:22:27,627 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "POST /placement/allocations" status: 204 len: 0 microversion: 1.13 2018-04-11 16:22:27,632 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/allocations/d661df8e-f8a0-45ea-9d90-0c55d7947120" status: 200 len: 19 microversion: 1.0 2018-04-11 16:22:27,641 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "PUT /placement/allocations/d661df8e-f8a0-45ea-9d90-0c55d7947120" status: 204 len: 0 microversion: 1.21 2018-04-11 16:22:27,657 ERROR [nova.compute.manager] Error: host2:fake_prep_resize Traceback (most recent call last): File "nova/compute/manager.py", line 4156, in prep_resize node, migration, clean_shutdown) File "/tmp/nova/.tox/functional/local/lib/python2.7/site-packages/fixtures/_fixtures/monkeypatch.py", line 89, in avoid_get return captured_method(*args, **kwargs) File "nova/tests/functional/regressions/test_bug_1718512.py", line 98, in fake_prep_resize raise Exception('%s:fake_prep_resize' % failed_host) Exception: host2:fake_prep_resize 2018-04-11 16:22:27,730 INFO [nova.compute.claims] Attempting claim on node host3: memory 2048 MB, disk 20 GB, vcpus 1 CPU 2018-04-11 16:22:27,730 INFO [nova.compute.claims] Total memory: 8192 MB, used: 512.00 MB 2018-04-11 16:22:27,730 INFO [nova.compute.claims] memory limit not specified, defaulting to unlimited 2018-04-11 16:22:27,731 INFO [nova.compute.claims] Total disk: 1028 GB, used: 0.00 GB 2018-04-11 16:22:27,731 INFO [nova.compute.claims] disk limit not specified, defaulting to unlimited 2018-04-11 16:22:27,731 INFO [nova.compute.claims] Total vcpu: 2 VCPU, used: 0.00 VCPU 2018-04-11 16:22:27,731 INFO [nova.compute.claims] vcpu limit not specified, defaulting to unlimited 2018-04-11 16:22:27,731 INFO [nova.compute.claims] Claim successful on node host3 2018-04-11 16:22:27,737 INFO [nova.compute.resource_tracker] Updating from migration d661df8e-f8a0-45ea-9d90-0c55d7947120 2018-04-11 16:22:27,748 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=2978286e-1551-4150-b04d-59d93f5626ab" status: 200 len: 854 microversion: 1.14 2018-04-11 16:22:27,753 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/2978286e-1551-4150-b04d-59d93f5626ab/inventories" status: 200 len: 399 microversion: 1.0 2018-04-11 16:22:27,757 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/2978286e-1551-4150-b04d-59d93f5626ab/aggregates" status: 200 len: 18 microversion: 1.1 2018-04-11 16:22:27,762 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/2978286e-1551-4150-b04d-59d93f5626ab/traits" status: 200 len: 49 microversion: 1.6 2018-04-11 16:22:27,769 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/2978286e-1551-4150-b04d-59d93f5626ab/inventories" status: 200 len: 399 microversion: 1.0 2018-04-11 16:22:27,775 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=2978286e-1551-4150-b04d-59d93f5626ab" status: 200 len: 854 microversion: 1.14 2018-04-11 16:22:27,780 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/2978286e-1551-4150-b04d-59d93f5626ab/inventories" status: 200 len: 399 microversion: 1.0 2018-04-11 16:22:27,784 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/2978286e-1551-4150-b04d-59d93f5626ab/aggregates" status: 200 len: 18 microversion: 1.1 2018-04-11 16:22:27,789 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/2978286e-1551-4150-b04d-59d93f5626ab/traits" status: 200 len: 49 microversion: 1.6 2018-04-11 16:22:27,796 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/2978286e-1551-4150-b04d-59d93f5626ab/inventories" status: 200 len: 399 microversion: 1.0 2018-04-11 16:22:27,796 INFO [nova.compute.manager] Migrating 2018-04-11 16:22:28,032 INFO [nova.scheduler.host_manager] Received an update from an unknown host 'host3'. Re-created its InstanceList. 2018-04-11 16:22:28,114 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1/6f70656e737461636b20342065766572/servers/d661df8e-f8a0-45ea-9d90-0c55d7947120" status: 200 len: 1952 microversion: 2.60 time: 0.056709 2018-04-11 16:22:28,144 INFO [nova.api.openstack.requestlog] 127.0.0.1 "POST /v2.1/6f70656e737461636b20342065766572/servers/d661df8e-f8a0-45ea-9d90-0c55d7947120/action" status: 204 len: 0 microversion: 2.60 time: 0.028682 2018-04-11 16:22:28,204 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1/6f70656e737461636b20342065766572/servers/d661df8e-f8a0-45ea-9d90-0c55d7947120" status: 200 len: 1952 microversion: 2.60 time: 0.058202 2018-04-11 16:22:28,282 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=f8528e77-80cf-4865-b1d3-a4e548159eb7" status: 200 len: 854 microversion: 1.14 2018-04-11 16:22:28,287 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/f8528e77-80cf-4865-b1d3-a4e548159eb7/inventories" status: 200 len: 399 microversion: 1.0 2018-04-11 16:22:28,291 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/f8528e77-80cf-4865-b1d3-a4e548159eb7/aggregates" status: 200 len: 18 microversion: 1.1 2018-04-11 16:22:28,297 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/f8528e77-80cf-4865-b1d3-a4e548159eb7/traits" status: 200 len: 49 microversion: 1.6 2018-04-11 16:22:28,302 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/f8528e77-80cf-4865-b1d3-a4e548159eb7/inventories" status: 200 len: 399 microversion: 1.0 2018-04-11 16:22:28,311 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=f8528e77-80cf-4865-b1d3-a4e548159eb7" status: 200 len: 854 microversion: 1.14 2018-04-11 16:22:28,316 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/f8528e77-80cf-4865-b1d3-a4e548159eb7/inventories" status: 200 len: 399 microversion: 1.0 2018-04-11 16:22:28,321 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/f8528e77-80cf-4865-b1d3-a4e548159eb7/aggregates" status: 200 len: 18 microversion: 1.1 2018-04-11 16:22:28,325 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/f8528e77-80cf-4865-b1d3-a4e548159eb7/traits" status: 200 len: 49 microversion: 1.6 2018-04-11 16:22:28,331 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/f8528e77-80cf-4865-b1d3-a4e548159eb7/inventories" status: 200 len: 399 microversion: 1.0 2018-04-11 16:22:28,336 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "DELETE /placement/allocations/43cf626d-73dd-44d3-a625-91f9bf5dc9f3" status: 204 len: 0 microversion: 1.0 2018-04-11 16:22:28,337 INFO [nova.scheduler.client.report] Deleted allocation for instance 43cf626d-73dd-44d3-a625-91f9bf5dc9f3 2018-04-11 16:22:28,337 INFO [nova.compute.manager] Source node host1 confirmed migration 43cf626d-73dd-44d3-a625-91f9bf5dc9f3; deleted migration-based allocation 2018-04-11 16:22:28,798 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1/6f70656e737461636b20342065766572/servers/d661df8e-f8a0-45ea-9d90-0c55d7947120" status: 200 len: 1944 microversion: 2.60 time: 0.086875 2018-04-11 16:22:28,953 INFO [nova.api.openstack.requestlog] 127.0.0.1 "POST /v2.1/6f70656e737461636b20342065766572/servers/d661df8e-f8a0-45ea-9d90-0c55d7947120/action" status: 202 len: 0 microversion: 2.60 time: 0.153560 2018-04-11 16:22:29,018 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1/6f70656e737461636b20342065766572/servers/d661df8e-f8a0-45ea-9d90-0c55d7947120" status: 200 len: 1954 microversion: 2.60 time: 0.063068 2018-04-11 16:22:29,032 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/allocations/d661df8e-f8a0-45ea-9d90-0c55d7947120" status: 200 len: 136 microversion: 1.0 2018-04-11 16:22:29,044 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "POST /placement/allocations" status: 204 len: 0 microversion: 1.13 2018-04-11 16:22:29,074 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/allocation_candidates?limit=1000&resources=DISK_GB%3A20%2CMEMORY_MB%3A2048%2CVCPU%3A1" status: 200 len: 1001 microversion: 1.21 2018-04-11 16:22:29,103 INFO [nova.scheduler.host_manager] Host filter only checking host host2 and node host2 2018-04-11 16:22:29,103 INFO [nova.scheduler.host_manager] Host filter ignoring hosts: 2018-04-11 16:22:29,108 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/allocations/d661df8e-f8a0-45ea-9d90-0c55d7947120" status: 200 len: 19 microversion: 1.0 2018-04-11 16:22:29,119 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "PUT /placement/allocations/d661df8e-f8a0-45ea-9d90-0c55d7947120" status: 204 len: 0 microversion: 1.21 2018-04-11 16:22:29,403 INFO [nova.compute.manager] _post_live_migration() is started.. 2018-04-11 16:22:29,446 INFO [nova.compute.manager] Post operation of migration started 2018-04-11 16:22:29,497 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=2978286e-1551-4150-b04d-59d93f5626ab" status: 200 len: 854 microversion: 1.14 2018-04-11 16:22:29,502 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/2978286e-1551-4150-b04d-59d93f5626ab/inventories" status: 200 len: 399 microversion: 1.0 2018-04-11 16:22:29,507 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/2978286e-1551-4150-b04d-59d93f5626ab/aggregates" status: 200 len: 18 microversion: 1.1 2018-04-11 16:22:29,512 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/2978286e-1551-4150-b04d-59d93f5626ab/traits" status: 200 len: 49 microversion: 1.6 2018-04-11 16:22:29,516 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/2978286e-1551-4150-b04d-59d93f5626ab/inventories" status: 200 len: 399 microversion: 1.0 2018-04-11 16:22:29,530 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=2978286e-1551-4150-b04d-59d93f5626ab" status: 200 len: 854 microversion: 1.14 2018-04-11 16:22:29,554 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/2978286e-1551-4150-b04d-59d93f5626ab/inventories" status: 200 len: 399 microversion: 1.0 2018-04-11 16:22:29,583 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/2978286e-1551-4150-b04d-59d93f5626ab/aggregates" status: 200 len: 18 microversion: 1.1 2018-04-11 16:22:29,596 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1/6f70656e737461636b20342065766572/servers/d661df8e-f8a0-45ea-9d90-0c55d7947120" status: 200 len: 1944 microversion: 2.60 time: 0.070165 2018-04-11 16:22:29,600 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/2978286e-1551-4150-b04d-59d93f5626ab/traits" status: 200 len: 49 microversion: 1.6 2018-04-11 16:22:29,609 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/2978286e-1551-4150-b04d-59d93f5626ab/inventories" status: 200 len: 399 microversion: 1.0 2018-04-11 16:22:29,624 INFO [nova.api.openstack.requestlog] 127.0.0.1 "GET /v2.1/6f70656e737461636b20342065766572/os-migrations?instance_uuid=d661df8e-f8a0-45ea-9d90-0c55d7947120&migration_type=live-migration" status: 200 len: 794 microversion: 2.60 time: 0.025910 2018-04-11 16:22:29,640 WARNING [nova.compute.resource_tracker] Instance not resizing, skipping migration. 2018-04-11 16:22:29,645 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/2978286e-1551-4150-b04d-59d93f5626ab/allocations" status: 200 len: 154 microversion: 1.0 2018-04-11 16:22:29,645 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-04-11 16:22:29,655 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=2978286e-1551-4150-b04d-59d93f5626ab" status: 200 len: 854 microversion: 1.14 2018-04-11 16:22:29,664 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/2978286e-1551-4150-b04d-59d93f5626ab/inventories" status: 200 len: 399 microversion: 1.0 2018-04-11 16:22:29,669 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/2978286e-1551-4150-b04d-59d93f5626ab/aggregates" status: 200 len: 18 microversion: 1.1 2018-04-11 16:22:29,674 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/2978286e-1551-4150-b04d-59d93f5626ab/traits" status: 200 len: 49 microversion: 1.6 2018-04-11 16:22:29,679 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/2978286e-1551-4150-b04d-59d93f5626ab/inventories" status: 200 len: 399 microversion: 1.0 2018-04-11 16:22:29,685 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers?in_tree=2978286e-1551-4150-b04d-59d93f5626ab" status: 200 len: 854 microversion: 1.14 2018-04-11 16:22:29,690 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/2978286e-1551-4150-b04d-59d93f5626ab/inventories" status: 200 len: 399 microversion: 1.0 2018-04-11 16:22:29,694 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/2978286e-1551-4150-b04d-59d93f5626ab/aggregates" status: 200 len: 18 microversion: 1.1 2018-04-11 16:22:29,701 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/2978286e-1551-4150-b04d-59d93f5626ab/traits" status: 200 len: 49 microversion: 1.6 2018-04-11 16:22:29,711 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/resource_providers/2978286e-1551-4150-b04d-59d93f5626ab/inventories" status: 200 len: 399 microversion: 1.0 2018-04-11 16:22:29,714 INFO [nova.compute.manager] Migrating instance to host2 finished successfully. 2018-04-11 16:22:29,730 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "GET /placement/allocations/40c63cb5-f161-4952-95b7-c7631da0932b" status: 200 len: 136 microversion: 1.0 2018-04-11 16:22:29,736 INFO [nova.api.openstack.placement.requestlog] 127.0.0.1 "DELETE /placement/allocations/40c63cb5-f161-4952-95b7-c7631da0932b" status: 204 len: 0 microversion: 1.0 2018-04-11 16:22:29,737 INFO [nova.scheduler.client.report] Deleted allocation for instance 40c63cb5-f161-4952-95b7-c7631da0932b 2018-04-11 16:22:29,737 INFO [nova.compute.manager] Source node host3 confirmed migration 40c63cb5-f161-4952-95b7-c7631da0932b; deleted migration-based allocation