[Gelöst]Fhem friert (heute Nacht) ein

[Gueco315]

Hallo,

mein FHEM ist heute Nacht stehen geblieben. Ich habe aus Zeitgründen keine weiteren Analysen gefahren, auch NICHT versucht FHEM einfach zu starten.
Gestartet habe ich durch RASPI-Reboot.


Könnte Clever Tanken oder Mailcheck die Ursache dafür sein oder ... die Zwangsabschaltung durch die Telekom??
Hier der Auszug aus dem FHEM-Log:

Code Auswählen Erweitern

2019.01.07 02:58:04 1: Perfmon: possible freeze starting at 02:58:03, delay is 1.606
2019.01.07 03:01:23 3: Star: Read callback: Error: gethostbyname www.clever-tanken.de failed
2019.01.07 03:01:23 1: Perfmon: possible freeze starting at 03:00:44, delay is 39.791
2019.01.07 03:02:03 3: Globus: Read callback: Error: gethostbyname www.clever-tanken.de failed
2019.01.07 03:02:04 1: Perfmon: possible freeze starting at 03:01:24, delay is 40.454
2019.01.07 03:02:12 1: PERL WARNING: Trying command when NOT connected! at /usr/local/share/perl/5.20.2/Mail/IMAPClient.pm line 122.
Mail::IMAPClient::LastError(Mail::IMAPClient=HASH(0x4e861e0), "NO not connected") called at /usr/local/share/perl/5.20.2/Mail/IMAPClient.pm line 1545
Mail::IMAPClient::_send_line(Mail::IMAPClient=HASH(0x4e861e0), "116 SELECT INBOX", 0) called at /usr/local/share/perl/5.20.2/Mail/IMAPClient.pm line 1321
Mail::IMAPClient::_imap_command_do(Mail::IMAPClient=HASH(0x4e861e0), "SELECT INBOX") called at /usr/local/share/perl/5.20.2/Mail/IMAPClient.pm line 1226
Mail::IMAPClient::_imap_command(Mail::IMAPClient=HASH(0x4e861e0), "SELECT INBOX") called at /usr/local/share/perl/5.20.2/Mail/IMAPClient.pm line 846
Mail::IMAPClient::select(Mail::IMAPClient=HASH(0x4e861e0), "INBOX") called at ./FHEM/32_mailcheck.pm line 275
main::mailcheck_poll(HASH(0x3fa38a0)) called at fhem.pl line 3146
main::HandleTimeout() called at fhem.pl line 649
2019.01.07 03:02:12 1: PERL WARNING: Trying command when NOT connected! LastError was: NO not connected at /usr/local/share/perl/5.20.2/Mail/IMAPClient.pm line 122.
Mail::IMAPClient::LastError(Mail::IMAPClient=HASH(0x4e861e0), "Error sending '116 SELECT INBOX': NO not connected") called at /usr/local/share/perl/5.20.2/Mail/IMAPClient.pm line 1322
Mail::IMAPClient::_imap_command_do(Mail::IMAPClient=HASH(0x4e861e0), "SELECT INBOX") called at /usr/local/share/perl/5.20.2/Mail/IMAPClient.pm line 1226
Mail::IMAPClient::_imap_command(Mail::IMAPClient=HASH(0x4e861e0), "SELECT INBOX") called at /usr/local/share/perl/5.20.2/Mail/IMAPClient.pm line 846
Mail::IMAPClient::select(Mail::IMAPClient=HASH(0x4e861e0), "INBOX") called at ./FHEM/32_mailcheck.pm line 275
main::mailcheck_poll(HASH(0x3fa38a0)) called at fhem.pl line 3146
main::HandleTimeout() called at fhem.pl line 649
2019.01.07 03:02:12 1: PERL WARNING: Trying command when NOT connected! LastError was: Error sending '116 SELECT INBOX': NO not connected at /usr/local/share/perl/5.20.2/Mail/IMAPClient.pm line 122.
Mail::IMAPClient::LastError(Mail::IMAPClient=HASH(0x4e861e0), "Error sending '116 SELECT INBOX': NO not connected") called at /usr/local/share/perl/5.20.2/Mail/IMAPClient.pm line 1275
Mail::IMAPClient::_imap_command(Mail::IMAPClient=HASH(0x4e861e0), "SELECT INBOX") called at /usr/local/share/perl/5.20.2/Mail/IMAPClient.pm line 846
Mail::IMAPClient::select(Mail::IMAPClient=HASH(0x4e861e0), "INBOX") called at ./FHEM/32_mailcheck.pm line 275
main::mailcheck_poll(HASH(0x3fa38a0)) called at fhem.pl line 3146
main::HandleTimeout() called at fhem.pl line 649
2019.01.07 03:02:12 1: PERL WARNING: Trying command when NOT connected! at /usr/local/share/perl/5.20.2/Mail/IMAPClient.pm line 122.
Mail::IMAPClient::LastError(Mail::IMAPClient=HASH(0x4e861e0), "NO not connected") called at /usr/local/share/perl/5.20.2/Mail/IMAPClient.pm line 1545
Mail::IMAPClient::_send_line(Mail::IMAPClient=HASH(0x4e861e0), "117 IDLE", 0) called at /usr/local/share/perl/5.20.2/Mail/IMAPClient.pm line 1321
Mail::IMAPClient::_imap_command_do(Mail::IMAPClient=HASH(0x4e861e0), "IDLE", "+") called at /usr/local/share/perl/5.20.2/Mail/IMAPClient.pm line 1226
Mail::IMAPClient::_imap_command(Mail::IMAPClient=HASH(0x4e861e0), "IDLE", "+") called at /usr/local/share/perl/5.20.2/Mail/IMAPClient.pm line 1119
Mail::IMAPClient::idle(Mail::IMAPClient=HASH(0x4e861e0)) called at ./FHEM/32_mailcheck.pm line 276
main::mailcheck_poll(HASH(0x3fa38a0)) called at fhem.pl line 3146
main::HandleTimeout() called at fhem.pl line 649
2019.01.07 03:02:12 1: PERL WARNING: Trying command when NOT connected! LastError was: NO not connected at /usr/local/share/perl/5.20.2/Mail/IMAPClient.pm line 122.
Mail::IMAPClient::LastError(Mail::IMAPClient=HASH(0x4e861e0), "Error sending '117 IDLE': NO not connected") called at /usr/local/share/perl/5.20.2/Mail/IMAPClient.pm line 1322
Mail::IMAPClient::_imap_command_do(Mail::IMAPClient=HASH(0x4e861e0), "IDLE", "+") called at /usr/local/share/perl/5.20.2/Mail/IMAPClient.pm line 1226
Mail::IMAPClient::_imap_command(Mail::IMAPClient=HASH(0x4e861e0), "IDLE", "+") called at /usr/local/share/perl/5.20.2/Mail/IMAPClient.pm line 1119
Mail::IMAPClient::idle(Mail::IMAPClient=HASH(0x4e861e0)) called at ./FHEM/32_mailcheck.pm line 276
main::mailcheck_poll(HASH(0x3fa38a0)) called at fhem.pl line 3146
main::HandleTimeout() called at fhem.pl line 649
2019.01.07 03:02:12 1: PERL WARNING: Trying command when NOT connected! LastError was: Error sending '117 IDLE': NO not connected at /usr/local/share/perl/5.20.2/Mail/IMAPClient.pm line 122.
Mail::IMAPClient::LastError(Mail::IMAPClient=HASH(0x4e861e0), "Error sending '117 IDLE': NO not connected") called at /usr/local/share/perl/5.20.2/Mail/IMAPClient.pm line 1275
Mail::IMAPClient::_imap_command(Mail::IMAPClient=HASH(0x4e861e0), "IDLE", "+") called at /usr/local/share/perl/5.20.2/Mail/IMAPClient.pm line 1119
Mail::IMAPClient::idle(Mail::IMAPClient=HASH(0x4e861e0)) called at ./FHEM/32_mailcheck.pm line 276
main::mailcheck_poll(HASH(0x3fa38a0)) called at fhem.pl line 3146
main::HandleTimeout() called at fhem.pl line 649
2019.01.07 03:02:52 3: Heinebach: Read callback: Error: gethostbyname www.clever-tanken.de failed
2019.01.07 03:02:53 1: Perfmon: possible freeze starting at 03:02:05, delay is 48.264
2019.01.07 03:03:03 1: 192.168.1.222:1883 disconnected, waiting to reappear (myBroker)
2019.01.07 03:03:03 1: Perfmon: possible freeze starting at 03:02:54, delay is 9.848
Can't call method "isa" on an undefined value at /usr/local/share/perl/5.20.2/Mail/IMAPClient.pm line 1857.
2019.01.07 07:17:05 2: Perfmon: ready to watch out for delays greater than one second
2019.01.07 07:17:05 1: Including fhem.cfg
2019.01.07 07:17:05 3: telnetPort: port 7072 opened
2019.01.07 07:17:05 3: WEB: port 8083 opened
2019.01.07 07:17:05 3: WEBphone: port 8084 opened
2019.01.07 07:17:05 3: WEBtablet: port 8085 opened

Danke vorab

Gruß Günter

[KernSani]

Hi Günter,

das Ganze deutet auf ein Problem mit MAILCHECK während der Zwangsabschaltung hin, schau dir mal diesen Thread an.

Frage am Rande: Wie alt ist dein FHEM?

Grüße,

Oli

[MadMax-FHEM]

Wenn ich mir das so anschaue:

Code Auswählen Erweitern


2019.01.07 03:01:23 3: Star: Read callback: Error: gethostbyname www.clever-tanken.de failed
2019.01.07 03:01:23 1: Perfmon: possible freeze starting at 03:00:44, delay is 39.791
2019.01.07 03:02:03 3: Globus: Read callback: Error: gethostbyname www.clever-tanken.de failed
2019.01.07 03:02:04 1: Perfmon: possible freeze starting at 03:01:24, delay is 40.454


Evtl. ein DNS-Auflösungsproblem...

Das ist ja (der einzige Aufruf) blocking (auch bei non-blocking HTTP)...

Hast du das Attribut "dnsServer" bei "global" gesetzt (dort den Router eintragen):

Code Auswählen Erweitern

attr global dnsServer ROUTER-IP

Gruß, Joachim

[KernSani]

Ich denke, die entscheidende Zeile ist die letzte im Log vor dem Neustart:

Code Auswählen Erweitern


Can't call method "isa" on an undefined value at /usr/local/share/perl/5.20.2/Mail/IMAPClient.pm line 1857.

Das tötet Perl...

DNS-Aufrufe wären ja "nur" blocking (aber kommen nach timeout wieder zurück)

[Gueco315]

Danke für die schnellen Antworten!

Da ich nachts die Mailcheck Funktion nicht brauche, werde ich im Step 1 via DOIF die Funktion Mailcheck nachts abschalten.

Zum Alter von FHEM? Seit 5 Jahren bin ich am werkeln...

2. zum Vorschlag von Joachim:
Du meinst die interne IP Adresse vom Router also die 192.168.178.1 der Fritzbox??

Gruß Günter

[MadMax-FHEM]

Ich denke, die entscheidende Zeile ist die letzte im Log vor dem Neustart:

Code Auswählen Erweitern


Can't call method "isa" on an undefined value at /usr/local/share/perl/5.20.2/Mail/IMAPClient.pm line 1857.

Das tötet Perl...

Da hast du nat. Recht!
Da habe ich wohl zu kurz gekuckt

DNS-Aufrufe wären ja "nur" blocking (aber kommen nach timeout wieder zurück)

Schaden kann es aber ja nicht

Gruß, Joachim

[MadMax-FHEM]

2. zum Vorschlag von Joachim:
Du meinst die interne IP Adresse vom Router also die 192.168.178.1 der Fritzbox??

Jep. Exakt.

dnsServer
Contains the IP address of the DNS Server. If some of the modules or user code calls the HttpUtils_NonblockingGet function, and this attribute is set, then FHEM specific nonblocking code will be used to resolve the given address. If this attribute is not set, the blocking OS implementation (inet_aton and gethostbyname) will be used.

Gruß, Joachim

[KernSani]

Danke für die schnellen Antworten!

Da ich nachts die Mailcheck Funktion nicht brauche, werde ich im Step 1 via DOIF die Funktion Mailcheck nachts abschalten.

Zum Alter von FHEM? Seit 5 Jahren bin ich am werkeln...

2. zum Vorschlag von Joachim:
Du meinst die interne IP Adresse vom Router also die 192.168.178.1 der Fritzbox??

Gruß Günter

Ich meinte, wann du das letzte update gemacht hast (von fhem, aber auch von Perl)
Zu 2. Ja, das meint Joachim (und das ist auf jeden Fall gut)


Kurz, weil mobil

[Gueco315]

Revision 17863

Perl: 5.20.2