Server Version#:1.29.0.6244
Player Version#: PlexAmp 4.4.0
Here is an excerpt of the logs when I tried to play music - behavior is that I am able to select Sonos as a player (connection comes right up in PlexAmp) however, as soon I choose a track to play, the Play button ‘spins’ and no music plays on my Sonos system. After about 10 seconds, PlexAmp skips to the next track in queue and the same behavior is observed (spinning play button and no audio output).
Excerpt of logs:
Oct 11, 2022 12:23:26.774 [0xb257cc50] Debug — Completed: [44.200.199.200:7913] 200 GET /playQueues/2621?own=1 (17 live) TLS GZIP 145ms 7465 bytes
Oct 11, 2022 12:23:27.930 [0xad904c50] Debug — Request: [192.168.100.108:57158 (Allowed Network (Subnet))] GET /playQueues/2621?window=30¢er=124036&includeFields=thumbBlurHash (16 live) #13d03 TLS GZIP Signed-in Token (jber790) (Android) / Accept => application/json / Accept-Encoding => gzip / Host => 192-168-100-99.fccd4750ad3147f5a3a9acaffb780714.plex.direct:32400 / User-Agent => TREBLE/2.1 / X-Plex-Client-Identifier => 15626e43-47ee-4f83-a030-ce24607eeec0 / X-Plex-Device => Android / X-Plex-Device-Name => JB-beats / X-Plex-Features => external-media / X-Plex-Platform => Android / X-Plex-Platform-Version => 12 / X-Plex-Product => Plexamp / X-Plex-Provides => client,player,pubsub-player / X-Plex-Token => xxxxxxxxxxxxxxxxxxxx / X-Plex-Version => 4.4.0
Oct 11, 2022 12:23:27.933 [0xad904c50] Debug — [Req#13d03] PlayQueue: total generated: 131, before cursor: 9, after cursor: 94
Oct 11, 2022 12:23:27.934 [0xad904c50] Debug — [Req#13d03] PlayQueue: No need to refresh, active window is operational.
Oct 11, 2022 12:23:27.980 [0xae5bac50] Debug — Request: [192.168.100.108:57160 (Allowed Network (Subnet))] GET /playQueues/2621?window=30¢er=124039&includeFields=thumbBlurHash (16 live) #13cb7 TLS GZIP Signed-in Token (jber790) (Android) / Accept => application/json / Accept-Encoding => gzip / Host => 192-168-100-99.fccd4750ad3147f5a3a9acaffb780714.plex.direct:32400 / User-Agent => TREBLE/2.1 / X-Plex-Client-Identifier => 15626e43-47ee-4f83-a030-ce24607eeec0 / X-Plex-Device => Android / X-Plex-Device-Name => JB-beats / X-Plex-Features => external-media / X-Plex-Platform => Android / X-Plex-Platform-Version => 12 / X-Plex-Product => Plexamp / X-Plex-Provides => client,player,pubsub-player / X-Plex-Token => xxxxxxxxxxxxxxxxxxxx / X-Plex-Version => 4.4.0
Oct 11, 2022 12:23:27.990 [0xad701c50] Debug — Request: [192.168.100.108:57162 (Allowed Network (Subnet))] GET /photo/:/transcode?width=134&height=134&url=undefined&format=jpeg (16 live) #13cd9 TLS Signed-in Token (jber790) (Android) / Accept => */* / Accept-Encoding => identity / Host => 192-168-100-99.fccd4750ad3147f5a3a9acaffb780714.plex.direct:32400 / User-Agent => TREBLE/2.1 / X-Plex-Token => xxxxxxxxxxxxxxxxxxxx
Oct 11, 2022 12:23:27.990 [0xad701c50] Debug — [Req#13cd9] Photo transcoder: Request for url [undefined] (is local: 0 upscaled: 0)
Oct 11, 2022 12:23:27.990 [0xad701c50] Debug — [Req#13cd9/HCl#198] HTTP requesting GET undefined
Oct 11, 2022 12:23:27.997 [0xb277fc50] Debug — Completed: [192.168.100.108:57162] 404 GET /photo/:/transcode?width=134&height=134&url=undefined&format=jpeg (16 live) TLS 7ms 288 bytes (pipelined: 2)
Oct 11, 2022 12:23:28.043 [0xad904c50] Debug — [Req#13d03] It took 110.000000 ms to retrieve 40 items.
Oct 11, 2022 12:23:28.081 [0xae5bac50] Debug — [Req#13cb7] PlayQueue: total generated: 131, before cursor: 12, after cursor: 91
Oct 11, 2022 12:23:28.081 [0xae5bac50] Debug — [Req#13cb7] PlayQueue: No need to refresh, active window is operational.
Oct 11, 2022 12:23:28.089 [0xad904c50] Debug — We're going to try to auto-select an audio stream for account 1.
Oct 11, 2022 12:23:28.090 [0xad904c50] Debug — Selecting best audio stream for part ID 3374 (autoselect: 0 language: en)
Oct 11, 2022 12:23:28.090 [0xad904c50] Debug — Audio Stream: 4484, Subtitle Stream: -1
Oct 11, 2022 12:23:28.091 [0xad904c50] Debug — We're going to try to auto-select an audio stream for account 1.
Oct 11, 2022 12:23:28.091 [0xad904c50] Debug — Selecting best audio stream for part ID 3463 (autoselect: 0 language: en)
Oct 11, 2022 12:23:28.092 [0xad904c50] Debug — Audio Stream: 4583, Subtitle Stream: -1
Oct 11, 2022 12:23:28.093 [0xad904c50] Debug — We're going to try to auto-select an audio stream for account 1.
Oct 11, 2022 12:23:28.093 [0xad904c50] Debug — Selecting best audio stream for part ID 3458 (autoselect: 0 language: en)
Oct 11, 2022 12:23:28.093 [0xad904c50] Debug — Audio Stream: 4578, Subtitle Stream: -1
Oct 11, 2022 12:23:28.094 [0xad904c50] Debug — We're going to try to auto-select an audio stream for account 1.
Oct 11, 2022 12:23:28.094 [0xad904c50] Debug — Selecting best audio stream for part ID 3451 (autoselect: 0 language: en)
Oct 11, 2022 12:23:28.094 [0xad904c50] Debug — Audio Stream: 4571, Subtitle Stream: -1
Oct 11, 2022 12:23:28.096 [0xad904c50] Debug — We're going to try to auto-select an audio stream for account 1.
Oct 11, 2022 12:23:28.096 [0xad904c50] Debug — Selecting best audio stream for part ID 3452 (autoselect: 0 language: en)
Oct 11, 2022 12:23:28.096 [0xad904c50] Debug — Audio Stream: 4572, Subtitle Stream: -1
Oct 11, 2022 12:23:28.097 [0xad904c50] Debug — We're going to try to auto-select an audio stream for account 1.
Oct 11, 2022 12:23:28.097 [0xad904c50] Debug — Selecting best audio stream for part ID 3453 (autoselect: 0 language: en)
Oct 11, 2022 12:23:28.097 [0xad904c50] Debug — Audio Stream: 4573, Subtitle Stream: -1
Oct 11, 2022 12:23:28.098 [0xad904c50] Debug — We're going to try to auto-select an audio stream for account 1.
Oct 11, 2022 12:23:28.098 [0xad904c50] Debug — Selecting best audio stream for part ID 3454 (autoselect: 0 language: en)
Oct 11, 2022 12:23:28.098 [0xad904c50] Debug — Audio Stream: 4574, Subtitle Stream: -1
Oct 11, 2022 12:23:28.099 [0xad904c50] Debug — We're going to try to auto-select an audio stream for account 1.
Oct 11, 2022 12:23:28.100 [0xad904c50] Debug — Selecting best audio stream for part ID 3455 (autoselect: 0 language: en)
Oct 11, 2022 12:23:28.100 [0xad904c50] Debug — Audio Stream: 4575, Subtitle Stream: -1
Oct 11, 2022 12:23:28.101 [0xad904c50] Debug — We're going to try to auto-select an audio stream for account 1.
Oct 11, 2022 12:23:28.101 [0xad904c50] Debug — Selecting best audio stream for part ID 3456 (autoselect: 0 language: en)
Oct 11, 2022 12:23:28.101 [0xad904c50] Debug — Audio Stream: 4576, Subtitle Stream: -1
Oct 11, 2022 12:23:28.102 [0xad904c50] Debug — We're going to try to auto-select an audio stream for account 1.
Oct 11, 2022 12:23:28.102 [0xad904c50] Debug — Selecting best audio stream for part ID 3457 (autoselect: 0 language: en)
Oct 11, 2022 12:23:28.102 [0xad904c50] Debug — Audio Stream: 4577, Subtitle Stream: -1
Oct 11, 2022 12:23:28.103 [0xad904c50] Debug — We're going to try to auto-select an audio stream for account 1.
Oct 11, 2022 12:23:28.104 [0xad904c50] Debug — Selecting best audio stream for part ID 3460 (autoselect: 0 language: en)
Oct 11, 2022 12:23:28.104 [0xad904c50] Debug — Audio Stream: 4580, Subtitle Stream: -1
Oct 11, 2022 12:23:28.105 [0xad904c50] Debug — We're going to try to auto-select an audio stream for account 1.
Oct 11, 2022 12:23:28.105 [0xad904c50] Debug — Selecting best audio stream for part ID 3461 (autoselect: 0 language: en)
Oct 11, 2022 12:23:28.105 [0xad904c50] Debug — Audio Stream: 4581, Subtitle Stream: -1
Oct 11, 2022 12:23:28.106 [0xad904c50] Debug — We're going to try to auto-select an audio stream for account 1.
Oct 11, 2022 12:23:28.106 [0xad904c50] Debug — Selecting best audio stream for part ID 3462 (autoselect: 0 language: en)
Oct 11, 2022 12:23:28.106 [0xad904c50] Debug — Audio Stream: 4582, Subtitle Stream: -1
Oct 11, 2022 12:23:28.107 [0xad904c50] Debug — We're going to try to auto-select an audio stream for account 1.
Oct 11, 2022 12:23:28.108 [0xad904c50] Debug — Selecting best audio stream for part ID 3441 (autoselect: 0 language: en)
Oct 11, 2022 12:23:28.108 [0xad904c50] Debug — Audio Stream: 4554, Subtitle Stream: -1
Oct 11, 2022 12:23:28.109 [0xad904c50] Debug — We're going to try to auto-select an audio stream for account 1.
Oct 11, 2022 12:23:28.109 [0xad904c50] Debug — Selecting best audio stream for part ID 3442 (autoselect: 0 language: en)
Oct 11, 2022 12:23:28.109 [0xad904c50] Debug — Audio Stream: 4555, Subtitle Stream: -1
Oct 11, 2022 12:23:28.110 [0xad904c50] Debug — We're going to try to auto-select an audio stream for account 1.
Oct 11, 2022 12:23:28.110 [0xad904c50] Debug — Selecting best audio stream for part ID 3443 (autoselect: 0 language: en)
Oct 11, 2022 12:23:28.110 [0xad904c50] Debug — Audio Stream: 4556, Subtitle Stream: -1
Oct 11, 2022 12:23:28.111 [0xad904c50] Debug — We're going to try to auto-select an audio stream for account 1.
Oct 11, 2022 12:23:28.111 [0xad904c50] Debug — Selecting best audio stream for part ID 3444 (autoselect: 0 language: en)
Oct 11, 2022 12:23:28.112 [0xad904c50] Debug — Audio Stream: 4557, Subtitle Stream: -1
Oct 11, 2022 12:23:28.113 [0xad904c50] Debug — We're going to try to auto-select an audio stream for account 1.
Oct 11, 2022 12:23:28.113 [0xad904c50] Debug — Selecting best audio stream for part ID 3445 (autoselect: 0 language: en)
Oct 11, 2022 12:23:28.113 [0xad904c50] Debug — Audio Stream: 4558, Subtitle Stream: -1
Oct 11, 2022 12:23:28.114 [0xad904c50] Debug — We're going to try to auto-select an audio stream for account 1.
Oct 11, 2022 12:23:28.114 [0xad904c50] Debug — Selecting best audio stream for part ID 3446 (autoselect: 0 language: en)
Oct 11, 2022 12:23:28.114 [0xad904c50] Debug — Audio Stream: 4559, Subtitle Stream: -1
Oct 11, 2022 12:23:28.115 [0xad904c50] Debug — We're going to try to auto-select an audio stream for account 1.
Oct 11, 2022 12:23:28.116 [0xad904c50] Debug — Selecting best audio stream for part ID 3447 (autoselect: 0 language: en)
Oct 11, 2022 12:23:28.116 [0xad904c50] Debug — Audio Stream: 4560, Subtitle Stream: -1
Oct 11, 2022 12:23:28.117 [0xad904c50] Debug — We're going to try to auto-select an audio stream for account 1.
Oct 11, 2022 12:23:28.117 [0xad904c50] Debug — Selecting best audio stream for part ID 3448 (autoselect: 0 language: en)
Oct 11, 2022 12:23:28.117 [0xad904c50] Debug — Audio Stream: 4561, Subtitle Stream: -1
Oct 11, 2022 12:23:28.118 [0xad904c50] Debug — We're going to try to auto-select an audio stream for account 1.
Oct 11, 2022 12:23:28.118 [0xad904c50] Debug — Selecting best audio stream for part ID 3449 (autoselect: 0 language: en)
Oct 11, 2022 12:23:28.118 [0xad904c50] Debug — Audio Stream: 4562, Subtitle Stream: -1
Oct 11, 2022 12:23:28.119 [0xad904c50] Debug — We're going to try to auto-select an audio stream for account 1.
Oct 11, 2022 12:23:28.119 [0xad904c50] Debug — Selecting best audio stream for part ID 3450 (autoselect: 0 language: en)
Oct 11, 2022 12:23:28.119 [0xad904c50] Debug — Audio Stream: 4563, Subtitle Stream: -1
Oct 11, 2022 12:23:28.120 [0xad904c50] Debug — We're going to try to auto-select an audio stream for account 1.
Oct 11, 2022 12:23:28.120 [0xad904c50] Debug — Selecting best audio stream for part ID 3433 (autoselect: 0 language: en)
Oct 11, 2022 12:23:28.120 [0xad904c50] Debug — Audio Stream: 4546, Subtitle Stream: -1
Oct 11, 2022 12:23:28.121 [0xad904c50] Debug — We're going to try to auto-select an audio stream for account 1.
Oct 11, 2022 12:23:28.121 [0xad904c50] Debug — Selecting best audio stream for part ID 3434 (autoselect: 0 language: en)
Oct 11, 2022 12:23:28.122 [0xad904c50] Debug — Audio Stream: 4547, Subtitle Stream: -1
Oct 11, 2022 12:23:28.122 [0xad904c50] Debug — We're going to try to auto-select an audio stream for account 1.
Oct 11, 2022 12:23:28.123 [0xad904c50] Debug — Selecting best audio stream for part ID 3435 (autoselect: 0 language: en)
Oct 11, 2022 12:23:28.123 [0xad904c50] Debug — Audio Stream: 4548, Subtitle Stream: -1
Oct 11, 2022 12:23:28.124 [0xad904c50] Debug — We're going to try to auto-select an audio stream for account 1.
Oct 11, 2022 12:23:28.124 [0xad904c50] Debug — Selecting best audio stream for part ID 3436 (autoselect: 0 language: en)
Oct 11, 2022 12:23:28.124 [0xad904c50] Debug — Audio Stream: 4549, Subtitle Stream: -1
Oct 11, 2022 12:23:28.125 [0xad904c50] Debug — We're going to try to auto-select an audio stream for account 1.
Oct 11, 2022 12:23:28.125 [0xad904c50] Debug — Selecting best audio stream for part ID 3437 (autoselect: 0 language: en)
Oct 11, 2022 12:23:28.125 [0xad904c50] Debug — Audio Stream: 4550, Subtitle Stream: -1
Oct 11, 2022 12:23:28.126 [0xad904c50] Debug — We're going to try to auto-select an audio stream for account 1.
Oct 11, 2022 12:23:28.127 [0xad904c50] Debug — Selecting best audio stream for part ID 3438 (autoselect: 0 language: en)
Oct 11, 2022 12:23:28.127 [0xad904c50] Debug — Audio Stream: 4551, Subtitle Stream: -1
Oct 11, 2022 12:23:28.128 [0xad904c50] Debug — We're going to try to auto-select an audio stream for account 1.
Oct 11, 2022 12:23:28.128 [0xad904c50] Debug — Selecting best audio stream for part ID 3439 (autoselect: 0 language: en)
Oct 11, 2022 12:23:28.128 [0xad904c50] Debug — Audio Stream: 4552, Subtitle Stream: -1
Oct 11, 2022 12:23:28.129 [0xad904c50] Debug — We're going to try to auto-select an audio stream for account 1.
Oct 11, 2022 12:23:28.129 [0xad904c50] Debug — Selecting best audio stream for part ID 3440 (autoselect: 0 language: en)
Oct 11, 2022 12:23:28.129 [0xad904c50] Debug — Audio Stream: 4553, Subtitle Stream: -1
Oct 11, 2022 12:23:28.130 [0xad904c50] Debug — We're going to try to auto-select an audio stream for account 1.
Oct 11, 2022 12:23:28.130 [0xad904c50] Debug — Selecting best audio stream for part ID 3421 (autoselect: 0 language: en)
Oct 11, 2022 12:23:28.131 [0xad904c50] Debug — Audio Stream: 4535, Subtitle Stream: -1
Oct 11, 2022 12:23:28.131 [0xad904c50] Debug — We're going to try to auto-select an audio stream for account 1.
Oct 11, 2022 12:23:28.131 [0xad904c50] Debug — Selecting best audio stream for part ID 3422 (autoselect: 0 language: en)
Oct 11, 2022 12:23:28.132 [0xad904c50] Debug — Audio Stream: 4541, Subtitle Stream: -1
Oct 11, 2022 12:23:28.132 [0xad904c50] Debug — We're going to try to auto-select an audio stream for account 1.
Oct 11, 2022 12:23:28.133 [0xad904c50] Debug — Selecting best audio stream for part ID 3423 (autoselect: 0 language: en)
Oct 11, 2022 12:23:28.133 [0xad904c50] Debug — Audio Stream: 4534, Subtitle Stream: -1
Oct 11, 2022 12:23:28.134 [0xad904c50] Debug — We're going to try to auto-select an audio stream for account 1.
Oct 11, 2022 12:23:28.134 [0xad904c50] Debug — Selecting best audio stream for part ID 3424 (autoselect: 0 language: en)
Oct 11, 2022 12:23:28.134 [0xad904c50] Debug — Audio Stream: 4536, Subtitle Stream: -1
Oct 11, 2022 12:23:28.135 [0xad904c50] Debug — We're going to try to auto-select an audio stream for account 1.
Oct 11, 2022 12:23:28.135 [0xad904c50] Debug — Selecting best audio stream for part ID 3425 (autoselect: 0 language: en)
Oct 11, 2022 12:23:28.135 [0xad904c50] Debug — Audio Stream: 4537, Subtitle Stream: -1
Oct 11, 2022 12:23:28.136 [0xad904c50] Debug — We're going to try to auto-select an audio stream for account 1.
Oct 11, 2022 12:23:28.136 [0xad904c50] Debug — Selecting best audio stream for part ID 3426 (autoselect: 0 language: en)
Oct 11, 2022 12:23:28.136 [0xad904c50] Debug — Audio Stream: 4538, Subtitle Stream: -1
Oct 11, 2022 12:23:28.145 [0xb257cc50] Debug — Completed: [192.168.100.108:57158] 200 GET /playQueues/2621?window=30¢er=124036&includeFields=thumbBlurHash (16 live) TLS GZIP 215ms 9319 bytes (pipelined: 7)
Plex Media Server Logs_2022-10-11_12-20-34.zip (3.6 MB)
NOTE: I am able to play music to my PlexAmp mobile player (Android) when I am remote so it appears Remote Access is fully functioning in PlexAmp/PMS. Also under Settings -->Remote Access, it shows as ’ Fully accessible outside your network’ and this does not change (Remote Access used to not work until getting all of the NAT ‘hairpinning’ fixed on my home network).
As a workaround to this issue, I have DeAuthorized the Sonos system from Plex Media Server and then gone through that authorization again. Playback to Sonos works well enough for a few hours and then just stops working on PlexAmp, (on home Wifi, remote cellular, and VPN).