Attachment 923803 Details for Bug 1121591 – new compute log

new compute log

compute.log (text/plain), 136.28 KB, created by Tzach Shefi on 2014-08-04 08:50:10 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Tzach Shefi

Created: 2014-08-04 08:50:10 UTC

Size: 136.28 KB

patch

obsolete

>2014-08-04 10:44:27.176 16648 INFO nova.openstack.common.periodic_task [-] Skipping periodic task _periodic_update_dns because its interval is negative
>2014-08-04 10:44:27.356 16648 INFO nova.virt.driver [-] Loading compute driver 'nova.virt.libvirt.LibvirtDriver'
>2014-08-04 10:44:27.425 16648 INFO oslo.messaging._drivers.impl_rabbit [-] Connected to AMQP server on 10.35.104.16:5672
>2014-08-04 10:44:27.441 16648 INFO oslo.messaging._drivers.impl_rabbit [-] Connected to AMQP server on 10.35.104.16:5672
>2014-08-04 10:44:27.499 16648 AUDIT nova.service [-] Starting compute node (version 2014.1.1-3.el6ost)
>2014-08-04 10:44:28.084 16648 WARNING nova.virt.libvirt.driver [req-8e90ac8d-02bd-44b7-8265-ffeb9bfbbda0 None None] Cannot update service status on host: orange-vdse.qa.lab.tlv.redhat.com,since it is not registered.
>2014-08-04 10:44:28.542 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 10:44:28.728 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 15439
>2014-08-04 10:44:28.728 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 60
>2014-08-04 10:44:28.728 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2014-08-04 10:44:28.924 16648 INFO nova.compute.resource_tracker [-] Compute_service record created for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 10:44:28.991 16648 INFO oslo.messaging._drivers.impl_rabbit [-] Connected to AMQP server on 10.35.104.16:5672
>2014-08-04 10:45:13.043 16648 AUDIT nova.compute.resource_tracker [req-08877e8d-7778-4e8d-8947-95988d0cd301 None None] Auditing locally available compute resources
>2014-08-04 10:45:13.346 16648 AUDIT nova.compute.resource_tracker [req-08877e8d-7778-4e8d-8947-95988d0cd301 None None] Free ram (MB): 15439
>2014-08-04 10:45:13.346 16648 AUDIT nova.compute.resource_tracker [req-08877e8d-7778-4e8d-8947-95988d0cd301 None None] Free disk (GB): 60
>2014-08-04 10:45:13.346 16648 AUDIT nova.compute.resource_tracker [req-08877e8d-7778-4e8d-8947-95988d0cd301 None None] Free VCPUS: 2
>2014-08-04 10:45:13.416 16648 INFO nova.compute.resource_tracker [req-08877e8d-7778-4e8d-8947-95988d0cd301 None None] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 10:45:26.947 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 10:45:27.179 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 15439
>2014-08-04 10:45:27.179 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 60
>2014-08-04 10:45:27.179 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2014-08-04 10:45:27.217 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 10:46:27.283 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 10:46:27.450 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 15439
>2014-08-04 10:46:27.450 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 60
>2014-08-04 10:46:27.450 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2014-08-04 10:46:27.481 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 10:47:27.530 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 10:47:27.772 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 15439
>2014-08-04 10:47:27.772 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 60
>2014-08-04 10:47:27.772 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2014-08-04 10:47:27.807 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 10:48:27.895 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 10:48:28.629 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 15439
>2014-08-04 10:48:28.630 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 60
>2014-08-04 10:48:28.630 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2014-08-04 10:48:28.681 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 10:49:26.976 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 10:49:27.248 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 15439
>2014-08-04 10:49:27.248 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 60
>2014-08-04 10:49:27.248 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2014-08-04 10:49:27.282 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 10:49:28.715 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 10:49:28.911 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 15439
>2014-08-04 10:49:28.912 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 60
>2014-08-04 10:49:28.912 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2014-08-04 10:49:28.943 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 10:50:29.003 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 10:50:29.525 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 15439
>2014-08-04 10:50:29.526 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 60
>2014-08-04 10:50:29.526 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2014-08-04 10:50:29.561 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 10:51:29.623 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 10:51:29.924 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 15439
>2014-08-04 10:51:29.925 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 60
>2014-08-04 10:51:29.925 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2014-08-04 10:51:29.959 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 10:52:30.025 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 10:52:30.185 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 15439
>2014-08-04 10:52:30.185 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 60
>2014-08-04 10:52:30.186 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2014-08-04 10:52:30.228 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 10:53:30.278 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 10:53:31.068 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 15439
>2014-08-04 10:53:31.069 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 60
>2014-08-04 10:53:31.069 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2014-08-04 10:53:31.224 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 10:54:27.014 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 10:54:27.190 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 15439
>2014-08-04 10:54:27.190 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 60
>2014-08-04 10:54:27.190 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2014-08-04 10:54:27.219 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 10:54:27.246 16648 INFO nova.compute.manager [-] Updating bandwidth usage cache
>2014-08-04 10:54:27.267 16648 WARNING nova.compute.manager [-] Bandwidth usage not supported by hypervisor.
>2014-08-04 10:54:31.340 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 10:54:31.506 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 15439
>2014-08-04 10:54:31.507 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 60
>2014-08-04 10:54:31.507 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2014-08-04 10:54:31.540 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 10:55:13.501 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 10:55:13.647 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 15439
>2014-08-04 10:55:13.648 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 60
>2014-08-04 10:55:13.648 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2014-08-04 10:55:13.673 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 10:55:31.576 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 10:55:31.711 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 15439
>2014-08-04 10:55:31.712 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 60
>2014-08-04 10:55:31.712 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2014-08-04 10:55:31.742 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 10:56:31.791 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 10:56:31.936 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 15439
>2014-08-04 10:56:31.937 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 60
>2014-08-04 10:56:31.937 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2014-08-04 10:56:31.964 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 10:57:32.014 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 10:57:32.240 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 15439
>2014-08-04 10:57:32.241 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 60
>2014-08-04 10:57:32.241 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2014-08-04 10:57:38.119 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 10:58:16.886 16648 WARNING nova.openstack.common.loopingcall [-] task run outlasted interval by 2.468345 sec
>2014-08-04 10:58:41.872 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 10:58:42.020 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 15439
>2014-08-04 10:58:42.021 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 60
>2014-08-04 10:58:42.021 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2014-08-04 10:58:42.049 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 10:59:27.288 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 10:59:27.432 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 15439
>2014-08-04 10:59:27.432 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 60
>2014-08-04 10:59:27.432 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2014-08-04 10:59:27.460 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 10:59:42.077 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 10:59:42.213 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 15439
>2014-08-04 10:59:42.214 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 60
>2014-08-04 10:59:42.214 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2014-08-04 10:59:42.242 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:00:00.308 16648 AUDIT nova.compute.manager [req-69109d73-726c-48f0-b1ad-6b556250a7c5 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: c91f8fe4-1bda-443a-b128-b1d378f59912] Starting instance...
>2014-08-04 11:00:00.430 16648 AUDIT nova.compute.claims [req-69109d73-726c-48f0-b1ad-6b556250a7c5 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: c91f8fe4-1bda-443a-b128-b1d378f59912] Attempting claim: memory 2048 MB, disk 20 GB, VCPUs 1
>2014-08-04 11:00:00.430 16648 AUDIT nova.compute.claims [req-69109d73-726c-48f0-b1ad-6b556250a7c5 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: c91f8fe4-1bda-443a-b128-b1d378f59912] Total memory: 15951 MB, used: 512.00 MB
>2014-08-04 11:00:00.430 16648 AUDIT nova.compute.claims [req-69109d73-726c-48f0-b1ad-6b556250a7c5 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: c91f8fe4-1bda-443a-b128-b1d378f59912] memory limit: 23926.50 MB, free: 23414.50 MB
>2014-08-04 11:00:00.431 16648 AUDIT nova.compute.claims [req-69109d73-726c-48f0-b1ad-6b556250a7c5 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: c91f8fe4-1bda-443a-b128-b1d378f59912] Total disk: 60 GB, used: 0.00 GB
>2014-08-04 11:00:00.431 16648 AUDIT nova.compute.claims [req-69109d73-726c-48f0-b1ad-6b556250a7c5 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: c91f8fe4-1bda-443a-b128-b1d378f59912] disk limit not specified, defaulting to unlimited
>2014-08-04 11:00:00.431 16648 AUDIT nova.compute.claims [req-69109d73-726c-48f0-b1ad-6b556250a7c5 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: c91f8fe4-1bda-443a-b128-b1d378f59912] Total CPUs: 2 VCPUs, used: 0.00 VCPUs
>2014-08-04 11:00:00.431 16648 AUDIT nova.compute.claims [req-69109d73-726c-48f0-b1ad-6b556250a7c5 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: c91f8fe4-1bda-443a-b128-b1d378f59912] CPUs limit: 32.00 VCPUs, free: 32.00 VCPUs
>2014-08-04 11:00:00.432 16648 AUDIT nova.compute.claims [req-69109d73-726c-48f0-b1ad-6b556250a7c5 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: c91f8fe4-1bda-443a-b128-b1d378f59912] Claim successful
>2014-08-04 11:00:01.486 16648 INFO nova.virt.libvirt.driver [req-69109d73-726c-48f0-b1ad-6b556250a7c5 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: c91f8fe4-1bda-443a-b128-b1d378f59912] Creating image
>2014-08-04 11:00:01.489 16648 INFO nova.openstack.common.lockutils [req-69109d73-726c-48f0-b1ad-6b556250a7c5 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] Created lock path: /var/lib/nova/instances/locks
>2014-08-04 11:00:26.879 16648 ERROR nova.compute.manager [req-69109d73-726c-48f0-b1ad-6b556250a7c5 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: c91f8fe4-1bda-443a-b128-b1d378f59912] Instance failed to spawn
>2014-08-04 11:00:26.879 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912] Traceback (most recent call last):
>2014-08-04 11:00:26.879 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]   File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 1714, in _spawn
>2014-08-04 11:00:26.879 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]     block_device_info)
>2014-08-04 11:00:26.879 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/driver.py", line 2282, in spawn
>2014-08-04 11:00:26.879 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]     admin_pass=admin_password)
>2014-08-04 11:00:26.879 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/driver.py", line 2651, in _create_image
>2014-08-04 11:00:26.879 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]     project_id=instance['project_id'])
>2014-08-04 11:00:26.879 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/imagebackend.py", line 192, in cache
>2014-08-04 11:00:26.879 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]     *args, **kwargs)
>2014-08-04 11:00:26.879 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/imagebackend.py", line 388, in create_image
>2014-08-04 11:00:26.879 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]     prepare_template(target=base, max_size=size, *args, **kwargs)
>2014-08-04 11:00:26.879 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]   File "/usr/lib/python2.6/site-packages/nova/openstack/common/lockutils.py", line 249, in inner
>2014-08-04 11:00:26.879 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]     return f(*args, **kwargs)
>2014-08-04 11:00:26.879 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/imagebackend.py", line 182, in fetch_func_sync
>2014-08-04 11:00:26.879 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]     fetch_func(target=target, *args, **kwargs)
>2014-08-04 11:00:26.879 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/utils.py", line 653, in fetch_image
>2014-08-04 11:00:26.879 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]     max_size=max_size)
>2014-08-04 11:00:26.879 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]   File "/usr/lib/python2.6/site-packages/nova/virt/images.py", line 81, in fetch_to_raw
>2014-08-04 11:00:26.879 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]     data = qemu_img_info(path_tmp)
>2014-08-04 11:00:26.879 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]   File "/usr/lib/python2.6/site-packages/nova/virt/images.py", line 54, in qemu_img_info
>2014-08-04 11:00:26.879 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]     'qemu-img', 'info', path)
>2014-08-04 11:00:26.879 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]   File "/usr/lib/python2.6/site-packages/nova/utils.py", line 165, in execute
>2014-08-04 11:00:26.879 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]     return processutils.execute(*cmd, **kwargs)
>2014-08-04 11:00:26.879 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]   File "/usr/lib/python2.6/site-packages/nova/openstack/common/processutils.py", line 193, in execute
>2014-08-04 11:00:26.879 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]     cmd=' '.join(cmd))
>2014-08-04 11:00:26.879 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912] ProcessExecutionError: Unexpected error while running command.
>2014-08-04 11:00:26.879 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912] Command: env LC_ALL=C LANG=C qemu-img info /var/lib/nova/instances/_base/759fd7c54b5726bb3e060daeb676e15f44be97f2.part
>2014-08-04 11:00:26.879 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912] Exit code: 1
>2014-08-04 11:00:26.879 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912] Stdout: ''
>2014-08-04 11:00:26.879 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912] Stderr: "Could not open '/var/lib/nova/instances/_base/759fd7c54b5726bb3e060daeb676e15f44be97f2.part': Operation not permitted\n"
>2014-08-04 11:00:26.879 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912] 
>2014-08-04 11:00:26.907 16648 INFO oslo.messaging._drivers.impl_rabbit [-] Connected to AMQP server on 10.35.104.16:5672
>2014-08-04 11:00:28.143 16648 AUDIT nova.compute.manager [req-69109d73-726c-48f0-b1ad-6b556250a7c5 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: c91f8fe4-1bda-443a-b128-b1d378f59912] Terminating instance
>2014-08-04 11:00:28.217 16648 ERROR nova.virt.libvirt.driver [-] [instance: c91f8fe4-1bda-443a-b128-b1d378f59912] During wait destroy, instance disappeared.
>2014-08-04 11:00:28.560 16648 INFO nova.virt.libvirt.driver [req-69109d73-726c-48f0-b1ad-6b556250a7c5 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: c91f8fe4-1bda-443a-b128-b1d378f59912] Deleting instance files /var/lib/nova/instances/c91f8fe4-1bda-443a-b128-b1d378f59912
>2014-08-04 11:00:28.561 16648 INFO nova.virt.libvirt.driver [req-69109d73-726c-48f0-b1ad-6b556250a7c5 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: c91f8fe4-1bda-443a-b128-b1d378f59912] Deletion of /var/lib/nova/instances/c91f8fe4-1bda-443a-b128-b1d378f59912 complete
>2014-08-04 11:00:29.093 16648 ERROR nova.compute.manager [req-69109d73-726c-48f0-b1ad-6b556250a7c5 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: c91f8fe4-1bda-443a-b128-b1d378f59912] Error: Unexpected error while running command.
>Command: env LC_ALL=C LANG=C qemu-img info /var/lib/nova/instances/_base/759fd7c54b5726bb3e060daeb676e15f44be97f2.part
>Exit code: 1
>Stdout: ''
>Stderr: "Could not open '/var/lib/nova/instances/_base/759fd7c54b5726bb3e060daeb676e15f44be97f2.part': Operation not permitted\n"
>2014-08-04 11:00:29.093 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912] Traceback (most recent call last):
>2014-08-04 11:00:29.093 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]   File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 1305, in _build_instance
>2014-08-04 11:00:29.093 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]     set_access_ip=set_access_ip)
>2014-08-04 11:00:29.093 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]   File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 393, in decorated_function
>2014-08-04 11:00:29.093 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]     return function(self, context, *args, **kwargs)
>2014-08-04 11:00:29.093 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]   File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 1717, in _spawn
>2014-08-04 11:00:29.093 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]     LOG.exception(_('Instance failed to spawn'), instance=instance)
>2014-08-04 11:00:29.093 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]   File "/usr/lib/python2.6/site-packages/nova/openstack/common/excutils.py", line 68, in __exit__
>2014-08-04 11:00:29.093 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]     six.reraise(self.type_, self.value, self.tb)
>2014-08-04 11:00:29.093 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]   File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 1714, in _spawn
>2014-08-04 11:00:29.093 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]     block_device_info)
>2014-08-04 11:00:29.093 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/driver.py", line 2282, in spawn
>2014-08-04 11:00:29.093 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]     admin_pass=admin_password)
>2014-08-04 11:00:29.093 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/driver.py", line 2651, in _create_image
>2014-08-04 11:00:29.093 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]     project_id=instance['project_id'])
>2014-08-04 11:00:29.093 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/imagebackend.py", line 192, in cache
>2014-08-04 11:00:29.093 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]     *args, **kwargs)
>2014-08-04 11:00:29.093 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/imagebackend.py", line 388, in create_image
>2014-08-04 11:00:29.093 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]     prepare_template(target=base, max_size=size, *args, **kwargs)
>2014-08-04 11:00:29.093 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]   File "/usr/lib/python2.6/site-packages/nova/openstack/common/lockutils.py", line 249, in inner
>2014-08-04 11:00:29.093 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]     return f(*args, **kwargs)
>2014-08-04 11:00:29.093 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/imagebackend.py", line 182, in fetch_func_sync
>2014-08-04 11:00:29.093 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]     fetch_func(target=target, *args, **kwargs)
>2014-08-04 11:00:29.093 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/utils.py", line 653, in fetch_image
>2014-08-04 11:00:29.093 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]     max_size=max_size)
>2014-08-04 11:00:29.093 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]   File "/usr/lib/python2.6/site-packages/nova/virt/images.py", line 81, in fetch_to_raw
>2014-08-04 11:00:29.093 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]     data = qemu_img_info(path_tmp)
>2014-08-04 11:00:29.093 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]   File "/usr/lib/python2.6/site-packages/nova/virt/images.py", line 54, in qemu_img_info
>2014-08-04 11:00:29.093 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]     'qemu-img', 'info', path)
>2014-08-04 11:00:29.093 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]   File "/usr/lib/python2.6/site-packages/nova/utils.py", line 165, in execute
>2014-08-04 11:00:29.093 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]     return processutils.execute(*cmd, **kwargs)
>2014-08-04 11:00:29.093 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]   File "/usr/lib/python2.6/site-packages/nova/openstack/common/processutils.py", line 193, in execute
>2014-08-04 11:00:29.093 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912]     cmd=' '.join(cmd))
>2014-08-04 11:00:29.093 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912] ProcessExecutionError: Unexpected error while running command.
>2014-08-04 11:00:29.093 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912] Command: env LC_ALL=C LANG=C qemu-img info /var/lib/nova/instances/_base/759fd7c54b5726bb3e060daeb676e15f44be97f2.part
>2014-08-04 11:00:29.093 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912] Exit code: 1
>2014-08-04 11:00:29.093 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912] Stdout: ''
>2014-08-04 11:00:29.093 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912] Stderr: "Could not open '/var/lib/nova/instances/_base/759fd7c54b5726bb3e060daeb676e15f44be97f2.part': Operation not permitted\n"
>2014-08-04 11:00:29.093 16648 TRACE nova.compute.manager [instance: c91f8fe4-1bda-443a-b128-b1d378f59912] 
>2014-08-04 11:00:42.291 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:00:42.526 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 15439
>2014-08-04 11:00:42.527 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 60
>2014-08-04 11:00:42.527 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2014-08-04 11:00:42.560 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:01:42.630 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:01:42.817 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 15439
>2014-08-04 11:01:42.817 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 60
>2014-08-04 11:01:42.817 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2014-08-04 11:01:42.868 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:02:42.917 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:02:43.058 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 15439
>2014-08-04 11:02:43.058 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 60
>2014-08-04 11:02:43.058 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2014-08-04 11:02:43.090 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:03:43.141 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:03:43.287 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 15439
>2014-08-04 11:03:43.287 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 60
>2014-08-04 11:03:43.287 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2014-08-04 11:03:43.318 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:04:27.268 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:04:27.407 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 15439
>2014-08-04 11:04:27.408 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 60
>2014-08-04 11:04:27.408 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2014-08-04 11:04:27.445 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:04:43.346 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:04:43.486 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 15439
>2014-08-04 11:04:43.486 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 60
>2014-08-04 11:04:43.486 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2014-08-04 11:04:43.509 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:05:13.727 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:05:13.871 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 15439
>2014-08-04 11:05:13.872 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 60
>2014-08-04 11:05:13.872 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2014-08-04 11:05:13.899 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:05:43.538 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:05:43.673 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 15439
>2014-08-04 11:05:43.673 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 60
>2014-08-04 11:05:43.674 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2014-08-04 11:05:43.701 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:06:43.750 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:06:43.902 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 15439
>2014-08-04 11:06:43.902 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 60
>2014-08-04 11:06:43.902 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2014-08-04 11:06:43.928 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:07:42.448 16648 AUDIT nova.compute.manager [req-3115f800-b7a7-4bd6-9f10-f078e965d703 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4] Starting instance...
>2014-08-04 11:07:42.690 16648 AUDIT nova.compute.claims [req-3115f800-b7a7-4bd6-9f10-f078e965d703 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4] Attempting claim: memory 2048 MB, disk 20 GB, VCPUs 1
>2014-08-04 11:07:42.691 16648 AUDIT nova.compute.claims [req-3115f800-b7a7-4bd6-9f10-f078e965d703 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4] Total memory: 15951 MB, used: 512.00 MB
>2014-08-04 11:07:42.691 16648 AUDIT nova.compute.claims [req-3115f800-b7a7-4bd6-9f10-f078e965d703 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4] memory limit: 23926.50 MB, free: 23414.50 MB
>2014-08-04 11:07:42.691 16648 AUDIT nova.compute.claims [req-3115f800-b7a7-4bd6-9f10-f078e965d703 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4] Total disk: 60 GB, used: 0.00 GB
>2014-08-04 11:07:42.691 16648 AUDIT nova.compute.claims [req-3115f800-b7a7-4bd6-9f10-f078e965d703 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4] disk limit not specified, defaulting to unlimited
>2014-08-04 11:07:42.691 16648 AUDIT nova.compute.claims [req-3115f800-b7a7-4bd6-9f10-f078e965d703 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4] Total CPUs: 2 VCPUs, used: 0.00 VCPUs
>2014-08-04 11:07:42.692 16648 AUDIT nova.compute.claims [req-3115f800-b7a7-4bd6-9f10-f078e965d703 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4] CPUs limit: 32.00 VCPUs, free: 32.00 VCPUs
>2014-08-04 11:07:42.692 16648 AUDIT nova.compute.claims [req-3115f800-b7a7-4bd6-9f10-f078e965d703 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4] Claim successful
>2014-08-04 11:07:43.972 16648 INFO nova.virt.libvirt.driver [req-3115f800-b7a7-4bd6-9f10-f078e965d703 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4] Creating image
>2014-08-04 11:07:43.997 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:07:45.157 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13391
>2014-08-04 11:07:45.157 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 40
>2014-08-04 11:07:45.157 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 1
>2014-08-04 11:07:45.238 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:08:17.613 16648 ERROR nova.compute.manager [req-3115f800-b7a7-4bd6-9f10-f078e965d703 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4] Instance failed to spawn
>2014-08-04 11:08:17.613 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4] Traceback (most recent call last):
>2014-08-04 11:08:17.613 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]   File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 1714, in _spawn
>2014-08-04 11:08:17.613 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]     block_device_info)
>2014-08-04 11:08:17.613 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/driver.py", line 2282, in spawn
>2014-08-04 11:08:17.613 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]     admin_pass=admin_password)
>2014-08-04 11:08:17.613 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/driver.py", line 2651, in _create_image
>2014-08-04 11:08:17.613 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]     project_id=instance['project_id'])
>2014-08-04 11:08:17.613 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/imagebackend.py", line 192, in cache
>2014-08-04 11:08:17.613 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]     *args, **kwargs)
>2014-08-04 11:08:17.613 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/imagebackend.py", line 388, in create_image
>2014-08-04 11:08:17.613 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]     prepare_template(target=base, max_size=size, *args, **kwargs)
>2014-08-04 11:08:17.613 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]   File "/usr/lib/python2.6/site-packages/nova/openstack/common/lockutils.py", line 249, in inner
>2014-08-04 11:08:17.613 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]     return f(*args, **kwargs)
>2014-08-04 11:08:17.613 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/imagebackend.py", line 182, in fetch_func_sync
>2014-08-04 11:08:17.613 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]     fetch_func(target=target, *args, **kwargs)
>2014-08-04 11:08:17.613 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/utils.py", line 653, in fetch_image
>2014-08-04 11:08:17.613 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]     max_size=max_size)
>2014-08-04 11:08:17.613 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]   File "/usr/lib/python2.6/site-packages/nova/virt/images.py", line 81, in fetch_to_raw
>2014-08-04 11:08:17.613 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]     data = qemu_img_info(path_tmp)
>2014-08-04 11:08:17.613 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]   File "/usr/lib/python2.6/site-packages/nova/virt/images.py", line 54, in qemu_img_info
>2014-08-04 11:08:17.613 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]     'qemu-img', 'info', path)
>2014-08-04 11:08:17.613 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]   File "/usr/lib/python2.6/site-packages/nova/utils.py", line 165, in execute
>2014-08-04 11:08:17.613 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]     return processutils.execute(*cmd, **kwargs)
>2014-08-04 11:08:17.613 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]   File "/usr/lib/python2.6/site-packages/nova/openstack/common/processutils.py", line 193, in execute
>2014-08-04 11:08:17.613 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]     cmd=' '.join(cmd))
>2014-08-04 11:08:17.613 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4] ProcessExecutionError: Unexpected error while running command.
>2014-08-04 11:08:17.613 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4] Command: env LC_ALL=C LANG=C qemu-img info /var/lib/nova/instances/_base/759fd7c54b5726bb3e060daeb676e15f44be97f2.part
>2014-08-04 11:08:17.613 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4] Exit code: 1
>2014-08-04 11:08:17.613 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4] Stdout: ''
>2014-08-04 11:08:17.613 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4] Stderr: "Could not open '/var/lib/nova/instances/_base/759fd7c54b5726bb3e060daeb676e15f44be97f2.part': Operation not permitted\n"
>2014-08-04 11:08:17.613 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4] 
>2014-08-04 11:08:18.893 16648 WARNING nova.openstack.common.loopingcall [-] task run outlasted interval by 1.826496 sec
>2014-08-04 11:08:19.015 16648 AUDIT nova.compute.manager [req-3115f800-b7a7-4bd6-9f10-f078e965d703 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4] Terminating instance
>2014-08-04 11:08:19.093 16648 ERROR nova.virt.libvirt.driver [-] [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4] During wait destroy, instance disappeared.
>2014-08-04 11:08:19.327 16648 INFO nova.virt.libvirt.driver [req-3115f800-b7a7-4bd6-9f10-f078e965d703 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4] Deleting instance files /var/lib/nova/instances/b907eb95-81ec-4cef-ac13-9d65fff47ad4
>2014-08-04 11:08:19.327 16648 INFO nova.virt.libvirt.driver [req-3115f800-b7a7-4bd6-9f10-f078e965d703 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4] Deletion of /var/lib/nova/instances/b907eb95-81ec-4cef-ac13-9d65fff47ad4 complete
>2014-08-04 11:08:19.995 16648 ERROR nova.compute.manager [req-3115f800-b7a7-4bd6-9f10-f078e965d703 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4] Error: Unexpected error while running command.
>Command: env LC_ALL=C LANG=C qemu-img info /var/lib/nova/instances/_base/759fd7c54b5726bb3e060daeb676e15f44be97f2.part
>Exit code: 1
>Stdout: ''
>Stderr: "Could not open '/var/lib/nova/instances/_base/759fd7c54b5726bb3e060daeb676e15f44be97f2.part': Operation not permitted\n"
>2014-08-04 11:08:19.995 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4] Traceback (most recent call last):
>2014-08-04 11:08:19.995 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]   File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 1305, in _build_instance
>2014-08-04 11:08:19.995 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]     set_access_ip=set_access_ip)
>2014-08-04 11:08:19.995 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]   File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 393, in decorated_function
>2014-08-04 11:08:19.995 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]     return function(self, context, *args, **kwargs)
>2014-08-04 11:08:19.995 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]   File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 1717, in _spawn
>2014-08-04 11:08:19.995 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]     LOG.exception(_('Instance failed to spawn'), instance=instance)
>2014-08-04 11:08:19.995 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]   File "/usr/lib/python2.6/site-packages/nova/openstack/common/excutils.py", line 68, in __exit__
>2014-08-04 11:08:19.995 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]     six.reraise(self.type_, self.value, self.tb)
>2014-08-04 11:08:19.995 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]   File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 1714, in _spawn
>2014-08-04 11:08:19.995 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]     block_device_info)
>2014-08-04 11:08:19.995 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/driver.py", line 2282, in spawn
>2014-08-04 11:08:19.995 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]     admin_pass=admin_password)
>2014-08-04 11:08:19.995 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/driver.py", line 2651, in _create_image
>2014-08-04 11:08:19.995 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]     project_id=instance['project_id'])
>2014-08-04 11:08:19.995 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/imagebackend.py", line 192, in cache
>2014-08-04 11:08:19.995 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]     *args, **kwargs)
>2014-08-04 11:08:19.995 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/imagebackend.py", line 388, in create_image
>2014-08-04 11:08:19.995 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]     prepare_template(target=base, max_size=size, *args, **kwargs)
>2014-08-04 11:08:19.995 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]   File "/usr/lib/python2.6/site-packages/nova/openstack/common/lockutils.py", line 249, in inner
>2014-08-04 11:08:19.995 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]     return f(*args, **kwargs)
>2014-08-04 11:08:19.995 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/imagebackend.py", line 182, in fetch_func_sync
>2014-08-04 11:08:19.995 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]     fetch_func(target=target, *args, **kwargs)
>2014-08-04 11:08:19.995 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/utils.py", line 653, in fetch_image
>2014-08-04 11:08:19.995 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]     max_size=max_size)
>2014-08-04 11:08:19.995 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]   File "/usr/lib/python2.6/site-packages/nova/virt/images.py", line 81, in fetch_to_raw
>2014-08-04 11:08:19.995 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]     data = qemu_img_info(path_tmp)
>2014-08-04 11:08:19.995 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]   File "/usr/lib/python2.6/site-packages/nova/virt/images.py", line 54, in qemu_img_info
>2014-08-04 11:08:19.995 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]     'qemu-img', 'info', path)
>2014-08-04 11:08:19.995 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]   File "/usr/lib/python2.6/site-packages/nova/utils.py", line 165, in execute
>2014-08-04 11:08:19.995 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]     return processutils.execute(*cmd, **kwargs)
>2014-08-04 11:08:19.995 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]   File "/usr/lib/python2.6/site-packages/nova/openstack/common/processutils.py", line 193, in execute
>2014-08-04 11:08:19.995 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4]     cmd=' '.join(cmd))
>2014-08-04 11:08:19.995 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4] ProcessExecutionError: Unexpected error while running command.
>2014-08-04 11:08:19.995 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4] Command: env LC_ALL=C LANG=C qemu-img info /var/lib/nova/instances/_base/759fd7c54b5726bb3e060daeb676e15f44be97f2.part
>2014-08-04 11:08:19.995 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4] Exit code: 1
>2014-08-04 11:08:19.995 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4] Stdout: ''
>2014-08-04 11:08:19.995 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4] Stderr: "Could not open '/var/lib/nova/instances/_base/759fd7c54b5726bb3e060daeb676e15f44be97f2.part': Operation not permitted\n"
>2014-08-04 11:08:19.995 16648 TRACE nova.compute.manager [instance: b907eb95-81ec-4cef-ac13-9d65fff47ad4] 
>2014-08-04 11:08:22.566 16648 AUDIT nova.compute.manager [req-3115f800-b7a7-4bd6-9f10-f078e965d703 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7] Starting instance...
>2014-08-04 11:08:22.794 16648 AUDIT nova.compute.claims [req-3115f800-b7a7-4bd6-9f10-f078e965d703 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7] Attempting claim: memory 2048 MB, disk 20 GB, VCPUs 1
>2014-08-04 11:08:22.794 16648 AUDIT nova.compute.claims [req-3115f800-b7a7-4bd6-9f10-f078e965d703 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7] Total memory: 15951 MB, used: 512.00 MB
>2014-08-04 11:08:22.795 16648 AUDIT nova.compute.claims [req-3115f800-b7a7-4bd6-9f10-f078e965d703 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7] memory limit: 23926.50 MB, free: 23414.50 MB
>2014-08-04 11:08:22.795 16648 AUDIT nova.compute.claims [req-3115f800-b7a7-4bd6-9f10-f078e965d703 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7] Total disk: 60 GB, used: 0.00 GB
>2014-08-04 11:08:22.795 16648 AUDIT nova.compute.claims [req-3115f800-b7a7-4bd6-9f10-f078e965d703 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7] disk limit not specified, defaulting to unlimited
>2014-08-04 11:08:22.795 16648 AUDIT nova.compute.claims [req-3115f800-b7a7-4bd6-9f10-f078e965d703 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7] Total CPUs: 2 VCPUs, used: 0.00 VCPUs
>2014-08-04 11:08:22.796 16648 AUDIT nova.compute.claims [req-3115f800-b7a7-4bd6-9f10-f078e965d703 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7] CPUs limit: 32.00 VCPUs, free: 32.00 VCPUs
>2014-08-04 11:08:22.796 16648 AUDIT nova.compute.claims [req-3115f800-b7a7-4bd6-9f10-f078e965d703 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7] Claim successful
>2014-08-04 11:08:23.842 16648 INFO nova.virt.libvirt.driver [req-3115f800-b7a7-4bd6-9f10-f078e965d703 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7] Creating image
>2014-08-04 11:08:45.453 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:08:56.757 16648 ERROR nova.compute.manager [req-3115f800-b7a7-4bd6-9f10-f078e965d703 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7] Instance failed to spawn
>2014-08-04 11:08:56.757 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7] Traceback (most recent call last):
>2014-08-04 11:08:56.757 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]   File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 1714, in _spawn
>2014-08-04 11:08:56.757 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]     block_device_info)
>2014-08-04 11:08:56.757 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/driver.py", line 2282, in spawn
>2014-08-04 11:08:56.757 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]     admin_pass=admin_password)
>2014-08-04 11:08:56.757 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/driver.py", line 2651, in _create_image
>2014-08-04 11:08:56.757 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]     project_id=instance['project_id'])
>2014-08-04 11:08:56.757 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/imagebackend.py", line 192, in cache
>2014-08-04 11:08:56.757 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]     *args, **kwargs)
>2014-08-04 11:08:56.757 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/imagebackend.py", line 388, in create_image
>2014-08-04 11:08:56.757 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]     prepare_template(target=base, max_size=size, *args, **kwargs)
>2014-08-04 11:08:56.757 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]   File "/usr/lib/python2.6/site-packages/nova/openstack/common/lockutils.py", line 249, in inner
>2014-08-04 11:08:56.757 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]     return f(*args, **kwargs)
>2014-08-04 11:08:56.757 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/imagebackend.py", line 182, in fetch_func_sync
>2014-08-04 11:08:56.757 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]     fetch_func(target=target, *args, **kwargs)
>2014-08-04 11:08:56.757 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/utils.py", line 653, in fetch_image
>2014-08-04 11:08:56.757 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]     max_size=max_size)
>2014-08-04 11:08:56.757 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]   File "/usr/lib/python2.6/site-packages/nova/virt/images.py", line 81, in fetch_to_raw
>2014-08-04 11:08:56.757 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]     data = qemu_img_info(path_tmp)
>2014-08-04 11:08:56.757 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]   File "/usr/lib/python2.6/site-packages/nova/virt/images.py", line 54, in qemu_img_info
>2014-08-04 11:08:56.757 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]     'qemu-img', 'info', path)
>2014-08-04 11:08:56.757 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]   File "/usr/lib/python2.6/site-packages/nova/utils.py", line 165, in execute
>2014-08-04 11:08:56.757 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]     return processutils.execute(*cmd, **kwargs)
>2014-08-04 11:08:56.757 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]   File "/usr/lib/python2.6/site-packages/nova/openstack/common/processutils.py", line 193, in execute
>2014-08-04 11:08:56.757 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]     cmd=' '.join(cmd))
>2014-08-04 11:08:56.757 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7] ProcessExecutionError: Unexpected error while running command.
>2014-08-04 11:08:56.757 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7] Command: env LC_ALL=C LANG=C qemu-img info /var/lib/nova/instances/_base/759fd7c54b5726bb3e060daeb676e15f44be97f2.part
>2014-08-04 11:08:56.757 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7] Exit code: 1
>2014-08-04 11:08:56.757 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7] Stdout: ''
>2014-08-04 11:08:56.757 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7] Stderr: "Could not open '/var/lib/nova/instances/_base/759fd7c54b5726bb3e060daeb676e15f44be97f2.part': Operation not permitted\n"
>2014-08-04 11:08:56.757 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7] 
>2014-08-04 11:08:57.173 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13391
>2014-08-04 11:08:57.174 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 40
>2014-08-04 11:08:57.174 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 1
>2014-08-04 11:08:57.233 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:08:57.360 16648 AUDIT nova.compute.manager [req-3115f800-b7a7-4bd6-9f10-f078e965d703 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7] Terminating instance
>2014-08-04 11:08:57.389 16648 ERROR nova.virt.libvirt.driver [-] [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7] During wait destroy, instance disappeared.
>2014-08-04 11:08:57.622 16648 INFO nova.virt.libvirt.driver [req-3115f800-b7a7-4bd6-9f10-f078e965d703 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7] Deleting instance files /var/lib/nova/instances/82898e5f-6586-46cf-a4f8-9f24268c99a7
>2014-08-04 11:08:57.623 16648 INFO nova.virt.libvirt.driver [req-3115f800-b7a7-4bd6-9f10-f078e965d703 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7] Deletion of /var/lib/nova/instances/82898e5f-6586-46cf-a4f8-9f24268c99a7 complete
>2014-08-04 11:08:58.142 16648 ERROR nova.compute.manager [req-3115f800-b7a7-4bd6-9f10-f078e965d703 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7] Error: Unexpected error while running command.
>Command: env LC_ALL=C LANG=C qemu-img info /var/lib/nova/instances/_base/759fd7c54b5726bb3e060daeb676e15f44be97f2.part
>Exit code: 1
>Stdout: ''
>Stderr: "Could not open '/var/lib/nova/instances/_base/759fd7c54b5726bb3e060daeb676e15f44be97f2.part': Operation not permitted\n"
>2014-08-04 11:08:58.142 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7] Traceback (most recent call last):
>2014-08-04 11:08:58.142 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]   File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 1305, in _build_instance
>2014-08-04 11:08:58.142 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]     set_access_ip=set_access_ip)
>2014-08-04 11:08:58.142 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]   File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 393, in decorated_function
>2014-08-04 11:08:58.142 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]     return function(self, context, *args, **kwargs)
>2014-08-04 11:08:58.142 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]   File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 1717, in _spawn
>2014-08-04 11:08:58.142 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]     LOG.exception(_('Instance failed to spawn'), instance=instance)
>2014-08-04 11:08:58.142 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]   File "/usr/lib/python2.6/site-packages/nova/openstack/common/excutils.py", line 68, in __exit__
>2014-08-04 11:08:58.142 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]     six.reraise(self.type_, self.value, self.tb)
>2014-08-04 11:08:58.142 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]   File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 1714, in _spawn
>2014-08-04 11:08:58.142 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]     block_device_info)
>2014-08-04 11:08:58.142 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/driver.py", line 2282, in spawn
>2014-08-04 11:08:58.142 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]     admin_pass=admin_password)
>2014-08-04 11:08:58.142 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/driver.py", line 2651, in _create_image
>2014-08-04 11:08:58.142 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]     project_id=instance['project_id'])
>2014-08-04 11:08:58.142 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/imagebackend.py", line 192, in cache
>2014-08-04 11:08:58.142 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]     *args, **kwargs)
>2014-08-04 11:08:58.142 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/imagebackend.py", line 388, in create_image
>2014-08-04 11:08:58.142 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]     prepare_template(target=base, max_size=size, *args, **kwargs)
>2014-08-04 11:08:58.142 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]   File "/usr/lib/python2.6/site-packages/nova/openstack/common/lockutils.py", line 249, in inner
>2014-08-04 11:08:58.142 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]     return f(*args, **kwargs)
>2014-08-04 11:08:58.142 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/imagebackend.py", line 182, in fetch_func_sync
>2014-08-04 11:08:58.142 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]     fetch_func(target=target, *args, **kwargs)
>2014-08-04 11:08:58.142 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/utils.py", line 653, in fetch_image
>2014-08-04 11:08:58.142 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]     max_size=max_size)
>2014-08-04 11:08:58.142 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]   File "/usr/lib/python2.6/site-packages/nova/virt/images.py", line 81, in fetch_to_raw
>2014-08-04 11:08:58.142 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]     data = qemu_img_info(path_tmp)
>2014-08-04 11:08:58.142 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]   File "/usr/lib/python2.6/site-packages/nova/virt/images.py", line 54, in qemu_img_info
>2014-08-04 11:08:58.142 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]     'qemu-img', 'info', path)
>2014-08-04 11:08:58.142 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]   File "/usr/lib/python2.6/site-packages/nova/utils.py", line 165, in execute
>2014-08-04 11:08:58.142 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]     return processutils.execute(*cmd, **kwargs)
>2014-08-04 11:08:58.142 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]   File "/usr/lib/python2.6/site-packages/nova/openstack/common/processutils.py", line 193, in execute
>2014-08-04 11:08:58.142 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7]     cmd=' '.join(cmd))
>2014-08-04 11:08:58.142 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7] ProcessExecutionError: Unexpected error while running command.
>2014-08-04 11:08:58.142 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7] Command: env LC_ALL=C LANG=C qemu-img info /var/lib/nova/instances/_base/759fd7c54b5726bb3e060daeb676e15f44be97f2.part
>2014-08-04 11:08:58.142 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7] Exit code: 1
>2014-08-04 11:08:58.142 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7] Stdout: ''
>2014-08-04 11:08:58.142 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7] Stderr: "Could not open '/var/lib/nova/instances/_base/759fd7c54b5726bb3e060daeb676e15f44be97f2.part': Operation not permitted\n"
>2014-08-04 11:08:58.142 16648 TRACE nova.compute.manager [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7] 
>2014-08-04 11:09:27.511 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:09:27.661 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13391
>2014-08-04 11:09:27.662 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 40
>2014-08-04 11:09:27.662 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 1
>2014-08-04 11:09:27.693 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:09:57.295 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:09:57.450 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13391
>2014-08-04 11:09:57.450 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 40
>2014-08-04 11:09:57.451 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 1
>2014-08-04 11:09:57.482 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:10:57.928 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:10:58.076 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13391
>2014-08-04 11:10:58.076 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 40
>2014-08-04 11:10:58.076 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 1
>2014-08-04 11:10:58.111 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:11:58.368 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:11:58.525 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13391
>2014-08-04 11:11:58.525 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 40
>2014-08-04 11:11:58.525 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 1
>2014-08-04 11:11:58.555 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:12:58.801 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:12:58.974 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13391
>2014-08-04 11:12:58.974 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 40
>2014-08-04 11:12:58.975 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 1
>2014-08-04 11:12:58.998 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:13:59.267 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:13:59.421 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13391
>2014-08-04 11:13:59.421 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 40
>2014-08-04 11:13:59.421 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 1
>2014-08-04 11:13:59.449 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:14:27.170 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:14:27.365 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13391
>2014-08-04 11:14:27.366 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 40
>2014-08-04 11:14:27.366 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 1
>2014-08-04 11:14:27.397 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:14:27.720 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:14:27.860 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13391
>2014-08-04 11:14:27.860 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 40
>2014-08-04 11:14:27.860 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 1
>2014-08-04 11:14:27.890 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:14:59.477 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:14:59.627 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13391
>2014-08-04 11:14:59.628 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 40
>2014-08-04 11:14:59.628 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 1
>2014-08-04 11:14:59.651 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:15:14.169 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:15:14.323 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13391
>2014-08-04 11:15:14.324 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 40
>2014-08-04 11:15:14.324 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 1
>2014-08-04 11:15:14.349 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:15:14.411 16648 WARNING nova.compute.manager [-] Found 1 in the database and 0 on the hypervisor.
>2014-08-04 11:15:59.680 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:15:59.825 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13391
>2014-08-04 11:15:59.826 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 40
>2014-08-04 11:15:59.826 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 1
>2014-08-04 11:15:59.854 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:17:00.094 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:17:00.248 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13391
>2014-08-04 11:17:00.248 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 40
>2014-08-04 11:17:00.248 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 1
>2014-08-04 11:17:00.274 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:18:00.522 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:18:00.667 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13391
>2014-08-04 11:18:00.667 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 40
>2014-08-04 11:18:00.667 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 1
>2014-08-04 11:18:00.693 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:19:00.956 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:19:01.108 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13391
>2014-08-04 11:19:01.108 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 40
>2014-08-04 11:19:01.108 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 1
>2014-08-04 11:19:01.136 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:19:28.203 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:19:28.368 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13391
>2014-08-04 11:19:28.368 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 40
>2014-08-04 11:19:28.368 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 1
>2014-08-04 11:19:28.394 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:20:01.164 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:20:01.310 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13391
>2014-08-04 11:20:01.310 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 40
>2014-08-04 11:20:01.310 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 1
>2014-08-04 11:20:01.339 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:21:01.793 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:21:01.968 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13391
>2014-08-04 11:21:01.969 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 40
>2014-08-04 11:21:01.969 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 1
>2014-08-04 11:21:01.998 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:22:02.250 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:22:02.478 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13391
>2014-08-04 11:22:02.478 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 40
>2014-08-04 11:22:02.478 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 1
>2014-08-04 11:22:02.513 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:23:02.757 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:23:03.070 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13391
>2014-08-04 11:23:03.071 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 40
>2014-08-04 11:23:03.071 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 1
>2014-08-04 11:23:03.138 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:23:04.057 16648 AUDIT nova.compute.manager [req-d1f8af1e-ed03-4395-9983-20140f487e0b b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: de933a87-29a6-4b22-82f4-76e1431397a5] Starting instance...
>2014-08-04 11:23:04.183 16648 AUDIT nova.compute.claims [req-d1f8af1e-ed03-4395-9983-20140f487e0b b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: de933a87-29a6-4b22-82f4-76e1431397a5] Attempting claim: memory 2 MB, disk 20 GB, VCPUs 1
>2014-08-04 11:23:04.183 16648 AUDIT nova.compute.claims [req-d1f8af1e-ed03-4395-9983-20140f487e0b b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: de933a87-29a6-4b22-82f4-76e1431397a5] Total memory: 15951 MB, used: 2560.00 MB
>2014-08-04 11:23:04.183 16648 AUDIT nova.compute.claims [req-d1f8af1e-ed03-4395-9983-20140f487e0b b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: de933a87-29a6-4b22-82f4-76e1431397a5] memory limit: 23926.50 MB, free: 21366.50 MB
>2014-08-04 11:23:04.183 16648 AUDIT nova.compute.claims [req-d1f8af1e-ed03-4395-9983-20140f487e0b b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: de933a87-29a6-4b22-82f4-76e1431397a5] Total disk: 60 GB, used: 20.00 GB
>2014-08-04 11:23:04.184 16648 AUDIT nova.compute.claims [req-d1f8af1e-ed03-4395-9983-20140f487e0b b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: de933a87-29a6-4b22-82f4-76e1431397a5] disk limit not specified, defaulting to unlimited
>2014-08-04 11:23:04.184 16648 AUDIT nova.compute.claims [req-d1f8af1e-ed03-4395-9983-20140f487e0b b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: de933a87-29a6-4b22-82f4-76e1431397a5] Total CPUs: 2 VCPUs, used: 1.00 VCPUs
>2014-08-04 11:23:04.184 16648 AUDIT nova.compute.claims [req-d1f8af1e-ed03-4395-9983-20140f487e0b b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: de933a87-29a6-4b22-82f4-76e1431397a5] CPUs limit: 32.00 VCPUs, free: 31.00 VCPUs
>2014-08-04 11:23:04.184 16648 AUDIT nova.compute.claims [req-d1f8af1e-ed03-4395-9983-20140f487e0b b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: de933a87-29a6-4b22-82f4-76e1431397a5] Claim successful
>2014-08-04 11:23:04.972 16648 INFO nova.virt.libvirt.driver [req-d1f8af1e-ed03-4395-9983-20140f487e0b b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: de933a87-29a6-4b22-82f4-76e1431397a5] Creating image
>2014-08-04 11:23:32.452 16648 ERROR nova.compute.manager [req-d1f8af1e-ed03-4395-9983-20140f487e0b b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: de933a87-29a6-4b22-82f4-76e1431397a5] Instance failed to spawn
>2014-08-04 11:23:32.452 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5] Traceback (most recent call last):
>2014-08-04 11:23:32.452 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]   File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 1714, in _spawn
>2014-08-04 11:23:32.452 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]     block_device_info)
>2014-08-04 11:23:32.452 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/driver.py", line 2282, in spawn
>2014-08-04 11:23:32.452 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]     admin_pass=admin_password)
>2014-08-04 11:23:32.452 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/driver.py", line 2651, in _create_image
>2014-08-04 11:23:32.452 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]     project_id=instance['project_id'])
>2014-08-04 11:23:32.452 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/imagebackend.py", line 192, in cache
>2014-08-04 11:23:32.452 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]     *args, **kwargs)
>2014-08-04 11:23:32.452 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/imagebackend.py", line 388, in create_image
>2014-08-04 11:23:32.452 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]     prepare_template(target=base, max_size=size, *args, **kwargs)
>2014-08-04 11:23:32.452 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]   File "/usr/lib/python2.6/site-packages/nova/openstack/common/lockutils.py", line 249, in inner
>2014-08-04 11:23:32.452 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]     return f(*args, **kwargs)
>2014-08-04 11:23:32.452 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/imagebackend.py", line 182, in fetch_func_sync
>2014-08-04 11:23:32.452 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]     fetch_func(target=target, *args, **kwargs)
>2014-08-04 11:23:32.452 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/utils.py", line 653, in fetch_image
>2014-08-04 11:23:32.452 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]     max_size=max_size)
>2014-08-04 11:23:32.452 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]   File "/usr/lib/python2.6/site-packages/nova/virt/images.py", line 81, in fetch_to_raw
>2014-08-04 11:23:32.452 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]     data = qemu_img_info(path_tmp)
>2014-08-04 11:23:32.452 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]   File "/usr/lib/python2.6/site-packages/nova/virt/images.py", line 54, in qemu_img_info
>2014-08-04 11:23:32.452 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]     'qemu-img', 'info', path)
>2014-08-04 11:23:32.452 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]   File "/usr/lib/python2.6/site-packages/nova/utils.py", line 165, in execute
>2014-08-04 11:23:32.452 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]     return processutils.execute(*cmd, **kwargs)
>2014-08-04 11:23:32.452 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]   File "/usr/lib/python2.6/site-packages/nova/openstack/common/processutils.py", line 193, in execute
>2014-08-04 11:23:32.452 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]     cmd=' '.join(cmd))
>2014-08-04 11:23:32.452 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5] ProcessExecutionError: Unexpected error while running command.
>2014-08-04 11:23:32.452 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5] Command: env LC_ALL=C LANG=C qemu-img info /var/lib/nova/instances/_base/759fd7c54b5726bb3e060daeb676e15f44be97f2.part
>2014-08-04 11:23:32.452 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5] Exit code: 1
>2014-08-04 11:23:32.452 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5] Stdout: ''
>2014-08-04 11:23:32.452 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5] Stderr: "Could not open '/var/lib/nova/instances/_base/759fd7c54b5726bb3e060daeb676e15f44be97f2.part': Operation not permitted\n"
>2014-08-04 11:23:32.452 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5] 
>2014-08-04 11:23:32.458 16648 WARNING nova.openstack.common.loopingcall [-] task run outlasted interval by 4.125129 sec
>2014-08-04 11:23:34.012 16648 AUDIT nova.compute.manager [req-d1f8af1e-ed03-4395-9983-20140f487e0b b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: de933a87-29a6-4b22-82f4-76e1431397a5] Terminating instance
>2014-08-04 11:23:34.049 16648 ERROR nova.virt.libvirt.driver [-] [instance: de933a87-29a6-4b22-82f4-76e1431397a5] During wait destroy, instance disappeared.
>2014-08-04 11:23:34.323 16648 INFO nova.virt.libvirt.driver [req-d1f8af1e-ed03-4395-9983-20140f487e0b b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: de933a87-29a6-4b22-82f4-76e1431397a5] Deleting instance files /var/lib/nova/instances/de933a87-29a6-4b22-82f4-76e1431397a5
>2014-08-04 11:23:34.324 16648 INFO nova.virt.libvirt.driver [req-d1f8af1e-ed03-4395-9983-20140f487e0b b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: de933a87-29a6-4b22-82f4-76e1431397a5] Deletion of /var/lib/nova/instances/de933a87-29a6-4b22-82f4-76e1431397a5 complete
>2014-08-04 11:23:34.967 16648 ERROR nova.compute.manager [req-d1f8af1e-ed03-4395-9983-20140f487e0b b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: de933a87-29a6-4b22-82f4-76e1431397a5] Error: Unexpected error while running command.
>Command: env LC_ALL=C LANG=C qemu-img info /var/lib/nova/instances/_base/759fd7c54b5726bb3e060daeb676e15f44be97f2.part
>Exit code: 1
>Stdout: ''
>Stderr: "Could not open '/var/lib/nova/instances/_base/759fd7c54b5726bb3e060daeb676e15f44be97f2.part': Operation not permitted\n"
>2014-08-04 11:23:34.967 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5] Traceback (most recent call last):
>2014-08-04 11:23:34.967 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]   File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 1305, in _build_instance
>2014-08-04 11:23:34.967 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]     set_access_ip=set_access_ip)
>2014-08-04 11:23:34.967 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]   File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 393, in decorated_function
>2014-08-04 11:23:34.967 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]     return function(self, context, *args, **kwargs)
>2014-08-04 11:23:34.967 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]   File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 1717, in _spawn
>2014-08-04 11:23:34.967 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]     LOG.exception(_('Instance failed to spawn'), instance=instance)
>2014-08-04 11:23:34.967 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]   File "/usr/lib/python2.6/site-packages/nova/openstack/common/excutils.py", line 68, in __exit__
>2014-08-04 11:23:34.967 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]     six.reraise(self.type_, self.value, self.tb)
>2014-08-04 11:23:34.967 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]   File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 1714, in _spawn
>2014-08-04 11:23:34.967 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]     block_device_info)
>2014-08-04 11:23:34.967 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/driver.py", line 2282, in spawn
>2014-08-04 11:23:34.967 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]     admin_pass=admin_password)
>2014-08-04 11:23:34.967 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/driver.py", line 2651, in _create_image
>2014-08-04 11:23:34.967 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]     project_id=instance['project_id'])
>2014-08-04 11:23:34.967 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/imagebackend.py", line 192, in cache
>2014-08-04 11:23:34.967 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]     *args, **kwargs)
>2014-08-04 11:23:34.967 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/imagebackend.py", line 388, in create_image
>2014-08-04 11:23:34.967 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]     prepare_template(target=base, max_size=size, *args, **kwargs)
>2014-08-04 11:23:34.967 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]   File "/usr/lib/python2.6/site-packages/nova/openstack/common/lockutils.py", line 249, in inner
>2014-08-04 11:23:34.967 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]     return f(*args, **kwargs)
>2014-08-04 11:23:34.967 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/imagebackend.py", line 182, in fetch_func_sync
>2014-08-04 11:23:34.967 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]     fetch_func(target=target, *args, **kwargs)
>2014-08-04 11:23:34.967 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/utils.py", line 653, in fetch_image
>2014-08-04 11:23:34.967 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]     max_size=max_size)
>2014-08-04 11:23:34.967 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]   File "/usr/lib/python2.6/site-packages/nova/virt/images.py", line 81, in fetch_to_raw
>2014-08-04 11:23:34.967 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]     data = qemu_img_info(path_tmp)
>2014-08-04 11:23:34.967 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]   File "/usr/lib/python2.6/site-packages/nova/virt/images.py", line 54, in qemu_img_info
>2014-08-04 11:23:34.967 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]     'qemu-img', 'info', path)
>2014-08-04 11:23:34.967 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]   File "/usr/lib/python2.6/site-packages/nova/utils.py", line 165, in execute
>2014-08-04 11:23:34.967 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]     return processutils.execute(*cmd, **kwargs)
>2014-08-04 11:23:34.967 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]   File "/usr/lib/python2.6/site-packages/nova/openstack/common/processutils.py", line 193, in execute
>2014-08-04 11:23:34.967 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5]     cmd=' '.join(cmd))
>2014-08-04 11:23:34.967 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5] ProcessExecutionError: Unexpected error while running command.
>2014-08-04 11:23:34.967 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5] Command: env LC_ALL=C LANG=C qemu-img info /var/lib/nova/instances/_base/759fd7c54b5726bb3e060daeb676e15f44be97f2.part
>2014-08-04 11:23:34.967 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5] Exit code: 1
>2014-08-04 11:23:34.967 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5] Stdout: ''
>2014-08-04 11:23:34.967 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5] Stderr: "Could not open '/var/lib/nova/instances/_base/759fd7c54b5726bb3e060daeb676e15f44be97f2.part': Operation not permitted\n"
>2014-08-04 11:23:34.967 16648 TRACE nova.compute.manager [instance: de933a87-29a6-4b22-82f4-76e1431397a5] 
>2014-08-04 11:24:03.630 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:24:03.789 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13389
>2014-08-04 11:24:03.789 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 20
>2014-08-04 11:24:03.789 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 0
>2014-08-04 11:24:03.822 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:24:27.172 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:24:27.318 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13389
>2014-08-04 11:24:27.318 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 20
>2014-08-04 11:24:27.318 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 0
>2014-08-04 11:24:27.350 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:24:28.452 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:24:28.605 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13389
>2014-08-04 11:24:28.610 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 20
>2014-08-04 11:24:28.611 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 0
>2014-08-04 11:24:28.639 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:25:03.851 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:25:04.008 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13389
>2014-08-04 11:25:04.008 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 20
>2014-08-04 11:25:04.008 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 0
>2014-08-04 11:25:04.044 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:25:14.612 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:25:14.759 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13389
>2014-08-04 11:25:14.760 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 20
>2014-08-04 11:25:14.760 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 0
>2014-08-04 11:25:14.787 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:25:14.855 16648 WARNING nova.compute.manager [-] Found 2 in the database and 0 on the hypervisor.
>2014-08-04 11:26:04.072 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:26:04.226 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13389
>2014-08-04 11:26:04.226 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 20
>2014-08-04 11:26:04.227 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 0
>2014-08-04 11:26:04.252 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:27:04.501 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:27:04.655 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13389
>2014-08-04 11:27:04.655 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 20
>2014-08-04 11:27:04.655 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 0
>2014-08-04 11:27:04.684 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:28:04.839 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:28:04.991 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13389
>2014-08-04 11:28:04.992 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 20
>2014-08-04 11:28:04.992 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 0
>2014-08-04 11:28:05.019 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:29:05.423 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:29:05.574 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13389
>2014-08-04 11:29:05.575 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 20
>2014-08-04 11:29:05.575 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 0
>2014-08-04 11:29:05.600 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:29:28.799 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:29:28.951 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13389
>2014-08-04 11:29:28.952 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 20
>2014-08-04 11:29:28.952 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 0
>2014-08-04 11:29:28.984 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:30:05.635 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:30:05.931 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13389
>2014-08-04 11:30:05.932 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 20
>2014-08-04 11:30:05.932 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 0
>2014-08-04 11:30:05.960 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:31:06.213 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:31:06.363 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13389
>2014-08-04 11:31:06.364 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 20
>2014-08-04 11:31:06.364 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 0
>2014-08-04 11:31:06.391 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:32:06.554 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:32:06.709 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13389
>2014-08-04 11:32:06.709 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 20
>2014-08-04 11:32:06.709 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 0
>2014-08-04 11:32:06.732 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:33:06.995 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:33:07.139 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13389
>2014-08-04 11:33:07.140 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 20
>2014-08-04 11:33:07.140 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 0
>2014-08-04 11:33:07.163 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:34:07.339 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:34:07.494 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13389
>2014-08-04 11:34:07.494 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 20
>2014-08-04 11:34:07.494 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 0
>2014-08-04 11:34:07.547 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:34:27.605 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:34:27.755 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13389
>2014-08-04 11:34:27.755 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 20
>2014-08-04 11:34:27.755 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 0
>2014-08-04 11:34:27.787 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:34:29.013 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:34:29.158 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13389
>2014-08-04 11:34:29.158 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 20
>2014-08-04 11:34:29.158 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 0
>2014-08-04 11:34:29.184 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:35:07.574 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:35:07.735 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13389
>2014-08-04 11:35:07.735 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 20
>2014-08-04 11:35:07.735 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 0
>2014-08-04 11:35:07.768 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:35:14.963 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:35:15.108 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13389
>2014-08-04 11:35:15.108 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 20
>2014-08-04 11:35:15.108 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 0
>2014-08-04 11:35:15.134 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:35:15.202 16648 WARNING nova.compute.manager [-] Found 2 in the database and 0 on the hypervisor.
>2014-08-04 11:36:07.797 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:36:07.945 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13389
>2014-08-04 11:36:07.946 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 20
>2014-08-04 11:36:07.946 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 0
>2014-08-04 11:36:07.983 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:37:08.270 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:37:08.425 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13389
>2014-08-04 11:37:08.425 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 20
>2014-08-04 11:37:08.425 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 0
>2014-08-04 11:37:08.456 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:37:43.358 16648 AUDIT nova.compute.manager [req-13e6fd4c-1109-4d40-a614-591841ac69ed b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: de933a87-29a6-4b22-82f4-76e1431397a5] Terminating instance
>2014-08-04 11:37:43.421 16648 ERROR nova.virt.libvirt.driver [-] [instance: de933a87-29a6-4b22-82f4-76e1431397a5] During wait destroy, instance disappeared.
>2014-08-04 11:37:43.713 16648 INFO nova.virt.libvirt.driver [req-13e6fd4c-1109-4d40-a614-591841ac69ed b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: de933a87-29a6-4b22-82f4-76e1431397a5] Deletion of /var/lib/nova/instances/de933a87-29a6-4b22-82f4-76e1431397a5 complete
>2014-08-04 11:37:44.529 16648 AUDIT nova.compute.manager [req-d1a65608-80df-4a5f-874f-a69d54139b05 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7] Terminating instance
>2014-08-04 11:37:44.560 16648 ERROR nova.virt.libvirt.driver [-] [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7] During wait destroy, instance disappeared.
>2014-08-04 11:37:45.203 16648 INFO nova.virt.libvirt.driver [req-d1a65608-80df-4a5f-874f-a69d54139b05 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: 82898e5f-6586-46cf-a4f8-9f24268c99a7] Deletion of /var/lib/nova/instances/82898e5f-6586-46cf-a4f8-9f24268c99a7 complete
>2014-08-04 11:38:08.610 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:38:08.938 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 15439
>2014-08-04 11:38:08.939 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 60
>2014-08-04 11:38:08.939 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2014-08-04 11:38:08.967 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:38:30.586 16648 AUDIT nova.compute.manager [req-4f7310a9-c532-4c0f-ac04-eb83dee4e3d5 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: fbbca624-8aac-42ad-a096-679228f4ae92] Starting instance...
>2014-08-04 11:38:30.731 16648 AUDIT nova.compute.claims [req-4f7310a9-c532-4c0f-ac04-eb83dee4e3d5 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: fbbca624-8aac-42ad-a096-679228f4ae92] Attempting claim: memory 2 MB, disk 20 GB, VCPUs 1
>2014-08-04 11:38:30.731 16648 AUDIT nova.compute.claims [req-4f7310a9-c532-4c0f-ac04-eb83dee4e3d5 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: fbbca624-8aac-42ad-a096-679228f4ae92] Total memory: 15951 MB, used: 512.00 MB
>2014-08-04 11:38:30.732 16648 AUDIT nova.compute.claims [req-4f7310a9-c532-4c0f-ac04-eb83dee4e3d5 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: fbbca624-8aac-42ad-a096-679228f4ae92] memory limit: 23926.50 MB, free: 23414.50 MB
>2014-08-04 11:38:30.732 16648 AUDIT nova.compute.claims [req-4f7310a9-c532-4c0f-ac04-eb83dee4e3d5 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: fbbca624-8aac-42ad-a096-679228f4ae92] Total disk: 60 GB, used: 0.00 GB
>2014-08-04 11:38:30.732 16648 AUDIT nova.compute.claims [req-4f7310a9-c532-4c0f-ac04-eb83dee4e3d5 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: fbbca624-8aac-42ad-a096-679228f4ae92] disk limit not specified, defaulting to unlimited
>2014-08-04 11:38:30.732 16648 AUDIT nova.compute.claims [req-4f7310a9-c532-4c0f-ac04-eb83dee4e3d5 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: fbbca624-8aac-42ad-a096-679228f4ae92] Total CPUs: 2 VCPUs, used: 0.00 VCPUs
>2014-08-04 11:38:30.733 16648 AUDIT nova.compute.claims [req-4f7310a9-c532-4c0f-ac04-eb83dee4e3d5 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: fbbca624-8aac-42ad-a096-679228f4ae92] CPUs limit: 32.00 VCPUs, free: 32.00 VCPUs
>2014-08-04 11:38:30.733 16648 AUDIT nova.compute.claims [req-4f7310a9-c532-4c0f-ac04-eb83dee4e3d5 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: fbbca624-8aac-42ad-a096-679228f4ae92] Claim successful
>2014-08-04 11:38:31.498 16648 INFO nova.virt.libvirt.driver [req-4f7310a9-c532-4c0f-ac04-eb83dee4e3d5 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: fbbca624-8aac-42ad-a096-679228f4ae92] Creating image
>2014-08-04 11:38:59.582 16648 WARNING nova.virt.disk.vfs.guestfs [req-4f7310a9-c532-4c0f-ac04-eb83dee4e3d5 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] Failed to close augeas aug_close: do_aug_close: you must call 'aug-init' first to initialize Augeas
>2014-08-04 11:39:07.364 16648 INFO nova.compute.manager [-] Lifecycle event 0 on VM fbbca624-8aac-42ad-a096-679228f4ae92
>2014-08-04 11:39:07.507 16648 INFO nova.compute.manager [req-23cc2e30-9b70-41cf-922f-6be7aa039851 None None] [instance: fbbca624-8aac-42ad-a096-679228f4ae92] During sync_power_state the instance has a pending task. Skip.
>2014-08-04 11:39:07.507 16648 INFO nova.compute.manager [-] Lifecycle event 2 on VM fbbca624-8aac-42ad-a096-679228f4ae92
>2014-08-04 11:39:07.630 16648 INFO nova.compute.manager [-] [instance: fbbca624-8aac-42ad-a096-679228f4ae92] During sync_power_state the instance has a pending task. Skip.
>2014-08-04 11:39:09.016 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:39:09.617 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 15437
>2014-08-04 11:39:09.618 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 40
>2014-08-04 11:39:09.618 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 1
>2014-08-04 11:39:09.645 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:39:11.795 16648 INFO nova.compute.manager [-] Lifecycle event 3 on VM fbbca624-8aac-42ad-a096-679228f4ae92
>2014-08-04 11:39:11.923 16648 INFO nova.virt.libvirt.driver [-] [instance: fbbca624-8aac-42ad-a096-679228f4ae92] Instance spawned successfully.
>2014-08-04 11:39:12.013 16648 INFO nova.compute.manager [-] [instance: fbbca624-8aac-42ad-a096-679228f4ae92] During sync_power_state the instance has a pending task. Skip.
>2014-08-04 11:39:12.014 16648 INFO nova.compute.manager [-] Lifecycle event 3 on VM fbbca624-8aac-42ad-a096-679228f4ae92
>2014-08-04 11:39:12.319 16648 INFO nova.compute.manager [-] [instance: fbbca624-8aac-42ad-a096-679228f4ae92] During sync_power_state the instance has a pending task. Skip.
>2014-08-04 11:39:29.247 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:39:30.056 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 15437
>2014-08-04 11:39:30.057 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 40
>2014-08-04 11:39:30.057 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 1
>2014-08-04 11:39:30.103 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:40:09.672 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:40:10.349 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 15437
>2014-08-04 11:40:10.350 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 40
>2014-08-04 11:40:10.350 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 1
>2014-08-04 11:40:10.384 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:40:58.861 16648 AUDIT nova.compute.manager [req-c121256f-4cdd-4770-a10c-3d3862e9c140 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: fbbca624-8aac-42ad-a096-679228f4ae92] Terminating instance
>2014-08-04 11:40:59.802 16648 INFO nova.compute.manager [-] Lifecycle event 1 on VM fbbca624-8aac-42ad-a096-679228f4ae92
>2014-08-04 11:40:59.870 16648 INFO nova.virt.libvirt.driver [-] [instance: fbbca624-8aac-42ad-a096-679228f4ae92] Instance destroyed successfully.
>2014-08-04 11:41:00.024 16648 ERROR nova.virt.driver [-] Exception dispatching event <nova.virt.event.LifecycleEvent object at 0x3b46210>: Info cache for instance fbbca624-8aac-42ad-a096-679228f4ae92 could not be found.
>Traceback (most recent call last):
>
>  File "/usr/lib/python2.6/site-packages/nova/conductor/manager.py", line 597, in _object_dispatch
>    return getattr(target, method)(context, *args, **kwargs)
>
>  File "/usr/lib/python2.6/site-packages/nova/objects/base.py", line 151, in wrapper
>    return fn(self, ctxt, *args, **kwargs)
>
>  File "/usr/lib/python2.6/site-packages/nova/objects/instance.py", line 500, in refresh
>    self.info_cache.refresh()
>
>  File "/usr/lib/python2.6/site-packages/nova/objects/base.py", line 151, in wrapper
>    return fn(self, ctxt, *args, **kwargs)
>
>  File "/usr/lib/python2.6/site-packages/nova/objects/instance_info_cache.py", line 103, in refresh
>    self.instance_uuid)
>
>  File "/usr/lib/python2.6/site-packages/nova/objects/base.py", line 112, in wrapper
>    result = fn(cls, context, *args, **kwargs)
>
>  File "/usr/lib/python2.6/site-packages/nova/objects/instance_info_cache.py", line 70, in get_by_instance_uuid
>    instance_uuid=instance_uuid)
>
>InstanceInfoCacheNotFound: Info cache for instance fbbca624-8aac-42ad-a096-679228f4ae92 could not be found.
>
>2014-08-04 11:41:00.757 16648 INFO nova.virt.libvirt.driver [req-c121256f-4cdd-4770-a10c-3d3862e9c140 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: fbbca624-8aac-42ad-a096-679228f4ae92] Deleting instance files /var/lib/nova/instances/fbbca624-8aac-42ad-a096-679228f4ae92
>2014-08-04 11:41:00.758 16648 INFO nova.virt.libvirt.driver [req-c121256f-4cdd-4770-a10c-3d3862e9c140 b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: fbbca624-8aac-42ad-a096-679228f4ae92] Deletion of /var/lib/nova/instances/fbbca624-8aac-42ad-a096-679228f4ae92 complete
>2014-08-04 11:41:10.774 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:41:11.012 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 15439
>2014-08-04 11:41:11.013 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 60
>2014-08-04 11:41:11.013 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2014-08-04 11:41:11.048 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:41:51.004 16648 AUDIT nova.compute.manager [req-9b5a8aa8-3bad-47bc-b1d4-4e732166810d b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: 75694195-1568-4234-810e-b60d4e798486] Starting instance...
>2014-08-04 11:41:51.124 16648 AUDIT nova.compute.claims [req-9b5a8aa8-3bad-47bc-b1d4-4e732166810d b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: 75694195-1568-4234-810e-b60d4e798486] Attempting claim: memory 2048 MB, disk 15 GB, VCPUs 1
>2014-08-04 11:41:51.124 16648 AUDIT nova.compute.claims [req-9b5a8aa8-3bad-47bc-b1d4-4e732166810d b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: 75694195-1568-4234-810e-b60d4e798486] Total memory: 15951 MB, used: 512.00 MB
>2014-08-04 11:41:51.124 16648 AUDIT nova.compute.claims [req-9b5a8aa8-3bad-47bc-b1d4-4e732166810d b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: 75694195-1568-4234-810e-b60d4e798486] memory limit: 23926.50 MB, free: 23414.50 MB
>2014-08-04 11:41:51.125 16648 AUDIT nova.compute.claims [req-9b5a8aa8-3bad-47bc-b1d4-4e732166810d b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: 75694195-1568-4234-810e-b60d4e798486] Total disk: 60 GB, used: 0.00 GB
>2014-08-04 11:41:51.125 16648 AUDIT nova.compute.claims [req-9b5a8aa8-3bad-47bc-b1d4-4e732166810d b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: 75694195-1568-4234-810e-b60d4e798486] disk limit not specified, defaulting to unlimited
>2014-08-04 11:41:51.125 16648 AUDIT nova.compute.claims [req-9b5a8aa8-3bad-47bc-b1d4-4e732166810d b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: 75694195-1568-4234-810e-b60d4e798486] Total CPUs: 2 VCPUs, used: 0.00 VCPUs
>2014-08-04 11:41:51.125 16648 AUDIT nova.compute.claims [req-9b5a8aa8-3bad-47bc-b1d4-4e732166810d b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: 75694195-1568-4234-810e-b60d4e798486] CPUs limit: 32.00 VCPUs, free: 32.00 VCPUs
>2014-08-04 11:41:51.125 16648 AUDIT nova.compute.claims [req-9b5a8aa8-3bad-47bc-b1d4-4e732166810d b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: 75694195-1568-4234-810e-b60d4e798486] Claim successful
>2014-08-04 11:41:52.051 16648 INFO nova.virt.libvirt.driver [req-9b5a8aa8-3bad-47bc-b1d4-4e732166810d b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: 75694195-1568-4234-810e-b60d4e798486] Creating image
>2014-08-04 11:42:04.911 16648 ERROR nova.compute.manager [req-9b5a8aa8-3bad-47bc-b1d4-4e732166810d b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: 75694195-1568-4234-810e-b60d4e798486] Instance failed to spawn
>2014-08-04 11:42:04.911 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486] Traceback (most recent call last):
>2014-08-04 11:42:04.911 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]   File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 1714, in _spawn
>2014-08-04 11:42:04.911 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]     block_device_info)
>2014-08-04 11:42:04.911 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/driver.py", line 2282, in spawn
>2014-08-04 11:42:04.911 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]     admin_pass=admin_password)
>2014-08-04 11:42:04.911 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/driver.py", line 2651, in _create_image
>2014-08-04 11:42:04.911 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]     project_id=instance['project_id'])
>2014-08-04 11:42:04.911 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/imagebackend.py", line 192, in cache
>2014-08-04 11:42:04.911 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]     *args, **kwargs)
>2014-08-04 11:42:04.911 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/imagebackend.py", line 388, in create_image
>2014-08-04 11:42:04.911 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]     prepare_template(target=base, max_size=size, *args, **kwargs)
>2014-08-04 11:42:04.911 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]   File "/usr/lib/python2.6/site-packages/nova/openstack/common/lockutils.py", line 249, in inner
>2014-08-04 11:42:04.911 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]     return f(*args, **kwargs)
>2014-08-04 11:42:04.911 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/imagebackend.py", line 182, in fetch_func_sync
>2014-08-04 11:42:04.911 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]     fetch_func(target=target, *args, **kwargs)
>2014-08-04 11:42:04.911 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/utils.py", line 653, in fetch_image
>2014-08-04 11:42:04.911 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]     max_size=max_size)
>2014-08-04 11:42:04.911 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]   File "/usr/lib/python2.6/site-packages/nova/virt/images.py", line 81, in fetch_to_raw
>2014-08-04 11:42:04.911 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]     data = qemu_img_info(path_tmp)
>2014-08-04 11:42:04.911 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]   File "/usr/lib/python2.6/site-packages/nova/virt/images.py", line 54, in qemu_img_info
>2014-08-04 11:42:04.911 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]     'qemu-img', 'info', path)
>2014-08-04 11:42:04.911 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]   File "/usr/lib/python2.6/site-packages/nova/utils.py", line 165, in execute
>2014-08-04 11:42:04.911 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]     return processutils.execute(*cmd, **kwargs)
>2014-08-04 11:42:04.911 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]   File "/usr/lib/python2.6/site-packages/nova/openstack/common/processutils.py", line 193, in execute
>2014-08-04 11:42:04.911 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]     cmd=' '.join(cmd))
>2014-08-04 11:42:04.911 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486] ProcessExecutionError: Unexpected error while running command.
>2014-08-04 11:42:04.911 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486] Command: env LC_ALL=C LANG=C qemu-img info /var/lib/nova/instances/_base/759fd7c54b5726bb3e060daeb676e15f44be97f2.part
>2014-08-04 11:42:04.911 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486] Exit code: 1
>2014-08-04 11:42:04.911 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486] Stdout: ''
>2014-08-04 11:42:04.911 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486] Stderr: "Could not open '/var/lib/nova/instances/_base/759fd7c54b5726bb3e060daeb676e15f44be97f2.part': Operation not permitted\n"
>2014-08-04 11:42:04.911 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486] 
>2014-08-04 11:42:24.331 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:42:24.462 16648 AUDIT nova.compute.manager [req-9b5a8aa8-3bad-47bc-b1d4-4e732166810d b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: 75694195-1568-4234-810e-b60d4e798486] Terminating instance
>2014-08-04 11:42:24.517 16648 ERROR nova.virt.libvirt.driver [-] [instance: 75694195-1568-4234-810e-b60d4e798486] During wait destroy, instance disappeared.
>2014-08-04 11:42:24.848 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13391
>2014-08-04 11:42:24.848 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 45
>2014-08-04 11:42:24.848 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 1
>2014-08-04 11:42:24.887 16648 INFO nova.virt.libvirt.driver [req-9b5a8aa8-3bad-47bc-b1d4-4e732166810d b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: 75694195-1568-4234-810e-b60d4e798486] Deleting instance files /var/lib/nova/instances/75694195-1568-4234-810e-b60d4e798486
>2014-08-04 11:42:24.887 16648 INFO nova.virt.libvirt.driver [req-9b5a8aa8-3bad-47bc-b1d4-4e732166810d b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: 75694195-1568-4234-810e-b60d4e798486] Deletion of /var/lib/nova/instances/75694195-1568-4234-810e-b60d4e798486 complete
>2014-08-04 11:42:24.899 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:42:25.318 16648 ERROR nova.compute.manager [req-9b5a8aa8-3bad-47bc-b1d4-4e732166810d b643e376b9f746c89744b218b3b3288e 12f4debd3b314201bbab93341f94d67e] [instance: 75694195-1568-4234-810e-b60d4e798486] Error: Unexpected error while running command.
>Command: env LC_ALL=C LANG=C qemu-img info /var/lib/nova/instances/_base/759fd7c54b5726bb3e060daeb676e15f44be97f2.part
>Exit code: 1
>Stdout: ''
>Stderr: "Could not open '/var/lib/nova/instances/_base/759fd7c54b5726bb3e060daeb676e15f44be97f2.part': Operation not permitted\n"
>2014-08-04 11:42:25.318 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486] Traceback (most recent call last):
>2014-08-04 11:42:25.318 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]   File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 1305, in _build_instance
>2014-08-04 11:42:25.318 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]     set_access_ip=set_access_ip)
>2014-08-04 11:42:25.318 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]   File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 393, in decorated_function
>2014-08-04 11:42:25.318 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]     return function(self, context, *args, **kwargs)
>2014-08-04 11:42:25.318 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]   File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 1717, in _spawn
>2014-08-04 11:42:25.318 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]     LOG.exception(_('Instance failed to spawn'), instance=instance)
>2014-08-04 11:42:25.318 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]   File "/usr/lib/python2.6/site-packages/nova/openstack/common/excutils.py", line 68, in __exit__
>2014-08-04 11:42:25.318 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]     six.reraise(self.type_, self.value, self.tb)
>2014-08-04 11:42:25.318 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]   File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 1714, in _spawn
>2014-08-04 11:42:25.318 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]     block_device_info)
>2014-08-04 11:42:25.318 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/driver.py", line 2282, in spawn
>2014-08-04 11:42:25.318 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]     admin_pass=admin_password)
>2014-08-04 11:42:25.318 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/driver.py", line 2651, in _create_image
>2014-08-04 11:42:25.318 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]     project_id=instance['project_id'])
>2014-08-04 11:42:25.318 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/imagebackend.py", line 192, in cache
>2014-08-04 11:42:25.318 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]     *args, **kwargs)
>2014-08-04 11:42:25.318 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/imagebackend.py", line 388, in create_image
>2014-08-04 11:42:25.318 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]     prepare_template(target=base, max_size=size, *args, **kwargs)
>2014-08-04 11:42:25.318 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]   File "/usr/lib/python2.6/site-packages/nova/openstack/common/lockutils.py", line 249, in inner
>2014-08-04 11:42:25.318 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]     return f(*args, **kwargs)
>2014-08-04 11:42:25.318 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/imagebackend.py", line 182, in fetch_func_sync
>2014-08-04 11:42:25.318 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]     fetch_func(target=target, *args, **kwargs)
>2014-08-04 11:42:25.318 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/utils.py", line 653, in fetch_image
>2014-08-04 11:42:25.318 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]     max_size=max_size)
>2014-08-04 11:42:25.318 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]   File "/usr/lib/python2.6/site-packages/nova/virt/images.py", line 81, in fetch_to_raw
>2014-08-04 11:42:25.318 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]     data = qemu_img_info(path_tmp)
>2014-08-04 11:42:25.318 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]   File "/usr/lib/python2.6/site-packages/nova/virt/images.py", line 54, in qemu_img_info
>2014-08-04 11:42:25.318 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]     'qemu-img', 'info', path)
>2014-08-04 11:42:25.318 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]   File "/usr/lib/python2.6/site-packages/nova/utils.py", line 165, in execute
>2014-08-04 11:42:25.318 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]     return processutils.execute(*cmd, **kwargs)
>2014-08-04 11:42:25.318 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]   File "/usr/lib/python2.6/site-packages/nova/openstack/common/processutils.py", line 193, in execute
>2014-08-04 11:42:25.318 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486]     cmd=' '.join(cmd))
>2014-08-04 11:42:25.318 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486] ProcessExecutionError: Unexpected error while running command.
>2014-08-04 11:42:25.318 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486] Command: env LC_ALL=C LANG=C qemu-img info /var/lib/nova/instances/_base/759fd7c54b5726bb3e060daeb676e15f44be97f2.part
>2014-08-04 11:42:25.318 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486] Exit code: 1
>2014-08-04 11:42:25.318 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486] Stdout: ''
>2014-08-04 11:42:25.318 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486] Stderr: "Could not open '/var/lib/nova/instances/_base/759fd7c54b5726bb3e060daeb676e15f44be97f2.part': Operation not permitted\n"
>2014-08-04 11:42:25.318 16648 TRACE nova.compute.manager [instance: 75694195-1568-4234-810e-b60d4e798486] 
>2014-08-04 11:43:25.044 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:43:25.203 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13391
>2014-08-04 11:43:25.204 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 45
>2014-08-04 11:43:25.204 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 1
>2014-08-04 11:43:25.234 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:44:25.482 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:44:25.639 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13391
>2014-08-04 11:44:25.639 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 45
>2014-08-04 11:44:25.639 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 1
>2014-08-04 11:44:25.667 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:44:27.160 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:44:27.301 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13391
>2014-08-04 11:44:27.302 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 45
>2014-08-04 11:44:27.302 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 1
>2014-08-04 11:44:27.329 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:44:27.815 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:44:27.955 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13391
>2014-08-04 11:44:27.955 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 45
>2014-08-04 11:44:27.955 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 1
>2014-08-04 11:44:27.989 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:44:30.147 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:44:30.335 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13391
>2014-08-04 11:44:30.336 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 45
>2014-08-04 11:44:30.336 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 1
>2014-08-04 11:44:30.384 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:45:15.189 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:45:15.330 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13391
>2014-08-04 11:45:15.330 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 45
>2014-08-04 11:45:15.330 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 1
>2014-08-04 11:45:15.361 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:45:15.422 16648 WARNING nova.compute.manager [-] Found 1 in the database and 0 on the hypervisor.
>2014-08-04 11:45:25.695 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:45:25.837 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13391
>2014-08-04 11:45:25.838 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 45
>2014-08-04 11:45:25.838 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 1
>2014-08-04 11:45:25.865 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:46:26.106 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:46:26.487 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13391
>2014-08-04 11:46:26.488 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 45
>2014-08-04 11:46:26.488 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 1
>2014-08-04 11:46:26.529 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:47:26.861 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:47:27.017 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13391
>2014-08-04 11:47:27.018 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 45
>2014-08-04 11:47:27.018 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 1
>2014-08-04 11:47:27.050 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com
>2014-08-04 11:48:27.436 16648 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
>2014-08-04 11:48:27.579 16648 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 13391
>2014-08-04 11:48:27.579 16648 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 45
>2014-08-04 11:48:27.579 16648 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 1
>2014-08-04 11:48:27.608 16648 INFO nova.compute.resource_tracker [-] Compute_service record updated for orange-vdse.qa.lab.tlv.redhat.com:orange-vdse.qa.lab.tlv.redhat.com

Actions: View

Attachments on bug 1121591: 919576 | 923803 | 924721