Plexamp not showing in Server Dashboard on Resome after Idle

jannis_goeing · March 3, 2025, 6:25am

Hi all,

I’m running Plexamp Headless on multiple Raspberry Pi’s and I’ve noticed that when you pause the playback and resume it after some time, e.g. next day or even after multiple days, it consistently does not show in the Plex Dashboard as playing, and nothing get recording in the playback history. If you start a new playback, it immediately shows up.

This on the one hand makes the plex playback statistics pretty much useless, because it leads to one or multiple days of missing playback history all the time if you don’t notice. On the other hand, this breaks smart home automation, e.g. if Home Assistant should turn on the speakers when something is playing, but Plex does not know that something is playing, nothing is going to happen.

sincerely,

Jannis

elan · March 3, 2025, 7:00am

i highly doubt it’s missing the playback history, Plexamp buffers playback events and batches them to the server at a later time if it has trouble reaching the server.

Please reproduce the issue and upload Plexamp logs so we can have a look.

jannis_goeing · March 3, 2025, 1:26pm

Hi Elan,

yes, you are correct, it does in fact do that. I did not notice that, sorry for the confusion. After starting a new Playback, when the device shows up in the Server Dashboard again, the Playback History from before gets added.

I’ve attached the logs from when I tried to resume to this Post. Looking at it, what I can see is that the Plex Server seems to be sending a 401 Unauthorized as a Response to the (or on of the?) /timeline Request, which seems to lead to this session now being offline.

However, despite of this, it is still fetching new media from the server, if i skip songs i can see networkings spikes in the Server Dashboard from when it cached the next new song.

plexamp.log (6.9 KB)

sincerely,
Jannis

jannis_goeing · March 13, 2025, 8:31am

I’ve managed to consistently reproduce this error by restarting the PMS whilst Playback is paused, it will then throw the 401 Unauthorized Error. It will keep playing though and also fetch new Songs, but it will only send the playback history to Plex and what’s currently playing once one starts some new playback.

Since plexamp headless is usually running 24/7, it’s quite a common scenario (at least for me) to just resume the playback after one or two days whilst having updated or restarted the PMS in the meantime.

elan · March 14, 2025, 5:49pm

Noted, we’ll try to improve this scenario in the future. However, I’m a bit surprised it’s returning a 401, are you running some sort of proxy in front of the server? Otherwise I would assume the connection would fail completely.

In either case, though, it makes sense to me to retry sooner.

elan · March 14, 2025, 5:56pm

I checked again, restarting my server, and it works as expected. Once the next track starts playing, it resumes sending progress to the server and flushes saved state.

Your logs above just show the period where there is a failure, but not once the server comes back online again.

jannis_goeing · March 14, 2025, 6:11pm

I do have my Plex available through a reverse proxy, so I can reach my web frontend through a custom domain, but I do also just have the port 32400 forward, so I assume Plexamp would by default just connect directly?

That’s weird, I’ve checked again and for me it does not recover on the next song, only when I start a new playback.

elan · March 14, 2025, 6:24pm

Well then post a complete log and we’ll have a look.

jannis_goeing · March 14, 2025, 7:15pm

I’ve attached the (now hopefully complete) logfile. It should contain the following actions:

Pausing the currently playing track
Restarting the PMS
Resuming → Client not showing as playing
Skipping two tracks → Client not showing as playing, Songs not yet in history
Me starting a new playlist → Client is now showing as playing, and the history gets backfilled

If you need any other info, i’d be happy to provide that.

plexamp.log (134.5 KB)

elan · March 15, 2025, 7:57pm

I see the requests into the server here: success, then error, then success again; from the perspective of Plexamp, it’s reporting playback status (as well as batching any missing events), so I’m not sure what to recommend, it may be a server issue. Server logs should have some indication of playback status as well.

Mar 14, 2025 19:06:02.361 [0xb17133d0] DEBUG - Networking: Completed request 17696: (GET https://192-168-1-2.c50e03bc1743451287498708476f70eb.plex.direct:32400/:/timeline?state=playing&duration=252020&time=74125&playQueueItemID=197508&key=%2Flibrary%2Fmetadata%2F14569&ratingKey=14569&playQueueID=2820&playQueueVersion=2&containerKey=%2FplayQueues%2F2820&hasMDE=1&includeFields=thumbBlurHash&X-Plex-Device-Name=Jannis%20Buero) with code 200 (have 104 bytes) in 7 ms.
Mar 14, 2025 19:06:06.025 [0xb17133d0] DEBUG - Networking: Completed request 17697: (GET https://192-168-1-2.c50e03bc1743451287498708476f70eb.plex.direct:32400/:/timeline?state=paused&duration=252020&time=77785&playQueueItemID=197508&key=%2Flibrary%2Fmetadata%2F14569&ratingKey=14569&playQueueID=2820&playQueueVersion=2&containerKey=%2FplayQueues%2F2820&hasMDE=1&includeFields=thumbBlurHash&X-Plex-Device-Name=Jannis%20Buero) with code 200 (have 104 bytes) in 4 ms.
Mar 14, 2025 19:06:12.299 [0xb17133d0] DEBUG - Networking: Completed request 15009: (GET https://192-168-1-2.c50e03bc1743451287498708476f70eb.plex.direct:32400/:/eventsource/notifications?filters=timeline&X-Plex-Token=<token>) with code -56 (have 0 bytes) in 44641856 ms.
Mar 14, 2025 19:06:17.309 [0xb17133d0] DEBUG - Networking: Completed request 17698: (GET https://192-168-1-2.c50e03bc1743451287498708476f70eb.plex.direct:32400/:/eventsource/notifications?filters=timeline&X-Plex-Token=<token>) with code -7 (have 0 bytes) in 6 ms.
Mar 14, 2025 19:06:21.669 [0xb17133d0] DEBUG - Networking: Completed request 17699: (GET https://192-168-1-2.c50e03bc1743451287498708476f70eb.plex.direct:32400/:/timeline?state=paused&duration=252020&time=78055&playQueueItemID=197508&key=%2Flibrary%2Fmetadata%2F14569&ratingKey=14569&playQueueID=2820&playQueueVersion=2&containerKey=%2FplayQueues%2F2820&hasMDE=1&includeFields=thumbBlurHash&X-Plex-Device-Name=Jannis%20Buero) with code 503 (have 111 bytes) in 46 ms.
Mar 14, 2025 19:06:22.312 [0xb17133d0] DEBUG - Networking: Completed request 17700: (GET https://192-168-1-2.c50e03bc1743451287498708476f70eb.plex.direct:32400/:/eventsource/notifications?filters=timeline&X-Plex-Token=<token>) with code 503 (have 155 bytes) in 1 ms.
Mar 14, 2025 19:07:54.081 [0xb17133d0] DEBUG - Networking: Completed request 17704: (GET https://192-168-1-2.c50e03bc1743451287498708476f70eb.plex.direct:32400/:/timeline?state=playing&duration=237679&time=0&playQueueItemID=197509&key=%2Flibrary%2Fmetadata%2F11922&ratingKey=11922&playQueueID=2820&playQueueVersion=2&containerKey=%2FplayQueues%2F2820&hasMDE=1&includeFields=thumbBlurHash&X-Plex-Device-Name=Jannis%20Buero) with code 401 (have 91 bytes) in 645 ms.
Mar 14, 2025 19:08:24.691 [0xb17133d0] DEBUG - Networking: Completed request 17729: (GET https://192-168-1-2.c50e03bc1743451287498708476f70eb.plex.direct:32400/:/timeline?state=playing&duration=245020&time=0&playQueueItemID=197510&key=%2Flibrary%2Fmetadata%2F14021&ratingKey=14021&playQueueID=2820&playQueueVersion=2&containerKey=%2FplayQueues%2F2820&hasMDE=1&includeFields=thumbBlurHash&X-Plex-Device-Name=Jannis%20Buero) with code 401 (have 91 bytes) in 640 ms.
Mar 14, 2025 19:09:00.436 [0xb17133d0] DEBUG - Networking: Completed request 17741: (GET https://192-168-1-2.c50e03bc1743451287498708476f70eb.plex.direct:32400/:/timeline?state=buffering&duration=348505&time=0&playQueueItemID=197720&key=%2Flibrary%2Fmetadata%2F21982&ratingKey=21982&playQueueID=2823&playQueueVersion=1&containerKey=%2FplayQueues%2F2823&hasMDE=1&includeFields=thumbBlurHash&X-Plex-Device-Name=Jannis%20Buero) with code 200 (have 99 bytes) in 6 ms.
Mar 14, 2025 19:09:00.596 [0xb17133d0] DEBUG - Networking: Completed request 17745: (GET https://192-168-1-2.c50e03bc1743451287498708476f70eb.plex.direct:32400/:/timeline?offline=1&ratingKey=11922&state=stopped&updatedAt=1741979304&time=16020&includeFields=thumbBlurHash&X-Plex-Device-Name=Jannis%20Buero) with code 200 (have 99 bytes) in 156 ms.
Mar 14, 2025 19:09:00.611 [0xb17133d0] DEBUG - Networking: Completed request 17748: (GET https://192-168-1-2.c50e03bc1743451287498708476f70eb.plex.direct:32400/:/timeline?offline=1&ratingKey=14021&state=stopped&updatedAt=1741979340&time=35850&includeFields=thumbBlurHash&X-Plex-Device-Name=Jannis%20Buero) with code 200 (have 99 bytes) in 7 ms.
Mar 14, 2025 19:09:00.631 [0xb17133d0] DEBUG - Networking: Completed request 17749: (GET https://192-168-1-2.c50e03bc1743451287498708476f70eb.plex.direct:32400/:/timeline?offline=1&ratingKey=14569&state=stopped&updatedAt=1741979273&viewCount=1&time=0&includeFields=thumbBlurHash&X-Plex-Device-Name=Jannis%20Buero) with code 200 (have 101 bytes) in 12 ms.
Mar 14, 2025 19:09:00.643 [0xb17133d0] DEBUG - Networking: Completed request 17750: (GET https://192-168-1-2.c50e03bc1743451287498708476f70eb.plex.direct:32400/:/timeline?state=playing&duration=348505&time=200&playQueueItemID=197720&key=%2Flibrary%2Fmetadata%2F21982&ratingKey=21982&playQueueID=2823&playQueueVersion=1&containerKey=%2FplayQueues%2F2823&hasMDE=1&includeFields=thumbBlurHash&X-Plex-Device-Name=Jannis%20Buero) with code 200 (have 99 bytes) in 16 ms.
Mar 14, 2025 19:09:15.525 [0xb17133d0] DEBUG - Networking: Completed request 17768: (GET https://192-168-1-2.c50e03bc1743451287498708476f70eb.plex.direct:32400/:/timeline?state=playing&duration=348505&time=15219&playQueueItemID=197720&key=%2Flibrary%2Fmetadata%2F21982&ratingKey=21982&playQueueID=2823&playQueueVersion=1&containerKey=%2FplayQueues%2F2823&hasMDE=1&includeFields=thumbBlurHash&X-Plex-Device-Name=Jannis%20Buero) with code 200 (have 99 bytes) in 93 ms.
Mar 14, 2025 19:09:17.016 [0xb17133d0] DEBUG - Networking: Completed request 17769: (GET https://192-168-1-2.c50e03bc1743451287498708476f70eb.plex.direct:32400/:/timeline?state=paused&duration=348505&time=16659&playQueueItemID=197720&key=%2Flibrary%2Fmetadata%2F21982&ratingKey=21982&playQueueID=2823&playQueueVersion=1&containerKey=%2FplayQueues%2F2823&hasMDE=1&includeFields=thumbBlurHash&X-Plex-Device-Name=Jannis%20Buero) with code 200 (have 99 bytes) in 3 ms.

jannis_goeing · March 16, 2025, 9:51pm

I can see the 401 Erorrs in the Server log, but sadly there isn’t much more information than that. I’ve tried a couple of times, but I couldn’t anything more useful in the server logs.

Mar 16, 2025 22:44:59.027 [128543496870712] DEBUG - [HttpClient/HCl#8e] HTTP/1.1 (0.1s) 200 response from GET https://plex.tv/api/v2/server/users/subscriptions?auth_token=xxxxxxxxxxxxxxxxxxxx (reused)
Mar 16, 2025 22:44:59.028 [128543482104632] DEBUG - MyPlex: using cached data for requestMar 16, 2025 22:44:59.027 [128543496870712] DEBUG - [HttpClient/HCl#8e] HTTP/1.1 (0.1s) 200 response from GET https://plex.tv/api/v2/server/users/subscriptions?auth_token=xxxxxxxxxxxxxxxxxxxx (reused)
Mar 16, 2025 22:44:59.028 [128543482104632] DEBUG - MyPlex: using cached data for request for https://plex.tv/api/v2/server/users/services?auth_token=xxxxxxxxxxxxxxxxxxxx
Mar 16, 2025 22:44:59.030 [128543723399992] DEBUG - Auth: authenticating user as guest
Mar 16, 2025 22:44:59.030 [128543725509432] DEBUG - Auth: authenticating user as guest
Mar 16, 2025 22:44:59.030 [128543723399992] DEBUG - Request: [192.168.1.59:57432 (Subnet)] GET /:/timeline?state=playing&duration=293040&time=0&playQueueItemID=198330&key=%2Flibrary%2Fmetadata%2F14040&ratingKey=14040&playQueueID=2829&playQueueVersion=1&containerKey=%2FplayQueues%2F2829&hasMDE=1&includeFields=thumbBlurHash (22 live) #15b TLS GZIP Signed-in Token ()
Mar 16, 2025 22:44:59.030 [128543725509432] DEBUG - Request: [192.168.1.59:57448 (Subnet)] GET /:/timeline?state=paused&duration=293040&time=0&playQueueItemID=198330&key=%2Flibrary%2Fmetadata%2F14040&ratingKey=14040&playQueueID=2829&playQueueVersion=1&containerKey=%2FplayQueues%2F2829&hasMDE=1&includeFields=thumbBlurHash (22 live) #146 TLS GZIP Signed-in Token ()
Mar 16, 2025 22:44:59.030 [128543723399992] DEBUG - Completed: [192.168.1.10:47538] 200 GET /photo/:/transcode?width=69&height=69&url=%2Flibrary%2Fmetadata%2F14028%2Fthumb%2F1741575432&format=jpeg (22 live) #15e TLS 608ms 2715 bytes (pipelined: 1)
Mar 16, 2025 22:44:59.030 [128543723399992] DEBUG - Completed: [192.168.1.59:57432] 401 GET /:/timeline?state=playing&duration=293040&time=0&playQueueItemID=198330&key=%2Flibrary%2Fmetadata%2F14040&ratingKey=14040&playQueueID=2829&playQueueVersion=1&containerKey=%2FplayQueues%2F2829&hasMDE=1&includeFields=thumbBlurHash (22 live) #15b TLS GZIP 0ms 357 bytes
Mar 16, 2025 22:44:59.030 [128543723399992] DEBUG - Completed: [192.168.1.59:57448] 401 GET /:/timeline?state=paused&duration=293040&time=0&playQueueItemID=198330&key=%2Flibrary%2Fmetadata%2F14040&ratingKey=14040&playQueueID=2829&playQueueVersion=1&containerKey=%2FplayQueues%2F2829&hasMDE=1&includeFields=thumbBlurHash (23 live) #146 TLS GZIP 0ms 357 bytes
Mar 16, 2025 22:44:59.031 [128543725509432] DEBUG - Activity: updated activity 07358a41-19d5-4000-8af0-4e9d14788e96 - completed 1.5% - Media download by jannis.goeing
Mar 16, 2025 22:44:59.031 [128543725509432] DEBUG - Completed: [192.168.1.10:47524] 200 GET /photo/:/transcode?width=150&height=150&url=%2Flibrary%2Fmetadata%2F14028%2Fthumb%2F1741575432&format=png (23 live) #15c TLS GZIP 610ms 51988 bytes (pipelined: 1)
Mar 16, 2025 22:44:59.031 [128543490018104] DEBUG - Request: [192.168.1.59:57462 (Subnet)] GET /playQueues/2829?window=30&center=198330&includeFields=thumbBlurHash (23 live) #159 TLS GZIP Signed-in Token (jannis.goeing) (Jannis Buero)
Mar 16, 2025 22:44:59.032 [128543490018104] DEBUG - [Req#159] PlayQueue: total generated: 14, before cursor: 2, after cursor: 11
Mar 16, 2025 22:44:59.032 [128543490018104] DEBUG - [Req#159] PlayQueue: No need to refresh, active window is operational.
 for https://plex.tv/api/v2/server/users/services?auth_token=xxxxxxxxxxxxxxxxxxxx
Mar 16, 2025 22:44:59.030 [128543723399992] DEBUG - Auth: authenticating user as guest
Mar 16, 2025 22:44:59.030 [128543725509432] DEBUG - Auth: authenticating user as guest
Mar 16, 2025 22:44:59.030 [128543723399992] DEBUG - Request: [192.168.1.59:57432 (Subnet)] GET /:/timeline?state=playing&duration=293040&time=0&playQueueItemID=198330&key=%2Flibrary%2Fmetadata%2F14040&ratingKey=14040&playQueueID=2829&playQueueVersion=1&containerKey=%2FplayQueues%2F2829&hasMDE=1&includeFields=thumbBlurHash (22 live) #15b TLS GZIP Signed-in Token ()
Mar 16, 2025 22:44:59.030 [128543725509432] DEBUG - Request: [192.168.1.59:57448 (Subnet)] GET /:/timeline?state=paused&duration=293040&time=0&playQueueItemID=198330&key=%2Flibrary%2Fmetadata%2F14040&ratingKey=14040&playQueueID=2829&playQueueVersion=1&containerKey=%2FplayQueues%2F2829&hasMDE=1&includeFields=thumbBlurHash (22 live) #146 TLS GZIP Signed-in Token ()
Mar 16, 2025 22:44:59.030 [128543723399992] DEBUG - Completed: [192.168.1.10:47538] 200 GET /photo/:/transcode?width=69&height=69&url=%2Flibrary%2Fmetadata%2F14028%2Fthumb%2F1741575432&format=jpeg (22 live) #15e TLS 608ms 2715 bytes (pipelined: 1)
Mar 16, 2025 22:44:59.030 [128543723399992] DEBUG - Completed: [192.168.1.59:57432] 401 GET /:/timeline?state=playing&duration=293040&time=0&playQueueItemID=198330&key=%2Flibrary%2Fmetadata%2F14040&ratingKey=14040&playQueueID=2829&playQueueVersion=1&containerKey=%2FplayQueues%2F2829&hasMDE=1&includeFields=thumbBlurHash (22 live) #15b TLS GZIP 0ms 357 bytes
Mar 16, 2025 22:44:59.030 [128543723399992] DEBUG - Completed: [192.168.1.59:57448] 401 GET /:/timeline?state=paused&duration=293040&time=0&playQueueItemID=198330&key=%2Flibrary%2Fmetadata%2F14040&ratingKey=14040&playQueueID=2829&playQueueVersion=1&containerKey=%2FplayQueues%2F2829&hasMDE=1&includeFields=thumbBlurHash (23 live) #146 TLS GZIP 0ms 357 bytes
Mar 16, 2025 22:44:59.031 [128543725509432] DEBUG - Activity: updated activity 07358a41-19d5-4000-8af0-4e9d14788e96 - completed 1.5% - Media download by jannis.goeing
Mar 16, 2025 22:44:59.031 [128543725509432] DEBUG - Completed: [192.168.1.10:47524] 200 GET /photo/:/transcode?width=150&height=150&url=%2Flibrary%2Fmetadata%2F14028%2Fthumb%2F1741575432&format=png (23 live) #15c TLS GZIP 610ms 51988 bytes (pipelined: 1)
Mar 16, 2025 22:44:59.031 [128543490018104] DEBUG - Request: [192.168.1.59:57462 (Subnet)] GET /playQueues/2829?window=30&center=198330&includeFields=thumbBlurHash (23 live) #159 TLS GZIP Signed-in Token (jannis.goeing) (Jannis Buero)
Mar 16, 2025 22:44:59.032 [128543490018104] DEBUG - [Req#159] PlayQueue: total generated: 14, before cursor: 2, after cursor: 11
Mar 16, 2025 22:44:59.032 [128543490018104] DEBUG - [Req#159] PlayQueue: No need to refresh, active window is operational.

jannis_goeing · March 16, 2025, 10:08pm

Additionally, it seems this is only a problem with Plexamp Headless, I could not reproduce this behaviour using the android app.

elan · March 16, 2025, 10:33pm

please reproduce and then post full plexamp and server logs.

jannis_goeing · March 25, 2025, 7:45pm

Hi Elan,

Sorry for taking so long, I’ve attached a matching plexamp and server log file.

Plex Media Server.log (685.1 KB)
plexamp.log (77.6 KB)

system · June 23, 2025, 7:45pm

This topic was automatically closed 90 days after the last reply. New replies are no longer allowed.

Topic		Replies	Views
Plexamp headless not playing music Plex Labs plexamp	63	1415	June 18, 2023
Plexamp Headless - Error loading cloud players from plex.tv Plex Labs plexamp	16	259	November 13, 2022
Plexamp Window desktop spinning play button Plex Labs plexamp	8	115	July 14, 2024
Plexamp Headless RPI No Connection to Server Plex Labs plexamp	15	271	August 7, 2022
Plexamp Headless Plex Labs plexamp	4	188	November 21, 2024

Plexamp not showing in Server Dashboard on Resome after Idle

Related topics