I’ve had the Sonos plugin and full TTS functionality working for awhile. However, today when I began to revisit an attempt to integrate some PLEG logic with Sonos Say ([url=http://forum.micasaverde.com/index.php/topic,30313.msg216884.html#msg216884]http://forum.micasaverde.com/index.php/topic,30313.msg216884.html#msg216884[/url], I have discovered that I can no longer get my PLEG action nor the Sonos TTS function to say anything. When I try I can see on my phone (acting as a Sonos controller) the attempt and I see say.123.mp3 (123 is my sonos device id) but then an error message pops up saying “Unable to play Say.123.mp3: the track is not encoded correctly” When I tail the log (in a browser) that error never appears. Everything appears to work. I see all the normal transport stuff
06 02/06/15 20:25:54.777 Device_Variable::m_szValue_set device: 125 service: urn:rts-services-com:serviceId:ProgramLogicC variable: ActionsMap was: {} now: {} #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x2b22a000>
08 02/06/15 20:25:54.778 JobHandler_LuaUPnP::HandleActionRequest device: 125 service: urn:rts-services-com:serviceId:ProgramLogicC action: StartTimer <0x2b22a000>
08 02/06/15 20:25:54.778 JobHandler_LuaUPnP::HandleActionRequest argument timerName=AnnounceTimer <0x2b22a000>
08 02/06/15 20:25:54.779 JobHandler_LuaUPnP::HandleActionRequest argument intervalTime= <0x2b22a000>
08 02/06/15 20:25:54.782 JobHandler_LuaUPnP::HandleActionRequest device: 123 service: urn:micasaverde-com:serviceId:Sonos1 action: Say <0x2b22a000>
08 02/06/15 20:25:54.782 JobHandler_LuaUPnP::HandleActionRequest argument Engine=GOOGLE <0x2b22a000>
08 02/06/15 20:25:54.783 JobHandler_LuaUPnP::HandleActionRequest argument SameVolumeForAll=t <0x2b22a000>
08 02/06/15 20:25:54.783 JobHandler_LuaUPnP::HandleActionRequest argument GroupZones=t <0x2b22a000>
08 02/06/15 20:25:54.783 JobHandler_LuaUPnP::HandleActionRequest argument GroupDevices=t <0x2b22a000>
08 02/06/15 20:25:54.783 JobHandler_LuaUPnP::HandleActionRequest argument Text=The basement door is now open <0x2b22a000>
08 02/06/15 20:25:54.784 JobHandler_LuaUPnP::HandleActionRequest argument Language=en <0x2b22a000>
08 02/06/15 20:25:54.784 JobHandler_LuaUPnP::HandleActionRequest argument Volume=30 <0x2b22a000>
06 02/06/15 20:25:55.164 Device_Variable::m_szValue_set device: 123 service: urn:upnp-org:serviceId:AVTransport variable: CurrentTransportActions was: Set, Play, Stop, Pause, Next now: Set, Play, Stop, Pause, Seek, Next, Previous #hooks: 0 upnp: 0 v:0xc25800/NONE duplicate:0 <0x2b22a000>
06 02/06/15 20:25:55.243 Device_Variable::m_szValue_set device: 123 service: urn:upnp-org:serviceId:AVTransport variable: NumberOfTracks was: 0 now: 1 #hooks: 0 upnp: 0 v:0xc245d0/NONE duplicate:0 <0x2b22a000>
06 02/06/15 20:25:55.244 Device_Variable::m_szValue_set device: 123 service: urn:upnp-org:serviceId:AVTransport variable: AVTransportURI was: pndrradio:1130179502462387786 now: http://192.168.1.101:80/Say.123.mp3 #hooks: 0 upnp: 0 v:0xcdec38/NONE duplicate:0 <0x2b22a000>
06 02/06/15 20:25:55.244 Device_Variable::m_szValue_set device: 123 service: urn:upnp-org:serviceId:AVTransport variable: AVTransportURIMetaData was: 90s Alternative Radioobject.item.audioItem.audioBroadcastSA_RINCON3_chrisguess@mac.com now: #hooks: 0 upnp: 0 v:0xcdc388/NONE duplicate:0 <0x2b22a000>
06 02/06/15 20:25:55.261 Device_Variable::m_szValue_set device: 123 service: urn:upnp-org:serviceId:AVTransport variable: CurrentTrack was: 0 now: 1 #hooks: 0 upnp: 0 v:0xc25b18/NONE duplicate:0 <0x2b22a000>
06 02/06/15 20:25:55.262 Device_Variable::m_szValue_set device: 123 service: urn:upnp-org:serviceId:AVTransport variable: CurrentTrackURI was: now: http://192.168.1.101:80/Say.123.mp3 #hooks: 0 upnp: 0 v:0xc25578/NONE duplicate:0 <0x2b22a000>
06 02/06/15 20:25:55.262 Device_Variable::m_szValue_set device: 123 service: urn:upnp-org:serviceId:AVTransport variable: CurrentTrackMetaData was: now: http://192.168.1.101:80/Say.123.mp3Say.123.mp3object.item.audioItem.musicTrack #hooks: 0 upnp: 0 v:0xc254f8/NONE duplicate:0 <0x2b22a000>
06 02/06/15 20:25:55.265 Device_Variable::m_szValue_set device: 123 service: urn:micasaverde-com:serviceId:Sonos1 variable: CurrentService was: Pandora now: #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:0 <0x2b22a000>
06 02/06/15 20:25:55.266 Device_Variable::m_szValue_set device: 123 service: urn:upnp-org:serviceId:AVTransport variable: CurrentRadio was: 90s Alternative Radio now: #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:0 <0x2b22a000>
06 02/06/15 20:25:55.266 Device_Variable::m_szValue_set device: 123 service: urn:upnp-org:serviceId:AVTransport variable: CurrentStatus was: Pandora: 90s Alternative Radio now: Say.123.mp3 #hooks: 0 upnp: 0 v:0xcb10b0/NONE duplicate:0 <0x2b22a000>
06 02/06/15 20:25:55.267 Device_Variable::m_szValue_set device: 123 service: urn:upnp-org:serviceId:AVTransport variable: CurrentTitle was: now: Say.123.mp3 #hooks: 0 upnp: 0 v:0xcb1500/NONE duplicate:0 <0x2b22a000>
06 02/06/15 20:25:55.268 Device_Variable::m_szValue_set device: 123 service: urn:upnp-org:serviceId:AVTransport variable: CurrentAlbumArt was: http://192.168.1.207:1400/getaa?s=1&u=pndrradio%3a1130179502462387786 now: http://192.168.1.207:1400/img/icon-S1.png #hooks: 0 upnp: 0 v:0xc25af8/NONE duplicate:0 <0x2b22a000>
06 02/06/15 20:25:55.291 Device_Variable::m_szValue_set device: 123 service: urn:upnp-org:serviceId:RenderingControl variable: Volume was: 32 now: 30 #hooks: 0 upnp: 0 v:0xdea250/NONE duplicate:0 <0x2b22a000>
06 02/06/15 20:25:55.292 Device_Variable::m_szValue_set device: 123 service: urn:micasaverde-com:serviceId:HaDevice1 variable: LastUpdate was: 1423275920 now: 1423275955 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:0 <0x2b22a000>
04 02/06/15 20:25:55.294 <0x2b22a000>
06 02/06/15 20:25:55.301 Device_Variable::m_szValue_set device: 125 service: urn:rts-services-com:serviceId:ProgramLogicC variable: ObjectStatusMap was: {{name = 'AnnounceTimer', state = false, seq = 1423275919.9164, oseq = 1423275939.1004},{name = 'InitialDoorOpen', state = false, seq = 1423270214.5761, oseq = 1423272574.7566},{name = 'AnnounceTimeout', state = false, seq = 1423270235.1344, oseq = 1423272574.7584},{name = 'DoorOpen', state = false, seq = 1423270214.5752, oseq = 1423272574.7557},{name = 'AnnounceTimer2', state = false, seq = 1422752904.5656, oseq = 1422752944.1006},{name = 'AnnounceTimeout2', state = false, seq = 0, oseq = 0},} now: {{name = 'AnnounceTimer', state = true, seq = 1423275955.2945, oseq = 1423275939.1004},{name = 'InitialDoorOpen', state = false, seq = 1423270214.5761, oseq = 1423272574.7566},{name = 'AnnounceTimeout', state = false, seq = 1423270235.1344, oseq = 1423272574.7584},{name = 'DoorOpen', state = false, seq = 1423270214.5752, oseq = 1423272574.7557},{name = 'AnnounceTimer2', state = false, seq = 1422752904.5656, oseq = 1422752944.1006},{name = 'AnnounceTimeout2', state = false, seq = 0, oseq = 0},} #hooks: 0 upnp: 0 v:0xcc7320/NONE duplicate:0 <0x2b22a000>
04 02/06/15 20:25:55.308 <0x2b22a000>
Anyone else having any issues or has anyone seen this before? It’s almost as if a Sonos software update changed something in terms of what it is expecting for encoding.