Attachment 698513 Details for Bug 896013 – compute.log

compute.log

compute.log (text/x-log), 36.56 KB, created by Omri Hochman on 2013-02-17 11:33:33 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Omri Hochman

Created: 2013-02-17 11:33:33 UTC

Size: 36.56 KB

patch

obsolete

>2013-02-17 12:34:19 12326 INFO nova.compute.manager [-] Loading compute driver 'libvirt.LibvirtDriver'
>2013-02-17 12:34:19 12326 AUDIT nova.service [-] Starting compute node (version 2012.2.3-1.el6ost)
>2013-02-17 12:34:21 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 12:34:21 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 12:34:21 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 12:34:21 12326 INFO nova.compute.resource_tracker [-] Compute_service record created for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 12:34:21 12326 INFO nova.openstack.common.rpc.impl_qpid [-] Connected to AMQP server on 10.35.160.11:5672
>2013-02-17 12:35:19 INFO nova.compute.manager [req-1f6c810a-d167-49e6-8d46-ac096a7c5c5c None None] Updating bandwidth usage cache
>2013-02-17 12:35:19 AUDIT nova.compute.resource_tracker [req-1f6c810a-d167-49e6-8d46-ac096a7c5c5c None None] Free ram (MB): 31587
>2013-02-17 12:35:19 AUDIT nova.compute.resource_tracker [req-1f6c810a-d167-49e6-8d46-ac096a7c5c5c None None] Free disk (GB): 442
>2013-02-17 12:35:19 AUDIT nova.compute.resource_tracker [req-1f6c810a-d167-49e6-8d46-ac096a7c5c5c None None] Free VCPUS: 12
>2013-02-17 12:35:19 INFO nova.compute.resource_tracker [req-1f6c810a-d167-49e6-8d46-ac096a7c5c5c None None] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 12:35:19 INFO nova.compute.manager [req-1f6c810a-d167-49e6-8d46-ac096a7c5c5c None None] Updating host status
>2013-02-17 12:36:19 12326 INFO nova.openstack.common.rpc.impl_qpid [-] Connected to AMQP server on 10.35.160.11:5672
>2013-02-17 12:36:19 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 12:36:19 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 12:36:19 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 12:36:19 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 12:37:19 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 12:37:19 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 12:37:19 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 12:37:19 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 12:37:19 12326 INFO nova.compute.manager [-] Updating host status
>2013-02-17 12:38:20 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 12:38:20 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 12:38:20 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 12:38:20 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 12:39:20 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 12:39:20 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 12:39:20 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 12:39:20 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 12:39:20 12326 INFO nova.compute.manager [-] Updating host status
>2013-02-17 12:40:20 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 12:40:20 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 12:40:20 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 12:40:20 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 12:41:20 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 12:41:20 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 12:41:20 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 12:41:20 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 12:41:20 12326 INFO nova.compute.manager [-] Updating host status
>2013-02-17 12:42:21 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 12:42:21 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 12:42:21 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 12:42:21 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 12:43:21 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 12:43:21 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 12:43:21 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 12:43:21 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 12:43:21 12326 INFO nova.compute.manager [-] Updating host status
>2013-02-17 12:44:21 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 12:44:21 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 12:44:21 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 12:44:21 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 12:45:21 12326 INFO nova.compute.manager [-] Updating bandwidth usage cache
>2013-02-17 12:45:21 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 12:45:21 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 12:45:21 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 12:45:21 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 12:45:21 12326 INFO nova.compute.manager [-] Updating host status
>2013-02-17 12:46:21 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 12:46:21 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 12:46:21 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 12:46:22 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 12:47:22 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 12:47:22 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 12:47:22 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 12:47:22 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 12:47:22 12326 INFO nova.compute.manager [-] Updating host status
>2013-02-17 12:48:22 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 12:48:22 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 12:48:22 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 12:48:22 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 12:49:22 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 12:49:22 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 12:49:22 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 12:49:22 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 12:49:22 12326 INFO nova.compute.manager [-] Updating host status
>2013-02-17 12:50:22 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 12:50:22 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 12:50:22 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 12:50:22 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 12:51:22 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 12:51:22 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 12:51:22 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 12:51:22 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 12:51:23 12326 INFO nova.compute.manager [-] Updating host status
>2013-02-17 12:52:23 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 12:52:23 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 12:52:23 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 12:52:23 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 12:53:23 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 12:53:23 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 12:53:23 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 12:53:23 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 12:53:23 12326 INFO nova.compute.manager [-] Updating host status
>2013-02-17 12:54:24 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 12:54:24 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 12:54:24 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 12:54:24 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 12:55:24 12326 INFO nova.compute.manager [-] Updating bandwidth usage cache
>2013-02-17 12:55:24 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 12:55:24 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 12:55:24 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 12:55:24 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 12:55:24 12326 INFO nova.compute.manager [-] Updating host status
>2013-02-17 12:56:24 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 12:56:24 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 12:56:24 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 12:56:24 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 12:57:24 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 12:57:24 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 12:57:24 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 12:57:24 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 12:57:24 12326 INFO nova.compute.manager [-] Updating host status
>2013-02-17 12:58:24 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 12:58:24 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 12:58:24 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 12:58:24 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 12:59:25 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 12:59:25 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 12:59:25 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 12:59:25 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 12:59:25 12326 INFO nova.compute.manager [-] Updating host status
>2013-02-17 13:00:25 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 13:00:25 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 13:00:25 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 13:00:25 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 13:01:25 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 13:01:25 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 13:01:25 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 13:01:25 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 13:01:25 12326 INFO nova.compute.manager [-] Updating host status
>2013-02-17 13:02:26 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 13:02:26 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 13:02:26 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 13:02:26 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 13:03:26 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 13:03:26 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 13:03:26 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 13:03:26 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 13:03:26 12326 INFO nova.compute.manager [-] Updating host status
>2013-02-17 13:04:26 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 13:04:26 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 13:04:26 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 13:04:26 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 13:05:26 12326 INFO nova.compute.manager [-] Updating bandwidth usage cache
>2013-02-17 13:05:26 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 13:05:26 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 13:05:26 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 13:05:26 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 13:05:26 12326 INFO nova.compute.manager [-] Updating host status
>2013-02-17 13:06:27 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 13:06:27 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 13:06:27 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 13:06:27 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 13:07:27 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 13:07:27 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 13:07:27 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 13:07:27 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 13:07:27 12326 INFO nova.compute.manager [-] Updating host status
>2013-02-17 13:08:27 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 13:08:27 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 13:08:27 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 13:08:27 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 13:09:27 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 13:09:27 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 13:09:27 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 13:09:27 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 13:09:27 12326 INFO nova.compute.manager [-] Updating host status
>2013-02-17 13:09:52 AUDIT nova.compute.manager [req-ec94b7a8-7286-4acf-b635-34f5aafb7536 24a32f3e151142ecb99476750185faca fa0688720d58455b92d35d4052ab7f24] [instance: 8520c4d3-0ee3-4fd0-ab44-6d8dde2678a8] Starting instance...
>2013-02-17 13:09:52 AUDIT nova.compute.resource_tracker [req-ec94b7a8-7286-4acf-b635-34f5aafb7536 24a32f3e151142ecb99476750185faca fa0688720d58455b92d35d4052ab7f24] Attempting claim: memory 512 MB, disk 0 GB, VCPUs 1
>2013-02-17 13:09:52 AUDIT nova.compute.resource_tracker [req-ec94b7a8-7286-4acf-b635-34f5aafb7536 24a32f3e151142ecb99476750185faca fa0688720d58455b92d35d4052ab7f24] Total memory: 32099 MB, used: 512 MB, free: 31587 MB
>2013-02-17 13:09:52 AUDIT nova.compute.resource_tracker [req-ec94b7a8-7286-4acf-b635-34f5aafb7536 24a32f3e151142ecb99476750185faca fa0688720d58455b92d35d4052ab7f24] Memory limit: 48148 MB, free: 47636 MB
>2013-02-17 13:09:52 AUDIT nova.compute.resource_tracker [req-ec94b7a8-7286-4acf-b635-34f5aafb7536 24a32f3e151142ecb99476750185faca fa0688720d58455b92d35d4052ab7f24] Total disk: 442 GB, used: 0 GB, free: 442 GB
>2013-02-17 13:09:52 AUDIT nova.compute.resource_tracker [req-ec94b7a8-7286-4acf-b635-34f5aafb7536 24a32f3e151142ecb99476750185faca fa0688720d58455b92d35d4052ab7f24] Disk limit not specified, defaulting to unlimited
>2013-02-17 13:09:52 AUDIT nova.compute.resource_tracker [req-ec94b7a8-7286-4acf-b635-34f5aafb7536 24a32f3e151142ecb99476750185faca fa0688720d58455b92d35d4052ab7f24] Total VCPUs: 12, used: 0
>2013-02-17 13:09:52 AUDIT nova.compute.resource_tracker [req-ec94b7a8-7286-4acf-b635-34f5aafb7536 24a32f3e151142ecb99476750185faca fa0688720d58455b92d35d4052ab7f24] VCPU limit not specified, defaulting to unlimited
>2013-02-17 13:09:56 INFO nova.virt.libvirt.driver [req-ec94b7a8-7286-4acf-b635-34f5aafb7536 24a32f3e151142ecb99476750185faca fa0688720d58455b92d35d4052ab7f24] [instance: 8520c4d3-0ee3-4fd0-ab44-6d8dde2678a8] Creating image
>2013-02-17 13:09:56 INFO nova.virt.libvirt.driver [req-ec94b7a8-7286-4acf-b635-34f5aafb7536 24a32f3e151142ecb99476750185faca fa0688720d58455b92d35d4052ab7f24] [instance: 8520c4d3-0ee3-4fd0-ab44-6d8dde2678a8] Injecting key into image c96ea3c8-f034-48dc-bcae-c06f9031a803
>2013-02-17 13:10:17 WARNING nova.virt.libvirt.driver [req-ec94b7a8-7286-4acf-b635-34f5aafb7536 24a32f3e151142ecb99476750185faca fa0688720d58455b92d35d4052ab7f24] [instance: 8520c4d3-0ee3-4fd0-ab44-6d8dde2678a8] Ignoring error injecting data into image c96ea3c8-f034-48dc-bcae-c06f9031a803 (
>--
>nbd unavailable: module not loaded
>--
>Failed to mount filesystem: Unexpected error while running command.
>Command: sudo nova-rootwrap /etc/nova/rootwrap.conf guestmount --rw -a /var/lib/nova/instances/instance-00000001/disk -i /tmp/openstack-disk-mount-tmp3hTFyw
>Exit code: 1
>Stdout: ''
>Stderr: "/usr/bin/guestmount: no operating system was found on this disk\n\nIf using guestfish '-i' option, remove this option and instead\nuse the commands 'run' followed by 'list-filesystems'.\nYou can then mount filesystems you want by hand using the\n'mount' or 'mount-ro' command.\n\nIf using guestmount '-i', remove this option and choose the\nfilesystem(s) you want to see by manually adding '-m' option(s).\nUse 'virt-filesystems' to see what filesystems are available.\n\nIf using other virt tools, this disk image won't work\nwith these tools.  Use the guestfish equivalent commands\n(see the virt tool manual page).\n\nRHEL 6 notice\n-------------\nlibguestfs will return this error for Microsoft Windows guests if the\nseparate 'libguestfs-winsupport' package is not installed. If the\nguest is running Microsoft Windows, please try again after installing\n'libguestfs-winsupport'.\n")
>2013-02-17 13:10:18 INFO nova.virt.libvirt.firewall [req-ec94b7a8-7286-4acf-b635-34f5aafb7536 24a32f3e151142ecb99476750185faca fa0688720d58455b92d35d4052ab7f24] [instance: 8520c4d3-0ee3-4fd0-ab44-6d8dde2678a8] Called setup_basic_filtering in nwfilter
>2013-02-17 13:10:18 INFO nova.virt.libvirt.firewall [req-ec94b7a8-7286-4acf-b635-34f5aafb7536 24a32f3e151142ecb99476750185faca fa0688720d58455b92d35d4052ab7f24] [instance: 8520c4d3-0ee3-4fd0-ab44-6d8dde2678a8] Ensuring static filters
>2013-02-17 13:10:21 ERROR nova.compute.manager [req-ec94b7a8-7286-4acf-b635-34f5aafb7536 24a32f3e151142ecb99476750185faca fa0688720d58455b92d35d4052ab7f24] [instance: 8520c4d3-0ee3-4fd0-ab44-6d8dde2678a8] Instance failed to spawn
>2013-02-17 13:10:21 12326 TRACE nova.compute.manager [instance: 8520c4d3-0ee3-4fd0-ab44-6d8dde2678a8] Traceback (most recent call last):
>2013-02-17 13:10:21 12326 TRACE nova.compute.manager [instance: 8520c4d3-0ee3-4fd0-ab44-6d8dde2678a8]   File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 754, in _spawn
>2013-02-17 13:10:21 12326 TRACE nova.compute.manager [instance: 8520c4d3-0ee3-4fd0-ab44-6d8dde2678a8]     block_device_info)
>2013-02-17 13:10:21 12326 TRACE nova.compute.manager [instance: 8520c4d3-0ee3-4fd0-ab44-6d8dde2678a8]   File "/usr/lib/python2.6/site-packages/nova/exception.py", line 117, in wrapped
>2013-02-17 13:10:21 12326 TRACE nova.compute.manager [instance: 8520c4d3-0ee3-4fd0-ab44-6d8dde2678a8]     temp_level, payload)
>2013-02-17 13:10:21 12326 TRACE nova.compute.manager [instance: 8520c4d3-0ee3-4fd0-ab44-6d8dde2678a8]   File "/usr/lib64/python2.6/contextlib.py", line 23, in __exit__
>2013-02-17 13:10:21 12326 TRACE nova.compute.manager [instance: 8520c4d3-0ee3-4fd0-ab44-6d8dde2678a8]     self.gen.next()
>2013-02-17 13:10:21 12326 TRACE nova.compute.manager [instance: 8520c4d3-0ee3-4fd0-ab44-6d8dde2678a8]   File "/usr/lib/python2.6/site-packages/nova/exception.py", line 92, in wrapped
>2013-02-17 13:10:21 12326 TRACE nova.compute.manager [instance: 8520c4d3-0ee3-4fd0-ab44-6d8dde2678a8]     return f(*args, **kw)
>2013-02-17 13:10:21 12326 TRACE nova.compute.manager [instance: 8520c4d3-0ee3-4fd0-ab44-6d8dde2678a8]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/driver.py", line 1094, in spawn
>2013-02-17 13:10:21 12326 TRACE nova.compute.manager [instance: 8520c4d3-0ee3-4fd0-ab44-6d8dde2678a8]     block_device_info)
>2013-02-17 13:10:21 12326 TRACE nova.compute.manager [instance: 8520c4d3-0ee3-4fd0-ab44-6d8dde2678a8]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/driver.py", line 1959, in _create_domain_and_network
>2013-02-17 13:10:21 12326 TRACE nova.compute.manager [instance: 8520c4d3-0ee3-4fd0-ab44-6d8dde2678a8]     domain = self._create_domain(xml)
>2013-02-17 13:10:21 12326 TRACE nova.compute.manager [instance: 8520c4d3-0ee3-4fd0-ab44-6d8dde2678a8]   File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/driver.py", line 1938, in _create_domain
>2013-02-17 13:10:21 12326 TRACE nova.compute.manager [instance: 8520c4d3-0ee3-4fd0-ab44-6d8dde2678a8]     domain.createWithFlags(launch_flags)
>2013-02-17 13:10:21 12326 TRACE nova.compute.manager [instance: 8520c4d3-0ee3-4fd0-ab44-6d8dde2678a8]   File "/usr/lib/python2.6/site-packages/eventlet/tpool.py", line 187, in doit
>2013-02-17 13:10:21 12326 TRACE nova.compute.manager [instance: 8520c4d3-0ee3-4fd0-ab44-6d8dde2678a8]     result = proxy_call(self._autowrap, f, *args, **kwargs)
>2013-02-17 13:10:21 12326 TRACE nova.compute.manager [instance: 8520c4d3-0ee3-4fd0-ab44-6d8dde2678a8]   File "/usr/lib/python2.6/site-packages/eventlet/tpool.py", line 147, in proxy_call
>2013-02-17 13:10:21 12326 TRACE nova.compute.manager [instance: 8520c4d3-0ee3-4fd0-ab44-6d8dde2678a8]     rv = execute(f,*args,**kwargs)
>2013-02-17 13:10:21 12326 TRACE nova.compute.manager [instance: 8520c4d3-0ee3-4fd0-ab44-6d8dde2678a8]   File "/usr/lib/python2.6/site-packages/eventlet/tpool.py", line 76, in tworker
>2013-02-17 13:10:21 12326 TRACE nova.compute.manager [instance: 8520c4d3-0ee3-4fd0-ab44-6d8dde2678a8]     rv = meth(*args,**kwargs)
>2013-02-17 13:10:21 12326 TRACE nova.compute.manager [instance: 8520c4d3-0ee3-4fd0-ab44-6d8dde2678a8]   File "/usr/lib64/python2.6/site-packages/libvirt.py", line 708, in createWithFlags
>2013-02-17 13:10:21 12326 TRACE nova.compute.manager [instance: 8520c4d3-0ee3-4fd0-ab44-6d8dde2678a8]     if ret == -1: raise libvirtError ('virDomainCreateWithFlags() failed', dom=self)
>2013-02-17 13:10:21 12326 TRACE nova.compute.manager [instance: 8520c4d3-0ee3-4fd0-ab44-6d8dde2678a8] libvirtError: internal error Process exited while reading console log output: char device redirected to /dev/pts/1
>2013-02-17 13:10:21 12326 TRACE nova.compute.manager [instance: 8520c4d3-0ee3-4fd0-ab44-6d8dde2678a8] qemu-kvm: -drive file=/var/lib/nova/instances/instance-00000001/disk,if=none,id=drive-virtio-disk0,format=qcow2,cache=none: could not open disk image /var/lib/nova/instances/instance-00000001/disk: Permission denied
>2013-02-17 13:10:21 12326 TRACE nova.compute.manager [instance: 8520c4d3-0ee3-4fd0-ab44-6d8dde2678a8] 
>2013-02-17 13:10:21 12326 TRACE nova.compute.manager [instance: 8520c4d3-0ee3-4fd0-ab44-6d8dde2678a8] 
>2013-02-17 13:10:21 INFO nova.compute.resource_tracker [req-ec94b7a8-7286-4acf-b635-34f5aafb7536 24a32f3e151142ecb99476750185faca fa0688720d58455b92d35d4052ab7f24] Aborting claim: [Claim 8520c4d3-0ee3-4fd0-ab44-6d8dde2678a8: 512 MB memory, 0 GB disk, 1 VCPUS]
>2013-02-17 13:10:24 ERROR nova.compute.manager [req-ec94b7a8-7286-4acf-b635-34f5aafb7536 24a32f3e151142ecb99476750185faca fa0688720d58455b92d35d4052ab7f24] [instance: 8520c4d3-0ee3-4fd0-ab44-6d8dde2678a8] Build error: ['Traceback (most recent call last):\n', '  File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 501, in _run_instance\n    injected_files, admin_password)\n', '  File "/usr/lib/python2.6/site-packages/nova/compute/manager.py", line 754, in _spawn\n    block_device_info)\n', '  File "/usr/lib/python2.6/site-packages/nova/exception.py", line 117, in wrapped\n    temp_level, payload)\n', '  File "/usr/lib64/python2.6/contextlib.py", line 23, in __exit__\n    self.gen.next()\n', '  File "/usr/lib/python2.6/site-packages/nova/exception.py", line 92, in wrapped\n    return f(*args, **kw)\n', '  File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/driver.py", line 1094, in spawn\n    block_device_info)\n', '  File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/driver.py", line 1959, in _create_domain_and_network\n    domain = self._create_domain(xml)\n', '  File "/usr/lib/python2.6/site-packages/nova/virt/libvirt/driver.py", line 1938, in _create_domain\n    domain.createWithFlags(launch_flags)\n', '  File "/usr/lib/python2.6/site-packages/eventlet/tpool.py", line 187, in doit\n    result = proxy_call(self._autowrap, f, *args, **kwargs)\n', '  File "/usr/lib/python2.6/site-packages/eventlet/tpool.py", line 147, in proxy_call\n    rv = execute(f,*args,**kwargs)\n', '  File "/usr/lib/python2.6/site-packages/eventlet/tpool.py", line 76, in tworker\n    rv = meth(*args,**kwargs)\n', '  File "/usr/lib64/python2.6/site-packages/libvirt.py", line 708, in createWithFlags\n    if ret == -1: raise libvirtError (\'virDomainCreateWithFlags() failed\', dom=self)\n', 'libvirtError: internal error Process exited while reading console log output: char device redirected to /dev/pts/1\nqemu-kvm: -drive file=/var/lib/nova/instances/instance-00000001/disk,if=none,id=drive-virtio-disk0,format=qcow2,cache=none: could not open disk image /var/lib/nova/instances/instance-00000001/disk: Permission denied\n\n']
>2013-02-17 13:10:27 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 13:10:27 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 13:10:27 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 13:10:28 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 13:11:28 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 13:11:28 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 13:11:28 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 13:11:28 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 13:11:28 12326 INFO nova.compute.manager [-] Updating host status
>2013-02-17 13:12:28 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 13:12:28 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 13:12:28 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 13:12:28 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 13:13:28 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 13:13:28 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 13:13:28 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 13:13:28 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 13:13:28 12326 INFO nova.compute.manager [-] Updating host status
>2013-02-17 13:14:28 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 13:14:28 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 13:14:28 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 13:14:28 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 13:15:28 12326 INFO nova.compute.manager [-] Updating bandwidth usage cache
>2013-02-17 13:15:29 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 13:15:29 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 13:15:29 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 13:15:29 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 13:15:29 12326 INFO nova.compute.manager [-] Updating host status
>2013-02-17 13:16:29 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 13:16:29 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 13:16:29 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 13:16:29 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 13:17:29 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 13:17:29 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 13:17:29 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 13:17:29 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 13:17:29 12326 INFO nova.compute.manager [-] Updating host status
>2013-02-17 13:18:29 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 13:18:29 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 13:18:29 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 13:18:29 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 13:19:29 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 13:19:29 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 13:19:29 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 13:19:29 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 13:19:29 12326 INFO nova.compute.manager [-] Updating host status
>2013-02-17 13:20:30 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 13:20:30 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 13:20:30 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 13:20:30 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 13:21:30 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 13:21:30 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 13:21:30 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 13:21:30 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 13:21:30 12326 INFO nova.compute.manager [-] Updating host status
>2013-02-17 13:22:30 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 13:22:30 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 13:22:30 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 13:22:30 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 13:23:30 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 13:23:30 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 13:23:30 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 13:23:30 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 13:23:30 12326 INFO nova.compute.manager [-] Updating host status
>2013-02-17 13:24:32 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 13:24:32 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 13:24:32 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 13:24:32 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 13:25:32 12326 INFO nova.compute.manager [-] Updating bandwidth usage cache
>2013-02-17 13:25:33 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 13:25:33 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 13:25:33 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 13:25:34 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 13:25:34 12326 INFO nova.compute.manager [-] Updating host status
>2013-02-17 13:26:34 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 13:26:34 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 13:26:34 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 13:26:34 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 13:27:34 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 13:27:34 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 13:27:34 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 13:27:34 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 13:27:34 12326 INFO nova.compute.manager [-] Updating host status
>2013-02-17 13:28:34 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 13:28:34 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 13:28:34 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 13:28:34 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 13:29:35 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 13:29:35 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 13:29:35 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 13:29:35 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 13:29:35 12326 INFO nova.compute.manager [-] Updating host status
>2013-02-17 13:30:35 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 13:30:35 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 13:30:35 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 13:30:35 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 13:31:35 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 13:31:35 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 13:31:35 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 13:31:35 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com 
>2013-02-17 13:31:35 12326 INFO nova.compute.manager [-] Updating host status
>2013-02-17 13:32:35 12326 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 31587
>2013-02-17 13:32:35 12326 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 442
>2013-02-17 13:32:35 12326 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 12
>2013-02-17 13:32:35 12326 INFO nova.compute.resource_tracker [-] Compute_service record updated for puma01.scl.lab.tlv.redhat.com

Actions: View

Attachments on bug 896013: 692957 | 693001 | 696818 | 696820 | 698513