Problems with Sonos ver 4.1

[peterford]

Log File:

07/06/2013 22:33:52 Startup Settings Loaded.
07/06/2013 22:33:52 Startup Current sunrise/sunset values calculated.
07/06/2013 22:33:52 Startup HSPhone external interface class started.
07/06/2013 22:33:52 Startup Local voice recognition class started.
07/06/2013 22:33:52 Startup Database SAVE process started.
07/06/2013 22:33:52 Startup Loading configuration file ...
07/06/2013 22:33:53 System Loading device types file...
07/06/2013 22:33:54 Database Opening (Mode=Read) up HomeSeer database: C:\Program Files (x86)\HomeSeer HSPRO\config\sample.mdb
07/06/2013 22:33:54 Database Loading Devices...
07/06/2013 22:33:55 Load Config 140 total devices loaded.
07/06/2013 22:33:55 Load Config Checking device type settings for all devices...
07/06/2013 22:33:55 Load Config Finished checking device type settings.
07/06/2013 22:33:55 Load Config Loading Events...
07/06/2013 22:33:56 Load Config 101 total events loaded.
07/06/2013 22:33:56 Database HomeSeer configuration database C:\Program Files (x86)\HomeSeer HSPRO\config\sample.mdb has been closed.
07/06/2013 22:33:56 Startup HomeSeer version is: 2.5.0.55
07/06/2013 22:33:56 Startup Starting remoting (Remote client connection monitor)
07/06/2013 22:33:56 Startup Creating Speaker Client Interface Object...
07/06/2013 22:33:57 Startup Initializing phone interface...
07/06/2013 22:33:57 HSPhone HomeSeer Phone Web Interface initializing.
07/06/2013 22:33:57 Startup Checking for pending updates to be installed...
07/06/2013 22:33:57 Startup Restoring Device Status...
07/06/2013 22:33:57 Z-Wave :- :- :- :- :- :- :- :- Initializing Z-Wave Interface -: -: -: -: -: -: -: -:
07/06/2013 22:33:57 Z-Wave Initializing Z-Wave Interface on COM port: 3 at 115K baud.
07/06/2013 22:33:57 Z-Wave Getting node information from controller...
07/06/2013 22:33:58 Z-Wave Z-Wave Serial API version: 5
07/06/2013 22:33:58 Z-Wave Z-Wave interface node ID: 1, Home ID: 0161DAD8
07/06/2013 22:33:58 Z-Wave Found 12 Z-Wave nodes in interface node ID 1
07/06/2013 22:33:58 Z-Wave Controller Manufacturer: Aeon Labs, ID=0x1, Type=0x2
07/06/2013 22:33:58 Z-Wave Controller firmware version: 3.7
07/06/2013 22:33:59 Z-Wave This Z-Wave PC Controller Library Version is: Z-Wave 2.78 (ZDK Version 5.02 Patch 3)
07/06/2013 22:33:59 Z-Wave This Z-Wave PC Controller is Type: ZW_LIB_CONTROLLER_STATIC
07/06/2013 22:33:59 Z-Wave Z-Wave services for communications to nodes utilizing the SECURITY COMMAND CLASS were successfully started.
07/06/2013 22:33:59 Z-Wave -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- --
07/06/2013 22:33:59 Startup Checking for available plug-ins
07/06/2013 22:33:59 Plug-In Found plug-in: X10 CM11A/CM12U, version: 2.2.0.4
07/06/2013 22:33:59 Plug-In Found plug-in: X10 CM15AUSB, version: 1.0.0.6
07/06/2013 22:33:59 Plug-In Found plug-in: HSTouch Server, version: 1.0.0.58
07/06/2013 22:33:59 Plug-In Found plug-in: HSTouch Android, version: 1.0.0.2
07/06/2013 22:33:59 Plug-In Found plug-in: HSTouch iPhone, version: 1.0.0.2
07/06/2013 22:33:59 Plug-In Found plug-in: HSTouch Server Unlimited, version: 1.0.0.1
07/06/2013 22:33:59 Plug-In Found plug-in: Insteon, version: 1.1.52.1
07/06/2013 22:34:00 Plug-In Found plug-in: iTunes, version: 2.5.0.12
07/06/2013 22:34:00 Plug-In Found plug-in: Media Player, version: 2.5.0.13
07/06/2013 22:34:00 Plug-In Found plug-in: SONOSCONTROLLER, version: 1.0.0.110
07/06/2013 22:34:00 Plug-In Found plug-in: UPB, version: 1.0.2.7
07/06/2013 22:34:00 Startup Initializing Plug-Ins
07/06/2013 22:34:00 Plug-In Initializing Plug-in: HSTouch Server
07/06/2013 22:34:01 Plug-In Finished initializing plug-in HSTouch Server
07/06/2013 22:34:01 HSTouch Server Unlimited Register callback completed
07/06/2013 22:34:01 Plug-In Initializing Plug-in: HSTouch Server Unlimited
07/06/2013 22:34:01 HSTouch Server Unlimited HSTouch Server Unlimited plugin initialized.
07/06/2013 22:34:01 Plug-In Finished initializing plug-in HSTouch Server Unlimited
07/06/2013 22:34:01 Plug-In Initializing Plug-in: HSTouch iPhone
07/06/2013 22:34:01 Plug-In Finished initializing plug-in HSTouch iPhone
07/06/2013 22:34:01 SonosController Application path: C:\Program Files (x86)\HomeSeer HSPRO
07/06/2013 22:34:01 SonosController Register callback completed
07/06/2013 22:34:01 Plug-In Initializing Plug-in: SONOSCONTROLLER
07/06/2013 22:34:01 SonosController InitIO Called
07/06/2013 22:34:01 SonosController Initializing Sonos Devices
07/06/2013 22:34:01 SonosController Found Housecode in inifile. HouseCode = [
07/06/2013 22:34:01 SonosController BuildButtonString: Found LinkgroupZoneSource = Party
07/06/2013 22:34:01 SonosController BuildButtonValues: Found LinkgroupZoneSource = Party
07/06/2013 22:34:01 SonosController FindZonePlayers: Attempting to locate all connected ZonePlayers. This may take up to 9 seconds.
07/06/2013 22:34:10 SonosController FindZonePlayers - Discovery succeeded: 4 ZonePlayer(s) found.
07/06/2013 22:34:10 SonosController Zone Name = Kitchen. Zone UDN = uuid:RINCON_000E58A2EE3801400 Friendly Name = 192.168.1.34 - Sonos CONNECT
07/06/2013 22:34:10 SonosController Zone Name = Living Room. Zone UDN = uuid:RINCON_000E58A2ECD001400 Friendly Name = 192.168.1.33 - Sonos CONNECT
07/06/2013 22:34:10 SonosController Zone Name = Family Room. Zone UDN = uuid:RINCON_000E585E89E201400 Friendly Name = 192.168.1.32 - Sonos PLAY:5
07/06/2013 22:34:10 SonosController Zone Name = Dining Room. Zone UDN = uuid:RINCON_000E585F339A01400 Friendly Name = 192.168.1.31 - Sonos PLAY:5
07/06/2013 22:34:10 SonosController FindZonePlayers - Discovery succeeded: 4 ZonePlayer(s) found.
07/06/2013 22:34:10 SonosController BuildHSSonosDevices: looking For Kitchen in ZoneInfo
07/06/2013 22:34:10 SonosController BuildHSSonosDevices: Found Zone Name for Transport. Zonename = Kitchen
07/06/2013 22:34:10 SonosController BuildHSSonosDevices:Found Zone Name for Rendering. Zonename = Kitchen
07/06/2013 22:34:10 SonosController BuildHSSonosDevices: looking For Living Room in ZoneInfo
07/06/2013 22:34:10 SonosController BuildHSSonosDevices: Found Zone Name for Transport. Zonename = Living Room
07/06/2013 22:34:10 SonosController BuildHSSonosDevices:Found Zone Name for Rendering. Zonename = Living Room
07/06/2013 22:34:10 SonosController BuildHSSonosDevices: looking For Family Room in ZoneInfo
07/06/2013 22:34:10 SonosController BuildHSSonosDevices: Found Zone Name for Transport. Zonename = Family Room
07/06/2013 22:34:10 SonosController BuildHSSonosDevices:Found Zone Name for Rendering. Zonename = Family Room
07/06/2013 22:34:10 SonosController BuildHSSonosDevices: looking For Dining Room in ZoneInfo
07/06/2013 22:34:10 SonosController BuildHSSonosDevices: Found Zone Name for Transport. Zonename = Dining Room
07/06/2013 22:34:10 SonosController BuildHSSonosDevices:Found Zone Name for Rendering. Zonename = Dining Room
07/06/2013 22:34:10 SonosController SetDeviceStringConnected called
07/06/2013 22:34:10 SonosController SortZonePlayers called. ZoneCount = 9
07/06/2013 22:34:10 SonosController CreateSonosControllers: found 9 Device Codes
07/06/2013 22:34:10 SonosController SetHSDeviceCodeTransport called for zone - Family Room with HS Code = [2
07/06/2013 22:34:11 SonosController DirectConnect called for Zone Family Room with device name = uuid:RINCON_000E585E89E201400 and Model = S5
07/06/2013 22:34:11 SonosController DirectConnect for zoneplayer = Family Room found IconURL = http://192.168.1.32:1400/img/icon-S5.png
07/06/2013 22:34:11 SonosController Family Room : MediaServer Friendly Name = uuid:RINCON_000E585E89E201400_MS
07/06/2013 22:34:11 SonosController Family Room : MediaRenderer Friendly Name = Family Room - Sonos PLAY:5 Media Renderer
07/06/2013 22:34:11 SonosController AlarmClock service added for zoneplayer = Family Room
07/06/2013 22:34:11 SonosController MusicServices service added for zoneplayer = Family Room
07/06/2013 22:34:11 SonosController AudioIN service added for zoneplayer = Family Room
07/06/2013 22:34:11 SonosController DeviceProperties service added for zoneplayer = Family Room
07/06/2013 22:34:11 SonosController SystemProperties service added for zoneplayer = Family Room
07/06/2013 22:34:11 SonosController ZoneGroupTopology service added for zoneplayer = Family Room
07/06/2013 22:34:11 SonosController GroupManagement service addedfor zoneplayer = Family Room
07/06/2013 22:34:11 SonosController RenderingControl service added for zoneplayer = Family Room
07/06/2013 22:34:11 SonosController RenderingControl service added for zoneplayer = Family Room
07/06/2013 22:34:12 SonosController AVTransport service added for zoneplayer = Family Room
07/06/2013 22:34:12 SonosController Error in MediaRenderer.Services for zoneplayer = Family Room. Error=System.Runtime.InteropServices.COMException (0x80040500): Exception from HRESULT: 0x80040500 at System.Runtime.InteropServices.Marshal.ThrowExceptionForHRIn ternal(Int32 errorCode, IntPtr errorInfo) at System.Runtime.InteropServices.CustomMarshalers.EnumeratorVi ewOfEnumVariant.MoveNext() at HSPI_SONOSCONTROLLER.HSMusicAPI.DirectConnect(IUPnPDevice pDevice, String inUDN)
07/06/2013 22:34:12 SonosController ContentDirectory service added for zoneplayer = Family Room
07/06/2013 22:34:12 SonosController ConnectionManager service added for zoneplayer = Family Room
07/06/2013 22:34:12 SonosController AvTransportCallback added for zoneplayer = Family Room
07/06/2013 22:34:12 SonosController RenderingControlCallback added for zoneplayer = Family Room
07/06/2013 22:34:12 SonosController ZoneGroupTopology ControlCallback added for zoneplayer = Family Room
07/06/2013 22:34:12 SonosController GroupManagement ControlCallback added for zoneplayer = Family Room
07/06/2013 22:34:12 SonosController AlarmClock ControlCallback added for zoneplayer = Family Room
07/06/2013 22:34:12 SonosController MusicServices ControlCallback added for zoneplayer = Family Room
07/06/2013 22:34:12 SonosController SystemProperties ControlCallback added for zoneplayer = Family Room
07/06/2013 22:34:12 SonosController ConnectionManagerCallback added for zoneplayer = Family Room
07/06/2013 22:34:12 SonosController AudioInCallback added for zoneplayer = Family Room
07/06/2013 22:34:12 SonosController DevicePropertiesCallback added for zoneplayer = Family Room
07/06/2013 22:34:12 SonosController Service callbacks established for zoneplayer = Family Room
07/06/2013 22:34:12 SonosController GetVolumeLevel called for ZonePlayer = Family Room with values Channel=Master
07/06/2013 22:34:12 SonosController DirectConnect for zoneplayer = Family Room found AudioInputName = Audio Component
07/06/2013 22:34:12 SonosController IPAddress for zoneplayer = Family Room= 192.168.1.32
07/06/2013 22:34:12 SonosController GetcurrentTrackInfo called for zoneplayer - Family Room
07/06/2013 22:34:12 SonosController Family Room TrackInfo (CurrentURI) = x-rincon:RINCON_000E58A2EE3801400 and TrackInfo (CurrentURIMetaData) = http://192.168.1.13:90/SonosController/Announcements/Announcement.jpgHomeSeer Announcementobject.item.audioItem.musicTrackDirk CorsusSonosControllerDirk Corsus
07/06/2013 22:34:12 SonosController Family Room TrackInfo (MediaInfo/Title) = HomeSeer Announcement
07/06/2013 22:34:12 SonosController Family Room TrackInfo (title) = HomeSeer Announcement
07/06/2013 22:34:12 SonosController Family Room TrackInfo (creator) = Dirk Corsus
07/06/2013 22:34:12 SonosController Family Room TrackInfo (album) = SonosController
07/06/2013 22:34:12 SonosController Family Room TrackInfo (albumArtURI) = http://192.168.1.13:90/SonosController/Announcements/Announcement.jpg
07/06/2013 22:34:12 SonosController Family Room TrackInfo (CurrentURI) = x-rincon:RINCON_000E58A2EE3801400
07/06/2013 22:34:12 SonosController Family Room TrackInfo(Source) = Linked
07/06/2013 22:34:12 SonosController HandleLinkedZones called for ZonePlayer = Family Room. LinkedUDN = RINCON_000E58A2EE3801400
07/06/2013 22:34:12 SonosController LinkAZone called with TargetUDN : RINCON_000E585E89E201400 and SourceUDN = RINCON_000E58A2EE3801400
07/06/2013 22:34:12 SonosController Issue within LinkAZone. UDN = RINCON_000E58A2EE3801400 cannot be Found
07/06/2013 22:34:12 SonosController PlayChangeNotifyCallback called for Zone - Family Room with ChangeType = SongChanged and Changevalue = playing and SendDeviceTrigger = True
07/06/2013 22:34:12 SonosController PlayChangeNotifyCallback called for Zone - Family Room with ChangeType = PlayStatusChanged and Changevalue = playing and SendDeviceTrigger = True
07/06/2013 22:34:12 SonosController HS updated in UpdateHS. HS Code = [2 Info = PlayLinkedHomeSeer AnnouncementDirk CorsusSonosController
07/06/2013 22:34:12 SonosController CreateSonosControllers: Created instance of ZonePlayerController for Zoneplayer = Family Room with index 2
07/06/2013 22:34:12 SonosController SetHSDeviceCodeTransport called for zone - Living Room with HS Code = [4
07/06/2013 22:34:12 SonosController DirectConnect called for Zone Living Room with device name = uuid:RINCON_000E58A2ECD001400 and Model = ZP90
07/06/2013 22:34:12 SonosController DirectConnect for zoneplayer = Living Room found IconURL = http://192.168.1.33:1400/img/icon-ZP90.png
07/06/2013 22:34:12 SonosController Living Room : MediaServer Friendly Name = uuid:RINCON_000E58A2ECD001400_MS
07/06/2013 22:34:12 SonosController Living Room : MediaRenderer Friendly Name = Living Room - Sonos CONNECT Media Renderer
07/06/2013 22:34:12 SonosController AlarmClock service added for zoneplayer = Living Room
07/06/2013 22:34:12 SonosController MusicServices service added for zoneplayer = Living Room
07/06/2013 22:34:12 SonosController AudioIN service added for zoneplayer = Living Room
07/06/2013 22:34:12 SonosController DeviceProperties service added for zoneplayer = Living Room
07/06/2013 22:34:12 SonosController SystemProperties service added for zoneplayer = Living Room
07/06/2013 22:34:13 SonosController ZoneGroupTopology service added for zoneplayer = Living Room
07/06/2013 22:34:13 SonosController GroupManagement service addedfor zoneplayer = Living Room
07/06/2013 22:34:13 SonosController RenderingControl service added for zoneplayer = Living Room
07/06/2013 22:34:13 SonosController RenderingControl service added for zoneplayer = Living Room
07/06/2013 22:34:13 SonosController AVTransport service added for zoneplayer = Living Room
07/06/2013 22:34:13 SonosController Error in MediaRenderer.Services for zoneplayer = Living Room. Error=System.Runtime.InteropServices.COMException (0x80040500): Exception from HRESULT: 0x80040500 at System.Runtime.InteropServices.Marshal.ThrowExceptionForHRIn ternal(Int32 errorCode, IntPtr errorInfo) at System.Runtime.InteropServices.CustomMarshalers.EnumeratorVi ewOfEnumVariant.MoveNext() at HSPI_SONOSCONTROLLER.HSMusicAPI.DirectConnect(IUPnPDevice pDevice, String inUDN)
07/06/2013 22:34:13 SonosController ContentDirectory service added for zoneplayer = Living Room
07/06/2013 22:34:13 SonosController ConnectionManager service added for zoneplayer = Living Room
07/06/2013 22:34:13 SonosController AvTransportCallback added for zoneplayer = Living Room
07/06/2013 22:34:13 SonosController RenderingControlCallback added for zoneplayer = Living Room
07/06/2013 22:34:13 SonosController ZoneGroupTopology ControlCallback added for zoneplayer = Living Room
07/06/2013 22:34:13 SonosController GroupManagement ControlCallback added for zoneplayer = Living Room
07/06/2013 22:34:13 SonosController AlarmClock ControlCallback added for zoneplayer = Living Room
07/06/2013 22:34:13 SonosController MusicServices ControlCallback added for zoneplayer = Living Room
07/06/2013 22:34:13 SonosController SystemProperties ControlCallback added for zoneplayer = Living Room
07/06/2013 22:34:13 SonosController ConnectionManagerCallback added for zoneplayer = Living Room
07/06/2013 22:34:13 SonosController AudioInCallback added for zoneplayer = Living Room
07/06/2013 22:34:13 SonosController DevicePropertiesCallback added for zoneplayer = Living Room
07/06/2013 22:34:13 SonosController Service callbacks established for zoneplayer = Living Room
07/06/2013 22:34:13 SonosController GetVolumeLevel called for ZonePlayer = Living Room with values Channel=Master
07/06/2013 22:34:13 SonosController DirectConnect for zoneplayer = Living Room found AudioInputName = Audio Component
07/06/2013 22:34:13 SonosController IPAddress for zoneplayer = Living Room= 192.168.1.33
07/06/2013 22:34:13 SonosController GetcurrentTrackInfo called for zoneplayer - Living Room
07/06/2013 22:34:13 SonosController Living Room TrackInfo (CurrentURI) = x-rincon:RINCON_000E58A2EE3801400 and TrackInfo (CurrentURIMetaData) = http://192.168.1.13:90/SonosController/Announcements/Announcement.jpgHomeSeer Announcementobject.item.audioItem.musicTrackDirk CorsusSonosControllerDirk Corsus
07/06/2013 22:34:13 SonosController Living Room TrackInfo (MediaInfo/Title) = HomeSeer Announcement
07/06/2013 22:34:13 SonosController Living Room TrackInfo (title) = HomeSeer Announcement
07/06/2013 22:34:13 SonosController Living Room TrackInfo (creator) = Dirk Corsus
07/06/2013 22:34:13 SonosController Living Room TrackInfo (album) = SonosController
07/06/2013 22:34:13 SonosController Living Room TrackInfo (albumArtURI) = http://192.168.1.13:90/SonosController/Announcements/Announcement.jpg
07/06/2013 22:34:13 SonosController Living Room TrackInfo (CurrentURI) = x-rincon:RINCON_000E58A2EE3801400
07/06/2013 22:34:13 SonosController Living Room TrackInfo(Source) = Linked
07/06/2013 22:34:13 SonosController HandleLinkedZones called for ZonePlayer = Living Room. LinkedUDN = RINCON_000E58A2EE3801400
07/06/2013 22:34:13 SonosController LinkAZone called with TargetUDN : RINCON_000E58A2ECD001400 and SourceUDN = RINCON_000E58A2EE3801400
07/06/2013 22:34:13 SonosController Issue within LinkAZone. UDN = RINCON_000E58A2EE3801400 cannot be Found
07/06/2013 22:34:13 SonosController UDN not matched in LinkAZone. UDNs found = RINCON_000E585E89E201400
07/06/2013 22:34:13 SonosController PlayChangeNotifyCallback called for Zone - Living Room with ChangeType = SongChanged and Changevalue = playing and SendDeviceTrigger = True
07/06/2013 22:34:13 SonosController PlayChangeNotifyCallback called for Zone - Living Room with ChangeType = PlayStatusChanged and Changevalue = playing and SendDeviceTrigger = True
07/06/2013 22:34:13 SonosController HS updated in UpdateHS. HS Code = [4 Info = PlayLinkedHomeSeer AnnouncementDirk CorsusSonosController
07/06/2013 22:34:13 SonosController CreateSonosControllers: Created instance of ZonePlayerController for Zoneplayer = Living Room with index 4
07/06/2013 22:34:13 SonosController SetHSDeviceCodeTransport called for zone - Dining Room with HS Code = [6
07/06/2013 22:34:13 SonosController DirectConnect called for Zone Dining Room with device name = uuid:RINCON_000E585F339A01400 and Model = S5
07/06/2013 22:34:13 SonosController DirectConnect for zoneplayer = Dining Room found IconURL = http://192.168.1.31:1400/img/icon-S5.png
07/06/2013 22:34:13 SonosController Dining Room : MediaServer Friendly Name = uuid:RINCON_000E585F339A01400_MS
07/06/2013 22:34:13 SonosController Dining Room : MediaRenderer Friendly Name = Dining Room - Sonos PLAY:5 Media Renderer
07/06/2013 22:34:13 SonosController AlarmClock service added for zoneplayer = Dining Room
07/06/2013 22:34:14 SonosController MusicServices service added for zoneplayer = Dining Room
07/06/2013 22:34:14 SonosController AudioIN service added for zoneplayer = Dining Room
07/06/2013 22:34:14 SonosController DeviceProperties service added for zoneplayer = Dining Room
07/06/2013 22:34:14 SonosController SystemProperties service added for zoneplayer = Dining Room
07/06/2013 22:34:14 SonosController ZoneGroupTopology service added for zoneplayer = Dining Room
07/06/2013 22:34:14 SonosController GroupManagement service addedfor zoneplayer = Dining Room
07/06/2013 22:34:14 SonosController RenderingControl service added for zoneplayer = Dining Room
07/06/2013 22:34:14 SonosController RenderingControl service added for zoneplayer = Dining Room
07/06/2013 22:34:14 SonosController AVTransport service added for zoneplayer = Dining Room
07/06/2013 22:34:14 SonosController Error in MediaRenderer.Services for zoneplayer = Dining Room. Error=System.Runtime.InteropServices.COMException (0x80040500): Exception from HRESULT: 0x80040500 at System.Runtime.InteropServices.Marshal.ThrowExceptionForHRIn ternal(Int32 errorCode, IntPtr errorInfo) at System.Runtime.InteropServices.CustomMarshalers.EnumeratorVi ewOfEnumVariant.MoveNext() at HSPI_SONOSCONTROLLER.HSMusicAPI.DirectConnect(IUPnPDevice pDevice, String inUDN)
07/06/2013 22:34:14 SonosController ContentDirectory service added for zoneplayer = Dining Room
07/06/2013 22:34:15 SonosController ConnectionManager service added for zoneplayer = Dining Room
07/06/2013 22:34:15 SonosController AvTransportCallback added for zoneplayer = Dining Room
07/06/2013 22:34:15 SonosController RenderingControlCallback added for zoneplayer = Dining Room
07/06/2013 22:34:15 SonosController ZoneGroupTopology ControlCallback added for zoneplayer = Dining Room
07/06/2013 22:34:15 SonosController GroupManagement ControlCallback added for zoneplayer = Dining Room
07/06/2013 22:34:15 SonosController AlarmClock ControlCallback added for zoneplayer = Dining Room
07/06/2013 22:34:15 SonosController MusicServices ControlCallback added for zoneplayer = Dining Room
07/06/2013 22:34:15 SonosController SystemProperties ControlCallback added for zoneplayer = Dining Room
07/06/2013 22:34:15 SonosController ConnectionManagerCallback added for zoneplayer = Dining Room
07/06/2013 22:34:15 SonosController AudioInCallback added for zoneplayer = Dining Room
07/06/2013 22:34:15 SonosController DevicePropertiesCallback added for zoneplayer = Dining Room
07/06/2013 22:34:15 SonosController Service callbacks established for zoneplayer = Dining Room
07/06/2013 22:34:15 SonosController GetVolumeLevel called for ZonePlayer = Dining Room with values Channel=Master
07/06/2013 22:34:15 SonosController DirectConnect for zoneplayer = Dining Room found AudioInputName = Audio Component
07/06/2013 22:34:15 SonosController IPAddress for zoneplayer = Dining Room= 192.168.1.31
07/06/2013 22:34:15 SonosController GetcurrentTrackInfo called for zoneplayer - Dining Room
07/06/2013 22:34:15 SonosController Dining Room TrackInfo (CurrentURI) = x-rincon:RINCON_000E58A2EE3801400 and TrackInfo (CurrentURIMetaData) = http://192.168.1.13:90/SonosController/Announcements/Announcement.jpgHomeSeer Announcementobject.item.audioItem.musicTrackDirk CorsusSonosControllerDirk Corsus
07/06/2013 22:34:15 SonosController Dining Room TrackInfo (MediaInfo/Title) = HomeSeer Announcement
07/06/2013 22:34:15 SonosController Dining Room TrackInfo (title) = HomeSeer Announcement
07/06/2013 22:34:15 SonosController Dining Room TrackInfo (creator) = Dirk Corsus
07/06/2013 22:34:15 SonosController Dining Room TrackInfo (album) = SonosController
07/06/2013 22:34:15 SonosController Dining Room TrackInfo (albumArtURI) = http://192.168.1.13:90/SonosController/Announcements/Announcement.jpg
07/06/2013 22:34:15 SonosController Dining Room TrackInfo (CurrentURI) = x-rincon:RINCON_000E58A2EE3801400
07/06/2013 22:34:15 SonosController Dining Room TrackInfo(Source) = Linked
07/06/2013 22:34:15 SonosController HandleLinkedZones called for ZonePlayer = Dining Room. LinkedUDN = RINCON_000E58A2EE3801400
07/06/2013 22:34:15 SonosController LinkAZone called with TargetUDN : RINCON_000E585F339A01400 and SourceUDN = RINCON_000E58A2EE3801400
07/06/2013 22:34:15 SonosController Issue within LinkAZone. UDN = RINCON_000E58A2EE3801400 cannot be Found
07/06/2013 22:34:15 SonosController UDN not matched in LinkAZone. UDNs found = RINCON_000E585E89E201400
07/06/2013 22:34:15 SonosController UDN not matched in LinkAZone. UDNs found = RINCON_000E58A2ECD001400
07/06/2013 22:34:15 SonosController PlayChangeNotifyCallback called for Zone - Dining Room with ChangeType = SongChanged and Changevalue = playing and SendDeviceTrigger = True
07/06/2013 22:34:15 SonosController PlayChangeNotifyCallback called for Zone - Dining Room with ChangeType = PlayStatusChanged and Changevalue = playing and SendDeviceTrigger = True
07/06/2013 22:34:15 SonosController HS updated in UpdateHS. HS Code = [6 Info = PlayLinkedHomeSeer AnnouncementDirk CorsusSonosController
07/06/2013 22:34:15 SonosController CreateSonosControllers: Created instance of ZonePlayerController for Zoneplayer = Dining Room with index 6
07/06/2013 22:34:15 SonosController SetHSDeviceCodeTransport called for zone - Kitchen with HS Code = [8
07/06/2013 22:34:15 SonosController DirectConnect called for Zone Kitchen with device name = uuid:RINCON_000E58A2EE3801400 and Model = ZP90
07/06/2013 22:34:15 SonosController DirectConnect for zoneplayer = Kitchen found IconURL = http://192.168.1.34:1400/img/icon-ZP90.png
07/06/2013 22:34:15 SonosController Kitchen : MediaServer Friendly Name = uuid:RINCON_000E58A2EE3801400_MS
07/06/2013 22:34:15 SonosController Kitchen : MediaRenderer Friendly Name = Kitchen - Sonos CONNECT Media Renderer
07/06/2013 22:34:15 SonosController AlarmClock service added for zoneplayer = Kitchen
07/06/2013 22:34:15 SonosController MusicServices service added for zoneplayer = Kitchen
07/06/2013 22:34:15 SonosController AudioIN service added for zoneplayer = Kitchen
07/06/2013 22:34:15 SonosController DeviceProperties service added for zoneplayer = Kitchen
07/06/2013 22:34:15 SonosController SystemProperties service added for zoneplayer = Kitchen
07/06/2013 22:34:15 SonosController ZoneGroupTopology service added for zoneplayer = Kitchen
07/06/2013 22:34:15 SonosController GroupManagement service addedfor zoneplayer = Kitchen
07/06/2013 22:34:15 SonosController RenderingControl service added for zoneplayer = Kitchen
07/06/2013 22:34:15 SonosController RenderingControl service added for zoneplayer = Kitchen
07/06/2013 22:34:16 SonosController AVTransport service added for zoneplayer = Kitchen
07/06/2013 22:34:16 SonosController Error in MediaRenderer.Services for zoneplayer = Kitchen. Error=System.Runtime.InteropServices.COMException (0x80040500): Exception from HRESULT: 0x80040500 at System.Runtime.InteropServices.Marshal.ThrowExceptionForHRIn ternal(Int32 errorCode, IntPtr errorInfo) at System.Runtime.InteropServices.CustomMarshalers.EnumeratorVi ewOfEnumVariant.MoveNext() at HSPI_SONOSCONTROLLER.HSMusicAPI.DirectConnect(IUPnPDevice pDevice, String inUDN)
07/06/2013 22:34:16 SonosController ContentDirectory service added for zoneplayer = Kitchen
07/06/2013 22:34:16 SonosController ConnectionManager service added for zoneplayer = Kitchen
07/06/2013 22:34:16 SonosController AvTransportCallback added for zoneplayer = Kitchen
07/06/2013 22:34:16 SonosController RenderingControlCallback added for zoneplayer = Kitchen
07/06/2013 22:34:16 SonosController ZoneGroupTopology ControlCallback added for zoneplayer = Kitchen
07/06/2013 22:34:16 SonosController GroupManagement ControlCallback added for zoneplayer = Kitchen
07/06/2013 22:34:16 SonosController AlarmClock ControlCallback added for zoneplayer = Kitchen
07/06/2013 22:34:16 SonosController MusicServices ControlCallback added for zoneplayer = Kitchen
07/06/2013 22:34:16 SonosController SystemProperties ControlCallback added for zoneplayer = Kitchen
07/06/2013 22:34:16 SonosController ConnectionManagerCallback added for zoneplayer = Kitchen
07/06/2013 22:34:16 SonosController AudioInCallback added for zoneplayer = Kitchen
07/06/2013 22:34:16 SonosController DevicePropertiesCallback added for zoneplayer = Kitchen
07/06/2013 22:34:16 SonosController Service callbacks established for zoneplayer = Kitchen
07/06/2013 22:34:16 SonosController GetVolumeLevel called for ZonePlayer = Kitchen with values Channel=Master
07/06/2013 22:34:16 SonosController DirectConnect for zoneplayer = Kitchen found AudioInputName = Audio Component
07/06/2013 22:34:16 SonosController IPAddress for zoneplayer = Kitchen= 192.168.1.34
07/06/2013 22:34:16 SonosController GetcurrentTrackInfo called for zoneplayer - Kitchen
07/06/2013 22:34:16 SonosController Kitchen TrackInfo (CurrentURI) = x-sonosapi-stream:s24941?sid=254&flags=32 and TrackInfo (CurrentURIMetaData) = BBC Radio 3object.item.audioItem.audioBroadcastSA_RINCON65031_
07/06/2013 22:34:16 SonosController Kitchen TrackInfo (MediaInfo/Title) = BBC Radio 3
07/06/2013 22:34:16 SonosController Kitchen TrackInfo (title) = BBC Radio 3
07/06/2013 22:34:16 SonosController Kitchen TrackInfo (Internet Radio) =
07/06/2013 22:34:16 SonosController Kitchen TrackInfo (Internet Radio Art) = x-sonosapi-stream:s24941?sid=254&flags=32
07/06/2013 22:34:16 SonosController Kitchen TrackInfo(Source) = Radio
07/06/2013 22:34:16 SonosController GetcurrentTrackInfo called GetPositionInfo for zoneplayer - Kitchen and MetaData aac://bbcmedia.ic.llnwd.net/stream/bbcmedia_lc2_radio3_p?s=1370629775&e=1370644175&h=805b3ff623 d02c4f2cb7b3d527f60981bbcmedia_lc2_radio3_p?s=1370629775&e=1 370644175&h=805b3ff623d02c4f2cb7b3d527f60981object.item
07/06/2013 22:34:16 SonosController Kitchen TrackInfo (Streamcontent/Title) = BBC Radio 3
07/06/2013 22:34:16 SonosController Kitchen TrackInfo (title) = bbcmedia_lc2_radio3_p?s=1370629775&e=1370644175&h=805b3ff623 d02c4f2cb7b3d527f60981
07/06/2013 22:34:16 SonosController GetAlbumArtPath called for zone Kitchen with AlbumURI = http://192.168.1.34:1400/getaa?s=1&u=x-sonosapi-stream:s24941?sid=254&flags=32 and NextTrack = False
07/06/2013 22:34:16 SonosController GetAlbumArtPath returned for Zone - Kitchen with AlbumURI = http://192.168.1.34:1400/getaa?s=1&u=x-sonosapi-stream:s24941?sid=254&flags=32 and returned path= /SonosController/Images/Cover4.jpg
07/06/2013 22:34:16 SonosController Kitchen TrackInfo (Track Position) = 0:00:00
07/06/2013 22:34:16 SonosController Kitchen TrackInfo (Track Position Seconds) = 0
07/06/2013 22:34:16 SonosController Kitchen TrackInfo (Track Duration) = 0:00:00
07/06/2013 22:34:16 SonosController Kitchen TrackInfo (Queue Position) = 1
07/06/2013 22:34:16 SonosController Kitchen TrackInfo (% played) = 100
07/06/2013 22:34:16 SonosController PlayChangeNotifyCallback called for Zone - Kitchen with ChangeType = SongChanged and Changevalue = stopped and SendDeviceTrigger = True
07/06/2013 22:34:16 SonosController PlayChangeNotifyCallback called for Zone - Kitchen with ChangeType = PlayStatusChanged and Changevalue = stopped and SendDeviceTrigger = True
07/06/2013 22:34:16 SonosController HS updated in UpdateHS. HS Code = [8 Info = StopRadiobbcmedia_lc2_radio3_p?s=1370629775&e=1370644175&h=8 05b3ff623d02c4f2cb7b3d527f60981
07/06/2013 22:34:16 SonosController CreateSonosControllers: Created instance of ZonePlayerController for Zoneplayer = Kitchen with index 8
07/06/2013 22:34:16 SonosController WriteSonosNamesToIniFile wrote new string Family Room;:;S5:|:Living Room;:;ZP90:|ining Room;:;S5:|:Kitchen;:;ZP90
07/06/2013 22:34:16 SonosController InitializeSonosDevices is upating the Rendering devices and set Controllerref for Family Room with TDC= [2
07/06/2013 22:34:16 SonosController SetHSDeviceCodeRendering called for zone - Family Room with HS Code = [3
07/06/2013 22:34:16 SonosController InitializeSonosDevices is upating the Rendering devices and set Controllerref for Living Room with TDC= [4
07/06/2013 22:34:16 SonosController SetHSDeviceCodeRendering called for zone - Living Room with HS Code = [5
07/06/2013 22:34:16 SonosController InitializeSonosDevices is upating the Rendering devices and set Controllerref for Dining Room with TDC= [6
07/06/2013 22:34:16 SonosController SetHSDeviceCodeRendering called for zone - Dining Room with HS Code = [7
07/06/2013 22:34:16 SonosController InitializeSonosDevices is upating the Rendering devices and set Controllerref for Kitchen with TDC= [8
07/06/2013 22:34:16 SonosController SetHSDeviceCodeRendering called for zone - Kitchen with HS Code = [9
07/06/2013 22:34:16 SonosController CreateWebLink called with ZoneUDN = RINCON_000E585E89E201400. ZoneIndex= 1 and PlugIn name = SonosController
07/06/2013 22:34:16 SonosController CreateWebLink called with ZoneUDN = RINCON_000E58A2ECD001400. ZoneIndex= 2 and PlugIn name = SonosController
07/06/2013 22:34:16 SonosController CreateWebLink called with ZoneUDN = RINCON_000E585F339A01400. ZoneIndex= 3 and PlugIn name = SonosController
07/06/2013 22:34:16 SonosController CreateWebLink called with ZoneUDN = RINCON_000E58A2EE3801400. ZoneIndex= 4 and PlugIn name = SonosController
07/06/2013 22:34:16 SonosController InitializeSonosDevices: Done Initializing Sonos Devices
07/06/2013 22:34:16 SonosController Initialized
07/06/2013 22:34:16 Plug-In Finished initializing plug-in SONOSCONTROLLER
07/06/2013 22:34:16 Plug-In Initializing X10 Plug-in: X10 CM11A/CM12U On COM Port 1
07/06/2013 22:34:16 Plug-In Initializing Plug-in: X10 CM11A/CM12U
07/06/2013 22:34:17 Info Initializing CM11A/12U on COM port 1
07/06/2013 22:34:17 Plug-In Finished initializing X10 interface X10 CM11A/CM12U
07/06/2013 22:34:17 Plug-In Finished initializing infrared interface
07/06/2013 22:34:17 Web Server Local IP address (subnet) is: 192.168.1.13 (255.255.255.0)
07/06/2013 22:34:17 Web Server UPNP Discovery started
07/06/2013 22:34:17 Web Server Web Server started on port 90
07/06/2013 22:34:17 Startup Creating ASP.NET application host...
07/06/2013 22:34:21 SonosController HandleLinkedZones called for ZonePlayer = Family Room. LinkedUDN = RINCON_000E58A2EE3801400
07/06/2013 22:34:21 SonosController LinkAZone called with TargetUDN : RINCON_000E585E89E201400 and SourceUDN = RINCON_000E58A2EE3801400
07/06/2013 22:34:21 SonosController UDN found in LinkAZone. UDN = RINCON_000E58A2EE3801400 and Zoneplayername = Kitchen
07/06/2013 22:34:21 SonosController AddTargetLinkZone Called for ZoneName Kitchen with new Target UDN List= RINCON_000E585E89E201400
07/06/2013 22:34:21 SonosController GetZoneByUDN called with UDN = RINCON_000E58A2EE3801400
07/06/2013 22:34:21 Startup ASP.NET application host created
07/06/2013 22:34:21 Startup Start automation engine...
07/06/2013 22:34:21 Startup This version of HomeSeer is registered.
07/06/2013 22:34:21 Startup Initializing Speaker Client Interface
07/06/2013 22:34:21 Speech Listening for remote speaker connections on port 8742
07/06/2013 22:34:21 Startup HS2Sentry is disabled in Setup, sentry will not be started.
07/06/2013 22:34:21 Startup Starting Event Scheduler...
07/06/2013 22:34:21 Z-Wave Starting Z-Wave Polling...
07/06/2013 22:34:21 Z-Wave Starting 'Z-Wave Health' Process Starter...
07/06/2013 22:34:21 Startup Checking HTTP connection...
07/06/2013 22:34:21 Web Server Web Server authorized local login successful from: 127.0.0.1 User: Peter
07/06/2013 22:34:21 Startup Updater services starting...
07/06/2013 22:34:21 Startup Starting DDNS service...
07/06/2013 22:34:21 Startup Running the Startup.txt Script
07/06/2013 22:34:21 HSTouch Server HomeSeer has started
07/06/2013 22:34:21 HSTouch Server Server is enabled for unlimited connections.
07/06/2013 22:34:21 HSTouch Server Server is enabled for iPhone/iPod HSTouch connections.
07/06/2013 22:34:21 SonosController Capabilities called. Capabilities are IO and Music
07/06/2013 22:34:21 SonosController GetMusicAPI (Integer) was called with Value: 1
07/06/2013 22:34:21 SonosController LibLoading called for Zone - Family Room
07/06/2013 22:34:21 HSTouch Server Music plugin SONOSCONTROLLER music library loaded.
07/06/2013 22:34:21 HSTouch Server Server started on port 10200
07/06/2013 22:34:21 Startup Scripting is OK and is now running Startup.txt
07/06/2013 22:34:22 Startup Start up complete.
07/06/2013 22:34:22 SonosController SendEventForAllZones called
07/06/2013 22:34:22 SonosController GetMusicAPI (Integer) was called with Value: 1
07/06/2013 22:34:22 SonosController PlayChangeNotifyCallback called for Zone - Family Room with ChangeType = PlayStatusChanged and Changevalue = playing and SendDeviceTrigger = False
07/06/2013 22:34:22 SonosController Capabilities called. Capabilities are IO and Music
07/06/2013 22:34:22 SonosController GetMultiZoneAPI was called
07/06/2013 22:34:22 SonosController NumInstances called. Instances is 4
07/06/2013 22:34:22 SonosController GetMusicAPI (Integer) was called with Value: 1
07/06/2013 22:34:22 SonosController GetMusicAPI (Integer) was called with Value: 2
07/06/2013 22:34:22 SonosController GetMusicAPI (Integer) was called with Value: 3
07/06/2013 22:34:22 SonosController GetMusicAPI (Integer) was called with Value: 4
07/06/2013 22:34:22 SonosController PlayChangeNotifyCallback called for Zone - Family Room with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
07/06/2013 22:34:22 SonosController GetMusicAPI (Integer) was called with Value: 2
07/06/2013 22:34:22 SonosController PlayChangeNotifyCallback called for Zone - Living Room with ChangeType = PlayStatusChanged and Changevalue = playing and SendDeviceTrigger = False
07/06/2013 22:34:22 SonosController PlayChangeNotifyCallback called for Zone - Living Room with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
07/06/2013 22:34:22 SonosController GetMusicAPI (Integer) was called with Value: 3
07/06/2013 22:34:22 SonosController PlayChangeNotifyCallback called for Zone - Dining Room with ChangeType = PlayStatusChanged and Changevalue = playing and SendDeviceTrigger = False
07/06/2013 22:34:22 SonosController PlayChangeNotifyCallback called for Zone - Dining Room with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
07/06/2013 22:34:22 SonosController GetMusicAPI (Integer) was called with Value: 4
07/06/2013 22:34:22 SonosController PlayChangeNotifyCallback called for Zone - Kitchen with ChangeType = PlayStatusChanged and Changevalue = stopped and SendDeviceTrigger = False
07/06/2013 22:34:22 SonosController PlayChangeNotifyCallback called for Zone - Kitchen with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
07/06/2013 22:34:22 SonosController HandleLinkedZones called for ZonePlayer = Living Room. LinkedUDN = RINCON_000E58A2EE3801400
07/06/2013 22:34:22 SonosController LinkAZone called with TargetUDN : RINCON_000E58A2ECD001400 and SourceUDN = RINCON_000E58A2EE3801400
07/06/2013 22:34:22 SonosController UDN found in LinkAZone. UDN = RINCON_000E58A2EE3801400 and Zoneplayername = Kitchen
07/06/2013 22:34:22 SonosController AddTargetLinkZone Called for ZoneName Kitchen with new Target UDN List= RINCON_000E585E89E201400;RINCON_000E58A2ECD001400
07/06/2013 22:34:22 SonosController GetZoneByUDN called with UDN = RINCON_000E58A2EE3801400
07/06/2013 22:34:23 SonosController SendEventForAllZones called
07/06/2013 22:34:23 SonosController GetMusicAPI (Integer) was called with Value: 1
07/06/2013 22:34:23 SonosController PlayChangeNotifyCallback called for Zone - Family Room with ChangeType = PlayStatusChanged and Changevalue = playing and SendDeviceTrigger = False
07/06/2013 22:34:23 SonosController PlayChangeNotifyCallback called for Zone - Family Room with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
07/06/2013 22:34:23 SonosController GetMusicAPI (Integer) was called with Value: 2
07/06/2013 22:34:23 SonosController PlayChangeNotifyCallback called for Zone - Living Room with ChangeType = PlayStatusChanged and Changevalue = playing and SendDeviceTrigger = False
07/06/2013 22:34:23 SonosController PlayChangeNotifyCallback called for Zone - Living Room with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
07/06/2013 22:34:23 SonosController GetMusicAPI (Integer) was called with Value: 3
07/06/2013 22:34:23 SonosController PlayChangeNotifyCallback called for Zone - Dining Room with ChangeType = PlayStatusChanged and Changevalue = playing and SendDeviceTrigger = False
07/06/2013 22:34:23 SonosController PlayChangeNotifyCallback called for Zone - Dining Room with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
07/06/2013 22:34:23 SonosController GetMusicAPI (Integer) was called with Value: 4
07/06/2013 22:34:23 SonosController PlayChangeNotifyCallback called for Zone - Kitchen with ChangeType = PlayStatusChanged and Changevalue = stopped and SendDeviceTrigger = False
07/06/2013 22:34:23 SonosController PlayChangeNotifyCallback called for Zone - Kitchen with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
07/06/2013 22:34:23 SonosController HandleLinkedZones called for ZonePlayer = Dining Room. LinkedUDN = RINCON_000E58A2EE3801400
07/06/2013 22:34:23 SonosController LinkAZone called with TargetUDN : RINCON_000E585F339A01400 and SourceUDN = RINCON_000E58A2EE3801400
07/06/2013 22:34:23 SonosController UDN found in LinkAZone. UDN = RINCON_000E58A2EE3801400 and Zoneplayername = Kitchen
07/06/2013 22:34:23 SonosController AddTargetLinkZone Called for ZoneName Kitchen with new Target UDN List= RINCON_000E585E89E201400;RINCON_000E58A2ECD001400;RINCON_000 E585F339A01400
07/06/2013 22:34:23 SonosController GetZoneByUDN called with UDN = RINCON_000E58A2EE3801400
07/06/2013 22:34:24 HSTouch Server Opening connection from:192.168.1.18/49197
07/06/2013 22:34:27 HSTouch Server Opening connection from:192.168.1.16/49195
07/06/2013 22:34:28 HSTouch Server Client Hall (192.168.1.16/49195) named Hall has CONNECTED.
07/06/2013 22:34:29 HSTouch Server Warning Client dropped connection 192.168.1.18/49197
07/06/2013 22:34:29 HSTouch Server Client (192.168.1.18/49197) has raised LineDisconnected.
07/06/2013 22:34:29 HSTouch Server Client has DISCONNECTED.
07/06/2013 22:34:29 HSTouch Server Opening connection from:192.168.1.18/49198
07/06/2013 22:34:29 SonosController Capabilities called. Capabilities are IO and Music
07/06/2013 22:34:29 HSTouch Server Client Bedroom (192.168.1.18/49198) named Bedroom has CONNECTED.
07/06/2013 22:34:30 SonosController SendEventForAllZones called
07/06/2013 22:34:30 SonosController GetMusicAPI (Integer) was called with Value: 1
07/06/2013 22:34:30 SonosController PlayChangeNotifyCallback called for Zone - Family Room with ChangeType = PlayStatusChanged and Changevalue = playing and SendDeviceTrigger = False
07/06/2013 22:34:30 SonosController PlayChangeNotifyCallback called for Zone - Family Room with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
07/06/2013 22:34:30 SonosController GetMusicAPI (Integer) was called with Value: 2
07/06/2013 22:34:30 SonosController PlayChangeNotifyCallback called for Zone - Living Room with ChangeType = PlayStatusChanged and Changevalue = playing and SendDeviceTrigger = False
07/06/2013 22:34:30 SonosController PlayChangeNotifyCallback called for Zone - Living Room with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
07/06/2013 22:34:30 SonosController GetMusicAPI (Integer) was called with Value: 3
07/06/2013 22:34:30 SonosController PlayChangeNotifyCallback called for Zone - Dining Room with ChangeType = PlayStatusChanged and Changevalue = playing and SendDeviceTrigger = False
07/06/2013 22:34:30 SonosController PlayChangeNotifyCallback called for Zone - Dining Room with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
07/06/2013 22:34:30 SonosController GetMusicAPI (Integer) was called with Value: 4
07/06/2013 22:34:30 SonosController PlayChangeNotifyCallback called for Zone - Kitchen with ChangeType = PlayStatusChanged and Changevalue = stopped and SendDeviceTrigger = False
07/06/2013 22:34:30 SonosController PlayChangeNotifyCallback called for Zone - Kitchen with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
07/06/2013 22:34:31 SonosController Capabilities called. Capabilities are IO and Music
07/06/2013 22:34:31 SonosController SendEventForAllZones called
07/06/2013 22:34:31 SonosController GetMusicAPI (Integer) was called with Value: 1
07/06/2013 22:34:31 SonosController PlayChangeNotifyCallback called for Zone - Family Room with ChangeType = PlayStatusChanged and Changevalue = playing and SendDeviceTrigger = False
07/06/2013 22:34:31 SonosController PlayChangeNotifyCallback called for Zone - Family Room with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
07/06/2013 22:34:31 SonosController GetMusicAPI (Integer) was called with Value: 2
07/06/2013 22:34:31 SonosController PlayChangeNotifyCallback called for Zone - Living Room with ChangeType = PlayStatusChanged and Changevalue = playing and SendDeviceTrigger = False
07/06/2013 22:34:31 SonosController PlayChangeNotifyCallback called for Zone - Living Room with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
07/06/2013 22:34:31 SonosController GetMusicAPI (Integer) was called with Value: 3
07/06/2013 22:34:31 SonosController PlayChangeNotifyCallback called for Zone - Dining Room with ChangeType = PlayStatusChanged and Changevalue = playing and SendDeviceTrigger = False
07/06/2013 22:34:31 SonosController PlayChangeNotifyCallback called for Zone - Dining Room with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
07/06/2013 22:34:31 SonosController GetMusicAPI (Integer) was called with Value: 4
07/06/2013 22:34:31 SonosController PlayChangeNotifyCallback called for Zone - Kitchen with ChangeType = PlayStatusChanged and Changevalue = stopped and SendDeviceTrigger = False
07/06/2013 22:34:31 SonosController PlayChangeNotifyCallback called for Zone - Kitchen with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
07/06/2013 22:34:36 Web Server Web Server authorized local login successful from: 192.168.1.13 User: Peter
07/06/2013 22:34:43 HSTouch Server Opening connection from:192.168.1.20/51371
07/06/2013 22:34:43 HSTouch Server Client Kitchen (192.168.1.20/51371) named Kitchen has CONNECTED.
07/06/2013 22:34:44 SonosController Capabilities called. Capabilities are IO and Music
07/06/2013 22:34:45 SonosController SendEventForAllZones called
07/06/2013 22:34:45 SonosController GetMusicAPI (Integer) was called with Value: 1
07/06/2013 22:34:45 SonosController PlayChangeNotifyCallback called for Zone - Family Room with ChangeType = PlayStatusChanged and Changevalue = playing and SendDeviceTrigger = False
07/06/2013 22:34:45 SonosController PlayChangeNotifyCallback called for Zone - Family Room with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
07/06/2013 22:34:45 SonosController GetMusicAPI (Integer) was called with Value: 2
07/06/2013 22:34:45 SonosController PlayChangeNotifyCallback called for Zone - Living Room with ChangeType = PlayStatusChanged and Changevalue = playing and SendDeviceTrigger = False
07/06/2013 22:34:45 SonosController PlayChangeNotifyCallback called for Zone - Living Room with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
07/06/2013 22:34:45 SonosController GetMusicAPI (Integer) was called with Value: 3
07/06/2013 22:34:45 SonosController PlayChangeNotifyCallback called for Zone - Dining Room with ChangeType = PlayStatusChanged and Changevalue = playing and SendDeviceTrigger = False
07/06/2013 22:34:45 SonosController PlayChangeNotifyCallback called for Zone - Dining Room with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
07/06/2013 22:34:45 SonosController GetMusicAPI (Integer) was called with Value: 4
07/06/2013 22:34:45 SonosController PlayChangeNotifyCallback called for Zone - Kitchen with ChangeType = PlayStatusChanged and Changevalue = stopped and SendDeviceTrigger = False
07/06/2013 22:34:45 SonosController PlayChangeNotifyCallback called for Zone - Kitchen with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
07/06/2013 22:34:46 Network Speaker client connected from: 127.0.0.1
07/06/2013 22:34:46 Speaker Speaker host added, Name: Automation-PC IP address: 127.0.0.1
07/06/2013 22:35:26 Device Update Device: Z-Wave Z-Wave Interface Child 4 Temperature Inst 1 value set to 17.4 (1740/C)
07/06/2013 22:35:26 Event Event Trigger "Thermostat 2 Scan"
07/06/2013 22:35:26 Event Running script in background: Heat2_Scan.vbs
07/06/2013 22:35:26 Device Control Command sent to Ground Floor Conservatory Thermostat Demand: T29 off Dimval: 0 Data: 0
07/06/2013 22:35:26 Device Control Command sent to Ground Floor Conservatory Heating: C10 off Dimval: 0 Data: 0
07/06/2013 22:35:27 Device Update Device: Z-Wave Z-Wave Interface Child 4 Relative Humidity Inst 2 value set to 62 (6200/%)
07/06/2013 22:36:22 Web Server Web Server authorized local login successful from: 192.168.1.37 User: Peter

[peterford]

Hi Dirk

Sorry, posted Log file to wrong thread initially.

To answer your points:

The original problem from the 'Losing SONOS Devices thread' seemed to fix itself by copying a backup of the sample.mdb file into the HS directory. Doing the same this time makes no difference.

HS speed this time seems fine as does SONOS control of the remaining 4 controllers via HSTouch.

HS version is 2.5.0.55

HS is not hogging the cpu, the only process that has a high value is 'System Idle Process' at 99 whatever that is.

Yes its the same two players (both Player 5s)

Cant think what is different between the players, all are wireless except the Office Player 5 which is linked directly to router by CAT6 cable. This office one is one of the players that is not seen anymore.

Re your 5 points on your last post, the only thing I have done was to go back to a HS config file from 2 days ago when I was having these problems anyway.

Peter

[peterford]

Hi Dirk

Just realised a mistake in one of my posts, for the missing two players, one is a CONNECT the other is a Play : 5

I have just noticed one thing though if you restart the PC that HS is on, and immediately look at Windows/Network/Media Devices only 4 players are seen. However if you wait 5 minutes or so then all 6 appear. BUT if you wait for all 6 to appear before HS is started then the problem still persists.

Peter

[dcorsus]

The original problem from the 'Losing SONOS Devices thread' seemed to fix itself by copying a backup of the sample.mdb file into the HS directory. Doing the same this time makes no difference.

I suspect, this is how you lost your 2 players by going to an older version that doesn't have the players in the HS DB ... so we need to fix the incompatibility between HS and Sonos PI first.

Don't remember you had a lot of linkgroups and stuff, if not, easiest way is to stop HS, delete the HSPI_Sonoscontroller.ini file, restart HS and the PI will delete all the HS devices and recreate them.

Here is one theory I have: the discovery is a based on broadcasting/multicasting in the network. Lets just say that something in your network causes some of the broadcasts to be dropped. It could explain why the 2 players seem to be showing up later ....

So perhaps the discovery is hit-and-miss, if you leave the rediscovery on, they should eventually be rediscovered ... unless ... your DB is out of whack. If the devices were deleted from HS but the PI doesn't know, the PI won't recreate the HS devices.

So one test is to delete the .ini file, have it recreate everything, keep the debug flag on, hit the "do rediscovery" button a couple of time (at least 20~30 seconds in between) and check whether they eventually come in. Maybe leave it overnight, would be interesting to see that the 10 minute rediscovery eventually picks them up.

Another thing to check is your DHCP lease time settings (check the DHCP table in your router setup). How long do your IP addresses last? If the IP addresses change, the PI can deal with that, provided the discovery process works properly. If the IP addresses have changed (perhaps because you power down the player for lets say more than a day) and the rediscovery is either off or "disturbed", I can see a scenario where a player never gets added. If you do power down players regularly, you MUST keep the rediscovery on or create "sticky" IP addresses (IP@ assigned to specific MAC@) in your router!

Dirk

[peterford]

Hi Dirk

Ok have stopped HS deleted the HSPI_SONOSCONTROLLER.ini restarted, pressed Rediscover Players a couple of times, still no show, so will leave overnight.

I do regularly power down 3 of the players although 1 of the missing players is not one of these, the other is. I haven't bound IP address to MAC address so will do this to help stop this problem reappearing - although this is only going to be useful if this current problem is solve.

IP Lease times are not a problem.

Will leave overnight - now 00:45 am in UK so off to bed and see what HS is up to in the morning.

Peter

[dcorsus]

just posted v.113 to take care of the start-up error
Dirk

[peterford]

Hi Dirk

Thanks for update, and also thanks for your speedy and helpful Replies

Peter