search

jurialmunkey / plugin.video.themoviedb.helper

GNU General Public License v3.0
204 stars 96 forks source link

Playback failed dialog is shown sporadically before the Player Choose dialog #431

Closed ventsislav-georgiev closed 3 years ago

ventsislav-georgiev commented 3 years ago

Issue

When clicking Play on a Movie or a TVShow a Playback failed dialog is shown and on top of it the Player Choose dialog.

image

This happens sporadically. It could run without it 5 times and then hit it 2 times in a row.

Log

Here is log when clicking play till the players choose dialog is shown.

Log when the Playback failed dialog is also shown ``` DEBUG: StartScript - calling plugin TheMovieDb Helper('plugin://plugin.video.themoviedb.helper/','31','?info=play&type=movie&nextpage=True&tmdb_id=464052','resume:false') DEBUG: Thread LanguageInvoker start, auto delete: false INFO: initializing python engine. DEBUG: CPythonInvoker(54, /Users/admin/Library/Application Support/Kodi/addons/plugin.video.themoviedb.helper/plugin.py): start processing DEBUG: -->Python Interpreter Initialized<-- DEBUG: CPythonInvoker(54, /Users/admin/Library/Application Support/Kodi/addons/plugin.video.themoviedb.helper/plugin.py): the source file to load is "/Users/admin/Library/Application Support/Kodi/addons/plugin.video.themoviedb.helper/plugin.py" DEBUG: CPythonInvoker(54, /Users/admin/Library/Application Support/Kodi/addons/plugin.video.themoviedb.helper/plugin.py): setting the Python path to /Users/admin/Library/Application Support/Kodi/addons/plugin.video.themoviedb.helper:/Applications/Kodi.app/Contents/Resources/Kodi/addons/script.module.pil/lib:/Users/admin/Library/Application Support/Kodi/addons/script.module.certifi/lib:/Users/admin/Library/Application Support/Kodi/addons/script.module.chardet/lib:/Users/admin/Library/Application Support/Kodi/addons/script.module.idna/lib:/Users/admin/Library/Application Support/Kodi/addons/script.module.requests/lib:/Users/admin/Library/Application Support/Kodi/addons/script.module.simplecache/lib:/Users/admin/Library/Application Support/Kodi/addons/script.module.urllib3/lib:/Applications/Kodi.app/Contents/Libraries/lib/python2.7/site-packages/setuptools-18.4-py2.7.egg:/Applications/Kodi.app/Contents/Libraries/lib/python2.7/site-packages/Pillow-3.0.0-py2.7-macosx-10.14-x86_64.egg:/Applications/Kodi.app/Contents/Libraries:/Applications/Kodi.app/Contents/Libraries/lib/python27.zip:/Applications/Kodi.app/Contents/Libraries/lib/python2.7:/Applications/Kodi.app/Contents/Libraries/lib/python2.7/site-packages:/Applications/Kodi.app/Contents/Libraries/lib/python2.7/plat-darwin:/Applications/Kodi.app/Contents/Libraries/lib/python2.7/plat-mac:/Applications/Kodi.app/Contents/Libraries/lib/python2.7/plat-mac/lib-scriptpackages:/Applications/Kodi.app/Contents/Libraries/lib/python2.7/lib-tk:/Applications/Kodi.app/Contents/Libraries/lib/python2.7/lib-old:/Applications/Kodi.app/Contents/Libraries/lib/python2.7/lib-dynload DEBUG: CPythonInvoker(54, /Users/admin/Library/Application Support/Kodi/addons/plugin.video.themoviedb.helper/plugin.py): entering source directory /Users/admin/Library/Application Support/Kodi/addons/plugin.video.themoviedb.helper DEBUG: CPythonInvoker(54, /Users/admin/Library/Application Support/Kodi/addons/plugin.video.themoviedb.helper/plugin.py): instantiating addon using automatically obtained id of "plugin.video.themoviedb.helper" dependent on version 2.26.0 of the xbmc.python api DEBUG: Thread scriptobs start, auto delete: false DEBUG: CAddonSettings[plugin.video.themoviedb.helper]: loading setting definitions DEBUG: CAddonSettings[plugin.video.themoviedb.helper]: trying to load setting definitions from old format... DEBUG: CAddonSettings[plugin.video.themoviedb.helper]: loading setting values DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnClear - data: {"playlistid":1} DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"title":"Wonder Woman 1984","type":"movie","year":2020},"playlistid":1,"position":0} DEBUG: ------ Window Init (DialogBusy.xml) ------ DEBUG: Skin Helper Simplecache --> Initialized DEBUG: CAddonSettings[plugin.video.themoviedb.helper]: loading setting definitions DEBUG: CAddonSettings[plugin.video.themoviedb.helper]: trying to load setting definitions from old format... DEBUG: CAddonSettings[plugin.video.themoviedb.helper]: loading setting values NOTICE: [plugin.video.themoviedb.helper] Container -- Attempting to Play Item...: {u'info': u'play', u'type': u'movie', u'tmdb_id': u'464052', u'nextpage': u'True'} INFO: CPythonInvoker(54, /Users/admin/Library/Application Support/Kodi/addons/plugin.video.themoviedb.helper/plugin.py): script successfully run DEBUG: onExecutionDone(54, /Users/admin/Library/Application Support/Kodi/addons/plugin.video.themoviedb.helper/plugin.py) DEBUG: Thread LanguageInvoker start, auto delete: false INFO: initializing python engine. DEBUG: CPythonInvoker(55, /Users/admin/Library/Application Support/Kodi/addons/plugin.video.themoviedb.helper/script.py): start processing DEBUG: -->Python Interpreter Initialized<-- DEBUG: CPythonInvoker(55, /Users/admin/Library/Application Support/Kodi/addons/plugin.video.themoviedb.helper/script.py): the source file to load is "/Users/admin/Library/Application Support/Kodi/addons/plugin.video.themoviedb.helper/script.py" DEBUG: CPythonInvoker(55, /Users/admin/Library/Application Support/Kodi/addons/plugin.video.themoviedb.helper/script.py): setting the Python path to /Users/admin/Library/Application Support/Kodi/addons/plugin.video.themoviedb.helper:/Applications/Kodi.app/Contents/Resources/Kodi/addons/script.module.pil/lib:/Users/admin/Library/Application Support/Kodi/addons/script.module.certifi/lib:/Users/admin/Library/Application Support/Kodi/addons/script.module.chardet/lib:/Users/admin/Library/Application Support/Kodi/addons/script.module.idna/lib:/Users/admin/Library/Application Support/Kodi/addons/script.module.requests/lib:/Users/admin/Library/Application Support/Kodi/addons/script.module.simplecache/lib:/Users/admin/Library/Application Support/Kodi/addons/script.module.urllib3/lib:/Applications/Kodi.app/Contents/Libraries/lib/python2.7/site-packages/setuptools-18.4-py2.7.egg:/Applications/Kodi.app/Contents/Libraries/lib/python2.7/site-packages/Pillow-3.0.0-py2.7-macosx-10.14-x86_64.egg:/Applications/Kodi.app/Contents/Libraries:/Applications/Kodi.app/Contents/Libraries/lib/python27.zip:/Applications/Kodi.app/Contents/Libraries/lib/python2.7:/Applications/Kodi.app/Contents/Libraries/lib/python2.7/site-packages:/Applications/Kodi.app/Contents/Libraries/lib/python2.7/plat-darwin:/Applications/Kodi.app/Contents/Libraries/lib/python2.7/plat-mac:/Applications/Kodi.app/Contents/Libraries/lib/python2.7/plat-mac/lib-scriptpackages:/Applications/Kodi.app/Contents/Libraries/lib/python2.7/lib-tk:/Applications/Kodi.app/Contents/Libraries/lib/python2.7/lib-old:/Applications/Kodi.app/Contents/Libraries/lib/python2.7/lib-dynload DEBUG: CPythonInvoker(55, /Users/admin/Library/Application Support/Kodi/addons/plugin.video.themoviedb.helper/script.py): entering source directory /Users/admin/Library/Application Support/Kodi/addons/plugin.video.themoviedb.helper DEBUG: CPythonInvoker(55, /Users/admin/Library/Application Support/Kodi/addons/plugin.video.themoviedb.helper/script.py): instantiating addon using automatically obtained id of "plugin.video.themoviedb.helper" dependent on version 2.26.0 of the xbmc.python api DEBUG: CAddonSettings[plugin.video.themoviedb.helper]: loading setting definitions DEBUG: CAddonSettings[plugin.video.themoviedb.helper]: trying to load setting definitions from old format... DEBUG: CAddonSettings[plugin.video.themoviedb.helper]: loading setting values DEBUG: Skin Helper Simplecache --> Closed INFO: Python interpreter stopped DEBUG: Thread LanguageInvoker 123145571082240 terminating DEBUG: Thread scriptobs 123145576984576 terminating DEBUG: ------ Window Deinit (DialogBusy.xml) ------ ERROR: Playlist Player: skipping unplayable item: 0, path [plugin://plugin.video.themoviedb.helper/?info=play&type=movie&nextpage=True&tmdb_id=464052] DEBUG: Playlist Player: one or more items failed to play... aborting playback DEBUG: ------ Window Init (DialogConfirm.xml) ------ DEBUG: Skin Helper Simplecache --> Initialized DEBUG: CAddonSettings[plugin.video.themoviedb.helper]: loading setting definitions DEBUG: CAddonSettings[plugin.video.themoviedb.helper]: trying to load setting definitions from old format... DEBUG: CAddonSettings[plugin.video.themoviedb.helper]: loading setting values DEBUG: CAddonSettings[plugin.video.themoviedb.helper]: loading setting definitions DEBUG: CAddonSettings[plugin.video.themoviedb.helper]: trying to load setting definitions from old format... DEBUG: CAddonSettings[plugin.video.themoviedb.helper]: loading setting values DEBUG: [plugin.video.themoviedb.helper] Script -- Attempting to play item: {'play': 'movie', 'tmdb_id': '464052'} DEBUG: CAddonSettings[plugin.video.themoviedb.helper]: loading setting definitions DEBUG: CAddonSettings[plugin.video.themoviedb.helper]: trying to load setting definitions from old format... DEBUG: CAddonSettings[plugin.video.themoviedb.helper]: loading setting values DEBUG: CAddonSettings[plugin.video.themoviedb.helper]: loading setting definitions DEBUG: CAddonSettings[plugin.video.themoviedb.helper]: trying to load setting definitions from old format... DEBUG: CAddonSettings[plugin.video.themoviedb.helper]: loading setting values DEBUG: Activating window ID: 10160 DEBUG: ------ Window Init (DialogBusy.xml) ------ DEBUG: [plugin.video.themoviedb.helper] Player Details - Item: defaultdict( at 0x600013329da0>, {'tmdb': '464052', 'imdb': u'tt7126948', 'year': u'2020', 'id': '464052', 'showname': u'Wonder Woman 1984', 'title': u'Wonder Woman 1984', 'fanart': 'https://image.tmdb.org/t/p/original/srYya1ZlI97Au4jUYAktDe3avyA.jpg', 'clearname': u'Wonder Woman 1984', 'trakt': 311212, 'thumbnail': '', 'tmdb_id': '464052', 'poster': 'https://image.tmdb.org/t/p/w500/8UlWHLMpgZm9bx6QYh0NFoq67TZ.jpg', 'tvshowtitle': u'Wonder Woman 1984', 'tvdb_id': None, 'imdb_id': u'tt7126948', 'released': u'2020-12-16', 'now': '20210204135404569141', 'slug': u'wonder-woman-1984-2020', 'name': u'Wonder Woman 1984 (2020)', 'premiered': u'2020-12-16', 'originaltitle': u'Wonder Woman 1984', 'tvdb': None, 'firstaired': u'2020-12-16'}) DEBUG: [plugin.video.themoviedb.helper] Player -- Built 1 Players! ['netflix.json play_movie'] DEBUG: ------ Window Deinit (DialogBusy.xml) ------ ```
Log when all works properly ``` DEBUG: StartScript - calling plugin TheMovieDb Helper('plugin://plugin.video.themoviedb.helper/','32','?info=play&type=movie&nextpage=True&tmdb_id=464052','resume:false') DEBUG: Thread LanguageInvoker start, auto delete: false INFO: initializing python engine. DEBUG: CPythonInvoker(56, /Users/admin/Library/Application Support/Kodi/addons/plugin.video.themoviedb.helper/plugin.py): start processing DEBUG: -->Python Interpreter Initialized<-- DEBUG: CPythonInvoker(56, /Users/admin/Library/Application Support/Kodi/addons/plugin.video.themoviedb.helper/plugin.py): the source file to load is "/Users/admin/Library/Application Support/Kodi/addons/plugin.video.themoviedb.helper/plugin.py" DEBUG: CPythonInvoker(56, /Users/admin/Library/Application Support/Kodi/addons/plugin.video.themoviedb.helper/plugin.py): setting the Python path to /Users/admin/Library/Application Support/Kodi/addons/plugin.video.themoviedb.helper:/Applications/Kodi.app/Contents/Resources/Kodi/addons/script.module.pil/lib:/Users/admin/Library/Application Support/Kodi/addons/script.module.certifi/lib:/Users/admin/Library/Application Support/Kodi/addons/script.module.chardet/lib:/Users/admin/Library/Application Support/Kodi/addons/script.module.idna/lib:/Users/admin/Library/Application Support/Kodi/addons/script.module.requests/lib:/Users/admin/Library/Application Support/Kodi/addons/script.module.simplecache/lib:/Users/admin/Library/Application Support/Kodi/addons/script.module.urllib3/lib:/Applications/Kodi.app/Contents/Libraries/lib/python2.7/site-packages/setuptools-18.4-py2.7.egg:/Applications/Kodi.app/Contents/Libraries/lib/python2.7/site-packages/Pillow-3.0.0-py2.7-macosx-10.14-x86_64.egg:/Applications/Kodi.app/Contents/Libraries:/Applications/Kodi.app/Contents/Libraries/lib/python27.zip:/Applications/Kodi.app/Contents/Libraries/lib/python2.7:/Applications/Kodi.app/Contents/Libraries/lib/python2.7/site-packages:/Applications/Kodi.app/Contents/Libraries/lib/python2.7/plat-darwin:/Applications/Kodi.app/Contents/Libraries/lib/python2.7/plat-mac:/Applications/Kodi.app/Contents/Libraries/lib/python2.7/plat-mac/lib-scriptpackages:/Applications/Kodi.app/Contents/Libraries/lib/python2.7/lib-tk:/Applications/Kodi.app/Contents/Libraries/lib/python2.7/lib-old:/Applications/Kodi.app/Contents/Libraries/lib/python2.7/lib-dynload DEBUG: CPythonInvoker(56, /Users/admin/Library/Application Support/Kodi/addons/plugin.video.themoviedb.helper/plugin.py): entering source directory /Users/admin/Library/Application Support/Kodi/addons/plugin.video.themoviedb.helper DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"title":"Wonder Woman 1984","type":"movie","year":2020},"playlistid":1,"position":0} DEBUG: CPythonInvoker(56, /Users/admin/Library/Application Support/Kodi/addons/plugin.video.themoviedb.helper/plugin.py): instantiating addon using automatically obtained id of "plugin.video.themoviedb.helper" dependent on version 2.26.0 of the xbmc.python api DEBUG: Thread scriptobs start, auto delete: false DEBUG: CAddonSettings[plugin.video.themoviedb.helper]: loading setting definitions DEBUG: CAddonSettings[plugin.video.themoviedb.helper]: trying to load setting definitions from old format... DEBUG: CAddonSettings[plugin.video.themoviedb.helper]: loading setting values DEBUG: ------ Window Init (DialogBusy.xml) ------ DEBUG: Skin Helper Simplecache --> Initialized DEBUG: CAddonSettings[plugin.video.themoviedb.helper]: loading setting definitions DEBUG: CAddonSettings[plugin.video.themoviedb.helper]: trying to load setting definitions from old format... DEBUG: CAddonSettings[plugin.video.themoviedb.helper]: loading setting values NOTICE: [plugin.video.themoviedb.helper] Container -- Attempting to Play Item...: {u'info': u'play', u'type': u'movie', u'tmdb_id': u'464052', u'nextpage': u'True'} INFO: CPythonInvoker(56, /Users/admin/Library/Application Support/Kodi/addons/plugin.video.themoviedb.helper/plugin.py): script successfully run DEBUG: onExecutionDone(56, /Users/admin/Library/Application Support/Kodi/addons/plugin.video.themoviedb.helper/plugin.py) DEBUG: Thread LanguageInvoker start, auto delete: false INFO: initializing python engine. DEBUG: CPythonInvoker(57, /Users/admin/Library/Application Support/Kodi/addons/plugin.video.themoviedb.helper/script.py): start processing DEBUG: Skin Helper Simplecache --> Closed INFO: Python interpreter stopped DEBUG: Thread LanguageInvoker 123145581355008 terminating DEBUG: Thread scriptobs 123145584574464 terminating DEBUG: -->Python Interpreter Initialized<-- DEBUG: CPythonInvoker(57, /Users/admin/Library/Application Support/Kodi/addons/plugin.video.themoviedb.helper/script.py): the source file to load is "/Users/admin/Library/Application Support/Kodi/addons/plugin.video.themoviedb.helper/script.py" DEBUG: CPythonInvoker(57, /Users/admin/Library/Application Support/Kodi/addons/plugin.video.themoviedb.helper/script.py): setting the Python path to /Users/admin/Library/Application Support/Kodi/addons/plugin.video.themoviedb.helper:/Applications/Kodi.app/Contents/Resources/Kodi/addons/script.module.pil/lib:/Users/admin/Library/Application Support/Kodi/addons/script.module.certifi/lib:/Users/admin/Library/Application Support/Kodi/addons/script.module.chardet/lib:/Users/admin/Library/Application Support/Kodi/addons/script.module.idna/lib:/Users/admin/Library/Application Support/Kodi/addons/script.module.requests/lib:/Users/admin/Library/Application Support/Kodi/addons/script.module.simplecache/lib:/Users/admin/Library/Application Support/Kodi/addons/script.module.urllib3/lib:/Applications/Kodi.app/Contents/Libraries/lib/python2.7/site-packages/setuptools-18.4-py2.7.egg:/Applications/Kodi.app/Contents/Libraries/lib/python2.7/site-packages/Pillow-3.0.0-py2.7-macosx-10.14-x86_64.egg:/Applications/Kodi.app/Contents/Libraries:/Applications/Kodi.app/Contents/Libraries/lib/python27.zip:/Applications/Kodi.app/Contents/Libraries/lib/python2.7:/Applications/Kodi.app/Contents/Libraries/lib/python2.7/site-packages:/Applications/Kodi.app/Contents/Libraries/lib/python2.7/plat-darwin:/Applications/Kodi.app/Contents/Libraries/lib/python2.7/plat-mac:/Applications/Kodi.app/Contents/Libraries/lib/python2.7/plat-mac/lib-scriptpackages:/Applications/Kodi.app/Contents/Libraries/lib/python2.7/lib-tk:/Applications/Kodi.app/Contents/Libraries/lib/python2.7/lib-old:/Applications/Kodi.app/Contents/Libraries/lib/python2.7/lib-dynload DEBUG: CPythonInvoker(57, /Users/admin/Library/Application Support/Kodi/addons/plugin.video.themoviedb.helper/script.py): entering source directory /Users/admin/Library/Application Support/Kodi/addons/plugin.video.themoviedb.helper DEBUG: CPythonInvoker(57, /Users/admin/Library/Application Support/Kodi/addons/plugin.video.themoviedb.helper/script.py): instantiating addon using automatically obtained id of "plugin.video.themoviedb.helper" dependent on version 2.26.0 of the xbmc.python api DEBUG: ------ Window Deinit (DialogBusy.xml) ------ ERROR: Playlist Player: skipping unplayable item: 0, path [plugin://plugin.video.themoviedb.helper/?info=play&type=movie&nextpage=True&tmdb_id=464052] DEBUG: Playlist Player: no more playable items... aborting playback DEBUG: Thread BackgroundLoader start, auto delete: false DEBUG: CAddonSettings[plugin.video.themoviedb.helper]: loading setting definitions DEBUG: CAddonSettings[plugin.video.themoviedb.helper]: trying to load setting definitions from old format... DEBUG: CAddonSettings[plugin.video.themoviedb.helper]: loading setting values DEBUG: Thread BackgroundLoader 123145581355008 terminating DEBUG: Skin Helper Simplecache --> Initialized DEBUG: CAddonSettings[plugin.video.themoviedb.helper]: loading setting definitions DEBUG: CAddonSettings[plugin.video.themoviedb.helper]: trying to load setting definitions from old format... DEBUG: CAddonSettings[plugin.video.themoviedb.helper]: loading setting values DEBUG: CAddonSettings[plugin.video.themoviedb.helper]: loading setting definitions DEBUG: CAddonSettings[plugin.video.themoviedb.helper]: trying to load setting definitions from old format... DEBUG: CAddonSettings[plugin.video.themoviedb.helper]: loading setting values DEBUG: [plugin.video.themoviedb.helper] Script -- Attempting to play item: {'play': 'movie', 'tmdb_id': '464052'} DEBUG: CAddonSettings[plugin.video.themoviedb.helper]: loading setting definitions DEBUG: CAddonSettings[plugin.video.themoviedb.helper]: trying to load setting definitions from old format... DEBUG: CAddonSettings[plugin.video.themoviedb.helper]: loading setting values DEBUG: CAddonSettings[plugin.video.themoviedb.helper]: loading setting definitions DEBUG: CAddonSettings[plugin.video.themoviedb.helper]: trying to load setting definitions from old format... DEBUG: CAddonSettings[plugin.video.themoviedb.helper]: loading setting values DEBUG: Activating window ID: 10160 DEBUG: ------ Window Init (DialogBusy.xml) ------ DEBUG: [plugin.video.themoviedb.helper] Player Details - Item: defaultdict( at 0x60000f278820>, {'tmdb': '464052', 'imdb': u'tt7126948', 'year': u'2020', 'id': '464052', 'showname': u'Wonder Woman 1984', 'title': u'Wonder Woman 1984', 'fanart': 'https://image.tmdb.org/t/p/original/srYya1ZlI97Au4jUYAktDe3avyA.jpg', 'clearname': u'Wonder Woman 1984', 'trakt': 311212, 'thumbnail': '', 'tmdb_id': '464052', 'poster': 'https://image.tmdb.org/t/p/w500/8UlWHLMpgZm9bx6QYh0NFoq67TZ.jpg', 'tvshowtitle': u'Wonder Woman 1984', 'tvdb_id': None, 'imdb_id': u'tt7126948', 'released': u'2020-12-16', 'now': '20210204140005723049', 'slug': u'wonder-woman-1984-2020', 'name': u'Wonder Woman 1984 (2020)', 'premiered': u'2020-12-16', 'originaltitle': u'Wonder Woman 1984', 'tvdb': None, 'firstaired': u'2020-12-16'}) DEBUG: [plugin.video.themoviedb.helper] Player -- Built 1 Players! ['netflix.json play_movie'] DEBUG: ------ Window Init (DialogSelect.xml) ------ ```

Details

The notable difference seems to be that after the playlist error message they get different type of playback abort.

This is when the Playback failed dialog is shown:

DEBUG: Playlist Player: one or more items failed to play... aborting playback

This is when it works properly:

DEBUG: Playlist Player: no more playable items... aborting playback

Platform

Platform: macOS Big Sur KODI: 18.9

jurialmunkey commented 3 years ago

Full logs not snippets please.

Also do you have default player set? Sounds like default player is failing.

ventsislav-georgiev commented 3 years ago

Interesting... while trying to get a full clean log I reinstalled KODI and deleted all its addons and settings in the process. After that I can't reproduce the issue at all. I am not sure what was causing it but now is gone, so I will close the issue.

P.S. I was not using a default player as explained in the issue I see the "Player Choose" dialog with the Netflix player. And sporadically when I press play I will immediately get a "Playback failed" while still a loading is active and a "Player Choose" dialog will open on top of it. I even could start a video playback, and when KODI's player started the only dialog that was left was the "Playback failed", which when closed stopped the video player...