OpenStack Compute (nova)

libvirtError: opening backend "file" failed

Asked by Yitao Jiang

start a instance with errors
=======================
Traceback (most recent call last):
2013-06-20 11:20:17.758 TRACE nova.openstack.common.rpc.amqp File "/opt/stack/nova/nova/openstack/common/rpc/amqp.py", line 430, in _process_data
2013-06-20 11:20:17.758 TRACE nova.openstack.common.rpc.amqp rval = self.proxy.dispatch(ctxt, version, method, **args)
2013-06-20 11:20:17.758 TRACE nova.openstack.common.rpc.amqp File "/opt/stack/nova/nova/openstack/common/rpc/dispatcher.py", line 133, in dispatch
2013-06-20 11:20:17.758 TRACE nova.openstack.common.rpc.amqp return getattr(proxyobj, method)(ctxt, **kwargs)
2013-06-20 11:20:17.758 TRACE nova.openstack.common.rpc.amqp File "/opt/stack/nova/nova/exception.py", line 117, in wrapped
2013-06-20 11:20:17.758 TRACE nova.openstack.common.rpc.amqp temp_level, payload)
2013-06-20 11:20:17.758 TRACE nova.openstack.common.rpc.amqp File "/usr/lib64/python2.7/contextlib.py", line 24, in __exit__
2013-06-20 11:20:17.758 TRACE nova.openstack.common.rpc.amqp self.gen.next()
2013-06-20 11:20:17.758 TRACE nova.openstack.common.rpc.amqp File "/opt/stack/nova/nova/exception.py", line 94, in wrapped
2013-06-20 11:20:17.758 TRACE nova.openstack.common.rpc.amqp return f(self, context, *args, **kw)
2013-06-20 11:20:17.758 TRACE nova.openstack.common.rpc.amqp File "/opt/stack/nova/nova/compute/manager.py", line 209, in decorated_function
2013-06-20 11:20:17.758 TRACE nova.openstack.common.rpc.amqp pass
2013-06-20 11:20:17.758 TRACE nova.openstack.common.rpc.amqp File "/usr/lib64/python2.7/contextlib.py", line 24, in __exit__
2013-06-20 11:20:17.758 TRACE nova.openstack.common.rpc.amqp self.gen.next()
2013-06-20 11:20:17.758 TRACE nova.openstack.common.rpc.amqp File "/opt/stack/nova/nova/compute/manager.py", line 195, in decorated_function
2013-06-20 11:20:17.758 TRACE nova.openstack.common.rpc.amqp return function(self, context, *args, **kwargs)
2013-06-20 11:20:17.758 TRACE nova.openstack.common.rpc.amqp File "/opt/stack/nova/nova/compute/manager.py", line 260, in decorated_function
2013-06-20 11:20:17.758 TRACE nova.openstack.common.rpc.amqp function(self, context, *args, **kwargs)
2013-06-20 11:20:17.758 TRACE nova.openstack.common.rpc.amqp File "/opt/stack/nova/nova/compute/manager.py", line 237, in decorated_function
2013-06-20 11:20:17.758 TRACE nova.openstack.common.rpc.amqp e, sys.exc_info())
2013-06-20 11:20:17.758 TRACE nova.openstack.common.rpc.amqp File "/usr/lib64/python2.7/contextlib.py", line 24, in __exit__
2013-06-20 11:20:17.758 TRACE nova.openstack.common.rpc.amqp self.gen.next()
2013-06-20 11:20:17.758 TRACE nova.openstack.common.rpc.amqp File "/opt/stack/nova/nova/compute/manager.py", line 224, in decorated_function
2013-06-20 11:20:17.758 TRACE nova.openstack.common.rpc.amqp return function(self, context, *args, **kwargs)
2013-06-20 11:20:17.758 TRACE nova.openstack.common.rpc.amqp File "/opt/stack/nova/nova/compute/manager.py", line 1415, in start_instance
2013-06-20 11:20:17.758 TRACE nova.openstack.common.rpc.amqp self.driver.power_on(instance)
2013-06-20 11:20:17.758 TRACE nova.openstack.common.rpc.amqp File "/opt/stack/nova/nova/virt/libvirt/driver.py", line 1407, in power_on
2013-06-20 11:20:17.758 TRACE nova.openstack.common.rpc.amqp self._create_domain(domain=dom, instance=instance)
2013-06-20 11:20:17.758 TRACE nova.openstack.common.rpc.amqp File "/opt/stack/nova/nova/virt/libvirt/driver.py", line 2405, in _create_domain
2013-06-20 11:20:17.758 TRACE nova.openstack.common.rpc.amqp domain.createWithFlags(launch_flags)
2013-06-20 11:20:17.758 TRACE nova.openstack.common.rpc.amqp File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 187, in doit
2013-06-20 11:20:17.758 TRACE nova.openstack.common.rpc.amqp result = proxy_call(self._autowrap, f, *args, **kwargs)
2013-06-20 11:20:17.758 TRACE nova.openstack.common.rpc.amqp File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 147, in proxy_call
2013-06-20 11:20:17.758 TRACE nova.openstack.common.rpc.amqp rv = execute(f,*args,**kwargs)
2013-06-20 11:20:17.758 TRACE nova.openstack.common.rpc.amqp File "/usr/lib/python2.7/site-packages/eventlet/tpool.py", line 76, in tworker
2013-06-20 11:20:17.758 TRACE nova.openstack.common.rpc.amqp rv = meth(*args,**kwargs)
2013-06-20 11:20:17.758 TRACE nova.openstack.common.rpc.amqp File "/usr/lib64/python2.7/site-packages/libvirt.py", line 708, in createWithFlags
2013-06-20 11:20:17.758 TRACE nova.openstack.common.rpc.amqp if ret == -1: raise libvirtError ('virDomainCreateWithFlags() failed', dom=self)
2013-06-20 11:20:17.758 TRACE nova.openstack.common.rpc.amqp libvirtError: internal error Process exited while reading console log output: chardev: opening backend "file" failed

======================

it is simlar with Question #193560 https://answers.launchpad.net/nova/+question/193560, but envn comment the lines of /etc/libvirt/qemu.conf , restart libvirtd it doesn't work. Below is it's permissions：
============
ll /opt/stack/data/
total 20
drwxrwxr-x. 3 stack stack 4096 May 11 23:38 cinder
drwxrwxr-x. 4 stack stack 4096 May 11 23:38 glance
drwxrwxrwx. 7 stack root 4096 Jun 20 01:35 nova
-rw-rw-r--. 1 stack stack 5379194880 May 11 23:38 stack-volumes-backing-file
[root@stackdev ~ (admin)]$ ll /opt/stack/data/nova/
total 20
drwxr-xr-x. 2 stack stack 4096 May 11 23:39 buckets
drwxr-xr-x. 6 stack stack 4096 May 11 23:40 CA
drwxr-xr-x. 8 stack root 4096 Jun 19 14:14 instances
drwxr-xr-x. 2 stack root 4096 May 11 23:39 keys
drwxr-xr-x. 2 stack root 4096 Jun 20 11:16 networks
-rw-rw-r--. 1 stack stack 0 Jun 20 11:18 nova-iptables
-rw-rw-r--. 1 stack stack 0 Jun 20 11:16 nova-lock_bridge
-rw-rw-r--. 1 stack libvirtd 0 Jun 20 01:51 nova-storage-registry-lock
[root@stackdev ~ (admin)]$ ll /opt/stack/data/nova/instances/
total 28
drwxrwxrwx. 2 stack libvirtd 4096 Jun 14 00:56 1db5b1a4-acc5-432b-8597-d3f671d6bdbc
drwxrwxrwx. 2 stack libvirtd 4096 Jun 5 18:15 44f2963d-1a75-469c-a21f-9645b4e03b60
drwxrwxrwx. 2 stack libvirtd 4096 Jun 5 18:13 af94ab5c-dbab-45de-825b-36ae81fcff0e
drwxrwxrwx. 2 stack libvirtd 4096 May 12 00:21 _base
-rwxrwxrwx. 1 stack libvirtd 31 Jun 20 01:51 compute_nodes
drwxrwxrwx. 2 stack libvirtd 4096 May 12 00:21 locks
drwxrwxrwx. 2 stack libvirtd 4096 Jun 13 20:42 snapshot

Question information

Language:
English Edit question
Status:
Answered
For:
OpenStack Compute (nova) Edit question
Assignee:
No assignee Edit question
Last query:
Last reply:
Revision history for this message
Yitao Jiang (jiangytcn) said : 		#1

Here is /var/log/messages output:
----------------------------------------
Jun 20 11:20:01 localhost kernel: [ 490.168845] br100: port 2(vnet0) entered disabled state
Jun 20 11:20:01 localhost kernel: [ 490.169636] device vnet0 left promiscuous mode
Jun 20 11:20:01 localhost kernel: [ 490.169764] br100: port 2(vnet0) entered disabled state
Jun 20 11:20:01 localhost libvirtd[957]: 2013-06-20 03:20:01.244+0000: 1288: error : qemuProcessReadLogOutput:1337 : internal error Process exited while reading console log output: chardev: opening backend "file" failed
Jun 20 11:20:01 localhost libvirtd[957]: 2013-06-20 03:20:01.244+0000: 1288: error : virNWFilterDHCPSnoopEnd:2131 : internal error ifname "vnet0" not in key map
Jun 20 11:20:01 localhost libvirtd[957]: 2013-06-20 03:20:01.431+0000: 1288: error : virNetDevGetIndex:653 : Unable to get index for interface vnet0: No such device

Revision history for this message
Xiang Hui (xianghui) said : 		#2

I have encountered the same question lately, but finally resolve it by reboot the compute nodes, after that can boot vm successfully, and seems the console.log need to work as a qemu user.

-rw-rw----. 1 qemu qemu 25496 Jul 1 02:35 console.log
-rw-r--r--. 1 qemu qemu 1441792 Jul 1 02:35 disk
-rw-r--r--. 1 nova nova 1488 Jul 1 02:33 libvirt.xml

Can you help with this problem?

Provide an answer of your own, or ask Yitao Jiang for more information if necessary.

To post a message you must log in.