Many Bugs. Slow Plex Menu's, laggy playback

Plex Media Server Desktops & Laptops
, , ,
21 
Aug 21, 2019 01:03:30.262 [0x7f4b557fa700] INFO - Network Service: Abandoning browse socket, it was closed.
Aug 21, 2019 01:03:30.262 [0x7f4b557fa700] ERROR - Network Service: Error in browser handle read: 125 (Operation canceled) socket=-1
Aug 21, 2019 01:03:30.362 [0x7f4b557fa700] INFO - Network Service: Abandoning browse socket, it was closed.
Aug 21, 2019 01:03:31.586 [0x7f4af0ff9700] ERROR - Error issuing curl_easy_perform(handle): 28
Aug 21, 2019 01:03:31.586 [0x7f4af1ffb700] ERROR - Error issuing curl_easy_perform(handle): 28
Aug 21, 2019 01:03:31.586 [0x7f4af37fe700] ERROR - Error issuing curl_easy_perform(handle): 28
Aug 21, 2019 01:03:31.586 [0x7f4af17fa700] ERROR - Error issuing curl_easy_perform(handle): 28
Aug 21, 2019 01:03:32.162 [0x7f4b557fa700] ERROR - Network Service: Error in browser handle read: 125 (Operation canceled) socket=-1
Aug 21, 2019 01:03:32.262 [0x7f4b557fa700] INFO - Network Service: Abandoning browse socket, it was closed.
Aug 21, 2019 01:03:32.262 [0x7f4b557fa700] ERROR - Network Service: Error in browser handle read: 125 (Operation canceled) socket=-1
Aug 21, 2019 01:03:32.362 [0x7f4b557fa700] INFO - Network Service: Abandoning browse socket, it was closed.
Aug 21, 2019 01:03:33.084 [0x7f4b56ffd700] INFO - LibraryUpdateManager path watching is disabled

How can I diagnose and fix this?
I have ZERO idea what it’s trying to do.

I’m good at my job but you make it impossible by your failure to cooperate.

22

FYI, should you choose Emby, you’re be uploading those posters again anyway so the point is moot

23

I told you, ok. I enable it.
But seriously… you told me 2 weeks ago to DISABLE DEBUG AND VERBOSE logs :wink:

PLUS i posted ONE DOZEND logs in the past 2 weeks … !

24

of course! IM PISSED ! YES ! for good!

25

Disable VERBOSE.
Keep DEBUG on.

With that settled, let’s find the problem. We can both calm down :slight_smile:

26

is on now.
PMS restarted (after crash) now everything is working again. question is for how long … it’s 2am here now. I’m done. ruined again my evening. i don’t have netflix :wink: … thats why. sorry for my tone
seriously, i want to love plex. i really do. and i did. i use it for more than 8 years now. where there was nothing more but a mac-os-server. no webui, no nothing…
but it keeps getting worse and worse for me, the last years 2 years or so… in a way i can’t even tell you.
I’ll post back logs

27

Plex Media Server Logs_2019-08-21_13-32-09.zip (4.4 MB)
13:30-13:32 — 5 items opened, two took around 10-20sec (just the item in library on androidTV, no playback).

hit database refresh (second time in 10 hours) , closed+reopened AndroidTV -> everything super fast again (20 items tried, constantly load in under 1s) .Plex Media Server Logs_2019-08-21_13-36-07.zip (4.5 MB)

5mins later, one item opened (no playback), 10-20sec loading
Plex Media Server Logs_2019-08-21_13-40-31.zip (4.5 MB)

the whole time server-CPU is almost sleeping at 10-15% (no spikes at all in netdata)

Another thought…
Is there anything heavy going on with “/usr/lib/plexmediaserver/Plex Media Scanner” ? Because this still sits on my way to active HDD, and is not yet symlinked to SSD

28

May I see the XML for this?

/mnt/media/Series/The Orville (2017) [328487]/Season 02/The Orville - S02E02 - Urtriebe/The Orville - S02E02 - Urtriebe tvs-orville-dd51-ded-dl-18p-azhd-avc-202.mkv

I am trying to understand why the i7-7700 CPU (which has QSV hw transcoding) is not active.

Urtriebe/The Orville - S02E02 - Urtriebe tvs-orville-dd51-ded-dl-18p-azhd-avc-202.mkv
Aug 21, 2019 13:00:50.441 [0x7f2b78ff9700] DEBUG - TPU: hardware transcoding: final decoder: , final encoder: 
Aug 21, 2019 13:00:50.441 [0x7f2b78ff9700] DEBUG - Job running: EAE_ROOT='/tmp/pms-5824e5d4-f704-4314-942a-b528010a5211/EasyAudioEncoder' FFMPEG_EXTERNAL_LIBS='/var/lib/plexmediaserver/Library/Application\ Support/Plex\ Media\ Server/Codecs/dd95667-2434-linux-x86_64/' XDG_CACHE_HOME='/var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Cache' XDG_DATA_HOME='/usr/lib/plexmediaserver/Resources' X_PLEX_TOKEN='xxxxxxxxxxxxxxxxxxxx' '/usr/lib/plexmediaserver/Plex Transcoder' '-codec:0' 'h264' '-codec:1' 'ac3' '-noaccurate_seek' '-analyzeduration' '20000000' '-probesize' '20000000' '-i' '/mnt/media/Series/The Orville (2017) [328487]/Season 02/The Orville - S02E02 - Urtriebe/The Orville - S02E02 - Urtriebe tvs-orville-dd51-ded-dl-18p-azhd-avc-202.mkv' '-filter_complex' '[0:1] aresample=async=1:ocl='\''stereo'\'':osr=48000[0]' '-map' '0:0' '-metadata:s:0' 'language=eng' '-codec:0' 'copy' '-map' '[0]' '-metadata:s:1' 'language=ger' '-codec:1' 'aac' '-b:1' '256k' '-f' 'dash' '-min_seg_duration' '5000000' '-skip_to_segment' '1' '-time_delta' '0.0625' '-manifest_name' 'http://127.0.0.1:32400/video/:/transcode/session/zsdoeqiz9wglx4saej065suu/d0c068c3-e1b9-4640-b93b-efe07c79e0c0/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/zsdoeqiz9wglx4saej065suu/d0c068c3-e1b9-4640-b93b-efe07c79e0c0/progress'
29

this is a special thing with my (very popular) server-provider, as described here

i followed all the steps to make sure it’s working. Plex shows “transcode (hw)”.

snikay@cloudBox:~$ sudo lspci -v -s $(lspci | grep VGA | cut -d" " -f 1)


00:02.0 VGA compatible controller: Intel Corporation HD Graphics 630 (rev 04) (prog-if 00 [VGA controller])
	Subsystem: Fujitsu Technology Solutions HD Graphics 630
	Flags: bus master, fast devsel, latency 0, IRQ 134
	Memory at ee000000 (64-bit, non-prefetchable) [size=16M]
	Memory at d0000000 (64-bit, prefetchable) [size=256M]
	I/O ports at f000 [size=64]
	[virtual] Expansion ROM at 000c0000 [disabled] [size=128K]
	Capabilities: [40] Vendor Specific Information: Len=0c <?>
	Capabilities: [70] Express Root Complex Integrated Endpoint, MSI 00
	Capabilities: [ac] MSI: Enable+ Count=1/1 Maskable- 64bit-
	Capabilities: [d0] Power Management version 2
	Capabilities: [100] Process Address Space ID (PASID)
	Capabilities: [200] Address Translation Service (ATS)
	Capabilities: [300] Page Request Interface (PRI)
	Kernel driver in use: i915
	Kernel modules: i915


snikay@cloudBox:~$ ls -la /dev/dri
total 0
drwxr-xr-x  3 root root       100 Aug 21 07:02 .
drwxr-xr-x 20 root root      4200 Aug 21 07:02 ..
drwxr-xr-x  2 root root        80 Aug 21 07:02 by-path
crw-rw----  1 root video 226,   0 Aug 21 07:02 card0

Tutorial shows Folder “renderD128” - but is not existing …?

snikay@cloudBox:~$ intel_gpu_top
                   render busy:   0%:                                        render space: 0/16384

                          task  percent busy
                            CS:   0%:                         vert fetch: 0 (0/sec)
                           GAM:   0%:                         prim fetch: 0 (0/sec)
                            VS:   0%:                      VS invocations: 0 (0/sec)
                            CL:   0%:                      GS invocations: 0 (0/sec)
                            SF:   0%:                           GS prims: 0 (0/sec)
                                                           CL invocations: 0 (0/sec)
                                                                CL prims: 0 (0/sec)
                                                           PS invocations: 0 (0/sec)
                                                           PS depth pass: 0 (0/sec)

just had a huge playback lag again on roku (very small file, 400mbps connection), but was fine till that.
Plex Media Server Logs_2019-08-21_20-31-19.zip (3.5 MB)

30

I see it now. Thank you for the logs.

Aug 21, 2019 20:11:01.210 [0x7fc95f7fe700] DEBUG - Asked for segment 469 from session.
Aug 21, 2019 20:11:01.210 [0x7fc95f7fe700] DEBUG - Returning segment 469 from session
Aug 21, 2019 20:11:01.210 [0x7fc95f7fe700] DEBUG - Content-Length of /var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Cache/Transcode/Sessions/plex-transcode-69e83236-93e4-4bf8-9f87-8b9c369ed669-922-c76b066c-ae2d-4e5d-a6c3-664be9d46286/media-00469.ts is 1395524.
Aug 21, 2019 20:11:01.326 [0x7fc9977fe700] DEBUG - Request: [34.248.59.52:57458 (WAN)] GET /identity (37 live) TLS Signed-in Token (snickers)
Aug 21, 2019 20:11:01.327 [0x7fc9dffff700] DEBUG - Completed: [34.248.59.52:57458] 200 GET /identity (37 live) TLS 0ms 398 bytes (pipelined: 1)
Aug 21, 2019 20:11:01.402 [0x7fc9e4e33700] DEBUG - EventSource: Got event [data] '<Message address="95.216.29.32" port="32400" asyncIdentifier="3a43bcb8-4abf-452f-86b0-5839e2a64019" connectivity="1" command="notifyConnectivity"/>'
Aug 21, 2019 20:11:01.402 [0x7fc9e4e33700] DEBUG - PubSub: Got notified of reachability for async identifier 3a43bcb8-4abf-452f-86b0-5839e2a64019: 1 for 95.216.29.32:32400 (responded in 0 seconds)
Aug 21, 2019 20:11:01.454 [0x7fc9e4e33700] DEBUG - Completed: [77.22.229.21:32857] 200 GET /video/:/transcode/universal/session/69e83236-93e4-4bf8-9f87-8b9c369ed669-922/base/00469.ts?protocol=hls&mediaIndex=0&waitForSegments=1 (37 live) TLS 244ms 346948 bytes (pipelined: 1) (range: bytes=1048576-1395523) 
Aug 21, 2019 20:11:01.454 [0x7fc9e4e33700] DEBUG - Removed transcode data consumer, active count 10 => 9
Aug 21, 2019 20:11:01.466 [0x7fc9dffff700] DEBUG - Auth: authenticated user 1 as snickers
Aug 21, 2019 20:11:01.467 [0x7fc93d7fa700] DEBUG - Request: [77.22.229.21:56890 (WAN)] GET /myplex/account (37 live) TLS GZIP Signed-in Token (snickers)
Aug 21, 2019 20:11:01.467 [0x7fc9dffff700] DEBUG - Completed: [77.22.229.21:56890] 200 GET /myplex/account (37 live) TLS GZIP 0ms 2434 bytes (pipelined: 2477)
Aug 21, 2019 20:11:01.514 [0x7fc9dffff700] DEBUG - Completed: [77.22.229.21:32856] 200 GET /video/:/transcode/universal/session/69e83236-93e4-4bf8-9f87-8b9c369ed669-922/base/00469.ts?protocol=hls&mediaIndex=0&waitForSegments=1 (37 live) TLS 434ms 609092 bytes (pipelined: 1) (range: bytes=786432-1048575) 
Aug 21, 2019 20:11:01.514 [0x7fc9dffff700] DEBUG - Removed transcode data consumer, active count 9 => 8
Aug 21, 2019 20:11:01.583 [0x7fc9e4e33700] DEBUG - Auth: authenticated user 1 as snickers
Aug 21, 2019 20:11:01.583 [0x7fc93ffff700] DEBUG - Request: [77.22.229.21:58847 (WAN)] GET /myplex/account (38 live) TLS GZIP Signed-in Token (snickers)
Aug 21, 2019 20:11:01.583 [0x7fc9e4e33700] DEBUG - Completed: [77.22.229.21:58847] 200 GET /myplex/account (38 live) TLS GZIP 0ms 2355 bytes (pipelined: 1)
Aug 21, 2019 20:11:01.756 [0x7fc9dffff700] DEBUG - Completed: [77.22.229.21:32855] 200 GET /video/:/transcode/universal/session/69e83236-93e4-4bf8-9f87-8b9c369ed669-922/base/00469.ts?protocol=hls&mediaIndex=0&waitForSegments=1 (37 live) TLS 730ms 871236 bytes (pipelined: 1) (range: bytes=524288-786431) 
Aug 21, 2019 20:11:01.756 [0x7fc9dffff700] DEBUG - Removed transcode data consumer, active count 8 => 7
Aug 21, 2019 20:11:01.769 [0x7fc9e4e33700] DEBUG - Failed to stream media, client probably disconnected after 1277952 bytes: 104 - Connection reset by peer
Aug 21, 2019 20:11:01.769 [0x7fc9e4e33700] DEBUG - Completed: [77.22.229.21:32853] 200 GET /video/:/transcode/universal/session/69e83236-93e4-4bf8-9f87-8b9c369ed669-922/base/00469.ts?protocol=hls&mediaIndex=0&waitForSegments=1 (35 live) TLS 906ms 1277952 bytes (pipelined: 2) (range: bytes=0-262143) 
Aug 21, 2019 20:11:01.769 [0x7fc9dffff700] DEBUG - Failed to stream media, client probably disconnected after 983040 bytes: 104 - Connection reset by peer
Aug 21, 2019 20:11:01.769 [0x7fc9e4e33700] DEBUG - Removed transcode data consumer, active count 7 => 6
Aug 21, 2019 20:11:01.769 [0x7fc9dffff700] DEBUG - Completed: [77.22.229.21:32854] 200 GET /video/:/transcode/universal/session/69e83236-93e4-4bf8-9f87-8b9c369ed669-922/base/00469.ts?protocol=hls&mediaIndex=0&waitForSegments=1 (34 live) TLS 769ms 983040 bytes (pipelined: 1) (range: bytes=262144-524287) 
Aug 21, 2019 20:11:01.770 [0x7fc9dffff700] DEBUG - Removed transcode data consumer, active count 6 => 5
Aug 21, 2019 20:11:01.770 [0x7fc9e4e33700] DEBUG - Auth: authenticated user 1039230 as koenig_g
Aug 21, 2019 20:11:01.770 [0x7fc95e7fc700] DEBUG - Request: [77.22.229.21:32857 (WAN)] GET /video/:/transcode/universal/session/69e83236-93e4-4bf8-9f87-8b9c369ed669-922/base/00470.ts?protocol=hls&mediaIndex=0&waitForSegments=1 (32 live) TLS Signed-in Token (koenig_g)
Aug 21, 2019 20:11:01.770 [0x7fc95e7fc700] DEBUG - Asked for segment 470 from session.
Aug 21, 2019 20:11:01.770 [0x7fc95e7fc700] DEBUG - Returning segment 470 from session
Aug 21, 2019 20:11:01.770 [0x7fc95e7fc700] DEBUG - Content-Length of /var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Cache/Transcode/Sessions/plex-transcode-69e83236-93e4-4bf8-9f87-8b9c369ed669-922-c76b066c-ae2d-4e5d-a6c3-664be9d46286/media-00470.ts is 1308104.
Aug 21, 2019 20:11:01.925 [0x7fc9e4e33700] DEBUG - Auth: authenticated user 1039230 as koenig_g
Aug 21, 2019 20:11:01.925 [0x7fc95f7fe700] DEBUG - Request: [77.22.229.21:32858 (WAN)] GET /video/:/transcode/universal/session/69e83236-93e4-4bf8-9f87-8b9c369ed669-922/base/00470.ts?protocol=hls&mediaIndex=0&waitForSegments=1 (35 live) TLS Signed-in Token (koenig_g)
Aug 21, 2019 20:11:01.925 [0x7fc95f7fe700] DEBUG - Asked for segment 470 from session.
Aug 21, 2019 20:11:01.925 [0x7fc95f7fe700] DEBUG - Returning segment 470 from session
Aug 21, 2019 20:11:01.925 [0x7fc95f7fe700] DEBUG - Content-Length of /var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Cache/Transcode/Sessions/plex-transcode-69e83236-93e4-4bf8-9f87-8b9c369ed669-922-c76b066c-ae2d-4e5d-a6c3-664be9d46286/media-00470.ts is 1308104.
Aug 21, 2019 20:11:01.945 [0x7fc9e4e33700] DEBUG - Auth: authenticated user 1039230 as koenig_g
Aug 21, 2019 20:11:01.946 [0x7fc905ffb700] DEBUG - Request: [77.22.229.21:32859 (WAN)] GET /video/:/transcode/universal/session/69e83236-93e4-4bf8-9f87-8b9c369ed669-922/base/00470.ts?protocol=hls&mediaIndex=0&waitForSegments=1 (35 live) TLS Signed-in Token (koenig_g)
Aug 21, 2019 20:11:01.946 [0x7fc905ffb700] DEBUG - Asked for segment 470 from session.
Aug 21, 2019 20:11:01.946 [0x7fc905ffb700] DEBUG - Returning segment 470 from session
Aug 21, 2019 20:11:01.946 [0x7fc905ffb700] DEBUG - Content-Length of /var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Cache/Transcode/Sessions/plex-transcode-69e83236-93e4-4bf8-9f87-8b9c369ed669-922-c76b066c-ae2d-4e5d-a6c3-664be9d46286/media-00470.ts is 1308104.
Aug 21, 2019 20:11:02.003 [0x7fc9dffff700] DEBUG - Auth: authenticated user 1039230 as koenig_g
Aug 21, 2019 20:11:02.003 [0x7fc9977fe700] DEBUG - Request: [77.22.229.21:32860 (WAN)] GET /video/:/transcode/universal/session/69e83236-93e4-4bf8-9f87-8b9c369ed669-922/base/00470.ts?protocol=hls&mediaIndex=0&waitForSegments=1 (36 live) TLS Signed-in Token (koenig_g)
Aug 21, 2019 20:11:02.004 [0x7fc9977fe700] DEBUG - Asked for segment 470 from session.
Aug 21, 2019 20:11:02.004 [0x7fc9977fe700] DEBUG - Returning segment 470 from session
Aug 21, 2019 20:11:02.004 [0x7fc9977fe700] DEBUG - Content-Length of /var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Cache/Transcode/Sessions/plex-transcode-69e83236-93e4-4bf8-9f87-8b9c369ed669-922-c76b066c-ae2d-4e5d-a6c3-664be9d46286/media-00470.ts is 1308104.
Aug 21, 2019 20:11:02.071 [0x7fc9dffff700] DEBUG - Auth: authenticated user 1039230 as koenig_g
Aug 21, 2019 20:11:02.071 [0x7fc93d7fa700] DEBUG - Request: [77.22.229.21:32861 (WAN)] GET /video/:/transcode/universal/session/69e83236-93e4-4bf8-9f87-8b9c369ed669-922/base/00470.ts?protocol=hls&mediaIndex=0&waitForSegments=1 (36 live) TLS Signed-in Token (koenig_g)
Aug 21, 2019 20:11:02.071 [0x7fc93d7fa700] DEBUG - Asked for segment 470 from session.
Aug 21, 2019 20:11:02.071 [0x7fc93d7fa700] DEBUG - Returning segment 470 from session
Aug 21, 2019 20:11:02.071 [0x7fc93d7fa700] DEBUG - Content-Length of /var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Cache/Transcode/Sessions/plex-transcode-69e83236-93e4-4bf8-9f87-8b9c369ed669-922-c76b066c-ae2d-4e5d-a6c3-664be9d46286/media-00470.ts is 1308104.
Aug 21, 2019 20:11:02.132 [0x7fc9dffff700] DEBUG - Completed: [77.22.229.21:32861] 200 GET /video/:/transcode/universal/session/69e83236-93e4-4bf8-9f87-8b9c369ed669-922/base/00470.ts?protocol=hls&mediaIndex=0&waitForSegments=1 (36 live) TLS 60ms 128456 bytes (pipelined: 1) (range: bytes=1179648-1308103) 
Aug 21, 2019 20:11:02.132 [0x7fc9dffff700] DEBUG - Removed transcode data consumer, active count 10 => 9
Aug 21, 2019 20:11:02.333 [0x7fc9e4e33700] DEBUG - Auth: authenticated user 1 as snickers
Aug 21, 2019 20:11:02.333 [0x7fc904ff9700] DEBUG - Request: [77.22.229.21:56890 (WAN)] GET /statistics/bandwidth?timespan=6 (36 live) TLS GZIP Signed-in Token (snickers)
Aug 21, 2019 20:11:02.338 [0x7fc9e4e33700] DEBUG - Completed: [77.22.229.21:56890] 200 GET /statistics/bandwidth?timespan=6 (36 live) TLS GZIP 5ms 4455 bytes (pipelined: 2478)
Aug 21, 2019 20:11:02.356 [0x7fc9dffff700] DEBUG - Completed: [77.22.229.21:32860] 200 GET /video/:/transcode/universal/session/69e83236-93e4-4bf8-9f87-8b9c369ed669-922/base/00470.ts?protocol=hls&mediaIndex=0&waitForSegments=1 (36 live) TLS 352ms 423368 bytes (pipelined: 1) (range: bytes=884736-1179647) 
Aug 21, 2019 20:11:02.356 [0x7fc9dffff700] DEBUG - Removed transcode data consumer, active count 9 => 8
Aug 21, 2019 20:11:02.480 [0x7fc9dffff700] DEBUG - Completed: [77.22.229.21:32857] 200 GET /video/:/transcode/universal/session/69e83236-93e4-4bf8-9f87-8b9c369ed669-922/base/00470.ts?protocol=hls&mediaIndex=0&waitForSegments=1 (36 live) TLS 710ms 1308104 bytes (pipelined: 2) (range: bytes=0-294911) 
Aug 21, 2019 20:11:02.481 [0x7fc9dffff700] DEBUG - Removed transcode data consumer, active count 8 => 7
Aug 21, 2019 20:11:02.486 [0x7fc9e4e33700] DEBUG - Failed to stream media, client probably disconnected after 671744 bytes: 104 - Connection reset by peer
Aug 21, 2019 20:11:02.486 [0x7fc9dffff700] DEBUG - Failed to stream media, client probably disconnected after 671744 bytes: 104 - Connection reset by peer
Aug 21, 2019 20:11:02.486 [0x7fc9e4e33700] DEBUG - Completed: [77.22.229.21:32858] 200 GET /video/:/transcode/universal/session/69e83236-93e4-4bf8-9f87-8b9c369ed669-922/base/00470.ts?protocol=hls&mediaIndex=0&waitForSegments=1 (36 live) TLS 561ms 671744 bytes (pipelined: 1) (range: bytes=294912-589823)

The player disconnected. (Connection reset by peer).

How is your home LAN setup ? Your outside connection ISP rates are not important.

Regarding Plex saying you have (hw), this is not possible. Your logs state to the contrary.

31

okay. thank you very much for this hint.
I found another tutorial, and by following that steps, i got a
renderD128 in /dev/dri this time.

So i’m pretty confident it’s working now.
Altough i would have tried to fix it before, if (hw) wasn’t showing up in Plex.

So hopefully this speeds up the whole thing. I’ll let you know.

32

To verify you have HW transcoding working is a very simple step.

  1. Start playback,
  2. Observe the status indicating (hw)
  3. Stop Playback
  4. Download logs
  5. Open Plex Media Server.log in the text editor
  6. Search for “TPU”.
  7. You will see vaapi listed if Intel QSV is being used (/dev/dri/renderD128)
33

Thank you.

Aug 22, 2019 15:29:43.621 [0x7fc02ffff700] DEBUG - Job running: EAE_ROOT=‘/tmp/pms-c47438bf-dae8-4cc5-8ffc-6d49ed4cce92/EasyAudioEncoder’ FFMPEG_EXTERNAL_LIBS=‘/var/lib/plexmediaserver/Library/Application\ Support/Plex\ Media\ Server/Codecs/dd95667-2434-linux-x86_64/’ XDG_CACHE_HOME=‘/var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Cache’ XDG_DATA_HOME=‘/usr/lib/plexmediaserver/Resources’ X_PLEX_TOKEN=‘xxxxxxxxxxxxxxxxxxxx’ ‘/usr/lib/plexmediaserver/Plex Transcoder’ ‘-codec:0’ ‘h264’ ‘-hwaccel:0’ ‘vaapi’ ‘-hwaccel_fallback_threshold:0’ ‘10’ ‘-hwaccel_output_format:0’ ‘vaapi’ ‘-codec:1’ ‘dca’ ‘-ss’ ‘273’ ‘-analyzeduration’ ‘20000000’ ‘-probesize’ ‘20000000’ ‘-i’ ‘/mnt/media/Movies/Naturlich blond (2001)/Naturlich blond (2001) - Natuerlich.blond.2001.German.DTS.DL.1080p.BluRay.x264-RSG - rsg-legallyblonde-1080p.mkv’ ‘-filter_complex’ ‘[0:0]hwupload[0];[0]scale_vaapi=w=720:h=306:format=nv12[1];[1]hwupload[2]’ ‘-filter_complex’ ‘[0:1] aresample=async=1:ocl=’'‘stereo’'‘:osr=48000[3]’ ‘-map’ ‘[2]’ ‘-metadata:s:0’ ‘language=eng’ ‘-codec:0’ ‘h264_vaapi’ ‘-b:0’ ‘1120k’ ‘-maxrate:0’ ‘1494k’ ‘-bufsize:0’ ‘2988k’ ‘-r:0’ ‘23.975999999999999’ ‘-map’ ‘[3]’ ‘-metadata:s:1’ ‘language=ger’ ‘-codec:1’ ‘libopus’ ‘-b:1’ ‘203k’ ‘-map’ ‘0:3’ ‘-metadata:s:2’ ‘language=ger’ ‘-codec:2’ ‘ass’ ‘-map’ ‘0:t?’ ‘-codec:t’ ‘copy’ ‘-f’ ‘segment’ ‘-segment_format’ ‘matroska’ ‘-segment_format_options’ ‘live=1’ ‘-segment_time’ ‘1’ ‘-segment_header_filename’ ‘header’ ‘-segment_start_number’ ‘0’ ‘-segment_list’ ‘http://127.0.0.1:32400/video/:/transcode/session/dtnvxc2zyksv7f53jquwvlt4/21171061-f0e7-4744-90da-52005c9f19b2/seglist’ ‘-segment_list_type’ ‘csv’ ‘-segment_list_unfinished’ ‘1’ ‘-segment_list_size’ ‘2147483647’ ‘-segment_list_separate_stream_times’ ‘1’ ‘-avoid_negative_ts’ ‘disabled’ ‘-map_metadata:g’ ‘-1’ ‘-map_metadata:c’ ‘-1’ ‘-map_chapters’ ‘-1’ ‘chunk-%05d’ ‘-start_at_zero’ ‘-copyts’ ‘-y’ ‘-vaapi_device’ ‘/dev/dri/renderD128’ ‘-nostats’ ‘-loglevel’ ‘quiet’ ‘-loglevel_plex’ ‘error’ ‘-progressurl’ ‘http://127.0.0.1:32400/video/:/transcode/session/dtnvxc2zyksv7f53jquwvlt4/21171061-f0e7-4744-90da-52005c9f19b2/progress’

'-vaapi_device' '/dev/dri/renderD128'
all good.

I’ll let you know if this was the reason for slow menu’s as well.
Altough i think it’s already better with daily database optimizations.
Btw. What about automatic optimization based on the amount added/removed ? Is once a week really optimal for any user?

34

The menu speed is not related to VAAPI. The size of the database (number of items in a library section to load into the GUI) is directly related.

VAAPI will have direct, and immediately visible, impact on playback start times.

35

Still the same Problems. Roku and AndroidTV playback crashes

  • Menu loading can take up to minutes till crash (tried to open 20 items on android (no playback), only loading … none came up … closed Plex(android), opened up again -> works …

Plex Media Server Logs_2019-08-23_10-15-13.zip (3.9 MB)

  • Clients very often has “indirect connection” … Closed, reopened -> Works (direct connection) … I guess this why it’s maxing out at 2mbps sometimes. for some reason, Clients thinks there is no direct connection possible. Altough PMS is accessable with direct-IP

  • Posters are still not matched always … have to click multiple times “refresh metadata”

while all of that, the only application maxing out my Servers CPU is Plex itself (photo transcoder)

So yeah, it’s pretty much everything still the same

36

okay, not the same. There is a new bug on the block.
From todays addings, 3 items, each recognized as 56 times ?! There is only one … not 56… (folder checked of course)

21
211126×444 95.9 KB

another item, Plex got it like 112 (56 x 2) .. still only one in folder-structure. obviously all the same path/name.

26
26976×512 104 KB

Should i make like 20 different bug-reports/topics or do we want to discuss everything here further? :wink:

39

So now the roku has problems too?

I’m a server/OS (mostly OS) guy.
I can’t help with Players.

40

yeah, the second i realized playing 4K on MacOS is just playig fine, same access-point, i figured it must be roku related, and deleted - so we can discuss the whole bunch of server related problems :wink:
i mean i have every problem you can dream of. so fixing my system should fix a lot others too :wink:

first thing to fix is database optimization by items added, not on weekly basis
one is adding 1TB per week, others per Year …
daily cron for this already has things speed up (menu lags)

41

got it. too many…
i’ll split each to different threads.

anyways, can’t say it enough, daily database optimizations fixed the whole “slow menu”-thing.
3 days now without any menu-freeze/slow load
even though you are not responding to this, i hope you make your conclusion.

and thank you again for your help with quick-sync … helped already speed up things. But Plex should give a clear output on itself, whether or not the quick-sync is fully working. Just like you figured from the logs. The “(HW)” output in Plex is misleading.

42

@snickers_1

It is just now Monday morning for me (06.45 to be precise). I’m reading while having morning tea.

I will get back to you after I address a few things here.