Plex buffering every 12 minutes

Server Version: 1.16.1.1291
Player Version#: Plex for Xbox One: 2.35.1.70 / Windows 10.0.18362.5047

Hi,

Every 12 minutes (pretty much exactly plex starts buffering. I’m not running anything special, just a simple 5-6GB 1080P film on a 1Gbps connection. It’s not the network bandwidth an issue and seems very strange it’s at 12-minute intervals.

I have attached the logs but I wasn’t sure how to delete them to clear them up so it does have some verbose logging in before I switched it off. However, not much and the issue should be near the end as I grabbed it when it happened last.

Can anyone help?

Thanks. (33.1 KB)

Wrong log, sorry.

  1. Settings - Server - General : DEBUG on, VERBOSE off
  2. Recreate the buffering
  3. Stop after 30 seconds
  4. Wait 30 seconds
  5. Settings - Server - Troubleshooting - Download Logs
  6. Attach the ZIP file it presents you.

Hi Chuck,

Just before I do that I don’t know if this helps… It seems to be something network related as it stops streaming so the buffer runs out.

Please see the attached pictures…

Picture 1 shows it has been streaming fine (the peaks are normal) then simply stops and flat-lines. It’s still playing at this point as it’s playing the buffer and the network speed just drops to around 70 kbps.

Picture 2 shows a continuation of picture 1 when it’s stopped and is buffering. Nothing happens and it doesn’t perk back up until I exit the film and tell it to go back in and resume from where it left off… then the issue repeats.

Does that help with diagnosing the issue or do you still need the logs?

Thanks buddy :slight_smile:

Hi Chuck,

Got the logs anyway :slight_smile:

Happened again, more logs attached.

I note at the bottom of “Plex Media Server.log” lots of entries for:
Jul 12, 2019 22:26:53.414 [0x7fec656fb700] DEBUG - We’re going to try to auto-select an audio stream for account 1.
Jul 12, 2019 22:26:53.414 [0x7fec656fb700] DEBUG - Selecting best audio stream for part ID 888 (autoselect: 0 language: en)
Jul 12, 2019 22:26:53.414 [0x7fec656fb700] DEBUG - We’re going to try to auto-select a subtitle.
Jul 12, 2019 22:26:53.414 [0x7fec656fb700] DEBUG - Audio Stream: 1960, Subtitle Stream: -1

I wonder if this is related?

I’m not trying to change any tracks or subtitles.

Thanks again.

Please stop Plex,
Uninstall the package.
Restart DSM
Reinstall the package.

It knows you have a capable CPU (J3455), and it’s enabled, but couldn’t find the hardware.
This means the permissions for /dev/dri are wrong and that user plex isn’t part of that group (which I add when the installer runs)

Jul 12, 2019 21:17:48.216 [0x7fec65fc5700] DEBUG - Starting a transcode session 9m2gjkuozf4w59fur49t7uf1 at offset -1.0 (state=3)
Jul 12, 2019 21:17:48.217 [0x7fec65fc5700] DEBUG - TPU: hardware transcoding: enabled, but no hardware decode accelerator found
Jul 12, 2019 21:17:48.217 [0x7fec65fc5700] DEBUG - [Universal] Using local file path instead of URL: /volume1/Media/Movies/HD Movies/Alita - Battle Angel (2019)/Alita - Battle Angel (2019).mkv
Jul 12, 2019 21:17:48.217 [0x7fec65fc5700] DEBUG - TPU: hardware transcoding: final decoder: , final encoder: 
Jul 12, 2019 21:17:48.218 [0x7fec65fc5700] DEBUG - Job running: EAE_ROOT='/volume1/Plex/tmp_transcoding/pms-6f59de4e-ab64-4294-bb9d-bc073cb06e24/EasyAudioEncoder' FFMPEG_EXTERNAL_LIBS='/volume1/Plex/Library/Application\ Support/Plex\ Media\ Server/Codecs/21b5515-2321-linux-x86_64/' XDG_CACHE_HOME='/volume1/Plex/Library/Application Support/Plex Media Server/Cache' XDG_DATA_HOME='/volume1/@appstore/Plex Media Server/Resources' X_PLEX_TOKEN='xxxxxxxxxxxxxxxxxxxx' '/volume1/@appstore/Plex Media Server/Plex Transcoder' '-codec:0' 'h264' '-codec:1' 'dca' '-noaccurate_seek' '-analyzeduration' '20000000' '-probesize' '20000000' '-i' '/volume1/Media/Movies/HD Movies/Alita - Battle Angel (2019)/Alita - Battle Angel (2019).mkv' '-filter_complex' '[0:1] aresample=async=1:ocl='\''5.1'\'':osr=48000[0]' '-map' '0:0' '-metadata:s:0' 'language=english' '-codec:0' 'copy' '-map' '[0]' '-metadata:s:1' 'language=english' '-codec:1' 'aac' '-strict:1' 'experimental' '-aac_coder:1' 'fast' '-q:1' '0' '-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/9m2gjkuozf4w59fur49t7uf1/a284bb8c-ee77-46e6-babb-83d4fcd9f5cb/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/9m2gjkuozf4w59fur49t7uf1/a284bb8c-ee77-46e6-babb-83d4fcd9f5cb/progress'

Hi Chuck,

Okay, should I re-install from the version in DSM Package Centre or download it from Plex then? If from Plex which architecture do I chose?

Thanks again.

Download and install from plex.tv/downloads

X86_64 (64 bit Intel) DSM 6+

I don’t know what Synology is using these days.

Okay done… seems to have retained my library and settings (bonus).

I’m playing the same video again to test it but how can I check the permissions? I ssh’d in but could only see /dev/dri owned by root

Cheers

It seems to be working now but it’s down scaling the picture from HD to SD.

I’m not sure if it was doing this before as I never checked so it could just be working because it’s down scaling it?

Why would it be down scaling on LAN? There’s more than enough bandwidth.

Thanks.

If you’ve already SSH’d into it,

ls -la /dev/dri

It should come back as root:video

Let me know please if it doesn’t come back as that.

As for uninstall/reinstall, I don’t remove your metadata (the Plex share) when I remove the package. They are stored in two different locations so it can retain your media info and save time.

We might need to delete the Plex user and reinstall the package again.
If it recreates the user, it makes certain Plex is added to the video group.

Looks good Chuck:

crw-rw---- 1 root video 226, 0 Jul 12 22:46 card0
crw-rw---- 1 root video 226, 64 Jul 12 22:46 controlD64
crw-rw---- 1 root video 226, 128 Jul 12 22:46 renderD128

Can you just see my last post about it down scaling please? If we can solve that we should be good :slight_smile:

Sorry forgot to attach image

12-23-04-13

It seems something has changed as it’s downscaling a 4K movie to SD as well. I know this file has played flawlessly before.

I did have the “Automatically adjust quality (Beta)” option on so turned it off and it’s the same.

Any help is appreciated :slight_smile:

Okay been through every setting and got it working. Didn’t realise the server needed to be restarted within Synology for the settings to take effect.

Just testing a 4K film and will re-test the original 1080p film I was having issues with originally.

I’ll update the thread in around half an hour with my progress :slight_smile:

Hi Chuck,

Nope, happened again at 18 minutes in for a 4K movie :frowning:

Logs attached, any ideas?Plex Media Server Logs_2019-07-12_23-47-31.zip (1.5 MB)

I can’t help with the Xbox client. I know it has a lot of restrictions (Microsoft).

From the Plex side, I see PMS sees the need to transcode the video. It attempts to perform the transcode and there is no hardware to be found for the H264 encoding.

It is also converting the audio down to AAC (which I think is normal for Xbox because of those Microsoft restrictions.

Would you pleases go into Settings - Server - Network and turn off IPv6 support?
You have an IPv4 network. It would make my job a bit easer, thanks.

Jul 12, 2019 21:17:48.218 [0x7fec65fc5700] DEBUG - Job running: EAE_ROOT='/volume1/Plex/tmp_transcoding/pms-6f59de4e-ab64-4294-bb9d-bc073cb06e24/EasyAudioEncoder' FFMPEG_EXTERNAL_LIBS='/volume1/Plex/Library/Application\ Support/Plex\ Media\ Server/Codecs/21b5515-2321-linux-x86_64/' XDG_CACHE_HOME='/volume1/Plex/Library/Application Support/Plex Media Server/Cache' XDG_DATA_HOME='/volume1/@appstore/Plex Media Server/Resources' X_PLEX_TOKEN='xxxxxxxxxxxxxxxxxxxx' '/volume1/@appstore/Plex Media Server/Plex Transcoder' '-codec:0' 'h264' '-codec:1' 'dca' '-noaccurate_seek' '-analyzeduration' '20000000' '-probesize' '20000000' '-i' '/volume1/Media/Movies/HD Movies/Alita - Battle Angel (2019)/Alita - Battle Angel (2019).mkv' '-filter_complex' '[0:1] aresample=async=1:ocl='\''5.1'\'':osr=48000[0]' '-map' '0:0' '-metadata:s:0' 'language=english' '-codec:0' 'copy' '-map' '[0]' '-metadata:s:1' 'language=english' '-codec:1' 'aac' '-strict:1' 'experimental' '-aac_coder:1' 'fast' '-q:1' '0' '-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/9m2gjkuozf4w59fur49t7uf1/a284bb8c-ee77-46e6-babb-83d4fcd9f5cb/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/9m2gjkuozf4w59fur49t7uf1/a284bb8c-ee77-46e6-babb-83d4fcd9f5cb/progress'

Here is where the transcoder has gotten head of the playback requirement.

live) TLS GZIP 2ms 365453 bytes (pipelined: 147) (range: bytes=0-) 
Jul 12, 2019 23:46:07.284 [0x7f2f83159700] DEBUG - Session w1u0vfnsuhwujkope30cs4dg (4) is unthrottling
Jul 12, 2019 23:46:07.285 [0x7f2fb4518700] DEBUG - [Transcoder] Throttle - Getting back to work.
Jul 12, 2019 23:46:07.403 [0x7f2f82b7d700] DEBUG - Transcoder segment range: 0 - 234 (234)
Jul 12, 2019 23:46:07.631 [0x7f2f816fb700] DEBUG - Transcoder segment range: 0 - 235 (235)
Jul 12, 2019 23:46:07.635 [0x7f2fb7d11700] DEBUG - Completed: [::ffff:192.168.0.71:51035] -2 GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (14 live) TLS GZIP 20001ms 5 bytes (pipelined: 65)
Jul 12, 2019 23:46:07.645 [0x7f2fb7a23700] DEBUG - Auth: authenticated user 1 as Daskew78
Jul 12, 2019 23:46:07.645 [0x7f2f83159700] DEBUG - Request: [::ffff:192.168.0.71:51035 (Allowed Network (Subnet))] GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (14 live) TLS GZIP Signed-in Token (Daskew78)
Jul 12, 2019 23:46:07.766 [0x7f2f82b7d700] DEBUG - Transcoder segment range: 0 - 236 (236)
Jul 12, 2019 23:46:08.712 [0x7f2f83159700] DEBUG - Transcoder segment range: 0 - 237 (237)
Jul 12, 2019 23:46:08.848 [0x7f2fb7a23700] DEBUG - Auth: authenticated user 1 as Daskew78
Jul 12, 2019 23:46:08.849 [0x7f2f82b7d700] DEBUG - Request: [::ffff:192.168.0.71:51053 (Allowed Network (Subnet))] GET /:/timeline?ratingKey=158&key=%2Flibrary%2Fmetadata%2F158&playbackTime=1094939&playQueueItemID=30&state=playing&hasMDE=1&time=1099000&duration=8489000 (13 live) TLS GZIP Signed-in Token (Daskew78)

It’s sleeping here until the XBox asks for more.
Slow network?

I’ve turned off IPv6 but quite the opposite of the slow network…

Xbox > Netgear JGS516PE Switch > Netgear JGS516PE Switch (4x1Gbps aggregated). > 2x 1Gbps aggregated Synology DS918+.

I’ll try with the aggregation turned off on the Synology. Unless you can think of anything else?

Also with regards to the transcoder, should it not be finding one with this NAS?
TPU: hardware transcoding: enabled, but no hardware decode accelerator found

I thought it had a dedicated chip?