<div dir="ltr">Actually, broker is the only thing acting "right" between broker and agent. Broker is up when I bring the system up but agent is restarting all the time. Have a look<div><br></div><div>The 11th is when I restarted this node after doing 'reinstall' in the web UI</div><div><br></div><div><div>● ovirt-ha-broker.service - oVirt Hosted Engine High Availability Communications Broker</div><div> Loaded: loaded (/usr/lib/systemd/system/ovirt-ha-broker.service; enabled; vendor preset: disabled)</div><div> Active: active (running) since Sat 2016-06-11 13:09:51 EDT; 3 days ago</div><div> Main PID: 1285 (ovirt-ha-broker)</div><div> CGroup: /system.slice/ovirt-ha-broker.service</div><div> └─1285 /usr/bin/python /usr/share/ovirt-hosted-engine-ha/ovirt-ha-broker --no-daemon</div><div><br></div><div>Jun 15 09:23:56 njsevcnp01 ovirt-ha-broker[1285]: INFO:mgmt_bridge.MgmtBridge:Found bridge ovirtmgmt with ports</div><div>Jun 15 09:23:58 njsevcnp01 ovirt-ha-broker[1285]: INFO:ovirt_hosted_engine_ha.broker.listener.ConnectionHandler:Connection established</div><div>Jun 15 09:23:58 njsevcnp01 ovirt-ha-broker[1285]: INFO:ovirt_hosted_engine_ha.broker.listener.ConnectionHandler:Connection closed</div><div>Jun 15 09:23:58 njsevcnp01 ovirt-ha-broker[1285]: INFO:ovirt_hosted_engine_ha.broker.listener.ConnectionHandler:Connection established</div><div>Jun 15 09:23:58 njsevcnp01 ovirt-ha-broker[1285]: INFO:ovirt_hosted_engine_ha.broker.listener.ConnectionHandler:Connection closed</div><div>Jun 15 09:23:58 njsevcnp01 ovirt-ha-broker[1285]: INFO:ovirt_hosted_engine_ha.broker.listener.ConnectionHandler:Connection established</div><div>Jun 15 09:23:58 njsevcnp01 ovirt-ha-broker[1285]: INFO:ovirt_hosted_engine_ha.broker.listener.ConnectionHandler:Connection closed</div><div>Jun 15 09:23:58 njsevcnp01 ovirt-ha-broker[1285]: INFO:ovirt_hosted_engine_ha.broker.listener.ConnectionHandler:Connection established</div><div>Jun 15 09:23:58 njsevcnp01 ovirt-ha-broker[1285]: INFO:ovirt_hosted_engine_ha.broker.listener.ConnectionHandler:Connection closed</div><div>Jun 15 09:23:58 njsevcnp01 ovirt-ha-broker[1285]: INFO:mem_free.MemFree:memFree: 26408</div></div><div><br></div><div>Uptime of proc .. </div><div><br></div><div><div># ps -Aef | grep -i broker</div><div>vdsm 1285 1 2 Jun11 ? 02:27:50 /usr/bin/python /usr/share/ovirt-hosted-engine-ha/ovirt-ha-broker --no-daemon</div></div><div><br></div><div>But the agent... is restarting all the time</div><div><br></div><div><div># ps -Aef | grep -i ovirt-ha-agent</div><div>vdsm 76116 1 0 09:19 ? 00:00:01 /usr/bin/python /usr/share/ovirt-hosted-engine-ha/ovirt-ha-agent --no-daemon</div></div><div><br></div><div>9:19 AM ET is last restart. Even the logs say it</div><div><br></div><div><div>[root@njsevcnp01 ovirt-hosted-engine-ha]# grep -i 'restarting agent' agent.log | wc -l</div><div>232719</div></div><div><br></div><div>And the restarts every </div><div><br></div><div><div>[root@njsevcnp01 ovirt-hosted-engine-ha]# tail -n 300 agent.log | grep -i 'restarting agent'</div><div>MainThread::WARNING::2016-06-15 09:23:53,029::agent::208::ovirt_hosted_engine_ha.agent.agent.Agent::(_run_agent) Restarting agent, attempt '6'</div><div>MainThread::WARNING::2016-06-15 09:24:28,953::agent::208::ovirt_hosted_engine_ha.agent.agent.Agent::(_run_agent) Restarting agent, attempt '7'</div><div>MainThread::WARNING::2016-06-15 09:25:04,879::agent::208::ovirt_hosted_engine_ha.agent.agent.Agent::(_run_agent) Restarting agent, attempt '8'</div><div>MainThread::WARNING::2016-06-15 09:25:40,790::agent::208::ovirt_hosted_engine_ha.agent.agent.Agent::(_run_agent) Restarting agent, attempt '9'</div><div>MainThread::WARNING::2016-06-15 09:26:17,136::agent::208::ovirt_hosted_engine_ha.agent.agent.Agent::(_run_agent) Restarting agent, attempt '0'</div><div>MainThread::WARNING::2016-06-15 09:26:53,063::agent::208::ovirt_hosted_engine_ha.agent.agent.Agent::(_run_agent) Restarting agent, attempt '1'</div></div><div><br></div><div>Full log of restart is like this saying "connection timed out" but its not saying to *what* is timing out, so I have nothing else to really go on here</div><div><br></div><div><div>[root@njsevcnp01 ovirt-hosted-engine-ha]# tail -n 300 agent.log | grep -i restart </div><div>MainThread::ERROR::2016-06-15 09:24:23,948::agent::205::ovirt_hosted_engine_ha.agent.agent.Agent::(_run_agent) Error: 'Failed to start monitor <type 'type'>, options {'hostname': 'njsevcnp01'}: Connection timed out' - trying to restart agent</div><div>MainThread::WARNING::2016-06-15 09:24:28,953::agent::208::ovirt_hosted_engine_ha.agent.agent.Agent::(_run_agent) Restarting agent, attempt '7'</div><div>MainThread::ERROR::2016-06-15 09:24:59,874::agent::205::ovirt_hosted_engine_ha.agent.agent.Agent::(_run_agent) Error: 'Failed to start monitor <type 'type'>, options {'hostname': 'njsevcnp01'}: Connection timed out' - trying to restart agent</div><div>MainThread::WARNING::2016-06-15 09:25:04,879::agent::208::ovirt_hosted_engine_ha.agent.agent.Agent::(_run_agent) Restarting agent, attempt '8'</div><div>MainThread::ERROR::2016-06-15 09:25:35,785::agent::205::ovirt_hosted_engine_ha.agent.agent.Agent::(_run_agent) Error: 'Failed to start monitor <type 'type'>, options {'hostname': 'njsevcnp01'}: Connection timed out' - trying to restart agent</div><div>MainThread::WARNING::2016-06-15 09:25:40,790::agent::208::ovirt_hosted_engine_ha.agent.agent.Agent::(_run_agent) Restarting agent, attempt '9'</div><div>MainThread::ERROR::2016-06-15 09:26:12,131::agent::205::ovirt_hosted_engine_ha.agent.agent.Agent::(_run_agent) Error: 'Failed to start monitor <type 'type'>, options {'hostname': 'njsevcnp01'}: Connection timed out' - trying to restart agent</div><div>MainThread::WARNING::2016-06-15 09:26:17,136::agent::208::ovirt_hosted_engine_ha.agent.agent.Agent::(_run_agent) Restarting agent, attempt '0'</div><div>MainThread::ERROR::2016-06-15 09:26:48,058::agent::205::ovirt_hosted_engine_ha.agent.agent.Agent::(_run_agent) Error: 'Failed to start monitor <type 'type'>, options {'hostname': 'njsevcnp01'}: Connection timed out' - trying to restart agent</div><div>MainThread::WARNING::2016-06-15 09:26:53,063::agent::208::ovirt_hosted_engine_ha.agent.agent.Agent::(_run_agent) Restarting agent, attempt '1'</div><div>MainThread::ERROR::2016-06-15 09:27:23,969::agent::205::ovirt_hosted_engine_ha.agent.agent.Agent::(_run_agent) Error: 'Failed to start monitor <type 'type'>, options {'hostname': 'njsevcnp01'}: Connection timed out' - trying to restart agent</div><div>MainThread::WARNING::2016-06-15 09:27:28,973::agent::208::ovirt_hosted_engine_ha.agent.agent.Agent::(_run_agent) Restarting agent, attempt '2'</div></div><div><br></div><div><br></div><div>Storage is also completely fine. No logs stating anything "going away" or having issues. Engine has dedicated NFS NAS device meanwhile VM storage is completely separate storage cluster. Storage has 100% dedicated backend network with no changes being done </div><div><br></div><div><br></div></div><div class="gmail_extra"><br><div class="gmail_quote">On Wed, Jun 15, 2016 at 7:42 AM, Martin Sivak <span dir="ltr"><<a href="mailto:msivak@redhat.com" target="_blank">msivak@redhat.com</a>></span> wrote:<br><blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex"><span class="">> Jun 14 08:11:11 njsevcnp01 ovirt-ha-agent[15713]: ovirt-ha-agent<br>
> ovirt_hosted_engine_ha.lib.brokerlink.BrokerLink ERROR Connection closed:<br>
> Connection timed out<br>
> Jun 14 08:11:11 <a href="http://njsevcnp01.fixflyer.com" rel="noreferrer" target="_blank">njsevcnp01.fixflyer.com</a> ovirt-ha-agent[15713]:<br>
> ovirt-ha-agent ovirt_hosted_engine_ha.agent.agent.Agent ERROR Error: 'Failed<br>
> to start monitor <type 'type'>, options {'hostname': 'njsevcnp01'}:<br>
> Connection timed out' - trying to restart agent<br>
<br>
</span>Broker is broken or down. Check the status of ovirt-ha-broker service.<br>
<span class=""><br>
> The other interesting thing is this log from node01. The odd thing is that<br>
> it seems there is some split brain somewhere in oVirt because this log is<br>
> from node02 but it is asking the engine and its getting back "vm not running<br>
> on this host' rather than 'stale data'. But I dont know engine internals<br>
<br>
</span>This is another piece that points to broker or storage issues. Agent<br>
collects local data and then publishes them to other nodes through<br>
broker. So it is possible for the agent to know the status of the VM<br>
locally, but not be able to publish it.<br>
<br>
hosted-engine command line tool then reads the synchronization<br>
whiteboard too, but it does not see anything that was not published<br>
and ends up reporting stale data.<br>
<span class=""><br>
>> What is the status of the hosted engine services? systemctl status<br>
>> ovirt-ha-agent ovirt-ha-broker<br>
<br>
</span>Please check the services.<br>
<br>
Best regards<br>
<span class="HOEnZb"><font color="#888888"><br>
Martin<br>
</font></span><div class="HOEnZb"><div class="h5"><br>
On Tue, Jun 14, 2016 at 2:16 PM, Charles Kozler <<a href="mailto:charles@fixflyer.com">charles@fixflyer.com</a>> wrote:<br>
> Martin -<br>
><br>
> One thing I noticed on all of the nodes is this:<br>
><br>
> Jun 14 08:11:11 njsevcnp01 ovirt-ha-agent[15713]: ovirt-ha-agent<br>
> ovirt_hosted_engine_ha.lib.brokerlink.BrokerLink ERROR Connection closed:<br>
> Connection timed out<br>
> Jun 14 08:11:11 <a href="http://njsevcnp01.fixflyer.com" rel="noreferrer" target="_blank">njsevcnp01.fixflyer.com</a> ovirt-ha-agent[15713]:<br>
> ovirt-ha-agent ovirt_hosted_engine_ha.agent.agent.Agent ERROR Error: 'Failed<br>
> to start monitor <type 'type'>, options {'hostname': 'njsevcnp01'}:<br>
> Connection timed out' - trying to restart agent<br>
><br>
> Then the agent is restarted<br>
><br>
> [root@njsevcnp01 ~]# ps -Aef | grep -i ovirt-ha-agent | grep -iv grep<br>
> vdsm 15713 1 0 08:09 ? 00:00:01 /usr/bin/python<br>
> /usr/share/ovirt-hosted-engine-ha/ovirt-ha-agent --no-daemon<br>
><br>
> I dont know why the connection would time out because as you can see that<br>
> log is from node01 and I cant figure out why its timing out on the<br>
> connection<br>
><br>
> The other interesting thing is this log from node01. The odd thing is that<br>
> it seems there is some split brain somewhere in oVirt because this log is<br>
> from node02 but it is asking the engine and its getting back "vm not running<br>
> on this host' rather than 'stale data'. But I dont know engine internals<br>
><br>
> MainThread::INFO::2016-06-14<br>
> 08:13:05,163::state_machine::171::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(refresh)<br>
> Host njsevcnp02 (id 2): {hostname: njsevcnp02, host-id: 2, engine-status:<br>
> {reason: vm not running on this host, health: bad, vm: down, detail:<br>
> unknown}, score: 0, stopped: True, maintenance: False, crc32: 25da07df,<br>
> host-ts: 3030}<br>
> MainThread::INFO::2016-06-14<br>
> 08:13:05,163::state_machine::171::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(refresh)<br>
> Host njsevcnp03 (id 3): {hostname: njsevcnp03, host-id: 3, engine-status:<br>
> {reason: vm not running on this host, health: bad, vm: down, detail:<br>
> unknown}, score: 0, stopped: True, maintenance: False, crc32: c67818cb,<br>
> host-ts: 10877406}<br>
><br>
><br>
> And that same log on node02 where the engine is running<br>
><br>
><br>
> MainThread::INFO::2016-06-14<br>
> 08:15:44,451::state_machine::171::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(refresh)<br>
> Host njsevcnp01 (id 1): {hostname: njsevcnp01, host-id: 1, engine-status:<br>
> {reason: vm not running on this host, health: bad, vm: down, detail:<br>
> unknown}, score: 0, stopped: True, maintenance: False, crc32: 260dbf06,<br>
> host-ts: 327}<br>
> MainThread::INFO::2016-06-14<br>
> 08:15:44,451::state_machine::171::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(refresh)<br>
> Host njsevcnp03 (id 3): {hostname: njsevcnp03, host-id: 3, engine-status:<br>
> {reason: vm not running on this host, health: bad, vm: down, detail:<br>
> unknown}, score: 0, stopped: True, maintenance: False, crc32: c67818cb,<br>
> host-ts: 10877406}<br>
> MainThread::INFO::2016-06-14<br>
> 08:15:44,451::state_machine::174::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(refresh)<br>
> Local (id 2): {engine-health: {health: good, vm: up, detail: up}, bridge:<br>
> True, mem-free: 20702.0, maintenance: False, cpu-load: None, gateway: True}<br>
> MainThread::INFO::2016-06-14<br>
> 08:15:44,452::brokerlink::111::ovirt_hosted_engine_ha.lib.brokerlink.BrokerLink::(notify)<br>
> Trying: notify time=1465906544.45 type=state_transition<br>
> detail=StartState-ReinitializeFSM hostname=njsevcnp02<br>
><br>
><br>
><br>
><br>
><br>
><br>
><br>
><br>
> On Tue, Jun 14, 2016 at 7:59 AM, Martin Sivak <<a href="mailto:msivak@redhat.com">msivak@redhat.com</a>> wrote:<br>
>><br>
>> Hi,<br>
>><br>
>> is there anything interesting in the hosted engine log files?<br>
>> /var/log/ovirt-hosted-engine-ha/agent.log<br>
>><br>
>> There should be something appearing there every 10 seconds or faster.<br>
>><br>
>> What is the status of the hosted engine services? systemctl status<br>
>> ovirt-ha-agent ovirt-ha-broker<br>
>><br>
>><br>
>> Best regards<br>
>><br>
>> --<br>
>> Martin Sivak<br>
>> SLA / oVirt<br>
>><br>
>> On Sat, Jun 11, 2016 at 8:53 PM, Charles Kozler <<a href="mailto:charles@fixflyer.com">charles@fixflyer.com</a>><br>
>> wrote:<br>
>> > See linked images please. As you can see all three nodes are reporting<br>
>> > stale<br>
>> > data. The results of this are:<br>
>> ><br>
>> > 1. Not all VM's migrate seamlessly in the cluster. Sometimes I have to<br>
>> > shut<br>
>> > them down to get them to be able to migrate again<br>
>> ><br>
>> > 2. Hosted engine refuses to move due to constraints (image). This part<br>
>> > doesnt make sense to me because I can forcefully shut it down and then<br>
>> > go<br>
>> > directly on a hosted engine node and bring it back up. Also, the Web UI<br>
>> > shows all nodes under the cluster except then it thinks its not apart of<br>
>> > the<br>
>> > cluster<br>
>> ><br>
>> > 3. Time is in sync (image)<br>
>> ><br>
>> > 4. Storage is 100% fine. Gluster back end reports mirroring and status<br>
>> > 'started'. No split brain has occurred and ovirt nodes have never lost<br>
>> > connectivity to storage<br>
>> ><br>
>> > 5. I reinstalled all three nodes. For some reason only node 3 still<br>
>> > shows as<br>
>> > having updates available. (image). For clarity, I did not click<br>
>> > "upgrade" I<br>
>> > simply did 'reinstall' from the Web UI. Having looked at the output and<br>
>> > yum.log from /var/log it almost looks like it did do an update. All<br>
>> > package<br>
>> > versions across all three nodes are the same (respective to ovirt/vdsm)<br>
>> > (image). For some reason though<br>
>> > ovirt-engine-appliance-3.6-20160126.1.el7.centos.noarch exists on node 1<br>
>> > but<br>
>> > not on node 2 or 3. Could this be relative? I dont recall installing<br>
>> > that<br>
>> > specifically on node 1 but I may have<br>
>> ><br>
>> > Been slamming my head on this so I am hoping you can provide some<br>
>> > assistance<br>
>> ><br>
>> > <a href="http://imgur.com/a/6xkaS" rel="noreferrer" target="_blank">http://imgur.com/a/6xkaS</a><br>
>> ><br>
>> > Thanks!<br>
>> ><br>
>> > --<br>
>> ><br>
>> > Charles Kozler<br>
>> > Vice President, IT Operations<br>
>> ><br>
>> > FIX Flyer, LLC<br>
>> > 225 Broadway | Suite 1600 | New York, NY 10007<br>
>> > <a href="tel:1-888-349-3593" value="+18883493593">1-888-349-3593</a><br>
>> > <a href="http://www.fixflyer.com" rel="noreferrer" target="_blank">http://www.fixflyer.com</a><br>
>> ><br>
>> > NOTICE TO RECIPIENT: THIS E-MAIL IS MEANT ONLY FOR THE INTENDED<br>
>> > RECIPIENT(S)<br>
>> > OF THE TRANSMISSION, AND CONTAINS CONFIDENTIAL INFORMATION WHICH IS<br>
>> > PROPRIETARY TO FIX FLYER LLC. ANY UNAUTHORIZED USE, COPYING,<br>
>> > DISTRIBUTION,<br>
>> > OR DISSEMINATION IS STRICTLY PROHIBITED. ALL RIGHTS TO THIS INFORMATION<br>
>> > IS<br>
>> > RESERVED BY FIX FLYER LLC. IF YOU ARE NOT THE INTENDED RECIPIENT,<br>
>> > PLEASE<br>
>> > CONTACT THE SENDER BY REPLY E-MAIL AND PLEASE DELETE THIS E-MAIL FROM<br>
>> > YOUR<br>
>> > SYSTEM AND DESTROY ANY COPIES.<br>
>> ><br>
>> > _______________________________________________<br>
>> > Users mailing list<br>
>> > <a href="mailto:Users@ovirt.org">Users@ovirt.org</a><br>
>> > <a href="http://lists.ovirt.org/mailman/listinfo/users" rel="noreferrer" target="_blank">http://lists.ovirt.org/mailman/listinfo/users</a><br>
>> ><br>
><br>
><br>
><br>
><br>
> --<br>
><br>
> Charles Kozler<br>
> Vice President, IT Operations<br>
><br>
> FIX Flyer, LLC<br>
> 225 Broadway | Suite 1600 | New York, NY 10007<br>
> <a href="tel:1-888-349-3593" value="+18883493593">1-888-349-3593</a><br>
> <a href="http://www.fixflyer.com" rel="noreferrer" target="_blank">http://www.fixflyer.com</a><br>
><br>
> NOTICE TO RECIPIENT: THIS E-MAIL IS MEANT ONLY FOR THE INTENDED RECIPIENT(S)<br>
> OF THE TRANSMISSION, AND CONTAINS CONFIDENTIAL INFORMATION WHICH IS<br>
> PROPRIETARY TO FIX FLYER LLC. ANY UNAUTHORIZED USE, COPYING, DISTRIBUTION,<br>
> OR DISSEMINATION IS STRICTLY PROHIBITED. ALL RIGHTS TO THIS INFORMATION IS<br>
> RESERVED BY FIX FLYER LLC. IF YOU ARE NOT THE INTENDED RECIPIENT, PLEASE<br>
> CONTACT THE SENDER BY REPLY E-MAIL AND PLEASE DELETE THIS E-MAIL FROM YOUR<br>
> SYSTEM AND DESTROY ANY COPIES.<br>
</div></div></blockquote></div><br><br clear="all"><div><br></div>-- <br><div class="gmail_signature" data-smartmail="gmail_signature"><div dir="ltr"><div><div dir="ltr"><div><div dir="ltr"><div><br style="color:rgb(0,0,0);font-family:'Times New Roman';font-size:medium"><div style="color:rgb(0,0,0);font-family:'Times New Roman'"><strong><span style="font-family:'times new roman',times,serif"><font size="2">Charles Kozler</font></span></strong></div><div style="color:rgb(0,0,0);font-family:'Times New Roman'"><em><span style="font-family:'times new roman',times,serif"><font size="2">Vice President, IT Operations</font></span></em></div><div style="color:rgb(0,0,0);font-family:'Times New Roman';font-size:medium"> </div><div style="color:rgb(0,0,0);font-family:'Times New Roman'"><span style="font-family:'times new roman',times,serif"><font size="2">FIX Flyer, LLC</font></span></div><div style="color:rgb(0,0,0);font-family:'Times New Roman'"><span style="font-family:'times new roman',times,serif"><font size="2">225 Broadway | Suite 1600 | New York, NY 10007</font></span></div><div style="color:rgb(0,0,0);font-family:'Times New Roman'"><span style="font-family:'times new roman',times,serif"><font size="2">1-888-349-3593</font></span></div><div style="color:rgb(0,0,0);font-family:'Times New Roman'"><a href="http://fixflyer.com" target="_blank"><span style="font-family:'times new roman',times,serif"><font size="2">http://www.fixflyer.com</font></span></a></div><div style="color:rgb(0,0,0);font-family:'Times New Roman'"><font size="2"> </font></div><div style="color:rgb(0,0,0);font-family:'Times New Roman'"><font size="1">NOTICE TO RECIPIENT: THIS E-MAIL IS MEANT ONLY FOR THE INTENDED RECIPIENT(S) OF THE TRANSMISSION, AND CONTAINS CONFIDENTIAL INFORMATION WHICH IS PROPRIETARY TO FIX FLYER LLC. ANY UNAUTHORIZED USE, COPYING, DISTRIBUTION, OR DISSEMINATION IS STRICTLY PROHIBITED. ALL RIGHTS TO THIS INFORMATION IS RESERVED BY FIX FLYER LLC. IF YOU ARE NOT THE INTENDED RECIPIENT, PLEASE CONTACT THE SENDER BY REPLY E-MAIL AND PLEASE DELETE THIS E-MAIL FROM YOUR SYSTEM AND DESTROY ANY COPIES.</font></div></div></div></div></div></div></div></div>
</div>