Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Since v1.1 Deezer download gives a network error when Download Fallback option is active. #62

Closed
ZordnajelA opened this issue Mar 17, 2025 · 13 comments

Comments

@ZordnajelA
Copy link

ZordnajelA commented Mar 17, 2025

Image

https://[HOST]/api/album/download?service=spotify&url=https%3A%2F%2Fopen.spotify.com%2Falbum%2F0fLhefnjlIV3pGNF9Wo8CD&name=Californication

Even when the service used should be Deezer it still tries for the Spotify URL and throws a 400 HTTP.

Issue is not present when Download Fallback option is not selected and downloads correctly from Spotify only

@Xoconoch
Copy link
Owner

Please test cooldockerizer93/spotizerr:dev and share logs

@ZordnajelA
Copy link
Author

ZordnajelA commented Mar 19, 2025

Just tried the dev version (fresh installed) and now it only downloads from Spotify (i see the .ogg files in the downloads folder, regardless if it's using the fallback option or not and both accounts are connected.

Also saw the new option for the default service, but only offers the Spotify option.

Image

2025-03-19 07:53:24 [MainProcess:MainThread] [root] [INFO] - Logging system initialized 2025-03-19 07:53:24 [MainProcess:MainThread] [root] [INFO] - === Spotizerr Application Starting === 2025-03-19 07:53:24 [MainProcess:MainThread] [root] [INFO] - Checking Redis connection to redis:6379 2025-03-19 07:53:24 [MainProcess:MainThread] [root] [INFO] - Successfully connected to Redis 2025-03-19 07:53:24 [MainProcess:MainThread] [root] [INFO] - Starting Celery workers with dynamic configuration 2025-03-19 07:53:24 [MainProcess:MainThread] [routes.utils.celery_manager] [INFO] - Updating Celery workers from 0 to 3 2025-03-19 07:53:24 [MainProcess:MainThread] [routes.utils.celery_manager] [INFO] - Started Celery workers with concurrency 3 2025-03-19 07:53:24 [MainProcess:Thread-3 (_monitor_config)] [routes.utils.celery_manager] [INFO] - Starting config monitoring thread 2025-03-19 07:53:24 [MainProcess:MainThread] [root] [INFO] - Starting Flask server on port 7171 2025-03-19 07:53:24 [MainProcess:MainThread] [waitress] [INFO] - Serving on http://0.0.0.0:7171 2025-03-19 07:53:25 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: -------------- celery@0f9add43303c v5.3.6 (emerald-rush) 2025-03-19 07:53:25 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: --- ***** ----- 2025-03-19 07:53:25 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: -- ******* ---- Linux-6.8.0-55-generic-x86_64-with-glibc2.36 2025-03-19 07:53:25 2025-03-19 07:53:25 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: - *** --- * --- 2025-03-19 07:53:25 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: - ** ---------- [config] 2025-03-19 07:53:25 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: - ** ---------- .> app: download_tasks:0x72346969a720 2025-03-19 07:53:25 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: - ** ---------- .> transport: redis://redis:6379/0 2025-03-19 07:53:25 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: - ** ---------- .> results: redis://redis:6379/0 2025-03-19 07:53:25 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: - *** --- * --- .> concurrency: 3 (prefork) 2025-03-19 07:53:25 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: -- ******* ---- .> task events: OFF (enable -E to monitor tasks in this worker) 2025-03-19 07:53:25 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: --- ***** ----- 2025-03-19 07:53:25 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: -------------- [queues] 2025-03-19 07:53:25 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: .> downloads exchange=downloads(direct) key=downloads 2025-03-19 07:53:25 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: [tasks] 2025-03-19 07:53:25 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: . download_album 2025-03-19 07:53:25 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: . download_playlist 2025-03-19 07:53:25 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: . download_track 2025-03-19 07:54:37 [MainProcess:waitress-1] [root] [INFO] - Config parameter 'maxConcurrentDownloads' changed from 'None' to '3' 2025-03-19 07:54:37 [MainProcess:waitress-1] [root] [INFO] - Config parameter 'service' changed from 'None' to 'spotify' 2025-03-19 07:54:37 [MainProcess:waitress-1] [root] [INFO] - Config parameter 'fallback' changed from 'None' to 'False' 2025-03-19 07:54:37 [MainProcess:waitress-1] [root] [INFO] - Config parameter 'spotifyQuality' changed from 'None' to 'NORMAL' 2025-03-19 07:54:37 [MainProcess:waitress-1] [root] [INFO] - Config parameter 'deezerQuality' changed from 'None' to 'MP3_128' 2025-03-19 07:56:29 [MainProcess:waitress-0] [root] [INFO] - Config parameter 'spotifyQuality' changed from 'NORMAL' to 'VERY_HIGH' 2025-03-19 07:56:32 [MainProcess:waitress-3] [root] [INFO] - Config parameter 'deezerQuality' changed from 'MP3_128' to 'FLAC' 2025-03-19 07:56:33 [MainProcess:waitress-1] [root] [INFO] - Config parameter 'fallback' changed from 'False' to 'True' 2025-03-19 07:58:19 [MainProcess:waitress-1] [spotipy.cache_handler] [WARNING] - Couldn't write token to cache at: .cache 2025-03-19 07:58:27 [MainProcess:waitress-2] [spotipy.cache_handler] [WARNING] - Couldn't write token to cache at: .cache 2025-03-19 07:58:32 [MainProcess:MainThread] [waitress.queue] [WARNING] - Task queue depth is 1 2025-03-19 07:58:32 [MainProcess:MainThread] [waitress.queue] [WARNING] - Task queue depth is 2 2025-03-19 07:58:32 [MainProcess:waitress-1] [spotipy.cache_handler] [WARNING] - Couldn't write token to cache at: .cache 2025-03-19 07:59:01 [MainProcess:waitress-0] [routes.utils.celery_queue_manager] [INFO] - Added album download task 553a9365-fa8a-4684-9195-244e352e61e2 to Celery queue 2025-03-19 07:59:04 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: Couldn't write token to cache at: .cache 2025-03-19 07:59:04 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: Couldn't write token to cache at: .cache 2025-03-19 07:59:05 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: Couldn't write token to cache at: .cache 2025-03-19 07:59:05 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: Couldn't write token to cache at: .cache 2025-03-19 07:59:05 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: Couldn't write token to cache at: .cache 2025-03-19 07:59:05 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: Couldn't write token to cache at: .cache 2025-03-19 07:59:05 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: Couldn't write token to cache at: .cache 2025-03-19 07:59:05 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: Couldn't write token to cache at: .cache 2025-03-19 07:59:05 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: Couldn't write token to cache at: .cache 2025-03-19 07:59:05 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: Couldn't write token to cache at: .cache 2025-03-19 07:59:08 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: Warning: Invalid numeric value for bpm 2025-03-19 07:59:12 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: Warning: Invalid numeric value for bpm 2025-03-19 07:59:15 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: Warning: Invalid numeric value for bpm 2025-03-19 07:59:17 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: Warning: Invalid numeric value for bpm 2025-03-19 07:59:21 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: Warning: Invalid numeric value for bpm 2025-03-19 07:59:24 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: Warning: Invalid numeric value for bpm 2025-03-19 07:59:26 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: Warning: Invalid numeric value for bpm 2025-03-19 07:59:30 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: Warning: Invalid numeric value for bpm 2025-03-19 07:59:33 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: Warning: Invalid numeric value for bpm 2025-03-19 07:59:39 [MainProcess:waitress-0] [routes.utils.celery_tasks] [INFO] - Task 553a9365-fa8a-4684-9195-244e352e61e2 cancelled by user 2025-03-19 08:00:24 [MainProcess:waitress-1] [root] [INFO] - Config parameter 'fallback' changed from 'True' to 'False' 2025-03-19 08:00:37 [MainProcess:waitress-2] [spotipy.cache_handler] [WARNING] - Couldn't write token to cache at: .cache 2025-03-19 08:00:41 [MainProcess:waitress-0] [spotipy.cache_handler] [WARNING] - Couldn't write token to cache at: .cache 2025-03-19 08:00:47 [MainProcess:waitress-3] [routes.utils.celery_queue_manager] [INFO] - Added album download task 62e3097c-e034-405b-b461-d448e9f8e79a to Celery queue 2025-03-19 08:00:50 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: Couldn't write token to cache at: .cache 2025-03-19 08:00:50 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: Couldn't write token to cache at: .cache 2025-03-19 08:00:50 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: Couldn't write token to cache at: .cache 2025-03-19 08:00:50 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: Couldn't write token to cache at: .cache 2025-03-19 08:00:50 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: Couldn't write token to cache at: .cache 2025-03-19 08:00:50 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: Couldn't write token to cache at: .cache 2025-03-19 08:00:50 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: Couldn't write token to cache at: .cache 2025-03-19 08:00:51 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: Couldn't write token to cache at: .cache 2025-03-19 08:00:51 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: Couldn't write token to cache at: .cache 2025-03-19 08:00:51 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: Couldn't write token to cache at: .cache 2025-03-19 08:00:51 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: Couldn't write token to cache at: .cache 2025-03-19 08:00:55 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: Warning: Invalid numeric value for bpm 2025-03-19 08:01:00 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: Warning: Invalid numeric value for bpm 2025-03-19 08:01:04 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: Warning: Invalid numeric value for bpm 2025-03-19 08:01:09 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: Warning: Invalid numeric value for bpm 2025-03-19 08:01:15 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: Warning: Invalid numeric value for bpm 2025-03-19 08:01:19 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: Warning: Invalid numeric value for bpm 2025-03-19 08:01:23 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: Warning: Invalid numeric value for bpm 2025-03-19 08:01:26 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: Warning: Invalid numeric value for bpm 2025-03-19 08:01:32 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: Warning: Invalid numeric value for bpm 2025-03-19 08:01:38 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: Warning: Invalid numeric value for bpm 2025-03-19 08:01:46 [MainProcess:waitress-0] [routes.utils.celery_tasks] [INFO] - Task 62e3097c-e034-405b-b461-d448e9f8e79a cancelled by user 2025-03-19 08:03:25 [MainProcess:waitress-2] [root] [INFO] - Config parameter 'fallback' changed from 'False' to 'True'

@Xoconoch
Copy link
Owner

Xoconoch commented Mar 20, 2025

The default services behaviour is intended, just preparing the terrain for long-term thingies.

Unfortunately, I currently don't have access to a premium deezer account, so you'll have to wait a while I debug this thing.

In the meantime, please use cooldockerizer/spotizerr:1.0.0

Edit: Never mind, I just remembered the api changes made it obsolete. The wait shouldn't be long tho, just a couple of weeks at most

@Xoconoch
Copy link
Owner

I got one yesterday lol.

Please run

docker pull cooldockerizer/spotizerr:dev

and test that image with the new docker compose file in the repo, in my testings it ran fine.

@Toomblr
Copy link

Toomblr commented Mar 22, 2025

I'm running into these issues (only downloading from Spotify while Deezer is configured and fallback enabled). Running 1.2 with the updated docker-compose.

Logs don't show any apparant issues:

spotizerr-spotizerr-1  | Created group: appgroup (GID: 568)
spotizerr-spotizerr-1  | useradd warning: appuser's uid 568 outside of the UID_MIN 1000 and UID_MAX 60000 range.
spotizerr-spotizerr-1  | Created user: appuser (UID: 568)
spotizerr-spotizerr-1  | Setting permissions for /app directories...
spotizerr-spotizerr-1  | Starting application as appuser...
spotizerr-spotizerr-1  | 2025-03-22 12:16:40 [MainProcess:MainThread] [root] [INFO] - Logging system initialized
spotizerr-spotizerr-1  | 2025-03-22 12:16:40 [MainProcess:MainThread] [root] [INFO] - === Spotizerr Application Starting ===
spotizerr-spotizerr-1  | 2025-03-22 12:16:40 [MainProcess:MainThread] [root] [INFO] - Checking Redis connection to spotizerr-redis:6379
spotizerr-spotizerr-1  | 2025-03-22 12:16:40 [MainProcess:MainThread] [root] [INFO] - Successfully connected to Redis
spotizerr-spotizerr-1  | 2025-03-22 12:16:40 [MainProcess:MainThread] [root] [INFO] - Starting Celery workers with dynamic configuration
spotizerr-spotizerr-1  | 2025-03-22 12:16:40 [MainProcess:MainThread] [routes.utils.celery_manager] [INFO] - Updating Celery workers from 0 to 3
spotizerr-spotizerr-1  | 2025-03-22 12:16:40 [MainProcess:MainThread] [routes.utils.celery_manager] [INFO] - Started Celery workers with concurrency 3
spotizerr-spotizerr-1  | 2025-03-22 12:16:40 [MainProcess:Thread-3 (_monitor_config)] [routes.utils.celery_manager] [INFO] - Starting config monitoring thread
spotizerr-spotizerr-1  | 2025-03-22 12:16:41 [MainProcess:MainThread] [root] [INFO] - Starting Flask server on port 7171
spotizerr-spotizerr-1  | 2025-03-22 12:16:41 [MainProcess:MainThread] [waitress] [INFO] - Serving on http://0.0.0.0:7171
spotizerr-spotizerr-1  | 2025-03-22 12:16:41 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: -------------- celery@ceed8059a0e3 v5.3.6 (emerald-rush)
spotizerr-spotizerr-1  | 2025-03-22 12:16:41 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: --- ***** -----
spotizerr-spotizerr-1  | 2025-03-22 12:16:41 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: -- ******* ---- Linux-6.6.44-production+truenas-x86_64-with-glibc2.36 2025-03-22 12:16:41
spotizerr-spotizerr-1  | 2025-03-22 12:16:41 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: - *** --- * ---
spotizerr-spotizerr-1  | 2025-03-22 12:16:41 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: - ** ---------- [config]
spotizerr-spotizerr-1  | 2025-03-22 12:16:41 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: - ** ---------- .> app:         download_tasks:0x7f740e103da0
spotizerr-spotizerr-1  | 2025-03-22 12:16:41 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: - ** ---------- .> transport:   redis://spotizerr-redis:6379/0
spotizerr-spotizerr-1  | 2025-03-22 12:16:41 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: - ** ---------- .> results:     redis://spotizerr-redis:6379/0
spotizerr-spotizerr-1  | 2025-03-22 12:16:41 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: - *** --- * --- .> concurrency: 3 (prefork)
spotizerr-spotizerr-1  | 2025-03-22 12:16:41 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: -- ******* ---- .> task events: OFF (enable -E to monitor tasks in this worker)
spotizerr-spotizerr-1  | 2025-03-22 12:16:41 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: --- ***** -----
spotizerr-spotizerr-1  | 2025-03-22 12:16:41 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: -------------- [queues]
spotizerr-spotizerr-1  | 2025-03-22 12:16:41 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: .> downloads        exchange=downloads(direct) key=downloads
spotizerr-spotizerr-1  | 2025-03-22 12:16:41 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: [tasks]
spotizerr-spotizerr-1  | 2025-03-22 12:16:41 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: . download_album
spotizerr-spotizerr-1  | 2025-03-22 12:16:41 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: . download_playlist
spotizerr-spotizerr-1  | 2025-03-22 12:16:41 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: . download_track
spotizerr-spotizerr-1  | 2025-03-22 12:16:53 [MainProcess:waitress-3] [spotipy.cache_handler] [WARNING] - Couldn't write token to cache at: .cache
spotizerr-spotizerr-1  | 2025-03-22 12:16:55 [MainProcess:waitress-0] [routes.utils.celery_queue_manager] [INFO] - Added track download task 219e970e-9d05-4841-9c3b-fea0479cec12 to Celery queue
spotizerr-redis        | 1:M 22 Mar 2025 11:16:55.267 * 100 changes in 300 seconds. Saving...
spotizerr-redis        | 1:M 22 Mar 2025 11:16:55.269 * Background saving started by pid 21
spotizerr-redis        | 21:C 22 Mar 2025 11:16:55.273 * DB saved on disk
spotizerr-redis        | 21:C 22 Mar 2025 11:16:55.273 * Fork CoW for RDB: current 0 MB, peak 0 MB, average 0 MB
spotizerr-redis        | 1:M 22 Mar 2025 11:16:55.369 * Background saving terminated with success
spotizerr-spotizerr-1  | 2025-03-22 12:16:58 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: Couldn't write token to cache at: .cache
spotizerr-spotizerr-1  | 2025-03-22 12:16:58 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: Couldn't write token to cache at: .cache
spotizerr-spotizerr-1  | 2025-03-22 12:16:59 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: Warning: Invalid numeric value for bpm
spotizerr-spotizerr-1  | 2025-03-22 12:17:06 [MainProcess:waitress-0] [routes.utils.celery_tasks] [INFO] - Task 219e970e-9d05-4841-9c3b-fea0479cec12 cancelled by user

@Xoconoch
Copy link
Owner

I added proper logging of deezer failures in cooldockerizer93/spotizerr:1.2.1

Please retest and share.

@RomainBoulay
Copy link

@Xoconoch could you also please build the arm64 version of your dev/1.2+ containers? Last version I can use is 1.1

@Toomblr
Copy link

Toomblr commented Mar 22, 2025

This is on 1.2.1

spotizerr-spotizerr-1  | Created group: appgroup (GID: 568)
spotizerr-spotizerr-1  | useradd warning: appuser's uid 568 outside of the UID_MIN 1000 and UID_MAX 60000 range.
spotizerr-spotizerr-1  | Created user: appuser (UID: 568)
spotizerr-spotizerr-1  | Setting permissions for /app directories...
spotizerr-spotizerr-1  | Starting application as appuser...
spotizerr-spotizerr-1  | 2025-03-22 17:01:29 [MainProcess:MainThread] [root] [INFO] - Logging system initialized
spotizerr-spotizerr-1  | 2025-03-22 17:01:29 [MainProcess:MainThread] [root] [INFO] - === Spotizerr Application Starting ===
spotizerr-spotizerr-1  | 2025-03-22 17:01:29 [MainProcess:MainThread] [root] [INFO] - Checking Redis connection to spotizerr-redis:6379
spotizerr-spotizerr-1  | 2025-03-22 17:01:29 [MainProcess:MainThread] [root] [INFO] - Successfully connected to Redis
spotizerr-spotizerr-1  | 2025-03-22 17:01:29 [MainProcess:MainThread] [root] [INFO] - Starting Celery workers with dynamic configuration
spotizerr-spotizerr-1  | 2025-03-22 17:01:29 [MainProcess:MainThread] [routes.utils.celery_manager] [INFO] - Updating Celery workers from 0 to 3
spotizerr-spotizerr-1  | 2025-03-22 17:01:29 [MainProcess:MainThread] [routes.utils.celery_manager] [INFO] - Started Celery workers with concurrency 3
spotizerr-spotizerr-1  | 2025-03-22 17:01:29 [MainProcess:Thread-3 (_monitor_config)] [routes.utils.celery_manager] [INFO] - Starting config monitoring thread
spotizerr-spotizerr-1  | 2025-03-22 17:01:29 [MainProcess:MainThread] [root] [INFO] - Starting Flask server on port 7171
spotizerr-spotizerr-1  | 2025-03-22 17:01:29 [MainProcess:MainThread] [waitress] [INFO] - Serving on http://0.0.0.0:7171
spotizerr-spotizerr-1  | 2025-03-22 17:01:29 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: -------------- celery@ea05660d916c v5.3.6 (emerald-rush)
spotizerr-spotizerr-1  | 2025-03-22 17:01:29 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: --- ***** -----
spotizerr-spotizerr-1  | 2025-03-22 17:01:29 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: -- ******* ---- Linux-6.6.44-production+truenas-x86_64-with-glibc2.36 2025-03-22 17:01:29
spotizerr-spotizerr-1  | 2025-03-22 17:01:29 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: - *** --- * ---
spotizerr-spotizerr-1  | 2025-03-22 17:01:29 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: - ** ---------- [config]
spotizerr-spotizerr-1  | 2025-03-22 17:01:29 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: - ** ---------- .> app:         download_tasks:0x7f910dd1c4d0
spotizerr-spotizerr-1  | 2025-03-22 17:01:29 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: - ** ---------- .> transport:   redis://spotizerr-redis:6379/0
spotizerr-spotizerr-1  | 2025-03-22 17:01:29 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: - ** ---------- .> results:     redis://spotizerr-redis:6379/0
spotizerr-spotizerr-1  | 2025-03-22 17:01:29 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: - *** --- * --- .> concurrency: 3 (prefork)
spotizerr-spotizerr-1  | 2025-03-22 17:01:29 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: -- ******* ---- .> task events: OFF (enable -E to monitor tasks in this worker)
spotizerr-spotizerr-1  | 2025-03-22 17:01:29 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: --- ***** -----
spotizerr-spotizerr-1  | 2025-03-22 17:01:29 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: -------------- [queues]
spotizerr-spotizerr-1  | 2025-03-22 17:01:29 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: .> downloads        exchange=downloads(direct) key=downloads
spotizerr-spotizerr-1  | 2025-03-22 17:01:29 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: [tasks]
spotizerr-spotizerr-1  | 2025-03-22 17:01:29 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: . download_album
spotizerr-spotizerr-1  | 2025-03-22 17:01:29 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: . download_playlist
spotizerr-spotizerr-1  | 2025-03-22 17:01:29 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: . download_track
spotizerr-spotizerr-1  | 2025-03-22 17:01:43 [MainProcess:waitress-3] [spotipy.cache_handler] [WARNING] - Couldn't write token to cache at: .cache
spotizerr-spotizerr-1  | 2025-03-22 17:01:45 [MainProcess:waitress-0] [routes.utils.celery_queue_manager] [INFO] - Added track download task 440642bd-4c2e-4207-a6cc-668dc79765c6 to Celery queue
spotizerr-spotizerr-1  | 2025-03-22 17:01:46 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [ERROR] - Celery[STDOUT]: ERROR: Deezer download attempt failed: Wrong token:  :(
spotizerr-spotizerr-1  | 2025-03-22 17:01:46 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: Traceback (most recent call last):
spotizerr-spotizerr-1  | 2025-03-22 17:01:46 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: File "/app/routes/utils/track.py", line 78, in download_track
spotizerr-spotizerr-1  | 2025-03-22 17:01:46 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: Attempting Spotify fallback...
spotizerr-spotizerr-1  | 2025-03-22 17:01:46 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: dl = DeeLogin(
spotizerr-spotizerr-1  | 2025-03-22 17:01:46 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: ^^^^^^^^^
spotizerr-spotizerr-1  | 2025-03-22 17:01:46 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: File "/usr/local/lib/python3.12/site-packages/deezspot/deezloader/__init__.py", line 72, in __init__
spotizerr-spotizerr-1  | 2025-03-22 17:01:46 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: self.__gw_api = API_GW(arl=arl)
spotizerr-spotizerr-1  | 2025-03-22 17:01:46 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: ^^^^^^^^^^^^^^^
spotizerr-spotizerr-1  | 2025-03-22 17:01:46 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: File "/usr/local/lib/python3.12/site-packages/deezspot/deezloader/deegw_api.py", line 50, in __init__
spotizerr-spotizerr-1  | 2025-03-22 17:01:46 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: cls.__refresh_token()
spotizerr-spotizerr-1  | 2025-03-22 17:01:46 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: File "/usr/local/lib/python3.12/site-packages/deezspot/deezloader/deegw_api.py", line 166, in __refresh_token
spotizerr-spotizerr-1  | 2025-03-22 17:01:46 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: cls.am_I_log()
spotizerr-spotizerr-1  | 2025-03-22 17:01:46 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: File "/usr/local/lib/python3.12/site-packages/deezspot/deezloader/deegw_api.py", line 193, in am_I_log
spotizerr-spotizerr-1  | 2025-03-22 17:01:46 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: raise BadCredentials(arl = cls.__arl)
spotizerr-spotizerr-1  | 2025-03-22 17:01:46 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: deezspot.exceptions.BadCredentials: Wrong token:  :(
spotizerr-spotizerr-1  | 2025-03-22 17:01:47 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: Couldn't write token to cache at: .cache
spotizerr-spotizerr-1  | 2025-03-22 17:01:47 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: Couldn't write token to cache at: .cache
spotizerr-spotizerr-1  | 2025-03-22 17:01:49 [MainProcess:waitress-3] [root] [INFO] - Config parameter 'service' changed from 'None' to 'spotify'
spotizerr-spotizerr-1  | 2025-03-22 17:01:50 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: Warning: Invalid numeric value for bpm
spotizerr-spotizerr-1  | 2025-03-22 17:01:57 [MainProcess:waitress-2] [routes.utils.celery_tasks] [INFO] - Task 440642bd-4c2e-4207-a6cc-668dc79765c6 cancelled by user
spotizerr-spotizerr-1  | 2025-03-22 17:05:32 [MainProcess:waitress-0] [spotipy.cache_handler] [WARNING] - Couldn't write token to cache at: .cache
spotizerr-spotizerr-1  | 2025-03-22 17:05:34 [MainProcess:waitress-1] [routes.utils.celery_queue_manager] [INFO] - Added track download task 3eb4cf2e-8b06-4f1b-9ef9-2a5c349ef17c to Celery queue
spotizerr-spotizerr-1  | 2025-03-22 17:05:35 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: Couldn't write token to cache at: .cache
spotizerr-spotizerr-1  | 2025-03-22 17:05:39 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: Missing encryption info in API response
spotizerr-spotizerr-1  | 2025-03-22 17:05:39 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: Failed to check track IDs: Missing encryption info
spotizerr-spotizerr-1  | 2025-03-22 17:05:40 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: Missing encryption info in API response
spotizerr-spotizerr-1  | 2025-03-22 17:05:40 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: Failed to check track IDs: Missing encryption info
spotizerr-spotizerr-1  | 2025-03-22 17:05:40 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [ERROR] - Celery[STDOUT]: ERROR: Deezer download attempt failed: Cannot download Sunday Bloody Sunday - U2, maybe it's not available in this format?
spotizerr-spotizerr-1  | 2025-03-22 17:05:40 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: Traceback (most recent call last):
spotizerr-spotizerr-1  | 2025-03-22 17:05:40 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: File "/app/routes/utils/track.py", line 84, in download_track
spotizerr-spotizerr-1  | 2025-03-22 17:05:40 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: Attempting Spotify fallback...
spotizerr-spotizerr-1  | 2025-03-22 17:05:40 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: dl.download_trackspo(
spotizerr-spotizerr-1  | 2025-03-22 17:05:40 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: File "/usr/local/lib/python3.12/site-packages/deezspot/deezloader/__init__.py", line 331, in download_trackspo
spotizerr-spotizerr-1  | 2025-03-22 17:05:40 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: track = self.download_trackdee(
spotizerr-spotizerr-1  | 2025-03-22 17:05:40 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: ^^^^^^^^^^^^^^^^^^^^^^^
spotizerr-spotizerr-1  | 2025-03-22 17:05:40 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: File "/usr/local/lib/python3.12/site-packages/deezspot/deezloader/__init__.py", line 139, in download_trackdee
spotizerr-spotizerr-1  | 2025-03-22 17:05:40 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: track = DW_TRACK(preferences).dw()
spotizerr-spotizerr-1  | 2025-03-22 17:05:40 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: ^^^^^^^^^^^^^^^^^^^^^^^^^^
spotizerr-spotizerr-1  | 2025-03-22 17:05:40 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: File "/usr/local/lib/python3.12/site-packages/deezspot/deezloader/__download__.py", line 568, in dw
spotizerr-spotizerr-1  | 2025-03-22 17:05:40 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: raise TrackNotFound(message = error_msg)
spotizerr-spotizerr-1  | 2025-03-22 17:05:40 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: deezspot.exceptions.TrackNotFound: Cannot download Sunday Bloody Sunday - U2, maybe it's not available in this format?
spotizerr-spotizerr-1  | 2025-03-22 17:05:42 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: Couldn't write token to cache at: .cache
spotizerr-spotizerr-1  | 2025-03-22 17:05:42 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: Couldn't write token to cache at: .cache
spotizerr-spotizerr-1  | 2025-03-22 17:05:45 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: Warning: Invalid numeric value for bpm
spotizerr-redis        | 1:M 22 Mar 2025 16:05:45.181 * 100 changes in 300 seconds. Saving...
spotizerr-redis        | 1:M 22 Mar 2025 16:05:45.182 * Background saving started by pid 26
spotizerr-redis        | 26:C 22 Mar 2025 16:05:45.186 * DB saved on disk
spotizerr-redis        | 26:C 22 Mar 2025 16:05:45.187 * Fork CoW for RDB: current 0 MB, peak 0 MB, average 0 MB
spotizerr-redis        | 1:M 22 Mar 2025 16:05:45.282 * Background saving terminated with success
spotizerr-spotizerr-1  | 2025-03-22 17:05:50 [MainProcess:waitress-1] [routes.utils.celery_tasks] [INFO] - Task 3eb4cf2e-8b06-4f1b-9ef9-2a5c349ef17c cancelled by user

So it says my token is wrong. But I double checked the ARL, and that is the right one

@Xoconoch
Copy link
Owner

Xoconoch commented Mar 22, 2025

"Token" is not referring to your arl, but the decrypting data that the api failed to provide. This seems like a deezspot lib issue, I'll look into it.

Oops, I confused your arl obfuscation with another common error lol. I would tell you to double check the validity of your account, try downloading directly with the deezer app or smth.

If you got your arl through other means note that deezer has implemented a patch that automatically kills over-used arl's.

Please clarify if you have access to the actual account (email+psswd) or just the arl so we can further debug this.

@RomainBoulay I forgot to add it to the github workflows, done.

@Toomblr
Copy link

Toomblr commented Mar 23, 2025

No it's my own ARL! It works perfectly fine in Deemix. But I prefer Spotizerr. I just rechecked the ARL.

Now the logs show different errors:

spotizerr-spotizerr-1  | Created group: appgroup (GID: 568)
spotizerr-spotizerr-1  | useradd warning: appuser's uid 568 outside of the UID_MIN 1000 and UID_MAX 60000 range.
spotizerr-spotizerr-1  | Created user: appuser (UID: 568)
spotizerr-spotizerr-1  | Setting permissions for /app directories...
spotizerr-spotizerr-1  | Starting application as appuser...
spotizerr-spotizerr-1  | 2025-03-23 13:07:34 [MainProcess:MainThread] [root] [INFO] - Logging system initialized
spotizerr-spotizerr-1  | 2025-03-23 13:07:34 [MainProcess:MainThread] [root] [INFO] - === Spotizerr Application Starting ===
spotizerr-spotizerr-1  | 2025-03-23 13:07:34 [MainProcess:MainThread] [root] [INFO] - Checking Redis connection to spotizerr-redis:6379
spotizerr-spotizerr-1  | 2025-03-23 13:07:34 [MainProcess:MainThread] [root] [INFO] - Successfully connected to Redis
spotizerr-spotizerr-1  | 2025-03-23 13:07:34 [MainProcess:MainThread] [root] [INFO] - Starting Celery workers with dynamic configuration
spotizerr-spotizerr-1  | 2025-03-23 13:07:34 [MainProcess:MainThread] [routes.utils.celery_manager] [INFO] - Updating Celery workers from 0 to 3
spotizerr-spotizerr-1  | 2025-03-23 13:07:34 [MainProcess:MainThread] [routes.utils.celery_manager] [INFO] - Started Celery workers with concurrency 3
spotizerr-spotizerr-1  | 2025-03-23 13:07:34 [MainProcess:Thread-3 (_monitor_config)] [routes.utils.celery_manager] [INFO] - Starting config monitoring thread
spotizerr-spotizerr-1  | 2025-03-23 13:07:34 [MainProcess:MainThread] [root] [INFO] - Starting Flask server on port 7171
spotizerr-spotizerr-1  | 2025-03-23 13:07:34 [MainProcess:MainThread] [waitress] [INFO] - Serving on http://0.0.0.0:7171
spotizerr-spotizerr-1  | 2025-03-23 13:07:35 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: -------------- celery@4336680764e9 v5.3.6 (emerald-rush)
spotizerr-spotizerr-1  | 2025-03-23 13:07:35 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: --- ***** -----
spotizerr-spotizerr-1  | 2025-03-23 13:07:35 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: -- ******* ---- Linux-6.6.44-production+truenas-x86_64-with-glibc2.36 2025-03-23 13:07:35
spotizerr-spotizerr-1  | 2025-03-23 13:07:35 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: - *** --- * ---
spotizerr-spotizerr-1  | 2025-03-23 13:07:35 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: - ** ---------- [config]
spotizerr-spotizerr-1  | 2025-03-23 13:07:35 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: - ** ---------- .> app:         download_tasks:0x7f47103fc3e0
spotizerr-spotizerr-1  | 2025-03-23 13:07:35 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: - ** ---------- .> transport:   redis://spotizerr-redis:6379/0
spotizerr-spotizerr-1  | 2025-03-23 13:07:35 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: - ** ---------- .> results:     redis://spotizerr-redis:6379/0
spotizerr-spotizerr-1  | 2025-03-23 13:07:35 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: - *** --- * --- .> concurrency: 3 (prefork)
spotizerr-spotizerr-1  | 2025-03-23 13:07:35 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: -- ******* ---- .> task events: OFF (enable -E to monitor tasks in this worker)
spotizerr-spotizerr-1  | 2025-03-23 13:07:35 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: --- ***** -----
spotizerr-spotizerr-1  | 2025-03-23 13:07:35 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: -------------- [queues]
spotizerr-spotizerr-1  | 2025-03-23 13:07:35 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: .> downloads        exchange=downloads(direct) key=downloads
spotizerr-spotizerr-1  | 2025-03-23 13:07:35 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: [tasks]
spotizerr-spotizerr-1  | 2025-03-23 13:07:35 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: . download_album
spotizerr-spotizerr-1  | 2025-03-23 13:07:35 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: . download_playlist
spotizerr-spotizerr-1  | 2025-03-23 13:07:35 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: . download_track
spotizerr-spotizerr-1  | 2025-03-23 13:07:58 [MainProcess:waitress-3] [spotipy.cache_handler] [WARNING] - Couldn't write token to cache at: .cache
spotizerr-spotizerr-1  | 2025-03-23 13:08:03 [MainProcess:waitress-0] [spotipy.cache_handler] [WARNING] - Couldn't write token to cache at: .cache
spotizerr-spotizerr-1  | 2025-03-23 13:08:04 [MainProcess:waitress-1] [spotipy.cache_handler] [WARNING] - Couldn't write token to cache at: .cache
spotizerr-spotizerr-1  | 2025-03-23 13:08:07 [MainProcess:waitress-0] [spotipy.cache_handler] [WARNING] - Couldn't write token to cache at: .cache
spotizerr-spotizerr-1  | 2025-03-23 13:08:11 [MainProcess:waitress-3] [spotipy.cache_handler] [WARNING] - Couldn't write token to cache at: .cache
spotizerr-spotizerr-1  | 2025-03-23 13:08:15 [MainProcess:waitress-1] [routes.utils.celery_queue_manager] [INFO] - Added track download task d50c15e0-fcf8-4738-9d0c-010c43ecbf9f to Celery queue
spotizerr-spotizerr-1  | 2025-03-23 13:08:16 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: Couldn't write token to cache at: .cache
spotizerr-spotizerr-1  | 2025-03-23 13:08:17 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: Missing encryption info in API response
spotizerr-spotizerr-1  | 2025-03-23 13:08:17 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: Failed to check track IDs: Missing encryption info
spotizerr-spotizerr-1  | 2025-03-23 13:08:18 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: Missing encryption info in API response
spotizerr-spotizerr-1  | 2025-03-23 13:08:18 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: Failed to check track IDs: Missing encryption info
spotizerr-spotizerr-1  | 2025-03-23 13:08:18 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [ERROR] - Celery[STDOUT]: ERROR: Deezer download attempt failed: Cannot download Hello - Adele, maybe it's not available in this format?
spotizerr-spotizerr-1  | 2025-03-23 13:08:18 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: Traceback (most recent call last):
spotizerr-spotizerr-1  | 2025-03-23 13:08:18 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: File "/app/routes/utils/track.py", line 84, in download_track
spotizerr-spotizerr-1  | 2025-03-23 13:08:18 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: dl.download_trackspo(
spotizerr-spotizerr-1  | 2025-03-23 13:08:18 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: Attempting Spotify fallback...
spotizerr-spotizerr-1  | 2025-03-23 13:08:18 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: File "/usr/local/lib/python3.12/site-packages/deezspot/deezloader/__init__.py", line 331, in download_trackspo
spotizerr-spotizerr-1  | 2025-03-23 13:08:18 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: track = self.download_trackdee(
spotizerr-spotizerr-1  | 2025-03-23 13:08:18 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: ^^^^^^^^^^^^^^^^^^^^^^^
spotizerr-spotizerr-1  | 2025-03-23 13:08:18 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: File "/usr/local/lib/python3.12/site-packages/deezspot/deezloader/__init__.py", line 139, in download_trackdee
spotizerr-spotizerr-1  | 2025-03-23 13:08:18 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: track = DW_TRACK(preferences).dw()
spotizerr-spotizerr-1  | 2025-03-23 13:08:18 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: ^^^^^^^^^^^^^^^^^^^^^^^^^^
spotizerr-spotizerr-1  | 2025-03-23 13:08:18 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: File "/usr/local/lib/python3.12/site-packages/deezspot/deezloader/__download__.py", line 568, in dw
spotizerr-spotizerr-1  | 2025-03-23 13:08:18 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: raise TrackNotFound(message = error_msg)
spotizerr-spotizerr-1  | 2025-03-23 13:08:18 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: deezspot.exceptions.TrackNotFound: Cannot download Hello - Adele, maybe it's not available in this format?
spotizerr-spotizerr-1  | 2025-03-23 13:08:20 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: Couldn't write token to cache at: .cache
spotizerr-spotizerr-1  | 2025-03-23 13:08:20 [MainProcess:Thread-2 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDERR]: Couldn't write token to cache at: .cache
spotizerr-spotizerr-1  | 2025-03-23 13:08:22 [MainProcess:Thread-1 (_process_output_reader)] [routes.utils.celery_manager] [INFO] - Celery[STDOUT]: Warning: Invalid numeric value for bpm
spotizerr-spotizerr-1  | 2025-03-23 13:08:27 [MainProcess:waitress-2] [routes.utils.celery_tasks] [INFO] - Task d50c15e0-fcf8-4738-9d0c-010c43ecbf9f cancelled by user

@Xoconoch
Copy link
Owner

I believe I have found the issue, the cooldockerizer93/spotizerr:1.2.1 image wasn't set to be the :latest tag as well. In my testings I was using the 1.2.1 tag directly, that's why I couldn't reproduce it, but once I changed to latest, it happened.

Now that I have set that, it should be fine after you run

docker compose pull && docker compose up -d

Just to confirm, what tag were you using in your docker-compose.yaml file?

@Toomblr
Copy link

Toomblr commented Mar 23, 2025

Indeed, did a repull and it fixed it! I was usingimage: cooldockerizer93/spotizerr in my compose.

@Xoconoch
Copy link
Owner

Perfect, I will close this issue since your testings confirm that the original error has been also fixed.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

No branches or pull requests

4 participants