philips hue wirft fehler ins log

[the ratman]

grüß euch.

seit ein paar tagen hab ich im log immer wieder fologende zeilen stehen:

Code Auswählen Erweitern

2024.04.04 17:13:55 1: PERL WARNING: Use of uninitialized value in split at ./FHEM/30_HUEBridge.pm line 2596.
2024.04.04 17:13:55 1: stacktrace:
2024.04.04 17:13:55 1:     main::__ANON__                      called by ./FHEM/30_HUEBridge.pm (2596)
2024.04.04 17:13:55 1:     main::HUEBridge_dispatch            called by FHEM/HttpUtils.pm (854)
2024.04.04 17:13:55 1:     main::HttpUtils_DataComplete        called by FHEM/HttpUtils.pm (746)
2024.04.04 17:13:55 1:     main::__ANON__                      called by fhem.pl (783)das kommt dann einige zeit im 5-minuten-takt im log an und hört dann auch einfach wieder auf.
zusammenhänge hab ich da noch keine entdeckt.
ich merke auch nicht, dass ein hue-gerät nicht ginge, oder die meldung nach einem schaltvorgang auftauchen würde.
wo muß ich den da zum suchen anfangen?

[the ratman]

neuigkeiten: jetzt kam einmal dieser ganze sermon. anschließend gings wieder weiter mit den 5min logeinträgen.

Code Auswählen Erweitern

2024.04.05 15:42:50 1: PERL WARNING: Use of uninitialized value in split at ./FHEM/30_HUEBridge.pm line 1632.
2024.04.05 15:42:50 1: stacktrace:
2024.04.05 15:42:50 1:     main::__ANON__                      called by ./FHEM/30_HUEBridge.pm (1632)
2024.04.05 15:42:50 1:     main::HUEBridge_Get                 called by fhem.pl (3985)
2024.04.05 15:42:50 1:     main::CallFn                        called by fhem.pl (2035)
2024.04.05 15:42:50 1:     main::CommandGet                    called by fhem.pl (1282)
2024.04.05 15:42:50 1:     main::AnalyzeCommand                called by ./FHEM/01_FHEMWEB.pm (2866)
2024.04.05 15:42:50 1:     main::FW_fC                         called by ./FHEM/01_FHEMWEB.pm (986)
2024.04.05 15:42:50 1:     main::FW_answerCall                 called by ./FHEM/01_FHEMWEB.pm (609)
2024.04.05 15:42:50 1:     main::FW_Read                       called by fhem.pl (3985)
2024.04.05 15:42:50 1:     main::CallFn                        called by fhem.pl (786)
2024.04.05 15:42:50 1: PERL WARNING: Use of uninitialized value $id in concatenation (.) or string at ./FHEM/30_HUEBridge.pm line 1634.
2024.04.05 15:42:50 1: stacktrace:
2024.04.05 15:42:50 1:     main::__ANON__                      called by ./FHEM/30_HUEBridge.pm (1634)
2024.04.05 15:42:50 1:     main::HUEBridge_Get                 called by fhem.pl (3985)
2024.04.05 15:42:50 1:     main::CallFn                        called by fhem.pl (2035)
2024.04.05 15:42:50 1:     main::CommandGet                    called by fhem.pl (1282)
2024.04.05 15:42:50 1:     main::AnalyzeCommand                called by ./FHEM/01_FHEMWEB.pm (2866)
2024.04.05 15:42:50 1:     main::FW_fC                         called by ./FHEM/01_FHEMWEB.pm (986)
2024.04.05 15:42:50 1:     main::FW_answerCall                 called by ./FHEM/01_FHEMWEB.pm (609)
2024.04.05 15:42:50 1:     main::FW_Read                       called by fhem.pl (3985)
2024.04.05 15:42:50 1:     main::CallFn                        called by fhem.pl (786)
2024.04.05 15:42:50 1: PERL WARNING: Use of uninitialized value $id in sprintf at ./FHEM/30_HUEBridge.pm line 1637.
2024.04.05 15:42:50 1: stacktrace:
2024.04.05 15:42:50 1:     main::__ANON__                      called by ./FHEM/30_HUEBridge.pm (1637)
2024.04.05 15:42:50 1:     main::HUEBridge_Get                 called by fhem.pl (3985)
2024.04.05 15:42:50 1:     main::CallFn                        called by fhem.pl (2035)
2024.04.05 15:42:50 1:     main::CommandGet                    called by fhem.pl (1282)
2024.04.05 15:42:50 1:     main::AnalyzeCommand                called by ./FHEM/01_FHEMWEB.pm (2866)
2024.04.05 15:42:50 1:     main::FW_fC                         called by ./FHEM/01_FHEMWEB.pm (986)
2024.04.05 15:42:50 1:     main::FW_answerCall                 called by ./FHEM/01_FHEMWEB.pm (609)
2024.04.05 15:42:50 1:     main::FW_Read                       called by fhem.pl (3985)
2024.04.05 15:42:50 1:     main::CallFn                        called by fhem.pl (786)
2024.04.05 15:43:06 1: PERL WARNING: Use of uninitialized value in split at ./FHEM/30_HUEBridge.pm line 2596.
2024.04.05 15:43:06 1: stacktrace:
2024.04.05 15:43:06 1:     main::__ANON__                      called by ./FHEM/30_HUEBridge.pm (2596)
2024.04.05 15:43:06 1:     main::HUEBridge_dispatch            called by FHEM/HttpUtils.pm (854)
2024.04.05 15:43:06 1:     main::HttpUtils_DataComplete        called by FHEM/HttpUtils.pm (746)
2024.04.05 15:43:06 1:     main::__ANON__                      called by fhem.pl (783)

aus welchen gründen auch immer, ich darf die def der bridge hier nicht reinschreiben, falls die also benötigt wird, müssen wir uns was einfallen lassen ...

Code Auswählen Erweitern

Bitte versuche es nochmal. Sollte der Fehler wieder auftreten, informiere bitte den Administrator.

[the ratman]

neues von der front.

ich hab mittlerweile 7 mal pro minute die meldung vom 1. beitrag im log.
drum hab ich eben testweise eine readingsgroup, die ich schon seit jahren am laufen habe, disabled. in der werden die batteriezustände von derzeit 6 geräten angezeigt. eines davon ist derzeit batterielos, ein weiteres zeigt ewig schon 1% ladung an, funzt aber.
(des weiteren hab ich einen osram-steckdosenschalter offline. aber der hat ja keine batterie.)
sofort wurden aus den 7 logeinträgen 2 ... derzeit kommt seit minuten sogar gar nix mehr im log.

der def-teil für hue schaut so aus:

Code Auswählen Erweitern

type=ZLLSwitch:battery,reachable
type=ZLLPresence:battery,reachabledas rennt seit jahren so.

jetzt stellt sich natürlich die frage: warum und auch: wo kommt der rest her?
hilfe erbeten!

[the ratman]

niemand 'ne idee?

[JoWiemann]

niemand 'ne idee?

Schon mal den Maintainer: justme1968 angefragt?

Grüße Jörg

[the ratman]

ich dachte @justme1968 liest hier mit? bin ich wieder im falschen unterforum?

aber auf jeden fall danke für deine reaktion! dachte schon, ich werd' hier total ignioriert *g*
wobei ich mich langsam eh frag, obs nicht am httpmod liegt? der hätte ja wenigstens in den letzten woche irgendein update bekommen ...

[justme1968]

zeig mal ein list der bridge und das log mit verbose 5 auf dem bridge device.

wozu hast du das v2devices kommando benutzt?

[the ratman]

danke dir fürs drüber schauen!

wozu hast du das v2devices kommando benutzt?

öhm, keine ahnung? ich hab an dem ganzen zeugs seit locker 2020 nix mehr geändert.

ach du schande - verbose 5 müllt mir das log zu.

kannst du mir sagen, was ich genau machen muss, damit du kriegst, was du brauchst?

list kommt
ansonsten kann ich nur sagen: es funzt alles - ich würde nicht mal (gefühlte) verzögerungen merken - nur eben die logeinträge nerven.

[the ratman]

Du darfst diesen Dateianhang nicht ansehen.

[the ratman]

meinst du mit dem log sowas?

Code Auswählen Erweitern

2024.04.09 09:56:35 5: Starting notify loop for global, 1 event(s), first is ATTR global verbose 5
2024.04.09 09:56:35 5: statistics stat_regen: Notify.285 Notification of 'global' received. Device not monitored.
2024.04.09 09:56:35 4: logdb - check Device: global , Event: ATTR global verbose 5
2024.04.09 09:56:35 5: AptToDate (up2date_fhem) - Notify: $VAR1 = [
          'ATTR global verbose 5'
        ];

2024.04.09 09:56:35 5: End notify loop for global
2024.04.09 09:56:35 4: WEB: /fhem?cmd.attrglobal%3Dattr%20global%20verbose%205&XHR=1&fw_id=1712649389.22875 / RL:20 / text/plain; charset=UTF-8 / Content-Encoding: gzip
 / Cache-Control: no-cache, no-store, must-revalidate

2024.04.09 09:56:35 4: using HttpUtils_NonblockingGet: GET sensors/23
2024.04.09 09:56:35 5: HttpUtils url=http://192.168.178.11/api/bJSrVLT2vxwPBeO8ApudFC1f4xKfKCngjY-KFHw8/sensors/23 NonBlocking via http
2024.04.09 09:56:35 4: IP: 192.168.178.11 -> 192.168.178.11
2024.04.09 09:56:35 4: using HttpUtils_NonblockingGet: GET sensors/56
2024.04.09 09:56:35 5: HttpUtils url=http://192.168.178.11/api/bJSrVLT2vxwPBeO8ApudFC1f4xKfKCngjY-KFHw8/sensors/56 NonBlocking via http
2024.04.09 09:56:35 4: IP: 192.168.178.11 -> 192.168.178.11
2024.04.09 09:56:35 5: HttpUtils request header:
GET /api/bJSrVLT2vxwPBeO8ApudFC1f4xKfKCngjY-KFHw8/sensors/23 HTTP/1.0
Host: 192.168.178.11
User-Agent: fhem
Accept-Encoding: gzip,deflate
Content-Type: application/json

2024.04.09 09:56:35 5: HttpUtils request header:
GET /api/bJSrVLT2vxwPBeO8ApudFC1f4xKfKCngjY-KFHw8/sensors/56 HTTP/1.0
Host: 192.168.178.11
User-Agent: fhem
Accept-Encoding: gzip,deflate
Content-Type: application/json

2024.04.09 09:56:35 4: http://192.168.178.11/api/bJSrVLT2vxwPBeO8ApudFC1f4xKfKCngjY-KFHw8/sensors/23: HTTP response code 200
2024.04.09 09:56:35 5: HttpUtils http://192.168.178.11/api/bJSrVLT2vxwPBeO8ApudFC1f4xKfKCngjY-KFHw8/sensors/23: Got data, length: 781
2024.04.09 09:56:35 5: HttpUtils response header:
HTTP/1.1 200 OK
Server: nginx
Date: Tue, 09 Apr 2024 07:56:35 GMT
Content-Type: application/json
Connection: close
Cache-Control: no-store, no-cache, must-revalidate, post-check=0, pre-check=0
Pragma: no-cache
Expires: Mon, 1 Aug 2011 09:00:00 GMT
Access-Control-Max-Age: 3600
Access-Control-Allow-Origin: *
Access-Control-Allow-Credentials: true
Access-Control-Allow-Methods: POST, GET, OPTIONS, PUT, DELETE, HEAD
Access-Control-Allow-Headers: Content-Type
X-XSS-Protection: 1; mode=block
X-Frame-Options: SAMEORIGIN
X-Content-Type-Options: nosniff
Content-Security-Policy: default-src 'self'
Cache-Control: no-store
Pragma: no-cache
Referrer-Policy: no-referrer
2024.04.09 09:56:35 4: huebridge: dispatch: http://192.168.178.11/api/bJSrVLT2vxwPBeO8ApudFC1f4xKfKCngjY-KFHw8/sensors/23
2024.04.09 09:56:35 5: HUEBridge_dispatch: sensors/23
2024.04.09 09:56:35 4: parse status message for button_sz_rollo_schwenk
2024.04.09 09:56:35 5: GET /fhem?detail=global&fw_id=1712649389.22875 HTTP/1.1
Host: 192.168.178.8:8083
User-Agent: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0
Accept: text/html,application/xhtml+xml,application/xml;q=0.9,image/avif,image/webp,*/*;q=0.8
Accept-Language: de,en-US;q=0.7,en;q=0.3
Accept-Encoding: gzip, deflate
Connection: keep-alive
Referer: http://192.168.178.8:8083/fhem?detail=global
Upgrade-Insecure-Requests: 1
2024.04.09 09:56:35 4: WEB_192.168.178.21_12805 GET /fhem?detail=global&fw_id=1712649389.22875; BUFLEN:0
2024.04.09 09:56:35 4: WEB: /fhem?detail=global&fw_id=1712649389.22875 / RL:6759 / text/html; charset=UTF-8 / Content-Encoding: gzip
 / Cache-Control: no-cache, no-store, must-revalidate

2024.04.09 09:56:35 4: http://192.168.178.11/api/bJSrVLT2vxwPBeO8ApudFC1f4xKfKCngjY-KFHw8/sensors/56: HTTP response code 200
2024.04.09 09:56:35 5: HttpUtils http://192.168.178.11/api/bJSrVLT2vxwPBeO8ApudFC1f4xKfKCngjY-KFHw8/sensors/56: Got data, length: 602
2024.04.09 09:56:35 5: HttpUtils response header:
HTTP/1.1 200 OK
Server: nginx
Date: Tue, 09 Apr 2024 07:56:35 GMT
Content-Type: application/json
Connection: close
Cache-Control: no-store, no-cache, must-revalidate, post-check=0, pre-check=0
Pragma: no-cache
Expires: Mon, 1 Aug 2011 09:00:00 GMT
Access-Control-Max-Age: 3600
Access-Control-Allow-Origin: *
Access-Control-Allow-Credentials: true
Access-Control-Allow-Methods: POST, GET, OPTIONS, PUT, DELETE, HEAD
Access-Control-Allow-Headers: Content-Type
X-XSS-Protection: 1; mode=block
X-Frame-Options: SAMEORIGIN
X-Content-Type-Options: nosniff
Content-Security-Policy: default-src 'self'
Cache-Control: no-store
Pragma: no-cache
Referrer-Policy: no-referrer
2024.04.09 09:56:35 4: huebridge: dispatch: http://192.168.178.11/api/bJSrVLT2vxwPBeO8ApudFC1f4xKfKCngjY-KFHw8/sensors/56
2024.04.09 09:56:35 5: HUEBridge_dispatch: sensors/56
2024.04.09 09:56:35 4: parse status message for gang_gz_lux
2024.04.09 09:56:35 5: gang_gz_lux: using offsetUTC 7200 from bridge
2024.04.09 09:56:35 4: gang_gz_lux: ignoring reading battery with timestamp 2024-04-09 09:52:45, current reading timestamp is 2024-04-09 09:52:45
2024.04.09 09:56:35 4: gang_gz_lux: ignoring reading state with timestamp 2024-04-09 09:52:45, current reading timestamp is 2024-04-09 09:52:45
2024.04.09 09:56:35 4: gang_gz_lux: ignoring reading daylight with timestamp 2024-04-09 09:52:45, current reading timestamp is 2024-04-09 09:52:45
2024.04.09 09:56:35 4: gang_gz_lux: ignoring reading lightlevel with timestamp 2024-04-09 09:52:45, current reading timestamp is 2024-04-09 09:52:45
2024.04.09 09:56:35 4: gang_gz_lux: ignoring reading reachable with timestamp 2024-04-09 09:52:45, current reading timestamp is 2024-04-09 09:52:45
2024.04.09 09:56:35 4: gang_gz_lux: ignoring reading batteryPercent with timestamp 2024-04-09 09:52:45, current reading timestamp is 2024-04-09 09:52:45
2024.04.09 09:56:35 4: gang_gz_lux: ignoring reading dark with timestamp 2024-04-09 09:52:45, current reading timestamp is 2024-04-09 09:52:45
2024.04.09 09:56:35 5: GET /fhem?cmd=%7BAttrVal(%22global%22%2C%22room%22%2C%22%22)%7D&XHR=1 HTTP/1.1
Host: 192.168.178.8:8083
User-Agent: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0
Accept: */*
Accept-Language: de,en-US;q=0.7,en;q=0.3
Accept-Encoding: gzip, deflate
Connection: keep-alive
Referer: http://192.168.178.8:8083/fhem?detail=global&fw_id=1712649389.22875
2024.04.09 09:56:35 4: WEB_192.168.178.21_12805 GET /fhem?cmd=%7BAttrVal(%22global%22%2C%22room%22%2C%22%22)%7D&XHR=1; BUFLEN:0
2024.04.09 09:56:35 5: Cmd: >{AttrVal("global","room","")}<
2024.04.09 09:56:35 4: WEB: /fhem?cmd=%7BAttrVal(%22global%22%2C%22room%22%2C%22%22)%7D&XHR=1 / RL:25 / text/plain; charset=UTF-8 / Content-Encoding: gzip
 / Cache-Control: no-cache, no-store, must-revalidate

2024.04.09 09:56:35 4: Connection accepted from WEB_192.168.178.21_12907
2024.04.09 09:56:35 5: GET /fhem?XHR=1&inform=type%3Dstatus%3Bfilter%3Dglobal%3Bsince%3D1712649394.32727%3Bfmt%3DJSON&fw_id=1712649389.22875×tamp=1712649395478 HTTP/1.1
Host: 192.168.178.8:8083
User-Agent: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0
Accept: */*
Accept-Language: de,en-US;q=0.7,en;q=0.3
Accept-Encoding: gzip, deflate
Sec-WebSocket-Version: 13
Origin: http://192.168.178.8:8083
Sec-WebSocket-Extensions: permessage-deflate
Sec-WebSocket-Key: Zrad6HPWifL3tbrP5VH1HQ==
Connection: keep-alive, Upgrade
Pragma: no-cache
Cache-Control: no-cache
Upgrade: websocket
2024.04.09 09:56:35 4: Connection accepted from WEB_192.168.178.21_12908
2024.04.09 09:56:35 5: GET /fhem?cmd={ReadingsVal(%22HUEDevice13%22,%22onoff%22,0)}&XHR=1 HTTP/1.1
User-Agent: Rainmeter WebParser plugin
Host: 192.168.178.8:8083
2024.04.09 09:56:35 4: WEB_192.168.178.21_12908 GET /fhem?cmd={ReadingsVal(%22HUEDevice13%22,%22onoff%22,0)}&XHR=1; BUFLEN:0
2024.04.09 09:56:35 5: Cmd: >{ReadingsVal("HUEDevice13","onoff",0)}<
2024.04.09 09:56:35 4: WEB: /fhem?cmd={ReadingsVal(%22HUEDevice13%22,%22onoff%22,0)}&XHR=1 / RL:2 / text/plain; charset=UTF-8 /  / Cache-Control: no-cache, no-store, must-revalidate

2024.04.09 09:56:36 4: using HttpUtils_NonblockingGet: GET sensors/23
2024.04.09 09:56:36 5: HttpUtils url=http://192.168.178.11/api/bJSrVLT2vxwPBeO8ApudFC1f4xKfKCngjY-KFHw8/sensors/23 NonBlocking via http
2024.04.09 09:56:36 4: IP: 192.168.178.11 -> 192.168.178.11
2024.04.09 09:56:36 4: using HttpUtils_NonblockingGet: GET sensors/56
2024.04.09 09:56:36 5: HttpUtils url=http://192.168.178.11/api/bJSrVLT2vxwPBeO8ApudFC1f4xKfKCngjY-KFHw8/sensors/56 NonBlocking via http
2024.04.09 09:56:36 4: IP: 192.168.178.11 -> 192.168.178.11
2024.04.09 09:56:36 5: HttpUtils request header:
GET /api/bJSrVLT2vxwPBeO8ApudFC1f4xKfKCngjY-KFHw8/sensors/23 HTTP/1.0
Host: 192.168.178.11
User-Agent: fhem
Accept-Encoding: gzip,deflate
Content-Type: application/json

2024.04.09 09:56:36 5: HttpUtils request header:
GET /api/bJSrVLT2vxwPBeO8ApudFC1f4xKfKCngjY-KFHw8/sensors/56 HTTP/1.0
Host: 192.168.178.11
User-Agent: fhem
Accept-Encoding: gzip,deflate
Content-Type: application/json

[justme1968]

schon besser. ich schaue mal was ich daraus erkennen kann.

noch besser wäre ein log mit den bridge meldungen und einem der fehler.

zeig mal bitte was ein

Code Auswählen Erweitern

get <bridge> v2resource ausgibt.

ps: den Kommentar zum 'zunmüllen' verkneife ich mir jetzt. das log hat ja schließlich sinn.

[the ratman]

Code Auswählen Erweitern

get huebridge v2resourceals anhang Du darfst diesen Dateianhang nicht ansehen.

[the ratman]

kann mir den da keiner helfen?

damit mein log nicht übergeht, hab ich in global derzeit verbose auf 0, stelle das immer auf 1, um täglich neues upzudaten.
das geht auf dauer sicher nicht gut, weil ich ja anderes wichtiges übersehen könnte.

wie könnte ich den wenigstens nur die hue-sachen aus dem log rausnehmen, damit ich wenigstens den globalen verbose wieder verwenden kann?

[juemuc]

Warum setzt Du nicht beim HUE-Device nicht verbose 0?

Viele Grüße
Jürgen

PS.: ich habe mit HUE keine Probleme

[the ratman]

ich hatte bis dahin mit hue auch 0 probleme.
alles bei hue verbose 0 ... hatte ich schon mal probiert. derzeit ist alles von der bridge, bis zur letzten lampe auf verbose 0.
kurz hatte ich hoffnung wegen einer scheinbar defekten osram steckdose. die hab ich dann abgesteckt.

aber, nach ca. 15 min gings wieder los (weiterhin alles auf verbose 0) ...

Code Auswählen Erweitern

2024.07.29 15:13:48 1: PERL WARNING: Use of uninitialized value in split at ./FHEM/30_HUEBridge.pm line 2596.
2024.07.29 15:13:48 1: stacktrace:
2024.07.29 15:13:48 1:     main::__ANON__                      called by ./FHEM/30_HUEBridge.pm (2596)
2024.07.29 15:13:48 1:     main::HUEBridge_dispatch            called by FHEM/HttpUtils.pm (854)
2024.07.29 15:13:48 1:     main::HttpUtils_DataComplete        called by FHEM/HttpUtils.pm (746)
2024.07.29 15:13:48 1:     main::__ANON__                      called by fhem.pl (783)

[gramtoc]

Hi, hab das gleiche Problem. Gibt es mittlerweilen einen Lösungsansatz?

Gruß

[the ratman]

bei mir leider nicht ...

[the ratman]

neues ergebnis "notbehelf" (funzt ... zumindest nach 10 min. testen) --> https://forum.fhem.de/index.php?topic=139144.msg1319772
derweil das eintragen von "30_HUEBridge.pm" in "exclude from update" in "global" nicht vergessen, sonst wirds nervig *g*

im anhang die modifizierte version der huebridge ...