A couple of weird behaviors moving to a new iMac

Posted on
Sat Dec 25, 2021 4:28 pm
gt3mike offline
Posts: 225
Joined: Dec 31, 2017
Location: Colorado

A couple of weird behaviors moving to a new iMac

I recently moved a stable Indigo 2021.1 config to a new (to me) late 2015 27" iMac running Monterey12.0.1. Since initially setting it up I've updated Indigo to 2021.2 but I saw this behavior on 2021.1 as well.

What's happening is several of my plugins (Sonos and Harmony most often) seem to intermittently stop responding to commands. Then when I stop and restart the plugins, all of the commands I've tried to execute show up in the log (and execute, I think). Example below.

Dec 25, 2021 at 2:51:32 PM
Action Group Reset Sonos
Reloading plugin "Sonos 1.0.17"
Stopping plugin "Sonos 1.0.17" (pid 15017)
Sonos Error Error getting ZonePlayer data: http://192.168.0.252:1400/status/zp
Sonos Error Offending ZonePlayer: Sonos Move
Sonos Error ZonePlayer may be physically turned off or in a bad state.
Sonos Error Please disable communications or remove from Indigo.

Sonos Adding ZonePlayer: 192.168.0.196, RINCON_5CAAFD0FAED001400, Sonos Office
Sonos SoCo Reactor Ignition...
Sonos ZonePlayer: Sonos Kitchen, Stop
Sonos ZonePlayer: Sonos Office, Stop
Sonos ZonePlayer: Sonos Bathroom Left, Stop
Sonos ZonePlayer: Sonos Bathroom Right, Stop
Sonos ZonePlayer: Sonos Living Room, Stop
Sonos ZonePlayer: Sonos Bedroom, Stop
Sonos ZonePlayer: Sonos Basement, Stop
Sonos ZonePlayer: Sonos Move, Stop
Sonos ZonePlayer: Sonos Kitchen, Stop
Sonos ZonePlayer: Sonos Office, Stop
Sonos ZonePlayer: Sonos Bathroom Left, Stop
Sonos ZonePlayer: Sonos Bathroom Right, Stop
Sonos ZonePlayer: Sonos Living Room, Stop
Sonos ZonePlayer: Sonos Bedroom, Stop
Sonos ZonePlayer: Sonos Basement, Stop
Sonos ZonePlayer: Sonos Move, Stop
Sonos ZonePlayer: Sonos Kitchen, Stop
Sonos ZonePlayer: Sonos Office, Stop
Sonos ZonePlayer: Sonos Bathroom Left, Stop
Sonos ZonePlayer: Sonos Bathroom Right, Stop
Sonos ZonePlayer: Sonos Living Room, Stop
Sonos ZonePlayer: Sonos Bedroom, Stop
Sonos ZonePlayer: Sonos Basement, Stop
Sonos ZonePlayer: Sonos Move, Stop
Sonos ZonePlayer: Sonos Office, Current Volume: , New Volume: 33
Sonos ZonePlayer: Sonos Basement, Current Volume: , New Volume: 47
Sonos ZonePlayer: Sonos Living Room, Current Volume: , New Volume: 49
Sonos ZonePlayer: Sonos Kitchen, Current Volume: , New Volume: 34
Sonos ZonePlayer: Sonos Bedroom, Current Volume: , New Volume: 17
Sonos ZonePlayer: Sonos Bathroom Right, Current Volume: , New Volume: 16
Sonos ZonePlayer: Sonos Move, Current Volume: , New Volume: 50
Sonos ZonePlayer: Sonos Kitchen, Stop
Sonos ZonePlayer: Sonos Office, Stop
Sonos ZonePlayer: Sonos Bathroom Left, Stop
Sonos ZonePlayer: Sonos Bathroom Right, Stop
Sonos ZonePlayer: Sonos Living Room, Stop
Sonos ZonePlayer: Sonos Bedroom, Stop
Sonos ZonePlayer: Sonos Basement, Stop
Sonos ZonePlayer: Sonos Move, Stop

Sonos SoCo Reactor Landing...
Stopped plugin "Sonos 1.0.17"
Starting plugin "Sonos 1.0.17" (pid 20699)
Started plugin "Sonos 1.0.17"
Sonos Error versionCheck: Unable to reach the version server.
Sonos Loaded Sonos Favorites... [7]
Sonos Loaded Playlists... [2]
Sonos Loaded RadioTime Favorite Stations... [5]
Sonos Error [Sat Dec 25 14:51:45 2021] Could not retrieve Event Check Interval; setting to 60 seconds.
Sonos Error [Sat Dec 25 14:51:45 2021] Could not retrieve Subscription Check Interval; setting to 15 seconds.
Sonos Error [Sat Dec 25 14:51:45 2021] Could not retrieve SiriusXM parameters.
Sonos [Sat Dec 25 14:51:45 2021] Processed plugin preferences.
Sonos Serving HTTP Streamer on 192.168.0.169 [0.0.0.0], port 8888
Sonos Loaded Apple Voices.. [48]
Sonos SSDP Listener Started...
Sonos Adding ZonePlayer: 192.168.0.28, RINCON_5CAAFDF12E1E01400, Sonos Basement
Sonos Adding ZonePlayer: 192.168.0.194, RINCON_949F3E76EF0601400, Sonos Bathroom Left
Sonos Adding ZonePlayer: 192.168.0.195, RINCON_949F3E786CE001400, Sonos Bathroom Right
Sonos Adding ZonePlayer: 192.168.0.213, RINCON_347E5C0B1D0E01400, Sonos Bedroom
Sonos Adding ZonePlayer: 192.168.0.191, RINCON_5CAAFD0E955201400, Sonos Kitchen
Sonos Adding ZonePlayer: 192.168.0.240, RINCON_48A6B821374401400, Sonos Living Room
Sonos Adding ZonePlayer: 192.168.0.252, RINCON_48A6B8E0CF2001400, Sonos Move
Sonos Adding ZonePlayer: 192.168.0.196, RINCON_5CAAFD0FAED001400, Sonos Office
Sonos SoCo Reactor Ignition...


The log entries highlighted in red above were queued from the previous day. They spewed out all at once when I reset the plugin. (The errors in blue are related to a Sonos speaker that lost power and I believe are unrelated to this issue.)

Does this look like a known scenario?

Thanks,
Mike
Last edited by gt3mike on Sun Dec 26, 2021 6:06 pm, edited 1 time in total.

Posted on
Sun Dec 26, 2021 4:45 pm
gt3mike offline
Posts: 225
Joined: Dec 31, 2017
Location: Colorado

Re: Plugins intermittently hang and queue commands until res

Also this is appearing intermittently in the Indigo log viewer -- let's say once every day or two.

Dec 26, 2021 at 2:51:02 PM
Lutron RRA2/Caséta Received: Motion Sensor Master Closet - Motion Sensor 62 vacancy detected

Dec 26, 2021 at 2:54:15 PM
Lutron RRA2/Caséta Received: Motion Sensor Laundry - Motion Sensor 60 vacancy detected

Dec 26, 2021 at 2:59:01 PM
Indigo Cocoa client disconnected (192.168.0.13)
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

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 677
AD2USB Alarm Interface Error panelMsgRead: Error: ServerCommunicationError -- timeout waiting for response

Sonos SoCo Reactor Ignition...

Dec 26, 2021 at 3:07:21 PM
Trigger Office Fan Reset
Z-Wave sent "Office Fan Switch" off
Lutron RRA2/Caséta Received: Motion Sensor Laundry - Motion Sensor 60 motion detected
Schedule Office Fan Reset (delayed action)
Z-Wave sent "Office Fan Switch" on

Dec 26, 2021 at 3:09:57 PM
Indigo Cocoa client authenticated (192.168.0.13)


Seems like some kind of networking problem on the new iMac. But I have a ping running every ten seconds in a terminal window, and I don't see any timeouts there. Odd.

Lastly, I see a TON of these in launchd.log. I don't know whether it's related or anything to be concerned about:

2021-12-26 14:59:01.394699 (user/501) <Warning>: failed lookup: name = com.apple.TrustEvaluationAgent, flags = 0x9, requestor = IndigoPluginHos[666], error = 1: Operation not permitted
2021-12-26 14:59:01.394713 (gui/501 [100003]) <Warning>: failed lookup: name = com.apple.TrustEvaluationAgent, flags = 0x9, requestor = IndigoPluginHos[666], error = 1: Operation not permitted
2021-12-26 14:59:01.394928 (user/501/com.apple.TrustEvaluationAgent) <Notice>: internal event: WILL_SPAWN, code = 0
2021-12-26 14:59:01.394945 (user/501/com.apple.TrustEvaluationAgent) <Notice>: service state: spawn scheduled
2021-12-26 14:59:01.394961 (user/501/com.apple.TrustEvaluationAgent) <Notice>: service state: spawning
2021-12-26 14:59:01.394971 (user/501/com.apple.TrustEvaluationAgent) <Notice>: launching: ipc (mach)
2021-12-26 14:59:01.396023 (user/501/com.apple.TrustEvaluationAgent [27276]) <Notice>: xpcproxy spawned with pid 27276
2021-12-26 14:59:01.396051 (user/501/com.apple.TrustEvaluationAgent [27276]) <Notice>: internal event: SPAWNED, code = 0
2021-12-26 14:59:01.396053 (user/501/com.apple.TrustEvaluationAgent [27276]) <Notice>: service state: xpcproxy
2021-12-26 14:59:01.396074 (user/501/com.apple.TrustEvaluationAgent [27276]) <Notice>: internal event: SOURCE_ATTACH, code = 0
2021-12-26 14:59:01.402385 (user/501/com.apple.TrustEvaluationAgent [27276]) <Notice>: service state: running
2021-12-26 14:59:01.402395 (user/501/com.apple.TrustEvaluationAgent [27276]) <Notice>: internal event: INIT, code = 0
2021-12-26 14:59:01.402399 (user/501/com.apple.TrustEvaluationAgent [27276]) <Notice>: Successfully spawned trustevaluationagent[27276] because ipc (mach)
2021-12-26 14:59:01.462221 (user/501) <Warning>: failed lookup: name = com.apple.TrustEvaluationAgent, flags = 0x9, requestor = IndigoPluginHos[666], error = 1: Operation not permitted
2021-12-26 14:59:01.462238 (gui/501 [100003]) <Warning>: failed lookup: name = com.apple.TrustEvaluationAgent, flags = 0x9, requestor = IndigoPluginHos[666], error = 1: Operation not permitted
2021-12-26 14:59:02.573648 (user/501) <Warning>: failed lookup: name = com.apple.TrustEvaluationAgent, flags = 0x9, requestor = IndigoPluginHos[666], error = 1: Operation not permitted
2021-12-26 14:59:02.573662 (gui/501 [100003]) <Warning>: failed lookup: name = com.apple.TrustEvaluationAgent, flags = 0x9, requestor = IndigoPluginHos[666], error = 1: Operation not permitted
2021-12-26 14:59:02.650942 (user/501) <Warning>: failed lookup: name = com.apple.TrustEvaluationAgent, flags = 0x9, requestor = IndigoPluginHos[666], error = 1: Operation not permitted
2021-12-26 14:59:02.650955 (gui/501 [100003]) <Warning>: failed lookup: name = com.apple.TrustEvaluationAgent, flags = 0x9, requestor = IndigoPluginHos[666], error = 1: Operation not permitted
2021-12-26 14:59:02.739661 (user/501) <Warning>: failed lookup: name = com.apple.TrustEvaluationAgent, flags = 0x9, requestor = IndigoPluginHos[666], error = 1: Operation not permitted
2021-12-26 14:59:02.739676 (gui/501 [100003]) <Warning>: failed lookup: name = com.apple.TrustEvaluationAgent, flags = 0x9, requestor = IndigoPluginHos[666], error = 1: Operation not permitted
2021-12-26 14:59:18.689869 (user/501) <Warning>: failed lookup: name = com.apple.TrustEvaluationAgent, flags = 0x9, requestor = IndigoPluginHos[666], error = 1: Operation not permitted
2021-12-26 14:59:18.689894 (gui/501 [100003]) <Warning>: failed lookup: name = com.apple.TrustEvaluationAgent, flags = 0x9, requestor = IndigoPluginHos[666], error = 1: Operation not permitted
2021-12-26 14:59:18.727272 (user/501) <Warning>: failed lookup: name = com.apple.TrustEvaluationAgent, flags = 0x9, requestor = IndigoPluginHos[666], error = 1: Operation not permitted
2021-12-26 14:59:18.727287 (gui/501 [100003]) <Warning>: failed lookup: name = com.apple.TrustEvaluationAgent, flags = 0x9, requestor = IndigoPluginHos[666], error = 1: Operation not permitted
2021-12-26 14:59:19.838855 (user/501) <Warning>: failed lookup: name = com.apple.TrustEvaluationAgent, flags = 0x9, requestor = IndigoPluginHos[666], error = 1: Operation not permitted
2021-12-26 14:59:19.838871 (gui/501 [100003]) <Warning>: failed lookup: name = com.apple.TrustEvaluationAgent, flags = 0x9, requestor = IndigoPluginHos[666], error = 1: Operation not permitted
2021-12-26 14:59:19.910419 (user/501) <Warning>: failed lookup: name = com.apple.TrustEvaluationAgent, flags = 0x9, requestor = IndigoPluginHos[666], error = 1: Operation not permitted
2021-12-26 14:59:19.910433 (gui/501 [100003]) <Warning>: failed lookup: name = com.apple.TrustEvaluationAgent, flags = 0x9, requestor = IndigoPluginHos[666], error = 1: Operation not permitted
2021-12-26 14:59:19.991439 (user/501) <Warning>: failed lookup: name = com.apple.TrustEvaluationAgent, flags = 0x9, requestor = IndigoPluginHos[666], error = 1: Operation not permitted
2021-12-26 14:59:19.991453 (gui/501 [100003]) <Warning>: failed lookup: name = com.apple.TrustEvaluationAgent, flags = 0x9, requestor = IndigoPluginHos[666], error = 1: Operation not permitted
2021-12-26 14:59:41.485013 (user/501/com.apple.TrustEvaluationAgent [27276]) <Notice>: service exited: dirty = 0, supported pressured-exit = 0
2021-12-26 14:59:41.485034 (user/501/com.apple.TrustEvaluationAgent [27276]) <Notice>: exited due to exit(0)
2021-12-26 14:59:41.485037 (user/501/com.apple.TrustEvaluationAgent [27276]) <Notice>: service state: exited
2021-12-26 14:59:41.485039 (user/501/com.apple.TrustEvaluationAgent [27276]) <Notice>: internal event: EXITED, code = 0
2021-12-26 14:59:41.485042 (user/501) <Notice>: service inactive: com.apple.TrustEvaluationAgent



Any suggestions are welcome.

Mike

Posted on
Sun Dec 26, 2021 5:51 pm
gt3mike offline
Posts: 225
Joined: Dec 31, 2017
Location: Colorado

Re: Plugins intermittently hang and queue commands until res

Looks like this is happening more often than I thought.

Dec 26, 2021 at 4:30:17 PM
miniUniFi Error Error in plugin execution runConcurrentThread:

Traceback (most recent call last):
File "plugin.py", line 122, in runConcurrentThread
File "plugin.py", line 434, in updateUniFiDevice
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...
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)
AD2USB Alarm Interface Error Error on line 749
AD2USB Alarm Interface Error panelMsgRead: Error: ServerCommunicationError -- timeout waiting for response

Posted on
Mon Dec 27, 2021 12:28 pm
jay (support) offline
Site Admin
User avatar
Posts: 18199
Joined: Mar 19, 2008
Location: Austin, Texas

Re: A couple of weird behaviors moving to a new iMac

It looks like one plugin is causing some issues by not replying to the Indigo Server. Try disabling 3rd party plugins to see if you can find the one that's hanging things up. I'd start with the miniUniFi plugin since it's the first error in both of your log snippets (though that might just be a symptom of another one hanging first).

Jay (Indigo Support)
Twitter | Facebook | LinkedIn

Posted on
Mon Dec 27, 2021 1:05 pm
gt3mike offline
Posts: 225
Joined: Dec 31, 2017
Location: Colorado

Re: A couple of weird behaviors moving to a new iMac

Will do. Are the 'com.apple.TrustEvaluationAgent' failures a concern?
Thanks,
Mike

Posted on
Mon Dec 27, 2021 3:04 pm
jay (support) offline
Site Admin
User avatar
Posts: 18199
Joined: Mar 19, 2008
Location: Austin, Texas

Re: A couple of weird behaviors moving to a new iMac

Not concerning about any malicious intent - but it may be contributing to the issues - hopefully we can identify the plugin that's causing the issue and then we can figure out if macOS is having an issue with the plugin itself for some reason.

Jay (Indigo Support)
Twitter | Facebook | LinkedIn

Posted on
Tue Dec 28, 2021 3:28 pm
gt3mike offline
Posts: 225
Joined: Dec 31, 2017
Location: Colorado

Re: A couple of weird behaviors moving to a new iMac

Quick update: I disabled all but the critical plugins (Lutron and AD2USB), and the system has been stable for over 24 hours. I still see the 'com.apple.TrustEvaluationAgent' failure messages in the launchd log, and eventually I'll try to identify a cause for them. But first I want to see if I have a stable system with my key plugins running.
Mike

Posted on
Thu Jan 27, 2022 8:22 am
gt3mike offline
Posts: 225
Joined: Dec 31, 2017
Location: Colorado

Re: A couple of weird behaviors moving to a new iMac

Another update: I've been slowly introducing plugins as suggested. After several weeks of Lutron, AD2USB, and Sonos running perfectly stable, I introduced miniUnifi and these errors started again. This is the latest.

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)


I'll chase this down with @FlyingDiver.

Page 1 of 1

Who is online

Users browsing this forum: No registered users and 8 guests