Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

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

Open
mikul9 opened this issue Dec 23, 2022 · 6 comments
Open

Comments

@mikul9
Copy link

mikul9 commented Dec 23, 2022

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):

  • OS: Android TV for Nvidia Shield 9.1
  • Jellyfin Version: 10.8.8
  • Kodi Version: 19.3
  • Addon Version: 0.7.10+py3
  • Playback Mode: Add-on

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
Copy link
Member

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
Copy link
Author

mikul9 commented Jan 21, 2023

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
Copy link
Member

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

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

@gurg1e
Copy link

gurg1e commented Dec 29, 2023

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:

  • OS: CoreELEC
  • Jellyfin Version: 10.8.13
  • Kodi Version: 19.5.0
  • Addon Version: 0.7.11+py3
  • Playback Mode: Native

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 ```

@sic6SaNdMaN
Copy link

sic6SaNdMaN commented Nov 22, 2024

I'm also having this issue.
The video stops after a few seconds.
Then you have to wait exactly one minute (most of the time, the playtime of the video advances normally), all of a sudden, the video plays with double the speed (or so), no audio then either.
When I "catches up" to the point it should be (the playtime is 1 minute and some seconds) it recovers itself and plays along fine.
Sometimes the audio is a bit async, so that I have to restart the video, but often it simply is fine.

Still, this is very annoying.
And it seems to occur more and more often since a few months.

Edit: This only happens in AddOn-mode to me. I witched to AddOn-mode several weeks ago because of
jellyfin/jellyfin#11654
And this bug occured nearly everytime I started/paused a video.
So I switched back to native mode and it works lika a charm again.
Not saying that it can't happen in native mode, too. But having this problem once or twice a month vs. several times every evening is a HUGE difference!

@sic6SaNdMaN
Copy link

More Info:
This also happens, when pausing a Video for some minutes, especially when the "screensaver" got active. I just use dimming as screensaver, activated after two minutes.
So when I resume playing, the behaviour described obove can occur.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

No branches or pull requests

4 participants