I had been able to stream to my Sonos with no problem until the recent Media Server update. Now I am getting messages saying I’ve lost my connection to the server as my Sonos attempts to play each track. It seems to be related to transcoding and bandwidth, but I am not recognizing the root problem from looking at the log. Plex seems to recognize the need to transcode, but I don’t see an actual error (timeout for some reason?). Here is a log excerpt. Any suggestions?
I have no problem playing audio on any of my other Plex clients, just the Sonos. Yes, I have deleted and reinstalled the Sonos Plex Client.
Thanks
Aug 23, 2020 12:10:54.113 [0x7f5140d1c0] DEBUG - We’re going to try to auto-select an audio stream for account 1.
Aug 23, 2020 12:10:54.113 [0x7f5140d1c0] DEBUG - Selecting best audio stream for part ID 26806 (autoselect: 0 language: en)
Aug 23, 2020 12:10:54.113 [0x7f5140d1c0] DEBUG - Audio Stream: 39609, Subtitle Stream: -1
Aug 23, 2020 12:10:54.114 [0x7fa45de1c0] DEBUG - Completed: [127.0.0.1:51188] 200 GET /library/metadata/29821 (2 live) TLS 9ms 2192 bytes
Aug 23, 2020 12:10:54.352 [0x7fa42f01c0] DEBUG - Auth: authenticated user 1 as (address redacted)
Aug 23, 2020 12:10:54.352 [0x7f51cd71c0] DEBUG - Request: [127.0.0.1:51190 (WAN)] GET /music/:/transcode/universal/decision?hasMDE=1&partIndex=0&mediaIndex=0&directPlay=1 (2 live) TLS Signed-in Token (address redacted)
Aug 23, 2020 12:10:54.354 [0x7f51cd71c0] DEBUG - TranscodeUniversalRequest: using augmented profile Generic
Aug 23, 2020 12:10:54.354 [0x7f51cd71c0] DEBUG - Downloading document http://127.0.0.1:32400/library/metadata/29821?includeBandwidths=1&offset=-1&X-Plex-Incomplete-Segments=1&X-Plex-Session-Identifier=78-28-CA-04-D0-60%3A0
Aug 23, 2020 12:10:54.354 [0x7f51cd71c0] DEBUG - Auth: authenticated user 1 as address redacted
Aug 23, 2020 12:10:54.363 [0x7f51cd71c0] DEBUG - We’re going to try to auto-select an audio stream for account 1.
Aug 23, 2020 12:10:54.363 [0x7f51cd71c0] DEBUG - Selecting best audio stream for part ID 26806 (autoselect: 0 language: en)
Aug 23, 2020 12:10:54.363 [0x7f51cd71c0] DEBUG - Audio Stream: 39609, Subtitle Stream: -1
Aug 23, 2020 12:10:54.365 [0x7f51cd71c0] WARN - Streaming Resource: Bandwidth exceeded: 1574 kbps > 1000 kbps
Aug 23, 2020 12:10:54.365 [0x7f51cd71c0] DEBUG - MDE: Selected protocol http; container: flac
Aug 23, 2020 12:10:54.365 [0x7f51cd71c0] DEBUG - MDE: analyzing media item 26728
Aug 23, 2020 12:10:54.365 [0x7f51cd71c0] DEBUG - MDE: Matte Kudasai: Direct Playing due to no transcode profile
Aug 23, 2020 12:10:54.365 [0x7f51cd71c0] DEBUG - MDE: Matte Kudasai: DirectPlay
Aug 23, 2020 12:10:54.365 [0x7f51cd71c0] DEBUG - MDE: Matte Kudasai: selected media 0 / 26728
Aug 23, 2020 12:10:54.365 [0x7f51cd71c0] DEBUG - Streaming Resource: Calculated bandwidth of 1653kbps exceeds bandwidth limit. Changing decision parameters provided by client to fit bandwidth limit of 1000kbps
Aug 23, 2020 12:10:54.365 [0x7f51cd71c0] DEBUG - Streaming Resource: Determining preferred transcode encoders through transcode only decision.
Aug 23, 2020 12:10:54.366 [0x7f51cd71c0] DEBUG - Streaming Resource: Reducing playback quality for 952kbps stream bitrate: disable audio DS as 2147483647kbps is > the 952kbps available
Aug 23, 2020 12:10:54.366 [0x7f51cd71c0] DEBUG - MDE: Selected protocol hls; container: mpegts
Aug 23, 2020 12:10:54.366 [0x7f51cd71c0] DEBUG - MDE: analyzing media item 26728
Aug 23, 2020 12:10:54.366 [0x7f51cd71c0] DEBUG - MDE: Matte Kudasai: Direct Play is disabled
Aug 23, 2020 12:10:54.366 [0x7f51cd71c0] DEBUG - MDE: Matte Kudasai: media must be transcoded in order to use the hls protocol
Aug 23, 2020 12:10:54.367 [0x7f51cd71c0] DEBUG - MDE: Matte Kudasai: Audio Direct Streaming is disabled, so video’s audio stream will be transcoded
Aug 23, 2020 12:10:54.367 [0x7f51cd71c0] DEBUG - MDE: Cannot direct stream audio stream due to profile or setting limitations
Aug 23, 2020 12:10:54.367 [0x7f51cd71c0] DEBUG - MDE: Matte Kudasai: selected media 0 / 26728
Aug 23, 2020 12:10:54.367 [0x7f51cd71c0] DEBUG - Streaming Resource: Reached Decision id=29821 codes=(MDE=3001,Not enough bandwidth for direct play of this item. Required bandwidth is 1574kbps and only 1000kbps is available. General=1001,Direct play not available; Conversion OK. Direct Play=3001,Not enough bandwidth for direct play of this item. Required bandwidth is 1653kbps and only 1000kbps is available. Transcode=1001,Direct play not available; Conversion OK.) media=(id=26728 part=(id=26806 decision=transcode protocol=hls streams=(Audio=(id=39609 decision=transcode bitrate=258 encoder=aac channels=2 rate=44100))))
Aug 23, 2020 12:10:54.369 [0x7fa42f01c0] DEBUG - Completed: [127.0.0.1:51190] 200 GET /music/:/transcode/universal/decision?hasMDE=1&partIndex=0&mediaIndex=0&directPlay=1 (2 live) TLS 16ms 2879 bytes
Aug 23, 2020 12:10:54.872 [0x7fa42f01c0] DEBUG - Auth: authenticated user 1 as address redacted
Aug 23, 2020 12:10:54.873 [0x7f5140d1c0] DEBUG - Request: [127.0.0.1:51192 (WAN)] GET /neighborhood/devices (2 live) TLS Signed-in Token (address redacted)
Aug 23, 2020 12:10:54.874 [0x7fa42f01c0] DEBUG - Completed: [127.0.0.1:51192] 200 GET /neighborhood/devices (2 live) TLS 1ms 8546 bytes
Aug 23, 2020 12:10:55.197 [0x7fa45de1c0] DEBUG - Auth: authenticated user 1 as address redacted
Aug 23, 2020 12:10:55.197 [0x7f51cd71c0] DEBUG - Request: [127.0.0.1:51194 (WAN)] GET /library/metadata/29822 (2 live) TLS Signed-in Token (address redacted)
Aug 23, 2020 12:10:55.205 [0x7f51cd71c0] DEBUG - We’re going to try to auto-select an audio stream for account 1.
Aug 23, 2020 12:10:55.205 [0x7f51cd71c0] DEBUG - Selecting best audio stream for part ID 26807 (autoselect: 0 language: en)
Aug 23, 2020 12:10:55.205 [0x7f51cd71c0] DEBUG - Audio Stream: 39610, Subtitle Stream: -1
Aug 23, 2020 12:10:55.206 [0x7fa45de1c0] DEBUG - Completed: [127.0.0.1:51194] 200 GET /library/metadata/29822 (2 live) TLS 9ms 2186 bytes
Aug 23, 2020 12:10:55.474 [0x7fa45de1c0] DEBUG - Auth: authenticated user 1 as address redacted
Aug 23, 2020 12:10:55.474 [0x7f51cd71c0] DEBUG - Request: [127.0.0.1:51196 (WAN)] GET /music/:/transcode/universal/decision?hasMDE=1&partIndex=0&mediaIndex=0&directPlay=1 (2 live) TLS Signed-in Token (address redacted)
Aug 23, 2020 12:10:55.476 [0x7f51cd71c0] DEBUG - TranscodeUniversalRequest: using augmented profile Generic
Aug 23, 2020 12:10:55.476 [0x7f51cd71c0] DEBUG - Downloading document http://127.0.0.1:32400/library/metadata/29822?includeBandwidths=1&offset=-1&X-Plex-Incomplete-Segments=1&X-Plex-Session-Identifier=78-28-CA-04-D0-60%3A0
Aug 23, 2020 12:10:55.476 [0x7f51cd71c0] DEBUG - Auth: authenticated user 1 as address redacted
Aug 23, 2020 12:10:55.485 [0x7f51cd71c0] DEBUG - We’re going to try to auto-select an audio stream for account 1.
Aug 23, 2020 12:10:55.485 [0x7f51cd71c0] DEBUG - Selecting best audio stream for part ID 26807 (autoselect: 0 language: en)
Aug 23, 2020 12:10:55.485 [0x7f51cd71c0] DEBUG - Audio Stream: 39610, Subtitle Stream: -1
Aug 23, 2020 12:10:55.487 [0x7f51cd71c0] WARN - Streaming Resource: Bandwidth exceeded: 1514 kbps > 1000 kbps
Aug 23, 2020 12:10:55.487 [0x7f51cd71c0] DEBUG - MDE: Selected protocol http; container: flac
Aug 23, 2020 12:10:55.487 [0x7f51cd71c0] DEBUG - MDE: analyzing media item 26729
Aug 23, 2020 12:10:55.487 [0x7f51cd71c0] DEBUG - MDE: Discipline: Direct Playing due to no transcode profile
Aug 23, 2020 12:10:55.487 [0x7f51cd71c0] DEBUG - MDE: Discipline: DirectPlay
Aug 23, 2020 12:10:55.487 [0x7f51cd71c0] DEBUG - MDE: Discipline: selected media 0 / 26729
Aug 23, 2020 12:10:55.487 [0x7f51cd71c0] DEBUG - Streaming Resource: Calculated bandwidth of 1590kbps exceeds bandwidth limit. Changing decision parameters provided by client to fit bandwidth limit of 1000kbps
