Here my com.plexapp.agents.xbmcnfo.log
2012-12-03 03:23:57,654 (4001f9b0) : INFO (core:336) - Starting framework core - Version: 2.2.3.0, Build: 092e9af (Fri Nov 16 15:10:37 UTC 2012)
2012-12-03 03:23:57,671 (4001f9b0) : DEBUG (core:348) - Using the elevated policy
2012-12-03 03:23:57,675 (4001f9b0) : DEBUG (core:437) - Starting runtime component.
2012-12-03 03:23:57,742 (4001f9b0) : DEBUG (core:437) - Starting caching component.
2012-12-03 03:23:57,757 (4001f9b0) : DEBUG (core:437) - Starting data component.
2012-12-03 03:23:57,764 (4001f9b0) : DEBUG (core:437) - Starting networking component.
2012-12-03 03:23:57,838 (4001f9b0) : DEBUG (networking:312) - Loaded HTTP cookies
2012-12-03 03:23:57,875 (4001f9b0) : DEBUG (networking:498) - Setting the default network timeout to 20.0
2012-12-03 03:23:57,988 (4001f9b0) : DEBUG (core:437) - Starting localization component.
2012-12-03 03:23:57,991 (4001f9b0) : INFO (localization:408) - Setting the default locale to en-us
2012-12-03 03:23:57,996 (4001f9b0) : DEBUG (core:437) - Starting messaging component.
2012-12-03 03:23:58,198 (4001f9b0) : DEBUG (core:437) - Starting debugging component.
2012-12-03 03:23:58,202 (4001f9b0) : DEBUG (core:437) - Starting services component.
2012-12-03 03:23:58,221 (4001f9b0) : DEBUG (core:437) - Starting myplex component.
2012-12-03 03:23:58,224 (4001f9b0) : DEBUG (core:437) - Starting notifications component.
2012-12-03 03:23:58,238 (4292a490) : DEBUG (networking:166) - Requesting ‘http://127.0.0.1:32400/system/messaging/clear_events/com.plexapp.agents.xbmcnfo’
2012-12-03 03:24:06,026 (4001f9b0) : DEBUG (accessor:68) - Creating a new model access point for provider com.plexapp.agents.xbmcnfo in namespace ‘metadata’
2012-12-03 03:24:06,707 (4001f9b0) : DEBUG (runtime:1044) - Created a thread named ‘load_all_services’
2012-12-03 03:24:06,713 (42c18490) : DEBUG (services:265) - Plug-in is not daemonized - loading services from system
2012-12-03 03:24:06,797 (4001f9b0) : DEBUG (runtime:1044) - Created a thread named ‘get_server_info’
2012-12-03 03:24:06,980 (4001f9b0) : DEBUG (core:149) - Finished starting framework core
2012-12-03 03:24:06,983 (4001f9b0) : DEBUG (core:547) - Loading plug-in code
2012-12-03 03:24:07,108 (431f4490) : DEBUG (networking:166) - Requesting ‘http://127.0.0.1:32400’
2012-12-03 03:24:07,113 (42c18490) : DEBUG (networking:166) - Requesting ‘http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_’
2012-12-03 03:24:13,336 (4001f9b0) : DEBUG (core:553) - Finished loading plug-in code
2012-12-03 03:24:13,419 (4001f9b0) : DEBUG (agentkit:1010) - Creating new agent class called xbmcnfo
2012-12-03 03:24:13,440 (4001f9b0) : DEBUG (agentkit:890) - Updating agent information: [{‘media_types’: [‘Movie’], ‘accepts_from’: None, ‘fallback_agent’: None, ‘contributes_to’: None, ‘languages’: [‘xn’], ‘persist_stored_files’: True, ‘primary_provider’: True, ‘prefs’: False, ‘name’: ‘XBMC .nfo Importer’}]
2012-12-03 03:24:13,555 (4001f9b0) : DEBUG (networking:166) - Requesting ‘http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo1CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdAoyCnMyNgpjb20ucGxleGFwcC5hZ2VudHMueGJtY25mb3MxMAppZGVudGlmaWVycjEKczEwCmFnZW50X2luZm8xCnIyCjkKcjMKczExCm1lZGlhX3R5cGVzbnMxMgphY2NlcHRzX2Zyb21uczE0CmZhbGxiYWNrX2FnZW50bnMxNApjb250cmlidXRlc190b3I0CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzYjFzMTYKcHJpbWFyeV9wcm92aWRlcmIwczUKcHJlZnNzMTgKWEJNQyAubmZvIEltcG9ydGVyczQKbmFtZTEKczUKTW92aWUxCnMyCnhucjAK’
2012-12-03 03:24:17,717 (431f4490) : DEBUG (core:525) - Machine identifier is 9398651809fc7a874781eddfe0b2da2b3272db96
2012-12-03 03:24:17,883 (431f4490) : DEBUG (core:526) - Server version is 0.9.6.9.240-8fd9c6a
2012-12-03 03:24:24,467 (4001f9b0) : CRITICAL (sandbox:298) - Function named ‘Start’ couldn’t be found in the current environment
2012-12-03 03:24:24,472 (4001f9b0) : INFO (core:598) - Started plug-in
2012-12-03 03:24:24,475 (4001f9b0) : DEBUG (socketinterface:144) - Starting socket server
2012-12-03 03:24:24,811 (4001f9b0) : DEBUG (runtime:1044) - Created a thread named ‘start’
2012-12-03 03:24:24,815 (4001f9b0) : INFO (socketinterface:168) - Socket server started on port 34168
2012-12-03 03:24:24,820 (4001f9b0) : INFO (pipeinterface:25) - Entering run loop
2012-12-03 03:24:24,824 (4001f9b0) : DEBUG (runtime:655) - Handling request GET /:/prefixes
2012-12-03 03:24:24,902 (4001f9b0) : DEBUG (runtime:751) - Found route matching /:/prefixes
2012-12-03 03:24:25,462 (4001f9b0) : DEBUG (runtime:851) - Response: [200] MediaContainer, 151 bytes
2012-12-03 03:24:26,326 (4292a490) : DEBUG (runtime:655) - Handling request GET /:/plugins/com.plexapp.agents.xbmcnfo/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo0CnMyCnhuczQKbGFuZ3M1Ck1vdmllczEwCm1lZGlhX3R5cGViMHM2Cm1hbnVhbHIxCnM2Cmt3YXJnczYKczE2CmM2MTI1OWY1MGIzMmMwYjZzMTcKb3BlblN1YnRpdGxlc0hhc2hzMTkKMTAgTWludXRlcyDDoCBWaXZyZXM0Cm5hbWVzODgKJTJGdm9sdW1lMSUyRnZpZGVvJTJGRmlsbXMlMkYxMCUyMG1pbnV0ZXMlMjDDoCUyMHZpdnJlJTJGMTAlMjBtaW51dGVzJTIww6AlMjB2aXZyZSUyRWF2aXM4CmZpbGVuYW1lczQwCjU5NGU3Y2FhZmE5Nzg2OTg4NGQ5ZTI5ZDBiZGYwNDBiNDI2YmMzOTNzOApwbGV4SGFzaHM3CjUwNTkwMDBzOApkdXJhdGlvbnM0CjU4MjJzMgppZHIwCg__
2012-12-03 03:24:26,991 (4292a490) : DEBUG (runtime:751) - Found route matching /:/plugins/com.plexapp.agents.xbmcnfo/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo0CnMyCnhuczQKbGFuZ3M1Ck1vdmllczEwCm1lZGlhX3R5cGViMHM2Cm1hbnVhbHIxCnM2Cmt3YXJnczYKczE2CmM2MTI1OWY1MGIzMmMwYjZzMTcKb3BlblN1YnRpdGxlc0hhc2hzMTkKMTAgTWludXRlcyDDoCBWaXZyZXM0Cm5hbWVzODgKJTJGdm9sdW1lMSUyRnZpZGVvJTJGRmlsbXMlMkYxMCUyMG1pbnV0ZXMlMjDDoCUyMHZpdnJlJTJGMTAlMjBtaW51dGVzJTIww6AlMjB2aXZyZSUyRWF2aXM4CmZpbGVuYW1lczQwCjU5NGU3Y2FhZmE5Nzg2OTg4NGQ5ZTI5ZDBiZGYwNDBiNDI2YmMzOTNzOApwbGV4SGFzaHM3CjUwNTkwMDBzOApkdXJhdGlvbnM0CjU4MjJzMgppZHIwCg__
2012-12-03 03:24:27,000 (4292a490) : INFO (agentkit:914) - Searching for matches for {‘openSubtitlesHash’: ‘c61259f50b32c0b6’, ‘name’: ‘10 Minutes \xc3\xa0 Vivre’, ‘filename’: ‘%2Fvolume1%2Fvideo%2FFilms%2F10%20minutes%20\xc3\xa0%20vivre%2F10%20minutes%20\xc3\xa0%20vivre%2Eavi’, ‘plexHash’: ‘594e7caafa97869884d9e29d0bdf040b426bc393’, ‘duration’: ‘5059000’, ‘id’: ‘5822’}
2012-12-03 03:24:27,149 (4292a490) : DEBUG (networking:166) - Requesting ‘http://127.0.0.1:32400/library/metadata/5822/tree’
2012-12-03 03:24:28,167 (4292a490) : INFO (init:15) - Searching
2012-12-03 03:24:28,268 (4292a490) : DEBUG (networking:166) - Requesting ‘http://localhost:32400/library/metadata/5822’
2012-12-03 03:24:31,467 (42c18490) : DEBUG (services:362) - Loaded services
2012-12-03 03:24:32,100 (42f06490) : DEBUG (services:438) - No shared code to load
2012-12-03 03:24:50,313 (4292a490) : CRITICAL (agentkit:930) - Exception in the search function of agent named ‘XBMC .nfo Importer’, called with keyword arguments {‘openSubtitlesHash’: ‘c61259f50b32c0b6’, ‘name’: ‘10 Minutes \xc3\xa0 Vivre’, ‘filename’: ‘%2Fvolume1%2Fvideo%2FFilms%2F10%20minutes%20\xc3\xa0%20vivre%2F10%20minutes%20\xc3\xa0%20vivre%2Eavi’, ‘plexHash’: ‘594e7caafa97869884d9e29d0bdf040b426bc393’, ‘duration’: ‘5059000’, ‘id’: ‘5822’} (most recent call last):
File “/volume1/Plex/Library/Application Support/Plex Media Server/Plug-ins/Framework.bundle/Contents/Resources/Versions/2/Python/Framework/api/agentkit.py”, line 923, in _search
agent.search(results, media, lang)
File “/volume1/Plex/Library/Application Support/Plex Media Server/Plug-ins/XBMC .nfo Importer.bundle/Contents/Code/init.py”, line 18, in search
xml = XML.ElementFromURL(pageUrl)
File “/volume1/Plex/Library/Application Support/Plex Media Server/Plug-ins/Framework.bundle/Contents/Resources/Versions/2/Python/Framework/api/parsekit.py”, line 328, in ElementFromURL
method=method,
File “/volume1/Plex/Library/Application Support/Plex Media Server/Plug-ins/Framework.bundle/Contents/Resources/Versions/2/Python/Framework/api/networkkit.py”, line 67, in _http_request
req = self._core.networking.http_request(url, *args, **kwargs)
File “/volume1/Plex/Library/Application Support/Plex Media Server/Plug-ins/Framework.bundle/Contents/Resources/Versions/2/Python/Framework/components/networking.py”, line 416, in http_request
return HTTPRequest(self._core, url, data, h, url_cache, encoding, errors, timeout, immediate, sleep, opener, follow_redirects, method)
File “/volume1/Plex/Library/Application Support/Plex Media Server/Plug-ins/Framework.bundle/Contents/Resources/Versions/2/Python/Framework/components/networking.py”, line 141, in init
self.load()
File “/volume1/Plex/Library/Application Support/Plex Media Server/Plug-ins/Framework.bundle/Contents/Resources/Versions/2/Python/Framework/components/networking.py”, line 177, in load
f = self._opener.open(req, timeout=self._timeout)
File “/volume1/Plex/Library/Application Support/Plex Media Server/Plug-ins/Framework.bundle/Contents/Resources/Platforms/Shared/Libraries/urllib2_new.py”, line 438, in open
response = self._open(req, data)
File “/volume1/Plex/Library/Application Support/Plex Media Server/Plug-ins/Framework.bundle/Contents/Resources/Platforms/Shared/Libraries/urllib2_new.py”, line 456, in _open
‘_open’, req)
File “/volume1/Plex/Library/Application Support/Plex Media Server/Plug-ins/Framework.bundle/Contents/Resources/Platforms/Shared/Libraries/urllib2_new.py”, line 416, in _call_chain
result = func(*args)
File “/volume1/Plex/Library/Application Support/Plex Media Server/Plug-ins/Framework.bundle/Contents/Resources/Platforms/Shared/Libraries/urllib2_new.py”, line 1217, in http_open
return self.do_open(httplib.HTTPConnection, req)
File “/volume1/Plex/Library/Application Support/Plex Media Server/Plug-ins/Framework.bundle/Contents/Resources/Platforms/Shared/Libraries/urllib2_new.py”, line 1192, in do_open
raise URLError(err)
URLError:
2012-12-03 03:24:50,476 (4292a490) : DEBUG (runtime:851) - Response: [200] str, 16 bytes