[jellyfin_rpc] error decoding response body

[anriho]

Is there an existing issue for this?

• I have searched the existing issues

Have you ran the program with debug logging?

• I used --log-level=debug when running the program

What went wrong?

I set up the program and ran it, connects to discord immediately however continues to produce this error.

Terminal output

2024-08-18 06:51:50 INFO [jellyfin_rpc] Initializing Jellyfin-RPC
2024-08-18 06:51:51 INFO [jellyfin_rpc] Connecting to Discord
2024-08-18 06:51:51 INFO [jellyfin_rpc] Attempt 1: Trying to connect
2024-08-18 06:51:52 INFO [jellyfin_rpc] Connected!
2024-08-18 06:51:55 ERROR [jellyfin_rpc] error decoding response body
2024-08-18 06:51:55 INFO [jellyfin_rpc] Attempt 1: Trying to reconnect
2024-08-18 06:51:55 INFO [jellyfin_rpc] Reconnected!
2024-08-18 06:51:58 ERROR [jellyfin_rpc] error decoding response body
2024-08-18 06:51:58 INFO [jellyfin_rpc] Attempt 1: Trying to reconnect

Jellyfin-RPC version

0.14.0

What OS are you using?

Windows 10

What version is the Jellyfin server on?

10.8.13

Anything else?

No response

[Radiicall]

Can you try updating your jellyfin server? I am on 10.9.9

[anriho]

Updated to 10.9.9, redid the config just in case.. still the same error.

[akurosoren]

Same error with Jellyfin 10.9.9, Discord and jellyfin-rpc up to date

[Radiicall]

Is it when something is playing or does it just happen all the time?

[Radiicall]

Try going to https://jellyfin.example.org/Sessions?api_key=YOUR_API_KEY and see if you see anything, I have heard of people having trouble reaching that end point sometimes.. Replace jellyfin.example.org with your jellyfin URL

[akurosoren]

Is it when something is playing or does it just happen all the time?

All the time, It just can't connect
I'll try with the API link

[erictbar]

Same error here. Jellyfin 10.9.11 Tried that link, and it shows info with devices that have played Jellyfin recently, and, only sometimes, the currently playing song on refresh.

My server is on a base URL with Docker

[akurosoren]

Same error here. Jellyfin 10.9.11 Tried that link, and it shows info with devices that have played Jellyfin recently, and, only sometimes, the currently playing song on refresh.

My server is on a base URL with Docker

Yup, same for the server

[iiPythonx]

Same issue with Jellyfin 10.9.11, while the Sessions endpoint is working fine.

[mancube]

2024-09-30 16:30:00 ERROR [jellyfin_rpc] error decoding response body
2024-09-30 16:30:00 DEBUG [jellyfin_rpc] reqwest::Error { kind: Decode, source: Error("invalid type: floating point 7.5, expected i64", line: 1, column: 1834) }
2024-09-30 16:30:00 INFO [jellyfin_rpc] Attempt 1: Trying to reconnect

In my case, this is the response I get back, it happens only on some movies/series, not always

[Radiicall]

2024-09-30 16:30:00 ERROR [jellyfin_rpc] error decoding response body 2024-09-30 16:30:00 DEBUG [jellyfin_rpc] reqwest::Error { kind: Decode, source: Error("invalid type: floating point 7.5, expected i64", line: 1, column: 1834) } 2024-09-30 16:30:00 INFO [jellyfin_rpc] Attempt 1: Trying to reconnect

In my case, this is the response I get back, it happens only on some movies/series, not always

Huh that one has some useful information, im decoding something wrongly apparently. I wish it said what though..

[erictbar]

Here is my log with debug on:

jellyfin-rpc.exe -v debug
2024-10-01 16:45:34 INFO [jellyfin_rpc] Initializing Jellyfin-RPC
2024-10-01 16:45:34 DEBUG [reqwest::connect] starting new connection: https://github.com/
2024-10-01 16:45:34 DEBUG [hyper_util::client::legacy::connect::dns] resolving host="github.com"
2024-10-01 16:45:34 DEBUG [hyper_util::client::legacy::connect::http] connecting to 140.82.113.3:443
2024-10-01 16:45:34 DEBUG [hyper_util::client::legacy::connect::http] connected to 140.82.113.3:443
2024-10-01 16:45:34 DEBUG [rustls::client::hs] No cached session for DnsName("github.com")
2024-10-01 16:45:34 DEBUG [rustls::client::hs] Not resuming any session
2024-10-01 16:45:34 DEBUG [rustls::client::hs] Using ciphersuite TLS13_AES_128_GCM_SHA256
2024-10-01 16:45:34 DEBUG [rustls::client::tls13] Not resuming
2024-10-01 16:45:34 DEBUG [rustls::client::tls13] TLS1.3 encrypted extensions: [ServerNameAck, Protocols([ProtocolName(687474702f312e31)])]
2024-10-01 16:45:34 DEBUG [rustls::client::hs] ALPN protocol is Some(b"http/1.1")
2024-10-01 16:45:34 DEBUG [hyper_util::client::legacy::pool] pooling idle connection for ("https", github.com)
2024-10-01 16:45:34 DEBUG [reqwest::async_impl::client] redirecting 'https://github.com/Radiicall/jellyfin-rpc/releases/latest' to 'https://github.com/Radiicall/jellyfin-rpc/releases/tag/1.3.0'
2024-10-01 16:45:34 DEBUG [hyper_util::client::legacy::pool] reuse idle connection for ("https", github.com)
2024-10-01 16:45:34 DEBUG [jellyfin_rpc::config] Getting config path
2024-10-01 16:45:34 DEBUG [jellyfin_rpc::config] Platform is Windows
2024-10-01 16:45:34 DEBUG [jellyfin_rpc::config] Config path is: C:\Users<redacted>\AppData\Roaming\jellyfin-rpc\main.json
2024-10-01 16:45:34 DEBUG [jellyfin_rpc::config] Config loaded successfully
2024-10-01 16:45:34 DEBUG [jellyfin_rpc] Creating jellyfin-rpc client builder
2024-10-01 16:45:34 DEBUG [jellyfin_rpc::config] Platform is Windows
2024-10-01 16:45:34 DEBUG [jellyfin_rpc] Found config.imgur.client_id
2024-10-01 16:45:34 DEBUG [jellyfin_rpc] Building client
2024-10-01 16:45:34 INFO [jellyfin_rpc] Connecting to Discord
2024-10-01 16:45:34 INFO [jellyfin_rpc] Attempt 1: Trying to connect
2024-10-01 16:46:36 INFO [jellyfin_rpc] Connected!
2024-10-01 16:46:43 DEBUG [reqwest::connect] starting new connection: https://subdomain.example.com/
2024-10-01 16:46:43 DEBUG [hyper_util::client::legacy::connect::dns] resolving host="subdomain.example.com"
2024-10-01 16:46:43 DEBUG [hyper_util::client::legacy::connect::http] connecting to :443
2024-10-01 16:46:43 DEBUG [hyper_util::client::legacy::connect::http] connected to :443
2024-10-01 16:46:43 DEBUG [rustls::client::hs] No cached session for DnsName("subdomain.example.com")
2024-10-01 16:46:43 DEBUG [rustls::client::hs] Not resuming any session
2024-10-01 16:46:43 DEBUG [rustls::client::hs] Using ciphersuite TLS13_AES_128_GCM_SHA256
2024-10-01 16:46:43 DEBUG [rustls::client::tls13] Not resuming
2024-10-01 16:46:43 DEBUG [rustls::client::tls13] TLS1.3 encrypted extensions: [Protocols([ProtocolName(687474702f312e31)])]
2024-10-01 16:46:43 DEBUG [rustls::client::hs] ALPN protocol is Some(b"http/1.1")
2024-10-01 16:46:43 DEBUG [hyper_util::client::legacy::pool] pooling idle connection for ("https", subdomain.example.com)
2024-10-01 16:46:43 ERROR [jellyfin_rpc] error decoding response body
2024-10-01 16:46:43 DEBUG [jellyfin_rpc] reqwest::Error { kind: Decode, source: Error("expected value", line: 1, column: 1) }
2024-10-01 16:46:43 INFO [jellyfin_rpc] Attempt 1: Trying to reconnect
2024-10-01 16:47:36 INFO [jellyfin_rpc] Reconnected!
2024-10-01 16:47:43 DEBUG [hyper_util::client::legacy::pool] reuse idle connection for ("https", subdomain.example.com)
2024-10-01 16:47:43 DEBUG [hyper_util::client::legacy::pool] pooling idle connection for ("https", subdomain.example.com)
2024-10-01 16:47:43 ERROR [jellyfin_rpc] error decoding response body
2024-10-01 16:47:43 DEBUG [jellyfin_rpc] reqwest::Error { kind: Decode, source: Error("expected value", line: 1, column: 1) }
2024-10-01 16:47:43 INFO [jellyfin_rpc] Attempt 1: Trying to reconnect

One thing I noticed when looking up my Jellyfin logs is that my Jellyfin username is different from what is on the user screen. I did change it at some point.

[2024-10-01 16:51:00.104 +00:00] [INF] Playback stopped reported by app "Finamp" "0.9.11" playing "05. Deep Drum Dunes". Stopped at "234387" ms
[2024-10-01 16:51:00.112 +00:00] [INF] Item is in a folder the user does not want to be monitored; ignoring
[2024-10-01 16:51:00.113 +00:00] [INF] Playback stop tracker found, processing stop : "31DD6CD4-AC89-49A2-8132-C0AB75BAEE96-54b4dfd8d2c44bcb896f3cd3a3adfd5a-cb6f5884dc9082b0e70a96aaade346fe"
[2024-10-01 16:51:00.113 +00:00] [INF] PlaybackTracker : Adding Stop Event : 10/01/2024 16:51:00
[2024-10-01 16:51:00.114 +00:00] [INF] Saving playback tracking activity in DB
[2024-10-01 16:51:00.131 +00:00] [INF] Adding playback tracker : 31DD6CD4-AC89-49A2-8132-C0AB75BAEE96-54b4dfd8d2c44bcb896f3cd3a3adfd5a-053713fb059eab6c689ba3bca30c7326
[2024-10-01 16:51:00.131 +00:00] [INF] PlaybackTracker : Adding Start Event : 10/01/2024 16:51:00
[2024-10-01 16:51:00.131 +00:00] [INF] Creating StartPlaybackTimer Task
[2024-10-01 16:51:00.131 +00:00] [INF] StartPlaybackTimer : Entered
[2024-10-01 16:51:00.391 +00:00] [INF] "username2" played artist="Marcin Sonnenberg", track="05. Deep Drum Dunes", album="Lichtspeer OST"
[2024-10-01 16:51:00.467 +00:00] [INF] "username2" is now playing artist="Marcin Sonnenberg", track="06. Ancient Cart", album="Lichtspeer OST"
[2024-10-01 16:51:00.628 +00:00] [INF] Started user data sync
[2024-10-01 16:51:00.742 +00:00] [INF] User Data Sync: User "username1" ("UUID redacted") posted 3 updates
[2024-10-01 16:51:00.742 +00:00] [INF] Finished user data sync, taking "00:00:00.1137396"
[2024-10-01 16:51:01.123 +00:00] [INF] Processing playback tracker : "31DD6CD4-AC89-49A2-8132-C0AB75BAEE96-54b4dfd8d2c44bcb896f3cd3a3adfd5a-053713fb059eab6c689ba3bca30c7326"
[2024-10-01 16:51:20.129 +00:00] [INF] session.RemoteEndPoint : "local IP of router"
[2024-10-01 16:51:20.130 +00:00] [INF] StartPlaybackTimer : event_playing_id = "053713fb059eab6c689ba3bca30c7326"
[2024-10-01 16:51:20.130 +00:00] [INF] StartPlaybackTimer : event_user_id = "54b4dfd8d2c44bcb896f3cd3a3adfd5a"
[2024-10-01 16:51:20.130 +00:00] [INF] StartPlaybackTimer : event_user_id_int = 1
[2024-10-01 16:51:20.130 +00:00] [INF] StartPlaybackTimer : session_playing_id = "053713fb059eab6c689ba3bca30c7326"
[2024-10-01 16:51:20.130 +00:00] [INF] StartPlaybackTimer : session_user_id = "54b4dfd8d2c44bcb896f3cd3a3adfd5a"
[2024-10-01 16:51:20.130 +00:00] [INF] StartPlaybackTimer : play_method = "DirectPlay"
[2024-10-01 16:51:20.130 +00:00] [INF] StartPlaybackTimer : e.ClientName = "Finamp"
[2024-10-01 16:51:20.130 +00:00] [INF] StartPlaybackTimer : e.DeviceName = "iPhone"
[2024-10-01 16:51:20.130 +00:00] [INF] StartPlaybackTimer : ItemName = "Marcin Sonnenberg - 06. Ancient Cart (Lichtspeer OST)"
[2024-10-01 16:51:20.130 +00:00] [INF] StartPlaybackTimer : ItemId = "053713fb059eab6c689ba3bca30c7326"
[2024-10-01 16:51:20.130 +00:00] [INF] StartPlaybackTimer : ItemType = "Audio"
[2024-10-01 16:51:20.130 +00:00] [INF] StartPlaybackTimer : All matches, playback registered
[2024-10-01 16:51:20.130 +00:00] [INF] Playback tracker found, adding playback info : "31DD6CD4-AC89-49A2-8132-C0AB75BAEE96-54b4dfd8d2c44bcb896f3cd3a3adfd5a-053713fb059eab6c689ba3bca30c7326"
[2024-10-01 16:51:20.130 +00:00] [INF] Saving playback tracking activity in DB
[2024-10-01 16:51:20.137 +00:00] [INF] StartPlaybackTimer : Exited
[2024-10-01 16:51:21.124 +00:00] [INF] Processing playback tracker : "31DD6CD4-AC89-49A2-8132-C0AB75BAEE96-54b4dfd8d2c44bcb896f3cd3a3adfd5a-053713fb059eab6c689ba3bca30c7326"
[2024-10-01 16:52:24.471 +00:00] [INF] Processing playback tracker : "31DD6CD4-AC89-49A2-8132-C0AB75BAEE96-54b4dfd8d2c44bcb896f3cd3a3adfd5a-053713fb059eab6c689ba3bca30c7326"
[2024-10-01 16:53:40.085 +00:00] [INF] Processing playback tracker : "31DD6CD4-AC89-49A2-8132-C0AB75BAEE96-54b4dfd8d2c44bcb896f3cd3a3adfd5a-053713fb059eab6c689ba3bca30c7326"
[2024-10-01 16:55:03.389 +00:00] [INF] WS "local IP of router" request
[2024-10-01 16:55:19.031 +00:00] [WRN] Compiling a query which loads related collections for more than one collection navigation, either via 'Include' or through projection, but no 'QuerySplittingBehavior' has been configured. By default, Entity Framework will use 'QuerySplittingBehavior.SingleQuery', which can potentially result in slow query performance. See https://go.microsoft.com/fwlink/?linkid=2134277 for more information. To identify the query that's triggering this warning call 'ConfigureWarnings(w =~ w.Throw(RelationalEventId.MultipleCollectionIncludeWarning))'.
[2024-10-01 16:55:28.728 +00:00] [INF] Processing playback tracker : "31DD6CD4-AC89-49A2-8132-C0AB75BAEE96-54b4dfd8d2c44bcb896f3cd3a3adfd5a-053713fb059eab6c689ba3bca30c7326"
[2024-10-01 16:55:48.734 +00:00] [INF] Processing playback tracker : "31DD6CD4-AC89-49A2-8132-C0AB75BAEE96-54b4dfd8d2c44bcb896f3cd3a3adfd5a-053713fb059eab6c689ba3bca30c7326"
[2024-10-01 16:56:08.740 +00:00] [INF] Processing playback tracker : "31DD6CD4-AC89-49A2-8132-C0AB75BAEE96-54b4dfd8d2c44bcb896f3cd3a3adfd5a-053713fb059eab6c689ba3bca30c7326"
[2024-10-01 16:56:29.738 +00:00] [INF] Processing playback tracker : "31DD6CD4-AC89-49A2-8132-C0AB75BAEE96-54b4dfd8d2c44bcb896f3cd3a3adfd5a-053713fb059eab6c689ba3bca30c7326"
[2024-10-01 16:56:49.739 +00:00] [INF] Processing playback tracker : "31DD6CD4-AC89-49A2-8132-C0AB75BAEE96-54b4dfd8d2c44bcb896f3cd3a3adfd5a-053713fb059eab6c689ba3bca30c7326"
[2024-10-01 16:57:10.738 +00:00] [INF] Processing playback tracker : "31DD6CD4-AC89-49A2-8132-C0AB75BAEE96-54b4dfd8d2c44bcb896f3cd3a3adfd5a-053713fb059eab6c689ba3bca30c7326"

[jmj30]

i had this error i fixed it by looking at the config file
"api_key": "\t{apikey}" to "api_key": "{apikey}"