Unexpected stop of openLuup
-
Every now and then my openLuup stops responding. This is the LuaUPnP.log when it happens:
2021-08-20 14:59:39.827 :: openLuup LOG ROTATION :: (runtime 11.0 days) 2021-08-20 14:59:39.831 openLuup.server:: GET /data_request?id=sdata HTTP/1.1 tcp{client}: 0x55b6023b8088 2021-08-20 14:59:39.853 openLuup.server:: request completed (49215 bytes, 4 chunks, 22 ms) tcp{client}: 0x55b6023b8088 2021-08-20 14:59:39.854 openLuup.io.server:: HTTP:3480 connection closed openLuup.server.receive closed tcp{client}: 0x55b6023b8088 2021-08-20 14:59:40.826 openLuup.io.server:: HTTP:3480 connection from 127.0.0.1 tcp{client}: 0x55b600f46ab8 2021-08-20 14:59:40.829 openLuup.server:: GET /data_request?id=sdata HTTP/1.1 tcp{client}: 0x55b600f46ab8 2021-08-20 14:59:40.846 openLuup.server:: request completed (49215 bytes, 4 chunks, 16 ms) tcp{client}: 0x55b600f46ab8 2021-08-20 14:59:40.847 openLuup.io.server:: HTTP:3480 connection closed openLuup.server.receive closed tcp{client}: 0x55b600f46ab8 2021-08-20 14:59:41.827 openLuup.io.server:: HTTP:3480 connection from 127.0.0.1 tcp{client}: 0x55b600d1ad98 2021-08-20 14:59:41.829 openLuup.server:: GET /data_request?id=sdata HTTP/1.1 tcp{client}: 0x55b600d1ad98 2021-08-20 14:59:41.838 openLuup.server:: request completed (49215 bytes, 4 chunks, 8 ms) tcp{client}: 0x55b600d1ad98 2021-08-20 14:59:41.838 openLuup.io.server:: HTTP:3480 connection closed openLuup.server.receive closed tcp{client}: 0x55b600d1ad98 2021-08-20 14:59:42.827 openLuup.io.server:: HTTP:3480 connection from 127.0.0.1 tcp{client}: 0x55b6016b9848 2021-08-20 14:59:42.829 openLuup.server:: GET /data_request?id=sdata HTTP/1.1 tcp{client}: 0x55b6016b9848 2021-08-20 14:59:42.847 openLuup.server:: request completed (49215 bytes, 4 chunks, 17 ms) tcp{client}: 0x55b6016b9848 2021-08-20 14:59:42.876 openLuup.io.server:: HTTP:3480 connection closed openLuup.server.receive closed tcp{client}: 0x55b6016b9848 2021-08-20 14:59:43.054 luup.watch_callback:: 30003.DS18B20.Temperature called [37]virtualSensorWatchCallback() function: 0x55b600c2fff0 2021-08-20 14:59:43.054 luup.variable_set:: 38.urn:toggledbits-com:serviceId:VirtualSensor1.PreviousRawValue was: 28.3 now: 28.5 #hooks:0 2021-08-20 14:59:43.054 luup.variable_set:: 38.urn:toggledbits-com:serviceId:VirtualSensor1.RawValue was: 28.5 now: 28.4 #hooks:0 2021-08-20 14:59:43.054 luup.variable_set:: 38.urn:upnp-org:serviceId:TemperatureSensor1.CurrentTemperature was: 28.5 now: 28.4 #hooks:0 2021-08-20 14:59:43.054 luup.variable_set:: 38.urn:toggledbits-com:serviceId:VirtualSensor1.PreviousValue was: 28.3 now: 28.5 #hooks:0 2021-08-20 14:59:43.054 luup.variable_set:: 38.urn:toggledbits-com:serviceId:VirtualSensor1.LastUpdate was: 1629464323 now: 1629464383 #hooks:0 2021-08-20 14:59:43.265 openLuup.server:: request completed (3193 bytes, 1 chunks, 5550 ms) tcp{client}: 0x55b60162e488 2021-08-20 14:59:43.267 openLuup.io.server:: HTTP:3480 connection closed openLuup.server.receive closed tcp{client}: 0x55b60162e488 2021-08-20 14:59:43.278 openLuup.io.server:: HTTP:3480 connection from 127.0.0.1 tcp{client}: 0x55b6023c8458 2021-08-20 14:59:43.278 openLuup.server:: GET /data_request?id=status&DataVersion=516611499&Timeout=15&MinimumDelay=100&output_format=json&_r=1629464383277 HTTP/1.1 tcp{client}: 0x55b6023c8458 2021-08-20 14:59:43.381 openLuup.server:: request completed (1091 bytes, 1 chunks, 102 ms) tcp{client}: 0x55b6023c8458 2021-08-20 14:59:43.381 openLuup.io.server:: HTTP:3480 connection closed openLuup.server.receive closed tcp{client}: 0x55b6023c8458 2021-08-20 14:59:43.392 openLuup.io.server:: HTTP:3480 connection from 127.0.0.1 tcp{client}: 0x55b6013d6cc8 2021-08-20 14:59:43.393 openLuup.server:: GET /data_request?id=status&DataVersion=516611500&Timeout=15&MinimumDelay=100&output_format=json&_r=1629464383391 HTTP/1.1 tcp{client}: 0x55b6013d6cc8 2021-08-20 14:59:43.496 openLuup.server:: request completed (3196 bytes, 1 chunks, 4981 ms) tcp{client}: 0x55b601a37db8 2021-08-20 14:59:43.826 openLuup.io.server:: HTTP:3480 connection from 127.0.0.1 tcp{client}: 0x55b601d05a08 2021-08-20 14:59:43.828 openLuup.server:: GET /data_request?id=sdata HTTP/1.1 tcp{client}: 0x55b601d05a08 2021-08-20 14:59:43.844 openLuup.server:: request completed (49215 bytes, 4 chunks, 15 ms) tcp{client}: 0x55b601d05a08 2021-08-20 14:59:43.844 openLuup.io.server:: HTTP:3480 connection closed openLuup.server.receive closed tcp{client}: 0x55b601d05a08 2021-08-20 14:59:44.524 openLuup.server:: GET /data_request?id=lu_status2&output_format=json&DataVersion=516611500&Timeout=60&MinimumDelay=1500&_=1629292536029 HTTP/1.1 tcp{client}: 0x55b601a37db8 2021-08-20 14:59:44.828 openLuup.io.server:: HTTP:3480 connection from 127.0.0.1 tcp{client}: 0x55b6023a7d18 2021-08-20 14:59:44.831 openLuup.server:: GET /data_request?id=sdata HTTP/1.1 tcp{client}: 0x55b6023a7d18 2021-08-20 14:59:44.844 openLuup.server:: request completed (49215 bytes, 4 chunks, 12 ms) tcp{client}: 0x55b6023a7d18 2021-08-20 14:59:44.844 openLuup.io.server:: HTTP:3480 connection closed openLuup.server.receive closed tcp{client}: 0x55b6023a7d18 2021-08-20 14:59:45.514 openLuup.server:: GET /data_request?id=variableget&DeviceNum=0&serviceId=urn:micasaverde-com:serviceId:HomeAutomationGateway1&Variable=Mode&_=1629292536030 HTTP/1.1 tcp{client}: 0x55b601ab8228 2021-08-20 14:59:45.617 openLuup.server:: request completed (1 bytes, 1 chunks, 102 ms) tcp{client}: 0x55b601ab8228 2021-08-20 14:59:45.827 openLuup.io.server:: HTTP:3480 connection from 127.0.0.1 tcp{client}: 0x55b600b8de38 2021-08-20 14:59:45.828 openLuup.server:: GET /data_request?id=sdata HTTP/1.1 tcp{client}: 0x55b600b8de38 2021-08-20 14:59:45.834 openLuup.server:: request completed (49215 bytes, 4 chunks, 5 ms) tcp{client}: 0x55b600b8de38 2021-08-20 14:59:45.836 openLuup.io.server:: HTTP:3480 connection closed openLuup.server.receive closed tcp{client}: 0x55b600b8de38 2021-08-20 14:59:46.828 openLuup.io.server:: HTTP:3480 connection from 127.0.0.1 tcp{client}: 0x55b6018a53f8 2021-08-20 14:59:46.830 openLuup.server:: GET /data_request?id=sdata HTTP/1.1 tcp{client}: 0x55b6018a53f8 2021-08-20 14:59:46.841 openLuup.server:: request completed (49215 bytes, 4 chunks, 11 ms) tcp{client}: 0x55b6018a53f8 2021-08-20 14:59:46.842 openLuup.io.server:: HTTP:3480 connection closed openLuup.server.receive closed tcp{client}: 0x55b6018a53f8 2021-08-20 14:59:47.829 openLuup.io.server:: HTTP:3480 connection from 127.0.0.1 tcp{client}: 0x55b600d2d938 2021-08-20 14:59:47.832 openLuup.server:: GET /data_request?id=sdata HTTP/1.1 tcp{client}: 0x55b600d2d938 2021-08-20 14:59:47.854 openLuup.server:: request completed (49215 bytes, 4 chunks, 21 ms) tcp{client}: 0x55b600d2d938 2021-08-20 14:59:47.855 openLuup.io.server:: HTTP:3480 connection closed openLuup.server.receive closed tcp{client}: 0x55b600d2d938 2021-08-20 14:59:47.962 luup.variable_set:: 10086.urn:upnp-org:serviceId:TemperatureSensor1.CurrentTemperature was: 28.3 now: 28.5 #hooks:0 2021-08-20 14:59:48.370 openLuup.server:: request completed (1359 bytes, 1 chunks, 4977 ms) tcp{client}: 0x55b6013d6cc8 2021-08-20 14:59:48.372 openLuup.server:: request completed (1359 bytes, 1 chunks, 3847 ms) tcp{client}: 0x55b601a37db8 2021-08-20 14:59:48.373 openLuup.io.server:: HTTP:3480 connection closed openLuup.server.receive closed tcp{client}: 0x55b6013d6cc8 2021-08-20 14:59:48.384 openLuup.io.server:: HTTP:3480 connection from 127.0.0.1 tcp{client}: 0x55b601e62df8 2021-08-20 14:59:48.385 openLuup.server:: GET /data_request?id=status&DataVersion=516611502&Timeout=15&MinimumDelay=100&output_format=json&_r=1629464388383 HTTP/1.1 tcp{client}: 0x55b601e62df8 2021-08-20 14:59:48.813 openLuup.server:: GET /data_request?id=lu_status2&output_format=json&DataVersion=516611502&Timeout=60&MinimumDelay=1500&_=1629292536031 HTTP/1.1 tcp{client}: 0x55b601a37db8 2021-08-20 14:59:48.829 openLuup.io.server:: HTTP:3480 connection from 127.0.0.1 tcp{client}: 0x55b600c44bf8 2021-08-20 14:59:48.832 openLuup.server:: GET /data_request?id=sdata HTTP/1.1 tcp{client}: 0x55b600c44bf8 2021-08-20 14:59:48.854 openLuup.server:: request completed (49215 bytes, 4 chunks, 21 ms) tcp{client}: 0x55b600c44bf8 2021-08-20 14:59:48.856 openLuup.io.server:: HTTP:3480 connection closed openLuup.server.receive closed tcp{client}: 0x55b600c44bf8 2021-08-20 14:59:49.829 openLuup.io.server:: HTTP:3480 connection from 127.0.0.1 tcp{client}: 0x55b600e1fea8 2021-08-20 14:59:49.830 openLuup.server:: GET /data_request?id=sdata HTTP/1.1 tcp{client}: 0x55b600e1fea8 2021-08-20 14:59:49.835 openLuup.server:: request completed (49215 bytes, 4 chunks, 5 ms) tcp{client}: 0x55b600e1fea8 2021-08-20 14:59:49.835 openLuup.io.server:: HTTP:3480 connection closed openLuup.server.receive closed tcp{client}: 0x55b600e1fea8 2021-08-20 14:59:50.831 openLuup.io.server:: HTTP:3480 connection from 127.0.0.1 tcp{client}: 0x55b601cfa118 2021-08-20 14:59:50.832 openLuup.server:: GET /data_request?id=sdata HTTP/1.1 tcp{client}: 0x55b601cfa118 2021-08-20 14:59:50.837 openLuup.server:: request completed (49215 bytes, 4 chunks, 4 ms) tcp{client}: 0x55b601cfa118 2021-08-20 14:59:50.838 openLuup.io.server:: HTTP:3480 connection closed openLuup.server.receive closed tcp{client}: 0x55b601cfa118 2021-08-20 14:59:51.833 openLuup.io.server:: HTTP:3480 connection from 127.0.0.1 tcp{client}: 0x55b601462bb8 2021-08-20 14:59:51.835 openLuup.server:: GET /data_request?id=sdata HTTP/1.1 tcp{client}: 0x55b601462bb8 2021-08-20 14:59:51.852 openLuup.server:: request completed (49215 bytes, 4 chunks, 17 ms) tcp{client}: 0x55b601462bb8 2021-08-20 14:59:51.854 openLuup.io.server:: HTTP:3480 connection closed openLuup.server.receive closed tcp{client}: 0x55b601462bb8 2021-08-20 14:59:52.834 openLuup.io.server:: HTTP:3480 connection from 127.0.0.1 tcp{client}: 0x55b6016b1c98 2021-08-20 14:59:52.836 openLuup.server:: GET /data_request?id=sdata HTTP/1.1 tcp{client}: 0x55b6016b1c98 2021-08-20 14:59:52.847 openLuup.server:: request completed (49215 bytes, 4 chunks, 11 ms) tcp{client}: 0x55b6016b1c98 2021-08-20 14:59:52.848 openLuup.io.server:: HTTP:3480 connection closed openLuup.server.receive closed tcp{client}: 0x55b6016b1c98 2021-08-20 14:59:53.193 luup.variable_set:: 10005.urn:micasaverde-com:serviceId:SceneController1.sl_SceneActivated was: 255 now: 255 #hooks:0 2021-08-20 14:59:53.193 luup.variable_set:: 10006.urn:micasaverde-com:serviceId:HaDevice1.sl_TamperAlarm was: 0 now: 0 #hooks:0 2021-08-20 14:59:53.193 luup.variable_set:: 10094.urn:micasaverde-com:serviceId:HaDevice1.sl_BatteryAlarm was: 0 now: 0 #hooks:0 2021-08-20 14:59:53.193 luup.variable_set:: 10096.urn:micasaverde-com:serviceId:HaDevice1.sl_BatteryAlarm was: 10 now: 10 #hooks:0 2021-08-20 14:59:53.193 luup.variable_set:: 10098.urn:micasaverde-com:serviceId:HaDevice1.sl_BatteryAlarm was: 10 now: 10 #hooks:0 2021-08-20 14:59:53.193 luup.variable_set:: 10104.urn:micasaverde-com:serviceId:HaDevice1.sl_BatteryAlarm was: 10 now: 10 #hooks:0 2021-08-20 14:59:53.194 luup.variable_set:: 10106.urn:micasaverde-com:serviceId:HaDevice1.sl_BatteryAlarm was: 0 now: 0 #hooks:0 2021-08-20 14:59:53.194 luup.variable_set:: 10109.urn:micasaverde-com:serviceId:HaDevice1.sl_BatteryAlarm was: 0 now: 0 #hooks:0 2021-08-20 14:59:53.194 luup.variable_set:: 10112.urn:micasaverde-com:serviceId:DoorLock1.sl_UserCode was: UserID="4" UserName="Ella" now: UserID="4" UserName="Ella" #hooks:0 2021-08-20 14:59:53.194 luup.variable_set:: 10112.urn:micasaverde-com:serviceId:DoorLock1.sl_LockButton was: 1 now: 1 #hooks:0 2021-08-20 14:59:53.194 luup.variable_set:: 10112.urn:micasaverde-com:serviceId:DoorLock1.sl_PinFailed was: 1 now: 1 #hooks:0 2021-08-20 14:59:53.194 luup.variable_set:: 10112.urn:micasaverde-com:serviceId:HaDevice1.sl_BatteryAlarm was: 0 now: 0 #hooks:0 2021-08-20 14:59:53.194 luup.variable_set:: 10131.urn:micasaverde-com:serviceId:HaDevice1.sl_TamperAlarm was: 0 now: 0 #hooks:0 2021-08-20 14:59:53.194 luup.variable_set:: 10132.urn:micasaverde-com:serviceId:HaDevice1.sl_TamperAlarm was: 0 now: 0 #hooks:0 2021-08-20 14:59:53.194 luup.variable_set:: 10133.urn:micasaverde-com:serviceId:HaDevice1.sl_TamperAlarm was: 0 now: 0 #hooks:0 2021-08-20 14:59:53.194 luup.variable_set:: 10165.urn:micasaverde-com:serviceId:HaDevice1.sl_Alarm was: SMOKE now: SMOKE #hooks:0 2021-08-20 14:59:53.194 luup.variable_set:: 10165.urn:micasaverde-com:serviceId:HaDevice1.sl_TamperAlarm was: 0 now: 0 #hooks:0 2021-08-20 14:59:53.194 luup.variable_set:: 10188.urn:micasaverde-com:serviceId:HaDevice1.sl_TamperAlarm was: 1 now: 1 #hooks:0 2021-08-20 14:59:53.194 luup.variable_set:: 10188.urn:micasaverde-com:serviceId:HaDevice1.sl_Alarm was: TAMPER_ALARM now: TAMPER_ALARM #hooks:0 2021-08-20 14:59:53.194 luup.variable_set:: 10191.urn:micasaverde-com:serviceId:HaDevice1.sl_BatteryAlarm was: 10 now: 10 #hooks:0 2021-08-20 14:59:53.299 openLuup.server:: request completed (5449 bytes, 1 chunks, 4485 ms) tcp{client}: 0x55b601a37db8 2021-08-20 14:59:53.505 openLuup.server:: request completed (5449 bytes, 1 chunks, 5119 ms) tcp{client}: 0x55b601e62df8 2021-08-20 14:59:53.511 openLuup.io.server:: HTTP:3480 connection closed openLuup.server.receive closed tcp{client}: 0x55b601e62df8 2021-08-20 14:59:53.513 openLuup.server:: GET /data_request?id=lu_status2&output_format=json&DataVersion=516611523&Timeout=60&MinimumDelay=1500&_=1629292536032 HTTP/1.1 tcp{client}: 0x55b601a37db8 2021-08-20 14:59:53.522 openLuup.io.server:: HTTP:3480 connection from 127.0.0.1 tcp{client}: 0x55b600bc7068 2021-08-20 14:59:53.524 openLuup.server:: GET /data_request?id=status&DataVersion=516611523&Timeout=15&MinimumDelay=100&output_format=json&_r=1629464393521 HTTP/1.1 tcp{client}: 0x55b600bc7068 2021-08-20 14:59:53.834 openLuup.io.server:: HTTP:3480 connection from 127.0.0.1 tcp{client}: 0x55b601b8cbe8 2021-08-20 14:59:53.835 openLuup.server:: GET /data_request?id=sdata HTTP/1.1 tcp{client}: 0x55b601b8cbe8 2021-08-20 14:59:53.840 openLuup.server:: request completed (49215 bytes, 4 chunks, 5 ms) tcp{client}: 0x55b601b8cbe8 2021-08-20 14:59:53.841 openLuup.io.server:: HTTP:3480 connection closed openLuup.server.receive closed tcp{client}: 0x55b601b8cbe8 2021-08-20 14:59:54.834 openLuup.io.server:: HTTP:3480 connection from 127.0.0.1 tcp{client}: 0x55b6013dacd8 2021-08-20 14:59:54.836 openLuup.server:: GET /data_request?id=sdata HTTP/1.1 tcp{client}: 0x55b6013dacd8 2021-08-20 14:59:54.849 openLuup.server:: request completed (49215 bytes, 4 chunks, 12 ms) tcp{client}: 0x55b6013dacd8 2021-08-20 14:59:54.850 openLuup.io.server:: HTTP:3480 connection closed openLuup.server.receive closed tcp{client}: 0x55b6013dacd8 2021-08-20 14:59:55.835 openLuup.io.server:: HTTP:3480 connection from 127.0.0.1 tcp{client}: 0x55b602316198 2021-08-20 14:59:55.836 openLuup.server:: GET /data_request?id=sdata HTTP/1.1 tcp{client}: 0x55b602316198 2021-08-20 14:59:55.841 openLuup.server:: request completed (49215 bytes, 4 chunks, 5 ms) tcp{client}: 0x55b602316198 2021-08-20 14:59:55.842 openLuup.io.server:: HTTP:3480 connection closed openLuup.server.receive closed tcp{client}: 0x55b602316198 2021-08-20 15:00:10.955 openLuup.io.server:: HTTP:3480 connection from 127.0.0.1 tcp{client}: 0x55b601547598 2021-08-20 15:00:10.955 openLuup.io.server:: HTTP:3480 connection from 127.0.0.1 tcp{client}: 0x55b6016c7cf8 2021-08-20 15:00:10.955 openLuup.io.server:: HTTP:3480 connection from 127.0.0.1 tcp{client}: 0x55b600b75158 2021-08-20 15:00:10.955 openLuup.io.server:: HTTP:3480 connection from 127.0.0.1 tcp{client}: 0x55b60187d188 2021-08-20 15:00:10.955 openLuup.io.server:: HTTP:3480 connection from 127.0.0.1 tcp{client}: 0x55b6011a5bf8 2021-08-20 15:00:10.955 openLuup.io.server:: HTTP:3480 connection from 127.0.0.1 tcp{client}: 0x55b60115ecd8 2021-08-20 15:00:10.955 openLuup.io.server:: HTTP:3480 connection from 127.0.0.1 tcp{client}: 0x55b601018c18 2021-08-20 15:00:10.956 openLuup.io.server:: HTTP:3480 connection from 127.0.0.1 tcp{client}: 0x55b60175d068 2021-08-20 15:00:10.956 openLuup.io.server:: HTTP:3480 connection from 127.0.0.1 tcp{client}: 0x55b60224ced8 2021-08-20 15:00:10.956 openLuup.io.server:: HTTP:3480 connection from 127.0.0.1 tcp{client}: 0x55b6017869d8 2021-08-20 15:00:10.956 openLuup.io.server:: HTTP:3480 connection from 127.0.0.1 tcp{client}: 0x55b601ab40e8 2021-08-20 15:00:10.956 openLuup.io.server:: HTTP:3480 connection from 127.0.0.1 tcp{client}: 0x55b601914178 2021-08-20 15:00:10.956 openLuup.io.server:: HTTP:3480 connection from 127.0.0.1 tcp{client}: 0x55b60100a228 2021-08-20 15:00:10.956 openLuup.io.server:: HTTP:3480 connection from 127.0.0.1 tcp{client}: 0x55b60197a998 2021-08-20 15:00:10.956 openLuup.io.server:: HTTP:3480 connection from 127.0.0.1 tcp{client}: 0x55b601103238 2021-08-20 15:00:10.956 openLuup.io.server:: MQTT:1888 connection from 127.0.0.1 tcp{client}: 0x55b600c62438 2021-08-20 15:00:10.956 openLuup.io.server:: MQTT:1888 connection from 127.0.0.1 tcp{client}: 0x55b601eaabf8 2021-08-20 15:00:10.956 openLuup.io.server:: MQTT:1888 connection from 127.0.0.1 tcp{client}: 0x55b6017c95b8 2021-08-20 15:00:10.956 openLuup.io.server:: MQTT:1888 connection from 127.0.0.1 tcp{client}: 0x55b600d6e508 2021-08-20 15:00:10.956 openLuup.io.server:: MQTT:1888 connection from 127.0.0.1 tcp{client}: 0x55b601dd2168 2021-08-20 15:00:10.956 openLuup.io.server:: MQTT:1888 connection from 127.0.0.1 tcp{client}: 0x55b60155eb68 2021-08-20 15:00:10.957 openLuup.io.server:: MQTT:1888 connection from 127.0.0.1 tcp{client}: 0x55b6016ddcd8 2021-08-20 15:00:10.957 openLuup.io.server:: MQTT:1888 connection from 127.0.0.1 tcp{client}: 0x55b602384ca8 2021-08-20 15:00:10.957 openLuup.io.server:: MQTT:1888 connection from 127.0.0.1 tcp{client}: 0x55b60230cc78 2021-08-20 15:00:10.957 openLuup.io.server:: MQTT:1888 connection from 127.0.0.1 tcp{client}: 0x55b6017e3338 2021-08-20 15:00:10.957 openLuup.io.server:: MQTT:1888 connection from 127.0.0.1 tcp{client}: 0x55b600965de8 2021-08-20 15:00:10.957 openLuup.io.server:: MQTT:1888 connection from 127.0.0.1 tcp{client}: 0x55b601e23608 2021-08-20 15:00:10.957 openLuup.io.server:: MQTT:1888 connection from 127.0.0.1 tcp{client}: 0x55b6018da2f8 2021-08-20 15:00:10.957 openLuup.io.server:: MQTT:1888 connection from 127.0.0.1 tcp{client}: 0x55b600c9aa08 2021-08-20 15:00:10.957 openLuup.io.server:: MQTT:1888 connection from 127.0.0.1 tcp{client}: 0x55b600bbf0f8 2021-08-20 15:00:10.957 openLuup.io.server:: MQTT:1888 connection from 127.0.0.1 tcp{client}: 0x55b601334518 2021-08-20 15:00:10.958 openLuup.io.server:: MQTT:1888 connection from 127.0.0.1 tcp{client}: 0x55b60183b5f8 2021-08-20 15:00:10.958 openLuup.io.server:: MQTT:1888 connection from 127.0.0.1 tcp{client}: 0x55b6015682a8 2021-08-20 15:00:10.958 openLuup.io.server:: MQTT:1888 connection from 127.0.0.1 tcp{client}: 0x55b6018869e8 2021-08-20 15:00:10.958 openLuup.io.server:: MQTT:1888 connection from 127.0.0.1 tcp{client}: 0x55b6008839c8 2021-08-20 15:00:10.958 openLuup.io.server:: MQTT:1888 connection from 127.0.0.1 tcp{client}: 0x55b60154ed58 2021-08-20 15:00:10.959 openLuup.io.server:: MQTT:1888 connection from 127.0.0.1 tcp{client}: 0x55b601a259e8 2021-08-20 15:00:10.959 openLuup.io.server:: MQTT:1888 connection from 127.0.0.1 tcp{client}: 0x55b600f617c8 2021-08-20 15:00:10.959 openLuup.io.server:: MQTT:1888 connection from 127.0.0.1 tcp{client}: 0x55b6015884c8 2021-08-20 15:00:10.959 openLuup.io.server:: MQTT:1888 connection from 127.0.0.1 tcp{client}: 0x55b601c85fe8 2021-08-20 15:00:10.959 openLuup.io.server:: MQTT:1888 connection from 127.0.0.1 tcp{client}: 0x55b602275ae8 2021-08-20 15:00:10.959 openLuup.io.server:: MQTT:1888 connection from 127.0.0.1 tcp{client}: 0x55b60190ab88 2021-08-20 15:00:10.959 openLuup.io.server:: MQTT:1888 connection from 127.0.0.1 tcp{client}: 0x55b601692038 2021-08-20 15:00:10.959 openLuup.io.server:: MQTT:1888 connection from 127.0.0.1 tcp{client}: 0x55b601fcea78 2021-08-20 15:00:10.960 openLuup.io.server:: MQTT:1888 connection from 127.0.0.1 tcp{client}: 0x55b601ca7f28 2021-08-20 15:00:10.960 openLuup.io.server:: MQTT:1888 connection from 127.0.0.1 tcp{client}: 0x55b601fd37f8 2021-08-20 15:00:10.960 openLuup.io.server:: MQTT:1888 connection from 127.0.0.1 tcp{client}: 0x55b6020ec968 2021-08-20 15:00:10.961 openLuup.server:: GET /data_request?id=variableget&DeviceNum=0&serviceId=urn:micasaverde-com:serviceId:HomeAutomationGateway1&Variable=Mode&_=1629292536033 HTTP/1.1 tcp{client}: 0x55b601ab8228 2021-08-20 15:00:10.967 luup_log:0: 32Mb, 3.3%cpu, 11.0days 2021-08-20 15:00:10.972 openLuup.server:: request completed (3361 bytes, 1 chunks, 17459 ms) tcp{client}: 0x55b601a37db8 2021-08-20 15:00:10.973 openLuup.server:: request completed (3361 bytes, 1 chunks, 17449 ms) tcp{client}: 0x55b600bc7068 2021-08-20 15:00:10.990 luup.variable_set:: 10116.urn:upnp-org:serviceId:TemperatureSensor1.CurrentTemperature was: 21.8 now: 21.9 #hooks:0 2021-08-20 15:00:10.990 luup.variable_set:: 10129.urn:micasaverde-com:serviceId:HumiditySensor1.CurrentLevel was: 58.8 now: 58.7 #hooks:0 2021-08-20 15:00:10.991 openLuup.io.server:: MQTT:1888 connection closed tcp{client}: 0x55b600ba45a8 2021-08-20 15:00:10.991 openLuup.mqtt:: closed tcp{client}: 0x55b600ba45a8 2021-08-20 15:00:10.992 openLuup.mqtt:: mqttthing_Klimat kllaren_838dee2e UNSUBSCRIBE from tele/Källartemp/SENSOR tcp{client}: 0x55b600ba45a8 2021-08-20 15:00:10.992 openLuup.mqtt:: ERROR publishing application message for mqtt:tele/Källartemp/SENSOR : closed 2021-08-20 15:00:10.992 openLuup.io.server:: HTTP:3480 connection from 127.0.0.1 tcp{client}: 0x55b601c8c288 2021-08-20 15:00:10.992 openLuup.io.server:: HTTP:3480 connection closed openLuup.server.receive closed tcp{client}: 0x55b600bc7068 2021-08-20 15:00:10.992 openLuup.server:: GET /data_request?id=sdata HTTP/1.1 tcp{client}: 0x55b601547598 2021-08-20 15:00:10.993 openLuup.io.server:: MQTT:1888 connection closed tcp{client}: 0x55b600a62818 2021-08-20 15:00:10.993 openLuup.mqtt:: RECEIVE ERROR: closed tcp{client}: 0x55b600a62818 2021-08-20 15:00:10.994 openLuup.mqtt:: mqttthing_Uttag garaget_6f4265a1 UNSUBSCRIBE from openLuup/update/10149/SwitchPower1/Status tcp{client}: 0x55b600a62818 2021-08-20 15:00:10.994 openLuup.io.server:: MQTT:1888 connection closed tcp{client}: 0x55b600ba8428 2021-08-20 15:00:10.994 openLuup.mqtt:: RECEIVE ERROR: closed tcp{client}: 0x55b600ba8428 2021-08-20 15:00:10.994 openLuup.mqtt:: mqttthing_Takflkt hallen_6da387bf UNSUBSCRIBE from tele/TakfläktHall/LWT tcp{client}: 0x55b600ba8428 2021-08-20 15:00:10.994 openLuup.mqtt:: mqttthing_Takflkt hallen_6da387bf UNSUBSCRIBE from stat/TakfläktHall/POWER2 tcp{client}: 0x55b600ba8428 2021-08-20 15:00:10.994 openLuup.io.server:: MQTT:1888 connection closed tcp{client}: 0x55b600e59b88 2021-08-20 15:00:10.994 openLuup.mqtt:: RECEIVE ERROR: closed tcp{client}: 0x55b600e59b88 2021-08-20 15:00:10.994 openLuup.mqtt:: mqttthing_Klimat sovrummet_0d664e41 UNSUBSCRIBE from openLuup/update/20010/HumiditySensor1/CurrentLevel tcp{client}: 0x55b600e59b88 2021-08-20 15:00:10.994 openLuup.mqtt:: mqttthing_Klimat sovrummet_0d664e41 UNSUBSCRIBE from openLuup/update/20021/TemperatureSensor1/CurrentTemperature tcp{client}: 0x55b600e59b88 2021-08-20 15:00:10.994 openLuup.io.server:: MQTT:1888 connection closed tcp{client}: 0x55b600bd02d8 2021-08-20 15:00:10.994 openLuup.mqtt:: RECEIVE ERROR: closed tcp{client}: 0x55b600bd02d8 2021-08-20 15:00:10.994 openLuup.mqtt:: mqttthing_Entrdrr_6acda58a UNSUBSCRIBE from openLuup/update/10095/SecuritySensor1/Tripped tcp{client}: 0x55b600bd02d8 2021-08-20 15:00:10.995 openLuup.io.server:: MQTT:1888 connection closed tcp{client}: 0x55b600bb6cf8 2021-08-20 15:00:10.995 openLuup.mqtt:: RECEIVE ERROR: closed tcp{client}: 0x55b600bb6cf8 2021-08-20 15:00:10.995 openLuup.mqtt:: mqttthing_Temperatur poolen_a589d192 UNSUBSCRIBE from tele/Pooltemp/SENSOR tcp{client}: 0x55b600bb6cf8 2021-08-20 15:00:10.995 openLuup.io.server:: MQTT:1888 connection closed tcp{client}: 0x55b6009ff868 2021-08-20 15:00:10.995 openLuup.mqtt:: RECEIVE ERROR: closed tcp{client}: 0x55b6009ff868 2021-08-20 15:00:10.995 openLuup.mqtt:: mqttthing_Rrelsesensor hallen_cf7c8293 UNSUBSCRIBE from openLuup/update/20013/SecuritySensor1/Tripped tcp{client}: 0x55b6009ff868 2021-08-20 15:00:10.995 openLuup.io.server:: MQTT:1888 connection closed tcp{client}: 0x55b600a23e28 2021-08-20 15:00:10.995 openLuup.mqtt:: RECEIVE ERROR: closed tcp{client}: 0x55b600a23e28 2021-08-20 15:00:10.995 openLuup.mqtt:: mqttthing_Luftfuktighet badrum_d79a0ffb UNSUBSCRIBE from openLuup/update/10190/HumiditySensor1/CurrentLevel tcp{client}: 0x55b600a23e28 2021-08-20 15:00:10.995 openLuup.io.server:: MQTT:1888 connection closed tcp{client}: 0x55b600bb33b8 2021-08-20 15:00:10.995 openLuup.mqtt:: RECEIVE ERROR: closed tcp{client}: 0x55b600bb33b8 2021-08-20 15:00:10.995 openLuup.mqtt:: mqttthing_Ljussensor hallen_19569c17 UNSUBSCRIBE from openLuup/update/20006/LightSensor1/CurrentLevel tcp{client}: 0x55b600bb33b8 2021-08-20 15:00:10.995 openLuup.io.server:: MQTT:1888 connection closed tcp{client}: 0x55b600a136b8 2021-08-20 15:00:10.995 openLuup.mqtt:: RECEIVE ERROR: closed tcp{client}: 0x55b600a136b8 2021-08-20 15:00:10.995 openLuup.mqtt:: mqttthing_Ljussensor trningsrum_ebf319c5 UNSUBSCRIBE from openLuup/update/20004/LightSensor1/CurrentLevel tcp{client}: 0x55b600a136b8 2021-08-20 15:00:10.995 openLuup.io.server:: MQTT:1888 connection closed tcp{client}: 0x55b600babaa8 2021-08-20 15:00:10.995 openLuup.mqtt:: RECEIVE ERROR: closed tcp{client}: 0x55b600babaa8 2021-08-20 15:00:10.995 openLuup.mqtt:: mqttthing_Solceller watt_e351a23b UNSUBSCRIBE from openLuup/update/10163/LightSensor1/CurrentLevel tcp{client}: 0x55b600babaa8 2021-08-20 15:00:10.995 openLuup.io.server:: MQTT:1888 connection closed tcp{client}: 0x55b6004a9de8 2021-08-20 15:00:10.995 openLuup.mqtt:: RECEIVE ERROR: closed tcp{client}: 0x55b6004a9de8 2021-08-20 15:00:10.995 openLuup.mqtt:: mqttthing_Luftfuktighet dusch_91df9a58 UNSUBSCRIBE from openLuup/update/20008/HumiditySensor1/CurrentLevel tcp{client}: 0x55b6004a9de8 2021-08-20 15:00:10.995 openLuup.io.server:: MQTT:1888 connection closed tcp{client}: 0x55b600b84628 2021-08-20 15:00:10.995 openLuup.mqtt:: RECEIVE ERROR: closed tcp{client}: 0x55b600b84628 2021-08-20 15:00:10.996 openLuup.mqtt:: mqttthing_Altandrr kk_db6d3edd UNSUBSCRIBE from openLuup/update/10097/SecuritySensor1/Tripped tcp{client}: 0x55b600b84628 2021-08-20 15:00:10.996 openLuup.io.server:: MQTT:1888 connection closed tcp{client}: 0x55b600be6548 2021-08-20 15:00:10.996 openLuup.mqtt:: RECEIVE ERROR: closed tcp{client}: 0x55b600be6548 2021-08-20 15:00:10.996 openLuup.mqtt:: mqttthing_Test datarum_adbe85e6 UNSUBSCRIBE from openLuup/update/20007/Dimming1/LoadLevelTarget tcp{client}: 0x55b600be6548 2021-08-20 15:00:10.996 openLuup.mqtt:: mqttthing_Test datarum_adbe85e6 UNSUBSCRIBE from openLuup/update/20007/SwitchPower1/Status tcp{client}: 0x55b600be6548
And then in syslog:
Aug 20 15:00:11 username systemd[1]: openluup.service: Control process exited, code=exited status=7 Aug 20 15:00:11 username systemd[1]: openluup.service: Failed with result 'exit-code'.
-
@akbooer I'm having openLuup crashes too with similar log errors. The only thing I have noticed is that at 2 occasions when I rebooted my Ezlo Plus connected by Ezlo Bridge, openLuup have crashed shortly after. It does not do that every time but it's the only thing I've managed to correlate to the hangs. I've captured the logs before restarting openLuup, if they are of interest.
-
Are you using VeraBridge in asynchronous mode?
This doesn't sound like an MQTT issue, perhaps we should move this to a new thread.
-
Crillereplied to akbooer on Aug 20, 2021, 4:30 PM last edited by Crille Aug 20, 2021, 12:31 PM
-
That rather looks as though it has run out of file handles (which are used for sockets as well.)
- what machine is this running on?
- can I see the complete Startup Log?
- also, when running, the openLuup console Servers page.
-
Crillereplied to akbooer on Aug 20, 2021, 6:17 PM last edited by Crille Aug 20, 2021, 2:27 PM
@akbooer It's running on a virtual Ubuntu 18.04 LTS server that also run Homebridge and MSR, therefore a lot of calls from 127.0.0.1.
Startup log:
2021-08-03 13:01:53.798 :: openLuup STARTUP :: /home/username/cmh-ludl 2021-08-03 13:01:53.798 openLuup.init:: version 2021.06.14 @akbooer 2021-08-03 13:01:53.822 openLuup.scheduler:: version 2021.03.19 @akbooer 2021-08-03 13:01:53.852 openLuup.io:: version 2021.03.27 @akbooer 2021-08-03 13:01:53.852 openLuup.mqtt:: version 2021.06.08 @akbooer 2021-08-03 13:01:53.853 openLuup.wsapi:: version 2019.08.12 @akbooer 2021-08-03 13:01:53.854 openLuup.servlet:: version 2021.04.30 @akbooer 2021-08-03 13:01:53.854 openLuup.client:: version 2019.10.14 @akbooer 2021-08-03 13:01:53.854 openLuup.server:: version 2020.03.26 @akbooer 2021-08-03 13:01:53.857 openLuup.scenes:: version 2021.06.02 @akbooer 2021-08-03 13:01:53.863 openLuup.chdev:: version 2021.05.04 @akbooer 2021-08-03 13:01:53.863 openLuup.userdata:: version 2021.04.30 @akbooer 2021-08-03 13:01:53.863 openLuup.requests:: version 2021.02.20 @akbooer 2021-08-03 13:01:53.863 openLuup.gateway:: version 2021.05.08 @akbooer 2021-08-03 13:01:53.866 openLuup.smtp:: version 2018.04.12 @akbooer 2021-08-03 13:01:53.893 openLuup.historian:: version 2021.06.12 @akbooer 2021-08-03 13:01:53.893 openLuup.luup:: version 2021.05.14 @akbooer 2021-08-03 13:01:53.894 openLuup.pop3:: version 2018.04.23 @akbooer 2021-08-03 13:01:53.895 openLuup.compression:: version 2016.06.30 @akbooer 2021-08-03 13:01:53.895 openLuup.timers:: version 2021.05.23 @akbooer 2021-08-03 13:01:53.895 openLuup.logs:: version 2018.03.25 @akbooer 2021-08-03 13:01:53.895 openLuup.json:: version Cjson (2.1.0) + openLuup (2021.05.01) @akbooer 2021-08-03 13:01:53.929 luup.create_device:: [1] D_ZWaveNetwork.xml / I_ZWave.xml / (urn:schemas-micasaverde-com:device:ZWaveNetwork:1) 2021-08-03 13:01:53.935 luup.create_device:: [2] D_openLuup.xml / I_openLuup.xml / D_openLuup.json (openLuup) 2021-08-03 13:01:53.935 openLuup.init:: loading configuration user_data.json 2021-08-03 13:01:53.942 openLuup.userdata:: loading user_data json... 2021-08-03 13:01:53.951 luup.variable_set:: 2.openLuup.HouseMode was: EMPTY now: 1 #hooks:0 2021-08-03 13:01:53.951 openLuup.userdata:: loading rooms... 2021-08-03 13:01:53.951 openLuup.userdata:: room#1 'MiOS-XXXXXX' 2021-08-03 13:01:53.951 openLuup.userdata:: room#4 'Källaren' 2021-08-03 13:01:53.951 openLuup.userdata:: room#5 'Sovrummet' 2021-08-03 13:01:53.951 openLuup.userdata:: room#6 'Vera sensorer' 2021-08-03 13:01:53.951 openLuup.userdata:: room#7 'Datarummet' 2021-08-03 13:01:53.951 openLuup.userdata:: room#8 'Z - Virtual' 2021-08-03 13:01:53.951 openLuup.userdata:: room#9 'Hela huset' 2021-08-03 13:01:53.951 openLuup.userdata:: room#10 'Köket' 2021-08-03 13:01:53.951 openLuup.userdata:: room#11 'Vardagsrummet' 2021-08-03 13:01:53.951 openLuup.userdata:: room#12 'Milias rum' 2021-08-03 13:01:53.951 openLuup.userdata:: room#13 'Hallen' 2021-08-03 13:01:53.951 openLuup.userdata:: room#14 'Ezlo-90000XXX' 2021-08-03 13:01:53.951 openLuup.userdata:: room#15 'Baksidan (Ute)' 2021-08-03 13:01:53.951 openLuup.userdata:: room#16 'Tasmota' 2021-08-03 13:01:53.951 openLuup.userdata:: room#101 'Room 101' 2021-08-03 13:01:53.951 openLuup.userdata:: room#17 'Växthuset' 2021-08-03 13:01:53.951 openLuup.userdata:: ...room loading completed 2021-08-03 13:01:53.951 openLuup.userdata:: loading devices... 2021-08-03 13:01:53.952 luup.create_device:: [1] D_ZWaveNetwork.xml / X / (urn:schemas-micasaverde-com:device:ZWaveNetwork:1) 2021-08-03 13:01:54.100 openLuup.scheduler.sandbox:: device 3 defined 'string.starts' (a function value) 2021-08-03 13:01:54.100 openLuup.scheduler.sandbox:: device 3 defined 'string.altui_split' (a function value) 2021-08-03 13:01:54.100 openLuup.scheduler.sandbox:: device 3 defined 'string.template' (a function value) 2021-08-03 13:01:54.100 openLuup.scheduler.sandbox:: device 3 defined 'string.trim' (a function value) 2021-08-03 13:01:54.100 luup.create_device:: [3] D_ALTUI.xml / I_ALTUI.xml / D_ALTUI_UI7.json (urn:schemas-upnp-org:device:altui:1) 2021-08-03 13:01:54.104 luup.create_device:: [4] D_AltAppStore.xml / I_AltAppStore.xml / D_AltAppStore.json (urn:schemas-upnp-org:device:AltAppStore:1) 2021-08-03 13:01:54.110 luup.create_device:: [5] D_VeraBridge.xml / I_VeraBridge.xml / D_VeraBridge.json (VeraBridge) 2021-08-03 13:01:54.119 luup.create_device:: [9] D_EzloBridge.xml / I_EzloBridge.xml / D_EzloBridge.json (urn:schemas-rboer-com:device:EzloBridge:1) 2021-08-03 13:01:54.130 luup.create_device:: [10] D_VirtualBinaryLight1.xml / I_VirtualBinaryLight1.xml / D_BinaryLight1.json (urn:schemas-upnp-org:device:BinaryLight:1) 2021-08-03 13:01:54.221 luup.create_device:: [23] D_Homebridge2openluup1.xml / I_Homebridge2openluup1.xml / D_Homebridge2openluup1.json (urn:schemas-ctrlable-com:device:Homebridge2openluup1:1) 2021-08-03 13:01:54.223 luup.create_device:: [28] D_VirtualBinaryLight1.xml / I_VirtualBinaryLight1.xml / D_BinaryLight1.json (urn:schemas-upnp-org:device:BinaryLight:1) 2021-08-03 13:01:54.225 luup.create_device:: [29] D_VirtualBinaryLight1.xml / I_VirtualBinaryLight1.xml / D_BinaryLight1.json (urn:schemas-upnp-org:device:BinaryLight:1) 2021-08-03 13:01:54.310 luup.create_device:: [33] D_HVAC_ZoneThermostat1.xml / / D_HVAC_ZoneThermostat1.json (urn:schemas-upnp-org:device:HVAC_ZoneThermostat:1) 2021-08-03 13:01:54.312 luup.create_device:: [34] D_HVAC_ZoneThermostat1.xml / / D_HVAC_ZoneThermostat1.json (urn:schemas-upnp-org:device:HVAC_ZoneThermostat:1) 2021-08-03 13:01:54.315 luup.create_device:: [35] D_TasmotaBridge.xml / I_TasmotaBridge.xml / D_TasmotaBridge.json (TasmotaBridge) 2021-08-03 13:01:54.332 luup.create_device:: [37] D_VirtualSensor1.xml / I_VirtualSensor1.xml / D_VirtualSensor1.json (urn:schemas-toggledbits-com:device:VirtualSensor:1) 2021-08-03 13:01:54.334 luup.create_device:: [38] D_TemperatureSensor1.xml / / D_TemperatureSensor1.json (urn:schemas-micasaverde-com:device:TemperatureSensor:1) 2021-08-03 13:01:54.335 luup.create_device:: [39] D_TemperatureSensor1.xml / / D_TemperatureSensor1.json (urn:schemas-micasaverde-com:device:TemperatureSensor:1) 2021-08-03 13:01:54.336 luup.create_device:: [40] D_HumiditySensor1.xml / / D_HumiditySensor1.json (urn:schemas-micasaverde-com:device:HumiditySensor:1) 2021-08-03 13:01:54.338 luup.create_device:: [42] D_VirtualBinaryLight1.xml / I_VirtualBinaryLight1.xml / D_BinaryLight1.json (urn:schemas-upnp-org:device:BinaryLight:1) 2021-08-03 13:01:54.340 luup.create_device:: [43] D_VirtualBinaryLight1.xml / I_VirtualBinaryLight1.xml / D_BinaryLight1.json (urn:schemas-upnp-org:device:BinaryLight:1) 2021-08-03 13:01:54.342 luup.create_device:: [44] D_VirtualBinaryLight1.xml / I_VirtualBinaryLight1.xml / D_BinaryLight1.json (urn:schemas-upnp-org:device:BinaryLight:1) 2021-08-03 13:01:54.344 luup.create_device:: [45] D_VirtualBinaryLight1.xml / I_VirtualBinaryLight1.xml / D_BinaryLight1.json (urn:schemas-upnp-org:device:BinaryLight:1) 2021-08-03 13:01:54.346 luup.create_device:: [46] D_VirtualBinaryLight1.xml / I_VirtualBinaryLight1.xml / D_BinaryLight1.json (urn:schemas-upnp-org:device:BinaryLight:1) 2021-08-03 13:01:54.348 luup.create_device:: [47] D_VirtualBinaryLight1.xml / I_VirtualBinaryLight1.xml / D_BinaryLight1.json (urn:schemas-upnp-org:device:BinaryLight:1) 2021-08-03 13:01:54.374 luup.create_device:: [51] D_SiteSensor1.xml / I_SiteSensor1.xml / D_SiteSensor1.json (urn:schemas-toggledbits-com:device:SiteSensor:1) 2021-08-03 13:01:54.375 luup.create_device:: [52] D_HumiditySensor1.xml / / D_HumiditySensor1.json (urn:schemas-micasaverde-com:device:HumiditySensor:1) 2021-08-03 13:01:54.381 luup.create_device:: [10002] D_ZigbeeNetwork.xml / X / (urn:schemas-micasaverde-com:device:ZigbeeNetwork:1) 2021-08-03 13:01:54.384 luup.create_device:: [10003] D_BluetoothNetwork.xml / X / (urn:schemas-micasaverde-com:device:BluetoothNetwork:1) 2021-08-03 13:01:54.388 luup.create_device:: [10004] D_LPRFNetwork.xml / X / (urn:schemas-micasaverde-com:device:LPRFNetwork:1) 2021-08-03 13:01:54.391 luup.create_device:: [10005] D_SceneController1.xml / X / D_SceneController1.json (urn:schemas-micasaverde-com:device:SceneController:1) 2021-08-03 13:01:54.395 luup.create_device:: [10006] D_G550Siren.xml / X / D_G550Siren1.json (urn:schemas-micasaverde-com:device:G550Siren:1) 2021-08-03 13:01:54.406 luup.create_device:: [10016] D_GarageDoorLock.xml / X / D_DoorLock1.json (urn:schemas-micasaverde-com:device:DoorLock:1) 2021-08-03 13:01:54.413 luup.create_device:: [10027] D_VSwitch.xml / X / D_VSwitch_UI7.json (urn:schemas-upnp-org:device:VSwitch:1) 2021-08-03 13:01:54.415 luup.create_device:: [10028] D_VSwitch.xml / X / D_VSwitch_UI7.json (urn:schemas-upnp-org:device:VSwitch:1) 2021-08-03 13:01:54.415 luup.create_device:: [10032] D_GarageDoorLock.xml / X / D_DoorLock1.json (urn:schemas-micasaverde-com:device:DoorLock:1) 2021-08-03 13:01:54.416 luup.create_device:: [10046] D_VirtualBinaryLight1.xml / X / D_BinaryLight1.json (urn:schemas-upnp-org:device:BinaryLight:1) 2021-08-03 13:01:54.417 luup.create_device:: [10047] D_VirtualBinaryLight1.xml / X / D_BinaryLight1.json (urn:schemas-upnp-org:device:BinaryLight:1) 2021-08-03 13:01:54.418 luup.create_device:: [10048] D_VirtualBinaryLight1.xml / X / D_BinaryLight1.json (urn:schemas-upnp-org:device:BinaryLight:1) 2021-08-03 13:01:54.584 luup.create_device:: [10050] D_HouseModes.xml / X / D_HouseModes.json (urn:schemas-micasaverde-com:device:HouseModes:1) 2021-08-03 13:01:54.584 luup.create_device:: [10064] D_VirtualBinaryLight1.xml / X / D_BinaryLight1.json (urn:schemas-upnp-org:device:BinaryLight:1) 2021-08-03 13:01:54.585 luup.create_device:: [10065] D_VirtualBinaryLight1.xml / X / D_BinaryLight1.json (urn:schemas-upnp-org:device:BinaryLight:1) 2021-08-03 13:01:54.586 luup.create_device:: [10068] D_VSwitch.xml / X / D_VSwitch_UI7.json (urn:schemas-upnp-org:device:VSwitch:1) 2021-08-03 13:01:54.588 luup.create_device:: [10069] D_VSwitch.xml / X / D_VSwitch_UI7.json (urn:schemas-upnp-org:device:VSwitch:1) 2021-08-03 13:01:54.589 luup.create_device:: [10070] D_VSwitch.xml / X / D_VSwitch_UI7.json (urn:schemas-upnp-org:device:VSwitch:1) 2021-08-03 13:01:54.590 luup.create_device:: [10071] D_VSwitch.xml / X / D_VSwitch_UI7.json (urn:schemas-upnp-org:device:VSwitch:1) 2021-08-03 13:01:54.591 luup.create_device:: [10076] D_VSwitch.xml / X / D_VSwitch_UI7.json (urn:schemas-upnp-org:device:VSwitch:1) 2021-08-03 13:01:54.592 luup.create_device:: [10086] D_TemperatureSensor1.xml / X / D_TemperatureSensor1.json (urn:schemas-micasaverde-com:device:TemperatureSensor:1) 2021-08-03 13:01:54.676 luup.create_device:: [10093] D_BinaryLight1.xml / X / D_BinaryLight1.json (urn:schemas-upnp-org:device:BinaryLight:1) 2021-08-03 13:01:54.678 luup.create_device:: [10094] D_GenericIO1.xml / X / D_GenericIO1.json (urn:schemas-micasaverde-com:device:GenericIO:1) 2021-08-03 13:01:54.680 luup.create_device:: [10095] D_DoorSensor1.xml / X / D_DoorSensor1.json (urn:schemas-micasaverde-com:device:DoorSensor:1) 2021-08-03 13:01:54.681 luup.create_device:: [10096] D_GenericIO1.xml / X / D_GenericIO1.json (urn:schemas-micasaverde-com:device:GenericIO:1) 2021-08-03 13:01:54.682 luup.create_device:: [10097] D_DoorSensor1.xml / X / D_DoorSensor1.json (urn:schemas-micasaverde-com:device:DoorSensor:1) 2021-08-03 13:01:54.683 luup.create_device:: [10098] D_GenericIO1.xml / X / D_GenericIO1.json (urn:schemas-micasaverde-com:device:GenericIO:1) 2021-08-03 13:01:54.684 luup.create_device:: [10099] D_DoorSensor1.xml / X / D_DoorSensor1.json (urn:schemas-micasaverde-com:device:DoorSensor:1) 2021-08-03 13:01:54.685 luup.create_device:: [10100] D_BinaryLight1.xml / X / D_BinaryLight1.json (urn:schemas-upnp-org:device:BinaryLight:1) 2021-08-03 13:01:54.686 luup.create_device:: [10104] D_GenericIO1.xml / X / D_GenericIO1.json (urn:schemas-micasaverde-com:device:GenericIO:1) 2021-08-03 13:01:54.687 luup.create_device:: [10105] D_DoorSensor1.xml / X / D_DoorSensor1.json (urn:schemas-micasaverde-com:device:DoorSensor:1) 2021-08-03 13:01:54.688 luup.create_device:: [10106] D_GenericIO1.xml / X / D_GenericIO1.json (urn:schemas-micasaverde-com:device:GenericIO:1) 2021-08-03 13:01:54.689 luup.create_device:: [10107] D_DoorSensor1.xml / X / D_DoorSensor1.json (urn:schemas-micasaverde-com:device:DoorSensor:1) 2021-08-03 13:01:54.690 luup.create_device:: [10109] D_GenericIO1.xml / X / D_GenericIO1.json (urn:schemas-micasaverde-com:device:GenericIO:1) 2021-08-03 13:01:54.691 luup.create_device:: [10110] D_DoorSensor1.xml / X / D_DoorSensor1.json (urn:schemas-micasaverde-com:device:DoorSensor:1) 2021-08-03 13:01:54.692 luup.create_device:: [10111] D_BinaryLight1.xml / X / D_BinaryLight1.json (urn:schemas-upnp-org:device:BinaryLight:1) 2021-08-03 13:01:54.766 luup.create_device:: [10112] D_Keypad1.xml / X / D_Keypad_Wintop.json (urn:schemas-micasaverde-com:device:Keypad:1) 2021-08-03 13:01:54.768 luup.create_device:: [10113] D_VirtualBinaryLight1.xml / X / D_BinaryLight1.json (urn:schemas-upnp-org:device:BinaryLight:1) 2021-08-03 13:01:54.770 luup.create_device:: [10115] D_VSwitch.xml / X / D_VSwitch_UI7.json (urn:schemas-upnp-org:device:VSwitch:1) 2021-08-03 13:01:54.771 luup.create_device:: [10116] D_TemperatureSensor1.xml / X / D_TemperatureSensor1.json (urn:schemas-micasaverde-com:device:TemperatureSensor:1) 2021-08-03 13:01:54.772 luup.create_device:: [10127] D_VSwitch.xml / X / D_VSwitch_UI7.json (urn:schemas-upnp-org:device:VSwitch:1) 2021-08-03 13:01:54.773 luup.create_device:: [10129] D_HumiditySensor1.xml / X / D_HumiditySensor1.json (urn:schemas-micasaverde-com:device:HumiditySensor:1) 2021-08-03 13:01:54.774 luup.create_device:: [10130] D_VirtualBinaryLight1.xml / X / D_BinaryLight1.json (urn:schemas-upnp-org:device:BinaryLight:1) 2021-08-03 13:01:54.864 luup.create_device:: [10131] D_MotionSensor1.xml / X / D_GlassBreakSensorWithTamper.json (urn:schemas-micasaverde-com:device:MotionSensor:1) 2021-08-03 13:01:54.865 luup.create_device:: [10132] D_MotionSensor1.xml / X / D_GlassBreakSensorWithTamper.json (urn:schemas-micasaverde-com:device:MotionSensor:1) 2021-08-03 13:01:54.865 luup.create_device:: [10133] D_MotionSensor1.xml / X / D_GlassBreakSensorWithTamper.json (urn:schemas-micasaverde-com:device:MotionSensor:1) 2021-08-03 13:01:54.866 luup.create_device:: [10149] D_BinaryLight1.xml / X / D_BinaryLight1.json (urn:schemas-upnp-org:device:BinaryLight:1) 2021-08-03 13:01:54.868 luup.create_device:: [10162] D_PowerMeter1.xml / X / D_PowerMeter1.json (urn:schemas-micasaverde-com:device:PowerMeter:1) 2021-08-03 13:01:54.870 luup.create_device:: [10163] D_LightSensor1.xml / X / D_LightSensor1.json (urn:schemas-micasaverde-com:device:LightSensor:1) 2021-08-03 13:01:54.872 luup.create_device:: [10165] D_SmokeSensor1.xml / X / D_SmokeSensorWithTamper1.json (urn:schemas-micasaverde-com:device:SmokeSensor:1) 2021-08-03 13:01:54.874 luup.create_device:: [10166] D_TemperatureSensor1.xml / X / D_TemperatureSensor1.json (urn:schemas-micasaverde-com:device:TemperatureSensor:1) 2021-08-03 13:01:54.960 luup.create_device:: [10167] D_DimmableLight1.xml / X / D_DimmableLightQD1.json (urn:schemas-upnp-org:device:DimmableLight:1) 2021-08-03 13:01:54.962 luup.create_device:: [10168] D_DimmableLight1.xml / X / D_DimmableLight1.json (urn:schemas-upnp-org:device:DimmableLight:1) 2021-08-03 13:01:54.963 luup.create_device:: [10184] D_VirtualBinaryLight1.xml / X / D_BinaryLight1.json (urn:schemas-upnp-org:device:BinaryLight:1) 2021-08-03 13:01:54.964 luup.create_device:: [10185] D_VirtualBinaryLight1.xml / X / D_BinaryLight1.json (urn:schemas-upnp-org:device:BinaryLight:1) 2021-08-03 13:01:54.965 luup.create_device:: [10187] D_VSwitch.xml / X / D_VSwitch_UI7.json (urn:schemas-upnp-org:device:VSwitch:1) 2021-08-03 13:01:54.967 luup.create_device:: [10188] D_ComboDevice1.xml / X / D_ComboDevice1.json (urn:schemas-micasaverde-com:device:ComboDevice:1) 2021-08-03 13:01:54.968 luup.create_device:: [10189] D_TemperatureSensor1.xml / X / D_TemperatureSensor1.json (urn:schemas-micasaverde-com:device:TemperatureSensor:1) 2021-08-03 13:01:54.969 luup.create_device:: [10190] D_HumiditySensor1.xml / X / D_HumiditySensor1.json (urn:schemas-micasaverde-com:device:HumiditySensor:1) 2021-08-03 13:01:54.970 luup.create_device:: [10191] D_Siren1.xml / X / D_Siren1.json (urn:schemas-micasaverde-com:device:Siren:1) 2021-08-03 13:01:54.971 luup.create_device:: [10192] D_Siren1.xml / X / D_Siren1.json (urn:schemas-micasaverde-com:device:Siren:1) 2021-08-03 13:01:54.973 luup.create_device:: [10199] D_DimmableLight1.xml / X / D_DimmableLight1.json (urn:schemas-upnp-org:device:DimmableLight:1) 2021-08-03 13:01:54.974 luup.create_device:: [10200] D_DimmableLight1.xml / X / D_DimmableLight1.json (urn:schemas-upnp-org:device:DimmableLight:1) 2021-08-03 13:01:54.975 luup.create_device:: [10215] D_HumiditySensor1.xml / X / D_HumiditySensor1.json (urn:schemas-micasaverde-com:device:HumiditySensor:1) 2021-08-03 13:01:54.976 luup.create_device:: [10216] D_TemperatureSensor1.xml / X / D_TemperatureSensor1.json (urn:schemas-micasaverde-com:device:TemperatureSensor:1) 2021-08-03 13:01:54.977 luup.create_device:: [10217] D_VSwitch.xml / X / D_VSwitch_UI7.json (urn:schemas-upnp-org:device:VSwitch:1) 2021-08-03 13:01:54.979 luup.create_device:: [10218] D_VirtualMotionSensor1.xml / X / D_MotionSensor1.json (urn:schemas-micasaverde-com:device:SmokeSensor:1) 2021-08-03 13:01:54.980 luup.create_device:: [10219] D_VirtualMotionSensor1.xml / X / D_MotionSensor1.json (urn:schemas-micasaverde-com:device:SmokeSensor:1) 2021-08-03 13:01:54.981 luup.create_device:: [10220] D_TemperatureSensor1.xml / X / D_TemperatureSensor1.json (urn:schemas-micasaverde-com:device:TemperatureSensor:1) 2021-08-03 13:01:54.981 luup.create_device:: [10221] D_HumiditySensor1.xml / X / D_HumiditySensor1.json (urn:schemas-micasaverde-com:device:HumiditySensor:1) 2021-08-03 13:01:54.982 luup.create_device:: [10222] D_LightSensor1.xml / X / D_LightSensor1.json (urn:schemas-micasaverde-com:device:LightSensor:1) 2021-08-03 13:01:54.983 luup.create_device:: [10223] D_LightSensor1.xml / X / D_LightSensor1.json (urn:schemas-micasaverde-com:device:LightSensor:1) 2021-08-03 13:01:54.984 luup.create_device:: [20004] D_LightSensor1.xml / X / D_LightSensor1.json (urn:schemas-micasaverde-com:device:LightSensor:1) 2021-08-03 13:01:54.985 luup.create_device:: [20005] D_DimmableLight1.xml / X / D_DimmableLight1.json (urn:schemas-upnp-org:device:DimmableLight:1) 2021-08-03 13:01:54.986 luup.create_device:: [20006] D_LightSensor1.xml / X / D_LightSensor1.json (urn:schemas-micasaverde-com:device:LightSensor:1) 2021-08-03 13:01:54.987 luup.create_device:: [20007] D_DimmableLight1.xml / X / D_DimmableLight1.json (urn:schemas-upnp-org:device:DimmableLight:1) 2021-08-03 13:01:54.988 luup.create_device:: [20008] D_HumiditySensor1.xml / X / D_HumiditySensor1.json (urn:schemas-micasaverde-com:device:HumiditySensor:1) 2021-08-03 13:01:54.989 luup.create_device:: [20009] D_DimmableLight1.xml / X / D_BinaryLight1.json (urn:schemas-upnp-org:device:DimmableLight:1) 2021-08-03 13:01:54.990 luup.create_device:: [20010] D_HumiditySensor1.xml / X / D_HumiditySensor1.json (urn:schemas-micasaverde-com:device:HumiditySensor:1) 2021-08-03 13:01:54.991 luup.create_device:: [20011] D_DimmableLight1.xml / X / D_DimmableLight1.json (urn:schemas-upnp-org:device:DimmableLight:1) 2021-08-03 13:01:54.992 luup.create_device:: [20012] D_DimmableLight1.xml / X / D_DimmableLight1.json (urn:schemas-upnp-org:device:DimmableLight:1) 2021-08-03 13:01:54.993 luup.create_device:: [20013] D_MotionSensor1.xml / X / D_MotionSensor1.json (urn:schemas-micasaverde-com:device:MotionSensor:1) 2021-08-03 13:01:54.994 luup.create_device:: [20014] D_DimmableLight1.xml / X / D_DimmableLight1.json (urn:schemas-upnp-org:device:DimmableLight:1) 2021-08-03 13:01:54.995 luup.create_device:: [20015] D_TemperatureSensor1.xml / X / D_TemperatureSensor1.json (urn:schemas-micasaverde-com:device:TemperatureSensor:1) 2021-08-03 13:01:54.996 luup.create_device:: [20016] D_DimmableLight1.xml / X / D_DimmableLight1.json (urn:schemas-upnp-org:device:DimmableLight:1) 2021-08-03 13:01:54.997 luup.create_device:: [20018] D_DimmableLight1.xml / X / D_DimmableLight1.json (urn:schemas-upnp-org:device:DimmableLight:1) 2021-08-03 13:01:54.999 luup.create_device:: [20019] D_DimmableLight1.xml / X / D_DimmableLight1.json (urn:schemas-upnp-org:device:DimmableLight:1) 2021-08-03 13:01:55.000 luup.create_device:: [20020] D_DimmableLight1.xml / X / D_DimmableLight1.json (urn:schemas-upnp-org:device:DimmableLight:1) 2021-08-03 13:01:55.000 luup.create_device:: [20021] D_TemperatureSensor1.xml / X / D_TemperatureSensor1.json (urn:schemas-micasaverde-com:device:TemperatureSensor:1) 2021-08-03 13:01:55.002 luup.create_device:: [20022] D_DimmableLight1.xml / X / D_DimmableLight1.json (urn:schemas-upnp-org:device:DimmableLight:1) 2021-08-03 13:01:55.003 luup.create_device:: [20024] D_DimmableLight1.xml / X / D_DimmableLight1.json (urn:schemas-upnp-org:device:DimmableLight:1) 2021-08-03 13:01:55.005 luup.create_device:: [20025] D_DimmableLight1.xml / X / D_DimmableLight1.json (urn:schemas-upnp-org:device:DimmableLight:1) 2021-08-03 13:01:55.006 luup.create_device:: [20026] D_MotionSensor1.xml / X / D_MotionSensor1.json (urn:schemas-micasaverde-com:device:MotionSensor:1) 2021-08-03 13:01:55.007 luup.create_device:: [20029] D_DimmableLight1.xml / X / D_DimmableLight1.json (urn:schemas-upnp-org:device:DimmableLight:1) 2021-08-03 13:01:55.008 luup.create_device:: [20030] D_DimmableLight1.xml / X / D_DimmableLight1.json (urn:schemas-upnp-org:device:DimmableLight:1) 2021-08-03 13:01:55.009 luup.create_device:: [20031] D_DimmableLight1.xml / X / D_DimmableLight1.json (urn:schemas-upnp-org:device:DimmableLight:1) 2021-08-03 13:01:55.010 luup.create_device:: [30001] D_GenericTasmotaDevice.xml / / D_GenericTasmotaDevice.json (GenericTasmotaDevice) 2021-08-03 13:01:55.010 luup.create_device:: [30003] D_GenericTasmotaDevice.xml / / D_GenericTasmotaDevice.json (GenericTasmotaDevice) 2021-08-03 13:01:55.010 luup.create_device:: [30004] D_GenericTasmotaDevice.xml / / D_GenericTasmotaDevice.json (GenericTasmotaDevice) 2021-08-03 13:01:55.011 luup.create_device:: [30005] D_GenericTasmotaDevice.xml / / D_GenericTasmotaDevice.json (GenericTasmotaDevice) 2021-08-03 13:01:55.011 luup.create_device:: [30006] D_GenericTasmotaDevice.xml / / D_GenericTasmotaDevice.json (GenericTasmotaDevice) 2021-08-03 13:01:55.011 luup.create_device:: [30007] D_GenericTasmotaDevice.xml / / D_GenericTasmotaDevice.json (GenericTasmotaDevice) 2021-08-03 13:01:55.012 luup.create_device:: [30008] D_GenericTasmotaDevice.xml / / D_GenericTasmotaDevice.json (GenericTasmotaDevice) 2021-08-03 13:01:55.012 luup.create_device:: [30009] D_GenericTasmotaDevice.xml / / D_GenericTasmotaDevice.json (GenericTasmotaDevice) 2021-08-03 13:01:55.012 openLuup.userdata:: loading scenes... 2021-08-03 13:01:55.012 openLuup.userdata:: [11] Dimma upp Sovrum Taklampa 2021-08-03 13:01:55.012 openLuup.userdata:: [10] Dimma ned Sovrummet 2021-08-03 13:01:55.012 openLuup.userdata:: [9] Dimma upp Milias rum 2021-08-03 13:01:55.012 openLuup.userdata:: number of scenes = 3 2021-08-03 13:01:55.012 openLuup.userdata:: ...scene loading completed 2021-08-03 13:01:55.012 openLuup.userdata:: loading installed plugin info... 2021-08-03 13:01:55.013 openLuup.userdata:: [openLuup] openLuup (21.7.25) 2021-08-03 13:01:55.013 openLuup.userdata:: [AltAppStore] Alternate App Store (20.3.30) 2021-08-03 13:01:55.013 openLuup.userdata:: [VeraBridge] VeraBridge (21.1.3) 2021-08-03 13:01:55.013 openLuup.userdata:: [8246] Alternate UI (2.53c.) 2021-08-03 13:01:55.013 openLuup.userdata:: [Z-Way] Z-Way (not.installed) 2021-08-03 13:01:55.013 openLuup.userdata:: [Arduino] MySensors (not.installed) 2021-08-03 13:01:55.013 openLuup.userdata:: [9314] Ezlo Bridge (GitHub.master) 2021-08-03 13:01:55.013 openLuup.userdata:: [9281] Virtual HTTP Devices (GitHub.master) 2021-08-03 13:01:55.013 openLuup.userdata:: [9031] Virtual Sensor (1.11) 2021-08-03 13:01:55.013 openLuup.userdata:: [8942] SiteSensor (1.15) 2021-08-03 13:01:55.013 openLuup.userdata:: ...user_data loading completed 2021-08-03 13:01:55.013 openLuup.init:: running _openLuup_STARTUP_ 2021-08-03 13:01:55.013 luup_log:0: MagicShowerFan: Function loaded successfully. 2021-08-03 13:01:55.013 luup_log:0: MagicShowerFan: Starting to monitor showers... 2021-08-03 13:01:55.013 luup.variable_watch:: callback=MagicShowerFan, watching=10190.urn:micasaverde-com:serviceId:HumiditySensor1.CurrentLevel 2021-08-03 13:01:55.013 luup.variable_watch:: callback=MagicShowerFan, watching=20008.urn:micasaverde-com:serviceId:HumiditySensor1.CurrentLevel 2021-08-03 13:01:55.013 openLuup.init:: init phase completed 2021-08-03 13:01:55.013 :: openLuup LOG ROTATION :: (runtime 0.0 days)
Server console page:
Server sockets watched for incoming connections # date / time device socket 1 2021-08-20 20:15:52 0 HTTP:3480 tcp{client}: 0x55bab90e01a8 2 2021-08-20 20:15:52 0 HTTP:3480 tcp{client}: 0x55bab9d0b718 3 2021-08-20 20:15:52 0 HTTP:3480 tcp{client}: 0x55bab8de9968 4 2021-08-20 20:15:52 0 HTTP:3480 tcp{client}: 0x55bab9cce768 5 2021-08-20 20:15:52 0 HTTP:3480 tcp{client}: 0x55bab9dda538 6 2021-08-20 20:15:52 0 HTTP:3480 tcp{client}: 0x55bab9856bb8 7 2021-08-20 20:15:43 0 HTTP:3480 tcp{client}: 0x55bab9ecaa58 8 2021-08-20 20:15:21 0 HTTP:3480 tcp{client}: 0x55bab8d74018 9 2021-08-20 20:04:16 0 HTTP:3480 tcp{client}: 0x55bab9aaf008 10 2021-08-20 20:00:15 0 HTTP:3480 tcp{client}: 0x55bab9863d48 11 2021-08-20 17:59:58 0 MQTT:1888 tcp{client}: 0x55bab8c24e48 12 2021-08-20 17:25:37 0 HTTP:3480 tcp{client}: 0x55bab8c3b128 13 2021-08-20 15:13:23 0 MQTT:1888 tcp{client}: 0x55bab90d5438 14 2021-08-20 15:12:18 0 MQTT:1888 tcp{client}: 0x55bab8bdb198 15 2021-08-20 15:12:18 0 MQTT:1888 tcp{client}: 0x55bab8f9d898 16 2021-08-20 15:12:18 0 MQTT:1888 tcp{client}: 0x55bab86e7f58 17 2021-08-20 15:12:18 0 MQTT:1888 tcp{client}: 0x55bab8e87328 18 2021-08-20 15:12:18 0 MQTT:1888 tcp{client}: 0x55bab8c69918 19 2021-08-20 15:12:18 0 MQTT:1888 tcp{client}: 0x55bab8839b78 20 2021-08-20 15:12:18 0 MQTT:1888 tcp{client}: 0x55bab8f0fa38 21 2021-08-20 15:12:18 0 MQTT:1888 tcp{client}: 0x55bab8bc3808 22 2021-08-20 15:12:18 0 MQTT:1888 tcp{client}: 0x55bab8df8c48 23 2021-08-20 15:12:18 0 MQTT:1888 tcp{client}: 0x55bab8fe2c88 24 2021-08-20 15:12:18 0 MQTT:1888 tcp{client}: 0x55bab8fc8938 25 2021-08-20 15:12:18 0 MQTT:1888 tcp{client}: 0x55bab8fc0fe8 26 2021-08-20 15:12:18 0 MQTT:1888 tcp{client}: 0x55bab8d39d68 27 2021-08-20 15:12:18 0 MQTT:1888 tcp{client}: 0x55bab8fd0288 28 2021-08-20 15:12:18 0 MQTT:1888 tcp{client}: 0x55bab8f20f68 29 2021-08-20 15:12:18 0 MQTT:1888 tcp{client}: 0x55bab8f52ee8 30 2021-08-20 15:12:18 0 MQTT:1888 tcp{client}: 0x55bab8f3b908 31 2021-08-20 15:12:18 0 MQTT:1888 tcp{client}: 0x55bab7fd7868 32 2021-08-20 15:12:18 0 MQTT:1888 tcp{client}: 0x55bab7fdbf88 33 2021-08-20 15:12:18 0 MQTT:1888 tcp{client}: 0x55bab8d5eb58 34 2021-08-20 15:12:18 0 MQTT:1888 tcp{client}: 0x55bab8ee8998 35 2021-08-20 15:12:18 0 MQTT:1888 tcp{client}: 0x55bab8c31438 36 2021-08-20 15:12:18 0 MQTT:1888 tcp{client}: 0x55bab8fd72b8 37 2021-08-20 15:12:18 0 MQTT:1888 tcp{client}: 0x55bab8fe6558 38 2021-08-20 15:12:18 0 MQTT:1888 tcp{client}: 0x55bab8fdec08 39 2021-08-20 15:12:18 0 MQTT:1888 tcp{client}: 0x55bab8647ca8 40 2021-08-20 15:12:18 0 MQTT:1888 tcp{client}: 0x55bab8f1da88 41 2021-08-20 15:12:18 0 MQTT:1888 tcp{client}: 0x55bab8c0a7d8 42 2021-08-20 15:12:18 0 MQTT:1888 tcp{client}: 0x55bab8d3e718 43 2021-08-20 15:12:18 0 MQTT:1888 tcp{client}: 0x55bab8f33728 44 2021-08-20 15:12:18 0 MQTT:1888 tcp{client}: 0x55bab8ec3eb8 45 2021-08-20 15:12:18 0 MQTT:1888 tcp{client}: 0x55bab8bc9118 46 2021-08-20 15:12:18 0 MQTT:1888 tcp{client}: 0x55bab8ebc6c8 47 2021-08-20 15:12:18 0 MQTT:1888 tcp{client}: 0x55bab8f03d38 48 2021-08-20 15:12:18 0 MQTT:1888 tcp{client}: 0x55bab8f0ad28 49 2021-08-20 15:12:18 0 MQTT:1888 tcp{client}: 0x55bab8f087d8 50 2021-08-20 15:12:18 0 MQTT:1888 tcp{client}: 0x55bab8efcd78 51 2021-08-20 15:12:18 0 MQTT:1888 tcp{client}: 0x55bab8efa858 52 2021-08-20 15:12:18 0 MQTT:1888 tcp{client}: 0x55bab8fdb8f8 53 2021-08-20 15:12:18 0 MQTT:1888 tcp{client}: 0x55bab8fff8f8 54 2021-08-20 15:12:10 0 MQTT:1888 tcp{server}: 0x55bab8a09878 55 2021-08-20 15:12:10 0 POP3 tcp{server}: 0x55bab89f0b58 56 2021-08-20 15:12:10 0 SMTP tcp{server}: 0x55bab89edbf8 57 2021-08-20 15:12:10 0 HTTP:3480 tcp{server}: 0x55bab89eb0b8 Received connections server date / time #connects from IP HTTP 2021-08-20 20:15:52 22187 127.0.0.1 HTTP 2021-08-20 20:15:21 19 192.168.1.240 HTTP 2021-08-20 20:04:16 4 192.168.1.3 SMTP 0 POP3 0 UDP 0 MQTT 2021-08-20 15:12:18 1 192.168.1.63 MQTT 2021-08-20 15:12:18 1 192.168.1.62 MQTT 2021-08-20 17:59:58 4 192.168.1.66 MQTT 2021-08-20 15:12:18 1 192.168.1.61 MQTT 2021-08-20 15:12:18 35 127.0.0.1 MQTT 2021-08-20 15:13:23 1 192.168.1.65 MQTT 2021-08-20 15:12:18 1 192.168.1.64 MQTT 2021-08-20 15:12:18 1 192.168.1.59
-
@crille said in Unexpected stop of openLuup:
I don't know if VeraBridge is running asynchronous, is that relevant too?
Yes, it's relevant. Synchronous polling takes a lot of time out from other things.
Check the VeraBridge variable AsyncPoll, if it's not set to true then you should try doing so (and reloading.)
-
@akbooer Ok. Thanks ,I changed it to true (was at, I guess default, false) and reloaded. Let’s hope that do good to the system.
I still always get errors after a reload though.
2021-08-20 23:44:09.048 openLuup.mqtt:: RECEIVE ERROR: closed tcp{client}: 0x55b0530b79b8 2021-08-20 23:44:09.049 openLuup.mqtt:: RECEIVE ERROR: closed tcp{client}: 0x55b0533b7bc8 2021-08-20 23:44:09.300 openLuup.server:: error 'socket.select() not ready to send tcp{client}: 0x55b0526b00d8' sending 2 bytes to tcp{client}: 0x55b0526b00d8
-
@akbooer I join in here since I have had some similar problems.
My Zway bridge has been running in asyc mode for long, however I checked my Vera bridge and it was not. I have changed it, but since I have no devices left on the Vera I thought that I just as well should remove it from OpenLuup to unload it a bit.
What is the best way of doing that? Just to delete the brigde device in OpenLuup and reload? -
Yup, just that.
-
Today I got a new crash. This time in the middle of the day. OpenLuup was not doing anything specific, I was not doing anything with it, no irregular cpu load (approx 3.6%). No errors in the log before what is shown below. Mqtt Explorer was not on either, so regular load there as well. (This was also before I tried with Mqtt from Home Assistant to OpenLuup, as described in another thread.)
I discovered a few hours later that the server had crashed. When checking the logs this is what I could see:
2021-09-17 11:04:36.136 openLuup.io.server:: MQTT:1883 connection from 192.168.1.144 tcp{client}: 0x29b5f80 2021-09-17 11:04:36.136 openLuup.io.server:: MQTT:1883 connection from 192.168.1.66 tcp{client}: 0x3136a28 2021-09-17 11:04:36.136 openLuup.io.server:: MQTT:1883 connection from 192.168.1.59 tcp{client}: 0x30932b0 2021-09-17 11:04:36.137 openLuup.io.server:: MQTT:1883 connection from 192.168.1.52 tcp{client}: 0x3671eb8 2021-09-17 11:04:36.137 openLuup.io.server:: MQTT:1883 connection from 192.168.1.51 tcp{client}: 0x3506688 2021-09-17 11:04:36.137 openLuup.io.server:: MQTT:1883 connection from 192.168.1.58 tcp{client}: 0x3005fa8 2021-09-17 11:04:36.182 luup.variable_set:: 144.urn:toggledbits-com:serviceId:ReactorValues.SpabadCut was: 349 now: 345 #hooks:1 2021-09-17 11:04:36.190 luup.variable_set:: 144.urn:toggledbits-com:serviceId:ReactorSensor.cstate was: {"cond0":{"evaledge":[],"stateedge":[],"valuestamp":1631631832,"id":"cond0","statestamp":0,"lastvalue":0},"root":{"... now: {"cond0":{"evaledge":[],"stateedge":[],"valuestamp":1631631832,"id":"cond0","statestamp":0,"lastvalue":0},"root":{"... #hooks:0 2021-09-17 11:04:36.191 luup.variable_set:: 144.urn:toggledbits-com:serviceId:ReactorSensor.lastacc was: 1631869442 now: 1631869476 #hooks:0 2021-09-17 11:04:36.191 luup.variable_set:: 144.urn:toggledbits-com:serviceId:ReactorGroup.GroupStatus_grp1b0eboj1 was: 1 now: 0 #hooks:0 2021-09-17 11:04:36.191 luup.variable_set:: 144.urn:toggledbits-com:serviceId:ReactorSensor.Message was: Not tripped now: Not tripped #hooks:0 2021-09-17 11:04:36.194 openLuup.io.server:: MQTT:1883 connection closed tcp{client}: 0x2a6a020 2021-09-17 11:04:36.195 openLuup.mqtt:: RECEIVE ERROR: closed tcp{client}: 0x2a6a020 2021-09-17 11:04:36.195 openLuup.mqtt:: TasmotaFriggebod UNSUBSCRIBE from cmnd/TasmotaFriggebod_fb/# tcp{client}: 0x2a6a020 2021-09-17 11:04:36.195 openLuup.mqtt:: TasmotaFriggebod UNSUBSCRIBE from cmnd/TasmotaFriggebod/# tcp{client}: 0x2a6a020 2021-09-17 11:04:36.195 openLuup.mqtt:: TasmotaFriggebod UNSUBSCRIBE from cmnd/tasmotas/# tcp{client}: 0x2a6a020 2021-09-17 11:04:36.195 openLuup.io.server:: MQTT:1883 connection closed tcp{client}: 0x2978900 2021-09-17 11:04:36.195 openLuup.mqtt:: RECEIVE ERROR: closed tcp{client}: 0x2978900 2021-09-17 11:04:36.195 openLuup.mqtt:: TasmotaSjo UNSUBSCRIBE from cmnd/TasmotaSjo_fb/# tcp{client}: 0x2978900 2021-09-17 11:04:36.195 openLuup.mqtt:: TasmotaSjo UNSUBSCRIBE from cmnd/tasmotas/# tcp{client}: 0x2978900 2021-09-17 11:04:36.195 openLuup.mqtt:: TasmotaSjo UNSUBSCRIBE from cmnd/Tasmota_Sjo/# tcp{client}: 0x2978900 2021-09-17 11:04:36.195 openLuup.io.server:: MQTT:1883 connection closed tcp{client}: 0x2c12be0 2021-09-17 11:04:36.195 openLuup.mqtt:: RECEIVE ERROR: closed tcp{client}: 0x2c12be0 2021-09-17 11:04:36.195 openLuup.mqtt:: TasmotaIR UNSUBSCRIBE from cmnd/tasmotas/# tcp{client}: 0x2c12be0 2021-09-17 11:04:36.195 openLuup.mqtt:: TasmotaIR UNSUBSCRIBE from cmnd/TasmotaIR_fb/# tcp{client}: 0x2c12be0 2021-09-17 11:04:36.195 openLuup.mqtt:: TasmotaIR UNSUBSCRIBE from cmnd/TasmotaIR/# tcp{client}: 0x2c12be0 2021-09-17 11:04:36.210 openLuup.io.server:: MQTT:1883 connection closed tcp{client}: 0x30c6558 2021-09-17 11:04:36.210 openLuup.mqtt:: RECEIVE ERROR: closed tcp{client}: 0x30c6558 2021-09-17 11:04:36.210 openLuup.io.server:: MQTT:1883 connection closed tcp{client}: 0x395fb70 2021-09-17 11:04:36.210 openLuup.mqtt:: RECEIVE ERROR: closed tcp{client}: 0x395fb70 2021-09-17 11:04:36.210 openLuup.mqtt:: TasmotaGarage UNSUBSCRIBE from cmnd/TasmotaGarage_fb/# tcp{client}: 0x395fb70 2021-09-17 11:04:36.210 openLuup.mqtt:: TasmotaGarage UNSUBSCRIBE from cmnd/tasmotas/# tcp{client}: 0x395fb70 2021-09-17 11:04:36.210 openLuup.mqtt:: TasmotaGarage UNSUBSCRIBE from cmnd/TasmotaGarage/# tcp{client}: 0x395fb70 ... 2021-09-17 11:04:36.219 openLuup.io.server:: MQTT:1883 connection closed tcp{client}: 0x29b5f80 2021-09-17 11:04:36.219 openLuup.mqtt:: RECEIVE ERROR: closed tcp{client}: 0x29b5f80 2021-09-17 11:04:36.219 openLuup.io.server:: MQTT:1883 connection closed tcp{client}: 0x3136a28 2021-09-17 11:04:36.219 openLuup.mqtt:: RECEIVE ERROR: closed tcp{client}: 0x3136a28 2021-09-17 11:04:36.223 luup.watch_callback:: 20013.MHZ19B.CarbonDioxide called [9]virtualSensorWatchCallback() function: 0x263bea8 ... 2021-09-17 11:04:36.243 openLuup.io.server:: MQTT:1883 connection closed tcp{client}: 0x2b7aff8 2021-09-17 11:04:36.243 openLuup.mqtt:: closed tcp{client}: 0x2b7aff8 2021-09-17 11:04:36.243 openLuup.mqtt:: TasmotaCO2Brandv UNSUBSCRIBE from cmnd/TasmotaCO2Brandv/# tcp{client}: 0x2b7aff8 2021-09-17 11:04:36.243 openLuup.mqtt:: TasmotaCO2Brandv UNSUBSCRIBE from cmnd/tasmotas/# tcp{client}: 0x2b7aff8 2021-09-17 11:04:36.243 openLuup.mqtt:: TasmotaCO2Brandv UNSUBSCRIBE from cmnd/TasmotaCO2Brandv_fb/# tcp{client}: 0x2b7aff8 2021-09-17 11:04:36.243 openLuup.mqtt:: ERROR publishing application message for mqtt:cmnd/TasmotaCO2Brandv/POWER : closed
I am seeing a number of Mqtt connections almost at the same time and then some unsubscribe and receive errors and then just a crash.
I have had not crashes for >25 days, so a bit strange.
The only change I can think of that I made a day ago was that I added a Reactor that fetches data from a site parses it and sends it to Thingspeak by making two "http get" actions to get data from energyhive.com and then two:
os.execute( "curl -s -i -H 'Accept:application/json' 'https://api.thingspeak.com/update?api_key=key&field1='" .. result)
Both actions were running every minute. I have now removed one of the "http get" and curl commands, and also run this every second minute instead. The Reactor is what is shown in the beginning of the log btw.
Maybe this is something that puts a lot of load on the Lua sockets of something? I will disable it and see.Any ideas around this would be welcome.
-
@akbooer I'm still having a lot of crashes but since I added an autorestart of the service it's not really a major problem anymore.
Even though openLuup is rock solid for most people, may I suggest you add[Service] Restart=on-failure RestartSec=5s
To the documentation for openLuup in the appendix for systemd?
At least it helped me not coming home to a dead house -
Hi akbooer,
randomly openluup hangs but today I noticed in the log (attached) something strange :
2022-08-13 12:54:35.283 openLuup.server:: GET /data_request?id=lr_render&target=Vera-45108342.024.urn:upnp-org:serviceId:VContainer1.Variable3&from=2022-08-13T00:00&format=json HTTP/1.1 tcp{client}: 0x556c471741f8
2022-08-13 12:54:35.286 luup_log:4: DataGraph: Whisper query: CPU = 2.068 mS for 775 points
2022-08-13 12:54:35.289 openLuup.server:: request completed (20282 bytes, 2 chunks, 6 ms) tcp{client}: 0x556c471741f8
2022-08-13 12:54:35.290 openLuup.io.server:: HTTP:3480 connection closed openLuup.server.receive closed tcp{client}: 0x556c471741f8
2022-08-13 12:54:57.889 openLuup.io.server:: HTTP:3480 connection from 92.255.85.183 tcp{client}: 0x556c482cf138
2022-08-13 12:54:57.889 openLuup.server:: /*: mstshash=Administr tcp{client}: 0x556c482cf138
2022-08-13 12:54:57.889 openLuup.context_switch:: ERROR: [dev #0] ./openLuup/server.lua:238: attempt to concatenate local 'method' (a nil value)
2022-08-13 12:54:57.889 luup.incoming_callback:: function: 0x556c4753ff20 ERROR: ./openLuup/server.lua:238: attempt to concatenate local 'method' (a nil value)at 12:54 openluup stopped to write and read the datayours files
Is a possible attack ?
-
Looks like an attack, from what I read online.
-
Hi akbooer,
in order to set firewall rules can you give me some info on the openluup log records ? Following there are few normal log lines of datayours read/write :
2022-08-13 12:52:21.921 luup.variable_set:: 4.urn:akbooer-com:serviceId:DataYours1.AppMemoryUsed was: 6568 now: 6871 #hooks:0
2022-08-13 12:52:26.570 openLuup.io.server:: HTTP:3480 connection from xx.xx.xx.xx tcp{client}: 0x556c474b0348
2022-08-13 12:52:26.571 openLuup.server:: GET /data_request?id=lr_render&target=Vera-yyyyyyyy.024.urn:upnp-org:serviceId:VContainer1.Variable3&from=-2h&format=json HTTP/1.1 tcp{client}: 0x556c474b0348
2022-08-13 12:52:26.571 luup_log:4: DataGraph: Whisper query: CPU = 0.405 mS for 121 points
2022-08-13 12:52:26.572 openLuup.server:: request completed (3359 bytes, 1 chunks, 1 ms) tcp{client}: 0x556c474b0348
2022-08-13 12:52:26.572 openLuup.io.server:: HTTP:3480 connection closed openLuup.server.receive closed tcp{client}: 0x556c474b0348
2022-08-13 12:52:26.574 openLuup.io.server:: HTTP:3480 connection from xx.xx.xx.xx tcp{client}: 0x556c47810548
2022-08-13 12:52:26.574 openLuup.server:: GET /data_request?id=lr_render&target=Vera-yyyyyyyy.024.urn:upnp-org:serviceId:VContainer1.Variable3&from=2022-08-13T00:00&format=json HTTP/1.1 tcp{client}: 0x556c47810548
2022-08-13 12:52:26.576 luup_log:4: DataGraph: Whisper query: CPU = 2.085 mS for 773 points
2022-08-13 12:52:26.580 openLuup.server:: request completed (20228 bytes, 2 chunks, 6 ms) tcp{client}: 0x556c47810548
2022-08-13 12:52:26.581 openLuup.io.server:: HTTP:3480 connection closed openLuup.server.receive closed tcp{client}: 0x556c47810548
2022-08-13 12:52:35.363 openLuup.io.server:: HTTP:3480 connection from xx.xx.xx.xx tcp{client}: 0x556c4756a4b8
2022-08-13 12:52:35.364 openLuup.server:: GET /data_request?id=lr_render&target=Vera-yyyyyyyy.024.urn:upnp-org:serviceId:VContainer1.Variable3&from=-2h&format=json HTTP/1.1 tcp{client}: 0x556c4756a4b8
2022-08-13 12:52:35.364 luup_log:4: DataGraph: Whisper query: CPU = 0.418 mS for 121 points
2022-08-13 12:52:35.366 openLuup.server:: request completed (3359 bytes, 1 chunks, 1 ms) tcp{client}: 0x556c4756a4b8
2022-08-13 12:52:35.366 openLuup.io.server:: HTTP:3480 connection closed openLuup.server.receive closed tcp{client}: 0x556c4756a4b8
2022-08-13 12:52:35.368 openLuup.io.server:: HTTP:3480 connection from xx.xx.xx.xx tcp{client}: 0x556c47294958
2022-08-13 12:52:35.368 openLuup.server:: GET /data_request?id=lr_render&target=Vera-yyyyyyyy.024.urn:upnp-org:serviceId:VContainer1.kwdaily3&from=2016-07-01&format=json HTTP/1.1 tcp{client}: 0x556c47294958
2022-08-13 12:52:35.375 luup_log:4: DataGraph: Whisper query: CPU = 6.521 mS for 2235 points
2022-08-13 12:52:35.392 openLuup.server:: request completed (62445 bytes, 4 chunks, 23 ms) tcp{client}: 0x556c47294958
2022-08-13 12:52:35.397 openLuup.io.server:: HTTP:3480 connection closed openLuup.server.receive closed tcp{client}: 0x556c47294958
2022-08-13 12:52:35.398 openLuup.io.server:: HTTP:3480 connection from xx.xx.xx.xx tcp{client}: 0x556c47158248
2022-08-13 12:52:35.398 openLuup.server:: GET /data_request?id=lr_render&target=Vera-yyyyyyyy.024.urn:upnp-org:serviceId:VContainer1.Variable3&from=2022-08-13T00:00&format=json HTTP/1.1 tcp{client}: 0x556c47158248
2022-08-13 12:52:35.401 luup_log:4: DataGraph: Whisper query: CPU = 2.409 mS for 773 points
2022-08-13 12:52:35.406 openLuup.server:: request completed (20228 bytes, 2 chunks, 7 ms) tcp{client}: 0x556c47158248
2022-08-13 12:52:35.406 openLuup.io.server:: HTTP:3480 connection closed openLuup.server.receive closed tcp{client}: 0x556c47158248
2022-08-13 12:53:26.581 openLuup.io.server:: HTTP:3480 connection from xx.xx.xx.xx tcp{client}: 0x556c4877f9d8
2022-08-13 12:53:26.581 openLuup.server:: GET /data_request?id=lr_render&target=Vera-yyyyyyyy.024.urn:upnp-org:serviceId:VContainer1.Variable3&from=-2h&format=json HTTP/1.1 tcp{client}: 0x556c4877f9d8
2022-08-13 12:53:26.582 luup_log:4: DataGraph: Whisper query: CPU = 0.735 mS for 121 pointsThe read commands of whisper files are all of kind "http://server-ip:3480......."
the following log record :
openLuup.io.server:: HTTP:3480 connection from xx.xx.xx.xx tcp{client}
is from a http read ?Can I see UDP write log record ?
The write commands come from remote datayours through UDP to "server-ip" .
All consolidated whisper files I read/write are on "server-ip".
Is it correct in this scenario that all the regular and normal commands (read/write) must come from "server-ip" ?
tnks
-
If your system is exposed to the internet (which I assume it must be, given that IP address is in HK) have you considered either setting up some port forwarding, or, a VPN and locking it down to internal access only? Would seem simpler than trying to set up firewall rules, assuming your Datayours server is on the same LAN?
I'm sure you have a good reason for your set up, just my $0.0002 worth
C
-
Yes, the server logs the initial HTTP connection, and the hex number at the end of the log line is an internal reference for the client socket. That same socket reference occurs at the end of the actual HTTP request line.
IIRC, DataYours by default does not log UDP writes (because in many instances they would flood the log. I’d have to refer back to the source code to see if debug logging enables that.
Agree with the comment on VPNs.
-
This error:
2022-08-13 12:54:57.889 openLuup.context_switch:: ERROR: [dev #0] ./openLuup/server.lua:238: attempt to concatenate local 'method' (a nil value)
…is an error in my error reporting! I can fix this easily. The message should just report an unsupported HTTP request and then carry on as usual. I’ll post an update shortly.
It still doesn’t address the issue that you’re receiving a request from someone/somewhere that you shouldn’t.
-
OK, latest development version (v220814) should fix the error message.