2017-08-02 14:32:19,702 (7f91bf1d8740) :  INFO (core:349) - Starting framework core - Version: 2.6.3, Build: 313f937 (Wed Jun 28 13:20:00 UTC 2017)
2017-08-02 14:32:19,702 (7f91bf1d8740) :  DEBUG (core:361) - Using the elevated policy
2017-08-02 14:32:19,703 (7f91bf1d8740) :  DEBUG (core:450) - Starting runtime component.
2017-08-02 14:32:19,708 (7f91bf1d8740) :  DEBUG (core:450) - Starting caching component.
2017-08-02 14:32:19,708 (7f91bf1d8740) :  DEBUG (core:450) - Starting data component.
2017-08-02 14:32:19,709 (7f91bf1d8740) :  DEBUG (core:450) - Starting networking component.
2017-08-02 14:32:19,710 (7f91bf1d8740) :  DEBUG (networking:284) - Loaded HTTP cookies
2017-08-02 14:32:19,714 (7f91bf1d8740) :  DEBUG (networking:452) - Setting the default network timeout to 20.0
2017-08-02 14:32:19,714 (7f91bf1d8740) :  DEBUG (core:450) - Starting localization component.
2017-08-02 14:32:19,715 (7f91bf1d8740) :  INFO (localization:409) - Setting the default locale to en-us
2017-08-02 14:32:19,715 (7f91bf1d8740) :  DEBUG (core:450) - Starting messaging component.
2017-08-02 14:32:19,716 (7f91bf1d8740) :  DEBUG (core:450) - Starting debugging component.
2017-08-02 14:32:19,716 (7f91bf1d8740) :  DEBUG (core:450) - Starting services component.
2017-08-02 14:32:19,717 (7f91bf1d8740) :  DEBUG (core:450) - Starting myplex component.
2017-08-02 14:32:19,717 (7f91bf1d8740) :  DEBUG (core:450) - Starting notifications component.
2017-08-02 14:32:19,725 (7f91a8ff9700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/system/messaging/clear_events/com.plexapp.agents.lastfm'
2017-08-02 14:32:19,902 (7f91bf1d8740) :  DEBUG (accessor:68) - Creating a new model access point for provider com.plexapp.agents.lastfm in namespace 'metadata'
2017-08-02 14:32:19,911 (7f91bf1d8740) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/resourceHashes'
2017-08-02 14:32:19,935 (7f91a8ff9700) :  DEBUG (services:265) - Plug-in is not daemonized - loading services from system
2017-08-02 14:32:19,935 (7f91bf1d8740) :  DEBUG (runtime:1117) - Created a thread named 'load_all_services'
2017-08-02 14:32:19,939 (7f91a8ff9700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_'
2017-08-02 14:32:19,940 (7f91bf1d8740) :  DEBUG (runtime:1117) - Created a thread named 'get_server_info'
2017-08-02 14:32:19,940 (7f91bf1d8740) :  DEBUG (core:150) - Finished starting framework core
2017-08-02 14:32:19,941 (7f91bf1d8740) :  DEBUG (core:560) - Loading plug-in code
2017-08-02 14:32:19,941 (7f91877fe700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400'
2017-08-02 14:32:19,993 (7f91877fe700) :  DEBUG (core:538) - Machine identifier is 9137ee3c856455d82ce048f60bd6e5b64d0ae2ab
2017-08-02 14:32:19,994 (7f91877fe700) :  DEBUG (core:539) - Server version is 1.7.5.4035-313f93718
2017-08-02 14:32:20,402 (7f91a8ff9700) :  DEBUG (services:362) - Loaded services
2017-08-02 14:32:20,412 (7f9187fff700) :  DEBUG (services:438) - No shared code to load
2017-08-02 14:32:20,449 (7f91bf1d8740) :  DEBUG (core:566) - Finished loading plug-in code
2017-08-02 14:32:20,450 (7f91bf1d8740) :  DEBUG (messaging:69) - Exposing function ArtistMbidLookup for remote access
2017-08-02 14:32:20,450 (7f91bf1d8740) :  DEBUG (messaging:69) - Exposing function GetMusicBrainzId for remote access
2017-08-02 14:32:20,450 (7f91bf1d8740) :  DEBUG (messaging:69) - Exposing function ArtistSearch for remote access
2017-08-02 14:32:20,450 (7f91bf1d8740) :  DEBUG (messaging:69) - Exposing function AlbumSearch for remote access
2017-08-02 14:32:20,451 (7f91bf1d8740) :  DEBUG (messaging:69) - Exposing function ArtistTopTracks for remote access
2017-08-02 14:32:20,451 (7f91bf1d8740) :  DEBUG (messaging:69) - Exposing function ArtistGetSimilar for remote access
2017-08-02 14:32:20,451 (7f91bf1d8740) :  DEBUG (messaging:69) - Exposing function ArtistGetEvents for remote access
2017-08-02 14:32:20,451 (7f91bf1d8740) :  DEBUG (agentkit:1115) - Creating new agent class called LastFmAgent
2017-08-02 14:32:20,452 (7f91bf1d8740) :  DEBUG (agentkit:933) - Updating agent information: [{'media_types': ['Artist'], 'accepts_from': None, 'fallback_agent': None, 'contributes_to': None, 'languages': ['en', 'sv', 'fr', 'es', 'de', 'pl', 'it', 'pt', 'ja', 'tr', 'ru', 'zh'], 'persist_stored_files': True, 'version': 0, 'primary_provider': True, 'prefs': True, 'name': 'Last.fm'}]
2017-08-02 14:32:20,453 (7f91bf1d8740) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo1CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdAoyCnMyNQpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtczEwCmlkZW50aWZpZXJyMQpzMTAKYWdlbnRfaW5mbzEKcjIKMTAKcjMKczExCm1lZGlhX3R5cGVzbnMxMgphY2NlcHRzX2Zyb21uczE0CmZhbGxiYWNrX2FnZW50bnMxNApjb250cmlidXRlc190b3I0CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczcKTGFzdC5mbXM0Cm5hbWUxCnM2CkFydGlzdDEyCnMyCmVuczIKc3ZzMgpmcnMyCmVzczIKZGVzMgpwbHMyCml0czIKcHRzMgpqYXMyCnRyczIKcnVzMgp6aHIwCg__'
2017-08-02 14:32:20,469 (7f91bf1d8740) :  DEBUG (agentkit:1115) - Creating new agent class called LastFmAlbumAgent
2017-08-02 14:32:20,471 (7f91bf1d8740) :  DEBUG (agentkit:933) - Updating agent information: [{'media_types': ['Artist'], 'accepts_from': None, 'fallback_agent': None, 'contributes_to': None, 'languages': ['en', 'sv', 'fr', 'es', 'de', 'pl', 'it', 'pt', 'ja', 'tr', 'ru', 'zh'], 'persist_stored_files': True, 'version': 0, 'primary_provider': True, 'prefs': True, 'name': 'Last.fm'}, {'media_types': ['Album'], 'accepts_from': None, 'fallback_agent': None, 'contributes_to': None, 'languages': ['en', 'sv', 'fr', 'es', 'de', 'pl', 'it', 'pt', 'ja', 'tr', 'ru', 'zh'], 'persist_stored_files': True, 'version': 0, 'primary_provider': True, 'prefs': True, 'name': 'Last.fm'}]
2017-08-02 14:32:20,473 (7f91bf1d8740) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo4CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApkaWN0Cmxpc3QKbGlzdAoyCnMyNQpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtczEwCmlkZW50aWZpZXJyMQpzMTAKYWdlbnRfaW5mbzIKcjIKcjUKMTAKcjMKczExCm1lZGlhX3R5cGVzbnMxMgphY2NlcHRzX2Zyb21uczE0CmZhbGxiYWNrX2FnZW50bnMxNApjb250cmlidXRlc190b3I0CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczcKTGFzdC5mbXM0Cm5hbWUxCnM2CkFydGlzdDEyCnMyCmVuczIKc3ZzMgpmcnMyCmVzczIKZGVzMgpwbHMyCml0czIKcHRzMgpqYXMyCnRyczIKcnVzMgp6aDEwCnI2CnMxMQptZWRpYV90eXBlc25zMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudG5zMTQKY29udHJpYnV0ZXNfdG9yNwpzOQpsYW5ndWFnZXNiMXMyMApwZXJzaXN0X3N0b3JlZF9maWxlc2kwCnM3CnZlcnNpb25iMXMxNgpwcmltYXJ5X3Byb3ZpZGVyYjFzNQpwcmVmc3M3Ckxhc3QuZm1zNApuYW1lMQpzNQpBbGJ1bTEyCnMyCmVuczIKc3ZzMgpmcnMyCmVzczIKZGVzMgpwbHMyCml0czIKcHRzMgpqYXMyCnRyczIKcnVzMgp6aHIwCg__'
2017-08-02 14:32:20,487 (7f91bf1d8740) :  INFO (core:611) - Started plug-in
2017-08-02 14:32:20,487 (7f91bf1d8740) :  DEBUG (socketinterface:160) - Starting socket server
2017-08-02 14:32:20,489 (7f91bf1d8740) :  DEBUG (runtime:1117) - Created a thread named 'start'
2017-08-02 14:32:20,489 (7f91bf1d8740) :  INFO (socketinterface:184) - Socket server started on port 36471
2017-08-02 14:32:20,489 (7f91bf1d8740) :  INFO (pipeinterface:25) - Entering run loop
2017-08-02 14:32:20,489 (7f91bf1d8740) :  DEBUG (runtime:717) - Handling request GET /:/prefixes
2017-08-02 14:32:20,491 (7f91bf1d8740) :  DEBUG (runtime:814) - Found route matching /:/prefixes
2017-08-02 14:32:20,492 (7f91bf1d8740) :  DEBUG (runtime:924) - Response: [200] MediaContainer, 150 bytes
2017-08-02 14:32:20,496 (7f91a8ff9700) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lastfm/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M2CkFydGlzdHMxMAptZWRpYV90eXBlMTEKczEzClRoZW4gTm93IE5leHRzNQphbGJ1bXMyCjE1czUKaW5kZXhzMTYKM2YyZjBmZjViMzMzZjc1MXMxNwpvcGVuU3VidGl0bGVzSGFzaHMzNQpZb3XigJl2ZSBHb3QgVG8gSGlkZSBZb3VyIExvdmUgQXdheXM0Cm5hbWVzMTEKR2xhc3MgVGlnZXJzNgphcnRpc3RuczUKdGl0bGVzMTkyCiUyRm1udCUyRm5mcyUyRm11c2ljJTJGTXVzaWMlMkZHbGFzcyUyMFRpZ2VyJTJGKDIwMDIpJTIwVGhlbiUyME5vdyUyME5leHQlMkZHbGFzcyUyMFRpZ2VyJTIwLSUyMFRoZW4lMjBOb3clMjBOZXh0JTIwJTIwLSUyMDE1JTIwLSUyMFlvdSVFMiU4MCU5OXZlJTIwR290JTIwVG8lMjBIaWRlJTIwWW91ciUyMExvdmUlMjBBd2F5JTJFZmxhY3M4CmZpbGVuYW1lczEKMXM0CmRpc2NzNDAKZGU0MjI0YzE3OWNjNDQ2YTQyOTZkYzg4MGNlNTU3NGI5NTc4ODk0MXM4CnBsZXhIYXNoczIKLTFzOApkdXJhdGlvbnM0CjkwNjVzMgppZHIwCg__
2017-08-02 14:32:20,512 (7f91a8ff9700) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lastfm/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M2CkFydGlzdHMxMAptZWRpYV90eXBlMTEKczEzClRoZW4gTm93IE5leHRzNQphbGJ1bXMyCjE1czUKaW5kZXhzMTYKM2YyZjBmZjViMzMzZjc1MXMxNwpvcGVuU3VidGl0bGVzSGFzaHMzNQpZb3XigJl2ZSBHb3QgVG8gSGlkZSBZb3VyIExvdmUgQXdheXM0Cm5hbWVzMTEKR2xhc3MgVGlnZXJzNgphcnRpc3RuczUKdGl0bGVzMTkyCiUyRm1udCUyRm5mcyUyRm11c2ljJTJGTXVzaWMlMkZHbGFzcyUyMFRpZ2VyJTJGKDIwMDIpJTIwVGhlbiUyME5vdyUyME5leHQlMkZHbGFzcyUyMFRpZ2VyJTIwLSUyMFRoZW4lMjBOb3clMjBOZXh0JTIwJTIwLSUyMDE1JTIwLSUyMFlvdSVFMiU4MCU5OXZlJTIwR290JTIwVG8lMjBIaWRlJTIwWW91ciUyMExvdmUlMjBBd2F5JTJFZmxhY3M4CmZpbGVuYW1lczEKMXM0CmRpc2NzNDAKZGU0MjI0YzE3OWNjNDQ2YTQyOTZkYzg4MGNlNTU3NGI5NTc4ODk0MXM4CnBsZXhIYXNoczIKLTFzOApkdXJhdGlvbnM0CjkwNjVzMgppZHIwCg__
2017-08-02 14:32:20,512 (7f91a8ff9700) :  INFO (agentkit:957) - Searching for matches for {'album': 'Then Now Next', 'index': '15', 'openSubtitlesHash': '3f2f0ff5b333f751', 'name': 'You\xe2\x80\x99ve Got To Hide Your Love Away', 'title': None, 'artist': 'Glass Tiger', 'filename': '%2Fmnt%2Fnfs%2Fmusic%2FMusic%2FGlass%20Tiger%2F(2002)%20Then%20Now%20Next%2FGlass%20Tiger%20-%20Then%20Now%20Next%20%20-%2015%20-%20You%E2%80%99ve%20Got%20To%20Hide%20Your%20Love%20Away%2Eflac', 'disc': '1', 'plexHash': 'de4224c179cc446a4296dc880ce5574b95788941', 'duration': '-1', 'id': '9065'}
2017-08-02 14:32:20,514 (7f91a8ff9700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/9065/tree'
2017-08-02 14:32:20,524 (7f91a8ff9700) :  INFO (__init__:246) - Artist search: Glass Tiger
2017-08-02 14:32:20,529 (7f91a8ff9700) :  DEBUG (networking:166) - Requesting 'http://lastfm-z.plexapp.com/2.0/?method=artist.search&artist=glass%20tiger&page=1&limit=9&format=json&api_key=d5310352469c2631e5976d0f4a599773'
2017-08-02 14:32:21,080 (7f91a8ff9700) :  INFO (__init__:201) - Fetching artist's albums and applying album bonus.
2017-08-02 14:32:21,085 (7f91a8ff9700) :  DEBUG (networking:166) - Requesting 'http://lastfm-z.plexapp.com/2.0/?method=artist.gettopalbums&artist=glass%20tiger&page=1&limit=50&format=json&api_key=d5310352469c2631e5976d0f4a599773'
2017-08-02 14:32:21,334 (7f91a8ff9700) :  INFO (__init__:225) - Applying album bonus of: 2
2017-08-02 14:32:21,334 (7f91a8ff9700) :  INFO (__init__:187) - Artist result: Glass Tiger dist: -1 album bonus: 2 listeners: 61936 score: 93
2017-08-02 14:32:21,335 (7f91a8ff9700) :  INFO (__init__:201) - Fetching artist's albums and applying album bonus.
2017-08-02 14:32:21,340 (7f91a8ff9700) :  DEBUG (networking:166) - Requesting 'http://lastfm-z.plexapp.com/2.0/?method=artist.gettopalbums&artist=glass%20tiger%20%28feat.%20rod%20stewart%29&page=1&limit=50&format=json&api_key=d5310352469c2631e5976d0f4a599773'
2017-08-02 14:32:21,568 (7f91a8ff9700) :  INFO (__init__:187) - Artist result: Glass Tiger (feat. Rod Stewart) dist: 21 album bonus: 0 listeners: 877 score: 69
2017-08-02 14:32:21,569 (7f91a8ff9700) :  INFO (__init__:193) - Skipping artist, didn't meet minimum score of 75
2017-08-02 14:32:21,569 (7f91a8ff9700) :  INFO (__init__:201) - Fetching artist's albums and applying album bonus.
2017-08-02 14:32:21,574 (7f91a8ff9700) :  DEBUG (networking:166) - Requesting 'http://lastfm-z.plexapp.com/2.0/?method=artist.gettopalbums&artist=glass%20tiger%20and%20bryan%20adams&page=1&limit=50&format=json&api_key=d5310352469c2631e5976d0f4a599773'
2017-08-02 14:32:21,801 (7f91a8ff9700) :  INFO (__init__:634) - No results for album search.
2017-08-02 14:32:21,801 (7f91a8ff9700) :  INFO (__init__:182) - Skipping Glass Tiger and Bryan Adams with only 3 listeners and score of 67.
2017-08-02 14:32:21,802 (7f91a8ff9700) :  INFO (__init__:201) - Fetching artist's albums and applying album bonus.
2017-08-02 14:32:21,807 (7f91a8ff9700) :  DEBUG (networking:166) - Requesting 'http://lastfm-z.plexapp.com/2.0/?method=artist.gettopalbums&artist=glass%20tiger%20%28holiday%29&page=1&limit=50&format=json&api_key=d5310352469c2631e5976d0f4a599773'
2017-08-02 14:32:22,026 (7f91a8ff9700) :  INFO (__init__:182) - Skipping Glass Tiger (Holiday) with only 162 listeners and score of 75.
2017-08-02 14:32:22,027 (7f91a8ff9700) :  INFO (__init__:201) - Fetching artist's albums and applying album bonus.
2017-08-02 14:32:22,032 (7f91a8ff9700) :  DEBUG (networking:166) - Requesting 'http://lastfm-z.plexapp.com/2.0/?method=artist.gettopalbums&artist=glass%20tiger%20feat.%20rod%20stewart&page=1&limit=50&format=json&api_key=d5310352469c2631e5976d0f4a599773'
2017-08-02 14:32:22,278 (7f91a8ff9700) :  INFO (__init__:182) - Skipping Glass Tiger feat. Rod Stewart with only 37 listeners and score of 67.
2017-08-02 14:32:22,278 (7f91a8ff9700) :  INFO (__init__:187) - Artist result: (Glass Tiger) dist: 0 album bonus: 0 listeners: 3 score: 90
2017-08-02 14:32:22,279 (7f91a8ff9700) :  INFO (__init__:182) - Skipping Glass Tigerꠀ with only 1 listeners and score of 87.
2017-08-02 14:32:22,280 (7f91a8ff9700) :  INFO (__init__:182) - Skipping Glass Tiger & Bryan Adams with only 5 listeners and score of 65.
2017-08-02 14:32:22,280 (7f91a8ff9700) :  INFO (__init__:182) - Skipping Glass Tiger W Rod Stewart with only 7 listeners and score of 68.
2017-08-02 14:32:22,282 (7f91a8ff9700) :  DEBUG (runtime:88) - Sending packed state data (590 bytes)
2017-08-02 14:32:22,282 (7f91a8ff9700) :  DEBUG (runtime:924) - Response: [200] str, 964 bytes
2017-08-02 14:32:22,336 (7f91a8ff9700) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lastfm/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNAo5MDY1czQKZGJpZGkwCnM3CnZlcnNpb25uczEwCnBhcmVudEdVSURuczgKcGFyZW50SURzNgpBcnRpc3RzMTAKbWVkaWFfdHlwZXM0OQpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtOi8vR2xhc3MlMjBUaWdlcj9sYW5nPWVuczQKZ3VpZHMxMwpHbGFzcyUyMFRpZ2VyczIKaWRyMAo_
2017-08-02 14:32:22,338 (7f91a8ff9700) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lastfm/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNAo5MDY1czQKZGJpZGkwCnM3CnZlcnNpb25uczEwCnBhcmVudEdVSURuczgKcGFyZW50SURzNgpBcnRpc3RzMTAKbWVkaWFfdHlwZXM0OQpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtOi8vR2xhc3MlMjBUaWdlcj9sYW5nPWVuczQKZ3VpZHMxMwpHbGFzcyUyMFRpZ2VyczIKaWRyMAo_
2017-08-02 14:32:22,340 (7f91a8ff9700) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.lastfm://Glass%20Tiger?lang=en
2017-08-02 14:32:22,342 (7f91a8ff9700) :  ERROR (model:205) - Cannot read model from /var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Metadata/Artists/6/915c04bc9ae3e3913872abb85ec7ee114a45f6a.bundle/Contents/com.plexapp.agents.lastfm
2017-08-02 14:32:22,343 (7f91a8ff9700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/9065/tree'
2017-08-02 14:32:22,358 (7f91a8ff9700) :  DEBUG (networking:166) - Requesting 'http://lastfm-z.plexapp.com/2.0/?method=artist.getInfo&artist=glass%20tiger&autocorrect=1&lang=en&format=json&api_key=d5310352469c2631e5976d0f4a599773'
2017-08-02 14:32:22,573 (7f91a8ff9700) :  DEBUG (networking:166) - Requesting 'https://lastfm-img2.akamaized.net/i/u/4a89e4221e954529c56fa6b8c96ab63e.png'
2017-08-02 14:32:23,103 (7f91a8ff9700) :  DEBUG (networking:198) - Not caching 'https://lastfm-img2.akamaized.net/i/u/4a89e4221e954529c56fa6b8c96ab63e.png' (content type 'image/png' not cacheable in Agent plug-ins)
2017-08-02 14:32:23,108 (7f91a8ff9700) :  DEBUG (networking:166) - Requesting 'http://lastfm-z.plexapp.com/2.0/?method=artist.getSimilar&artist=glass%20tiger&lang=en&format=json&limit=20&api_key=d5310352469c2631e5976d0f4a599773'
2017-08-02 14:32:23,325 (7f91a8ff9700) :  DEBUG (preferences:258) - Loaded preferences from DefaultPrefs.json
2017-08-02 14:32:23,327 (7f91a8ff9700) :  DEBUG (preferences:178) - Loaded the user preferences for com.plexapp.agents.lastfm
2017-08-02 14:32:23,331 (7f91a8ff9700) :  DEBUG (model:229) - Serializing to /var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Metadata/Artists/6/915c04bc9ae3e3913872abb85ec7ee114a45f6a.bundle/Contents/com.plexapp.agents.lastfm/Info.xml
2017-08-02 14:32:23,332 (7f91a8ff9700) :  DEBUG (runtime:88) - Sending packed state data (590 bytes)
2017-08-02 14:32:23,333 (7f91a8ff9700) :  DEBUG (runtime:924) - Response: [200] str, 16 bytes
2017-08-02 14:32:27,523 (7f91a8ff9700) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lastfm/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGUxMwpzMTMKVGhlbiBOb3cgTmV4dHM1CmFsYnVtczEKMXM1CmluZGV4czE2CjdkZDFmN2ZmNDc4YTAxMGFzMTcKb3BlblN1YnRpdGxlc0hhc2hzMzgKSeKAmW0gU3RpbGwgU2VhcmNoaW5nIChTaW5nbGUgVmVyc2lvbilzNApuYW1lczExCkdsYXNzIFRpZ2VyczYKYXJ0aXN0czQwCjRjMjM1N2VkNjJmYTI2Y2MyNWU3NjJiY2YwYTIwMjdlYzgyZjRhOGZzOApwbGV4SGFzaHMxOTEKJTJGbW50JTJGbmZzJTJGbXVzaWMlMkZNdXNpYyUyRkdsYXNzJTIwVGlnZXIlMkYoMjAwMiklMjBUaGVuJTIwTm93JTIwTmV4dCUyRkdsYXNzJTIwVGlnZXIlMjAtJTIwVGhlbiUyME5vdyUyME5leHQlMjAlMjAtJTIwMDElMjAtJTIwSSVFMiU4MCU5OW0lMjBTdGlsbCUyMFNlYXJjaGluZyUyMChTaW5nbGUlMjBWZXJzaW9uKSUyRWZsYWNzOApmaWxlbmFtZXMxCjFzNApkaXNjczQ5CmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9HbGFzcyUyMFRpZ2VyP2xhbmc9ZW5zMTAKcGFyZW50R1VJRHM0CjkwNjVzOApwYXJlbnRJRHMyCi0xczgKZHVyYXRpb25uczUKdGl0bGVzNAo5MDY2czIKaWRyMAo_
2017-08-02 14:32:27,524 (7f91a8ff9700) :  DEBUG (runtime:49) - Received packed state data (564 bytes)
2017-08-02 14:32:27,525 (7f91a8ff9700) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lastfm/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGUxMwpzMTMKVGhlbiBOb3cgTmV4dHM1CmFsYnVtczEKMXM1CmluZGV4czE2CjdkZDFmN2ZmNDc4YTAxMGFzMTcKb3BlblN1YnRpdGxlc0hhc2hzMzgKSeKAmW0gU3RpbGwgU2VhcmNoaW5nIChTaW5nbGUgVmVyc2lvbilzNApuYW1lczExCkdsYXNzIFRpZ2VyczYKYXJ0aXN0czQwCjRjMjM1N2VkNjJmYTI2Y2MyNWU3NjJiY2YwYTIwMjdlYzgyZjRhOGZzOApwbGV4SGFzaHMxOTEKJTJGbW50JTJGbmZzJTJGbXVzaWMlMkZNdXNpYyUyRkdsYXNzJTIwVGlnZXIlMkYoMjAwMiklMjBUaGVuJTIwTm93JTIwTmV4dCUyRkdsYXNzJTIwVGlnZXIlMjAtJTIwVGhlbiUyME5vdyUyME5leHQlMjAlMjAtJTIwMDElMjAtJTIwSSVFMiU4MCU5OW0lMjBTdGlsbCUyMFNlYXJjaGluZyUyMChTaW5nbGUlMjBWZXJzaW9uKSUyRWZsYWNzOApmaWxlbmFtZXMxCjFzNApkaXNjczQ5CmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9HbGFzcyUyMFRpZ2VyP2xhbmc9ZW5zMTAKcGFyZW50R1VJRHM0CjkwNjVzOApwYXJlbnRJRHMyCi0xczgKZHVyYXRpb25uczUKdGl0bGVzNAo5MDY2czIKaWRyMAo_
2017-08-02 14:32:27,526 (7f91a8ff9700) :  INFO (agentkit:957) - Searching for matches for {'album': 'Then Now Next', 'index': '1', 'openSubtitlesHash': '7dd1f7ff478a010a', 'name': 'I\xe2\x80\x99m Still Searching (Single Version)', 'artist': 'Glass Tiger', 'parentID': '9065', 'filename': '%2Fmnt%2Fnfs%2Fmusic%2FMusic%2FGlass%20Tiger%2F(2002)%20Then%20Now%20Next%2FGlass%20Tiger%20-%20Then%20Now%20Next%20%20-%2001%20-%20I%E2%80%99m%20Still%20Searching%20(Single%20Version)%2Eflac', 'disc': '1', 'parentGUID': 'com.plexapp.agents.lastfm://Glass%20Tiger?lang=en', 'plexHash': '4c2357ed62fa26cc25e762bcf0a2027ec82f4a8f', 'duration': '-1', 'title': None, 'id': '9066'}
2017-08-02 14:32:27,528 (7f91a8ff9700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/9066/tree'
2017-08-02 14:32:27,537 (7f91a8ff9700) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.lastfm://Glass%20Tiger?lang=en
2017-08-02 14:32:27,538 (7f91a8ff9700) :  DEBUG (model:234) - Deserializing from /var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Metadata/Artists/6/915c04bc9ae3e3913872abb85ec7ee114a45f6a.bundle/Contents/com.plexapp.agents.lastfm/Info.xml
2017-08-02 14:32:27,540 (7f91a8ff9700) :  INFO (__init__:358) - Album search: Then Now Next
2017-08-02 14:32:27,553 (7f91a8ff9700) :  DEBUG (networking:161) - Fetching 'http://lastfm-z.plexapp.com/2.0/?method=artist.gettopalbums&artist=glass%20tiger&page=1&limit=50&format=json&api_key=d5310352469c2631e5976d0f4a599773' from the HTTP cache
2017-08-02 14:32:27,599 (7f91a8ff9700) :  DEBUG (networking:166) - Requesting 'http://lastfm-z.plexapp.com/2.0/?method=album.getInfo&artist=glass%20tiger&album=then%20now%20next&autocorrect=1&lang=en&format=json&api_key=d5310352469c2631e5976d0f4a599773'
2017-08-02 14:32:28,173 (7f91a8ff9700) :  INFO (__init__:508) - Applying track bonus of: 20
2017-08-02 14:32:28,173 (7f91a8ff9700) :  INFO (__init__:471) - Album result: Then Now Next album bonus: 20 score: 112
2017-08-02 14:32:28,178 (7f91a8ff9700) :  DEBUG (networking:166) - Requesting 'http://lastfm-z.plexapp.com/2.0/?method=album.getInfo&artist=glass%20tiger&album=then...now...next&autocorrect=1&lang=en&format=json&api_key=d5310352469c2631e5976d0f4a599773'
2017-08-02 14:32:28,758 (7f91a8ff9700) :  INFO (__init__:508) - Applying track bonus of: 10
2017-08-02 14:32:28,759 (7f91a8ff9700) :  INFO (__init__:471) - Album result: Then...NOW...Next album bonus: 10 score: 96
2017-08-02 14:32:28,764 (7f91a8ff9700) :  DEBUG (networking:166) - Requesting 'http://lastfm-z.plexapp.com/2.0/?method=album.getInfo&artist=glass%20tiger&album=thin%20red%20line&autocorrect=1&lang=en&format=json&api_key=d5310352469c2631e5976d0f4a599773'
2017-08-02 14:32:28,985 (7f91a8ff9700) :  INFO (__init__:474) - Skipping 48 album results that don't meet the minimum score of 75.
2017-08-02 14:32:28,985 (7f91a8ff9700) :  INFO (__init__:372) - Good album match found (quick search) with score: 112
2017-08-02 14:32:28,989 (7f91a8ff9700) :  DEBUG (networking:166) - Requesting 'http://lastfm-z.plexapp.com/2.0/?method=album.search&album=then%20now%20next&limit=8&format=json&api_key=d5310352469c2631e5976d0f4a599773'
2017-08-02 14:32:29,310 (7f91a8ff9700) :  INFO (__init__:451) - Suppressing album result because artist looks wrong: Jim Jones
2017-08-02 14:32:29,313 (7f91a8ff9700) :  INFO (__init__:451) - Suppressing album result because artist looks wrong: United Future Organization
2017-08-02 14:32:29,315 (7f91a8ff9700) :  INFO (__init__:451) - Suppressing album result because artist looks wrong: United Future Organization
2017-08-02 14:32:29,317 (7f91a8ff9700) :  INFO (__init__:451) - Suppressing album result because artist looks wrong: United Future Organization
2017-08-02 14:32:29,469 (7f91a8ff9700) :  DEBUG (caching:268) - HTTP.system cache trimmed - new size is 8273 KB (1024 items)
2017-08-02 14:32:29,486 (7f91a8ff9700) :  DEBUG (networking:161) - Fetching 'http://lastfm-z.plexapp.com/2.0/?method=album.getInfo&artist=glass%20tiger&album=then%20now%20next&autocorrect=1&lang=en&format=json&api_key=d5310352469c2631e5976d0f4a599773' from the HTTP cache
2017-08-02 14:32:29,646 (7f91a8ff9700) :  INFO (__init__:508) - Applying track bonus of: 20
2017-08-02 14:32:29,646 (7f91a8ff9700) :  INFO (__init__:471) - Album result: Then Now Next album bonus: 20 score: 112
2017-08-02 14:32:29,652 (7f91a8ff9700) :  DEBUG (networking:166) - Requesting 'http://lastfm-z.plexapp.com/2.0/?method=album.getInfo&artist=glass%20tiger&album=then..%20now..%20next&autocorrect=1&lang=en&format=json&api_key=d5310352469c2631e5976d0f4a599773'
2017-08-02 14:32:30,038 (7f91a8ff9700) :  INFO (__init__:471) - Album result: Then.. NOW.. Next album bonus: 0 score: 92
2017-08-02 14:32:30,046 (7f91a8ff9700) :  DEBUG (networking:161) - Fetching 'http://lastfm-z.plexapp.com/2.0/?method=album.getInfo&artist=glass%20tiger&album=then...now...next&autocorrect=1&lang=en&format=json&api_key=d5310352469c2631e5976d0f4a599773' from the HTTP cache
2017-08-02 14:32:30,250 (7f91a8ff9700) :  INFO (__init__:508) - Applying track bonus of: 10
2017-08-02 14:32:30,250 (7f91a8ff9700) :  INFO (__init__:471) - Album result: Then...NOW...Next album bonus: 10 score: 96
2017-08-02 14:32:30,254 (7f91a8ff9700) :  DEBUG (networking:166) - Requesting 'http://lastfm-z.plexapp.com/2.0/?method=album.getInfo&artist=glass%20tiger&album=then...%20now...%20next%20%28remastered%29&autocorrect=1&lang=en&format=json&api_key=d5310352469c2631e5976d0f4a599773'
2017-08-02 14:32:30,772 (7f91a8ff9700) :  INFO (__init__:508) - Applying track bonus of: 9
2017-08-02 14:32:30,772 (7f91a8ff9700) :  INFO (__init__:474) - Skipping 5 album results that don't meet the minimum score of 75.
2017-08-02 14:32:30,773 (7f91a8ff9700) :  INFO (__init__:399) - Found a good match for album search.
2017-08-02 14:32:30,773 (7f91a8ff9700) :  INFO (__init__:422) - Found 3 albums...
2017-08-02 14:32:30,775 (7f91a8ff9700) :  DEBUG (runtime:88) - Sending packed state data (590 bytes)
2017-08-02 14:32:30,775 (7f91a8ff9700) :  DEBUG (runtime:924) - Response: [200] str, 1372 bytes
2017-08-02 14:32:30,825 (7f91a8ff9700) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lastfm/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNAo5MDY2czQKZGJpZGkwCnM3CnZlcnNpb25zNDkKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL0dsYXNzJTIwVGlnZXI%40bGFuZz1lbnMxMApwYXJlbnRHVUlEczQKOTA2NXM4CnBhcmVudElEczUKQWxidW1zMTAKbWVkaWFfdHlwZXM2Nwpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtOi8vR2xhc3MlMjBUaWdlci9UaGVuJTIwTm93JTIwTmV4dD9sYW5nPWVuczQKZ3VpZHMzMQpHbGFzcyUyMFRpZ2VyL1RoZW4lMjBOb3clMjBOZXh0czIKaWRyMAo_
2017-08-02 14:32:30,826 (7f91a8ff9700) :  DEBUG (runtime:49) - Received packed state data (564 bytes)
2017-08-02 14:32:30,827 (7f91a8ff9700) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lastfm/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNAo5MDY2czQKZGJpZGkwCnM3CnZlcnNpb25zNDkKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL0dsYXNzJTIwVGlnZXI@bGFuZz1lbnMxMApwYXJlbnRHVUlEczQKOTA2NXM4CnBhcmVudElEczUKQWxidW1zMTAKbWVkaWFfdHlwZXM2Nwpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtOi8vR2xhc3MlMjBUaWdlci9UaGVuJTIwTm93JTIwTmV4dD9sYW5nPWVuczQKZ3VpZHMzMQpHbGFzcyUyMFRpZ2VyL1RoZW4lMjBOb3clMjBOZXh0czIKaWRyMAo_
2017-08-02 14:32:30,828 (7f91a8ff9700) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.lastfm://Glass%20Tiger/Then%20Now%20Next?lang=en
2017-08-02 14:32:30,829 (7f91a8ff9700) :  ERROR (model:205) - Cannot read model from /var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Metadata/Albums/f/2241db7dc0e347dfdaa3250b118ab3328095e04.bundle/Contents/com.plexapp.agents.lastfm
2017-08-02 14:32:30,830 (7f91a8ff9700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/9066/tree'
2017-08-02 14:32:30,851 (7f91a8ff9700) :  DEBUG (networking:161) - Fetching 'http://lastfm-z.plexapp.com/2.0/?method=album.getInfo&artist=glass%20tiger&album=then%20now%20next&autocorrect=1&lang=en&format=json&api_key=d5310352469c2631e5976d0f4a599773' from the HTTP cache
2017-08-02 14:32:30,864 (7f91a8ff9700) :  DEBUG (networking:166) - Requesting 'https://lastfm-img2.akamaized.net/i/u/9e4a7139b3b542faab2a2c928f66e8e1.png'
2017-08-02 14:32:32,791 (7f91a8ff9700) :  DEBUG (networking:198) - Not caching 'https://lastfm-img2.akamaized.net/i/u/9e4a7139b3b542faab2a2c928f66e8e1.png' (content type 'image/png' not cacheable in Agent plug-ins)
2017-08-02 14:32:32,791 (7f91a8ff9700) :  INFO (__init__:539) - Couldn't add release date to album.
2017-08-02 14:32:32,792 (7f91a8ff9700) :  INFO (__init__:548) - Couldn't add genre tags to album.
2017-08-02 14:32:32,796 (7f91a8ff9700) :  DEBUG (networking:166) - Requesting 'http://lastfm-z.plexapp.com/2.0/?method=artist.getTopTracks&artist=glass%20tiger&lang=en&format=json&api_key=d5310352469c2631e5976d0f4a599773'
2017-08-02 14:32:33,404 (7f91a8ff9700) :  DEBUG (model:229) - Serializing to /var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Metadata/Albums/f/2241db7dc0e347dfdaa3250b118ab3328095e04.bundle/Contents/com.plexapp.agents.lastfm/Info.xml
2017-08-02 14:32:33,406 (7f91a8ff9700) :  DEBUG (runtime:88) - Sending packed state data (590 bytes)
2017-08-02 14:32:33,406 (7f91a8ff9700) :  DEBUG (runtime:924) - Response: [200] str, 16 bytes
