Indigo Domotics

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?

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

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?

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

Don't see the attachment.

Albatros wrote:

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

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?

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?

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?

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?

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


So in the debug I have send is nothing wrong?

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....

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.

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....

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.