search

morpheus65535 / bazarr

Bazarr is a companion application to Sonarr and Radarr. It manages and downloads subtitles based on your requirements. You define your preferences by TV show or movie and Bazarr takes care of everything for you.
https://www.bazarr.media
GNU General Public License v3.0
2.82k stars 217 forks source link

unable to connect to Sonarr #2268

Closed NathZ1 closed 11 months ago

NathZ1 commented 11 months ago

Describe the bug Configured both Radarr and Sonarr within Bararr per ultra.cc instructions. Both test successfully, showing the correct version. Movies shows 'LIVE' tag however no similar tag for Series. Can't see any monitored series in tab.

Disabled Radarr (just leaving Sonarr enabled), enabled debug logs, cleared logs then restarted server. Debug log below.

Software (please complete the following information):

Additional context Happy to help debug where required. Redacted API key from some of the debug logs (replaced with 'REDACTED')

16/10/2023 14:10:06|INFO    |root                            |BAZARR Log file emptied|
16/10/2023 14:10:09|INFO    |root                            |Bazarr is being restarted...|
16/10/2023 14:10:13|DEBUG   |root                            |Bazarr version: 1.3.0|
16/10/2023 14:10:13|DEBUG   |root                            |Bazarr branch: master|
16/10/2023 14:10:13|DEBUG   |root                            |Operating system: Linux-5.10.0-0.deb10.24-amd64-x86_64-with|
16/10/2023 14:10:13|DEBUG   |root                            |Python version: 3.11.5|
16/10/2023 14:10:15|INFO    |subliminal_patch.providers.zimuku|[('zho', 'CN', None), ('zho', 'TW', None), ('eng', None, None), ('zho', None, None)]|
16/10/2023 14:10:15|DEBUG   |subliminal_patch.providers.tusubtitulo|{<Language: alpha3=spa>, <Language: alpha3=spa;country=MX>, <Language: alpha3=eng>}|
16/10/2023 14:10:15|DEBUG   |urllib3.connectionpool          |Starting new HTTPS connection (1): nathz1.palawan.usbx.me:443|
16/10/2023 14:10:15|DEBUG   |urllib3.connectionpool          |https://nathz1.palawan.usbx.me:443 "GET /sonarr/api/system/status?apikey=(removed) HTTP/1.1" 404 0|
16/10/2023 14:10:15|DEBUG   |urllib3.connectionpool          |Starting new HTTPS connection (1): nathz1.palawan.usbx.me:443|
16/10/2023 14:10:15|DEBUG   |urllib3.connectionpool          |https://nathz1.palawan.usbx.me:443 "GET /sonarr/api/v3/system/status?apikey=(removed) HTTP/1.1" 200 None|
16/10/2023 14:10:15|DEBUG   |root                            |BAZARR got this Sonarr version from its API: 4.0.0.695|
16/10/2023 14:10:15|DEBUG   |root                            |BAZARR got this Radarr version from its API: |
16/10/2023 14:10:15|DEBUG   |root                            |BAZARR returning this binary: /usr/bin/ffprobe|
16/10/2023 14:10:15|DEBUG   |root                            |BAZARR returning this binary: /usr/bin/ffmpeg|
16/10/2023 14:10:15|DEBUG   |urllib3.connectionpool          |Starting new HTTPS connection (1): raw.githubusercontent.com:443|
16/10/2023 14:10:15|DEBUG   |urllib3.connectionpool          |https://raw.githubusercontent.com:443 "GET /morpheus65535/bazarr-binaries/master/announcements.json HTTP/1.1" 200 226|
16/10/2023 14:10:15|DEBUG   |root                            |BAZARR Created announcements file|
16/10/2023 14:10:15|DEBUG   |root                            |BAZARR binary not found in path, searching for it...|
16/10/2023 14:10:15|DEBUG   |dogpile.cache.region            |No value present for key: 'utilities.binaries:get_binaries_from_json|'|
16/10/2023 14:10:15|DEBUG   |dogpile.lock                    |NeedRegenerationException|
16/10/2023 14:10:15|DEBUG   |dogpile.lock                    |no value, waiting for create lock|
16/10/2023 14:10:15|DEBUG   |dogpile.lock                    |value creation lock <dogpile.cache.region.CacheRegion._LockWrapper object at 0x7fac32064c10> acquired|
16/10/2023 14:10:15|DEBUG   |dogpile.cache.region            |No value present for key: 'utilities.binaries:get_binaries_from_json|'|
16/10/2023 14:10:15|DEBUG   |dogpile.lock                    |Calling creation function for not-yet-present value|
16/10/2023 14:10:15|DEBUG   |dogpile.cache.region            |Cache value generated in 0.000 seconds for key(s): 'utilities.binaries:get_binaries_from_json|'|
16/10/2023 14:10:15|DEBUG   |dogpile.lock                    |Released creation lock|
16/10/2023 14:10:15|DEBUG   |root                            |BAZARR binary not found in binaries.json|
16/10/2023 14:10:15|DEBUG   |root                            |BAZARR returning this binary: /usr/bin/unrar|
16/10/2023 14:10:15|DEBUG   |root                            |Using UnRAR from: /usr/bin/unrar|
16/10/2023 14:10:15|DEBUG   |root                            |BAZARR getting releases from Github: https://api.github.com/repos/morpheus65535/Bazarr/releases?per_page=100|
16/10/2023 14:10:15|DEBUG   |urllib3.connectionpool          |Starting new HTTPS connection (1): api.github.com:443|
16/10/2023 14:10:15|DEBUG   |urllib3.connectionpool          |https://api.github.com:443 "GET /repos/morpheus65535/Bazarr/releases?per_page=100 HTTP/1.1" 200 71884|
16/10/2023 14:10:15|DEBUG   |root                            |BAZARR saved 100 releases to releases.txt|
16/10/2023 14:10:16|DEBUG   |subtitles.tools.score           |No score profiles found|
16/10/2023 14:10:16|DEBUG   |subtitles.tools.score           |No score profiles found|
16/10/2023 14:10:16|INFO    |apscheduler.scheduler           |Adding job tentatively -- it will be properly scheduled when the scheduler starts|
16/10/2023 14:10:16|INFO    |apscheduler.scheduler           |Adding job tentatively -- it will be properly scheduled when the scheduler starts|
16/10/2023 14:10:16|INFO    |apscheduler.scheduler           |Adding job tentatively -- it will be properly scheduled when the scheduler starts|
16/10/2023 14:10:16|INFO    |apscheduler.scheduler           |Adding job tentatively -- it will be properly scheduled when the scheduler starts|
16/10/2023 14:10:16|INFO    |apscheduler.scheduler           |Adding job tentatively -- it will be properly scheduled when the scheduler starts|
16/10/2023 14:10:16|INFO    |apscheduler.scheduler           |Adding job tentatively -- it will be properly scheduled when the scheduler starts|
16/10/2023 14:10:16|INFO    |apscheduler.scheduler           |Adding job tentatively -- it will be properly scheduled when the scheduler starts|
16/10/2023 14:10:16|INFO    |apscheduler.scheduler           |Adding job tentatively -- it will be properly scheduled when the scheduler starts|
16/10/2023 14:10:16|INFO    |apscheduler.scheduler           |Adding job tentatively -- it will be properly scheduled when the scheduler starts|
16/10/2023 14:10:16|INFO    |apscheduler.scheduler           |Added job "Cache maintenance" to job store "default"|
16/10/2023 14:10:16|INFO    |apscheduler.scheduler           |Added job "Check health" to job store "default"|
16/10/2023 14:10:16|INFO    |apscheduler.scheduler           |Added job "Sync with Sonarr" to job store "default"|
16/10/2023 14:10:16|INFO    |apscheduler.scheduler           |Added job "Index all Episode Subtitles from disk" to job store "default"|
16/10/2023 14:10:16|INFO    |apscheduler.scheduler           |Added job "Update Release Info" to job store "default"|
16/10/2023 14:10:16|INFO    |apscheduler.scheduler           |Added job "Update Announcements File" to job store "default"|
16/10/2023 14:10:16|INFO    |apscheduler.scheduler           |Added job "Search for wanted Series Subtitles" to job store "default"|
16/10/2023 14:10:16|INFO    |apscheduler.scheduler           |Added job "Upgrade previously downloaded Subtitles" to job store "default"|
16/10/2023 14:10:16|INFO    |apscheduler.scheduler           |Added job "Backup database and configuration file" to job store "default"|
16/10/2023 14:10:16|INFO    |apscheduler.scheduler           |Scheduler started|
16/10/2023 14:10:16|DEBUG   |apscheduler.scheduler           |Looking for jobs to run|
16/10/2023 14:10:16|DEBUG   |apscheduler.scheduler           |Next wakeup is due at 2023-10-16 15:01:01.179343+02:00 (in 3044.997578 seconds)|
16/10/2023 14:10:16|INFO    |engineio.server                 |Server initialized for threading.|
16/10/2023 14:10:16|DEBUG   |root                            |Upgrading database schema|
16/10/2023 14:10:16|INFO    |alembic.runtime.migration       |Context impl SQLiteImpl.|
16/10/2023 14:10:16|INFO    |alembic.runtime.migration       |Will assume non-transactional DDL.|
16/10/2023 14:10:16|DEBUG   |urllib3.connectionpool          |Starting new HTTPS connection (1): raw.githubusercontent.com:443|
16/10/2023 14:10:16|DEBUG   |urllib3.connectionpool          |https://raw.githubusercontent.com:443 "GET /morpheus65535/bazarr-binaries/master/announcements.json HTTP/1.1" 200 226|
16/10/2023 14:10:17|DEBUG   |root                            |Adding new notifier agent: Nextcloud Talk|
16/10/2023 14:10:17|DEBUG   |SignalRCoreClient               |Handler registered started receiveMessage|
16/10/2023 14:10:17|INFO    |root                            |BAZARR is started and waiting for request on http://***.***.***.***:6767|
16/10/2023 14:10:17|INFO    |root                            |BAZARR trying to connect to Sonarr SignalR feed...|
16/10/2023 14:10:17|DEBUG   |SignalRCoreClient               |Connection started|
16/10/2023 14:10:17|DEBUG   |SignalRCoreClient               |Negotiate url:https://nathz1.palawan.usbx.me:443/sonarr/signalr/messages/negotiate?access_token=**REDACTED**|
16/10/2023 14:10:17|DEBUG   |urllib3.connectionpool          |Starting new HTTPS connection (1): nathz1.palawan.usbx.me:443|
16/10/2023 14:10:17|DEBUG   |urllib3.connectionpool          |https://nathz1.palawan.usbx.me:443 "POST /sonarr/signalr/messages/negotiate?access_token=**REDACTED** HTTP/1.1" 200 None|
16/10/2023 14:10:17|DEBUG   |SignalRCoreClient               |Response status code200|
16/10/2023 14:10:17|DEBUG   |SignalRCoreClient               |start url:wss://nathz1.palawan.usbx.me:443/sonarr/signalr/messages?access_token=**REDACTED**&id=8QdX-xrSd05CPZucmPqQTA|
16/10/2023 14:10:17|ERROR   |websocket                       |Handshake status 200 OK - goodbye|
16/10/2023 14:10:17|DEBUG   |SignalRCoreClient               |-- web socket error --|
16/10/2023 14:10:17|ERROR   |SignalRCoreClient               |Traceback (most recent call last):
  File "/app/bazarr/bin/bazarr/../libs/websocket/_app.py", line 371, in setSock
    self.sock.connect(
  File "/app/bazarr/bin/bazarr/../libs/websocket/_core.py", line 253, in connect
    self.handshake_response = handshake(self.sock, url, *addrs, **options)
                              ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/app/bazarr/bin/bazarr/../libs/websocket/_handshake.py", line 57, in handshake
    status, resp = _get_resp_headers(sock)
                   ^^^^^^^^^^^^^^^^^^^^^^^
  File "/app/bazarr/bin/bazarr/../libs/websocket/_handshake.py", line 147, in _get_resp_headers
    raise WebSocketBadStatusException("Handshake status %d %s", status, status_message, resp_headers)
websocket._exceptions.WebSocketBadStatusException: Handshake status 200 OK
|
16/10/2023 14:10:17|ERROR   |SignalRCoreClient               |<signalrcore.transport.websockets.websocket_transport.WebsocketTransport object at 0x7fab9f3fb890> Handshake status 200 OK|
16/10/2023 14:10:17|ERROR   |SignalRCoreClient               |Handshake status 200 OK <class 'websocket._exceptions.WebSocketBadStatusException'>|
16/10/2023 14:10:17|DEBUG   |root                            |BAZARR SignalR client for Sonarr is disconnected.|
16/10/2023 14:10:17|DEBUG   |SignalRCoreClient               |-- web socket close --|
16/10/2023 14:10:17|DEBUG   |SignalRCoreClient               |None|
16/10/2023 14:10:17|DEBUG   |SignalRCoreClient               |None|
16/10/2023 14:10:17|DEBUG   |root                            |BAZARR SignalR client for Sonarr is disconnected.|
16/10/2023 14:10:18|INFO    |engineio.server                 |d7lGN2PqfYJx6zXHAAAA: Sending packet OPEN data {'sid': 'd7lGN2PqfYJx6zXHAAAA', 'upgrades': [], 'pingTimeout': 20000, 'pingInterval': 25000}|
16/10/2023 14:10:18|INFO    |engineio.server                 |d7lGN2PqfYJx6zXHAAAA: Received packet MESSAGE data 0|
16/10/2023 14:10:18|INFO    |engineio.server                 |d7lGN2PqfYJx6zXHAAAA: Sending packet MESSAGE data 0{"sid":"dIQT2vbhtwNVWNB7AAAB"}|
16/10/2023 14:10:18|DEBUG   |root                            |BAZARR got this Radarr version from its API: |
16/10/2023 14:10:43|INFO    |engineio.server                 |d7lGN2PqfYJx6zXHAAAA: Sending packet PING data None|
16/10/2023 14:10:43|INFO    |engineio.server                 |d7lGN2PqfYJx6zXHAAAA: Received packet PONG data |
16/10/2023 14:11:08|INFO    |engineio.server                 |d7lGN2PqfYJx6zXHAAAA: Sending packet PING data None|
16/10/2023 14:11:08|INFO    |engineio.server                 |d7lGN2PqfYJx6zXHAAAA: Received packet PONG data |
16/10/2023 14:11:33|INFO    |engineio.server                 |d7lGN2PqfYJx6zXHAAAA: Sending packet PING data None|
16/10/2023 14:11:33|INFO    |engineio.server                 |d7lGN2PqfYJx6zXHAAAA: Received packet PONG data |
16/10/2023 14:11:58|INFO    |engineio.server                 |d7lGN2PqfYJx6zXHAAAA: Sending packet PING data None|
16/10/2023 14:11:58|INFO    |engineio.server                 |d7lGN2PqfYJx6zXHAAAA: Received packet PONG data |
16/10/2023 14:12:23|INFO    |engineio.server                 |d7lGN2PqfYJx6zXHAAAA: Sending packet PING data None|
16/10/2023 14:12:23|INFO    |engineio.server                 |d7lGN2PqfYJx6zXHAAAA: Received packet PONG data |
16/10/2023 14:12:26|DEBUG   |urllib3.connectionpool          |Starting new HTTPS connection (1): nathz1.palawan.usbx.me:443|
16/10/2023 14:12:26|DEBUG   |urllib3.connectionpool          |https://nathz1.palawan.usbx.me:443 "GET /sonarr/api/system/status?apikey=(removed) HTTP/1.1" 404 0|
16/10/2023 14:12:26|DEBUG   |urllib3.connectionpool          |Starting new HTTPS connection (1): nathz1.palawan.usbx.me:443|
16/10/2023 14:12:26|DEBUG   |urllib3.connectionpool          |https://nathz1.palawan.usbx.me:443 "GET /sonarr/api/v3/system/status?apikey=(removed) HTTP/1.1" 200 None|
16/10/2023 14:12:26|DEBUG   |root                            |BAZARR got this Sonarr version from its API: 4.0.0.695|
16/10/2023 14:12:26|DEBUG   |root                            |BAZARR got this Radarr version from its API: |
16/10/2023 14:12:27|INFO    |engineio.server                 |d7lGN2PqfYJx6zXHAAAA: Received packet CLOSE data |
16/10/2023 14:12:29|DEBUG   |root                            |BAZARR got this Radarr version from its API: |
16/10/2023 14:12:29|INFO    |engineio.server                 |kRCkF9P7iZyoBzH_AAAC: Sending packet OPEN data {'sid': 'kRCkF9P7iZyoBzH_AAAC', 'upgrades': [], 'pingTimeout': 20000, 'pingInterval': 25000}|
16/10/2023 14:12:29|INFO    |engineio.server                 |kRCkF9P7iZyoBzH_AAAC: Received packet MESSAGE data 0|
16/10/2023 14:12:29|INFO    |engineio.server                 |kRCkF9P7iZyoBzH_AAAC: Sending packet MESSAGE data 0{"sid":"cwIHl9zjC7-DNbYsAAAD"}|
16/10/2023 14:12:35|DEBUG   |root                            |BAZARR got this Radarr version from its API: |
morpheus65535 commented 11 months ago

Bazarr will never show LIVE for Sonarr v3 or lower if it's restarted after Bazarr. It just won't reconnect to live stream.

Regarding your empty show list, simply run Sync with Sonarr in System-->Tasks.

For configuration questions or assistance, come to Discord. This is an issues tracker.