AVRemote crash

[beninsteon]

Hi @firstone

Had an AVRemote chromecast crash today. Plugin stopped polling at 17:27:41 Here's the log. There's nothing in the main polyglot log. Had to restart AVRemote for polling to be restarted.

Also, would be nice to have a "Query" action for chromecast devices in programs. Thanks!

2019-05-31 17:27:12,867 [Thread-65 ] [ERROR] ERROR:pychromecast.socket_client:[192.168.0.12:8009] Failed to connect: timed out. aborting due to stop signal.
2019-05-31 17:27:12,869 [Controller] [INFO ] Updating Driver d_10 - ST: 0, uom: 2
2019-05-31 17:27:12,871 [Controller] [DEBUG] Refreshing state for Living room speakers
2019-05-31 17:27:12,873 [Controller] [DEBUG] Refreshing state for Bedroom speakers
2019-05-31 17:27:12,875 [Controller] [DEBUG] Refreshing state for First floor speakers
2019-05-31 17:27:12,878 [Controller] [DEBUG] Refreshing state for Basement speakers
2019-05-31 17:27:12,880 [Controller] [DEBUG] Refreshing state for Ceiling speakers
2019-05-31 17:27:12,882 [Controller] [DEBUG] Host not found for Family Room TV. Discovering
2019-05-31 17:27:22,051 [Controller] [DEBUG] Refreshing state for Living room speakers
2019-05-31 17:27:22,053 [Controller] [DEBUG] Refreshing state for Bedroom speakers
2019-05-31 17:27:22,055 [Controller] [DEBUG] Refreshing state for First floor speakers
2019-05-31 17:27:22,057 [Controller] [DEBUG] Refreshing state for Basement speakers
2019-05-31 17:27:22,058 [Controller] [DEBUG] Refreshing state for Ceiling speakers
2019-05-31 17:27:22,060 [Controller] [DEBUG] Host not found for Family Room TV. Discovering
2019-05-31 17:27:31,376 [Controller] [DEBUG] Host found
2019-05-31 17:27:41,706 [Thread-66 ] [ERROR] ERROR:pychromecast.socket_client:[192.168.0.12:8009] Failed to connect, retrying in 5.0s
2019-05-31 17:27:41,708 [Thread-66 ] [ERROR] ERROR:pychromecast.socket_client:[192.168.0.12:8009] Failed to connect. No retries.
2019-05-31 17:49:16,812 [Interface ] [DEBUG] Received stop from Polyglot... Shutting Down.
2019-05-31 17:49:16,814 [Interface ] [INFO ] Disconnecting from MQTT... localhost:1883
2019-05-31 17:49:16,817 [Interface ] [INFO ] MQTT Graceful disconnection.
2019-05-31 17:49:21,204 [MainThread] [INFO ] Received Config from STDIN.
2019-05-31 17:49:21,205 [MainThread] [INFO ] UDI Polyglot v2 Interface 2.0.29 Starting...

 

[beninsteon]

Hi @firstone

Same thing again today at 10:55:16. Restart of AVRemote needed.

2019-06-01 10:54:18,258 [Controller] [DEBUG] Refreshing state for Ceiling speakers
2019-06-01 10:54:18,260 [Controller] [DEBUG] Refreshing state for Family Room TV
2019-06-01 10:54:18,261 [Controller] [DEBUG] Refreshing state for Basement speakers
2019-06-01 10:54:18,263 [Controller] [DEBUG] Refreshing state for Bedroom speakers
2019-06-01 10:54:18,264 [Controller] [DEBUG] Refreshing state for First floor speakers
2019-06-01 10:54:18,265 [Controller] [DEBUG] Refreshing state for Living room speakers
2019-06-01 10:54:23,688 [Thread-16 ] [ERROR] WARNING:pychromecast.socket_client:[192.168.0.12:8009] Heartbeat timeout, resetting connection
2019-06-01 10:54:25,258 [Controller] [DEBUG] Refreshing state for Ceiling speakers
2019-06-01 10:54:53,711 [Thread-16 ] [ERROR] ERROR:pychromecast.socket_client:[192.168.0.12:8009] Failed to connect: timed out. aborting due to stop signal.
2019-06-01 10:54:53,714 [Controller] [INFO ] Updating Driver d_10 - ST: 0, uom: 2
2019-06-01 10:54:53,718 [Controller] [DEBUG] Refreshing state for Basement speakers
2019-06-01 10:54:53,720 [Controller] [DEBUG] Refreshing state for Bedroom speakers
2019-06-01 10:54:53,722 [Controller] [DEBUG] Refreshing state for First floor speakers
2019-06-01 10:54:53,724 [Controller] [INFO ] Updating Driver d_4 - SVOL: 25.0, uom: 51
2019-06-01 10:54:53,728 [Controller] [DEBUG] Refreshing state for Living room speakers
2019-06-01 10:54:53,730 [Controller] [DEBUG] Refreshing state for Ceiling speakers
2019-06-01 10:54:53,734 [Controller] [DEBUG] Host not found for Family Room TV. Discovering
2019-06-01 10:55:03,681 [Controller] [DEBUG] Host found
2019-06-01 10:55:16,826 [Thread-21 ] [ERROR] ERROR:pychromecast.socket_client:[192.168.0.12:8009] Failed to connect, retrying in 5.0s
2019-06-01 10:55:16,828 [Thread-21 ] [ERROR] ERROR:pychromecast.socket_client:[192.168.0.12:8009] Failed to connect. No retries.
2019-06-01 11:23:21,668 [Interface ] [DEBUG] Received stop from Polyglot... Shutting Down.
2019-06-01 11:23:21,669 [Interface ] [INFO ] Disconnecting from MQTT... localhost:1883
2019-06-01 11:23:21,672 [Interface ] [INFO ] MQTT Graceful disconnection.
2019-06-01 11:23:25,609 [MainThread] [INFO ] Received Config from STDIN.
2019-06-01 11:23:25,610 [MainThread] [INFO ] UDI Polyglot v2 Interface 2.0.29 Starting...
2019-06-01 11:23:25,904 [MainThread] [WARNING] ./polyrestremote.py:13: YAMLLoadWarning: calling yaml.load() without Loader=... is deprecated, as the default Loader is unsafe. Please read https://msg.pyyaml.org/load for full details.
2019-06-01 11:23:27,839 [Interface ] [INFO ] Connecting to MQTT... localhost:1883
2019-06-01 11:23:27,902 [Interface ] [INFO ] MQTT Connected with result code 0 (Success)
2019-06-01 11:23:27,903 [Interface ] [INFO ] MQTT Subscribing to topic: udi/polyglot/ns/4 -  MID: 1 Result: 0
2019-06-01 11:23:27,904 [Interface ] [INFO ] MQTT Subscribing to topic: udi/polyglot/connections/polyglot -  MID: 2 Result: 0
2019-06-01 11:23:27,905 [Interface ] [INFO ] Sent Connected message to Polyglot
2019-06-01 11:23:28,057 [NodeServer] [INFO ] Sending typed parameters to Polyglot.
2019-06-01 11:23:28,865 [MainThread] [ERROR] Traceback (most recent call last):
2019-06-01 11:23:28,871 [MainThread] [ERROR] File "./polyrestremote.py", line 27, in <module>
2019-06-01 11:23:28,898 [MainThread] [ERROR] PolyRemote()
2019-06-01 11:23:28,904 [MainThread] [ERROR] File "/home/pi/.local/lib/python3.5/site-packages/click/core.py", line 764, in __call__
2019-06-01 11:23:28,948 [MainThread] [ERROR] return self.main(*args, **kwargs)
2019-06-01 11:23:28,954 [MainThread] [ERROR] File "/home/pi/.local/lib/python3.5/site-packages/click/core.py", line 717, in main
2019-06-01 11:23:29,003 [MainThread] [ERROR] rv = self.invoke(ctx)
2019-06-01 11:23:29,009 [MainThread] [ERROR] File "/home/pi/.local/lib/python3.5/site-packages/click/core.py", line 956, in invoke
2019-06-01 11:23:29,053 [MainThread] [ERROR] return ctx.invoke(self.callback, **ctx.params)
2019-06-01 11:23:29,059 [MainThread] [ERROR] File "/home/pi/.local/lib/python3.5/site-packages/click/core.py", line 555, in invoke
2019-06-01 11:23:29,102 [MainThread] [ERROR] return callback(*args, **kwargs)
2019-06-01 11:23:29,108 [MainThread] [ERROR] File "./polyrestremote.py", line 20, in PolyRemote
2019-06-01 11:23:29,129 [MainThread] [ERROR] controller = RemoteController(polyglot, configData, config is not None)
2019-06-01 11:23:29,135 [MainThread] [ERROR] File "/home/pi/.polyglot/nodeservers/AVRemote/poly/remotecontroller.py", line 24, in __init__
2019-06-01 11:23:29,162 [MainThread] [ERROR] self.createDevices()
2019-06-01 11:23:29,168 [MainThread] [ERROR] File "/home/pi/.polyglot/nodeservers/AVRemote/poly/remotecontroller.py", line 214, in createDevices
2019-06-01 11:23:29,195 [MainThread] [ERROR] deviceDriver = self.deviceDriverInstances[driverName].get(
2019-06-01 11:23:29,201 [MainThread] [ERROR] KeyError
2019-06-01 11:23:29,207 [MainThread] [ERROR] :
2019-06-01 11:23:29,213 [MainThread] [ERROR] 'chromecast'
2019-06-01 11:23:31,409 [Interface ] [DEBUG] Regenerating profile
2019-06-01 11:23:31,469 [Interface ] [DEBUG] Profile has changed. Updating
2019-06-01 11:23:31,470 [Interface ] [INFO ] Sending Install Profile command to Polyglot.
2019-06-01 11:23:31,477 [Interface ] [INFO ] Loaded Chromecast driver
2019-06-01 11:23:31,481 [Interface ] [INFO ] Adding node First floor speakers(d_4)