Can't download subtitles

[mrlook2]

Not sure if I should post this here, as an issue. Bit kind of new into github and couldn't find any forum or something like that. Anyway, I attach the log, I can't see whats wrong but perhaps you can:

2015-09-25 08:42:27,854 (7fac7cf97740) : INFO (core:347) - Starting framework core - Version: 2.6.2, Build: 4ffb9d5 (Mon Aug 17 09:49:09 UTC 2015) 2015-09-25 08:42:27,854 (7fac7cf97740) : DEBUG (core:359) - Using the elevated policy 2015-09-25 08:42:27,854 (7fac7cf97740) : DEBUG (core:448) - Starting runtime component. 2015-09-25 08:42:27,859 (7fac7cf97740) : DEBUG (core:448) - Starting caching component. 2015-09-25 08:42:27,859 (7fac7cf97740) : DEBUG (core:448) - Starting data component. 2015-09-25 08:42:27,859 (7fac7cf97740) : DEBUG (core:448) - Starting networking component. 2015-09-25 08:42:27,864 (7fac7cf97740) : DEBUG (networking:282) - Loaded HTTP cookies 2015-09-25 08:42:27,872 (7fac7cf97740) : DEBUG (networking:450) - Setting the default network timeout to 20.0 2015-09-25 08:42:27,873 (7fac7cf97740) : DEBUG (core:448) - Starting localization component. 2015-09-25 08:42:27,873 (7fac7cf97740) : INFO (localization:408) - Setting the default locale to en-us 2015-09-25 08:42:27,882 (7fac7cf97740) : WARNING (data:179) - Error decoding with simplejson, using demjson instead (this will cause a performance hit) - Expecting property name enclosed in double quotes: line 45 column 1 (char 789) 2015-09-25 08:42:27,896 (7fac7cf97740) : DEBUG (localization:426) - Loaded en strings 2015-09-25 08:42:27,897 (7fac7cf97740) : DEBUG (core:448) - Starting messaging component. 2015-09-25 08:42:27,897 (7fac7cf97740) : DEBUG (core:448) - Starting debugging component. 2015-09-25 08:42:27,898 (7fac657fa700) : DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/system/messaging/clear_events/com.plexapp.agents.subliminal' 2015-09-25 08:42:27,898 (7fac7cf97740) : DEBUG (core:448) - Starting services component. 2015-09-25 08:42:27,899 (7fac7cf97740) : DEBUG (core:448) - Starting myplex component. 2015-09-25 08:42:27,900 (7fac7cf97740) : DEBUG (core:448) - Starting notifications component. 2015-09-25 08:42:28,014 (7fac7cf97740) : DEBUG (accessor:68) - Creating a new model access point for provider com.plexapp.agents.subliminal in namespace 'metadata' 2015-09-25 08:42:28,019 (7fac7cf97740) : DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/resourceHashes' 2015-09-25 08:42:28,035 (7fac657fa700) : DEBUG (services:265) - Plug-in is not daemonized - loading services from system 2015-09-25 08:42:28,035 (7fac7cf97740) : DEBUG (runtime:1111) - Created a thread named 'load_allservices' 2015-09-25 08:42:28,037 (7fac657fa700) : DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM/Y2VyZWFsMQoxCmxpc3QKMApyMAo/Y2VyZWFsMQoxCmRpY3QKMApyMAo' 2015-09-25 08:42:28,041 (7fac7cf97740) : DEBUG (runtime:1111) - Created a thread named 'get_server_info' 2015-09-25 08:42:28,041 (7fac47fff700) : DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400' 2015-09-25 08:42:28,041 (7fac7cf97740) : DEBUG (core:150) - Finished starting framework core 2015-09-25 08:42:28,043 (7fac7cf97740) : DEBUG (core:558) - Loading plug-in code 2015-09-25 08:42:28,106 (7fac7cf97740) : DEBUG (core:564) - Finished loading plug-in code 2015-09-25 08:42:28,165 (7fac47fff700) : DEBUG (core:536) - Machine identifier is b32aacea57ab776146aba9393b9ebaf2c404b56c 2015-09-25 08:42:28,166 (7fac47fff700) : DEBUG (core:537) - Server version is 0.9.12.11.1406-8403350 2015-09-25 08:42:28,328 (7fac657fa700) : DEBUG (services:362) - Loaded services 2015-09-25 08:42:28,332 (7fac64ff9700) : DEBUG (services:438) - No shared code to load 2015-09-25 08:42:28,575 (7fac7cf97740) : DEBUG (agentkit:1094) - Creating new agent class called SubliminalSubtitlesAgentMovies 2015-09-25 08:42:28,576 (7fac7cf97740) : DEBUG (agentkit:912) - Updating agent information: [{'media_types': ['Movie'], 'accepts_from': None, 'fallback_agent': None, 'contributes_to': ['com.plexapp.agents.imdb'], 'languages': ['en'], 'persist_stored_files': True, 'version': 0, 'primaryprovider': False, 'prefs': True, 'name': 'Subliminal Movie Subtitles'}] 2015-09-25 08:42:28,576 (7fac7cf97740) : DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo/Y2VyZWFsMQo2CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0CjIKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5zdWJsaW1pbmFsczEwCmlkZW50aWZpZXJyMQpzMTAKYWdlbnRfaW5mbzEKcjIKMTAKcjMKczExCm1lZGlhX3R5cGVzbnMxMgphY2NlcHRzX2Zyb21uczE0CmZhbGxiYWNrX2FnZW50cjQKczE0CmNvbnRyaWJ1dGVzX3RvcjUKczkKbGFuZ3VhZ2VzYjFzMjAKcGVyc2lzdF9zdG9yZWRfZmlsZXNpMApzNwp2ZXJzaW9uYjBzMTYKcHJpbWFyeV9wcm92aWRlcmIxczUKcHJlZnNzMjYKU3VibGltaW5hbCBNb3ZpZSBTdWJ0aXRsZXNzNApuYW1lMQpzNQpNb3ZpZTEKczIzCmNvbS5wbGV4YXBwLmFnZW50cy5pbWRiMQpzMgplbnIwCg' 2015-09-25 08:42:28,585 (7fac7cf97740) : DEBUG (agentkit:1094) - Creating new agent class called SubliminalSubtitlesAgentTvShows 2015-09-25 08:42:28,586 (7fac7cf97740) : DEBUG (agentkit:912) - Updating agent information: [{'media_types': ['Movie'], 'accepts_from': None, 'fallback_agent': None, 'contributes_to': ['com.plexapp.agents.imdb'], 'languages': ['en'], 'persist_stored_files': True, 'version': 0, 'primary_provider': False, 'prefs': True, 'name': 'Subliminal Movie Subtitles'}, {'media_types': ['TV_Show'], 'accepts_from': None, 'fallback_agent': None, 'contributes_to': ['com.plexapp.agents.thetvdb'], 'languages': ['en'], 'persist_stored_files': True, 'version': 0, 'primaryprovider': False, 'prefs': True, 'name': 'Subliminal TV Subtitles'}] 2015-09-25 08:42:28,587 (7fac7cf97740) : DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo/Y2VyZWFsMQoxMApkaWN0Cmxpc3QKZGljdApsaXN0Cmxpc3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0CjIKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5zdWJsaW1pbmFsczEwCmlkZW50aWZpZXJyMQpzMTAKYWdlbnRfaW5mbzIKcjIKcjYKMTAKcjMKczExCm1lZGlhX3R5cGVzbnMxMgphY2NlcHRzX2Zyb21uczE0CmZhbGxiYWNrX2FnZW50cjQKczE0CmNvbnRyaWJ1dGVzX3RvcjUKczkKbGFuZ3VhZ2VzYjFzMjAKcGVyc2lzdF9zdG9yZWRfZmlsZXNpMApzNwp2ZXJzaW9uYjBzMTYKcHJpbWFyeV9wcm92aWRlcmIxczUKcHJlZnNzMjYKU3VibGltaW5hbCBNb3ZpZSBTdWJ0aXRsZXNzNApuYW1lMQpzNQpNb3ZpZTEKczIzCmNvbS5wbGV4YXBwLmFnZW50cy5pbWRiMQpzMgplbjEwCnI3CnMxMQptZWRpYV90eXBlc25zMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI4CnMxNApjb250cmlidXRlc190b3I5CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIwczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczIzClN1YmxpbWluYWwgVFYgU3VidGl0bGVzczQKbmFtZTEKczcKVFZfU2hvdzEKczI2CmNvbS5wbGV4YXBwLmFnZW50cy50aGV0dmRiMQpzMgplbnIwCg' 2015-09-25 08:42:28,596 (7fac7cf97740) : DEBUG (init:16) - START CALLED 2015-09-25 08:42:28,596 (7fac7cf97740) : DEBUG (logger:6) - Registering LoggerHandler for dependency: subliminal 2015-09-25 08:42:28,596 (7fac7cf97740) : DEBUG (logger:6) - Registering LoggerHandler for dependency: enzyme 2015-09-25 08:42:28,597 (7fac7cf97740) : DEBUG (logger:6) - Registering LoggerHandler for dependency: guessit 2015-09-25 08:42:28,597 (7fac7cf97740) : DEBUG (logger:6) - Registering LoggerHandler for dependency: requests 2015-09-25 08:42:28,598 (7fac7cf97740) : INFO (core:609) - Started plug-in 2015-09-25 08:42:28,598 (7fac7cf97740) : DEBUG (socketinterface:160) - Starting socket server 2015-09-25 08:42:28,599 (7fac7cf97740) : DEBUG (runtime:1111) - Created a thread named 'start' 2015-09-25 08:42:28,599 (7fac7cf97740) : INFO (socketinterface:184) - Socket server started on port 33724 2015-09-25 08:42:28,599 (7fac7cf97740) : INFO (pipeinterface:25) - Entering run loop 2015-09-25 08:42:28,599 (7fac7cf97740) : DEBUG (runtime:717) - Handling request GET /:/prefixes 2015-09-25 08:42:28,601 (7fac7cf97740) : DEBUG (runtime:814) - Found route matching /:/prefixes 2015-09-25 08:42:28,601 (7fac7cf97740) : DEBUG (runtime:918) - Response: [200] MediaContainer, 154 bytes 2015-09-25 08:42:28,629 (7fac657fa700) : DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.subliminal/messaging/function/X0FnZW50S2l0OlNlYXJjaA/Y2VyZWFsMQoxCmxpc3QKMApyMAo/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCnN2czQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M3ClRWX1Nob3dzMTAKbWVkaWFfdHlwZTUKczMKMTYzczgKcGFyZW50SURzNDcKY29tLnBsZXhhcHAuYWdlbnRzLnRoZXR2ZGI6Ly8yNTUzMTYvMy8yP2xhbmc9c3ZzNApndWlkYjFzNQpmb3JjZXMyNgpjb20ucGxleGFwcC5hZ2VudHMudGhldHZkYnMxMwpwcmltYXJ5X2FnZW50czMKMTY1czIKaWRyMAo 2015-09-25 08:42:28,629 (7fac657fa700) : DEBUG (runtime:49) - Received packed state data (80 bytes) 2015-09-25 08:42:28,641 (7fac657fa700) : DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.subliminal/messaging/function/X0FnZW50S2l0OlNlYXJjaA/Y2VyZWFsMQoxCmxpc3QKMApyMAo/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCnN2czQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M3ClRWX1Nob3dzMTAKbWVkaWFfdHlwZTUKczMKMTYzczgKcGFyZW50SURzNDcKY29tLnBsZXhhcHAuYWdlbnRzLnRoZXR2ZGI6Ly8yNTUzMTYvMy8yP2xhbmc9c3ZzNApndWlkYjFzNQpmb3JjZXMyNgpjb20ucGxleGFwcC5hZ2VudHMudGhldHZkYnMxMwpwcmltYXJ5X2FnZW50czMKMTY1czIKaWRyMAo 2015-09-25 08:42:28,641 (7fac657fa700) : INFO (agentkit:936) - Searching for matches for {'id': '165', 'guid': 'com.plexapp.agents.thetvdb://255316/3/2?lang=sv', 'force': True, 'primaryagent': 'com.plexapp.agents.thetvdb', 'parentID': '163'} 2015-09-25 08:42:28,642 (7fac657fa700) : DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/165/tree' 2015-09-25 08:42:28,648 (7fac657fa700) : DEBUG (accessor:68) - Creating a new read-only model access point for provider com.plexapp.agents.thetvdb in namespace 'metadata' 2015-09-25 08:42:28,652 (7fac657fa700) : DEBUG (model:32) - Loading model with GUID com.plexapp.agents.thetvdb://255316/3/2?lang=sv 2015-09-25 08:42:28,654 (7fac657fa700) : DEBUG (model:230) - Deserializing from /home/look2/Library/Application Support/Plex Media Server/Metadata/TV Shows/3/d139bd90dac5b223a04b6b53191d8bc516d52c0.bundle/Contents/com.plexapp.agents.thetvdb/Info.xml 2015-09-25 08:42:28,713 (7fac657fa700) : DEBUG (init:142) - TV SEARCH CALLED 2015-09-25 08:42:28,714 (7fac657fa700) : DEBUG (runtime:106) - Sending packed state data (110 bytes) 2015-09-25 08:42:28,714 (7fac657fa700) : DEBUG (runtime:918) - Response: [200] str, 600 bytes 2015-09-25 08:42:28,721 (7fac657fa700) : DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.subliminal/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo/Y2VyZWFsMQoxCmRpY3QKMTAKczIKc3ZzNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzMwoxNjVzNApkYmlkaTAKczcKdmVyc2lvbnM0NQpjb20ucGxleGFwcC5hZ2VudHMudGhldHZkYjovLzI1NTMxNi8zP2xhbmc9c3ZzMTAKcGFyZW50R1VJRHMzCjE2M3M4CnBhcmVudElEczcKVFZfU2hvd3MxMAptZWRpYV90eXBlczQ3CmNvbS5wbGV4YXBwLmFnZW50cy50aGV0dmRiOi8vMjU1MzE2LzMvMj9sYW5nPXN2czQKZ3VpZHM0Cm51bGxzMgppZHIwCg 2015-09-25 08:42:28,722 (7fac657fa700) : DEBUG (runtime:49) - Received packed state data (80 bytes) 2015-09-25 08:42:28,723 (7fac657fa700) : DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.subliminal/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKc3ZzNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzMwoxNjVzNApkYmlkaTAKczcKdmVyc2lvbnM0NQpjb20ucGxleGFwcC5hZ2VudHMudGhldHZkYjovLzI1NTMxNi8zP2xhbmc9c3ZzMTAKcGFyZW50R1VJRHMzCjE2M3M4CnBhcmVudElEczcKVFZfU2hvd3MxMAptZWRpYV90eXBlczQ3CmNvbS5wbGV4YXBwLmFnZW50cy50aGV0dmRiOi8vMjU1MzE2LzMvMj9sYW5nPXN2czQKZ3VpZHM0Cm51bGxzMgppZHIwCg 2015-09-25 08:42:28,723 (7fac657fa700) : DEBUG (model:32) - Loading model with GUID com.plexapp.agents.thetvdb://255316/3/2?lang=sv 2015-09-25 08:42:28,724 (7fac657fa700) : ERROR (model:201) - Cannot read model from /home/look2/Library/Application Support/Plex Media Server/Metadata/TV Shows/3/d139bd90dac5b223a04b6b53191d8bc516d52c0.bundle/Contents/com.plexapp.agents.subliminal 2015-09-25 08:42:28,724 (7fac657fa700) : DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/165/tree' 2015-09-25 08:42:28,730 (7fac657fa700) : DEBUG (init:146) - TvUpdate. Lang sv 2015-09-25 08:42:28,748 (7fac657fa700) : WARNING (data:179) - Error decoding with simplejson, using demjson instead (this will cause a performance hit) - Expecting value: line 104 column 1 (char 4063) 2015-09-25 08:42:28,757 (7fac657fa700) : DEBUG (preferences:258) - Loaded preferences from DefaultPrefs.json 2015-09-25 08:42:28,758 (7fac657fa700) : DEBUG (preferences:178) - Loaded the user preferences for com.plexapp.agents.subliminal 2015-09-25 08:42:28,758 (7fac657fa700) : DEBUG (init:71) - Scanning video: /home/look2/fserv/film_tv/tv/Elementary/Elementary.S03E02.720p.HDTV.X264-DIMENSION.mkv, subtitles=False, embedded_subtitles=False 2015-09-25 08:42:28,758 (7fac657fa700) : INFO (logger:31) - subliminal.video: Scanning video 'Elementary.S03E02.720p.HDTV.X264-DIMENSION.mkv' in '/home/look2/fserv/film_tv/tv/Elementary' 2015-09-25 08:42:29,087 (7fac657fa700) : DEBUG (logger:21) - guessit.matcher: Property found: type=movie (None) (confidence=1.00) 2015-09-25 08:42:29,105 (7fac657fa700) : DEBUG (logger:21) - guessit.matcher: Property found: mimetype=video/x-matroska (None) (confidence=1.00) 2015-09-25 08:42:29,105 (7fac657fa700) : DEBUG (logger:21) - guessit.matcher: Property found: container=mkv (None) (confidence=1.00) 2015-09-25 08:42:29,306 (7fac657fa700) : DEBUG (logger:21) - guessit.matcher: Found match tree: 01111 22222 33333 4444444 55 6666666666 777777777777777777777777777777777777777777 888 00000 00000 00000 0000000 00 0000000000 000000000000000000000000000000000000000000 000 000000000000000000000001111222222222222222 000 00000000000000000011112 011112222222222 0111111111 /home/look2/fserv/film_tv/tv/Elementary/____..-___._ ttttttttttttttttttssss ffff vvvv rrrrrrrrr ccc /home/look2/fserv/film_tv/tv/Elementary/Elementary.S03E02.720p.HDTV.X264-DIMENSION.mkv 2015-09-25 08:42:29,307 (7fac657fa700) : DEBUG (logger:21) - guessit.guess: Updating matching property 'container' with confidence 1.00 2015-09-25 08:42:29,308 (7fac657fa700) : DEBUG (logger:21) - guessit.matchtree: Final result: { [1.00] "mimetype": "video/x-matroska", [1.00] "videoCodec": "h264", [1.00] "container": "mkv", [1.00] "format": "HDTV", [0.60] "title": "Elementary S03E02", [1.00] "releaseGroup": "DIMENSION", [1.00] "screenSize": "720p", [1.00] "type": "movie" } 2015-09-25 08:42:29,310 (7fac657fa700) : DEBUG (logger:21) - subliminal.video: Size is 878229730 2015-09-25 08:42:29,421 (7fac657fa700) : DEBUG (logger:21) - subliminal.video: Computed hashes {u'opensubtitles': u'c864259a8734d7e7', u'thesubdb': 'dc7bc77da2ba08f95ecf6f3530d20a23'} 2015-09-25 08:42:29,422 (7fac657fa700) : INFO (logger:31) - enzyme.mkv: Reading Segment element 2015-09-25 08:42:29,466 (7fac657fa700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: MasterElement EBML parsed 2015-09-25 08:42:29,466 (7fac657fa700) : INFO (logger:31) - enzyme.parsers.ebml.core: MasterElement EBML ignored 2015-09-25 08:42:29,466 (7fac657fa700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: MasterElement Segment parsed 2015-09-25 08:42:29,467 (7fac657fa700) : INFO (logger:31) - enzyme.parsers.ebml.core: Maximum level 0 reached for children of MasterElement Segment 2015-09-25 08:42:29,467 (7fac657fa700) : INFO (logger:31) - enzyme.mkv: Reading SeekHead element 2015-09-25 08:42:29,468 (7fac657fa700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: MasterElement Seek parsed 2015-09-25 08:42:29,469 (7fac657fa700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: Loading child elements for MasterElement Seek with size 12 2015-09-25 08:42:29,470 (7fac657fa700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: Element SeekID parsed 2015-09-25 08:42:29,470 (7fac657fa700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: Element SeekPosition parsed 2015-09-25 08:42:29,470 (7fac657fa700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: MasterElement Seek parsed 2015-09-25 08:42:29,470 (7fac657fa700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: Loading child elements for MasterElement Seek with size 12 2015-09-25 08:42:29,471 (7fac657fa700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: Element SeekID parsed 2015-09-25 08:42:29,471 (7fac657fa700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: Element SeekPosition parsed 2015-09-25 08:42:29,471 (7fac657fa700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: MasterElement Seek parsed 2015-09-25 08:42:29,472 (7fac657fa700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: Loading child elements for MasterElement Seek with size 14 2015-09-25 08:42:29,472 (7fac657fa700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: Element SeekID parsed 2015-09-25 08:42:29,472 (7fac657fa700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: Element SeekPosition parsed 2015-09-25 08:42:29,472 (7fac657fa700) : INFO (logger:31) - enzyme.mkv: Processing element Info from SeekHead at position 4151 2015-09-25 08:42:29,473 (7fac657fa700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: Element TimecodeScale parsed 2015-09-25 08:42:29,473 (7fac657fa700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: Element MuxingApp parsed 2015-09-25 08:42:29,473 (7fac657fa700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: Element WritingApp parsed 2015-09-25 08:42:29,474 (7fac657fa700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: Element Duration parsed 2015-09-25 08:42:29,474 (7fac657fa700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: Element DateUTC parsed 2015-09-25 08:42:29,474 (7fac657fa700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: Element SegmentUID parsed 2015-09-25 08:42:29,475 (7fac657fa700) : INFO (logger:31) - enzyme.mkv: Processing element Tracks from SeekHead at position 4316 2015-09-25 08:42:29,475 (7fac657fa700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: MasterElement TrackEntry parsed 2015-09-25 08:42:29,475 (7fac657fa700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: Loading child elements for MasterElement TrackEntry with size 114 2015-09-25 08:42:29,475 (7fac657fa700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: Element TrackNumber parsed 2015-09-25 08:42:29,476 (7fac657fa700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: Element TrackUID parsed 2015-09-25 08:42:29,476 (7fac657fa700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: Element TrackType parsed 2015-09-25 08:42:29,476 (7fac657fa700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: Element FlagLacing parsed 2015-09-25 08:42:29,476 (7fac657fa700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: Element MinCache parsed 2015-09-25 08:42:29,477 (7fac657fa700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: Element CodecID parsed 2015-09-25 08:42:29,477 (7fac657fa700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: Element CodecPrivate parsed 2015-09-25 08:42:29,477 (7fac657fa700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: Element DefaultDuration parsed 2015-09-25 08:42:29,477 (7fac657fa700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: MasterElement Video parsed 2015-09-25 08:42:29,478 (7fac657fa700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: Loading child elements for MasterElement Video with size 22 2015-09-25 08:42:29,478 (7fac657fa700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: Element PixelWidth parsed 2015-09-25 08:42:29,478 (7fac657fa700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: Element PixelHeight parsed 2015-09-25 08:42:29,479 (7fac657fa700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: Element DisplayWidth parsed 2015-09-25 08:42:29,479 (7fac657fa700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: Element DisplayHeight parsed 2015-09-25 08:42:29,479 (7fac657fa700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: MasterElement TrackEntry parsed 2015-09-25 08:42:29,479 (7fac657fa700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: Loading child elements for MasterElement TrackEntry with size 50 2015-09-25 08:42:29,480 (7fac657fa700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: Element TrackNumber parsed 2015-09-25 08:42:29,480 (7fac657fa700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: Element TrackUID parsed 2015-09-25 08:42:29,480 (7fac657fa700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: Element TrackType parsed 2015-09-25 08:42:29,480 (7fac657fa700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: Element CodecID parsed 2015-09-25 08:42:29,481 (7fac657fa700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: Element DefaultDuration parsed 2015-09-25 08:42:29,481 (7fac657fa700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: Element Language parsed 2015-09-25 08:42:29,481 (7fac657fa700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: MasterElement Audio parsed 2015-09-25 08:42:29,482 (7fac657fa700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: Loading child elements for MasterElement Audio with size 9 2015-09-25 08:42:29,482 (7fac657fa700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: Element SamplingFrequency parsed 2015-09-25 08:42:29,482 (7fac657fa700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: Element Channels parsed 2015-09-25 08:42:29,483 (7fac657fa700) : DEBUG (logger:21) - enzyme.mkv: Element Cues ignored 2015-09-25 08:42:29,483 (7fac657fa700) : DEBUG (logger:21) - subliminal.video: Found resolution 720p with enzyme 2015-09-25 08:42:29,484 (7fac657fa700) : DEBUG (logger:21) - subliminal.video: Found video_codec h264 with enzyme 2015-09-25 08:42:29,484 (7fac657fa700) : DEBUG (logger:21) - subliminal.video: Found audio_codec AC3 with enzyme 2015-09-25 08:42:29,484 (7fac657fa700) : DEBUG (logger:21) - subliminal.video: MKV has no subtitle track 2015-09-25 08:42:29,484 (7fac657fa700) : DEBUG (init:80) - Download best subtitles using settings: min_score: 20, hearing_impaired: False 2015-09-25 08:42:29,719 (7fac657fa700) : INFO (logger:31) - subliminal.api: Listing subtitles for <Movie [u'Elementary S03E02']> 2015-09-25 08:42:29,870 (7fac657fa700) : INFO (logger:31) - subliminal.providers: Listing subtitles with provider 'opensubtitles' and languages set([<Language [en]>, <Language [sv]>]) 2015-09-25 08:42:29,870 (7fac657fa700) : DEBUG (logger:21) - subliminal.providers.opensubtitles: Searching subtitles [{u'moviebytesize': '878229730', u'sublanguageid': u'eng,swe', u'moviehash': u'c864259a8734d7e7'}] 2015-09-25 08:42:30,026 (7fac657fa700) : INFO (logger:31) - subliminal.providers: Found 6 subtitles 2015-09-25 08:42:30,070 (7fac657fa700) : INFO (logger:31) - subliminal.providers: Listing subtitles with provider 'thesubdb' and languages set([<Language [en]>, <Language [sv]>]) 2015-09-25 08:42:30,070 (7fac657fa700) : DEBUG (logger:21) - subliminal.providers.thesubdb: Searching subtitles {u'action': u'search', u'hash': 'dc7bc77da2ba08f95ecf6f3530d20a23'} 2015-09-25 08:42:30,115 (7fac657fa700) : INFO (logger:31) - requests.packages.urllib3.connectionpool: Starting new HTTP connection (1): api.thesubdb.com 2015-09-25 08:42:30,411 (7fac657fa700) : DEBUG (logger:21) - requests.packages.urllib3.connectionpool: "GET /?action=search&hash=dc7bc77da2ba08f95ecf6f3530d20a23 HTTP/1.1" 200 None 2015-09-25 08:42:30,412 (7fac657fa700) : INFO (logger:31) - subliminal.providers: Found 1 subtitles 2015-09-25 08:42:30,412 (7fac657fa700) : INFO (logger:31) - subliminal.providers: Listing subtitles with provider 'podnapisi' and languages set([<Language [en]>, <Language [sv]>]) 2015-09-25 08:42:30,413 (7fac657fa700) : DEBUG (logger:21) - subliminal.providers.podnapisi: Searching episode {u'sK': u'Elementary S03E02', u'sJ': 2, u'sXML': 1} 2015-09-25 08:42:30,414 (7fac657fa700) : INFO (logger:31) - requests.packages.urllib3.connectionpool: Starting new HTTP connection (1): simple.podnapisi.net 2015-09-25 08:42:30,615 (7fac657fa700) : DEBUG (logger:21) - requests.packages.urllib3.connectionpool: "GET /ppodnapisi/search?sK=Elementary+S03E02&sJ=2&sXML=1 HTTP/1.1" 302 160 2015-09-25 08:42:30,616 (7fac657fa700) : INFO (logger:31) - requests.packages.urllib3.connectionpool: Starting new HTTP connection (1): www.podnapisi.net 2015-09-25 08:42:30,757 (7fac657fa700) : DEBUG (logger:21) - requests.packages.urllib3.connectionpool: "GET /ppodnapisi/search?sK=Elementary+S03E02&sJ=2&sXML=1 HTTP/1.1" 200 1420 2015-09-25 08:42:30,758 (7fac657fa700) : DEBUG (logger:21) - subliminal.providers.podnapisi: No subtitle found 2015-09-25 08:42:30,759 (7fac657fa700) : DEBUG (logger:21) - subliminal.providers.podnapisi: Searching episode {u'sK': u'Elementary S03E02', u'sJ': 25, u'sXML': 1} 2015-09-25 08:42:30,811 (7fac657fa700) : DEBUG (logger:21) - requests.packages.urllib3.connectionpool: "GET /ppodnapisi/search?sK=Elementary+S03E02&sJ=25&sXML=1 HTTP/1.1" 302 160 2015-09-25 08:42:30,884 (7fac657fa700) : DEBUG (logger:21) - requests.packages.urllib3.connectionpool: "GET /ppodnapisi/search?sK=Elementary+S03E02&sJ=25&sXML=1 HTTP/1.1" 200 1420 2015-09-25 08:42:30,885 (7fac657fa700) : DEBUG (logger:21) - subliminal.providers.podnapisi: No subtitle found 2015-09-25 08:42:30,885 (7fac657fa700) : INFO (logger:31) - subliminal.providers: Found 0 subtitles 2015-09-25 08:42:30,886 (7fac657fa700) : INFO (logger:31) - subliminal.providers: Skipping provider 'addic7ed': not a valid video 2015-09-25 08:42:30,886 (7fac657fa700) : INFO (logger:31) - subliminal.providers: Skipping provider 'tvsubtitles': not a valid video 2015-09-25 08:42:30,886 (7fac657fa700) : INFO (logger:31) - subliminal.api: Found 7 subtitles total 2015-09-25 08:42:30,886 (7fac657fa700) : INFO (logger:31) - subliminal.providers.opensubtitles: 'episode' is not a valid movie_kind for <Movie [u'Elementary S03E02']> 2015-09-25 08:42:30,887 (7fac657fa700) : INFO (logger:31) - subliminal.subtitle: Computed score 0 with matches set([]) 2015-09-25 08:42:30,887 (7fac657fa700) : INFO (logger:31) - subliminal.providers.opensubtitles: 'episode' is not a valid movie_kind for <Movie [u'Elementary S03E02']> 2015-09-25 08:42:30,887 (7fac657fa700) : INFO (logger:31) - subliminal.subtitle: Computed score 0 with matches set([]) 2015-09-25 08:42:30,887 (7fac657fa700) : INFO (logger:31) - subliminal.providers.opensubtitles: 'episode' is not a valid movie_kind for <Movie [u'Elementary S03E02']> 2015-09-25 08:42:30,888 (7fac657fa700) : INFO (logger:31) - subliminal.subtitle: Computed score 0 with matches set([]) 2015-09-25 08:42:30,888 (7fac657fa700) : INFO (logger:31) - subliminal.providers.opensubtitles: 'episode' is not a valid movie_kind for <Movie [u'Elementary S03E02']> 2015-09-25 08:42:30,888 (7fac657fa700) : INFO (logger:31) - subliminal.subtitle: Computed score 0 with matches set([]) 2015-09-25 08:42:30,889 (7fac657fa700) : INFO (logger:31) - subliminal.providers.opensubtitles: 'episode' is not a valid movie_kind for <Movie [u'Elementary S03E02']> 2015-09-25 08:42:30,889 (7fac657fa700) : INFO (logger:31) - subliminal.subtitle: Computed score 0 with matches set([]) 2015-09-25 08:42:30,889 (7fac657fa700) : INFO (logger:31) - subliminal.providers.opensubtitles: 'episode' is not a valid movie_kind for <Movie [u'Elementary S03E02']> 2015-09-25 08:42:30,889 (7fac657fa700) : INFO (logger:31) - subliminal.subtitle: Computed score 0 with matches set([]) 2015-09-25 08:42:30,890 (7fac657fa700) : INFO (logger:31) - subliminal.subtitle: Computed score 34 with matches set([u'hash']) 2015-09-25 08:42:30,890 (7fac657fa700) : INFO (logger:31) - subliminal.api: Downloading subtitle <TheSubDBSubtitle [en]> with score 34 2015-09-25 08:42:31,027 (7fac657fa700) : DEBUG (logger:21) - requests.packages.urllib3.connectionpool: "GET /?action=download&hash=dc7bc77da2ba08f95ecf6f3530d20a23&language=en HTTP/1.1" 200 68250 2015-09-25 08:42:31,314 (7fac657fa700) : INFO (logger:31) - subliminal.api: No subtitle with score >= 20 2015-09-25 08:42:31,595 (7fac657fa700) : DEBUG (init:88) - Saving subtitles as metadata 2015-09-25 08:42:31,596 (7fac657fa700) : CRITICAL (agentkit:1057) - Exception in the update function of agent named 'Subliminal TV Subtitles', called with guid 'com.plexapp.agents.thetvdb://255316/3/2?lang=sv' (most recent call last): File "bundles-release/Framework.bundle-dist/Contents/Resources/Versions/2/Python/Framework/api/agentkit.py", line 1055, in _update File "/home/look2/Library/Application Support/Plex Media Server/Plug-ins/Subliminal.bundle/Contents/Code/init__.py", line 149, in update saveSubtitles(videos, subtitles) File "/home/look2/Library/Application Support/Plex Media Server/Plug-ins/Subliminal.bundle/Contents/Code/init.py", line 89, in saveSubtitles saveSubtitlesToMetadata(videos, subtitles) File "/home/look2/Library/Application Support/Plex Media Server/Plug-ins/Subliminal.bundle/Contents/Code/init.py", line 116, in saveSubtitlesToMetadata mediaPart.subtitles[Locale.Language.Match(subtitle.language.alpha2)][subtitle.page_link] = Proxy.Media(subtitle.content, ext="srt") File "bundles-release/Framework.bundle-dist/Contents/Resources/Versions/2/Python/Framework/api/agentkit.py", line 237, in setitem File "bundles-release/Framework.bundle-dist/Contents/Resources/Versions/2/Python/Framework/api/agentkit.py", line 220, in make_tuple File "bundles-release/Framework.bundle-dist/Contents/Resources/Versions/2/Python/Framework/components/data.py", line 32, in sha1 File "bundles-release/Framework.bundle-dist/Contents/Resources/Versions/2/Python/Framework/components/data.py", line 22, in _generateHash TypeError: must be string or buffer, not None

2015-09-25 08:42:31,598 (7fac657fa700) : DEBUG (model:225) - Serializing to /home/look2/Library/Application Support/Plex Media Server/Metadata/TV Shows/3/d139bd90dac5b223a04b6b53191d8bc516d52c0.bundle/Contents/com.plexapp.agents.subliminal/Info.xml 2015-09-25 08:42:31,599 (7fac657fa700) : DEBUG (runtime:106) - Sending packed state data (110 bytes) 2015-09-25 08:42:31,599 (7fac657fa700) : DEBUG (runtime:918) - Response: [200] str, 16 bytes

[pannal]

Which version of subliminal.bundle are you using?

[pannal]

No answer yet, please reopen.

[mrlook2]

Sorry about late reply. The notification mail was classes as spam.

I'm using beta 5. Or you need some other version number?

[pannal]

Please update to RC2.

Also: could you provide screenshots of your agent lists aswell as the configuration screen of your library?

[mrlook2]

I have uppdated. Downloaded the latest zip from the site. But still no luck.

[pannal]

Please disable Local Media Assets, move Local Media Assets Extended above TheTVDB and subliminal above that.

And please try adding a new library temporarily to see if that fixes it.

[mrlook2]

Still no luck. I have checked that the subtile exist in open subtitles and I'm not banned.

[pannal]

It seems to detect the episode as a movie. Could you create a subfolder named "Season 03" and put the file into it? Also please make a screenshot of your library's settings.

[mrlook2]

Moved the episodes to a "season 3" folder. Still no subtitles.

[pannal]

Please name your folders exactly as the Plex doc states: https://support.plex.tv/hc/en-us/articles/200220687-Naming-Series-Season-Based-TV-Shows "Season 03", not "season 3".

And again, take a screenshot of your Library config (Home -> hover over your TV library, press the little pen, take a screenshot of all three tabs (General, Add Folders, Advanced).

[mrlook2]

Renamed the folder. Attaching screenshots.

[pannal]

Set the folder of the "test" library to "/home/look2/fserv/film_tv/tv/" and rename "Season 3" to "Season 03". Which version is your Plex Media Server? (Settings -> Server -> Version)

[mrlook2]

I did specify the test library to just season 03 of elementary just so I don't have to scan the entire tv-show folder. I'm using Version 0.9.12.1

[pannal]

Well that won't work, though :) You can simply create a new base folder, something like "/home/look2/fserv/film_tv/tv_test/", create "Elementary/Season 03" in there and copy the media file there.

Then set the path of the test library to "/home/look2/fserv/film_tv/tv_test/".

And you should consider updating to 0.9.12.13, your version is over 5 months old.

[mrlook2]

Updated to 0.9.12.13 Made the file structure you suggested, and guess what..... still no luck :(

[pannal]

Can you show me your current subliminal log?

[mrlook2]

It didn't work to attach the file, so I paste the content of it below:

2015-10-09 14:25:00,173 (7f9fc2c46740) : INFO (core:347) - Starting framework core - Version: 2.6.2, Build: 4ffb9d5 (Mon Aug 17 09:49:09 UTC 2015) 2015-10-09 14:25:00,174 (7f9fc2c46740) : DEBUG (core:359) - Using the elevated policy 2015-10-09 14:25:00,174 (7f9fc2c46740) : DEBUG (core:448) - Starting runtime component. 2015-10-09 14:25:00,176 (7f9fc2c46740) : DEBUG (core:448) - Starting caching component. 2015-10-09 14:25:00,176 (7f9fc2c46740) : DEBUG (core:448) - Starting data component. 2015-10-09 14:25:00,176 (7f9fc2c46740) : DEBUG (core:448) - Starting networking component. 2015-10-09 14:25:00,177 (7f9fc2c46740) : DEBUG (networking:282) - Loaded HTTP cookies 2015-10-09 14:25:00,177 (7f9fc2c46740) : DEBUG (networking:450) - Setting the default network timeout to 20.0 2015-10-09 14:25:00,178 (7f9fc2c46740) : DEBUG (core:448) - Starting localization component. 2015-10-09 14:25:00,178 (7f9fc2c46740) : INFO (localization:408) - Setting the default locale to en-us 2015-10-09 14:25:00,178 (7f9fc2c46740) : WARNING (data:179) - Error decoding with simplejson, using demjson instead (this will cause a performance hit) - Expecting property name enclosed in double quotes: line 47 column 1 (char 848) 2015-10-09 14:25:00,181 (7f9fc2c46740) : DEBUG (localization:426) - Loaded en strings 2015-10-09 14:25:00,181 (7f9fc2c46740) : DEBUG (core:448) - Starting messaging component. 2015-10-09 14:25:00,181 (7f9fc2c46740) : DEBUG (core:448) - Starting debugging component. 2015-10-09 14:25:00,182 (7f9f9b7fe700) : DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/system/messaging/clear_events/com.plexapp.agents.subliminal' 2015-10-09 14:25:00,182 (7f9fc2c46740) : DEBUG (core:448) - Starting services component. 2015-10-09 14:25:00,183 (7f9fc2c46740) : DEBUG (core:448) - Starting myplex component. 2015-10-09 14:25:00,184 (7f9fc2c46740) : DEBUG (core:448) - Starting notifications component. 2015-10-09 14:25:00,322 (7f9fc2c46740) : DEBUG (accessor:68) - Creating a new model access point for provider com.plexapp.agents.subliminal in namespace 'metadata' 2015-10-09 14:25:00,327 (7f9fc2c46740) : DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/resourceHashes' 2015-10-09 14:25:00,342 (7f9f9b7fe700) : DEBUG (services:265) - Plug-in is not daemonized - loading services from system 2015-10-09 14:25:00,342 (7f9fc2c46740) : DEBUG (runtime:1111) - Created a thread named 'load_allservices' 2015-10-09 14:25:00,343 (7f9f9b7fe700) : DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM/Y2VyZWFsMQoxCmxpc3QKMApyMAo/Y2VyZWFsMQoxCmRpY3QKMApyMAo' 2015-10-09 14:25:00,344 (7f9fc2c46740) : DEBUG (runtime:1111) - Created a thread named 'get_server_info' 2015-10-09 14:25:00,344 (7f9f9a7fc700) : DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400' 2015-10-09 14:25:00,345 (7f9fc2c46740) : DEBUG (core:150) - Finished starting framework core 2015-10-09 14:25:00,347 (7f9fc2c46740) : DEBUG (core:558) - Loading plug-in code 2015-10-09 14:25:00,422 (7f9fc2c46740) : DEBUG (core:564) - Finished loading plug-in code 2015-10-09 14:25:00,450 (7f9f9a7fc700) : DEBUG (core:536) - Machine identifier is b32aacea57ab776146aba9393b9ebaf2c404b56c 2015-10-09 14:25:00,451 (7f9f9a7fc700) : DEBUG (core:537) - Server version is 0.9.12.13.1464-4ccd2ca 2015-10-09 14:25:00,753 (7f9f9b7fe700) : DEBUG (services:362) - Loaded services 2015-10-09 14:25:00,759 (7f9f9affd700) : DEBUG (services:438) - No shared code to load 2015-10-09 14:25:00,911 (7f9fc2c46740) : DEBUG (agentkit:1094) - Creating new agent class called SubliminalSubtitlesAgentMovies 2015-10-09 14:25:00,911 (7f9fc2c46740) : DEBUG (agentkit:912) - Updating agent information: [{'media_types': ['Movie'], 'accepts_from': None, 'fallback_agent': None, 'contributes_to': ['com.plexapp.agents.imdb'], 'languages': ['en'], 'persist_stored_files': True, 'version': 0, 'primaryprovider': False, 'prefs': True, 'name': 'Subliminal Movie Subtitles'}] 2015-10-09 14:25:00,912 (7f9fc2c46740) : DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo/Y2VyZWFsMQo2CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0CjIKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5zdWJsaW1pbmFsczEwCmlkZW50aWZpZXJyMQpzMTAKYWdlbnRfaW5mbzEKcjIKMTAKcjMKczExCm1lZGlhX3R5cGVzbnMxMgphY2NlcHRzX2Zyb21uczE0CmZhbGxiYWNrX2FnZW50cjQKczE0CmNvbnRyaWJ1dGVzX3RvcjUKczkKbGFuZ3VhZ2VzYjFzMjAKcGVyc2lzdF9zdG9yZWRfZmlsZXNpMApzNwp2ZXJzaW9uYjBzMTYKcHJpbWFyeV9wcm92aWRlcmIxczUKcHJlZnNzMjYKU3VibGltaW5hbCBNb3ZpZSBTdWJ0aXRsZXNzNApuYW1lMQpzNQpNb3ZpZTEKczIzCmNvbS5wbGV4YXBwLmFnZW50cy5pbWRiMQpzMgplbnIwCg' 2015-10-09 14:25:00,920 (7f9fc2c46740) : DEBUG (agentkit:1094) - Creating new agent class called SubliminalSubtitlesAgentTvShows 2015-10-09 14:25:00,920 (7f9fc2c46740) : DEBUG (agentkit:912) - Updating agent information: [{'media_types': ['Movie'], 'accepts_from': None, 'fallback_agent': None, 'contributes_to': ['com.plexapp.agents.imdb'], 'languages': ['en'], 'persist_stored_files': True, 'version': 0, 'primary_provider': False, 'prefs': True, 'name': 'Subliminal Movie Subtitles'}, {'media_types': ['TV_Show'], 'accepts_from': None, 'fallback_agent': None, 'contributes_to': ['com.plexapp.agents.thetvdb', 'com.plexapp.agents.thetvdbdvdorder'], 'languages': ['en'], 'persist_stored_files': True, 'version': 0, 'primaryprovider': False, 'prefs': True, 'name': 'Subliminal TV Subtitles'}] 2015-10-09 14:25:00,921 (7f9fc2c46740) : DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo/Y2VyZWFsMQoxMApkaWN0Cmxpc3QKZGljdApsaXN0Cmxpc3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0CjIKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5zdWJsaW1pbmFsczEwCmlkZW50aWZpZXJyMQpzMTAKYWdlbnRfaW5mbzIKcjIKcjYKMTAKcjMKczExCm1lZGlhX3R5cGVzbnMxMgphY2NlcHRzX2Zyb21uczE0CmZhbGxiYWNrX2FnZW50cjQKczE0CmNvbnRyaWJ1dGVzX3RvcjUKczkKbGFuZ3VhZ2VzYjFzMjAKcGVyc2lzdF9zdG9yZWRfZmlsZXNpMApzNwp2ZXJzaW9uYjBzMTYKcHJpbWFyeV9wcm92aWRlcmIxczUKcHJlZnNzMjYKU3VibGltaW5hbCBNb3ZpZSBTdWJ0aXRsZXNzNApuYW1lMQpzNQpNb3ZpZTEKczIzCmNvbS5wbGV4YXBwLmFnZW50cy5pbWRiMQpzMgplbjEwCnI3CnMxMQptZWRpYV90eXBlc25zMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI4CnMxNApjb250cmlidXRlc190b3I5CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIwczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczIzClN1YmxpbWluYWwgVFYgU3VidGl0bGVzczQKbmFtZTEKczcKVFZfU2hvdzIKczI2CmNvbS5wbGV4YXBwLmFnZW50cy50aGV0dmRiczM0CmNvbS5wbGV4YXBwLmFnZW50cy50aGV0dmRiZHZkb3JkZXIxCnMyCmVucjAK' 2015-10-09 14:25:00,929 (7f9fc2c46740) : DEBUG (init:17) - START CALLED 2015-10-09 14:25:00,929 (7f9fc2c46740) : DEBUG (logger:6) - Registering LoggerHandler for dependency: subliminal 2015-10-09 14:25:00,930 (7f9fc2c46740) : DEBUG (logger:6) - Registering LoggerHandler for dependency: subliminal_patch 2015-10-09 14:25:00,930 (7f9fc2c46740) : DEBUG (logger:6) - Registering LoggerHandler for dependency: enzyme 2015-10-09 14:25:00,930 (7f9fc2c46740) : DEBUG (logger:6) - Registering LoggerHandler for dependency: guessit 2015-10-09 14:25:00,930 (7f9fc2c46740) : DEBUG (logger:6) - Registering LoggerHandler for dependency: requests 2015-10-09 14:25:00,931 (7f9fc2c46740) : INFO (core:609) - Started plug-in 2015-10-09 14:25:00,931 (7f9fc2c46740) : DEBUG (socketinterface:160) - Starting socket server 2015-10-09 14:25:00,932 (7f9fc2c46740) : DEBUG (runtime:1111) - Created a thread named 'start' 2015-10-09 14:25:00,932 (7f9fc2c46740) : INFO (socketinterface:184) - Socket server started on port 48002 2015-10-09 14:25:00,932 (7f9fc2c46740) : INFO (pipeinterface:25) - Entering run loop 2015-10-09 14:25:00,932 (7f9fc2c46740) : DEBUG (runtime:717) - Handling request GET /:/prefixes 2015-10-09 14:25:00,934 (7f9fc2c46740) : DEBUG (runtime:814) - Found route matching /:/prefixes 2015-10-09 14:25:00,934 (7f9fc2c46740) : DEBUG (runtime:918) - Response: [200] MediaContainer, 154 bytes 2015-10-09 14:25:00,936 (7f9f9b7fe700) : DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.subliminal/messaging/function/X0FnZW50S2l0OlNlYXJjaA_/Y2VyZWFsMQoxCmxpc3QKMApyMAo/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M3ClRWX1Nob3dzMTAKbWVkaWFfdHlwZTUKczQKMTkwMnM4CnBhcmVudElEczQ3CmNvbS5wbGV4YXBwLmFnZW50cy50aGV0dmRiOi8vMjU1MzE2LzMvMT9sYW5nPWVuczQKZ3VpZGIxczUKZm9yY2VzMjYKY29tLnBsZXhhcHAuYWdlbnRzLnRoZXR2ZGJzMTMKcHJpbWFyeV9hZ2VudHM0CjE5MDNzMgppZHIwCg 2015-10-09 14:25:00,937 (7f9f9b7fe700) : DEBUG (runtime:49) - Received packed state data (80 bytes) 2015-10-09 14:25:00,948 (7f9f9b7fe700) : DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.subliminal/messaging/function/X0FnZW50S2l0OlNlYXJjaA/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M3ClRWX1Nob3dzMTAKbWVkaWFfdHlwZTUKczQKMTkwMnM4CnBhcmVudElEczQ3CmNvbS5wbGV4YXBwLmFnZW50cy50aGV0dmRiOi8vMjU1MzE2LzMvMT9sYW5nPWVuczQKZ3VpZGIxczUKZm9yY2VzMjYKY29tLnBsZXhhcHAuYWdlbnRzLnRoZXR2ZGJzMTMKcHJpbWFyeV9hZ2VudHM0CjE5MDNzMgppZHIwCg__ 2015-10-09 14:25:00,948 (7f9f9b7fe700) : INFO (agentkit:936) - Searching for matches for {'id': '1903', 'guid': 'com.plexapp.agents.thetvdb://255316/3/1?lang=en', 'force': True, 'primaryagent': 'com.plexapp.agents.thetvdb', 'parentID': '1902'} 2015-10-09 14:25:00,978 (7f9f9b7fe700) : DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/1903/tree' 2015-10-09 14:25:00,984 (7f9f9b7fe700) : DEBUG (accessor:68) - Creating a new read-only model access point for provider com.plexapp.agents.thetvdb in namespace 'metadata' 2015-10-09 14:25:00,987 (7f9f9b7fe700) : DEBUG (model:32) - Loading model with GUID com.plexapp.agents.thetvdb://255316/3/1?lang=en 2015-10-09 14:25:00,989 (7f9f9b7fe700) : DEBUG (model:230) - Deserializing from /home/look2/Library/Application Support/Plex Media Server/Metadata/TV Shows/2/aad8bb433160d2b3a2b2aa3d0e65be1630cd8b5.bundle/Contents/com.plexapp.agents.thetvdb/Info.xml 2015-10-09 14:25:01,002 (7f9f9b7fe700) : DEBUG (init:181) - TV SEARCH CALLED 2015-10-09 14:25:01,004 (7f9f9b7fe700) : DEBUG (runtime:106) - Sending packed state data (110 bytes) 2015-10-09 14:25:01,004 (7f9f9b7fe700) : DEBUG (runtime:918) - Response: [200] str, 600 bytes 2015-10-09 14:25:01,010 (7f9f9b7fe700) : DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.subliminal/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNAoxOTAzczQKZGJpZGkwCnM3CnZlcnNpb25zNDUKY29tLnBsZXhhcHAuYWdlbnRzLnRoZXR2ZGI6Ly8yNTUzMTYvMz9sYW5nPWVuczEwCnBhcmVudEdVSURzNAoxOTAyczgKcGFyZW50SURzNwpUVl9TaG93czEwCm1lZGlhX3R5cGVzNDcKY29tLnBsZXhhcHAuYWdlbnRzLnRoZXR2ZGI6Ly8yNTUzMTYvMy8xP2xhbmc9ZW5zNApndWlkczQKbnVsbHMyCmlkcjAK 2015-10-09 14:25:01,010 (7f9f9b7fe700) : DEBUG (runtime:49) - Received packed state data (80 bytes) 2015-10-09 14:25:01,011 (7f9f9b7fe700) : DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.subliminal/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNAoxOTAzczQKZGJpZGkwCnM3CnZlcnNpb25zNDUKY29tLnBsZXhhcHAuYWdlbnRzLnRoZXR2ZGI6Ly8yNTUzMTYvMz9sYW5nPWVuczEwCnBhcmVudEdVSURzNAoxOTAyczgKcGFyZW50SURzNwpUVl9TaG93czEwCm1lZGlhX3R5cGVzNDcKY29tLnBsZXhhcHAuYWdlbnRzLnRoZXR2ZGI6Ly8yNTUzMTYvMy8xP2xhbmc9ZW5zNApndWlkczQKbnVsbHMyCmlkcjAK 2015-10-09 14:25:01,012 (7f9f9b7fe700) : DEBUG (model:32) - Loading model with GUID com.plexapp.agents.thetvdb://255316/3/1?lang=en 2015-10-09 14:25:01,012 (7f9f9b7fe700) : DEBUG (model:230) - Deserializing from /home/look2/Library/Application Support/Plex Media Server/Metadata/TV Shows/2/aad8bb433160d2b3a2b2aa3d0e65be1630cd8b5.bundle/Contents/com.plexapp.agents.subliminal/Info.xml 2015-10-09 14:25:01,021 (7f9f9b7fe700) : DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/1903/tree' 2015-10-09 14:25:01,026 (7f9f9b7fe700) : DEBUG (init:185) - TvUpdate. Lang en 2015-10-09 14:25:01,027 (7f9f9b7fe700) : WARNING (data:179) - Error decoding with simplejson, using demjson instead (this will cause a performance hit) - Expecting value: line 130 column 1 (char 5483) 2015-10-09 14:25:01,039 (7f9f9b7fe700) : DEBUG (preferences:258) - Loaded preferences from DefaultPrefs.json 2015-10-09 14:25:01,040 (7f9f9b7fe700) : DEBUG (preferences:178) - Loaded the user preferences for com.plexapp.agents.subliminal 2015-10-09 14:25:01,040 (7f9f9b7fe700) : DEBUG (init:92) - Scanning video: /home/look2/fserv/film_tv/tv/test/Elementary/Season 03/Elementary.S03E01.720p.HDTV.X264-DIMENSION.mkv, subtitles=False, embedded_subtitles=False 2015-10-09 14:25:01,042 (7f9f9b7fe700) : INFO (logger:31) - subliminal.video: Scanning video 'Elementary.S03E01.720p.HDTV.X264-DIMENSION.mkv' in '/home/look2/fserv/film_tv/tv/test/Elementary/Season 03' 2015-10-09 14:25:01,044 (7f9f9b7fe700) : DEBUG (logger:21) - guessit.matcher: Property found: type=movie (None) (confidence=1.00) 2015-10-09 14:25:01,047 (7f9f9b7fe700) : DEBUG (logger:21) - guessit.matcher: Property found: mimetype=video/x-matroska (None) (confidence=1.00) 2015-10-09 14:25:01,048 (7f9f9b7fe700) : DEBUG (logger:21) - guessit.matcher: Property found: container=mkv (None) (confidence=1.00) 2015-10-09 14:25:01,146 (7f9f9b7fe700) : DEBUG (logger:21) - guessit.matcher: Found match tree: 01111 22222 33333 4444444 55 6666 7777777777 888888888 999999999999999999999999999999999999999999 AAA 0000 00000 00000 0000000 00 0000 0000000000 000000000 000000000000000000000000000000000000000000 000 000000000000000000000001111222222222222222 000 00000000000000000011112 011112222222222 0111111111 /home/look2/fserv/film_tv/tv/test/Elementary/Season 03/____..-___._ ttttttttttttttttttssss ffff vvvv rrrrrrrrr ccc /home/look2/fserv/film_tv/tv/test/Elementary/Season 03/Elementary.S03E01.720p.HDTV.X264-DIMENSION.mkv 2015-10-09 14:25:01,147 (7f9f9b7fe700) : DEBUG (logger:21) - guessit.guess: Updating matching property 'container' with confidence 1.00 2015-10-09 14:25:01,147 (7f9f9b7fe700) : DEBUG (logger:21) - guessit.matchtree: Final result: { [1.00] "mimetype": "video/x-matroska", [0.60] "videoCodec": "h264", [1.00] "container": "mkv", [0.60] "format": "HDTV", [0.36] "title": "Elementary S03E01", [0.60] "releaseGroup": "DIMENSION", [0.60] "screenSize": "720p", [1.00] "type": "movie" } 2015-10-09 14:25:01,148 (7f9f9b7fe700) : DEBUG (logger:21) - subliminal.video: Size is 969849251 2015-10-09 14:25:01,171 (7f9f9b7fe700) : DEBUG (logger:21) - subliminal.video: Computed hashes {u'opensubtitles': u'9a50a90f696867ed', u'thesubdb': '7861c3e968a25a390c329b13d5876cc5'} 2015-10-09 14:25:01,172 (7f9f9b7fe700) : INFO (logger:31) - enzyme.mkv: Reading Segment element 2015-10-09 14:25:01,194 (7f9f9b7fe700) : INFO (logger:31) - enzyme.parsers.ebml.core: MasterElement EBML 6 ignored 2015-10-09 14:25:01,194 (7f9f9b7fe700) : INFO (logger:31) - enzyme.parsers.ebml.core: Maximum level 0 reached for children of MasterElement Segment 2015-10-09 14:25:01,195 (7f9f9b7fe700) : INFO (logger:31) - enzyme.mkv: Reading SeekHead element 2015-10-09 14:25:01,195 (7f9f9b7fe700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: Loading child elements for MasterElement Seek with size 12 2015-10-09 14:25:01,195 (7f9f9b7fe700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: Loading child elements for MasterElement Seek with size 12 2015-10-09 14:25:01,196 (7f9f9b7fe700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: Loading child elements for MasterElement Seek with size 14 2015-10-09 14:25:01,196 (7f9f9b7fe700) : INFO (logger:31) - enzyme.mkv: Processing element Info from SeekHead at position 4151 2015-10-09 14:25:01,196 (7f9f9b7fe700) : INFO (logger:31) - enzyme.mkv: Processing element Tracks from SeekHead at position 4316 2015-10-09 14:25:01,197 (7f9f9b7fe700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: Loading child elements for MasterElement TrackEntry with size 114 2015-10-09 14:25:01,197 (7f9f9b7fe700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: Loading child elements for MasterElement Video with size 22 2015-10-09 14:25:01,198 (7f9f9b7fe700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: Loading child elements for MasterElement TrackEntry with size 50 2015-10-09 14:25:01,198 (7f9f9b7fe700) : DEBUG (logger:21) - enzyme.parsers.ebml.core: Loading child elements for MasterElement Audio with size 9 2015-10-09 14:25:01,198 (7f9f9b7fe700) : DEBUG (logger:21) - enzyme.mkv: Element Cues ignored 2015-10-09 14:25:01,199 (7f9f9b7fe700) : DEBUG (logger:21) - subliminal.video: Found resolution 720p with enzyme 2015-10-09 14:25:01,199 (7f9f9b7fe700) : DEBUG (logger:21) - subliminal.video: Found video_codec h264 with enzyme 2015-10-09 14:25:01,199 (7f9f9b7fe700) : DEBUG (logger:21) - subliminal.video: Found audio_codec AC3 with enzyme 2015-10-09 14:25:01,200 (7f9f9b7fe700) : DEBUG (logger:21) - subliminal.video: MKV has no subtitle track 2015-10-09 14:25:01,200 (7f9f9b7fe700) : DEBUG (init:113) - Download best subtitles using settings: min_score: 15, hearing_impaired: False 2015-10-09 14:25:01,201 (7f9f9b7fe700) : INFO (logger:31) - subliminal.api: Downloading best subtitles for <Movie [u'Elementary S03E01']> 2015-10-09 14:25:01,216 (7f9f9b7fe700) : INFO (logger:31) - subliminal_patch.patch_provider_pool: Listing subtitles with provider 'opensubtitles' and languages set([<Language [en]>, <Language [sl]>]) 2015-10-09 14:25:01,216 (7f9f9b7fe700) : INFO (logger:31) - subliminal.api: Initializing provider opensubtitles 2015-10-09 14:25:01,217 (7f9f9b7fe700) : INFO (logger:31) - subliminal.providers.opensubtitles: Logging in 2015-10-09 14:25:01,462 (7f9f9b7fe700) : DEBUG (logger:21) - subliminal.providers.opensubtitles: Logged in with token 'ahieh17cdhc5f8ocao5fh0k2g5' 2015-10-09 14:25:01,462 (7f9f9b7fe700) : INFO (logger:31) - subliminal.providers.opensubtitles: Searching subtitles [{u'moviebytesize': '969849251', u'sublanguageid': u'eng,slv', u'moviehash': u'9a50a90f696867ed'}] 2015-10-09 14:25:01,544 (7f9f9b7fe700) : DEBUG (logger:21) - subliminal.providers.opensubtitles: Found subtitle <OpenSubtitlesSubtitle '1954455211' [en]> 2015-10-09 14:25:01,545 (7f9f9b7fe700) : DEBUG (logger:21) - subliminal.providers.opensubtitles: Found subtitle <OpenSubtitlesSubtitle '1954455521' [en]> 2015-10-09 14:25:01,545 (7f9f9b7fe700) : DEBUG (logger:21) - subliminal.providers.opensubtitles: Found subtitle <OpenSubtitlesSubtitle '1954455208' [en]> 2015-10-09 14:25:01,545 (7f9f9b7fe700) : DEBUG (logger:21) - subliminal.providers.opensubtitles: Found subtitle <OpenSubtitlesSubtitle '1954455209' [en]> 2015-10-09 14:25:01,546 (7f9f9b7fe700) : DEBUG (logger:21) - subliminal.providers.opensubtitles: Found subtitle <OpenSubtitlesSubtitle '1954636370' [en]> 2015-10-09 14:25:01,546 (7f9f9b7fe700) : DEBUG (logger:21) - subliminal.providers.opensubtitles: Found subtitle <OpenSubtitlesSubtitle '1954690905' [en]> 2015-10-09 14:25:01,547 (7f9f9b7fe700) : INFO (logger:31) - subliminal_patch.patch_provider_pool: Listing subtitles with provider 'thesubdb' and languages set([<Language [en]>]) 2015-10-09 14:25:01,547 (7f9f9b7fe700) : INFO (logger:31) - subliminal.api: Initializing provider thesubdb 2015-10-09 14:25:01,552 (7f9f9b7fe700) : INFO (logger:31) - subliminal.providers.thesubdb: Searching subtitles {u'action': u'search', u'hash': '7861c3e968a25a390c329b13d5876cc5'} 2015-10-09 14:25:01,563 (7f9f9b7fe700) : INFO (logger:31) - requests.packages.urllib3.connectionpool: Starting new HTTP connection (1): api.thesubdb.com 2015-10-09 14:25:02,054 (7f9f9b7fe700) : DEBUG (logger:21) - requests.packages.urllib3.connectionpool: "GET /?action=search&hash=7861c3e968a25a390c329b13d5876cc5 HTTP/1.1" 200 None 2015-10-09 14:25:02,055 (7f9f9b7fe700) : INFO (logger:31) - subliminal.providers.thesubdb: Found subtitle <TheSubDBSubtitle '7861c3e968a25a390c329b13d5876cc5' [en]> 2015-10-09 14:25:02,055 (7f9f9b7fe700) : INFO (logger:31) - subliminal.providers.thesubdb: Found subtitle <TheSubDBSubtitle '7861c3e968a25a390c329b13d5876cc5' [nl]> 2015-10-09 14:25:02,055 (7f9f9b7fe700) : INFO (logger:31) - subliminal.providers.thesubdb: Found subtitle <TheSubDBSubtitle '7861c3e968a25a390c329b13d5876cc5' [pt]> 2015-10-09 14:25:02,058 (7f9f9b7fe700) : INFO (logger:31) - subliminal_patch.patch_provider_pool: Listing subtitles with provider 'podnapisi' and languages set([<Language [en]>, <Language [sl]>]) 2015-10-09 14:25:02,059 (7f9f9b7fe700) : INFO (logger:31) - subliminal.api: Initializing provider podnapisi 2015-10-09 14:25:02,059 (7f9f9b7fe700) : INFO (logger:31) - subliminal.providers.podnapisi: Searching subtitles {u'sK': u'Elementary S03E01', u'sXML': 1, u'sL': 'en'} 2015-10-09 14:25:02,060 (7f9f9b7fe700) : INFO (logger:31) - requests.packages.urllib3.connectionpool: Starting new HTTP connection (1): podnapisi.net 2015-10-09 14:25:02,163 (7f9f9b7fe700) : DEBUG (logger:21) - requests.packages.urllib3.connectionpool: "GET /subtitles/search/old?sK=Elementary+S03E01&sXML=1&sL=en HTTP/1.1" 302 160 2015-10-09 14:25:02,164 (7f9f9b7fe700) : INFO (logger:31) - requests.packages.urllib3.connectionpool: Starting new HTTP connection (1): www.podnapisi.net 2015-10-09 14:25:02,333 (7f9f9b7fe700) : DEBUG (logger:21) - requests.packages.urllib3.connectionpool: "GET /subtitles/search/old?sK=Elementary+S03E01&sXML=1&sL=en HTTP/1.1" 200 1420 2015-10-09 14:25:02,334 (7f9f9b7fe700) : DEBUG (logger:21) - subliminal.providers.podnapisi: No subtitles found 2015-10-09 14:25:02,334 (7f9f9b7fe700) : INFO (logger:31) - subliminal.providers.podnapisi: Searching subtitles {u'sK': u'Elementary S03E01', u'sXML': 1, u'sL': 'sl'} 2015-10-09 14:25:02,386 (7f9f9b7fe700) : DEBUG (logger:21) - requests.packages.urllib3.connectionpool: "GET /subtitles/search/old?sK=Elementary+S03E01&sXML=1&sL=sl HTTP/1.1" 302 160 2015-10-09 14:25:02,457 (7f9f9b7fe700) : DEBUG (logger:21) - requests.packages.urllib3.connectionpool: "GET /subtitles/search/old?sK=Elementary+S03E01&sXML=1&sL=sl HTTP/1.1" 200 1420 2015-10-09 14:25:02,458 (7f9f9b7fe700) : DEBUG (logger:21) - subliminal.providers.podnapisi: No subtitles found 2015-10-09 14:25:02,459 (7f9f9b7fe700) : INFO (logger:31) - subliminal_patch.patch_provider_pool: Skipping provider 'addic7ed': not a valid video 2015-10-09 14:25:02,461 (7f9f9b7fe700) : INFO (logger:31) - subliminal_patch.patch_provider_pool: Skipping provider 'tvsubtitles': not a valid video 2015-10-09 14:25:02,461 (7f9f9b7fe700) : INFO (logger:31) - subliminal.providers.opensubtitles: 'episode' is not a valid movie_kind 2015-10-09 14:25:02,461 (7f9f9b7fe700) : INFO (logger:31) - subliminal.subtitle: Computing score for matches set([u'hearing_impaired']) and <Movie [u'Elementary S03E01']> 2015-10-09 14:25:02,461 (7f9f9b7fe700) : DEBUG (logger:21) - subliminal.subtitle: Final matches: set([u'hearing_impaired']) 2015-10-09 14:25:02,462 (7f9f9b7fe700) : INFO (logger:31) - subliminal.subtitle: Computed score 1 2015-10-09 14:25:02,462 (7f9f9b7fe700) : INFO (logger:31) - subliminal.providers.opensubtitles: 'episode' is not a valid movie_kind 2015-10-09 14:25:02,462 (7f9f9b7fe700) : INFO (logger:31) - subliminal.subtitle: Computing score for matches set([u'hearing_impaired']) and <Movie [u'Elementary S03E01']> 2015-10-09 14:25:02,463 (7f9f9b7fe700) : DEBUG (logger:21) - subliminal.subtitle: Final matches: set([u'hearing_impaired']) 2015-10-09 14:25:02,463 (7f9f9b7fe700) : INFO (logger:31) - subliminal.subtitle: Computed score 1 2015-10-09 14:25:02,463 (7f9f9b7fe700) : INFO (logger:31) - subliminal.providers.opensubtitles: 'episode' is not a valid movie_kind 2015-10-09 14:25:02,463 (7f9f9b7fe700) : INFO (logger:31) - subliminal.subtitle: Computing score for matches set([]) and <Movie [u'Elementary S03E01']> 2015-10-09 14:25:02,464 (7f9f9b7fe700) : DEBUG (logger:21) - subliminal.subtitle: Final matches: set([]) 2015-10-09 14:25:02,464 (7f9f9b7fe700) : INFO (logger:31) - subliminal.subtitle: Computed score 0 2015-10-09 14:25:02,464 (7f9f9b7fe700) : INFO (logger:31) - subliminal.providers.opensubtitles: 'episode' is not a valid movie_kind 2015-10-09 14:25:02,464 (7f9f9b7fe700) : INFO (logger:31) - subliminal.subtitle: Computing score for matches set([u'hearing_impaired']) and <Movie [u'Elementary S03E01']> 2015-10-09 14:25:02,465 (7f9f9b7fe700) : DEBUG (logger:21) - subliminal.subtitle: Final matches: set([u'hearing_impaired']) 2015-10-09 14:25:02,465 (7f9f9b7fe700) : INFO (logger:31) - subliminal.subtitle: Computed score 1 2015-10-09 14:25:02,465 (7f9f9b7fe700) : INFO (logger:31) - subliminal.providers.opensubtitles: 'episode' is not a valid movie_kind 2015-10-09 14:25:02,465 (7f9f9b7fe700) : INFO (logger:31) - subliminal.subtitle: Computing score for matches set([u'hearing_impaired']) and <Movie [u'Elementary S03E01']> 2015-10-09 14:25:02,466 (7f9f9b7fe700) : DEBUG (logger:21) - subliminal.subtitle: Final matches: set([u'hearing_impaired']) 2015-10-09 14:25:02,466 (7f9f9b7fe700) : INFO (logger:31) - subliminal.subtitle: Computed score 1 2015-10-09 14:25:02,466 (7f9f9b7fe700) : INFO (logger:31) - subliminal.providers.opensubtitles: 'episode' is not a valid movie_kind 2015-10-09 14:25:02,466 (7f9f9b7fe700) : INFO (logger:31) - subliminal.subtitle: Computing score for matches set([u'hearing_impaired']) and <Movie [u'Elementary S03E01']> 2015-10-09 14:25:02,467 (7f9f9b7fe700) : DEBUG (logger:21) - subliminal.subtitle: Final matches: set([u'hearing_impaired']) 2015-10-09 14:25:02,467 (7f9f9b7fe700) : INFO (logger:31) - subliminal.subtitle: Computed score 1 2015-10-09 14:25:02,467 (7f9f9b7fe700) : INFO (logger:31) - subliminal.subtitle: Computing score for matches set([u'hash', u'hearing_impaired']) and <Movie [u'Elementary S03E01']> 2015-10-09 14:25:02,467 (7f9f9b7fe700) : DEBUG (logger:21) - subliminal.subtitle: Final matches: set([u'hash', u'hearing_impaired']) 2015-10-09 14:25:02,468 (7f9f9b7fe700) : INFO (logger:31) - subliminal.subtitle: Computed score 63 2015-10-09 14:25:02,468 (7f9f9b7fe700) : INFO (logger:31) - subliminal_patch.patch_provider_pool: Downloading subtitle <TheSubDBSubtitle '7861c3e968a25a390c329b13d5876cc5' [en]> with score 63 2015-10-09 14:25:02,468 (7f9f9b7fe700) : INFO (logger:31) - subliminal.api: Downloading subtitle <TheSubDBSubtitle '7861c3e968a25a390c329b13d5876cc5' [en]> 2015-10-09 14:25:02,469 (7f9f9b7fe700) : INFO (logger:31) - subliminal.providers.thesubdb: Downloading subtitle %r 2015-10-09 14:25:02,583 (7f9f9b7fe700) : DEBUG (logger:21) - requests.packages.urllib3.connectionpool: "GET /?action=download&hash=7861c3e968a25a390c329b13d5876cc5&language=en HTTP/1.1" 200 66463 2015-10-09 14:25:02,805 (7f9f9b7fe700) : INFO (logger:31) - subliminal.subtitle: Guessing encoding for language en 2015-10-09 14:25:02,805 (7f9f9b7fe700) : DEBUG (logger:21) - subliminal.subtitle: Trying encodings [u'utf-8', u'latin-1'] 2015-10-09 14:25:02,805 (7f9f9b7fe700) : INFO (logger:31) - subliminal.subtitle: Guessed encoding utf-8 2015-10-09 14:25:02,806 (7f9f9b7fe700) : INFO (logger:31) - subliminal.subtitle: Guessing encoding for language en 2015-10-09 14:25:02,806 (7f9f9b7fe700) : DEBUG (logger:21) - subliminal.subtitle: Trying encodings [u'utf-8', u'latin-1'] 2015-10-09 14:25:02,806 (7f9f9b7fe700) : INFO (logger:31) - subliminal.subtitle: Guessed encoding utf-8 2015-10-09 14:25:02,844 (7f9f9b7fe700) : INFO (logger:31) - subliminal_patch.patch_provider_pool: Score 1 is below min_score (15) 2015-10-09 14:25:02,844 (7f9f9b7fe700) : INFO (logger:31) - subliminal.api: Downloaded 1 subtitle(s) 2015-10-09 14:25:02,844 (7f9f9b7fe700) : DEBUG (logger:21) - subliminal.api: Terminating initialized providers 2015-10-09 14:25:02,845 (7f9f9b7fe700) : INFO (logger:31) - subliminal.api: Terminating provider opensubtitles 2015-10-09 14:25:02,845 (7f9f9b7fe700) : INFO (logger:31) - subliminal.providers.opensubtitles: Logging out 2015-10-09 14:25:02,972 (7f9f9b7fe700) : DEBUG (logger:21) - subliminal.providers.opensubtitles: Logged out 2015-10-09 14:25:02,973 (7f9f9b7fe700) : INFO (logger:31) - subliminal.api: Terminating provider thesubdb 2015-10-09 14:25:02,973 (7f9f9b7fe700) : INFO (logger:31) - subliminal.api: Terminating provider podnapisi 2015-10-09 14:25:02,974 (7f9f9b7fe700) : DEBUG (init:123) - Using metadata as subtitle storage 2015-10-09 14:25:02,975 (7f9f9b7fe700) : CRITICAL (agentkit:1057) - Exception in the update function of agent named 'Subliminal TV Subtitles', called with guid 'com.plexapp.agents.thetvdb://255316/3/1?lang=en' (most recent call last): File "bundles-release/Framework.bundle-dist/Contents/Resources/Versions/2/Python/Framework/api/agentkit.py", line 1055, in _update File "/home/look2/Library/Application Support/Plex Media Server/Plug-ins/Subliminal.bundle/Contents/Code/init.py", line 190, in update saveSubtitles(videos, subtitles) File "/home/look2/Library/Application Support/Plex Media Server/Plug-ins/Subliminal.bundle/Contents/Code/init.py", line 124, in saveSubtitles saveSubtitlesToMetadata(videos, subtitles) File "/home/look2/Library/Application Support/Plex Media Server/Plug-ins/Subliminal.bundle/Contents/Code/init.py", line 153, in saveSubtitlesToMetadata mediaPart.subtitles[Locale.Language.Match(subtitle.language.alpha2)][subtitle.page_link] = Proxy.Media(subtitle.content, ext="srt") File "bundles-release/Framework.bundle-dist/Contents/Resources/Versions/2/Python/Framework/api/agentkit.py", line 237, in setitem File "bundles-release/Framework.bundle-dist/Contents/Resources/Versions/2/Python/Framework/api/agentkit.py", line 220, in make_tuple File "bundles-release/Framework.bundle-dist/Contents/Resources/Versions/2/Python/Framework/components/data.py", line 32, in sha1 File "bundles-release/Framework.bundle-dist/Contents/Resources/Versions/2/Python/Framework/components/data.py", line 22, in _generateHash TypeError: must be string or buffer, not None

2015-10-09 14:25:02,976 (7f9f9b7fe700) : DEBUG (model:225) - Serializing to /home/look2/Library/Application Support/Plex Media Server/Metadata/TV Shows/2/aad8bb433160d2b3a2b2aa3d0e65be1630cd8b5.bundle/Contents/com.plexapp.agents.subliminal/Info.xml 2015-10-09 14:25:02,977 (7f9f9b7fe700) : DEBUG (runtime:106) - Sending packed state data (110 bytes) 2015-10-09 14:25:02,977 (7f9f9b7fe700) : DEBUG (runtime:918) - Response: [200] str, 16 bytes

[pannal]

Something's massively wrong here. When I try subliminal.bundle's guessit, the result is: python -c "from guessit import guess_file_info; print guess_file_info('Elementary.S03E01.720p.HDTV.X264-DIMENSION.mkv')"

{u'mimetype': u'video/x-matroska', u'episodeNumber': 1, u'videoCodec': u'h264', u'container': u'mkv', u'format': u'HDTV', u'series': u'Elementary', u'releaseGroup': u'DIMENSION', u'screenSize': u'720p', u'season': 3, u'type': u'episode'}

Which is correct. Your instance detects: 2015-10-09 14:25:01,147 (7f9f9b7fe700) : DEBUG (logger:21) - guessit.matchtree: Final result: { [1.00] "mimetype": "video/x-matroska", [0.60] "videoCodec": "h264", [1.00] "container": "mkv", [0.60] "format": "HDTV", [0.36] "title": "Elementary S03E01", [0.60] "releaseGroup": "DIMENSION", [0.60] "screenSize": "720p", [1.00] "type": "movie" }

Which is wrong because it detects a movie.

How did you update Subliminal.bundle? Please try the following:

• stop Plex Media Server
• rm -rf Subliminal.bundle
• download the newest RC3 package and unpack it into your plugins folder
• start PMS and try again

I can only guess that there's some leftover .pyc files in the guessit folder or something like that.

[mrlook2]

And i will get the latest RC3 if i just push "download zip" on this site: https://github.com/pannal/Subliminal.bundle/

[pannal]

https://github.com/pannal/Subliminal.bundle/releases/download/1.1-rc3/Subliminal.bundle-1.1-rc3.zip

[mrlook2]

YEAH! it's working now... Do I need a user for open subtitles? Or can't I download subtitles from that site without a username ?

[mrlook2]

Atleast for the Elemantary in the test library, tried with another series, no luck...

[pannal]

Well that's a different topic. This issue is closed. You can always look inside the logs why it didn't download any subtitles.