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

Last track not submitted to ListenBrainz #438

Closed
alessandrococco opened this issue Mar 26, 2024 · 12 comments
Closed

Last track not submitted to ListenBrainz #438

alessandrococco opened this issue Mar 26, 2024 · 12 comments
Labels
Milestone

Comments

@alessandrococco
Copy link

Hello,
I noticed a strange behaviour with the ListenBrainz integration:

if I play an album then all songs will be submitted to ListenBrainz, except for the last one: the album ends but the last track will remain in "Listening Now":

image
image

If I start another track (without F5ing the browser) then the previous track will be submitted (with the wrong timestamp):

image

The problem is that if I don't play another track then the last track won't be submitted to ListenBrainz 😿

@littlebitofcode
Copy link

I can confirm the same happens. Not every time tough. My client is Symfonium.

@epoupon
Copy link
Owner

epoupon commented Mar 27, 2024

@littlebitofcode this is maybe another root cause for Symfonium.
Can you ensure Symfonium does send the scrobbling request to LMS? (You can check access logs for that)

@alessandrococco
Copy link
Author

Note: I have the issue only when I use the web UI. No issues with Ultrasonic nor Supersonic (so far 😄)

@littlebitofcode
Copy link

I will check. On desktop I am using Supersonic and although it does not send playing now, it always send the scrobble after the track finish. So I would think it is on Symfonium side.

I think Symfonium sends the scrobble coz it is showing as playing now but maybe doesn't send finish flag when the track is the last one in the query.

@littlebitofcode
Copy link

littlebitofcode commented Mar 27, 2024

Here is the log from the last sucessful submit.
The behavior is as follows: The last track in the list it is sent to Listenbrainz 'playing now' but when the track finished it just hangs there without being pushed into 'recent listens'.
If I start listening to a new list within something like 5 minutes, the track in 'playing now' will be pushed into 'recent listens', otherwise it wont be submitted.
I suppose Listenbrainz will remove the track from 'playing now' after certain amount of time.

Cheers

[2024-Mar-27 12:02:20.381] 1 - [debug] - 139959211498296 [SCROBBLING] [listenbrainz] LISTEN CREATED for user wojtek, track 'Violin Concerto in C major, Hob. VII A no. 1: II. Cadenza' AT Wed Mar 27 12:02:20 2024
[2024-Mar-27 12:02:20.381] 1 - [debug] - 139959211498296 [API_SUBSONIC] Request 2932 '/scrobble' handled!
[2024-Mar-27 12:02:20.381] 1 - [info] "wthttp: 192.168.1.118   GET /rest/scrobble.view?id=tr-10803&time=1711540940000&submission=true&u=wojtek&p=enc%REDACTED&v=1.13.0&c=Symfonium&f=json HTTP/1.1 200 109"
[2024-Mar-27 12:02:20.381] 1 - [info] "WebRequest: took 8.531 ms"
[2024-Mar-27 12:02:20.395] 1 - [debug] - 139959211195192 [API_SUBSONIC] Handling request 2933 '/deleteBookmark.view', continuation = false, params = {c=Symfonium}, {f=json}, {id=tr-10803}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:02:20.395] 1 - [debug] - 139959211195192 [AUTH] Checking password for user 'wojtek'
[2024-Mar-27 12:02:20.395] 1 - [debug] - 139959211195192 [AUTH] Checking internal password for user 'wojtek'
[2024-Mar-27 12:02:20.406] 1 - [error] - 139959211195192 [API_SUBSONIC] Error while processing request '/deleteBookmark', params = [{c=Symfonium}, {f=json}, {id=tr-10803}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, ], code = 70, msg = 'The requested data was not found.'
[2024-Mar-27 12:02:20.407] 1 - [info] "wthttp: 192.168.1.118   GET /rest/deleteBookmark.view?id=tr-10803&u=wojtek&p=enc%REDACTED&v=1.13.0&c=Symfonium&f=json HTTP/1.1 200 179"
[2024-Mar-27 12:02:20.407] 1 - [info] "WebRequest: took 11.506 ms"
[2024-Mar-27 12:02:20.943] 1 - [debug] - 139959211649848 [API_SUBSONIC] Handling request 2934 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:02:20.944] 1 - [debug] - 139959211649848 [UTILS] Written 262144 bytes, range = 2883584-3145727
[2024-Mar-27 12:02:20.944] 1 - [debug] - 139959211649848 [UTILS] Job not complete! Remaining range: 3145728-25993426
[2024-Mar-27 12:02:20.944] 1 - [debug] - 139959211649848 [API_SUBSONIC] Request 2934 '/stream' handled!
[2024-Mar-27 12:02:21.177] 1 - [debug] - 139959211498296 [API_SUBSONIC] Handling request 2935 '/scrobble.view', continuation = false, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {submission=false}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:02:21.177] 1 - [debug] - 139959211498296 [AUTH] Checking password for user 'wojtek'
[2024-Mar-27 12:02:21.177] 1 - [debug] - 139959211498296 [AUTH] Checking internal password for user 'wojtek'
[2024-Mar-27 12:02:21.209] 1 - [debug] - 139959211498296 [API_SUBSONIC] Request 2935 '/scrobble' handled!
[2024-Mar-27 12:02:21.210] 1 - [info] "wthttp: 192.168.1.118   GET /rest/scrobble.view?id=tr-10804&submission=false&u=wojtek&p=enc%REDACTED&v=1.13.0&c=Symfonium&f=json HTTP/1.1 200 109"
[2024-Mar-27 12:02:21.210] 1 - [info] "WebRequest: took 32.942 ms"
[2024-Mar-27 12:02:21.478] 1 - [debug] - 139959216999224 [SCROBBLING] [Http SendQueue] - Client done. status = 200
[2024-Mar-27 12:02:21.478] 1 - [debug] - 139959216999224 [SCROBBLING] [Http SendQueue] - Remaining messages = 29
[2024-Mar-27 12:02:21.479] 1 - [debug] - 139959216999224 [SCROBBLING] [Http SendQueue] - Processing prio 0, request count = 2
[2024-Mar-27 12:02:21.479] 1 - [debug] - 139959216999224 [SCROBBLING] [Http SendQueue] - Sending request to url 'https://api.listenbrainz.org/1/submit-listens'
[2024-Mar-27 12:02:22.651] 1 - [debug] - 139959217302328 [SCROBBLING] [Http SendQueue] - Client done. status = 200
[2024-Mar-27 12:02:22.651] 1 - [debug] - 139959217302328 [SCROBBLING] [Http SendQueue] - Remaining messages = 28
[2024-Mar-27 12:02:22.651] 1 - [debug] - 139959217302328 [SCROBBLING] [Http SendQueue] - Processing prio 0, request count = 1
[2024-Mar-27 12:02:22.651] 1 - [debug] - 139959217302328 [SCROBBLING] [Http SendQueue] - Sending request to url 'https://api.listenbrainz.org/1/submit-listens'
[2024-Mar-27 12:02:23.546] 1 - [debug] - 139959211195192 [API_SUBSONIC] Handling request 2936 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:02:23.547] 1 - [debug] - 139959211195192 [UTILS] Written 262144 bytes, range = 3145728-3407871
[2024-Mar-27 12:02:23.547] 1 - [debug] - 139959211195192 [UTILS] Job not complete! Remaining range: 3407872-25993426
[2024-Mar-27 12:02:23.547] 1 - [debug] - 139959211195192 [API_SUBSONIC] Request 2936 '/stream' handled!
[2024-Mar-27 12:02:23.787] 1 - [debug] - 139959217150776 [SCROBBLING] [Http SendQueue] - Client done. status = 200
[2024-Mar-27 12:02:23.787] 1 - [debug] - 139959217150776 [SCROBBLING] [Http SendQueue] - Remaining messages = 27
[2024-Mar-27 12:02:23.787] 1 - [debug] - 139959217150776 [SCROBBLING] [Http SendQueue] - Processing prio 0, request count = 0
[2024-Mar-27 12:02:23.787] 1 - [debug] - 139959217150776 [SCROBBLING] [Http SendQueue] - Processing prio 1, request count = 1
[2024-Mar-27 12:02:23.787] 1 - [debug] - 139959217150776 [SCROBBLING] [Http SendQueue] - Sending request to url 'https://api.listenbrainz.org/1/submit-listens'
[2024-Mar-27 12:02:24.952] 1 - [debug] - 139959216999224 [SCROBBLING] [Http SendQueue] - Client done. status = 200
[2024-Mar-27 12:02:24.952] 1 - [debug] - 139959216999224 [SCROBBLING] [Http SendQueue] - Remaining messages = 26
[2024-Mar-27 12:02:24.953] 1 - [debug] - 139959216999224 [SCROBBLING] [Http SendQueue] - Processing prio 0, request count = 0
[2024-Mar-27 12:02:24.953] 1 - [debug] - 139959216999224 [SCROBBLING] [Http SendQueue] - Processing prio 1, request count = 0
[2024-Mar-27 12:02:24.953] 1 - [debug] - 139959216999224 [SCROBBLING] [Http SendQueue] - Processing prio 2, request count = 0
[2024-Mar-27 12:02:26.174] 1 - [debug] - 139959211649848 [API_SUBSONIC] Handling request 2937 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:02:26.176] 1 - [debug] - 139959211649848 [UTILS] Written 262144 bytes, range = 3407872-3670015
[2024-Mar-27 12:02:26.176] 1 - [debug] - 139959211649848 [UTILS] Job not complete! Remaining range: 3670016-25993426
[2024-Mar-27 12:02:26.176] 1 - [debug] - 139959211649848 [API_SUBSONIC] Request 2937 '/stream' handled!
[2024-Mar-27 12:02:28.730] 1 - [debug] - 139959211346744 [API_SUBSONIC] Handling request 2938 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:02:28.732] 1 - [debug] - 139959211346744 [UTILS] Written 262144 bytes, range = 3670016-3932159
[2024-Mar-27 12:02:28.732] 1 - [debug] - 139959211346744 [UTILS] Job not complete! Remaining range: 3932160-25993426
[2024-Mar-27 12:02:28.732] 1 - [debug] - 139959211346744 [API_SUBSONIC] Request 2938 '/stream' handled!
[2024-Mar-27 12:02:28.923] 1 - [debug] - 139959211498296 [API_SUBSONIC] Handling request 2939 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:02:28.924] 1 - [debug] - 139959211498296 [UTILS] Written 262144 bytes, range = 3932160-4194303
[2024-Mar-27 12:02:28.924] 1 - [debug] - 139959211498296 [UTILS] Job not complete! Remaining range: 4194304-25993426
[2024-Mar-27 12:02:28.924] 1 - [debug] - 139959211498296 [API_SUBSONIC] Request 2939 '/stream' handled!
[2024-Mar-27 12:02:31.488] 1 - [debug] - 139959211195192 [API_SUBSONIC] Handling request 2940 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:02:31.489] 1 - [debug] - 139959211195192 [UTILS] Written 262144 bytes, range = 4194304-4456447
[2024-Mar-27 12:02:31.489] 1 - [debug] - 139959211195192 [UTILS] Job not complete! Remaining range: 4456448-25993426
[2024-Mar-27 12:02:31.489] 1 - [debug] - 139959211195192 [API_SUBSONIC] Request 2940 '/stream' handled!
[2024-Mar-27 12:02:34.124] 1 - [debug] - 139959211649848 [API_SUBSONIC] Handling request 2941 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:02:34.125] 1 - [debug] - 139959211649848 [UTILS] Written 262144 bytes, range = 4456448-4718591
[2024-Mar-27 12:02:34.134] 1 - [debug] - 139959211649848 [UTILS] Job not complete! Remaining range: 4718592-25993426
[2024-Mar-27 12:02:34.134] 1 - [debug] - 139959211649848 [API_SUBSONIC] Request 2941 '/stream' handled!
[2024-Mar-27 12:02:36.679] 1 - [debug] - 139959211498296 [API_SUBSONIC] Handling request 2942 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:02:36.680] 1 - [debug] - 139959211498296 [UTILS] Written 262144 bytes, range = 4718592-4980735
[2024-Mar-27 12:02:36.680] 1 - [debug] - 139959211498296 [UTILS] Job not complete! Remaining range: 4980736-25993426
[2024-Mar-27 12:02:36.681] 1 - [debug] - 139959211498296 [API_SUBSONIC] Request 2942 '/stream' handled!
[2024-Mar-27 12:02:39.319] 1 - [debug] - 139959211346744 [API_SUBSONIC] Handling request 2943 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:02:39.321] 1 - [debug] - 139959211346744 [UTILS] Written 262144 bytes, range = 4980736-5242879
[2024-Mar-27 12:02:39.329] 1 - [debug] - 139959211346744 [UTILS] Job not complete! Remaining range: 5242880-25993426
[2024-Mar-27 12:02:39.330] 1 - [debug] - 139959211346744 [API_SUBSONIC] Request 2943 '/stream' handled!
[2024-Mar-27 12:02:41.880] 1 - [debug] - 139959211195192 [API_SUBSONIC] Handling request 2944 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:02:41.881] 1 - [debug] - 139959211195192 [UTILS] Written 262144 bytes, range = 5242880-5505023
[2024-Mar-27 12:02:41.881] 1 - [debug] - 139959211195192 [UTILS] Job not complete! Remaining range: 5505024-25993426
[2024-Mar-27 12:02:41.882] 1 - [debug] - 139959211195192 [API_SUBSONIC] Request 2944 '/stream' handled!
[2024-Mar-27 12:02:44.417] 1 - [debug] - 139959211649848 [API_SUBSONIC] Handling request 2945 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:02:44.418] 1 - [debug] - 139959211649848 [UTILS] Written 262144 bytes, range = 5505024-5767167
[2024-Mar-27 12:02:44.427] 1 - [debug] - 139959211649848 [UTILS] Job not complete! Remaining range: 5767168-25993426
[2024-Mar-27 12:02:44.427] 1 - [debug] - 139959211649848 [API_SUBSONIC] Request 2945 '/stream' handled!
[2024-Mar-27 12:02:47.039] 1 - [debug] - 139959211346744 [API_SUBSONIC] Handling request 2946 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:02:47.041] 1 - [debug] - 139959211346744 [UTILS] Written 262144 bytes, range = 5767168-6029311
[2024-Mar-27 12:02:47.041] 1 - [debug] - 139959211346744 [UTILS] Job not complete! Remaining range: 6029312-25993426
[2024-Mar-27 12:02:47.041] 1 - [debug] - 139959211346744 [API_SUBSONIC] Request 2946 '/stream' handled!
[2024-Mar-27 12:02:47.857] 1 - [debug] - 139959211649848 [API_SUBSONIC] Handling request 2947 '/ping.view', continuation = false, params = {c=Symfonium}, {f=json}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:02:47.857] 1 - [debug] - 139959211649848 [AUTH] Checking password for user 'wojtek'
[2024-Mar-27 12:02:47.857] 1 - [debug] - 139959211649848 [AUTH] Checking internal password for user 'wojtek'
[2024-Mar-27 12:02:47.870] 1 - [debug] - 139959211649848 [API_SUBSONIC] Request 2947 '/ping' handled!
[2024-Mar-27 12:02:47.870] 1 - [info] "wthttp: 192.168.1.118   GET /rest/ping.view?u=wojtek&p=enc%REDACTED&v=1.13.0&c=Symfonium&f=json HTTP/1.1 200 109"
[2024-Mar-27 12:02:47.870] 1 - [info] "WebRequest: took 13.294 ms"
[2024-Mar-27 12:02:49.800] 1 - [debug] - 139959211498296 [API_SUBSONIC] Handling request 2948 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:02:49.801] 1 - [debug] - 139959211498296 [UTILS] Written 262144 bytes, range = 6029312-6291455
[2024-Mar-27 12:02:49.801] 1 - [debug] - 139959211498296 [UTILS] Job not complete! Remaining range: 6291456-25993426
[2024-Mar-27 12:02:49.801] 1 - [debug] - 139959211498296 [API_SUBSONIC] Request 2948 '/stream' handled!
[2024-Mar-27 12:02:52.271] 1 - [debug] - 139959211649848 [API_SUBSONIC] Handling request 2949 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:02:52.277] 1 - [debug] - 139959211649848 [UTILS] Written 262144 bytes, range = 6291456-6553599
[2024-Mar-27 12:02:52.277] 1 - [debug] - 139959211649848 [UTILS] Job not complete! Remaining range: 6553600-25993426
[2024-Mar-27 12:02:52.277] 1 - [debug] - 139959211649848 [API_SUBSONIC] Request 2949 '/stream' handled!
[2024-Mar-27 12:02:52.484] 1 - [debug] - 139959211195192 [API_SUBSONIC] Handling request 2950 '/scrobble.view', continuation = false, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {submission=false}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:02:52.484] 1 - [debug] - 139959211195192 [AUTH] Checking password for user 'wojtek'
[2024-Mar-27 12:02:52.484] 1 - [debug] - 139959211195192 [AUTH] Checking internal password for user 'wojtek'
[2024-Mar-27 12:02:52.497] 1 - [debug] - 139959211195192 [API_SUBSONIC] Request 2950 '/scrobble' handled!
[2024-Mar-27 12:02:52.497] 1 - [debug] - 139959216847672 [SCROBBLING] [Http SendQueue] - Processing prio 0, request count = 1
[2024-Mar-27 12:02:52.497] 1 - [debug] - 139959216847672 [SCROBBLING] [Http SendQueue] - Sending request to url 'https://api.listenbrainz.org/1/submit-listens'
[2024-Mar-27 12:02:52.497] 1 - [info] "wthttp: 192.168.1.118   GET /rest/scrobble.view?id=tr-10804&submission=false&u=wojtek&p=enc%REDACTED&v=1.13.0&c=Symfonium&f=json HTTP/1.1 200 109"
[2024-Mar-27 12:02:52.497] 1 - [info] "WebRequest: took 13.356 ms"
[2024-Mar-27 12:02:53.955] 1 - [debug] - 139959216999224 [SCROBBLING] [Http SendQueue] - Client done. status = 200
[2024-Mar-27 12:02:53.955] 1 - [debug] - 139959216999224 [SCROBBLING] [Http SendQueue] - Remaining messages = 29
[2024-Mar-27 12:02:53.955] 1 - [debug] - 139959216999224 [SCROBBLING] [Http SendQueue] - Processing prio 0, request count = 0
[2024-Mar-27 12:02:53.955] 1 - [debug] - 139959216999224 [SCROBBLING] [Http SendQueue] - Processing prio 1, request count = 0
[2024-Mar-27 12:02:53.955] 1 - [debug] - 139959216999224 [SCROBBLING] [Http SendQueue] - Processing prio 2, request count = 0
[2024-Mar-27 12:02:54.888] 1 - [debug] - 139959211498296 [API_SUBSONIC] Handling request 2951 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:02:54.889] 1 - [debug] - 139959211498296 [UTILS] Written 262144 bytes, range = 6553600-6815743
[2024-Mar-27 12:02:54.898] 1 - [debug] - 139959211498296 [UTILS] Job not complete! Remaining range: 6815744-25993426
[2024-Mar-27 12:02:54.898] 1 - [debug] - 139959211498296 [API_SUBSONIC] Request 2951 '/stream' handled!
[2024-Mar-27 12:02:57.628] 1 - [debug] - 139959211195192 [API_SUBSONIC] Handling request 2952 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:02:57.629] 1 - [debug] - 139959211195192 [UTILS] Written 262144 bytes, range = 6815744-7077887
[2024-Mar-27 12:02:57.629] 1 - [debug] - 139959211195192 [UTILS] Job not complete! Remaining range: 7077888-25993426
[2024-Mar-27 12:02:57.629] 1 - [debug] - 139959211195192 [API_SUBSONIC] Request 2952 '/stream' handled!
[2024-Mar-27 12:02:59.995] 1 - [debug] - 139959211649848 [API_SUBSONIC] Handling request 2953 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:02:59.997] 1 - [debug] - 139959211649848 [UTILS] Written 262144 bytes, range = 7077888-7340031
[2024-Mar-27 12:03:00.000] 1 - [debug] - 139959211649848 [UTILS] Job not complete! Remaining range: 7340032-25993426
[2024-Mar-27 12:03:00.001] 1 - [debug] - 139959211649848 [API_SUBSONIC] Request 2953 '/stream' handled!
[2024-Mar-27 12:03:02.549] 1 - [debug] - 139959211195192 [API_SUBSONIC] Handling request 2954 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:03:02.550] 1 - [debug] - 139959211195192 [UTILS] Written 262144 bytes, range = 7340032-7602175
[2024-Mar-27 12:03:02.550] 1 - [debug] - 139959211195192 [UTILS] Job not complete! Remaining range: 7602176-25993426
[2024-Mar-27 12:03:02.550] 1 - [debug] - 139959211195192 [API_SUBSONIC] Request 2954 '/stream' handled!
[2024-Mar-27 12:03:02.739] 1 - [debug] - 139959211649848 [API_SUBSONIC] Handling request 2955 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:03:02.740] 1 - [debug] - 139959211649848 [UTILS] Written 262144 bytes, range = 7602176-7864319
[2024-Mar-27 12:03:02.740] 1 - [debug] - 139959211649848 [UTILS] Job not complete! Remaining range: 7864320-25993426
[2024-Mar-27 12:03:02.741] 1 - [debug] - 139959211649848 [API_SUBSONIC] Request 2955 '/stream' handled!
[2024-Mar-27 12:03:05.318] 1 - [debug] - 139959211346744 [API_SUBSONIC] Handling request 2956 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:03:05.318] 1 - [debug] - 139959211346744 [UTILS] Written 262144 bytes, range = 7864320-8126463
[2024-Mar-27 12:03:05.327] 1 - [debug] - 139959211346744 [UTILS] Job not complete! Remaining range: 8126464-25993426
[2024-Mar-27 12:03:05.327] 1 - [debug] - 139959211346744 [API_SUBSONIC] Request 2956 '/stream' handled!
[2024-Mar-27 12:03:10.396] 1 - [debug] - 139959211649848 [API_SUBSONIC] Handling request 2957 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:03:10.397] 1 - [debug] - 139959211649848 [UTILS] Written 262144 bytes, range = 8126464-8388607
[2024-Mar-27 12:03:10.406] 1 - [debug] - 139959211649848 [UTILS] Job not complete! Remaining range: 8388608-25993426
[2024-Mar-27 12:03:10.406] 1 - [debug] - 139959211649848 [API_SUBSONIC] Request 2957 '/stream' handled!
[2024-Mar-27 12:03:12.994] 1 - [debug] - 139959211346744 [API_SUBSONIC] Handling request 2958 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:03:12.996] 1 - [debug] - 139959211346744 [UTILS] Written 262144 bytes, range = 8388608-8650751
[2024-Mar-27 12:03:12.996] 1 - [debug] - 139959211346744 [UTILS] Job not complete! Remaining range: 8650752-25993426
[2024-Mar-27 12:03:12.996] 1 - [debug] - 139959211346744 [API_SUBSONIC] Request 2958 '/stream' handled!
[2024-Mar-27 12:03:15.646] 1 - [debug] - 139959211195192 [API_SUBSONIC] Handling request 2959 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:03:15.647] 1 - [debug] - 139959211195192 [UTILS] Written 262144 bytes, range = 8650752-8912895
[2024-Mar-27 12:03:15.656] 1 - [debug] - 139959211195192 [UTILS] Job not complete! Remaining range: 8912896-25993426
[2024-Mar-27 12:03:15.656] 1 - [debug] - 139959211195192 [API_SUBSONIC] Request 2959 '/stream' handled!
[2024-Mar-27 12:03:18.200] 1 - [debug] - 139959211498296 [API_SUBSONIC] Handling request 2960 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:03:18.201] 1 - [debug] - 139959211498296 [UTILS] Written 262144 bytes, range = 8912896-9175039
[2024-Mar-27 12:03:18.201] 1 - [debug] - 139959211498296 [UTILS] Job not complete! Remaining range: 9175040-25993426
[2024-Mar-27 12:03:18.201] 1 - [debug] - 139959211498296 [API_SUBSONIC] Request 2960 '/stream' handled!
[2024-Mar-27 12:03:20.792] 1 - [debug] - 139959211649848 [API_SUBSONIC] Handling request 2961 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:03:20.792] 1 - [debug] - 139959211649848 [UTILS] Written 262144 bytes, range = 9175040-9437183
[2024-Mar-27 12:03:20.792] 1 - [debug] - 139959211649848 [UTILS] Job not complete! Remaining range: 9437184-25993426
[2024-Mar-27 12:03:20.792] 1 - [debug] - 139959211649848 [API_SUBSONIC] Request 2961 '/stream' handled!
[2024-Mar-27 12:03:23.056] 1 - [debug] - 139959211346744 [API_SUBSONIC] Handling request 2962 '/scrobble.view', continuation = false, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {submission=false}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:03:23.056] 1 - [debug] - 139959211346744 [AUTH] Checking password for user 'wojtek'
[2024-Mar-27 12:03:23.056] 1 - [debug] - 139959211346744 [AUTH] Checking internal password for user 'wojtek'
[2024-Mar-27 12:03:23.069] 1 - [debug] - 139959211346744 [API_SUBSONIC] Request 2962 '/scrobble' handled!
[2024-Mar-27 12:03:23.069] 1 - [debug] - 139959216847672 [SCROBBLING] [Http SendQueue] - Processing prio 0, request count = 1
[2024-Mar-27 12:03:23.069] 1 - [debug] - 139959216847672 [SCROBBLING] [Http SendQueue] - Sending request to url 'https://api.listenbrainz.org/1/submit-listens'
[2024-Mar-27 12:03:23.069] 1 - [info] "wthttp: 192.168.1.118   GET /rest/scrobble.view?id=tr-10804&submission=false&u=wojtek&p=enc%REDACTED&v=1.13.0&c=Symfonium&f=json HTTP/1.1 200 109"
[2024-Mar-27 12:03:23.069] 1 - [info] "WebRequest: took 13.768 ms"
[2024-Mar-27 12:03:23.350] 1 - [debug] - 139959211498296 [API_SUBSONIC] Handling request 2963 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:03:23.352] 1 - [debug] - 139959211498296 [UTILS] Written 262144 bytes, range = 9437184-9699327
[2024-Mar-27 12:03:23.352] 1 - [debug] - 139959211498296 [UTILS] Job not complete! Remaining range: 9699328-25993426
[2024-Mar-27 12:03:23.352] 1 - [debug] - 139959211498296 [API_SUBSONIC] Request 2963 '/stream' handled!
[2024-Mar-27 12:03:24.207] 1 - [debug] - 139959216999224 [SCROBBLING] [Http SendQueue] - Client done. status = 200
[2024-Mar-27 12:03:24.208] 1 - [debug] - 139959216999224 [SCROBBLING] [Http SendQueue] - Remaining messages = 29
[2024-Mar-27 12:03:24.208] 1 - [debug] - 139959216999224 [SCROBBLING] [Http SendQueue] - Processing prio 0, request count = 0
[2024-Mar-27 12:03:24.208] 1 - [debug] - 139959216999224 [SCROBBLING] [Http SendQueue] - Processing prio 1, request count = 0
[2024-Mar-27 12:03:24.208] 1 - [debug] - 139959216999224 [SCROBBLING] [Http SendQueue] - Processing prio 2, request count = 0
[2024-Mar-27 12:03:25.935] 1 - [debug] - 139959211195192 [API_SUBSONIC] Handling request 2964 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:03:25.936] 1 - [debug] - 139959211195192 [UTILS] Written 262144 bytes, range = 9699328-9961471
[2024-Mar-27 12:03:25.945] 1 - [debug] - 139959211195192 [UTILS] Job not complete! Remaining range: 9961472-25993426
[2024-Mar-27 12:03:25.945] 1 - [debug] - 139959211195192 [API_SUBSONIC] Request 2964 '/stream' handled!
[2024-Mar-27 12:03:27.901] 1 - [debug] - 139959211346744 [API_SUBSONIC] Handling request 2965 '/ping.view', continuation = false, params = {c=Symfonium}, {f=json}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:03:27.902] 1 - [debug] - 139959211346744 [AUTH] Checking password for user 'wojtek'
[2024-Mar-27 12:03:27.902] 1 - [debug] - 139959211346744 [AUTH] Checking internal password for user 'wojtek'
[2024-Mar-27 12:03:27.915] 1 - [debug] - 139959211346744 [API_SUBSONIC] Request 2965 '/ping' handled!
[2024-Mar-27 12:03:27.915] 1 - [info] "wthttp: 192.168.1.118   GET /rest/ping.view?u=wojtek&p=enc%REDACTED&v=1.13.0&c=Symfonium&f=json HTTP/1.1 200 109"
[2024-Mar-27 12:03:27.915] 1 - [info] "WebRequest: took 13.613 ms"
[2024-Mar-27 12:03:28.543] 1 - [debug] - 139959211649848 [API_SUBSONIC] Handling request 2966 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:03:28.544] 1 - [debug] - 139959211649848 [UTILS] Written 262144 bytes, range = 9961472-10223615
[2024-Mar-27 12:03:28.544] 1 - [debug] - 139959211649848 [UTILS] Job not complete! Remaining range: 10223616-25993426
[2024-Mar-27 12:03:28.544] 1 - [debug] - 139959211649848 [API_SUBSONIC] Request 2966 '/stream' handled!
[2024-Mar-27 12:03:31.137] 1 - [debug] - 139959211498296 [API_SUBSONIC] Handling request 2967 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:03:31.139] 1 - [debug] - 139959211498296 [UTILS] Written 262144 bytes, range = 10223616-10485759
[2024-Mar-27 12:03:31.147] 1 - [debug] - 139959211498296 [UTILS] Job not complete! Remaining range: 10485760-25993426
[2024-Mar-27 12:03:31.147] 1 - [debug] - 139959211498296 [API_SUBSONIC] Request 2967 '/stream' handled!
[2024-Mar-27 12:03:33.740] 1 - [debug] - 139959211346744 [API_SUBSONIC] Handling request 2968 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:03:33.741] 1 - [debug] - 139959211346744 [UTILS] Written 262144 bytes, range = 10485760-10747903
[2024-Mar-27 12:03:33.741] 1 - [debug] - 139959211346744 [UTILS] Job not complete! Remaining range: 10747904-25993426
[2024-Mar-27 12:03:33.741] 1 - [debug] - 139959211346744 [API_SUBSONIC] Request 2968 '/stream' handled!
[2024-Mar-27 12:03:36.404] 1 - [debug] - 139959211195192 [API_SUBSONIC] Handling request 2969 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:03:36.405] 1 - [debug] - 139959211195192 [UTILS] Written 262144 bytes, range = 10747904-11010047
[2024-Mar-27 12:03:36.405] 1 - [debug] - 139959211195192 [UTILS] Job not complete! Remaining range: 11010048-25993426
[2024-Mar-27 12:03:36.406] 1 - [debug] - 139959211195192 [API_SUBSONIC] Request 2969 '/stream' handled!
[2024-Mar-27 12:03:36.604] 1 - [debug] - 139959211649848 [API_SUBSONIC] Handling request 2970 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:03:36.605] 1 - [debug] - 139959211649848 [UTILS] Written 262144 bytes, range = 11010048-11272191
[2024-Mar-27 12:03:36.605] 1 - [debug] - 139959211649848 [UTILS] Job not complete! Remaining range: 11272192-25993426
[2024-Mar-27 12:03:36.606] 1 - [debug] - 139959211649848 [API_SUBSONIC] Request 2970 '/stream' handled!
[2024-Mar-27 12:03:41.534] 1 - [debug] - 139959211346744 [API_SUBSONIC] Handling request 2971 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:03:41.535] 1 - [debug] - 139959211346744 [UTILS] Written 262144 bytes, range = 11272192-11534335
[2024-Mar-27 12:03:41.535] 1 - [debug] - 139959211346744 [UTILS] Job not complete! Remaining range: 11534336-25993426
[2024-Mar-27 12:03:41.535] 1 - [debug] - 139959211346744 [API_SUBSONIC] Request 2971 '/stream' handled!
[2024-Mar-27 12:03:41.698] 1 - [debug] - 139959211195192 [API_SUBSONIC] Handling request 2972 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:03:41.700] 1 - [debug] - 139959211195192 [UTILS] Written 262144 bytes, range = 11534336-11796479
[2024-Mar-27 12:03:41.700] 1 - [debug] - 139959211195192 [UTILS] Job not complete! Remaining range: 11796480-25993426
[2024-Mar-27 12:03:41.700] 1 - [debug] - 139959211195192 [API_SUBSONIC] Request 2972 '/stream' handled!
[2024-Mar-27 12:03:44.251] 1 - [debug] - 139959211649848 [API_SUBSONIC] Handling request 2973 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:03:44.252] 1 - [debug] - 139959211649848 [UTILS] Written 262144 bytes, range = 11796480-12058623
[2024-Mar-27 12:03:44.252] 1 - [debug] - 139959211649848 [UTILS] Job not complete! Remaining range: 12058624-25993426
[2024-Mar-27 12:03:44.252] 1 - [debug] - 139959211649848 [API_SUBSONIC] Request 2973 '/stream' handled!
[2024-Mar-27 12:03:49.324] 1 - [debug] - 139959211498296 [API_SUBSONIC] Handling request 2974 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:03:49.326] 1 - [debug] - 139959211498296 [UTILS] Written 262144 bytes, range = 12058624-12320767
[2024-Mar-27 12:03:49.326] 1 - [debug] - 139959211498296 [UTILS] Job not complete! Remaining range: 12320768-25993426
[2024-Mar-27 12:03:49.326] 1 - [debug] - 139959211498296 [API_SUBSONIC] Request 2974 '/stream' handled!
[2024-Mar-27 12:03:49.447] 1 - [debug] - 139959211195192 [API_SUBSONIC] Handling request 2975 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:03:49.449] 1 - [debug] - 139959211195192 [UTILS] Written 262144 bytes, range = 12320768-12582911
[2024-Mar-27 12:03:49.449] 1 - [debug] - 139959211195192 [UTILS] Job not complete! Remaining range: 12582912-25993426
[2024-Mar-27 12:03:49.449] 1 - [debug] - 139959211195192 [API_SUBSONIC] Request 2975 '/stream' handled!
[2024-Mar-27 12:03:52.155] 1 - [debug] - 139959211649848 [API_SUBSONIC] Handling request 2976 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:03:52.156] 1 - [debug] - 139959211649848 [UTILS] Written 262144 bytes, range = 12582912-12845055
[2024-Mar-27 12:03:52.157] 1 - [debug] - 139959211649848 [UTILS] Job not complete! Remaining range: 12845056-25993426
[2024-Mar-27 12:03:52.157] 1 - [debug] - 139959211649848 [API_SUBSONIC] Request 2976 '/stream' handled!
[2024-Mar-27 12:03:54.651] 1 - [debug] - 139959211346744 [API_SUBSONIC] Handling request 2977 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:03:54.653] 1 - [debug] - 139959211346744 [UTILS] Written 262144 bytes, range = 12845056-13107199
[2024-Mar-27 12:03:54.653] 1 - [debug] - 139959211346744 [UTILS] Job not complete! Remaining range: 13107200-25993426
[2024-Mar-27 12:03:54.653] 1 - [debug] - 139959211346744 [API_SUBSONIC] Request 2977 '/stream' handled!
[2024-Mar-27 12:03:54.844] 1 - [debug] - 139959211195192 [API_SUBSONIC] Handling request 2978 '/scrobble.view', continuation = false, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {submission=false}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:03:54.845] 1 - [debug] - 139959211195192 [AUTH] Checking password for user 'wojtek'
[2024-Mar-27 12:03:54.845] 1 - [debug] - 139959211195192 [AUTH] Checking internal password for user 'wojtek'
[2024-Mar-27 12:03:54.858] 1 - [debug] - 139959211195192 [API_SUBSONIC] Request 2978 '/scrobble' handled!
[2024-Mar-27 12:03:54.858] 1 - [debug] - 139959216847672 [SCROBBLING] [Http SendQueue] - Processing prio 0, request count = 1
[2024-Mar-27 12:03:54.858] 1 - [debug] - 139959216847672 [SCROBBLING] [Http SendQueue] - Sending request to url 'https://api.listenbrainz.org/1/submit-listens'
[2024-Mar-27 12:03:54.858] 1 - [info] "wthttp: 192.168.1.118   GET /rest/scrobble.view?id=tr-10804&submission=false&u=wojtek&p=enc%REDACTED&v=1.13.0&c=Symfonium&f=json HTTP/1.1 200 109"
[2024-Mar-27 12:03:54.858] 1 - [info] "WebRequest: took 13.293 ms"
[2024-Mar-27 12:03:55.991] 1 - [debug] - 139959217150776 [SCROBBLING] [Http SendQueue] - Client done. status = 200
[2024-Mar-27 12:03:55.991] 1 - [debug] - 139959217150776 [SCROBBLING] [Http SendQueue] - Remaining messages = 29
[2024-Mar-27 12:03:55.991] 1 - [debug] - 139959217150776 [SCROBBLING] [Http SendQueue] - Processing prio 0, request count = 0
[2024-Mar-27 12:03:55.991] 1 - [debug] - 139959217150776 [SCROBBLING] [Http SendQueue] - Processing prio 1, request count = 0
[2024-Mar-27 12:03:55.991] 1 - [debug] - 139959217150776 [SCROBBLING] [Http SendQueue] - Processing prio 2, request count = 0
[2024-Mar-27 12:03:57.291] 1 - [debug] - 139959211649848 [API_SUBSONIC] Handling request 2979 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:03:57.293] 1 - [debug] - 139959211649848 [UTILS] Written 262144 bytes, range = 13107200-13369343
[2024-Mar-27 12:03:57.293] 1 - [debug] - 139959211649848 [UTILS] Job not complete! Remaining range: 13369344-25993426
[2024-Mar-27 12:03:57.293] 1 - [debug] - 139959211649848 [API_SUBSONIC] Request 2979 '/stream' handled!
[2024-Mar-27 12:03:59.851] 1 - [debug] - 139959211498296 [API_SUBSONIC] Handling request 2980 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:03:59.852] 1 - [debug] - 139959211498296 [UTILS] Written 262144 bytes, range = 13369344-13631487
[2024-Mar-27 12:03:59.853] 1 - [debug] - 139959211498296 [UTILS] Job not complete! Remaining range: 13631488-25993426
[2024-Mar-27 12:03:59.853] 1 - [debug] - 139959211498296 [API_SUBSONIC] Request 2980 '/stream' handled!
[2024-Mar-27 12:04:02.438] 1 - [debug] - 139959211195192 [API_SUBSONIC] Handling request 2981 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:04:02.439] 1 - [debug] - 139959211195192 [UTILS] Written 262144 bytes, range = 13631488-13893631
[2024-Mar-27 12:04:02.448] 1 - [debug] - 139959211195192 [UTILS] Job not complete! Remaining range: 13893632-25993426
[2024-Mar-27 12:04:02.448] 1 - [debug] - 139959211195192 [API_SUBSONIC] Request 2981 '/stream' handled!
[2024-Mar-27 12:04:05.062] 1 - [debug] - 139959211649848 [API_SUBSONIC] Handling request 2982 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:04:05.063] 1 - [debug] - 139959211649848 [UTILS] Written 262144 bytes, range = 13893632-14155775
[2024-Mar-27 12:04:05.063] 1 - [debug] - 139959211649848 [UTILS] Job not complete! Remaining range: 14155776-25993426
[2024-Mar-27 12:04:05.064] 1 - [debug] - 139959211649848 [API_SUBSONIC] Request 2982 '/stream' handled!
[2024-Mar-27 12:04:07.950] 1 - [debug] - 139959211195192 [API_SUBSONIC] Handling request 2983 '/ping.view', continuation = false, params = {c=Symfonium}, {f=json}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:04:07.950] 1 - [debug] - 139959211195192 [AUTH] Checking password for user 'wojtek'
[2024-Mar-27 12:04:07.950] 1 - [debug] - 139959211195192 [AUTH] Checking internal password for user 'wojtek'
[2024-Mar-27 12:04:07.963] 1 - [debug] - 139959211195192 [API_SUBSONIC] Request 2983 '/ping' handled!
[2024-Mar-27 12:04:07.963] 1 - [info] "wthttp: 192.168.1.118   GET /rest/ping.view?u=wojtek&p=enc%REDACTED&v=1.13.0&c=Symfonium&f=json HTTP/1.1 200 109"
[2024-Mar-27 12:04:07.963] 1 - [info] "WebRequest: took 13.538 ms"
[2024-Mar-27 12:04:10.220] 1 - [debug] - 139959211649848 [API_SUBSONIC] Handling request 2984 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:04:10.221] 1 - [debug] - 139959211649848 [UTILS] Written 262144 bytes, range = 14155776-14417919
[2024-Mar-27 12:04:10.221] 1 - [debug] - 139959211649848 [UTILS] Job not complete! Remaining range: 14417920-25993426
[2024-Mar-27 12:04:10.221] 1 - [debug] - 139959211649848 [API_SUBSONIC] Request 2984 '/stream' handled!
[2024-Mar-27 12:04:12.835] 1 - [debug] - 139959211195192 [API_SUBSONIC] Handling request 2985 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:04:12.837] 1 - [debug] - 139959211195192 [UTILS] Written 262144 bytes, range = 14417920-14680063
[2024-Mar-27 12:04:12.837] 1 - [debug] - 139959211195192 [UTILS] Job not complete! Remaining range: 14680064-25993426
[2024-Mar-27 12:04:12.837] 1 - [debug] - 139959211195192 [API_SUBSONIC] Request 2985 '/stream' handled!
[2024-Mar-27 12:04:15.362] 1 - [debug] - 139959211346744 [API_SUBSONIC] Handling request 2986 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:04:15.364] 1 - [debug] - 139959211346744 [UTILS] Written 262144 bytes, range = 14680064-14942207
[2024-Mar-27 12:04:15.364] 1 - [debug] - 139959211346744 [UTILS] Job not complete! Remaining range: 14942208-25993426
[2024-Mar-27 12:04:15.364] 1 - [debug] - 139959211346744 [API_SUBSONIC] Request 2986 '/stream' handled!
[2024-Mar-27 12:04:17.965] 1 - [debug] - 139959211498296 [API_SUBSONIC] Handling request 2987 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:04:17.966] 1 - [debug] - 139959211498296 [UTILS] Written 262144 bytes, range = 14942208-15204351
[2024-Mar-27 12:04:17.975] 1 - [debug] - 139959211498296 [UTILS] Job not complete! Remaining range: 15204352-25993426
[2024-Mar-27 12:04:17.975] 1 - [debug] - 139959211498296 [API_SUBSONIC] Request 2987 '/stream' handled!
[2024-Mar-27 12:04:18.111] 1 - [debug] - 139959211195192 [API_SUBSONIC] Handling request 2988 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:04:18.113] 1 - [debug] - 139959211195192 [UTILS] Written 262144 bytes, range = 15204352-15466495
[2024-Mar-27 12:04:18.113] 1 - [debug] - 139959211195192 [UTILS] Job not complete! Remaining range: 15466496-25993426
[2024-Mar-27 12:04:18.113] 1 - [debug] - 139959211195192 [API_SUBSONIC] Request 2988 '/stream' handled!
[2024-Mar-27 12:04:20.647] 1 - [debug] - 139959211649848 [API_SUBSONIC] Handling request 2989 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:04:20.648] 1 - [debug] - 139959211649848 [UTILS] Written 262144 bytes, range = 15466496-15728639
[2024-Mar-27 12:04:20.648] 1 - [debug] - 139959211649848 [UTILS] Job not complete! Remaining range: 15728640-25993426
[2024-Mar-27 12:04:20.648] 1 - [debug] - 139959211649848 [API_SUBSONIC] Request 2989 '/stream' handled!
[2024-Mar-27 12:04:23.300] 1 - [debug] - 139959211498296 [API_SUBSONIC] Handling request 2990 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:04:23.301] 1 - [debug] - 139959211498296 [UTILS] Written 262144 bytes, range = 15728640-15990783
[2024-Mar-27 12:04:23.309] 1 - [debug] - 139959211498296 [UTILS] Job not complete! Remaining range: 15990784-25993426
[2024-Mar-27 12:04:23.310] 1 - [debug] - 139959211498296 [API_SUBSONIC] Request 2990 '/stream' handled!
[2024-Mar-27 12:04:25.846] 1 - [debug] - 139959211649848 [API_SUBSONIC] Handling request 2991 '/scrobble.view', continuation = false, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {submission=false}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:04:25.847] 1 - [debug] - 139959211649848 [AUTH] Checking password for user 'wojtek'
[2024-Mar-27 12:04:25.847] 1 - [debug] - 139959211649848 [AUTH] Checking internal password for user 'wojtek'
[2024-Mar-27 12:04:25.847] 1 - [debug] - 139959211498296 [API_SUBSONIC] Handling request 2992 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:04:25.849] 1 - [debug] - 139959211498296 [UTILS] Written 262144 bytes, range = 15990784-16252927
[2024-Mar-27 12:04:25.849] 1 - [debug] - 139959211498296 [UTILS] Job not complete! Remaining range: 16252928-25993426
[2024-Mar-27 12:04:25.849] 1 - [debug] - 139959211498296 [API_SUBSONIC] Request 2992 '/stream' handled!
[2024-Mar-27 12:04:25.860] 1 - [debug] - 139959211649848 [API_SUBSONIC] Request 2991 '/scrobble' handled!
[2024-Mar-27 12:04:25.860] 1 - [debug] - 139959216847672 [SCROBBLING] [Http SendQueue] - Processing prio 0, request count = 1
[2024-Mar-27 12:04:25.860] 1 - [info] "wthttp: 192.168.1.118   GET /rest/scrobble.view?id=tr-10804&submission=false&u=wojtek&p=enc%REDACTED&v=1.13.0&c=Symfonium&f=json HTTP/1.1 200 109"
[2024-Mar-27 12:04:25.860] 1 - [info] "wthttp: 192.168.1.118   GET /rest/scrobble.view?id=tr-10804&submission=false&u=wojtek&p=enc%REDACTED&v=1.13.0&c=Symfonium&f=json HTTP/1.1 200 109"
[2024-Mar-27 12:04:25.860] 1 - [debug] - 139959216847672 [SCROBBLING] [Http SendQueue] - Sending request to url 'https://api.listenbrainz.org/1/submit-listens'
[2024-Mar-27 12:04:25.860] 1 - [info] "WebRequest: took 13.898 ms"
[2024-Mar-27 12:04:26.991] 1 - [debug] - 139959217150776 [SCROBBLING] [Http SendQueue] - Client done. status = 200
[2024-Mar-27 12:04:26.991] 1 - [debug] - 139959217150776 [SCROBBLING] [Http SendQueue] - Remaining messages = 29
[2024-Mar-27 12:04:26.991] 1 - [debug] - 139959217150776 [SCROBBLING] [Http SendQueue] - Processing prio 0, request count = 0
[2024-Mar-27 12:04:26.991] 1 - [debug] - 139959217150776 [SCROBBLING] [Http SendQueue] - Processing prio 1, request count = 0
[2024-Mar-27 12:04:26.992] 1 - [debug] - 139959217150776 [SCROBBLING] [Http SendQueue] - Processing prio 2, request count = 0
[2024-Mar-27 12:04:28.478] 1 - [debug] - 139959211346744 [API_SUBSONIC] Handling request 2993 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:04:28.479] 1 - [debug] - 139959211346744 [UTILS] Written 262144 bytes, range = 16252928-16515071
[2024-Mar-27 12:04:28.488] 1 - [debug] - 139959211346744 [UTILS] Job not complete! Remaining range: 16515072-25993426
[2024-Mar-27 12:04:28.488] 1 - [debug] - 139959211346744 [API_SUBSONIC] Request 2993 '/stream' handled!
[2024-Mar-27 12:04:31.054] 1 - [debug] - 139959211649848 [API_SUBSONIC] Handling request 2994 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:04:31.056] 1 - [debug] - 139959211649848 [UTILS] Written 262144 bytes, range = 16515072-16777215
[2024-Mar-27 12:04:31.056] 1 - [debug] - 139959211649848 [UTILS] Job not complete! Remaining range: 16777216-25993426
[2024-Mar-27 12:04:31.056] 1 - [debug] - 139959211649848 [API_SUBSONIC] Request 2994 '/stream' handled!
[2024-Mar-27 12:04:33.628] 1 - [debug] - 139959211195192 [API_SUBSONIC] Handling request 2995 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:04:33.629] 1 - [debug] - 139959211195192 [UTILS] Written 262144 bytes, range = 16777216-17039359
[2024-Mar-27 12:04:33.638] 1 - [debug] - 139959211195192 [UTILS] Job not complete! Remaining range: 17039360-25993426
[2024-Mar-27 12:04:33.638] 1 - [debug] - 139959211195192 [API_SUBSONIC] Request 2995 '/stream' handled!
[2024-Mar-27 12:04:36.290] 1 - [debug] - 139959211346744 [API_SUBSONIC] Handling request 2996 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:04:36.291] 1 - [debug] - 139959211346744 [UTILS] Written 262144 bytes, range = 17039360-17301503
[2024-Mar-27 12:04:36.291] 1 - [debug] - 139959211346744 [UTILS] Job not complete! Remaining range: 17301504-25993426
[2024-Mar-27 12:04:36.291] 1 - [debug] - 139959211346744 [API_SUBSONIC] Request 2996 '/stream' handled!
[2024-Mar-27 12:04:38.767] 1 - [debug] - 139959211195192 [API_SUBSONIC] Handling request 2997 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:04:38.769] 1 - [debug] - 139959211195192 [UTILS] Written 262144 bytes, range = 17301504-17563647
[2024-Mar-27 12:04:38.777] 1 - [debug] - 139959211195192 [UTILS] Job not complete! Remaining range: 17563648-25993426
[2024-Mar-27 12:04:38.777] 1 - [debug] - 139959211195192 [API_SUBSONIC] Request 2997 '/stream' handled!
[2024-Mar-27 12:04:41.349] 1 - [debug] - 139959211649848 [API_SUBSONIC] Handling request 2998 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:04:41.349] 1 - [debug] - 139959211649848 [UTILS] Written 262144 bytes, range = 17563648-17825791
[2024-Mar-27 12:04:41.349] 1 - [debug] - 139959211649848 [UTILS] Job not complete! Remaining range: 17825792-25993426
[2024-Mar-27 12:04:41.350] 1 - [debug] - 139959211649848 [API_SUBSONIC] Request 2998 '/stream' handled!
[2024-Mar-27 12:04:41.543] 1 - [debug] - 139959211498296 [API_SUBSONIC] Handling request 2999 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:04:41.545] 1 - [debug] - 139959211498296 [UTILS] Written 262144 bytes, range = 17825792-18087935
[2024-Mar-27 12:04:41.545] 1 - [debug] - 139959211498296 [UTILS] Job not complete! Remaining range: 18087936-25993426
[2024-Mar-27 12:04:41.545] 1 - [debug] - 139959211498296 [API_SUBSONIC] Request 2999 '/stream' handled!
[2024-Mar-27 12:04:44.115] 1 - [debug] - 139959211346744 [API_SUBSONIC] Handling request 3000 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:04:44.117] 1 - [debug] - 139959211346744 [UTILS] Written 262144 bytes, range = 18087936-18350079
[2024-Mar-27 12:04:44.125] 1 - [debug] - 139959211346744 [UTILS] Job not complete! Remaining range: 18350080-25993426
[2024-Mar-27 12:04:44.125] 1 - [debug] - 139959211346744 [API_SUBSONIC] Request 3000 '/stream' handled!
[2024-Mar-27 12:04:46.704] 1 - [debug] - 139959211649848 [API_SUBSONIC] Handling request 3001 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:04:46.705] 1 - [debug] - 139959211649848 [UTILS] Written 262144 bytes, range = 18350080-18612223
[2024-Mar-27 12:04:46.705] 1 - [debug] - 139959211649848 [UTILS] Job not complete! Remaining range: 18612224-25993426
[2024-Mar-27 12:04:46.705] 1 - [debug] - 139959211649848 [API_SUBSONIC] Request 3001 '/stream' handled!
[2024-Mar-27 12:04:47.994] 1 - [debug] - 139959211498296 [API_SUBSONIC] Handling request 3002 '/ping.view', continuation = false, params = {c=Symfonium}, {f=json}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:04:47.995] 1 - [debug] - 139959211498296 [AUTH] Checking password for user 'wojtek'
[2024-Mar-27 12:04:47.995] 1 - [debug] - 139959211498296 [AUTH] Checking internal password for user 'wojtek'
[2024-Mar-27 12:04:48.006] 1 - [debug] - 139959211498296 [API_SUBSONIC] Request 3002 '/ping' handled!
[2024-Mar-27 12:04:48.006] 1 - [info] "wthttp: 192.168.1.118   GET /rest/ping.view?u=wojtek&p=enc%REDACTED&v=1.13.0&c=Symfonium&f=json HTTP/1.1 200 109"
[2024-Mar-27 12:04:48.006] 1 - [info] "WebRequest: took 12.019 ms"
[2024-Mar-27 12:04:49.355] 1 - [debug] - 139959211346744 [API_SUBSONIC] Handling request 3003 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:04:49.357] 1 - [debug] - 139959211346744 [UTILS] Written 262144 bytes, range = 18612224-18874367
[2024-Mar-27 12:04:49.357] 1 - [debug] - 139959211346744 [UTILS] Job not complete! Remaining range: 18874368-25993426
[2024-Mar-27 12:04:49.357] 1 - [debug] - 139959211346744 [API_SUBSONIC] Request 3003 '/stream' handled!
[2024-Mar-27 12:04:51.921] 1 - [debug] - 139959211195192 [API_SUBSONIC] Handling request 3004 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:04:51.922] 1 - [debug] - 139959211195192 [UTILS] Written 262144 bytes, range = 18874368-19136511
[2024-Mar-27 12:04:51.922] 1 - [debug] - 139959211195192 [UTILS] Job not complete! Remaining range: 19136512-25993426
[2024-Mar-27 12:04:51.922] 1 - [debug] - 139959211195192 [API_SUBSONIC] Request 3004 '/stream' handled!
[2024-Mar-27 12:04:54.504] 1 - [debug] - 139959211498296 [API_SUBSONIC] Handling request 3005 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:04:54.505] 1 - [debug] - 139959211498296 [UTILS] Written 262144 bytes, range = 19136512-19398655
[2024-Mar-27 12:04:54.505] 1 - [debug] - 139959211498296 [UTILS] Job not complete! Remaining range: 19398656-25993426
[2024-Mar-27 12:04:54.505] 1 - [debug] - 139959211498296 [API_SUBSONIC] Request 3005 '/stream' handled!
[2024-Mar-27 12:04:57.020] 1 - [debug] - 139959211346744 [API_SUBSONIC] Handling request 3006 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:04:57.021] 1 - [debug] - 139959211346744 [UTILS] Written 262144 bytes, range = 19398656-19660799
[2024-Mar-27 12:04:57.021] 1 - [debug] - 139959211346744 [UTILS] Job not complete! Remaining range: 19660800-25993426
[2024-Mar-27 12:04:57.021] 1 - [debug] - 139959211346744 [API_SUBSONIC] Request 3006 '/stream' handled!
[2024-Mar-27 12:04:57.168] 1 - [debug] - 139959211649848 [API_SUBSONIC] Handling request 3007 '/scrobble.view', continuation = false, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {submission=false}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:04:57.168] 1 - [debug] - 139959211649848 [AUTH] Checking password for user 'wojtek'
[2024-Mar-27 12:04:57.168] 1 - [debug] - 139959211649848 [AUTH] Checking internal password for user 'wojtek'
[2024-Mar-27 12:04:57.182] 1 - [debug] - 139959211649848 [API_SUBSONIC] Request 3007 '/scrobble' handled!
[2024-Mar-27 12:04:57.182] 1 - [debug] - 139959216999224 [SCROBBLING] [Http SendQueue] - Processing prio 0, request count = 1
[2024-Mar-27 12:04:57.182] 1 - [debug] - 139959216999224 [SCROBBLING] [Http SendQueue] - Sending request to url 'https://api.listenbrainz.org/1/submit-listens'
[2024-Mar-27 12:04:57.182] 1 - [info] "wthttp: 192.168.1.118   GET /rest/scrobble.view?id=tr-10804&submission=false&u=wojtek&p=enc%REDACTED&v=1.13.0&c=Symfonium&f=json HTTP/1.1 200 109"
[2024-Mar-27 12:04:57.182] 1 - [info] "WebRequest: took 14.065 ms"
[2024-Mar-27 12:04:58.320] 1 - [debug] - 139959216999224 [SCROBBLING] [Http SendQueue] - Client done. status = 200
[2024-Mar-27 12:04:58.320] 1 - [debug] - 139959216999224 [SCROBBLING] [Http SendQueue] - Remaining messages = 29
[2024-Mar-27 12:04:58.320] 1 - [debug] - 139959216999224 [SCROBBLING] [Http SendQueue] - Processing prio 0, request count = 0
[2024-Mar-27 12:04:58.320] 1 - [debug] - 139959216999224 [SCROBBLING] [Http SendQueue] - Processing prio 1, request count = 0
[2024-Mar-27 12:04:58.320] 1 - [debug] - 139959216999224 [SCROBBLING] [Http SendQueue] - Processing prio 2, request count = 0
[2024-Mar-27 12:04:59.622] 1 - [debug] - 139959211195192 [API_SUBSONIC] Handling request 3008 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:04:59.624] 1 - [debug] - 139959211195192 [UTILS] Written 262144 bytes, range = 19660800-19922943
[2024-Mar-27 12:04:59.624] 1 - [debug] - 139959211195192 [UTILS] Job not complete! Remaining range: 19922944-25993426
[2024-Mar-27 12:04:59.624] 1 - [debug] - 139959211195192 [API_SUBSONIC] Request 3008 '/stream' handled!
[2024-Mar-27 12:05:04.756] 1 - [debug] - 139959211346744 [API_SUBSONIC] Handling request 3009 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:05:04.758] 1 - [debug] - 139959211346744 [UTILS] Written 262144 bytes, range = 19922944-20185087
[2024-Mar-27 12:05:04.758] 1 - [debug] - 139959211346744 [UTILS] Job not complete! Remaining range: 20185088-25993426
[2024-Mar-27 12:05:04.758] 1 - [debug] - 139959211346744 [API_SUBSONIC] Request 3009 '/stream' handled!
[2024-Mar-27 12:05:07.419] 1 - [debug] - 139959211346744 [API_SUBSONIC] Handling request 3010 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:05:07.420] 1 - [debug] - 139959211346744 [UTILS] Written 262144 bytes, range = 20185088-20447231
[2024-Mar-27 12:05:07.421] 1 - [debug] - 139959211346744 [UTILS] Job not complete! Remaining range: 20447232-25993426
[2024-Mar-27 12:05:07.421] 1 - [debug] - 139959211346744 [API_SUBSONIC] Request 3010 '/stream' handled!
[2024-Mar-27 12:05:07.615] 1 - [debug] - 139959211498296 [API_SUBSONIC] Handling request 3011 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:05:07.616] 1 - [debug] - 139959211498296 [UTILS] Written 262144 bytes, range = 20447232-20709375
[2024-Mar-27 12:05:07.616] 1 - [debug] - 139959211498296 [UTILS] Job not complete! Remaining range: 20709376-25993426
[2024-Mar-27 12:05:07.616] 1 - [debug] - 139959211498296 [API_SUBSONIC] Request 3011 '/stream' handled!
[2024-Mar-27 12:05:10.066] 1 - [debug] - 139959211195192 [API_SUBSONIC] Handling request 3012 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:05:10.068] 1 - [debug] - 139959211195192 [UTILS] Written 262144 bytes, range = 20709376-20971519
[2024-Mar-27 12:05:10.068] 1 - [debug] - 139959211195192 [UTILS] Job not complete! Remaining range: 20971520-25993426
[2024-Mar-27 12:05:10.068] 1 - [debug] - 139959211195192 [API_SUBSONIC] Request 3012 '/stream' handled!
[2024-Mar-27 12:05:12.616] 1 - [debug] - 139959211649848 [API_SUBSONIC] Handling request 3013 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:05:12.617] 1 - [debug] - 139959211649848 [UTILS] Written 262144 bytes, range = 20971520-21233663
[2024-Mar-27 12:05:12.617] 1 - [debug] - 139959211649848 [UTILS] Job not complete! Remaining range: 21233664-25993426
[2024-Mar-27 12:05:12.617] 1 - [debug] - 139959211649848 [API_SUBSONIC] Request 3013 '/stream' handled!
[2024-Mar-27 12:05:15.224] 1 - [debug] - 139959211346744 [API_SUBSONIC] Handling request 3014 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:05:15.225] 1 - [debug] - 139959211346744 [UTILS] Written 262144 bytes, range = 21233664-21495807
[2024-Mar-27 12:05:15.225] 1 - [debug] - 139959211346744 [UTILS] Job not complete! Remaining range: 21495808-25993426
[2024-Mar-27 12:05:15.225] 1 - [debug] - 139959211346744 [API_SUBSONIC] Request 3014 '/stream' handled!
[2024-Mar-27 12:05:17.769] 1 - [debug] - 139959211498296 [API_SUBSONIC] Handling request 3015 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:05:17.771] 1 - [debug] - 139959211498296 [UTILS] Written 262144 bytes, range = 21495808-21757951
[2024-Mar-27 12:05:17.771] 1 - [debug] - 139959211498296 [UTILS] Job not complete! Remaining range: 21757952-25993426
[2024-Mar-27 12:05:17.771] 1 - [debug] - 139959211498296 [API_SUBSONIC] Request 3015 '/stream' handled!
[2024-Mar-27 12:05:20.371] 1 - [debug] - 139959211195192 [API_SUBSONIC] Handling request 3016 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:05:20.372] 1 - [debug] - 139959211195192 [UTILS] Written 262144 bytes, range = 21757952-22020095
[2024-Mar-27 12:05:20.372] 1 - [debug] - 139959211195192 [UTILS] Job not complete! Remaining range: 22020096-25993426
[2024-Mar-27 12:05:20.372] 1 - [debug] - 139959211195192 [API_SUBSONIC] Request 3016 '/stream' handled!
[2024-Mar-27 12:05:22.954] 1 - [debug] - 139959211649848 [API_SUBSONIC] Handling request 3017 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:05:22.955] 1 - [debug] - 139959211649848 [UTILS] Written 262144 bytes, range = 22020096-22282239
[2024-Mar-27 12:05:22.964] 1 - [debug] - 139959211649848 [UTILS] Job not complete! Remaining range: 22282240-25993426
[2024-Mar-27 12:05:22.964] 1 - [debug] - 139959211649848 [API_SUBSONIC] Request 3017 '/stream' handled!
[2024-Mar-27 12:05:25.570] 1 - [debug] - 139959211346744 [API_SUBSONIC] Handling request 3018 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:05:25.572] 1 - [debug] - 139959211346744 [UTILS] Written 262144 bytes, range = 22282240-22544383
[2024-Mar-27 12:05:25.572] 1 - [debug] - 139959211346744 [UTILS] Job not complete! Remaining range: 22544384-25993426
[2024-Mar-27 12:05:25.572] 1 - [debug] - 139959211346744 [API_SUBSONIC] Request 3018 '/stream' handled!
[2024-Mar-27 12:05:28.060] 1 - [debug] - 139959211195192 [API_SUBSONIC] Handling request 3019 '/ping.view', continuation = false, params = {c=Symfonium}, {f=json}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:05:28.060] 1 - [debug] - 139959211195192 [AUTH] Checking password for user 'wojtek'
[2024-Mar-27 12:05:28.070] 1 - [debug] - 139959211195192 [AUTH] Checking internal password for user 'wojtek'
[2024-Mar-27 12:05:28.084] 1 - [debug] - 139959211195192 [API_SUBSONIC] Request 3019 '/ping' handled!
[2024-Mar-27 12:05:28.084] 1 - [info] "wthttp: 192.168.1.118   GET /rest/ping.view?u=wojtek&p=enc%REDACTED&v=1.13.0&c=Symfonium&f=json HTTP/1.1 200 109"
[2024-Mar-27 12:05:28.084] 1 - [info] "WebRequest: took 24.046 ms"
[2024-Mar-27 12:05:28.236] 1 - [debug] - 139959211346744 [API_SUBSONIC] Handling request 3020 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:05:28.237] 1 - [debug] - 139959211346744 [UTILS] Written 262144 bytes, range = 22544384-22806527
[2024-Mar-27 12:05:28.237] 1 - [debug] - 139959211346744 [UTILS] Job not complete! Remaining range: 22806528-25993426
[2024-Mar-27 12:05:28.237] 1 - [debug] - 139959211346744 [API_SUBSONIC] Request 3020 '/stream' handled!
[2024-Mar-27 12:05:28.310] 1 - [debug] - 139959211498296 [API_SUBSONIC] Handling request 3021 '/scrobble.view', continuation = false, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {submission=false}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:05:28.310] 1 - [debug] - 139959211498296 [AUTH] Checking password for user 'wojtek'
[2024-Mar-27 12:05:28.310] 1 - [debug] - 139959211498296 [AUTH] Checking internal password for user 'wojtek'
[2024-Mar-27 12:05:28.323] 1 - [debug] - 139959211498296 [API_SUBSONIC] Request 3021 '/scrobble' handled!
[2024-Mar-27 12:05:28.323] 1 - [debug] - 139959217150776 [SCROBBLING] [Http SendQueue] - Processing prio 0, request count = 1
[2024-Mar-27 12:05:28.323] 1 - [debug] - 139959217150776 [SCROBBLING] [Http SendQueue] - Sending request to url 'https://api.listenbrainz.org/1/submit-listens'
[2024-Mar-27 12:05:28.323] 1 - [info] "wthttp: 192.168.1.118   GET /rest/scrobble.view?id=tr-10804&submission=false&u=wojtek&p=enc%REDACTED&v=1.13.0&c=Symfonium&f=json HTTP/1.1 200 109"
[2024-Mar-27 12:05:28.323] 1 - [info] "WebRequest: took 13.144 ms"
[2024-Mar-27 12:05:29.456] 1 - [debug] - 139959216999224 [SCROBBLING] [Http SendQueue] - Client done. status = 200
[2024-Mar-27 12:05:29.456] 1 - [debug] - 139959216999224 [SCROBBLING] [Http SendQueue] - Remaining messages = 29
[2024-Mar-27 12:05:29.457] 1 - [debug] - 139959216999224 [SCROBBLING] [Http SendQueue] - Processing prio 0, request count = 0
[2024-Mar-27 12:05:29.457] 1 - [debug] - 139959216999224 [SCROBBLING] [Http SendQueue] - Processing prio 1, request count = 0
[2024-Mar-27 12:05:29.457] 1 - [debug] - 139959216999224 [SCROBBLING] [Http SendQueue] - Processing prio 2, request count = 0
[2024-Mar-27 12:05:30.774] 1 - [debug] - 139959211649848 [API_SUBSONIC] Handling request 3022 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:05:30.775] 1 - [debug] - 139959211649848 [UTILS] Written 262144 bytes, range = 22806528-23068671
[2024-Mar-27 12:05:30.776] 1 - [debug] - 139959211649848 [UTILS] Job not complete! Remaining range: 23068672-25993426
[2024-Mar-27 12:05:30.776] 1 - [debug] - 139959211649848 [API_SUBSONIC] Request 3022 '/stream' handled!
[2024-Mar-27 12:05:33.511] 1 - [debug] - 139959211346744 [API_SUBSONIC] Handling request 3023 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:05:33.512] 1 - [debug] - 139959211346744 [UTILS] Written 262144 bytes, range = 23068672-23330815
[2024-Mar-27 12:05:33.521] 1 - [debug] - 139959211346744 [UTILS] Job not complete! Remaining range: 23330816-25993426
[2024-Mar-27 12:05:33.521] 1 - [debug] - 139959211346744 [API_SUBSONIC] Request 3023 '/stream' handled!
[2024-Mar-27 12:05:36.006] 1 - [debug] - 139959211195192 [API_SUBSONIC] Handling request 3024 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:05:36.007] 1 - [debug] - 139959211195192 [UTILS] Written 262144 bytes, range = 23330816-23592959
[2024-Mar-27 12:05:36.007] 1 - [debug] - 139959211195192 [UTILS] Job not complete! Remaining range: 23592960-25993426
[2024-Mar-27 12:05:36.007] 1 - [debug] - 139959211195192 [API_SUBSONIC] Request 3024 '/stream' handled!
[2024-Mar-27 12:05:38.561] 1 - [debug] - 139959211195192 [API_SUBSONIC] Handling request 3025 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:05:38.562] 1 - [debug] - 139959211195192 [UTILS] Written 262144 bytes, range = 23592960-23855103
[2024-Mar-27 12:05:38.571] 1 - [debug] - 139959211195192 [UTILS] Job not complete! Remaining range: 23855104-25993426
[2024-Mar-27 12:05:38.571] 1 - [debug] - 139959211195192 [API_SUBSONIC] Request 3025 '/stream' handled!
[2024-Mar-27 12:05:41.160] 1 - [debug] - 139959211346744 [API_SUBSONIC] Handling request 3026 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:05:41.161] 1 - [debug] - 139959211346744 [UTILS] Written 262144 bytes, range = 23855104-24117247
[2024-Mar-27 12:05:41.161] 1 - [debug] - 139959211346744 [UTILS] Job not complete! Remaining range: 24117248-25993426
[2024-Mar-27 12:05:41.162] 1 - [debug] - 139959211346744 [API_SUBSONIC] Request 3026 '/stream' handled!
[2024-Mar-27 12:05:41.282] 1 - [debug] - 139959211498296 [API_SUBSONIC] Handling request 3027 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:05:41.284] 1 - [debug] - 139959211498296 [UTILS] Written 262144 bytes, range = 24117248-24379391
[2024-Mar-27 12:05:41.284] 1 - [debug] - 139959211498296 [UTILS] Job not complete! Remaining range: 24379392-25993426
[2024-Mar-27 12:05:41.284] 1 - [debug] - 139959211498296 [API_SUBSONIC] Request 3027 '/stream' handled!
[2024-Mar-27 12:05:43.910] 1 - [debug] - 139959211649848 [API_SUBSONIC] Handling request 3028 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:05:43.911] 1 - [debug] - 139959211649848 [UTILS] Written 262144 bytes, range = 24379392-24641535
[2024-Mar-27 12:05:43.911] 1 - [debug] - 139959211649848 [UTILS] Job not complete! Remaining range: 24641536-25993426
[2024-Mar-27 12:05:43.912] 1 - [debug] - 139959211649848 [API_SUBSONIC] Request 3028 '/stream' handled!
[2024-Mar-27 12:05:46.572] 1 - [debug] - 139959211346744 [API_SUBSONIC] Handling request 3029 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:05:46.573] 1 - [debug] - 139959211346744 [UTILS] Written 262144 bytes, range = 24641536-24903679
[2024-Mar-27 12:05:46.574] 1 - [debug] - 139959211346744 [UTILS] Job not complete! Remaining range: 24903680-25993426
[2024-Mar-27 12:05:46.574] 1 - [debug] - 139959211346744 [API_SUBSONIC] Request 3029 '/stream' handled!
[2024-Mar-27 12:05:51.585] 1 - [debug] - 139959211498296 [API_SUBSONIC] Handling request 3030 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:05:51.587] 1 - [debug] - 139959211498296 [UTILS] Written 262144 bytes, range = 24903680-25165823
[2024-Mar-27 12:05:51.587] 1 - [debug] - 139959211498296 [UTILS] Job not complete! Remaining range: 25165824-25993426
[2024-Mar-27 12:05:51.587] 1 - [debug] - 139959211498296 [API_SUBSONIC] Request 3030 '/stream' handled!
[2024-Mar-27 12:05:54.227] 1 - [debug] - 139959211346744 [API_SUBSONIC] Handling request 3031 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:05:54.228] 1 - [debug] - 139959211346744 [UTILS] Written 262144 bytes, range = 25165824-25427967
[2024-Mar-27 12:05:54.229] 1 - [debug] - 139959211346744 [UTILS] Job not complete! Remaining range: 25427968-25993426
[2024-Mar-27 12:05:54.229] 1 - [debug] - 139959211346744 [API_SUBSONIC] Request 3031 '/stream' handled!
[2024-Mar-27 12:05:56.795] 1 - [debug] - 139959211649848 [API_SUBSONIC] Handling request 3032 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:05:56.796] 1 - [debug] - 139959211649848 [UTILS] Written 262144 bytes, range = 25427968-25690111
[2024-Mar-27 12:05:56.804] 1 - [debug] - 139959211649848 [UTILS] Job not complete! Remaining range: 25690112-25993426
[2024-Mar-27 12:05:56.805] 1 - [debug] - 139959211649848 [API_SUBSONIC] Request 3032 '/stream' handled!
[2024-Mar-27 12:05:59.390] 1 - [debug] - 139959211195192 [API_SUBSONIC] Handling request 3033 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:05:59.391] 1 - [debug] - 139959211195192 [UTILS] Written 262144 bytes, range = 25690112-25952255
[2024-Mar-27 12:05:59.391] 1 - [debug] - 139959211195192 [UTILS] Job not complete! Remaining range: 25952256-25993426
[2024-Mar-27 12:05:59.392] 1 - [debug] - 139959211195192 [API_SUBSONIC] Request 3033 '/stream' handled!
[2024-Mar-27 12:05:59.538] 1 - [debug] - 139959211346744 [API_SUBSONIC] Handling request 3034 '/scrobble.view', continuation = false, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {submission=false}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:05:59.538] 1 - [debug] - 139959211346744 [AUTH] Checking password for user 'wojtek'
[2024-Mar-27 12:05:59.538] 1 - [debug] - 139959211346744 [AUTH] Checking internal password for user 'wojtek'
[2024-Mar-27 12:05:59.552] 1 - [debug] - 139959211346744 [API_SUBSONIC] Request 3034 '/scrobble' handled!
[2024-Mar-27 12:05:59.552] 1 - [debug] - 139959217150776 [SCROBBLING] [Http SendQueue] - Processing prio 0, request count = 1
[2024-Mar-27 12:05:59.552] 1 - [debug] - 139959217150776 [SCROBBLING] [Http SendQueue] - Sending request to url 'https://api.listenbrainz.org/1/submit-listens'
[2024-Mar-27 12:05:59.552] 1 - [info] "wthttp: 192.168.1.118   GET /rest/scrobble.view?id=tr-10804&submission=false&u=wojtek&p=enc%REDACTED&v=1.13.0&c=Symfonium&f=json HTTP/1.1 200 109"
[2024-Mar-27 12:05:59.552] 1 - [info] "WebRequest: took 13.968 ms"
[2024-Mar-27 12:06:00.688] 1 - [debug] - 139959217302328 [SCROBBLING] [Http SendQueue] - Client done. status = 200
[2024-Mar-27 12:06:00.688] 1 - [debug] - 139959217302328 [SCROBBLING] [Http SendQueue] - Remaining messages = 29
[2024-Mar-27 12:06:00.689] 1 - [debug] - 139959217302328 [SCROBBLING] [Http SendQueue] - Processing prio 0, request count = 0
[2024-Mar-27 12:06:00.689] 1 - [debug] - 139959217302328 [SCROBBLING] [Http SendQueue] - Processing prio 1, request count = 0
[2024-Mar-27 12:06:00.689] 1 - [debug] - 139959217302328 [SCROBBLING] [Http SendQueue] - Processing prio 2, request count = 0
[2024-Mar-27 12:06:02.091] 1 - [debug] - 139959211649848 [API_SUBSONIC] Handling request 3035 '/stream.view', continuation = true, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:06:02.092] 1 - [debug] - 139959211649848 [UTILS] Written 41171 bytes, range = 25952256-25993426
[2024-Mar-27 12:06:02.092] 1 - [debug] - 139959211649848 [UTILS] Job complete!
[2024-Mar-27 12:06:02.092] 1 - [debug] - 139959211649848 [API_SUBSONIC] Request 3035 '/stream' handled!
[2024-Mar-27 12:06:02.093] 1 - [info] "wthttp: 192.168.1.106   GET /rest/stream.view?id=tr-10804&u=wojtek&p=enc%REDACTED&v=1.13.0&c=Symfonium&f=json HTTP/1.1 200 25993427"
[2024-Mar-27 12:06:02.093] 1 - [info] "WebRequest: took 236680 ms"
[2024-Mar-27 12:06:08.114] 1 - [debug] - 139959211498296 [API_SUBSONIC] Handling request 3036 '/ping.view', continuation = false, params = {c=Symfonium}, {f=json}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:06:08.114] 1 - [debug] - 139959211498296 [AUTH] Checking password for user 'wojtek'
[2024-Mar-27 12:06:08.114] 1 - [debug] - 139959211498296 [AUTH] Checking internal password for user 'wojtek'
[2024-Mar-27 12:06:08.127] 1 - [debug] - 139959211498296 [API_SUBSONIC] Request 3036 '/ping' handled!
[2024-Mar-27 12:06:08.127] 1 - [info] "wthttp: 192.168.1.118   GET /rest/ping.view?u=wojtek&p=enc%REDACTED&v=1.13.0&c=Symfonium&f=json HTTP/1.1 200 109"
[2024-Mar-27 12:06:08.127] 1 - [info] "WebRequest: took 13.387 ms"
[2024-Mar-27 12:06:30.278] 1 - [debug] - 139959211498296 [API_SUBSONIC] Handling request 3037 '/scrobble.view', continuation = false, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {submission=false}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:06:30.278] 1 - [debug] - 139959211498296 [AUTH] Checking password for user 'wojtek'
[2024-Mar-27 12:06:30.278] 1 - [debug] - 139959211498296 [AUTH] Checking internal password for user 'wojtek'
[2024-Mar-27 12:06:30.291] 1 - [debug] - 139959211498296 [API_SUBSONIC] Request 3037 '/scrobble' handled!
[2024-Mar-27 12:06:30.291] 1 - [debug] - 139959216999224 [SCROBBLING] [Http SendQueue] - Processing prio 0, request count = 1
[2024-Mar-27 12:06:30.291] 1 - [debug] - 139959216999224 [SCROBBLING] [Http SendQueue] - Sending request to url 'https://api.listenbrainz.org/1/submit-listens'
[2024-Mar-27 12:06:30.291] 1 - [info] "wthttp: 192.168.1.118   GET /rest/scrobble.view?id=tr-10804&submission=false&u=wojtek&p=enc%REDACTED&v=1.13.0&c=Symfonium&f=json HTTP/1.1 200 109"
[2024-Mar-27 12:06:30.291] 1 - [info] "WebRequest: took 12.988 ms"
[2024-Mar-27 12:06:31.423] 1 - [debug] - 139959216999224 [SCROBBLING] [Http SendQueue] - Client done. status = 200
[2024-Mar-27 12:06:31.423] 1 - [debug] - 139959216999224 [SCROBBLING] [Http SendQueue] - Remaining messages = 29
[2024-Mar-27 12:06:31.423] 1 - [debug] - 139959216999224 [SCROBBLING] [Http SendQueue] - Processing prio 0, request count = 0
[2024-Mar-27 12:06:31.423] 1 - [debug] - 139959216999224 [SCROBBLING] [Http SendQueue] - Processing prio 1, request count = 0
[2024-Mar-27 12:06:31.423] 1 - [debug] - 139959216999224 [SCROBBLING] [Http SendQueue] - Processing prio 2, request count = 0
[2024-Mar-27 12:06:38.749] 1 - [debug] - 139959211346744 [API_SUBSONIC] Handling request 3038 '/scrobble.view', continuation = false, params = {c=Symfonium}, {f=json}, {id=tr-10804}, {p=*REDACTED*}, {submission=false}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:06:38.749] 1 - [debug] - 139959211346744 [AUTH] Checking password for user 'wojtek'
[2024-Mar-27 12:06:38.749] 1 - [debug] - 139959211346744 [AUTH] Checking internal password for user 'wojtek'
[2024-Mar-27 12:06:38.763] 1 - [debug] - 139959211346744 [API_SUBSONIC] Request 3038 '/scrobble' handled!
[2024-Mar-27 12:06:38.763] 1 - [debug] - 139959217150776 [SCROBBLING] [Http SendQueue] - Processing prio 0, request count = 1
[2024-Mar-27 12:06:38.763] 1 - [debug] - 139959217150776 [SCROBBLING] [Http SendQueue] - Sending request to url 'https://api.listenbrainz.org/1/submit-listens'
[2024-Mar-27 12:06:38.763] 1 - [info] "wthttp: 192.168.1.118   GET /rest/scrobble.view?id=tr-10804&submission=false&u=wojtek&p=enc%REDACTED&v=1.13.0&c=Symfonium&f=json HTTP/1.1 200 109"
[2024-Mar-27 12:06:38.763] 1 - [info] "WebRequest: took 14.265 ms"
[2024-Mar-27 12:06:39.909] 1 - [debug] - 139959217150776 [SCROBBLING] [Http SendQueue] - Client done. status = 200
[2024-Mar-27 12:06:39.909] 1 - [debug] - 139959217150776 [SCROBBLING] [Http SendQueue] - Remaining messages = 28
[2024-Mar-27 12:06:39.909] 1 - [debug] - 139959217150776 [SCROBBLING] [Http SendQueue] - Processing prio 0, request count = 0
[2024-Mar-27 12:06:39.909] 1 - [debug] - 139959217150776 [SCROBBLING] [Http SendQueue] - Processing prio 1, request count = 0
[2024-Mar-27 12:06:39.909] 1 - [debug] - 139959217150776 [SCROBBLING] [Http SendQueue] - Processing prio 2, request count = 0
[2024-Mar-27 12:06:48.141] 1 - [debug] - 139959211498296 [API_SUBSONIC] Handling request 3039 '/ping.view', continuation = false, params = {c=Symfonium}, {f=json}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:06:48.141] 1 - [debug] - 139959211498296 [AUTH] Checking password for user 'wojtek'
[2024-Mar-27 12:06:48.141] 1 - [debug] - 139959211498296 [AUTH] Checking internal password for user 'wojtek'
[2024-Mar-27 12:06:48.155] 1 - [debug] - 139959211498296 [API_SUBSONIC] Request 3039 '/ping' handled!
[2024-Mar-27 12:06:48.155] 1 - [info] "wthttp: 192.168.1.118   GET /rest/ping.view?u=wojtek&p=enc%REDACTED&v=1.13.0&c=Symfonium&f=json HTTP/1.1 200 109"
[2024-Mar-27 12:06:48.155] 1 - [info] "WebRequest: took 13.679 ms"
[2024-Mar-27 12:07:28.190] 1 - [debug] - 139959211649848 [API_SUBSONIC] Handling request 3040 '/ping.view', continuation = false, params = {c=Symfonium}, {f=json}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:07:28.190] 1 - [debug] - 139959211649848 [AUTH] Checking password for user 'wojtek'
[2024-Mar-27 12:07:28.190] 1 - [debug] - 139959211649848 [AUTH] Checking internal password for user 'wojtek'
[2024-Mar-27 12:07:28.203] 1 - [debug] - 139959211649848 [API_SUBSONIC] Request 3040 '/ping' handled!
[2024-Mar-27 12:07:28.203] 1 - [info] "wthttp: 192.168.1.118   GET /rest/ping.view?u=wojtek&p=enc%REDACTED&v=1.13.0&c=Symfonium&f=json HTTP/1.1 200 109"
[2024-Mar-27 12:07:28.203] 1 - [info] "WebRequest: took 13.262 ms"
[2024-Mar-27 12:08:08.262] 1 - [debug] - 139959211649848 [API_SUBSONIC] Handling request 3041 '/ping.view', continuation = false, params = {c=Symfonium}, {f=json}, {p=*REDACTED*}, {u=wojtek}, {v=1.13.0}, 
[2024-Mar-27 12:08:08.263] 1 - [debug] - 139959211649848 [AUTH] Checking password for user 'wojtek'
[2024-Mar-27 12:08:08.263] 1 - [debug] - 139959211649848 [AUTH] Checking internal password for user 'wojtek'
[2024-Mar-27 12:08:08.275] 1 - [debug] - 139959211649848 [API_SUBSONIC] Request 3041 '/ping' handled!
[2024-Mar-27 12:08:08.275] 1 - [info] "wthttp: 192.168.1.118   GET /rest/ping.view?u=wojtek&p=enc%REDACTED&v=1.13.0&c=Symfonium&f=json HTTP/1.1 200 109"
[2024-Mar-27 12:08:08.275] 1 - [info] "WebRequest: took 13.147 ms"
[2024-Mar-27 12:21:07.997] 1 - [info] - 139959212211000 [UI] New scan started!

@littlebitofcode
Copy link

Tested Symfonium + Navidrome and when the last track finish it is immediately push to 'recent listens', so no problem here.

@littlebitofcode
Copy link

Maybe this can be helpful, when streaming from Subsonic I got this error and the track wont show in 'playing now':

[2024-Mar-27 20:43:26.681] 1 - [error] - 139959211498296 [API_SUBSONIC] Error while processing request '/scrobble', params = [{c=supersonic}, {f=xml}, {id=tr-7830}, {p=*REDACTED*}, {submission=false}, {time=1711572206676}, {u=wojtek}, {v=1.15.0}, ], code = 0, msg = 'Parameter 'time': bad value'

It will correctly submit scrobble when the track finish tough.

@epoupon
Copy link
Owner

epoupon commented Mar 27, 2024

Maybe this can be helpful, when streaming from Subsonic I got this error and the track wont show in 'playing now':

[2024-Mar-27 20:43:26.681] 1 - [error] - 139959211498296 [API_SUBSONIC] Error while processing request '/scrobble', params = [{c=supersonic}, {f=xml}, {id=tr-7830}, {p=*REDACTED*}, {submission=false}, {time=1711572206676}, {u=wojtek}, {v=1.15.0}, ], code = 0, msg = 'Parameter 'time': bad value'

It will correctly submit scrobble when the track finish tough.

For this, LMS is a bit strict: if it is not a submission, it considers it is a "now playing" event that must not have its time set. We could just ignore the time parameter in this case

@epoupon
Copy link
Owner

epoupon commented Mar 27, 2024

@littlebitofcode looks like a 'now playing' scrobble is sent right after each downloaded chunk. @Tolriq: not sure if it is intended?
And I don't see the last 'scrobble' request with submission=true

@littlebitofcode
Copy link

If that helps the missing track from yesterday was submitted today when I started a new queue in Symfonium on the same device. It was submitted with the current date and time tough.

It looks like it needs to start playing next track to submit previous.

Screenshot_20240328_094919
...
Screenshot_20240328_095005

@Tolriq
Copy link

Tolriq commented Mar 28, 2024

I send scrobble event every 30 seconds for the now playing and submission when setting playcount.

Current version have an issue with last track not scribbled it's fixed for next release.

@epoupon
Copy link
Owner

epoupon commented Mar 28, 2024

Thanks!
So everything is well handled now :)

@epoupon epoupon closed this as completed Mar 28, 2024
@epoupon epoupon added this to the v3.52.0 milestone Mar 28, 2024
@epoupon epoupon added the bug label Mar 28, 2024
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
Projects
None yet
Development

No branches or pull requests

4 participants