<html>
<head>
<meta http-equiv="Content-Type" content="text/html; charset=utf-8">
</head>
<body style="word-wrap: break-word; -webkit-nbsp-mode: space; -webkit-line-break: after-white-space; color: rgb(0, 0, 0); font-size: 14px; font-family: Calibri, sans-serif;">
<div>
<div>
<div>Evan,</div>
<div><br>
</div>
<div>I would recommend restarting anything that takes to rabbitmq. This is a case of your rpc workers thinking that they are connected to rabbitmq but they really aren't. I assume that this setup use to work at one time and now doesn’t?</div>
<div>
<div id="MAC_OUTLOOK_SIGNATURE">
<div><font class="Apple-style-span" color="#000000"><font class="Apple-style-span" face="Calibri"><span class="Apple-style-span" style="font-size: 14px;"><br>
</span></font></font></div>
<div><font class="Apple-style-span" color="#000000"><font class="Apple-style-span" face="Calibri"><span class="Apple-style-span" style="font-size: 14px;">___________________________________________________________________</span></font></font></div>
<div><font class="Apple-style-span" color="#000000"><font class="Apple-style-span" face="Calibri"><span class="Apple-style-span" style="font-size: 14px;">Kris Lindgren</span></font></font></div>
<div><font class="Apple-style-span" color="#000000"><font class="Apple-style-span" face="Calibri"><span class="Apple-style-span" style="font-size: 14px;">Senior Linux Systems Engineer</span></font></font></div>
<div><font class="Apple-style-span" color="#000000"><font class="Apple-style-span" face="Calibri"><span class="Apple-style-span" style="font-size: 14px;">GoDaddy</span></font></font></div>
</div>
</div>
</div>
</div>
<div><br>
</div>
<span id="OLK_SRC_BODY_SECTION">
<div style="font-family:Calibri; font-size:12pt; text-align:left; color:black; BORDER-BOTTOM: medium none; BORDER-LEFT: medium none; PADDING-BOTTOM: 0in; PADDING-LEFT: 0in; PADDING-RIGHT: 0in; BORDER-TOP: #b5c4df 1pt solid; BORDER-RIGHT: medium none; PADDING-TOP: 3pt">
<span style="font-weight:bold">From: </span>Evan <<a href="mailto:evandeng2009@qq.com">evandeng2009@qq.com</a>><br>
<span style="font-weight:bold">Date: </span>Tuesday, November 24, 2015 at 5:02 AM<br>
<span style="font-weight:bold">To: </span>OpenStack-operators <<a href="mailto:OpenStack-operators@lists.openstack.org">OpenStack-operators@lists.openstack.org</a>><br>
<span style="font-weight:bold">Subject: </span>[Openstack-operators] Can't boot image getting nova.api.openstack MessagingTimeout: Timed out waiting for a reply to message ID msg_id<br>
</div>
<div><br>
</div>
<div>
<div>hi,<br>
<br>
I keep getting such error(as subject) when trying to boot an image for instance, no matter from webpage dashboard or cli. firstly it show the status of scheduling and after a while it's Error. I checked status of all service ,nothing gets failed. Below are
the logs for nova-api and nova-conductor. <br>
<br>
It seems like the problem of rabbitmq, but it's running well without any explicit error shown. Once I tried to restart the rabbitmq then instance created successfully. Before this case happens I tried to set a 2nd external network on Network node but failed
so I rolled it back(changed configs back and restart related services). Also checked those common CLI they're all good like nova image-list, nova service-list,neutron agent-list ,keystone service-list.<br>
<br>
Openstack-Juno, with 1 controller, 1 network, 3 compute, 1 block and 1 object, servers have much more resources than needed.<br>
<br>
<br>
<span style="font-weight: bold;">controller# grep MessagingTimeout /var/log/nova/nova-api.log</span><br>
......<br>
2015-11-24 15:58:00.404 18197 TRACE nova.api.openstack MessagingTimeout: Timed out waiting for a reply to message ID 039995083875445bacdd3bd8667cda51<br>
2015-11-24 15:59:02.618 18204 TRACE nova.api.openstack MessagingTimeout: Timed out waiting for a reply to message ID 58c3ea4f3c2d43699632c1999df79e91<br>
2015-11-24 18:58:28.030 18201 TRACE nova.api.openstack MessagingTimeout: Timed out waiting for a reply to message ID 6b43bf60e2cd494fab116515c5997ab5<br>
<br>
<span style="font-weight: bold;">controller# grep ERROR /var/log/nova/nova-api.log</span><br>
......<br>
2015-11-24 18:57:27.003 18201 ERROR oslo.messaging._drivers.impl_rabbit [req-2b810cc0-d809-4f2c-8a01-d1f7eca62b48 ] Failed to consume message from queue:
<span style="color: rgb(255, 0, 0);">[Errno 104] Connection reset by peer</span><br>
2015-11-24 18:58:28.030 18201 ERROR nova.api.openstack [req-2b810cc0-d809-4f2c-8a01-d1f7eca62b48 None] Caught error: Timed out waiting for a reply to message ID 6b43bf60e2cd494fab116515c5997ab5<br>
2015-11-24 19:07:26.591 18200 ERROR oslo.messaging._drivers.impl_rabbit [req-f532b095-5f37-432d-8cdc-06db8498b3c1 ] Failed to publish message to topic 'conductor': [Errno 104] Connection reset by peer<br>
2015-11-24 19:22:36.310 18183 ERROR oslo.messaging._drivers.impl_rabbit [req-24c4ce2a-6516-401e-a6f8-8a89758cc6fa ]
<span style="color: rgb(255, 0, 0);">Failed to publish message to topic 'conductor': [Errno 104] Connection reset by peer</span><br>
<br>
<span style="font-weight: bold;"># tail -n 80 /var/log/nova/nova-conductor.log</span><br>
......<br>
2015-11-24 19:22:37.356 17770 TRACE oslo.messaging._drivers.impl_rabbit File "/usr/lib/python2.7/site-packages/eventlet/greenio.py", line 342, in send<br>
2015-11-24 19:22:37.356 17770 TRACE oslo.messaging._drivers.impl_rabbit total_sent += fd.send(data[total_sent:], flags)<br>
2015-11-24 19:22:37.356 17770 TRACE oslo.messaging._drivers.impl_rabbit error: [Errno 104] Connection reset by peer<br>
2015-11-24 19:22:37.356 17770 TRACE oslo.messaging._drivers.impl_rabbit <br>
2015-11-24 19:22:37.357 17770 INFO oslo.messaging._drivers.impl_rabbit [req-24c4ce2a-6516-401e-a6f8-8a89758cc6fa ] Delaying reconnect for 1.0 seconds...<br>
2015-11-24 19:22:38.358 17770 INFO oslo.messaging._drivers.impl_rabbit [req-24c4ce2a-6516-401e-a6f8-8a89758cc6fa ] Connecting to AMQP server on controller:5672<br>
2015-11-24 19:22:38.379 17770 INFO oslo.messaging._drivers.impl_rabbit [req-24c4ce2a-6516-401e-a6f8-8a89758cc6fa ] Connected to AMQP server on controller:5672<br>
2015-11-24 19:23:38.385 17770 ERROR nova.scheduler.driver [req-24c4ce2a-6516-401e-a6f8-8a89758cc6fa None] Exception during scheduler.run_instance<br>
2015-11-24 19:23:38.385 17770 TRACE nova.scheduler.driver Traceback (most recent call last):<br>
2015-11-24 19:23:38.385 17770 TRACE nova.scheduler.driver File "/usr/lib/python2.7/site-packages/nova/conductor/manager.py", line 616, in build_instances<br>
2015-11-24 19:23:38.385 17770 TRACE nova.scheduler.driver request_spec, filter_properties)<br>
2015-11-24 19:23:38.385 17770 TRACE nova.scheduler.driver File "/usr/lib/python2.7/site-packages/nova/scheduler/client/__init__.py", line 49, in select_destinations<br>
2015-11-24 19:23:38.385 17770 TRACE nova.scheduler.driver context, request_spec, filter_properties)<br>
2015-11-24 19:23:38.385 17770 TRACE nova.scheduler.driver File "/usr/lib/python2.7/site-packages/nova/scheduler/client/__init__.py", line 35, in __run_method<br>
2015-11-24 19:23:38.385 17770 TRACE nova.scheduler.driver return getattr(self.instance, __name)(*args, **kwargs)<br>
2015-11-24 19:23:38.385 17770 TRACE nova.scheduler.driver File "/usr/lib/python2.7/site-packages/nova/scheduler/client/query.py", line 34, in select_destinations<br>
2015-11-24 19:23:38.385 17770 TRACE nova.scheduler.driver context, request_spec, filter_properties)<br>
2015-11-24 19:23:38.385 17770 TRACE nova.scheduler.driver File "/usr/lib/python2.7/site-packages/nova/scheduler/rpcapi.py", line 108, in select_destinations<br>
2015-11-24 19:23:38.385 17770 TRACE nova.scheduler.driver request_spec=request_spec, filter_properties=filter_properties)<br>
2015-11-24 19:23:38.385 17770 TRACE nova.scheduler.driver File "/usr/lib/python2.7/site-packages/oslo/messaging/rpc/client.py", line 152, in call<br>
2015-11-24 19:23:38.385 17770 TRACE nova.scheduler.driver retry=self.retry)<br>
2015-11-24 19:23:38.385 17770 TRACE nova.scheduler.driver File "/usr/lib/python2.7/site-packages/oslo/messaging/transport.py", line 90, in _send<br>
2015-11-24 19:23:38.385 17770 TRACE nova.scheduler.driver timeout=timeout, retry=retry)<br>
2015-11-24 19:23:38.385 17770 TRACE nova.scheduler.driver File "/usr/lib/python2.7/site-packages/oslo/messaging/_drivers/amqpdriver.py", line 408, in send<br>
2015-11-24 19:23:38.385 17770 TRACE nova.scheduler.driver retry=retry)<br>
2015-11-24 19:23:38.385 17770 TRACE nova.scheduler.driver File "/usr/lib/python2.7/site-packages/oslo/messaging/_drivers/amqpdriver.py", line 397, in _send<br>
2015-11-24 19:23:38.385 17770 TRACE nova.scheduler.driver result = self._waiter.wait(msg_id, timeout)<br>
2015-11-24 19:23:38.385 17770 TRACE nova.scheduler.driver File "/usr/lib/python2.7/site-packages/oslo/messaging/_drivers/amqpdriver.py", line 285, in wait<br>
2015-11-24 19:23:38.385 17770 TRACE nova.scheduler.driver reply, ending = self._poll_connection(msg_id, timeout)<br>
2015-11-24 19:23:38.385 17770 TRACE nova.scheduler.driver File "/usr/lib/python2.7/site-packages/oslo/messaging/_drivers/amqpdriver.py", line 235, in _poll_connection<br>
2015-11-24 19:23:38.385 17770 TRACE nova.scheduler.driver % msg_id)<br>
2015-11-24 19:23:38.385 17770 TRACE nova.scheduler.driver MessagingTimeout: Timed out waiting for a reply to message ID d015995266a24e05b21ff0cd2ff3c6bc<br>
2015-11-24 19:23:38.385 17770 TRACE nova.scheduler.driver <br>
2015-11-24 19:23:38.388 17770 WARNING nova.scheduler.driver [req-24c4ce2a-6516-401e-a6f8-8a89758cc6fa None] [instance: 66147c0d-fa53-4e2b-8447-d4f20ac7bcbb] Setting instance to ERROR state.<br>
<br>
<span style="font-weight: bold;">controller# systemctl status rabbitmq-server</span><br>
rabbitmq-server.service - RabbitMQ broker<br>
Loaded: loaded (/usr/lib/systemd/system/rabbitmq-server.service; enabled)<br>
Active: <span style="color: rgb(51, 153, 102);">active (running) </span>since Tue 2015-11-24 15:02:41 CST; 4h 43min ago<br>
Process: 137583 ExecStop=/usr/lib/rabbitmq/bin/rabbitmqctl stop (code=exited, status=0/SUCCESS)<br>
Main PID: 137639 (beam.smp)<br>
CGroup: /system.slice/rabbitmq-server.service<br>
├─137639 /usr/lib64/erlang/erts-5.10.4/bin/beam.smp -W w -K true -A30 -P 1048576 -- -root /usr/lib64/erlang -progname erl -- -home /var/lib/rabbitmq -- -pa /usr/lib/rabbitmq/lib/rabbitmq_s...<br>
├─137654 /usr/lib64/erlang/erts-5.10.4/bin/epmd -daemon<br>
├─137767 inet_gethost 4<br>
└─137768 inet_gethost 4<br>
<br>
Nov 24 15:02:40 controller systemd[1]: rabbitmq-server.service: Got notification message from PID 137701, but reception only permitted for PID 137639<br>
Nov 24 15:02:40 controller systemd[1]: rabbitmq-server.service: Got notification message from PID 137702, but reception only permitted for PID 137639<br>
Nov 24 15:02:41 controller rabbitmq-server[137639]: RabbitMQ 3.3.5. Copyright (C) 2007-2014 GoPivotal, Inc.<br>
Nov 24 15:02:41 controller rabbitmq-server[137639]: ## ## Licensed under the MPL. See
<a href="http://www.rabbitmq.com/">http://www.rabbitmq.com/</a><br>
Nov 24 15:02:41 controller rabbitmq-server[137639]: ## ##<br>
Nov 24 15:02:41 controller rabbitmq-server[137639]: ########## Logs: <a href="mailto:/var/log/rabbitmq/rabbit@controller.log">
/var/log/rabbitmq/rabbit@controller.log</a><br>
Nov 24 15:02:41 controller rabbitmq-server[137639]: ###### ## <a href="mailto:/var/log/rabbitmq/rabbit@controller-sasl.log">
/var/log/rabbitmq/rabbit@controller-sasl.log</a><br>
Nov 24 15:02:41 controller rabbitmq-server[137639]: ##########<br>
Nov 24 15:02:41 controller rabbitmq-server[137639]: Starting broker... completed with 6 plugins.<br>
Nov 24 15:02:41 controller systemd[1]: Started RabbitMQ broker.<br>
</div>
</div>
</span>
</body>
</html>