Announcement

Collapse
No announcement yet.

Losing SONOS Devices

Collapse
X
 
  • Filter
  • Time
  • Show
Clear All
new posts

    Losing SONOS Devices

    I have a Sonos system with six players. Have been using this Plug-In fine for a few months. Suddenly two devices have disappeared from the Status page, just have Music UPNP Master entry and Music Transport and Music Rendering for remaining 4 players. Pressing 'Rediscover Players' does not help. Have de-installed Plug-In and Re-Installed (to later 1.0.0.105), no difference. Separate Controller (as supplied by Sonos) shows all 6 no problem and all 6 work fine on this player - problem is just in Homeseer. Any suggestions would be gratefully received....

    #2
    Originally posted by peterford View Post
    I have a Sonos system with six players. Have been using this Plug-In fine for a few months. Suddenly two devices have disappeared from the Status page, just have Music UPNP Master entry and Music Transport and Music Rendering for remaining 4 players. Pressing 'Rediscover Players' does not help. Have de-installed Plug-In and Re-Installed (to later 1.0.0.105), no difference. Separate Controller (as supplied by Sonos) shows all 6 no problem and all 6 work fine on this player - problem is just in Homeseer. Any suggestions would be gratefully received....
    The plugin doesn't delete devices from HS so either it got accidentally deleted by the user or the HS DB got corrupted.

    Do you have a lot of work invested in events, actions, HST screens? If not, here is what I would recommend.

    Go to the HS status page and delete all the Sonos devices. Stop HS. Go to the <HS root>\Config subdirectory and locate the HSPI_Sonoscontroller.ini file. Delete the file. Restart HS and the PI will rediscover and recreate the devices.

    If you have a lot of effort spent on creating events etc. you could go into the .ini file and delete the devices that were accidentally deleted out of HS. However, it would take too much time to explain what to delete, I would just post your .ini file here and tell me which 2 devices got lost and I can fix the ini file for you.

    Let me know what you decided to do.

    Dirk

    Comment


      #3
      Hi Dirk

      Thanks for the really speedy reply!

      I did the former since not got many events just a few screens in HST.

      However it still only discovered just the 4 players. Checked on the SONOS supplied utility and all six are present and working.

      What do you suggest I try next?

      Comment


        #4
        Originally posted by peterford View Post
        Hi Dirk

        Thanks for the really speedy reply!

        I did the former since not got many events just a few screens in HST.

        However it still only discovered just the 4 players. Checked on the SONOS supplied utility and all six are present and working.

        What do you suggest I try next?
        Go to HS setup, make sure you have logging to disk on. Go to Sonos Config screen and turn debug flag on (don't forget to click save). Restart HS, let is all come up (20 seconds or so), post the log file from start of HS to where it ended.

        In parallel, explain to me what players you have, more importantly which are missing and what type are those? Are they wired or wireless. Check your firewall settings and make sure they are not blocked on the HS-PC. Are they all in the same subnet or different subnet? Wireless or wired? What OS does HS run on?

        Thanks

        Dirk

        Comment


          #5
          Originally posted by dcorsus View Post
          Go to HS setup, make sure you have logging to disk on. Go to Sonos Config screen and turn debug flag on (don't forget to click save). Restart HS, let is all come up (20 seconds or so), post the log file from start of HS to where it ended.

          In parallel, explain to me what players you have, more importantly which are missing and what type are those? Are they wired or wireless. Check your firewall settings and make sure they are not blocked on the HS-PC. Are they all in the same subnet or different subnet? Wireless or wired? What OS does HS run on?

          Thanks

          Dirk
          Hi Dirk, done all that. Log at end of this.

          Have 6 players, 3 Connects and 3 Play 5 all are wireless apart from one S5 which is wired. The missing two are the wired S5 and one Connect. Turned firewall off to make sure that wasnt the problem - still no change. All are on same subnet and HS PC is running Windows 7 Prof 64 bit.

          Log File:

          21/03/2013 21:59:56 - Startup -
          21/03/2013 21:59:56 - Startup - ************************************************************ ********************
          21/03/2013 21:59:56 - Startup - HomeSeer version 2.5.0.55 Starting Now (PRO Build)
          21/03/2013 21:59:56 - Startup - ************************************************************ ********************
          21/03/2013 21:59:56 - Startup -
          21/03/2013 21:59:56 - Startup - Using system's regional settings
          21/03/2013 21:59:56 - Startup - Creating Class Objects...
          21/03/2013 21:59:56 - Startup - COM port classes started.
          21/03/2013 21:59:56 - Startup - Application interface class started.
          21/03/2013 21:59:56 - Startup - Web server class started.
          21/03/2013 21:59:56 - Startup - E-Mail SEND class started.
          21/03/2013 21:59:56 - Startup - E-Mail RECEIVE class started.
          21/03/2013 21:59:56 - Startup - Z-Wave class started.
          21/03/2013 21:59:56 - Startup - Plug-In Interface class started.
          21/03/2013 21:59:56 - Startup - Speech recognition class started.
          21/03/2013 21:59:56 - Startup - HSPhone dialer class started.
          21/03/2013 21:59:56 - Startup - Loading Settings
          21/03/2013 21:59:56 - Startup - Settings Loaded.
          21/03/2013 21:59:56 - Startup - Current sunrise/sunset values calculated.
          21/03/2013 21:59:56 - Startup - HSPhone external interface class started.
          21/03/2013 21:59:56 - Startup - Local voice recognition class started.
          21/03/2013 21:59:56 - Startup - Database SAVE process started.
          21/03/2013 21:59:56 - Startup - Loading configuration file ...
          21/03/2013 21:59:56 - System - Loading device types file...
          21/03/2013 21:59:58 - Database - Opening (Mode=Read) up HomeSeer database: C:\Program Files (x86)\HomeSeer HSPRO\config\sample.mdb
          21/03/2013 21:59:58 - Database - Loading Devices...
          21/03/2013 21:59:59 - Load Config - 106 total devices loaded.
          21/03/2013 21:59:59 - Load Config - Checking device type settings for all devices...
          21/03/2013 21:59:59 - Load Config - Finished checking device type settings.
          21/03/2013 21:59:59 - Load Config - Loading Events...
          21/03/2013 21:59:59 - Load Config - 97 total events loaded.
          21/03/2013 21:59:59 - Database - HomeSeer configuration database C:\Program Files (x86)\HomeSeer HSPRO\config\sample.mdb has been closed.
          21/03/2013 22:00:00 - Startup - HomeSeer version is: 2.5.0.55
          21/03/2013 22:00:00 - Startup - Starting remoting (Remote client connection monitor)
          21/03/2013 22:00:00 - Startup - Creating Speaker Client Interface Object...
          21/03/2013 22:00:00 - Startup - Initializing phone interface...
          21/03/2013 22:00:00 - HSPhone - HomeSeer Phone Web Interface initializing.
          21/03/2013 22:00:00 - Startup - Checking for pending updates to be installed...
          21/03/2013 22:00:00 - Startup - Restoring Device Status...
          21/03/2013 22:00:00 - Startup - Checking for available plug-ins
          21/03/2013 22:00:01 - Plug-In - Found plug-in: X10 CM11A/CM12U, version: 2.2.0.4
          21/03/2013 22:00:01 - Plug-In - Found plug-in: X10 CM15AUSB, version: 1.0.0.6
          21/03/2013 22:00:01 - Plug-In - Found plug-in: HSTouch Server, version: 1.0.0.58
          21/03/2013 22:00:01 - Plug-In - Found plug-in: HSTouch Android, version: 1.0.0.2
          21/03/2013 22:00:01 - Plug-In - Found plug-in: HSTouch iPhone, version: 1.0.0.2
          21/03/2013 22:00:01 - Plug-In - Found plug-in: HSTouch Server Unlimited, version: 1.0.0.1
          21/03/2013 22:00:01 - Plug-In - Found plug-in: Insteon, version: 1.1.52.1
          21/03/2013 22:00:01 - Plug-In - Found plug-in: iTunes, version: 2.5.0.12
          21/03/2013 22:00:01 - Plug-In - Found plug-in: Media Player, version: 2.5.0.13
          21/03/2013 22:00:02 - Plug-In - Found plug-in: SONOSCONTROLLER, version: 1.0.0.105
          21/03/2013 22:00:02 - Plug-In - Found plug-in: UPB, version: 1.0.2.7
          21/03/2013 22:00:02 - Startup - Initializing Plug-Ins
          21/03/2013 22:00:02 - Plug-In - Initializing Plug-in: HSTouch Server
          21/03/2013 22:00:02 - Plug-In - Finished initializing plug-in HSTouch Server
          21/03/2013 22:00:02 - HSTouch Server Unl - Register callback completed
          21/03/2013 22:00:02 - Plug-In - Initializing Plug-in: HSTouch Server Unlimited
          21/03/2013 22:00:02 - HSTouch Server Unl - HSTouch Server Unlimited plugin initialized.
          21/03/2013 22:00:02 - Plug-In - Finished initializing plug-in HSTouch Server Unlimited
          21/03/2013 22:00:02 - Plug-In - Initializing Plug-in: HSTouch iPhone
          21/03/2013 22:00:02 - Plug-In - Finished initializing plug-in HSTouch iPhone
          21/03/2013 22:00:02 - Plug-In - Initializing Plug-in: HSTouch Android
          21/03/2013 22:00:02 - Plug-In - Finished initializing plug-in HSTouch Android
          21/03/2013 22:00:02 - SonosController - Application path: C:\Program Files (x86)\HomeSeer HSPRO
          21/03/2013 22:00:02 - SonosController - Register callback completed
          21/03/2013 22:00:02 - Plug-In - Initializing Plug-in: SONOSCONTROLLER
          21/03/2013 22:00:02 - SonosController - InitIO Called
          21/03/2013 22:00:03 - SonosController - Initializing Sonos Devices
          21/03/2013 22:00:03 - SonosController - Found Housecode in inifile. HouseCode = [
          21/03/2013 22:00:03 - SonosController - BuildButtonString: Found LinkgroupZoneSource =
          21/03/2013 22:00:03 - SonosController - BuildButtonValues: Found LinkgroupZoneSource =
          21/03/2013 22:00:03 - SonosController - FindZonePlayers: Attempting to locate all connected ZonePlayers. This may take up to 9 seconds.
          21/03/2013 22:00:12 - SonosController - FindZonePlayers - Discovery succeeded: 4 ZonePlayer(s) found.
          21/03/2013 22:00:12 - SonosController - Zone Name = Kitchen. Zone UDN = uuid:RINCON_000E58A2EE3801400 Friendly Name = 192.168.1.32 - Sonos CONNECT
          21/03/2013 22:00:12 - SonosController - Zone Name = Family Room. Zone UDN = uuid:RINCON_000E585E89E201400 Friendly Name = 192.168.1.30 - Sonos PLAY:5
          21/03/2013 22:00:12 - SonosController - Zone Name = Dining Room. Zone UDN = uuid:RINCON_000E585F339A01400 Friendly Name = 192.168.1.41 - Sonos PLAY:5
          21/03/2013 22:00:12 - SonosController - Zone Name = Living Room. Zone UDN = uuid:RINCON_000E58A2ECD001400 Friendly Name = 192.168.1.31 - Sonos CONNECT
          21/03/2013 22:00:12 - SonosController - FindZonePlayers - Discovery succeeded: 4 ZonePlayer(s) found.
          21/03/2013 22:00:12 - SonosController - BuildHSSonosDevices: looking For Kitchen in ZoneInfo
          21/03/2013 22:00:13 - SonosController - BuildHSSonosDevices: Found Zone Name for Transport. Zonename = Kitchen
          21/03/2013 22:00:13 - SonosController - BuildHSSonosDevices:Found Zone Name for Rendering. Zonename = Kitchen
          21/03/2013 22:00:13 - SonosController - BuildHSSonosDevices: looking For Family Room in ZoneInfo
          21/03/2013 22:00:13 - SonosController - BuildHSSonosDevices: Found Zone Name for Transport. Zonename = Family Room
          21/03/2013 22:00:13 - SonosController - BuildHSSonosDevices:Found Zone Name for Rendering. Zonename = Family Room
          21/03/2013 22:00:13 - SonosController - BuildHSSonosDevices: looking For Dining Room in ZoneInfo
          21/03/2013 22:00:13 - SonosController - BuildHSSonosDevices: Found Zone Name for Transport. Zonename = Dining Room
          21/03/2013 22:00:13 - SonosController - BuildHSSonosDevices:Found Zone Name for Rendering. Zonename = Dining Room
          21/03/2013 22:00:13 - SonosController - BuildHSSonosDevices: looking For Living Room in ZoneInfo
          21/03/2013 22:00:13 - SonosController - BuildHSSonosDevices: Found Zone Name for Transport. Zonename = Living Room
          21/03/2013 22:00:13 - SonosController - BuildHSSonosDevices:Found Zone Name for Rendering. Zonename = Living Room
          21/03/2013 22:00:13 - SonosController - SetDeviceStringConnected called
          21/03/2013 22:00:13 - SonosController - SortZonePlayers called. ZoneCount = 9
          21/03/2013 22:00:13 - SonosController - CreateSonosControllers: found 9 Device Codes
          21/03/2013 22:00:13 - SonosController - SetHSDeviceCodeTransport called for zone - Kitchen with HS Code = [2
          21/03/2013 22:00:13 - SonosController - SetHSDeviceCodeRendering called for zone - Kitchen with HS Code =
          21/03/2013 22:00:13 - SonosController - DirectConnect called for Zone Kitchen with device name = uuid:RINCON_000E58A2EE3801400 and Model = ZP90
          21/03/2013 22:00:13 - SonosController - DirectConnect for zoneplayer = Kitchen found IconURL = http://192.168.1.32:1400/img/icon-ZP90.png
          21/03/2013 22:00:14 - SonosController - Kitchen : MediaServer Friendly Name = uuid:RINCON_000E58A2EE3801400_MS
          21/03/2013 22:00:14 - SonosController - Kitchen : MediaRenderer Friendly Name = Kitchen - Sonos CONNECT Media Renderer
          21/03/2013 22:00:14 - SonosController - AlarmClock service added for zoneplayer = Kitchen
          21/03/2013 22:00:14 - SonosController - MusicServices service added for zoneplayer = Kitchen
          21/03/2013 22:00:14 - SonosController - AudioIN service added for zoneplayer = Kitchen
          21/03/2013 22:00:14 - SonosController - DeviceProperties service added for zoneplayer = Kitchen
          21/03/2013 22:00:14 - SonosController - SystemProperties service added for zoneplayer = Kitchen
          21/03/2013 22:00:14 - SonosController - ZoneGroupTopology service added for zoneplayer = Kitchen
          21/03/2013 22:00:14 - SonosController - GroupManagement service addedfor zoneplayer = Kitchen
          21/03/2013 22:00:14 - SonosController - RenderingControl service added for zoneplayer = Kitchen
          21/03/2013 22:00:14 - SonosController - Kitchen : Additional Renderer services found with ID = urn:upnp-org:serviceId:ConnectionManager
          21/03/2013 22:00:15 - SonosController - AVTransport service added for zoneplayer = Kitchen
          21/03/2013 22:00:15 - SonosController - ContentDirectory service added for zoneplayer = Kitchen
          21/03/2013 22:00:15 - SonosController - ConnectionManager service added for zoneplayer = Kitchen
          21/03/2013 22:00:15 - SonosController - AvTransportCallback added for zoneplayer = Kitchen
          21/03/2013 22:00:15 - SonosController - RenderingControlCallback added for zoneplayer = Kitchen
          21/03/2013 22:00:15 - SonosController - ZoneGroupTopology ControlCallback added for zoneplayer = Kitchen
          21/03/2013 22:00:15 - SonosController - GroupManagement ControlCallback added for zoneplayer = Kitchen
          21/03/2013 22:00:15 - SonosController - AlarmClock ControlCallback added for zoneplayer = Kitchen
          21/03/2013 22:00:15 - SonosController - MusicServices ControlCallback added for zoneplayer = Kitchen
          21/03/2013 22:00:15 - SonosController - SystemProperties ControlCallback added for zoneplayer = Kitchen
          21/03/2013 22:00:15 - SonosController - ConnectionManagerCallback added for zoneplayer = Kitchen
          21/03/2013 22:00:15 - SonosController - AudioInCallback added for zoneplayer = Kitchen
          21/03/2013 22:00:15 - SonosController - DevicePropertiesCallback added for zoneplayer = Kitchen
          21/03/2013 22:00:15 - SonosController - Service callbacks established for zoneplayer = Kitchen
          21/03/2013 22:00:15 - SonosController - GetVolumeLevel called for ZonePlayer = Kitchen with values Channel=Master
          21/03/2013 22:00:15 - SonosController - MusicServices callback ZonePlayer Kitchen: Var Name = ServiceListVersion Value = RINCON_000E585E89E201400:195
          21/03/2013 22:00:15 - SonosController - DirectConnect for zoneplayer = Kitchen found AudioInputName = Line-In
          21/03/2013 22:00:15 - SonosController - IPAddress for zoneplayer = Kitchen= 192.168.1.32
          21/03/2013 22:00:15 - SonosController - GetcurrentTrackInfo called for zoneplayer - Kitchen
          21/03/2013 22:00:15 - SonosController - Kitchen TrackInfo (CurrentURI) = x-rincon-queue:RINCON_000E58A2EE3801400#0 and TrackInfo (CurrentURIMetaData) =
          21/03/2013 22:00:15 - SonosController - Kitchen TrackInfo (CurrentURI) = x-rincon-queue:RINCON_000E58A2EE3801400#0
          21/03/2013 22:00:15 - SonosController - Kitchen TrackInfo(Source) = Tracks
          21/03/2013 22:00:15 - SonosController - GetcurrentTrackInfo called GetPositionInfo for zoneplayer - Kitchen and MetaData <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"><res protocolInfo="x-file-cifs:*:audio/mpeg:*" duration="0:03:47">x-file-cifs://SERVER/Music/Tegan%20and%20Sara/Heartthrob/05%20Drove%20Me%20Wild.mp3</res><r:streamContent></r:streamContent><upnp:albumArtURI>/getaa?u=x-file-cifs%3a%2f%2fSERVER%2fMusic%2fTegan%2520and%2520Sara%2fHeart throb%2f05%2520Drove%2520Me%2520Wild.mp3&v=19</upnp:albumArtURI><dc:title>Drove Me Wild</dc:title><upnp:class>object.item.audioItem.musicTrack</upnp:class><dc:creator>Tegan and Sara</dc:creator><upnp:album>Heartthrob</upnp:album><upnpriginalTrackNumber>5</upnpriginalTrackNumber><r:albumArtist>Tegan and Sara</r:albumArtist></item></DIDL-Lite>
          21/03/2013 22:00:15 - SonosController - Kitchen TrackInfo (title) = Drove Me Wild
          21/03/2013 22:00:15 - SonosController - Kitchen TrackInfo (creator) = Tegan and Sara
          21/03/2013 22:00:15 - SonosController - Kitchen TrackInfo (album) = Heartthrob
          21/03/2013 22:00:15 - SonosController - Kitchen TrackInfo (albumArtURI) = http://192.168.1.32:1400/getaa?u=x-f...0Wild.mp3&v=19
          21/03/2013 22:00:15 - SonosController - GetAlbumArtPath called for zone Kitchen with AlbumURI = http://192.168.1.32:1400/getaa?u=x-f...0Wild.mp3&v=19 and NextTrack = False
          21/03/2013 22:00:15 - SonosController - GetAlbumArtPath returned for Zone - Kitchen with AlbumURI = http://192.168.1.32:1400/getaa?u=x-f...0Wild.mp3&v=19 and returned path= /SonosController/Images/Cover1.jpg
          21/03/2013 22:00:15 - SonosController - Kitchen TrackInfo (Track Position) = 0:00:20
          21/03/2013 22:00:15 - SonosController - Kitchen TrackInfo (Track Position Seconds) = 20
          21/03/2013 22:00:15 - SonosController - Kitchen TrackInfo (Track Duration) = 0:03:47
          21/03/2013 22:00:15 - SonosController - Kitchen TrackInfo (Queue Position) = 5
          21/03/2013 22:00:15 - SonosController - Kitchen TrackInfo (% played) = 9
          21/03/2013 22:00:15 - SonosController - PlayChangeNotifyCallback called for Zone - Kitchen with ChangeType = SongChanged and Changevalue = paused
          21/03/2013 22:00:15 - SonosController - PlayChangeNotifyCallback called for Zone - Kitchen with ChangeType = PlayStatusChanged and Changevalue = paused
          21/03/2013 22:00:15 - SonosController - HS updated in UpdateHS. HS Code = [2 Info = <table><tr><td><img src=/SonosController/Images/Cover1.jpg></td><td><p>Pause</p><p>Tracks</p><p>Drove Me Wild</p><p>Tegan and Sara</p><p>Heartthrob</p></td></tr></table>
          21/03/2013 22:00:15 - SonosController - CreateSonosControllers: Created instance of ZonePlayerController for Zoneplayer = Kitchen with index 2
          21/03/2013 22:00:15 - SonosController - SetHSDeviceCodeTransport called for zone - Family Room with HS Code = [4
          21/03/2013 22:00:15 - SonosController - SetHSDeviceCodeRendering called for zone - Family Room with HS Code =
          21/03/2013 22:00:15 - SonosController - DirectConnect called for Zone Family Room with device name = uuid:RINCON_000E585E89E201400 and Model = S5
          21/03/2013 22:00:15 - SonosController - DirectConnect for zoneplayer = Family Room found IconURL = http://192.168.1.30:1400/img/icon-S5.png
          21/03/2013 22:00:15 - SonosController - Family Room : MediaServer Friendly Name = uuid:RINCON_000E585E89E201400_MS
          21/03/2013 22:00:15 - SonosController - Family Room : MediaRenderer Friendly Name = Family Room - Sonos PLAY:5 Media Renderer
          21/03/2013 22:00:15 - SonosController - AlarmClock service added for zoneplayer = Family Room
          21/03/2013 22:00:15 - SonosController - MusicServices service added for zoneplayer = Family Room
          21/03/2013 22:00:16 - SonosController - AudioIN service added for zoneplayer = Family Room
          21/03/2013 22:00:16 - SonosController - DeviceProperties service added for zoneplayer = Family Room
          21/03/2013 22:00:16 - SonosController - SystemProperties service added for zoneplayer = Family Room
          21/03/2013 22:00:16 - SonosController - ZoneGroupTopology service added for zoneplayer = Family Room
          21/03/2013 22:00:16 - SonosController - GroupManagement service addedfor zoneplayer = Family Room
          21/03/2013 22:00:16 - SonosController - RenderingControl service added for zoneplayer = Family Room
          21/03/2013 22:00:16 - SonosController - Family Room : Additional Renderer services found with ID = urn:upnp-org:serviceId:ConnectionManager
          21/03/2013 22:00:16 - SonosController - AVTransport service added for zoneplayer = Family Room
          21/03/2013 22:00:16 - SonosController - ContentDirectory service added for zoneplayer = Family Room
          21/03/2013 22:00:17 - SonosController - ConnectionManager service added for zoneplayer = Family Room
          21/03/2013 22:00:17 - SonosController - AvTransportCallback added for zoneplayer = Family Room
          21/03/2013 22:00:17 - SonosController - RenderingControlCallback added for zoneplayer = Family Room
          21/03/2013 22:00:17 - SonosController - ZoneGroupTopology ControlCallback added for zoneplayer = Family Room
          21/03/2013 22:00:17 - SonosController - GroupManagement ControlCallback added for zoneplayer = Family Room
          21/03/2013 22:00:17 - SonosController - AlarmClock ControlCallback added for zoneplayer = Family Room
          21/03/2013 22:00:17 - SonosController - MusicServices ControlCallback added for zoneplayer = Family Room
          21/03/2013 22:00:17 - SonosController - SystemProperties ControlCallback added for zoneplayer = Family Room
          21/03/2013 22:00:17 - SonosController - ConnectionManagerCallback added for zoneplayer = Family Room
          21/03/2013 22:00:17 - SonosController - AudioInCallback added for zoneplayer = Family Room
          21/03/2013 22:00:17 - SonosController - DevicePropertiesCallback added for zoneplayer = Family Room
          21/03/2013 22:00:17 - SonosController - Service callbacks established for zoneplayer = Family Room
          21/03/2013 22:00:17 - SonosController - GetVolumeLevel called for ZonePlayer = Family Room with values Channel=Master
          21/03/2013 22:00:17 - SonosController - DirectConnect for zoneplayer = Family Room found AudioInputName = Line-In
          21/03/2013 22:00:17 - SonosController - IPAddress for zoneplayer = Family Room= 192.168.1.30
          21/03/2013 22:00:17 - SonosController - GetcurrentTrackInfo called for zoneplayer - Family Room
          21/03/2013 22:00:17 - SonosController - Family Room TrackInfo (CurrentURI) = x-rincon-queue:RINCON_000E585E89E201400#0 and TrackInfo (CurrentURIMetaData) =
          21/03/2013 22:00:17 - SonosController - TransportChangeCallback for ZP - Family Room VarName = LastChange Value = <Event xmlns="urn:schemas-upnp-org:metadata-1-0/AVT/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/"><InstanceID val="0"><TransportState val="STOPPED"/><CurrentPlayMode val="NORMAL"/><CurrentCrossfadeMode val="0"/><NumberOfTracks val="0"/><CurrentTrack val="0"/><CurrentSection val="0"/><CurrentTrackURI val=""/><CurrentTrackDuration val="0:00:00"/><CurrentTrackMetaData val=""/><r:NextTrackURI val=""/><r:NextTrackMetaData val=""/><r:EnqueuedTransportURI val=""/><r:EnqueuedTransportURIMetaData val=""/><PlaybackStorageMedium val="NETWORK"/><AVTransportURI val="x-rincon-queue:RINCON_000E585E89E201400#0"/><AVTransportURIMetaData val=""/><CurrentTransportActions val="Set, Play, Stop, Pause, Seek, Next, Previous"/><TransportStatus val="OK"/><r:SleepTimerGeneration val="0"/><r:AlarmRunning val="0"/><r:SnoozeRunning val="0"/><r:RestartPending val="0"/><TransportPlaySpeed val="NOT_IMPLEMENTED"/><CurrentMediaDuration val="NOT_IMPLEMENTED"/><RecordStorageMedium val="NOT_IMPLEMENTED"/><PossiblePlaybackStorageMedia val="NONE, NETWORK"/><PossibleRecordStorageMedia val="NOT_IMPLEMENTED"/><RecordMediumWriteStatus val="NOT_IMPLEMENTED"/><CurrentRecordQualityMode val="NOT_IMPLEMENTED"/><PossibleRecordQualityModes val="NOT_IMPLEMENTED"/><NextAVTransportURI val="NOT_IMPLEMENTED"/><NextAVTransportURIMetaData val="NOT_IMPLEMENTED"/></InstanceID></Event>
          21/03/2013 22:00:17 - SonosController - TransportChangeCallback for ZP - Family Room TransportState(TransportState) = STOPPED and previous state was = stopped
          21/03/2013 22:00:17 - SonosController - GetcurrentTrackInfo called for zoneplayer - Family Room
          21/03/2013 22:00:17 - SonosController - Family Room TrackInfo (CurrentURI) = x-rincon-queue:RINCON_000E585E89E201400#0
          21/03/2013 22:00:17 - SonosController - Family Room TrackInfo(Source) = Tracks
          21/03/2013 22:00:17 - SonosController - PlayChangeNotifyCallback called for Zone - Family Room with ChangeType = SongChanged and Changevalue = stopped
          21/03/2013 22:00:17 - SonosController - PlayChangeNotifyCallback called for Zone - Family Room with ChangeType = PlayStatusChanged and Changevalue = stopped
          21/03/2013 22:00:17 - SonosController - Family Room TrackInfo (CurrentURI) = x-rincon-queue:RINCON_000E585E89E201400#0 and TrackInfo (CurrentURIMetaData) =
          21/03/2013 22:00:17 - SonosController - HS updated in UpdateHS. HS Code = [4 Info = <table><tr><td><img src=/SonosController/images/noart.jpeg></td><td><p>Stop</p><p>Tracks</p><p></p><p></p><p></p></td></tr></table>
          21/03/2013 22:00:17 - SonosController - CreateSonosControllers: Created instance of ZonePlayerController for Zoneplayer = Family Room with index 4
          21/03/2013 22:00:17 - SonosController - SetHSDeviceCodeTransport called for zone - Dining Room with HS Code = [6
          21/03/2013 22:00:17 - SonosController - SetHSDeviceCodeRendering called for zone - Dining Room with HS Code =
          21/03/2013 22:00:17 - SonosController - DirectConnect called for Zone Dining Room with device name = uuid:RINCON_000E585F339A01400 and Model = S5
          21/03/2013 22:00:17 - SonosController - DirectConnect for zoneplayer = Dining Room found IconURL = http://192.168.1.41:1400/img/icon-S5.png
          21/03/2013 22:00:17 - SonosController - Family Room TrackInfo (CurrentURI) = x-rincon-queue:RINCON_000E585E89E201400#0
          21/03/2013 22:00:17 - SonosController - Family Room TrackInfo(Source) = Tracks
          21/03/2013 22:00:17 - SonosController - TransportChangeCallback for ZP - Family Room TransportState(CurrentTrack) = 0
          21/03/2013 22:00:17 - SonosController - TransportChangeCallback for ZP - Family Room TransportState(CurrentTrackDuration) = 0:00:00
          21/03/2013 22:00:17 - SonosController - TransportChangeCallback for ZP - Family Room TransportState(NumberOfTracks) = 0
          21/03/2013 22:00:17 - SonosController - TransportChangeCallback for ZP - Family Room TransportState(CurrentPlayMode) = NORMAL
          21/03/2013 22:00:17 - SonosController - TransportChangeCallback for ZP - Family Room TransportState(CurrentSection) = 0
          21/03/2013 22:00:17 - SonosController - TransportChangeCallback for ZP - Family Room TransportState(TransportStatus) = OK
          21/03/2013 22:00:17 - SonosController - Dining Room : MediaServer Friendly Name = uuid:RINCON_000E585F339A01400_MS
          21/03/2013 22:00:17 - SonosController - TransportChangeCallback for ZP - Family Room TransportState(Artist) =
          21/03/2013 22:00:17 - SonosController - TransportChangeCallback for ZP - Family Room TransportState(title) =
          21/03/2013 22:00:17 - SonosController - TransportChangeCallback for ZP - Family Room TransportState(Album) =
          21/03/2013 22:00:17 - SonosController - TransportChangeCallback for ZP - Family Room TransportState(CurrentURI) = x-rincon-queue:RINCON_000E585E89E201400#0
          21/03/2013 22:00:17 - SonosController - TransportChangeCallback for ZP - Family Room TransportState(AlbumArtURL) = /SonosController/images/noart.jpeg
          21/03/2013 22:00:17 - SonosController - TransportStateChange processed XML succesfully for zoneplayer = Family Room
          21/03/2013 22:00:17 - SonosController - Dining Room : MediaRenderer Friendly Name = Dining Room - Sonos PLAY:5 Media Renderer
          21/03/2013 22:00:17 - SonosController - PlayChangeNotifyCallback called for Zone - Family Room with ChangeType = SongChanged and Changevalue = stopped
          21/03/2013 22:00:17 - SonosController - Checking linked zones for SourceZone=Family Room. MyZoneIsSourceForLinkedZone=False and TargetZones =
          21/03/2013 22:00:17 - SonosController - HS updated in TransportChanged for zonePlayer Family Room. HS Code = [4 and updated DeviceValue = False. Info = <table><tr><td><img src=/SonosController/images/noart.jpeg></td><td><p>STOPPED</p><p>Tracks</p><p></p><p></p><p></p></td></tr></table>
          21/03/2013 22:00:17 - SonosController - AlarmClock service added for zoneplayer = Dining Room
          21/03/2013 22:00:17 - SonosController - MusicServices service added for zoneplayer = Dining Room
          21/03/2013 22:00:17 - SonosController - AudioIN service added for zoneplayer = Dining Room
          21/03/2013 22:00:17 - SonosController - DeviceProperties service added for zoneplayer = Dining Room
          21/03/2013 22:00:17 - SonosController - SystemProperties service added for zoneplayer = Dining Room
          21/03/2013 22:00:17 - SonosController - ZoneGroupTopology service added for zoneplayer = Dining Room
          21/03/2013 22:00:17 - SonosController - GroupManagement service addedfor zoneplayer = Dining Room
          21/03/2013 22:00:17 - SonosController - RenderingControl service added for zoneplayer = Dining Room
          21/03/2013 22:00:17 - SonosController - Dining Room : Additional Renderer services found with ID = urn:upnp-org:serviceId:ConnectionManager
          21/03/2013 22:00:18 - SonosController - AVTransport service added for zoneplayer = Dining Room
          21/03/2013 22:00:18 - SonosController - ContentDirectory service added for zoneplayer = Dining Room
          21/03/2013 22:00:18 - SonosController - ConnectionManager service added for zoneplayer = Dining Room
          21/03/2013 22:00:18 - SonosController - AvTransportCallback added for zoneplayer = Dining Room
          21/03/2013 22:00:18 - SonosController - RenderingControlCallback added for zoneplayer = Dining Room
          21/03/2013 22:00:18 - SonosController - ZoneGroupTopology ControlCallback added for zoneplayer = Dining Room
          21/03/2013 22:00:18 - SonosController - GroupManagement ControlCallback added for zoneplayer = Dining Room
          21/03/2013 22:00:18 - SonosController - AlarmClock ControlCallback added for zoneplayer = Dining Room
          21/03/2013 22:00:18 - SonosController - MusicServices ControlCallback added for zoneplayer = Dining Room
          21/03/2013 22:00:18 - SonosController - SystemProperties ControlCallback added for zoneplayer = Dining Room
          21/03/2013 22:00:18 - SonosController - ConnectionManagerCallback added for zoneplayer = Dining Room
          21/03/2013 22:00:18 - SonosController - AudioInCallback added for zoneplayer = Dining Room
          21/03/2013 22:00:18 - SonosController - DevicePropertiesCallback added for zoneplayer = Dining Room
          21/03/2013 22:00:18 - SonosController - Service callbacks established for zoneplayer = Dining Room
          21/03/2013 22:00:18 - SonosController - GetVolumeLevel called for ZonePlayer = Dining Room with values Channel=Master
          21/03/2013 22:00:18 - SonosController - DirectConnect for zoneplayer = Dining Room found AudioInputName = Line-In
          21/03/2013 22:00:18 - SonosController - IPAddress for zoneplayer = Dining Room= 192.168.1.41
          21/03/2013 22:00:18 - SonosController - GetcurrentTrackInfo called for zoneplayer - Dining Room
          21/03/2013 22:00:18 - SonosController - MusicServices callback ZonePlayer Dining Room: Var Name = ServiceListVersion Value = RINCON_000E585E89E201400:195
          21/03/2013 22:00:18 - SonosController - Dining Room TrackInfo (CurrentURI) = x-rincon-queue:RINCON_000E585F339A01400#0 and TrackInfo (CurrentURIMetaData) =
          21/03/2013 22:00:18 - SonosController - AlarmClock callback ZonePlayer Dining Room: Var Name = TimeZone Value = 00000a000502000003000501ffc4
          21/03/2013 22:00:18 - SonosController - AlarmClock callback ZonePlayer Dining Room: Var Name = TimeServer Value = 0.sonostime.pool.ntp.org,1.sonostime.pool.ntp.org,2.sonostim e.pool.ntp.org,3.sonostime.pool.ntp.org
          21/03/2013 22:00:18 - SonosController - AlarmClock callback ZonePlayer Dining Room: Var Name = TimeGeneration Value = 57
          21/03/2013 22:00:18 - SonosController - Dining Room TrackInfo (CurrentURI) = x-rincon-queue:RINCON_000E585F339A01400#0
          21/03/2013 22:00:18 - SonosController - Dining Room TrackInfo(Source) = Tracks
          21/03/2013 22:00:18 - SonosController - PlayChangeNotifyCallback called for Zone - Dining Room with ChangeType = SongChanged and Changevalue = stopped
          21/03/2013 22:00:18 - SonosController - PlayChangeNotifyCallback called for Zone - Dining Room with ChangeType = PlayStatusChanged and Changevalue = stopped
          21/03/2013 22:00:18 - SonosController - HS updated in UpdateHS. HS Code = [6 Info = <table><tr><td><img src=/SonosController/images/noart.jpeg></td><td><p>Stop</p><p>Tracks</p><p></p><p></p><p></p></td></tr></table>
          21/03/2013 22:00:18 - SonosController - CreateSonosControllers: Created instance of ZonePlayerController for Zoneplayer = Dining Room with index 6
          21/03/2013 22:00:18 - SonosController - SetHSDeviceCodeTransport called for zone - Living Room with HS Code = [8
          21/03/2013 22:00:18 - SonosController - SetHSDeviceCodeRendering called for zone - Living Room with HS Code =
          21/03/2013 22:00:18 - SonosController - DirectConnect called for Zone Living Room with device name = uuid:RINCON_000E58A2ECD001400 and Model = ZP90
          21/03/2013 22:00:18 - SonosController - DirectConnect for zoneplayer = Living Room found IconURL = http://192.168.1.31:1400/img/icon-ZP90.png
          21/03/2013 22:00:18 - SonosController - Rendering Change callback - ZonePlayer Family Room: Var Name = LastChange Value = <Event xmlns="urn:schemas-upnp-org:metadata-1-0/RCS/"><InstanceID val="0"><Volume channel="Master" val="25"/><Volume channel="LF" val="100"/><Volume channel="RF" val="100"/><Mute channel="Master" val="0"/><Mute channel="LF" val="0"/><Mute channel="RF" val="0"/><Bass val="0"/><Treble val="0"/><Loudness channel="Master" val="1"/><OutputFixed val="0"/><HeadphoneConnected val="0"/><SpeakerSize val="3"/><SubGain val="0"/><SubCrossover val="0"/><SubPolarity val="0"/><SubEnabled val="1"/><PresetNameList>FactoryDefaults</PresetNameList></InstanceID></Event>
          21/03/2013 22:00:18 - SonosController - Family Room : Rendering (Master Volume) = 25
          21/03/2013 22:00:18 - SonosController - Family Room : Rendering (LF Volume) = 100
          21/03/2013 22:00:18 - SonosController - Family Room : Rendering (RF Volume) = 100
          21/03/2013 22:00:18 - SonosController - Family Room : Rendering (Mute Master Volume) = 0
          21/03/2013 22:00:18 - SonosController - Family Room : Rendering (Mute LF Volume) = 0
          21/03/2013 22:00:18 - SonosController - Family Room : Rendering (Mute RF Volume) = 0
          21/03/2013 22:00:18 - SonosController - Family Room : Rendering (Treble) = 0
          21/03/2013 22:00:18 - SonosController - Family Room : Rendering (Bass) = 0
          21/03/2013 22:00:18 - SonosController - Family Room : Rendering (Loudness) = 1
          21/03/2013 22:00:18 - SonosController - Family Room : Rendering (OutputFixed) = 0
          21/03/2013 22:00:18 - SonosController - Family Room : Rendering (SpeakerSize) = 3
          21/03/2013 22:00:18 - SonosController - Family Room : Rendering (SubGain) = 0
          21/03/2013 22:00:18 - SonosController - Family Room : Rendering (SubCrossover) = 0
          21/03/2013 22:00:18 - SonosController - Family Room : Rendering (SubPolarity) = 0
          21/03/2013 22:00:18 - SonosController - Family Room : Rendering (SubEnabled) = 1
          21/03/2013 22:00:18 - SonosController - Living Room : MediaServer Friendly Name = uuid:RINCON_000E58A2ECD001400_MS
          21/03/2013 22:00:18 - SonosController - Living Room : MediaRenderer Friendly Name = Living Room - Sonos CONNECT Media Renderer
          21/03/2013 22:00:18 - SonosController - AlarmClock service added for zoneplayer = Living Room
          21/03/2013 22:00:18 - SonosController - ZonePairingChanged called for Zone = Family Room with ChannelMapSet
          21/03/2013 22:00:18 - SonosController - MusicServices service added for zoneplayer = Living Room
          21/03/2013 22:00:18 - SonosController - AudioIN service added for zoneplayer = Living Room
          21/03/2013 22:00:18 - SonosController - DeviceProperties service added for zoneplayer = Living Room
          21/03/2013 22:00:18 - SonosController - SystemProperties service added for zoneplayer = Living Room
          21/03/2013 22:00:18 - SonosController - ZoneGroupTopology service added for zoneplayer = Living Room
          21/03/2013 22:00:18 - SonosController - GroupManagement service addedfor zoneplayer = Living Room
          21/03/2013 22:00:19 - SonosController - RenderingControl service added for zoneplayer = Living Room
          21/03/2013 22:00:19 - SonosController - Living Room : Additional Renderer services found with ID = urn:upnp-org:serviceId:ConnectionManager
          21/03/2013 22:00:19 - SonosController - AVTransport service added for zoneplayer = Living Room
          21/03/2013 22:00:19 - SonosController - ContentDirectory service added for zoneplayer = Living Room
          21/03/2013 22:00:19 - SonosController - ConnectionManager service added for zoneplayer = Living Room
          21/03/2013 22:00:19 - SonosController - AvTransportCallback added for zoneplayer = Living Room
          21/03/2013 22:00:19 - SonosController - RenderingControlCallback added for zoneplayer = Living Room
          21/03/2013 22:00:19 - SonosController - ZoneGroupTopology ControlCallback added for zoneplayer = Living Room
          21/03/2013 22:00:19 - SonosController - GroupManagement ControlCallback added for zoneplayer = Living Room
          21/03/2013 22:00:19 - SonosController - AlarmClock ControlCallback added for zoneplayer = Living Room
          21/03/2013 22:00:19 - SonosController - MusicServices ControlCallback added for zoneplayer = Living Room
          21/03/2013 22:00:19 - SonosController - SystemProperties ControlCallback added for zoneplayer = Living Room
          21/03/2013 22:00:19 - SonosController - ConnectionManagerCallback added for zoneplayer = Living Room
          21/03/2013 22:00:19 - SonosController - AudioInCallback added for zoneplayer = Living Room
          21/03/2013 22:00:19 - SonosController - DevicePropertiesCallback added for zoneplayer = Living Room
          21/03/2013 22:00:19 - SonosController - Service callbacks established for zoneplayer = Living Room
          21/03/2013 22:00:19 - SonosController - GetVolumeLevel called for ZonePlayer = Living Room with values Channel=Master
          21/03/2013 22:00:19 - SonosController - MusicServices callback ZonePlayer Living Room: Var Name = ServiceListVersion Value = RINCON_000E585E89E201400:195
          21/03/2013 22:00:19 - SonosController - DirectConnect for zoneplayer = Living Room found AudioInputName = Line-In
          21/03/2013 22:00:19 - SonosController - MusicServices callback ZonePlayer Family Room: Var Name = ServiceListVersion Value = RINCON_000E585E89E201400:195
          21/03/2013 22:00:19 - SonosController - IPAddress for zoneplayer = Living Room= 192.168.1.31
          21/03/2013 22:00:19 - SonosController - GetcurrentTrackInfo called for zoneplayer - Living Room
          21/03/2013 22:00:19 - SonosController - Living Room TrackInfo (CurrentURI) = x-rincon-queue:RINCON_000E58A2ECD001400#0 and TrackInfo (CurrentURIMetaData) =
          21/03/2013 22:00:19 - SonosController - Living Room TrackInfo (CurrentURI) = x-rincon-queue:RINCON_000E58A2ECD001400#0
          21/03/2013 22:00:19 - SonosController - Living Room TrackInfo(Source) = Tracks
          21/03/2013 22:00:19 - SonosController - PlayChangeNotifyCallback called for Zone - Living Room with ChangeType = SongChanged and Changevalue = stopped
          21/03/2013 22:00:19 - SonosController - PlayChangeNotifyCallback called for Zone - Living Room with ChangeType = PlayStatusChanged and Changevalue = stopped
          21/03/2013 22:00:19 - SonosController - HS updated in UpdateHS. HS Code = [8 Info = <table><tr><td><img src=/SonosController/images/noart.jpeg></td><td><p>Stop</p><p>Tracks</p><p></p><p></p><p></p></td></tr></table>
          21/03/2013 22:00:19 - SonosController - CreateSonosControllers: Created instance of ZonePlayerController for Zoneplayer = Living Room with index 8
          21/03/2013 22:00:19 - SonosController - WriteSonosNamesToIniFile wrote new string Kitchen;:;ZP90:|:Family Room;:;S5:|ining Room;:;S5:|:Living Room;:;ZP90
          21/03/2013 22:00:19 - SonosController - InitializeSonosDevices: UpdateDevices Controllerref for Kitchen with TDC= [2
          21/03/2013 22:00:19 - SonosController - SetHSDeviceCodeRendering called for zone - Kitchen with HS Code = [3
          21/03/2013 22:00:19 - SonosController - InitializeSonosDevices: UpdateDevices Controllerref for Family Room with TDC= [4
          21/03/2013 22:00:19 - SonosController - SetHSDeviceCodeRendering called for zone - Family Room with HS Code = [5
          21/03/2013 22:00:19 - SonosController - InitializeSonosDevices: UpdateDevices Controllerref for Dining Room with TDC= [6
          21/03/2013 22:00:19 - SonosController - SetHSDeviceCodeRendering called for zone - Dining Room with HS Code = [7
          21/03/2013 22:00:19 - SonosController - InitializeSonosDevices: UpdateDevices Controllerref for Living Room with TDC= [8
          21/03/2013 22:00:19 - SonosController - SetHSDeviceCodeRendering called for zone - Living Room with HS Code = [9
          21/03/2013 22:00:19 - SonosController - CreateWebLink called with ZoneUDN = RINCON_000E58A2EE3801400. ZoneIndex= 1 and PlugIn name = SonosController
          21/03/2013 22:00:19 - SonosController - CreateWebLink called with ZoneUDN = RINCON_000E585E89E201400. ZoneIndex= 2 and PlugIn name = SonosController
          21/03/2013 22:00:19 - SonosController - CreateWebLink called with ZoneUDN = RINCON_000E585F339A01400. ZoneIndex= 3 and PlugIn name = SonosController
          21/03/2013 22:00:19 - SonosController - CreateWebLink called with ZoneUDN = RINCON_000E58A2ECD001400. ZoneIndex= 4 and PlugIn name = SonosController
          21/03/2013 22:00:19 - SonosController - InitializeSonosDevices: Done Initializing Sonos Devices
          21/03/2013 22:00:19 - SonosController - Initialized
          21/03/2013 22:00:19 - Plug-In - Finished initializing plug-in SONOSCONTROLLER
          21/03/2013 22:00:19 - Plug-In - Initializing X10 Plug-in: X10 CM11A/CM12U On COM Port 1
          21/03/2013 22:00:19 - Plug-In - Initializing Plug-in: X10 CM11A/CM12U
          21/03/2013 22:00:19 - Info - Initializing CM11A/12U on COM port 1
          21/03/2013 22:00:19 - Plug-In - Finished initializing X10 interface X10 CM11A/CM12U
          21/03/2013 22:00:19 - Plug-In - Finished initializing infrared interface
          21/03/2013 22:00:19 - Web Server - Local IP address (subnet) is: 192.168.1.13 (255.255.255.0)
          21/03/2013 22:00:19 - Web Server - UPNP Discovery started
          21/03/2013 22:00:19 - Web Server - Web Server started on port 90
          21/03/2013 22:00:19 - Startup - Creating ASP.NET application host...
          21/03/2013 22:00:23 - Startup - ASP.NET application host created
          21/03/2013 22:00:23 - Startup - Start automation engine...
          21/03/2013 22:00:23 - Startup - This version of HomeSeer is registered.
          21/03/2013 22:00:23 - Startup - Initializing Speaker Client Interface
          21/03/2013 22:00:23 - Speech - Listening for remote speaker connections on port 8742
          21/03/2013 22:00:23 - Startup - HS2Sentry is disabled in Setup, sentry will not be started.
          21/03/2013 22:00:23 - Startup - Starting Event Scheduler...
          21/03/2013 22:00:23 - Startup - Checking HTTP connection...
          21/03/2013 22:00:24 - Web Server - Web Server authorized local login successful from: 127.0.0.1 User: Peter
          21/03/2013 22:00:24 - Startup - Updater services starting...
          21/03/2013 22:00:24 - Startup - Starting DDNS service...
          21/03/2013 22:00:24 - HSTouch Server - HomeSeer has started
          21/03/2013 22:00:24 - Startup - Running the Startup.VBS Script
          21/03/2013 22:00:24 - HSTouch Server - Server is enabled for unlimited connections.
          21/03/2013 22:00:24 - HSTouch Server - Server is enabled for iPhone/iPod HSTouch connections.
          21/03/2013 22:00:24 - HSTouch Server - Server is enabled for Android HSTouch connections.
          21/03/2013 22:00:24 - SonosController - Capabilities called. Capabilities are IO and Music
          21/03/2013 22:00:24 - SonosController - GetMusicAPI (Integer) was called with Value: 1
          21/03/2013 22:00:24 - SonosController - LibLoading called for Zone - Kitchen
          21/03/2013 22:00:24 - HSTouch Server - Music plugin SONOSCONTROLLER music library loaded.
          21/03/2013 22:00:24 - HSTouch Server - Server started on port 10200
          21/03/2013 22:00:24 - Startup - Scripting is OK
          21/03/2013 22:00:24 - Startup - Start up complete.
          21/03/2013 22:00:24 - SonosController - SendEventForAllZones called
          21/03/2013 22:00:24 - SonosController - GetMusicAPI (Integer) was called with Value: 1
          21/03/2013 22:00:24 - SonosController - PlayChangeNotifyCallback called for Zone - Kitchen with ChangeType = PlayStatusChanged and Changevalue = paused
          21/03/2013 22:00:24 - SonosController - Capabilities called. Capabilities are IO and Music
          21/03/2013 22:00:24 - SonosController - GetMultiZoneAPI was called
          21/03/2013 22:00:24 - SonosController - NumInstances called. Instances is 4
          21/03/2013 22:00:24 - SonosController - GetMusicAPI (Integer) was called with Value: 1
          21/03/2013 22:00:24 - SonosController - GetMusicAPI (Integer) was called with Value: 2
          21/03/2013 22:00:24 - SonosController - GetMusicAPI (Integer) was called with Value: 3
          21/03/2013 22:00:24 - SonosController - GetMusicAPI (Integer) was called with Value: 4
          21/03/2013 22:00:24 - SonosController - PlayChangeNotifyCallback called for Zone - Kitchen with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly
          21/03/2013 22:00:24 - SonosController - GetMusicAPI (Integer) was called with Value: 2
          21/03/2013 22:00:24 - SonosController - PlayChangeNotifyCallback called for Zone - Family Room with ChangeType = PlayStatusChanged and Changevalue = stopped
          21/03/2013 22:00:24 - SonosController - PlayChangeNotifyCallback called for Zone - Family Room with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly
          21/03/2013 22:00:24 - SonosController - GetMusicAPI (Integer) was called with Value: 3
          21/03/2013 22:00:24 - SonosController - PlayChangeNotifyCallback called for Zone - Dining Room with ChangeType = PlayStatusChanged and Changevalue = stopped
          21/03/2013 22:00:24 - SonosController - PlayChangeNotifyCallback called for Zone - Dining Room with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly
          21/03/2013 22:00:24 - SonosController - GetMusicAPI (Integer) was called with Value: 4
          21/03/2013 22:00:24 - SonosController - PlayChangeNotifyCallback called for Zone - Living Room with ChangeType = PlayStatusChanged and Changevalue = stopped
          21/03/2013 22:00:24 - SonosController - PlayChangeNotifyCallback called for Zone - Living Room with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly
          21/03/2013 22:00:25 - SonosController - SendEventForAllZones called
          21/03/2013 22:00:25 - SonosController - GetMusicAPI (Integer) was called with Value: 1
          21/03/2013 22:00:25 - SonosController - PlayChangeNotifyCallback called for Zone - Kitchen with ChangeType = PlayStatusChanged and Changevalue = paused
          21/03/2013 22:00:25 - SonosController - PlayChangeNotifyCallback called for Zone - Kitchen with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly
          21/03/2013 22:00:25 - SonosController - GetMusicAPI (Integer) was called with Value: 2
          21/03/2013 22:00:25 - SonosController - PlayChangeNotifyCallback called for Zone - Family Room with ChangeType = PlayStatusChanged and Changevalue = stopped
          21/03/2013 22:00:25 - SonosController - PlayChangeNotifyCallback called for Zone - Family Room with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly
          21/03/2013 22:00:25 - SonosController - GetMusicAPI (Integer) was called with Value: 3
          21/03/2013 22:00:25 - SonosController - PlayChangeNotifyCallback called for Zone - Dining Room with ChangeType = PlayStatusChanged and Changevalue = stopped
          21/03/2013 22:00:25 - SonosController - PlayChangeNotifyCallback called for Zone - Dining Room with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly
          21/03/2013 22:00:25 - SonosController - GetMusicAPI (Integer) was called with Value: 4
          21/03/2013 22:00:25 - SonosController - PlayChangeNotifyCallback called for Zone - Living Room with ChangeType = PlayStatusChanged and Changevalue = stopped
          21/03/2013 22:00:25 - SonosController - PlayChangeNotifyCallback called for Zone - Living Room with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly
          21/03/2013 22:00:30 - Network - Speaker client connected from: 127.0.0.1
          21/03/2013 22:00:30 - Speaker - Speaker host added, Name: Automation-PC IP address: 127.0.0.1
          21/03/2013 22:00:37 - Web Server - Web Server authorized local login successful from: 192.168.1.13 User: Peter
          21/03/2013 22:01:04 - Web Server - Web Server authorized local login successful from: 192.168.1.42 User: Peter
          21/03/2013 22:05:23 - SonosController - DoRediscover called
          21/03/2013 22:05:23 - SonosController - FindZonePlayers: Attempting to locate all connected ZonePlayers. This may take up to 9 seconds.
          21/03/2013 22:05:28 - Database - Creating new database, result is Configuration Database Created.
          21/03/2013 22:05:28 - Database - Opening (Mode=Read) up HomeSeer database: \\DESKTOP\Users\Peter\Documents\HomeSeer.mdb
          21/03/2013 22:05:29 - Database - Opening (Mode=Read) up HomeSeer database \\DESKTOP\Users\Peter\Documents\HomeSeer.mdb
          21/03/2013 22:05:30 - Database - HomeSeer configuration database \\DESKTOP\Users\Peter\Documents\HomeSeer.mdb has been closed.
          21/03/2013 22:05:32 - SonosController - FindZonePlayers - Discovery succeeded: 4 ZonePlayer(s) found.
          21/03/2013 22:05:32 - SonosController - Zone Name = Living Room. Zone UDN = uuid:RINCON_000E58A2ECD001400 Friendly Name = 192.168.1.31 - Sonos CONNECT
          21/03/2013 22:05:32 - SonosController - Zone Name = Kitchen. Zone UDN = uuid:RINCON_000E58A2EE3801400 Friendly Name = 192.168.1.32 - Sonos CONNECT
          21/03/2013 22:05:33 - SonosController - Zone Name = Dining Room. Zone UDN = uuid:RINCON_000E585F339A01400 Friendly Name = 192.168.1.41 - Sonos PLAY:5
          21/03/2013 22:05:33 - SonosController - Zone Name = Family Room. Zone UDN = uuid:RINCON_000E585E89E201400 Friendly Name = 192.168.1.30 - Sonos PLAY:5
          21/03/2013 22:05:33 - SonosController - FindZonePlayers - Discovery succeeded: 4 ZonePlayer(s) found.
          21/03/2013 22:05:33 - SonosController - BuildHSSonosDevices: looking For Living Room in ZoneInfo
          21/03/2013 22:05:33 - SonosController - BuildHSSonosDevices: looking For Kitchen in ZoneInfo
          21/03/2013 22:05:33 - SonosController - BuildHSSonosDevices: looking For Dining Room in ZoneInfo
          21/03/2013 22:05:33 - SonosController - BuildHSSonosDevices: looking For Family Room in ZoneInfo
          21/03/2013 22:05:33 - SonosController - CreateSonosControllers: found 9 Device Codes
          21/03/2013 22:05:33 - SonosController - WriteSonosNamesToIniFile wrote new string Kitchen;:;ZP90:|:Family Room;:;S5:|ining Room;:;S5:|:Living Room;:;ZP90
          21/03/2013 22:05:33 - SonosController - SortZonePlayers called. ZoneCount = 9

          Comment


            #6
            Originally posted by dcorsus View Post
            Go to HS setup, make sure you have logging to disk on. Go to Sonos Config screen and turn debug flag on (don't forget to click save). Restart HS, let is all come up (20 seconds or so), post the log file from start of HS to where it ended.

            In parallel, explain to me what players you have, more importantly which are missing and what type are those? Are they wired or wireless. Check your firewall settings and make sure they are not blocked on the HS-PC. Are they all in the same subnet or different subnet? Wireless or wired? What OS does HS run on?

            Thanks

            Dirk
            Here is SONOS Ssytem info, miising two are Office and Master Bedroom

            Associated ZP: 192.168.1.33
            ---------------------------------
            PLAY:5: Dining Room
            Serial Number: 00-0E-58-5F-33-9A:E
            Version: 3.8.4 (build 19460120)
            Hardware Version: 1.16.4.1-2
            IP Address: 192.168.1.41
            OTP: 1.1.1(1-16-4-zp5s-0.5)
            ---------------------------------
            PLAY:5: Family Room
            Serial Number: 00-0E-58-5E-89-E2:1
            Version: 3.8.4 (build 19460120)
            Hardware Version: 1.16.4.1-2
            IP Address: 192.168.1.30
            OTP: 1.1.1(1-16-4-zp5s-0.5)
            ---------------------------------
            CONNECT: Kitchen
            Serial Number: 00-0E-58-A2-EE-38:0
            Version: 3.8.4 (build 19460120)
            Hardware Version: 1.16.5.5-2
            IP Address: 192.168.1.32
            OTP: 1.1.1(1-16-5-zp90-0.8)
            ---------------------------------
            CONNECT: Living Room
            Serial Number: 00-0E-58-A2-EC-D0:E
            Version: 3.8.4 (build 19460120)
            Hardware Version: 1.16.5.5-2
            IP Address: 192.168.1.31
            OTP: 1.1.1(1-16-5-zp90-0.8)
            ---------------------------------
            CONNECT: Master Bedroom
            Serial Number: 00-0E-58-A3-34-84:A
            Version: 3.8.4 (build 19460120)
            Hardware Version: 1.16.5.5-2
            IP Address: 192.168.1.33
            OTP: 1.1.1(1-16-5-zp90-0.8)
            ---------------------------------
            PLAY:5: Office
            Serial Number: 00-0E-58-5D-68-AE:0
            Version: 3.8.4 (build 19460120)
            Hardware Version: 1.16.4.1-2
            IP Address: 192.168.1.34
            OTP: 1.1.1(1-16-4-zp5s-0.5)

            Comment


              #7
              Well the PI and your PC only "sees" 4 players:

              21/03/2013 22:00:12 - SonosController - Zone Name = Kitchen. Zone UDN = uuid:RINCON_000E58A2EE3801400 Friendly Name = 192.168.1.32 - Sonos CONNECT
              21/03/2013 22:00:12 - SonosController - Zone Name = Family Room. Zone UDN = uuid:RINCON_000E585E89E201400 Friendly Name = 192.168.1.30 - Sonos PLAY:5
              21/03/2013 22:00:12 - SonosController - Zone Name = Dining Room. Zone UDN = uuid:RINCON_000E585F339A01400 Friendly Name = 192.168.1.41 - Sonos PLAY:5
              21/03/2013 22:00:12 - SonosController - Zone Name = Living Room. Zone UDN = uuid:RINCON_000E58A2ECD001400 Friendly Name = 192.168.1.31 - Sonos CONNECT

              So something is preventing the SDDP multicast messages from those players not to come through, or they are not responding.

              Are these S5 players part of a stereo pair? Perhaps they are a stereo pair? Did you try to reboot one of them and see if it now shows up.

              In Win7, under devices, you can block specific (UPNP) devices. Are these blocked (go to Control Panel ->Network and Internet->Network and Sharing Center->Advanced Sharing Settings and Media Streaming Options)?

              Dirk

              Comment


                #8
                Originally posted by dcorsus View Post
                Well the PI and your PC only "sees" 4 players:

                21/03/2013 22:00:12 - SonosController - Zone Name = Kitchen. Zone UDN = uuid:RINCON_000E58A2EE3801400 Friendly Name = 192.168.1.32 - Sonos CONNECT
                21/03/2013 22:00:12 - SonosController - Zone Name = Family Room. Zone UDN = uuid:RINCON_000E585E89E201400 Friendly Name = 192.168.1.30 - Sonos PLAY:5
                21/03/2013 22:00:12 - SonosController - Zone Name = Dining Room. Zone UDN = uuid:RINCON_000E585F339A01400 Friendly Name = 192.168.1.41 - Sonos PLAY:5
                21/03/2013 22:00:12 - SonosController - Zone Name = Living Room. Zone UDN = uuid:RINCON_000E58A2ECD001400 Friendly Name = 192.168.1.31 - Sonos CONNECT

                So something is preventing the SDDP multicast messages from those players not to come through, or they are not responding.

                Are these S5 players part of a stereo pair? Perhaps they are a stereo pair? Did you try to reboot one of them and see if it now shows up.

                In Win7, under devices, you can block specific (UPNP) devices. Are these blocked (go to Control Panel ->Network and Internet->Network and Sharing Center->Advanced Sharing Settings and Media Streaming Options)?

                Dirk
                Maybe one more thing to add. The discovery part is part of standard Windows, and Windows only reports 4 players. So the problem is either in one of Windows settings (or firewall/anti virus) or in the network. If you can run the Sonos controller on this PC and it can communicate with all players, then the problem is not in the network. Although I've never seen it with Sonos, the problem could be at the player itself, in which case a boot would solve it but as I said, I've never seen it with Sonos devices but have seen it with many other UPnP (DLNA) devices.

                You can always boot your PC and see if that makes a difference, my top suspicion is still a firewall or Win7 setting issue.

                Dirk

                Comment


                  #9
                  Originally posted by dcorsus View Post
                  Well the PI and your PC only "sees" 4 players:

                  21/03/2013 22:00:12 - SonosController - Zone Name = Kitchen. Zone UDN = uuid:RINCON_000E58A2EE3801400 Friendly Name = 192.168.1.32 - Sonos CONNECT
                  21/03/2013 22:00:12 - SonosController - Zone Name = Family Room. Zone UDN = uuid:RINCON_000E585E89E201400 Friendly Name = 192.168.1.30 - Sonos PLAY:5
                  21/03/2013 22:00:12 - SonosController - Zone Name = Dining Room. Zone UDN = uuid:RINCON_000E585F339A01400 Friendly Name = 192.168.1.41 - Sonos PLAY:5
                  21/03/2013 22:00:12 - SonosController - Zone Name = Living Room. Zone UDN = uuid:RINCON_000E58A2ECD001400 Friendly Name = 192.168.1.31 - Sonos CONNECT

                  So something is preventing the SDDP multicast messages from those players not to come through, or they are not responding.

                  Are these S5 players part of a stereo pair? Perhaps they are a stereo pair? Did you try to reboot one of them and see if it now shows up.

                  In Win7, under devices, you can block specific (UPNP) devices. Are these blocked (go to Control Panel ->Network and Internet->Network and Sharing Center->Advanced Sharing Settings and Media Streaming Options)?

                  Dirk
                  Hi Dirk

                  No they are not a stereo pair. Tried re-booting both but no change. Checked media streaming but nothing blocked.

                  If you run the Sonos supplied utility on the same HS PC it sees all six players and can play to all six with no problem.

                  Your plug-in has been working perfectly for a several months and then suddenly these two players could not be controlled by HST. No one was using any PC when they went down.

                  What do you think?

                  Comment


                    #10
                    Originally posted by dcorsus View Post
                    Maybe one more thing to add. The discovery part is part of standard Windows, and Windows only reports 4 players. So the problem is either in one of Windows settings (or firewall/anti virus) or in the network. If you can run the Sonos controller on this PC and it can communicate with all players, then the problem is not in the network. Although I've never seen it with Sonos, the problem could be at the player itself, in which case a boot would solve it but as I said, I've never seen it with Sonos devices but have seen it with many other UPnP (DLNA) devices.

                    You can always boot your PC and see if that makes a difference, my top suspicion is still a firewall or Win7 setting issue.

                    Dirk
                    Hi Dirk

                    Firewall is turned off. Have reset HS PC.

                    However I have noticed that the HS PC does see all six players under Media Devices in the Networks screen. So if windows sees them here, why do you think the PI doesnt?

                    Comment


                      #11
                      Originally posted by peterford View Post
                      why do you think the PI doesnt?
                      As I said, the PI doesn't discover them, the UPNP component of Windows does, so this is quite a mystery to me ... a bit out of ideas at this point. I need to think about this some more ....

                      Dirk

                      Comment


                        #12
                        Originally posted by dcorsus View Post
                        As I said, the PI doesn't discover them, the UPNP component of Windows does, so this is quite a mystery to me ... a bit out of ideas at this point. I need to think about this some more ....

                        Dirk
                        Hi Dirk

                        OK, thanks for your help so far, much appreciated.

                        It's bedtime here in England, and tomorrow I'm on holiday til next Wednesday, but will try to update this post when I can.

                        Peter

                        Comment


                          #13
                          Originally posted by peterford View Post
                          Hi Dirk

                          Firewall is turned off. Have reset HS PC.

                          However I have noticed that the HS PC does see all six players under Media Devices in the Networks screen. So if windows sees them here, why do you think the PI doesnt?
                          OK, back to basics. Is this a Windows firewall or 3rd party (like McAfee)? Double check allowance/exclusion lists.

                          There is a setting under HS, to allow HS to be discoverable using UPNP. Can you turn that off, restart HS and see if that makes a difference. If you keep the log on, look for the:
                          21/03/2013 22:00:03 - SonosController - FindZonePlayers: Attempting to locate all connected ZonePlayers. This may take up to 9 seconds.
                          21/03/2013 22:00:12 - SonosController - FindZonePlayers - Discovery succeeded: 4 ZonePlayer(s) found.
                          part. Here you can see what Windows reported. If it reports the 4, the problem is still within Windows.

                          On the network side, any routers in between PC and players?

                          I assume you use DHCP to assign IP adresses, are there any devices on the network that have static IP addresses and they don't conflict w/ 192.168.1.33 and .34?

                          Open a browser and type in URL:

                          http://192.168.1.33:1400/status
                          http://192.168.1.34:1400/status

                          Does that work?

                          Dirk

                          Comment


                            #14
                            Originally posted by dcorsus View Post

                            Open a browser and type in URL:

                            http://192.168.1.33:1400/status
                            http://192.168.1.34:1400/status

                            Does that work?

                            Dirk
                            actually what I'm really interested is, is the result of:This would show the DeviceType and if that for some reason has changed, the PI and Win7 wouldn't discover the player
                            <deviceType>urn:schemas-upnp-org:device:ZonePlayer:1</deviceType>
                            Dirk

                            Comment


                              #15
                              Originally posted by dcorsus View Post
                              actually what I'm really interested is, is the result of:This would show the DeviceType and if that for some reason has changed, the PI and Win7 wouldn't discover the player
                              <deviceType>urn:schemas-upnp-org:device:ZonePlayer:1</deviceType>
                              Dirk
                              Hi Dirk

                              Update on this problem: I later noticed all types of problems with other Homeseer events not working and being very slow and unresponsive especially in HST. The four working SONOS players also were incredibly slow to respond in HST.

                              I had a backup of the Homeseer directory from the HS computer and restored that back. Problem solved - everything is back to normal and six players are found.

                              Could file corruption explain this problem?

                              Peter

                              Comment

                              Working...
                              X