!!! Probleme mit Presence, FRITZBOX und Meldungen von Blocking.pm !!!

CoolTux · 05 Oktober 2017, 09:18:53

Guten Morgen FHEM User,

Mit dem letzten Update hat sich ein Problem eingeschlichen welches sich zum jetzigen Zeitpunkt im Presence, FRITZBOX und EQ3BT Modul zeigt. Die Vermutung lässt darauf schließen das die Ursache eine Änderung im Modul Blocking.pm ist.

Da es mittlerweile nicht nur Module von Markus Bloch betrifft, eröffne ich diesen Thread um die Informationen über alle betroffenen Module zu zu bündeln.
Bitte seit so nett und postet Eure Logausgaben hier.

Reine Entwickler Diskussionen zum Problem werden hier diskutiert werden

Grüße
Leon

Otto123 · 05 Oktober 2017, 10:34:08

Moin,

das WOL Modul ist auch betroffen, diese Einträge habe ich jetzt zu Hauf:

Code Auswählen

2017.10.05 01:35:49 2: PRESENCE (Pres_HMUART) - device could not be checked (retrying in 10 seconds)
2017.10.05 01:35:49 2: PRESENCE (Pres_HMUART) - check returned a valid result after 1 unsuccesful retry
2017.10.05 03:55:40 3: BlockingCall for LG_WOL was aborted
2017.10.05 04:46:47 3: BlockingCall for LG_WOL was aborted

Gruß Otto

CoolTux · 05 Oktober 2017, 10:37:33

Rudi hat gerade eine Blocking.pm Version ins SVN geladen, kannst Du die mal bitte testen? Ich bekomme leider das Problem auch nicht nachvollzogen.

darkness · 05 Oktober 2017, 13:01:32

Hallo,

ich habe gerade in SYSMON Error: Blocking call aborted (timeout) stehen.

Ist das das gleiche Problem?

CoolTux · 05 Oktober 2017, 13:03:30

Eher nicht. Das ist eigentlich die ganz normale Meldung wenn Blocking.pm die $finishFn nihct in der angegeben Zeit auf ruft.

darkness · 05 Oktober 2017, 13:29:50

ok, danke. Dann schaue ich mal.

Kawaci · 05 Oktober 2017, 14:11:18

Hier mein logging für das Problem mit Eq3bt!

Code Auswählen

_processGatttoolResult): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:09 1: BlockingInformParent (BlockingStart): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:09 1: BlockingInformParent (EQ3BT_processGatttoolResult): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:09 1: BlockingInformParent (BlockingStart): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:09 1: BlockingInformParent (EQ3BT_processGatttoolResult): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:09 1: BlockingInformParent (EQ3BT_processGatttoolResult): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:09 1: BlockingInformParent (BlockingStart): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:09 1: BlockingInformParent (EQ3BT_processGatttoolResult): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:09 1: BlockingInformParent (BlockingStart): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:09 1: BlockingInformParent (EQ3BT_processGatttoolResult): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:09 1: BlockingInformParent (BlockingStart): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:09 1: BlockingInformParent (EQ3BT_processGatttoolResult): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:09 1: BlockingInformParent (BlockingStart): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:09 1: BlockingInformParent (EQ3BT_processGatttoolResult): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:09 1: BlockingInformParent (BlockingStart): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:09 1: BlockingInformParent (EQ3BT_processGatttoolResult): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:09 1: BlockingInformParent (BlockingStart): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:09 1: BlockingInformParent (EQ3BT_processGatttoolResult): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:09 1: BlockingInformParent (BlockingStart): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:09 1: BlockingInformParent (EQ3BT_processGatttoolResult): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:09 1: BlockingInformParent (BlockingStart): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:09 1: BlockingInformParent (EQ3BT_processGatttoolResult): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:09 1: BlockingInformParent (BlockingStart): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:09 1: BlockingInformParent (EQ3BT_processGatttoolResult): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:10 1: BlockingInformParent (BlockingStart): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:10 1: BlockingInformParent (EQ3BT_processGatttoolResult): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:10 1: BlockingInformParent (BlockingStart): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:10 1: BlockingInformParent (EQ3BT_processGatttoolResult): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:10 1: BlockingInformParent (BlockingStart): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:10 1: BlockingInformParent (EQ3BT_processGatttoolResult): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:10 1: BlockingInformParent (BlockingStart): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:10 1: BlockingInformParent (EQ3BT_processGatttoolResult): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:10 1: BlockingInformParent (BlockingStart): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:10 1: BlockingInformParent (EQ3BT_processGatttoolResult): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:10 1: BlockingInformParent (BlockingStart): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:10 1: BlockingInformParent (EQ3BT_processGatttoolResult): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:10 1: BlockingInformParent (BlockingStart): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:10 1: BlockingInformParent (EQ3BT_processGatttoolResult): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:10 1: BlockingInformParent (BlockingStart): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:10 1: BlockingInformParent (EQ3BT_processGatttoolResult): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:10 1: BlockingInformParent (BlockingStart): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:10 1: BlockingInformParent (EQ3BT_processGatttoolResult): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:15 1: BlockingInformParent (BlockingStart): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:15 1: BlockingInformParent (EQ3BT_processGatttoolResult): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:17 1: BlockingInformParent (BlockingStart): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:17 1: BlockingInformParent (EQ3BT_processGatttoolResult): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:23 1: BlockingInformParent (BlockingStart): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:23 1: BlockingInformParent (EQ3BT_processGatttoolResult): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:26 1: BlockingInformParent (BlockingStart): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:26 1: BlockingInformParent (EQ3BT_processGatttoolResult): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:32 1: BlockingInformParent (BlockingStart): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:32 1: BlockingInformParent (EQ3BT_processGatttoolResult): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:39 1: BlockingInformParent (BlockingStart): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:39 1: BlockingInformParent (EQ3BT_processGatttoolResult): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:39 1: BlockingInformParent (BlockingStart): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:39 1: BlockingInformParent (EQ3BT_processGatttoolResult): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:40 1: BlockingInformParent (BlockingStart): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:40 1: BlockingInformParent (EQ3BT_processGatttoolResult): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:40 1: BlockingInformParent (BlockingStart): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:40 1: BlockingInformParent (EQ3BT_processGatttoolResult): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:40 1: BlockingInformParent (BlockingStart): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:40 1: BlockingInformParent (EQ3BT_processGatttoolResult): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:40 1: BlockingInformParent (BlockingStart): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:40 1: BlockingInformParent (EQ3BT_processGatttoolResult): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:40 1: BlockingInformParent (BlockingStart): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:40 1: BlockingInformParent (EQ3BT_processGatttoolResult): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:40 1: BlockingInformParent (BlockingStart): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:40 1: BlockingInformParent (EQ3BT_processGatttoolResult): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:40 1: BlockingInformParent (BlockingStart): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:40 1: BlockingInformParent (EQ3BT_processGatttoolResult): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:40 1: BlockingInformParent (BlockingStart): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:40 1: BlockingInformParent (EQ3BT_processGatttoolResult): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:40 1: BlockingInformParent (BlockingStart): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:40 1: BlockingInformParent (EQ3BT_processGatttoolResult): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:40 1: BlockingInformParent (BlockingStart): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:40 1: BlockingInformParent (EQ3BT_processGatttoolResult): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:40 1: BlockingInformParent (BlockingStart): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:40 1: BlockingInformParent (EQ3BT_processGatttoolResult): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:40 1: BlockingInformParent (BlockingStart): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:40 1: BlockingInformParent (EQ3BT_processGatttoolResult): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:40 1: BlockingInformParent (BlockingStart): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:40 1: BlockingInformParent (EQ3BT_processGatttoolResult): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:40 1: BlockingInformParent (BlockingStart): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:40 1: BlockingInformParent (EQ3BT_processGatttoolResult): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:40 1: BlockingInformParent (BlockingStart): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:40 1: BlockingInformParent (EQ3BT_processGatttoolResult): Can't connect to localhost:37871: IO::Socket::INET: connect: Connection refused
2017.10.05 14:05:40 1: BlockingInformParent (Bl

CoolTux · 05 Oktober 2017, 14:23:01

Bitte einmal Morgen ein Update machen. Es gibt eine angepasste Version von Blocking.pm

Otto123 · 05 Oktober 2017, 14:48:39

Ich habe die neue Blocking am laufen. Schnelle Ergebnisse kann ich nicht liefern, die Meldungen kamen sehr sporadisch

Bis jetzt läuft es...

CoolTux · 05 Oktober 2017, 14:50:47

Na schauen wir mal.

viegener · 05 Oktober 2017, 15:00:38

Die presence-Fehlermeldungen beim Start sind bei mir zumindest weg (mit der neuen Blocking aus svn) - die kamen bei jedem Neustart seit dem Update

sku · 05 Oktober 2017, 15:26:02

Ich habe seit dem Update heute folgende Einträge auf meine FHEM VM:

Code Auswählen

2017.10.05 15:19:34 2: PRESENCE (steffi_p10lite) - device could not be checked (retrying in 10 seconds): Process died prematurely
2017.10.05 15:19:34 2: PRESENCE (steffi_p10lite) - check returned a valid result after 1 unsuccesful retry
2017.10.05 15:19:49 2: PRESENCE (stffi_SGS3) - device could not be checked (retrying in 10 seconds): Process died prematurely
2017.10.05 15:19:49 2: PRESENCE (stffi_SGS3) - check returned a valid result after 1 unsuccesful retry
2017.10.05 15:20:19 2: PRESENCE (steffi_p10lite) - device could not be checked (retrying in 10 seconds): Process died prematurely
2017.10.05 15:20:19 2: PRESENCE (stffi_SGS3) - device could not be checked (retrying in 10 seconds): Process died prematurely
2017.10.05 15:20:19 2: PRESENCE (steffi_p10lite) - check returned a valid result after 1 unsuccesful retry
2017.10.05 15:20:19 2: PRESENCE (stffi_SGS3) - check returned a valid result after 1 unsuccesful retry
2017.10.05 15:20:34 2: PRESENCE (presence_P900) - device could not be checked (retrying in 10 seconds): Process died prematurely
2017.10.05 15:20:34 2: PRESENCE (presence_P900) - check returned a valid result after 1 unsuccesful retry
2017.10.05 15:20:50 2: PRESENCE (steffi_p10lite) - device could not be checked (retrying in 10 seconds): Process died prematurely
2017.10.05 15:20:50 2: PRESENCE (stffi_SGS3) - device could not be checked (retrying in 10 seconds): Process died prematurely
2017.10.05 15:20:50 2: PRESENCE (stffi_SGS3) - check returned a valid result after 1 unsuccesful retry
2017.10.05 15:20:50 2: PRESENCE (steffi_p10lite) - check returned a valid result after 1 unsuccesful retry

~~Interessant daran ist, dass ein PRESENCE device kein Logeinträge generiert.~~ Konfiguriert sind alle gleich, standard verbose level. Neustart von FHEM und der kompletten VM hat nix geändert.
Edit: Das fehlende device ist doch noch aufgetaucht.

FHEM am raspi3 hat auch solge Einträge, jedoch seltener.

viegener · 05 Oktober 2017, 15:28:48

Wie hier zu lesen - Es gibt ein neues Blocking.pm, das morgen wohl auch per update verfügbar ist - Also morgen update durchführen

Interessant wäre ob die Fehler morgen noch auftreten

sku · 06 Oktober 2017, 12:43:50

Hallo
bei mir ist der Fehler gelöst. Update vor ca. 2h durchgeführt, keine Fehler mehr im Log.

Gruß

viegener · 06 Oktober 2017, 12:50:52

In den letzen 24 h auch bei mir kein weiteres Auftreten, vorsichtig optimistisch...

Otto123 · 06 Oktober 2017, 12:58:07

Seit 24 h habe ich auch keine Meldungen mehr

Kawaci · 06 Oktober 2017, 13:40:57

Heute morgen update und kein blocking mehr!

ulli · 06 Oktober 2017, 20:49:28

Problem ist bei mir nach heutigen update immer noch vorhanden
2017.10.06 18:22:36 1: FRITZBOX FritzBox: Readout_Aborted.1865 Error: Timeout when reading Fritz!Box data.
Can't call method "sockhost" on an undefined value at ./FHEM/72_FRITZBOX.pm line 803.

jove01 · 11 Oktober 2017, 16:18:36

Hallo
auch nach einem Update heute habe ich nach dem Neustart

Code Auswählen


2017.10.11 15:43:49 1: Timeout for PRESENCE_DoLocalPingScan reached, terminated process 1391
2017.10.11 15:43:49 1: Timeout for PRESENCE_DoLocalPingScan reached, terminated process 1393
2017.10.11 15:46:05 1: Timeout for FRITZBOX_Readout_Run_Web reached, terminated process 1397
2017.10.11 15:46:05 1: FRITZBOX FritzBox: Readout_Aborted.1865 Error: Timeout when reading Fritz!Box data.
2017.10.11 15:46:05 1: Timeout for PRESENCE_DoLocalPingScan reached, terminated process 1396
2017.10.11 15:48:19 1: Timeout for PRESENCE_DoLocalPingScan reached, terminated process 1405
2017.10.11 15:48:19 1: Timeout for PRESENCE_DoLocalPingScan reached, terminated process 1406
2017.10.11 15:50:33 1: Timeout for WOL_Ping reached, terminated process 1424
2017.10.11 15:50:34 1: Timeout for PRESENCE_DoLocalPingScan reached, terminated process 1423
2017.10.11 15:52:48 1: Timeout for FRITZBOX_Readout_Run_Web reached, terminated process 1430
2017.10.11 15:52:48 1: FRITZBOX FritzBox: Readout_Aborted.1865 Error: Timeout when reading Fritz!Box data.
2017.10.11 15:52:48 1: Timeout for PRESENCE_DoLocalPingScan reached, terminated process 1428
2017.10.11 15:52:48 1: Timeout for PRESENCE_DoLocalPingScan reached, terminated process 1429
2017.10.11 15:55:04 1: Timeout for PRESENCE_DoLocalPingScan reached, terminated process 1435
2017.10.11 15:57:23 1: Timeout for PRESENCE_DoLocalPingScan reached, terminated process 1440
2017.10.11 15:57:23 1: Timeout for PRESENCE_DoLocalPingScan reached, terminated process 1441
2017.10.11 15:59:38 1: Timeout for FRITZBOX_Readout_Run_Web reached, terminated process 1461
2017.10.11 15:59:38 1: FRITZBOX FritzBox: Readout_Aborted.1865 Error: Timeout when reading Fritz!Box data.
2017.10.11 15:59:38 1: Timeout for PRESENCE_DoLocalPingScan reached, terminated process 1460
2017.10.11 16:04:12 1: Timeout for PRESENCE_DoLocalPingScan reached, terminated process 1477
2017.10.11 16:06:25 1: Timeout for FRITZBOX_Readout_Run_Web reached, terminated process 1494
2017.10.11 16:06:25 1: FRITZBOX FritzBox: Readout_Aborted.1865 Error: Timeout when reading Fritz!Box data.
2017.10.11 16:06:25 1: Timeout for PRESENCE_DoLocalPingScan reached, terminated process 1492
2017.10.11 16:06:25 1: Timeout for PRESENCE_DoLocalPingScan reached, terminated process 1493
2017.10.11 16:06:30 1: Timeout for WOL_Ping reached, terminated process 1522

freddykr · 04 November 2017, 21:26:36

Zitat von: ulli am 06 Oktober 2017, 20:49:28
Problem ist bei mir nach heutigen update immer noch vorhanden
2017.10.06 18:22:36 1: FRITZBOX FritzBox: Readout_Aborted.1865 Error: Timeout when reading Fritz!Box data.
Can't call method "sockhost" on an undefined value at ./FHEM/72_FRITZBOX.pm line 803.

Keiner mehr eine Idee dazu?
Bei mir müllt es leider auch das Log zu (siehe Zitat).

Blocking.pm 15200 2017-10-05 08:27:30Z
72_FRITZBOX.pm 15153 2017-09-30 08:48:53Z

Edit: Nach ein paar Stunden nächtlicher Fehlersuche fand ich den Fehler bei mir selbst. "fritz.box" war nicht mehr vom Raspi auflösbar. Nach dem Beheben dieses Fehlers war alles wieder ok und der Fehler weg.

djatebbi · 07 November 2017, 13:22:07

Hallo zusammen,

ich muss mich dieser Thematik jetzt leider doch mal anschließen.
Seit einem Update von der Blocking.pm Ende September bzw. Anfang Oktober startet mein FHEM nicht mehr bzw. friert beim Starten ein.
Nur wenn ich meine letzte funktionierende Blocking.pm vom 28.09.2017 wieder einspiele, startet und läuft FHEM bei mir normal.
Ich habe das ganze erst heute wieder getestet und bin der Sache bei mir nachgegangen.

Wenn ich meine Fritzboxen auf disable setzte, bleibt FHEM bei verbose 5 hier hängen:

Code Auswählen


2017.11.07 12:28:16 5: PRESENCE (NB_Garage_presence) - stopping timer
2017.11.07 12:28:16 5: PRESENCE (NB_Garage_presence) - starting blocking call for mode lan-ping

Und wenn ich die Presence auf disable setzte, bleibt FHEM bei verbose 5 hier hängen:

Code Auswählen


2017.11.07 12:32:04 4: BlockingCall (FRITZBOX_API_Check_Run): created child (-3044), uses telnetForBlockingFn_1510054324 to connect back
2017.11.07 12:32:04 4: FRITZBOX 1750E: Readout_Start.673 Fork process FRITZBOX_API_Check_Run

Wenn ich Fritzbox und Presence auf disable setzte, bleibt FHEM bei verbose 5 hier hängen:

Code Auswählen


2017.11.07 12:56:31 4: [NB_Garage] result executing ping: 
Ping wird ausgefhrt fr 192.168.100.54 mit 32 Bytes Daten:
Zeitberschreitung der Anforderung.
Zeitberschreitung der Anforderung.
Zeitberschreitung der Anforderung.
Zeitberschreitung der Anforderung.
Ping-Statistik fr 192.168.100.54:
    Pakete: Gesendet = 4, Empfangen = 0, Verloren = 4
    (100% Verlust),
2017.11.07 12:56:31 5: Cmd: >{BlockingStart('1')}<

Erst wenn ich alles deaktiviere was mit Fritzbox.pm, Presence.pm oder WOL.pm zu tun hat, starte bei mir FHEM komplett durch.
Das sind alles Module die offiziell die Blocking.pm laut Wikipedia Eintrag: https://wiki.fhem.de/wiki/Blocking_Call verwendet.
Derzeit setze ich das Update einfach aus, aber das kann doch auch keine Lösung sein.
Kann mir irgendjemand diesbezüglich weiterhelfen?
Gruß,

CoolTux · 07 November 2017, 13:38:13

Zitat von: djatebbi am 07 November 2017, 13:22:07
Seit einem Update von der Blocking.pm Ende September bzw. Anfang Oktober startet mein FHEM nicht mehr bzw. friert beim Starten ein.
Nur wenn ich meine letzte funktionierende Blocking.pm vom 28.09.2017 wieder einspiele, startet und läuft FHEM bei mir normal.

Wie verhält es sich wenn Du Dein System jetzt, hier und heute updatest. Hast Du die Probleme dann immer noch?
Bis auf WOL verwende ich alle von Dir genannten Module mit einem Update von vor 2 Wochen. Ich stelle keine Probleme fest.

Grüße

Fredi69 · 07 November 2017, 16:58:42

Zitat von: CoolTux am 07 November 2017, 13:38:13
Bis auf WOL verwende ich alle von Dir genannten Module mit einem Update von vor 2 Wochen. Ich stelle keine Probleme fest.

Kann ich bestätigen, ich verwende das auch so (ohne WOL) und mache jede Woche ein automatisches Update, es gibt keine Probleme.

Otto123 · 07 November 2017, 21:15:14

Ich muss einwerfen, dass ich zwar keine Meldungen mehr hatte, aber nach zwei Tagen alles zurück gedreht habe.
Bei mir ist es so, dass die Kombination presence, wol usw. und neue Blocking.pm seit dem Zeitpunkt (6.10.) "den Speicher frisst". d.h FHEM stellt nach ein paar Tagen nach und nach die Aktivitäten ein.
Ich habe leider noch keine Zeit gefunden das genau zu untersuchen. Ich dachte es klärt sich irgendwie, aber ich habe seitdem kein Update mehr gemacht, da bei den Modulen keine Neuerung kam. Da sich der Fehler hier im Forum nicht zeigte war ich ruhig. Ich muss das aber noch aufarbeiten.

Ich will unterm Strich nur sagen: Irgendwas ist da noch mit blocking und presence & Co.

Gruß Otto

djatebbi · 08 November 2017, 06:08:43

ZitatWie verhält es sich wenn Du Dein System jetzt, hier und heute updatest. Hast Du die Probleme dann immer noch?

Ja, wie ich schon schieb, gestern erst wieder getestet. Alle anderen Module sind auf neuesten Stand.
Gibt es irgendwo ein Changelog von der Blocking.pm?
Ich habe mal die letzte, bei mir funktionierende Blocking.pm, angehängt.

CoolTux · 08 November 2017, 06:17:56

Zitat von: djatebbi am 08 November 2017, 06:08:43
Ja, wie ich schon schieb, gestern erst wieder getestet. Alle anderen Module sind auf neuesten Stand.
Gibt es irgendwo ein Changelog von der Blocking.pm?
Ich habe mal die letzte, bei mir funktionierende Blocking.pm, angehängt.

Im SVN kann man nachlesen was geändert wurde.
Da die Angaben zeitlich gesehen von diesem Threadthema weit entfernt sind, empfehle ich einen neuen Thread im Forum Automatisierung auf zu machen und dort noch einmal Dein Problem genau zu beschreiben.

Grüße

djatebbi · 08 November 2017, 06:38:12

Ich sehe das nicht so, habe gerade die alte mit der neuen Datei verglichen,
meine alte Version ist vom 24.09.2017 und die neue 05.10.2017.
Daher würde ich sagen, das dies Zeitlich exakt rein passt.
Im Quellcode ist einiges hinzugekommen, hauptsächlich etwas wegen Telnet.
Leider kenne ich mich mit diesbezüglich nicht so gut aus.

djatebbi · 08 November 2017, 07:05:41

Ich habe jetzt nochmal die verschiedenen Versionen getestet.
Vom 24.09.2017, FHEM läuft ohne Probleme.
Vom 02.10.2017, FHEM läuft, bekomme aber die selben Fehlermeldungen wie die anderen User.
Vom 05.10.2017, FHEM läuft bei mir überhaupt nicht mehr.

Was jetzt? Passt es hier rein oder soll ich ein neues Thema aufmachen?

Amenophis86 · 08 November 2017, 07:25:28

Im Anfägerforum macht es eigentlich wenig Sinn, sondern eher im Forum welches für BlockingPM zuständig ist. Dafür mal in die Maintainer.txt schauen. Weiterhin wären weitere Informationen wichtig Log etc. Siehe die angepinnten Beiträge in diesem Board.

CoolTux · 08 November 2017, 07:33:01

Zitat von: djatebbi am 08 November 2017, 06:38:12
Ich sehe das nicht so, habe gerade die alte mit der neuen Datei verglichen,
meine alte Version ist vom 24.09.2017 und die neue 05.10.2017.
Daher würde ich sagen, das dies Zeitlich exakt rein passt.
Im Quellcode ist einiges hinzugekommen, hauptsächlich etwas wegen Telnet.
Leider kenne ich mich mit diesbezüglich nicht so gut aus.

Ich rede ja auch vom eigentlich großen Problem worauf dieser Thread basiert und bis heute. Rudi wird hier nicht mehr reinschauen und ihn betrifft es mit. Er ist der Maintainer der Blocking.pm. Aber was soll ich sagen, wenn an der Tür steht ziehen, kannst Du auch gerne drücken. Wird halt nur schwieriger

Kawaci · 24 November 2017, 17:47:25

hey leute! ich habe ein problem mit dem lepresenced!

wenn 2 gtags zuhause sind kommt das beide abwesend sind nach nem Neustart von lepresenced sind beide für 5 min da dann wieder das selbe!
im fhem log steht

Code Auswählen


2017.11.24 15:48:25 1: localhost:5333 disconnected, waiting to reappear (gtag_red)
2017.11.24 15:48:25 1: 127.0.0.1:5333 disconnected, waiting to reappear (gtag_green)
2017.11.24 15:48:51 1: FHEMWEB SSL/HTTPS error:  SSL accept attempt failed (peer: 192.168.1.103)
2017.11.24 15:49:25 1: 127.0.0.1:5333 reappeared (gtag_green)
2017.11.24 15:49:25 1: localhost:5333 reappeared (gtag_red)

und wenn ich den status mit sudo service lepresenced abfrage

Code Auswählen

● lepresenced.service - lepresenced
   Loaded: loaded (/lib/systemd/system/lepresenced.service; enabled; vendor preset: enabled)
   Active: active (running) since Fri 2017-11-24 16:09:17 CET; 1h 37min ago
  Process: 24805 ExecStartPre=/bin/sleep 10 (code=exited, status=0/SUCCESS)
 Main PID: 24848 (lepresenced)
   CGroup: /system.slice/lepresenced.service
           ├─24848 /usr/bin/perl /usr/sbin/lepresenced --device hci0 --listenaddress 0.0.0.0 --listenport 5333 --loglevel LOG_WARNING
           ├─24873 hcidump -i hci0
           └─26483 hcitool -i hci0 lescan --duplicates

Nov 24 16:09:07 fhemserver2 systemd[1]: Starting lepresenced...
Nov 24 16:09:17 fhemserver2 systemd[1]: Started lepresenced.
Nov 24 16:09:19 fhemserver2 lepresenced[24848]: [tid:1] main::bluetooth_scan_thread: Received unknown output: 'Disable scan failed: Input/output error'!
Nov 24 16:09:19 fhemserver2 lepresenced[24848]: [tid:1] main::bluetooth_scan_thread: hcitool exited, retrying...
Nov 24 16:09:24 fhemserver2 lepresenced[24848]: [tid:1] main::bluetooth_scan_thread: Received unknown output: 'Disable scan failed: Input/output error'!
Nov 24 16:09:24 fhemserver2 lepresenced[24848]: [tid:1] main::bluetooth_scan_thread: hcitool exited, retrying...
Nov 24 16:17:37 fhemserver2 lepresenced[24848]: [tid:1] main::bluetooth_scan_thread: Received unknown output: 'Disable scan failed: Input/output error'!
Nov 24 16:17:37 fhemserver2 lepresenced[24848]: [tid:1] main::bluetooth_scan_thread: hcitool exited, retrying...
Nov 24 16:17:49 fhemserver2 lepresenced[24848]: [tid:1] main::bluetooth_scan_thread: Received unknown output: 'Disable scan failed: Input/output error'!
Nov 24 16:17:49 fhemserver2 lepresenced[24848]: [tid:1] main::bluetooth_scan_thread: hcitool exited, retrying...
~

und das steht im syslog

Code Auswählen

Nov 24 11:31:06 fhemserver2 lepresenced[22691]: [tid:1] main::bluetooth_scan_thread: Received unknown output: 'Disable scan failed: Input/output error'!
Nov 24 11:31:06 fhemserver2 lepresenced[22691]: [tid:1] main::bluetooth_scan_thread: hcitool exited, retrying...
Nov 24 11:31:08 fhemserver2 kernel: [429732.342093] Bluetooth: hci0 advertising data length corrected