jellyfin-media-player
jellyfin-media-player copied to clipboard
Playback failed with error "unrecognized file format" the first time, but works after repeated retries
Describe the bug When I first try to play any video, it fails to play and suggests I retry with transcode. However, if I cancel out of that screen, do not retry with transcode, and simply try to play the video again, it often works.
I have another computer set up with identical settings that does not experience this problem.
To Reproduce This seems to be specific to one machine, as I have another computer set up that does not experience this problem. I don't know that it will be easily reproducible on other hardware.
Expected behavior If the server has a compatible codec available, it will play it the first time.
Screenshots
First click:
After clicking cancel:
After clicking "Got it" and trying to play the exact video again in the same way, it will often work after enough tries.
Desktop (please complete the following information):
- OS: Windows 10
- Latest release (1.7.1)
Relevant logs:
2022-08-14 23:05:28 [ INFO ] JS: Sending web socket message: KeepAlive
2022-08-14 23:05:28 [ INFO ] JS: Received KeepAlive from server.
2022-08-14 23:05:29 [ INFO ] JS: Requesting url without automatic networking: http://garnet.local:8096/Sessions/Playing/Progress
2022-08-14 23:05:30 [ DEBUG ] KonvergoWindow.cpp @ 671 - Screen 0 QScreen(0x1abd9b2bea0, name="\\\\.\\DISPLAY1") QRect(0,0 1920x1080) QRect(0,0 3200x1080) active: false selected: false
2022-08-14 23:05:30 [ DEBUG ] KonvergoWindow.cpp @ 671 - Screen 1 QScreen(0x1abd9b2bde0, name="\\\\.\\DISPLAY2") QRect(1920,0 1280x1024) QRect(0,0 3200x1080) active: true selected: false
2022-08-14 23:05:30 [ DEBUG ] SettingsComponent.cpp @ 140 - Updated possible values for: "forceFSScreen" to (QVariant(QVariantMap, QMap(("title", QVariant(QString, "Auto"))("value", QVariant(QString, "")))), QVariant(QVariantMap, QMap(("title", QVariant(QString, "0,0 1919x1079 (\\\\.\\DISPLAY1)"))("value", QVariant(QString, "\\\\.\\DISPLAY1")))), QVariant(QVariantMap, QMap(("title", QVariant(QString, "1920,0 3199x1023 (\\\\.\\DISPLAY2) *"))("value", QVariant(QString, "\\\\.\\DISPLAY2")))))
2022-08-14 23:05:30 [ DEBUG ] KonvergoWindow.cpp @ 582 - resize event: QSize(1280, 961)
2022-08-14 23:05:30 [ DEBUG ] KonvergoWindow.cpp @ 479 - QWindow visibility set to QWindow::Maximized
2022-08-14 23:05:39 [ INFO ] JS: Requesting url without automatic networking: http://garnet.local:8096/Sessions/Playing/Progress
2022-08-14 23:05:49 [ INFO ] JS: Requesting url without automatic networking: http://garnet.local:8096/Sessions/Playing/Progress
2022-08-14 23:05:58 [ INFO ] JS: Sending web socket message: KeepAlive
2022-08-14 23:05:58 [ INFO ] JS: Received KeepAlive from server.
2022-08-14 23:05:59 [ INFO ] JS: Requesting url without automatic networking: http://garnet.local:8096/Sessions/Playing/Progress
2022-08-14 23:06:09 [ INFO ] JS: Requesting url without automatic networking: http://garnet.local:8096/Sessions/Playing/Progress
2022-08-14 23:06:19 [ INFO ] JS: Requesting url without automatic networking: http://garnet.local:8096/Sessions/Playing/Progress
2022-08-14 23:06:20 [ ERROR ] PlayerComponent.cpp @ 594 - ffmpeg: tcp: Connection to tcp://garnet.local:8096 failed: Error number -138 occurred
2022-08-14 23:06:20 [ ERROR ] PlayerComponent.cpp @ 594 - stream: Failed to open http://garnet.local:8096/Videos/b69c2ee95b75ce90531c018d5e4a280b/stream.mkv?Static=true&mediaSourceId=b69c2ee95b75ce90531c018d5e4a280b&deviceId=SmVsbHlmaW5NZWRpYVBsYXllciAxLjcuMCAod2luZG93cy14ODZfNjQgMTApfDE2NTUwOTUxODc4Njk1&api_key=xxxxxxxxxxxxxxxxxxxx1a6786404a22&Tag=5eaa969eda63c319d13edebf50acb5bb.
2022-08-14 23:06:20 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: Opening failed or was aborted: http://garnet.local:8096/Videos/b69c2ee95b75ce90531c018d5e4a280b/stream.mkv?Static=true&mediaSourceId=b69c2ee95b75ce90531c018d5e4a280b&deviceId=SmVsbHlmaW5NZWRpYVBsYXllciAxLjcuMCAod2luZG93cy14ODZfNjQgMTApfDE2NTUwOTUxODc4Njk1&api_key=xxxxxxxxxxxxxxxxxxxx1a6786404a22&Tag=5eaa969eda63c319d13edebf50acb5bb
2022-08-14 23:06:20 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: finished playback, loading failed (reason 4)
2022-08-14 23:06:20 [ INFO ] PlayerComponent.cpp @ 590 - cplayer:
2022-08-14 23:06:20 [ INFO ] PlayerComponent.cpp @ 474 - "Entering state: error (loading failed)"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 20 - "DisplayManager found 2 Display(s)."
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 26 - "Available modes for Display #0 (Intel(R) HD Graphics 530)"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 0: 320 x 200 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 1: 320 x 200 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 2: 320 x 200 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 3: 320 x 240 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 4: 320 x 240 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 5: 320 x 240 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 6: 400 x 300 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 7: 400 x 300 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 8: 400 x 300 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 9: 512 x 384 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 10: 512 x 384 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 11: 512 x 384 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 12: 640 x 400 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 13: 640 x 400 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 14: 640 x 400 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 15: 640 x 480 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 16: 640 x 480 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 17: 640 x 480 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 18: 800 x 600 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 19: 800 x 600 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 20: 800 x 600 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 21: 1024 x 768 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 22: 1024 x 768 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 23: 1024 x 768 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 24: 1152 x 864 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 25: 1152 x 864 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 26: 1152 x 864 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 27: 1280 x 600 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 28: 1280 x 600 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 29: 1280 x 600 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 30: 1280 x 720 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 31: 1280 x 720 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 32: 1280 x 720 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 33: 1280 x 768 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 34: 1280 x 768 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 35: 1280 x 768 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 36: 1280 x 800 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 37: 1280 x 800 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 38: 1280 x 800 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 39: 1280 x 960 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 40: 1280 x 960 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 41: 1280 x 960 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 42: 1280 x 1024 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 43: 1280 x 1024 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 44: 1280 x 1024 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 45: 1360 x 768 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 46: 1360 x 768 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 47: 1360 x 768 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 48: 1366 x 768 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 49: 1366 x 768 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 50: 1366 x 768 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 51: 1400 x 1050 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 52: 1400 x 1050 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 53: 1400 x 1050 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 54: 1440 x 900 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 55: 1440 x 900 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 56: 1440 x 900 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 57: 1600 x 900 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 58: 1600 x 900 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 59: 1600 x 900 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 60: 1680 x 1050 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 61: 1680 x 1050 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 62: 1680 x 1050 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 63: 1920 x 1080 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 26 - "Available modes for Display #1 (Intel(R) HD Graphics 530)"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 0: 320 x 200 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 1: 320 x 200 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 2: 320 x 200 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 3: 320 x 200 x 32bpp @75Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 4: 320 x 200 x 32bpp @75Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 5: 320 x 200 x 32bpp @75Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 6: 320 x 240 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 7: 320 x 240 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 8: 320 x 240 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 9: 320 x 240 x 32bpp @75Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 10: 320 x 240 x 32bpp @75Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 11: 320 x 240 x 32bpp @75Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 12: 400 x 300 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 13: 400 x 300 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 14: 400 x 300 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 15: 400 x 300 x 32bpp @75Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 16: 400 x 300 x 32bpp @75Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 17: 400 x 300 x 32bpp @75Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 18: 512 x 384 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 19: 512 x 384 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 20: 512 x 384 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 21: 512 x 384 x 32bpp @75Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 22: 512 x 384 x 32bpp @75Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 23: 512 x 384 x 32bpp @75Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 24: 640 x 400 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 25: 640 x 400 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 26: 640 x 400 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 27: 640 x 400 x 32bpp @75Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 28: 640 x 400 x 32bpp @75Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 29: 640 x 400 x 32bpp @75Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 30: 640 x 480 x 32bpp @59.9401Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 31: 640 x 480 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 32: 640 x 480 x 32bpp @75Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 33: 640 x 480 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 34: 640 x 480 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 35: 640 x 480 x 32bpp @75Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 36: 640 x 480 x 32bpp @75Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 37: 800 x 600 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 38: 800 x 600 x 32bpp @75Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 39: 800 x 600 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 40: 800 x 600 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 41: 800 x 600 x 32bpp @75Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 42: 800 x 600 x 32bpp @75Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 43: 1024 x 768 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 44: 1024 x 768 x 32bpp @75Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 45: 1024 x 768 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 46: 1024 x 768 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 47: 1024 x 768 x 32bpp @75Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 48: 1024 x 768 x 32bpp @75Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 49: 1152 x 864 x 32bpp @75Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 50: 1152 x 864 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 51: 1152 x 864 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 52: 1152 x 864 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 53: 1152 x 864 x 32bpp @75Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 54: 1152 x 864 x 32bpp @75Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 55: 1280 x 600 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 56: 1280 x 600 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 57: 1280 x 600 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 58: 1280 x 600 x 32bpp @75Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 59: 1280 x 600 x 32bpp @75Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 60: 1280 x 600 x 32bpp @75Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 61: 1280 x 720 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 62: 1280 x 720 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 63: 1280 x 720 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 64: 1280 x 720 x 32bpp @75Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 65: 1280 x 720 x 32bpp @75Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 66: 1280 x 720 x 32bpp @75Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 67: 1280 x 768 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 68: 1280 x 768 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 69: 1280 x 768 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 70: 1280 x 768 x 32bpp @75Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 71: 1280 x 768 x 32bpp @75Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 72: 1280 x 768 x 32bpp @75Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 73: 1280 x 800 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 74: 1280 x 800 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 75: 1280 x 800 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 76: 1280 x 800 x 32bpp @75Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 77: 1280 x 800 x 32bpp @75Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 78: 1280 x 800 x 32bpp @75Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 79: 1280 x 960 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 80: 1280 x 960 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 81: 1280 x 960 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 82: 1280 x 960 x 32bpp @75Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 83: 1280 x 960 x 32bpp @75Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 84: 1280 x 960 x 32bpp @75Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 85: 1280 x 1024 x 32bpp @60Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 30 - "Mode 86: 1280 x 1024 x 32bpp @75Hz"
2022-08-14 23:06:20 [ INFO ] DisplayManager.cpp @ 40 - "DisplayManager : Current Display Mode on Display #0 is 1920 x 1080 x 32bpp @60Hz"
2022-08-14 23:06:20 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: Set property: video-sync="audio" -> 1
2022-08-14 23:06:20 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: Set property: hwdec="auto" -> 1
2022-08-14 23:06:20 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: Set property: videotoolbox-format="nv12" -> -3
2022-08-14 23:06:20 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: Set property: deinterlace="no" -> 1
2022-08-14 23:06:20 [ WARN ] PlayerComponent.cpp @ 592 - cplayer: Setting the display-fps property is deprecated; set the override-display-fps property instead.
2022-08-14 23:06:20 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: Set property: display-fps=60.000000 -> 1
2022-08-14 23:06:20 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: Set property: audio-delay=0.000000 -> 1
2022-08-14 23:06:20 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: Set property: cache=76800 -> -4
2022-08-14 23:06:20 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: Set property: video-unscaled=false -> 1
2022-08-14 23:06:20 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: Set property: video-aspect="-1" -> 1
2022-08-14 23:06:20 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: Set property: keepaspect=true -> 1
2022-08-14 23:06:20 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: Set property: panscan=0.000000 -> 1
2022-08-14 23:06:20 [ DEBUG ] PowerComponent.cpp @ 53 - Enabling OS screensaver
2022-08-14 23:06:20 [ INFO ] JS: media error: loading failed
2022-08-14 23:06:20 [ INFO ] JS: Autofocus processing was blocked because a document's URL has a fragment '#!/details?id=efcabe38a2cd1aae0e932ad3d3c42e6f&serverId=6ec303d0c4ed4e4fa92453bc6f4827ef'.
2022-08-14 23:06:21 [ DEBUG ] Log.cpp @ 29 - "qrc:/ui/webview.qml:178:onLoadingChanged: WebEngineLoadRequest starting: file:///C:/Program Files/Jellyfin/Jellyfin Media Player/web-client/desktop/index.html#!/dialog?dlg=dlg1660536380736"
2022-08-14 23:06:23 [ DEBUG ] Log.cpp @ 29 - "qrc:/ui/webview.qml:178:onLoadingChanged: WebEngineLoadRequest starting: file:///C:/Program Files/Jellyfin/Jellyfin Media Player/web-client/desktop/index.html#!/details?id=efcabe38a2cd1aae0e932ad3d3c42e6f&serverId=6ec303d0c4ed4e4fa92453bc6f4827ef"
2022-08-14 23:06:23 [ DEBUG ] Log.cpp @ 29 - "qrc:/ui/webview.qml:178:onLoadingChanged: WebEngineLoadRequest starting: file:///C:/Program Files/Jellyfin/Jellyfin Media Player/web-client/desktop/index.html#!/details?id=efcabe38a2cd1aae0e932ad3d3c42e6f&serverId=6ec303d0c4ed4e4fa92453bc6f4827ef"
2022-08-14 23:06:23 [ INFO ] JS: playbackmanager playback error type: mediadecodeerror
2022-08-14 23:06:23 [ INFO ] JS: Requesting url without automatic networking: http://garnet.local:8096/Sessions/Playing/Stopped
2022-08-14 23:06:23 [ INFO ] JS: nowplaying event: playbackstop
2022-08-14 23:06:23 [ DEBUG ] PowerComponent.cpp @ 53 - Enabling OS screensaver
2022-08-14 23:06:23 [ DEBUG ] Log.cpp @ 29 - "qrc:/ui/webview.qml:178:onLoadingChanged: WebEngineLoadRequest starting: file:///C:/Program Files/Jellyfin/Jellyfin Media Player/web-client/desktop/index.html#!/dialog?dlg=dlg1660536383400"
2022-08-14 23:06:26 [ DEBUG ] Log.cpp @ 29 - "qrc:/ui/webview.qml:178:onLoadingChanged: WebEngineLoadRequest starting: file:///C:/Program Files/Jellyfin/Jellyfin Media Player/web-client/desktop/index.html#!/details?id=efcabe38a2cd1aae0e932ad3d3c42e6f&serverId=6ec303d0c4ed4e4fa92453bc6f4827ef"
2022-08-14 23:06:26 [ DEBUG ] Log.cpp @ 29 - "qrc:/ui/webview.qml:178:onLoadingChanged: WebEngineLoadRequest starting: file:///C:/Program Files/Jellyfin/Jellyfin Media Player/web-client/desktop/index.html#!/details?id=efcabe38a2cd1aae0e932ad3d3c42e6f&serverId=6ec303d0c4ed4e4fa92453bc6f4827ef"
2022-08-14 23:06:28 [ INFO ] JS: Requesting http://garnet.local:8096/Users/804b24af32aa47b09fa2e92e508f68e7/Items/b69c2ee95b75ce90531c018d5e4a280b
2022-08-14 23:06:28 [ INFO ] JS: Requesting http://garnet.local:8096/Shows/25c03a7fb102fc27a97d77bbfef5e7e9/Episodes?IsVirtualUnaired=false&IsMissing=false&UserId=804b24af32aa47b09fa2e92e508f68e7&Fields=Chapters
2022-08-14 23:06:28 [ INFO ] JS: Requesting http://garnet.local:8096/Users/804b24af32aa47b09fa2e92e508f68e7/Items/b69c2ee95b75ce90531c018d5e4a280b/Intros
2022-08-14 23:06:28 [ INFO ] JS: Requesting url without automatic networking: http://garnet.local:8096/Items/b69c2ee95b75ce90531c018d5e4a280b/PlaybackInfo?UserId=804b24af32aa47b09fa2e92e508f68e7&StartTimeTicks=0&IsPlayback=true&AutoOpenLiveStream=true&MaxStreamingBitrate=140000000
2022-08-14 23:06:28 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: Set property: sub-delay=0.000000 -> 1
2022-08-14 23:06:28 [ DEBUG ] PowerComponent.cpp @ 58 - Disabling OS screensaver
2022-08-14 23:06:28 [ INFO ] JS: playing url: http://garnet.local:8096/Videos/b69c2ee95b75ce90531c018d5e4a280b/stream.mkv?Static=true&mediaSourceId=b69c2ee95b75ce90531c018d5e4a280b&deviceId=SmVsbHlmaW5NZWRpYVBsYXllciAxLjcuMCAod2luZG93cy14ODZfNjQgMTApfDE2NTUwOTUxODc4Njk1&api_key=xxxxxxxxxxxxxxxxxxxx1a6786404a22&Tag=5eaa969eda63c319d13edebf50acb5bb
2022-08-14 23:06:28 [ DEBUG ] PlayerComponent.cpp @ 360 - Video framerate: 30 fps
2022-08-14 23:06:28 [ DEBUG ] PlayerComponent.cpp @ 364 - Not switching refresh-rate (disabled by settings).
2022-08-14 23:06:28 [ INFO ] PlayerComponent.cpp @ 655 - checking codecs
2022-08-14 23:06:28 [ INFO ] CodecsComponent.cpp @ 1219 - Using system audio decoders: true
2022-08-14 23:06:28 [ INFO ] CodecsComponent.cpp @ 1220 - Using system video decoders: false
2022-08-14 23:06:28 [ INFO ] CodecsComponent.cpp @ 1223 - Not using on-demand codecs.
2022-08-14 23:06:28 [ INFO ] PlayerComponent.cpp @ 657 - resuming loading
2022-08-14 23:06:28 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: Set property: video-sync="audio" -> 1
2022-08-14 23:06:28 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: Set property: hwdec="auto" -> 1
2022-08-14 23:06:28 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: Set property: videotoolbox-format="nv12" -> -3
2022-08-14 23:06:28 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: Set property: deinterlace="no" -> 1
2022-08-14 23:06:28 [ WARN ] PlayerComponent.cpp @ 592 - cplayer: Setting the display-fps property is deprecated; set the override-display-fps property instead.
2022-08-14 23:06:28 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: Set property: display-fps=60.000000 -> 1
2022-08-14 23:06:28 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: Set property: audio-delay=0.000000 -> 1
2022-08-14 23:06:28 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: Set property: cache=76800 -> -4
2022-08-14 23:06:28 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: Set property: video-unscaled=false -> 1
2022-08-14 23:06:28 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: Set property: video-aspect="-1" -> 1
2022-08-14 23:06:28 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: Set property: keepaspect=true -> 1
2022-08-14 23:06:28 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: Set property: panscan=0.000000 -> 1
2022-08-14 23:06:28 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: Setting option 'ad' = '' (flags = 16)
2022-08-14 23:06:28 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: Setting option 'aid' = 'no' (flags = 16)
2022-08-14 23:06:28 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: Setting option 'pause' = 'no' (flags = 16)
2022-08-14 23:06:28 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: Setting option 'sid' = 'no' (flags = 16)
2022-08-14 23:06:28 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: Setting option 'user-agent' = 'JellyfinMediaPlayer' (flags = 16)
2022-08-14 23:06:28 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: Setting option 'vd' = '' (flags = 16)
2022-08-14 23:06:28 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: Running hook: main/on_load
2022-08-14 23:06:28 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: Set property: ad="" -> 1
2022-08-14 23:06:28 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: Set property: vd="" -> 1
2022-08-14 23:06:28 [ DEBUG ] PlayerComponent.cpp @ 588 - ffmpeg: Opening http://garnet.local:8096/Videos/b69c2ee95b75ce90531c018d5e4a280b/stream.mkv?Static=true&mediaSourceId=b69c2ee95b75ce90531c018d5e4a280b&deviceId=SmVsbHlmaW5NZWRpYVBsYXllciAxLjcuMCAod2luZG93cy14ODZfNjQgMTApfDE2NTUwOTUxODc4Njk1&api_key=xxxxxxxxxxxxxxxxxxxx1a6786404a22&Tag=5eaa969eda63c319d13edebf50acb5bb
2022-08-14 23:06:28 [ INFO ] PlayerComponent.cpp @ 460 - Entering state: buffering
2022-08-14 23:06:28 [ INFO ] JS: Active player: {"name":"MPV Video Player","id":"mpvvideoplayer","playerName":"MPV Video Player","playableMediaTypes":[false,true,false,false],"isLocalPlayer":true,"supportedCommands":["GoHome","GoToSettings","VolumeUp","VolumeDown","Mute","Unmute","ToggleMute","SetVolume","SetAudioStreamIndex","SetSubtitleStreamIndex","SetMaxStreamingBitrate","DisplayContent","GoToSearch","DisplayMessage","SetRepeatMode","SetShuffleQueue","PlayMediaSource","PlayTrailers","ToggleFullscreen","PlaybackRate"]}
2022-08-14 23:06:28 [ INFO ] JS: SyncPlay WrapperFactory getWrapper: htmlvideoplayer
2022-08-14 23:06:28 [ INFO ] JS: Requesting url without automatic networking: http://garnet.local:8096/Sessions/Playing
2022-08-14 23:06:28 [ INFO ] JS: nowplaying event: playbackstart
2022-08-14 23:06:28 [ INFO ] JS: nowplaying event: playbackstart
2022-08-14 23:06:28 [ INFO ] JS: Sending web socket message: KeepAlive
2022-08-14 23:06:28 [ INFO ] JS: Received KeepAlive from server.
2022-08-14 23:06:28 [ DEBUG ] PlayerComponent.cpp @ 588 - ffmpeg: Could not set AVOption tls_verify='0'
2022-08-14 23:06:28 [ DEBUG ] PlayerComponent.cpp @ 588 - ffmpeg: Mime-type: 'video/x-matroska'
2022-08-14 23:06:28 [ DEBUG ] PlayerComponent.cpp @ 588 - demux: Trying demuxers for level=normal.
2022-08-14 23:06:28 [ DEBUG ] PlayerComponent.cpp @ 588 - mkv: Deferring reading cues.
2022-08-14 23:06:28 [ DEBUG ] PlayerComponent.cpp @ 588 - mkv: All headers are parsed!
2022-08-14 23:06:28 [ DEBUG ] PlayerComponent.cpp @ 588 - demux: Detected file format: Matroska
2022-08-14 23:06:28 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: Opening done: http://garnet.local:8096/Videos/b69c2ee95b75ce90531c018d5e4a280b/stream.mkv?Static=true&mediaSourceId=b69c2ee95b75ce90531c018d5e4a280b&deviceId=SmVsbHlmaW5NZWRpYVBsYXllciAxLjcuMCAod2luZG93cy14ODZfNjQgMTApfDE2NTUwOTUxODc4Njk1&api_key=xxxxxxxxxxxxxxxxxxxx1a6786404a22&Tag=5eaa969eda63c319d13edebf50acb5bb
2022-08-14 23:06:30 [ INFO ] CodecsComponent.cpp @ 1219 - Using system audio decoders: true
2022-08-14 23:06:30 [ INFO ] CodecsComponent.cpp @ 1220 - Using system video decoders: false
2022-08-14 23:06:30 [ INFO ] CodecsComponent.cpp @ 1223 - Not using on-demand codecs.
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: Running hook: main/on_preloaded
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - ffmpeg: Opening http://garnet.local:8096/Videos/b69c2ee9-5b75-ce90-531c-018d5e4a280b/b69c2ee95b75ce90531c018d5e4a280b/Subtitles/2/0/Stream.srt?api_key=xxxxxxxxxxxxxxxxxxxx1a6786404a22
2022-08-14 23:06:30 [ ERROR ] PlayerComponent.cpp @ 594 - ffmpeg: tcp: Failed to resolve hostname garnet.local: The name does not resolve for the supplied parameters
2022-08-14 23:06:30 [ ERROR ] PlayerComponent.cpp @ 594 - stream: Failed to open http://garnet.local:8096/Videos/b69c2ee9-5b75-ce90-531c-018d5e4a280b/b69c2ee95b75ce90531c018d5e4a280b/Subtitles/2/0/Stream.srt?api_key=xxxxxxxxxxxxxxxxxxxx1a6786404a22.
2022-08-14 23:06:30 [ ERROR ] PlayerComponent.cpp @ 594 - cplayer: Can not open external file http://garnet.local:8096/Videos/b69c2ee9-5b75-ce90-531c-018d5e4a280b/b69c2ee95b75ce90531c018d5e4a280b/Subtitles/2/0/Stream.srt?api_key=xxxxxxxxxxxxxxxxxxxx1a6786404a22.
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: Set property: sid="no" -> 1
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: Set property: aid="1" -> 1
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: Set property: ad="vp9,aac" -> 1
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: Set property: vd="vp9,aac" -> 1
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - mkv: select track 0
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - mkv: select track 1
2022-08-14 23:06:30 [ INFO ] PlayerComponent.cpp @ 590 - cplayer: (+) Video --vid=1 (*) (vp9 960x720 30.000fps)
2022-08-14 23:06:30 [ INFO ] PlayerComponent.cpp @ 590 - cplayer: (+) Audio --aid=1 (*) (aac 2ch 44100Hz)
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - vo/libmpv: Assuming 60.000000 FPS for display sync.
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - vd: Container reported FPS: 30.000000
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - vd: Codec list:
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - vd: vp9 - Google VP9
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - vd: vp9 - Google VP9
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - vd: libvpx-vp9 (vp9) - libvpx VP9
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - vd: vp9_cuvid (vp9) - Nvidia CUVID VP9 decoder
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - vd: vp9_qsv (vp9) - VP9 video (Intel Quick Sync Video acceleration)
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - vd: Opening decoder vp9
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - vd: Looking at hwdec vp9-d3d11va...
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - vd: Could not create device.
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - vd: Looking at hwdec vp9-dxva2...
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - vd: Trying hardware decoding via vp9-dxva2.
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - vd: Selected codec: vp9 (Google VP9)
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - vf: User filter list:
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - vf: (empty)
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - ad: Codec list:
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - ad: aac - AAC (Advanced Audio Coding)
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - ad: aac - AAC (Advanced Audio Coding)
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - ad: aac_fixed (aac) - AAC (Advanced Audio Coding)
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - ad: Opening decoder aac
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - ad: Requesting 1 threads for decoding.
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - ad: Selected codec: aac (AAC (Advanced Audio Coding))
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - af: User filter list:
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - af: (empty)
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: Starting playback...
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - af: [in] 44100Hz stereo 2ch floatp
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - af: [userspeed] 44100Hz stereo 2ch floatp
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - af: [userspeed] (disabled)
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - af: [convert] 44100Hz stereo 2ch floatp
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - vd: Pixel formats supported by decoder: dxva2_vld d3d11va_vld d3d11 cuda yuv420p
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - vd: Codec profile: Profile 0 (0x0)
2022-08-14 23:06:30 [ INFO ] JS: Requesting url without automatic networking: http://garnet.local:8096/Sessions/Playing/Progress
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - vd: Requesting pixfmt 'dxva2_vld' from decoder.
2022-08-14 23:06:30 [ ERROR ] PlayerComponent.cpp @ 594 - ffmpeg/video: vp9: Failed setup for format dxva2_vld: hwaccel initialisation returned error.
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - vd: Pixel formats supported by decoder: d3d11va_vld d3d11 cuda yuv420p yuv420p
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - vd: Codec profile: Profile 0 (0x0)
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - vd: Requesting pixfmt 'yuv420p' from decoder.
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - vd: Falling back to software decoding.
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - vd: Detected 8 logical cores.
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - vd: Requesting 9 threads for decoding.
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - vd: DR failed - disabling.
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - vd: Using software decoding.
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - vd: Decoder format: 960x720 [0:1] yuv420p auto/auto/auto/limited/auto CL=unknown
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - vd: Using container aspect ratio.
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - vf: [in] 960x720 yuv420p bt.709/bt.709/bt.1886/limited/display SP=1.000000 CL=mpeg2/4/h264
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - vf: [userdeint] 960x720 yuv420p bt.709/bt.709/bt.1886/limited/display SP=1.000000 CL=mpeg2/4/h264
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - vf: [userdeint] (disabled)
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - vf: [autorotate] 960x720 yuv420p bt.709/bt.709/bt.1886/limited/display SP=1.000000 CL=mpeg2/4/h264
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - vf: [autorotate] (disabled)
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - vf: [convert] 960x720 yuv420p bt.709/bt.709/bt.1886/limited/display SP=1.000000 CL=mpeg2/4/h264
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - vf: [convert] (disabled)
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - vf: [out] 960x720 yuv420p bt.709/bt.709/bt.1886/limited/display SP=1.000000 CL=mpeg2/4/h264
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - ao: Trying audio driver 'wasapi'
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - ao/wasapi: requested format: 44100 Hz, stereo channels, floatp
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - ao/wasapi: No device specified. Selecting default.
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - ao/wasapi: Selecting device '{897487a5-1908-4875-bd5c-e0afde7b425e}' (Speakers (Realtek High Definition Audio))
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - ao/wasapi: Monitoring changes in device {0.0.0.00000000}.{897487a5-1908-4875-bd5c-e0afde7b425e}
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - ao/wasapi: Trying stereo float (32/32 bits) @ 48000hz (shared) -> ok
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - ao/wasapi: Accepted as stereo float @ 48000hz -> stereo float (32/32 bits) @ 48000hz (shared)
2022-08-14 23:06:30 [ DEBUG ] PlayerComponent.cpp @ 588 - ao/wasapi: Device period: 10 ms
2022-08-14 23:06:31 [ DEBUG ] PlayerComponent.cpp @ 588 - ao/wasapi: Buffer frame count: 2400 (50 ms)
2022-08-14 23:06:31 [ DEBUG ] PlayerComponent.cpp @ 588 - ao/wasapi: IAudioClock::GetFrequency gave a frequency of 384000.
2022-08-14 23:06:31 [ DEBUG ] PlayerComponent.cpp @ 588 - ao/wasapi: device buffer: 2400 samples.
2022-08-14 23:06:31 [ DEBUG ] PlayerComponent.cpp @ 588 - ao/wasapi: using soft-buffer of 9600 samples.
2022-08-14 23:06:31 [ INFO ] PlayerComponent.cpp @ 590 - cplayer: AO: [wasapi] 48000Hz stereo 2ch float
2022-08-14 23:06:31 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: AO: Description: Windows WASAPI audio output (event mode)
2022-08-14 23:06:31 [ DEBUG ] PlayerComponent.cpp @ 588 - autoconvert: inserting resampler
2022-08-14 23:06:31 [ DEBUG ] PlayerComponent.cpp @ 588 - swresample: format change, reinitializing resampler
2022-08-14 23:06:31 [ DEBUG ] PlayerComponent.cpp @ 588 - swresample: 44100Hz stereo floatp -> 48000Hz stereo float
2022-08-14 23:06:31 [ DEBUG ] PlayerComponent.cpp @ 588 - af: [out] 48000Hz stereo 2ch float
2022-08-14 23:06:31 [ INFO ] PlayerComponent.cpp @ 590 - cplayer: VO: [libmpv] 960x720 yuv420p
2022-08-14 23:06:31 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: VO: Description: render API for libmpv
2022-08-14 23:06:31 [ DEBUG ] PlayerComponent.cpp @ 588 - vo/libmpv: reconfig to 960x720 yuv420p bt.709/bt.709/bt.1886/limited/display SP=1.000000 CL=mpeg2/4/h264
2022-08-14 23:06:31 [ DEBUG ] PlayerComponent.cpp @ 588 - libmpv_render: Window size: 1280x961 (Borders: l=0 t=0 r=0 b=0)
2022-08-14 23:06:31 [ DEBUG ] PlayerComponent.cpp @ 588 - libmpv_render: Video source: 960x720 (1:1)
2022-08-14 23:06:31 [ DEBUG ] PlayerComponent.cpp @ 588 - libmpv_render: Video display: (0, 0) 960x720 -> (0, 0) 1280x960
2022-08-14 23:06:31 [ DEBUG ] PlayerComponent.cpp @ 588 - libmpv_render: Video scale: 1.333333/1.333333
2022-08-14 23:06:31 [ DEBUG ] PlayerComponent.cpp @ 588 - libmpv_render: OSD borders: l=0 t=0 r=0 b=1
2022-08-14 23:06:31 [ DEBUG ] PlayerComponent.cpp @ 588 - libmpv_render: Video borders: l=0 t=0 r=0 b=1
2022-08-14 23:06:31 [ DEBUG ] PlayerComponent.cpp @ 588 - libmpv_render: Texture for plane 0: 960x720
2022-08-14 23:06:31 [ DEBUG ] PlayerComponent.cpp @ 588 - libmpv_render: Texture for plane 1: 480x360
2022-08-14 23:06:31 [ DEBUG ] PlayerComponent.cpp @ 588 - libmpv_render: Texture for plane 2: 480x360
2022-08-14 23:06:31 [ DEBUG ] PlayerComponent.cpp @ 588 - libmpv_render: Testing FBO format rgba16f
2022-08-14 23:06:31 [ DEBUG ] PlayerComponent.cpp @ 588 - libmpv_render: Using FBO format rgba16f.
2022-08-14 23:06:31 [ DEBUG ] PlayerComponent.cpp @ 588 - libmpv_render: No advanced processing required. Enabling dumb mode.
2022-08-14 23:06:31 [ DEBUG ] PlayerComponent.cpp @ 588 - libmpv_render: shader link log (status=1):
2022-08-14 23:06:31 [ DEBUG ] PlayerComponent.cpp @ 588 - libmpv_render:
2022-08-14 23:06:31 [ DEBUG ] PlayerComponent.cpp @ 588 - libmpv_render:
2022-08-14 23:06:31 [ DEBUG ] PlayerComponent.cpp @ 588 - libmpv_render:
2022-08-14 23:06:31 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: first video frame after restart shown
2022-08-14 23:06:31 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: Set property: video-unscaled=false -> 1
2022-08-14 23:06:31 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: Set property: video-aspect="-1" -> 1
2022-08-14 23:06:31 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: Set property: keepaspect=true -> 1
2022-08-14 23:06:31 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: Set property: panscan=0.000000 -> 1
2022-08-14 23:06:31 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: audio ready
2022-08-14 23:06:31 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: starting audio playback
2022-08-14 23:06:31 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: playback restart complete @ 0.000000, audio=playing, video=playing
2022-08-14 23:06:31 [ INFO ] PlayerComponent.cpp @ 456 - Entering state: playing
2022-08-14 23:06:31 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: Set property: volume=100 -> 1
2022-08-14 23:06:31 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: Set property: speed=1.000000 -> 1
2022-08-14 23:06:31 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: Set property: mute=false -> 1
2022-08-14 23:06:31 [ INFO ] JS: processing path request: /c:/program files/jellyfin/jellyfin media player/web-client/desktop#!/video
2022-08-14 23:06:31 [ INFO ] JS: user is authenticated
2022-08-14 23:06:31 [ INFO ] JS: proceeding to page: /c:/program files/jellyfin/jellyfin media player/web-client/desktop#!/video
2022-08-14 23:06:31 [ DEBUG ] Log.cpp @ 29 - "qrc:/ui/webview.qml:178:onLoadingChanged: WebEngineLoadRequest starting: file:///C:/Program Files/Jellyfin/Jellyfin Media Player/web-client/desktop/index.html#!/video"
2022-08-14 23:06:31 [ INFO ] JS: Translation key is missing from dictionary: AirPlay
2022-08-14 23:06:31 [ DEBUG ] PlayerComponent.cpp @ 588 - mkv: EOF reached.
2022-08-14 23:06:38 [ DEBUG ] PlayerComponent.cpp @ 588 - cplayer: Set property: pause=yes -> 1
2022-08-14 23:06:38 [ INFO ] PlayerComponent.cpp @ 452 - Entering state: paused
2022-08-14 23:06:38 [ INFO ] JS: Requesting url without automatic networking: http://garnet.local:8096/Sessions/Playing/Progress
2022-08-14 23:06:38 [ DEBUG ] KonvergoWindow.cpp @ 479 - QWindow visibility set to QWindow::Minimized
2022-08-14 23:06:48 [ INFO ] JS: Requesting url without automatic networking: http://garnet.local:8096/Sessions/Playing/Progress
2022-08-14 23:06:58 [ INFO ] JS: Requesting url without automatic networking: http://garnet.local:8096/Sessions/Playing/Progress
2022-08-14 23:06:58 [ INFO ] JS: Sending web socket message: KeepAlive
2022-08-14 23:06:58 [ INFO ] JS: Received KeepAlive from server.
2022-08-14 23:07:08 [ INFO ] JS: Requesting url without automatic networking: http://garnet.local:8096/Sessions/Playing/Progress
2022-08-14 23:07:18 [ INFO ] JS: Requesting url without automatic networking: http://garnet.local:8096/Sessions/Playing/Progress
2022-08-14 23:07:28 [ INFO ] JS: Requesting url without automatic networking: http://garnet.local:8096/Sessions/Playing/Progress
2022-08-14 23:07:28 [ INFO ] JS: Sending web socket message: KeepAlive
2022-08-14 23:07:28 [ INFO ] JS: Received KeepAlive from server.
2022-08-14 23:07:38 [ INFO ] JS: Requesting url without automatic networking: http://garnet.local:8096/Sessions/Playing/Progress
2022-08-14 23:07:48 [ INFO ] JS: Requesting url without automatic networking: http://garnet.local:8096/Sessions/Playing/Progress
2022-08-14 23:07:58 [ INFO ] JS: Requesting url without automatic networking: http://garnet.local:8096/Sessions/Playing/Progress
2022-08-14 23:07:58 [ INFO ] JS: Sending web socket message: KeepAlive
2022-08-14 23:07:58 [ INFO ] JS: Received KeepAlive from server.
Same issue on my side, on my Windows and my Linux. Occurred after upgrading my server from 10.7.7 to 10.8.3, for any encoding (H/x264 SDR, HEVC SDR, HEVC HDR, ...)
Fixed after updating my server to 10.8.4 and/or restarting my server, dunno why :man_shrugging:
FYI, I run my server with the official Docker image, maybe the 10.8.3 image was broken
I believe there was some rather significant breakage in the 10.8.3 release. It should be safe to close this.
I am still experiencing this with the latest version of the official docker image, running server 10.9.0 @iwalton3 please do not close, this issue is not resolved.
This definitely seems to be an issue with the player and not the server, as the working and non-working machines are both connecting to the same server.
This definitely seems to be an issue with the player and not the server, as the working and non-working machines are both connecting to the same server.
On my side I didn't changed the clients. The only thing I did was upgrading from 10.8.3 to 10.8.4
Can you downgrade your server to 10.8.4 to see if it persists?
Yes, same issue with jellyfin/jellyfin:10.8.4
.
Note that the "retry with transcode" option does work as expected, but my hosting box isn't powerful enough to transcode at full speed.
help me
[2023-03-28 23:06:44.041 +07:00] [INF] [18] Jellyfin.Api.Helpers.MediaInfoHelper: User policy for "kurikuri". EnablePlaybackRemuxing: True EnableVideoPlaybackTranscoding: True EnableAudioPlaybackTranscoding: True
[2023-03-28 23:06:44.042 +07:00] [INF] [18] Jellyfin.Api.Helpers.MediaInfoHelper: StreamBuilder.BuildVideoItem( Profile="Jellyfin Media Player", Path="/mnt/hgfs/TD-JELLY/K-JELLY/ANIME-COMPLETED/DISK-04/Seitokai Yakuindomo/Season 01/Seitokai Yakuindomo S01E03.mp4", AudioStreamIndex=null, SubtitleStreamIndex=null ) => ( PlayMethod=DirectPlay, TranscodeReason=0 ) "media:/videos/56290404-d228-b6ff-d53b-e6241bab4e81/stream.mov?MediaSourceId=56290404d228b6ffd53be6241bab4e81&Static=true&VideoCodec=h264&AudioCodec=aac&AudioStreamIndex=1&api_key=<token>&Tag=c444ecc3803786a4062f61dc2b2d13de"
[2023-03-28 23:06:44.432 +07:00] [ERR] [27] Microsoft.AspNetCore.Server.Kestrel: Connection id ""0HMPFJHJREF8M"", Request id ""0HMPFJHJREF8M:00000002"": An unhandled exception was thrown by the application.
System.InvalidOperationException: Response Content-Length mismatch: too few bytes written (1128976 of 385903022).
[2023-03-28 23:06:46.801 +07:00] [ERR] [17] Microsoft.AspNetCore.Server.Kestrel: Connection id ""0HMPFJHJREF92"", Request id ""0HMPFJHJREF92:00000002"": An unhandled exception was thrown by the application.
System.InvalidOperationException: Response Content-Length mismatch: too few bytes written (0 of 384774046).
[2023-03-28 23:06:48.031 +07:00] [ERR] [17] Microsoft.AspNetCore.Server.Kestrel: Connection id ""0HMPFJHJREF94"", Request id ""0HMPFJHJREF94:00000002"": An unhandled exception was thrown by the application.
System.InvalidOperationException: Response Content-Length mismatch: too few bytes written (0 of 384774046).
[2023-03-28 23:06:48.862 +07:00] [WRN] [3] Jellyfin.Server.Middleware.ResponseTimeMiddleware: Slow HTTP Response from "http://stream.kuhaku.id:8000/Items/415a5ba34be62b3aac24857cf4f36265/Images/Backdrop/0?tag=9c611e87149e4aee0a67184e8f8ec99e&maxWidth=1920&quality=80" to "10.10.10.100" in 0:00:01.3467965 with Status Code 200
[2023-03-28 23:06:51.291 +07:00] [ERR] [42] Microsoft.AspNetCore.Server.Kestrel: Connection id ""0HMPFJHJREF9C"", Request id ""0HMPFJHJREF9C:00000002"": An unhandled exception was thrown by the application.
System.InvalidOperationException: Response Content-Length mismatch: too few bytes written (0 of 384774046).
[2023-03-28 23:06:57.232 +07:00] [INF] [9] Emby.Server.Implementations.ScheduledTasks.TaskManager: "IntervalTrigger" fired for task: "Webhook Item Added Notifier"
[2023-03-28 23:06:57.233 +07:00] [INF] [9] Emby.Server.Implementations.ScheduledTasks.TaskManager: Queuing task "ItemAddedScheduledTask"
[2023-03-28 23:06:57.233 +07:00] [INF] [15] Emby.Server.Implementations.ScheduledTasks.TaskManager: Executing "Webhook Item Added Notifier"
[2023-03-28 23:06:57.233 +07:00] [INF] [15] Emby.Server.Implementations.ScheduledTasks.TaskManager: "Webhook Item Added Notifier" Completed after 0 minute(s) and 0 seconds
[2023-03-28 23:06:57.233 +07:00] [INF] [15] Emby.Server.Implementations.ScheduledTasks.TaskManager: ExecuteQueuedTasks
[2023-03-28 23:06:58.527 +07:00] [ERR] [27] Microsoft.AspNetCore.Server.Kestrel: Connection id ""0HMPFJHJREFA6"", Request id ""0HMPFJHJREFA6:00000002"": An unhandled exception was thrown by the application.
System.InvalidOperationException: Response Content-Length mismatch: too few bytes written (0 of 384774046).
[2023-03-28 23:06:58.726 +07:00] [ERR] [33] Microsoft.AspNetCore.Server.Kestrel: Connection id ""0HMPFJHJREFA8"", Request id ""0HMPFJHJREFA8:00000002"": An unhandled exception was thrown by the application.
System.InvalidOperationException: Response Content-Length mismatch: too few bytes written (1128976 of 385903022).
[2023-03-28 23:07:00.018 +07:00] [ERR] [9] Microsoft.AspNetCore.Server.Kestrel: Connection id ""0HMPFJHJREFAC"", Request id ""0HMPFJHJREFAC:00000002"": An unhandled exception was thrown by the application.
System.InvalidOperationException: Response Content-Length mismatch: too few bytes written (0 of 384774046).
[2023-03-28 23:07:01.256 +07:00] [ERR] [9] Microsoft.AspNetCore.Server.Kestrel: Connection id ""0HMPFJHJREFAE"", Request id ""0HMPFJHJREFAE:00000002"": An unhandled exception was thrown by the application.
System.InvalidOperationException: Response Content-Length mismatch: too few bytes written (0 of 384774046).
If you are using symlinks, note that they aren't supported by Jellyfin.
I am seeing this behavior again after it subsided for a while.
If you are using symlinks, note that they aren't supported by Jellyfin.
I don't use symlink, it's directly mounted to /mnt/hgfs/.
I can confirm that I am also not using symlinks.
btw, how to downgrade jellyfin on fedora, i didn't find jellyfin-10.8.9-2.fc37.x86_64 package. i just realized this problem happens when upgrading jellyfin to jellyfin-10.8.9-3.fc37.x86_64. i have another jellyfin server with the same content and it doesn't behave badly. i think this is the impact of jellyfin package update on fedora server 37.
I have not seen this happen for a while. Possibly addressed in an update?
Personally never had this issue again after upgrading to 10.8.4 :shrug: