· 6 years ago · Jan 30, 2019, 06:58 PM
12019-01-30 12:52:00,612 (14b7195e6740) : INFO (core:349) - Starting framework core - Version: 2.6.3, Build: cc260c4 (Fri Dec 14 20:06:30 UTC 2018)
22019-01-30 12:52:00,613 (14b7195e6740) : DEBUG (core:361) - Using the elevated policy
32019-01-30 12:52:00,613 (14b7195e6740) : DEBUG (core:450) - Starting runtime component.
42019-01-30 12:52:00,615 (14b7195e6740) : DEBUG (core:450) - Starting caching component.
52019-01-30 12:52:00,615 (14b7195e6740) : DEBUG (core:450) - Starting data component.
62019-01-30 12:52:00,616 (14b7195e6740) : DEBUG (core:450) - Starting networking component.
72019-01-30 12:52:00,616 (14b7195e6740) : DEBUG (networking:284) - Loaded HTTP cookies
82019-01-30 12:52:00,616 (14b7195e6740) : DEBUG (networking:452) - Setting the default network timeout to 20.0
92019-01-30 12:52:00,617 (14b7195e6740) : DEBUG (core:450) - Starting localization component.
102019-01-30 12:52:00,617 (14b7195e6740) : INFO (localization:409) - Setting the default locale to en-us
112019-01-30 12:52:00,617 (14b7195e6740) : DEBUG (localization:427) - Loaded en strings
122019-01-30 12:52:00,618 (14b7195e6740) : DEBUG (core:450) - Starting messaging component.
132019-01-30 12:52:00,618 (14b7195e6740) : DEBUG (core:450) - Starting debugging component.
142019-01-30 12:52:00,618 (14b710926700) : DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/system/messaging/clear_events/com.plexapp.plugins.trakttv'
152019-01-30 12:52:00,619 (14b7195e6740) : DEBUG (core:450) - Starting services component.
162019-01-30 12:52:00,620 (14b7195e6740) : DEBUG (core:450) - Starting myplex component.
172019-01-30 12:52:00,620 (14b7195e6740) : DEBUG (core:450) - Starting notifications component.
182019-01-30 12:52:00,817 (14b7195e6740) : DEBUG (accessor:68) - Creating a new model access point for provider com.plexapp.plugins.trakttv in namespace 'metadata'
192019-01-30 12:52:00,824 (14b7195e6740) : DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/resourceHashes'
202019-01-30 12:52:01,016 (14b710926700) : DEBUG (services:265) - Plug-in is not daemonized - loading services from system
212019-01-30 12:52:01,016 (14b7195e6740) : DEBUG (runtime:1117) - Created a thread named 'load_all_services'
222019-01-30 12:52:01,017 (14b710926700) : DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_'
232019-01-30 12:52:01,018 (14b7195e6740) : DEBUG (runtime:1117) - Created a thread named 'get_server_info'
242019-01-30 12:52:01,018 (14b7103e4700) : DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400'
252019-01-30 12:52:01,019 (14b7195e6740) : DEBUG (core:150) - Finished starting framework core
262019-01-30 12:52:01,019 (14b7195e6740) : DEBUG (core:560) - Loading plug-in code
272019-01-30 12:52:01,059 (14b7103e4700) : DEBUG (core:538) - Machine identifier is 34ed0906a5a6ccd970c24539ab9f7115ced83206
282019-01-30 12:52:01,060 (14b7103e4700) : DEBUG (core:539) - Server version is 1.14.1.5488-cc260c476
292019-01-30 12:52:01,075 (14b7195e6740) : DEBUG (core:566) - Finished loading plug-in code
302019-01-30 12:52:01,214 (14b7195e6740) : DEBUG (fs_migrator:19) - Running migration: <fs_migrator.Clean object at 0x14b71013b910>
312019-01-30 12:52:01,238 (14b7195e6740) : DEBUG (fs_migrator:19) - Running migration: <fs_migrator.ForceLegacy object at 0x14b71013b950>
322019-01-30 12:52:01,241 (14b7195e6740) : DEBUG (fs_migrator:19) - Running migration: <fs_migrator.SelectiveSync object at 0x14b71013b990>
332019-01-30 12:52:01,527 - plugin.core.logger.main (14b7195e6740) : DEBUG (plugin.core.logger.main:65) - Initialized logging (report: True, storage: False)
342019-01-30 12:52:01,550 - pyemitter (14b7195e6740) : DEBUG (pyemitter:45) - [exception_wrappers.manager.Manager]:on(events: ['exception'], func: <bound method InterfaceMessagesMeta._on_exception of <class 'plugin.core.message.InterfaceMessages'>>)
352019-01-30 12:52:01,552 - plugin.core.message (14b7195e6740) : INFO (plugin.core.message:90) - Bound to exception events
362019-01-30 12:52:01,612 - com.plexapp.plugins.trakttv (14b710926700) : DEBUG (com.plexapp.plugins.trakttv:362) - Loaded services
372019-01-30 12:52:01,619 - com.plexapp.plugins.trakttv (14b7105e5700) : DEBUG (com.plexapp.plugins.trakttv:438) - No shared code to load
382019-01-30 12:52:01,619 - com.plexapp.plugins.trakttv (14b7195e6740) : DEBUG (com.plexapp.plugins.trakttv:258) - Loaded preferences from DefaultPrefs.json
392019-01-30 12:52:01,620 - com.plexapp.plugins.trakttv (14b7195e6740) : DEBUG (com.plexapp.plugins.trakttv:178) - Loaded the user preferences for com.plexapp.plugins.trakttv
402019-01-30 12:52:01,621 - plugin.core.environment (14b7195e6740) : INFO (plugin.core.environment:127) - Locale extension not available, using the "C" locale
412019-01-30 12:52:01,627 - plugin.core.environment (14b7195e6740) : INFO (plugin.core.environment:183) - Using languages: ['en_us', 'en'] (translation: <gettext.GNUTranslations instance at 0x14b71011e128>)
422019-01-30 12:52:01,718 - plugin.core.configuration (14b7195e6740) : DEBUG (plugin.core.configuration:33) - Parsing configuration file: 'Data/com.plexapp.plugins.trakttv/advanced.ini'
432019-01-30 12:52:01,736 - plugin.core.libraries.manager (14b7195e6740) : INFO (plugin.core.libraries.manager:205) - Cached libraries to 'Plug-in Support/Data/com.plexapp.plugins.trakttv/Libraries'
442019-01-30 12:52:01,736 - plugin.core.libraries.manager (14b7195e6740) : INFO (plugin.core.libraries.manager:40) - Using native libraries at 'Plug-in Support/Data/com.plexapp.plugins.trakttv/Libraries'
452019-01-30 12:52:01,736 - plugin.core.libraries.helpers.path (14b7195e6740) : DEBUG (plugin.core.libraries.helpers.path:45) - Removed path: 'Plug-ins/Trakttv.bundle/Contents/Libraries/Linux/i386'
462019-01-30 12:52:01,737 - plugin.core.libraries.manager (14b7195e6740) : DEBUG (plugin.core.libraries.manager:214) - Bits: '64bit', Machine: 'x86_64'
472019-01-30 12:52:01,737 - plugin.core.libraries.manager (14b7195e6740) : DEBUG (plugin.core.libraries.manager:224) - System: 'Linux', Architecture: 'x86_64'
482019-01-30 12:52:01,737 - plugin.core.libraries.helpers.path (14b7195e6740) : DEBUG (plugin.core.libraries.helpers.path:30) - Inserted path: 'Plug-in Support/Data/com.plexapp.plugins.trakttv/Libraries/Linux/x86_64'
492019-01-30 12:52:01,737 - plugin.core.libraries.manager (14b7195e6740) : DEBUG (plugin.core.libraries.manager:276) - UCS: 'ucs4'
502019-01-30 12:52:01,738 - plugin.core.libraries.helpers.path (14b7195e6740) : DEBUG (plugin.core.libraries.helpers.path:30) - Inserted path: 'Plug-in Support/Data/com.plexapp.plugins.trakttv/Libraries/Linux/x86_64/ucs4'
512019-01-30 12:52:01,738 - plugin.core.libraries.helpers.arm (14b7195e6740) : DEBUG (plugin.core.libraries.helpers.arm:115) - Fetching processor information from "/proc/cpuinfo"...
522019-01-30 12:52:01,742 - plugin.core.libraries.helpers.arm (14b7195e6740) : INFO (plugin.core.libraries.helpers.arm:44) - Unable to retrieve processor identifier from "/proc/cpuinfo"
532019-01-30 12:52:01,742 - plugin.core.libraries.helpers.system (14b7195e6740) : INFO (plugin.core.libraries.helpers.system:317) - Executable at '' doesn't exist, using '/bin/ls' instead
542019-01-30 12:52:01,746 - plugin.core.libraries.helpers.system (14b7195e6740) : INFO (plugin.core.libraries.helpers.system:340) - Unable to find attributes section in ELF: '/bin/ls'
552019-01-30 12:52:01,747 - plugin.core.libraries.manager (14b7195e6740) : DEBUG (plugin.core.libraries.manager:285) - CPU Type: None
562019-01-30 12:52:01,747 - plugin.core.libraries.manager (14b7195e6740) : DEBUG (plugin.core.libraries.manager:286) - Page Size: '4k'
572019-01-30 12:52:01,747 - plugin.core.libraries.manager (14b7195e6740) : DEBUG (plugin.core.libraries.manager:238) - Inserted libraries path for system: 'Linux', arch: 'x86_64'
582019-01-30 12:52:01,747 - plugin.core.libraries.manager (14b7195e6740) : INFO (plugin.core.libraries.manager:55) - [PATH] Plug-in Support/Data/com.plexapp.plugins.trakttv/Libraries/Linux/x86_64/ucs4
592019-01-30 12:52:01,748 - plugin.core.libraries.manager (14b7195e6740) : INFO (plugin.core.libraries.manager:55) - [PATH] Plug-in Support/Data/com.plexapp.plugins.trakttv/Libraries/Linux/x86_64
602019-01-30 12:52:01,748 - plugin.core.libraries.manager (14b7195e6740) : INFO (plugin.core.libraries.manager:55) - [PATH] Plug-ins/Trakttv.bundle/Contents/Libraries/Shared
612019-01-30 12:52:01,748 - plugin.core.libraries.manager (14b7195e6740) : INFO (plugin.core.libraries.manager:60) - Testing native library support...
622019-01-30 12:52:01,748 - plugin.core.libraries.tests.core.base (14b7195e6740) : DEBUG (plugin.core.libraries.tests.core.base:96) - Starting test: plugin.core.libraries.tests.apsw_:test_import
632019-01-30 12:52:01,966 - plugin.core.libraries.manager (14b7195e6740) : INFO (plugin.core.libraries.manager:106) - apsw: available (sqlite: 3015000, apsw: 3.15.0-r1)
642019-01-30 12:52:01,967 - plugin.core.libraries.tests.core.base (14b7195e6740) : DEBUG (plugin.core.libraries.tests.core.base:96) - Starting test: plugin.core.libraries.tests.cryptography_:test_import
652019-01-30 12:52:02,164 - plugin.core.libraries.manager (14b7195e6740) : INFO (plugin.core.libraries.manager:111) - cryptography: available (1.5.3)
662019-01-30 12:52:02,164 - plugin.core.libraries.tests.core.base (14b7195e6740) : DEBUG (plugin.core.libraries.tests.core.base:96) - Starting test: plugin.core.libraries.tests.llist_:test_import
672019-01-30 12:52:02,349 - plugin.core.libraries.manager (14b7195e6740) : INFO (plugin.core.libraries.manager:113) - llist: available
682019-01-30 12:52:02,350 - plugin.core.libraries.tests.core.base (14b7195e6740) : DEBUG (plugin.core.libraries.tests.core.base:96) - Starting test: plugin.core.libraries.tests.openssl_:test_import
692019-01-30 12:52:02,584 - plugin.core.libraries.tests.openssl_ (14b7195e6740) : DEBUG (plugin.core.libraries.tests.openssl_:104) - Using standard SSL library (ssl)
702019-01-30 12:52:02,584 - plugin.core.libraries.manager (14b7195e6740) : INFO (plugin.core.libraries.manager:111) - openssl: available (268443823)
712019-01-30 12:52:02,899 - plugin.core.database.connection (14b7195e6740) : DEBUG (plugin.core.database.connection:34) - Connected to database at '/config/Library/Application Support/Plex Media Server/Plug-in Support/Databases/com.plexapp.plugins.trakttv.db'
722019-01-30 12:52:03,051 - pyemitter (14b7195e6740) : DEBUG (pyemitter:45) - [plex_activity.activity.Activity ]:on(events: ['websocket.timeline.created'], func: <bound method Metadata.timeline_created of <plex_metadata.metadata.Metadata object at 0x14b6e34c8bd0>>)
732019-01-30 12:52:03,052 - pyemitter (14b7195e6740) : DEBUG (pyemitter:45) - [plex_activity.activity.Activity ]:on(events: ['websocket.timeline.deleted'], func: <bound method Metadata.timeline_deleted of <plex_metadata.metadata.Metadata object at 0x14b6e34c8bd0>>)
742019-01-30 12:52:03,052 - pyemitter (14b7195e6740) : DEBUG (pyemitter:45) - [plex_activity.activity.Activity ]:on(events: ['websocket.timeline.finished'], func: <bound method Metadata.timeline_finished of <plex_metadata.metadata.Metadata object at 0x14b6e34c8bd0>>)
752019-01-30 12:52:03,087 - plex_database.core.database (14b7195e6740) : DEBUG (plex_database.core.database:29) - Connecting to '/config/Library/Application Support/Plex Media Server/Plug-in Support/Databases/com.plexapp.plugins.library.db'
762019-01-30 12:52:03,197 - oem_framework.models.core.registry (14b7195e6740) : DEBUG (oem_framework.models.core.registry:29) - [Model ] Registered: <class 'oem_framework.models.core.base.model.Model'>
772019-01-30 12:52:03,198 - oem_framework.models.core.registry (14b7195e6740) : DEBUG (oem_framework.models.core.registry:29) - [BaseMapping ] Registered: <class 'oem_framework.models.core.base.mapping.BaseMapping'>
782019-01-30 12:52:03,202 - oem_framework.models.core.registry (14b7195e6740) : DEBUG (oem_framework.models.core.registry:29) - [BaseMedia ] Registered: <class 'oem_framework.models.core.base.media.BaseMedia'>
792019-01-30 12:52:03,202 - oem_framework.models.core.registry (14b7195e6740) : DEBUG (oem_framework.models.core.registry:29) - [Collection ] Registered: <class 'oem_framework.models.collection.Collection'>
802019-01-30 12:52:03,203 - oem_framework.models.core.registry (14b7195e6740) : DEBUG (oem_framework.models.core.registry:29) - [Database ] Registered: <class 'oem_framework.models.database.Database'>
812019-01-30 12:52:03,203 - oem_framework.models.core.registry (14b7195e6740) : DEBUG (oem_framework.models.core.registry:29) - [Index ] Registered: <class 'oem_framework.models.index.Index'>
822019-01-30 12:52:03,204 - oem_framework.models.core.registry (14b7195e6740) : DEBUG (oem_framework.models.core.registry:29) - [Item ] Registered: <class 'oem_framework.models.item.Item'>
832019-01-30 12:52:03,205 - oem_framework.models.core.registry (14b7195e6740) : DEBUG (oem_framework.models.core.registry:29) - [Metadata ] Registered: <class 'oem_framework.models.metadata.Metadata'>
842019-01-30 12:52:03,205 - oem_framework.models.core.registry (14b7195e6740) : DEBUG (oem_framework.models.core.registry:29) - [Range ] Registered: <class 'oem_framework.models.range.Range'>
852019-01-30 12:52:03,206 - oem_framework.models.core.registry (14b7195e6740) : DEBUG (oem_framework.models.core.registry:29) - [Movie ] Registered: <class 'oem_framework.models.movie.Movie'>
862019-01-30 12:52:03,207 - oem_framework.models.core.registry (14b7195e6740) : DEBUG (oem_framework.models.core.registry:29) - [Part ] Registered: <class 'oem_framework.models.part.Part'>
872019-01-30 12:52:03,208 - oem_framework.models.core.registry (14b7195e6740) : DEBUG (oem_framework.models.core.registry:29) - [Show ] Registered: <class 'oem_framework.models.show.Show'>
882019-01-30 12:52:03,210 - oem_framework.models.core.registry (14b7195e6740) : DEBUG (oem_framework.models.core.registry:29) - [Season ] Registered: <class 'oem_framework.models.season.Season'>
892019-01-30 12:52:03,210 - oem_framework.models.core.registry (14b7195e6740) : DEBUG (oem_framework.models.core.registry:29) - [SeasonMapping ] Registered: <class 'oem_framework.models.season.SeasonMapping'>
902019-01-30 12:52:03,212 - oem_framework.models.core.registry (14b7195e6740) : DEBUG (oem_framework.models.core.registry:29) - [Episode ] Registered: <class 'oem_framework.models.episode.Episode'>
912019-01-30 12:52:03,212 - oem_framework.models.core.registry (14b7195e6740) : DEBUG (oem_framework.models.core.registry:29) - [EpisodeMapping] Registered: <class 'oem_framework.models.episode.EpisodeMapping'>
922019-01-30 12:52:03,216 - oem_framework.models.core.registry (14b7195e6740) : DEBUG (oem_framework.models.core.registry:27) - [Collection ] Replaced: <class 'oem_core.models.collection.Collection'>
932019-01-30 12:52:03,216 - oem_framework.models.core.registry (14b7195e6740) : DEBUG (oem_framework.models.core.registry:27) - [Database ] Replaced: <class 'oem_core.models.database.Database'>
942019-01-30 12:52:03,217 - oem_framework.models.core.registry (14b7195e6740) : DEBUG (oem_framework.models.core.registry:27) - [Index ] Replaced: <class 'oem_core.models.index.Index'>
952019-01-30 12:52:03,217 - oem_framework.models.core.registry (14b7195e6740) : DEBUG (oem_framework.models.core.registry:27) - [Metadata ] Replaced: <class 'oem_core.models.metadata.Metadata'>
962019-01-30 12:52:03,249 - pyemitter (14b7195e6740) : DEBUG (pyemitter:45) - [plex_activity.activity.Activity ]:on(events: ['websocket.scanner.started'], func: <bound method LibraryState.on_started of <plugin.sync.triggers.library_update.LibraryState object at 0x14b6e2c17b50>>)
972019-01-30 12:52:03,249 - pyemitter (14b7195e6740) : DEBUG (pyemitter:45) - [plex_activity.activity.Activity ]:on(events: ['websocket.timeline.created'], func: <bound method LibraryState.on_added of <plugin.sync.triggers.library_update.LibraryState object at 0x14b6e2c17b50>>)
982019-01-30 12:52:03,249 - pyemitter (14b7195e6740) : DEBUG (pyemitter:45) - [plex_activity.activity.Activity ]:on(events: ['websocket.scanner.finished'], func: <bound method LibraryUpdateTrigger.trigger of <plugin.sync.triggers.library_update.LibraryUpdateTrigger object at 0x14b6e2c17b10>>)
992019-01-30 12:52:03,249 - pyemitter (14b7195e6740) : DEBUG (pyemitter:45) - [plex_activity.activity.Activity ]:on(events: ['websocket.timeline.loading'], func: <bound method LibraryUpdateTrigger.trigger of <plugin.sync.triggers.library_update.LibraryUpdateTrigger object at 0x14b6e2c17b10>>)
1002019-01-30 12:52:03,250 - pyemitter (14b7195e6740) : DEBUG (pyemitter:45) - [plex_activity.activity.Activity ]:on(events: ['websocket.timeline.finished'], func: <bound method LibraryUpdateTrigger.trigger of <plugin.sync.triggers.library_update.LibraryUpdateTrigger object at 0x14b6e2c17b10>>)
1012019-01-30 12:52:03,341 - com.plexapp.plugins.trakttv (14b7195e6740) : DEBUG (com.plexapp.plugins.trakttv:640) - Adding a prefix handler for 'Trakt.tv' ('/video/trakt')
1022019-01-30 12:52:03,490 - com.plexapp.plugins.trakttv (14b7195e6740) : DEBUG (com.plexapp.plugins.trakttv:69) - Exposing function Api for remote access
1032019-01-30 12:52:03,491 - plugin.core.singleton (14b7195e6740) : DEBUG (plugin.core.singleton:92) - Starting server (port: 35374)...
1042019-01-30 12:52:03,491 - plugin.core.singleton (14b7195e6740) : DEBUG (plugin.core.singleton:110) - Started
1052019-01-30 12:52:03,492 - plugin.core.logger.main (14b7195e6740) : DEBUG (plugin.core.logger.main:65) - Initialized logging (report: True, storage: True)
1062019-01-30 12:52:03,494 - com.plexapp.plugins.trakttv (14b7195e6740) : INFO (com.plexapp.plugins.trakttv:93) - Loaded the dictionary file
1072019-01-30 12:52:03,495 - com.plexapp.plugins.trakttv (14b7195e6740) : INFO (com.plexapp.plugins.trakttv:13) - ==================================================
1082019-01-30 12:52:03,495 - com.plexapp.plugins.trakttv (14b7195e6740) : INFO (com.plexapp.plugins.trakttv:9) - | Trakt.tv
1092019-01-30 12:52:03,495 - com.plexapp.plugins.trakttv (14b7195e6740) : INFO (com.plexapp.plugins.trakttv:9) - | https://github.com/trakt/Plex-Trakt-Scrobbler
1102019-01-30 12:52:03,495 - com.plexapp.plugins.trakttv (14b7195e6740) : INFO (com.plexapp.plugins.trakttv:13) - --------------------------------------------------
1112019-01-30 12:52:03,495 - com.plexapp.plugins.trakttv (14b7195e6740) : INFO (com.plexapp.plugins.trakttv:9) - | Current Version: v1.3.3
1122019-01-30 12:52:03,496 - com.plexapp.plugins.trakttv (14b7195e6740) : INFO (com.plexapp.plugins.trakttv:13) - --------------------------------------------------
1132019-01-30 12:52:03,497 - com.plexapp.plugins.trakttv (14b7195e6740) : INFO (com.plexapp.plugins.trakttv:9) - | core.header
1142019-01-30 12:52:03,498 - com.plexapp.plugins.trakttv (14b7195e6740) : INFO (com.plexapp.plugins.trakttv:9) - | core.helpers
1152019-01-30 12:52:03,498 - com.plexapp.plugins.trakttv (14b7195e6740) : INFO (com.plexapp.plugins.trakttv:9) - | core.logger
1162019-01-30 12:52:03,498 - com.plexapp.plugins.trakttv (14b7195e6740) : INFO (com.plexapp.plugins.trakttv:9) - | interface.m_main
1172019-01-30 12:52:03,498 - com.plexapp.plugins.trakttv (14b7195e6740) : INFO (com.plexapp.plugins.trakttv:9) - | interface.m_messages
1182019-01-30 12:52:03,498 - com.plexapp.plugins.trakttv (14b7195e6740) : INFO (com.plexapp.plugins.trakttv:9) - | interface.m_sync
1192019-01-30 12:52:03,498 - com.plexapp.plugins.trakttv (14b7195e6740) : INFO (com.plexapp.plugins.trakttv:9) - | interface.resources
1202019-01-30 12:52:03,499 - com.plexapp.plugins.trakttv (14b7195e6740) : INFO (com.plexapp.plugins.trakttv:13) - ==================================================
1212019-01-30 12:52:03,499 - requests.packages.urllib3.util.retry (14b7195e6740) : DEBUG (requests.packages.urllib3.util.retry:191) - Converted retries value: 3 -> Retry(total=3, connect=None, read=None, redirect=None)
1222019-01-30 12:52:03,499 - requests.packages.urllib3.util.retry (14b7195e6740) : DEBUG (requests.packages.urllib3.util.retry:191) - Converted retries value: 3 -> Retry(total=3, connect=None, read=None, redirect=None)
1232019-01-30 12:52:03,500 - plugin.core.logger.main (14b7195e6740) : DEBUG (plugin.core.logger.main:120) - Changed 'plex_activity' logger level to DEBUG
1242019-01-30 12:52:03,500 - plugin.core.logger.main (14b7195e6740) : DEBUG (plugin.core.logger.main:120) - Changed 'pyemitter' logger level to DEBUG
1252019-01-30 12:52:03,500 - plugin.core.logger.main (14b7195e6740) : DEBUG (plugin.core.logger.main:120) - Changed 'peewee_migrate' logger level to DEBUG
1262019-01-30 12:52:03,501 - plugin.core.logger.main (14b7195e6740) : DEBUG (plugin.core.logger.main:120) - Changed 'plugin' logger level to DEBUG
1272019-01-30 12:52:03,501 - plugin.core.logger.main (14b7195e6740) : DEBUG (plugin.core.logger.main:120) - Changed 'plex_metadata' logger level to DEBUG
1282019-01-30 12:52:03,501 - plugin.core.logger.main (14b7195e6740) : DEBUG (plugin.core.logger.main:120) - Changed 'peewee' logger level to DEBUG
1292019-01-30 12:52:03,501 - plugin.core.logger.main (14b7195e6740) : DEBUG (plugin.core.logger.main:120) - Changed 'requests' logger level to DEBUG
1302019-01-30 12:52:03,502 - plugin.core.logger.main (14b7195e6740) : DEBUG (plugin.core.logger.main:120) - Changed 'trakt' logger level to DEBUG
1312019-01-30 12:52:03,502 - plugin.core.logger.main (14b7195e6740) : DEBUG (plugin.core.logger.main:120) - Changed 'plex' logger level to DEBUG
1322019-01-30 12:52:03,502 - trakt.core.http (14b7195e6740) : INFO (trakt.core.http:159) - Rebuilding session and connection pools...
1332019-01-30 12:52:03,503 - trakt.core.emitter (14b7195e6740) : DEBUG (trakt.core.emitter:45) - [trakt.client.TraktClient ]:on(events: ['oauth.refresh'], func: <bound method type.on_trakt_refresh of <class 'main.Main'>>)
1342019-01-30 12:52:03,503 - trakt.core.emitter (14b7195e6740) : DEBUG (trakt.core.emitter:45) - [trakt.client.TraktClient ]:on(events: ['oauth.refresh.rejected'], func: <bound method type.on_trakt_refresh_rejected of <class 'main.Main'>>)
1352019-01-30 12:52:03,503 - plugin (14b7195e6740) : INFO (plugin:19) - Configured trakt.py (timeout=(6.05, 24), base_url='https://api.trakt.tv', keep_alive=True, adapter_kwargs={'pool_connections': 10, 'pool_maxsize': 10, 'max_retries': Retry(total=3, connect=None, read=0, redirect=None)})
1362019-01-30 12:52:03,504 - plex.objects.core.manager (14b7195e6740) : DEBUG (plex.objects.core.manager:79) - Loading descriptors...
1372019-01-30 12:52:03,510 - plex.objects.core.manager (14b7195e6740) : DEBUG (plex.objects.core.manager:89) - Loaded 57 descriptors (Album, AlbumChildrenContainer, Artist, ArtistChildrenContainer, ArtistLeavesContainer, ChildrenContainer, Client, ClientContainer, Clip, Container, Country, Descriptor, DescriptorMeta, DescriptorMixin, Detail, Director, Directory, Episode, Genre, LeavesContainer, Location, Media, MediaContainer, Metadata, Movie, MyPlexDetail, ObjectManager, Part, Photo, PhotoAlbum, Player, Playlist, PlaylistItemContainer, PlaylistItemMixin, Property, RateMixin, Role, ScrobbleMixin, Season, SeasonChildrenContainer, Section, SectionContainer, Server, Session, SessionContainer, SessionMixin, Setting, Show, ShowChildrenContainer, ShowLeavesContainer, Stream, Track, TranscodeSession, TranscoderDetail, User, Video, Writer)
1382019-01-30 12:52:03,510 - plugin (14b7195e6740) : INFO (plugin:19) - Initialized 0 modules:
1392019-01-30 12:52:03,562 - plugin.modules.core.manager (14b7195e6740) : DEBUG (plugin.modules.core.manager:69) - Constructed 5 module(s): mapper, matcher, scheduler, sessions, upgrade
1402019-01-30 12:52:03,562 - plugin.core.helpers (14b7195e6740) : DEBUG (plugin.core.helpers:19) - Spawned thread with name 'main'
1412019-01-30 12:52:03,563 - plugin (14b7103e4700) : INFO (plugin:19) - X-Plex-Token: available
1422019-01-30 12:52:03,563 - com.plexapp.plugins.trakttv (14b7195e6740) : INFO (com.plexapp.plugins.trakttv:611) - Started plug-in
1432019-01-30 12:52:03,564 - com.plexapp.plugins.trakttv (14b7195e6740) : DEBUG (com.plexapp.plugins.trakttv:160) - Starting socket server
1442019-01-30 12:52:03,627 - requests.packages.urllib3.connectionpool (14b7103e4700) : DEBUG (requests.packages.urllib3.connectionpool:213) - Starting new HTTP connection (1): 127.0.0.1
1452019-01-30 12:52:03,628 - com.plexapp.plugins.trakttv (14b7195e6740) : DEBUG (com.plexapp.plugins.trakttv:1117) - Created a thread named 'start'
1462019-01-30 12:52:03,628 - com.plexapp.plugins.trakttv (14b7195e6740) : INFO (com.plexapp.plugins.trakttv:184) - Socket server started on port 40759
1472019-01-30 12:52:03,629 - com.plexapp.plugins.trakttv (14b7195e6740) : INFO (com.plexapp.plugins.trakttv:25) - Entering run loop
1482019-01-30 12:52:03,629 - com.plexapp.plugins.trakttv (14b7195e6740) : DEBUG (com.plexapp.plugins.trakttv:717) - Handling request GET /:/prefixes
1492019-01-30 12:52:03,631 - requests.packages.urllib3.connectionpool (14b7103e4700) : DEBUG (requests.packages.urllib3.connectionpool:401) - http://127.0.0.1:32400 "GET / HTTP/1.1" 200 5410
1502019-01-30 12:52:03,633 - com.plexapp.plugins.trakttv (14b7195e6740) : DEBUG (com.plexapp.plugins.trakttv:814) - Found route matching /:/prefixes
1512019-01-30 12:52:03,634 - com.plexapp.plugins.trakttv (14b7195e6740) : DEBUG (com.plexapp.plugins.trakttv:924) - Response: [200] MediaContainer, 441 bytes
1522019-01-30 12:52:03,637 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Detail] Omitted attribute: maxuploadbitrate
1532019-01-30 12:52:03,637 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Detail] Omitted attribute: maxuploadbitratereason
1542019-01-30 12:52:03,637 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Detail] Omitted attribute: maxuploadbitratereasonmessage
1552019-01-30 12:52:03,637 - plugin (14b7103e4700) : DEBUG (plugin:19) - on_starting_plugins
1562019-01-30 12:52:03,637 - plugin.scrobbler.core.session_prefix (14b7103e4700) : DEBUG (plugin.scrobbler.core.session_prefix:31) - Incremented session prefix to 3
1572019-01-30 12:52:03,637 - plugin.core.helpers.thread (14b7103e4700) : DEBUG (plugin.core.helpers.thread:45) - plugin_dir: '/config/Library/Application Support/Plex Media Server/Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/plugin'
1582019-01-30 12:52:03,638 - plugin.core.importer (14b7103e4700) : DEBUG (plugin.core.importer:12) - Importing modules from: plugin.api
1592019-01-30 12:52:03,763 - plugin.api.core.manager (14b7103e4700) : DEBUG (plugin.api.core.manager:117) - Registered service: 'option' (<class 'plugin.api.option.OptionService'>)
1602019-01-30 12:52:03,774 - plugin.api.core.manager (14b7103e4700) : DEBUG (plugin.api.core.manager:117) - Registered service: 'system' (<class 'plugin.api.system.SystemService'>)
1612019-01-30 12:52:03,774 - plugin.core.importer (14b7103e4700) : DEBUG (plugin.core.importer:12) - Importing modules from: plugin.api.account
1622019-01-30 12:52:03,779 - plugin.api.core.manager (14b7103e4700) : DEBUG (plugin.api.core.manager:117) - Registered service: 'account' (<class 'plugin.api.account.main.AccountService'>)
1632019-01-30 12:52:03,783 - plugin.api.core.manager (14b7103e4700) : DEBUG (plugin.api.core.manager:117) - Registered service: 'account.plex' (<class 'plugin.api.account.plex.PlexAccountService'>)
1642019-01-30 12:52:03,786 - plugin.api.core.manager (14b7103e4700) : DEBUG (plugin.api.core.manager:117) - Registered service: 'account.trakt' (<class 'plugin.api.account.trakt.TraktAccountService'>)
1652019-01-30 12:52:03,786 - plugin.core.importer (14b7103e4700) : DEBUG (plugin.core.importer:12) - Importing modules from: plugin.api.session
1662019-01-30 12:52:03,793 - plugin.api.core.manager (14b7103e4700) : DEBUG (plugin.api.core.manager:117) - Registered service: 'session.client' (<class 'plugin.api.session.client.ClientService'>)
1672019-01-30 12:52:03,793 - plugin.api.core.manager (14b7103e4700) : DEBUG (plugin.api.core.manager:117) - Registered service: 'session.client.rule' (<class 'plugin.api.session.client.ClientRuleService'>)
1682019-01-30 12:52:03,799 - plugin.api.core.manager (14b7103e4700) : DEBUG (plugin.api.core.manager:117) - Registered service: 'session.user' (<class 'plugin.api.session.user.UserService'>)
1692019-01-30 12:52:03,799 - plugin.api.core.manager (14b7103e4700) : DEBUG (plugin.api.core.manager:117) - Registered service: 'session.user.rule' (<class 'plugin.api.session.user.UserRuleService'>)
1702019-01-30 12:52:03,799 - plugin.core.importer (14b7103e4700) : DEBUG (plugin.core.importer:12) - Importing modules from: plugin.managers
1712019-01-30 12:52:03,801 - plugin.core.importer (14b7103e4700) : DEBUG (plugin.core.importer:12) - Importing modules from: plugin.modules
1722019-01-30 12:52:03,802 - plugin.core.importer (14b7103e4700) : DEBUG (plugin.core.importer:12) - Importing modules from: plugin.scrobbler
1732019-01-30 12:52:03,811 - plugin.core.importer (14b7103e4700) : DEBUG (plugin.core.importer:12) - Importing modules from: plugin.scrobbler.handlers
1742019-01-30 12:52:03,812 - plugin.core.helpers.thread (14b7103e4700) : DEBUG (plugin.core.helpers.thread:85) - Starting 3 module(s)...
1752019-01-30 12:52:03,812 - plugin.core.helpers.thread (14b7103e4700) : DEBUG (plugin.core.helpers.thread:91) - -> Migrations (priority: 0, blocking: True)
1762019-01-30 12:52:03,813 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('PRAGMA journal_mode=WAL;', None)
1772019-01-30 12:52:03,814 - plugin.modules.migrations.main (14b7103e4700) : INFO (plugin.modules.migrations.main:38) - Running migration: 'SchemaMigration'
1782019-01-30 12:52:03,814 - plugin.modules.migrations.schema (14b7103e4700) : DEBUG (plugin.modules.migrations.schema:18) - migrations_path: '/config/Library/Application Support/Plex Media Server/Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/plugin/models/migrations'
1792019-01-30 12:52:03,814 - peewee_migrate.core (14b7103e4700) : INFO (peewee_migrate.core:53) - Configuration file `conf.py` wasn't found in the migration directory
1802019-01-30 12:52:03,815 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('CREATE TABLE "migratehistory" ("id" INTEGER NOT NULL PRIMARY KEY, "name" VARCHAR(255) NOT NULL, "migrated_at" DATETIME NOT NULL)', [])
1812019-01-30 12:52:03,815 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."name", "t1"."migrated_at" FROM "migratehistory" AS t1', [])
1822019-01-30 12:52:03,836 - peewee_migrate.core (14b7103e4700) : DEBUG (peewee_migrate.core:205) - Validating migration "016_message"...
1832019-01-30 12:52:03,837 - peewee (14b7103e4700) : DEBUG (peewee:99) - ("SELECT name FROM sqlite_master WHERE type='table';", None)
1842019-01-30 12:52:03,837 - peewee (14b7103e4700) : DEBUG (peewee:99) - ("PRAGMA table_info('session.client')", None)
1852019-01-30 12:52:03,837 - peewee (14b7103e4700) : DEBUG (peewee:99) - ("PRAGMA table_info('configuration.option')", None)
1862019-01-30 12:52:03,837 - peewee (14b7103e4700) : DEBUG (peewee:99) - ("PRAGMA table_info('action.queue')", None)
1872019-01-30 12:52:03,838 - peewee (14b7103e4700) : DEBUG (peewee:99) - ("PRAGMA table_info('scheduler.job')", None)
1882019-01-30 12:52:03,838 - peewee (14b7103e4700) : DEBUG (peewee:99) - ("PRAGMA table_info('sync.status')", None)
1892019-01-30 12:52:03,838 - peewee (14b7103e4700) : DEBUG (peewee:99) - ("PRAGMA table_info('session')", None)
1902019-01-30 12:52:03,838 - peewee (14b7103e4700) : DEBUG (peewee:99) - ("PRAGMA table_info('message')", None)
1912019-01-30 12:52:03,839 - peewee (14b7103e4700) : DEBUG (peewee:99) - ("PRAGMA table_info('session.user')", None)
1922019-01-30 12:52:03,839 - peewee (14b7103e4700) : DEBUG (peewee:99) - ("PRAGMA table_info('session.user.rule')", None)
1932019-01-30 12:52:03,839 - peewee (14b7103e4700) : DEBUG (peewee:99) - ("PRAGMA table_info('trakt.credential.basic')", None)
1942019-01-30 12:52:03,839 - peewee (14b7103e4700) : DEBUG (peewee:99) - ("PRAGMA table_info('account')", None)
1952019-01-30 12:52:03,840 - peewee (14b7103e4700) : DEBUG (peewee:99) - ("PRAGMA table_info('scheduler.task')", None)
1962019-01-30 12:52:03,840 - peewee (14b7103e4700) : DEBUG (peewee:99) - ("PRAGMA table_info('sync.result')", None)
1972019-01-30 12:52:03,840 - peewee (14b7103e4700) : DEBUG (peewee:99) - ("PRAGMA table_info('sync.result.error')", None)
1982019-01-30 12:52:03,840 - peewee (14b7103e4700) : DEBUG (peewee:99) - ("PRAGMA table_info('exception')", None)
1992019-01-30 12:52:03,841 - peewee (14b7103e4700) : DEBUG (peewee:99) - ("PRAGMA table_info('plex.account')", None)
2002019-01-30 12:52:03,841 - peewee (14b7103e4700) : DEBUG (peewee:99) - ("PRAGMA table_info('trakt.credential.oauth')", None)
2012019-01-30 12:52:03,841 - peewee (14b7103e4700) : DEBUG (peewee:99) - ("PRAGMA table_info('plex.credential.basic')", None)
2022019-01-30 12:52:03,841 - peewee (14b7103e4700) : DEBUG (peewee:99) - ("PRAGMA table_info('sync.result.exception')", None)
2032019-01-30 12:52:03,841 - peewee (14b7103e4700) : DEBUG (peewee:99) - ("PRAGMA table_info('trakt.account')", None)
2042019-01-30 12:52:03,842 - peewee (14b7103e4700) : DEBUG (peewee:99) - ("PRAGMA table_info('action.history')", None)
2052019-01-30 12:52:03,842 - peewee (14b7103e4700) : DEBUG (peewee:99) - ("PRAGMA table_info('session.client.rule')", None)
2062019-01-30 12:52:03,842 - peewee_migrate.core (14b7103e4700) : INFO (peewee_migrate.core:318) - Validated 22 table(s)
2072019-01-30 12:52:03,842 - plugin.modules.migrations.schema (14b7103e4700) : INFO (plugin.modules.migrations.schema:31) - Database schema is valid
2082019-01-30 12:52:03,842 - peewee_migrate.core (14b7103e4700) : INFO (peewee_migrate.core:106) - Running migrations...
2092019-01-30 12:52:03,843 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."name", "t1"."migrated_at" FROM "migratehistory" AS t1', [])
2102019-01-30 12:52:03,844 - peewee_migrate.core (14b7103e4700) : INFO (peewee_migrate.core:118) - Database has 17 migrations applied:
211 000_initial
212 001_session
213 002_rule
214 003_plex_account
215 004_scheduler
216 005_sync_result
217 006_scheduler_job
218 007_plex_account
219 008_plex_basic_credential
220 009_account
221 010_account
222 011_message
223 012_account
224 013_action
225 014_session
226 015_action
227 016_message
2282019-01-30 12:52:03,844 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."name", "t1"."migrated_at" FROM "migratehistory" AS t1', [])
2292019-01-30 12:52:03,846 - peewee_migrate.core (14b7103e4700) : INFO (peewee_migrate.core:129) - Nothing to migrate
2302019-01-30 12:52:03,846 - plugin.modules.migrations.main (14b7103e4700) : INFO (plugin.modules.migrations.main:38) - Running migration: 'AccountMigration'
2312019-01-30 12:52:03,846 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."name", "t1"."thumb", "t1"."deleted", "t1"."refreshed_at" FROM "account" AS t1 WHERE ("t1"."id" = ?) LIMIT 1', [0])
2322019-01-30 12:52:03,847 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."name", "t1"."thumb", "t1"."deleted", "t1"."refreshed_at" FROM "account" AS t1 WHERE ("t1"."id" = ?) LIMIT 1', [1])
2332019-01-30 12:52:03,848 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('INSERT INTO "plex.account" ("account_id") VALUES (?)', [1])
2342019-01-30 12:52:03,848 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."key", "t1"."username", "t1"."title", "t1"."thumb", "t1"."refreshed_at" FROM "plex.account" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE ("t1"."account_id" = ?) LIMIT 1', [1])
2352019-01-30 12:52:03,849 - plugin.modules.migrations.account (14b7103e4700) : INFO (plugin.modules.migrations.account:244) - Plex Token: environment
2362019-01-30 12:52:03,849 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('INSERT INTO "plex.credential.basic" ("account_id", "token_plex") VALUES (?, ?)', [1, u'KAzqZe57s3L7WxiWM5ny'])
2372019-01-30 12:52:03,850 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('UPDATE "plex.credential.basic" SET "token_plex" = ?, "token_server" = ? WHERE (("plex.credential.basic"."account_id" = ?) AND ("plex.credential.basic"."token_plex" != ?))', [u'KAzqZe57s3L7WxiWM5ny', None, 1, u'KAzqZe57s3L7WxiWM5ny'])
2382019-01-30 12:52:03,859 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."password", "t1"."token_plex", "t1"."token_server" FROM "plex.credential.basic" AS t1 WHERE ("t1"."account_id" = ?)', [1])
2392019-01-30 12:52:03,859 - plugin.models.m_plex.account (14b7103e4700) : INFO (plugin.models.m_plex.account:118) - Refreshing plex account: <PlexAccount username: u'iammiak'>
2402019-01-30 12:52:03,863 - requests.packages.urllib3.connectionpool (14b7103e4700) : DEBUG (requests.packages.urllib3.connectionpool:810) - Starting new HTTPS connection (1): plex.tv
2412019-01-30 12:52:04,655 - requests.packages.urllib3.connectionpool (14b7103e4700) : DEBUG (requests.packages.urllib3.connectionpool:401) - https://plex.tv:443 "GET /users/account HTTP/1.1" 200 None
2422019-01-30 12:52:04,661 - plugin.models.m_plex.credential (14b7103e4700) : INFO (plugin.models.m_plex.credential:59) - Refreshing plex credential: <plugin.models.m_plex.credential.PlexBasicCredential object at 0x14b6e194fc10>
2432019-01-30 12:52:04,662 - requests.packages.urllib3.connectionpool (14b7103e4700) : DEBUG (requests.packages.urllib3.connectionpool:810) - Starting new HTTPS connection (1): plex.tv
2442019-01-30 12:52:06,148 - requests.packages.urllib3.connectionpool (14b7103e4700) : DEBUG (requests.packages.urllib3.connectionpool:401) - https://plex.tv:443 "GET /api/resources?includeHttps=1 HTTP/1.1" 200 None
2452019-01-30 12:52:06,154 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('UPDATE "plex.credential.basic" SET "account_id" = ?, "password" = ?, "token_plex" = ?, "token_server" = ? WHERE ("plex.credential.basic"."id" = ?)', [1, None, u'KAzqZe57s3L7WxiWM5ny', u'KAzqZe57s3L7WxiWM5ny', 1])
2462019-01-30 12:52:06,161 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('UPDATE "plex.account" SET "account_id" = ?, "key" = ?, "username" = ?, "title" = ?, "thumb" = ?, "refreshed_at" = ? WHERE ("plex.account"."id" = ?)', [1, 1, u'iammiak', u'iammiak', u'https://plex.tv/users/fc268f67a3ba7696/avatar?c=1548261261', '2019-01-30 18:52:06.160873', 1])
2472019-01-30 12:52:06,170 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('INSERT INTO "trakt.account" ("account_id", "username") VALUES (?, ?)', [1, None])
2482019-01-30 12:52:06,171 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."username", "t1"."thumb", "t1"."cover", "t1"."timezone", "t1"."refreshed_at" FROM "trakt.account" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE ("t1"."account_id" = ?) LIMIT 1', [1])
2492019-01-30 12:52:06,172 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."key", "t1"."username", "t1"."title", "t1"."thumb", "t1"."refreshed_at" FROM "plex.account" AS t1 WHERE ("t1"."account_id" = ?)', [1])
2502019-01-30 12:52:06,173 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."username", "t1"."thumb", "t1"."cover", "t1"."timezone", "t1"."refreshed_at" FROM "trakt.account" AS t1 WHERE ("t1"."account_id" = ?)', [1])
2512019-01-30 12:52:06,175 - requests.packages.urllib3.connectionpool (14b7103e4700) : DEBUG (requests.packages.urllib3.connectionpool:810) - Starting new HTTPS connection (1): secure.gravatar.com
2522019-01-30 12:52:06,392 - requests.packages.urllib3.connectionpool (14b7103e4700) : DEBUG (requests.packages.urllib3.connectionpool:401) - https://secure.gravatar.com:443 "GET /avatar/9b0c257bfb71bc15aca2ca1203852673?s=256&r=pg&d=404 HTTP/1.1" 404 13
2532019-01-30 12:52:06,396 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('UPDATE "account" SET "name" = ?, "thumb" = ?, "deleted" = ?, "refreshed_at" = ? WHERE ("account"."id" = ?)', [u'iammiak', u'https://plex.tv/users/fc268f67a3ba7696/avatar?c=1548261261', 0, '2019-01-30 18:52:06.396114', 1])
2542019-01-30 12:52:06,405 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."name", "t1"."thumb", "t1"."deleted", "t1"."refreshed_at" FROM "account" AS t1 WHERE (("t1"."id" > ?) AND ("t1"."deleted" = ?))', [1, 0])
2552019-01-30 12:52:06,405 - plugin.modules.migrations.main (14b7103e4700) : INFO (plugin.modules.migrations.main:38) - Running migration: 'PreferencesMigration'
2562019-01-30 12:52:06,407 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."key", "t1"."value" FROM "configuration.option" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."key" = ?)) LIMIT 1', [0, u'sync.idle_defer'])
2572019-01-30 12:52:06,408 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."key", "t1"."value" FROM "configuration.option" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."key" = ?)) LIMIT 1', [0, u'sync.profiler'])
2582019-01-30 12:52:06,408 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."key", "t1"."value" FROM "configuration.option" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."key" = ?)) LIMIT 1', [0, u'sync.action.mode'])
2592019-01-30 12:52:06,409 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."key", "t1"."value" FROM "configuration.option" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."key" = ?)) LIMIT 1', [0, u'sync.idle_delay'])
2602019-01-30 12:52:06,410 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."key" FROM "scheduler.task" AS t1 WHERE ("t1"."key" = ?) LIMIT 1', [u'backup.interval'])
2612019-01-30 12:52:06,411 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."task_id", "t1"."trigger", "t1"."ran_at", "t1"."due_at" FROM "scheduler.job" AS t1 INNER JOIN "scheduler.task" AS t2 ON ("t1"."task_id" = "t2"."key") INNER JOIN "account" AS t3 ON ("t1"."account_id" = "t3"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."task_id" = ?)) LIMIT 1', [0, u'backup.interval'])
2622019-01-30 12:52:06,414 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."key", "t1"."value" FROM "configuration.option" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."key" = ?)) LIMIT 1', [0, u'activity.mode'])
2632019-01-30 12:52:06,415 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."key", "t1"."value" FROM "configuration.option" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."key" = ?)) LIMIT 1', [0, u'scrobble.duplication_period'])
2642019-01-30 12:52:06,416 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."key", "t1"."value" FROM "configuration.option" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."key" = ?)) LIMIT 1', [0, u'api.enabled'])
2652019-01-30 12:52:06,417 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."key", "t1"."value" FROM "configuration.option" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."key" = ?)) LIMIT 1', [0, u'matcher.mode'])
2662019-01-30 12:52:06,417 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('INSERT OR REPLACE INTO "configuration.option" ("account_id", "key", "value") VALUES (?, ?, ?)', [0, u'sync.idle_defer', <read-only buffer for 0x14b719554210, size -1, offset 0 at 0x14b6e1959830>])
2672019-01-30 12:52:06,426 - plugin.preferences.main (14b7103e4700) : DEBUG (plugin.preferences.main:63) - Updated 'sync_idle_defer' option in database
2682019-01-30 12:52:06,426 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('INSERT OR REPLACE INTO "configuration.option" ("account_id", "key", "value") VALUES (?, ?, ?)', [0, u'sync.idle_delay', <read-only buffer for 0x14b7195419b8, size -1, offset 0 at 0x14b6e19597f0>])
2692019-01-30 12:52:06,438 - plugin.preferences.main (14b7103e4700) : DEBUG (plugin.preferences.main:63) - Updated 'sync_idle_delay' option in database
2702019-01-30 12:52:06,439 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('INSERT OR REPLACE INTO "configuration.option" ("account_id", "key", "value") VALUES (?, ?, ?)', [0, u'activity.mode', <read-only buffer for 0x14b7195540d0, size -1, offset 0 at 0x14b6e19596b0>])
2712019-01-30 12:52:06,448 - plugin.preferences.main (14b7103e4700) : DEBUG (plugin.preferences.main:63) - Updated 'activity_mode' option in database
2722019-01-30 12:52:06,449 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('INSERT OR REPLACE INTO "configuration.option" ("account_id", "key", "value") VALUES (?, ?, ?)', [0, u'matcher.mode', <read-only buffer for 0x14b719541058, size -1, offset 0 at 0x14b6e19590b0>])
2732019-01-30 12:52:06,456 - plugin.preferences.options.matcher (14b7103e4700) : DEBUG (plugin.preferences.options.matcher:45) - Configured matcher, extended: True
2742019-01-30 12:52:06,457 - plugin.preferences.main (14b7103e4700) : DEBUG (plugin.preferences.main:63) - Updated 'matcher' option in database
2752019-01-30 12:52:06,457 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('INSERT OR REPLACE INTO "configuration.option" ("account_id", "key", "value") VALUES (?, ?, ?)', [0, u'scrobble.duplication_period', <read-only buffer for 0x14b7143c3a08, size -1, offset 0 at 0x14b6e1959b30>])
2762019-01-30 12:52:06,467 - plugin.preferences.main (14b7103e4700) : DEBUG (plugin.preferences.main:63) - Updated 'scrobble_duplication_period' option in database
2772019-01-30 12:52:06,468 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."key", "t1"."value" FROM "configuration.option" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."key" = ?)) LIMIT 1', [1, u'sync.lists.liked.playlists'])
2782019-01-30 12:52:06,469 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."key", "t1"."value" FROM "configuration.option" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."key" = ?)) LIMIT 1', [1, u'sync.lists.watchlist.mode'])
2792019-01-30 12:52:06,470 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."key", "t1"."value" FROM "configuration.option" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."key" = ?)) LIMIT 1', [1, u'sync.lists.personal.playlists'])
2802019-01-30 12:52:06,471 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."key" FROM "scheduler.task" AS t1 WHERE ("t1"."key" = ?) LIMIT 1', [u'sync.interval'])
2812019-01-30 12:52:06,472 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."task_id", "t1"."trigger", "t1"."ran_at", "t1"."due_at" FROM "scheduler.job" AS t1 INNER JOIN "scheduler.task" AS t2 ON ("t1"."task_id" = "t2"."key") INNER JOIN "account" AS t3 ON ("t1"."account_id" = "t3"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."task_id" = ?)) LIMIT 1', [1, u'sync.interval'])
2822019-01-30 12:52:06,474 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."key", "t1"."value" FROM "configuration.option" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."key" = ?)) LIMIT 1', [1, u'sync.lists.personal.mode'])
2832019-01-30 12:52:06,475 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."key", "t1"."value" FROM "configuration.option" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."key" = ?)) LIMIT 1', [1, u'scrobble.enabled'])
2842019-01-30 12:52:06,476 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."key", "t1"."value" FROM "configuration.option" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."key" = ?)) LIMIT 1', [1, u'sync.watched.mode'])
2852019-01-30 12:52:06,477 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."key", "t1"."value" FROM "configuration.option" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."key" = ?)) LIMIT 1', [1, u'sync.collection.clean'])
2862019-01-30 12:52:06,478 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."key", "t1"."value" FROM "configuration.option" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."key" = ?)) LIMIT 1', [1, u'sync.lists.watchlist.playlists'])
2872019-01-30 12:52:06,479 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."key", "t1"."value" FROM "configuration.option" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."key" = ?)) LIMIT 1', [1, u'sync.library_update'])
2882019-01-30 12:52:06,480 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."key", "t1"."value" FROM "configuration.option" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."key" = ?)) LIMIT 1', [1, u'sync.ratings.mode'])
2892019-01-30 12:52:06,480 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."key", "t1"."value" FROM "configuration.option" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."key" = ?)) LIMIT 1', [1, u'sync.ratings.conflict'])
2902019-01-30 12:52:06,481 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."key", "t1"."value" FROM "configuration.option" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."key" = ?)) LIMIT 1', [1, u'sync.playback.mode'])
2912019-01-30 12:52:06,482 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."key", "t1"."value" FROM "configuration.option" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."key" = ?)) LIMIT 1', [1, u'sync.lists.liked.mode'])
2922019-01-30 12:52:06,483 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."key", "t1"."value" FROM "configuration.option" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."key" = ?)) LIMIT 1', [1, u'sync.collection.mode'])
2932019-01-30 12:52:06,484 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('INSERT OR REPLACE INTO "configuration.option" ("account_id", "key", "value") VALUES (?, ?, ?)', [1, u'sync.lists.watchlist.mode', <read-only buffer for 0x14b7195540d0, size -1, offset 0 at 0x14b6e1959e70>])
2942019-01-30 12:52:06,491 - plugin.preferences.main (14b7103e4700) : DEBUG (plugin.preferences.main:63) - Updated 'sync_watchlist' option in database
2952019-01-30 12:52:06,492 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('INSERT OR REPLACE INTO "configuration.option" ("account_id", "key", "value") VALUES (?, ?, ?)', [1, u'sync.ratings.mode', <read-only buffer for 0x14b7195540d0, size -1, offset 0 at 0x14b6e1959a70>])
2962019-01-30 12:52:06,500 - plugin.preferences.main (14b7103e4700) : DEBUG (plugin.preferences.main:63) - Updated 'sync_ratings' option in database
2972019-01-30 12:52:06,501 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."username", "t1"."thumb", "t1"."cover", "t1"."timezone", "t1"."refreshed_at" FROM "trakt.account" AS t1 WHERE ("t1"."account_id" = ?) LIMIT 1', [1])
2982019-01-30 12:52:06,501 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."code", "t1"."access_token", "t1"."refresh_token", "t1"."created_at", "t1"."expires_in", "t1"."token_type", "t1"."scope" FROM "trakt.credential.oauth" AS t1 WHERE ("t1"."account_id" = ?)', [1])
2992019-01-30 12:52:06,504 - requests.packages.urllib3.connectionpool (14b7103e4700) : DEBUG (requests.packages.urllib3.connectionpool:810) - Starting new HTTPS connection (1): api.trakt.tv
3002019-01-30 12:52:06,663 - requests.packages.urllib3.connectionpool (14b7103e4700) : DEBUG (requests.packages.urllib3.connectionpool:401) - https://api.trakt.tv:443 "POST /oauth/token HTTP/1.1" 401 213
3012019-01-30 12:52:06,666 - trakt.interfaces.base (14b7103e4700) : WARNING (trakt.interfaces.base:48) - Request failed: "POST /oauth/token" - 401: "OAuth must be provided" (Unauthorized)
3022019-01-30 12:52:06,667 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."username", "t1"."thumb", "t1"."cover", "t1"."timezone", "t1"."refreshed_at" FROM "trakt.account" AS t1 WHERE ("t1"."id" = ?) LIMIT 1', [1])
3032019-01-30 12:52:06,668 - plugin.managers.m_trakt.credential (14b7103e4700) : WARNING (plugin.managers.m_trakt.credential:76) - Token exchange failed for <TraktAccount username: u'hermy65'>
3042019-01-30 12:52:06,669 - plugin.managers.m_trakt.account (14b7103e4700) : WARNING (plugin.managers.m_trakt.account:125) - Unable to update OAuthCredential (token exchange failed, hasn't changed, etc..)
3052019-01-30 12:52:06,670 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('UPDATE "trakt.credential.oauth" SET "account_id" = ?, "code" = ?, "access_token" = ?, "refresh_token" = ?, "created_at" = ?, "expires_in" = ?, "token_type" = ?, "scope" = ? WHERE ("trakt.credential.oauth"."id" = ?)', [1, u'72AF5C95', u'1c387f2f0ebbbe90b607a70e8446d17ae50087a45d22a45425fbbb312783989a', u'c6684770df64b94eb75bd6b0cc3b0b7869473a83e3b470b0e1171fcc125035d5', 1548874076, 7776000, u'Bearer', u'public', 2])
3062019-01-30 12:52:06,676 - plugin.preferences.options.pin (14b7103e4700) : WARNING (plugin.preferences.options.pin:33) - Unable to update account
3072019-01-30 12:52:06,676 - plugin.preferences.main (14b7103e4700) : DEBUG (plugin.preferences.main:63) - Updated 'pin' option in database
3082019-01-30 12:52:06,678 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('INSERT OR REPLACE INTO "configuration.option" ("account_id", "key", "value") VALUES (?, ?, ?)', [1, u'sync.collection.clean', <read-only buffer for 0x14b719554238, size -1, offset 0 at 0x14b6e1959bf0>])
3092019-01-30 12:52:06,686 - plugin.preferences.main (14b7103e4700) : DEBUG (plugin.preferences.main:63) - Updated 'sync_clean_collection' option in database
3102019-01-30 12:52:06,687 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('INSERT OR REPLACE INTO "configuration.option" ("account_id", "key", "value") VALUES (?, ?, ?)', [1, u'sync.playback.mode', <read-only buffer for 0x14b719541058, size -1, offset 0 at 0x14b6e1959170>])
3112019-01-30 12:52:06,698 - plugin.preferences.main (14b7103e4700) : DEBUG (plugin.preferences.main:63) - Updated 'sync_playback' option in database
3122019-01-30 12:52:06,699 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('INSERT OR REPLACE INTO "configuration.option" ("account_id", "key", "value") VALUES (?, ?, ?)', [1, u'sync.library_update', <read-only buffer for 0x14b719554238, size -1, offset 0 at 0x14b6e19598b0>])
3132019-01-30 12:52:06,709 - plugin.preferences.main (14b7103e4700) : DEBUG (plugin.preferences.main:63) - Updated 'sync_run_library' option in database
3142019-01-30 12:52:06,710 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."key" FROM "scheduler.task" AS t1 WHERE ("t1"."key" = ?) LIMIT 1', [u'sync.interval'])
3152019-01-30 12:52:06,712 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."task_id", "t1"."trigger", "t1"."ran_at", "t1"."due_at" FROM "scheduler.job" AS t1 INNER JOIN "scheduler.task" AS t2 ON ("t1"."task_id" = "t2"."key") INNER JOIN "account" AS t3 ON ("t1"."account_id" = "t3"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."task_id" = ?)) LIMIT 1', [1, u'sync.interval'])
3162019-01-30 12:52:06,713 - plugin.preferences.main (14b7103e4700) : DEBUG (plugin.preferences.main:63) - Updated 'sync_run_interval' option in database
3172019-01-30 12:52:06,714 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('INSERT OR REPLACE INTO "configuration.option" ("account_id", "key", "value") VALUES (?, ?, ?)', [1, u'sync.collection.mode', <read-only buffer for 0x14b7195540d0, size -1, offset 0 at 0x14b6e1959e70>])
3182019-01-30 12:52:06,726 - plugin.preferences.main (14b7103e4700) : DEBUG (plugin.preferences.main:63) - Updated 'sync_collection' option in database
3192019-01-30 12:52:06,727 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('INSERT OR REPLACE INTO "configuration.option" ("account_id", "key", "value") VALUES (?, ?, ?)', [1, u'sync.watched.mode', <read-only buffer for 0x14b719541058, size -1, offset 0 at 0x14b6e19591b0>])
3202019-01-30 12:52:06,736 - plugin.preferences.main (14b7103e4700) : DEBUG (plugin.preferences.main:63) - Updated 'sync_watched' option in database
3212019-01-30 12:52:06,737 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('INSERT OR REPLACE INTO "configuration.option" ("account_id", "key", "value") VALUES (?, ?, ?)', [1, u'sync.lists.liked.mode', <read-only buffer for 0x14b7195540d0, size -1, offset 0 at 0x14b6e1959ab0>])
3222019-01-30 12:52:06,747 - plugin.preferences.main (14b7103e4700) : DEBUG (plugin.preferences.main:63) - Updated 'sync_liked_lists' option in database
3232019-01-30 12:52:06,748 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('INSERT OR REPLACE INTO "configuration.option" ("account_id", "key", "value") VALUES (?, ?, ?)', [1, u'sync.ratings.conflict', <read-only buffer for 0x14b71943c600, size -1, offset 0 at 0x14b6e19590b0>])
3242019-01-30 12:52:06,758 - plugin.preferences.main (14b7103e4700) : DEBUG (plugin.preferences.main:63) - Updated 'sync_ratings_conflict' option in database
3252019-01-30 12:52:06,759 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('INSERT OR REPLACE INTO "configuration.option" ("account_id", "key", "value") VALUES (?, ?, ?)', [1, u'scrobble.enabled', <read-only buffer for 0x14b719554210, size -1, offset 0 at 0x14b6e1959130>])
3262019-01-30 12:52:06,767 - plugin.preferences.main (14b7103e4700) : DEBUG (plugin.preferences.main:63) - Updated 'start_scrobble' option in database
3272019-01-30 12:52:06,768 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('INSERT OR REPLACE INTO "configuration.option" ("account_id", "key", "value") VALUES (?, ?, ?)', [1, u'sync.lists.personal.mode', <read-only buffer for 0x14b7195540d0, size -1, offset 0 at 0x14b6e19596b0>])
3282019-01-30 12:52:06,777 - plugin.preferences.main (14b7103e4700) : DEBUG (plugin.preferences.main:63) - Updated 'sync_personal_lists' option in database
3292019-01-30 12:52:06,777 - plugin.modules.migrations.main (14b7103e4700) : INFO (plugin.modules.migrations.main:41) - Migrations complete
3302019-01-30 12:52:06,777 - plugin.core.helpers.thread (14b7103e4700) : DEBUG (plugin.core.helpers.thread:91) - -> ActionManager (priority: 100, blocking: True)
3312019-01-30 12:52:06,778 - plugin.core.helpers.thread (14b7103e4700) : DEBUG (plugin.core.helpers.thread:91) - -> Scrobbler (priority: 100, blocking: True)
3322019-01-30 12:52:06,779 - peewee (14b6e1dba700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."session_id", "t1"."progress", "t1"."part", "t1"."rating_key", "t1"."event", "t1"."request", "t1"."queued_at" FROM "action.queue" AS t1 LIMIT 1', [])
3332019-01-30 12:52:06,780 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."key", "t1"."value" FROM "configuration.option" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."key" = ?)) LIMIT 1', [0, u'activity.mode'])
3342019-01-30 12:52:06,786 - requests.packages.urllib3.connectionpool (14b7103e4700) : DEBUG (requests.packages.urllib3.connectionpool:401) - http://127.0.0.1:32400 "GET /status/sessions HTTP/1.1" 200 83
3352019-01-30 12:52:06,791 - requests.packages.urllib3.connectionpool (14b7103e4700) : DEBUG (requests.packages.urllib3.connectionpool:401) - http://127.0.0.1:32400 "GET / HTTP/1.1" 200 5460
3362019-01-30 12:52:06,793 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Detail] Omitted attribute: maxuploadbitrate
3372019-01-30 12:52:06,793 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Detail] Omitted attribute: maxuploadbitratereason
3382019-01-30 12:52:06,793 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Detail] Omitted attribute: maxuploadbitratereasonmessage
3392019-01-30 12:52:06,794 - pyemitter (14b7103e4700) : DEBUG (pyemitter:45) - [plex_activity.activity.Activity ]:on(events: ['websocket.playing'], func: <bound method WebSocket.on_playing of <plugin.scrobbler.methods.s_websocket.WebSocket object at 0x14b7194c0510>>)
3402019-01-30 12:52:06,794 - plugin.core.method_manager (14b7103e4700) : INFO (plugin.core.method_manager:44) - Started 1 method(s): WebSocket
3412019-01-30 12:52:06,794 - plugin.core.helpers.thread (14b7103e4700) : DEBUG (plugin.core.helpers.thread:105) - Finished starting 3 module(s)
3422019-01-30 12:52:06,794 - plugin (14b7103e4700) : INFO (plugin:19) - Started 0 modules:
3432019-01-30 12:52:06,796 - oem_core.core.plugin (14b7103e4700) : DEBUG (oem_core.core.plugin:101) - Found client: anidb
3442019-01-30 12:52:06,796 - oem_core.core.plugin (14b7103e4700) : DEBUG (oem_core.core.plugin:101) - Found client: anidb/constants
3452019-01-30 12:52:06,796 - oem_core.core.plugin (14b7103e4700) : DEBUG (oem_core.core.plugin:101) - Found client-provider: release
3462019-01-30 12:52:06,796 - oem_core.core.plugin (14b7103e4700) : DEBUG (oem_core.core.plugin:101) - Found client-provider: release/complete
3472019-01-30 12:52:06,796 - oem_core.core.plugin (14b7103e4700) : DEBUG (oem_core.core.plugin:101) - Found client-provider: release/incremental
3482019-01-30 12:52:06,797 - oem_core.core.plugin (14b7103e4700) : DEBUG (oem_core.core.plugin:101) - Found format: minimize
3492019-01-30 12:52:06,797 - oem_core.core.plugin (14b7103e4700) : DEBUG (oem_core.core.plugin:101) - Found format: minimize-msgpack
3502019-01-30 12:52:06,797 - oem_core.core.plugin (14b7103e4700) : DEBUG (oem_core.core.plugin:101) - Found format: msgpack
3512019-01-30 12:52:06,797 - oem_core.core.plugin (14b7103e4700) : DEBUG (oem_core.core.plugin:101) - Found format: msgpack/packer
3522019-01-30 12:52:06,798 - oem_core.core.plugin (14b7103e4700) : DEBUG (oem_core.core.plugin:101) - Found storage: codernitydb
3532019-01-30 12:52:06,798 - oem_core.core.plugin (14b7103e4700) : DEBUG (oem_core.core.plugin:101) - Found storage: codernitydb/collection
3542019-01-30 12:52:06,798 - oem_core.core.plugin (14b7103e4700) : DEBUG (oem_core.core.plugin:101) - Found storage: codernitydb/database
3552019-01-30 12:52:06,798 - oem_core.core.plugin (14b7103e4700) : DEBUG (oem_core.core.plugin:101) - Found storage: codernitydb/index
3562019-01-30 12:52:06,798 - oem_core.core.plugin (14b7103e4700) : DEBUG (oem_core.core.plugin:101) - Found storage: codernitydb/indices
3572019-01-30 12:52:06,798 - oem_core.core.plugin (14b7103e4700) : DEBUG (oem_core.core.plugin:101) - Found storage: codernitydb/item
3582019-01-30 12:52:06,799 - oem_core.core.plugin (14b7103e4700) : DEBUG (oem_core.core.plugin:101) - Found storage: codernitydb/metadata
3592019-01-30 12:52:06,807 - oem_core.core.plugin (14b7103e4700) : INFO (oem_core.core.plugin:196) - Loaded client: 'anidb'
3602019-01-30 12:52:06,816 - oem_core.core.plugin (14b7103e4700) : INFO (oem_core.core.plugin:196) - Loaded format: 'minimize+msgpack'
3612019-01-30 12:52:06,817 - plugin.modules.upgrade.main (14b7103e4700) : DEBUG (plugin.modules.upgrade.main:23) - Checking for version change...
3622019-01-30 12:52:06,817 - plugin.modules.upgrade.main (14b7103e4700) : DEBUG (plugin.modules.upgrade.main:46) - No change
3632019-01-30 12:52:06,818 - peewee (14b6e1bb9700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."task_id", "t1"."trigger", "t1"."ran_at", "t1"."due_at" FROM "scheduler.job" AS t1 WHERE ("t1"."due_at" <= ?)', ['2019-01-30 18:52:06.817688'])
3642019-01-30 12:52:06,818 - plugin.modules.scheduler.main (14b7103e4700) : DEBUG (plugin.modules.scheduler.main:36) - Started
3652019-01-30 12:52:06,818 - plugin.core.database.connection (14b7103e4700) : DEBUG (plugin.core.database.connection:34) - Connected to database at '/config/Library/Application Support/Plex Media Server/Plug-in Support/Caches/com.plexapp.plugins.trakttv/plex.db'
3662019-01-30 12:52:06,820 - plugin.core.cache (14b7103e4700) : DEBUG (plugin.core.cache:55) - Opened "plex.matcher" cache (serializer: 'msgpack:///')
3672019-01-30 12:52:06,821 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."key", "t1"."value" FROM "configuration.option" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."key" = ?)) LIMIT 1', [0, u'matcher.mode'])
3682019-01-30 12:52:06,821 - plugin.modules.core.manager (14b7103e4700) : DEBUG (plugin.modules.core.manager:95) - Started 5 module(s): mapper, sessions, upgrade, scheduler, matcher
3692019-01-30 12:52:06,822 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."key", "t1"."value" FROM "configuration.option" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."key" = ?)) LIMIT 1', [0, u'activity.mode'])
3702019-01-30 12:52:06,823 - pyemitter (14b7103e4700) : DEBUG (pyemitter:45) - [plex_activity.sources.s_websocket.main.WebSocket]:pipe(events: ['websocket.playing', 'websocket.scanner.started', 'websocket.scanner.progress', 'websocket.scanner.finished', 'websocket.timeline.created', 'websocket.timeline.matching', 'websocket.timeline.downloading', 'websocket.timeline.loading', 'websocket.timeline.finished', 'websocket.timeline.analyzing', 'websocket.timeline.deleted'], other: <plex_activity.activity.Activity object at 0x14b6e3420810>)
3712019-01-30 12:52:06,823 - pyemitter (14b7103e4700) : DEBUG (pyemitter:45) - [plex_activity.sources.s_websocket.main.WebSocket]:on(events: ['websocket.playing'], func: <pyemitter.PipeHandler object at 0x14b6e2964150>)
3722019-01-30 12:52:06,823 - pyemitter (14b7103e4700) : DEBUG (pyemitter:45) - [plex_activity.sources.s_websocket.main.WebSocket]:on(events: ['websocket.scanner.started'], func: <pyemitter.PipeHandler object at 0x14b6e2964110>)
3732019-01-30 12:52:06,823 - pyemitter (14b7103e4700) : DEBUG (pyemitter:45) - [plex_activity.sources.s_websocket.main.WebSocket]:on(events: ['websocket.scanner.progress'], func: <pyemitter.PipeHandler object at 0x14b6e2964290>)
3742019-01-30 12:52:06,823 - pyemitter (14b7103e4700) : DEBUG (pyemitter:45) - [plex_activity.sources.s_websocket.main.WebSocket]:on(events: ['websocket.scanner.finished'], func: <pyemitter.PipeHandler object at 0x14b6e2964090>)
3752019-01-30 12:52:06,823 - pyemitter (14b7103e4700) : DEBUG (pyemitter:45) - [plex_activity.sources.s_websocket.main.WebSocket]:on(events: ['websocket.timeline.created'], func: <pyemitter.PipeHandler object at 0x14b6e2964050>)
3762019-01-30 12:52:06,824 - pyemitter (14b7103e4700) : DEBUG (pyemitter:45) - [plex_activity.sources.s_websocket.main.WebSocket]:on(events: ['websocket.timeline.matching'], func: <pyemitter.PipeHandler object at 0x14b6e2964210>)
3772019-01-30 12:52:06,824 - pyemitter (14b7103e4700) : DEBUG (pyemitter:45) - [plex_activity.sources.s_websocket.main.WebSocket]:on(events: ['websocket.timeline.downloading'], func: <pyemitter.PipeHandler object at 0x14b6e2964190>)
3782019-01-30 12:52:06,824 - pyemitter (14b7103e4700) : DEBUG (pyemitter:45) - [plex_activity.sources.s_websocket.main.WebSocket]:on(events: ['websocket.timeline.loading'], func: <pyemitter.PipeHandler object at 0x14b6e2964250>)
3792019-01-30 12:52:06,824 - pyemitter (14b7103e4700) : DEBUG (pyemitter:45) - [plex_activity.sources.s_websocket.main.WebSocket]:on(events: ['websocket.timeline.finished'], func: <pyemitter.PipeHandler object at 0x14b6e2964310>)
3802019-01-30 12:52:06,824 - pyemitter (14b7103e4700) : DEBUG (pyemitter:45) - [plex_activity.sources.s_websocket.main.WebSocket]:on(events: ['websocket.timeline.analyzing'], func: <pyemitter.PipeHandler object at 0x14b6e2964350>)
3812019-01-30 12:52:06,824 - pyemitter (14b7103e4700) : DEBUG (pyemitter:45) - [plex_activity.sources.s_websocket.main.WebSocket]:on(events: ['websocket.timeline.deleted'], func: <pyemitter.PipeHandler object at 0x14b6e29643d0>)
3822019-01-30 12:52:06,825 - plex_activity.sources.s_websocket.main (14b6e16b1700) : DEBUG (plex_activity.sources.s_websocket.main:108) - Connecting...
3832019-01-30 12:52:06,825 - pyemitter (14b7103e4700) : DEBUG (pyemitter:45) - [plex_activity.sources.s_logging.parsers.now_playing.NowPlayingParser]:pipe(events: ['logging.playing'], other: <plex_activity.sources.s_logging.main.Logging object at 0x14b6e2964390>)
3842019-01-30 12:52:06,826 - pyemitter (14b7103e4700) : DEBUG (pyemitter:45) - [plex_activity.sources.s_logging.parsers.now_playing.NowPlayingParser]:on(events: ['logging.playing'], func: <pyemitter.PipeHandler object at 0x14b6e2964710>)
3852019-01-30 12:52:06,826 - pyemitter (14b7103e4700) : DEBUG (pyemitter:45) - [plex_activity.sources.s_logging.parsers.scrobble.ScrobbleParser]:pipe(events: ['logging.action.played', 'logging.action.unplayed'], other: <plex_activity.sources.s_logging.main.Logging object at 0x14b6e2964390>)
3862019-01-30 12:52:06,827 - pyemitter (14b7103e4700) : DEBUG (pyemitter:45) - [plex_activity.sources.s_logging.parsers.scrobble.ScrobbleParser]:on(events: ['logging.action.played'], func: <pyemitter.PipeHandler object at 0x14b6e2964a10>)
3872019-01-30 12:52:06,827 - pyemitter (14b7103e4700) : DEBUG (pyemitter:45) - [plex_activity.sources.s_logging.parsers.scrobble.ScrobbleParser]:on(events: ['logging.action.unplayed'], func: <pyemitter.PipeHandler object at 0x14b6e2964690>)
3882019-01-30 12:52:06,827 - pyemitter (14b7103e4700) : DEBUG (pyemitter:45) - [plex_activity.sources.s_logging.main.Logging]:pipe(events: ['logging.playing', 'logging.action.played', 'logging.action.unplayed'], other: <plex_activity.activity.Activity object at 0x14b6e3420810>)
3892019-01-30 12:52:06,827 - pyemitter (14b7103e4700) : DEBUG (pyemitter:45) - [plex_activity.sources.s_logging.main.Logging]:on(events: ['logging.playing'], func: <pyemitter.PipeHandler object at 0x14b6e2964990>)
3902019-01-30 12:52:06,827 - pyemitter (14b7103e4700) : DEBUG (pyemitter:45) - [plex_activity.sources.s_logging.main.Logging]:on(events: ['logging.action.played'], func: <pyemitter.PipeHandler object at 0x14b6e2964a50>)
3912019-01-30 12:52:06,828 - pyemitter (14b7103e4700) : DEBUG (pyemitter:45) - [plex_activity.sources.s_logging.main.Logging]:on(events: ['logging.action.unplayed'], func: <pyemitter.PipeHandler object at 0x14b6e2964b10>)
3922019-01-30 12:52:06,828 - plex_activity.activity (14b7103e4700) : INFO (plex_activity.activity:48) - Finished starting 2 method(s): "websocket", "logging"
3932019-01-30 12:52:06,832 - plex_activity.sources.s_websocket.main (14b6e16b1700) : DEBUG (plex_activity.sources.s_websocket.main:122) - Connected
3942019-01-30 12:52:06,836 - requests.packages.urllib3.connectionpool (14b6e14b0700) : DEBUG (requests.packages.urllib3.connectionpool:401) - http://127.0.0.1:32400 "GET /:/prefs HTTP/1.1" 200 28389
3952019-01-30 12:52:06,859 - plex_activity.sources.s_logging.main (14b6e14b0700) : INFO (plex_activity.sources.s_logging.main:221) - Unable to retrieve "LocalAppDataPath" from server
3962019-01-30 12:52:06,860 - plex_activity.sources.s_logging.main (14b6e14b0700) : DEBUG (plex_activity.sources.s_logging.main:176) - hints: ['/config/Library/Application Support/Plex Media Server/Logs/Plex Media Server.log', '/share/HDA_DATA/.qpkg/PlexMediaServer/Library/Plex Media Server/Logs/Plex Media Server.log', '/var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Logs/Plex Media Server.log']
3972019-01-30 12:52:06,860 - plex_activity.sources.s_logging.main (14b6e14b0700) : DEBUG (plex_activity.sources.s_logging.main:183) - Testing if "/config/Library/Application Support/Plex Media Server/Logs/Plex Media Server.log" exists
3982019-01-30 12:52:06,860 - plex_activity.sources.s_logging.main (14b6e14b0700) : DEBUG (plex_activity.sources.s_logging.main:190) - Using the path: '/config/Library/Application Support/Plex Media Server/Logs/Plex Media Server.log'
3992019-01-30 12:52:06,861 - plex_activity.sources.s_logging.main (14b6e14b0700) : INFO (plex_activity.sources.s_logging.main:107) - Opened file path: "/config/Library/Application Support/Plex Media Server/Logs/Plex Media Server.log"
4002019-01-30 12:52:07,412 - plex_activity.sources.s_logging.main (14b6e14b0700) : DEBUG (plex_activity.sources.s_logging.main:75) - Ready
4012019-01-30 12:52:08,497 - com.plexapp.plugins.trakttv (14b710926700) : INFO (com.plexapp.plugins.trakttv:131) - Saved the dictionary file
4022019-01-30 12:52:11,787 - peewee (14b6e1dba700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."session_id", "t1"."progress", "t1"."part", "t1"."rating_key", "t1"."event", "t1"."request", "t1"."queued_at" FROM "action.queue" AS t1 LIMIT 1', [])
4032019-01-30 12:52:12,218 - pyemitter (14b6e16b1700) : DEBUG (pyemitter:45) - [plex_activity.sources.s_websocket.main.WebSocket]:emit(event: u'websocket.notification.activity', args: ({u'Activity': {u'subtitle': u'', u'uuid': u'4ec31ad2-0a1f-4988-9a26-908672e7db6c', u'title': u'Processing subscriptions', u'cancellable': True, u'userID': 1, u'progress': 0, u'type': u'provider.subscriptions.process'}, u'event': u'started', u'uuid': u'4ec31ad2-0a1f-4988-9a26-908672e7db6c'},), kwargs: {})
4042019-01-30 12:52:12,222 - pyemitter (14b6e16b1700) : DEBUG (pyemitter:45) - [plex_activity.sources.s_websocket.main.WebSocket]:emit(event: u'websocket.notification.activity', args: ({u'Activity': {u'subtitle': u'', u'uuid': u'4ec31ad2-0a1f-4988-9a26-908672e7db6c', u'title': u'Processing subscriptions', u'cancellable': True, u'userID': 1, u'progress': 100, u'type': u'provider.subscriptions.process'}, u'event': u'ended', u'uuid': u'4ec31ad2-0a1f-4988-9a26-908672e7db6c'},), kwargs: {})
4052019-01-30 12:52:12,223 - pyemitter (14b6e16b1700) : DEBUG (pyemitter:45) - [plex_activity.sources.s_websocket.main.WebSocket]:emit(event: u'websocket.notification.activity', args: ({u'Activity': {u'subtitle': u'', u'uuid': u'e0618694-11db-4e46-adb7-b20a5390ec7f', u'title': u'Processing subscriptions', u'cancellable': True, u'userID': 1, u'progress': 100, u'type': u'provider.subscriptions.process'}, u'event': u'ended', u'uuid': u'e0618694-11db-4e46-adb7-b20a5390ec7f'},), kwargs: {})
4062019-01-30 12:52:16,652 - pyemitter (14b6e16b1700) : DEBUG (pyemitter:45) - [plex_activity.sources.s_websocket.main.WebSocket]:emit(event: u'websocket.notification.activity', args: ({u'Activity': {u'subtitle': u'', u'uuid': u'9518ce64-0175-4095-96e2-bfaef45e753d', u'title': u'Processing subscriptions', u'cancellable': True, u'userID': 1, u'progress': 0, u'type': u'provider.subscriptions.process'}, u'event': u'started', u'uuid': u'9518ce64-0175-4095-96e2-bfaef45e753d'},), kwargs: {})
4072019-01-30 12:52:16,655 - pyemitter (14b6e16b1700) : DEBUG (pyemitter:45) - [plex_activity.sources.s_websocket.main.WebSocket]:emit(event: u'websocket.notification.activity', args: ({u'Activity': {u'subtitle': u'', u'uuid': u'f04c7f4e-6603-4e89-ac44-80aa48107bfb', u'title': u'Processing subscriptions', u'cancellable': True, u'userID': 1, u'progress': 0, u'type': u'provider.subscriptions.process'}, u'event': u'started', u'uuid': u'f04c7f4e-6603-4e89-ac44-80aa48107bfb'},), kwargs: {})
4082019-01-30 12:52:16,661 - pyemitter (14b6e16b1700) : DEBUG (pyemitter:45) - [plex_activity.sources.s_websocket.main.WebSocket]:emit(event: u'websocket.notification.activity', args: ({u'Activity': {u'subtitle': u'', u'uuid': u'f04c7f4e-6603-4e89-ac44-80aa48107bfb', u'title': u'Processing subscriptions', u'cancellable': True, u'userID': 1, u'progress': 100, u'type': u'provider.subscriptions.process'}, u'event': u'ended', u'uuid': u'f04c7f4e-6603-4e89-ac44-80aa48107bfb'},), kwargs: {})
4092019-01-30 12:52:16,661 - pyemitter (14b6e16b1700) : DEBUG (pyemitter:45) - [plex_activity.sources.s_websocket.main.WebSocket]:emit(event: u'websocket.notification.activity', args: ({u'Activity': {u'subtitle': u'', u'uuid': u'9518ce64-0175-4095-96e2-bfaef45e753d', u'title': u'Processing subscriptions', u'cancellable': True, u'userID': 1, u'progress': 100, u'type': u'provider.subscriptions.process'}, u'event': u'ended', u'uuid': u'9518ce64-0175-4095-96e2-bfaef45e753d'},), kwargs: {})
4102019-01-30 12:52:16,794 - peewee (14b6e1dba700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."session_id", "t1"."progress", "t1"."part", "t1"."rating_key", "t1"."event", "t1"."request", "t1"."queued_at" FROM "action.queue" AS t1 LIMIT 1', [])
4112019-01-30 12:52:17,249 - pyemitter (14b6e16b1700) : DEBUG (pyemitter:45) - [plex_activity.sources.s_websocket.main.WebSocket]:emit(event: u'websocket.notification.preference', args: ({u'Setting': [{u'group': u'', u'default': False, u'value': True, u'summary': u'', u'hidden': True, u'label': u'', u'type': u'bool', u'id': u'LanguageInCloud', u'advanced': False}], u'type': u'preference', u'size': 1},), kwargs: {})
4122019-01-30 12:52:17,311 - pyemitter (14b6e16b1700) : DEBUG (pyemitter:45) - [plex_activity.sources.s_websocket.main.WebSocket]:emit(event: u'websocket.notification.account', args: ({u'event': u'mapping', u'hasPlexPass': True},), kwargs: {})
4132019-01-30 12:52:19,938 - pyemitter (14b6e16b1700) : DEBUG (pyemitter:45) - [plex_activity.sources.s_websocket.main.WebSocket]:emit(event: u'websocket.notification.account', args: ({u'event': u'mapping', u'hasPlexPass': True},), kwargs: {})
4142019-01-30 12:52:20,664 - pyemitter (14b6e16b1700) : DEBUG (pyemitter:45) - [plex_activity.sources.s_websocket.main.WebSocket]:emit(event: u'websocket.notification.reachability', args: ({u'reachability': True},), kwargs: {})
4152019-01-30 12:52:21,011 - pyemitter (14b6e16b1700) : DEBUG (pyemitter:45) - [plex_activity.sources.s_websocket.main.WebSocket]:emit(event: u'websocket.notification.update.statechange', args: ({u'state': u'done', u'version': u'', u'key': u''},), kwargs: {})
4162019-01-30 12:52:21,800 - peewee (14b6e1dba700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."session_id", "t1"."progress", "t1"."part", "t1"."rating_key", "t1"."event", "t1"."request", "t1"."queued_at" FROM "action.queue" AS t1 LIMIT 1', [])
4172019-01-30 12:52:24,290 - com.plexapp.plugins.trakttv (14b710926700) : DEBUG (com.plexapp.plugins.trakttv:717) - Handling request GET /video/trakt/:/prefs
4182019-01-30 12:52:24,305 - com.plexapp.plugins.trakttv (14b710926700) : DEBUG (com.plexapp.plugins.trakttv:814) - Found route matching /video/trakt/:/prefs
4192019-01-30 12:52:24,311 - com.plexapp.plugins.trakttv (14b710926700) : DEBUG (com.plexapp.plugins.trakttv:88) - Sending packed state data (108 bytes)
4202019-01-30 12:52:24,311 - com.plexapp.plugins.trakttv (14b710926700) : DEBUG (com.plexapp.plugins.trakttv:924) - Response: [200] MediaContainer, 4786 bytes
4212019-01-30 12:52:24,311 - root (14b710926700) : INFO (root:840) - 200 GET /video/trakt/:/prefs (127.0.0.1) 22.09ms
4222019-01-30 12:52:26,132 - pyemitter (14b6e16b1700) : DEBUG (pyemitter:45) - [plex_activity.sources.s_websocket.main.WebSocket]:emit(event: u'websocket.notification.activity', args: ({u'Activity': {u'subtitle': u'Initializing', u'uuid': u'5b31d5d5-530f-4d0b-ac0b-dc69760b158b', u'title': u'Refreshing guide data', u'cancellable': True, u'userID': 1, u'Context': {u'identifier': u'tv.plex.providers.epg.onconnect:2', u'dvrID': u'2'}, u'progress': 1, u'type': u'provider.epg.load'}, u'event': u'updated', u'uuid': u'5b31d5d5-530f-4d0b-ac0b-dc69760b158b'},), kwargs: {})
4232019-01-30 12:52:26,776 - pyemitter (14b6e16b1700) : DEBUG (pyemitter:45) - [plex_activity.sources.s_websocket.main.WebSocket]:emit(event: 'websocket.timeline.downloading', args: ({u'itemID': 453377, u'title': u'', u'sectionID': 1, u'state': 3, u'updatedAt': 1548874346, u'identifier': u'tv.plex.providers.epg.onconnect:2', u'type': 1, u'metadataState': u'loading'},), kwargs: {})
4242019-01-30 12:52:26,777 - pyemitter (14b6e16b1700) : DEBUG (pyemitter:45) - [plex_activity.activity.Activity ]:emit(event: 'websocket.timeline.downloading', args: ({u'itemID': 453377, u'title': u'', u'sectionID': 1, u'state': 3, u'updatedAt': 1548874346, u'identifier': u'tv.plex.providers.epg.onconnect:2', u'type': 1, u'metadataState': u'loading'},), kwargs: {})
4252019-01-30 12:52:26,777 - pyemitter (14b6e16b1700) : DEBUG (pyemitter:45) - [plex_activity.sources.s_websocket.main.WebSocket]:emit(event: 'websocket.timeline.finished', args: ({u'itemID': 453377, u'title': u'', u'sectionID': 1, u'state': 5, u'updatedAt': 1548874346, u'identifier': u'tv.plex.providers.epg.onconnect:2', u'type': 1},), kwargs: {})
4262019-01-30 12:52:26,777 - pyemitter (14b6e16b1700) : DEBUG (pyemitter:45) - [plex_activity.activity.Activity ]:emit(event: 'websocket.timeline.finished', args: ({u'itemID': 453377, u'title': u'', u'sectionID': 1, u'state': 5, u'updatedAt': 1548874346, u'identifier': u'tv.plex.providers.epg.onconnect:2', u'type': 1},), kwargs: {})
4272019-01-30 12:52:26,777 - plugin.sync.triggers.library_update (14b6e16b1700) : DEBUG (plugin.sync.triggers.library_update:40) - Scanner activity, sync will be triggered in 120 seconds
4282019-01-30 12:52:26,784 - pyemitter (14b6e16b1700) : DEBUG (pyemitter:45) - [plex_activity.sources.s_websocket.main.WebSocket]:emit(event: 'websocket.timeline.downloading', args: ({u'itemID': 453377, u'title': u'', u'sectionID': 1, u'state': 3, u'updatedAt': 1548874346, u'identifier': u'tv.plex.providers.epg.onconnect:2', u'type': 1, u'metadataState': u'loading'},), kwargs: {})
4292019-01-30 12:52:26,785 - pyemitter (14b6e16b1700) : DEBUG (pyemitter:45) - [plex_activity.activity.Activity ]:emit(event: 'websocket.timeline.downloading', args: ({u'itemID': 453377, u'title': u'', u'sectionID': 1, u'state': 3, u'updatedAt': 1548874346, u'identifier': u'tv.plex.providers.epg.onconnect:2', u'type': 1, u'metadataState': u'loading'},), kwargs: {})
4302019-01-30 12:52:26,785 - pyemitter (14b6e16b1700) : DEBUG (pyemitter:45) - [plex_activity.sources.s_websocket.main.WebSocket]:emit(event: 'websocket.timeline.finished', args: ({u'itemID': 453377, u'title': u'', u'sectionID': 1, u'state': 5, u'updatedAt': 1548874346, u'identifier': u'tv.plex.providers.epg.onconnect:2', u'type': 1},), kwargs: {})
4312019-01-30 12:52:26,785 - pyemitter (14b6e16b1700) : DEBUG (pyemitter:45) - [plex_activity.activity.Activity ]:emit(event: 'websocket.timeline.finished', args: ({u'itemID': 453377, u'title': u'', u'sectionID': 1, u'state': 5, u'updatedAt': 1548874346, u'identifier': u'tv.plex.providers.epg.onconnect:2', u'type': 1},), kwargs: {})
4322019-01-30 12:52:26,785 - plugin.sync.triggers.library_update (14b6e16b1700) : DEBUG (plugin.sync.triggers.library_update:40) - Scanner activity, sync will be triggered in 120 seconds
4332019-01-30 12:52:26,794 - pyemitter (14b6e16b1700) : DEBUG (pyemitter:45) - [plex_activity.sources.s_websocket.main.WebSocket]:emit(event: 'websocket.timeline.downloading', args: ({u'itemID': 526218, u'title': u'', u'sectionID': 2, u'state': 3, u'updatedAt': 1548874346, u'identifier': u'tv.plex.providers.epg.onconnect:2', u'type': 4, u'metadataState': u'loading'},), kwargs: {})
4342019-01-30 12:52:26,794 - pyemitter (14b6e16b1700) : DEBUG (pyemitter:45) - [plex_activity.activity.Activity ]:emit(event: 'websocket.timeline.downloading', args: ({u'itemID': 526218, u'title': u'', u'sectionID': 2, u'state': 3, u'updatedAt': 1548874346, u'identifier': u'tv.plex.providers.epg.onconnect:2', u'type': 4, u'metadataState': u'loading'},), kwargs: {})
4352019-01-30 12:52:26,795 - pyemitter (14b6e16b1700) : DEBUG (pyemitter:45) - [plex_activity.sources.s_websocket.main.WebSocket]:emit(event: 'websocket.timeline.finished', args: ({u'itemID': 526218, u'title': u'', u'sectionID': 2, u'state': 5, u'updatedAt': 1548874346, u'identifier': u'tv.plex.providers.epg.onconnect:2', u'type': 4},), kwargs: {})
4362019-01-30 12:52:26,795 - pyemitter (14b6e16b1700) : DEBUG (pyemitter:45) - [plex_activity.activity.Activity ]:emit(event: 'websocket.timeline.finished', args: ({u'itemID': 526218, u'title': u'', u'sectionID': 2, u'state': 5, u'updatedAt': 1548874346, u'identifier': u'tv.plex.providers.epg.onconnect:2', u'type': 4},), kwargs: {})
4372019-01-30 12:52:26,795 - plugin.sync.triggers.library_update (14b6e16b1700) : DEBUG (plugin.sync.triggers.library_update:40) - Scanner activity, sync will be triggered in 120 seconds
4382019-01-30 12:52:26,798 - pyemitter (14b6e16b1700) : DEBUG (pyemitter:45) - [plex_activity.sources.s_websocket.main.WebSocket]:emit(event: 'websocket.timeline.deleted', args: ({u'itemID': 526218, u'rootItemID': 238, u'sectionID': 2, u'parentItemID': 101669, u'title': u'', u'state': 9, u'updatedAt': 1548874346, u'identifier': u'tv.plex.providers.epg.onconnect:2', u'type': 4, u'metadataState': u'deleted'},), kwargs: {})
4392019-01-30 12:52:26,798 - pyemitter (14b6e16b1700) : DEBUG (pyemitter:45) - [plex_activity.activity.Activity ]:emit(event: 'websocket.timeline.deleted', args: ({u'itemID': 526218, u'rootItemID': 238, u'sectionID': 2, u'parentItemID': 101669, u'title': u'', u'state': 9, u'updatedAt': 1548874346, u'identifier': u'tv.plex.providers.epg.onconnect:2', u'type': 4, u'metadataState': u'deleted'},), kwargs: {})
4402019-01-30 12:52:26,806 - peewee (14b6e1dba700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."session_id", "t1"."progress", "t1"."part", "t1"."rating_key", "t1"."event", "t1"."request", "t1"."queued_at" FROM "action.queue" AS t1 LIMIT 1', [])
4412019-01-30 12:52:26,807 - pyemitter (14b6e16b1700) : DEBUG (pyemitter:45) - [plex_activity.sources.s_websocket.main.WebSocket]:emit(event: u'websocket.notification.activity', args: ({u'Activity': {u'subtitle': u'Downloaded 1249 airings', u'uuid': u'5b31d5d5-530f-4d0b-ac0b-dc69760b158b', u'title': u'Refreshing guide data', u'cancellable': True, u'userID': 1, u'Context': {u'identifier': u'tv.plex.providers.epg.onconnect:2', u'dvrID': u'2'}, u'progress': 1, u'type': u'provider.epg.load'}, u'event': u'updated', u'uuid': u'5b31d5d5-530f-4d0b-ac0b-dc69760b158b'},), kwargs: {})
4422019-01-30 12:52:30,040 - pyemitter (14b6e16b1700) : DEBUG (pyemitter:45) - [plex_activity.sources.s_websocket.main.WebSocket]:emit(event: u'websocket.notification.activity', args: ({u'Activity': {u'subtitle': u'Downloaded 1249 airings', u'uuid': u'5b31d5d5-530f-4d0b-ac0b-dc69760b158b', u'title': u'Refreshing guide data', u'cancellable': True, u'userID': 1, u'Context': {u'identifier': u'tv.plex.providers.epg.onconnect:2', u'dvrID': u'2'}, u'progress': 2, u'type': u'provider.epg.load'}, u'event': u'updated', u'uuid': u'5b31d5d5-530f-4d0b-ac0b-dc69760b158b'},), kwargs: {})
4432019-01-30 12:52:31,050 - pyemitter (14b6e16b1700) : DEBUG (pyemitter:45) - [plex_activity.sources.s_websocket.main.WebSocket]:emit(event: u'websocket.notification.activity', args: ({u'Activity': {u'subtitle': u'Downloaded 2526 airings', u'uuid': u'5b31d5d5-530f-4d0b-ac0b-dc69760b158b', u'title': u'Refreshing guide data', u'cancellable': True, u'userID': 1, u'Context': {u'identifier': u'tv.plex.providers.epg.onconnect:2', u'dvrID': u'2'}, u'progress': 2, u'type': u'provider.epg.load'}, u'event': u'updated', u'uuid': u'5b31d5d5-530f-4d0b-ac0b-dc69760b158b'},), kwargs: {})
4442019-01-30 12:52:31,811 - peewee (14b6e1dba700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."session_id", "t1"."progress", "t1"."part", "t1"."rating_key", "t1"."event", "t1"."request", "t1"."queued_at" FROM "action.queue" AS t1 LIMIT 1', [])
4452019-01-30 12:52:34,029 - pyemitter (14b6e16b1700) : DEBUG (pyemitter:45) - [plex_activity.sources.s_websocket.main.WebSocket]:emit(event: u'websocket.notification.activity', args: ({u'Activity': {u'subtitle': u'Downloaded 2526 airings', u'uuid': u'5b31d5d5-530f-4d0b-ac0b-dc69760b158b', u'title': u'Refreshing guide data', u'cancellable': True, u'userID': 1, u'Context': {u'identifier': u'tv.plex.providers.epg.onconnect:2', u'dvrID': u'2'}, u'progress': 3, u'type': u'provider.epg.load'}, u'event': u'updated', u'uuid': u'5b31d5d5-530f-4d0b-ac0b-dc69760b158b'},), kwargs: {})
4462019-01-30 12:52:34,961 - pyemitter (14b6e16b1700) : DEBUG (pyemitter:45) - [plex_activity.sources.s_websocket.main.WebSocket]:emit(event: u'websocket.notification.activity', args: ({u'Activity': {u'subtitle': u'Downloaded 3764 airings', u'uuid': u'5b31d5d5-530f-4d0b-ac0b-dc69760b158b', u'title': u'Refreshing guide data', u'cancellable': True, u'userID': 1, u'Context': {u'identifier': u'tv.plex.providers.epg.onconnect:2', u'dvrID': u'2'}, u'progress': 3, u'type': u'provider.epg.load'}, u'event': u'updated', u'uuid': u'5b31d5d5-530f-4d0b-ac0b-dc69760b158b'},), kwargs: {})
4472019-01-30 12:52:36,818 - peewee (14b6e1dba700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."session_id", "t1"."progress", "t1"."part", "t1"."rating_key", "t1"."event", "t1"."request", "t1"."queued_at" FROM "action.queue" AS t1 LIMIT 1', [])
4482019-01-30 12:52:37,348 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:717) - Handling request GET /video/trakt
4492019-01-30 12:52:37,355 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:814) - Found route matching /video/trakt
4502019-01-30 12:52:37,356 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."code", "t1"."type", "t1"."last_logged_at", "t1"."last_viewed_at", "t1"."exception_hash", "t1"."revision", "t1"."version_base", "t1"."version_branch", "t1"."summary", "t1"."description" FROM "message" AS t1 WHERE ((("t1"."last_logged_at" > ?) AND ("t1"."version_base" = ?)) AND (("t1"."last_viewed_at" IS ?) OR ("t1"."last_viewed_at" < "t1"."last_logged_at")))', ['2019-01-16 18:52:37.355747', u'1.3.3', None])
4512019-01-30 12:52:37,358 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT Count(*) FROM "account" AS t1 WHERE (("t1"."id" != ?) AND ("t1"."deleted" = ?))', [0, 0])
4522019-01-30 12:52:37,359 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:117) - Checking if com.plexapp.plugins.trakttv is broken
4532019-01-30 12:52:37,359 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X1N0b3JlU2VydmljZTpJc0NoYW5uZWxCcm9rZW4_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMQpzMjcKY29tLnBsZXhhcHAucGx1Z2lucy50cmFrdHR2czEwCmlkZW50aWZpZXJyMAo_'
4542019-01-30 12:52:37,369 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:88) - Sending packed state data (108 bytes)
4552019-01-30 12:52:37,370 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:924) - Response: [200] MediaContainer, 1302 bytes
4562019-01-30 12:52:37,370 - root (14b7103e4700) : INFO (root:840) - 200 GET /video/trakt (127.0.0.1) 22.29ms
4572019-01-30 12:52:38,246 - pyemitter (14b6e16b1700) : DEBUG (pyemitter:45) - [plex_activity.sources.s_websocket.main.WebSocket]:emit(event: u'websocket.notification.activity', args: ({u'Activity': {u'subtitle': u'Downloaded 3764 airings', u'uuid': u'5b31d5d5-530f-4d0b-ac0b-dc69760b158b', u'title': u'Refreshing guide data', u'cancellable': True, u'userID': 1, u'Context': {u'identifier': u'tv.plex.providers.epg.onconnect:2', u'dvrID': u'2'}, u'progress': 4, u'type': u'provider.epg.load'}, u'event': u'updated', u'uuid': u'5b31d5d5-530f-4d0b-ac0b-dc69760b158b'},), kwargs: {})
4582019-01-30 12:52:38,333 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:717) - Handling request GET /video/trakt/messages/list?viewed=False
4592019-01-30 12:52:38,334 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:814) - Found route matching /video/trakt/messages/list
4602019-01-30 12:52:38,335 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."code", "t1"."type", "t1"."last_logged_at", "t1"."last_viewed_at", "t1"."exception_hash", "t1"."revision", "t1"."version_base", "t1"."version_branch", "t1"."summary", "t1"."description" FROM "message" AS t1 WHERE ((("t1"."last_logged_at" > ?) AND ("t1"."version_base" = ?)) AND (("t1"."last_viewed_at" IS ?) OR ("t1"."last_viewed_at" < "t1"."last_logged_at"))) ORDER BY "t1"."last_logged_at" DESC LIMIT 50', ['2019-01-16 18:52:38.334770', u'1.3.3', None])
4612019-01-30 12:52:38,337 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT Count(*) FROM "message" AS t1 WHERE (("t1"."last_logged_at" > ?) AND ("t1"."version_base" = ?))', ['2019-01-16 18:52:38.336898', u'1.3.3'])
4622019-01-30 12:52:38,338 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:88) - Sending packed state data (108 bytes)
4632019-01-30 12:52:38,338 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:924) - Response: [200] MediaContainer, 938 bytes
4642019-01-30 12:52:38,339 - root (14b7103e4700) : INFO (root:840) - 200 GET /video/trakt/messages/list?viewed=False (127.0.0.1) 6.49ms
4652019-01-30 12:52:39,228 - pyemitter (14b6e16b1700) : DEBUG (pyemitter:45) - [plex_activity.sources.s_websocket.main.WebSocket]:emit(event: u'websocket.notification.activity', args: ({u'Activity': {u'subtitle': u'Downloaded 5110 airings', u'uuid': u'5b31d5d5-530f-4d0b-ac0b-dc69760b158b', u'title': u'Refreshing guide data', u'cancellable': True, u'userID': 1, u'Context': {u'identifier': u'tv.plex.providers.epg.onconnect:2', u'dvrID': u'2'}, u'progress': 4, u'type': u'provider.epg.load'}, u'event': u'updated', u'uuid': u'5b31d5d5-530f-4d0b-ac0b-dc69760b158b'},), kwargs: {})
4662019-01-30 12:52:40,280 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:717) - Handling request GET /video/trakt
4672019-01-30 12:52:40,282 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:814) - Found route matching /video/trakt
4682019-01-30 12:52:40,283 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."code", "t1"."type", "t1"."last_logged_at", "t1"."last_viewed_at", "t1"."exception_hash", "t1"."revision", "t1"."version_base", "t1"."version_branch", "t1"."summary", "t1"."description" FROM "message" AS t1 WHERE ((("t1"."last_logged_at" > ?) AND ("t1"."version_base" = ?)) AND (("t1"."last_viewed_at" IS ?) OR ("t1"."last_viewed_at" < "t1"."last_logged_at")))', ['2019-01-16 18:52:40.282537', u'1.3.3', None])
4692019-01-30 12:52:40,284 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT Count(*) FROM "account" AS t1 WHERE (("t1"."id" != ?) AND ("t1"."deleted" = ?))', [0, 0])
4702019-01-30 12:52:40,285 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:117) - Checking if com.plexapp.plugins.trakttv is broken
4712019-01-30 12:52:40,286 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X1N0b3JlU2VydmljZTpJc0NoYW5uZWxCcm9rZW4_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMQpzMjcKY29tLnBsZXhhcHAucGx1Z2lucy50cmFrdHR2czEwCmlkZW50aWZpZXJyMAo_'
4722019-01-30 12:52:40,298 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:88) - Sending packed state data (108 bytes)
4732019-01-30 12:52:40,298 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:924) - Response: [200] MediaContainer, 1302 bytes
4742019-01-30 12:52:40,298 - root (14b7103e4700) : INFO (root:840) - 304 GET /video/trakt (127.0.0.1) 18.64ms
4752019-01-30 12:52:41,522 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:717) - Handling request GET /video/trakt/about
4762019-01-30 12:52:41,524 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:814) - Found route matching /video/trakt/about
4772019-01-30 12:52:41,525 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:88) - Sending packed state data (108 bytes)
4782019-01-30 12:52:41,525 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:924) - Response: [200] MediaContainer, 907 bytes
4792019-01-30 12:52:41,526 - root (14b7103e4700) : INFO (root:840) - 200 GET /video/trakt/about (127.0.0.1) 4.26ms
4802019-01-30 12:52:41,824 - peewee (14b6e1dba700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."session_id", "t1"."progress", "t1"."part", "t1"."rating_key", "t1"."event", "t1"."request", "t1"."queued_at" FROM "action.queue" AS t1 LIMIT 1', [])
4812019-01-30 12:52:43,026 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:717) - Handling request GET /video/trakt
4822019-01-30 12:52:43,027 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:814) - Found route matching /video/trakt
4832019-01-30 12:52:43,028 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."code", "t1"."type", "t1"."last_logged_at", "t1"."last_viewed_at", "t1"."exception_hash", "t1"."revision", "t1"."version_base", "t1"."version_branch", "t1"."summary", "t1"."description" FROM "message" AS t1 WHERE ((("t1"."last_logged_at" > ?) AND ("t1"."version_base" = ?)) AND (("t1"."last_viewed_at" IS ?) OR ("t1"."last_viewed_at" < "t1"."last_logged_at")))', ['2019-01-16 18:52:43.028091', u'1.3.3', None])
4842019-01-30 12:52:43,030 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT Count(*) FROM "account" AS t1 WHERE (("t1"."id" != ?) AND ("t1"."deleted" = ?))', [0, 0])
4852019-01-30 12:52:43,031 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:117) - Checking if com.plexapp.plugins.trakttv is broken
4862019-01-30 12:52:43,031 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X1N0b3JlU2VydmljZTpJc0NoYW5uZWxCcm9rZW4_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMQpzMjcKY29tLnBsZXhhcHAucGx1Z2lucy50cmFrdHR2czEwCmlkZW50aWZpZXJyMAo_'
4872019-01-30 12:52:43,042 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:88) - Sending packed state data (108 bytes)
4882019-01-30 12:52:43,042 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:924) - Response: [200] MediaContainer, 1302 bytes
4892019-01-30 12:52:43,043 - root (14b7103e4700) : INFO (root:840) - 304 GET /video/trakt (127.0.0.1) 17.31ms
4902019-01-30 12:52:43,779 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:717) - Handling request GET /video/trakt/sync
4912019-01-30 12:52:43,781 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:814) - Found route matching /video/trakt/sync
4922019-01-30 12:52:43,782 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."name", "t1"."thumb", "t1"."deleted", "t1"."refreshed_at" FROM "account" AS t1 WHERE ("t1"."id" = ?) LIMIT 1', [1])
4932019-01-30 12:52:43,784 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" IS ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 0, None, None])
4942019-01-30 12:52:43,870 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."result_id", "t1"."error_id", "t2"."id", "t2"."code", "t2"."type", "t2"."last_logged_at", "t2"."last_viewed_at", "t2"."exception_hash", "t2"."revision", "t2"."version_base", "t2"."version_branch", "t2"."summary", "t2"."description" FROM "sync.result.error" AS t1 LEFT OUTER JOIN "message" AS t2 ON ("t2"."id" = "t1"."error_id") WHERE ("t1"."result_id" = ?)', [34624])
4952019-01-30 12:52:43,871 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" IS ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 1, None, None])
4962019-01-30 12:52:43,892 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."result_id", "t1"."error_id", "t2"."id", "t2"."code", "t2"."type", "t2"."last_logged_at", "t2"."last_viewed_at", "t2"."exception_hash", "t2"."revision", "t2"."version_base", "t2"."version_branch", "t2"."summary", "t2"."description" FROM "sync.result.error" AS t1 LEFT OUTER JOIN "message" AS t2 ON ("t2"."id" = "t1"."error_id") WHERE ("t1"."result_id" = ?)', [34632])
4972019-01-30 12:52:43,893 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" IS ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 4, None, None])
4982019-01-30 12:52:43,913 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."result_id", "t1"."error_id", "t2"."id", "t2"."code", "t2"."type", "t2"."last_logged_at", "t2"."last_viewed_at", "t2"."exception_hash", "t2"."revision", "t2"."version_base", "t2"."version_branch", "t2"."summary", "t2"."description" FROM "sync.result.error" AS t1 LEFT OUTER JOIN "message" AS t2 ON ("t2"."id" = "t1"."error_id") WHERE ("t1"."result_id" = ?)', [34587])
4992019-01-30 12:52:43,914 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."key", "t1"."username", "t1"."title", "t1"."thumb", "t1"."refreshed_at" FROM "plex.account" AS t1 WHERE ("t1"."account_id" = ?)', [1])
5002019-01-30 12:52:43,915 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."password", "t1"."token_plex", "t1"."token_server" FROM "plex.credential.basic" AS t1 WHERE ("t1"."account_id" = ?)', [1])
5012019-01-30 12:52:43,915 - plugin.models.m_plex.account (14b7103e4700) : DEBUG (plugin.models.m_plex.account:78) - Using basic authorization for <PlexAccount username: u'iammiak'>
5022019-01-30 12:52:43,918 - requests.packages.urllib3.connectionpool (14b7103e4700) : DEBUG (requests.packages.urllib3.connectionpool:248) - Resetting dropped connection: 127.0.0.1
5032019-01-30 12:52:43,922 - requests.packages.urllib3.connectionpool (14b7103e4700) : DEBUG (requests.packages.urllib3.connectionpool:401) - http://127.0.0.1:32400 "GET /library/sections HTTP/1.1" 200 3932
5042019-01-30 12:52:43,925 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
5052019-01-30 12:52:43,926 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" = ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, u'8', None])
5062019-01-30 12:52:43,945 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
5072019-01-30 12:52:43,946 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" = ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, u'7', None])
5082019-01-30 12:52:43,965 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
5092019-01-30 12:52:43,966 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" = ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, u'3', None])
5102019-01-30 12:52:43,984 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
5112019-01-30 12:52:43,985 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" = ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, u'5', None])
5122019-01-30 12:52:44,004 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
5132019-01-30 12:52:44,006 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" = ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, u'1', None])
5142019-01-30 12:52:44,024 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
5152019-01-30 12:52:44,025 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" = ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, u'2', None])
5162019-01-30 12:52:44,044 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
5172019-01-30 12:52:44,045 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" = ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, u'6', None])
5182019-01-30 12:52:44,062 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
5192019-01-30 12:52:44,063 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" = ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, u'4', None])
5202019-01-30 12:52:44,081 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" IS ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, None, None])
5212019-01-30 12:52:44,100 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:88) - Sending packed state data (108 bytes)
5222019-01-30 12:52:44,100 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:924) - Response: [200] MediaContainer, 5318 bytes
5232019-01-30 12:52:44,101 - root (14b7103e4700) : INFO (root:840) - 200 GET /video/trakt/sync (127.0.0.1) 321.78ms
5242019-01-30 12:52:44,543 - pyemitter (14b6e16b1700) : DEBUG (pyemitter:45) - [plex_activity.sources.s_websocket.main.WebSocket]:emit(event: u'websocket.notification.reachability', args: ({u'reachability': True},), kwargs: {})
5252019-01-30 12:52:45,225 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:717) - Handling request GET /video/trakt/sync/pull?account_id=1&t=1548874363.87
5262019-01-30 12:52:45,226 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:814) - Found route matching /video/trakt/sync/pull
5272019-01-30 12:52:45,227 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."name", "t2"."id", "t2"."key", "t2"."username", "t3"."token_plex", "t3"."token_server", "t4"."username", "t5"."token", "t6"."access_token", "t6"."refresh_token", "t6"."created_at", "t6"."expires_in" FROM "account" AS t1 LEFT OUTER JOIN "plex.account" AS t2 ON ("t2"."account_id" = "t1"."id") LEFT OUTER JOIN "trakt.account" AS t4 ON ("t4"."account_id" = "t1"."id") LEFT OUTER JOIN "trakt.credential.basic" AS t5 ON ("t5"."account_id" = "t4"."id") LEFT OUTER JOIN "trakt.credential.oauth" AS t6 ON ("t6"."account_id" = "t4"."id") LEFT OUTER JOIN "plex.credential.basic" AS t3 ON ("t3"."account_id" = "t2"."id") WHERE ("t1"."id" = ?) LIMIT 1', [1])
5282019-01-30 12:52:45,229 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section" FROM "sync.status" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE ((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" = ?)) LIMIT 1', [1, 1, None])
5292019-01-30 12:52:45,252 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('INSERT INTO "sync.status" ("account_id", "mode", "section") VALUES (?, ?, ?)', [1, 1, None])
5302019-01-30 12:52:45,273 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('INSERT INTO "sync.result" ("status_id", "trigger", "started_at") VALUES (?, ?, ?)', [34647, 0, '2019-01-30 18:52:45.273234'])
5312019-01-30 12:52:45,288 - plugin.sync.core.task.main (14b7103e4700) : DEBUG (plugin.sync.core.task.main:81) - Task Arguments: {}
5322019-01-30 12:52:45,288 - plugin.sync.core.task.configuration (14b7103e4700) : DEBUG (plugin.sync.core.task.configuration:17) - Task Configuration:
5332019-01-30 12:52:45,288 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."key", "t1"."value" FROM "configuration.option" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."key" = ?)) LIMIT 1', [1, u'sync.lists.liked.playlists'])
5342019-01-30 12:52:45,289 - plugin.sync.core.task.configuration (14b7103e4700) : DEBUG (plugin.sync.core.task.configuration:26) - - [sync.lists.liked.playlists]: True
5352019-01-30 12:52:45,290 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."key", "t1"."value" FROM "configuration.option" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."key" = ?)) LIMIT 1', [0, u'sync.idle_defer'])
5362019-01-30 12:52:45,290 - plugin.sync.core.task.configuration (14b7103e4700) : DEBUG (plugin.sync.core.task.configuration:26) - - [sync.idle_defer]: True
5372019-01-30 12:52:45,291 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."key", "t1"."value" FROM "configuration.option" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."key" = ?)) LIMIT 1', [1, u'sync.lists.watchlist.mode'])
5382019-01-30 12:52:45,291 - plugin.sync.core.task.configuration (14b7103e4700) : DEBUG (plugin.sync.core.task.configuration:26) - - [sync.lists.watchlist.mode]: None
5392019-01-30 12:52:45,292 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."key", "t1"."value" FROM "configuration.option" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."key" = ?)) LIMIT 1', [1, u'sync.lists.personal.playlists'])
5402019-01-30 12:52:45,292 - plugin.sync.core.task.configuration (14b7103e4700) : DEBUG (plugin.sync.core.task.configuration:26) - - [sync.lists.personal.playlists]: True
5412019-01-30 12:52:45,292 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."key" FROM "scheduler.task" AS t1 WHERE ("t1"."key" = ?) LIMIT 1', [u'sync.interval'])
5422019-01-30 12:52:45,293 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."task_id", "t1"."trigger", "t1"."ran_at", "t1"."due_at" FROM "scheduler.job" AS t1 INNER JOIN "scheduler.task" AS t2 ON ("t1"."task_id" = "t2"."key") INNER JOIN "account" AS t3 ON ("t1"."account_id" = "t3"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."task_id" = ?)) LIMIT 1', [1, u'sync.interval'])
5432019-01-30 12:52:45,294 - plugin.sync.core.task.configuration (14b7103e4700) : DEBUG (plugin.sync.core.task.configuration:26) - - [sync.interval]: None
5442019-01-30 12:52:45,294 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."key", "t1"."value" FROM "configuration.option" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."key" = ?)) LIMIT 1', [0, u'sync.profiler'])
5452019-01-30 12:52:45,295 - plugin.sync.core.task.configuration (14b7103e4700) : DEBUG (plugin.sync.core.task.configuration:26) - - [sync.profiler]: None
5462019-01-30 12:52:45,295 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."key", "t1"."value" FROM "configuration.option" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."key" = ?)) LIMIT 1', [1, u'sync.lists.personal.mode'])
5472019-01-30 12:52:45,296 - plugin.sync.core.task.configuration (14b7103e4700) : DEBUG (plugin.sync.core.task.configuration:26) - - [sync.lists.personal.mode]: None
5482019-01-30 12:52:45,296 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."key", "t1"."value" FROM "configuration.option" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."key" = ?)) LIMIT 1', [0, u'sync.action.mode'])
5492019-01-30 12:52:45,297 - plugin.sync.core.task.configuration (14b7103e4700) : DEBUG (plugin.sync.core.task.configuration:26) - - [sync.action.mode]: 0
5502019-01-30 12:52:45,297 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."key", "t1"."value" FROM "configuration.option" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."key" = ?)) LIMIT 1', [0, u'sync.idle_delay'])
5512019-01-30 12:52:45,298 - plugin.sync.core.task.configuration (14b7103e4700) : DEBUG (plugin.sync.core.task.configuration:26) - - [sync.idle_delay]: 30
5522019-01-30 12:52:45,299 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."key", "t1"."value" FROM "configuration.option" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."key" = ?)) LIMIT 1', [1, u'scrobble.enabled'])
5532019-01-30 12:52:45,299 - plugin.sync.core.task.configuration (14b7103e4700) : DEBUG (plugin.sync.core.task.configuration:26) - - [scrobble.enabled]: True
5542019-01-30 12:52:45,299 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."key" FROM "scheduler.task" AS t1 WHERE ("t1"."key" = ?) LIMIT 1', [u'backup.interval'])
5552019-01-30 12:52:45,300 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."task_id", "t1"."trigger", "t1"."ran_at", "t1"."due_at" FROM "scheduler.job" AS t1 INNER JOIN "scheduler.task" AS t2 ON ("t1"."task_id" = "t2"."key") INNER JOIN "account" AS t3 ON ("t1"."account_id" = "t3"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."task_id" = ?)) LIMIT 1', [0, u'backup.interval'])
5562019-01-30 12:52:45,301 - plugin.sync.core.task.configuration (14b7103e4700) : DEBUG (plugin.sync.core.task.configuration:26) - - [backup.interval]: u'0 0 * * *'
5572019-01-30 12:52:45,302 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."key", "t1"."value" FROM "configuration.option" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."key" = ?)) LIMIT 1', [1, u'sync.watched.mode'])
5582019-01-30 12:52:45,302 - plugin.sync.core.task.configuration (14b7103e4700) : DEBUG (plugin.sync.core.task.configuration:26) - - [sync.watched.mode]: 1
5592019-01-30 12:52:45,303 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."key", "t1"."value" FROM "configuration.option" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."key" = ?)) LIMIT 1', [1, u'sync.collection.clean'])
5602019-01-30 12:52:45,303 - plugin.sync.core.task.configuration (14b7103e4700) : DEBUG (plugin.sync.core.task.configuration:26) - - [sync.collection.clean]: False
5612019-01-30 12:52:45,304 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."key", "t1"."value" FROM "configuration.option" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."key" = ?)) LIMIT 1', [1, u'sync.lists.watchlist.playlists'])
5622019-01-30 12:52:45,304 - plugin.sync.core.task.configuration (14b7103e4700) : DEBUG (plugin.sync.core.task.configuration:26) - - [sync.lists.watchlist.playlists]: True
5632019-01-30 12:52:45,305 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."key", "t1"."value" FROM "configuration.option" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."key" = ?)) LIMIT 1', [0, u'activity.mode'])
5642019-01-30 12:52:45,305 - plugin.sync.core.task.configuration (14b7103e4700) : DEBUG (plugin.sync.core.task.configuration:26) - - [activity.mode]: None
5652019-01-30 12:52:45,306 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."key", "t1"."value" FROM "configuration.option" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."key" = ?)) LIMIT 1', [1, u'sync.library_update'])
5662019-01-30 12:52:45,306 - plugin.sync.core.task.configuration (14b7103e4700) : DEBUG (plugin.sync.core.task.configuration:26) - - [sync.library_update]: False
5672019-01-30 12:52:45,307 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."key", "t1"."value" FROM "configuration.option" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."key" = ?)) LIMIT 1', [0, u'scrobble.duplication_period'])
5682019-01-30 12:52:45,307 - plugin.sync.core.task.configuration (14b7103e4700) : DEBUG (plugin.sync.core.task.configuration:26) - - [scrobble.duplication_period]: 360
5692019-01-30 12:52:45,308 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."key", "t1"."value" FROM "configuration.option" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."key" = ?)) LIMIT 1', [1, u'sync.ratings.mode'])
5702019-01-30 12:52:45,308 - plugin.sync.core.task.configuration (14b7103e4700) : DEBUG (plugin.sync.core.task.configuration:26) - - [sync.ratings.mode]: None
5712019-01-30 12:52:45,309 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."key", "t1"."value" FROM "configuration.option" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."key" = ?)) LIMIT 1', [1, u'sync.ratings.conflict'])
5722019-01-30 12:52:45,309 - plugin.sync.core.task.configuration (14b7103e4700) : DEBUG (plugin.sync.core.task.configuration:26) - - [sync.ratings.conflict]: 0
5732019-01-30 12:52:45,310 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."key", "t1"."value" FROM "configuration.option" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."key" = ?)) LIMIT 1', [0, u'api.enabled'])
5742019-01-30 12:52:45,310 - plugin.sync.core.task.configuration (14b7103e4700) : DEBUG (plugin.sync.core.task.configuration:26) - - [api.enabled]: True
5752019-01-30 12:52:45,311 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."key", "t1"."value" FROM "configuration.option" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."key" = ?)) LIMIT 1', [1, u'sync.playback.mode'])
5762019-01-30 12:52:45,311 - plugin.sync.core.task.configuration (14b7103e4700) : DEBUG (plugin.sync.core.task.configuration:26) - - [sync.playback.mode]: 1
5772019-01-30 12:52:45,312 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."key", "t1"."value" FROM "configuration.option" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."key" = ?)) LIMIT 1', [0, u'matcher.mode'])
5782019-01-30 12:52:45,312 - plugin.sync.core.task.configuration (14b7103e4700) : DEBUG (plugin.sync.core.task.configuration:26) - - [matcher.mode]: 1
5792019-01-30 12:52:45,313 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."key", "t1"."value" FROM "configuration.option" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."key" = ?)) LIMIT 1', [1, u'sync.lists.liked.mode'])
5802019-01-30 12:52:45,313 - plugin.sync.core.task.configuration (14b7103e4700) : DEBUG (plugin.sync.core.task.configuration:26) - - [sync.lists.liked.mode]: None
5812019-01-30 12:52:45,314 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."key", "t1"."value" FROM "configuration.option" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."key" = ?)) LIMIT 1', [1, u'sync.collection.mode'])
5822019-01-30 12:52:45,314 - plugin.sync.core.task.configuration (14b7103e4700) : DEBUG (plugin.sync.core.task.configuration:26) - - [sync.collection.mode]: None
5832019-01-30 12:52:45,314 - plugin.sync.core.task.main (14b7103e4700) : DEBUG (plugin.sync.core.task.main:90) - Task Data: 10
5842019-01-30 12:52:45,314 - plugin.sync.core.task.main (14b7103e4700) : DEBUG (plugin.sync.core.task.main:91) - Task Media: 0
5852019-01-30 12:52:45,315 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."key", "t1"."value" FROM "configuration.option" AS t1 INNER JOIN "account" AS t2 ON ("t1"."account_id" = "t2"."id") WHERE (("t1"."account_id" = ?) AND ("t1"."key" = ?)) LIMIT 1', [0, u'matcher.mode'])
5862019-01-30 12:52:45,316 - plugin.core.database.connection (14b7103e4700) : DEBUG (plugin.core.database.connection:34) - Connected to database at '/config/Library/Application Support/Plex Media Server/Plug-in Support/Caches/com.plexapp.plugins.trakttv/trakt.db'
5872019-01-30 12:52:45,317 - pyemitter (14b7103e4700) : DEBUG (pyemitter:45) - [pyemitter.Emitter ]:on(events: ['refresh.sync.progress', 'refresh.list.progress'], func: <bound method SyncStateTrakt.on_refresh_progress of <plugin.sync.core.task.state.trakt_.SyncStateTrakt object at 0x14b6e292e390>>)
5882019-01-30 12:52:45,318 - plugin.sync.main (14b7103e4700) : DEBUG (plugin.sync.main:129) - Spawned syncing thread: <Thread(Thread-5, started 22775694292736)>
5892019-01-30 12:52:45,318 - plugin.sync.main (14b6e12af700) : INFO (plugin.sync.main:162) - (1) Started
5902019-01-30 12:52:45,319 - plugin.sync.core.task.main (14b6e12af700) : DEBUG (plugin.sync.core.task.main:74) - Constructing 5 handlers...
5912019-01-30 12:52:45,321 - plugin.sync.core.task.main (14b6e12af700) : DEBUG (plugin.sync.core.task.main:77) - Constructing 4 modes...
5922019-01-30 12:52:45,321 - plugin.sync.modes.core.base.mode (14b6e12af700) : DEBUG (plugin.sync.modes.core.base.mode:66) - Mode 4 disabled on: <plugin.sync.modes.fast_pull.lists.liked.LikedLists object at 0x14b6e28c4150>
5932019-01-30 12:52:45,322 - plugin.sync.modes.core.base.mode (14b6e12af700) : DEBUG (plugin.sync.modes.core.base.mode:66) - Mode 4 disabled on: <plugin.sync.modes.fast_pull.lists.personal.PersonalLists object at 0x14b6e28c41d0>
5942019-01-30 12:52:45,322 - plugin.sync.modes.core.base.mode (14b6e12af700) : DEBUG (plugin.sync.modes.core.base.mode:66) - Mode 4 disabled on: <plugin.sync.modes.fast_pull.lists.watchlist.Watchlist object at 0x14b6e28c4190>
5952019-01-30 12:52:45,323 - plugin.sync.modes.core.base.mode (14b6e12af700) : DEBUG (plugin.sync.modes.core.base.mode:66) - Mode 1 disabled on: <plugin.sync.modes.pull.lists.liked.LikedLists object at 0x14b6e28c4310>
5962019-01-30 12:52:45,323 - plugin.sync.modes.core.base.mode (14b6e12af700) : DEBUG (plugin.sync.modes.core.base.mode:66) - Mode 1 disabled on: <plugin.sync.modes.pull.lists.personal.PersonalLists object at 0x14b6e28c4390>
5972019-01-30 12:52:45,323 - plugin.sync.modes.core.base.mode (14b6e12af700) : DEBUG (plugin.sync.modes.core.base.mode:66) - Mode 1 disabled on: <plugin.sync.modes.pull.lists.watchlist.Watchlist object at 0x14b6e28c4350>
5982019-01-30 12:52:45,323 - plugin.sync.modes.core.base.mode (14b6e12af700) : DEBUG (plugin.sync.modes.core.base.mode:66) - Mode 2 disabled on: <plugin.sync.modes.push.movies.Movies object at 0x14b6e28c4450>
5992019-01-30 12:52:45,324 - plugin.sync.modes.core.base.mode (14b6e12af700) : DEBUG (plugin.sync.modes.core.base.mode:66) - Mode 2 disabled on: <plugin.sync.modes.push.shows.Shows object at 0x14b6e28c4490>
6002019-01-30 12:52:45,324 - plugin.sync.modes.core.base.mode (14b6e12af700) : DEBUG (plugin.sync.modes.core.base.mode:66) - Mode 2 disabled on: <plugin.sync.modes.push.Push object at 0x14b6e28c4290>
6012019-01-30 12:52:45,324 - plugin.models.m_plex.account (14b6e12af700) : DEBUG (plugin.models.m_plex.account:78) - Using basic authorization for <PlexAccount username: u'iammiak'>
6022019-01-30 12:52:45,324 - plugin.models.m_trakt.account (14b6e12af700) : DEBUG (plugin.models.m_trakt.account:88) - Using oauth authorization for <TraktAccount username: u'hermy65'>
6032019-01-30 12:52:45,325 - plugin.sync.modes.core.base.mode (14b6e12af700) : INFO (plugin.sync.modes.core.base.mode:127) - Executing construct() on child: <plugin.sync.modes.pull.movies.Movies object at 0x14b6e28c4250>
6042019-01-30 12:52:45,330 - requests.packages.urllib3.connectionpool (14b6e12af700) : DEBUG (requests.packages.urllib3.connectionpool:401) - http://127.0.0.1:32400 "GET /library/sections HTTP/1.1" 200 3932
6052019-01-30 12:52:45,332 - plex.objects.core.base (14b6e12af700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
6062019-01-30 12:52:45,333 - plugin.core.filters (14b6e12af700) : DEBUG (plugin.core.filters:67) - [filter_sections] 'documentaries' - wildcard
6072019-01-30 12:52:45,333 - plex.objects.core.base (14b6e12af700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
6082019-01-30 12:52:45,334 - plex.objects.core.base (14b6e12af700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
6092019-01-30 12:52:45,334 - plugin.core.filters (14b6e12af700) : DEBUG (plugin.core.filters:67) - [filter_sections] 'kids movies' - wildcard
6102019-01-30 12:52:45,334 - plex.objects.core.base (14b6e12af700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
6112019-01-30 12:52:45,335 - plex.objects.core.base (14b6e12af700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
6122019-01-30 12:52:45,335 - plugin.core.filters (14b6e12af700) : DEBUG (plugin.core.filters:67) - [filter_sections] 'movies' - wildcard
6132019-01-30 12:52:45,336 - plex.objects.core.base (14b6e12af700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
6142019-01-30 12:52:45,336 - plugin.core.filters (14b6e12af700) : DEBUG (plugin.core.filters:67) - [filter_sections] 'older kids movies' - wildcard
6152019-01-30 12:52:45,337 - plex.objects.core.base (14b6e12af700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
6162019-01-30 12:52:45,337 - plex.objects.core.base (14b6e12af700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
6172019-01-30 12:52:45,337 - plugin.sync.modes.core.base.mode (14b6e12af700) : INFO (plugin.sync.modes.core.base.mode:127) - Executing construct() on child: <plugin.sync.modes.pull.shows.Shows object at 0x14b6e28c42d0>
6182019-01-30 12:52:45,342 - requests.packages.urllib3.connectionpool (14b6e12af700) : DEBUG (requests.packages.urllib3.connectionpool:401) - http://127.0.0.1:32400 "GET /library/sections HTTP/1.1" 200 3932
6192019-01-30 12:52:45,344 - plex.objects.core.base (14b6e12af700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
6202019-01-30 12:52:45,344 - plex.objects.core.base (14b6e12af700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
6212019-01-30 12:52:45,345 - plugin.core.filters (14b6e12af700) : DEBUG (plugin.core.filters:67) - [filter_sections] 'heathers shows' - wildcard
6222019-01-30 12:52:45,345 - plex.objects.core.base (14b6e12af700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
6232019-01-30 12:52:45,346 - plex.objects.core.base (14b6e12af700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
6242019-01-30 12:52:45,347 - plugin.core.filters (14b6e12af700) : DEBUG (plugin.core.filters:67) - [filter_sections] 'kids tv shows' - wildcard
6252019-01-30 12:52:45,347 - plex.objects.core.base (14b6e12af700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
6262019-01-30 12:52:45,347 - plex.objects.core.base (14b6e12af700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
6272019-01-30 12:52:45,348 - plex.objects.core.base (14b6e12af700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
6282019-01-30 12:52:45,348 - plugin.core.filters (14b6e12af700) : DEBUG (plugin.core.filters:67) - [filter_sections] 'older kids tv shows' - wildcard
6292019-01-30 12:52:45,349 - plex.objects.core.base (14b6e12af700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
6302019-01-30 12:52:45,349 - plugin.core.filters (14b6e12af700) : DEBUG (plugin.core.filters:67) - [filter_sections] 'tv shows' - wildcard
6312019-01-30 12:52:45,349 - plugin.sync.modes.core.base.mode (14b6e12af700) : DEBUG (plugin.sync.modes.core.base.mode:123) - Ignoring construct() call on child: <plugin.sync.modes.pull.lists.liked.LikedLists object at 0x14b6e28c4310>
6322019-01-30 12:52:45,350 - plugin.sync.modes.core.base.mode (14b6e12af700) : DEBUG (plugin.sync.modes.core.base.mode:123) - Ignoring construct() call on child: <plugin.sync.modes.pull.lists.personal.PersonalLists object at 0x14b6e28c4390>
6332019-01-30 12:52:45,350 - plugin.sync.modes.core.base.mode (14b6e12af700) : DEBUG (plugin.sync.modes.core.base.mode:123) - Ignoring construct() call on child: <plugin.sync.modes.pull.lists.watchlist.Watchlist object at 0x14b6e28c4350>
6342019-01-30 12:52:45,350 - pyemitter (14b6e12af700) : DEBUG (pyemitter:45) - [pyemitter.Emitter ]:emit(event: 'refresh.list.started', args: (), kwargs: {'source': 'list', 'total': 0})
6352019-01-30 12:52:45,351 - pyemitter (14b6e12af700) : DEBUG (pyemitter:45) - [pyemitter.Emitter ]:emit(event: 'refresh.list.finished', args: (), kwargs: {'current': 0, 'source': 'list'})
6362019-01-30 12:52:45,351 - pyemitter (14b6e12af700) : DEBUG (pyemitter:45) - [pyemitter.Emitter ]:emit(event: 'refresh.sync.started', args: (), kwargs: {'source': 'sync', 'total': 5})
6372019-01-30 12:52:45,620 - requests.packages.urllib3.connectionpool (14b6e12af700) : DEBUG (requests.packages.urllib3.connectionpool:401) - https://api.trakt.tv:443 "GET /sync/last_activities HTTP/1.1" 200 None
6382019-01-30 12:52:45,622 - pyemitter (14b6e12af700) : DEBUG (pyemitter:45) - [pyemitter.Emitter ]:emit(event: 'refresh.sync.progress', args: (), kwargs: {'current': 0, 'source': 'sync'})
6392019-01-30 12:52:45,627 - pyemitter (14b6e12af700) : DEBUG (pyemitter:45) - [pyemitter.Emitter ]:emit(event: 'refresh.sync.progress', args: (), kwargs: {'current': 1, 'source': 'sync'})
6402019-01-30 12:52:45,628 - pyemitter (14b6e12af700) : DEBUG (pyemitter:45) - [pyemitter.Emitter ]:emit(event: 'refresh.sync.progress', args: (), kwargs: {'current': 2, 'source': 'sync'})
6412019-01-30 12:52:45,629 - trakt_sync.cache.sources.sync (14b6e12af700) : INFO (trakt_sync.cache.sources.sync:162) - Fetching "sync/playback/episodes"
6422019-01-30 12:52:46,320 - plugin.sync.main (14b7103e4700) : DEBUG (plugin.sync.main:113) - Task <plugin.sync.core.task.main.SyncTask object at 0x14b6e2900d10> has started
6432019-01-30 12:52:46,321 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:88) - Sending packed state data (108 bytes)
6442019-01-30 12:52:46,321 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:924) - Response: [200] Redirect, 0 bytes
6452019-01-30 12:52:46,322 - root (14b7103e4700) : INFO (root:840) - 200 GET /video/trakt/sync/pull?account_id=1&t=1548874363.87 (127.0.0.1) 1097.40ms
6462019-01-30 12:52:46,830 - peewee (14b6e1dba700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."session_id", "t1"."progress", "t1"."part", "t1"."rating_key", "t1"."event", "t1"."request", "t1"."queued_at" FROM "action.queue" AS t1 LIMIT 1', [])
6472019-01-30 12:52:48,151 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:717) - Handling request GET /video/trakt/sync
6482019-01-30 12:52:48,152 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:814) - Found route matching /video/trakt/sync
6492019-01-30 12:52:48,153 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."name", "t1"."thumb", "t1"."deleted", "t1"."refreshed_at" FROM "account" AS t1 WHERE ("t1"."id" = ?) LIMIT 1', [1])
6502019-01-30 12:52:48,156 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" IS ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 0, None, None])
6512019-01-30 12:52:48,235 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."result_id", "t1"."error_id", "t2"."id", "t2"."code", "t2"."type", "t2"."last_logged_at", "t2"."last_viewed_at", "t2"."exception_hash", "t2"."revision", "t2"."version_base", "t2"."version_branch", "t2"."summary", "t2"."description" FROM "sync.result.error" AS t1 LEFT OUTER JOIN "message" AS t2 ON ("t2"."id" = "t1"."error_id") WHERE ("t1"."result_id" = ?)', [34624])
6522019-01-30 12:52:48,237 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" IS ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 1, None, None])
6532019-01-30 12:52:48,254 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."result_id", "t1"."error_id", "t2"."id", "t2"."code", "t2"."type", "t2"."last_logged_at", "t2"."last_viewed_at", "t2"."exception_hash", "t2"."revision", "t2"."version_base", "t2"."version_branch", "t2"."summary", "t2"."description" FROM "sync.result.error" AS t1 LEFT OUTER JOIN "message" AS t2 ON ("t2"."id" = "t1"."error_id") WHERE ("t1"."result_id" = ?)', [34632])
6542019-01-30 12:52:48,255 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" IS ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 4, None, None])
6552019-01-30 12:52:48,273 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."result_id", "t1"."error_id", "t2"."id", "t2"."code", "t2"."type", "t2"."last_logged_at", "t2"."last_viewed_at", "t2"."exception_hash", "t2"."revision", "t2"."version_base", "t2"."version_branch", "t2"."summary", "t2"."description" FROM "sync.result.error" AS t1 LEFT OUTER JOIN "message" AS t2 ON ("t2"."id" = "t1"."error_id") WHERE ("t1"."result_id" = ?)', [34587])
6562019-01-30 12:52:48,274 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."key", "t1"."username", "t1"."title", "t1"."thumb", "t1"."refreshed_at" FROM "plex.account" AS t1 WHERE ("t1"."account_id" = ?)', [1])
6572019-01-30 12:52:48,275 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."password", "t1"."token_plex", "t1"."token_server" FROM "plex.credential.basic" AS t1 WHERE ("t1"."account_id" = ?)', [1])
6582019-01-30 12:52:48,275 - plugin.models.m_plex.account (14b7103e4700) : DEBUG (plugin.models.m_plex.account:78) - Using basic authorization for <PlexAccount username: u'iammiak'>
6592019-01-30 12:52:48,282 - requests.packages.urllib3.connectionpool (14b7103e4700) : DEBUG (requests.packages.urllib3.connectionpool:401) - http://127.0.0.1:32400 "GET /library/sections HTTP/1.1" 200 3932
6602019-01-30 12:52:48,284 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
6612019-01-30 12:52:48,286 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" = ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, u'8', None])
6622019-01-30 12:52:48,306 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
6632019-01-30 12:52:48,307 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" = ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, u'7', None])
6642019-01-30 12:52:48,325 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
6652019-01-30 12:52:48,326 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" = ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, u'3', None])
6662019-01-30 12:52:48,343 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
6672019-01-30 12:52:48,344 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" = ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, u'5', None])
6682019-01-30 12:52:48,363 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
6692019-01-30 12:52:48,364 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" = ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, u'1', None])
6702019-01-30 12:52:48,384 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
6712019-01-30 12:52:48,385 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" = ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, u'2', None])
6722019-01-30 12:52:48,405 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
6732019-01-30 12:52:48,406 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" = ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, u'6', None])
6742019-01-30 12:52:48,425 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
6752019-01-30 12:52:48,426 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" = ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, u'4', None])
6762019-01-30 12:52:48,446 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" IS ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, None, None])
6772019-01-30 12:52:48,468 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:88) - Sending packed state data (108 bytes)
6782019-01-30 12:52:48,468 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:924) - Response: [200] MediaContainer, 5930 bytes
6792019-01-30 12:52:48,469 - root (14b7103e4700) : INFO (root:840) - 200 GET /video/trakt/sync (127.0.0.1) 317.95ms
6802019-01-30 12:52:49,535 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:717) - Handling request GET /video/trakt/sync?account_id=1&refresh=1548874368.16
6812019-01-30 12:52:49,536 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:814) - Found route matching /video/trakt/sync
6822019-01-30 12:52:49,537 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."name", "t1"."thumb", "t1"."deleted", "t1"."refreshed_at" FROM "account" AS t1 WHERE ("t1"."id" = ?) LIMIT 1', [1])
6832019-01-30 12:52:49,540 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" IS ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 0, None, None])
6842019-01-30 12:52:49,620 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."result_id", "t1"."error_id", "t2"."id", "t2"."code", "t2"."type", "t2"."last_logged_at", "t2"."last_viewed_at", "t2"."exception_hash", "t2"."revision", "t2"."version_base", "t2"."version_branch", "t2"."summary", "t2"."description" FROM "sync.result.error" AS t1 LEFT OUTER JOIN "message" AS t2 ON ("t2"."id" = "t1"."error_id") WHERE ("t1"."result_id" = ?)', [34624])
6852019-01-30 12:52:49,623 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" IS ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 1, None, None])
6862019-01-30 12:52:49,642 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."result_id", "t1"."error_id", "t2"."id", "t2"."code", "t2"."type", "t2"."last_logged_at", "t2"."last_viewed_at", "t2"."exception_hash", "t2"."revision", "t2"."version_base", "t2"."version_branch", "t2"."summary", "t2"."description" FROM "sync.result.error" AS t1 LEFT OUTER JOIN "message" AS t2 ON ("t2"."id" = "t1"."error_id") WHERE ("t1"."result_id" = ?)', [34632])
6872019-01-30 12:52:49,643 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" IS ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 4, None, None])
6882019-01-30 12:52:49,660 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."result_id", "t1"."error_id", "t2"."id", "t2"."code", "t2"."type", "t2"."last_logged_at", "t2"."last_viewed_at", "t2"."exception_hash", "t2"."revision", "t2"."version_base", "t2"."version_branch", "t2"."summary", "t2"."description" FROM "sync.result.error" AS t1 LEFT OUTER JOIN "message" AS t2 ON ("t2"."id" = "t1"."error_id") WHERE ("t1"."result_id" = ?)', [34587])
6892019-01-30 12:52:49,661 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."key", "t1"."username", "t1"."title", "t1"."thumb", "t1"."refreshed_at" FROM "plex.account" AS t1 WHERE ("t1"."account_id" = ?)', [1])
6902019-01-30 12:52:49,662 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."password", "t1"."token_plex", "t1"."token_server" FROM "plex.credential.basic" AS t1 WHERE ("t1"."account_id" = ?)', [1])
6912019-01-30 12:52:49,662 - plugin.models.m_plex.account (14b7103e4700) : DEBUG (plugin.models.m_plex.account:78) - Using basic authorization for <PlexAccount username: u'iammiak'>
6922019-01-30 12:52:49,668 - requests.packages.urllib3.connectionpool (14b7103e4700) : DEBUG (requests.packages.urllib3.connectionpool:401) - http://127.0.0.1:32400 "GET /library/sections HTTP/1.1" 200 3932
6932019-01-30 12:52:49,670 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
6942019-01-30 12:52:49,671 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" = ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, u'8', None])
6952019-01-30 12:52:49,689 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
6962019-01-30 12:52:49,690 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" = ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, u'7', None])
6972019-01-30 12:52:49,708 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
6982019-01-30 12:52:49,709 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" = ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, u'3', None])
6992019-01-30 12:52:49,727 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
7002019-01-30 12:52:49,728 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" = ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, u'5', None])
7012019-01-30 12:52:49,747 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
7022019-01-30 12:52:49,748 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" = ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, u'1', None])
7032019-01-30 12:52:49,765 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
7042019-01-30 12:52:49,766 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" = ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, u'2', None])
7052019-01-30 12:52:49,784 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
7062019-01-30 12:52:49,785 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" = ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, u'6', None])
7072019-01-30 12:52:49,802 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
7082019-01-30 12:52:49,803 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" = ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, u'4', None])
7092019-01-30 12:52:49,819 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" IS ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, None, None])
7102019-01-30 12:52:49,839 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:88) - Sending packed state data (108 bytes)
7112019-01-30 12:52:49,839 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:924) - Response: [200] MediaContainer, 5930 bytes
7122019-01-30 12:52:49,839 - root (14b7103e4700) : INFO (root:840) - 200 GET /video/trakt/sync?account_id=1&refresh=1548874368.16 (127.0.0.1) 305.11ms
7132019-01-30 12:52:50,888 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:717) - Handling request GET /video/trakt/sync?account_id=1&refresh=1548874369.54
7142019-01-30 12:52:50,890 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:814) - Found route matching /video/trakt/sync
7152019-01-30 12:52:50,891 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."name", "t1"."thumb", "t1"."deleted", "t1"."refreshed_at" FROM "account" AS t1 WHERE ("t1"."id" = ?) LIMIT 1', [1])
7162019-01-30 12:52:50,893 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" IS ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 0, None, None])
7172019-01-30 12:52:50,979 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."result_id", "t1"."error_id", "t2"."id", "t2"."code", "t2"."type", "t2"."last_logged_at", "t2"."last_viewed_at", "t2"."exception_hash", "t2"."revision", "t2"."version_base", "t2"."version_branch", "t2"."summary", "t2"."description" FROM "sync.result.error" AS t1 LEFT OUTER JOIN "message" AS t2 ON ("t2"."id" = "t1"."error_id") WHERE ("t1"."result_id" = ?)', [34624])
7182019-01-30 12:52:50,981 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" IS ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 1, None, None])
7192019-01-30 12:52:51,000 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."result_id", "t1"."error_id", "t2"."id", "t2"."code", "t2"."type", "t2"."last_logged_at", "t2"."last_viewed_at", "t2"."exception_hash", "t2"."revision", "t2"."version_base", "t2"."version_branch", "t2"."summary", "t2"."description" FROM "sync.result.error" AS t1 LEFT OUTER JOIN "message" AS t2 ON ("t2"."id" = "t1"."error_id") WHERE ("t1"."result_id" = ?)', [34632])
7202019-01-30 12:52:51,002 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" IS ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 4, None, None])
7212019-01-30 12:52:51,021 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."result_id", "t1"."error_id", "t2"."id", "t2"."code", "t2"."type", "t2"."last_logged_at", "t2"."last_viewed_at", "t2"."exception_hash", "t2"."revision", "t2"."version_base", "t2"."version_branch", "t2"."summary", "t2"."description" FROM "sync.result.error" AS t1 LEFT OUTER JOIN "message" AS t2 ON ("t2"."id" = "t1"."error_id") WHERE ("t1"."result_id" = ?)', [34587])
7222019-01-30 12:52:51,022 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."key", "t1"."username", "t1"."title", "t1"."thumb", "t1"."refreshed_at" FROM "plex.account" AS t1 WHERE ("t1"."account_id" = ?)', [1])
7232019-01-30 12:52:51,022 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."password", "t1"."token_plex", "t1"."token_server" FROM "plex.credential.basic" AS t1 WHERE ("t1"."account_id" = ?)', [1])
7242019-01-30 12:52:51,023 - plugin.models.m_plex.account (14b7103e4700) : DEBUG (plugin.models.m_plex.account:78) - Using basic authorization for <PlexAccount username: u'iammiak'>
7252019-01-30 12:52:51,029 - requests.packages.urllib3.connectionpool (14b7103e4700) : DEBUG (requests.packages.urllib3.connectionpool:401) - http://127.0.0.1:32400 "GET /library/sections HTTP/1.1" 200 3932
7262019-01-30 12:52:51,031 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
7272019-01-30 12:52:51,032 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" = ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, u'8', None])
7282019-01-30 12:52:51,050 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
7292019-01-30 12:52:51,051 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" = ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, u'7', None])
7302019-01-30 12:52:51,070 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
7312019-01-30 12:52:51,071 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" = ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, u'3', None])
7322019-01-30 12:52:51,089 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
7332019-01-30 12:52:51,090 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" = ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, u'5', None])
7342019-01-30 12:52:51,109 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
7352019-01-30 12:52:51,110 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" = ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, u'1', None])
7362019-01-30 12:52:51,128 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
7372019-01-30 12:52:51,129 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" = ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, u'2', None])
7382019-01-30 12:52:51,146 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
7392019-01-30 12:52:51,147 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" = ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, u'6', None])
7402019-01-30 12:52:51,164 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
7412019-01-30 12:52:51,165 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" = ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, u'4', None])
7422019-01-30 12:52:51,184 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" IS ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, None, None])
7432019-01-30 12:52:51,203 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:88) - Sending packed state data (108 bytes)
7442019-01-30 12:52:51,203 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:924) - Response: [200] MediaContainer, 5930 bytes
7452019-01-30 12:52:51,203 - root (14b7103e4700) : INFO (root:840) - 200 GET /video/trakt/sync?account_id=1&refresh=1548874369.54 (127.0.0.1) 315.37ms
7462019-01-30 12:52:51,836 - peewee (14b6e1dba700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."session_id", "t1"."progress", "t1"."part", "t1"."rating_key", "t1"."event", "t1"."request", "t1"."queued_at" FROM "action.queue" AS t1 LIMIT 1', [])
7472019-01-30 12:52:52,423 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:717) - Handling request GET /video/trakt/sync?account_id=1&refresh=1548874370.89
7482019-01-30 12:52:52,425 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:814) - Found route matching /video/trakt/sync
7492019-01-30 12:52:52,425 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."name", "t1"."thumb", "t1"."deleted", "t1"."refreshed_at" FROM "account" AS t1 WHERE ("t1"."id" = ?) LIMIT 1', [1])
7502019-01-30 12:52:52,428 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" IS ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 0, None, None])
7512019-01-30 12:52:52,508 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."result_id", "t1"."error_id", "t2"."id", "t2"."code", "t2"."type", "t2"."last_logged_at", "t2"."last_viewed_at", "t2"."exception_hash", "t2"."revision", "t2"."version_base", "t2"."version_branch", "t2"."summary", "t2"."description" FROM "sync.result.error" AS t1 LEFT OUTER JOIN "message" AS t2 ON ("t2"."id" = "t1"."error_id") WHERE ("t1"."result_id" = ?)', [34624])
7522019-01-30 12:52:52,510 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" IS ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 1, None, None])
7532019-01-30 12:52:52,533 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."result_id", "t1"."error_id", "t2"."id", "t2"."code", "t2"."type", "t2"."last_logged_at", "t2"."last_viewed_at", "t2"."exception_hash", "t2"."revision", "t2"."version_base", "t2"."version_branch", "t2"."summary", "t2"."description" FROM "sync.result.error" AS t1 LEFT OUTER JOIN "message" AS t2 ON ("t2"."id" = "t1"."error_id") WHERE ("t1"."result_id" = ?)', [34632])
7542019-01-30 12:52:52,535 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" IS ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 4, None, None])
7552019-01-30 12:52:52,555 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."result_id", "t1"."error_id", "t2"."id", "t2"."code", "t2"."type", "t2"."last_logged_at", "t2"."last_viewed_at", "t2"."exception_hash", "t2"."revision", "t2"."version_base", "t2"."version_branch", "t2"."summary", "t2"."description" FROM "sync.result.error" AS t1 LEFT OUTER JOIN "message" AS t2 ON ("t2"."id" = "t1"."error_id") WHERE ("t1"."result_id" = ?)', [34587])
7562019-01-30 12:52:52,556 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."key", "t1"."username", "t1"."title", "t1"."thumb", "t1"."refreshed_at" FROM "plex.account" AS t1 WHERE ("t1"."account_id" = ?)', [1])
7572019-01-30 12:52:52,557 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."password", "t1"."token_plex", "t1"."token_server" FROM "plex.credential.basic" AS t1 WHERE ("t1"."account_id" = ?)', [1])
7582019-01-30 12:52:52,557 - plugin.models.m_plex.account (14b7103e4700) : DEBUG (plugin.models.m_plex.account:78) - Using basic authorization for <PlexAccount username: u'iammiak'>
7592019-01-30 12:52:52,564 - requests.packages.urllib3.connectionpool (14b7103e4700) : DEBUG (requests.packages.urllib3.connectionpool:401) - http://127.0.0.1:32400 "GET /library/sections HTTP/1.1" 200 3932
7602019-01-30 12:52:52,566 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
7612019-01-30 12:52:52,567 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" = ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, u'8', None])
7622019-01-30 12:52:52,586 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
7632019-01-30 12:52:52,587 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" = ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, u'7', None])
7642019-01-30 12:52:52,606 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
7652019-01-30 12:52:52,607 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" = ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, u'3', None])
7662019-01-30 12:52:52,626 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
7672019-01-30 12:52:52,628 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" = ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, u'5', None])
7682019-01-30 12:52:52,645 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
7692019-01-30 12:52:52,646 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" = ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, u'1', None])
7702019-01-30 12:52:52,663 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
7712019-01-30 12:52:52,664 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" = ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, u'2', None])
7722019-01-30 12:52:52,682 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
7732019-01-30 12:52:52,683 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" = ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, u'6', None])
7742019-01-30 12:52:52,701 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
7752019-01-30 12:52:52,702 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" = ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, u'4', None])
7762019-01-30 12:52:52,719 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" IS ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, None, None])
7772019-01-30 12:52:52,737 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:88) - Sending packed state data (108 bytes)
7782019-01-30 12:52:52,737 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:924) - Response: [200] MediaContainer, 5930 bytes
7792019-01-30 12:52:52,737 - root (14b7103e4700) : INFO (root:840) - 200 GET /video/trakt/sync?account_id=1&refresh=1548874370.89 (127.0.0.1) 315.35ms
7802019-01-30 12:52:53,005 - requests.packages.urllib3.connectionpool (14b6e12af700) : DEBUG (requests.packages.urllib3.connectionpool:401) - https://api.trakt.tv:443 "GET /sync/playback/episodes HTTP/1.1" 500 None
7812019-01-30 12:52:53,007 - trakt.interfaces.base (14b6e12af700) : WARNING (trakt.interfaces.base:48) - Request failed: "GET /sync/playback/episodes" - 500: "Server error" (Server Error)
7822019-01-30 12:52:53,008 - plugin.sync.main (14b6e12af700) : WARNING (plugin.sync.main:177) - Exception raised in sync task: Server Error - "Server error"
783Traceback (most recent call last):
784 File "/config/Library/Application Support/Plex Media Server/Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/plugin/sync/main.py", line 173, in run_wrapper
785 self.run()
786 File "/config/Library/Application Support/Plex Media Server/Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/plugin/sync/main.py", line 260, in run
787 'stop'
788 File "/config/Library/Application Support/Plex Media Server/Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/plugin/sync/main.py", line 277, in _trigger
789 func()
790 File "/config/Library/Application Support/Plex Media Server/Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/elapsed/main.py", line 64, in inner
791 return func(*args, **kwargs)
792 File "/config/Library/Application Support/Plex Media Server/Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/plugin/sync/modes/pull/__init__.py", line 36, in start
793 self.trakt.refresh()
794 File "/config/Library/Application Support/Plex Media Server/Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/elapsed/main.py", line 64, in inner
795 return func(*args, **kwargs)
796 File "/config/Library/Application Support/Plex Media Server/Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/plugin/sync/core/task/state/trakt_.py", line 110, in refresh
797 self.changes = list(self.changes)
798 File "/config/Library/Application Support/Plex Media Server/Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/trakt_sync/cache/main.py", line 39, in refresh
799 for result in source.refresh(username):
800 File "/config/Library/Application Support/Plex Media Server/Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/trakt_sync/cache/sources/sync.py", line 77, in refresh
801 store = self.fetch(d, m)
802 File "/config/Library/Application Support/Plex Media Server/Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/trakt_sync/cache/sources/sync.py", line 165, in fetch
803 return func(exceptions=True)
804 File "/config/Library/Application Support/Plex Media Server/Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/trakt/interfaces/base/__init__.py", line 20, in wrap
805 return func(*args, **kwargs)
806 File "/config/Library/Application Support/Plex Media Server/Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/trakt/interfaces/sync/playback.py", line 17, in episodes
807 **kwargs
808 File "/config/Library/Application Support/Plex Media Server/Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/trakt/interfaces/base/__init__.py", line 20, in wrap
809 return func(*args, **kwargs)
810 File "/config/Library/Application Support/Plex Media Server/Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/trakt/interfaces/sync/core/mixins.py", line 30, in get
811 items = self.get_data(response, **kwargs)
812 File "/config/Library/Application Support/Plex Media Server/Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/trakt/interfaces/base/__init__.py", line 81, in get_data
813 raise ServerError(response)
814ServerError: Server Error - "Server error"
8152019-01-30 12:52:53,010 - peewee (14b6e12af700) : DEBUG (peewee:99) - ('UPDATE "sync.result" SET "status_id" = ?, "trigger" = ?, "started_at" = ?, "ended_at" = ?, "success" = ? WHERE ("sync.result"."id" = ?)', [34647, 0, '2019-01-30 18:52:45.273234', '2019-01-30 18:52:53.010203', 0, 34633])
8162019-01-30 12:52:53,033 - peewee (14b6e12af700) : DEBUG (peewee:99) - ('INSERT INTO "message" ("type", "last_logged_at", "exception_hash", "revision", "version_base", "version_branch", "summary", "description") VALUES (?, ?, ?, ?, ?, ?, ?, ?)', [50, '2019-01-30 18:52:53.032973', u'0d0ac3c2f2f376fd8513de6776a19729', 0, u'1.3.3', u'master', u'Server error (code: 500)', u' File "Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/plugin/sync/main.py", line 173, in run_wrapper\n self.run()\n File "Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/plugin/sync/main.py", line 260, in run\n \'stop\'\n File "Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/plugin/sync/main.py", line 277, in _trigger\n func()\n File "Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/elapsed/main.py", line 64, in inner\n return func(*args, **kwargs)\n File "Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/plugin/sync/modes/pull/__init__.py", line 36, in start\n self.trakt.refresh()\n File "Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/elapsed/main.py", line 64, in inner\n return func(*args, **kwargs)\n File "Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/plugin/sync/core/task/state/trakt_.py", line 110, in refresh\n self.changes = list(self.changes)\n File "Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/trakt_sync/cache/main.py", line 39, in refresh\n for result in source.refresh(username):\n File "Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/trakt_sync/cache/sources/sync.py", line 77, in refresh\n store = self.fetch(d, m)\n File "Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/trakt_sync/cache/sources/sync.py", line 165, in fetch\n return func(exceptions=True)\n File "Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/trakt/interfaces/base/__init__.py", line 20, in wrap\n return func(*args, **kwargs)\n File "Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/trakt/interfaces/sync/playback.py", line 17, in episodes\n **kwargs\n File "Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/trakt/interfaces/base/__init__.py", line 20, in wrap\n return func(*args, **kwargs)\n File "Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/trakt/interfaces/sync/core/mixins.py", line 30, in get\n items = self.get_data(response, **kwargs)\n File "Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/trakt/interfaces/base/__init__.py", line 81, in get_data\n raise ServerError(response)\n'])
8172019-01-30 12:52:53,034 - plugin.managers.core.base (14b6e12af700) : DEBUG (plugin.managers.core.base:50) - or_create() - ex: IntegrityError('ConstraintError: UNIQUE constraint failed: message.type, message.exception_hash',)
8182019-01-30 12:52:53,035 - peewee (14b6e12af700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."code", "t1"."type", "t1"."last_logged_at", "t1"."last_viewed_at", "t1"."exception_hash", "t1"."revision", "t1"."version_base", "t1"."version_branch", "t1"."summary", "t1"."description" FROM "message" AS t1 WHERE (("t1"."type" = ?) AND ("t1"."exception_hash" = ?)) LIMIT 1', [50, u'0d0ac3c2f2f376fd8513de6776a19729'])
8192019-01-30 12:52:53,036 - peewee (14b6e12af700) : DEBUG (peewee:99) - ('UPDATE "message" SET "code" = ?, "type" = ?, "last_logged_at" = ?, "last_viewed_at" = ?, "exception_hash" = ?, "revision" = ?, "version_base" = ?, "version_branch" = ?, "summary" = ?, "description" = ? WHERE ("message"."id" = ?)', [None, 50, '2019-01-30 18:52:53.036055', '2019-01-30 18:49:25.414002', u'0d0ac3c2f2f376fd8513de6776a19729', 0, u'1.3.3', u'master', u'Server error (code: 500)', u' File "Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/plugin/sync/main.py", line 173, in run_wrapper\n self.run()\n File "Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/plugin/sync/main.py", line 260, in run\n \'stop\'\n File "Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/plugin/sync/main.py", line 277, in _trigger\n func()\n File "Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/elapsed/main.py", line 64, in inner\n return func(*args, **kwargs)\n File "Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/plugin/sync/modes/pull/__init__.py", line 36, in start\n self.trakt.refresh()\n File "Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/elapsed/main.py", line 64, in inner\n return func(*args, **kwargs)\n File "Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/plugin/sync/core/task/state/trakt_.py", line 110, in refresh\n self.changes = list(self.changes)\n File "Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/trakt_sync/cache/main.py", line 39, in refresh\n for result in source.refresh(username):\n File "Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/trakt_sync/cache/sources/sync.py", line 77, in refresh\n store = self.fetch(d, m)\n File "Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/trakt_sync/cache/sources/sync.py", line 165, in fetch\n return func(exceptions=True)\n File "Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/trakt/interfaces/base/__init__.py", line 20, in wrap\n return func(*args, **kwargs)\n File "Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/trakt/interfaces/sync/playback.py", line 17, in episodes\n **kwargs\n File "Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/trakt/interfaces/base/__init__.py", line 20, in wrap\n return func(*args, **kwargs)\n File "Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/trakt/interfaces/sync/core/mixins.py", line 30, in get\n items = self.get_data(response, **kwargs)\n File "Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/trakt/interfaces/base/__init__.py", line 81, in get_data\n raise ServerError(response)\n', 23])
8202019-01-30 12:52:53,056 - peewee (14b6e12af700) : DEBUG (peewee:99) - ('INSERT INTO "exception" ("error_id", "type", "message", "traceback", "hash", "timestamp", "version_base", "version_branch") VALUES (?, ?, ?, ?, ?, ?, ?, ?)', [23, u'ConnectionError', u'Server error (code: 500)', u' File "Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/plugin/sync/main.py", line 173, in run_wrapper\n self.run()\n File "Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/plugin/sync/main.py", line 260, in run\n \'stop\'\n File "Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/plugin/sync/main.py", line 277, in _trigger\n func()\n File "Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/elapsed/main.py", line 64, in inner\n return func(*args, **kwargs)\n File "Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/plugin/sync/modes/pull/__init__.py", line 36, in start\n self.trakt.refresh()\n File "Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/elapsed/main.py", line 64, in inner\n return func(*args, **kwargs)\n File "Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/plugin/sync/core/task/state/trakt_.py", line 110, in refresh\n self.changes = list(self.changes)\n File "Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/trakt_sync/cache/main.py", line 39, in refresh\n for result in source.refresh(username):\n File "Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/trakt_sync/cache/sources/sync.py", line 77, in refresh\n store = self.fetch(d, m)\n File "Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/trakt_sync/cache/sources/sync.py", line 165, in fetch\n return func(exceptions=True)\n File "Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/trakt/interfaces/base/__init__.py", line 20, in wrap\n return func(*args, **kwargs)\n File "Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/trakt/interfaces/sync/playback.py", line 17, in episodes\n **kwargs\n File "Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/trakt/interfaces/base/__init__.py", line 20, in wrap\n return func(*args, **kwargs)\n File "Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/trakt/interfaces/sync/core/mixins.py", line 30, in get\n items = self.get_data(response, **kwargs)\n File "Plug-ins/Trakttv.bundle/Contents/Libraries/Shared/trakt/interfaces/base/__init__.py", line 81, in get_data\n raise ServerError(response)\n', u'0d0ac3c2f2f376fd8513de6776a19729', '2019-01-30 18:52:53.032857', u'1.3.3', u'master'])
8212019-01-30 12:52:53,068 - peewee (14b6e12af700) : DEBUG (peewee:99) - ('INSERT INTO "sync.result.error" ("result_id", "error_id") VALUES (?, ?)', [34633, 23])
8222019-01-30 12:52:53,081 - peewee (14b6e12af700) : DEBUG (peewee:99) - ('INSERT INTO "sync.result.exception" ("result_id", "exception_id") VALUES (?, ?)', [34633, 39519])
8232019-01-30 12:52:53,087 - plugin.sync.core.task.state.main (14b6e12af700) : DEBUG (plugin.sync.core.task.state.main:24) - Flushing caches...
8242019-01-30 12:52:53,087 - plugin.sync.core.task.state.plex_ (14b6e12af700) : DEBUG (plugin.sync.core.task.state.plex_:29) - Flushing matcher cache...
8252019-01-30 12:52:53,088 - plugin.core.cache (14b6e12af700) : DEBUG (plugin.core.cache:149) - Flushed "plex.matcher" cache
8262019-01-30 12:52:53,183 - plugin.sync.core.task.state.trakt_ (14b6e12af700) : DEBUG (plugin.sync.core.task.state.trakt_:129) - [hermy65/episodes/playback ] Flushing collection...
8272019-01-30 12:52:53,183 - plugin.sync.core.task.state.trakt_ (14b6e12af700) : DEBUG (plugin.sync.core.task.state.trakt_:129) - [hermy65/movies/watched ] Flushing collection...
8282019-01-30 12:52:53,183 - plugin.sync.core.task.state.trakt_ (14b6e12af700) : DEBUG (plugin.sync.core.task.state.trakt_:129) - [hermy65/movies/playback ] Flushing collection...
8292019-01-30 12:52:53,184 - plugin.core.backup.sources.database (14b6e12af700) : INFO (plugin.core.backup.sources.database:25) - [trakt/1] Backing up database to '/config/Library/Application Support/Plex Media Server/Plug-in Support/Data/com.plexapp.plugins.trakttv/Backups/trakt/1.bgr/2019/01/30_125253_34633.db'
8302019-01-30 12:52:53,184 - plugin.core.database.connection (14b6e12af700) : DEBUG (plugin.core.database.connection:34) - Connected to database at '/config/Library/Application Support/Plex Media Server/Plug-in Support/Data/com.plexapp.plugins.trakttv/Backups/trakt/1.bgr/2019/01/30_125253_34633.db'
8312019-01-30 12:52:53,185 - plugin.core.backup.sources.database (14b6e12af700) : DEBUG (plugin.core.backup.sources.database:70) - [trakt/1] Backup Progress: 11%
8322019-01-30 12:52:53,187 - plugin.core.backup.sources.database (14b6e12af700) : DEBUG (plugin.core.backup.sources.database:70) - [trakt/1] Backup Progress: 23%
8332019-01-30 12:52:53,188 - plugin.core.backup.sources.database (14b6e12af700) : DEBUG (plugin.core.backup.sources.database:70) - [trakt/1] Backup Progress: 35%
8342019-01-30 12:52:53,189 - plugin.core.backup.sources.database (14b6e12af700) : DEBUG (plugin.core.backup.sources.database:70) - [trakt/1] Backup Progress: 47%
8352019-01-30 12:52:53,206 - plugin.core.backup.sources.database (14b6e12af700) : DEBUG (plugin.core.backup.sources.database:70) - [trakt/1] Backup Progress: 59%
8362019-01-30 12:52:53,207 - plugin.core.backup.sources.database (14b6e12af700) : DEBUG (plugin.core.backup.sources.database:70) - [trakt/1] Backup Progress: 71%
8372019-01-30 12:52:53,209 - plugin.core.backup.sources.database (14b6e12af700) : DEBUG (plugin.core.backup.sources.database:70) - [trakt/1] Backup Progress: 83%
8382019-01-30 12:52:53,210 - plugin.core.backup.sources.database (14b6e12af700) : DEBUG (plugin.core.backup.sources.database:70) - [trakt/1] Backup Progress: 95%
8392019-01-30 12:52:53,252 - plugin.core.backup.sources.database (14b6e12af700) : DEBUG (plugin.core.backup.sources.database:70) - [trakt/1] Backup Progress: 100%
8402019-01-30 12:52:53,253 - plugin.core.backup.models.revision (14b6e12af700) : DEBUG (plugin.core.backup.models.revision:94) - Writing backup revision metadata to '/config/Library/Application Support/Plex Media Server/Plug-in Support/Data/com.plexapp.plugins.trakttv/Backups/trakt/1.bgr/2019/01/30_125253_34633.bre'
8412019-01-30 12:52:53,253 - plugin.sync.main (14b6e12af700) : INFO (plugin.sync.main:222) - (1) Done
8422019-01-30 12:52:54,634 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:717) - Handling request GET /video/trakt/sync?account_id=1&refresh=1548874372.43
8432019-01-30 12:52:54,635 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:814) - Found route matching /video/trakt/sync
8442019-01-30 12:52:54,636 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."name", "t1"."thumb", "t1"."deleted", "t1"."refreshed_at" FROM "account" AS t1 WHERE ("t1"."id" = ?) LIMIT 1', [1])
8452019-01-30 12:52:54,638 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" IS ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 0, None, None])
8462019-01-30 12:52:54,721 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."result_id", "t1"."error_id", "t2"."id", "t2"."code", "t2"."type", "t2"."last_logged_at", "t2"."last_viewed_at", "t2"."exception_hash", "t2"."revision", "t2"."version_base", "t2"."version_branch", "t2"."summary", "t2"."description" FROM "sync.result.error" AS t1 LEFT OUTER JOIN "message" AS t2 ON ("t2"."id" = "t1"."error_id") WHERE ("t1"."result_id" = ?)', [34624])
8472019-01-30 12:52:54,723 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" IS ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 1, None, None])
8482019-01-30 12:52:54,741 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."result_id", "t1"."error_id", "t2"."id", "t2"."code", "t2"."type", "t2"."last_logged_at", "t2"."last_viewed_at", "t2"."exception_hash", "t2"."revision", "t2"."version_base", "t2"."version_branch", "t2"."summary", "t2"."description" FROM "sync.result.error" AS t1 LEFT OUTER JOIN "message" AS t2 ON ("t2"."id" = "t1"."error_id") WHERE ("t1"."result_id" = ?)', [34633])
8492019-01-30 12:52:54,743 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" IS ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 4, None, None])
8502019-01-30 12:52:54,761 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."result_id", "t1"."error_id", "t2"."id", "t2"."code", "t2"."type", "t2"."last_logged_at", "t2"."last_viewed_at", "t2"."exception_hash", "t2"."revision", "t2"."version_base", "t2"."version_branch", "t2"."summary", "t2"."description" FROM "sync.result.error" AS t1 LEFT OUTER JOIN "message" AS t2 ON ("t2"."id" = "t1"."error_id") WHERE ("t1"."result_id" = ?)', [34587])
8512019-01-30 12:52:54,762 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."key", "t1"."username", "t1"."title", "t1"."thumb", "t1"."refreshed_at" FROM "plex.account" AS t1 WHERE ("t1"."account_id" = ?)', [1])
8522019-01-30 12:52:54,763 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."password", "t1"."token_plex", "t1"."token_server" FROM "plex.credential.basic" AS t1 WHERE ("t1"."account_id" = ?)', [1])
8532019-01-30 12:52:54,763 - plugin.models.m_plex.account (14b7103e4700) : DEBUG (plugin.models.m_plex.account:78) - Using basic authorization for <PlexAccount username: u'iammiak'>
8542019-01-30 12:52:54,770 - requests.packages.urllib3.connectionpool (14b7103e4700) : DEBUG (requests.packages.urllib3.connectionpool:401) - http://127.0.0.1:32400 "GET /library/sections HTTP/1.1" 200 3932
8552019-01-30 12:52:54,772 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
8562019-01-30 12:52:54,773 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" = ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, u'8', None])
8572019-01-30 12:52:54,791 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
8582019-01-30 12:52:54,792 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" = ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, u'7', None])
8592019-01-30 12:52:54,809 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
8602019-01-30 12:52:54,810 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" = ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, u'3', None])
8612019-01-30 12:52:54,826 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
8622019-01-30 12:52:54,828 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" = ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, u'5', None])
8632019-01-30 12:52:54,845 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
8642019-01-30 12:52:54,846 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" = ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, u'1', None])
8652019-01-30 12:52:54,864 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
8662019-01-30 12:52:54,865 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" = ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, u'2', None])
8672019-01-30 12:52:54,883 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
8682019-01-30 12:52:54,884 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" = ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, u'6', None])
8692019-01-30 12:52:54,902 - plex.objects.core.base (14b7103e4700) : WARNING (plex.objects.core.base:156) - [Section] Omitted attribute: scannedat
8702019-01-30 12:52:54,904 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" = ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, u'4', None])
8712019-01-30 12:52:54,923 - peewee (14b7103e4700) : DEBUG (peewee:99) - ('SELECT "t1"."id", "t1"."account_id", "t1"."mode", "t1"."section", "t2"."id", "t2"."status_id", "t2"."trigger", "t2"."started_at", "t2"."ended_at", "t2"."success" FROM "sync.status" AS t1 LEFT OUTER JOIN "sync.result" AS t2 ON ("t2"."status_id" = "t1"."id") WHERE (((("t1"."account_id" = ?) AND ("t1"."mode" = ?)) AND ("t1"."section" IS ?)) AND ("t2"."success" IS NOT ?)) ORDER BY "t2"."started_at" DESC LIMIT 1', [1, 2, None, None])
8722019-01-30 12:52:54,945 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:88) - Sending packed state data (108 bytes)
8732019-01-30 12:52:54,945 - com.plexapp.plugins.trakttv (14b7103e4700) : DEBUG (com.plexapp.plugins.trakttv:924) - Response: [200] MediaContainer, 5317 bytes
8742019-01-30 12:52:54,945 - root (14b7103e4700) : INFO (root:840) - 200 GET /video/trakt/sync?account_id=1&refresh=1548874372.43 (127.0.0.1) 312.21ms
8752019-01-30 12:52:56,843 - peewee (14b6e1dba700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."session_id", "t1"."progress", "t1"."part", "t1"."rating_key", "t1"."event", "t1"."request", "t1"."queued_at" FROM "action.queue" AS t1 LIMIT 1', [])
8762019-01-30 12:53:01,848 - peewee (14b6e1dba700) : DEBUG (peewee:99) - ('SELECT "t1"."account_id", "t1"."session_id", "t1"."progress", "t1"."part", "t1"."rating_key", "t1"."event", "t1"."request", "t1"."queued_at" FROM "action.queue" AS t1 LIMIT 1', [])