...konnte nicht pausiert werden...

[wintermute]

Ich bekomme seit einiger Zeit (gefuehlt seit 1.47, aber ich denke das wird nicht der Grund sein) jedes Mal beim Aktivieren eines Projektes obige Fehlermeldung. Beim zweiten Versuch klappt es dann (auch jedes Mal).
Ich habe die Vermutung, es koennte an einem meiner Test-LBS liegen die als Daemon im System rumgammeln. Bevor ich jetzt anfange rumzusuchen: kann das der Grund sein? Weil zumindest frueher hat Edomi die EXEC-Prozesse immer einfach abgewuergt und gut war. Oder wird es doch woanders haken?

[gaert]

Am Test-LBS wird's vermutlich nicht liegen, da alle PHP-Prozesse beim Beenden abgeschossen werden (selbst wenn dies scheitern würde, hätte dies keine Auswirkungen auf das Pausieren). Mit 1.47 habe ich allerdings den shared-memory durch eine Memory-DB ersetzt und diese liefert u.a. den aktuellen "Betriebszustand". Dies wäre die einzige Änderung, die in diesem Kontext irgendwas bewirken könnte - muss ich mir mal in Ruhe anschauen... (bin zur Zeit tief im Import/Export eingetaucht und von daher in ganz anderen Welten...)

EDIT:
Kommt die Meldung quasi sofort oder erst nach 30 Sekunden (timeout)?

[wintermute]

Jo, das mit dem "Abschiessen" hatte ich ja auch nicht nur vermutet, sondern schon umfangreich selbst beobachten koennen
Ist ja auch kein Beinbruch, faellt halt nur auf weil es irgendwie jedesmal auftritt - egal ob was am Projekt geaendert wurde oder nicht. Ich habs grad nochmal ausprobiert: es ist nach dem "Fehler" tatsaechlich pausiert, nur bekommt die betreffende Komponente das scheinbar nicht mit.

Der Vollstaendigkeit halber hab ich das grad auch mal - nur fuer Spass - mit nem anderen Browser ausprobiert, verhaelt sich identisch...

[gaert]

VM oder echte Hardware? Bei VMs habe ich schon die merkwürdigsten Dinge erlebt in Verbindung mit Dateien... Es wird nämlich u.a. schlicht eine Datei erstellt und abgewartet, bis diese wieder gelöscht wird (beim Start). Einige VMs (so z.B. Fusion bei mir) scheinen das Dateisystem irgendwie zu cachen und PHP kommt damit offenbar nicht immer klar.

[wintermute]

Nein, das ist eine harte Ware. Ich hab noch ne Test-VM, bei der ist alles ok - allerdings auch mit nem komplett anderen Projekt
Manuell pausieren klappt uebrigens, ist nur bei der Projektaktivierung...

[gaert]

Ok... Na wie gesagt, ich guck mal drübber wenn ich Zeit finde - ist ja nicht soooo dringend nehme ich an

[wintermute]

Noe, das eilt nun wirklich nicht

[wintermute]

Ich muss das leider mal bumpen, denn irgendwie wird es immer schlimmer

Erst war es nur "konnte nicht pausiert werden", jetzt kommt beim zweiten Versuch ein "konnte nicht aktiviert werden", dann wird die Admin-GUI rot weil der Edomi-Status nicht ermittelt werden konnte, dann muss ich eine gefuehlte Viertelstunde warten bis es wieder gelb wird, dann noch mal versuchen, dann gehts. Das waer normal nur sowild/2, aber wenn man da grad irgendwie massiv dran rumbaut wegen LBS/Visu/whatever, dann ist das extrem laestig

[gaert]

Ich fühle mit Dir... Aber anscheinend bist Du ziemlich alleine mit diesem Problem?! "Leider" kann ich dieses Verhalten nicht rekonstruieren (hab' schon alles mögliche versucht) - was nun?!

Wenn ich Dich richtig verstehe scheitert z.B. das Pausieren - und klappt dann nach 15 Minuten doch? Das ist sehr merkwürdig...

[wintermute]

Ich hab ja auch mehrere "Edomis" bei denen das nicht passiert - dummerweise ist die betreffende Maschine natuerlich mein Produktivsystem
Also, ich mach irgendwelche Aenderungen und aktiviere dann das Projekt. In geschaetzten 5-10% aller Faelle ist alle tutti, aber in den restlichen (gefuehlten) 408% laeuft das ganze in einen Timeout, was in etwa, mompls...
40s dauert. Waehrend der 40s steht da "Vorbereitungen und EDOMI pausieren", dann wirds rot und da steht "EDOMI konnte nicht pausiert werden!". Will ich dann nochmal aktivieren, kommt entweder ein "Projekt konnte nicht aktiviert werden", dann kann die GUI den EDOMI-Status nicht mehr feststellen und das dauert dann nochmal etwa eine Minute oder so bis die Admin-GUI wieder von rot nach gelb wechselt, dann kann ich aktivieren.
Beim testen jetzt hat der zweite Versuch allerdings geklappt (was sehr sehr selten ist), nach 20s hat er pausiert und aktiviert.

Ist uebrigens egal ob man wirklich was geaendert hat oder nicht. Verhalten ist bei Chromium und Safari identisch.

Und so wie ich das verstehe: ja, es scheint das Pausieren zu sein was Probleme bringt.
Jetzt hab ich das mal manuell in der GUI ausprobiert: zickt auch
Ich klick auf "Pause" und nach ein paar Sekunden (2 oder 3 vllt) wird die GUI rot ("Beendet, kein Status verfuegbar"), dann nach nochmal 20-30s etwa gelb... also scheinbar pausiert er, bekommt es aber nicht mit.. oder so

[gaert]

Was unterscheidet denn das "Problem-System" von den anderen?

Im Prinzip wird beim "Pausieren"-Kommando ein DB-Eintrag gesetzt, der vom Hauptprozess zyklisch abgefragt wird. Dann wird der Hauptprozess beendet und neugestartet (um dann in einer Warteschleife aka "Pause" auf weitere DB-Befehle zu warten). Das kurze "rot" ist also völlig normal, in dieser Zeit wird der Hauptprozess neugestartet.

Siehst Du denn in den Logs irgendwas auffälligen, z.B. wäre die Zeitspanne zwischen "Pause drücken" (selbst auf die Uhr gucken) und dem tatsächlichen Beenden/Neustarten interessant...

[wintermute]

Die anderen sind VMs, das Problemsystem ist ein Supermicro-Board mit ner SSD

Das "kurze rote" ist bei mir eher ein laengeres rotes... teilweise bis zu einer Minute oder gar laenger.
Logfiles willste? Kannste haben
(Aus der Huefte raus: MySQL reagiert zu traege)

Hier wird das Projekt aktiviert:

2017-02-23 20:14:20    905965    MAIN    19701    Prozess MAIN beenden... (Modus 10)    Ok
2017-02-23 20:14:20    906687    MAIN    19701    EDOMI beenden...    Ok
2017-02-23 20:14:23    921031    LOGIC    19737    Logik-Berechnungen werden beendet    Ok
2017-02-23 20:14:24    951510    SYSINFO    19710    MAIN-SIGNAL erhalten -> Beenden...    Ok
2017-02-23 20:14:24    953973    SYSINFO    19710    Prozess SYSINFO beenden...    Ok
2017-02-23 20:14:24    954713    SYSINFO    19710    mySQL disconnect    Ok
2017-02-23 20:14:24    955265    SYSINFO    19710    Exit.    Ok
2017-02-23 20:14:25    064623    MAIN    19701    Prozess SYSINFO (PID=19710) beenden    Ok
2017-02-23 20:14:25    223501    KNX    19735    MAIN-SIGNAL erhalten -> Beenden...    Ok
2017-02-23 20:14:25    237044    KNX    19735    Prozess KNX beenden...    Ok
2017-02-23 20:14:25    237723    KNX    19735    mySQL disconnect    Ok
2017-02-23 20:14:25    238333    KNX    19735    Exit.    Ok
2017-02-23 20:14:25    266400    MAIN    19701    Prozess KNX (PID=19735) beenden    Ok
2017-02-23 20:14:25    533509    LOGIC    19737    MAIN-SIGNAL erhalten -> Beenden...    Ok
2017-02-23 20:14:25    633126    LOGIC    19737    Prozess LOGIC beenden...    Ok
2017-02-23 20:14:25    633785    LOGIC    19737    mySQL disconnect    Ok
2017-02-23 20:14:25    634316    LOGIC    19737    Exit.    Ok
2017-02-23 20:14:25    669346    MAIN    19701    Prozess LOGIC (PID=19737) beenden    Ok
2017-02-23 20:14:25    792169    QUEUE    19708    MAIN-SIGNAL erhalten -> Beenden...    Ok
2017-02-23 20:14:25    891571    QUEUE    19708    Prozess QUEUE beenden...    Ok
2017-02-23 20:14:25    892204    QUEUE    19708    mySQL disconnect    Ok
2017-02-23 20:14:25    892764    QUEUE    19708    Exit.    Ok
2017-02-23 20:14:25    971251    MAIN    19701    Prozess QUEUE (PID=19708) beenden    Ok
2017-02-23 20:14:25    974004    MAIN    19701    db.RAMsysInfo löschen    Ok
2017-02-23 20:14:25    975450    MAIN    19701    db.RAMcmdQueue löschen    Ok
2017-02-23 20:14:25    977600    MAIN    19701    db.RAMko löschen    Ok
2017-02-23 20:14:25    979681    MAIN    19701    db.RAMlogicElement löschen    Ok
2017-02-23 20:14:25    981714    MAIN    19701    db.RAMlogicElementVar löschen    Ok
2017-02-23 20:14:25    984143    MAIN    19701    db.RAMlogicLink löschen    Ok
2017-02-23 20:14:25    985481    MAIN    19701    db.RAMlogicCmdList löschen    Ok
2017-02-23 20:14:25    988141    MAIN    19701    db.RAMknxRead löschen    Ok
2017-02-23 20:14:25    990375    MAIN    19701    db.RAMknxWrite löschen    Ok
2017-02-23 20:14:25    993016    MAIN    19701    db.RAMsysProc löschen    Ok
2017-02-23 20:14:28    994374    MAIN    19701    mySQL disconnect    Ok
2017-02-23 20:14:29    004765    MAIN    19701    Prozess MAIN -> EXIT(10)    Ok
2017-02-23 20:14:29    150468    MAIN    25325    Prozess MAIN gestartet    Ok
2017-02-23 20:14:29    151138    MAIN    25325    EDOMI: Version 1.49    Ok
2017-02-23 20:14:29    152729    MAIN    25325    EDOMI: ClientId=6569ff67e39a02afa38a1a040ee198dfc83ef007187a9d9469dea0133d9caca8    Ok
2017-02-23 20:14:29    163237    MAIN    25325    Check: 64-Bit-System?    Ok
2017-02-23 20:14:29    163847    MAIN    25325    PHP-Zeitzone: Europe/Berlin (Winterzeit)    Ok
2017-02-23 20:14:29    169969    MAIN    25325    mySQL: Connect    Ok

Jetzt sagt die UI "kann nicht pausiert werden", dann gehts (kurz danach) weiter im Log:

2017-02-23 20:15:38    725974    MAIN    25325    Check: 97 Datenbanken überprüft (0 Fehler)    Ok
2017-02-23 20:15:38    726761    MAIN    25325    mySQL: RAM reservieren (100 MB pro RAM-DB)    Ok
2017-02-23 20:15:40    847503    MAIN    25325    db.edomiLive erstellen    Ok
2017-02-23 20:15:40    849033    MAIN    25325    Queue löschen    Ok
2017-02-23 20:15:40    852284    MAIN    25325    Queue erstellen    Ok
2017-02-23 20:15:40    861180    MAIN    25325    Prozess QUEUE starten    Ok
2017-02-23 20:15:40    872812    MAIN    25325    Prozess SYSINFO starten    Ok
2017-02-23 20:15:40    874411    MAIN    25325    Visualisierungen inititalisieren    Ok
2017-02-23 20:15:40    891309    MAIN    25325    Pause (Warten auf Start oder Live-Projekt)    Ok
2017-02-23 20:15:40    960905    QUEUE    25347    Prozess QUEUE gestartet    Ok
2017-02-23 20:15:40    966870    SYSINFO    25349    Prozess SYSINFO gestartet    Ok

Jetzt wird die GUI wieder gruen, erneutes Aktivieren:

2017-02-23 20:15:57    197793    MAIN    25325    Prozess MAIN beenden... (Modus 10)    Ok
2017-02-23 20:15:57    198323    MAIN    25325    EDOMI beenden...    Ok
2017-02-23 20:16:00    273977    SYSINFO    25349    MAIN-SIGNAL erhalten -> Beenden...    Ok
2017-02-23 20:16:00    346596    SYSINFO    25349    Prozess SYSINFO beenden...    Ok
2017-02-23 20:16:00    347268    SYSINFO    25349    mySQL disconnect    Ok
2017-02-23 20:16:00    347984    SYSINFO    25349    Exit.    Ok
2017-02-23 20:16:00    403564    MAIN    25325    Prozess SYSINFO (PID=25349) beenden    Ok
2017-02-23 20:16:00    404274    MAIN    25325    Prozess KNX (PID=) beenden    Ok
2017-02-23 20:16:00    405059    MAIN    25325    Prozess LOGIC (PID=) beenden    Ok
2017-02-23 20:16:00    490123    QUEUE    25347    MAIN-SIGNAL erhalten -> Beenden...    Ok
2017-02-23 20:16:00    589549    QUEUE    25347    Prozess QUEUE beenden...    Ok
2017-02-23 20:16:00    590147    QUEUE    25347    mySQL disconnect    Ok
2017-02-23 20:16:00    590713    QUEUE    25347    Exit.    Ok
2017-02-23 20:16:00    606851    MAIN    25325    Prozess QUEUE (PID=25347) beenden    Ok
2017-02-23 20:16:00    608757    MAIN    25325    db.RAMsysInfo löschen    Ok
2017-02-23 20:16:00    610225    MAIN    25325    db.RAMcmdQueue löschen    Ok
2017-02-23 20:16:00    611346    MAIN    25325    db.RAMko löschen    Ok
2017-02-23 20:16:00    612590    MAIN    25325    db.RAMlogicElement löschen    Ok
2017-02-23 20:16:00    613918    MAIN    25325    db.RAMlogicElementVar löschen    Ok
2017-02-23 20:16:00    615011    MAIN    25325    db.RAMlogicLink löschen    Ok
2017-02-23 20:16:00    616369    MAIN    25325    db.RAMlogicCmdList löschen    Ok
2017-02-23 20:16:00    617814    MAIN    25325    db.RAMknxRead löschen    Ok
2017-02-23 20:16:00    619033    MAIN    25325    db.RAMknxWrite löschen    Ok
2017-02-23 20:16:00    620592    MAIN    25325    db.RAMsysProc löschen    Ok
2017-02-23 20:16:03    622155    MAIN    25325    mySQL disconnect    Ok
2017-02-23 20:16:03    632715    MAIN    25325    Prozess MAIN -> EXIT(10)    Ok
2017-02-23 20:16:03    777731    MAIN    25533    Prozess MAIN gestartet    Ok
2017-02-23 20:16:03    778548    MAIN    25533    EDOMI: Version 1.49    Ok
2017-02-23 20:16:03    780421    MAIN    25533    EDOMI: ClientId=6569ff67e39a02afa38a1a040ee198dfc83ef007187a9d9469dea0133d9caca8    Ok
2017-02-23 20:16:03    790859    MAIN    25533    Check: 64-Bit-System?    Ok
2017-02-23 20:16:03    791485    MAIN    25533    PHP-Zeitzone: Europe/Berlin (Winterzeit)    Ok
2017-02-23 20:16:03    798060    MAIN    25533    mySQL: Connect    Ok
2017-02-23 20:16:13    836396    MAIN    25533    Check: 97 Datenbanken überprüft (0 Fehler)    Ok
2017-02-23 20:16:13    837189    MAIN    25533    mySQL: RAM reservieren (100 MB pro RAM-DB)    Ok
2017-02-23 20:16:13    939537    MAIN    25533    db.edomiLive erstellen    Ok
2017-02-23 20:16:13    941251    MAIN    25533    Queue löschen    Ok
2017-02-23 20:16:13    944822    MAIN    25533    Queue erstellen    Ok
2017-02-23 20:16:13    953916    MAIN    25533    Prozess QUEUE starten    Ok
2017-02-23 20:16:13    964879    MAIN    25533    Prozess SYSINFO starten    Ok
2017-02-23 20:16:13    966448    MAIN    25533    Visualisierungen inititalisieren    Ok
2017-02-23 20:16:13    983369    MAIN    25533    Pause (Warten auf Start oder Live-Projekt)    Ok
2017-02-23 20:16:14    051889    QUEUE    25540    Prozess QUEUE gestartet    Ok
2017-02-23 20:16:14    062016    SYSINFO    25542    Prozess SYSINFO gestartet    Ok
2017-02-23 20:16:30    346007    MAIN    25533    Queue leeren    Ok
2017-02-23 20:16:30    347588    MAIN    25533    db.RAMko löschen    Ok
2017-02-23 20:16:30    351746    MAIN    25533    db.RAMko erstellen    Ok
2017-02-23 20:16:30    396487    MAIN    25533    db.RAMko befüllen    Ok
2017-02-23 20:16:30    435431    MAIN    25533    db.RAMko modifizieren    Ok
2017-02-23 20:16:30    503670    MAIN    25533    db.RAMko Index erstellen    Ok
2017-02-23 20:16:30    504520    MAIN    25533    db.RAMlogicElement löschen    Ok
2017-02-23 20:16:30    509102    MAIN    25533    db.RAMlogicElement kopieren    Ok
2017-02-23 20:16:30    516931    MAIN    25533    db.RAMlogicElement Index erstellen    Ok
2017-02-23 20:16:30    517788    MAIN    25533    db.RAMlogicElementVar löschen    Ok
2017-02-23 20:16:30    521394    MAIN    25533    db.RAMlogicElementVar erstellen    Ok
2017-02-23 20:16:30    526304    MAIN    25533    db.RAMlogicElementVar befüllen    Ok
2017-02-23 20:16:30    527137    MAIN    25533    db.RAMlogicLink löschen    Ok
2017-02-23 20:16:30    531363    MAIN    25533    db.RAMlogicLink erstellen    Ok
2017-02-23 20:16:30    574814    MAIN    25533    db.RAMlogicLink befüllen    Ok
2017-02-23 20:16:30    634123    MAIN    25533    db.RAMlogicLink Index erstellen    Ok
2017-02-23 20:16:30    634999    MAIN    25533    db.RAMlogicCmdList löschen    Ok
2017-02-23 20:16:30    638886    MAIN    25533    db.RAMlogicCmdList erstellen    Ok
2017-02-23 20:16:30    648454    MAIN    25533    db.RAMlogicCmdList befüllen    Ok
2017-02-23 20:16:30    649348    MAIN    25533    db.RAMknxRead löschen    Ok
2017-02-23 20:16:30    653435    MAIN    25533    db.RAMknxRead erstellen    Ok
2017-02-23 20:16:30    654258    MAIN    25533    db.RAMknxWrite löschen    Ok
2017-02-23 20:16:30    657751    MAIN    25533    db.RAMknxWrite erstellen    Ok
2017-02-23 20:16:30    668734    MAIN    25533    Prozess KNX starten    Ok
2017-02-23 20:16:30    680193    MAIN    25533    Prozess LOGIC starten    Ok
2017-02-23 20:16:30    787036    KNX    25567    Prozess KNX gestartet    Ok
2017-02-23 20:16:30    793843    KNX    25567    KNX-Verbindung: Control-Endpoint: 192.168.1.200:50000    Ok
2017-02-23 20:16:30    794456    KNX    25567    KNX-Verbindung: Data-Endpoint: 192.168.1.200:50001    Ok
2017-02-23 20:16:30    807571    KNX    25567    KNX-Schnittstelle: Name: wago01 / PA: 1.1.0 / MAC: 0030de03edc9    Ok
2017-02-23 20:16:30    870268    LOGIC    25569    Prozess LOGIC gestartet    Ok
2017-02-23 20:16:30    879992    KNX    25567    KNX-Verbindung: Tunnel-PA: 1.1.254 / Channel-ID: 43    Ok
2017-02-23 20:16:31    206070    MAIN    25533    KNX-Verbindung abwarten    Ok
2017-02-23 20:16:31    708553    MAIN    25533    INIT    Ok
2017-02-23 20:16:31    761990    MAIN    25533    InitSend (0 GAs)    Ok
2017-02-23 20:16:31    762798    MAIN    25533    InitScan: Abfrage 1...    Ok
2017-02-23 20:16:31    764792    MAIN    25533    InitScan: Check (max. 30 Zyklen)...    Ok
2017-02-23 20:16:31    766225    MAIN    25533    InitScan (0 GAs / 1 Abfragen / 1 Checks)    Ok
2017-02-23 20:16:31    771173    MAIN    25533    Logik-Bausteine initialisieren...    Ok
2017-02-23 20:16:34    002937    LOGIC    25569    Logik-Init: alle 380 Logikbausteine initialisiert    Ok
2017-02-23 20:16:34    003710    LOGIC    25569    Logik-Berechnungen werden aktiviert    Ok
2017-02-23 20:16:34    058518    MAIN    25533    START    Ok
2017-02-23 20:16:34    400093    EXEC    25606    Logs/Archive/Autobackups aufräumen    Ok

[mars]

Aber anscheinend bist Du ziemlich alleine mit diesem Problem?!

... nein, ist er nicht. Bei mir kann Edomi sehr oft nicht pausiert werden - nicht gezählt aber gefühlte 80 .. 90% von "Arbeitsprojekt aktivieren"
Sieht dann so aus:

Unbenannt1.PNG

Manchmal reicht eine Wiederholung aber meist muss ich 10 - 20 Mal wiederholen bis es dann klappt. Allerdings habe ich keine Wartezeit ohne Status und kann sofort wieder auf "Arbeitsprojekt aktivieren" klicken. (EDOMI auf NUC / Chrome 56 unter Win7)


Gruß Marco

[wintermute]

Ha, Danke

[gaert]

Verdammt...

Mh - vermutlich klappt im Prinzip alles, nur das Timing nicht (zwischen GUI und dem Backend). Will sagen: EDOMI wird zwar neugestartet/pausiert, aber die GUI schnallt's nicht.

Ich werde mal experimentieren...

Übrigens: Auch Dateien spielen eine Rolle (diverse Status-Dateien zur Absicherung, falls die DB nicht so will). Vielleicht liegt auch da der Hase im Pfeffer - an anderer Stelle habe ich ja mal beschrieben, dass ich in einer VM sporadisch Probleme mit Dateien hatte: Völlig willkürlich wurde eine definitiv vorhandene (gerade angelegte) Datei von PHP nicht gefunden - erst nach einer kleinen Warteschleife. Trotz diverse "Flushs" etc. auf's Dateisystem! Auf echter HW ist dies noch nie vorgekommen. Dein System "virtualisiert" vielleicht irgendwie das FS der SSD oder so?!