MQTT Adapter 3.0.6 und 4.0 keine Verbindung mehr [gelöst]

[mickym]

@wildbill Nochmal - hat mit Bridge etc- alles nichts zu tun. Unten siehst Du wie es unter dem Adapter 2.7.4 alles super läuft. Sobald Adapter Update - kann sich der Adapter nicht mehr verbinden. mosquitto hat damit 0,0 zu tun - das wurde nicht mal gestoppt. (habe ich zwar auch mal getestet).

Aber wenn es direkt mit Port 1883 von iobroker zu mosquitto läuft, dann kann doch eigentlich der Adapter nichts dafür, .....

tut es eben nicht - das unten ist das Log mit dem Adapter 2.7.4 unter 3.0.6 oder 4.0 bekommst Du nur das in Dauerschleife:

1652392625: New connection from 127.0.0.1:39242 on port 1883.
1652392625: Client <unknown> disconnected, not authorised.
1652392634: New connection from 127.0.0.1:39244 on port 1883.
1652392634: Client <unknown> disconnected, not authorised.
1652392644: New connection from 127.0.0.1:39246 on port 1883.
1652392644: Client <unknown> disconnected, not authorised.

Ich werde mal versuchen - eine 2 Kennung in mosquitto mit simplem Passwort anzulegen und dann erst mit 2.7.4 Funktion testen und anschliessend updaten. Wenn es an dem Passwort liegt - dann wurde hier aber bei der Implementierung daneben gelangt.

[Wildbill]

@mickym sagte in MQTT Adapter 3.0.6 und 4.0 keine Verbindung mehr:
Das hattest Du oben geschrieben und deshalb hatte ich es so verstanden, dass die Verbindung direkt zu mosquitto auf Port 1883 auch nach dem Update funktioniert:

Nun mein mosquitto ist eigentlich normal, ausser dass es 2 Verbindungen hat, einen zu einer mosquitto instanz als Bridge und einmal normal. Über 1883 ist aber alles normal und es läuft ja auch mit der Version 2.x schon seit Jahren 2 Client Instanzen im iobroker.

Ich kenne mich mit dem bridge-Zeug aber wirklich 0,0 aus und sehe auch keinen Bedarf, das jetzt zu testen oder mich da einzulesen. Bei mir läuft es mit simplem direkten Zugriff mit user/password direkt über Port 1883 ohne Probleme. Das würde ich testen, wenn es dann geht, sehe ich das Problem zumindest nicht 100% beim Adapter.
Dann solltest Du im Issue eher angeben, dass es Problem mit mosquitto-bridge gibt, nicht direkt beim Zusammenspiel mit mosquitto.

Gruss, Jürgen

[mickym]

Hmm ich glaube ich bin dem Fehler auf der Spur, da ich den nun auch unter 2.7.4 bekomme, nachdem ich eine neue Kennung im mosquitto angelegt habe.

Es war bis dato wohl nie ein Problem, da alles korrekt von den Vorgängerversionen übernommen wurde - wobei es jetzt unter 2.7.4 mit einer neuen Kennung erst nicht und nun wieder funktioniert - wobei der Dialog weiterhin ein Fehler beim Verbindungstest anzeugt.

[mickym]

@wildbill Wie gesagt mit der Bridge hat es nichts zu tun, ich bekomme den Fehler nun auch - nur nachdem ich das Kennwort und einen anderen User hinzugefügt habe.

[Wildbill]

@mickym Dann passt aber wohl doch mit Deinem mosquitto was nicht?! Wie geschrieben, bei mir geht es mit dem aktuellen Adapter auf mosquitto mit user/password nach wie vor problemlos.

Gruss, Jürgen

[mickym]

@wildbill Nein - liegt definitiv nicht am mosquitto - zumindest unter 2.7.4 funktioniert die Verbindung immer noch - ABER:
wenn man den Verbindungstest in der Adapter config mitgibt, dann wird wohl nicht die ID mitgegeben und da zeigt auch das Fenster einen ERROR an.

Anscheinend kommen erst mal die Fehlermeldung unter 2.7.4. nur von dem Verbindungstest:

2022-05-14 15:09:58.133 - warn: mqtt.2 (918) Error on mqtt test: Error: Connection refused: Not authorized
2022-05-14 15:11:05.713 - info: mqtt.1 (28175) send2Server mqtt.1.logic.states.sleep[logic/states/sleep]
2022-05-14 15:12:48.034 - info: host.MWHome stopInstance system.adapter.mqtt.1 (force=false, process=true)
2022-05-14 15:12:48.112 - info: mqtt.1 (28175) Got terminate signal TERMINATE_YOURSELF
2022-05-14 15:12:48.116 - info: mqtt.1 (28175) Disconnected from localhost: undefined
2022-05-14 15:12:48.121 - info: host.MWHome stopInstance system.adapter.mqtt.1 send kill signal
2022-05-14 15:12:48.616 - info: mqtt.1 (28175) terminating
2022-05-14 15:12:48.617 - info: mqtt.1 (28175) Terminated (ADAPTER_REQUESTED_TERMINATION): Without reason
2022-05-14 15:12:49.125 - info: host.MWHome stopInstance system.adapter.mqtt.1 killing pid 28175
2022-05-14 15:12:49.267 - info: host.MWHome instance system.adapter.mqtt.1 terminated with code 11 (ADAPTER_REQUESTED_TERMINATION)
2022-05-14 15:12:49.471 - info: host.MWHome stopInstance system.adapter.mqtt.2 (force=false, process=true)
2022-05-14 15:12:49.638 - info: mqtt.2 (918) Got terminate signal TERMINATE_YOURSELF
2022-05-14 15:12:49.643 - info: mqtt.2 (918) Disconnected from localhost: undefined
2022-05-14 15:12:49.653 - info: host.MWHome stopInstance system.adapter.mqtt.2 send kill signal
2022-05-14 15:12:50.142 - info: mqtt.2 (918) terminating
2022-05-14 15:12:50.144 - info: mqtt.2 (918) Terminated (ADAPTER_REQUESTED_TERMINATION): Without reason
2022-05-14 15:12:50.654 - info: host.MWHome stopInstance system.adapter.mqtt.2 killing pid 918
2022-05-14 15:12:50.930 - info: host.MWHome instance system.adapter.mqtt.2 terminated with code 11 (ADAPTER_REQUESTED_TERMINATION)
2022-05-14 15:12:51.301 - info: host.MWHome instance system.adapter.mqtt.1 started with pid 2412
2022-05-14 15:12:52.956 - info: host.MWHome instance system.adapter.mqtt.2 started with pid 2431
2022-05-14 15:12:56.473 - info: mqtt.1 (2412) starting. Version 2.7.4 in /opt/iobroker/node_modules/iobroker.mqtt, node: v14.19.2, js-controller: 4.0.23
2022-05-14 15:12:57.564 - info: mqtt.1 (2412) Try to connect to mqtt://mwmqtt4iob:*******************@localhost:1883?clientId=mqttClientIobrokerPublishSubscribeAll
2022-05-14 15:12:57.799 - info: mqtt.1 (2412) Connected to localhost
2022-05-14 15:12:57.802 - info: mqtt.1 (2412) Subscribe on: "#"
2022-05-14 15:13:01.507 - info: mqtt.2 (2431) starting. Version 2.7.4 in /opt/iobroker/node_modules/iobroker.mqtt, node: v14.19.2, js-controller: 4.0.23
2022-05-14 15:13:02.575 - info: mqtt.2 (2431) Try to connect to mqtt://mwmqtt4iob:*******************@localhost:1883?clientId=mqttClientIobrokerPublishOnly
2022-05-14 15:13:02.854 - info: mqtt.2 (2431) Connected to localhost
2022-05-14 15:13:02.887 - info: mqtt.2 (2431) Subscribe on: "iobroker/#"
2022-05-14 15:13:05.797 - info: mqtt.2 (2431) All states published

Siehe 1. Meldung - dann alle mqtt instanzen gestoppt und neu gestartet - läuft alles auch unter der neuen Kennung.

Auch mosquitto log alles ruhig. Wahrscheinlich ist aber der fehlerhafte Verbindungstest bereits ein Indiz für die fehlerhafte Verbindung. Ich deaktiviere mal die Bridge und schaue ob sich was verändert. Dann wäre es aber immer noch ein Problem des Adapters, da alle anderen Geräte kein Problem haben. Ich habe ja nicht nur zu den mqtt- Adaptern eine funktionierende Verbindung.

Update: so ich habe den mosquitto ohne Bridge laufen - gleiches Problem beim Verbindungstest:

2022-05-14 15:27:36.516 - warn: mqtt.1 (2412) Error on mqtt test: Error: Connection refused: Not authorized
2022-05-14 15:28:00.552 - info: host.MWHome stopInstance system.adapter.mqtt.1 (force=false, process=true)
2022-05-14 15:28:00.561 - info: mqtt.1 (2412) Got terminate signal TERMINATE_YOURSELF
2022-05-14 15:28:00.563 - info: host.MWHome stopInstance system.adapter.mqtt.1 send kill signal
2022-05-14 15:28:00.565 - info: mqtt.1 (2412) Disconnected from localhost: undefined
2022-05-14 15:28:01.065 - info: mqtt.1 (2412) terminating
2022-05-14 15:28:01.067 - info: mqtt.1 (2412) Terminated (ADAPTER_REQUESTED_TERMINATION): Without reason
2022-05-14 15:28:01.757 - info: host.MWHome stopInstance system.adapter.mqtt.1 killing pid 2412
2022-05-14 15:28:01.948 - info: host.MWHome instance system.adapter.mqtt.1 terminated with code 11 (ADAPTER_REQUESTED_TERMINATION)
2022-05-14 15:28:03.824 - info: host.MWHome instance system.adapter.mqtt.1 started with pid 7103
2022-05-14 15:28:07.928 - info: mqtt.1 (7103) starting. Version 2.7.4 in /opt/iobroker/node_modules/iobroker.mqtt, node: v14.19.2, js-controller: 4.0.23
2022-05-14 15:28:08.812 - info: mqtt.1 (7103) Try to connect to mqtt://mwmqtt4iob:*******************@localhost:1883?clientId=mqttClientIobrokerPublishSubscribeAll
2022-05-14 15:28:09.023 - info: mqtt.1 (7103) Connected to localhost
2022-05-14 15:28:09.026 - info: mqtt.1 (7103) Subscribe on: "#"
2022-05-14 15:28:20.330 - warn: mqtt.1 (7103) Error on mqtt test: Error: Connection refused: Not authorized
2022-05-14 15:28:21.659 - info: mqtt.1 (7103) send2Server mqtt.1.logic.states.sleep[undefined]
2022-05-14 15:28:21.663 - info: mqtt.1 (7103) send2Server mqtt.1.logic.states.sleep[logic/states/sleep]
2022-05-14 15:28:24.550 - info: host.MWHome stopInstance system.adapter.mqtt.2 (force=false, process=true)
2022-05-14 15:28:24.662 - info: mqtt.2 (2431) Got terminate signal TERMINATE_YOURSELF
2022-05-14 15:28:24.672 - info: host.MWHome stopInstance system.adapter.mqtt.2 send kill signal
2022-05-14 15:28:24.676 - info: mqtt.2 (2431) Disconnected from localhost: undefined
2022-05-14 15:28:25.174 - info: mqtt.2 (2431) terminating
2022-05-14 15:28:25.177 - info: mqtt.2 (2431) Terminated (ADAPTER_REQUESTED_TERMINATION): Without reason
2022-05-14 15:28:25.675 - info: host.MWHome stopInstance system.adapter.mqtt.2 killing pid 2431
2022-05-14 15:28:26.070 - info: host.MWHome instance system.adapter.mqtt.2 terminated with code 11 (ADAPTER_REQUESTED_TERMINATION)
2022-05-14 15:28:27.876 - info: host.MWHome instance system.adapter.mqtt.2 started with pid 7232
2022-05-14 15:28:29.610 - info: node-red.0 (1553) 14 May 15:28:29 - [info] [debug:set fMWDesktop] true
2022-05-14 15:28:29.614 - info: node-red.0 (1553) 14 May 15:28:29 - [info] [debug:set fMWDesktop] true
2022-05-14 15:28:29.730 - info: node-red.0 (1553) 14 May 15:28:29 - [info] [debug:logic/states/presentDefault] true
2022-05-14 15:28:29.735 - info: node-red.0 (1553) 14 May 15:28:29 - [info] [debug:logic/states/presentDefault] true
2022-05-14 15:28:32.021 - info: mqtt.2 (7232) starting. Version 2.7.4 in /opt/iobroker/node_modules/iobroker.mqtt, node: v14.19.2, js-controller: 4.0.23
2022-05-14 15:28:32.699 - info: mqtt.2 (7232) Try to connect to mqtt://mwmqtt4iob:*******************@localhost:1883?clientId=mqttClientIobrokerPublishOnly
2022-05-14 15:28:32.893 - info: mqtt.2 (7232) Connected to localhost
2022-05-14 15:28:32.896 - info: mqtt.2 (7232) Subscribe on: "iobroker/#"
2022-05-14 15:28:35.648 - info: mqtt.2 (7232) All states published

Was mich nur wundert - ist auch hier beim Stoppen des Adapters - Disconnect from localhost:undefined

[Wildbill]

@mickym Ich kann Dir ehrlich gesagt nicht mehr ganz folgen. Mal schriebst Du, es funktioniert über 1883 alles normal, dann funktioniert es weder mit neuem user/password, dann hast Du auch unter 2.7.4 Fehlermeldungen?!
Ich denke, ich kann Dir hier nicht wirklich eine Hilfe sein. Wenn es nichtmal mit normalem user/password direkt ohne bridge funktioniert, egal ob Adapter 2.x oder 3.x, dann muss da was bei Dir krumm sein. Bei mir geht es ja, und bei anderen wohl auch, sonst wäre das Forum und dieser Thread voller Meldungen.
Was ich allerdings nie verwendet habe ist der Verbindungstest in der Adapter-Konfig. Wenn die Daten in alle Richtungen übertragen werden und korrekt ankommen, dann passt die Verbindung für mich. Eventuell ist ja nur da was faul und bei der eigentlichen Funktion als mqtt-client oder broker passt es dennoch?!
Gruss, Jürgen

[mickym]

@wildbill sagte in MQTT Adapter 3.0.6 und 4.0 keine Verbindung mehr:

Was ich allerdings nie verwendet habe ist der Verbindungstest in der Adapter-Konfig. Wenn die Daten in alle Richtungen übertragen werden und korrekt ankommen, dann passt die Verbindung für mich. Eventuell ist ja nur da was faul und bei der eigentlichen Funktion als mqtt-client oder broker passt es dennoch?!

Ja so sieht es unter 2.7.4. aus - es läuft alles nur der Verbindungstest macht Mucken und bei dem Disconnect kommt irgendwie bei localhost unknown - aber sonst passt alles. Ab wann der Verbindungstest Probleme macht - kann ich nicht sagen, da ich den ja nicht mehr benötigt habe. Ich probiere nun nochmal ein Update, aber wie gesagt irgendwas stimmt trotzdem mit dem Adapter nicht mehr und wenn mosquitto zu mehr als 40 devices kein Problem hat und nur zum iobroker Adapter - dann würde ich auf den Adapter tippen. Wie gesagt ob Bridge oder nicht spielt keine Rolle und hab es getestet.

Ich kann Dir ehrlich gesagt nicht mehr ganz folgen. Mal schriebst Du, es funktioniert über 1883 alles normal, dann funktioniert es weder mit neuem user/password, dann hast Du auch unter 2.7.4 Fehlermeldungen?!

Nun ich versuche ja das ganze einzugrenzen.

Um wieder hier wieder auf den aktuellen Stand zu bringen.

Nach Benutzerwechsel funktioniert auf 2.7.4 wieder alles, nur Verbindungstest macht Mucken. Werde jetzt nochmal auf 3.0.6 updaten.

[mickym]

Ok ich habe nun nochmals auf 3.0.6 aktualisiert und nun scheint alles zu funktionieren - inkl. Verbindungstest.

Bei dem Verbindungstest gibt nun der Adapter die ID mit - das hat er vorher nicht gemacht.

1652537744: New connection from 127.0.0.1:48164 on port 1883.
1652537744: New client connected from 127.0.0.1:48164 as mqttClientIobrokerPublishSubscribeAll (p2, c1, k1000, u'mwmqtt4iob').
1652537888: New connection from 127.0.0.1:48166 on port 1883.
1652537888: New client connected from 127.0.0.1:48166 as mqttClientIobrokerPublishOnly (p2, c1, k1000, u'mwmqtt4iob').
1652537981: Client mqttClientIobrokerPublishSubscribeAll disconnected.
1652537987: New connection from 127.0.0.1:48168 on port 1883.
1652537987: New client connected from 127.0.0.1:48168 as mqttClientIobrokerPublishSubscribeAll (p2, c1, k1000, u'mwmqtt4iob').
1652538051: New connection from 127.0.0.1:48170 on port 1883.
1652538051: New client connected from 127.0.0.1:48170 as ioBroker.mqtt.1 (p2, c1, k60, u'mwmqtt4iob').
1652538051: Client ioBroker.mqtt.1 disconnected.
1652538134: New connection from 127.0.0.1:48172 on port 1883.
1652538134: New client connected from 127.0.0.1:48172 as ioBroker.mqtt.1 (p2, c1, k60, u'mwmqtt4iob').
1652538134: Client ioBroker.mqtt.1 disconnected.
1652538246: New connection from 127.0.0.1:48174 on port 1883.
1652538246: New client connected from 127.0.0.1:48174 as ioBroker.mqtt.2 (p2, c1, k60, u'mwmqtt4iob').
1652538246: Client ioBroker.mqtt.2 disconnected.

Fazit; Beim 1. Update ist wohl was schief gelaufen, was nun aber funktioniert.

@Wildbill : Danke für Dein "Mitleiden" - aber durch Deine Anregungen habe ich das Ganze nochmals gemacht und nun geht es mit 3.0.6 - keine Ahnung was sich beim letzten Mal verhängt hat. Kann sein, dass der Browser auch gesponnen hat, weil die Masken damals zur Neueingabe der Passwörter nicht funktioniert hatten. Ausserdem habe ich dieses Mal vor dem Update manuell alle Instanzen gestoppt und das Passwort neu eingetragen, bevor ich die Instanzen nach dem Update neu eingetragen habe. Keine Ahnung was passiert war. Ach und funktioniert auch mit Bridge.

Ein Problem habe ich allerdings noch - auch wenn es funktioniert unter 3.0.6 sehe ich die published states nicht mehr.

[Wildbill]

@mickym Die published states sehe ich auch nicht. Ist mir aber gar nicht aufgefallen, da ich da wohl nie was eingetragen hatte. Das wäre dann wohl ein Issue wert.
Aber gut, dass es jetzt wenigstens wieder läuft bei Dir.

Gruss, Jürgen