-- Logs begin at Tue 2026-01-13 19:47:44 CET, end at Tue 2026-01-13 21:20:14 CET. -- Jan 13 21:19:00 volumio go-librespot[24273]: time="2026-01-13T21:19:00+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 21:19:00 volumio volumio[827]: info: Installing/fusiondsp dependencies Jan 13 21:19:00 volumio go-librespot[24273]: time="2026-01-13T21:19:00+01:00" level=debug msg="app state loaded" Jan 13 21:19:00 volumio go-librespot[24273]: time="2026-01-13T21:19:00+01:00" level=debug msg="stored credentials not found" Jan 13 21:19:00 volumio go-librespot[24273]: time="2026-01-13T21:19:00+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 21:19:00 volumio sudo[24280]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt update Jan 13 21:19:00 volumio sudo[24280]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 21:19:00 volumio go-librespot[24273]: time="2026-01-13T21:19:00+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 13 21:19:00 volumio go-librespot[24273]: time="2026-01-13T21:19:00+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 13 21:19:00 volumio go-librespot[24273]: time="2026-01-13T21:19:00+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 13 21:19:00 volumio go-librespot[24273]: time="2026-01-13T21:19:00+01:00" level=info msg="zeroconf server listening on port 42587" Jan 13 21:19:00 volumio go-librespot[24273]: time="2026-01-13T21:19:00+01:00" level=debug msg="obtained new client token: AADVPIibyRDJRdZvwxCkSjOSiPLxOrmyL2ROaRTcdXnml1n+gjLTpzi5tbFt1Da+N5eSBxHeyphhS+HCGMrc5wjzqZF8P/z9nkWcyDt1M+imbmXiTCgRszAJxNSA0Bra1BZzD7Mq8cZkUbkEpwGgtbM5UmR/ONNhFt7SJD56ZUNgSQwDTxGOy1B+5OjFjSIKExi4S6/wuASyFI5kk6v7HLKxxQW4fU/rhrQtwNl9io1LV2hR12jt7WJ2" Jan 13 21:19:00 volumio go-librespot[24273]: time="2026-01-13T21:19:00+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Jan 13 21:19:00 volumio go-librespot[24273]: time="2026-01-13T21:19:00+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Jan 13 21:19:00 volumio go-librespot[24273]: time="2026-01-13T21:19:00+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 13 21:19:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:19:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:19:00 volumio volumio[827]: info: Hit:3 https://deb.nodesource.com/node_14.x buster InRelease Jan 13 21:19:01 volumio volumio[827]: info: Get:1 https://apt2.volumio.org/raspbian buster InRelease [15.0 kB] Jan 13 21:19:01 volumio volumio[827]: info: Get:2 https://archive2.volumio.org/debian buster InRelease [54.2 kB] Jan 13 21:19:02 volumio volumio[827]: info: Initializing connection to go-librespot Websocket Jan 13 21:19:02 volumio volumio[827]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 21:19:02 volumio volumio[827]: info: Get:4 https://apt2.volumio.org/raspbian buster/main armhf Packages [13.0 MB] Jan 13 21:19:03 volumio volumio[827]: info: Get:5 https://archive2.volumio.org/debian buster/main armhf Packages [400 kB] Jan 13 21:19:04 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 13 21:19:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2935. Jan 13 21:19:04 volumio systemd[1]: Stopped go-librespot Daemon. Jan 13 21:19:04 volumio systemd[1]: Started go-librespot Daemon. Jan 13 21:19:04 volumio go-librespot[24503]: go-librespot daemon starting... Jan 13 21:19:04 volumio go-librespot[24503]: time="2026-01-13T21:19:04+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 21:19:04 volumio go-librespot[24503]: time="2026-01-13T21:19:04+01:00" level=debug msg="app state loaded" Jan 13 21:19:04 volumio go-librespot[24503]: time="2026-01-13T21:19:04+01:00" level=debug msg="stored credentials not found" Jan 13 21:19:04 volumio go-librespot[24503]: time="2026-01-13T21:19:04+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 21:19:05 volumio volumio[827]: info: Initializing connection to go-librespot Websocket Jan 13 21:19:05 volumio go-librespot[24503]: time="2026-01-13T21:19:05+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 13 21:19:05 volumio go-librespot[24503]: time="2026-01-13T21:19:05+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 13 21:19:05 volumio go-librespot[24503]: time="2026-01-13T21:19:05+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 13 21:19:05 volumio go-librespot[24503]: time="2026-01-13T21:19:05+01:00" level=info msg="zeroconf server listening on port 42019" Jan 13 21:19:05 volumio go-librespot[24503]: time="2026-01-13T21:19:05+01:00" level=debug msg="obtained new client token: AAD2Ri6Oxtu1+LqFySP29NIzuJAiHW9Iuy0wbmqMcwjU3LDnchADVqKZ7yR2ehNp+P7C2eH4k08D9rnebDlBgNSTq0FZWjVivd4YbkuyaPZNtzDuYiCqgVHIzjUnNlkIz5Fq2aAoLzxGE6Vwp2ojBvtwcyHsyUwLoHL3CEt/2EP8jiOcvE2FFF2T/JYN1B19qhw60F0bo7zn07PVFGPfspokmAE8BtWHOc5PIm3pH2qdjwl80LckMbZt" Jan 13 21:19:05 volumio go-librespot[24503]: time="2026-01-13T21:19:05+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jan 13 21:19:06 volumio go-librespot[24503]: time="2026-01-13T21:19:06+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: read tcp 192.168.68.107:41288->34.158.1.133:4070: read: connection reset by peer" Jan 13 21:19:06 volumio volumio[827]: info: Error connecting to go-librespot Websocket: Error: socket hang up Jan 13 21:19:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:19:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:19:08 volumio volumio[827]: info: Get:6 https://apt2.volumio.org/raspbian buster/contrib armhf Packages [58.8 kB] Jan 13 21:19:08 volumio volumio[827]: info: Get:7 https://apt2.volumio.org/raspbian buster/non-free armhf Packages [110 kB] Jan 13 21:19:08 volumio volumio[827]: info: Get:8 https://apt2.volumio.org/raspbian buster/rpi armhf Packages [1360 B] Jan 13 21:19:09 volumio volumio[827]: info: Initializing connection to go-librespot Websocket Jan 13 21:19:09 volumio volumio[827]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 21:19:09 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 13 21:19:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2936. Jan 13 21:19:09 volumio systemd[1]: Stopped go-librespot Daemon. Jan 13 21:19:09 volumio systemd[1]: Started go-librespot Daemon. Jan 13 21:19:09 volumio go-librespot[24536]: go-librespot daemon starting... Jan 13 21:19:09 volumio go-librespot[24536]: time="2026-01-13T21:19:09+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 21:19:09 volumio go-librespot[24536]: time="2026-01-13T21:19:09+01:00" level=debug msg="app state loaded" Jan 13 21:19:09 volumio go-librespot[24536]: time="2026-01-13T21:19:09+01:00" level=debug msg="stored credentials not found" Jan 13 21:19:09 volumio go-librespot[24536]: time="2026-01-13T21:19:09+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 21:19:09 volumio go-librespot[24536]: time="2026-01-13T21:19:09+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jan 13 21:19:09 volumio go-librespot[24536]: time="2026-01-13T21:19:09+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jan 13 21:19:09 volumio go-librespot[24536]: time="2026-01-13T21:19:09+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jan 13 21:19:09 volumio go-librespot[24536]: time="2026-01-13T21:19:09+01:00" level=info msg="zeroconf server listening on port 36077" Jan 13 21:19:09 volumio go-librespot[24536]: time="2026-01-13T21:19:09+01:00" level=debug msg="obtained new client token: AAA0NcjX90f7SMTouOTLxCJepSuMqt+enm6KcI4MQ6yYxyou/IkyYIsvKEQvTcweLWC829OqSFUliJpShWmrZEMbvn0mOJ/7wB4PHbOffxkYoJfv7C4C19BOKcXKyfyIoc7j5YD3sln3ePgCHIQ+yo3Nup3awzVS6AG+3480s8sxS/OcRUW6jbxUTZHTOxb7fj4iON/HjnphezQ62BkfXawbOQh/MJ6Ia/yRONsfgI17M85kiq+7hAbU" Jan 13 21:19:09 volumio go-librespot[24536]: time="2026-01-13T21:19:09+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jan 13 21:19:10 volumio go-librespot[24536]: time="2026-01-13T21:19:10+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: read tcp 192.168.68.107:41290->34.158.1.133:4070: read: connection reset by peer" Jan 13 21:19:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:19:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:19:12 volumio volumio[827]: info: Initializing connection to go-librespot Websocket Jan 13 21:19:12 volumio volumio[827]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 21:19:13 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 13 21:19:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2937. Jan 13 21:19:13 volumio systemd[1]: Stopped go-librespot Daemon. Jan 13 21:19:13 volumio systemd[1]: Started go-librespot Daemon. Jan 13 21:19:13 volumio go-librespot[24578]: go-librespot daemon starting... Jan 13 21:19:29 volumio volumio[827]: info: Initializing connection to go-librespot Websocket Jan 13 21:19:29 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 13 21:19:29 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 13 21:19:29 volumio volumio[827]: verbose: New Socket.io Connection to 192.168.68.107:3000 from 192.168.68.100 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 1 Jan 13 21:19:29 volumio volumio[827]: verbose: New Socket.io Connection to 192.168.68.107 from 192.168.68.100 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 16_7_12 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 2 Jan 13 21:19:29 volumio volumio[827]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 21:19:29 volumio volumio[827]: verbose: New Socket.io Connection to 192.168.68.107:3000 from 192.168.68.100 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 Jan 13 21:19:30 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 13 21:19:30 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 13 21:19:30 volumio volumio[827]: info: Discovery: Getting this device information Jan 13 21:19:30 volumio volumio[827]: info: CoreCommandRouter::volumioGetState Jan 13 21:19:30 volumio volumio[827]: info: CorePlayQueue::getTrack 0 Jan 13 21:19:30 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 13 21:19:30 volumio volumio[827]: verbose: New Socket.io Connection to 192.168.68.107 from 192.168.68.100 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 16_7_12 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 4 Jan 13 21:19:30 volumio volumio[827]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jan 13 21:19:30 volumio volumio[827]: info: CoreCommandRouter::volumioGetState Jan 13 21:19:30 volumio volumio[827]: info: CorePlayQueue::getTrack 0 Jan 13 21:19:30 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 13 21:19:30 volumio go-librespot[24578]: time="2026-01-13T21:19:30+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 21:19:30 volumio volumio[827]: info: CoreCommandRouter::volumioGetVisibleSources Jan 13 21:19:30 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 21:19:30 volumio volumio[827]: info: CoreCommandRouter::volumioGetState Jan 13 21:19:30 volumio volumio[827]: info: CorePlayQueue::getTrack 0 Jan 13 21:19:30 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 13 21:19:30 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 13 21:19:30 volumio volumio[827]: info: Received Get System Info Jan 13 21:19:30 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 13 21:19:30 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 13 21:19:30 volumio volumio[827]: info: Discovery: Getting this device information Jan 13 21:19:30 volumio volumio[827]: info: CoreCommandRouter::volumioGetState Jan 13 21:19:30 volumio volumio[827]: info: CorePlayQueue::getTrack 0 Jan 13 21:19:30 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 13 21:19:30 volumio go-librespot[24578]: time="2026-01-13T21:19:30+01:00" level=debug msg="app state loaded" Jan 13 21:19:30 volumio go-librespot[24578]: time="2026-01-13T21:19:30+01:00" level=debug msg="stored credentials not found" Jan 13 21:19:30 volumio volumio[827]: info: CoreCommandRouter::volumioGetState Jan 13 21:19:30 volumio volumio[827]: info: CorePlayQueue::getTrack 0 Jan 13 21:19:30 volumio volumio[827]: info: Listing playlists Jan 13 21:19:30 volumio volumio[827]: info: CoreCommandRouter::volumioGetQueue Jan 13 21:19:30 volumio volumio[827]: info: CoreStateMachine::getQueue Jan 13 21:19:30 volumio volumio[827]: info: CorePlayQueue::getQueue Jan 13 21:19:30 volumio volumio-remote-updater[495]: [2026-01-13 21:19:30] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1000] Jan 13 21:19:30 volumio go-librespot[24578]: time="2026-01-13T21:19:30+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 21:19:30 volumio volumio[827]: verbose: New Socket.io Connection to 192.168.68.107 from 192.168.68.100 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 16_7_12 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Jan 13 21:19:30 volumio volumio[827]: info: Fetched 13.7 MB in 23s (593 kB/s) Jan 13 21:19:30 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 13 21:19:30 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 13 21:19:30 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 13 21:19:30 volumio volumio[827]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jan 13 21:19:30 volumio volumio[827]: info: CoreCommandRouter::volumioGetVisibleSources Jan 13 21:19:30 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 21:19:30 volumio volumio[827]: info: CoreCommandRouter::volumioGetState Jan 13 21:19:30 volumio volumio[827]: info: CorePlayQueue::getTrack 0 Jan 13 21:19:30 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 13 21:19:30 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 13 21:19:30 volumio volumio[827]: info: Received Get System Info Jan 13 21:19:30 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 13 21:19:30 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 13 21:19:30 volumio volumio[827]: info: Discovery: Getting this device information Jan 13 21:19:30 volumio volumio[827]: info: CoreCommandRouter::volumioGetState Jan 13 21:19:30 volumio volumio[827]: info: CorePlayQueue::getTrack 0 Jan 13 21:19:30 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 13 21:19:30 volumio volumio[827]: info: CoreCommandRouter::volumioGetState Jan 13 21:19:30 volumio volumio[827]: info: CorePlayQueue::getTrack 0 Jan 13 21:19:30 volumio volumio[827]: info: Listing playlists Jan 13 21:19:31 volumio go-librespot[24578]: time="2026-01-13T21:19:31+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 13 21:19:31 volumio go-librespot[24578]: time="2026-01-13T21:19:31+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 13 21:19:31 volumio go-librespot[24578]: time="2026-01-13T21:19:31+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 13 21:19:31 volumio volumio[827]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jan 13 21:19:31 volumio go-librespot[24578]: time="2026-01-13T21:19:31+01:00" level=info msg="zeroconf server listening on port 41815" Jan 13 21:19:31 volumio volumio[827]: info: CoreCommandRouter::volumioGetState Jan 13 21:19:31 volumio volumio[827]: info: CorePlayQueue::getTrack 0 Jan 13 21:19:31 volumio go-librespot[24578]: time="2026-01-13T21:19:31+01:00" level=debug msg="obtained new client token: AABo6k9tJua2h1BsqPppg879CjIOsAwn2es13LB2wJwRPkfUT5oU7ghmixwrCXb8egopz7w1VGtDiPpTLS+WUPbvsZxkh2TRulJX9LkJp8SF6tRy1gjGlxOevugOSPTH5lkRK6TUi+kI0tKGZha+JiONt5tH4uqyWsKaiT6JnkHBUwoA1/+GlwG0isN5kRa98hub7+k4bQ5Ge9wR4u/M1YuCIQ9ZvtC+kjeCWAlZwxRdchlQrwAu2w==" Jan 13 21:19:31 volumio go-librespot[24578]: time="2026-01-13T21:19:31+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jan 13 21:19:31 volumio go-librespot[24578]: time="2026-01-13T21:19:31+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: read tcp 192.168.68.107:49148->34.158.1.133:4070: read: connection reset by peer" Jan 13 21:19:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:19:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:19:32 volumio volumio[827]: info: Initializing connection to go-librespot Websocket Jan 13 21:19:32 volumio volumio[827]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 21:19:34 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 13 21:19:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2938. Jan 13 21:19:34 volumio systemd[1]: Stopped go-librespot Daemon. Jan 13 21:19:34 volumio systemd[1]: Started go-librespot Daemon. Jan 13 21:19:35 volumio go-librespot[24605]: go-librespot daemon starting... Jan 13 21:19:35 volumio go-librespot[24605]: time="2026-01-13T21:19:35+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 21:19:35 volumio go-librespot[24605]: time="2026-01-13T21:19:35+01:00" level=debug msg="app state loaded" Jan 13 21:19:35 volumio go-librespot[24605]: time="2026-01-13T21:19:35+01:00" level=debug msg="stored credentials not found" Jan 13 21:19:35 volumio go-librespot[24605]: time="2026-01-13T21:19:35+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 21:19:35 volumio volumio[827]: info: Initializing connection to go-librespot Websocket Jan 13 21:19:35 volumio volumio[827]: info: Connection to go-librespot Websocket established Jan 13 21:19:35 volumio go-librespot[24605]: time="2026-01-13T21:19:35+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 13 21:19:35 volumio go-librespot[24605]: time="2026-01-13T21:19:35+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 13 21:19:35 volumio go-librespot[24605]: time="2026-01-13T21:19:35+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 13 21:19:35 volumio go-librespot[24605]: time="2026-01-13T21:19:35+01:00" level=info msg="zeroconf server listening on port 42081" Jan 13 21:19:35 volumio volumio-remote-updater[495]: [2026-01-13 21:19:35] [connect] Successful connection Jan 13 21:19:35 volumio volumio-remote-updater[495]: [2026-01-13 21:19:35] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1768335575 101 Jan 13 21:19:35 volumio volumio[827]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 9 Jan 13 21:19:35 volumio go-librespot[24605]: time="2026-01-13T21:19:35+01:00" level=debug msg="new websocket client" Jan 13 21:19:35 volumio go-librespot[24605]: time="2026-01-13T21:19:35+01:00" level=debug msg="obtained new client token: AAC9Q0KLb4vczzZS01L/JnPrc32NKzYaUltM3u93iddAV140nUWJ5yqio3JAJCC/WMt4+jyBoGyNPjfCQ2Yae4ZDVzyaj+zOI8+niU5tkzPAIm4TrFRztDBUNm6Y1psoXJomTnaRhF7MDDJD1ZCzP+tbdJ6FyF9Ks0rski4FevF+sY7NB8J0DWLApasx99ItAk8fbZzqKLm5lxN6Qo6nwmnR76vlj9Of4pl4LjP+0/rBXscaupXEGorv" Jan 13 21:19:35 volumio go-librespot[24605]: time="2026-01-13T21:19:35+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jan 13 21:19:36 volumio go-librespot[24605]: time="2026-01-13T21:19:36+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: read tcp 192.168.68.107:46356->34.158.1.133:4070: read: connection reset by peer" Jan 13 21:19:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:19:36 volumio volumio[827]: info: Connection to go-librespot Websocket closed Jan 13 21:19:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:19:38 volumio volumio[827]: info: Getting Spotify volume Jan 13 21:19:38 volumio volumio[827]: (node:827) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 21:19:38 volumio volumio[827]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jan 13 21:19:38 volumio volumio[827]: (node:827) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 767) Jan 13 21:19:38 volumio volumio[827]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jan 13 21:19:38 volumio volumio[827]: info: CoreCommandRouter::volumioGetState Jan 13 21:19:38 volumio volumio[827]: info: CorePlayQueue::getTrack 0 Jan 13 21:19:39 volumio volumio[827]: info: Initializing connection to go-librespot Websocket Jan 13 21:19:39 volumio volumio[827]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 21:19:39 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 13 21:19:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2939. Jan 13 21:19:39 volumio systemd[1]: Stopped go-librespot Daemon. Jan 13 21:19:39 volumio systemd[1]: Started go-librespot Daemon. Jan 13 21:19:39 volumio go-librespot[24614]: go-librespot daemon starting... Jan 13 21:19:39 volumio go-librespot[24614]: time="2026-01-13T21:19:39+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 21:19:39 volumio go-librespot[24614]: time="2026-01-13T21:19:39+01:00" level=debug msg="app state loaded" Jan 13 21:19:39 volumio go-librespot[24614]: time="2026-01-13T21:19:39+01:00" level=debug msg="stored credentials not found" Jan 13 21:19:39 volumio go-librespot[24614]: time="2026-01-13T21:19:39+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 21:19:39 volumio go-librespot[24614]: time="2026-01-13T21:19:39+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 13 21:19:39 volumio go-librespot[24614]: time="2026-01-13T21:19:39+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 13 21:19:39 volumio go-librespot[24614]: time="2026-01-13T21:19:39+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 13 21:19:39 volumio go-librespot[24614]: time="2026-01-13T21:19:39+01:00" level=info msg="zeroconf server listening on port 38905" Jan 13 21:19:39 volumio go-librespot[24614]: time="2026-01-13T21:19:39+01:00" level=debug msg="obtained new client token: AABjIxMQ/K0e8+OBSbRjE8qjWsp5y/kFKUdGfrUcX5rPr1rTjzqoC7bJgsPgUwz3CM0O0agmXJ4FVw74IWN57ML0avgWzHtmjBs9X7P4Ybpks4qxvEqzLvaZe1kauErIWCl1lKVT9ZRAHwWuz3R+KE6s7FAl38kBMvYWsToHj/RJu5ZjuCGDPFtCK5xvXcGS12km5T4UWatR/NVCs5Zzu3XaA4XoyDdFNO4nclWYwpKaZ/bXEiQVw6NL" Jan 13 21:19:39 volumio go-librespot[24614]: time="2026-01-13T21:19:39+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jan 13 21:19:39 volumio go-librespot[24614]: time="2026-01-13T21:19:39+01:00" level=debug msg="completed keyexchange" Jan 13 21:19:39 volumio go-librespot[24614]: time="2026-01-13T21:19:39+01:00" level=debug msg="completed challenge" Jan 13 21:19:40 volumio go-librespot[24614]: time="2026-01-13T21:19:40+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Jan 13 21:19:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:19:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:19:42 volumio volumio[827]: info: Initializing connection to go-librespot Websocket Jan 13 21:19:42 volumio volumio[827]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 21:19:43 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 13 21:19:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2940. Jan 13 21:19:43 volumio systemd[1]: Stopped go-librespot Daemon. Jan 13 21:19:43 volumio systemd[1]: Started go-librespot Daemon. Jan 13 21:19:45 volumio volumio[827]: info: Initializing connection to go-librespot Websocket Jan 13 21:19:45 volumio volumio[827]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 21:19:48 volumio volumio[827]: info: Initializing connection to go-librespot Websocket Jan 13 21:19:48 volumio volumio[827]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 21:19:49 volumio volumio[827]: info: Reading package lists... Jan 13 21:19:51 volumio volumio[827]: info: Initializing connection to go-librespot Websocket Jan 13 21:19:51 volumio volumio[827]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 21:19:51 volumio go-librespot[24626]: go-librespot daemon starting... Jan 13 21:19:51 volumio go-librespot[24626]: time="2026-01-13T21:19:51+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 21:19:51 volumio go-librespot[24626]: time="2026-01-13T21:19:51+01:00" level=debug msg="app state loaded" Jan 13 21:19:51 volumio go-librespot[24626]: time="2026-01-13T21:19:51+01:00" level=debug msg="stored credentials not found" Jan 13 21:19:51 volumio go-librespot[24626]: time="2026-01-13T21:19:51+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 21:19:51 volumio go-librespot[24626]: time="2026-01-13T21:19:51+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jan 13 21:19:51 volumio go-librespot[24626]: time="2026-01-13T21:19:51+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jan 13 21:19:51 volumio go-librespot[24626]: time="2026-01-13T21:19:51+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jan 13 21:19:51 volumio go-librespot[24626]: time="2026-01-13T21:19:51+01:00" level=info msg="zeroconf server listening on port 40203" Jan 13 21:19:51 volumio go-librespot[24626]: time="2026-01-13T21:19:51+01:00" level=debug msg="obtained new client token: AAAsSqBT8yo9Pr4N4xdl0VQsFqsPbuJz9vWnofGYMRcec8XEBCcIr5LpKwySET3oHhDHUu8iQMzokv4eosXZAJrX7zNIkYcZgMBqgUxrVhHYUkq4+u66dd66isyiOywWB2caSl/KAmxvAUU36vRpuFRd8zBMoJ7SkxqQtpb3fv6YVeRnfXVg6hjTOrNt3g2WXRMjdCWI2tyAdb50JyxBgy0VtxLq9cmbjRj1iv4N8hxz1DqH3V1IhBe4" Jan 13 21:19:51 volumio go-librespot[24626]: time="2026-01-13T21:19:51+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Jan 13 21:19:51 volumio volumio[827]: info: Building dependency tree... Jan 13 21:19:51 volumio volumio[827]: info: Reading state information... Jan 13 21:19:51 volumio go-librespot[24626]: time="2026-01-13T21:19:51+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Jan 13 21:19:52 volumio go-librespot[24626]: time="2026-01-13T21:19:52+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: read tcp 192.168.68.107:52568->34.158.1.133:443: read: connection reset by peer" Jan 13 21:19:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:19:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:19:52 volumio volumio[827]: info: 162 packages can be upgraded. Run 'apt list --upgradable' to see them. Jan 13 21:19:52 volumio sudo[24280]: pam_unix(sudo:session): session closed for user root Jan 13 21:19:52 volumio sudo[24649]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt -y install python3-aiohttp python3-pip Jan 13 21:19:52 volumio sudo[24649]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 21:19:52 volumio volumio[827]: info: Reading package lists... Jan 13 21:19:53 volumio volumio[827]: info: Building dependency tree... Jan 13 21:19:53 volumio volumio[827]: info: Reading state information... Jan 13 21:19:54 volumio volumio[827]: info: Initializing connection to go-librespot Websocket Jan 13 21:19:54 volumio volumio[827]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 21:19:55 volumio volumio[827]: info: python3-aiohttp is already the newest version (3.5.1-1+deb10u1). Jan 13 21:19:55 volumio sudo[24649]: pam_unix(sudo:session): session closed for user root Jan 13 21:19:55 volumio volumio[827]: info: python3-pip is already the newest version (18.1-5+rpt1). Jan 13 21:19:55 volumio volumio[827]: info: 0 upgraded, 0 newly installed, 0 to remove and 163 not upgraded. Jan 13 21:19:55 volumio sudo[24654]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/tar -xvf fusiondsp.service.tar -C / Jan 13 21:19:55 volumio sudo[24654]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 21:19:55 volumio volumio[827]: info: lib/systemd/system/fusiondsp.service Jan 13 21:19:55 volumio sudo[24654]: pam_unix(sudo:session): session closed for user root Jan 13 21:19:55 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 13 21:19:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2941. Jan 13 21:19:55 volumio systemd[1]: Stopped go-librespot Daemon. Jan 13 21:19:55 volumio systemd[1]: Started go-librespot Daemon. Jan 13 21:19:55 volumio go-librespot[24657]: go-librespot daemon starting... Jan 13 21:19:55 volumio go-librespot[24657]: time="2026-01-13T21:19:55+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 21:19:55 volumio go-librespot[24657]: time="2026-01-13T21:19:55+01:00" level=debug msg="app state loaded" Jan 13 21:19:55 volumio go-librespot[24657]: time="2026-01-13T21:19:55+01:00" level=debug msg="stored credentials not found" Jan 13 21:19:55 volumio go-librespot[24657]: time="2026-01-13T21:19:55+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 21:19:55 volumio go-librespot[24657]: time="2026-01-13T21:19:55+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 13 21:19:55 volumio go-librespot[24657]: time="2026-01-13T21:19:55+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 13 21:19:55 volumio go-librespot[24657]: time="2026-01-13T21:19:55+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 13 21:19:55 volumio go-librespot[24657]: time="2026-01-13T21:19:55+01:00" level=info msg="zeroconf server listening on port 35989" Jan 13 21:19:55 volumio go-librespot[24657]: time="2026-01-13T21:19:55+01:00" level=debug msg="obtained new client token: AADOdEdlE3I++Qz7FHnaJMiR8tWA0HEsnmG9ITj5NSd+LXBK17JWOd3juhyQh/D8WZxVCR/YY3BH32ay6YCfxyd69N9tdDv/318wDcHzRq+m357Sb6muU4Vve87zC5t1RabgkoagucUt7xLb5oE6MSeuoW5ZNtb8qwjpQbB7nzoNY1Q7HW5vMs0UwfqJRsSshZ2TeoCjQIc22xiRRgM2i7jYdFYEMU6JKYm1DZ0IuqUy7N3P9YU1iJbV" Jan 13 21:19:55 volumio go-librespot[24657]: time="2026-01-13T21:19:55+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jan 13 21:19:55 volumio go-librespot[24657]: time="2026-01-13T21:19:55+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: read tcp 192.168.68.107:58176->34.158.1.133:4070: read: connection reset by peer" Jan 13 21:19:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:19:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:19:57 volumio volumio[827]: info: Initializing connection to go-librespot Websocket Jan 13 21:19:57 volumio volumio[827]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 21:19:58 volumio volumio[827]: info: Enabling plugin fusiondsp Jan 13 21:19:58 volumio volumio[827]: info: Loading plugin "fusiondsp"... Jan 13 21:19:58 volumio sudo[24666]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chown -R volumio cgui Jan 13 21:19:58 volumio sudo[24666]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 21:19:58 volumio sudo[24666]: pam_unix(sudo:session): session closed for user root Jan 13 21:19:58 volumio sudo[24668]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chgrp -R volumio cgui Jan 13 21:19:58 volumio sudo[24668]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 21:19:58 volumio sudo[24668]: pam_unix(sudo:session): session closed for user root Jan 13 21:19:58 volumio sudo[24670]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/rm cgui-1.0.0.zip Jan 13 21:19:58 volumio sudo[24670]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 21:19:58 volumio sudo[24670]: pam_unix(sudo:session): session closed for user root Jan 13 21:19:59 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 13 21:19:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2942. Jan 13 21:19:59 volumio systemd[1]: Stopped go-librespot Daemon. Jan 13 21:19:59 volumio systemd[1]: Started go-librespot Daemon. Jan 13 21:19:59 volumio go-librespot[24673]: go-librespot daemon starting... Jan 13 21:19:59 volumio go-librespot[24673]: time="2026-01-13T21:19:59+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 21:19:59 volumio go-librespot[24673]: time="2026-01-13T21:19:59+01:00" level=debug msg="app state loaded" Jan 13 21:19:59 volumio go-librespot[24673]: time="2026-01-13T21:19:59+01:00" level=debug msg="stored credentials not found" Jan 13 21:19:59 volumio go-librespot[24673]: time="2026-01-13T21:19:59+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 21:19:59 volumio volumio[827]: info: Preparing to generate the ALSA configuration file Jan 13 21:19:59 volumio go-librespot[24673]: time="2026-01-13T21:19:59+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 13 21:19:59 volumio go-librespot[24673]: time="2026-01-13T21:19:59+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 13 21:19:59 volumio go-librespot[24673]: time="2026-01-13T21:19:59+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 13 21:19:59 volumio go-librespot[24673]: time="2026-01-13T21:19:59+01:00" level=info msg="zeroconf server listening on port 33713" Jan 13 21:19:59 volumio volumio[827]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 13 21:19:59 volumio volumio[827]: info: Reading ALSA contributions from plugins. Jan 13 21:19:59 volumio volumio[827]: info: MiniUnz 1.01b, demo of zLib + Unz package written by Gilles Vollant Jan 13 21:19:59 volumio volumio[827]: info: more info at http://www.winimage.com/zLibDll/unzip.html Jan 13 21:19:59 volumio volumio[827]: info: Jan 13 21:19:59 volumio volumio[827]: info: cgui-1.0.0.zip opened Jan 13 21:19:59 volumio volumio[827]: info: creating directory: cgui/ Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/LICENSE.txt Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/README.md Jan 13 21:19:59 volumio volumio[827]: info: creating directory: cgui/build/ Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/build/robots.txt Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/build/logo512.png Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/build/asset-manifest.json Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/build/index.html Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/build/.put_statics_here Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/build/favicon.ico Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/build/logo192.png Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/build/.xdp-css-variables-1.css-PvrWoX Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/build/manifest.json Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/build/css-variables.css Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/build/precache-manifest.76db5aad1647230454f47b1e22783b54.js Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/build/service-worker.js Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/build/precache-manifest.5e6c675b70ef6a3fe73d0209febfe347.js Jan 13 21:19:59 volumio volumio[827]: info: creating directory: cgui/build/static/ Jan 13 21:19:59 volumio volumio[827]: info: creating directory: cgui/build/static/css/ Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css.map Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css.map Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css.map Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/build/static/css/main.1503f6cd.css.map Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css.map Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/build/static/css/main.1503f6cd.css Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css Jan 13 21:19:59 volumio volumio[827]: info: creating directory: cgui/build/static/media/ Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/build/static/media/camilladsp.95719842.svg Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/build/static/media/camilladsp.4767ea4ad3510467418b818dc677a87e.svg Jan 13 21:19:59 volumio volumio[827]: info: creating directory: cgui/build/static/js/ Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.map Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/build/static/js/main.71e11955.js Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.LICENSE.txt Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js.map Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js.map Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/build/static/js/main.71e11955.js.LICENSE.txt Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/build/static/js/main.71e11955.js.map Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js.map Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.map Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.LICENSE.txt Jan 13 21:19:59 volumio volumio[827]: info: creating directory: cgui/config/ Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/config/camillagui.yml Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/config/.xdp-css-variables-1.css-ppBDuX Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/config/gui-config.yml Jan 13 21:19:59 volumio volumio[827]: info: creating directory: cgui/backend/ Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/backend/filters_test.py Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/backend/version.py Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/backend/filters.py Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/backend/settings.py Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/backend/views.py Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/backend/filterdefaults.py Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/backend/__init__.py Jan 13 21:19:59 volumio volumio[827]: info: creating directory: cgui/backend/__pycache__/ Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/backend/__pycache__/filterdefaults.cpython-37.pyc Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/backend/__pycache__/views.cpython-37.pyc Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/backend/__pycache__/settings.cpython-37.pyc Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/backend/__pycache__/__init__.cpython-37.pyc Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/backend/__pycache__/routes.cpython-37.pyc Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/backend/__pycache__/filemanagement.cpython-37.pyc Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/backend/__pycache__/filters.cpython-37.pyc Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/backend/__pycache__/version.cpython-37.pyc Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/backend/routes.py Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/backend/filemanagement.py Jan 13 21:19:59 volumio volumio[827]: info: extracting: cgui/main.py Jan 13 21:19:59 volumio go-librespot[24673]: time="2026-01-13T21:19:59+01:00" level=debug msg="obtained new client token: AACKm1Zeb4Pf98JoHjwxY61cqismld7UY8BbeFvbWLEN9Xpgdf6L7GYZl4Nu2/Vr2u7M1AMOan5bic3DzH4Oj+72caIzMjuQ8l4d6q/BmsaayNg6f7+4xHN1ZN2cW+7MY0Ku/LSmPQm6jZprOH1Z+ySYhDEvGyr9lzD9lpO7nXZRcJVpOULTsGfAiMOB/lSJrUmatXRzdrZ76bFQwNoIlDCFlB9Pd11no/wjwGHsVpO4kTmcymrEMe/l" Jan 13 21:19:59 volumio go-librespot[24673]: time="2026-01-13T21:19:59+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jan 13 21:20:00 volumio go-librespot[24673]: time="2026-01-13T21:20:00+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: read tcp 192.168.68.107:58188->34.158.1.133:4070: read: connection reset by peer" Jan 13 21:20:00 volumio volumio[827]: info: Initializing connection to go-librespot Websocket Jan 13 21:20:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:20:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:20:00 volumio volumio[827]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 21:20:00 volumio volumio[827]: info: Asound.conf file written Jan 13 21:20:00 volumio sudo[24682]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jan 13 21:20:00 volumio sudo[24682]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 21:20:00 volumio sudo[24682]: pam_unix(sudo:session): session closed for user root Jan 13 21:20:00 volumio volumio[827]: info: Output device has changed, restarting MPD Jan 13 21:20:00 volumio sudo[24701]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 13 21:20:00 volumio sudo[24701]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 21:20:00 volumio volumio[827]: info: Output device has changed, restarting Shairport Sync Jan 13 21:20:00 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 21:20:00 volumio sudo[24701]: pam_unix(sudo:session): session closed for user root Jan 13 21:20:00 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 21:20:00 volumio sudo[24704]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 21:20:00 volumio sudo[24704]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 21:20:00 volumio volumio[827]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 21:20:00 volumio volumio[827]: info: PLUGIN START: fusiondsp Jan 13 21:20:00 volumio volumio[827]: info: Loading i18n strings for locale en Jan 13 21:20:00 volumio systemd[1]: Stopping Music Player Daemon... Jan 13 21:20:00 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jan 13 21:20:00 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 21:20:00 volumio volumio[827]: info: FusionDsp - mixtype--------------------- None Jan 13 21:20:00 volumio volumio[827]: info: Preparing to generate the ALSA configuration file Jan 13 21:20:00 volumio volumio[827]: info: Done. Jan 13 21:20:00 volumio volumio[827]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 13 21:20:00 volumio volumio[827]: info: Reading ALSA contributions from plugins. Jan 13 21:20:00 volumio volumio[827]: info: MPD Permissions set Jan 13 21:20:00 volumio volumio[827]: info: Asound.conf file unchanged, so no further update is needed Jan 13 21:20:00 volumio volumio[827]: info: Output device has changed, restarting MPD Jan 13 21:20:00 volumio sudo[24710]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 13 21:20:00 volumio sudo[24710]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 21:20:01 volumio volumio[827]: info: Output device has changed, restarting Shairport Sync Jan 13 21:20:01 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 21:20:01 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 21:20:01 volumio sudo[24710]: pam_unix(sudo:session): session closed for user root Jan 13 21:20:01 volumio sudo[24713]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 21:20:01 volumio sudo[24713]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 21:20:01 volumio volumio[827]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 21:20:01 volumio volumio[827]: info: MPD Permissions set Jan 13 21:20:01 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 21:20:01 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 21:20:01 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 21:20:01 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 21:20:01 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 21:20:01 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 21:20:01 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 21:20:01 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 21:20:01 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 21:20:01 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 21:20:01 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 21:20:01 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 21:20:01 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 21:20:01 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 21:20:01 volumio volumio[827]: info: Starting Shairport Sync Jan 13 21:20:01 volumio volumio[827]: info: Starting Shairport Sync Jan 13 21:20:01 volumio sudo[24727]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 13 21:20:01 volumio sudo[24727]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 21:20:01 volumio volumio[827]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 21:20:01 volumio volumio[827]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 21:20:01 volumio sudo[24730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 13 21:20:01 volumio sudo[24730]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 21:20:01 volumio volumio[827]: info: FusionDsp - Jan 13 21:20:01 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 13 21:20:01 volumio systemd[1]: shairport-sync.service: Succeeded. Jan 13 21:20:01 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 13 21:20:01 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 13 21:20:01 volumio sudo[24730]: pam_unix(sudo:session): session closed for user root Jan 13 21:20:01 volumio sudo[24727]: pam_unix(sudo:session): session closed for user root Jan 13 21:20:01 volumio volumio[827]: info: FusionDsp - Jan 13 21:20:02 volumio volumio[827]: info: FusionDsp - undefined Jan 13 21:20:02 volumio volumio[827]: info: FusionDsp - undefined Jan 13 21:20:03 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 13 21:20:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2943. Jan 13 21:20:03 volumio systemd[1]: Stopped go-librespot Daemon. Jan 13 21:20:03 volumio systemd[1]: Started go-librespot Daemon. Jan 13 21:20:03 volumio go-librespot[24736]: go-librespot daemon starting... Jan 13 21:20:03 volumio go-librespot[24736]: time="2026-01-13T21:20:03+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 21:20:03 volumio go-librespot[24736]: time="2026-01-13T21:20:03+01:00" level=debug msg="app state loaded" Jan 13 21:20:03 volumio go-librespot[24736]: time="2026-01-13T21:20:03+01:00" level=debug msg="stored credentials not found" Jan 13 21:20:03 volumio go-librespot[24736]: time="2026-01-13T21:20:03+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 21:20:03 volumio go-librespot[24736]: time="2026-01-13T21:20:03+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 13 21:20:03 volumio go-librespot[24736]: time="2026-01-13T21:20:03+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 13 21:20:03 volumio go-librespot[24736]: time="2026-01-13T21:20:03+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 13 21:20:03 volumio go-librespot[24736]: time="2026-01-13T21:20:03+01:00" level=info msg="zeroconf server listening on port 32993" Jan 13 21:20:03 volumio go-librespot[24736]: time="2026-01-13T21:20:03+01:00" level=debug msg="obtained new client token: AAD+W6gFET/9VhWHrW6PcLcZXw2BStnRbf25RlpkZ5EQZmFqAPyjV0zo+kS2UOR14AIGnSx3/qr2bC4BO+adAkANHIASayKpssBaoKZnp21GPsvgt5/BfY+OLbOEc3L97RdtihU/aGVr8sv2vpWAQ0WLiQe9LbvJxlP7ZKI7ODlvZopMmzsyxRXUEASQ70zZblGwXYENHGbAMGr4Npo+Yrr3fYP9RH9/PNAmrCrBeeI659/qS6UOxPaL" Jan 13 21:20:04 volumio go-librespot[24736]: time="2026-01-13T21:20:04+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Jan 13 21:20:04 volumio go-librespot[24736]: time="2026-01-13T21:20:04+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Jan 13 21:20:04 volumio go-librespot[24736]: time="2026-01-13T21:20:04+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: read tcp 192.168.68.107:53348->34.158.1.133:443: read: connection reset by peer" Jan 13 21:20:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:20:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:20:07 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 13 21:20:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2944. Jan 13 21:20:07 volumio systemd[1]: Stopped go-librespot Daemon. Jan 13 21:20:07 volumio systemd[1]: Started go-librespot Daemon. Jan 13 21:20:07 volumio go-librespot[24745]: go-librespot daemon starting... Jan 13 21:20:07 volumio go-librespot[24745]: time="2026-01-13T21:20:07+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 21:20:07 volumio go-librespot[24745]: time="2026-01-13T21:20:07+01:00" level=debug msg="app state loaded" Jan 13 21:20:07 volumio go-librespot[24745]: time="2026-01-13T21:20:07+01:00" level=debug msg="stored credentials not found" Jan 13 21:20:07 volumio go-librespot[24745]: time="2026-01-13T21:20:07+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 21:20:07 volumio go-librespot[24745]: time="2026-01-13T21:20:07+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jan 13 21:20:07 volumio go-librespot[24745]: time="2026-01-13T21:20:07+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jan 13 21:20:07 volumio go-librespot[24745]: time="2026-01-13T21:20:07+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jan 13 21:20:07 volumio go-librespot[24745]: time="2026-01-13T21:20:07+01:00" level=info msg="zeroconf server listening on port 39205" Jan 13 21:20:08 volumio go-librespot[24745]: time="2026-01-13T21:20:08+01:00" level=debug msg="obtained new client token: AACSqic7gPhCETgSJ6teCIhfXKWgUJQhBepg9aP5xotuSg7mtif2TknS+eXFQa21KZydb8SQTJx3CGrXz7oK6Zf/QGr6iTDpKMrdR5LBGcNT/PfsErOvmRiYSaWNz7cf+tf8cTeA4aQ16zglQK+q56I+I0cualyLmS9/UiFL644hZcIF40EzsH/xn4gad23vnOsADZpIBgZwrc26tG/zeaU0oXJ3wQKq8+yP4946N2I2fupx9/dd5w==" Jan 13 21:20:08 volumio go-librespot[24745]: time="2026-01-13T21:20:08+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jan 13 21:20:08 volumio volumio[827]: info: Shairport-Sync Started Jan 13 21:20:08 volumio volumio[827]: info: Shairport-Sync Started Jan 13 21:20:08 volumio go-librespot[24745]: time="2026-01-13T21:20:08+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: read tcp 192.168.68.107:35340->34.158.1.133:4070: read: connection reset by peer" Jan 13 21:20:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:20:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:20:08 volumio volumio[827]: info: camilladsp service started and running in background, instance 1 Jan 13 21:20:08 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 21:20:08 volumio volumio[827]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jan 13 21:20:08 volumio volumio[827]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jan 13 21:20:08 volumio volumio[827]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jan 13 21:20:08 volumio volumio[827]: info: FusionDsp loaded Jan 13 21:20:08 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 13 21:20:08 volumio sudo[24763]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jan 13 21:20:08 volumio sudo[24763]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 21:20:08 volumio volumio[827]: info: FusionDsp - Reporting Fusion DSP Enabled Jan 13 21:20:08 volumio volumio[827]: info: Adding Signal Path Element [object Object] Jan 13 21:20:08 volumio volumio[827]: info: Adding fusiondspeq DSP Signal Path Element Jan 13 21:20:08 volumio volumio[827]: info: FusionDsp - ---- installed callbackRead Jan 13 21:20:08 volumio volumio[827]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 21:20:08 volumio systemd[1]: Started FusionDsp Daemon. Jan 13 21:20:08 volumio volumio[827]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Jan 13 21:20:08 volumio volumio[827]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Jan 13 21:20:08 volumio volumio[827]: at onErrorNT (internal/child_process.js:465:16) Jan 13 21:20:08 volumio volumio[827]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jan 13 21:20:08 volumio volumio[827]: at runNextTicks (internal/process/task_queues.js:62:3) Jan 13 21:20:08 volumio volumio[827]: at listOnTimeout (internal/timers.js:523:9) Jan 13 21:20:08 volumio volumio[827]: at processTimers (internal/timers.js:497:7) { Jan 13 21:20:08 volumio volumio[827]: errno: -2, Jan 13 21:20:08 volumio volumio[827]: code: 'ENOENT', Jan 13 21:20:08 volumio volumio[827]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Jan 13 21:20:08 volumio volumio[827]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Jan 13 21:20:08 volumio volumio[827]: spawnargs: [ Jan 13 21:20:08 volumio volumio[827]: '-p', Jan 13 21:20:08 volumio volumio[827]: 9876, Jan 13 21:20:08 volumio volumio[827]: '-o', Jan 13 21:20:08 volumio volumio[827]: '/tmp/camilladsp.log', Jan 13 21:20:08 volumio volumio[827]: '-l', Jan 13 21:20:08 volumio volumio[827]: 'warn', Jan 13 21:20:08 volumio volumio[827]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Jan 13 21:20:08 volumio volumio[827]: ] Jan 13 21:20:08 volumio volumio[827]: } Jan 13 21:20:08 volumio volumio[827]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 21:20:08 volumio sudo[24763]: pam_unix(sudo:session): session closed for user root Jan 13 21:20:11 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 13 21:20:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2945. Jan 13 21:20:11 volumio systemd[1]: Stopped go-librespot Daemon. Jan 13 21:20:11 volumio systemd[1]: Started go-librespot Daemon. Jan 13 21:20:11 volumio go-librespot[24807]: go-librespot daemon starting... Jan 13 21:20:11 volumio go-librespot[24807]: time="2026-01-13T21:20:11+01:00" level=info msg="running go-librespot 0.4.0" Jan 13 21:20:11 volumio go-librespot[24807]: time="2026-01-13T21:20:11+01:00" level=debug msg="app state loaded" Jan 13 21:20:11 volumio go-librespot[24807]: time="2026-01-13T21:20:11+01:00" level=debug msg="stored credentials not found" Jan 13 21:20:11 volumio go-librespot[24807]: time="2026-01-13T21:20:11+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 21:20:12 volumio go-librespot[24807]: time="2026-01-13T21:20:12+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 13 21:20:12 volumio go-librespot[24807]: time="2026-01-13T21:20:12+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 13 21:20:12 volumio go-librespot[24807]: time="2026-01-13T21:20:12+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 13 21:20:12 volumio go-librespot[24807]: time="2026-01-13T21:20:12+01:00" level=info msg="zeroconf server listening on port 46185" Jan 13 21:20:12 volumio go-librespot[24807]: time="2026-01-13T21:20:12+01:00" level=debug msg="obtained new client token: AADj7TWU9DV4TrC8cXMg5u7ZvCSUtUE7uhVkEao2hYWudtcpnXrBZDCMNDu2b6GQ8eKmIL0HuiLQBM06wZFaamq5dcD0EUeUsEgK0uUKqRpAJFHmOGD8E4wgY+ECD4pKYuqi3nyul8f3kAY/zulRrCYWDkf281vurv/4ZX9RvE1THnyYqAsjOQjHWhdGpXy2z/icM1azJRy5lj4HNSKht1Xx43ix1F91rZa5OCDSSRU0ab+t1lue4Syw" Jan 13 21:20:12 volumio go-librespot[24807]: time="2026-01-13T21:20:12+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jan 13 21:20:12 volumio go-librespot[24807]: time="2026-01-13T21:20:12+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: read tcp 192.168.68.107:35348->34.158.1.133:4070: read: connection reset by peer" Jan 13 21:20:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:20:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:20:14 volumio sudo[24838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-13 21:19 Jan 13 21:20:14 volumio sudo[24838]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"