Announcement

Collapse
No announcement yet.

TTS Resume Music Issue

Collapse
X
Collapse
 
  • Page of 1
  • Filter
  • Time
  • Show
Clear All
new posts
Previous template Next
    #1

    TTS Resume Music Issue

    Playing a bit with the plugin, I'm messing around with TTS.

    I've created a TTS group (although I only have one Sonos device ATM), and am able to create an event to send speech just fine. It says the speech on the event okay, but when it is done, it simply sits there and never goes back to the music. Is there something special I need to do?

    Here is the relevant log file, and attached are the Sonos set up screens as well as the event.


    8/16/2012 1:59:13 PM - Event - Event Trigger "Test Speech"
    8/16/2012 1:59:13 PM - SonosController - SpeakIn called for Device = 0, Text = This is a test, Wait=True, Host = $SONOS$TTS$windogefault
    8/16/2012 1:59:13 PM - SonosController - AddAnnouncementToQueue called for LinkGroupName = TTS and Text = This is a test
    8/16/2012 1:59:13 PM - SonosController - GetLinkgroupSourceZone called with LinkgroupName = TTS
    8/16/2012 1:59:13 PM - SonosController - GetMusicAPI (Integer) was called with Value: 1
    8/16/2012 1:59:13 PM - SonosController - GetTailOfAnnouncementQueue called
    8/16/2012 1:59:13 PM - SonosController - DoCheckAnnouncementQueue called for linkgroup TTS and State = asIdle and isFile = True
    8/16/2012 1:59:13 PM - SonosController - HandleLinkingOn called with LinkgroupName = TTS
    8/16/2012 1:59:13 PM - SonosController - GetLinkGroupInfo called with LinkgroupName = TTS
    8/16/2012 1:59:13 PM - SonosController - HandleLinkingOn found uuid:RINCON_000E587B2EB801400 as source
    8/16/2012 1:59:13 PM - SonosController - SaveCurrentTrackInfo called for zoneplayer - Main
    8/16/2012 1:59:13 PM - SonosController - SaveCurrentTrackInfo for zoneplayer Main saved ZoneIsLinked = False
    8/16/2012 1:59:13 PM - SonosController - SaveCurrentTrackInfo for zoneplayer Main saved SourceLinkedZone =
    8/16/2012 1:59:13 PM - SonosController - SaveCurrentTrackInfo for zoneplayer Main with Track(0) =
    8/16/2012 1:59:13 PM - SonosController - SaveCurrentTrackInfo for zoneplayer Main with Track(1) =
    8/16/2012 1:59:13 PM - SonosController - SaveCurrentTrackInfo for zoneplayer Main with Track(2) =
    8/16/2012 1:59:13 PM - SonosController - SaveCurrentTrackInfo for zoneplayer Main with Track(3) = x-rincon-queue:RINCON_000E587B2EB801400#0
    8/16/2012 1:59:13 PM - SonosController - SaveCurrentTrackInfo for zoneplayer Main with Track(4) = /SonosController/images/noart.jpeg
    8/16/2012 1:59:13 PM - SonosController - SaveCurrentTrackInfo for zoneplayer Main with Track(5) = 00:00:00
    8/16/2012 1:59:13 PM - SonosController - SaveCurrentTrackInfo for zoneplayer Main with Track(6) =
    8/16/2012 1:59:13 PM - SonosController - SaveCurrentTrackInfo for zoneplayer Main with Track(7) = 0
    8/16/2012 1:59:13 PM - SonosController - SaveCurrentTrackInfo for zoneplayer Main with Track(8) =
    8/16/2012 1:59:13 PM - SonosController - SaveCurrentTrackInfo for zoneplayer Main with Track(9) = Tracks
    8/16/2012 1:59:13 PM - SonosController - SaveCurrentTrackInfo for zoneplayer Main with Track(10) = False
    8/16/2012 1:59:13 PM - SonosController - SaveCurrentTrackInfo for zoneplayer Main with Track(11) =
    8/16/2012 1:59:13 PM - SonosController - SaveCurrentTrackInfo for zoneplayer Main with Track(12) =
    8/16/2012 1:59:13 PM - SonosController - SaveCurrentTrackInfo for zoneplayer Main saved Queue position = 0
    8/16/2012 1:59:13 PM - SonosController - SaveCurrentTrackInfo for zoneplayer Main saved Player State = STOPPED
    8/16/2012 1:59:13 PM - SonosController - SaveCurrentTrackInfo for zoneplayer Main saved Master Volume = 43
    8/16/2012 1:59:13 PM - SonosController - SaveCurrentTrackInfo for zoneplayer Main saved Mute State = False
    8/16/2012 1:59:13 PM - SonosController - SaveCurrentTrackInfo for zoneplayer Main saved PlayMode State = NORMAL
    8/16/2012 1:59:13 PM - SonosController - TracksInQueue called for ZonePlayer = Main and found 56 tracks.
    8/16/2012 1:59:13 PM - SonosController - SaveQueue called with QueueName = SCQueue-Main-TTS for Zone = Main
    8/16/2012 1:59:14 PM - SonosController - CompareReplicationChanges for zoneplayer Main detected change for SavedQueuesUpdateID with Old = 10 and New = 11
    8/16/2012 1:59:14 PM - SonosController - ZonePairingChanged called for Zone = Main with ChannelMapSet
    8/16/2012 1:59:14 PM - SonosController - SaveCurrentTrackInfo for zoneplayer Main saved Queue with ID = SQ:5
    8/16/2012 1:59:14 PM - SonosController - SaveCurrentTrackInfo for zoneplayer Main saved MyTargetZoneLinkedList =
    8/16/2012 1:59:14 PM - SonosController - SaveCurrentTrackInfo for zoneplayer Main saved ChannelMapSet =
    8/16/2012 1:59:14 PM - SonosController - SearchZoneUDN called with SearchZoneUDN = RINCON_000E587B2EB801400 and SourceZoneUDN = RINCON_000E587B2EB801400 and DestinationZoneString =
    8/16/2012 1:59:14 PM - SonosController - GetZoneDestination called for ZoneName Main and TargetZones =
    8/16/2012 1:59:14 PM - SonosController - SavedPlayerList.add called with PlayerIndex = 1
    8/16/2012 1:59:14 PM - SonosController - SearchZoneUDN called with SearchZoneUDN = RINCON_000E587B2EB801400 and SourceZoneUDN = RINCON_000E587B2EB801400 and DestinationZoneString =
    8/16/2012 1:59:14 PM - SonosController - GetZoneDestination called for ZoneName Main and TargetZones =
    8/16/2012 1:59:14 PM - SonosController - HandleLinkingOn: Done Saving LinkgroupZoneDestination = uuid:RINCON_000E587B2EB801400
    8/16/2012 1:59:14 PM - SonosController - SetTransportState called for zoneplayer - Main with value = Stop
    8/16/2012 1:59:14 PM - SonosController - HandleLinkingOn: Start LinkgroupZoneDestination = uuid:RINCON_000E587B2EB801400
    8/16/2012 1:59:14 PM - SonosController - HandleLinkingOn: Done LinkgroupZoneDestination = uuid:RINCON_000E587B2EB801400
    8/16/2012 1:59:14 PM - SonosController - HandleLinkingOn done with LinkgroupName = TTS
    8/16/2012 1:59:14 PM - SonosController - DoCheckAnnouncementQueue activated with HostName = windogefault Text = This is a test and LinkgroupName = TTS
    8/16/2012 1:59:14 PM - SonosController - DoCheckAnnouncementQueue adds file = C:\Program Files (x86)\HomeSeer HSPRO\html/SonosController/Announcements/Ann_TTS_0 to Queue
    8/16/2012 1:59:14 PM - SonosController - DoCheckAnnouncementQueue calling SpeakToFile with Text This is a test and File C:\Program Files (x86)\HomeSeer HSPRO\html/SonosController/Announcements/Ann_TTS_0.wav
    8/16/2012 1:59:14 PM - SonosController - DoCheckAnnouncementQueue finished SpeakToFile
    8/16/2012 1:59:14 PM - SonosController - PlayURI called for zoneplayer Main with strURI = http://10.10.10.41:8080/SonosControl.../Ann_TTS_0.wav and MetaData = <DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><res protocolInfo="http-get:*:audio/wav:*">http://10.10.10.41:8080/SonosController/Announcements/Ann_TTS_0.wav</res><r:streamContent></r:streamContent><upnp:albumArtURI>http://10.10.10.41:8080/SonosController/Announcements/Announcement.jpg</upnp:albumArtURI><dc:title>HomeSeer Announcement</dc:title><upnp:class>object.item.audioItem.musicTrack</upnp:class><dc:creator>Dirk Corsus</dc:creator><upnp:album>SonosController</upnp:album><r:albumArtist>Dirk Corsus</r:albumArtist></item></DIDL-Lite>
    8/16/2012 1:59:14 PM - SonosController - DoCheckAnnouncementQueue is calling PlayURI with http://10.10.10.41:8080/SonosControl.../Ann_TTS_0.wav
    8/16/2012 1:59:14 PM - SonosController - SetTransportState called for zoneplayer - Main with value = Play
    8/16/2012 1:59:16 PM - SonosController - TransportChangeCallback for ZP - Main VarName = LastChange Value = <Event xmlns="urn:schemas-upnp-org:metadata-1-0/AVT/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/"><InstanceID val="0"><TransportState val="STOPPED"/><CurrentPlayMode val="NORMAL"/><CurrentCrossfadeMode val="0"/><NumberOfTracks val="1"/><CurrentTrack val="1"/><CurrentSection val="0"/><CurrentTrackURI val="http://10.10.10.41:8080/SonosController/Announcements/Ann_TTS_0.wav"/><CurrentTrackDuration val="0:00:00"/><CurrentTrackMetaData val="<DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><res protocolInfo="http-get:*:audio/wav:*">http://10.10.10.41:8080/SonosController/Announcements/Ann_TTS_0.wav</res><r:streamContent></r:streamContent><r:radioShowMd></r:radioShowMd><dc:title>Ann_TTS_0.wav</dc:title><upnp:class>object.item.audioItem.musicTrack</upnp:class></item></DIDL-Lite>"/><r:NextTrackURI val=""/><r:NextTrackMetaData val=""/><r:EnqueuedTransportURI val="http://10.10.10.41:8080/SonosController/Announcements/Ann_TTS_0.wav"/><r:EnqueuedTransportURIMetaData val="<DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><res protocolInfo="http-get:*:audio/wav:*">http://10.10.10.41:8080/SonosController/Announcements/Ann_TTS_0.wav</res><r:streamContent></r:streamContent><upnp:albumArtURI>http://10.10.10.41:8080/SonosController/Announcements/Announcement.jpg</upnp:albumArtURI><dc:title>HomeSeer Announcement</dc:title><upnp:class>object.item.audioItem.musicTrack</upnp:class><dc:creator>Dirk Corsus</dc:creator><upnp:album>SonosController</upnp:album><r:albumArtist>Dirk Corsus</r:albumArtist></item></DIDL-Lite>"/><PlaybackStorageMedium val="NETWORK"/><AVTransportURI val="http://10.10.10.41:8080/SonosController/Announcements/Ann_TTS_0.wav"/><AVTransportURIMetaData val="<DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><res protocolInfo="http-get:*:audio/wav:*">http://10.10.10.41:8080/SonosController/Announcements/Ann_TTS_0.wav</res><r:streamContent></r:streamContent><upnp:albumArtURI>http://10.10.10.41:8080/SonosController/Announcements/Announcement.jpg</upnp:albumArtURI><dc:title>HomeSeer Announcement</dc:title><upnp:class>object.item.audioItem.musicTrack</upnp:class><dc:creator>Dirk Corsus</dc:creator><upnp:album>SonosController</upnp:album><r:albumArtist>Dirk Corsus</r:albumArtist></item></DIDL-Lite>"/><CurrentTransportActions val="Set, Play, Stop, Pause, Seek, Next, Previous"/><TransportStatus val="OK"/><r:SleepTimerGeneration val="0"/><r:AlarmRunning val="0"/><r:SnoozeRunning val="0"/><r:RestartPending val="0"/><TransportPlaySpeed val="NOT_IMPLEMENTED"/><CurrentMediaDuration val="NOT_IMPLEMENTED"/><RecordStorageMedium val="NOT_IMPLEMENTED"/><PossiblePlaybackStorageMedia val="NONE, NETWORK"/><PossibleRecordStorageMedia val="NOT_IMPLEMENTED"/><RecordMediumWriteStatus val="NOT_IMPLEMENTED"/><CurrentRecordQualityMode val="NOT_IMPLEMENTED"/><PossibleRecordQualityModes val="NOT_IMPLEMENTED"/><NextAVTransportURI val="NOT_IMPLEMENTED"/><NextAVTransportURIMetaData val="NOT_IMPLEMENTED"/></InstanceID></Event>
    8/16/2012 1:59:16 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(TransportState) = STOPPED and previous state was = stopped
    8/16/2012 1:59:16 PM - SonosController - GetcurrentTrackInfo called for zoneplayer - Main
    8/16/2012 1:59:16 PM - SonosController - Main TrackInfo (CurrentURI) = http://10.10.10.41:8080/SonosControl.../Ann_TTS_0.wav and TrackInfo (CurrentURIMetaData) = <DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><res protocolInfo="http-get:*:audio/wav:*">http://10.10.10.41:8080/SonosController/Announcements/Ann_TTS_0.wav</res><r:streamContent></r:streamContent><upnp:albumArtURI>http://10.10.10.41:8080/SonosController/Announcements/Announcement.jpg</upnp:albumArtURI><dc:title>HomeSeer Announcement</dc:title><upnp:class>object.item.audioItem.musicTrack</upnp:class><dc:creator>Dirk Corsus</dc:creator><upnp:album>SonosController</upnp:album><r:albumArtist>Dirk Corsus</r:albumArtist></item></DIDL-Lite>
    8/16/2012 1:59:16 PM - SonosController - Main TrackInfo (MediaInfo/Title) = HomeSeer Announcement
    8/16/2012 1:59:16 PM - SonosController - Main TrackInfo (title) = HomeSeer Announcement
    8/16/2012 1:59:16 PM - SonosController - Main TrackInfo (creator) = Dirk Corsus
    8/16/2012 1:59:16 PM - SonosController - Main TrackInfo (album) = SonosController
    8/16/2012 1:59:16 PM - SonosController - Main TrackInfo (albumArtURI) = http://10.10.10.41:8080/SonosControl...nouncement.jpg
    8/16/2012 1:59:16 PM - SonosController - Main TrackInfo (CurrentURI) = http://10.10.10.41:8080/SonosControl.../Ann_TTS_0.wav
    8/16/2012 1:59:16 PM - SonosController - Main TrackInfo(Source) = Tracks
    8/16/2012 1:59:16 PM - SonosController - GetcurrentTrackInfo called GetPositionInfo for zoneplayer - Main and MetaData <DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><res protocolInfo="http-get:*:audio/wav:*">http://10.10.10.41:8080/SonosController/Announcements/Ann_TTS_0.wav</res><r:streamContent></r:streamContent><dc:title>Ann_TTS_0.wav</dc:title><upnp:class>object.item.audioItem.musicTrack</upnp:class></item></DIDL-Lite>
    8/16/2012 1:59:16 PM - SonosController - Main TrackInfo (title) = Ann_TTS_0.wav
    8/16/2012 1:59:16 PM - SonosController - GetAlbumArtPath called for zone Main with AlbumURI = http://10.10.10.41:8080/SonosControl...nouncement.jpg and NextTrack = False
    8/16/2012 1:59:16 PM - SonosController - GetAlbumArtPath returned for Zone - Main with AlbumURI = http://10.10.10.41:8080/SonosControl...nouncement.jpg and returned path= /SonosController/Images/Cover1.jpg
    8/16/2012 1:59:16 PM - SonosController - Main TrackInfo (Track Position) = 0:00:01
    8/16/2012 1:59:16 PM - SonosController - Main TrackInfo (Track Position Seconds) = 1
    8/16/2012 1:59:16 PM - SonosController - Main TrackInfo (Track Duration) = 0:00:00
    8/16/2012 1:59:16 PM - SonosController - Main TrackInfo (Queue Position) = 1
    8/16/2012 1:59:16 PM - SonosController - Main TrackInfo (% played) = 100
    8/16/2012 1:59:16 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentTrack) = 1
    8/16/2012 1:59:16 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentTrackDuration) = 0:00:00
    8/16/2012 1:59:16 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(NumberOfTracks) = 1
    8/16/2012 1:59:16 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentPlayMode) = NORMAL
    8/16/2012 1:59:16 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentSection) = 0
    8/16/2012 1:59:16 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(TransportStatus) = OK
    8/16/2012 1:59:16 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(Artist) = Dirk Corsus
    8/16/2012 1:59:16 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(title) = Ann_TTS_0.wav
    8/16/2012 1:59:16 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(Album) = SonosController
    8/16/2012 1:59:16 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentURI) = http://10.10.10.41:8080/SonosControl.../Ann_TTS_0.wav
    8/16/2012 1:59:16 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(AlbumArtURL) = /SonosController/Images/Cover1.jpg
    8/16/2012 1:59:16 PM - SonosController - TransportStateChange processed XML succesfully for zoneplayer = Main
    8/16/2012 1:59:16 PM - SonosController - Track Set for ZoneName = Main. Track = Ann_TTS_0.wav
    8/16/2012 1:59:16 PM - SonosController - Artist Set for ZoneName = Main. Artist = Dirk Corsus
    8/16/2012 1:59:16 PM - SonosController - Album Set for ZoneName = Main. Album = SonosController
    8/16/2012 1:59:16 PM - SonosController - ArtworkURL Set for ZoneName = Main. Album = /SonosController/Images/Cover1.jpg
    8/16/2012 1:59:16 PM - SonosController - PlayChangeNotifyCallback called for Zone - Main with ChangeType = SongChanged and Changevalue = stopped
    8/16/2012 1:59:16 PM - SonosController - CheckTrigger called in DeviceTrigger for Zone - Main with Trigger = SONOSCONTROLLERSonos Player TriggerTrack Change
    8/16/2012 1:59:16 PM - SonosController - Checking linked zones for SourceZone=Main. MyZoneIsSourceForLinkedZone=False and TargetZones =
    8/16/2012 1:59:16 PM - SonosController - HS updated in TransportChanged for zonePlayer Main. HS Code = ]2 and updated DeviceValue = False. Info = <table><tr><td><img src=/SonosController/Images/Cover1.jpg></td><td><p>STOPPED</p><p>Tracks</p><p>Ann_TTS_0.wav</p><p>Dirk Corsus</p><p>SonosController</p><p>/SonosController/Images/Cover1.jpg<p/></td></tr></table>
    8/16/2012 1:59:16 PM - SonosController - TransportChangeCallback for ZP - Main VarName = LastChange Value = <Event xmlns="urn:schemas-upnp-org:metadata-1-0/AVT/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/"><InstanceID val="0"><TransportState val="PLAYING"/><CurrentPlayMode val="NORMAL"/><CurrentCrossfadeMode val="0"/><NumberOfTracks val="1"/><CurrentTrack val="1"/><CurrentSection val="0"/><CurrentTrackURI val="http://10.10.10.41:8080/SonosController/Announcements/Ann_TTS_0.wav"/><CurrentTrackDuration val="0:00:00"/><CurrentTrackMetaData val="<DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><res protocolInfo="http-get:*:audio/wav:*">http://10.10.10.41:8080/SonosController/Announcements/Ann_TTS_0.wav</res><r:streamContent></r:streamContent><r:radioShowMd></r:radioShowMd><dc:title>Ann_TTS_0.wav</dc:title><upnp:class>object.item.audioItem.musicTrack</upnp:class></item></DIDL-Lite>"/><r:NextTrackURI val=""/><r:NextTrackMetaData val=""/><r:EnqueuedTransportURI val="http://10.10.10.41:8080/SonosController/Announcements/Ann_TTS_0.wav"/><r:EnqueuedTransportURIMetaData val="<DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><res protocolInfo="http-get:*:audio/wav:*">http://10.10.10.41:8080/SonosController/Announcements/Ann_TTS_0.wav</res><r:streamContent></r:streamContent><upnp:albumArtURI>http://10.10.10.41:8080/SonosController/Announcements/Announcement.jpg</upnp:albumArtURI><dc:title>HomeSeer Announcement</dc:title><upnp:class>object.item.audioItem.musicTrack</upnp:class><dc:creator>Dirk Corsus</dc:creator><upnp:album>SonosController</upnp:album><r:albumArtist>Dirk Corsus</r:albumArtist></item></DIDL-Lite>"/><PlaybackStorageMedium val="NETWORK"/><AVTransportURI val="http://10.10.10.41:8080/SonosController/Announcements/Ann_TTS_0.wav"/><AVTransportURIMetaData val="<DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><res protocolInfo="http-get:*:audio/wav:*">http://10.10.10.41:8080/SonosController/Announcements/Ann_TTS_0.wav</res><r:streamContent></r:streamContent><upnp:albumArtURI>http://10.10.10.41:8080/SonosController/Announcements/Announcement.jpg</upnp:albumArtURI><dc:title>HomeSeer Announcement</dc:title><upnp:class>object.item.audioItem.musicTrack</upnp:class><dc:creator>Dirk Corsus</dc:creator><upnp:album>SonosController</upnp:album><r:albumArtist>Dirk Corsus</r:albumArtist></item></DIDL-Lite>"/><CurrentTransportActions val="Set, Play, Stop, Pause, Seek, Next, Previous"/><TransportStatus val="OK"/><r:SleepTimerGeneration val="0"/><r:AlarmRunning val="0"/><r:SnoozeRunning val="0"/><r:RestartPending val="0"/><TransportPlaySpeed val="NOT_IMPLEMENTED"/><CurrentMediaDuration val="NOT_IMPLEMENTED"/><RecordStorageMedium val="NOT_IMPLEMENTED"/><PossiblePlaybackStorageMedia val="NONE, NETWORK"/><PossibleRecordStorageMedia val="NOT_IMPLEMENTED"/><RecordMediumWriteStatus val="NOT_IMPLEMENTED"/><CurrentRecordQualityMode val="NOT_IMPLEMENTED"/><PossibleRecordQualityModes val="NOT_IMPLEMENTED"/><NextAVTransportURI val="NOT_IMPLEMENTED"/><NextAVTransportURIMetaData val="NOT_IMPLEMENTED"/></InstanceID></Event>
    8/16/2012 1:59:16 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(TransportState) = PLAYING and previous state was = stopped
    8/16/2012 1:59:16 PM - SonosController - GetcurrentTrackInfo called for zoneplayer - Main
    8/16/2012 1:59:16 PM - SonosController - Main TrackInfo (CurrentURI) = http://10.10.10.41:8080/SonosControl.../Ann_TTS_0.wav and TrackInfo (CurrentURIMetaData) = <DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><res protocolInfo="http-get:*:audio/wav:*">http://10.10.10.41:8080/SonosController/Announcements/Ann_TTS_0.wav</res><r:streamContent></r:streamContent><upnp:albumArtURI>http://10.10.10.41:8080/SonosController/Announcements/Announcement.jpg</upnp:albumArtURI><dc:title>HomeSeer Announcement</dc:title><upnp:class>object.item.audioItem.musicTrack</upnp:class><dc:creator>Dirk Corsus</dc:creator><upnp:album>SonosController</upnp:album><r:albumArtist>Dirk Corsus</r:albumArtist></item></DIDL-Lite>
    8/16/2012 1:59:16 PM - SonosController - Main TrackInfo (MediaInfo/Title) = HomeSeer Announcement
    8/16/2012 1:59:16 PM - SonosController - Main TrackInfo (title) = HomeSeer Announcement
    8/16/2012 1:59:16 PM - SonosController - Main TrackInfo (creator) = Dirk Corsus
    8/16/2012 1:59:16 PM - SonosController - Main TrackInfo (album) = SonosController
    8/16/2012 1:59:16 PM - SonosController - Main TrackInfo (albumArtURI) = http://10.10.10.41:8080/SonosControl...nouncement.jpg
    8/16/2012 1:59:16 PM - SonosController - Main TrackInfo (CurrentURI) = http://10.10.10.41:8080/SonosControl.../Ann_TTS_0.wav
    8/16/2012 1:59:16 PM - SonosController - Main TrackInfo(Source) = Tracks
    8/16/2012 1:59:16 PM - SonosController - GetcurrentTrackInfo called GetPositionInfo for zoneplayer - Main and MetaData <DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><res protocolInfo="http-get:*:audio/wav:*">http://10.10.10.41:8080/SonosController/Announcements/Ann_TTS_0.wav</res><r:streamContent></r:streamContent><dc:title>Ann_TTS_0.wav</dc:title><upnp:class>object.item.audioItem.musicTrack</upnp:class></item></DIDL-Lite>
    8/16/2012 1:59:16 PM - SonosController - Main TrackInfo (title) = Ann_TTS_0.wav
    8/16/2012 1:59:16 PM - SonosController - GetAlbumArtPath called for zone Main with AlbumURI = http://10.10.10.41:8080/SonosControl...nouncement.jpg and NextTrack = False
    8/16/2012 1:59:16 PM - SonosController - GetAlbumArtPath returned for Zone - Main with AlbumURI = http://10.10.10.41:8080/SonosControl...nouncement.jpg and cached returned path= /SonosController/Images/Cover1.jpg
    8/16/2012 1:59:16 PM - SonosController - Main TrackInfo (Track Position) = 0:00:01
    8/16/2012 1:59:16 PM - SonosController - Main TrackInfo (Track Position Seconds) = 1
    8/16/2012 1:59:16 PM - SonosController - Main TrackInfo (Track Duration) = 0:00:00
    8/16/2012 1:59:16 PM - SonosController - Main TrackInfo (Queue Position) = 1
    8/16/2012 1:59:16 PM - SonosController - Main TrackInfo (% played) = 100
    8/16/2012 1:59:16 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentTrack) = 1
    8/16/2012 1:59:16 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentTrackDuration) = 0:00:00
    8/16/2012 1:59:16 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(NumberOfTracks) = 1
    8/16/2012 1:59:16 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentPlayMode) = NORMAL
    8/16/2012 1:59:16 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentSection) = 0
    8/16/2012 1:59:16 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(TransportStatus) = OK
    8/16/2012 1:59:16 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(Artist) = Dirk Corsus
    8/16/2012 1:59:16 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(title) = Ann_TTS_0.wav
    8/16/2012 1:59:16 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(Album) = SonosController
    8/16/2012 1:59:16 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentURI) = http://10.10.10.41:8080/SonosControl.../Ann_TTS_0.wav
    8/16/2012 1:59:16 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(AlbumArtURL) = /SonosController/Images/Cover1.jpg
    8/16/2012 1:59:16 PM - SonosController - TransportStateChange processed XML succesfully for zoneplayer = Main
    8/16/2012 1:59:16 PM - SonosController - Track Set for ZoneName = Main. Track = Ann_TTS_0.wav
    8/16/2012 1:59:16 PM - SonosController - Artist Set for ZoneName = Main. Artist = Dirk Corsus
    8/16/2012 1:59:16 PM - SonosController - Album Set for ZoneName = Main. Album = SonosController
    8/16/2012 1:59:16 PM - SonosController - ArtworkURL Set for ZoneName = Main. Album = /SonosController/Images/Cover1.jpg
    8/16/2012 1:59:16 PM - SonosController - CurrentPlayerState Set for ZoneName = Main. Value = playing
    8/16/2012 1:59:16 PM - SonosController - PlayChangeNotifyCallback called for Zone - Main with ChangeType = PlayStatusChanged and Changevalue = playing
    8/16/2012 1:59:16 PM - SonosController - CheckTrigger called in DeviceTrigger for Zone - Main with Trigger = SONOSCONTROLLERSonos Player TriggerPlayer Start Playing
    8/16/2012 1:59:16 PM - SonosController - PlayChangeNotifyCallback called for Zone - Main with ChangeType = SongChanged and Changevalue = playing
    8/16/2012 1:59:16 PM - SonosController - CheckTrigger called in DeviceTrigger for Zone - Main with Trigger = SONOSCONTROLLERSonos Player TriggerTrack Change
    8/16/2012 1:59:16 PM - SonosController - Checking linked zones for SourceZone=Main. MyZoneIsSourceForLinkedZone=False and TargetZones =
    8/16/2012 1:59:16 PM - SonosController - HS DeviceValue updated in TransportChanged for zonePlayer Main. HS Code = ]2 and value = playing
    8/16/2012 1:59:16 PM - SonosController - HS updated in TransportChanged for zonePlayer Main. HS Code = ]2 and updated DeviceValue = True. Info = <table><tr><td><img src=/SonosController/Images/Cover1.jpg></td><td><p>PLAYING</p><p>Tracks</p><p>Ann_TTS_0.wav</p><p>Dirk Corsus</p><p>SonosController</p><p>/SonosController/Images/Cover1.jpg<p/></td></tr></table>
    8/16/2012 2:00:56 PM - SonosController - Error in DoCheckAnnouncementQueue. 100 seconds expired since the announcement started and no end was received.
    8/16/2012 2:00:56 PM - SonosController - DoCheckAnnouncementQueue called for linkgroup TTS and State = asFilePlayed and isFile = True
    8/16/2012 2:00:56 PM - SonosController - HandleLinkingOff called with LinkgroupName = TTS
    8/16/2012 2:00:56 PM - SonosController - GetLinkGroupInfo called with LinkgroupName = TTS
    8/16/2012 2:00:56 PM - SonosController - SavedPlayerList.GetLastIndex called
    8/16/2012 2:00:56 PM - SonosController - SavedPlayerList.GetLastIndex called and returned Index = 1
    8/16/2012 2:00:56 PM - SonosController - RestoreCurrentTrackInfo for Zonename = Main and MySavedTrackinfo = x-rincon-queue:RINCON_000E587B2EB801400#0
    8/16/2012 2:00:56 PM - SonosController - PlayURI called for zoneplayer Main with strURI = x-rincon-queue:RINCON_000E587B2EB801400#0 and MetaData =
    8/16/2012 2:00:57 PM - SonosController - RestoreCurrentTrackInfo is restoring a saved queue for zoneplayer Main with MySavedQueueObjectID = SQ:5
    8/16/2012 2:00:57 PM - SonosController - GetTrackMetaData called for zoneplayer = Main with URI= SQ:5 and BrowseOnlyMetaData = True
    8/16/2012 2:00:57 PM - SonosController - AddTrackToQueue called for zoneplayer Main with URI = file:///jffs/settings/savedqueues.rsq#5 MetaData=<DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><container id="SQ:5" parentID="SQ:" restricted="true"><dc:title>SCQueue-Main-TTS</dc:title><res protocolInfo="file:*:audio/mpegurl:*">file:///jffs/settings/savedqueues.rsq#5</res><upnp:class>object.container.playlistContainer</upnp:class></container></DIDL-Lite> QueuePosition=0 EnqueuedNext=False
    8/16/2012 2:00:57 PM - SonosController - DestroySonosObject called with ObjectID = SQ:5 for Zone = Main
    8/16/2012 2:00:57 PM - SonosController - SetVolumeLevel called for ZonePlayer = Main with values Channel=Master Value=43
    8/16/2012 2:00:57 PM - SonosController - SetPlayMode called for ZoneName Main with value = NORMAL
    8/16/2012 2:00:57 PM - SonosController - CompareReplicationChanges for zoneplayer Main detected change for SavedQueuesUpdateID with Old = 11 and New = 12
    8/16/2012 2:00:57 PM - SonosController - ZonePairingChanged called for Zone = Main with ChannelMapSet
    8/16/2012 2:00:57 PM - SonosController - SetTransportState called for zoneplayer - Main with value = STOPPED
    8/16/2012 2:00:57 PM - SonosController - PlayChangeNotifyCallback called for Zone - Main with ChangeType = PlayStatusChanged and Changevalue = stopped
    8/16/2012 2:00:57 PM - SonosController - CheckTrigger called in DeviceTrigger for Zone - Main with Trigger = SONOSCONTROLLERSonos Player TriggerPlayer Start Playing
    8/16/2012 2:00:57 PM - SonosController - RestoreCurrentTrackInfo updated HS DeviceValue for zonePlayer Main. HS Code = ]2 and value = stopped
    8/16/2012 2:00:57 PM - SonosController - RestoreCurrentTrackInfo is done for zonePlayer Main
    8/16/2012 2:00:57 PM - SonosController - SavedPlayerList.GetLastIndex called
    8/16/2012 2:00:57 PM - SonosController - HandleLinkingOff done with LinkgroupName = TTS
    8/16/2012 2:00:57 PM - SonosController - DeleteHeadOfAnnouncementQueue called
    8/16/2012 2:00:57 PM - SonosController - DoCheckAnnouncementQueue called and all announcements were processed
    8/16/2012 2:00:57 PM - SonosController - Rendering Change callback - ZonePlayer Main: Var Name = LastChange Value = <Event xmlns="urn:schemas-upnp-org:metadata-1-0/RCS/"><InstanceID val="0"><Volume channel="Master" val="43"/><Volume channel="LF" val="100"/><Volume channel="RF" val="100"/><Mute channel="Master" val="0"/><Mute channel="LF" val="0"/><Mute channel="RF" val="0"/></InstanceID></Event>
    8/16/2012 2:00:57 PM - SonosController - Main : Rendering (Master Volume) = 43
    8/16/2012 2:00:57 PM - SonosController - Main : Rendering (LF Volume) = 100
    8/16/2012 2:00:57 PM - SonosController - Main : Rendering (RF Volume) = 100
    8/16/2012 2:00:57 PM - SonosController - Main : Rendering (Mute Master Volume) = 0
    8/16/2012 2:00:57 PM - SonosController - Main : Rendering (Mute LF Volume) = 0
    8/16/2012 2:00:57 PM - SonosController - Main : Rendering (Mute RF Volume) = 0
    8/16/2012 2:00:57 PM - SonosController - PlayChangeNotifyCallback called for Zone - Main with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly
    8/16/2012 2:01:26 PM - SonosController - TransportChangeCallback for ZP - Main VarName = LastChange Value = <Event xmlns="urn:schemas-upnp-org:metadata-1-0/AVT/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/"><InstanceID val="0"><TransportState val="STOPPED"/><CurrentPlayMode val="NORMAL"/><CurrentCrossfadeMode val="0"/><NumberOfTracks val="1"/><CurrentTrack val="1"/><CurrentSection val="0"/><CurrentTrackURI val="http://10.10.10.41:8080/SonosController/Announcements/Ann_TTS_0.wav"/><CurrentTrackDuration val="0:00:00"/><CurrentTrackMetaData val="<DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><res protocolInfo="http-get:*:audio/wav:*">http://10.10.10.41:8080/SonosController/Announcements/Ann_TTS_0.wav</res><r:streamContent></r:streamContent><r:radioShowMd></r:radioShowMd><dc:title>Ann_TTS_0.wav</dc:title><upnp:class>object.item.audioItem.musicTrack</upnp:class></item></DIDL-Lite>"/><r:NextTrackURI val=""/><r:NextTrackMetaData val=""/><r:EnqueuedTransportURI val="http://10.10.10.41:8080/SonosController/Announcements/Ann_TTS_0.wav"/><r:EnqueuedTransportURIMetaData val="<DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><res protocolInfo="http-get:*:audio/wav:*">http://10.10.10.41:8080/SonosController/Announcements/Ann_TTS_0.wav</res><r:streamContent></r:streamContent><upnp:albumArtURI>http://10.10.10.41:8080/SonosController/Announcements/Announcement.jpg</upnp:albumArtURI><dc:title>HomeSeer Announcement</dc:title><upnp:class>object.item.audioItem.musicTrack</upnp:class><dc:creator>Dirk Corsus</dc:creator><upnp:album>SonosController</upnp:album><r:albumArtist>Dirk Corsus</r:albumArtist></item></DIDL-Lite>"/><PlaybackStorageMedium val="NETWORK"/><AVTransportURI val="http://10.10.10.41:8080/SonosController/Announcements/Ann_TTS_0.wav"/><AVTransportURIMetaData val="<DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><res protocolInfo="http-get:*:audio/wav:*">http://10.10.10.41:8080/SonosController/Announcements/Ann_TTS_0.wav</res><r:streamContent></r:streamContent><upnp:albumArtURI>http://10.10.10.41:8080/SonosController/Announcements/Announcement.jpg</upnp:albumArtURI><dc:title>HomeSeer Announcement</dc:title><upnp:class>object.item.audioItem.musicTrack</upnp:class><dc:creator>Dirk Corsus</dc:creator><upnp:album>SonosController</upnp:album><r:albumArtist>Dirk Corsus</r:albumArtist></item></DIDL-Lite>"/><CurrentTransportActions val="Set, Play, Stop, Pause, Seek, Next, Previous"/><TransportStatus val="OK"/><r:SleepTimerGeneration val="0"/><r:AlarmRunning val="0"/><r:SnoozeRunning val="0"/><r:RestartPending val="0"/><TransportPlaySpeed val="NOT_IMPLEMENTED"/><CurrentMediaDuration val="NOT_IMPLEMENTED"/><RecordStorageMedium val="NOT_IMPLEMENTED"/><PossiblePlaybackStorageMedia val="NONE, NETWORK"/><PossibleRecordStorageMedia val="NOT_IMPLEMENTED"/><RecordMediumWriteStatus val="NOT_IMPLEMENTED"/><CurrentRecordQualityMode val="NOT_IMPLEMENTED"/><PossibleRecordQualityModes val="NOT_IMPLEMENTED"/><NextAVTransportURI val="NOT_IMPLEMENTED"/><NextAVTransportURIMetaData val="NOT_IMPLEMENTED"/></InstanceID></Event>
    8/16/2012 2:01:26 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(TransportState) = STOPPED and previous state was = playing
    8/16/2012 2:01:26 PM - SonosController - GetcurrentTrackInfo called for zoneplayer - Main
    8/16/2012 2:01:26 PM - SonosController - Main TrackInfo (CurrentURI) = x-rincon-queue:RINCON_000E587B2EB801400#0 and TrackInfo (CurrentURIMetaData) =
    8/16/2012 2:01:26 PM - SonosController - Main TrackInfo (CurrentURI) = x-rincon-queue:RINCON_000E587B2EB801400#0
    8/16/2012 2:01:26 PM - SonosController - Main TrackInfo(Source) = Tracks
    8/16/2012 2:01:26 PM - SonosController - GetcurrentTrackInfo called GetPositionInfo for zoneplayer - Main and MetaData <DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><res protocolInfo="x-file-cifs:*:audio/mpeg:*" duration="0:02:24">x-file-cifs://media/music/DJ%20Tiesto/In%20Search%20of%20Sunrise,%20Vol.%205/01%20-%20DJ%20Tiesto%20-%20Malibu%20Beach%20-%20Conil.mp3</res><r:streamContent></r:streamContent><upnp:albumArtURI>/getaa?u=x-file-cifs%3a%2f%2fmedia%2fmusic%2fDJ%2520Tiesto%2fIn%2520Search%2 520of%2520Sunrise,%2520Vol.%25205%2f01%2520-%2520DJ%2520Tiesto%2520-%2520Malibu%2520Beach%2520-%2520Conil.mp3&v=1</upnp:albumArtURI><dc:title>Malibu Beach - Conil</dc:title><upnp:class>object.item.audioItem.musicTrack</upnp:class><dc:creator>DJ Tiesto</dc:creator><upnp:album>In Search of Sunrise, Vol. 5</upnp:album><upnpriginalTrackNumber>1</upnpriginalTrackNumber><r:albumArtist>DJ Tiesto</r:albumArtist></item></DIDL-Lite>
    8/16/2012 2:01:26 PM - SonosController - Main TrackInfo (title) = Malibu Beach - Conil
    8/16/2012 2:01:26 PM - SonosController - Main TrackInfo (creator) = DJ Tiesto
    8/16/2012 2:01:26 PM - SonosController - Main TrackInfo (album) = In Search of Sunrise, Vol. 5
    8/16/2012 2:01:26 PM - SonosController - Main TrackInfo (albumArtURI) = http://10.10.10.167:1400/getaa?u=x-f...0Conil.mp3&v=1
    8/16/2012 2:01:26 PM - SonosController - GetAlbumArtPath called for zone Main with AlbumURI = http://10.10.10.167:1400/getaa?u=x-f...0Conil.mp3&v=1 and NextTrack = False
    8/16/2012 2:01:26 PM - SonosController - GetAlbumArtPath returned for Zone - Main with AlbumURI = http://10.10.10.167:1400/getaa?u=x-f...0Conil.mp3&v=1 and returned path= /SonosController/Images/Cover1.jpg
    8/16/2012 2:01:26 PM - SonosController - Main TrackInfo (Track Position) = 0:00:00
    8/16/2012 2:01:26 PM - SonosController - Main TrackInfo (Track Position Seconds) = 0
    8/16/2012 2:01:26 PM - SonosController - Main TrackInfo (Track Duration) = 0:02:24
    8/16/2012 2:01:26 PM - SonosController - Main TrackInfo (Queue Position) = 1
    8/16/2012 2:01:26 PM - SonosController - Main TrackInfo (% played) = 0
    8/16/2012 2:01:26 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentTrack) = 1
    8/16/2012 2:01:26 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentTrackDuration) = 0:00:00
    8/16/2012 2:01:26 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(NumberOfTracks) = 1
    8/16/2012 2:01:26 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentPlayMode) = NORMAL
    8/16/2012 2:01:26 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentSection) = 0
    8/16/2012 2:01:26 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(TransportStatus) = OK
    8/16/2012 2:01:26 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(Artist) = DJ Tiesto
    8/16/2012 2:01:26 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(title) = Malibu Beach - Conil
    8/16/2012 2:01:26 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(Album) = In Search of Sunrise, Vol. 5
    8/16/2012 2:01:26 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentURI) = x-rincon-queue:RINCON_000E587B2EB801400#0
    8/16/2012 2:01:26 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(AlbumArtURL) = /SonosController/Images/Cover1.jpg
    8/16/2012 2:01:26 PM - SonosController - TransportStateChange processed XML succesfully for zoneplayer = Main
    8/16/2012 2:01:26 PM - SonosController - Track Set for ZoneName = Main. Track = Malibu Beach - Conil
    8/16/2012 2:01:26 PM - SonosController - Artist Set for ZoneName = Main. Artist = DJ Tiesto
    8/16/2012 2:01:26 PM - SonosController - Album Set for ZoneName = Main. Album = In Search of Sunrise, Vol. 5
    8/16/2012 2:01:26 PM - SonosController - ArtworkURL Set for ZoneName = Main. Album = /SonosController/Images/Cover1.jpg
    8/16/2012 2:01:26 PM - SonosController - CurrentPlayerState Set for ZoneName = Main. Value = stopped
    8/16/2012 2:01:26 PM - SonosController - PlayChangeNotifyCallback called for Zone - Main with ChangeType = PlayStatusChanged and Changevalue = stopped
    8/16/2012 2:01:26 PM - SonosController - CheckTrigger called in DeviceTrigger for Zone - Main with Trigger = SONOSCONTROLLERSonos Player TriggerPlayer Stop
    8/16/2012 2:01:26 PM - SonosController - Checking linked zones for SourceZone=Main. MyZoneIsSourceForLinkedZone=False and TargetZones =
    8/16/2012 2:01:26 PM - SonosController - HS DeviceValue updated in TransportChanged for zonePlayer Main. HS Code = ]2 and value = stopped
    8/16/2012 2:01:26 PM - SonosController - HS updated in TransportChanged for zonePlayer Main. HS Code = ]2 and updated DeviceValue = True. Info = <table><tr><td><img src=/SonosController/Images/Cover1.jpg></td><td><p>STOPPED</p><p>Tracks</p><p>Malibu Beach - Conil</p><p>DJ Tiesto</p><p>In Search of Sunrise, Vol. 5</p><p>/SonosController/Images/Cover1.jpg<p/></td></tr></table>
    8/16/2012 2:01:26 PM - SonosController - TransportChangeCallback for ZP - Main VarName = LastChange Value = <Event xmlns="urn:schemas-upnp-org:metadata-1-0/AVT/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/"><InstanceID val="0"><TransportState val="STOPPED"/><CurrentPlayMode val="NORMAL"/><CurrentCrossfadeMode val="0"/><NumberOfTracks val="56"/><CurrentTrack val="1"/><CurrentSection val="0"/><CurrentTrackURI val="x-file-cifs://media/music/DJ%20Tiesto/In%20Search%20of%20Sunrise,%20Vol.%205/01%20-%20DJ%20Tiesto%20-%20Malibu%20Beach%20-%20Conil.mp3"/><CurrentTrackDuration val="0:02:24"/><CurrentTrackMetaData val="<DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><res protocolInfo="x-file-cifs:*:audio/mpeg:*" duration="0:02:24">x-file-cifs://media/music/DJ%20Tiesto/In%20Search%20of%20Sunrise,%20Vol.%205/01%20-%20DJ%20Tiesto%20-%20Malibu%20Beach%20-%20Conil.mp3</res><r:streamContent></r:streamContent><r:radioShowMd></r:radioShowMd><upnp:albumArtURI>/getaa?u=x-file-cifs%3a%2f%2fmedia%2fmusic%2fDJ%2520Tiesto%2fIn%2520Search%2 520of%2520Sunrise,%2520Vol.%25205%2f01%2520-%2520DJ%2520Tiesto%2520-%2520Malibu%2520Beach%2520-%2520Conil.mp3&amp;v=1</upnp:albumArtURI><dc:title>Malibu Beach - Conil</dc:title><upnp:class>object.item.audioItem.musicTrack</upnp:class><dc:creator>DJ Tiesto</dc:creator><upnp:album>In Search of Sunrise, Vol. 5</upnp:album><upnpriginalTrackNumber>1</upnpriginalTrackNumber><r:albumArtist>DJ Tiesto</r:albumArtist></item></DIDL-Lite>"/><r:NextTrackURI val="x-file-cifs://media/music/DJ%20Tiesto/In%20Search%20of%20Sunrise,%20Vol.%205/02%20-%20DJ%20Tiesto%20-%20Colour%20My%20Eyes%20-%20Celine.mp3"/><r:NextTrackMetaData val="<DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><res protocolInfo="x-file-cifs:*:audio/mpeg:*" duration="0:06:24">x-file-cifs://media/music/DJ%20Tiesto/In%20Search%20of%20Sunrise,%20Vol.%205/02%20-%20DJ%20Tiesto%20-%20Colour%20My%20Eyes%20-%20Celine.mp3</res><upnp:albumArtURI>/getaa?u=x-file-cifs%3a%2f%2fmedia%2fmusic%2fDJ%2520Tiesto%2fIn%2520Search%2 520of%2520Sunrise,%2520Vol.%25205%2f02%2520-%2520DJ%2520Tiesto%2520-%2520Colour%2520My%2520Eyes%2520-%2520Celine.mp3&amp;v=1</upnp:albumArtURI><dc:title>Colour My Eyes - Celine</dc:title><upnp:class>object.item.audioItem.musicTrack</upnp:class><dc:creator>DJ Tiesto</dc:creator><upnp:album>In Search of Sunrise, Vol. 5</upnp:album><upnpriginalTrackNumber>2</upnpriginalTrackNumber><r:albumArtist>DJ Tiesto</r:albumArtist></item></DIDL-Lite>"/><r:EnqueuedTransportURI val="file:///jffs/settings/savedqueues.rsq#5"/><r:EnqueuedTransportURIMetaData val="<DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><container id="SQ:5" parentID="SQ:" restricted="true"><dc:title>SCQueue-Main-TTS</dc:title><res protocolInfo="file:*:audio/mpegurl:*">file:///jffs/settings/savedqueues.rsq#5</res><upnp:class>object.container.playlistContainer</upnp:class></container></DIDL-Lite>"/><PlaybackStorageMedium val="NETWORK"/><AVTransportURI val="x-rincon-queue:RINCON_000E587B2EB801400#0"/><AVTransportURIMetaData val=""/><CurrentTransportActions val="Set, Play, Stop, Pause, Seek, Next, Previous"/><TransportStatus val="OK"/><r:SleepTimerGeneration val="0"/><r:AlarmRunning val="0"/><r:SnoozeRunning val="0"/><r:RestartPending val="0"/><TransportPlaySpeed val="NOT_IMPLEMENTED"/><CurrentMediaDuration val="NOT_IMPLEMENTED"/><RecordStorageMedium val="NOT_IMPLEMENTED"/><PossiblePlaybackStorageMedia val="NONE, NETWORK"/><PossibleRecordStorageMedia val="NOT_IMPLEMENTED"/><RecordMediumWriteStatus val="NOT_IMPLEMENTED"/><CurrentRecordQualityMode val="NOT_IMPLEMENTED"/><PossibleRecordQualityModes val="NOT_IMPLEMENTED"/><NextAVTransportURI val="NOT_IMPLEMENTED"/><NextAVTransportURIMetaData val="NOT_IMPLEMENTED"/></InstanceID></Event>
    8/16/2012 2:01:26 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(TransportState) = STOPPED and previous state was = stopped
    8/16/2012 2:01:26 PM - SonosController - GetcurrentTrackInfo called for zoneplayer - Main
    8/16/2012 2:01:26 PM - SonosController - Main TrackInfo (CurrentURI) = x-rincon-queue:RINCON_000E587B2EB801400#0 and TrackInfo (CurrentURIMetaData) =
    8/16/2012 2:01:26 PM - SonosController - Main TrackInfo (CurrentURI) = x-rincon-queue:RINCON_000E587B2EB801400#0
    8/16/2012 2:01:26 PM - SonosController - Main TrackInfo(Source) = Tracks
    8/16/2012 2:01:26 PM - SonosController - GetcurrentTrackInfo called GetPositionInfo for zoneplayer - Main and MetaData <DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><res protocolInfo="x-file-cifs:*:audio/mpeg:*" duration="0:02:24">x-file-cifs://media/music/DJ%20Tiesto/In%20Search%20of%20Sunrise,%20Vol.%205/01%20-%20DJ%20Tiesto%20-%20Malibu%20Beach%20-%20Conil.mp3</res><r:streamContent></r:streamContent><upnp:albumArtURI>/getaa?u=x-file-cifs%3a%2f%2fmedia%2fmusic%2fDJ%2520Tiesto%2fIn%2520Search%2 520of%2520Sunrise,%2520Vol.%25205%2f01%2520-%2520DJ%2520Tiesto%2520-%2520Malibu%2520Beach%2520-%2520Conil.mp3&v=1</upnp:albumArtURI><dc:title>Malibu Beach - Conil</dc:title><upnp:class>object.item.audioItem.musicTrack</upnp:class><dc:creator>DJ Tiesto</dc:creator><upnp:album>In Search of Sunrise, Vol. 5</upnp:album><upnpriginalTrackNumber>1</upnpriginalTrackNumber><r:albumArtist>DJ Tiesto</r:albumArtist></item></DIDL-Lite>
    8/16/2012 2:01:26 PM - SonosController - Main TrackInfo (title) = Malibu Beach - Conil
    8/16/2012 2:01:26 PM - SonosController - Main TrackInfo (creator) = DJ Tiesto
    8/16/2012 2:01:26 PM - SonosController - Main TrackInfo (album) = In Search of Sunrise, Vol. 5
    8/16/2012 2:01:26 PM - SonosController - Main TrackInfo (albumArtURI) = http://10.10.10.167:1400/getaa?u=x-f...0Conil.mp3&v=1
    8/16/2012 2:01:26 PM - SonosController - GetAlbumArtPath called for zone Main with AlbumURI = http://10.10.10.167:1400/getaa?u=x-f...0Conil.mp3&v=1 and NextTrack = False
    8/16/2012 2:01:26 PM - SonosController - GetAlbumArtPath returned for Zone - Main with AlbumURI = http://10.10.10.167:1400/getaa?u=x-f...0Conil.mp3&v=1 and cached returned path= /SonosController/Images/Cover1.jpg
    8/16/2012 2:01:26 PM - SonosController - Main TrackInfo (Track Position) = 0:00:00
    8/16/2012 2:01:26 PM - SonosController - Main TrackInfo (Track Position Seconds) = 0
    8/16/2012 2:01:26 PM - SonosController - Main TrackInfo (Track Duration) = 0:02:24
    8/16/2012 2:01:26 PM - SonosController - Main TrackInfo (Queue Position) = 1
    8/16/2012 2:01:26 PM - SonosController - Main TrackInfo (% played) = 0
    8/16/2012 2:01:26 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentTrack) = 1
    8/16/2012 2:01:26 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentTrackDuration) = 0:02:24
    8/16/2012 2:01:26 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(NumberOfTracks) = 56
    8/16/2012 2:01:26 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentPlayMode) = NORMAL
    8/16/2012 2:01:26 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentSection) = 0
    8/16/2012 2:01:26 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(TransportStatus) = OK
    8/16/2012 2:01:26 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(Artist) = DJ Tiesto
    8/16/2012 2:01:26 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(title) = Malibu Beach - Conil
    8/16/2012 2:01:26 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(Album) = In Search of Sunrise, Vol. 5
    8/16/2012 2:01:26 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentURI) = x-rincon-queue:RINCON_000E587B2EB801400#0
    8/16/2012 2:01:26 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(AlbumArtURL) = /SonosController/Images/Cover1.jpg
    8/16/2012 2:01:26 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(Next Artist) = DJ Tiesto
    8/16/2012 2:01:26 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(Next Title) = Colour My Eyes - Celine
    8/16/2012 2:01:26 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(Next Album) = In Search of Sunrise, Vol. 5
    8/16/2012 2:01:26 PM - SonosController - GetAlbumArtPath called for zone Main with AlbumURI = http://10.10.10.167:1400/getaa?u=x-f...Celine.mp3&v=1 and NextTrack = True
    8/16/2012 2:01:26 PM - SonosController - GetAlbumArtPath returned for Zone - Main with AlbumURI = http://10.10.10.167:1400/getaa?u=x-f...Celine.mp3&v=1 and cached returned path= /SonosController/Images/NextCover1.jpg
    8/16/2012 2:01:26 PM - SonosController - TransportChangeCallback for ZP - Main TransportState(Next albumArtURI) = /SonosController/Images/NextCover1.jpg
    8/16/2012 2:01:26 PM - SonosController - TransportStateChange processed XML succesfully for zoneplayer = Main
    8/16/2012 2:01:26 PM - SonosController - Track Set for ZoneName = Main. Track = Malibu Beach - Conil
    8/16/2012 2:01:26 PM - SonosController - Artist Set for ZoneName = Main. Artist = DJ Tiesto
    8/16/2012 2:01:26 PM - SonosController - Album Set for ZoneName = Main. Album = In Search of Sunrise, Vol. 5
    8/16/2012 2:01:26 PM - SonosController - ArtworkURL Set for ZoneName = Main. Album = /SonosController/Images/Cover1.jpg
    8/16/2012 2:01:26 PM - SonosController - PlayChangeNotifyCallback called for Zone - Main with ChangeType = SongChanged and Changevalue = stopped
    8/16/2012 2:01:26 PM - SonosController - CheckTrigger called in DeviceTrigger for Zone - Main with Trigger = SONOSCONTROLLERSonos Player TriggerTrack Change
    8/16/2012 2:01:26 PM - SonosController - Checking linked zones for SourceZone=Main. MyZoneIsSourceForLinkedZone=False and TargetZones =
    8/16/2012 2:01:26 PM - SonosController - HS updated in TransportChanged for zonePlayer Main. HS Code = ]2 and updated DeviceValue = False. Info = <table><tr><td><img src=/SonosController/Images/Cover1.jpg></td><td><p>STOPPED</p><p>Tracks</p><p>Malibu Beach - Conil</p><p>DJ Tiesto</p><p>In Search of Sunrise, Vol. 5</p><p>/SonosController/Images/Cover1.jpg<p/></td></tr></table>
    Attached Files
    Tags: None
    #2
    According to this log, the player wasn't doing anything when the announcement started. See this:

    8/16/2012 1:59:13 PM - SonosController - SaveCurrentTrackInfo for zoneplayer Main saved Player State = STOPPED

    The player was in stopped state.

    Dirk

    Comment

      #3
      Originally posted by dcorsus View Post
      According to this log, the player wasn't doing anything when the announcement started. See this:

      8/16/2012 1:59:13 PM - SonosController - SaveCurrentTrackInfo for zoneplayer Main saved Player State = STOPPED

      The player was in stopped state.

      Dirk
      It was though, it was playing a TuneIn radio channel (DI: Drum & Bass).

      I've actually noticed that when playing these channels, it doesn't auto update the HS Sonos plugin with information either. However, if I play from library, it updates immediately. Is there possibly an issue of playing internet radio streams and it not updating HS Sonos plugin? Any more debugging I can do?

      Also, this is on Windows 7 with firewall completely disabled and no virus software installed.

      Comment

        #4
        This is one where the plugin definitely thinks it is playing:

        8/17/2012 11:40:46 AM - Event - Event Trigger "Test Speech"
        8/17/2012 11:40:46 AM - SonosController - SpeakIn called for Device = 0, Text = This is a test, Wait=True, Host = $SONOS$TTS$windogefault
        8/17/2012 11:40:46 AM - SonosController - AddAnnouncementToQueue called for LinkGroupName = TTS and Text = This is a test
        8/17/2012 11:40:46 AM - SonosController - GetLinkgroupSourceZone called with LinkgroupName = TTS
        8/17/2012 11:40:46 AM - SonosController - GetMusicAPI (Integer) was called with Value: 1
        8/17/2012 11:40:46 AM - SonosController - GetTailOfAnnouncementQueue called
        8/17/2012 11:40:46 AM - SonosController - DoCheckAnnouncementQueue called for linkgroup TTS and State = asIdle and isFile = True
        8/17/2012 11:40:46 AM - SonosController - HandleLinkingOn called with LinkgroupName = TTS
        8/17/2012 11:40:46 AM - SonosController - GetLinkGroupInfo called with LinkgroupName = TTS
        8/17/2012 11:40:46 AM - SonosController - HandleLinkingOn found uuid:RINCON_000E587B2EB801400 as source
        8/17/2012 11:40:46 AM - SonosController - SaveCurrentTrackInfo called for zoneplayer - Main
        8/17/2012 11:40:46 AM - SonosController - SaveCurrentTrackInfo for zoneplayer Main saved ZoneIsLinked = False
        8/17/2012 11:40:46 AM - SonosController - SaveCurrentTrackInfo for zoneplayer Main saved SourceLinkedZone =
        8/17/2012 11:40:46 AM - SonosController - SaveCurrentTrackInfo for zoneplayer Main with Track(0) = [DI] Vocal Trance
        8/17/2012 11:40:46 AM - SonosController - SaveCurrentTrackInfo for zoneplayer Main with Track(1) =
        8/17/2012 11:40:46 AM - SonosController - SaveCurrentTrackInfo for zoneplayer Main with Track(2) = Immanuel D - Let Me In
        8/17/2012 11:40:46 AM - SonosController - SaveCurrentTrackInfo for zoneplayer Main with Track(3) = x-sonosapi-stream:s49632?sid=254&flags=32
        8/17/2012 11:40:46 AM - SonosController - SaveCurrentTrackInfo for zoneplayer Main with Track(4) = /SonosController/Images/Cover1.jpg
        8/17/2012 11:40:46 AM - SonosController - SaveCurrentTrackInfo for zoneplayer Main with Track(5) = 00:00:32
        8/17/2012 11:40:46 AM - SonosController - SaveCurrentTrackInfo for zoneplayer Main with Track(6) = 0:00:00
        8/17/2012 11:40:46 AM - SonosController - SaveCurrentTrackInfo for zoneplayer Main with Track(7) = 1
        8/17/2012 11:40:46 AM - SonosController - SaveCurrentTrackInfo for zoneplayer Main with Track(8) = 100
        8/17/2012 11:40:46 AM - SonosController - SaveCurrentTrackInfo for zoneplayer Main with Track(9) = Radio - [DI] Vocal Trance
        8/17/2012 11:40:46 AM - SonosController - SaveCurrentTrackInfo for zoneplayer Main with Track(10) = True
        8/17/2012 11:40:46 AM - SonosController - SaveCurrentTrackInfo for zoneplayer Main with Track(11) = [DI] Vocal Trance
        8/17/2012 11:40:46 AM - SonosController - SaveCurrentTrackInfo for zoneplayer Main with Track(12) = <DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><dc:title>[DI] Vocal Trance</dc:title><upnp:class>object.item.audioItem.audioBroadcast</upnp:class><desc id="cdudn" nameSpace="urn:schemas-rinconnetworks-com:metadata-1-0/">SA_RINCON65031_</desc></item></DIDL-Lite>
        8/17/2012 11:40:46 AM - SonosController - SaveCurrentTrackInfo for zoneplayer Main saved Queue position = 0
        8/17/2012 11:40:46 AM - SonosController - SaveCurrentTrackInfo for zoneplayer Main saved Player State = PLAYING
        8/17/2012 11:40:46 AM - SonosController - SaveCurrentTrackInfo for zoneplayer Main saved Master Volume = 47
        8/17/2012 11:40:46 AM - SonosController - SaveCurrentTrackInfo for zoneplayer Main saved Mute State = False
        8/17/2012 11:40:46 AM - SonosController - SaveCurrentTrackInfo for zoneplayer Main saved PlayMode State = NORMAL
        8/17/2012 11:40:46 AM - SonosController - TracksInQueue called for ZonePlayer = Main and found 0 tracks.
        8/17/2012 11:40:46 AM - SonosController - SaveCurrentTrackInfo for zoneplayer Main saved MyTargetZoneLinkedList =
        8/17/2012 11:40:46 AM - SonosController - SaveCurrentTrackInfo for zoneplayer Main saved ChannelMapSet =
        8/17/2012 11:40:46 AM - SonosController - SearchZoneUDN called with SearchZoneUDN = RINCON_000E587B2EB801400 and SourceZoneUDN = RINCON_000E587B2EB801400 and DestinationZoneString =
        8/17/2012 11:40:46 AM - SonosController - GetZoneDestination called for ZoneName Main and TargetZones =
        8/17/2012 11:40:46 AM - SonosController - SavedPlayerList.add called with PlayerIndex = 1
        8/17/2012 11:40:46 AM - SonosController - SearchZoneUDN called with SearchZoneUDN = RINCON_000E587B2EB801400 and SourceZoneUDN = RINCON_000E587B2EB801400 and DestinationZoneString =
        8/17/2012 11:40:46 AM - SonosController - GetZoneDestination called for ZoneName Main and TargetZones =
        8/17/2012 11:40:46 AM - SonosController - HandleLinkingOn: Done Saving LinkgroupZoneDestination = uuid:RINCON_000E587B2EB801400
        8/17/2012 11:40:46 AM - SonosController - SetTransportState called for zoneplayer - Main with value = Stop
        8/17/2012 11:40:46 AM - SonosController - HandleLinkingOn: Start LinkgroupZoneDestination = uuid:RINCON_000E587B2EB801400
        8/17/2012 11:40:46 AM - SonosController - HandleLinkingOn: Done LinkgroupZoneDestination = uuid:RINCON_000E587B2EB801400
        8/17/2012 11:40:46 AM - SonosController - HandleLinkingOn done with LinkgroupName = TTS
        8/17/2012 11:40:46 AM - SonosController - DoCheckAnnouncementQueue activated with HostName = windogefault Text = This is a test and LinkgroupName = TTS
        8/17/2012 11:40:46 AM - SonosController - DoCheckAnnouncementQueue adds file = C:\Program Files (x86)\HomeSeer HSPRO\html/SonosController/Announcements/Ann_TTS_0 to Queue
        8/17/2012 11:40:46 AM - SonosController - DoCheckAnnouncementQueue calling SpeakToFile with Text This is a test and File C:\Program Files (x86)\HomeSeer HSPRO\html/SonosController/Announcements/Ann_TTS_0.wav
        8/17/2012 11:40:46 AM - SonosController - DoCheckAnnouncementQueue finished SpeakToFile
        8/17/2012 11:40:46 AM - SonosController - PlayURI called for zoneplayer Main with strURI = http://10.10.10.41:8080/SonosControl.../Ann_TTS_0.wav and MetaData = <DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><res protocolInfo="http-get:*:audio/wav:*">http://10.10.10.41:8080/SonosController/Announcements/Ann_TTS_0.wav</res><r:streamContent></r:streamContent><upnp:albumArtURI>http://10.10.10.41:8080/SonosController/Announcements/Announcement.jpg</upnp:albumArtURI><dc:title>HomeSeer Announcement</dc:title><upnp:class>object.item.audioItem.musicTrack</upnp:class><dc:creator>Dirk Corsus</dc:creator><upnp:album>SonosController</upnp:album><r:albumArtist>Dirk Corsus</r:albumArtist></item></DIDL-Lite>
        8/17/2012 11:40:46 AM - SonosController - DoCheckAnnouncementQueue is calling PlayURI with http://10.10.10.41:8080/SonosControl.../Ann_TTS_0.wav
        8/17/2012 11:40:46 AM - SonosController - SetTransportState called for zoneplayer - Main with value = Play
        8/17/2012 11:40:51 AM - SonosController - Warning in DoCheckAnnouncementQueue. Resend Play command first time after 6 second still not playing
        8/17/2012 11:41:16 AM - SonosController - Warning in DoCheckAnnouncementQueue. Resend Play command second time after 31 second still not playing
        8/17/2012 11:42:20 AM - SonosController - TransportChangeCallback for ZP - Main VarName = LastChange Value = <Event xmlns="urn:schemas-upnp-org:metadata-1-0/AVT/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/"><InstanceID val="0"><TransportState val="PLAYING"/><CurrentPlayMode val="NORMAL"/><CurrentCrossfadeMode val="0"/><NumberOfTracks val="6"/><CurrentTrack val="1"/><CurrentSection val="0"/><CurrentTrackURI val="x-rincon-mp3radio://u12b.di.fm:80/di_vocaltrance"/><CurrentTrackDuration val="0:00:00"/><CurrentTrackMetaData val="<DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><res protocolInfo="sonos.com-http:*:*:*">x-sonosapi-stream:s49632?sid=254&amp;flags=32</res><r:streamContent>Immanuel D - Let Me In</r:streamContent><r:radioShowMd></r:radioShowMd><upnp:albumArtURI>/getaa?s=1&amp;u=x-sonosapi-stream%3as49632%3fsid%3d254%26flags%3d32</upnp:albumArtURI><dc:title>x-sonosapi-stream:s49632?sid=254&amp;flags=32</dc:title><upnp:class>object.item</upnp:class></item></DIDL-Lite>"/><r:NextTrackURI val="x-rincon-mp3radio://u17b.di.fm:80/di_vocaltrance"/><r:NextTrackMetaData val="<DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><res protocolInfo="x-rincon-mp3radio:*:*:*">x-rincon-mp3radio://u17b.di.fm:80/di_vocaltrance</res><upnp:albumArtURI>/getaa?u=x-rincon-mp3radio%3a%2f%2fu17b.di.fm%3a80%2fdi_vocaltrance&amp;v=2</upnp:albumArtURI><dc:title>di_vocaltrance</dc:title><upnp:class>object.item</upnp:class></item></DIDL-Lite>"/><r:EnqueuedTransportURI val="x-sonosapi-stream:s49632?sid=254&flags=32"/><r:EnqueuedTransportURIMetaData val="<DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><dc:title>[DI] Vocal Trance</dc:title><upnp:class>object.item.audioItem.audioBroadcast</upnp:class><desc id="cdudn" nameSpace="urn:schemas-rinconnetworks-com:metadata-1-0/">SA_RINCON65031_</desc></item></DIDL-Lite>"/><PlaybackStorageMedium val="NETWORK"/><AVTransportURI val="x-sonosapi-stream:s49632?sid=254&flags=32"/><AVTransportURIMetaData val="<DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><dc:title>[DI] Vocal Trance</dc:title><upnp:class>object.item.audioItem.audioBroadcast</upnp:class><desc id="cdudn" nameSpace="urn:schemas-rinconnetworks-com:metadata-1-0/">SA_RINCON65031_</desc></item></DIDL-Lite>"/><CurrentTransportActions val="Set, Play, Stop, Pause, Seek, Next, Previous"/><TransportStatus val="OK"/><r:SleepTimerGeneration val="0"/><r:AlarmRunning val="0"/><r:SnoozeRunning val="0"/><r:RestartPending val="0"/><TransportPlaySpeed val="NOT_IMPLEMENTED"/><CurrentMediaDuration val="NOT_IMPLEMENTED"/><RecordStorageMedium val="NOT_IMPLEMENTED"/><PossiblePlaybackStorageMedia val="NONE, NETWORK"/><PossibleRecordStorageMedia val="NOT_IMPLEMENTED"/><RecordMediumWriteStatus val="NOT_IMPLEMENTED"/><CurrentRecordQualityMode val="NOT_IMPLEMENTED"/><PossibleRecordQualityModes val="NOT_IMPLEMENTED"/><NextAVTransportURI val="NOT_IMPLEMENTED"/><NextAVTransportURIMetaData val="NOT_IMPLEMENTED"/></InstanceID></Event>
        8/17/2012 11:42:20 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(TransportState) = PLAYING and previous state was = playing
        8/17/2012 11:42:20 AM - SonosController - GetcurrentTrackInfo called for zoneplayer - Main
        8/17/2012 11:42:20 AM - SonosController - Main TrackInfo (CurrentURI) = http://10.10.10.41:8080/SonosControl.../Ann_TTS_0.wav and TrackInfo (CurrentURIMetaData) = <DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><res protocolInfo="http-get:*:audio/wav:*">http://10.10.10.41:8080/SonosController/Announcements/Ann_TTS_0.wav</res><r:streamContent></r:streamContent><upnp:albumArtURI>http://10.10.10.41:8080/SonosController/Announcements/Announcement.jpg</upnp:albumArtURI><dc:title>HomeSeer Announcement</dc:title><upnp:class>object.item.audioItem.musicTrack</upnp:class><dc:creator>Dirk Corsus</dc:creator><upnp:album>SonosController</upnp:album><r:albumArtist>Dirk Corsus</r:albumArtist></item></DIDL-Lite>
        8/17/2012 11:42:20 AM - SonosController - Main TrackInfo (MediaInfo/Title) = HomeSeer Announcement
        8/17/2012 11:42:20 AM - SonosController - Main TrackInfo (title) = HomeSeer Announcement
        8/17/2012 11:42:20 AM - SonosController - Main TrackInfo (creator) = Dirk Corsus
        8/17/2012 11:42:20 AM - SonosController - Main TrackInfo (album) = SonosController
        8/17/2012 11:42:20 AM - SonosController - Main TrackInfo (albumArtURI) = http://10.10.10.41:8080/SonosControl...nouncement.jpg
        8/17/2012 11:42:20 AM - SonosController - Main TrackInfo (CurrentURI) = http://10.10.10.41:8080/SonosControl.../Ann_TTS_0.wav
        8/17/2012 11:42:20 AM - SonosController - Main TrackInfo(Source) = Tracks
        8/17/2012 11:42:20 AM - SonosController - GetcurrentTrackInfo called GetPositionInfo for zoneplayer - Main and MetaData <DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><res protocolInfo="http-get:*:audio/wav:*">http://10.10.10.41:8080/SonosController/Announcements/Ann_TTS_0.wav</res><r:streamContent></r:streamContent><dc:title>Ann_TTS_0.wav</dc:title><upnp:class>object.item.audioItem.musicTrack</upnp:class></item></DIDL-Lite>
        8/17/2012 11:42:20 AM - SonosController - Main TrackInfo (title) = Ann_TTS_0.wav
        8/17/2012 11:42:20 AM - SonosController - GetAlbumArtPath called for zone Main with AlbumURI = http://10.10.10.41:8080/SonosControl...nouncement.jpg and NextTrack = False
        8/17/2012 11:42:20 AM - SonosController - GetAlbumArtPath returned for Zone - Main with AlbumURI = http://10.10.10.41:8080/SonosControl...nouncement.jpg and returned path= /SonosController/Images/Cover1.jpg
        8/17/2012 11:42:20 AM - SonosController - Main TrackInfo (Track Position) = 0:00:00
        8/17/2012 11:42:20 AM - SonosController - Main TrackInfo (Track Position Seconds) = 0
        8/17/2012 11:42:20 AM - SonosController - Main TrackInfo (Track Duration) = 0:00:00
        8/17/2012 11:42:20 AM - SonosController - Main TrackInfo (Queue Position) = 1
        8/17/2012 11:42:20 AM - SonosController - Main TrackInfo (% played) = 100
        8/17/2012 11:42:20 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentTrack) = 1
        8/17/2012 11:42:20 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentTrackDuration) = 0:00:00
        8/17/2012 11:42:20 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(NumberOfTracks) = 6
        8/17/2012 11:42:20 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentPlayMode) = NORMAL
        8/17/2012 11:42:20 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentSection) = 0
        8/17/2012 11:42:20 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(TransportStatus) = OK
        8/17/2012 11:42:20 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(Artist) = Dirk Corsus
        8/17/2012 11:42:20 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(title) = Ann_TTS_0.wav
        8/17/2012 11:42:20 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(Album) = SonosController
        8/17/2012 11:42:20 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentURI) = http://10.10.10.41:8080/SonosControl.../Ann_TTS_0.wav
        8/17/2012 11:42:20 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(AlbumArtURL) = /SonosController/Images/Cover1.jpg
        8/17/2012 11:42:20 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(Next Title) = di_vocaltrance
        8/17/2012 11:42:20 AM - SonosController - GetAlbumArtPath called for zone Main with AlbumURI = http://10.10.10.167:1400/getaa?u=x-r...ocaltrance&v=2 and NextTrack = True
        8/17/2012 11:42:20 AM - SonosController - GetPicture called for Zone - Main url= http://10.10.10.167:1400/getaa?u=x-r...ocaltrance&v=2 caused error: The remote server returned an error: (404) Not Found.
        8/17/2012 11:42:20 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(Next albumArtURI) = /SonosController/images/noart.jpeg
        8/17/2012 11:42:20 AM - SonosController - TransportStateChange processed XML succesfully for zoneplayer = Main
        8/17/2012 11:42:20 AM - SonosController - Track Set for ZoneName = Main. Track = Ann_TTS_0.wav
        8/17/2012 11:42:20 AM - SonosController - Artist Set for ZoneName = Main. Artist = Dirk Corsus
        8/17/2012 11:42:20 AM - SonosController - Album Set for ZoneName = Main. Album = SonosController
        8/17/2012 11:42:20 AM - SonosController - ArtworkURL Set for ZoneName = Main. Album = /SonosController/Images/Cover1.jpg
        8/17/2012 11:42:20 AM - SonosController - PlayChangeNotifyCallback called for Zone - Main with ChangeType = SongChanged and Changevalue = playing
        8/17/2012 11:42:20 AM - SonosController - CheckTrigger called in DeviceTrigger for Zone - Main with Trigger = SONOSCONTROLLERSonos Player TriggerTrack Change
        8/17/2012 11:42:20 AM - SonosController - Checking linked zones for SourceZone=Main. MyZoneIsSourceForLinkedZone=False and TargetZones =
        8/17/2012 11:42:20 AM - SonosController - HS updated in TransportChanged for zonePlayer Main. HS Code = ]2 and updated DeviceValue = False. Info = <table><tr><td><img src=/SonosController/Images/Cover1.jpg></td><td><p>PLAYING</p><p>Tracks</p><p>Ann_TTS_0.wav</p><p>Dirk Corsus</p><p>SonosController</p><p>/SonosController/Images/Cover1.jpg<p/></td></tr></table>
        8/17/2012 11:42:20 AM - SonosController - TransportChangeCallback for ZP - Main VarName = LastChange Value = <Event xmlns="urn:schemas-upnp-org:metadata-1-0/AVT/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/"><InstanceID val="0"><TransportState val="STOPPED"/><CurrentPlayMode val="NORMAL"/><CurrentCrossfadeMode val="0"/><NumberOfTracks val="1"/><CurrentTrack val="1"/><CurrentSection val="0"/><CurrentTrackURI val="http://10.10.10.41:8080/SonosController/Announcements/Ann_TTS_0.wav"/><CurrentTrackDuration val="0:00:00"/><CurrentTrackMetaData val="<DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><res protocolInfo="http-get:*:audio/wav:*">http://10.10.10.41:8080/SonosController/Announcements/Ann_TTS_0.wav</res><r:streamContent></r:streamContent><r:radioShowMd></r:radioShowMd><dc:title>Ann_TTS_0.wav</dc:title><upnp:class>object.item.audioItem.musicTrack</upnp:class></item></DIDL-Lite>"/><r:NextTrackURI val=""/><r:NextTrackMetaData val=""/><r:EnqueuedTransportURI val="http://10.10.10.41:8080/SonosController/Announcements/Ann_TTS_0.wav"/><r:EnqueuedTransportURIMetaData val="<DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><res protocolInfo="http-get:*:audio/wav:*">http://10.10.10.41:8080/SonosController/Announcements/Ann_TTS_0.wav</res><r:streamContent></r:streamContent><upnp:albumArtURI>http://10.10.10.41:8080/SonosController/Announcements/Announcement.jpg</upnp:albumArtURI><dc:title>HomeSeer Announcement</dc:title><upnp:class>object.item.audioItem.musicTrack</upnp:class><dc:creator>Dirk Corsus</dc:creator><upnp:album>SonosController</upnp:album><r:albumArtist>Dirk Corsus</r:albumArtist></item></DIDL-Lite>"/><PlaybackStorageMedium val="NETWORK"/><AVTransportURI val="http://10.10.10.41:8080/SonosController/Announcements/Ann_TTS_0.wav"/><AVTransportURIMetaData val="<DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><res protocolInfo="http-get:*:audio/wav:*">http://10.10.10.41:8080/SonosController/Announcements/Ann_TTS_0.wav</res><r:streamContent></r:streamContent><upnp:albumArtURI>http://10.10.10.41:8080/SonosController/Announcements/Announcement.jpg</upnp:albumArtURI><dc:title>HomeSeer Announcement</dc:title><upnp:class>object.item.audioItem.musicTrack</upnp:class><dc:creator>Dirk Corsus</dc:creator><upnp:album>SonosController</upnp:album><r:albumArtist>Dirk Corsus</r:albumArtist></item></DIDL-Lite>"/><CurrentTransportActions val="Set, Play, Stop, Pause, Seek, Next, Previous"/><TransportStatus val="OK"/><r:SleepTimerGeneration val="0"/><r:AlarmRunning val="0"/><r:SnoozeRunning val="0"/><r:RestartPending val="0"/><TransportPlaySpeed val="NOT_IMPLEMENTED"/><CurrentMediaDuration val="NOT_IMPLEMENTED"/><RecordStorageMedium val="NOT_IMPLEMENTED"/><PossiblePlaybackStorageMedia val="NONE, NETWORK"/><PossibleRecordStorageMedia val="NOT_IMPLEMENTED"/><RecordMediumWriteStatus val="NOT_IMPLEMENTED"/><CurrentRecordQualityMode val="NOT_IMPLEMENTED"/><PossibleRecordQualityModes val="NOT_IMPLEMENTED"/><NextAVTransportURI val="NOT_IMPLEMENTED"/><NextAVTransportURIMetaData val="NOT_IMPLEMENTED"/></InstanceID></Event>
        8/17/2012 11:42:20 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(TransportState) = STOPPED and previous state was = playing
        8/17/2012 11:42:20 AM - SonosController - GetcurrentTrackInfo called for zoneplayer - Main
        8/17/2012 11:42:20 AM - SonosController - Main TrackInfo (CurrentURI) = http://10.10.10.41:8080/SonosControl.../Ann_TTS_0.wav and TrackInfo (CurrentURIMetaData) = <DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><res protocolInfo="http-get:*:audio/wav:*">http://10.10.10.41:8080/SonosController/Announcements/Ann_TTS_0.wav</res><r:streamContent></r:streamContent><upnp:albumArtURI>http://10.10.10.41:8080/SonosController/Announcements/Announcement.jpg</upnp:albumArtURI><dc:title>HomeSeer Announcement</dc:title><upnp:class>object.item.audioItem.musicTrack</upnp:class><dc:creator>Dirk Corsus</dc:creator><upnp:album>SonosController</upnp:album><r:albumArtist>Dirk Corsus</r:albumArtist></item></DIDL-Lite>
        8/17/2012 11:42:20 AM - SonosController - Main TrackInfo (MediaInfo/Title) = HomeSeer Announcement
        8/17/2012 11:42:20 AM - SonosController - Main TrackInfo (title) = HomeSeer Announcement
        8/17/2012 11:42:20 AM - SonosController - Main TrackInfo (creator) = Dirk Corsus
        8/17/2012 11:42:20 AM - SonosController - Main TrackInfo (album) = SonosController
        8/17/2012 11:42:20 AM - SonosController - Main TrackInfo (albumArtURI) = http://10.10.10.41:8080/SonosControl...nouncement.jpg
        8/17/2012 11:42:20 AM - SonosController - Main TrackInfo (CurrentURI) = http://10.10.10.41:8080/SonosControl.../Ann_TTS_0.wav
        8/17/2012 11:42:20 AM - SonosController - Main TrackInfo(Source) = Tracks
        8/17/2012 11:42:20 AM - SonosController - GetcurrentTrackInfo called GetPositionInfo for zoneplayer - Main and MetaData <DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><res protocolInfo="http-get:*:audio/wav:*">http://10.10.10.41:8080/SonosController/Announcements/Ann_TTS_0.wav</res><r:streamContent></r:streamContent><dc:title>Ann_TTS_0.wav</dc:title><upnp:class>object.item.audioItem.musicTrack</upnp:class></item></DIDL-Lite>
        8/17/2012 11:42:20 AM - SonosController - Main TrackInfo (title) = Ann_TTS_0.wav
        8/17/2012 11:42:20 AM - SonosController - GetAlbumArtPath called for zone Main with AlbumURI = http://10.10.10.41:8080/SonosControl...nouncement.jpg and NextTrack = False
        8/17/2012 11:42:20 AM - SonosController - GetAlbumArtPath returned for Zone - Main with AlbumURI = http://10.10.10.41:8080/SonosControl...nouncement.jpg and cached returned path= /SonosController/Images/Cover1.jpg
        8/17/2012 11:42:20 AM - SonosController - Main TrackInfo (Track Position) = 0:00:00
        8/17/2012 11:42:20 AM - SonosController - Main TrackInfo (Track Position Seconds) = 0
        8/17/2012 11:42:20 AM - SonosController - Main TrackInfo (Track Duration) = 0:00:00
        8/17/2012 11:42:20 AM - SonosController - Main TrackInfo (Queue Position) = 1
        8/17/2012 11:42:20 AM - SonosController - Main TrackInfo (% played) = 100
        8/17/2012 11:42:20 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentTrack) = 1
        8/17/2012 11:42:20 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentTrackDuration) = 0:00:00
        8/17/2012 11:42:20 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(NumberOfTracks) = 1
        8/17/2012 11:42:20 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentPlayMode) = NORMAL
        8/17/2012 11:42:20 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentSection) = 0
        8/17/2012 11:42:20 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(TransportStatus) = OK
        8/17/2012 11:42:20 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(Artist) = Dirk Corsus
        8/17/2012 11:42:20 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(title) = Ann_TTS_0.wav
        8/17/2012 11:42:20 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(Album) = SonosController
        8/17/2012 11:42:20 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentURI) = http://10.10.10.41:8080/SonosControl.../Ann_TTS_0.wav
        8/17/2012 11:42:20 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(AlbumArtURL) = /SonosController/Images/Cover1.jpg
        8/17/2012 11:42:20 AM - SonosController - TransportStateChange processed XML succesfully for zoneplayer = Main
        8/17/2012 11:42:20 AM - SonosController - Track Set for ZoneName = Main. Track = Ann_TTS_0.wav
        8/17/2012 11:42:20 AM - SonosController - Artist Set for ZoneName = Main. Artist = Dirk Corsus
        8/17/2012 11:42:20 AM - SonosController - Album Set for ZoneName = Main. Album = SonosController
        8/17/2012 11:42:20 AM - SonosController - ArtworkURL Set for ZoneName = Main. Album = /SonosController/Images/Cover1.jpg
        8/17/2012 11:42:20 AM - SonosController - CurrentPlayerState Set for ZoneName = Main. Value = stopped
        8/17/2012 11:42:20 AM - SonosController - Checking linked zones for SourceZone=Main. MyZoneIsSourceForLinkedZone=False and TargetZones =
        8/17/2012 11:42:20 AM - SonosController - HS DeviceValue updated in TransportChanged for zonePlayer Main. HS Code = ]2 and value = stopped
        8/17/2012 11:42:20 AM - SonosController - HS updated in TransportChanged for zonePlayer Main. HS Code = ]2 and updated DeviceValue = True. Info = <table><tr><td><img src=/SonosController/Images/Cover1.jpg></td><td><p>STOPPED</p><p>Tracks</p><p>Ann_TTS_0.wav</p><p>Dirk Corsus</p><p>SonosController</p><p>/SonosController/Images/Cover1.jpg<p/></td></tr></table>
        8/17/2012 11:42:21 AM - SonosController - DoCheckAnnouncementQueue called for linkgroup TTS and State = asFilePlayed and isFile = True
        8/17/2012 11:42:21 AM - SonosController - HandleLinkingOff called with LinkgroupName = TTS
        8/17/2012 11:42:21 AM - SonosController - GetLinkGroupInfo called with LinkgroupName = TTS
        8/17/2012 11:42:21 AM - SonosController - SavedPlayerList.GetLastIndex called
        8/17/2012 11:42:21 AM - SonosController - SavedPlayerList.GetLastIndex called and returned Index = 1
        8/17/2012 11:42:21 AM - SonosController - RestoreCurrentTrackInfo for Zonename = Main and MySavedTrackinfo = x-sonosapi-stream:s49632?sid=254&flags=32
        8/17/2012 11:42:21 AM - SonosController - PlayURI called for zoneplayer Main with strURI = x-sonosapi-stream:s49632?sid=254&flags=32 and MetaData = <DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><dc:title>[DI] Vocal Trance</dc:title><upnp:class>object.item.audioItem.audioBroadcast</upnp:class><desc id="cdudn" nameSpace="urn:schemas-rinconnetworks-com:metadata-1-0/">SA_RINCON65031_</desc></item></DIDL-Lite>
        8/17/2012 11:42:26 AM - SonosController - TransportChangeCallback for ZP - Main VarName = LastChange Value = <Event xmlns="urn:schemas-upnp-org:metadata-1-0/AVT/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/"><InstanceID val="0"><TransportState val="STOPPED"/><CurrentPlayMode val="NORMAL"/><CurrentCrossfadeMode val="0"/><NumberOfTracks val="6"/><CurrentTrack val="1"/><CurrentSection val="0"/><CurrentTrackURI val="x-rincon-mp3radio://u12b.di.fm:80/di_vocaltrance"/><CurrentTrackDuration val="0:00:00"/><CurrentTrackMetaData val="<DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><res protocolInfo="sonos.com-http:*:*:*">x-sonosapi-stream:s49632?sid=254&amp;flags=32</res><r:streamContent></r:streamContent><r:radioShowMd></r:radioShowMd><upnp:albumArtURI>/getaa?s=1&amp;u=x-sonosapi-stream%3as49632%3fsid%3d254%26flags%3d32</upnp:albumArtURI><dc:title>x-sonosapi-stream:s49632?sid=254&amp;flags=32</dc:title><upnp:class>object.item</upnp:class></item></DIDL-Lite>"/><r:NextTrackURI val="x-rincon-mp3radio://u17b.di.fm:80/di_vocaltrance"/><r:NextTrackMetaData val="<DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><res protocolInfo="x-rincon-mp3radio:*:*:*">x-rincon-mp3radio://u17b.di.fm:80/di_vocaltrance</res><upnp:albumArtURI>/getaa?u=x-rincon-mp3radio%3a%2f%2fu17b.di.fm%3a80%2fdi_vocaltrance&amp;v=2</upnp:albumArtURI><dc:title>di_vocaltrance</dc:title><upnp:class>object.item</upnp:class></item></DIDL-Lite>"/><r:EnqueuedTransportURI val="x-sonosapi-stream:s49632?sid=254&flags=32"/><r:EnqueuedTransportURIMetaData val="<DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><dc:title>[DI] Vocal Trance</dc:title><upnp:class>object.item.audioItem.audioBroadcast</upnp:class><desc id="cdudn" nameSpace="urn:schemas-rinconnetworks-com:metadata-1-0/">SA_RINCON65031_</desc></item></DIDL-Lite>"/><PlaybackStorageMedium val="NETWORK"/><AVTransportURI val="x-sonosapi-stream:s49632?sid=254&flags=32"/><AVTransportURIMetaData val="<DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><dc:title>[DI] Vocal Trance</dc:title><upnp:class>object.item.audioItem.audioBroadcast</upnp:class><desc id="cdudn" nameSpace="urn:schemas-rinconnetworks-com:metadata-1-0/">SA_RINCON65031_</desc></item></DIDL-Lite>"/><CurrentTransportActions val="Set, Play, Stop, Pause, Seek, Next, Previous"/></InstanceID></Event>
        8/17/2012 11:42:26 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(TransportState) = STOPPED and previous state was = stopped
        8/17/2012 11:42:26 AM - SonosController - GetcurrentTrackInfo called for zoneplayer - Main
        8/17/2012 11:42:26 AM - SonosController - Main TrackInfo (CurrentURI) = x-sonosapi-stream:s49632?sid=254&flags=32 and TrackInfo (CurrentURIMetaData) = <DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><dc:title>[DI] Vocal Trance</dc:title><upnp:class>object.item.audioItem.audioBroadcast</upnp:class><desc id="cdudn" nameSpace="urn:schemas-rinconnetworks-com:metadata-1-0/">SA_RINCON65031_</desc></item></DIDL-Lite>
        8/17/2012 11:42:26 AM - SonosController - Main TrackInfo (MediaInfo/Title) = [DI] Vocal Trance
        8/17/2012 11:42:26 AM - SonosController - Main TrackInfo (title) = [DI] Vocal Trance
        8/17/2012 11:42:26 AM - SonosController - Main TrackInfo (Internet Radio) = [DI] Vocal Trance
        8/17/2012 11:42:26 AM - SonosController - Main TrackInfo (Internet Radio Art) = x-sonosapi-stream:s49632?sid=254&flags=32
        8/17/2012 11:42:26 AM - SonosController - Main TrackInfo(Source) = Radio
        8/17/2012 11:42:26 AM - SonosController - GetcurrentTrackInfo called GetPositionInfo for zoneplayer - Main and MetaData <DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><res protocolInfo="x-rincon-mp3radio:*:*:*">x-rincon-mp3radio://u12b.di.fm:80/di_vocaltrance</res><r:streamContent></r:streamContent><dc:title>di_vocaltrance</dc:title><upnp:class>object.item</upnp:class></item></DIDL-Lite>
        8/17/2012 11:42:26 AM - SonosController - Main TrackInfo (Streamcontent/Title) =
        8/17/2012 11:42:26 AM - SonosController - Main TrackInfo (title) = di_vocaltrance
        8/17/2012 11:42:26 AM - SonosController - GetAlbumArtPath called for zone Main with AlbumURI = http://10.10.10.167:1400/getaa?s=1&u...d=254&flags=32 and NextTrack = False
        8/17/2012 11:42:26 AM - SonosController - SetVolumeLevel called for ZonePlayer = Main with values Channel=Master Value=47
        8/17/2012 11:42:26 AM - SonosController - SetPlayMode called for ZoneName Main with value = NORMAL
        8/17/2012 11:42:26 AM - SonosController - SetTransportState called for zoneplayer - Main with value = PLAYING
        8/17/2012 11:42:26 AM - SonosController - RestoreCurrentTrackInfo is done for zonePlayer Main
        8/17/2012 11:42:26 AM - SonosController - SavedPlayerList.GetLastIndex called
        8/17/2012 11:42:26 AM - SonosController - HandleLinkingOff done with LinkgroupName = TTS
        8/17/2012 11:42:26 AM - SonosController - DeleteHeadOfAnnouncementQueue called
        8/17/2012 11:42:26 AM - SonosController - DoCheckAnnouncementQueue called and all announcements were processed
        8/17/2012 11:42:27 AM - SonosController - GetAlbumArtPath returned for Zone - Main with AlbumURI = http://10.10.10.167:1400/getaa?s=1&u...d=254&flags=32 and returned path= /SonosController/Images/Cover1.jpg
        8/17/2012 11:42:27 AM - SonosController - Main TrackInfo (Track Position) = 0:00:00
        8/17/2012 11:42:27 AM - SonosController - Main TrackInfo (Track Position Seconds) = 0
        8/17/2012 11:42:27 AM - SonosController - Main TrackInfo (Track Duration) = 0:00:00
        8/17/2012 11:42:27 AM - SonosController - Main TrackInfo (Queue Position) = 1
        8/17/2012 11:42:27 AM - SonosController - Main TrackInfo (% played) = 100
        8/17/2012 11:42:27 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentTrack) = 1
        8/17/2012 11:42:27 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentTrackDuration) = 0:00:00
        8/17/2012 11:42:27 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(NumberOfTracks) = 6
        8/17/2012 11:42:27 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentPlayMode) = NORMAL
        8/17/2012 11:42:27 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentSection) = 0
        8/17/2012 11:42:27 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(Artist) = [DI] Vocal Trance
        8/17/2012 11:42:27 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(Radio) = Radio - Title info = di_vocaltrance
        8/17/2012 11:42:27 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(Album) =
        8/17/2012 11:42:27 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentURI) = x-sonosapi-stream:s49632?sid=254&flags=32
        8/17/2012 11:42:27 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(AlbumArtURL) = /SonosController/Images/Cover1.jpg
        8/17/2012 11:42:27 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(Next Title) = di_vocaltrance
        8/17/2012 11:42:27 AM - SonosController - GetAlbumArtPath called for zone Main with AlbumURI = http://10.10.10.167:1400/getaa?u=x-r...ocaltrance&v=2 and NextTrack = True
        8/17/2012 11:42:27 AM - SonosController - GetPicture called for Zone - Main url= http://10.10.10.167:1400/getaa?u=x-r...ocaltrance&v=2 caused error: The remote server returned an error: (404) Not Found.
        8/17/2012 11:42:27 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(Next albumArtURI) = /SonosController/images/noart.jpeg
        8/17/2012 11:42:27 AM - SonosController - TransportStateChange processed XML succesfully for zoneplayer = Main
        8/17/2012 11:42:27 AM - SonosController - TransportChangeCallback for ZP - Main is not tracks and radio Radio
        8/17/2012 11:42:27 AM - SonosController - Track Set for ZoneName = Main. Track = [DI] Vocal Trance
        8/17/2012 11:42:27 AM - SonosController - Artist Set for ZoneName = Main. Artist = di_vocaltrance
        8/17/2012 11:42:27 AM - SonosController - Album Set for ZoneName = Main. Album =
        8/17/2012 11:42:27 AM - SonosController - ArtworkURL Set for ZoneName = Main. Album = /SonosController/Images/Cover1.jpg
        8/17/2012 11:42:27 AM - SonosController - PlayChangeNotifyCallback called for Zone - Main with ChangeType = SongChanged and Changevalue = stopped
        8/17/2012 11:42:27 AM - SonosController - CheckTrigger called in DeviceTrigger for Zone - Main with Trigger = SONOSCONTROLLERSonos Player TriggerTrack Change
        8/17/2012 11:42:27 AM - SonosController - Checking linked zones for SourceZone=Main. MyZoneIsSourceForLinkedZone=False and TargetZones =
        8/17/2012 11:42:27 AM - SonosController - HS updated in TransportChanged for zonePlayer Main. HS Code = ]2 and updated DeviceValue = False. Info = <table><tr><td><img src=/SonosController/Images/Cover1.jpg></td><td><p>STOPPED</p><p>Radio - [DI] Vocal Trance</p><p>[DI] Vocal Trance</p><p>di_vocaltrance</p><p></p><p>/SonosController/Images/Cover1.jpg<p/></td></tr></table>
        8/17/2012 11:42:27 AM - SonosController - TransportChangeCallback for ZP - Main VarName = LastChange Value = <Event xmlns="urn:schemas-upnp-org:metadata-1-0/AVT/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/"><InstanceID val="0"><TransportState val="STOPPED"/><CurrentPlayMode val="NORMAL"/><CurrentCrossfadeMode val="0"/><NumberOfTracks val="6"/><CurrentTrack val="1"/><CurrentSection val="0"/><CurrentTrackURI val="x-rincon-mp3radio://u12b.di.fm:80/di_vocaltrance"/><CurrentTrackDuration val="0:00:00"/><CurrentTrackMetaData val="<DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><res protocolInfo="sonos.com-http:*:*:*">x-sonosapi-stream:s49632?sid=254&amp;flags=32</res><r:streamContent></r:streamContent><r:radioShowMd></r:radioShowMd><upnp:albumArtURI>/getaa?s=1&amp;u=x-sonosapi-stream%3as49632%3fsid%3d254%26flags%3d32</upnp:albumArtURI><dc:title>x-sonosapi-stream:s49632?sid=254&amp;flags=32</dc:title><upnp:class>object.item</upnp:class></item></DIDL-Lite>"/><r:NextTrackURI val="x-rincon-mp3radio://u17b.di.fm:80/di_vocaltrance"/><r:NextTrackMetaData val="<DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><res protocolInfo="x-rincon-mp3radio:*:*:*">x-rincon-mp3radio://u17b.di.fm:80/di_vocaltrance</res><upnp:albumArtURI>/getaa?u=x-rincon-mp3radio%3a%2f%2fu17b.di.fm%3a80%2fdi_vocaltrance&amp;v=2</upnp:albumArtURI><dc:title>di_vocaltrance</dc:title><upnp:class>object.item</upnp:class></item></DIDL-Lite>"/><r:EnqueuedTransportURI val="x-sonosapi-stream:s49632?sid=254&flags=32"/><r:EnqueuedTransportURIMetaData val="<DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><dc:title>[DI] Vocal Trance</dc:title><upnp:class>object.item.audioItem.audioBroadcast</upnp:class><desc id="cdudn" nameSpace="urn:schemas-rinconnetworks-com:metadata-1-0/">SA_RINCON65031_</desc></item></DIDL-Lite>"/></InstanceID></Event>
        8/17/2012 11:42:27 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(TransportState) = STOPPED and previous state was = stopped
        8/17/2012 11:42:27 AM - SonosController - GetcurrentTrackInfo called for zoneplayer - Main
        8/17/2012 11:42:27 AM - SonosController - Main TrackInfo (CurrentURI) = x-sonosapi-stream:s49632?sid=254&flags=32 and TrackInfo (CurrentURIMetaData) = <DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><dc:title>[DI] Vocal Trance</dc:title><upnp:class>object.item.audioItem.audioBroadcast</upnp:class><desc id="cdudn" nameSpace="urn:schemas-rinconnetworks-com:metadata-1-0/">SA_RINCON65031_</desc></item></DIDL-Lite>
        8/17/2012 11:42:27 AM - SonosController - Main TrackInfo (MediaInfo/Title) = [DI] Vocal Trance
        8/17/2012 11:42:27 AM - SonosController - Main TrackInfo (title) = [DI] Vocal Trance
        8/17/2012 11:42:27 AM - SonosController - Main TrackInfo (Internet Radio) = [DI] Vocal Trance
        8/17/2012 11:42:27 AM - SonosController - Main TrackInfo (Internet Radio Art) = x-sonosapi-stream:s49632?sid=254&flags=32
        8/17/2012 11:42:27 AM - SonosController - Main TrackInfo(Source) = Radio
        8/17/2012 11:42:27 AM - SonosController - GetcurrentTrackInfo called GetPositionInfo for zoneplayer - Main and MetaData <DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><res protocolInfo="x-rincon-mp3radio:*:*:*">x-rincon-mp3radio://u12b.di.fm:80/di_vocaltrance</res><r:streamContent>ZPSTR_CONNECTING</r:streamContent><dc:title>di_vocaltrance</dc:title><upnp:class>object.item</upnp:class></item></DIDL-Lite>
        8/17/2012 11:42:27 AM - SonosController - Main TrackInfo (Streamcontent/Title) = ZPSTR_CONNECTING
        8/17/2012 11:42:27 AM - SonosController - GetAlbumArtPath called for zone Main with AlbumURI = http://10.10.10.167:1400/getaa?s=1&u...d=254&flags=32 and NextTrack = False
        8/17/2012 11:42:27 AM - SonosController - GetAlbumArtPath returned for Zone - Main with AlbumURI = http://10.10.10.167:1400/getaa?s=1&u...d=254&flags=32 and cached returned path= /SonosController/Images/Cover1.jpg
        8/17/2012 11:42:27 AM - SonosController - Main TrackInfo (Track Position) = 0:00:00
        8/17/2012 11:42:27 AM - SonosController - Main TrackInfo (Track Position Seconds) = 0
        8/17/2012 11:42:27 AM - SonosController - Main TrackInfo (Track Duration) = 0:00:00
        8/17/2012 11:42:27 AM - SonosController - Main TrackInfo (Queue Position) = 1
        8/17/2012 11:42:27 AM - SonosController - Main TrackInfo (% played) = 100
        8/17/2012 11:42:27 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentTrack) = 1
        8/17/2012 11:42:27 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentTrackDuration) = 0:00:00
        8/17/2012 11:42:27 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(NumberOfTracks) = 6
        8/17/2012 11:42:27 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentPlayMode) = NORMAL
        8/17/2012 11:42:27 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentSection) = 0
        8/17/2012 11:42:27 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(Artist) = [DI] Vocal Trance
        8/17/2012 11:42:27 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(Radio) = Radio - Title info = ZPSTR_CONNECTING
        8/17/2012 11:42:27 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(Album) =
        8/17/2012 11:42:27 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentURI) = x-sonosapi-stream:s49632?sid=254&flags=32
        8/17/2012 11:42:27 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(AlbumArtURL) = /SonosController/Images/Cover1.jpg
        8/17/2012 11:42:27 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(Next Title) = di_vocaltrance
        8/17/2012 11:42:27 AM - SonosController - GetAlbumArtPath called for zone Main with AlbumURI = http://10.10.10.167:1400/getaa?u=x-r...ocaltrance&v=2 and NextTrack = True
        8/17/2012 11:42:27 AM - SonosController - GetPicture called for Zone - Main url= http://10.10.10.167:1400/getaa?u=x-r...ocaltrance&v=2 caused error: The remote server returned an error: (404) Not Found.
        8/17/2012 11:42:27 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(Next albumArtURI) = /SonosController/images/noart.jpeg
        8/17/2012 11:42:27 AM - SonosController - TransportStateChange processed XML succesfully for zoneplayer = Main
        8/17/2012 11:42:27 AM - SonosController - TransportChangeCallback for ZP - Main is filtered out because player is connecting to Radiostation
        8/17/2012 11:42:27 AM - SonosController - TransportChangeCallback for ZP - Main VarName = LastChange Value = <Event xmlns="urn:schemas-upnp-org:metadata-1-0/AVT/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/"><InstanceID val="0"><TransportState val="PLAYING"/><CurrentPlayMode val="NORMAL"/><CurrentCrossfadeMode val="0"/><NumberOfTracks val="6"/><CurrentTrack val="1"/><CurrentSection val="0"/><CurrentTrackURI val="x-rincon-mp3radio://u12b.di.fm:80/di_vocaltrance"/><CurrentTrackDuration val="0:00:00"/><CurrentTrackMetaData val="<DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><res protocolInfo="sonos.com-http:*:*:*">x-sonosapi-stream:s49632?sid=254&amp;flags=32</res><r:streamContent>ZPSTR_CONNECTING</r:streamContent><r:radioShowMd></r:radioShowMd><upnp:albumArtURI>/getaa?s=1&amp;u=x-sonosapi-stream%3as49632%3fsid%3d254%26flags%3d32</upnp:albumArtURI><dc:title>x-sonosapi-stream:s49632?sid=254&amp;flags=32</dc:title><upnp:class>object.item</upnp:class></item></DIDL-Lite>"/><r:NextTrackURI val="x-rincon-mp3radio://u17b.di.fm:80/di_vocaltrance"/><r:NextTrackMetaData val="<DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><res protocolInfo="x-rincon-mp3radio:*:*:*">x-rincon-mp3radio://u17b.di.fm:80/di_vocaltrance</res><upnp:albumArtURI>/getaa?u=x-rincon-mp3radio%3a%2f%2fu17b.di.fm%3a80%2fdi_vocaltrance&amp;v=2</upnp:albumArtURI><dc:title>di_vocaltrance</dc:title><upnp:class>object.item</upnp:class></item></DIDL-Lite>"/><r:EnqueuedTransportURI val="x-sonosapi-stream:s49632?sid=254&flags=32"/><r:EnqueuedTransportURIMetaData val="<DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><dc:title>[DI] Vocal Trance</dc:title><upnp:class>object.item.audioItem.audioBroadcast</upnp:class><desc id="cdudn" nameSpace="urn:schemas-rinconnetworks-com:metadata-1-0/">SA_RINCON65031_</desc></item></DIDL-Lite>"/></InstanceID></Event>
        8/17/2012 11:42:27 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(TransportState) = PLAYING and previous state was = stopped
        8/17/2012 11:42:27 AM - SonosController - GetcurrentTrackInfo called for zoneplayer - Main
        8/17/2012 11:42:27 AM - SonosController - Main TrackInfo (CurrentURI) = x-sonosapi-stream:s49632?sid=254&flags=32 and TrackInfo (CurrentURIMetaData) = <DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><dc:title>[DI] Vocal Trance</dc:title><upnp:class>object.item.audioItem.audioBroadcast</upnp:class><desc id="cdudn" nameSpace="urn:schemas-rinconnetworks-com:metadata-1-0/">SA_RINCON65031_</desc></item></DIDL-Lite>
        8/17/2012 11:42:27 AM - SonosController - Main TrackInfo (MediaInfo/Title) = [DI] Vocal Trance
        8/17/2012 11:42:27 AM - SonosController - Main TrackInfo (title) = [DI] Vocal Trance
        8/17/2012 11:42:27 AM - SonosController - Main TrackInfo (Internet Radio) = [DI] Vocal Trance
        8/17/2012 11:42:27 AM - SonosController - Main TrackInfo (Internet Radio Art) = x-sonosapi-stream:s49632?sid=254&flags=32
        8/17/2012 11:42:27 AM - SonosController - Main TrackInfo(Source) = Radio
        8/17/2012 11:42:27 AM - SonosController - GetcurrentTrackInfo called GetPositionInfo for zoneplayer - Main and MetaData <DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><res protocolInfo="x-rincon-mp3radio:*:*:*">x-rincon-mp3radio://u12b.di.fm:80/di_vocaltrance</res><r:streamContent>ZPSTR_CONNECTING</r:streamContent><dc:title>di_vocaltrance</dc:title><upnp:class>object.item</upnp:class></item></DIDL-Lite>
        8/17/2012 11:42:27 AM - SonosController - Main TrackInfo (Streamcontent/Title) = ZPSTR_CONNECTING
        8/17/2012 11:42:27 AM - SonosController - GetAlbumArtPath called for zone Main with AlbumURI = http://10.10.10.167:1400/getaa?s=1&u...d=254&flags=32 and NextTrack = False
        8/17/2012 11:42:27 AM - SonosController - GetAlbumArtPath returned for Zone - Main with AlbumURI = http://10.10.10.167:1400/getaa?s=1&u...d=254&flags=32 and cached returned path= /SonosController/Images/Cover1.jpg
        8/17/2012 11:42:27 AM - SonosController - Main TrackInfo (Track Position) = 0:00:00
        8/17/2012 11:42:27 AM - SonosController - Main TrackInfo (Track Position Seconds) = 0
        8/17/2012 11:42:27 AM - SonosController - Main TrackInfo (Track Duration) = 0:00:00
        8/17/2012 11:42:27 AM - SonosController - Main TrackInfo (Queue Position) = 1
        8/17/2012 11:42:27 AM - SonosController - Main TrackInfo (% played) = 100
        8/17/2012 11:42:27 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentTrack) = 1
        8/17/2012 11:42:27 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentTrackDuration) = 0:00:00
        8/17/2012 11:42:27 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(NumberOfTracks) = 6
        8/17/2012 11:42:27 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentPlayMode) = NORMAL
        8/17/2012 11:42:27 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentSection) = 0
        8/17/2012 11:42:27 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(Artist) = [DI] Vocal Trance
        8/17/2012 11:42:27 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(Radio) = Radio - Title info = ZPSTR_CONNECTING
        8/17/2012 11:42:27 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(Album) =
        8/17/2012 11:42:27 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentURI) = x-sonosapi-stream:s49632?sid=254&flags=32
        8/17/2012 11:42:27 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(AlbumArtURL) = /SonosController/Images/Cover1.jpg
        8/17/2012 11:42:27 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(Next Title) = di_vocaltrance
        8/17/2012 11:42:27 AM - SonosController - GetAlbumArtPath called for zone Main with AlbumURI = http://10.10.10.167:1400/getaa?u=x-r...ocaltrance&v=2 and NextTrack = True
        8/17/2012 11:42:27 AM - SonosController - GetPicture called for Zone - Main url= http://10.10.10.167:1400/getaa?u=x-r...ocaltrance&v=2 caused error: The remote server returned an error: (404) Not Found.
        8/17/2012 11:42:27 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(Next albumArtURI) = /SonosController/images/noart.jpeg
        8/17/2012 11:42:27 AM - SonosController - TransportStateChange processed XML succesfully for zoneplayer = Main
        8/17/2012 11:42:27 AM - SonosController - TransportChangeCallback for ZP - Main is filtered out because player is connecting to Radiostation
        8/17/2012 11:42:27 AM - SonosController - Rendering Change callback - ZonePlayer Main: Var Name = LastChange Value = <Event xmlns="urn:schemas-upnp-org:metadata-1-0/RCS/"><InstanceID val="0"><Volume channel="Master" val="47"/><Volume channel="LF" val="100"/><Volume channel="RF" val="100"/></InstanceID></Event>
        8/17/2012 11:42:27 AM - SonosController - Main : Rendering (Master Volume) = 47
        8/17/2012 11:42:27 AM - SonosController - Main : Rendering (LF Volume) = 100
        8/17/2012 11:42:27 AM - SonosController - Main : Rendering (RF Volume) = 100
        8/17/2012 11:42:27 AM - SonosController - PlayChangeNotifyCallback called for Zone - Main with ChangeType = SongChanged and Changevalue = UpdateHSServerOnly
        8/17/2012 11:42:27 AM - SonosController - Rendering Change callback - ZonePlayer Main: Var Name = LastChange Value = <Event xmlns="urn:schemas-upnp-org:metadata-1-0/RCS/"><InstanceID val="0"><Mute channel="Master" val="0"/><Mute channel="LF" val="0"/><Mute channel="RF" val="0"/></InstanceID></Event>
        8/17/2012 11:42:27 AM - SonosController - Main : Rendering (Mute Master Volume) = 0
        8/17/2012 11:42:27 AM - SonosController - Main : Rendering (Mute LF Volume) = 0
        8/17/2012 11:42:27 AM - SonosController - Main : Rendering (Mute RF Volume) = 0
        8/17/2012 11:42:27 AM - SonosController - CurrentPlayerState Set for ZoneName = Main. Value = playing
        8/17/2012 11:42:27 AM - SonosController - PlayChangeNotifyCallback called for Zone - Main with ChangeType = PlayStatusChanged and Changevalue = playing
        8/17/2012 11:42:27 AM - SonosController - CheckTrigger called in DeviceTrigger for Zone - Main with Trigger = SONOSCONTROLLERSonos Player TriggerPlayer Start Playing
        8/17/2012 11:42:27 AM - SonosController - PlayChangeNotifyCallback called for Zone - Main with ChangeType = SongChanged and Changevalue = playing
        8/17/2012 11:42:27 AM - SonosController - CheckTrigger called in DeviceTrigger for Zone - Main with Trigger = SONOSCONTROLLERSonos Player TriggerTrack Change
        8/17/2012 11:42:27 AM - SonosController - Checking linked zones for SourceZone=Main. MyZoneIsSourceForLinkedZone=False and TargetZones =
        8/17/2012 11:42:27 AM - SonosController - HS DeviceValue updated in TransportChanged for zonePlayer Main. HS Code = ]2 and value = playing
        8/17/2012 11:42:27 AM - SonosController - HS updated in TransportChanged for zonePlayer Main. HS Code = ]2 and updated DeviceValue = True. Info = <table><tr><td><img src=/SonosController/Images/Cover1.jpg></td><td><p>PLAYING</p><p>Radio - [DI] Vocal Trance</p><p>[DI] Vocal Trance</p><p>di_vocaltrance</p><p></p><p>/SonosController/Images/Cover1.jpg<p/></td></tr></table>
        8/17/2012 11:42:28 AM - SonosController - TransportChangeCallback for ZP - Main VarName = LastChange Value = <Event xmlns="urn:schemas-upnp-org:metadata-1-0/AVT/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/"><InstanceID val="0"><TransportState val="PLAYING"/><CurrentPlayMode val="NORMAL"/><CurrentCrossfadeMode val="0"/><NumberOfTracks val="6"/><CurrentTrack val="1"/><CurrentSection val="0"/><CurrentTrackURI val="x-rincon-mp3radio://u12b.di.fm:80/di_vocaltrance"/><CurrentTrackDuration val="0:00:00"/><CurrentTrackMetaData val="<DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><res protocolInfo="sonos.com-http:*:*:*">x-sonosapi-stream:s49632?sid=254&amp;flags=32</res><r:streamContent>Immanuel D - Let Me In</r:streamContent><r:radioShowMd></r:radioShowMd><upnp:albumArtURI>/getaa?s=1&amp;u=x-sonosapi-stream%3as49632%3fsid%3d254%26flags%3d32</upnp:albumArtURI><dc:title>x-sonosapi-stream:s49632?sid=254&amp;flags=32</dc:title><upnp:class>object.item</upnp:class></item></DIDL-Lite>"/><r:NextTrackURI val="x-rincon-mp3radio://u17b.di.fm:80/di_vocaltrance"/><r:NextTrackMetaData val="<DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><res protocolInfo="x-rincon-mp3radio:*:*:*">x-rincon-mp3radio://u17b.di.fm:80/di_vocaltrance</res><upnp:albumArtURI>/getaa?u=x-rincon-mp3radio%3a%2f%2fu17b.di.fm%3a80%2fdi_vocaltrance&amp;v=2</upnp:albumArtURI><dc:title>di_vocaltrance</dc:title><upnp:class>object.item</upnp:class></item></DIDL-Lite>"/><r:EnqueuedTransportURI val="x-sonosapi-stream:s49632?sid=254&flags=32"/><r:EnqueuedTransportURIMetaData val="<DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><dc:title>[DI] Vocal Trance</dc:title><upnp:class>object.item.audioItem.audioBroadcast</upnp:class><desc id="cdudn" nameSpace="urn:schemas-rinconnetworks-com:metadata-1-0/">SA_RINCON65031_</desc></item></DIDL-Lite>"/></InstanceID></Event>
        8/17/2012 11:42:28 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(TransportState) = PLAYING and previous state was = playing
        8/17/2012 11:42:28 AM - SonosController - GetcurrentTrackInfo called for zoneplayer - Main
        8/17/2012 11:42:28 AM - SonosController - Main TrackInfo (CurrentURI) = x-sonosapi-stream:s49632?sid=254&flags=32 and TrackInfo (CurrentURIMetaData) = <DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><dc:title>[DI] Vocal Trance</dc:title><upnp:class>object.item.audioItem.audioBroadcast</upnp:class><desc id="cdudn" nameSpace="urn:schemas-rinconnetworks-com:metadata-1-0/">SA_RINCON65031_</desc></item></DIDL-Lite>
        8/17/2012 11:42:28 AM - SonosController - Main TrackInfo (MediaInfo/Title) = [DI] Vocal Trance
        8/17/2012 11:42:28 AM - SonosController - Main TrackInfo (title) = [DI] Vocal Trance
        8/17/2012 11:42:28 AM - SonosController - Main TrackInfo (Internet Radio) = [DI] Vocal Trance
        8/17/2012 11:42:28 AM - SonosController - Main TrackInfo (Internet Radio Art) = x-sonosapi-stream:s49632?sid=254&flags=32
        8/17/2012 11:42:28 AM - SonosController - Main TrackInfo(Source) = Radio
        8/17/2012 11:42:28 AM - SonosController - GetcurrentTrackInfo called GetPositionInfo for zoneplayer - Main and MetaData <DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:r="urn:schemas-rinconnetworks-com:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="-1" parentID="-1" restricted="true"><res protocolInfo="x-rincon-mp3radio:*:*:*">x-rincon-mp3radio://u12b.di.fm:80/di_vocaltrance</res><r:streamContent>Immanuel D - Let Me In</r:streamContent><dc:title>di_vocaltrance</dc:title><upnp:class>object.item</upnp:class></item></DIDL-Lite>
        8/17/2012 11:42:28 AM - SonosController - Main TrackInfo (Streamcontent/Title) = Immanuel D - Let Me In
        8/17/2012 11:42:28 AM - SonosController - GetAlbumArtPath called for zone Main with AlbumURI = http://10.10.10.167:1400/getaa?s=1&u...d=254&flags=32 and NextTrack = False
        8/17/2012 11:42:28 AM - SonosController - GetAlbumArtPath returned for Zone - Main with AlbumURI = http://10.10.10.167:1400/getaa?s=1&u...d=254&flags=32 and cached returned path= /SonosController/Images/Cover1.jpg
        8/17/2012 11:42:28 AM - SonosController - Main TrackInfo (Track Position) = 0:00:00
        8/17/2012 11:42:28 AM - SonosController - Main TrackInfo (Track Position Seconds) = 0
        8/17/2012 11:42:28 AM - SonosController - Main TrackInfo (Track Duration) = 0:00:00
        8/17/2012 11:42:28 AM - SonosController - Main TrackInfo (Queue Position) = 1
        8/17/2012 11:42:28 AM - SonosController - Main TrackInfo (% played) = 100
        8/17/2012 11:42:28 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentTrack) = 1
        8/17/2012 11:42:28 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentTrackDuration) = 0:00:00
        8/17/2012 11:42:28 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(NumberOfTracks) = 6
        8/17/2012 11:42:28 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentPlayMode) = NORMAL
        8/17/2012 11:42:28 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentSection) = 0
        8/17/2012 11:42:28 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(Artist) = [DI] Vocal Trance
        8/17/2012 11:42:28 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(Radio) = Radio - Title info = Immanuel D - Let Me In
        8/17/2012 11:42:28 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(Album) =
        8/17/2012 11:42:28 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(CurrentURI) = x-sonosapi-stream:s49632?sid=254&flags=32
        8/17/2012 11:42:28 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(AlbumArtURL) = /SonosController/Images/Cover1.jpg
        8/17/2012 11:42:28 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(Next Title) = di_vocaltrance
        8/17/2012 11:42:28 AM - SonosController - GetAlbumArtPath called for zone Main with AlbumURI = http://10.10.10.167:1400/getaa?u=x-r...ocaltrance&v=2 and NextTrack = True
        8/17/2012 11:42:28 AM - SonosController - GetPicture called for Zone - Main url= http://10.10.10.167:1400/getaa?u=x-r...ocaltrance&v=2 caused error: The remote server returned an error: (404) Not Found.
        8/17/2012 11:42:28 AM - SonosController - TransportChangeCallback for ZP - Main TransportState(Next albumArtURI) = /SonosController/images/noart.jpeg
        8/17/2012 11:42:28 AM - SonosController - TransportStateChange processed XML succesfully for zoneplayer = Main
        8/17/2012 11:42:28 AM - SonosController - TransportChangeCallback for ZP - Main is not tracks and radio Radio
        8/17/2012 11:42:28 AM - SonosController - Track Set for ZoneName = Main. Track = Let Me In
        8/17/2012 11:42:28 AM - SonosController - Artist Set for ZoneName = Main. Artist = Immanuel D
        8/17/2012 11:42:28 AM - SonosController - Album Set for ZoneName = Main. Album =
        8/17/2012 11:42:28 AM - SonosController - ArtworkURL Set for ZoneName = Main. Album = /SonosController/Images/Cover1.jpg
        8/17/2012 11:42:28 AM - SonosController - PlayChangeNotifyCallback called for Zone - Main with ChangeType = SongChanged and Changevalue = playing
        8/17/2012 11:42:28 AM - SonosController - CheckTrigger called in DeviceTrigger for Zone - Main with Trigger = SONOSCONTROLLERSonos Player TriggerTrack Change
        8/17/2012 11:42:28 AM - SonosController - Checking linked zones for SourceZone=Main. MyZoneIsSourceForLinkedZone=False and TargetZones =
        8/17/2012 11:42:28 AM - SonosController - HS updated in TransportChanged for zonePlayer Main. HS Code = ]2 and updated DeviceValue = False. Info = <table><tr><td><img src=/SonosController/Images/Cover1.jpg></td><td><p>PLAYING</p><p>Radio - [DI] Vocal Trance</p><p> Let Me In</p><p>Immanuel D </p><p></p><p>/SonosController/Images/Cover1.jpg<p/></td></tr></table>
        After which about 60+ seconds after the announcement, it started playing again. However there was about 60 seconds of silence after the announcement before it started playing again.

        Comment

          #5
          Any ideas on this?

          Comment

            #6
            Originally posted by ClemsonJeeper View Post
            Any ideas on this?
            This case is actually a problem with Sonos SW R3.8

            The play event is missing so the PI is waiting for the announcement to start/end and after 50 seconds, a safety kicks in which brings everything back to its original state.

            I have multiple email/trouble tickets into the Sonos support team but so far I've gotten the boilerplate answer about them wanting to log into my system (which they always do to look at network issues) but I'm out until next week on travel.

            Dirk

            Comment

              #7
              Originally posted by dcorsus View Post
              This case is actually a problem with Sonos SW R3.8

              The play event is missing so the PI is waiting for the announcement to start/end and after 50 seconds, a safety kicks in which brings everything back to its original state.

              I have multiple email/trouble tickets into the Sonos support team but so far I've gotten the boilerplate answer about them wanting to log into my system (which they always do to look at network issues) but I'm out until next week on travel.

              Dirk
              So is there any workaround for this?

              If this is an issue with the latest Sonos Software, is everyone running a HS/Sonos set up running into this issue using the plugin?

              Comment

                #8
                Originally posted by ClemsonJeeper View Post
                So is there any workaround for this?

                If this is an issue with the latest Sonos Software, is everyone running a HS/Sonos set up running into this issue using the plugin?
                Sorry for the slow response, I was out of the country for the last 2 weeks.

                At this point I have not identified a work around. It happens on my set up, on occasion, not all the time, if I could reproduce it reliable I could try a few things and see if that solves the problem. As I wrote, I have reported it to the Sonos team but once they read it has to do with an external app, they lose interest very fast. I started seeing it on my setup pretty much around the time I upgraded my Sonos players to SW version R3.8 so I'm quite confident it is something with this release.

                If your announcements are all very short, like "door opened" etc. one of the things you can do it to set the max announcement time in the Sonos PI config page to lets say 20 seconds, so for the occasions when it happens, it's effect will be hardly noticeable.

                Does it happen all the time for you? I have one or two other users who have "seen" it.

                Dirk

                Comment

                  #9
                  Originally posted by dcorsus View Post
                  Sorry for the slow response, I was out of the country for the last 2 weeks.

                  At this point I have not identified a work around. It happens on my set up, on occasion, not all the time, if I could reproduce it reliable I could try a few things and see if that solves the problem. As I wrote, I have reported it to the Sonos team but once they read it has to do with an external app, they lose interest very fast. I started seeing it on my setup pretty much around the time I upgraded my Sonos players to SW version R3.8 so I'm quite confident it is something with this release.

                  If your announcements are all very short, like "door opened" etc. one of the things you can do it to set the max announcement time in the Sonos PI config page to lets say 20 seconds, so for the occasions when it happens, it's effect will be hardly noticeable.

                  Does it happen all the time for you? I have one or two other users who have "seen" it.

                  Dirk
                  Unfortunately, its pretty sporatic. For instance, the first time it worked just fine, but the next time it just didn't start playing again.

                  Either way, I'm just trying to decide if HomeSeer + Sonos is going to be what I'm using for my WHA system.

                  Comment

                  Previous template Next
                  Working...
                  X