Speech sent to my Sonos devices keep cutting out

[ViperJD]

Speech sent to my Sonos devices keep cutting out and I get errors in the logs. Anything I need to change?

Jun-17 9:46:38 AM

Sonos

MyTcpListener.DoAccept received Error = Unable to read data from the transport connection: A connection attempt failed because the connected party did not properly respond after a period of time, or established connection failed because connected host has failed to respond.

[ViperJD]

I stopped and restarted the plugin, that did ok, but when I ask it to say something, I get issues. Here is some of the logs:


Jun-20 9:10:39 AM TTS Speak: (HOMETROLLERS EFAULT):Testing Jeanettes Speakers when muted
Jun-20 9:10:24 AM Z-Wave Device: Node 145 Z-Wave General Purpose Set to 3068 ()
Jun-20 9:10:18 AM Device Control Device: Sonos Den Mute to Off (1000)
Jun-20 9:10:18 AM Device Control Device: Virtual Status Hold State 2 to Off (0) by/from: CAPI Control Handler
Jun-20 9:10:18 AM Event Event Trigger "Testing Test Speakers - 1"
Jun-20 9:10:18 AM Event Event Testing Test Speakers - 1 triggered by the event page 'Run' button.
Jun-20 9:05:53 AM Warning File does not exist: C:\Program Files (x86)\HomeSeer HS3\html\images\Sonos\Artwork\CoverRINCON_48A6B817210C01400_ 6799.jpg
Jun-20 9:05:33 AM Sonos Error Error in DoCheckAnnouncementQueue. 100 seconds expired since the announcement started and no end was received.
Jun-20 9:04:02 AM Sonos MyTcpListener.DoAccept received Error = Unable to read data from the transport connection: A connection attempt failed because the connected party did not properly respond after a period of time, or established connection failed because connected host has failed to respond.
Jun-20 9:03:55 AM TTS Speak: (HOMETROLLERS EFAULT):Testing Jeanettes Speakers when muted
Jun-20 9:03:47 AM Sonos Error ERROR in PlayURI for zoneplayer = Master Bedroom with UPNP Error = Read only tag / Transport is locked / Access denied. URI=x-rincon:RINCON_48A6B8D2EC7801400 and isObjectID = False, MetaData=http://192.168.1.3:81/images/Sonos/Announcement.jpgHomeSeer Announcementobject.item.audioItem.musicTrackDirk CorsusSonosControllerDirk Corsus, Error =MyUPnPService.InvokeAction for ServiceID = http://192.168.1.226:1400/xml/AVTransport1.xml while sending Action = SetAVTransportURI for URI = http://192.168.1.226:1400/MediaRende...nsport/Control and Request = 0 x-rincon:RINCON_48A6B8D2EC7801400 <DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><upnp:albumArtURI>http://192.168.1.3:81/images/Sonos/Announcement.jpg</upnp:albumArtURI><dc:title>HomeSeer Announcement</dc:title><upnp:class>object.item.audioItem.musicTrack</upnp:class><dc:creator>Dirk Corsus</dc:creator><upnp:album>SonosController</upnp:album><r:albumArtist>Dirk Corsus</r:albumArtist></item></DIDL-Lite> UPNP Error = faultcode = s:Client, faultstring = UPnPError, detail = 501 with error = The remote server returned an error: (500) Internal Server Error.
Jun-20 9:03:38 AM Device Control Device: Sonos Den Mute to Off (1000)
Jun-20 9:03:38 AM Device Control Device: Virtual Status Hold State 2 to Off (0) by/from: CAPI Control Handler
Jun-20 9:03:38 AM Event Event Trigger "Testing Test Speakers - 1"
Jun-20 9:03:38 AM Event Event Testing Test Speakers - 1 triggered by the event page 'Run' button.
Jun-20 9:03:38 AM Sonos Registered SpeakerProxy
Jun-20 9:03:38 AM Sonos CreateOneSonosController: Created instance of ZonePlayerController for Zoneplayer = Office with index 6
Jun-20 9:03:34 AM Sonos DirectConnect called for Zone Office with device name = uuid:RINCON_48A6B8A2360401400 and Model = S22
Jun-20 9:03:33 AM Starting Plug-In Plugin Sonos started successfully in 7 milliseconds
Jun-20 9:03:33 AM Sonos InitIO Called for Instance = RINCON_48A6B8A2360401400 and running on OS = Win32NT
Jun-20 9:03:33 AM Starting Plug-In Initializing plugin Sonos ...
Jun-20 9:03:33 AM Info Plugin Sonos with instance RINCON_48A6B8A2360401400 has connected. IP:127.0.0.1:58181
Jun-20 9:03:33 AM Sonos CreateOneSonosController: Created instance of ZonePlayerController for Zoneplayer = Master Bedroom_RF with index 5
Jun-20 9:03:26 AM Sonos DirectConnect called for Zone Master Bedroom_RF with device name = uuid:RINCON_48A6B81A503A01400 and Model = S22
Jun-20 9:03:25 AM Starting Plug-In Plugin Sonos started successfully in 26 milliseconds
Jun-20 9:03:25 AM Sonos InitIO Called for Instance = RINCON_48A6B81A503A01400 and running on OS = Win32NT
Jun-20 9:03:25 AM Starting Plug-In Initializing plugin Sonos ...
Jun-20 9:03:25 AM Info Plugin Sonos with instance RINCON_48A6B81A503A01400 has connected. IP:127.0.0.1:58156
Jun-20 9:03:25 AM Sonos CreateOneSonosController: Created instance of ZonePlayerController for Zoneplayer = Family Room with index 4
Jun-20 9:03:24 AM Z-Wave Device: Node 145 Z-Wave General Purpose Set to 3069 ()
Jun-20 9:03:22 AM Sonos DirectConnect called for Zone Family Room with device name = uuid:RINCON_7828CAEA93BE01400 and Model = S18
Jun-20 9:03:21 AM Starting Plug-In Plugin Sonos started successfully in 72 milliseconds
Jun-20 9:03:21 AM Sonos InitIO Called for Instance = RINCON_7828CAEA93BE01400 and running on OS = Win32NT
Jun-20 9:03:21 AM Starting Plug-In Initializing plugin Sonos ...
Jun-20 9:03:21 AM Info Plugin Sonos with instance RINCON_7828CAEA93BE01400 has connected. IP:127.0.0.1:58131
Jun-20 9:03:21 AM Sonos CreateOneSonosController: Created instance of ZonePlayerController for Zoneplayer = Master Bedroom with index 3
Jun-20 9:03:13 AM Sonos DirectConnect called for Zone Master Bedroom with device name = uuid:RINCON_48A6B817210C01400 and Model = S22
Jun-20 9:03:12 AM Starting Plug-In Plugin Sonos started successfully in 7 milliseconds
Jun-20 9:03:12 AM Sonos InitIO Called for Instance = RINCON_48A6B817210C01400 and running on OS = Win32NT
Jun-20 9:03:12 AM Starting Plug-In Initializing plugin Sonos ...
Jun-20 9:03:12 AM Info Plugin Sonos with instance RINCON_48A6B817210C01400 has connected. IP:127.0.0.1:58102
Jun-20 9:03:12 AM Sonos CreateOneSonosController: Created instance of ZonePlayerController for Zoneplayer = Den with index 2
Jun-20 9:03:11 AM Sonos DirectConnect called for Zone Den with device name = uuid:RINCON_48A6B8D2EC7801400 and Model = S18
Jun-20 9:03:10 AM Starting Plug-In Plugin Sonos started successfully in 23 milliseconds
Jun-20 9:03:10 AM Sonos InitIO Called for Instance = RINCON_48A6B8D2EC7801400 and running on OS = Win32NT
Jun-20 9:03:10 AM Starting Plug-In Initializing plugin Sonos ...
Jun-20 9:03:10 AM Info Plugin Sonos with instance RINCON_48A6B8D2EC7801400 has connected. IP:127.0.0.1:58079
Jun-20 9:03:10 AM Sonos CreateOneSonosController: Created instance of ZonePlayerController for Zoneplayer = Living Room with index 1
Jun-20 9:03:05 AM Sonos DirectConnect called for Zone Living Room with device name = uuid:RINCON_48A6B81720A801400 and Model = S22
Jun-20 9:03:04 AM Starting Plug-In Plugin Sonos started successfully in 8 milliseconds
Jun-20 9:03:04 AM Sonos InitIO Called for Instance = RINCON_48A6B81720A801400 and running on OS = Win32NT
Jun-20 9:03:04 AM Starting Plug-In Initializing plugin Sonos ...
Jun-20 9:03:04 AM Info Plugin Sonos with instance RINCON_48A6B81720A801400 has connected. IP:127.0.0.1:58054
Jun-20 9:03:03 AM Sonos MySSDP.StartSSDPDiscovery found 6 Devices. Still 0 Entries awaiting processing .....
Jun-20 9:02:33 AM Sonos MySSDP.StartSSDPDiscovery waiting for an additional 30 seconds while receiving and processing the UPnP Device Info .....
Jun-20 9:02:33 AM Sonos MySSDP.StartSSDPDiscovery found so far 6 Devices. Still 0 Entries awaiting processing .....
Jun-20 9:02:28 AM Sonos MySSDP.StartSSDPDiscovery waiting for an additional 5 seconds while discovering UPnP Devices. Still 44 Entries awaiting processing .....
Jun-20 9:02:19 AM Sonos MySSDP.StartSSDPDiscovery, waiting for 9 seconds while discovering UPnP Devices .....
Jun-20 9:02:19 AM Sonos MySSDP.StartEventListener started listening on interface = 192.168.1.3 and port = 57921
Jun-20 9:02:17 AM Plug-In Finished initializing plug-in Sonos
Jun-20 9:02:17 AM Starting Plug-In Sonos loaded in 1742 milliseconds
Jun-20 9:02:16 AM Starting Plug-In Plugin Sonos started successfully in 547 milliseconds
Jun-20 9:02:16 AM Sonos Sonos Plugin Initialized
Jun-20 9:02:16 AM Sonos InitIO Called for Instance = and running on OS = Win32NT
Jun-20 9:02:16 AM Starting Plug-In Initializing plugin Sonos ...
Jun-20 9:02:16 AM Info Plugin Sonos has connected. IP:127.0.0.1:57919
Jun-20 9:02:05 AM Info Plugin Sonos with instance: has disconnected
Jun-20 9:02:05 AM Info Plugin Sonos with instance: RINCON_48A6B8A2360401400 has disconnected
Jun-20 9:02:05 AM Sonos Disconnect: Disconnected from ZonePlayer - Office with Full = True
Jun-20 9:02:05 AM Sonos ShutdownIO called for Instance = RINCON_48A6B8A2360401400 and isRoot = False
Jun-20 9:02:05 AM Sonos Disconnect: Disconnected from ZonePlayer - Office with Full = True
Jun-20 9:02:05 AM Info Plugin Sonos with instance: RINCON_48A6B81A503A01400 has disconnected
Jun-20 9:02:05 AM Sonos Disconnect: Disconnected from ZonePlayer - Master Bedroom_RF with Full = True
Jun-20 9:02:05 AM Sonos ShutdownIO called for Instance = RINCON_48A6B81A503A01400 and isRoot = False
Jun-20 9:02:05 AM Sonos Disconnect: Disconnected from ZonePlayer - Master Bedroom_RF with Full = True
Jun-20 9:02:05 AM Info Plugin Sonos with instance: RINCON_7828CAEA93BE01400 has disconnected
Jun-20 9:02:05 AM Sonos Disconnect: Disconnected from ZonePlayer - Family Room with Full = True
Jun-20 9:02:05 AM Sonos ShutdownIO called for Instance = RINCON_7828CAEA93BE01400 and isRoot = False
Jun-20 9:02:05 AM Sonos Disconnect: Disconnected from ZonePlayer - Family Room with Full = True
Jun-20 9:02:05 AM Info Plugin Sonos with instance: RINCON_48A6B817210C01400 has disconnected
Jun-20 9:02:05 AM Sonos Disconnect: Disconnected from ZonePlayer - Master Bedroom with Full = True
Jun-20 9:02:05 AM Sonos ShutdownIO called for Instance = RINCON_48A6B817210C01400 and isRoot = False
Jun-20 9:02:05 AM Sonos Disconnect: Disconnected from ZonePlayer - Master Bedroom with Full = True
Jun-20 9:02:05 AM Info Plugin Sonos with instance: RINCON_48A6B8D2EC7801400 has disconnected
Jun-20 9:02:05 AM Sonos Disconnect: Disconnected from ZonePlayer - Den with Full = True
Jun-20 9:02:05 AM Sonos ShutdownIO called for Instance = RINCON_48A6B8D2EC7801400 and isRoot = False
Jun-20 9:02:05 AM Sonos Disconnect: Disconnected from ZonePlayer - Den with Full = True
Jun-20 9:02:05 AM Info Plugin Sonos with instance: RINCON_48A6B81720A801400 has disconnected
Jun-20 9:02:05 AM Sonos Disconnect: Disconnected from ZonePlayer - Living Room with Full = True
Jun-20 9:02:05 AM Sonos ShutdownIO called for Instance = RINCON_48A6B81720A801400 and isRoot = False
Jun-20 9:02:05 AM Sonos Disconnect: Disconnected from ZonePlayer - Living Room with Full = True
Jun-20 9:02:05 AM Sonos MyUPnPDevices.Dispose called
Jun-20 9:02:02 AM Sonos MyUPnPDevices.Dispose called
Jun-20 9:02:00 AM Sonos MyUPnPDevices.Dispose called
Jun-20 9:01:59 AM Sonos MyUPnPDevices.Dispose called
Jun-20 9:01:59 AM Sonos MyUPnPDevices.Dispose called
Jun-20 9:01:59 AM Sonos MyUPnPDevices.Dispose called
Jun-20 9:01:59 AM Sonos MyUPnPDevices.Dispose called
Jun-20 9:01:59 AM Sonos ShutdownIO called for Instance = and isRoot = True
Jun-20 9:01:59 AM Plug-In Shutting down Plug-In: Sonos
Jun-20 9:00:31 AM Sonos MyTcpListener.DoAccept received Error = Unable to read data from the transport connection: A connection attempt failed because the connected party did not properly respond after a period of time, or established connection failed because connected host has failed to respond.
Jun-20 9:00:30 AM Warning File does not exist: C:\Program Files (x86)\HomeSeer HS3\html\images\Sonos\Artwork\CoverRINCON_48A6B8D2EC7801400_ 27535.jpg
Jun-20 9:00:21 AM TTS Speak: (HOMETROLLERSEFAULT):It is 9:00 AM

[dcorsus]

Looks like network problems. Can you post a screenshot of the player table and the linkgroup table. Which players are wired and which ones are wireless? Is the den player further away? Use of a boost?
Have you checked your network. Copy this URL to your browser http://192.168.1.226:1400/support/review and post result

[ViperJD]

All are wireless (could wire them in).

Den (RINCON_48A6B8D2EC7801400)
Living Room (L) (RINCON_7828CAEA93BE01400)
Living Room (R) (RINCON_48A6B81720A801400)
Office (RINCON_48A6B8A2360401400)
Master Bedroom (R) (RINCON_48A6B81A503A01400)
Master Bedroom (L) (RINCON_48A6B817210C01400)
Network Matrix

[dcorsus]

oh oh, I think there is an issue with our home seer database and the PI's .ini file. Did you recently delete players or perhaps do a restore of a back up or moved setups from one system to another. The player table showing player status in red isn't good.
By the way, you are supposed to click in the "network Matrix" link to get the Network Matrix information.

[ViperJD]

I don't think I did anything to the player, but I could have. I did not do a restore lately. What should I do to fix it?

[ViperJD]

Now it is green. I really did not do anything to change it. When I run something that "speaks" to it, I get one the 1st word or two and this in the log:

Jun-21 9:36:09 AM

Sonos Warning

Warning in SaveCurrentTrackInfo for zoneplayer - Family Room Track info is being stored and overwriting previously stored info

[dcorsus]

Not sure what is going on with your setup, the network matrix is supposed to give receive levels and stp settings for each player, it shows nothing.
I did pick the IP address of the DEN player which I suspect might be part of the issue here. Try to pick a different source player for linkgroup AllRooms, make sure you pick a player that is not (slave) paired , I think living room could be good. See if that improves. Suspect network issues, maybe just with Den