Moin liebes Forum,
heute Morgen war das Frontend von FHEM nicht mehr erreichbar.
Per SSH konnte ich mich problemlos mit dem Server verbinden.
Der service lief, seit ca. 23Tagen ununterbrochen.
Ich habe mir die Logdatei angeschaut, der letzte Eintrag war um 02:12:31Uhr.
Dummerweise habe ich mir per "top" nicht den Prozess angeschaut.
Ich habe daraufhin ein
service fhem stop
gemacht. Plötzlich fing an mehrere Einträge ins Log geschrieben zu werden.
service fhem status
signalisierte mir wenige Sekunden nach dem Stop-Signal, dass der Service angeblich angehalten wurde. Es flossen aber noch ungefähr eine Minute nach dem Stop-Signal weitere Einträge in die Logdatei.
Erst, als ich im Logdatei die Mitteilung:
2018.12.07 05:45:23 0: Server shutdown
2018.12.07 05:45:25 1: Shutdown executed
gesehen habe, habe ich den Service problemlos neugestartet.
Hier ist einmal der Logauszug bis zum Shutdown:
2018.12.07 01:01:39 3: CUL_HM set bad_vt_Sensor1 virtTemp 17.9
2018.12.07 01:28:58 3: CUL_HM set sz_vt_Sensor1 virtTemp 16.9
2018.12.07 01:29:37 3: CUL_HM set sz_vt_Sensor1 virtTemp 16.8
2018.12.07 01:33:47 3: UWZ Unwetterwarnungen: Run.1043 Done fetching data
2018.12.07 01:34:10 3: CUL_HM set sz_vt_Sensor1 virtTemp 16.9
2018.12.07 01:37:25 3: CUL_HM set sz_vt_Sensor1 virtTemp 16.8
2018.12.07 02:04:43 3: CUL_HM set sz_vt_Sensor1 virtTemp 16.9
2018.12.07 02:10:34 3: CUL_HM set sz_vt_Sensor1 virtTemp 16.8
2018.12.07 02:12:31 3: CUL_HM set sz_vt_Sensor1 virtTemp 16.9
2018.12.07 05:44:20 1: PERL WARNING: Use of uninitialized value in concatenation (.) or string at FHEM/HttpUtils.pm line 853.
2018.12.07 05:44:20 3: Star_Rahlstedt: Read response to update didn't match any Reading
2018.12.07 05:44:26 3: mb_Hamburg: Read callback: Error: read from https://www.verkehrslage.de:443 timed out
2018.12.07 05:44:27 3: Star_Horn: Read callback: Error: read from https://www.clever-tanken.de:443 timed out
2018.12.07 05:44:28 3: CUL_HM sz_vt_Sensor1 virtualTC timer off by:12616
2018.12.07 05:44:28 3: CUL_HM az_vt_Sensor1 virtualTC timer off by:12542
2018.12.07 05:44:28 3: CUL_HM bad_vt_Sensor1 virtualTC timer off by:12537
2018.12.07 05:44:29 2: ZWDongle_ProcessSendStack: no ACK, resending message 01030015e9
2018.12.07 05:44:30 2: ZWDongle_ProcessSendStack: no ACK, resending message 01030015e9
2018.12.07 05:44:37 2: ZWDongle_ProcessSendStack: no ACK, resending message 01030015e9
2018.12.07 05:44:39 2: ZWDongle_ProcessSendStack: no ACK, resending message 01030015e9
2018.12.07 05:44:39 2: ZWDongle_ProcessSendStack: no ACK, resending message 01030015e9
2018.12.07 05:44:43 2: ZWDongle_ProcessSendStack: no ACK, resending message 01030015e9
2018.12.07 05:44:45 2: ZWDongle_ProcessSendStack: no ACK, resending message 01030015e9
2018.12.07 05:44:45 2: ZWDongle_ProcessSendStack: no ACK, resending message 01030015e9
2018.12.07 05:44:49 2: ZWDongle_ProcessSendStack: no ACK, resending message 01030015e9
2018.12.07 05:44:51 2: ZWDongle_ProcessSendStack: no ACK, resending message 01030015e9
2018.12.07 05:44:51 2: ZWDongle_ProcessSendStack: no ACK, resending message 01030015e9
2018.12.07 05:45:04 1: PERL WARNING: Use of uninitialized value $d in concatenation (.) or string at (eval 33913216) line 1.
2018.12.07 05:45:04 1: PERL WARNING: Use of uninitialized value $d in concatenation (.) or string at (eval 33913217) line 1.
2018.12.07 05:45:04 1: PERL WARNING: Use of uninitialized value $d in concatenation (.) or string at (eval 33913218) line 1.
2018.12.07 05:45:04 1: PERL WARNING: Use of uninitialized value $d in concatenation (.) or string at (eval 33913219) line 1.
2018.12.07 05:45:04 1: PERL WARNING: Use of uninitialized value $d in concatenation (.) or string at (eval 33913220) line 1.
2018.12.07 05:45:04 1: PERL WARNING: Use of uninitialized value $d in concatenation (.) or string at (eval 33913221) line 1.
2018.12.07 05:45:04 1: PERL WARNING: Use of uninitialized value $d in concatenation (.) or string at (eval 33913222) line 1.
2018.12.07 05:45:04 1: PERL WARNING: Use of uninitialized value $d in concatenation (.) or string at (eval 33913223) line 1.
2018.12.07 05:45:04 1: PERL WARNING: Use of uninitialized value $d in concatenation (.) or string at (eval 33913224) line 1.
2018.12.07 05:45:04 1: PERL WARNING: Use of uninitialized value $d in concatenation (.) or string at (eval 33913225) line 1.
2018.12.07 05:45:12 2: ZWDongle_ProcessSendStack: no ACK, resending message 01030015e9
2018.12.07 05:45:13 2: ZWDongle_ProcessSendStack: no ACK, resending message 01030015e9
2018.12.07 05:45:15 2: ZWDongle_ProcessSendStack: no ACK, resending message 01030015e9
2018.12.07 05:45:17 3: sz_Bett_LED set HSV 204, 83, 50 with ramp: 0, flags:
2018.12.07 05:45:18 3: ZWave set fl_Display on
2018.12.07 05:45:20 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00132c032501FF2596a1
2018.12.07 05:45:21 2: ZWDongle_ProcessSendStack: no ACK, resending message 01030015e9
2018.12.07 05:45:21 2: ZWDongle_ProcessSendStack: no ACK, resending message 01030015e9
2018.12.07 05:45:23 0: Server shutdown
2018.12.07 05:45:25 1: Shutdown executed
Die erste Meldung um 05:44:20Uhr, kenne ich soweit nicht.
Könnt ihr das Problem erkennen?
Gruß
Mathze
2018.12.07 05:44:26 3: mb_Hamburg: Read callback: Error: read from https://www.verkehrslage.de:443 timed out
2018.12.07 05:44:27 3: Star_Horn: Read callback: Error: read from https://www.clever-tanken.de:443 timed out
Hattest Du internetprobleme?
Wie liest Du die Webside aus, Stichwort "Non Blocking"?
Nein, Internet lief laut FritzBox ohne Probleme.
Die Daten werden per HTTPMOD ausgelesen.
Wie schon gesagt, diese Meldungen kamen erst nachdem ich per SSH den FHEM service gestoppt habe, davor tat sich nichts.
Gruß
Mathze
Wie hängt dein Server im Netz?
(Verdacht: Pi, integriertes WLAN+Fritte, kein Kabel?)
FHEM läuft in einer VM mit Ubuntu 16.04 auf einem QNAP, welcher per RJ45 direkt mit der FritzBox verbunden ist.
Viel mehr interessiert mich, was bedeutet folgender Logeintrag?
2018.12.07 05:44:20 1: PERL WARNING: Use of uninitialized value in concatenation (.) or string at FHEM/HttpUtils.pm line 853.
Im Code steht an der o.g. Stelle (851-855) folgendes:
if($HU_use_zlib) {
if($hash->{httpheader} =~ /^Content-Encoding: gzip/mi) {
eval { $ret = Compress::Zlib::memGunzip($ret) };
return ($@, $ret) if($@);
}
Gruß
Mathze
Das ist "nur" ein Warning (auch wenn mir nicht klar ist, warum das noch kommt, wenn es in eval gepackt ist). Kann man beheben, ist aber ziemlich sicher nicht für die Unterbrechnung verantwortlich.
Ansonsten könntest du mit freezemon & co mal lauschen, was da so abläuft.
Um zukünftige Rückfragen zum System zu vermeiden, kann man z.B. die Signatur nutzen ;) .