Transcoding not working with Swiftfin, even though it is fine on other devices

[AlfredAn]

Describe the bug

Transcoding seems to not work properly on my Apple TV. When I try to play a video that requires transcoding the video player appears but just keeps loading indefinitely and never actually plays the video.

Direct play works fine. Transcoding works fine on all other clients I've tested, even Infuse on the same Apple TV. I have tried the "use fmp4 with HLS" and "use native player" settings but they made no difference. For some reason I was able to play the files I tested with when using the "force direct play" option, but it was not usable for one of the movies since it selected Vietnamese subtitles and there was no way to change it.

I have attached the relevant log files. If there is a way to get logs from swiftfin and not just from the server, please let me know and I will get those as well.
FFmpeg.DirectStream-2023-03-03_10-23-18_3f4959b1c1665f9ffa3e5e45f859e88e_b5129905.log
log_20230303.log

Application version

1.0.0(84)

Where did you install the app from?

App Store

Device information

Apple TV HD (A1625) (2015)

OS version

16.3.2 (20K672)

Jellyfin server version

10.8.9

[JGBMichalski]

I have also experience nced this issue!

[dlasher]

Same issue here - AppleTV HD (A1625) - anything requiring transcoding doesn't work.

[2023-07-05 18:37:05.233 -07:00] [INF] StreamBuilder.BuildVideoItem( Profile="Anonymous Profile", Path="/raid/video/Old.TV/xxxxxx/Season.01/S01E01_xxxxxx.mkv", AudioStreamIndex=null, SubtitleStreamIndex=null ) => ( PlayMethod=Transcode, TranscodeReason=VideoCodecNotSupported, AudioCodecNotSupported ) "media:/videos/20276578-e416-ad8f-e4d4-b06e74b2fb84/stream.ts?MediaSourceId=20276578e416ad8fe4d4b06e74b2fb84&VideoCodec=h264,mpeg4&AudioCodec=aac,mp3,wav&AudioStreamIndex=1&VideoBitrate=149744000&AudioBitrate=256000&MaxFramerate=23.976025&api_key=<token>&RequireAvc=false&Tag=4b71e3c8edef96b05a1c0cdf1111957b&hevc-level=123&hevc-videobitdepth=10&hevc-profile=main10&TranscodeReasons=VideoCodecNotSupported,%20AudioCodecNotSupported"
[2023-07-05 18:37:11.381 -07:00] [INF] User policy for "xxxxx". EnablePlaybackRemuxing: True EnableVideoPlaybackTranscoding: True EnableAudioPlaybackTranscoding: True
[2023-07-05 18:37:11.381 -07:00] [INF] RemoteClientBitrateLimit: 150000000, RemoteIp: "xx.xx.xx.xx", IsInLocalNetwork: False
[2023-07-05 18:37:11.381 -07:00] [INF] StreamBuilder.BuildVideoItem( Profile="Anonymous Profile", Path="/raid/video/Old.TV/xxxxxx/Season.01/S01E01_xxxxxx.mkv", AudioStreamIndex=null, SubtitleStreamIndex=null ) => ( PlayMethod=Transcode, TranscodeReason=VideoCodecNotSupported, AudioCodecNotSupported ) "media:/videos/20276578-e416-ad8f-e4d4-b06e74b2fb84/stream.ts?MediaSourceId=20276578e416ad8fe4d4b06e74b2fb84&VideoCodec=h264,mpeg4&AudioCodec=aac,mp3,wav&AudioStreamIndex=1&VideoBitrate=149744000&AudioBitrate=256000&MaxFramerate=23.976025&api_key=<token>&RequireAvc=false&Tag=4b71e3c8edef96b05a1c0cdf1111957b&hevc-level=123&hevc-videobitdepth=10&hevc-profile=main10&TranscodeReasons=VideoCodecNotSupported,%20AudioCodecNotSupported"
[2023-07-05 18:37:11.389 -07:00] [INF] User policy for "xxxxx". EnablePlaybackRemuxing: True EnableVideoPlaybackTranscoding: True EnableAudioPlaybackTranscoding: True
[2023-07-05 18:37:11.390 -07:00] [INF] RemoteClientBitrateLimit: 150000000, RemoteIp: "xx.xx.xx.xx", IsInLocalNetwork: False
[2023-07-05 18:37:11.390 -07:00] [INF] StreamBuilder.BuildVideoItem( Profile="Anonymous Profile", Path="/raid/video/Old.TV/xxxxxx/Season.01/S01E01_xxxxxx.mkv", AudioStreamIndex=null, SubtitleStreamIndex=null ) => ( PlayMethod=Transcode, TranscodeReason=VideoCodecNotSupported, AudioCodecNotSupported ) "media:/videos/20276578-e416-ad8f-e4d4-b06e74b2fb84/stream.ts?MediaSourceId=20276578e416ad8fe4d4b06e74b2fb84&VideoCodec=h264,mpeg4&AudioCodec=aac,mp3,wav&AudioStreamIndex=1&VideoBitrate=149744000&AudioBitrate=256000&MaxFramerate=23.976025&api_key=<token>&RequireAvc=false&Tag=4b71e3c8edef96b05a1c0cdf1111957b&hevc-level=123&hevc-videobitdepth=10&hevc-profile=main10&TranscodeReasons=VideoCodecNotSupported,%20AudioCodecNotSupported"
[2023-07-05 18:37:13.840 -07:00] [INF] User policy for "xxxxxx". EnablePlaybackRemuxing: True EnableVideoPlaybackTranscoding: True EnableAudioPlaybackTranscoding: True
[2023-07-05 18:37:13.840 -07:00] [INF] RemoteClientBitrateLimit: 150000000, RemoteIp: "xx.xx.xx.xx", IsInLocalNetwork: False
[2023-07-05 18:37:13.841 -07:00] [INF] StreamBuilder.BuildVideoItem( Profile="Anonymous Profile", Path="/raid/video/Old.TV/xxxxxx/Season.01/S01E01_xxxxxx.mkv", AudioStreamIndex=null, SubtitleStreamIndex=null ) => ( PlayMethod=Transcode, TranscodeReason=VideoCodecNotSupported, AudioCodecNotSupported ) "media:/videos/4e31edb8-1652-40c6-c1d8-5658a3bbfb2c/stream.ts?MediaSourceId=4e31edb8165240c6c1d85658a3bbfb2c&VideoCodec=h264,mpeg4&AudioCodec=aac,mp3,wav&AudioStreamIndex=1&VideoBitrate=149744000&AudioBitrate=256000&MaxFramerate=23.976025&api_key=<token>&RequireAvc=false&Tag=c6ce37c107ec6cdd5aaa16ba6779db98&hevc-level=120&hevc-videobitdepth=10&hevc-profile=main10&TranscodeReasons=VideoCodecNotSupported,%20AudioCodecNotSupported"
[2023-07-05 18:37:14.394 -07:00] [INF] "/usr/lib/jellyfin-ffmpeg/ffmpeg" "-analyzeduration 200M -init_hw_device vaapi=va:/dev/dri/renderD128 -filter_hw_device va -hwaccel vaapi -hwaccel_output_format vaapi -autorotate 0 -i file:\"/raid/video/Old.TV/xxxxxx/Season.01/S01E01_xxxxxx.mkv\" -autoscale 0 -map 0:0 -map 0:1 -map -0:s -codec:v:0 h264_vaapi -force_key_frames \"expr:gte(t,n_forced*5)\" -vf \"setparams=color_primaries=bt709:color_trc=bt709:colorspace=bt709,scale_vaapi=format=nv12:extra_hw_frames=24\" -rc_mode VBR -b:v 4936264 -maxrate 4936264 -bufsize 9872528 -map_metadata -1 -map_chapters -1 -threads 4 -codec:a:0 libfdk_aac -ac 6 -ab 256000 -y \"/var/lib/jellyfin/transcodes/d3eac9a7fed02ec8dff5c2d500af32f9.ts\""
[2023-07-05 18:37:15.526 -07:00] [WRN] Slow HTTP Response from "https://jf1/videos/20276578-e416-ad8f-e4d4-b06e74b2fb84/stream.ts?DeviceId=tvOS_CFEF6475-D19E-4B1C-B9DC-EA77680D09BB_1688606955.541638&MediaSourceId=20276578e416ad8fe4d4b06e74b2fb84&VideoCodec=h264,mpeg4&AudioCodec=aac,mp3,wav&AudioStreamIndex=1&VideoBitrate=149744000&AudioBitrate=256000&MaxFramerate=23.976025&PlaySessionId=3dc11b89f8624c34ab4437973274ac6b&api_key=a4b8a11eb6d4418aa2edc7a8c9fba957&RequireAvc=false&Tag=4b71e3c8edef96b05a1c0cdf1111957b&hevc-level=123&hevc-videobitdepth=10&hevc-profile=main10&TranscodeReasons=VideoCodecNotSupported,%20AudioCodecNotSupported" to "xx.xx.xx.xx" in 0:00:01.1530852 with Status Code 200

Transcode log attached

transcode-fail.txt

I don't see any way to either force a profile on a specific device, or even know which profile is being used for playback in the WebUI. I suspect building one for this device will solve the problem?

More of probably same: #790

( Would suggest renaming this original issue to "Transcoding failures to AppleTV HD" )

[jacksonwelsh]

Same issue on both iOS 1.0.1 (70) and Apple TV 4K 1.0.1 (70).

Both show an infinite loader. Logs below:

Jan 05 18:20:22 jellyfin jellyfin[696]: [18:20:22] [INF] Current HLS implementation doesn't support non-keyframe breaks but one is requested, ignoring that request
Jan 05 18:20:22 jellyfin jellyfin[696]: [18:20:22] [INF] /usr/lib/jellyfin-ffmpeg/ffmpeg -analyzeduration 200M -ss 01:42:53.209 -fflags +genpts  -i file:"/shared/video/movies/Inglourious Basterds/Inglourious Basterds [1080P].mkv" -map_metadata -1 -map_chapters -1 -threads 0 -map 0:0 -map 0:1 -map -0:s -codec:v:0 copy -tag:v:0 hvc1 -bsf:v hevc_mp4toannexb -start_at_zero -codec:a:0 copy -strict -2 -copyts -avoid_negative_ts disabled -max_muxing_queue_size 2048 -f hls -max_delay 5000000 -hls_time 6 -hls_segment_type mpegts -start_number 1027 -hls_segment_filename "/var/lib/jellyfin/transcodes/4dcb26cd792404467e5dc220e2335123%d.ts" -hls_playlist_type vod -hls_list_size 0 -y "/var/lib/jellyfin/transcodes/4dcb26cd792404467e5dc220e2335123.m3u8"
Jan 05 18:20:22 jellyfin jellyfin[696]: [18:20:22] [INF] Playback stopped reported by app Jellyfin tvOS 1.0.1 playing Inglourious Basterds. Stopped at 4313000 ms
Jan 05 18:20:22 jellyfin jellyfin[696]: [18:20:22] [WRN] Slow HTTP Response from http://10.255.255.16:8096/Sessions/Playing/Stopped to 10.255.255.179 in 0:00:01.6759534 with Status Code 204
Jan 05 18:20:22 jellyfin jellyfin[696]: [18:20:22] [INF] User policy for sysadmin. EnablePlaybackRemuxing: True EnableVideoPlaybackTranscoding: True EnableAudioPlaybackTranscoding: True
Jan 05 18:20:22 jellyfin jellyfin[696]: [18:20:22] [INF] StreamBuilder.BuildVideoItem( Profile=Anonymous Profile, Path=/shared/video/movies/Inglourious Basterds/Inglourious Basterds [1080P].mkv, AudioStreamIndex=null, SubtitleStreamIndex=null ) => ( PlayMethod=DirectPlay, TranscodeReason=0 ) media:/videos/8f3d64b6-7245-c0a3-b9c2-8eecb5ab6870/stream.mkv?MediaSourceId=8f3d64b67245c0a3b9c28eecb5ab6870&Static=true&VideoCodec=hevc&AudioCodec=dts&AudioStreamIndex=1&SubtitleStreamIndex=6&api_key=<token>&SubtitleMethod=Embed&Tag=7ebcc065319bb58e3dafefd50552a6bf
Jan 05 18:20:22 jellyfin jellyfin[696]: [18:20:22] [INF] User policy for sysadmin. EnablePlaybackRemuxing: True EnableVideoPlaybackTranscoding: True EnableAudioPlaybackTranscoding: True
Jan 05 18:20:22 jellyfin jellyfin[696]: [18:20:22] [INF] StreamBuilder.BuildVideoItem( Profile=Anonymous Profile, Path=/shared/video/movies/Inglourious Basterds/Inglourious Basterds [1080P].mkv, AudioStreamIndex=null, SubtitleStreamIndex=null ) => ( PlayMethod=DirectPlay, TranscodeReason=0 ) media:/videos/8f3d64b6-7245-c0a3-b9c2-8eecb5ab6870/stream.mkv?MediaSourceId=8f3d64b67245c0a3b9c28eecb5ab6870&Static=true&VideoCodec=hevc&AudioCodec=dts&AudioStreamIndex=1&SubtitleStreamIndex=6&api_key=<token>&SubtitleMethod=Embed&Tag=7ebcc065319bb58e3dafefd50552a6bf
Jan 05 18:20:22 jellyfin jellyfin[696]: [18:20:22] [INF] User policy for sysadmin. EnablePlaybackRemuxing: True EnableVideoPlaybackTranscoding: True EnableAudioPlaybackTranscoding: True
Jan 05 18:20:22 jellyfin jellyfin[696]: [18:20:22] [INF] StreamBuilder.BuildVideoItem( Profile=Anonymous Profile, Path=/shared/video/movies/Inglourious Basterds/Inglourious Basterds [1080P].mkv, AudioStreamIndex=null, SubtitleStreamIndex=null ) => ( PlayMethod=DirectPlay, TranscodeReason=0 ) media:/videos/8f3d64b6-7245-c0a3-b9c2-8eecb5ab6870/stream.mkv?MediaSourceId=8f3d64b67245c0a3b9c28eecb5ab6870&Static=true&VideoCodec=hevc&AudioCodec=dts&AudioStreamIndex=1&SubtitleStreamIndex=6&api_key=<token>&SubtitleMethod=Embed&Tag=7ebcc065319bb58e3dafefd50552a6bf
Jan 05 18:20:25 jellyfin jellyfin[696]: [18:20:25] [INF] FFmpeg exited with code 0
Jan 05 18:20:35 jellyfin jellyfin[696]: [18:20:35] [INF] Current HLS implementation doesn't support non-keyframe breaks but one is requested, ignoring that request
Jan 05 18:20:35 jellyfin jellyfin[696]: [18:20:35] [INF] /usr/lib/jellyfin-ffmpeg/ffmpeg -analyzeduration 200M -fflags +genpts  -i file:"/shared/video/movies/Inglourious Basterds/Inglourious Basterds [1080P].mkv" -map_metadata -1 -map_chapters -1 -threads 0 -map 0:0 -map 0:1 -map -0:s -codec:v:0 copy -tag:v:0 hvc1 -bsf:v hevc_mp4toannexb -start_at_zero -codec:a:0 copy -strict -2 -copyts -avoid_negative_ts disabled -max_muxing_queue_size 2048 -f hls -max_delay 5000000 -hls_time 6 -hls_segment_type mpegts -start_number 0 -hls_segment_filename "/var/lib/jellyfin/transcodes/fb6c43fc3acfaa2a6618ea181a27b21d%d.ts" -hls_playlist_type vod -hls_list_size 0 -y "/var/lib/jellyfin/transcodes/fb6c43fc3acfaa2a6618ea181a27b21d.m3u8"
Jan 05 18:20:36 jellyfin jellyfin[696]: [18:20:36] [INF] Stopping ffmpeg process with q command for /var/lib/jellyfin/transcodes/fb6c43fc3acfaa2a6618ea181a27b21d.m3u8
Jan 05 18:20:36 jellyfin jellyfin[696]: [18:20:36] [INF] FFmpeg exited with code 0
Jan 05 18:20:36 jellyfin jellyfin[696]: [18:20:36] [INF] Current HLS implementation doesn't support non-keyframe breaks but one is requested, ignoring that request
Jan 05 18:20:36 jellyfin jellyfin[696]: [18:20:36] [INF] /usr/lib/jellyfin-ffmpeg/ffmpeg -analyzeduration 200M -ss 01:11:53.559 -fflags +genpts  -i file:"/shared/video/movies/Inglourious Basterds/Inglourious Basterds [1080P].mkv" -map_metadata -1 -map_chapters -1 -threads 0 -map 0:0 -map 0:1 -map -0:s -codec:v:0 copy -tag:v:0 hvc1 -bsf:v hevc_mp4toannexb -start_at_zero -codec:a:0 copy -strict -2 -copyts -avoid_negative_ts disabled -max_muxing_queue_size 2048 -f hls -max_delay 5000000 -hls_time 6 -hls_segment_type mpegts -start_number 715 -hls_segment_filename "/var/lib/jellyfin/transcodes/fb6c43fc3acfaa2a6618ea181a27b21d%d.ts" -hls_playlist_type vod -hls_list_size 0 -y "/var/lib/jellyfin/transcodes/fb6c43fc3acfaa2a6618ea181a27b21d.m3u8"
Jan 05 18:20:41 jellyfin jellyfin[696]: [18:20:41] [INF] FFmpeg exited with code 0
Jan 05 18:21:08 jellyfin jellyfin[696]: [18:21:08] [INF] Playback stopped reported by app Jellyfin tvOS 1.0.1 playing Inglourious Basterds. Stopped at 545000 ms
Jan 05 18:21:08 jellyfin jellyfin[696]: [18:21:08] [INF] Playback stopped reported by app Jellyfin tvOS 1.0.1 playing Inglourious Basterds. Stopped at 4672000 ms

TV shows infinite loader even after final log message - I never tried to cancel playback.

Loud, distinct clicking sounds are coming from my sound system intermittently while in the app and stop immediate after closing it.

[Fiddoodle]

I believe this is an issue with either the open subtitles plugin or the subrip files it creates. I was having this issue for some files and not others on my apple TV HD and I realised the problem files had subrip files generated by the plug in. I subsuqently removed the plugin and the files and playback worked fine.

Apologies I didn't isolate the issue to specifically the plugin or the subrip file.