Server Version#: 1.15.3.835 (also occurred on the previous public version)
Player Version#: Web Client 3.83.1
Windows 10 x64
Suddenly files cannot be played locally in Plex Web. Some flac music files and mkv video files seem to buffer forever. They work fine locally using Plex Media Player. The errors that stand out are:
2019-03-29 18:08:07,953 (2308) : CRITICAL (runtime:709) - Private handlers are no longer supported; couldn’t register <bound method StreamService.token_handler of <StreamService object at 0x058D3490>>
Mar 29, 2019 18:08:08.809 [6592] ERROR - QueryParser: Invalid field ‘onlyTransient’ found, ignoring.
Mar 29, 2019 18:10:04.783 [2520] ERROR - Couldn’t delete the file “C:\Users\xxxxxxx\AppData\Local\Temp\logs.zipe2e6d954-c661-46f5-a7f4-9def55e5fc97”: The process cannot access the file because it is being used by another process
I tried exiting PMS, deleting logs, plugins, and starting PMS again.
I found that PMP also does not play these files if “Allow Direct Play” and “Allow Direct Stream” are not checked, so this would indicate some transcoder issue.
It seems like a random 10% of all media files fail to transcode. Attached is another log where Call Ya Bluff.mp3 fails to load (also in PMP), but the next song, Lyrical Healing.mp3 plays fine. Directly playing Call Ya Bluff.mp3 via VLC works fine, so there’s nothing wrong with the file.
Mar 30, 2019 12:07:30.055 [3644] DEBUG - [Universal] Using local file path instead of URL: Z:\Music\Ludacris\Ludaversal\Call Ya Bluff.mp3
Mar 30, 2019 12:07:30.057 [3644] DEBUG - Job running: EAE_ROOT='\\?\C:\Users\xxxxxxx\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\EasyAudioEncoder' FFMPEG_EXTERNAL_LIBS='C\:\\Users\\xxxxxxx\\AppData\\Local\\Plex\ Media\ Server\\Codecs\\a22632d-2131-windows-x86\\' X_PLEX_TOKEN='xxxxxxxxxxxxxxxxxxxx' C:\Program Files (x86)\Plex\Plex Media Server\Plex Transcoder.exe -analyzeduration 20000000 -probesize 20000000 -i "Z:\Music\Ludacris\Ludaversal\Call Ya Bluff.mp3" -map 0:0 -codec:0 copy -copypriorss:0 0 -f dash -min_seg_duration 1000000 -skip_to_segment 1 -time_delta 0.0625 -manifest_name http://127.0.0.1:32400/video/:/transcode/session/4en7w91k9yedmq9jid2pof3l/aab538d1-2d28-4dbc-ba89-70d61f35495e/manifest -avoid_negative_ts disabled -map_metadata -1 -map_chapters -1 dash -start_at_zero -copyts -vsync cfr -y -nostats -loglevel quiet -loglevel_plex error -progressurl http://127.0.0.1:32400/video/:/transcode/session/4en7w91k9yedmq9jid2pof3l/aab538d1-2d28-4dbc-ba89-70d61f35495e/progress
Mar 30, 2019 12:08:30.340 [2884] DEBUG - Jobs: 'C:\Program Files (x86)\Plex\Plex Media Server\Plex Transcoder.exe' exit code for process 10332 is 1 (failure)
Mar 30, 2019 12:08:30.340 [1220] DEBUG - Killing job.
Mar 30, 2019 12:08:30.340 [2100] DEBUG - Streaming Resource: Terminated session 0x5ae9a48:jqe8xwif2wn58jtm2iywhq9p with reason Conversion failed. The transcoder exited due to an error.
Mar 30, 2019 12:08:30.735 [9232] DEBUG - Request: [127.0.0.1:60299 (Loopback)] GET /library/parts/1515934/1511646860/file.mp3 (19 live) Signed-in Token (xxxxxxx)
Mar 30, 2019 12:08:30.739 [9232] DEBUG - Found an ID3v2 tag at start of MP3 file with size 427017, skipping.
Mar 30, 2019 12:08:30.740 [9232] DEBUG - Content-Length of Z:\Music\Ludacris\Ludaversal\Lyrical Healing.mp3 is 2318258.
Mar 30, 2019 12:08:30.795 [5152] DEBUG - Completed: [127.0.0.1:60299] 200 GET /library/parts/1515934/1511646860/file.mp3 (19 live) 60ms 2318258 bytes (pipelined: 5) (range: bytes=0-)
Thanks for the logs. The Plex Transcoder process seems to hang and then fails
Could you check the windows event log - both the System event log and Application event log for the following times
March 30 between 12:07:30 and 12:08:30
March 29 between 18:08:10 and 18:10:00
So try one more time -
shutdown plex media server (exit)
upgrade to 1.15.3.835
and try with one of these files
If it hangs for about a minute in the same way - locate the Plex Transcoder.exe process in windows task manager and dump it (right click / create dump) and zip the dmp and attach with the logs
March 29 18:08:10 and 18:10:00
(No Application Logs)
System Log 6:09:11 PM (Event ID 10016): The application-specific permission settings do not grant Local Activation permission for the COM Server application with CLSID {2593F8B9-4EAF-457C-B68A-50F6B8EA6B54} and APPID {15C20B67-12E7-4BB6-92BB-7AFF07997402} to the user ENTERTAINMENT\xxxxxxx SID (S-1-5-21-3060271350-3576781399-3570078349-1001) from address LocalHost (Using LRPC) running in the application container Unavailable SID (Unavailable). This security permission can be modified using the Component Services administrative tool.
March 30 12:07:30 and 12:08:30
(No Application Logs)
System Log 12:07:04 PM (Event ID 10016): The application-specific permission settings do not grant Local Activation permission for the COM Server application with CLSID {2593F8B9-4EAF-457C-B68A-50F6B8EA6B54} and APPID {15C20B67-12E7-4BB6-92BB-7AFF07997402} to the user ENTERTAINMENT\xxxxxxx SID (S-1-5-21-3060271350-3576781399-3570078349-1001) from address LocalHost (Using LRPC) running in the application container Unavailable SID (Unavailable). This security permission can be modified using the Component Services administrative tool.
I can update again to provide dump logs and I can provide the .mp3 in a PM later tonight if still needed.
This specific failure for the mp3 has now been resolved. The media analysis was incomplete and the codec and audio channels / container information was missing.
Why is a re-analysis of music files required after upgrading from PMS 1.14.x to 1.15.x ? Is this noted somewhere?
Also, re-analysis did not fix the playback issue with mkv files noted in the first post. The only way I can find to get these certain video files to play is by reverting back to 1.14.x.
For the video file issue, would like to see a clip created that crashes in the same way and please let me have the media info xml plus the clip and dmp and logs from 1.15.x and also logs of it playing ok on 1.14.x
Are you actually saying the mp3 file played ok in 1.14.x but not in 1.15.x ?
Yes, that is correct. All of my media files played fine in 1.14.x. Once I upgraded to 1.15.x, it seems maybe 5-10% of seemingly random media from different libraries failed to play. Also, every time I revert back to 1.14.x media plays fine again.
I’ll try to find another mp3 that this happens to and provide logs from both 1.14.x and 1.15.x.
I’ll also try to make a clip of the mkv file that this is also occurring on and PM you the XML, logs, and dumps.
It might be a few days until I have time to do all of this.
And please get me the logs and media info xml from both 1.14.x and 1.15.x. For the mp3 i would like to see if missing codec information in the media info xml does not affect playback on 1.14.x
I just PM’d you the dump, logs, XML, and sample .mp3 and .mkv.
On PMS 1.15.x, it seems that re-analyzing music files seems to fix the issue, but it does not work for video files. Reverting to 1.14.x allows me to play all of my media perfectly fine again.
sa2000 was able to recreate the issue I was experiencing with my test .mkv file, but only with Direct Play unchecked in the Web settings. I am still experiencing this issue while Direct Play is checked in 1.15.x. I also tried after clicking the ‘Restore Defaults’ button in Web Client -> Debug.
I provided sa2000 and the development team screenshots of my settings, the test .mkv, a force-dump of PlexTranscoder.exe, the file’s XML info, Web Debug Logs, and the Server Logs.
After reverting to 1.14.1.5488 again, the file plays fine.
I did get a hang when testing your clip on v1.15.4.919-8e2884e4b and that was referred to the development team. But my tests on 1.15.5.994-4610c6e8d have been successful replicating all your playback options with Automatic Quality Adjustment off in Plex Web and using a wan connected Plex Web chrome session and with server transcoder Prefer high quality encoding setting and using same test clip.
Your latest diagnostic showed the transcoder process hanging on launch and then failing 75 seconds later. Previous diagnostics showed on 1.15.4.919 showed a server hang
My advice now is to look at the windows system and application event logs in case of some system issues eg disk failures. Also to try uninstall and reboot and check for any residual files in C:\Program Files (x86)\Plex\Plex Media Server and remove if any found and then re-install 1.15.5.994 and try again
Also to run sfc /scannow in elevated command line window in case of windows issue
As a side note: recently we discovered Malwarebytes Premium affecting the operation of the Plex Transcoder and causing failures. I do not know if you run this
I updated to PMS 1.15.6.1079 and confirmed the playback issue still exists.
After reverting to 1.14.1.5488 again, the file plays fine.
I did not see any relevant Event Viewer errors, I followed the correct uninstall/install instructions, I ran sfc /scannow and it resulted in “Windows Resource Protection did not find any integrity violations.”
I do not have any 3rd party antivirus or security software installed.
The newest test logs and dumps have been PM’d to sa2000.
