SOLVED! Plex Docker not transcoding music for PS4

Plex Players Gaming Consoles
, ,
1

Server Version#: 1.16.3.1402
Plex for Playstation 4#: 3.107.1
Playstation 4#: 01.81

I have an issue with playing music on the PS4. Whatever file I select, it simply won’t play: it will show an error for a split second and will get back to the music selection. Movies / TV works flawless.

I recently switched from a CentOS-based VM Plex Server to the Docker version (plexinc/pms-docker:plexpass). Testing the behaviour on different players (such as PlexAmp, Plex web client, Plex for iOS) showed, that only the PS4 has this issue.

I’ve tried to search for that specific issue, but couldn’t find anything in relation to that. Here is an excerpt of the “Plex Media Server.log”:

Jul 19, 2019 11:50:27.331 [0x7f11937fe700] DEBUG - [Now] User is music (ID: 24267440)
Jul 19, 2019 11:50:27.331 [0x7f11937fe700] DEBUG - [Now] Device is WebMAF (PlayStation 4 Pro).
Jul 19, 2019 11:50:27.332 [0x7f11937fe700] ERROR - Unable to find client profile for device; platform=WebMAF, platformVersion=01.81, device=PlayStation 4 Pro, model=
Jul 19, 2019 11:50:27.332 [0x7f11937fe700] DEBUG - [Now] Updated play state for /library/metadata/2559.
Jul 19, 2019 11:50:27.334 [0x7f11937fe700] DEBUG - HubCache: Expiring '24267440/music.recent.artist.6/1/hubs/sections/6/en-GBexternal-media/count=8&excludeElements=Actor%2CCollection%2CCountry%2CDirector%2CGenre%2CLabel%2CMood%2CPart%2CProducer%2CRole%2CSimilar%2CWriter%2CPhoto%2CMedia&excludeFields=file%2Csummary%2Ctagline&excludePlaylists=1&includeExternalMetadata=1&includeStations=1&includeTypeFirst=1&libraryHubsOnly=1' because of event plex.event.play.session.state.update.
Jul 19, 2019 11:50:27.334 [0x7f11937fe700] DEBUG - HubCache: Expiring '24267440/music.recent.genre.6/1/hubs/sections/6/en-GBexternal-media/count=8&excludeElements=Actor%2CCollection%2CCountry%2CDirector%2CGenre%2CLabel%2CMood%2CPart%2CProducer%2CRole%2CSimilar%2CWriter%2CPhoto%2CMedia&excludeFields=file%2Csummary%2Ctagline&excludePlaylists=1&includeExternalMetadata=1&includeStations=1&includeTypeFirst=1&libraryHubsOnly=1' because of event plex.event.play.session.state.update.
Jul 19, 2019 11:50:27.334 [0x7f11937fe700] DEBUG - HubCache: Expiring '24267440/music.recent.played.6/1/hubs/sections/6/en-GBexternal-media/count=8&excludeElements=Actor%2CCollection%2CCountry%2CDirector%2CGenre%2CLabel%2CMood%2CPart%2CProducer%2CRole%2CSimilar%2CWriter%2CPhoto%2CMedia&excludeFields=file%2Csummary%2Ctagline&excludePlaylists=1&includeExternalMetadata=1&includeStations=1&includeTypeFirst=1&libraryHubsOnly=1' because of event plex.event.play.session.state.update.
Jul 19, 2019 11:50:27.334 [0x7f11937fe700] DEBUG - HubCache: Expiring '24267440/music.top.period.6/1/hubs/sections/6/en-GBexternal-media/count=8&excludeElements=Actor%2CCollection%2CCountry%2CDirector%2CGenre%2CLabel%2CMood%2CPart%2CProducer%2CRole%2CSimilar%2CWriter%2CPhoto%2CMedia&excludeFields=file%2Csummary%2Ctagline&excludePlaylists=1&includeExternalMetadata=1&includeStations=1&includeTypeFirst=1&libraryHubsOnly=1' because of event plex.event.play.session.state.update.
Jul 19, 2019 11:50:27.338 [0x7f11937fe700] WARN - WebSocket: Ignoring message since we're closing the connection
Jul 19, 2019 11:50:27.338 [0x7f11937fe700] DEBUG - Statistics: (fkv4txc6kqo28nzttbyiq4i5) Reporting active playback in state 2 of type 10 (scrobble: 0) for account 24267440
Jul 19, 2019 11:50:27.341 [0x7f11c0d90700] DEBUG - Completed: [192.168.6.30:57785] 200 GET /:/timeline?ratingKey=2559&key=%2Flibrary%2Fmetadata%2F2559&playbackTime=0&playQueueItemID=212&state=buffering&hasMDE=1&time=0&duration=181000 (17 live) TLS GZIP 14ms 598 bytes (pipelined: 6)
Jul 19, 2019 11:50:27.343 [0x7f11bbfff700] DEBUG - Auth: authenticated user 1 as SadFool
Jul 19, 2019 11:50:27.343 [0x7f1199ffb700] DEBUG - Request: [192.168.6.102:61548 (Subnet)] GET /status/sessions (16 live) TLS GZIP Signed-in Token (SadFool)
Jul 19, 2019 11:50:27.344 [0x7f1199ffb700] DEBUG - [Now] Adding 1 sessions.
Jul 19, 2019 11:50:27.346 [0x7f11c0d90700] DEBUG - Completed: [192.168.6.102:61548] 200 GET /status/sessions (16 live) TLS GZIP 1ms 429 bytes (pipelined: 12)
Jul 19, 2019 11:50:27.357 [0x7f11bbfff700] DEBUG - Auth: authenticated user 24267440 as music
Jul 19, 2019 11:50:27.358 [0x7f1193fff700] DEBUG - Request: [192.168.6.30:57785 (Subnet)] GET /music/:/transcode/universal/decision?hasMDE=1&path=%2Flibrary%2Fmetadata%2F2559&mediaIndex=0&partIndex=0&directStream=0&directStreamAudio=0&session=y4m8reds0un2qwt61mlt9l9m&protocol=hls&directPlay=0&Accept-Language=en-GB (15 live) TLS GZIP Signed-in Token (music)
Jul 19, 2019 11:50:27.358 [0x7f1193fff700] DEBUG - Found session GUID of y4m8reds0un2qwt61mlt9l9m in session start.
Jul 19, 2019 11:50:27.358 [0x7f1193fff700] ERROR - ClientProfileExtra: music transcode target already exists for  streaming dash
Jul 19, 2019 11:50:27.358 [0x7f1193fff700] DEBUG - TranscodeUniversalRequest: using augmented profile PlayStation 4 App
Jul 19, 2019 11:50:27.358 [0x7f1193fff700] DEBUG - Downloading document http://127.0.0.1:32400/library/metadata/2559?includeBandwidths=1&offset=-1&X-Plex-Incomplete-Segments=1&X-Plex-Session-Identifier=ypgnstngv5ds8mjkrfgxtbie
Jul 19, 2019 11:50:27.358 [0x7f1193fff700] DEBUG - Auth: authenticated user 24267440 as music
Jul 19, 2019 11:50:27.363 [0x7f1193fff700] DEBUG - MDE: Selected protocol hls; container: mpegts
Jul 19, 2019 11:50:27.363 [0x7f1193fff700] DEBUG - MDE: analyzing media item 3955
Jul 19, 2019 11:50:27.363 [0x7f1193fff700] DEBUG - MDE: Lucky Prince: Direct Play is disabled
Jul 19, 2019 11:50:27.363 [0x7f1193fff700] DEBUG - MDE: Lucky Prince: media must be transcoded in order to use the hls protocol
Jul 19, 2019 11:50:27.363 [0x7f1193fff700] DEBUG - MDE: Lucky Prince: no direct play music profile exists for http/mp3/mp3
Jul 19, 2019 11:50:27.363 [0x7f1193fff700] DEBUG - MDE: Lucky Prince: Audio Direct Streaming is disabled, so video's audio stream will be transcoded
Jul 19, 2019 11:50:27.363 [0x7f1193fff700] DEBUG - MDE: Cannot direct stream audio stream due to profile or setting limitations
Jul 19, 2019 11:50:27.363 [0x7f1193fff700] DEBUG - MDE: Lucky Prince: selected media 0 / 3955
Jul 19, 2019 11:50:27.363 [0x7f1193fff700] DEBUG - Streaming Resource: Added session 0x7f11b01b8990:ypgnstngv5ds8mjkrfgxtbie
Jul 19, 2019 11:50:27.363 [0x7f1193fff700] DEBUG - Streaming Resource: Reached Decision id=2559 codes=(General=1001,Direct play not available; Conversion OK. Direct Play=3000,App cannot direct play this item. Direct play is disabled. Transcode=1001,Direct play not available; Conversion OK.) media=(id=3955 part=(id=3977 decision=transcode container=mpegts protocol=hls streams=(Video=(id=-1 decision=transcode bitrate=2147483647 encoder=libx264 width=50 height=50) Audio=(id=5991 decision=transcode bitrate=258 encoder=aac channels=2 rate=44100))))
Jul 19, 2019 11:50:27.364 [0x7f11c0d90700] DEBUG - Completed: [192.168.6.30:57785] 200 GET /music/:/transcode/universal/decision?hasMDE=1&path=%2Flibrary%2Fmetadata%2F2559&mediaIndex=0&partIndex=0&directStream=0&directStreamAudio=0&session=y4m8reds0un2qwt61mlt9l9m&protocol=hls&directPlay=0&Accept-Language=en-GB (15 live) TLS GZIP 6ms 1203 bytes (pipelined: 7)
Jul 19, 2019 11:50:27.724 [0x7f11bbfff700] DEBUG - Auth: authenticated user 24267440 as music
Jul 19, 2019 11:50:27.724 [0x7f11997fa700] DEBUG - Request: [192.168.6.30:57785 (Subnet)] GET /playQueues/27?repeat=0&own=1&includeChapters=1&includeGeolocation=1&includeExternalMedia=1 (15 live) TLS GZIP Signed-in Token (music)
Jul 19, 2019 11:50:27.725 [0x7f11997fa700] DEBUG - PlayQueue: total generated: 12, before cursor: 0, after cursor: 11
Jul 19, 2019 11:50:27.725 [0x7f11997fa700] DEBUG - PlayQueue: No need to refresh, active window is operational.
Jul 19, 2019 11:50:27.728 [0x7f11bbfff700] DEBUG - Auth: authenticated user 24267440 as music
Jul 19, 2019 11:50:27.729 [0x7f1198ff9700] DEBUG - Request: [192.168.6.30:59204 (Subnet)] GET /:/timeline?ratingKey=2559&key=%2Flibrary%2Fmetadata%2F2559&playbackTime=0&playQueueItemID=212&state=stopped&hasMDE=1&time=0&duration=181000 (14 live) TLS GZIP Signed-in Token (music)
Jul 19, 2019 11:50:27.729 [0x7f1198ff9700] DEBUG - Client [ypgnstngv5ds8mjkrfgxtbie] reporting timeline state stopped, progress of 0/181000ms for guid=, ratingKey=2559 url=, key=/library/metadata/2559, containerKey=, metadataId=2559, source=
Jul 19, 2019 11:50:27.730 [0x7f1198ff9700] DEBUG - [Now] User is music (ID: 24267440)
Jul 19, 2019 11:50:27.730 [0x7f1198ff9700] DEBUG - [Now] Device is WebMAF (PlayStation 4 Pro).
Jul 19, 2019 11:50:27.730 [0x7f1198ff9700] ERROR - Unable to find client profile for device; platform=WebMAF, platformVersion=01.81, device=PlayStation 4 Pro, model=
Jul 19, 2019 11:50:27.730 [0x7f1198ff9700] DEBUG - [Now] Updated play state for /library/metadata/2559.
Jul 19, 2019 11:50:27.731 [0x7f1198ff9700] WARN - WebSocket: Ignoring message since we're closing the connection
Jul 19, 2019 11:50:27.731 [0x7f1198ff9700] DEBUG - Statistics: (fkv4txc6kqo28nzttbyiq4i5) Reporting active playback in state 3 of type 10 (scrobble: 0) for account 24267440
Jul 19, 2019 11:50:27.732 [0x7f1198ff9700] DEBUG - Streaming Resource: Terminated session 0x7f11b01b8990:ypgnstngv5ds8mjkrfgxtbie with reason Client stopped playback.
Jul 19, 2019 11:50:27.732 [0x7f1198ff9700] DEBUG - Streaming Resource: Removing session 0x7f11b01b8990:ypgnstngv5ds8mjkrfgxtbie

I hope anyone can shed some light into this…

Cheers,
Marc

2

I don’t have a solution, I just think it’s completely busted on the PS4. Many have mentioned this in other areas, and it’s still broke. Hopefully they can get it fixed.

3

The PS4 app got updated on the 19th (haven’t used the app since my post).

It is still not working, but at least now the error message stays on screen: “Conversion failed. Unknown error”. Doesn’t help much, but it looks like it’s still busted for music (my whole library is MP3).

Any other device (Windows, iOS, Android, Mac) works.

4

Tried with different settings in the PS4 app, now it tries to play, shows an error (super quick) and cancels. After that, I’ll have to quit the Plex App, as it otherwise won’t react.

I made a video of the process, and could pause the error message. The error is:

An error occurred trying to play “Intro”.
Error code: -2140536828 (disaster)
[Skip]
[Retry]
[Close]

Funnily enough, even though there are buttons, I never have a chance to click on them.

Is there anywhere a list of error codes?

5

Hi All,

case can be closed. I figured what my issue was.

First off, this is related to Plex running as docker image on openmediavault.
It is very important, that not only the disk containing the docker images, but also the disk where the transcoder folder is located on has NOT the flag “noexec” set. That’s an issue on my side, as I would not expect the “output” folder for the transcode having the exec requirement… but anyhow.

If you have the same issue (not transcoding anything - I only had the issue with music as I’m on the local network and did not require transcoding for video):

  1. Check your mounts using command “cat /proc/mounts”. Identify you’re disks / folders used for the docker containers itself as well as the disk where the transcode folder is mapped to:
/dev/sdb1 /sharedfolders/dockerCE ext4 rw,relatime,discard,jqfmt=vfsv0,usrjquota=aquota.user,grpjquota=aquota.group 0 0                                                                                            
/dev/sdc1 /sharedfolders/dockerCfg ext4 rw,relatime,noexec,discard,jqfmt=vfsv0,usrjquota=aquota.user,grpjquota=aquota.group 0 0

You see above, that my shared folder “dockerCfg” has the noexec flag set. This is the shared folder which has the transcode folder on.

Next:
Open /etc/openmediavault/config.xml with your preferred editor. I show here the info when using nano:

  1. nano /etc/openmediavault/config.xml
  2. in nano, press and type ‘mntent’
  3. find the entry, which contains the disk id (in my case “dockerCfg”):
<mntent>
  <uuid>f800...</uid>
  <fsname>/dev/disk/by-label/dockerCfg</fsname>
  <opts>defaults,nofail,noexec,... etc </opts>
</mntent>
  1. Once the related entry has been found, simply remove the “noexec” for this drive / sharedfolder.
  2. Press +, press and hit . This saves the file.
  3. Rebuilt the fstab using the command “omv-mkconf stab”.
  4. Reboot openmediavault.
  5. Check with “cat /proc/mounts” if the noexec flag has been removed.
  6. Enjoy your transcoding from the Plex docker!

Hope above helps anyone :wink:

Cheers,

Marc

6

This topic was automatically closed 90 days after the last reply. New replies are no longer allowed.