Closed dsrtusr88 closed 4 years ago
That log snippet doesn't seem to contain normal match attempt at all. However.... Given it is the same network I am guessing your issue is the same as #494 with older scenes.
That log snippet doesn't seem to contain normal match attempt at all. However.... Given it is the same network I am guessing your issue is the same as #494 with older scenes.
Think it's the Plex log, not the plugin log.
found the plugin log:
2019-12-15 17:33:00,225 (7fcb995eb700) : DEBUG (core:361) - Using the elevated policy
2019-12-15 17:33:00,225 (7fcb995eb700) : DEBUG (core:450) - Starting runtime component.
2019-12-15 17:33:00,226 (7fcb995eb700) : DEBUG (core:450) - Starting caching component.
2019-12-15 17:33:00,227 (7fcb995eb700) : DEBUG (core:450) - Starting data component.
2019-12-15 17:33:00,227 (7fcb995eb700) : DEBUG (core:450) - Starting networking component.
2019-12-15 17:33:00,227 (7fcb995eb700) : DEBUG (networking:261) - Loaded HTTP cookies
2019-12-15 17:33:00,227 (7fcb995eb700) : DEBUG (networking:428) - Setting the default network timeout to 20.0
2019-12-15 17:33:00,228 (7fcb995eb700) : DEBUG (core:450) - Starting localization component.
2019-12-15 17:33:00,228 (7fcb995eb700) : INFO (localization:409) - Setting the default locale to en-us
2019-12-15 17:33:00,228 (7fcb995eb700) : DEBUG (core:450) - Starting messaging component.
2019-12-15 17:33:00,228 (7fcb995eb700) : DEBUG (core:450) - Starting debugging component.
2019-12-15 17:33:00,228 (7fcb995eb700) : DEBUG (core:450) - Starting services component.
2019-12-15 17:33:00,228 (7fcb995eb700) : DEBUG (core:450) - Starting myplex component.
2019-12-15 17:33:00,229 (7fcb995eb700) : DEBUG (core:450) - Starting notifications component.
2019-12-15 17:33:00,229 (7fcb7ba23700) : DEBUG (networking:143) - Requesting 'http://127.0.0.1:32400/system/messaging/clear_events/com.plexapp.agents.phoenixadult'
2019-12-15 17:33:00,307 (7fcb995eb700) : DEBUG (accessor:68) - Creating a new model access point for provider com.plexapp.agents.phoenixadult in namespace 'metadata'
2019-12-15 17:33:00,311 (7fcb995eb700) : DEBUG (networking:143) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/resourceHashes'
2019-12-15 17:33:00,320 (7fcb7ba23700) : DEBUG (services:265) - Plug-in is not daemonized - loading services from system
2019-12-15 17:33:00,320 (7fcb995eb700) : DEBUG (runtime:1117) - Created a thread named 'load_all_services'
2019-12-15 17:33:00,320 (7fcb7ba23700) : DEBUG (networking:143) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_'
2019-12-15 17:33:00,321 (7fcb995eb700) : DEBUG (runtime:1117) - Created a thread named 'get_server_info'
2019-12-15 17:33:00,321 (7fcb995eb700) : DEBUG (core:150) - Finished starting framework core
2019-12-15 17:33:00,321 (7fcb995eb700) : DEBUG (core:560) - Loading plug-in code
2019-12-15 17:33:00,322 (7fcb7b447700) : DEBUG (networking:143) - Requesting 'http://127.0.0.1:32400'
2019-12-15 17:33:00,508 (7fcb7b447700) : DEBUG (core:538) - Machine identifier is 1f4e7afc5fac825fea8f57077aa418c6c1db6ae4
2019-12-15 17:33:00,609 (7fcb7b447700) : DEBUG (core:539) - Server version is 1.18.2.2058-e67a4e892
2019-12-15 17:33:00,664 (7fcb7ba23700) : DEBUG (services:362) - Loaded services
2019-12-15 17:33:00,919 (7fcb7b735700) : DEBUG (services:438) - No shared code to load
2019-12-15 17:33:00,996 (7fcb995eb700) : DEBUG (core:566) - Finished loading plug-in code
2019-12-15 17:33:06,654 (7fcb995eb700) : DEBUG (agentkit:1128) - Creating new agent class called PhoenixAdultAgent
2019-12-15 17:33:06,654 (7fcb995eb700) : DEBUG (agentkit:933) - Updating agent information: [{'media_types': ['Movie'], 'accepts_from': ['com.plexapp.agents.localmedia'], 'fallback_agent': None, 'contributes_to': None, 'languages': ['en'], 'persist_stored_files': True, 'version': 0, 'primary_provider': True, 'prefs': True, 'name': 'PhoenixAdult'}]
2019-12-15 17:33:06,655 (7fcb995eb700) : DEBUG (networking:143) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo2CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0CjIKczMxCmNvbS5wbGV4YXBwLmFnZW50cy5waG9lbml4YWR1bHRzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudG5zMTQKY29udHJpYnV0ZXNfdG9yNQpzOQpsYW5ndWFnZXNiMXMyMApwZXJzaXN0X3N0b3JlZF9maWxlc2kwCnM3CnZlcnNpb25iMXMxNgpwcmltYXJ5X3Byb3ZpZGVyYjFzNQpwcmVmc3MxMgpQaG9lbml4QWR1bHRzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMgplbnIwCg__'
2019-12-15 17:33:06,707 (7fcb995eb700) : INFO (core:611) - Started plug-in
2019-12-15 17:33:06,707 (7fcb995eb700) : DEBUG (socketinterface:160) - Starting socket server
2019-12-15 17:33:06,708 (7fcb995eb700) : DEBUG (runtime:1117) - Created a thread named 'start'
2019-12-15 17:33:06,708 (7fcb995eb700) : INFO (socketinterface:184) - Socket server started on port 46335
2019-12-15 17:33:06,708 (7fcb995eb700) : INFO (pipeinterface:25) - Entering run loop
2019-12-15 17:33:06,708 (7fcb995eb700) : DEBUG (runtime:717) - Handling request GET /:/prefixes
2019-12-15 17:33:06,709 (7fcb995eb700) : DEBUG (runtime:814) - Found route matching /:/prefixes
2019-12-15 17:33:06,709 (7fcb995eb700) : DEBUG (runtime:924) - Response: [200] MediaContainer, 156 bytes
2019-12-15 17:33:06,711 (7fcb7ba23700) : DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.phoenixadult/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IxczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU2CnMxNgo4NzdiMmQ1YWMxYzc2Nzk5czE3Cm9wZW5TdWJ0aXRsZXNIYXNoczIwClBhc3Npb25oZCBPcmd5IFBhcnR5czQKbmFtZXMxNDIKJTJGc2hhcmUlMkZDQUNIRURFVjFfREFUQSUyRk1lZGlhJTJGTWVkaWElMkZBZHVsdCUyRnBhc3Npb25oZCUyRW9yZ3ktcGFydHklMkVbMTclMkUxMCUyRTA0XSUyRVthZHJpYW5hJTJFY2hlY2hpayUyRWFuZCUyRWxhbmElMkVyaG9hZGVzXSUyRW1wNHM4CmZpbGVuYW1lczQwCmMyYWU2YjhhMTljYmI1NmFiOWY1MTUxZTAyNTgxZTdkMThjZGZmY2VzOApwbGV4SGFzaHM3CjI4OTk4NDBzOApkdXJhdGlvbnM1CjMxNjI2czIKaWRyMAo_
2019-12-15 17:33:06,711 (7fcb7ba23700) : DEBUG (runtime:49) - Received packed state data (13340 bytes)
2019-12-15 17:33:06,719 (7fcb7ba23700) : DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.phoenixadult/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IxczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU2CnMxNgo4NzdiMmQ1YWMxYzc2Nzk5czE3Cm9wZW5TdWJ0aXRsZXNIYXNoczIwClBhc3Npb25oZCBPcmd5IFBhcnR5czQKbmFtZXMxNDIKJTJGc2hhcmUlMkZDQUNIRURFVjFfREFUQSUyRk1lZGlhJTJGTWVkaWElMkZBZHVsdCUyRnBhc3Npb25oZCUyRW9yZ3ktcGFydHklMkVbMTclMkUxMCUyRTA0XSUyRVthZHJpYW5hJTJFY2hlY2hpayUyRWFuZCUyRWxhbmElMkVyaG9hZGVzXSUyRW1wNHM4CmZpbGVuYW1lczQwCmMyYWU2YjhhMTljYmI1NmFiOWY1MTUxZTAyNTgxZTdkMThjZGZmY2VzOApwbGV4SGFzaHM3CjI4OTk4NDBzOApkdXJhdGlvbnM1CjMxNjI2czIKaWRyMAo_
2019-12-15 17:33:06,719 (7fcb7ba23700) : INFO (agentkit:957) - Searching for matches for {'openSubtitlesHash': '877b2d5ac1c76799', 'name': 'Passionhd Orgy Party', 'filename': '%2Fshare%2FCACHEDEV1_DATA%2FMedia%2FMedia%2FAdult%2Fpassionhd%2Eorgy-party%2E[17%2E10%2E04]%2E[adriana%2Echechik%2Eand%2Elana%2Erhoades]%2Emp4', 'plexHash': 'c2ae6b8a19cbb56ab9f5151e02581e7d18cdffce', 'duration': '2899840', 'id': '31626'}
2019-12-15 17:33:06,720 (7fcb7ba23700) : DEBUG (networking:143) - Requesting 'http://127.0.0.1:32400/library/metadata/31626/tree'
2019-12-15 17:33:06,723 (7fcb7ba23700) : INFO (__init__:46) - *******MEDIA TITLE****** Passionhd Orgy Party
2019-12-15 17:33:06,723 (7fcb7ba23700) : INFO (__init__:53) - Getting Search Settings for: Passionhd Orgy Party
2019-12-15 17:33:06,723 (7fcb7ba23700) : INFO (PAsearchSites:934) - mediaTitle w/ possible abbrieviation: Passionhd Orgy Party
2019-12-15 17:33:06,728 (7fcb7ba23700) : INFO (PAsearchSites:1124) - mediaTitle w/ possible abbrieviation fixed: Passionhd Orgy Party
2019-12-15 17:33:06,729 (7fcb7ba23700) : INFO (PAsearchSites:1139) - ^^^^^^^ siteID: 306
2019-12-15 17:33:06,729 (7fcb7ba23700) : INFO (PAsearchSites:1141) - ^^^^^^^ Shortening Title
2019-12-15 17:33:06,729 (7fcb7ba23700) : INFO (PAsearchSites:1142) - passionhd vs passionhd
2019-12-15 17:33:06,729 (7fcb7ba23700) : INFO (PAsearchSites:1146) - 1
2019-12-15 17:33:06,729 (7fcb7ba23700) : INFO (PAsearchSites:1201) - 7
2019-12-15 17:33:06,729 (7fcb7ba23700) : INFO (PAsearchSites:1201) - searchTitle (before date processing): Orgy Party
2019-12-15 17:33:06,729 (7fcb7ba23700) : INFO (__init__:63) - Search Title: Orgy Party
2019-12-15 17:33:06,730 (7fcb7ba23700) : INFO (__init__:72) - Orgy%20Party
2019-12-15 17:33:06,730 (7fcb7ba23700) : INFO (networkPornPros:12) - This is repeating
2019-12-15 17:33:06,731 (7fcb7ba23700) : DEBUG (networking:143) - Requesting 'https://passion-hd.com/video/orgy-party'
2019-12-15 17:33:07,337 (7fcb7ba23700) : CRITICAL (agentkit:1014) - Exception in the search function of agent named 'PhoenixAdult', called with keyword arguments {'openSubtitlesHash': '877b2d5ac1c76799', 'name': 'Passionhd Orgy Party', 'filename': '%2Fshare%2FCACHEDEV1_DATA%2FMedia%2FMedia%2FAdult%2Fpassionhd%2Eorgy-party%2E[17%2E10%2E04]%2E[adriana%2Echechik%2Eand%2Elana%2Erhoades]%2Emp4', 'plexHash': 'c2ae6b8a19cbb56ab9f5151e02581e7d18cdffce', 'duration': '2899840', 'id': '31626'} (most recent call last):
File "/share/CACHEDEV1_DATA/.qpkg/PlexMediaServer/Resources/Plug-ins-e67a4e892/Framework.bundle/Contents/Resources/Versions/2/Python/Framework/api/agentkit.py", line 1007, in _search
agent.search(*f_args, **f_kwargs)
File "/share/CACHEDEV1_DATA/.qpkg/PlexMediaServer/Library/Plex Media Server/Plug-ins/PhoenixAdult.bundle/Contents/Code/__init__.py", line 469, in search
results = PAsearchSites.networkPornPros.search(results,encodedTitle,title,searchTitle,siteNum,lang,searchByDateActor,searchDate, searchSiteID)
File "/share/CACHEDEV1_DATA/.qpkg/PlexMediaServer/Library/Plex Media Server/Plug-ins/PhoenixAdult.bundle/Contents/Code/networkPornPros.py", line 20, in search
titleNoFormatting = searchResult.xpath('//h1')[0].text_content()
File "/share/CACHEDEV1_DATA/.qpkg/PlexMediaServer/Resources/Plug-ins-e67a4e892/Framework.bundle/Contents/Resources/Versions/2/Python/Framework/code/sandbox.py", line 108, in <lambda>
_getitem_ = lambda x, y: x.__getitem__(y),
IndexError: list index out of range
2019-12-15 17:33:07,340 (7fcb7ba23700) : DEBUG (runtime:88) - Sending packed state data (13372 bytes)
2019-12-15 17:33:07,340 (7fcb7ba23700) : DEBUG (runtime:924) - Response: [200] str, 16 bytes
2019-12-15 17:33:14,443 (7fcb7ba23700) : DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.phoenixadult/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IxczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGUzCnM1CjMxNjI2czIKaWRzMzcKUGFzc2lvbmhkIG9yZ3ktcGFydHktb3VyLTEwMDB0aC1zY2VuZXM0Cm5hbWVuczQKeWVhcnIwCg__
2019-12-15 17:33:14,443 (7fcb7ba23700) : DEBUG (runtime:49) - Received packed state data (13340 bytes)
2019-12-15 17:33:14,445 (7fcb7ba23700) : DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.phoenixadult/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IxczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGUzCnM1CjMxNjI2czIKaWRzMzcKUGFzc2lvbmhkIG9yZ3ktcGFydHktb3VyLTEwMDB0aC1zY2VuZXM0Cm5hbWVuczQKeWVhcnIwCg__
2019-12-15 17:33:14,445 (7fcb7ba23700) : INFO (agentkit:957) - Searching for matches for {'year': None, 'id': '31626', 'name': 'Passionhd orgy-party-our-1000th-scene'}
2019-12-15 17:33:14,445 (7fcb7ba23700) : DEBUG (networking:143) - Requesting 'http://127.0.0.1:32400/library/metadata/31626/tree'
2019-12-15 17:33:14,448 (7fcb7ba23700) : INFO (__init__:46) - *******MEDIA TITLE****** Passionhd orgy-party-our-1000th-scene
2019-12-15 17:33:14,448 (7fcb7ba23700) : INFO (__init__:53) - Getting Search Settings for: Passionhd orgy-party-our-1000th-scene
2019-12-15 17:33:14,448 (7fcb7ba23700) : INFO (PAsearchSites:934) - mediaTitle w/ possible abbrieviation: Passionhd orgy party our 1000th scene
2019-12-15 17:33:14,453 (7fcb7ba23700) : INFO (PAsearchSites:1124) - mediaTitle w/ possible abbrieviation fixed: Passionhd orgy party our 1000th scene
2019-12-15 17:33:14,454 (7fcb7ba23700) : INFO (PAsearchSites:1139) - ^^^^^^^ siteID: 306
2019-12-15 17:33:14,454 (7fcb7ba23700) : INFO (PAsearchSites:1141) - ^^^^^^^ Shortening Title
2019-12-15 17:33:14,454 (7fcb7ba23700) : INFO (PAsearchSites:1142) - passionhd vs passionhd
2019-12-15 17:33:14,454 (7fcb7ba23700) : INFO (PAsearchSites:1146) - 1
2019-12-15 17:33:14,454 (7fcb7ba23700) : INFO (PAsearchSites:1201) - 7
2019-12-15 17:33:14,455 (7fcb7ba23700) : INFO (PAsearchSites:1201) - searchTitle (before date processing): orgy party our 1000th scene
2019-12-15 17:33:14,455 (7fcb7ba23700) : INFO (__init__:63) - Search Title: orgy party our 1000th scene
2019-12-15 17:33:14,455 (7fcb7ba23700) : INFO (__init__:72) - orgy%20party%20our%201000th%20scene
2019-12-15 17:33:14,456 (7fcb7ba23700) : INFO (networkPornPros:12) - This is repeating
2019-12-15 17:33:14,456 (7fcb7ba23700) : DEBUG (networking:143) - Requesting 'https://passion-hd.com/video/orgy-party-our-1000th-scene'
2019-12-15 17:33:14,764 (7fcb7ba23700) : CRITICAL (agentkit:1014) - Exception in the search function of agent named 'PhoenixAdult', called with keyword arguments {'year': None, 'id': '31626', 'name': 'Passionhd orgy-party-our-1000th-scene'} (most recent call last):
File "/share/CACHEDEV1_DATA/.qpkg/PlexMediaServer/Resources/Plug-ins-e67a4e892/Framework.bundle/Contents/Resources/Versions/2/Python/Framework/api/agentkit.py", line 1007, in _search
agent.search(*f_args, **f_kwargs)
File "/share/CACHEDEV1_DATA/.qpkg/PlexMediaServer/Library/Plex Media Server/Plug-ins/PhoenixAdult.bundle/Contents/Code/__init__.py", line 469, in search
results = PAsearchSites.networkPornPros.search(results,encodedTitle,title,searchTitle,siteNum,lang,searchByDateActor,searchDate, searchSiteID)
File "/share/CACHEDEV1_DATA/.qpkg/PlexMediaServer/Library/Plex Media Server/Plug-ins/PhoenixAdult.bundle/Contents/Code/networkPornPros.py", line 22, in search
releaseDate = parse(searchResult.xpath('//div[@class="d-inline d-lg-block mb-1"]/span')[0].text_content().strip()).strftime('%Y-%m-%d')
File "/share/CACHEDEV1_DATA/.qpkg/PlexMediaServer/Resources/Plug-ins-e67a4e892/Framework.bundle/Contents/Resources/Versions/2/Python/Framework/code/sandbox.py", line 108, in <lambda>
_getitem_ = lambda x, y: x.__getitem__(y),
IndexError: list index out of range
2019-12-15 17:33:14,767 (7fcb7ba23700) : DEBUG (runtime:88) - Sending packed state data (13372 bytes)
2019-12-15 17:33:14,767 (7fcb7ba23700) : DEBUG (runtime:924) - Response: [200] str, 16 bytes
That log snippet doesn't seem to contain normal match attempt at all. However.... Given it is the same network I am guessing your issue is the same as #494 with older scenes.
I think you're right, the error looks the same. That's really strange though because it's finding the exact url needed to do the query.
Looks like PornPros have removed the date from their subsites, so the agent errors out when it tries to scrape it.
Looks like PornPros have removed the date from their subsites, so the agent errors out when it tries to scrape it.
What parts of the code could I comment out and run over the files I need? If it's just date, that's an easy fix in Plex.
noticing passionhd has issues finding scenes from more than a few years ago.