Something happened today, while watching a large media file.
At some random point, it started glitching, freezing for half a second, then resuming, then glitching again. When I exited Jellyfin, opened it again and resumed, the problem went away.
This is what the log shows:
2024/02/01 21:30:56 DEBUG : vfs cache: looking for range={Pos:46949662720 Size:131072} in [{Pos:43661799424 Size:3288330240}] - present true
2024/02/01 21:30:56 DEBUG : Movies/2000 - 2009/movie.mkv(0xc00aef6
380): _readAt: size=131072, off=46949793792
2024/02/01 21:30:56 DEBUG : Movies/2000 - 2009/movie.mkv(0xc00aef6
380): >_readAt: n=131072, err=<nil>
2024/02/01 21:30:56 DEBUG : vfs cache: looking for range={Pos:46949793792 Size:131072} in [{Pos:43661799424 Size:3288330240}] - present true
2024/02/01 21:30:56 DEBUG : &{Movies/2000 - 2009/movie.mkv (rw)}:
>Read: read=131072, err=<nil>
2024/02/01 21:30:56 DEBUG : Movies/2000 - 2009/movie.mkv(0xc00aef6
380): >_readAt: n=131072, err=<nil>
2024/02/01 21:30:56 DEBUG : &{Movies/2000 - 2009/movie.mkv (rw)}:
>Read: read=131072, err=<nil>
2024/02/01 21:30:56 DEBUG : &{Movies/2000 - 2009/movie.mkv (rw)}:
Read: len=131072, offset=46949924864
2024/02/01 21:30:56 DEBUG : Movies/2000 - 2009/movie.mkv(0xc00aef6
380): _readAt: size=131072, off=46949924864
2024/02/01 21:30:56 DEBUG : vfs cache: looking for range={Pos:46949924864 Size:131072} in [{Pos:43661799424 Size:3288330240}] - present true
2024/02/01 21:30:56 DEBUG : Movies/2000 - 2009/movie.mkv(0xc00aef6
380): >_readAt: n=131072, err=<nil>
2024/02/01 21:30:56 DEBUG : &{Movies/2000 - 2009/movie.mkv (rw)}: >Read: read=131072, err=<nil>
2024/02/01 21:30:56 DEBUG : &{Movies/2000 - 2009/movie.mkv (rw)}: Read: len=131072, offset=46950055936
2024/02/01 21:30:56 DEBUG : Movies/2000 - 2009/movie.mkv(0xc00aef6380): _readAt: size=131072, off=46950055936
2024/02/01 21:30:56 DEBUG : vfs cache: looking for range={Pos:46950055936 Size:131072} in [{Pos:43661799424 Size:3288330240}] - present false
2024/02/01 21:30:56 DEBUG : &{Movies/2000 - 2009/movie.mkv (rw)}: Read: len=131072, offset=46950187008
2024/02/01 21:30:56 DEBUG : Movies/2000 - 2009/movie.mkv(0xc00aef6380): _readAt: size=131072, off=46950187008
Suddenly, there is a 'present false'.
Does this mean that at that point, it is failing to find the next segment of the file in the vfs cache?
And if so, what could be the reason?
I don't see any of "increasing sleep|Reducing sleep|throttledRequest" anywhere in the entire log file.
EDIT: I see this 'present false' elsewhere in the log, when the file was playing fine, so probably not it.
EDIT2: At the final point of glitching where it was doing that so much it was unwatchable, I can see 3 'present false' in quick succession, maybe it is related after all?
However, I found something else:
2024/02/01 21:31:00 DEBUG : Movies/2000 - 2009/movie.mkv(0xc00aef6380): _r
eadAt: size=131072, off=46974435328
2024/02/01 21:31:00 DEBUG : vfs cache: looking for range={Pos:46974435328 Size:131072} in [{Pos:43661799424 Size:3312447488}] - present false
2024/02/01 21:31:00 INFO : OneDrive root 'dirname': Change notify listener failure: invalidRequest: [token.String] A value is required but was not present in the request.
2024/02/01 21:31:00 INFO : OneDrive root 'dirname': Change notify listener failure: invalidRequest: [token.String] A value is required but was not present in the request.
2024/02/01 21:31:01 DEBUG : Movies/2000 - 2009/movie.mkv: ChunkedReader.Re
ad at 46975295488 length 1048576 chunkOffset 45675065344 chunkSize 2147483648
2024/02/01 21:31:01 DEBUG : Movies/2000 - 2009/movie.mkv(0xc00aef6380): >_
readAt: n=131072, err=<nil>
All this was at the time the glitch was happening. I am really not sure what that log entry might mean.