2019-05-02 06:55:20,744 (14868adc1b80) :  INFO (core:349) - Starting framework core - Version: 2.6.3, Build: 4610c6e (Mon Apr 22 17:59:55 UTC 2019)
2019-05-02 06:55:20,744 (14868adc1b80) :  DEBUG (core:361) - Using the elevated policy
2019-05-02 06:55:20,745 (14868adc1b80) :  DEBUG (core:450) - Starting runtime component.
2019-05-02 06:55:20,746 (14868adc1b80) :  DEBUG (core:450) - Starting caching component.
2019-05-02 06:55:20,746 (14868adc1b80) :  DEBUG (core:450) - Starting data component.
2019-05-02 06:55:20,746 (14868adc1b80) :  DEBUG (core:450) - Starting networking component.
2019-05-02 06:55:20,747 (14868adc1b80) :  DEBUG (networking:284) - Loaded HTTP cookies
2019-05-02 06:55:20,747 (14868adc1b80) :  DEBUG (networking:452) - Setting the default network timeout to 20.0
2019-05-02 06:55:20,748 (14868adc1b80) :  DEBUG (core:450) - Starting localization component.
2019-05-02 06:55:20,748 (14868adc1b80) :  INFO (localization:409) - Setting the default locale to en-us
2019-05-02 06:55:20,748 (14868adc1b80) :  DEBUG (core:450) - Starting messaging component.
2019-05-02 06:55:20,749 (14868adc1b80) :  DEBUG (core:450) - Starting debugging component.
2019-05-02 06:55:20,749 (14868adc1b80) :  DEBUG (core:450) - Starting services component.
2019-05-02 06:55:20,749 (14868adc1b80) :  DEBUG (core:450) - Starting myplex component.
2019-05-02 06:55:20,749 (14868224e700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/system/messaging/clear_events/com.plexapp.agents.lyricfind'
2019-05-02 06:55:20,749 (14868adc1b80) :  DEBUG (core:450) - Starting notifications component.
2019-05-02 06:55:20,851 (14868adc1b80) :  DEBUG (accessor:68) - Creating a new model access point for provider com.plexapp.agents.lyricfind in namespace 'metadata'
2019-05-02 06:55:20,855 (14868adc1b80) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/resourceHashes'
2019-05-02 06:55:20,866 (14868224e700) :  DEBUG (services:265) - Plug-in is not daemonized - loading services from system
2019-05-02 06:55:20,866 (14868adc1b80) :  DEBUG (runtime:1117) - Created a thread named 'load_all_services'
2019-05-02 06:55:20,866 (14868224e700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_'
2019-05-02 06:55:20,867 (14868adc1b80) :  DEBUG (runtime:1117) - Created a thread named 'get_server_info'
2019-05-02 06:55:20,867 (14868adc1b80) :  DEBUG (core:150) - Finished starting framework core
2019-05-02 06:55:20,867 (148681ccc700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400'
2019-05-02 06:55:20,867 (14868adc1b80) :  DEBUG (core:560) - Loading plug-in code
2019-05-02 06:55:20,893 (14868adc1b80) :  DEBUG (core:566) - Finished loading plug-in code
2019-05-02 06:55:20,893 (14868adc1b80) :  DEBUG (agentkit:1115) - Creating new agent class called LyricFindAlbumAgent
2019-05-02 06:55:20,894 (14868adc1b80) :  DEBUG (agentkit:933) - Updating agent information: [{'media_types': ['Album'], 'accepts_from': None, 'fallback_agent': None, 'contributes_to': ['com.plexapp.agents.plexmusic', 'com.plexapp.agents.localmedia', 'com.plexapp.agents.lastfm'], 'languages': ['xn'], 'persist_stored_files': True, 'version': 0, 'primary_provider': False, 'prefs': False, 'name': 'LyricFind'}]
2019-05-02 06:55:20,895 (14868adc1b80) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo2CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0CjIKczI4CmNvbS5wbGV4YXBwLmFnZW50cy5seXJpY2ZpbmRzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNuczEyCmFjY2VwdHNfZnJvbW5zMTQKZmFsbGJhY2tfYWdlbnRyNApzMTQKY29udHJpYnV0ZXNfdG9yNQpzOQpsYW5ndWFnZXNiMXMyMApwZXJzaXN0X3N0b3JlZF9maWxlc2kwCnM3CnZlcnNpb25iMHMxNgpwcmltYXJ5X3Byb3ZpZGVyYjBzNQpwcmVmc3M5Ckx5cmljRmluZHM0Cm5hbWUxCnM1CkFsYnVtMwpzMjgKY29tLnBsZXhhcHAuYWdlbnRzLnBsZXhtdXNpY3MyOQpjb20ucGxleGFwcC5hZ2VudHMubG9jYWxtZWRpYXMyNQpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtMQpzMgp4bnIwCg__'
2019-05-02 06:55:20,897 (148681ccc700) :  DEBUG (core:538) - Machine identifier is aafb04cbaf3c88efa62cfdecf86b1c6bd9aa279a
2019-05-02 06:55:20,898 (148681ccc700) :  DEBUG (core:539) - Server version is 1.15.5.994-4610c6e8d
2019-05-02 06:55:20,953 (14868224e700) :  DEBUG (services:362) - Loaded services
2019-05-02 06:55:20,956 (148681ecd700) :  DEBUG (services:438) - No shared code to load
2019-05-02 06:55:20,957 (14868adc1b80) :  INFO (core:611) - Started plug-in
2019-05-02 06:55:20,957 (14868adc1b80) :  DEBUG (socketinterface:160) - Starting socket server
2019-05-02 06:55:20,958 (14868adc1b80) :  DEBUG (runtime:1117) - Created a thread named 'start'
2019-05-02 06:55:20,958 (14868adc1b80) :  INFO (socketinterface:184) - Socket server started on port 33447
2019-05-02 06:55:20,958 (14868adc1b80) :  INFO (pipeinterface:25) - Entering run loop
2019-05-02 06:55:20,958 (14868adc1b80) :  DEBUG (runtime:717) - Handling request GET /:/prefixes
2019-05-02 06:55:20,959 (14868adc1b80) :  DEBUG (runtime:814) - Found route matching /:/prefixes
2019-05-02 06:55:20,960 (14868adc1b80) :  DEBUG (runtime:924) - Response: [200] MediaContainer, 153 bytes
2019-05-02 06:55:20,961 (14868224e700) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnM1CjY0ODE2czgKcGFyZW50SURzMTE0CmNvbS5wbGV4YXBwLmFnZW50cy5wbGV4bXVzaWM6Ly9ncmFjZW5vdGUvYWxidW0vMDU0MTVERjIzNTc2RTZFNy84OTY5ODQ4MDctNDE1REYyNEFCNzIyNkI0MDYxMjRBODExQUVENDIwMTE%40bGFuZz1lbnM0Cmd1aWRiMXM1CmZvcmNlczI4CmNvbS5wbGV4YXBwLmFnZW50cy5wbGV4bXVzaWNzMTMKcHJpbWFyeV9hZ2VudHM2CjEzNDU5MnMyCmlkcjAK
2019-05-02 06:55:20,971 (14868224e700) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnM1CjY0ODE2czgKcGFyZW50SURzMTE0CmNvbS5wbGV4YXBwLmFnZW50cy5wbGV4bXVzaWM6Ly9ncmFjZW5vdGUvYWxidW0vMDU0MTVERjIzNTc2RTZFNy84OTY5ODQ4MDctNDE1REYyNEFCNzIyNkI0MDYxMjRBODExQUVENDIwMTE@bGFuZz1lbnM0Cmd1aWRiMXM1CmZvcmNlczI4CmNvbS5wbGV4YXBwLmFnZW50cy5wbGV4bXVzaWNzMTMKcHJpbWFyeV9hZ2VudHM2CjEzNDU5MnMyCmlkcjAK
2019-05-02 06:55:20,972 (14868224e700) :  INFO (agentkit:957) - Searching for matches for {'id': '134592', 'guid': 'com.plexapp.agents.plexmusic://gracenote/album/05415DF23576E6E7/896984807-415DF24AB7226B406124A811AED42011?lang=en', 'force': True, 'primary_agent': 'com.plexapp.agents.plexmusic', 'parentID': '64816'}
2019-05-02 06:55:20,972 (14868224e700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/134592/tree'
2019-05-02 06:55:20,977 (14868224e700) :  DEBUG (accessor:68) - Creating a new read-only model access point for provider com.plexapp.agents.plexmusic in namespace 'metadata'
2019-05-02 06:55:20,980 (14868224e700) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.plexmusic://gracenote/album/05415DF23576E6E7/896984807-415DF24AB7226B406124A811AED42011?lang=en
2019-05-02 06:55:20,981 (14868224e700) :  DEBUG (model:234) - Deserializing from /config/Library/Application Support/Plex Media Server/Metadata/Albums/c/902c85879518e1f97723c5e7f20b46c7137d6ae.bundle/Contents/com.plexapp.agents.plexmusic/Info.xml
2019-05-02 06:55:20,989 (14868224e700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/64816/tree'
2019-05-02 06:55:21,011 (14868224e700) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2019-05-02 06:55:21,011 (14868224e700) :  DEBUG (runtime:924) - Response: [200] str, 300 bytes
2019-05-02 06:55:21,016 (14868224e700) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNgoxMzQ1OTJzNApkYmlkaTAKczcKdmVyc2lvbnM3Mgpjb20ucGxleGFwcC5hZ2VudHMucGxleG11c2ljOi8vZ3JhY2Vub3RlL2FydGlzdC8wNTY4QzJGNTA2Q0ZBRENGP2xhbmc9ZW5zMTAKcGFyZW50R1VJRHM1CjY0ODE2czgKcGFyZW50SURzNQpBbGJ1bXMxMAptZWRpYV90eXBlczExNApjb20ucGxleGFwcC5hZ2VudHMucGxleG11c2ljOi8vZ3JhY2Vub3RlL2FsYnVtLzA1NDE1REYyMzU3NkU2RTcvODk2OTg0ODA3LTQxNURGMjRBQjcyMjZCNDA2MTI0QTgxMUFFRDQyMDExP2xhbmc9ZW5zNApndWlkczQKbnVsbHMyCmlkcjAK
2019-05-02 06:55:21,017 (14868224e700) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNgoxMzQ1OTJzNApkYmlkaTAKczcKdmVyc2lvbnM3Mgpjb20ucGxleGFwcC5hZ2VudHMucGxleG11c2ljOi8vZ3JhY2Vub3RlL2FydGlzdC8wNTY4QzJGNTA2Q0ZBRENGP2xhbmc9ZW5zMTAKcGFyZW50R1VJRHM1CjY0ODE2czgKcGFyZW50SURzNQpBbGJ1bXMxMAptZWRpYV90eXBlczExNApjb20ucGxleGFwcC5hZ2VudHMucGxleG11c2ljOi8vZ3JhY2Vub3RlL2FsYnVtLzA1NDE1REYyMzU3NkU2RTcvODk2OTg0ODA3LTQxNURGMjRBQjcyMjZCNDA2MTI0QTgxMUFFRDQyMDExP2xhbmc9ZW5zNApndWlkczQKbnVsbHMyCmlkcjAK
2019-05-02 06:55:21,017 (14868224e700) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.plexmusic://gracenote/album/05415DF23576E6E7/896984807-415DF24AB7226B406124A811AED42011?lang=en
2019-05-02 06:55:21,017 (14868224e700) :  ERROR (model:205) - Cannot read model from /config/Library/Application Support/Plex Media Server/Metadata/Albums/c/902c85879518e1f97723c5e7f20b46c7137d6ae.bundle/Contents/com.plexapp.agents.lyricfind
2019-05-02 06:55:21,017 (14868224e700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/134592/tree'
2019-05-02 06:55:21,051 (14868224e700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Amon%20Amarth&album=Berserker'
2019-05-02 06:55:21,488 (14868224e700) :  DEBUG (threadkit:234) - Starting a parallel task set named MatchLyrics with 12 tasks
2019-05-02 06:55:21,488 (1486819cb700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Amon%20Amarth&track=Fafner%27s%20Gold'
2019-05-02 06:55:21,489 (1486817ca700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Amon%20Amarth&track=Shield%20Wall'
2019-05-02 06:55:21,490 (148681ccc700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Amon%20Amarth&track=Crack%20the%20Sky'
2019-05-02 06:55:21,492 (1486815c9700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Amon%20Amarth&track=Mj%F6lner%2C%20Hammer%20of%20Thor'
2019-05-02 06:55:21,910 (1486819cb700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Amon%20Amarth&track=Raven%27s%20Flight'
2019-05-02 06:55:21,912 (148681ccc700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Amon%20Amarth&track=Valkyria'
2019-05-02 06:55:21,917 (1486815c9700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Amon%20Amarth&track=Ironside'
2019-05-02 06:55:21,919 (1486817ca700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Amon%20Amarth&track=The%20Berserker%20at%20Stamford%20Bridge'
2019-05-02 06:55:22,206 (1486817ca700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Amon%20Amarth&track=When%20Once%20Again%20We%20Can%20Set%20Our%20Sails'
2019-05-02 06:55:22,324 (148681ccc700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Amon%20Amarth&track=Skoll%20and%20Hati'
2019-05-02 06:55:22,338 (1486819cb700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Amon%20Amarth&track=Wings%20of%20Eagles'
2019-05-02 06:55:22,343 (1486815c9700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Amon%20Amarth&track=Into%20the%20Dark'
2019-05-02 06:55:22,784 (14868224e700) :  DEBUG (threadkit:244) - Parallel task set MatchLyrics ended
2019-05-02 06:55:22,794 (14868224e700) :  DEBUG (model:229) - Serializing to /config/Library/Application Support/Plex Media Server/Metadata/Albums/c/902c85879518e1f97723c5e7f20b46c7137d6ae.bundle/Contents/com.plexapp.agents.lyricfind/Info.xml
2019-05-02 06:55:22,795 (14868224e700) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2019-05-02 06:55:22,795 (14868224e700) :  DEBUG (runtime:924) - Response: [200] str, 16 bytes
