Problems with Sonos ver 4.1

[peterford]

Made the mistake of upgrading to 4.1, now every time I start Homeseer I get the following errors for each and every player, any ideas?

30/052013 19:11:36 Plug-In Found plug-in: SONOSCONTROLLER, version: 1.0.0.11030/05/2013 19:11:37 Plug-In Found plug-in: UPB, version: 1.0.2.730/05/2013 19:11:37 Startup Initializing Plug-Ins30/05/2013 19:11:37 Plug-In Initializing Plug-in: HSTouch Server30/05/2013 19:11:37 Plug-In Finished initializing plug-in HSTouch Server30/05/2013 19:11:37 HSTouch Server Unlimited Register callback completed30/05/2013 19:11:37 Plug-In Initializing Plug-in: HSTouch Server Unlimited30/05/2013 19:11:37 HSTouch Server Unlimited HSTouch Server Unlimited plugin initialized.30/05/2013 19:11:37 Plug-In Finished initializing plug-in HSTouch Server Unlimited30/05/2013 19:11:37 Plug-In Initializing Plug-in: HSTouch iPhone30/05/2013 19:11:37 Plug-In Finished initializing plug-in HSTouch iPhone30/05/2013 19:11:37 SonosController Application path: C:\Program Files (x86)\HomeSeer HSPRO30/05/2013 19:11:37 SonosController Register callback completed30/05/2013 19:11:37 Plug-In Initializing Plug-in: SONOSCONTROLLER30/05/2013 19:11:37 SonosController InitIO Called30/05/2013 19:11:47 SonosController DirectConnect called for Zone Family Room with device name = uuid:RINCON_000E585E89E201400 and Model = S530/05/2013 19:11:48 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)30/05/2013 19:11:48 SonosController AvTransportCallback added for zoneplayer = Family Room30/05/2013 19:11:48 SonosController RenderingControlCallback added for zoneplayer = Family Room30/05/2013 19:11:48 SonosController ZoneGroupTopology ControlCallback added for zoneplayer = Family Room30/05/2013 19:11:48 SonosController GroupManagement ControlCallback added for zoneplayer = Family Room30/05/2013 19:11:48 SonosController AlarmClock ControlCallback added for zoneplayer = Family Room30/05/2013 19:11:48 SonosController AudioInCallback added for zoneplayer = Family Room30/05/2013 19:11:48 SonosController DevicePropertiesCallback added for zoneplayer = Family Room30/05/2013 19:11:48 SonosController Service callbacks established for zoneplayer = Family Room30/05/2013 19:11:48 SonosController DirectConnect for zoneplayer = Family Room found AudioInputName = Audio Component30/05/2013 19:11:48 SonosController IPAddress for zoneplayer = Family Room= 192.168.1.4430/05/2013 19:11:49 SonosController Issue within LinkAZone. UDN = RINCON_000E58A2EE3801400 cannot be Found30/05/2013 19:11:49 SonosController CreateSonosControllers: Created instance of ZonePlayerController for Zoneplayer = Family Room with index 2

[peterford]

Sorry that looks a mess, hope this formats better:

30/05/2013 19:11:36 Plug-In Found plug-in: SONOSCONTROLLER, version: 1.0.0.110
30/05/2013 19:11:37 Plug-In Found plug-in: UPB, version: 1.0.2.7
30/05/2013 19:11:37 Startup Initializing Plug-Ins
30/05/2013 19:11:37 Plug-In Initializing Plug-in: HSTouch Server
30/05/2013 19:11:37 Plug-In Finished initializing plug-in HSTouch Server
30/05/2013 19:11:37 HSTouch Server Unlimited Register callback completed
30/05/2013 19:11:37 Plug-In Initializing Plug-in: HSTouch Server Unlimited
30/05/2013 19:11:37 HSTouch Server Unlimited HSTouch Server Unlimited plugin initialized.
30/05/2013 19:11:37 Plug-In Finished initializing plug-in HSTouch Server Unlimited
30/05/2013 19:11:37 Plug-In Initializing Plug-in: HSTouch iPhone
30/05/2013 19:11:37 Plug-In Finished initializing plug-in HSTouch iPhone
30/05/2013 19:11:37 SonosController Application path: C:\Program Files (x86)\HomeSeer HSPRO
30/05/2013 19:11:37 SonosController Register callback completed
30/05/2013 19:11:37 Plug-In Initializing Plug-in: SONOSCONTROLLER
30/05/2013 19:11:37 SonosController InitIO Called
30/05/2013 19:11:47 SonosController DirectConnect called for Zone Family Room with device name = uuid:RINCON_000E585E89E201400 and Model = S5
30/05/2013 19:11:48 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)
30/05/2013 19:11:48 SonosController AvTransportCallback added for zoneplayer = Family Room
30/05/2013 19:11:48 SonosController RenderingControlCallback added for zoneplayer = Family Room
30/05/2013 19:11:48 SonosController ZoneGroupTopology ControlCallback added for zoneplayer = Family Room
30/05/2013 19:11:48 SonosController GroupManagement ControlCallback added for zoneplayer = Family Room
30/05/2013 19:11:48 SonosController AlarmClock ControlCallback added for zoneplayer = Family Room
30/05/2013 19:11:48 SonosController AudioInCallback added for zoneplayer = Family Room
30/05/2013 19:11:48 SonosController DevicePropertiesCallback added for zoneplayer = Family Room
30/05/2013 19:11:48 SonosController Service callbacks established for zoneplayer = Family Room
30/05/2013 19:11:48 SonosController DirectConnect for zoneplayer = Family Room found AudioInputName = Audio Component
30/05/2013 19:11:48 SonosController IPAddress for zoneplayer = Family Room= 192.168.1.44
30/05/2013 19:11:49 SonosController Issue within LinkAZone. UDN = RINCON_000E58A2EE3801400 cannot be Found
30/05/2013 19:11:49 SonosController CreateSonosControllers: Created instance of ZonePlayerController for Zoneplayer = Family Room with index 2

[dcorsus]

Made the mistake of upgrading to 4.1, now every time I start Homeseer I get the following errors for each and every player, any ideas?

Anything not working?

Could you turn the plugin's debug flag on, restart HS and capture the whole log.

Thanks

Dirk

[peterford]

Hi Dirk

Many thanks for your reply.

Everything seems ok, might be my imagination but it seems a little slow and unresponsive.

Part 1 of log file (too long for 1 post):

31/05/2013 07:57:05 Startup Settings Loaded.
31/05/2013 07:57:05 Startup Current sunrise/sunset values calculated.
31/05/2013 07:57:05 Startup HSPhone external interface class started.
31/05/2013 07:57:05 Startup Local voice recognition class started.
31/05/2013 07:57:06 Startup Database SAVE process started.
31/05/2013 07:57:06 Startup Loading configuration file ...
31/05/2013 07:57:06 System Loading device types file...
31/05/2013 07:57:07 Database Opening (Mode=Read) up HomeSeer database: C:\Program Files (x86)\HomeSeer HSPRO\config\sample.mdb
31/05/2013 07:57:07 Database Loading Devices...
31/05/2013 07:57:09 Load Config 144 total devices loaded.
31/05/2013 07:57:09 Load Config Checking device type settings for all devices...
31/05/2013 07:57:09 Load Config Finished checking device type settings.
31/05/2013 07:57:09 Load Config Loading Events...
31/05/2013 07:57:09 Load Config 101 total events loaded.
31/05/2013 07:57:09 Database HomeSeer configuration database C:\Program Files (x86)\HomeSeer HSPRO\config\sample.mdb has been closed.
31/05/2013 07:57:09 Startup HomeSeer version is: 2.5.0.55
31/05/2013 07:57:09 Startup Starting remoting (Remote client connection monitor)
31/05/2013 07:57:10 Startup Creating Speaker Client Interface Object...
31/05/2013 07:57:10 Startup Initializing phone interface...
31/05/2013 07:57:10 HSPhone HomeSeer Phone Web Interface initializing.
31/05/2013 07:57:10 Startup Checking for pending updates to be installed...
31/05/2013 07:57:10 Startup Restoring Device Status...
31/05/2013 07:57:10 Z-Wave :- :- :- :- :- :- :- :- Initializing Z-Wave Interface -: -: -: -: -: -: -: -:
31/05/2013 07:57:10 Z-Wave Initializing Z-Wave Interface on COM port: 3 at 115K baud.
31/05/2013 07:57:10 Z-Wave Getting node information from controller...
31/05/2013 07:57:11 Z-Wave Z-Wave Serial API version: 5
31/05/2013 07:57:11 Z-Wave Z-Wave interface node ID: 1, Home ID: 0161DAD8
31/05/2013 07:57:11 Z-Wave Found 12 Z-Wave nodes in interface node ID 1
31/05/2013 07:57:11 Z-Wave Controller Manufacturer: UNKNOWN, ID=0x0, Type=0x0
31/05/2013 07:57:11 Z-Wave Controller firmware version: 5.40
31/05/2013 07:57:12 Z-Wave This Z-Wave PC Controller Library Version is: Z-Wave 2.78 (ZDK Version 5.02 Patch 3)
31/05/2013 07:57:12 Z-Wave This Z-Wave PC Controller is Type: ZW_LIB_CONTROLLER_STATIC
31/05/2013 07:57:12 Z-Wave Z-Wave services for communications to nodes utilizing the SECURITY COMMAND CLASS were successfully started.
31/05/2013 07:57:12 Z-Wave -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- --
31/05/2013 07:57:12 Startup Checking for available plug-ins
31/05/2013 07:57:12 Plug-In Found plug-in: X10 CM11A/CM12U, version: 2.2.0.4
31/05/2013 07:57:12 Plug-In Found plug-in: X10 CM15AUSB, version: 1.0.0.6
31/05/2013 07:57:12 Plug-In Found plug-in: HSTouch Server, version: 1.0.0.58
31/05/2013 07:57:13 Plug-In Found plug-in: HSTouch Android, version: 1.0.0.2
31/05/2013 07:57:13 Plug-In Found plug-in: HSTouch iPhone, version: 1.0.0.2
31/05/2013 07:57:13 Plug-In Found plug-in: HSTouch Server Unlimited, version: 1.0.0.1
31/05/2013 07:57:13 Plug-In Found plug-in: Insteon, version: 1.1.52.1
31/05/2013 07:57:13 Plug-In Found plug-in: iTunes, version: 2.5.0.12
31/05/2013 07:57:13 Plug-In Found plug-in: Media Player, version: 2.5.0.13
31/05/2013 07:57:13 Plug-In Found plug-in: SONOSCONTROLLER, version: 1.0.0.110
31/05/2013 07:57:13 Plug-In Found plug-in: UPB, version: 1.0.2.7
31/05/2013 07:57:13 Startup Initializing Plug-Ins
31/05/2013 07:57:14 Plug-In Initializing Plug-in: HSTouch Server
31/05/2013 07:57:14 Plug-In Finished initializing plug-in HSTouch Server
31/05/2013 07:57:14 HSTouch Server Unlimited Register callback completed
31/05/2013 07:57:14 Plug-In Initializing Plug-in: HSTouch Server Unlimited
31/05/2013 07:57:14 HSTouch Server Unlimited HSTouch Server Unlimited plugin initialized.
31/05/2013 07:57:14 Plug-In Finished initializing plug-in HSTouch Server Unlimited
31/05/2013 07:57:14 Plug-In Initializing Plug-in: HSTouch iPhone
31/05/2013 07:57:14 Plug-In Finished initializing plug-in HSTouch iPhone
31/05/2013 07:57:14 SonosController Application path: C:\Program Files (x86)\HomeSeer HSPRO
31/05/2013 07:57:14 SonosController Register callback completed
31/05/2013 07:57:14 Plug-In Initializing Plug-in: SONOSCONTROLLER
31/05/2013 07:57:14 SonosController InitIO Called
31/05/2013 07:57:14 SonosController Initializing Sonos Devices
31/05/2013 07:57:14 SonosController Found Housecode in inifile. HouseCode = [
31/05/2013 07:57:14 SonosController BuildButtonString: Found LinkgroupZoneSource = Party
31/05/2013 07:57:14 SonosController BuildButtonValues: Found LinkgroupZoneSource = Party
31/05/2013 07:57:14 SonosController FindZonePlayers: Attempting to locate all connected ZonePlayers. This may take up to 9 seconds.
31/05/2013 07:57:23 SonosController FindZonePlayers - Discovery succeeded: 6 ZonePlayer(s) found.
31/05/2013 07:57:23 SonosController Zone Name = Master Bedroom. Zone UDN = uuid:RINCON_000E58A3348401400 Friendly Name = 192.168.1.43 - Sonos CONNECT
31/05/2013 07:57:23 SonosController Zone Name = Kitchen. Zone UDN = uuid:RINCON_000E58A2EE3801400 Friendly Name = 192.168.1.38 - Sonos CONNECT
31/05/2013 07:57:23 SonosController Zone Name = Dining Room. Zone UDN = uuid:RINCON_000E585F339A01400 Friendly Name = 192.168.1.46 - Sonos PLAY:5
31/05/2013 07:57:23 SonosController Zone Name = Office. Zone UDN = uuid:RINCON_000E585D68AE01400 Friendly Name = 192.168.1.30 - Sonos PLAY:5
31/05/2013 07:57:24 SonosController Zone Name = Living Room. Zone UDN = uuid:RINCON_000E58A2ECD001400 Friendly Name = 192.168.1.42 - Sonos CONNECT
31/05/2013 07:57:24 SonosController Zone Name = Family Room. Zone UDN = uuid:RINCON_000E585E89E201400 Friendly Name = 192.168.1.44 - Sonos PLAY:5
31/05/2013 07:57:24 SonosController FindZonePlayers - Discovery succeeded: 6 ZonePlayer(s) found.
31/05/2013 07:57:24 SonosController BuildHSSonosDevices: looking For Master Bedroom in ZoneInfo
31/05/2013 07:57:24 SonosController BuildHSSonosDevices: Found Zone Name for Transport. Zonename = Master Bedroom
31/05/2013 07:57:24 SonosController BuildHSSonosDevices:Found Zone Name for Rendering. Zonename = Master Bedroom
31/05/2013 07:57:24 SonosController BuildHSSonosDevices: looking For Kitchen in ZoneInfo
31/05/2013 07:57:24 SonosController BuildHSSonosDevices: Found Zone Name for Transport. Zonename = Kitchen
31/05/2013 07:57:24 SonosController BuildHSSonosDevices:Found Zone Name for Rendering. Zonename = Kitchen
31/05/2013 07:57:24 SonosController BuildHSSonosDevices: looking For Dining Room in ZoneInfo
31/05/2013 07:57:24 SonosController BuildHSSonosDevices: Found Zone Name for Transport. Zonename = Dining Room
31/05/2013 07:57:24 SonosController BuildHSSonosDevices:Found Zone Name for Rendering. Zonename = Dining Room
31/05/2013 07:57:24 SonosController BuildHSSonosDevices: looking For Office in ZoneInfo
31/05/2013 07:57:24 SonosController BuildHSSonosDevices: Found Zone Name for Transport. Zonename = Office
31/05/2013 07:57:24 SonosController BuildHSSonosDevices:Found Zone Name for Rendering. Zonename = Office
31/05/2013 07:57:24 SonosController BuildHSSonosDevices: looking For Living Room in ZoneInfo
31/05/2013 07:57:24 SonosController BuildHSSonosDevices: Found Zone Name for Transport. Zonename = Living Room
31/05/2013 07:57:24 SonosController BuildHSSonosDevices:Found Zone Name for Rendering. Zonename = Living Room
31/05/2013 07:57:24 SonosController BuildHSSonosDevices: looking For Family Room in ZoneInfo
31/05/2013 07:57:24 SonosController BuildHSSonosDevices: Found Zone Name for Transport. Zonename = Family Room
31/05/2013 07:57:24 SonosController BuildHSSonosDevices:Found Zone Name for Rendering. Zonename = Family Room
31/05/2013 07:57:24 SonosController SetDeviceStringConnected called
31/05/2013 07:57:24 SonosController SortZonePlayers called. ZoneCount = 13
31/05/2013 07:57:24 SonosController CreateSonosControllers: found 13 Device Codes
31/05/2013 07:57:24 SonosController SetHSDeviceCodeTransport called for zone - Family Room with HS Code = [2
31/05/2013 07:57:24 SonosController DirectConnect called for Zone Family Room with device name = uuid:RINCON_000E585E89E201400 and Model = S5
31/05/2013 07:57:24 SonosController DirectConnect for zoneplayer = Family Room found IconURL = http://192.168.1.44:1400/img/icon-S5.png
31/05/2013 07:57:24 SonosController Family Room : MediaServer Friendly Name = uuid:RINCON_000E585E89E201400_MS
31/05/2013 07:57:24 SonosController Family Room : MediaRenderer Friendly Name = Family Room - Sonos PLAY:5 Media Renderer
31/05/2013 07:57:24 SonosController AlarmClock service added for zoneplayer = Family Room
31/05/2013 07:57:24 SonosController MusicServices service added for zoneplayer = Family Room
31/05/2013 07:57:24 SonosController AudioIN service added for zoneplayer = Family Room
31/05/2013 07:57:24 SonosController DeviceProperties service added for zoneplayer = Family Room
31/05/2013 07:57:24 SonosController SystemProperties service added for zoneplayer = Family Room
31/05/2013 07:57:24 SonosController ZoneGroupTopology service added for zoneplayer = Family Room
31/05/2013 07:57:24 SonosController GroupManagement service addedfor zoneplayer = Family Room
31/05/2013 07:57:25 SonosController RenderingControl service added for zoneplayer = Family Room
31/05/2013 07:57:25 SonosController RenderingControl service added for zoneplayer = Family Room
31/05/2013 07:57:25 SonosController AVTransport service added for zoneplayer = Family Room
31/05/2013 07:57:25 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)
31/05/2013 07:57:25 SonosController ContentDirectory service added for zoneplayer = Family Room
31/05/2013 07:57:25 SonosController ConnectionManager service added for zoneplayer = Family Room
31/05/2013 07:57:25 SonosController AvTransportCallback added for zoneplayer = Family Room
31/05/2013 07:57:25 SonosController RenderingControlCallback added for zoneplayer = Family Room
31/05/2013 07:57:25 SonosController ZoneGroupTopology ControlCallback added for zoneplayer = Family Room
31/05/2013 07:57:25 SonosController GroupManagement ControlCallback added for zoneplayer = Family Room
31/05/2013 07:57:25 SonosController AlarmClock ControlCallback added for zoneplayer = Family Room
31/05/2013 07:57:25 SonosController MusicServices ControlCallback added for zoneplayer = Family Room
31/05/2013 07:57:25 SonosController SystemProperties ControlCallback added for zoneplayer = Family Room
31/05/2013 07:57:25 SonosController ConnectionManagerCallback added for zoneplayer = Family Room
31/05/2013 07:57:25 SonosController AudioInCallback added for zoneplayer = Family Room
31/05/2013 07:57:25 SonosController DevicePropertiesCallback added for zoneplayer = Family Room
31/05/2013 07:57:25 SonosController Service callbacks established for zoneplayer = Family Room
31/05/2013 07:57:25 SonosController GetVolumeLevel called for ZonePlayer = Family Room with values Channel=Master
31/05/2013 07:57:25 SonosController DirectConnect for zoneplayer = Family Room found AudioInputName = Audio Component
31/05/2013 07:57:25 SonosController IPAddress for zoneplayer = Family Room= 192.168.1.44
31/05/2013 07:57:25 SonosController GetcurrentTrackInfo called for zoneplayer - Family Room
31/05/2013 07:57:25 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
31/05/2013 07:57:25 SonosController Family Room TrackInfo (MediaInfo/Title) = HomeSeer Announcement
31/05/2013 07:57:25 SonosController Family Room TrackInfo (title) = HomeSeer Announcement
31/05/2013 07:57:25 SonosController Family Room TrackInfo (creator) = Dirk Corsus
31/05/2013 07:57:25 SonosController Family Room TrackInfo (album) = SonosController
31/05/2013 07:57:26 SonosController Family Room TrackInfo (albumArtURI) = http://192.168.1.13:90/SonosController/Announcements/Announcement.jpg
31/05/2013 07:57:26 SonosController Family Room TrackInfo (CurrentURI) = x-rincon:RINCON_000E58A2EE3801400
31/05/2013 07:57:26 SonosController Family Room TrackInfo(Source) = Linked
31/05/2013 07:57:26 SonosController HandleLinkedZones called for ZonePlayer = Family Room. LinkedUDN = RINCON_000E58A2EE3801400
31/05/2013 07:57:26 SonosController LinkAZone called with TargetUDN : RINCON_000E585E89E201400 and SourceUDN = RINCON_000E58A2EE3801400
31/05/2013 07:57:26 SonosController Issue within LinkAZone. UDN = RINCON_000E58A2EE3801400 cannot be Found
31/05/2013 07:57:26 SonosController PlayChangeNotifyCallback called for Zone - Family Room with ChangeType = SongChanged and Changevalue = playing and SendDeviceTrigger = True
31/05/2013 07:57:26 SonosController PlayChangeNotifyCallback called for Zone - Family Room with ChangeType = PlayStatusChanged and Changevalue = playing and SendDeviceTrigger = True
31/05/2013 07:57:26 SonosController HS updated in UpdateHS. HS Code = [2 Info = PlayLinkedHomeSeer AnnouncementDirk CorsusSonosController
31/05/2013 07:57:26 SonosController CreateSonosControllers: Created instance of ZonePlayerController for Zoneplayer = Family Room with index 2
31/05/2013 07:57:26 SonosController SetHSDeviceCodeTransport called for zone - Living Room with HS Code = [4
31/05/2013 07:57:26 SonosController DirectConnect called for Zone Living Room with device name = uuid:RINCON_000E58A2ECD001400 and Model = ZP90
31/05/2013 07:57:26 SonosController DirectConnect for zoneplayer = Living Room found IconURL = http://192.168.1.42:1400/img/icon-ZP90.png
31/05/2013 07:57:26 SonosController Living Room : MediaServer Friendly Name = uuid:RINCON_000E58A2ECD001400_MS
31/05/2013 07:57:26 SonosController Living Room : MediaRenderer Friendly Name = Living Room - Sonos CONNECT Media Renderer
31/05/2013 07:57:26 SonosController AlarmClock service added for zoneplayer = Living Room
31/05/2013 07:57:26 SonosController MusicServices service added for zoneplayer = Living Room
31/05/2013 07:57:26 SonosController AudioIN service added for zoneplayer = Living Room
31/05/2013 07:57:26 SonosController DeviceProperties service added for zoneplayer = Living Room
31/05/2013 07:57:26 SonosController SystemProperties service added for zoneplayer = Living Room
31/05/2013 07:57:26 SonosController ZoneGroupTopology service added for zoneplayer = Living Room
31/05/2013 07:57:26 SonosController GroupManagement service addedfor zoneplayer = Living Room
31/05/2013 07:57:26 SonosController RenderingControl service added for zoneplayer = Living Room
31/05/2013 07:57:26 SonosController RenderingControl service added for zoneplayer = Living Room
31/05/2013 07:57:27 SonosController AVTransport service added for zoneplayer = Living Room
31/05/2013 07:57:27 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)
31/05/2013 07:57:27 SonosController ContentDirectory service added for zoneplayer = Living Room
31/05/2013 07:57:27 SonosController ConnectionManager service added for zoneplayer = Living Room
31/05/2013 07:57:27 SonosController AvTransportCallback added for zoneplayer = Living Room
31/05/2013 07:57:27 SonosController RenderingControlCallback added for zoneplayer = Living Room
31/05/2013 07:57:27 SonosController ZoneGroupTopology ControlCallback added for zoneplayer = Living Room
31/05/2013 07:57:27 SonosController GroupManagement ControlCallback added for zoneplayer = Living Room
31/05/2013 07:57:27 SonosController AlarmClock ControlCallback added for zoneplayer = Living Room
31/05/2013 07:57:27 SonosController MusicServices ControlCallback added for zoneplayer = Living Room
31/05/2013 07:57:27 SonosController SystemProperties ControlCallback added for zoneplayer = Living Room
31/05/2013 07:57:27 SonosController ConnectionManagerCallback added for zoneplayer = Living Room
31/05/2013 07:57:27 SonosController AudioInCallback added for zoneplayer = Living Room
31/05/2013 07:57:27 SonosController DevicePropertiesCallback added for zoneplayer = Living Room
31/05/2013 07:57:27 SonosController Service callbacks established for zoneplayer = Living Room
31/05/2013 07:57:27 SonosController GetVolumeLevel called for ZonePlayer = Living Room with values Channel=Master
31/05/2013 07:57:27 SonosController DirectConnect for zoneplayer = Living Room found AudioInputName = Audio Component
31/05/2013 07:57:27 SonosController IPAddress for zoneplayer = Living Room= 192.168.1.42
31/05/2013 07:57:27 SonosController GetcurrentTrackInfo called for zoneplayer - Living Room
31/05/2013 07:57:27 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
31/05/2013 07:57:27 SonosController Living Room TrackInfo (MediaInfo/Title) = HomeSeer Announcement
31/05/2013 07:57:27 SonosController Living Room TrackInfo (title) = HomeSeer Announcement
31/05/2013 07:57:27 SonosController Living Room TrackInfo (creator) = Dirk Corsus
31/05/2013 07:57:27 SonosController Living Room TrackInfo (album) = SonosController
31/05/2013 07:57:27 SonosController Living Room TrackInfo (albumArtURI) = http://192.168.1.13:90/SonosController/Announcements/Announcement.jpg
31/05/2013 07:57:27 SonosController Living Room TrackInfo (CurrentURI) = x-rincon:RINCON_000E58A2EE3801400
31/05/2013 07:57:27 SonosController Living Room TrackInfo(Source) = Linked
31/05/2013 07:57:27 SonosController HandleLinkedZones called for ZonePlayer = Living Room. LinkedUDN = RINCON_000E58A2EE3801400
31/05/2013 07:57:27 SonosController LinkAZone called with TargetUDN : RINCON_000E58A2ECD001400 and SourceUDN = RINCON_000E58A2EE3801400
31/05/2013 07:57:27 SonosController Issue within LinkAZone. UDN = RINCON_000E58A2EE3801400 cannot be Found
31/05/2013 07:57:27 SonosController UDN not matched in LinkAZone. UDNs found = RINCON_000E585E89E201400
31/05/2013 07:57:27 SonosController PlayChangeNotifyCallback called for Zone - Living Room with ChangeType = SongChanged and Changevalue = playing and SendDeviceTrigger = True
31/05/2013 07:57:27 SonosController PlayChangeNotifyCallback called for Zone - Living Room with ChangeType = PlayStatusChanged and Changevalue = playing and SendDeviceTrigger = True
31/05/2013 07:57:27 SonosController HS updated in UpdateHS. HS Code = [4 Info = PlayLinkedHomeSeer AnnouncementDirk CorsusSonosController
31/05/2013 07:57:27 SonosController CreateSonosControllers: Created instance of ZonePlayerController for Zoneplayer = Living Room with index 4
31/05/2013 07:57:27 SonosController SetHSDeviceCodeTransport called for zone - Dining Room with HS Code = [6
31/05/2013 07:57:27 SonosController DirectConnect called for Zone Dining Room with device name = uuid:RINCON_000E585F339A01400 and Model = S5
31/05/2013 07:57:27 SonosController DirectConnect for zoneplayer = Dining Room found IconURL = http://192.168.1.46:1400/img/icon-S5.png
31/05/2013 07:57:27 SonosController Dining Room : MediaServer Friendly Name = uuid:RINCON_000E585F339A01400_MS
31/05/2013 07:57:27 SonosController Dining Room : MediaRenderer Friendly Name = Dining Room - Sonos PLAY:5 Media Renderer
31/05/2013 07:57:27 SonosController AlarmClock service added for zoneplayer = Dining Room
31/05/2013 07:57:27 SonosController MusicServices service added for zoneplayer = Dining Room
31/05/2013 07:57:27 SonosController AudioIN service added for zoneplayer = Dining Room
31/05/2013 07:57:27 SonosController DeviceProperties service added for zoneplayer = Dining Room
31/05/2013 07:57:27 SonosController SystemProperties service added for zoneplayer = Dining Room
31/05/2013 07:57:27 SonosController ZoneGroupTopology service added for zoneplayer = Dining Room
31/05/2013 07:57:27 SonosController GroupManagement service addedfor zoneplayer = Dining Room
31/05/2013 07:57:28 SonosController RenderingControl service added for zoneplayer = Dining Room
31/05/2013 07:57:28 SonosController RenderingControl service added for zoneplayer = Dining Room
31/05/2013 07:57:28 SonosController AVTransport service added for zoneplayer = Dining Room
31/05/2013 07:57:28 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)
31/05/2013 07:57:28 SonosController ContentDirectory service added for zoneplayer = Dining Room
31/05/2013 07:57:28 SonosController ConnectionManager service added for zoneplayer = Dining Room
31/05/2013 07:57:28 SonosController AvTransportCallback added for zoneplayer = Dining Room
31/05/2013 07:57:28 SonosController RenderingControlCallback added for zoneplayer = Dining Room
31/05/2013 07:57:28 SonosController ZoneGroupTopology ControlCallback added for zoneplayer = Dining Room
31/05/2013 07:57:28 SonosController GroupManagement ControlCallback added for zoneplayer = Dining Room
31/05/2013 07:57:28 SonosController AlarmClock ControlCallback added for zoneplayer = Dining Room
31/05/2013 07:57:28 SonosController MusicServices ControlCallback added for zoneplayer = Dining Room
31/05/2013 07:57:28 SonosController SystemProperties ControlCallback added for zoneplayer = Dining Room
31/05/2013 07:57:28 SonosController ConnectionManagerCallback added for zoneplayer = Dining Room
31/05/2013 07:57:28 SonosController AudioInCallback added for zoneplayer = Dining Room
31/05/2013 07:57:28 SonosController DevicePropertiesCallback added for zoneplayer = Dining Room
31/05/2013 07:57:28 SonosController Service callbacks established for zoneplayer = Dining Room
31/05/2013 07:57:28 SonosController GetVolumeLevel called for ZonePlayer = Dining Room with values Channel=Master
31/05/2013 07:57:28 SonosController DirectConnect for zoneplayer = Dining Room found AudioInputName = Audio Component
31/05/2013 07:57:28 SonosController IPAddress for zoneplayer = Dining Room= 192.168.1.46
31/05/2013 07:57:28 SonosController GetcurrentTrackInfo called for zoneplayer - Dining Room
31/05/2013 07:57:28 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
31/05/2013 07:57:28 SonosController Dining Room TrackInfo (MediaInfo/Title) = HomeSeer Announcement
31/05/2013 07:57:28 SonosController Dining Room TrackInfo (title) = HomeSeer Announcement
31/05/2013 07:57:28 SonosController Dining Room TrackInfo (creator) = Dirk Corsus
31/05/2013 07:57:28 SonosController Dining Room TrackInfo (album) = SonosController
31/05/2013 07:57:28 SonosController Dining Room TrackInfo (albumArtURI) = http://192.168.1.13:90/SonosController/Announcements/Announcement.jpg
31/05/2013 07:57:28 SonosController Dining Room TrackInfo (CurrentURI) = x-rincon:RINCON_000E58A2EE3801400
31/05/2013 07:57:28 SonosController Dining Room TrackInfo(Source) = Linked
31/05/2013 07:57:28 SonosController HandleLinkedZones called for ZonePlayer = Dining Room. LinkedUDN = RINCON_000E58A2EE3801400
31/05/2013 07:57:28 SonosController LinkAZone called with TargetUDN : RINCON_000E585F339A01400 and SourceUDN = RINCON_000E58A2EE3801400
31/05/2013 07:57:28 SonosController Issue within LinkAZone. UDN = RINCON_000E58A2EE3801400 cannot be Found
31/05/2013 07:57:28 SonosController UDN not matched in LinkAZone. UDNs found = RINCON_000E585E89E201400
31/05/2013 07:57:28 SonosController UDN not matched in LinkAZone. UDNs found = RINCON_000E58A2ECD001400
31/05/2013 07:57:28 SonosController PlayChangeNotifyCallback called for Zone - Dining Room with ChangeType = SongChanged and Changevalue = playing and SendDeviceTrigger = True
31/05/2013 07:57:28 SonosController PlayChangeNotifyCallback called for Zone - Dining Room with ChangeType = PlayStatusChanged and Changevalue = playing and SendDeviceTrigger = True
31/05/2013 07:57:28 SonosController HS updated in UpdateHS. HS Code = [6 Info = PlayLinkedHomeSeer AnnouncementDirk CorsusSonosController
31/05/2013 07:57:28 SonosController CreateSonosControllers: Created instance of ZonePlayerController for Zoneplayer = Dining Room with index 6
31/05/2013 07:57:28 SonosController SetHSDeviceCodeTransport called for zone - Kitchen with HS Code = [8
31/05/2013 07:57:28 SonosController DirectConnect called for Zone Kitchen with device name = uuid:RINCON_000E58A2EE3801400 and Model = ZP90
31/05/2013 07:57:28 SonosController DirectConnect for zoneplayer = Kitchen found IconURL = http://192.168.1.38:1400/img/icon-ZP90.png
31/05/2013 07:57:28 SonosController Kitchen : MediaServer Friendly Name = uuid:RINCON_000E58A2EE3801400_MS
31/05/2013 07:57:29 SonosController Kitchen : MediaRenderer Friendly Name = Kitchen - Sonos CONNECT Media Renderer
31/05/2013 07:57:29 SonosController AlarmClock service added for zoneplayer = Kitchen
31/05/2013 07:57:29 SonosController MusicServices service added for zoneplayer = Kitchen
31/05/2013 07:57:29 SonosController AudioIN service added for zoneplayer = Kitchen
31/05/2013 07:57:29 SonosController DeviceProperties service added for zoneplayer = Kitchen
31/05/2013 07:57:29 SonosController SystemProperties service added for zoneplayer = Kitchen
31/05/2013 07:57:29 SonosController ZoneGroupTopology service added for zoneplayer = Kitchen
31/05/2013 07:57:29 SonosController GroupManagement service addedfor zoneplayer = Kitchen
31/05/2013 07:57:29 SonosController RenderingControl service added for zoneplayer = Kitchen
31/05/2013 07:57:29 SonosController RenderingControl service added for zoneplayer = Kitchen
31/05/2013 07:57:30 SonosController AVTransport service added for zoneplayer = Kitchen
31/05/2013 07:57:30 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)
31/05/2013 07:57:30 SonosController ContentDirectory service added for zoneplayer = Kitchen
31/05/2013 07:57:30 SonosController ConnectionManager service added for zoneplayer = Kitchen
31/05/2013 07:57:30 SonosController AvTransportCallback added for zoneplayer = Kitchen
31/05/2013 07:57:30 SonosController RenderingControlCallback added for zoneplayer = Kitchen
31/05/2013 07:57:30 SonosController ZoneGroupTopology ControlCallback added for zoneplayer = Kitchen
31/05/2013 07:57:30 SonosController GroupManagement ControlCallback added for zoneplayer = Kitchen
31/05/2013 07:57:30 SonosController AlarmClock ControlCallback added for zoneplayer = Kitchen
31/05/2013 07:57:30 SonosController MusicServices ControlCallback added for zoneplayer = Kitchen
31/05/2013 07:57:30 SonosController SystemProperties ControlCallback added for zoneplayer = Kitchen
31/05/2013 07:57:30 SonosController ConnectionManagerCallback added for zoneplayer = Kitchen
31/05/2013 07:57:30 SonosController AudioInCallback added for zoneplayer = Kitchen
31/05/2013 07:57:30 SonosController DevicePropertiesCallback added for zoneplayer = Kitchen
31/05/2013 07:57:30 SonosController Service callbacks established for zoneplayer = Kitchen
31/05/2013 07:57:30 SonosController GetVolumeLevel called for ZonePlayer = Kitchen with values Channel=Master
31/05/2013 07:57:30 SonosController DirectConnect for zoneplayer = Kitchen found AudioInputName = Audio Component
31/05/2013 07:57:30 SonosController IPAddress for zoneplayer = Kitchen= 192.168.1.38
31/05/2013 07:57:30 SonosController GetcurrentTrackInfo called for zoneplayer - Kitchen
31/05/2013 07:57:30 SonosController Kitchen TrackInfo (CurrentURI) = x-rincon-queue:RINCON_000E58A2EE3801400#0 and TrackInfo (CurrentURIMetaData) =
31/05/2013 07:57:30 SonosController Kitchen TrackInfo (CurrentURI) = x-rincon-queue:RINCON_000E58A2EE3801400#0
31/05/2013 07:57:30 SonosController Kitchen TrackInfo(Source) = Tracks
31/05/2013 07:57:30 SonosController GetcurrentTrackInfo called GetPositionInfo for zoneplayer - Kitchen and MetaData x-file-cifs://SERVER/Music/Various%20Artists/Trancendental%20Euphoria%20Disc%201/10%20Join%20Me%20%5bClub%20Mix%5d.mp3/getaa?u=x-file-cifs%3a%2f%2fSERVER%2fMusic%2fVarious%2520Artists%2fTrancend ental%2520Euphoria%2520Disc%25201%2f10%2520Join%2520Me%2520% 255bClub%2520Mix%255d.mp3&v=25Join Me [Club Mix]object.item.audioItem.musicTrackLightforceTrancendental Euphoria Disc 110Various Artists
31/05/2013 07:57:30 SonosController Kitchen TrackInfo (Streamcontent/Title) =
31/05/2013 07:57:30 SonosController Kitchen TrackInfo (creator) = Lightforce

[peterford]

Part 2:

31/05/2013 07:57:30 SonosController Kitchen TrackInfo (Streamcontent/Title) =
31/05/2013 07:57:30 SonosController Kitchen TrackInfo (creator) = Lightforce
31/05/2013 07:57:30 SonosController Kitchen TrackInfo (album) = Trancendental Euphoria Disc 1
31/05/2013 07:57:30 SonosController Kitchen TrackInfo (title) = Join Me [Club Mix]
31/05/2013 07:57:30 SonosController Kitchen TrackInfo (albumArtURI) = http://192.168.1.38:1400/getaa?u=x-file-cifs://SERVER/Music/Various%20Artists/Trancendental%20Euphoria%20Disc%201/10%20Join%20Me%20%5bClub%20Mix%5d.mp3&v=25
31/05/2013 07:57:30 SonosController GetAlbumArtPath called for zone Kitchen with AlbumURI = http://192.168.1.38:1400/getaa?u=x-file-cifs://SERVER/Music/Various%20Artists/Trancendental%20Euphoria%20Disc%201/10%20Join%20Me%20%5bClub%20Mix%5d.mp3&v=25 and NextTrack = False
31/05/2013 07:57:30 SonosController GetAlbumArtPath returned for Zone - Kitchen with AlbumURI = http://192.168.1.38:1400/getaa?u=x-file-cifs://SERVER/Music/Various%20Artists/Trancendental%20Euphoria%20Disc%201/10%20Join%20Me%20%5bClub%20Mix%5d.mp3&v=25 and returned path= /SonosController/Images/Cover4.jpg
31/05/2013 07:57:30 SonosController Kitchen TrackInfo (Track Position) = 0:04:08
31/05/2013 07:57:30 SonosController Kitchen TrackInfo (Track Position Seconds) = 248
31/05/2013 07:57:30 SonosController Kitchen TrackInfo (Track Duration) = 0:04:23
31/05/2013 07:57:30 SonosController Kitchen TrackInfo (Queue Position) = 41
31/05/2013 07:57:30 SonosController Kitchen TrackInfo (% played) = 94
31/05/2013 07:57:30 SonosController PlayChangeNotifyCallback called for Zone - Kitchen with ChangeType = SongChanged and Changevalue = paused and SendDeviceTrigger = True
31/05/2013 07:57:30 SonosController PlayChangeNotifyCallback called for Zone - Kitchen with ChangeType = PlayStatusChanged and Changevalue = paused and SendDeviceTrigger = True
31/05/2013 07:57:30 SonosController HS updated in UpdateHS. HS Code = [8 Info = PauseTracksJoin Me [Club Mix]LightforceTrancendental Euphoria Disc 1
31/05/2013 07:57:30 SonosController CreateSonosControllers: Created instance of ZonePlayerController for Zoneplayer = Kitchen with index 8
31/05/2013 07:57:30 SonosController SetHSDeviceCodeTransport called for zone - Master Bedroom with HS Code = [10
31/05/2013 07:57:30 SonosController DirectConnect called for Zone Master Bedroom with device name = uuid:RINCON_000E58A3348401400 and Model = ZP90
31/05/2013 07:57:30 SonosController DirectConnect for zoneplayer = Master Bedroom found IconURL = http://192.168.1.43:1400/img/icon-ZP90.png
31/05/2013 07:57:30 SonosController Master Bedroom : MediaServer Friendly Name = uuid:RINCON_000E58A3348401400_MS
31/05/2013 07:57:30 SonosController Master Bedroom : MediaRenderer Friendly Name = Master Bedroom - Sonos CONNECT Media Renderer
31/05/2013 07:57:30 SonosController AlarmClock service added for zoneplayer = Master Bedroom
31/05/2013 07:57:30 SonosController MusicServices service added for zoneplayer = Master Bedroom
31/05/2013 07:57:30 SonosController AudioIN service added for zoneplayer = Master Bedroom
31/05/2013 07:57:30 SonosController DeviceProperties service added for zoneplayer = Master Bedroom
31/05/2013 07:57:30 SonosController SystemProperties service added for zoneplayer = Master Bedroom
31/05/2013 07:57:31 SonosController ZoneGroupTopology service added for zoneplayer = Master Bedroom
31/05/2013 07:57:31 SonosController GroupManagement service addedfor zoneplayer = Master Bedroom
31/05/2013 07:57:31 SonosController RenderingControl service added for zoneplayer = Master Bedroom
31/05/2013 07:57:31 SonosController RenderingControl service added for zoneplayer = Master Bedroom
31/05/2013 07:57:31 SonosController AVTransport service added for zoneplayer = Master Bedroom
31/05/2013 07:57:31 SonosController Error in MediaRenderer.Services for zoneplayer = Master Bedroom. 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)
31/05/2013 07:57:31 SonosController ContentDirectory service added for zoneplayer = Master Bedroom
31/05/2013 07:57:31 SonosController ConnectionManager service added for zoneplayer = Master Bedroom
31/05/2013 07:57:31 SonosController AvTransportCallback added for zoneplayer = Master Bedroom
31/05/2013 07:57:31 SonosController RenderingControlCallback added for zoneplayer = Master Bedroom
31/05/2013 07:57:31 SonosController ZoneGroupTopology ControlCallback added for zoneplayer = Master Bedroom
31/05/2013 07:57:31 SonosController GroupManagement ControlCallback added for zoneplayer = Master Bedroom
31/05/2013 07:57:31 SonosController AlarmClock ControlCallback added for zoneplayer = Master Bedroom
31/05/2013 07:57:31 SonosController MusicServices ControlCallback added for zoneplayer = Master Bedroom
31/05/2013 07:57:31 SonosController SystemProperties ControlCallback added for zoneplayer = Master Bedroom
31/05/2013 07:57:31 SonosController ConnectionManagerCallback added for zoneplayer = Master Bedroom
31/05/2013 07:57:31 SonosController AudioInCallback added for zoneplayer = Master Bedroom
31/05/2013 07:57:31 SonosController DevicePropertiesCallback added for zoneplayer = Master Bedroom
31/05/2013 07:57:31 SonosController Service callbacks established for zoneplayer = Master Bedroom
31/05/2013 07:57:31 SonosController GetVolumeLevel called for ZonePlayer = Master Bedroom with values Channel=Master
31/05/2013 07:57:31 SonosController DirectConnect for zoneplayer = Master Bedroom found AudioInputName = Audio Component
31/05/2013 07:57:31 SonosController IPAddress for zoneplayer = Master Bedroom= 192.168.1.43
31/05/2013 07:57:31 SonosController GetcurrentTrackInfo called for zoneplayer - Master Bedroom
31/05/2013 07:57:31 SonosController Master Bedroom 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
31/05/2013 07:57:31 SonosController Master Bedroom TrackInfo (MediaInfo/Title) = HomeSeer Announcement
31/05/2013 07:57:31 SonosController Master Bedroom TrackInfo (title) = HomeSeer Announcement
31/05/2013 07:57:31 SonosController Master Bedroom TrackInfo (creator) = Dirk Corsus
31/05/2013 07:57:31 SonosController Master Bedroom TrackInfo (album) = SonosController
31/05/2013 07:57:31 SonosController Master Bedroom TrackInfo (albumArtURI) = http://192.168.1.13:90/SonosController/Announcements/Announcement.jpg
31/05/2013 07:57:32 SonosController Master Bedroom TrackInfo (CurrentURI) = x-rincon:RINCON_000E58A2EE3801400
31/05/2013 07:57:32 SonosController Master Bedroom TrackInfo(Source) = Linked
31/05/2013 07:57:32 SonosController HandleLinkedZones called for ZonePlayer = Master Bedroom. LinkedUDN = RINCON_000E58A2EE3801400
31/05/2013 07:57:32 SonosController LinkAZone called with TargetUDN : RINCON_000E58A3348401400 and SourceUDN = RINCON_000E58A2EE3801400
31/05/2013 07:57:32 SonosController UDN found in LinkAZone. UDN = RINCON_000E58A2EE3801400 and Zoneplayername = Kitchen
31/05/2013 07:57:32 SonosController AddTargetLinkZone Called for ZoneName Kitchen with new Target UDN List= RINCON_000E58A3348401400
31/05/2013 07:57:32 SonosController GetZoneByUDN called with UDN = RINCON_000E58A2EE3801400
31/05/2013 07:57:32 SonosController PlayChangeNotifyCallback called for Zone - Master Bedroom with ChangeType = SongChanged and Changevalue = playing and SendDeviceTrigger = True
31/05/2013 07:57:32 SonosController PlayChangeNotifyCallback called for Zone - Master Bedroom with ChangeType = PlayStatusChanged and Changevalue = playing and SendDeviceTrigger = True
31/05/2013 07:57:32 SonosController HS updated in UpdateHS. HS Code = [10 Info = PlayLinkedHomeSeer AnnouncementDirk CorsusSonosController
31/05/2013 07:57:32 SonosController CreateSonosControllers: Created instance of ZonePlayerController for Zoneplayer = Master Bedroom with index 10
31/05/2013 07:57:32 SonosController SetHSDeviceCodeTransport called for zone - Office with HS Code = [12
31/05/2013 07:57:32 SonosController DirectConnect called for Zone Office with device name = uuid:RINCON_000E585D68AE01400 and Model = S5
31/05/2013 07:57:32 SonosController DirectConnect for zoneplayer = Office found IconURL = http://192.168.1.30:1400/img/icon-S5.png
31/05/2013 07:57:32 SonosController Office : MediaServer Friendly Name = uuid:RINCON_000E585D68AE01400_MS
31/05/2013 07:57:32 SonosController Office : MediaRenderer Friendly Name = Office - Sonos PLAY:5 Media Renderer
31/05/2013 07:57:32 SonosController AlarmClock service added for zoneplayer = Office
31/05/2013 07:57:32 SonosController MusicServices service added for zoneplayer = Office
31/05/2013 07:57:32 SonosController AudioIN service added for zoneplayer = Office
31/05/2013 07:57:32 SonosController DeviceProperties service added for zoneplayer = Office
31/05/2013 07:57:32 SonosController SystemProperties service added for zoneplayer = Office
31/05/2013 07:57:32 SonosController ZoneGroupTopology service added for zoneplayer = Office
31/05/2013 07:57:32 SonosController GroupManagement service addedfor zoneplayer = Office
31/05/2013 07:57:32 SonosController RenderingControl service added for zoneplayer = Office
31/05/2013 07:57:32 SonosController RenderingControl service added for zoneplayer = Office
31/05/2013 07:57:33 SonosController AVTransport service added for zoneplayer = Office
31/05/2013 07:57:33 SonosController Error in MediaRenderer.Services for zoneplayer = Office. 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)
31/05/2013 07:57:33 SonosController ContentDirectory service added for zoneplayer = Office
31/05/2013 07:57:33 SonosController ConnectionManager service added for zoneplayer = Office
31/05/2013 07:57:33 SonosController AvTransportCallback added for zoneplayer = Office
31/05/2013 07:57:33 SonosController RenderingControlCallback added for zoneplayer = Office
31/05/2013 07:57:33 SonosController ZoneGroupTopology ControlCallback added for zoneplayer = Office
31/05/2013 07:57:33 SonosController GroupManagement ControlCallback added for zoneplayer = Office
31/05/2013 07:57:33 SonosController AlarmClock ControlCallback added for zoneplayer = Office
31/05/2013 07:57:33 SonosController MusicServices ControlCallback added for zoneplayer = Office
31/05/2013 07:57:33 SonosController SystemProperties ControlCallback added for zoneplayer = Office
31/05/2013 07:57:33 SonosController ConnectionManagerCallback added for zoneplayer = Office
31/05/2013 07:57:33 SonosController AudioInCallback added for zoneplayer = Office
31/05/2013 07:57:33 SonosController DevicePropertiesCallback added for zoneplayer = Office
31/05/2013 07:57:33 SonosController Service callbacks established for zoneplayer = Office
31/05/2013 07:57:33 SonosController GetVolumeLevel called for ZonePlayer = Office with values Channel=Master
31/05/2013 07:57:33 SonosController DirectConnect for zoneplayer = Office found AudioInputName = Audio Component
31/05/2013 07:57:33 SonosController IPAddress for zoneplayer = Office= 192.168.1.30
31/05/2013 07:57:33 SonosController GetcurrentTrackInfo called for zoneplayer - Office
31/05/2013 07:57:33 SonosController Office 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
31/05/2013 07:57:33 SonosController Office TrackInfo (MediaInfo/Title) = HomeSeer Announcement
31/05/2013 07:57:33 SonosController Office TrackInfo (title) = HomeSeer Announcement
31/05/2013 07:57:33 SonosController Office TrackInfo (creator) = Dirk Corsus
31/05/2013 07:57:33 SonosController Office TrackInfo (album) = SonosController
31/05/2013 07:57:33 SonosController Office TrackInfo (albumArtURI) = http://192.168.1.13:90/SonosController/Announcements/Announcement.jpg
31/05/2013 07:57:33 SonosController Office TrackInfo (CurrentURI) = x-rincon:RINCON_000E58A2EE3801400
31/05/2013 07:57:33 SonosController Office TrackInfo(Source) = Linked
31/05/2013 07:57:33 SonosController HandleLinkedZones called for ZonePlayer = Office. LinkedUDN = RINCON_000E58A2EE3801400
31/05/2013 07:57:33 SonosController LinkAZone called with TargetUDN : RINCON_000E585D68AE01400 and SourceUDN = RINCON_000E58A2EE3801400
31/05/2013 07:57:33 SonosController UDN found in LinkAZone. UDN = RINCON_000E58A2EE3801400 and Zoneplayername = Kitchen
31/05/2013 07:57:33 SonosController AddTargetLinkZone Called for ZoneName Kitchen with new Target UDN List= RINCON_000E58A3348401400;RINCON_000E585D68AE01400
31/05/2013 07:57:33 SonosController GetZoneByUDN called with UDN = RINCON_000E58A2EE3801400
31/05/2013 07:57:33 SonosController PlayChangeNotifyCallback called for Zone - Office with ChangeType = SongChanged and Changevalue = playing and SendDeviceTrigger = True
31/05/2013 07:57:33 SonosController PlayChangeNotifyCallback called for Zone - Office with ChangeType = PlayStatusChanged and Changevalue = playing and SendDeviceTrigger = True
31/05/2013 07:57:33 SonosController HS updated in UpdateHS. HS Code = [12 Info = PlayLinkedHomeSeer AnnouncementDirk CorsusSonosController
31/05/2013 07:57:33 SonosController CreateSonosControllers: Created instance of ZonePlayerController for Zoneplayer = Office with index 12
31/05/2013 07:57:33 SonosController WriteSonosNamesToIniFile wrote new string Family Room;:;S5:|:Living Room;:;ZP90:|Dining Room;:;S5:|:Kitchen;:;ZP90:|:Master Bedroom;:;ZP90:|:Office;:;S5
31/05/2013 07:57:33 SonosController InitializeSonosDevices is upating the Rendering devices and set Controllerref for Family Room with TDC= [2
31/05/2013 07:57:33 SonosController SetHSDeviceCodeRendering called for zone - Family Room with HS Code = [3
31/05/2013 07:57:33 SonosController InitializeSonosDevices is upating the Rendering devices and set Controllerref for Living Room with TDC= [4
31/05/2013 07:57:33 SonosController SetHSDeviceCodeRendering called for zone - Living Room with HS Code = [5
31/05/2013 07:57:33 SonosController InitializeSonosDevices is upating the Rendering devices and set Controllerref for Dining Room with TDC= [6
31/05/2013 07:57:33 SonosController SetHSDeviceCodeRendering called for zone - Dining Room with HS Code = [7
31/05/2013 07:57:33 SonosController InitializeSonosDevices is upating the Rendering devices and set Controllerref for Kitchen with TDC= [8
31/05/2013 07:57:33 SonosController SetHSDeviceCodeRendering called for zone - Kitchen with HS Code = [9
31/05/2013 07:57:33 SonosController InitializeSonosDevices is upating the Rendering devices and set Controllerref for Master Bedroom with TDC= [10
31/05/2013 07:57:33 SonosController SetHSDeviceCodeRendering called for zone - Master Bedroom with HS Code = [11
31/05/2013 07:57:33 SonosController InitializeSonosDevices is upating the Rendering devices and set Controllerref for Office with TDC= [12
31/05/2013 07:57:33 SonosController SetHSDeviceCodeRendering called for zone - Office with HS Code = [13
31/05/2013 07:57:33 SonosController CreateWebLink called with ZoneUDN = RINCON_000E585E89E201400. ZoneIndex= 1 and PlugIn name = SonosController
31/05/2013 07:57:33 SonosController CreateWebLink called with ZoneUDN = RINCON_000E58A2ECD001400. ZoneIndex= 2 and PlugIn name = SonosController
31/05/2013 07:57:33 SonosController CreateWebLink called with ZoneUDN = RINCON_000E585F339A01400. ZoneIndex= 3 and PlugIn name = SonosController
31/05/2013 07:57:33 SonosController CreateWebLink called with ZoneUDN = RINCON_000E58A2EE3801400. ZoneIndex= 4 and PlugIn name = SonosController
31/05/2013 07:57:33 SonosController CreateWebLink called with ZoneUDN = RINCON_000E58A3348401400. ZoneIndex= 5 and PlugIn name = SonosController
31/05/2013 07:57:33 SonosController CreateWebLink called with ZoneUDN = RINCON_000E585D68AE01400. ZoneIndex= 6 and PlugIn name = SonosController
31/05/2013 07:57:33 SonosController InitializeSonosDevices: Done Initializing Sonos Devices
31/05/2013 07:57:33 SonosController Initialized
31/05/2013 07:57:33 Plug-In Finished initializing plug-in SONOSCONTROLLER
31/05/2013 07:57:33 Plug-In Initializing X10 Plug-in: X10 CM11A/CM12U On COM Port 1
31/05/2013 07:57:33 Plug-In Initializing Plug-in: X10 CM11A/CM12U
31/05/2013 07:57:33 Info Initializing CM11A/12U on COM port 1
31/05/2013 07:57:33 Plug-In Finished initializing X10 interface X10 CM11A/CM12U
31/05/2013 07:57:33 Plug-In Finished initializing infrared interface
31/05/2013 07:57:33 Web Server Local IP address (subnet) is: 192.168.1.13 (255.255.255.0)
31/05/2013 07:57:33 Web Server UPNP Discovery started
31/05/2013 07:57:33 Web Server Web Server started on port 90
31/05/2013 07:57:33 Startup Creating ASP.NET application host...
31/05/2013 07:57:34 SonosController HandleLinkedZones called for ZonePlayer = Family Room. LinkedUDN = RINCON_000E58A2EE3801400
31/05/2013 07:57:34 SonosController LinkAZone called with TargetUDN : RINCON_000E585E89E201400 and SourceUDN = RINCON_000E58A2EE3801400
31/05/2013 07:57:34 SonosController UDN found in LinkAZone. UDN = RINCON_000E58A2EE3801400 and Zoneplayername = Kitchen
31/05/2013 07:57:34 SonosController AddTargetLinkZone Called for ZoneName Kitchen with new Target UDN List= RINCON_000E58A3348401400;RINCON_000E585D68AE01400;RINCON_000 E585E89E201400
31/05/2013 07:57:34 SonosController GetZoneByUDN called with UDN = RINCON_000E58A2EE3801400
31/05/2013 07:57:36 SonosController HandleLinkedZones called for ZonePlayer = Living Room. LinkedUDN = RINCON_000E58A2EE3801400
31/05/2013 07:57:36 SonosController LinkAZone called with TargetUDN : RINCON_000E58A2ECD001400 and SourceUDN = RINCON_000E58A2EE3801400
31/05/2013 07:57:36 SonosController UDN found in LinkAZone. UDN = RINCON_000E58A2EE3801400 and Zoneplayername = Kitchen
31/05/2013 07:57:36 SonosController AddTargetLinkZone Called for ZoneName Kitchen with new Target UDN List= RINCON_000E58A3348401400;RINCON_000E585D68AE01400;RINCON_000 E585E89E201400;RINCON_000E58A2ECD001400
31/05/2013 07:57:36 SonosController GetZoneByUDN called with UDN = RINCON_000E58A2EE3801400
31/05/2013 07:57:37 SonosController HandleLinkedZones called for ZonePlayer = Dining Room. LinkedUDN = RINCON_000E58A2EE3801400
31/05/2013 07:57:37 SonosController LinkAZone called with TargetUDN : RINCON_000E585F339A01400 and SourceUDN = RINCON_000E58A2EE3801400
31/05/2013 07:57:37 SonosController UDN found in LinkAZone. UDN = RINCON_000E58A2EE3801400 and Zoneplayername = Kitchen
31/05/2013 07:57:37 SonosController AddTargetLinkZone Called for ZoneName Kitchen with new Target UDN List= RINCON_000E58A3348401400;RINCON_000E585D68AE01400;RINCON_000 E585E89E201400;RINCON_000E58A2ECD001400;RINCON_000E585F339A0 1400
31/05/2013 07:57:37 SonosController GetZoneByUDN called with UDN = RINCON_000E58A2EE3801400
31/05/2013 07:57:37 Startup ASP.NET application host created
31/05/2013 07:57:37 Startup Start automation engine...
31/05/2013 07:57:37 Startup This version of HomeSeer is registered.
31/05/2013 07:57:37 Startup Initializing Speaker Client Interface
31/05/2013 07:57:37 Speech Listening for remote speaker connections on port 8742
31/05/2013 07:57:37 Startup HS2Sentry is disabled in Setup, sentry will not be started.
31/05/2013 07:57:37 Startup Starting Event Scheduler...
31/05/2013 07:57:37 Z-Wave Starting Z-Wave Polling...
31/05/2013 07:57:37 Z-Wave Starting 'Z-Wave Health' Process Starter...
31/05/2013 07:57:37 Startup Checking HTTP connection...
31/05/2013 07:57:37 Web Server Web Server authorized local login successful from: 127.0.0.1 User: Peter
31/05/2013 07:57:38 Startup Updater services starting...
31/05/2013 07:57:38 Startup Starting DDNS service...
31/05/2013 07:57:38 HSTouch Server HomeSeer has started
31/05/2013 07:57:38 HSTouch Server Server is enabled for unlimited connections.
31/05/2013 07:57:38 Startup Running the Startup.txt Script
31/05/2013 07:57:38 HSTouch Server Server is enabled for iPhone/iPod HSTouch connections.
31/05/2013 07:57:38 SonosController Capabilities called. Capabilities are IO and Music
31/05/2013 07:57:38 SonosController GetMusicAPI (Integer) was called with Value: 1
31/05/2013 07:57:38 SonosController LibLoading called for Zone - Family Room
31/05/2013 07:57:38 HSTouch Server Music plugin SONOSCONTROLLER music library loaded.
31/05/2013 07:57:38 HSTouch Server Server started on port 10200
31/05/2013 07:57:38 Startup Scripting is OK and is now running Startup.txt
31/05/2013 07:57:38 Startup Start up complete.
31/05/2013 07:57:38 SonosController SendEventForAllZones called
31/05/2013 07:57:38 SonosController GetMusicAPI (Integer) was called with Value: 1
31/05/2013 07:57:38 SonosController PlayChangeNotifyCallback called for Zone - Family Room with ChangeType = PlayStatusChanged and Changevalue = playing and SendDeviceTrigger = False
31/05/2013 07:57:38 SonosController Capabilities called. Capabilities are IO and Music
31/05/2013 07:57:38 SonosController GetMultiZoneAPI was called
31/05/2013 07:57:38 SonosController NumInstances called. Instances is 6
31/05/2013 07:57:38 SonosController GetMusicAPI (Integer) was called with Value: 1
31/05/2013 07:57:38 SonosController GetMusicAPI (Integer) was called with Value: 2
31/05/2013 07:57:38 SonosController GetMusicAPI (Integer) was called with Value: 3
31/05/2013 07:57:38 SonosController GetMusicAPI (Integer) was called with Value: 4
31/05/2013 07:57:38 SonosController GetMusicAPI (Integer) was called with Value: 5
31/05/2013 07:57:38 SonosController GetMusicAPI (Integer) was called with Value: 6
31/05/2013 07:57:38 SonosController PlayChangeNotifyCallback called for Zone - Family Room with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
31/05/2013 07:57:38 SonosController GetMusicAPI (Integer) was called with Value: 2
31/05/2013 07:57:38 SonosController PlayChangeNotifyCallback called for Zone - Living Room with ChangeType = PlayStatusChanged and Changevalue = playing and SendDeviceTrigger = False
31/05/2013 07:57:38 SonosController PlayChangeNotifyCallback called for Zone - Living Room with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
31/05/2013 07:57:38 SonosController GetMusicAPI (Integer) was called with Value: 3
31/05/2013 07:57:38 SonosController PlayChangeNotifyCallback called for Zone - Dining Room with ChangeType = PlayStatusChanged and Changevalue = playing and SendDeviceTrigger = False
31/05/2013 07:57:38 SonosController PlayChangeNotifyCallback called for Zone - Dining Room with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
31/05/2013 07:57:38 SonosController GetMusicAPI (Integer) was called with Value: 4
31/05/2013 07:57:38 SonosController PlayChangeNotifyCallback called for Zone - Kitchen with ChangeType = PlayStatusChanged and Changevalue = paused and SendDeviceTrigger = False
31/05/2013 07:57:38 SonosController PlayChangeNotifyCallback called for Zone - Kitchen with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
31/05/2013 07:57:38 SonosController GetMusicAPI (Integer) was called with Value: 5
31/05/2013 07:57:38 SonosController PlayChangeNotifyCallback called for Zone - Master Bedroom with ChangeType = PlayStatusChanged and Changevalue = playing and SendDeviceTrigger = False
31/05/2013 07:57:38 SonosController PlayChangeNotifyCallback called for Zone - Master Bedroom with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
31/05/2013 07:57:38 SonosController GetMusicAPI (Integer) was called with Value: 6
31/05/2013 07:57:38 SonosController PlayChangeNotifyCallback called for Zone - Office with ChangeType = PlayStatusChanged and Changevalue = playing and SendDeviceTrigger = False
31/05/2013 07:57:38 SonosController PlayChangeNotifyCallback called for Zone - Office with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
31/05/2013 07:57:38 HSTouch Server Opening connection from:192.168.1.20/51809
31/05/2013 07:57:38 HSTouch Server Opening connection from:192.168.1.18/49661
31/05/2013 07:57:39 HSTouch Server Client Kitchen (192.168.1.20/51809) named Kitchen has CONNECTED.
31/05/2013 07:57:39 SonosController SendEventForAllZones called
31/05/2013 07:57:39 SonosController GetMusicAPI (Integer) was called with Value: 1
31/05/2013 07:57:39 SonosController PlayChangeNotifyCallback called for Zone - Family Room with ChangeType = PlayStatusChanged and Changevalue = playing and SendDeviceTrigger = False
31/05/2013 07:57:39 SonosController PlayChangeNotifyCallback called for Zone - Family Room with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
31/05/2013 07:57:39 SonosController GetMusicAPI (Integer) was called with Value: 2
31/05/2013 07:57:39 SonosController PlayChangeNotifyCallback called for Zone - Living Room with ChangeType = PlayStatusChanged and Changevalue = playing and SendDeviceTrigger = False
31/05/2013 07:57:39 SonosController PlayChangeNotifyCallback called for Zone - Living Room with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
31/05/2013 07:57:39 SonosController GetMusicAPI (Integer) was called with Value: 3
31/05/2013 07:57:39 SonosController PlayChangeNotifyCallback called for Zone - Dining Room with ChangeType = PlayStatusChanged and Changevalue = playing and SendDeviceTrigger = False
31/05/2013 07:57:39 SonosController PlayChangeNotifyCallback called for Zone - Dining Room with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
31/05/2013 07:57:39 SonosController GetMusicAPI (Integer) was called with Value: 4
31/05/2013 07:57:39 SonosController PlayChangeNotifyCallback called for Zone - Kitchen with ChangeType = PlayStatusChanged and Changevalue = paused and SendDeviceTrigger = False
31/05/2013 07:57:39 SonosController PlayChangeNotifyCallback called for Zone - Kitchen with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
31/05/2013 07:57:39 SonosController GetMusicAPI (Integer) was called with Value: 5
31/05/2013 07:57:39 SonosController PlayChangeNotifyCallback called for Zone - Master Bedroom with ChangeType = PlayStatusChanged and Changevalue = playing and SendDeviceTrigger = False
31/05/2013 07:57:39 SonosController PlayChangeNotifyCallback called for Zone - Master Bedroom with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
31/05/2013 07:57:39 SonosController GetMusicAPI (Integer) was called with Value: 6
31/05/2013 07:57:39 SonosController PlayChangeNotifyCallback called for Zone - Office with ChangeType = PlayStatusChanged and Changevalue = playing and SendDeviceTrigger = False
31/05/2013 07:57:39 SonosController PlayChangeNotifyCallback called for Zone - Office with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
31/05/2013 07:57:39 HSTouch Server Opening connection from:192.168.1.16/50778
31/05/2013 07:57:39 HSTouch Server Client Bedroom (192.168.1.18/49661) named Bedroom has CONNECTED.
31/05/2013 07:57:40 HSTouch Server Client Hall (192.168.1.16/50778) named Hall has CONNECTED.
31/05/2013 07:57:41 SonosController Capabilities called. Capabilities are IO and Music
31/05/2013 07:57:41 SonosController SendEventForAllZones called
31/05/2013 07:57:41 SonosController GetMusicAPI (Integer) was called with Value: 1
31/05/2013 07:57:41 SonosController PlayChangeNotifyCallback called for Zone - Family Room with ChangeType = PlayStatusChanged and Changevalue = playing and SendDeviceTrigger = False
31/05/2013 07:57:41 SonosController PlayChangeNotifyCallback called for Zone - Family Room with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
31/05/2013 07:57:41 SonosController GetMusicAPI (Integer) was called with Value: 2
31/05/2013 07:57:41 SonosController PlayChangeNotifyCallback called for Zone - Living Room with ChangeType = PlayStatusChanged and Changevalue = playing and SendDeviceTrigger = False
31/05/2013 07:57:41 SonosController PlayChangeNotifyCallback called for Zone - Living Room with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
31/05/2013 07:57:41 SonosController GetMusicAPI (Integer) was called with Value: 3
31/05/2013 07:57:41 SonosController PlayChangeNotifyCallback called for Zone - Dining Room with ChangeType = PlayStatusChanged and Changevalue = playing and SendDeviceTrigger = False
31/05/2013 07:57:41 SonosController PlayChangeNotifyCallback called for Zone - Dining Room with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
31/05/2013 07:57:41 SonosController GetMusicAPI (Integer) was called with Value: 4
31/05/2013 07:57:41 SonosController PlayChangeNotifyCallback called for Zone - Kitchen with ChangeType = PlayStatusChanged and Changevalue = paused and SendDeviceTrigger = False
31/05/2013 07:57:41 SonosController PlayChangeNotifyCallback called for Zone - Kitchen with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
31/05/2013 07:57:41 SonosController GetMusicAPI (Integer) was called with Value: 5
31/05/2013 07:57:41 SonosController PlayChangeNotifyCallback called for Zone - Master Bedroom with ChangeType = PlayStatusChanged and Changevalue = playing and SendDeviceTrigger = False
31/05/2013 07:57:41 SonosController PlayChangeNotifyCallback called for Zone - Master Bedroom with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
31/05/2013 07:57:41 SonosController GetMusicAPI (Integer) was called with Value: 6
31/05/2013 07:57:41 SonosController PlayChangeNotifyCallback called for Zone - Office with ChangeType = PlayStatusChanged and Changevalue = playing and SendDeviceTrigger = False
31/05/2013 07:57:41 SonosController PlayChangeNotifyCallback called for Zone - Office with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
31/05/2013 07:57:42 SonosController Capabilities called. Capabilities are IO and Music
31/05/2013 07:57:42 SonosController SendEventForAllZones called
31/05/2013 07:57:42 SonosController GetMusicAPI (Integer) was called with Value: 1
31/05/2013 07:57:42 SonosController PlayChangeNotifyCallback called for Zone - Family Room with ChangeType = PlayStatusChanged and Changevalue = playing and SendDeviceTrigger = False
31/05/2013 07:57:42 SonosController PlayChangeNotifyCallback called for Zone - Family Room with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
31/05/2013 07:57:42 SonosController GetMusicAPI (Integer) was called with Value: 2
31/05/2013 07:57:42 SonosController PlayChangeNotifyCallback called for Zone - Living Room with ChangeType = PlayStatusChanged and Changevalue = playing and SendDeviceTrigger = False
31/05/2013 07:57:42 SonosController PlayChangeNotifyCallback called for Zone - Living Room with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
31/05/2013 07:57:42 SonosController GetMusicAPI (Integer) was called with Value: 3
31/05/2013 07:57:42 SonosController PlayChangeNotifyCallback called for Zone - Dining Room with ChangeType = PlayStatusChanged and Changevalue = playing and SendDeviceTrigger = False
31/05/2013 07:57:42 SonosController PlayChangeNotifyCallback called for Zone - Dining Room with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
31/05/2013 07:57:42 SonosController GetMusicAPI (Integer) was called with Value: 4
31/05/2013 07:57:42 SonosController PlayChangeNotifyCallback called for Zone - Kitchen with ChangeType = PlayStatusChanged and Changevalue = paused and SendDeviceTrigger = False
31/05/2013 07:57:42 SonosController PlayChangeNotifyCallback called for Zone - Kitchen with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
31/05/2013 07:57:42 SonosController GetMusicAPI (Integer) was called with Value: 5
31/05/2013 07:57:42 SonosController PlayChangeNotifyCallback called for Zone - Master Bedroom with ChangeType = PlayStatusChanged and Changevalue = playing and SendDeviceTrigger = False
31/05/2013 07:57:42 SonosController PlayChangeNotifyCallback called for Zone - Master Bedroom with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
31/05/2013 07:57:42 SonosController GetMusicAPI (Integer) was called with Value: 6
31/05/2013 07:57:42 SonosController PlayChangeNotifyCallback called for Zone - Office with ChangeType = PlayStatusChanged and Changevalue = playing and SendDeviceTrigger = False
31/05/2013 07:57:42 SonosController PlayChangeNotifyCallback called for Zone - Office with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
31/05/2013 07:57:43 SonosController Capabilities called. Capabilities are IO and Music
31/05/2013 07:57:43 SonosController SendEventForAllZones called
31/05/2013 07:57:43 SonosController GetMusicAPI (Integer) was called with Value: 1
31/05/2013 07:57:43 SonosController PlayChangeNotifyCallback called for Zone - Family Room with ChangeType = PlayStatusChanged and Changevalue = playing and SendDeviceTrigger = False
31/05/2013 07:57:43 SonosController PlayChangeNotifyCallback called for Zone - Family Room with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
31/05/2013 07:57:43 SonosController GetMusicAPI (Integer) was called with Value: 2
31/05/2013 07:57:43 SonosController PlayChangeNotifyCallback called for Zone - Living Room with ChangeType = PlayStatusChanged and Changevalue = playing and SendDeviceTrigger = False
31/05/2013 07:57:43 SonosController PlayChangeNotifyCallback called for Zone - Living Room with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
31/05/2013 07:57:43 SonosController GetMusicAPI (Integer) was called with Value: 3
31/05/2013 07:57:43 SonosController PlayChangeNotifyCallback called for Zone - Dining Room with ChangeType = PlayStatusChanged and Changevalue = playing and SendDeviceTrigger = False
31/05/2013 07:57:43 SonosController PlayChangeNotifyCallback called for Zone - Dining Room with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
31/05/2013 07:57:43 SonosController GetMusicAPI (Integer) was called with Value: 4
31/05/2013 07:57:43 SonosController PlayChangeNotifyCallback called for Zone - Kitchen with ChangeType = PlayStatusChanged and Changevalue = paused and SendDeviceTrigger = False
31/05/2013 07:57:43 SonosController PlayChangeNotifyCallback called for Zone - Kitchen with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
31/05/2013 07:57:43 SonosController GetMusicAPI (Integer) was called with Value: 5
31/05/2013 07:57:43 SonosController PlayChangeNotifyCallback called for Zone - Master Bedroom with ChangeType = PlayStatusChanged and Changevalue = playing and SendDeviceTrigger = False
31/05/2013 07:57:43 SonosController PlayChangeNotifyCallback called for Zone - Master Bedroom with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
31/05/2013 07:57:43 SonosController GetMusicAPI (Integer) was called with Value: 6
31/05/2013 07:57:43 SonosController PlayChangeNotifyCallback called for Zone - Office with ChangeType = PlayStatusChanged and Changevalue = playing and SendDeviceTrigger = False
31/05/2013 07:57:43 SonosController PlayChangeNotifyCallback called for Zone - Office with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly and SendDeviceTrigger = False
31/05/2013 07:57:55 Network Speaker client connected from: 127.0.0.1
31/05/2013 07:57:55 Speaker Speaker host added, Name: Automation-PC IP address: 127.0.0.1
31/05/2013 07:57:59 Web Server Web Server authorized local login successful from: 192.168.1.13 User: Peter
31/05/2013 07:58:23 Web Server Web Server authorized local login successful from: 192.168.1.37 User: Peter

[dcorsus]

Issue w/ Sonos SW R4.1

I upgraded my system this morning as well and see the same. At this point, it looks benign but I will need more time to find out, so if you have spotted something not working with the rendering functions (volume, mute, loudness) let me know.
Thanks
Dirk

[peterford]

Ok, look forward to the results of your investigation.

As I see it there is no easy way to revert back to an earlier version. If you use the original CD it insists on checking for the later version, if you deny it the internet to do this then the install fails - all rather annoying.

Thanks for your prompt attention as usual

Peter

[dcorsus]

Ok, look forward to the results of your investigation.

As I see it there is no easy way to revert back to an earlier version. If you use the original CD it insists on checking for the later version, if you deny it the internet to do this then the install fails - all rather annoying.

Thanks for your prompt attention as usual

Peter

Peter,

as far as I can tell, nothing is broken so no need to revert your Sonos SW! What happens is that at discovery, UPNP/DLNA goes through a list of services that are being published and my PI picks out pointers to those it needs. I can tell that SW 41 added a new service and that somehow has the windows UPNP service chocking (note this is a Microsoft Windows service I use not one that I develop, so I'll have to find a work around) when it enumerates through the list of services. The good news is that I have the pointers to the services I need so it is just (or at least appears) to be a pesky error that I can easily suppress.

Dirk

[peterford]

Hi Dirk

Now seemed to have developed a new problem, the log file I originally gave you had six sets of errors, one for each player and as you said all seems well despite the errors.

I now only get 4 errors for 4 of the players, the remaining 2 players don't give an error. Yet if you select BuildDB you get the new set of errors shown below in the log file. I haven't changed anything except some of the players may have been powered off and then back on.

I can also confirm that all 6 players are seen under Windows / Network under media devices and in this screen the correct player types are displayed.

Any ideas?

Log File:

06/06/2013 21:15:37 Startup Settings Loaded.
06/06/2013 21:15:37 Startup Current sunrise/sunset values calculated.
06/06/2013 21:15:37 Startup HSPhone external interface class started.
06/06/2013 21:15:37 Startup Local voice recognition class started.
06/06/2013 21:15:37 Startup Database SAVE process started.
06/06/2013 21:15:37 Startup Loading configuration file ...
06/06/2013 21:15:37 System Loading device types file...
06/06/2013 21:15:39 Database Opening (Mode=Read) up HomeSeer database: C:\Program Files (x86)\HomeSeer HSPRO\config\sample.mdb
06/06/2013 21:15:39 Database Loading Devices...
06/06/2013 21:15:40 Load Config 144 total devices loaded.
06/06/2013 21:15:40 Load Config Checking device type settings for all devices...
06/06/2013 21:15:40 Load Config Finished checking device type settings.
06/06/2013 21:15:40 Load Config Loading Events...
06/06/2013 21:15:41 Load Config 101 total events loaded.
06/06/2013 21:15:41 Database HomeSeer configuration database C:\Program Files (x86)\HomeSeer HSPRO\config\sample.mdb has been closed.
06/06/2013 21:15:41 Startup HomeSeer version is: 2.5.0.55
06/06/2013 21:15:41 Startup Starting remoting (Remote client connection monitor)
06/06/2013 21:15:41 Startup Creating Speaker Client Interface Object...
06/06/2013 21:15:41 Startup Initializing phone interface...
06/06/2013 21:15:42 HSPhone HomeSeer Phone Web Interface initializing.
06/06/2013 21:15:42 Startup Checking for pending updates to be installed...
06/06/2013 21:15:42 Startup Restoring Device Status...
06/06/2013 21:15:42 Z-Wave :- :- :- :- :- :- :- :- Initializing Z-Wave Interface -: -: -: -: -: -: -: -:
06/06/2013 21:15:42 Z-Wave Initializing Z-Wave Interface on COM port: 3 at 115K baud.
06/06/2013 21:15:42 Z-Wave Getting node information from controller...
06/06/2013 21:15:43 Z-Wave Z-Wave Serial API version: 5
06/06/2013 21:15:43 Z-Wave Z-Wave interface node ID: 1, Home ID: 0161DAD8
06/06/2013 21:15:43 Z-Wave Found 12 Z-Wave nodes in interface node ID 1
06/06/2013 21:15:43 Z-Wave Controller Manufacturer: Aeon Labs, ID=0x1, Type=0x2
06/06/2013 21:15:43 Z-Wave Controller firmware version: 3.7
06/06/2013 21:15:44 Z-Wave This Z-Wave PC Controller Library Version is: Z-Wave 2.78 (ZDK Version 5.02 Patch 3)
06/06/2013 21:15:44 Z-Wave This Z-Wave PC Controller is Type: ZW_LIB_CONTROLLER_STATIC
06/06/2013 21:15:44 Z-Wave Z-Wave services for communications to nodes utilizing the SECURITY COMMAND CLASS were successfully started.
06/06/2013 21:15:44 Z-Wave -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- --
06/06/2013 21:15:44 Startup Checking for available plug-ins
06/06/2013 21:15:44 Plug-In Found plug-in: X10 CM11A/CM12U, version: 2.2.0.4
06/06/2013 21:15:44 Plug-In Found plug-in: X10 CM15AUSB, version: 1.0.0.6
06/06/2013 21:15:45 Plug-In Found plug-in: HSTouch Server, version: 1.0.0.58
06/06/2013 21:15:45 Plug-In Found plug-in: HSTouch Android, version: 1.0.0.2
06/06/2013 21:15:45 Plug-In Found plug-in: HSTouch iPhone, version: 1.0.0.2
06/06/2013 21:15:45 Plug-In Found plug-in: HSTouch Server Unlimited, version: 1.0.0.1
06/06/2013 21:15:45 Plug-In Found plug-in: Insteon, version: 1.1.52.1
06/06/2013 21:15:45 Plug-In Found plug-in: iTunes, version: 2.5.0.12
06/06/2013 21:15:45 Plug-In Found plug-in: Media Player, version: 2.5.0.13
06/06/2013 21:15:45 Plug-In Found plug-in: SONOSCONTROLLER, version: 1.0.0.110
06/06/2013 21:15:45 Plug-In Found plug-in: UPB, version: 1.0.2.7
06/06/2013 21:15:46 Startup Initializing Plug-Ins
06/06/2013 21:15:46 Plug-In Initializing Plug-in: HSTouch Server
06/06/2013 21:15:46 Plug-In Finished initializing plug-in HSTouch Server
06/06/2013 21:15:46 HSTouch Server Unlimited Register callback completed
06/06/2013 21:15:46 Plug-In Initializing Plug-in: HSTouch Server Unlimited
06/06/2013 21:15:46 HSTouch Server Unlimited HSTouch Server Unlimited plugin initialized.
06/06/2013 21:15:46 Plug-In Finished initializing plug-in HSTouch Server Unlimited
06/06/2013 21:15:46 Plug-In Initializing Plug-in: HSTouch iPhone
06/06/2013 21:15:46 Plug-In Finished initializing plug-in HSTouch iPhone
06/06/2013 21:15:46 SonosController Application path: C:\Program Files (x86)\HomeSeer HSPRO
06/06/2013 21:15:46 SonosController Register callback completed
06/06/2013 21:15:46 Plug-In Initializing Plug-in: SONOSCONTROLLER
06/06/2013 21:15:46 SonosController InitIO Called
06/06/2013 21:15:56 SonosController DirectConnect called for Zone Family Room with device name = uuid:RINCON_000E585E89E201400 and Model = S5
06/06/2013 21:15:57 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)
06/06/2013 21:15:57 SonosController AvTransportCallback added for zoneplayer = Family Room
06/06/2013 21:15:57 SonosController RenderingControlCallback added for zoneplayer = Family Room
06/06/2013 21:15:57 SonosController ZoneGroupTopology ControlCallback added for zoneplayer = Family Room
06/06/2013 21:15:57 SonosController GroupManagement ControlCallback added for zoneplayer = Family Room
06/06/2013 21:15:57 SonosController AlarmClock ControlCallback added for zoneplayer = Family Room
06/06/2013 21:15:57 SonosController AudioInCallback added for zoneplayer = Family Room
06/06/2013 21:15:57 SonosController DevicePropertiesCallback added for zoneplayer = Family Room
06/06/2013 21:15:57 SonosController Service callbacks established for zoneplayer = Family Room
06/06/2013 21:15:57 SonosController DirectConnect for zoneplayer = Family Room found AudioInputName = Audio Component
06/06/2013 21:15:57 SonosController IPAddress for zoneplayer = Family Room= 192.168.1.32
06/06/2013 21:15:57 SonosController CreateSonosControllers: Created instance of ZonePlayerController for Zoneplayer = Family Room with index 2
06/06/2013 21:15:57 SonosController DirectConnect called for Zone Living Room with device name = uuid:RINCON_000E58A2ECD001400 and Model = ZP90
06/06/2013 21:15:58 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)
06/06/2013 21:15:58 SonosController AvTransportCallback added for zoneplayer = Living Room
06/06/2013 21:15:58 SonosController RenderingControlCallback added for zoneplayer = Living Room
06/06/2013 21:15:58 SonosController ZoneGroupTopology ControlCallback added for zoneplayer = Living Room
06/06/2013 21:15:58 SonosController GroupManagement ControlCallback added for zoneplayer = Living Room
06/06/2013 21:15:58 SonosController AlarmClock ControlCallback added for zoneplayer = Living Room
06/06/2013 21:15:58 SonosController AudioInCallback added for zoneplayer = Living Room
06/06/2013 21:15:58 SonosController DevicePropertiesCallback added for zoneplayer = Living Room
06/06/2013 21:15:58 SonosController Service callbacks established for zoneplayer = Living Room
06/06/2013 21:15:58 SonosController DirectConnect for zoneplayer = Living Room found AudioInputName = Audio Component
06/06/2013 21:15:58 SonosController IPAddress for zoneplayer = Living Room= 192.168.1.33
06/06/2013 21:15:58 SonosController CreateSonosControllers: Created instance of ZonePlayerController for Zoneplayer = Living Room with index 4
06/06/2013 21:15:58 SonosController DirectConnect called for Zone Dining Room with device name = uuid:RINCON_000E585F339A01400 and Model = S5
06/06/2013 21:15:59 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)
06/06/2013 21:15:59 SonosController AvTransportCallback added for zoneplayer = Dining Room
06/06/2013 21:15:59 SonosController RenderingControlCallback added for zoneplayer = Dining Room
06/06/2013 21:15:59 SonosController ZoneGroupTopology ControlCallback added for zoneplayer = Dining Room
06/06/2013 21:15:59 SonosController GroupManagement ControlCallback added for zoneplayer = Dining Room
06/06/2013 21:15:59 SonosController AlarmClock ControlCallback added for zoneplayer = Dining Room
06/06/2013 21:15:59 SonosController AudioInCallback added for zoneplayer = Dining Room
06/06/2013 21:15:59 SonosController DevicePropertiesCallback added for zoneplayer = Dining Room
06/06/2013 21:15:59 SonosController Service callbacks established for zoneplayer = Dining Room
06/06/2013 21:15:59 SonosController DirectConnect for zoneplayer = Dining Room found AudioInputName = Audio Component
06/06/2013 21:15:59 SonosController IPAddress for zoneplayer = Dining Room= 192.168.1.31
06/06/2013 21:15:59 SonosController CreateSonosControllers: Created instance of ZonePlayerController for Zoneplayer = Dining Room with index 6
06/06/2013 21:15:59 SonosController DirectConnect called for Zone Kitchen with device name = uuid:RINCON_000E58A2EE3801400 and Model = ZP90
06/06/2013 21:16:00 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)
06/06/2013 21:16:00 SonosController AvTransportCallback added for zoneplayer = Kitchen
06/06/2013 21:16:00 SonosController RenderingControlCallback added for zoneplayer = Kitchen
06/06/2013 21:16:00 SonosController ZoneGroupTopology ControlCallback added for zoneplayer = Kitchen
06/06/2013 21:16:00 SonosController GroupManagement ControlCallback added for zoneplayer = Kitchen
06/06/2013 21:16:00 SonosController AlarmClock ControlCallback added for zoneplayer = Kitchen
06/06/2013 21:16:00 SonosController AudioInCallback added for zoneplayer = Kitchen
06/06/2013 21:16:00 SonosController DevicePropertiesCallback added for zoneplayer = Kitchen
06/06/2013 21:16:00 SonosController Service callbacks established for zoneplayer = Kitchen
06/06/2013 21:16:00 SonosController DirectConnect for zoneplayer = Kitchen found AudioInputName = Audio Component
06/06/2013 21:16:00 SonosController IPAddress for zoneplayer = Kitchen= 192.168.1.34
06/06/2013 21:16:00 SonosController CreateSonosControllers: Created instance of ZonePlayerController for Zoneplayer = Kitchen with index 8
06/06/2013 21:16:00 SonosController CreateSonosControllers: Created instance of ZonePlayerController for Zoneplayer = Master Bedroom with index 10
06/06/2013 21:16:00 SonosController CreateSonosControllers: Created instance of ZonePlayerController for Zoneplayer = Office with index 12
06/06/2013 21:16:00 SonosController Initialized
06/06/2013 21:16:00 Plug-In Finished initializing plug-in SONOSCONTROLLER
06/06/2013 21:16:00 Plug-In Initializing X10 Plug-in: X10 CM11A/CM12U On COM Port 1
06/06/2013 21:16:00 Plug-In Initializing Plug-in: X10 CM11A/CM12U
06/06/2013 21:16:00 Info Initializing CM11A/12U on COM port 1
06/06/2013 21:16:00 Plug-In Finished initializing X10 interface X10 CM11A/CM12U
06/06/2013 21:16:00 Plug-In Finished initializing infrared interface
06/06/2013 21:16:00 Web Server Local IP address (subnet) is: 192.168.1.13 (255.255.255.0)
06/06/2013 21:16:00 Web Server UPNP Discovery started
06/06/2013 21:16:00 Web Server Web Server started on port 90
06/06/2013 21:16:00 Startup Creating ASP.NET application host...
06/06/2013 21:16:04 Startup ASP.NET application host created
06/06/2013 21:16:04 Startup Start automation engine...
06/06/2013 21:16:04 Startup This version of HomeSeer is registered.
06/06/2013 21:16:04 Startup Initializing Speaker Client Interface
06/06/2013 21:16:04 Speech Listening for remote speaker connections on port 8742
06/06/2013 21:16:04 Startup HS2Sentry is disabled in Setup, sentry will not be started.
06/06/2013 21:16:04 Startup Starting Event Scheduler...
06/06/2013 21:16:04 Z-Wave Starting Z-Wave Polling...
06/06/2013 21:16:04 Z-Wave Starting 'Z-Wave Health' Process Starter...
06/06/2013 21:16:04 Startup Checking HTTP connection...
06/06/2013 21:16:04 Web Server Web Server authorized local login successful from: 127.0.0.1 User: Peter
06/06/2013 21:16:04 Startup Updater services starting...
06/06/2013 21:16:04 Startup Starting DDNS service...
06/06/2013 21:16:04 HSTouch Server HomeSeer has started
06/06/2013 21:16:04 Startup Running the Startup.txt Script
06/06/2013 21:16:04 HSTouch Server Server is enabled for unlimited connections.
06/06/2013 21:16:04 HSTouch Server Server is enabled for iPhone/iPod HSTouch connections.
06/06/2013 21:16:04 HSTouch Server Music plugin SONOSCONTROLLER music library loaded.
06/06/2013 21:16:04 HSTouch Server Server started on port 10200
06/06/2013 21:16:05 Startup Scripting is OK and is now running Startup.txt
06/06/2013 21:16:05 Startup Start up complete.
06/06/2013 21:16:06 Web Server Web Server authorized local login successful from: 192.168.1.13 User: Peter
06/06/2013 21:16:10 Network Speaker client connected from: 127.0.0.1
06/06/2013 21:16:11 Speaker Speaker host added, Name: Automation-PC IP address: 127.0.0.1
06/06/2013 21:16:20 Device Update Device: Z-Wave Z-Wave Interface Child 4 Temperature Inst 1 value set to 19.2 (1920/C)
06/06/2013 21:16:20 Event Event Trigger "Thermostat 2 Scan"
06/06/2013 21:16:20 Event Running script in background: Heat2_Scan.vbs
06/06/2013 21:16:20 Device Control Command sent to Ground Floor Conservatory Thermostat Demand: T29 off Dimval: 0 Data: 0
06/06/2013 21:16:20 Device Control Command sent to Ground Floor Conservatory Heating: C10 off Dimval: 0 Data: 0
06/06/2013 21:16:21 Device Update Device: Z-Wave Z-Wave Interface Child 4 Relative Humidity Inst 2 value set to 55 (5500/%)
06/06/2013 21:16:26 Web Server Web Server authorized local login successful from: 192.168.1.37 User: Peter
06/06/2013 21:16:29 Network Remote Speaker Client disconnected from 127.0.0.1
06/06/2013 21:16:29 ASR Speaker Client speech recognition stopped
06/06/2013 21:17:02 Web Server Web Server authorized local login successful from: 192.168.1.13 User: Peter
06/06/2013 21:17:02 SonosController CreateTrackDatabase called with C:\Program Files (x86)\HomeSeer HSPRO\html\SonosController\MusicDb\SonosDB.mdb and Provider=Microsoft.Jet.OLEDB.4.0;Data Source=C:\Program Files (x86)\HomeSeer HSPRO\html\SonosController\MusicDb\SonosDB.mdb for zoneplayer = Office
06/06/2013 21:17:02 SonosController CreateTrackDatabase file deleted for zoneplayer = Office
06/06/2013 21:17:02 SonosController ERROR in BuildTrackDB/Browse for zoneplayer = Office with UPNP Error = 791: Unknown error type. OrgError=91. Error = Object reference not set to an instance of an object.
06/06/2013 21:17:02 SonosController ERROR in BuildArtistDB/Browse for zoneplayer = Office with UPNP Error = 791: Unknown error type. OrgError=91. Error = Object reference not set to an instance of an object.
06/06/2013 21:17:02 SonosController ERROR in BuildAlbumDB/Browse for zoneplayer = Office with UPNP Error = 791: Unknown error type. OrgError=91. Error = Object reference not set to an instance of an object.
06/06/2013 21:17:03 SonosController ERROR in BuildRadioStationDB/Browse for zoneplayer = Office with UPNP Error = 791: Unknown error type. OrgError=91. Error = Object reference not set to an instance of an object.
06/06/2013 21:17:03 SonosController ERROR in BuildPlaylistDB/Browse for zoneplayer = Office with UPNP Error = 791: Unknown error type. OrgError=91. Error = Object reference not set to an instance of an object.
06/06/2013 21:17:03 SonosController ERROR in BuildSonosPlaylistDB/Browse for zoneplayer = Office with UPNP Error = 791: Unknown error type. OrgError=91. Error = Object reference not set to an instance of an object.
06/06/2013 21:17:03 SonosController ERROR in BuildGenreDB/Browse for zoneplayer = Office with UPNP Error = 791: Unknown error type. OrgError=91. Error = Object reference not set to an instance of an object.
06/06/2013 21:17:03 SonosController BuildTrackDatabase Done for zoneplayer = Office

[dcorsus]

Hi Dirk

Now seemed to have developed a new problem, the log file I originally gave you had six sets of errors, one for each player and as you said all seems well despite the errors.

I now only get 4 errors for 4 of the players, the remaining 2 players don't give an error. Yet if you select BuildDB you get the new set of errors shown below in the log file. I haven't changed anything except some of the players may have been powered off and then back on.

I can also confirm that all 6 players are seen under Windows / Network under media devices and in this screen the correct player types are displayed.

Any ideas?

At start-up only 4 players were detected. if you have the option to rediscover players NOT set than players that are powered up AFTER you started HS won't be discovered. So either all players are on-line when HS is started OR re-discovery needs to be set to on.

The reason the DB creation is failing is because it tries to do it on the Office player which was not detected at start-up.

Dirk

[peterford]

Hi Dirk

Thanks for your reply. I understand what you say but I have restarted HS several times after all the players have been turned on, also the 'Don't do auto rediscoveries' option is not selected.

Every time I restart HS I have checked in Windows/Network/Media Devices to check all 6 players are shown.

Peter

[dcorsus]

Hi Dirk

Thanks for your reply. I understand what you say but I have restarted HS several times after all the players have been turned on, also the 'Don't do auto rediscoveries' option is not selected.

Every time I restart HS I have checked in Windows/Network/Media Devices to check all 6 players are shown.

Peter

... I thought I responded to this thread this morning but I don't see it ....
... It was going something like this ...

Did you try to restart the PC that has HS on it? When you write that you see the devices in Windows/Network/Media, is this on the same PC as you have HS running or a different one?

So far, whenever a few players are missing in the discovery, without a fault, the issue is either network or Antivirus (firewall) related

Thanks

Dirk

[peterford]

Hi Dirk

Yes I have restarted the PC that HS is on, and yes that same pc that is running HS shows all 6 players in windows/network/media.

I've even shut down all the PCs on my network, all the IP cameras, all the Sonos players, all the iPADS that use HSTouch, and my home server, rebooted my router then fired everything up - no change!

Also I haven't changed anything on my network or Anti virus for ages.

Since I posted last, the status page in HS which yesterday showed all 6 players now only shows 4. This is starting to look like the problem we discussed on 21st March from my post 'Losing SONOS devices'

At a loss as to what to do next.

Peter

[dcorsus]

Hi Dirk

Yes I have restarted the PC that HS is on, and yes that same pc that is running HS shows all 6 players in windows/network/media.

I've even shut down all the PCs on my network, all the IP cameras, all the Sonos players, all the iPADS that use HSTouch, and my home server, rebooted my router then fired everything up - no change!

Also I haven't changed anything on my network or Anti virus for ages.

Since I posted last, the status page in HS which yesterday showed all 6 players now only shows 4. This is starting to look like the problem we discussed on 21st March from my post 'Losing SONOS devices'

At a loss as to what to do next.

Peter

Can you turn the plugin's debug flag on, save it, restart HS, email me the FULL log from start - up to fully initialized.

I just reread that thread, so it went away by rebuilding your machine?

You wrote then that HS was very slow, do you see this again? Which HS version are you on? If you open the task manager, do you see HS hogging the CPU or something else?
Is it the same 2 players that are gone?

The discovery has zero relationship to HS or the HS database but it is dependent on a maximum wait time of 9 seconds. Can't immediately think of anything that would hog your CPU for a while and therefore have the discovery partially fail. My first suspicion is still with firewall(s) who might have blocked a specific IP address or a specific relationship between APP and IP address. Anything you can think off what might be different between the missing players and the discovered players (wireless versus wired, different subnet, different bridge ....)?

Dirk

[dcorsus]

Since I posted last, the status page in HS which yesterday showed all 6 players now only shows 4.

OK we need to focus on this as well. The players you see in HS are part of the HS database! They NEVER get deleted in normal operations or restarts.

The ONLY way these players could have disappeared is that either:

1. you went back to an older version of HS database or HS itself?
2. your HS database got corrupted, not sure it would revert to an older version or you would lose all
3. you deleted them manually
4. you deleted the hspi_sonoscontroller.ini file manually
5. the HSPI_sonoscontroller.ini file got corrupted

Anything that you think might be the cause?


Dirk