Plexamp Window desktop spinning play button

Plex Labs
1

I listen to Plexamp all day on my PC while working. Starting with version 4.10 it will stop playing audio several times a day. It is always within a few seconds of starting a new track. The play button will turn to a spinner. Hitting next plays the next track fine. Going back to the previous track and then next to the song with the original problem also works fine. I’ve been listening to Plexamp like this basically since it was released, and I’ve never had it stop during playback like this.

This error will show up in the logs:

Mar 29, 2024 08:43:14.284 [Javascript] INFO - PlayQueue: Saving state {"source":"7c943a1c0b8ee71e80c79ebd88b9ec906e355cdf","state":"playing","time":59139,"rate":1,"volume":100,"playQueueID":"12407","playQueueVersion":1,"playQueueItemID":"738404"}
Mar 29, 2024 08:43:19.295 [Javascript] INFO - PlayQueue: Saving state {"source":"7c943a1c0b8ee71e80c79ebd88b9ec906e355cdf","state":"playing","time":64149,"rate":1,"volume":100,"playQueueID":"12407","playQueueVersion":1,"playQueueItemID":"738404"}
Mar 29, 2024 08:43:19.296 [Javascript] DEBUG - PlayQueue: Processing send timelines in state playing time 64149.
Mar 29, 2024 08:43:19.296 [Javascript] INFO - ViewState: Progress 28% (64149 ms) on item 7c943a1c0b8ee71e80c79ebd88b9ec906e355cdf/335407.
Mar 29, 2024 08:43:19.296 [Javascript] INFO - ViewState: Trying to send directly.
Mar 29, 2024 08:43:19.296 [0x00004224] DEBUG - Networking: Create request 3358: (GET https://192-168-10-100.abcc8e1c124141a080dd58a1ba27b78b.plex.direct:32400/:/timeline?state=playing&duration=226927&time=64149&playQueueItemID=738404&key=%2Flibrary%2Fmetadata%2F335407&ratingKey=335407&playQueueID=12407&playQueueVersion=1&containerKey=%2FplayQueues%2F12407&hasMDE=1&includeFields=thumbBlurHash&X-Plex-Device-Name=6PL6RW3) network type 2.
Mar 29, 2024 08:43:19.311 [0x00004bdc] DEBUG - Networking: Completed request 3358: (GET https://192-168-10-100.abcc8e1c124141a080dd58a1ba27b78b.plex.direct:32400/:/timeline?state=playing&duration=226927&time=64149&playQueueItemID=738404&key=%2Flibrary%2Fmetadata%2F335407&ratingKey=335407&playQueueID=12407&playQueueVersion=1&containerKey=%2FplayQueues%2F12407&hasMDE=1&includeFields=thumbBlurHash&X-Plex-Device-Name=6PL6RW3) with code 200 (have 103 bytes) in 12 ms.
Mar 29, 2024 08:43:19.311 [Javascript] INFO - ViewState: Server reports playback state progress.
Mar 29, 2024 08:43:24.308 [Javascript] INFO - PlayQueue: Saving state {"source":"7c943a1c0b8ee71e80c79ebd88b9ec906e355cdf","state":"playing","time":69169,"rate":1,"volume":100,"playQueueID":"12407","playQueueVersion":1,"playQueueItemID":"738404"}
Mar 29, 2024 08:43:30.124 [Javascript] INFO - PlayQueue: Saving state {"source":"7c943a1c0b8ee71e80c79ebd88b9ec906e355cdf","state":"playing","time":74969,"rate":1,"volume":100,"playQueueID":"12407","playQueueVersion":1,"playQueueItemID":"738404"}
Mar 29, 2024 08:43:31.772 [0x00003b28] ERROR - Network: Failed request for https://192-168-10-100.abcc8e1c124141a080dd58a1ba27b78b.plex.direct:32400/playQueues/12407?window=16&center=738404&X-Plex-Client-Identifier=911066c7-76a7-4d42-947d-d4494f9fb80d&X-Plex-Device=Windows&X-Plex-Device-Name=6PL6RW3&X-Plex-Platform=Generic&X-Plex-Platform-Version=10%2E0%2E19045&X-Plex-Product=Plexamp&X-Plex-Token=<token>&X-Plex-Version=4%2E10%2E1 after connection test, giving up.
Mar 29, 2024 08:43:31.773 [0x00003b28] ERROR - PQCache: Unable to parse play queue.
Mar 29, 2024 08:43:31.773 [0x00003b28] INFO - PQCache: Parsed 0 items (0 currently active).
Mar 29, 2024 08:43:34.739 [Javascript] DEBUG - PlayQueue: Processing send timelines in state playing time 79589.
Mar 29, 2024 08:43:34.739 [Javascript] INFO - ViewState: Progress 35% (79589 ms) on item 7c943a1c0b8ee71e80c79ebd88b9ec906e355cdf/335407.
Mar 29, 2024 08:43:34.739 [Javascript] INFO - ViewState: Trying to send directly.
Mar 29, 2024 08:43:34.739 [0x00004224] DEBUG - Networking: Create request 3359: (GET https://192-168-10-100.abcc8e1c124141a080dd58a1ba27b78b.plex.direct:32400/:/timeline?state=playing&duration=226927&time=79589&playQueueItemID=738404&key=%2Flibrary%2Fmetadata%2F335407&ratingKey=335407&playQueueID=12407&playQueueVersion=1&containerKey=%2FplayQueues%2F12407&hasMDE=1&includeFields=thumbBlurHash&X-Plex-Device-Name=6PL6RW3) network type 2.
Mar 29, 2024 08:43:34.746 [0x00004bdc] DEBUG - Networking: Completed request 3359: (GET https://192-168-10-100.abcc8e1c124141a080dd58a1ba27b78b.plex.direct:32400/:/timeline?state=playing&duration=226927&time=79589&playQueueItemID=738404&key=%2Flibrary%2Fmetadata%2F335407&ratingKey=335407&playQueueID=12407&playQueueVersion=1&containerKey=%2FplayQueues%2F12407&hasMDE=1&includeFields=thumbBlurHash&X-Plex-Device-Name=6PL6RW3) with code 200 (have 103 bytes) in 4 ms.
Mar 29, 2024 08:43:34.746 [Javascript] INFO - ViewState: Server reports playback state progress.
Mar 29, 2024 08:43:35.742 [Javascript] INFO - PlayQueue: Saving state {"source":"7c943a1c0b8ee71e80c79ebd88b9ec906e355cdf","state":"playing","time":80599,"rate":1,"volume":100,"playQueueID":"12407","playQueueVersion":1,"playQueueItemID":"738404"}
Mar 29, 2024 08:43:41.758 [Javascript] INFO - PlayQueue: Saving state {"source":"7c943a1c0b8ee71e80c79ebd88b9ec906e355cdf","state":"playing","time":86589,"rate":1,"volume":100,"playQueueID":"12407","playQueueVersion":1,"playQueueItemID":"738404"}
Mar 29, 2024 08:43:42.992 [Javascript] INFO - Memory usage: 82.4MB
2

It’s always best to post a full log, as many times the snippet is lacking nuance.

Based on that error looks like you have an intermittent network issue.

3

Plexamp.zip (430.3 KB)
Here is the complete log. I wouldn’t think it would be a network issue (everything is wired local LAN). Its strange that it started with 4.10. I will try rolling back to 4.9.5 and see if it still happens.

4

I knew the full log would help :laughing:

Mar 29, 2024 13:42:43.746 [0x00005658] INFO - Cache: READ returning EOF.
...
Mar 29, 2024 13:42:43.871 [0x00004d50] ERROR - BASS: Error [ret = BASS_StreamCreateFileUser((DWORD)STREAMFILE_NOBUFFER, flags, &proc, m_context.get())] - 41

Looks like in some cases, the server is not giving back any data for a media file. Could be network, could be disk or similar issue on server, that log would probably show the root cause.

5

Plex Media Server.zip (459.8 KB)
The media server logs covering that same timeframe.

I’m back on 4.9.5 for now. No hiccups yet. I will report back if run into it on Monday on this older version.

6

Hmm, it’s still unclear what’s going on, unfortunately. Please keep me posted if you continue to reproduce, and I can see if we can provide further assistance.

7

Sorry for the late follow up, I was out of town last week.

I rolled back to 4.9.5 and let it play through for 3 days without a hiccup. It then auto updated back to 4.10 and the issue is back. I haven’t seen the issue on any other client (iPhone, other computers) but I don’t listen on them for nearly as long as on this computer.

I appreciate the investigation you have done so far. It isn’t a killer bug (I have to hit the next button once every couple of hours), but I was hoping the issue would be an obvious difference between the two versions. I am in software development myself, so I should have know that would not be the case. :slight_smile:

8

Hmm… that’s pretty clear evidence something has changed.

If you DM me your email, I can get you into our slack and you can try the beta and/or we can try some other things. We highly value technical users!

9

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