piVCCU Absturz

rbeudel · Beitrag von **rbeudel** » 17.10.2018, 10:43

Hallo,
gestern Abend wurde ich durch den Zuruf meiner Frau:"Das Licht unten geht nicht aus" aufgeschreckt. Als ich mal schnell ins Programm schauen wollte, wurde das Programm nur für einen sekundenbruchteil unvollständig von der WebUI dargestellt. Dann kam sofort die Seite " Die CCU ist noch nicht fertig" oder so ähnlich. Das blieb dann auch so. Anderer Browser gleiches Symptom. Funktionen konnten auch nicht mehr ausgelöst werden. Openhab zeigte die Things als Online an. Mit winscp habe ich keine Fehler in den Logdateien gefunden, jedenfalls die ich gefunden habe. Also habe ich openhab und dann piVCCU angehalten und dann neu gestartet. Das funktionierte ohne Probleme. Seit dem läuft es wieder.
Danach war folgender Text in der Syslog:

Code: Alles auswählen

Oct 16 22:23:34 openhab systemd[1]: Stopping piVCCU...
Oct 16 22:23:34 openhab stop_container.sh[6555]: Error parsing response <html>
Oct 16 22:23:34 openhab stop_container.sh[6555]: <head>
Oct 16 22:23:34 openhab stop_container.sh[6555]: #011<title>HomeMatic</title>
Oct 16 22:23:34 openhab stop_container.sh[6555]:   #015
Oct 16 22:23:34 openhab stop_container.sh[6555]:   <style rel="stylesheet" type="text/css">
Oct 16 22:23:34 openhab stop_container.sh[6555]:   #015
Oct 16 22:23:34 openhab stop_container.sh[6555]:     .Invisible {
Oct 16 22:23:34 openhab stop_container.sh[6555]:       display: none;
Oct 16 22:23:34 openhab stop_container.sh[6555]:     }
Oct 16 22:23:34 openhab stop_container.sh[6555]:       #015
Oct 16 22:23:34 openhab stop_container.sh[6555]:   </style>
Oct 16 22:23:34 openhab stop_container.sh[6555]:     <script type="text/javascript" src="/webui/js/extern/jquery.js?_version_=2.0pre1"></script>
Oct 16 22:23:34 openhab stop_container.sh[6555]:     <script type="text/javascript" src="/webui/js/extern/jqueryURLPlugin.js?_version_=2.0pre1"></script>
Oct 16 22:23:34 openhab stop_container.sh[6555]:     <script type="text/javascript" src="/webui/js/lang/loadTextResource.js"></script>
Oct 16 22:23:34 openhab stop_container.sh[6555]:     <script type="text/javascript" src="/webui/js/lang/translate.js"></script>
Oct 16 22:23:34 openhab stop_container.sh[6555]:   <script type="text/javascript">
Oct 16 22:23:34 openhab stop_container.sh[6555]: #015
Oct 16 22:23:34 openhab stop_container.sh[6555]:     CHECK_INTERVAL = 3000;  // Intervall, in dem geprüft wird, ob der ReGa Webserver aktiv ist
Oct 16 22:23:34 openhab stop_container.sh[6555]:   #015
Oct 16 22:23:34 openhab stop_container.sh[6555]:     /**
Oct 16 22:23:34 openhab stop_container.sh[6555]:      * Erzeugt eine Instanz des XMLHttpRequest-Objekts
Oct 16 22:23:34 openhab stop_container.sh[6555]:      */
Oct 16 22:23:34 openhab stop_container.sh[6555]:     createXMLHttpRequest = function()
Oct 16 22:23:34 openhab stop_container.sh[6555]:     {
Oct 16 22:23:34 openhab stop_container.sh[6555]:       var xmlHttp = null;
Oct 16 22:23:34 openhab stop_container.sh[6555]:       #015
Oct 16 22:23:34 openhab stop_container.sh[6555]:       if ( window.XMLHttpRequest )
Oct 16 22:23:34 openhab stop_container.sh[6555]:       {
Oct 16 22:23:34 openhab stop_container.sh[6555]:         xmlHttp = new XMLHttpRequest();
Oct 16 22:23:34 openhab stop_container.sh[6555]:       }
Oct 16 22:23:34 openhab stop_container.sh[6555]:       else if ( window.ActiveXObject )
Oct 16 22:23:34 openhab stop_container.sh[6555]:       {
Oct 16 22:23:34 openhab stop_container.sh[6555]:         try
Oct 16 22:23:34 openhab stop_container.sh[6555]:         {
Oct 16 22:23:34 openhab stop_container.sh[6555]:           xmlHttp = new ActiveXObject("Msxml2.XMLHTTP");
Oct 16 22:23:34 openhab stop_container.sh[6555]:         }
Oct 16 22:23:34 openhab stop_container.sh[6555]:         catch (ex)
Oct 16 22:23:34 openhab stop_container.sh[6555]:         {
Oct 16 22:23:34 openhab stop_container.sh[6555]:           try
Oct 16 22:23:34 openhab stop_container.sh[6555]:           {
Oct 16 22:23:34 openhab stop_container.sh[6555]:             xmlHttp = new ActiveXObject("Microsoft.XMLHTTP");
Oct 16 22:23:34 openhab stop_container.sh[6555]:           }
Oct 16 22:23:34 openhab stop_container.sh[6555]:           catch (ex)
Oct 16 22:23:34 openhab stop_container.sh[6555]:           {
Oct 16 22:23:34 openhab stop_container.sh[6555]:             // leer
Oct 16 22:23:34 openhab stop_container.sh[6555]:           }
Oct 16 22:23:34 openhab stop_container.sh[6555]:         }
Oct 16 22:23:34 openhab stop_container.sh[6555]:       }
Oct 16 22:23:34 openhab stop_container.sh[6555]:       #015
Oct 16 22:23:34 openhab stop_container.sh[6555]:       return xmlHttp;
Oct 16 22:23:34 openhab stop_container.sh[6555]:     };
Oct 16 22:23:34 openhab stop_container.sh[6555]:     #015
Oct 16 22:23:34 openhab stop_container.sh[6555]:     /**
Oct 16 22:23:34 openhab stop_container.sh[6555]:      * Prüft zyklsich, ob der ReGa Webserver verfügbar ist.
Oct 16 22:23:34 openhab stop_container.sh[6555]:      */
Oct 16 22:23:34 openhab stop_container.sh[6555]:     check = function()
Oct 16 22:23:34 openhab stop_container.sh[6555]:     {
Oct 16 22:23:34 openhab stop_container.sh[6555]:       var request = createXMLHttpRequest();
Oct 16 22:23:34 openhab stop_container.sh[6555]:       #015
Oct 16 22:23:34 openhab stop_container.sh[6555]:       if (request)
Oct 16 22:23:34 openhab stop_container.sh[6555]:       {
Oct 16 22:23:34 openhab stop_container.sh[6555]:         request.open("GET", "/ise/checkrega.cgi", false); // synchrone Anfrage
Oct 16 22:23:34 openhab stop_container.sh[6555]:         request.send(null);
Oct 16 22:23:34 openhab stop_container.sh[6555]:         #015
Oct 16 22:23:34 openhab stop_container.sh[6555]:         if ("OK" == request.responseText)
Oct 16 22:23:34 openhab stop_container.sh[6555]:         {
Oct 16 22:23:34 openhab stop_container.sh[6555]:           window.setTimeout("window.location.href='/index.htm'", 1000);
Oct 16 22:23:34 openhab stop_container.sh[6555]:         }
Oct 16 22:23:34 openhab stop_container.sh[6555]:         else
Oct 16 22:23:34 openhab stop_container.sh[6555]:         {
Oct 16 22:23:34 openhab stop_container.sh[6555]:           window.setTimeout("check();", CHECK_INTERVAL);
Oct 16 22:23:34 openhab stop_container.sh[6555]:         }
Oct 16 22:23:34 openhab stop_container.sh[6555]:       }
Oct 16 22:23:34 openhab stop_container.sh[6555]:     };
Oct 16 22:23:34 openhab stop_container.sh[6555]:     #015
Oct 16 22:23:34 openhab stop_container.sh[6555]:     /**
Oct 16 22:23:34 openhab stop_container.sh[6555]:      * Wird beim Laden der Seite aufgerufen.
Oct 16 22:23:34 openhab stop_container.sh[6555]:      **/
Oct 16 22:23:34 openhab stop_container.sh[6555]:     startup = function()
Oct 16 22:23:34 openhab stop_container.sh[6555]:     {
Oct 16 22:23:34 openhab stop_container.sh[6555]:       var content = document.getElementById("content");
Oct 16 22:23:34 openhab stop_container.sh[6555]:       content.className = "";
Oct 16 22:23:34 openhab stop_container.sh[6555]:       #015
Oct 16 22:23:34 openhab stop_container.sh[6555]:       check();
Oct 16 22:23:34 openhab stop_container.sh[6555]:     };
Oct 16 22:23:34 openhab stop_container.sh[6555]:     #015
Oct 16 22:23:34 openhab stop_container.sh[6555]:   </script>
Oct 16 22:23:34 openhab stop_container.sh[6555]: </head>
Oct 16 22:23:34 openhab stop_container.sh[6555]: <body style="background-color: #183473;color: #FFFFFF;" onload="startup();">
Oct 16 22:23:34 openhab stop_container.sh[6555]:   #015
Oct 16 22:23:34 openhab stop_container.sh[6555]: <div id="content" class="Invisible">
Oct 16 22:23:34 openhab stop_container.sh[6555]:   <div id="content_" align="center" >
Oct 16 22:23:34 openhab stop_container.sh[6555]:     <h1>${ccuNotReady}</h1>
Oct 16 22:23:34 openhab stop_container.sh[6555]:     <p>${ccuNotReadyHint}</p>
Oct 16 22:23:34 openhab stop_container.sh[6555]:   </div>
Oct 16 22:23:34 openhab stop_container.sh[6555]:   <div align="center" style="padding-top:250px;">
Oct 16 22:23:34 openhab stop_container.sh[6555]:     <img id="imgLogo" src="/ise/img/hm-logo.png" alt=""/>
Oct 16 22:23:34 openhab stop_container.sh[6555]:   </div>
Oct 16 22:23:34 openhab stop_container.sh[6555]: </div>
Oct 16 22:23:34 openhab stop_container.sh[6555]: #015
Oct 16 22:23:34 openhab stop_container.sh[6555]: <script type="text/javascript">
Oct 16 22:23:34 openhab stop_container.sh[6555]:     translatePage();
Oct 16 22:23:34 openhab stop_container.sh[6555]: </script>
Oct 16 22:23:34 openhab stop_container.sh[6555]: #015
Oct 16 22:23:34 openhab stop_container.sh[6555]: <noscript>
Oct 16 22:23:34 openhab stop_container.sh[6555]:   <div align="center">
Oct 16 22:23:34 openhab stop_container.sh[6555]:     <!-- <p>Um die HomeMatic WebUI nutzen zu k&ouml;nnen, muss JavaScript in Ihrem Browser aktiviert sein.</p> -->
Oct 16 22:23:34 openhab stop_container.sh[6555]:       <p>Please activate JavaScript in your browser</p>
Oct 16 22:23:34 openhab stop_container.sh[6555]:   </div>
Oct 16 22:23:34 openhab stop_container.sh[6555]: </noscript>
Oct 16 22:23:34 openhab stop_container.sh[6555]:   #015
Oct 16 22:23:34 openhab stop_container.sh[6555]: </body>
Oct 16 22:23:34 openhab stop_container.sh[6555]: </html>
Oct 16 22:23:34 openhab stop_container.sh[6555]:     while executing
Oct 16 22:23:34 openhab stop_container.sh[6555]: "rega_script {system.Save();}"
Oct 16 22:23:34 openhab stop_container.sh[6555]:     (file "/etc/piVCCU3/save-rega.tcl" line 3)
Oct 16 22:23:38 openhab kernel: [486665.633766] eq3loop: eq3loop_close_slave() mmd_bidcos
Oct 16 22:23:38 openhab kernel: [486666.204066] eq3loop: eq3loop_close_slave() mmd_hmip
Oct 16 22:23:39 openhab kernel: [486666.623818] eq3loop: eq3loop_close_master() mmd_bidcos
Oct 16 22:23:39 openhab kernel: [486666.623832] eq3loop: eq3loop_close_master() mmd_bidcos destroy device
Oct 16 22:23:39 openhab kernel: [486666.624789] eq3loop: eq3loop_close_master() mmd_hmip
Oct 16 22:23:39 openhab kernel: [486666.624800] eq3loop: eq3loop_close_master() mmd_hmip destroy device
Oct 16 22:23:41 openhab avahi-daemon[355]: Interface vethpivccu.IPv6 no longer relevant for mDNS.
Oct 16 22:23:41 openhab avahi-daemon[355]: Leaving mDNS multicast group on interface vethpivccu.IPv6 with address fe80::fc92:d2ff:fe59:bd6d.
Oct 16 22:23:41 openhab kernel: [486668.677684] br0: port 2(vethpivccu) entered disabled state
Oct 16 22:23:41 openhab kernel: [486668.678909] device vethpivccu left promiscuous mode
Oct 16 22:23:41 openhab kernel: [486668.678925] br0: port 2(vethpivccu) entered disabled state
Oct 16 22:23:41 openhab avahi-daemon[355]: Withdrawing address record for fe80::fc92:d2ff:fe59:bd6d on vethpivccu.
Oct 16 22:23:41 openhab systemd[1]: pivccu.service: Main process exited, code=exited, status=1/FAILURE
Oct 16 22:23:41 openhab stop_container.sh[6555]: rmmod: ERROR: Module fake_hmrf is not currently loaded
Oct 16 22:23:41 openhab systemd[1]: Stopped piVCCU.
Oct 16 22:23:41 openhab systemd[1]: pivccu.service: Unit entered failed state.
Oct 16 22:23:41 openhab systemd[1]: pivccu.service: Failed with result 'exit-code'.
Oct 16 22:24:12 openhab systemd[1]: Starting piVCCU...
Oct 16 22:24:24 openhab start_container.sh[6776]: kernel.sched_rt_runtime_us = -1
Oct 16 22:24:24 openhab kernel: [486712.353398] br0: port 2(vethpivccu) entered blocking state
Oct 16 22:24:24 openhab kernel: [486712.353408] br0: port 2(vethpivccu) entered disabled state
Oct 16 22:24:24 openhab kernel: [486712.353612] device vethpivccu entered promiscuous mode
Oct 16 22:24:24 openhab kernel: [486712.353931] IPv6: ADDRCONF(NETDEV_UP): vethpivccu: link is not ready
Oct 16 22:24:25 openhab kernel: [486712.461576] eth0: renamed from veth2F66GG
Oct 16 22:24:25 openhab kernel: [486712.526429] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
Oct 16 22:24:25 openhab kernel: [486712.526449] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
Oct 16 22:24:25 openhab kernel: [486712.526544] IPv6: ADDRCONF(NETDEV_CHANGE): vethpivccu: link becomes ready
Oct 16 22:24:25 openhab kernel: [486712.526602] br0: port 2(vethpivccu) entered blocking state
Oct 16 22:24:25 openhab kernel: [486712.526607] br0: port 2(vethpivccu) entered forwarding state
Oct 16 22:24:25 openhab systemd[1]: Started piVCCU.
Oct 16 22:24:26 openhab avahi-daemon[355]: Joining mDNS multicast group on interface vethpivccu.IPv6 with address fe80::fc99:4fff:fe41:3fc.
Oct 16 22:24:26 openhab avahi-daemon[355]: New relevant interface vethpivccu.IPv6 for mDNS.
Oct 16 22:24:26 openhab avahi-daemon[355]: Registering new address record for fe80::fc99:4fff:fe41:3fc on vethpivccu.*.
Oct 16 22:24:33 openhab systemd[1]: Time has been changed
Oct 16 22:24:33 openhab systemd[6136]: Time has been changed
Oct 16 22:24:33 openhab systemd[942]: Time has been changed
Oct 16 22:24:33 openhab systemd[1]: apt-daily-upgrade.timer: Adding 43min 27.549109s random time.
Oct 16 22:24:33 openhab systemd[1]: apt-daily.timer: Adding 4h 11min 18.754104s random time.
Oct 16 22:24:33 openhab systemd[1]: Starting Daily apt download activities...
Oct 16 22:24:34 openhab kernel: [486721.827463] eq3loop: created slave mmd_hmip
Oct 16 22:24:34 openhab kernel: [486721.827691] eq3loop: created slave mmd_bidcos
Oct 16 22:24:35 openhab systemd[1]: Started Daily apt download activities.
Oct 16 22:24:35 openhab systemd[1]: apt-daily.timer: Adding 6h 5min 34.493978s random time.
Oct 16 22:24:35 openhab systemd[1]: apt-daily.timer: Adding 8h 18min 5.533174s random time.
Oct 16 22:24:36 openhab kernel: [486723.927668] eq3loop: eq3loop_open_slave() mmd_bidcos
Oct 16 22:24:48 openhab kernel: [486735.963330] eq3loop: eq3loop_open_slave() mmd_hmip
Oct 16 22:24:48 openhab kernel: [486735.963564] eq3loop: eq3loop_close_slave() mmd_hmip
Oct 16 22:24:48 openhab kernel: [486735.967038] eq3loop: eq3loop_close_slave() mmd_hmip
Oct 16 22:24:48 openhab kernel: [486735.978645] eq3loop: eq3loop_ioctl_slave() mmd_hmip: unhandled ioctl 0x5459
Oct 16 22:24:48 openhab kernel: [486735.978657] eq3loop: eq3loop_ioctl_slave() mmd_hmip: unhandled ioctl 0x545D

Was war das denn?
Viele Grüße,
Ralf

deimos · Beitrag von **deimos** » 17.10.2018, 10:58

Hi,

kann man leider nicht wirklich was erkennen, ich vermute, dass der Logausschnitt nur deinen Restart zeigt. Vom Log und der Fehlerbeschreibung her würde ich vermuten, dass "nur" der Rega-Prozess abgestürzt war, dadurch werden keine Programme mehr ausgeführt und auch ein Großteil der WebUI läuft nicht mehr (u.A. die Startseite). Die RPC Schnittstellen von RFD und HmServer, welche von Openhab angesprochen werden, scheinen davon nicht betroffen gewesen zu sein, weswegen du weiterhin die Things in Openhab als Online sehen konntest.

Das Log beginnt damit, dass SystemD um 22:23:34 piVCCU beenden will, hier kann ich nicht sagen, ob das dein manuelles Eingreifen war, oder ob das von irgendwas auf der Maschine ausgelöst wurde.
Da wird als erstes versucht, die Rega zum Speichern zu bringen, was hier nicht geklappt hat, weil der Rega Prozess im Container nicht verfügbar war. (Das ist der Block mit dem Webseite).
Um 22:23:41 war piVCCU dann komplett beendet.

Danach kommen dann ab 22:24:12 die normalen Einträge vom Starten. Auch hier kann ich nicht sagen, ob das von deinem manuellen Eingreifen kam, oder ob das automatisch war, wobei mich letzteres sehr wundern würde.

Falls das nochmal vorkommen sollte: Vor dem Neustart von piVCCU mal folgende Befehle ausführen, dann kann man vielleicht mehr erkennen:

Code: Alles auswählen

sudo pivccu-attach cat /var/log/messages
sudo pivccu-attach cat /var/log/hmserver.log

Viele Grüße
Alex

rbeudel · Beitrag von **rbeudel** » 17.10.2018, 15:02

Hallo,
das waren die Zeiten des von mir ausgelösten haltens und wieder starten.

Falls das nochmal vorkommen sollte: Vor dem Neustart von piVCCU mal folgende Befehle ausführen, dann kann man vielleicht mehr erkennen:

OK, werde ich mit merken.
Vielen Dank,
Ralf

klassisch · Beitrag von **klassisch** » 17.10.2018, 19:21

Hatte bei der CCU2 den Effekt, daß sie abgestürzt ist, wenn ich webUI in mehreren Browsern längere Zeit gleichzeitig offen hatte. Habe mich daran gewöhnt und das dann vermieden. Also bei der CCU2 habe ich dann WebUI generell vermieden, weil langsam und soo viel ändere ich derzeit nicht mehr. Gibt ja noch ioBroker und die 433MHz Sensoren; da reicht das Spielgeld weiter.
Mit piVCCU läufts ja flott, also wieder mehrere WebUI Sessions parallel laufen lassen. Und einen ähnlichen halblebigen Absturz wie oben beschrieben. Zeitmodul war glaube ich nio, aber ioBroker hat Daten bekommen und CUxD hatte auch keine Auffälligkeit. WinSCP auch alles unauffällig. WebUI ging aber rgendwann nicht mehr.
Könnte das der Fall gewesen sein? Seither vermeide ich Parallelsessions von WebUI und gut ist wieder.
Eine pivCCU ist eben eine CCU und verhält sich wie eine solche. Und die Systeme läufen am stabilsten, wenn man sie in Ruhe arbeiten läßt.

rbeudel · Beitrag von **rbeudel** » 17.10.2018, 19:47

Parallelsessions hatte ich nicht laufen. Nur Openhab hat noch auf die piVCCU zugrgriffen. Ich hatte kürzlich ein paar Totalausfälle des raspi durch Probleme mit der Stromversorgung. Dabei ist auch die SSD einmal zerschossen worden, aber ich hatte ja ein backup. Ich hatte auch ccuhistorian über einen anderen raspi laufen. Ich hatte das Gefühl das die VCCU dadurch instabiler wurde. Das hatte ich deshalb wieder gestoppt, bis die Stromversorgung als Fehlerquelle ausgeschlossen ist. Seit dem Austausch eines Kabels war aber aus der Richtung kein Fehler mehr.
Nun warten wir mal ab, wird scho

HomeMatic-Forum / FHZ-Forum

piVCCU Absturz

piVCCU Absturz

Re: piVCCU Absturz

Re: piVCCU Absturz

Re: piVCCU Absturz

Re: piVCCU Absturz