-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Thu 2026-01-08 15:19:38 CET. -- Jan 08 15:18:00 volumio go-librespot[3567]: time="2026-01-08T15:18:00+01:00" level=debug msg="obtained new client token: AADj0fVkDsrXo9iYuHXJh9oiP0EVB80Eddt0ssVhnVYKSQdjt3+Yn9xedzI9PxSOQlHvTYstp1OwoTKVw6WtfwLqG71emp9VRZlF4xPN1ULZH3yQmCYEQdapL3XefiLbZDVjKLrW1MtMy0TRTTSvKmPfQoWPhP7KQia059NPiTev+lmLdKN05OM2Flop1gC+qOOLBQpn16eeWRpGqWPev1Gl2hcIGFWZuOyLC2LAZKR9sLCC00Cglk97EmE=" Jan 08 15:18:00 volumio go-librespot[3567]: time="2026-01-08T15:18: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-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jan 08 15:18:00 volumio go-librespot[3567]: time="2026-01-08T15:18:00+01:00" level=debug msg="completed keyexchange" Jan 08 15:18:00 volumio go-librespot[3567]: time="2026-01-08T15:18:00+01:00" level=debug msg="completed challenge" Jan 08 15:18:00 volumio go-librespot[3567]: time="2026-01-08T15:18:00+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 08 15:18:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 08 15:18:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 08 15:18:01 volumio volumio[791]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 08 15:18:01 volumio volumio[791]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 08 15:18:01 volumio volumio[791]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 15:18:01 volumio volumio[791]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 15:18:01 volumio volumio[791]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 15:18:02 volumio volumio[791]: info: Initializing connection to go-librespot Websocket Jan 08 15:18:02 volumio volumio[791]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 08 15:18:03 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 08 15:18:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 89. Jan 08 15:18:03 volumio systemd[1]: Stopped go-librespot Daemon. Jan 08 15:18:03 volumio systemd[1]: Started go-librespot Daemon. Jan 08 15:18:03 volumio go-librespot[3584]: Librespot-go daemon starting... Jan 08 15:18:04 volumio go-librespot[3584]: time="2026-01-08T15:18:04+01:00" level=info msg="generated new device id: 56844dbe477987fc6b7f9f5451a47c44d1393282" Jan 08 15:18:04 volumio go-librespot[3584]: time="2026-01-08T15:18:04+01:00" level=debug msg="stored credentials found for 1150052341" Jan 08 15:18:04 volumio go-librespot[3584]: time="2026-01-08T15:18:04+01:00" level=debug msg="obtained new client token: AADYKPyBjk/Ye29wViQUIZ15xkXV5FXyvniXzIi4pjtJNTO6Pb/2hp6MpWnhNEVQ9k4uBGIKRiPGesz1ixRWhRjUSwFv6hutqa6yJomNQCcpThxPkppobmmBRWM5XgGERLxuDZErLX+PRndKVBxkdPmO6RECr25kWL6zVDyl76k4/XY992SdwXeq7JMdeglO4eM6ewxLoHWknt8B1cwCuhzSWkyAmbvO8jSOTTEjyNKFAfggs3agkNSxrCQ=" Jan 08 15:18:04 volumio go-librespot[3584]: time="2026-01-08T15:18:04+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 08 15:18:04 volumio go-librespot[3584]: time="2026-01-08T15:18:04+01:00" level=debug msg="completed keyexchange" Jan 08 15:18:05 volumio go-librespot[3584]: time="2026-01-08T15:18:05+01:00" level=debug msg="completed challenge" Jan 08 15:18:05 volumio go-librespot[3584]: time="2026-01-08T15:18:05+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 08 15:18:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 08 15:18:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 08 15:18:05 volumio volumio[791]: info: Initializing connection to go-librespot Websocket Jan 08 15:18:05 volumio volumio[791]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 08 15:18:08 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 08 15:18:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 90. Jan 08 15:18:08 volumio systemd[1]: Stopped go-librespot Daemon. Jan 08 15:18:08 volumio systemd[1]: Started go-librespot Daemon. Jan 08 15:18:08 volumio go-librespot[3601]: Librespot-go daemon starting... Jan 08 15:18:08 volumio go-librespot[3601]: time="2026-01-08T15:18:08+01:00" level=info msg="generated new device id: 7446e85888796a62bcd292fc7af18e0a78a8a134" Jan 08 15:18:08 volumio go-librespot[3601]: time="2026-01-08T15:18:08+01:00" level=debug msg="stored credentials found for 1150052341" Jan 08 15:18:08 volumio go-librespot[3601]: time="2026-01-08T15:18:08+01:00" level=debug msg="obtained new client token: AABwGEDd0egcUt0i/mmjQmYBuywzBo9a5CcFR3odG1bQECvEszhs1zAAnNXETOSmaN3F1l1n7j5Uk/3KJKDk9CXJo1A6XDOzOZ8Ozb7XvyaJOrMBVpxazqNYF9b3m+xgnQtgzvycalUFsFNWbZNtj8OgGZM5hFikCBQ8LzdEd8nSx3gYIiulqX4he8fe3dmFYEdmog+X6iX49OxkBiKzeM2E5emhAeLCzzY+M6o60UDRIJ2liKGVLKOBpJTKVA==" Jan 08 15:18:08 volumio go-librespot[3601]: time="2026-01-08T15:18:08+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 08 15:18:08 volumio go-librespot[3601]: time="2026-01-08T15:18:08+01:00" level=debug msg="completed keyexchange" Jan 08 15:18:08 volumio volumio[791]: info: Initializing connection to go-librespot Websocket Jan 08 15:18:08 volumio go-librespot[3601]: time="2026-01-08T15:18:08+01:00" level=debug msg="new websocket client" Jan 08 15:18:08 volumio volumio[791]: info: Connection to go-librespot Websocket established Jan 08 15:18:09 volumio go-librespot[3601]: time="2026-01-08T15:18:09+01:00" level=debug msg="completed challenge" Jan 08 15:18:09 volumio go-librespot[3601]: time="2026-01-08T15:18:09+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 08 15:18:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 08 15:18:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 08 15:18:09 volumio volumio[791]: info: Connection to go-librespot Websocket closed Jan 08 15:18:11 volumio volumio[791]: info: Getting Spotify volume Jan 08 15:18:11 volumio volumio[791]: (node:791) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 08 15:18:11 volumio volumio[791]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jan 08 15:18:11 volumio volumio[791]: (node:791) 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: 17) Jan 08 15:18:11 volumio volumio[791]: 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 08 15:18:11 volumio volumio[791]: info: CoreCommandRouter::volumioGetState Jan 08 15:18:11 volumio volumio[791]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 Jan 08 15:18:12 volumio volumio[791]: info: Initializing connection to go-librespot Websocket Jan 08 15:18:12 volumio volumio[791]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 08 15:18:12 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 08 15:18:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 91. Jan 08 15:18:12 volumio systemd[1]: Stopped go-librespot Daemon. Jan 08 15:18:12 volumio systemd[1]: Started go-librespot Daemon. Jan 08 15:18:12 volumio go-librespot[3634]: Librespot-go daemon starting... Jan 08 15:18:12 volumio go-librespot[3634]: time="2026-01-08T15:18:12+01:00" level=info msg="generated new device id: 2778410a2ea0b6c9e017dc03c7ca0d6560c378ec" Jan 08 15:18:12 volumio go-librespot[3634]: time="2026-01-08T15:18:12+01:00" level=debug msg="stored credentials found for 1150052341" Jan 08 15:18:12 volumio go-librespot[3634]: time="2026-01-08T15:18:12+01:00" level=debug msg="obtained new client token: AADtQFTVHIXnJpWxInGDWFyV/p7KBznCiO7GQ192l3CftZogp4ohNNjs+UrfS8lyTY3WnRscjG2/OE5hZ17nk6VLvDCr86GgNuW+0PumeNIqUj+v8sZ3uyISFxAFWdraBX/WbNqf+wkFio0O61d8ss55f51B0hxNlPScQkAfPqgcxi+wcdtdGKfoz1Res9fWTpF6bFf/prYLqN7rx+S7YjPOTQFZhW3ymF/cLQyimBY82ar8rtz4m0gKwMKMGg==" Jan 08 15:18:12 volumio go-librespot[3634]: time="2026-01-08T15:18: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 08 15:18:12 volumio go-librespot[3634]: time="2026-01-08T15:18:12+01:00" level=debug msg="completed keyexchange" Jan 08 15:18:13 volumio go-librespot[3634]: time="2026-01-08T15:18:13+01:00" level=debug msg="completed challenge" Jan 08 15:18:13 volumio go-librespot[3634]: time="2026-01-08T15:18:13+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 08 15:18:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 08 15:18:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 08 15:18:15 volumio volumio[791]: info: Initializing connection to go-librespot Websocket Jan 08 15:18:15 volumio volumio[791]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 08 15:18:16 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 08 15:18:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 92. Jan 08 15:18:16 volumio systemd[1]: Stopped go-librespot Daemon. Jan 08 15:18:16 volumio systemd[1]: Started go-librespot Daemon. Jan 08 15:18:16 volumio go-librespot[3650]: Librespot-go daemon starting... Jan 08 15:18:16 volumio go-librespot[3650]: time="2026-01-08T15:18:16+01:00" level=info msg="generated new device id: ad9e7a5453100203cd60577bd07f5b9a11f04bbd" Jan 08 15:18:16 volumio go-librespot[3650]: time="2026-01-08T15:18:16+01:00" level=debug msg="stored credentials found for 1150052341" Jan 08 15:18:16 volumio go-librespot[3650]: time="2026-01-08T15:18:16+01:00" level=debug msg="obtained new client token: AADoYKzWfiwOkn6wjIDn6KNbwe0EC9AmOvIHWXD2MaBm77ZZ1xJ6sD4V49NRvnLBZDu7o09aU/oMp1JVmVlkTUGnKrM6PCk/4kpsDGTYpgY8Ka+fwP+M4sV3TlIh0br2rSYWAG0QjRElGKqnZQ0pkeKx6UcVwXvWRUMAFWWFkfSKqhRRAQ/s51GayRGnvm5jxmiitKL9zGMSWVx8wfFyKlipXqqEb4clggs0bkn9O0+3qyIvFKiwyrZLtJzzhQ==" Jan 08 15:18:16 volumio go-librespot[3650]: time="2026-01-08T15:18:16+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 08 15:18:16 volumio go-librespot[3650]: time="2026-01-08T15:18:16+01:00" level=debug msg="completed keyexchange" Jan 08 15:18:17 volumio go-librespot[3650]: time="2026-01-08T15:18:17+01:00" level=debug msg="completed challenge" Jan 08 15:18:17 volumio go-librespot[3650]: time="2026-01-08T15:18:17+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 08 15:18:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 08 15:18:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 08 15:18:18 volumio volumio[791]: info: Initializing connection to go-librespot Websocket Jan 08 15:18:18 volumio volumio[791]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 08 15:18:18 volumio volumio[791]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf Jan 08 15:18:20 volumio volumio[791]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf Jan 08 15:18:20 volumio volumio[791]: info: FusionDsp - Stopping FusionDsp service Jan 08 15:18:20 volumio volumio[791]: info: camilladsp stopping service pid 1492... Jan 08 15:18:20 volumio kernel: bcm2835_audio bcm2835_audio: failed to close VCHI service connection (status=-11) Jan 08 15:18:20 volumio volumio[791]: info: camilladsp service terminated, instance 1 Jan 08 15:18:20 volumio sudo[3686]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service Jan 08 15:18:20 volumio sudo[3686]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 15:18:20 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 08 15:18:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 93. Jan 08 15:18:20 volumio systemd[1]: Stopped go-librespot Daemon. Jan 08 15:18:20 volumio systemd[1]: Started go-librespot Daemon. Jan 08 15:18:20 volumio systemd[1]: Stopping FusionDsp Daemon... Jan 08 15:18:20 volumio volumio[1149]: Backend configuration: Jan 08 15:18:20 volumio volumio[1149]: active_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Jan 08 15:18:20 volumio volumio[1149]: active_config_txt: null Jan 08 15:18:20 volumio volumio[1149]: camilla_host: 127.0.0.1 Jan 08 15:18:20 volumio volumio[1149]: camilla_port: 9876 Jan 08 15:18:20 volumio volumio[1149]: coeff_dir: /data/INTERNAL/FusionDsp/filters Jan 08 15:18:20 volumio volumio[1149]: config_dir: /data/configuration/audio_interface/fusiondsp Jan 08 15:18:20 volumio volumio[1149]: default_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Jan 08 15:18:20 volumio volumio[1149]: log_file: /tmp/camilladsp.log Jan 08 15:18:20 volumio volumio[1149]: on_get_active_config: null Jan 08 15:18:20 volumio volumio[1149]: on_set_active_config: null Jan 08 15:18:20 volumio volumio[1149]: port: 5011 Jan 08 15:18:20 volumio volumio[1149]: supported_capture_types: null Jan 08 15:18:20 volumio volumio[1149]: supported_playback_types: null Jan 08 15:18:20 volumio volumio[1149]: update_config_symlink: false Jan 08 15:18:20 volumio volumio[1149]: update_config_txt: false Jan 08 15:18:20 volumio volumio[1149]: ======== Running on http://0.0.0.0:5011 ======== Jan 08 15:18:20 volumio volumio[1149]: (Press CTRL+C to quit) Jan 08 15:18:20 volumio go-librespot[3689]: Librespot-go daemon starting... Jan 08 15:18:20 volumio go-librespot[3689]: time="2026-01-08T15:18:20+01:00" level=info msg="generated new device id: 9e14cba2d56fedd26472c460f504b83287600158" Jan 08 15:18:20 volumio go-librespot[3689]: time="2026-01-08T15:18:20+01:00" level=debug msg="stored credentials found for 1150052341" Jan 08 15:18:20 volumio systemd[1]: fusiondsp.service: Succeeded. Jan 08 15:18:20 volumio systemd[1]: Stopped FusionDsp Daemon. Jan 08 15:18:20 volumio sudo[3686]: pam_unix(sudo:session): session closed for user root Jan 08 15:18:20 volumio volumio[791]: info: FusionDsp - Reporting Fusion DSP Disabled Jan 08 15:18:20 volumio volumio[791]: info: Removing fusiondspeq DSP Signal Path Element Jan 08 15:18:20 volumio go-librespot[3689]: time="2026-01-08T15:18:20+01:00" level=debug msg="obtained new client token: AAD6O12sBqI9c6F3Vod6BAp0yo8u4Cz2F02nLxN/dZ8zkjSr3VQkpCETnSLc0pq+UhMuY/+8jG42FPOnDitjDViJ+AXSV0JjGntTm+pMeeHQvgac8QzrUCA7bpAqIXmy9636Rr2nF+tLQ4Y1d9nuJF68YrqMu1XPdDTo8VzPE2Gcak7A6DdSF2/e9vgFbNQI1M0cmPLxMSeEu/jBZSi+IKxL6l593/U4p3N0lrvngBx7DwHEN4crsZrgu9lBzw==" Jan 08 15:18:20 volumio go-librespot[3689]: time="2026-01-08T15:18:20+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 08 15:18:20 volumio volumio[791]: info: Check plugin dependencies Jan 08 15:18:20 volumio volumio[791]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 08 15:18:20 volumio go-librespot[3689]: time="2026-01-08T15:18:20+01:00" level=debug msg="completed keyexchange" Jan 08 15:18:20 volumio volumio[791]: info: Rename folder Jan 08 15:18:21 volumio volumio[791]: info: Folder /tmp/downloaded_plugin.zip removed Jan 08 15:18:21 volumio volumio[791]: info: Move to category Jan 08 15:18:21 volumio volumio[791]: info: Initializing connection to go-librespot Websocket Jan 08 15:18:21 volumio go-librespot[3689]: time="2026-01-08T15:18:21+01:00" level=debug msg="new websocket client" Jan 08 15:18:21 volumio volumio[791]: info: Connection to go-librespot Websocket established Jan 08 15:18:21 volumio go-librespot[3689]: time="2026-01-08T15:18:21+01:00" level=debug msg="completed challenge" Jan 08 15:18:21 volumio go-librespot[3689]: time="2026-01-08T15:18:21+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 08 15:18:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 08 15:18:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 08 15:18:22 volumio volumio[791]: info: Checking if install.sh is present Jan 08 15:18:22 volumio volumio[791]: info: Executing install.sh Jan 08 15:18:22 volumio volumio[791]: info: Connection to go-librespot Websocket closed Jan 08 15:18:22 volumio sudo[3712]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/fusiondsp/install.sh Jan 08 15:18:22 volumio sudo[3712]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 15:18:22 volumio volumio[791]: info: creating filters folder and copying demo filters Jan 08 15:18:22 volumio volumio[791]: info: copying demo flters Jan 08 15:18:23 volumio volumio[791]: info: ./presets/ Jan 08 15:18:23 volumio volumio[791]: info: ./presets/2XEQ15/ Jan 08 15:18:23 volumio volumio[791]: info: ./presets/2XEQ15/.Bass-FusionDsp.json Jan 08 15:18:23 volumio volumio[791]: info: ./presets/2XEQ15/.Soundtrack-FusionDsp.json Jan 08 15:18:23 volumio volumio[791]: info: ./presets/2XEQ15/.Classic-FusionDsp.json Jan 08 15:18:23 volumio volumio[791]: info: ./presets/2XEQ15/.Voice-FusionDsp.json Jan 08 15:18:23 volumio volumio[791]: info: ./presets/2XEQ15/.Rock-FusionDsp.json Jan 08 15:18:23 volumio volumio[791]: info: ./presets/PEQ/ Jan 08 15:18:23 volumio volumio[791]: info: ./presets/convfir/ Jan 08 15:18:23 volumio volumio[791]: info: ./presets/convfir/kef.json Jan 08 15:18:23 volumio volumio[791]: info: ./presets/convfir/test.json Jan 08 15:18:23 volumio volumio[791]: info: ./presets/EQ15/ Jan 08 15:18:23 volumio volumio[791]: info: ./presets/EQ15/.Bass-FusionDsp.json Jan 08 15:18:23 volumio volumio[791]: info: ./presets/EQ15/.Soundtrack-FusionDsp.json Jan 08 15:18:23 volumio volumio[791]: info: ./presets/EQ15/.Classic-FusionDsp.json Jan 08 15:18:23 volumio volumio[791]: info: ./presets/EQ15/.Voice-FusionDsp.json Jan 08 15:18:23 volumio volumio[791]: info: ./presets/EQ15/.Rock-FusionDsp.json Jan 08 15:18:23 volumio sudo[3739]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt update Jan 08 15:18:23 volumio sudo[3739]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 15:18:23 volumio volumio[791]: info: Installing/fusiondsp dependencies Jan 08 15:18:23 volumio volumio[791]: info: Hit:2 https://deb.nodesource.com/node_14.x buster InRelease Jan 08 15:18:23 volumio volumio[791]: info: Get:1 https://apt2.volumio.org/raspbian buster InRelease [15.0 kB] Jan 08 15:18:23 volumio volumio[791]: info: Get:3 https://archive2.volumio.org/debian buster InRelease [54.2 kB] Jan 08 15:18:24 volumio volumio[791]: info: Getting Spotify volume Jan 08 15:18:24 volumio volumio[791]: (node:791) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 08 15:18:24 volumio volumio[791]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jan 08 15:18:24 volumio volumio[791]: (node:791) 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: 18) Jan 08 15:18:24 volumio volumio[791]: 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 08 15:18:24 volumio volumio[791]: info: CoreCommandRouter::volumioGetState Jan 08 15:18:24 volumio volumio[791]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 Jan 08 15:18:24 volumio volumio[791]: info: Get:4 https://apt2.volumio.org/raspbian buster/main armhf Packages [13.0 MB] Jan 08 15:18:24 volumio volumio[791]: info: Get:5 https://archive2.volumio.org/debian buster/main armhf Packages [400 kB] Jan 08 15:18:24 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 08 15:18:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 94. Jan 08 15:18:24 volumio systemd[1]: Stopped go-librespot Daemon. Jan 08 15:18:24 volumio systemd[1]: Started go-librespot Daemon. Jan 08 15:18:24 volumio go-librespot[3946]: Librespot-go daemon starting... Jan 08 15:18:24 volumio go-librespot[3946]: time="2026-01-08T15:18:24+01:00" level=info msg="generated new device id: c073e0f6b5238b2c8483c26ba8072202fce620d9" Jan 08 15:18:24 volumio go-librespot[3946]: time="2026-01-08T15:18:24+01:00" level=debug msg="stored credentials found for 1150052341" Jan 08 15:18:24 volumio go-librespot[3946]: time="2026-01-08T15:18:24+01:00" level=debug msg="obtained new client token: AADbMRhmm0evo4tXMO9NJ7mPSfvIdWGSPUStac+yQep5CP4SK09S9yn5G2zRNv657UQqDIq8vkjW6ie1y/WJUF4MKt+NQzFMY3CWrJRuzi/KoAhGyRhu083/qHkoQNhsZm9otSaobiVLvZvgvHrNvIs++4xbCGXTO74dYNEpZSXHVgw6HC+4KAB5NXofhVizuXVVb4D+9GuKEtamb85NR3nt9WL9Uz5JDAsj2if0c+eCELpYZ2GlXWVdsNBEmw==" Jan 08 15:18:24 volumio go-librespot[3946]: time="2026-01-08T15:18:24+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 08 15:18:25 volumio go-librespot[3946]: time="2026-01-08T15:18:25+01:00" level=debug msg="completed keyexchange" Jan 08 15:18:25 volumio volumio[791]: info: Initializing connection to go-librespot Websocket Jan 08 15:18:25 volumio go-librespot[3946]: time="2026-01-08T15:18:25+01:00" level=debug msg="new websocket client" Jan 08 15:18:25 volumio volumio[791]: info: Connection to go-librespot Websocket established Jan 08 15:18:25 volumio go-librespot[3946]: time="2026-01-08T15:18:25+01:00" level=debug msg="completed challenge" Jan 08 15:18:25 volumio go-librespot[3946]: time="2026-01-08T15:18:25+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 08 15:18:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 08 15:18:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 08 15:18:25 volumio volumio[791]: info: Connection to go-librespot Websocket closed Jan 08 15:18:25 volumio volumio[791]: info: Get:6 https://apt2.volumio.org/raspbian buster/contrib armhf Packages [58.8 kB] Jan 08 15:18:25 volumio volumio[791]: info: Get:7 https://apt2.volumio.org/raspbian buster/non-free armhf Packages [110 kB] Jan 08 15:18:25 volumio volumio[791]: info: Get:8 https://apt2.volumio.org/raspbian buster/rpi armhf Packages [1360 B] Jan 08 15:18:28 volumio volumio[791]: info: Getting Spotify volume Jan 08 15:18:28 volumio volumio[791]: (node:791) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 08 15:18:28 volumio volumio[791]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jan 08 15:18:28 volumio volumio[791]: (node:791) 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: 19) Jan 08 15:18:28 volumio volumio[791]: 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 08 15:18:28 volumio volumio[791]: info: CoreCommandRouter::volumioGetState Jan 08 15:18:28 volumio volumio[791]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 Jan 08 15:18:28 volumio volumio[791]: info: Initializing connection to go-librespot Websocket Jan 08 15:18:28 volumio volumio[791]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 08 15:18:28 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 08 15:18:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 95. Jan 08 15:18:28 volumio systemd[1]: Stopped go-librespot Daemon. Jan 08 15:18:28 volumio systemd[1]: Started go-librespot Daemon. Jan 08 15:18:28 volumio go-librespot[3964]: Librespot-go daemon starting... Jan 08 15:18:28 volumio go-librespot[3964]: time="2026-01-08T15:18:28+01:00" level=info msg="generated new device id: 360ae9499fc3db836e2639a07ea48edfca18cef5" Jan 08 15:18:28 volumio go-librespot[3964]: time="2026-01-08T15:18:28+01:00" level=debug msg="stored credentials found for 1150052341" Jan 08 15:18:28 volumio go-librespot[3964]: time="2026-01-08T15:18:28+01:00" level=debug msg="obtained new client token: AACMMwXhfQZO0WHYsS/MC8uLjIzQYurr1t2PR6I8awrNJGXEnEER5yEB/RXNLi8JsXMol+5I9RiPOLzI/IyoS+l1O7gJhvfZEtk2fWH50+wxc+yHWNHpTTfDZk0cUXzG010DS5kmXex/Vm88xPQ2YbYJ0Z9453WQuPGlKSC2xv+JRHaSqfUX1/7zhD7rgyB+1gea0Hbpa7HaiHzNqudJfNkyROp9Dhs1UPco5B5ZP5bBLus/L4/ceTrKWEVcCg==" Jan 08 15:18:28 volumio go-librespot[3964]: time="2026-01-08T15:18:28+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 08 15:18:29 volumio go-librespot[3964]: time="2026-01-08T15:18:29+01:00" level=debug msg="completed keyexchange" Jan 08 15:18:29 volumio go-librespot[3964]: time="2026-01-08T15:18:29+01:00" level=debug msg="completed challenge" Jan 08 15:18:29 volumio go-librespot[3964]: time="2026-01-08T15:18:29+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 08 15:18:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 08 15:18:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 08 15:18:30 volumio volumio[791]: info: Fetched 13.7 MB in 7s (1971 kB/s) Jan 08 15:18:31 volumio volumio[791]: info: Initializing connection to go-librespot Websocket Jan 08 15:18:31 volumio volumio[791]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 08 15:18:32 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 08 15:18:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 96. Jan 08 15:18:32 volumio systemd[1]: Stopped go-librespot Daemon. Jan 08 15:18:32 volumio systemd[1]: Started go-librespot Daemon. Jan 08 15:18:32 volumio go-librespot[3998]: Librespot-go daemon starting... Jan 08 15:18:32 volumio go-librespot[3998]: time="2026-01-08T15:18:32+01:00" level=info msg="generated new device id: f82532c888fbaed900e04a92a005ab90db9be344" Jan 08 15:18:32 volumio go-librespot[3998]: time="2026-01-08T15:18:32+01:00" level=debug msg="stored credentials found for 1150052341" Jan 08 15:18:32 volumio go-librespot[3998]: time="2026-01-08T15:18:32+01:00" level=debug msg="obtained new client token: AABFfGkPO/7ydl85ptUUFTOWdmUfpZQoj48iJ31ujO1dkD1reXdHtPp6JyunGZjj9cgBMyMkgp6s1M9LEmMhALoMnPZfZ7PC3S5iyTghJltDZF/uIZhT6cob1aQeZrUIIo46zJ2h30ZwkyI71XapcevXgcMrDPHenEZFiivvDEz0AyEMI+2KW6YkAH5PmveF/QzyldNo6OXe0sMGjr9wnfHCGod46b7oDa6+plbhsSqf5J3WEZGMCIGS3BOvOg==" Jan 08 15:18:32 volumio go-librespot[3998]: time="2026-01-08T15:18:32+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 08 15:18:33 volumio go-librespot[3998]: time="2026-01-08T15:18:33+01:00" level=debug msg="completed keyexchange" Jan 08 15:18:33 volumio go-librespot[3998]: time="2026-01-08T15:18:33+01:00" level=debug msg="completed challenge" Jan 08 15:18:33 volumio go-librespot[3998]: time="2026-01-08T15:18:33+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 08 15:18:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 08 15:18:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 08 15:18:34 volumio volumio[791]: info: Initializing connection to go-librespot Websocket Jan 08 15:18:34 volumio volumio[791]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 08 15:18:36 volumio volumio[791]: info: Reading package lists... Jan 08 15:18:36 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 08 15:18:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 97. Jan 08 15:18:36 volumio systemd[1]: Stopped go-librespot Daemon. Jan 08 15:18:36 volumio systemd[1]: Started go-librespot Daemon. Jan 08 15:18:36 volumio go-librespot[4013]: Librespot-go daemon starting... Jan 08 15:18:36 volumio go-librespot[4013]: time="2026-01-08T15:18:36+01:00" level=info msg="generated new device id: fc082df356d4b028fbb080554279118ad56ccc13" Jan 08 15:18:36 volumio go-librespot[4013]: time="2026-01-08T15:18:36+01:00" level=debug msg="stored credentials found for 1150052341" Jan 08 15:18:36 volumio volumio[791]: info: Building dependency tree... Jan 08 15:18:36 volumio go-librespot[4013]: time="2026-01-08T15:18:36+01:00" level=debug msg="obtained new client token: AACzjZTeF4mRYD7NyZplRWVx8ZhIgWPO/OVA0hl87zlKtXwxFZD3zUduIXw6BaUpfKveTIMG9M4YMaNWO9Zu/Ij5adALiRWM9KT/2EsGoTwxLw9RXdM0KeiSvRGEMev6/o0rVsgNgdH01qz+NR6gXoddHKr/khJ9xzJhY624Rc0UmE1CnVF0IZX3XlQjxCr27EGQ/j/vTXEx28F9Ad8wCmKNd4x5urEDb5Z1gh1YWtnPszw8Z66EAGDsDInrMA==" Jan 08 15:18:36 volumio volumio[791]: info: Reading state information... Jan 08 15:18:36 volumio volumio[791]: info: 35 packages can be upgraded. Run 'apt list --upgradable' to see them. Jan 08 15:18:36 volumio sudo[3739]: pam_unix(sudo:session): session closed for user root Jan 08 15:18:36 volumio go-librespot[4013]: time="2026-01-08T15:18:36+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 08 15:18:36 volumio sudo[4024]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt -y install python3-aiohttp python3-pip Jan 08 15:18:36 volumio sudo[4024]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 15:18:37 volumio go-librespot[4013]: time="2026-01-08T15:18:37+01:00" level=debug msg="completed keyexchange" Jan 08 15:18:37 volumio volumio[791]: info: Reading package lists... Jan 08 15:18:37 volumio go-librespot[4013]: time="2026-01-08T15:18:37+01:00" level=debug msg="completed challenge" Jan 08 15:18:37 volumio volumio[791]: info: Initializing connection to go-librespot Websocket Jan 08 15:18:37 volumio go-librespot[4013]: time="2026-01-08T15:18:37+01:00" level=debug msg="new websocket client" Jan 08 15:18:37 volumio volumio[791]: info: Connection to go-librespot Websocket established Jan 08 15:18:37 volumio go-librespot[4013]: time="2026-01-08T15:18:37+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 08 15:18:37 volumio volumio[791]: info: Connection to go-librespot Websocket closed Jan 08 15:18:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 08 15:18:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 08 15:18:37 volumio volumio[791]: info: Building dependency tree... Jan 08 15:18:37 volumio volumio[791]: info: Reading state information... Jan 08 15:18:40 volumio volumio[791]: info: Getting Spotify volume Jan 08 15:18:40 volumio volumio[791]: (node:791) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 08 15:18:40 volumio volumio[791]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jan 08 15:18:40 volumio volumio[791]: (node:791) 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: 20) Jan 08 15:18:40 volumio volumio[791]: 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 08 15:18:40 volumio volumio[791]: info: Initializing connection to go-librespot Websocket Jan 08 15:18:40 volumio volumio[791]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 08 15:18:40 volumio volumio[791]: info: CoreCommandRouter::volumioGetState Jan 08 15:18:40 volumio sudo[4024]: pam_unix(sudo:session): session closed for user root Jan 08 15:18:40 volumio volumio[791]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 Jan 08 15:18:40 volumio volumio[791]: info: python3-aiohttp is already the newest version (3.5.1-1+deb10u1). Jan 08 15:18:40 volumio sudo[4048]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/tar -xvf fusiondsp.service.tar -C / Jan 08 15:18:40 volumio volumio[791]: info: python3-pip is already the newest version (18.1-5+rpt1). Jan 08 15:18:40 volumio sudo[4048]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 15:18:40 volumio volumio[791]: info: 0 upgraded, 0 newly installed, 0 to remove and 37 not upgraded. Jan 08 15:18:40 volumio sudo[4048]: pam_unix(sudo:session): session closed for user root Jan 08 15:18:40 volumio volumio[791]: info: lib/systemd/system/fusiondsp.service Jan 08 15:18:40 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 08 15:18:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 98. Jan 08 15:18:40 volumio systemd[1]: Stopped go-librespot Daemon. Jan 08 15:18:40 volumio systemd[1]: Started go-librespot Daemon. Jan 08 15:18:40 volumio go-librespot[4051]: Librespot-go daemon starting... Jan 08 15:18:40 volumio go-librespot[4051]: time="2026-01-08T15:18:40+01:00" level=info msg="generated new device id: b8f10d2bb0748f72d561069494fccdd2a4630544" Jan 08 15:18:40 volumio go-librespot[4051]: time="2026-01-08T15:18:40+01:00" level=debug msg="stored credentials found for 1150052341" Jan 08 15:18:40 volumio go-librespot[4051]: time="2026-01-08T15:18:40+01:00" level=debug msg="obtained new client token: AAC0+JKdZTLZVJ7VFn/52GiLuqx13fB5r2ERLiQEXGER3b3tI22TkyVkm4NzHUMO0TTtGeJbyU5PBkQRpz53Q4Yv/v7TwiV6jRayZcYY/a29NC7+sfn0VbLol1nizMr/dwP0yKXES2gHa5VmCyjze5wY+mLMeoI3Jk2tLJgaTw5qzNJ5zWMFcGL7kh4JjqTmUnoO5M+ZyOAuoPpZRshlVZ20OzcjRlG4NNN8XzaweU3vRyna89IF0CxcB5v6mQ==" Jan 08 15:18:40 volumio go-librespot[4051]: time="2026-01-08T15:18:40+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 08 15:18:41 volumio go-librespot[4051]: time="2026-01-08T15:18:41+01:00" level=debug msg="completed keyexchange" Jan 08 15:18:41 volumio go-librespot[4051]: time="2026-01-08T15:18:41+01:00" level=debug msg="completed challenge" Jan 08 15:18:41 volumio go-librespot[4051]: time="2026-01-08T15:18:41+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 08 15:18:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 08 15:18:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 08 15:18:42 volumio volumio[791]: info: MiniUnz 1.01b, demo of zLib + Unz package written by Gilles Vollant Jan 08 15:18:42 volumio volumio[791]: info: more info at http://www.winimage.com/zLibDll/unzip.html Jan 08 15:18:42 volumio volumio[791]: info: Jan 08 15:18:42 volumio volumio[791]: info: cgui-1.0.0.zip opened Jan 08 15:18:42 volumio volumio[791]: info: creating directory: cgui/ Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/LICENSE.txt Jan 08 15:18:42 volumio sudo[4064]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chown -R volumio cgui Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/README.md Jan 08 15:18:42 volumio volumio[791]: info: creating directory: cgui/build/ Jan 08 15:18:42 volumio sudo[4064]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/build/robots.txt Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/build/logo512.png Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/build/asset-manifest.json Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/build/index.html Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/build/.put_statics_here Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/build/favicon.ico Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/build/logo192.png Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/build/.xdp-css-variables-1.css-PvrWoX Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/build/manifest.json Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/build/css-variables.css Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/build/precache-manifest.76db5aad1647230454f47b1e22783b54.js Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/build/service-worker.js Jan 08 15:18:42 volumio sudo[4064]: pam_unix(sudo:session): session closed for user root Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/build/precache-manifest.5e6c675b70ef6a3fe73d0209febfe347.js Jan 08 15:18:42 volumio volumio[791]: info: creating directory: cgui/build/static/ Jan 08 15:18:42 volumio volumio[791]: info: creating directory: cgui/build/static/css/ Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css.map Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css.map Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css.map Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/build/static/css/main.1503f6cd.css.map Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css.map Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/build/static/css/main.1503f6cd.css Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css Jan 08 15:18:42 volumio volumio[791]: info: creating directory: cgui/build/static/media/ Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/build/static/media/camilladsp.95719842.svg Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/build/static/media/camilladsp.4767ea4ad3510467418b818dc677a87e.svg Jan 08 15:18:42 volumio volumio[791]: info: creating directory: cgui/build/static/js/ Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.map Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/build/static/js/main.71e11955.js Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js Jan 08 15:18:42 volumio sudo[4066]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chgrp -R volumio cgui Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.LICENSE.txt Jan 08 15:18:42 volumio sudo[4066]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js.map Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js.map Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/build/static/js/main.71e11955.js.LICENSE.txt Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/build/static/js/main.71e11955.js.map Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js.map Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.map Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.LICENSE.txt Jan 08 15:18:42 volumio volumio[791]: info: creating directory: cgui/config/ Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/config/camillagui.yml Jan 08 15:18:42 volumio sudo[4066]: pam_unix(sudo:session): session closed for user root Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/config/.xdp-css-variables-1.css-ppBDuX Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/config/gui-config.yml Jan 08 15:18:42 volumio volumio[791]: info: creating directory: cgui/backend/ Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/backend/filters_test.py Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/backend/version.py Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/backend/filters.py Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/backend/settings.py Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/backend/views.py Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/backend/filterdefaults.py Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/backend/__init__.py Jan 08 15:18:42 volumio volumio[791]: info: creating directory: cgui/backend/__pycache__/ Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/backend/__pycache__/filterdefaults.cpython-37.pyc Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/backend/__pycache__/views.cpython-37.pyc Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/backend/__pycache__/settings.cpython-37.pyc Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/backend/__pycache__/__init__.cpython-37.pyc Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/backend/__pycache__/routes.cpython-37.pyc Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/backend/__pycache__/filemanagement.cpython-37.pyc Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/backend/__pycache__/filters.cpython-37.pyc Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/backend/__pycache__/version.cpython-37.pyc Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/backend/routes.py Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/backend/filemanagement.py Jan 08 15:18:42 volumio volumio[791]: info: extracting: cgui/main.py Jan 08 15:18:42 volumio sudo[4068]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/rm cgui-1.0.0.zip Jan 08 15:18:42 volumio sudo[4068]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 15:18:42 volumio sudo[4068]: pam_unix(sudo:session): session closed for user root Jan 08 15:18:43 volumio volumio[791]: info: Initializing connection to go-librespot Websocket Jan 08 15:18:43 volumio volumio[791]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 08 15:18:44 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 08 15:18:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 99. Jan 08 15:18:44 volumio systemd[1]: Stopped go-librespot Daemon. Jan 08 15:18:44 volumio systemd[1]: Started go-librespot Daemon. Jan 08 15:18:44 volumio go-librespot[4076]: Librespot-go daemon starting... Jan 08 15:18:44 volumio go-librespot[4076]: time="2026-01-08T15:18:44+01:00" level=info msg="generated new device id: 13135ef47113ca880ebe871519836847aec92567" Jan 08 15:18:44 volumio go-librespot[4076]: time="2026-01-08T15:18:44+01:00" level=debug msg="stored credentials found for 1150052341" Jan 08 15:18:44 volumio go-librespot[4076]: time="2026-01-08T15:18:44+01:00" level=debug msg="obtained new client token: AABEAlQOWwDt1FCiUMnh+tKzcimyeLdgzlziAPzJ9aeegaHUt/wzFkSKSPY5Yt0lxZ079uPmK2RkLHjKIKLcNfBDEo2ShIcgyXVPm4zFQbYfyjsOU9VMmLxatGiRfK54noYmhnzuT3vHHIprIJ4+BBrKTqEkJVEBXSfsRmVIc+cQOfi2Er+rOlJlm35m1FEJBj6Ap4TbErWag1BsglJ3HfpaSSu+Eky5bZa2U9MXVMyVE1kYZ2GThTdzs8jRUg==" Jan 08 15:18:45 volumio go-librespot[4076]: time="2026-01-08T15:18:45+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 08 15:18:45 volumio go-librespot[4076]: time="2026-01-08T15:18:45+01:00" level=debug msg="completed keyexchange" Jan 08 15:18:45 volumio go-librespot[4076]: time="2026-01-08T15:18:45+01:00" level=debug msg="completed challenge" Jan 08 15:18:45 volumio go-librespot[4076]: time="2026-01-08T15:18:45+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 08 15:18:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 08 15:18:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 08 15:18:46 volumio volumio[791]: info: Initializing connection to go-librespot Websocket Jan 08 15:18:46 volumio volumio[791]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 08 15:18:47 volumio volumio[791]: info: Looking in indexes: https://pypi.org/simple, https://www.piwheels.org/simple Jan 08 15:18:47 volumio volumio[791]: info: Collecting git+https://github.com/HEnquist/pycamilladsp.git@v1.0.0 Jan 08 15:18:47 volumio volumio[791]: info: Cloning https://github.com/HEnquist/pycamilladsp.git (to revision v1.0.0) to /tmp/pip-req-build-p4xxtjrz Jan 08 15:18:48 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 08 15:18:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 100. Jan 08 15:18:48 volumio systemd[1]: Stopped go-librespot Daemon. Jan 08 15:18:48 volumio systemd[1]: Started go-librespot Daemon. Jan 08 15:18:48 volumio go-librespot[4111]: Librespot-go daemon starting... Jan 08 15:18:48 volumio go-librespot[4111]: time="2026-01-08T15:18:48+01:00" level=info msg="generated new device id: e6578a4ca7c14a73ff59b008002d81d5eb4b20e5" Jan 08 15:18:48 volumio go-librespot[4111]: time="2026-01-08T15:18:48+01:00" level=debug msg="stored credentials found for 1150052341" Jan 08 15:18:48 volumio go-librespot[4111]: time="2026-01-08T15:18:48+01:00" level=debug msg="obtained new client token: AAAHJX5CZOC+1x2Evw+uUIFXIX2aQEn3gFI7hX/7rqvsBV4hSVbYHpMGqeQ31FGc+lZBvAvEH03CJm83vegVVFlK5sAN3A0ywjJjWeH0zzWxRPNp95eCa47rN/YNbSaSfOrrgaEOp1XQeNNjqZh7x9cRaDigfvtDBoUGJAr+7GuHYcIfKZXL82rCXRPECsCGLECQVqisNOgN9hUGrapJWnYGCllulClxeiUJJRKljN8u1dhRoa7049ia3Hg34A==" Jan 08 15:18:49 volumio go-librespot[4111]: time="2026-01-08T15:18:49+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 08 15:18:49 volumio go-librespot[4111]: time="2026-01-08T15:18:49+01:00" level=debug msg="completed keyexchange" Jan 08 15:18:49 volumio volumio[791]: info: Requirement already satisfied (use --upgrade to upgrade): camilladsp==1.0.0 from git+https://github.com/HEnquist/pycamilladsp.git@v1.0.0 in /usr/local/lib/python3.7/dist-packages Jan 08 15:18:49 volumio volumio[791]: info: Requirement already satisfied: PyYAML in /usr/local/lib/python3.7/dist-packages (from camilladsp==1.0.0) (6.0.1) Jan 08 15:18:49 volumio volumio[791]: info: Requirement already satisfied: websocket_client in /usr/local/lib/python3.7/dist-packages (from camilladsp==1.0.0) (1.6.1) Jan 08 15:18:49 volumio volumio[791]: info: Building wheels for collected packages: camilladsp Jan 08 15:18:49 volumio volumio[791]: info: Running setup.py bdist_wheel for camilladsp: started Jan 08 15:18:49 volumio go-librespot[4111]: time="2026-01-08T15:18:49+01:00" level=debug msg="completed challenge" Jan 08 15:18:49 volumio volumio[791]: info: Initializing connection to go-librespot Websocket Jan 08 15:18:49 volumio go-librespot[4111]: time="2026-01-08T15:18:49+01:00" level=debug msg="new websocket client" Jan 08 15:18:49 volumio volumio[791]: info: Connection to go-librespot Websocket established Jan 08 15:18:49 volumio go-librespot[4111]: time="2026-01-08T15:18:49+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 08 15:18:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 08 15:18:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 08 15:18:49 volumio volumio[791]: info: Connection to go-librespot Websocket closed Jan 08 15:18:50 volumio volumio[791]: info: Running setup.py bdist_wheel for camilladsp: finished with status 'done' Jan 08 15:18:50 volumio volumio[791]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-kh5o6o88/wheels/ca/47/7d/ee36beb671f74869a8e2ecb2c3fd4a28c1b7488a9c78e442b6 Jan 08 15:18:50 volumio volumio[791]: info: Successfully built camilladsp Jan 08 15:18:52 volumio volumio[791]: info: Getting Spotify volume Jan 08 15:18:52 volumio volumio[791]: (node:791) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 08 15:18:52 volumio volumio[791]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jan 08 15:18:52 volumio volumio[791]: (node:791) 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: 21) Jan 08 15:18:52 volumio volumio[791]: 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 08 15:18:52 volumio volumio[791]: info: Initializing connection to go-librespot Websocket Jan 08 15:18:52 volumio volumio[791]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 08 15:18:52 volumio volumio[791]: info: CoreCommandRouter::volumioGetState Jan 08 15:18:52 volumio volumio[791]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 Jan 08 15:18:52 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 08 15:18:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 101. Jan 08 15:18:52 volumio systemd[1]: Stopped go-librespot Daemon. Jan 08 15:18:52 volumio systemd[1]: Started go-librespot Daemon. Jan 08 15:18:52 volumio go-librespot[4150]: Librespot-go daemon starting... Jan 08 15:18:52 volumio go-librespot[4150]: time="2026-01-08T15:18:52+01:00" level=info msg="generated new device id: 8e77a49d21fb923e647701414303f9a0705591c5" Jan 08 15:18:52 volumio go-librespot[4150]: time="2026-01-08T15:18:52+01:00" level=debug msg="stored credentials found for 1150052341" Jan 08 15:18:52 volumio go-librespot[4150]: time="2026-01-08T15:18:52+01:00" level=debug msg="obtained new client token: AACuGCPvbUPbu09AnuWQaK9+O04tG6CGGpJn2DpwMFg8eJuFMW1V9kUpcgpJsVsrVTl20HKQhH2JTnvJuj09RF6gvQ30i/enm1Q6Jb1aRvC/zxQR85d38gDjabrNRzY4KxXubowaV+8GycVC9f/NLGNu/yVatLRz+uRjmSoOKZF2xTN65I8NY+BOOd6VfjVV9MiHECTxOViy7LoFeoemLFv/y6CmJyoZu5Tvfcay8UnMaTRTm7JEfYPxYiah4A==" Jan 08 15:18:52 volumio go-librespot[4150]: time="2026-01-08T15:18:52+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 08 15:18:53 volumio go-librespot[4150]: time="2026-01-08T15:18:53+01:00" level=debug msg="completed keyexchange" Jan 08 15:18:53 volumio go-librespot[4150]: time="2026-01-08T15:18:53+01:00" level=debug msg="completed challenge" Jan 08 15:18:53 volumio go-librespot[4150]: time="2026-01-08T15:18:53+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 08 15:18:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 08 15:18:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 08 15:18:54 volumio volumio[791]: info: Looking in indexes: https://pypi.org/simple, https://www.piwheels.org/simple Jan 08 15:18:54 volumio volumio[791]: info: Collecting git+https://github.com/HEnquist/pycamilladsp-plot.git@v1.0.2 Jan 08 15:18:54 volumio volumio[791]: info: Cloning https://github.com/HEnquist/pycamilladsp-plot.git (to revision v1.0.2) to /tmp/pip-req-build-vgh8n3yz Jan 08 15:18:55 volumio volumio[791]: info: Initializing connection to go-librespot Websocket Jan 08 15:18:55 volumio volumio[791]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 08 15:18:55 volumio volumio[791]: info: Requirement already satisfied (use --upgrade to upgrade): camilladsp-plot==1.0.2 from git+https://github.com/HEnquist/pycamilladsp-plot.git@v1.0.2 in /usr/local/lib/python3.7/dist-packages Jan 08 15:18:55 volumio volumio[791]: info: Requirement already satisfied: PyYAML in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (6.0.1) Jan 08 15:18:55 volumio volumio[791]: info: Requirement already satisfied: jsonschema in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (4.17.3) Jan 08 15:18:55 volumio volumio[791]: info: Requirement already satisfied: pyrsistent!=0.17.0,!=0.17.1,!=0.17.2,>=0.14.0 in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (0.19.3) Jan 08 15:18:55 volumio volumio[791]: info: Requirement already satisfied: attrs>=17.4.0 in /usr/lib/python3/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (18.2.0) Jan 08 15:18:55 volumio volumio[791]: info: Requirement already satisfied: importlib-resources>=1.4.0; python_version < "3.9" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (5.12.0) Jan 08 15:18:55 volumio volumio[791]: info: Requirement already satisfied: importlib-metadata; python_version < "3.8" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (6.7.0) Jan 08 15:18:55 volumio volumio[791]: info: Requirement already satisfied: typing-extensions; python_version < "3.8" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (4.7.1) Jan 08 15:18:55 volumio volumio[791]: info: Requirement already satisfied: pkgutil-resolve-name>=1.3.10; python_version < "3.9" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (1.3.10) Jan 08 15:18:55 volumio volumio[791]: info: Requirement already satisfied: zipp>=3.1.0; python_version < "3.10" in /usr/local/lib/python3.7/dist-packages (from importlib-resources>=1.4.0; python_version < "3.9"->jsonschema->camilladsp-plot==1.0.2) (3.15.0) Jan 08 15:18:56 volumio volumio[791]: info: Building wheels for collected packages: camilladsp-plot Jan 08 15:18:56 volumio volumio[791]: info: Running setup.py bdist_wheel for camilladsp-plot: started Jan 08 15:18:56 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 08 15:18:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 102. Jan 08 15:18:56 volumio systemd[1]: Stopped go-librespot Daemon. Jan 08 15:18:56 volumio systemd[1]: Started go-librespot Daemon. Jan 08 15:18:56 volumio go-librespot[4227]: Librespot-go daemon starting... Jan 08 15:18:56 volumio go-librespot[4227]: time="2026-01-08T15:18:56+01:00" level=info msg="generated new device id: 2e15735ca772b538a32d6cdf1a63e0650ef3d9ce" Jan 08 15:18:56 volumio go-librespot[4227]: time="2026-01-08T15:18:56+01:00" level=debug msg="stored credentials found for 1150052341" Jan 08 15:18:56 volumio go-librespot[4227]: time="2026-01-08T15:18:56+01:00" level=debug msg="obtained new client token: AAB0ZAH5cTif1qVX8eD1DWl1wkRQo69ihWucjVyl5bMFo4yMBfiDC+0gfMUjngYeUYCWczDFpOyoZSVFeo6SfriYXJmcPDuGhEYseAWbWGqaag9LlVKtk2p2aJOZIPxoOGEyUNa8819e0qBXiZCVCxFKywQsNI9gzjIrAs6oCg8rCBLtlXrFIS4f658Dy82FQWiER+QJfJW02cxbzkp9uIOHV+on/WzZxZ+4s2Vbj1V9tuvu0lHpAuObzdpnZQ==" Jan 08 15:18:56 volumio volumio[791]: info: Running setup.py bdist_wheel for camilladsp-plot: finished with status 'done' Jan 08 15:18:56 volumio volumio[791]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-e9dg2h38/wheels/11/e1/43/8275af2f537d569c7097d202b4955f9aec629859be39dab2e9 Jan 08 15:18:56 volumio volumio[791]: info: Successfully built camilladsp-plot Jan 08 15:18:57 volumio go-librespot[4227]: time="2026-01-08T15:18:57+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 08 15:18:57 volumio go-librespot[4227]: time="2026-01-08T15:18:57+01:00" level=debug msg="completed keyexchange" Jan 08 15:18:57 volumio volumio[791]: info: copying hw detection script Jan 08 15:18:57 volumio volumio[791]: info: Detected cpu architecture as armv7l Jan 08 15:18:57 volumio go-librespot[4227]: time="2026-01-08T15:18:57+01:00" level=debug msg="completed challenge" Jan 08 15:18:57 volumio go-librespot[4227]: time="2026-01-08T15:18:57+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 08 15:18:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 08 15:18:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 08 15:18:58 volumio sudo[4250]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/bin/cp /data/plugins/audio_interface/fusiondsp/c/hw_params_arm /data/plugins/audio_interface/fusiondsp/hw_params Jan 08 15:18:58 volumio sudo[4250]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 15:18:58 volumio sudo[4250]: pam_unix(sudo:session): session closed for user root Jan 08 15:18:58 volumio sudo[4252]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/bin/chmod +x /data/plugins/audio_interface/fusiondsp/hw_params Jan 08 15:18:58 volumio sudo[4252]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 15:18:58 volumio sudo[4252]: pam_unix(sudo:session): session closed for user root Jan 08 15:18:58 volumio sudo[4254]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/usr/bin/apt-get -y install drc Jan 08 15:18:58 volumio sudo[4254]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 15:18:58 volumio volumio[791]: info: Reading package lists... Jan 08 15:18:58 volumio volumio[791]: info: Initializing connection to go-librespot Websocket Jan 08 15:18:58 volumio volumio[791]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 08 15:18:58 volumio volumio[791]: info: Building dependency tree... Jan 08 15:18:58 volumio volumio[791]: info: Reading state information... Jan 08 15:18:59 volumio volumio[791]: info: drc is already the newest version (3.2.2~dfsg0-2). Jan 08 15:18:59 volumio sudo[4254]: pam_unix(sudo:session): session closed for user root Jan 08 15:18:59 volumio sudo[3712]: pam_unix(sudo:session): session closed for user root Jan 08 15:18:59 volumio volumio[791]: info: 0 upgraded, 0 newly installed, 0 to remove and 37 not upgraded. Jan 08 15:18:59 volumio volumio[791]: info: Install script completed Jan 08 15:18:59 volumio volumio[791]: info: Done installing plugin. Jan 08 15:18:59 volumio volumio[791]: info: Enabling plugin fusiondsp Jan 08 15:18:59 volumio volumio[791]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 08 15:18:59 volumio volumio[791]: info: [{"prettyName":"FusionDsp","name":"fusiondsp","category":"audio_interface","version":"1.0.62","icon":"fa-sliders fa-rotate-90","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"80s80s Radio","name":"80s80s","category":"music_service","version":"1.0.4","icon":"fa-headphones","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.0.4","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Backup & Restore Data","name":"backup_restore","category":"system_controller","version":"0.7.4","icon":"fa-floppy-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Peppy-spectrum","name":"peppyspectrum","category":"user_interface","version":"0.0.23","icon":"fa-bar-chart","isManuallyInstalled":true,"enabled":true,"active":true}] Jan 08 15:18:59 volumio volumio[791]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 08 15:18:59 volumio volumio[791]: info: Folder /tmp/plugins removed Jan 08 15:18:59 volumio volumio[791]: info: Folder /tmp/downloaded_plugin.zip removed Jan 08 15:18:59 volumio volumio[791]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 15:18:59 volumio volumio[791]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 15:18:59 volumio volumio[791]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 15:18:59 volumio volumio[791]: Plugin install end detected on script Jan 08 15:18:59 volumio volumio[791]: info: Folder /data/temp removed Jan 08 15:19:00 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 08 15:19:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 103. Jan 08 15:19:00 volumio systemd[1]: Stopped go-librespot Daemon. Jan 08 15:19:00 volumio systemd[1]: Started go-librespot Daemon. Jan 08 15:19:00 volumio go-librespot[4276]: Librespot-go daemon starting... Jan 08 15:19:00 volumio go-librespot[4276]: time="2026-01-08T15:19:00+01:00" level=info msg="generated new device id: d2c3b0eadee0f55df15c9402222e9da80860b568" Jan 08 15:19:00 volumio go-librespot[4276]: time="2026-01-08T15:19:00+01:00" level=debug msg="stored credentials found for 1150052341" Jan 08 15:19:00 volumio go-librespot[4276]: time="2026-01-08T15:19:00+01:00" level=debug msg="obtained new client token: AAA3r5nly4gPZI5MUngR/7GVeIlxHQeMe2hSPhwoXwiUJxt3+KF4fcp+2Jjp0xo/miPiSrfsUaI8+QR9hjlc0uSdH6a7hfJFfrb+m7OW4Dl1OSM0UB5YLlXAsUAWcpISXGh1fdsE6oo1/K8aceu/daMfaa3EyzELFkXTnOnXoUSBwCx+ahD5dNphgPT4/I/KA2COCbSBOvKtLURQrJWkWZo8FaCb8yGxjdSh0/7zF9Vf2Ke1vB/ewjeT6u+W/g==" Jan 08 15:19:00 volumio go-librespot[4276]: time="2026-01-08T15: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 08 15:19:01 volumio go-librespot[4276]: time="2026-01-08T15:19:01+01:00" level=debug msg="completed keyexchange" Jan 08 15:19:01 volumio go-librespot[4276]: time="2026-01-08T15:19:01+01:00" level=debug msg="completed challenge" Jan 08 15:19:01 volumio go-librespot[4276]: time="2026-01-08T15:19:01+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 08 15:19:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 08 15:19:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 08 15:19:01 volumio volumio[791]: info: Initializing connection to go-librespot Websocket Jan 08 15:19:01 volumio volumio[791]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 08 15:19:04 volumio volumio[791]: info: Initializing connection to go-librespot Websocket Jan 08 15:19:04 volumio volumio[791]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 08 15:19:04 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 08 15:19:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 104. Jan 08 15:19:04 volumio systemd[1]: Stopped go-librespot Daemon. Jan 08 15:19:04 volumio systemd[1]: Started go-librespot Daemon. Jan 08 15:19:04 volumio go-librespot[4293]: Librespot-go daemon starting... Jan 08 15:19:04 volumio go-librespot[4293]: time="2026-01-08T15:19:04+01:00" level=info msg="generated new device id: 4377092a0db976b7b4c85b6cff69d69f4a9e8693" Jan 08 15:19:04 volumio go-librespot[4293]: time="2026-01-08T15:19:04+01:00" level=debug msg="stored credentials found for 1150052341" Jan 08 15:19:04 volumio go-librespot[4293]: time="2026-01-08T15:19:04+01:00" level=debug msg="obtained new client token: AAAjWXQZsQf01xArPuGi867yWpfLkxyLlq0e4Z+biXRURwE8wLz1yOnIXgq+AmHbZCE45xD6cRjUuNKdaot3ANlZdsfR0PulC2wAgTYQNc3teibC5ErRNhNZ3m8gsOV98z6H7mwhvVY+NZh1diWIDl/+QHwZAu5JRTwDPkk05Nvq8RrlyR8vFWr/RUTlQLouXt/ZF/QkShSEyvYI+MSt373yaPcoGSvrNyTratTiadX1o0FipoV19XdNtUShNg==" Jan 08 15:19:04 volumio go-librespot[4293]: time="2026-01-08T15:19:04+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 08 15:19:04 volumio go-librespot[4293]: time="2026-01-08T15:19:04+01:00" level=debug msg="completed keyexchange" Jan 08 15:19:05 volumio go-librespot[4293]: time="2026-01-08T15:19:05+01:00" level=debug msg="completed challenge" Jan 08 15:19:05 volumio go-librespot[4293]: time="2026-01-08T15:19:05+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 08 15:19:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 08 15:19:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 08 15:19:07 volumio volumio[791]: info: Initializing connection to go-librespot Websocket Jan 08 15:19:07 volumio volumio[791]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 08 15:19:08 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 08 15:19:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 105. Jan 08 15:19:08 volumio systemd[1]: Stopped go-librespot Daemon. Jan 08 15:19:08 volumio systemd[1]: Started go-librespot Daemon. Jan 08 15:19:08 volumio go-librespot[4310]: Librespot-go daemon starting... Jan 08 15:19:08 volumio go-librespot[4310]: time="2026-01-08T15:19:08+01:00" level=info msg="generated new device id: b855398eca87eaae66af0acd24ff09a53cab4a88" Jan 08 15:19:08 volumio go-librespot[4310]: time="2026-01-08T15:19:08+01:00" level=debug msg="stored credentials found for 1150052341" Jan 08 15:19:08 volumio go-librespot[4310]: time="2026-01-08T15:19:08+01:00" level=debug msg="obtained new client token: AAAUgk3sE0ET+jjBm+odj9ejd1IPMNHvlcNBk/vnA7odgM/fP/Ws2jdj7adjLNDA1FwFKDauWTVsjAOjh/sd111dD+oGOyLSTHxZmDhzCJfFYiE45dZva2ODsFz2e84BzY0vT3Yz11i1bN4HYEEv66uWTTIf3ar+SX5BW5TK9rTh52q+3UCs2AkeZ7mtI9Z6QycuDvP6l2WEL7D7ccHUjuu0Ck0d7CnVXXxVUqwjJTw9jbn6HemoGqzHUOVUwQ==" Jan 08 15:19:08 volumio go-librespot[4310]: time="2026-01-08T15:19:08+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 08 15:19:09 volumio go-librespot[4310]: time="2026-01-08T15:19:09+01:00" level=debug msg="completed keyexchange" Jan 08 15:19:09 volumio go-librespot[4310]: time="2026-01-08T15:19:09+01:00" level=debug msg="completed challenge" Jan 08 15:19:09 volumio go-librespot[4310]: time="2026-01-08T15:19:09+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 08 15:19:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 08 15:19:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 08 15:19:10 volumio volumio[791]: info: Initializing connection to go-librespot Websocket Jan 08 15:19:10 volumio volumio[791]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 08 15:19:12 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 08 15:19:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 106. Jan 08 15:19:12 volumio systemd[1]: Stopped go-librespot Daemon. Jan 08 15:19:12 volumio systemd[1]: Started go-librespot Daemon. Jan 08 15:19:12 volumio go-librespot[4343]: Librespot-go daemon starting... Jan 08 15:19:12 volumio go-librespot[4343]: time="2026-01-08T15:19:12+01:00" level=info msg="generated new device id: 23dd73c8cfa62eb93b460e761314c9dbecac9348" Jan 08 15:19:12 volumio go-librespot[4343]: time="2026-01-08T15:19:12+01:00" level=debug msg="stored credentials found for 1150052341" Jan 08 15:19:12 volumio go-librespot[4343]: time="2026-01-08T15:19:12+01:00" level=debug msg="obtained new client token: AACP0y9mmJy8THWGODZY5M4jg5S+cHNklBBPc8B0pFJmBHd+r+2nEW+swKUMzW1R1Jcu6vp+mASg7QcsPn0OVlXM/K1cDFrbErSl0N5D+YFkePGM9m922AxNEJmMB9emLc9Z+HrWb7INHkXzEMmKfb81z6ag1t7P/0Cc1T4ioIVzXTV2CopyuUANqXEWVZkJPCLvN96O0SffXr7c0WfVMLc8GZ/55XzOCkMSlH1ONFhyUKqSaz0YXcTUb/qNSw==" Jan 08 15:19:12 volumio go-librespot[4343]: time="2026-01-08T15:19: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 08 15:19:13 volumio go-librespot[4343]: time="2026-01-08T15:19:13+01:00" level=debug msg="completed keyexchange" Jan 08 15:19:13 volumio go-librespot[4343]: time="2026-01-08T15:19:13+01:00" level=debug msg="completed challenge" Jan 08 15:19:13 volumio go-librespot[4343]: time="2026-01-08T15:19:13+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 08 15:19:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 08 15:19:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 08 15:19:13 volumio volumio[791]: info: Initializing connection to go-librespot Websocket Jan 08 15:19:13 volumio volumio[791]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 08 15:19:16 volumio volumio[791]: info: Initializing connection to go-librespot Websocket Jan 08 15:19:16 volumio volumio[791]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 08 15:19:16 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 08 15:19:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 107. Jan 08 15:19:16 volumio systemd[1]: Stopped go-librespot Daemon. Jan 08 15:19:16 volumio systemd[1]: Started go-librespot Daemon. Jan 08 15:19:16 volumio go-librespot[4359]: Librespot-go daemon starting... Jan 08 15:19:16 volumio go-librespot[4359]: time="2026-01-08T15:19:16+01:00" level=info msg="generated new device id: 4a684c97628cedc47838a734f1cf26e788e37c27" Jan 08 15:19:16 volumio go-librespot[4359]: time="2026-01-08T15:19:16+01:00" level=debug msg="stored credentials found for 1150052341" Jan 08 15:19:16 volumio go-librespot[4359]: time="2026-01-08T15:19:16+01:00" level=debug msg="obtained new client token: AAC2PHOEFo0g0MBycnJVhzxycesdaEpmPCXdr0gWIwpfsfVKgpaq0qvUPmvnFAal7i7IYvOEdLe53B5I2bSIZzdTubx2iUyOBjFHP02EoXvvWiwsJ2AwBvPXqAVj1FuqsTLKO+DFQaCvl9hj/dfzYAUx4l2z7joXUQWJ+K6krQr7jO6l4xpP13IhrSt6+GFldiClURHo4LRfblCFceqfnWIEddwS14gQ+mfHtniV1oGuxBj/bvXqlm7NsDjqxQ==" Jan 08 15:19:16 volumio go-librespot[4359]: time="2026-01-08T15:19:16+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 08 15:19:17 volumio go-librespot[4359]: time="2026-01-08T15:19:17+01:00" level=debug msg="completed keyexchange" Jan 08 15:19:17 volumio go-librespot[4359]: time="2026-01-08T15:19:17+01:00" level=debug msg="completed challenge" Jan 08 15:19:17 volumio go-librespot[4359]: time="2026-01-08T15:19:17+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 08 15:19:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 08 15:19:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 08 15:19:19 volumio volumio[791]: info: Initializing connection to go-librespot Websocket Jan 08 15:19:19 volumio volumio[791]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 08 15:19:20 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 08 15:19:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 108. Jan 08 15:19:20 volumio systemd[1]: Stopped go-librespot Daemon. Jan 08 15:19:20 volumio systemd[1]: Started go-librespot Daemon. Jan 08 15:19:20 volumio go-librespot[4390]: Librespot-go daemon starting... Jan 08 15:19:20 volumio go-librespot[4390]: time="2026-01-08T15:19:20+01:00" level=info msg="generated new device id: 67cf645abdb60878d8263e8ed923175ffbac606b" Jan 08 15:19:20 volumio go-librespot[4390]: time="2026-01-08T15:19:20+01:00" level=debug msg="stored credentials found for 1150052341" Jan 08 15:19:20 volumio go-librespot[4390]: time="2026-01-08T15:19:20+01:00" level=debug msg="obtained new client token: AAClqjL5dGpVwdeCQJDkchTdOf+DKax+G1bGEohtZaBxFETrjYtyQml8vfrozDzeFuzYz+M1Uz11Ou1ViFIawA91vrySZxVQJYek6k+bt0HIBOub8p+eCHTqQq3RczEGvvSifPBXPTW0KFqXmz8X9o+o+6lhoZJmg7qFW5KSPAog6OF5T5DX5W/Qi5uE//Y44cDV4+qkdCLqt2FF5WCqcUFPv1cCxALZGQ68cnOq9vzQAS/An9YVPJPLqChCdw==" Jan 08 15:19:20 volumio go-librespot[4390]: time="2026-01-08T15:19:20+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 08 15:19:21 volumio go-librespot[4390]: time="2026-01-08T15:19:21+01:00" level=debug msg="completed keyexchange" Jan 08 15:19:21 volumio volumio[791]: info: CoreCommandRouter::volumioStop Jan 08 15:19:21 volumio volumio[791]: info: CoreStateMachine::stop Jan 08 15:19:21 volumio volumio[791]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 08 15:19:21 volumio volumio[791]: info: CoreStateMachine::stPlaybackTimer Jan 08 15:19:21 volumio volumio[791]: info: CoreStateMachine::updateTrackBlock Jan 08 15:19:21 volumio volumio[791]: info: CorePlayQueue::getTrackBlock Jan 08 15:19:21 volumio volumio[791]: info: CoreStateMachine::pushState Jan 08 15:19:21 volumio volumio[791]: info: CorePlayQueue::getTrack 0 Jan 08 15:19:21 volumio volumio[791]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 08 15:19:21 volumio volumio[791]: info: CoreCommandRouter::volumioPushState Jan 08 15:19:21 volumio volumio[791]: info: CoreStateMachine::serviceStop Jan 08 15:19:21 volumio volumio[791]: info: CorePlayQueue::getTrack 0 Jan 08 15:19:21 volumio volumio[791]: info: CoreCommandRouter::serviceStop Jan 08 15:19:21 volumio volumio[791]: info: [1767881961417] ControllerWebradio::stop Jan 08 15:19:21 volumio volumio[791]: verbose: ControllerMpd::sendMpdCommand stop Jan 08 15:19:21 volumio volumio[791]: info: PeppySpectrum ---peppyspectrum status stop Jan 08 15:19:21 volumio volumio[791]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 Jan 08 15:19:21 volumio sudo[4401]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jan 08 15:19:21 volumio sudo[4401]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 15:19:21 volumio sudo[4401]: pam_unix(sudo:session): session closed for user root Jan 08 15:19:21 volumio volumio[791]: info: peppyspectrum Daemon Stop Jan 08 15:19:21 volumio volumio[791]: info: sendMpdCommand stop took 61 milliseconds Jan 08 15:19:21 volumio volumio[791]: info: Jan 08 15:19:21 volumio volumio[791]: ---------------------------- MPD announces state update: player Jan 08 15:19:21 volumio volumio[791]: info: ControllerMpd::getState Jan 08 15:19:21 volumio volumio[791]: verbose: ControllerMpd::sendMpdCommand status Jan 08 15:19:21 volumio volumio[791]: info: Jan 08 15:19:21 volumio volumio[791]: ---------------------------- MPD announces state update: player Jan 08 15:19:21 volumio volumio[791]: info: ControllerMpd::getState Jan 08 15:19:21 volumio volumio[791]: verbose: ControllerMpd::sendMpdCommand status Jan 08 15:19:21 volumio volumio[791]: info: Jan 08 15:19:21 volumio volumio[791]: ---------------------------- MPD announces state update: player Jan 08 15:19:21 volumio volumio[791]: info: ControllerMpd::getState Jan 08 15:19:21 volumio volumio[791]: verbose: ControllerMpd::sendMpdCommand status Jan 08 15:19:21 volumio volumio[791]: info: Jan 08 15:19:21 volumio volumio[791]: ---------------------------- MPD announces state update: player Jan 08 15:19:21 volumio volumio[791]: info: ControllerMpd::getState Jan 08 15:19:21 volumio volumio[791]: verbose: ControllerMpd::sendMpdCommand status Jan 08 15:19:21 volumio volumio[791]: info: Jan 08 15:19:21 volumio volumio[791]: ---------------------------- MPD announces state update: player Jan 08 15:19:21 volumio volumio[791]: info: ControllerMpd::getState Jan 08 15:19:21 volumio volumio[791]: verbose: ControllerMpd::sendMpdCommand status Jan 08 15:19:21 volumio volumio[791]: info: sendMpdCommand status took 4 milliseconds Jan 08 15:19:21 volumio volumio[791]: info: sendMpdCommand status took 4 milliseconds Jan 08 15:19:21 volumio volumio[791]: info: sendMpdCommand status took 3 milliseconds Jan 08 15:19:21 volumio volumio[791]: info: sendMpdCommand status took 2 milliseconds Jan 08 15:19:21 volumio volumio[791]: info: sendMpdCommand status took 2 milliseconds Jan 08 15:19:21 volumio volumio[791]: verbose: ControllerMpd::parseState Jan 08 15:19:21 volumio volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 15:19:21 volumio volumio[791]: verbose: ControllerMpd::parseState Jan 08 15:19:21 volumio volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 15:19:21 volumio volumio[791]: verbose: ControllerMpd::parseState Jan 08 15:19:21 volumio volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 15:19:21 volumio volumio[791]: verbose: ControllerMpd::parseState Jan 08 15:19:21 volumio volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 15:19:21 volumio volumio[791]: verbose: ControllerMpd::parseState Jan 08 15:19:21 volumio volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 15:19:21 volumio volumio[791]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 08 15:19:21 volumio volumio[791]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 08 15:19:21 volumio volumio[791]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 08 15:19:21 volumio volumio[791]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 08 15:19:21 volumio volumio[791]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 08 15:19:21 volumio volumio[791]: verbose: ControllerMpd::parseTrackInfo Jan 08 15:19:21 volumio volumio[791]: verbose: ControllerMpd::parseTrackInfo Jan 08 15:19:21 volumio volumio[791]: verbose: ControllerMpd::parseTrackInfo Jan 08 15:19:21 volumio volumio[791]: verbose: ControllerMpd::parseTrackInfo Jan 08 15:19:21 volumio volumio[791]: verbose: ControllerMpd::parseTrackInfo Jan 08 15:19:21 volumio volumio[791]: info: ControllerMpd::pushState Jan 08 15:19:21 volumio volumio[791]: info: CoreCommandRouter::servicePushState Jan 08 15:19:21 volumio volumio[791]: info: CoreStateMachine::pushState Jan 08 15:19:21 volumio volumio[791]: info: CorePlayQueue::getTrack 0 Jan 08 15:19:21 volumio volumio[791]: info: CoreCommandRouter::volumioPushState Jan 08 15:19:21 volumio volumio[791]: info: CorePlayQueue::getTrack 0 Jan 08 15:19:21 volumio volumio[791]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 08 15:19:21 volumio volumio[791]: info: ControllerMpd::pushState Jan 08 15:19:21 volumio volumio[791]: info: CoreCommandRouter::servicePushState Jan 08 15:19:21 volumio volumio[791]: info: CoreStateMachine::pushState Jan 08 15:19:21 volumio volumio[791]: info: CorePlayQueue::getTrack 0 Jan 08 15:19:21 volumio volumio[791]: info: CoreCommandRouter::volumioPushState Jan 08 15:19:21 volumio volumio[791]: info: CorePlayQueue::getTrack 0 Jan 08 15:19:21 volumio volumio[791]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 08 15:19:21 volumio volumio[791]: info: ControllerMpd::pushState Jan 08 15:19:21 volumio volumio[791]: info: CoreCommandRouter::servicePushState Jan 08 15:19:21 volumio volumio[791]: info: CoreStateMachine::pushState Jan 08 15:19:21 volumio volumio[791]: info: CorePlayQueue::getTrack 0 Jan 08 15:19:21 volumio volumio[791]: info: CoreCommandRouter::volumioPushState Jan 08 15:19:21 volumio volumio[791]: info: CorePlayQueue::getTrack 0 Jan 08 15:19:21 volumio volumio[791]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 08 15:19:21 volumio volumio[791]: info: ControllerMpd::pushState Jan 08 15:19:21 volumio volumio[791]: info: CoreCommandRouter::servicePushState Jan 08 15:19:21 volumio volumio[791]: info: CoreStateMachine::pushState Jan 08 15:19:21 volumio volumio[791]: info: CorePlayQueue::getTrack 0 Jan 08 15:19:21 volumio volumio[791]: info: CoreCommandRouter::volumioPushState Jan 08 15:19:21 volumio volumio[791]: info: CorePlayQueue::getTrack 0 Jan 08 15:19:21 volumio volumio[791]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 08 15:19:21 volumio volumio[791]: info: ControllerMpd::pushState Jan 08 15:19:21 volumio volumio[791]: info: CoreCommandRouter::servicePushState Jan 08 15:19:21 volumio volumio[791]: info: CoreStateMachine::pushState Jan 08 15:19:21 volumio volumio[791]: info: CorePlayQueue::getTrack 0 Jan 08 15:19:21 volumio volumio[791]: info: CoreCommandRouter::volumioPushState Jan 08 15:19:21 volumio volumio[791]: info: CorePlayQueue::getTrack 0 Jan 08 15:19:21 volumio volumio[791]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 08 15:19:21 volumio volumio[791]: info: ------------------------------ 61ms Jan 08 15:19:21 volumio go-librespot[4390]: time="2026-01-08T15:19:21+01:00" level=debug msg="completed challenge" Jan 08 15:19:21 volumio volumio[791]: info: ------------------------------ 60ms Jan 08 15:19:21 volumio volumio[791]: info: ------------------------------ 59ms Jan 08 15:19:21 volumio volumio[791]: info: ------------------------------ 58ms Jan 08 15:19:21 volumio volumio[791]: info: PeppySpectrum ---peppyspectrum status stop Jan 08 15:19:21 volumio volumio[791]: info: PeppySpectrum ---peppyspectrum status stop Jan 08 15:19:21 volumio go-librespot[4390]: time="2026-01-08T15:19:21+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 08 15:19:21 volumio sudo[4406]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jan 08 15:19:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 08 15:19:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 08 15:19:21 volumio sudo[4406]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 15:19:21 volumio volumio[791]: info: PeppySpectrum ---peppyspectrum status stop Jan 08 15:19:21 volumio sudo[4409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jan 08 15:19:21 volumio sudo[4409]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 15:19:21 volumio sudo[4406]: pam_unix(sudo:session): session closed for user root Jan 08 15:19:21 volumio volumio[791]: info: PeppySpectrum ---peppyspectrum status stop Jan 08 15:19:21 volumio sudo[4412]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jan 08 15:19:21 volumio sudo[4409]: pam_unix(sudo:session): session closed for user root Jan 08 15:19:21 volumio sudo[4412]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 15:19:21 volumio volumio[791]: info: PeppySpectrum ---peppyspectrum status stop Jan 08 15:19:21 volumio sudo[4412]: pam_unix(sudo:session): session closed for user root Jan 08 15:19:21 volumio sudo[4415]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jan 08 15:19:21 volumio sudo[4415]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 15:19:21 volumio volumio[791]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 Jan 08 15:19:21 volumio volumio[791]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 Jan 08 15:19:21 volumio volumio[791]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 Jan 08 15:19:21 volumio volumio[791]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 Jan 08 15:19:21 volumio volumio[791]: info: peppyspectrum Daemon Stop Jan 08 15:19:21 volumio volumio[791]: info: peppyspectrum Daemon Stop Jan 08 15:19:21 volumio volumio[791]: info: peppyspectrum Daemon Stop Jan 08 15:19:21 volumio sudo[4418]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jan 08 15:19:21 volumio sudo[4418]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 15:19:21 volumio sudo[4415]: pam_unix(sudo:session): session closed for user root Jan 08 15:19:21 volumio volumio[791]: info: peppyspectrum Daemon Stop Jan 08 15:19:21 volumio sudo[4418]: pam_unix(sudo:session): session closed for user root Jan 08 15:19:21 volumio volumio[791]: info: peppyspectrum Daemon Stop Jan 08 15:19:22 volumio volumio[791]: info: Initializing connection to go-librespot Websocket Jan 08 15:19:22 volumio volumio[791]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 08 15:19:23 volumio volumio[791]: info: CoreCommandRouter::volumioPlay Jan 08 15:19:23 volumio volumio[791]: info: CoreStateMachine::play index undefined Jan 08 15:19:23 volumio volumio[791]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 08 15:19:23 volumio volumio[791]: info: CorePlayQueue::getTrack 0 Jan 08 15:19:23 volumio volumio[791]: info: CoreStateMachine::startPlaybackTimer Jan 08 15:19:23 volumio volumio[791]: info: CorePlayQueue::getTrack 0 Jan 08 15:19:23 volumio volumio[791]: info: [1767881963322] ControllerWebradio::clearAddPlayTrack Jan 08 15:19:23 volumio volumio[791]: verbose: ControllerMpd::sendMpdCommand stop Jan 08 15:19:23 volumio volumio[791]: info: sendMpdCommand stop took 1 milliseconds Jan 08 15:19:23 volumio volumio[791]: verbose: ControllerMpd::sendMpdCommand clear Jan 08 15:19:23 volumio volumio[791]: info: Jan 08 15:19:23 volumio volumio[791]: ---------------------------- MPD announces system playlist update Jan 08 15:19:23 volumio volumio[791]: info: Ignoring MPD Status Update Jan 08 15:19:23 volumio volumio[791]: info: sendMpdCommand clear took 1 milliseconds Jan 08 15:19:23 volumio volumio[791]: verbose: ControllerMpd::sendMpdCommand load "https://streams.80s80s.de/maxis/mp3-192/streams.80s80s.de/play.m3u" Jan 08 15:19:23 volumio volumio[791]: info: Jan 08 15:19:23 volumio volumio[791]: ---------------------------- MPD announces system playlist update Jan 08 15:19:23 volumio volumio[791]: info: Ignoring MPD Status Update Jan 08 15:19:23 volumio volumio[791]: info: Jan 08 15:19:23 volumio volumio[791]: ---------------------------- MPD announces system playlist update Jan 08 15:19:23 volumio volumio[791]: info: Ignoring MPD Status Update Jan 08 15:19:23 volumio volumio[791]: info: Jan 08 15:19:23 volumio volumio[791]: ---------------------------- MPD announces system playlist update Jan 08 15:19:23 volumio volumio[791]: info: Ignoring MPD Status Update Jan 08 15:19:23 volumio volumio[791]: info: Jan 08 15:19:23 volumio volumio[791]: ---------------------------- MPD announces system playlist update Jan 08 15:19:23 volumio volumio[791]: info: Ignoring MPD Status Update Jan 08 15:19:23 volumio volumio[791]: error: updateQueue error: null Jan 08 15:19:23 volumio volumio[791]: info: ------------------------------ 10ms Jan 08 15:19:24 volumio volumio[791]: info: Jan 08 15:19:24 volumio volumio[791]: ---------------------------- MPD announces system playlist update Jan 08 15:19:24 volumio volumio[791]: info: Ignoring MPD Status Update Jan 08 15:19:24 volumio volumio[791]: info: sendMpdCommand load "https://streams.80s80s.de/maxis/mp3-192/streams.80s80s.de/play.m3u" took 1187 milliseconds Jan 08 15:19:24 volumio volumio[791]: info: ------------------------------ 1186ms Jan 08 15:19:24 volumio volumio[791]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 08 15:19:24 volumio volumio[791]: verbose: ControllerMpd::sendMpdCommand play Jan 08 15:19:24 volumio volumio[791]: info: ------------------------------ 1188ms Jan 08 15:19:24 volumio volumio[791]: info: ------------------------------ 1184ms Jan 08 15:19:24 volumio volumio[791]: info: ------------------------------ 1183ms Jan 08 15:19:24 volumio volumio[791]: info: Jan 08 15:19:24 volumio volumio[791]: ---------------------------- MPD announces system playlist update Jan 08 15:19:24 volumio volumio[791]: info: Ignoring MPD Status Update Jan 08 15:19:24 volumio volumio[791]: info: Jan 08 15:19:24 volumio volumio[791]: ---------------------------- MPD announces system playlist update Jan 08 15:19:24 volumio volumio[791]: info: Ignoring MPD Status Update Jan 08 15:19:24 volumio volumio[791]: info: Jan 08 15:19:24 volumio volumio[791]: ---------------------------- MPD announces system playlist update Jan 08 15:19:24 volumio volumio[791]: info: Ignoring MPD Status Update Jan 08 15:19:24 volumio volumio[791]: info: Jan 08 15:19:24 volumio volumio[791]: ---------------------------- MPD announces system playlist update Jan 08 15:19:24 volumio volumio[791]: info: Ignoring MPD Status Update Jan 08 15:19:24 volumio volumio[791]: info: ------------------------------ 7ms Jan 08 15:19:24 volumio volumio[791]: info: sendMpdCommand play took 5 milliseconds Jan 08 15:19:24 volumio volumio[791]: info: ------------------------------ 4ms Jan 08 15:19:24 volumio volumio[791]: info: ------------------------------ 4ms Jan 08 15:19:24 volumio volumio[791]: info: ------------------------------ 3ms Jan 08 15:19:24 volumio volumio[791]: info: ------------------------------ 3ms Jan 08 15:19:24 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 08 15:19:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 109. Jan 08 15:19:24 volumio systemd[1]: Stopped go-librespot Daemon. Jan 08 15:19:24 volumio systemd[1]: Started go-librespot Daemon. Jan 08 15:19:24 volumio go-librespot[4424]: Librespot-go daemon starting... Jan 08 15:19:24 volumio go-librespot[4424]: time="2026-01-08T15:19:24+01:00" level=info msg="generated new device id: b990c5a29a8df885208792a4f8ec0d358a9b9699" Jan 08 15:19:24 volumio go-librespot[4424]: time="2026-01-08T15:19:24+01:00" level=debug msg="stored credentials found for 1150052341" Jan 08 15:19:24 volumio go-librespot[4424]: time="2026-01-08T15:19:24+01:00" level=debug msg="obtained new client token: AABy5x9a2AQj5gu1rJpUXBt342i+4V1fkBgIQOYNeO2W0HD7o3kg5GfkR+UFdb7AiwG863JOfi3HYXZckK/BT1POGGsN1GL8KIt8Tj2jF37sfmkRWl3bhhEQG99Sa/UWlzUg9lTUWn7XmTTtqpBQLCl4R6LnYC4m5aRvIxZfMGxw4TlzZsCx8pz23fUjwZgOt+e+wDz7OURDxc19o+k5FpiLVOEDfbFu3VTzK+1asJpD+Ctx0DZOuzpY6XUUNQ==" Jan 08 15:19:25 volumio go-librespot[4424]: time="2026-01-08T15:19:25+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 08 15:19:25 volumio go-librespot[4424]: time="2026-01-08T15:19:25+01:00" level=debug msg="completed keyexchange" Jan 08 15:19:25 volumio go-librespot[4424]: time="2026-01-08T15:19:25+01:00" level=debug msg="completed challenge" Jan 08 15:19:25 volumio go-librespot[4424]: time="2026-01-08T15:19:25+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 08 15:19:25 volumio volumio[791]: info: Initializing connection to go-librespot Websocket Jan 08 15:19:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 08 15:19:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 08 15:19:25 volumio volumio[791]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 08 15:19:26 volumio volumio[791]: info: Jan 08 15:19:26 volumio volumio[791]: ---------------------------- MPD announces state update: player Jan 08 15:19:26 volumio volumio[791]: info: ControllerMpd::getState Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::sendMpdCommand status Jan 08 15:19:26 volumio volumio[791]: info: Jan 08 15:19:26 volumio volumio[791]: ---------------------------- MPD announces state update: player Jan 08 15:19:26 volumio volumio[791]: info: ControllerMpd::getState Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::sendMpdCommand status Jan 08 15:19:26 volumio volumio[791]: info: Jan 08 15:19:26 volumio volumio[791]: ---------------------------- MPD announces state update: player Jan 08 15:19:26 volumio volumio[791]: info: ControllerMpd::getState Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::sendMpdCommand status Jan 08 15:19:26 volumio volumio[791]: info: Jan 08 15:19:26 volumio volumio[791]: ---------------------------- MPD announces state update: player Jan 08 15:19:26 volumio volumio[791]: info: ControllerMpd::getState Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::sendMpdCommand status Jan 08 15:19:26 volumio volumio[791]: info: Jan 08 15:19:26 volumio volumio[791]: ---------------------------- MPD announces state update: player Jan 08 15:19:26 volumio volumio[791]: info: ControllerMpd::getState Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::sendMpdCommand status Jan 08 15:19:26 volumio volumio[791]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jan 08 15:19:26 volumio volumio[791]: info: FusionDsp - ---- read samplerate from file: 44100 Jan 08 15:19:26 volumio volumio[791]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 08 15:19:26 volumio volumio[791]: info: FusionDsp - Loudness is ON true Jan 08 15:19:26 volumio volumio[791]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jan 08 15:19:26 volumio volumio[791]: info: FusionDsp - ---- read samplerate from file: 44100 Jan 08 15:19:26 volumio volumio[791]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 08 15:19:26 volumio volumio[791]: info: FusionDsp - Loudness is ON true Jan 08 15:19:26 volumio volumio[791]: info: Jan 08 15:19:26 volumio volumio[791]: ---------------------------- MPD announces state update: player Jan 08 15:19:26 volumio volumio[791]: info: Jan 08 15:19:26 volumio volumio[791]: ---------------------------- MPD announces system playlist update Jan 08 15:19:26 volumio volumio[791]: info: Ignoring MPD Status Update Jan 08 15:19:26 volumio volumio[791]: info: Jan 08 15:19:26 volumio volumio[791]: ---------------------------- MPD announces state update: player Jan 08 15:19:26 volumio volumio[791]: info: sendMpdCommand status took 33 milliseconds Jan 08 15:19:26 volumio volumio[791]: info: ControllerMpd::getState Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::sendMpdCommand status Jan 08 15:19:26 volumio volumio[791]: info: sendMpdCommand status took 33 milliseconds Jan 08 15:19:26 volumio volumio[791]: info: sendMpdCommand status took 32 milliseconds Jan 08 15:19:26 volumio volumio[791]: info: sendMpdCommand status took 31 milliseconds Jan 08 15:19:26 volumio volumio[791]: info: sendMpdCommand status took 30 milliseconds Jan 08 15:19:26 volumio volumio[791]: info: ControllerMpd::getState Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::sendMpdCommand status Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::parseState Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::parseState Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::parseState Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::parseState Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::parseState Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 15:19:26 volumio volumio[791]: info: Jan 08 15:19:26 volumio volumio[791]: ---------------------------- MPD announces state update: player Jan 08 15:19:26 volumio volumio[791]: info: ControllerMpd::getState Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::sendMpdCommand status Jan 08 15:19:26 volumio volumio[791]: info: Jan 08 15:19:26 volumio volumio[791]: ---------------------------- MPD announces state update: player Jan 08 15:19:26 volumio volumio[791]: info: ControllerMpd::getState Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::sendMpdCommand status Jan 08 15:19:26 volumio volumio[791]: info: Jan 08 15:19:26 volumio volumio[791]: ---------------------------- MPD announces state update: player Jan 08 15:19:26 volumio volumio[791]: info: ControllerMpd::getState Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::sendMpdCommand status Jan 08 15:19:26 volumio volumio[791]: info: Jan 08 15:19:26 volumio volumio[791]: ---------------------------- MPD announces state update: player Jan 08 15:19:26 volumio volumio[791]: info: ControllerMpd::getState Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::sendMpdCommand status Jan 08 15:19:26 volumio volumio[791]: info: sendMpdCommand status took 8 milliseconds Jan 08 15:19:26 volumio volumio[791]: info: ------------------------------ 9ms Jan 08 15:19:26 volumio volumio[791]: info: sendMpdCommand status took 7 milliseconds Jan 08 15:19:26 volumio volumio[791]: info: sendMpdCommand playlistinfo took 7 milliseconds Jan 08 15:19:26 volumio volumio[791]: info: sendMpdCommand playlistinfo took 7 milliseconds Jan 08 15:19:26 volumio volumio[791]: info: sendMpdCommand playlistinfo took 7 milliseconds Jan 08 15:19:26 volumio volumio[791]: info: sendMpdCommand playlistinfo took 7 milliseconds Jan 08 15:19:26 volumio volumio[791]: info: sendMpdCommand status took 5 milliseconds Jan 08 15:19:26 volumio volumio[791]: info: sendMpdCommand status took 5 milliseconds Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::parseState Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::parseState Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::parseTrackInfo Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::parseState Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::parseState Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::parseState Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 15:19:26 volumio volumio[791]: info: ControllerMpd::pushState Jan 08 15:19:26 volumio volumio[791]: info: CoreCommandRouter::servicePushState Jan 08 15:19:26 volumio volumio[791]: info: CorePlayQueue::getTrack 0 Jan 08 15:19:26 volumio volumio[791]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"80s80s MAXIS","artist":"80s80s MAXIS","album":null,"uri":"https://regiocast.streamabc.net/regc-80s80smaxis2114493-mp3-192-5359027?sABC=695soprp%230%23r526pr7pr2sn8659976s9sonq811soo7%23fgernzf.80f80f.qr&aw_0_1st.playerid=streams.80s80s.de&amsparams=playerid:streams.80s80s.de;skey:1767881964","trackType":""} Jan 08 15:19:26 volumio volumio[791]: verbose: CURRENT POSITION 0 Jan 08 15:19:26 volumio volumio[791]: info: CoreStateMachine::syncState stateService play Jan 08 15:19:26 volumio volumio[791]: info: CoreStateMachine::syncState currentStatus stop Jan 08 15:19:26 volumio volumio[791]: info: ControllerMpd::pushState Jan 08 15:19:26 volumio volumio[791]: info: CoreCommandRouter::servicePushState Jan 08 15:19:26 volumio volumio[791]: info: CorePlayQueue::getTrack 0 Jan 08 15:19:26 volumio volumio[791]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"80s80s MAXIS","artist":"80s80s MAXIS","album":null,"uri":"https://regiocast.streamabc.net/regc-80s80smaxis2114493-mp3-192-5359027?sABC=695soprp%230%23r526pr7pr2sn8659976s9sonq811soo7%23fgernzf.80f80f.qr&aw_0_1st.playerid=streams.80s80s.de&amsparams=playerid:streams.80s80s.de;skey:1767881964","trackType":""} Jan 08 15:19:26 volumio volumio[791]: verbose: CURRENT POSITION 0 Jan 08 15:19:26 volumio volumio[791]: info: CoreStateMachine::syncState stateService play Jan 08 15:19:26 volumio volumio[791]: info: CoreStateMachine::syncState currentStatus play Jan 08 15:19:26 volumio volumio[791]: info: Received an update from plugin. extracting info from payload Jan 08 15:19:26 volumio volumio[791]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 08 15:19:26 volumio volumio[791]: info: CoreStateMachine::pushState Jan 08 15:19:26 volumio volumio[791]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 08 15:19:26 volumio volumio[791]: info: CoreCommandRouter::volumioPushState Jan 08 15:19:26 volumio volumio[791]: info: CoreStateMachine::pushState Jan 08 15:19:26 volumio volumio[791]: info: CoreCommandRouter::volumioPushState Jan 08 15:19:26 volumio volumio[791]: info: ControllerMpd::pushState Jan 08 15:19:26 volumio volumio[791]: info: CoreCommandRouter::servicePushState Jan 08 15:19:26 volumio volumio[791]: info: CorePlayQueue::getTrack 0 Jan 08 15:19:26 volumio volumio[791]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"80s80s MAXIS","artist":"80s80s MAXIS","album":null,"uri":"https://regiocast.streamabc.net/regc-80s80smaxis2114493-mp3-192-5359027?sABC=695soprp%230%23r526pr7pr2sn8659976s9sonq811soo7%23fgernzf.80f80f.qr&aw_0_1st.playerid=streams.80s80s.de&amsparams=playerid:streams.80s80s.de;skey:1767881964","trackType":""} Jan 08 15:19:26 volumio volumio[791]: verbose: CURRENT POSITION 0 Jan 08 15:19:26 volumio volumio[791]: info: CoreStateMachine::syncState stateService play Jan 08 15:19:26 volumio volumio[791]: info: CoreStateMachine::syncState currentStatus play Jan 08 15:19:26 volumio volumio[791]: info: Received an update from plugin. extracting info from payload Jan 08 15:19:26 volumio volumio[791]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 08 15:19:26 volumio volumio[791]: info: CoreStateMachine::pushState Jan 08 15:19:26 volumio volumio[791]: info: CoreCommandRouter::volumioPushState Jan 08 15:19:26 volumio volumio[791]: info: CoreStateMachine::pushState Jan 08 15:19:26 volumio volumio[791]: info: CoreCommandRouter::volumioPushState Jan 08 15:19:26 volumio volumio[791]: info: ControllerMpd::pushState Jan 08 15:19:26 volumio volumio[791]: info: CoreCommandRouter::servicePushState Jan 08 15:19:26 volumio volumio[791]: info: CorePlayQueue::getTrack 0 Jan 08 15:19:26 volumio volumio[791]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"80s80s MAXIS","artist":"80s80s MAXIS","album":null,"uri":"https://regiocast.streamabc.net/regc-80s80smaxis2114493-mp3-192-5359027?sABC=695soprp%230%23r526pr7pr2sn8659976s9sonq811soo7%23fgernzf.80f80f.qr&aw_0_1st.playerid=streams.80s80s.de&amsparams=playerid:streams.80s80s.de;skey:1767881964","trackType":""} Jan 08 15:19:26 volumio volumio[791]: verbose: CURRENT POSITION 0 Jan 08 15:19:26 volumio volumio[791]: info: CoreStateMachine::syncState stateService play Jan 08 15:19:26 volumio volumio[791]: info: CoreStateMachine::syncState currentStatus play Jan 08 15:19:26 volumio volumio[791]: info: Received an update from plugin. extracting info from payload Jan 08 15:19:26 volumio volumio[791]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 08 15:19:26 volumio volumio[791]: info: CoreStateMachine::pushState Jan 08 15:19:26 volumio volumio[791]: info: CoreCommandRouter::volumioPushState Jan 08 15:19:26 volumio volumio[791]: info: CoreStateMachine::pushState Jan 08 15:19:26 volumio volumio[791]: info: CoreCommandRouter::volumioPushState Jan 08 15:19:26 volumio volumio[791]: info: ControllerMpd::pushState Jan 08 15:19:26 volumio volumio[791]: info: CoreCommandRouter::servicePushState Jan 08 15:19:26 volumio volumio[791]: info: CorePlayQueue::getTrack 0 Jan 08 15:19:26 volumio volumio[791]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"80s80s MAXIS","artist":"80s80s MAXIS","album":null,"uri":"https://regiocast.streamabc.net/regc-80s80smaxis2114493-mp3-192-5359027?sABC=695soprp%230%23r526pr7pr2sn8659976s9sonq811soo7%23fgernzf.80f80f.qr&aw_0_1st.playerid=streams.80s80s.de&amsparams=playerid:streams.80s80s.de;skey:1767881964","trackType":""} Jan 08 15:19:26 volumio volumio[791]: verbose: CURRENT POSITION 0 Jan 08 15:19:26 volumio volumio[791]: info: CoreStateMachine::syncState stateService play Jan 08 15:19:26 volumio volumio[791]: info: CoreStateMachine::syncState currentStatus play Jan 08 15:19:26 volumio volumio[791]: info: Received an update from plugin. extracting info from payload Jan 08 15:19:26 volumio volumio[791]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 08 15:19:26 volumio volumio[791]: info: CoreStateMachine::pushState Jan 08 15:19:26 volumio volumio[791]: info: CoreCommandRouter::volumioPushState Jan 08 15:19:26 volumio volumio[791]: info: CoreStateMachine::pushState Jan 08 15:19:26 volumio volumio[791]: info: CoreCommandRouter::volumioPushState Jan 08 15:19:26 volumio volumio[791]: info: ------------------------------ 70ms Jan 08 15:19:26 volumio volumio[791]: info: ------------------------------ 112ms Jan 08 15:19:26 volumio volumio[791]: info: ------------------------------ 112ms Jan 08 15:19:26 volumio volumio[791]: info: ------------------------------ 111ms Jan 08 15:19:26 volumio volumio[791]: info: ------------------------------ 111ms Jan 08 15:19:26 volumio volumio[791]: info: Jan 08 15:19:26 volumio volumio[791]: ---------------------------- MPD announces system playlist update Jan 08 15:19:26 volumio volumio[791]: info: Ignoring MPD Status Update Jan 08 15:19:26 volumio volumio[791]: info: Jan 08 15:19:26 volumio volumio[791]: ---------------------------- MPD announces state update: player Jan 08 15:19:26 volumio volumio[791]: info: ControllerMpd::getState Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::sendMpdCommand status Jan 08 15:19:26 volumio volumio[791]: info: Jan 08 15:19:26 volumio volumio[791]: ---------------------------- MPD announces system playlist update Jan 08 15:19:26 volumio volumio[791]: info: Ignoring MPD Status Update Jan 08 15:19:26 volumio volumio[791]: info: Jan 08 15:19:26 volumio volumio[791]: ---------------------------- MPD announces state update: player Jan 08 15:19:26 volumio volumio[791]: info: ControllerMpd::getState Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::sendMpdCommand status Jan 08 15:19:26 volumio volumio[791]: info: Jan 08 15:19:26 volumio volumio[791]: ---------------------------- MPD announces system playlist update Jan 08 15:19:26 volumio volumio[791]: info: Ignoring MPD Status Update Jan 08 15:19:26 volumio volumio[791]: info: Jan 08 15:19:26 volumio volumio[791]: ---------------------------- MPD announces state update: player Jan 08 15:19:26 volumio volumio[791]: info: ControllerMpd::getState Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::sendMpdCommand status Jan 08 15:19:26 volumio volumio[791]: info: Jan 08 15:19:26 volumio volumio[791]: ---------------------------- MPD announces system playlist update Jan 08 15:19:26 volumio volumio[791]: info: Ignoring MPD Status Update Jan 08 15:19:26 volumio volumio[791]: info: Jan 08 15:19:26 volumio volumio[791]: ---------------------------- MPD announces state update: player Jan 08 15:19:26 volumio volumio[791]: info: ControllerMpd::getState Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::sendMpdCommand status Jan 08 15:19:26 volumio volumio[791]: error: FusionDsp - WebSocket error: [object Object] Jan 08 15:19:26 volumio volumio[791]: error: FusionDsp - WebSocket error: [object Object] Jan 08 15:19:26 volumio volumio[791]: info: sendMpdCommand status took 82 milliseconds Jan 08 15:19:26 volumio volumio[791]: info: sendMpdCommand playlistinfo took 78 milliseconds Jan 08 15:19:26 volumio volumio[791]: info: sendMpdCommand playlistinfo took 77 milliseconds Jan 08 15:19:26 volumio volumio[791]: info: sendMpdCommand playlistinfo took 76 milliseconds Jan 08 15:19:26 volumio volumio[791]: info: sendMpdCommand playlistinfo took 77 milliseconds Jan 08 15:19:26 volumio volumio[791]: info: sendMpdCommand playlistinfo took 77 milliseconds Jan 08 15:19:26 volumio volumio[791]: info: ------------------------------ 9ms Jan 08 15:19:26 volumio volumio[791]: info: sendMpdCommand status took 8 milliseconds Jan 08 15:19:26 volumio volumio[791]: info: ------------------------------ 7ms Jan 08 15:19:26 volumio volumio[791]: info: sendMpdCommand status took 7 milliseconds Jan 08 15:19:26 volumio volumio[791]: info: ------------------------------ 6ms Jan 08 15:19:26 volumio volumio[791]: info: sendMpdCommand status took 7 milliseconds Jan 08 15:19:26 volumio volumio[791]: info: ------------------------------ 6ms Jan 08 15:19:26 volumio volumio[791]: info: sendMpdCommand status took 6 milliseconds Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::parseState Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::parseTrackInfo Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::parseTrackInfo Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::parseTrackInfo Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::parseTrackInfo Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::parseTrackInfo Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::parseState Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::parseState Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::parseState Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::parseState Jan 08 15:19:26 volumio volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 15:19:26 volumio volumio[791]: info: ControllerMpd::pushState Jan 08 15:19:26 volumio volumio[791]: info: CoreCommandRouter::servicePushState Jan 08 15:19:26 volumio volumio[791]: info: CorePlayQueue::getTrack 0 Jan 08 15:19:26 volumio volumio[791]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"80s80s MAXIS","artist":"80s80s MAXIS","album":null,"uri":"https://regiocast.streamabc.net/regc-80s80smaxis2114493-mp3-192-5359027?sABC=695soprp%230%23r526pr7pr2sn8659976s9sonq811soo7%23fgernzf.80f80f.qr&aw_0_1st.playerid=streams.80s80s.de&amsparams=playerid:streams.80s80s.de;skey:1767881964","trackType":""} Jan 08 15:19:26 volumio volumio[791]: verbose: CURRENT POSITION 0 Jan 08 15:19:26 volumio volumio[791]: info: CoreStateMachine::syncState stateService play Jan 08 15:19:26 volumio volumio[791]: info: CoreStateMachine::syncState currentStatus play Jan 08 15:19:26 volumio volumio[791]: info: Received an update from plugin. extracting info from payload Jan 08 15:19:26 volumio volumio[791]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 08 15:19:26 volumio volumio[791]: info: CoreStateMachine::pushState Jan 08 15:19:26 volumio volumio[791]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 08 15:19:26 volumio volumio[791]: info: CoreCommandRouter::volumioPushState Jan 08 15:19:26 volumio volumio[791]: info: CoreStateMachine::pushState Jan 08 15:19:26 volumio volumio[791]: info: CoreCommandRouter::volumioPushState Jan 08 15:19:26 volumio volumio[791]: info: ControllerMpd::pushState Jan 08 15:19:26 volumio volumio[791]: info: CoreCommandRouter::servicePushState Jan 08 15:19:26 volumio volumio[791]: info: CorePlayQueue::getTrack 0 Jan 08 15:19:26 volumio volumio[791]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"80s80s MAXIS","artist":"80s80s MAXIS","album":null,"uri":"https://regiocast.streamabc.net/regc-80s80smaxis2114493-mp3-192-5359027?sABC=695soprp%230%23r526pr7pr2sn8659976s9sonq811soo7%23fgernzf.80f80f.qr&aw_0_1st.playerid=streams.80s80s.de&amsparams=playerid:streams.80s80s.de;skey:1767881964","trackType":""} Jan 08 15:19:26 volumio volumio[791]: verbose: CURRENT POSITION 0 Jan 08 15:19:26 volumio volumio[791]: info: CoreStateMachine::syncState stateService play Jan 08 15:19:26 volumio volumio[791]: info: CoreStateMachine::syncState currentStatus play Jan 08 15:19:26 volumio volumio[791]: info: Received an update from plugin. extracting info from payload Jan 08 15:19:26 volumio volumio[791]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 08 15:19:26 volumio volumio[791]: info: CoreStateMachine::pushState Jan 08 15:19:26 volumio volumio[791]: info: CoreCommandRouter::volumioPushState Jan 08 15:19:26 volumio volumio[791]: info: CoreStateMachine::pushState Jan 08 15:19:26 volumio volumio[791]: info: CoreCommandRouter::volumioPushState Jan 08 15:19:26 volumio volumio[791]: info: ControllerMpd::pushState Jan 08 15:19:26 volumio volumio[791]: info: CoreCommandRouter::servicePushState Jan 08 15:19:26 volumio volumio[791]: info: CorePlayQueue::getTrack 0 Jan 08 15:19:26 volumio volumio[791]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"80s80s MAXIS","artist":"80s80s MAXIS","album":null,"uri":"https://regiocast.streamabc.net/regc-80s80smaxis2114493-mp3-192-5359027?sABC=695soprp%230%23r526pr7pr2sn8659976s9sonq811soo7%23fgernzf.80f80f.qr&aw_0_1st.playerid=streams.80s80s.de&amsparams=playerid:streams.80s80s.de;skey:1767881964","trackType":""} Jan 08 15:19:26 volumio volumio[791]: verbose: CURRENT POSITION 0 Jan 08 15:19:26 volumio volumio[791]: info: CoreStateMachine::syncState stateService play Jan 08 15:19:26 volumio volumio[791]: info: CoreStateMachine::syncState currentStatus play Jan 08 15:19:26 volumio volumio[791]: info: Received an update from plugin. extracting info from payload Jan 08 15:19:26 volumio volumio[791]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 08 15:19:26 volumio volumio[791]: info: CoreStateMachine::pushState Jan 08 15:19:26 volumio volumio[791]: info: CoreCommandRouter::volumioPushState Jan 08 15:19:26 volumio volumio[791]: info: CoreStateMachine::pushState Jan 08 15:19:26 volumio volumio[791]: info: CoreCommandRouter::volumioPushState Jan 08 15:19:26 volumio volumio[791]: info: ControllerMpd::pushState Jan 08 15:19:26 volumio volumio[791]: info: CoreCommandRouter::servicePushState Jan 08 15:19:26 volumio volumio[791]: info: CorePlayQueue::getTrack 0 Jan 08 15:19:26 volumio volumio[791]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"80s80s MAXIS","artist":"80s80s MAXIS","album":null,"uri":"https://regiocast.streamabc.net/regc-80s80smaxis2114493-mp3-192-5359027?sABC=695soprp%230%23r526pr7pr2sn8659976s9sonq811soo7%23fgernzf.80f80f.qr&aw_0_1st.playerid=streams.80s80s.de&amsparams=playerid:streams.80s80s.de;skey:1767881964","trackType":""} Jan 08 15:19:26 volumio volumio[791]: verbose: CURRENT POSITION 0 Jan 08 15:19:26 volumio volumio[791]: info: CoreStateMachine::syncState stateService play Jan 08 15:19:26 volumio volumio[791]: info: CoreStateMachine::syncState currentStatus play Jan 08 15:19:26 volumio volumio[791]: info: Received an update from plugin. extracting info from payload Jan 08 15:19:26 volumio volumio[791]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 08 15:19:26 volumio volumio[791]: info: CoreStateMachine::pushState Jan 08 15:19:26 volumio volumio[791]: info: CoreCommandRouter::volumioPushState Jan 08 15:19:26 volumio volumio[791]: info: CoreStateMachine::pushState Jan 08 15:19:26 volumio volumio[791]: info: CoreCommandRouter::volumioPushState Jan 08 15:19:26 volumio volumio[791]: info: ControllerMpd::pushState Jan 08 15:19:26 volumio volumio[791]: info: CoreCommandRouter::servicePushState Jan 08 15:19:26 volumio volumio[791]: info: CorePlayQueue::getTrack 0 Jan 08 15:19:26 volumio volumio[791]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"80s80s MAXIS","artist":"80s80s MAXIS","album":null,"uri":"https://regiocast.streamabc.net/regc-80s80smaxis2114493-mp3-192-5359027?sABC=695soprp%230%23r526pr7pr2sn8659976s9sonq811soo7%23fgernzf.80f80f.qr&aw_0_1st.playerid=streams.80s80s.de&amsparams=playerid:streams.80s80s.de;skey:1767881964","trackType":""} Jan 08 15:19:26 volumio volumio[791]: verbose: CURRENT POSITION 0 Jan 08 15:19:26 volumio volumio[791]: info: CoreStateMachine::syncState stateService play Jan 08 15:19:26 volumio volumio[791]: info: CoreStateMachine::syncState currentStatus play Jan 08 15:19:26 volumio volumio[791]: info: Received an update from plugin. extracting info from payload Jan 08 15:19:26 volumio volumio[791]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 08 15:19:26 volumio volumio[791]: info: CoreStateMachine::pushState Jan 08 15:19:26 volumio volumio[791]: info: CoreCommandRouter::volumioPushState Jan 08 15:19:26 volumio volumio[791]: info: CoreStateMachine::pushState Jan 08 15:19:26 volumio volumio[791]: info: CoreCommandRouter::volumioPushState Jan 08 15:19:26 volumio volumio[791]: info: ------------------------------ 160ms Jan 08 15:19:26 volumio volumio[791]: info: ------------------------------ 159ms Jan 08 15:19:26 volumio volumio[791]: info: ------------------------------ 156ms Jan 08 15:19:26 volumio volumio[791]: info: ------------------------------ 155ms Jan 08 15:19:26 volumio volumio[791]: info: ------------------------------ 155ms Jan 08 15:19:26 volumio volumio[791]: info: PeppySpectrum ---peppyspectrum status play Jan 08 15:19:26 volumio volumio[791]: info: PeppySpectrum ---peppyspectrum status play Jan 08 15:19:26 volumio sudo[4439]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jan 08 15:19:26 volumio sudo[4439]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 15:19:26 volumio volumio[791]: info: PeppySpectrum ---peppyspectrum status play Jan 08 15:19:26 volumio sudo[4442]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jan 08 15:19:26 volumio sudo[4442]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 15:19:26 volumio sudo[4446]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jan 08 15:19:26 volumio volumio[791]: info: PeppySpectrum ---peppyspectrum status play Jan 08 15:19:26 volumio sudo[4446]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 15:19:26 volumio systemd[1]: Started peppyspectrum Daemon. Jan 08 15:19:26 volumio sudo[4449]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jan 08 15:19:26 volumio sudo[4449]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 15:19:26 volumio sudo[4452]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jan 08 15:19:26 volumio sudo[4452]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 15:19:26 volumio sudo[4455]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jan 08 15:19:26 volumio sudo[4455]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 15:19:26 volumio volumio[791]: info: PeppySpectrum ---peppyspectrum status play Jan 08 15:19:26 volumio volumio[791]: info: PeppySpectrum ---peppyspectrum status play Jan 08 15:19:26 volumio sudo[4439]: pam_unix(sudo:session): session closed for user root Jan 08 15:19:26 volumio sudo[4458]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jan 08 15:19:26 volumio sudo[4458]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 15:19:26 volumio sudo[4461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jan 08 15:19:26 volumio sudo[4461]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 15:19:26 volumio sudo[4452]: pam_unix(sudo:session): session closed for user root Jan 08 15:19:26 volumio sudo[4455]: pam_unix(sudo:session): session closed for user root Jan 08 15:19:26 volumio sudo[4446]: pam_unix(sudo:session): session closed for user root Jan 08 15:19:26 volumio volumio[791]: info: PeppySpectrum ---peppyspectrum status play Jan 08 15:19:26 volumio sudo[4449]: pam_unix(sudo:session): session closed for user root Jan 08 15:19:26 volumio sudo[4442]: pam_unix(sudo:session): session closed for user root Jan 08 15:19:26 volumio volumio[791]: info: PeppySpectrum ---peppyspectrum status play Jan 08 15:19:26 volumio sudo[4465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jan 08 15:19:26 volumio sudo[4465]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 15:19:26 volumio sudo[4458]: pam_unix(sudo:session): session closed for user root Jan 08 15:19:26 volumio sudo[4467]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jan 08 15:19:26 volumio sudo[4467]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 15:19:26 volumio sudo[4461]: pam_unix(sudo:session): session closed for user root Jan 08 15:19:26 volumio sudo[4465]: pam_unix(sudo:session): session closed for user root Jan 08 15:19:26 volumio volumio[791]: info: PeppySpectrum ---peppyspectrum status play Jan 08 15:19:26 volumio sudo[4467]: pam_unix(sudo:session): session closed for user root Jan 08 15:19:26 volumio sudo[4471]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jan 08 15:19:26 volumio sudo[4471]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 15:19:26 volumio volumio[791]: info: PeppySpectrum ---peppyspectrum status play Jan 08 15:19:26 volumio sudo[4471]: pam_unix(sudo:session): session closed for user root Jan 08 15:19:26 volumio sudo[4474]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jan 08 15:19:26 volumio sudo[4474]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 15:19:26 volumio volumio[791]: info: PeppySpectrum ---peppyspectrum status play Jan 08 15:19:26 volumio volumio[791]: info: PeppySpectrum ---peppyspectrum status play Jan 08 15:19:26 volumio sudo[4477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jan 08 15:19:26 volumio sudo[4477]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 15:19:26 volumio sudo[4474]: pam_unix(sudo:session): session closed for user root Jan 08 15:19:26 volumio sudo[4479]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jan 08 15:19:26 volumio sudo[4479]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 15:19:26 volumio volumio[791]: info: PeppySpectrum ---peppyspectrum status play Jan 08 15:19:26 volumio sudo[4483]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jan 08 15:19:26 volumio sudo[4477]: pam_unix(sudo:session): session closed for user root Jan 08 15:19:26 volumio sudo[4483]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 15:19:26 volumio volumio[791]: info: PeppySpectrum ---peppyspectrum status play Jan 08 15:19:26 volumio sudo[4479]: pam_unix(sudo:session): session closed for user root Jan 08 15:19:26 volumio volumio[791]: info: PeppySpectrum ---peppyspectrum status play Jan 08 15:19:26 volumio sudo[4483]: pam_unix(sudo:session): session closed for user root Jan 08 15:19:26 volumio sudo[4486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jan 08 15:19:26 volumio sudo[4488]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jan 08 15:19:26 volumio sudo[4488]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 15:19:26 volumio sudo[4486]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 15:19:26 volumio sudo[4488]: pam_unix(sudo:session): session closed for user root Jan 08 15:19:26 volumio sudo[4486]: pam_unix(sudo:session): session closed for user root Jan 08 15:19:26 volumio sudo[4492]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jan 08 15:19:26 volumio sudo[4492]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 15:19:26 volumio volumio[791]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 Jan 08 15:19:26 volumio volumio[791]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 Jan 08 15:19:26 volumio volumio[791]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 Jan 08 15:19:26 volumio volumio[791]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 Jan 08 15:19:26 volumio volumio[791]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 Jan 08 15:19:26 volumio volumio[791]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 Jan 08 15:19:26 volumio volumio[791]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 Jan 08 15:19:26 volumio volumio[791]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 Jan 08 15:19:26 volumio volumio[791]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 Jan 08 15:19:26 volumio volumio[791]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 Jan 08 15:19:26 volumio volumio[791]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 Jan 08 15:19:26 volumio volumio[791]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 Jan 08 15:19:26 volumio volumio[791]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 Jan 08 15:19:26 volumio sudo[4492]: pam_unix(sudo:session): session closed for user root Jan 08 15:19:26 volumio volumio[791]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 Jan 08 15:19:26 volumio volumio[791]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 Jan 08 15:19:26 volumio volumio[791]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 Jan 08 15:19:26 volumio volumio[791]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 Jan 08 15:19:26 volumio volumio[791]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 Jan 08 15:19:27 volumio volumio[791]: info: sendMpdCommand playlistinfo took 552 milliseconds Jan 08 15:19:27 volumio volumio[791]: info: sendMpdCommand playlistinfo took 552 milliseconds Jan 08 15:19:27 volumio volumio[791]: info: sendMpdCommand playlistinfo took 552 milliseconds Jan 08 15:19:27 volumio volumio[791]: info: sendMpdCommand playlistinfo took 551 milliseconds Jan 08 15:19:27 volumio volumio[791]: info: sendMpdCommand playlistinfo took 552 milliseconds Jan 08 15:19:27 volumio volumio[791]: verbose: ControllerMpd::parseTrackInfo Jan 08 15:19:27 volumio volumio[791]: verbose: ControllerMpd::parseTrackInfo Jan 08 15:19:27 volumio volumio[791]: verbose: ControllerMpd::parseTrackInfo Jan 08 15:19:27 volumio volumio[791]: verbose: ControllerMpd::parseTrackInfo Jan 08 15:19:27 volumio volumio[791]: verbose: ControllerMpd::parseTrackInfo Jan 08 15:19:27 volumio volumio[791]: info: ControllerMpd::pushState Jan 08 15:19:27 volumio volumio[791]: info: CoreCommandRouter::servicePushState Jan 08 15:19:27 volumio volumio[791]: info: CorePlayQueue::getTrack 0 Jan 08 15:19:27 volumio volumio[791]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"80s80s MAXIS","artist":"80s80s MAXIS","album":null,"uri":"https://regiocast.streamabc.net/regc-80s80smaxis2114493-mp3-192-5359027?sABC=695soprp%230%23r526pr7pr2sn8659976s9sonq811soo7%23fgernzf.80f80f.qr&aw_0_1st.playerid=streams.80s80s.de&amsparams=playerid:streams.80s80s.de;skey:1767881964","trackType":""} Jan 08 15:19:27 volumio volumio[791]: verbose: CURRENT POSITION 0 Jan 08 15:19:27 volumio volumio[791]: info: CoreStateMachine::syncState stateService play Jan 08 15:19:27 volumio volumio[791]: info: CoreStateMachine::syncState currentStatus play Jan 08 15:19:27 volumio volumio[791]: info: Received an update from plugin. extracting info from payload Jan 08 15:19:27 volumio volumio[791]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 08 15:19:27 volumio volumio[791]: info: CoreStateMachine::pushState Jan 08 15:19:27 volumio volumio[791]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 08 15:19:27 volumio volumio[791]: info: CoreCommandRouter::volumioPushState Jan 08 15:19:27 volumio volumio[791]: info: CoreStateMachine::pushState Jan 08 15:19:27 volumio volumio[791]: info: CoreCommandRouter::volumioPushState Jan 08 15:19:27 volumio volumio[791]: info: ControllerMpd::pushState Jan 08 15:19:27 volumio volumio[791]: info: CoreCommandRouter::servicePushState Jan 08 15:19:27 volumio volumio[791]: info: CorePlayQueue::getTrack 0 Jan 08 15:19:27 volumio volumio[791]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"80s80s MAXIS","artist":"80s80s MAXIS","album":null,"uri":"https://regiocast.streamabc.net/regc-80s80smaxis2114493-mp3-192-5359027?sABC=695soprp%230%23r526pr7pr2sn8659976s9sonq811soo7%23fgernzf.80f80f.qr&aw_0_1st.playerid=streams.80s80s.de&amsparams=playerid:streams.80s80s.de;skey:1767881964","trackType":""} Jan 08 15:19:27 volumio volumio[791]: verbose: CURRENT POSITION 0 Jan 08 15:19:27 volumio volumio[791]: info: CoreStateMachine::syncState stateService play Jan 08 15:19:27 volumio volumio[791]: info: CoreStateMachine::syncState currentStatus play Jan 08 15:19:27 volumio volumio[791]: info: Received an update from plugin. extracting info from payload Jan 08 15:19:27 volumio volumio[791]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 08 15:19:27 volumio volumio[791]: info: CoreStateMachine::pushState Jan 08 15:19:27 volumio volumio[791]: info: CoreCommandRouter::volumioPushState Jan 08 15:19:27 volumio volumio[791]: info: CoreStateMachine::pushState Jan 08 15:19:27 volumio volumio[791]: info: CoreCommandRouter::volumioPushState Jan 08 15:19:27 volumio volumio[791]: info: ControllerMpd::pushState Jan 08 15:19:27 volumio volumio[791]: info: CoreCommandRouter::servicePushState Jan 08 15:19:27 volumio volumio[791]: info: CorePlayQueue::getTrack 0 Jan 08 15:19:27 volumio volumio[791]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"80s80s MAXIS","artist":"80s80s MAXIS","album":null,"uri":"https://regiocast.streamabc.net/regc-80s80smaxis2114493-mp3-192-5359027?sABC=695soprp%230%23r526pr7pr2sn8659976s9sonq811soo7%23fgernzf.80f80f.qr&aw_0_1st.playerid=streams.80s80s.de&amsparams=playerid:streams.80s80s.de;skey:1767881964","trackType":""} Jan 08 15:19:27 volumio volumio[791]: verbose: CURRENT POSITION 0 Jan 08 15:19:27 volumio volumio[791]: info: CoreStateMachine::syncState stateService play Jan 08 15:19:27 volumio volumio[791]: info: CoreStateMachine::syncState currentStatus play Jan 08 15:19:27 volumio volumio[791]: info: Received an update from plugin. extracting info from payload Jan 08 15:19:27 volumio volumio[791]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 08 15:19:27 volumio volumio[791]: info: CoreStateMachine::pushState Jan 08 15:19:27 volumio volumio[791]: info: CoreCommandRouter::volumioPushState Jan 08 15:19:27 volumio volumio[791]: info: CoreStateMachine::pushState Jan 08 15:19:27 volumio volumio[791]: info: CoreCommandRouter::volumioPushState Jan 08 15:19:27 volumio volumio[791]: info: ControllerMpd::pushState Jan 08 15:19:27 volumio volumio[791]: info: CoreCommandRouter::servicePushState Jan 08 15:19:27 volumio volumio[791]: info: CorePlayQueue::getTrack 0 Jan 08 15:19:27 volumio volumio[791]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"80s80s MAXIS","artist":"80s80s MAXIS","album":null,"uri":"https://regiocast.streamabc.net/regc-80s80smaxis2114493-mp3-192-5359027?sABC=695soprp%230%23r526pr7pr2sn8659976s9sonq811soo7%23fgernzf.80f80f.qr&aw_0_1st.playerid=streams.80s80s.de&amsparams=playerid:streams.80s80s.de;skey:1767881964","trackType":""} Jan 08 15:19:27 volumio volumio[791]: verbose: CURRENT POSITION 0 Jan 08 15:19:27 volumio volumio[791]: info: CoreStateMachine::syncState stateService play Jan 08 15:19:27 volumio volumio[791]: info: CoreStateMachine::syncState currentStatus play Jan 08 15:19:27 volumio volumio[791]: info: Received an update from plugin. extracting info from payload Jan 08 15:19:27 volumio volumio[791]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 08 15:19:27 volumio volumio[791]: info: CoreStateMachine::pushState Jan 08 15:19:27 volumio volumio[791]: info: CoreCommandRouter::volumioPushState Jan 08 15:19:27 volumio volumio[791]: info: CoreStateMachine::pushState Jan 08 15:19:27 volumio volumio[791]: info: CoreCommandRouter::volumioPushState Jan 08 15:19:27 volumio volumio[791]: info: ControllerMpd::pushState Jan 08 15:19:27 volumio volumio[791]: info: CoreCommandRouter::servicePushState Jan 08 15:19:27 volumio volumio[791]: info: CorePlayQueue::getTrack 0 Jan 08 15:19:27 volumio volumio[791]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"80s80s MAXIS","artist":"80s80s MAXIS","album":null,"uri":"https://regiocast.streamabc.net/regc-80s80smaxis2114493-mp3-192-5359027?sABC=695soprp%230%23r526pr7pr2sn8659976s9sonq811soo7%23fgernzf.80f80f.qr&aw_0_1st.playerid=streams.80s80s.de&amsparams=playerid:streams.80s80s.de;skey:1767881964","trackType":""} Jan 08 15:19:27 volumio volumio[791]: verbose: CURRENT POSITION 0 Jan 08 15:19:27 volumio volumio[791]: info: CoreStateMachine::syncState stateService play Jan 08 15:19:27 volumio volumio[791]: info: CoreStateMachine::syncState currentStatus play Jan 08 15:19:27 volumio volumio[791]: info: Received an update from plugin. extracting info from payload Jan 08 15:19:27 volumio volumio[791]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 08 15:19:27 volumio volumio[791]: info: CoreStateMachine::pushState Jan 08 15:19:27 volumio volumio[791]: info: CoreCommandRouter::volumioPushState Jan 08 15:19:27 volumio volumio[791]: info: CoreStateMachine::pushState Jan 08 15:19:27 volumio volumio[791]: info: CoreCommandRouter::volumioPushState Jan 08 15:19:27 volumio volumio[791]: info: ------------------------------ 727ms Jan 08 15:19:27 volumio volumio[791]: info: ------------------------------ 653ms Jan 08 15:19:27 volumio volumio[791]: info: ------------------------------ 653ms Jan 08 15:19:27 volumio volumio[791]: info: ------------------------------ 653ms Jan 08 15:19:27 volumio volumio[791]: info: ------------------------------ 653ms Jan 08 15:19:27 volumio volumio[791]: info: peppyspectrum Daemon Started Jan 08 15:19:27 volumio volumio[791]: info: peppyspectrum Daemon Started Jan 08 15:19:27 volumio volumio[791]: info: peppyspectrum Daemon Started Jan 08 15:19:27 volumio volumio[791]: info: peppyspectrum Daemon Started Jan 08 15:19:27 volumio volumio[791]: info: peppyspectrum Daemon Started Jan 08 15:19:27 volumio volumio[791]: info: peppyspectrum Daemon Started Jan 08 15:19:27 volumio volumio[791]: info: peppyspectrum Daemon Started Jan 08 15:19:27 volumio volumio[791]: info: peppyspectrum Daemon Started Jan 08 15:19:27 volumio volumio[791]: info: peppyspectrum Daemon Started Jan 08 15:19:27 volumio volumio[791]: info: peppyspectrum Daemon Started Jan 08 15:19:27 volumio volumio[791]: info: peppyspectrum Daemon Started Jan 08 15:19:27 volumio volumio[791]: info: peppyspectrum Daemon Started Jan 08 15:19:27 volumio volumio[791]: info: peppyspectrum Daemon Started Jan 08 15:19:27 volumio volumio[791]: info: peppyspectrum Daemon Started Jan 08 15:19:27 volumio volumio[791]: info: peppyspectrum Daemon Started Jan 08 15:19:27 volumio volumio[791]: info: peppyspectrum Daemon Started Jan 08 15:19:27 volumio volumio[791]: info: peppyspectrum Daemon Started Jan 08 15:19:27 volumio volumio[791]: info: peppyspectrum Daemon Started Jan 08 15:19:27 volumio volumio[791]: info: PeppySpectrum ---peppyspectrum status play Jan 08 15:19:27 volumio volumio[791]: info: PeppySpectrum ---peppyspectrum status play Jan 08 15:19:27 volumio sudo[4495]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jan 08 15:19:27 volumio sudo[4495]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 15:19:27 volumio volumio[791]: info: PeppySpectrum ---peppyspectrum status play Jan 08 15:19:27 volumio sudo[4497]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jan 08 15:19:27 volumio sudo[4497]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 15:19:27 volumio volumio[791]: info: PeppySpectrum ---peppyspectrum status play Jan 08 15:19:27 volumio sudo[4495]: pam_unix(sudo:session): session closed for user root Jan 08 15:19:27 volumio sudo[4500]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jan 08 15:19:27 volumio sudo[4500]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 15:19:27 volumio sudo[4497]: pam_unix(sudo:session): session closed for user root Jan 08 15:19:27 volumio volumio[791]: info: PeppySpectrum ---peppyspectrum status play Jan 08 15:19:27 volumio volumio[4445]: pygame 1.9.4.post1 Jan 08 15:19:27 volumio volumio[4445]: Hello from the pygame community. https://www.pygame.org/contribute.html Jan 08 15:19:27 volumio volumio[4445]: Traceback (most recent call last): Jan 08 15:19:27 volumio volumio[4445]: File "spectrum.py", line 30, in Jan 08 15:19:27 volumio volumio[4445]: from spectrumutil import SpectrumUtil Jan 08 15:19:27 volumio volumio[4445]: File "/data/plugins/user_interface/peppyspectrum/PeppySpectrum/spectrumutil.py", line 19, in Jan 08 15:19:27 volumio volumio[4445]: from PIL import Image Jan 08 15:19:27 volumio volumio[4445]: ModuleNotFoundError: No module named 'PIL' Jan 08 15:19:27 volumio sudo[4500]: pam_unix(sudo:session): session closed for user root Jan 08 15:19:27 volumio sudo[4504]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jan 08 15:19:27 volumio sudo[4504]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 15:19:27 volumio volumio[791]: info: PeppySpectrum ---peppyspectrum status play Jan 08 15:19:27 volumio volumio[791]: info: PeppySpectrum ---peppyspectrum status play Jan 08 15:19:27 volumio sudo[4507]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jan 08 15:19:27 volumio sudo[4507]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 15:19:27 volumio sudo[4504]: pam_unix(sudo:session): session closed for user root Jan 08 15:19:27 volumio volumio[791]: info: PeppySpectrum ---peppyspectrum status play Jan 08 15:19:27 volumio sudo[4509]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jan 08 15:19:27 volumio sudo[4509]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 15:19:27 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE Jan 08 15:19:27 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Jan 08 15:19:27 volumio volumio[791]: info: PeppySpectrum ---peppyspectrum status play Jan 08 15:19:27 volumio sudo[4513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jan 08 15:19:27 volumio sudo[4513]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 15:19:27 volumio volumio[791]: info: PeppySpectrum ---peppyspectrum status play Jan 08 15:19:27 volumio sudo[4515]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jan 08 15:19:27 volumio sudo[4515]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 15:19:27 volumio volumio[791]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 Jan 08 15:19:27 volumio systemd[1]: Started peppyspectrum Daemon. Jan 08 15:19:27 volumio sudo[4519]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jan 08 15:19:27 volumio sudo[4519]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 15:19:27 volumio sudo[4523]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jan 08 15:19:27 volumio sudo[4523]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 15:19:27 volumio volumio[791]: info: peppyspectrum Daemon Started Jan 08 15:19:27 volumio sudo[4507]: pam_unix(sudo:session): session closed for user root Jan 08 15:19:27 volumio volumio[791]: info: peppyspectrum Daemon Started Jan 08 15:19:27 volumio volumio[791]: info: peppyspectrum Daemon Started Jan 08 15:19:27 volumio volumio[791]: info: peppyspectrum Daemon Started Jan 08 15:19:27 volumio sudo[4509]: pam_unix(sudo:session): session closed for user root Jan 08 15:19:27 volumio sudo[4515]: pam_unix(sudo:session): session closed for user root Jan 08 15:19:27 volumio sudo[4513]: pam_unix(sudo:session): session closed for user root Jan 08 15:19:27 volumio sudo[4519]: pam_unix(sudo:session): session closed for user root Jan 08 15:19:27 volumio sudo[4523]: pam_unix(sudo:session): session closed for user root Jan 08 15:19:27 volumio volumio[791]: info: peppyspectrum Daemon Started Jan 08 15:19:27 volumio volumio[791]: info: peppyspectrum Daemon Started Jan 08 15:19:27 volumio volumio[791]: info: peppyspectrum Daemon Started Jan 08 15:19:27 volumio volumio[791]: info: peppyspectrum Daemon Started Jan 08 15:19:27 volumio volumio[791]: info: peppyspectrum Daemon Started Jan 08 15:19:27 volumio volumio[4517]: pygame 1.9.4.post1 Jan 08 15:19:27 volumio volumio[4517]: Hello from the pygame community. https://www.pygame.org/contribute.html Jan 08 15:19:27 volumio volumio[4517]: Traceback (most recent call last): Jan 08 15:19:27 volumio volumio[4517]: File "spectrum.py", line 30, in Jan 08 15:19:27 volumio volumio[4517]: from spectrumutil import SpectrumUtil Jan 08 15:19:27 volumio volumio[4517]: File "/data/plugins/user_interface/peppyspectrum/PeppySpectrum/spectrumutil.py", line 19, in Jan 08 15:19:27 volumio volumio[4517]: from PIL import Image Jan 08 15:19:27 volumio volumio[4517]: ModuleNotFoundError: No module named 'PIL' Jan 08 15:19:27 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE Jan 08 15:19:27 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Jan 08 15:19:28 volumio volumio[791]: info: Initializing connection to go-librespot Websocket Jan 08 15:19:28 volumio volumio[791]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 08 15:19:28 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 08 15:19:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 110. Jan 08 15:19:28 volumio systemd[1]: Stopped go-librespot Daemon. Jan 08 15:19:28 volumio systemd[1]: Started go-librespot Daemon. Jan 08 15:19:28 volumio go-librespot[4532]: Librespot-go daemon starting... Jan 08 15:19:28 volumio go-librespot[4532]: time="2026-01-08T15:19:28+01:00" level=info msg="generated new device id: fc1f7b03295e39879b183ee52521c7997a5ca27a" Jan 08 15:19:28 volumio go-librespot[4532]: time="2026-01-08T15:19:28+01:00" level=debug msg="stored credentials found for 1150052341" Jan 08 15:19:28 volumio go-librespot[4532]: time="2026-01-08T15:19:28+01:00" level=debug msg="obtained new client token: AAAt01Y6lwBYSTcHZXcWXIS8oDka300B0ELDl0dHEPamRp8+5WO94QnFtX4eaSOvQ0hQoXgq0EMh+W3cq+8X6J5uUOXHzeqSZtJtyv46grsFU/QEp5B8fnOC2tVMRpLnIxEW5ouALQdxUmiqPQxpPlIwS18Vw+QLTRvjP+Xbw4xMnvmjQj4CgN9IciGbsjuNUUdfDvUm/msDTsgTh638jxl22fjL3g4+vLrjmY0eKa3IKCVZ/hvjgzOIMVKuGA==" Jan 08 15:19:28 volumio go-librespot[4532]: time="2026-01-08T15:19:28+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 08 15:19:29 volumio go-librespot[4532]: time="2026-01-08T15:19:29+01:00" level=debug msg="completed keyexchange" Jan 08 15:19:29 volumio go-librespot[4532]: time="2026-01-08T15:19:29+01:00" level=debug msg="completed challenge" Jan 08 15:19:29 volumio go-librespot[4532]: time="2026-01-08T15:19:29+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 08 15:19:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 08 15:19:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 08 15:19:31 volumio volumio[791]: info: Initializing connection to go-librespot Websocket Jan 08 15:19:31 volumio volumio[791]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 08 15:19:32 volumio sudo[4566]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 08 15:19:32 volumio sudo[4566]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 15:19:32 volumio sudo[4566]: pam_unix(sudo:session): session closed for user root Jan 08 15:19:32 volumio sudo[4569]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 08 15:19:32 volumio sudo[4569]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 15:19:32 volumio sudo[4569]: pam_unix(sudo:session): session closed for user root Jan 08 15:19:32 volumio volumio[791]: verbose: New Socket.io Connection to 192.168.1.55 from 192.168.1.176 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Edg/143.0.0.0 Engine version: 3 Transport: polling Total Clients: 7 Jan 08 15:19:32 volumio volumio[791]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 08 15:19:32 volumio volumio[791]: info: CoreCommandRouter::volumioGetVisibleSources Jan 08 15:19:32 volumio volumio[791]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 08 15:19:32 volumio volumio[791]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 08 15:19:32 volumio volumio[791]: info: CoreCommandRouter::volumioGetState Jan 08 15:19:32 volumio volumio[791]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 08 15:19:32 volumio volumio[791]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 08 15:19:32 volumio volumio[791]: info: Received Get System Info Jan 08 15:19:32 volumio volumio[791]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 08 15:19:32 volumio volumio[791]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 08 15:19:32 volumio volumio[791]: info: Discovery: Getting this device information Jan 08 15:19:32 volumio volumio[791]: info: CoreCommandRouter::volumioGetState Jan 08 15:19:32 volumio volumio[791]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 08 15:19:32 volumio volumio[791]: info: CoreCommandRouter::volumioGetState Jan 08 15:19:32 volumio volumio[791]: info: Listing playlists Jan 08 15:19:32 volumio volumio[791]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 08 15:19:32 volumio volumio[791]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 08 15:19:32 volumio volumio[791]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 08 15:19:32 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 08 15:19:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 111. Jan 08 15:19:32 volumio systemd[1]: Stopped go-librespot Daemon. Jan 08 15:19:32 volumio volumio[791]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 15:19:32 volumio volumio[791]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 15:19:32 volumio systemd[1]: Started go-librespot Daemon. Jan 08 15:19:32 volumio go-librespot[4571]: Librespot-go daemon starting... Jan 08 15:19:32 volumio go-librespot[4571]: time="2026-01-08T15:19:32+01:00" level=info msg="generated new device id: 31d0ac7165c914937ae6128d237b95e927f02fdd" Jan 08 15:19:32 volumio go-librespot[4571]: time="2026-01-08T15:19:32+01:00" level=debug msg="stored credentials found for 1150052341" Jan 08 15:19:32 volumio volumio[791]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 08 15:19:32 volumio go-librespot[4571]: time="2026-01-08T15:19:32+01:00" level=debug msg="obtained new client token: AAB9uKurqofUAnetDHup/BBQr55zIu9rVbDUwEyjYRGBogynjYC/bAzp/9ifdLRb85fU/m7FMq6Cykd33kjUyVHap968jVyI4YhriH2BboK8mPhkzlofwdone8NYlScurlH5/FlDmtV7IBTh+sbb72Kf0zZjefauRro471sKXDuJYtG71i/hU/E6NX7nenksrytV/5Y8k3ZBPWp2ImvRuvy9aGlKB9YKA26tPQU2VYWblUgmBLvC+kzZYzj9Mg==" Jan 08 15:19:33 volumio go-librespot[4571]: time="2026-01-08T15:19:33+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 08 15:19:33 volumio go-librespot[4571]: time="2026-01-08T15:19:33+01:00" level=debug msg="completed keyexchange" Jan 08 15:19:33 volumio go-librespot[4571]: time="2026-01-08T15:19:33+01:00" level=debug msg="completed challenge" Jan 08 15:19:33 volumio go-librespot[4571]: time="2026-01-08T15:19:33+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 08 15:19:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 08 15:19:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 08 15:19:34 volumio volumio[791]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 08 15:19:34 volumio volumio[791]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 08 15:19:34 volumio volumio[791]: info: Received Get System Info Jan 08 15:19:34 volumio volumio[791]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 08 15:19:34 volumio volumio[791]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 08 15:19:34 volumio volumio[791]: info: Discovery: Getting this device information Jan 08 15:19:34 volumio volumio[791]: info: CoreCommandRouter::volumioGetState Jan 08 15:19:34 volumio volumio[791]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 08 15:19:34 volumio volumio[791]: info: Initializing connection to go-librespot Websocket Jan 08 15:19:34 volumio volumio[791]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 08 15:19:35 volumio volumio[791]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 08 15:19:35 volumio volumio[791]: info: Received Get System Info Jan 08 15:19:35 volumio volumio[791]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 08 15:19:35 volumio volumio[791]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 08 15:19:35 volumio volumio[791]: info: Discovery: Getting this device information Jan 08 15:19:35 volumio volumio[791]: info: CoreCommandRouter::volumioGetState Jan 08 15:19:35 volumio volumio[791]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 08 15:19:36 volumio volumio[791]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf Jan 08 15:19:36 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 08 15:19:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 112. Jan 08 15:19:36 volumio systemd[1]: Stopped go-librespot Daemon. Jan 08 15:19:36 volumio systemd[1]: Started go-librespot Daemon. Jan 08 15:19:36 volumio go-librespot[4589]: Librespot-go daemon starting... Jan 08 15:19:36 volumio go-librespot[4589]: time="2026-01-08T15:19:36+01:00" level=info msg="generated new device id: 769483378363578c511b93b03c5abc3898d3b48b" Jan 08 15:19:36 volumio go-librespot[4589]: time="2026-01-08T15:19:36+01:00" level=debug msg="stored credentials found for 1150052341" Jan 08 15:19:36 volumio go-librespot[4589]: time="2026-01-08T15:19:36+01:00" level=debug msg="obtained new client token: AAD6Y1XBznIx/aSdWAhpMOSYN/ur0zRAfUQOQPf/uwWS2S6H42JvNYdPN/RhHYNf7snfO/2JtCorbddMpxK8vlUlAgymC7YH5Sq1g3/2v83vyR1ysNLgYmE+GPnJDhJQ17xFXSbtc8W+b7kDqUeF2L4NbQusdBVmdbp7l++8ud47VOCF/fhp+2ED3XOw7rfKtkqKsWjTQEbOjOdBKqb6V/AqGSU7oC9h8G6eWYh7dz5fMMe+bXTltksKGn1DtQ==" Jan 08 15:19:36 volumio go-librespot[4589]: time="2026-01-08T15:19:36+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 08 15:19:37 volumio go-librespot[4589]: time="2026-01-08T15:19:37+01:00" level=debug msg="completed keyexchange" Jan 08 15:19:37 volumio go-librespot[4589]: time="2026-01-08T15:19:37+01:00" level=debug msg="completed challenge" Jan 08 15:19:37 volumio go-librespot[4589]: time="2026-01-08T15:19:37+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 08 15:19:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 08 15:19:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 08 15:19:37 volumio volumio[791]: info: Initializing connection to go-librespot Websocket Jan 08 15:19:37 volumio volumio[791]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 08 15:19:37 volumio volumio[791]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf Jan 08 15:19:37 volumio volumio[791]: info: FusionDsp - Stopping FusionDsp service Jan 08 15:19:37 volumio volumio[791]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 08 15:19:37 volumio volumio[791]: TypeError: Cannot read property 'stop' of null Jan 08 15:19:37 volumio volumio[791]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:109:23) Jan 08 15:19:37 volumio volumio[791]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Jan 08 15:19:37 volumio volumio[791]: at /volumio/app/pluginmanager.js:956:12 Jan 08 15:19:37 volumio volumio[791]: at ChildProcess.exithandler (child_process.js:299:7) Jan 08 15:19:37 volumio volumio[791]: at ChildProcess.emit (events.js:315:20) Jan 08 15:19:37 volumio volumio[791]: at maybeClose (internal/child_process.js:1048:16) Jan 08 15:19:37 volumio volumio[791]: at Process.ChildProcess._handle.onexit (internal/child_process.js:288:5) Jan 08 15:19:37 volumio volumio[791]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 08 15:19:38 volumio sudo[4608]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-08 15:18 Jan 08 15:19:38 volumio sudo[4608]: 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"