badaix / snapcast

Synchronous multiroom audio player
GNU General Public License v3.0
6.16k stars 457 forks source link

librespot causing snapcast to crash when starting a stream #1063

Closed elParaguayo closed 1 year ago

elParaguayo commented 2 years ago

Describe the bug I recently updated both snapcast and librespot and, since that time, I cannot play any spotify streams.

If I connect to the librespot device from my phone, it says it's connecting but then shows the white icon (rather than the green icon when it's connected to another device). Looking at the snapcast logs, I can see that I get a load of data from Spotify but snapcast crashes when it statrs to get the metadata.

I can run librespot separately with the pipe backend and that works ok so I feel like the issue is with snapcast.

Steps to Reproduce

  1. Open spotify on phone, try to connect to librespot named device

Environment details

Attach logfile if applicable Generate logs with snapclient --logfilter debug or snapserver --logging.filter debug if possible and paste them in the following codeblock

My snapserver runs as a systemd service so I've pulled this from the journal. Hope that's ok.

Oct 08 09:26:56 htpc systemd[1]: Started Snapcast server.
Oct 08 09:26:56 htpc snapserver[3305]: Version 0.26.0
Oct 08 09:26:56 htpc snapserver[3305]: Adding stream: librespot:///usr/bin/librespot?name=Spotify&devicename=Home%20Speakers&bitrate=320
Oct 08 09:26:56 htpc snapserver[3305]: Adding stream: alsa://?name=Server%20Audio&sampleformat=48000:16:2&device=hw:2,1,0
Oct 08 09:26:56 htpc snapserver[3305]: Adding stream: alsa://?name=Mopidy&sampleformat=48000:16:2&device=hw:2,1,1
Oct 08 09:26:56 htpc snapserver[3305]: Adding stream: alsa://?name=Youtube&sampleformat=48000:16:2&device=hw:2,1,2
Oct 08 09:26:56 htpc snapserver[3305]: Adding stream: alsa://?name=Audible&sampleformat=48000:16:2&device=hw:2,1,3
Oct 08 09:26:56 htpc snapserver[3305]: Adding stream: meta:///Spotify/Mopidy/Youtube?name=HTPC
Oct 08 09:26:56 htpc snapserver[3305]: Settings file: "/var/lib/snapserver/server.json"
Oct 08 09:26:56 htpc snapserver[3305]: Using HTTP host name: htpc
Oct 08 09:26:56 htpc snapserver[3305]: Adding service 'Snapcast'
Oct 08 09:26:56 htpc snapserver[3305]: StreamUri: librespot:///usr/bin/librespot?name=Spotify&devicename=Home%20Speakers&bitrate=320
Oct 08 09:26:56 htpc snapserver[3305]: StreamUri.toString: librespot:////usr/bin/librespot?bitrate=320&devicename=Home Speakers&name=Spotify
Oct 08 09:26:56 htpc snapserver[3305]: PcmStream: Spotify, sampleFormat: 44100:16:2
Oct 08 09:26:56 htpc snapserver[3305]: Chunk duration: 20 ms, frames: 882, size: 3528
Oct 08 09:26:56 htpc snapserver[3305]: Watchdog timeout: 0
Oct 08 09:26:56 htpc snapserver[3305]: Stream: {"fragment":"","host":"","path":"/usr/bin/librespot","query":{"bitrate":"320","chunk_ms":"20","codec":"flac","devicename":"Home Speakers","name":"Spotify","sampleformat":"44100:16:2"},"raw":"librespot:////usr/bin/librespot?bitrate=320&chunk_ms=20&codec=flac&devicename=Home Speakers&name=Spotify&sampleformat=44100:16:2","scheme":"librespot"}
Oct 08 09:26:56 htpc snapserver[3305]: StreamUri: alsa://?name=Server%20Audio&sampleformat=48000:16:2&device=hw:2,1,0
Oct 08 09:26:56 htpc snapserver[3305]: StreamUri.toString: alsa:///?device=hw:2,1,0&name=Server Audio&sampleformat=48000:16:2
Oct 08 09:26:56 htpc snapserver[3305]: PcmStream: Server Audio, sampleFormat: 48000:16:2
Oct 08 09:26:56 htpc snapserver[3305]: Device: hw:2,1,0, silence threshold percent: 0, silence threshold amplitude: 0
Oct 08 09:26:56 htpc snapserver[3305]: Stream: {"fragment":"","host":"","path":"","query":{"chunk_ms":"20","codec":"flac","device":"hw:2,1,0","name":"Server Audio","sampleformat":"48000:16:2"},"raw":"alsa:///?chunk_ms=20&codec=flac&device=hw:2,1,0&name=Server Audio&sampleformat=48000:16:2","scheme":"alsa"}
Oct 08 09:26:56 htpc snapserver[3305]: StreamUri: alsa://?name=Mopidy&sampleformat=48000:16:2&device=hw:2,1,1
Oct 08 09:26:56 htpc snapserver[3305]: StreamUri.toString: alsa:///?device=hw:2,1,1&name=Mopidy&sampleformat=48000:16:2
Oct 08 09:26:56 htpc snapserver[3305]: PcmStream: Mopidy, sampleFormat: 48000:16:2
Oct 08 09:26:56 htpc snapserver[3305]: Device: hw:2,1,1, silence threshold percent: 0, silence threshold amplitude: 0
Oct 08 09:26:56 htpc snapserver[3305]: Stream: {"fragment":"","host":"","path":"","query":{"chunk_ms":"20","codec":"flac","device":"hw:2,1,1","name":"Mopidy","sampleformat":"48000:16:2"},"raw":"alsa:///?chunk_ms=20&codec=flac&device=hw:2,1,1&name=Mopidy&sampleformat=48000:16:2","scheme":"alsa"}
Oct 08 09:26:56 htpc snapserver[3305]: StreamUri: alsa://?name=Youtube&sampleformat=48000:16:2&device=hw:2,1,2
Oct 08 09:26:56 htpc snapserver[3305]: StreamUri.toString: alsa:///?device=hw:2,1,2&name=Youtube&sampleformat=48000:16:2
Oct 08 09:26:56 htpc snapserver[3305]: PcmStream: Youtube, sampleFormat: 48000:16:2
Oct 08 09:26:56 htpc snapserver[3305]: Device: hw:2,1,2, silence threshold percent: 0, silence threshold amplitude: 0
Oct 08 09:26:56 htpc snapserver[3305]: Stream: {"fragment":"","host":"","path":"","query":{"chunk_ms":"20","codec":"flac","device":"hw:2,1,2","name":"Youtube","sampleformat":"48000:16:2"},"raw":"alsa:///?chunk_ms=20&codec=flac&device=hw:2,1,2&name=Youtube&sampleformat=48000:16:2","scheme":"alsa"}
Oct 08 09:26:56 htpc snapserver[3305]: StreamUri: alsa://?name=Audible&sampleformat=48000:16:2&device=hw:2,1,3
Oct 08 09:26:56 htpc snapserver[3305]: StreamUri.toString: alsa:///?device=hw:2,1,3&name=Audible&sampleformat=48000:16:2
Oct 08 09:26:56 htpc snapserver[3305]: PcmStream: Audible, sampleFormat: 48000:16:2
Oct 08 09:26:56 htpc snapserver[3305]: Device: hw:2,1,3, silence threshold percent: 0, silence threshold amplitude: 0
Oct 08 09:26:56 htpc snapserver[3305]: Stream: {"fragment":"","host":"","path":"","query":{"chunk_ms":"20","codec":"flac","device":"hw:2,1,3","name":"Audible","sampleformat":"48000:16:2"},"raw":"alsa:///?chunk_ms=20&codec=flac&device=hw:2,1,3&name=Audible&sampleformat=48000:16:2","scheme":"alsa"}
Oct 08 09:26:56 htpc snapserver[3305]: StreamUri: meta:///Spotify/Mopidy/Youtube?name=HTPC
Oct 08 09:26:56 htpc snapserver[3305]: StreamUri.toString: meta:////Spotify/Mopidy/Youtube?name=HTPC
Oct 08 09:26:56 htpc snapserver[3305]: StreamUri: librespot:///usr/bin/librespot?name=Spotify&devicename=Home%20Speakers&bitrate=320
Oct 08 09:26:56 htpc snapserver[3305]: StreamUri.toString: librespot:////usr/bin/librespot?bitrate=320&devicename=Home Speakers&name=Spotify
Oct 08 09:26:56 htpc snapserver[3305]: StreamUri: alsa://?name=Server%20Audio&sampleformat=48000:16:2&device=hw:2,1,0
Oct 08 09:26:56 htpc snapserver[3305]: StreamUri.toString: alsa:///?device=hw:2,1,0&name=Server Audio&sampleformat=48000:16:2
Oct 08 09:26:56 htpc snapserver[3305]: StreamUri: alsa://?name=Mopidy&sampleformat=48000:16:2&device=hw:2,1,1
Oct 08 09:26:56 htpc snapserver[3305]: StreamUri.toString: alsa:///?device=hw:2,1,1&name=Mopidy&sampleformat=48000:16:2
Oct 08 09:26:56 htpc snapserver[3305]: StreamUri: alsa://?name=Youtube&sampleformat=48000:16:2&device=hw:2,1,2
Oct 08 09:26:56 htpc snapserver[3305]: StreamUri.toString: alsa:///?device=hw:2,1,2&name=Youtube&sampleformat=48000:16:2
Oct 08 09:26:56 htpc snapserver[3305]: StreamUri: alsa://?name=Audible&sampleformat=48000:16:2&device=hw:2,1,3
Oct 08 09:26:56 htpc snapserver[3305]: StreamUri.toString: alsa:///?device=hw:2,1,3&name=Audible&sampleformat=48000:16:2
Oct 08 09:26:56 htpc snapserver[3305]: StreamUri: meta:///Spotify/Mopidy/Youtube?name=HTPC
Oct 08 09:26:56 htpc snapserver[3305]: StreamUri.toString: meta:////Spotify/Mopidy/Youtube?name=HTPC
Oct 08 09:26:56 htpc snapserver[3305]: PcmStream: HTPC, sampleFormat: 48000:16:2
Oct 08 09:26:56 htpc snapserver[3305]: Resampling from 44100:16:2 to 48000:16:2
Oct 08 09:26:56 htpc snapserver[3305]: Stream: {"fragment":"","host":"","path":"/Spotify/Mopidy/Youtube","query":{"chunk_ms":"20","codec":"flac","name":"HTPC","sampleformat":"48000:16:2"},"raw":"meta:////Spotify/Mopidy/Youtube?chunk_ms=20&codec=flac&name=HTPC&sampleformat=48000:16:2","scheme":"meta"}
Oct 08 09:26:56 htpc snapserver[3305]: Start, sampleformat: 48000:16:2
Oct 08 09:26:56 htpc snapserver[3305]: Start: HTPC, type: meta, sampleformat: 48000:16:2, codec: flac
Oct 08 09:26:56 htpc snapserver[3305]: Init - compression level: 2
Oct 08 09:26:56 htpc snapserver[3305]: Start: Spotify, type: librespot, sampleformat: 44100:16:2, codec: flac
Oct 08 09:26:56 htpc snapserver[3305]: Init - compression level: 2
Oct 08 09:26:56 htpc snapserver[3305]: Launching: '/usr/bin/librespot', with params: '--name "Home Speakers" --bitrate 320 --backend pipe --initial-volume 100 --verbose', in path: '/usr/bin/'
Oct 08 09:26:56 htpc snapserver[3305]: first read, updating timestamp
Oct 08 09:26:56 htpc snapserver[3305]: Starting watchdog, timeout: 7800s
Oct 08 09:26:56 htpc snapserver[3305]: Start, sampleformat: 48000:16:2
Oct 08 09:26:56 htpc snapserver[3305]: Chunk duration: 20 ms, frames: 960, size: 3840
Oct 08 09:26:56 htpc snapserver[3305]: Start: Server Audio, type: alsa, sampleformat: 48000:16:2, codec: flac
Oct 08 09:26:56 htpc snapserver[3305]: Init - compression level: 2
Oct 08 09:26:56 htpc snapserver[3305]: No data availabale, playing silence.
Oct 08 09:26:56 htpc snapserver[3305]: Start, sampleformat: 48000:16:2
Oct 08 09:26:56 htpc snapserver[3305]: Chunk duration: 20 ms, frames: 960, size: 3840
Oct 08 09:26:56 htpc snapserver[3305]: Start: Mopidy, type: alsa, sampleformat: 48000:16:2, codec: flac
Oct 08 09:26:56 htpc snapserver[3305]: Init - compression level: 2
Oct 08 09:26:56 htpc snapserver[3305]: No data availabale, playing silence.
Oct 08 09:26:56 htpc snapserver[3305]: Start, sampleformat: 48000:16:2
Oct 08 09:26:56 htpc snapserver[3305]: Chunk duration: 20 ms, frames: 960, size: 3840
Oct 08 09:26:56 htpc snapserver[3305]: Start: Youtube, type: alsa, sampleformat: 48000:16:2, codec: flac
Oct 08 09:26:56 htpc snapserver[3305]: Init - compression level: 2
Oct 08 09:26:56 htpc snapserver[3305]: No data availabale, playing silence.
Oct 08 09:26:56 htpc snapserver[3305]: Start, sampleformat: 48000:16:2
Oct 08 09:26:56 htpc snapserver[3305]: Chunk duration: 20 ms, frames: 960, size: 3840
Oct 08 09:26:56 htpc snapserver[3305]: Start: Audible, type: alsa, sampleformat: 48000:16:2, codec: flac
Oct 08 09:26:56 htpc snapserver[3305]: Init - compression level: 2
Oct 08 09:26:56 htpc snapserver[3305]: No data availabale, playing silence.
Oct 08 09:26:56 htpc snapserver[3305]: Creating TCP acceptor for address: 0.0.0.0, port: 1705
Oct 08 09:26:56 htpc snapserver[3305]: Creating HTTP acceptor for address: 0.0.0.0, port: 1780
Oct 08 09:26:56 htpc snapserver[3305]: Creating stream acceptor for address: 0.0.0.0, port: 1704
Oct 08 09:26:56 htpc snapserver[3305]: Number of threads: 2, hw threads: 2
Oct 08 09:26:56 htpc snapserver[3305]: librespot 0.5.0-dev 29ec49b (Built on 2022-10-06, Build ID: 1665077239, Profile: release)
Oct 08 09:26:56 htpc snapserver[3305]: new Session
Oct 08 09:26:56 htpc snapserver[3305]: Zeroconf server listening on 0.0.0.0:33093
Oct 08 09:26:56 htpc snapserver[3305]: Shutting down discovery server
Oct 08 09:26:56 htpc snapserver[3305]: Resample buffer completely filled, adding space for 5ms; new buffer size: 4800 bytes
Oct 08 09:26:56 htpc snapserver[3305]: Resample buffer completely filled, adding space for 5ms; new buffer size: 5760 bytes
Oct 08 09:26:57 htpc snapserver[3305]: Resample buffer completely filled, adding space for 5ms; new buffer size: 6720 bytes
Oct 08 09:26:57 htpc snapserver[3305]: Resample buffer completely filled, adding space for 5ms; new buffer size: 7680 bytes
Oct 08 09:26:57 htpc snapserver[3305]: Service 'Snapcast' successfully established.
Oct 08 09:26:57 htpc snapserver[3305]: StreamServer::NewConnection: 192.168.0.70
Oct 08 09:26:57 htpc snapserver[3305]: getNextMessage: Hello, size: 196, id: 1034, refers: 0
Oct 08 09:26:57 htpc snapserver[3305]: onMessageReceived: Hello, size: 196, id: 1034, refers: 0, sent: 904,909568, recv: 904,909766
Oct 08 09:26:57 htpc snapserver[3305]: Hello from 1c:6f:65:85:2c:d5, host: htpc, v0.26.0, ClientName: Snapclient, OS: Arch Linux, Arch: x86_64, Protocol version: 2
Oct 08 09:26:57 htpc snapserver[3305]: Sending ServerSettings to 1c:6f:65:85:2c:d5
Oct 08 09:26:57 htpc snapserver[3305]: Group: ed47d674-c32a-1750-246a-da9cff8968f6, stream: HTPC
Oct 08 09:26:57 htpc snapserver[3305]: Sending codec header to 1c:6f:65:85:2c:d5
Oct 08 09:26:57 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1035, refers: 0
Oct 08 09:26:57 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1035, refers: 0, sent: 904,909650, recv: 904,909992
Oct 08 09:26:57 htpc snapserver[3305]: StreamServer::NewConnection: 192.168.0.32
Oct 08 09:26:57 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 0, refers: 0
Oct 08 09:26:57 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 0, refers: 0, sent: 1665217617,989552, recv: 905,104251
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Hello, size: 219, id: 0, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Hello, size: 219, id: 0, refers: 0, sent: 1665217618,10969, recv: 905,124186
Oct 08 09:26:58 htpc snapserver[3305]: Hello from b8:27:eb:f2:ff:1a, host: pi-radio, v0.15.0, ClientName: Snapclient, OS: Raspbian GNU/Linux 8 (jessie), Arch: armv7l, Protocol version: 2
Oct 08 09:26:58 htpc snapserver[3305]: Sending ServerSettings to b8:27:eb:f2:ff:1a
Oct 08 09:26:58 htpc snapserver[3305]: Group: ed47d674-c32a-1750-246a-da9cff8968f6, stream: HTPC
Oct 08 09:26:58 htpc snapserver[3305]: Sending codec header to b8:27:eb:f2:ff:1a
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 802, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 802, refers: 0, sent: 1665217618,11184, recv: 905,142446
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 803, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 803, refers: 0, sent: 1665217618,36323, recv: 905,150475
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 804, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 804, refers: 0, sent: 1665217618,41744, recv: 905,154445
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 805, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 805, refers: 0, sent: 1665217618,45452, recv: 905,158461
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 806, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 806, refers: 0, sent: 1665217618,51492, recv: 905,165450
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 807, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 807, refers: 0, sent: 1665217618,57486, recv: 905,171559
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 808, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 808, refers: 0, sent: 1665217618,63175, recv: 905,175906
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 809, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 809, refers: 0, sent: 1665217618,67356, recv: 905,181537
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 810, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 810, refers: 0, sent: 1665217618,71681, recv: 905,186292
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 811, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 811, refers: 0, sent: 1665217618,76766, recv: 905,189536
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 812, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 812, refers: 0, sent: 1665217618,79489, recv: 905,192189
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 813, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 813, refers: 0, sent: 1665217618,82930, recv: 905,195640
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 814, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 814, refers: 0, sent: 1665217618,86482, recv: 905,200484
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 815, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 815, refers: 0, sent: 1665217618,91411, recv: 905,206297
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 816, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 816, refers: 0, sent: 1665217618,102851, recv: 905,215560
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 817, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 817, refers: 0, sent: 1665217618,109164, recv: 905,222637
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1036, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1036, refers: 0, sent: 905,225185, recv: 905,225742
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1037, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1037, refers: 0, sent: 905,226061, recv: 905,226203
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1038, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1038, refers: 0, sent: 905,226493, recv: 905,226700
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1039, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1039, refers: 0, sent: 905,226985, recv: 905,227101
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1040, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1040, refers: 0, sent: 905,227350, recv: 905,227501
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1041, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1041, refers: 0, sent: 905,227887, recv: 905,228031
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1042, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1042, refers: 0, sent: 905,228412, recv: 905,228560
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1043, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1043, refers: 0, sent: 905,228892, recv: 905,228986
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1044, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1044, refers: 0, sent: 905,229291, recv: 905,229433
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1045, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1045, refers: 0, sent: 905,229810, recv: 905,229953
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1046, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1046, refers: 0, sent: 905,230369, recv: 905,230520
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1047, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1047, refers: 0, sent: 905,230898, recv: 905,231038
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1048, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1048, refers: 0, sent: 905,231417, recv: 905,231560
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1049, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1049, refers: 0, sent: 905,231926, recv: 905,232068
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1050, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1050, refers: 0, sent: 905,232454, recv: 905,232605
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1051, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1051, refers: 0, sent: 905,232984, recv: 905,233126
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1052, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1052, refers: 0, sent: 905,233508, recv: 905,233655
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1053, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 818, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1053, refers: 0, sent: 905,234008, recv: 905,234161
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 818, refers: 0, sent: 1665217618,121403, recv: 905,234188
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1054, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1054, refers: 0, sent: 905,234564, recv: 905,234706
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1055, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1055, refers: 0, sent: 905,235074, recv: 905,235217
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1056, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1056, refers: 0, sent: 905,235597, recv: 905,235739
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1057, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: Resample idone: 882/882, odone: 1785/1920, delay: 130
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1057, refers: 0, sent: 905,236068, recv: 905,236218
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1058, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1058, refers: 0, sent: 905,236585, recv: 905,236695
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1059, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1059, refers: 0, sent: 905,236999, recv: 905,237139
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1060, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1060, refers: 0, sent: 905,237508, recv: 905,237659
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1061, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1061, refers: 0, sent: 905,238013, recv: 905,238126
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1062, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1062, refers: 0, sent: 905,238431, recv: 905,238579
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1063, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1063, refers: 0, sent: 905,238972, recv: 905,239136
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1064, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1064, refers: 0, sent: 905,239554, recv: 905,239698
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1065, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1065, refers: 0, sent: 905,240087, recv: 905,240229
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1066, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1066, refers: 0, sent: 905,240591, recv: 905,240732
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1067, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1067, refers: 0, sent: 905,241094, recv: 905,241177
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1068, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 819, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1068, refers: 0, sent: 905,241498, recv: 905,241916
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 819, refers: 0, sent: 1665217618,124935, recv: 905,241943
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1069, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1069, refers: 0, sent: 905,243826, recv: 905,243998
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1070, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1070, refers: 0, sent: 905,244374, recv: 905,244523
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1071, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1071, refers: 0, sent: 905,244926, recv: 905,245066
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1072, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1072, refers: 0, sent: 905,245461, recv: 905,245600
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1073, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1073, refers: 0, sent: 905,245943, recv: 905,246046
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1074, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1074, refers: 0, sent: 905,246371, recv: 905,246509
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1075, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1075, refers: 0, sent: 905,246921, recv: 905,247055
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1076, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1076, refers: 0, sent: 905,247307, recv: 905,247431
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1077, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1077, refers: 0, sent: 905,247826, recv: 905,247961
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1078, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1078, refers: 0, sent: 905,248346, recv: 905,248485
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1079, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1079, refers: 0, sent: 905,248944, recv: 905,249089
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 820, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 820, refers: 0, sent: 1665217618,135636, recv: 905,249405
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1080, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1080, refers: 0, sent: 905,249486, recv: 905,249647
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1081, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1081, refers: 0, sent: 905,250050, recv: 905,250195
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1082, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1082, refers: 0, sent: 905,250584, recv: 905,250728
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1083, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1083, refers: 0, sent: 905,251091, recv: 905,251242
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1084, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1084, refers: 0, sent: 905,251631, recv: 905,251773
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1085, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1085, refers: 0, sent: 905,252158, recv: 905,252307
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 821, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 821, refers: 0, sent: 1665217618,139950, recv: 905,255281
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 822, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 822, refers: 0, sent: 1665217618,149899, recv: 905,263753
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 823, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 823, refers: 0, sent: 1665217618,157109, recv: 905,272055
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 824, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 824, refers: 0, sent: 1665217618,164622, recv: 905,279080
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 825, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 825, refers: 0, sent: 1665217618,170420, recv: 905,283614
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 826, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 826, refers: 0, sent: 1665217618,174374, recv: 905,287081
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 827, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 827, refers: 0, sent: 1665217618,178992, recv: 905,291761
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 828, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 828, refers: 0, sent: 1665217618,182029, recv: 905,294955
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 829, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 829, refers: 0, sent: 1665217618,185130, recv: 905,297831
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 830, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 830, refers: 0, sent: 1665217618,198984, recv: 905,311787
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 831, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 831, refers: 0, sent: 1665217618,211716, recv: 905,324448
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 832, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 832, refers: 0, sent: 1665217618,234653, recv: 905,351575
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 833, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 833, refers: 0, sent: 1665217618,278805, recv: 905,392209
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 834, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 834, refers: 0, sent: 1665217618,282987, recv: 905,395729
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 835, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 835, refers: 0, sent: 1665217618,286427, recv: 905,402322
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 836, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 836, refers: 0, sent: 1665217618,294788, recv: 905,407961
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 837, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 837, refers: 0, sent: 1665217618,302379, recv: 905,415587
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 838, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 838, refers: 0, sent: 1665217618,306116, recv: 905,419000
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 839, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 839, refers: 0, sent: 1665217618,316150, recv: 905,429361
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 840, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 840, refers: 0, sent: 1665217618,329072, recv: 905,441770
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 841, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 841, refers: 0, sent: 1665217618,339585, recv: 905,452548
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 842, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 842, refers: 0, sent: 1665217618,343802, recv: 905,456641
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 843, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 843, refers: 0, sent: 1665217618,349186, recv: 905,462677
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 844, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 844, refers: 0, sent: 1665217618,353596, recv: 905,467040
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 845, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 845, refers: 0, sent: 1665217618,368294, recv: 905,481662
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 846, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 846, refers: 0, sent: 1665217618,372391, recv: 905,485781
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 847, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 847, refers: 0, sent: 1665217618,376983, recv: 905,490933
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 848, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 848, refers: 0, sent: 1665217618,381088, recv: 905,493796
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 849, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 849, refers: 0, sent: 1665217618,387162, recv: 905,507690
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 850, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 850, refers: 0, sent: 1665217618,398218, recv: 905,511301
Oct 08 09:26:58 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 851, refers: 0
Oct 08 09:26:58 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 851, refers: 0, sent: 1665217618,406272, recv: 905,519128
Oct 08 09:26:59 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1086, refers: 0
Oct 08 09:26:59 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1086, refers: 0, sent: 906,252637, recv: 906,252782
Oct 08 09:27:00 htpc snapserver[3305]: Saving config
Oct 08 09:27:00 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1087, refers: 0
Oct 08 09:27:00 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1087, refers: 0, sent: 907,253334, recv: 907,253486
Oct 08 09:27:01 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1088, refers: 0
Oct 08 09:27:01 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1088, refers: 0, sent: 908,253679, recv: 908,253833
Oct 08 09:27:02 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1089, refers: 0
Oct 08 09:27:02 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1089, refers: 0, sent: 909,254437, recv: 909,254605
Oct 08 09:27:03 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1090, refers: 0
Oct 08 09:27:03 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1090, refers: 0, sent: 910,255073, recv: 910,255238
Oct 08 09:27:03 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 0, refers: 0
Oct 08 09:27:03 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 0, refers: 0, sent: 1665217623,415678, recv: 910,534177
Oct 08 09:27:04 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1091, refers: 0
Oct 08 09:27:04 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1091, refers: 0, sent: 911,255734, recv: 911,255885
Oct 08 09:27:05 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1092, refers: 0
Oct 08 09:27:05 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1092, refers: 0, sent: 912,256373, recv: 912,256570
Oct 08 09:27:06 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1093, refers: 0
Oct 08 09:27:06 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1093, refers: 0, sent: 913,257054, recv: 913,257204
Oct 08 09:27:07 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1094, refers: 0
Oct 08 09:27:07 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1094, refers: 0, sent: 914,257722, recv: 914,257876
Oct 08 09:27:08 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1095, refers: 0
Oct 08 09:27:08 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1095, refers: 0, sent: 915,258499, recv: 915,258641
Oct 08 09:27:08 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 0, refers: 0
Oct 08 09:27:08 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 0, refers: 0, sent: 1665217628,419496, recv: 915,533785
Oct 08 09:27:09 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1096, refers: 0
Oct 08 09:27:09 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1096, refers: 0, sent: 916,258838, recv: 916,258984
Oct 08 09:27:10 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1097, refers: 0
Oct 08 09:27:10 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1097, refers: 0, sent: 917,259146, recv: 917,259294
Oct 08 09:27:11 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1098, refers: 0
Oct 08 09:27:11 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1098, refers: 0, sent: 918,259427, recv: 918,259893
Oct 08 09:27:12 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1099, refers: 0
Oct 08 09:27:12 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1099, refers: 0, sent: 919,260192, recv: 919,260334
Oct 08 09:27:13 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1100, refers: 0
Oct 08 09:27:13 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1100, refers: 0, sent: 920,260890, recv: 920,261058
Oct 08 09:27:13 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 0, refers: 0
Oct 08 09:27:13 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 0, refers: 0, sent: 1665217633,423305, recv: 920,539948
Oct 08 09:27:14 htpc snapserver[3305]: getNextMessage: Time, size: 8, id: 1101, refers: 0
Oct 08 09:27:14 htpc snapserver[3305]: onMessageReceived: Time, size: 8, id: 1101, refers: 0, sent: 921,261254, recv: 921,261397
Oct 08 09:27:14 htpc snapserver[3305]: POST "/" {}
Oct 08 09:27:14 htpc snapserver[3305]: Mixing with softvol and volume control: Log(60.0)
Oct 08 09:27:14 htpc snapserver[3305]: new Spirc[0]
Oct 08 09:27:14 htpc snapserver[3305]: new MercuryManager
Oct 08 09:27:14 htpc snapserver[3305]: new ApResolver
Oct 08 09:27:14 htpc snapserver[3305]: Requesting https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient
Oct 08 09:27:14 htpc snapserver[3305]: new Player [0]
Oct 08 09:27:14 htpc snapserver[3305]: Converting with ditherer: tpdf
Oct 08 09:27:14 htpc snapserver[3305]: Using StdoutSink (pipe) with format: S16
Oct 08 09:27:14 htpc snapserver[3305]: Connecting to AP "ap-gew1.spotify.com:4070"
Oct 08 09:27:14 htpc snapserver[3305]: Authenticated as "MY_USER_NAME" !
Oct 08 09:27:14 htpc snapserver[3305]: canonical_username: MY_USER_NAME
Oct 08 09:27:14 htpc snapserver[3305]: listening to uri=spotify:user:attributes:mutated
Oct 08 09:27:14 htpc snapserver[3305]: listening to uri=hm://pusher/v1/connections/
Oct 08 09:27:14 htpc snapserver[3305]: listening to uri=hm://remote/user/
Oct 08 09:27:14 htpc snapserver[3305]: listening to uri=spotify:user:attributes:update
Oct 08 09:27:14 htpc snapserver[3305]: Session strong=4 weak=3
Oct 08 09:27:14 htpc snapserver[3305]: Country: "GB"
Oct 08 09:27:14 htpc snapserver[3305]: command=AddEventSender
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "streaming": "1",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "arsenal_country": "1",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "npt-disabled": "2",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "profile-image-upload": "1",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "ab_recently_played_feature_time_filter_threshold": "com.spotify.gaia=30,driving-mode=120,spotify%3Ainternal%3Astartpage=30",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "ad-formats-video-takeover": "1",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "audio-quality": "1",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "name": "Spotify Premium",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "loudness-levels": "1:-5.0,0.0,3.0:-2.0",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "ab-playlist-extender": "5",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "publish-playlist": "0",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "expiry": "1",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "streaming-rules": "",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "head-files-url": "https://heads-ak-spotify-com.akamaized.net/head/{file_id}",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "ab-ad-player-targeting": "1",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "ab-watch-now": "0",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "use-playlist-uris": "0",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "ab-desktop-playlist-annotation-edit": "1",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "multiuserplan-current-size": "3",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "key-caching-max-offline-seconds": "1800",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "prefetch-keys": "1",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "india-experience": "0",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "preferred-locale": "en",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "ab-collection-hide-unavailable-albums": "0",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "instant-search-expand-sidebar": "0",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "key-caching-auto-offline": "0",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "enable-annotations-read": "0",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "backend-advised-bitrate": "1",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "libspotify": "1",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "mobile-browse": "0",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "ab-browse-music-tuesday": "1",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "payment-state": "",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "capping-bar-threshold": "3601",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "financial-product": "pr:premium,tc:0,rt:v2_GB_default_new-family-master-1m_16.99_GBP_default",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "is_maybe_in_social_session": "0",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "payments-locked-state": "0",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "license-acceptance-grace-days": "30",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "shuffle": "0",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "storage-size-config": "10240,90,500,3",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "unrestricted": "1",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "user-profile-show-invitation-codes": "0",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "ab-play-history": "0",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "browse-overview-enabled": "1",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "track-cap": "0",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "is_email_verified": "1",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "player-license": "premium",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "ab-desktop-hide-follow": "0",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "autoplay": "1",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "enable-gapless": "1",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "catalogue": "premium",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "products": "",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "ab-sugarpills-sanity-check": "0",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "type": "premium",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "ab-collection-followed-artists-only": "0",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "request-time": "6",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "video-initial-bitrate": "200000",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "video-cdn-sampling": "1",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "app-developer": "0",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "allow-override-internal-prefs": "0",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "use-pl3": "0",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "mobile-login": "1",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "addon-hifi": "0",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "buffering-strategy-parameters": "0.8:0.2:0.0:0.0:0.0:0.0:1.0:10:10:2000:10000:10485760",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "filter-explicit-content": "0",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "metadata-link-lookup-modes": "0",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "ab-mobile-running-tempo-detection": "Control",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "multiuserplan-member-type": "FAMILY_MANAGER",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "ab-new-share-flow": "0",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "send-email": "0",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "ad-session-persistence": "1",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "offline": "1",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "fb-grant-permission-local-render": "0",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "ab-mobile-social-feed": "1",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "collection": "1",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "explicit-content": "1",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "ab-mobile-running-onlymanualmode": "only-manual",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "pause-after": "0",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "high-bitrate": "1",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "ab-mobile-startpage": "0",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "incognito_mode_timeout": "21600",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "buffering-strategy": "0",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "license-agreements": "",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "ap-resolve-pods": "1",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "remote-control": "6",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "ab-test-group": "97",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "product": "",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "head-file-caching": "1",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "hifi-eligible": "0",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "publish-activity": "0",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "video-keyframe-url": "http://keyframes-fa.cdn.spotify.com/keyframes/v1/sources/{source_id}/keyframe/heights/{height}/timestamps/{timestamp_ms}.jpg",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "nft-disabled": "1",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "ab-collection-offline-mode": "0",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "payments-initial-campaign": "default",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "ads": "0",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "enable-autostart": "1",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "lastfm-session": "",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "enable-annotations": "2",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "key-memory-cache-mode": "1:15,300",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "mobile": "1",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "mobile-payment": "0",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "network-operator-premium-activation": "1",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "playlist-annotations-markup": "0",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "ab-android-push-notifications": "1",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "enable-crossfade": "1",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "shows-collection": "1",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "ab-collection-union": "1",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "ab-ad-requester": "1",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "shuffle-algorithm": "1",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "key-caching-max-count": "10000",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "ad-persist-reward-time": "0",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "fb-info-confirmation": "control",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "use-fb-publish-backend": "2",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "wanted-licenses": "",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "use-playlist-app": "0",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "radio": "1",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "hifi-optin-intent": "0",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "widevine-license-url": "https://spclient.wg.spotify.com/widevine-license/v1/video/license",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "shows-collection-jam": "1",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "video-wifi-initial-bitrate": "800000",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "prefetch-window-max": "2",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "video-device-blacklisted": "0",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "capper-profile": "",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "ab-collection-bookmark-model": "1",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "audio-preview-url-template": "https://p.scdn.co/mp3-preview/{id}",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "prefetch-strategy": "18",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "sidebar-navigation-enabled": "0",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "playlist-folders": "1",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "ad-formats-preroll-video": "0",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "ab-moments-experience": "0",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "ab-mobile-discover": "0",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "ugc-abuse-report-url": "https://support.spotify.com/abuse/?uri={uri}",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "instant-search": "0",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "head-files": "1",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "image-url": "https://i.scdn.co/image/{file_id}",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "local-files-import": "0",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "ugc-abuse-report": "1",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "on-demand": "1",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         "video-manifest-url": "https://spclient.wg.spotify.com/manifests/v6/{type}/sources/{source_id}/options/supports_drm",
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)     }
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)     ident: "HIDDEN"
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)     protocol_version: "2.0.0"
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)     seq_nr: 917808001
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)     typ: kMessageTypeNotify
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)     device_state {
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)       sw_version: "8.6.46.886"
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)       is_active: false
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)       can_play: true
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)       volume: 65535
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)       name: "Galaxy S8"
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)       capabilities {
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         typ: kCanBePlayer
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         intValue: 1
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)       }
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)       capabilities {
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         typ: kRestrictToLocal
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         intValue: 0
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)       }
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)       capabilities {
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         typ: kGaiaEqConnectId
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         intValue: 1
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)       }
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)       capabilities {
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         typ: kSupportsLogout
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         intValue: 1
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)       }
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)       capabilities {
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         typ: kIsObservable
Oct 08 09:27:14 htpc snapserver[3305]: terminate called after throwing an instance of 'nlohmann::detail::type_error'
Oct 08 09:27:14 htpc snapserver[3305]:   what():  [json.exception.type_error.305] cannot use operator[] with a string argument with string
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         intValue: 1
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)       }
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)       capabilities {
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         typ: kCommandAcks
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         intValue: 1
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)       }
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)       capabilities {
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         typ: kSupportsRename
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         intValue: 1
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)       }
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)       capabilities {
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         typ: kHidden
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         intValue: 0
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)       }
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)       capabilities {
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         typ: kDeviceType
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         intValue: 3
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)       }
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)       capabilities {
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         typ: kVolumeSteps
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         intValue: 32
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)       }
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)       capabilities {
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         typ: kSupportsPlaylistV2
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         intValue: 1
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)       }
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)       capabilities {
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         typ: kSupportsExternalEpisodes
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         intValue: 1
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)       }
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)       capabilities {
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         typ: kSupportedTypes
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         stringValue: "audio/ad"
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         stringValue: "audio/episode"
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         stringValue: "audio/episode+track"
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         stringValue: "audio/interruption"
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         stringValue: "audio/local"
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         stringValue: "audio/track"
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         stringValue: "video/ad"
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         stringValue: "video/episode"
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)       }
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)       metadata {
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         type: "device_address_mask"
Oct 08 09:27:14 htpc snapserver[3305]: (Spotify)         metadata: "192.168.0.41/24"
Oct 08 09:27:14 htpc snapserver[3305]: metadata: < "192.168.0.41/24">
Oct 08 09:27:14 htpc systemd[1]: snapserver.service: Main process exited, code=killed, status=6/ABRT
Oct 08 09:27:14 htpc systemd[1]: snapserver.service: Failed with result 'signal'.
Oct 08 09:27:14 htpc systemd[1]: snapserver.service: Scheduled restart job, restart counter is at 1.
Oct 08 09:27:14 htpc systemd[1]: Stopped Snapcast server.
Oct 08 09:27:14 htpc systemd[1]: Started Snapcast server.
kingosticks commented 2 years ago

There are some fixes in the develop branch for previous reported issues like this. Have you tried the latest code?

elParaguayo commented 2 years ago

No. I haven't tried that. The AUR package just picks up the last tagged release. Let me modify it and try the develop branch. Will report back later.

Thanks.

elParaguayo commented 2 years ago

Just installed the development branch - got the same error.

EDIT: Installing the librespot master branch (dev is default) removes this issue so it looks like there's some change that snapcast may need to cater for.

badaix commented 2 years ago

Please try the latest develop version, I've replaces all regex searches with simple string parsing (f3372b1491ebeec3d0d84bed1985e28006ebfdc3). I hope this fixes the issue.

elParaguayo commented 2 years ago

Thanks. Will test when I can.

Jeepmb commented 1 year ago

Getting a very similar issue on Raspberry Pi OS 11. Installed via apt from the downloaded _snapserver_0.26.0-1armhf.deb. Server runs fine with Librespot disabled, but fails to start the service when enabled. Getting the following output (scrubbed sensitive info):

Nov  9 10:47:04 home-pi systemd[1]: Started Snapcast server.
Nov  9 10:47:04 home-pi snapserver[5470]: Version 0.26.0, revision e30a9f33
Nov  9 10:47:04 home-pi snapserver[5470]: Adding stream: spotify:///librespot?name=Spotify&username=<SECRET_USERNAME>&password=<SECRET_PASSWORD>&devicename=Snapcast&bitrate=320&volume=90
Nov  9 10:47:04 home-pi snapserver[5470]: Adding source: pipe:///tmp/snapfifo?name=default
Nov  9 10:47:04 home-pi snapserver[5470]: Settings file: "/var/lib/snapserver/server.json"
Nov  9 10:47:04 home-pi snapserver[5470]: Using HTTP host name: home-pi
Nov  9 10:47:04 home-pi snapserver[5470]: Adding service 'Snapcast'
Nov  9 10:47:04 home-pi snapserver[5470]: PcmStream: Spotify, sampleFormat: 44100:16:2
Nov  9 10:47:04 home-pi snapserver[5470]: Stream: {"fragment":"","host":"","path":"/librespot","query":{"bitrate":"320","chunk_ms":"20","codec":"flac","devicename":"Snapcast","name":"Spotify","password":"xxx","sampleformat":"44100:16:2","username":"xxx","volume":"90"},"raw":"spotify:////librespot?bitrate=320&chunk_ms=20&codec=flac&devicename=Snapcast&name=Spotify&password=xxx&sampleformat=44100:16:2&username=xxx&volume=90","scheme":"spotify"}
Nov  9 10:47:04 home-pi snapserver[5470]: PcmStream: default, sampleFormat: 48000:16:2
Nov  9 10:47:04 home-pi snapserver[5470]: PipeStream mode: create
Nov  9 10:47:04 home-pi snapserver[5470]: Stream: {"fragment":"","host":"","path":"/tmp/snapfifo","query":{"chunk_ms":"20","codec":"flac","name":"default","sampleformat":"48000:16:2"},"raw":"pipe:////tmp/snapfifo?chunk_ms=20&codec=flac&name=default&sampleformat=48000:16:2","scheme":"pipe"}
Nov  9 10:47:04 home-pi snapserver[5470]: Init - compression level: 2
Nov  9 10:47:04 home-pi snapserver[5470]: Starting watchdog, timeout: 7800s
Nov  9 10:47:04 home-pi snapserver[5470]: Init - compression level: 2
Nov  9 10:47:04 home-pi snapserver[5470]: Exception: end of file
Nov  9 10:47:04 home-pi snapserver[5470]: Creating TCP acceptor for address: 0.0.0.0, port: 1705
Nov  9 10:47:04 home-pi snapserver[5470]: Creating HTTP acceptor for address: 0.0.0.0, port: 1780
Nov  9 10:47:04 home-pi snapserver[5470]: Creating stream acceptor for address: 0.0.0.0, port: 1704
Nov  9 10:47:04 home-pi snapserver[5470]: Number of threads: 4, hw threads: 4
Nov  9 10:47:04 home-pi snapserver[5470]: librespot 0.5.0-dev 2c20669 (Built on 2022-11-09, Build ID: DimfJ3O8, Profile: release)
Nov  9 10:47:04 home-pi snapserver[5470]: Mixing with softvol and volume control: Log(60.0)
Nov  9 10:47:04 home-pi snapserver[5470]: Converting with ditherer: tpdf
Nov  9 10:47:04 home-pi snapserver[5470]: Using StdoutSink (pipe) with format: S16
Nov  9 10:47:05 home-pi snapserver[5470]: Connecting to AP "ap-guc3.spotify.com:4070"
Nov  9 10:47:05 home-pi snapserver[5470]: Authenticated as "****************************" !
Nov  9 10:47:05 home-pi snapserver[5470]: Country: "US"
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "ab-collection-union": "1",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "ab-sugarpills-sanity-check": "0",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "audio-preview-url-template": "https://p.scdn.co/mp3-preview/{id}",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "pause-after": "0",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "playlist-folders": "1",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "ab-android-push-notifications": "1",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "key-caching-max-offline-seconds": "1800",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "payment-state": "",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "preferred-locale": "en",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "video-manifest-url": "https://spclient.wg.spotify.com/manifests/v3/{type}/sources/{source_id}",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "high-bitrate": "1",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "streaming-rules": "",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "user-profile-show-invitation-codes": "0",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "lastfm-session": "",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "ads": "0",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "mobile-login": "1",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "player-license": "premium",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "publish-activity": "0",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "shuffle-algorithm": "1",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "video-cdn-sampling": "1",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "ab-collection-hide-unavailable-albums": "0",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "ad-session-persistence": "1",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "ab-test-group": "86",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "financial-product": "pr:premium,tc:0,rt:v2_US_default_new-family-sub-1m_0_USD_default",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "ab-collection-followed-artists-only": "1",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "ab-browse-music-tuesday": "1",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "enable-annotations-read": "0",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "local-files-import": "0",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "product": "",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "on-demand": "1",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "radio": "1",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "active-session-days": "14",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "mobile-payment": "0",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "is_email_verified": "0",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "streaming": "1",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "video-device-blacklisted": "0",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "track-cap": "0",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "license-agreements": "",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "network-operator-premium-activation": "1",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "ab-mobile-running-tempo-detection": "Control",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "sidebar-navigation-enabled": "0",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "ugc-abuse-report": "0",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "ab-mobile-startpage": "0",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "name": "Spotify Premium",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "buffering-strategy": "2",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "prefetch-keys": "1",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "enable-crossfade": "1",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "collection": "1",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "offline": "1",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "arsenal_country": "1",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "video-keyframe-url": "http://keyframes-fa.cdn.spotify.com/keyframes/v1/sources/{source_id}/keyframe/heights/{height}/timestamps/{timestamp_ms}.jpg",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "head-files-url": "http://heads-fa.spotify.com/head/{file_id}",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "license-acceptance-grace-days": "30",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "ap-resolve-pods": "0",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "ab-play-history": "0",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "fb-grant-permission-local-render": "0",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "shows-collection-jam": "0",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "widevine-license-url": "https://spclient.wg.spotify.com/widevine-license/v1/video/license",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "fb-info-confirmation": "control",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "instant-search-expand-sidebar": "0",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "key-caching-max-count": "10000",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "use-playlist-uris": "0",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "video-initial-bitrate": "200000",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "wanted-licenses": "",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "shuffle": "0",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "unrestricted": "1",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "expiry": "1",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "ab_recently_played_feature_time_filter_threshold": "com.spotify.gaia=30,driving-mode=120,spotify%3Ainternal%3Astartpage=30",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "browse-overview-enabled": "1",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "use-fb-publish-backend": "2",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "type": "premium",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "ab-new-share-flow": "0",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "enable-autostart": "1",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "send-email": "1",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "prefetch-window-max": "2",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "head-file-caching": "1",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "capper-profile": "",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "publish-playlist": "1",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "created_by_partner": "",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "india-experience": "0",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "instant-search": "0",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "products": "",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "libspotify": "1",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "remote-control": "0",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "storage-size-config": "10240,90,500,3",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "allow-override-internal-prefs": "0",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "explicit-content": "1",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "payments-locked-state": "0",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "nft-disabled": "1",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "ab-mobile-social-feed": "1",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "ad-formats-video-takeover": "0",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "ab-mobile-running-onlymanualmode": "only-manual",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "mobile": "1",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "head-files": "1",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "mobile-browse": "0",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "shows-collection": "0",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "buffering-strategy-parameters": "0.8:0.2:0.0:0.0:0.0:0.0:1.0:10:10:2000:10000:10485760",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "ab-collection-offline-mode": "0",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "prefetch-strategy": "18",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "catalogue": "premium",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "enable-gapless": "1",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "npt-disabled": "2",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "ab-ad-player-targeting": "1",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "payments-initial-campaign": "default",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "ugc-abuse-report-url": "https://support.spotify.com/abuse/?uri={uri}",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "ad-formats-preroll-video": "0",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "metadata-link-lookup-modes": "0",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "app-developer": "0",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "capping-bar-threshold": "3601",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "ab-desktop-hide-follow": "1",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "video-wifi-initial-bitrate": "800000",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "image-url": "https://i.scdn.co/image/{file_id}",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "ab-watch-now": "0",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "ab-mobile-discover": "0",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "incognito_mode_timeout": "21600",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "use-pl3": "0",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "ab-collection-bookmark-model": "1",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "playlist-annotations-markup": "0",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "ad-persist-reward-time": "0",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "is_maybe_in_social_session": "0",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         "ab-moments-experience": "0",
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)     }
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)     ident: "8b55cb21d5ecb544f58c376805b7576141f71f51"
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)     protocol_version: "2.0.0"
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)     seq_nr: 1568715317
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)     typ: kMessageTypeNotify
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)     device_state {
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)       sw_version: "harmony:4.27.1-af7f4f3/track-playback"
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)       is_active: false
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)       can_play: true
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)       volume: 65535
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)       name: "Web Player (Chrome)"
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)       capabilities {
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         typ: kCanBePlayer
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         intValue: 1
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)       }
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)       capabilities {
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         typ: kRestrictToLocal
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         intValue: 0
Nov  9 10:47:05 home-pi snapserver[5470]: terminate called after throwing an instance of 'nlohmann::detail::type_error'
Nov  9 10:47:05 home-pi snapserver[5470]:   what():  [json.exception.type_error.305] cannot use operator[] with a string argument with string
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)       }
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)       capabilities {
Nov  9 10:47:05 home-pi systemd[1]: snapserver.service: Main process exited, code=killed, status=6/ABRT
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         typ: kGaiaEqConnectId
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         intValue: 1
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)       }
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)       capabilities {
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         typ: kSupportsLogout
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         intValue: 1
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)       }
Nov  9 10:47:05 home-pi systemd[1]: snapserver.service: Failed with result 'signal'.
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)       capabilities {
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         typ: kIsObservable
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         intValue: 1
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)       }
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)       capabilities {
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         typ: kCommandAcks
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         intValue: 1
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)       }
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)       capabilities {
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         typ: kSupportsRename
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         intValue: 0
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)       }
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)       capabilities {
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         typ: kHidden
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         intValue: 0
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)       }
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)       capabilities {
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         typ: kDeviceType
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         intValue: 1
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)       }
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)       capabilities {
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         typ: kVolumeSteps
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         intValue: 64
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)       }
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)       capabilities {
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         typ: kSupportsPlaylistV2
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         intValue: 0
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)       }
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)       capabilities {
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         typ: kSupportsExternalEpisodes
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         intValue: 1
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)       }
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)       capabilities {
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         typ: kSupportedTypes
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         stringValue: "audio/track"
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         stringValue: "audio/episode"
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         stringValue: "video/episode"
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         stringValue: "mixed/episode"
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)       }
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)       metadata {
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         type: "client_id"
Nov  9 10:47:05 home-pi snapserver[5470]: (Spotify)         metadata: "d8a5ed958d274c2e8ee717e6a4b0971d"
Nov  9 10:47:05 home-pi snapserver[5470]: metadata: < "d8a5ed958d274c2e8ee717e6a4b0971d">
Nov  9 10:47:05 home-pi systemd[1]: snapserver.service: Scheduled restart job, restart counter is at 5.
Nov  9 10:47:05 home-pi systemd[1]: Stopped Snapcast server.
Nov  9 10:47:05 home-pi systemd[1]: snapserver.service: Start request repeated too quickly.
Nov  9 10:47:05 home-pi systemd[1]: snapserver.service: Failed with result 'signal'.
Nov  9 10:47:05 home-pi systemd[1]: Failed to start Snapcast server.
Jeepmb commented 1 year ago

Just to follow up, using @elParaguayo suggestion of building Librespot from the stable 0.4.2 instead of 0.5.0-dev, I was able to get everything working without issue.

@badaix, I would have texted but I wasn't quite able to figure out how to pull the dev version of Snapcast. Sorry!

badaix commented 1 year ago

Fixed in snapcast v0.27.0