2019-05-31 07:34:40,888 (2050) :  INFO (core:349) - Starting framework core - Version: 2.6.3, Build: 005053b (Mon May 27 08:39:22 UTC 2019)
2019-05-31 07:34:40,890 (2050) :  DEBUG (core:361) - Using the elevated policy
2019-05-31 07:34:40,890 (2050) :  DEBUG (core:450) - Starting runtime component.
2019-05-31 07:34:40,891 (2050) :  DEBUG (core:450) - Starting caching component.
2019-05-31 07:34:40,892 (2050) :  DEBUG (core:450) - Starting data component.
2019-05-31 07:34:40,892 (2050) :  DEBUG (core:450) - Starting networking component.
2019-05-31 07:34:40,892 (2050) :  DEBUG (networking:284) - Loaded HTTP cookies
2019-05-31 07:34:40,903 (2050) :  DEBUG (networking:452) - Setting the default network timeout to 20.0
2019-05-31 07:34:40,903 (2050) :  DEBUG (core:450) - Starting localization component.
2019-05-31 07:34:40,903 (2050) :  INFO (localization:409) - Setting the default locale to en-us
2019-05-31 07:34:40,903 (2050) :  DEBUG (core:450) - Starting messaging component.
2019-05-31 07:34:40,904 (2050) :  DEBUG (core:450) - Starting debugging component.
2019-05-31 07:34:40,905 (2050) :  DEBUG (core:450) - Starting services component.
2019-05-31 07:34:40,905 (18ec) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/system/messaging/clear_events/com.plexapp.agents.imdb'
2019-05-31 07:34:40,905 (2050) :  DEBUG (core:450) - Starting myplex component.
2019-05-31 07:34:40,907 (2050) :  DEBUG (core:450) - Starting notifications component.
2019-05-31 07:34:40,982 (2050) :  DEBUG (accessor:68) - Creating a new model access point for provider com.plexapp.agents.imdb in namespace 'metadata'
2019-05-31 07:34:40,984 (2050) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/resourceHashes'
2019-05-31 07:34:40,999 (1f70) :  DEBUG (services:265) - Plug-in is not daemonized - loading services from system
2019-05-31 07:34:40,999 (2050) :  DEBUG (runtime:1117) - Created a thread named 'load_all_services'
2019-05-31 07:34:41,002 (1f70) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_'
2019-05-31 07:34:41,002 (2050) :  DEBUG (runtime:1117) - Created a thread named 'get_server_info'
2019-05-31 07:34:41,002 (1a6c) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400'
2019-05-31 07:34:41,003 (2050) :  DEBUG (core:150) - Finished starting framework core
2019-05-31 07:34:41,003 (2050) :  DEBUG (core:560) - Loading plug-in code
2019-05-31 07:34:41,028 (1a6c) :  DEBUG (core:538) - Machine identifier is 4256a60ef14a834e594a358767f71608f4043506
2019-05-31 07:34:41,029 (1a6c) :  DEBUG (core:539) - Server version is 1.15.8.1163-005053bb5
2019-05-31 07:34:41,263 (1f70) :  DEBUG (services:362) - Loaded services
2019-05-31 07:34:41,272 (e54) :  DEBUG (services:438) - No shared code to load
2019-05-31 07:34:41,480 (2050) :  DEBUG (core:566) - Finished loading plug-in code
2019-05-31 07:34:41,561 (2050) :  DEBUG (agentkit:1115) - Creating new agent class called PlexMovieAgent
2019-05-31 07:34:41,561 (2050) :  DEBUG (agentkit:933) - Updating agent information: [{'media_types': ['Movie'], 'accepts_from': ['com.plexapp.agents.localmedia'], 'fallback_agent': None, 'contributes_to': ['com.plexapp.agents.themoviedb'], 'languages': ['en', 'sv', 'fr', 'es', 'nl', 'de', 'it', 'da', 'ar', 'ca', 'zh', 'cs', 'et', 'fi', 'el', 'he', 'hi', 'hu', 'id', 'ja', 'ko', 'lv', 'no', 'fa', 'pl', 'pt', 'ro', 'ru', 'sk', 'th', 'tr', 'uk', 'vi'], 'persist_stored_files': True, 'version': 0, 'primary_provider': True, 'prefs': True, 'name': 'Plex Movie'}]
2019-05-31 07:34:41,562 (2050) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_'
2019-05-31 07:34:41,569 (2050) :  INFO (core:611) - Started plug-in
2019-05-31 07:34:41,569 (2050) :  DEBUG (socketinterface:160) - Starting socket server
2019-05-31 07:34:41,571 (2050) :  DEBUG (runtime:1117) - Created a thread named 'start'
2019-05-31 07:34:41,572 (2050) :  INFO (socketinterface:184) - Socket server started on port 60791
2019-05-31 07:34:41,572 (2050) :  INFO (pipeinterface:25) - Entering run loop
2019-05-31 07:34:41,572 (2050) :  DEBUG (runtime:717) - Handling request GET /:/prefixes
2019-05-31 07:34:41,572 (2050) :  DEBUG (runtime:814) - Found route matching /:/prefixes
2019-05-31 07:34:41,572 (2050) :  DEBUG (runtime:924) - Response: [200] MediaContainer, 148 bytes
2019-05-31 07:34:41,582 (a88) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IxczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU3CnMxNgphNjNkZmI4YjAxZDY1OTAwczE3Cm9wZW5TdWJ0aXRsZXNIYXNoczM0CkRyIEdvbGRmb290IGFuZCB0aGUgQmlraW5pIE1hY2hpbmVzNApuYW1lczQKMTk2NXM0CnllYXJzMTY2CiU1QyU1QzE5MiUyRTE2OCUyRTQ3JTJFMjUxJTVDcGxleCU1Q01vdmllcyU1Q0RyJTJFJTIwR29sZGZvb3QlMjBhbmQlMjB0aGUlMjBCaWtpbmklMjBNYWNoaW5lJTIwKDE5NjUpJTVDRHIlMkUlMjBHb2xkZm9vdCUyMGFuZCUyMHRoZSUyMEJpa2luaSUyME1hY2hpbmUlMjAoMTk2NSklMkVtcDRzOApmaWxlbmFtZXM0MAplNWIyYTIyNGY2OTUxOGEzOTdlNzcxNTU4MDVhNjc2ZDA5YmMyYzUxczgKcGxleEhhc2hzNwo1NjMyMzYyczgKZHVyYXRpb25zNgoxNDcwODVzMgppZHIwCg__
2019-05-31 07:34:41,585 (a88) :  DEBUG (runtime:49) - Received packed state data (556 bytes)
2019-05-31 07:34:41,591 (a88) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IxczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU3CnMxNgphNjNkZmI4YjAxZDY1OTAwczE3Cm9wZW5TdWJ0aXRsZXNIYXNoczM0CkRyIEdvbGRmb290IGFuZCB0aGUgQmlraW5pIE1hY2hpbmVzNApuYW1lczQKMTk2NXM0CnllYXJzMTY2CiU1QyU1QzE5MiUyRTE2OCUyRTQ3JTJFMjUxJTVDcGxleCU1Q01vdmllcyU1Q0RyJTJFJTIwR29sZGZvb3QlMjBhbmQlMjB0aGUlMjBCaWtpbmklMjBNYWNoaW5lJTIwKDE5NjUpJTVDRHIlMkUlMjBHb2xkZm9vdCUyMGFuZCUyMHRoZSUyMEJpa2luaSUyME1hY2hpbmUlMjAoMTk2NSklMkVtcDRzOApmaWxlbmFtZXM0MAplNWIyYTIyNGY2OTUxOGEzOTdlNzcxNTU4MDVhNjc2ZDA5YmMyYzUxczgKcGxleEhhc2hzNwo1NjMyMzYyczgKZHVyYXRpb25zNgoxNDcwODVzMgppZHIwCg__
2019-05-31 07:34:41,591 (a88) :  INFO (agentkit:957) - Searching for matches for {'openSubtitlesHash': 'a63dfb8b01d65900', 'name': 'Dr Goldfoot and the Bikini Machine', 'year': '1965', 'filename': '%5C%5C192%2E168%2E47%2E251%5Cplex%5CMovies%5CDr%2E%20Goldfoot%20and%20the%20Bikini%20Machine%20(1965)%5CDr%2E%20Goldfoot%20and%20the%20Bikini%20Machine%20(1965)%2Emp4', 'plexHash': 'e5b2a224f69518a397e77155805a676d09bc2c51', 'duration': '5632362', 'id': '147085'}
2019-05-31 07:34:41,591 (a88) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/147085/tree'
2019-05-31 07:34:41,595 (a88) :  INFO (logkit:16) - checking hash search vector: https://meta.plex.tv/movie/hash/e5/e5b2a224f69518a397e77155805a676d09bc2c51.xml
2019-05-31 07:34:41,612 (a88) :  DEBUG (networking:161) - Fetching 'https://meta.plex.tv/movie/hash/e5/e5b2a224f69518a397e77155805a676d09bc2c51.xml' from the HTTP cache
2019-05-31 07:34:41,631 (a88) :  INFO (logkit:16) - Scoring {'tt0059124': [0, u'Dr. Goldfoot and the Bikini Machine', u'1965', 400, 472, 0]}
2019-05-31 07:34:41,631 (a88) :  INFO (logkit:16) - ---- HASH RESULTS MAP ----
2019-05-31 07:34:41,631 (a88) :  INFO (logkit:16) - {'tt0059124': [0, u'Dr. Goldfoot and the Bikini Machine', u'1965', 400, 472, 159]}
2019-05-31 07:34:41,632 (a88) :  DEBUG (preferences:258) - Loaded preferences from DefaultPrefs.json
2019-05-31 07:34:41,634 (a88) :  DEBUG (preferences:178) - Loaded the user preferences for com.plexapp.agents.imdb
2019-05-31 07:34:41,657 (a88) :  DEBUG (networking:161) - Fetching 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt0059124%3Fappend_to_response%3Dreleases%2Ccredits%26language%3Den' from the HTTP cache
2019-05-31 07:34:41,676 (a88) :  INFO (logkit:16) - Adding hash match: Dr. Goldfoot and the Bikini Machine (1965) score=159, key=tt0059124
2019-05-31 07:34:41,676 (a88) :  INFO (logkit:16) - Found perfect match with plex hash query.
2019-05-31 07:34:41,676 (a88) :  INFO (logkit:16) - checking title/year search vector: https://meta.plex.tv/movie/guid/0e/0e91e4bccafed875911491ec6a9de33c259bd557.xml
2019-05-31 07:34:41,694 (a88) :  DEBUG (networking:161) - Fetching 'https://meta.plex.tv/movie/guid/0e/0e91e4bccafed875911491ec6a9de33c259bd557.xml' from the HTTP cache
2019-05-31 07:34:41,713 (a88) :  INFO (logkit:16) - Scoring {'tt0059124': [0, u'Dr Goldfoot and the Bikini Machine', u'1965', 100, 3737, 0]}
2019-05-31 07:34:41,713 (a88) :  INFO (logkit:16) - ---- TITLE_YEAR RESULTS MAP ----
2019-05-31 07:34:41,713 (a88) :  INFO (logkit:16) - {'tt0059124': [0, u'Dr Goldfoot and the Bikini Machine', u'1965', 100, 3737, 100]}
2019-05-31 07:34:41,736 (a88) :  DEBUG (networking:161) - Fetching 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt0059124%3Fappend_to_response%3Dreleases%2Ccredits%26language%3Den' from the HTTP cache
2019-05-31 07:34:41,755 (a88) :  INFO (logkit:16) - Adding title_year match: Dr. Goldfoot and the Bikini Machine (1965) score=100, key=tt0059124
2019-05-31 07:34:41,755 (a88) :  INFO (logkit:16) - Found perfect match with title/year query.
2019-05-31 07:34:41,755 (a88) :  INFO (logkit:16) - ---- TMDb RESULTS MAP ----
2019-05-31 07:34:41,775 (a88) :  DEBUG (networking:161) - Fetching 'http://127.0.0.1:32400/services/tmdb?uri=%2Fsearch%2Fmovie%3Fquery%3DDr%2BGoldfoot%2Band%2Bthe%2BBikini%2BMachine%26year%3D1965%26language%3Den%26include_adult%3Dtrue' from the HTTP cache
2019-05-31 07:34:41,792 (a88) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.agents.themoviedb/messaging/function/TWVzc2FnZUtpdDpHZXRJbWRiSWQ_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMQpzNQoyNjU3M3M3CnRtZGJfaWRyMAo_'
2019-05-31 07:34:42,999 (a88) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb=None, score=90, year=1965, id=u'tt0059124', name=u'Dr. Goldfoot and the Bikini Machine')
2019-05-31 07:34:42,999 (a88) :  INFO (logkit:16) - ---- UMP RESULTS MAP ----
2019-05-31 07:34:43,016 (a88) :  DEBUG (networking:161) - Fetching 'http://127.0.0.1:32400/services/ump/matches?type=1&title=Dr%20Goldfoot%20and%20the%20Bikini%20Machine&year=1965&plexHash=e5b2a224f69518a397e77155805a676d09bc2c51&lang=en&manual=1' from the HTTP cache
2019-05-31 07:34:43,042 (a88) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/tTw2lkfF4qLQJM0VgJDPbZYNC2o.jpg', score=164, year=1965, id='tt0059124', name='Dr. Goldfoot and the Bikini Machine')
2019-05-31 07:34:43,042 (a88) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/5TRckyM3TEPQnswMwgY0yfzAqoV.jpg', score=72, year=1965, id='tt0058886', name='The Agony and the Ecstasy')
2019-05-31 07:34:43,042 (a88) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/ucuGLn2EqXUB2ZaWEY7JKtIiIMZ.jpg', score=72, year=1965, id='tt0059126', name='Dr. Who and the Daleks')
2019-05-31 07:34:43,042 (a88) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/ol9HijeS63nEWGCCrc54aQUegAP.jpg', score=72, year=1965, id='tt0059287', name='How to Stuff a Wild Bikini')
2019-05-31 07:34:43,042 (a88) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/cXmuzUai3wOiRK9asq6Tl96RJZZ.jpg', score=71, year=1966, id='tt0061014', name='Dr. Goldfoot and the Girl Bombs')
2019-05-31 07:34:43,042 (a88) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/ntBYSb9O1TWWOwv2Mh3aux9ouJH.jpg', score=60, year=1964, id='tt0057887', name='Bikini Beach')
2019-05-31 07:34:43,043 (a88) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/smbzmGrpVNiZWyHHazrCDihjloV.jpg', score=52, year=2013, id='tt2317225', name='The Machine')
2019-05-31 07:34:43,043 (a88) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/i6mUxAGj5UaUl8BgT2sG7MxUtlx.jpg', score=52, year=1981, id='tt0083021', name='Sailor Suit and Machine Gun')
2019-05-31 07:34:43,043 (a88) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/hM7SWpIuwS8arlnfUdPRaNIP5FH.jpg', score=52, year=1956, id='tt0049358', name='The Sword and the Dragon')
2019-05-31 07:34:43,043 (a88) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/Aay15LtGwU0oz2ReXnMpGzAo7iu.jpg', score=52, year=2014, id='tt3115712', name='The Heart Machine')
2019-05-31 07:34:43,043 (a88) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/wisqqJr1tBfoHugYibwOgfktgMT.jpg', score=52, year=2003, id='tt0379296', name='Game Over: Kasparov and the Machine')
2019-05-31 07:34:43,043 (a88) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/o0j6q804A4JHRXgx6XgrXRVvTj.jpg', score=52, year=1961, id='tt0054989', name='The Honeymoon Machine')
2019-05-31 07:34:43,045 (a88) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/nkh7qFp6imdnK7j6YGD1gKrdRpx.jpg', score=50, year=1979, id='tt0079902', name='The Adventures of Sherlock Holmes and Dr. Watson: Acquaintance')
2019-05-31 07:34:43,045 (a88) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/DNxtF2q9hvBfkAbsbKp6XZYXio.jpg', score=50, year=1962, id='tt0056040', name='The Awful Dr. Orlof')
2019-05-31 07:34:43,045 (a88) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/nbm4qwrIsCys0CpEJhNnHh5yIbI.jpg', score=50, year=2013, id='tt2769896', name='Louis & Luca and the Snow Machine')
2019-05-31 07:34:43,062 (a88) :  DEBUG (networking:161) - Fetching 'https://api.cinematerial.com/1/request.json?imdb_id=tt0059124&key=plex&secret=rap/F4B23dN2s&width=720&thumb_width=100.' from the HTTP cache
2019-05-31 07:34:43,096 (a88) :  DEBUG (networking:161) - Fetching 'https://api.cinematerial.com/1/request.json?imdb_id=tt0059287&key=plex&secret=rasGz5chBBYak&width=720&thumb_width=100.' from the HTTP cache
2019-05-31 07:34:43,131 (a88) :  DEBUG (networking:161) - Fetching 'https://api.cinematerial.com/1/request.json?imdb_id=tt0059126&key=plex&secret=rap/F4B23dN2s&width=720&thumb_width=100.' from the HTTP cache
2019-05-31 07:34:43,150 (a88) :  DEBUG (runtime:88) - Sending packed state data (580 bytes)
2019-05-31 07:34:43,150 (a88) :  DEBUG (runtime:924) - Response: [200] str, 6748 bytes
