Sonos - Not Updating Status

Posted on
Wed Nov 09, 2016 2:45 am
Albatros offline
Posts: 132
Joined: Feb 07, 2015

Re: Sonos - Not Updating Status

First of all thank you for all your efforts.

Unfortunately the new version didn't solve my problem. Back again to not getting status in Indigo. Also restarting the plug-in, indigo or the mac mini didn't resolve the problem. As it sometimes did before. Funny, the players who before after a restart provided status updates are now not showing anything. But the one which din't before provide status after restart is now providing status updates. weird..

In the log file I can see this:

2016-11-09 00:00:57.345 Sonos Debug [Wed Nov 9 00:00:57 2016] [Sonos Jesper] [SoCo AVTransport] {u'current_crossfade_mode': '0', u'next_track_uri': '', u'current_section': '0', u'enqueued_transport_uri': 'x-sonosapi-stream:s6712?sid=254&flags=8224&sn=0', u'current_track_duration': '0:00:00', u'current_track_meta_data': <DidlItem 'x-sonosapi-stream:s6712?sid=254&flags=82' at 0x73cde50>, u'transport_state': 'STOPPED', u'current_play_mode': 'NORMAL', u'number_of_tracks': '1', u'current_track': '1', u'enqueued_transport_uri_meta_data': <DidlAudioBroadcast 'Radio 538' at 0x73cd030>, u'current_track_uri': 'x-rincon-mp3radio://vip-icecast.538.lw.triple-it.nl/RADIO538_MP3', u'next_track_meta_data': ''}
2016-11-09 00:00:57.346 Sonos Debug zoneIP: 192.168.1.XX, soapRoot: /ZonePlayer, soapBranch: /ZoneGroupTopology, soapAction: GetZoneGroupAttributes
2016-11-09 00:00:57.354 Sonos Debug <?xml version="1.0" encoding="utf-8"?><s:Envelope s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/" xmlns:s="http://schemas.xmlsoap.org/soap/envelope/"><s:Body><ns0:GetZoneGroupAttributes xmlns:ns0="urn:schemas-upnp-org:service:ZoneGroupTopology:1"><InstanceID>0</InstanceID></ns0:GetZoneGroupAttributes></s:Body></s:Envelope>
2016-11-09 00:00:57.355 Sonos Debug <s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><s:Body><u:GetZoneGroupAttributesResponse xmlns:u="urn:schemas-upnp-org:service:ZoneGroupTopology:1"><CurrentZoneGroupName>Slaapkamer Jesp</CurrentZoneGroupName><CurrentZoneGroupID>RINCON_B8E93728C70801400:8</CurrentZoneGroupID><CurrentZonePlayerUUIDsInGroup>RINCON_B8E93728C70801400</CurrentZonePlayerUUIDsInGroup></u:GetZoneGroupAttributesResponse></s:Body></s:Envelope>
2016-11-09 00:00:57.356 Sonos Debug Updating Device: Sonos Jesper, State: ZoneGroupName, Value: Slaapkamer Jesp
2016-11-09 00:00:57.357 Sonos Debug Updating Device: Sonos Jesper, State: ZoneGroupID, Value: RINCON_B8E93728C70801400:8
2016-11-09 00:00:57.357 Sonos Debug Updating Device: Sonos Jesper, State: ZonePlayerUUIDsInGroup, Value: RINCON_B8E93728C70801400
2016-11-09 00:00:57.374 Sonos Debug Updating Device: Sonos Jesper, State: GROUP_Coordinator, Value: true
2016-11-09 00:00:57.375 Sonos Debug Updating Device: Sonos Jesper, State: bootseq, Value: 80
2016-11-09 00:00:57.376 Sonos Debug Updating Device: Sonos Jesper, State: ZP_STATE, Value: STOPPED
2016-11-09 00:00:57.377 Sonos Debug Updating Device: Sonos Jesper, State: Q_Repeat, Value: off
2016-11-09 00:00:57.378 Sonos Debug Updating Device: Sonos Jesper, State: Q_Shuffle, Value: off
2016-11-09 00:00:57.379 Sonos Debug Updating Device: Sonos Jesper, State: Q_Crossfade, Value: off
2016-11-09 00:00:57.379 Sonos Debug Updating Device: Sonos Jesper, State: ZP_CurrentTrack, Value: 1
2016-11-09 00:00:57.380 Sonos Debug Updating Device: Sonos Jesper, State: ZP_CurrentTrackURI, Value: x-rincon-mp3radio://vip-icecast.538.lw.triple-it.nl/RADIO538_MP3
2016-11-09 00:00:57.381 Sonos Debug Updating Device: Sonos Jesper, State: ZP_DURATION, Value: 0:00:00
2016-11-09 00:00:57.381 Sonos Debug Updating Device: Sonos Jesper, State: ZP_CREATOR, Value:
2016-11-09 00:00:57.382 Sonos Debug Updating Device: Sonos Jesper, State: ZP_ALBUM, Value:
2016-11-09 00:00:57.382 Sonos Debug Updating Device: Sonos Jesper, State: ZP_ARTIST, Value:
2016-11-09 00:00:57.383 Sonos Debug Processing Cover Art: Sonos Jesper:/getaa?s=1&u=x-sonosapi-stream%3as6712%3fsid%3d254%26flags%3d8224%26sn%3d0
2016-11-09 00:00:57.383 Sonos Debug Updating Device: Sonos Jesper, State: ZP_ART, Value: http://d1i6vahw24eb07.cloudfront.net/s6712q.png
2016-11-09 00:00:57.389 Sonos Debug Updating Device: Sonos Jesper, State: ZP_INFO, Value:
2016-11-09 00:00:57.390 Sonos Debug Updating Device: Sonos Jesper, State: ZP_STATION, Value: Radio 538
2016-11-09 00:00:57.391 Sonos Debug Updating Device: Sonos Jesper, State: ZP_NTRACK, Value:
2016-11-09 00:00:57.391 Sonos Debug Updating Device: Sonos Jesper, State: ZP_NCREATOR, Value:
2016-11-09 00:00:57.392 Sonos Debug Updating Device: Sonos Jesper, State: ZP_NALBUM, Value:
2016-11-09 00:00:57.393 Sonos Debug Updating Device: Sonos Jesper, State: ZP_NARTIST, Value:
2016-11-09 00:00:57.393 Sonos Debug Updating Device: Sonos Jesper, State: ZP_NART, Value:

The above sonsos player. Just now provides updates. Before it didn't.

These players are right now not giving status in Indigo:

2016-11-09 00:03:03.998 Sonos Debug Updating Device: Sonos Nick, State: alive, Value: Wed Nov 9 00:03:03 2016
2016-11-09 00:03:04.000 Sonos Debug [Wed Nov 9 00:03:03 2016] Received ALIVE message from ZonePlayer: Sonos Nick
2016-11-09 00:03:04.005 Sonos Debug Updating Device: Sonos Nick, State: alive, Value: Wed Nov 9 00:03:04 2016
2016-11-09 00:03:04.007 Sonos Debug [Wed Nov 9 00:03:04 2016] Received ALIVE message from ZonePlayer: Sonos Nick
2016-11-09 00:03:04.116 Sonos Debug Updating Device: Sonos Woonkamer, State: alive, Value: Wed Nov 9 00:03:04 2016
2016-11-09 00:03:04.118 Sonos Debug [Wed Nov 9 00:03:04 2016] Received ALIVE message from ZonePlayer: Sonos Woonkamer
2016-11-09 00:03:04.125 Sonos Debug Updating Device: Sonos Woonkamer, State: alive, Value: Wed Nov 9 00:03:04 2016
2016-11-09 00:03:04.126 Sonos Debug [Wed Nov 9 00:03:04 2016] Received ALIVE message from ZonePlayer: Sonos Woonkamer


This looks like the plugin can communicate with the sonos players. Any idea?

Posted on
Wed Nov 09, 2016 6:37 am
george99 offline
Posts: 224
Joined: May 27, 2015

Re: Sonos - Not Updating Status

The problems I had with the. Update haven't happened agai.n to me.

Posted on
Wed Nov 09, 2016 6:38 am
nlagaros offline
Posts: 1646
Joined: Dec 20, 2010

Re: Sonos - Not Updating Status

Can I see every line of the Sonos debug log from the point where the plugin started. That would be a good place to start.

Albatros wrote:
First of all thank you for all your efforts.

Unfortunately the new version didn't solve my problem. Back again to not getting status in Indigo. Also restarting the plug-in, indigo or the mac mini didn't resolve the problem. As it sometimes did before. Funny, the players who before after a restart provided status updates are now not showing anything. But the one which din't before provide status after restart is now providing status updates. weird..

In the log file I can see this:

2016-11-09 00:00:57.345 Sonos Debug [Wed Nov 9 00:00:57 2016] [Sonos Jesper] [SoCo AVTransport] {u'current_crossfade_mode': '0', u'next_track_uri': '', u'current_section': '0', u'enqueued_transport_uri': 'x-sonosapi-stream:s6712?sid=254&flags=8224&sn=0', u'current_track_duration': '0:00:00', u'current_track_meta_data': <DidlItem 'x-sonosapi-stream:s6712?sid=254&flags=82' at 0x73cde50>, u'transport_state': 'STOPPED', u'current_play_mode': 'NORMAL', u'number_of_tracks': '1', u'current_track': '1', u'enqueued_transport_uri_meta_data': <DidlAudioBroadcast 'Radio 538' at 0x73cd030>, u'current_track_uri': 'x-rincon-mp3radio://vip-icecast.538.lw.triple-it.nl/RADIO538_MP3', u'next_track_meta_data': ''}
2016-11-09 00:00:57.346 Sonos Debug zoneIP: 192.168.1.XX, soapRoot: /ZonePlayer, soapBranch: /ZoneGroupTopology, soapAction: GetZoneGroupAttributes
2016-11-09 00:00:57.354 Sonos Debug <?xml version="1.0" encoding="utf-8"?><s:Envelope s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/" xmlns:s="http://schemas.xmlsoap.org/soap/envelope/"><s:Body><ns0:GetZoneGroupAttributes xmlns:ns0="urn:schemas-upnp-org:service:ZoneGroupTopology:1"><InstanceID>0</InstanceID></ns0:GetZoneGroupAttributes></s:Body></s:Envelope>
2016-11-09 00:00:57.355 Sonos Debug <s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><s:Body><u:GetZoneGroupAttributesResponse xmlns:u="urn:schemas-upnp-org:service:ZoneGroupTopology:1"><CurrentZoneGroupName>Slaapkamer Jesp</CurrentZoneGroupName><CurrentZoneGroupID>RINCON_B8E93728C70801400:8</CurrentZoneGroupID><CurrentZonePlayerUUIDsInGroup>RINCON_B8E93728C70801400</CurrentZonePlayerUUIDsInGroup></u:GetZoneGroupAttributesResponse></s:Body></s:Envelope>
2016-11-09 00:00:57.356 Sonos Debug Updating Device: Sonos Jesper, State: ZoneGroupName, Value: Slaapkamer Jesp
2016-11-09 00:00:57.357 Sonos Debug Updating Device: Sonos Jesper, State: ZoneGroupID, Value: RINCON_B8E93728C70801400:8
2016-11-09 00:00:57.357 Sonos Debug Updating Device: Sonos Jesper, State: ZonePlayerUUIDsInGroup, Value: RINCON_B8E93728C70801400
2016-11-09 00:00:57.374 Sonos Debug Updating Device: Sonos Jesper, State: GROUP_Coordinator, Value: true
2016-11-09 00:00:57.375 Sonos Debug Updating Device: Sonos Jesper, State: bootseq, Value: 80
2016-11-09 00:00:57.376 Sonos Debug Updating Device: Sonos Jesper, State: ZP_STATE, Value: STOPPED
2016-11-09 00:00:57.377 Sonos Debug Updating Device: Sonos Jesper, State: Q_Repeat, Value: off
2016-11-09 00:00:57.378 Sonos Debug Updating Device: Sonos Jesper, State: Q_Shuffle, Value: off
2016-11-09 00:00:57.379 Sonos Debug Updating Device: Sonos Jesper, State: Q_Crossfade, Value: off
2016-11-09 00:00:57.379 Sonos Debug Updating Device: Sonos Jesper, State: ZP_CurrentTrack, Value: 1
2016-11-09 00:00:57.380 Sonos Debug Updating Device: Sonos Jesper, State: ZP_CurrentTrackURI, Value: x-rincon-mp3radio://vip-icecast.538.lw.triple-it.nl/RADIO538_MP3
2016-11-09 00:00:57.381 Sonos Debug Updating Device: Sonos Jesper, State: ZP_DURATION, Value: 0:00:00
2016-11-09 00:00:57.381 Sonos Debug Updating Device: Sonos Jesper, State: ZP_CREATOR, Value:
2016-11-09 00:00:57.382 Sonos Debug Updating Device: Sonos Jesper, State: ZP_ALBUM, Value:
2016-11-09 00:00:57.382 Sonos Debug Updating Device: Sonos Jesper, State: ZP_ARTIST, Value:
2016-11-09 00:00:57.383 Sonos Debug Processing Cover Art: Sonos Jesper:/getaa?s=1&u=x-sonosapi-stream%3as6712%3fsid%3d254%26flags%3d8224%26sn%3d0
2016-11-09 00:00:57.383 Sonos Debug Updating Device: Sonos Jesper, State: ZP_ART, Value: http://d1i6vahw24eb07.cloudfront.net/s6712q.png
2016-11-09 00:00:57.389 Sonos Debug Updating Device: Sonos Jesper, State: ZP_INFO, Value:
2016-11-09 00:00:57.390 Sonos Debug Updating Device: Sonos Jesper, State: ZP_STATION, Value: Radio 538
2016-11-09 00:00:57.391 Sonos Debug Updating Device: Sonos Jesper, State: ZP_NTRACK, Value:
2016-11-09 00:00:57.391 Sonos Debug Updating Device: Sonos Jesper, State: ZP_NCREATOR, Value:
2016-11-09 00:00:57.392 Sonos Debug Updating Device: Sonos Jesper, State: ZP_NALBUM, Value:
2016-11-09 00:00:57.393 Sonos Debug Updating Device: Sonos Jesper, State: ZP_NARTIST, Value:
2016-11-09 00:00:57.393 Sonos Debug Updating Device: Sonos Jesper, State: ZP_NART, Value:

The above sonsos player. Just now provides updates. Before it didn't.

These players are right now not giving status in Indigo:

2016-11-09 00:03:03.998 Sonos Debug Updating Device: Sonos Nick, State: alive, Value: Wed Nov 9 00:03:03 2016
2016-11-09 00:03:04.000 Sonos Debug [Wed Nov 9 00:03:03 2016] Received ALIVE message from ZonePlayer: Sonos Nick
2016-11-09 00:03:04.005 Sonos Debug Updating Device: Sonos Nick, State: alive, Value: Wed Nov 9 00:03:04 2016
2016-11-09 00:03:04.007 Sonos Debug [Wed Nov 9 00:03:04 2016] Received ALIVE message from ZonePlayer: Sonos Nick
2016-11-09 00:03:04.116 Sonos Debug Updating Device: Sonos Woonkamer, State: alive, Value: Wed Nov 9 00:03:04 2016
2016-11-09 00:03:04.118 Sonos Debug [Wed Nov 9 00:03:04 2016] Received ALIVE message from ZonePlayer: Sonos Woonkamer
2016-11-09 00:03:04.125 Sonos Debug Updating Device: Sonos Woonkamer, State: alive, Value: Wed Nov 9 00:03:04 2016
2016-11-09 00:03:04.126 Sonos Debug [Wed Nov 9 00:03:04 2016] Received ALIVE message from ZonePlayer: Sonos Woonkamer


This looks like the plugin can communicate with the sonos players. Any idea?

Posted on
Wed Nov 09, 2016 9:56 am
Albatros offline
Posts: 132
Joined: Feb 07, 2015

Re: Sonos - Not Updating Status

Thanks for looking into it. I have attached the debug info form starting the plugin.

Posted on
Wed Nov 09, 2016 10:03 am
nlagaros offline
Posts: 1646
Joined: Dec 20, 2010

Re: Sonos - Not Updating Status

Don't see the attachment.

Albatros wrote:
Thanks for looking into it. I have attached the debug info form starting the plugin.

Posted on
Wed Nov 09, 2016 10:04 am
rapamatic offline
Posts: 276
Joined: Aug 03, 2015
Location: Glencoe, IL

Re: Sonos - Not Updating Status

Just to add another data point, I was having similar issues, even under 0.9.12 and as of yesterday, but today it is showing status for all my players. If the problem comes up again I can post logs, but in the meantime I assume not much I can do to help debug?

Posted on
Wed Nov 09, 2016 10:09 am
Albatros offline
Posts: 132
Joined: Feb 07, 2015

Re: Sonos - Not Updating Status

I can't attache an rtf file and the data of the debug is to large for the forum.

Can I send the RTF in another way of can I limit the data of the debug?

Posted on
Wed Nov 09, 2016 10:11 am
nlagaros offline
Posts: 1646
Joined: Dec 20, 2010

Re: Sonos - Not Updating Status

I pm'ed you my email address.

Albatros wrote:
I can't attache an rtf file and the data of the debug is to large for the forum.

Can I send the RTF in another way of can I limit the data of the debug?

Posted on
Wed Nov 09, 2016 10:12 am
nlagaros offline
Posts: 1646
Joined: Dec 20, 2010

Re: Sonos - Not Updating Status

I don't think there is much else that is wrong with the code - just monitor the log for any errors. Some errors are ok - like disconnects and reconnects for ZonePlayers. That means it is working as expected when something happens on the network.

rapamatic wrote:
Just to add another data point, I was having similar issues, even under 0.9.12 and as of yesterday, but today it is showing status for all my players. If the problem comes up again I can post logs, but in the meantime I assume not much I can do to help debug?

Posted on
Wed Nov 09, 2016 10:17 am
rapamatic offline
Posts: 276
Joined: Aug 03, 2015
Location: Glencoe, IL

Re: Sonos - Not Updating Status

Looking through my logs. Right now all my sonos devices show a state of Stopped. I have code on an all off button that pauses all sonos devices. I'm seeing these errors in the log. Is this normal?

Code: Select all
Nov 9, 2016, 8:12:11 AM
  Trigger                         Back Door All Off
  Action Group                    All Sonos Off
  Sonos Error                     UPNP Error: No Such Object
  Sonos Error                     Offending Command -> zoneIP: 192.168.2.35, soapRoot: /MediaRenderer, soapBranch: /AVTransport, soapAction: Pause
  Sonos                           ZonePlayer: Sonos: Basement, Pause
  Sonos Error                     UPNP Error: No Such Object
  Sonos Error                     Offending Command -> zoneIP: 192.168.2.32, soapRoot: /MediaRenderer, soapBranch: /AVTransport, soapAction: Pause
  Sonos                           ZonePlayer: Sonos: Dining Room, Pause
  Sonos Error                     UPNP Error: No Such Object
  Sonos Error                     Offending Command -> zoneIP: 192.168.2.31, soapRoot: /MediaRenderer, soapBranch: /AVTransport, soapAction: Pause
  Sonos                           ZonePlayer: Sonos: Family Room, Pause
  Sonos Error                     UPNP Error: No Such Object
  Sonos Error                     Offending Command -> zoneIP: 192.168.2.36, soapRoot: /MediaRenderer, soapBranch: /AVTransport, soapAction: Pause
  Sonos                           ZonePlayer: Sonos: Kitchen, Pause
  Sonos Error                     UPNP Error: No Such Object
  Sonos Error                     Offending Command -> zoneIP: 192.168.2.34, soapRoot: /MediaRenderer, soapBranch: /AVTransport, soapAction: Pause
  Sonos                           ZonePlayer: Sonos: Library, Pause
  Sonos Error                     UPNP Error: No Such Object
  Sonos Error                     Offending Command -> zoneIP: 192.168.2.41, soapRoot: /MediaRenderer, soapBranch: /AVTransport, soapAction: Pause
  Sonos                           ZonePlayer: Sonos: Master Bath, Pause
  Sonos Error                     UPNP Error: No Such Object
  Sonos Error                     Offending Command -> zoneIP: 192.168.2.33, soapRoot: /MediaRenderer, soapBranch: /AVTransport, soapAction: Pause
  Sonos                           ZonePlayer: Sonos: Master Bed, Pause
  Sonos Error                     UPNP Error: No Such Object
  Sonos Error                     Offending Command -> zoneIP: 192.168.2.45, soapRoot: /MediaRenderer, soapBranch: /AVTransport, soapAction: Pause
  Sonos                           ZonePlayer: Sonos: Patio, Pause

Posted on
Wed Nov 09, 2016 10:42 am
Albatros offline
Posts: 132
Joined: Feb 07, 2015

Re: Sonos - Not Updating Status

So in the debug I have send is nothing wrong?

Posted on
Thu Nov 10, 2016 11:10 pm
rapamatic offline
Posts: 276
Joined: Aug 03, 2015
Location: Glencoe, IL

Re: Sonos - Not Updating Status

Nick - my states stopped showing up for a number of my sonos devices, as in the screenshots below.

I've attached my indigo log from today - I reset the plugin at 20:47 tonight (around line 1200 in the log)... it looks like the issue may have to do with updating the slave zone status from the master? I notice it is just the grouped zones that aren't updating....

Screen Shot 2016-11-10 at 10.59.03 PM.png
Screen Shot 2016-11-10 at 10.59.03 PM.png (171.69 KiB) Viewed 3413 times

Screen Shot 2016-11-10 at 10.59.46 PM.png
Screen Shot 2016-11-10 at 10.59.46 PM.png (376.08 KiB) Viewed 3413 times
Attachments
2016-11-10 Events.txt
(340.82 KiB) Downloaded 148 times

Posted on
Fri Nov 11, 2016 2:03 am
Albatros offline
Posts: 132
Joined: Feb 07, 2015

Re: Sonos - Not Updating Status

For my setup it doesn't seems to have to do with groups. Or it should have to do that the latest grouping of my sonsos devices have got im some way in memory and the "Slaapkamer Nick" was the last master of a group session. That could well be. So indeed something to do with grouping.
Attachments
Schermafbeelding 2016-11-11 om 09.00.00.png
Schermafbeelding 2016-11-11 om 09.00.00.png (148.99 KiB) Viewed 3395 times

Posted on
Fri Nov 11, 2016 6:45 am
nlagaros offline
Posts: 1646
Joined: Dec 20, 2010

Re: Sonos - Not Updating Status

Thanks for the report. This is peculiar. Could you do this entire test again with all the SoCo Events debugging option turned on.

[quote="rapamatic"]Nick - my states stopped showing up for a number of my sonos devices, as in the screenshots below.

I've attached my indigo log from today - I reset the plugin at 20:47 tonight (around line 1200 in the log)... it looks like the issue may have to do with updating the slave zone status from the master? I notice it is just the grouped zones that aren't updating....

Posted on
Fri Nov 11, 2016 6:47 am
nlagaros offline
Posts: 1646
Joined: Dec 20, 2010

Re: Sonos - Not Updating Status

Your log helped. I do see that one subscription was missing for the Slaapkamer Nick ZonePlayer. I might have to add some additional error handling or logging to figure out why that is the case with that device.


Albatros wrote:
For my setup it doesn't seems to have to do with groups. Or it should have to do that the latest grouping of my sonsos devices have got im some way in memory and the "Slaapkamer Nick" was the last master of a group session. That could well be. So indeed something to do with grouping.

Who is online

Users browsing this forum: No registered users and 1 guest

cron