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

[Question] Stream url opened in browser, different behaviour compared to navidrome #367

Closed
GioF71 opened this issue Nov 12, 2023 · 30 comments
Labels
Milestone

Comments

@GioF71
Copy link

GioF71 commented Nov 12, 2023

Hello, I have these two urls for the very same track:

navidrome (traefik reverse proxy)
https://navidrome.mydomain.com:443/rest/stream?id=0f2638a69d0310e14d5e8c29b51a4194&format=flac&u=giovanni&s=111111111111111111&c=upmpdcli&v=1.16.1

lms (no reverse proxy)
http://gruesome04.homelab.local:5082/rest/stream?id=tr-105685&format=flac&u=giovanni&p=enc:111111111111111111111111111&c=upmpdcli&v=1.16.1

I just changed the domain names, the s and p fields.
When I open the navidrome url in the browser, it starts playing.
When I open the other one, the browser offers me a download file suggesting the name "stream".
Obviously it's the same browser.

Both tracks play perfectly on Music Player Daemon (proxied by upmpdcli), but seek operations fail with the lms url: playback would stop immediately if I try to seek the track.

I wonder if this has to do with some missing metadata, but I opened both URLs with 'mediainfo' and the result is identical.
I also tried to generate the url for navidrome skipping the reverse proxy, it still plays directly on the browser, nothing changes.

Do you know what can lead to this different behavior?

Thank you

@epoupon
Copy link
Owner

epoupon commented Nov 12, 2023

Hmm you should check the logs. I guess lms tries to transcode the file. See #351
Not sure which version you have, but in v3.42.1 there is no content type exposed via the subsonic api (already fixed on develop) when the raw file is served

@GioF71
Copy link
Author

GioF71 commented Nov 12, 2023

I am using docker and the tag is afcb4d9, but I will revert to latest starting your next release.
It appears to be a flac according to what mediainfo says when I execute it against the full url.

Here are the logs, I don't seem to recognize a transcoding:

192.168.1.173 - - [2023-Nov-12 17:50:30.429] "GET /rest/getCoverArt?id=al-6849&u=giovanni&p=enc:48456E254357676E5264667A3569417432243439357A6345&c=upmpdcli&v=1.16.1 HTTP/1.1" 200 101835
192.168.1.173 - - [2023-Nov-12 17:50:37.453] "POST /rest/getSong.view HTTP/1.1" 200 991
192.168.1.173 - - [2023-Nov-12 17:50:38.494] "POST /rest/getSong.view HTTP/1.1" 200 991
192.168.1.173 - - [2023-Nov-12 17:50:38.548] "POST /rest/getSong.view HTTP/1.1" 200 991
192.168.1.173 - - [2023-Nov-12 17:50:38.594] "POST /rest/getSong.view HTTP/1.1" 200 991
192.168.1.173 - - [2023-Nov-12 17:50:38.610] "POST /rest/getSong.view HTTP/1.1" 200 991
192.168.1.173 - - [2023-Nov-12 17:50:39.471] "POST /rest/getSong.view HTTP/1.1" 200 991
192.168.1.173 - - [2023-Nov-12 17:50:39.482] "POST /rest/getSong.view HTTP/1.1" 200 991
192.168.1.173 - - [2023-Nov-12 17:50:40.478] "POST /rest/getSong.view HTTP/1.1" 200 991
192.168.1.173 - - [2023-Nov-12 17:50:40.491] "POST /rest/getSong.view HTTP/1.1" 200 991
192.168.1.173 - - [2023-Nov-12 17:50:41.480] "POST /rest/getSong.view HTTP/1.1" 200 991
192.168.1.173 - - [2023-Nov-12 17:50:41.488] "POST /rest/getSong.view HTTP/1.1" 200 991
192.168.1.173 - - [2023-Nov-12 17:50:42.467] "POST /rest/getSong.view HTTP/1.1" 200 991
192.168.1.173 - - [2023-Nov-12 17:50:42.492] "POST /rest/getSong.view HTTP/1.1" 200 991
192.168.1.173 - - [2023-Nov-12 17:50:43.463] "POST /rest/getSong.view HTTP/1.1" 200 991
192.168.1.173 - - [2023-Nov-12 17:50:43.471] "POST /rest/getSong.view HTTP/1.1" 200 991

@epoupon
Copy link
Owner

epoupon commented Nov 12, 2023

Yes you need to enable the debug logs in the lms.conf file (remove "-debug")

@GioF71
Copy link
Author

GioF71 commented Nov 12, 2023

Ok. But how do I change that file? If I enter the container with a docker exec and run /bin/sh, I am user 1000, I cannot modify lms.conf.
And the file is not bound to a local file.
In my mounted data directory I have the wt_config.xml file which has this content:

<?xml version="1.0" encoding="utf-8"?>
<server><application-settings location="*"><log-file/><log-config>* -debug -info:WebRequest</log-config><behind-reverse-proxy>false</behind-reverse-proxy><head-matter><meta name="viewport" content="width=device-width, initial-scale=1, user-scalable=no"/><meta name="theme-color" content="#303030"/></head-matter></application-settings></server>

Can I change this file and restart the container?

@GioF71
Copy link
Author

GioF71 commented Nov 12, 2023

This is probably because I am using user 1000:1000 in my compose file ...

@epoupon
Copy link
Owner

epoupon commented Nov 12, 2023

You can map the file and specify the conf file to be used when running the container (this is the first arg of the executable being run)
Edit: or copy/paste the file inside the container and run lms using the new file

@GioF71
Copy link
Author

GioF71 commented Nov 13, 2023

Hello, I finally could do the test, are the logs:

lms-lightweight-music-server | 192.168.1.8 - - [2023-Nov-13 08:02:37.342] "POST /?wtd=pW9hqtnPE1p6y462 HTTP/1.1" 200 48
lms-lightweight-music-server | [2023-Nov-13 08:02:46.399] 1 - [debug] - 140618071206712 [API_SUBSONIC] Handling request 0 '/getAlbum.view', continuation = false, params = {c=upmpdcli}, {f=json}, {id=al-6848}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:02:46.401] 1 - [debug] - 140618071206712 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:46.401] 1 - [debug] - 140618071206712 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:46.417] 1 - [error] - 140618071206712 [API_SUBSONIC] Error while processing request '/getAlbum', params = [{c=upmpdcli}, {f=json}, {id=al-6848}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, ], code = 70, msg = 'The requested data was not found.'
lms-lightweight-music-server | 192.168.1.173 - - [2023-Nov-13 08:02:46.417] "POST /rest/getAlbum.view HTTP/1.1" 200 179
lms-lightweight-music-server | [2023-Nov-13 08:02:47.740] 1 - [debug] - 140618070903608 [API_SUBSONIC] Handling request 1 '/getCoverArt', continuation = false, params = {c=upmpdcli}, {id=al-6849}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:02:47.740] 1 - [debug] - 140618070903608 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:47.740] 1 - [debug] - 140618070903608 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:47.785] 1 - [debug] - 140618070752056 [API_SUBSONIC] Handling request 2 '/getCoverArt', continuation = false, params = {c=upmpdcli}, {id=al-6849}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:02:47.785] 1 - [debug] - 140618070752056 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:47.786] 1 - [debug] - 140618070752056 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:47.874] 1 - [debug] - 140618070903608 [COVER] Default cache entries = 2
lms-lightweight-music-server | [2023-Nov-13 08:02:47.874] 1 - [debug] - 140618070903608 [API_SUBSONIC] Request 1 '/getCoverArt' handled!
lms-lightweight-music-server | 192.168.1.8 - - [2023-Nov-13 08:02:47.875] "GET /rest/getCoverArt?id=al-6849&u=giovanni&p=enc:48456E254357676E5264667A3569417432243439357A6345&c=upmpdcli&v=1.16.1 HTTP/1.1" 200 43778
lms-lightweight-music-server | [2023-Nov-13 08:02:47.876] 1 - [debug] - 140618070752056 [API_SUBSONIC] Request 2 '/getCoverArt' handled!
lms-lightweight-music-server | 192.168.1.8 - - [2023-Nov-13 08:02:47.876] "GET /rest/getCoverArt?id=al-6849&u=giovanni&p=enc:48456E254357676E5264667A3569417432243439357A6345&c=upmpdcli&v=1.16.1 HTTP/1.1" 200 43778
lms-lightweight-music-server | [2023-Nov-13 08:02:52.489] 1 - [debug] - 140618071055160 [API_SUBSONIC] Handling request 3 '/getAlbum.view', continuation = false, params = {c=upmpdcli}, {f=json}, {id=al-6848}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:02:52.489] 1 - [debug] - 140618071055160 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:52.489] 1 - [debug] - 140618071055160 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | 192.168.1.173 - - [2023-Nov-13 08:02:52.509] "POST /rest/getAlbum.view HTTP/1.1" 200 179
lms-lightweight-music-server | [2023-Nov-13 08:02:52.508] 1 - [error] - 140618071055160 [API_SUBSONIC] Error while processing request '/getAlbum', params = [{c=upmpdcli}, {f=json}, {id=al-6848}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, ], code = 70, msg = 'The requested data was not found.'
lms-lightweight-music-server | [2023-Nov-13 08:02:54.033] 1 - [debug] - 140618071206712 [API_SUBSONIC] Handling request 4 '/getAlbum.view', continuation = false, params = {c=upmpdcli}, {f=json}, {id=al-6848}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:02:54.033] 1 - [debug] - 140618071206712 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:54.033] 1 - [debug] - 140618071206712 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | 192.168.1.173 - - [2023-Nov-13 08:02:54.052] "POST /rest/getAlbum.view HTTP/1.1" 200 179
lms-lightweight-music-server | [2023-Nov-13 08:02:54.052] 1 - [error] - 140618071206712 [API_SUBSONIC] Error while processing request '/getAlbum', params = [{c=upmpdcli}, {f=json}, {id=al-6848}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, ], code = 70, msg = 'The requested data was not found.'
lms-lightweight-music-server | [2023-Nov-13 08:02:55.097] 1 - [debug] - 140618070752056 [API_SUBSONIC] Handling request 5 '/getAlbumList2.view', continuation = false, params = {c=upmpdcli}, {f=json}, {offset=0}, {p=*REDACTED*}, {size=36}, {type=newest}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:02:55.097] 1 - [debug] - 140618070752056 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:55.097] 1 - [debug] - 140618070752056 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:55.187] 1 - [debug] - 140618070752056 [API_SUBSONIC] Request 5 '/getAlbumList2' handled!
lms-lightweight-music-server | 192.168.1.173 - - [2023-Nov-13 08:02:55.188] "POST /rest/getAlbumList2.view HTTP/1.1" 200 9404
lms-lightweight-music-server | [2023-Nov-13 08:02:55.251] 1 - [debug] - 140618071206712 [API_SUBSONIC] Handling request 6 '/getAlbumList2.view', continuation = false, params = {c=upmpdcli}, {f=json}, {offset=0}, {p=*REDACTED*}, {size=36}, {type=newest}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:02:55.251] 1 - [debug] - 140618071206712 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:55.252] 1 - [debug] - 140618071206712 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:55.268] 1 - [debug] - 140618070903608 [API_SUBSONIC] Handling request 7 '/getCoverArt', continuation = false, params = {c=upmpdcli}, {id=al-12}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:02:55.268] 1 - [debug] - 140618070903608 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:55.269] 1 - [debug] - 140618070903608 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:55.268] 1 - [debug] - 140618071055160 [API_SUBSONIC] Handling request 8 '/getCoverArt', continuation = false, params = {c=upmpdcli}, {id=al-7}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:02:55.269] 1 - [debug] - 140618071055160 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:55.269] 1 - [debug] - 140618071055160 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:55.268] 1 - [debug] - 140618070752056 [API_SUBSONIC] Handling request 9 '/getCoverArt', continuation = false, params = {c=upmpdcli}, {id=al-5}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:02:55.271] 1 - [debug] - 140618070752056 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:55.272] 1 - [debug] - 140618070752056 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:55.344] 1 - [debug] - 140618071206712 [API_SUBSONIC] Request 6 '/getAlbumList2' handled!
lms-lightweight-music-server | [2023-Nov-13 08:02:55.345] 1 - [debug] - 140618071206712 [API_SUBSONIC] Handling request 10 '/getCoverArt', continuation = false, params = {c=upmpdcli}, {id=al-6}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:02:55.345] 1 - [debug] - 140618071206712 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:55.346] 1 - [debug] - 140618071206712 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:55.450] 1 - [debug] - 140618071055160 [API_SUBSONIC] Request 8 '/getCoverArt' handled!
lms-lightweight-music-server | [2023-Nov-13 08:02:55.451] 1 - [debug] - 140618071055160 [API_SUBSONIC] Handling request 11 '/getCoverArt', continuation = false, params = {c=upmpdcli}, {id=al-4}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:02:55.451] 1 - [debug] - 140618071055160 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:55.451] 1 - [debug] - 140618071055160 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:55.467] 1 - [debug] - 140618070752056 [API_SUBSONIC] Request 9 '/getCoverArt' handled!
lms-lightweight-music-server | [2023-Nov-13 08:02:55.468] 1 - [debug] - 140618070752056 [API_SUBSONIC] Handling request 12 '/getCoverArt', continuation = false, params = {c=upmpdcli}, {id=al-3}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:02:55.468] 1 - [debug] - 140618070752056 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | 192.168.1.173 - - [2023-Nov-13 08:02:55.468] "POST /rest/getAlbumList2.view HTTP/1.1" 200 9404
lms-lightweight-music-server | 192.168.1.8 - - [2023-Nov-13 08:02:55.468] "GET /rest/getCoverArt?id=al-7&u=giovanni&p=enc:48456E254357676E5264667A3569417432243439357A6345&c=upmpdcli&v=1.16.1 HTTP/1.1" 200 52999
lms-lightweight-music-server | [2023-Nov-13 08:02:55.468] 1 - [debug] - 140618070752056 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:55.565] 1 - [debug] - 140618071055160 [API_SUBSONIC] Request 11 '/getCoverArt' handled!
lms-lightweight-music-server | [2023-Nov-13 08:02:55.567] 1 - [debug] - 140618071055160 [API_SUBSONIC] Handling request 13 '/getCoverArt', continuation = false, params = {c=upmpdcli}, {id=al-11}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:02:55.567] 1 - [debug] - 140618071055160 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:55.567] 1 - [debug] - 140618071055160 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:55.637] 1 - [debug] - 140618070903608 [API_SUBSONIC] Request 7 '/getCoverArt' handled!
lms-lightweight-music-server | 192.168.1.8 - - [2023-Nov-13 08:02:55.639] "GET /rest/getCoverArt?id=al-5&u=giovanni&p=enc:48456E254357676E5264667A3569417432243439357A6345&c=upmpdcli&v=1.16.1 HTTP/1.1" 200 103026
lms-lightweight-music-server | [2023-Nov-13 08:02:55.641] 1 - [debug] - 140618070903608 [API_SUBSONIC] Handling request 14 '/getCoverArt', continuation = false, params = {c=upmpdcli}, {id=al-2}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:02:55.642] 1 - [debug] - 140618070903608 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:55.642] 1 - [debug] - 140618070903608 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:55.675] 1 - [debug] - 140618071206712 [API_SUBSONIC] Request 10 '/getCoverArt' handled!
lms-lightweight-music-server | 192.168.1.8 - - [2023-Nov-13 08:02:55.676] "GET /rest/getCoverArt?id=al-4&u=giovanni&p=enc:48456E254357676E5264667A3569417432243439357A6345&c=upmpdcli&v=1.16.1 HTTP/1.1" 200 135233
lms-lightweight-music-server | 192.168.1.8 - - [2023-Nov-13 08:02:55.676] "GET /rest/getCoverArt?id=al-12&u=giovanni&p=enc:48456E254357676E5264667A3569417432243439357A6345&c=upmpdcli&v=1.16.1 HTTP/1.1" 200 170006
lms-lightweight-music-server | 192.168.1.8 - - [2023-Nov-13 08:02:55.677] "GET /rest/getCoverArt?id=al-6&u=giovanni&p=enc:48456E254357676E5264667A3569417432243439357A6345&c=upmpdcli&v=1.16.1 HTTP/1.1" 200 188867
lms-lightweight-music-server | [2023-Nov-13 08:02:55.681] 1 - [debug] - 140618071206712 [API_SUBSONIC] Handling request 15 '/getCoverArt', continuation = false, params = {c=upmpdcli}, {id=al-13}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:02:55.681] 1 - [debug] - 140618071206712 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:55.682] 1 - [debug] - 140618071206712 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:55.762] 1 - [debug] - 140618070752056 [API_SUBSONIC] Request 12 '/getCoverArt' handled!
lms-lightweight-music-server | [2023-Nov-13 08:02:55.762] 1 - [debug] - 140618070752056 [API_SUBSONIC] Handling request 16 '/getCoverArt', continuation = false, params = {c=upmpdcli}, {id=al-16}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:02:55.763] 1 - [debug] - 140618070752056 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:55.763] 1 - [debug] - 140618070752056 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:55.815] 1 - [debug] - 140618071206712 [API_SUBSONIC] Request 15 '/getCoverArt' handled!
lms-lightweight-music-server | [2023-Nov-13 08:02:55.816] 1 - [debug] - 140618071206712 [API_SUBSONIC] Handling request 17 '/getCoverArt', continuation = false, params = {c=upmpdcli}, {id=al-8}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:02:55.817] 1 - [debug] - 140618071206712 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:55.817] 1 - [debug] - 140618071206712 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:55.818] 1 - [debug] - 140618070903608 [API_SUBSONIC] Request 14 '/getCoverArt' handled!
lms-lightweight-music-server | 192.168.1.8 - - [2023-Nov-13 08:02:55.820] "GET /rest/getCoverArt?id=al-2&u=giovanni&p=enc:48456E254357676E5264667A3569417432243439357A6345&c=upmpdcli&v=1.16.1 HTTP/1.1" 200 89781
lms-lightweight-music-server | [2023-Nov-13 08:02:55.821] 1 - [debug] - 140618071055160 [API_SUBSONIC] Request 13 '/getCoverArt' handled!
lms-lightweight-music-server | 192.168.1.8 - - [2023-Nov-13 08:02:55.821] "GET /rest/getCoverArt?id=al-13&u=giovanni&p=enc:48456E254357676E5264667A3569417432243439357A6345&c=upmpdcli&v=1.16.1 HTTP/1.1" 200 133179
lms-lightweight-music-server | 192.168.1.8 - - [2023-Nov-13 08:02:55.822] "GET /rest/getCoverArt?id=al-3&u=giovanni&p=enc:48456E254357676E5264667A3569417432243439357A6345&c=upmpdcli&v=1.16.1 HTTP/1.1" 200 168828
lms-lightweight-music-server | 192.168.1.8 - - [2023-Nov-13 08:02:55.823] "GET /rest/getCoverArt?id=al-11&u=giovanni&p=enc:48456E254357676E5264667A3569417432243439357A6345&c=upmpdcli&v=1.16.1 HTTP/1.1" 200 114753
lms-lightweight-music-server | [2023-Nov-13 08:02:55.823] 1 - [debug] - 140618070903608 [API_SUBSONIC] Handling request 18 '/getCoverArt', continuation = false, params = {c=upmpdcli}, {id=al-1}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:02:55.824] 1 - [debug] - 140618070903608 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:55.824] 1 - [debug] - 140618070903608 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:55.955] 1 - [debug] - 140618070903608 [API_SUBSONIC] Request 18 '/getCoverArt' handled!
lms-lightweight-music-server | 192.168.1.8 - - [2023-Nov-13 08:02:55.956] "GET /rest/getCoverArt?id=al-1&u=giovanni&p=enc:48456E254357676E5264667A3569417432243439357A6345&c=upmpdcli&v=1.16.1 HTTP/1.1" 200 109035
lms-lightweight-music-server | [2023-Nov-13 08:02:55.959] 1 - [debug] - 140618070903608 [API_SUBSONIC] Handling request 20 '/getCoverArt', continuation = false, params = {c=upmpdcli}, {id=al-10}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:02:55.959] 1 - [debug] - 140618071055160 [API_SUBSONIC] Handling request 19 '/getCoverArt', continuation = false, params = {c=upmpdcli}, {id=al-20}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:02:55.960] 1 - [debug] - 140618070903608 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:55.960] 1 - [debug] - 140618070903608 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:55.961] 1 - [debug] - 140618071055160 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:55.963] 1 - [debug] - 140618071055160 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:56.110] 1 - [debug] - 140618071055160 [API_SUBSONIC] Request 19 '/getCoverArt' handled!
lms-lightweight-music-server | 192.168.1.8 - - [2023-Nov-13 08:02:56.113] "GET /rest/getCoverArt?id=al-20&u=giovanni&p=enc:48456E254357676E5264667A3569417432243439357A6345&c=upmpdcli&v=1.16.1 HTTP/1.1" 200 65531
lms-lightweight-music-server | [2023-Nov-13 08:02:56.115] 1 - [debug] - 140618071055160 [API_SUBSONIC] Handling request 21 '/getCoverArt', continuation = false, params = {c=upmpdcli}, {id=al-9}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:02:56.117] 1 - [debug] - 140618071055160 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:56.118] 1 - [debug] - 140618071055160 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:56.243] 1 - [debug] - 140618071055160 [API_SUBSONIC] Request 21 '/getCoverArt' handled!
lms-lightweight-music-server | 192.168.1.8 - - [2023-Nov-13 08:02:56.245] "GET /rest/getCoverArt?id=al-9&u=giovanni&p=enc:48456E254357676E5264667A3569417432243439357A6345&c=upmpdcli&v=1.16.1 HTTP/1.1" 200 73227
lms-lightweight-music-server | [2023-Nov-13 08:02:56.249] 1 - [debug] - 140618071055160 [API_SUBSONIC] Handling request 22 '/getCoverArt', continuation = false, params = {c=upmpdcli}, {id=al-17}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:02:56.250] 1 - [debug] - 140618071055160 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:56.251] 1 - [debug] - 140618071055160 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:56.309] 1 - [debug] - 140618071206712 [API_SUBSONIC] Request 17 '/getCoverArt' handled!
lms-lightweight-music-server | 192.168.1.8 - - [2023-Nov-13 08:02:56.309] "GET /rest/getCoverArt?id=al-8&u=giovanni&p=enc:48456E254357676E5264667A3569417432243439357A6345&c=upmpdcli&v=1.16.1 HTTP/1.1" 200 62213
lms-lightweight-music-server | [2023-Nov-13 08:02:56.313] 1 - [debug] - 140618071206712 [API_SUBSONIC] Handling request 23 '/getCoverArt', continuation = false, params = {c=upmpdcli}, {id=al-21}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:02:56.313] 1 - [debug] - 140618071206712 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:56.314] 1 - [debug] - 140618071206712 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:56.330] 1 - [debug] - 140618070903608 [API_SUBSONIC] Request 20 '/getCoverArt' handled!
lms-lightweight-music-server | [2023-Nov-13 08:02:56.332] 1 - [debug] - 140618070903608 [API_SUBSONIC] Handling request 24 '/getCoverArt', continuation = false, params = {c=upmpdcli}, {id=al-19}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:02:56.334] 1 - [debug] - 140618070903608 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:56.334] 1 - [debug] - 140618070903608 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:56.390] 1 - [debug] - 140618071055160 [API_SUBSONIC] Request 22 '/getCoverArt' handled!
lms-lightweight-music-server | [2023-Nov-13 08:02:56.392] 1 - [debug] - 140618071055160 [API_SUBSONIC] Handling request 25 '/getCoverArt', continuation = false, params = {c=upmpdcli}, {id=al-14}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:02:56.393] 1 - [debug] - 140618071055160 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:56.394] 1 - [debug] - 140618071055160 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:56.431] 1 - [debug] - 140618071206712 [API_SUBSONIC] Request 23 '/getCoverArt' handled!
lms-lightweight-music-server | 192.168.1.8 - - [2023-Nov-13 08:02:56.432] "GET /rest/getCoverArt?id=al-21&u=giovanni&p=enc:48456E254357676E5264667A3569417432243439357A6345&c=upmpdcli&v=1.16.1 HTTP/1.1" 200 61693
lms-lightweight-music-server | 192.168.1.8 - - [2023-Nov-13 08:02:56.432] "GET /rest/getCoverArt?id=al-17&u=giovanni&p=enc:48456E254357676E5264667A3569417432243439357A6345&c=upmpdcli&v=1.16.1 HTTP/1.1" 200 97440
lms-lightweight-music-server | 192.168.1.8 - - [2023-Nov-13 08:02:56.433] "GET /rest/getCoverArt?id=al-10&u=giovanni&p=enc:48456E254357676E5264667A3569417432243439357A6345&c=upmpdcli&v=1.16.1 HTTP/1.1" 200 321332
lms-lightweight-music-server | [2023-Nov-13 08:02:56.437] 1 - [debug] - 140618071206712 [API_SUBSONIC] Handling request 26 '/getCoverArt', continuation = false, params = {c=upmpdcli}, {id=al-15}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:02:56.438] 1 - [debug] - 140618071206712 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:56.438] 1 - [debug] - 140618071206712 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:56.639] 1 - [debug] - 140618070752056 [API_SUBSONIC] Request 16 '/getCoverArt' handled!
lms-lightweight-music-server | [2023-Nov-13 08:02:56.640] 1 - [debug] - 140618070752056 [API_SUBSONIC] Handling request 27 '/getCoverArt', continuation = false, params = {c=upmpdcli}, {id=al-18}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:02:56.641] 1 - [debug] - 140618070752056 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:56.641] 1 - [debug] - 140618070752056 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:56.748] 1 - [debug] - 140618071206712 [API_SUBSONIC] Request 26 '/getCoverArt' handled!
lms-lightweight-music-server | [2023-Nov-13 08:02:56.749] 1 - [debug] - 140618071206712 [API_SUBSONIC] Handling request 28 '/getAlbum.view', continuation = false, params = {c=upmpdcli}, {f=json}, {id=al-12}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:02:56.749] 1 - [debug] - 140618071206712 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:56.750] 1 - [debug] - 140618071206712 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:56.817] 1 - [debug] - 140618071206712 [API_SUBSONIC] Request 28 '/getAlbum' handled!
lms-lightweight-music-server | 192.168.1.8 - - [2023-Nov-13 08:02:56.818] "GET /rest/getCoverArt?id=al-15&u=giovanni&p=enc:48456E254357676E5264667A3569417432243439357A6345&c=upmpdcli&v=1.16.1 HTTP/1.1" 200 171352
lms-lightweight-music-server | 192.168.1.173 - - [2023-Nov-13 08:02:56.819] "POST /rest/getAlbum.view HTTP/1.1" 200 14642
lms-lightweight-music-server | [2023-Nov-13 08:02:56.828] 1 - [debug] - 140618071206712 [API_SUBSONIC] Handling request 29 '/getAlbum.view', continuation = false, params = {c=upmpdcli}, {f=json}, {id=al-12}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:02:56.828] 1 - [debug] - 140618071206712 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:56.828] 1 - [debug] - 140618071206712 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:56.882] 1 - [debug] - 140618071206712 [API_SUBSONIC] Request 29 '/getAlbum' handled!
lms-lightweight-music-server | 192.168.1.173 - - [2023-Nov-13 08:02:56.884] "POST /rest/getAlbum.view HTTP/1.1" 200 14642
lms-lightweight-music-server | [2023-Nov-13 08:02:56.917] 1 - [debug] - 140618071206712 [API_SUBSONIC] Handling request 30 '/getArtist.view', continuation = false, params = {c=upmpdcli}, {f=json}, {id=ar-4}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:02:56.917] 1 - [debug] - 140618071206712 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:56.917] 1 - [debug] - 140618071206712 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:56.953] 1 - [debug] - 140618071206712 [API_SUBSONIC] Request 30 '/getArtist' handled!
lms-lightweight-music-server | 192.168.1.173 - - [2023-Nov-13 08:02:56.954] "POST /rest/getArtist.view HTTP/1.1" 200 2924
lms-lightweight-music-server | [2023-Nov-13 08:02:56.964] 1 - [debug] - 140618071206712 [API_SUBSONIC] Handling request 31 '/getTopSongs.view', continuation = false, params = {artist=Eric Clapton}, {c=upmpdcli}, {count=50}, {f=json}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:02:56.964] 1 - [debug] - 140618071206712 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:56.964] 1 - [debug] - 140618071206712 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:56.978] 1 - [error] - 140618071206712 [API_SUBSONIC] Error while processing request '/getTopSongs', params = [{artist=Eric Clapton}, {c=upmpdcli}, {count=50}, {f=json}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, ], code = 0, msg = 'Not implemented'
lms-lightweight-music-server | 192.168.1.173 - - [2023-Nov-13 08:02:56.978] "POST /rest/getTopSongs.view HTTP/1.1" 200 160
lms-lightweight-music-server | [2023-Nov-13 08:02:56.986] 1 - [debug] - 140618071206712 [API_SUBSONIC] Handling request 32 '/getArtistInfo2.view', continuation = false, params = {c=upmpdcli}, {count=20}, {f=json}, {id=ar-4}, {includeNotPresent=False}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:02:56.987] 1 - [debug] - 140618071206712 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:56.987] 1 - [debug] - 140618071206712 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:57.001] 1 - [debug] - 140618071206712 [RECOMMENDATION] Trying engine 'clusters' to get similar artists
lms-lightweight-music-server | [2023-Nov-13 08:02:57.007] 1 - [debug] - 140618071206712 [RECOMMENDATION] Got 12 similar artists using engine 'clusters'
lms-lightweight-music-server | [2023-Nov-13 08:02:57.014] 1 - [debug] - 140618071206712 [API_SUBSONIC] Request 32 '/getArtistInfo2' handled!
lms-lightweight-music-server | 192.168.1.173 - - [2023-Nov-13 08:02:57.015] "POST /rest/getArtistInfo2.view HTTP/1.1" 200 1781
lms-lightweight-music-server | [2023-Nov-13 08:02:57.025] 1 - [debug] - 140618071206712 [API_SUBSONIC] Handling request 33 '/getSimilarSongs2.view', continuation = false, params = {c=upmpdcli}, {count=10}, {f=json}, {id=al-12}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:02:57.026] 1 - [debug] - 140618071206712 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:57.026] 1 - [debug] - 140618071206712 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:57.040] 1 - [debug] - 140618071206712 [RECOMMENDATION] Trying engine 'clusters' to get similar releases
lms-lightweight-music-server | [2023-Nov-13 08:02:57.043] 1 - [debug] - 140618071206712 [RECOMMENDATION] Got 2 similar releases using engine 'clusters'
lms-lightweight-music-server | [2023-Nov-13 08:02:57.075] 1 - [debug] - 140618071206712 [API_SUBSONIC] Request 33 '/getSimilarSongs2' handled!
lms-lightweight-music-server | 192.168.1.173 - - [2023-Nov-13 08:02:57.077] "POST /rest/getSimilarSongs2.view HTTP/1.1" 200 9177
lms-lightweight-music-server | [2023-Nov-13 08:02:57.081] 1 - [debug] - 140618070903608 [API_SUBSONIC] Request 24 '/getCoverArt' handled!
lms-lightweight-music-server | 192.168.1.8 - - [2023-Nov-13 08:02:57.081] "GET /rest/getCoverArt?id=al-19&u=giovanni&p=enc:48456E254357676E5264667A3569417432243439357A6345&c=upmpdcli&v=1.16.1 HTTP/1.1" 200 189089
lms-lightweight-music-server | [2023-Nov-13 08:02:57.092] 1 - [debug] - 140618071206712 [API_SUBSONIC] Handling request 34 '/getCoverArt', continuation = false, params = {c=upmpdcli}, {id=al-12}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:02:57.093] 1 - [debug] - 140618071206712 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:57.093] 1 - [debug] - 140618071206712 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:57.093] 1 - [debug] - 140618070903608 [API_SUBSONIC] Handling request 35 '/getCoverArt', continuation = false, params = {c=upmpdcli}, {id=al-7}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:02:57.096] 1 - [debug] - 140618070903608 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:57.097] 1 - [debug] - 140618070903608 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:57.112] 1 - [debug] - 140618071206712 [API_SUBSONIC] Request 34 '/getCoverArt' handled!
lms-lightweight-music-server | [2023-Nov-13 08:02:57.114] 1 - [debug] - 140618071206712 [API_SUBSONIC] Handling request 36 '/getCoverArt', continuation = false, params = {c=upmpdcli}, {id=al-17}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:02:57.115] 1 - [debug] - 140618070903608 [API_SUBSONIC] Request 35 '/getCoverArt' handled!
lms-lightweight-music-server | [2023-Nov-13 08:02:57.116] 1 - [debug] - 140618071206712 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:57.116] 1 - [debug] - 140618071206712 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | 192.168.1.8 - - [2023-Nov-13 08:02:57.118] "GET /rest/getCoverArt?id=al-12&u=giovanni&p=enc:48456E254357676E5264667A3569417432243439357A6345&c=upmpdcli&v=1.16.1 HTTP/1.1" 200 170006
lms-lightweight-music-server | 192.168.1.8 - - [2023-Nov-13 08:02:57.118] "GET /rest/getCoverArt?id=al-7&u=giovanni&p=enc:48456E254357676E5264667A3569417432243439357A6345&c=upmpdcli&v=1.16.1 HTTP/1.1" 200 52999
lms-lightweight-music-server | [2023-Nov-13 08:02:57.130] 1 - [debug] - 140618071206712 [API_SUBSONIC] Request 36 '/getCoverArt' handled!
lms-lightweight-music-server | 192.168.1.8 - - [2023-Nov-13 08:02:57.131] "GET /rest/getCoverArt?id=al-17&u=giovanni&p=enc:48456E254357676E5264667A3569417432243439357A6345&c=upmpdcli&v=1.16.1 HTTP/1.1" 200 97440
lms-lightweight-music-server | [2023-Nov-13 08:02:57.292] 1 - [debug] - 140618071055160 [API_SUBSONIC] Request 25 '/getCoverArt' handled!
lms-lightweight-music-server | [2023-Nov-13 08:02:57.501] 1 - [debug] - 140618070752056 [API_SUBSONIC] Request 27 '/getCoverArt' handled!
lms-lightweight-music-server | 192.168.1.8 - - [2023-Nov-13 08:02:57.502] "GET /rest/getCoverArt?id=al-18&u=giovanni&p=enc:48456E254357676E5264667A3569417432243439357A6345&c=upmpdcli&v=1.16.1 HTTP/1.1" 200 84985
lms-lightweight-music-server | [2023-Nov-13 08:02:58.065] 1 - [debug] - 140618070903608 [API_SUBSONIC] Handling request 37 '/getAlbum.view', continuation = false, params = {c=upmpdcli}, {f=json}, {id=al-12}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:02:58.065] 1 - [debug] - 140618070903608 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:58.065] 1 - [debug] - 140618070903608 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | 192.168.1.173 - - [2023-Nov-13 08:02:58.123] "POST /rest/getAlbum.view HTTP/1.1" 200 14642
lms-lightweight-music-server | [2023-Nov-13 08:02:58.123] 1 - [debug] - 140618070903608 [API_SUBSONIC] Request 37 '/getAlbum' handled!
lms-lightweight-music-server | [2023-Nov-13 08:02:58.146] 1 - [debug] - 140618071206712 [API_SUBSONIC] Handling request 38 '/getAlbum.view', continuation = false, params = {c=upmpdcli}, {f=json}, {id=al-12}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:02:58.146] 1 - [debug] - 140618071206712 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:58.146] 1 - [debug] - 140618071206712 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:02:58.205] 1 - [debug] - 140618071206712 [API_SUBSONIC] Request 38 '/getAlbum' handled!
lms-lightweight-music-server | 192.168.1.173 - - [2023-Nov-13 08:02:58.206] "POST /rest/getAlbum.view HTTP/1.1" 200 14642
lms-lightweight-music-server | [2023-Nov-13 08:03:04.436] 1 - [debug] - 140618074581816 [FEEDBACK] [listenbrainz] Starting sync!
lms-lightweight-music-server | [2023-Nov-13 08:03:04.437] 1 - [debug] - 140618074278712 [SCROBBLING] [listenbrainz] Starting sync!
lms-lightweight-music-server | [2023-Nov-13 08:03:04.439] 1 - [debug] - 140618074581816 [FEEDBACK] [listenbrainz] Queing 0 pending 'love' feedbacks
lms-lightweight-music-server | [2023-Nov-13 08:03:04.442] 1 - [debug] - 140618074278712 [SCROBBLING] [listenbrainz] Queing 0 pending listen
lms-lightweight-music-server | [2023-Nov-13 08:03:04.442] 1 - [debug] - 140618074278712 [SCROBBLING] [listenbrainz] Scheduled sync in 3600 seconds...
lms-lightweight-music-server | [2023-Nov-13 08:03:04.443] 1 - [debug] - 140618074581816 [FEEDBACK] [listenbrainz] Queing 0 pending 'erase' feedbacks
lms-lightweight-music-server | [2023-Nov-13 08:03:04.443] 1 - [debug] - 140618074581816 [FEEDBACK] [listenbrainz] Scheduled sync in 3600 seconds...
lms-lightweight-music-server | [2023-Nov-13 08:03:08.384] 1 - [debug] - 140618071055160 [API_SUBSONIC] Handling request 39 '/getAlbum.view', continuation = false, params = {c=upmpdcli}, {f=json}, {id=al-12}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:03:08.385] 1 - [debug] - 140618071055160 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:08.385] 1 - [debug] - 140618071055160 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:08.453] 1 - [debug] - 140618071055160 [API_SUBSONIC] Request 39 '/getAlbum' handled!
lms-lightweight-music-server | 192.168.1.173 - - [2023-Nov-13 08:03:08.454] "POST /rest/getAlbum.view HTTP/1.1" 200 14642
lms-lightweight-music-server | [2023-Nov-13 08:03:08.478] 1 - [debug] - 140618070903608 [API_SUBSONIC] Handling request 40 '/getAlbum.view', continuation = false, params = {c=upmpdcli}, {f=json}, {id=al-12}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:03:08.478] 1 - [debug] - 140618070903608 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:08.478] 1 - [debug] - 140618070903608 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:08.537] 1 - [debug] - 140618070903608 [API_SUBSONIC] Request 40 '/getAlbum' handled!
lms-lightweight-music-server | 192.168.1.173 - - [2023-Nov-13 08:03:08.538] "POST /rest/getAlbum.view HTTP/1.1" 200 14642
lms-lightweight-music-server | [2023-Nov-13 08:03:09.340] 1 - [debug] - 140618071206712 [API_SUBSONIC] Handling request 41 '/getCoverArt', continuation = false, params = {c=upmpdcli}, {id=al-12}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:03:09.341] 1 - [debug] - 140618071206712 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:09.341] 1 - [debug] - 140618071206712 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:09.360] 1 - [debug] - 140618071206712 [API_SUBSONIC] Request 41 '/getCoverArt' handled!
lms-lightweight-music-server | 192.168.1.8 - - [2023-Nov-13 08:03:09.361] "GET /rest/getCoverArt?id=al-12&u=giovanni&p=enc:48456E254357676E5264667A3569417432243439357A6345&c=upmpdcli&v=1.16.1 HTTP/1.1" 200 170006
lms-lightweight-music-server | [2023-Nov-13 08:03:10.646] 1 - [debug] - 140618071206712 [API_SUBSONIC] Handling request 42 '/getSong.view', continuation = false, params = {c=upmpdcli}, {f=json}, {id=tr-234}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:03:10.646] 1 - [debug] - 140618071206712 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:10.646] 1 - [debug] - 140618071206712 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:10.664] 1 - [debug] - 140618071206712 [API_SUBSONIC] Request 42 '/getSong' handled!
lms-lightweight-music-server | 192.168.1.173 - - [2023-Nov-13 08:03:10.665] "POST /rest/getSong.view HTTP/1.1" 200 841
lms-lightweight-music-server | [2023-Nov-13 08:03:10.675] 1 - [debug] - 140618070752056 [API_SUBSONIC] Handling request 43 '/stream', continuation = false, params = {c=upmpdcli}, {format=flac}, {id=tr-234}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:03:10.676] 1 - [debug] - 140618070752056 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:10.676] 1 - [debug] - 140618070752056 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:10.715] 1 - [debug] - 140618070752056 [API_SUBSONIC] stream parameters are compatible with actual file: no transcode
lms-lightweight-music-server | [2023-Nov-13 08:03:10.716] 1 - [debug] - 140618070752056 [UTILS] File '/music/Music01/Blues/Eric Clapton/The Cream of Clapton (1995)/01 - I Feel Free (Cream).flac', fileSize = 18230043
lms-lightweight-music-server | [2023-Nov-13 08:03:10.716] 1 - [debug] - 140618070752056 [UTILS] No range requested
lms-lightweight-music-server | [2023-Nov-13 08:03:10.716] 1 - [debug] - 140618070752056 [UTILS] Written 65536 bytes
lms-lightweight-music-server | [2023-Nov-13 08:03:10.716] 1 - [debug] - 140618070752056 [UTILS] Progress: 65536/18230043
lms-lightweight-music-server | [2023-Nov-13 08:03:10.716] 1 - [debug] - 140618070752056 [UTILS] Job not complete! Next chunk offset = 65536
lms-lightweight-music-server | [2023-Nov-13 08:03:10.716] 1 - [debug] - 140618070752056 [API_SUBSONIC] Request 43 '/stream' handled!
lms-lightweight-music-server | [2023-Nov-13 08:03:10.717] 1 - [debug] - 140618070752056 [API_SUBSONIC] Handling request 44 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-234}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:03:10.717] 1 - [debug] - 140618070752056 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:10.717] 1 - [debug] - 140618070752056 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:10.731] 1 - [debug] - 140618070752056 [UTILS] Written 65536 bytes
lms-lightweight-music-server | [2023-Nov-13 08:03:10.731] 1 - [debug] - 140618070752056 [UTILS] Progress: 65536/18164507
lms-lightweight-music-server | [2023-Nov-13 08:03:10.731] 1 - [debug] - 140618070752056 [UTILS] Job not complete! Next chunk offset = 131072
lms-lightweight-music-server | [2023-Nov-13 08:03:10.731] 1 - [debug] - 140618070752056 [API_SUBSONIC] Request 44 '/stream' handled!
lms-lightweight-music-server | [2023-Nov-13 08:03:10.731] 1 - [debug] - 140618070752056 [API_SUBSONIC] Handling request 45 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-234}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:03:10.732] 1 - [debug] - 140618070752056 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:10.732] 1 - [debug] - 140618070752056 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:10.746] 1 - [debug] - 140618070752056 [UTILS] Written 65536 bytes
lms-lightweight-music-server | [2023-Nov-13 08:03:10.746] 1 - [debug] - 140618070752056 [UTILS] Progress: 65536/18098971
lms-lightweight-music-server | [2023-Nov-13 08:03:10.746] 1 - [debug] - 140618070752056 [UTILS] Job not complete! Next chunk offset = 196608
lms-lightweight-music-server | [2023-Nov-13 08:03:10.746] 1 - [debug] - 140618070752056 [API_SUBSONIC] Request 45 '/stream' handled!
lms-lightweight-music-server | [2023-Nov-13 08:03:10.747] 1 - [debug] - 140618070752056 [API_SUBSONIC] Handling request 46 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-234}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:03:10.747] 1 - [debug] - 140618070752056 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:10.747] 1 - [debug] - 140618070752056 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:10.760] 1 - [debug] - 140618070752056 [UTILS] Written 65536 bytes
lms-lightweight-music-server | [2023-Nov-13 08:03:10.760] 1 - [debug] - 140618070752056 [UTILS] Progress: 65536/18033435
lms-lightweight-music-server | [2023-Nov-13 08:03:10.760] 1 - [debug] - 140618070752056 [UTILS] Job not complete! Next chunk offset = 262144
lms-lightweight-music-server | [2023-Nov-13 08:03:10.760] 1 - [debug] - 140618070752056 [API_SUBSONIC] Request 46 '/stream' handled!
lms-lightweight-music-server | [2023-Nov-13 08:03:10.761] 1 - [debug] - 140618070752056 [API_SUBSONIC] Handling request 47 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-234}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:03:10.761] 1 - [debug] - 140618070752056 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:10.761] 1 - [debug] - 140618070752056 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:10.774] 1 - [debug] - 140618070752056 [UTILS] Written 65536 bytes
lms-lightweight-music-server | [2023-Nov-13 08:03:10.774] 1 - [debug] - 140618070752056 [UTILS] Progress: 65536/17967899
lms-lightweight-music-server | [2023-Nov-13 08:03:10.774] 1 - [debug] - 140618070752056 [UTILS] Job not complete! Next chunk offset = 327680
lms-lightweight-music-server | [2023-Nov-13 08:03:10.774] 1 - [debug] - 140618070752056 [API_SUBSONIC] Request 47 '/stream' handled!
lms-lightweight-music-server | [2023-Nov-13 08:03:10.774] 1 - [debug] - 140618070752056 [API_SUBSONIC] Handling request 48 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-234}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:03:10.775] 1 - [debug] - 140618070752056 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:10.775] 1 - [debug] - 140618070752056 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:10.788] 1 - [debug] - 140618070752056 [UTILS] Written 65536 bytes
lms-lightweight-music-server | [2023-Nov-13 08:03:10.788] 1 - [debug] - 140618070752056 [UTILS] Progress: 65536/17902363
lms-lightweight-music-server | [2023-Nov-13 08:03:10.788] 1 - [debug] - 140618070752056 [UTILS] Job not complete! Next chunk offset = 393216
lms-lightweight-music-server | [2023-Nov-13 08:03:10.788] 1 - [debug] - 140618070752056 [API_SUBSONIC] Request 48 '/stream' handled!
lms-lightweight-music-server | [2023-Nov-13 08:03:10.789] 1 - [debug] - 140618070752056 [API_SUBSONIC] Handling request 49 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-234}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:03:10.789] 1 - [debug] - 140618070752056 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:10.789] 1 - [debug] - 140618070752056 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:10.802] 1 - [debug] - 140618070752056 [UTILS] Written 65536 bytes
lms-lightweight-music-server | [2023-Nov-13 08:03:10.802] 1 - [debug] - 140618070752056 [UTILS] Progress: 65536/17836827
lms-lightweight-music-server | [2023-Nov-13 08:03:10.802] 1 - [debug] - 140618070752056 [UTILS] Job not complete! Next chunk offset = 458752
lms-lightweight-music-server | [2023-Nov-13 08:03:10.802] 1 - [debug] - 140618070752056 [API_SUBSONIC] Request 49 '/stream' handled!
lms-lightweight-music-server | [2023-Nov-13 08:03:10.803] 1 - [debug] - 140618070752056 [API_SUBSONIC] Handling request 50 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-234}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:03:10.803] 1 - [debug] - 140618070752056 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:10.803] 1 - [debug] - 140618070752056 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:10.816] 1 - [debug] - 140618070903608 [API_SUBSONIC] Handling request 51 '/getSong.view', continuation = false, params = {c=mpd-subsonic-scrobbler}, {f=json}, {id=tr-234}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:03:10.816] 1 - [debug] - 140618070903608 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:10.816] 1 - [debug] - 140618070903608 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:10.817] 1 - [debug] - 140618070752056 [UTILS] Written 65536 bytes
lms-lightweight-music-server | [2023-Nov-13 08:03:10.817] 1 - [debug] - 140618070752056 [UTILS] Progress: 65536/17771291
lms-lightweight-music-server | [2023-Nov-13 08:03:10.817] 1 - [debug] - 140618070752056 [UTILS] Job not complete! Next chunk offset = 524288
lms-lightweight-music-server | [2023-Nov-13 08:03:10.817] 1 - [debug] - 140618070752056 [API_SUBSONIC] Request 50 '/stream' handled!
lms-lightweight-music-server | [2023-Nov-13 08:03:10.817] 1 - [debug] - 140618070752056 [API_SUBSONIC] Handling request 52 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-234}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:03:10.817] 1 - [debug] - 140618070752056 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:10.817] 1 - [debug] - 140618070752056 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:10.830] 1 - [debug] - 140618070752056 [UTILS] Written 65536 bytes
lms-lightweight-music-server | [2023-Nov-13 08:03:10.830] 1 - [debug] - 140618070752056 [UTILS] Progress: 65536/17705755
lms-lightweight-music-server | [2023-Nov-13 08:03:10.830] 1 - [debug] - 140618070752056 [UTILS] Job not complete! Next chunk offset = 589824
lms-lightweight-music-server | [2023-Nov-13 08:03:10.830] 1 - [debug] - 140618070752056 [API_SUBSONIC] Request 52 '/stream' handled!
lms-lightweight-music-server | [2023-Nov-13 08:03:10.831] 1 - [debug] - 140618070752056 [API_SUBSONIC] Handling request 53 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-234}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:03:10.831] 1 - [debug] - 140618070752056 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:10.831] 1 - [debug] - 140618070752056 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:10.836] 1 - [debug] - 140618070903608 [API_SUBSONIC] Request 51 '/getSong' handled!
lms-lightweight-music-server | 192.168.1.173 - - [2023-Nov-13 08:03:10.837] "POST /rest/getSong.view HTTP/1.1" 200 841
lms-lightweight-music-server | [2023-Nov-13 08:03:10.844] 1 - [debug] - 140618070752056 [UTILS] Written 65536 bytes
lms-lightweight-music-server | [2023-Nov-13 08:03:10.844] 1 - [debug] - 140618070752056 [UTILS] Progress: 65536/17640219
lms-lightweight-music-server | [2023-Nov-13 08:03:10.844] 1 - [debug] - 140618070752056 [UTILS] Job not complete! Next chunk offset = 655360
lms-lightweight-music-server | [2023-Nov-13 08:03:10.844] 1 - [debug] - 140618070752056 [API_SUBSONIC] Request 53 '/stream' handled!
lms-lightweight-music-server | [2023-Nov-13 08:03:10.845] 1 - [debug] - 140618070752056 [API_SUBSONIC] Handling request 54 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-234}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:03:10.845] 1 - [debug] - 140618070752056 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:10.845] 1 - [debug] - 140618070752056 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:10.858] 1 - [debug] - 140618070752056 [UTILS] Written 65536 bytes
lms-lightweight-music-server | [2023-Nov-13 08:03:10.858] 1 - [debug] - 140618070752056 [UTILS] Progress: 65536/17574683
lms-lightweight-music-server | [2023-Nov-13 08:03:10.858] 1 - [debug] - 140618070752056 [UTILS] Job not complete! Next chunk offset = 720896
lms-lightweight-music-server | [2023-Nov-13 08:03:10.858] 1 - [debug] - 140618070752056 [API_SUBSONIC] Request 54 '/stream' handled!
lms-lightweight-music-server | [2023-Nov-13 08:03:10.858] 1 - [debug] - 140618070752056 [API_SUBSONIC] Handling request 55 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-234}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:03:10.859] 1 - [debug] - 140618070752056 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:10.859] 1 - [debug] - 140618070752056 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:10.871] 1 - [debug] - 140618070752056 [UTILS] Written 65536 bytes
lms-lightweight-music-server | [2023-Nov-13 08:03:10.872] 1 - [debug] - 140618070752056 [UTILS] Progress: 65536/17509147
lms-lightweight-music-server | [2023-Nov-13 08:03:10.872] 1 - [debug] - 140618070752056 [UTILS] Job not complete! Next chunk offset = 786432
lms-lightweight-music-server | [2023-Nov-13 08:03:10.872] 1 - [debug] - 140618070752056 [API_SUBSONIC] Request 55 '/stream' handled!
lms-lightweight-music-server | [2023-Nov-13 08:03:10.872] 1 - [debug] - 140618070752056 [API_SUBSONIC] Handling request 56 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-234}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:03:10.872] 1 - [debug] - 140618070752056 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:10.872] 1 - [debug] - 140618070752056 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:10.885] 1 - [debug] - 140618070752056 [UTILS] Written 65536 bytes
lms-lightweight-music-server | [2023-Nov-13 08:03:10.885] 1 - [debug] - 140618070752056 [UTILS] Progress: 65536/17443611
lms-lightweight-music-server | [2023-Nov-13 08:03:10.885] 1 - [debug] - 140618070752056 [UTILS] Job not complete! Next chunk offset = 851968
lms-lightweight-music-server | [2023-Nov-13 08:03:10.885] 1 - [debug] - 140618070752056 [API_SUBSONIC] Request 56 '/stream' handled!
lms-lightweight-music-server | [2023-Nov-13 08:03:10.886] 1 - [debug] - 140618070752056 [API_SUBSONIC] Handling request 57 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-234}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:03:10.886] 1 - [debug] - 140618070752056 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:10.886] 1 - [debug] - 140618070752056 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:10.900] 1 - [debug] - 140618070752056 [UTILS] Written 65536 bytes
lms-lightweight-music-server | [2023-Nov-13 08:03:10.900] 1 - [debug] - 140618070752056 [UTILS] Progress: 65536/17378075
lms-lightweight-music-server | [2023-Nov-13 08:03:10.900] 1 - [debug] - 140618070752056 [UTILS] Job not complete! Next chunk offset = 917504
lms-lightweight-music-server | [2023-Nov-13 08:03:10.900] 1 - [debug] - 140618070752056 [API_SUBSONIC] Request 57 '/stream' handled!
lms-lightweight-music-server | [2023-Nov-13 08:03:10.901] 1 - [debug] - 140618070752056 [API_SUBSONIC] Handling request 58 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-234}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:03:10.901] 1 - [debug] - 140618070752056 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:10.901] 1 - [debug] - 140618070752056 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:10.914] 1 - [debug] - 140618070752056 [UTILS] Written 65536 bytes
lms-lightweight-music-server | [2023-Nov-13 08:03:10.914] 1 - [debug] - 140618070752056 [UTILS] Progress: 65536/17312539
lms-lightweight-music-server | [2023-Nov-13 08:03:10.914] 1 - [debug] - 140618070752056 [UTILS] Job not complete! Next chunk offset = 983040
lms-lightweight-music-server | [2023-Nov-13 08:03:10.914] 1 - [debug] - 140618070752056 [API_SUBSONIC] Request 58 '/stream' handled!
lms-lightweight-music-server | [2023-Nov-13 08:03:10.915] 1 - [debug] - 140618070752056 [API_SUBSONIC] Handling request 59 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-234}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:03:10.915] 1 - [debug] - 140618070752056 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:10.915] 1 - [debug] - 140618070752056 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:10.928] 1 - [debug] - 140618070752056 [UTILS] Written 65536 bytes
lms-lightweight-music-server | [2023-Nov-13 08:03:10.928] 1 - [debug] - 140618070752056 [UTILS] Progress: 65536/17247003
lms-lightweight-music-server | [2023-Nov-13 08:03:10.928] 1 - [debug] - 140618070752056 [UTILS] Job not complete! Next chunk offset = 1048576
lms-lightweight-music-server | [2023-Nov-13 08:03:10.928] 1 - [debug] - 140618070752056 [API_SUBSONIC] Request 59 '/stream' handled!
lms-lightweight-music-server | [2023-Nov-13 08:03:10.928] 1 - [debug] - 140618070752056 [API_SUBSONIC] Handling request 60 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-234}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:03:10.928] 1 - [debug] - 140618070752056 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:10.928] 1 - [debug] - 140618070752056 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:10.931] 1 - [debug] - 140618071055160 [API_SUBSONIC] Handling request 61 '/getSong.view', continuation = false, params = {c=mpd-subsonic-scrobbler}, {f=json}, {id=tr-234}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:03:10.932] 1 - [debug] - 140618071055160 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:10.932] 1 - [debug] - 140618071055160 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:10.941] 1 - [debug] - 140618070752056 [UTILS] Written 65536 bytes
lms-lightweight-music-server | [2023-Nov-13 08:03:10.941] 1 - [debug] - 140618070752056 [UTILS] Progress: 65536/17181467
lms-lightweight-music-server | [2023-Nov-13 08:03:10.941] 1 - [debug] - 140618070752056 [UTILS] Job not complete! Next chunk offset = 1114112
lms-lightweight-music-server | [2023-Nov-13 08:03:10.942] 1 - [debug] - 140618070752056 [API_SUBSONIC] Request 60 '/stream' handled!
lms-lightweight-music-server | [2023-Nov-13 08:03:10.942] 1 - [debug] - 140618070752056 [API_SUBSONIC] Handling request 62 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-234}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:03:10.942] 1 - [debug] - 140618070752056 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:10.942] 1 - [debug] - 140618070752056 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:10.948] 1 - [debug] - 140618071055160 [API_SUBSONIC] Request 61 '/getSong' handled!
lms-lightweight-music-server | 192.168.1.173 - - [2023-Nov-13 08:03:10.949] "POST /rest/getSong.view HTTP/1.1" 200 841
lms-lightweight-music-server | [2023-Nov-13 08:03:10.955] 1 - [debug] - 140618070752056 [UTILS] Written 65536 bytes
lms-lightweight-music-server | [2023-Nov-13 08:03:10.955] 1 - [debug] - 140618070752056 [UTILS] Progress: 65536/17115931
lms-lightweight-music-server | [2023-Nov-13 08:03:10.955] 1 - [debug] - 140618070752056 [UTILS] Job not complete! Next chunk offset = 1179648
lms-lightweight-music-server | [2023-Nov-13 08:03:10.955] 1 - [debug] - 140618070752056 [API_SUBSONIC] Request 62 '/stream' handled!
lms-lightweight-music-server | [2023-Nov-13 08:03:10.956] 1 - [debug] - 140618070752056 [API_SUBSONIC] Handling request 63 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-234}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:03:10.956] 1 - [debug] - 140618070752056 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:10.956] 1 - [debug] - 140618070752056 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:10.969] 1 - [debug] - 140618070752056 [UTILS] Written 65536 bytes
lms-lightweight-music-server | [2023-Nov-13 08:03:10.969] 1 - [debug] - 140618070752056 [UTILS] Progress: 65536/17050395
lms-lightweight-music-server | [2023-Nov-13 08:03:10.969] 1 - [debug] - 140618070752056 [UTILS] Job not complete! Next chunk offset = 1245184
lms-lightweight-music-server | [2023-Nov-13 08:03:10.969] 1 - [debug] - 140618070752056 [API_SUBSONIC] Request 63 '/stream' handled!
lms-lightweight-music-server | [2023-Nov-13 08:03:10.969] 1 - [debug] - 140618070752056 [API_SUBSONIC] Handling request 64 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-234}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:03:10.969] 1 - [debug] - 140618070752056 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:10.970] 1 - [debug] - 140618070752056 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:10.982] 1 - [debug] - 140618070752056 [UTILS] Written 65536 bytes
lms-lightweight-music-server | [2023-Nov-13 08:03:10.982] 1 - [debug] - 140618070752056 [UTILS] Progress: 65536/16984859
lms-lightweight-music-server | [2023-Nov-13 08:03:10.983] 1 - [debug] - 140618070752056 [UTILS] Job not complete! Next chunk offset = 1310720
lms-lightweight-music-server | [2023-Nov-13 08:03:10.983] 1 - [debug] - 140618070752056 [API_SUBSONIC] Request 64 '/stream' handled!
lms-lightweight-music-server | [2023-Nov-13 08:03:10.983] 1 - [debug] - 140618070752056 [API_SUBSONIC] Handling request 65 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-234}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:03:10.983] 1 - [debug] - 140618070752056 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:10.983] 1 - [debug] - 140618070752056 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:10.997] 1 - [debug] - 140618070752056 [UTILS] Written 65536 bytes
lms-lightweight-music-server | [2023-Nov-13 08:03:10.997] 1 - [debug] - 140618070752056 [UTILS] Progress: 65536/16919323
lms-lightweight-music-server | [2023-Nov-13 08:03:10.997] 1 - [debug] - 140618070752056 [UTILS] Job not complete! Next chunk offset = 1376256
lms-lightweight-music-server | [2023-Nov-13 08:03:10.997] 1 - [debug] - 140618070752056 [API_SUBSONIC] Request 65 '/stream' handled!
lms-lightweight-music-server | [2023-Nov-13 08:03:10.998] 1 - [debug] - 140618070752056 [API_SUBSONIC] Handling request 66 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-234}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:03:10.998] 1 - [debug] - 140618070752056 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:10.998] 1 - [debug] - 140618070752056 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:11.011] 1 - [debug] - 140618070752056 [UTILS] Written 65536 bytes
lms-lightweight-music-server | [2023-Nov-13 08:03:11.011] 1 - [debug] - 140618070752056 [UTILS] Progress: 65536/16853787
lms-lightweight-music-server | [2023-Nov-13 08:03:11.011] 1 - [debug] - 140618070752056 [UTILS] Job not complete! Next chunk offset = 1441792
lms-lightweight-music-server | [2023-Nov-13 08:03:11.011] 1 - [debug] - 140618070752056 [API_SUBSONIC] Request 66 '/stream' handled!
lms-lightweight-music-server | [2023-Nov-13 08:03:11.011] 1 - [debug] - 140618070752056 [API_SUBSONIC] Handling request 67 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-234}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:03:11.012] 1 - [debug] - 140618070752056 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:11.012] 1 - [debug] - 140618070752056 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:11.024] 1 - [debug] - 140618070752056 [UTILS] Written 65536 bytes
lms-lightweight-music-server | [2023-Nov-13 08:03:11.024] 1 - [debug] - 140618070752056 [UTILS] Progress: 65536/16788251
lms-lightweight-music-server | [2023-Nov-13 08:03:11.025] 1 - [debug] - 140618070752056 [UTILS] Job not complete! Next chunk offset = 1507328
lms-lightweight-music-server | [2023-Nov-13 08:03:11.025] 1 - [debug] - 140618070752056 [API_SUBSONIC] Request 67 '/stream' handled!
lms-lightweight-music-server | [2023-Nov-13 08:03:11.025] 1 - [debug] - 140618070752056 [API_SUBSONIC] Handling request 68 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-234}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:03:11.025] 1 - [debug] - 140618070752056 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:11.025] 1 - [debug] - 140618070752056 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:11.038] 1 - [debug] - 140618070752056 [UTILS] Written 65536 bytes
lms-lightweight-music-server | [2023-Nov-13 08:03:11.038] 1 - [debug] - 140618070752056 [UTILS] Progress: 65536/16722715
lms-lightweight-music-server | [2023-Nov-13 08:03:11.038] 1 - [debug] - 140618070752056 [UTILS] Job not complete! Next chunk offset = 1572864
lms-lightweight-music-server | [2023-Nov-13 08:03:11.038] 1 - [debug] - 140618070752056 [API_SUBSONIC] Request 68 '/stream' handled!
lms-lightweight-music-server | [2023-Nov-13 08:03:11.039] 1 - [debug] - 140618070752056 [API_SUBSONIC] Handling request 69 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-234}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:03:11.039] 1 - [debug] - 140618070752056 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:11.039] 1 - [debug] - 140618070752056 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:11.052] 1 - [debug] - 140618070752056 [UTILS] Written 65536 bytes
lms-lightweight-music-server | [2023-Nov-13 08:03:11.052] 1 - [debug] - 140618070752056 [UTILS] Progress: 65536/16657179
lms-lightweight-music-server | [2023-Nov-13 08:03:11.052] 1 - [debug] - 140618070752056 [UTILS] Job not complete! Next chunk offset = 1638400
lms-lightweight-music-server | [2023-Nov-13 08:03:11.052] 1 - [debug] - 140618070752056 [API_SUBSONIC] Request 69 '/stream' handled!
lms-lightweight-music-server | [2023-Nov-13 08:03:11.052] 1 - [debug] - 140618070752056 [API_SUBSONIC] Handling request 70 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-234}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 
lms-lightweight-music-server | [2023-Nov-13 08:03:11.052] 1 - [debug] - 140618070752056 [AUTH] Checking password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:11.052] 1 - [debug] - 140618070752056 [AUTH] Checking internal password for user 'giovanni'
lms-lightweight-music-server | [2023-Nov-13 08:03:11.065] 1 - [debug] - 140618070752056 [UTILS] Written 65536 bytes
lms-lightweight-music-server | [2023-Nov-13 08:03:11.065] 1 - [debug] - 140618070752056 [UTILS] Progress: 65536/16591643
lms-lightweight-music-server | [2023-Nov-13 08:03:11.065] 1 - [debug] - 140618070752056 [UTILS] Job not complete! Next chunk offset = 1703936
lms-lightweight-music-server | [2023-Nov-13 08:03:11.065] 1 - [debug] - 140618070752056 [API_SUBSONIC] Request 70 '/stream' handled!
lms-lightweight-music-server | [2023-Nov-13 08:03:11.066] 1 - [debug] - 140618070752056 [API_SUBSONIC] Handling request 71 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-234}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1}, 

It seems to me there is no transcoding.
Does this help? Maybe I can filter out some unnecessary information?

@epoupon
Copy link
Owner

epoupon commented Nov 13, 2023

Thanks for the logs, would you mind testing a new version (if yes, will generate you a docker image)?
Code already changed to handle #351 and it will be easier to make sure this is already fixed (or to debug from the new code).

@GioF71
Copy link
Author

GioF71 commented Nov 13, 2023

Sure, of course, will be waiting

epoupon added a commit that referenced this issue Nov 13, 2023
…lt (breaking change: default is disabled). fixes #351, ref #367
@epoupon
Copy link
Owner

epoupon commented Nov 13, 2023

You can test 28a80b3
Note that:

  • you need to rescan your library in order for the bitrate info to be properly scanned and used in the stream endpoint.
  • db schema has been updated

@GioF71
Copy link
Author

GioF71 commented Nov 13, 2023

Ok, thank you, the library is scanning, I will report back asap

@GioF71
Copy link
Author

GioF71 commented Nov 13, 2023

Hello, there are very good improvements... I see mimetype is available now, along with bitrate.
From the browser, playback starts (no download window) and I can seek.
From mpd, I cannot seek unfortunately.
In both case it seems to me there is no transcoding.

Logs when controlling playback with upnp client, playing to upmpdcli/mpd (seek not functional):

[2023-Nov-13 14:42:03.781] 1 - [debug] - 140361820384056 [API_SUBSONIC] Handling request 1024 '/getSong.view', continuation = false, params = {c=upmpdcli}, {f=json}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:42:03.781] 1 - [debug] - 140361820384056 [AUTH] Checking password for user 'giovanni'
[2023-Nov-13 14:42:03.781] 1 - [debug] - 140361820384056 [AUTH] Checking internal password for user 'giovanni'
192.168.1.173 - - [2023-Nov-13 14:42:03.803] "POST /rest/getSong.view HTTP/1.1" 200 1070
[2023-Nov-13 14:42:03.802] 1 - [debug] - 140361820384056 [API_SUBSONIC] Request 1024 '/getSong' handled!
[2023-Nov-13 14:42:03.814] 1 - [debug] - 140361820142392 [API_SUBSONIC] Handling request 1025 '/stream', continuation = false, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:42:03.814] 1 - [debug] - 140361820142392 [AUTH] Checking password for user 'giovanni'
[2023-Nov-13 14:42:03.814] 1 - [debug] - 140361820142392 [AUTH] Checking internal password for user 'giovanni'
[2023-Nov-13 14:42:03.832] 1 - [debug] - 140361820142392 [API_SUBSONIC] File's bitrate is compatible with parameters => no transcoding
[2023-Nov-13 14:42:03.832] 1 - [debug] - 140361820142392 [UTILS] File '/music/Music03/2023.Library.Add/2023-11/2023-11-03/Qobuz Downloads/The Orb - Metallic Spheres In Colour (2023) [24B-44.1kHz]/01. Round Side Seamless Solar Spheres of Affection Mix Movement 1.flac', fileSize = 124382797
[2023-Nov-13 14:42:03.832] 1 - [debug] - 140361820142392 [UTILS] No range requested
[2023-Nov-13 14:42:03.832] 1 - [debug] - 140361820142392 [UTILS] Mimetype set to 'audio/flac'
[2023-Nov-13 14:42:03.833] 1 - [debug] - 140361820142392 [UTILS] Written 262144 bytes
[2023-Nov-13 14:42:03.833] 1 - [debug] - 140361820142392 [UTILS] Progress: 262144/124382797
[2023-Nov-13 14:42:03.833] 1 - [debug] - 140361820142392 [UTILS] Job not complete! Next chunk offset = 262144
[2023-Nov-13 14:42:03.834] 1 - [debug] - 140361820142392 [API_SUBSONIC] Request 1025 '/stream' handled!
[2023-Nov-13 14:42:03.835] 1 - [debug] - 140361819839288 [API_SUBSONIC] Handling request 1026 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:42:03.836] 1 - [debug] - 140361819839288 [UTILS] Written 262144 bytes
[2023-Nov-13 14:42:03.836] 1 - [debug] - 140361819839288 [UTILS] Progress: 262144/124120653
[2023-Nov-13 14:42:03.836] 1 - [debug] - 140361819839288 [UTILS] Job not complete! Next chunk offset = 524288
[2023-Nov-13 14:42:03.836] 1 - [debug] - 140361819839288 [API_SUBSONIC] Request 1026 '/stream' handled!
[2023-Nov-13 14:42:03.839] 1 - [debug] - 140361820384056 [API_SUBSONIC] Handling request 1027 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:42:03.840] 1 - [debug] - 140361820384056 [UTILS] Written 262144 bytes
[2023-Nov-13 14:42:03.840] 1 - [debug] - 140361820384056 [UTILS] Progress: 262144/123858509
[2023-Nov-13 14:42:03.840] 1 - [debug] - 140361820384056 [UTILS] Job not complete! Next chunk offset = 786432
[2023-Nov-13 14:42:03.840] 1 - [debug] - 140361820384056 [API_SUBSONIC] Request 1027 '/stream' handled!
[2023-Nov-13 14:42:03.842] 1 - [debug] - 140361820142392 [API_SUBSONIC] Handling request 1028 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:42:03.843] 1 - [debug] - 140361820142392 [UTILS] Written 262144 bytes
[2023-Nov-13 14:42:03.843] 1 - [debug] - 140361820142392 [UTILS] Progress: 262144/123596365
[2023-Nov-13 14:42:03.844] 1 - [debug] - 140361820142392 [UTILS] Job not complete! Next chunk offset = 1048576
[2023-Nov-13 14:42:03.844] 1 - [debug] - 140361820142392 [API_SUBSONIC] Request 1028 '/stream' handled!
[2023-Nov-13 14:42:03.844] 1 - [debug] - 140361820142392 [API_SUBSONIC] Handling request 1029 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:42:03.846] 1 - [debug] - 140361820142392 [UTILS] Written 262144 bytes
[2023-Nov-13 14:42:03.846] 1 - [debug] - 140361820142392 [UTILS] Progress: 262144/123334221
[2023-Nov-13 14:42:03.846] 1 - [debug] - 140361820142392 [UTILS] Job not complete! Next chunk offset = 1310720
[2023-Nov-13 14:42:03.846] 1 - [debug] - 140361820142392 [API_SUBSONIC] Request 1029 '/stream' handled!
[2023-Nov-13 14:42:03.859] 1 - [debug] - 140361819839288 [API_SUBSONIC] Handling request 1030 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:42:03.860] 1 - [debug] - 140361819839288 [UTILS] Written 262144 bytes
[2023-Nov-13 14:42:03.860] 1 - [debug] - 140361819839288 [UTILS] Progress: 262144/123072077
[2023-Nov-13 14:42:03.860] 1 - [debug] - 140361819839288 [UTILS] Job not complete! Next chunk offset = 1572864
[2023-Nov-13 14:42:03.861] 1 - [debug] - 140361819839288 [API_SUBSONIC] Request 1030 '/stream' handled!
[2023-Nov-13 14:42:03.887] 1 - [debug] - 140361820384056 [API_SUBSONIC] Handling request 1031 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:42:03.888] 1 - [debug] - 140361820384056 [UTILS] Written 262144 bytes
[2023-Nov-13 14:42:03.888] 1 - [debug] - 140361820384056 [UTILS] Progress: 262144/122809933
[2023-Nov-13 14:42:03.888] 1 - [debug] - 140361820384056 [UTILS] Job not complete! Next chunk offset = 1835008
[2023-Nov-13 14:42:03.888] 1 - [debug] - 140361820384056 [API_SUBSONIC] Request 1031 '/stream' handled!
[2023-Nov-13 14:42:03.889] 1 - [debug] - 140361820384056 [API_SUBSONIC] Handling request 1032 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:42:03.890] 1 - [debug] - 140361820384056 [UTILS] Written 262144 bytes
[2023-Nov-13 14:42:03.890] 1 - [debug] - 140361820384056 [UTILS] Progress: 262144/122547789
[2023-Nov-13 14:42:03.890] 1 - [debug] - 140361820384056 [UTILS] Job not complete! Next chunk offset = 2097152
[2023-Nov-13 14:42:03.891] 1 - [debug] - 140361820384056 [API_SUBSONIC] Request 1032 '/stream' handled!
[2023-Nov-13 14:42:03.910] 1 - [debug] - 140361820142392 [API_SUBSONIC] Handling request 1033 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:42:03.912] 1 - [debug] - 140361820142392 [UTILS] Written 262144 bytes
[2023-Nov-13 14:42:03.912] 1 - [debug] - 140361820142392 [UTILS] Progress: 262144/122285645
[2023-Nov-13 14:42:03.912] 1 - [debug] - 140361820142392 [UTILS] Job not complete! Next chunk offset = 2359296
[2023-Nov-13 14:42:03.912] 1 - [debug] - 140361820142392 [API_SUBSONIC] Request 1033 '/stream' handled!
[2023-Nov-13 14:42:03.924] 1 - [debug] - 140361819990840 [API_SUBSONIC] Handling request 1034 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:42:03.926] 1 - [debug] - 140361819990840 [UTILS] Written 262144 bytes
[2023-Nov-13 14:42:03.926] 1 - [debug] - 140361819990840 [UTILS] Progress: 262144/122023501
[2023-Nov-13 14:42:03.926] 1 - [debug] - 140361819990840 [UTILS] Job not complete! Next chunk offset = 2621440
[2023-Nov-13 14:42:03.926] 1 - [debug] - 140361819990840 [API_SUBSONIC] Request 1034 '/stream' handled!
[2023-Nov-13 14:42:03.926] 1 - [debug] - 140361819990840 [API_SUBSONIC] Handling request 1035 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:42:03.928] 1 - [debug] - 140361819990840 [UTILS] Written 262144 bytes
[2023-Nov-13 14:42:03.928] 1 - [debug] - 140361819990840 [UTILS] Progress: 262144/121761357
[2023-Nov-13 14:42:03.928] 1 - [debug] - 140361819990840 [UTILS] Job not complete! Next chunk offset = 2883584
[2023-Nov-13 14:42:03.928] 1 - [debug] - 140361819990840 [API_SUBSONIC] Request 1035 '/stream' handled!
[2023-Nov-13 14:42:03.932] 1 - [debug] - 140361820384056 [API_SUBSONIC] Handling request 1036 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:42:03.934] 1 - [debug] - 140361820384056 [UTILS] Written 262144 bytes
[2023-Nov-13 14:42:03.934] 1 - [debug] - 140361820384056 [UTILS] Progress: 262144/121499213
[2023-Nov-13 14:42:03.934] 1 - [debug] - 140361820384056 [UTILS] Job not complete! Next chunk offset = 3145728
[2023-Nov-13 14:42:03.934] 1 - [debug] - 140361820384056 [API_SUBSONIC] Request 1036 '/stream' handled!
[2023-Nov-13 14:42:03.934] 1 - [debug] - 140361820384056 [API_SUBSONIC] Handling request 1037 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:42:03.936] 1 - [debug] - 140361820384056 [UTILS] Written 262144 bytes
[2023-Nov-13 14:42:03.936] 1 - [debug] - 140361820384056 [UTILS] Progress: 262144/121237069
[2023-Nov-13 14:42:03.936] 1 - [debug] - 140361820384056 [UTILS] Job not complete! Next chunk offset = 3407872
[2023-Nov-13 14:42:03.936] 1 - [debug] - 140361820384056 [API_SUBSONIC] Request 1037 '/stream' handled!
[2023-Nov-13 14:42:03.945] 1 - [debug] - 140361819839288 [API_SUBSONIC] Handling request 1038 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:42:03.946] 1 - [debug] - 140361819839288 [UTILS] Written 262144 bytes
[2023-Nov-13 14:42:03.946] 1 - [debug] - 140361819839288 [UTILS] Progress: 262144/120974925
[2023-Nov-13 14:42:03.946] 1 - [debug] - 140361819839288 [UTILS] Job not complete! Next chunk offset = 3670016
[2023-Nov-13 14:42:03.946] 1 - [debug] - 140361819839288 [API_SUBSONIC] Request 1038 '/stream' handled!
[2023-Nov-13 14:42:03.947] 1 - [debug] - 140361819839288 [API_SUBSONIC] Handling request 1039 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:42:03.948] 1 - [debug] - 140361819839288 [UTILS] Written 262144 bytes
[2023-Nov-13 14:42:03.948] 1 - [debug] - 140361819839288 [UTILS] Progress: 262144/120712781
[2023-Nov-13 14:42:03.948] 1 - [debug] - 140361819839288 [UTILS] Job not complete! Next chunk offset = 3932160
[2023-Nov-13 14:42:03.948] 1 - [debug] - 140361819839288 [API_SUBSONIC] Request 1039 '/stream' handled!
[2023-Nov-13 14:42:03.992] 1 - [debug] - 140361820142392 [API_SUBSONIC] Handling request 1040 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:42:03.993] 1 - [debug] - 140361820142392 [UTILS] Written 262144 bytes
[2023-Nov-13 14:42:03.993] 1 - [debug] - 140361820142392 [UTILS] Progress: 262144/120450637
[2023-Nov-13 14:42:03.994] 1 - [debug] - 140361820142392 [UTILS] Job not complete! Next chunk offset = 4194304
[2023-Nov-13 14:42:03.994] 1 - [debug] - 140361820142392 [API_SUBSONIC] Request 1040 '/stream' handled!
[2023-Nov-13 14:42:03.994] 1 - [debug] - 140361820142392 [API_SUBSONIC] Handling request 1041 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:42:03.995] 1 - [debug] - 140361820142392 [UTILS] Written 262144 bytes
[2023-Nov-13 14:42:03.995] 1 - [debug] - 140361820142392 [UTILS] Progress: 262144/120188493
[2023-Nov-13 14:42:03.996] 1 - [debug] - 140361820142392 [UTILS] Job not complete! Next chunk offset = 4456448
[2023-Nov-13 14:42:03.996] 1 - [debug] - 140361820142392 [API_SUBSONIC] Request 1041 '/stream' handled!
[2023-Nov-13 14:42:04.728] 1 - [debug] - 140361819990840 [API_SUBSONIC] Handling request 1042 '/getSong.view', continuation = false, params = {c=mpd-subsonic-scrobbler}, {f=json}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:42:04.728] 1 - [debug] - 140361819990840 [AUTH] Checking password for user 'giovanni'
[2023-Nov-13 14:42:04.728] 1 - [debug] - 140361819990840 [AUTH] Checking internal password for user 'giovanni'
[2023-Nov-13 14:42:04.751] 1 - [debug] - 140361819990840 [API_SUBSONIC] Request 1042 '/getSong' handled!
192.168.1.44 - - [2023-Nov-13 14:42:04.751] "POST /rest/getSong.view HTTP/1.1" 200 1070
[2023-Nov-13 14:42:04.868] 1 - [debug] - 140361820142392 [API_SUBSONIC] Handling request 1043 '/getSong.view', continuation = false, params = {c=mpd-subsonic-scrobbler}, {f=json}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:42:04.868] 1 - [debug] - 140361820142392 [AUTH] Checking password for user 'giovanni'
[2023-Nov-13 14:42:04.868] 1 - [debug] - 140361820142392 [AUTH] Checking internal password for user 'giovanni'
192.168.1.44 - - [2023-Nov-13 14:42:04.888] "POST /rest/getSong.view HTTP/1.1" 200 1070
[2023-Nov-13 14:42:04.888] 1 - [debug] - 140361820142392 [API_SUBSONIC] Request 1043 '/getSong' handled!
[2023-Nov-13 14:42:05.718] 1 - [debug] - 140361819990840 [API_SUBSONIC] Handling request 1044 '/getSong.view', continuation = false, params = {c=mpd-subsonic-scrobbler}, {f=json}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:42:05.718] 1 - [debug] - 140361819990840 [AUTH] Checking password for user 'giovanni'
[2023-Nov-13 14:42:05.718] 1 - [debug] - 140361819990840 [AUTH] Checking internal password for user 'giovanni'
[2023-Nov-13 14:42:05.739] 1 - [debug] - 140361819990840 [API_SUBSONIC] Request 1044 '/getSong' handled!
192.168.1.44 - - [2023-Nov-13 14:42:05.739] "POST /rest/getSong.view HTTP/1.1" 200 1070

Logs when opening the url from the browser (chrome):

[2023-Nov-13 14:48:21.924] 1 - [debug] - 140361820384056 [API_SUBSONIC] Handling request 1344 '/stream', continuation = false, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:48:21.924] 1 - [debug] - 140361820384056 [AUTH] Checking password for user 'giovanni'
[2023-Nov-13 14:48:21.924] 1 - [debug] - 140361820384056 [AUTH] Checking internal password for user 'giovanni'
[2023-Nov-13 14:48:21.943] 1 - [debug] - 140361820384056 [API_SUBSONIC] File's bitrate is compatible with parameters => no transcoding
[2023-Nov-13 14:48:21.943] 1 - [debug] - 140361820384056 [UTILS] File '/music/Music03/2023.Library.Add/2023-11/2023-11-03/Qobuz Downloads/The Orb - Metallic Spheres In Colour (2023) [24B-44.1kHz]/01. Round Side Seamless Solar Spheres of Affection Mix Movement 1.flac', fileSize = 124382797
[2023-Nov-13 14:48:21.943] 1 - [debug] - 140361820384056 [UTILS] No range requested
[2023-Nov-13 14:48:21.943] 1 - [debug] - 140361820384056 [UTILS] Mimetype set to 'audio/flac'
[2023-Nov-13 14:48:21.945] 1 - [debug] - 140361820384056 [UTILS] Written 262144 bytes
[2023-Nov-13 14:48:21.945] 1 - [debug] - 140361820384056 [UTILS] Progress: 262144/124382797
[2023-Nov-13 14:48:21.945] 1 - [debug] - 140361820384056 [UTILS] Job not complete! Next chunk offset = 262144
[2023-Nov-13 14:48:21.945] 1 - [debug] - 140361820384056 [API_SUBSONIC] Request 1344 '/stream' handled!
[2023-Nov-13 14:48:21.959] 1 - [debug] - 140361819839288 [API_SUBSONIC] Handling request 1345 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:48:21.960] 1 - [debug] - 140361819839288 [UTILS] Written 262144 bytes
[2023-Nov-13 14:48:21.960] 1 - [debug] - 140361819839288 [UTILS] Progress: 262144/124120653
[2023-Nov-13 14:48:21.960] 1 - [debug] - 140361819839288 [UTILS] Job not complete! Next chunk offset = 524288
[2023-Nov-13 14:48:21.960] 1 - [debug] - 140361819839288 [API_SUBSONIC] Request 1345 '/stream' handled!
[2023-Nov-13 14:48:21.976] 1 - [debug] - 140361820384056 [API_SUBSONIC] Handling request 1346 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:48:21.977] 1 - [debug] - 140361820384056 [UTILS] Written 262144 bytes
[2023-Nov-13 14:48:21.977] 1 - [debug] - 140361820384056 [UTILS] Progress: 262144/123858509
[2023-Nov-13 14:48:21.977] 1 - [debug] - 140361820384056 [UTILS] Job not complete! Next chunk offset = 786432
[2023-Nov-13 14:48:21.978] 1 - [debug] - 140361820384056 [API_SUBSONIC] Request 1346 '/stream' handled!
[2023-Nov-13 14:48:21.991] 1 - [debug] - 140361820142392 [API_SUBSONIC] Handling request 1347 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:48:21.992] 1 - [debug] - 140361820142392 [UTILS] Written 262144 bytes
[2023-Nov-13 14:48:21.992] 1 - [debug] - 140361820142392 [UTILS] Progress: 262144/123596365
[2023-Nov-13 14:48:21.992] 1 - [debug] - 140361820142392 [UTILS] Job not complete! Next chunk offset = 1048576
[2023-Nov-13 14:48:21.992] 1 - [debug] - 140361820142392 [API_SUBSONIC] Request 1347 '/stream' handled!
[2023-Nov-13 14:48:21.998] 1 - [error] "Http::ResponseContinuation: WebWriteEvent::Error"
[2023-Nov-13 14:48:22.024] 1 - [debug] - 140361820142392 [API_SUBSONIC] Handling request 1348 '/stream', continuation = false, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:48:22.025] 1 - [debug] - 140361820142392 [AUTH] Checking password for user 'giovanni'
[2023-Nov-13 14:48:22.025] 1 - [debug] - 140361820142392 [AUTH] Checking internal password for user 'giovanni'
[2023-Nov-13 14:48:22.043] 1 - [debug] - 140361820142392 [API_SUBSONIC] File's bitrate is compatible with parameters => no transcoding
[2023-Nov-13 14:48:22.043] 1 - [debug] - 140361820142392 [UTILS] File '/music/Music03/2023.Library.Add/2023-11/2023-11-03/Qobuz Downloads/The Orb - Metallic Spheres In Colour (2023) [24B-44.1kHz]/01. Round Side Seamless Solar Spheres of Affection Mix Movement 1.flac', fileSize = 124382797
[2023-Nov-13 14:48:22.043] 1 - [debug] - 140361820142392 [UTILS] Range requested = 0/124382796
[2023-Nov-13 14:48:22.043] 1 - [debug] - 140361820142392 [UTILS] Mimetype set to 'audio/flac'
[2023-Nov-13 14:48:22.045] 1 - [debug] - 140361820142392 [UTILS] Written 262144 bytes
[2023-Nov-13 14:48:22.045] 1 - [debug] - 140361820142392 [UTILS] Progress: 262144/124382797
[2023-Nov-13 14:48:22.045] 1 - [debug] - 140361820142392 [UTILS] Job not complete! Next chunk offset = 262144
[2023-Nov-13 14:48:22.045] 1 - [debug] - 140361820142392 [API_SUBSONIC] Request 1348 '/stream' handled!
[2023-Nov-13 14:48:22.078] 1 - [debug] - 140361819839288 [API_SUBSONIC] Handling request 1349 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:48:22.079] 1 - [debug] - 140361819839288 [UTILS] Written 262144 bytes
[2023-Nov-13 14:48:22.080] 1 - [debug] - 140361819839288 [UTILS] Progress: 262144/124120653
[2023-Nov-13 14:48:22.080] 1 - [debug] - 140361819839288 [UTILS] Job not complete! Next chunk offset = 524288
[2023-Nov-13 14:48:22.080] 1 - [debug] - 140361819839288 [API_SUBSONIC] Request 1349 '/stream' handled!
[2023-Nov-13 14:48:22.100] 1 - [debug] - 140361820142392 [API_SUBSONIC] Handling request 1350 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:48:22.101] 1 - [debug] - 140361820142392 [UTILS] Written 262144 bytes
[2023-Nov-13 14:48:22.102] 1 - [debug] - 140361820142392 [UTILS] Progress: 262144/123858509
[2023-Nov-13 14:48:22.102] 1 - [debug] - 140361820142392 [UTILS] Job not complete! Next chunk offset = 786432
[2023-Nov-13 14:48:22.102] 1 - [debug] - 140361820142392 [API_SUBSONIC] Request 1350 '/stream' handled!
[2023-Nov-13 14:48:22.130] 1 - [debug] - 140361820384056 [API_SUBSONIC] Handling request 1351 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:48:22.132] 1 - [debug] - 140361820384056 [UTILS] Written 262144 bytes
[2023-Nov-13 14:48:22.132] 1 - [debug] - 140361820384056 [UTILS] Progress: 262144/123596365
[2023-Nov-13 14:48:22.132] 1 - [debug] - 140361820384056 [UTILS] Job not complete! Next chunk offset = 1048576
[2023-Nov-13 14:48:22.132] 1 - [debug] - 140361820384056 [API_SUBSONIC] Request 1351 '/stream' handled!
[2023-Nov-13 14:48:22.151] 1 - [debug] - 140361819839288 [API_SUBSONIC] Handling request 1352 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:48:22.152] 1 - [debug] - 140361819839288 [UTILS] Written 262144 bytes
[2023-Nov-13 14:48:22.152] 1 - [debug] - 140361819839288 [UTILS] Progress: 262144/123334221
[2023-Nov-13 14:48:22.152] 1 - [debug] - 140361819839288 [UTILS] Job not complete! Next chunk offset = 1310720
[2023-Nov-13 14:48:22.153] 1 - [debug] - 140361819839288 [API_SUBSONIC] Request 1352 '/stream' handled!
[2023-Nov-13 14:48:22.174] 1 - [debug] - 140361819990840 [API_SUBSONIC] Handling request 1353 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:48:22.175] 1 - [debug] - 140361819990840 [UTILS] Written 262144 bytes
[2023-Nov-13 14:48:22.175] 1 - [debug] - 140361819990840 [UTILS] Progress: 262144/123072077
[2023-Nov-13 14:48:22.175] 1 - [debug] - 140361819990840 [UTILS] Job not complete! Next chunk offset = 1572864
[2023-Nov-13 14:48:22.175] 1 - [debug] - 140361819990840 [API_SUBSONIC] Request 1353 '/stream' handled!
[2023-Nov-13 14:48:22.193] 1 - [debug] - 140361819990840 [API_SUBSONIC] Handling request 1354 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:48:22.194] 1 - [debug] - 140361819990840 [UTILS] Written 262144 bytes
[2023-Nov-13 14:48:22.194] 1 - [debug] - 140361819990840 [UTILS] Progress: 262144/122809933
[2023-Nov-13 14:48:22.194] 1 - [debug] - 140361819990840 [UTILS] Job not complete! Next chunk offset = 1835008
[2023-Nov-13 14:48:22.194] 1 - [debug] - 140361819990840 [API_SUBSONIC] Request 1354 '/stream' handled!
[2023-Nov-13 14:48:22.218] 1 - [debug] - 140361820384056 [API_SUBSONIC] Handling request 1355 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:48:22.219] 1 - [debug] - 140361820384056 [UTILS] Written 262144 bytes
[2023-Nov-13 14:48:22.219] 1 - [debug] - 140361820384056 [UTILS] Progress: 262144/122547789
[2023-Nov-13 14:48:22.219] 1 - [debug] - 140361820384056 [UTILS] Job not complete! Next chunk offset = 2097152
[2023-Nov-13 14:48:22.220] 1 - [debug] - 140361820384056 [API_SUBSONIC] Request 1355 '/stream' handled!
[2023-Nov-13 14:48:22.234] 1 - [debug] - 140361820142392 [API_SUBSONIC] Handling request 1356 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:48:22.236] 1 - [debug] - 140361820142392 [UTILS] Written 262144 bytes
[2023-Nov-13 14:48:22.236] 1 - [debug] - 140361820142392 [UTILS] Progress: 262144/122285645
[2023-Nov-13 14:48:22.236] 1 - [debug] - 140361820142392 [UTILS] Job not complete! Next chunk offset = 2359296
[2023-Nov-13 14:48:22.236] 1 - [debug] - 140361820142392 [API_SUBSONIC] Request 1356 '/stream' handled!
[2023-Nov-13 14:48:22.250] 1 - [debug] - 140361819990840 [API_SUBSONIC] Handling request 1357 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:48:22.252] 1 - [debug] - 140361819990840 [UTILS] Written 262144 bytes
[2023-Nov-13 14:48:22.252] 1 - [debug] - 140361819990840 [UTILS] Progress: 262144/122023501
[2023-Nov-13 14:48:22.252] 1 - [debug] - 140361819990840 [UTILS] Job not complete! Next chunk offset = 2621440
[2023-Nov-13 14:48:22.252] 1 - [debug] - 140361819990840 [API_SUBSONIC] Request 1357 '/stream' handled!
[2023-Nov-13 14:48:22.281] 1 - [debug] - 140361819990840 [API_SUBSONIC] Handling request 1358 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:48:22.282] 1 - [debug] - 140361819990840 [UTILS] Written 262144 bytes
[2023-Nov-13 14:48:22.283] 1 - [debug] - 140361819990840 [UTILS] Progress: 262144/121761357
[2023-Nov-13 14:48:22.283] 1 - [debug] - 140361819990840 [UTILS] Job not complete! Next chunk offset = 2883584
[2023-Nov-13 14:48:22.283] 1 - [debug] - 140361819990840 [API_SUBSONIC] Request 1358 '/stream' handled!
[2023-Nov-13 14:48:22.302] 1 - [debug] - 140361820142392 [API_SUBSONIC] Handling request 1359 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:48:22.303] 1 - [debug] - 140361820142392 [UTILS] Written 262144 bytes
[2023-Nov-13 14:48:22.303] 1 - [debug] - 140361820142392 [UTILS] Progress: 262144/121499213
[2023-Nov-13 14:48:22.303] 1 - [debug] - 140361820142392 [UTILS] Job not complete! Next chunk offset = 3145728
[2023-Nov-13 14:48:22.303] 1 - [debug] - 140361820142392 [API_SUBSONIC] Request 1359 '/stream' handled!
[2023-Nov-13 14:48:22.317] 1 - [debug] - 140361820142392 [API_SUBSONIC] Handling request 1360 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:48:22.318] 1 - [debug] - 140361820142392 [UTILS] Written 262144 bytes
[2023-Nov-13 14:48:22.318] 1 - [debug] - 140361820142392 [UTILS] Progress: 262144/121237069
[2023-Nov-13 14:48:22.318] 1 - [debug] - 140361820142392 [UTILS] Job not complete! Next chunk offset = 3407872
[2023-Nov-13 14:48:22.318] 1 - [debug] - 140361820142392 [API_SUBSONIC] Request 1360 '/stream' handled!
[2023-Nov-13 14:48:22.337] 1 - [debug] - 140361819839288 [API_SUBSONIC] Handling request 1361 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:48:22.338] 1 - [debug] - 140361819839288 [UTILS] Written 262144 bytes
[2023-Nov-13 14:48:22.338] 1 - [debug] - 140361819839288 [UTILS] Progress: 262144/120974925
[2023-Nov-13 14:48:22.338] 1 - [debug] - 140361819839288 [UTILS] Job not complete! Next chunk offset = 3670016
[2023-Nov-13 14:48:22.338] 1 - [debug] - 140361819839288 [API_SUBSONIC] Request 1361 '/stream' handled!
[2023-Nov-13 14:48:22.358] 1 - [debug] - 140361819990840 [API_SUBSONIC] Handling request 1362 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:48:22.359] 1 - [debug] - 140361819990840 [UTILS] Written 262144 bytes
[2023-Nov-13 14:48:22.359] 1 - [debug] - 140361819990840 [UTILS] Progress: 262144/120712781
[2023-Nov-13 14:48:22.359] 1 - [debug] - 140361819990840 [UTILS] Job not complete! Next chunk offset = 3932160
[2023-Nov-13 14:48:22.360] 1 - [debug] - 140361819990840 [API_SUBSONIC] Request 1362 '/stream' handled!
[2023-Nov-13 14:48:22.379] 1 - [debug] - 140361820384056 [API_SUBSONIC] Handling request 1363 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:48:22.380] 1 - [debug] - 140361820384056 [UTILS] Written 262144 bytes
[2023-Nov-13 14:48:22.380] 1 - [debug] - 140361820384056 [UTILS] Progress: 262144/120450637
[2023-Nov-13 14:48:22.380] 1 - [debug] - 140361820384056 [UTILS] Job not complete! Next chunk offset = 4194304
[2023-Nov-13 14:48:22.380] 1 - [debug] - 140361820384056 [API_SUBSONIC] Request 1363 '/stream' handled!
[2023-Nov-13 14:48:22.404] 1 - [debug] - 140361819990840 [API_SUBSONIC] Handling request 1364 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:48:22.406] 1 - [debug] - 140361819990840 [UTILS] Written 262144 bytes
[2023-Nov-13 14:48:22.406] 1 - [debug] - 140361819990840 [UTILS] Progress: 262144/120188493
[2023-Nov-13 14:48:22.406] 1 - [debug] - 140361819990840 [UTILS] Job not complete! Next chunk offset = 4456448
[2023-Nov-13 14:48:22.406] 1 - [debug] - 140361819990840 [API_SUBSONIC] Request 1364 '/stream' handled!
[2023-Nov-13 14:48:22.406] 1 - [debug] - 140361819990840 [API_SUBSONIC] Handling request 1365 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:48:22.408] 1 - [debug] - 140361819990840 [UTILS] Written 262144 bytes
[2023-Nov-13 14:48:22.408] 1 - [debug] - 140361819990840 [UTILS] Progress: 262144/119926349
[2023-Nov-13 14:48:22.408] 1 - [debug] - 140361819990840 [UTILS] Job not complete! Next chunk offset = 4718592
[2023-Nov-13 14:48:22.408] 1 - [debug] - 140361819990840 [API_SUBSONIC] Request 1365 '/stream' handled!
[2023-Nov-13 14:48:22.430] 1 - [debug] - 140361819839288 [API_SUBSONIC] Handling request 1366 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:48:22.431] 1 - [debug] - 140361819839288 [UTILS] Written 262144 bytes
[2023-Nov-13 14:48:22.431] 1 - [debug] - 140361819839288 [UTILS] Progress: 262144/119664205
[2023-Nov-13 14:48:22.431] 1 - [debug] - 140361819839288 [UTILS] Job not complete! Next chunk offset = 4980736
[2023-Nov-13 14:48:22.431] 1 - [debug] - 140361819839288 [API_SUBSONIC] Request 1366 '/stream' handled!
[2023-Nov-13 14:48:22.456] 1 - [debug] - 140361820384056 [API_SUBSONIC] Handling request 1367 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:48:22.457] 1 - [debug] - 140361820384056 [UTILS] Written 262144 bytes
[2023-Nov-13 14:48:22.457] 1 - [debug] - 140361820384056 [UTILS] Progress: 262144/119402061
[2023-Nov-13 14:48:22.458] 1 - [debug] - 140361820384056 [UTILS] Job not complete! Next chunk offset = 5242880
[2023-Nov-13 14:48:22.458] 1 - [debug] - 140361820384056 [API_SUBSONIC] Request 1367 '/stream' handled!
[2023-Nov-13 14:48:22.481] 1 - [debug] - 140361819839288 [API_SUBSONIC] Handling request 1368 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:48:22.483] 1 - [debug] - 140361819839288 [UTILS] Written 262144 bytes
[2023-Nov-13 14:48:22.483] 1 - [debug] - 140361819839288 [UTILS] Progress: 262144/119139917
[2023-Nov-13 14:48:22.483] 1 - [debug] - 140361819839288 [UTILS] Job not complete! Next chunk offset = 5505024
[2023-Nov-13 14:48:22.483] 1 - [debug] - 140361819839288 [API_SUBSONIC] Request 1368 '/stream' handled!
[2023-Nov-13 14:48:22.512] 1 - [debug] - 140361820142392 [API_SUBSONIC] Handling request 1369 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:48:22.513] 1 - [debug] - 140361820142392 [UTILS] Written 262144 bytes
[2023-Nov-13 14:48:22.513] 1 - [debug] - 140361820142392 [UTILS] Progress: 262144/118877773
[2023-Nov-13 14:48:22.513] 1 - [debug] - 140361820142392 [UTILS] Job not complete! Next chunk offset = 5767168
[2023-Nov-13 14:48:22.513] 1 - [debug] - 140361820142392 [API_SUBSONIC] Request 1369 '/stream' handled!
[2023-Nov-13 14:48:22.514] 1 - [debug] - 140361820142392 [API_SUBSONIC] Handling request 1370 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:48:22.515] 1 - [debug] - 140361820142392 [UTILS] Written 262144 bytes
[2023-Nov-13 14:48:22.515] 1 - [debug] - 140361820142392 [UTILS] Progress: 262144/118615629
[2023-Nov-13 14:48:22.515] 1 - [debug] - 140361820142392 [UTILS] Job not complete! Next chunk offset = 6029312
[2023-Nov-13 14:48:22.515] 1 - [debug] - 140361820142392 [API_SUBSONIC] Request 1370 '/stream' handled!
[2023-Nov-13 14:48:22.544] 1 - [debug] - 140361819990840 [API_SUBSONIC] Handling request 1371 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:48:22.545] 1 - [debug] - 140361819990840 [UTILS] Written 262144 bytes
[2023-Nov-13 14:48:22.545] 1 - [debug] - 140361819990840 [UTILS] Progress: 262144/118353485
[2023-Nov-13 14:48:22.545] 1 - [debug] - 140361819990840 [UTILS] Job not complete! Next chunk offset = 6291456
[2023-Nov-13 14:48:22.546] 1 - [debug] - 140361819990840 [API_SUBSONIC] Request 1371 '/stream' handled!
[2023-Nov-13 14:48:22.574] 1 - [debug] - 140361820384056 [API_SUBSONIC] Handling request 1372 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:48:22.576] 1 - [debug] - 140361820384056 [UTILS] Written 262144 bytes
[2023-Nov-13 14:48:22.576] 1 - [debug] - 140361820384056 [UTILS] Progress: 262144/118091341
[2023-Nov-13 14:48:22.576] 1 - [debug] - 140361820384056 [UTILS] Job not complete! Next chunk offset = 6553600
[2023-Nov-13 14:48:22.576] 1 - [debug] - 140361820384056 [API_SUBSONIC] Request 1372 '/stream' handled!
[2023-Nov-13 14:48:22.576] 1 - [debug] - 140361820384056 [API_SUBSONIC] Handling request 1373 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:48:22.578] 1 - [debug] - 140361820384056 [UTILS] Written 262144 bytes
[2023-Nov-13 14:48:22.578] 1 - [debug] - 140361820384056 [UTILS] Progress: 262144/117829197
[2023-Nov-13 14:48:22.578] 1 - [debug] - 140361820384056 [UTILS] Job not complete! Next chunk offset = 6815744
[2023-Nov-13 14:48:22.578] 1 - [debug] - 140361820384056 [API_SUBSONIC] Request 1373 '/stream' handled!
[2023-Nov-13 14:48:22.610] 1 - [debug] - 140361820142392 [API_SUBSONIC] Handling request 1374 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:48:22.612] 1 - [debug] - 140361820142392 [UTILS] Written 262144 bytes
[2023-Nov-13 14:48:22.612] 1 - [debug] - 140361820142392 [UTILS] Progress: 262144/117567053
[2023-Nov-13 14:48:22.612] 1 - [debug] - 140361820142392 [UTILS] Job not complete! Next chunk offset = 7077888
[2023-Nov-13 14:48:22.612] 1 - [debug] - 140361820142392 [API_SUBSONIC] Request 1374 '/stream' handled!
[2023-Nov-13 14:48:22.613] 1 - [debug] - 140361820142392 [API_SUBSONIC] Handling request 1375 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:48:22.614] 1 - [debug] - 140361820142392 [UTILS] Written 262144 bytes
[2023-Nov-13 14:48:22.615] 1 - [debug] - 140361820142392 [UTILS] Progress: 262144/117304909
[2023-Nov-13 14:48:22.615] 1 - [debug] - 140361820142392 [UTILS] Job not complete! Next chunk offset = 7340032
[2023-Nov-13 14:48:22.615] 1 - [debug] - 140361820142392 [API_SUBSONIC] Request 1375 '/stream' handled!
[2023-Nov-13 14:48:22.654] 1 - [debug] - 140361820384056 [API_SUBSONIC] Handling request 1376 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:48:22.655] 1 - [debug] - 140361820384056 [UTILS] Written 262144 bytes
[2023-Nov-13 14:48:22.655] 1 - [debug] - 140361820384056 [UTILS] Progress: 262144/117042765
[2023-Nov-13 14:48:22.655] 1 - [debug] - 140361820384056 [UTILS] Job not complete! Next chunk offset = 7602176
[2023-Nov-13 14:48:22.656] 1 - [debug] - 140361820384056 [API_SUBSONIC] Request 1376 '/stream' handled!
[2023-Nov-13 14:48:22.656] 1 - [debug] - 140361820384056 [API_SUBSONIC] Handling request 1377 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:48:22.657] 1 - [debug] - 140361820384056 [UTILS] Written 262144 bytes
[2023-Nov-13 14:48:22.657] 1 - [debug] - 140361820384056 [UTILS] Progress: 262144/116780621
[2023-Nov-13 14:48:22.657] 1 - [debug] - 140361820384056 [UTILS] Job not complete! Next chunk offset = 7864320
[2023-Nov-13 14:48:22.658] 1 - [debug] - 140361820384056 [API_SUBSONIC] Request 1377 '/stream' handled!
[2023-Nov-13 14:48:22.687] 1 - [debug] - 140361819990840 [API_SUBSONIC] Handling request 1378 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:48:22.688] 1 - [debug] - 140361819990840 [UTILS] Written 262144 bytes
[2023-Nov-13 14:48:22.688] 1 - [debug] - 140361819990840 [UTILS] Progress: 262144/116518477
[2023-Nov-13 14:48:22.688] 1 - [debug] - 140361819990840 [UTILS] Job not complete! Next chunk offset = 8126464
[2023-Nov-13 14:48:22.688] 1 - [debug] - 140361819990840 [API_SUBSONIC] Request 1378 '/stream' handled!
[2023-Nov-13 14:48:22.689] 1 - [debug] - 140361819990840 [API_SUBSONIC] Handling request 1379 '/stream', continuation = true, params = {c=upmpdcli}, {format=flac}, {id=tr-105685}, {p=*REDACTED*}, {u=giovanni}, {v=1.16.1},
[2023-Nov-13 14:48:22.690] 1 - [debug] - 140361819990840 [UTILS] Written 262144 bytes
[2023-Nov-13 14:48:22.690] 1 - [debug] - 140361819990840 [UTILS] Progress: 262144/116256333
[2023-Nov-13 14:48:22.691] 1 - [debug] - 140361819990840 [UTILS] Job not complete! Next chunk offset = 8388608
[2023-Nov-13 14:48:22.691] 1 - [debug] - 140361819990840 [API_SUBSONIC] Request 1379 '/stream' handled!
192.168.16.1 - - [2023-Nov-13 14:48:40.718] "POST /?wtd=xr7H7tufP7Pu1ieJ HTTP/1.1" 200 0
[2023-Nov-13 14:48:40.805] 1 - [error] "Http::ResponseContinuation: WebWriteEvent::Error"
192.168.16.1 - - [2023-Nov-13 14:49:31.723] "POST /?wtd=xr7H7tufP7Pu1ieJ HTTP/1.1" 200 0

@epoupon
Copy link
Owner

epoupon commented Nov 13, 2023

Thanks for testing!
Not sure why mpd is not happy, something is likely missing indeed... Will investigate for v3.45.0.

@epoupon epoupon added the bug label Nov 13, 2023
@epoupon epoupon added this to the v3.45.0 milestone Nov 13, 2023
@epoupon
Copy link
Owner

epoupon commented Nov 13, 2023

(btw I just noticed you "format=flac" parameter, lms is not able to transcode to "flac", but only to "mp3", "opus" and "vorbis")

@GioF71
Copy link
Author

GioF71 commented Nov 13, 2023

uhm, ok, I kinda suspected there was something there.
But isn't it possible to support to stream to mpd leaving the audio file as-is?
Maybe I just simply have to drop the format parameter from the url?

@epoupon
Copy link
Owner

epoupon commented Nov 13, 2023

If you ask for format "raw", you are garanteed the file is served without transcoding. Otherwise, it depends on other parameters like format, maxBitrate, the codec of the file being read and the settings server side.

That being said, from the logs you pasted, the file is not transcoded and served as is. Maybe there is some header missing in this code path, we would need to compare output with navidrome to check what is missing/different.

@GioF71
Copy link
Author

GioF71 commented Nov 13, 2023

The headers are generated in the same way, it's the same code: the only difference is in the authentication method which is legacy with lms. So with navidrome there are "u" and "s", with lms there are "u" and "p", see the first post. Or are you referring to something else?

@epoupon
Copy link
Owner

epoupon commented Nov 13, 2023

Actually I was just saying that the parameter "format=flac" will not be honored by lms (and neither by navidrome I guess)
By the way are you sure the files are both 100% identical using lms and navidrome?

@GioF71
Copy link
Author

GioF71 commented Nov 13, 2023

Yes they were the same files!

@GioF71
Copy link
Author

GioF71 commented Nov 13, 2023

will not be honored by lms (and neither by navidrome I guess)

I probably added this query argument in the early stages of the development of the plugin, and probably never noticed it's not strictly required. Of course 'format=flac' is not hard-coded, it is taken from the suffix iirc. I will try and see if I can remove all of this, thank you

@GioF71
Copy link
Author

GioF71 commented Nov 13, 2023

Yes they were the same files!

I still have the mediainfo output for both url and I am quite positive it's the same file, if needed I can post those outputs

@GioF71
Copy link
Author

GioF71 commented Nov 13, 2023

Sorry to spam here, I see there is a new release, but I see that latest docker image is still from 9 days ago, are they maybe building? Thank you!

@epoupon
Copy link
Owner

epoupon commented Nov 13, 2023

Yes they are building, but it will be nearly the same as the snapshot you already have.
And if you download the files for each server and md5sum them, are they the same?

@GioF71
Copy link
Author

GioF71 commented Nov 13, 2023

I have done the test, they are identical:

➜ ~ mkdir test-file-md5
➜ ~ cd test-file-md5
➜ test-file-md5 wget http://gruesome04.homelab.local:5082/rest/stream\?id\=tr-105597\&format\=flac\&u\=giovanni\&p\=enc:48456E254357676E5264667A3569417432243439357A6345\&c\=upmpdcli\&v\=1.16.1
--2023-11-13 18:26:04-- http://gruesome04.homelab.local:5082/rest/stream?id=tr-105597&format=flac&u=giovanni&p=enc:48456E254357676E5264667A3569417432243439357A6345&c=upmpdcli&v=1.16.1
Resolving gruesome04.homelab.local (gruesome04.homelab.local)... 192.168.1.174
Connecting to gruesome04.homelab.local (gruesome04.homelab.local)|192.168.1.174|:5082... connected.
HTTP request sent, awaiting response... 200 OK
Length: 45608799 (43M) [audio/flac]
Saving to: ‘stream?id=tr-105597&format=flac&u=giovanni&p=enc:48456E254357676E5264667A3569417432243439357A6345&c=upmpdcli&v=1.16.1’

stream?id=tr-105597&format=fl 100%[=================================================>] 43.50M 99.1MB/s in 0.4s

2023-11-13 18:26:05 (99.1 MB/s) - ‘stream?id=tr-105597&format=flac&u=giovanni&p=enc:48456E254357676E5264667A3569417432243439357A6345&c=upmpdcli&v=1.16.1’ saved [45608799/45608799]

➜ test-file-md5 ls -la
total 44548
drwxr-xr-x 2 power power 4096 Nov 13 18:26 .
drwxr-xr-x 23 power power 4096 Nov 13 18:26 ..
-rw-r--r-- 1 power power 45608799 Nov 13 18:26 'stream?id=tr-105597&format=flac&u=giovanni&p=enc:48456E254357676E5264667A3569417432243439357A6345&c=upmpdcli&v=1.16.1'
➜ test-file-md5 mv stream?id=tr-105597&format=flac&u=giovanni&p=enc:48456E254357676E5264667A3569417432243439357A6345&c=upmpdcli&v=1.16.1 lms.flac
➜ test-file-md5 wget https://navidrome.mydomain.com:443/rest/stream\?id\=782d67568d643d6ca22a678f1186f910\&format\=flac\&u\=giovanni\&s\=7ad282d7def7\&t\=89eb0b011a12f5e7fb782520a9d12624\&c\=upmpdcli\&v\=1.16.1
--2023-11-13 18:27:09-- https://navidrome.mydomain.com/rest/stream?id=782d67568d643d6ca22a678f1186f910&format=flac&u=giovanni&s=7ad282d7def7&t=89eb0b011a12f5e7fb782520a9d12624&c=upmpdcli&v=1.16.1
Resolving navidrome.mydomain.com (navidrome.mydomain.com)... 192.168.1.102
Connecting to navidrome.mydomain.com (navidrome.mydomain.com)|192.168.1.102|:443... connected.
HTTP request sent, awaiting response... 200 OK
Length: 45608799 (43M) [audio/flac]
Saving to: ‘stream?id=782d67568d643d6ca22a678f1186f910&format=flac&u=giovanni&s=7ad282d7def7&t=89eb0b011a12f5e7fb782520a9d12624&c=upmpdcli&v=1.16.1’

stream?id=782d67568d643d6ca22 100%[=================================================>] 43.50M 43.6MB/s in 1.0s

2023-11-13 18:27:10 (43.6 MB/s) - ‘stream?id=782d67568d643d6ca22a678f1186f910&format=flac&u=giovanni&s=7ad282d7def7&t=89eb0b011a12f5e7fb782520a9d12624&c=upmpdcli&v=1.16.1’ saved [45608799/45608799]

➜ test-file-md5 ls -la
total 89092
drwxr-xr-x 2 power power 4096 Nov 13 18:27 .
drwxr-xr-x 23 power power 4096 Nov 13 18:27 ..
-rw-r--r-- 1 power power 45608799 Nov 13 18:26 lms.flac
-rw-r--r-- 1 power power 45608799 Nov 7 17:31 'stream?id=782d67568d643d6ca22a678f1186f910&format=flac&u=giovanni&s=7ad282d7def7&t=89eb0b011a12f5e7fb782520a9d12624&c=upmpdcli&v=1.16.1'
➜ test-file-md5 mv stream?id=782d67568d643d6ca22a678f1186f910&format=flac&u=giovanni&s=7ad282d7def7&t=89eb0b011a12f5e7fb782520a9d12624&c=upmpdcli&v=1.16.1 navidrome.flac
➜ *test-file-md5 md5sum flac
ab5e62518697e2bcbf060e8a70d096cc lms.flac
ab5e62518697e2bcbf060e8a70d096cc navidrome.flac

➜ test-file-md5

@GioF71
Copy link
Author

GioF71 commented Nov 13, 2023

I just checked the mpd output and it says a very succint:

exception: Not seekable

@GioF71
Copy link
Author

GioF71 commented Nov 13, 2023

I just made the necessary changes and confirm that using the query argument 'format' is not strictly needed (both lms and navidrome), thank you

@epoupon
Copy link
Owner

epoupon commented Nov 13, 2023

Ah, it looks like lms does not return "accept-ranges" and according to mpd source code it is needed to consider the stream to be seekable

@epoupon
Copy link
Owner

epoupon commented Nov 13, 2023

@GioF71 would you mind testing 83347e8 to confirm this is fixed?

@GioF71
Copy link
Author

GioF71 commented Nov 13, 2023

oh yes sir, now it works, seeks like a charm :-)

@epoupon
Copy link
Owner

epoupon commented Nov 13, 2023

Thanks again for reporting and testing!

epoupon added a commit that referenced this issue Nov 13, 2023
@epoupon epoupon closed this as completed Nov 13, 2023
@epoupon epoupon modified the milestones: v3.45.0, v3.44.1 Nov 14, 2023
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

2 participants