Sonos - The Beginning

FYI other commands work, like play, pause, skip


/RAID5
Sent from my iPhone with Tapatalk

The plugin should restart automatically after selecting the debugging boxes. Would you also post all the output from plugin startup. Thanks.

Do I need to restart the plugin after debugging is enabled?


/RAID5
Sent from my iPhone with Tapatalk

This makes sense to me as these actions require no knowledge of the state of the device. Volume Up/Down needs to know what the volume is and add/subtract from it.

FYI other commands work, like play, pause, skip


/RAID5
Sent from my iPhone with Tapatalk

The volume up/down works for a couple of minutes after plugin restart, then is stops working


Sonos Debug Method: stopConcurrentThread
Sonos Debug Stopping Concurrent Thread
Sonos Debug Stopping device: SONOS Connect
Sonos Debug Stopping device: SONOS Connect
Sonos Debug Method: shutdown
Stopped plugin "Sonos 0.9.3"
Starting plugin "Sonos 0.9.3" (pid 7845)
Started plugin "Sonos 0.9.3"
Sonos Your plugin version, v0.9.3, is current.
Sonos Adding ZonePlayer: 192.168.90.220, RINCON_000E58AB528601400, SONOS Connect
Sonos Debug Getting Plugin Configuration Settings
Sonos Debug Reference ZP IP: 192.168.90.220
Sonos Debug Event Processsor: SoCo
Sonos Debug HTTP Streaming IP: auto
Sonos Debug HTTP Streaming Port: 8888
Sonos Debug Sound File Path:
Sonos Debug Pandora: False
Sonos Debug Pandora Email Address:
Sonos Debug Pandora Password: *****
Sonos Debug SiriusXM: False
Sonos Debug IVONA: False
Sonos Debug Microsoft Translate: False
Sonos Debug zoneIP: 192.168.90.220, soapRoot: /MediaServer, soapBranch: /ContentDirectory, soapAction: Browse
Sonos Debug Sonos Favorites: FV:2/0, Dinner Chill, x-rincon-cpcontainer:0006206cspotify%3auser%3aspotify%3aplaylist%3a1oTVoAzl0uw6tcmPxznwSy
Sonos Loaded Sonos Favorites... [1]
Sonos Debug zoneIP: 192.168.90.220, soapRoot: /MediaServer, soapBranch: /ContentDirectory, soapAction: Browse
Sonos Debug ZP: <s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><s:Body><u:BrowseResponse xmlns:u="urn:schemas-upnp-org:service:ContentDirectory:1"><Result><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/"><container id="SQ:0" parentID="SQ:" restricted="true"><dc:title>Indigo_RINCON_000E58AB528601400</dc:title><res protocolInfo="file:*:audio/mpegurl:*">file:///jffs/settings/savedqueues.rsq#0</res><upnp:class>object.container.playlistContainer</upnp:class><upnp:albumArtURI>/getaa?s=1&amp;amp;u=x-sonos-spotify%3aspotify%253atrack%253a0XbC1z6tfhd2167ca1icMk%3fsid%3d9%26flags%3d8224%26sn%3d1</upnp:albumArtURI><upnp:albumArtURI>/getaa?s=1&amp;amp;u=x-sonos-spotify%3aspotify%253atrack%253a5iecOuX1N3SyGr0cO11Rgz%3fsid%3d9%26flags%3d8224%26sn%3d1</upnp:albumArtURI><upnp:albumArtURI>/getaa?s=1&amp;amp;u=x-sonos-spotify%3aspotify%253atrack%253a4K9u33qj92wRSHsuFTMvYv%3fsid%3d9%26flags%3d8224%26sn%3d1</upnp:albumArtURI><upnp:albumArtURI>/getaa?s=1&amp;amp;u=x-sonos-spotify%3aspotify%253atrack%253a03BydMeQaLos0ByLllKEjJ%3fsid%3d9%26flags%3d8224%26sn%3d1</upnp:albumArtURI></container></DIDL-Lite></Result><NumberReturned>1</NumberReturned><TotalMatches>1</TotalMatches><UpdateID>18</UpdateID></u:BrowseResponse></s:Body></s:Envelope>
Sonos Debug Playlist: SQ:0, Indigo_RINCON_000E58AB528601400, file:///jffs/settings/savedqueues.rsq#0
Sonos Loaded Playlists... [1]
Sonos Debug zoneIP: 192.168.90.220, soapRoot: /MediaServer, soapBranch: /ContentDirectory, soapAction: Browse
Sonos Debug ZP: <s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><s:Body><u:BrowseResponse xmlns:u="urn:schemas-upnp-org:service:ContentDirectory:1"><Result><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/"></DIDL-Lite></Result><NumberReturned>0</NumberReturned><TotalMatches>0</TotalMatches><UpdateID>2</UpdateID></u:BrowseResponse></s:Body></s:Envelope>
Sonos Loaded RadioTime Favorite Stations... [0]
Sonos Debug ZonePlayer: SONOS Connect, Enabled: True
Sonos Debug Resetting States for zone: 192.168.90.220
Sonos Debug Updating Device: SONOS Connect, State: ZP_ALBUM, Value:
Sonos Debug Replicate state to slave ZonePlayers...
Sonos Debug Updating Device: SONOS Connect, State: ZP_ART, Value:
Sonos Debug Updating Device: SONOS Connect, State: ZP_ARTIST, Value:
Sonos Debug Replicate state to slave ZonePlayers...
Sonos Debug Updating Device: SONOS Connect, State: ZP_CREATOR, Value:
Sonos Debug Replicate state to slave ZonePlayers...
Sonos Debug Updating Device: SONOS Connect, State: ZP_CurrentURI, Value:
Sonos Debug Updating Device: SONOS Connect, State: ZP_DURATION, Value:
Sonos Debug Replicate state to slave ZonePlayers...
Sonos Debug Updating Device: SONOS Connect, State: ZP_INFO, Value:
Sonos Debug Replicate state to slave ZonePlayers...
Sonos Debug Updating Device: SONOS Connect, State: ZP_MUTE, Value:
Sonos Debug Updating Device: SONOS Connect, State: ZP_STATE, Value:
Sonos Debug Replicate state to slave ZonePlayers...
Sonos Debug Updating Device: SONOS Connect, State: ZP_STATION, Value:
Sonos Debug Replicate state to slave ZonePlayers...
Sonos Debug Updating Device: SONOS Connect, State: ZP_TRACK, Value:
Sonos Debug Replicate state to slave ZonePlayers...
Sonos Debug Updating Device: SONOS Connect, State: ZP_VOLUME, Value:
Sonos Debug Updating Device: SONOS Connect, State: ZP_VOLUME_FIXED, Value:
Sonos Debug Updating Device: SONOS Connect, State: ZP_BASS, Value:
Sonos Debug Updating Device: SONOS Connect, State: ZP_TREBLE, Value:
Sonos Debug Updating Device: SONOS Connect, State: ZP_ZoneName, Value:
Sonos Debug Updating Device: SONOS Connect, State: ZP_LocalUID, Value:
Sonos Debug Updating Device: SONOS Connect, State: ZP_AIName, Value:
Sonos Debug Updating Device: SONOS Connect, State: ZP_AIPath, Value:
Sonos Debug Updating Device: SONOS Connect, State: ZP_NALBUM, Value:
Sonos Debug Replicate state to slave ZonePlayers...
Sonos Debug Updating Device: SONOS Connect, State: ZP_NART, Value:
Sonos Debug Replicate state to slave ZonePlayers...
Sonos Debug Updating Device: SONOS Connect, State: ZP_NARTIST, Value:
Sonos Debug Replicate state to slave ZonePlayers...
Sonos Debug Updating Device: SONOS Connect, State: ZP_NCREATOR, Value:
Sonos Debug Replicate state to slave ZonePlayers...
Sonos Debug Updating Device: SONOS Connect, State: ZP_NTRACK, Value:
Sonos Debug Replicate state to slave ZonePlayers...
Sonos Debug Updating Device: SONOS Connect, State: Q_Crossfade, Value: off
Sonos Debug Updating Device: SONOS Connect, State: Q_Repeat, Value: off
Sonos Debug Updating Device: SONOS Connect, State: Q_Shuffle, Value: off
Sonos Debug Updating Device: SONOS Connect, State: Q_Number, Value: 0
Sonos Debug Updating Device: SONOS Connect, State: Q_ObjectID, Value:
Sonos Debug Updating Device: SONOS Connect, State: GROUP_Coordinator, Value:
Sonos Debug Updating Device: SONOS Connect, State: GROUP_Name, Value:
Sonos Debug Updating Device: SONOS Connect, State: ZP_CurrentTrack, Value:
Sonos Debug Updating Device: SONOS Connect, State: ZP_CurrentTrackURI, Value:
Sonos Debug Updating Device: SONOS Connect, State: ZoneGroupID, Value:
Sonos Debug Updating Device: SONOS Connect, State: ZoneGroupName, Value:
Sonos Debug Updating Device: SONOS Connect, State: ZonePlayerUUIDsInGroup, Value:
Sonos Debug Updating Device: SONOS Connect, State: ZP_ZoneName, Value: Sittingroom
Sonos Debug Updating Device: SONOS Connect, State: ZP_LocalUID, Value: RINCON_000E58AB528601400
Sonos Debug zoneIP: 192.168.90.220, soapRoot: /ZonePlayer, soapBranch: /ZoneGroupTopology, soapAction: GetZoneGroupAttributes
Sonos Debug Updating Device: SONOS Connect, State: ZoneGroupName, Value: Sittingroom + 2
Sonos Debug Updating Device: SONOS Connect, State: ZoneGroupID, Value: RINCON_000E58AB528601400:1
Sonos Debug Updating Device: SONOS Connect, State: ZonePlayerUUIDsInGroup, Value: RINCON_000E58AB528601400,RINCON_000E58702CEE01400,RINCON_B8E93751957E01400
Sonos Debug Updating Device: SONOS Connect, State: GROUP_Coordinator, Value: true
Sonos Adding ZonePlayer: 192.168.90.220, RINCON_000E58AB528601400, SONOS Connect
Sonos Debug zoneIP: 192.168.90.220, soapRoot: /ZonePlayer, soapBranch: /ZoneGroupTopology, soapAction: GetZoneGroupAttributes
Sonos Serving HTTP Streamer on 192.168.90.103 [0.0.0.0], port 8888
Sonos Debug Updating Device: SONOS Connect, State: ZoneGroupName, Value: Sittingroom + 2
Sonos Debug Updating Device: SONOS Connect, State: ZoneGroupID, Value: RINCON_000E58AB528601400:1
Sonos Debug Updating Device: SONOS Connect, State: ZonePlayerUUIDsInGroup, Value: RINCON_000E58AB528601400,RINCON_000E58702CEE01400,RINCON_B8E93751957E01400
Sonos Debug Updating Device: SONOS Connect, State: GROUP_Coordinator, Value: true
Sonos Debug zoneIP: 192.168.90.220, soapRoot: /MediaServer, soapBranch: /ContentDirectory, soapAction: Browse
Sonos Debug Updating Device: SONOS Connect, State: Q_Number, Value: 815
Sonos Debug zoneIP: 192.168.90.220, soapRoot: /MediaRenderer, soapBranch: /Queue, soapAction: SaveAsSonosPlaylist
Sonos Debug Updating Device: SONOS Connect, State: Q_ObjectID, Value: SQ:0
Sonos ZonePlayer: SONOS Connect, Save Queue: Indigo_RINCON_000E58AB528601400
Sonos Debug zoneIP: 192.168.90.220, soapRoot: /MediaServer, soapBranch: /ContentDirectory, soapAction: Browse
Sonos Debug ZP: <s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><s:Body><u:BrowseResponse xmlns:u="urn:schemas-upnp-org:service:ContentDirectory:1"><Result><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/"><container id="SQ:0" parentID="SQ:" restricted="true"><dc:title>Indigo_RINCON_000E58AB528601400</dc:title><res protocolInfo="file:*:audio/mpegurl:*">file:///jffs/settings/savedqueues.rsq#0</res><upnp:class>object.container.playlistContainer</upnp:class><upnp:albumArtURI>/getaa?s=1&amp;amp;u=x-sonos-spotify%3aspotify%253atrack%253a0XbC1z6tfhd2167ca1icMk%3fsid%3d9%26flags%3d8224%26sn%3d1</upnp:albumArtURI><upnp:albumArtURI>/getaa?s=1&amp;amp;u=x-sonos-spotify%3aspotify%253atrack%253a5iecOuX1N3SyGr0cO11Rgz%3fsid%3d9%26flags%3d8224%26sn%3d1</upnp:albumArtURI><upnp:albumArtURI>/getaa?s=1&amp;amp;u=x-sonos-spotify%3aspotify%253atrack%253a4K9u33qj92wRSHsuFTMvYv%3fsid%3d9%26flags%3d8224%26sn%3d1</upnp:albumArtURI><upnp:albumArtURI>/getaa?s=1&amp;amp;u=x-sonos-spotify%3aspotify%253atrack%253a03BydMeQaLos0ByLllKEjJ%3fsid%3d9%26flags%3d8224%26sn%3d1</upnp:albumArtURI></container></DIDL-Lite></Result><NumberReturned>1</NumberReturned><TotalMatches>1</TotalMatches><UpdateID>19</UpdateID></u:BrowseResponse></s:Body></s:Envelope>
Sonos Debug Playlist: SQ:0, Indigo_RINCON_000E58AB528601400, file:///jffs/settings/savedqueues.rsq#0
Sonos Loaded Playlists... [1]
Sonos Debug Updating Device: SONOS Connect, State: GROUP_Coordinator, Value: true
Sonos Debug Updating Device: SONOS Connect, State: GROUP_Name, Value: RINCON_000E58AB528601400:1
Sonos Debug Update all states to slave ZonePlayers...
Sonos Debug Update all states to slave ZonePlayers...
Sonos Debug Updating Device: SONOS Connect, State: ZP_AIName, Value: Audio Component
Sonos LineIn: RINCON_000E58AB528601400, Audio Component:Sittingroom
Sonos Debug zoneIP: 192.168.90.220, soapRoot: /MediaServer, soapBranch: /ContentDirectory, soapAction: Browse
Sonos Debug ZP: <s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><s:Body><u:BrowseResponse xmlns:u="urn:schemas-upnp-org:service:ContentDirectory:1"><Result><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/"><container id="SQ:0" parentID="SQ:" restricted="true"><dc:title>Indigo_RINCON_000E58AB528601400</dc:title><res protocolInfo="file:*:audio/mpegurl:*">file:///jffs/settings/savedqueues.rsq#0</res><upnp:class>object.container.playlistContainer</upnp:class><upnp:albumArtURI>/getaa?s=1&amp;amp;u=x-sonos-spotify%3aspotify%253atrack%253a0XbC1z6tfhd2167ca1icMk%3fsid%3d9%26flags%3d8224%26sn%3d1</upnp:albumArtURI><upnp:albumArtURI>/getaa?s=1&amp;amp;u=x-sonos-spotify%3aspotify%253atrack%253a5iecOuX1N3SyGr0cO11Rgz%3fsid%3d9%26flags%3d8224%26sn%3d1</upnp:albumArtURI><upnp:albumArtURI>/getaa?s=1&amp;amp;u=x-sonos-spotify%3aspotify%253atrack%253a4K9u33qj92wRSHsuFTMvYv%3fsid%3d9%26flags%3d8224%26sn%3d1</upnp:albumArtURI><upnp:albumArtURI>/getaa?s=1&amp;amp;u=x-sonos-spotify%3aspotify%253atrack%253a03BydMeQaLos0ByLllKEjJ%3fsid%3d9%26flags%3d8224%26sn%3d1</upnp:albumArtURI></container></DIDL-Lite></Result><NumberReturned>1</NumberReturned><TotalMatches>1</TotalMatches><UpdateID>19</UpdateID></u:BrowseResponse></s:Body></s:Envelope>
Sonos Debug Playlist: SQ:0, Indigo_RINCON_000E58AB528601400, file:///jffs/settings/savedqueues.rsq#0
Sonos Loaded Playlists... [1]
Sonos Debug Updating Device: SONOS Connect, State: ZP_VOLUME, Value: 78
Sonos Debug Updating Device: SONOS Connect, State: ZP_MUTE, Value: 0
Sonos Debug Updating Device: SONOS Connect, State: ZP_VOLUME_FIXED, Value: 0
Sonos Debug Updating Device: SONOS Connect, State: ZP_BASS, Value: 0
Sonos Debug Updating Device: SONOS Connect, State: ZP_TREBLE, Value: 0
Z-Wave received "Bathroom Motion Sensor" status update is on
Trigger Turn Off Timer Bathroom 5min Motion Is Detected
Trigger Bathroom_Motion_Trigger_On-Variable_False
Trigger Turn On Mirror Lights when Motion Detected Sun
Z-Wave sent "Bathroom - Mirror" on

Mar 6, 2016 5:31:07 PM
Sonos Debug Sonos Action: Volume Up
Sonos Debug zoneIP: 192.168.90.220, soapRoot: /MediaRenderer, soapBranch: /RenderingControl, soapAction: SetVolume
Sonos ZonePlayer: SONOS Connect, Current Volume: 78, New Volume: 80
Sonos Debug Updating Device: SONOS Connect, State: ZP_VOLUME, Value: 80
Sonos Debug Sonos Action: Volume Up
Sonos Debug zoneIP: 192.168.90.220, soapRoot: /MediaRenderer, soapBranch: /RenderingControl, soapAction: SetVolume
Sonos ZonePlayer: SONOS Connect, Current Volume: 80, New Volume: 82
Sonos Debug Updating Device: SONOS Connect, State: ZP_VOLUME, Value: 82
Sonos Debug Sonos Action: Volume Up
Sonos Debug zoneIP: 192.168.90.220, soapRoot: /MediaRenderer, soapBranch: /RenderingControl, soapAction: SetVolume
Sonos ZonePlayer: SONOS Connect, Current Volume: 82, New Volume: 84
Sonos Debug Updating Device: SONOS Connect, State: ZP_VOLUME, Value: 84
Sonos Debug Sonos Action: Volume Up
Sonos Debug zoneIP: 192.168.90.220, soapRoot: /MediaRenderer, soapBranch: /RenderingControl, soapAction: SetVolume
Sonos ZonePlayer: SONOS Connect, Current Volume: 84, New Volume: 86
Sonos Debug Updating Device: SONOS Connect, State: ZP_VOLUME, Value: 86
Sonos Debug Sonos Action: Volume Up
Sonos Debug zoneIP: 192.168.90.220, soapRoot: /MediaRenderer, soapBranch: /RenderingControl, soapAction: SetVolume
Sonos ZonePlayer: SONOS Connect, Current Volume: 86, New Volume: 88
Sonos Debug Updating Device: SONOS Connect, State: ZP_VOLUME, Value: 88
Z-Wave received "Bathroom Motion Sensor" status update is off
Trigger Turn Off Bathroom Lights When No Motion Detected For 5 Minutes
Trigger Bathroom_Motion_Trigger_Off-Variable_True
Sonos Debug Sonos Action: Volume Down
Sonos Debug zoneIP: 192.168.90.220, soapRoot: /MediaRenderer, soapBranch: /RenderingControl, soapAction: SetVolume
Sonos ZonePlayer: SONOS Connect, Current Volume: 88, New Volume: 86
Sonos Debug Updating Device: SONOS Connect, State: ZP_VOLUME, Value: 86
Sonos Debug Sonos Action: Volume Down
Sonos Debug zoneIP: 192.168.90.220, soapRoot: /MediaRenderer, soapBranch: /RenderingControl, soapAction: SetVolume
Sonos ZonePlayer: SONOS Connect, Current Volume: 86, New Volume: 84
Sonos Debug Updating Device: SONOS Connect, State: ZP_VOLUME, Value: 84
Sonos Debug Sonos Action: Volume Down
Sonos Debug zoneIP: 192.168.90.220, soapRoot: /MediaRenderer, soapBranch: /RenderingControl, soapAction: SetVolume
Sonos ZonePlayer: SONOS Connect, Current Volume: 84, New Volume: 82
Sonos Debug Updating Device: SONOS Connect, State: ZP_VOLUME, Value: 82
Sonos Debug Sonos Action: Volume Down
Sonos Debug zoneIP: 192.168.90.220, soapRoot: /MediaRenderer, soapBranch: /RenderingControl, soapAction: SetVolume
Sonos ZonePlayer: SONOS Connect, Current Volume: 82, New Volume: 80
Sonos Debug Sonos Action: Volume Down
Sonos Debug zoneIP: 192.168.90.220, soapRoot: /MediaRenderer, soapBranch: /RenderingControl, soapAction: SetVolume
Sonos ZonePlayer: SONOS Connect, Current Volume: 82, New Volume: 80
Sonos Debug Updating Device: SONOS Connect, State: ZP_VOLUME, Value: 80

The output looks solid. Do you have anything else running on the Indigo server that may have started a TCP listener on port 1400? Could be a conflict??

I'm not very good at Mac and OS X, but I will try som command line and maby find out. I do have some other plugins running, I can try stopping them to see if it makes any difference.


/RAID5
Sent from my iPhone with Tapatalk

And this is what it looks like now some minutes later
Mar 6, 2016 5:59:01 PM
Sonos Debug Sonos Action: Volume Up
Sonos Debug zoneIP: 192.168.90.220, soapRoot: /MediaRenderer, soapBranch: /RenderingControl, soapAction: SetVolume
Sonos ZonePlayer: SONOS Connect, Current Volume: 80, New Volume: 82
Sonos Debug Sonos Action: Volume Up
Sonos Debug zoneIP: 192.168.90.220, soapRoot: /MediaRenderer, soapBranch: /RenderingControl, soapAction: SetVolume
Sonos ZonePlayer: SONOS Connect, Current Volume: 80, New Volume: 82
Sonos Debug Sonos Action: Volume Up
Sonos Debug zoneIP: 192.168.90.220, soapRoot: /MediaRenderer, soapBranch: /RenderingControl, soapAction: SetVolume
Sonos ZonePlayer: SONOS Connect, Current Volume: 80, New Volume: 82
Sonos Debug Sonos Action: Volume Up
Sonos Debug zoneIP: 192.168.90.220, soapRoot: /MediaRenderer, soapBranch: /RenderingControl, soapAction: SetVolume
Sonos ZonePlayer: SONOS Connect, Current Volume: 80, New Volume: 82
Sonos Debug Sonos Action: Volume Up
Sonos Debug zoneIP: 192.168.90.220, soapRoot: /MediaRenderer, soapBranch: /RenderingControl, soapAction: SetVolume
Sonos ZonePlayer: SONOS Connect, Current Volume: 80, New Volume: 82

It's pretty clear that the event stream has stopped. Out of curiosity, what music services do you have? It's possible one of them is blowing this up.

And this is what it looks like now some minutes later
Mar 6, 2016 5:59:01 PM
Sonos Debug Sonos Action: Volume Up
Sonos Debug zoneIP: 192.168.90.220, soapRoot: /MediaRenderer, soapBranch: /RenderingControl, soapAction: SetVolume
Sonos ZonePlayer: SONOS Connect, Current Volume: 80, New Volume: 82
Sonos Debug Sonos Action: Volume Up
Sonos Debug zoneIP: 192.168.90.220, soapRoot: /MediaRenderer, soapBranch: /RenderingControl, soapAction: SetVolume
Sonos ZonePlayer: SONOS Connect, Current Volume: 80, New Volume: 82
Sonos Debug Sonos Action: Volume Up
Sonos Debug zoneIP: 192.168.90.220, soapRoot: /MediaRenderer, soapBranch: /RenderingControl, soapAction: SetVolume
Sonos ZonePlayer: SONOS Connect, Current Volume: 80, New Volume: 82
Sonos Debug Sonos Action: Volume Up
Sonos Debug zoneIP: 192.168.90.220, soapRoot: /MediaRenderer, soapBranch: /RenderingControl, soapAction: SetVolume
Sonos ZonePlayer: SONOS Connect, Current Volume: 80, New Volume: 82
Sonos Debug Sonos Action: Volume Up
Sonos Debug zoneIP: 192.168.90.220, soapRoot: /MediaRenderer, soapBranch: /RenderingControl, soapAction: SetVolume
Sonos ZonePlayer: SONOS Connect, Current Volume: 80, New Volume: 82

I'm using Spotify


/RAID5
Sent from my iPhone with Tapatalk

Hi again
Some trouble shooting has been done.
1: Problem remains with other plugins running or not
2: Problem remains with diffrent scources (Spotify or TuneIn Radio)
3: The biggest discovery, the problem is isolated to the SONOS Connect, Everything works fine on my Play1 and Play3

Some error messages found and after that logging from testing volume up/down and mute on all three devices.

Error:
Sonos Debug Method: runConcurrentThread
Sonos Debug Running Concurrent Thread
Sonos Debug Copy states from master ZonePlayer...
Sonos Debug Updating Slave Device: SONOS Living Room, State: ZP_DURATION, Value:
Sonos Debug Updating Slave Device: SONOS Living Room, State: ZP_STATION, Value:
Sonos Debug Updating Slave Device: SONOS Living Room, State: ZP_NALBUM, Value:
Sonos Debug Updating Slave Device: SONOS Living Room, State: ZP_NCREATOR, Value:
Sonos Debug Updating Slave Device: SONOS Living Room, State: ZP_ALBUM, Value:
Sonos Debug Updating Slave Device: SONOS Living Room, State: ZP_CurrentTrackURI, Value:
Sonos Debug Updating Slave Device: SONOS Living Room, State: ZP_CurrentTrack, Value:
Sonos Debug Updating Slave Device: SONOS Living Room, State: ZP_NART, Value:
Sonos Debug Updating Slave Device: SONOS Living Room, State: ZP_ARTIST, Value:
Sonos Debug Updating Slave Device: SONOS Living Room, State: ZP_TRACK, Value:
Sonos Debug Updating Slave Device: SONOS Living Room, State: ZP_INFO, Value:
Sonos Debug Updating Slave Device: SONOS Living Room, State: ZP_NTRACK, Value:
Sonos Debug Updating Slave Device: SONOS Living Room, State: ZP_STATE, Value:
Sonos Debug Updating Slave Device: SONOS Living Room, State: ZP_CREATOR, Value:
Sonos Debug Updating Slave Device: SONOS Living Room, State: ZP_NARTIST, Value:
Sonos Error Error in plugin execution runConcurrentThread:

Traceback (most recent call last):
File "plugin.py", line 59, in runConcurrentThread
File "/Library/Application Support/Perceptive Automation/Indigo 6/Plugins/Sonos.indigoPlugin/Contents/Server Plugin/Sonos.py", line 390, in runConcurrentThread
File "/Library/Application Support/Perceptive Automation/Indigo 6/Plugins/Sonos.indigoPlugin/Contents/Server Plugin/Sonos.py", line 802, in socoEvents
File "/Library/Application Support/Perceptive Automation/Indigo 6/Plugins/Sonos.indigoPlugin/Contents/Server Plugin/Sonos.py", line 2359, in copyStateFromMaster
File "/System/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/shutil.py", line 99, in copy2
File "/System/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/shutil.py", line 52, in copyfile
<type 'exceptions.IOError'>: [Errno 2] No such file or directory: u'/Library/Application Support/Perceptive Automation/images/Sonos/Sittingroom_art.jpg'

Sonos Error plugin runConcurrentThread function returned or failed (will attempt again in 10 seconds)



Testing Volume up/down and Mute
Mar 6, 2016 10:16:40 PM
WebServer request to execute "_Main_iPad" control #23 from 192.168.90.83
Sonos Debug Sonos Action: Volume Up
Sonos Debug zoneIP: 192.168.90.220, soapRoot: /MediaRenderer, soapBranch: /RenderingControl, soapAction: SetVolume
WebServer request to execute "_Main_iPad" control #23 from 192.168.90.83
Sonos ZonePlayer: SONOS Connect, Current Volume: 74, New Volume: 76
Sonos Debug Sonos Action: Volume Up
Sonos Debug zoneIP: 192.168.90.220, soapRoot: /MediaRenderer, soapBranch: /RenderingControl, soapAction: SetVolume
Sonos ZonePlayer: SONOS Connect, Current Volume: 74, New Volume: 76
WebServer request to execute "_Main_iPad" control #23 from 192.168.90.83
Sonos Debug Sonos Action: Volume Up
Sonos Debug zoneIP: 192.168.90.220, soapRoot: /MediaRenderer, soapBranch: /RenderingControl, soapAction: SetVolume
Sonos ZonePlayer: SONOS Connect, Current Volume: 74, New Volume: 76
WebServer request to execute "_Main_iPad" control #28 from 192.168.90.83
Sonos Debug Sonos Action: Volume Up
Sonos Debug zoneIP: 192.168.90.221, soapRoot: /MediaRenderer, soapBranch: /RenderingControl, soapAction: SetVolume
WebServer request to execute "_Main_iPad" control #28 from 192.168.90.83
Sonos ZonePlayer: SONOS Kitchen, Current Volume: 8, New Volume: 10
Sonos Debug Sonos Action: Volume Up
Sonos Debug zoneIP: 192.168.90.221, soapRoot: /MediaRenderer, soapBranch: /RenderingControl, soapAction: SetVolume
Sonos Debug Updating Device: SONOS Kitchen, State: ZP_VOLUME, Value: 10
Sonos ZonePlayer: SONOS Kitchen, Current Volume: 8, New Volume: 10
Sonos Debug Updating Device: SONOS Kitchen, State: ZP_VOLUME, Value: 10
WebServer request to execute "_Main_iPad" control #28 from 192.168.90.83
Sonos Debug Sonos Action: Volume Up
Sonos Debug zoneIP: 192.168.90.221, soapRoot: /MediaRenderer, soapBranch: /RenderingControl, soapAction: SetVolume
Sonos ZonePlayer: SONOS Kitchen, Current Volume: 10, New Volume: 12
Sonos Debug Updating Device: SONOS Kitchen, State: ZP_VOLUME, Value: 12
WebServer request to execute "_Main_iPad" control #28 from 192.168.90.83
Sonos Debug Sonos Action: Volume Up
Sonos Debug zoneIP: 192.168.90.221, soapRoot: /MediaRenderer, soapBranch: /RenderingControl, soapAction: SetVolume
Sonos ZonePlayer: SONOS Kitchen, Current Volume: 12, New Volume: 14
Sonos Debug Updating Device: SONOS Kitchen, State: ZP_VOLUME, Value: 14
WebServer request to execute "_Main_iPad" control #32 from 192.168.90.83
Sonos Debug Sonos Action: Volume Up
Sonos Debug zoneIP: 192.168.90.222, soapRoot: /MediaRenderer, soapBranch: /RenderingControl, soapAction: SetVolume
WebServer request to execute "_Main_iPad" control #32 from 192.168.90.83
Sonos ZonePlayer: SONOS Living Room, Current Volume: 11, New Volume: 13
Sonos Debug Sonos Action: Volume Up
Sonos Debug zoneIP: 192.168.90.222, soapRoot: /MediaRenderer, soapBranch: /RenderingControl, soapAction: SetVolume
Sonos Debug Updating Device: SONOS Living Room, State: ZP_VOLUME, Value: 13
Sonos ZonePlayer: SONOS Living Room, Current Volume: 11, New Volume: 13
Sonos Debug Updating Device: SONOS Living Room, State: ZP_VOLUME, Value: 13
WebServer request to execute "_Main_iPad" control #32 from 192.168.90.83
Sonos Debug Sonos Action: Volume Up
Sonos Debug zoneIP: 192.168.90.222, soapRoot: /MediaRenderer, soapBranch: /RenderingControl, soapAction: SetVolume
Sonos ZonePlayer: SONOS Living Room, Current Volume: 13, New Volume: 15
Sonos Debug Updating Device: SONOS Living Room, State: ZP_VOLUME, Value: 15
WebServer request to execute "_Main_iPad" control #32 from 192.168.90.83
Sonos Debug Sonos Action: Volume Up
Sonos Debug zoneIP: 192.168.90.222, soapRoot: /MediaRenderer, soapBranch: /RenderingControl, soapAction: SetVolume
Sonos ZonePlayer: SONOS Living Room, Current Volume: 15, New Volume: 17
Sonos Debug Updating Device: SONOS Living Room, State: ZP_VOLUME, Value: 17
WebServer request to execute "_Main_iPad" control #32 from 192.168.90.83
Sonos Debug Sonos Action: Volume Up
Sonos Debug zoneIP: 192.168.90.222, soapRoot: /MediaRenderer, soapBranch: /RenderingControl, soapAction: SetVolume
Sonos ZonePlayer: SONOS Living Room, Current Volume: 17, New Volume: 19
Sonos Debug Updating Device: SONOS Living Room, State: ZP_VOLUME, Value: 19
WebServer request to execute "_Main_iPad" control #32 from 192.168.90.83
Sonos Debug Sonos Action: Volume Up
Sonos Debug zoneIP: 192.168.90.222, soapRoot: /MediaRenderer, soapBranch: /RenderingControl, soapAction: SetVolume
Sonos ZonePlayer: SONOS Living Room, Current Volume: 19, New Volume: 21
Sonos Debug Updating Device: SONOS Living Room, State: ZP_VOLUME, Value: 21
WebServer request to execute "_Main_iPad" control #31 from 192.168.90.83
Sonos Debug Sonos Action: Volume Down
Sonos Debug zoneIP: 192.168.90.222, soapRoot: /MediaRenderer, soapBranch: /RenderingControl, soapAction: SetVolume
Sonos ZonePlayer: SONOS Living Room, Current Volume: 21, New Volume: 19
Sonos Debug Updating Device: SONOS Living Room, State: ZP_VOLUME, Value: 19
WebServer request to execute "_Main_iPad" control #31 from 192.168.90.83
Sonos Debug Sonos Action: Volume Down
Sonos Debug zoneIP: 192.168.90.222, soapRoot: /MediaRenderer, soapBranch: /RenderingControl, soapAction: SetVolume
Sonos ZonePlayer: SONOS Living Room, Current Volume: 19, New Volume: 17
Sonos Debug Updating Device: SONOS Living Room, State: ZP_VOLUME, Value: 17
WebServer request to execute "_Main_iPad" control #31 from 192.168.90.83
Sonos Debug Sonos Action: Volume Down
Sonos Debug zoneIP: 192.168.90.222, soapRoot: /MediaRenderer, soapBranch: /RenderingControl, soapAction: SetVolume
Sonos ZonePlayer: SONOS Living Room, Current Volume: 17, New Volume: 15
Sonos Debug Updating Device: SONOS Living Room, State: ZP_VOLUME, Value: 15
WebServer request to execute "_Main_iPad" control #31 from 192.168.90.83
Sonos Debug Sonos Action: Volume Down
Sonos Debug zoneIP: 192.168.90.222, soapRoot: /MediaRenderer, soapBranch: /RenderingControl, soapAction: SetVolume
Sonos ZonePlayer: SONOS Living Room, Current Volume: 15, New Volume: 13
Sonos Debug Updating Device: SONOS Living Room, State: ZP_VOLUME, Value: 13
WebServer request to execute "_Main_iPad" control #27 from 192.168.90.83
Sonos Debug Sonos Action: Volume Down
Sonos Debug zoneIP: 192.168.90.221, soapRoot: /MediaRenderer, soapBranch: /RenderingControl, soapAction: SetVolume
Sonos ZonePlayer: SONOS Kitchen, Current Volume: 14, New Volume: 12
Sonos Debug Updating Device: SONOS Kitchen, State: ZP_VOLUME, Value: 12
WebServer request to execute "_Main_iPad" control #27 from 192.168.90.83
Sonos Debug Sonos Action: Volume Down
Sonos Debug zoneIP: 192.168.90.221, soapRoot: /MediaRenderer, soapBranch: /RenderingControl, soapAction: SetVolume
Sonos ZonePlayer: SONOS Kitchen, Current Volume: 12, New Volume: 10
Sonos Debug Updating Device: SONOS Kitchen, State: ZP_VOLUME, Value: 10
WebServer request to execute "_Main_iPad" control #31 from 192.168.90.83
Sonos Debug Sonos Action: Volume Down
Sonos Debug zoneIP: 192.168.90.222, soapRoot: /MediaRenderer, soapBranch: /RenderingControl, soapAction: SetVolume
Sonos ZonePlayer: SONOS Living Room, Current Volume: 13, New Volume: 11
Sonos Debug Updating Device: SONOS Living Room, State: ZP_VOLUME, Value: 11
WebServer request to execute "_Main_iPad" control #33 from 192.168.90.83
Sonos Debug Sonos Action: Mute Toggle
Sonos Debug zoneIP: 192.168.90.222, soapRoot: /MediaRenderer, soapBranch: /RenderingControl, soapAction: SetMute
Sonos ZonePlayer: SONOS Living Room, Mute On
Sonos Debug Updating Device: SONOS Living Room, State: ZP_MUTE, Value: 1
WebServer request to execute "_Main_iPad" control #33 from 192.168.90.83
Sonos Debug Sonos Action: Mute Toggle
Sonos Debug zoneIP: 192.168.90.222, soapRoot: /MediaRenderer, soapBranch: /RenderingControl, soapAction: SetMute
Sonos ZonePlayer: SONOS Living Room, Mute Off
Sonos Debug Updating Device: SONOS Living Room, State: ZP_MUTE, Value: 0
WebServer request to execute "_Main_iPad" control #29 from 192.168.90.83
Sonos Debug Sonos Action: Mute Toggle
Sonos Debug zoneIP: 192.168.90.221, soapRoot: /MediaRenderer, soapBranch: /RenderingControl, soapAction: SetMute
Sonos ZonePlayer: SONOS Kitchen, Mute On
Sonos Debug Updating Device: SONOS Kitchen, State: ZP_MUTE, Value: 1
WebServer request to execute "_Main_iPad" control #33 from 192.168.90.83
Sonos Debug Sonos Action: Mute Toggle
Sonos Debug zoneIP: 192.168.90.222, soapRoot: /MediaRenderer, soapBranch: /RenderingControl, soapAction: SetMute
Sonos ZonePlayer: SONOS Living Room, Mute On
Sonos Debug Updating Device: SONOS Living Room, State: ZP_MUTE, Value: 1
WebServer request to execute "_Main_iPad" control #33 from 192.168.90.83
Sonos Debug Sonos Action: Mute Toggle
Sonos Debug zoneIP: 192.168.90.222, soapRoot: /MediaRenderer, soapBranch: /RenderingControl, soapAction: SetMute
Sonos ZonePlayer: SONOS Living Room, Mute Off
Sonos Debug Updating Device: SONOS Living Room, State: ZP_MUTE, Value: 0
WebServer request to execute "_Main_iPad" control #29 from 192.168.90.83
Sonos Debug Sonos Action: Mute Toggle
Sonos Debug zoneIP: 192.168.90.221, soapRoot: /MediaRenderer, soapBranch: /RenderingControl, soapAction: SetMute
Sonos ZonePlayer: SONOS Kitchen, Mute Off
Sonos Debug Updating Device: SONOS Kitchen, State: ZP_MUTE, Value: 0
Z-Wave received "Back Yard - Luminance" sensor update to 0 lux
Z-Wave received "Back Yard - Temperature" sensor update to -1.4 °C
Trigger SWA-Test Current temperature
Z-Wave received "Back Yard - Humidity" sensor update to 95%
WebServer request to execute "_Main_iPad" control #22 from 192.168.90.83
Sonos Debug Sonos Action: Mute Toggle
Sonos Debug zoneIP: 192.168.90.220, soapRoot: /MediaRenderer, soapBranch: /RenderingControl, soapAction: SetMute
Sonos ZonePlayer: SONOS Connect, Mute On
WebServer request to execute "_Main_iPad" control #22 from 192.168.90.83
Sonos Debug Sonos Action: Mute Toggle
Sonos Debug zoneIP: 192.168.90.220, soapRoot: /MediaRenderer, soapBranch: /RenderingControl, soapAction: SetMute
Sonos ZonePlayer: SONOS Connect, Mute On

Would you give the latest release (v0.9.6) a shot?

Hi Nick,

Not a big deal, but while running v.9.6 on March 24th, I pressed "Check for Updates" in the Sonos Plugin menu. Although v.9.7 had been released on the 23rd, my log said:

Mar 24, 2016, 1:33:25 PM
Sonos Manually checking for updates
Sonos Your plugin version, v0.9.6, is current.

mjc

Ha! I forgot to change the update file! Thanks for letting me know.

Hi Nick,


Not a big deal, but while running v.9.6 on March 24th, I pressed "Check for Updates" in the Sonos Plugin menu. Although v.9.7 had been released on the 23rd, my log said:

Mar 24, 2016, 1:33:25 PM
Sonos Manually checking for updates
Sonos Your plugin version, v0.9.6, is current.

mjc

Hi again

I still try to figure out what the problem is with my installation.
I have upgraded to latest version and the problem still remains.
I have done a port scan with the very easy built in utility "Network Utility" (result further down)
Is doesn't show anything on port 1400 even if the plugin is running and active.
I have tied with both SoCo and Coherence.
The only thing I managed to find is that it fails more or less exactly 5 minutes after the plugin is reloaded.
I made a scheduled job that will do "volume up" 5 times with 1 second delay every time and then "volume down" 5 times with 1 second delay. (to create continuously activity)
The plugin will fail if I continually run this to create activity during these 5 minutes or if I just do nothing,
The plug in will fail after about 5 minutes ether way.
I really need some help here.


Port Scan has started…

Port Scanning host: 127.0.0.1


Open TCP Port: 80 http

Open TCP Port: 631 ipp

Open TCP Port: 1176 indigo-server

Open TCP Port: 3052 apc-3052

Open TCP Port: 4370

Open TCP Port: 4380

Open TCP Port: 5000 commplex-main

Open TCP Port: 6547 apc-6547

Open TCP Port: 8000 irdmi

Open TCP Port: 8176

Open TCP Port: 8888 ddi-tcp-1

Open TCP Port: 9877

Open TCP Port: 19877

Open TCP Port: 33602

Open TCP Port: 49157

Open TCP Port: 53562

Port Scan has completed…

Hi there
Tried the new version today but ran in to problems, it fails with following error.
Looks like something need to be upgraded.

Traceback (most recent call last):
File "plugin.py", line 9, in <module>
File "/Library/Application Support/Perceptive Automation/Indigo 6/Plugins/Sonos.indigoPlugin/Contents/Server Plugin/Sonos.py", line 31, in <module>
File "./lib/twisted/__init__.py", line 53, in <module>
File "./lib/twisted/__init__.py", line 51, in _checkRequirements
<type 'exceptions.ImportError'>: Twisted requires zope.interface 3.6.0 or later.