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.

Sign up for GitHub

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

Jump to bottom

Nothing syncing #486

Closed
ronnodas opened this issue Dec 20, 2019 · 14 comments
Closed

Nothing syncing #486

ronnodas opened this issue Dec 20, 2019 · 14 comments

Comments

@ronnodas
Copy link

I've been trying to sync watched status on a fresh install, but nothing seems to happen. Below is the part of the log that's relevant, with the list of shows truncated. I can connect to trakt from the machine just fine, and the api isn't down as far as I can tell.

2019-12-19 18:41:43.218 T:2294276304   DEBUG: CPythonInvoker(11, /home/pi/.kodi/addons/script.trakt/defaultscript.py): the source file to load is "/home/pi/.kodi/addons/script.trakt/defaultscript.py"
2019-12-19 18:41:43.220 T:2294276304   DEBUG: CPythonInvoker(11, /home/pi/.kodi/addons/script.trakt/defaultscript.py): setting the Python path to /home/pi/.kodi/addons/script.trakt:/home/pi/.kodi/addons/script.module.arrow/lib:/home/pi/.kodi/addons/script.module.backports.functools_lru_cache/lib:/home/pi/.kodi/addons/script.module.certifi/lib:/home/pi/.kodi/addons/script.module.chardet/lib:/home/pi/.kodi/addons/script.module.dateutil/lib:/home/pi/.kodi/addons/script.module.idna/lib:/home/pi/.kodi/addons/script.module.requests/lib:/home/pi/.kodi/addons/script.module.six/lib:/home/pi/.kodi/addons/script.module.trakt/lib:/home/pi/.kodi/addons/script.module.urllib3/lib:/usr/lib/python2.7:/usr/lib/python2.7/plat-arm-linux-gnueabihf:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/gtk-2.0
2019-12-19 18:41:43.220 T:2294276304   DEBUG: CPythonInvoker(11, /home/pi/.kodi/addons/script.trakt/defaultscript.py): entering source directory /home/pi/.kodi/addons/script.trakt
2019-12-19 18:41:43.220 T:2294276304   DEBUG: CPythonInvoker(11, /home/pi/.kodi/addons/script.trakt/defaultscript.py): instantiating addon using automatically obtained id of "script.trakt" dependent on version 2.26.0 of the xbmc.python api
2019-12-19 18:41:43.959 T:2294276304    INFO: CPythonInvoker(11, /home/pi/.kodi/addons/script.trakt/defaultscript.py): script successfully run
2019-12-19 18:41:43.959 T:2294276304   DEBUG: onExecutionDone(11, /home/pi/.kodi/addons/script.trakt/defaultscript.py)
2019-12-19 18:41:44.067 T:2666516688   DEBUG: [script.trakt] resources.lib.service: Queued dispatch: {u'action': u'manualSync', u'silent': False, u'library': u'all'}
2019-12-19 18:41:44.067 T:2666516688   DEBUG: [script.trakt] resources.lib.service: Dispatch: {u'action': u'manualSync', u'silent': False, u'library': u'all'}
2019-12-19 18:41:44.067 T:2666516688   DEBUG: [script.trakt] resources.lib.service: Performing a manual sync.
2019-12-19 18:41:44.068 T:2294276304   DEBUG: [script.trakt] resources.lib.sync: Starting synchronization with Trakt.tv
2019-12-19 18:41:44.069 T:2294276304   DEBUG: [script.trakt] resources.lib.syncMovies: [Movies Sync] Getting movie data from Kodi
2019-12-19 18:41:45.463 T:2294276304   DEBUG: [script.trakt] resources.lib.syncMovies: [Movies Sync] Getting movie collection from Trakt.tv
2019-12-19 18:41:45.724 T:2294276304   DEBUG: [script.trakt] urllib3.connectionpool: https://api.trakt.tv:443 "GET /sync/collection/movies HTTP/1.1" 200 None
2019-12-19 18:41:45.779 T:2294276304   DEBUG: [script.trakt] resources.lib.syncMovies: [Movies Sync] Error getting Trakt.tv movie list, aborting movie Sync.
2019-12-19 18:41:45.803 T:2294276304   DEBUG: [script.trakt] resources.lib.syncEpisodes: [Episodes Sync] Getting show data from Kodi
2019-12-19 18:41:46.012 T:2294276304   DEBUG: [script.trakt] resources.lib.syncEpisodes: [Episode Sync] Getting shows from kodi finished [{'rating': 0, u'tvshowid': 1, u'title': u'12 Monkeys', 'ids': {u'tvdb': u'272644', u'tmdb': u'60948', u'imdb': u'tt3148266'}, u'userrating': 0, u'year': 2015}, ...]
2019-12-19 18:41:46.013 T:2294276304   DEBUG: [script.trakt] resources.lib.syncEpisodes: [Episodes Sync] Getting episode data from Kodi
2019-12-19 18:41:54.599 T:2294276304   DEBUG: [script.trakt] resources.lib.syncEpisodes: [Episodes Sync] 'Gaza: the killing zone' has no episodes in Kodi.
2019-12-19 18:41:55.707 T:2294276304   DEBUG: [script.trakt] resources.lib.syncEpisodes: [Episodes Sync] Getting episode collection/watched/rated from Trakt.tv
2019-12-19 18:41:56.003 T:2294276304   DEBUG: [script.trakt] urllib3.connectionpool: https://api.trakt.tv:443 "GET /sync/collection/shows HTTP/1.1" 200 None
2019-12-19 18:41:56.144 T:2294276304   DEBUG: [script.trakt] resources.lib.syncEpisodes: [Episodes Sync] Invalid Trakt.tv show list, possible error getting data from Trakt, aborting Trakt.tv collection/watched/rated update.
2019-12-19 18:41:56.146 T:2294276304   DEBUG: [script.trakt] resources.lib.syncEpisodes: [Episodes Sync] Error getting Trakt.tv collected show list, aborting tv show sync.
2019-12-19 18:41:56.208 T:2294276304   DEBUG: [script.trakt] resources.lib.sync: [Sync] Finished synchronization with Trakt.tv`
@pogman-code
Copy link

pogman-code commented Dec 20, 2019 •
edited

I have the same issue, seem like the Trakt API fails?
"GET /sync/collection/shows HTTP/1.1" 200 None
"GET /sync/collection/movies HTTP/1.1" 200 None
Here is my logs:

2019-12-20 10:36:04.578 T:139917509244672   DEBUG: CWebServer[8080]: request received for /jsonrpc
2019-12-20 10:36:04.608 T:139917443610368   DEBUG: Thread LanguageInvoker start, auto delete: false
2019-12-20 10:36:04.608 T:139917443610368    INFO: initializing python engine.
2019-12-20 10:36:04.608 T:139917443610368   DEBUG: CPythonInvoker(16, /var/lib/kodi/.kodi/addons/script.trakt/defaultscript.py): start processing
2019-12-20 10:36:04.992 T:139917443610368   DEBUG: -->Python Interpreter Initialized<--
2019-12-20 10:36:04.992 T:139917443610368   DEBUG: CPythonInvoker(16, /var/lib/kodi/.kodi/addons/script.trakt/defaultscript.py): the source file to load is "/var/lib/kodi/.kodi/addons/script.trakt/defaultscript.py"
2019-12-20 10:36:04.993 T:139917443610368   DEBUG: CPythonInvoker(16, /var/lib/kodi/.kodi/addons/script.trakt/defaultscript.py): setting the Python path to /var/lib/kodi/.kodi/addons/script.trakt:/var/lib/kodi/.kodi/addons/script.module.arrow/lib:/var/lib/kodi/.kodi/addons/script.module.backports.functools_lru_cache/lib:/var/lib/kodi/.kodi/addons/script.module.certifi/lib:/var/lib/kodi/.kodi/addons/script.module.chardet/lib:/var/lib/kodi/.kodi/addons/script.module.dateutil/lib:/var/lib/kodi/.kodi/addons/script.module.idna/lib:/var/lib/kodi/.kodi/addons/script.module.requests/lib:/var/lib/kodi/.kodi/addons/script.module.six/lib:/var/lib/kodi/.kodi/addons/script.module.trakt/lib:/var/lib/kodi/.kodi/addons/script.module.urllib3/lib:/usr/lib/python27.zip:/usr/lib/python2.7:/usr/lib/python2.7/plat-linux2:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/lib/python2.7/site-packages:/usr/lib/python2.7/site-packages
2019-12-20 10:36:04.993 T:139917443610368   DEBUG: CPythonInvoker(16, /var/lib/kodi/.kodi/addons/script.trakt/defaultscript.py): entering source directory /var/lib/kodi/.kodi/addons/script.trakt
2019-12-20 10:36:04.993 T:139917443610368   DEBUG: CPythonInvoker(16, /var/lib/kodi/.kodi/addons/script.trakt/defaultscript.py): instantiating addon using automatically obtained id of "script.trakt" dependent on version 2.26.0 of the xbmc.python api
2019-12-20 10:36:05.518 T:139917443610368    INFO: CPythonInvoker(16, /var/lib/kodi/.kodi/addons/script.trakt/defaultscript.py): script successfully run
2019-12-20 10:36:05.518 T:139917443610368   DEBUG: onExecutionDone(16, /var/lib/kodi/.kodi/addons/script.trakt/defaultscript.py)
2019-12-20 10:36:05.585 T:139917443610368    INFO: Python interpreter stopped
2019-12-20 10:36:05.585 T:139917443610368   DEBUG: Thread LanguageInvoker 139917443610368 terminating
2019-12-20 10:36:05.747 T:139917812295424   DEBUG: [script.trakt] resources.lib.service: Queued dispatch: {u'action': u'manualSync', u'silent': False, u'library': u'all'}
2019-12-20 10:36:05.747 T:139917812295424   DEBUG: [script.trakt] resources.lib.service: Dispatch: {u'action': u'manualSync', u'silent': False, u'library': u'all'}
2019-12-20 10:36:05.747 T:139917812295424   DEBUG: [script.trakt] resources.lib.service: Performing a manual sync.
2019-12-20 10:36:05.748 T:139917443610368   DEBUG: [script.trakt] resources.lib.sync: Starting synchronization with Trakt.tv
2019-12-20 10:36:05.749 T:139917443610368   DEBUG: DialogProgress::Open called
2019-12-20 10:36:05.749 T:139917443610368   DEBUG: ------ Window Init (DialogConfirm.xml) ------
2019-12-20 10:36:05.750 T:139917443610368   DEBUG: [script.trakt] resources.lib.syncMovies: [Movies Sync] Getting movie data from Kodi
2019-12-20 10:36:09.337 T:139917443610368   DEBUG: [script.trakt] resources.lib.syncMovies: [Movies Sync] Getting movie collection from Trakt.tv
2019-12-20 10:36:09.719 T:139917443610368   DEBUG: [script.trakt] urllib3.connectionpool: https://api.trakt.tv:443 "GET /sync/collection/movies HTTP/1.1" 200 None
2019-12-20 10:36:09.753 T:139917443610368   DEBUG: [script.trakt] resources.lib.syncMovies: [Movies Sync] Error getting Trakt.tv movie list, aborting movie Sync.
2019-12-20 10:36:09.786 T:139917443610368   DEBUG: DialogProgress::Open called (already running)!
2019-12-20 10:36:09.787 T:139917443610368   DEBUG: ------ Window Init (DialogConfirm.xml) ------
2019-12-20 10:36:09.789 T:139917443610368   DEBUG: [script.trakt] resources.lib.syncEpisodes: [Episodes Sync] Getting show data from Kodi
2019-12-20 10:36:10.474 T:139917443610368   DEBUG: [script.trakt] resources.lib.syncEpisodes: [Episode Sync] Getting shows from kodi finished [{'rating': 0, u'tvshowid': 1, u'title': u'The Rain', 'ids': {u'tvdb': u'341704', u'imdb': u'tt6656238'}, u'userrating': 0, u'year': 2018}, {'rating': 0, u'tvshowid': 2, u'title': u'DARK', 'ids': {u'tvdb': u'334824', u'imdb': u'tt5753856'}, u'userrating': 0, u'year': 2017}, {'rating': 0, u'tvshowid': 3, u'title': u'This Is Us', 'ids': {u'tvdb': u'311714', u'imdb': u'tt5555260'}, u'userrating': 0, u'year': 2016}, ...]
2019-12-20 10:36:10.474 T:139917443610368   DEBUG: [script.trakt] resources.lib.syncEpisodes: [Episodes Sync] Getting episode data from Kodi
2019-12-20 10:36:10.994 T:139917443610368   DEBUG: [script.trakt] resources.lib.utilities: checkExclusion(): Video is from location, which is currently set as excluded path 2.
2019-12-20 10:36:11.399 T:139917443610368   DEBUG: Previous line repeats 137 times.
2019-12-20 10:36:11.399 T:139917443610368   DEBUG: [script.trakt] resources.lib.syncEpisodes: [Episodes Sync] 'Z Nation' has no episodes in Kodi.
2019-12-20 10:36:11.442 T:139917443610368   DEBUG: [script.trakt] resources.lib.utilities: checkExclusion(): Video is from location, which is currently set as excluded path 2.
2019-12-20 10:36:11.454 T:139917443610368   DEBUG: Previous line repeats 3 times.
2019-12-20 10:36:11.454 T:139917443610368   DEBUG: [script.trakt] resources.lib.syncEpisodes: [Episodes Sync] 'The 100' has no episodes in Kodi.
2019-12-20 10:36:11.477 T:139917443610368   DEBUG: [script.trakt] resources.lib.utilities: checkExclusion(): Video is from location, which is currently set as excluded path 2.
2019-12-20 10:36:11.811 T:139917443610368   DEBUG: Previous line repeats 89 times.
2019-12-20 10:36:11.811 T:139917443610368   DEBUG: [script.trakt] resources.lib.syncEpisodes: [Episodes Sync] 'Neon Genesis Evangelion' has no episodes in Kodi.
2019-12-20 10:36:11.820 T:139917443610368   DEBUG: [script.trakt] resources.lib.syncEpisodes: [Episodes Sync] 'Re:ZERO -Starting Life in Another World-' has no episodes in Kodi.
2019-12-20 10:36:11.828 T:139917443610368   DEBUG: [script.trakt] resources.lib.syncEpisodes: [Episodes Sync] 'The Expanse' has no episodes in Kodi.
2019-12-20 10:36:11.846 T:139917443610368   DEBUG: [script.trakt] resources.lib.utilities: checkExclusion(): Video is from location, which is currently set as excluded path 2.
2019-12-20 10:36:12.565 T:139917443610368   DEBUG: Previous line repeats 235 times.
2019-12-20 10:36:12.565 T:139917443610368   DEBUG: [script.trakt] resources.lib.syncEpisodes: [Episodes Sync] 'Code Geass: Lelouch of the Rebellion' has no episodes in Kodi.
2019-12-20 10:36:12.597 T:139917443610368   DEBUG: [script.trakt] resources.lib.utilities: checkExclusion(): Video is from location, which is currently set as excluded path 2.
2019-12-20 10:36:12.683 T:139917481109248   DEBUG: Previous line repeats 15 times.
2019-12-20 10:36:12.683 T:139917481109248   DEBUG: Thread JobWorker 139917481109248 terminating (autodelete)
2019-12-20 10:36:12.722 T:139917443610368   DEBUG: [script.trakt] resources.lib.utilities: checkExclusion(): Video is from location, which is currently set as excluded path 2.
2019-12-20 10:36:42.300 T:139917443610368   DEBUG: Previous line repeats 115 times.
2019-12-20 10:36:42.301 T:139917443610368   DEBUG: [script.trakt] resources.lib.syncEpisodes: [Episodes Sync] 'Unbelievable' has no episodes in Kodi.
2019-12-20 10:36:42.617 T:139917443610368   DEBUG: [script.trakt] resources.lib.utilities: checkExclusion(): Video is from location, which is currently set as excluded path 2.
2019-12-20 10:36:43.624 T:139917443610368   DEBUG: Previous line repeats 149 times.
2019-12-20 10:36:43.625 T:139917443610368   DEBUG: [script.trakt] resources.lib.syncEpisodes: [Episodes Sync] Getting episode collection/watched/rated from Trakt.tv
2019-12-20 10:36:43.976 T:139917443610368   DEBUG: [script.trakt] urllib3.connectionpool: https://api.trakt.tv:443 "GET /sync/collection/shows HTTP/1.1" 200 None
2019-12-20 10:36:44.035 T:139917443610368   DEBUG: [script.trakt] resources.lib.syncEpisodes: [Episodes Sync] Invalid Trakt.tv show list, possible error getting data from Trakt, aborting Trakt.tv collection/watched/rated update.
2019-12-20 10:36:44.039 T:139917443610368   DEBUG: [script.trakt] resources.lib.syncEpisodes: [Episodes Sync] Error getting Trakt.tv collected show list, aborting tv show sync.
2019-12-20 10:36:44.079 T:139917443610368   DEBUG: [script.trakt] resources.lib.sync: [Sync] Finished synchronization with Trakt.tv
2019-12-20 10:36:44.318 T:139918998018752   DEBUG: ------ Window Deinit (DialogConfirm.xml) ------

Edit: I made a python script to test the API calls and they seem to work though

@ronnodas
Copy link
Author

ronnodas commented Dec 21, 2019 •
edited

Changing the debug message to output the actual exception, I got Exception('"arrow" module is not available',). I tried reinstalling script.module.arrow and running pip install arrow but that doesn't seem to have helped.

@razzeee
Copy link
Collaborator

razzeee commented Dec 21, 2019 via email

@razzeee
Copy link
Collaborator

razzeee commented Dec 21, 2019 via email

@ronnodas
Copy link
Author

Here are the full logs. To answer your question, it's Kodi 18.4 running on raspbian, on a raspberry pi 4,
kodi.log

@pogman-code
Copy link

Oops, sorry! Here is mine:
https://paste.kodi.tv/xedolopere.kodi

Running Arch Linux 5.4.5 / Python 3.8.0 / Kodi 18.5

@razzeee
Copy link
Collaborator

razzeee commented Dec 22, 2019

@adrian-maurin your error is weird, as you clearly have that module installed and still get the error. you installed everything via the official repo?

@ronnodas your log does not show an error, so you both seem to have different problems. did you try to login to trakt again?

@pogman-code
Copy link

Yup, I checked that arrow is installed, it's OK. I did installed it through the official repo.
And the weirdest part is that it work flawlessly until this week.

Can I debug anything else?

@ronnodas
Copy link
Author

@razzeee I'm not sure what you mean, see lines 4980 and 4994. The logs are from the unmodified version of the script, if I change the output to the exception thrown in that point of the script, it's an Exception('"arrow" module is not available',).

@pogman-code
Copy link

By the way I have the same 2019-12-22 21:19:44.387 T:140631145948928 DEBUG: [script.trakt] resources.lib.syncEpisodes: "arrow" module is not available

Don't understand why though

@pogman-code
Copy link

Found the issue: arrow-py/arrow#495
recent versions of arrow mess up python2 compat

@ronnodas
Copy link
Author

Does that help with a fix/workaround? Should I try installing an older version of script.module.arrow?

@pogman-code
Copy link

pogman-code commented Dec 23, 2019 •
edited

pip2 install backports.functools_lru_cache;python_version<"3" fixed it for me

But first, try to import arrow manually to be sure we have the same issue

PYTHONPATH=/home/pi/.kodi/addons/script.trakt:/home/pi/.kodi/addons/script.module.arrow/lib:/home/pi/.kodi/addons/script.module.backports.functools_lru_cache/lib:/home/pi/.kodi/addons/script.module.certifi/lib:/home/pi/.kodi/addons/script.module.chardet/lib:/home/pi/.kodi/addons/script.module.dateutil/lib:/home/pi/.kodi/addons/script.module.idna/lib:/home/pi/.kodi/addons/script.module.requests/lib:/home/pi/.kodi/addons/script.module.six/lib:/home/pi/.kodi/addons/script.module.trakt/lib:/home/pi/.kodi/addons/script.module.urllib3/lib:/usr/lib/python2.7:/usr/lib/python2.7/plat-arm-linux-gnueabihf:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/home/pi/.local/lib/python2.7/site-packages:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/gtk-2.0 python2

>>> import arrow

@ronnodas
Copy link
Author

Thanks! For me, installing functools-lru-cache via variations of the pip command did not work, but what did work was installing the debian python-backports.functools-lru-cache package.

@razzeee razzeee mentioned this issue Jan 28, 2020
Open
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
None yet
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
3 participants
@razzeee @ronnodas @pogman-code