Ive noticed that when it first starts to buffer on screen, Then checking the PMS log for the exact same time stamp that the buffering accrued at 2:20am, Its saying that “hardware transcoding: enabled, but no hardware decode accelerator found”
02:20:41.290 [19597] DEBUG - Transcode Session: Going to restart at offset 750.750732 with segment number 750
Aug 03, 2018 02:20:41.290 [19597] DEBUG - Killing job.
Aug 03, 2018 02:20:41.290 [19597] DEBUG - Signalling job ID 19796 with 9
Aug 03, 2018 02:20:41.290 [19597] DEBUG - Job was already killed, not killing again.
Aug 03, 2018 02:20:41.290 [19597] DEBUG - Stopping transcode session c39uqwz4vo3zbyb92sb0ougz
Aug 03, 2018 02:20:41.290 [19597] DEBUG - Starting a transcode session c39uqwz4vo3zbyb92sb0ougz at offset 750.8 (state=3)
Aug 03, 2018 02:20:41.290 [19597] DEBUG - TPU: hardware transcoding: enabled, but no hardware decode accelerator found
Aug 03, 2018 02:20:41.293 [19597] DEBUG - [Universal] Using local file path instead of URL: /storage/6472DE6872DE3E8C/My Media/Movies/The Time Machine (2002).mkv
Aug 03, 2018 02:20:41.293 [19597] DEBUG - TPU: hardware transcoding: zero-copy support not present
Aug 03, 2018 02:20:41.293 [19597] DEBUG - TPU: hardware transcoding: final decoder: , final encoder:
Aug 03, 2018 02:20:41.308 [19597] DEBUG - Job running: EAE_ROOT='/data/user/0/com.plexapp.mediaserver.smb/tmp/pms-4388a2be-3b0d-48ee-ab10-06faed4b3f6f/EasyAudioEncoder' FFMPEG_EXTERNAL_LIBS='/data/user/0/com.plexapp.mediaserver.smb/Plex\ Media\ Server/Codecs/e944d3a-1309-android-aarch64/' XDG_CACHE_HOME='/storage/emulated/0/Android/data/com.plexapp.mediaserver.smb/cache' XDG_DATA_HOME='/data/user/0/com.plexapp.mediaserver.smb/Resources' X_PLEX_TOKEN='xxxxxxxxxxxxxxxxxxxx' '/data/user/0/com.plexapp.mediaserver.smb/Resources/Plex Transcoder' '-codec:0' 'h264' '-codec:1' 'dca' '-ss' '750' '-i' '/storage/6472DE6872DE3E8C/My Media/Movies/The Time Machine (2002).mkv' '-filter_complex' '[0:0]scale=w=1920:h=800[0];[0]format=pix_fmts=yuv420p|nv12[1]' '-filter_complex' '[0:1] aresample=async=1:ocl='\''5.1'\'':osr=48000:rematrix_maxval=5.000000dB[2]' '-map' '[1]' '-metadata:s:0' 'language=eng' '-codec:0' 'libx264' '-crf:0' '22' '-maxrate:0' '6780k' '-bufsize:0' '13560k' '-r:0' '23.975999999999999' '-preset:0' 'veryfast' '-level:0' '5.1' '-x264opts:0' 'subme=0:me_range=4:rc_lookahead=10:me=dia:no_chroma_me:8x8dct=0:partitions=none' '-force_key_frames:0' 'expr:gte(t,750.750732+n_forced*1)' '-map' '[2]' '-metadata:s:1' 'language=eng' '-codec:1' 'aac' '-strict:1' 'experimental' '-aac_coder:1' 'fast' '-q:1' '0' '-segment_format' 'mpegts' '-f' 'ssegment' '-individual_header_trailer' '0' '-segment_time' '1' '-segment_start_number' '750' '-segment_copyts' '1' '-segment_time_delta' '0.0625' '-segment_list' 'http://127.0.0.1:32400/video/:/transcode/session/c39uqwz4vo3zbyb92sb0ougz/78acd315-f0e1-45e9-b982-3750244f9444/seglist' '-segment_list_type' 'csv' '-segment_list_size' '2147483647' '-segment_list_separate_stream_times' '1' '-max_delay' '5000000' '-avoid_negative_ts' 'disabled' '-map_metadata' '-1' '-map_chapters' '-1' 'media-%05d.ts' '-start_at_zero' '-copyts' '-y' '-nostats' '-loglevel' 'quiet' '-loglevel_plex' 'error' '-progressurl' 'http://127.0.0.1:32400/video/:/transcode/session/c39uqwz4vo3zbyb92sb0ougz/78acd315-f0e1-45e9-b982-3750244f9444/progress'
Aug 03, 2018 02:20:41.324 [19597] DEBUG - Jobs: Starting child process with pid 20498
Aug 03, 2018 02:20:41.324 [19597] DEBUG - Started session successfully: c39uqwz4vo3zbyb92sb0ougz
Aug 03, 2018 02:20:42.256 [19712] DEBUG - Request: [127.0.0.1:60731 (Loopback)] PUT /video/:/transcode/session/c39uqwz4vo3zbyb92sb0ougz/78acd315-f0e1-45e9-b982-3750244f9444/progress/stream?index=0&id=0&codec=h264&type=video (12 live) Signed-in Token (Strategizer8520)
Aug 03, 2018 02:20:42.257 [4452] DEBUG - Completed: [127.0.0.1:60731] 206 PUT /video/:/transcode/session/c39uqwz4vo3zbyb92sb0ougz/78acd315-f0e1-45e9-b982-3750244f9444/progress/stream?index=0&id=0&codec=h264&type=video (11 live) 1ms 227 bytes (range: bytes=0-)
Aug 03, 2018 02:20:42.258 [19609] DEBUG - Request: [127.0.0.1:60732 (Loopback)] PUT /video/:/transcode/session/c39uqwz4vo3zbyb92sb0ougz/78acd315-f0e1-45e9-b982-3750244f9444/progress/stream?index=1&id=0&codec=dts&type=audio (12 live) Signed-in Token (Strategizer8520)
Aug 03, 2018 02:20:42.259 [4452] DEBUG - Completed: [127.0.0.1:60732] 206 PUT /video/:/transcode/session/c39uqwz4vo3zbyb92sb0ougz/78acd315-f0e1-45e9-b982-3750244f9444/progress/stream?index=1&id=0&codec=dts&type=audio (12 live) 0ms 227 bytes (range: bytes=0-)
Aug 03, 2018 02:20:42.266 [19703] ERROR - [Transcoder] [h264 @ 0x20258fda00] SEI type 5 size 4856 truncated at 4843
Aug 03, 2018 02:20:42.403 [19600] DEBUG - Request: [127.0.0.1:60734 (Loopback)] PUT /video/:/transcode/session/c39uqwz4vo3zbyb92sb0ougz/78acd315-f0e1-45e9-b982-3750244f9444/progress/streamDetail?index=0&id=0&codec=h264&type=video&profile=High&language=eng&width=1920&height=800&interlaced=0&sar=1:1&level=41&frameRate=23.976&disp_default=1 (11 live) Signed-in Token (Strategizer8520)
Aug 03, 2018 02:20:42.404 [4453] DEBUG - Completed: [127.0.0.1:60734] 206 PUT /video/:/transcode/session/c39uqwz4vo3zbyb92sb0ougz/78acd315-f0e1-45e9-b982-3750244f9444/progress/streamDetail?index=0&id=0&codec=h264&type=video&profile=High&language=eng&width=1920&height=800&interlaced=0&sar=1:1&level=41&frameRate=23.976&disp_default=1 (11 live) 0ms 227 bytes (range: bytes=0-)
Aug 03, 2018 02:20:42.405 [19709] DEBUG - Request: [127.0.0.1:60735 (Loopback)] PUT /video/:/transcode/session/c39uqwz4vo3zbyb92sb0ougz/78acd315-f0e1-45e9-b982-3750244f9444/progress/streamDetail?index=1&id=0&codec=dts&type=audio&bitrate=768000&profile=DTS&language=eng&channels=6&layout=5.1%28side%29&sampleRate=48000&bitDepth=24&disp_default=1 (12 live) Signed-in Token (Strategizer8520)
Aug 03, 2018 02:20:42.406 [4452] DEBUG - Completed: [127.0.0.1:60735] 206 PUT /video/:/transcode/session/c39uqwz4vo3zbyb92sb0ougz/78acd315-f0e1-45e9-b982-3750244f9444/progress/streamDetail?index=1&id=0&codec=dts&type=audio&bitrate=768000&profile=DTS&language=eng&channels=6&layout=5.1%28side%29&sampleRate=48000&bitDepth=24&disp_default=1 (11 live) 0ms 227 bytes (range: bytes=0-)
Aug 03, 2018 02:20:42.407 [19408] DEBUG - Request: [127.0.0.1:60736 (Loopback)] PUT /video/:/transcode/session/c39uqwz4vo3zbyb92sb0ougz/78acd315-f0e1-45e9-b982-3750244f9444/progress/streamDetail?index=2&id=0&codec=subrip&type=subtitle&language=eng&disp_default=1 (12 live) Signed-in Token (Strategizer8520)
Aug 03, 2018 02:20:42.408 [4452] DEBUG - Completed: [127.0.0.1:60736] 206 PUT /video/:/transcode/session/c39uqwz4vo3zbyb92sb0ougz/78acd315-f0e1-45e9-b982-3750244f9444/progress/streamDetail?index=2&id=0&codec=subrip&type=subtitle&language=eng&disp_default=1 (11 live) 0ms 227 bytes (range: bytes=0-)
Aug 03, 2018 02:20:42.455 [19615] DEBUG - Request: [127.0.0.1:60737 (Loopback)] PUT /video/:/transcode/session/c39uqwz4vo3zbyb92sb0ougz/78acd315-f0e1-45e9-b982-3750244f9444/progress?duration=5743.126000 (11 live) Signed-in Token (Strategizer8520)
Aug 03, 2018 02:20:42.456 [4452] DEBUG - Completed: [127.0.0.1:60737] 206 PUT /video/:/transcode/session/c39uqwz4vo3zbyb92sb0ougz/78acd315-f0e1-45e9-b982-3750244f9444/progress?duration=5743.126000 (11 live) 1ms 326 bytes (range: bytes=0-)
Aug 03, 2018 02:20:42.980 [19712] DEBUG - Transcoder: session c39uqwz4vo3zbyb92sb0ougz indicated fallback to software decoding
Also at this exact time as the buffering accrues, Im monitoring Plex via the web app & Tatulli & notice both Plex & Tautulli remove the (HW) Hardware accelerator from the session info. This is also noticeable because its stops throttling & stays at a speed of 1-1.2, Sometimes much lower.
