psyciknz / NextPVR.bundle

Plex Channel for viewing NextPVR content
29 stars 4 forks source link

CRITICAL (core:572) - Exception in thread named 'SocketListen' #6

Closed briandunaway closed 7 years ago

briandunaway commented 9 years ago

UPDATE: I stand corrected. I changed the redirectme url in the channel settings to localhost and now it functions.

Hey, First let me say "Thank you for your hard work and I look forward to using your channel soon."

Secondly, when I start the channel in Plex I am able to see What's New, Live, Recordings and Upcoming links but when I click on them I am presented with a blank screen in all instances.

Here is a dump from the log files:

2015-02-19 13:53:16,026 (4e80) : INFO (core:347) - Starting framework core - Version: 2.5.0, Build: a51033b (Mon Jul 28 12:19:14 UTC 2014) 2015-02-19 13:53:16,026 (4e80) : DEBUG (core:359) - Using the elevated policy 2015-02-19 13:53:16,026 (4e80) : DEBUG (core:448) - Starting runtime component. 2015-02-19 13:53:16,029 (4e80) : DEBUG (core:448) - Starting caching component. 2015-02-19 13:53:16,029 (4e80) : DEBUG (core:448) - Starting data component. 2015-02-19 13:53:16,029 (4e80) : DEBUG (core:448) - Starting networking component. 2015-02-19 13:53:16,030 (4e80) : DEBUG (networking:288) - Loaded HTTP cookies 2015-02-19 13:53:16,032 (4e80) : DEBUG (caching:108) - No info file found, trashing the cache folder 2015-02-19 13:53:16,038 (4e80) : DEBUG (networking:458) - Setting the default network timeout to 20.0 2015-02-19 13:53:16,038 (4e80) : DEBUG (core:448) - Starting localization component. 2015-02-19 13:53:16,038 (4e80) : INFO (localization:408) - Setting the default locale to en-us 2015-02-19 13:53:16,039 (4e80) : DEBUG (localization:426) - Loaded en strings 2015-02-19 13:53:16,039 (4e80) : DEBUG (core:448) - Starting messaging component. 2015-02-19 13:53:16,040 (4e80) : DEBUG (core:448) - Starting debugging component. 2015-02-19 13:53:16,040 (4e80) : DEBUG (core:448) - Starting services component. 2015-02-19 13:53:16,042 (4e80) : DEBUG (core:448) - Starting myplex component. 2015-02-19 13:53:16,042 (3088) : DEBUG (networking:172) - Requesting 'http://127.0.0.1:32400/system/messaging/clear_events/com.dcSoftware.plexapp.nextpvr' 2015-02-19 13:53:16,042 (4e80) : DEBUG (core:448) - Starting notifications component. 2015-02-19 13:53:16,181 (4e80) : DEBUG (accessor:68) - Creating a new model access point for provider com.dcSoftware.plexapp.nextpvr in namespace 'metadata' 2015-02-19 13:53:16,187 (4e80) : DEBUG (networking:172) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/resourceHashes' 2015-02-19 13:53:16,724 (4e80) : DEBUG (runtime:1111) - Created a thread named 'load_all_services' 2015-02-19 13:53:16,724 (4680) : DEBUG (services:265) - Plug-in is not daemonized - loading services from system 2015-02-19 13:53:16,727 (4e80) : DEBUG (runtime:1111) - Created a thread named 'get_serverinfo' 2015-02-19 13:53:16,729 (4e80) : DEBUG (core:150) - Finished starting framework core 2015-02-19 13:53:16,729 (50d4) : DEBUG (networking:172) - Requesting 'http://127.0.0.1:32400' 2015-02-19 13:53:16,730 (4680) : DEBUG (networking:172) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM/Y2VyZWFsMQoxCmxpc3QKMApyMAo/Y2VyZWFsMQoxCmRpY3QKMApyMAo' 2015-02-19 13:53:16,730 (4e80) : DEBUG (core:558) - Loading plug-in code 2015-02-19 13:53:16,746 (50d4) : DEBUG (core:536) - Machine identifier is 77cce9701dbc0430876c0177d50d094d326849e3 2015-02-19 13:53:16,746 (50d4) : DEBUG (core:537) - Server version is 0.9.11.7.803-87d0708 2015-02-19 13:53:17,247 (4680) : DEBUG (services:362) - Loaded services 2015-02-19 13:53:17,259 (37c4) : DEBUG (services:438) - No shared code to load 2015-02-19 13:53:17,312 (4e80) : DEBUG (core:564) - Finished loading plug-in code 2015-02-19 13:53:17,352 (4e80) : DEBUG (preferences:256) - Loaded preferences from DefaultPrefs.json 2015-02-19 13:53:17,354 (4e80) : DEBUG (preferences:178) - Loaded the user preferences for com.dcSoftware.plexapp.nextpvr 2015-02-19 13:53:17,355 (4e80) : DEBUG (runtime:640) - Adding a prefix handler for 'NextPVR' ('/video/nextpvr') 2015-02-19 13:53:17,358 (4e80) : INFO (logkit:16) - NextPVR Started 2015-02-19 13:53:17,358 (4e80) : INFO (logkit:16) - URL set to http://MYSERVERNAME.redirectme.net:8866/ 2015-02-19 13:53:17,359 (4e80) : DEBUG (networking:172) - Requesting 'http://localhost:32400' 2015-02-19 13:53:18,368 (4e80) : ERROR (networking:223) - Error opening URL 'http://localhost:32400' 2015-02-19 13:53:18,368 (4e80) : DEBUG (runtime:1111) - Created a thread named 'SocketListen' 2015-02-19 13:53:18,371 (4e80) : INFO (logkit:16) - ValidatePrefs: PVR URL = http://MYSERVERNAME.redirectme.net:8866/ 2015-02-19 13:53:18,372 (4e80) : INFO (core:609) - Started plug-in 2015-02-19 13:53:18,372 (4e80) : DEBUG (socketinterface:160) - Starting socket server 2015-02-19 13:53:18,375 (5144) : CRITICAL (core:572) - Exception in thread named 'SocketListen' (most recent call last): File "C:\Users\MYUSERNAME\AppData\Local\Plex Media Server\Plug-ins\Framework.bundle\Contents\Resources\Versions\2\Python\Framework\components\runtime.py", line 1120, in _start_thread f(_args, _kwargs) File "C:\Users\MYUSERNAME\AppData\Local\Plex Media Server\Plug-ins\NextPVR.bundle\Contents\Codeinit.py", line 509, in SocketListen ws = websocket.create_connection('ws://localhost:32400/:/websockets/notifications') File "C:\Users\MYUSERNAME\AppData\Local\Plex Media Server\Plug-ins\NextPVR.bundle\Contents\Libraries\Shared\websocket.py", line 178, in create_connection websock.connect(url, _options) File "C:\Users\MYUSERNAME\AppData\Local\Plex Media Server\Plug-ins\NextPVR.bundle\Contents\Libraries\Shared\websocket.py", line 424, in connect self._handshake(hostname, port, resource, *_options) File "C:\Users\MYUSERNAME\AppData\Local\Plex Media Server\Plug-ins\NextPVR.bundle\Contents\Libraries\Shared\websocket.py", line 462, in _handshake raise WebSocketException("Handshake Status %d" % status) WebSocketException: Handshake Status 401

2015-02-19 13:53:18,375 (4e80) : DEBUG (runtime:1111) - Created a thread named 'start' 2015-02-19 13:53:18,377 (4e80) : INFO (socketinterface:184) - Socket server started on port 25542 2015-02-19 13:53:18,378 (4e80) : INFO (pipeinterface:25) - Entering run loop 2015-02-19 13:53:18,378 (4e80) : DEBUG (runtime:717) - Handling request GET /:/prefixes 2015-02-19 13:53:18,378 (4e80) : DEBUG (runtime:814) - Found route matching /:/prefixes 2015-02-19 13:53:18,381 (4e80) : DEBUG (runtime:918) - Response: [200] MediaContainer, 458 bytes 2015-02-19 13:53:18,459 (3458) : DEBUG (runtime:717) - Handling request GET /video/nextpvr 2015-02-19 13:53:18,463 (3458) : DEBUG (runtime:814) - Found route matching /video/nextpvr 2015-02-19 13:53:18,463 (3458) : INFO (logkit:16) - Client {'Origin': 'chrome-extension://fpniocchabmgenibceglhnfeimmdhdfm', 'Via': '1.1 tinyproxy (tinyproxy/1.8.3)', 'Accept-Language': 'en', 'Accept-Encoding': 'gzip, deflate, sdch', 'X-Plex-Token': 'LyK8bxqvzsrUrsXTmiad', 'X-Plex-Platform-Version': '40.0', 'X-Plex-Client-Identifier': 'fe2z3v6q94ibe29', 'Connection': 'close', 'X-Plex-Platform': 'Chrome', 'User-Agent': 'Mozilla/5.0 (Windows NT 10.0; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/40.0.2214.111 Safari/537.36', 'X-Plex-Device': 'Windows', 'Host': '69.59.90.152:32400', 'Accept': 'text/plain, /; q=0.01', 'X-Plex-Product': 'Plex Web', 'X-Plex-Username': 'MYUSERNAME', 'If-None-Match': '"98f68e9e92e3cad7b9c3a88c52fecda8074681d0"', 'X-Plex-Device-Name': 'Plex Web (Chrome)', 'X-Plex-Version': '2.3.20'} 2015-02-19 13:53:18,463 (3458) : INFO (logkit:16) - Client Details: ident:fe2z3v6q94ibe29 2015-02-19 13:53:18,464 (3458) : INFO (logkit:16) - MainMenu: Adding What's New Menu 2015-02-19 13:53:18,464 (3458) : INFO (logkit:16) - MainMenu: Adding Live Menu 2015-02-19 13:53:18,464 (3458) : INFO (logkit:16) - MainMenu: Adding Recordings Menu 2015-02-19 13:53:18,466 (3458) : INFO (logkit:16) - MainMenu: Adding Pending Recordings Menu 2015-02-19 13:53:18,467 (3458) : INFO (logkit:16) - MainMenu: URL set to http://MYSERVERNAME.redirectme.net:8866/ 2015-02-19 13:53:18,467 (3458) : DEBUG (base:125) - Checking if com.dcSoftware.plexapp.nextpvr is broken 2015-02-19 13:53:18,469 (3458) : DEBUG (networking:172) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X1N0b3JlU2VydmljZTpJc0NoYW5uZWxCcm9rZW4_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMQpzMzAKY29tLmRjU29mdHdhcmUucGxleGFwcC5uZXh0cHZyczEwCmlkZW50aWZpZXJyMAo_' 2015-02-19 13:53:18,483 (3458) : DEBUG (runtime:106) - Sending packed state data (111 bytes) 2015-02-19 13:53:18,483 (3458) : DEBUG (runtime:918) - Response: [200] MediaContainer, 800 bytes

psyciknz commented 9 years ago

What version of server are you on?

As far as I know those sockets being listened to are the event ones for notifying the channel that a video is being watched (so I can let nextpvr know this)

On 20 February 2015 at 08:04, guyinabush notifications@github.com wrote:

Hey, First let me say "Thank you for your hard work and I look forward to using your channel soon."

Secondly, when I start the channel in Plex I am able to see What's New, Live, Recordings and Upcoming links but when I click on them I am presented with a blank screen in all instances.

Here is a dump from the log files:

2015-02-19 13:53:16,026 (4e80) : INFO (core:347) - Starting framework core

  • Version: 2.5.0, Build: a51033b (Mon Jul 28 12:19:14 UTC 2014) 2015-02-19 13:53:16,026 (4e80) : DEBUG (core:359) - Using the elevated policy 2015-02-19 13:53:16,026 (4e80) : DEBUG (core:448) - Starting runtime component. 2015-02-19 13:53:16,029 (4e80) : DEBUG (core:448) - Starting caching component. 2015-02-19 13:53:16,029 (4e80) : DEBUG (core:448) - Starting data component. 2015-02-19 13:53:16,029 (4e80) : DEBUG (core:448) - Starting networking component. 2015-02-19 13:53:16,030 (4e80) : DEBUG (networking:288) - Loaded HTTP cookies 2015-02-19 13:53:16,032 (4e80) : DEBUG (caching:108) - No info file found, trashing the cache folder 2015-02-19 13:53:16,038 (4e80) : DEBUG (networking:458) - Setting the default network timeout to 20.0 2015-02-19 13:53:16,038 (4e80) : DEBUG (core:448) - Starting localization component. 2015-02-19 13:53:16,038 (4e80) : INFO (localization:408) - Setting the default locale to en-us 2015-02-19 13:53:16,039 (4e80) : DEBUG (localization:426) - Loaded en strings 2015-02-19 13:53:16,039 (4e80) : DEBUG (core:448) - Starting messaging component. 2015-02-19 13:53:16,040 (4e80) : DEBUG (core:448) - Starting debugging component. 2015-02-19 13:53:16,040 (4e80) : DEBUG (core:448) - Starting services component. 2015-02-19 13:53:16,042 (4e80) : DEBUG (core:448) - Starting myplex component. 2015-02-19 13:53:16,042 (3088) : DEBUG (networking:172) - Requesting ' http://127.0.0.1:32400/system/messaging/clear_events/com.dcSoftware.plexapp.nextpvr ' 2015-02-19 13:53:16,042 (4e80) : DEBUG (core:448) - Starting notifications component. 2015-02-19 13:53:16,181 (4e80) : DEBUG (accessor:68) - Creating a new model access point for provider com.dcSoftware.plexapp.nextpvr in namespace 'metadata' 2015-02-19 13:53:16,187 (4e80) : DEBUG (networking:172) - Requesting ' http://127.0.0.1:32400/:/plugins/com.plexapp.system/resourceHashes' 2015-02-19 13:53:16,724 (4e80) : DEBUG (runtime:1111) - Created a thread named 'load_all_services' 2015-02-19 13:53:16,724 (4680) : DEBUG (services:265) - Plug-in is not daemonized - loading services from system 2015-02-19 13:53:16,727 (4e80) : DEBUG (runtime:1111) - Created a thread named 'get_serverinfo' 2015-02-19 13:53:16,729 (4e80) : DEBUG (core:150) - Finished starting framework core 2015-02-19 13:53:16,729 (50d4) : DEBUG (networking:172) - Requesting ' http://127.0.0.1:32400' 2015-02-19 13:53:16,730 (4680) : DEBUG (networking:172) - Requesting ' http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM/Y2VyZWFsMQoxCmxpc3QKMApyMAo/Y2VyZWFsMQoxCmRpY3QKMApyMAo ' 2015-02-19 13:53:16,730 (4e80) : DEBUG (core:558) - Loading plug-in code 2015-02-19 13:53:16,746 (50d4) : DEBUG (core:536) - Machine identifier is 77cce9701dbc0430876c0177d50d094d326849e3 2015-02-19 13:53:16,746 (50d4) : DEBUG (core:537) - Server version is 0.9.11.7.803-87d0708 2015-02-19 13:53:17,247 (4680) : DEBUG (services:362) - Loaded services 2015-02-19 13:53:17,259 (37c4) : DEBUG (services:438) - No shared code to load 2015-02-19 13:53:17,312 (4e80) : DEBUG (core:564) - Finished loading plug-in code 2015-02-19 13:53:17,352 (4e80) : DEBUG (preferences:256) - Loaded preferences from DefaultPrefs.json 2015-02-19 13:53:17,354 (4e80) : DEBUG (preferences:178) - Loaded the user preferences for com.dcSoftware.plexapp.nextpvr 2015-02-19 13:53:17,355 (4e80) : DEBUG (runtime:640) - Adding a prefix handler for 'NextPVR' ('/video/nextpvr') 2015-02-19 13:53:17,358 (4e80) : INFO (logkit:16) - NextPVR Started 2015-02-19 13:53:17,358 (4e80) : INFO (logkit:16) - URL set to http://MYSERVERNAME.redirectme.net:8866/ 2015-02-19 13:53:17,359 (4e80) : DEBUG (networking:172) - Requesting ' http://localhost:32400' 2015-02-19 13:53:18,368 (4e80) : ERROR (networking:223) - Error opening URL 'http://localhost:32400' 2015-02-19 13:53:18,368 (4e80) : DEBUG (runtime:1111) - Created a thread named 'SocketListen' 2015-02-19 13:53:18,371 (4e80) : INFO (logkit:16) - ValidatePrefs: PVR URL = http://MYSERVERNAME.redirectme.net:8866/ 2015-02-19 13:53:18,372 (4e80) : INFO (core:609) - Started plug-in 2015-02-19 13:53:18,372 (4e80) : DEBUG (socketinterface:160) - Starting socket server 2015-02-19 13:53:18,375 (5144) : CRITICAL (core:572) - Exception in thread named 'SocketListen' (most recent call last): File "C:\Users\MYUSERNAME\AppData\Local\Plex Media Server\Plug-ins\Framework.bundle\Contents\Resources\Versions\2\Python\Framework\components\runtime.py", line 1120, in

_start_thread f(args, kwargs) File "C:\Users\MYUSERNAME\AppData\Local\Plex Media Server\Plug-ins\NextPVR.bundle\Contents\Codeinit.py", line 509, in SocketListen ws = websocket.create_connection('ws://localhost:32400/:/websockets/notifications') File "C:\Users\MYUSERNAME\AppData\Local\Plex Media Server\Plug-ins\NextPVR.bundle\Contents\Libraries\Shared\websocket.py", line 178, in create_connection websock.connect(url,

_options) File "C:\Users\MYUSERNAME\AppData\Local\Plex Media Server\Plug-ins\NextPVR.bundle\Contents\Libraries\Shared\websocket.py", line 424, in connect self._handshake(hostname, port, resource, *_options) File "C:\Users\MYUSERNAME\AppData\Local\Plex Media Server\Plug-ins\NextPVR.bundle\Contents\Libraries\Shared\websocket.py", line 462, in _handshake raise WebSocketException("Handshake Status %d" % status) WebSocketException: Handshake Status 401

2015-02-19 13:53:18,375 (4e80) : DEBUG (runtime:1111) - Created a thread named 'start' 2015-02-19 13:53:18,377 (4e80) : INFO (socketinterface:184) - Socket server started on port 25542 2015-02-19 13:53:18,378 (4e80) : INFO (pipeinterface:25) - Entering run loop 2015-02-19 13:53:18,378 (4e80) : DEBUG (runtime:717) - Handling request GET /:/prefixes 2015-02-19 13:53:18,378 (4e80) : DEBUG (runtime:814) - Found route matching /:/prefixes 2015-02-19 13:53:18,381 (4e80) : DEBUG (runtime:918) - Response: [200] MediaContainer, 458 bytes 2015-02-19 13:53:18,459 (3458) : DEBUG (runtime:717) - Handling request GET /video/nextpvr 2015-02-19 13:53:18,463 (3458) : DEBUG (runtime:814) - Found route matching /video/nextpvr 2015-02-19 13:53:18,463 (3458) : INFO (logkit:16) - Client {'Origin': 'chrome-extension://fpniocchabmgenibceglhnfeimmdhdfm', 'Via': '1.1 tinyproxy (tinyproxy/1.8.3)', 'Accept-Language': 'en', 'Accept-Encoding': 'gzip, deflate, sdch', 'X-Plex-Token': 'LyK8bxqvzsrUrsXTmiad', 'X-Plex-Platform-Version': '40.0', 'X-Plex-Client-Identifier': 'fe2z3v6q94ibe29', 'Connection': 'close', 'X-Plex-Platform': 'Chrome', 'User-Agent': 'Mozilla/5.0 (Windows NT 10.0; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/40.0.2214.111 Safari/537.36', 'X-Plex-Device': 'Windows', 'Host': '69.59.90.152:32400', 'Accept': 'text/plain, /; q=0.01', 'X-Plex-Product': 'Plex Web', 'X-Plex-Username': 'MYUSERNAME', 'If-None-Match': '"98f68e9e92e3cad7b9c3a88c52fecda8074681d0"', 'X-Plex-Device-Name': 'Plex Web (Chrome)', 'X-Plex-Version': '2.3.20'} 2015-02-19 13:53:18,463 (3458) : INFO (logkit:16) - Client Details: ident:fe2z3v6q94ibe29 2015-02-19 13:53:18,464 (3458) : INFO (logkit:16) - MainMenu: Adding What's New Menu 2015-02-19 13:53:18,464 (3458) : INFO (logkit:16) - MainMenu: Adding Live Menu 2015-02-19 13:53:18,464 (3458) : INFO (logkit:16) - MainMenu: Adding Recordings Menu 2015-02-19 13:53:18,466 (3458) : INFO (logkit:16) - MainMenu: Adding Pending Recordings Menu 2015-02-19 13:53:18,467 (3458) : INFO (logkit:16) - MainMenu: URL set to http://MYSERVERNAME.redirectme.net:8866/ 2015-02-19 13:53:18,467 (3458) : DEBUG (base:125) - Checking if com.dcSoftware.plexapp.nextpvr is broken 2015-02-19 13:53:18,469 (3458) : DEBUG (networking:172) - Requesting ' http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X1N0b3JlU2VydmljZTpJc0NoYW5uZWxCcm9rZW4_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMQpzMzAKY29tLmRjU29mdHdhcmUucGxleGFwcC5uZXh0cHZyczEwCmlkZW50aWZpZXJyMAo_ ' 2015-02-19 13:53:18,483 (3458) : DEBUG (runtime:106) - Sending packed state data (111 bytes) 2015-02-19 13:53:18,483 (3458) : DEBUG (runtime:918) - Response: [200] MediaContainer, 800 bytes

— Reply to this email directly or view it on GitHub https://github.com/psyciknz/NextPVR-Plex/issues/6.

psyciknz commented 7 years ago

All this code has been removed as it was part of the scrobbling to Nextpvr code (for updating the playback position