[BUG] Endless loop MQTT battery sensor publish #29

FredericMa · 2022-08-28T21:06:58Z

Describe the bug
Since last week I updated to the latest version of WallPanel since I was still using the last version of the ThanksMister repo.
Since then I noticed that sometimes there was a significant increase of traffic on the MQTT broker. See screenshot below:

It happened again this morning so I subscribed to the root topic with the wildcard character. Apparently there was a massive amount of messages being published on the sensor/battery topic. It generated about 24.000 messages in about 3 minutes.

Even now the battery state is published twice every minute:

To Reproduce
No clear scenario to reproduce. I don't know what could trigger it since it started mostly during the night. The screen is turned off during the night via an automation. I don't know if that could cause that behavior.

This behavior has never been observed with the previous versions of WallPanel I've used.

Smartphone (please complete the following information):

Device: Lenovo TB-X304F
OS: Android 10
Application version v0.10.0 Build 1

The text was updated successfully, but these errors were encountered:

FredericMa · 2022-08-29T06:19:29Z

It happened again this morning. Now I managed to take screenshot of the messages on the broker:

When I terminate the app the flood of messages also stops.

TheTimeWalker · 2022-08-29T09:07:38Z

Have you looked in the settings on how often the sensors should be updated? The battery readings are only sent in a set timer so it kind of looks like that the sensor settings are set to (close to) 0ms.

Also since it happens when the screen is off, do you have the option to check if the app suddenly restarts all the time overnight?

FredericMa · 2022-08-29T09:18:55Z

Thanks for your reply.

I used the same settings as the latets version of ThanksMister and it is set to 60 seconds.

It starts most of the time during the night but the app isn't restarting since this morning it was sending flood of messages when I took the screenshot and app was running fine (from the UI point of view).

Is it possible that additional timers are being created over time but sometimes not get canceled?

FredericMa · 2022-09-01T16:12:55Z

Is there anything I can do to get this issue forward?

TheTimeWalker · 2022-09-01T21:17:42Z

Sorry, I'm a bit stumped on this as well as I can't reproduce this personally :(

Is it only the battery state that gets pushed endlessly or do you have other messages sent as well?

FredericMa · 2022-09-02T20:44:11Z

Yes, it is only the battery state but the tablet also doesn't have any other supported sensors. The regular state with the current url, screenon, ... is only reported once every minute.
If I restart the app I notice that the battery state is always reported twice every minute with one millisecond in between.
But for some reason it runs out of hand sometimes with this massive amount of publishes. It didn't had the issue for the last 24h.

HA also sends a few commands to the tablet; at midnight it restarts the app (since I noticed the app slowing down if it remained running for several days) and it controls the brightness and the screen on/off. I don't know if this could be related.

Is there any way to debug the app or to see logs on the tablet?

h44z · 2022-09-19T15:46:14Z

I also have this issue if the connection to the MQTT broker is lost / restored. I am using mosquitto as broker and after I restart mosquitto, the Wallpanel app starts spamming MQTT messages.

Clement-B · 2022-09-24T23:58:56Z

I have this same issue using Mosquitto Broker with Home assistant.

After restarting everything, all seems fine at first, but after some times, more and more messages from battery level are send at roughly the same second.

Components used:

Mosquitto Broker v6.1.3
Wall Panel v0.10.0 Build 1

Configuration of WallPanel:

Camera : enabled
MQTT discovery : disabled
Sensor publishing : enabled
Publish frequency : 60
Sensor list : Battery level, USB plugged, AC plugged, Charging

Example with the extract from MQTT logs of HA :

FredericMa · 2022-09-28T09:44:42Z

I reverted back to the latest version of ThanksMister but it looks like it suffers the same issue. So the issue was already present before upgrading to your latest build.
I switched over to EMQX last weekend so now you can see the increase in messages during the night:

A part of the log (milliseconds weren't logged):

2022-09-28T09:24:07+00:00 [PUBLISH] [email protected]:38273 msg: publish_to, topic: wallpanel/sensor/battery, payload: {"value":77,"unit":"%","charging":true,"acPlugged":false,"usbPlugged":false}
2022-09-28T09:24:07+00:00 [PUBLISH] [email protected]:38273 msg: publish_to, topic: wallpanel/sensor/battery, payload: {"value":77,"unit":"%","charging":true,"acPlugged":false,"usbPlugged":false}
2022-09-28T09:24:07+00:00 [PUBLISH] [email protected]:38273 msg: publish_to, topic: wallpanel/sensor/battery, payload: {"value":77,"unit":"%","charging":true,"acPlugged":false,"usbPlugged":false}
2022-09-28T09:24:07+00:00 [PUBLISH] [email protected]:38273 msg: publish_to, topic: wallpanel/sensor/battery, payload: {"value":77,"unit":"%","charging":true,"acPlugged":false,"usbPlugged":false}
2022-09-28T09:24:07+00:00 [PUBLISH] [email protected]:38273 msg: publish_to, topic: wallpanel/sensor/battery, payload: {"value":77,"unit":"%","charging":true,"acPlugged":false,"usbPlugged":false}
2022-09-28T09:24:07+00:00 [PUBLISH] [email protected]:38273 msg: publish_to, topic: wallpanel/sensor/battery, payload: {"value":77,"unit":"%","charging":true,"acPlugged":false,"usbPlugged":false}
2022-09-28T09:24:07+00:00 [PUBLISH] [email protected]:38273 msg: publish_to, topic: wallpanel/sensor/battery, payload: {"value":77,"unit":"%","charging":true,"acPlugged":false,"usbPlugged":false}
2022-09-28T09:24:07+00:00 [PUBLISH] [email protected]:38273 msg: publish_to, topic: wallpanel/sensor/battery, payload: {"value":77,"unit":"%","charging":true,"acPlugged":false,"usbPlugged":false}
2022-09-28T09:24:07+00:00 [PUBLISH] [email protected]:38273 msg: publish_to, topic: wallpanel/sensor/battery, payload: {"value":77,"unit":"%","charging":true,"acPlugged":false,"usbPlugged":false}
2022-09-28T09:24:07+00:00 [PUBLISH] [email protected]:38273 msg: publish_to, topic: wallpanel/sensor/battery, payload: {"value":77,"unit":"%","charging":true,"acPlugged":false,"usbPlugged":false}
2022-09-28T09:24:07+00:00 [PUBLISH] [email protected]:38273 msg: publish_to, topic: wallpanel/sensor/battery, payload: {"value":77,"unit":"%","charging":true,"acPlugged":false,"usbPlugged":false}
2022-09-28T09:24:07+00:00 [PUBLISH] [email protected]:38273 msg: publish_to, topic: wallpanel/sensor/battery, payload: {"value":77,"unit":"%","charging":true,"acPlugged":false,"usbPlugged":false}
2022-09-28T09:24:07+00:00 [PUBLISH] [email protected]:38273 msg: publish_to, topic: wallpanel/sensor/battery, payload: {"value":77,"unit":"%","charging":true,"acPlugged":false,"usbPlugged":false}
2022-09-28T09:24:07+00:00 [PUBLISH] [email protected]:38273 msg: publish_to, topic: wallpanel/sensor/battery, payload: {"value":77,"unit":"%","charging":true,"acPlugged":false,"usbPlugged":false}
2022-09-28T09:24:07+00:00 [PUBLISH] [email protected]:38273 msg: publish_to, topic: wallpanel/sensor/battery, payload: {"value":77,"unit":"%","charging":true,"acPlugged":false,"usbPlugged":false}
2022-09-28T09:24:07+00:00 [PUBLISH] [email protected]:38273 msg: publish_to, topic: wallpanel/sensor/battery, payload: {"value":77,"unit":"%","charging":true,"acPlugged":false,"usbPlugged":false}
2022-09-28T09:24:07+00:00 [PUBLISH] [email protected]:38273 msg: publish_to, topic: wallpanel/sensor/battery, payload: {"value":77,"unit":"%","charging":true,"acPlugged":false,"usbPlugged":false}
2022-09-28T09:24:07+00:00 [PUBLISH] [email protected]:38273 msg: publish_to, topic: wallpanel/sensor/battery, payload: {"value":77,"unit":"%","charging":true,"acPlugged":false,"usbPlugged":false}
2022-09-28T09:24:07+00:00 [PUBLISH] [email protected]:38273 msg: publish_to, topic: wallpanel/sensor/battery, payload: {"value":77,"unit":"%","charging":true,"acPlugged":false,"usbPlugged":false}
2022-09-28T09:24:07+00:00 [PUBLISH] [email protected]:38273 msg: publish_to, topic: wallpanel/sensor/battery, payload: {"value":77,"unit":"%","charging":true,"acPlugged":false,"usbPlugged":false}
2022-09-28T09:24:07+00:00 [PUBLISH] [email protected]:38273 msg: publish_to, topic: wallpanel/sensor/battery, payload: {"value":77,"unit":"%","charging":true,"acPlugged":false,"usbPlugged":false}
2022-09-28T09:24:07+00:00 [PUBLISH] [email protected]:38273 msg: publish_to, topic: wallpanel/sensor/battery, payload: {"value":77,"unit":"%","charging":true,"acPlugged":false,"usbPlugged":false}
2022-09-28T09:24:07+00:00 [PUBLISH] [email protected]:38273 msg: publish_to, topic: wallpanel/sensor/battery, payload: {"value":77,"unit":"%","charging":true,"acPlugged":false,"usbPlugged":false}
2022-09-28T09:24:07+00:00 [PUBLISH] [email protected]:38273 msg: publish_to, topic: wallpanel/sensor/battery, payload: {"value":77,"unit":"%","charging":true,"acPlugged":false,"usbPlugged":false}
2022-09-28T09:24:07+00:00 [PUBLISH] [email protected]:38273 msg: publish_to, topic: wallpanel/sensor/battery, payload: {"value":77,"unit":"%","charging":true,"acPlugged":false,"usbPlugged":false}
2022-09-28T09:24:07+00:00 [PUBLISH] [email protected]:38273 msg: publish_to, topic: wallpanel/sensor/battery, payload: {"value":77,"unit":"%","charging":true,"acPlugged":false,"usbPlugged":false}
2022-09-28T09:24:07+00:00 [PUBLISH] [email protected]:38273 msg: publish_to, topic: wallpanel/sensor/battery, payload: {"value":77,"unit":"%","charging":true,"acPlugged":false,"usbPlugged":false}

FredericMa · 2022-09-29T18:50:32Z

At the bottom there is a full log that's from the point where I turned off the screen.
You can see that there are disconnects which might be related to battery optimization of Android so disabling that for the app might solve it for me but nonetheless there are also use cases this can happen while the screen remains on like broker restart, network disruption, ... So this is an issue that should be fixed.
The number of published messages grows over time. It looks like there are multiple connections active to the broker at the same time. Like you can see here (port 49105 and 49106 for example):

2022-09-29T16:11:50+00:00 [SUBSCRIBE] [email protected]:49103 msg: subscribe, sub_id: wallpanel, sub_opts: [nl: 0, qos: 2, rap: 0, rh: 0, sub_props: [Subscription-Identifier: 1], subid: 1], topic: wallpanel/command
2022-09-29T16:11:50+00:00 [UNSUBSCRIBE] [email protected]:49102 msg: unsubscribe, sub_opts: [nl: 0, qos: 2, rap: 0, rh: 0, sub_props: [Subscription-Identifier: 1], subid: wallpanel], topic: wallpanel/command
2022-09-29T16:11:50+00:00 [SUBSCRIBE] [email protected]:49104 msg: subscribe, sub_id: wallpanel, sub_opts: [nl: 0, qos: 2, rap: 0, rh: 0, sub_props: [Subscription-Identifier: 1], subid: 1], topic: wallpanel/command
2022-09-29T16:11:50+00:00 [UNSUBSCRIBE] [email protected]:49103 msg: unsubscribe, sub_opts: [nl: 0, qos: 2, rap: 0, rh: 0, sub_props: [Subscription-Identifier: 1], subid: wallpanel], topic: wallpanel/command
2022-09-29T16:11:50+00:00 [SUBSCRIBE] [email protected]:49105 msg: subscribe, sub_id: wallpanel, sub_opts: [nl: 0, qos: 2, rap: 0, rh: 0, sub_props: [Subscription-Identifier: 1], subid: 1], topic: wallpanel/command
2022-09-29T16:11:50+00:00 [UNSUBSCRIBE] [email protected]:49104 msg: unsubscribe, sub_opts: [nl: 0, qos: 2, rap: 0, rh: 0, sub_props: [Subscription-Identifier: 1], subid: wallpanel], topic: wallpanel/command
2022-09-29T16:11:50+00:00 [SUBSCRIBE] [email protected]:49106 msg: subscribe, sub_id: wallpanel, sub_opts: [nl: 0, qos: 2, rap: 0, rh: 0, sub_props: [Subscription-Identifier: 1], subid: 1], topic: wallpanel/command
2022-09-29T16:11:50+00:00 [UNSUBSCRIBE] [email protected]:49105 msg: unsubscribe, sub_opts: [nl: 0, qos: 2, rap: 0, rh: 0, sub_props: [Subscription-Identifier: 1], subid: wallpanel], topic: wallpanel/command
2022-09-29T16:11:50+00:00 [SUBSCRIBE] [email protected]:49106 msg: subscribe, sub_id: wallpanel, sub_opts: [nl: 0, qos: 2, rap: 0, rh: 0, sub_props: [Subscription-Identifier: 1], subid: 1], topic: wallpanel/command
2022-09-29T16:11:50+00:00 [SUBSCRIBE] [email protected]:49106 msg: subscribe, sub_id: wallpanel, sub_opts: [nl: 0, qos: 2, rap: 0, rh: 0, sub_props: [Subscription-Identifier: 2], subid: 2], topic: wallpanel/command

[email protected]_WallpanelLog_2022-09-29.log

Clement-B · 2022-10-05T13:19:16Z

I also have another strange behavior and I dont know if t's related.

When sometimes I try to disable camera using mqtt, wall panel suddently close off MQTT connection and become offline. I got no sensor publishing anymore. It become online again after a long time or the day after when a full restart has been done during the night.

@FredericMa Would you say your PR #33 could fix this kind of issue as well ? I see that you did a fix about reconnect logic.

TheTimeWalker · 2022-10-05T14:55:31Z

I'd suggest creating a new issue for that so we can keep that in track. It could be an unrelated issue as it doesn't sound like it's in an endless state

FredericMa · 2022-10-05T15:54:37Z

@FredericMa Would you say your PR #33 could fix this kind of issue as well ? I see that you did a fix about reconnect logic.

It might fix it since I also fixed a few things in the reconnect logic. If you can easily reproduce it I would give it a try once the PR is merged and a new release is available.

FredericMa mentioned this issue Oct 2, 2022

Mqtt and other small fixes #33

Merged

TheTimeWalker closed this as completed in #33 Oct 11, 2022

Clement-B mentioned this issue Oct 31, 2022

[BUG] Lost connection to wall panel #39

Open

Provide feedback

Saved searches

Use saved searches to filter your results more quickly

[BUG] Endless loop MQTT battery sensor publish #29

[BUG] Endless loop MQTT battery sensor publish #29

FredericMa commented Aug 28, 2022

FredericMa commented Aug 29, 2022 •

edited

Loading

TheTimeWalker commented Aug 29, 2022 •

edited

Loading

FredericMa commented Aug 29, 2022 •

edited

Loading

FredericMa commented Sep 1, 2022

TheTimeWalker commented Sep 1, 2022

FredericMa commented Sep 2, 2022

h44z commented Sep 19, 2022

Clement-B commented Sep 24, 2022

FredericMa commented Sep 28, 2022

FredericMa commented Sep 29, 2022

Clement-B commented Oct 5, 2022

TheTimeWalker commented Oct 5, 2022

FredericMa commented Oct 5, 2022

[BUG] Endless loop MQTT battery sensor publish #29

[BUG] Endless loop MQTT battery sensor publish #29

Comments

FredericMa commented Aug 28, 2022

FredericMa commented Aug 29, 2022 • edited Loading

TheTimeWalker commented Aug 29, 2022 • edited Loading

FredericMa commented Aug 29, 2022 • edited Loading

FredericMa commented Sep 1, 2022

TheTimeWalker commented Sep 1, 2022

FredericMa commented Sep 2, 2022

h44z commented Sep 19, 2022

Clement-B commented Sep 24, 2022

FredericMa commented Sep 28, 2022

FredericMa commented Sep 29, 2022

Clement-B commented Oct 5, 2022

TheTimeWalker commented Oct 5, 2022

FredericMa commented Oct 5, 2022

FredericMa commented Aug 29, 2022 •

edited

Loading

TheTimeWalker commented Aug 29, 2022 •

edited

Loading

FredericMa commented Aug 29, 2022 •

edited

Loading