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

Token expired, send Mercury request, Request timeout out #554

Open
AndreKR opened this issue Jan 15, 2023 · 1 comment
Open

Token expired, send Mercury request, Request timeout out #554

AndreKR opened this issue Jan 15, 2023 · 1 comment
Labels
bug Something isn't working

Comments

@AndreKR
Copy link

AndreKR commented Jan 15, 2023

Describe the bug
After running for a while the device doesn't appear as a device in Spotify anymore. Checking the logs shows a repeated loop of failed reconnects.

A restart fixed the issue for now.

To Reproduce
I just let it run idle for a couple of days.

Expected behavior
The librespot-java instance should appear in Spotify on another device ready to play on it.

Screenshots/Stracktraces/Logs

[...]
2023-01-15 21:44:26,224 DEBUG TokenProvider:67 - Token expired or not suitable, requesting again. {scopes: [playlist-read], oldToken: null}
2023-01-15 21:44:26,224 TRACE MercuryClient:156 - Send Mercury request, seq: 16738, uri: hm://keymaster/token/authenticated?scope=playlist-read&client_id=65b7[REDACTED]&device_id=61a8[REDACTED], method: GET
2023-01-15 21:44:29,224 ERROR DealerClient:278 - Failed reconnecting, retrying...
java.io.IOException: Request timeout out, 3000 passed, yet no response. {seq: 16738}
        at xyz.gianlu.librespot.mercury.MercuryClient.sendSync(MercuryClient.java:94) ~[player.jar:1.6.2]
        at xyz.gianlu.librespot.mercury.MercuryClient.sendSync(MercuryClient.java:104) ~[player.jar:1.6.2]
        at xyz.gianlu.librespot.core.TokenProvider.getToken(TokenProvider.java:68) ~[player.jar:1.6.2]
        at xyz.gianlu.librespot.core.TokenProvider.get(TokenProvider.java:79) ~[player.jar:1.6.2]
        at xyz.gianlu.librespot.dealer.DealerClient.connect(DealerClient.java:79) ~[player.jar:1.6.2]
        at xyz.gianlu.librespot.dealer.DealerClient.lambda$connectionInvalided$3(DealerClient.java:276) ~[player.jar:1.6.2]
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) [?:?]
        at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?]
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) [?:?]
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) [?:?]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [?:?]
        at java.lang.Thread.run(Thread.java:833) [?:?]
2023-01-15 21:44:29,224 TRACE DealerClient:271 - Scheduled reconnection attempt in 10 seconds...
2023-01-15 21:44:39,225 DEBUG TokenProvider:67 - Token expired or not suitable, requesting again. {scopes: [playlist-read], oldToken: null}
2023-01-15 21:44:39,225 TRACE MercuryClient:156 - Send Mercury request, seq: 16739, uri: hm://keymaster/token/authenticated?scope=playlist-read&client_id=65b7[REDACTED]&device_id=61a8[REDACTED], method: GET
2023-01-15 21:44:42,226 ERROR DealerClient:278 - Failed reconnecting, retrying...
java.io.IOException: Request timeout out, 3000 passed, yet no response. {seq: 16739}
        at xyz.gianlu.librespot.mercury.MercuryClient.sendSync(MercuryClient.java:94) ~[player.jar:1.6.2]
        at xyz.gianlu.librespot.mercury.MercuryClient.sendSync(MercuryClient.java:104) ~[player.jar:1.6.2]
        at xyz.gianlu.librespot.core.TokenProvider.getToken(TokenProvider.java:68) ~[player.jar:1.6.2]
        at xyz.gianlu.librespot.core.TokenProvider.get(TokenProvider.java:79) ~[player.jar:1.6.2]
        at xyz.gianlu.librespot.dealer.DealerClient.connect(DealerClient.java:79) ~[player.jar:1.6.2]
        at xyz.gianlu.librespot.dealer.DealerClient.lambda$connectionInvalided$3(DealerClient.java:276) ~[player.jar:1.6.2]
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) [?:?]
        at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?]
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) [?:?]
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) [?:?]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [?:?]
        at java.lang.Thread.run(Thread.java:833) [?:?]
2023-01-15 21:44:42,226 TRACE DealerClient:271 - Scheduled reconnection attempt in 10 seconds...

Version/Commit
I couldn't find a version number in the log. I'm using this image: https://hub.docker.com/r/agneev/librespot-java

@AndreKR AndreKR added the bug Something isn't working label Jan 15, 2023
@thedtvn
Copy link

thedtvn commented Apr 20, 2023

same when host on linux

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
bug Something isn't working
Projects
None yet
Development

No branches or pull requests

2 participants