Invalid state message -last error

Anto79-ops commented 1 year ago

Before submitting this form

[X] I have checked that there hasn't been a similar issue submitted in the past
[X] I have updated the issue title to something relevant and clear to understand the bug I'm facing

Description of the bug

Hi!

Just wanted to report that on every restart of the integration or mqtt broker or HA etc. I get these messages in the logs. Before I had a z-wave siren and it was telling that was the error, but I removed the z-wave siren but still gives the error.

Logger: homeassistant.components.mqtt.sensor
Source: components/mqtt/sensor.py:292
Integration: MQTT (documentation, issues)
First occurred: July 9, 2023 at 8:34:23 PM (1 occurrences)
Last logged: July 9, 2023 at 8:34:23 PM

Invalid state message '' from 'homeassistant/sensor/qolsys_panel_last_error/state'

since removing the z-wave siren, the sensor is correctly(?) showing unknown:

but the above error message still logs

Expected behavior

no log error

DEBUG logs

I don't know if this is helpful, but at 8:34 pm (the core log error time stamp), I reset my home's internet, and below is the log for AD, you can see there is a log for the time that the core log reported the issue.

s6-rc: info: service legacy-cont-init: starting
s6-rc: info: service legacy-cont-init successfully started
s6-rc: info: service init-appdaemon: starting
s6-rc: info: service init-appdaemon successfully started
s6-rc: info: service appdaemon: starting
s6-rc: info: service appdaemon successfully started
s6-rc: info: service legacy-services: starting
s6-rc: info: service legacy-services successfully started
[16:37:57] INFO: Starting AppDaemon...
2023-07-07 16:38:09.438042 INFO AppDaemon: AppDaemon Version 4.4.2 starting
2023-07-07 16:38:09.438446 INFO AppDaemon: Python version is 3.11.4
2023-07-07 16:38:09.439078 INFO AppDaemon: Configuration read from: /config/appdaemon/appdaemon.yaml
2023-07-07 16:38:09.439676 INFO AppDaemon: Added log: AppDaemon
2023-07-07 16:38:09.440379 INFO AppDaemon: Added log: Error
2023-07-07 16:38:09.441131 INFO AppDaemon: Added log: Access
2023-07-07 16:38:09.441800 INFO AppDaemon: Added log: Diag
2023-07-07 16:38:09.949934 INFO AppDaemon: Loading Plugin MQTT using class MqttPlugin from module mqttplugin
2023-07-07 16:38:10.167848 INFO MQTT: MQTT Plugin Initializing
2023-07-07 16:38:10.169056 INFO MQTT: Using 'mqtt-client/status' as Will Topic
2023-07-07 16:38:10.170215 INFO MQTT: Using 'mqtt-client/status' as Birth Topic
2023-07-07 16:38:10.171285 INFO MQTT: Using appdaemon_mqtt_client as Client ID
2023-07-07 16:38:10.172987 INFO AppDaemon: HTTP is disabled
2023-07-07 16:38:10.196626 INFO MQTT: Connected to Broker at URL 192.168.1.139:1883
2023-07-07 16:38:10.202779 INFO AppDaemon: App 'qolsys_panel' added
2023-07-07 16:38:10.206259 INFO AppDaemon: Found 1 active apps
2023-07-07 16:38:10.207707 INFO AppDaemon: Found 0 inactive apps
2023-07-07 16:38:10.210055 INFO AppDaemon: Found 0 global libraries
2023-07-07 16:38:10.210974 INFO AppDaemon: Starting Apps with 1 workers and 1 pins
2023-07-07 16:38:10.215754 INFO AppDaemon: Got initial state from namespace mqtt
2023-07-07 16:38:10.216621 INFO MQTT: MQTT Plugin initialization complete
2023-07-07 16:38:10.559685 INFO MQTT: Unable to decode MQTT message
2023-07-07 16:38:10.562477 ERROR MQTT: Unable to decode MQTT message, with Traceback: Traceback (most recent call last):
  File "/usr/lib/python3.11/site-packages/appdaemon/plugins/mqtt/mqttplugin.py", line 271, in mqtt_on_message
    payload = payload.decode()
              ^^^^^^^^^^^^^^^^
UnicodeDecodeError: 'utf-8' codec can't decode byte 0x89 in position 0: invalid start byte
2023-07-07 16:38:12.242210 INFO AppDaemon: Scheduler running in realtime
2023-07-07 16:38:12.247224 INFO AppDaemon: Adding /config/appdaemon/apps to module import path
2023-07-07 16:38:12.248826 INFO AppDaemon: Adding /config/appdaemon/apps/qolsysgw to module import path
2023-07-07 16:38:12.250164 INFO AppDaemon: Adding /config/appdaemon/apps/qolsysgw/mqtt to module import path
2023-07-07 16:38:12.251431 INFO AppDaemon: Adding /config/appdaemon/apps/qolsysgw/qolsys to module import path
2023-07-07 16:38:12.286544 WARNING AppDaemon: No app description found for: /config/appdaemon/apps/hello.py - ignoring
2023-07-07 16:38:12.288693 INFO AppDaemon: Loading App Module: /config/appdaemon/apps/qolsysgw/gateway.py
2023-07-07 16:38:12.535020 WARNING AppDaemon: No app description found for: /config/appdaemon/apps/qolsysgw/mqtt/exceptions.py - ignoring
2023-07-07 16:38:12.537162 WARNING AppDaemon: No app description found for: /config/appdaemon/apps/qolsysgw/mqtt/updater.py - ignoring
2023-07-07 16:38:12.546102 WARNING AppDaemon: No app description found for: /config/appdaemon/apps/qolsysgw/mqtt/utils.py - ignoring
2023-07-07 16:38:12.550304 WARNING AppDaemon: No app description found for: /config/appdaemon/apps/qolsysgw/mqtt/listener.py - ignoring
2023-07-07 16:38:12.555061 WARNING AppDaemon: No app description found for: /config/appdaemon/apps/qolsysgw/qolsys/exceptions.py - ignoring
2023-07-07 16:38:12.556895 WARNING AppDaemon: No app description found for: /config/appdaemon/apps/qolsysgw/qolsys/actions.py - ignoring
2023-07-07 16:38:12.560797 WARNING AppDaemon: No app description found for: /config/appdaemon/apps/qolsysgw/qolsys/socket.py - ignoring
2023-07-07 16:38:12.565533 WARNING AppDaemon: No app description found for: /config/appdaemon/apps/qolsysgw/qolsys/utils.py - ignoring
2023-07-07 16:38:12.569025 WARNING AppDaemon: No app description found for: /config/appdaemon/apps/qolsysgw/qolsys/partition.py - ignoring
2023-07-07 16:38:12.573974 WARNING AppDaemon: No app description found for: /config/appdaemon/apps/qolsysgw/qolsys/state.py - ignoring
2023-07-07 16:38:12.579065 WARNING AppDaemon: No app description found for: /config/appdaemon/apps/qolsysgw/qolsys/observable.py - ignoring
2023-07-07 16:38:12.584144 WARNING AppDaemon: No app description found for: /config/appdaemon/apps/qolsysgw/qolsys/sensors.py - ignoring
2023-07-07 16:38:12.592048 WARNING AppDaemon: No app description found for: /config/appdaemon/apps/qolsysgw/qolsys/events.py - ignoring
2023-07-07 16:38:12.594936 WARNING AppDaemon: No app description found for: /config/appdaemon/apps/qolsysgw/qolsys/control.py - ignoring
2023-07-07 16:38:12.598312 WARNING AppDaemon: No app description found for: /config/appdaemon/apps/qolsysgw/qolsys/config.py - ignoring
2023-07-07 16:38:12.600423 INFO AppDaemon: Loading app qolsys_panel using class QolsysGateway from module gateway
2023-07-07 16:38:12.609671 INFO AppDaemon: Calling initialize() for qolsys_panel
2023-07-07 16:38:12.833831 INFO qolsys_panel: Starting
2023-07-07 16:38:12.875598 INFO qolsys_panel: Started
2023-07-07 16:38:12.882324 INFO AppDaemon: App initialization complete
2023-07-07 16:38:12.916397 INFO qolsys_panel: Establishing connection to 192.168.1.69:12345
2023-07-09 10:22:59.790081 INFO qolsys_panel: Using code sent from home assistant since no panel code configured
2023-07-09 20:34:22.409969 ERROR qolsys_panel: error while listening
Traceback (most recent call last):
  File "/config/appdaemon/apps/qolsysgw/qolsys/socket.py", line 81, in listen
    line = await reader.readline()
           ^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/asyncio/streams.py", line 545, in readline
    line = await self.readuntil(sep)
           ^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/asyncio/streams.py", line 637, in readuntil
    await self._wait_for_data('readuntil')
  File "/usr/lib/python3.11/asyncio/streams.py", line 522, in _wait_for_data
    await self._waiter
  File "/usr/lib/python3.11/asyncio/selector_events.py", line 970, in _read_ready__get_buffer
    nbytes = self._sock.recv_into(buf)
             ^^^^^^^^^^^^^^^^^^^^^^^^^
ConnectionResetError: [Errno 104] Connection reset by peer
2023-07-09 20:34:22.423341 ERROR qolsys_panel: unable to wait for writer to be fully closed; this might not be an issue if the connection was closed on the other side
Traceback (most recent call last):
  File "/config/appdaemon/apps/qolsysgw/qolsys/socket.py", line 125, in listen
    await writer.wait_closed()
  File "/usr/lib/python3.11/asyncio/streams.py", line 350, in wait_closed
    await self._protocol._get_close_waiter(self)
  File "/usr/lib/python3.11/asyncio/selector_events.py", line 970, in _read_ready__get_buffer
    nbytes = self._sock.recv_into(buf)
             ^^^^^^^^^^^^^^^^^^^^^^^^^
ConnectionResetError: [Errno 104] Connection reset by peer
2023-07-09 20:34:22.430459 INFO qolsys_panel: sleeping 1 second(s) before reconnecting
2023-07-09 20:34:23.442635 INFO qolsys_panel: Establishing connection to 192.168.1.XXX:12345

Additional context

No response

XaF commented 10 months ago

Invalid state message '' from 'homeassistant/sensor/qolsys_panel_last_error/state'

Is safe to ignore. I need to fix that in a later version, but this is basically that the "state" is set to empty string, which is considered invalid by home assistant. I haven't figured out what I'd want to do here, since I don't want to make the sensor unavailable, but just indicate there's no error.

Anto79-ops commented 10 months ago

Thanks, I'll ignore it for now.

XaF commented 10 months ago

Of course! Sorry for the delay! 😅

XaF / qolsysgw