vbabiy
March 30, 2023, 2:09pm
1
Issue Description:
I am having issues when trying to offline music, I am using navidrome which is sitting behind caddy v2. It is reporting that the server is returning a 416 error.
I was asked in the ticket to post here with app logs.
Logs:
debug-20230329_112116.zip (69.6 KB)
Screenshots:
Additional information:
opened 11:52AM - 29 Mar 23 UTC
bug
triage
### I confirm that:
- [X] I have searched the existing [open AND closed issue… s](https://github.com/navidrome/navidrome/issues?q=is%3Aissue) to see if an issue already exists for the bug I've encountered
- [X] I'm using the latest version (your issue may have been fixed already)
### Version
v0.49.3
### Current Behavior
Server is throwing 416 errors on file downloads in Symfonium
### Expected Behavior
File to download
### Steps To Reproduce
_No response_
### Environment
```markdown
- OS: Ubuntu 20.04
- Client: Latest Symfonium
```
### How Navidrome is installed?
Binary (from downloads page)
### Configuration
```toml
MusicFolder = '/nas/Media/Music/Library'
TranscodingCacheSize = '150MiB'
LogLevel = 'DEBUG'
ScanSchedule = '@every 1h'
EnableSharing = true
[Scanner]
Extractor = 'ffmpeg'
[Prometheus]
Enabled = true
MetricsPath = '/metrics_vb_zone_9192010'
```
### Relevant log output
```shell
Mar 29 11:45:47 navidrome navidrome[298]: time="2023-03-29T11:45:47Z" level=warning msg="HTTP: GET http://<server>/rest/stream.view?id=037c13627017d1906675f9b877d8111c&u=vbabiy&t=[REDACTED]&s=[REDACTED]&v=1.13.0&c=Symfonium&f=json" elapsedTime=1ms httpStatus=416 remoteAddr=10.10.100.134 requestId=navidrome/OCuvAPT3s6-238134 responseSize=33 userAgent="Symfonium/4.3.0 (Linux;Android 13)"
Mar 29 11:50:20 navidrome navidrome[298]: time="2023-03-29T11:50:20Z" level=warning msg="HTTP: GET http://<server>/rest/stream.view?id=ee7f8bacccdfd2a6af2cc956414c3602&u=vbabiy&t=[REDACTED]&s=[REDACTED]&v=1.13.0&c=Symfonium&f=json" elapsedTime=2.1ms httpStatus=416 remoteAddr=10.10.100.134 requestId=navidrome/OCuvAPT3s6-238576 responseSize=33 userAgent="Symfonium/4.3.0 (Linux;Android 13)"
```
### Anything else?
I am get some music but others fail.
### Code of Conduct
- [X] I agree to follow Navidrome's Code of Conduct
Tolriq
March 30, 2023, 2:54pm
2
2023-03-29 11:21:03.254 Verbose/DownloaderService: Download url: https://xxx/rest/stream.view?id=ad09e6d52cd5647bb073d2c3acadc350 -> 83F9BD4CCA5B59BD5676155D1DDEF867
2023-03-29 11:21:03.254 Verbose/DownloaderService: Partial file exists: /storage/emulated/0/Android/data/app.symfonik.music.player/files/OfflineMedias/8/83F9BD4CCA5B59BD5676155D1DDEF867.flac.part
2023-03-29 11:21:03.255 Verbose/DownloaderService: Debug 83F9BD4CCA5B59BD5676155D1DDEF867 [0 ms] callStart: Request{method=GET, url=https://xxx/rest/stream.view?id=ad09e6d52cd5647bb073d2c3acadc350, headers=[Accept-Encoding:, connection:close, Range:bytes=12912783-]}
2023-03-29 11:21:03.256 Verbose/DownloaderService: --> [338] GET https://xxx/rest/stream.view?id=ad09e6d52cd5647bb073d2c3acadc350
2023-03-29 11:21:03.256 Verbose/DownloaderService: Debug 83F9BD4CCA5B59BD5676155D1DDEF867 [0 ms] connectionAcquired: Connection{xxx:443, proxy=DIRECT hostAddress=xxx/71.75.72.123:443 cipherSuite=TLS_AES_128_GCM_SHA256 protocol=h2}
2023-03-29 11:21:03.256 Verbose/DownloaderService: Debug 83F9BD4CCA5B59BD5676155D1DDEF867 [0 ms] requestHeadersStart
2023-03-29 11:21:03.256 Verbose/DownloaderService: Debug 83F9BD4CCA5B59BD5676155D1DDEF867 [1 ms] requestHeadersEnd
2023-03-29 11:21:03.266 Verbose/DownloaderService: Debug 83F9BD4CCA5B59BD5676155D1DDEF867 [11 ms] responseHeadersStart
2023-03-29 11:21:03.267 Verbose/DownloaderService: Debug 83F9BD4CCA5B59BD5676155D1DDEF867 [11 ms] responseHeadersEnd: Response{protocol=h2, code=416, message=, url=https://xxx/rest/stream.view?id=ad09e6d52cd5647bb073d2c3acadc350&u=REDACTED&t=REDACTED&s=REDACTED&v=1.13.0&c=Symfonium&f=json}
2023-03-29 11:21:03.267 Verbose/DownloaderService: <-- [338] 416 https://xxx/rest/stream.view?id=ad09e6d52cd5647bb073d2c3acadc350&u=REDACTED&t=REDACTED&s=REDACTED&v=1.13.0&c=Symfonium&f=json (10ms, 33-byte body)
2023-03-29 11:21:03.268 Verbose/DownloaderService: Debug 83F9BD4CCA5B59BD5676155D1DDEF867 [11 ms] responseBodyStart
2023-03-29 11:21:03.268 Verbose/DownloaderService: Debug 83F9BD4CCA5B59BD5676155D1DDEF867 [11 ms] responseBodyEnd: byteCount=0
2023-03-29 11:21:03.269 Verbose/DownloaderService: Debug 83F9BD4CCA5B59BD5676155D1DDEF867 [11 ms] connectionReleased
2023-03-29 11:21:03.269 Verbose/DownloaderService: Debug 83F9BD4CCA5B59BD5676155D1DDEF867 [11 ms] callEnd
So there’s download issues and when the app tries to resume the download the server refuse the range.
You need to check Navidrome and Caddy logs to see who fails here.
You can enable the advanced settings Slower downloads to workaround those issues.
Tolriq
March 31, 2023, 10:08am
3
Next release will try to better recover from error 416 to avoid being stuck in that state.