Attachment 617531 Details for Bug 860658 – compute.log

compute.log

compute.log (text/plain), 186.69 KB, created by Sandro Mathys on 2012-09-26 12:24:07 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Sandro Mathys

Created: 2012-09-26 12:24:07 UTC

Size: 186.69 KB

patch

obsolete

>2012-09-26 11:08:20 INFO nova.compute.manager [-] Loading compute driver 'libvirt.LibvirtDriver'
>2012-09-26 11:08:20 AUDIT nova.service [-] Starting compute node (version 2012.2-LOCALBRANCH:LOCALREVISION)
>2012-09-26 11:08:30 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 11:08:30 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 11:08:30 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 11:08:30 INFO nova.compute.resource_tracker [-] Compute_service record created for slabstb250.ethz.ch 
>2012-09-26 11:08:30 INFO nova.openstack.common.rpc.impl_qpid [-] Connected to AMQP server on localhost:5672
>2012-09-26 11:08:34 INFO nova.service [-] Caught SIGTERM, exiting
>2012-09-26 11:08:35 INFO nova.compute.manager [-] Loading compute driver 'libvirt.LibvirtDriver'
>2012-09-26 11:08:35 AUDIT nova.service [-] Starting compute node (version 2012.2-LOCALBRANCH:LOCALREVISION)
>2012-09-26 11:08:44 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 11:08:44 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 11:08:44 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 11:08:44 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 11:08:44 INFO nova.openstack.common.rpc.impl_qpid [-] Connected to AMQP server on localhost:5672
>2012-09-26 11:09:25 INFO nova.compute.manager [req-abc24ae7-3b80-482c-8f0f-43ee1dd4b3f8 None None] Updating bandwidth usage cache
>2012-09-26 11:09:36 AUDIT nova.compute.resource_tracker [req-abc24ae7-3b80-482c-8f0f-43ee1dd4b3f8 None None] Free ram (MB): 7314
>2012-09-26 11:09:36 AUDIT nova.compute.resource_tracker [req-abc24ae7-3b80-482c-8f0f-43ee1dd4b3f8 None None] Free disk (GB): 49
>2012-09-26 11:09:36 AUDIT nova.compute.resource_tracker [req-abc24ae7-3b80-482c-8f0f-43ee1dd4b3f8 None None] Free VCPUS: 4
>2012-09-26 11:09:36 INFO nova.compute.resource_tracker [req-abc24ae7-3b80-482c-8f0f-43ee1dd4b3f8 None None] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 11:09:36 INFO nova.compute.manager [req-abc24ae7-3b80-482c-8f0f-43ee1dd4b3f8 None None] Updating host status
>2012-09-26 11:10:57 INFO nova.openstack.common.rpc.impl_qpid [-] Connected to AMQP server on localhost:5672
>2012-09-26 11:11:08 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 11:11:08 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 11:11:08 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 11:11:08 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 11:12:18 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 11:12:18 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 11:12:18 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 11:12:18 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 11:12:18 INFO nova.compute.manager [-] Updating host status
>2012-09-26 11:13:40 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 11:13:40 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 11:13:40 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 11:13:40 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 11:14:50 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 11:14:50 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 11:14:50 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 11:14:50 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 11:14:50 INFO nova.compute.manager [-] Updating host status
>2012-09-26 11:16:12 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 11:16:12 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 11:16:12 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 11:16:12 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 11:17:22 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 11:17:22 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 11:17:22 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 11:17:22 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 11:17:22 INFO nova.compute.manager [-] Updating host status
>2012-09-26 11:18:44 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 11:18:44 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 11:18:44 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 11:18:44 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 11:19:44 INFO nova.compute.manager [-] Updating bandwidth usage cache
>2012-09-26 11:19:54 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 11:19:54 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 11:19:54 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 11:19:54 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 11:19:54 INFO nova.compute.manager [-] Updating host status
>2012-09-26 11:21:16 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 11:21:16 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 11:21:16 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 11:21:16 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 11:22:27 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 11:22:27 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 11:22:27 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 11:22:27 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 11:22:27 INFO nova.compute.manager [-] Updating host status
>2012-09-26 11:23:49 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 11:23:49 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 11:23:49 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 11:23:49 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 11:24:59 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 11:24:59 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 11:24:59 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 11:24:59 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 11:24:59 INFO nova.compute.manager [-] Updating host status
>2012-09-26 11:26:20 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 11:26:20 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 11:26:20 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 11:26:20 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 11:27:30 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 11:27:30 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 11:27:30 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 11:27:31 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 11:27:31 INFO nova.compute.manager [-] Updating host status
>2012-09-26 11:28:52 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 11:28:52 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 11:28:52 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 11:28:52 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 11:29:52 INFO nova.compute.manager [-] Updating bandwidth usage cache
>2012-09-26 11:30:03 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 11:30:03 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 11:30:03 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 11:30:03 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 11:30:03 INFO nova.compute.manager [-] Updating host status
>2012-09-26 11:31:25 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 11:31:25 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 11:31:25 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 11:31:25 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 11:32:35 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 11:32:35 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 11:32:35 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 11:32:35 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 11:32:35 INFO nova.compute.manager [-] Updating host status
>2012-09-26 11:33:57 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 11:33:57 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 11:33:57 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 11:33:57 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 11:35:07 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 11:35:07 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 11:35:07 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 11:35:07 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 11:35:07 INFO nova.compute.manager [-] Updating host status
>2012-09-26 11:36:30 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 11:36:30 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 11:36:30 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 11:36:30 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 11:37:41 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 11:37:41 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 11:37:41 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 11:37:41 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 11:37:41 INFO nova.compute.manager [-] Updating host status
>2012-09-26 11:39:02 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 11:39:02 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 11:39:02 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 11:39:02 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 11:40:02 INFO nova.compute.manager [-] Updating bandwidth usage cache
>2012-09-26 11:40:13 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 11:40:13 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 11:40:13 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 11:40:13 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 11:40:13 INFO nova.compute.manager [-] Updating host status
>2012-09-26 11:41:35 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 11:41:35 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 11:41:35 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 11:41:35 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 11:42:45 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 11:42:45 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 11:42:45 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 11:42:45 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 11:42:45 INFO nova.compute.manager [-] Updating host status
>2012-09-26 11:44:07 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 11:44:07 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 11:44:07 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 11:44:07 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 11:45:17 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 11:45:17 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 11:45:17 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 11:45:17 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 11:45:17 INFO nova.compute.manager [-] Updating host status
>2012-09-26 11:46:39 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 11:46:39 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 11:46:39 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 11:46:39 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 11:47:50 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 11:47:50 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 11:47:50 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 11:47:50 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 11:47:51 INFO nova.compute.manager [-] Updating host status
>2012-09-26 11:49:13 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 11:49:13 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 11:49:13 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 11:49:13 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 11:50:14 INFO nova.compute.manager [-] Updating bandwidth usage cache
>2012-09-26 11:50:24 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 11:50:24 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 11:50:24 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 11:50:24 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 11:50:24 INFO nova.compute.manager [-] Updating host status
>2012-09-26 11:51:46 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 11:51:46 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 11:51:46 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 11:51:46 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 11:52:56 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 11:52:56 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 11:52:56 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 11:52:56 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 11:52:56 INFO nova.compute.manager [-] Updating host status
>2012-09-26 11:54:18 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 11:54:18 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 11:54:18 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 11:54:18 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 11:55:28 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 11:55:28 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 11:55:28 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 11:55:29 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 11:55:29 INFO nova.compute.manager [-] Updating host status
>2012-09-26 11:56:50 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 11:56:50 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 11:56:50 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 11:56:50 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 11:58:01 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 11:58:01 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 11:58:01 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 11:58:01 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 11:58:01 INFO nova.compute.manager [-] Updating host status
>2012-09-26 11:59:23 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 11:59:23 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 11:59:23 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 11:59:23 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 12:00:23 INFO nova.compute.manager [-] Updating bandwidth usage cache
>2012-09-26 12:00:33 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 12:00:33 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 12:00:33 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 12:00:33 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 12:00:33 INFO nova.compute.manager [-] Updating host status
>2012-09-26 12:01:55 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 12:01:55 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 12:01:55 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 12:01:55 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 12:03:05 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 12:03:05 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 12:03:05 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 12:03:05 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 12:03:05 INFO nova.compute.manager [-] Updating host status
>2012-09-26 12:04:28 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 12:04:28 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 12:04:28 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 12:04:28 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 12:05:39 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 12:05:39 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 12:05:39 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 12:05:39 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 12:05:39 INFO nova.compute.manager [-] Updating host status
>2012-09-26 12:07:01 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 12:07:01 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 12:07:01 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 12:07:01 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 12:08:11 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 12:08:11 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 12:08:11 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 12:08:11 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 12:08:11 INFO nova.compute.manager [-] Updating host status
>2012-09-26 12:09:33 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 12:09:33 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 12:09:33 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 12:09:33 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 12:10:33 INFO nova.compute.manager [-] Updating bandwidth usage cache
>2012-09-26 12:10:43 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 12:10:43 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 12:10:43 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 12:10:43 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 12:10:43 INFO nova.compute.manager [-] Updating host status
>2012-09-26 12:12:05 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 12:12:05 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 12:12:05 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 12:12:05 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 12:13:16 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 12:13:16 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 12:13:16 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 12:13:16 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 12:13:16 INFO nova.compute.manager [-] Updating host status
>2012-09-26 12:14:37 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 12:14:37 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 12:14:37 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 12:14:37 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 12:15:48 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 12:15:48 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 12:15:48 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 12:15:48 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 12:15:48 INFO nova.compute.manager [-] Updating host status
>2012-09-26 12:17:09 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 12:17:09 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 12:17:09 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 12:17:09 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 12:18:21 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 12:18:21 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 12:18:21 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 12:18:21 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 12:18:21 INFO nova.compute.manager [-] Updating host status
>2012-09-26 12:19:43 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 12:19:43 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 12:19:43 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 12:19:43 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 12:20:43 INFO nova.compute.manager [-] Updating bandwidth usage cache
>2012-09-26 12:20:53 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 12:20:53 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 12:20:53 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 12:20:53 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 12:20:53 INFO nova.compute.manager [-] Updating host status
>2012-09-26 12:22:15 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 12:22:15 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 12:22:15 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 12:22:15 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 12:23:25 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 12:23:25 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 12:23:25 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 12:23:25 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 12:23:25 INFO nova.compute.manager [-] Updating host status
>2012-09-26 12:24:47 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 12:24:47 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 12:24:47 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 12:24:47 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 12:25:58 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 12:25:58 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 12:25:58 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 12:25:58 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 12:25:58 INFO nova.compute.manager [-] Updating host status
>2012-09-26 12:27:19 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 12:27:19 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 12:27:19 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 12:27:19 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 12:28:31 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 12:28:31 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 12:28:31 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 12:28:31 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 12:28:31 INFO nova.compute.manager [-] Updating host status
>2012-09-26 12:29:52 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 12:29:52 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 12:29:52 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 12:29:52 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 12:30:52 INFO nova.compute.manager [-] Updating bandwidth usage cache
>2012-09-26 12:31:03 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 12:31:03 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 12:31:03 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 12:31:03 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 12:31:03 INFO nova.compute.manager [-] Updating host status
>2012-09-26 12:32:26 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 12:32:26 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 12:32:26 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 12:32:26 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 12:33:36 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 12:33:36 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 12:33:36 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 12:33:36 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 12:33:36 INFO nova.compute.manager [-] Updating host status
>2012-09-26 12:34:58 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 12:34:58 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 12:34:58 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 12:34:58 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 12:36:08 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 12:36:08 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 12:36:08 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 12:36:08 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 12:36:08 INFO nova.compute.manager [-] Updating host status
>2012-09-26 12:37:20 ERROR nova.manager [-] Error during ComputeManager.update_available_resource: Failed to connect socket to '/var/run/libvirt/libvirt-sock': No such file or directory
>2012-09-26 12:37:20 TRACE nova.manager Traceback (most recent call last):
>2012-09-26 12:37:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/manager.py", line 175, in periodic_tasks
>2012-09-26 12:37:20 TRACE nova.manager     task(self, context)
>2012-09-26 12:37:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/compute/manager.py", line 2730, in update_available_resource
>2012-09-26 12:37:20 TRACE nova.manager     self.resource_tracker.update_available_resource(context)
>2012-09-26 12:37:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/utils.py", line 752, in inner
>2012-09-26 12:37:20 TRACE nova.manager     retval = f(*args, **kwargs)
>2012-09-26 12:37:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/compute/resource_tracker.py", line 366, in update_available_resource
>2012-09-26 12:37:20 TRACE nova.manager     resources = self.driver.get_available_resource()
>2012-09-26 12:37:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 2202, in get_available_resource
>2012-09-26 12:37:20 TRACE nova.manager     'memory_mb': self.get_memory_mb_total(),
>2012-09-26 12:37:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1984, in get_memory_mb_total
>2012-09-26 12:37:20 TRACE nova.manager     return self._conn.getInfo()[1]
>2012-09-26 12:37:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 347, in _get_connection
>2012-09-26 12:37:20 TRACE nova.manager     self._connect, self.uri, self.read_only)
>2012-09-26 12:37:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 147, in proxy_call
>2012-09-26 12:37:20 TRACE nova.manager     rv = execute(f,*args,**kwargs)
>2012-09-26 12:37:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 76, in tworker
>2012-09-26 12:37:20 TRACE nova.manager     rv = meth(*args,**kwargs)
>2012-09-26 12:37:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 401, in _connect
>2012-09-26 12:37:20 TRACE nova.manager     return libvirt.openAuth(uri, auth, 0)
>2012-09-26 12:37:20 TRACE nova.manager   File "/usr/lib64/python2.7/site-packages/libvirt.py", line 102, in openAuth
>2012-09-26 12:37:20 TRACE nova.manager     if ret is None:raise libvirtError('virConnectOpenAuth() failed')
>2012-09-26 12:37:20 TRACE nova.manager libvirtError: Failed to connect socket to '/var/run/libvirt/libvirt-sock': No such file or directory
>2012-09-26 12:37:20 TRACE nova.manager 
>2012-09-26 12:38:20 ERROR nova.manager [-] Error during ComputeManager.update_available_resource: internal error client socket is closed
>2012-09-26 12:38:20 TRACE nova.manager Traceback (most recent call last):
>2012-09-26 12:38:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/manager.py", line 175, in periodic_tasks
>2012-09-26 12:38:20 TRACE nova.manager     task(self, context)
>2012-09-26 12:38:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/compute/manager.py", line 2730, in update_available_resource
>2012-09-26 12:38:20 TRACE nova.manager     self.resource_tracker.update_available_resource(context)
>2012-09-26 12:38:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/utils.py", line 752, in inner
>2012-09-26 12:38:20 TRACE nova.manager     retval = f(*args, **kwargs)
>2012-09-26 12:38:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/compute/resource_tracker.py", line 366, in update_available_resource
>2012-09-26 12:38:20 TRACE nova.manager     resources = self.driver.get_available_resource()
>2012-09-26 12:38:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 2202, in get_available_resource
>2012-09-26 12:38:20 TRACE nova.manager     'memory_mb': self.get_memory_mb_total(),
>2012-09-26 12:38:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1984, in get_memory_mb_total
>2012-09-26 12:38:20 TRACE nova.manager     return self._conn.getInfo()[1]
>2012-09-26 12:38:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 339, in _get_connection
>2012-09-26 12:38:20 TRACE nova.manager     if not self._wrapped_conn or not self._test_connection():
>2012-09-26 12:38:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 355, in _test_connection
>2012-09-26 12:38:20 TRACE nova.manager     self._wrapped_conn.getCapabilities()
>2012-09-26 12:38:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 187, in doit
>2012-09-26 12:38:20 TRACE nova.manager     result = proxy_call(self._autowrap, f, *args, **kwargs)
>2012-09-26 12:38:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 147, in proxy_call
>2012-09-26 12:38:20 TRACE nova.manager     rv = execute(f,*args,**kwargs)
>2012-09-26 12:38:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 76, in tworker
>2012-09-26 12:38:20 TRACE nova.manager     rv = meth(*args,**kwargs)
>2012-09-26 12:38:20 TRACE nova.manager   File "/usr/lib64/python2.7/site-packages/libvirt.py", line 2681, in getCapabilities
>2012-09-26 12:38:20 TRACE nova.manager     if ret is None: raise libvirtError ('virConnectGetCapabilities() failed', conn=self)
>2012-09-26 12:38:20 TRACE nova.manager libvirtError: internal error client socket is closed
>2012-09-26 12:38:20 TRACE nova.manager 
>2012-09-26 12:38:20 INFO nova.compute.manager [-] Updating host status
>2012-09-26 12:38:20 ERROR nova.manager [-] Error during ComputeManager._report_driver_status: Failed to connect socket to '/var/run/libvirt/libvirt-sock': No such file or directory
>2012-09-26 12:38:20 TRACE nova.manager Traceback (most recent call last):
>2012-09-26 12:38:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/manager.py", line 175, in periodic_tasks
>2012-09-26 12:38:20 TRACE nova.manager     task(self, context)
>2012-09-26 12:38:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/compute/manager.py", line 2559, in _report_driver_status
>2012-09-26 12:38:20 TRACE nova.manager     capabilities = self.driver.get_host_stats(refresh=True)
>2012-09-26 12:38:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 2740, in get_host_stats
>2012-09-26 12:38:20 TRACE nova.manager     return self.host_state.get_host_stats(refresh=refresh)
>2012-09-26 12:38:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 3014, in get_host_stats
>2012-09-26 12:38:20 TRACE nova.manager     self.update_status()
>2012-09-26 12:38:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 3024, in update_status
>2012-09-26 12:38:20 TRACE nova.manager     data["vcpus_used"] = self.connection.get_vcpu_used()
>2012-09-26 12:38:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 2008, in get_vcpu_used
>2012-09-26 12:38:20 TRACE nova.manager     for dom_id in self.list_instance_ids():
>2012-09-26 12:38:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 417, in list_instance_ids
>2012-09-26 12:38:20 TRACE nova.manager     if self._conn.numOfDomains() == 0:
>2012-09-26 12:38:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 347, in _get_connection
>2012-09-26 12:38:20 TRACE nova.manager     self._connect, self.uri, self.read_only)
>2012-09-26 12:38:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 147, in proxy_call
>2012-09-26 12:38:20 TRACE nova.manager     rv = execute(f,*args,**kwargs)
>2012-09-26 12:38:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 76, in tworker
>2012-09-26 12:38:20 TRACE nova.manager     rv = meth(*args,**kwargs)
>2012-09-26 12:38:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 401, in _connect
>2012-09-26 12:38:20 TRACE nova.manager     return libvirt.openAuth(uri, auth, 0)
>2012-09-26 12:38:20 TRACE nova.manager   File "/usr/lib64/python2.7/site-packages/libvirt.py", line 102, in openAuth
>2012-09-26 12:38:20 TRACE nova.manager     if ret is None:raise libvirtError('virConnectOpenAuth() failed')
>2012-09-26 12:38:20 TRACE nova.manager libvirtError: Failed to connect socket to '/var/run/libvirt/libvirt-sock': No such file or directory
>2012-09-26 12:38:20 TRACE nova.manager 
>2012-09-26 12:39:20 ERROR nova.manager [-] Error during ComputeManager.update_available_resource: internal error client socket is closed
>2012-09-26 12:39:20 TRACE nova.manager Traceback (most recent call last):
>2012-09-26 12:39:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/manager.py", line 175, in periodic_tasks
>2012-09-26 12:39:20 TRACE nova.manager     task(self, context)
>2012-09-26 12:39:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/compute/manager.py", line 2730, in update_available_resource
>2012-09-26 12:39:20 TRACE nova.manager     self.resource_tracker.update_available_resource(context)
>2012-09-26 12:39:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/utils.py", line 752, in inner
>2012-09-26 12:39:20 TRACE nova.manager     retval = f(*args, **kwargs)
>2012-09-26 12:39:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/compute/resource_tracker.py", line 366, in update_available_resource
>2012-09-26 12:39:20 TRACE nova.manager     resources = self.driver.get_available_resource()
>2012-09-26 12:39:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 2202, in get_available_resource
>2012-09-26 12:39:20 TRACE nova.manager     'memory_mb': self.get_memory_mb_total(),
>2012-09-26 12:39:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1984, in get_memory_mb_total
>2012-09-26 12:39:20 TRACE nova.manager     return self._conn.getInfo()[1]
>2012-09-26 12:39:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 339, in _get_connection
>2012-09-26 12:39:20 TRACE nova.manager     if not self._wrapped_conn or not self._test_connection():
>2012-09-26 12:39:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 355, in _test_connection
>2012-09-26 12:39:20 TRACE nova.manager     self._wrapped_conn.getCapabilities()
>2012-09-26 12:39:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 187, in doit
>2012-09-26 12:39:20 TRACE nova.manager     result = proxy_call(self._autowrap, f, *args, **kwargs)
>2012-09-26 12:39:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 147, in proxy_call
>2012-09-26 12:39:20 TRACE nova.manager     rv = execute(f,*args,**kwargs)
>2012-09-26 12:39:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 76, in tworker
>2012-09-26 12:39:20 TRACE nova.manager     rv = meth(*args,**kwargs)
>2012-09-26 12:39:20 TRACE nova.manager   File "/usr/lib64/python2.7/site-packages/libvirt.py", line 2681, in getCapabilities
>2012-09-26 12:39:20 TRACE nova.manager     if ret is None: raise libvirtError ('virConnectGetCapabilities() failed', conn=self)
>2012-09-26 12:39:20 TRACE nova.manager libvirtError: internal error client socket is closed
>2012-09-26 12:39:20 TRACE nova.manager 
>2012-09-26 12:40:20 ERROR nova.manager [-] Error during ComputeManager.update_available_resource: internal error client socket is closed
>2012-09-26 12:40:20 TRACE nova.manager Traceback (most recent call last):
>2012-09-26 12:40:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/manager.py", line 175, in periodic_tasks
>2012-09-26 12:40:20 TRACE nova.manager     task(self, context)
>2012-09-26 12:40:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/compute/manager.py", line 2730, in update_available_resource
>2012-09-26 12:40:20 TRACE nova.manager     self.resource_tracker.update_available_resource(context)
>2012-09-26 12:40:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/utils.py", line 752, in inner
>2012-09-26 12:40:20 TRACE nova.manager     retval = f(*args, **kwargs)
>2012-09-26 12:40:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/compute/resource_tracker.py", line 366, in update_available_resource
>2012-09-26 12:40:20 TRACE nova.manager     resources = self.driver.get_available_resource()
>2012-09-26 12:40:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 2202, in get_available_resource
>2012-09-26 12:40:20 TRACE nova.manager     'memory_mb': self.get_memory_mb_total(),
>2012-09-26 12:40:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1984, in get_memory_mb_total
>2012-09-26 12:40:20 TRACE nova.manager     return self._conn.getInfo()[1]
>2012-09-26 12:40:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 339, in _get_connection
>2012-09-26 12:40:20 TRACE nova.manager     if not self._wrapped_conn or not self._test_connection():
>2012-09-26 12:40:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 355, in _test_connection
>2012-09-26 12:40:20 TRACE nova.manager     self._wrapped_conn.getCapabilities()
>2012-09-26 12:40:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 187, in doit
>2012-09-26 12:40:20 TRACE nova.manager     result = proxy_call(self._autowrap, f, *args, **kwargs)
>2012-09-26 12:40:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 147, in proxy_call
>2012-09-26 12:40:20 TRACE nova.manager     rv = execute(f,*args,**kwargs)
>2012-09-26 12:40:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 76, in tworker
>2012-09-26 12:40:20 TRACE nova.manager     rv = meth(*args,**kwargs)
>2012-09-26 12:40:20 TRACE nova.manager   File "/usr/lib64/python2.7/site-packages/libvirt.py", line 2681, in getCapabilities
>2012-09-26 12:40:20 TRACE nova.manager     if ret is None: raise libvirtError ('virConnectGetCapabilities() failed', conn=self)
>2012-09-26 12:40:20 TRACE nova.manager libvirtError: internal error client socket is closed
>2012-09-26 12:40:20 TRACE nova.manager 
>2012-09-26 12:40:20 INFO nova.compute.manager [-] Updating host status
>2012-09-26 12:40:20 ERROR nova.manager [-] Error during ComputeManager._report_driver_status: internal error client socket is closed
>2012-09-26 12:40:20 TRACE nova.manager Traceback (most recent call last):
>2012-09-26 12:40:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/manager.py", line 175, in periodic_tasks
>2012-09-26 12:40:20 TRACE nova.manager     task(self, context)
>2012-09-26 12:40:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/compute/manager.py", line 2559, in _report_driver_status
>2012-09-26 12:40:20 TRACE nova.manager     capabilities = self.driver.get_host_stats(refresh=True)
>2012-09-26 12:40:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 2740, in get_host_stats
>2012-09-26 12:40:20 TRACE nova.manager     return self.host_state.get_host_stats(refresh=refresh)
>2012-09-26 12:40:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 3014, in get_host_stats
>2012-09-26 12:40:20 TRACE nova.manager     self.update_status()
>2012-09-26 12:40:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 3024, in update_status
>2012-09-26 12:40:20 TRACE nova.manager     data["vcpus_used"] = self.connection.get_vcpu_used()
>2012-09-26 12:40:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 2008, in get_vcpu_used
>2012-09-26 12:40:20 TRACE nova.manager     for dom_id in self.list_instance_ids():
>2012-09-26 12:40:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 417, in list_instance_ids
>2012-09-26 12:40:20 TRACE nova.manager     if self._conn.numOfDomains() == 0:
>2012-09-26 12:40:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 339, in _get_connection
>2012-09-26 12:40:20 TRACE nova.manager     if not self._wrapped_conn or not self._test_connection():
>2012-09-26 12:40:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 355, in _test_connection
>2012-09-26 12:40:20 TRACE nova.manager     self._wrapped_conn.getCapabilities()
>2012-09-26 12:40:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 187, in doit
>2012-09-26 12:40:20 TRACE nova.manager     result = proxy_call(self._autowrap, f, *args, **kwargs)
>2012-09-26 12:40:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 147, in proxy_call
>2012-09-26 12:40:20 TRACE nova.manager     rv = execute(f,*args,**kwargs)
>2012-09-26 12:40:20 TRACE nova.manager   File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 76, in tworker
>2012-09-26 12:40:20 TRACE nova.manager     rv = meth(*args,**kwargs)
>2012-09-26 12:40:20 TRACE nova.manager   File "/usr/lib64/python2.7/site-packages/libvirt.py", line 2681, in getCapabilities
>2012-09-26 12:40:20 TRACE nova.manager     if ret is None: raise libvirtError ('virConnectGetCapabilities() failed', conn=self)
>2012-09-26 12:40:20 TRACE nova.manager libvirtError: internal error client socket is closed
>2012-09-26 12:40:20 TRACE nova.manager 
>2012-09-26 12:40:40 INFO nova.service [-] Caught SIGTERM, exiting
>2012-09-26 12:40:41 INFO nova.compute.manager [-] Loading compute driver 'libvirt.LibvirtDriver'
>2012-09-26 12:40:41 AUDIT nova.service [-] Starting compute node (version 2012.2-LOCALBRANCH:LOCALREVISION)
>2012-09-26 12:40:52 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 12:40:52 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 12:40:52 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 12:40:52 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 12:40:52 INFO nova.openstack.common.rpc.impl_qpid [-] Connected to AMQP server on localhost:5672
>2012-09-26 12:40:58 INFO nova.compute.manager [req-8b341300-10a5-4abc-8a81-2de6a8ae6d4b None None] Updating bandwidth usage cache
>2012-09-26 12:41:08 AUDIT nova.compute.resource_tracker [req-8b341300-10a5-4abc-8a81-2de6a8ae6d4b None None] Free ram (MB): 7314
>2012-09-26 12:41:08 AUDIT nova.compute.resource_tracker [req-8b341300-10a5-4abc-8a81-2de6a8ae6d4b None None] Free disk (GB): 49
>2012-09-26 12:41:08 AUDIT nova.compute.resource_tracker [req-8b341300-10a5-4abc-8a81-2de6a8ae6d4b None None] Free VCPUS: 4
>2012-09-26 12:41:08 INFO nova.compute.resource_tracker [req-8b341300-10a5-4abc-8a81-2de6a8ae6d4b None None] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 12:41:08 INFO nova.compute.manager [req-8b341300-10a5-4abc-8a81-2de6a8ae6d4b None None] Updating host status
>2012-09-26 12:42:30 INFO nova.openstack.common.rpc.impl_qpid [-] Connected to AMQP server on localhost:5672
>2012-09-26 12:42:40 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 12:42:40 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 12:42:40 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 12:42:40 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 12:43:50 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 12:43:50 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 12:43:50 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 12:43:50 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 12:43:51 INFO nova.compute.manager [-] Updating host status
>2012-09-26 12:45:12 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 12:45:12 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 12:45:12 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 12:45:12 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 12:46:22 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 12:46:22 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 12:46:22 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 12:46:22 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 12:46:22 INFO nova.compute.manager [-] Updating host status
>2012-09-26 12:47:44 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 12:47:44 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 12:47:44 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 12:47:44 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 12:48:54 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 12:48:54 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 12:48:54 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 12:48:54 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 12:48:54 INFO nova.compute.manager [-] Updating host status
>2012-09-26 12:50:15 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 12:50:15 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 12:50:15 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 12:50:15 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 12:51:15 INFO nova.compute.manager [-] Updating bandwidth usage cache
>2012-09-26 12:51:25 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 12:51:25 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 12:51:25 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 12:51:25 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 12:51:25 INFO nova.compute.manager [-] Updating host status
>2012-09-26 12:52:47 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 12:52:47 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 12:52:47 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 12:52:47 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 12:53:58 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 12:53:58 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 12:53:58 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 12:53:58 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 12:53:58 INFO nova.compute.manager [-] Updating host status
>2012-09-26 12:55:19 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 12:55:19 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 12:55:19 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 12:55:19 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 12:56:29 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 12:56:29 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 12:56:29 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 12:56:29 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 12:56:29 INFO nova.compute.manager [-] Updating host status
>2012-09-26 12:57:51 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 12:57:51 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 12:57:51 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 12:57:51 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 12:59:01 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 12:59:01 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 12:59:01 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 12:59:01 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 12:59:01 INFO nova.compute.manager [-] Updating host status
>2012-09-26 13:00:22 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 13:00:22 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 13:00:22 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 13:00:22 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 13:01:22 INFO nova.compute.manager [-] Updating bandwidth usage cache
>2012-09-26 13:01:32 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 13:01:32 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 13:01:32 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 13:01:32 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 13:01:32 INFO nova.compute.manager [-] Updating host status
>2012-09-26 13:02:53 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 13:02:53 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 13:02:53 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 13:02:53 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 13:04:03 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 13:04:03 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 13:04:03 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 13:04:03 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 13:04:03 INFO nova.compute.manager [-] Updating host status
>2012-09-26 13:05:24 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 13:05:24 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 13:05:24 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 13:05:24 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 13:06:34 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 13:06:34 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 13:06:34 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 13:06:34 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 13:06:34 INFO nova.compute.manager [-] Updating host status
>2012-09-26 13:07:58 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 13:07:58 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 13:07:58 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 13:07:58 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 13:09:08 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 13:09:08 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 13:09:08 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 13:09:08 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 13:09:08 INFO nova.compute.manager [-] Updating host status
>2012-09-26 13:10:29 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 13:10:29 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 13:10:29 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 13:10:29 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 13:11:29 INFO nova.compute.manager [-] Updating bandwidth usage cache
>2012-09-26 13:11:38 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 13:11:38 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 13:11:38 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 13:11:38 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 13:11:38 INFO nova.compute.manager [-] Updating host status
>2012-09-26 13:12:59 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 13:12:59 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 13:12:59 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 13:12:59 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 13:14:09 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 13:14:09 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 13:14:09 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 13:14:09 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 13:14:09 INFO nova.compute.manager [-] Updating host status
>2012-09-26 13:15:30 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 13:15:30 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 13:15:30 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 13:15:30 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 13:16:40 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 13:16:40 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 13:16:40 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 13:16:40 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 13:16:40 INFO nova.compute.manager [-] Updating host status
>2012-09-26 13:18:01 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 13:18:01 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 13:18:01 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 13:18:01 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 13:19:11 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 13:19:11 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 13:19:11 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 4
>2012-09-26 13:19:11 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 13:19:12 INFO nova.compute.manager [-] Updating host status
>2012-09-26 13:20:21 AUDIT nova.compute.manager [req-d004da9e-cd8e-4e9c-92b5-2e4dc330b991 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: cb47147e-b00a-4586-ba51-fb4f8eacbbc6] Starting instance...
>2012-09-26 13:20:22 AUDIT nova.compute.resource_tracker [req-d004da9e-cd8e-4e9c-92b5-2e4dc330b991 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Attempting claim: memory 512 MB, disk 0 GB, VCPUs 1
>2012-09-26 13:20:22 AUDIT nova.compute.resource_tracker [req-d004da9e-cd8e-4e9c-92b5-2e4dc330b991 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Total memory: 7826 MB, used: 512 MB, free: 0 MB
>2012-09-26 13:20:22 AUDIT nova.compute.resource_tracker [req-d004da9e-cd8e-4e9c-92b5-2e4dc330b991 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Memory limit: 11739 MB, free: 11227 MB
>2012-09-26 13:20:22 AUDIT nova.compute.resource_tracker [req-d004da9e-cd8e-4e9c-92b5-2e4dc330b991 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Total disk: 49 GB, used: 0 GB, free: 49 GB
>2012-09-26 13:20:22 AUDIT nova.compute.resource_tracker [req-d004da9e-cd8e-4e9c-92b5-2e4dc330b991 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Disk limit not specified, defaulting to unlimited
>2012-09-26 13:20:22 AUDIT nova.compute.resource_tracker [req-d004da9e-cd8e-4e9c-92b5-2e4dc330b991 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Total VCPUs: 4, used: 0
>2012-09-26 13:20:22 AUDIT nova.compute.resource_tracker [req-d004da9e-cd8e-4e9c-92b5-2e4dc330b991 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] VCPU limit not specified, defaulting to unlimited
>2012-09-26 13:20:25 INFO nova.virt.libvirt.driver [req-d004da9e-cd8e-4e9c-92b5-2e4dc330b991 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: cb47147e-b00a-4586-ba51-fb4f8eacbbc6] Creating image
>2012-09-26 13:20:27 AUDIT nova.compute.manager [req-5ca15aec-9d87-4031-926f-0e298ce82e40 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 0fc53d09-f6ea-4e00-92cb-bec43035de14] Starting instance...
>2012-09-26 13:20:34 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 6290
>2012-09-26 13:20:34 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 13:20:34 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2012-09-26 13:20:34 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 13:20:34 INFO nova.virt.libvirt.firewall [req-d004da9e-cd8e-4e9c-92b5-2e4dc330b991 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: cb47147e-b00a-4586-ba51-fb4f8eacbbc6] Called setup_basic_filtering in nwfilter
>2012-09-26 13:20:34 INFO nova.virt.libvirt.firewall [req-d004da9e-cd8e-4e9c-92b5-2e4dc330b991 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: cb47147e-b00a-4586-ba51-fb4f8eacbbc6] Ensuring static filters
>2012-09-26 13:20:35 AUDIT nova.compute.resource_tracker [req-5ca15aec-9d87-4031-926f-0e298ce82e40 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Attempting claim: memory 512 MB, disk 0 GB, VCPUs 1
>2012-09-26 13:20:35 AUDIT nova.compute.resource_tracker [req-5ca15aec-9d87-4031-926f-0e298ce82e40 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Total memory: 7826 MB, used: 1536 MB, free: 0 MB
>2012-09-26 13:20:35 AUDIT nova.compute.resource_tracker [req-5ca15aec-9d87-4031-926f-0e298ce82e40 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Memory limit: 11739 MB, free: 10203 MB
>2012-09-26 13:20:35 AUDIT nova.compute.resource_tracker [req-5ca15aec-9d87-4031-926f-0e298ce82e40 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Total disk: 49 GB, used: 0 GB, free: 49 GB
>2012-09-26 13:20:35 AUDIT nova.compute.resource_tracker [req-5ca15aec-9d87-4031-926f-0e298ce82e40 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Disk limit not specified, defaulting to unlimited
>2012-09-26 13:20:35 AUDIT nova.compute.resource_tracker [req-5ca15aec-9d87-4031-926f-0e298ce82e40 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Total VCPUs: 4, used: 2
>2012-09-26 13:20:35 AUDIT nova.compute.resource_tracker [req-5ca15aec-9d87-4031-926f-0e298ce82e40 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] VCPU limit not specified, defaulting to unlimited
>2012-09-26 13:20:37 INFO nova.virt.libvirt.driver [req-5ca15aec-9d87-4031-926f-0e298ce82e40 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 0fc53d09-f6ea-4e00-92cb-bec43035de14] Creating image
>2012-09-26 13:20:37 INFO nova.virt.libvirt.firewall [req-5ca15aec-9d87-4031-926f-0e298ce82e40 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 0fc53d09-f6ea-4e00-92cb-bec43035de14] Called setup_basic_filtering in nwfilter
>2012-09-26 13:20:37 INFO nova.virt.libvirt.firewall [req-5ca15aec-9d87-4031-926f-0e298ce82e40 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 0fc53d09-f6ea-4e00-92cb-bec43035de14] Ensuring static filters
>2012-09-26 13:20:49 AUDIT nova.compute.manager [req-cccfc114-330e-472f-9d0f-75cc18350a95 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: db62a0bf-06db-47c6-b722-efb972a5322c] Starting instance...
>2012-09-26 13:20:49 AUDIT nova.compute.resource_tracker [req-cccfc114-330e-472f-9d0f-75cc18350a95 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Attempting claim: memory 512 MB, disk 0 GB, VCPUs 1
>2012-09-26 13:20:49 AUDIT nova.compute.resource_tracker [req-cccfc114-330e-472f-9d0f-75cc18350a95 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Total memory: 7826 MB, used: 1536 MB, free: 0 MB
>2012-09-26 13:20:49 AUDIT nova.compute.resource_tracker [req-cccfc114-330e-472f-9d0f-75cc18350a95 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Memory limit: 11739 MB, free: 10203 MB
>2012-09-26 13:20:49 AUDIT nova.compute.resource_tracker [req-cccfc114-330e-472f-9d0f-75cc18350a95 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Total disk: 49 GB, used: 0 GB, free: 49 GB
>2012-09-26 13:20:49 AUDIT nova.compute.resource_tracker [req-cccfc114-330e-472f-9d0f-75cc18350a95 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Disk limit not specified, defaulting to unlimited
>2012-09-26 13:20:49 AUDIT nova.compute.resource_tracker [req-cccfc114-330e-472f-9d0f-75cc18350a95 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Total VCPUs: 4, used: 2
>2012-09-26 13:20:49 AUDIT nova.compute.resource_tracker [req-cccfc114-330e-472f-9d0f-75cc18350a95 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] VCPU limit not specified, defaulting to unlimited
>2012-09-26 13:20:52 INFO nova.virt.libvirt.driver [req-cccfc114-330e-472f-9d0f-75cc18350a95 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: db62a0bf-06db-47c6-b722-efb972a5322c] Creating image
>2012-09-26 13:20:52 INFO nova.virt.libvirt.firewall [req-cccfc114-330e-472f-9d0f-75cc18350a95 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: db62a0bf-06db-47c6-b722-efb972a5322c] Called setup_basic_filtering in nwfilter
>2012-09-26 13:20:52 INFO nova.virt.libvirt.firewall [req-cccfc114-330e-472f-9d0f-75cc18350a95 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: db62a0bf-06db-47c6-b722-efb972a5322c] Ensuring static filters
>2012-09-26 13:20:56 AUDIT nova.compute.manager [req-fea0ef11-58cd-40f8-82ac-b96fd1a00bc6 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: cbf0df21-22bb-4d9d-b31c-7e0b202028c4] Starting instance...
>2012-09-26 13:20:56 AUDIT nova.compute.resource_tracker [req-fea0ef11-58cd-40f8-82ac-b96fd1a00bc6 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Attempting claim: memory 512 MB, disk 0 GB, VCPUs 1
>2012-09-26 13:20:56 AUDIT nova.compute.resource_tracker [req-fea0ef11-58cd-40f8-82ac-b96fd1a00bc6 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Total memory: 7826 MB, used: 2048 MB, free: 0 MB
>2012-09-26 13:20:56 AUDIT nova.compute.resource_tracker [req-fea0ef11-58cd-40f8-82ac-b96fd1a00bc6 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Memory limit: 11739 MB, free: 9691 MB
>2012-09-26 13:20:56 AUDIT nova.compute.resource_tracker [req-fea0ef11-58cd-40f8-82ac-b96fd1a00bc6 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Total disk: 49 GB, used: 0 GB, free: 49 GB
>2012-09-26 13:20:56 AUDIT nova.compute.resource_tracker [req-fea0ef11-58cd-40f8-82ac-b96fd1a00bc6 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Disk limit not specified, defaulting to unlimited
>2012-09-26 13:20:56 AUDIT nova.compute.resource_tracker [req-fea0ef11-58cd-40f8-82ac-b96fd1a00bc6 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Total VCPUs: 4, used: 3
>2012-09-26 13:20:56 AUDIT nova.compute.resource_tracker [req-fea0ef11-58cd-40f8-82ac-b96fd1a00bc6 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] VCPU limit not specified, defaulting to unlimited
>2012-09-26 13:20:59 INFO nova.virt.libvirt.driver [req-fea0ef11-58cd-40f8-82ac-b96fd1a00bc6 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: cbf0df21-22bb-4d9d-b31c-7e0b202028c4] Creating image
>2012-09-26 13:20:59 INFO nova.virt.libvirt.firewall [req-fea0ef11-58cd-40f8-82ac-b96fd1a00bc6 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: cbf0df21-22bb-4d9d-b31c-7e0b202028c4] Called setup_basic_filtering in nwfilter
>2012-09-26 13:20:59 INFO nova.virt.libvirt.firewall [req-fea0ef11-58cd-40f8-82ac-b96fd1a00bc6 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: cbf0df21-22bb-4d9d-b31c-7e0b202028c4] Ensuring static filters
>2012-09-26 13:21:03 ERROR nova.compute.manager [req-d004da9e-cd8e-4e9c-92b5-2e4dc330b991 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: cb47147e-b00a-4586-ba51-fb4f8eacbbc6] Instance failed to spawn
>2012-09-26 13:21:03 TRACE nova.compute.manager [instance: cb47147e-b00a-4586-ba51-fb4f8eacbbc6] Traceback (most recent call last):
>2012-09-26 13:21:03 TRACE nova.compute.manager [instance: cb47147e-b00a-4586-ba51-fb4f8eacbbc6]   File "/usr/lib/python2.7/site-packages/nova/compute/manager.py", line 743, in _spawn
>2012-09-26 13:21:03 TRACE nova.compute.manager [instance: cb47147e-b00a-4586-ba51-fb4f8eacbbc6]     block_device_info)
>2012-09-26 13:21:03 TRACE nova.compute.manager [instance: cb47147e-b00a-4586-ba51-fb4f8eacbbc6]   File "/usr/lib/python2.7/site-packages/nova/exception.py", line 117, in wrapped
>2012-09-26 13:21:03 TRACE nova.compute.manager [instance: cb47147e-b00a-4586-ba51-fb4f8eacbbc6]     temp_level, payload)
>2012-09-26 13:21:03 TRACE nova.compute.manager [instance: cb47147e-b00a-4586-ba51-fb4f8eacbbc6]   File "/usr/lib64/python2.7/contextlib.py", line 24, in __exit__
>2012-09-26 13:21:03 TRACE nova.compute.manager [instance: cb47147e-b00a-4586-ba51-fb4f8eacbbc6]     self.gen.next()
>2012-09-26 13:21:03 TRACE nova.compute.manager [instance: cb47147e-b00a-4586-ba51-fb4f8eacbbc6]   File "/usr/lib/python2.7/site-packages/nova/exception.py", line 92, in wrapped
>2012-09-26 13:21:03 TRACE nova.compute.manager [instance: cb47147e-b00a-4586-ba51-fb4f8eacbbc6]     return f(*args, **kw)
>2012-09-26 13:21:03 TRACE nova.compute.manager [instance: cb47147e-b00a-4586-ba51-fb4f8eacbbc6]   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1061, in spawn
>2012-09-26 13:21:03 TRACE nova.compute.manager [instance: cb47147e-b00a-4586-ba51-fb4f8eacbbc6]     block_device_info)
>2012-09-26 13:21:03 TRACE nova.compute.manager [instance: cb47147e-b00a-4586-ba51-fb4f8eacbbc6]   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1887, in _create_domain_and_network
>2012-09-26 13:21:03 TRACE nova.compute.manager [instance: cb47147e-b00a-4586-ba51-fb4f8eacbbc6]     domain = self._create_domain(xml)
>2012-09-26 13:21:03 TRACE nova.compute.manager [instance: cb47147e-b00a-4586-ba51-fb4f8eacbbc6]   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1866, in _create_domain
>2012-09-26 13:21:03 TRACE nova.compute.manager [instance: cb47147e-b00a-4586-ba51-fb4f8eacbbc6]     domain.createWithFlags(launch_flags)
>2012-09-26 13:21:03 TRACE nova.compute.manager [instance: cb47147e-b00a-4586-ba51-fb4f8eacbbc6]   File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 187, in doit
>2012-09-26 13:21:03 TRACE nova.compute.manager [instance: cb47147e-b00a-4586-ba51-fb4f8eacbbc6]     result = proxy_call(self._autowrap, f, *args, **kwargs)
>2012-09-26 13:21:03 TRACE nova.compute.manager [instance: cb47147e-b00a-4586-ba51-fb4f8eacbbc6]   File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 147, in proxy_call
>2012-09-26 13:21:03 TRACE nova.compute.manager [instance: cb47147e-b00a-4586-ba51-fb4f8eacbbc6]     rv = execute(f,*args,**kwargs)
>2012-09-26 13:21:03 TRACE nova.compute.manager [instance: cb47147e-b00a-4586-ba51-fb4f8eacbbc6]   File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 76, in tworker
>2012-09-26 13:21:03 TRACE nova.compute.manager [instance: cb47147e-b00a-4586-ba51-fb4f8eacbbc6]     rv = meth(*args,**kwargs)
>2012-09-26 13:21:03 TRACE nova.compute.manager [instance: cb47147e-b00a-4586-ba51-fb4f8eacbbc6]   File "/usr/lib64/python2.7/site-packages/libvirt.py", line 708, in createWithFlags
>2012-09-26 13:21:03 TRACE nova.compute.manager [instance: cb47147e-b00a-4586-ba51-fb4f8eacbbc6]     if ret == -1: raise libvirtError ('virDomainCreateWithFlags() failed', dom=self)
>2012-09-26 13:21:03 TRACE nova.compute.manager [instance: cb47147e-b00a-4586-ba51-fb4f8eacbbc6] libvirtError: internal error Process exited while reading console log output: Warning: option deprecated, use lost_tick_policy property of kvm-pit instead.
>2012-09-26 13:21:03 TRACE nova.compute.manager [instance: cb47147e-b00a-4586-ba51-fb4f8eacbbc6] char device redirected to /dev/pts/1
>2012-09-26 13:21:03 TRACE nova.compute.manager [instance: cb47147e-b00a-4586-ba51-fb4f8eacbbc6] Could not access KVM kernel module: Permission denied
>2012-09-26 13:21:03 TRACE nova.compute.manager [instance: cb47147e-b00a-4586-ba51-fb4f8eacbbc6] failed to initialize KVM: Permission denied
>2012-09-26 13:21:03 TRACE nova.compute.manager [instance: cb47147e-b00a-4586-ba51-fb4f8eacbbc6] No accelerator found!
>2012-09-26 13:21:03 TRACE nova.compute.manager [instance: cb47147e-b00a-4586-ba51-fb4f8eacbbc6] 
>2012-09-26 13:21:03 TRACE nova.compute.manager [instance: cb47147e-b00a-4586-ba51-fb4f8eacbbc6] 
>2012-09-26 13:21:03 INFO nova.compute.resource_tracker [req-d004da9e-cd8e-4e9c-92b5-2e4dc330b991 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Aborting claim: [Claim cb47147e-b00a-4586-ba51-fb4f8eacbbc6: 512 MB memory, 0 GB disk, 1 VCPUS]
>2012-09-26 13:21:04 ERROR nova.compute.manager [req-d004da9e-cd8e-4e9c-92b5-2e4dc330b991 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: cb47147e-b00a-4586-ba51-fb4f8eacbbc6] Build error: ['Traceback (most recent call last):\n', '  File "/usr/lib/python2.7/site-packages/nova/compute/manager.py", line 496, in _run_instance\n    injected_files, admin_password)\n', '  File "/usr/lib/python2.7/site-packages/nova/compute/manager.py", line 743, in _spawn\n    block_device_info)\n', '  File "/usr/lib/python2.7/site-packages/nova/exception.py", line 117, in wrapped\n    temp_level, payload)\n', '  File "/usr/lib64/python2.7/contextlib.py", line 24, in __exit__\n    self.gen.next()\n', '  File "/usr/lib/python2.7/site-packages/nova/exception.py", line 92, in wrapped\n    return f(*args, **kw)\n', '  File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1061, in spawn\n    block_device_info)\n', '  File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1887, in _create_domain_and_network\n    domain = self._create_domain(xml)\n', '  File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1866, in _create_domain\n    domain.createWithFlags(launch_flags)\n', '  File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 187, in doit\n    result = proxy_call(self._autowrap, f, *args, **kwargs)\n', '  File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 147, in proxy_call\n    rv = execute(f,*args,**kwargs)\n', '  File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 76, in tworker\n    rv = meth(*args,**kwargs)\n', '  File "/usr/lib64/python2.7/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: Warning: option deprecated, use lost_tick_policy property of kvm-pit instead.\nchar device redirected to /dev/pts/1\nCould not access KVM kernel module: Permission denied\nfailed to initialize KVM: Permission denied\nNo accelerator found!\n\n']
>2012-09-26 13:21:04 ERROR nova.compute.manager [req-cccfc114-330e-472f-9d0f-75cc18350a95 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: db62a0bf-06db-47c6-b722-efb972a5322c] Instance failed to spawn
>2012-09-26 13:21:04 TRACE nova.compute.manager [instance: db62a0bf-06db-47c6-b722-efb972a5322c] Traceback (most recent call last):
>2012-09-26 13:21:04 TRACE nova.compute.manager [instance: db62a0bf-06db-47c6-b722-efb972a5322c]   File "/usr/lib/python2.7/site-packages/nova/compute/manager.py", line 743, in _spawn
>2012-09-26 13:21:04 TRACE nova.compute.manager [instance: db62a0bf-06db-47c6-b722-efb972a5322c]     block_device_info)
>2012-09-26 13:21:04 TRACE nova.compute.manager [instance: db62a0bf-06db-47c6-b722-efb972a5322c]   File "/usr/lib/python2.7/site-packages/nova/exception.py", line 117, in wrapped
>2012-09-26 13:21:04 TRACE nova.compute.manager [instance: db62a0bf-06db-47c6-b722-efb972a5322c]     temp_level, payload)
>2012-09-26 13:21:04 TRACE nova.compute.manager [instance: db62a0bf-06db-47c6-b722-efb972a5322c]   File "/usr/lib64/python2.7/contextlib.py", line 24, in __exit__
>2012-09-26 13:21:04 TRACE nova.compute.manager [instance: db62a0bf-06db-47c6-b722-efb972a5322c]     self.gen.next()
>2012-09-26 13:21:04 TRACE nova.compute.manager [instance: db62a0bf-06db-47c6-b722-efb972a5322c]   File "/usr/lib/python2.7/site-packages/nova/exception.py", line 92, in wrapped
>2012-09-26 13:21:04 TRACE nova.compute.manager [instance: db62a0bf-06db-47c6-b722-efb972a5322c]     return f(*args, **kw)
>2012-09-26 13:21:04 TRACE nova.compute.manager [instance: db62a0bf-06db-47c6-b722-efb972a5322c]   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1061, in spawn
>2012-09-26 13:21:04 TRACE nova.compute.manager [instance: db62a0bf-06db-47c6-b722-efb972a5322c]     block_device_info)
>2012-09-26 13:21:04 TRACE nova.compute.manager [instance: db62a0bf-06db-47c6-b722-efb972a5322c]   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1887, in _create_domain_and_network
>2012-09-26 13:21:04 TRACE nova.compute.manager [instance: db62a0bf-06db-47c6-b722-efb972a5322c]     domain = self._create_domain(xml)
>2012-09-26 13:21:04 TRACE nova.compute.manager [instance: db62a0bf-06db-47c6-b722-efb972a5322c]   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1866, in _create_domain
>2012-09-26 13:21:04 TRACE nova.compute.manager [instance: db62a0bf-06db-47c6-b722-efb972a5322c]     domain.createWithFlags(launch_flags)
>2012-09-26 13:21:04 TRACE nova.compute.manager [instance: db62a0bf-06db-47c6-b722-efb972a5322c]   File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 187, in doit
>2012-09-26 13:21:04 TRACE nova.compute.manager [instance: db62a0bf-06db-47c6-b722-efb972a5322c]     result = proxy_call(self._autowrap, f, *args, **kwargs)
>2012-09-26 13:21:04 TRACE nova.compute.manager [instance: db62a0bf-06db-47c6-b722-efb972a5322c]   File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 147, in proxy_call
>2012-09-26 13:21:04 TRACE nova.compute.manager [instance: db62a0bf-06db-47c6-b722-efb972a5322c]     rv = execute(f,*args,**kwargs)
>2012-09-26 13:21:04 TRACE nova.compute.manager [instance: db62a0bf-06db-47c6-b722-efb972a5322c]   File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 76, in tworker
>2012-09-26 13:21:04 TRACE nova.compute.manager [instance: db62a0bf-06db-47c6-b722-efb972a5322c]     rv = meth(*args,**kwargs)
>2012-09-26 13:21:04 TRACE nova.compute.manager [instance: db62a0bf-06db-47c6-b722-efb972a5322c]   File "/usr/lib64/python2.7/site-packages/libvirt.py", line 708, in createWithFlags
>2012-09-26 13:21:04 TRACE nova.compute.manager [instance: db62a0bf-06db-47c6-b722-efb972a5322c]     if ret == -1: raise libvirtError ('virDomainCreateWithFlags() failed', dom=self)
>2012-09-26 13:21:04 TRACE nova.compute.manager [instance: db62a0bf-06db-47c6-b722-efb972a5322c] libvirtError: internal error Process exited while reading console log output: Warning: option deprecated, use lost_tick_policy property of kvm-pit instead.
>2012-09-26 13:21:04 TRACE nova.compute.manager [instance: db62a0bf-06db-47c6-b722-efb972a5322c] char device redirected to /dev/pts/1
>2012-09-26 13:21:04 TRACE nova.compute.manager [instance: db62a0bf-06db-47c6-b722-efb972a5322c] Could not access KVM kernel module: Permission denied
>2012-09-26 13:21:04 TRACE nova.compute.manager [instance: db62a0bf-06db-47c6-b722-efb972a5322c] failed to initialize KVM: Permission denied
>2012-09-26 13:21:04 TRACE nova.compute.manager [instance: db62a0bf-06db-47c6-b722-efb972a5322c] No accelerator found!
>2012-09-26 13:21:04 TRACE nova.compute.manager [instance: db62a0bf-06db-47c6-b722-efb972a5322c] 
>2012-09-26 13:21:04 TRACE nova.compute.manager [instance: db62a0bf-06db-47c6-b722-efb972a5322c] 
>2012-09-26 13:21:04 INFO nova.compute.resource_tracker [req-cccfc114-330e-472f-9d0f-75cc18350a95 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Aborting claim: [Claim db62a0bf-06db-47c6-b722-efb972a5322c: 512 MB memory, 0 GB disk, 1 VCPUS]
>2012-09-26 13:21:04 ERROR nova.compute.manager [req-cccfc114-330e-472f-9d0f-75cc18350a95 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: db62a0bf-06db-47c6-b722-efb972a5322c] Build error: ['Traceback (most recent call last):\n', '  File "/usr/lib/python2.7/site-packages/nova/compute/manager.py", line 496, in _run_instance\n    injected_files, admin_password)\n', '  File "/usr/lib/python2.7/site-packages/nova/compute/manager.py", line 743, in _spawn\n    block_device_info)\n', '  File "/usr/lib/python2.7/site-packages/nova/exception.py", line 117, in wrapped\n    temp_level, payload)\n', '  File "/usr/lib64/python2.7/contextlib.py", line 24, in __exit__\n    self.gen.next()\n', '  File "/usr/lib/python2.7/site-packages/nova/exception.py", line 92, in wrapped\n    return f(*args, **kw)\n', '  File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1061, in spawn\n    block_device_info)\n', '  File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1887, in _create_domain_and_network\n    domain = self._create_domain(xml)\n', '  File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1866, in _create_domain\n    domain.createWithFlags(launch_flags)\n', '  File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 187, in doit\n    result = proxy_call(self._autowrap, f, *args, **kwargs)\n', '  File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 147, in proxy_call\n    rv = execute(f,*args,**kwargs)\n', '  File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 76, in tworker\n    rv = meth(*args,**kwargs)\n', '  File "/usr/lib64/python2.7/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: Warning: option deprecated, use lost_tick_policy property of kvm-pit instead.\nchar device redirected to /dev/pts/1\nCould not access KVM kernel module: Permission denied\nfailed to initialize KVM: Permission denied\nNo accelerator found!\n\n']
>2012-09-26 13:21:07 ERROR nova.compute.manager [req-5ca15aec-9d87-4031-926f-0e298ce82e40 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 0fc53d09-f6ea-4e00-92cb-bec43035de14] Instance failed to spawn
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: 0fc53d09-f6ea-4e00-92cb-bec43035de14] Traceback (most recent call last):
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: 0fc53d09-f6ea-4e00-92cb-bec43035de14]   File "/usr/lib/python2.7/site-packages/nova/compute/manager.py", line 743, in _spawn
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: 0fc53d09-f6ea-4e00-92cb-bec43035de14]     block_device_info)
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: 0fc53d09-f6ea-4e00-92cb-bec43035de14]   File "/usr/lib/python2.7/site-packages/nova/exception.py", line 117, in wrapped
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: 0fc53d09-f6ea-4e00-92cb-bec43035de14]     temp_level, payload)
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: 0fc53d09-f6ea-4e00-92cb-bec43035de14]   File "/usr/lib64/python2.7/contextlib.py", line 24, in __exit__
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: 0fc53d09-f6ea-4e00-92cb-bec43035de14]     self.gen.next()
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: 0fc53d09-f6ea-4e00-92cb-bec43035de14]   File "/usr/lib/python2.7/site-packages/nova/exception.py", line 92, in wrapped
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: 0fc53d09-f6ea-4e00-92cb-bec43035de14]     return f(*args, **kw)
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: 0fc53d09-f6ea-4e00-92cb-bec43035de14]   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1061, in spawn
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: 0fc53d09-f6ea-4e00-92cb-bec43035de14]     block_device_info)
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: 0fc53d09-f6ea-4e00-92cb-bec43035de14]   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1887, in _create_domain_and_network
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: 0fc53d09-f6ea-4e00-92cb-bec43035de14]     domain = self._create_domain(xml)
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: 0fc53d09-f6ea-4e00-92cb-bec43035de14]   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1866, in _create_domain
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: 0fc53d09-f6ea-4e00-92cb-bec43035de14]     domain.createWithFlags(launch_flags)
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: 0fc53d09-f6ea-4e00-92cb-bec43035de14]   File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 187, in doit
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: 0fc53d09-f6ea-4e00-92cb-bec43035de14]     result = proxy_call(self._autowrap, f, *args, **kwargs)
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: 0fc53d09-f6ea-4e00-92cb-bec43035de14]   File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 147, in proxy_call
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: 0fc53d09-f6ea-4e00-92cb-bec43035de14]     rv = execute(f,*args,**kwargs)
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: 0fc53d09-f6ea-4e00-92cb-bec43035de14]   File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 76, in tworker
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: 0fc53d09-f6ea-4e00-92cb-bec43035de14]     rv = meth(*args,**kwargs)
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: 0fc53d09-f6ea-4e00-92cb-bec43035de14]   File "/usr/lib64/python2.7/site-packages/libvirt.py", line 708, in createWithFlags
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: 0fc53d09-f6ea-4e00-92cb-bec43035de14]     if ret == -1: raise libvirtError ('virDomainCreateWithFlags() failed', dom=self)
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: 0fc53d09-f6ea-4e00-92cb-bec43035de14] libvirtError: internal error Process exited while reading console log output: Warning: option deprecated, use lost_tick_policy property of kvm-pit instead.
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: 0fc53d09-f6ea-4e00-92cb-bec43035de14] char device redirected to /dev/pts/1
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: 0fc53d09-f6ea-4e00-92cb-bec43035de14] Could not access KVM kernel module: Permission denied
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: 0fc53d09-f6ea-4e00-92cb-bec43035de14] failed to initialize KVM: Permission denied
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: 0fc53d09-f6ea-4e00-92cb-bec43035de14] No accelerator found!
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: 0fc53d09-f6ea-4e00-92cb-bec43035de14] 
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: 0fc53d09-f6ea-4e00-92cb-bec43035de14] 
>2012-09-26 13:21:07 INFO nova.compute.resource_tracker [req-5ca15aec-9d87-4031-926f-0e298ce82e40 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Aborting claim: [Claim 0fc53d09-f6ea-4e00-92cb-bec43035de14: 512 MB memory, 0 GB disk, 1 VCPUS]
>2012-09-26 13:21:07 ERROR nova.compute.manager [req-5ca15aec-9d87-4031-926f-0e298ce82e40 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 0fc53d09-f6ea-4e00-92cb-bec43035de14] Build error: ['Traceback (most recent call last):\n', '  File "/usr/lib/python2.7/site-packages/nova/compute/manager.py", line 496, in _run_instance\n    injected_files, admin_password)\n', '  File "/usr/lib/python2.7/site-packages/nova/compute/manager.py", line 743, in _spawn\n    block_device_info)\n', '  File "/usr/lib/python2.7/site-packages/nova/exception.py", line 117, in wrapped\n    temp_level, payload)\n', '  File "/usr/lib64/python2.7/contextlib.py", line 24, in __exit__\n    self.gen.next()\n', '  File "/usr/lib/python2.7/site-packages/nova/exception.py", line 92, in wrapped\n    return f(*args, **kw)\n', '  File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1061, in spawn\n    block_device_info)\n', '  File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1887, in _create_domain_and_network\n    domain = self._create_domain(xml)\n', '  File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1866, in _create_domain\n    domain.createWithFlags(launch_flags)\n', '  File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 187, in doit\n    result = proxy_call(self._autowrap, f, *args, **kwargs)\n', '  File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 147, in proxy_call\n    rv = execute(f,*args,**kwargs)\n', '  File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 76, in tworker\n    rv = meth(*args,**kwargs)\n', '  File "/usr/lib64/python2.7/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: Warning: option deprecated, use lost_tick_policy property of kvm-pit instead.\nchar device redirected to /dev/pts/1\nCould not access KVM kernel module: Permission denied\nfailed to initialize KVM: Permission denied\nNo accelerator found!\n\n']
>2012-09-26 13:21:07 ERROR nova.compute.manager [req-fea0ef11-58cd-40f8-82ac-b96fd1a00bc6 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: cbf0df21-22bb-4d9d-b31c-7e0b202028c4] Instance failed to spawn
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: cbf0df21-22bb-4d9d-b31c-7e0b202028c4] Traceback (most recent call last):
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: cbf0df21-22bb-4d9d-b31c-7e0b202028c4]   File "/usr/lib/python2.7/site-packages/nova/compute/manager.py", line 743, in _spawn
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: cbf0df21-22bb-4d9d-b31c-7e0b202028c4]     block_device_info)
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: cbf0df21-22bb-4d9d-b31c-7e0b202028c4]   File "/usr/lib/python2.7/site-packages/nova/exception.py", line 117, in wrapped
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: cbf0df21-22bb-4d9d-b31c-7e0b202028c4]     temp_level, payload)
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: cbf0df21-22bb-4d9d-b31c-7e0b202028c4]   File "/usr/lib64/python2.7/contextlib.py", line 24, in __exit__
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: cbf0df21-22bb-4d9d-b31c-7e0b202028c4]     self.gen.next()
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: cbf0df21-22bb-4d9d-b31c-7e0b202028c4]   File "/usr/lib/python2.7/site-packages/nova/exception.py", line 92, in wrapped
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: cbf0df21-22bb-4d9d-b31c-7e0b202028c4]     return f(*args, **kw)
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: cbf0df21-22bb-4d9d-b31c-7e0b202028c4]   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1061, in spawn
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: cbf0df21-22bb-4d9d-b31c-7e0b202028c4]     block_device_info)
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: cbf0df21-22bb-4d9d-b31c-7e0b202028c4]   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1887, in _create_domain_and_network
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: cbf0df21-22bb-4d9d-b31c-7e0b202028c4]     domain = self._create_domain(xml)
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: cbf0df21-22bb-4d9d-b31c-7e0b202028c4]   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1866, in _create_domain
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: cbf0df21-22bb-4d9d-b31c-7e0b202028c4]     domain.createWithFlags(launch_flags)
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: cbf0df21-22bb-4d9d-b31c-7e0b202028c4]   File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 187, in doit
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: cbf0df21-22bb-4d9d-b31c-7e0b202028c4]     result = proxy_call(self._autowrap, f, *args, **kwargs)
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: cbf0df21-22bb-4d9d-b31c-7e0b202028c4]   File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 147, in proxy_call
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: cbf0df21-22bb-4d9d-b31c-7e0b202028c4]     rv = execute(f,*args,**kwargs)
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: cbf0df21-22bb-4d9d-b31c-7e0b202028c4]   File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 76, in tworker
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: cbf0df21-22bb-4d9d-b31c-7e0b202028c4]     rv = meth(*args,**kwargs)
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: cbf0df21-22bb-4d9d-b31c-7e0b202028c4]   File "/usr/lib64/python2.7/site-packages/libvirt.py", line 708, in createWithFlags
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: cbf0df21-22bb-4d9d-b31c-7e0b202028c4]     if ret == -1: raise libvirtError ('virDomainCreateWithFlags() failed', dom=self)
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: cbf0df21-22bb-4d9d-b31c-7e0b202028c4] libvirtError: internal error Process exited while reading console log output: Warning: option deprecated, use lost_tick_policy property of kvm-pit instead.
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: cbf0df21-22bb-4d9d-b31c-7e0b202028c4] char device redirected to /dev/pts/1
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: cbf0df21-22bb-4d9d-b31c-7e0b202028c4] Could not access KVM kernel module: Permission denied
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: cbf0df21-22bb-4d9d-b31c-7e0b202028c4] failed to initialize KVM: Permission denied
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: cbf0df21-22bb-4d9d-b31c-7e0b202028c4] No accelerator found!
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: cbf0df21-22bb-4d9d-b31c-7e0b202028c4] 
>2012-09-26 13:21:07 TRACE nova.compute.manager [instance: cbf0df21-22bb-4d9d-b31c-7e0b202028c4] 
>2012-09-26 13:21:07 INFO nova.compute.resource_tracker [req-fea0ef11-58cd-40f8-82ac-b96fd1a00bc6 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Aborting claim: [Claim cbf0df21-22bb-4d9d-b31c-7e0b202028c4: 512 MB memory, 0 GB disk, 1 VCPUS]
>2012-09-26 13:21:07 ERROR nova.compute.manager [req-fea0ef11-58cd-40f8-82ac-b96fd1a00bc6 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: cbf0df21-22bb-4d9d-b31c-7e0b202028c4] Build error: ['Traceback (most recent call last):\n', '  File "/usr/lib/python2.7/site-packages/nova/compute/manager.py", line 496, in _run_instance\n    injected_files, admin_password)\n', '  File "/usr/lib/python2.7/site-packages/nova/compute/manager.py", line 743, in _spawn\n    block_device_info)\n', '  File "/usr/lib/python2.7/site-packages/nova/exception.py", line 117, in wrapped\n    temp_level, payload)\n', '  File "/usr/lib64/python2.7/contextlib.py", line 24, in __exit__\n    self.gen.next()\n', '  File "/usr/lib/python2.7/site-packages/nova/exception.py", line 92, in wrapped\n    return f(*args, **kw)\n', '  File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1061, in spawn\n    block_device_info)\n', '  File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1887, in _create_domain_and_network\n    domain = self._create_domain(xml)\n', '  File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1866, in _create_domain\n    domain.createWithFlags(launch_flags)\n', '  File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 187, in doit\n    result = proxy_call(self._autowrap, f, *args, **kwargs)\n', '  File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 147, in proxy_call\n    rv = execute(f,*args,**kwargs)\n', '  File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 76, in tworker\n    rv = meth(*args,**kwargs)\n', '  File "/usr/lib64/python2.7/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: Warning: option deprecated, use lost_tick_policy property of kvm-pit instead.\nchar device redirected to /dev/pts/1\nCould not access KVM kernel module: Permission denied\nfailed to initialize KVM: Permission denied\nNo accelerator found!\n\n']
>2012-09-26 13:21:37 WARNING nova.compute.manager [-] Found 4 in the database and 0 on the hypervisor.
>2012-09-26 13:21:41 INFO nova.compute.manager [-] Updating bandwidth usage cache
>2012-09-26 13:21:51 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 5266
>2012-09-26 13:21:51 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 13:21:51 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 0
>2012-09-26 13:21:51 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 13:21:52 INFO nova.compute.manager [-] Updating host status
>2012-09-26 13:23:12 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 5266
>2012-09-26 13:23:12 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 13:23:12 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 0
>2012-09-26 13:23:12 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 13:23:56 AUDIT nova.compute.manager [req-7500385f-43ae-4e93-97b2-986f4bec2ca3 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: cb47147e-b00a-4586-ba51-fb4f8eacbbc6] Terminating instance
>2012-09-26 13:23:59 INFO nova.virt.libvirt.driver [-] [instance: cb47147e-b00a-4586-ba51-fb4f8eacbbc6] Instance destroyed successfully.
>2012-09-26 13:24:01 INFO nova.virt.libvirt.driver [req-7500385f-43ae-4e93-97b2-986f4bec2ca3 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: cb47147e-b00a-4586-ba51-fb4f8eacbbc6] Deleting instance files /var/lib/nova/instances/instance-00000001
>2012-09-26 13:24:05 AUDIT nova.compute.manager [req-fdaac0d7-f1cc-480b-8dce-324b8288a188 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 0fc53d09-f6ea-4e00-92cb-bec43035de14] Terminating instance
>2012-09-26 13:24:08 INFO nova.virt.libvirt.driver [-] [instance: 0fc53d09-f6ea-4e00-92cb-bec43035de14] Instance destroyed successfully.
>2012-09-26 13:24:10 INFO nova.virt.libvirt.driver [req-fdaac0d7-f1cc-480b-8dce-324b8288a188 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 0fc53d09-f6ea-4e00-92cb-bec43035de14] Deleting instance files /var/lib/nova/instances/instance-00000002
>2012-09-26 13:24:11 AUDIT nova.compute.manager [req-3357f7e6-0098-4c51-a780-f15aa2750dab 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: db62a0bf-06db-47c6-b722-efb972a5322c] Terminating instance
>2012-09-26 13:24:14 INFO nova.virt.libvirt.driver [-] [instance: db62a0bf-06db-47c6-b722-efb972a5322c] Instance destroyed successfully.
>2012-09-26 13:24:17 INFO nova.virt.libvirt.driver [req-3357f7e6-0098-4c51-a780-f15aa2750dab 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: db62a0bf-06db-47c6-b722-efb972a5322c] Deleting instance files /var/lib/nova/instances/instance-00000003
>2012-09-26 13:24:23 AUDIT nova.compute.manager [req-705525a8-0e08-4a24-8dbd-4bc9457e2b83 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: cbf0df21-22bb-4d9d-b31c-7e0b202028c4] Terminating instance
>2012-09-26 13:24:25 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 13:24:25 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 13:24:25 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 3
>2012-09-26 13:24:25 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 13:24:26 INFO nova.compute.manager [-] Updating host status
>2012-09-26 13:24:27 INFO nova.virt.libvirt.driver [-] [instance: cbf0df21-22bb-4d9d-b31c-7e0b202028c4] Instance destroyed successfully.
>2012-09-26 13:24:31 INFO nova.virt.libvirt.driver [req-705525a8-0e08-4a24-8dbd-4bc9457e2b83 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: cbf0df21-22bb-4d9d-b31c-7e0b202028c4] Deleting instance files /var/lib/nova/instances/instance-00000004
>2012-09-26 13:24:48 AUDIT nova.compute.manager [req-4c125b4c-5542-4577-8358-d0e118cd5c95 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 8b60f052-5238-43ea-a50c-670b488685f8] Starting instance...
>2012-09-26 13:24:48 AUDIT nova.compute.resource_tracker [req-4c125b4c-5542-4577-8358-d0e118cd5c95 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Attempting claim: memory 512 MB, disk 0 GB, VCPUs 1
>2012-09-26 13:24:48 AUDIT nova.compute.resource_tracker [req-4c125b4c-5542-4577-8358-d0e118cd5c95 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Total memory: 7826 MB, used: 0 MB, free: 0 MB
>2012-09-26 13:24:48 AUDIT nova.compute.resource_tracker [req-4c125b4c-5542-4577-8358-d0e118cd5c95 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Memory limit: 11739 MB, free: 11739 MB
>2012-09-26 13:24:48 AUDIT nova.compute.resource_tracker [req-4c125b4c-5542-4577-8358-d0e118cd5c95 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Total disk: 49 GB, used: 0 GB, free: 49 GB
>2012-09-26 13:24:48 AUDIT nova.compute.resource_tracker [req-4c125b4c-5542-4577-8358-d0e118cd5c95 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Disk limit not specified, defaulting to unlimited
>2012-09-26 13:24:48 AUDIT nova.compute.resource_tracker [req-4c125b4c-5542-4577-8358-d0e118cd5c95 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Total VCPUs: 4, used: 0
>2012-09-26 13:24:48 AUDIT nova.compute.resource_tracker [req-4c125b4c-5542-4577-8358-d0e118cd5c95 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] VCPU limit not specified, defaulting to unlimited
>2012-09-26 13:24:51 INFO nova.virt.libvirt.driver [req-4c125b4c-5542-4577-8358-d0e118cd5c95 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 8b60f052-5238-43ea-a50c-670b488685f8] Creating image
>2012-09-26 13:24:51 INFO nova.virt.libvirt.firewall [req-4c125b4c-5542-4577-8358-d0e118cd5c95 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 8b60f052-5238-43ea-a50c-670b488685f8] Called setup_basic_filtering in nwfilter
>2012-09-26 13:24:51 INFO nova.virt.libvirt.firewall [req-4c125b4c-5542-4577-8358-d0e118cd5c95 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 8b60f052-5238-43ea-a50c-670b488685f8] Ensuring static filters
>2012-09-26 13:24:53 AUDIT nova.compute.manager [req-39bea5fa-01cf-4189-a913-585c6cd5a163 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 157e5cc4-86b6-497c-aed6-24aa5c6895e2] Starting instance...
>2012-09-26 13:24:54 AUDIT nova.compute.resource_tracker [req-39bea5fa-01cf-4189-a913-585c6cd5a163 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Attempting claim: memory 512 MB, disk 0 GB, VCPUs 1
>2012-09-26 13:24:54 AUDIT nova.compute.resource_tracker [req-39bea5fa-01cf-4189-a913-585c6cd5a163 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Total memory: 7826 MB, used: 512 MB, free: 0 MB
>2012-09-26 13:24:54 AUDIT nova.compute.resource_tracker [req-39bea5fa-01cf-4189-a913-585c6cd5a163 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Memory limit: 11739 MB, free: 11227 MB
>2012-09-26 13:24:54 AUDIT nova.compute.resource_tracker [req-39bea5fa-01cf-4189-a913-585c6cd5a163 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Total disk: 49 GB, used: 0 GB, free: 49 GB
>2012-09-26 13:24:54 AUDIT nova.compute.resource_tracker [req-39bea5fa-01cf-4189-a913-585c6cd5a163 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Disk limit not specified, defaulting to unlimited
>2012-09-26 13:24:54 AUDIT nova.compute.resource_tracker [req-39bea5fa-01cf-4189-a913-585c6cd5a163 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Total VCPUs: 4, used: 1
>2012-09-26 13:24:54 AUDIT nova.compute.resource_tracker [req-39bea5fa-01cf-4189-a913-585c6cd5a163 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] VCPU limit not specified, defaulting to unlimited
>2012-09-26 13:24:56 INFO nova.virt.libvirt.driver [req-39bea5fa-01cf-4189-a913-585c6cd5a163 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 157e5cc4-86b6-497c-aed6-24aa5c6895e2] Creating image
>2012-09-26 13:24:56 INFO nova.virt.libvirt.firewall [req-39bea5fa-01cf-4189-a913-585c6cd5a163 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 157e5cc4-86b6-497c-aed6-24aa5c6895e2] Called setup_basic_filtering in nwfilter
>2012-09-26 13:24:56 INFO nova.virt.libvirt.firewall [req-39bea5fa-01cf-4189-a913-585c6cd5a163 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 157e5cc4-86b6-497c-aed6-24aa5c6895e2] Ensuring static filters
>2012-09-26 13:24:57 AUDIT nova.compute.manager [req-875eb396-f9a9-423b-b250-a7b9ac927ee3 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 289c51cd-f9a7-448c-a732-72617106183e] Starting instance...
>2012-09-26 13:24:58 AUDIT nova.compute.resource_tracker [req-875eb396-f9a9-423b-b250-a7b9ac927ee3 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Attempting claim: memory 512 MB, disk 0 GB, VCPUs 1
>2012-09-26 13:24:58 AUDIT nova.compute.resource_tracker [req-875eb396-f9a9-423b-b250-a7b9ac927ee3 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Total memory: 7826 MB, used: 1024 MB, free: 0 MB
>2012-09-26 13:24:58 AUDIT nova.compute.resource_tracker [req-875eb396-f9a9-423b-b250-a7b9ac927ee3 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Memory limit: 11739 MB, free: 10715 MB
>2012-09-26 13:24:58 AUDIT nova.compute.resource_tracker [req-875eb396-f9a9-423b-b250-a7b9ac927ee3 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Total disk: 49 GB, used: 0 GB, free: 49 GB
>2012-09-26 13:24:58 AUDIT nova.compute.resource_tracker [req-875eb396-f9a9-423b-b250-a7b9ac927ee3 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Disk limit not specified, defaulting to unlimited
>2012-09-26 13:24:58 AUDIT nova.compute.resource_tracker [req-875eb396-f9a9-423b-b250-a7b9ac927ee3 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Total VCPUs: 4, used: 2
>2012-09-26 13:24:58 AUDIT nova.compute.resource_tracker [req-875eb396-f9a9-423b-b250-a7b9ac927ee3 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] VCPU limit not specified, defaulting to unlimited
>2012-09-26 13:25:01 AUDIT nova.compute.manager [req-35f080a6-9c76-42c9-8641-b1fd0ac8b692 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 74ef539f-816c-4045-9ff1-5cc063371394] Starting instance...
>2012-09-26 13:25:01 ERROR nova.compute.manager [req-4c125b4c-5542-4577-8358-d0e118cd5c95 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 8b60f052-5238-43ea-a50c-670b488685f8] Instance failed to spawn
>2012-09-26 13:25:01 TRACE nova.compute.manager [instance: 8b60f052-5238-43ea-a50c-670b488685f8] Traceback (most recent call last):
>2012-09-26 13:25:01 TRACE nova.compute.manager [instance: 8b60f052-5238-43ea-a50c-670b488685f8]   File "/usr/lib/python2.7/site-packages/nova/compute/manager.py", line 743, in _spawn
>2012-09-26 13:25:01 TRACE nova.compute.manager [instance: 8b60f052-5238-43ea-a50c-670b488685f8]     block_device_info)
>2012-09-26 13:25:01 TRACE nova.compute.manager [instance: 8b60f052-5238-43ea-a50c-670b488685f8]   File "/usr/lib/python2.7/site-packages/nova/exception.py", line 117, in wrapped
>2012-09-26 13:25:01 TRACE nova.compute.manager [instance: 8b60f052-5238-43ea-a50c-670b488685f8]     temp_level, payload)
>2012-09-26 13:25:01 TRACE nova.compute.manager [instance: 8b60f052-5238-43ea-a50c-670b488685f8]   File "/usr/lib64/python2.7/contextlib.py", line 24, in __exit__
>2012-09-26 13:25:01 TRACE nova.compute.manager [instance: 8b60f052-5238-43ea-a50c-670b488685f8]     self.gen.next()
>2012-09-26 13:25:01 TRACE nova.compute.manager [instance: 8b60f052-5238-43ea-a50c-670b488685f8]   File "/usr/lib/python2.7/site-packages/nova/exception.py", line 92, in wrapped
>2012-09-26 13:25:01 TRACE nova.compute.manager [instance: 8b60f052-5238-43ea-a50c-670b488685f8]     return f(*args, **kw)
>2012-09-26 13:25:01 TRACE nova.compute.manager [instance: 8b60f052-5238-43ea-a50c-670b488685f8]   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1061, in spawn
>2012-09-26 13:25:01 TRACE nova.compute.manager [instance: 8b60f052-5238-43ea-a50c-670b488685f8]     block_device_info)
>2012-09-26 13:25:01 TRACE nova.compute.manager [instance: 8b60f052-5238-43ea-a50c-670b488685f8]   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1887, in _create_domain_and_network
>2012-09-26 13:25:01 TRACE nova.compute.manager [instance: 8b60f052-5238-43ea-a50c-670b488685f8]     domain = self._create_domain(xml)
>2012-09-26 13:25:01 TRACE nova.compute.manager [instance: 8b60f052-5238-43ea-a50c-670b488685f8]   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1866, in _create_domain
>2012-09-26 13:25:01 TRACE nova.compute.manager [instance: 8b60f052-5238-43ea-a50c-670b488685f8]     domain.createWithFlags(launch_flags)
>2012-09-26 13:25:01 TRACE nova.compute.manager [instance: 8b60f052-5238-43ea-a50c-670b488685f8]   File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 187, in doit
>2012-09-26 13:25:01 TRACE nova.compute.manager [instance: 8b60f052-5238-43ea-a50c-670b488685f8]     result = proxy_call(self._autowrap, f, *args, **kwargs)
>2012-09-26 13:25:01 TRACE nova.compute.manager [instance: 8b60f052-5238-43ea-a50c-670b488685f8]   File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 147, in proxy_call
>2012-09-26 13:25:01 TRACE nova.compute.manager [instance: 8b60f052-5238-43ea-a50c-670b488685f8]     rv = execute(f,*args,**kwargs)
>2012-09-26 13:25:01 TRACE nova.compute.manager [instance: 8b60f052-5238-43ea-a50c-670b488685f8]   File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 76, in tworker
>2012-09-26 13:25:01 TRACE nova.compute.manager [instance: 8b60f052-5238-43ea-a50c-670b488685f8]     rv = meth(*args,**kwargs)
>2012-09-26 13:25:01 TRACE nova.compute.manager [instance: 8b60f052-5238-43ea-a50c-670b488685f8]   File "/usr/lib64/python2.7/site-packages/libvirt.py", line 708, in createWithFlags
>2012-09-26 13:25:01 TRACE nova.compute.manager [instance: 8b60f052-5238-43ea-a50c-670b488685f8]     if ret == -1: raise libvirtError ('virDomainCreateWithFlags() failed', dom=self)
>2012-09-26 13:25:01 TRACE nova.compute.manager [instance: 8b60f052-5238-43ea-a50c-670b488685f8] libvirtError: internal error Process exited while reading console log output: Warning: option deprecated, use lost_tick_policy property of kvm-pit instead.
>2012-09-26 13:25:01 TRACE nova.compute.manager [instance: 8b60f052-5238-43ea-a50c-670b488685f8] char device redirected to /dev/pts/1
>2012-09-26 13:25:01 TRACE nova.compute.manager [instance: 8b60f052-5238-43ea-a50c-670b488685f8] Could not access KVM kernel module: Permission denied
>2012-09-26 13:25:01 TRACE nova.compute.manager [instance: 8b60f052-5238-43ea-a50c-670b488685f8] failed to initialize KVM: Permission denied
>2012-09-26 13:25:01 TRACE nova.compute.manager [instance: 8b60f052-5238-43ea-a50c-670b488685f8] No accelerator found!
>2012-09-26 13:25:01 TRACE nova.compute.manager [instance: 8b60f052-5238-43ea-a50c-670b488685f8] 
>2012-09-26 13:25:01 TRACE nova.compute.manager [instance: 8b60f052-5238-43ea-a50c-670b488685f8] 
>2012-09-26 13:25:01 INFO nova.compute.resource_tracker [req-4c125b4c-5542-4577-8358-d0e118cd5c95 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Aborting claim: [Claim 8b60f052-5238-43ea-a50c-670b488685f8: 512 MB memory, 0 GB disk, 1 VCPUS]
>2012-09-26 13:25:01 INFO nova.virt.libvirt.driver [req-875eb396-f9a9-423b-b250-a7b9ac927ee3 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 289c51cd-f9a7-448c-a732-72617106183e] Creating image
>2012-09-26 13:25:02 ERROR nova.compute.manager [req-4c125b4c-5542-4577-8358-d0e118cd5c95 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 8b60f052-5238-43ea-a50c-670b488685f8] Build error: ['Traceback (most recent call last):\n', '  File "/usr/lib/python2.7/site-packages/nova/compute/manager.py", line 496, in _run_instance\n    injected_files, admin_password)\n', '  File "/usr/lib/python2.7/site-packages/nova/compute/manager.py", line 743, in _spawn\n    block_device_info)\n', '  File "/usr/lib/python2.7/site-packages/nova/exception.py", line 117, in wrapped\n    temp_level, payload)\n', '  File "/usr/lib64/python2.7/contextlib.py", line 24, in __exit__\n    self.gen.next()\n', '  File "/usr/lib/python2.7/site-packages/nova/exception.py", line 92, in wrapped\n    return f(*args, **kw)\n', '  File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1061, in spawn\n    block_device_info)\n', '  File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1887, in _create_domain_and_network\n    domain = self._create_domain(xml)\n', '  File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1866, in _create_domain\n    domain.createWithFlags(launch_flags)\n', '  File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 187, in doit\n    result = proxy_call(self._autowrap, f, *args, **kwargs)\n', '  File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 147, in proxy_call\n    rv = execute(f,*args,**kwargs)\n', '  File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 76, in tworker\n    rv = meth(*args,**kwargs)\n', '  File "/usr/lib64/python2.7/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: Warning: option deprecated, use lost_tick_policy property of kvm-pit instead.\nchar device redirected to /dev/pts/1\nCould not access KVM kernel module: Permission denied\nfailed to initialize KVM: Permission denied\nNo accelerator found!\n\n']
>2012-09-26 13:25:02 INFO nova.virt.libvirt.firewall [req-875eb396-f9a9-423b-b250-a7b9ac927ee3 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 289c51cd-f9a7-448c-a732-72617106183e] Called setup_basic_filtering in nwfilter
>2012-09-26 13:25:02 INFO nova.virt.libvirt.firewall [req-875eb396-f9a9-423b-b250-a7b9ac927ee3 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 289c51cd-f9a7-448c-a732-72617106183e] Ensuring static filters
>2012-09-26 13:25:02 AUDIT nova.compute.resource_tracker [req-35f080a6-9c76-42c9-8641-b1fd0ac8b692 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Attempting claim: memory 512 MB, disk 0 GB, VCPUs 1
>2012-09-26 13:25:02 AUDIT nova.compute.resource_tracker [req-35f080a6-9c76-42c9-8641-b1fd0ac8b692 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Total memory: 7826 MB, used: 1024 MB, free: 0 MB
>2012-09-26 13:25:02 AUDIT nova.compute.resource_tracker [req-35f080a6-9c76-42c9-8641-b1fd0ac8b692 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Memory limit: 11739 MB, free: 10715 MB
>2012-09-26 13:25:02 AUDIT nova.compute.resource_tracker [req-35f080a6-9c76-42c9-8641-b1fd0ac8b692 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Total disk: 49 GB, used: 0 GB, free: 49 GB
>2012-09-26 13:25:02 AUDIT nova.compute.resource_tracker [req-35f080a6-9c76-42c9-8641-b1fd0ac8b692 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Disk limit not specified, defaulting to unlimited
>2012-09-26 13:25:02 AUDIT nova.compute.resource_tracker [req-35f080a6-9c76-42c9-8641-b1fd0ac8b692 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Total VCPUs: 4, used: 2
>2012-09-26 13:25:02 AUDIT nova.compute.resource_tracker [req-35f080a6-9c76-42c9-8641-b1fd0ac8b692 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] VCPU limit not specified, defaulting to unlimited
>2012-09-26 13:25:05 INFO nova.virt.libvirt.driver [req-35f080a6-9c76-42c9-8641-b1fd0ac8b692 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 74ef539f-816c-4045-9ff1-5cc063371394] Creating image
>2012-09-26 13:25:05 INFO nova.virt.libvirt.firewall [req-35f080a6-9c76-42c9-8641-b1fd0ac8b692 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 74ef539f-816c-4045-9ff1-5cc063371394] Called setup_basic_filtering in nwfilter
>2012-09-26 13:25:05 INFO nova.virt.libvirt.firewall [req-35f080a6-9c76-42c9-8641-b1fd0ac8b692 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 74ef539f-816c-4045-9ff1-5cc063371394] Ensuring static filters
>2012-09-26 13:25:08 ERROR nova.compute.manager [req-39bea5fa-01cf-4189-a913-585c6cd5a163 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 157e5cc4-86b6-497c-aed6-24aa5c6895e2] Instance failed to spawn
>2012-09-26 13:25:08 TRACE nova.compute.manager [instance: 157e5cc4-86b6-497c-aed6-24aa5c6895e2] Traceback (most recent call last):
>2012-09-26 13:25:08 TRACE nova.compute.manager [instance: 157e5cc4-86b6-497c-aed6-24aa5c6895e2]   File "/usr/lib/python2.7/site-packages/nova/compute/manager.py", line 743, in _spawn
>2012-09-26 13:25:08 TRACE nova.compute.manager [instance: 157e5cc4-86b6-497c-aed6-24aa5c6895e2]     block_device_info)
>2012-09-26 13:25:08 TRACE nova.compute.manager [instance: 157e5cc4-86b6-497c-aed6-24aa5c6895e2]   File "/usr/lib/python2.7/site-packages/nova/exception.py", line 117, in wrapped
>2012-09-26 13:25:08 TRACE nova.compute.manager [instance: 157e5cc4-86b6-497c-aed6-24aa5c6895e2]     temp_level, payload)
>2012-09-26 13:25:08 TRACE nova.compute.manager [instance: 157e5cc4-86b6-497c-aed6-24aa5c6895e2]   File "/usr/lib64/python2.7/contextlib.py", line 24, in __exit__
>2012-09-26 13:25:08 TRACE nova.compute.manager [instance: 157e5cc4-86b6-497c-aed6-24aa5c6895e2]     self.gen.next()
>2012-09-26 13:25:08 TRACE nova.compute.manager [instance: 157e5cc4-86b6-497c-aed6-24aa5c6895e2]   File "/usr/lib/python2.7/site-packages/nova/exception.py", line 92, in wrapped
>2012-09-26 13:25:08 TRACE nova.compute.manager [instance: 157e5cc4-86b6-497c-aed6-24aa5c6895e2]     return f(*args, **kw)
>2012-09-26 13:25:08 TRACE nova.compute.manager [instance: 157e5cc4-86b6-497c-aed6-24aa5c6895e2]   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1061, in spawn
>2012-09-26 13:25:08 TRACE nova.compute.manager [instance: 157e5cc4-86b6-497c-aed6-24aa5c6895e2]     block_device_info)
>2012-09-26 13:25:08 TRACE nova.compute.manager [instance: 157e5cc4-86b6-497c-aed6-24aa5c6895e2]   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1887, in _create_domain_and_network
>2012-09-26 13:25:08 TRACE nova.compute.manager [instance: 157e5cc4-86b6-497c-aed6-24aa5c6895e2]     domain = self._create_domain(xml)
>2012-09-26 13:25:08 TRACE nova.compute.manager [instance: 157e5cc4-86b6-497c-aed6-24aa5c6895e2]   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1866, in _create_domain
>2012-09-26 13:25:08 TRACE nova.compute.manager [instance: 157e5cc4-86b6-497c-aed6-24aa5c6895e2]     domain.createWithFlags(launch_flags)
>2012-09-26 13:25:08 TRACE nova.compute.manager [instance: 157e5cc4-86b6-497c-aed6-24aa5c6895e2]   File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 187, in doit
>2012-09-26 13:25:08 TRACE nova.compute.manager [instance: 157e5cc4-86b6-497c-aed6-24aa5c6895e2]     result = proxy_call(self._autowrap, f, *args, **kwargs)
>2012-09-26 13:25:08 TRACE nova.compute.manager [instance: 157e5cc4-86b6-497c-aed6-24aa5c6895e2]   File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 147, in proxy_call
>2012-09-26 13:25:08 TRACE nova.compute.manager [instance: 157e5cc4-86b6-497c-aed6-24aa5c6895e2]     rv = execute(f,*args,**kwargs)
>2012-09-26 13:25:08 TRACE nova.compute.manager [instance: 157e5cc4-86b6-497c-aed6-24aa5c6895e2]   File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 76, in tworker
>2012-09-26 13:25:08 TRACE nova.compute.manager [instance: 157e5cc4-86b6-497c-aed6-24aa5c6895e2]     rv = meth(*args,**kwargs)
>2012-09-26 13:25:08 TRACE nova.compute.manager [instance: 157e5cc4-86b6-497c-aed6-24aa5c6895e2]   File "/usr/lib64/python2.7/site-packages/libvirt.py", line 708, in createWithFlags
>2012-09-26 13:25:08 TRACE nova.compute.manager [instance: 157e5cc4-86b6-497c-aed6-24aa5c6895e2]     if ret == -1: raise libvirtError ('virDomainCreateWithFlags() failed', dom=self)
>2012-09-26 13:25:08 TRACE nova.compute.manager [instance: 157e5cc4-86b6-497c-aed6-24aa5c6895e2] libvirtError: internal error Process exited while reading console log output: Warning: option deprecated, use lost_tick_policy property of kvm-pit instead.
>2012-09-26 13:25:08 TRACE nova.compute.manager [instance: 157e5cc4-86b6-497c-aed6-24aa5c6895e2] char device redirected to /dev/pts/1
>2012-09-26 13:25:08 TRACE nova.compute.manager [instance: 157e5cc4-86b6-497c-aed6-24aa5c6895e2] Could not access KVM kernel module: Permission denied
>2012-09-26 13:25:08 TRACE nova.compute.manager [instance: 157e5cc4-86b6-497c-aed6-24aa5c6895e2] failed to initialize KVM: Permission denied
>2012-09-26 13:25:08 TRACE nova.compute.manager [instance: 157e5cc4-86b6-497c-aed6-24aa5c6895e2] No accelerator found!
>2012-09-26 13:25:08 TRACE nova.compute.manager [instance: 157e5cc4-86b6-497c-aed6-24aa5c6895e2] 
>2012-09-26 13:25:08 TRACE nova.compute.manager [instance: 157e5cc4-86b6-497c-aed6-24aa5c6895e2] 
>2012-09-26 13:25:08 INFO nova.compute.resource_tracker [req-39bea5fa-01cf-4189-a913-585c6cd5a163 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Aborting claim: [Claim 157e5cc4-86b6-497c-aed6-24aa5c6895e2: 512 MB memory, 0 GB disk, 1 VCPUS]
>2012-09-26 13:25:08 ERROR nova.compute.manager [req-39bea5fa-01cf-4189-a913-585c6cd5a163 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 157e5cc4-86b6-497c-aed6-24aa5c6895e2] Build error: ['Traceback (most recent call last):\n', '  File "/usr/lib/python2.7/site-packages/nova/compute/manager.py", line 496, in _run_instance\n    injected_files, admin_password)\n', '  File "/usr/lib/python2.7/site-packages/nova/compute/manager.py", line 743, in _spawn\n    block_device_info)\n', '  File "/usr/lib/python2.7/site-packages/nova/exception.py", line 117, in wrapped\n    temp_level, payload)\n', '  File "/usr/lib64/python2.7/contextlib.py", line 24, in __exit__\n    self.gen.next()\n', '  File "/usr/lib/python2.7/site-packages/nova/exception.py", line 92, in wrapped\n    return f(*args, **kw)\n', '  File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1061, in spawn\n    block_device_info)\n', '  File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1887, in _create_domain_and_network\n    domain = self._create_domain(xml)\n', '  File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1866, in _create_domain\n    domain.createWithFlags(launch_flags)\n', '  File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 187, in doit\n    result = proxy_call(self._autowrap, f, *args, **kwargs)\n', '  File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 147, in proxy_call\n    rv = execute(f,*args,**kwargs)\n', '  File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 76, in tworker\n    rv = meth(*args,**kwargs)\n', '  File "/usr/lib64/python2.7/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: Warning: option deprecated, use lost_tick_policy property of kvm-pit instead.\nchar device redirected to /dev/pts/1\nCould not access KVM kernel module: Permission denied\nfailed to initialize KVM: Permission denied\nNo accelerator found!\n\n']
>2012-09-26 13:25:09 ERROR nova.compute.manager [req-875eb396-f9a9-423b-b250-a7b9ac927ee3 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 289c51cd-f9a7-448c-a732-72617106183e] Instance failed to spawn
>2012-09-26 13:25:09 TRACE nova.compute.manager [instance: 289c51cd-f9a7-448c-a732-72617106183e] Traceback (most recent call last):
>2012-09-26 13:25:09 TRACE nova.compute.manager [instance: 289c51cd-f9a7-448c-a732-72617106183e]   File "/usr/lib/python2.7/site-packages/nova/compute/manager.py", line 743, in _spawn
>2012-09-26 13:25:09 TRACE nova.compute.manager [instance: 289c51cd-f9a7-448c-a732-72617106183e]     block_device_info)
>2012-09-26 13:25:09 TRACE nova.compute.manager [instance: 289c51cd-f9a7-448c-a732-72617106183e]   File "/usr/lib/python2.7/site-packages/nova/exception.py", line 117, in wrapped
>2012-09-26 13:25:09 TRACE nova.compute.manager [instance: 289c51cd-f9a7-448c-a732-72617106183e]     temp_level, payload)
>2012-09-26 13:25:09 TRACE nova.compute.manager [instance: 289c51cd-f9a7-448c-a732-72617106183e]   File "/usr/lib64/python2.7/contextlib.py", line 24, in __exit__
>2012-09-26 13:25:09 TRACE nova.compute.manager [instance: 289c51cd-f9a7-448c-a732-72617106183e]     self.gen.next()
>2012-09-26 13:25:09 TRACE nova.compute.manager [instance: 289c51cd-f9a7-448c-a732-72617106183e]   File "/usr/lib/python2.7/site-packages/nova/exception.py", line 92, in wrapped
>2012-09-26 13:25:09 TRACE nova.compute.manager [instance: 289c51cd-f9a7-448c-a732-72617106183e]     return f(*args, **kw)
>2012-09-26 13:25:09 TRACE nova.compute.manager [instance: 289c51cd-f9a7-448c-a732-72617106183e]   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1061, in spawn
>2012-09-26 13:25:09 TRACE nova.compute.manager [instance: 289c51cd-f9a7-448c-a732-72617106183e]     block_device_info)
>2012-09-26 13:25:09 TRACE nova.compute.manager [instance: 289c51cd-f9a7-448c-a732-72617106183e]   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1887, in _create_domain_and_network
>2012-09-26 13:25:09 TRACE nova.compute.manager [instance: 289c51cd-f9a7-448c-a732-72617106183e]     domain = self._create_domain(xml)
>2012-09-26 13:25:09 TRACE nova.compute.manager [instance: 289c51cd-f9a7-448c-a732-72617106183e]   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1866, in _create_domain
>2012-09-26 13:25:09 TRACE nova.compute.manager [instance: 289c51cd-f9a7-448c-a732-72617106183e]     domain.createWithFlags(launch_flags)
>2012-09-26 13:25:09 TRACE nova.compute.manager [instance: 289c51cd-f9a7-448c-a732-72617106183e]   File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 187, in doit
>2012-09-26 13:25:09 TRACE nova.compute.manager [instance: 289c51cd-f9a7-448c-a732-72617106183e]     result = proxy_call(self._autowrap, f, *args, **kwargs)
>2012-09-26 13:25:09 TRACE nova.compute.manager [instance: 289c51cd-f9a7-448c-a732-72617106183e]   File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 147, in proxy_call
>2012-09-26 13:25:09 TRACE nova.compute.manager [instance: 289c51cd-f9a7-448c-a732-72617106183e]     rv = execute(f,*args,**kwargs)
>2012-09-26 13:25:09 TRACE nova.compute.manager [instance: 289c51cd-f9a7-448c-a732-72617106183e]   File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 76, in tworker
>2012-09-26 13:25:09 TRACE nova.compute.manager [instance: 289c51cd-f9a7-448c-a732-72617106183e]     rv = meth(*args,**kwargs)
>2012-09-26 13:25:09 TRACE nova.compute.manager [instance: 289c51cd-f9a7-448c-a732-72617106183e]   File "/usr/lib64/python2.7/site-packages/libvirt.py", line 708, in createWithFlags
>2012-09-26 13:25:09 TRACE nova.compute.manager [instance: 289c51cd-f9a7-448c-a732-72617106183e]     if ret == -1: raise libvirtError ('virDomainCreateWithFlags() failed', dom=self)
>2012-09-26 13:25:09 TRACE nova.compute.manager [instance: 289c51cd-f9a7-448c-a732-72617106183e] libvirtError: internal error Process exited while reading console log output: Warning: option deprecated, use lost_tick_policy property of kvm-pit instead.
>2012-09-26 13:25:09 TRACE nova.compute.manager [instance: 289c51cd-f9a7-448c-a732-72617106183e] char device redirected to /dev/pts/1
>2012-09-26 13:25:09 TRACE nova.compute.manager [instance: 289c51cd-f9a7-448c-a732-72617106183e] Could not access KVM kernel module: Permission denied
>2012-09-26 13:25:09 TRACE nova.compute.manager [instance: 289c51cd-f9a7-448c-a732-72617106183e] failed to initialize KVM: Permission denied
>2012-09-26 13:25:09 TRACE nova.compute.manager [instance: 289c51cd-f9a7-448c-a732-72617106183e] No accelerator found!
>2012-09-26 13:25:09 TRACE nova.compute.manager [instance: 289c51cd-f9a7-448c-a732-72617106183e] 
>2012-09-26 13:25:09 TRACE nova.compute.manager [instance: 289c51cd-f9a7-448c-a732-72617106183e] 
>2012-09-26 13:25:09 INFO nova.compute.resource_tracker [req-875eb396-f9a9-423b-b250-a7b9ac927ee3 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Aborting claim: [Claim 289c51cd-f9a7-448c-a732-72617106183e: 512 MB memory, 0 GB disk, 1 VCPUS]
>2012-09-26 13:25:09 ERROR nova.compute.manager [req-875eb396-f9a9-423b-b250-a7b9ac927ee3 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 289c51cd-f9a7-448c-a732-72617106183e] Build error: ['Traceback (most recent call last):\n', '  File "/usr/lib/python2.7/site-packages/nova/compute/manager.py", line 496, in _run_instance\n    injected_files, admin_password)\n', '  File "/usr/lib/python2.7/site-packages/nova/compute/manager.py", line 743, in _spawn\n    block_device_info)\n', '  File "/usr/lib/python2.7/site-packages/nova/exception.py", line 117, in wrapped\n    temp_level, payload)\n', '  File "/usr/lib64/python2.7/contextlib.py", line 24, in __exit__\n    self.gen.next()\n', '  File "/usr/lib/python2.7/site-packages/nova/exception.py", line 92, in wrapped\n    return f(*args, **kw)\n', '  File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1061, in spawn\n    block_device_info)\n', '  File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1887, in _create_domain_and_network\n    domain = self._create_domain(xml)\n', '  File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1866, in _create_domain\n    domain.createWithFlags(launch_flags)\n', '  File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 187, in doit\n    result = proxy_call(self._autowrap, f, *args, **kwargs)\n', '  File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 147, in proxy_call\n    rv = execute(f,*args,**kwargs)\n', '  File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 76, in tworker\n    rv = meth(*args,**kwargs)\n', '  File "/usr/lib64/python2.7/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: Warning: option deprecated, use lost_tick_policy property of kvm-pit instead.\nchar device redirected to /dev/pts/1\nCould not access KVM kernel module: Permission denied\nfailed to initialize KVM: Permission denied\nNo accelerator found!\n\n']
>2012-09-26 13:25:10 ERROR nova.compute.manager [req-35f080a6-9c76-42c9-8641-b1fd0ac8b692 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 74ef539f-816c-4045-9ff1-5cc063371394] Instance failed to spawn
>2012-09-26 13:25:10 TRACE nova.compute.manager [instance: 74ef539f-816c-4045-9ff1-5cc063371394] Traceback (most recent call last):
>2012-09-26 13:25:10 TRACE nova.compute.manager [instance: 74ef539f-816c-4045-9ff1-5cc063371394]   File "/usr/lib/python2.7/site-packages/nova/compute/manager.py", line 743, in _spawn
>2012-09-26 13:25:10 TRACE nova.compute.manager [instance: 74ef539f-816c-4045-9ff1-5cc063371394]     block_device_info)
>2012-09-26 13:25:10 TRACE nova.compute.manager [instance: 74ef539f-816c-4045-9ff1-5cc063371394]   File "/usr/lib/python2.7/site-packages/nova/exception.py", line 117, in wrapped
>2012-09-26 13:25:10 TRACE nova.compute.manager [instance: 74ef539f-816c-4045-9ff1-5cc063371394]     temp_level, payload)
>2012-09-26 13:25:10 TRACE nova.compute.manager [instance: 74ef539f-816c-4045-9ff1-5cc063371394]   File "/usr/lib64/python2.7/contextlib.py", line 24, in __exit__
>2012-09-26 13:25:10 TRACE nova.compute.manager [instance: 74ef539f-816c-4045-9ff1-5cc063371394]     self.gen.next()
>2012-09-26 13:25:10 TRACE nova.compute.manager [instance: 74ef539f-816c-4045-9ff1-5cc063371394]   File "/usr/lib/python2.7/site-packages/nova/exception.py", line 92, in wrapped
>2012-09-26 13:25:10 TRACE nova.compute.manager [instance: 74ef539f-816c-4045-9ff1-5cc063371394]     return f(*args, **kw)
>2012-09-26 13:25:10 TRACE nova.compute.manager [instance: 74ef539f-816c-4045-9ff1-5cc063371394]   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1061, in spawn
>2012-09-26 13:25:10 TRACE nova.compute.manager [instance: 74ef539f-816c-4045-9ff1-5cc063371394]     block_device_info)
>2012-09-26 13:25:10 TRACE nova.compute.manager [instance: 74ef539f-816c-4045-9ff1-5cc063371394]   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1887, in _create_domain_and_network
>2012-09-26 13:25:10 TRACE nova.compute.manager [instance: 74ef539f-816c-4045-9ff1-5cc063371394]     domain = self._create_domain(xml)
>2012-09-26 13:25:10 TRACE nova.compute.manager [instance: 74ef539f-816c-4045-9ff1-5cc063371394]   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1866, in _create_domain
>2012-09-26 13:25:10 TRACE nova.compute.manager [instance: 74ef539f-816c-4045-9ff1-5cc063371394]     domain.createWithFlags(launch_flags)
>2012-09-26 13:25:10 TRACE nova.compute.manager [instance: 74ef539f-816c-4045-9ff1-5cc063371394]   File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 187, in doit
>2012-09-26 13:25:10 TRACE nova.compute.manager [instance: 74ef539f-816c-4045-9ff1-5cc063371394]     result = proxy_call(self._autowrap, f, *args, **kwargs)
>2012-09-26 13:25:10 TRACE nova.compute.manager [instance: 74ef539f-816c-4045-9ff1-5cc063371394]   File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 147, in proxy_call
>2012-09-26 13:25:10 TRACE nova.compute.manager [instance: 74ef539f-816c-4045-9ff1-5cc063371394]     rv = execute(f,*args,**kwargs)
>2012-09-26 13:25:10 TRACE nova.compute.manager [instance: 74ef539f-816c-4045-9ff1-5cc063371394]   File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 76, in tworker
>2012-09-26 13:25:10 TRACE nova.compute.manager [instance: 74ef539f-816c-4045-9ff1-5cc063371394]     rv = meth(*args,**kwargs)
>2012-09-26 13:25:10 TRACE nova.compute.manager [instance: 74ef539f-816c-4045-9ff1-5cc063371394]   File "/usr/lib64/python2.7/site-packages/libvirt.py", line 708, in createWithFlags
>2012-09-26 13:25:10 TRACE nova.compute.manager [instance: 74ef539f-816c-4045-9ff1-5cc063371394]     if ret == -1: raise libvirtError ('virDomainCreateWithFlags() failed', dom=self)
>2012-09-26 13:25:10 TRACE nova.compute.manager [instance: 74ef539f-816c-4045-9ff1-5cc063371394] libvirtError: internal error Process exited while reading console log output: Warning: option deprecated, use lost_tick_policy property of kvm-pit instead.
>2012-09-26 13:25:10 TRACE nova.compute.manager [instance: 74ef539f-816c-4045-9ff1-5cc063371394] char device redirected to /dev/pts/1
>2012-09-26 13:25:10 TRACE nova.compute.manager [instance: 74ef539f-816c-4045-9ff1-5cc063371394] Could not access KVM kernel module: Permission denied
>2012-09-26 13:25:10 TRACE nova.compute.manager [instance: 74ef539f-816c-4045-9ff1-5cc063371394] failed to initialize KVM: Permission denied
>2012-09-26 13:25:10 TRACE nova.compute.manager [instance: 74ef539f-816c-4045-9ff1-5cc063371394] No accelerator found!
>2012-09-26 13:25:10 TRACE nova.compute.manager [instance: 74ef539f-816c-4045-9ff1-5cc063371394] 
>2012-09-26 13:25:10 TRACE nova.compute.manager [instance: 74ef539f-816c-4045-9ff1-5cc063371394] 
>2012-09-26 13:25:10 INFO nova.compute.resource_tracker [req-35f080a6-9c76-42c9-8641-b1fd0ac8b692 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Aborting claim: [Claim 74ef539f-816c-4045-9ff1-5cc063371394: 512 MB memory, 0 GB disk, 1 VCPUS]
>2012-09-26 13:25:11 ERROR nova.compute.manager [req-35f080a6-9c76-42c9-8641-b1fd0ac8b692 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 74ef539f-816c-4045-9ff1-5cc063371394] Build error: ['Traceback (most recent call last):\n', '  File "/usr/lib/python2.7/site-packages/nova/compute/manager.py", line 496, in _run_instance\n    injected_files, admin_password)\n', '  File "/usr/lib/python2.7/site-packages/nova/compute/manager.py", line 743, in _spawn\n    block_device_info)\n', '  File "/usr/lib/python2.7/site-packages/nova/exception.py", line 117, in wrapped\n    temp_level, payload)\n', '  File "/usr/lib64/python2.7/contextlib.py", line 24, in __exit__\n    self.gen.next()\n', '  File "/usr/lib/python2.7/site-packages/nova/exception.py", line 92, in wrapped\n    return f(*args, **kw)\n', '  File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1061, in spawn\n    block_device_info)\n', '  File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1887, in _create_domain_and_network\n    domain = self._create_domain(xml)\n', '  File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1866, in _create_domain\n    domain.createWithFlags(launch_flags)\n', '  File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 187, in doit\n    result = proxy_call(self._autowrap, f, *args, **kwargs)\n', '  File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 147, in proxy_call\n    rv = execute(f,*args,**kwargs)\n', '  File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 76, in tworker\n    rv = meth(*args,**kwargs)\n', '  File "/usr/lib64/python2.7/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: Warning: option deprecated, use lost_tick_policy property of kvm-pit instead.\nchar device redirected to /dev/pts/1\nCould not access KVM kernel module: Permission denied\nfailed to initialize KVM: Permission denied\nNo accelerator found!\n\n']
>2012-09-26 13:25:47 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 5266
>2012-09-26 13:25:47 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 13:25:47 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 0
>2012-09-26 13:25:47 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 13:26:58 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 5266
>2012-09-26 13:26:58 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 13:26:58 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 0
>2012-09-26 13:26:58 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 13:26:59 INFO nova.compute.manager [-] Updating host status
>2012-09-26 13:28:19 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 5266
>2012-09-26 13:28:19 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 13:28:19 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 0
>2012-09-26 13:28:19 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 13:29:29 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 5266
>2012-09-26 13:29:29 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 13:29:29 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 0
>2012-09-26 13:29:29 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 13:29:30 INFO nova.compute.manager [-] Updating host status
>2012-09-26 13:30:50 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 5266
>2012-09-26 13:30:50 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 13:30:50 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 0
>2012-09-26 13:30:50 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 13:31:51 INFO nova.compute.manager [-] Updating bandwidth usage cache
>2012-09-26 13:32:00 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 5266
>2012-09-26 13:32:00 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 13:32:00 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 0
>2012-09-26 13:32:00 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 13:32:01 INFO nova.virt.libvirt.imagecache [-] aad09209-89c2-498d-9310-459166c7a092 (/var/lib/nova/instances/_base/092d6f508d0e338cb3faf92b187b9718297279e7): checking
>2012-09-26 13:32:01 INFO nova.virt.libvirt.imagecache [-] aad09209-89c2-498d-9310-459166c7a092 (/var/lib/nova/instances/_base/092d6f508d0e338cb3faf92b187b9718297279e7): image verification skipped, no hash stored
>2012-09-26 13:32:01 INFO nova.virt.libvirt.imagecache [-] Active base files: /var/lib/nova/instances/_base/092d6f508d0e338cb3faf92b187b9718297279e7
>2012-09-26 13:32:01 INFO nova.compute.manager [-] Updating host status
>2012-09-26 13:33:21 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 5266
>2012-09-26 13:33:21 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 13:33:21 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 0
>2012-09-26 13:33:21 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 13:34:31 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 5266
>2012-09-26 13:34:31 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 13:34:31 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 0
>2012-09-26 13:34:31 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 13:34:32 INFO nova.compute.manager [-] Updating host status
>2012-09-26 13:35:44 WARNING nova.compute.manager [-] Found 4 in the database and 0 on the hypervisor.
>2012-09-26 13:35:58 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 5266
>2012-09-26 13:35:58 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 13:35:58 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 0
>2012-09-26 13:35:58 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 13:37:08 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 5266
>2012-09-26 13:37:08 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 13:37:08 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 0
>2012-09-26 13:37:08 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 13:37:09 INFO nova.compute.manager [-] Updating host status
>2012-09-26 13:38:29 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 5266
>2012-09-26 13:38:29 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 13:38:29 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 0
>2012-09-26 13:38:29 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 13:39:39 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 5266
>2012-09-26 13:39:39 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 13:39:39 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 0
>2012-09-26 13:39:39 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 13:39:40 INFO nova.compute.manager [-] Updating host status
>2012-09-26 13:41:00 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 5266
>2012-09-26 13:41:00 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 13:41:00 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 0
>2012-09-26 13:41:00 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 13:42:01 INFO nova.compute.manager [-] Updating bandwidth usage cache
>2012-09-26 13:42:10 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 5266
>2012-09-26 13:42:10 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 13:42:10 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 0
>2012-09-26 13:42:10 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 13:42:11 INFO nova.compute.manager [-] Updating host status
>2012-09-26 13:43:31 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 5266
>2012-09-26 13:43:31 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 13:43:31 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 0
>2012-09-26 13:43:31 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 13:44:36 AUDIT nova.compute.manager [req-d8a31fef-60e3-4d44-9ab5-352428175e2d 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 8b60f052-5238-43ea-a50c-670b488685f8] Terminating instance
>2012-09-26 13:44:43 INFO nova.virt.libvirt.driver [-] [instance: 8b60f052-5238-43ea-a50c-670b488685f8] Instance destroyed successfully.
>2012-09-26 13:44:43 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 5266
>2012-09-26 13:44:43 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 13:44:43 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 0
>2012-09-26 13:44:43 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 13:44:44 AUDIT nova.compute.manager [req-824fc2e6-c830-4a5f-a816-a01a8936eb0e 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 157e5cc4-86b6-497c-aed6-24aa5c6895e2] Terminating instance
>2012-09-26 13:44:44 INFO nova.compute.manager [-] Updating host status
>2012-09-26 13:44:47 INFO nova.virt.libvirt.driver [req-d8a31fef-60e3-4d44-9ab5-352428175e2d 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 8b60f052-5238-43ea-a50c-670b488685f8] Deleting instance files /var/lib/nova/instances/instance-00000005
>2012-09-26 13:44:50 INFO nova.virt.libvirt.driver [-] [instance: 157e5cc4-86b6-497c-aed6-24aa5c6895e2] Instance destroyed successfully.
>2012-09-26 13:44:50 AUDIT nova.compute.manager [req-1d2f4b55-b272-4778-832d-55e61fed85a1 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 289c51cd-f9a7-448c-a732-72617106183e] Terminating instance
>2012-09-26 13:44:55 INFO nova.virt.libvirt.driver [req-824fc2e6-c830-4a5f-a816-a01a8936eb0e 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 157e5cc4-86b6-497c-aed6-24aa5c6895e2] Deleting instance files /var/lib/nova/instances/instance-00000006
>2012-09-26 13:44:57 AUDIT nova.compute.manager [req-2b897977-6fd1-4119-8342-bdb94820f395 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 74ef539f-816c-4045-9ff1-5cc063371394] Terminating instance
>2012-09-26 13:45:01 INFO nova.virt.libvirt.driver [-] [instance: 289c51cd-f9a7-448c-a732-72617106183e] Instance destroyed successfully.
>2012-09-26 13:45:03 INFO nova.virt.libvirt.driver [-] [instance: 74ef539f-816c-4045-9ff1-5cc063371394] Instance destroyed successfully.
>2012-09-26 13:45:03 INFO nova.virt.libvirt.driver [req-1d2f4b55-b272-4778-832d-55e61fed85a1 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 289c51cd-f9a7-448c-a732-72617106183e] Deleting instance files /var/lib/nova/instances/instance-00000007
>2012-09-26 13:45:04 INFO nova.virt.libvirt.driver [req-2b897977-6fd1-4119-8342-bdb94820f395 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 74ef539f-816c-4045-9ff1-5cc063371394] Deleting instance files /var/lib/nova/instances/instance-00000008
>2012-09-26 13:45:28 AUDIT nova.compute.manager [req-61b39626-79a2-412f-a959-2be93891ce1c 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 3d56144f-f226-4a26-b6cb-6407dfea39e8] Starting instance...
>2012-09-26 13:45:28 AUDIT nova.compute.resource_tracker [req-61b39626-79a2-412f-a959-2be93891ce1c 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Attempting claim: memory 512 MB, disk 0 GB, VCPUs 1
>2012-09-26 13:45:28 AUDIT nova.compute.resource_tracker [req-61b39626-79a2-412f-a959-2be93891ce1c 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Total memory: 7826 MB, used: 512 MB, free: 0 MB
>2012-09-26 13:45:28 AUDIT nova.compute.resource_tracker [req-61b39626-79a2-412f-a959-2be93891ce1c 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Memory limit: 11739 MB, free: 11227 MB
>2012-09-26 13:45:28 AUDIT nova.compute.resource_tracker [req-61b39626-79a2-412f-a959-2be93891ce1c 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Total disk: 49 GB, used: 0 GB, free: 49 GB
>2012-09-26 13:45:28 AUDIT nova.compute.resource_tracker [req-61b39626-79a2-412f-a959-2be93891ce1c 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Disk limit not specified, defaulting to unlimited
>2012-09-26 13:45:28 AUDIT nova.compute.resource_tracker [req-61b39626-79a2-412f-a959-2be93891ce1c 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Total VCPUs: 4, used: 0
>2012-09-26 13:45:28 AUDIT nova.compute.resource_tracker [req-61b39626-79a2-412f-a959-2be93891ce1c 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] VCPU limit not specified, defaulting to unlimited
>2012-09-26 13:45:30 INFO nova.virt.libvirt.driver [req-61b39626-79a2-412f-a959-2be93891ce1c 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 3d56144f-f226-4a26-b6cb-6407dfea39e8] Creating image
>2012-09-26 13:45:32 INFO nova.virt.libvirt.driver [req-61b39626-79a2-412f-a959-2be93891ce1c 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 3d56144f-f226-4a26-b6cb-6407dfea39e8] Injecting key into image 36440286-9889-4600-8681-2f89143b5bab
>2012-09-26 13:45:33 AUDIT nova.compute.manager [req-77991b09-ff0c-4261-bdbf-4b07f9cec0e8 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: a5905503-0fef-47cd-bcde-e36cb940e019] Starting instance...
>2012-09-26 13:45:34 AUDIT nova.compute.resource_tracker [req-77991b09-ff0c-4261-bdbf-4b07f9cec0e8 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Attempting claim: memory 512 MB, disk 0 GB, VCPUs 1
>2012-09-26 13:45:34 AUDIT nova.compute.resource_tracker [req-77991b09-ff0c-4261-bdbf-4b07f9cec0e8 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Total memory: 7826 MB, used: 1024 MB, free: 0 MB
>2012-09-26 13:45:34 AUDIT nova.compute.resource_tracker [req-77991b09-ff0c-4261-bdbf-4b07f9cec0e8 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Memory limit: 11739 MB, free: 10715 MB
>2012-09-26 13:45:34 AUDIT nova.compute.resource_tracker [req-77991b09-ff0c-4261-bdbf-4b07f9cec0e8 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Total disk: 49 GB, used: 0 GB, free: 49 GB
>2012-09-26 13:45:34 AUDIT nova.compute.resource_tracker [req-77991b09-ff0c-4261-bdbf-4b07f9cec0e8 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Disk limit not specified, defaulting to unlimited
>2012-09-26 13:45:34 AUDIT nova.compute.resource_tracker [req-77991b09-ff0c-4261-bdbf-4b07f9cec0e8 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Total VCPUs: 4, used: 1
>2012-09-26 13:45:34 AUDIT nova.compute.resource_tracker [req-77991b09-ff0c-4261-bdbf-4b07f9cec0e8 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] VCPU limit not specified, defaulting to unlimited
>2012-09-26 13:45:37 INFO nova.virt.libvirt.driver [req-77991b09-ff0c-4261-bdbf-4b07f9cec0e8 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: a5905503-0fef-47cd-bcde-e36cb940e019] Creating image
>2012-09-26 13:45:37 INFO nova.virt.libvirt.driver [req-77991b09-ff0c-4261-bdbf-4b07f9cec0e8 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: a5905503-0fef-47cd-bcde-e36cb940e019] Injecting key into image 36440286-9889-4600-8681-2f89143b5bab
>2012-09-26 13:45:55 WARNING nova.virt.libvirt.driver [req-61b39626-79a2-412f-a959-2be93891ce1c 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 3d56144f-f226-4a26-b6cb-6407dfea39e8] Ignoring error injecting data into image 36440286-9889-4600-8681-2f89143b5bab (
>--
>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-00000009/disk -m /dev/sda /tmp/openstack-disk-mount-tmpEQsBCr
>Exit code: 1
>Stdout: ''
>Stderr: 'libguestfs: error: could not create appliance through libvirt: internal error process exited while connecting to monitor: Could not access KVM kernel module: Permission denied\nfailed to initialize KVM: Permission denied\nNo accelerator found!\n [code=1 domain=10]\n')
>2012-09-26 13:45:55 WARNING nova.virt.libvirt.driver [req-77991b09-ff0c-4261-bdbf-4b07f9cec0e8 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: a5905503-0fef-47cd-bcde-e36cb940e019] Ignoring error injecting data into image 36440286-9889-4600-8681-2f89143b5bab (
>--
>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-0000000a/disk -m /dev/sda /tmp/openstack-disk-mount-tmpPVqzFh
>Exit code: 1
>Stdout: ''
>Stderr: 'libguestfs: error: could not create appliance through libvirt: internal error process exited while connecting to monitor: Could not access KVM kernel module: Permission denied\nfailed to initialize KVM: Permission denied\nNo accelerator found!\n [code=1 domain=10]\n')
>2012-09-26 13:45:55 INFO nova.virt.libvirt.firewall [req-61b39626-79a2-412f-a959-2be93891ce1c 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 3d56144f-f226-4a26-b6cb-6407dfea39e8] Called setup_basic_filtering in nwfilter
>2012-09-26 13:45:55 INFO nova.virt.libvirt.firewall [req-61b39626-79a2-412f-a959-2be93891ce1c 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 3d56144f-f226-4a26-b6cb-6407dfea39e8] Ensuring static filters
>2012-09-26 13:45:56 INFO nova.virt.libvirt.firewall [req-77991b09-ff0c-4261-bdbf-4b07f9cec0e8 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: a5905503-0fef-47cd-bcde-e36cb940e019] Called setup_basic_filtering in nwfilter
>2012-09-26 13:45:56 INFO nova.virt.libvirt.firewall [req-77991b09-ff0c-4261-bdbf-4b07f9cec0e8 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: a5905503-0fef-47cd-bcde-e36cb940e019] Ensuring static filters
>2012-09-26 13:46:06 ERROR nova.compute.manager [req-61b39626-79a2-412f-a959-2be93891ce1c 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 3d56144f-f226-4a26-b6cb-6407dfea39e8] Instance failed to spawn
>2012-09-26 13:46:06 TRACE nova.compute.manager [instance: 3d56144f-f226-4a26-b6cb-6407dfea39e8] Traceback (most recent call last):
>2012-09-26 13:46:06 TRACE nova.compute.manager [instance: 3d56144f-f226-4a26-b6cb-6407dfea39e8]   File "/usr/lib/python2.7/site-packages/nova/compute/manager.py", line 743, in _spawn
>2012-09-26 13:46:06 TRACE nova.compute.manager [instance: 3d56144f-f226-4a26-b6cb-6407dfea39e8]     block_device_info)
>2012-09-26 13:46:06 TRACE nova.compute.manager [instance: 3d56144f-f226-4a26-b6cb-6407dfea39e8]   File "/usr/lib/python2.7/site-packages/nova/exception.py", line 117, in wrapped
>2012-09-26 13:46:06 TRACE nova.compute.manager [instance: 3d56144f-f226-4a26-b6cb-6407dfea39e8]     temp_level, payload)
>2012-09-26 13:46:06 TRACE nova.compute.manager [instance: 3d56144f-f226-4a26-b6cb-6407dfea39e8]   File "/usr/lib64/python2.7/contextlib.py", line 24, in __exit__
>2012-09-26 13:46:06 TRACE nova.compute.manager [instance: 3d56144f-f226-4a26-b6cb-6407dfea39e8]     self.gen.next()
>2012-09-26 13:46:06 TRACE nova.compute.manager [instance: 3d56144f-f226-4a26-b6cb-6407dfea39e8]   File "/usr/lib/python2.7/site-packages/nova/exception.py", line 92, in wrapped
>2012-09-26 13:46:06 TRACE nova.compute.manager [instance: 3d56144f-f226-4a26-b6cb-6407dfea39e8]     return f(*args, **kw)
>2012-09-26 13:46:06 TRACE nova.compute.manager [instance: 3d56144f-f226-4a26-b6cb-6407dfea39e8]   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1061, in spawn
>2012-09-26 13:46:06 TRACE nova.compute.manager [instance: 3d56144f-f226-4a26-b6cb-6407dfea39e8]     block_device_info)
>2012-09-26 13:46:06 TRACE nova.compute.manager [instance: 3d56144f-f226-4a26-b6cb-6407dfea39e8]   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1887, in _create_domain_and_network
>2012-09-26 13:46:06 TRACE nova.compute.manager [instance: 3d56144f-f226-4a26-b6cb-6407dfea39e8]     domain = self._create_domain(xml)
>2012-09-26 13:46:06 TRACE nova.compute.manager [instance: 3d56144f-f226-4a26-b6cb-6407dfea39e8]   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1866, in _create_domain
>2012-09-26 13:46:06 TRACE nova.compute.manager [instance: 3d56144f-f226-4a26-b6cb-6407dfea39e8]     domain.createWithFlags(launch_flags)
>2012-09-26 13:46:06 TRACE nova.compute.manager [instance: 3d56144f-f226-4a26-b6cb-6407dfea39e8]   File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 187, in doit
>2012-09-26 13:46:06 TRACE nova.compute.manager [instance: 3d56144f-f226-4a26-b6cb-6407dfea39e8]     result = proxy_call(self._autowrap, f, *args, **kwargs)
>2012-09-26 13:46:06 TRACE nova.compute.manager [instance: 3d56144f-f226-4a26-b6cb-6407dfea39e8]   File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 147, in proxy_call
>2012-09-26 13:46:06 TRACE nova.compute.manager [instance: 3d56144f-f226-4a26-b6cb-6407dfea39e8]     rv = execute(f,*args,**kwargs)
>2012-09-26 13:46:06 TRACE nova.compute.manager [instance: 3d56144f-f226-4a26-b6cb-6407dfea39e8]   File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 76, in tworker
>2012-09-26 13:46:06 TRACE nova.compute.manager [instance: 3d56144f-f226-4a26-b6cb-6407dfea39e8]     rv = meth(*args,**kwargs)
>2012-09-26 13:46:06 TRACE nova.compute.manager [instance: 3d56144f-f226-4a26-b6cb-6407dfea39e8]   File "/usr/lib64/python2.7/site-packages/libvirt.py", line 708, in createWithFlags
>2012-09-26 13:46:06 TRACE nova.compute.manager [instance: 3d56144f-f226-4a26-b6cb-6407dfea39e8]     if ret == -1: raise libvirtError ('virDomainCreateWithFlags() failed', dom=self)
>2012-09-26 13:46:06 TRACE nova.compute.manager [instance: 3d56144f-f226-4a26-b6cb-6407dfea39e8] libvirtError: internal error Process exited while reading console log output: Warning: option deprecated, use lost_tick_policy property of kvm-pit instead.
>2012-09-26 13:46:06 TRACE nova.compute.manager [instance: 3d56144f-f226-4a26-b6cb-6407dfea39e8] char device redirected to /dev/pts/1
>2012-09-26 13:46:06 TRACE nova.compute.manager [instance: 3d56144f-f226-4a26-b6cb-6407dfea39e8] Could not access KVM kernel module: Permission denied
>2012-09-26 13:46:06 TRACE nova.compute.manager [instance: 3d56144f-f226-4a26-b6cb-6407dfea39e8] failed to initialize KVM: Permission denied
>2012-09-26 13:46:06 TRACE nova.compute.manager [instance: 3d56144f-f226-4a26-b6cb-6407dfea39e8] No accelerator found!
>2012-09-26 13:46:06 TRACE nova.compute.manager [instance: 3d56144f-f226-4a26-b6cb-6407dfea39e8] 
>2012-09-26 13:46:06 TRACE nova.compute.manager [instance: 3d56144f-f226-4a26-b6cb-6407dfea39e8] 
>2012-09-26 13:46:09 ERROR nova.compute.manager [req-77991b09-ff0c-4261-bdbf-4b07f9cec0e8 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: a5905503-0fef-47cd-bcde-e36cb940e019] Instance failed to spawn
>2012-09-26 13:46:09 TRACE nova.compute.manager [instance: a5905503-0fef-47cd-bcde-e36cb940e019] Traceback (most recent call last):
>2012-09-26 13:46:09 TRACE nova.compute.manager [instance: a5905503-0fef-47cd-bcde-e36cb940e019]   File "/usr/lib/python2.7/site-packages/nova/compute/manager.py", line 743, in _spawn
>2012-09-26 13:46:09 TRACE nova.compute.manager [instance: a5905503-0fef-47cd-bcde-e36cb940e019]     block_device_info)
>2012-09-26 13:46:09 TRACE nova.compute.manager [instance: a5905503-0fef-47cd-bcde-e36cb940e019]   File "/usr/lib/python2.7/site-packages/nova/exception.py", line 117, in wrapped
>2012-09-26 13:46:09 TRACE nova.compute.manager [instance: a5905503-0fef-47cd-bcde-e36cb940e019]     temp_level, payload)
>2012-09-26 13:46:09 TRACE nova.compute.manager [instance: a5905503-0fef-47cd-bcde-e36cb940e019]   File "/usr/lib64/python2.7/contextlib.py", line 24, in __exit__
>2012-09-26 13:46:09 TRACE nova.compute.manager [instance: a5905503-0fef-47cd-bcde-e36cb940e019]     self.gen.next()
>2012-09-26 13:46:09 TRACE nova.compute.manager [instance: a5905503-0fef-47cd-bcde-e36cb940e019]   File "/usr/lib/python2.7/site-packages/nova/exception.py", line 92, in wrapped
>2012-09-26 13:46:09 TRACE nova.compute.manager [instance: a5905503-0fef-47cd-bcde-e36cb940e019]     return f(*args, **kw)
>2012-09-26 13:46:09 TRACE nova.compute.manager [instance: a5905503-0fef-47cd-bcde-e36cb940e019]   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1061, in spawn
>2012-09-26 13:46:09 TRACE nova.compute.manager [instance: a5905503-0fef-47cd-bcde-e36cb940e019]     block_device_info)
>2012-09-26 13:46:09 TRACE nova.compute.manager [instance: a5905503-0fef-47cd-bcde-e36cb940e019]   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1887, in _create_domain_and_network
>2012-09-26 13:46:09 TRACE nova.compute.manager [instance: a5905503-0fef-47cd-bcde-e36cb940e019]     domain = self._create_domain(xml)
>2012-09-26 13:46:09 TRACE nova.compute.manager [instance: a5905503-0fef-47cd-bcde-e36cb940e019]   File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1866, in _create_domain
>2012-09-26 13:46:09 TRACE nova.compute.manager [instance: a5905503-0fef-47cd-bcde-e36cb940e019]     domain.createWithFlags(launch_flags)
>2012-09-26 13:46:09 TRACE nova.compute.manager [instance: a5905503-0fef-47cd-bcde-e36cb940e019]   File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 187, in doit
>2012-09-26 13:46:09 TRACE nova.compute.manager [instance: a5905503-0fef-47cd-bcde-e36cb940e019]     result = proxy_call(self._autowrap, f, *args, **kwargs)
>2012-09-26 13:46:09 TRACE nova.compute.manager [instance: a5905503-0fef-47cd-bcde-e36cb940e019]   File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 147, in proxy_call
>2012-09-26 13:46:09 TRACE nova.compute.manager [instance: a5905503-0fef-47cd-bcde-e36cb940e019]     rv = execute(f,*args,**kwargs)
>2012-09-26 13:46:09 TRACE nova.compute.manager [instance: a5905503-0fef-47cd-bcde-e36cb940e019]   File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 76, in tworker
>2012-09-26 13:46:09 TRACE nova.compute.manager [instance: a5905503-0fef-47cd-bcde-e36cb940e019]     rv = meth(*args,**kwargs)
>2012-09-26 13:46:09 TRACE nova.compute.manager [instance: a5905503-0fef-47cd-bcde-e36cb940e019]   File "/usr/lib64/python2.7/site-packages/libvirt.py", line 708, in createWithFlags
>2012-09-26 13:46:09 TRACE nova.compute.manager [instance: a5905503-0fef-47cd-bcde-e36cb940e019]     if ret == -1: raise libvirtError ('virDomainCreateWithFlags() failed', dom=self)
>2012-09-26 13:46:09 TRACE nova.compute.manager [instance: a5905503-0fef-47cd-bcde-e36cb940e019] libvirtError: internal error Process exited while reading console log output: Warning: option deprecated, use lost_tick_policy property of kvm-pit instead.
>2012-09-26 13:46:09 TRACE nova.compute.manager [instance: a5905503-0fef-47cd-bcde-e36cb940e019] char device redirected to /dev/pts/1
>2012-09-26 13:46:09 TRACE nova.compute.manager [instance: a5905503-0fef-47cd-bcde-e36cb940e019] Could not access KVM kernel module: Permission denied
>2012-09-26 13:46:09 TRACE nova.compute.manager [instance: a5905503-0fef-47cd-bcde-e36cb940e019] failed to initialize KVM: Permission denied
>2012-09-26 13:46:09 TRACE nova.compute.manager [instance: a5905503-0fef-47cd-bcde-e36cb940e019] No accelerator found!
>2012-09-26 13:46:09 TRACE nova.compute.manager [instance: a5905503-0fef-47cd-bcde-e36cb940e019] 
>2012-09-26 13:46:09 TRACE nova.compute.manager [instance: a5905503-0fef-47cd-bcde-e36cb940e019] 
>2012-09-26 13:46:16 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 6290
>2012-09-26 13:46:16 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 13:46:16 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2012-09-26 13:46:16 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 13:46:16 INFO nova.compute.resource_tracker [req-77991b09-ff0c-4261-bdbf-4b07f9cec0e8 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Aborting claim: [Claim a5905503-0fef-47cd-bcde-e36cb940e019: 512 MB memory, 0 GB disk, 1 VCPUS]
>2012-09-26 13:46:16 INFO nova.compute.resource_tracker [req-61b39626-79a2-412f-a959-2be93891ce1c 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Aborting claim: [Claim 3d56144f-f226-4a26-b6cb-6407dfea39e8: 512 MB memory, 0 GB disk, 1 VCPUS]
>2012-09-26 13:46:17 ERROR nova.compute.manager [req-77991b09-ff0c-4261-bdbf-4b07f9cec0e8 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: a5905503-0fef-47cd-bcde-e36cb940e019] Build error: ['Traceback (most recent call last):\n', '  File "/usr/lib/python2.7/site-packages/nova/compute/manager.py", line 496, in _run_instance\n    injected_files, admin_password)\n', '  File "/usr/lib/python2.7/site-packages/nova/compute/manager.py", line 743, in _spawn\n    block_device_info)\n', '  File "/usr/lib/python2.7/site-packages/nova/exception.py", line 117, in wrapped\n    temp_level, payload)\n', '  File "/usr/lib64/python2.7/contextlib.py", line 24, in __exit__\n    self.gen.next()\n', '  File "/usr/lib/python2.7/site-packages/nova/exception.py", line 92, in wrapped\n    return f(*args, **kw)\n', '  File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1061, in spawn\n    block_device_info)\n', '  File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1887, in _create_domain_and_network\n    domain = self._create_domain(xml)\n', '  File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1866, in _create_domain\n    domain.createWithFlags(launch_flags)\n', '  File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 187, in doit\n    result = proxy_call(self._autowrap, f, *args, **kwargs)\n', '  File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 147, in proxy_call\n    rv = execute(f,*args,**kwargs)\n', '  File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 76, in tworker\n    rv = meth(*args,**kwargs)\n', '  File "/usr/lib64/python2.7/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: Warning: option deprecated, use lost_tick_policy property of kvm-pit instead.\nchar device redirected to /dev/pts/1\nCould not access KVM kernel module: Permission denied\nfailed to initialize KVM: Permission denied\nNo accelerator found!\n\n']
>2012-09-26 13:46:17 INFO nova.openstack.common.rpc.impl_qpid [-] Connected to AMQP server on localhost:5672
>2012-09-26 13:46:17 ERROR nova.compute.manager [req-61b39626-79a2-412f-a959-2be93891ce1c 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 3d56144f-f226-4a26-b6cb-6407dfea39e8] Build error: ['Traceback (most recent call last):\n', '  File "/usr/lib/python2.7/site-packages/nova/compute/manager.py", line 496, in _run_instance\n    injected_files, admin_password)\n', '  File "/usr/lib/python2.7/site-packages/nova/compute/manager.py", line 743, in _spawn\n    block_device_info)\n', '  File "/usr/lib/python2.7/site-packages/nova/exception.py", line 117, in wrapped\n    temp_level, payload)\n', '  File "/usr/lib64/python2.7/contextlib.py", line 24, in __exit__\n    self.gen.next()\n', '  File "/usr/lib/python2.7/site-packages/nova/exception.py", line 92, in wrapped\n    return f(*args, **kw)\n', '  File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1061, in spawn\n    block_device_info)\n', '  File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1887, in _create_domain_and_network\n    domain = self._create_domain(xml)\n', '  File "/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py", line 1866, in _create_domain\n    domain.createWithFlags(launch_flags)\n', '  File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 187, in doit\n    result = proxy_call(self._autowrap, f, *args, **kwargs)\n', '  File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 147, in proxy_call\n    rv = execute(f,*args,**kwargs)\n', '  File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 76, in tworker\n    rv = meth(*args,**kwargs)\n', '  File "/usr/lib64/python2.7/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: Warning: option deprecated, use lost_tick_policy property of kvm-pit instead.\nchar device redirected to /dev/pts/1\nCould not access KVM kernel module: Permission denied\nfailed to initialize KVM: Permission denied\nNo accelerator found!\n\n']
>2012-09-26 13:47:27 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 6290
>2012-09-26 13:47:27 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 13:47:27 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2012-09-26 13:47:27 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 13:47:28 INFO nova.compute.manager [-] Updating host status
>2012-09-26 13:48:47 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 6290
>2012-09-26 13:48:47 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 13:48:47 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2012-09-26 13:48:47 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 13:49:49 WARNING nova.compute.manager [-] Found 2 in the database and 0 on the hypervisor.
>2012-09-26 13:50:01 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 6290
>2012-09-26 13:50:01 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 13:50:01 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2012-09-26 13:50:01 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 13:50:02 INFO nova.compute.manager [-] Updating host status
>2012-09-26 13:51:22 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 6290
>2012-09-26 13:51:22 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 13:51:22 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2012-09-26 13:51:22 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 13:52:23 INFO nova.compute.manager [-] Updating bandwidth usage cache
>2012-09-26 13:52:32 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 6290
>2012-09-26 13:52:32 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 13:52:32 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2012-09-26 13:52:32 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 13:52:33 INFO nova.compute.manager [-] Updating host status
>2012-09-26 13:53:52 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 6290
>2012-09-26 13:53:52 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 13:53:52 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2012-09-26 13:53:52 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 13:55:02 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 6290
>2012-09-26 13:55:02 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 13:55:02 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2012-09-26 13:55:03 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 13:55:03 INFO nova.compute.manager [-] Updating host status
>2012-09-26 13:56:23 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 6290
>2012-09-26 13:56:23 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 13:56:23 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2012-09-26 13:56:23 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 13:57:33 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 6290
>2012-09-26 13:57:33 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 13:57:33 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2012-09-26 13:57:33 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 13:57:34 INFO nova.compute.manager [-] Updating host status
>2012-09-26 13:58:53 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 6290
>2012-09-26 13:58:53 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 13:58:53 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2012-09-26 13:58:53 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 14:00:05 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 6290
>2012-09-26 14:00:05 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 14:00:05 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2012-09-26 14:00:05 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 14:00:05 INFO nova.compute.manager [-] Updating host status
>2012-09-26 14:01:25 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 6290
>2012-09-26 14:01:25 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 14:01:25 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2012-09-26 14:01:25 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 14:02:26 INFO nova.compute.manager [-] Updating bandwidth usage cache
>2012-09-26 14:02:35 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 6290
>2012-09-26 14:02:35 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 14:02:35 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2012-09-26 14:02:35 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 14:02:36 INFO nova.compute.manager [-] Updating host status
>2012-09-26 14:03:47 WARNING nova.compute.manager [-] Found 2 in the database and 0 on the hypervisor.
>2012-09-26 14:03:59 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 6290
>2012-09-26 14:03:59 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 14:03:59 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2012-09-26 14:03:59 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 14:05:09 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 6290
>2012-09-26 14:05:09 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 14:05:09 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2012-09-26 14:05:09 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 14:05:10 INFO nova.compute.manager [-] Updating host status
>2012-09-26 14:06:30 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 6290
>2012-09-26 14:06:30 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 14:06:30 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2012-09-26 14:06:30 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 14:07:40 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 6290
>2012-09-26 14:07:40 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 14:07:40 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2012-09-26 14:07:40 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 14:07:41 INFO nova.compute.manager [-] Updating host status
>2012-09-26 14:08:25 AUDIT nova.compute.manager [req-46feea9e-78ca-4db4-9818-81b6378f705c 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: fea4a79e-320a-42b2-9604-6f08d7693130] Starting instance...
>2012-09-26 14:08:26 AUDIT nova.compute.resource_tracker [req-46feea9e-78ca-4db4-9818-81b6378f705c 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Attempting claim: memory 512 MB, disk 0 GB, VCPUs 1
>2012-09-26 14:08:26 AUDIT nova.compute.resource_tracker [req-46feea9e-78ca-4db4-9818-81b6378f705c 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Total memory: 7826 MB, used: 1536 MB, free: 0 MB
>2012-09-26 14:08:26 AUDIT nova.compute.resource_tracker [req-46feea9e-78ca-4db4-9818-81b6378f705c 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Memory limit: 11739 MB, free: 10203 MB
>2012-09-26 14:08:26 AUDIT nova.compute.resource_tracker [req-46feea9e-78ca-4db4-9818-81b6378f705c 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Total disk: 49 GB, used: 0 GB, free: 49 GB
>2012-09-26 14:08:26 AUDIT nova.compute.resource_tracker [req-46feea9e-78ca-4db4-9818-81b6378f705c 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Disk limit not specified, defaulting to unlimited
>2012-09-26 14:08:26 AUDIT nova.compute.resource_tracker [req-46feea9e-78ca-4db4-9818-81b6378f705c 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Total VCPUs: 4, used: 2
>2012-09-26 14:08:26 AUDIT nova.compute.resource_tracker [req-46feea9e-78ca-4db4-9818-81b6378f705c 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] VCPU limit not specified, defaulting to unlimited
>2012-09-26 14:08:28 INFO nova.virt.libvirt.driver [req-46feea9e-78ca-4db4-9818-81b6378f705c 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: fea4a79e-320a-42b2-9604-6f08d7693130] Creating image
>2012-09-26 14:08:28 INFO nova.virt.libvirt.driver [req-46feea9e-78ca-4db4-9818-81b6378f705c 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: fea4a79e-320a-42b2-9604-6f08d7693130] Injecting key into image 36440286-9889-4600-8681-2f89143b5bab
>2012-09-26 14:08:30 AUDIT nova.compute.manager [req-c64942e8-125d-4633-956b-0f91506560ca 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 944d0acf-5524-4684-9372-a1534e70dc2c] Starting instance...
>2012-09-26 14:08:31 AUDIT nova.compute.resource_tracker [req-c64942e8-125d-4633-956b-0f91506560ca 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Attempting claim: memory 512 MB, disk 0 GB, VCPUs 1
>2012-09-26 14:08:31 AUDIT nova.compute.resource_tracker [req-c64942e8-125d-4633-956b-0f91506560ca 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Total memory: 7826 MB, used: 2048 MB, free: 0 MB
>2012-09-26 14:08:31 AUDIT nova.compute.resource_tracker [req-c64942e8-125d-4633-956b-0f91506560ca 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Memory limit: 11739 MB, free: 9691 MB
>2012-09-26 14:08:31 AUDIT nova.compute.resource_tracker [req-c64942e8-125d-4633-956b-0f91506560ca 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Total disk: 49 GB, used: 0 GB, free: 49 GB
>2012-09-26 14:08:31 AUDIT nova.compute.resource_tracker [req-c64942e8-125d-4633-956b-0f91506560ca 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Disk limit not specified, defaulting to unlimited
>2012-09-26 14:08:31 AUDIT nova.compute.resource_tracker [req-c64942e8-125d-4633-956b-0f91506560ca 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Total VCPUs: 4, used: 3
>2012-09-26 14:08:31 AUDIT nova.compute.resource_tracker [req-c64942e8-125d-4633-956b-0f91506560ca 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] VCPU limit not specified, defaulting to unlimited
>2012-09-26 14:08:33 INFO nova.virt.libvirt.driver [req-c64942e8-125d-4633-956b-0f91506560ca 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 944d0acf-5524-4684-9372-a1534e70dc2c] Creating image
>2012-09-26 14:08:34 INFO nova.virt.libvirt.driver [req-c64942e8-125d-4633-956b-0f91506560ca 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 944d0acf-5524-4684-9372-a1534e70dc2c] Injecting key into image 36440286-9889-4600-8681-2f89143b5bab
>2012-09-26 14:08:42 AUDIT nova.compute.manager [req-54e04d4e-1364-4841-9110-6627a35e14fb 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 3d56144f-f226-4a26-b6cb-6407dfea39e8] Terminating instance
>2012-09-26 14:08:42 INFO nova.virt.libvirt.firewall [req-46feea9e-78ca-4db4-9818-81b6378f705c 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: fea4a79e-320a-42b2-9604-6f08d7693130] Called setup_basic_filtering in nwfilter
>2012-09-26 14:08:42 INFO nova.virt.libvirt.firewall [req-46feea9e-78ca-4db4-9818-81b6378f705c 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: fea4a79e-320a-42b2-9604-6f08d7693130] Ensuring static filters
>2012-09-26 14:08:44 INFO nova.virt.libvirt.firewall [req-c64942e8-125d-4633-956b-0f91506560ca 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 944d0acf-5524-4684-9372-a1534e70dc2c] Called setup_basic_filtering in nwfilter
>2012-09-26 14:08:44 INFO nova.virt.libvirt.firewall [req-c64942e8-125d-4633-956b-0f91506560ca 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 944d0acf-5524-4684-9372-a1534e70dc2c] Ensuring static filters
>2012-09-26 14:08:48 AUDIT nova.compute.manager [req-bccaf03a-beeb-4820-8450-8f72724ddc5a 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: a5905503-0fef-47cd-bcde-e36cb940e019] Terminating instance
>2012-09-26 14:08:48 INFO nova.virt.libvirt.driver [-] [instance: 3d56144f-f226-4a26-b6cb-6407dfea39e8] Instance destroyed successfully.
>2012-09-26 14:08:52 INFO nova.virt.libvirt.driver [req-54e04d4e-1364-4841-9110-6627a35e14fb 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: 3d56144f-f226-4a26-b6cb-6407dfea39e8] Deleting instance files /var/lib/nova/instances/instance-00000009
>2012-09-26 14:08:56 INFO nova.virt.libvirt.driver [-] [instance: a5905503-0fef-47cd-bcde-e36cb940e019] Instance destroyed successfully.
>2012-09-26 14:08:58 INFO nova.virt.libvirt.driver [req-bccaf03a-beeb-4820-8450-8f72724ddc5a 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] [instance: a5905503-0fef-47cd-bcde-e36cb940e019] Deleting instance files /var/lib/nova/instances/instance-0000000a
>2012-09-26 14:08:59 INFO nova.virt.libvirt.driver [-] [instance: fea4a79e-320a-42b2-9604-6f08d7693130] Instance spawned successfully.
>2012-09-26 14:09:02 INFO nova.virt.libvirt.driver [-] [instance: 944d0acf-5524-4684-9372-a1534e70dc2c] Instance spawned successfully.
>2012-09-26 14:09:16 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 7314
>2012-09-26 14:09:16 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 14:09:16 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2012-09-26 14:09:16 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 14:09:16 INFO nova.compute.resource_tracker [req-46feea9e-78ca-4db4-9818-81b6378f705c 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Finishing claim: [Claim fea4a79e-320a-42b2-9604-6f08d7693130: 512 MB memory, 0 GB disk, 1 VCPUS]
>2012-09-26 14:09:16 INFO nova.compute.resource_tracker [req-c64942e8-125d-4633-956b-0f91506560ca 533a0b9c6f0a47fda0a8e211bcdcb706 176c396cd2ea4e7dad80535d1f936fe8] Finishing claim: [Claim 944d0acf-5524-4684-9372-a1534e70dc2c: 512 MB memory, 0 GB disk, 1 VCPUS]
>2012-09-26 14:10:34 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 6290
>2012-09-26 14:10:34 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 14:10:34 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2012-09-26 14:10:35 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 14:10:35 INFO nova.compute.manager [-] Updating host status
>2012-09-26 14:12:07 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 6290
>2012-09-26 14:12:07 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 14:12:07 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2012-09-26 14:12:07 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 14:13:07 INFO nova.compute.manager [-] Updating bandwidth usage cache
>2012-09-26 14:13:25 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 6290
>2012-09-26 14:13:25 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 14:13:25 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2012-09-26 14:13:25 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 14:13:26 INFO nova.compute.manager [-] Updating host status
>2012-09-26 14:14:57 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 6290
>2012-09-26 14:14:57 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 14:14:57 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2012-09-26 14:14:57 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 14:16:15 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 6290
>2012-09-26 14:16:15 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 14:16:15 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2012-09-26 14:16:15 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 14:16:16 INFO nova.compute.manager [-] Updating host status
>2012-09-26 14:17:47 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 6290
>2012-09-26 14:17:47 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 14:17:47 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2012-09-26 14:17:47 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 14:19:08 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 6290
>2012-09-26 14:19:08 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 14:19:08 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2012-09-26 14:19:08 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 14:19:09 INFO nova.compute.manager [-] Updating host status
>2012-09-26 14:20:40 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 6290
>2012-09-26 14:20:40 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 14:20:40 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2012-09-26 14:20:40 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 14:21:58 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 6290
>2012-09-26 14:21:58 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 49
>2012-09-26 14:21:58 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 2
>2012-09-26 14:21:59 INFO nova.compute.resource_tracker [-] Compute_service record updated for slabstb250.ethz.ch 
>2012-09-26 14:21:59 INFO nova.compute.manager [-] Updating host status

Actions: View

Attachments on bug 860658: 617531 | 617534