Plex constantly buffers on TS-451

Plex Media Server NAS & Devices
1

I’m having the same problem discussed here.

The TS-451 has 8gb ram installed and 4 x 8tb Seagate Ironwolf drives setup in Raid 5.

Since that thread was closed I’m starting a new one. The PMS on my QNAP is the most recent version available and each client has the latest Plex version installed. I’m a Plex Pass subscriber and have HW transcoding enabled. It’s been working great for months and months streaming HD quality mp4’s. I watched all 5 seasons of Prison Break without a hiccup. However, recently I can watch a show for 5-10 minutes and then it starts pausing and buffering. Once it starts doing this it never corrects itself. If I look at the resource monitor the CPU on the TS-451 is less than 20%. If I switch over to Kodi on the same device the exact same file plays perfectly. I’ve changed nothing in my network configuration. I’ve even rebooted the QNAP, and hardwired switches but nothing fixes the issue. I really don’t want to use Kodi, I want to use Plex. But I’m not sure what else to try. Can anyone help me please?

PS: I forgot to mention I’m only streaming on my local network. I have remote access disabled.

2

Well I figured maybe the QNAP didn’t have enough horse power despite it working for months without issue. I finally uninstalled the server on the QNAP and installed it on my Windows 10 machine with an Intel i9 processor and 64gb of ram. Exact same issue. Occasionally when it starts to buffer a message will display that my network is not fast enough or the server is not powerful enough to convert video. This is even on a client that is hard wired to the network and the same media plays fine in Kodi. I’m completely out of options at this point. Has anyone else ever had this problem and solved it? Please give me a clue…

3

Looking at the logs I see this over and over when it starts buffering.

Failed to stream media, client probably disconnected after 770048 bytes: 10054 - An existing connection was forcibly closed by the remote host

4

May I please have the DEBUG log files which capture this happening?

Please also feel free to recreate the issue –

  • play 2 minutes
  • Stop
  • wait 20 seconds
  • Download Logs ZIP file
  • Attach here please
5

Thanks @ChuckPa

I duplicated the issue for 2 minutes, stopped, waited 20 seconds and downloaded the logs. They are attached.

I have the Plex client installed on 3 new Chromecast with Google TV, 4k FireTV sticks and 2 DuneHD players. The issue happens on all devices. Some are wireless and some are hardwired to the network. I only use one at a time.

Thanks for looking at it. I hope there’s an answer. Plex Media Server Logs_2021-02-23_20-02-59.zip (6.7 MB)

6

@DarqMan

This is a Windows server. I’m sorry but I can’t help with WIndows.

Do you realize you posted in the QNAP (Linux) sub-forum (NAS & Devices) ?

Perhaps you should create a new thread and post again, with your logs because transcoding on Windows is entirely different than Linux and unfortunately I don’t understand it (don’t have a Windows machine or VM)

I did look deeper at the logs.

This is a network / player issue.

Please notice where the server Throttles waiting to catch up.

what’s not clear is if it’s waiting for the data inbound or transmitting outbound

Feb 23, 2021 16:37:04.805 [16440] ERROR - XML: status=ok\00
Feb 23, 2021 16:37:04.805 [16440] ERROR - XML: ^\00
Feb 23, 2021 16:37:04.805 [16440] ERROR - Error parsing content.
Feb 23, 2021 16:37:04.805 [16440] ERROR - Error parsing XML: Error parsing file.
Feb 23, 2021 16:37:04.805 [16440] ERROR - SSDP: Error parsing device schema for http://192.168.86.49:9080
Feb 23, 2021 16:37:05.007 [21716] DEBUG - Request: [192.168.86.40:57400 (Subnet)] GET /video/:/transcode/universal/session/7eda9a8f1cdc78dc-com-plexapp-android/base/00008.ts (21 live) TLS Signed-in
Feb 23, 2021 16:37:05.007 [21716] DEBUG - [Transcode/7eda9a8f1cdc78dc-com-plexapp-android] Asked for segment 8 from session.
Feb 23, 2021 16:37:05.007 [21716] DEBUG - [Transcode/7eda9a8f1cdc78dc-com-plexapp-android] Returning segment 8 from session
Feb 23, 2021 16:37:05.007 [21716] DEBUG - [Transcode/7eda9a8f1cdc78dc-com-plexapp-android] Throttling 8.ts for 0.841000 seconds
Feb 23, 2021 16:37:05.007 [33752] DEBUG - [Transcode/7eda9a8f1cdc78dc-com-plexapp-android] Streaming Resource: Estimated bandwidth for 7eda9a8f1cdc78dc-com-plexapp-android to be 32796kbps over 3.97s
Feb 23, 2021 16:37:05.007 [21716] DEBUG - Content-Length of C:\Users\steve\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-7eda9a8f1cdc78dc-com-plexapp-android-3a9806bf-c312-4853-9d63-e8cf22c129bc\media-00008.ts is 2293976 (of total: 2293976).
Feb 23, 2021 16:37:05.076 [21716] DEBUG - [Transcode] Transcoder segment range: 0 - 10 (10)
Feb 23, 2021 16:37:05.077 [21716] DEBUG - [Transcode] Transcoder segment range: 0 - 11 (10)
Feb 23, 2021 16:37:05.104 [28748] DEBUG - Completed: [192.168.86.40:57402] 200 GET /video/:/transcode/universal/session/7eda9a8f1cdc78dc-com-plexapp-android/base/00007.ts (20 live) TLS 818ms 2434976 bytes (pipelined: 4)
Feb 23, 2021 16:37:05.104 [28748] DEBUG - Removed transcode data consumer, active count 9 => 9
Feb 23, 2021 16:37:05.132 [16440] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.26:9080
Feb 23, 2021 16:37:05.132 [16440] DEBUG - HTTP requesting GET http://192.168.86.26:9080
Feb 23, 2021 16:37:05.183 [21716] DEBUG - Request: [192.168.86.40:57402 (Subnet)] GET /video/:/transcode/universal/session/7eda9a8f1cdc78dc-com-plexapp-android/base/00009.ts (20 live) TLS Signed-in
Feb 23, 2021 16:37:05.183 [21716] DEBUG - [Transcode/7eda9a8f1cdc78dc-com-plexapp-android] Asked for segment 9 from session.
Feb 23, 2021 16:37:05.183 [21716] DEBUG - [Transcode/7eda9a8f1cdc78dc-com-plexapp-android] Returning segment 9 from session
Feb 23, 2021 16:37:05.183 [21716] DEBUG - [Transcode/7eda9a8f1cdc78dc-com-plexapp-android] Throttling 9.ts for 3.668000 seconds
Feb 23, 2021 16:37:05.183 [33752] DEBUG - [Transcode/7eda9a8f1cdc78dc-com-plexapp-android] Streaming Resource: Estimated bandwidth for 7eda9a8f1cdc78dc-com-plexapp-android to be 35152kbps over 4.15s
Feb 23, 2021 16:37:05.183 [33752] DEBUG - [Transcode/7eda9a8f1cdc78dc-com-plexapp-android] Streaming Resource: Last segment has been delayed for 3.67 and last request was 0.00s ago, so changing to client buffered on 7eda9a8f1cdc78dc-com-plexapp-android with an estimate of 35152kbps
Feb 23, 2021 16:37:05.183 [33752] DEBUG - [Transcode/7eda9a8f1cdc78dc-com-plexapp-android] Streaming Resource: Changing client bandwidth 7eda9a8f1cdc78dc-com-plexapp-android to 20000kbps from 7910kbps
Feb 23, 2021 16:37:05.183 [33752] DEBUG - [Transcode/7eda9a8f1cdc78dc-com-plexapp-android] Found session GUID of 7eda9a8f1cdc78dc-com-plexapp-android in session start.
Feb 23, 2021 16:37:05.183 [21716] DEBUG - Content-Length of C:\Users\steve\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-7eda9a8f1cdc78dc-com-plexapp-android-3a9806bf-c312-4853-9d63-e8cf22c129bc\media-00009.ts is 2366544 (of total: 2366544).
Feb 23, 2021 16:37:05.184 [33752] DEBUG - [Transcode/7eda9a8f1cdc78dc-com-plexapp-android] TranscodeUniversalRequest: using augmented profile Android
Feb 23, 2021 16:37:05.184 [33752] DEBUG - [Transcode/7eda9a8f1cdc78dc-com-plexapp-android] Downloading document http://127.0.0.1:32400/library/metadata/7039?includeBandwidths=1&offset=-1&X-Plex-Incomplete-Segments=1&X-Plex-Session-Identifier=7eda9a8f1cdc78dc-com-plexapp-android
Feb 23, 2021 16:37:05.184 [33752] DEBUG - [Transcode/7eda9a8f1cdc78dc-com-plexapp-android] Auth: authenticated user 1 as darqman
Feb 23, 2021 16:37:05.187 [33752] DEBUG - [Transcode/7eda9a8f1cdc78dc-com-plexapp-android] We're going to try to auto-select an audio stream for account 1.
Feb 23, 2021 16:37:05.187 [33752] DEBUG - [Transcode/7eda9a8f1cdc78dc-com-plexapp-android] Selecting best audio stream for part ID 10151 (autoselect: 0 language: en)
Feb 23, 2021 16:37:05.187 [33752] DEBUG - [Transcode/7eda9a8f1cdc78dc-com-plexapp-android] Audio Stream: 21143, Subtitle Stream: -1
7

@ChuckPa I apologize! I incorrectly assumed that you had read my entire post before you requested logs.

My original post was regarding this issue happening on my QNAP. When I couldn’t resolve it, I uninstalled from the NAS and installed on Windows and I have the exact same issue.

Let me make sure I understand. My options to get help from Plex support is to re-install on the QNAP and duplicate the exact same issue and send those logs here or create a new post in the Windows sub-forum? Is that correct?

8

Essentially yes. It’s because I don’t understand windows – for which I’m sorry I don’t. I can address just about anything Linux. I write the packaging for QNAP (the support scripts / tools in the QPKG file you install)

I’m an operating systems engineer with focus on Linux.

If I had a Windows machine here at home, I’d definitely understand it better but I don’t.

I don’t want to give up but have very little idea what to look at next.

Please let me show you where I get lost. Maybe if I describe it to you, it will make sense?

In the clip below,

  1. Playback has been started.
  2. PMS has determined what it needs to do.
  3. It knows the file is at M:\TV\The People vs. O.J. Simpson\The People vs. O.J. Simpson.s01e04.100% Not Guilty.mp4
  4. It starts to transcode.
  5. Almost immediately after getting the first few blocks of data from the file,
  6. The source media goes offline. (not slow – completely disconnected/offline)
  7. The transcoder stalls waiting for it to become available again.
  8. Eventually, it does come back and processing continues.
  9. This cycle repeats throughout playback.

That looks like a network error to me.

If you have PMS on the Windows machine and still have media on the QNAP
-then-
Windows is telling transcoder that the ‘Mapped (?)’ drive is disconnected/ not ready.

Does this make any sense to you?

WiFi between the PMS machine and the QNAP? possibly overloaded or weak signal ?

You had the same thing happen when PMS was on the QNAP.

What’s common between the two?

The hard drives and QTS are common. Is a hard drive failing?

Feb 23, 2021 17:12:28.455 [17804] DEBUG - [Universal] Using local file path instead of URL: M:\TV\The People vs. O.J. Simpson\The People vs. O.J. Simpson.s01e03.The Dream Team.mp4
Feb 23, 2021 17:12:28.455 [17804] DEBUG - TPU: hardware transcoding: final decoder: , final encoder: 
Feb 23, 2021 17:12:28.456 [17804] DEBUG - [JobRunner] Job running: set "FFMPEG_EXTERNAL_LIBS=\\\\?\\C\:\\Users\\steve\\AppData\\Local\\Plex\ Media\ Server\\Codecs\\367b3d4-3673-windows-x86\\" & set "X_PLEX_TOKEN=xxxxxxxxxxxxxxxxxxxx" & "C:\Program Files (x86)\Plex\Plex Media Server\Plex Transcoder.exe" -codec:#0x02 eac3_mf -analyzeduration 20000000 -probesize 20000000 -i "M:\TV\The People vs. O.J. Simpson\The People vs. O.J. Simpson.s01e03.The Dream Team.mp4" -filter_complex "[0:#0x02] aresample=async=1:ocl='5.1':rematrix_maxval=0.000000dB:osr=48000[0]" -map [0] -codec:0 flac -b:0 4096k -f flac -map_metadata -1 -map_chapters -1 -t 1329.5574999999999 "C:\Users\steve\AppData\Local\Plex Media Server\Cache\Transcode\Detection\a74d3e5d-f0b2-47d6-8410-f4c8ab7ab8c8" -y -nostats -loglevel quiet -loglevel_plex error -progressurl http://127.0.0.1:32400/video/:/transcode/session/de1e7530-f85a-4d1e-aa10-19b5303f401d/17ca8406-a329-45af-8686-2a867c3ad1b9/progress 
Feb 23, 2021 17:12:28.459 [17804] DEBUG - [JobRunner] Jobs: Starting child process with pid 37056
Feb 23, 2021 17:12:28.489 [50232] DEBUG - Request: [127.0.0.1:51747 (Loopback)] PUT /video/:/transcode/session/de1e7530-f85a-4d1e-aa10-19b5303f401d/17ca8406-a329-45af-8686-2a867c3ad1b9/progress?status=startup (12 live) Signed-in Token (darqman) (range: bytes=0-) 
Feb 23, 2021 17:12:28.489 [28748] DEBUG - Completed: [127.0.0.1:51747] 204 PUT /video/:/transcode/session/de1e7530-f85a-4d1e-aa10-19b5303f401d/17ca8406-a329-45af-8686-2a867c3ad1b9/progress?status=startup (12 live) 0ms 203 bytes (pipelined: 1) (range: bytes=0-) 
Feb 23, 2021 17:12:28.495 [50232] DEBUG - Request: [127.0.0.1:51747 (Loopback)] PUT /video/:/transcode/session/de1e7530-f85a-4d1e-aa10-19b5303f401d/17ca8406-a329-45af-8686-2a867c3ad1b9/progress?status=startup (12 live) Signed-in Token (darqman) (range: bytes=0-) 
Feb 23, 2021 17:12:28.495 [28748] DEBUG - Completed: [127.0.0.1:51747] 204 PUT /video/:/transcode/session/de1e7530-f85a-4d1e-aa10-19b5303f401d/17ca8406-a329-45af-8686-2a867c3ad1b9/progress?status=startup (12 live) 0ms 203 bytes (pipelined: 2) (range: bytes=0-) 
Feb 23, 2021 17:12:28.495 [50232] DEBUG - Request: [127.0.0.1:51747 (Loopback)] PUT /video/:/transcode/session/de1e7530-f85a-4d1e-aa10-19b5303f401d/17ca8406-a329-45af-8686-2a867c3ad1b9/progress?status=opening (12 live) Signed-in Token (darqman) (range: bytes=0-) 
Feb 23, 2021 17:12:28.495 [28748] DEBUG - Completed: [127.0.0.1:51747] 204 PUT /video/:/transcode/session/de1e7530-f85a-4d1e-aa10-19b5303f401d/17ca8406-a329-45af-8686-2a867c3ad1b9/progress?status=opening (12 live) 0ms 203 bytes (pipelined: 3) (range: bytes=0-) 
Feb 23, 2021 17:12:28.527 [50232] DEBUG - Request: [127.0.0.1:51747 (Loopback)] PUT /video/:/transcode/session/de1e7530-f85a-4d1e-aa10-19b5303f401d/17ca8406-a329-45af-8686-2a867c3ad1b9/progress?status=opened (12 live) Signed-in Token (darqman) (range: bytes=0-) 
Feb 23, 2021 17:12:28.528 [28748] DEBUG - Completed: [127.0.0.1:51747] 204 PUT /video/:/transcode/session/de1e7530-f85a-4d1e-aa10-19b5303f401d/17ca8406-a329-45af-8686-2a867c3ad1b9/progress?status=opened (12 live) 0ms 203 bytes (pipelined: 4) (range: bytes=0-) 
Feb 23, 2021 17:12:28.528 [50232] DEBUG - Request: [127.0.0.1:51747 (Loopback)] PUT /video/:/transcode/session/de1e7530-f85a-4d1e-aa10-19b5303f401d/17ca8406-a329-45af-8686-2a867c3ad1b9/progress/stream?index=0&id=1&codec=h264&type=video (12 live) Signed-in Token (darqman) (range: bytes=0-) 
Feb 23, 2021 17:12:28.528 [36480] DEBUG - Completed: [127.0.0.1:51747] 200 PUT /video/:/transcode/session/de1e7530-f85a-4d1e-aa10-19b5303f401d/17ca8406-a329-45af-8686-2a867c3ad1b9/progress/stream?index=0&id=1&codec=h264&type=video (12 live) 0ms 195 bytes (pipelined: 5) (range: bytes=0-) 
Feb 23, 2021 17:12:28.528 [50232] DEBUG - Request: [127.0.0.1:51747 (Loopback)] PUT /video/:/transcode/session/de1e7530-f85a-4d1e-aa10-19b5303f401d/17ca8406-a329-45af-8686-2a867c3ad1b9/progress/stream?index=1&id=2&codec=eac3&type=audio (12 live) Signed-in Token (darqman) (range: bytes=0-) 
Feb 23, 2021 17:12:28.528 [28748] DEBUG - Completed: [127.0.0.1:51747] 200 PUT /video/:/transcode/session/de1e7530-f85a-4d1e-aa10-19b5303f401d/17ca8406-a329-45af-8686-2a867c3ad1b9/progress/stream?index=1&id=2&codec=eac3&type=audio (12 live) 0ms 195 bytes (pipelined: 6) (range: bytes=0-) 
Feb 23, 2021 17:12:28.555 [50232] DEBUG - Request: [127.0.0.1:51747 (Loopback)] PUT /video/:/transcode/session/de1e7530-f85a-4d1e-aa10-19b5303f401d/17ca8406-a329-45af-8686-2a867c3ad1b9/progress/streamDetail?index=0&id=1&codec=h264&type=video&bitrate=2992232&profile=High&language=und&width=1920&height=1080&interlaced=0&sar=1:1&level=40&frameRate=23.976&disp_default=1 (12 live) Signed-in Token (darqman) (range: bytes=0-) 
Feb 23, 2021 17:12:28.556 [28748] DEBUG - Completed: [127.0.0.1:51747] 200 PUT /video/:/transcode/session/de1e7530-f85a-4d1e-aa10-19b5303f401d/17ca8406-a329-45af-8686-2a867c3ad1b9/progress/streamDetail?index=0&id=1&codec=h264&type=video&bitrate=2992232&profile=High&language=und&width=1920&height=1080&interlaced=0&sar=1:1&level=40&frameRate=23.976&disp_default=1 (12 live) 0ms 195 bytes (pipelined: 7) (range: bytes=0-) 
Feb 23, 2021 17:12:28.556 [50232] DEBUG - Request: [127.0.0.1:51747 (Loopback)] PUT /video/:/transcode/session/de1e7530-f85a-4d1e-aa10-19b5303f401d/17ca8406-a329-45af-8686-2a867c3ad1b9/progress/streamDetail?index=1&id=2&codec=eac3&type=audio&bitrate=640000&language=und&channels=6&layout=5.1%28side%29&sampleRate=48000&disp_default=1 (12 live) Signed-in Token (darqman) (range: bytes=0-) 
Feb 23, 2021 17:12:28.556 [28748] DEBUG - Completed: [127.0.0.1:51747] 200 PUT /video/:/transcode/session/de1e7530-f85a-4d1e-aa10-19b5303f401d/17ca8406-a329-45af-8686-2a867c3ad1b9/progress/streamDetail?index=1&id=2&codec=eac3&type=audio&bitrate=640000&language=und&channels=6&layout=5.1%28side%29&sampleRate=48000&disp_default=1 (12 live) 0ms 195 bytes (pipelined: 8) (range: bytes=0-) 
Feb 23, 2021 17:12:28.556 [50232] DEBUG - Request: [127.0.0.1:51747 (Loopback)] PUT /video/:/transcode/session/de1e7530-f85a-4d1e-aa10-19b5303f401d/17ca8406-a329-45af-8686-2a867c3ad1b9/progress/streamDetail?index=2&id=3&codec=mov_text&type=subtitle&bitrate=106&language=eng&disp_default=1 (12 live) Signed-in Token (darqman) (range: bytes=0-) 
Feb 23, 2021 17:12:28.556 [36480] DEBUG - Completed: [127.0.0.1:51747] 200 PUT /video/:/transcode/session/de1e7530-f85a-4d1e-aa10-19b5303f401d/17ca8406-a329-45af-8686-2a867c3ad1b9/progress/streamDetail?index=2&id=3&codec=mov_text&type=subtitle&bitrate=106&language=eng&disp_default=1 (12 live) 0ms 195 bytes (pipelined: 9) (range: bytes=0-) 
Feb 23, 2021 17:12:28.559 [50232] DEBUG - Request: [127.0.0.1:51747 (Loopback)] PUT /video/:/transcode/session/de1e7530-f85a-4d1e-aa10-19b5303f401d/17ca8406-a329-45af-8686-2a867c3ad1b9/progress?duration=2659.114791 (12 live) Signed-in Token (darqman) (range: bytes=0-) 
Feb 23, 2021 17:12:28.559 [17804] DEBUG - Using default transcode duration of 120 minutes
Feb 23, 2021 17:12:28.559 [36480] DEBUG - Completed: [127.0.0.1:51747] 204 PUT /video/:/transcode/session/de1e7530-f85a-4d1e-aa10-19b5303f401d/17ca8406-a329-45af-8686-2a867c3ad1b9/progress?duration=2659.114791 (12 live) 0ms 203 bytes (pipelined: 10) (range: bytes=0-) 
Feb 23, 2021 17:12:28.559 [17804] DEBUG - Started session successfully: de1e7530-f85a-4d1e-aa10-19b5303f401d
Feb 23, 2021 17:12:28.563 [28748] DEBUG - Auth: authenticated user 1 as darqman
Feb 23, 2021 17:12:28.564 [50232] DEBUG - Request: [192.168.86.60:50868 (Subnet)] GET /status/sessions/background (12 live) TLS GZIP Signed-in Token (darqman)
Feb 23, 2021 17:12:28.564 [36480] DEBUG - Completed: [192.168.86.60:50868] 200 GET /status/sessions/background (12 live) TLS GZIP 0ms 476 bytes (pipelined: 37)
Feb 23, 2021 17:12:29.139 [49632] DEBUG - [Transcode] Streaming Resource: Unthrottling session 0xe79a078:de1e7530-f85a-4d1e-aa10-19b5303f401d which is using static transcoder slot.  Used slots is now 1
Feb 23, 2021 17:12:29.182 [28748] DEBUG - Completed: [192.168.86.60:50570] 200 GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (12 live) TLS GZIP 20006ms 5 bytes (pipelined: 29)
Feb 23, 2021 17:12:29.190 [28748] DEBUG - Auth: authenticated user 1 as darqman
Feb 23, 2021 17:12:29.190 [49632] DEBUG - Request: [192.168.86.60:50570 (Subnet)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (12 live) TLS GZIP Signed-in Token (darqman)
Feb 23, 2021 17:12:29.190 [49632] DEBUG - Content-Length is -1 (of total: -1).
Feb 23, 2021 17:12:33.646 [36480] DEBUG - Auth: authenticated user 1 as darqman
Feb 23, 2021 17:12:33.646 [50232] DEBUG - Request: [192.168.86.60:50868 (Subnet)] GET /status/sessions/background (12 live) TLS GZIP Signed-in Token (darqman)
Feb 23, 2021 17:12:33.647 [28748] DEBUG - Completed: [192.168.86.60:50868] 200 GET /status/sessions/background (12 live) TLS GZIP 0ms 476 bytes (pipelined: 38)
Feb 23, 2021 17:12:36.111 [16440] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.26:9080
Feb 23, 2021 17:12:36.111 [16440] DEBUG - HTTP requesting GET http://192.168.86.26:9080
Feb 23, 2021 17:12:36.182 [16440] DEBUG - HTTP 200 response from GET http://192.168.86.26:9080
Feb 23, 2021 17:12:36.182 [16440] ERROR - XML: Entity: line 1:\00
Feb 23, 2021 17:12:36.182 [16440] ERROR - XML: parser\00
Feb 23, 2021 17:12:36.182 [16440] ERROR - XML: error :\00
Feb 23, 2021 17:12:36.182 [16440] ERROR - XML: Start tag expected, '<' not found\00
Feb 23, 2021 17:12:36.182 [16440] ERROR - XML: status=ok\00
Feb 23, 2021 17:12:36.182 [16440] ERROR - XML: ^\00
Feb 23, 2021 17:12:36.182 [16440] ERROR - Error parsing content.
Feb 23, 2021 17:12:36.182 [16440] ERROR - Error parsing XML: Error parsing file.
Feb 23, 2021 17:12:36.182 [16440] ERROR - SSDP: Error parsing device schema for http://192.168.86.26:9080
Feb 23, 2021 17:12:38.651 [28748] DEBUG - Auth: authenticated user 1 as darqman
Feb 23, 2021 17:12:38.651 [10756] DEBUG - Request: [192.168.86.60:50868 (Subnet)] GET /status/sessions/background (12 live) TLS GZIP Signed-in Token (darqman)
Feb 23, 2021 17:12:38.652 [36480] DEBUG - Completed: [192.168.86.60:50868] 200 GET /status/sessions/background (12 live) TLS GZIP 0ms 476 bytes (pipelined: 39)
Feb 23, 2021 17:12:43.657 [36480] DEBUG - Auth: authenticated user 1 as darqman
Feb 23, 2021 17:12:43.657 [50232] DEBUG - Request: [192.168.86.60:50868 (Subnet)] GET /status/sessions/background (12 live) TLS GZIP Signed-in Token (darqman)
Feb 23, 2021 17:12:43.658 [28748] DEBUG - Completed: [192.168.86.60:50868] 200 GET /status/sessions/background (12 live) TLS GZIP 0ms 476 bytes (pipelined: 40)
Feb 23, 2021 17:12:44.423 [16440] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.244:49154/MediaRenderer/desc.xml
Feb 23, 2021 17:12:44.423 [16440] DEBUG - HTTP requesting GET http://192.168.86.244:49154/MediaRenderer/desc.xml
Feb 23, 2021 17:12:44.434 [16440] DEBUG - HTTP 200 response from GET http://192.168.86.244:49154/MediaRenderer/desc.xml
Feb 23, 2021 17:12:44.435 [16440] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.86.244:49154/MediaRenderer/desc.xml
Feb 23, 2021 17:12:44.435 [16440] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.244 (PATIO)
Feb 23, 2021 17:12:46.709 [16440] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.49:9080
Feb 23, 2021 17:12:46.709 [16440] DEBUG - HTTP requesting GET http://192.168.86.49:9080
Feb 23, 2021 17:12:46.773 [16440] DEBUG - HTTP 200 response from GET http://192.168.86.49:9080
Feb 23, 2021 17:12:46.773 [16440] ERROR - XML: Entity: line 1:\00
Feb 23, 2021 17:12:46.773 [16440] ERROR - XML: parser\00
Feb 23, 2021 17:12:46.773 [16440] ERROR - XML: error :\00
Feb 23, 2021 17:12:46.773 [16440] ERROR - XML: Start tag expected, '<' not found\00
Feb 23, 2021 17:12:46.773 [16440] ERROR - XML: status=ok\00
Feb 23, 2021 17:12:46.773 [16440] ERROR - XML: ^\00
Feb 23, 2021 17:12:46.773 [16440] ERROR - Error parsing content.
Feb 23, 2021 17:12:46.773 [16440] ERROR - Error parsing XML: Error parsing file.
Feb 23, 2021 17:12:46.773 [16440] ERROR - SSDP: Error parsing device schema for http://192.168.86.49:9080
Feb 23, 2021 17:12:48.662 [28748] DEBUG - Auth: authenticated user 1 as darqman
Feb 23, 2021 17:12:48.662 [46088] DEBUG - Request: [192.168.86.60:50868 (Subnet)] GET /status/sessions/background (12 live) TLS GZIP Signed-in Token (darqman)
Feb 23, 2021 17:12:48.662 [36480] DEBUG - Completed: [192.168.86.60:50868] 200 GET /status/sessions/background (12 live) TLS GZIP 0ms 476 bytes (pipelined: 41)
Feb 23, 2021 17:12:49.196 [28748] DEBUG - Completed: [192.168.86.60:50570] 200 GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (12 live) TLS GZIP 20005ms 5 bytes (pipelined: 30)
Feb 23, 2021 17:12:49.201 [28748] DEBUG - Auth: authenticated user 1 as darqman
Feb 23, 2021 17:12:49.201 [10756] DEBUG - Request: [192.168.86.60:50570 (Subnet)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (12 live) TLS GZIP Signed-in Token (darqman)
Feb 23, 2021 17:12:49.201 [10756] DEBUG - Content-Length is -1 (of total: -1).
Feb 23, 2021 17:12:51.328 [19172] DEBUG - Jobs: 'C:\Program Files (x86)\Plex\Plex Media Server\Plex Transcoder.exe' exit code for process 37056 is 0 (success)
Feb 23, 2021 17:12:51.329 [17804] DEBUG - Transcoder: Cleaning old transcode directories.
Feb 23, 2021 17:12:51.329 [17804] DEBUG - Transcoder: Cleaning old transcode directory: "C:\Users\steve\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-de1e7530-f85a-4d1e-aa10-19b5303f401d-17ca8406-a329-45af-8686-2a867c3ad1b9"
Feb 23, 2021 17:12:51.330 [17804] DEBUG - Whacked session de1e7530-f85a-4d1e-aa10-19b5303f401d, 0 remaining.
Feb 23, 2021 17:12:51.330 [17804] DEBUG - Cleaning directory for session de1e7530-f85a-4d1e-aa10-19b5303f401d (C:\Users\steve\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-de1e7530-f85a-4d1e-aa10-19b5303f401d-17ca8406-a329-45af-8686-2a867c3ad1b9)
Feb 23, 2021 17:12:51.330 [17804] DEBUG - Streaming Resource: Terminating session 0xe79a078:de1e7530-f85a-4d1e-aa10-19b5303f401d which is using static transcoder slot.  Used slots is now 0
Feb 23, 2021 17:12:51.330 [17804] DEBUG - Streaming Resource: Terminated session 0xe79a078:de1e7530-f85a-4d1e-aa10-19b5303f401d with reason Client stopped playback.
Feb 23, 2021 17:12:51.330 [17804] DEBUG - Streaming Resource: Removing session 0xe79a078:de1e7530-f85a-4d1e-aa10-19b5303f401d
Feb 23, 2021 17:12:51.330 [17804] DEBUG - [JobRunner] Job running: "C:\Program Files (x86)\Plex\Plex Media Server\Plex Media Fingerprinter.exe" -raw -length 1329.5574999999999 "C:\Users\steve\AppData\Local\Plex Media Server\Cache\Transcode\Detection\a74d3e5d-f0b2-47d6-8410-f4c8ab7ab8c8" 
Feb 23, 2021 17:12:51.333 [36480] DEBUG - Auth: authenticated user 1 as darqman
Feb 23, 2021 17:12:51.333 [50232] DEBUG - Request: [192.168.86.60:50868 (Subnet)] GET /status/sessions/background (12 live) TLS GZIP Signed-in Token (darqman)
Feb 23, 2021 17:12:51.333 [28748] DEBUG - Completed: [192.168.86.60:50868] 200 GET /status/sessions/background (12 live) TLS GZIP 0ms 476 bytes (pipelined: 42)
Feb 23, 2021 17:12:51.334 [17804] DEBUG - [JobRunner] Jobs: Starting child process with pid 52380
Feb 23, 2021 17:12:56.372 [17804] DEBUG - Killing job.
Feb 23, 2021 17:12:56.374 [19172] DEBUG - Jobs: 'C:\Program Files (x86)\Plex\Plex Media Server\Plex Media Fingerprinter.exe' exit code for process 52380 is -1059143458 (intentional termination)
Feb 23, 2021 17:12:56.420 [17804] DEBUG - Activity: updated activity 2cdaa3c8-061b-481a-a79c-d5ae8bf97d2c - completed 31.9% - Detecting intros
Feb 23, 2021 17:12:56.420 [17804] DEBUG - IntroDetector: Initializing for "" (7168)
Feb 23, 2021 17:12:56.422 [17804] DEBUG - Downloading document http://127.0.0.1:32400/library/metadata/7168
Feb 23, 2021 17:12:56.426 [17804] DEBUG - MDE: video has no video stream but does have an audio stream
Feb 23, 2021 17:12:56.426 [17804] DEBUG - MDE: video has no video stream but does have an audio stream
Feb 23, 2021 17:12:56.426 [17804] DEBUG - Cleaning directory for session 1ad8f868-0663-433a-a691-80aa704fd3c0 ()
Feb 23, 2021 17:12:56.426 [17804] DEBUG - Starting a transcode session 1ad8f868-0663-433a-a691-80aa704fd3c0 at offset -1.0 (state=3)
Feb 23, 2021 17:12:56.426 [17804] DEBUG - Streaming Resource: Added session 0x97c5a30:1ad8f868-0663-433a-a691-80aa704fd3c0
Feb 23, 2021 17:12:56.477 [17804] DEBUG - [Universal] Using local file path instead of URL: M:\TV\The People vs. O.J. Simpson\The People vs. O.J. Simpson.s01e04.100% Not Guilty.mp4
Feb 23, 2021 17:12:56.477 [17804] DEBUG - TPU: hardware transcoding: final decoder: , final encoder: 
Feb 23, 2021 17:12:56.478 [17804] DEBUG - [JobRunner] Job running: set "FFMPEG_EXTERNAL_LIBS=\\\\?\\C\:\\Users\\steve\\AppData\\Local\\Plex\ Media\ Server\\Codecs\\367b3d4-3673-windows-x86\\" & set "X_PLEX_TOKEN=xxxxxxxxxxxxxxxxxxxx" & "C:\Program Files (x86)\Plex\Plex Media Server\Plex Transcoder.exe" -codec:0 eac3_mf -analyzeduration 20000000 -probesize 20000000 -i "M:\TV\The People vs. O.J. Simpson\The People vs. O.J. Simpson.s01e04.100% Not Guilty.mp4" -filter_complex "[0:0] aresample=async=1:ocl='5.1':rematrix_maxval=0.000000dB:osr=48000[0]" -map [0] -codec:0 flac -b:0 4096k -f flac -map_metadata -1 -map_chapters -1 -t 600 "C:\Users\steve\AppData\Local\Plex Media Server\Cache\Transcode\Detection\92951962-8620-43b2-a379-bd9f92249186" -y -nostats -loglevel quiet -loglevel_plex error -progressurl http://127.0.0.1:32400/video/:/transcode/session/1ad8f868-0663-433a-a691-80aa704fd3c0/f744db13-3c84-4cf9-97d1-d453c8df7d53/progress 
Feb 23, 2021 17:12:56.481 [17804] DEBUG - [JobRunner] Jobs: Starting child process with pid 15220
Feb 23, 2021 17:12:56.507 [50232] DEBUG - Request: [127.0.0.1:51761 (Loopback)] PUT /video/:/transcode/session/1ad8f868-0663-433a-a691-80aa704fd3c0/f744db13-3c84-4cf9-97d1-d453c8df7d53/progress?status=startup (12 live) Signed-in Token (darqman) (range: bytes=0-) 
Feb 23, 2021 17:12:56.507 [28748] DEBUG - Completed: [127.0.0.1:51761] 204 PUT /video/:/transcode/session/1ad8f868-0663-433a-a691-80aa704fd3c0/f744db13-3c84-4cf9-97d1-d453c8df7d53/progress?status=startup (12 live) 0ms 203 bytes (pipelined: 1) (range: bytes=0-) 
Feb 23, 2021 17:12:56.512 [50232] DEBUG - Request: [127.0.0.1:51761 (Loopback)] PUT /video/:/transcode/session/1ad8f868-0663-433a-a691-80aa704fd3c0/f744db13-3c84-4cf9-97d1-d453c8df7d53/progress?status=startup (12 live) Signed-in Token (darqman) (range: bytes=0-) 
Feb 23, 2021 17:12:56.512 [28748] DEBUG - Completed: [127.0.0.1:51761] 204 PUT /video/:/transcode/session/1ad8f868-0663-433a-a691-80aa704fd3c0/f744db13-3c84-4cf9-97d1-d453c8df7d53/progress?status=startup (12 live) 0ms 203 bytes (pipelined: 2) (range: bytes=0-) 
Feb 23, 2021 17:12:56.513 [50232] DEBUG - Request: [127.0.0.1:51761 (Loopback)] PUT /video/:/transcode/session/1ad8f868-0663-433a-a691-80aa704fd3c0/f744db13-3c84-4cf9-97d1-d453c8df7d53/progress?status=opening (12 live) Signed-in Token (darqman) (range: bytes=0-) 
Feb 23, 2021 17:12:56.513 [28748] DEBUG - Completed: [127.0.0.1:51761] 204 PUT /video/:/transcode/session/1ad8f868-0663-433a-a691-80aa704fd3c0/f744db13-3c84-4cf9-97d1-d453c8df7d53/progress?status=opening (12 live) 0ms 203 bytes (pipelined: 3) (range: bytes=0-) 
Feb 23, 2021 17:12:56.519 [50232] DEBUG - Request: [127.0.0.1:51761 (Loopback)] PUT /video/:/transcode/session/1ad8f868-0663-433a-a691-80aa704fd3c0/f744db13-3c84-4cf9-97d1-d453c8df7d53/progress?status=opened (12 live) Signed-in Token (darqman) (range: bytes=0-) 
Feb 23, 2021 17:12:56.519 [28748] DEBUG - Completed: [127.0.0.1:51761] 204 PUT /video/:/transcode/session/1ad8f868-0663-433a-a691-80aa704fd3c0/f744db13-3c84-4cf9-97d1-d453c8df7d53/progress?status=opened (12 live) 0ms 203 bytes (pipelined: 4) (range: bytes=0-) 
Feb 23, 2021 17:12:56.519 [50232] DEBUG - Request: [127.0.0.1:51761 (Loopback)] PUT /video/:/transcode/session/1ad8f868-0663-433a-a691-80aa704fd3c0/f744db13-3c84-4cf9-97d1-d453c8df7d53/progress/stream?index=0&id=0&codec=eac3&type=audio (12 live) Signed-in Token (darqman) (range: bytes=0-) 
Feb 23, 2021 17:12:56.519 [28748] DEBUG - Completed: [127.0.0.1:51761] 200 PUT /video/:/transcode/session/1ad8f868-0663-433a-a691-80aa704fd3c0/f744db13-3c84-4cf9-97d1-d453c8df7d53/progress/stream?index=0&id=0&codec=eac3&type=audio (12 live) 0ms 195 bytes (pipelined: 5) (range: bytes=0-) 
Feb 23, 2021 17:12:56.522 [50232] DEBUG - Request: [127.0.0.1:51761 (Loopback)] PUT /video/:/transcode/session/1ad8f868-0663-433a-a691-80aa704fd3c0/f744db13-3c84-4cf9-97d1-d453c8df7d53/progress/streamDetail?index=0&id=0&codec=eac3&type=audio&channels=6&layout=5.1%28side%29&sampleRate=48000 (12 live) Signed-in Token (darqman) (range: bytes=0-) 
Feb 23, 2021 17:12:56.522 [28748] DEBUG - Completed: [127.0.0.1:51761] 200 PUT /video/:/transcode/session/1ad8f868-0663-433a-a691-80aa704fd3c0/f744db13-3c84-4cf9-97d1-d453c8df7d53/progress/streamDetail?index=0&id=0&codec=eac3&type=audio&channels=6&layout=5.1%28side%29&sampleRate=48000 (12 live) 0ms 195 bytes (pipelined: 6) (range: bytes=0-) 
Feb 23, 2021 17:12:56.524 [50232] DEBUG - Request: [127.0.0.1:51761 (Loopback)] PUT /video/:/transcode/session/1ad8f868-0663-433a-a691-80aa704fd3c0/f744db13-3c84-4cf9-97d1-d453c8df7d53/progress?duration=-1.000000 (12 live) Signed-in Token (darqman) (range: bytes=0-) 
Feb 23, 2021 17:12:56.524 [17804] DEBUG - Using default transcode duration of 120 minutes
Feb 23, 2021 17:12:56.524 [28748] DEBUG - Completed: [127.0.0.1:51761] 204 PUT /video/:/transcode/session/1ad8f868-0663-433a-a691-80aa704fd3c0/f744db13-3c84-4cf9-97d1-d453c8df7d53/progress?duration=-1.000000 (12 live) 0ms 203 bytes (pipelined: 7) (range: bytes=0-) 
Feb 23, 2021 17:12:56.524 [17804] DEBUG - Using default transcode duration of 120 minutes
Feb 23, 2021 17:12:56.524 [17804] DEBUG - Started session successfully: 1ad8f868-0663-433a-a691-80aa704fd3c0
Feb 23, 2021 17:12:56.529 [28748] DEBUG - Auth: authenticated user 1 as darqman
Feb 23, 2021 17:12:56.529 [50232] DEBUG - Request: [192.168.86.60:50868 (Subnet)] GET /status/sessions/background (12 live) TLS GZIP Signed-in Token (darqman)
Feb 23, 2021 17:12:56.530 [36480] DEBUG - Completed: [192.168.86.60:50868] 200 GET /status/sessions/background (12 live) TLS GZIP 0ms 476 bytes (pipelined: 43)
Feb 23, 2021 17:12:56.664 [50232] ERROR - [Transcoder] Error while decoding stream #0:0: Resource temporarily unavailable
Feb 23, 2021 17:12:56.667 [50232] ERROR - [Transcoder] Error while decoding stream #0:0: Resource temporarily unavailable
Feb 23, 2021 17:12:56.667 [50232] ERROR - [Transcoder] Error while decoding stream #0:0: Resource temporarily unavailable
Feb 23, 2021 17:12:56.667 [50232] ERROR - [Transcoder] Error while decoding stream #0:0: Resource temporarily unavailable
Feb 23, 2021 17:12:56.668 [50232] ERROR - [Transcoder] Error while decoding stream #0:0: Resource temporarily unavailable
Feb 23, 2021 17:12:56.668 [50232] ERROR - [Transcoder] Error while decoding stream #0:0: Resource temporarily unavailable
Feb 23, 2021 17:12:56.668 [50232] ERROR - [Transcoder] Error while decoding stream #0:0: Resource temporarily unavailable
Feb 23, 2021 17:12:56.668 [50232] ERROR - [Transcoder] Error while decoding stream #0:0: Resource temporarily unavailable
Feb 23, 2021 17:12:56.668 [50232] ERROR - [Transcoder] Error while decoding stream #0:0: Resource temporarily unavailable
Feb 23, 2021 17:12:56.669 [50232] ERROR - [Transcoder] Error while decoding stream #0:0: Resource temporarily unavailable
Feb 23, 2021 17:12:56.669 [50232] ERROR - [Transcoder] Error while decoding stream #0:0: Resource temporarily unavailable
Feb 23, 2021 17:12:56.669 [50232] ERROR - [Transcoder] Error while decoding stream #0:0: Resource temporarily unavailable
Feb 23, 2021 17:12:56.669 [50232] ERROR - [Transcoder] Error while decoding stream #0:0: Resource temporarily unavailable
Feb 23, 2021 17:12:56.669 [50232] ERROR - [Transcoder] Error while decoding stream #0:0: Resource temporarily unavailable
Feb 23, 2021 17:12:56.669 [50232] ERROR - [Transcoder] Error while decoding stream #0:0: Resource temporarily unavailable
Feb 23, 2021 17:12:56.669 [50232] ERROR - [Transcoder] Error while decoding stream #0:0: Resource temporarily unavailable
Feb 23, 2021 17:12:56.670 [50232] ERROR - [Transcoder] Error while decoding stream #0:0: Resource temporarily unavailable
Feb 23, 2021 17:12:56.670 [50232] ERROR - [Transcoder] Error while decoding stream #0:0: Resource temporarily unavailable
Feb 23, 2021 17:12:56.670 [50232] ERROR - [Transcoder] Error while decoding stream #0:0: Resource temporarily unavailable
Feb 23, 2021 17:12:56.670 [50232] ERROR - [Transcoder] Error while decoding stream #0:0: Resource temporarily unavailable
Feb 23, 2021 17:12:56.670 [50232] ERROR - [Transcoder] Error while decoding stream #0:0: Resource temporarily unavailable
Feb 23, 2021 17:12:56.670 [50232] ERROR - [Transcoder] Error while decoding stream #0:0: Resource temporarily unavailable
Feb 23, 2021 17:12:56.670 [50232] ERROR - [Transcoder] Error while decoding stream #0:0: Resource temporarily unavailable
Feb 23, 2021 17:12:56.670 [50232] ERROR - [Transcoder] Error while decoding stream #0:0: Resource temporarily unavailable
Feb 23, 2021 17:12:56.670 [50232] ERROR - [Transcoder] Error while decoding stream #0:0: Resource temporarily unavailable
Feb 23, 2021 17:12:56.671 [50232] ERROR - [Transcoder] Error while decoding stream #0:0: Resource temporarily unavailable
Feb 23, 2021 17:12:56.671 [50232] ERROR - [Transcoder] Error while decoding stream #0:0: Resource temporarily unavailable
Feb 23, 2021 17:12:56.671 [50232] ERROR - [Transcoder] Error while decoding stream #0:0: Resource temporarily unavailable
Feb 23, 2021 17:12:56.671 [50232] ERROR - [Transcoder] Error while decoding stream #0:0: Resource temporarily unavailable
Feb 23, 2021 17:12:56.671 [50232] ERROR - [Transcoder] Error while decoding stream #0:0: Resource temporarily unavailable
Feb 23, 2021 17:12:56.671 [50232] ERROR - [Transcoder] Error while decoding stream #0:0: Resource temporarily unavailable
Feb 23, 2021 17:12:56.671 [50232] ERROR - [Transcoder] Error while decoding stream #0:0: Resource temporarily unavailable
Feb 23, 2021 17:12:56.671 [50232] ERROR - [Transcoder] Error while decoding stream #0:0: Resource temporarily unavailable
Feb 23, 2021 17:12:56.671 [50232] ERROR - [Transcoder] Error while decoding stream #0:0: Resource temporarily unavailable
Feb 23, 2021 17:12:56.671 [50232] ERROR - [Transcoder] Error while decoding stream #0:0: Resource temporarily unavailable
Feb 23, 2021 17:12:56.671 [50232] ERROR - [Transcoder] Error while decoding stream #0:0: Resource temporarily unavailable
Feb 23, 2021 17:12:56.672 [50232] ERROR - [Transcoder] Error while decoding stream #0:0: Resource temporarily unavailable
Feb 23, 2021 17:12:56.672 [50232] ERROR - [Transcoder] Error while decoding stream #0:0: Resource temporarily unavailable
Feb 23, 2021 17:12:56.672 [50232] ERROR - [Transcoder] Error while decoding stream #0:0: Resource temporarily unavailable
Feb 23, 2021 17:12:56.672 [50232] ERROR - [Transcoder] Error while decoding stream #0:0: Resource temporarily unavailable
Feb 23, 2021 17:12:56.672 [50232] ERROR - [Transcoder] Error while decoding stream #0:0: Resource temporarily unavailable
9

Well I discovered PlexKodiConnect which allows me to play my Plex library on Kodi. Since using this I have no problems playing media in Kodi and can still use Plex to manage my library. You guys really need to do some work on your player. It’s pretty lame.

10

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