search

jellyfin / jellyfin-kodi

Jellyfin Plugin for Kodi
https://jellyfin.org
GNU General Public License v3.0
808 stars 109 forks source link

After hours of playback, a movie will start with no sound and stop 10s in #700

Open mikul9 opened 1 year ago

mikul9 commented 1 year ago

Describe the bug After playing several videos, when starting a new video, the video begins to play with no audio and stops ~10s in. Stopping and restarting the video produces the same result stopping on (seemingly) the exact same frame. Stopping and starting Kodi does not fix the problem. The Nvidia Shield must be restarted to resolve the issue.

There are no issue when using the Jellyfin app from other devices.

To Reproduce Play a series of videos (probably 3 hours or more). As each one ends, start another and let it play. Eventually the next video that plays will have no sound and stop 10s in.

Expected behavior The video should play with sound all the way through.

Logs

                                                           "Fields": "Overview"
                                                       },
                                                       "type": "GET",
                                                       "url": "http://{jellyfin-server}/Shows/d4ff3ada545968be3ff9216adc8db635/Episodes",
                                                       "headers": {
                                                           "Content-type": "application/json",
                                                           "Accept-Charset": "UTF-8,*",
                                                           "Accept-encoding": "gzip",
                                                           "User-Agent": "Jellyfin-Kodi/0.7.10+py3",
                                                           "x-emby-authorization": "MediaBrowser Client=Kodi, Device=Kodi (192.168.1.105), DeviceId=72d9b309-a8e3-4ae6-bcea-c7f6fe04f876, Version=0.7.10+py3, UserId=60967e4bfe444a11839737d7a03d3571",
                                                           "X-MediaBrowser-Token": "{jellyfin-token}"
                                                       },
                                                       "timeout": 30,
                                                       "verify": true
                                                   }
2022-12-22 22:57:09.811 T:7944     INFO <general>: JELLYFIN.jellyfin_kodi.jellyfin.http -> DEBUG::jellyfin_kodi/jellyfin/http.py:170 ---<[ http ][38 ms]
2022-12-22 22:57:09.819 T:7944     INFO <general>: JELLYFIN.jellyfin_kodi.jellyfin.http -> DEBUG::jellyfin_kodi/jellyfin/http.py:171 {
                                                       "Items": [
                                                           {
                                                               "Name": "Episode 5",
                                                               "ServerId": "90d4f4a8d68d4c98b66be20fe3aef477",
                                                               "Id": "0d4e066d7a26d594427404378143a7e7",
                                                               "HasSubtitles": true,
                                                               "Container": "mkv,webm",
                                                               "PremiereDate": "2013-10-20T00:00:00.0000000Z",
                                                               "ChannelId": null,
                                                               "Overview": "Change is in the air as an exciting opportunity for Alfred creates tension between Ivy and Daisy. Isobel appeals to Violet's charitable nature, but it does not come without a fight, and Branson considers a new future for himself and baby Sybbie.",
                                                               "CommunityRating": 7.9,
                                                               "RunTimeTicks": 29341100032,
                                                               "ProductionYear": 2013,
                                                               "IndexNumber": 5,
                                                               "ParentIndexNumber": 4,
                                                               "IsFolder": false,
                                                               "Type": "Episode",
                                                               "ParentLogoItemId": "d4ff3ada545968be3ff9216adc8db635",
                                                               "ParentBackdropItemId": "d4ff3ada545968be3ff9216adc8db635",
                                                               "ParentBackdropImageTags": [
                                                                   "8fa370cf952bdd4c114d665188144f4f"
                                                               ],
                                                               "UserData": {
                                                                   "PlaybackPositionTicks": 0,
                                                                   "PlayCount": 1,
                                                                   "IsFavorite": false,
                                                                   "LastPlayedDate": "2022-12-12T03:55:24.1647224Z",
                                                                   "Played": true,
                                                                   "Key": "193131004005"
                                                               },
                                                               "SeriesName": "Downton Abbey",
                                                               "SeriesId": "d4ff3ada545968be3ff9216adc8db635",
                                                               "SeasonId": "ef751ceabccbd4cde88d26f294ac7f5f",
                                                               "SeriesPrimaryImageTag": "27e56aff9bb1bbc33a7f08e2fe0ab499",
                                                               "SeasonName": "Season 4",
                                                               "VideoType": "VideoFile",
                                                               "ImageTags": {
                                                                   "Primary": "6ec56ec521ec40f06434a064dbcca77a"
                                                               },
                                                               "BackdropImageTags": [],
                                                               "ParentLogoImageTag": "ca490a14fdd2d8afda46b38ce67109c3",
                                                               "ImageBlurHashes": {
                                                                   "Primary": {
                                                                       "6ec56ec521ec40f06434a064dbcca77a": "WHF}u}~A9ZRjxVIp?vo#M{M{NGRj^+ozRioIRkWB?vtRMyWAWBjF",
                                                                       "27e56aff9bb1bbc33a7f08e2fe0ab499": "d~KUWaxuxuxu~qt7oft7%MofRPj[WBayRjj[j[ayofof"
                                                                   },
                                                                   "Logo": {
                                                                       "ca490a14fdd2d8afda46b38ce67109c3": "OB6RM%j[j[t7WBj[fQWBj[j[fQj[fQj[00ayayRjofayj["
                                                                   },
                                                                   "Thumb": {
                                                                       "bdaac7fd3c3e6659ec741b89c9abdc09": "NdGlbJj^tRtOocxu%jxttRRlM|bI-;WCWBa_axof"
                                                                   },
                                                                   "Backdrop": {
                                                                       "8fa370cf952bdd4c114d665188144f4f": "WD8|eXxuxu%MNbog~WxbkDtRozf+s.ofI:R-xuWYM{kCIpWBxubI"
                                                                   }
                                                               },
                                                               "ParentThumbItemId": "d4ff3ada545968be3ff9216adc8db635",
                                                               "ParentThumbImageTag": "bdaac7fd3c3e6659ec741b89c9abdc09",
                                                               "LocationType": "FileSystem",
                                                               "MediaType": "Video"
                                                           },
                                                           {
                                                               "Name": "Episode 6",
                                                               "ServerId": "90d4f4a8d68d4c98b66be20fe3aef477",
                                                               "Id": "8cf69b1302194a72100b6b7898de3f20",
                                                               "HasSubtitles": true,
                                                               "Container": "mkv,webm",
                                                               "PremiereDate": "2013-10-27T00:00:00.0000000Z",
                                                               "ChannelId": null,
                                                               "Overview": "A birthday party is planned for Robert, but Rose's surprise contribution to the event ruffles feathers above and below stairs. A new farming venture on the Downton estate comes with dramatic repercussions for Mary, and Edith receives more unwelcome news.",
                                                               "CommunityRating": 7.7,
                                                               "RunTimeTicks": 29758169088,
                                                               "ProductionYear": 2013,
                                                               "IndexNumber": 6,
                                                               "ParentIndexNumber": 4,
                                                               "IsFolder": false,
                                                               "Type": "Episode",
                                                               "ParentLogoItemId": "d4ff3ada545968be3ff9216adc8db635",
                                                               "ParentBackdropItemId": "d4ff3ada545968be3ff9216adc8db635",
                                                               "ParentBackdropImageTags": [
                                                                   "8fa370cf952bdd4c114d665188144f4f"
                                                               ],
                                                               "UserData": {
                                                                   "PlaybackPositionTicks": 0,
                                                                   "PlayCount": 1,
                                                                   "IsFavorite": false,
                                                                   "LastPlayedDate": "2022-12-23T03:55:56.6135861Z",
                                                                   "Played": false,
                                                                   "Key": "193131004006"
                                                               },
                                                               "SeriesName": "Downton Abbey",
                                                               "SeriesId": "d4ff3ada545968be3ff9216adc8db635",
                                                               "SeasonId": "ef751ceabccbd4cde88d26f294ac7f5f",
                                                               "SeriesPrimaryImageTag": "27e56aff9bb1bbc33a7f08e2fe0ab499",
                                                               "SeasonName": "Season 4",
                                                               "VideoType": "VideoFile",
                                                               "ImageTags": {
                                                                   "Primary": "7e6559330669ebbf3ceb823714d951ed"
                                                               },
                                                               "BackdropImageTags": [],
                                                               "ParentLogoImageTag": "ca490a14fdd2d8afda46b38ce67109c3",
                                                               "ImageBlurHashes": {
                                                                   "Primary": {
                                                                       "7e6559330669ebbf3ceb823714d951ed": "WSEL7%I:R*W.xZ%1~AWCNHs.oexZ%1azRkjuj[azs.bGj@WVayR+",
                                                                       "27e56aff9bb1bbc33a7f08e2fe0ab499": "d~KUWaxuxuxu~qt7oft7%MofRPj[WBayRjj[j[ayofof"
                                                                   },
                                                                   "Logo": {
                                                                       "ca490a14fdd2d8afda46b38ce67109c3": "OB6RM%j[j[t7WBj[fQWBj[j[fQj[fQj[00ayayRjofayj["
                                                                   },
                                                                   "Thumb": {
                                                                       "bdaac7fd3c3e6659ec741b89c9abdc09": "NdGlbJj^tRtOocxu%jxttRRlM|bI-;WCWBa_axof"
                                                                   },
                                                                   "Backdrop": {
                                                                       "8fa370cf952bdd4c114d665188144f4f": "WD8|eXxuxu%MNbog~WxbkDtRozf+s.ofI:R-xuWYM{kCIpWBxubI"
                                                                   }
                                                               },
                                                               "ParentThumbItemId": "d4ff3ada545968be3ff9216adc8db635",
                                                               "ParentThumbImageTag": "bdaac7fd3c3e6659ec741b89c9abdc09",
                                                               "LocationType": "FileSystem",
                                                               "MediaType": "Video"
                                                           },
                                                           {
                                                               "Name": "Episode 7",
                                                               "ServerId": "90d4f4a8d68d4c98b66be20fe3aef477",
                                                               "Id": "08c92d83d1006bc150a524f44b1c9316",
                                                               "HasSubtitles": true,
                                                               "Container": "mkv,webm",
                                                               "PremiereDate": "2013-11-03T00:00:00.0000000Z",
                                                               "ChannelId": null,
                                                               "Overview": "Cora's playboy brother has got himself into a spot of bother and Robert has to make a trip to America to bail him out. Blake and Mary are forced to put aside their differences to deal with a new farming venture on the estate. When Violet is taken ill, help comes from an unlikely quarter. In spite of Mrs Hughes, Mrs Patmore and Carson's attempts to keep the peace, romantic tensions below stairs cause problems.",
                                                               "CommunityRating": 7.9,
                                                               "RunTimeTicks": 29758169088,
                                                               "ProductionYear": 2013,
                                                               "IndexNumber": 7,
                                                               "ParentIndexNumber": 4,
                                                               "IsFolder": false,
                                                               "Type": "Episode",
                                                               "ParentLogoItemId": "d4ff3ada545968be3ff9216adc8db635",
                                                               "ParentBackdropItemId": "d4ff3ada545968be3ff9216adc8db635",
                                                               "ParentBackdropImageTags": [
                                                                   "8fa370cf952bdd4c114d665188144f4f"
                                                               ],
                                                               "UserData": {
                                                                   "PlaybackPositionTicks": 0,
                                                                   "PlayCount": 0,
                                                                   "IsFavorite": false,
                                                                   "Played": false,
                                                                   "Key": "193131004007"
                                                               },
                                                               "SeriesName": "Downton Abbey",
                                                               "SeriesId": "d4ff3ada545968be3ff9216adc8db635",
                                                               "SeasonId": "ef751ceabccbd4cde88d26f294ac7f5f",
                                                               "SeriesPrimaryImageTag": "27e56aff9bb1bbc33a7f08e2fe0ab499",
                                                               "SeasonName": "Season 4",
                                                               "VideoType": "VideoFile",
                                                               "ImageTags": {
                                                                   "Primary": "6ef34b09980b7ca2dc0bc9f35659d156"
                                                               },
                                                               "BackdropImageTags": [],
                                                               "ParentLogoImageTag": "ca490a14fdd2d8afda46b38ce67109c3",
                                                               "ImageBlurHashes": {
                                                                   "Primary": {
                                                                       "6ef34b09980b7ca2dc0bc9f35659d156": "W48|kd4n%KMy4.^*56tRt8ob9G%L0e-pIUS6-:57MzxWxZn$^+IV",
                                                                       "27e56aff9bb1bbc33a7f08e2fe0ab499": "d~KUWaxuxuxu~qt7oft7%MofRPj[WBayRjj[j[ayofof"
                                                                   },
                                                                   "Logo": {
                                                                       "ca490a14fdd2d8afda46b38ce67109c3": "OB6RM%j[j[t7WBj[fQWBj[j[fQj[fQj[00ayayRjofayj["
                                                                   },
                                                                   "Thumb": {
                                                                       "bdaac7fd3c3e6659ec741b89c9abdc09": "NdGlbJj^tRtOocxu%jxttRRlM|bI-;WCWBa_axof"
                                                                   },
                                                                   "Backdrop": {
                                                                       "8fa370cf952bdd4c114d665188144f4f": "WD8|eXxuxu%MNbog~WxbkDtRozf+s.ofI:R-xuWYM{kCIpWBxubI"
                                                                   }
                                                               },
                                                               "ParentThumbItemId": "d4ff3ada545968be3ff9216adc8db635",
                                                               "ParentThumbImageTag": "bdaac7fd3c3e6659ec741b89c9abdc09",
                                                               "LocationType": "FileSystem",
                                                               "MediaType": "Video"
                                                           }
                                                       ],
                                                       "TotalRecordCount": 3,
                                                       "StartIndex": 0
                                                   }
2022-12-22 22:57:09.842 T:7944     INFO <general>: JELLYFIN.jellyfin_kodi.player -> INFO::jellyfin_kodi/player.py:281 --[ next up ] {'play_info': {'ItemIds': ['08c92d83d1006bc150a524f44b1c9316'], 'ServerId': None, 'PlayCommand': 'PlayNow'}, 'current_episode': {'episodeid': '8cf69b1302194a72100b6b7898de3f20', 'tvshowid': 'd4ff3ada545968be3ff9216adc8db635', 'plot': "A birthday party is planned for Robert, but Rose's surprise contribution to the event ruffles feathers above and below stairs. A new farming venture on the Downton estate comes with dramatic repercussions for Mary, and Edith receives more unwelcome news.", 'showtitle': 'Downton Abbey', 'title': 'Episode 6', 'playcount': 0, 'season': 4, 'episode': 6, 'rating': 7.7, 'firstaired': 2013, 'art': {'tvshow.poster': 'http://{jellyfin-server}/Items/d4ff3ada545968be3ff9216adc8db635/Images/Primary/0?Format=original&Tag=27e56aff9bb1bbc33a7f08e2fe0ab499&Quality=90&MaxHeight=1080', 'thumb': 'http://{jellyfin-server}/Items/8cf69b1302194a72100b6b7898de3f20/Images/Primary/0?Format=original&Tag=7e6559330669ebbf3ceb823714d951ed&Quality=90&MaxHeight=1080', 'tvshow.fanart': 'http://{jellyfin-server}/Items/d4ff3ada545968be3ff9216adc8db635/Images/Backdrop/0?Format=original&Tag=8fa370cf952bdd4c114d665188144f4f&Quality=90&MaxHeight=1080'}}, 'next_episode': {'episodeid': '08c92d83d1006bc150a524f44b1c9316', 'tvshowid': 'd4ff3ada545968be3ff9216adc8db635', 'plot': "Cora's playboy brother has got himself into a spot of bother and Robert has to make a trip to America to bail him out. Blake and Mary are forced to put aside their differences to deal with a new farming venture on the estate. When Violet is taken ill, help comes from an unlikely quarter. In spite of Mrs Hughes, Mrs Patmore and Carson's attempts to keep the peace, romantic tensions below stairs cause problems.", 'showtitle': 'Downton Abbey', 'title': 'Episode 7', 'playcount': 0, 'season': 4, 'episode': 7, 'rating': 7.9, 'firstaired': 2013, 'art': {'tvshow.poster': 'http://{jellyfin-server}/Items/d4ff3ada545968be3ff9216adc8db635/Images/Primary/0?Format=original&Tag=27e56aff9bb1bbc33a7f08e2fe0ab499&Quality=90&MaxHeight=1080', 'tvshow.fanart': 'http://{jellyfin-server}/Items/d4ff3ada545968be3ff9216adc8db635/Images/Backdrop/0?Format=original&Tag=8fa370cf952bdd4c114d665188144f4f&Quality=90&MaxHeight=1080', 'thumb': 'http://{jellyfin-server}/Items/08c92d83d1006bc150a524f44b1c9316/Images/Primary/0?Format=original&Tag=6ef34b09980b7ca2dc0bc9f35659d156&Quality=90&MaxHeight=1080'}}}
2022-12-22 22:57:09.849 T:7944     INFO <general>: JELLYFIN.jellyfin_kodi.helper.utils -> DEBUG::jellyfin_kodi/helper/utils.py:152 ---[ event: plugin.video.jellyfin/upnext_data ] "[\"7b22706c61795f696e666f223a207b224974656d496473223a205b223038633932643833643130303662633135306135323466343462316339333136225d2c20225365727665724964223a206e756c6c2c2022506c6179436f6d6d616e64223a2022506c61794e6f77227d2c202263757272656e745f657069736f6465223a207b22657069736f64656964223a20223863663639623133303231393461373231303062366237383938646533663230222c2022747673686f776964223a20226434666633616461353435393638626533666639323136616463386462363335222c2022706c6f74223a20224120626972746864617920706172747920697320706c616e6e656420666f7220526f626572742c2062757420526f7365277320737572707269736520636f6e747269627574696f6e20746f20746865206576656e7420727566666c65732066656174686572732061626f766520616e642062656c6f77207374616972732e2041206e6577206661726d696e672076656e74757265206f6e2074686520446f776e746f6e2065737461746520636f6d65732077697468206472616d6174696320726570657263757373696f6e7320666f72204d6172792c20616e64204564697468207265636569766573206d6f726520756e77656c636f6d65206e6577732e222c202273686f777469746c65223a2022446f776e746f6e204162626579222c20227469746c65223a2022457069736f64652036222c2022706c6179636f756e74223a20302c2022736561736f6e223a20342c2022657069736f6465223a20362c2022726174696e67223a20372e372c202266697273746169726564223a20323031332c2022617274223a207b22747673686f772e706f73746572223a2022687474703a2f2f3139322e3136382e312e31303a383039362f4974656d732f64346666336164613534353936386265336666393231366164633864623633352f496d616765732f5072696d6172792f303f466f726d61743d6f726967696e616c265461673d3237653536616666396262316262633333613766303865326665306162343939265175616c6974793d3930264d61784865696768743d31303830222c20227468756d62223a2022687474703a2f2f3139322e3136382e312e31303a383039362f4974656d732f38636636396231333032313934613732313030623662373839386465336632302f496d616765732f5072696d6172792f303f466f726d61743d6f726967696e616c265461673d3765363535393333303636396562626633636562383233373134643935316564265175616c6974793d3930264d61784865696768743d31303830222c2022747673686f772e66616e617274223a2022687474703a2f2f3139322e3136382e312e31303a383039362f4974656d732f64346666336164613534353936386265336666393231366164633864623633352f496d616765732f4261636b64726f702f303f466f726d61743d6f726967696e616c265461673d3866613337306366393532626464346331313464363635313838313434663466265175616c6974793d3930264d61784865696768743d31303830227d7d2c20226e6578745f657069736f6465223a207b22657069736f64656964223a20223038633932643833643130303662633135306135323466343462316339333136222c2022747673686f776964223a20226434666633616461353435393638626533666639323136616463386462363335222c2022706c6f74223a2022436f7261277320706c6179626f792062726f746865722068617320676f742068696d73656c6620696e746f20612073706f74206f6620626f7468657220616e6420526f626572742068617320746f206d616b652061207472697020746f20416d657269636120746f206261696c2068696d206f75742e20426c616b6520616e64204d6172792061726520666f7263656420746f2070757420617369646520746865697220646966666572656e63657320746f206465616c20776974682061206e6577206661726d696e672076656e74757265206f6e20746865206573746174652e205768656e2056696f6c65742069732074616b656e20696c6c2c2068656c7020636f6d65732066726f6d20616e20756e6c696b656c7920717561727465722e20496e207370697465206f66204d7273204875676865732c204d7273205061746d6f726520616e6420436172736f6e277320617474656d70747320746f206b656570207468652070656163652c20726f6d616e7469632074656e73696f6e732062656c6f77207374616972732063617573652070726f626c656d732e222c202273686f777469746c65223a2022446f776e746f6e204162626579222c20227469746c65223a2022457069736f64652037222c2022706c6179636f756e74223a20302c2022736561736f6e223a20342c2022657069736f6465223a20372c2022726174696e67223a20372e392c202266697273746169726564223a20323031332c2022617274223a207b22747673686f772e706f73746572223a2022687474703a2f2f3139322e3136382e312e31303a383039362f4974656d732f64346666336164613534353936386265336666393231366164633864623633352f496d616765732f5072696d6172792f303f466f726d61743d6f726967696e616c265461673d3237653536616666396262316262633333613766303865326665306162343939265175616c6974793d3930264d61784865696768743d31303830222c2022747673686f772e66616e617274223a2022687474703a2f2f3139322e3136382e312e31303a383039362f4974656d732f64346666336164613534353936386265336666393231366164633864623633352f496d616765732f4261636b64726f702f303f466f726d61743d6f726967696e616c265461673d3866613337306366393532626464346331313464363635313838313434663466265175616c6974793d3930264d61784865696768743d31303830222c20227468756d62223a2022687474703a2f2f3139322e3136382e312e31303a383039362f4974656d732f30386339326438336431303036626331353061353234663434623163393331362f496d616765732f5072696d6172792f303f466f726d61743d6f726967696e616c265461673d3665663334623039393830623763613264633062633966333536353964313536265175616c6974793d3930264d61784865696768743d31303830227d7d7d\"]"
2022-12-22 22:57:20.652 T:7944     INFO <general>: JELLYFIN.jellyfin_kodi.helper.utils -> DEBUG::jellyfin_kodi/helper/utils.py:152 ---[ event: plugin.video.jellyfin/ReportProgressRequested ] "[{\"Report\": false}]"
2022-12-22 22:57:20.760 T:7944     INFO <general>: JELLYFIN.jellyfin_kodi.monitor -> DEBUG::jellyfin_kodi/monitor.py:91 [ plugin.video.jellyfin: ReportProgressRequested ] {
                                                       "Report": false
                                                   }
2022-12-22 22:57:31.667 T:7944     INFO <general>: JELLYFIN.jellyfin_kodi.helper.utils -> DEBUG::jellyfin_kodi/helper/utils.py:152 ---[ event: plugin.video.jellyfin/ReportProgressRequested ] "[{\"Report\": false}]"
2022-12-22 22:57:31.778 T:7944     INFO <general>: JELLYFIN.jellyfin_kodi.monitor -> DEBUG::jellyfin_kodi/monitor.py:91 [ plugin.video.jellyfin: ReportProgressRequested ] {
                                                       "Report": false
                                                   }
2022-12-22 22:57:42.677 T:7944     INFO <general>: JELLYFIN.jellyfin_kodi.helper.utils -> DEBUG::jellyfin_kodi/helper/utils.py:152 ---[ event: plugin.video.jellyfin/ReportProgressRequested ] "[{\"Report\": false}]"
2022-12-22 22:57:42.786 T:7944     INFO <general>: JELLYFIN.jellyfin_kodi.monitor -> DEBUG::jellyfin_kodi/monitor.py:91 [ plugin.video.jellyfin: ReportProgressRequested ] {
                                                       "Report": false
                                                   }
2022-12-22 22:57:53.694 T:7944     INFO <general>: JELLYFIN.jellyfin_kodi.helper.utils -> DEBUG::jellyfin_kodi/helper/utils.py:152 ---[ event: plugin.video.jellyfin/ReportProgressRequested ] "[{\"Report\": false}]"
2022-12-22 22:57:53.803 T:7944     INFO <general>: JELLYFIN.jellyfin_kodi.monitor -> DEBUG::jellyfin_kodi/monitor.py:91 [ plugin.video.jellyfin: ReportProgressRequested ] {
                                                       "Report": false
                                                   }
2022-12-22 22:58:04.708 T:7944     INFO <general>: JELLYFIN.jellyfin_kodi.helper.utils -> DEBUG::jellyfin_kodi/helper/utils.py:152 ---[ event: plugin.video.jellyfin/ReportProgressRequested ] "[{\"Report\": false}]"
2022-12-22 22:58:04.816 T:7944     INFO <general>: JELLYFIN.jellyfin_kodi.monitor -> DEBUG::jellyfin_kodi/monitor.py:91 [ plugin.video.jellyfin: ReportProgressRequested ] {
                                                       "Report": false
                                                   }
2022-12-22 22:58:15.728 T:7944     INFO <general>: JELLYFIN.jellyfin_kodi.helper.utils -> DEBUG::jellyfin_kodi/helper/utils.py:152 ---[ event: plugin.video.jellyfin/ReportProgressRequested ] "[{\"Report\": false}]"
2022-12-22 22:58:15.836 T:7944     INFO <general>: JELLYFIN.jellyfin_kodi.monitor -> DEBUG::jellyfin_kodi/monitor.py:91 [ plugin.video.jellyfin: ReportProgressRequested ] {
                                                       "Report": false
                                                   }
2022-12-22 22:58:26.747 T:7944     INFO <general>: JELLYFIN.jellyfin_kodi.helper.utils -> DEBUG::jellyfin_kodi/helper/utils.py:152 ---[ event: plugin.video.jellyfin/ReportProgressRequested ] "[{\"Report\": false}]"
2022-12-22 22:58:26.854 T:7944     INFO <general>: JELLYFIN.jellyfin_kodi.monitor -> DEBUG::jellyfin_kodi/monitor.py:91 [ plugin.video.jellyfin: ReportProgressRequested ] {
                                                       "Report": false
                                                   }
2022-12-22 22:58:37.764 T:7944     INFO <general>: JELLYFIN.jellyfin_kodi.helper.utils -> DEBUG::jellyfin_kodi/helper/utils.py:152 ---[ event: plugin.video.jellyfin/ReportProgressRequested ] "[{\"Report\": false}]"
2022-12-22 22:58:37.872 T:7944     INFO <general>: JELLYFIN.jellyfin_kodi.monitor -> DEBUG::jellyfin_kodi/monitor.py:91 [ plugin.video.jellyfin: ReportProgressRequested ] {
                                                       "Report": false
                                                   }
2022-12-22 22:58:48.778 T:7944     INFO <general>: JELLYFIN.jellyfin_kodi.helper.utils -> DEBUG::jellyfin_kodi/helper/utils.py:152 ---[ event: plugin.video.jellyfin/ReportProgressRequested ] "[{\"Report\": false}]"
2022-12-22 22:58:48.891 T:7944     INFO <general>: JELLYFIN.jellyfin_kodi.monitor -> DEBUG::jellyfin_kodi/monitor.py:91 [ plugin.video.jellyfin: ReportProgressRequested ] {
                                                       "Report": false
                                                   }
2022-12-22 22:58:59.797 T:7944     INFO <general>: JELLYFIN.jellyfin_kodi.helper.utils -> DEBUG::jellyfin_kodi/helper/utils.py:152 ---[ event: plugin.video.jellyfin/ReportProgressRequested ] "[{\"Report\": false}]"
2022-12-22 22:58:59.905 T:7944     INFO <general>: JELLYFIN.jellyfin_kodi.monitor -> DEBUG::jellyfin_kodi/monitor.py:91 [ plugin.video.jellyfin: ReportProgressRequested ] {
                                                       "Report": false
                                                   }
2022-12-22 22:59:10.810 T:7944     INFO <general>: JELLYFIN.jellyfin_kodi.helper.utils -> DEBUG::jellyfin_kodi/helper/utils.py:152 ---[ event: plugin.video.jellyfin/ReportProgressRequested ] "[{\"Report\": false}]"
2022-12-22 22:59:10.918 T:7944     INFO <general>: JELLYFIN.jellyfin_kodi.monitor -> DEBUG::jellyfin_kodi/monitor.py:91 [ plugin.video.jellyfin: ReportProgressRequested ] {
                                                       "Report": false
                                                   }
2022-12-22 22:59:21.824 T:7944     INFO <general>: JELLYFIN.jellyfin_kodi.helper.utils -> DEBUG::jellyfin_kodi/helper/utils.py:152 ---[ event: plugin.video.jellyfin/ReportProgressRequested ] "[{\"Report\": false}]"
2022-12-22 22:59:21.933 T:7944     INFO <general>: JELLYFIN.jellyfin_kodi.monitor -> DEBUG::jellyfin_kodi/monitor.py:91 [ plugin.video.jellyfin: ReportProgressRequested ] {
                                                       "Report": false
                                                   }
2022-12-22 22:59:32.848 T:7944     INFO <general>: JELLYFIN.jellyfin_kodi.helper.utils -> DEBUG::jellyfin_kodi/helper/utils.py:152 ---[ event: plugin.video.jellyfin/ReportProgressRequested ] "[{\"Report\": true}]"
2022-12-22 22:59:32.956 T:7944     INFO <general>: JELLYFIN.jellyfin_kodi.monitor -> DEBUG::jellyfin_kodi/monitor.py:91 [ plugin.video.jellyfin: ReportProgressRequested ] {
                                                       "Report": true
                                                   }
2022-12-22 22:59:32.969 T:7944     INFO <general>: JELLYFIN.jellyfin_kodi.jellyfin.http -> DEBUG::jellyfin_kodi/jellyfin/http.py:84 --->[ http ] {
                                                       "params": null,
                                                       "json": {
                                                           "QueueableMediaTypes": "Video,Audio",
                                                           "CanSeek": true,
                                                           "ItemId": "8cf69b1302194a72100b6b7898de3f20",
                                                           "MediaSourceId": "8cf69b1302194a72100b6b7898de3f20",
                                                           "PlayMethod": "DirectPlay",
                                                           "VolumeLevel": 100,
                                                           "PositionTicks": 2130000000,
                                                           "IsPaused": false,
                                                           "IsMuted": false,
                                                           "PlaySessionId": "16b5c00f4c1549bd9605da9287bf37e8",
                                                           "AudioStreamIndex": 1,
                                                           "SubtitleStreamIndex": null
                                                       },
                                                       "type": "POST",
                                                       "url": "http://{jellyfin-server}/Sessions/Playing/Progress",
                                                       "headers": {
                                                           "Content-type": "application/json",
                                                           "Accept-Charset": "UTF-8,*",
                                                           "Accept-encoding": "gzip",
                                                           "User-Agent": "Jellyfin-Kodi/0.7.10+py3",
                                                           "x-emby-authorization": "MediaBrowser Client=Kodi, Device=Kodi (192.168.1.105), DeviceId=72d9b309-a8e3-4ae6-bcea-c7f6fe04f876, Version=0.7.10+py3, UserId=60967e4bfe444a11839737d7a03d3571",
                                                           "X-MediaBrowser-Token": "{jellyfin-token}"
                                                       },
                                                       "timeout": 30,
                                                       "verify": true
                                                   }

Screenshots

System (please complete the following information):

Additional context Kodi is having multiple problems with Jellyfin including: 1) Refreshing the entire library every day. 2) When using NextUp to play the next TV episode, it instead replays the episode it was on. Checking the Jellyfin server shows that the just-finished episode has not been marked as played.

mcarlton00 commented 1 year ago

There's no errors in yours logs, or any indication that something is wrong. Playback also isn't something that we directly control, we essentially just hand Kodi a URL and it handles playback all on it's own. Without something more concrete, everything appears to be working as intended.

As for your other issues, you're going to have to be more specific.

  1. You're forcing it to refresh every day? It's randomly doing it on it's own? It's not pulling changes?
  2. You're referring to the Up Next addon? I don't believe this addon has any next up functionality built in by default.
mikul9 commented 1 year ago

Thanks for getting back to me.

The playback issue is baffling. After years of running PlexKodiConnect, I did not encounter this, and I cannot think of any other changes (aside from plugin updates) to the Shield since that started to have caused this. However, I am suspicious that this is a out of memory issue as the Shield has now shown a "Memory is running critically low" error twice when this happened. This would also explain why a reboot is required to resolve the issue. My guess is that something has a memory leak and the most likely culprits are the jellyfinKodi plugin or the skin as they are used constantly and have been updated multiple times. Memory leaks are so much fun to debug.

  1. Whenever it does an update, it always updates the entire library. E.g. if it updated yesterday, it will show that it is pulling 2,000 changes (the approximate library size) . When I use it again today (and any other day), it will again pull 2,000 changes. It never updates the 1 or 2 files that actually changed.

  2. Whenever UpNext replays the episode we just watched we see that the watched flag has not been updated iin Jellyfin server. E.g. We watch S01E01, the episode finishes to the very end, UpNext starts a video and it's S01E01, Jellyfin shows that the episode is unwatched. Kodi also shows that S01E01 is unwatched. I'm not sure if this is a chicken and egg problem.

mcarlton00 commented 1 year ago

in regards to 1, it's basically out of our hands. It needs changes to the plugin in the server. See https://github.com/jellyfin/jellyfin-kodi/issues/509 and https://github.com/jellyfin/jellyfin-plugin-kodisyncqueue/issues/44 if you wanna know more.

This is the first report I've heard of any issues with the up next addon

gurg1e commented 7 months ago

I'm experiencing this issue, same symptoms as in the OP but it only gets a few seconds into the video, rather than ~10 - the time seems related to the filesize, larger files quit faster. Has been happening for a while now but it seems to have gotten worse lately. Seems to happen at least once a day now. Is tempfixed by rebooting my Kodi box.

System:

When it drops the video, this is the first line in the log: 

INFO <general>: Process - eof reading from demuxer

My box has 4GB RAM, usually with a few GB free so I doubt it's memory related.

A couple extra observations:

  1. On the times it occurs, It takes maybe 3 or 4 seconds to load, before playing 2-3 seconds of the file and stopping. This is in contrast to when it works normally, playback starts within a second.
  2. Only occurs with Library media from JF, IPTV via the PVR will work normally when this is failing.

If I try to play via Jellycon, I get an error in the UI directing me to check the log, and this - 


2023-12-29 10:56:09.328 T:9331     INFO <general>: JELLYFIN.resources.lib.play_utils -> INFO::resources/lib/play_utils.py:425 Play URL: {jellyfin-server}/Videos/c42bd40c58889c2798f5aa61ee57a8b3/stream?static=True&PlaySessionId=7454d904cff94ffe94ce410ad2eb834c&MediaSourceId=c42bd40c58889c2798f5aa61ee57a8b3 Playback Type: 1 ListItem Properties: []
2023-12-29 10:56:09.361 T:3470     INFO <general>: VideoPlayer::OpenFile: https://$MYSERVER.COM$/Videos/c42bd40c58889c2798f5aa61ee57a8b3/stream?static=True&PlaySessionId=7454d904cff94ffe94ce410ad2eb834c&MediaSourceId=c42bd40c58889c2798f5aa61ee57a8b3
2023-12-29 10:56:09.362 T:9331     INFO <general>: CPythonInvoker(42, /storage/.kodi/addons/plugin.video.jellycon/default.py): script successfully run
2023-12-29 10:56:09.363 T:9335     INFO <general>: Creating InputStream
2023-12-29 10:56:09.403 T:9335    ERROR <general>: Open - Unhandled exception
2023-12-29 10:56:09.403 T:9335    ERROR <general>: Open - Error opening https://$MYSERVER.COM$/Videos/c42bd40c58889c2798f5aa61ee57a8b3/stream?static=True&PlaySessionId=7454d904cff94ffe94ce410ad2eb834c&MediaSourceId=c42bd40c58889c2798f5aa61ee57a8b3
2023-12-29 10:56:09.403 T:9335    ERROR <general>: CVideoPlayer::OpenInputStream - error opening [https://$MYSERVER.COM$/Videos/c42bd40c58889c2798f5aa61ee57a8b3/stream?static=True&PlaySessionId=7454d904cff94ffe94ce410ad2eb834c&MediaSourceId=c42bd40c58889c2798f5aa61ee57a8b3]
2023-12-29 10:56:09.403 T:9335     INFO <general>: CVideoPlayer::OnExit()
2023-12-29 10:56:09.477 T:3470     INFO <general>: CVideoPlayer::CloseFile()
2023-12-29 10:56:09.477 T:3470     INFO <general>: VideoPlayer: waiting for threads to exit
2023-12-29 10:56:09.477 T:3470     INFO <general>: VideoPlayer: finished waiting
2023-12-29 10:56:09.625 T:3779    ERROR <general>: EXCEPTION: XBMC is not playing any file
2023-12-29 10:56:10.143 T:9331     INFO <general>: Skipped 1 duplicate messages..
2023-12-29 10:56:10.143 T:9331     INFO <general>: Python interpreter stopped
2023-12-29 10:56:10.145 T:3779     INFO <general>: JELLYFIN.jellyfin_kodi.monitor -> DEBUG::jellyfin_kodi/monitor.py:91 [ xbmc: Player.OnPlay ] {
                                                       "item": {
                                                           "title": "The Prestige",
                                                           "type": "movie",
                                                           "year": 2006
                                                       },
                                                       "player": {
                                                           "playerid": 1,
                                                           "speed": 1
                                                       }
                                                   }
2023-12-29 10:56:10.149 T:3779     INFO <general>: JELLYFIN.jellyfin_kodi.objects.actions -> DEBUG::jellyfin_kodi/objects/actions.py:765 Invalid playstate update
2023-12-29 10:56:10.270 T:3779     INFO <general>: JELLYFIN.jellyfin_kodi.helper.utils -> DEBUG::jellyfin_kodi/helper/utils.py:56 --[ window clear: jellyfin_play ]
2023-12-29 10:56:10.282 T:3779     INFO <general>: JELLYFIN.jellyfin_kodi.player -> INFO::jellyfin_kodi/player.py:377 --<[ playback ]
2023-12-29 10:56:13.159 T:3672     INFO <general>: script.module.slyguy - Repo: repository.slyguy requires force update
2023-12-29 10:56:13.181 T:3779     INFO <general>: JELLYFIN.jellyfin_kodi.monitor -> INFO::jellyfin_kodi/monitor.py:85 [ playlist ] cleared
2023-12-29 10:56:13.181 T:3779    ERROR <general>: EXCEPTION: XBMC is not playing any file
2023-12-29 10:56:17.192 T:3779     INFO <general>: Skipped 3 duplicate messages..
2023-12-29 10:56:17.192 T:3779     INFO <general>: JELLYFIN.jellyfin_kodi.player -> INFO::jellyfin_kodi/player.py:72 Cancel playback report
2023-12-29 10:56:17.192 T:3779    ERROR <general>: EXCEPTION: XBMC is not playing any file
2023-12-29 10:56:19.202 T:3779     INFO <general>: Skipped 1 duplicate messages..
2023-12-29 10:56:19.202 T:3779     INFO <general>: JELLYFIN.jellyfin_kodi.player -> INFO::jellyfin_kodi/player.py:72 Cancel playback report ```