devspec2array (.*,(.*: Unmatched ( in regex; marked by <-- HERE in m/^( <-- HERE

[ToM_ToM]

Guten Abend Zusammen

Ich habe seit einigen Wochen alle paar Sekunden folgenden Text in meinem Logfile:

Code Auswählen Erweitern

2018.07.21 18:45:25 1: devspec2array (.*,(.*: Unmatched ( in regex; marked by <-- HERE in m/^( <-- HERE (.*)$/ at fhem.pl line 1288

Hat jemand eine Idee für mich wie ich der Sache auf dem Grund gehen kann um herauszufinden wo mein Fehler ist?


Hier nochmal ein längerer Auszug aus dem Logfile:

Code Auswählen Erweitern


2018.07.21 18:45:25 1: devspec2array (.*,(.*: Unmatched ( in regex; marked by <-- HERE in m/^( <-- HERE (.*)$/ at fhem.pl line 1288.

2018.07.21 18:45:25 1: stacktrace:
2018.07.21 18:45:25 1:     main::devspec2array                 called by fhem.pl (4923)
2018.07.21 18:45:25 1:     main::createNtfyHash                called by fhem.pl (3499)
2018.07.21 18:45:25 1:     main::DoTrigger                     called by fhem.pl (4531)
2018.07.21 18:45:25 1:     main::readingsEndUpdate             called by /opt/fhem/FHEM/88_HMCCU.pm (2841)
2018.07.21 18:45:25 1:     main::HMCCU_UpdateSingleDevice      called by /opt/fhem/FHEM/88_HMCCU.pm (2871)
2018.07.21 18:45:25 1:     main::HMCCU_UpdateMultipleDevices   called by /opt/fhem/FHEM/88_HMCCU.pm (5057)
2018.07.21 18:45:25 1:     main::HMCCU_ReadRPCQueue            called by fhem.pl (3127)
2018.07.21 18:45:25 1:     main::HandleTimeout                 called by fhem.pl (646)
2018.07.21 18:45:25 1: devspec2array (.*\.UNREACH,(.*\.UNREACH: Unmatched ( in regex; marked by <-- HERE in m/^( <-- HERE (.*\.UNREACH)$/ at fhem.pl line 1288.

2018.07.21 18:45:25 1: stacktrace:
2018.07.21 18:45:25 1:     main::devspec2array                 called by fhem.pl (4923)
2018.07.21 18:45:25 1:     main::createNtfyHash                called by fhem.pl (3499)
2018.07.21 18:45:25 1:     main::DoTrigger                     called by fhem.pl (4531)
2018.07.21 18:45:25 1:     main::readingsEndUpdate             called by /opt/fhem/FHEM/88_HMCCU.pm (2841)
2018.07.21 18:45:25 1:     main::HMCCU_UpdateSingleDevice      called by /opt/fhem/FHEM/88_HMCCU.pm (2871)
2018.07.21 18:45:25 1:     main::HMCCU_UpdateMultipleDevices   called by /opt/fhem/FHEM/88_HMCCU.pm (5057)
2018.07.21 18:45:25 1:     main::HMCCU_ReadRPCQueue            called by fhem.pl (3127)
2018.07.21 18:45:25 1:     main::HandleTimeout                 called by fhem.pl (646)
ping: icmp open socket: Operation not permitted
2018.07.21 18:45:52 1: devspec2array (.*,(.*: Unmatched ( in regex; marked by <-- HERE in m/^( <-- HERE (.*)$/ at fhem.pl line 1288.

2018.07.21 18:45:52 1: stacktrace:
2018.07.21 18:45:52 1:     main::devspec2array                 called by fhem.pl (4923)
2018.07.21 18:45:52 1:     main::createNtfyHash                called by fhem.pl (3499)
2018.07.21 18:45:52 1:     main::DoTrigger                     called by fhem.pl (4531)
2018.07.21 18:45:52 1:     main::readingsEndUpdate             called by fhem.pl (4713)
2018.07.21 18:45:52 1:     main::readingsSingleUpdate          called by /opt/fhem/FHEM/91_notify.pm (58)
2018.07.21 18:45:52 1:     main::notify_Define                 called by fhem.pl (3584)
2018.07.21 18:45:52 1:     main::CallFn                        called by fhem.pl (2016)
2018.07.21 18:45:52 1:     main::CommandModify                 called by fhem.pl (1209)
2018.07.21 18:45:52 1:     main::AnalyzeCommand                called by /opt/fhem/FHEM/01_FHEMWEB.pm (2576)
2018.07.21 18:45:52 1:     main::FW_fC                         called by /opt/fhem/FHEM/01_FHEMWEB.pm (859)
2018.07.21 18:45:52 1:     main::FW_answerCall                 called by /opt/fhem/FHEM/01_FHEMWEB.pm (532)
2018.07.21 18:45:52 1:     main::FW_Read                       called by fhem.pl (3584)
2018.07.21 18:45:52 1:     main::CallFn                        called by fhem.pl (723)
2018.07.21 18:45:52 1: devspec2array (.*\.UNREACH,(.*\.UNREACH: Unmatched ( in regex; marked by <-- HERE in m/^( <-- HERE (.*\.UNREACH)$/ at fhem.pl line 1288.

2018.07.21 18:45:52 1: stacktrace:
2018.07.21 18:45:52 1:     main::devspec2array                 called by fhem.pl (4923)
2018.07.21 18:45:52 1:     main::createNtfyHash                called by fhem.pl (3499)
2018.07.21 18:45:52 1:     main::DoTrigger                     called by fhem.pl (4531)
2018.07.21 18:45:52 1:     main::readingsEndUpdate             called by fhem.pl (4713)
2018.07.21 18:45:52 1:     main::readingsSingleUpdate          called by /opt/fhem/FHEM/91_notify.pm (58)
2018.07.21 18:45:52 1:     main::notify_Define                 called by fhem.pl (3584)
2018.07.21 18:45:52 1:     main::CallFn                        called by fhem.pl (2016)
2018.07.21 18:45:52 1:     main::CommandModify                 called by fhem.pl (1209)
2018.07.21 18:45:52 1:     main::AnalyzeCommand                called by /opt/fhem/FHEM/01_FHEMWEB.pm (2576)
2018.07.21 18:45:52 1:     main::FW_fC                         called by /opt/fhem/FHEM/01_FHEMWEB.pm (859)
2018.07.21 18:45:52 1:     main::FW_answerCall                 called by /opt/fhem/FHEM/01_FHEMWEB.pm (532)
2018.07.21 18:45:52 1:     main::FW_Read                       called by fhem.pl (3584)
2018.07.21 18:45:52 1:     main::CallFn                        called by fhem.pl (723)
2018.07.21 18:45:52 1: devspec2array (.*,(.*: Unmatched ( in regex; marked by <-- HERE in m/^( <-- HERE (.*)$/ at fhem.pl line 1288.

2018.07.21 18:45:52 1: stacktrace:
2018.07.21 18:45:52 1:     main::devspec2array                 called by fhem.pl (4923)
2018.07.21 18:45:52 1:     main::createNtfyHash                called by fhem.pl (3499)
2018.07.21 18:45:52 1:     main::DoTrigger                     called by fhem.pl (4531)
2018.07.21 18:45:52 1:     main::readingsEndUpdate             called by fhem.pl (4713)
2018.07.21 18:45:52 1:     main::readingsSingleUpdate          called by /opt/fhem/FHEM/00_MQTT.pm (522)
2018.07.21 18:45:52 1:     MQTT::__ANON__                      called by FHEM/GPUtils.pm (48)
2018.07.21 18:45:52 1:     GPUtils::GP_ForallClients           called by /opt/fhem/FHEM/00_MQTT.pm (534)
2018.07.21 18:45:52 1:     MQTT::Read                          called by fhem.pl (3584)
2018.07.21 18:45:52 1:     main::CallFn                        called by fhem.pl (723)
2018.07.21 18:45:52 1: devspec2array (.*\.UNREACH,(.*\.UNREACH: Unmatched ( in regex; marked by <-- HERE in m/^( <-- HERE (.*\.UNREACH)$/ at fhem.pl line 1288.

2018.07.21 18:45:52 1: stacktrace:
2018.07.21 18:45:52 1:     main::devspec2array                 called by fhem.pl (4923)
2018.07.21 18:45:52 1:     main::createNtfyHash                called by fhem.pl (3499)
2018.07.21 18:45:52 1:     main::DoTrigger                     called by fhem.pl (4531)
2018.07.21 18:45:52 1:     main::readingsEndUpdate             called by fhem.pl (4713)
2018.07.21 18:45:52 1:     main::readingsSingleUpdate          called by /opt/fhem/FHEM/00_MQTT.pm (522)
2018.07.21 18:45:52 1:     MQTT::__ANON__                      called by FHEM/GPUtils.pm (48)
2018.07.21 18:45:52 1:     GPUtils::GP_ForallClients           called by /opt/fhem/FHEM/00_MQTT.pm (534)
2018.07.21 18:45:52 1:     MQTT::Read                          called by fhem.pl (3584)
2018.07.21 18:45:52 1:     main::CallFn                        called by fhem.pl (723)
ping: icmp open socket: Operation not permitted
2018.07.21 18:46:08 1: devspec2array (.*,(.*: Unmatched ( in regex; marked by <-- HERE in m/^( <-- HERE (.*)$/ at fhem.pl line 1288.

2018.07.21 18:46:08 1: stacktrace:
2018.07.21 18:46:08 1:     main::devspec2array                 called by fhem.pl (4923)
2018.07.21 18:46:08 1:     main::createNtfyHash                called by fhem.pl (3499)
2018.07.21 18:46:08 1:     main::DoTrigger                     called by fhem.pl (4531)
2018.07.21 18:46:08 1:     main::readingsEndUpdate             called by /opt/fhem/FHEM/88_HMCCU.pm (2841)
2018.07.21 18:46:08 1:     main::HMCCU_UpdateSingleDevice      called by /opt/fhem/FHEM/88_HMCCU.pm (2871)
2018.07.21 18:46:08 1:     main::HMCCU_UpdateMultipleDevices   called by /opt/fhem/FHEM/88_HMCCU.pm (5057)
2018.07.21 18:46:08 1:     main::HMCCU_ReadRPCQueue            called by fhem.pl (3127)
2018.07.21 18:46:08 1:     main::HandleTimeout                 called by fhem.pl (646)
2018.07.21 18:46:08 1: devspec2array (.*\.UNREACH,(.*\.UNREACH: Unmatched ( in regex; marked by <-- HERE in m/^( <-- HERE (.*\.UNREACH)$/ at fhem.pl line 1288.

2018.07.21 18:46:08 1: stacktrace:
2018.07.21 18:46:08 1:     main::devspec2array                 called by fhem.pl (4923)
2018.07.21 18:46:08 1:     main::createNtfyHash                called by fhem.pl (3499)
2018.07.21 18:46:08 1:     main::DoTrigger                     called by fhem.pl (4531)
2018.07.21 18:46:08 1:     main::readingsEndUpdate             called by /opt/fhem/FHEM/88_HMCCU.pm (2841)
2018.07.21 18:46:08 1:     main::HMCCU_UpdateSingleDevice      called by /opt/fhem/FHEM/88_HMCCU.pm (2871)
2018.07.21 18:46:08 1:     main::HMCCU_UpdateMultipleDevices   called by /opt/fhem/FHEM/88_HMCCU.pm (5057)
2018.07.21 18:46:08 1:     main::HMCCU_ReadRPCQueue            called by fhem.pl (3127)
2018.07.21 18:46:08 1:     main::HandleTimeout                 called by fhem.pl (646)
2018.07.21 18:46:19 1: devspec2array (.*,(.*: Unmatched ( in regex; marked by <-- HERE in m/^( <-- HERE (.*)$/ at fhem.pl line 1288.

2018.07.21 18:46:19 1: stacktrace:
2018.07.21 18:46:19 1:     main::devspec2array                 called by fhem.pl (4923)
2018.07.21 18:46:19 1:     main::createNtfyHash                called by fhem.pl (3499)
2018.07.21 18:46:19 1:     main::DoTrigger                     called by fhem.pl (4531)
2018.07.21 18:46:19 1:     main::readingsEndUpdate             called by /opt/fhem/FHEM/88_HMCCU.pm (2841)
2018.07.21 18:46:19 1:     main::HMCCU_UpdateSingleDevice      called by /opt/fhem/FHEM/88_HMCCU.pm (2871)
2018.07.21 18:46:19 1:     main::HMCCU_UpdateMultipleDevices   called by /opt/fhem/FHEM/88_HMCCU.pm (5057)
2018.07.21 18:46:19 1:     main::HMCCU_ReadRPCQueue            called by fhem.pl (3127)
2018.07.21 18:46:19 1:     main::HandleTimeout                 called by fhem.pl (646)
2018.07.21 18:46:19 1: devspec2array (.*\.UNREACH,(.*\.UNREACH: Unmatched ( in regex; marked by <-- HERE in m/^( <-- HERE (.*\.UNREACH)$/ at fhem.pl line 1288.

2018.07.21 18:46:19 1: stacktrace:
2018.07.21 18:46:19 1:     main::devspec2array                 called by fhem.pl (4923)
2018.07.21 18:46:19 1:     main::createNtfyHash                called by fhem.pl (3499)
2018.07.21 18:46:19 1:     main::DoTrigger                     called by fhem.pl (4531)
2018.07.21 18:46:19 1:     main::readingsEndUpdate             called by /opt/fhem/FHEM/88_HMCCU.pm (2841)
2018.07.21 18:46:19 1:     main::HMCCU_UpdateSingleDevice      called by /opt/fhem/FHEM/88_HMCCU.pm (2871)
2018.07.21 18:46:19 1:     main::HMCCU_UpdateMultipleDevices   called by /opt/fhem/FHEM/88_HMCCU.pm (5057)
2018.07.21 18:46:19 1:     main::HMCCU_ReadRPCQueue            called by fhem.pl (3127)
2018.07.21 18:46:19 1:     main::HandleTimeout                 called by fhem.pl (646)
ping: icmp open socket: Operation not permitted
2018.07.21 18:46:52 1: devspec2array (.*,(.*: Unmatched ( in regex; marked by <-- HERE in m/^( <-- HERE (.*)$/ at fhem.pl line 1288.

2018.07.21 18:46:52 1: stacktrace:
2018.07.21 18:46:52 1:     main::devspec2array                 called by fhem.pl (4923)
2018.07.21 18:46:52 1:     main::createNtfyHash                called by fhem.pl (3499)
2018.07.21 18:46:52 1:     main::DoTrigger                     called by fhem.pl (4531)
2018.07.21 18:46:52 1:     main::readingsEndUpdate             called by fhem.pl (4713)
2018.07.21 18:46:52 1:     main::readingsSingleUpdate          called by /opt/fhem/FHEM/00_MQTT.pm (522)
2018.07.21 18:46:52 1:     MQTT::__ANON__                      called by FHEM/GPUtils.pm (48)
2018.07.21 18:46:52 1:     GPUtils::GP_ForallClients           called by /opt/fhem/FHEM/00_MQTT.pm (534)
2018.07.21 18:46:52 1:     MQTT::Read                          called by fhem.pl (3584)
2018.07.21 18:46:52 1:     main::CallFn                        called by fhem.pl (723)
2018.07.21 18:46:52 1: devspec2array (.*\.UNREACH,(.*\.UNREACH: Unmatched ( in regex; marked by <-- HERE in m/^( <-- HERE (.*\.UNREACH)$/ at fhem.pl line 1288.

2018.07.21 18:46:52 1: stacktrace:
2018.07.21 18:46:52 1:     main::devspec2array                 called by fhem.pl (4923)
2018.07.21 18:46:52 1:     main::createNtfyHash                called by fhem.pl (3499)
2018.07.21 18:46:52 1:     main::DoTrigger                     called by fhem.pl (4531)
2018.07.21 18:46:52 1:     main::readingsEndUpdate             called by fhem.pl (4713)
2018.07.21 18:46:52 1:     main::readingsSingleUpdate          called by /opt/fhem/FHEM/00_MQTT.pm (522)
2018.07.21 18:46:52 1:     MQTT::__ANON__                      called by FHEM/GPUtils.pm (48)
2018.07.21 18:46:52 1:     GPUtils::GP_ForallClients           called by /opt/fhem/FHEM/00_MQTT.pm (534)
2018.07.21 18:46:52 1:     MQTT::Read                          called by fhem.pl (3584)
2018.07.21 18:46:52 1:     main::CallFn                        called by fhem.pl (723)
2018.07.21 18:47:01 1: devspec2array (.*,(.*: Unmatched ( in regex; marked by <-- HERE in m/^( <-- HERE (.*)$/ at fhem.pl line 1288.

2018.07.21 18:47:01 1: stacktrace:
2018.07.21 18:47:01 1:     main::devspec2array                 called by fhem.pl (4923)
2018.07.21 18:47:01 1:     main::createNtfyHash                called by fhem.pl (3499)
2018.07.21 18:47:01 1:     main::DoTrigger                     called by fhem.pl (4531)
2018.07.21 18:47:01 1:     main::readingsEndUpdate             called by /opt/fhem/FHEM/89_FULLY.pm (567)
2018.07.21 18:47:01 1:     main::FULLY_UpdateReadings          called by /opt/fhem/FHEM/89_FULLY.pm (489)
2018.07.21 18:47:01 1:     main::FULLY_GotDeviceInfo           called by (eval 283395) (1)
2018.07.21 18:47:01 1:     (eval)                              called by fhem.pl (1112)
2018.07.21 18:47:01 1:     main::AnalyzePerlCommand            called by fhem.pl (1135)
2018.07.21 18:47:01 1:     main::AnalyzeCommand                called by fhem.pl (1059)
2018.07.21 18:47:01 1:     main::AnalyzeCommandChain           called by /opt/fhem/FHEM/98_telnet.pm (241)
2018.07.21 18:47:01 1:     main::telnet_Read                   called by fhem.pl (3584)
2018.07.21 18:47:01 1:     main::CallFn                        called by fhem.pl (723)
2018.07.21 18:47:01 1: devspec2array (.*\.UNREACH,(.*\.UNREACH: Unmatched ( in regex; marked by <-- HERE in m/^( <-- HERE (.*\.UNREACH)$/ at fhem.pl line 1288.

2018.07.21 18:47:01 1: stacktrace:
2018.07.21 18:47:01 1:     main::devspec2array                 called by fhem.pl (4923)
2018.07.21 18:47:01 1:     main::createNtfyHash                called by fhem.pl (3499)
2018.07.21 18:47:01 1:     main::DoTrigger                     called by fhem.pl (4531)
2018.07.21 18:47:01 1:     main::readingsEndUpdate             called by /opt/fhem/FHEM/89_FULLY.pm (567)
2018.07.21 18:47:01 1:     main::FULLY_UpdateReadings          called by /opt/fhem/FHEM/89_FULLY.pm (489)
2018.07.21 18:47:01 1:     main::FULLY_GotDeviceInfo           called by (eval 283395) (1)
2018.07.21 18:47:01 1:     (eval)                              called by fhem.pl (1112)
2018.07.21 18:47:01 1:     main::AnalyzePerlCommand            called by fhem.pl (1135)
2018.07.21 18:47:01 1:     main::AnalyzeCommand                called by fhem.pl (1059)
2018.07.21 18:47:01 1:     main::AnalyzeCommandChain           called by /opt/fhem/FHEM/98_telnet.pm (241)
2018.07.21 18:47:01 1:     main::telnet_Read                   called by fhem.pl (3584)
2018.07.21 18:47:01 1:     main::CallFn                        called by fhem.pl (723)
ping: icmp open socket: Operation not permitted
2018.07.21 18:47:32 1: devspec2array (.*,(.*: Unmatched ( in regex; marked by <-- HERE in m/^( <-- HERE (.*)$/ at fhem.pl line 1288.

2018.07.21 18:47:32 1: stacktrace:
2018.07.21 18:47:32 1:     main::devspec2array                 called by fhem.pl (4923)
2018.07.21 18:47:32 1:     main::createNtfyHash                called by fhem.pl (3499)
2018.07.21 18:47:32 1:     main::DoTrigger                     called by fhem.pl (4531)
2018.07.21 18:47:32 1:     main::readingsEndUpdate             called by fhem.pl (4713)
2018.07.21 18:47:32 1:     main::readingsSingleUpdate          called by /opt/fhem/FHEM/00_MQTT.pm (522)
2018.07.21 18:47:32 1:     MQTT::__ANON__                      called by FHEM/GPUtils.pm (48)
2018.07.21 18:47:32 1:     GPUtils::GP_ForallClients           called by /opt/fhem/FHEM/00_MQTT.pm (534)
2018.07.21 18:47:32 1:     MQTT::Read                          called by fhem.pl (3584)
2018.07.21 18:47:32 1:     main::CallFn                        called by fhem.pl (723)
2018.07.21 18:47:32 1: devspec2array (.*\.UNREACH,(.*\.UNREACH: Unmatched ( in regex; marked by <-- HERE in m/^( <-- HERE (.*\.UNREACH)$/ at fhem.pl line 1288.

2018.07.21 18:47:32 1: stacktrace:
2018.07.21 18:47:32 1:     main::devspec2array                 called by fhem.pl (4923)
2018.07.21 18:47:32 1:     main::createNtfyHash                called by fhem.pl (3499)
2018.07.21 18:47:32 1:     main::DoTrigger                     called by fhem.pl (4531)
2018.07.21 18:47:32 1:     main::readingsEndUpdate             called by fhem.pl (4713)
2018.07.21 18:47:32 1:     main::readingsSingleUpdate          called by /opt/fhem/FHEM/00_MQTT.pm (522)
2018.07.21 18:47:32 1:     MQTT::__ANON__                      called by FHEM/GPUtils.pm (48)
2018.07.21 18:47:32 1:     GPUtils::GP_ForallClients           called by /opt/fhem/FHEM/00_MQTT.pm (534)
2018.07.21 18:47:32 1:     MQTT::Read                          called by fhem.pl (3584)
2018.07.21 18:47:32 1:     main::CallFn                        called by fhem.pl (723)
ping: icmp open socket: Operation not permitted
2018.07.21 18:47:57 1: devspec2array (.*,(.*: Unmatched ( in regex; marked by <-- HERE in m/^( <-- HERE (.*)$/ at fhem.pl line 1288.





VG, Thomas

[rudolfkoenig]

Kannst du bitte in fhem.pl, vor der Zeile 4293 (nach dem else) folgende Zeile einfuegen:

Code Auswählen Erweitern

Log 1, "$d:$ndl";
und die Ausgabe hier anhaengen?

[ToM_ToM]

Da gibt's bei mir gar kein else.
Aber ich denke, du meinst vor dem normalen return?

Wenn ich es dort einfüge, startet FHEM nicht mehr und das Log spuckt folgendes aus:

Code Auswählen Erweitern


2018.07.25 17:15:52 1: update finished, "shutdown restart" is needed to activate the changes.
2018.07.25 17:15:52 1:
2018.07.25 17:15:52 1: Please consider using the global attribute sendStatistics
ping: icmp open socket: Operation not permitted
ping: icmp open socket: Operation not permitted
2018.07.25 17:17:04 0: Server shutdown
2018.07.25 17:17:04 2: HMCCURPCPROC: [d_rpcBidCos_RF] Found no running processes. Cleaning up ...
2018.07.25 17:17:04 1: HMCCURPCPROC: [d_rpcBidCos_RF] Housekeeping called. Cleaning up RPC environment
2018.07.25 17:17:04 2: HMCCURPCPROC: [d_rpcBidCos_Wired] Found no running processes. Cleaning up ...
2018.07.25 17:17:04 1: HMCCURPCPROC: [d_rpcBidCos_Wired] Housekeeping called. Cleaning up RPC environment
2018.07.25 17:17:04 1: PERL WARNING: Use of uninitialized value $rpcdev in string eq at /opt/fhem/FHEM/88_HMCCU.pm line 3236.
2018.07.25 17:17:04 1: stacktrace:
2018.07.25 17:17:04 1:     main::__ANON__                      called by /opt/fhem/FHEM/88_HMCCU.pm (3236)
2018.07.25 17:17:04 1:     main::HMCCU_StopExtRPCServer        called by /opt/fhem/FHEM/88_HMCCU.pm (1131)
2018.07.25 17:17:04 1:     main::HMCCU_Shutdown                called by fhem.pl (3584)
2018.07.25 17:17:04 1:     main::CallFn                        called by fhem.pl (1705)
2018.07.25 17:17:04 1:     main::CommandShutdown               called by fhem.pl (1209)
2018.07.25 17:17:04 1:     main::AnalyzeCommand                called by fhem.pl (1059)
2018.07.25 17:17:04 1:     main::AnalyzeCommandChain           called by /opt/fhem/FHEM/01_FHEMWEB.pm (2578)
2018.07.25 17:17:04 1:     main::FW_fC                         called by /opt/fhem/FHEM/01_FHEMWEB.pm (859)
2018.07.25 17:17:04 1:     main::FW_answerCall                 called by /opt/fhem/FHEM/01_FHEMWEB.pm (532)
2018.07.25 17:17:04 1:     main::FW_Read                       called by fhem.pl (3584)
2018.07.25 17:17:04 1:     main::CallFn                        called by fhem.pl (723)
2018.07.25 17:17:04 0: HMCCU: Can't find RPC device
2018.07.25 17:17:04 1: Shutdown executed
2018.07.25 17:17:06 2: HMCCURPCPROC: [d_rpcBidCos_RF] Found no running processes. Cleaning up ...
2018.07.25 17:17:06 1: HMCCURPCPROC: [d_rpcBidCos_RF] Housekeeping called. Cleaning up RPC environment
2018.07.25 17:17:06 2: HMCCURPCPROC: [d_rpcBidCos_Wired] Found no running processes. Cleaning up ...
2018.07.25 17:17:06 1: HMCCURPCPROC: [d_rpcBidCos_Wired] Housekeeping called. Cleaning up RPC environment
Global symbol "$d" requires explicit package name at fhem.pl line 4291.
Global symbol "$ndl" requires explicit package name at fhem.pl line 4291.
BEGIN not safe after errors--compilation aborted at fhem.pl line 4888.



VG, Thomas

[rudolfkoenig]

Sorry, mein Fehler, ich meinte die Zeile 4923, so wie in deinem ersten Stacktrace erwaehnt wurde, in der Funktion createNtfyHash

[ToM_ToM]

Guten Morgen.

Anbei ein neuer Auszug aus dem Logfile nach dem Einfügen der Zeile und einem Restart:

EDIT: Mussste es anhängen da es zu viel für den Thread-Eintrag ist.

VG, Thomas

[rudolfkoenig]

Da ist wohl einiges schief, Folgendes faellt mir auf:
- der Perl-Interpreter beschwert sich ueber unachtsame Programmierung in 99_myRaumfeld.pm
- HUEDevice* hat kein I/O device
- 192.168.20.21 ist nicht erreichbar
- viele nicht mehr zulaessige ReadingNamen in HTTPMOD
- CUL: Can't open /dev/ttyS0: Keine Berechtigung
- CUL: Can't open /dev/ttyS10: Eingabe-/Ausgabefehler (ich wuerde initialUsbCheck abschalten)

Das eigentliche Problem scheint mit den *monitoring Geraeten zu tun haben (Batterie_monitoring, DeviceReachable_monitoring), kannst du bitte deren Definition hier zeigen?

[ToM_ToM]

Das mit der Erreichbarkeit ist ein Problem da wir einen Wasserschaden hatten und dadurch einige Geräte defekt sind. Das kann ich leider erst wieder beheben wenn ich vor Ort bin.
initialUSBCheck habe ich deaktiviert und die myRaumfeld rausgeschmissen da ich die aktuell nicht mehr verwende.
Die HTTPMOD muss ich mir gleich mal anschauen was da nicht mehr zulässig ist.

Hier meine monitoring devices:

DeviceReachable_monitoring

Code Auswählen Erweitern


defmod DeviceReachable_monitoring monitoring (.*\.UNREACH:.1|.*\.UNREACH:.yes|.*\.UNREACH:.true) (.*\.UNREACH:.0|.*\.UNREACH:.no|.*\.UNREACH:.false)
attr DeviceReachable_monitoring errorReturn {return unless(@errors);;\
$_ = AttrVal($_, "alias", $_) foreach(@errors);;\
return("Das Gerät \"$errors[0]\" ist schon länger nicht mehr erreichbar.") if(int(@errors) == 1);;\
@errors = sort {lc($a) cmp lc($b)} @errors;;\
return(join("\n - ", "Die folgenden ".@errors." Geräte sind schon länger nicht mehr erreichbar:", @errors))\
}
attr DeviceReachable_monitoring errorWait 60*60*24
attr DeviceReachable_monitoring group Monitoring
attr DeviceReachable_monitoring room Allgemein
attr DeviceReachable_monitoring userReadings warning_count:warning.+ {split(",", ReadingsVal($name, "warning", ""))}, \
error_count:error.+ {split(",", ReadingsVal($name, "error", ""))}, \
all_count:(warning.+|error.+) {split(",", ReadingsVal($name, "warning", "")) + split(",", ReadingsVal($name, "error", ""))}
attr DeviceReachable_monitoring warningReturn {return unless(@warnings);;\
$_ = AttrVal($_, "alias", $_) foreach(@warnings);;\
return("Das Gerät \"$warnings[0]\" ist schon länger nicht mehr erreichbar.") if(int(@warnings) == 1);;\
@warnings = sort {lc($a) cmp lc($b)} @warnings;;\
return(join("\n - ", "Die folgenden ".@warnings." Geräte sind schon länger nicht mehr erreichbar:", @warnings))\
}


Batterie_monitoring

Code Auswählen Erweitern


defmod Batterie_monitoring monitoring (.*:battery:.low|.*LOWBAT:.yes|.*LOWBAT:.true) (.*:battery:.ok|.*LOWBAT:.no|.*LOWBAT:.false)
attr Batterie_monitoring errorReturn {return unless(@errors);;\
$_ = AttrVal($_, "alias", $_) foreach(@errors);;\
return("Bei dem Gerät \"$errors[0]\" ist die Batterie leer und muss gewechselt werden.") if(int(@errors) == 1);;\
@errors = sort {lc($a) cmp lc($b)} @errors;;\
return(join("\n - ", "Bei den ".@errors." Geräten sind die Batterien leer und müssen gewechselt werden:", @errors))\
}
attr Batterie_monitoring errorWait 60*60*24*14
attr Batterie_monitoring group Monitoring
attr Batterie_monitoring room Allgemein
attr Batterie_monitoring userReadings warning_count:warning.+ {split(",", ReadingsVal($name, "warning", ""))}, \
error_count:error.+ {split(",", ReadingsVal($name, "error", ""))}, \
all_count:(warning.+|error.+) {split(",", ReadingsVal($name, "warning", "")) + split(",", ReadingsVal($name, "error", ""))}
attr Batterie_monitoring warningReturn {return unless(@warnings);;\
$_ = AttrVal($_, "alias", $_) foreach(@warnings);;\
return("Bei dem Gerät \"$warnings[0]\" muss die Batterie demnächst gewechselt werden.") if(int(@warnings) == 1);;\
@warnings = sort {lc($a) cmp lc($b)} @warnings;;\
return(join("\n - ", "Bei folgenden ".@warnings." Geräten muss die Batterie demnächst gewechselt werden:", @warnings))\
}
attr Batterie_monitoring warningWait 60*60*5


[rudolfkoenig]

Das Modul 98_monitoring.pm setzt NOTIFYDEV selbst, und erzeugt dabei einen falschen Regexp.
Der empfohlene Weg ist die vom FHEM Framework bereitgestellte notifyRegexpChanged Funktion zu verwenden.

Bitte Modul-Maintainer benachrichtigen mit Hinweis auf diese Diskussion, z.Bsp. durch neues Thema im Forums-Abschnitt Automatisierung, mit passenden Betreff.

[ToM_ToM]

Okay, mache ich.

Vielen Dank für deine Unterstützung.

VG, Thomas

[ToM_ToM]

Hi Rudolf,

habe es an den Entwickler weitergegeben und er ist schon dran.
Zusätzlich habe ich vorübergehend die monitoring Devices aus meinem System rausgeschmissen um zu testen ob es dann mit dem notify funktioniert.

Leider besteht mein Problem mit dem notify weiterhin. Wenn ich die vereinfachte Form verwende, wird das erste setreading ausgeführt und das zweite ignoriert als wäre es gar nicht da.
Das reading EnergyTodayFormatted  sollte hier auf 5W gesetzt werden, es passiert aber nichts. Das reading EnergyToday hingegen wird korrekt gefüllt.

Code Auswählen Erweitern


HM_GN_Solarstrom_[\w]+[\s:]?2\.EnergyToday:.* {
my $woodshedEnergyToday = ReadingsVal("HM_GN_Solarstrom_Woodshed","EnergyToday",0);
my $carportEnergyToday = ReadingsVal("HM_GN_Solarstrom_Carport","EnergyToday",0);
my $energyTodayTotal = ($woodshedEnergyToday + $carportEnergyToday);
fhem("setreading Solarstrom_Total EnergyToday $energyTodayTotal");
fhem("setreading Solarstrom_Total EnergyTodayFormatted 5W");
}


EDIT: Ich habe jetzt einfach mal die Reihenfolge der beiden setreading - Befehle getauscht.
Und es wird weiterhin das folgende ignoriert:

Code Auswählen Erweitern

fhem("setreading Solarstrom_Total EnergyTodayFormatted 5W");

Reihenfolge kann also nicht mehr das Thema sein.
Aber das verstehe ich nicht. Wenn ich folgendes in der Kommandozeile eingebe, wird der Wert korrekt auf 5W geändert:

Code Auswählen Erweitern

setreading Solarstrom_Total EnergyTodayFormatted 5W

Hast du noch eine Idee?

[betateilchen]

Nachdem Du nun schon einiges geändert hast, wäre es vielleicht hilfreich, die aktuell noch auftretende Meldung im Logfile zu sehen.

Die Klammern bei Deiner Addition kannst Du übrigens weglassen. (hat aber nix mit Deinem "Problem" zu tun)

[ToM_ToM]

Hallo betateilchen,

das mit der Klammer habe ich wegen der besseren Lesbarkeit.

Anbei ein Auszug aus dem aktuellen Log:

Code Auswählen Erweitern


ping: icmp open socket: Operation not permitted
2018.07.29 18:37:23 1: PERL WARNING: 400-URL-Absolute-Error! Location: "192.168.178.46", Content: "400 URL must be absolute
" at /opt/fhem/FHEM/98_DLNARenderer.pm line 249.
2018.07.29 18:37:23 1: stacktrace:
2018.07.29 18:37:23 1:     main::__ANON__                      called by /usr/local/share/perl/5.20.2/Carp.pm (169)
2018.07.29 18:37:23 1:     Carp::carp                          called by FHEM/lib/UPnP/ControlPoint.pm (368)
2018.07.29 18:37:23 1:     UPnP::ControlPoint::_createDevice   called by FHEM/lib/UPnP/ControlPoint.pm (400)
2018.07.29 18:37:23 1:     UPnP::ControlPoint::_getDeviceFromHeaders called by FHEM/lib/UPnP/ControlPoint.pm (524)
2018.07.29 18:37:23 1:     UPnP::ControlPoint::_receiveSSDPEvent called by FHEM/lib/UPnP/ControlPoint.pm (225)
2018.07.29 18:37:23 1:     UPnP::ControlPoint::handleOnce      called by /opt/fhem/FHEM/98_DLNARenderer.pm (249)
2018.07.29 18:37:23 1:     (eval)                              called by /opt/fhem/FHEM/98_DLNARenderer.pm (248)
2018.07.29 18:37:23 1:     main::DLNARenderer_Read             called by fhem.pl (3584)
2018.07.29 18:37:23 1:     main::CallFn                        called by fhem.pl (723)
2018.07.29 18:37:23 1: PERL WARNING: Loading device description failed with error: 400 URL must be absolute (Location: 192.168.178.46) at /opt/fhem/FHEM/98_DLNARenderer.pm line 249.
2018.07.29 18:37:23 1: stacktrace:
2018.07.29 18:37:23 1:     main::__ANON__                      called by /usr/local/share/perl/5.20.2/Carp.pm (169)
2018.07.29 18:37:23 1:     Carp::carp                          called by FHEM/lib/UPnP/ControlPoint.pm (369)
2018.07.29 18:37:23 1:     UPnP::ControlPoint::_createDevice   called by FHEM/lib/UPnP/ControlPoint.pm (400)
2018.07.29 18:37:23 1:     UPnP::ControlPoint::_getDeviceFromHeaders called by FHEM/lib/UPnP/ControlPoint.pm (524)
2018.07.29 18:37:23 1:     UPnP::ControlPoint::_receiveSSDPEvent called by FHEM/lib/UPnP/ControlPoint.pm (225)
2018.07.29 18:37:23 1:     UPnP::ControlPoint::handleOnce      called by /opt/fhem/FHEM/98_DLNARenderer.pm (249)
2018.07.29 18:37:23 1:     (eval)                              called by /opt/fhem/FHEM/98_DLNARenderer.pm (248)
2018.07.29 18:37:23 1:     main::DLNARenderer_Read             called by fhem.pl (3584)
2018.07.29 18:37:23 1:     main::CallFn                        called by fhem.pl (723)
2018.07.29 18:37:23 1: PERL WARNING: 400-URL-Absolute-Error! Location: "192.168.178.46", Content: "400 URL must be absolute
" at /opt/fhem/FHEM/98_DLNARenderer.pm line 249.
2018.07.29 18:37:23 1: stacktrace:
2018.07.29 18:37:23 1:     main::__ANON__                      called by /usr/local/share/perl/5.20.2/Carp.pm (169)
2018.07.29 18:37:23 1:     Carp::carp                          called by FHEM/lib/UPnP/ControlPoint.pm (368)
2018.07.29 18:37:23 1:     UPnP::ControlPoint::_createDevice   called by FHEM/lib/UPnP/ControlPoint.pm (400)
2018.07.29 18:37:23 1:     UPnP::ControlPoint::_getDeviceFromHeaders called by FHEM/lib/UPnP/ControlPoint.pm (524)
2018.07.29 18:37:23 1:     UPnP::ControlPoint::_receiveSSDPEvent called by FHEM/lib/UPnP/ControlPoint.pm (225)
2018.07.29 18:37:23 1:     UPnP::ControlPoint::handleOnce      called by /opt/fhem/FHEM/98_DLNARenderer.pm (249)
2018.07.29 18:37:23 1:     (eval)                              called by /opt/fhem/FHEM/98_DLNARenderer.pm (248)
2018.07.29 18:37:23 1:     main::DLNARenderer_Read             called by fhem.pl (3584)
2018.07.29 18:37:23 1:     main::CallFn                        called by fhem.pl (723)
2018.07.29 18:37:23 1: PERL WARNING: Loading device description failed with error: 400 URL must be absolute (Location: 192.168.178.46) at /opt/fhem/FHEM/98_DLNARenderer.pm line 249.
2018.07.29 18:37:23 1: stacktrace:
2018.07.29 18:37:23 1:     main::__ANON__                      called by /usr/local/share/perl/5.20.2/Carp.pm (169)
2018.07.29 18:37:23 1:     Carp::carp                          called by FHEM/lib/UPnP/ControlPoint.pm (369)
2018.07.29 18:37:23 1:     UPnP::ControlPoint::_createDevice   called by FHEM/lib/UPnP/ControlPoint.pm (400)
2018.07.29 18:37:23 1:     UPnP::ControlPoint::_getDeviceFromHeaders called by FHEM/lib/UPnP/ControlPoint.pm (524)
2018.07.29 18:37:23 1:     UPnP::ControlPoint::_receiveSSDPEvent called by FHEM/lib/UPnP/ControlPoint.pm (225)
2018.07.29 18:37:23 1:     UPnP::ControlPoint::handleOnce      called by /opt/fhem/FHEM/98_DLNARenderer.pm (249)
2018.07.29 18:37:23 1:     (eval)                              called by /opt/fhem/FHEM/98_DLNARenderer.pm (248)
2018.07.29 18:37:23 1:     main::DLNARenderer_Read             called by fhem.pl (3584)
2018.07.29 18:37:23 1:     main::CallFn                        called by fhem.pl (723)
2018.07.29 18:37:23 1: PERL WARNING: 400-URL-Absolute-Error! Location: "192.168.178.46", Content: "400 URL must be absolute
" at /opt/fhem/FHEM/98_DLNARenderer.pm line 249.
2018.07.29 18:37:23 1: stacktrace:
2018.07.29 18:37:23 1:     main::__ANON__                      called by /usr/local/share/perl/5.20.2/Carp.pm (169)
2018.07.29 18:37:23 1:     Carp::carp                          called by FHEM/lib/UPnP/ControlPoint.pm (368)
2018.07.29 18:37:23 1:     UPnP::ControlPoint::_createDevice   called by FHEM/lib/UPnP/ControlPoint.pm (400)
2018.07.29 18:37:23 1:     UPnP::ControlPoint::_getDeviceFromHeaders called by FHEM/lib/UPnP/ControlPoint.pm (524)
2018.07.29 18:37:23 1:     UPnP::ControlPoint::_receiveSSDPEvent called by FHEM/lib/UPnP/ControlPoint.pm (225)
2018.07.29 18:37:23 1:     UPnP::ControlPoint::handleOnce      called by /opt/fhem/FHEM/98_DLNARenderer.pm (249)
2018.07.29 18:37:23 1:     (eval)                              called by /opt/fhem/FHEM/98_DLNARenderer.pm (248)
2018.07.29 18:37:23 1:     main::DLNARenderer_Read             called by fhem.pl (3584)
2018.07.29 18:37:23 1:     main::CallFn                        called by fhem.pl (723)
2018.07.29 18:37:23 1: PERL WARNING: Loading device description failed with error: 400 URL must be absolute (Location: 192.168.178.46) at /opt/fhem/FHEM/98_DLNARenderer.pm line 249.
2018.07.29 18:37:23 1: stacktrace:
2018.07.29 18:37:23 1:     main::__ANON__                      called by /usr/local/share/perl/5.20.2/Carp.pm (169)
2018.07.29 18:37:23 1:     Carp::carp                          called by FHEM/lib/UPnP/ControlPoint.pm (369)
2018.07.29 18:37:23 1:     UPnP::ControlPoint::_createDevice   called by FHEM/lib/UPnP/ControlPoint.pm (400)
2018.07.29 18:37:23 1:     UPnP::ControlPoint::_getDeviceFromHeaders called by FHEM/lib/UPnP/ControlPoint.pm (524)
2018.07.29 18:37:23 1:     UPnP::ControlPoint::_receiveSSDPEvent called by FHEM/lib/UPnP/ControlPoint.pm (225)
2018.07.29 18:37:23 1:     UPnP::ControlPoint::handleOnce      called by /opt/fhem/FHEM/98_DLNARenderer.pm (249)
2018.07.29 18:37:23 1:     (eval)                              called by /opt/fhem/FHEM/98_DLNARenderer.pm (248)
2018.07.29 18:37:23 1:     main::DLNARenderer_Read             called by fhem.pl (3584)
2018.07.29 18:37:23 1:     main::CallFn                        called by fhem.pl (723)
ping: icmp open socket: Operation not permitted


Die alten Fehlermeldungen sind durch das löschen der monitoring devices verschwunden.
Fehlermeldungen die jetzt noch im Log sind, weisen noch auf die Geräte hin die aufgrund von Wasserschaden aktuell noch außer Betrieb sind.

[rudolfkoenig]

Bitte diese Diskussion nicht hier weiterfuehren, es hat doch nichts mit dem Betreff zu tun.
Und: bitte ein "attr global verbose 5" Log-Mitschnitt nach dem Eintreffen des entsprechenden Events im anderen Thread anhaengen.