OpenLuup unavailable
-
A short status update.
Yesterday evening I updated OpenLupp to v21.3.26 and LuaFileSystem to 1.8.0.
I have since then left the system up and running as-is, i.e. without Mqtt re-enabled and with most of the http updates from my various Tasmota devices turned off.So far so good, the system seems as responsive as it has been earlier for the past approx 20 hours.
I have not changed the http server waiting time, it is at the default value for v21.3.26.When looking in the logs, it looks ok. Usually quite fast responses and no errors.
I found two similar lines that had a bit longer response time:
2002 lines, 0 errors, max gap 41s @ 2021-03-28 17:30:00.131 2021-03-28 17:29:18.910 luup.variable_set:: 10909.urn:micasaverde-com:serviceId:HaDevice1.sl_BatteryAlarm was: 0 now: 0 #hooks:0 2021-03-28 17:30:00.131 luup_log:0: 40Mb, 3.5%cpu, 0.8days 2021-03-28 17:30:00.646 luup.variable_set:: 68.urn:toggledbits-com:serviceId:ReactorSensor.cstate was: {"cond0":{"evaledge":[],"stateedge":[],"valuestamp":1615722020,"id":"cond0","statestamp":0,"lastvalue":0},"vars":{"... now: {"cond0":{"evaledge":[],"stateedge":[],"valuestamp":1615722020,"id":"cond0","statestamp":0,"lastvalue":0},"vars":{"... #hooks:0 2002 lines, 0 errors, max gap 65s @ 2021-03-28 16:54:07.724 2021-03-28 16:53:02.682 luup.variable_set:: 244.urn:toggledbits-com:serviceId:VirtualSensor1.LastUpdate was: 1616943122 now: 1616943182 #hooks:0 2021-03-28 16:54:07.724 luup_log:0: 44Mb, 1.5%cpu, 0.7days 2021-03-28 16:54:07.755 luup.variable_set:: 122.urn:toggledbits-com:serviceId:DelayLightTimer.Message was: Delay Off 9m now: Delay Off 8m #hooks:0
I will keep off the system until tomorrow to see that it is stable over time before I restart the Mqtt server.
-
Nothing to worry about with those logs. Anything under 120s is fine.
-
ArcherSreplied to ArcherS on Mar 28, 2021, 6:49 PM last edited by ArcherS Mar 28, 2021, 3:57 PM
I looked in the logs again after I had some slow responses in the system and found some errors. The IP address 192.168.1.127 this my iPhone and Homewave. I tested with Homewave on my iPad and got a similar set of errors for that IP address. These errors seem to be in the direction of what @rafale77 mentioned.
Edit: Still had some errors so I changed it to 0.1 which seems to give even fewer errors.
I changed the http server wait to0.20.1 from 1.0 withluup.attr_set ("openLuup.HTTP.SelectWait", 0.1)
and it seems as if it may have helped removing the errors. I will check some more later on and see if the errors return.From the log file:
2021-03-28 19:58:23.148 openLuup.server:: error 'closed' sending 2 bytes to tcp{client}: 0x55979ef92ba8 2021-03-28 19:58:23.148 openLuup.server:: error 'closed' sending 6 bytes to tcp{client}: 0x55979ef92ba8 2021-03-28 19:58:23.148 openLuup.server:: error 'closed' sending 858578 bytes to tcp{client}: 0x55979ef92ba8 2021-03-28 19:58:23.148 openLuup.server:: error 'closed' sending 2 bytes to tcp{client}: 0x55979ef92ba8 2021-03-28 19:58:23.149 openLuup.server:: error 'closed' sending 5 bytes to tcp{client}: 0x55979ef92ba8 2021-03-28 20:01:02.561 openLuup.server:: error 'closed' sending 4 bytes to tcp{client}: 0x55979f3f9768 2021-03-28 20:01:02.561 openLuup.server:: error 'closed' sending 104 bytes to tcp{client}: 0x55979f3f9768 2021-03-28 20:01:02.561 openLuup.server:: error 'closed' sending 2 bytes to tcp{client}: 0x55979f3f9768 ... 2021-03-28 19:58:21.155 openLuup.io.server:: HTTP:3480 connection from 192.168.1.127 tcp{client}: 0x55979ef92ba8 2021-03-28 20:01:00.882 openLuup.io.server:: HTTP:3480 connection from 127.0.0.1 tcp{client}: 0x55979f3f9768 2021-03-28 19:58:22.954 luup_log:203: VirtualBinaryLight[2.40@203]:[updateMeters] Watts Path: "meters[1].power" - Value: 0 2021-03-28 19:58:23.148 openLuup.server:: error 'closed' sending 2 bytes to tcp{client}: 0x55979ef92ba8 2021-03-28 19:58:23.148 openLuup.server:: ...only 0 bytes sent 2021-03-28 19:58:23.148 openLuup.server:: error 'closed' sending 6 bytes to tcp{client}: 0x55979ef92ba8 2021-03-28 19:58:23.148 openLuup.server:: ...only 0 bytes sent 2021-03-28 19:58:23.148 openLuup.server:: error 'closed' sending 858578 bytes to tcp{client}: 0x55979ef92ba8 2021-03-28 19:58:23.148 openLuup.server:: ...only 64000 bytes sent 2021-03-28 19:58:23.148 openLuup.server:: error 'closed' sending 2 bytes to tcp{client}: 0x55979ef92ba8 2021-03-28 19:58:23.148 openLuup.server:: ...only 0 bytes sent 2021-03-28 19:58:23.149 openLuup.server:: error 'closed' sending 5 bytes to tcp{client}: 0x55979ef92ba8 2021-03-28 19:58:23.149 openLuup.server:: ...only 0 bytes sent 2021-03-28 19:58:23.149 openLuup.server:: request completed (858578 bytes, 5 chunks, 446 ms) tcp{client}: 0x55979ef92ba8
-
I have now had the Mqtt server up and running for approx 30 hours without any crashes so far, a step in the right direction as it seems.
I am still on OpenLuup version: v21.3.26 and LuaFileSystem v1.8.0 (luasec not updated), want to keep the setup as-is to see what happens over longer time.
So far only one Tasmota device connected via Mqtt reporting every minute.
No errors in the log files (except for Homewave, see below), max response time usually approx 16 seconds. Changing the http wait to 0.1 was a big change. With 1.0 OpenLuup was sluggish and there is a noticable difference also when comparing to 0.2.
With 0.1 the system is much better.Messages as displayed by MqttExplorer:
I have noticed two brief Mqtt disconnects during this time.
Log file:1575 lines, 0 errors, max gap 75s @ 2021-03-29 13:35:48.179 2021-03-29 13:34:33.581 luup.variable_set:: 216.urn:upnp-org:serviceId:TemperatureSensor1.CurrentTemperature was: 25.3 now: 25.4 #hooks:0 2021-03-29 13:34:33.582 luup.variable_set:: 228.urn:micasaverde-com:serviceId:HumiditySensor1.CurrentLevel was: 36.1 now: 36.1 #hooks:0 2021-03-29 13:35:48.179 openLuup.io.server:: MQTT:1883 connection from 192.168.1.60 tcp{client}: 0x5652106f2f48 2021-03-29 13:35:48.180 openLuup.io.server:: HTTP:3480 connection closed EXPIRED tcp{client}: 0x56520e9d91c8 2021-03-29 13:35:48.180 openLuup.io.server:: MQTT:1883 connection closed EXPIRED tcp{client}: 0x565210622978 2021-03-29 13:35:48.180 openLuup.mqtt:: mqtt-explorer-22233d66 UNSUBSCRIBE from # tcp{client}: 0x565210622978 2021-03-29 13:35:48.180 openLuup.mqtt:: mqtt-explorer-22233d66 UNSUBSCRIBE from $SYS/# tcp{client}: 0x565210622978
Log from the Tasmota device console:
12:43:08 MQT: tele/tasmota_test/SENSOR = {"Time":"2021-03-29T12:43:08","AM2301":{"Temperature":25.4,"Humidity":36.1,"DewPoint":9.3},"TempUnit":"C"} 12:43:33 MQT: Attempting connection... 12:43:48 MQT: Connect failed to 192.168.1.107:1883, rc -4. Retry in 10 sec 12:43:59 MQT: Attempting connection... 12:44:10 MQT: Connected 12:44:10 MQT: tele/tasmota_test/LWT = Online (retained) 12:44:10 MQT: cmnd/tasmota_test/POWER = 12:44:33 MQT: tele/tasmota_test/STATE = {"Time":"2021-03-29T12:44:33","Uptime":"0T01:05:15","UptimeSec":3915,"Heap":26,"SleepMode":"Dynamic","Sleep":50,"LoadAvg":19,"MqttCount":4,"Wifi":{"AP":1,"SSId":"BeachAC","BSSId":"FC:EC:DA:D1:7A:64","Channel":11,"RSSI":100,"Signal":-50,"LinkCount":1,"Downtime":"0T00:00:03"}}
It reconnected without problem quite fast, so that seems to be fine.
I have not used Homewave very much during this time, but it also seems to work ok. However I do see errors for it when I use it. So something is not 100% right there perhaps. I got more errors with a longer http wait (1.0 >> 0.2 > 0.1).
Log file:2021-03-30 20:10:47.908 openLuup.server:: request completed (5907 bytes, 1 chunks, 194 ms) tcp{client}: 0x565210f173b8 2021-03-30 20:10:48.260 openLuup.server:: GET /data_request?output_format=json&id=lu_status HTTP/1.1 tcp{client}: 0x5652108936d8 2021-03-30 20:10:48.484 openLuup.server:: error 'socket.select() not ready to send tcp{client}: 0x5652108936d8' sending 2 bytes to tcp{client}: 0x5652108936d8 2021-03-30 20:10:48.563 openLuup.server:: request completed (858554 bytes, 54 chunks, 302 ms) tcp{client}: 0x5652108936d8 2021-03-30 20:10:48.932 openLuup.server:: GET /data_request?id=lu_status2&output_format=json&DataVersion=14594009&Timeout=60&MinimumDelay=1500&_=1617014398016 HTTP/1.1 tcp{client}: 0x565210f173b8 2021-03-30 20:10:49.068 openLuup.server:: request completed (5909 bytes, 1 chunks, 135 ms) tcp{client}: 0x565210f173b8
-
-
@akbooer today I got another crash unfortunately.
I had it up and running with two Mqtt devices reporting during approx 24 hours before the crash. Before that approx 2 days with one device.
This morning 4 hours before the crash it looked like this:
When looking through the log I can find the a lot of errors building up the last 2.5 hours before the crash.
2021-04-01 10:10:20.698 openLuup.io.server:: MQTT:1883 connection closed tcp{client}: 0x55d8bb5a9b48 2021-04-01 10:10:20.698 openLuup.mqtt:: RECEIVE ERROR: closed tcp{client}: 0x55d8bb5a9b48 2021-04-01 10:10:20.698 openLuup.mqtt:: TasmotaUterum UNSUBSCRIBE from cmnd/TasmotaUterum_fb/# tcp{client}: 0x55d8bb5a9b48 2021-04-01 10:10:20.698 openLuup.mqtt:: TasmotaUterum UNSUBSCRIBE from cmnd/TasmotaUterum/# tcp{client}: 0x55d8bb5a9b48 2021-04-01 10:10:20.698 openLuup.mqtt:: TasmotaUterum UNSUBSCRIBE from cmnd/tasmotas/# tcp{client}: 0x55d8bb5a9b48 ... 2021-04-01 10:10:20.707 openLuup.io.server:: MQTT:1883 connection closed tcp{client}: 0x55d8bae0e948 2021-04-01 10:10:20.707 openLuup.mqtt:: RECEIVE ERROR: closed tcp{client}: 0x55d8bae0e948 ... 2021-04-01 10:10:20.709 openLuup.io.server:: MQTT:1883 connection closed tcp{client}: 0x55d8bb8a48a8 2021-04-01 10:10:20.709 openLuup.mqtt:: RECEIVE ERROR: closed tcp{client}: 0x55d8bb8a48a8 2021-04-01 10:10:20.709 openLuup.mqtt:: tasmota_test UNSUBSCRIBE from cmnd/tasmota_test_fb/# tcp{client}: 0x55d8bb8a48a8 2021-04-01 10:10:20.709 openLuup.mqtt:: tasmota_test UNSUBSCRIBE from cmnd/tasmotas/# tcp{client}: 0x55d8bb8a48a8 2021-04-01 10:10:20.709 openLuup.mqtt:: tasmota_test UNSUBSCRIBE from cmnd/tasmota_test/# tcp{client}: 0x55d8bb8a48a8 2021-04-01 10:10:20.741 openLuup.io.server:: MQTT:1883 connection from 192.168.1.50 tcp{client}: 0x55d8bad50918 2021-04-01 10:10:20.744 openLuup.mqtt:: tasmota_test SUBSCRIBE to cmnd/tasmota_test/# tcp{client}: 0x55d8bb53f518 2021-04-01 10:10:20.746 openLuup.mqtt:: tasmota_test SUBSCRIBE to cmnd/tasmotas/# tcp{client}: 0x55d8bb53f518 2021-04-01 10:10:20.752 openLuup.mqtt:: tasmota_test SUBSCRIBE to cmnd/tasmota_test_fb/# tcp{client}: 0x55d8bb53f518 2021-04-01 10:10:20.770 openLuup.mqtt:: TasmotaUterum SUBSCRIBE to cmnd/TasmotaUterum/# tcp{client}: 0x55d8bad50918 2021-04-01 10:10:20.770 openLuup.mqtt:: TasmotaUterum SUBSCRIBE to cmnd/tasmotas/# tcp{client}: 0x55d8bad50918 2021-04-01 10:10:20.771 openLuup.mqtt:: TasmotaUterum SUBSCRIBE to cmnd/TasmotaUterum_fb/# tcp{client}: 0x55d8bad50918 ... 2021-04-01 10:13:26.365 openLuup.io.server:: MQTT:1883 connection closed tcp{client}: 0x55d8bb6e71e8 2021-04-01 10:13:26.365 openLuup.mqtt:: RECEIVE ERROR: closed tcp{client}: 0x55d8bb6e71e8 2021-04-01 10:13:26.365 openLuup.io.server:: MQTT:1883 connection closed tcp{client}: 0x55d8bab0a8d8 2021-04-01 10:13:26.365 openLuup.mqtt:: RECEIVE ERROR: closed tcp{client}: 0x55d8bab0a8d8 .. 2021-04-01 10:13:26.368 openLuup.io.server:: MQTT:1883 connection closed tcp{client}: 0x55d8bad50918 2021-04-01 10:13:26.368 openLuup.mqtt:: RECEIVE ERROR: closed tcp{client}: 0x55d8bad50918 2021-04-01 10:13:26.368 openLuup.mqtt:: TasmotaUterum UNSUBSCRIBE from cmnd/TasmotaUterum_fb/# tcp{client}: 0x55d8bad50918 2021-04-01 10:13:26.368 openLuup.mqtt:: TasmotaUterum UNSUBSCRIBE from cmnd/TasmotaUterum/# tcp{client}: 0x55d8bad50918 2021-04-01 10:13:26.368 openLuup.mqtt:: TasmotaUterum UNSUBSCRIBE from cmnd/tasmotas/# tcp{client}: 0x55d8bad50918 2021-04-01 10:13:26.368 openLuup.io.server:: MQTT:1883 connection closed tcp{client}: 0x55d8bb53f518 2021-04-01 10:13:26.368 openLuup.mqtt:: RECEIVE ERROR: closed tcp{client}: 0x55d8bb53f518 2021-04-01 10:13:26.368 openLuup.mqtt:: tasmota_test UNSUBSCRIBE from cmnd/tasmota_test_fb/# tcp{client}: 0x55d8bb53f518 2021-04-01 10:13:26.368 openLuup.mqtt:: tasmota_test UNSUBSCRIBE from cmnd/tasmotas/# tcp{client}: 0x55d8bb53f518 2021-04-01 10:13:26.368 openLuup.mqtt:: tasmota_test UNSUBSCRIBE from cmnd/tasmota_test/# tcp{client}: 0x55d8bb53f518 2021-04-01 10:13:26.374 openLuup.mqtt:: TasmotaUterum SUBSCRIBE to cmnd/TasmotaUterum/# tcp{client}: 0x55d8bacf35d8 2021-04-01 10:13:26.379 openLuup.mqtt:: TasmotaUterum SUBSCRIBE to cmnd/tasmotas/# tcp{client}: 0x55d8bacf35d8 2021-04-01 10:13:26.386 openLuup.mqtt:: TasmotaUterum SUBSCRIBE to cmnd/TasmotaUterum_fb/# tcp{client}: 0x55d8bacf35d8 2021-04-01 10:13:26.397 openLuup.mqtt:: tasmota_test SUBSCRIBE to cmnd/tasmota_test/# tcp{client}: 0x55d8bad3fc48 2021-04-01 10:13:26.400 openLuup.mqtt:: tasmota_test SUBSCRIBE to cmnd/tasmotas/# tcp{client}: 0x55d8bad3fc48 2021-04-01 10:13:26.406 openLuup.mqtt:: tasmota_test SUBSCRIBE to cmnd/tasmota_test_fb/# tcp{client}: 0x55d8bad3fc48 .. 2021-04-01 10:35:23.058 openLuup.io.server:: MQTT:1883 connection closed tcp{client}: 0x55d8bacf35d8 2021-04-01 10:35:23.059 openLuup.mqtt:: RECEIVE ERROR: closed tcp{client}: 0x55d8bacf35d8 2021-04-01 10:35:23.059 openLuup.mqtt:: TasmotaUterum UNSUBSCRIBE from cmnd/TasmotaUterum_fb/# tcp{client}: 0x55d8bacf35d8 2021-04-01 10:35:23.059 openLuup.mqtt:: TasmotaUterum UNSUBSCRIBE from cmnd/TasmotaUterum/# tcp{client}: 0x55d8bacf35d8 2021-04-01 10:35:23.059 openLuup.mqtt:: TasmotaUterum UNSUBSCRIBE from cmnd/tasmotas/# tcp{client}: 0x55d8bacf35d8 ... 2021-04-01 10:35:23.088 openLuup.io.server:: MQTT:1883 connection closed tcp{client}: 0x55d8bb736018 2021-04-01 10:35:23.088 openLuup.mqtt:: RECEIVE ERROR: closed tcp{client}: 0x55d8bb736018 2021-04-01 10:35:23.088 openLuup.io.server:: MQTT:1883 connection closed tcp{client}: 0x55d8bb7380c8 2021-04-01 10:35:23.089 openLuup.mqtt:: RECEIVE ERROR: closed tcp{client}: 0x55d8bb7380c8 ... 2021-04-01 10:35:23.096 openLuup.io.server:: MQTT:1883 connection closed tcp{client}: 0x55d8bad3fc48 2021-04-01 10:35:23.096 openLuup.mqtt:: RECEIVE ERROR: closed tcp{client}: 0x55d8bad3fc48 2021-04-01 10:35:23.096 openLuup.mqtt:: tasmota_test UNSUBSCRIBE from cmnd/tasmota_test_fb/# tcp{client}: 0x55d8bad3fc48 2021-04-01 10:35:23.096 openLuup.mqtt:: tasmota_test UNSUBSCRIBE from cmnd/tasmotas/# tcp{client}: 0x55d8bad3fc48 2021-04-01 10:35:23.096 openLuup.mqtt:: tasmota_test UNSUBSCRIBE from cmnd/tasmota_test/# tcp{client}: 0x55d8bad3fc48 2021-04-01 10:35:25.955 openLuup.io.server:: MQTT:1883 connection from 192.168.1.50 tcp{client}: 0x55d8b9bd4838 2021-04-01 10:35:25.973 openLuup.mqtt:: TasmotaUterum SUBSCRIBE to cmnd/TasmotaUterum/# tcp{client}: 0x55d8b9bd4838 2021-04-01 10:35:25.977 openLuup.mqtt:: TasmotaUterum SUBSCRIBE to cmnd/tasmotas/# tcp{client}: 0x55d8b9bd4838 2021-04-01 10:35:25.977 openLuup.mqtt:: TasmotaUterum SUBSCRIBE to cmnd/TasmotaUterum_fb/# tcp{client}: 0x55d8b9bd4838 2021-04-01 10:35:29.040 openLuup.io.server:: MQTT:1883 connection from 192.168.1.60 tcp{client}: 0x55d8bb605448 2021-04-01 10:35:29.058 openLuup.mqtt:: tasmota_test SUBSCRIBE to cmnd/tasmota_test/# tcp{client}: 0x55d8bb605448 2021-04-01 10:35:29.062 openLuup.mqtt:: tasmota_test SUBSCRIBE to cmnd/tasmotas/# tcp{client}: 0x55d8bb605448 2021-04-01 10:35:29.063 openLuup.mqtt:: tasmota_test SUBSCRIBE to cmnd/tasmota_test_fb/# tcp{client}: 0x55d8bb605448 ... 2021-04-01 10:39:23.441 openLuup.io.server:: MQTT:1883 connection closed tcp{client}: 0x55d8b9bd4838 2021-04-01 10:39:23.441 openLuup.mqtt:: RECEIVE ERROR: closed tcp{client}: 0x55d8b9bd4838 2021-04-01 10:39:23.441 openLuup.mqtt:: TasmotaUterum UNSUBSCRIBE from cmnd/TasmotaUterum_fb/# tcp{client}: 0x55d8b9bd4838 2021-04-01 10:39:23.441 openLuup.mqtt:: TasmotaUterum UNSUBSCRIBE from cmnd/TasmotaUterum/# tcp{client}: 0x55d8b9bd4838 2021-04-01 10:39:23.441 openLuup.mqtt:: TasmotaUterum UNSUBSCRIBE from cmnd/tasmotas/# tcp{client}: 0x55d8b9bd4838 ... 2021-04-01 10:39:29.585 openLuup.io.server:: MQTT:1883 connection from 192.168.1.60 tcp{client}: 0x55d8b8fedf68 2021-04-01 10:39:29.585 openLuup.io.server:: MQTT:1883 connection closed tcp{client}: 0x55d8baee2da8 2021-04-01 10:39:29.585 openLuup.mqtt:: RECEIVE ERROR: closed tcp{client}: 0x55d8baee2da8 2021-04-01 10:39:29.585 openLuup.io.server:: MQTT:1883 connection closed tcp{client}: 0x55d8baacc308 2021-04-01 10:39:29.585 openLuup.mqtt:: RECEIVE ERROR: closed tcp{client}: 0x55d8baacc308 ... 2021-04-01 10:39:35.729 openLuup.io.server:: MQTT:1883 connection closed tcp{client}: 0x55d8bb605448 2021-04-01 10:39:35.729 openLuup.mqtt:: RECEIVE ERROR: closed tcp{client}: 0x55d8bb605448 2021-04-01 10:39:35.729 openLuup.mqtt:: tasmota_test UNSUBSCRIBE from cmnd/tasmota_test_fb/# tcp{client}: 0x55d8bb605448 ... 2021-04-01 10:40:49.043 openLuup.context_switch:: ERROR: [dev #106] table: 0x55d8bb856790 2021-04-01 10:40:49.043 luup.incoming_callback:: function: 0x55d8bacaad00 ERROR: table: 0x55d8bb856790 ... 2021-04-01 10:43:20.159 openLuup.io.server:: MQTT:1883 connection closed tcp{client}: 0x55d8bb0f87b8 2021-04-01 10:43:20.159 openLuup.mqtt:: RECEIVE ERROR: closed tcp{client}: 0x55d8bb0f87b8 ... 2021-04-01 10:43:20.161 openLuup.io.server:: MQTT:1883 connection closed tcp{client}: 0x55d8b8fedf68 2021-04-01 10:43:20.161 openLuup.mqtt:: RECEIVE ERROR: closed tcp{client}: 0x55d8b8fedf68 2021-04-01 10:43:20.161 openLuup.mqtt:: tasmota_test UNSUBSCRIBE from cmnd/tasmota_test_fb/# tcp{client}: 0x55d8b8fedf68 2021-04-01 10:43:20.161 openLuup.mqtt:: tasmota_test UNSUBSCRIBE from cmnd/tasmotas/# tcp{client}: 0x55d8b8fedf68 2021-04-01 10:43:20.161 openLuup.mqtt:: tasmota_test UNSUBSCRIBE from cmnd/tasmota_test/# tcp{client}: 0x55d8b8fedf68 2021-04-01 10:43:20.161 openLuup.io.server:: MQTT:1883 connection closed tcp{client}: 0x55d8bb46f488 2021-04-01 10:43:20.161 openLuup.mqtt:: RECEIVE ERROR: closed tcp{client}: 0x55d8bb46f488 2021-04-01 10:43:20.161 openLuup.mqtt:: TasmotaUterum UNSUBSCRIBE from cmnd/TasmotaUterum_fb/# tcp{client}: 0x55d8bb46f488 2021-04-01 10:43:20.162 openLuup.mqtt:: TasmotaUterum UNSUBSCRIBE from cmnd/TasmotaUterum/# tcp{client}: 0x55d8bb46f488 2021-04-01 10:43:20.162 openLuup.mqtt:: TasmotaUterum UNSUBSCRIBE from cmnd/tasmotas/# tcp{client}: 0x55d8bb46f488 2021-04-01 10:43:20.254 openLuup.mqtt:: TasmotaUterum SUBSCRIBE to cmnd/TasmotaUterum/# tcp{client}: 0x55d8ba375f28 2021-04-01 10:43:20.258 openLuup.mqtt:: TasmotaUterum SUBSCRIBE to cmnd/tasmotas/# tcp{client}: 0x55d8ba375f28 2021-04-01 10:43:20.258 openLuup.mqtt:: TasmotaUterum SUBSCRIBE to cmnd/TasmotaUterum_fb/# tcp{client}: 0x55d8ba375f28 2021-04-01 10:43:20.260 openLuup.mqtt:: tasmota_test SUBSCRIBE to cmnd/tasmota_test/# tcp{client}: 0x55d8bb83d468 2021-04-01 10:43:20.260 openLuup.mqtt:: tasmota_test SUBSCRIBE to cmnd/tasmotas/# tcp{client}: 0x55d8bb83d468 2021-04-01 10:43:20.260 openLuup.mqtt:: tasmota_test SUBSCRIBE to cmnd/tasmota_test_fb/# tcp{client}: 0x55d8bb83d468 ... 2021-04-01 10:51:25.662 openLuup.io.server:: MQTT:1883 connection closed tcp{client}: 0x55d8baaa6608 2021-04-01 10:51:25.662 openLuup.mqtt:: RECEIVE ERROR: closed tcp{client}: 0x55d8baaa6608 2021-04-01 10:51:25.663 openLuup.io.server:: MQTT:1883 connection closed tcp{client}: 0x55d8badfb488 2021-04-01 10:51:25.663 openLuup.mqtt:: RECEIVE ERROR: closed tcp{client}: 0x55d8badfb488 ... 2021-04-01 10:51:25.665 openLuup.io.server:: MQTT:1883 connection closed tcp{client}: 0x55d8bb83d468 2021-04-01 10:51:25.665 openLuup.mqtt:: RECEIVE ERROR: closed tcp{client}: 0x55d8bb83d468 2021-04-01 10:51:25.665 openLuup.mqtt:: tasmota_test UNSUBSCRIBE from cmnd/tasmota_test_fb/# tcp{client}: 0x55d8bb83d468 2021-04-01 10:51:25.665 openLuup.mqtt:: tasmota_test UNSUBSCRIBE from cmnd/tasmotas/# tcp{client}: 0x55d8bb83d468 2021-04-01 10:51:25.665 openLuup.mqtt:: tasmota_test UNSUBSCRIBE from cmnd/tasmota_test/# tcp{client}: 0x55d8bb83d468 2021-04-01 10:51:25.665 openLuup.io.server:: MQTT:1883 connection closed tcp{client}: 0x55d8ba375f28 2021-04-01 10:51:25.665 openLuup.mqtt:: RECEIVE ERROR: closed tcp{client}: 0x55d8ba375f28 2021-04-01 10:51:25.665 openLuup.mqtt:: TasmotaUterum UNSUBSCRIBE from cmnd/TasmotaUterum_fb/# tcp{client}: 0x55d8ba375f28 2021-04-01 10:51:25.665 openLuup.mqtt:: TasmotaUterum UNSUBSCRIBE from cmnd/TasmotaUterum/# tcp{client}: 0x55d8ba375f28 2021-04-01 10:51:25.665 openLuup.mqtt:: TasmotaUterum UNSUBSCRIBE from cmnd/tasmotas/# tcp{client}: 0x55d8ba375f28 2021-04-01 10:51:25.735 openLuup.mqtt:: TasmotaUterum SUBSCRIBE to cmnd/TasmotaUterum/# tcp{client}: 0x55d8ba738ad8 2021-04-01 10:51:25.739 openLuup.mqtt:: TasmotaUterum SUBSCRIBE to cmnd/tasmotas/# tcp{client}: 0x55d8ba738ad8 2021-04-01 10:51:25.739 openLuup.mqtt:: TasmotaUterum SUBSCRIBE to cmnd/TasmotaUterum_fb/# tcp{client}: 0x55d8ba738ad8 2021-04-01 10:51:25.844 openLuup.mqtt:: tasmota_test SUBSCRIBE to cmnd/tasmota_test/# tcp{client}: 0x55d8baf3baa8 2021-04-01 10:51:25.847 openLuup.mqtt:: tasmota_test SUBSCRIBE to cmnd/tasmotas/# tcp{client}: 0x55d8baf3baa8 2021-04-01 10:51:25.851 openLuup.mqtt:: tasmota_test SUBSCRIBE to cmnd/tasmota_test_fb/# tcp{client}: 0x55d8baf3baa8 ... 2021-04-01 10:54:32.113 openLuup.io.server:: MQTT:1883 connection closed tcp{client}: 0x55d8baf3baa8 2021-04-01 10:54:32.113 openLuup.mqtt:: RECEIVE ERROR: closed tcp{client}: 0x55d8baf3baa8 2021-04-01 10:54:32.113 openLuup.mqtt:: tasmota_test UNSUBSCRIBE from cmnd/tasmota_test_fb/# tcp{client}: 0x55d8baf3baa8 2021-04-01 10:54:32.113 openLuup.mqtt:: tasmota_test UNSUBSCRIBE from cmnd/tasmotas/# tcp{client}: 0x55d8baf3baa8 2021-04-01 10:54:32.113 openLuup.mqtt:: tasmota_test UNSUBSCRIBE from cmnd/tasmota_test/# tcp{client}: 0x55d8baf3baa8 2021-04-01 10:54:32.113 openLuup.io.server:: MQTT:1883 connection closed tcp{client}: 0x55d8ba738ad8 2021-04-01 10:54:32.113 openLuup.mqtt:: RECEIVE ERROR: closed tcp{client}: 0x55d8ba738ad8 2021-04-01 10:54:32.113 openLuup.mqtt:: TasmotaUterum UNSUBSCRIBE from cmnd/TasmotaUterum_fb/# tcp{client}: 0x55d8ba738ad8 2021-04-01 10:54:32.113 openLuup.mqtt:: TasmotaUterum UNSUBSCRIBE from cmnd/TasmotaUterum/# tcp{client}: 0x55d8ba738ad8 2021-04-01 10:54:32.113 openLuup.mqtt:: TasmotaUterum UNSUBSCRIBE from cmnd/tasmotas/# tcp{client}: 0x55d8ba738ad8 2021-04-01 10:54:32.175 openLuup.mqtt:: TasmotaUterum SUBSCRIBE to cmnd/TasmotaUterum/# tcp{client}: 0x55d8ba6ce668 2021-04-01 10:54:32.178 openLuup.mqtt:: tasmota_test SUBSCRIBE to cmnd/tasmota_test/# tcp{client}: 0x55d8bb6b8028 2021-04-01 10:54:32.185 openLuup.mqtt:: tasmota_test SUBSCRIBE to cmnd/tasmotas/# tcp{client}: 0x55d8bb6b8028 2021-04-01 10:54:32.185 openLuup.mqtt:: tasmota_test SUBSCRIBE to cmnd/tasmota_test_fb/# tcp{client}: 0x55d8bb6b8028 2021-04-01 10:54:32.190 openLuup.mqtt:: TasmotaUterum SUBSCRIBE to cmnd/tasmotas/# tcp{client}: 0x55d8ba6ce668 2021-04-01 10:54:32.190 openLuup.mqtt:: TasmotaUterum SUBSCRIBE to cmnd/TasmotaUterum_fb/# tcp{client}: 0x55d8ba6ce668 ... <<<<repeats itself every 3rd minute or so>>>> ... 2021-04-01 12:42:40.786 openLuup.io.server:: MQTT:1883 connection closed tcp{client}: 0x55d8bb0f87b8 2021-04-01 12:42:40.786 openLuup.mqtt:: closed tcp{client}: 0x55d8bb0f87b8 2021-04-01 12:42:40.786 openLuup.mqtt:: tasmota_test UNSUBSCRIBE from cmnd/tasmota_test_fb/# tcp{client}: 0x55d8bb0f87b8 2021-04-01 12:42:40.786 openLuup.mqtt:: tasmota_test UNSUBSCRIBE from cmnd/tasmotas/# tcp{client}: 0x55d8bb0f87b8 2021-04-01 12:42:40.786 openLuup.mqtt:: tasmota_test UNSUBSCRIBE from cmnd/tasmota_test/# tcp{client}: 0x55d8bb0f87b8 2021-04-01 12:42:40.786 openLuup.mqtt:: ERROR publishing application message for mqtt:cmnd/tasmota_test/POWER : closed 2021-04-01 12:42:40.786 openLuup.io.server:: MQTT:1883 connection closed tcp{client}: 0x55d8bab88d38 2021-04-01 12:42:40.786 openLuup.mqtt:: RECEIVE ERROR: closed tcp{client}: 0x55d8bab88d38 2021-04-01 12:42:40.786 openLuup.mqtt:: TasmotaUterum UNSUBSCRIBE from cmnd/TasmotaUterum_fb/# tcp{client}: 0x55d8bab88d38 2021-04-01 12:42:40.786 openLuup.mqtt:: TasmotaUterum UNSUBSCRIBE from cmnd/TasmotaUterum/# tcp{client}: 0x55d8bab88d38 2021-04-01 12:42:40.786 openLuup.mqtt:: TasmotaUterum UNSUBSCRIBE from cmnd/tasmotas/# tcp{client}: 0x55d8bab88d38 <<<<crashed>>>>
In addition to the above I also found the following in the log, during one period (approx 20 times during a few minutes, two hours before the crash) "no route to host" for the Zway server (it is not on the same server as OpenLuup) and another error (only once), see below.
2021-04-01 10:39:23.440 luup_log:106: ZWay ASYNC request: No route to host 2021-04-01 10:40:49.043 openLuup.context_switch:: ERROR: [dev #106] table: 0x55d8bb856790 2021-04-01 10:40:49.043 luup.incoming_callback:: function: 0x55d8bacaad00 ERROR: table: 0x55d8bb856790
Now I have disabled the Mqtt and restarted the server to see what happens.
-
@akbooer In my testing I have disabled the Mqtt server in OpenLuup. When doing this I noticed that "publish/messages/received" continues to update despite the Mqtt being disabled in LuaStartup.
What could be the reason for that?
-
The is absolutely fine, although understandably confusing...
The published messages come from within the openLuup system, rather than over TCP. You must have PublishVariableUpdates or PublishDeviceStatus still enabled.
-
Ah, no, there is one other thing. I think this is the $SYS/broker set of topics which get updated every minute. I'll see about disabling that too. Thanks for pointing this out.
-
I have now tested this back and forth during the last few weeks and for some reason my OpenLuup installation crashes when I have Mqtt devices running against it for some time. Sometimes it takes a bit longer somtimes faster. I cannot see anything specific in the log, I think the socket crash mentioned above could be something.
I had it up and running for a few days with the Mqtt server enabled but no Mqtt devices sending any data to the server and then it did not crash.
The Homewave erros have disappeared more or less, I moved my Zway Pi so that it has proper gigabit Ethernet (it was on a slow powerline connection for historical reasons from when I set it up). That also amended the Zway delay errors in the log.
I have also tested it with @therealdb Mqtt bridge reporting over http and that seem to work.
I set up a fresh install OpenLuup on a Raspberry Pi 4 with Mqtt enabled and that did not crash. The devices were the same as I had for testing on my original server, so I can conclude they do work together with OpenLuup. The Pi did more or less only have the Mqtt server active.
Next step is to test and migrate my original configuration with the Zway etc integration and see if that works on the Pi or not.
-
@archers I’ll soon release a modified version running with openluup sockets (or Vera’s) to avoid http calls. Lemme know if you want to test it, I have it running in my system and it’s sub second as performance and very stable. I’ll need some more time.
-
I’m entirely dependant now on openLuup’s MQTT server, and it seems to be performing flawlessly for me under Synology docker and Homebridge.
84/88