Closed jimboca closed 1 year ago
2022-09-17 10:33:03,767 MainThread udi_interface INFO polylogger:set_basic_config: set_basic_config: enable=True level=30 2022-09-17 10:33:15,869 MainThread udi_interface INFO __init__:<module>: UDI Python Interface for Polyglot version 3 3.0.47a Starting... 2022-09-17 10:33:17,797 MainThread udi_interface.interface INFO interface:__init__: Initialization received from Polyglot V3 3.1.6 [ISY: 5.4.4, Slot: 4] 2022-09-17 10:33:17,801 MainThread udi_interface.interface INFO interface:__init__: Connect: Network Interface: {'addr': '192.168.86.88', 'netmask': '255.255.255.0', 'broadcast': '192.168.86.255'} 2022-09-17 10:33:17,855 Interface udi_interface.interface INFO interface:_startMqtt: Connecting to MQTT... localhost:1888 2022-09-17 10:33:17,976 MainThread udi_interface.interface WARNING interface:db_getNodeDrivers: controller not found in database. 2022-09-17 10:33:17,993 MainThread udi_interface.custom INFO custom:_save: Sending data notices to Polyglot. 2022-09-17 10:33:17,995 MainThread udi_interface.interface WARNING interface:send: MQTT Send waiting on connection :: {'set': [{'key': 'notices', 'value': {}}]} 2022-09-17 10:33:18,084 MQTT udi_interface.interface INFO interface:_connect: MQTT Connected with result code 0 (Success) 2022-09-17 10:33:18,085 MQTT udi_interface.interface INFO interface:_connect: MQTT Subscribing to topic: udi/pg3/ns/clients/00:0d:b9:52:bf:fc_4 - MID: 1 Result: 0 2022-09-17 10:33:18,186 MQTT udi_interface.interface INFO interface:_subscribe: MQTT Subscribed Succesfully for Message ID: 1 - QoS: (0,) 2022-09-17 10:33:19,728 MQTT udi_interface.interface INFO interface:_message: Successfully set controller :: ST to 1 UOM 25 2022-09-17 10:33:21,001 MainThread udi_interface.interface INFO interface:addNode: Adding node ELK Controller(controller) [None] 2022-09-17 10:33:21,005 MainThread udi_interface.interface INFO interface:setController: Using node "controller", driver "ST" for connection status. 2022-09-17 10:33:24,930 MQTT udi_interface.interface INFO interface:_message: Successfully set key = notices 2022-09-17 10:33:34,493 Command udi_interface ERROR Controller:query: ELK Controller: Traceback (most recent call last): File "/var/polyglot/pg3/ns/000db952bffc_4/nodes/Controller.py", line 212, in query self.set_drivers(force=True) File "/var/polyglot/pg3/ns/000db952bffc_4/nodes/Controller.py", line 220, in set_drivers self.set_remote_programming_status(force=force) File "/var/polyglot/pg3/ns/000db952bffc_4/nodes/Controller.py", line 239, in set_remote_programming_status val = self.elk.panel.remote_programming_status AttributeError: 'NoneType' object has no attribute 'panel' 2022-09-17 10:33:42,779 ELK-2242 udi_interface WARNING Controller:sync_complete: ELK Controller: Sync of panel is complete, adding nodes... 2022-09-17 10:34:08,998 Thread-9 udi_interface WARNING Controller:handler_poll: waiting for sync and node initialization to complete 2022-09-17 10:35:08,813 Thread-10 udi_interface WARNING Controller:handler_poll: waiting for sync and node initialization to complete 2022-09-17 10:36:09,385 Thread-11 udi_interface WARNING Controller:handler_poll: waiting for sync and node initialization to complete 2022-09-17 10:37:09,503 Thread-12 udi_interface WARNING Controller:handler_poll: waiting for sync and node initialization to complete 2022-09-17 10:38:08,629 Thread-13 udi_interface WARNING Controller:handler_poll: waiting for sync and node initialization to complete 2022-09-17 10:38:10,913 Thread-14 udi_interface WARNING Controller:handler_poll: waiting for sync and node initialization to complete 2022-09-17 10:39:10,838 Thread-21 udi_interface WARNING Controller:handler_poll: waiting for sync and node initialization to complete 2022-09-17 10:40:10,597 Thread-22 udi_interface WARNING Controller:handler_poll: waiting for sync and node initialization to complete 2022-09-17 10:41:11,087 Thread-23 udi_interface WARNING Controller:handler_poll: waiting for sync and node initialization to complete 2022-09-17 10:42:11,193 Thread-24 udi_interface WARNING Controller:handler_poll: waiting for sync and node initialization to complete 2022-09-17 10:43:09,659 Thread-25 udi_interface WARNING Controller:handler_poll: waiting for sync and node initialization to complete 2022-09-17 10:43:11,993 Thread-26 udi_interface WARNING Controller:handler_poll: waiting for sync and node initialization to complete 2022-09-17 10:44:13,202 Thread-27 udi_interface WARNING Controller:handler_poll: waiting for sync and node initialization to complete 2022-09-17 10:45:12,956 Thread-28 udi_interface WARNING Controller:handler_poll: waiting for sync and node initialization to complete 2022-09-17 10:45:57,999 Command udi_interface.interface ERROR interface:_handleInput: _parseInput: node address zone_87 does not exist. {'address': 'zone_87', 'cmd': 'DOF', 'query': {}} 2022-09-17 10:46:13,399 Thread-29 udi_interface WARNING Controller:handler_poll: waiting for sync and node initialization to complete 2022-09-17 10:47:14,143 Thread-30 udi_interface WARNING Controller:handler_poll: waiting for sync and node initialization to complete 2022-09-17 10:48:09,274 Thread-31 udi_interface WARNING Controller:handler_poll: waiting for sync and node initialization to complete 2022-09-17 10:48:13,463 Thread-32 udi_interface WARNING Controller:handler_poll: waiting for sync and node initialization to complete 2022-09-17 10:49:13,479 Thread-42 udi_interface WARNING Controller:handler_poll: waiting for sync and node initialization to complete 2022-09-17 10:50:13,713 Thread-61 udi_interface WARNING Controller:handler_poll: waiting for sync and node initialization to complete 2022-09-17 10:50:20,933 ELK-2242 udi_interface WARNING Controller:sync_complete: ELK Controller: All nodes added, ready to go...
This happened because query was called by the ISY before the node was ready. Should ignore query in this case.