[Rdo-list] Horizon in fresh install of Havana not working

Friday, 25 October 2013

Hi!

I made a clean install of Havana on a machine and by the time it finished installing, I
was unable to use the dashboard as a message saying:

...
 Something went wrong!

 An unexpected error has occurred. Try refreshing the page. If that doesn't help,
contact your local administrator. 
I'm now trying to find what the problem is and I found the status of the dashboard in
openstack-status is 500 and that neutron-dhcp-agent failed to start with this relevant
part of the dhcp-agent.log:

...
 2013-10-25 18:00:15.550 2165 INFO neutron.common.config [-] Logging
enabled!
 2013-10-25 18:00:15.551 2165 ERROR neutron.common.legacy [-] Skipping unknown group key:
firewall_driver
 2013-10-25 18:00:15.585 2165 WARNING neutron.agent.linux.dhcp [-] Unable to determine
dnsmasq version. Please ensure that its version is 2.59 or above!
 2013-10-25 18:00:15.658 2165 ERROR neutron.openstack.common.threadgroup [-] [Errno 22]
Invalid argument
 2013-10-25 18:00:15.658 2165 TRACE neutron.openstack.common.threadgroup Traceback (most
recent call last):
 2013-10-25 18:00:15.658 2165 TRACE neutron.openstack.common.threadgroup   File
"/usr/lib/python2.7/site-packages/neutron/openstack/common/threadgroup.py", line
117, in wait
 2013-10-25 18:00:15.658 2165 TRACE neutron.openstack.common.threadgroup     x.wait()
 2013-10-25 18:00:15.658 2165 TRACE neutron.openstack.common.threadgroup   File
"/usr/lib/python2.7/site-packages/neutron/openstack/common/threadgroup.py", line
49, in wait
 2013-10-25 18:00:15.658 2165 TRACE neutron.openstack.common.threadgroup     return
self.thread.wait()
 2013-10-25 18:00:15.658 2165 TRACE neutron.openstack.common.threadgroup   File
"/usr/lib/python2.7/site-packages/eventlet/greenthread.py", line 168, in wait
 2013-10-25 18:00:15.658 2165 TRACE neutron.openstack.common.threadgroup     return
self._exit_event.wait()
 2013-10-25 18:00:15.658 2165 TRACE neutron.openstack.common.threadgroup   File
"/usr/lib/python2.7/site-packages/eventlet/event.py", line 116, in wait
 2013-10-25 18:00:15.658 2165 TRACE neutron.openstack.common.threadgroup     return
hubs.get_hub().switch()
 2013-10-25 18:00:15.658 2165 TRACE neutron.openstack.common.threadgroup   File
"/usr/lib/python2.7/site-packages/eventlet/hubs/hub.py", line 187, in switch
 2013-10-25 18:00:15.658 2165 TRACE neutron.openstack.common.threadgroup     return
self.greenlet.switch()
 2013-10-25 18:00:15.658 2165 TRACE neutron.openstack.common.threadgroup   File
"/usr/lib/python2.7/site-packages/eventlet/hubs/hub.py", line 238, in run
 2013-10-25 18:00:15.658 2165 TRACE neutron.openstack.common.threadgroup     self.wait(0)
 2013-10-25 18:00:15.658 2165 TRACE neutron.openstack.common.threadgroup   File
"/usr/lib/python2.7/site-packages/eventlet/hubs/poll.py", line 84, in wait
 2013-10-25 18:00:15.658 2165 TRACE neutron.openstack.common.threadgroup     presult =
self.do_poll(seconds)
 2013-10-25 18:00:15.658 2165 TRACE neutron.openstack.common.threadgroup   File
"/usr/lib/python2.7/site-packages/eventlet/hubs/epolls.py", line 61, in do_poll
 2013-10-25 18:00:15.658 2165 TRACE neutron.openstack.common.threadgroup     return
self.poll.poll(seconds)
 2013-10-25 18:00:15.658 2165 TRACE neutron.openstack.common.threadgroup IOError: [Errno
22] Invalid argument
 2013-10-25 18:00:15.658 2165 TRACE neutron.openstack.common.threadgroup 
 2013-10-25 18:00:15.665 2165 ERROR neutron.agent.dhcp_agent [-] Failed reporting state!
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent Traceback (most recent call
last):
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent   File
"/usr/lib/python2.7/site-packages/neutron/agent/dhcp_agent.py", line 555, in
_report_state
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent    
self.state_rpc.report_state(ctx, self.agent_state, self.use_call)
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent   File
"/usr/lib/python2.7/site-packages/neutron/agent/rpc.py", line 72, in
report_state
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent     return self.call(context,
msg, topic=self.topic)
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent   File
"/usr/lib/python2.7/site-packages/neutron/openstack/common/rpc/proxy.py", line
126, in call
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent     result =
rpc.call(context, real_topic, msg, timeout)
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent   File
"/usr/lib/python2.7/site-packages/neutron/openstack/common/rpc/__init__.py",
line 140, in call
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent     return
_get_impl().call(CONF, context, topic, msg, timeout)
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent   File
"/usr/lib/python2.7/site-packages/neutron/openstack/common/rpc/impl_qpid.py",
line 767, in call
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent    
rpc_amqp.get_connection_pool(conf, Connection))
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent   File
"/usr/lib/python2.7/site-packages/neutron/openstack/common/rpc/amqp.py", line
549, in call
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent     rv = multicall(conf,
context, topic, msg, timeout, connection_pool)
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent   File
"/usr/lib/python2.7/site-packages/neutron/openstack/common/rpc/amqp.py", line
539, in multicall
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent    
connection_pool.reply_proxy = ReplyProxy(conf, connection_pool)
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent   File
"/usr/lib/python2.7/site-packages/neutron/openstack/common/rpc/amqp.py", line
192, in __init__
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent     super(ReplyProxy,
self).__init__(conf, connection_pool, pooled=False)
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent   File
"/usr/lib/python2.7/site-packages/neutron/openstack/common/rpc/amqp.py", line
126, in __init__
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent    
server_params=server_params)
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent   File
"/usr/lib/python2.7/site-packages/neutron/openstack/common/rpc/impl_qpid.py",
line 458, in __init__
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent     self.reconnect()
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent   File
"/usr/lib/python2.7/site-packages/neutron/openstack/common/rpc/impl_qpid.py",
line 499, in reconnect
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent     self.connection.open()
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent   File
"<string>", line 6, in open
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent   File
"/usr/lib/python2.7/site-packages/qpid/messaging/endpoints.py", line 273, in
open
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent    
self.attach(timeout=timeout)
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent   File
"<string>", line 6, in attach
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent   File
"/usr/lib/python2.7/site-packages/qpid/messaging/endpoints.py", line 291, in
attach
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent     if not
self._ewait(lambda: self._transport_connected and not self._unlinked(), timeout=timeout):
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent   File
"/usr/lib/python2.7/site-packages/qpid/messaging/endpoints.py", line 224, in
_ewait
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent     self.check_error()
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent   File
"/usr/lib/python2.7/site-packages/qpid/messaging/endpoints.py", line 217, in
check_error
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent     raise e
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent InternalError: Traceback
(most recent call last):
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent   File
"/usr/lib/python2.7/site-packages/qpid/messaging/driver.py", line 495, in
dispatch
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent     self.connect()
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent   File
"/usr/lib/python2.7/site-packages/qpid/messaging/driver.py", line 522, in
connect
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent     self._transport =
trans(self.connection, host, port)
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent   File
"/usr/lib/python2.7/site-packages/qpid/messaging/transports.py", line 28, in
__init__
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent     self.socket =
connect(host, port)
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent   File
"/usr/lib/python2.7/site-packages/qpid/util.py", line 68, in connect
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent     sock.connect(sa)
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent   File
"/usr/lib/python2.7/site-packages/eventlet/greenio.py", line 180, in connect
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent     trampoline(fd,
write=True)
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent   File
"/usr/lib/python2.7/site-packages/eventlet/hubs/__init__.py", line 119, in
trampoline
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent     listener =
hub.add(hub.WRITE, fileno, current.switch)
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent   File
"/usr/lib/python2.7/site-packages/eventlet/hubs/epolls.py", line 52, in add
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent     self.register(fileno,
new=True)
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent   File
"/usr/lib/python2.7/site-packages/eventlet/hubs/poll.py", line 44, in register
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent    
self.poll.register(fileno, mask)
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent IOError: [Errno 22] Invalid
argument
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent 
 2013-10-25 18:00:15.665 2165 TRACE neutron.agent.dhcp_agent 
I seem to have read somewhere that a 500 status in horizon may be caused by one of the
others components failing. So I'm assuming this could be my problem.

Furthermore, I found that snippet may be the relevant part of the logs because I found
some bug reports indicating that this could be a problem with dnsmasq. The problem is that
I have version 2.66 of dnsmasq installed in the system.

Can someone help me?

Thank you very much in advance,
Diogo Vieira

2024

2023

2022

2021

2020

2019

2018

2017

2016

2015

2014

2013

[Rdo-list] Horizon in fresh install of Havana not working