MiniUnifi intermittently failing on new (to me) iMac

I moved Indigo from an old iMac (2009) to a newer iMac (2015) and started having problems with plugins hanging. After quite a bit of process-of-elimination testing, I think MiniUnifi is the problem.

Lutron, Pushover, AD2USB, and Sonos have been running together quite happily for a couple of weeks now. But as soon as I enable miniUnifi, I see these failure chains happening periodically.

Code: Select all

   miniUniFi Error                 Error in plugin execution runConcurrentThread:

Traceback (most recent call last):
  File "plugin.py", line 122, in runConcurrentThread
StandardError: ServerCommunicationError -- timeout waiting for response

   miniUniFi Error                 plugin runConcurrentThread function returned or failed (will attempt again in 10 seconds)
   Sonos Error                     Error in plugin execution runConcurrentThread:

Traceback (most recent call last):
  File "plugin.py", line 47, in runConcurrentThread
  File "/Library/Application Support/Perceptive Automation/Indigo 2021.2/Plugins/Sonos.indigoPlugin/Contents/Server Plugin/Sonos.py", line 384, in runConcurrentThread
  File "/Library/Application Support/Perceptive Automation/Indigo 2021.2/Plugins/Sonos.indigoPlugin/Contents/Server Plugin/Sonos.py", line 634, in updateRelTime
StandardError: ServerCommunicationError -- timeout waiting for response

   Sonos Error                     plugin runConcurrentThread function returned or failed (will attempt again in 10 seconds)
   AD2USB Alarm Interface Error    Error on line 749
   AD2USB Alarm Interface Error    panelMsgRead: Error: ServerCommunicationError -- timeout waiting for response
   Sonos                           SoCo Reactor Ignition...
   AD2USB Alarm Interface Error    Error on line 749
   AD2USB Alarm Interface Error    panelMsgRead: Error: ServerCommunicationError -- timeout waiting for response
   miniUniFi Error                 Error in plugin execution runConcurrentThread:

Traceback (most recent call last):
  File "plugin.py", line 112, in runConcurrentThread
StandardError: ServerCommunicationError -- timeout waiting for response

   miniUniFi Error                 plugin runConcurrentThread function returned or failed (will attempt again in 10 seconds)
   Sonos Error                     Error in plugin execution runConcurrentThread:

Traceback (most recent call last):
  File "plugin.py", line 47, in runConcurrentThread
  File "/Library/Application Support/Perceptive Automation/Indigo 2021.2/Plugins/Sonos.indigoPlugin/Contents/Server Plugin/Sonos.py", line 384, in runConcurrentThread
  File "/Library/Application Support/Perceptive Automation/Indigo 2021.2/Plugins/Sonos.indigoPlugin/Contents/Server Plugin/Sonos.py", line 634, in updateRelTime
StandardError: ServerCommunicationError -- timeout waiting for response

   Sonos Error                     plugin runConcurrentThread function returned or failed (will attempt again in 10 seconds)
   Sonos                           SoCo Reactor Ignition...
   AD2USB Alarm Interface Error    Error on line 749
   AD2USB Alarm Interface Error    panelMsgRead: Error: ServerCommunicationError -- timeout waiting for response
   miniUniFi Error                 Error in plugin execution runConcurrentThread:

Traceback (most recent call last):
  File "plugin.py", line 112, in runConcurrentThread
  File "plugin.py", line 204, in updateUniFiController
KeyError: 'key port not found in dict'

   miniUniFi Error                 plugin runConcurrentThread function returned or failed (will attempt again in 10 seconds)

Any idea why this might be happening?

iMac late 2015
Monterey 12.0.1
Indigo 2021.2
miniUnifi 0.1.2
Unifi Controller 6.5.55 running on CloudKey

Any suggestions? I've switched miniUnifi to display debugging messages so that I have more detail the next time it happens.

This look like time outs.
Indigo is late responding to the plugins
Not just UniFi but also Sonos.

Do you have any apple script?
And scripts that take long?


Sent from my iPhone using Tapatalk

No scripts at all, and this wasn't happening at all for any of the running plugins until I enabled miniUnifi.

[jay (support)]

Were there any errors before the log you posted above? By that time, whatever went wrong had already happened. Maybe post another 20 or so lines before the first error in your log snippit above so we can see what was happening before.

The failure is on this line:

Code: Select all

                        self.updateUniFiDevice(indigo.devices[deviceID])


So it's timing out getting the device instance from the server.

I have some experience with that behavior

with high likelihood it is not that plugin but something that is stopping Indigo from answering.


Sent from my iPhone using Tapatalk

[jay (support)]

As I stated above, the log lines posted are not particularly useful as it just indicates that the server is having issues talking to plugins in general.

The more interesting comment is that it didn't start until you enabled this plugin. While that's definitely not enough proof to indicate that it's the problem, it might be an indicator. Which is why I asked for log lines that happen before the above posted log - that would give us a better idea of what might have caused the issue.

Were there any errors before the log you posted above? By that time, whatever went wrong had already happened. Maybe post another 20 or so lines before the first error in your log snippit above so we can see what was happening before.

Will do.

The failure is on this line:

Code: Select all

 self.updateUniFiDevice(indigo.devices[deviceID])

So it's timing out getting the device instance from the server.

Which server? Indigo or Unifi?

The failure is on this line:

Code: Select all

 self.updateUniFiDevice(indigo.devices[deviceID])

So it's timing out getting the device instance from the server.

Which server? Indigo or Unifi?

Indigo. As Jay said, there's something going on preventing communication between the Indigo server and multiple plugins.

Here you go. Lots of info.

Code: Select all

2022-01-26 07:54:46.275   Application   Indigo Cocoa client authenticated (192.168.0.13)
2022-01-26 08:01:56.166   Lutron RRA2/Caséta   Received: Motion Sensor Master Closet - Motion Sensor 62 vacancy detected
2022-01-26 08:03:37.571   Lutron RRA2/Caséta   Received: Motion Sensor Master Closet - Motion Sensor 62 motion detected
2022-01-26 08:11:17.348   Sonos Error   versionCheck: Unable to reach the version server.
2022-01-26 08:13:10.664   Z-Wave   received "Basement - Temperature" sensor update to 58.9 °F
2022-01-26 08:15:24.220   Lutron RRA2/Caséta   Received: Motion Sensor Master Closet - Motion Sensor 62 vacancy detected
2022-01-26 08:19:12.912   Lutron RRA2/Caséta   Received: Motion Sensor Kitchen - Motion Sensor 32 vacancy detected
2022-01-26 08:19:39.796   Lutron RRA2/Caséta   Received: Motion Sensor Master Closet - Motion Sensor 62 motion detected
2022-01-26 08:24:45.361   Lutron RRA2/Caséta   Received: Motion Sensor Master Closet - Motion Sensor 62 vacancy detected
2022-01-26 08:30:41.388   Lutron RRA2/Caséta   Received: Motion Sensor Master Closet - Motion Sensor 62 motion detected
2022-01-26 08:31:50.822   Z-Wave   received "Garage Temp" sensor update to 45.6 °F
2022-01-26 08:38:19.060   Lutron RRA2/Caséta   Received: Motion Sensor Kitchen - Motion Sensor 32 motion detected
2022-01-26 08:39:04.344   Lutron RRA2/Caséta   Received: Motion Sensor Master Closet - Motion Sensor 62 vacancy detected
2022-01-26 08:41:12.044   Lutron RRA2/Caséta   Received: Motion Sensor Laundry - Motion Sensor 60 motion detected
2022-01-26 08:46:54.580   Lutron RRA2/Caséta   Received: Motion Sensor Laundry - Motion Sensor 60 vacancy detected
2022-01-26 08:49:23.172   Trigger   House Off - Exit Lights if not dark
2022-01-26 08:49:23.173   Lutron RRA2/Caséta   Foyer Pendant: Set brightness to 0 with fade 2
2022-01-26 08:49:23.174   Lutron RRA2/Caséta   Garage Lights: Set brightness to 0 with fade 2
2022-01-26 08:49:23.386   Trigger   House Off Double Click - Sonos Stop
2022-01-26 08:49:23.386   Action Group   Stop All Sonos Players
2022-01-26 08:49:23.407   Sonos   ZonePlayer: Sonos Kitchen, Stop
2022-01-26 08:49:23.421   Sonos   ZonePlayer: Sonos Office, Stop
2022-01-26 08:49:23.437   Sonos   ZonePlayer: Sonos Bathroom Left, Stop
2022-01-26 08:49:23.450   Sonos   ZonePlayer: Sonos Bathroom Right, Stop
2022-01-26 08:49:23.462   Sonos   ZonePlayer: Sonos Living Room, Stop
2022-01-26 08:49:23.476   Sonos   ZonePlayer: Sonos Bedroom, Stop
2022-01-26 08:49:23.486   Sonos   ZonePlayer: Sonos Basement, Stop
2022-01-26 08:49:23.499   Sonos   ZonePlayer: Sonos Move, Stop
2022-01-26 08:49:23.595   Trigger   House Off Triple Click - Arm Alarm
2022-01-26 08:49:24.083   Pushover   Pushover notification was sent sucessfully, title: , body: TRIPLE TAP!!!
2022-01-26 08:49:28.166   Trigger   Master Bath Shower Off
2022-01-26 08:51:53.290   Schedule   House Off Triple Click - Arm Alarm (delayed action)
2022-01-26 08:51:54.426   Trigger   Alarm Armed Away Variable Set
2022-01-26 08:51:54.426   Trigger   Alarm Armed Away -> RR2 Away
2022-01-26 08:51:54.426   Trigger   Alarm Armed Away -> Humidifier Off
2022-01-26 08:51:54.584   Z-Wave   sent "Aprilaire Humidifier" off
2022-01-26 08:52:24.330   Schedule   Alarm Armed Away -> RR2 Away (delayed action)
2022-01-26 09:00:21.000   Trigger   iPhone Liz Away
2022-01-26 09:00:21.482   Pushover   Pushover notification was sent sucessfully, title: , body: Mike and Liz are away
2022-01-26 09:05:06.355   Lutron RRA2/Caséta   Received: Motion Sensor Kitchen - Motion Sensor 32 vacancy detected
2022-01-26 09:06:51.455   Application   Indigo Cocoa client disconnected (192.168.0.13)
2022-01-26 09:06:51.456   miniUniFi Error   Error in plugin execution runConcurrentThread:

Traceback (most recent call last):
  File "plugin.py", line 122, in runConcurrentThread
StandardError: ServerCommunicationError -- timeout waiting for response

2022-01-26 09:06:51.456   miniUniFi Error   plugin runConcurrentThread function returned or failed (will attempt again in 10 seconds)
2022-01-26 09:06:51.456   Sonos Error   Error in plugin execution runConcurrentThread:

Traceback (most recent call last):
  File "plugin.py", line 47, in runConcurrentThread
  File "/Library/Application Support/Perceptive Automation/Indigo 2021.2/Plugins/Sonos.indigoPlugin/Contents/Server Plugin/Sonos.py", line 384, in runConcurrentThread
  File "/Library/Application Support/Perceptive Automation/Indigo 2021.2/Plugins/Sonos.indigoPlugin/Contents/Server Plugin/Sonos.py", line 634, in updateRelTime
StandardError: ServerCommunicationError -- timeout waiting for response

2022-01-26 09:06:51.456   Sonos Error   plugin runConcurrentThread function returned or failed (will attempt again in 10 seconds)
2022-01-26 09:06:51.456   AD2USB Alarm Interface Error   Error on line 749
2022-01-26 09:06:51.456   AD2USB Alarm Interface Error   panelMsgRead: Error: ServerCommunicationError -- timeout waiting for response
2022-01-26 09:06:51.457   Sonos   SoCo Reactor Ignition...
2022-01-26 09:06:51.457   AD2USB Alarm Interface Error   Error on line 749
2022-01-26 09:06:51.457   AD2USB Alarm Interface Error   panelMsgRead: Error: ServerCommunicationError -- timeout waiting for response
2022-01-26 09:06:51.457   miniUniFi Error   Error in plugin execution runConcurrentThread:

Traceback (most recent call last):
  File "plugin.py", line 112, in runConcurrentThread
StandardError: ServerCommunicationError -- timeout waiting for response

2022-01-26 09:06:51.457   miniUniFi Error   plugin runConcurrentThread function returned or failed (will attempt again in 10 seconds)
2022-01-26 09:06:51.457   Sonos Error   Error in plugin execution runConcurrentThread:

Traceback (most recent call last):
  File "plugin.py", line 47, in runConcurrentThread
  File "/Library/Application Support/Perceptive Automation/Indigo 2021.2/Plugins/Sonos.indigoPlugin/Contents/Server Plugin/Sonos.py", line 384, in runConcurrentThread
  File "/Library/Application Support/Perceptive Automation/Indigo 2021.2/Plugins/Sonos.indigoPlugin/Contents/Server Plugin/Sonos.py", line 634, in updateRelTime
StandardError: ServerCommunicationError -- timeout waiting for response

2022-01-26 09:06:51.457   Sonos Error   plugin runConcurrentThread function returned or failed (will attempt again in 10 seconds)
2022-01-26 09:06:59.622   Sonos   SoCo Reactor Ignition...
2022-01-26 09:13:36.075   Z-Wave   received "Basement - Temperature" sensor update to 59.0 °F
2022-01-26 09:13:37.287   Z-Wave   received "015 - Basement - Water Sensor" status update battery level 33%
2022-01-26 09:19:32.363   Trigger   Alarm Disarmed -> RR2 Home
2022-01-26 09:19:32.363   Trigger   Alarm Disarmed -> Stop any alarm arming delayed action
2022-01-26 09:19:32.363   Trigger   Alarm Disarmed -> Humidifier On
2022-01-26 09:19:32.422   Z-Wave   sent "Aprilaire Humidifier" on
2022-01-26 09:21:24.684   Trigger   Front Door Open
2022-01-26 09:21:35.726   Lutron RRA2/Caséta   Received: Motion Sensor Kitchen - Motion Sensor 32 motion detected
2022-01-26 09:21:37.801   Trigger   Front Door Closed
2022-01-26 09:21:42.641   Trigger   iPhone Mike Home
2022-01-26 09:21:43.042   Pushover   Pushover notification was sent sucessfully, title: , body: Mike or Liz came home
2022-01-26 09:22:56.429   Trigger   Guest Bath On
2022-01-26 09:25:20.315   Lutron RRA2/Caséta   Received: Motion Sensor Laundry - Motion Sensor 60 motion detected
2022-01-26 09:31:10.854   Lutron RRA2/Caséta   Received: Motion Sensor Laundry - Motion Sensor 60 vacancy detected
2022-01-26 09:32:42.077   Trigger   Guest Bath Off
2022-01-26 09:32:45.155   Lutron RRA2/Caséta   Received: Motion Sensor Laundry - Motion Sensor 60 motion detected
2022-01-26 09:33:00.801   Z-Wave   received "Garage Temp" sensor update to 45.5 °F
2022-01-26 09:43:51.882   Lutron RRA2/Caséta   Received: Motion Sensor Laundry - Motion Sensor 60 vacancy detected
2022-01-26 09:45:59.844   Trigger   Master Bath Sink On
2022-01-26 09:46:01.463   Trigger   Master Bath Shower On
2022-01-26 10:04:01.492   Lutron RRA2/Caséta   Received: Motion Sensor Basement Stairs Motion motion detected
2022-01-26 10:05:14.874   Lutron RRA2/Caséta   Received: Motion Sensor Basement Stairs Motion vacancy detected
2022-01-26 10:08:43.645   Lutron RRA2/Caséta   Received: Motion Sensor Basement Stairs Motion motion detected
2022-01-26 10:10:00.460   Lutron RRA2/Caséta   Received: Motion Sensor Basement Stairs Motion vacancy detected
2022-01-26 10:14:00.003   Z-Wave   received "Basement - Temperature" sensor update to 59.4 °F
2022-01-26 10:14:36.859   Application   Indigo Cocoa client authenticated (192.168.0.13)
2022-01-26 10:15:38.123   miniUniFi Error   Error in plugin execution runConcurrentThread:

Traceback (most recent call last):
  File "plugin.py", line 122, in runConcurrentThread
  File "plugin.py", line 430, in updateUniFiDevice
StandardError: ServerCommunicationError -- timeout waiting for response

2022-01-26 10:15:38.123   miniUniFi Error   plugin runConcurrentThread function returned or failed (will attempt again in 10 seconds)
2022-01-26 10:17:32.408   Application   Indigo Cocoa client disconnected (192.168.0.13)
2022-01-26 10:17:32.409   miniUniFi Error   Error in plugin execution runConcurrentThread:

Traceback (most recent call last):
  File "plugin.py", line 122, in runConcurrentThread
StandardError: ServerCommunicationError -- timeout waiting for response

2022-01-26 10:17:32.409   miniUniFi Error   plugin runConcurrentThread function returned or failed (will attempt again in 10 seconds)
2022-01-26 10:17:32.409   Sonos Error   Error in plugin execution runConcurrentThread:

Traceback (most recent call last):
  File "plugin.py", line 47, in runConcurrentThread
  File "/Library/Application Support/Perceptive Automation/Indigo 2021.2/Plugins/Sonos.indigoPlugin/Contents/Server Plugin/Sonos.py", line 384, in runConcurrentThread
  File "/Library/Application Support/Perceptive Automation/Indigo 2021.2/Plugins/Sonos.indigoPlugin/Contents/Server Plugin/Sonos.py", line 634, in updateRelTime
StandardError: ServerCommunicationError -- timeout waiting for response

2022-01-26 10:17:32.409   Sonos Error   plugin runConcurrentThread function returned or failed (will attempt again in 10 seconds)
2022-01-26 10:17:32.409   AD2USB Alarm Interface Error   Error on line 749
2022-01-26 10:17:32.409   AD2USB Alarm Interface Error   panelMsgRead: Error: ServerCommunicationError -- timeout waiting for response
2022-01-26 10:17:32.409   AD2USB Alarm Interface Error   Error on line 749
2022-01-26 10:17:32.410   AD2USB Alarm Interface Error   panelMsgRead: Error: ServerCommunicationError -- timeout waiting for response
2022-01-26 10:17:32.410   miniUniFi Error   Error in plugin execution runConcurrentThread:

Traceback (most recent call last):
  File "plugin.py", line 112, in runConcurrentThread
StandardError: ServerCommunicationError -- timeout waiting for response

2022-01-26 10:17:32.410   miniUniFi Error   plugin runConcurrentThread function returned or failed (will attempt again in 10 seconds)
2022-01-26 10:17:32.410   Sonos   SoCo Reactor Ignition...
2022-01-26 10:17:32.410   AD2USB Alarm Interface Error   Error on line 749
2022-01-26 10:17:32.410   AD2USB Alarm Interface Error   panelMsgRead: Error: ServerCommunicationError -- timeout waiting for response
2022-01-26 10:17:32.417   miniUniFi Error   Error in plugin execution runConcurrentThread:

Traceback (most recent call last):
  File "plugin.py", line 112, in runConcurrentThread
  File "plugin.py", line 204, in updateUniFiController
KeyError: 'key port not found in dict'

2022-01-26 10:17:32.417   miniUniFi Error   plugin runConcurrentThread function returned or failed (will attempt again in 10 seconds)
2022-01-26 10:34:10.856   Z-Wave   received "Garage Temp" sensor update to 47.1 °F
2022-01-26 10:39:47.691   Lutron RRA2/Caséta   Received: Motion Sensor Master Closet - Motion Sensor 62 motion detected
2022-01-26 10:43:41.057   Trigger   Guest Bath On
2022-01-26 10:45:04.440   Lutron RRA2/Caséta   Received: Motion Sensor Master Closet - Motion Sensor 62 vacancy detected
2022-01-26 10:47:31.652   Lutron RRA2/Caséta   Received: Motion Sensor Laundry - Motion Sensor 60 motion detected
2022-01-26 10:48:48.786   Lutron RRA2/Caséta   Received: Motion Sensor Master Closet - Motion Sensor 62 motion detected
2022-01-26 10:52:45.228   Lutron RRA2/Caséta   Received: Motion Sensor Laundry - Motion Sensor 60 vacancy detected
2022-01-26 10:56:05.931   Trigger   Master Bath Shower Off
2022-01-26 10:57:30.277   Lutron RRA2/Caséta   Received: Motion Sensor Master Closet - Motion Sensor 62 vacancy detected
2022-01-26 11:05:19.765   Trigger   Front Door Open
2022-01-26 11:05:23.764   Trigger   Front Door Closed
2022-01-26 11:06:39.435   Trigger   Front Door Open
2022-01-26 11:06:57.515   Trigger   Front Door Closed
2022-01-26 11:08:06.077   Trigger   Front Door Open
2022-01-26 11:08:10.015   Trigger   Front Door Closed
2022-01-26 11:12:11.241   Trigger   Front Door Open
2022-01-26 11:13:11.149   Schedule   Front Door Open (delayed action)
2022-01-26 11:13:11.154   Email+   sending email 'Front Door is open' to 'mike@mikeandliz.com' using Email+ SMTP Server
2022-01-26 11:13:35.225   Trigger   Front Door Closed
2022-01-26 11:13:35.809   Trigger   Front Door Open
2022-01-26 11:14:25.752   Z-Wave   received "Basement - Temperature" sensor update to 59.8 °F
2

Can you make sure the miniUniFi plugin is set to debug logging, then restart that plugin, and show the log from that point until you get the error again.

Thanks!

I did it this morning. Lots of logging output now, so there's a lot more scrolling back to find the error. But I'll keep looking. It could be a couple of days.

Oh, wait. I see this buried in that log:

Code: Select all

2022-01-26 10:17:32.417   miniUniFi Error   Error in plugin execution runConcurrentThread:

Traceback (most recent call last):
  File "plugin.py", line 112, in runConcurrentThread
  File "plugin.py", line 204, in updateUniFiController
KeyError: 'key port not found in dict'


Edit the UniFi Controller device and make sure the port is specified. If that used to be there, I'm thinking something corrupted the database.

It looks OK.

Screen Shot 2022-01-27 at 5.07.21 PM.png (508.68 KiB) Viewed 3311 times

Restart the plugin and see if you get another error when that device starts up.