Login
[x]
Log in using an account from:
Fedora Account System
Red Hat Associate
Red Hat Customer
Or login using a Red Hat Bugzilla account
Forgot Password
Login:
Hide Forgot
Create an Account
Red Hat Bugzilla – Attachment 923803 Details for
Bug 1121591
Viewing console while launching a VDI instances causes error status
[?]
New
Simple Search
Advanced Search
My Links
Browse
Requests
Reports
Current State
Search
Tabular reports
Graphical reports
Duplicates
Other Reports
User Changes
Plotly Reports
Bug Status
Bug Severity
Non-Defaults
|
Product Dashboard
Help
Page Help!
Bug Writing Guidelines
What's new
Browser Support Policy
5.0.4.rh83 Release notes
FAQ
Guides index
User guide
Web Services
Contact
Legal
This site requires JavaScript to be enabled to function correctly, please enable it.
new compute log
compute.log (text/plain), 136.28 KB, created by
Tzach Shefi
on 2014-08-04 08:50:10 UTC
(
hide
)
Description:
new compute log
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
You cannot view the attachment while viewing its details because your browser does not support IFRAMEs.
View the attachment on a separate page
.
View Attachment As Raw
Actions:
View
Attachments on
bug 1121591
:
919576
| 923803 |
924721