Plexamp Raspberry stops music with Plex server update 1.18.0.1913

Plex Labs
1

Okay disclaimer I’m not 100% sure but after updating to 1.18.0.1913 my Plexamp on Raspbian 4.19.71-v7+ stops playing music after anywhere between 20 seconds and a few minutes.

The Plexamp Serverlog shows:

Oct 12, 2019 18:23:24 DEBUG - HTTP: Issuing request to https://172.16.10.153:32400/:/timeline?state=playing&duration=492573&time=54954&buffered=100&playQueueItemID=22862&key=%2Flibrary%2Fmetadata%2F37146&ratingKey=37146&playQueueID=1398&playQueueVersion=2&containerKey=%2FplayQueues%2F1398&hasMDE=1&X-Plex-Device-Name=PLEXAAP
Oct 12, 2019 18:23:25 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 12, 2019 18:23:25 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 12, 2019 18:23:25 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 12, 2019 18:23:25 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 12, 2019 18:23:25 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 12, 2019 18:23:25 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 12, 2019 18:23:25 DEBUG - [TREBLE] Opus: Stream processing has completed.
--- etc ---

After much troubleshooting I’ve downgraded Plexserver to 1.17.0.1841 and the issue did not happen again.

2

Super interesting, any chance you can reproduce and post the server logs from 1.18 which correspond to when playback aborts?

We’ll been investigating a similar-sounding issue, but haven’t managed to reproduce on our end.

3

Sorry did not have time to test this till now.

It looks like it’s not happening again after updating:

Sun Oct 13 16:51:53 CEST 2019
[media@plex ~]$ uname -a
Linux bla-bla 3.10.0-1062.1.2.el7.x86_64 #1 SMP Mon Sep 30 14:19:46 UTC 2019 x86_64 x86_64 x86_64 GNU/Linux

[media@plex ~]$ sudo yum update plexmediaserver
Running transaction
  Updating   : plexmediaserver-1.18.0.1913-e5cc93306.x86_64                                                         1/2
  Cleanup    : plexmediaserver-1.17.0.1841-d42cfa161.x86_64                                                         2/2
  Verifying  : plexmediaserver-1.18.0.1913-e5cc93306.x86_64                                                         1/2
  Verifying  : plexmediaserver-1.17.0.1841-d42cfa161.x86_64                                                         2/2

Updated:
  plexmediaserver.x86_64 0:1.18.0.1913-e5cc93306

Complete!

media@plex ~]$ sudo systemctl status plexmediaserver
● plexmediaserver.service - Plex Media Server
   Loaded: loaded (/usr/lib/systemd/system/plexmediaserver.service; enabled; vendor preset: disabled)
   Active: active (running) since Sun 2019-10-13 16:58:40 CEST; 4s ag

(Now lets play some music and tail -f log file)

[media@plex Logs]$ tail -f Plex\ Media\ Server.log

--- listened to music for about 15 minutes without errors ---

So maybe the downgrade + upgrade fixed it?

4

Okay it seems I was to soon, the issue happened again. This time after switching to another album. I cannot find anything in the Plex Server logs so I’ve added a more complete log from PlexAmp:

yQueueVersion=2&containerKey=%2FplayQueues%2F1416&hasMDE=1&X-Plex-Device-Name=PLEXAAP
Oct 13, 2019 18:09:17 DEBUG - PlayQueue: Saving state PQ 1416 PQID 23190 TIME: 96618
Oct 13, 2019 18:09:22 DEBUG - HTTP: Issuing request to https://172.16.10.153:32400/:/timeline?state=playing&duration=499026&time=102016&buffered=100&playQueueItemID=23190&key=%2Flibrary%2Fmetadata%2F37793&ratingKey=37793&playQueueID=1416&playQueueVersion=2&containerKey=%2FplayQueues%2F1416&hasMDE=1&X-Plex-Device-Name=PLEXAAP
Oct 13, 2019 18:09:22 DEBUG - PlayQueue: Saving state PQ 1416 PQID 23190 TIME: 102016
Oct 13, 2019 18:09:27 DEBUG - HTTP: Issuing request to https://172.16.10.153:32400/:/timeline?state=playing&duration=499026&time=107434&buffered=100&playQueueItemID=23190&key=%2Flibrary%2Fmetadata%2F37793&ratingKey=37793&playQueueID=1416&playQueueVersion=2&containerKey=%2FplayQueues%2F1416&hasMDE=1&X-Plex-Device-Name=PLEXAAP
Oct 13, 2019 18:09:27 DEBUG - PlayQueue: Saving state PQ 1416 PQID 23190 TIME: 107434
Oct 13, 2019 18:09:33 DEBUG - HTTP: Issuing request to https://172.16.10.153:32400/:/timeline?state=playing&duration=499026&time=113024&buffered=100&playQueueItemID=23190&key=%2Flibrary%2Fmetadata%2F37793&ratingKey=37793&playQueueID=1416&playQueueVersion=2&containerKey=%2FplayQueues%2F1416&hasMDE=1&X-Plex-Device-Name=PLEXAAP
Oct 13, 2019 18:09:33 DEBUG - PlayQueue: Saving state PQ 1416 PQID 23190 TIME: 113024
Oct 13, 2019 18:09:38 DEBUG - HTTP: Issuing request to https://172.16.10.153:32400/:/timeline?state=playing&duration=499026&time=118229&buffered=100&playQueueItemID=23190&key=%2Flibrary%2Fmetadata%2F37793&ratingKey=37793&playQueueID=1416&playQueueVersion=2&containerKey=%2FplayQueues%2F1416&hasMDE=1&X-Plex-Device-Name=PLEXAAP
Oct 13, 2019 18:09:38 DEBUG - PlayQueue: Saving state PQ 1416 PQID 23190 TIME: 118229
Oct 13, 2019 18:09:44 DEBUG - HTTP: Issuing request to https://172.16.10.153:32400/:/timeline?state=playing&duration=499026&time=123626&buffered=100&playQueueItemID=23190&key=%2Flibrary%2Fmetadata%2F37793&ratingKey=37793&playQueueID=1416&playQueueVersion=2&containerKey=%2FplayQueues%2F1416&hasMDE=1&X-Plex-Device-Name=PLEXAAP
Oct 13, 2019 18:09:44 DEBUG - PlayQueue: Saving state PQ 1416 PQID 23190 TIME: 123626
Oct 13, 2019 18:09:49 DEBUG - HTTP: Issuing request to https://172.16.10.153:32400/:/timeline?state=playing&duration=499026&time=129024&buffered=100&playQueueItemID=23190&key=%2Flibrary%2Fmetadata%2F37793&ratingKey=37793&playQueueID=1416&playQueueVersion=2&containerKey=%2FplayQueues%2F1416&hasMDE=1&X-Plex-Device-Name=PLEXAAP
Oct 13, 2019 18:09:49 DEBUG - PlayQueue: Saving state PQ 1416 PQID 23190 TIME: 129024
Oct 13, 2019 18:09:54 DEBUG - HTTP: Issuing request to https://172.16.10.153:32400/:/timeline?state=playing&duration=499026&time=134229&buffered=100&playQueueItemID=23190&key=%2Flibrary%2Fmetadata%2F37793&ratingKey=37793&playQueueID=1416&playQueueVersion=2&containerKey=%2FplayQueues%2F1416&hasMDE=1&X-Plex-Device-Name=PLEXAAP
Oct 13, 2019 18:09:54 DEBUG - PlayQueue: Saving state PQ 1416 PQID 23190 TIME: 134229
Oct 13, 2019 18:09:59 DEBUG - HTTP: Issuing request to https://172.16.10.153:32400/:/timeline?state=playing&duration=499026&time=139434&buffered=100&playQueueItemID=23190&key=%2Flibrary%2Fmetadata%2F37793&ratingKey=37793&playQueueID=1416&playQueueVersion=2&containerKey=%2FplayQueues%2F1416&hasMDE=1&X-Plex-Device-Name=PLEXAAP
Oct 13, 2019 18:09:59 DEBUG - PlayQueue: Saving state PQ 1416 PQID 23190 TIME: 139434
Oct 13, 2019 18:10:04 DEBUG - METRICS: Ending last track forcefully with status stopped
Oct 13, 2019 18:10:04 DEBUG - HTTP: Issuing request to https://172.16.10.153:32400/playQueues/1417?includeLoudnessRamps=1&own=1&X-Plex-Device-Name=PLEXAAP
Oct 13, 2019 18:10:04 DEBUG - PlayQueue: Loaded play queue.
Oct 13, 2019 18:10:04 DEBUG - PlayQueue: Processing PQ 1417 (full: true, play after: false).
Oct 13, 2019 18:10:04 DEBUG - Audio: Setting play queue to 666f6bce11772ca47a4fba2ba72241dcfd306a6f /playQueues/1417
Oct 13, 2019 18:10:04 DEBUG - PlayQueue: Play queue: 23193,23194
Oct 13, 2019 18:10:04 DEBUG - PlayQueue: Audio queue: 23190,23191
Oct 13, 2019 18:10:04 DEBUG - PlayQueue: Adding both current and next track.
Oct 13, 2019 18:10:04 DEBUG - PlayQueue: Stop.
Oct 13, 2019 18:10:04 DEBUG - PlayQueue: no offset for track.
Oct 13, 2019 18:10:04 DEBUG - PlayQueue: Adding track 23193 to audio player (gapless: true).
Oct 13, 2019 18:10:04 DEBUG - PlayQueue: no offset for track.
Oct 13, 2019 18:10:04 DEBUG - PlayQueue: Adding track 23194 to audio player (gapless: true).
Oct 13, 2019 18:10:04 DEBUG - PlayQueue: Final AudioQueue: 23193,23194.
Oct 13, 2019 18:10:04 DEBUG - GET /player/playback/playMedia?providerIdentifier=com.plexapp.plugins.library&protocol=https&containerKey=%2FplayQueues%2F1417%3Fown%3D1&address=172-16-10-153.80374c65e9f640a3a486d131faaffde7.plex.direct&port=32400&token=<token>-70d83801-0c15-43d2-afad-1c6604ea8204&key=%2Flibrary%2Fmetadata%2F31994&offset=0&machineIdentifier=666f6bce11772ca47a4fba2ba72241dcfd306a6f&type=music&commandID=2 200 2 - 200.222 ms
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] BASS: Stop.
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] Cache: Asked to close file.
Oct 13, 2019 18:10:04 DEBUG - Audio: State changed from playing to buffering
Oct 13, 2019 18:10:04 DEBUG - Audio: ID changed from 23190 to 23193
Oct 13, 2019 18:10:04 DEBUG - HTTP: Issuing request to https://172.16.10.153:32400/:/timeline?state=buffering&duration=393500&playQueueItemID=23193&key=%2Flibrary%2Fmetadata%2F31994&ratingKey=31994&playQueueID=1417&playQueueVersion=2&containerKey=%2FplayQueues%2F1417&hasMDE=1&X-Plex-Device-Name=PLEXAAP
Oct 13, 2019 18:10:04 DEBUG - PlayQueue: Saving state PQ 1417 PQID 23193 TIME: 0
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] Cache: Ending network read thread.
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] BASS: Deregister stream 23190 (channel 0x80000018), 2 remaining.
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] BASS: Pausing stream 23190 (channel: 0x80000018) with fade, kill: 1
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] BASS: Getting rid of stream 23191 (channel: 0x8000001d).
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] BASS: Finally deleting channel 0x8000000d.
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] BASS: Deregister stream 23180 (channel 0x8000000d), 1 remaining.
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] BASS: Deleting audio stream with channel 0x8000000d.
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] Cache: Deleting file reader.
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] BASS: Channel 0x8000000d freed.
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] BASS: Saving channel 0x8000001d to be deleted next.
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] Cache: Asked to close file.
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] Cache: Ending network read thread.
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] BASS: Deregister stream 23191 (channel 0x8000001d), 0 remaining.
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] Play Queue: Set item 23191 active to 0, 0 active remaining.
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] Play Queue: Looking for 2 items ahead (total of 0) to pre-cache with network type of -1
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] BASS: Deleting audio stream with channel 0x8000001d.
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] Cache: Deleting file reader.
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] Play Queue: Set item 23193 active to 1, 1 active remaining.
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] BASS: Queueing track (now 1 total) with identifier 23193, gain -6.4 dB, overlap duration 0 ms, start offset 0 ms (paused: 0).
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] Play Queue: Setting current item to 23193.
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] BASS: Opening stream 23193 with max bitrate of 0.
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] Media: Couldn't find item with supported codec and bitrate under 0, transcoding.
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] BASS: Opening stream 23193 (opus, 128 Kbps)
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] Cache: Opened cache file with 0 bytes (of 0 total) at '/home/pi/Library/Caches/Media/7d5fd377fff76ae537eea523e7df8e52e615a4de927d225948c431ed390906e2'.
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] Cache: Opened file: https://172-16-10-153.80374c65e9f640a3a486d131faaffde7.plex.direct:32400/audio/:/transcode/universal/start?directPlay=0&musicBitrate=128&path=%2Flibrary%2Fmetadata%2F31994&X-Plex-Chunked=1&X-Plex-Client-Identifier=%3F%3F%3F&X-Plex-Client-Profile-Extra=add-transcode-target(replace%3Dtrue%26type%3DmusicProfile%26context%3Dstreaming%26protocol%3Dhttp%26container%3Dogg%26audioCodec%3Dopus)%2Badd-limitation(scope%3DmusicCodec%26scopeName%3Dopus%26type%3DupperBound%26name%3Daudio%2Echannels%26value%3D2%26onlyTranscodes%3Dtrue%26replace%3Dtrue)&X-Plex-Device=Linux&X-Plex-Device-Name=PLEXAAP&X-Plex-Platform=Plexamp&X-Plex-Platform-Version=v9%2E11%2E2&X-Plex-Product=Plexamp&X-Plex-Session-Identifier=7d5fd377fff76ae537eea523e7df8e52e615a4de927d225948c431ed390906e223193&X-Plex-Token=jT46N-ZXT6KmDsjV7N5Q&X-Plex-Version=1%2E0
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] Cache: Kicking off buffering thread at offset 0, disk cache has 0 / 0.
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] Play Queue: Set item 23194 active to 1, 2 active remaining.
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] BASS: Queueing track (now 2 total) with identifier 23194, gain -6.4 dB, overlap duration 0 ms, start offset 0 ms (paused: 1).
Oct 13, 2019 18:10:04 DEBUG - METRICS: Sending event: playback:itemend to https://analytics.plex.tv
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] BASS: Channel 0x80000018 has been faded out and now we'll kill it.
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] BASS: Getting rid of stream 23190 (channel: 0x80000018).
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] BASS: Finally deleting channel 0x8000001d.
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] BASS: Channel 0x8000001d freed.
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] BASS: Saving channel 0x80000018 to be deleted next.
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] BASS: Deregister stream 23190 (channel 0x80000018), 4294967295 remaining.
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] Play Queue: Set item 23190 active to 0, 2 active remaining.
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] BASS: Deleting audio stream with channel 0x80000018.
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] Cache: Deleting file reader.
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] Cache: HTTP response code was 200.
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] Cache: READ underflowed at 0 bytes, will go into buffering state.
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] Cache: HTTP response did not have a size.
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] Opus: The stream has 2 channels, bitrate of -1.
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] BASS: Opened stream 23193 (channel 0x80000023) in 246 ms.
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] BASS: Register stream 23193 (channel 0x80000023), 1 total.
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] BASS: Stream 23193 is 18 KB/sec (stream reported 16 KB/sec).
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] BASS: Added stream 23193 (channel: 0x80000023) to the mixer (paused: 0).
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] Cache: Buffering until offset reaches 100352 (read buffer offset: 18432, size: 81920).
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] BASS: Reading first bytes to ensure stream 23193 is warmed up.
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] BASS: Channel 0x80000023 changed buffering state to 1.
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] Cache: Buffering complete as offset is now greater than 100352 (102470).
Oct 13, 2019 18:10:04 DEBUG - Audio: State changed from buffering to playing
Oct 13, 2019 18:10:04 DEBUG - HTTP: Issuing request to https://172.16.10.153:32400/:/timeline?state=playing&duration=393500&buffered=1&playQueueItemID=23193&key=%2Flibrary%2Fmetadata%2F31994&ratingKey=31994&playQueueID=1417&playQueueVersion=2&containerKey=%2FplayQueues%2F1417&hasMDE=1&X-Plex-Device-Name=PLEXAAP
Oct 13, 2019 18:10:04 DEBUG - PlayQueue: Saving state PQ 1417 PQID 23193 TIME: 0
Oct 13, 2019 18:10:04 DEBUG - [TREBLE] BASS: Channel 0x80000023 changed buffering state to 0.
Oct 13, 2019 18:10:04 DEBUG - METRICS: Starting new track.
Oct 13, 2019 18:10:04 DEBUG - METRICS: Sending event: playback:itemstart to https://analytics.plex.tv
Oct 13, 2019 18:10:06 DEBUG - [TREBLE] Cache: Network buffer is now ahead of read buffer, which has 2097152 bytes. Godspeed.
Oct 13, 2019 18:10:08 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:08 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:08 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:08 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:08 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:08 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:08 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:08 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:08 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:08 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:08 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:08 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:08 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:08 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:08 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:08 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:08 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:08 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:09 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:09 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:09 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:09 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:09 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:09 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:09 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:09 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:09 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:09 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:09 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:09 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:09 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:09 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:09 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:09 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:09 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:09 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:09 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:09 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:09 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:09 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:09 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:09 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:09 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:09 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:09 DEBUG - HTTP: Issuing request to https://172.16.10.153:32400/:/timeline?state=playing&duration=393500&time=3989&buffered=91&playQueueItemID=23193&key=%2Flibrary%2Fmetadata%2F31994&ratingKey=31994&playQueueID=1417&playQueueVersion=2&containerKey=%2FplayQueues%2F1417&hasMDE=1&X-Plex-Device-Name=PLEXAAP
Oct 13, 2019 18:10:09 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:09 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:09 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:09 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:09 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:09 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:09 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:09 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:10 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:10 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:10 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:10 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:10 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:10 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:10 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:10 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:10 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:10 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:10 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:10 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:10 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:10 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:10 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:10 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:10 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:10 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:10 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:10 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:10 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:10 DEBUG - [TREBLE] Cache: End of file on a resource of unknown length, treated it as complete with 6775491 bytes. Awesome!
Oct 13, 2019 18:10:10 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:10 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:10 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:10 DEBUG - [TREBLE] BASS: Stream 23193 completed caching.
Oct 13, 2019 18:10:10 DEBUG - [TREBLE] Play Queue: Set item 23193 active to 0, 1 active remaining.
Oct 13, 2019 18:10:10 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:10 DEBUG - [TREBLE] BASS: Pre-caching next stream 23194
Oct 13, 2019 18:10:10 DEBUG - [TREBLE] BASS: Opening stream 23194 with max bitrate of 0.
Oct 13, 2019 18:10:10 DEBUG - [TREBLE] Media: Couldn't find item with supported codec and bitrate under 0, transcoding.
Oct 13, 2019 18:10:10 DEBUG - [TREBLE] BASS: Opening stream 23194 (opus, 128 Kbps)
Oct 13, 2019 18:10:10 DEBUG - [TREBLE] Cache: Opened cache file with 0 bytes (of 0 total) at '/home/pi/Library/Caches/Media/1b1c83b3002375d8be62b2c852ed63cc51d6b33a7714ddc138dd2e7a4c97f0d7'.
Oct 13, 2019 18:10:10 DEBUG - [TREBLE] Cache: Opened file: https://172-16-10-153.80374c65e9f640a3a486d131faaffde7.plex.direct:32400/audio/:/transcode/universal/start?directPlay=0&musicBitrate=128&path=%2Flibrary%2Fmetadata%2F31995&X-Plex-Chunked=1&X-Plex-Client-Identifier=%3F%3F%3F&X-Plex-Client-Profile-Extra=add-transcode-target(replace%3Dtrue%26type%3DmusicProfile%26context%3Dstreaming%26protocol%3Dhttp%26container%3Dogg%26audioCodec%3Dopus)%2Badd-limitation(scope%3DmusicCodec%26scopeName%3Dopus%26type%3DupperBound%26name%3Daudio%2Echannels%26value%3D2%26onlyTranscodes%3Dtrue%26replace%3Dtrue)&X-Plex-Device=Linux&X-Plex-Device-Name=PLEXAAP&X-Plex-Platform=Plexamp&X-Plex-Platform-Version=v9%2E11%2E2&X-Plex-Product=Plexamp&X-Plex-Session-Identifier=1b1c83b3002375d8be62b2c852ed63cc51d6b33a7714ddc138dd2e7a4c97f0d723194&X-Plex-Token=jT46N-ZXT6KmDsjV7N5Q&X-Plex-Version=1%2E0
Oct 13, 2019 18:10:10 DEBUG - [TREBLE] Cache: Kicking off buffering thread at offset 0, disk cache has 0 / 0.
Oct 13, 2019 18:10:10 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:10 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:10 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:10 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:10 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:10 DEBUG - [TREBLE] Cache: HTTP response code was 200.
Oct 13, 2019 18:10:10 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:10 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:10 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:11 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:11 DEBUG - [TREBLE] Cache: READ underflowed at 0 bytes, will go into buffering state.
Oct 13, 2019 18:10:11 DEBUG - [TREBLE] Cache: HTTP response did not have a size.
Oct 13, 2019 18:10:11 DEBUG - [TREBLE] Opus: The stream has 2 channels, bitrate of -1.
Oct 13, 2019 18:10:11 DEBUG - [TREBLE] BASS: Opened stream 23194 (channel 0x80000028) in 265 ms.
Oct 13, 2019 18:10:11 DEBUG - [TREBLE] BASS: Register stream 23194 (channel 0x80000028), 2 total.
Oct 13, 2019 18:10:11 DEBUG - [TREBLE] BASS: Stream 23194 is 18 KB/sec (stream reported 16 KB/sec).
Oct 13, 2019 18:10:11 DEBUG - [TREBLE] BASS: Added stream 23194 (channel: 0x80000028) to the mixer (paused: 1).
Oct 13, 2019 18:10:11 DEBUG - [TREBLE] Cache: Buffering until offset reaches 98304 (read buffer offset: 16384, size: 81920).
Oct 13, 2019 18:10:11 DEBUG - [TREBLE] BASS: Reading first bytes to ensure stream 23194 is warmed up.
Oct 13, 2019 18:10:11 DEBUG - [TREBLE] BASS: Channel 0x80000028 changed buffering state to 1.
Oct 13, 2019 18:10:11 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:11 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:11 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:11 DEBUG - [TREBLE] Cache: Buffering complete as offset is now greater than 98304 (103950).
Oct 13, 2019 18:10:11 DEBUG - [TREBLE] BASS: Channel 0x80000028 changed buffering state to 0.
Oct 13, 2019 18:10:11 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:11 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:11 DEBUG - [TREBLE] Opus: Stream processing has completed.
Oct 13, 2019 18:10:11 DEBUG - [TREBLE] Opus: Stream processing has completed.

this goes on forever until I restart PlexAmp.
5

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