Jahresrückblick 2025 – unser neuer Blogbeitrag ist online! ✨

[cdn]

@homoran Okay, dann schaue ich mal, ob ich mir ein solches Szenario bauen kann.
Habe auch das Phänomen in iobroker, dass die Verbindung "zum Host" immer wieder abbricht. Also immer nachdem die Abfrage durch ist, dann ist es disconnected.

Log:

modbus.0
2023-10-10 19:36:23.717	error	Client in error state.
modbus.0
2023-10-10 19:36:23.717	error	Request timed out.

[Homoran]

@cdn sagte in Modbus nur niedrigste ID funktioniert:

dass die Verbindung "zum Host" immer wieder abbricht. Also immer nachdem die Abfrage durch ist, dann ist es disconnected.

och nööö,
warum kommt jetzt erst nach Tagen solche wichtigen Infos, wie in den letzten Minuten

womögöich, nachdem die erste ID geantwortet hat....

[cdn]

@homoran Nené. Das ist auch wenn nur 5 Bewegungsmelder dran hängen und die laufen alle.

[cdn]

@homoran so habe es als erstes einmal mit einem anderen Gerät getestet.

U.S.R. USR-TCP232-410S Serieller zu Ethernet RS232 RS485 auf TCP Modbus RTU zu Modbus TCP Modbus Polling https://amzn.eu/d/963UUj9

Antwortzeiten sind besser, das Problem an sich bleibt aber bestehen. Werde es als nächstes mit dem USB Gerät testen.

[cdn]

@homoran Vielleicht könntest du mir einmal kurz auf die Sprünge helfen: Ich habe ioBroker nun temporär auf Bare Metal migriert und die USB-Version angeschlossen.
Die ersten paar Polls funktionieren, danach erhalte ich nur noch on error bis ich den Adapter neu starte:

modbus.1
2023-10-13 16:47:12.932	warn	On error: {}

modbus.1
2023-10-13 16:47:12.930	warn	On error: {}

modbus.1
2023-10-13 16:47:12.926	debug	connect to serial /dev/ttyUSB0 with 9600

modbus.1
2023-10-13 16:47:11.926	warn	On error: {}

modbus.1
2023-10-13 16:47:11.924	warn	On error: {}

modbus.1
2023-10-13 16:47:11.922	debug	connect to serial /dev/ttyUSB0 with 9600

modbus.1
2023-10-13 16:47:10.924	warn	On error: {}

modbus.1
2023-10-13 16:47:10.921	warn	On error: {}

modbus.1
2023-10-13 16:47:10.918	debug	connect to serial /dev/ttyUSB0 with 9600

modbus.1
2023-10-13 16:47:09.919	warn	On error: {}

modbus.1
2023-10-13 16:47:09.916	warn	On error: {}

modbus.1
2023-10-13 16:47:09.913	debug	connect to serial /dev/ttyUSB0 with 9600

modbus.1
2023-10-13 16:47:08.913	warn	On error: {}

modbus.1
2023-10-13 16:47:08.911	warn	On error: {}

modbus.1
2023-10-13 16:47:08.906	debug	connect to serial /dev/ttyUSB0 with 9600

[Homoran]

@cdn stell mal die Instanz auf debug

edit
ist ja schon!
kommt da nicht mehr?

[cdn]

@homoran steht sie bereits...

[Homoran]

@cdn hab noch editiert!

dann bitte komplett ab Neustart

[cdn]

@homoran

2023-10-13 16:50:03.143 - info: host.FHEM stopInstance system.adapter.modbus.1 (force=false, process=true)
2023-10-13 16:50:03.152 - info: modbus.1 (964) Got terminate signal TERMINATE_YOURSELF
2023-10-13 16:50:03.154 - info: modbus.1 (964) terminating
2023-10-13 16:50:03.156 - debug: modbus.1 (964) Plugin sentry destroyed
2023-10-13 16:50:03.156 - info: modbus.1 (964) Terminated (ADAPTER_REQUESTED_TERMINATION): Without reason
2023-10-13 16:50:03.226 - info: host.FHEM stopInstance system.adapter.modbus.1 send kill signal
2023-10-13 16:50:03.660 - info: modbus.1 (964) terminating
2023-10-13 16:50:03.745 - info: host.FHEM instance system.adapter.modbus.1 terminated with code 11 (ADAPTER_REQUESTED_TERMINATION)
2023-10-13 16:50:06.314 - info: host.FHEM instance system.adapter.modbus.1 started with pid 980
2023-10-13 16:50:07.806 - debug: modbus.1 (980) Redis Objects: Use Redis connection: 127.0.0.1:9001
2023-10-13 16:50:07.862 - debug: modbus.1 (980) Objects client ready ... initialize now
2023-10-13 16:50:07.865 - debug: modbus.1 (980) Objects create System PubSub Client
2023-10-13 16:50:07.867 - debug: modbus.1 (980) Objects create User PubSub Client
2023-10-13 16:50:07.917 - debug: modbus.1 (980) Objects client initialize lua scripts
2023-10-13 16:50:07.930 - debug: modbus.1 (980) Objects connected to redis: 127.0.0.1:9001
2023-10-13 16:50:07.977 - debug: modbus.1 (980) Redis States: Use Redis connection: 127.0.0.1:9000
2023-10-13 16:50:07.992 - debug: modbus.1 (980) States create System PubSub Client
2023-10-13 16:50:07.993 - debug: modbus.1 (980) States create User PubSub Client
2023-10-13 16:50:08.065 - debug: modbus.1 (980) States connected to redis: 127.0.0.1:9000
2023-10-13 16:50:08.158 - debug: modbus.1 (980) Plugin sentry Initialize Plugin (enabled=true)
2023-10-13 16:50:08.706 - info: modbus.1 (980) starting. Version 5.0.11 in /opt/iobroker/node_modules/iobroker.modbus, node: v18.18.1, js-controller: 5.0.12
2023-10-13 16:50:08.918 - debug: modbus.1 (980) Initialize Objects for disInputs: []
2023-10-13 16:50:08.920 - debug: modbus.1 (980) Initialize Objects for coils: []
2023-10-13 16:50:08.920 - debug: modbus.1 (980) Initialize Objects for inputRegs: []
2023-10-13 16:50:08.921 - debug: modbus.1 (980) Initialize Objects for holdingRegs: [{"_address":"6","name":"","description":"","unit":"","type":"uint16be","len":1,"factor":1,"offset":0,"formula":"","role":"state","room":"","poll":true,"wp":"","cw":"","isScale":"","address":6,"deviceId":25,"id":"holdingRegisters.25.6"}]
2023-10-13 16:50:08.922 - debug: modbus.1 (980) Add holdingRegisters.25.6: {"_id":"holdingRegisters.25.6","type":"state","common":{"name":"","role":"state","type":"number","read":true,"write":true,"def":0,"unit":""},"native":{"regType":"holdingRegs","address":6,"deviceId":25,"type":"uint16be","len":1,"offset":0,"factor":1,"poll":true}}
2023-10-13 16:50:09.292 - debug: modbus.1 (980) connect to serial /dev/ttyUSB0 with 9600
2023-10-13 16:50:09.301 - info: modbus.1 (980) Connected to slave
2023-10-13 16:50:09.302 - debug: modbus.1 (980) [DevID_25] Poll start ---------------------
2023-10-13 16:50:09.305 - debug: modbus.1 (980) Initialization of scale factors done!
2023-10-13 16:50:09.306 - debug: modbus.1 (980) [DevID_25/holdingRegs] Poll address 6 - 1 registers
2023-10-13 16:50:09.353 - debug: modbus.1 (980) [DevID_25/holdingRegs] Poll address 6 DONE
2023-10-13 16:50:10.412 - debug: modbus.1 (980) [DevID_25] Poll start ---------------------
2023-10-13 16:50:10.413 - debug: modbus.1 (980) [DevID_25/holdingRegs] Poll address 6 - 1 registers
2023-10-13 16:50:10.469 - debug: modbus.1 (980) [DevID_25/holdingRegs] Poll address 6 DONE
2023-10-13 16:50:11.525 - debug: modbus.1 (980) [DevID_25] Poll start ---------------------
2023-10-13 16:50:11.527 - debug: modbus.1 (980) [DevID_25/holdingRegs] Poll address 6 - 1 registers
2023-10-13 16:50:11.556 - debug: modbus.1 (980) [DevID_25/holdingRegs] Poll address 6 DONE
2023-10-13 16:50:12.611 - debug: modbus.1 (980) [DevID_25] Poll start ---------------------
2023-10-13 16:50:12.613 - debug: modbus.1 (980) [DevID_25/holdingRegs] Poll address 6 - 1 registers
2023-10-13 16:50:12.643 - debug: modbus.1 (980) [DevID_25/holdingRegs] Poll address 6 DONE
2023-10-13 16:50:13.697 - debug: modbus.1 (980) [DevID_25] Poll start ---------------------
2023-10-13 16:50:13.699 - debug: modbus.1 (980) [DevID_25/holdingRegs] Poll address 6 - 1 registers
2023-10-13 16:50:13.763 - debug: modbus.1 (980) [DevID_25/holdingRegs] Poll address 6 DONE
2023-10-13 16:50:14.818 - debug: modbus.1 (980) [DevID_25] Poll start ---------------------
2023-10-13 16:50:14.820 - debug: modbus.1 (980) [DevID_25/holdingRegs] Poll address 6 - 1 registers
2023-10-13 16:50:14.882 - debug: modbus.1 (980) [DevID_25/holdingRegs] Poll address 6 DONE
2023-10-13 16:50:15.939 - debug: modbus.1 (980) [DevID_25] Poll start ---------------------
2023-10-13 16:50:15.941 - debug: modbus.1 (980) [DevID_25/holdingRegs] Poll address 6 - 1 registers
2023-10-13 16:50:16.000 - debug: modbus.1 (980) [DevID_25/holdingRegs] Poll address 6 DONE
2023-10-13 16:50:17.054 - debug: modbus.1 (980) [DevID_25] Poll start ---------------------
2023-10-13 16:50:17.055 - debug: modbus.1 (980) [DevID_25/holdingRegs] Poll address 6 - 1 registers
2023-10-13 16:50:17.120 - debug: modbus.1 (980) [DevID_25/holdingRegs] Poll address 6 DONE
2023-10-13 16:50:18.174 - debug: modbus.1 (980) [DevID_25] Poll start ---------------------
2023-10-13 16:50:18.176 - debug: modbus.1 (980) [DevID_25/holdingRegs] Poll address 6 - 1 registers
2023-10-13 16:50:18.240 - debug: modbus.1 (980) [DevID_25/holdingRegs] Poll address 6 DONE
2023-10-13 16:50:19.297 - debug: modbus.1 (980) [DevID_25] Poll start ---------------------
2023-10-13 16:50:19.298 - debug: modbus.1 (980) [DevID_25/holdingRegs] Poll address 6 - 1 registers
2023-10-13 16:50:19.358 - debug: modbus.1 (980) [DevID_25/holdingRegs] Poll address 6 DONE
2023-10-13 16:50:20.411 - debug: modbus.1 (980) [DevID_25] Poll start ---------------------
2023-10-13 16:50:20.413 - debug: modbus.1 (980) [DevID_25/holdingRegs] Poll address 6 - 1 registers
2023-10-13 16:50:20.477 - debug: modbus.1 (980) [DevID_25/holdingRegs] Poll address 6 DONE
2023-10-13 16:50:21.534 - debug: modbus.1 (980) [DevID_25] Poll start ---------------------
2023-10-13 16:50:21.535 - debug: modbus.1 (980) [DevID_25/holdingRegs] Poll address 6 - 1 registers
2023-10-13 16:50:21.596 - debug: modbus.1 (980) [DevID_25/holdingRegs] Poll address 6 DONE
2023-10-13 16:50:22.653 - debug: modbus.1 (980) [DevID_25] Poll start ---------------------
2023-10-13 16:50:22.655 - debug: modbus.1 (980) [DevID_25/holdingRegs] Poll address 6 - 1 registers
2023-10-13 16:50:22.716 - debug: modbus.1 (980) [DevID_25/holdingRegs] Poll address 6 DONE
2023-10-13 16:50:23.770 - debug: modbus.1 (980) [DevID_25] Poll start ---------------------
2023-10-13 16:50:23.772 - debug: modbus.1 (980) [DevID_25/holdingRegs] Poll address 6 - 1 registers
2023-10-13 16:50:23.834 - debug: modbus.1 (980) [DevID_25/holdingRegs] Poll address 6 DONE
2023-10-13 16:50:24.887 - debug: modbus.1 (980) [DevID_25] Poll start ---------------------
2023-10-13 16:50:24.892 - debug: modbus.1 (980) [DevID_25/holdingRegs] Poll address 6 - 1 registers
2023-10-13 16:50:24.954 - debug: modbus.1 (980) [DevID_25/holdingRegs] Poll address 6 DONE
2023-10-13 16:50:26.009 - debug: modbus.1 (980) [DevID_25] Poll start ---------------------
2023-10-13 16:50:26.011 - debug: modbus.1 (980) [DevID_25/holdingRegs] Poll address 6 - 1 registers
2023-10-13 16:50:26.073 - debug: modbus.1 (980) [DevID_25/holdingRegs] Poll address 6 DONE
2023-10-13 16:50:27.128 - debug: modbus.1 (980) [DevID_25] Poll start ---------------------
2023-10-13 16:50:27.130 - debug: modbus.1 (980) [DevID_25/holdingRegs] Poll address 6 - 1 registers
2023-10-13 16:50:27.192 - debug: modbus.1 (980) [DevID_25/holdingRegs] Poll address 6 DONE
2023-10-13 16:50:28.248 - debug: modbus.1 (980) [DevID_25] Poll start ---------------------
2023-10-13 16:50:28.249 - debug: modbus.1 (980) [DevID_25/holdingRegs] Poll address 6 - 1 registers
2023-10-13 16:50:33.254 - warn: modbus.1 (980) Error: undefined
2023-10-13 16:50:33.256 - error: modbus.1 (980) Request timed out.
2023-10-13 16:50:33.258 - warn: modbus.1 (980) Poll error count: 1 code: {"err":"timeout","timeout":5000}
2023-10-13 16:50:34.261 - info: modbus.1 (980) Disconnected from slave
2023-10-13 16:50:34.266 - debug: modbus.1 (980) Clearing timeout of the current request.
2023-10-13 16:50:34.267 - debug: modbus.1 (980) Cleaning up request fifo.
2023-10-13 16:50:35.265 - debug: modbus.1 (980) connect to serial /dev/ttyUSB0 with 9600
2023-10-13 16:50:35.273 - warn: modbus.1 (980) On error: {}
2023-10-13 16:50:35.276 - info: modbus.1 (980) Connected to slave
2023-10-13 16:50:35.278 - debug: modbus.1 (980) [DevID_25] Poll start ---------------------
2023-10-13 16:50:35.279 - debug: modbus.1 (980) [DevID_25/holdingRegs] Poll address 6 - 1 registers
2023-10-13 16:50:35.280 - debug: modbus.1 (980) connect to serial /dev/ttyUSB0 with 9600
2023-10-13 16:50:35.283 - warn: modbus.1 (980) On error: {}
2023-10-13 16:50:36.275 - info: modbus.1 (980) Disconnected from slave
2023-10-13 16:50:34.266 - debug: modbus.1 (980) Clearing timeout of the current request.
2023-10-13 16:50:34.267 - debug: modbus.1 (980) Cleaning up request fifo.
2023-10-13 16:50:35.265 - debug: modbus.1 (980) connect to serial /dev/ttyUSB0 with 9600
2023-10-13 16:50:35.273 - warn: modbus.1 (980) On error: {}
2023-10-13 16:50:35.276 - info: modbus.1 (980) Connected to slave
2023-10-13 16:50:35.278 - debug: modbus.1 (980) [DevID_25] Poll start ---------------------
2023-10-13 16:50:35.279 - debug: modbus.1 (980) [DevID_25/holdingRegs] Poll address 6 - 1 registers
2023-10-13 16:50:35.280 - debug: modbus.1 (980) connect to serial /dev/ttyUSB0 with 9600
2023-10-13 16:50:35.283 - warn: modbus.1 (980) On error: {}
2023-10-13 16:50:36.275 - info: modbus.1 (980) Disconnected from slave
2023-10-13 16:50:36.277 - warn: modbus.1 (980) On error: {}
2023-10-13 16:50:37.278 - debug: modbus.1 (980) connect to serial /dev/ttyUSB0 with 9600
2023-10-13 16:50:37.282 - warn: modbus.1 (980) On error: {}
2023-10-13 16:50:37.287 - warn: modbus.1 (980) On error: {}
2023-10-13 16:50:38.287 - debug: modbus.1 (980) connect to serial /dev/ttyUSB0 with 9600
2023-10-13 16:50:38.291 - warn: modbus.1 (980) On error: {}
2023-10-13 16:50:38.296 - warn: modbus.1 (980) On error: {}
2023-10-13 16:50:39.296 - debug: modbus.1 (980) connect to serial /dev/ttyUSB0 with 9600
2023-10-13 16:50:39.301 - warn: modbus.1 (980) On error: {}
2023-10-13 16:50:39.303 - warn: modbus.1 (980) On error: {}
2023-10-13 16:50:40.281 - warn: modbus.1 (980) Error: undefined
2023-10-13 16:50:40.282 - error: modbus.1 (980) Request timed out.
2023-10-13 16:50:40.301 - debug: modbus.1 (980) connect to serial /dev/ttyUSB0 with 9600
2023-10-13 16:50:40.304 - warn: modbus.1 (980) On error: {}
2023-10-13 16:50:40.305 - warn: modbus.1 (980) On error: {}
2023-10-13 16:50:41.304 - debug: modbus.1 (980) connect to serial /dev/ttyUSB0 with 9600
2023-10-13 16:50:41.310 - warn: modbus.1 (980) On error: {}
2023-10-13 16:50:41.312 - warn: modbus.1 (980) On error: {}

Nicht über FHEM wundern. Hatte hier noch alte Hardware zum testen rumfliegen ;-)

[Homoran]

@cdn nicht dauernd editieten, während ich versuche die Zeilen zu verstehen :-)

du pollst jede Sekunde?
nach 20 Sekunden knallts?

der Timeout greift nach 5 Sekunden, dazwischen wurden aber weitere Polls abgesetzt

hast du immer noch die selben Grundeinstellungen?
und immer noch alle tausend Adressen?

oder pollst du nur device ID 6?

[cdn]

@homoran Nein ich habe zum testen eine frische Instanz mit nur einer Adresse erstellt und den Grundeinstellungen.
Nur die Wartezeitzum erneuten verbinden habe ich runtergeschraubt auf 1s.
Sorry, wollte nur noch etwas hinzufügen, damit nachher nichts fehlt :-)

[Homoran]

@cdn wieviele Geräte hängen am Bus und wie sind die verdrahtet?

[cdn]

@homoran Natürlich keine Sternverdrathung. Da habe ich mich dran gehalten :-) Es sind 13 Geräte gerade.
Interessant ist: Den Fehler hatte ich mit den beiden anderen RS485 2 ETH Geräten nicht.
Sobald ich die Instanz dann wieder neustarte klappt es dann ja auch ohne Probleme.

[Homoran]

@cdn sagte in Modbus nur niedrigste ID funktioniert:

klappt es dann ja auch ohne Probleme.

kommen auch Werte an?
sieht man im log leider nicht?

wieso nur Holdingregister?
du schreibst doch nichts.

aber 20 sekunden bis zum Error, der mit 5 Sekunden delay, sind gerade mal 15 polls.

möglich wäre noch ein Problem mit USB

[cdn]

@homoran Ähm ja was soll ich denn sonst nehmen, anstatt holding register? Ich möchte nur die Werte auslesen.
USB-Port habe ich schon 3 Stück durchprobiert. Dort hing bis vor kurzem ein CUL dran der ohne Probleme funktioniert hat.

Ja Werte kommen an.

[bahnuhr]

@cdn sagte in Modbus nur niedrigste ID funktioniert:

Ähm ja was soll ich denn sonst nehmen, anstatt holding register?

Wäre nicht

das richtige ?!

[Homoran]

@cdn sagte in Modbus nur niedrigste ID funktioniert:

Ich möchte nur die Werte auslesen.

dafür sind die Inputregister

Holdingregister sind schreibregister

[Homoran]

@cdn sagte in Modbus nur niedrigste ID funktioniert:

USB-Port habe ich schon 3 Stück durchprobiert. Dort hing bis vor kurzem ein CUL dran der ohne Probleme funktioniert hat.

der CUL wird nicht mit dieser Datenrate gearbeitet haben.

Apropos: hast du mal die Baudrate hochgesetzt (oder runter, solange nur eine Adresse läuft)

[cdn]

@homoran Da klappt es gar nicht.

[cdn]

@homoran Die Melder die an dem Bus hängen unterstützen nur 9600 laut Hersteller.