2013-12-13 15:17:06,709 (1dc4) :  INFO (core:336) - Starting framework core - Version: 2.3.12, Build: bd749cb (Mon Nov 18 18:57:02 UTC 2013)
2013-12-13 15:17:06,709 (1dc4) :  DEBUG (core:348) - Using the elevated policy
2013-12-13 15:17:06,710 (1dc4) :  DEBUG (core:352) - Extending whitelist: ['cgi', 'tempfile']
2013-12-13 15:17:06,710 (1dc4) :  DEBUG (core:437) - Starting runtime component.
2013-12-13 15:17:06,713 (1dc4) :  DEBUG (core:437) - Starting caching component.
2013-12-13 15:17:06,713 (1dc4) :  DEBUG (core:437) - Starting data component.
2013-12-13 15:17:06,713 (1dc4) :  DEBUG (core:437) - Starting networking component.
2013-12-13 15:17:06,714 (1dc4) :  DEBUG (networking:318) - Loaded HTTP cookies
2013-12-13 15:17:06,717 (1dc4) :  DEBUG (networking:504) - Setting the default network timeout to 20.0
2013-12-13 15:17:06,717 (1dc4) :  DEBUG (core:437) - Starting localization component.
2013-12-13 15:17:06,717 (1dc4) :  INFO (localization:408) - Setting the default locale to en-us
2013-12-13 15:17:06,720 (1dc4) :  DEBUG (localization:426) - Loaded en strings
2013-12-13 15:17:06,720 (1dc4) :  DEBUG (core:437) - Starting messaging component.
2013-12-13 15:17:06,720 (1dc4) :  DEBUG (core:437) - Starting debugging component.
2013-12-13 15:17:06,720 (1dc4) :  DEBUG (core:437) - Starting services component.
2013-12-13 15:17:06,720 (1dc4) :  DEBUG (core:437) - Starting myplex component.
2013-12-13 15:17:06,721 (1dc4) :  DEBUG (core:437) - Starting notifications component.
2013-12-13 15:17:06,834 (1dc4) :  DEBUG (accessor:68) - Creating a new model access point for provider com.plexapp.system in namespace 'metadata'
2013-12-13 15:17:06,845 (1ecc) :  DEBUG (services:265) - Plug-in is not daemonized - loading services from system
2013-12-13 15:17:06,846 (1dc4) :  DEBUG (runtime:1107) - Created a thread named 'load_all_services'
2013-12-13 15:17:06,848 (1dc4) :  DEBUG (runtime:1107) - Created a thread named 'get_server_info'
2013-12-13 15:17:06,848 (1ecc) :  DEBUG (networking:172) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_'
2013-12-13 15:17:06,848 (1dc4) :  DEBUG (core:149) - Finished starting framework core
2013-12-13 15:17:06,849 (1064) :  DEBUG (networking:172) - Requesting 'http://127.0.0.1:32400'
2013-12-13 15:17:06,849 (1dc4) :  DEBUG (core:547) - Loading plug-in code
2013-12-13 15:17:06,865 (1064) :  DEBUG (core:525) - Machine identifier is f476cd55811b7d8773a23a0d0a54d7134a6150f6
2013-12-13 15:17:06,867 (1064) :  DEBUG (core:526) - Server version is 0.9.8.14.263-139ddbc
2013-12-13 15:17:06,887 (1dc4) :  DEBUG (core:553) - Finished loading plug-in code
2013-12-13 15:17:06,888 (1dc4) :  DEBUG (runtime:640) - Adding a prefix handler for 'System' ('/system')
2013-12-13 15:17:08,111 (1dc4) :  INFO (logkit:16) - Starting System 632a6e1 (Tue Jul 16 22:04:05 UTC 2013)
2013-12-13 15:17:08,119 (1dc4) :  INFO (datakit:93) - Loaded the dictionary file
2013-12-13 15:17:08,121 (1dc4) :  DEBUG (logkit:13) - Starting the agent service
2013-12-13 15:17:08,227 (1dc4) :  DEBUG (accessor:68) - Creating a new model access point for provider com.plexapp.system in namespace 'metadata'
2013-12-13 15:17:08,233 (1dc4) :  DEBUG (logkit:13) - Starting the bundle service
2013-12-13 15:17:08,233 (1dc4) :  DEBUG (runtime:1107) - Created a thread named 'start'
2013-12-13 15:17:08,234 (1dc4) :  DEBUG (logkit:13) - Starting the help service
2013-12-13 15:17:08,234 (1dc4) :  DEBUG (logkit:13) - Starting the store service
2013-12-13 15:17:08,236 (1dc0) :  DEBUG (runtime:1107) - Created a thread named 'ensure_agent_info_exists_inner'
2013-12-13 15:17:08,243 (1dc4) :  DEBUG (runtime:1107) - Created a thread named 'start'
2013-12-13 15:17:08,246 (1b6c) :  DEBUG (networking:172) - Requesting 'http://127.0.0.1:32400'
2013-12-13 15:17:08,246 (1dc4) :  DEBUG (logkit:13) - Starting the install service
2013-12-13 15:17:08,255 (1b6c) :  DEBUG (core:508) - Attribute 'platformVersion' set to '6.1 (Build 7601)'
2013-12-13 15:17:08,260 (1b6c) :  DEBUG (networking:172) - Requesting 'http://nine.plugins.plexapp.com/apps/all.json?frameworkVersion=2.3.12&machineIdentifier=f476cd55811b7d8773a23a0d0a54d7134a6150f6&os=Windows&osVersion=6.1%20%28Build%207601%29&cpu=i386&serverVersion=0.9.8.14.263-139ddbc&systemRevision=632a6e1'
2013-12-13 15:17:08,451 (1dc0) :  DEBUG (runtime:1107) - Created a thread named 'ensure_agent_info_exists_inner'
2013-12-13 15:17:08,457 (1dc0) :  DEBUG (runtime:1107) - Created a thread named 'ensure_agent_info_exists_inner'
2013-12-13 15:17:08,464 (1dc0) :  DEBUG (runtime:1107) - Created a thread named 'ensure_agent_info_exists_inner'
2013-12-13 15:17:08,470 (1dc0) :  DEBUG (runtime:1107) - Created a thread named 'ensure_agent_info_exists_inner'
2013-12-13 15:17:08,615 (1dc4) :  DEBUG (logkit:13) - Starting the player service
2013-12-13 15:17:08,618 (1dc4) :  DEBUG (logkit:13) - Starting the media flag service
2013-12-13 15:17:08,618 (1dc4) :  DEBUG (runtime:1107) - Created a thread named 'start'
2013-12-13 15:17:08,619 (1dc4) :  DEBUG (logkit:13) - Starting the peer service
2013-12-13 15:17:08,625 (1dc4) :  DEBUG (runtime:1107) - Created a thread named 'refresh_servers'
2013-12-13 15:17:08,625 (1788) :  DEBUG (logkit:13) - Fetching the current list of servers
2013-12-13 15:17:08,628 (1dc4) :  DEBUG (runtime:1140) - Scheduled a timed thread named 'refresh_servers'
2013-12-13 15:17:08,628 (1dc4) :  DEBUG (logkit:13) - Starting the stream service
2013-12-13 15:17:08,628 (1788) :  DEBUG (networking:172) - Requesting 'http://127.0.0.1:32400/servers'
2013-12-13 15:17:08,630 (1dc4) :  CRITICAL (runtime:709) - Private handlers are no longer supported; couldn't register <bound method StreamService.token_handler of <StreamService object at 0x05207410>>
2013-12-13 15:17:08,632 (1dc4) :  DEBUG (runtime:1140) - Scheduled a timed thread named 'invalidation_timer'
2013-12-13 15:17:08,634 (1dc4) :  DEBUG (logkit:13) - Starting the proxy service
2013-12-13 15:17:08,638 (1788) :  DEBUG (logkit:13) - Refreshing local server
2013-12-13 15:17:08,640 (1788) :  DEBUG (logkit:13) - Updating servers (True/False True/True/True)
2013-12-13 15:17:08,641 (1788) :  DEBUG (logkit:13) - Found 1 servers
2013-12-13 15:17:08,642 (1788) :  DEBUG (logkit:13) - Creating new server MediaMachine (f476cd55811b7d8773a23a0d0a54d7134a6150f6)
2013-12-13 15:17:08,644 (1788) :  DEBUG (networking:172) - Requesting 'http://127.0.0.1:32400/library/sections'
2013-12-13 15:17:08,657 (1788) :  DEBUG (networking:172) - Requesting 'http://127.0.0.1:32400/channels/all'
2013-12-13 15:17:08,660 (1dc4) :  DEBUG (runtime:1107) - Created a thread named 'update_attribution_flags_inner'
2013-12-13 15:17:08,661 (1dc4) :  INFO (core:598) - Started plug-in
2013-12-13 15:17:08,661 (1dc4) :  DEBUG (socketinterface:144) - Starting socket server
2013-12-13 15:17:08,665 (1788) :  DEBUG (runtime:1107) - Created a thread named 'background_refresh'
2013-12-13 15:17:08,667 (f70) :  DEBUG (logkit:13) - Background-refreshing remote servers
2013-12-13 15:17:08,668 (1dc4) :  DEBUG (runtime:1107) - Created a thread named 'start'
2013-12-13 15:17:08,668 (f70) :  DEBUG (logkit:13) - Updating servers (False/True True/True/True)
2013-12-13 15:17:08,670 (1dc4) :  INFO (socketinterface:168) - Socket server started on port 63157
2013-12-13 15:17:08,671 (f70) :  DEBUG (logkit:13) - Found 1 servers
2013-12-13 15:17:08,671 (1dc4) :  INFO (pipeinterface:25) - Entering run loop
2013-12-13 15:17:08,673 (1dc4) :  DEBUG (runtime:717) - Handling request GET /:/prefixes
2013-12-13 15:17:08,681 (1dc4) :  DEBUG (runtime:814) - Found route matching /:/prefixes
2013-12-13 15:17:08,686 (1dc4) :  DEBUG (runtime:914) - Response: [200] MediaContainer, 323 bytes
2013-12-13 15:17:08,819 (19e4) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
2013-12-13 15:17:08,865 (19e4) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
2013-12-13 15:17:09,157 (1dc0) :  DEBUG (runtime:1107) - Created a thread named 'ensure_agent_info_exists_inner'
2013-12-13 15:17:09,161 (1dc0) :  DEBUG (runtime:1107) - Created a thread named 'ensure_agent_info_exists_inner'
2013-12-13 15:17:09,168 (1dc0) :  DEBUG (runtime:1107) - Created a thread named 'ensure_agent_info_exists_inner'
2013-12-13 15:17:09,173 (1dc0) :  DEBUG (runtime:1107) - Created a thread named 'ensure_agent_info_exists_inner'
2013-12-13 15:17:09,595 (1dc0) :  DEBUG (runtime:1107) - Created a thread named 'ensure_agent_info_exists_inner'
2013-12-13 15:17:09,601 (1dc0) :  DEBUG (runtime:1107) - Created a thread named 'ensure_agent_info_exists_inner'
2013-12-13 15:17:09,846 (1dc0) :  DEBUG (runtime:1107) - Created a thread named 'ensure_agent_info_exists_inner'
2013-12-13 15:17:09,851 (1dc0) :  DEBUG (runtime:1107) - Created a thread named 'remove_unavailable_agents'
2013-12-13 15:17:09,851 (1dc0) :  DEBUG (logkit:13) - Started the bundle service
2013-12-13 15:17:09,861 (1aac) :  DEBUG (logkit:13) - Started the media flag service
2013-12-13 15:17:09,868 (1a2c) :  INFO (logkit:16) - Agent info: {'com.plexapp.agents.wikipedia': [{'media_types': ['Movie'], 'accepts_from': None, 'fallback_agent': None, 'contributes_to': ['com.plexapp.agents.imdb'], 'has_attribution': True, 'languages': ['en'], 'persist_stored_files': True, 'primary_provider': False, 'prefs': False, 'name': 'Wikipedia'}], 'com.plexapp.agents.none': [{'media_types': ['Movie'], 'accepts_from': None, 'fallback_agent': None, 'contributes_to': None, 'has_attribution': False, 'languages': ['xn'], 'persist_stored_files': True, 'primary_provider': True, 'prefs': False, 'name': 'Personal Media'}, {'media_types': ['TV_Show'], 'accepts_from': None, 'fallback_agent': None, 'contributes_to': None, 'has_attribution': False, 'languages': ['xn'], 'persist_stored_files': True, 'primary_provider': True, 'prefs': False, 'name': 'Personal Media Shows'}, {'media_types': ['Artist'], 'accepts_from': None, 'fallback_agent': None, 'contributes_to': None, 'has_attribution': False, 'languages': ['xn'], 'persist_stored_files': True, 'primary_provider': True, 'prefs': False, 'name': 'Personal Media Artists'}, {'media_types': ['Album'], 'accepts_from': None, 'fallback_agent': None, 'contributes_to': None, 'has_attribution': False, 'languages': ['xn'], 'persist_stored_files': True, 'primary_provider': True, 'prefs': False, 'name': 'Personal Media Albums'}, {'media_types': ['Photo'], 'accepts_from': None, 'fallback_agent': None, 'contributes_to': None, 'has_attribution': False, 'languages': ['xn'], 'persist_stored_files': True, 'primary_provider': True, 'prefs': False, 'name': 'Photos'}], 'com.plexapp.agents.htbackdrops': [{'media_types': ['Artist'], 'primary_provider': False, 'fallback_agent': None, 'contributes_to': ['com.plexapp.agents.lastfm'], 'has_attribution': True, 'languages': ['ab', 'aa', 'af', 'ak', 'sq', 'am', 'ar', 'an', 'hy', 'as', 'av', 'ae', 'ay', 'az', 'bm', 'ba', 'eu', 'be', 'bn', 'bh', 'bi', 'bs', 'pb', 'br', 'bg', 'my', 'ca', 'ch', 'ce', 'ny', 'zh', 'cu', 'cv', 'kw', 'co', 'cr', 'hr', 'cs', 'da', 'dv', 'nl', 'dz', 'en', 'eo', 'et', 'ee', 'fo', 'fj', 'fi', 'fr', 'fy', 'ff', 'gd', 'gl', 'lg', 'ka', 'de', 'el', 'gn', 'gu', 'ht', 'ha', 'he', 'hz', 'hi', 'ho', 'hu', 'is', 'io', 'ig', 'id', 'ia', 'ie', 'iu', 'ik', 'ga', 'it', 'ja', 'jv', 'kl', 'kn', 'kr', 'ks', 'kk', 'km', 'ki', 'rw', 'ky', 'kv', 'kg', 'ko', 'kj', 'ku', 'lo', 'la', 'lv', 'li', 'ln', 'lt', 'lu', 'lb', 'mk', 'mg', 'ms', 'ml', 'mt', 'gv', 'mi', 'mr', 'mh', 'mo', 'mn', 'na', 'nv', 'ng', 'ne', 'xn', 'nd', 'no', 'nb', 'nn', 'oc', 'oj', 'or', 'om', 'os', 'pi', 'pa', 'fa', 'pl', 'pt', 'ps', 'qu', 'rm', 'ro', 'rn', 'ru', 'se', 'sm', 'sg', 'sa', 'sc', 'sr', 'sn', 'ii', 'sd', 'si', 'sk', 'sl', 'so', 'st', 'nr', 'es', 'su', 'sw', 'ss', 'sv', 'tl', 'ty', 'tg', 'ta', 'tt', 'te', 'th', 'bo', 'ti', 'to', 'ts', 'tn', 'tr', 'tk', 'tw', 'ug', 'uk', 'ur', 'uz', 've', 'vi', 'vo', 'wa', 'cy', 'wo', 'xh', 'yi', 'yo', 'za', 'zu'], 'persist_stored_files': True, 'accepts_from': None, 'prefs': False, 'name': 'Home Theater Backdrops'}], 'com.plexapp.agents.imdb': [{'media_types': ['Movie'], 'primary_provider': True, 'fallback_agent': None, 'contributes_to': None, 'has_attribution': True, '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, 'accepts_from': None, 'prefs': True, 'name': 'Freebase'}], 'com.plexapp.agents.thetvdb': [{'media_types': ['TV_Show'], 'accepts_from': None, 'fallback_agent': None, 'contributes_to': None, 'has_attribution': True, 'languages': ['en', 'fr', 'zh', 'sv', 'no', 'da', 'fi', 'nl', 'de', 'it', 'es', 'pl', 'hu', 'el', 'tr', 'ru', 'he', 'ja', 'pt', 'cs', 'ko'], 'persist_stored_files': True, 'primary_provider': True, 'prefs': False, 'name': 'TheTVDB'}], 'com.plexapp.agents.bmtagenttvshows': [{'media_types': ['TV_Show'], 'accepts_from': ['com.plexapp.agents.plexthememusic'], 'fallback_agent': None, 'contributes_to': None, 'has_attribution': False, 'languages': ['en'], 'persist_stored_files': True, 'primary_provider': True, 'prefs': False, 'name': 'SageTV BMT Agent (TV Shows)'}], 'com.plexapp.agents.lastfm': [{'media_types': ['Artist'], 'primary_provider': True, 'fallback_agent': None, 'contributes_to': None, 'has_attribution': False, 'languages': ['en', 'sv', 'fr', 'es', 'de', 'pl', 'it', 'pt', 'ja', 'tr', 'ru', 'zh'], 'persist_stored_files': True, 'accepts_from': None, 'prefs': True, 'name': 'Last.fm'}, {'media_types': ['Album'], 'primary_provider': True, 'fallback_agent': None, 'contributes_to': None, 'has_attribution': False, 'languages': ['en', 'sv', 'fr', 'es', 'de', 'pl', 'it', 'pt', 'ja', 'tr', 'ru', 'zh'], 'persist_stored_files': True, 'accepts_from': None, 'prefs': True, 'name': 'Last.fm'}], 'com.plexapp.agents.themoviedb': [{'media_types': ['Movie'], 'accepts_from': ['com.plexapp.agents.localmedia'], 'fallback_agent': None, 'contributes_to': ['com.plexapp.agents.imdb'], 'has_attribution': True, 'languages': ['en', 'cs', 'da', 'de', 'el', 'es', 'fi', 'fr', 'he', 'hr', 'hu', 'it', 'lv', 'nl', 'no', 'pl', 'pt', 'ru', 'sk', 'sv', 'th', 'tr', 'vi', 'zh'], 'persist_stored_files': True, 'primary_provider': True, 'prefs': True, 'name': 'The Movie Database'}], 'com.plexapp.agents.localmedia': [{'media_types': ['Movie'], 'primary_provider': False, 'fallback_agent': None, 'contributes_to': ['com.plexapp.agents.imdb', 'com.plexapp.agents.none'], 'has_attribution': False, 'languages': ['xn'], 'persist_stored_files': False, 'accepts_from': None, 'prefs': False, 'name': 'Local Media Assets (Movies)'}, {'media_types': ['TV_Show'], 'primary_provider': False, 'fallback_agent': None, 'contributes_to': ['com.plexapp.agents.thetvdb', 'com.plexapp.agents.none'], 'has_attribution': False, 'languages': ['xn'], 'persist_stored_files': False, 'accepts_from': None, 'prefs': False, 'name': 'Local Media Assets (TV)'}, {'media_types': ['Artist'], 'primary_provider': False, 'fallback_agent': None, 'contributes_to': ['com.plexapp.agents.discogs', 'com.plexapp.agents.lastfm', 'com.plexapp.agents.none'], 'has_attribution': False, 'languages': ['xn'], 'persist_stored_files': False, 'accepts_from': None, 'prefs': False, 'name': 'Local Media Assets (Artists)'}, {'media_types': ['Album'], 'primary_provider': False, 'fallback_agent': None, 'contributes_to': ['com.plexapp.agents.discogs', 'com.plexapp.agents.lastfm', 'com.plexapp.agents.none'], 'has_attribution': False, 'languages': ['xn'], 'persist_stored_files': False, 'accepts_from': None, 'prefs': False, 'name': 'Local Media Assets (Albums)'}], 'com.plexapp.agents.movieposterdb': [{'media_types': ['Movie'], 'primary_provider': False, 'fallback_agent': None, 'contributes_to': ['com.plexapp.agents.imdb'], 'has_attribution': True, 'languages': ['xn'], 'persist_stored_files': True, 'accepts_from': None, 'prefs': False, 'name': 'MoviePosterDB'}], 'com.plexapp.agents.opensubtitles': [{'media_types': ['Movie'], 'primary_provider': False, 'fallback_agent': None, 'contributes_to': ['com.plexapp.agents.imdb'], 'has_attribution': False, 'languages': ['xn'], 'persist_stored_files': True, 'accepts_from': None, 'prefs': True, 'name': 'OpenSubtitles.org'}, {'media_types': ['TV_Show'], 'primary_provider': False, 'fallback_agent': None, 'contributes_to': ['com.plexapp.agents.thetvdb'], 'has_attribution': False, 'languages': ['xn'], 'persist_stored_files': True, 'accepts_from': None, 'prefs': True, 'name': 'OpenSubtitles.org'}], 'com.plexapp.agents.plexthememusic': [{'media_types': ['TV_Show'], 'accepts_from': None, 'fallback_agent': None, 'contributes_to': ['com.plexapp.agents.thetvdb'], 'has_attribution': False, 'languages': ['xn'], 'persist_stored_files': True, 'primary_provider': False, 'prefs': False, 'name': 'Plex Theme Music'}]}
2013-12-13 15:17:09,888 (1a2c) :  INFO (datakit:131) - Saved the dictionary file
2013-12-13 15:17:09,911 (19e4) :  DEBUG (runtime:914) - Response: [200] str, 84122 bytes
2013-12-13 15:17:10,078 (1ecc) :  DEBUG (services:362) - Loaded services
2013-12-13 15:17:10,081 (1b6c) :  DEBUG (preferences:256) - Loaded preferences from DefaultPrefs.json
2013-12-13 15:17:10,085 (1b6c) :  DEBUG (preferences:178) - Loaded the user preferences for com.plexapp.system
2013-12-13 15:17:10,085 (1588) :  DEBUG (services:438) - No shared code to load
2013-12-13 15:17:10,085 (1b6c) :  INFO (logkit:16) - No beta plugins.
2013-12-13 15:17:10,098 (1b6c) :  INFO (logkit:16) - Starting automatic update thread
2013-12-13 15:17:10,098 (1b6c) :  DEBUG (runtime:1140) - Scheduled a timed thread named 'auto_update_thread'
2013-12-13 15:17:10,098 (1b6c) :  DEBUG (logkit:13) - Started the store service
2013-12-13 15:18:09,009 (1ab4) :  DEBUG (runtime:717) - Handling request GET /system/agents/com.plexapp.agents.none/config/1
2013-12-13 15:18:09,009 (1ab4) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2013-12-13 15:18:09,059 (1ab4) :  DEBUG (runtime:814) - Found route matching /system/agents/com.plexapp.agents.none/config/1
2013-12-13 15:18:09,059 (1ab4) :  DEBUG (accessor:68) - Creating a new read-only model access point for provider com.plexapp.agents.none in namespace 'metadata'
2013-12-13 15:18:09,069 (1ab4) :  DEBUG (runtime:106) - Sending packed state data (99 bytes)
2013-12-13 15:18:09,069 (1ab4) :  DEBUG (runtime:914) - Response: [200] MediaContainer, 795 bytes
2013-12-13 15:18:09,145 (1504) :  DEBUG (runtime:717) - Handling request GET /system/agents/attribution?identifier=com.plexapp.agents.localmedia
2013-12-13 15:18:09,148 (1c78) :  DEBUG (runtime:717) - Handling request GET /system/agents/attribution?identifier=com.plexapp.agents.none
2013-12-13 15:18:09,151 (1504) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2013-12-13 15:18:09,151 (998) :  DEBUG (runtime:717) - Handling request GET /system/agents/attribution?identifier=com.plexapp.agents.movieposterdb
2013-12-13 15:18:09,154 (1c78) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2013-12-13 15:18:09,157 (f68) :  DEBUG (runtime:717) - Handling request GET /system/agents/attribution?identifier=com.plexapp.agents.themoviedb
2013-12-13 15:18:09,161 (998) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2013-12-13 15:18:09,161 (1504) :  DEBUG (runtime:814) - Found route matching /system/agents/attribution
2013-12-13 15:18:09,161 (12ec) :  DEBUG (runtime:717) - Handling request GET /system/agents/attribution?identifier=com.plexapp.agents.imdb
2013-12-13 15:18:09,164 (f68) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2013-12-13 15:18:09,164 (1c78) :  DEBUG (runtime:814) - Found route matching /system/agents/attribution
2013-12-13 15:18:09,164 (1938) :  DEBUG (runtime:717) - Handling request GET /system/agents/attribution?identifier=com.plexapp.agents.opensubtitles
2013-12-13 15:18:09,168 (998) :  DEBUG (runtime:814) - Found route matching /system/agents/attribution
2013-12-13 15:18:09,170 (12ec) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2013-12-13 15:18:09,174 (1504) :  DEBUG (runtime:106) - Sending packed state data (99 bytes)
2013-12-13 15:18:09,177 (f68) :  DEBUG (runtime:814) - Found route matching /system/agents/attribution
2013-12-13 15:18:09,177 (1938) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2013-12-13 15:18:09,178 (1504) :  DEBUG (runtime:914) - Response: [404] NoneType, 0 bytes
2013-12-13 15:18:09,180 (1c78) :  DEBUG (runtime:106) - Sending packed state data (99 bytes)
2013-12-13 15:18:09,181 (12ec) :  DEBUG (runtime:814) - Found route matching /system/agents/attribution
2013-12-13 15:18:09,183 (998) :  DEBUG (runtime:106) - Sending packed state data (99 bytes)
2013-12-13 15:18:09,184 (1c78) :  DEBUG (runtime:914) - Response: [404] NoneType, 0 bytes
2013-12-13 15:18:09,187 (1938) :  DEBUG (runtime:814) - Found route matching /system/agents/attribution
2013-12-13 15:18:09,191 (f68) :  DEBUG (runtime:106) - Sending packed state data (99 bytes)
2013-12-13 15:18:09,194 (998) :  DEBUG (runtime:914) - Response: [200] str, 228 bytes
2013-12-13 15:18:09,200 (f68) :  DEBUG (runtime:914) - Response: [200] str, 232 bytes
2013-12-13 15:18:09,200 (12ec) :  DEBUG (runtime:106) - Sending packed state data (99 bytes)
2013-12-13 15:18:09,200 (9a4) :  DEBUG (runtime:717) - Handling request GET /system/agents/attribution?identifier=com.plexapp.agents.wikipedia
2013-12-13 15:18:09,214 (1938) :  DEBUG (runtime:106) - Sending packed state data (99 bytes)
2013-12-13 15:18:09,216 (12ec) :  DEBUG (runtime:914) - Response: [200] str, 289 bytes
2013-12-13 15:18:09,217 (1760) :  DEBUG (runtime:717) - Handling request GET /system/agents/contributors?mediaType=1&primaryAgent=com.plexapp.agents.none
2013-12-13 15:18:09,219 (1938) :  DEBUG (runtime:914) - Response: [404] NoneType, 0 bytes
2013-12-13 15:18:09,220 (9a4) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2013-12-13 15:18:09,223 (1760) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2013-12-13 15:18:09,226 (9a4) :  DEBUG (runtime:814) - Found route matching /system/agents/attribution
2013-12-13 15:18:09,227 (1760) :  DEBUG (runtime:814) - Found route matching /system/agents/contributors
2013-12-13 15:18:09,230 (1760) :  DEBUG (runtime:106) - Sending packed state data (99 bytes)
2013-12-13 15:18:09,230 (9a4) :  DEBUG (runtime:106) - Sending packed state data (99 bytes)
2013-12-13 15:18:09,232 (1760) :  DEBUG (runtime:914) - Response: [200] MediaContainer, 249 bytes
2013-12-13 15:18:09,232 (9a4) :  DEBUG (runtime:914) - Response: [200] str, 376 bytes
2013-12-13 15:18:09,247 (1ffc) :  DEBUG (runtime:717) - Handling request GET /system/agents/attribution?identifier=com.plexapp.agents.localmedia
2013-12-13 15:18:09,249 (1ffc) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2013-12-13 15:18:09,250 (1ffc) :  DEBUG (runtime:814) - Found route matching /system/agents/attribution
2013-12-13 15:18:09,252 (1ffc) :  DEBUG (runtime:106) - Sending packed state data (99 bytes)
2013-12-13 15:18:09,252 (1ffc) :  DEBUG (runtime:914) - Response: [404] NoneType, 0 bytes
2013-12-13 15:18:14,085 (9ac) :  INFO (datakit:131) - Saved the dictionary file
2013-12-13 15:19:08,628 (19a0) :  DEBUG (runtime:1146) - Starting timed thread named 'refresh_servers'
2013-12-13 15:19:08,628 (19a0) :  DEBUG (logkit:13) - Fetching the current list of servers
2013-12-13 15:19:08,631 (19a0) :  DEBUG (networking:172) - Requesting 'http://127.0.0.1:32400/servers'
2013-12-13 15:19:08,647 (19a0) :  DEBUG (logkit:13) - Refreshing local server
2013-12-13 15:19:08,648 (19a0) :  DEBUG (logkit:13) - Updating servers (True/False True/True/True)
2013-12-13 15:19:08,648 (19a0) :  DEBUG (logkit:13) - Found 1 servers
2013-12-13 15:19:08,650 (19a0) :  DEBUG (logkit:13) - Updating existing server MediaMachine (f476cd55811b7d8773a23a0d0a54d7134a6150f6)
2013-12-13 15:19:08,653 (19a0) :  DEBUG (networking:172) - Requesting 'http://127.0.0.1:32400/library/sections'
2013-12-13 15:19:08,674 (19a0) :  DEBUG (networking:172) - Requesting 'http://127.0.0.1:32400/channels/all'
2013-12-13 15:19:08,680 (19a0) :  DEBUG (runtime:1107) - Created a thread named 'background_refresh'
2013-12-13 15:19:08,680 (1008) :  DEBUG (logkit:13) - Background-refreshing remote servers
2013-12-13 15:19:08,680 (1008) :  DEBUG (logkit:13) - Updating servers (False/True True/True/True)
2013-12-13 15:19:08,681 (1008) :  DEBUG (logkit:13) - Found 1 servers
2013-12-13 15:20:28,733 (1f90) :  DEBUG (runtime:717) - Handling request GET /system/appstore/history
2013-12-13 15:20:28,737 (1f90) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2013-12-13 15:20:28,742 (1f90) :  DEBUG (runtime:814) - Found route matching /system/appstore/history
2013-12-13 15:20:28,744 (1f90) :  DEBUG (runtime:106) - Sending packed state data (99 bytes)
2013-12-13 15:20:28,746 (1f90) :  DEBUG (runtime:914) - Response: [200] MediaContainer, 669 bytes
2013-12-13 15:20:34,306 (1e80) :  DEBUG (runtime:717) - Handling request GET /system/appstore/history
2013-12-13 15:20:34,311 (1e80) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2013-12-13 15:20:34,315 (1e80) :  DEBUG (runtime:814) - Found route matching /system/appstore/history
2013-12-13 15:20:34,322 (1e80) :  DEBUG (runtime:106) - Sending packed state data (99 bytes)
2013-12-13 15:20:34,323 (1e80) :  DEBUG (runtime:914) - Response: [200] MediaContainer, 669 bytes
2013-12-13 15:22:10,098 (1b54) :  DEBUG (runtime:1146) - Starting timed thread named 'auto_update_thread'
2013-12-13 15:22:10,099 (1b54) :  INFO (logkit:16) - Starting automatic update check
2013-12-13 15:22:10,101 (1b54) :  DEBUG (runtime:1107) - Created a thread named 'remove_unavailable_agents'
2013-12-13 15:22:10,121 (1b54) :  DEBUG (networking:172) - Requesting 'http://nine.plugins.plexapp.com/apps/all.json?frameworkVersion=2.3.12&machineIdentifier=f476cd55811b7d8773a23a0d0a54d7134a6150f6&os=Windows&osVersion=6.1%20%28Build%207601%29&cpu=i386&serverVersion=0.9.8.14.263-139ddbc&systemRevision=632a6e1'
2013-12-13 15:22:10,562 (1b54) :  INFO (logkit:16) - No beta plugins.
2013-12-13 15:22:10,575 (1b54) :  INFO (logkit:16) - 'Framework' plug-in [com.plexapp.framework] is up to date (822cccdfff6b04474be1aa514f6ea21ec4dbd4cf)
2013-12-13 15:22:10,575 (1b54) :  INFO (logkit:16) - 'System' plug-in [com.plexapp.system] is up to date (63aeca1f488e9107d467e880ba60c7a32bc73e8d)
2013-12-13 15:22:10,575 (1b54) :  INFO (logkit:16) - 'WebClient' plug-in [com.plexapp.plugins.webclient] is up to date (0740400195b2e49a20af82fd1e654d28082f9ecb)
2013-12-13 15:22:10,576 (1b54) :  INFO (logkit:16) - 'Scanners' plug-in [com.plexapp.system.scanners] is up to date (74d06ea04291116338712727cdbf4ea7221807b3)
2013-12-13 15:22:10,576 (1b54) :  INFO (logkit:16) - 'Services' plug-in [com.plexapp.system.services] is up to date (3302c93debdb28d05cf902aa4108bfdadf6111a9)
2013-12-13 15:22:10,576 (1b54) :  INFO (logkit:16) - 'Wikipedia' plug-in [com.plexapp.agents.wikipedia] is up to date (7e5fde2bcf42102a1365d37bc43855ff11c18eac)
2013-12-13 15:22:10,576 (1b54) :  INFO (logkit:16) - 'PersonalMedia' plug-in [com.plexapp.agents.none] is up to date (dc4db74657edf6a6dc60e26bfea7c4e015d33969)
2013-12-13 15:22:10,576 (1b54) :  INFO (logkit:16) - 'HTbackdrops' plug-in [com.plexapp.agents.htbackdrops] is up to date (ca28887040b079c97512fa5c73bb892ea69c83a4)
2013-12-13 15:22:10,578 (1b54) :  INFO (logkit:16) - 'WebManager' plug-in [com.plexapp.plugins.webmanager] is up to date (402fcf6e4d1ee8b4153d404708f02a2afa47ab8c)
2013-12-13 15:22:10,578 (1b54) :  INFO (logkit:16) - 'OpenSubtitles' plug-in [com.plexapp.agents.opensubtitles] is up to date (c22d50eba63a546d8d4d80c9b4fd886cebff42ba)
2013-12-13 15:22:10,578 (1b54) :  INFO (logkit:16) - 'Media-Flags' plug-in [com.plexapp.resources.mediaflags] is up to date (fe4f31fd3767824de8de04f18fbf222c3294a0e7)
2013-12-13 15:22:10,578 (1b54) :  INFO (logkit:16) - 'TheTVDB' plug-in [com.plexapp.agents.thetvdb] is up to date (0cd3dd148a19f3a9a6dc92e11c35593acdeb8663)
2013-12-13 15:22:10,578 (1b54) :  INFO (logkit:16) - 'SiteConfigurations' plug-in [com.plexapp.plugins.siteconfigurations] is up to date (134e1c9994fa2e4bd7dd3f7c71d42ee0b5eaad27)
2013-12-13 15:22:10,578 (1b54) :  INFO (logkit:16) - 'LocalMedia' plug-in [com.plexapp.agents.localmedia] is up to date (d0464245cfa6a3b543e1a158cc0241d3b6dee6d3)
2013-12-13 15:22:10,579 (1b54) :  INFO (logkit:16) - 'MoviePosterDB' plug-in [com.plexapp.agents.movieposterdb] is up to date (6be8820955797243a47b2595ab901e0c595d45bf)
2013-12-13 15:22:10,579 (1b54) :  INFO (logkit:16) - 'PlexThemeMusic' plug-in [com.plexapp.agents.plexthememusic] is up to date (67f4eef55866401d4f96a757260b41d90e842264)
2013-12-13 15:22:10,579 (1b54) :  INFO (logkit:16) - 'IMDB' plug-in [com.plexapp.agents.imdb] is up to date (3887cdf38f3b40a44a44d0d706ff8821e86c36a9)
2013-12-13 15:22:10,579 (1b54) :  INFO (logkit:16) - 'LastFM' plug-in [com.plexapp.agents.lastfm] is up to date (24cf1f367384e394c85a1ff0e5d0440bf0ba908e)
2013-12-13 15:22:10,581 (1b54) :  INFO (logkit:16) - 'The-Movie-Database' plug-in [com.plexapp.agents.themoviedb] is up to date (54a9008d3cd86e444977e44af15a7f4d8a0b5ad5)
2013-12-13 15:22:10,581 (1b54) :  INFO (logkit:16) - All plug-ins are up to date
2013-12-13 15:22:10,581 (1b54) :  DEBUG (runtime:1140) - Scheduled a timed thread named 'auto_update_thread'
2013-12-13 15:22:15,132 (18b4) :  INFO (datakit:131) - Saved the dictionary file