Plexamp Headless - Error loading cloud players from plex.tv

Plex Labs
1

Hi all,

I am trying to install Plexamp Headless on a Raspberry Pi 4 with a HifiBerry Dac2 HD.

Used Raspberry Pi Imager to put Raspberry OS Lite on a 128gb sd card.

I ssh into it. Updated and changed the /boot/config.txt in accordance to HifiBerry https://www.hifiberry.com/docs/software/configuring-linux-3-18-x/

Then after reboot:

It tells me to visit https://plex.tv/claim and I copied the token and insert it and I get the error:

Schermata da 2022-08-11 19-16-41
Schermata da 2022-08-11 19-16-411135×189 31.1 KB

Its claims that Plexamp is now signed and ready. So, I do the final steps:

$ sudo cp plexamp.service /lib/systemd/system/
$ sudo systemctl daemon-reload
$ sudo systemctl enable plexamp
$ sudo systemctl start plexamp

In a browser, I type in the url: http://:32500 and I get the log in page and after that, I indeed reach my Plex Media Server:

Schermata da 2022-08-11 19-10-30
Schermata da 2022-08-11 19-10-301007×497 17.1 KB

However, when I click on any song, it is just blank, wont play anything:

Schermata da 2022-08-11 18-24-10
Schermata da 2022-08-11 18-24-101187×421 57 KB

The logs ~/.cache/Plexamp/log/Plexamp.log is an endless loop of the folowing:

Aug 11, 2022 18:53:48.762 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 11, 2022 18:53:53.767 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc

Any help would be most appreciated.

Joaquim

2

This is fixed in the next release, but select the cast icon from top right and select the player you wish to control.

3

Thanks Elan for the reply. I tried the Cast icon on the top right corner and selected RPi selected but the problem persists. Great news that this has been fixed when the next release comes. Let me know if I can help in anyway.

When the release does come, is there a simply way to update it or I should install it from scratch?

4

Then we’ll need logs; the websocket issue can be ignored.

You can just overwrite the existing install and restart the service.

5

These are the logs when I click the Cast icon and then select a song:

<pre>Aug 12, 2022 10:48:49.012 [Javascript] INFO - HttpServer: [192.168.1.61] OPTIONS /resources 
Aug 12, 2022 10:48:49.015 [Javascript] INFO - HttpServer: [192.168.1.61] GET /resources 
Aug 12, 2022 10:48:49.112 [Javascript] INFO - HttpServer: [192.168.1.61] OPTIONS /resources 
Aug 12, 2022 10:48:49.117 [Javascript] INFO - HttpServer: [192.168.1.61] GET /resources 
Aug 12, 2022 10:48:49.132 [Javascript] INFO - HttpServer: [192.168.1.61] OPTIONS /resources 
Aug 12, 2022 10:48:49.135 [Javascript] INFO - HttpServer: [192.168.1.61] OPTIONS /resources 
Aug 12, 2022 10:48:49.137 [Javascript] INFO - HttpServer: [192.168.1.61] GET /resources 
Aug 12, 2022 10:48:49.142 [Javascript] INFO - HttpServer: [192.168.1.61] GET /resources 
Aug 12, 2022 10:48:54.015 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 10:48:54.016 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 10:48:59.024 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 10:48:59.024 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 10:49:04.029 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 10:49:04.029 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 10:49:09.034 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 10:49:09.035 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 10:49:12.437 [Javascript] INFO - HttpServer: [192.168.1.61] GET /player/playback/createPlayQueue source=80256acc7d74d11bfe9bdb305b965f7733583580&amp;uri=server%3A%2F%2F80256acc7d74d11bfe9bdb305b965f7733583580%2Fcom.plexapp.plugins.library%2Flibrary%2Fmetadata%2F2144&amp;key=%2Flibrary%2Fmetadata%2F2148&amp;token=&lt;token&gt;&amp;includeExternalMedia=1&amp;type=audio&amp;protocol=https&amp;address=192-168-1-53.a36bc24d8bae4942811a22f18e9ebc41.plex.direct&amp;port=32400&amp;machineIdentifier=80256acc7d74d11bfe9bdb305b965f7733583580&amp;commandID=40
Aug 12, 2022 10:49:12.448 [Javascript] INFO - PLAYER: Issuing play for music from server 80256acc7d74d11bfe9bdb305b965f7733583580
Aug 12, 2022 10:49:12.456 [Javascript] DEBUG - PlayQueue: Processing STOP
Aug 12, 2022 10:49:12.457 [Javascript] INFO - PlayQueue: Stop.
Aug 12, 2022 10:49:12.458 [0xaf70c440] INFO - BASS: Stop.
Aug 12, 2022 10:49:12.458 [0xaf70c440] INFO - PQCache: Clearing.
Aug 12, 2022 10:49:12.458 [0xaf70c440] DEBUG - BASS: Stopped in 0 ms.
Aug 12, 2022 10:49:12.460 [Javascript] DEBUG - PlayQueue: Processing send timelines in state stopped time -1000.
Aug 12, 2022 10:49:12.461 [Javascript] INFO - PlayQueue: Processing play media.
Aug 12, 2022 10:49:12.471 [0xb6f0d040] DEBUG - Networking: Create request 40: (POST https://192-168-1-53.a36bc24d8bae4942811a22f18e9ebc41.plex.direct:32400/playQueues?includeLoudnessRamps=1&amp;uri=server%3A%2F%2F80256acc7d74d11bfe9bdb305b965f7733583580%2Fcom.plexapp.plugins.library%2Flibrary%2Fmetadata%2F2144&amp;key=%2Flibrary%2Fmetadata%2F2148&amp;includeExternalMedia=1&amp;type=audio&amp;commandID=40&amp;includeFields=thumbBlurHash&amp;X-Plex-Device-Name=Harmonia).
Aug 12, 2022 10:49:12.579 [0xad708440] DEBUG - Networking: Completed request 40: (POST https://192-168-1-53.a36bc24d8bae4942811a22f18e9ebc41.plex.direct:32400/playQueues?includeLoudnessRamps=1&amp;uri=server%3A%2F%2F80256acc7d74d11bfe9bdb305b965f7733583580%2Fcom.plexapp.plugins.library%2Flibrary%2Fmetadata%2F2144&amp;key=%2Flibrary%2Fmetadata%2F2148&amp;includeExternalMedia=1&amp;type=audio&amp;commandID=40&amp;includeFields=thumbBlurHash&amp;X-Plex-Device-Name=Harmonia) with code 200 (have 23916 bytes) in 108 ms.
Aug 12, 2022 10:49:12.582 [Javascript] INFO - PlayQueue: Saving to storage.
Aug 12, 2022 10:49:12.584 [Javascript] INFO - PlayQueue: Processing PQ 80256acc7d74d11bfe9bdb305b965f7733583580 880 with 10 items (full: true, play after: true), selected 40113 at offset 3.
Aug 12, 2022 10:49:12.586 [Javascript] INFO - PlayQueue: Looked at all the tracks and consecutive: true.
Aug 12, 2022 10:49:12.587 [Javascript] INFO - Audio: Effective repeat mode is: undefined from mode: undefined play queue length: 10
Aug 12, 2022 10:49:12.589 [Javascript] DEBUG - PlayQueue: Audio queue: 
Aug 12, 2022 10:49:12.590 [Javascript] DEBUG - PlayQueue: Play queue: 40113,40114
Aug 12, 2022 10:49:12.590 [Javascript] DEBUG - PlayQueue: Adding both current and next track.
Aug 12, 2022 10:49:12.591 [0xadf09440] INFO - BASS: Stop.
Aug 12, 2022 10:49:12.591 [0xadf09440] INFO - PQCache: Clearing.
Aug 12, 2022 10:49:12.591 [0xadf09440] DEBUG - BASS: Stopped in 0 ms.
Aug 12, 2022 10:49:12.592 [Javascript] DEBUG - PlayQueue: Adding track 40113 to audio player, gapless true gain -4.1 looping false.
Aug 12, 2022 10:49:12.597 [0xaf70c440] INFO - PQCache: Set item 40113 active to 1, 1 active remaining.
Aug 12, 2022 10:49:12.597 [0xaf70c440] INFO - BASS: Queueing stream (1 total, 0 handles) with identifier 40113, gain -4.1 dB, overlap duration 0 ms, start offset 0 ms (paused: 0).
Aug 12, 2022 10:49:12.597 [0xaf70c440] INFO - Media: Found an item to direct play with bitrate under 0 (it was 2516 kbps).
</pre>

Just to confirm, delete the plexamp folder, unzip the new version cd /plexamp and node js/index.js and then sudo systemctl restart plexamp.

6

please zip up all the logs, snippets are rarely enough.

7

Below are all the logs before I clicked the Cast icon:

Aug 12, 2022 10:47:43.930 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 10:47:43.931 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 10:47:48.936 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 10:47:48.937 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 10:47:53.942 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 10:47:53.943 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 10:47:58.947 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 10:47:58.948 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 10:48:03.953 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 10:48:03.954 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 10:48:08.959 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 10:48:08.960 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 10:48:13.965 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 10:48:13.965 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 10:48:18.971 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 10:48:18.972 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 10:48:23.976 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 10:48:23.976 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 10:48:28.981 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 10:48:28.981 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 10:48:33.516 [Javascript] INFO - HttpServer: [192.168.1.61] GET / 
Aug 12, 2022 10:48:33.594 [Javascript] INFO - HttpServer: [192.168.1.61] GET /index.js 
Aug 12, 2022 10:48:33.985 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 10:48:33.985 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 10:48:34.943 [Javascript] INFO - HttpServer: [192.168.1.61] GET /settings 
Aug 12, 2022 10:48:34.951 [Javascript] INFO - HttpServer: [192.168.1.61] GET /resources 
Aug 12, 2022 10:48:34.973 [Javascript] INFO - HttpServer: [192.168.1.61] OPTIONS /resources 
Aug 12, 2022 10:48:35.042 [Javascript] INFO - HttpServer: [192.168.1.61] PUT /settings name=equalizerValues&value=0,0,0,0,0,0,0,0,0,0
Aug 12, 2022 10:48:35.055 [Javascript] INFO - HTTP: Setting equalizerValues of type object to 0,0,0,0,0,0,0,0,0,0
Aug 12, 2022 10:48:35.067 [Javascript] INFO - HttpServer: [192.168.1.61] GET /resources 
Aug 12, 2022 10:48:35.115 [Javascript] INFO - HttpServer: [192.168.1.61] OPTIONS /resources 
Aug 12, 2022 10:48:35.120 [Javascript] INFO - HttpServer: [192.168.1.61] OPTIONS /resources 
Aug 12, 2022 10:48:35.122 [Javascript] INFO - HttpServer: [192.168.1.61] GET /resources 
Aug 12, 2022 10:48:35.130 [Javascript] INFO - HttpServer: [192.168.1.61] GET /resources 
Aug 12, 2022 10:48:35.141 [Javascript] INFO - HttpServer: [192.168.1.61] GET /settings/values name=audioDeviceUuid
Aug 12, 2022 10:48:38.990 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 10:48:38.991 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 10:48:42.675 [Javascript] INFO - HttpServer: [192.168.1.61] GET /colors/extract source=80256acc7d74d11bfe9bdb305b965f7733583580&endpoint=/library/metadata/11858/thumb/1629513577
Aug 12, 2022 10:48:42.681 [0xb6f0d040] DEBUG - Networking: Create request 39: (GET https://192-168-1-53.a36bc24d8bae4942811a22f18e9ebc41.plex.direct:32400/photo/:/transcode?width=150&height=150&url=%2Flibrary%2Fmetadata%2F11858%2Fthumb%2F1629513577&format=png&X-Plex-Token=<token>).
Aug 12, 2022 10:48:42.799 [0xad708440] DEBUG - Networking: Completed request 39: (GET https://192-168-1-53.a36bc24d8bae4942811a22f18e9ebc41.plex.direct:32400/photo/:/transcode?width=150&height=150&url=%2Flibrary%2Fmetadata%2F11858%2Fthumb%2F1629513577&format=png&X-Plex-Token=<token>) with code 200 (have 40006 bytes) in 117 ms.
Aug 12, 2022 10:48:43.108 [Javascript] INFO - IMAGE: Extracted palette #BDD0BB,#1E1F38,#658F9A,#384C75,#E0B339,#C7D8BD,#273A39,#40707D,#769298,#A2C6B5,#5C9177,#17392F,#4B6339,#CFAB2D,#909039,#6D9B79,#4A7765,#AB9A2F,#647F39,#394939.
Aug 12, 2022 10:48:43.994 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 10:48:43.994 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 10:48:48.994 [Javascript] INFO - HttpServer: [192.168.1.61] GET /resources 
Aug 12, 2022 10:48:48.998 [Javascript] INFO - HttpServer: [192.168.1.61] GET /resources 
Aug 12, 2022 10:48:49.011 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 10:48:49.011 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 10:48:49.012 [Javascript] INFO - HttpServer: [192.168.1.61] OPTIONS /resources 
Aug 12, 2022 10:48:49.015 [Javascript] INFO - HttpServer: [192.168.1.61] GET /resources 
Aug 12, 2022 10:48:49.112 [Javascript] INFO - HttpServer: [192.168.1.61] OPTIONS /resources 
Aug 12, 2022 10:48:49.117 [Javascript] INFO - HttpServer: [192.168.1.61] GET /resources 
Aug 12, 2022 10:48:49.132 [Javascript] INFO - HttpServer: [192.168.1.61] OPTIONS /resources 
Aug 12, 2022 10:48:49.135 [Javascript] INFO - HttpServer: [192.168.1.61] OPTIONS /resources 
Aug 12, 2022 10:48:49.137 [Javascript] INFO - HttpServer: [192.168.1.61] GET /resources 
Aug 12, 2022 10:48:49.142 [Javascript] INFO - HttpServer: [192.168.1.61] GET /resources 
Aug 12, 2022 10:48:54.015 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 10:48:54.016 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 10:48:59.024 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 10:48:59.024 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 10:49:04.029 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 10:49:04.029 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 10:49:09.034 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 10:49:09.035 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 10:49:12.437 [Javascript] INFO - HttpServer: [192.168.1.61] GET /player/playback/createPlayQueue source=80256acc7d74d11bfe9bdb305b965f7733583580&uri=server%3A%2F%2F80256acc7d74d11bfe9bdb305b965f7733583580%2Fcom.plexapp.plugins.library%2Flibrary%2Fmetadata%2F2144&key=%2Flibrary%2Fmetadata%2F2148&token=<token>&includeExternalMedia=1&type=audio&protocol=https&address=192-168-1-53.a36bc24d8bae4942811a22f18e9ebc41.plex.direct&port=32400&machineIdentifier=80256acc7d74d11bfe9bdb305b965f7733583580&commandID=40
Aug 12, 2022 10:49:12.448 [Javascript] INFO - PLAYER: Issuing play for music from server 80256acc7d74d11bfe9bdb305b965f7733583580
Aug 12, 2022 10:49:12.456 [Javascript] DEBUG - PlayQueue: Processing STOP
Aug 12, 2022 10:49:12.457 [Javascript] INFO - PlayQueue: Stop.
Aug 12, 2022 10:49:12.458 [0xaf70c440] INFO - BASS: Stop.
Aug 12, 2022 10:49:12.458 [0xaf70c440] INFO - PQCache: Clearing.
Aug 12, 2022 10:49:12.458 [0xaf70c440] DEBUG - BASS: Stopped in 0 ms.
Aug 12, 2022 10:49:12.460 [Javascript] DEBUG - PlayQueue: Processing send timelines in state stopped time -1000.
Aug 12, 2022 10:49:12.461 [Javascript] INFO - PlayQueue: Processing play media.
Aug 12, 2022 10:49:12.471 [0xb6f0d040] DEBUG - Networking: Create request 40: (POST https://192-168-1-53.a36bc24d8bae4942811a22f18e9ebc41.plex.direct:32400/playQueues?includeLoudnessRamps=1&uri=server%3A%2F%2F80256acc7d74d11bfe9bdb305b965f7733583580%2Fcom.plexapp.plugins.library%2Flibrary%2Fmetadata%2F2144&key=%2Flibrary%2Fmetadata%2F2148&includeExternalMedia=1&type=audio&commandID=40&includeFields=thumbBlurHash&X-Plex-Device-Name=Harmonia).
Aug 12, 2022 10:49:12.579 [0xad708440] DEBUG - Networking: Completed request 40: (POST https://192-168-1-53.a36bc24d8bae4942811a22f18e9ebc41.plex.direct:32400/playQueues?includeLoudnessRamps=1&uri=server%3A%2F%2F80256acc7d74d11bfe9bdb305b965f7733583580%2Fcom.plexapp.plugins.library%2Flibrary%2Fmetadata%2F2144&key=%2Flibrary%2Fmetadata%2F2148&includeExternalMedia=1&type=audio&commandID=40&includeFields=thumbBlurHash&X-Plex-Device-Name=Harmonia) with code 200 (have 23916 bytes) in 108 ms.
Aug 12, 2022 10:49:12.582 [Javascript] INFO - PlayQueue: Saving to storage.
Aug 12, 2022 10:49:12.584 [Javascript] INFO - PlayQueue: Processing PQ 80256acc7d74d11bfe9bdb305b965f7733583580 880 with 10 items (full: true, play after: true), selected 40113 at offset 3.
Aug 12, 2022 10:49:12.586 [Javascript] INFO - PlayQueue: Looked at all the tracks and consecutive: true.
Aug 12, 2022 10:49:12.587 [Javascript] INFO - Audio: Effective repeat mode is: undefined from mode: undefined play queue length: 10
Aug 12, 2022 10:49:12.589 [Javascript] DEBUG - PlayQueue: Audio queue: 
Aug 12, 2022 10:49:12.590 [Javascript] DEBUG - PlayQueue: Play queue: 40113,40114
Aug 12, 2022 10:49:12.590 [Javascript] DEBUG - PlayQueue: Adding both current and next track.
Aug 12, 2022 10:49:12.591 [0xadf09440] INFO - BASS: Stop.
Aug 12, 2022 10:49:12.591 [0xadf09440] INFO - PQCache: Clearing.
Aug 12, 2022 10:49:12.591 [0xadf09440] DEBUG - BASS: Stopped in 0 ms.
Aug 12, 2022 10:49:12.592 [Javascript] DEBUG - PlayQueue: Adding track 40113 to audio player, gapless true gain -4.1 looping false.
Aug 12, 2022 10:49:12.597 [0xaf70c440] INFO - PQCache: Set item 40113 active to 1, 1 active remaining.
Aug 12, 2022 10:49:12.597 [0xaf70c440] INFO - BASS: Queueing stream (1 total, 0 handles) with identifier 40113, gain -4.1 dB, overlap duration 0 ms, start offset 0 ms (paused: 0).
Aug 12, 2022 10:49:12.597 [0xaf70c440] INFO - Media: Found an item to direct play with bitrate under 0 (it was 2516 kbps).
8

These logs are from the moment I turn on the RPi and I can ssh into it. Then on a browser I log in, choose the folder from the server and click the Cast icon:

 joaquim@athena  ~  ssh pi@192.168.1.62
pi@192.168.1.62's password: 
Linux raspberrypi 5.15.32-v7l+ #1538 SMP Thu Mar 31 19:39:41 BST 2022 armv7l

The programs included with the Debian GNU/Linux system are free software;
the exact distribution terms for each program are described in the
individual files in /usr/share/doc/*/copyright.

Debian GNU/Linux comes with ABSOLUTELY NO WARRANTY, to the extent
permitted by applicable law.
Last login: Fri Aug 12 13:01:35 2022
pi@raspberrypi:~ $ tail -f ~/.cache/Plexamp/log/Plexamp.log
Aug 12, 2022 13:01:55.000 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:02:00.004 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:02:00.004 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:02:05.012 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:02:05.013 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:02:08.996 [Javascript] INFO - DEVICE: Persisting devices because CPH2307 was dirty.
Aug 12, 2022 13:02:08.996 [Javascript] INFO - DEVICE: Persisting 10 devices.
Aug 12, 2022 13:02:09.309 [Javascript] INFO - Sync: Error cleaning up: ENOENT: no such file or directory, scandir '/home/pi/.local/share/Plexamp/Offline'.
Aug 12, 2022 13:02:10.018 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:02:10.018 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:02:15.027 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:02:15.028 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:02:20.032 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:02:20.033 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:02:25.039 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:02:25.039 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:02:30.043 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:02:30.044 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:02:35.049 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:02:35.049 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:02:40.055 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:02:40.055 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:02:42.897 [Javascript] INFO - HttpServer: [192.168.1.61] GET / 
Aug 12, 2022 13:02:42.954 [Javascript] INFO - HttpServer: [192.168.1.61] GET /index.js 
Aug 12, 2022 13:02:45.006 [Javascript] INFO - HttpServer: [192.168.1.61] GET /settings 
Aug 12, 2022 13:02:45.013 [Javascript] INFO - HttpServer: [192.168.1.61] GET /favicon.ico 
Aug 12, 2022 13:02:45.024 [Javascript] INFO - HttpServer: [192.168.1.61] PUT /settings name=equalizerValues&value=0,0,0,0,0,0,0,0,0,0
Aug 12, 2022 13:02:45.052 [Javascript] INFO - HTTP: Setting equalizerValues of type object to 0,0,0,0,0,0,0,0,0,0
Aug 12, 2022 13:02:45.076 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:02:45.077 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:02:45.147 [Javascript] INFO - HttpServer: [192.168.1.61] GET /settings/values name=audioDeviceUuid
Aug 12, 2022 13:02:50.081 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:02:50.082 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:02:55.087 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:02:55.088 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:03:00.093 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:03:00.093 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:03:05.098 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:03:05.098 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:03:10.102 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:03:10.102 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:03:15.106 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:03:15.107 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:03:20.111 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:03:20.111 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:03:25.116 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:03:25.116 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:03:30.120 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:03:30.120 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:03:35.125 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:03:35.126 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:03:40.130 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:03:40.130 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:03:45.135 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:03:45.135 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:03:50.139 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:03:50.139 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:03:55.144 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:03:55.145 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:03:59.963 [Javascript] INFO - HttpServer: [192.168.1.61] OPTIONS /resources 
Aug 12, 2022 13:03:59.964 [Javascript] INFO - HttpServer: [192.168.1.61] OPTIONS /resources 
Aug 12, 2022 13:03:59.965 [Javascript] INFO - HttpServer: [192.168.1.61] OPTIONS /resources 
Aug 12, 2022 13:03:59.965 [Javascript] INFO - HttpServer: [192.168.1.61] OPTIONS /resources 
Aug 12, 2022 13:03:59.972 [Javascript] INFO - HttpServer: [192.168.1.61] GET /resources 
Aug 12, 2022 13:03:59.990 [Javascript] INFO - HttpServer: [192.168.1.61] GET /resources 
Aug 12, 2022 13:04:00.002 [Javascript] INFO - HttpServer: [192.168.1.61] GET /resources 
Aug 12, 2022 13:04:00.005 [Javascript] INFO - HttpServer: [192.168.1.61] GET /resources 
Aug 12, 2022 13:04:00.015 [Javascript] INFO - HttpServer: [192.168.1.61] GET /resources 
Aug 12, 2022 13:04:00.030 [Javascript] INFO - HttpServer: [192.168.1.61] GET /resources 
Aug 12, 2022 13:04:00.149 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:04:00.149 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:04:02.587 [Javascript] INFO - HttpServer: [192.168.1.61] GET /colors/extract source=80256acc7d74d11bfe9bdb305b965f7733583580&endpoint=/library/metadata/11858/thumb/1629513577
Aug 12, 2022 13:04:02.593 [0xb6f22040] DEBUG - Networking: Create request 38: (GET https://192-168-1-53.a36bc24d8bae4942811a22f18e9ebc41.plex.direct:32400/photo/:/transcode?width=150&height=150&url=%2Flibrary%2Fmetadata%2F11858%2Fthumb%2F1629513577&format=png&X-Plex-Token=<token>).
Aug 12, 2022 13:04:02.748 [0xad708440] DEBUG - Networking: Completed request 38: (GET https://192-168-1-53.a36bc24d8bae4942811a22f18e9ebc41.plex.direct:32400/photo/:/transcode?width=150&height=150&url=%2Flibrary%2Fmetadata%2F11858%2Fthumb%2F1629513577&format=png&X-Plex-Token=<token>) with code 200 (have 40006 bytes) in 154 ms.
Aug 12, 2022 13:04:02.996 [Javascript] INFO - HttpServer: [192.168.1.61] GET /colors/extract source=80256acc7d74d11bfe9bdb305b965f7733583580&endpoint=/library/metadata/2144/thumb/1655174882
Aug 12, 2022 13:04:02.998 [0xb6f22040] DEBUG - Networking: Create request 39: (GET https://192-168-1-53.a36bc24d8bae4942811a22f18e9ebc41.plex.direct:32400/photo/:/transcode?width=150&height=150&url=%2Flibrary%2Fmetadata%2F2144%2Fthumb%2F1655174882&format=png&X-Plex-Token=<token>).
Aug 12, 2022 13:04:03.009 [0xad708440] DEBUG - Networking: Completed request 39: (GET https://192-168-1-53.a36bc24d8bae4942811a22f18e9ebc41.plex.direct:32400/photo/:/transcode?width=150&height=150&url=%2Flibrary%2Fmetadata%2F2144%2Fthumb%2F1655174882&format=png&X-Plex-Token=<token>) with code 200 (have 23508 bytes) in 11 ms.
Aug 12, 2022 13:04:03.153 [Javascript] INFO - HttpServer: [192.168.1.61] GET /colors/extract source=80256acc7d74d11bfe9bdb305b965f7733583580&endpoint=/library/metadata/13217/thumb/1660184095
Aug 12, 2022 13:04:03.155 [0xb6f22040] DEBUG - Networking: Create request 40: (GET https://192-168-1-53.a36bc24d8bae4942811a22f18e9ebc41.plex.direct:32400/photo/:/transcode?width=150&height=150&url=%2Flibrary%2Fmetadata%2F13217%2Fthumb%2F1660184095&format=png&X-Plex-Token=<token>).
Aug 12, 2022 13:04:03.164 [0xad708440] DEBUG - Networking: Completed request 40: (GET https://192-168-1-53.a36bc24d8bae4942811a22f18e9ebc41.plex.direct:32400/photo/:/transcode?width=150&height=150&url=%2Flibrary%2Fmetadata%2F13217%2Fthumb%2F1660184095&format=png&X-Plex-Token=<token>) with code 200 (have 33870 bytes) in 9 ms.
Aug 12, 2022 13:04:03.485 [Javascript] INFO - IMAGE: Extracted palette #BDD0BB,#1E1F38,#658F9A,#384C75,#E0B339,#C7D8BD,#273A39,#40707D,#769298,#A2C6B5,#5C9177,#17392F,#4B6339,#CFAB2D,#909039,#6D9B79,#4A7765,#AB9A2F,#647F39,#394939.
Aug 12, 2022 13:04:03.538 [Javascript] INFO - IMAGE: Extracted palette #19252E,#27536C,#1D384B,#19252E,#19252E,#295A6E,#2E667E,#316E87,#418FAB,#387F99,#192730,#1F414C,#255363,#387890,#192730,#1C374A,#1C374A,#1C374A,#1C374A,#1C374A.
Aug 12, 2022 13:04:03.822 [Javascript] INFO - IMAGE: Extracted palette #6D6D6D,#4A2F26,#878787,#C7C6C7,#AB6432,#392721,#5B3027,#9A6F41,#7F5331,#AB8653,#B2B5A3,#301F1D,#7F7864,#784A30,#BD9164,#2F1F1D,#E0B47E,#B47742,#C7C6B3,#804B2F.
Aug 12, 2022 13:04:05.154 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:04:05.155 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:04:10.159 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:04:10.160 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:04:15.164 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:04:15.165 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:04:20.170 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:04:20.171 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:04:25.177 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:04:25.178 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:04:30.183 [Javascript] INFO - WebSocket: Connection error to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:04:30.183 [Javascript] INFO - WebSocket: Connection closed to ws://127.0.0.1:2000/jsonrpc
Aug 12, 2022 13:04:32.540 [Javascript] INFO - HttpServer: [192.168.1.61] GET /player/playback/createPlayQueue source=80256acc7d74d11bfe9bdb305b965f7733583580&uri=server%3A%2F%2F80256acc7d74d11bfe9bdb305b965f7733583580%2Fcom.plexapp.plugins.library%2Flibrary%2Fmetadata%2F2144&key=%2Flibrary%2Fmetadata%2F2148&token=<token>&includeExternalMedia=1&type=audio&protocol=https&address=192-168-1-53.a36bc24d8bae4942811a22f18e9ebc41.plex.direct&port=32400&machineIdentifier=80256acc7d74d11bfe9bdb305b965f7733583580&commandID=31
Aug 12, 2022 13:04:32.551 [Javascript] INFO - PLAYER: Issuing play for music from server 80256acc7d74d11bfe9bdb305b965f7733583580
Aug 12, 2022 13:04:32.559 [Javascript] DEBUG - PlayQueue: Processing STOP
Aug 12, 2022 13:04:32.561 [Javascript] INFO - PlayQueue: Stop.
Aug 12, 2022 13:04:32.562 [0xaf70c440] INFO - BASS: Stop.
Aug 12, 2022 13:04:32.562 [0xaf70c440] INFO - PQCache: Clearing.
Aug 12, 2022 13:04:32.562 [0xaf70c440] DEBUG - BASS: Stopped in 0 ms.
Aug 12, 2022 13:04:32.564 [Javascript] DEBUG - PlayQueue: Processing send timelines in state stopped time -1000.
Aug 12, 2022 13:04:32.565 [Javascript] INFO - PlayQueue: Processing play media.
Aug 12, 2022 13:04:32.574 [0xb6f22040] DEBUG - Networking: Create request 41: (POST https://192-168-1-53.a36bc24d8bae4942811a22f18e9ebc41.plex.direct:32400/playQueues?includeLoudnessRamps=1&uri=server%3A%2F%2F80256acc7d74d11bfe9bdb305b965f7733583580%2Fcom.plexapp.plugins.library%2Flibrary%2Fmetadata%2F2144&key=%2Flibrary%2Fmetadata%2F2148&includeExternalMedia=1&type=audio&commandID=31&includeFields=thumbBlurHash&X-Plex-Device-Name=Harmonia).
Aug 12, 2022 13:04:32.678 [0xad708440] DEBUG - Networking: Completed request 41: (POST https://192-168-1-53.a36bc24d8bae4942811a22f18e9ebc41.plex.direct:32400/playQueues?includeLoudnessRamps=1&uri=server%3A%2F%2F80256acc7d74d11bfe9bdb305b965f7733583580%2Fcom.plexapp.plugins.library%2Flibrary%2Fmetadata%2F2144&key=%2Flibrary%2Fmetadata%2F2148&includeExternalMedia=1&type=audio&commandID=31&includeFields=thumbBlurHash&X-Plex-Device-Name=Harmonia) with code 200 (have 23916 bytes) in 103 ms.
Aug 12, 2022 13:04:32.686 [Javascript] INFO - PlayQueue: Saving to storage.
Aug 12, 2022 13:04:32.691 [Javascript] INFO - PlayQueue: Processing PQ 80256acc7d74d11bfe9bdb305b965f7733583580 881 with 10 items (full: true, play after: true), selected 40123 at offset 3.
Aug 12, 2022 13:04:32.694 [Javascript] INFO - PlayQueue: Looked at all the tracks and consecutive: true.
Aug 12, 2022 13:04:32.696 [Javascript] INFO - Audio: Effective repeat mode is: undefined from mode: undefined play queue length: 10
Aug 12, 2022 13:04:32.702 [Javascript] DEBUG - PlayQueue: Audio queue: 
Aug 12, 2022 13:04:32.704 [Javascript] DEBUG - PlayQueue: Play queue: 40123,40124
Aug 12, 2022 13:04:32.711 [Javascript] DEBUG - PlayQueue: Adding both current and next track.
Aug 12, 2022 13:04:32.712 [0xae70a440] INFO - BASS: Stop.
Aug 12, 2022 13:04:32.712 [0xae70a440] INFO - PQCache: Clearing.
Aug 12, 2022 13:04:32.712 [0xae70a440] DEBUG - BASS: Stopped in 0 ms.
Aug 12, 2022 13:04:32.715 [Javascript] DEBUG - PlayQueue: Adding track 40123 to audio player, gapless true gain -4.1 looping false.
Aug 12, 2022 13:04:32.726 [0xaf70c440] INFO - PQCache: Set item 40123 active to 1, 1 active remaining.
Aug 12, 2022 13:04:32.727 [0xaf70c440] INFO - BASS: Queueing stream (1 total, 0 handles) with identifier 40123, gain -4.1 dB, overlap duration 0 ms, start offset 0 ms (paused: 0).
Aug 12, 2022 13:04:32.727 [0xaf70c440] INFO - Media: Found an item to direct play with bitrate under 0 (it was 2516 kbps).
9

please, for the love of god, zip up the entire log. it’s like watching a stripper take off a heavy jacket to reveal a turtleneck.

5 Likes
10

logs.txt.zip (2.8 KB)
My last post HAS the entire log. And attached is a zip file of the SAME logs above, I don’t have any other logs for plexamp.

11

No, it doesn’t. tail -f grabs all subsequent logs, not all logs prior to the time in question.

Please zip up the entire Plexamp.log (and others alongside).

12

Ok, I did not know that about tail -f so I attach two files. The Plexam.1.log is the session where I clicked the Cast icon.
Plexamp.log.zip (4.5 KB)
Plexamp.1.log.zip (5.4 KB)

FYI, artemis is the name of the server where Plex Media Server is installed. Harmonia is the name I gave to the RPi.

13

there’s no evidence i can see in either log of you connecting to the pi for playback using the cast menu. that lines up with the black screen you’re seeing.

14

There might not be any evidence in the logs of me connecting to the pi for playback, but the Cast icon was clicked, the pi was selected and an orange tick was next to it and then a song was selected and as the logs say, a black screen. Let me know if you want me to do anything else. I have what I mentioned in the first entry of this post about 6 times, tried it with various versions of v4.2.0 such as, beta 5, 7 but the one that worked was the v.4.3.0

15

Going on holidays tomorrow for a week, so no computers, but when I come back would be more than happy to video chat and show you.

16

I have a few BerryDACs and a couple of Pi 4Bs like you do that run Plexamp headless.
You might try the Odinb1 Plexamp install script that works on our hardware.

Some things i also do:

  • set my DHCP server to give out Cloudflare DNS 1.1.1.1, and 9.9.9.9 as a secondary.
  • check my aplay -l output to see if vc4 or bcm2835 are loading.

Have a great holiday!

1 Like
17

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