search

roopesh / ad-qolsys

AppDaemon app for Qolsys IQ Panel 2
MIT License
22 stars 10 forks source link

Errors in AppDaemon logs and no entities being created. #5

Closed smwoodward closed 3 years ago

smwoodward commented 3 years ago

So I'm seeing this in the logs of Appdaemon and no entities are being made.

WARNING qolsys_panel: ------------------------------------------------------------ INFO qolsys_panel: publishing ZONE_EVENT event to: qolsys/panel/zone_event WARNING qolsys_panel: ------------------------------------------------------------ WARNING qolsys_panel: Unexpected error in worker for App qolsys_panel: WARNING qolsys_panel: Worker Ags: {'id': 'b5ed0d8bcd18461783e2b3009831e15a', 'name': 'qolsys_panel', 'objectid': '52c5e8f035944301990a22cc0fefb7f9', 'type': 'event', 'event': 'MQTT_MESSAGE', 'function': <bound method MQTTSubscriber.mqtt_zone_event_event_received of <qolsys_requests.MQTTSubscriber object at 0x7fb56a1ac0>>, 'data': {'topic': 'qolsys/panel/zone_event', 'wildcard': '#', 'payload': '{"event":"ZONE_EVENT","zone_event_type":"ZONE_ACTIVE","version":1,"zone":{"status":"Closed","zone_id":2},"requestID":"a46a60df-6562-4228-8127-0da022150ec4"}\n'}, 'pin_app': True, 'pin_thread': 0, 'kwargs': {'topic': 'qolsys/panel/zone_event', '__thread_id': 'thread-0'}} WARNING qolsys_panel: ------------------------------------------------------------ WARNING qolsys_panel: Traceback (most recent call last): File "/usr/lib/python3.8/site-packages/appdaemon/threading.py", line 936, in worker funcref(args["event"], data, self.AD.events.sanitize_event_kwargs(app, args["kwargs"])) File "/config/appdaemon/apps/ad-qolsys/qolsys_requests.py", line 57, in mqtt_zone_event_event_received self.mqtt_zone_update_event_received(event_name, data, kwargs) File "/config/appdaemon/apps/ad-qolsys/qolsys_requests.py", line 33, in mqtt_zone_update_event_received this_zone = self.app.zones[zoneid] KeyError: 2 WARNING qolsys_panel: ------------------------------------------------------------

There are several more than just this one also.

roopesh commented 3 years ago

Can you send an INFO request and see if there are errors creating any zones?

smwoodward commented 3 years ago

I can post {"event":"INFO", "token":"blah"} to the qolsys/requests mqtt topic and nothing happens. I'm not sure how to send an INFO request directly in appdaemon.

smwoodward commented 3 years ago

ok, I have an openssl s_client open at the same time, and I can see that if I publish {"event":"INFO", "token":"blah"} to mqtt it does send and receive an INFO summary from the panel. But that is all I'm seeing. No entities are being published.

roopesh commented 3 years ago

You can't have two connections to the panel at the same time

smwoodward commented 3 years ago

In testing the syntax when I reversed engineered the C4 drivers I was able to. Additionally I am seeing the zone events both in the terminal connection that I have open and in appdaemon.

smwoodward commented 3 years ago

For example, I do see a {"event":"ZONE_EVENT","zone_event_type":"ZONE_UPDATE", blah blah blah} show up in the openssl s_client session I have open, and I also see the publishing ZONE_EVENT to qolsys/panel/zone_event and all of the warnings that I shared above at the same time.

smwoodward commented 3 years ago

I'm able to connect with openssl in a terminal window and then started the appdaemon and this is what I see in the logs at start up.

`2021-04-09 11:27:42.433209 INFO AppDaemon: AppDaemon Version 4.0.7 starting

2021-04-09 11:27:42.433944 INFO AppDaemon: Python version is 3.8.8

2021-04-09 11:27:42.434585 INFO AppDaemon: Configuration read from: /config/appdaemon/appdaemon.yaml

2021-04-09 11:27:42.435371 INFO AppDaemon: Added log: AppDaemon

2021-04-09 11:27:42.436041 INFO AppDaemon: Added log: Error

2021-04-09 11:27:42.436635 INFO AppDaemon: Added log: Access

2021-04-09 11:27:42.437250 INFO AppDaemon: Added log: Diag

2021-04-09 11:27:42.485215 INFO AppDaemon: Loading Plugin HASS using class HassPlugin from module hassplugin

2021-04-09 11:27:42.541880 INFO HASS: HASS Plugin Initializing

2021-04-09 11:27:42.542806 INFO HASS: HASS Plugin initialization complete

2021-04-09 11:27:42.544007 INFO AppDaemon: Loading Plugin MQTT using class MqttPlugin from module mqttplugin

2021-04-09 11:27:42.560225 INFO MQTT: MQTT Plugin Initializing

2021-04-09 11:27:42.561131 INFO MQTT: Using 'mqtt-client/status' as Will Topic

2021-04-09 11:27:42.561788 INFO MQTT: Using 'mqtt-client/status' as Birth Topic

2021-04-09 11:27:42.562700 INFO MQTT: Using appdaemon_mqtt_client as Client ID

2021-04-09 11:27:42.564867 INFO AppDaemon: Initializing HTTP

2021-04-09 11:27:42.566072 INFO AppDaemon: Using 'ws' for event stream

2021-04-09 11:27:42.574194 INFO AppDaemon: Starting API

2021-04-09 11:27:42.582785 INFO AppDaemon: Starting Admin Interface

2021-04-09 11:27:42.583930 INFO AppDaemon: Starting Dashboards

2021-04-09 11:27:42.631907 INFO HASS: Connected to Home Assistant 2021.4.1

2021-04-09 11:27:42.652001 INFO MQTT: Connected to Broker at URL 172.30.33.1:1883

2021-04-09 11:27:42.679364 INFO AppDaemon: Got initial state from namespace mqtt

2021-04-09 11:27:42.682440 INFO MQTT: MQTT Plugin initialization complete

2021-04-09 11:27:42.686845 INFO AppDaemon: App 'qolsys_panel' added

2021-04-09 11:27:42.689242 INFO AppDaemon: Found 1 total apps

2021-04-09 11:27:42.690683 INFO AppDaemon: Starting Apps with 1 workers and 1 pins

2021-04-09 11:27:42.693525 INFO AppDaemon: Running on port 5050

2021-04-09 11:27:42.778241 INFO HASS: Evaluating startup conditions

2021-04-09 11:27:42.809657 INFO HASS: Startup condition met: hass state=RUNNING

2021-04-09 11:27:42.810481 INFO HASS: All startup conditions met

2021-04-09 11:27:42.868343 INFO AppDaemon: Got initial state from namespace default

2021-04-09 11:27:44.718312 INFO AppDaemon: Scheduler running in realtime

2021-04-09 11:27:44.725482 INFO AppDaemon: Adding /config/appdaemon/apps to module import path

2021-04-09 11:27:44.726970 INFO AppDaemon: Adding /config/appdaemon/apps/ad-qolsys to module import path

2021-04-09 11:27:44.738571 WARNING AppDaemon: No app description found for: /config/appdaemon/apps/hello.py - ignoring

2021-04-09 11:27:44.741236 INFO AppDaemon: Loading App Module: /config/appdaemon/apps/ad-qolsys/qolsys_client.py

2021-04-09 11:27:44.762738 WARNING AppDaemon: No app description found for: /config/appdaemon/apps/ad-qolsys/partition.py - ignoring

2021-04-09 11:27:44.766665 WARNING AppDaemon: No app description found for: /config/appdaemon/apps/ad-qolsys/qolsys_socket.py - ignoring

2021-04-09 11:27:44.769897 WARNING AppDaemon: No app description found for: /config/appdaemon/apps/ad-qolsys/door_window.py - ignoring

2021-04-09 11:27:44.772780 WARNING AppDaemon: No app description found for: /config/appdaemon/apps/ad-qolsys/qolsys_requests.py - ignoring

2021-04-09 11:27:44.775757 INFO AppDaemon: Initializing app qolsys_panel using class QolsysClient from module qolsys_client

2021-04-09 11:27:44.987899 INFO qolsys_panel: Creating qolsys_socket

2021-04-09 11:27:44.991369 INFO qolsys_panel: wrapping socket

2021-04-09 11:27:44.998053 INFO qolsys_panel: connecting to socket

2021-04-09 11:27:45.457127 INFO qolsys_panel: Connected wrappedSocket: <ssl.SSLSocket fd=12, family=AddressFamily.AF_INET, type=SocketKind.SOCK_STREAM, proto=0, laddr=('172.30.33.7', 38138), raddr=('192.168.7.1', 12345)>

2021-04-09 11:27:45.462804 INFO qolsys_panel: Starting listener thread

2021-04-09 11:27:45.473130 INFO qolsys_panel: Previous message repeated 1 times

2021-04-09 11:27:45.474280 INFO qolsys_panel: starting listen

2021-04-09 11:27:45.476603 INFO qolsys_panel: started listener thread

2021-04-09 11:27:45.479537 INFO qolsys_panel: started listener

2021-04-09 11:27:45.482877 INFO qolsys_panel: QolSys Socket Created

2021-04-09 11:27:45.486466 INFO qolsys_panel: listener for requests topic: qolsys/requests

2021-04-09 11:27:45.500174 INFO qolsys_panel: listener for info topic: qolsys/panel/info

2021-04-09 11:27:45.509812 INFO qolsys_panel: listener for zone event topic: qolsys/panel/zone_event

2021-04-09 11:27:45.520097 INFO qolsys_panel: listener for zone update topic: qolsys/panel/zone_update

2021-04-09 11:27:45.530484 INFO qolsys_panel: listener for arming topic: qolsys/panel/alarming

2021-04-09 11:27:45.548378 INFO AppDaemon: App initialization complete

2021-04-09 11:27:45.591815 INFO qolsys_panel: event: INFO

2021-04-09 11:27:45.594446 INFO qolsys_panel: event: INFO, usercode: None, partition_id: None, arm_type: None`

Additionally after the event: INFO usercode: None, partition_id: None, arm_type:None is sent, I see the summary info being sent back from the panel in the openssl session.

smwoodward commented 3 years ago

Are there any particular Python packages that need to be installed with appdaemon?

smwoodward commented 3 years ago

Should there be anything in the appdaemon logs where when it requests INFO that it shows it's parsing that data? It almost seems like it isn't parsing the INFO response.

I can confirm that manually publishing arm to mqtt armed the panel. It didn't disarm though, but I haven't tried too much into that yet.

roopesh commented 3 years ago

Are there any particular Python packages that need to be installed with appdaemon?

no

Should there be anything in the appdaemon logs where when it requests INFO that it shows it's parsing that data? It almost seems like it isn't parsing the INFO response.

you'd have to turn up logging to debug.

Additionally after the event: INFO usercode: None, partition_id: None, arm_type:None is sent, I see the summary info being sent back from the panel in the openssl session.

There's no token?

smwoodward commented 3 years ago

Are there any particular Python packages that need to be installed with appdaemon?

no

Should there be anything in the appdaemon logs where when it requests INFO that it shows it's parsing that data? It almost seems like it isn't parsing the INFO response.

you'd have to turn up logging to debug.

Additionally after the event: INFO usercode: None, partition_id: None, arm_type:None is sent, I see the summary info being sent back from the panel in the openssl session.

There's no token?

The token is in the configs, I’m only seeing “None” in the app daemon container logs.

smwoodward commented 3 years ago

766DBB28-CA7A-45BA-B957-CE81F71EAEBC

smwoodward commented 3 years ago

EE55CE8D-02E8-4B14-9E40-A51DEBA258F1

as soon as the panel sends an event this is what I see. No sensors are created in home assistant nor do I see them in any mqtt topics. As I said manually publishing an arming topic in mqtt will arm the panel, but still nothing is in mqtt or HA for sensors or the panel.

roopesh commented 3 years ago

Can you change logging to debug?

qolsys_panel:
  module: qolsys_client
  class: QolsysClient
...
  log_level: "DEBUG"
smwoodward commented 3 years ago

`2021-04-10 02:24:32.285623 INFO qolsys_panel: Creating qolsys_socket

2021-04-10 02:24:32.289341 INFO qolsys_panel: wrapping socket

2021-04-10 02:24:32.296245 INFO qolsys_panel: connecting to socket

2021-04-10 02:24:32.748427 INFO qolsys_panel: Connected wrappedSocket: <ssl.SSLSocket fd=12, family=AddressFamily.AF_INET, type=SocketKind.SOCK_STREAM, proto=0, laddr=('172.30.33.7', 34104), raddr=('192.168.7.1', 12345)>

2021-04-10 02:24:32.751975 INFO qolsys_panel: Starting listener thread

2021-04-10 02:24:32.766236 INFO qolsys_panel: Previous message repeated 1 times

2021-04-10 02:24:32.767143 INFO qolsys_panel: starting listen

2021-04-10 02:24:32.771181 INFO qolsys_panel: started listener thread

2021-04-10 02:24:32.775706 INFO qolsys_panel: started listener

2021-04-10 02:24:32.780593 INFO qolsys_panel: QolSys Socket Created

2021-04-10 02:24:32.785366 INFO qolsys_panel: listener for requests topic: qolsys/requests

2021-04-10 02:24:32.792942 DEBUG qolsys_panel: call_service: mqtt/subscribe, {'namespace': 'mqtt', 'topic': 'qolsys/requests'}

2021-04-10 02:24:32.803149 DEBUG qolsys_panel: Calling listen_event for qolsys_panel

2021-04-10 02:24:32.809999 INFO qolsys_panel: listener for info topic: qolsys/panel/info

2021-04-10 02:24:32.812580 DEBUG qolsys_panel: call_service: mqtt/subscribe, {'namespace': 'mqtt', 'topic': 'qolsys/panel/info'}

2021-04-10 02:24:32.817803 DEBUG qolsys_panel: Calling listen_event for qolsys_panel

2021-04-10 02:24:32.822305 INFO qolsys_panel: listener for zone event topic: qolsys/panel/zone_event

2021-04-10 02:24:32.824865 DEBUG qolsys_panel: call_service: mqtt/subscribe, {'namespace': 'mqtt', 'topic': 'qolsys/panel/zone_event'}

2021-04-10 02:24:32.828285 DEBUG qolsys_panel: Calling listen_event for qolsys_panel

2021-04-10 02:24:32.832004 INFO qolsys_panel: listener for zone update topic: qolsys/panel/zone_update

2021-04-10 02:24:32.834278 DEBUG qolsys_panel: call_service: mqtt/subscribe, {'namespace': 'mqtt', 'topic': 'qolsys/panel/zone_update'}

2021-04-10 02:24:32.837938 DEBUG qolsys_panel: Calling listen_event for qolsys_panel

2021-04-10 02:24:32.841794 INFO qolsys_panel: listener for arming topic: qolsys/panel/alarming

2021-04-10 02:24:32.844044 DEBUG qolsys_panel: call_service: mqtt/subscribe, {'namespace': 'mqtt', 'topic': 'qolsys/panel/alarming'}

2021-04-10 02:24:32.847792 DEBUG qolsys_panel: Calling listen_event for qolsys_panel

2021-04-10 02:24:32.850516 DEBUG qolsys_panel: call_service: mqtt/publish, {'namespace': 'mqtt', 'topic': 'qolsys/requests', 'payload': '{"event": "INFO", "token": "0tts1d"}'}

2021-04-10 02:24:32.855610 INFO AppDaemon: App initialization complete

2021-04-10 02:24:33.905332 DEBUG qolsys_panel: event_name: MQTT_MESSAGE

2021-04-10 02:24:33.913390 DEBUG qolsys_panel: kwargs: {'topic': 'qolsys/requests', '__thread_id': 'thread-0'}

2021-04-10 02:24:33.923764 DEBUG qolsys_panel: data: {'topic': 'qolsys/requests', 'wildcard': '#', 'payload': '{"event": "INFO", "token": "0tts1d"}'}

2021-04-10 02:24:33.933747 DEBUG qolsys_panel: payload: {"event": "INFO", "token": "0tts1d"}

2021-04-10 02:24:33.939387 DEBUG qolsys_panel: payload_json: {'event': 'INFO', 'token': '0tts1d'}

2021-04-10 02:24:33.942257 INFO qolsys_panel: event: INFO

2021-04-10 02:24:33.944854 INFO qolsys_panel: event: INFO, usercode: None, partition_id: None, arm_type: None

2021-04-10 02:24:34.056589 DEBUG qolsys_panel: data received from qolsys panel: ACK

len(data): 4

2021-04-10 02:24:34.146080 INFO qolsys_panel: Previous message repeated 1 times

2021-04-10 02:24:34.147831 DEBUG qolsys_panel: data received from qolsys panel: {"event":"INFO","info_type":"SUMMARY","partition_list":[{"partition_id":0,"name":"partition1","status":"DISARM","secure_arm":false,"zone_list":[{"id":"A634A3","type":"Door_Window","name":"Front Door","group":"entryexitdelay","status":"Closed","state":"0","zone_id":1,"zone_physical_type":1,"zone_alarm_type":3,"zone_type":1,"partition_id":0},{"id":"83B7A6","type":"Door_Window","name":"Back Door","group":"entryexitdelay","status":"Closed","state":"0","zone_id":2,"zone_physical_type":1,"zone_alarm_type":3,"zone_type":1,"partition_id":0},{"id":"B4DDAE","type":"Door_Window","name":"Laundry Door","group":"entryexitdelay","status":"Closed","state":"0","zone_id":3,"zone_physical_type":1,"zone_alarm_type":3,"zone_type":1,"partition_id":0},{"id":"3B8FA1","type":"Door_Window","name":"Den Door","group":"entryexitdelay","status":"Closed","state":"0","zone_id":4,"zone_physical_type":1,"zone_alarm_type":3,"zone_type":1,"partition_id":0},{"id":"46A5A4","type":"Door_Window","name":"Dining Room Window 1","group":"instantperimeter","status":"Closed","state":"0","zone_id":5,"zone_physical_type":1,"zone_alarm_type":3,"zone_type":1,"partition_id":0},{"id":"07CBA9","type":"Door_Window","name":"Dining Room Window 2","group":"instantperimeter","status":"Closed","state":"0","zone_id":6,"zone_physical_type":1,"zone_alarm_type":3,"zone_type":1,"partition_id":0},{"id":"8CA8AD","type":"Door_Window","name":"Den Window 1","group":"instantperimeter","status":"Closed","state":"0","zone_id":7,"zone_physical_type":1,"zone_alarm_type":3,"zone_type":1,"partition_id":0},{"id":"B42BA0","type":"Door_Window","name":"Den Window 2","group":"instantperimeter","status":"Closed","state":"0","zone_id":8,"zone_physical_type":1,"zone_alarm_type":3,"zone_type":1,"partition_id":0},{"id":"4C25AA","type":"Door_Window","name":"Den Window 3","group":"instantperimeter","status":"Closed","state":"0","zone_id":9,"zone_physical_type":1,"zone_alarm_type":3,"zone_type":1,"partition_id":0},{"id":"A7F4AB","type":"Door_Window","name":"Kitchen Window","group":"instantperimeter","status":"Closed","state":"0","zone_id":10,"zone_physical_type":1,"zone_alarm_type":3,"zone_type":1,"partition_id":0},{"id":"87F7A0","type":"Door_Window","name":"Cooper's Window","group":"instantperimeter","status":"Closed","state":"0","zone_id":11,"zone_physical_type":1,"zone_alarm_type":3,"zone_type":1,"partition_id":0},{"id":"00FFA1","type":"Door_Window","name":"Hannah's Window 1","group":"instantperimeter","status":"Closed","state":"0","zone_id":12,"zone_physical_type":1,"zone_alarm_type":3,"zone_type":1,"partition_id":0},{"id":"8CBFA4","type":"Door_Window","name":"Hannah's Window 2","group":"instantperimeter","status":"Closed","state":"0","zone_id":13,"zone_physical_type":1,"zone_alarm_type":3,"zone_type":1,"partition_id":0},{"id":"C72EAF","type":"Door_Window","name":"Master Bath Window","group":"instantperimeter","status":"Closed","state":"0","zone_id":14,"zone_physical_type":1,"zone_alarm_type":3,"zone_type":1,"partition_id":0},{"id":"09CCA8","type":"Door_Window","name":"Master Bedroom Window","group":"instantperimeter","status":"Closed","state":"0","zone_id":15,"zone_physical_type":1,"zone_alarm_type":3,"zone_type":1,"partition_id":0},{"id":"98B2AE","type":"Door_Window","name":"Living Room Window 1","group":"instantperimeter","status":"Closed","state":"0","zone_id":16,"zone_physical_type":1,"zone_alarm_type":3,"zone_type":1,"partition_id":0},{"id":"6FD5A8","type":"Door_Window","name":"Living Room Window 2","group":"instantperimeter","status":"Closed","state":"0","zone_id":17,"zone_physical_type":1,"zone_alarm_type":3,"zone_type":1,"partition_id":0},{"id":"0","type":"Bluetooth","name":"Michael’s iPhone","group":"mobileintrusion","status":"Closed","state":"0","zone_id":18,"zone_physical_type":1,"zone_alarm_type":1,"zone_type":115,"partition_id":0},{"id":"996B2C","type":"SmokeDetector","name":"Smoke Detector 1 Smoke D","group":"smoke_heat","status":"Closed","state":"0","zone_id":19,"zone_physical_type":9,"zone_alarm_type":9,"zone_type":5,"partition_id":0},{"id":"BCA327","type":"SmokeDetector","name":"Smoke len(data): 4094

2021-04-10 02:24:34.152263 DEBUG qolsys_panel: data received from qolsys panel: Detector 2 Smoke D","group":"smoke_heat","status":"Closed","state":"0","zone_id":20,"zone_physical_type":9,"zone_alarm_type":9,"zone_type":5,"partition_id":0},{"id":"855122","type":"SmokeDetector","name":"Smoke Detector 3 Smoke D","group":"smoke_heat","status":"Closed","state":"0","zone_id":21,"zone_physical_type":9,"zone_alarm_type":9,"zone_type":5,"partition_id":0},{"id":"SWG1","type":"Panel Glass Break","name":"Panel Glass Break","group":"glassbreakawayonly","status":"Closed","state":"0","zone_id":22,"zone_physical_type":1,"zone_alarm_type":0,"zone_type":116,"partition_id":0},{"id":"0","type":"Bluetooth","name":"Heather's iPhone","group":"mobileintrusion","status":"Closed","state":"0","zone_id":23,"zone_physical_type":1,"zone_alarm_type":1,"zone_type":115,"partition_id":0},{"id":"SWPM1","type":"Panel Motion","name":"Panel Motion","group":"safetymotion","status":"Closed","state":"0","zone_id":25,"zone_physical_type":1,"zone_alarm_type":3,"zone_type":2,"partition_id":0}]}],"nonce":"qolsys","requestID":"6d0e6493-29fd-4aef-8d89-e048543d7c4d"}

len(data): 1059

2021-04-10 02:25:44.890479 DEBUG qolsys_panel: data received from qolsys panel: {"event":"ZONE_EVENT","zone_event_type":"ZONE_ACTIVE","version":1,"zone":{"status":"Closed","zone_id":9},"requestID":"1f6d3ad3-f86a-4b72-972d-0270560a058a"}

len(data): 157

2021-04-10 02:25:44.894592 INFO qolsys_panel: publishing ZONE_EVENT event to: qolsys/panel/zone_event

2021-04-10 02:25:44.898747 DEBUG qolsys_panel: data being published: {"event":"ZONE_EVENT","zone_event_type":"ZONE_ACTIVE","version":1,"zone":{"status":"Closed","zone_id":9},"requestID":"1f6d3ad3-f86a-4b72-972d-0270560a058a"}

2021-04-10 02:25:44.900415 DEBUG qolsys_panel: call_service: mqtt/publish, {'namespace': 'mqtt', 'topic': 'qolsys/panel/zone_event', 'payload': '{"event":"ZONE_EVENT","zone_event_type":"ZONE_ACTIVE","version":1,"zone":{"status":"Closed","zone_id":9},"requestID":"1f6d3ad3-f86a-4b72-972d-0270560a058a"}\n'}

2021-04-10 02:25:45.193614 DEBUG qolsys_panel: Got zone event: {'topic': 'qolsys/panel/zone_event', 'wildcard': '#', 'payload': '{"event":"ZONE_EVENT","zone_event_type":"ZONE_ACTIVE","version":1,"zone":{"status":"Closed","zone_id":9},"requestID":"1f6d3ad3-f86a-4b72-972d-0270560a058a"}\n'}

2021-04-10 02:25:45.196261 DEBUG qolsys_panel: Got zone Update event: {'topic': 'qolsys/panel/zone_event', 'wildcard': '#', 'payload': '{"event":"ZONE_EVENT","zone_event_type":"ZONE_ACTIVE","version":1,"zone":{"status":"Closed","zone_id":9},"requestID":"1f6d3ad3-f86a-4b72-972d-0270560a058a"}\n'}

2021-04-10 02:25:45.198596 DEBUG qolsys_panel: event_name: MQTT_MESSAGE

2021-04-10 02:25:45.200846 DEBUG qolsys_panel: data: {'topic': 'qolsys/panel/zone_event', 'wildcard': '#', 'payload': '{"event":"ZONE_EVENT","zone_event_type":"ZONE_ACTIVE","version":1,"zone":{"status":"Closed","zone_id":9},"requestID":"1f6d3ad3-f86a-4b72-972d-0270560a058a"}\n'}

2021-04-10 02:25:45.203326 DEBUG qolsys_panel: kwargs: {'topic': 'qolsys/panel/zone_event', '__thread_id': 'thread-0'}

2021-04-10 02:25:45.205759 DEBUG qolsys_panel: data: {'topic': 'qolsys/panel/zone_event', 'wildcard': '#', 'payload': '{"event":"ZONE_EVENT","zone_event_type":"ZONE_ACTIVE","version":1,"zone":{"status":"Closed","zone_id":9},"requestID":"1f6d3ad3-f86a-4b72-972d-0270560a058a"}\n'}

2021-04-10 02:25:45.208070 DEBUG qolsys_panel: payload: {"event":"ZONE_EVENT","zone_event_type":"ZONE_ACTIVE","version":1,"zone":{"status":"Closed","zone_id":9},"requestID":"1f6d3ad3-f86a-4b72-972d-0270560a058a"}

2021-04-10 02:25:45.210325 DEBUG qolsys_panel: payload_json: {'event': 'ZONE_EVENT', 'zone_event_type': 'ZONE_ACTIVE', 'version': 1, 'zone': {'status': 'Closed', 'zone_id': 9}, 'requestID': '1f6d3ad3-f86a-4b72-972d-0270560a058a'}

2021-04-10 02:25:45.212428 DEBUG qolsys_panel: Zones: {}

2021-04-10 02:25:45.212862 WARNING qolsys_panel: ------------------------------------------------------------

2021-04-10 02:25:45.213513 WARNING qolsys_panel: Unexpected error in worker for App qolsys_panel:

2021-04-10 02:25:45.214206 WARNING qolsys_panel: Worker Ags: {'id': '37d7b8d32b514a57a55aaea35b9fdf0a', 'name': 'qolsys_panel', 'objectid': '8f21142e616a4039bd8527a78f2acc80', 'type': 'event', 'event': 'MQTT_MESSAGE', 'function': <bound method MQTTSubscriber.mqtt_zone_event_event_received of <qolsys_requests.MQTTSubscriber object at 0x7f9b6d9a90>>, 'data': {'topic': 'qolsys/panel/zone_event', 'wildcard': '#', 'payload': '{"event":"ZONE_EVENT","zone_event_type":"ZONE_ACTIVE","version":1,"zone":{"status":"Closed","zone_id":9},"requestID":"1f6d3ad3-f86a-4b72-972d-0270560a058a"}\n'}, 'pin_app': True, 'pin_thread': 0, 'kwargs': {'topic': 'qolsys/panel/zone_event', '__thread_id': 'thread-0'}}

2021-04-10 02:25:45.214825 WARNING qolsys_panel: ------------------------------------------------------------

2021-04-10 02:25:45.215977 WARNING qolsys_panel: Traceback (most recent call last):

File "/usr/lib/python3.8/site-packages/appdaemon/threading.py", line 936, in worker

funcref(args["event"], data, self.AD.events.sanitize_event_kwargs(app, args["kwargs"]))

File "/config/appdaemon/apps/ad-qolsys/qolsys_requests.py", line 57, in mqtt_zone_event_event_received

self.mqtt_zone_update_event_received(event_name, data, kwargs)

File "/config/appdaemon/apps/ad-qolsys/qolsys_requests.py", line 33, in mqtt_zone_update_event_received

this_zone = self.app.zones[zoneid]

KeyError: 9

2021-04-10 02:25:45.216623 WARNING qolsys_panel: ------------------------------------------------------------

2021-04-10 02:26:04.345551 DEBUG qolsys_panel: data received from qolsys panel: {"event":"ZONE_EVENT","zone_event_type":"ZONE_UPDATE","zone":{"id":"4C25AA","type":"Door_Window","name":"Den Window 3","group":"instantperimeter","status":"Closed","state":"0","zone_id":9,"zone_physical_type":1,"zone_alarm_type":3,"zone_type":1,"partition_id":0},"version":1,"requestID":"74a08d34-6d39-4023-b5df-55b4bfbcf5b7"}

len(data): 327

2021-04-10 02:26:04.350163 INFO qolsys_panel: publishing ZONE_EVENT event to: qolsys/panel/zone_event

2021-04-10 02:26:04.353825 DEBUG qolsys_panel: data being published: {"event":"ZONE_EVENT","zone_event_type":"ZONE_UPDATE","zone":{"id":"4C25AA","type":"Door_Window","name":"Den Window 3","group":"instantperimeter","status":"Closed","state":"0","zone_id":9,"zone_physical_type":1,"zone_alarm_type":3,"zone_type":1,"partition_id":0},"version":1,"requestID":"74a08d34-6d39-4023-b5df-55b4bfbcf5b7"}

2021-04-10 02:26:04.356093 DEBUG qolsys_panel: call_service: mqtt/publish, {'namespace': 'mqtt', 'topic': 'qolsys/panel/zone_event', 'payload': '{"event":"ZONE_EVENT","zone_event_type":"ZONE_UPDATE","zone":{"id":"4C25AA","type":"Door_Window","name":"Den Window 3","group":"instantperimeter","status":"Closed","state":"0","zone_id":9,"zone_physical_type":1,"zone_alarm_type":3,"zone_type":1,"partition_id":0},"version":1,"requestID":"74a08d34-6d39-4023-b5df-55b4bfbcf5b7"}\n'}

2021-04-10 02:26:04.994773 DEBUG qolsys_panel: Got zone event: {'topic': 'qolsys/panel/zone_event', 'wildcard': '#', 'payload': '{"event":"ZONE_EVENT","zone_event_type":"ZONE_UPDATE","zone":{"id":"4C25AA","type":"Door_Window","name":"Den Window 3","group":"instantperimeter","status":"Closed","state":"0","zone_id":9,"zone_physical_type":1,"zone_alarm_type":3,"zone_type":1,"partition_id":0},"version":1,"requestID":"74a08d34-6d39-4023-b5df-55b4bfbcf5b7"}\n'}

2021-04-10 02:26:05.002459 DEBUG qolsys_panel: Got zone Update event: {'topic': 'qolsys/panel/zone_event', 'wildcard': '#', 'payload': '{"event":"ZONE_EVENT","zone_event_type":"ZONE_UPDATE","zone":{"id":"4C25AA","type":"Door_Window","name":"Den Window 3","group":"instantperimeter","status":"Closed","state":"0","zone_id":9,"zone_physical_type":1,"zone_alarm_type":3,"zone_type":1,"partition_id":0},"version":1,"requestID":"74a08d34-6d39-4023-b5df-55b4bfbcf5b7"}\n'}

2021-04-10 02:26:05.008328 DEBUG qolsys_panel: event_name: MQTT_MESSAGE

2021-04-10 02:26:05.017667 DEBUG qolsys_panel: data: {'topic': 'qolsys/panel/zone_event', 'wildcard': '#', 'payload': '{"event":"ZONE_EVENT","zone_event_type":"ZONE_UPDATE","zone":{"id":"4C25AA","type":"Door_Window","name":"Den Window 3","group":"instantperimeter","status":"Closed","state":"0","zone_id":9,"zone_physical_type":1,"zone_alarm_type":3,"zone_type":1,"partition_id":0},"version":1,"requestID":"74a08d34-6d39-4023-b5df-55b4bfbcf5b7"}\n'}

2021-04-10 02:26:05.024779 DEBUG qolsys_panel: kwargs: {'topic': 'qolsys/panel/zone_event', '__thread_id': 'thread-0'}

2021-04-10 02:26:05.027828 DEBUG qolsys_panel: data: {'topic': 'qolsys/panel/zone_event', 'wildcard': '#', 'payload': '{"event":"ZONE_EVENT","zone_event_type":"ZONE_UPDATE","zone":{"id":"4C25AA","type":"Door_Window","name":"Den Window 3","group":"instantperimeter","status":"Closed","state":"0","zone_id":9,"zone_physical_type":1,"zone_alarm_type":3,"zone_type":1,"partition_id":0},"version":1,"requestID":"74a08d34-6d39-4023-b5df-55b4bfbcf5b7"}\n'}

2021-04-10 02:26:05.030885 DEBUG qolsys_panel: payload: {"event":"ZONE_EVENT","zone_event_type":"ZONE_UPDATE","zone":{"id":"4C25AA","type":"Door_Window","name":"Den Window 3","group":"instantperimeter","status":"Closed","state":"0","zone_id":9,"zone_physical_type":1,"zone_alarm_type":3,"zone_type":1,"partition_id":0},"version":1,"requestID":"74a08d34-6d39-4023-b5df-55b4bfbcf5b7"}

2021-04-10 02:26:05.034259 DEBUG qolsys_panel: payload_json: {'event': 'ZONE_EVENT', 'zone_event_type': 'ZONE_UPDATE', 'zone': {'id': '4C25AA', 'type': 'Door_Window', 'name': 'Den Window 3', 'group': 'instantperimeter', 'status': 'Closed', 'state': '0', 'zone_id': 9, 'zone_physical_type': 1, 'zone_alarm_type': 3, 'zone_type': 1, 'partition_id': 0}, 'version': 1, 'requestID': '74a08d34-6d39-4023-b5df-55b4bfbcf5b7'}

2021-04-10 02:26:05.036952 DEBUG qolsys_panel: Zones: {}

2021-04-10 02:26:05.037552 WARNING qolsys_panel: ------------------------------------------------------------

2021-04-10 02:26:05.038485 WARNING qolsys_panel: Unexpected error in worker for App qolsys_panel:

2021-04-10 02:26:05.039363 WARNING qolsys_panel: Worker Ags: {'id': '37d7b8d32b514a57a55aaea35b9fdf0a', 'name': 'qolsys_panel', 'objectid': '8f21142e616a4039bd8527a78f2acc80', 'type': 'event', 'event': 'MQTT_MESSAGE', 'function': <bound method MQTTSubscriber.mqtt_zone_event_event_received of <qolsys_requests.MQTTSubscriber object at 0x7f9b6d9a90>>, 'data': {'topic': 'qolsys/panel/zone_event', 'wildcard': '#', 'payload': '{"event":"ZONE_EVENT","zone_event_type":"ZONE_UPDATE","zone":{"id":"4C25AA","type":"Door_Window","name":"Den Window 3","group":"instantperimeter","status":"Closed","state":"0","zone_id":9,"zone_physical_type":1,"zone_alarm_type":3,"zone_type":1,"partition_id":0},"version":1,"requestID":"74a08d34-6d39-4023-b5df-55b4bfbcf5b7"}\n'}, 'pin_app': True, 'pin_thread': 0, 'kwargs': {'topic': 'qolsys/panel/zone_event', '__thread_id': 'thread-0'}}

2021-04-10 02:26:05.040055 WARNING qolsys_panel: ------------------------------------------------------------

2021-04-10 02:26:05.041205 WARNING qolsys_panel: Traceback (most recent call last):

File "/usr/lib/python3.8/site-packages/appdaemon/threading.py", line 936, in worker

funcref(args["event"], data, self.AD.events.sanitize_event_kwargs(app, args["kwargs"]))

File "/config/appdaemon/apps/ad-qolsys/qolsys_requests.py", line 57, in mqtt_zone_event_event_received

self.mqtt_zone_update_event_received(event_name, data, kwargs)

File "/config/appdaemon/apps/ad-qolsys/qolsys_requests.py", line 33, in mqtt_zone_update_event_received

this_zone = self.app.zones[zoneid]

KeyError: 9

2021-04-10 02:26:05.041902 WARNING qolsys_panel: ------------------------------------------------------------`

roopesh commented 3 years ago

oh I think the problem is your INFO message is bigger than 4k. Can you go to qolsys_socket.py line 110, change 4096 to 8192 and see if that fixes it? Obviously, restart AppDaemon after.

smwoodward commented 3 years ago

oh I think the problem is your INFO message is bigger than 4k. Can you go to qolsys_socket.py line 110, change 4096 to 8192 and see if that fixes it? Obviously, restart AppDaemon after.

That did it. Is this where it’s suppose to publish the items?

DEBUG qolsys_panel: call_service: mqtt/publish, {'namespace': 'mqtt', 'topic': 'mqtt_states/binary_sensor/panel_motion/state', 'payload': 'Closed'}

smwoodward commented 3 years ago

Never mind. It started publishing under home assistant.

roopesh commented 3 years ago

Fixed in 1.2.1