NEWS
-
mit dieser Adapterversion hatte ich massive Probleme mit HM-IP Geräten. (Raspberrrymatik 3.37.8.20181026, also NICHT die ganz neue)
vorher Verion 1.7.6 HM IP Geräte konnten status empfangen (von IOBroker und WebUI der CCU aus) als auch von WEB UI und IOBroker aus bedient werden.
nach Wechsel auf 1.7.8:
Status der HM-IP geräte konnte empfangen werden (sowohl bei IOBroker als auch der CCU), Gerät ist ein Markenchalter Dimmer)
sobald im Log zu beobachten war, das der HM-RPC Adaper von HMIP gestartet war sah alles zwar aus wie immer, Stati wurden empfangen.
ABER: egal an welches HM-IP gerät gesendet werden sollte:
bedienung des Dimmers von der WEB-UI: Sofort Kommunikatiosnstörung, keine Reaktion des gerätes
bedienung des Dimmers von IOBroker: Sofort Kommunikatiosnstörung, keine Reaktion des gerätes
Direktverknüpfungen eines tasters mit dem Dimmer tatens einwandfrei, ebenso kam rückmeldung sowohl in WebUI als auch IOBroker an der Direktverknüpfungsbedienung
SSH Iobroker Stop
Trotzdem war das Gerät über die WebUI nicht mehr bedienbar
CCU Neuboot bei iobroker stop
gerät ist bedienbar
warten von paar minuten
gerät ist von WB UI immer noch bedienbar
start IOBroker
sobald im Log start der HM-IP RPC Instanz kam: bei bedienung WEB UI: Sofort Kommunikatiosnstörung, keine Reaktion des gerätes
Lösung:
Downgrade des Adapters.
mit 1.7.6 wieder alles normal, alles tut, WAF ist vorhanden. IObroker und der HMIP-RPC spielen miteinander
im log liefen keine Fehlermeldungen auf unter 1.7.8, aber die CCU wurde in ihrer HMIP Sendefunktionalität lahmgelegt. HM Classic tats komischerweise mit der 1.7.8
Black
-
ich werde das heute nachmittag, abend nochmal auf meinem Spielesystem dachvollziehen.
Um das Produktivsystem möchte ich eigentlich einen Bogen machen. der WAF halt ^^
Ich hab aber die gleiche Raspberrymatic als Spielesystem mit ebenfalls 2 HMIP komponenten, mit denn ich noch rumexperimentire und auf dem orangePI noch einen Spiele IOBroker. dort werd ichs mal versuchen nachzustellen.
wobei, wenn wir einmal dabei sind
bei dem dimmer sind die Kanäle 4,5,6 . level (also die Sollwerte quai) in Iobroker skaliert auf 0-100% . ist ja auch sinnig.
Kanal 3. level (also der Istwert Quasi) leider von 0-1 in nicht prozent.
Das bild zeit eine Einstellung mit 10 Prozent Sollwert auf Kanal 4 und 10 Prozent istwert auf Kanal 3
Black
Logauszug 1.7.6: alles geht:
`2018-11-05 23:01:02.912 - [32minfo[39m: hm-rpc.3 starting. Version 1.7.6 in /opt/iobroker/node_modules/iobroker.hm-rpc, node: v8.11.3 2018-11-05 23:01:03.280 - [32minfo[39m: web.0 starting. Version 2.4.1 in /opt/iobroker/node_modules/iobroker.web, node: v8.11.3 2018-11-05 23:01:03.374 - [32minfo[39m: hm-rpc.2 xmlrpc server is trying to listen on 192.168.2.5:2001 2018-11-05 23:01:03.375 - [32minfo[39m: hm-rpc.2 xmlrpc client is trying to connect to 192.168.2.6:2001 with ["http://192.168.2.5:2001","hm-rpc.2"] 2018-11-05 23:01:03.307 - [32minfo[39m: simple-api.0 starting. Version 2.0.0 in /opt/iobroker/node_modules/iobroker.simple-api, node: v8.11.3 2018-11-05 23:01:03.450 - [32minfo[39m: hm-rpc.2 xmlrpc <- system.listMethods ["hm-rpc.2"] 2018-11-05 23:01:03.368 - [32minfo[39m: simple-api.0 simpleAPI server listening on port 8087 2018-11-05 23:01:03.369 - [32minfo[39m: simple-api.0 Allow states only when user is owner: false 2018-11-05 23:01:03.372 - [32minfo[39m: simple-api.0 http server listening on port 8087 2018-11-05 23:01:03.482 - [32minfo[39m: hm-rpc.2 xmlrpc <- listDevices ["hm-rpc.2"] 2018-11-05 23:01:03.524 - [32minfo[39m: hm-rpc.3 xmlrpc server is trying to listen on 192.168.2.5:2010 2018-11-05 23:01:03.573 - [32minfo[39m: hm-rpc.2 xmlrpc -> 380 devices 2018-11-05 23:01:03.533 - [32minfo[39m: hm-rpc.3 xmlrpc client is trying to connect to 192.168.2.6:2010 with ["http://192.168.2.5:2010","hm-rpc.3"] 2018-11-05 23:01:03.608 - [32minfo[39m: hm-rpc.3 Connected 2018-11-05 23:01:03.914 - [32minfo[39m: hm-rpc.2 Connected 2018-11-05 23:01:03.918 - [32minfo[39m: cloud.0 starting. Version 2.6.2 in /opt/iobroker/node_modules/iobroker.cloud, node: v8.11.3 2018-11-05 23:01:03.658 - [32minfo[39m: hm-rpc.3 xmlrpc <- listDevices ["hm-rpc.3"] 2018-11-05 23:01:03.676 - [32minfo[39m: hm-rpc.3 xmlrpc -> 0 devices 2018-11-05 23:01:04.253 - [32minfo[39m: hm-rpc.3 xmlrpc <- newDevices 19[/code]` Logauszug 1.7.8: Ansteuerung Gerät liefert Komm Störung sobald connection da war `~~[code]~~2018-11-05 22:53:19.557 - [32minfo[39m: hm-rpc.2 xmlrpc server is trying to listen on 192.168.2.5:2001 2018-11-05 22:53:19.558 - [32minfo[39m: hm-rpc.2 xmlrpc client is trying to connect to 192.168.2.6:2001/ with ["http://192.168.2.5:2001","hm-rpc.2"] 2018-11-05 22:53:19.599 - [32minfo[39m: hm-rpc.2 xmlrpc <- system.listMethods ["hm-rpc.2"] 2018-11-05 22:53:19.616 - [32minfo[39m: hm-rpc.2 xmlrpc <- listDevices ["hm-rpc.2"] 2018-11-05 22:53:19.643 - [32minfo[39m: hm-rpc.2 xmlrpc -> 380 devices 2018-11-05 22:53:19.689 - [32minfo[39m: host.tinkerboard instance system.adapter.hm-rpc.3 started with pid 1316 2018-11-05 22:53:19.930 - [32minfo[39m: hm-rpc.2 Connected 2018-11-05 22:53:21.022 - [32minfo[39m: hm-rpc.3 starting. Version 1.7.8 in /opt/iobroker/node_modules/iobroker.hm-rpc, node: v8.11.3 2018-11-05 22:53:21.480 - [32minfo[39m: hm-rpc.3 xmlrpc server is trying to listen on 192.168.2.5:2010 2018-11-05 22:53:21.481 - [32minfo[39m: hm-rpc.3 xmlrpc client is trying to connect to 192.168.2.6:2010/ with ["http://192.168.2.5:2010","hm-rpc.3"] 2018-11-05 22:53:21.520 - [32minfo[39m: hm-rpc.3 Connected 2018-11-05 22:53:21.544 - [32minfo[39m: hm-rpc.3 xmlrpc <- listDevices ["hm-rpc.3"] 2018-11-05 22:53:21.558 - [32minfo[39m: hm-rpc.3 xmlrpc -> 0 devices 2018-11-05 22:53:21.805 - [32minfo[39m: hm-rpc.3 xmlrpc <- newDevices 19 2018-11-05 22:53:21.816 - [32minfo[39m: hm-rpc.3 new HmIP devices/channels after filter: 0[/code]`[/code] -
mach ich jetzt mal
das ist übrigens der log im debug vom produktivsystem… da tacht der fehler wieder auf.
hm-rpc.3 2018-11-06 10:03:38.131 debug hm-rpc.3.000BD5699D4DFD.1.MOTION_DETECTION_ACTIVE ==> UNIT: "undefined" (min: false, max: true) From "true" => "true" hm-rpc.3 2018-11-06 10:03:38.131 debug xmlrpc <- event ["hm-rpc.3","000BD5699D4DFD:1","MOTION_DETECTION_ACTIVE",true] hm-rpc.3 2018-11-06 10:03:38.131 debug xml multicall <event>: hm-rpc.3,000BD5699D4DFD:1,MOTION_DETECTION_ACTIVE,true hm-rpc.3 2018-11-06 10:03:38.131 debug hm-rpc.3.000BD5699D4DFD.1.ILLUMINATION_STATUS ==> UNIT: "undefined" (min: NORMAL, max: OVERFLOW) From "0" => "0" hm-rpc.3 2018-11-06 10:03:38.131 debug xmlrpc <- event ["hm-rpc.3","000BD5699D4DFD:1","ILLUMINATION_STATUS",0] hm-rpc.3 2018-11-06 10:03:38.131 debug xml multicall <event>: hm-rpc.3,000BD5699D4DFD:1,ILLUMINATION_STATUS,0 hm-rpc.3 2018-11-06 10:03:38.130 debug hm-rpc.3.000BD5699D4DFD.1.ILLUMINATION ==> UNIT: "undefined" (min: 0, max: 163830) From "208.1" => "208.1" hm-rpc.3 2018-11-06 10:03:38.130 debug xmlrpc <- event ["hm-rpc.3","000BD5699D4DFD:1","ILLUMINATION",208.1] hm-rpc.3 2018-11-06 10:03:38.130 debug xml multicall <event>: hm-rpc.3,000BD5699D4DFD:1,ILLUMINATION,208.1 hm-rpc.3 2018-11-06 10:03:38.130 debug hm-rpc.3.000BD5699D4DFD.1.MOTION ==> UNIT: "undefined" (min: false, max: true) From "false" => "false" hm-rpc.3 2018-11-06 10:03:38.130 debug xmlrpc <- event ["hm-rpc.3","000BD5699D4DFD:1","MOTION",false] hm-rpc.3 2018-11-06 10:03:38.130 debug xml multicall <event>: hm-rpc.3,000BD5699D4DFD:1,MOTION,false hm-rpc.3 2018-11-06 10:03:38.130 debug hm-rpc.3.000BD5699D4DFD.0.ERROR_CODE ==> UNIT: "undefined" (min: 0, max: 255) From "0" => "0" hm-rpc.3 2018-11-06 10:03:38.129 debug xmlrpc <- event ["hm-rpc.3","000BD5699D4DFD:0","ERROR_CODE",0] hm-rpc.3 2018-11-06 10:03:38.129 debug xml multicall <event>: hm-rpc.3,000BD5699D4DFD:0,ERROR_CODE,0 hm-rpc.3 2018-11-06 10:03:38.129 debug hm-rpc.3.000BD5699D4DFD.0.RSSI_PEER ==> UNIT: "undefined" (min: -128, max: 127) From "-74" => "-74" hm-rpc.3 2018-11-06 10:03:38.129 debug xmlrpc <- event ["hm-rpc.3","000BD5699D4DFD:0","RSSI_PEER",-74] hm-rpc.3 2018-11-06 10:03:38.129 debug xml multicall <event>: hm-rpc.3,000BD5699D4DFD:0,RSSI_PEER,-74 hm-rpc.3 2018-11-06 10:03:38.129 debug hm-rpc.3.000BD5699D4DFD.0.OPERATING_VOLTAGE ==> UNIT: "undefined" (min: 0, max: 25.2) From "2.7" => "2.7" hm-rpc.3 2018-11-06 10:03:38.129 debug xmlrpc <- event ["hm-rpc.3","000BD5699D4DFD:0","OPERATING_VOLTAGE",2.7] hm-rpc.3 2018-11-06 10:03:38.128 debug xml multicall <event>: hm-rpc.3,000BD5699D4DFD:0,OPERATING_VOLTAGE,2.7 hm-rpc.3 2018-11-06 10:03:38.128 debug hm-rpc.3.000BD5699D4DFD.0.OPERATING_VOLTAGE_STATUS ==> UNIT: "undefined" (min: NORMAL, max: OVERFLOW) From "0" => "0" hm-rpc.3 2018-11-06 10:03:38.128 debug xmlrpc <- event ["hm-rpc.3","000BD5699D4DFD:0","OPERATING_VOLTAGE_STATUS",0] hm-rpc.3 2018-11-06 10:03:38.128 debug xml multicall <event>: hm-rpc.3,000BD5699D4DFD:0,OPERATING_VOLTAGE_STATUS,0 hm-rpc.3 2018-11-06 10:03:38.128 debug hm-rpc.3.000BD5699D4DFD.0.RSSI_DEVICE ==> UNIT: "undefined" (min: -128, max: 127) From "-65" => "-65" hm-rpc.3 2018-11-06 10:03:38.128 debug xmlrpc <- event ["hm-rpc.3","000BD5699D4DFD:0","RSSI_DEVICE",-65] hm-rpc.3 2018-11-06 10:03:38.128 debug xml multicall <event>: hm-rpc.3,000BD5699D4DFD:0,RSSI_DEVICE,-65 hm-rpc.3 2018-11-06 10:03:38.127 debug hm-rpc.3.000BD5699D4DFD.0.UNREACH ==> UNIT: "undefined" (min: false, max: true) From "false" => "false" hm-rpc.3 2018-11-06 10:03:38.127 debug xmlrpc <- event ["hm-rpc.3","000BD5699D4DFD:0","UNREACH",false] hm-rpc.3 2018-11-06 10:03:38.127 debug xml multicall <event>: hm-rpc.3,000BD5699D4DFD:0,UNREACH,false hm-rpc.3 2018-11-06 10:03:38.127 debug hm-rpc.3.000BD5699D4DFD.0.LOW_BAT ==> UNIT: "undefined" (min: false, max: true) From "false" => "false" hm-rpc.3 2018-11-06 10:03:38.127 debug xmlrpc <- event ["hm-rpc.3","000BD5699D4DFD:0","LOW_BAT",false] hm-rpc.3 2018-11-06 10:03:38.127 debug xml multicall <event>: hm-rpc.3,000BD5699D4DFD:0,LOW_BAT,false hm-rpc.3 2018-11-06 10:03:38.126 debug hm-rpc.3.000BD5699D4DFD.0.DUTY_CYCLE ==> UNIT: "undefined" (min: false, max: true) From "false" => "false" hm-rpc.3 2018-11-06 10:03:38.126 debug xmlrpc <- event ["hm-rpc.3","000BD5699D4DFD:0","DUTY_CYCLE",false] hm-rpc.3 2018-11-06 10:03:38.126 debug xml multicall <event>: hm-rpc.3,000BD5699D4DFD:0,DUTY_CYCLE,false hm-rpc.3 2018-11-06 10:03:38.126 debug hm-rpc.3.000BD5699D4DFD.0.CONFIG_PENDING ==> UNIT: "undefined" (min: false, max: true) From "false" => "false" hm-rpc.3 2018-11-06 10:03:38.126 debug xmlrpc <- event ["hm-rpc.3","000BD5699D4DFD:0","CONFIG_PENDING",false] hm-rpc.3 2018-11-06 10:03:38.111 debug xml multicall <event>: hm-rpc.3,000BD5699D4DFD:0,CONFIG_PENDING,false hm-rpc.3 2018-11-06 10:02:47.113 debug hm-rpc.3.CENTRAL.0.PONG ==> UNIT: "none" (min: none, max: none) From "hm-rpc.3" => "hm-rpc.3" hm-rpc.3 2018-11-06 10:02:47.113 debug xmlrpc <- event ["hm-rpc.3","CENTRAL:0","PONG","hm-rpc.3"] hm-rpc.3 2018-11-06 10:02:47.101 debug PING ok hm-rpc.3 2018-11-06 10:02:47.079 debug Send PING... hm-rpc.3 2018-11-06 10:01:25.533 debug hm-rpc.3.000BD5699D4DFD.1.MOTION_DETECTION_ACTIVE ==> UNIT: "undefined" (min: false, max: true) From "true" => "true" hm-rpc.3 2018-11-06 10:01:25.533 debug xmlrpc <- event ["hm-rpc.3","000BD5699D4DFD:1","MOTION_DETECTION_ACTIVE",true] hm-rpc.3 2018-11-06 10:01:25.532 debug xml multicall <event>: hm-rpc.3,000BD5699D4DFD:1,MOTION_DETECTION_ACTIVE,true hm-rpc.3 2018-11-06 10:01:25.532 debug hm-rpc.3.000BD5699D4DFD.1.ILLUMINATION_STATUS ==> UNIT: "undefined" (min: NORMAL, max: OVERFLOW) From "0" => "0" hm-rpc.3 2018-11-06 10:01:25.532 debug xmlrpc <- event ["hm-rpc.3","000BD5699D4DFD:1","ILLUMINATION_STATUS",0] hm-rpc.3 2018-11-06 10:01:25.531 debug xml multicall <event>: hm-rpc.3,000BD5699D4DFD:1,ILLUMINATION_STATUS,0 hm-rpc.3 2018-11-06 10:01:25.531 debug hm-rpc.3.000BD5699D4DFD.1.ILLUMINATION ==> UNIT: "undefined" (min: 0, max: 163830) From "202.6" => "202.6" hm-rpc.3 2018-11-06 10:01:25.531 debug xmlrpc <- event ["hm-rpc.3","000BD5699D4DFD:1","ILLUMINATION",202.6] hm-rpc.3 2018-11-06 10:01:25.530 debug xml multicall <event>: hm-rpc.3,000BD5699D4DFD:1,ILLUMINATION,202.6 hm-rpc.3 2018-11-06 10:01:25.530 debug hm-rpc.3.000BD5699D4DFD.1.MOTION ==> UNIT: "undefined" (min: false, max: true) From "false" => "false" hm-rpc.3 2018-11-06 10:01:25.530 debug xmlrpc <- event ["hm-rpc.3","000BD5699D4DFD:1","MOTION",false] hm-rpc.3 2018-11-06 10:01:25.529 debug xml multicall <event>: hm-rpc.3,000BD5699D4DFD:1,MOTION,false hm-rpc.3 2018-11-06 10:01:25.471 debug hm-rpc.3.000BD5699D4DFD.0.ERROR_CODE ==> UNIT: "undefined" (min: 0, max: 255) From "0" => "0" hm-rpc.3 2018-11-06 10:01:25.471 debug xmlrpc <- event ["hm-rpc.3","000BD5699D4DFD:0","ERROR_CODE",0] hm-rpc.3 2018-11-06 10:01:25.471 debug xml multicall <event>: hm-rpc.3,000BD5699D4DFD:0,ERROR_CODE,0 hm-rpc.3 2018-11-06 10:01:25.470 debug hm-rpc.3.000BD5699D4DFD.0.RSSI_PEER ==> UNIT: "undefined" (min: -128, max: 127) From "-74" => "-74" hm-rpc.3 2018-11-06 10:01:25.470 debug xmlrpc <- event ["hm-rpc.3","000BD5699D4DFD:0","RSSI_PEER",-74] hm-rpc.3 2018-11-06 10:01:25.469 debug xml multicall <event>: hm-rpc.3,000BD5699D4DFD:0,RSSI_PEER,-74 hm-rpc.3 2018-11-06 10:01:25.469 debug hm-rpc.3.000BD5699D4DFD.0.OPERATING_VOLTAGE ==> UNIT: "undefined" (min: 0, max: 25.2) From "2.7" => "2.7" hm-rpc.3 2018-11-06 10:01:25.469 debug xmlrpc <- event ["hm-rpc.3","000BD5699D4DFD:0","OPERATING_VOLTAGE",2.7] hm-rpc.3 2018-11-06 10:01:25.468 debug xml multicall <event>: hm-rpc.3,000BD5699D4DFD:0,OPERATING_VOLTAGE,2.7 hm-rpc.3 2018-11-06 10:01:25.468 debug hm-rpc.3.000BD5699D4DFD.0.OPERATING_VOLTAGE_STATUS ==> UNIT: "undefined" (min: NORMAL, max: OVERFLOW) From "0" => "0" hm-rpc.3 2018-11-06 10:01:25.468 debug xmlrpc <- event ["hm-rpc.3","000BD5699D4DFD:0","OPERATING_VOLTAGE_STATUS",0] hm-rpc.3 2018-11-06 10:01:25.467 debug xml multicall <event>: hm-rpc.3,000BD5699D4DFD:0,OPERATING_VOLTAGE_STATUS,0 hm-rpc.3 2018-11-06 10:01:25.467 debug hm-rpc.3.000BD5699D4DFD.0.RSSI_DEVICE ==> UNIT: "undefined" (min: -128, max: 127) From "-65" => "-65" hm-rpc.3 2018-11-06 10:01:25.467 debug xmlrpc <- event ["hm-rpc.3","000BD5699D4DFD:0","RSSI_DEVICE",-65] hm-rpc.3 2018-11-06 10:01:25.466 debug xml multicall <event>: hm-rpc.3,000BD5699D4DFD:0,RSSI_DEVICE,-65 hm-rpc.3 2018-11-06 10:01:25.466 debug hm-rpc.3.000BD5699D4DFD.0.UNREACH ==> UNIT: "undefined" (min: false, max: true) From "false" => "false" hm-rpc.3 2018-11-06 10:01:25.466 debug xmlrpc <- event ["hm-rpc.3","000BD5699D4DFD:0","UNREACH",false] hm-rpc.3 2018-11-06 10:01:25.465 debug xml multicall <event>: hm-rpc.3,000BD5699D4DFD:0,UNREACH,false hm-rpc.3 2018-11-06 10:01:25.465 debug hm-rpc.3.000BD5699D4DFD.0.LOW_BAT ==> UNIT: "undefined" (min: false, max: true) From "false" => "false" hm-rpc.3 2018-11-06 10:01:25.465 debug xmlrpc <- event ["hm-rpc.3","000BD5699D4DFD:0","LOW_BAT",false] hm-rpc.3 2018-11-06 10:01:25.464 debug xml multicall <event>: hm-rpc.3,000BD5699D4DFD:0,LOW_BAT,false hm-rpc.3 2018-11-06 10:01:25.463 debug hm-rpc.3.000BD5699D4DFD.0.DUTY_CYCLE ==> UNIT: "undefined" (min: false, max: true) From "false" => "false" hm-rpc.3 2018-11-06 10:01:25.463 debug xmlrpc <- event ["hm-rpc.3","000BD5699D4DFD:0","DUTY_CYCLE",false] hm-rpc.3 2018-11-06 10:01:25.462 debug xml multicall <event>: hm-rpc.3,000BD5699D4DFD:0,DUTY_CYCLE,false hm-rpc.3 2018-11-06 10:01:25.461 debug hm-rpc.3.000BD5699D4DFD.0.CONFIG_PENDING ==> UNIT: "undefined" (min: false, max: true) From "false" => "false" hm-rpc.3 2018-11-06 10:01:25.460 debug xmlrpc <- event ["hm-rpc.3","000BD5699D4DFD:0","CONFIG_PENDING",false] hm-rpc.3 2018-11-06 10:01:25.459 debug xml multicall <event>: hm-rpc.3,000BD5699D4DFD:0,CONFIG_PENDING,false hm-rpc.3 2018-11-06 10:01:17.333 info new HmIP devices/channels after filter: 0 hm-rpc.3 2018-11-06 10:01:17.321 info xmlrpc <- newDevices 19 hm-rpc.3 2018-11-06 10:01:17.103 info xmlrpc -> 0 devices hm-rpc.3 2018-11-06 10:01:17.091 info xmlrpc <- listDevices ["hm-rpc.3"] hm-rpc.3 2018-11-06 10:01:17.073 debug start ping interval hm-rpc.3 2018-11-06 10:01:17.072 debug clear connecting interval hm-rpc.3 2018-11-06 10:01:17.071 info Connected hm-rpc.3 2018-11-06 10:01:17.046 debug start connecting interval hm-rpc.3 2018-11-06 10:01:17.040 debug xmlrpc -> 192.168.2.6:2010/ init ["http://192.168.2.5:2010","hm-rpc.3"] hm-rpc.3 2018-11-06 10:01:17.040 debug Connect... hm-rpc.3 2018-11-06 10:01:17.039 info xmlrpc client is trying to connect to 192.168.2.6:2010/ with ["http://192.168.2.5:2010","hm-rpc.3"] hm-rpc.3 2018-11-06 10:01:17.038 info xmlrpc server is trying to listen on 192.168.2.5:2010 hm-rpc.3 2018-11-06 10:01:16.779 info starting. Version 1.7.8 in /opt/iobroker/node_modules/iobroker.hm-rpc, node: v8.11.3 hm-rpc.3 2018-11-06 10:01:16.655 debug statesDB connected hm-rpc.3 2018-11-06 10:01:16.620 debug objectDB connected hm-rpc.3 2018-11-06 10:01:13.386 info terminating hm-rpc.3 2018-11-06 10:01:13.374 info Disconnected hm-rpc.3 2018-11-06 10:01:13.353 info xmlrpc -> 192.168.2.6:2010/ init ["http://192.168.2.5:2010",""]</event></event></event></event></event></event></event></event></event></event></event></event></event></event></event></event></event></event></event></event></event></event></event></event></event></event> -
nach Github Installation leider immer noch nicht erfolgreich.
das ist das ccu Log:
Nov 6 09:59:06 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO [vert.x-eventloop-thread-5] SYSTEM: 0 Accesspoints in Queue Nov 6 09:59:06 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO [vert.x-eventloop-thread-5] SYSTEM: Permanent-/Burstlistener Handler utilization: 0/50 used Nov 6 09:59:06 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO [vert.x-eventloop-thread-5] SYSTEM: Eventlistener Handler utilization: 0/50 used Nov 6 09:59:43 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO [vert.x-worker-thread-2] (un)registerCallback on LegacyServiceHandler called from url: http://192.168.2.5:2010 Nov 6 09:59:43 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO [vert.x-worker-thread-2] init finished Nov 6 09:59:43 de.eq3.cbcs.legacy.bidcos.rpc.internal.InterfaceInitializer INFO [vert.x-worker-thread-3] Added InterfaceId: hm-rpc.3 Nov 6 09:59:43 de.eq3.cbcs.legacy.bidcos.rpc.internal.DeviceUtil INFO [vert.x-worker-thread-3] updateDevicesForClient hm-rpc.3 -> 19 device addresses will be added Nov 6 10:01:13 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO [vert.x-worker-thread-4] (un)registerCallback on LegacyServiceHandler called from url: http://192.168.2.5:2010 Nov 6 10:01:13 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO [vert.x-worker-thread-4] Removed InterfaceId: hm-rpc.3 Nov 6 10:01:13 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO [vert.x-worker-thread-4] init finished Nov 6 10:01:17 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO [vert.x-worker-thread-3] (un)registerCallback on LegacyServiceHandler called from url: http://192.168.2.5:2010 Nov 6 10:01:17 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO [vert.x-worker-thread-3] init finished Nov 6 10:01:17 de.eq3.cbcs.legacy.bidcos.rpc.internal.InterfaceInitializer INFO [vert.x-worker-thread-1] Added InterfaceId: hm-rpc.3 Nov 6 10:01:17 de.eq3.cbcs.legacy.bidcos.rpc.internal.DeviceUtil INFO [vert.x-worker-thread-1] updateDevicesForClient hm-rpc.3 -> 19 device addresses will be added Nov 6 10:04:06 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO [vert.x-eventloop-thread-5] SYSTEM: 0 Accesspoints in Queue Nov 6 10:04:06 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO [vert.x-eventloop-thread-5] SYSTEM: Permanent-/Burstlistener Handler utilization: 0/50 used Nov 6 10:04:06 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO [vert.x-eventloop-thread-5] SYSTEM: Eventlistener Handler utilization: 0/50 used Nov 6 10:09:06 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO [vert.x-eventloop-thread-5] SYSTEM: 0 Accesspoints in Queue Nov 6 10:09:06 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO [vert.x-eventloop-thread-5] SYSTEM: Permanent-/Burstlistener Handler utilization: 0/50 used Nov 6 10:09:06 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO [vert.x-eventloop-thread-5] SYSTEM: Eventlistener Handler utilization: 0/50 used Nov 6 10:10:09 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO [vert.x-worker-thread-0] (un)registerCallback on LegacyServiceHandler called from url: http://192.168.2.5:2010 Nov 6 10:10:09 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO [vert.x-worker-thread-0] Removed InterfaceId: hm-rpc.3 Nov 6 10:10:09 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO [vert.x-worker-thread-0] init finished Nov 6 10:10:13 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO [vert.x-worker-thread-2] (un)registerCallback on LegacyServiceHandler called from url: http://192.168.2.5:2010 Nov 6 10:10:13 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO [vert.x-worker-thread-2] init finished Nov 6 10:10:13 de.eq3.cbcs.legacy.bidcos.rpc.internal.InterfaceInitializer INFO [vert.x-worker-thread-3] Added InterfaceId: hm-rpc.3 Nov 6 10:10:13 de.eq3.cbcs.legacy.bidcos.rpc.internal.DeviceUtil INFO [vert.x-worker-thread-3] updateDevicesForClient hm-rpc.3 -> 19 device addresses will be added Nov 6 10:10:15 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO [vert.x-worker-thread-4] (un)registerCallback on LegacyServiceHandler called from url: http://192.168.2.5:2010 Nov 6 10:10:15 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO [vert.x-worker-thread-4] Removed InterfaceId: hm-rpc.3 Nov 6 10:10:15 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO [vert.x-worker-thread-4] init finished Nov 6 10:10:19 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO [vert.x-worker-thread-1] (un)registerCallback on LegacyServiceHandler called from url: http://192.168.2.5:2010 Nov 6 10:10:19 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO [vert.x-worker-thread-1] init finished Nov 6 10:10:19 de.eq3.cbcs.legacy.bidcos.rpc.internal.InterfaceInitializer INFO [vert.x-worker-thread-2] Added InterfaceId: hm-rpc.3 Nov 6 10:10:19 de.eq3.cbcs.legacy.bidcos.rpc.internal.DeviceUtil INFO [vert.x-worker-thread-2] updateDevicesForClient hm-rpc.3 -> 19 device addresses will be added Nov 6 10:14:06 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO [vert.x-eventloop-thread-5] SYSTEM: 0 Accesspoints in Queue Nov 6 10:14:06 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO [vert.x-eventloop-thread-5] SYSTEM: Permanent-/Burstlistener Handler utilization: 0/50 used Nov 6 10:14:06 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO [vert.x-eventloop-thread-5] SYSTEM: Eventlistener Handler utilization: 0/50 usedzumindest aber dahin schon mal besser, wenn ich nun den ip-rpc stoppe, geht die komm störung auf der ccu wieder weg.
die ccu ist nach neustart des ip rpc adapters eine weile bedienbar, danahc kommen wieder die komm störungen
hm-rpc.3 2018-11-06 10:23:49.879 debug hm-rpc.3.CENTRAL.0.PONG ==> UNIT: "none" (min: none, max: none) From "hm-rpc.3" => "hm-rpc.3" hm-rpc.3 2018-11-06 10:23:49.878 debug xmlrpc <- event ["hm-rpc.3","CENTRAL:0","PONG","hm-rpc.3"] hm-rpc.3 2018-11-06 10:23:49.871 debug PING ok hm-rpc.3 2018-11-06 10:23:49.841 debug Send PING... hm-rpc.3 2018-11-06 10:23:32.774 debug system.adapter.admin.0: logging true hm-rpc.3 2018-11-06 10:23:02.195 debug hm-rpc.3.000BD5699D4DFD.1.MOTION_DETECTION_ACTIVE ==> UNIT: "undefined" (min: false, max: true) From "true" => "true" hm-rpc.3 2018-11-06 10:23:02.195 debug xmlrpc <- event ["hm-rpc.3","000BD5699D4DFD:1","MOTION_DETECTION_ACTIVE",true] hm-rpc.3 2018-11-06 10:23:02.195 debug xml multicall <event>: hm-rpc.3,000BD5699D4DFD:1,MOTION_DETECTION_ACTIVE,true hm-rpc.3 2018-11-06 10:23:02.195 debug hm-rpc.3.000BD5699D4DFD.1.ILLUMINATION_STATUS ==> UNIT: "undefined" (min: NORMAL, max: OVERFLOW) From "0" => "0" hm-rpc.3 2018-11-06 10:23:02.194 debug xmlrpc <- event ["hm-rpc.3","000BD5699D4DFD:1","ILLUMINATION_STATUS",0] hm-rpc.3 2018-11-06 10:23:02.193 debug xml multicall <event>: hm-rpc.3,000BD5699D4DFD:1,ILLUMINATION_STATUS,0 hm-rpc.3 2018-11-06 10:23:02.193 debug hm-rpc.3.000BD5699D4DFD.1.ILLUMINATION ==> UNIT: "undefined" (min: 0, max: 163830) From "357.7" => "357.7" hm-rpc.3 2018-11-06 10:23:02.192 debug xmlrpc <- event ["hm-rpc.3","000BD5699D4DFD:1","ILLUMINATION",357.7] hm-rpc.3 2018-11-06 10:23:02.192 debug xml multicall <event>: hm-rpc.3,000BD5699D4DFD:1,ILLUMINATION,357.7 hm-rpc.3 2018-11-06 10:23:02.191 debug hm-rpc.3.000BD5699D4DFD.1.MOTION ==> UNIT: "undefined" (min: false, max: true) From "false" => "false" hm-rpc.3 2018-11-06 10:23:02.191 debug xmlrpc <- event ["hm-rpc.3","000BD5699D4DFD:1","MOTION",false] hm-rpc.3 2018-11-06 10:23:02.191 debug xml multicall <event>: hm-rpc.3,000BD5699D4DFD:1,MOTION,false hm-rpc.3 2018-11-06 10:23:02.168 debug hm-rpc.3.000BD5699D4DFD.0.ERROR_CODE ==> UNIT: "undefined" (min: 0, max: 255) From "0" => "0" hm-rpc.3 2018-11-06 10:23:02.168 debug xmlrpc <- event ["hm-rpc.3","000BD5699D4DFD:0","ERROR_CODE",0] hm-rpc.3 2018-11-06 10:23:02.168 debug xml multicall <event>: hm-rpc.3,000BD5699D4DFD:0,ERROR_CODE,0 hm-rpc.3 2018-11-06 10:23:02.168 debug hm-rpc.3.000BD5699D4DFD.0.RSSI_PEER ==> UNIT: "undefined" (min: -128, max: 127) From "-74" => "-74" hm-rpc.3 2018-11-06 10:23:02.167 debug xmlrpc <- event ["hm-rpc.3","000BD5699D4DFD:0","RSSI_PEER",-74] hm-rpc.3 2018-11-06 10:23:02.167 debug xml multicall <event>: hm-rpc.3,000BD5699D4DFD:0,RSSI_PEER,-74 hm-rpc.3 2018-11-06 10:23:02.167 debug hm-rpc.3.000BD5699D4DFD.0.OPERATING_VOLTAGE ==> UNIT: "undefined" (min: 0, max: 25.2) From "2.7" => "2.7" hm-rpc.3 2018-11-06 10:23:02.167 debug xmlrpc <- event ["hm-rpc.3","000BD5699D4DFD:0","OPERATING_VOLTAGE",2.7] hm-rpc.3 2018-11-06 10:23:02.167 debug xml multicall <event>: hm-rpc.3,000BD5699D4DFD:0,OPERATING_VOLTAGE,2.7 hm-rpc.3 2018-11-06 10:23:02.166 debug hm-rpc.3.000BD5699D4DFD.0.OPERATING_VOLTAGE_STATUS ==> UNIT: "undefined" (min: NORMAL, max: OVERFLOW) From "0" => "0" hm-rpc.3 2018-11-06 10:23:02.166 debug xmlrpc <- event ["hm-rpc.3","000BD5699D4DFD:0","OPERATING_VOLTAGE_STATUS",0] hm-rpc.3 2018-11-06 10:23:02.166 debug xml multicall <event>: hm-rpc.3,000BD5699D4DFD:0,OPERATING_VOLTAGE_STATUS,0 hm-rpc.3 2018-11-06 10:23:02.165 debug hm-rpc.3.000BD5699D4DFD.0.RSSI_DEVICE ==> UNIT: "undefined" (min: -128, max: 127) From "-64" => "-64" hm-rpc.3 2018-11-06 10:23:02.165 debug xmlrpc <- event ["hm-rpc.3","000BD5699D4DFD:0","RSSI_DEVICE",-64] hm-rpc.3 2018-11-06 10:23:02.165 debug xml multicall <event>: hm-rpc.3,000BD5699D4DFD:0,RSSI_DEVICE,-64 hm-rpc.3 2018-11-06 10:23:02.165 debug hm-rpc.3.000BD5699D4DFD.0.UNREACH ==> UNIT: "undefined" (min: false, max: true) From "false" => "false" hm-rpc.3 2018-11-06 10:23:02.164 debug xmlrpc <- event ["hm-rpc.3","000BD5699D4DFD:0","UNREACH",false] hm-rpc.3 2018-11-06 10:23:02.164 debug xml multicall <event>: hm-rpc.3,000BD5699D4DFD:0,UNREACH,false hm-rpc.3 2018-11-06 10:23:02.164 debug hm-rpc.3.000BD5699D4DFD.0.LOW_BAT ==> UNIT: "undefined" (min: false, max: true) From "false" => "false" hm-rpc.3 2018-11-06 10:23:02.164 debug xmlrpc <- event ["hm-rpc.3","000BD5699D4DFD:0","LOW_BAT",false] hm-rpc.3 2018-11-06 10:23:02.163 debug xml multicall <event>: hm-rpc.3,000BD5699D4DFD:0,LOW_BAT,false hm-rpc.3 2018-11-06 10:23:02.162 debug hm-rpc.3.000BD5699D4DFD.0.DUTY_CYCLE ==> UNIT: "undefined" (min: false, max: true) From "false" => "false" hm-rpc.3 2018-11-06 10:23:02.162 debug xmlrpc <- event ["hm-rpc.3","000BD5699D4DFD:0","DUTY_CYCLE",false] hm-rpc.3 2018-11-06 10:23:02.161 debug xml multicall <event>: hm-rpc.3,000BD5699D4DFD:0,DUTY_CYCLE,false hm-rpc.3 2018-11-06 10:23:02.161 debug hm-rpc.3.000BD5699D4DFD.0.CONFIG_PENDING ==> UNIT: "undefined" (min: false, max: true) From "false" => "false" hm-rpc.3 2018-11-06 10:23:02.160 debug xmlrpc <- event ["hm-rpc.3","000BD5699D4DFD:0","CONFIG_PENDING",false] hm-rpc.3 2018-11-06 10:23:02.160 debug xml multicall <event>: hm-rpc.3,000BD5699D4DFD:0,CONFIG_PENDING,false</event></event></event></event></event></event></event></event></event></event></event></event></event> -
Hänge mich hier mal dran.
Hatte mir im Oktober einen Lichtsensor HmIP-SLO zugelegt, der aber ständig keine Werte mehr übertragen hat. Nach Werksreset lief er ca. 4 Wochen problemlos, dann fing es wieder an.
Nachdem noch 3 Froll-Rolladenaktoren und ein Bewegungsmelder innen dazu kamen und letzterer auch immer wieder nicht mehr übertragen hat und erstere mit dem SLO Direktverknüpfung klappten, auch wenn er nicht an die CCU2 gesendet hat, war klar, das liegt nicht an den Geräten.
Hab dann diesen Thread gefunden und auch nen Downgrade vom RPC 1.7.8. auf 1.7.6. durchgeführt.
Kurz sah es so aus, als ob alles läuft, aber kurz darauf ging es wieder los, manchmal nach Tagen, manchmal nach 12 Stunden senden die Geräte nicht mehr an die CCU. Wenn ich dann den RPC stoppe, klappt alles kurz darauf wieder und auch z.B. das "Rollo runter bei 20 LUX" wird nachträglich ausgeführt.
Also habe ich gestern mal das Upgrade auf 1.8.0 gewagt, aber heute waren die (nicht-IP) Rollos wieder nicht unten. Adapter aus, Rollos kurz darauf runter.
Das ganze scheint tatsächlich nur die HmIP-Instanz zu betreffen, beim "normalen" HM ist mir diesbezüglich noch nichts aufgefallen.
-
Hallo,
irgendwie passt die Beschreibung zu meinem Fehlerbild
ich habe seit einiger Zeit Probleme dass die HMIP-Sensoren/Gerräte nach einem Reboot des ioBRoker Raspberry Systems nicht mehr aktualisiert werden und den falschen Status anzeigen z.B.Fenster sind geöffnet, in dem Homematic (Raspberrymatic) sind sie aber geschlossen. D.h. die Werte werden nicht mehr richtig von der Raspberymatic an den iobroker übergeben.
Folgende Lösung scheint zu funktioniernen damit die Werte wieder richtig übernommen werden:
-
im Adapter hm-rega.0 das Polling dekativieren.
-
Danach einen Resync im hm-rpc1 (ist bei mir der rpc für die HMIP Geräte) den Haken "Synchronisiere Geräte neu (einmalig)" setzen und "speichern und schließen" drücken.
-
Anschließend im Adpater hm-rega.0 das polling wieder aktivieren, sonst werden die Parameter die im HM-rega vorhanden sind z.B. Duty-Cycle nicht mehr aktualisiert.
Mit dem Geräten der früheren Homamatic (on IP) habe ich bis jetzt zumindest keine Probleme
Andreas
-
-
Bin mir gerade nicht sicher, ob mein Post komplett verständlich war (hab das glaub ich etwas ungenau ausgedrückt), weil bei dir klingt es so, als würden "nur" die Werte an den iobroker nicht korrekt übertragen.
Bei mir senden aber die IP-Geräte auch nicht mehr an die CCU2. Da steht dann gerne um 20 Uhr, dass die letzte Übertragung um z. B. 10:37 Uhr war (mit den entsprechenden vormittäglichen Helligkeitswerten).
Ich hab den Adapter jetzt nochmal aktiviert und werde auf den nächsten "Hänger" warten. Dann kann ich mal im Log schauen, ob um die Uhrzeit der letzten Übertragung herum der Adapter was gemeldet hat und das im GitHub melden.
-
Also, gestern Abend (bzw. heute nach) wars mal wieder soweit:
Um ca. 00:10 Uhr aufgefallen, das der BWM nicht reagiert. In der CCU2 nachgeschaut, letzter Kontakt der beiden Geräte um 00:04.
Beim Lichtsensor wäre das ja vertretbar, aber der BWM müsste ja senden. Habs dann bissl später auch noch getestet, nichts ging.
Das ioBroker-Log gibt ausser der "warn: hm-rpc.0 [KEEPALIVE] Connection timed out" - Meldung (was der cuxd-Adapter ist, gibts nen anderen Thread zu) in diesem Zeitraum auch keine Meldung einen HM-Adapter betreffend raus (und auch sonst nix auffälliges).
In der CCU2 wird dann halt einfach nix geloggt, eine Fehlermeldung oder was auffälliges habe ich dort nicht gefunden.
Oder gibt es noch einen Loglevel dort ausser dem "Systemprotokoll"?
-
Es gibt ein richtiges Log dass man in der Zentralenwartung herunterladen kann.
-
Ich mal wieder zum aktuellen Stand:
Bei einem "Ausfall" hab ich mal die Logs ioBroker & CCU2 gecheckt, wobei mir um den Zeitraum herum nichts aufgefallen ist.
Danach waren die Umstände eines Ausfalls immer unpraktisch zum Auswerten (z.B. eh Absturz des ioBrokers usw)
Dann ist mir mal aufgefallen, dass ich auch einen BWM-Ausfall hatte, obgleich die RPC für IP ausgeschalten war.
Aktueller Stand ist, dass ich noch eine veraltete CUXD-Version auf der CCU2 hatte, diese habe ich jetzt mal einem Update unterzogen, ebenso wie die Node und NPM-Versionen der ioBroker-Installation.
Jetzt läuft das IP-Zeug seit ein paar Tagen (bei ausgeschaltetem RPC-Adapter) ganz gut, werde jetzt den Adapter mal wieder zuschalten und weiter beobachten…
-
Und das wa3rs schon wieder :white_frowning_face:
Komme eben heim, keine Lichter an, programmgesteuerte Rollos oben...
Letzte Meldung
Lichtsensor: 00:04
BWM 00:56
Lichtsensor geht aber, da die HmIP-FROLL per DV runtergefahren wurden.
Zudem habe ich eben mal das Licht auf dem Balkon angeschalten und nach kurzer Zeit wurden die Werte im ioBroker aktualisiert. Jetzt bin ich total verwirrt. Da die Werte ja über die CCU2 an den Broker übertragen werden, scheint die Verbindung zwischen Sensor und CCU2 ja doch zu klappen. Diese zeigt die Werte aber nicht an...
Ein paar Min nach abschalten des RPC geht der BWM wieder, der Lichtsensor zeigt erst nach ca. 10 Minuten wieder was an.
Also, nach wie vor scheint da ein Zusammenhang zu bestehen, 21 Std. angeblich kein Kontakt Gerät - Ccu2, Adapter aus und nach 10 Min geht alles wieder...
Log ioBroker zeigt nicht verdächtiges an, Syslog der CCU2 zeigt nur die letzten paar Minuten an :confused: -
So, nachdem ich dann gestern abend den RPC-Adapter gleich wieder angeschalten habe, waren die letzten Statusmeldungen heute morgen gleich wieder:
BWM ca. 01:02 Uhr (obwohl er gg. 01:30 nochmal hätte auslösen müssen), Lichtsensor 00:29
Das mit dem CCU2-Log lag wohl am Tablet, am PC kann ich es vollständig sehen.
Gg. 11:20 hab ich den RPC-Adapter wieder ausgeschalten, gleich danach ging alles wieder. Hier mal die Logs von 00:20 bis 01:45 und 11:20 bis 11:30:
CCU2
Support us
830
Online32.4k
Users81.5k
Topics1.3m
Posts