jellyfin-kodi icon indicating copy to clipboard operation
jellyfin-kodi copied to clipboard

Published 20 hours ago • verified publisher icon jellyfin

Failling playing file-HTTP Response 206 to "HIDDEN-IP". Time (slow)

Open haunter81 opened this issue 4 years ago • 8 comments

Describe the bug Video files failed to open(play) . some are playing , but particular 3 won't play. i tried to play them over plexconnect and browser, and all files are played well.

To Reproduce

  1. tried many times , checked my speed , rebooted the machine , restarted the jellyfin server

Logs

[2020-03-22 19:11:08.892 +01:00] [ERR] Error processing request: "A task was canceled."
[2020-03-22 19:11:08.892 +01:00] [WRN] HTTP Response 206 to "HIDDEN-IP". Time (slow): 0:00:02.2781524. "http://HIDDEN-IP:8096/Videos/8270a5acf9dda46bdcd3e2420c550b6d/stream?static=true&MediaSourceId=8270a5acf9dda46bdcd3e2420c550b6d"
[2020-03-22 19:11:10.297 +01:00] [INF] Processing playback tracker : "5C9C8407F2054CDF9BB9213D0309FF38-4a3a11b090c54e61b9a7a490f04c27da-8270a5acf9dda46bdcd3e2420c550b6d"
[2020-03-22 19:11:11.125 +01:00] [ERR] Error processing request: "The operation was canceled."
[2020-03-22 19:11:11.125 +01:00] [WRN] HTTP Response 206 to "HIDDEN-IP". Time (slow): 0:00:02.8297898. "http://HIDDEN-IP:8096/Videos/8270a5acf9dda46bdcd3e2420c550b6d/stream?static=true&MediaSourceId=8270a5acf9dda46bdcd3e2420c550b6d"
[2020-03-22 19:11:11.967 +01:00] [ERR] Error processing request: "The operation was canceled."
[2020-03-22 19:11:11.967 +01:00] [WRN] HTTP Response 206 to "HIDDEN-IP". Time (slow): 0:00:02.8262726. "http://HIDDEN-IP:8096/Videos/8270a5acf9dda46bdcd3e2420c550b6d/stream?static=true&MediaSourceId=8270a5acf9dda46bdcd3e2420c550b6d"
[2020-03-22 19:11:13.853 +01:00] [ERR] Error processing request: "The operation was canceled."
[2020-03-22 19:11:13.853 +01:00] [WRN] HTTP Response 206 to "HIDDEN-IP". Time (slow): 0:00:02.5318205. "http://HIDDEN-IP:8096/Videos/8270a5acf9dda46bdcd3e2420c550b6d/stream?static=true&MediaSourceId=8270a5acf9dda46bdcd3e2420c550b6d"
[2020-03-22 19:11:14.837 +01:00] [ERR] Error processing request: "The operation was canceled."
[2020-03-22 19:11:14.837 +01:00] [WRN] HTTP Response 206 to "HIDDEN-IP". Time (slow): 0:00:02.766497. "http://HIDDEN-IP:8096/Videos/8270a5acf9dda46bdcd3e2420c550b6d/stream?static=true&MediaSourceId=8270a5acf9dda46bdcd3e2420c550b6d"
[2020-03-22 19:11:17.128 +01:00] [ERR] Error processing request: "The operation was canceled."
[2020-03-22 19:11:17.128 +01:00] [WRN] HTTP Response 206 to "HIDDEN-IP". Time (slow): 0:00:03.0571768. "http://HIDDEN-IP:8096/Videos/8270a5acf9dda46bdcd3e2420c550b6d/stream?static=true&MediaSourceId=8270a5acf9dda46bdcd3e2420c550b6d"
[2020-03-22 19:11:17.750 +01:00] [ERR] Error processing request: "The operation was canceled."
[2020-03-22 19:11:17.750 +01:00] [WRN] HTTP Response 206 to "HIDDEN-IP". Time (slow): 0:00:02.7319792. "http://HIDDEN-IP:8096/Videos/8270a5acf9dda46bdcd3e2420c550b6d/stream?static=true&MediaSourceId=8270a5acf9dda46bdcd3e2420c550b6d"
[2020-03-22 19:11:19.396 +01:00] [ERR] Error processing request: "The operation was canceled."
[2020-03-22 19:11:19.396 +01:00] [WRN] HTTP Response 206 to "HIDDEN-IP". Time (slow): 0:00:01.4706504. "http://HIDDEN-IP:8096/Videos/8270a5acf9dda46bdcd3e2420c550b6d/stream?static=true&MediaSourceId=8270a5acf9dda46bdcd3e2420c550b6d"
[2020-03-22 19:11:19.622 +01:00] [INF] Playback stopped reported by app "Kodi" "0.5.1" playing "Alice Through the Looking Glass". Stopped at "0" ms
[2020-03-22 19:11:19.656 +01:00] [INF] Playback stop tracker found, processing stop : "5C9C8407F2054CDF9BB9213D0309FF38-4a3a11b090c54e61b9a7a490f04c27da-8270a5acf9dda46bdcd3e2420c550b6d"
[2020-03-22 19:11:19.656 +01:00] [INF] PlaybackTracker : Adding Stop Event : 03/22/2020 19:11:19
[2020-03-22 19:11:19.656 +01:00] [INF] Saving playback tracking activity in D

System (please complete the following information):

  • OS: Libreelec
  • Jellyfin Version: [e.g. 10.4.3]
  • Kodi Version: [e.g. 18.5]
  • Addon Version: [e.g. 0.5.1]

haunter81 avatar Mar 22 '20 19:03 haunter81

Your Kodi logs will be a lot more helpful than the server logs will. On Libreelec the log file is located at /root/.kodi/temp/kodi.log. Or you can use the log uploading addon with details here: https://kodi.wiki/view/Log_file/Easy

mcarlton00 avatar Mar 22 '20 20:03 mcarlton00 

2020-03-22 20:08:12.807 T:1049592704  NOTICE: JELLYFIN.downloader -> INFO::jellyfin_kodi/downloader.py:360 ---[ contact MU-TH-UR 6000/GetServerAddress ]
2020-03-22 20:08:12.910 T:1049592704  NOTICE: JELLYFIN.downloader -> INFO::jellyfin_kodi/downloader.py:386 --[ void/12438d98-eda6-47d4-b1ae-47c01a5c7c74 ]
2020-03-22 20:08:13.017 T:1049592704  NOTICE: Previous line repeats 1 times.
2020-03-22 20:08:13.017 T:1049592704  NOTICE: JELLYFIN.objects.actions -> INFO::jellyfin_kodi/objects/actions.py:48 [ play/8270a5acf9dda46bdcd3e2420c550b6d ] Alice Through the Looking Glass
2020-03-22 20:08:13.031 T:1049592704  NOTICE: JELLYFIN.downloader -> INFO::jellyfin_kodi/downloader.py:360 ---[ contact MU-TH-UR 6000/GetToken ]
2020-03-22 20:08:13.134 T:1049592704  NOTICE: JELLYFIN.downloader -> INFO::jellyfin_kodi/downloader.py:386 --[ void/f6aa19d6-cacc-421d-b854-bbb5fb29439e ]
2020-03-22 20:08:13.171 T:1049592704  NOTICE: JELLYFIN.downloader -> INFO::jellyfin_kodi/downloader.py:360 ---[ contact MU-TH-UR 6000/GetPlaybackInfo ]
2020-03-22 20:08:13.275 T:1049592704  NOTICE: JELLYFIN.downloader -> INFO::jellyfin_kodi/downloader.py:386 --[ void/e1a28a57-31ec-4b54-b69c-6389393d4c1a ]
2020-03-22 20:08:13.594 T:1049592704  NOTICE: Previous line repeats 3 times.
2020-03-22 20:08:13.594 T:1049592704  NOTICE: JELLYFIN.helper.playutils -> INFO::jellyfin_kodi/helper/playutils.py:80 {u'PlaySessionId': u'a10add34c40d438e8db70d89a9e1c69e', u'MediaSources': [{u'SupportsDirectS>
2020-03-22 20:08:13.596 T:1049592704  NOTICE: JELLYFIN.helper.playutils -> INFO::jellyfin_kodi/helper/playutils.py:97 Skip source selection.
2020-03-22 20:08:13.619 T:1049592704  NOTICE: JELLYFIN.helper.playutils -> INFO::jellyfin_kodi/helper/playutils.py:202 --[ direct stream ]
2020-03-22 20:08:13.754 T:1049592704  NOTICE: JELLYFIN.downloader -> INFO::jellyfin_kodi/downloader.py:360 ---[ contact MU-TH-UR 6000/GetIntros ]
2020-03-22 20:08:13.790 T:1057985408  NOTICE: JELLYFIN.library -> INFO::jellyfin_kodi/library.py:705 --<[ q:userdata/1275325456 ]
2020-03-22 20:08:13.859 T:1049592704  NOTICE: JELLYFIN.downloader -> INFO::jellyfin_kodi/downloader.py:386 --[ void/fef33e1a-93ad-4d7d-9dfe-d5a262c0e326 ]
2020-03-22 20:08:14.583 T:1380680576  NOTICE: Previous line repeats 4 times.
2020-03-22 20:08:14.583 T:1380680576  NOTICE: JELLYFIN.library -> INFO::jellyfin_kodi/library.py:455 --[ sync/2020-03-22T18:06:13z ]
2020-03-22 20:08:14.776 T:1135448960  NOTICE: VideoInfoScanner: Starting scan ..
2020-03-22 20:08:14.779 T:1135448960  NOTICE: VideoInfoScanner: Finished scan. Scanning for video info took 00:00
2020-03-22 20:08:14.830 T:1545102208  NOTICE: JELLYFIN.monitor -> INFO::jellyfin_kodi/monitor.py:46 -->[ kodi scan/video ]
2020-03-22 20:08:14.838 T:1936921648 WARNING: CGUIMediaWindow::OnMessage - updating in progress
2020-03-22 20:08:14.844 T:1545102208  NOTICE: JELLYFIN.monitor -> INFO::jellyfin_kodi/monitor.py:49 --<[ kodi scan/video ]
2020-03-22 20:08:14.943 T:1049592704  NOTICE: JELLYFIN.default -> INFO::default.py:39 ---<[ default ]
2020-03-22 20:08:15.104 T:1936921648  NOTICE: VideoPlayer::OpenFile: plugin://plugin.video.jellyfin/ccee870ac85b8a30a4e206ee57b82b0e/?dbid=964&mode=play&id=8270a5acf9dda46bdcd3e2420c550b6d&filename=Alice+Throug>
2020-03-22 20:08:15.105 T:1083163520  NOTICE: Creating InputStream
2020-03-22 20:08:15.339 T:1545102208  NOTICE: JELLYFIN.player -> INFO::jellyfin_kodi/player.py:155 -->[ play/8270a5acf9dda46bdcd3e2420c550b6d ] {u'Volume': 100, u'PlaySessionId': u'a10add34c40d438e8db70d89a9e1c>
2020-03-22 20:08:15.710 T:1545102208  NOTICE: JELLYFIN.objects.actions -> INFO::jellyfin_kodi/objects/actions.py:757  [ play ] kodi_id: 964 media: movie
2020-03-22 20:08:16.133 T:1545102208  NOTICE: JELLYFIN.entrypoint.service -> INFO::jellyfin_kodi/entrypoint/service.py:261 [ UserDataChanged ] {u'UserId': HIDDEN: [>
2020-03-22 20:08:16.136 T:1545102208  NOTICE: JELLYFIN.library -> INFO::jellyfin_kodi/library.py:561 ---[ userdata:2 ]
2020-03-22 20:08:16.741 T:1380680576  NOTICE: JELLYFIN.library -> INFO::jellyfin_kodi/library.py:232 -->[ q:download/1153913168 ]
2020-03-22 20:08:17.424 T:1083163520  NOTICE: Creating Demuxer
2020-03-22 20:08:17.609 T:1545102208  NOTICE: JELLYFIN.player -> INFO::jellyfin_kodi/player.py:161 Setting audio: 1 subs: None
2020-03-22 20:08:18.237 T:1049592704  NOTICE: JELLYFIN.downloader -> INFO::jellyfin_kodi/downloader.py:312 --<[ q:download/1153913168 ]
2020-03-22 20:08:18.785 T:1380680576  NOTICE: JELLYFIN.library -> INFO::jellyfin_kodi/library.py:279 -->[ q:userdata/Movie/1251039696 ]
2020-03-22 20:08:21.461 T:1049592704  NOTICE: JELLYFIN.library -> INFO::jellyfin_kodi/library.py:705 --<[ q:userdata/1251039696 ]
2020-03-22 20:08:22.943 T:1380680576  NOTICE: JELLYFIN.library -> INFO::jellyfin_kodi/library.py:455 --[ sync/2020-03-22T18:06:16z ]
2020-03-22 20:08:23.159 T:1227510656  NOTICE: VideoInfoScanner: Starting scan ..
2020-03-22 20:08:23.165 T:1227510656  NOTICE: VideoInfoScanner: Finished scan. Scanning for video info took 00:00
2020-03-22 20:08:23.185 T:1936921648 WARNING: CGUIMediaWindow::OnMessage - updating in progress
2020-03-22 20:08:23.202 T:1545102208  NOTICE: JELLYFIN.monitor -> INFO::jellyfin_kodi/monitor.py:46 -->[ kodi scan/video ]
2020-03-22 20:08:23.216 T:1545102208  NOTICE: JELLYFIN.monitor -> INFO::jellyfin_kodi/monitor.py:49 --<[ kodi scan/video ]
2020-03-22 20:08:44.564 T:1355502464  NOTICE: PLEX.utils: It took 0:00:32.141819 to run the function processing_loop_playstates
2020-03-22 20:08:44.656 T:1355502464  NOTICE: PLEX.utils: It took 0:00:39.330681 to run the function _run
2020-03-22 20:08:44.708 T:1227510656  NOTICE: My Music: Scanning for music info using worker thread, operation took 00:00
2020-03-22 20:08:44.736 T:1936921648 WARNING: CGUIMediaWindow::OnMessage - updating in progress
2020-03-22 20:08:44.794 T:1545102208  NOTICE: JELLYFIN.monitor -> INFO::jellyfin_kodi/monitor.py:46 -->[ kodi scan/music ]
2020-03-22 20:08:44.879 T:1545102208  NOTICE: JELLYFIN.monitor -> INFO::jellyfin_kodi/monitor.py:49 --<[ kodi scan/music ]
2020-03-22 20:08:48.319 T:1083163520   ERROR: Open - Error, could not open file http://HIDDEN-IP/Videos/8270a5acf9dda46bdcd3e2420c550b6d/stream?static=true&MediaSourceId=8270a5acf9dda46bdcd3e2420c550b>
2020-03-22 20:08:48.319 T:1083163520   ERROR: OpenDemuxStream - Error creating demuxer
2020-03-22 20:08:48.319 T:1083163520  NOTICE: CVideoPlayer::OnExit()
2020-03-22 20:08:48.447 T:1936921648  NOTICE: CVideoPlayer::CloseFile()

haunter81 avatar Mar 22 '20 22:03 haunter81

I see references to Plex in your Kodi logs. Assuming you're running PlexKodiConnect then the two addons likely aren't compatible and are clobbering each other's database. To my knowledge, both addons modify the Kodi database directly, so I'm surprised if they're playing nice.

The 3 files that aren't playing properly, is there anything special about them that you can tell? 7.1 audio/4k maybe? And they play correctly in Jellyfin in the browser?

mcarlton00 avatar Mar 22 '20 23:03 mcarlton00

Nothing special about the video files. Two 720p and one 1080p. All played fine through browser in my pc and through Google drive add-on in kodi.

haunter81 avatar Mar 23 '20 09:03 haunter81

also i just tried to deactivate both addons , restart , and enable jellyfin . after that only one out of the three still not playing . also i read this line 2020-03-23 17:24:01.298 T:1339020160 ERROR: Open - Error, could not open file http://HIDDEN-IP8096/Videos/8270a5acf9dda46bdcd3e2420c550b6d/stream?static=true&MediaSourceId=8270a5acf9dda46bdcd3e2420c550b> 2020-03-23 17:24:01.298 T:1339020160 ERROR: OpenDemuxStream - Error creating demuxer is it possible the problem is somewhere there? the file is .avi

haunter81 avatar Mar 23 '20 15:03 haunter81

I've never seen Kodi fail to play a file unless it was corrupted somehow. avi files should be fine. The error itself is coming from Kodi though, not the Jellyfin addon. Can you turn on debug logging in Kodi and try again? Settings -> System -> Logging -> Enable debug logging.

mcarlton00 avatar Mar 24 '20 01:03 mcarlton00

ok , i tried full reinstall jellyfin server "just in case"(latest release) . reinstall jellyfin kodi addon . clear kodi from other addons except google drive one. and still not playing

my log: https://pastebin.com/jgEzp1kZ

haunter81 avatar Mar 29 '20 19:03 haunter81

I'll recommend checking to see if https://github.com/jellyfin/jellyfin/issues/2354 is your problem. I found this issue looking into my issue and eventually found that issue was my problem.

grmrgecko avatar May 04 '22 04:05 grmrgecko