Jump to content

3.3.4 Isn't Working

btreinders

By btreinders
in ELK

Recommended Posts

btreinders Advanced

btreinders

Posted
Posted (edited)

Nothing is working since I updated.  The controller says it's connected and I am on the latest IoP version 5.4.4.  I cannot arm, or see any violations.  I have rebooted the Polisy.  Any ideas? Thank you!



2022-06-11 11:22:34,841 Thread-7130 udi_interface      WARNING  Controller:handler_poll: waiting for sync to complete
2022-06-11 11:23:04,809 Thread-7131 udi_interface      WARNING  Controller:handler_poll: waiting for sync to complete
2022-06-11 11:23:34,824 Thread-7132 udi_interface      WARNING  Controller:handler_poll: waiting for sync to complete
2022-06-11 11:24:04,833 Thread-7133 udi_interface      WARNING  Controller:handler_poll: waiting for sync to complete
2022-06-11 11:24:34,847 Thread-7134 udi_interface      WARNING  Controller:handler_poll: waiting for sync to complete
2022-06-11 11:25:04,852 Thread-7135 udi_interface      WARNING  Controller:handler_poll: waiting for sync to complete
2022-06-11 11:25:34,854 Thread-7136 udi_interface      WARNING  Controller:handler_poll: waiting for sync to complete
2022-06-11 11:25:38,421 Thread-7137 udi_interface      WARNING  Controller:handler_poll: waiting for sync to complete
2022-06-11 11:26:04,857 Thread-7138 udi_interface      WARNING  Controller:handler_poll: waiting for sync to complete
2022-06-11 11:26:34,864 Thread-7139 udi_interface      WARNING  Controller:handler_poll: waiting for sync to complete
2022-06-11 11:27:04,874 Thread-7140 udi_interface      WARNING  Controller:handler_poll: waiting for sync to complete
2022-06-11 11:27:34,888 Thread-7141 udi_interface      WARNING  Controller:handler_poll: waiting for sync to complete
2022-06-11 11:28:04,884 Thread-7142 udi_interface      WARNING  Controller:handler_poll: waiting for sync to complete
2022-06-11 11:28:34,881 Thread-7143 udi_interface      WARNING  Controller:handler_poll: waiting for sync to complete
2022-06-11 11:29:04,899 Thread-7144 udi_interface      WARNING  Controller:handler_poll: waiting for sync to complete
2022-06-11 11:29:34,907 Thread-7145 udi_interface      WARNING  Controller:handler_poll: waiting for sync to complete
2022-06-11 11:29:38,425 Thread-7146 udi_interface      WARNING  Controller:handler_poll: waiting for sync to complete
2022-06-11 11:30:04,912 Thread-7147 udi_interface      WARNING  Controller:handler_poll: waiting for sync to complete
2022-06-11 11:30:34,924 Thread-7148 udi_interface      WARNING  Controller:handler_poll: waiting for sync to complete
2022-06-11 11:31:04,926 Thread-7149 udi_interface      WARNING  Controller:handler_poll: waiting for sync to complete
2022-06-11 11:31:34,931 Thread-7150 udi_interface      WARNING  Controller:handler_poll: waiting for sync to complete
2022-06-11 11:32:04,982 Thread-7151 udi_interface      WARNING  Controller:handler_poll: waiting for sync to complete
2022-06-11 11:32:34,939 Thread-7152 udi_interface      WARNING  Controller:handler_poll: waiting for sync to complete
2022-06-11 11:32:36,845 Thread-7153 udi_interface      WARNING  Controller:stop: ELK Controller: Node server stopping...
2022-06-11 11:32:36,846 Thread-7153 udi_interface      WARNING  Controller:elk_stop: Stopping ELK monitor...
2022-06-11 11:32:36,848 Thread-7153 udi_interface      WARNING  Controller:elk_stop: Stopping ELK thread...
2022-06-11 11:32:44,529 MainThread udi_interface      INFO     polylogger:set_basic_config: set_basic_config: enable=True level=30
2022-06-11 11:32:47,085 MainThread udi_interface      INFO     __init__:<module>: UDI Python Interface for Polyglot version 3 3.0.46 Starting...
2022-06-11 11:32:47,261 MainThread udi_interface.interface INFO     interface:__init__: Initialization received from Polyglot V3 3.0.62  [ISY: 5.4.4, Slot: 9]
2022-06-11 11:32:47,263 MainThread udi_interface.interface INFO     interface:__init__: Connect: Network Interface: {'addr': '192.168.1.99', 'netmask': '255.255.255.0', 'broadcast': '192.168.1.255'}
2022-06-11 11:32:47,264 Interface  udi_interface.interface INFO     interface:_startMqtt: Connecting to MQTT... localhost:1888
2022-06-11 11:32:47,270 MainThread udi_interface.interface WARNING  interface:db_getNodeDrivers: controller not found in database.
2022-06-11 11:32:47,270 MainThread udi_interface.custom INFO     custom:_save: Sending data notices to Polyglot.
2022-06-11 11:32:47,271 MainThread udi_interface.interface WARNING  interface:send: MQTT Send waiting on connection :: {'set': [{'key': 'notices', 'value': {}}]}
2022-06-11 11:32:47,317 MQTT       udi_interface.interface INFO     interface:_connect: MQTT Connected with result code 0 (Success)
2022-06-11 11:32:47,318 MQTT       udi_interface.interface INFO     interface:_connect: MQTT Subscribing to topic: udi/pg3/ns/clients/00:0d:b9:59:41:b0_9 -  MID: 1 Result: 0
2022-06-11 11:32:47,331 MQTT       udi_interface.interface INFO     interface:_subscribe: MQTT Subscribed Succesfully for Message ID: 1 - QoS: (0,)
2022-06-11 11:32:47,419 MQTT       udi_interface.interface INFO     interface:_message: Successfully set controller :: ST to 1 UOM 25
2022-06-11 11:32:50,277 MainThread udi_interface.interface INFO     interface:addNode: Adding node ELK Controller(controller) [None]
2022-06-11 11:32:50,279 MainThread udi_interface.interface INFO     interface:setController: Using node "controller", driver "ST" for connection status.
2022-06-11 11:32:50,376 MQTT       udi_interface.interface INFO     interface:_message: Successfully set key = notices
2022-06-11 11:33:15,127 Thread-129 udi_interface      WARNING  Controller:handler_poll: waiting for sync to complete
2022-06-11 11:33:45,095 Thread-1108 udi_interface      WARNING  Controller:handler_poll: waiting for sync to complete
2022-06-11 11:34:15,085 Thread-3522 udi_interface      WARNING  Controller:handler_poll: waiting for sync to complete
2022-06-11 11:34:45,258 Thread-7561 udi_interface      WARNING  Controller:handler_poll: waiting for sync to complete
2022-06-11 11:35:15,223 Thread-12807 udi_interface      WARNING  Controller:handler_poll: waiting for sync to complete
2022-06-11 11:35:25,465 Thread-14975 udi_interface      ERROR    udi_interface:write: Exception in thread
2022-06-11 11:35:25,470 Thread-14975 udi_interface      ERROR    udi_interface:write: Thread-14975
2022-06-11 11:35:25,475 Thread-14975 udi_interface      ERROR    udi_interface:write: :
2022-06-11 11:35:25,479 Thread-14975 udi_interface      ERROR    udi_interface:write: Traceback (most recent call last):
2022-06-11 11:35:25,485 Thread-14975 udi_interface      ERROR    udi_interface:write: File "/usr/local/lib/python3.8/threading.py", line 932, in _bootstrap_inner
2022-06-11 11:35:25,530 Thread-14975 udi_interface      ERROR    udi_interface:write: self.run()
2022-06-11 11:35:25,534 Thread-14975 udi_interface      ERROR    udi_interface:write: File "/usr/local/lib/python3.8/threading.py", line 870, in run
2022-06-11 11:35:25,577 Thread-14975 udi_interface      ERROR    udi_interface:write: self._target(*self._args, **self._kwargs)
2022-06-11 11:35:25,578 Thread-14975 udi_interface      ERROR    udi_interface:write: File "/var/polyglot/pg3/ns/00:0d:b9:59:41:b0_9/nodes/Counter.py", line 37, in handler_addnodedone
2022-06-11 11:35:25,580 Thread-14975 udi_interface      ERROR    udi_interface:write: self.set_val()
2022-06-11 11:35:25,580 Thread-14975 udi_interface      ERROR    udi_interface:write: File "/var/polyglot/pg3/ns/00:0d:b9:59:41:b0_9/nodes/Counter.py", line 53, in set_val
2022-06-11 11:35:25,581 Thread-14975 udi_interface      ERROR    udi_interface:write: self.set_driver('ST',int(val))
2022-06-11 11:35:25,582 Thread-14975 udi_interface      ERROR    udi_interface:write: TypeError
2022-06-11 11:35:25,583 Thread-14975 udi_interface      ERROR    udi_interface:write: :
2022-06-11 11:35:25,583 Thread-14975 udi_interface      ERROR    udi_interface:write: int() argument must be a string, a bytes-like object or a number, not 'NoneType'
2022-06-11 11:40:47,046 Thread-17328 udi_interface      WARNING  Controller:stop: ELK Controller: Node server stopping...
2022-06-11 11:40:47,048 Thread-17328 udi_interface      WARNING  Controller:elk_stop: Stopping ELK monitor...
2022-06-11 11:40:47,050 Thread-17328 udi_interface      WARNING  Controller:elk_stop: Stopping ELK thread...
2022-06-11 11:41:21,316 MainThread udi_interface      INFO     polylogger:set_basic_config: set_basic_config: enable=True level=30
2022-06-11 11:41:25,782 MainThread udi_interface      INFO     __init__:<module>: UDI Python Interface for Polyglot version 3 3.0.46 Starting...
2022-06-11 11:41:26,058 MainThread udi_interface.interface INFO     interface:__init__: Initialization received from Polyglot V3 3.0.62  [ISY: 5.4.4, Slot: 9]
2022-06-11 11:41:26,061 MainThread udi_interface.interface INFO     interface:__init__: Connect: Network Interface: {'addr': '192.168.1.99', 'netmask': '255.255.255.0', 'broadcast': '192.168.1.255'}
2022-06-11 11:41:26,063 Interface  udi_interface.interface INFO     interface:_startMqtt: Connecting to MQTT... localhost:1888
2022-06-11 11:41:26,126 MainThread udi_interface.interface WARNING  interface:db_getNodeDrivers: controller not found in database.
2022-06-11 11:41:26,127 MainThread udi_interface.custom INFO     custom:_save: Sending data notices to Polyglot.
2022-06-11 11:41:26,128 MainThread udi_interface.interface WARNING  interface:send: MQTT Send waiting on connection :: {'set': [{'key': 'notices', 'value': {}}]}
2022-06-11 11:41:26,218 MQTT       udi_interface.interface INFO     interface:_connect: MQTT Connected with result code 0 (Success)
2022-06-11 11:41:26,220 MQTT       udi_interface.interface INFO     interface:_connect: MQTT Subscribing to topic: udi/pg3/ns/clients/00:0d:b9:59:41:b0_9 -  MID: 1 Result: 0
2022-06-11 11:41:26,257 MQTT       udi_interface.interface INFO     interface:_subscribe: MQTT Subscribed Succesfully for Message ID: 1 - QoS: (0,)
2022-06-11 11:41:26,545 MQTT       udi_interface.interface INFO     interface:_message: Successfully set controller :: ST to 1 UOM 25
2022-06-11 11:41:29,132 MainThread udi_interface.interface INFO     interface:addNode: Adding node ELK Controller(controller) [None]
2022-06-11 11:41:29,135 MainThread udi_interface.interface INFO     interface:setController: Using node "controller", driver "ST" for connection status.
2022-06-11 11:41:29,245 MQTT       udi_interface.interface INFO     interface:_message: Successfully set key = notices
2022-06-11 11:41:52,530 Thread-9   udi_interface      WARNING  Controller:handler_poll: waiting for sync to complete
2022-06-11 11:42:22,204 Thread-49  udi_interface      WARNING  Controller:handler_poll: waiting for sync to complete
2022-06-11 11:42:52,318 Thread-777 udi_interface      WARNING  Controller:handler_poll: waiting for sync to complete
2022-06-11 11:43:22,205 Thread-3048 udi_interface      WARNING  Controller:handler_poll: waiting for sync to complete
2022-06-11 11:43:52,359 Thread-6969 udi_interface      WARNING  Controller:handler_poll: waiting for sync to complete
2022-06-11 11:44:22,360 Thread-12655 udi_interface      WARNING  Controller:handler_poll: waiting for sync to complete
2022-06-11 11:44:32,883 Thread-14982 udi_interface      ERROR    udi_interface:write: Exception in thread
2022-06-11 11:44:32,885 Thread-14982 udi_interface      ERROR    udi_interface:write: Thread-14982
2022-06-11 11:44:32,885 Thread-14982 udi_interface      ERROR    udi_interface:write: :
2022-06-11 11:44:32,886 Thread-14982 udi_interface      ERROR    udi_interface:write: Traceback (most recent call last):
2022-06-11 11:44:32,887 Thread-14982 udi_interface      ERROR    udi_interface:write: File "/usr/local/lib/python3.8/threading.py", line 932, in _bootstrap_inner
2022-06-11 11:44:32,891 Thread-14982 udi_interface      ERROR    udi_interface:write: self.run()
2022-06-11 11:44:32,893 Thread-14982 udi_interface      ERROR    udi_interface:write: File "/usr/local/lib/python3.8/threading.py", line 870, in run
2022-06-11 11:44:32,897 Thread-14982 udi_interface      ERROR    udi_interface:write: self._target(*self._args, **self._kwargs)
2022-06-11 11:44:32,897 Thread-14982 udi_interface      ERROR    udi_interface:write: File "/var/polyglot/pg3/ns/00:0d:b9:59:41:b0_9/nodes/Counter.py", line 37, in handler_addnodedone
2022-06-11 11:44:32,899 Thread-14982 udi_interface      ERROR    udi_interface:write: self.set_val()
2022-06-11 11:44:32,899 Thread-14982 udi_interface      ERROR    udi_interface:write: File "/var/polyglot/pg3/ns/00:0d:b9:59:41:b0_9/nodes/Counter.py", line 53, in set_val
2022-06-11 11:44:32,900 Thread-14982 udi_interface      ERROR    udi_interface:write: self.set_driver('ST',int(val))
2022-06-11 11:44:32,901 Thread-14982 udi_interface      ERROR    udi_interface:write: TypeError
2022-06-11 11:44:32,901 Thread-14982 udi_interface      ERROR    udi_interface:write: :
2022-06-11 11:44:32,902 Thread-14982 udi_interface      ERROR    udi_interface:write: int() argument must be a string, a bytes-like object or a number, not 'NoneType'
2022-06-11 12:24:02,453 Thread-17407 udi_interface      WARNING  Controller:stop: ELK Controller: Node server stopping...
2022-06-11 12:24:02,455 Thread-17407 udi_interface      WARNING  Controller:elk_stop: Stopping ELK monitor...
2022-06-11 12:24:02,457 Thread-17407 udi_interface      WARNING  Controller:elk_stop: Stopping ELK thread...
2022-06-11 12:25:46,202 MainThread udi_interface      INFO     polylogger:set_basic_config: set_basic_config: enable=True level=30
2022-06-11 12:25:52,293 MainThread udi_interface      INFO     __init__:<module>: UDI Python Interface for Polyglot version 3 3.0.46 Starting...
2022-06-11 12:25:53,051 MainThread udi_interface.interface INFO     interface:__init__: Initialization received from Polyglot V3 3.0.62  [ISY: 5.4.4, Slot: 9]
2022-06-11 12:25:53,053 MainThread udi_interface.interface INFO     interface:__init__: Connect: Network Interface: {'addr': '192.168.1.99', 'netmask': '255.255.255.0', 'broadcast': '192.168.1.255'}
2022-06-11 12:25:53,072 Interface  udi_interface.interface INFO     interface:_startMqtt: Connecting to MQTT... localhost:1888
2022-06-11 12:25:53,084 MainThread udi_interface.interface WARNING  interface:db_getNodeDrivers: controller not found in database.
2022-06-11 12:25:53,085 MainThread udi_interface.custom INFO     custom:_save: Sending data notices to Polyglot.
2022-06-11 12:25:53,085 MainThread udi_interface.interface WARNING  interface:send: MQTT Send waiting on connection :: {'set': [{'key': 'notices', 'value': {}}]}
2022-06-11 12:25:53,260 MQTT       udi_interface.interface INFO     interface:_connect: MQTT Connected with result code 0 (Success)
2022-06-11 12:25:53,264 MQTT       udi_interface.interface INFO     interface:_connect: MQTT Subscribing to topic: udi/pg3/ns/clients/00:0d:b9:59:41:b0_9 -  MID: 1 Result: 0
2022-06-11 12:25:53,323 MQTT       udi_interface.interface INFO     interface:_subscribe: MQTT Subscribed Succesfully for Message ID: 1 - QoS: (0,)
2022-06-11 12:25:53,492 MQTT       udi_interface.interface INFO     interface:_message: Successfully set controller :: ST to 1 UOM 25
2022-06-11 12:25:56,094 MainThread udi_interface.interface INFO     interface:addNode: Adding node ELK Controller(controller) [None]
2022-06-11 12:25:56,097 MainThread udi_interface.interface INFO     interface:setController: Using node "controller", driver "ST" for connection status.
2022-06-11 12:26:05,031 MQTT       udi_interface.interface INFO     interface:_message: Successfully set key = notices
2022-06-11 12:26:16,717 Thread-9   udi_interface      WARNING  Controller:handler_poll: waiting for sync to complete
2022-06-11 12:26:47,076 Thread-10  udi_interface      WARNING  Controller:handler_poll: waiting for sync to complete
2022-06-11 12:27:17,296 Thread-11  udi_interface      WARNING  Controller:handler_poll: waiting for sync to complete
2022-06-11 12:27:48,606 Thread-12  udi_interface      WARNING  Controller:handler_poll: waiting for sync to complete
2022-06-11 12:28:19,599 Thread-13  udi_interface      WARNING  Controller:handler_poll: waiting for sync to complet

Edited by btreinders
Jimbo.Automates Advanced

Jimbo.Automates

Posted
Posted

Sorry for the late reply, been out of town.  This looks like a bug handling Counters.  Please set log level to Debug + Modules and restart the node server, then wait a few minutes and download log package and send that to me in a PM.

 

btreinders Advanced

btreinders

Posted
  • Author
Posted
On 6/17/2022 at 11:39 AM, JimboAutomates said:

Sorry for the late reply, been out of town.  This looks like a bug handling Counters.  Please set log level to Debug + Modules and restart the node server, then wait a few minutes and download log package and send that to me in a PM.

 

Thank you!  Sent in PM.  It is working now, it set off the alarm randomly and then started working.  Was very strange.

  • 3 weeks later...
garybixler Advanced

garybixler

Posted
Posted

I am at 3.3.5 and I am getting this error. Tried rebooting. Started after doing pg3 upgrade. 3.0.63.

Thanks Gary

2022-07-08 15:15:41,342 ELK-1757 udi_interface INFO Controller:sync_complete: ELK Controller: Adding Area 0

2022-07-08 15:15:41,347 ELK-1757 elkm1_lib.elk ERROR elk:_got_data: Invalid message '19UA000000003030661C41F00CF'

Traceback (most recent call last):

File "/var/polyglot/.local/lib/python3.9/site-packages/elkm1_lib/elk.py", line 139, in _got_data

self._message_decode.decode(data)

File "/var/polyglot/.local/lib/python3.9/site-packages/elkm1_lib/message.py", line 67, in decode

self.call_handlers(cmd, decoder(msg))

File "/var/polyglot/.local/lib/python3.9/site-packages/elkm1_lib/message.py", line 55, in call_handlers

handler(**decoded_msg)

File "/var/polyglot/.local/lib/python3.9/site-packages/elkm1_lib/elk.py", line 56, in _sync_complete

self._message_decode.call_handlers("sync_complete", {})

File "/var/polyglot/.local/lib/python3.9/site-packages/elkm1_lib/message.py", line 55, in call_handlers

handler(**decoded_msg)

File "/var/polyglot/pg3/ns/00:0d:b9:52:bf:38_14/nodes/Controller.py", line 243, in sync_complete

node = self.add_node(address,AreaNode(self, address, self.elk.areas[an]))

File "/var/polyglot/pg3/ns/00:0d:b9:52:bf:38_14/nodes/Controller.py", line 205, in add_node

cname = self.poly.getNodeNameFromDb(address)

AttributeError: 'Interface' object has no attribute 'getNodeNameFromDb'

2022-07-08 15:15:41,472 MQTT udi_interface.interface INFO interface:_message: Successfully set controller :: GV1 to 5 UOM 25

2022-07-08 15:16:00,711 Thread-9 udi_interface WARNING Controller:handler_poll: waiting for sync to complete

garybixler Advanced

garybixler

Posted
Posted

No, I assume it was something done after the pg3 update. I was getting the message that pg3 needed to be rebooted so I just did the update and pg3 restart  through SSH

garybixler Advanced

garybixler

Posted
Posted

change_nodes_names was originally set to false on the config. Tried setting to true but same results. Not sure if this would be of any importance.

Jimbo.Automates Advanced

Jimbo.Automates

Posted
Posted

Looks like your PG3 python interface is not up-to-date, not sure how that can happen.  Please PM me the download log package file.

You can ssh to your polisy and run this, then restart the node server: 

sudo -u polyglot pip3 install --upgrade udi_interface

 

bpwwer Advanced

bpwwer

Posted
Posted

 

46 minutes ago, JimboAutomates said:

@bpwwer was this intentionally removed? Interface getNodeNameFromDb

Sent from my Pixel 6 Pro using Tapatalk
 

No. it was not removed. 

PG3, since version 3.0.57 has forced the udi_interface to update to the latest version when it starts.

Jimbo.Automates Advanced

Jimbo.Automates

Posted
Posted
Just now, bpwwer said:

 

No. it was not removed. 

PG3, since version 3.0.57 has forced the udi_interface to update to the latest version when it starts.

Weird, he was till on an older version, and pip3 command above fixed it.

Jimbo.Automates Advanced

Jimbo.Automates

Posted
Posted

He was on: 

2022-07-08 10:42:19,374 MainThread udi_interface      INFO     __init__:<module>: UDI Python Interface for Polyglot version 3 3.0.32 Starting...

and now is on 

2022-07-08 16:13:16,193 MainThread udi_interface      INFO     __init__:<module>: UDI Python Interface for Polyglot version 3 3.0.46 Starting...

 

Guest
This topic is now closed to further replies.
Go to topic listing

  • Recently Browsing

    • No registered users viewing this page.

  • Who's Online (See full list)

  • Forum Statistics

    • Total Topics
      37.1k
    • Total Posts
      371.5k
×
×
  • Create New...