NEWS
Fehler: hm-rega.0... Script ignored, because still pending.
-
@foxriver76 Version 2.5.4 - auch ein vorrübergehender downgrade auf 2.5.3 brachte keine Verbesserung...
-
@pietro hast du zufällig ein log von Adapterstart bis zum Auftreten der Fehlermeldung? Bzw wäre es interessant ob davor ein anderer Fehler auftritt.
-
@foxriver76 Also wenn ich den Adapter abdrehe bekomme ich keine Fehlermeldungen mehr.
Sobald ich ihn aufdrehe sieht dies wie folgt aus (log level warn):hm-rega.0 2020-02-17 09:24:44.783 warn (5955) Script ignored, because still pending.
hm-rega.0 2020-02-17 09:24:43.987 warn (5955) Script ignored, because still pending.
hm-rega.0 2020-02-17 09:24:43.986 warn (5955) Script ignored, because still pending.
hm-rega.0 2020-02-17 09:24:41.403 warn (5955) Script ignored, because still pending.
hm-rega.0 2020-02-17 09:24:41.402 warn (5955) Script ignored, because still pending.
hm-rega.0 2020-02-17 09:24:40.188 warn (5955) Script ignored, because still pending.
hm-rega.0 2020-02-17 09:24:40.188 warn (5955) Script ignored, because still pending.
hm-rega.0 2020-02-17 09:24:40.187 warn (5955) Script ignored, because still pending.
hm-rega.0 2020-02-17 09:24:40.187 warn (5955) Script ignored, because still pending.
hm-rega.0 2020-02-17 09:24:37.099 warn (5955) Got unexpected ID: hm-rega.0.48757
hm-rega.0 2020-02-17 09:24:37.099 warn (5955) Got unexpected ID: hm-rega.0.48757
hm-rega.0 2020-02-17 09:24:37.098 warn (5955) Got unexpected ID: hm-rega.0.48757 -
hier das komplette debug log nach dem Starten des Adapters:
hm-rega.0 2020-02-17 09:34:53.711 warn (18402) Script ignored, because still pending. hm-rega.0 2020-02-17 09:34:51.015 warn (18402) Script ignored, because still pending. hm-rega.0 2020-02-17 09:34:51.014 warn (18402) Script ignored, because still pending. hm-rega.0 2020-02-17 09:34:50.292 warn (18402) Script ignored, because still pending. hm-rega.0 2020-02-17 09:34:50.292 warn (18402) Script ignored, because still pending. hm-rega.0 2020-02-17 09:34:46.954 info (18402) got state values hm-rega.0 2020-02-17 09:34:46.682 info (18402) request state values hm-rega.0 2020-02-17 09:34:46.681 info (18402) added/updated 2 objects hm-rega.0 2020-02-17 09:34:46.554 info (18402) deleted 0 variables hm-rega.0 2020-02-17 09:34:46.554 info (18402) added/updated 66 variables hm-rega.0 2020-02-17 09:34:46.245 info (18402) got 66 variables hm-rega.0 2020-02-17 09:34:46.143 info (18402) deleted 0 programs hm-rega.0 2020-02-17 09:34:46.142 info (18402) added/updated 30 programs hm-rega.0 2020-02-17 09:34:46.112 info (18402) got 30 programs hm-rega.0 2020-02-17 09:34:45.784 info (18402) hm-rpc.2.000ED7.1 has been removed from room AU_Pool hm-rega.0 2020-02-17 09:34:45.784 info (18402) hm-rpc.2.000ED7.2 has been removed from room AU_Pool hm-rega.0 2020-02-17 09:34:45.783 info (18402) hm-rpc.0.GEQ02.1 has been removed from room AU_Pool hm-rega.0 2020-02-17 09:34:45.783 info (18402) hm-rpc.0.EEQ0049.1 has been removed from room AU_Pool hm-rega.0 2020-02-17 09:34:45.783 info (18402) hm-rpc.1.FEQ0045.18 has been removed from room AU_Pool hm-rega.0 2020-02-17 09:34:45.783 info (18402) hm-rpc.2.00199A4.8 has been added to room AU_Pool hm-rega.0 2020-02-17 09:34:45.783 info (18402) hm-rpc.2.00199A4.11 has been added to room AU_Pool hm-rega.0 2020-02-17 09:34:45.782 info (18402) hm-rpc.2.00199A4.10 has been added to room AU_Pool hm-rega.0 2020-02-17 09:34:45.782 info (18402) hm-rpc.2.00199A4.9 has been added to room AU_Pool hm-rega.0 2020-02-17 09:34:45.782 info (18402) hm-rpc.2.00199A4.6 has been added to room AU_Pool hm-rega.0 2020-02-17 09:34:45.777 info (18402) hm-rpc.1.GEQ0039.11 has been removed from room KG_TechnikR hm-rega.0 2020-02-17 09:34:45.777 info (18402) hm-rpc.1.GEQ0039.8 has been removed from room KG_TechnikR hm-rega.0 2020-02-17 09:34:45.777 info (18402) hm-rpc.1.FEQ0046.19 has been removed from room KG_TechnikR hm-rega.0 2020-02-17 09:34:45.776 info (18402) hm-rpc.2.001898A.1 has been added to room KG_TechnikR hm-rega.0 2020-02-17 09:34:45.776 info (18402) hm-rpc.1.MEQ072.3 has been added to room KG_TechnikR hm-rega.0 2020-02-17 09:34:45.776 info (18402) hm-rpc.1.MEQ072.4 has been added to room KG_TechnikR hm-rega.0 2020-02-17 09:34:45.776 info (18402) hm-rpc.0.GEQ020.2 has been removed from room AU_Garten hm-rega.0 2020-02-17 09:34:45.775 info (18402) hm-rpc.0.GEQ0203 has been removed from room AU_Garten hm-rega.0 2020-02-17 09:34:45.775 info (18402) hm-rpc.0.GEQ020.1 has been removed from room AU_Garten hm-rega.0 2020-02-17 09:34:45.775 info (18402) hm-rpc.0.OEQ059.2 has been added to room AU_Garten hm-rega.0 2020-02-17 09:34:45.775 info (18402) hm-rpc.0.OEQ059.1 has been added to room AU_Garten hm-rega.0 2020-02-17 09:34:45.775 info (18402) hm-rpc.1.JEQ012.26 has been added to room AU_Garten hm-rega.0 2020-02-17 09:34:45.762 info (18402) update rooms to enum.rooms hm-rega.0 2020-02-17 09:34:45.720 info (18402) hm-rpc.0.DEQ000.1 has been removed from functions Taster hm-rega.0 2020-02-17 09:34:45.720 info (18402) hm-rpc.0.DEQ000.2 has been removed from functions Taster hm-rega.0 2020-02-17 09:34:45.720 info (18402) hm-rpc.0.DEQ000.1 has been removed from functions Taster hm-rega.0 2020-02-17 09:34:45.719 info (18402) hm-rpc.0.DEQ000.2 has been removed from functions Taster hm-rega.0 2020-02-17 09:34:45.717 info (18402) hm-rpc.2.000997.4 has been added to functions Taster hm-rega.0 2020-02-17 09:34:45.717 info (18402) hm-rpc.2.000997.3 has been added to functions Taster hm-rega.0 2020-02-17 09:34:45.717 info (18402) hm-rpc.2.000997.1 has been added to functions Taster hm-rega.0 2020-02-17 09:34:45.716 info (18402) hm-rpc.2.000997.2 has been added to functions Taster hm-rega.0 2020-02-17 09:34:45.716 info (18402) hm-rpc.0.*GEQ00.1 has been removed from functions Sicherheit hm-rega.0 2020-02-17 09:34:45.715 info (18402) hm-rpc.0.DEQ000.1 has been removed from functions Sicherheit hm-rega.0 2020-02-17 09:34:45.715 info (18402) hm-rpc.0.DEQ000.2 has been removed from functions Sicherheit hm-rega.0 2020-02-17 09:34:45.715 info (18402) hm-rpc.0.DEQ000.1 has been removed from functions Sicherheit hm-rega.0 2020-02-17 09:34:45.715 info (18402) hm-rpc.0.DEQ000.2 has been removed from functions Sicherheit hm-rega.0 2020-02-17 09:34:45.714 info (18402) hm-rpc.2.00099709A.1 has been added to functions Sicherheit hm-rega.0 2020-02-17 09:34:45.714 info (18402) hm-rpc.2.00099709A.2 has been added to functions Sicherheit hm-rega.0 2020-02-17 09:34:45.714 info (18402) hm-rpc.2.00099709A.4 has been added to functions Sicherheit hm-rega.0 2020-02-17 09:34:45.714 info (18402) hm-rpc.2.00099709A.1 has been added to functions Sicherheit hm-rega.0 2020-02-17 09:34:45.714 info (18402) hm-rpc.2.00099709A.2 has been added to functions Sicherheit hm-rega.0 2020-02-17 09:34:45.713 info (18402) hm-rpc.2.000ED709B.1 has been removed from functions Umwelt hm-rega.0 2020-02-17 09:34:45.713 info (18402) hm-rpc.2.000ED709B.2 has been removed from functions Umwelt hm-rega.0 2020-02-17 09:34:45.713 info (18402) hm-rpc.1.FEQ004.19 has been removed from functions Umwelt hm-rega.0 2020-02-17 09:34:45.713 info (18402) hm-rpc.2.00199A498.11 has been added to functions Umwelt hm-rega.0 2020-02-17 09:34:45.712 info (18402) hm-rpc.2.00199A498.7 has been added to functions Umwelt hm-rega.0 2020-02-17 09:34:45.712 info (18402) hm-rpc.2.00199A498.3 has been added to functions Umwelt hm-rega.0 2020-02-17 09:34:45.710 info (18402) hm-rpc.0.GEQ020.2 has been removed from functions Licht hm-rega.0 2020-02-17 09:34:45.710 info (18402) hm-rpc.0.GEQ020.3 has been removed from functions Licht hm-rega.0 2020-02-17 09:34:45.710 info (18402) hm-rpc.0.GEQ020.1 has been removed from functions Licht hm-rega.0 2020-02-17 09:34:45.708 info (18402) hm-rpc.2.00099709.3 has been added to functions Licht hm-rega.0 2020-02-17 09:34:45.708 info (18402) hm-rpc.2.00099709.3 has been added to functions Licht hm-rega.0 2020-02-17 09:34:45.708 info (18402) hm-rpc.2.00099569.3 has been added to functions Licht hm-rega.0 2020-02-17 09:34:45.703 info (18402) update functions to enum.functions hm-rega.0 2020-02-17 09:34:45.642 info (18402) update favorites to enum.favorites hm-rega.0 2020-02-17 09:34:45.388 info (18402) time difference local-ccu 0s hm-rega.0 2020-02-17 09:34:45.364 info (18402) ReGaHSS 192.168.20.1 up hm-rega.0 2020-02-17 09:34:45.308 info (18402) subscribe hm-rpc.0.BidCoS-RF.50.PRESS_SHORT hm-rega.0 2020-02-17 09:34:45.250 info (18402) starting. Version 2.5.4 in /opt/iobroker/node_modules/iobroker.hm-rega, node: v10.19.0
-
@pietro Bitte mal Adapter auf debug stellen, muss man separat machen. Sprich im Admin, Tab Instanzen, oben Expertenmodus Button aktivieren, dann Spalte Logstufe klicken und Debug wählen.
-
@foxriver76
Bitte sehr:hm-rega.0 2020-02-17 10:57:53.562 debug (11450) <-- hm-rega.0 2020-02-17 10:57:53.556 debug (11450) --> dom.GetObject(40974).State(48.6) hm-rega.0 2020-02-17 10:57:53.506 debug (11450) <-- hm-rega.0 2020-02-17 10:57:53.501 debug (11450) --> dom.GetObject(48757).State(6.7) hm-rega.0 2020-02-17 10:57:53.457 warn (11450) Script ignored, because still pending. hm-rega.0 2020-02-17 10:57:53.457 debug (11450) Set state 40974: 48.6 hm-rega.0 2020-02-17 10:57:53.457 debug (11450) stateChange hm-rega.0.40974 {"val":48.6,"ack":false,"ts":1581933473451,"q":0,"from":"system.adapter.javascript.0","user":"system.user.admin","lc":1581933473451} hm-rega.0 2020-02-17 10:57:53.456 warn (11450) Script ignored, because still pending. hm-rega.0 2020-02-17 10:57:53.456 debug (11450) Set state 40974: 48.6 hm-rega.0 2020-02-17 10:57:53.456 debug (11450) stateChange hm-rega.0.40974 {"val":48.6,"ack":false,"ts":1581933473451,"q":0,"from":"system.adapter.javascript.0","user":"system.user.admin","lc":1581933473451} hm-rega.0 2020-02-17 10:57:53.455 debug (11450) 2 pending requests hm-rega.0 2020-02-17 10:57:53.454 debug (11450) Set state 40974: 48.6 hm-rega.0 2020-02-17 10:57:53.454 debug (11450) stateChange hm-rega.0.40974 {"val":48.6,"ack":false,"ts":1581933473449,"q":0,"from":"system.adapter.javascript.0","user":"system.user.admin","lc":1581933473449} hm-rega.0 2020-02-17 10:57:53.454 warn (11450) Script ignored, because still pending. hm-rega.0 2020-02-17 10:57:53.454 debug (11450) Set state 48757: 6.7 hm-rega.0 2020-02-17 10:57:53.454 debug (11450) stateChange hm-rega.0.48757 {"val":6.7,"ack":false,"ts":1581933473448,"q":0,"from":"system.adapter.javascript.0","user":"system.user.admin","lc":1581933473448} hm-rega.0 2020-02-17 10:57:53.453 warn (11450) Script ignored, because still pending. hm-rega.0 2020-02-17 10:57:53.452 debug (11450) Set state 48757: 6.7 hm-rega.0 2020-02-17 10:57:53.452 debug (11450) stateChange hm-rega.0.48757 {"val":6.7,"ack":false,"ts":1581933473448,"q":0,"from":"system.adapter.javascript.0","user":"system.user.admin","lc":1581933473448} hm-rega.0 2020-02-17 10:57:53.451 debug (11450) <-- hm-rega.0 2020-02-17 10:57:53.450 debug (11450) 2 pending requests hm-rega.0 2020-02-17 10:57:53.450 debug (11450) Set state 48757: 6.7 hm-rega.0 2020-02-17 10:57:53.450 debug (11450) stateChange hm-rega.0.48757 {"val":6.7,"ack":false,"ts":1581933473448,"q":0,"from":"system.adapter.javascript.0","user":"system.user.admin","lc":1581933473448} hm-rega.0 2020-02-17 10:57:53.447 warn (11450) Script ignored, because still pending. hm-rega.0 2020-02-17 10:57:53.447 debug (11450) Set state 40974: 48.5 hm-rega.0 2020-02-17 10:57:53.446 debug (11450) stateChange hm-rega.0.40974 {"val":48.5,"ack":false,"ts":1581933473442,"q":0,"from":"system.adapter.javascript.0","user":"system.user.admin","lc":1581933473442} hm-rega.0 2020-02-17 10:57:53.445 warn (11450) Script ignored, because still pending. hm-rega.0 2020-02-17 10:57:53.445 debug (11450) Set state 40974: 48.5 hm-rega.0 2020-02-17 10:57:53.445 debug (11450) stateChange hm-rega.0.40974 {"val":48.5,"ack":false,"ts":1581933473442,"q":0,"from":"system.adapter.javascript.0","user":"system.user.admin","lc":1581933473442} hm-rega.0 2020-02-17 10:57:53.444 debug (11450) --> dom.GetObject(40974).State(48.5) hm-rega.0 2020-02-17 10:57:53.444 debug (11450) Set state 40974: 48.5 hm-rega.0 2020-02-17 10:57:53.443 debug (11450) stateChange hm-rega.0.40974 {"val":48.5,"ack":false,"ts":1581933473442,"q":0,"from":"system.adapter.javascript.0","user":"system.user.admin","lc":1581933473442} hm-rpc.0 2020-02-17 10:57:52.990 info (14028) Connected hm-rpc.0 2020-02-17 10:57:52.663 info (14028) xmlrpc -> 511 devices hm-rpc.0 2020-02-17 10:57:52.455 info (14028) xmlrpc <- listDevices ["hm-rpc.0"] hm-rpc.0 2020-02-17 10:57:52.445 info (14028) xmlrpc <- system.listMethods ["hm-rpc.0"] hm-rpc.0 2020-02-17 10:57:52.401 info (14028) xmlrpc client is trying to connect to 192.168.20.1:2001/ with ["http://192.168.15.115:2001","hm-rpc.0"] hm-rpc.0 2020-02-17 10:57:52.401 info (14028) xmlrpc server is trying to listen on 192.168.15.115:2001 hm-rpc.0 2020-02-17 10:57:51.643 info (14028) starting. Version 1.12.6 in /opt/iobroker/node_modules/iobroker.hm-rpc, node: v10.19.0 influxdb.0 2020-02-17 10:57:51.471 info (15011) Store 88 buffered influxDB history points host.pietro-hc2 2020-02-17 10:57:49.467 info instance system.adapter.hm-rpc.0 started with pid 14028 hm-rega.0 2020-02-17 10:57:48.845 debug (11450) <-- hm-rega.0 2020-02-17 10:57:48.838 debug (11450) --> dom.GetObject(40974).State(48.4) hm-rega.0 2020-02-17 10:57:48.838 debug (11450) Set state 40974: 48.4 hm-rega.0 2020-02-17 10:57:48.838 debug (11450) stateChange hm-rega.0.40974 {"val":48.4,"ack":false,"ts":1581933468829,"q":0,"from":"system.adapter.javascript.0","user":"system.user.admin","lc":1581933468829} hm-rega.0 2020-02-17 10:57:48.827 debug (11450) <-- hm-rega.0 2020-02-17 10:57:48.823 warn (11450) Script ignored, because still pending. hm-rega.0 2020-02-17 10:57:48.823 debug (11450) Set state 40974: 48.4 hm-rega.0 2020-02-17 10:57:48.823 debug (11450) stateChange hm-rega.0.40974 {"val":48.4,"ack":false,"ts":1581933468818,"q":0,"from":"system.adapter.javascript.0","user":"system.user.admin","lc":1581933468818} hm-rega.0 2020-02-17 10:57:48.820 debug (11450) --> dom.GetObject(40974).State(48.4) hm-rega.0 2020-02-17 10:57:48.820 debug (11450) Set state 40974: 48.4 hm-rega.0 2020-02-17 10:57:48.820 debug (11450) stateChange hm-rega.0.40974 {"val":48.4,"ack":false,"ts":1581933468818,"q":0,"from":"system.adapter.javascript.0","user":"system.user.admin","lc":1581933468818} hm-rega.0 2020-02-17 10:57:47.563 debug (11450) <-- {"7600":{"Name":"Alle%20Jalousien%20steuern","TypeName":"PROGRAM","PrgInfo":"Alle%20Jalousien%20werden%20gesteuert", "Active":true,"Timestamp":"2020-02-17 08:59:00"}, "18192":{"Name":"All hm-rega.0 2020-02-17 10:57:47.551 debug (11450) --> !# programs.fn 1.3 !# Dieses Script gibt eine Liste der Programme als JSON Strin
-
@lemonbiter Hallo! Hatte ich letztens auch, nachdem ich den Adapter HomeMatic RegaHss upgedatet hatte (glaube auf Version 2.5.3). Bin dann wieder auf die vorherige Version dgewechselt und der Fehler war weg. Dann gab es wieder irgendwann ein Update des Adapter auf 2.5.4 In der Hoffnung, dass es dann besser läuft, hatte ich dieses Update gemacht. Der Fehler war aber wieder vorhanden. Hatte mich auf die Suche gemacht und Schritt für Schritt alles Scripte angehalten, die irgendwie auf die HomeMatic CCU (Raspberrymatic) zugreifen. Letztendlich lag es bei mir an einem Script, welches in Variablen auf der Raspberrymatic Werte aktualisiert. Hatte vorher nie bemerkt, dass in dem Script zeitgleich Werte in Variablen zweimal geschrieben werden sollten (Adapter Das Wetter). Dopplungen herausgenommen und Ruhe ist. Vorher, bei den älteren Versionen des Adapters HomeMatic RegaHss fiel das nicht auf. Also solltest Du vielleicht auch versuchen: Relevante Scripte einzeln stoppen und log beobachten....kann zwar langwierig sein, könnte aber helfen
-
Jop du schreibst ganz oft nahezu zeitgleich mehrmals ein und denselben Wert in einen DP und zwar u.a. in
hm-rega.0.40974
solltest du mal deine Skripte checken, ich werde mal was einbauen, damit man zukünftig sieht, an welchem Script es liegt. Sprich er wird dann in Zukunft sagenScript <Auszug-von-Skriptinhalt> ignored, because still pending
Früher ist der Fehler nicht aufgefallen, da er nur auf
debug
geloggt wurde. Lässt sich allerdings definitiv auf suboptimal programmierte Skripte zurückführen. -
@foxriver76 said in Fehler: hm-rega.0... Script ignored, because still pending.:
Früher ist der Fehler nicht aufgefallen, da er nur auf
debug
geloggt wurde. Lässt sich allerdings definitiv auf suboptimal programmierte Skripte zurückführen.Ah - danke ! Das macht Sinn !
Werde mal meine Scripts durchackern !Wäre super, wenn Du in Zukunft den "Schuldigen" mitloggen könntest!
lg Pietro
-
setStateDelayed ist Dein Freund
Hab 10ms delay eingebaut und schon sind die log-Einträge weg.Nochmals Danke an @foxriver76 für die Unterstützung !
lg Pietro
-
Ich habe heute auch mal die 2.5.5 upgedated und die Rega auf Debug gestellt.
Da ich nicht so ganz verstanden habe, worüber genau ihr redet hoffe ich auf mehr klaren output im Debug Modus:
"Jop du schreibst ganz oft nahezu zeitgleich mehrmals ein und denselben Wert in einen DP" und
"Relevante Scripte einzeln stoppen und log beobachten" ???Sorry... bin noch zu frisch dabei (Anfänger halt)
Mal schauen ob die Fehlermeldungen der 2.5.5 mir helfenDanke, ich melde mich ASAP
LG
lem -
@lemonbiter Das schreiben passiert normalerweise durch den Javascript Adapter, deshalb das stoppen der Skripte. Ob die Warning darauf zurückzuführen ist, lässt sich auch leicht ermitteln indem man mal seine Javascript Instanzen stoppt, falls die Warnung dann nicht mehr auftaucht ist es ziemlich sicher dieser Fall.
Dann muss man noch identifizieren, welches Skript schreibt und dieses entsprechend anpassen.
-
Lieber @foxriver76
Danke für Deine Bemühungen. Ich habe nun ALLE Skripte beendet und dennoch...
Wer weiss Rat??
-
@lemonbiter die Skipte sind nicht von dir sondern vom Rega Adapter. Wie ist denn das pollingInterval eingestellt leider sieht man nicht wie viel Zeit zwischen den Meldungen ist.
-
Hallo @foxriver76
Mal wieder Danke für Dein Feedback.Ich finde es sehr schade, dass ich leider noch überhaupt nicht verstehe, was mir der Fehler "sagen" will. Das ist für den geringen Sachverstand den ich habe viel zu kryptisch leider....
Die Fehlermeldung ist quasi den ganzen Tag immer wieder da. Im Anhang mal der gesamte Log. Im Moment ist es auch nicht mehr so, dass ein Rega Neustart den Fehler für eine Zeit unterbindet...
Polling steht auf 30s
-
@lemonbiter Hm naja, die grundlegende Funktionsweise von Rega ist Skripte auf der CCU auszuführen. Rega tut nichts anderes - um eine reinbungslose Abfolge zu gewähren, gibt es eine Queue. Wenn ein und dasselbe Skript schon in der Queue ist, dann wird es ignoriert was es schlicht kein Sinn macht, es mehrmals auszuführen. Also eigentlich genau das, was die Fehlermeldung sagt..
Ich schau mir das Log dann mal an.
-
@lemonbiter Polling Intervall wurde auch eingehalten.. passt soweit. Kannst du mir mal die Stelle im Log schicken, wo die Meldung das erste mal nach Start auftritt?
-
@foxriver76 Vielen Dank. Soll ich Rega anhalten und dann neustarten oder muss ich ioBroker neustarten?
-
@lemonbiter Rega reicht, ein kompletter ioBroker Neustart sollte sich da nicht drauf auswirken. Falls es wirklich so gut nachstellbar ist, gerne auch Debug Log.
-
@foxriver76
Hi ... Na klar, habe soeben den Rega Adapter gestoppt und neu gestartet und jetzt ist erst mal kein Fehler im Log. Muss also abwarten und dann schicke ich sofort.
Wie kann ich den Debug Log senden bzw. wo finde ich den? Sorry... ;-(Lg
Lem