AvrLogger : Thermallogger und Visualisierung Tool

[vater]

Um welche DSM handelt es sich überhaupt und was läuft auf ihr?

Die Screenshots sind von der RS2414+.

Besonders oft lässt sich Elasticsearch und der index sehen. Beides nie sehr lang, aber teilweise recht heftig.

  PID USER      PR  NI    VIRT    RES  %CPU %MEM     TIME+ S COMMAND
16308 root      20   0 3817.7m 183.6m  99.2  4.7   1163:54 S /var/packages/SynoFinder/target/sbin/synoelasticd
17037 root      35  15 2159.1m 1.389g   7.2 36.0 518:46.53 S /var/packages/SynoFinder/target/sbin/fileindexd

 

[peterhoffmann]

"synoelasticd" hatte ich auch gestern (mit ca. 10% Last) und nur zufällig gesehen. Nach ein paar Stunden war es dann wieder weg.

 

[vater]

Ich beobachte es mal weiter. Seit heute läuft die neueste AvrLogger Version im „Medium“ Modus.

 

[peterhoffmann]

Bei mir läuft er auf 12 Stunden und "low". Eingeschaltet ist er von 9 Uhr morgens bis 21 Uhr abends, von Montag bis Freitag.
Um 21.05 Uhr läuft ein Script, welches die Grafik vom Tag sichert und die Datenbank vom AvrLogger leert.
Auf dem Webserver wiederum habe ich eine kleine HTML-Datei, die die aktuelle Grafik, sowie die Grafiken der letzten 5 Tage anzeigt.

Nun warte ich mal 5 Tage ab und schau mal, wie das dann so ausschaut.

 

[peterhoffmann]

Leider habe ich noch ein Problem gefunden.

Es läuft die aktuelle Version aus dem Paketzentrum. Die Einstellungen sehen so aus:


Heute morgen sollte um 9.00 Uhr der AvrLogger loslaufen. Um kurz nach 9 Uhr habe ich testweise geschaut, ob die Grafik (Plot-Copy) frisch ist. Ja, das war sie (9:00:04 erstellt).
Um 9:40 Uhr habe ich noch mal geschaut. Da war die Plot-Copy immer noch von 9:00:04.
Unter "top" habe ich kein avrlogger.sh gefunden.

Als ich mich ins DSM eingeloggt habe und auf das Icon vom AvrLogger klickte, lief er sofort los.
Ich sah das sofort, weil im gleichen Moment die Plot-Copy ausgetauscht wurde. Auf der Grafik stehen auch erst Werte ab 9:40 Uhr.


Ich habe dann in die avrlogger.dat geschaut. Inhalt:

94 468 444 0 320 310 315 0 0
67 465 444 0 320 310 315 0 0
39 460 440 0 320 310 315 0 0
17 455 435 0 320 310 315 0 0
17 452 428 0 320 310 315 0 0
17 451 431 0 320 310 315 0 0
206 460 448 0 320 310 315 0 0
402 470 462 0 320 310 315 0 0
592 476 466 0 320 310 315 0 0
707 477 467 0 320 310 315 0 0
113 448 421 0 320 310 315 0 0
110 445 421 0 320 310 315 0 0
92 444 427 0 320 310 315 0 0
97 443 434 0 320 310 315 0 0

 

[BigRonin]

Was steht denn im LOG zu der fraglichen Zeit?

 

[peterhoffmann]

Was steht denn im LOG zu der fraglichen Zeit?

Auszug aus der /usr/syno/etc/packages/AvrLogger/log/error

2018.09.27 09:00:01 Cron      : Hibernation-Mode was stopped ...
2018.09.27 09:00:01 AvrLogger started ...
/volume1/@appstore/AvrLogger/lib/svg2.sh: line 100: plotTime[$[$i+$j]]: unbound variable
2018.09.27 09:45:19 Cron      : Hibernation-Mode was stopped ...
2018.09.27 09:45:19 AvrLogger started ...

 

[BigRonin]

die Datenbank vom AvrLogger leert.

Wie genau machst du das?

 

[peterhoffmann]

:> /usr/syno/etc/packages/AvrLogger/tmp/AvrLogger.dat

Die Datei ist damit leer und hat 0kb.

 

[BigRonin]

… hm … es gelingt mir momentan nicht den Fehler zu reproduzieren. Ich habe dieselben Einstellungen wie du gemacht nur die Start-Zeit angepasst. Mit genau deinem Befehl die AvrLogger.dat geleert und dann geschaut ob der Fehler auftritt wenn AvrLogger gestartet wird … :-( … bis jetzt ohne Murren.

Ich weiß bis jetzt noch nicht was da passiert ist. Kannst du den Fehler reproduzieren?

 

[peterhoffmann]

Ich würde vorschlagen, dass wir einfach mal bis morgen warten. Dann wiederholt sich der Ablauf in der DS 1:1.

 

[BigRonin]

hm ... ok.

"Wenn" der Fehler morgen wieder auftritt ... dann bitte AvrLogger "NICHT" aufrufen oder starten. Kopier mir dann bitte die AvrLogger.dat UND die tmp.svg aus dem gleichem tmp-Ordner.

Und Danke für die Gedult

 

[peterhoffmann]

Heute wird es noch etwas kurioser.

Um kurz nach 9 Uhr kontrollierte ich die Plot-Copy. Es war noch die Alte. Unter "top" lief kein avrlogger.sh. Die Dateien AvrLogger.dat und tmp.svg waren auch unberührt (gestern 21 Uhr). Im Verzeichnis /log hatte aber die error-Datei einen frischen Zeitstempel (28.09. um 9:00 Uhr). Aber der letzte Eintrag ist von gestern abend (2018.09.27 21:00:02 AvrLogger stopped ...). Also hat er nichts reingeschrieben, aber sie definitiv einmal gespeichert (frischer Zeitstempel).

Daraufhin bin ich ins DSM. Auch beim Klicken auf den AvrLogger läuft er nicht los. Er ist wie tot und zeigt die Grafik von gestern Abend. Beim Klicken auf "Save Config" (ohne Änderungen) tut sich auch nichts. Er bleibt tot.

Hier sind meine Einstellungen:


Ich habe gerade gesehen, dass eine neue Version online ist. Ich werde die alte Version nun deinstallieren und die neue frisch ohne Altdaten installieren. Dann testen wir halt noch mal.

---

Nachtrag:
Beim Deinstallieren habe ich ihn die alten Daten speichern lassen. Dort steht im error-Log :

[...]
2018.09.27 09:45:19 AvrLogger started ...
2018.09.27 21:00:02 Cron      : Hibernation-Mode was started ...
2018.09.27 21:00:02 AvrLogger stopped ...
(ab hier hat er bei der Deinstallation geschrieben)
/volume1/@appstore/AvrLogger/lib/init.sh: line 80: LogBase: unbound variable
/volume1/@appstore/AvrLogger/lib/init.sh: line 80: LogBase: unbound variable
2018.09.28 09:19:56 PreUninst : UNINSTALL is being prepared ...
2018.09.28 09:19:56 PreUninst : Symbolic link in 3rdparty deleted
2018.09.28 09:19:56 PreUninst : Saving Data to : "/volume1/temp/AvrLogger"
2018.09.28 09:19:56 PreUninst : "AvrLogger.dat" saved
2018.09.28 09:19:56 PreUninst : Logfile-"error" saved
2018.09.28 09:19:56 PreUninst : Logfile-"messages" saved
2018.09.28 09:19:56 PreUninst : Config "log.confs" saved
2018.09.28 09:19:56 PreUninst : Config "svg.conf" saved

 

[peterhoffmann]

Frische Neuinstallation (v1.0.4-004), folgende Einstellung habe ich wieder vorgenommen:


Die Plot-Copy wurde einmal abgespeichert, in top war einmal kurz avrlogger.sh zu sehen, danach nicht mehr. Jetzt (9:55 Uhr) nach 15 Minuten keinerlei Aktivität. Die AvrLogger.dat und tmp.svg haben den Zeitstempel von 9:41 Uhr.

error-Log:

2018.09.28 09:40:21 PreInst   : Preparing INSTALL v1.0.4-004
2018.09.28 09:40:22 PostInst  : INSTALL v1.0.4-004
2018.09.28 09:40:22 PostInst  : Default Folder "etc" copied
2018.09.28 09:40:22 PostInst  : Check Save-Folder : ""
2018.09.28 09:40:31 AvrLogger started ...
2018.09.28 09:41:59 Cron      : Hibernation-Mode was started ...
2018.09.28 09:41:59 AvrLogger stopped ...

---

Weiterer Test:
Ich habe jetzt um 9:56 Uhr testweise die Zeit von 9 Uhr auf 10 Uhr gestellt. Mal sehen, ob er um 10 Uhr losläuft.

---

Nachtrag1 (9:56 Uhr):
Beim Speichern der Änderung wurde folgendes ins error-Log geschrieben:

/volume1/@appstore/AvrLogger/lib/init.sh: line 80: LogBase: unbound variable

---

Nachtrag2 (10:04 Uhr):
AvrLogger läuft nicht. Plot-Copy, AvrLogger.dat, tmp.svg ist auch unberührt (9:41 Uhr). Das error-Log hat nun einen Zeitstempel von 10:00 Uhr (vorher 9:56), aber es ist kein Eintrag dazugekommen.

---

Nachtrag3 (10:05 Uhr):
Der neue Zeitstempel vom error-Log wird dadurch ausgelöst, wenn man im DSM beim AvrLogger schon auf einen Menübutton (z.B. NAS-Status) klickt. Wobei der Zeitstempel von 10.00 Uhr nicht dadurch kam, da ich erst um 10:02 Uhr nachgeschaut habe.

 

[BigRonin]

schau mal bitte in /var/packages/AvrLogger ob eine datei "enabled" vorhanden ist.

Die legt der Paketmanager an / oder löscht sie wenn im Paketmanager das Program gestartet bzw. angehalten wird.
Ich prüfe diese Datei auf vorhanden sein ... AvrLogger soll ja nicht laufen wenn wenn der Benutzer es im Paketmanager ausdrücklich gestoppt hat.

Wenn die Datei nicht vorhanden ist, dann deaktiviere bitte mal in AvrLogger Hibernation, starte im Paketmanager AvrLogger, und aktiviere in AvrLogger wieder den Hibernation modus.
Dann sollte es wieder laufen.

 

[BigRonin]

... und vielleicht ... wenn du schon im Terminal bis ...

vielleicht magst du in der index.cgi ab zeile 140 die 4 Zeilen gengen diese austauschen:

if [[ ${AvrStatus[0]} -ge 1 && $ScheduleStatus -ge 0 ]]
    then echo  '                    <li><a style="padding: 2px 5px 0px 5px" title="Active / On"><img src="images/status-on.gif" height="20px" /></a></li>'
    else echo  '                    <li><a style="padding: 2px 5px 0px 5px" title="Hibernation / Off"><img src="images/status-off.gif" height="20px" /></a></li>'
fi

Mit ist zuspät aufgefallen das ich den Prozess AvrLogger garnicht abfrage

 

[BigRonin]

/volume1/@appstore/AvrLogger/lib/init.sh: line 80: LogBase: unbound variable
/volume1/@appstore/AvrLogger/lib/init.sh: line 80: LogBase: unbound variable

... da ist doch der Wurm drin

 

[BigRonin]

Ok ... leider oder auch nicht, kann ich den Fehler /volume1/@appstore/AvrLogger/lib/init.sh: line 80: LogBase: unbound variable reproduzueren.

Es ist mir bald peinlich

 

[peterhoffmann]

schau mal bitte in /var/packages/AvrLogger ob eine datei "enabled" vorhanden ist.

Ja, war vorhanden (Zeitstempel 9:41 Uhr).

---

So, alles mal wieder auf Anfang:
Ich habe nun im ersten Schritt alles noch mal deinstalliert, dann (ohne Start) frisch installiert, die Änderung in der index.cgi vorgenommen und dann AvrLogger erst gestartet. AvrLogger läuft.
Dann habe ich die üblichen Einstellungen gemacht (siehe Screenshots).
Danach wird der Plot noch einmal gespeichert und AvrLogger läuft nicht mehr.

Was mir gerade aufgefallen ist: Im Paketzentrum steht er auf angehalten. Vorher stand da "läuft".

 

[BigRonin]

Im Augenblick ist eine Init-Routine buggie ... das macht sich immer bemerkbar wenn man AvrLogger neu installiert.
Ich habe so viel getestet ... aber das wohl nicht

Ja ... das ist normal. Der Paketmanager fragt ja über das start-stop-status Script ab ob es läuft ... und im Schlafmodus ist der Prozess ja nicht da