Sep 14 12:42:00 muzyka systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 14 12:42:00 muzyka systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 14 12:42:00 muzyka sudo[2316]: pam_unix(sudo:session): session closed for user root Sep 14 12:42:00 muzyka volumio[1193]: info: Install script completed Sep 14 12:42:00 muzyka volumio[1193]: info: Adding reference to registry Sep 14 12:42:00 muzyka volumio[1193]: info: Done installing plugin. Sep 14 12:42:00 muzyka volumio[1193]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 14 12:42:00 muzyka volumio[1193]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 14 12:42:00 muzyka volumio[1193]: info: Folder /tmp/plugins removed Sep 14 12:42:00 muzyka volumio[1193]: info: Folder /tmp/downloaded_plugin.zip removed Sep 14 12:42:00 muzyka volumio[1193]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 12:42:00 muzyka volumio[1193]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 12:42:00 muzyka volumio[1193]: Plugin install end detected on script Sep 14 12:42:00 muzyka volumio[1193]: info: Folder /data/temp removed Sep 14 12:42:01 muzyka volumio[1193]: info: Enabling plugin spop Sep 14 12:42:01 muzyka volumio[1193]: info: Loading plugin "spop"... Sep 14 12:42:03 muzyka volumio[1193]: info: PLUGIN START: spop Sep 14 12:42:03 muzyka volumio[1193]: info: Creating Spotify config file Sep 14 12:42:03 muzyka volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:42:03 muzyka volumio[1193]: info: Done. Sep 14 12:42:03 muzyka volumio[1193]: info: Spotify config file written Sep 14 12:42:03 muzyka volumio[1193]: info: No need to fix Spotify hosts Sep 14 12:42:03 muzyka sudo[2399]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 14 12:42:03 muzyka sudo[2399]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:42:03 muzyka systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 14 12:42:03 muzyka systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 14 12:42:03 muzyka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 14 12:42:03 muzyka go-librespot[2401]: go-librespot daemon starting... Sep 14 12:42:03 muzyka sudo[2399]: pam_unix(sudo:session): session closed for user root Sep 14 12:42:03 muzyka go-librespot[2402]: time="2025-09-14T12:42:03+02:00" level=info msg="running go-librespot 0.3.2" Sep 14 12:42:03 muzyka go-librespot[2402]: time="2025-09-14T12:42:03+02:00" level=debug msg="no app state found" Sep 14 12:42:03 muzyka go-librespot[2402]: time="2025-09-14T12:42:03+02:00" level=debug msg="stored credentials not found" Sep 14 12:42:03 muzyka go-librespot[2402]: time="2025-09-14T12:42:03+02:00" level=info msg="generated new device id: b542fb97aeefd6872f8fff41fa57ccc02521d581" Sep 14 12:42:03 muzyka go-librespot[2402]: time="2025-09-14T12:42:03+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 14 12:42:03 muzyka go-librespot[2402]: time="2025-09-14T12:42:03+02: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]" Sep 14 12:42:03 muzyka go-librespot[2402]: time="2025-09-14T12:42:03+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 14 12:42:03 muzyka go-librespot[2402]: time="2025-09-14T12:42:03+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 14 12:42:03 muzyka go-librespot[2402]: time="2025-09-14T12:42:03+02:00" level=info msg="zeroconf server listening on port 37191" Sep 14 12:42:03 muzyka go-librespot[2402]: time="2025-09-14T12:42:03+02:00" level=debug msg="obtained new client token: AABJZkJHgwZo2jjHaENuyOOXhlYksSzxIq6ZFZSZyXZpjjXdmiJXIrCoSSobhbT8MzL7WCv1gOE+EkxNcH7jHX5ZsukZsTAtVEmEIBvRCIkvfnUxeqihEYA1U4uxVF6iSeuQm5HmttfXNojUla9g87etMXoDht686kJvyixEztxzy/fQk6+ZBmLKr30UOtdlaSxyOaJKdOYbWPdcZGA/UROsU1ND2Nb3zYYhKPByKIC9uDj4nQOO" Sep 14 12:42:03 muzyka volumio[1193]: info: An error occurred while refreshing Spotify Token Error: Bad Request Sep 14 12:42:04 muzyka go-librespot[2402]: time="2025-09-14T12:42:04+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 14 12:42:04 muzyka go-librespot[2402]: time="2025-09-14T12:42:04+02:00" level=debug msg="completed keyexchange" Sep 14 12:42:04 muzyka go-librespot[2402]: time="2025-09-14T12:42:04+02:00" level=debug msg="completed challenge" Sep 14 12:42:04 muzyka volumio[1193]: info: CoreCommandRouter::volumioGetState Sep 14 12:42:04 muzyka volumio[1193]: info: CorePlayQueue::getTrack 7 Sep 14 12:42:04 muzyka go-librespot[2402]: time="2025-09-14T12:42:04+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Sep 14 12:42:04 muzyka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 14 12:42:04 muzyka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 14 12:42:05 muzyka volumio[1193]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 12:42:05 muzyka volumio[1193]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 12:42:05 muzyka volumio[1193]: info: Retrieving Cloud Streaming UI Sep 14 12:42:05 muzyka volumio[1193]: info: Getting Tidal Cloud Configuration Sep 14 12:42:05 muzyka volumio[1193]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 12:42:05 muzyka volumio[1193]: info: Getting Qobuz Cloud Configuration Sep 14 12:42:05 muzyka volumio[1193]: info: Asking plugin for UI Config Sep 14 12:42:05 muzyka volumio[1193]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 12:42:05 muzyka volumio[1193]: info: Getting Spotify Cloud Configuration Sep 14 12:42:05 muzyka volumio[1193]: info: Asking plugin for UI Config Sep 14 12:42:05 muzyka volumio[1193]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 12:42:05 muzyka volumio[1193]: info: Saving Spotify Acccount Sep 14 12:42:05 muzyka volumio[1193]: info: Got it Sep 14 12:42:05 muzyka volumio[1193]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Sep 14 12:42:05 muzyka volumio[1193]: info: Got Tidal Cloud Configuration Sep 14 12:42:05 muzyka volumio[1193]: info: Got it Sep 14 12:42:05 muzyka volumio[1193]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 12:42:05 muzyka volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 14 12:42:05 muzyka volumio[1193]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 14 12:42:05 muzyka volumio[1193]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 14 12:42:05 muzyka volumio[1193]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 14 12:42:05 muzyka volumio[1193]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 12:42:05 muzyka volumio[1193]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 12:42:05 muzyka volumio[1193]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 12:42:05 muzyka volumio[1193]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 12:42:05 muzyka volumio[1193]: info: CoreCommandRouter::volumioGetBrowseSources Sep 14 12:42:05 muzyka volumio[1193]: info: CoreCommandRouter::volumioGetBrowseSources Sep 14 12:42:05 muzyka volumio[1193]: info: CoreCommandRouter::volumioGetBrowseSources Sep 14 12:42:05 muzyka volumio[1193]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 12:42:05 muzyka volumio[1193]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Sep 14 12:42:06 muzyka volumio[1193]: info: Executing endpoint metavolumio Sep 14 12:42:06 muzyka volumio[1193]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 14 12:42:06 muzyka volumio[1193]: info: Executing endpoint metavolumio Sep 14 12:42:06 muzyka volumio[1193]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 14 12:42:06 muzyka volumio[1193]: info: Executing endpoint metavolumio Sep 14 12:42:06 muzyka volumio[1193]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 14 12:42:06 muzyka volumio[1193]: info: go-librespot daemon successfully initialized Sep 14 12:42:07 muzyka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Sep 14 12:42:07 muzyka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 14 12:42:07 muzyka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 14 12:42:07 muzyka go-librespot[2410]: go-librespot daemon starting... Sep 14 12:42:07 muzyka go-librespot[2411]: time="2025-09-14T12:42:07+02:00" level=info msg="running go-librespot 0.3.2" Sep 14 12:42:07 muzyka go-librespot[2411]: time="2025-09-14T12:42:07+02:00" level=debug msg="app state loaded" Sep 14 12:42:07 muzyka go-librespot[2411]: time="2025-09-14T12:42:07+02:00" level=debug msg="stored credentials not found" Sep 14 12:42:07 muzyka go-librespot[2411]: time="2025-09-14T12:42:07+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 14 12:42:07 muzyka go-librespot[2411]: time="2025-09-14T12:42:07+02: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]" Sep 14 12:42:07 muzyka go-librespot[2411]: time="2025-09-14T12:42:07+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 14 12:42:07 muzyka go-librespot[2411]: time="2025-09-14T12:42:07+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 14 12:42:07 muzyka go-librespot[2411]: time="2025-09-14T12:42:07+02:00" level=info msg="zeroconf server listening on port 45941" Sep 14 12:42:08 muzyka go-librespot[2411]: time="2025-09-14T12:42:08+02:00" level=debug msg="obtained new client token: AACL69htlyJyS/qeQ7U/yEedF9ZOLD8FKwY/iPK2v1Bsq6tl3gocf1WtAqJdU9eVhE6J41XSZDorpiaQCuq+eO0NtBWAPxp3oU+gKPX6glcUlXH4cklycjx496K/vUsRVBqy32P7GAC6dKYAxiht8RwE1/Zin0R7+PY8fErRPaZFi1G00cwGfpCm2LHYLJybFxb5o848GFBb5VOgoIeZqdjsOmnmtXVRC58cSLeWCYVj07pRpQk+" Sep 14 12:42:08 muzyka go-librespot[2411]: time="2025-09-14T12:42:08+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 14 12:42:08 muzyka go-librespot[2411]: time="2025-09-14T12:42:08+02:00" level=debug msg="completed keyexchange" Sep 14 12:42:08 muzyka go-librespot[2411]: time="2025-09-14T12:42:08+02:00" level=debug msg="completed challenge" Sep 14 12:42:08 muzyka go-librespot[2411]: time="2025-09-14T12:42:08+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Sep 14 12:42:08 muzyka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 14 12:42:08 muzyka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 14 12:42:09 muzyka volumio[1193]: info: Initializing connection to go-librespot Websocket Sep 14 12:42:09 muzyka volumio[1193]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 14 12:42:09 muzyka volumio[1193]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 12:42:11 muzyka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Sep 14 12:42:11 muzyka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 14 12:42:11 muzyka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 14 12:42:11 muzyka go-librespot[2419]: go-librespot daemon starting... Sep 14 12:42:11 muzyka go-librespot[2420]: time="2025-09-14T12:42:11+02:00" level=info msg="running go-librespot 0.3.2" Sep 14 12:42:11 muzyka go-librespot[2420]: time="2025-09-14T12:42:11+02:00" level=debug msg="app state loaded" Sep 14 12:42:11 muzyka go-librespot[2420]: time="2025-09-14T12:42:11+02:00" level=debug msg="stored credentials not found" Sep 14 12:42:11 muzyka go-librespot[2420]: time="2025-09-14T12:42:11+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 14 12:42:11 muzyka go-librespot[2420]: time="2025-09-14T12:42:11+02: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]" Sep 14 12:42:11 muzyka go-librespot[2420]: time="2025-09-14T12:42:11+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 14 12:42:11 muzyka go-librespot[2420]: time="2025-09-14T12:42:11+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 14 12:42:11 muzyka go-librespot[2420]: time="2025-09-14T12:42:11+02:00" level=info msg="zeroconf server listening on port 39855" Sep 14 12:42:11 muzyka go-librespot[2420]: time="2025-09-14T12:42:11+02:00" level=debug msg="obtained new client token: AABIxndvuYBcTwuUrzx3SgFyEBHaG7VbKwd40Vq+7PyQqZRws0Brn6b5Vf2n0cJwoPFJEyZUF2EJW29c5YrqIexi8rT1VM4GkiqTZwQlWYfhyIOrriuJyLx5JeNns/nwE6AqoAVTlim7lXvWplX70EG1dSRfJpN9zDsyuGfYjW5RHwbCzR8uV/050HsYwbmDkbo5eakqh8ivYBs47S/R5n92q6i9Lg3hUrAStfm5+V+Jnid/vqMF" Sep 14 12:42:12 muzyka go-librespot[2420]: time="2025-09-14T12:42:12+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 14 12:42:12 muzyka volumio[1193]: info: Initializing connection to go-librespot Websocket Sep 14 12:42:12 muzyka go-librespot[2420]: time="2025-09-14T12:42:12+02:00" level=debug msg="new websocket client" Sep 14 12:42:12 muzyka volumio[1193]: info: Connection to go-librespot Websocket established Sep 14 12:42:12 muzyka go-librespot[2420]: time="2025-09-14T12:42:12+02:00" level=debug msg="completed keyexchange" Sep 14 12:42:12 muzyka go-librespot[2420]: time="2025-09-14T12:42:12+02:00" level=debug msg="completed challenge" Sep 14 12:42:12 muzyka go-librespot[2420]: time="2025-09-14T12:42:12+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Sep 14 12:42:12 muzyka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 14 12:42:12 muzyka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 14 12:42:12 muzyka volumio[1193]: info: Connection to go-librespot Websocket closed Sep 14 12:42:13 muzyka volumio[1193]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 12:42:15 muzyka volumio[1193]: info: Getting Spotify volume Sep 14 12:42:15 muzyka volumio[1193]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 12:42:15 muzyka volumio[1193]: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 14 12:42:15 muzyka volumio[1193]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Sep 14 12:42:15 muzyka volumio[1193]: errno: -111, Sep 14 12:42:15 muzyka volumio[1193]: code: 'ECONNREFUSED', Sep 14 12:42:15 muzyka volumio[1193]: syscall: 'connect', Sep 14 12:42:15 muzyka volumio[1193]: address: '127.0.0.1', Sep 14 12:42:15 muzyka volumio[1193]: port: 9879, Sep 14 12:42:15 muzyka volumio[1193]: response: undefined Sep 14 12:42:15 muzyka volumio[1193]: } Sep 14 12:42:15 muzyka volumio[1193]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 12:42:15 muzyka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Sep 14 12:42:15 muzyka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 14 12:42:15 muzyka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 14 12:42:15 muzyka go-librespot[2452]: go-librespot daemon starting... Sep 14 12:42:15 muzyka go-librespot[2454]: time="2025-09-14T12:42:15+02:00" level=info msg="running go-librespot 0.3.2" Sep 14 12:42:15 muzyka go-librespot[2454]: time="2025-09-14T12:42:15+02:00" level=debug msg="app state loaded" Sep 14 12:42:15 muzyka go-librespot[2454]: time="2025-09-14T12:42:15+02:00" level=debug msg="stored credentials not found" Sep 14 12:42:15 muzyka go-librespot[2454]: time="2025-09-14T12:42:15+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 14 12:42:16 muzyka go-librespot[2454]: time="2025-09-14T12:42:16+02: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]" Sep 14 12:42:16 muzyka go-librespot[2454]: time="2025-09-14T12:42:16+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 14 12:42:16 muzyka go-librespot[2454]: time="2025-09-14T12:42:16+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 14 12:42:16 muzyka go-librespot[2454]: time="2025-09-14T12:42:16+02:00" level=info msg="zeroconf server listening on port 46329" Sep 14 12:42:16 muzyka go-librespot[2454]: time="2025-09-14T12:42:16+02:00" level=debug msg="obtained new client token: AABGA5ZwvLaFGjpnEVtWp2ww9h8nFxLjmnO+6WC26YmMRuB4wFDDioSFPIyTYBUHQcIBeYn6SX2SK2xsPVaSFJMPC7CNSMB3M0+Q7JQSlvmEBooVjOFVtVGz+ogtVdMHcQ9BUWNFQQ+m5ACWk/C67Eu057I1HYII27opuP23go9Iau89WNp0WoaaijyoJhZiUzcp4ak7x9rMCnVRAJy85VqMFWX0YnUqbD3t7nB9GtfbjC10Ru/+mmQ=" Sep 14 12:42:16 muzyka go-librespot[2454]: time="2025-09-14T12:42:16+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 14 12:42:16 muzyka go-librespot[2454]: time="2025-09-14T12:42:16+02:00" level=debug msg="completed keyexchange" Sep 14 12:42:16 muzyka go-librespot[2454]: time="2025-09-14T12:42:16+02:00" level=debug msg="completed challenge" Sep 14 12:42:16 muzyka go-librespot[2454]: time="2025-09-14T12:42:16+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Sep 14 12:42:16 muzyka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 14 12:42:16 muzyka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 14 12:42:17 muzyka sudo[2465]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-09-14 12:41' Sep 14 12:42:17 muzyka sudo[2465]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:42:17 muzyka sudo[2465]: pam_unix(sudo:session): session closed for user root Sep 14 12:42:18 muzyka volumio-remote-updater[665]: [2025-09-14 12:42:18] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 14 12:42:18 muzyka volumio-remote-updater[665]: [2025-09-14 12:42:18] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 14 12:42:18 muzyka systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 14 12:42:18 muzyka systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 14 12:42:18 muzyka systemd[1]: volumio.service: Consumed 9min 11.641s CPU time. Sep 14 12:42:18 muzyka systemd[1]: Started dynamicswap.service - dynamicswap service. Sep 14 12:42:18 muzyka systemd[1]: dynamicswap.service: Deactivated successfully. Sep 14 12:42:18 muzyka systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Sep 14 12:42:18 muzyka systemd[1]: Started dynamicswap.service - dynamicswap service. Sep 14 12:42:18 muzyka systemd[1]: Stopped volumio.service - Volumio Backend Module. Sep 14 12:42:18 muzyka systemd[1]: volumio.service: Consumed 9min 11.641s CPU time. Sep 14 12:42:18 muzyka systemd[1]: Started volumio.service - Volumio Backend Module. Sep 14 12:42:18 muzyka systemd[1]: dynamicswap.service: Deactivated successfully. Sep 14 12:42:19 muzyka volumio[2488]: info: ------------------------------------------- Sep 14 12:42:19 muzyka volumio[2488]: info: ----- Volumio3 ---- Sep 14 12:42:19 muzyka volumio[2488]: info: ------------------------------------------- Sep 14 12:42:19 muzyka volumio[2488]: info: ----- System startup ---- Sep 14 12:42:19 muzyka volumio[2488]: info: ------------------------------------------- Sep 14 12:42:19 muzyka volumio[2488]: info: MYVOLUMIO Environment detected Sep 14 12:42:19 muzyka volumio[2488]: info: Plugin folders cleanup Sep 14 12:42:19 muzyka volumio[2488]: info: Scanning into folder /volumio/app/plugins/ Sep 14 12:42:19 muzyka volumio[2488]: info: Scanning category audio_interface Sep 14 12:42:19 muzyka volumio[2488]: info: Scanning category miscellanea Sep 14 12:42:19 muzyka volumio[2488]: info: Scanning category music_service Sep 14 12:42:19 muzyka volumio[2488]: info: Scanning category plugins.json Sep 14 12:42:19 muzyka volumio[2488]: info: Scanning category system_controller Sep 14 12:42:19 muzyka volumio[2488]: info: Scanning category user_interface Sep 14 12:42:19 muzyka volumio[2488]: info: Scanning into folder /data/plugins/ Sep 14 12:42:19 muzyka volumio[2488]: info: Scanning category audio_interface Sep 14 12:42:19 muzyka volumio[2488]: info: Scanning category music_service Sep 14 12:42:19 muzyka volumio[2488]: info: Scanning category system_controller Sep 14 12:42:19 muzyka volumio[2488]: info: Scanning category user_interface Sep 14 12:42:19 muzyka volumio[2488]: info: Plugin folders cleanup completed Sep 14 12:42:19 muzyka volumio[2488]: info: ------------------------------------------- Sep 14 12:42:19 muzyka volumio[2488]: info: ----- Core plugins startup ---- Sep 14 12:42:19 muzyka volumio[2488]: info: ------------------------------------------- Sep 14 12:42:19 muzyka volumio[2488]: info: Loading plugins from folder /volumio/app/plugins/ Sep 14 12:42:19 muzyka volumio[2488]: info: Adding plugin upnp to MyMusic Plugins Sep 14 12:42:19 muzyka volumio[2488]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 14 12:42:19 muzyka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Sep 14 12:42:19 muzyka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 14 12:42:19 muzyka volumio[2488]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 14 12:42:19 muzyka volumio[2488]: info: Loading plugins from folder /data/plugins/ Sep 14 12:42:19 muzyka volumio[2488]: info: Loading plugin "system"... Sep 14 12:42:19 muzyka volumio[2488]: info: Loading plugin "appearance"... Sep 14 12:42:20 muzyka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 14 12:42:20 muzyka go-librespot[2512]: go-librespot daemon starting... Sep 14 12:42:20 muzyka go-librespot[2516]: time="2025-09-14T12:42:20+02:00" level=info msg="running go-librespot 0.3.2" Sep 14 12:42:20 muzyka go-librespot[2516]: time="2025-09-14T12:42:20+02:00" level=debug msg="app state loaded" Sep 14 12:42:20 muzyka go-librespot[2516]: time="2025-09-14T12:42:20+02:00" level=debug msg="stored credentials not found" Sep 14 12:42:20 muzyka go-librespot[2516]: time="2025-09-14T12:42:20+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 14 12:42:20 muzyka go-librespot[2516]: time="2025-09-14T12:42:20+02: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]" Sep 14 12:42:20 muzyka go-librespot[2516]: time="2025-09-14T12:42:20+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 14 12:42:20 muzyka go-librespot[2516]: time="2025-09-14T12:42:20+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 14 12:42:20 muzyka go-librespot[2516]: time="2025-09-14T12:42:20+02:00" level=info msg="zeroconf server listening on port 35805" Sep 14 12:42:20 muzyka volumio[2488]: info: Loading plugin "network"... Sep 14 12:42:20 muzyka volumio[2488]: info: Refreshing Cached IP Addresses Sep 14 12:42:20 muzyka sudo[2525]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 14 12:42:20 muzyka sudo[2525]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:42:20 muzyka sudo[2525]: pam_unix(sudo:session): session closed for user root Sep 14 12:42:20 muzyka sudo[2527]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 14 12:42:20 muzyka sudo[2527]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:42:20 muzyka volumio[2488]: info: Loading plugin "services"... Sep 14 12:42:20 muzyka volumio[2488]: info: Loading plugin "alsa_controller"... Sep 14 12:42:20 muzyka sudo[2527]: pam_unix(sudo:session): session closed for user root Sep 14 12:42:20 muzyka sudo[2535]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 14 12:42:20 muzyka sudo[2535]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:42:20 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 14 12:42:20 muzyka volumio[2488]: info: Loading plugin "wizard"... Sep 14 12:42:20 muzyka volumio[2488]: info: Loading plugin "networkfs"... Sep 14 12:42:20 muzyka volumio[2488]: info: Starting Udev Watcher for removable devices Sep 14 12:42:20 muzyka volumio[2488]: info: Ignoring mount for partition: boot Sep 14 12:42:20 muzyka volumio[2488]: info: Ignoring mount for partition: volumio Sep 14 12:42:20 muzyka volumio[2488]: info: Ignoring mount for partition: volumio_data Sep 14 12:42:20 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 14 12:42:20 muzyka volumio[2488]: info: Loading plugin "volumio_command_line_client"... Sep 14 12:42:20 muzyka volumio[2488]: info: Loading plugin "upnp"... Sep 14 12:42:20 muzyka volumio[2488]: info: [1757846540796] Starting Upmpd Daemon Sep 14 12:42:20 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 14 12:42:20 muzyka volumio[2488]: info: Loading plugin "my_music"... Sep 14 12:42:20 muzyka volumio[2488]: info: Loading plugin "mpd"... Sep 14 12:42:20 muzyka go-librespot[2516]: time="2025-09-14T12:42:20+02:00" level=debug msg="obtained new client token: AAA29VNeVvxLm9DYwMC1xtgH2n9umtvosjxCIDVzryDzOk8f8ixe32U1UbZPUl0PV74rtCHgJ0elV/IIJ3jPqXIhASQ8LPm/6KSL8vZkmFDaJgF3siC1hHj4M8GZAkBL/14R38hOa9EUIQX9PFrWj2elPYEMjxTN8lxWW2ScaPVncvKLm7W8GUOKeL25m/oNr4k/VbHFD3hmDQdbv8c6Ss9W9NweyDDb9/GOtlmNCzZ5Q2GHqhHrtls=" Sep 14 12:42:21 muzyka go-librespot[2516]: time="2025-09-14T12:42:21+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 14 12:42:21 muzyka volumio[2488]: info: Plugin upnp_browser is not enabled Sep 14 12:42:21 muzyka volumio[2488]: info: Loading plugin "alarm-clock"... Sep 14 12:42:21 muzyka volumio[2488]: info: Plugin airplay_emulation is not enabled Sep 14 12:42:21 muzyka volumio[2488]: info: Loading plugin "last_100"... Sep 14 12:42:21 muzyka volumio[2488]: info: Loading plugin "webradio"... Sep 14 12:42:21 muzyka go-librespot[2516]: time="2025-09-14T12:42:21+02:00" level=debug msg="completed keyexchange" Sep 14 12:42:21 muzyka go-librespot[2516]: time="2025-09-14T12:42:21+02:00" level=debug msg="completed challenge" Sep 14 12:42:21 muzyka go-librespot[2516]: time="2025-09-14T12:42:21+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Sep 14 12:42:21 muzyka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 14 12:42:21 muzyka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 14 12:42:21 muzyka volumio[2488]: info: Loading plugin "i2s_dacs"... Sep 14 12:42:21 muzyka volumio[2488]: info: Loading plugin "volumiodiscovery"... Sep 14 12:42:21 muzyka volumio[2488]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 14 12:42:21 muzyka volumio[2488]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 14 12:42:21 muzyka volumio[2488]: *** WARNING *** For more information see Sep 14 12:42:21 muzyka volumio[2488]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 14 12:42:21 muzyka volumio[2488]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 14 12:42:21 muzyka volumio[2488]: *** WARNING *** For more information see Sep 14 12:42:21 muzyka node[2488]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 14 12:42:21 muzyka node[2488]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 14 12:42:21 muzyka node[2488]: *** WARNING *** For more information see Sep 14 12:42:21 muzyka node[2488]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 14 12:42:21 muzyka node[2488]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 14 12:42:21 muzyka node[2488]: *** WARNING *** For more information see Sep 14 12:42:21 muzyka volumio[2488]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 14 12:42:21 muzyka volumio[2488]: info: Discovery: Started advertising with name: muzyka Sep 14 12:42:21 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 14 12:42:21 muzyka volumio[2488]: info: Loading plugin "spop"... Sep 14 12:42:22 muzyka volumio[2488]: info: Loading plugin "ytmusic"... Sep 14 12:42:23 muzyka volumio-remote-updater[665]: [2025-09-14 12:42:23] [connect] Successful connection Sep 14 12:42:23 muzyka systemd[1]: systemd-timedated.service: Deactivated successfully. Sep 14 12:42:24 muzyka volumio[2488]: info: Loading plugin "autostart"... Sep 14 12:42:24 muzyka volumio[2488]: info: Applying required configuration parameters for plugin autostart Sep 14 12:42:24 muzyka volumio[2488]: info: AutoStart - onVolumioStart - read config.json Sep 14 12:42:24 muzyka volumio[2488]: info: Loading plugin "outputs"... Sep 14 12:42:24 muzyka volumio[2488]: info: Loading plugin "albumart"... Sep 14 12:42:24 muzyka volumio[2488]: info: Plugin example_plugin is not enabled Sep 14 12:42:24 muzyka volumio[2488]: info: Loading plugin "inputs"... Sep 14 12:42:24 muzyka volumio[2488]: info: Loading plugin "updater_comm"... Sep 14 12:42:24 muzyka volumio[2488]: info: Plugin mpdemulation is not enabled Sep 14 12:42:24 muzyka volumio[2488]: info: Loading plugin "rest_api"... Sep 14 12:42:24 muzyka volumio[2488]: info: Loading plugin "websocket"... Sep 14 12:42:24 muzyka volumio[2488]: info: Starting Socket.io Server version 1.7.4 Sep 14 12:42:24 muzyka volumio[2488]: info: Loading plugin "fusiondsp"... Sep 14 12:42:24 muzyka sudo[2535]: pam_unix(sudo:session): session closed for user root Sep 14 12:42:24 muzyka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Sep 14 12:42:24 muzyka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 14 12:42:24 muzyka volumio[2488]: info: Applying required configuration parameters for plugin fusiondsp Sep 14 12:42:24 muzyka volumio[2488]: info: Plugin mpdoutput is not enabled Sep 14 12:42:24 muzyka volumio[2488]: info: Loading plugin "80s80s"... Sep 14 12:42:24 muzyka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 14 12:42:24 muzyka go-librespot[2569]: go-librespot daemon starting... Sep 14 12:42:24 muzyka go-librespot[2570]: time="2025-09-14T12:42:24+02:00" level=info msg="running go-librespot 0.3.2" Sep 14 12:42:24 muzyka go-librespot[2570]: time="2025-09-14T12:42:24+02:00" level=debug msg="app state loaded" Sep 14 12:42:24 muzyka go-librespot[2570]: time="2025-09-14T12:42:24+02:00" level=debug msg="stored credentials not found" Sep 14 12:42:24 muzyka go-librespot[2570]: time="2025-09-14T12:42:24+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 14 12:42:24 muzyka volumio[2488]: info: Applying required configuration parameters for plugin 80s80s Sep 14 12:42:24 muzyka volumio[2488]: info: [1757846544839] [80s80s] API delay: 5 Sep 14 12:42:24 muzyka volumio[2488]: info: Loading plugin "backup_restore"... Sep 14 12:42:24 muzyka volumio[2559]: Forking 3 albumart workers Sep 14 12:42:24 muzyka go-librespot[2570]: time="2025-09-14T12:42:24+02: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]" Sep 14 12:42:24 muzyka go-librespot[2570]: time="2025-09-14T12:42:24+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Sep 14 12:42:24 muzyka go-librespot[2570]: time="2025-09-14T12:42:24+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Sep 14 12:42:24 muzyka go-librespot[2570]: time="2025-09-14T12:42:24+02:00" level=info msg="zeroconf server listening on port 42915" Sep 14 12:42:25 muzyka go-librespot[2570]: time="2025-09-14T12:42:25+02:00" level=debug msg="obtained new client token: AAB8FXz3nw2eBl95YqKFXxq1FFyIoOpuqG1kA2c2WlO6Pd+ZgWdZArR7rLvdXeX6RoW/6ONpu7Na5N6N7E8dbbNRhqyMBh103bMzuW8DvEZRhrn/4PGmPzy40uCbEiTn0oNMIh2i6Fb3GUM0GpKqVQasYA38VQEUglEG5NeRPBycQbu0F6sCY02u8qnsb8I0X153gzIp9FywfZ6SBRp0QCN924GOJZWykR+9lp3BDABmVVvgze8q" Sep 14 12:42:25 muzyka go-librespot[2570]: time="2025-09-14T12:42:25+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 14 12:42:25 muzyka go-librespot[2570]: time="2025-09-14T12:42:25+02:00" level=debug msg="completed keyexchange" Sep 14 12:42:25 muzyka go-librespot[2570]: time="2025-09-14T12:42:25+02:00" level=debug msg="completed challenge" Sep 14 12:42:25 muzyka go-librespot[2570]: time="2025-09-14T12:42:25+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Sep 14 12:42:25 muzyka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 14 12:42:25 muzyka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 14 12:42:25 muzyka volumio[2488]: info: Applying required configuration parameters for plugin backup_restore Sep 14 12:42:25 muzyka volumio[2488]: info: Loading plugin "Systeminfo"... Sep 14 12:42:26 muzyka volumio[2488]: info: Loading plugin "touch_display"... Sep 14 12:42:27 muzyka volumio[2488]: info: Applying required configuration parameters for plugin touch_display Sep 14 12:42:27 muzyka volumio[2488]: info: Loading i18n strings for locale pl Sep 14 12:42:27 muzyka volumio[2488]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Sep 14 12:42:27 muzyka volumio[2488]: Updating browse sources language Sep 14 12:42:27 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 12:42:27 muzyka volumio[2577]: Starting albumart workers Sep 14 12:42:27 muzyka volumio[2578]: Starting albumart workers Sep 14 12:42:28 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 12:42:28 muzyka volumio[2488]: info: CoreCommandRouter::initPlayerControls Sep 14 12:42:28 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:42:28 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:42:28 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:42:28 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:42:28 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:42:28 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:42:28 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:42:28 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:42:28 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 12:42:28 muzyka volumio[2488]: Express server listening on port 3000 Sep 14 12:42:28 muzyka volumio[2488]: [Metrics] WebUI: 8s 975.87ms Sep 14 12:42:28 muzyka volumio[2579]: Starting albumart workers Sep 14 12:42:28 muzyka volumio[2488]: info: CoreStateMachine::resetVolumioState Sep 14 12:42:28 muzyka volumio[2488]: info: CoreStateMachine::getcurrentVolume Sep 14 12:42:28 muzyka volumio[2488]: info: CoreCommandRouter::volumioRetrievevolume Sep 14 12:42:28 muzyka volumio[2488]: info: CoreStateMachine::pushState Sep 14 12:42:28 muzyka volumio[2488]: info: CorePlayQueue::getTrack 0 Sep 14 12:42:28 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 12:42:28 muzyka volumio[2488]: info: CoreCommandRouter::volumioPushState Sep 14 12:42:28 muzyka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Sep 14 12:42:28 muzyka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 14 12:42:28 muzyka volumio[2488]: info: Volumio Network Manager: Network status updated: 2 Sep 14 12:42:28 muzyka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 14 12:42:28 muzyka go-librespot[2610]: go-librespot daemon starting... Sep 14 12:42:28 muzyka go-librespot[2611]: time="2025-09-14T12:42:28+02:00" level=info msg="running go-librespot 0.3.2" Sep 14 12:42:28 muzyka go-librespot[2611]: time="2025-09-14T12:42:28+02:00" level=debug msg="app state loaded" Sep 14 12:42:28 muzyka go-librespot[2611]: time="2025-09-14T12:42:28+02:00" level=debug msg="stored credentials not found" Sep 14 12:42:28 muzyka go-librespot[2611]: time="2025-09-14T12:42:28+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 14 12:42:28 muzyka volumio[2488]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Sep 14 12:42:28 muzyka volumio[2488]: verbose: New Socket.io Connection to 192.168.0.101 from 192.168.0.72 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Sep 14 12:42:28 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 12:42:28 muzyka volumio[2488]: info: Reloading queue from file Sep 14 12:42:28 muzyka volumio[2488]: verbose: New Socket.io Connection to 192.168.0.101 from 192.168.0.72 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Sep 14 12:42:28 muzyka volumio[2488]: info: CoreStateMachine::setRepeat false single undefined Sep 14 12:42:28 muzyka volumio[2488]: info: CoreStateMachine::pushState Sep 14 12:42:28 muzyka volumio[2488]: info: CorePlayQueue::getTrack 0 Sep 14 12:42:28 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 12:42:28 muzyka volumio[2488]: info: CoreCommandRouter::volumioPushState Sep 14 12:42:28 muzyka go-librespot[2611]: time="2025-09-14T12:42:28+02: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]" Sep 14 12:42:28 muzyka go-librespot[2611]: time="2025-09-14T12:42:28+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Sep 14 12:42:28 muzyka go-librespot[2611]: time="2025-09-14T12:42:28+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Sep 14 12:42:28 muzyka go-librespot[2611]: time="2025-09-14T12:42:28+02:00" level=info msg="zeroconf server listening on port 37315" Sep 14 12:42:28 muzyka volumio[2488]: info: CoreStateMachine::setRandom null Sep 14 12:42:28 muzyka volumio[2488]: info: CoreStateMachine::pushState Sep 14 12:42:28 muzyka volumio[2488]: info: CorePlayQueue::getTrack 0 Sep 14 12:42:28 muzyka volumio[2488]: info: CoreCommandRouter::volumioPushState Sep 14 12:42:28 muzyka volumio[2488]: info: Setting Device type: Raspberry PI Sep 14 12:42:28 muzyka volumio[2488]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Sep 14 12:42:28 muzyka volumio[2488]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03114 Sep 14 12:42:28 muzyka volumio[2488]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Sep 14 12:42:29 muzyka volumio[2488]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Sep 14 12:42:29 muzyka volumio[2488]: info: Completed loading Core Plugins Sep 14 12:42:29 muzyka volumio[2488]: info: Preparing to generate the ALSA configuration file Sep 14 12:42:29 muzyka volumio[2488]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Sep 14 12:42:29 muzyka volumio[2488]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 14 12:42:29 muzyka volumio[2488]: info: Reading ALSA contributions from plugins. Sep 14 12:42:29 muzyka volumio[2488]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Sep 14 12:42:29 muzyka volumio[2488]: verbose: New Socket.io Connection to 192.168.0.101 from 192.168.0.72 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Sep 14 12:42:29 muzyka volumio[2488]: info: Discovery: adding 2ae8cc93-7fac-4f11-a512-4df746bfaae9 Sep 14 12:42:29 muzyka volumio[2488]: info: Discovery: Found device muzyka Sep 14 12:42:29 muzyka volumio[2488]: info: CoreCommandRouter::volumioGetState Sep 14 12:42:29 muzyka volumio[2488]: info: CorePlayQueue::getTrack 0 Sep 14 12:42:29 muzyka volumio[2488]: info: Discovery: this is already registered, 2ae8cc93-7fac-4f11-a512-4df746bfaae9 Sep 14 12:42:29 muzyka volumio[2488]: info: Discovery: Found device muzyka Sep 14 12:42:29 muzyka volumio[2488]: info: CoreCommandRouter::volumioGetState Sep 14 12:42:29 muzyka volumio[2488]: info: CorePlayQueue::getTrack 0 Sep 14 12:42:29 muzyka volumio[2488]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Sep 14 12:42:29 muzyka go-librespot[2611]: time="2025-09-14T12:42:29+02:00" level=debug msg="obtained new client token: AADEGs4W0ID4xzNMgCRod9ygMOMehQ4+j9qXX0V3pz9Q9Xu8F7+Esas6XI017xIc5ZUf7vyl5AJ6gCGFXudwAzxGuoHHb1+WhZbCtHi4zpzjxiaUCCgD+HX9R+e8f8C+h48Sr//VWYCLX+lubPlUAabHWFO+fr8L/yIGo5Y6ZFlDVAQysKq5ZrrK7c7DVHlj/i0O5OoQJtqH8UqGte+9Z6njN2QuRpdPQXYcQFUfh0pFXMhUy8j27pI=" Sep 14 12:42:29 muzyka go-librespot[2611]: time="2025-09-14T12:42:29+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 14 12:42:29 muzyka volumio[2488]: info: Asound.conf file unchanged, so no further update is needed Sep 14 12:42:29 muzyka volumio[2488]: info: Output device has changed, restarting MPD Sep 14 12:42:29 muzyka volumio[2488]: info: ___________ START PLUGINS ___________ Sep 14 12:42:29 muzyka volumio[2488]: info: ControllerMpd::onStart: Initializing MPD Sep 14 12:42:29 muzyka volumio[2488]: info: Creating MPD Configuration file Sep 14 12:42:29 muzyka sudo[2646]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 14 12:42:29 muzyka sudo[2646]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:42:29 muzyka sudo[2648]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 14 12:42:29 muzyka sudo[2648]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:42:30 muzyka sudo[2646]: pam_unix(sudo:session): session closed for user root Sep 14 12:42:30 muzyka sudo[2650]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 14 12:42:30 muzyka sudo[2650]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:42:30 muzyka volumio[2488]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 14 12:42:30 muzyka volumio[2488]: info: [1757846550015] CoreMusicLibrary::Adding element Last_100 Sep 14 12:42:30 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 12:42:30 muzyka volumio[2488]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 14 12:42:30 muzyka volumio[2488]: info: [1757846550023] CoreMusicLibrary::Adding element Webradio Sep 14 12:42:30 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 12:42:30 muzyka sudo[2650]: pam_unix(sudo:session): session closed for user root Sep 14 12:42:30 muzyka sudo[2655]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 14 12:42:30 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 14 12:42:30 muzyka volumio[2488]: info: Initializing BBC Radios Sep 14 12:42:30 muzyka systemd[1]: Stopping mpd.service - Music Player Daemon... Sep 14 12:42:30 muzyka sudo[2655]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:42:30 muzyka systemd[1]: mpd.service: Deactivated successfully. Sep 14 12:42:30 muzyka systemd[1]: Stopped mpd.service - Music Player Daemon. Sep 14 12:42:30 muzyka systemd[1]: mpd.service: Consumed 1.603s CPU time. Sep 14 12:42:30 muzyka systemd[1]: mpd.socket: Deactivated successfully. Sep 14 12:42:30 muzyka systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Sep 14 12:42:30 muzyka systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Sep 14 12:42:30 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 14 12:42:30 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 12:42:30 muzyka systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Sep 14 12:42:30 muzyka systemd[1]: Starting mpd.service - Music Player Daemon... Sep 14 12:42:30 muzyka systemd[1]: mpd.service: Deactivated successfully. Sep 14 12:42:30 muzyka systemd[1]: Stopped mpd.service - Music Player Daemon. Sep 14 12:42:30 muzyka volumio[2488]: info: Creating Spotify config file Sep 14 12:42:30 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:42:30 muzyka systemd[1]: mpd.socket: Deactivated successfully. Sep 14 12:42:30 muzyka systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Sep 14 12:42:30 muzyka systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Sep 14 12:42:30 muzyka systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Sep 14 12:42:30 muzyka systemd[1]: Starting mpd.service - Music Player Daemon... Sep 14 12:42:30 muzyka sudo[2673]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 14 12:42:30 muzyka sudo[2673]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 14 12:42:30 muzyka sudo[2673]: pam_unix(sudo:session): session closed for user root Sep 14 12:42:30 muzyka go-librespot[2611]: time="2025-09-14T12:42:30+02:00" level=debug msg="completed keyexchange" Sep 14 12:42:30 muzyka go-librespot[2611]: time="2025-09-14T12:42:30+02:00" level=debug msg="completed challenge" Sep 14 12:42:30 muzyka go-librespot[2611]: time="2025-09-14T12:42:30+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Sep 14 12:42:30 muzyka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 14 12:42:30 muzyka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 14 12:42:30 muzyka volumio[2488]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 14 12:42:31 muzyka volumio[2488]: info: [1757846551013] CoreMusicLibrary::Adding element YouTube Music Sep 14 12:42:31 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 12:42:31 muzyka volumio[2488]: Cannot find translation for source YouTube Music Sep 14 12:42:31 muzyka volumio[2488]: info: AutoStart - onStart Sep 14 12:42:31 muzyka volumio[2488]: info: Loading i18n strings for locale pl Sep 14 12:42:31 muzyka volumio[2488]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Sep 14 12:42:31 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 14 12:42:31 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:42:31 muzyka volumio[2488]: info: FusionDsp - mixtype--------------------- None Sep 14 12:42:31 muzyka volumio[2488]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 14 12:42:31 muzyka volumio[2488]: info: [1757846551108] CoreMusicLibrary::Adding element 80s80s Radio Sep 14 12:42:31 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 12:42:31 muzyka volumio[2488]: Cannot find translation for source YouTube Music Sep 14 12:42:31 muzyka volumio[2488]: Cannot find translation for source 80s80s Radio Sep 14 12:42:31 muzyka volumio[2488]: info: Loading i18n strings for locale pl Sep 14 12:42:31 muzyka volumio[2488]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Sep 14 12:42:31 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 14 12:42:31 muzyka volumio[2488]: info: Volumio Calling Home Sep 14 12:42:31 muzyka volumio[2488]: info: Preparing to generate the ALSA configuration file Sep 14 12:42:31 muzyka sudo[2699]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Sep 14 12:42:31 muzyka sudo[2699]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:42:31 muzyka sudo[2696]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Sep 14 12:42:31 muzyka sudo[2696]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:42:31 muzyka sudo[2698]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Sep 14 12:42:31 muzyka sudo[2699]: pam_unix(sudo:session): session closed for user root Sep 14 12:42:31 muzyka sudo[2698]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:42:31 muzyka systemd[1]: Reloading. Sep 14 12:42:31 muzyka mpd[2675]: 2025-09-14T12:42:31 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 14 12:42:32 muzyka volumio[2488]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Sep 14 12:42:32 muzyka volumio[2488]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Sep 14 12:42:32 muzyka volumio[2488]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 14 12:42:32 muzyka volumio[2488]: info: Reading ALSA contributions from plugins. Sep 14 12:42:32 muzyka volumio[2488]: info: touch_display: No backlight interface detected. Sep 14 12:42:32 muzyka sudo[2724]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 14 12:42:32 muzyka sudo[2724]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:42:32 muzyka sudo[2722]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 14 12:42:32 muzyka sudo[2722]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:42:32 muzyka sudo[2724]: pam_unix(sudo:session): session closed for user root Sep 14 12:42:32 muzyka volumio[2488]: verbose: New Socket.io Connection to 192.168.0.101 from 192.168.0.72 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Sep 14 12:42:32 muzyka sudo[2722]: pam_unix(sudo:session): session closed for user root Sep 14 12:42:32 muzyka volumio[2488]: info: touch_display: systemctl stop getty@tty1.service succeeded. Sep 14 12:42:32 muzyka volumio[2488]: info: MPD Permissions set Sep 14 12:42:32 muzyka volumio[2488]: info: MPD Permissions set Sep 14 12:42:32 muzyka volumio[2488]: info: Volumio called home Sep 14 12:42:32 muzyka volumio[2488]: info: Spotify config file written Sep 14 12:42:32 muzyka volumio[2488]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Sep 14 12:42:32 muzyka volumio[2488]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Sep 14 12:42:32 muzyka sudo[2728]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 14 12:42:32 muzyka sudo[2728]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:42:32 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:42:32 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:42:32 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:42:32 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:42:32 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:42:32 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:42:32 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:42:32 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:42:32 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 12:42:32 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:42:32 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:42:32 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:42:32 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:42:32 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:42:32 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:42:32 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:42:32 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:42:32 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:42:32 muzyka systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 14 12:42:32 muzyka systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 14 12:42:32 muzyka systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 14 12:42:32 muzyka systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 14 12:42:32 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 12:42:32 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:42:32 muzyka volumio[2488]: info: No need to fix Spotify hosts Sep 14 12:42:32 muzyka systemd[1]: Started mpd.service - Music Player Daemon. Sep 14 12:42:32 muzyka sudo[2655]: pam_unix(sudo:session): session closed for user root Sep 14 12:42:32 muzyka systemd[1]: Reloading. Sep 14 12:42:32 muzyka sudo[2696]: pam_unix(sudo:session): session closed for user root Sep 14 12:42:32 muzyka sudo[2648]: pam_unix(sudo:session): session closed for user root Sep 14 12:42:33 muzyka volumio[2488]: info: touch_display: systemctl disable getty@tty1.service succeeded. Sep 14 12:42:33 muzyka volumio[2488]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 14 12:42:33 muzyka volumio[2488]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Sep 14 12:42:33 muzyka volumio[2488]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 14 12:42:33 muzyka volumio[2488]: info: camilladsp service started and running in background, instance 1 Sep 14 12:42:33 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:42:33 muzyka volumio[2488]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 <-AAAAAAAAAAAAA Sep 14 12:42:33 muzyka volumio[2488]: info: FusionDsp loaded Sep 14 12:42:33 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 12:42:33 muzyka volumio[2488]: error: FusionDsp - Socket connection not established Sep 14 12:42:33 muzyka volumio[2488]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 14 12:42:33 muzyka volumio[2488]: info: Adding Signal Path Element [object Object] Sep 14 12:42:33 muzyka volumio[2488]: info: Adding fusiondspeq DSP Signal Path Element Sep 14 12:42:33 muzyka sudo[2772]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 14 12:42:33 muzyka sudo[2772]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:42:33 muzyka systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 14 12:42:33 muzyka systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 14 12:42:33 muzyka volumio[2488]: info: FusionDsp - ---- installed callbackRead Sep 14 12:42:33 muzyka volumio[2488]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 14 12:42:33 muzyka systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 14 12:42:33 muzyka systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 14 12:42:33 muzyka sudo[2698]: pam_unix(sudo:session): session closed for user root Sep 14 12:42:33 muzyka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Sep 14 12:42:33 muzyka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 14 12:42:33 muzyka sudo[2772]: pam_unix(sudo:session): session closed for user root Sep 14 12:42:33 muzyka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 14 12:42:33 muzyka go-librespot[2774]: go-librespot daemon starting... Sep 14 12:42:33 muzyka sudo[2728]: pam_unix(sudo:session): session closed for user root Sep 14 12:42:33 muzyka volumio[2488]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Sep 14 12:42:33 muzyka go-librespot[2776]: time="2025-09-14T12:42:33+02:00" level=info msg="running go-librespot 0.3.2" Sep 14 12:42:33 muzyka go-librespot[2776]: time="2025-09-14T12:42:33+02:00" level=debug msg="app state loaded" Sep 14 12:42:33 muzyka go-librespot[2776]: time="2025-09-14T12:42:33+02:00" level=debug msg="stored credentials not found" Sep 14 12:42:33 muzyka go-librespot[2776]: time="2025-09-14T12:42:33+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 14 12:42:33 muzyka sudo[2782]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Sep 14 12:42:33 muzyka sudo[2782]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:42:33 muzyka sudo[2782]: pam_unix(sudo:session): session closed for user root Sep 14 12:42:33 muzyka volumio[2488]: info: MPD running with PID2675 Sep 14 12:42:33 muzyka volumio[2488]: ,establishing connection Sep 14 12:42:33 muzyka volumio[2488]: info: An error occurred while refreshing Spotify Token Error: Bad Request Sep 14 12:42:33 muzyka sudo[2787]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 14 12:42:33 muzyka sudo[2787]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:42:34 muzyka go-librespot[2776]: time="2025-09-14T12:42:34+02: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]" Sep 14 12:42:34 muzyka go-librespot[2776]: time="2025-09-14T12:42:34+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 14 12:42:34 muzyka go-librespot[2776]: time="2025-09-14T12:42:34+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 14 12:42:34 muzyka go-librespot[2776]: time="2025-09-14T12:42:34+02:00" level=info msg="zeroconf server listening on port 43153" Sep 14 12:42:34 muzyka sudo[2787]: pam_unix(sudo:session): session closed for user root Sep 14 12:42:34 muzyka go-librespot[2776]: time="2025-09-14T12:42:34+02:00" level=debug msg="obtained new client token: AAApUF5S64rBiMgZAlnkQbRfZ9bfX+wKuhhuBmNgNIO6sPZ/JAm4swCA7XaOwcaVJ1M/VJHOnz035dF43+7xkK0tjA43mjyizV3Wz7R8oE2MdC4rSZuUTowtrveIchSPgIib4pseG+ZTRmOQZdOmV+rFXx24Yh4JMKOFtV006lV8ykViJUsjRsGZ46PVy+wbyw1JQYSVMBm6eoEFZeBlmQO0Km9h40/3bU/BS9QJhPkzMSs66vVlNUk=" Sep 14 12:42:34 muzyka volumio[2488]: error: MPD error: The expression evaluated to a falsy value: Sep 14 12:42:34 muzyka volumio[2488]: assert.ok(self.idling) Sep 14 12:42:34 muzyka volumio[2488]: error: The expression evaluated to a falsy value: Sep 14 12:42:34 muzyka volumio[2488]: assert.ok(self.idling) Sep 14 12:42:34 muzyka volumio[2488]: error: MPD error: The expression evaluated to a falsy value: Sep 14 12:42:34 muzyka volumio[2488]: assert.ok(self.idling) Sep 14 12:42:34 muzyka volumio[2488]: error: The expression evaluated to a falsy value: Sep 14 12:42:34 muzyka volumio[2488]: assert.ok(self.idling) Sep 14 12:42:34 muzyka go-librespot[2776]: time="2025-09-14T12:42:34+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 14 12:42:34 muzyka volumio[2488]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Sep 14 12:42:34 muzyka volumio[2488]: info: touch_display: systemctl daemon-reload succeeded. Sep 14 12:42:34 muzyka go-librespot[2776]: time="2025-09-14T12:42:34+02:00" level=debug msg="completed keyexchange" Sep 14 12:42:34 muzyka go-librespot[2776]: time="2025-09-14T12:42:34+02:00" level=debug msg="completed challenge" Sep 14 12:42:34 muzyka sudo[2790]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Sep 14 12:42:34 muzyka sudo[2790]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:42:34 muzyka volumio[2488]: error: FusionDsp - Reload WebSocket error: [object Object] Sep 14 12:42:34 muzyka go-librespot[2776]: time="2025-09-14T12:42:34+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Sep 14 12:42:34 muzyka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 14 12:42:34 muzyka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 14 12:42:34 muzyka sudo[2790]: pam_unix(sudo:session): session closed for user root Sep 14 12:42:34 muzyka volumio[2488]: info: Upmpdcli Daemon Started Sep 14 12:42:34 muzyka volumio[2488]: info: Asound.conf file unchanged, so no further update is needed Sep 14 12:42:34 muzyka volumio[2488]: info: Output device has changed, restarting MPD Sep 14 12:42:34 muzyka volumio[2488]: error: updateQueue error: null Sep 14 12:42:34 muzyka volumio[2488]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Sep 14 12:42:34 muzyka volumio[2488]: info: touch_display: Volumio Kiosk started. Sep 14 12:42:34 muzyka sudo[2795]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 14 12:42:34 muzyka sudo[2795]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:42:34 muzyka volumio[2488]: info: CoreCommandRouter::volumioGetState Sep 14 12:42:34 muzyka volumio[2488]: info: CorePlayQueue::getTrack 0 Sep 14 12:42:34 muzyka sudo[2794]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 14 12:42:34 muzyka sudo[2794]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:42:34 muzyka sudo[2795]: pam_unix(sudo:session): session closed for user root Sep 14 12:42:34 muzyka volumio[2488]: info: Completed starting Core Plugins Sep 14 12:42:34 muzyka volumio[2488]: info: ------------------------------------------- Sep 14 12:42:34 muzyka volumio[2488]: info: ----- MyVolumio plugins startup ---- Sep 14 12:42:34 muzyka volumio[2488]: info: ------------------------------------------- Sep 14 12:42:34 muzyka volumio[2488]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 14 12:42:34 muzyka volumio[2488]: info: CoreCommandRouter::volumioGetState Sep 14 12:42:34 muzyka volumio[2488]: info: CorePlayQueue::getTrack 0 Sep 14 12:42:34 muzyka volumio[2488]: info: MPD Permissions set Sep 14 12:42:34 muzyka systemd[1]: Stopping mpd.service - Music Player Daemon... Sep 14 12:42:34 muzyka systemd[1]: mpd.service: Deactivated successfully. Sep 14 12:42:34 muzyka systemd[1]: Stopped mpd.service - Music Player Daemon. Sep 14 12:42:34 muzyka systemd[1]: mpd.service: Consumed 1.581s CPU time. Sep 14 12:42:34 muzyka systemd[1]: mpd.socket: Deactivated successfully. Sep 14 12:42:34 muzyka systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Sep 14 12:42:34 muzyka systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Sep 14 12:42:34 muzyka volumio[2488]: info: touch_display: X display number found: 0 Sep 14 12:42:34 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:42:34 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:42:34 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:42:34 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:42:34 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:42:34 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:42:34 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:42:34 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:42:34 muzyka systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Sep 14 12:42:34 muzyka systemd[1]: Starting mpd.service - Music Player Daemon... Sep 14 12:42:34 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 12:42:34 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:42:34 muzyka volumio[2488]: info: CoreCommandRouter::volumioGetState Sep 14 12:42:34 muzyka volumio[2488]: info: CorePlayQueue::getTrack 0 Sep 14 12:42:34 muzyka sudo[2803]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 14 12:42:34 muzyka sudo[2803]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 14 12:42:34 muzyka sudo[2803]: pam_unix(sudo:session): session closed for user root Sep 14 12:42:34 muzyka volumio[2488]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Sep 14 12:42:34 muzyka volumio[2488]: info: touch_display: X display number found: 0 Sep 14 12:42:34 muzyka volumio[2488]: info: CoreCommandRouter::volumioGetState Sep 14 12:42:34 muzyka volumio[2488]: info: CorePlayQueue::getTrack 0 Sep 14 12:42:35 muzyka volumio[2488]: info: touch_display: Setting screensaver timeout to 120 seconds. Sep 14 12:42:35 muzyka volumio-remote-updater[665]: [2025-09-14 12:42:35] [connect] Successful connection Sep 14 12:42:35 muzyka volumio-remote-updater[665]: [2025-09-14 12:42:35] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1757846555 101 Sep 14 12:42:35 muzyka volumio[2488]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 12 Sep 14 12:42:36 muzyka volumio[2488]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Sep 14 12:42:36 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 14 12:42:36 muzyka volumio[2488]: info: CoreCommandRouter::volumioGetVisibleSources Sep 14 12:42:36 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 12:42:36 muzyka volumio[2488]: info: CoreCommandRouter::volumioGetState Sep 14 12:42:36 muzyka volumio[2488]: info: CorePlayQueue::getTrack 0 Sep 14 12:42:36 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 14 12:42:36 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 14 12:42:36 muzyka volumio[2488]: info: Received Get System Info Sep 14 12:42:36 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 14 12:42:36 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 14 12:42:36 muzyka volumio[2488]: info: Discovery: Getting this device information Sep 14 12:42:36 muzyka volumio[2488]: info: CoreCommandRouter::volumioGetState Sep 14 12:42:36 muzyka volumio[2488]: info: CorePlayQueue::getTrack 0 Sep 14 12:42:36 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 12:42:36 muzyka volumio[2488]: info: CoreCommandRouter::volumioGetState Sep 14 12:42:36 muzyka volumio[2488]: info: CorePlayQueue::getTrack 0 Sep 14 12:42:36 muzyka mpd[2810]: 2025-09-14T12:42:36 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 14 12:42:36 muzyka systemd[1]: Started mpd.service - Music Player Daemon. Sep 14 12:42:36 muzyka sudo[2794]: pam_unix(sudo:session): session closed for user root Sep 14 12:42:36 muzyka volumio[2488]: info: Listing playlists Sep 14 12:42:36 muzyka volumio[2488]: error: updateQueue error: null Sep 14 12:42:36 muzyka volumio[2488]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 12:42:36 muzyka volumio[2488]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 12:42:37 muzyka volumio[2488]: verbose: New Socket.io Connection to 192.168.0.101 from 192.168.0.72 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Sep 14 12:42:37 muzyka volumio[2488]: info: go-librespot daemon successfully initialized Sep 14 12:42:37 muzyka volumio[2488]: info: CoreCommandRouter::volumioGetState Sep 14 12:42:37 muzyka volumio[2488]: info: CorePlayQueue::getTrack 0 Sep 14 12:42:37 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 14 12:42:37 muzyka volumio[2488]: info: CoreCommandRouter::volumioGetVisibleSources Sep 14 12:42:37 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 12:42:37 muzyka volumio[2488]: info: CoreCommandRouter::volumioGetState Sep 14 12:42:37 muzyka volumio[2488]: info: CorePlayQueue::getTrack 0 Sep 14 12:42:37 muzyka volumio[2488]: info: CoreCommandRouter::volumioGetState Sep 14 12:42:37 muzyka volumio[2488]: info: CorePlayQueue::getTrack 0 Sep 14 12:42:37 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 14 12:42:37 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 14 12:42:37 muzyka volumio[2488]: info: Received Get System Info Sep 14 12:42:37 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 14 12:42:37 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 14 12:42:37 muzyka volumio[2488]: info: Discovery: Getting this device information Sep 14 12:42:37 muzyka volumio[2488]: info: CoreCommandRouter::volumioGetState Sep 14 12:42:37 muzyka volumio[2488]: info: CorePlayQueue::getTrack 0 Sep 14 12:42:37 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 12:42:37 muzyka volumio[2488]: info: CoreCommandRouter::volumioGetState Sep 14 12:42:37 muzyka volumio[2488]: info: CorePlayQueue::getTrack 0 Sep 14 12:42:37 muzyka volumio[2488]: info: Listing playlists Sep 14 12:42:37 muzyka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Sep 14 12:42:37 muzyka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 14 12:42:37 muzyka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 14 12:42:37 muzyka go-librespot[2818]: go-librespot daemon starting... Sep 14 12:42:37 muzyka go-librespot[2820]: time="2025-09-14T12:42:37+02:00" level=info msg="running go-librespot 0.3.2" Sep 14 12:42:37 muzyka go-librespot[2820]: time="2025-09-14T12:42:37+02:00" level=debug msg="app state loaded" Sep 14 12:42:37 muzyka go-librespot[2820]: time="2025-09-14T12:42:37+02:00" level=debug msg="stored credentials not found" Sep 14 12:42:37 muzyka go-librespot[2820]: time="2025-09-14T12:42:37+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 14 12:42:37 muzyka volumio[2488]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 12:42:37 muzyka go-librespot[2820]: time="2025-09-14T12:42:37+02: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]" Sep 14 12:42:37 muzyka go-librespot[2820]: time="2025-09-14T12:42:37+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 14 12:42:37 muzyka go-librespot[2820]: time="2025-09-14T12:42:37+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 14 12:42:37 muzyka go-librespot[2820]: time="2025-09-14T12:42:37+02:00" level=info msg="zeroconf server listening on port 43103" Sep 14 12:42:37 muzyka go-librespot[2820]: time="2025-09-14T12:42:37+02:00" level=debug msg="obtained new client token: AADbIeIb+NDhdkTV0BIh9gPkIqBkoy2+pSrSoys6oQ25cOazndELq9VeqKCQSWU9Mi+mU9HHJPa99YQUbnsSDx0GcKNrBYbRKDmUsud7yQmz31z959dGTjwo8raGnsnAw4E7wduMyHQ/zBESM63FbTKPXqcUQHHKNCK3Bbb1MkUh3mJ8Bxs+bRUwwSu9MmSnXKI54tgz9WobqGzpxoSH+t3rCwFJ8CDvPGPNfUIlMldCxAs/DUxP" Sep 14 12:42:38 muzyka go-librespot[2820]: time="2025-09-14T12:42:38+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 14 12:42:38 muzyka go-librespot[2820]: time="2025-09-14T12:42:38+02:00" level=debug msg="completed keyexchange" Sep 14 12:42:38 muzyka go-librespot[2820]: time="2025-09-14T12:42:38+02:00" level=debug msg="completed challenge" Sep 14 12:42:38 muzyka go-librespot[2820]: time="2025-09-14T12:42:38+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Sep 14 12:42:38 muzyka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 14 12:42:38 muzyka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 14 12:42:38 muzyka volumio[2488]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Sep 14 12:42:40 muzyka volumio[2488]: info: Initializing connection to go-librespot Websocket Sep 14 12:42:40 muzyka volumio[2488]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 14 12:42:41 muzyka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Sep 14 12:42:41 muzyka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 14 12:42:41 muzyka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 14 12:42:41 muzyka go-librespot[2841]: go-librespot daemon starting... Sep 14 12:42:41 muzyka go-librespot[2842]: time="2025-09-14T12:42:41+02:00" level=info msg="running go-librespot 0.3.2" Sep 14 12:42:41 muzyka go-librespot[2842]: time="2025-09-14T12:42:41+02:00" level=debug msg="app state loaded" Sep 14 12:42:41 muzyka go-librespot[2842]: time="2025-09-14T12:42:41+02:00" level=debug msg="stored credentials not found" Sep 14 12:42:41 muzyka go-librespot[2842]: time="2025-09-14T12:42:41+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 14 12:42:41 muzyka go-librespot[2842]: time="2025-09-14T12:42:41+02: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]" Sep 14 12:42:41 muzyka go-librespot[2842]: time="2025-09-14T12:42:41+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Sep 14 12:42:41 muzyka go-librespot[2842]: time="2025-09-14T12:42:41+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Sep 14 12:42:41 muzyka go-librespot[2842]: time="2025-09-14T12:42:41+02:00" level=info msg="zeroconf server listening on port 40915" Sep 14 12:42:41 muzyka volumio[2488]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 12:42:41 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 14 12:42:41 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 14 12:42:41 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 14 12:42:41 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 14 12:42:41 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 12:42:41 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 12:42:41 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 12:42:41 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 12:42:41 muzyka volumio[2488]: info: CoreCommandRouter::volumioGetBrowseSources Sep 14 12:42:41 muzyka volumio[2488]: info: CoreCommandRouter::volumioGetBrowseSources Sep 14 12:42:41 muzyka volumio[2488]: info: CoreCommandRouter::volumioGetBrowseSources Sep 14 12:42:41 muzyka go-librespot[2842]: time="2025-09-14T12:42:41+02:00" level=debug msg="obtained new client token: AABCnG5Osh8Fof1q9ktV23Chjpvh6zQ9vCQJf3dwiD/76iZQO9yL9TCx/J7lDrH8znaJhBbbVEdnTzMw0eOldDQGNgI3hcmDKcoKDPTlrbizReoQztxwZRXYfjwuLT3f6WsjJYzZYNUv0oo+kdf9bao7xvRJObOL1d7OgYYkY+EMwQ8a3qw8koS3tEN7Lu4T9OsX1lgFbOCfJ03cBE1frxMCpID6ug3GF0fXnq4OxefgXMPg4kJfqaU=" Sep 14 12:42:41 muzyka go-librespot[2842]: time="2025-09-14T12:42:41+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 14 12:42:41 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 12:42:41 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Sep 14 12:42:41 muzyka go-librespot[2842]: time="2025-09-14T12:42:41+02:00" level=debug msg="completed keyexchange" Sep 14 12:42:41 muzyka go-librespot[2842]: time="2025-09-14T12:42:41+02:00" level=debug msg="completed challenge" Sep 14 12:42:41 muzyka go-librespot[2842]: time="2025-09-14T12:42:41+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Sep 14 12:42:41 muzyka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 14 12:42:41 muzyka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 14 12:42:43 muzyka volumio[2488]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 14 12:42:43 muzyka volumio[2488]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 14 12:42:43 muzyka volumio[2488]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 14 12:42:43 muzyka volumio[2488]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 14 12:42:43 muzyka volumio[2488]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 14 12:42:43 muzyka volumio[2488]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 14 12:42:43 muzyka volumio[2488]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 14 12:42:43 muzyka volumio[2488]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 14 12:42:43 muzyka volumio[2488]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 14 12:42:43 muzyka volumio[2488]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 14 12:42:43 muzyka volumio[2488]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Sep 14 12:42:43 muzyka volumio[2488]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 14 12:42:43 muzyka volumio[2488]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 14 12:42:43 muzyka volumio[2488]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 14 12:42:43 muzyka volumio[2488]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 14 12:42:43 muzyka volumio[2488]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 14 12:42:43 muzyka volumio[2488]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 14 12:42:43 muzyka volumio[2488]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 14 12:42:43 muzyka volumio[2488]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 14 12:42:43 muzyka volumio[2488]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 14 12:42:43 muzyka volumio[2488]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 14 12:42:43 muzyka volumio[2488]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Sep 14 12:42:43 muzyka volumio[2488]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 14 12:42:43 muzyka volumio[2488]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 14 12:42:43 muzyka volumio[2488]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 14 12:42:43 muzyka volumio[2488]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 14 12:42:43 muzyka volumio[2488]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 14 12:42:43 muzyka volumio[2488]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 14 12:42:43 muzyka volumio[2488]: info: Adding plugin bluetooth to MyMusic Plugins Sep 14 12:42:43 muzyka volumio[2488]: info: Adding plugin multiroom to MyMusic Plugins Sep 14 12:42:43 muzyka volumio[2488]: info: Adding plugin metavolumio to MyMusic Plugins Sep 14 12:42:43 muzyka volumio[2488]: info: Adding plugin cd_controller to MyMusic Plugins Sep 14 12:42:43 muzyka volumio[2488]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 14 12:42:43 muzyka volumio[2488]: info: Adding plugin tidalconnect to MyMusic Plugins Sep 14 12:42:43 muzyka volumio[2488]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 14 12:42:44 muzyka volumio[2488]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 14 12:42:44 muzyka volumio[2488]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 14 12:42:44 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 12:42:44 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 12:42:44 muzyka volumio[2488]: info: Starting MyVolumio Remote Streaming Endpoints Sep 14 12:42:44 muzyka volumio[2488]: info: MyVolumio login type: Token Sep 14 12:42:44 muzyka volumio[2488]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 14 12:42:44 muzyka volumio[2488]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 14 12:42:45 muzyka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Sep 14 12:42:45 muzyka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 14 12:42:45 muzyka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 14 12:42:45 muzyka go-librespot[2850]: go-librespot daemon starting... Sep 14 12:42:45 muzyka go-librespot[2851]: time="2025-09-14T12:42:45+02:00" level=info msg="running go-librespot 0.3.2" Sep 14 12:42:45 muzyka go-librespot[2851]: time="2025-09-14T12:42:45+02:00" level=debug msg="app state loaded" Sep 14 12:42:45 muzyka go-librespot[2851]: time="2025-09-14T12:42:45+02:00" level=debug msg="stored credentials not found" Sep 14 12:42:45 muzyka go-librespot[2851]: time="2025-09-14T12:42:45+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 14 12:42:45 muzyka go-librespot[2851]: time="2025-09-14T12:42:45+02: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]" Sep 14 12:42:45 muzyka go-librespot[2851]: time="2025-09-14T12:42:45+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 14 12:42:45 muzyka go-librespot[2851]: time="2025-09-14T12:42:45+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 14 12:42:45 muzyka go-librespot[2851]: time="2025-09-14T12:42:45+02:00" level=info msg="zeroconf server listening on port 41279" Sep 14 12:42:46 muzyka volumio[2488]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 14 12:42:46 muzyka volumio[2488]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 14 12:42:46 muzyka volumio[2488]: info: Streaming services startup Sep 14 12:42:46 muzyka volumio[2488]: info: Starting Streaming Daemon Sep 14 12:42:46 muzyka volumio[2488]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Sep 14 12:42:46 muzyka sudo[2859]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 14 12:42:46 muzyka sudo[2859]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:42:46 muzyka sudo[2859]: pam_unix(sudo:session): session closed for user root Sep 14 12:42:46 muzyka volumio[2488]: info: Initializing connection to go-librespot Websocket Sep 14 12:42:46 muzyka volumio[2488]: info: AutoStart - Plugin is starting Sep 14 12:42:46 muzyka volumio[2488]: info: CoreCommandRouter::volumioGetQueue Sep 14 12:42:46 muzyka volumio[2488]: info: CoreStateMachine::getQueue Sep 14 12:42:46 muzyka volumio[2488]: info: CorePlayQueue::getQueue Sep 14 12:42:46 muzyka volumio[2488]: info: AutoStart - start playing Sep 14 12:42:46 muzyka volumio[2488]: info: AutoStart - start playing from position 7 Sep 14 12:42:46 muzyka volumio[2488]: info: CoreCommandRouter::volumioPlay Sep 14 12:42:46 muzyka volumio[2488]: info: CoreStateMachine::play index 7 Sep 14 12:42:46 muzyka volumio[2488]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 12:42:46 muzyka volumio[2488]: info: CoreStateMachine::stop Sep 14 12:42:46 muzyka volumio[2488]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 12:42:46 muzyka volumio[2488]: info: CoreStateMachine::play index undefined Sep 14 12:42:46 muzyka volumio[2488]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 12:42:46 muzyka volumio[2488]: info: CorePlayQueue::getTrack 7 Sep 14 12:42:46 muzyka volumio[2488]: info: CoreStateMachine::startPlaybackTimer Sep 14 12:42:46 muzyka volumio[2488]: info: CorePlayQueue::getTrack 7 Sep 14 12:42:46 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 12:42:46 muzyka volumio[2488]: error: Cannot start Volumio Streaming Daemon Sep 14 12:42:46 muzyka volumio[2488]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 14 12:42:46 muzyka volumio[2488]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 14 12:42:46 muzyka go-librespot[2851]: time="2025-09-14T12:42:46+02:00" level=debug msg="new websocket client" Sep 14 12:42:46 muzyka volumio[2488]: info: Connection to go-librespot Websocket established Sep 14 12:42:47 muzyka go-librespot[2851]: time="2025-09-14T12:42:47+02:00" level=debug msg="obtained new client token: AACiAODcMv/t7uqy6NpOhoIvLz03Wq7Y6sCOHGFeN8YzaaTNQ6rGGCPHOK+FePzscnPeWIHW2WPQEkjpVh5TY6t8fxERBPV4tEarFEud5NAd9ElCsZwsSGIiVyynt/boIx2tASv7LEjccxgqBS1EViyVdf4/U+51EGqM3C6FerYkwrt1u+0LlN8PVuR4Evkeip7uHc1Gn2Ddtj4fBAomGsi48ko9O80j4P+ivTqobHlzo485bVJOwh4=" Sep 14 12:42:47 muzyka go-librespot[2851]: time="2025-09-14T12:42:47+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 14 12:42:47 muzyka go-librespot[2851]: time="2025-09-14T12:42:47+02:00" level=debug msg="completed keyexchange" Sep 14 12:42:47 muzyka go-librespot[2851]: time="2025-09-14T12:42:47+02:00" level=debug msg="completed challenge" Sep 14 12:42:47 muzyka go-librespot[2851]: time="2025-09-14T12:42:47+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Sep 14 12:42:47 muzyka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 14 12:42:47 muzyka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 14 12:42:47 muzyka volumio[2488]: info: Connection to go-librespot Websocket closed Sep 14 12:42:47 muzyka volumio[2488]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Sep 14 12:42:48 muzyka volumio[2488]: info: MyVolumio token set successfully Sep 14 12:42:48 muzyka volumio[2488]: info: MYVOLUMIO: Adding device Sep 14 12:42:48 muzyka volumio[2488]: info: MYVOLUMIO: Evaluating Server Sep 14 12:42:48 muzyka volumio[2488]: info: MyVolumio Plan changed: premium Sep 14 12:42:48 muzyka volumio[2488]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Sep 14 12:42:48 muzyka volumio[2488]: info: Removing browser output: myVolumio user plan is not superstar Sep 14 12:42:48 muzyka volumio[2488]: info: Removing audio output: Sep 14 12:42:48 muzyka volumio[2488]: info: MYVOLUMIO: Adding device Sep 14 12:42:48 muzyka volumio[2488]: info: MYVOLUMIO: Evaluating Server Sep 14 12:42:49 muzyka volumio[2488]: info: Remote config written successfully Sep 14 12:42:49 muzyka volumio[2488]: info: Starting Tunnel 1 Sep 14 12:42:49 muzyka volumio[2488]: info: Starting Tunnel Connection Checker Sep 14 12:42:49 muzyka volumio[2488]: info: Completed starting MyVolumio Plugin Sep 14 12:42:49 muzyka volumio[2488]: info: MYVolumio Device enabled Sep 14 12:42:49 muzyka volumio[2488]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Sep 14 12:42:49 muzyka volumio[2488]: info: MyVolumio status changed Sep 14 12:42:49 muzyka volumio[2488]: info: Streaming services startup Sep 14 12:42:49 muzyka volumio[2488]: info: Starting Streaming Daemon Sep 14 12:42:49 muzyka volumio[2488]: info: Setting Geolocation for MyVolumio to eu6 Sep 14 12:42:49 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 12:42:49 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 12:42:49 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 12:42:49 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Sep 14 12:42:49 muzyka sudo[2898]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 14 12:42:49 muzyka sudo[2898]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:42:49 muzyka volumio[2488]: info: [MyVolumio PluginManager] Plugin music_service/qobuzconnect is enabled for this plan, but could not be found on the local filesystem! Sep 14 12:42:49 muzyka volumio[2488]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Sep 14 12:42:49 muzyka sudo[2898]: pam_unix(sudo:session): session closed for user root Sep 14 12:42:49 muzyka volumio[2488]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Sep 14 12:42:49 muzyka volumio[2488]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Sep 14 12:42:50 muzyka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Sep 14 12:42:50 muzyka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 14 12:42:51 muzyka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 14 12:42:51 muzyka go-librespot[2900]: go-librespot daemon starting... Sep 14 12:42:51 muzyka go-librespot[2909]: time="2025-09-14T12:42:51+02:00" level=info msg="running go-librespot 0.3.2" Sep 14 12:42:51 muzyka go-librespot[2909]: time="2025-09-14T12:42:51+02:00" level=debug msg="app state loaded" Sep 14 12:42:51 muzyka go-librespot[2909]: time="2025-09-14T12:42:51+02:00" level=debug msg="stored credentials not found" Sep 14 12:42:51 muzyka go-librespot[2909]: time="2025-09-14T12:42:51+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 14 12:42:51 muzyka go-librespot[2909]: time="2025-09-14T12:42:51+02: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]" Sep 14 12:42:51 muzyka go-librespot[2909]: time="2025-09-14T12:42:51+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 14 12:42:51 muzyka go-librespot[2909]: time="2025-09-14T12:42:51+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 14 12:42:51 muzyka go-librespot[2909]: time="2025-09-14T12:42:51+02:00" level=info msg="zeroconf server listening on port 38379" Sep 14 12:42:51 muzyka volumio[2488]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Sep 14 12:42:51 muzyka sudo[2924]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Sep 14 12:42:51 muzyka sudo[2924]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:42:51 muzyka sudo[2924]: pam_unix(sudo:session): session closed for user root Sep 14 12:42:51 muzyka go-librespot[2909]: time="2025-09-14T12:42:51+02:00" level=debug msg="obtained new client token: AAA5IxkpUwGnu1tnXqOFi4Uwbgfs+f0lcpOhTRuEPF2Txpik26qMiUWRNINp75/wtPxt4E24Ote5UGj3DQpfftSoc7y0Gmu7LKET2LKAHir7jDONiyularQ1kbdLgEhA61W19i/BP7uP7cxFreiKQwFkylSeLbh/FbBlXDfGGQJGSAHTx1buaudfym5OEJc6IyfLEsWb/I4YFVTptx5sLX1pGq0YnFP/bztRF4X8VfUj4Pq+AGL5EwE=" Sep 14 12:42:51 muzyka volumio[2488]: /usr/bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Sep 14 12:42:51 muzyka go-librespot[2909]: time="2025-09-14T12:42:51+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 14 12:42:51 muzyka volumio[2488]: info: MRS: MultiRoom plugin initialized Sep 14 12:42:51 muzyka volumio[2488]: info: MRS: STOPPING SNAPCLIENT Sep 14 12:42:51 muzyka volumio[2488]: info: MRS: Snap server stop Sep 14 12:42:51 muzyka sudo[2941]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Sep 14 12:42:51 muzyka sudo[2941]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:42:51 muzyka volumio[2488]: info: MRS: STOPPING volumioStreaming Sep 14 12:42:51 muzyka go-librespot[2909]: time="2025-09-14T12:42:51+02:00" level=debug msg="completed keyexchange" Sep 14 12:42:51 muzyka go-librespot[2909]: time="2025-09-14T12:42:51+02:00" level=debug msg="completed challenge" Sep 14 12:42:51 muzyka sudo[2943]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Sep 14 12:42:51 muzyka sudo[2943]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:42:51 muzyka volumio[2488]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Sep 14 12:42:51 muzyka sudo[2946]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Sep 14 12:42:51 muzyka sudo[2946]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:42:51 muzyka sudo[2949]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Sep 14 12:42:51 muzyka sudo[2949]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:42:51 muzyka sudo[2949]: pam_unix(sudo:session): session closed for user root Sep 14 12:42:51 muzyka volumio[2488]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Sep 14 12:42:51 muzyka sudo[2941]: pam_unix(sudo:session): session closed for user root Sep 14 12:42:51 muzyka sudo[2946]: pam_unix(sudo:session): session closed for user root Sep 14 12:42:51 muzyka sudo[2943]: pam_unix(sudo:session): session closed for user root Sep 14 12:42:51 muzyka volumio[2488]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Sep 14 12:42:51 muzyka volumio[2488]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Sep 14 12:42:51 muzyka volumio[2488]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Sep 14 12:42:51 muzyka volumio[2488]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Sep 14 12:42:51 muzyka volumio[2488]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Sep 14 12:42:51 muzyka volumio[2488]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Sep 14 12:42:51 muzyka volumio[2488]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Sep 14 12:42:51 muzyka volumio[2488]: info: Preparing to generate the ALSA configuration file Sep 14 12:42:52 muzyka volumio[2488]: info: Getting Spotify volume Sep 14 12:42:52 muzyka volumio[2488]: info: Initializing connection to go-librespot Websocket Sep 14 12:42:52 muzyka volumio[2488]: info: Updating MyVolumio device info Sep 14 12:42:52 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 12:42:52 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 12:42:52 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 12:42:52 muzyka go-librespot[2909]: time="2025-09-14T12:42:52+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Sep 14 12:42:52 muzyka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 14 12:42:52 muzyka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 14 12:42:52 muzyka volumio[2488]: info: Setting Geolocation for MyVolumio to eu10 Sep 14 12:42:52 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 12:42:52 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 12:42:52 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 12:42:52 muzyka volumio[2488]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 12:42:52 muzyka volumio[2488]: info: Successfully Added MyVolumio device Sep 14 12:42:52 muzyka volumio[2488]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Sep 14 12:42:52 muzyka volumio[2488]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 14 12:42:52 muzyka volumio[2488]: info: Reading ALSA contributions from plugins. Sep 14 12:42:52 muzyka volumio[2488]: info: MRS: Removed streaming files Sep 14 12:42:52 muzyka volumio[2488]: info: MRS: volumioStreaming STOPPED Sep 14 12:42:52 muzyka volumio[2488]: info: MRS: SNAPSERVER STOPPED Sep 14 12:42:52 muzyka volumio[2488]: info: MRS: SNAPCLIENT STOPPED Sep 14 12:42:52 muzyka volumio[2488]: error: Cannot start Volumio Streaming Daemon Sep 14 12:42:52 muzyka volumio[2488]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 14 12:42:52 muzyka volumio[2488]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 14 12:42:52 muzyka volumio[2488]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 12:42:52 muzyka volumio[2488]: Error: socket hang up Sep 14 12:42:52 muzyka volumio[2488]: at connResetException (node:internal/errors:720:14) Sep 14 12:42:52 muzyka volumio[2488]: at Socket.socketOnEnd (node:_http_client:519:23) Sep 14 12:42:52 muzyka volumio[2488]: at Socket.emit (node:events:526:35) Sep 14 12:42:52 muzyka volumio[2488]: at endReadableNT (node:internal/streams/readable:1376:12) Sep 14 12:42:52 muzyka volumio[2488]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Sep 14 12:42:52 muzyka volumio[2488]: code: 'ECONNRESET', Sep 14 12:42:52 muzyka volumio[2488]: response: undefined Sep 14 12:42:52 muzyka volumio[2488]: } Sep 14 12:42:52 muzyka volumio[2488]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 12:42:54 muzyka sudo[2966]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-09-14 12:41' Sep 14 12:42:54 muzyka sudo[2966]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:42:54 muzyka sudo[2966]: pam_unix(sudo:session): session closed for user root Sep 14 12:42:55 muzyka systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Sep 14 12:42:55 muzyka dbus-daemon[643]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.42' (uid=0 pid=2975 comm="timedatectl show --property=NTPSynchronized --valu") Sep 14 12:42:55 muzyka systemd[1]: Starting systemd-timedated.service - Time & Date Service... Sep 14 12:42:55 muzyka volumio-remote-updater[665]: [2025-09-14 12:42:55] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 14 12:42:55 muzyka volumio-remote-updater[665]: [2025-09-14 12:42:55] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 14 12:42:55 muzyka systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 14 12:42:55 muzyka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Sep 14 12:42:55 muzyka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 14 12:42:55 muzyka dbus-daemon[643]: [system] Successfully activated service 'org.freedesktop.timedate1' Sep 14 12:42:55 muzyka setdatetime-helper.sh[2974]: Time is not synchronized. Attempting to sync... Sep 14 12:42:55 muzyka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 14 12:42:55 muzyka go-librespot[2979]: go-librespot daemon starting... Sep 14 12:42:55 muzyka systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 14 12:42:55 muzyka systemd[1]: volumio.service: Consumed 2min 11.593s CPU time. Sep 14 12:42:55 muzyka systemd[1]: Started systemd-timedated.service - Time & Date Service. Sep 14 12:42:55 muzyka systemd[1]: Started dynamicswap.service - dynamicswap service. Sep 14 12:42:55 muzyka go-librespot[2994]: time="2025-09-14T12:42:55+02:00" level=info msg="running go-librespot 0.3.2" Sep 14 12:42:55 muzyka go-librespot[2994]: time="2025-09-14T12:42:55+02:00" level=debug msg="app state loaded" Sep 14 12:42:55 muzyka go-librespot[2994]: time="2025-09-14T12:42:55+02:00" level=debug msg="stored credentials not found" Sep 14 12:42:55 muzyka go-librespot[2994]: time="2025-09-14T12:42:55+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 14 12:42:55 muzyka systemd[1]: dynamicswap.service: Deactivated successfully. Sep 14 12:42:55 muzyka sudo[3007]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Sun, 14 Sep 2025 10:42:55 GMT#015' Sep 14 12:42:55 muzyka sudo[3007]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 14 12:42:55 muzyka setdatetime-helper.sh[3008]: Sun Sep 14 12:42:55 CEST 2025 Sep 14 12:42:55 muzyka sudo[3007]: pam_unix(sudo:session): session closed for user root Sep 14 12:42:55 muzyka systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Sep 14 12:42:55 muzyka systemd-journald[338]: Time jumped backwards, rotating. Sep 14 12:42:55 muzyka setdatetime-helper.sh[2974]: Time synchronized successfully. Sep 14 12:42:55 muzyka systemd[1]: Started dynamicswap.service - dynamicswap service. Sep 14 12:42:55 muzyka systemd[1]: Stopped volumio.service - Volumio Backend Module. Sep 14 12:42:55 muzyka systemd[1]: volumio.service: Consumed 2min 11.593s CPU time. Sep 14 12:42:55 muzyka systemd[1]: Started volumio.service - Volumio Backend Module. Sep 14 12:42:55 muzyka systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Sep 14 12:42:55 muzyka systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Sep 14 12:42:55 muzyka systemd[1]: dynamicswap.service: Deactivated successfully. Sep 14 12:42:55 muzyka go-librespot[2994]: time="2025-09-14T12:42:55+02: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]" Sep 14 12:42:55 muzyka go-librespot[2994]: time="2025-09-14T12:42:55+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 14 12:42:55 muzyka go-librespot[2994]: time="2025-09-14T12:42:55+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 14 12:42:55 muzyka go-librespot[2994]: time="2025-09-14T12:42:55+02:00" level=info msg="zeroconf server listening on port 36703" Sep 14 12:42:55 muzyka go-librespot[2994]: time="2025-09-14T12:42:55+02:00" level=debug msg="obtained new client token: AADviOh+I42rUsK7HDLPLk5a2bWG0ztftLn/Y25NjWJ+IfO6r/+ocl0dkdmVR7PVE5/x8u4D4Jgs01/kEQPkg0dcLBdWboAfBFwQQAG/RunrrtiLx4DYGq/WuDC/toi1ndK6wuQCaT+0Bt7zEir6gOcbXEwsQ79OV8XoCbwPvzZ7CdKemAZFYTC9JzfqdsQZUt8/RT/8Kl0oJPoTJzhr+61GxAl+y/P2Z7uVXpPk8bvUnpaM733yRVM=" Sep 14 12:42:55 muzyka go-librespot[2994]: time="2025-09-14T12:42:55+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 14 12:42:55 muzyka go-librespot[2994]: time="2025-09-14T12:42:55+02:00" level=debug msg="completed keyexchange" Sep 14 12:42:55 muzyka go-librespot[2994]: time="2025-09-14T12:42:55+02:00" level=debug msg="completed challenge" Sep 14 12:42:55 muzyka go-librespot[2994]: time="2025-09-14T12:42:55+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Sep 14 12:42:55 muzyka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 14 12:42:55 muzyka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 14 12:42:56 muzyka volumio[3012]: info: ------------------------------------------- Sep 14 12:42:56 muzyka volumio[3012]: info: ----- Volumio3 ---- Sep 14 12:42:56 muzyka volumio[3012]: info: ------------------------------------------- Sep 14 12:42:56 muzyka volumio[3012]: info: ----- System startup ---- Sep 14 12:42:56 muzyka volumio[3012]: info: ------------------------------------------- Sep 14 12:42:56 muzyka volumio[3012]: info: MYVOLUMIO Environment detected Sep 14 12:42:56 muzyka volumio[3012]: info: Plugin folders cleanup Sep 14 12:42:56 muzyka volumio[3012]: info: Scanning into folder /volumio/app/plugins/ Sep 14 12:42:56 muzyka volumio[3012]: info: Scanning category audio_interface Sep 14 12:42:56 muzyka volumio[3012]: info: Scanning category miscellanea Sep 14 12:42:56 muzyka volumio[3012]: info: Scanning category music_service Sep 14 12:42:56 muzyka volumio[3012]: info: Scanning category plugins.json Sep 14 12:42:56 muzyka volumio[3012]: info: Scanning category system_controller Sep 14 12:42:56 muzyka volumio[3012]: info: Scanning category user_interface Sep 14 12:42:56 muzyka volumio[3012]: info: Scanning into folder /data/plugins/ Sep 14 12:42:56 muzyka volumio[3012]: info: Scanning category audio_interface Sep 14 12:42:56 muzyka volumio[3012]: info: Scanning category music_service Sep 14 12:42:56 muzyka volumio[3012]: info: Scanning category system_controller Sep 14 12:42:56 muzyka volumio[3012]: info: Scanning category user_interface Sep 14 12:42:56 muzyka volumio[3012]: info: Plugin folders cleanup completed Sep 14 12:42:56 muzyka volumio[3012]: info: ------------------------------------------- Sep 14 12:42:56 muzyka volumio[3012]: info: ----- Core plugins startup ---- Sep 14 12:42:56 muzyka volumio[3012]: info: ------------------------------------------- Sep 14 12:42:56 muzyka volumio[3012]: info: Loading plugins from folder /volumio/app/plugins/ Sep 14 12:42:56 muzyka volumio[3012]: info: Adding plugin upnp to MyMusic Plugins Sep 14 12:42:56 muzyka volumio[3012]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 14 12:42:56 muzyka volumio[3012]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 14 12:42:56 muzyka volumio[3012]: info: Loading plugins from folder /data/plugins/ Sep 14 12:42:56 muzyka volumio[3012]: info: Loading plugin "system"... Sep 14 12:42:56 muzyka volumio[3012]: info: Loading plugin "appearance"... Sep 14 12:42:57 muzyka volumio[3012]: info: Loading plugin "network"... Sep 14 12:42:57 muzyka volumio[3012]: info: Refreshing Cached IP Addresses Sep 14 12:42:57 muzyka sudo[3043]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 14 12:42:57 muzyka sudo[3043]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:42:57 muzyka sudo[3042]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 14 12:42:57 muzyka sudo[3042]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:42:57 muzyka volumio[3012]: info: Loading plugin "services"... Sep 14 12:42:57 muzyka volumio[3012]: info: Loading plugin "alsa_controller"... Sep 14 12:42:57 muzyka sudo[3043]: pam_unix(sudo:session): session closed for user root Sep 14 12:42:57 muzyka sudo[3042]: pam_unix(sudo:session): session closed for user root Sep 14 12:42:57 muzyka sudo[3046]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 14 12:42:57 muzyka sudo[3046]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:42:57 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 14 12:42:57 muzyka volumio[3012]: info: Loading plugin "wizard"... Sep 14 12:42:57 muzyka volumio[3012]: info: Loading plugin "networkfs"... Sep 14 12:42:57 muzyka volumio[3012]: info: Starting Udev Watcher for removable devices Sep 14 12:42:57 muzyka volumio[3012]: info: Ignoring mount for partition: boot Sep 14 12:42:57 muzyka volumio[3012]: info: Ignoring mount for partition: volumio Sep 14 12:42:57 muzyka volumio[3012]: info: Ignoring mount for partition: volumio_data Sep 14 12:42:57 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 14 12:42:57 muzyka volumio[3012]: info: Loading plugin "volumio_command_line_client"... Sep 14 12:42:57 muzyka volumio[3012]: info: Loading plugin "upnp"... Sep 14 12:42:57 muzyka volumio[3012]: info: [1757846577643] Starting Upmpd Daemon Sep 14 12:42:57 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 14 12:42:57 muzyka volumio[3012]: info: Loading plugin "my_music"... Sep 14 12:42:57 muzyka volumio[3012]: info: Loading plugin "mpd"... Sep 14 12:42:57 muzyka volumio[3012]: info: Plugin upnp_browser is not enabled Sep 14 12:42:57 muzyka volumio[3012]: info: Loading plugin "alarm-clock"... Sep 14 12:42:57 muzyka volumio[3012]: info: Plugin airplay_emulation is not enabled Sep 14 12:42:57 muzyka volumio[3012]: info: Loading plugin "last_100"... Sep 14 12:42:57 muzyka volumio[3012]: info: Loading plugin "webradio"... Sep 14 12:42:58 muzyka volumio[3012]: info: Loading plugin "i2s_dacs"... Sep 14 12:42:58 muzyka volumio[3012]: info: Loading plugin "volumiodiscovery"... Sep 14 12:42:58 muzyka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Sep 14 12:42:58 muzyka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 14 12:42:58 muzyka volumio[3012]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 14 12:42:58 muzyka volumio[3012]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 14 12:42:58 muzyka volumio[3012]: *** WARNING *** For more information see Sep 14 12:42:58 muzyka volumio[3012]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 14 12:42:58 muzyka volumio[3012]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 14 12:42:58 muzyka volumio[3012]: *** WARNING *** For more information see Sep 14 12:42:58 muzyka node[3012]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 14 12:42:58 muzyka node[3012]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 14 12:42:58 muzyka node[3012]: *** WARNING *** For more information see Sep 14 12:42:58 muzyka node[3012]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 14 12:42:58 muzyka node[3012]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 14 12:42:58 muzyka node[3012]: *** WARNING *** For more information see Sep 14 12:42:58 muzyka volumio[3012]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 14 12:42:58 muzyka volumio[3012]: info: Discovery: Started advertising with name: muzyka Sep 14 12:42:58 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 14 12:42:58 muzyka volumio[3012]: info: Loading plugin "spop"... Sep 14 12:42:58 muzyka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 14 12:42:58 muzyka go-librespot[3071]: go-librespot daemon starting... Sep 14 12:42:58 muzyka go-librespot[3074]: time="2025-09-14T12:42:58+02:00" level=info msg="running go-librespot 0.3.2" Sep 14 12:42:58 muzyka go-librespot[3074]: time="2025-09-14T12:42:58+02:00" level=debug msg="app state loaded" Sep 14 12:42:58 muzyka go-librespot[3074]: time="2025-09-14T12:42:58+02:00" level=debug msg="stored credentials not found" Sep 14 12:42:58 muzyka go-librespot[3074]: time="2025-09-14T12:42:58+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 14 12:42:59 muzyka volumio[3012]: info: Loading plugin "ytmusic"... Sep 14 12:42:59 muzyka go-librespot[3074]: time="2025-09-14T12:42:59+02: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]" Sep 14 12:42:59 muzyka go-librespot[3074]: time="2025-09-14T12:42:59+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 14 12:42:59 muzyka go-librespot[3074]: time="2025-09-14T12:42:59+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 14 12:42:59 muzyka go-librespot[3074]: time="2025-09-14T12:42:59+02:00" level=info msg="zeroconf server listening on port 36577" Sep 14 12:42:59 muzyka volumio-remote-updater[665]: [2025-09-14 12:42:59] [connect] Successful connection Sep 14 12:43:00 muzyka go-librespot[3074]: time="2025-09-14T12:43:00+02:00" level=debug msg="obtained new client token: AACCveAJWUt4e/9srQY3w1vVarsmN/eoaxtQxR2Nw+9Y85e/SAdJJTyb0JilXp+h4Zx7Ub0PoMvH36qXUQr70vcEO9SN5S8Slbwrc4C80SryyN5fjCIWYw87zytT8UsOEcKrxuxpSBA4hgrNu8ha34ry/z/AJzxWWB6MiHiZRrofZyiZNHUcZpNZNV7kx5ihcpXFHd5aXDSCe8ox/c111p8jZIgkK4TEfVgeRs/ftbPmnXHEd5xwC5A=" Sep 14 12:43:00 muzyka go-librespot[3074]: time="2025-09-14T12:43:00+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 14 12:43:00 muzyka go-librespot[3074]: time="2025-09-14T12:43:00+02:00" level=debug msg="completed keyexchange" Sep 14 12:43:00 muzyka go-librespot[3074]: time="2025-09-14T12:43:00+02:00" level=debug msg="completed challenge" Sep 14 12:43:00 muzyka go-librespot[3074]: time="2025-09-14T12:43:00+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Sep 14 12:43:00 muzyka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 14 12:43:00 muzyka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 14 12:43:00 muzyka volumio[3012]: info: Loading plugin "autostart"... Sep 14 12:43:01 muzyka volumio[3012]: info: Applying required configuration parameters for plugin autostart Sep 14 12:43:01 muzyka volumio[3012]: info: AutoStart - onVolumioStart - read config.json Sep 14 12:43:01 muzyka volumio[3012]: info: Loading plugin "outputs"... Sep 14 12:43:01 muzyka volumio[3012]: info: Loading plugin "albumart"... Sep 14 12:43:01 muzyka volumio[3012]: info: Plugin example_plugin is not enabled Sep 14 12:43:01 muzyka volumio[3012]: info: Loading plugin "inputs"... Sep 14 12:43:01 muzyka volumio[3012]: info: Loading plugin "updater_comm"... Sep 14 12:43:01 muzyka volumio[3012]: info: Plugin mpdemulation is not enabled Sep 14 12:43:01 muzyka volumio[3012]: info: Loading plugin "rest_api"... Sep 14 12:43:01 muzyka volumio[3012]: info: Loading plugin "websocket"... Sep 14 12:43:01 muzyka volumio[3012]: info: Starting Socket.io Server version 1.7.4 Sep 14 12:43:01 muzyka volumio[3012]: info: Loading plugin "fusiondsp"... Sep 14 12:43:01 muzyka sudo[3046]: pam_unix(sudo:session): session closed for user root Sep 14 12:43:01 muzyka volumio[3081]: Forking 3 albumart workers Sep 14 12:43:01 muzyka volumio[3012]: info: Applying required configuration parameters for plugin fusiondsp Sep 14 12:43:02 muzyka volumio[3012]: info: Plugin mpdoutput is not enabled Sep 14 12:43:02 muzyka volumio[3012]: info: Loading plugin "80s80s"... Sep 14 12:43:02 muzyka volumio[3012]: info: Applying required configuration parameters for plugin 80s80s Sep 14 12:43:02 muzyka volumio[3012]: info: [1757846582941] [80s80s] API delay: 5 Sep 14 12:43:02 muzyka volumio[3012]: info: Loading plugin "backup_restore"... Sep 14 12:43:03 muzyka volumio[3091]: Starting albumart workers Sep 14 12:43:03 muzyka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Sep 14 12:43:03 muzyka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 14 12:43:03 muzyka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 14 12:43:03 muzyka go-librespot[3121]: go-librespot daemon starting... Sep 14 12:43:03 muzyka go-librespot[3122]: time="2025-09-14T12:43:03+02:00" level=info msg="running go-librespot 0.3.2" Sep 14 12:43:03 muzyka go-librespot[3122]: time="2025-09-14T12:43:03+02:00" level=debug msg="app state loaded" Sep 14 12:43:03 muzyka go-librespot[3122]: time="2025-09-14T12:43:03+02:00" level=debug msg="stored credentials not found" Sep 14 12:43:03 muzyka go-librespot[3122]: time="2025-09-14T12:43:03+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 14 12:43:03 muzyka volumio[3012]: info: Applying required configuration parameters for plugin backup_restore Sep 14 12:43:03 muzyka volumio[3012]: info: Loading plugin "Systeminfo"... Sep 14 12:43:04 muzyka go-librespot[3122]: time="2025-09-14T12:43:04+02: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]" Sep 14 12:43:04 muzyka go-librespot[3122]: time="2025-09-14T12:43:04+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Sep 14 12:43:04 muzyka go-librespot[3122]: time="2025-09-14T12:43:04+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Sep 14 12:43:04 muzyka go-librespot[3122]: time="2025-09-14T12:43:04+02:00" level=info msg="zeroconf server listening on port 42037" Sep 14 12:43:04 muzyka go-librespot[3122]: time="2025-09-14T12:43:04+02:00" level=debug msg="obtained new client token: AACr64v08n2xCp56GFzhy75lpRFoAymxCEdZj3dwjNYOa/QveM9ZUjLpf7LD4g3w/mL48ZaBgoEO4VKLDpB9QijoTrDqmNRDco9M5Dvt7kRFEW8DN8r1CfFjaYdClVeHkOuadxvwqaBzhGHHf3O/zwocq/waWWB6p7325GLTACmsooamUDsKxvYMyAY/GGoeg0UxHBPCoo52KcDFX3Rq2egFP79q/yFwG0wRdw/FDIzIEzmmzj6l5Ck=" Sep 14 12:43:04 muzyka go-librespot[3122]: time="2025-09-14T12:43:04+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 14 12:43:04 muzyka go-librespot[3122]: time="2025-09-14T12:43:04+02:00" level=debug msg="completed keyexchange" Sep 14 12:43:04 muzyka go-librespot[3122]: time="2025-09-14T12:43:04+02:00" level=debug msg="completed challenge" Sep 14 12:43:04 muzyka go-librespot[3122]: time="2025-09-14T12:43:04+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Sep 14 12:43:04 muzyka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 14 12:43:04 muzyka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 14 12:43:04 muzyka volumio[3012]: info: Loading plugin "touch_display"... Sep 14 12:43:04 muzyka volumio[3093]: Starting albumart workers Sep 14 12:43:04 muzyka volumio[3092]: Starting albumart workers Sep 14 12:43:05 muzyka volumio[3012]: info: Applying required configuration parameters for plugin touch_display Sep 14 12:43:05 muzyka volumio[3012]: info: Loading i18n strings for locale pl Sep 14 12:43:05 muzyka volumio[3012]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Sep 14 12:43:05 muzyka volumio[3012]: Updating browse sources language Sep 14 12:43:05 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 12:43:06 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 12:43:06 muzyka volumio[3012]: info: CoreCommandRouter::initPlayerControls Sep 14 12:43:06 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:43:06 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:43:06 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:43:06 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:43:06 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:43:06 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:43:06 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:43:06 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:43:06 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 12:43:06 muzyka volumio[3012]: Express server listening on port 3000 Sep 14 12:43:06 muzyka volumio[3012]: [Metrics] WebUI: 10s 250.03ms Sep 14 12:43:06 muzyka volumio[3012]: info: CoreStateMachine::resetVolumioState Sep 14 12:43:06 muzyka volumio[3012]: info: CoreStateMachine::getcurrentVolume Sep 14 12:43:06 muzyka volumio[3012]: info: CoreCommandRouter::volumioRetrievevolume Sep 14 12:43:06 muzyka volumio[3012]: info: CoreStateMachine::pushState Sep 14 12:43:06 muzyka volumio[3012]: info: CorePlayQueue::getTrack 0 Sep 14 12:43:06 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 12:43:06 muzyka volumio[3012]: info: CoreCommandRouter::volumioPushState Sep 14 12:43:06 muzyka volumio[3012]: info: Volumio Network Manager: Network status updated: 2 Sep 14 12:43:06 muzyka volumio[3012]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Sep 14 12:43:06 muzyka volumio[3012]: verbose: New Socket.io Connection to 192.168.0.101 from 192.168.0.72 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Sep 14 12:43:06 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 12:43:06 muzyka volumio[3012]: info: Reloading queue from file Sep 14 12:43:06 muzyka volumio[3012]: verbose: New Socket.io Connection to 192.168.0.101 from 192.168.0.72 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Sep 14 12:43:06 muzyka volumio[3012]: info: CoreStateMachine::setRepeat false single undefined Sep 14 12:43:06 muzyka volumio[3012]: info: CoreStateMachine::pushState Sep 14 12:43:06 muzyka volumio[3012]: info: CorePlayQueue::getTrack 0 Sep 14 12:43:06 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 12:43:06 muzyka volumio[3012]: info: CoreCommandRouter::volumioPushState Sep 14 12:43:06 muzyka volumio[3012]: info: CoreStateMachine::setRandom null Sep 14 12:43:06 muzyka volumio[3012]: info: CoreStateMachine::pushState Sep 14 12:43:06 muzyka volumio[3012]: info: CorePlayQueue::getTrack 0 Sep 14 12:43:06 muzyka volumio[3012]: info: CoreCommandRouter::volumioPushState Sep 14 12:43:06 muzyka volumio[3012]: info: Setting Device type: Raspberry PI Sep 14 12:43:06 muzyka volumio[3012]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Sep 14 12:43:06 muzyka volumio[3012]: info: Completed loading Core Plugins Sep 14 12:43:06 muzyka volumio[3012]: info: Preparing to generate the ALSA configuration file Sep 14 12:43:06 muzyka volumio[3012]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Sep 14 12:43:06 muzyka volumio[3012]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03114 Sep 14 12:43:06 muzyka volumio[3012]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Sep 14 12:43:06 muzyka volumio[3012]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Sep 14 12:43:06 muzyka volumio[3012]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 14 12:43:06 muzyka volumio[3012]: info: Reading ALSA contributions from plugins. Sep 14 12:43:07 muzyka volumio[3012]: verbose: New Socket.io Connection to 192.168.0.101 from 192.168.0.72 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Sep 14 12:43:07 muzyka volumio[3012]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Sep 14 12:43:07 muzyka volumio[3012]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Sep 14 12:43:07 muzyka volumio[3012]: info: Discovery: adding 2ae8cc93-7fac-4f11-a512-4df746bfaae9 Sep 14 12:43:07 muzyka volumio[3012]: info: Discovery: Found device muzyka Sep 14 12:43:07 muzyka volumio[3012]: info: CoreCommandRouter::volumioGetState Sep 14 12:43:07 muzyka volumio[3012]: info: CorePlayQueue::getTrack 0 Sep 14 12:43:07 muzyka volumio[3012]: info: Discovery: this is already registered, 2ae8cc93-7fac-4f11-a512-4df746bfaae9 Sep 14 12:43:07 muzyka volumio[3012]: info: Discovery: Found device muzyka Sep 14 12:43:07 muzyka volumio[3012]: info: CoreCommandRouter::volumioGetState Sep 14 12:43:07 muzyka volumio[3012]: info: CorePlayQueue::getTrack 0 Sep 14 12:43:07 muzyka volumio[3012]: info: Asound.conf file unchanged, so no further update is needed Sep 14 12:43:07 muzyka volumio[3012]: info: Output device has changed, restarting MPD Sep 14 12:43:07 muzyka volumio[3012]: info: ___________ START PLUGINS ___________ Sep 14 12:43:07 muzyka volumio[3012]: info: ControllerMpd::onStart: Initializing MPD Sep 14 12:43:07 muzyka volumio[3012]: info: Creating MPD Configuration file Sep 14 12:43:07 muzyka sudo[3160]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 14 12:43:07 muzyka sudo[3160]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:43:07 muzyka sudo[3160]: pam_unix(sudo:session): session closed for user root Sep 14 12:43:07 muzyka sudo[3162]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 14 12:43:07 muzyka sudo[3162]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:43:07 muzyka volumio[3012]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 14 12:43:07 muzyka volumio[3012]: info: [1757846587459] CoreMusicLibrary::Adding element Last_100 Sep 14 12:43:07 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 12:43:07 muzyka volumio[3012]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 14 12:43:07 muzyka volumio[3012]: info: [1757846587461] CoreMusicLibrary::Adding element Webradio Sep 14 12:43:07 muzyka sudo[3165]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 14 12:43:07 muzyka sudo[3165]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:43:07 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 12:43:07 muzyka sudo[3165]: pam_unix(sudo:session): session closed for user root Sep 14 12:43:07 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 14 12:43:07 muzyka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Sep 14 12:43:07 muzyka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 14 12:43:07 muzyka volumio[3012]: info: Initializing BBC Radios Sep 14 12:43:07 muzyka sudo[3167]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 14 12:43:07 muzyka sudo[3167]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:43:07 muzyka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 14 12:43:07 muzyka go-librespot[3171]: go-librespot daemon starting... Sep 14 12:43:07 muzyka systemd[1]: Stopping mpd.service - Music Player Daemon... Sep 14 12:43:07 muzyka go-librespot[3180]: time="2025-09-14T12:43:07+02:00" level=info msg="running go-librespot 0.3.2" Sep 14 12:43:07 muzyka go-librespot[3180]: time="2025-09-14T12:43:07+02:00" level=debug msg="app state loaded" Sep 14 12:43:07 muzyka go-librespot[3180]: time="2025-09-14T12:43:07+02:00" level=debug msg="stored credentials not found" Sep 14 12:43:07 muzyka go-librespot[3180]: time="2025-09-14T12:43:07+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 14 12:43:07 muzyka systemd[1]: mpd.service: Deactivated successfully. Sep 14 12:43:07 muzyka systemd[1]: Stopped mpd.service - Music Player Daemon. Sep 14 12:43:07 muzyka systemd[1]: mpd.service: Consumed 1.529s CPU time. Sep 14 12:43:07 muzyka systemd[1]: mpd.socket: Deactivated successfully. Sep 14 12:43:07 muzyka systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Sep 14 12:43:07 muzyka systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Sep 14 12:43:07 muzyka systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Sep 14 12:43:07 muzyka systemd[1]: Starting mpd.service - Music Player Daemon... Sep 14 12:43:07 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 14 12:43:07 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 12:43:07 muzyka volumio[3012]: info: Creating Spotify config file Sep 14 12:43:07 muzyka sudo[3193]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 14 12:43:07 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:43:07 muzyka sudo[3193]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 14 12:43:07 muzyka sudo[3193]: pam_unix(sudo:session): session closed for user root Sep 14 12:43:07 muzyka go-librespot[3180]: time="2025-09-14T12:43:07+02: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]" Sep 14 12:43:07 muzyka go-librespot[3180]: time="2025-09-14T12:43:07+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Sep 14 12:43:07 muzyka go-librespot[3180]: time="2025-09-14T12:43:07+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Sep 14 12:43:07 muzyka go-librespot[3180]: time="2025-09-14T12:43:07+02:00" level=info msg="zeroconf server listening on port 39569" Sep 14 12:43:08 muzyka go-librespot[3180]: time="2025-09-14T12:43:08+02:00" level=debug msg="obtained new client token: AAB5nXYRTCFYk9GZI3wCKY8mMMZ5/lJEn+bomDvulkB7lKTu1TnN+THxGRL6+9OkVTH1sWVLAAnD06ZHPT2vn0ukFb4ExFJB4WnqvLPThkCBDEQKzmPZ1wEWnibv12blMFQwR5jSuN9UGm3ZmL6XDhzfp0/Q8IqU3LEnImkYIlN3+0rkgQJvhGRp9zb2LnI9UG9r/m8DDOiHw4vHg4fJk3HKvTGQEOQxiEHkKf3qQepuV1PptV15r3A=" Sep 14 12:43:08 muzyka go-librespot[3180]: time="2025-09-14T12:43:08+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 14 12:43:08 muzyka go-librespot[3180]: time="2025-09-14T12:43:08+02:00" level=debug msg="completed keyexchange" Sep 14 12:43:08 muzyka go-librespot[3180]: time="2025-09-14T12:43:08+02:00" level=debug msg="completed challenge" Sep 14 12:43:08 muzyka go-librespot[3180]: time="2025-09-14T12:43:08+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Sep 14 12:43:08 muzyka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 14 12:43:08 muzyka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 14 12:43:08 muzyka volumio[3012]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 14 12:43:08 muzyka volumio[3012]: info: [1757846588737] CoreMusicLibrary::Adding element YouTube Music Sep 14 12:43:08 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 12:43:08 muzyka volumio[3012]: Cannot find translation for source YouTube Music Sep 14 12:43:08 muzyka volumio[3012]: info: AutoStart - onStart Sep 14 12:43:08 muzyka volumio[3012]: info: Loading i18n strings for locale pl Sep 14 12:43:08 muzyka volumio[3012]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Sep 14 12:43:08 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 14 12:43:08 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:43:08 muzyka volumio[3012]: info: FusionDsp - mixtype--------------------- None Sep 14 12:43:08 muzyka volumio[3012]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 14 12:43:08 muzyka volumio[3012]: info: [1757846588926] CoreMusicLibrary::Adding element 80s80s Radio Sep 14 12:43:08 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 12:43:08 muzyka volumio[3012]: Cannot find translation for source YouTube Music Sep 14 12:43:08 muzyka volumio[3012]: Cannot find translation for source 80s80s Radio Sep 14 12:43:08 muzyka volumio[3012]: info: Loading i18n strings for locale pl Sep 14 12:43:09 muzyka volumio[3012]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Sep 14 12:43:09 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 14 12:43:09 muzyka volumio[3012]: info: Volumio Calling Home Sep 14 12:43:09 muzyka volumio[3012]: info: Preparing to generate the ALSA configuration file Sep 14 12:43:09 muzyka mpd[3208]: 2025-09-14T12:43:09 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 14 12:43:09 muzyka systemd[1]: Started mpd.service - Music Player Daemon. Sep 14 12:43:09 muzyka sudo[3167]: pam_unix(sudo:session): session closed for user root Sep 14 12:43:09 muzyka sudo[3162]: pam_unix(sudo:session): session closed for user root Sep 14 12:43:09 muzyka sudo[3216]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Sep 14 12:43:09 muzyka sudo[3216]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:43:09 muzyka sudo[3218]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Sep 14 12:43:09 muzyka sudo[3218]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:43:09 muzyka sudo[3221]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Sep 14 12:43:09 muzyka sudo[3221]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:43:09 muzyka systemd[1]: Reloading. Sep 14 12:43:09 muzyka sudo[3216]: pam_unix(sudo:session): session closed for user root Sep 14 12:43:09 muzyka sudo[3241]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 14 12:43:09 muzyka sudo[3241]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:43:09 muzyka sudo[3241]: pam_unix(sudo:session): session closed for user root Sep 14 12:43:09 muzyka volumio[3012]: verbose: New Socket.io Connection to 192.168.0.101 from 192.168.0.72 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Sep 14 12:43:09 muzyka volumio[3012]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Sep 14 12:43:09 muzyka volumio[3012]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 14 12:43:09 muzyka volumio[3012]: info: Reading ALSA contributions from plugins. Sep 14 12:43:09 muzyka volumio[3012]: info: touch_display: No backlight interface detected. Sep 14 12:43:10 muzyka sudo[3243]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 14 12:43:10 muzyka sudo[3243]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:43:10 muzyka sudo[3243]: pam_unix(sudo:session): session closed for user root Sep 14 12:43:10 muzyka volumio[3012]: info: touch_display: systemctl stop getty@tty1.service succeeded. Sep 14 12:43:10 muzyka volumio[3012]: info: MPD Permissions set Sep 14 12:43:10 muzyka volumio[3012]: info: MPD Permissions set Sep 14 12:43:10 muzyka volumio[3012]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Sep 14 12:43:10 muzyka volumio[3012]: info: Volumio called home Sep 14 12:43:10 muzyka volumio[3012]: info: Spotify config file written Sep 14 12:43:10 muzyka systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 14 12:43:10 muzyka systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 14 12:43:10 muzyka sudo[3249]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 14 12:43:10 muzyka sudo[3249]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:43:10 muzyka systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 14 12:43:10 muzyka systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 14 12:43:10 muzyka sudo[3218]: pam_unix(sudo:session): session closed for user root Sep 14 12:43:10 muzyka systemd[1]: Reloading. Sep 14 12:43:10 muzyka volumio[3012]: error: MPD error: The expression evaluated to a falsy value: Sep 14 12:43:10 muzyka volumio[3012]: assert.ok(self.idling) Sep 14 12:43:10 muzyka volumio[3012]: error: The expression evaluated to a falsy value: Sep 14 12:43:10 muzyka volumio[3012]: assert.ok(self.idling) Sep 14 12:43:10 muzyka volumio[3012]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Sep 14 12:43:10 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:43:10 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:43:10 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:43:10 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:43:10 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:43:10 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:43:10 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:43:10 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:43:10 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 12:43:10 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:43:10 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:43:10 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:43:10 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:43:10 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:43:10 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:43:10 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:43:10 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:43:10 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:43:10 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 12:43:10 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:43:10 muzyka volumio[3012]: info: No need to fix Spotify hosts Sep 14 12:43:11 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 14 12:43:11 muzyka volumio[3012]: error: updateQueue error: null Sep 14 12:43:11 muzyka systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 14 12:43:11 muzyka systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 14 12:43:11 muzyka systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 14 12:43:11 muzyka systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 14 12:43:11 muzyka volumio[3012]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Sep 14 12:43:11 muzyka sudo[3221]: pam_unix(sudo:session): session closed for user root Sep 14 12:43:11 muzyka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Sep 14 12:43:11 muzyka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 14 12:43:11 muzyka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 14 12:43:11 muzyka go-librespot[3277]: go-librespot daemon starting... Sep 14 12:43:11 muzyka sudo[3249]: pam_unix(sudo:session): session closed for user root Sep 14 12:43:11 muzyka go-librespot[3278]: time="2025-09-14T12:43:11+02:00" level=info msg="running go-librespot 0.3.2" Sep 14 12:43:11 muzyka go-librespot[3278]: time="2025-09-14T12:43:11+02:00" level=debug msg="app state loaded" Sep 14 12:43:11 muzyka go-librespot[3278]: time="2025-09-14T12:43:11+02:00" level=debug msg="stored credentials not found" Sep 14 12:43:11 muzyka go-librespot[3278]: time="2025-09-14T12:43:11+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 14 12:43:11 muzyka volumio[3012]: info: touch_display: systemctl disable getty@tty1.service succeeded. Sep 14 12:43:11 muzyka volumio[3012]: info: MPD running with PID3208 Sep 14 12:43:11 muzyka volumio[3012]: ,establishing connection Sep 14 12:43:11 muzyka volumio[3012]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 14 12:43:11 muzyka volumio[3012]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Sep 14 12:43:11 muzyka volumio[3012]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 14 12:43:11 muzyka volumio[3012]: info: camilladsp service started and running in background, instance 1 Sep 14 12:43:11 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:43:11 muzyka go-librespot[3278]: time="2025-09-14T12:43:11+02: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]" Sep 14 12:43:11 muzyka go-librespot[3278]: time="2025-09-14T12:43:11+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 14 12:43:11 muzyka go-librespot[3278]: time="2025-09-14T12:43:11+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 14 12:43:11 muzyka volumio[3012]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 <-AAAAAAAAAAAAA Sep 14 12:43:11 muzyka go-librespot[3278]: time="2025-09-14T12:43:11+02:00" level=info msg="zeroconf server listening on port 39615" Sep 14 12:43:11 muzyka volumio[3012]: info: FusionDsp loaded Sep 14 12:43:11 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 12:43:11 muzyka volumio[3012]: error: FusionDsp - Socket connection not established Sep 14 12:43:11 muzyka sudo[3300]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 14 12:43:11 muzyka sudo[3300]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:43:11 muzyka volumio[3012]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 14 12:43:11 muzyka volumio[3012]: info: Adding Signal Path Element [object Object] Sep 14 12:43:11 muzyka volumio[3012]: info: Adding fusiondspeq DSP Signal Path Element Sep 14 12:43:11 muzyka volumio[3012]: info: FusionDsp - ---- installed callbackRead Sep 14 12:43:11 muzyka volumio[3012]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 14 12:43:11 muzyka sudo[3300]: pam_unix(sudo:session): session closed for user root Sep 14 12:43:12 muzyka go-librespot[3278]: time="2025-09-14T12:43:12+02:00" level=debug msg="obtained new client token: AAAvRR2AWGcOcGTCDtbSAy6G5Hj/AvM9oJflBmUMNYNHcH660KbJP/OVtiX48FL6AJoNc+Y3vo6ZZr8UuiYnKpslmK7XQLDacnx9RqSY07KIwn3aUHpAadeKEc+vFVyg6T+VJpfDa88Rq575eJS81+c2i7YBp+PJWqC7FW89ommfuMHfzd7zkQ69nC8D81V4PGKUfWhOP9uk03vK4H432EOBLiRNVbYvQWP+PUEF45VbjaxcSthI3A0=" Sep 14 12:43:12 muzyka go-librespot[3278]: time="2025-09-14T12:43:12+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 14 12:43:12 muzyka volumio[3012]: info: CoreCommandRouter::volumioGetVisibleSources Sep 14 12:43:12 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 12:43:12 muzyka volumio[3012]: info: CoreCommandRouter::volumioGetState Sep 14 12:43:12 muzyka volumio[3012]: info: CorePlayQueue::getTrack 0 Sep 14 12:43:12 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 14 12:43:12 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 14 12:43:12 muzyka volumio[3012]: info: Received Get System Info Sep 14 12:43:12 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 14 12:43:12 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 14 12:43:12 muzyka volumio[3012]: info: Discovery: Getting this device information Sep 14 12:43:12 muzyka volumio[3012]: info: CoreCommandRouter::volumioGetState Sep 14 12:43:12 muzyka volumio[3012]: info: CorePlayQueue::getTrack 0 Sep 14 12:43:12 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 12:43:12 muzyka volumio[3012]: info: CoreCommandRouter::volumioGetState Sep 14 12:43:12 muzyka volumio[3012]: info: CorePlayQueue::getTrack 0 Sep 14 12:43:12 muzyka volumio[3012]: info: Listing playlists Sep 14 12:43:12 muzyka volumio-remote-updater[665]: [2025-09-14 12:43:12] [connect] Successful connection Sep 14 12:43:12 muzyka volumio[3012]: info: touch_display: systemctl daemon-reload succeeded. Sep 14 12:43:12 muzyka volumio[3012]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Sep 14 12:43:12 muzyka volumio[3012]: info: An error occurred while refreshing Spotify Token Error: Bad Request Sep 14 12:43:12 muzyka sudo[3304]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Sep 14 12:43:12 muzyka sudo[3304]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:43:12 muzyka go-librespot[3278]: time="2025-09-14T12:43:12+02:00" level=debug msg="completed keyexchange" Sep 14 12:43:12 muzyka go-librespot[3278]: time="2025-09-14T12:43:12+02:00" level=debug msg="completed challenge" Sep 14 12:43:12 muzyka volumio[3012]: error: FusionDsp - Reload WebSocket error: [object Object] Sep 14 12:43:12 muzyka go-librespot[3278]: time="2025-09-14T12:43:12+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Sep 14 12:43:12 muzyka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 14 12:43:12 muzyka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 14 12:43:12 muzyka sudo[3306]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Sep 14 12:43:12 muzyka sudo[3306]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:43:12 muzyka sudo[3304]: pam_unix(sudo:session): session closed for user root Sep 14 12:43:12 muzyka sudo[3306]: pam_unix(sudo:session): session closed for user root Sep 14 12:43:12 muzyka volumio[3012]: error: updateQueue error: null Sep 14 12:43:12 muzyka sudo[3310]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 14 12:43:12 muzyka sudo[3310]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:43:12 muzyka volumio[3012]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Sep 14 12:43:12 muzyka volumio[3012]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Sep 14 12:43:12 muzyka volumio[3012]: info: touch_display: Volumio Kiosk started. Sep 14 12:43:12 muzyka sudo[3310]: pam_unix(sudo:session): session closed for user root Sep 14 12:43:12 muzyka volumio[3012]: info: CoreCommandRouter::volumioGetState Sep 14 12:43:12 muzyka volumio[3012]: info: CorePlayQueue::getTrack 0 Sep 14 12:43:12 muzyka volumio[3012]: info: Completed starting Core Plugins Sep 14 12:43:12 muzyka volumio[3012]: info: ------------------------------------------- Sep 14 12:43:12 muzyka volumio[3012]: info: ----- MyVolumio plugins startup ---- Sep 14 12:43:12 muzyka volumio[3012]: info: ------------------------------------------- Sep 14 12:43:12 muzyka volumio[3012]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 14 12:43:12 muzyka volumio[3012]: info: CoreCommandRouter::volumioGetState Sep 14 12:43:12 muzyka volumio[3012]: info: CorePlayQueue::getTrack 0 Sep 14 12:43:13 muzyka volumio[3012]: info: Upmpdcli Daemon Started Sep 14 12:43:13 muzyka volumio[3012]: info: Asound.conf file unchanged, so no further update is needed Sep 14 12:43:13 muzyka volumio[3012]: info: Output device has changed, restarting MPD Sep 14 12:43:13 muzyka volumio-remote-updater[665]: [2025-09-14 12:43:13] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1757846592 101 Sep 14 12:43:13 muzyka sudo[3315]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 14 12:43:13 muzyka sudo[3315]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:43:13 muzyka volumio[3012]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 12 Sep 14 12:43:13 muzyka volumio[3012]: info: touch_display: X display number found: 0 Sep 14 12:43:13 muzyka sudo[3315]: pam_unix(sudo:session): session closed for user root Sep 14 12:43:13 muzyka sudo[3317]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 14 12:43:13 muzyka sudo[3317]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:43:13 muzyka volumio[3012]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Sep 14 12:43:13 muzyka volumio[3012]: info: MPD Permissions set Sep 14 12:43:13 muzyka systemd[1]: Stopping mpd.service - Music Player Daemon... Sep 14 12:43:13 muzyka systemd[1]: mpd.service: Deactivated successfully. Sep 14 12:43:13 muzyka systemd[1]: Stopped mpd.service - Music Player Daemon. Sep 14 12:43:13 muzyka systemd[1]: mpd.service: Consumed 1.533s CPU time. Sep 14 12:43:13 muzyka systemd[1]: mpd.socket: Deactivated successfully. Sep 14 12:43:13 muzyka systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Sep 14 12:43:13 muzyka systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Sep 14 12:43:13 muzyka volumio[3012]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 12:43:13 muzyka volumio[3012]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 12:43:13 muzyka volumio[3012]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 12:43:13 muzyka volumio[3012]: error: Upnp client error: Error: This socket has been ended by the other party Sep 14 12:43:13 muzyka systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Sep 14 12:43:13 muzyka systemd[1]: Starting mpd.service - Music Player Daemon... Sep 14 12:43:13 muzyka volumio[3012]: info: touch_display: X display number found: 0 Sep 14 12:43:13 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:43:13 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:43:13 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:43:13 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:43:13 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:43:13 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:43:13 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:43:13 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:43:13 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 12:43:13 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 12:43:13 muzyka sudo[3327]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 14 12:43:13 muzyka sudo[3327]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 14 12:43:13 muzyka sudo[3327]: pam_unix(sudo:session): session closed for user root Sep 14 12:43:13 muzyka volumio[3012]: info: CoreCommandRouter::volumioGetState Sep 14 12:43:13 muzyka volumio[3012]: info: CorePlayQueue::getTrack 0 Sep 14 12:43:13 muzyka volumio[3012]: info: CoreCommandRouter::volumioGetState Sep 14 12:43:13 muzyka volumio[3012]: info: CorePlayQueue::getTrack 0 Sep 14 12:43:14 muzyka volumio[3012]: info: touch_display: Setting screensaver timeout to 120 seconds. Sep 14 12:43:14 muzyka mpd[3334]: 2025-09-14T12:43:14 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 14 12:43:14 muzyka systemd[1]: Started mpd.service - Music Player Daemon. Sep 14 12:43:14 muzyka sudo[3317]: pam_unix(sudo:session): session closed for user root Sep 14 12:43:15 muzyka volumio[3012]: verbose: New Socket.io Connection to 192.168.0.101 from 192.168.0.72 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Sep 14 12:43:15 muzyka volumio[3012]: error: updateQueue error: null Sep 14 12:43:15 muzyka volumio[3012]: info: go-librespot daemon successfully initialized Sep 14 12:43:15 muzyka volumio[3012]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Sep 14 12:43:15 muzyka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Sep 14 12:43:15 muzyka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 14 12:43:15 muzyka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 14 12:43:15 muzyka go-librespot[3339]: go-librespot daemon starting... Sep 14 12:43:15 muzyka go-librespot[3340]: time="2025-09-14T12:43:15+02:00" level=info msg="running go-librespot 0.3.2" Sep 14 12:43:15 muzyka go-librespot[3340]: time="2025-09-14T12:43:15+02:00" level=debug msg="app state loaded" Sep 14 12:43:15 muzyka go-librespot[3340]: time="2025-09-14T12:43:15+02:00" level=debug msg="stored credentials not found" Sep 14 12:43:15 muzyka go-librespot[3340]: time="2025-09-14T12:43:15+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 14 12:43:16 muzyka go-librespot[3340]: time="2025-09-14T12:43:16+02: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]" Sep 14 12:43:16 muzyka go-librespot[3340]: time="2025-09-14T12:43:16+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 14 12:43:16 muzyka go-librespot[3340]: time="2025-09-14T12:43:16+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 14 12:43:16 muzyka go-librespot[3340]: time="2025-09-14T12:43:16+02:00" level=info msg="zeroconf server listening on port 45905" Sep 14 12:43:16 muzyka go-librespot[3340]: time="2025-09-14T12:43:16+02:00" level=debug msg="obtained new client token: AABRhZwiU9NWj1WLmxjma9/abNIlKx5LRfpYXf1mPR1IxuIYPfEovTSTtgnCLAM2XHIAiiOQOXIq6a1VXFf5aMiUSX7aa+7rnUrWEIxIKeAkOwrwG5Ndn7jcoSQm/+wVJZG8cUUMytnS77LZZslbLTU7s1YFXyg8T5oiRnWQLYchCFTQP/jfNc7PrWPfpHG78FnqBgyfFA9Ts3VFJP1MY6zBpnsNlVdABV3/Ejsz7DHlXlmcoMiNv0A=" Sep 14 12:43:16 muzyka go-librespot[3340]: time="2025-09-14T12:43:16+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 14 12:43:16 muzyka go-librespot[3340]: time="2025-09-14T12:43:16+02:00" level=debug msg="completed keyexchange" Sep 14 12:43:16 muzyka go-librespot[3340]: time="2025-09-14T12:43:16+02:00" level=debug msg="completed challenge" Sep 14 12:43:16 muzyka go-librespot[3340]: time="2025-09-14T12:43:16+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Sep 14 12:43:16 muzyka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 14 12:43:16 muzyka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 14 12:43:18 muzyka volumio[3012]: info: Initializing connection to go-librespot Websocket Sep 14 12:43:18 muzyka volumio[3012]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 14 12:43:19 muzyka volumio[3012]: verbose: New Socket.io Connection to 192.168.0.101 from 192.168.0.72 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Sep 14 12:43:19 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 14 12:43:19 muzyka volumio[3012]: info: CoreCommandRouter::volumioGetVisibleSources Sep 14 12:43:19 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 12:43:19 muzyka volumio[3012]: info: CoreCommandRouter::volumioGetState Sep 14 12:43:19 muzyka volumio[3012]: info: CorePlayQueue::getTrack 0 Sep 14 12:43:19 muzyka volumio[3012]: info: CoreCommandRouter::volumioGetState Sep 14 12:43:19 muzyka volumio[3012]: info: CorePlayQueue::getTrack 0 Sep 14 12:43:19 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 14 12:43:19 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 14 12:43:19 muzyka volumio[3012]: info: Received Get System Info Sep 14 12:43:19 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 14 12:43:19 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 14 12:43:19 muzyka volumio[3012]: info: Discovery: Getting this device information Sep 14 12:43:19 muzyka volumio[3012]: info: CoreCommandRouter::volumioGetState Sep 14 12:43:19 muzyka volumio[3012]: info: CorePlayQueue::getTrack 0 Sep 14 12:43:19 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 12:43:19 muzyka volumio[3012]: info: CoreCommandRouter::volumioGetState Sep 14 12:43:19 muzyka volumio[3012]: info: CorePlayQueue::getTrack 0 Sep 14 12:43:19 muzyka volumio[3012]: info: Listing playlists Sep 14 12:43:19 muzyka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Sep 14 12:43:19 muzyka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 14 12:43:19 muzyka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 14 12:43:19 muzyka go-librespot[3361]: go-librespot daemon starting... Sep 14 12:43:19 muzyka go-librespot[3362]: time="2025-09-14T12:43:19+02:00" level=info msg="running go-librespot 0.3.2" Sep 14 12:43:19 muzyka go-librespot[3362]: time="2025-09-14T12:43:19+02:00" level=debug msg="app state loaded" Sep 14 12:43:19 muzyka go-librespot[3362]: time="2025-09-14T12:43:19+02:00" level=debug msg="stored credentials not found" Sep 14 12:43:19 muzyka go-librespot[3362]: time="2025-09-14T12:43:19+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 14 12:43:20 muzyka go-librespot[3362]: time="2025-09-14T12:43:20+02: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]" Sep 14 12:43:20 muzyka go-librespot[3362]: time="2025-09-14T12:43:20+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 14 12:43:20 muzyka go-librespot[3362]: time="2025-09-14T12:43:20+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 14 12:43:20 muzyka go-librespot[3362]: time="2025-09-14T12:43:20+02:00" level=info msg="zeroconf server listening on port 38109" Sep 14 12:43:20 muzyka volumio[3012]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 12:43:20 muzyka volumio[3012]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 12:43:21 muzyka go-librespot[3362]: time="2025-09-14T12:43:21+02:00" level=debug msg="obtained new client token: AABUzpXAnFely1/1X4QsNBJE1w7AXnj+y3ZTY+zEKNVV4IDwOuaP6EOATll/FBj/6Y1uCNPHQ9H/AAT+AnjP4I0S/LYZbZTzeAQMAkGWXblK4Wr9ihyXbqalAAEYZetQKKxfxbBl38SWv9AuhjegItE+Q4lwTbCnkH3QURzyhuA2vjoPHTiaCbxUD2m6D8BPZCnVnX6dzoUQ9D1hUka3VYXsmanaFkgOF3kI3JnkfDnbhlH3ujDz+XE=" Sep 14 12:43:21 muzyka go-librespot[3362]: time="2025-09-14T12:43:21+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 14 12:43:21 muzyka go-librespot[3362]: time="2025-09-14T12:43:21+02:00" level=debug msg="completed keyexchange" Sep 14 12:43:21 muzyka go-librespot[3362]: time="2025-09-14T12:43:21+02:00" level=debug msg="completed challenge" Sep 14 12:43:21 muzyka go-librespot[3362]: time="2025-09-14T12:43:21+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Sep 14 12:43:21 muzyka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 14 12:43:21 muzyka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 14 12:43:21 muzyka volumio[3012]: info: Initializing connection to go-librespot Websocket Sep 14 12:43:21 muzyka volumio[3012]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 14 12:43:21 muzyka volumio[3012]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 14 12:43:21 muzyka volumio[3012]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 14 12:43:21 muzyka volumio[3012]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 14 12:43:21 muzyka volumio[3012]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 14 12:43:21 muzyka volumio[3012]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 14 12:43:21 muzyka volumio[3012]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 14 12:43:21 muzyka volumio[3012]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 14 12:43:21 muzyka volumio[3012]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 14 12:43:21 muzyka volumio[3012]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 14 12:43:21 muzyka volumio[3012]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 14 12:43:21 muzyka volumio[3012]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Sep 14 12:43:21 muzyka volumio[3012]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 14 12:43:21 muzyka volumio[3012]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 14 12:43:21 muzyka volumio[3012]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 14 12:43:21 muzyka volumio[3012]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 14 12:43:21 muzyka volumio[3012]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 14 12:43:21 muzyka volumio[3012]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 14 12:43:21 muzyka volumio[3012]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 14 12:43:21 muzyka volumio[3012]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 14 12:43:21 muzyka volumio[3012]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 14 12:43:21 muzyka volumio[3012]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 14 12:43:21 muzyka volumio[3012]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Sep 14 12:43:21 muzyka volumio[3012]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 14 12:43:21 muzyka volumio[3012]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 14 12:43:21 muzyka volumio[3012]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 14 12:43:21 muzyka volumio[3012]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 14 12:43:21 muzyka volumio[3012]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 14 12:43:21 muzyka volumio[3012]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 14 12:43:21 muzyka volumio[3012]: info: Adding plugin bluetooth to MyMusic Plugins Sep 14 12:43:21 muzyka volumio[3012]: info: Adding plugin multiroom to MyMusic Plugins Sep 14 12:43:21 muzyka volumio[3012]: info: Adding plugin metavolumio to MyMusic Plugins Sep 14 12:43:21 muzyka volumio[3012]: info: Adding plugin cd_controller to MyMusic Plugins Sep 14 12:43:21 muzyka volumio[3012]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 14 12:43:21 muzyka volumio[3012]: info: Adding plugin tidalconnect to MyMusic Plugins Sep 14 12:43:21 muzyka volumio[3012]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 14 12:43:23 muzyka volumio[3012]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 14 12:43:23 muzyka volumio[3012]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 14 12:43:23 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 12:43:23 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 12:43:23 muzyka volumio[3012]: info: Starting MyVolumio Remote Streaming Endpoints Sep 14 12:43:23 muzyka volumio[3012]: info: MyVolumio login type: Token Sep 14 12:43:23 muzyka volumio[3012]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 14 12:43:23 muzyka volumio[3012]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 14 12:43:24 muzyka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Sep 14 12:43:24 muzyka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 14 12:43:24 muzyka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 14 12:43:24 muzyka go-librespot[3370]: go-librespot daemon starting... Sep 14 12:43:24 muzyka go-librespot[3371]: time="2025-09-14T12:43:24+02:00" level=info msg="running go-librespot 0.3.2" Sep 14 12:43:24 muzyka go-librespot[3371]: time="2025-09-14T12:43:24+02:00" level=debug msg="app state loaded" Sep 14 12:43:24 muzyka go-librespot[3371]: time="2025-09-14T12:43:24+02:00" level=debug msg="stored credentials not found" Sep 14 12:43:24 muzyka go-librespot[3371]: time="2025-09-14T12:43:24+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 14 12:43:24 muzyka volumio[3012]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 14 12:43:24 muzyka volumio[3012]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 14 12:43:24 muzyka volumio[3012]: info: Streaming services startup Sep 14 12:43:24 muzyka volumio[3012]: info: Starting Streaming Daemon Sep 14 12:43:24 muzyka sudo[3378]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 14 12:43:24 muzyka sudo[3378]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:43:24 muzyka volumio[3012]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Sep 14 12:43:24 muzyka sudo[3378]: pam_unix(sudo:session): session closed for user root Sep 14 12:43:24 muzyka volumio[3012]: info: AutoStart - Plugin is starting Sep 14 12:43:24 muzyka volumio[3012]: info: CoreCommandRouter::volumioGetQueue Sep 14 12:43:24 muzyka volumio[3012]: info: CoreStateMachine::getQueue Sep 14 12:43:24 muzyka volumio[3012]: info: CorePlayQueue::getQueue Sep 14 12:43:24 muzyka volumio[3012]: info: AutoStart - start playing Sep 14 12:43:24 muzyka volumio[3012]: info: AutoStart - start playing from position 7 Sep 14 12:43:24 muzyka volumio[3012]: info: CoreCommandRouter::volumioPlay Sep 14 12:43:24 muzyka volumio[3012]: info: CoreStateMachine::play index 7 Sep 14 12:43:24 muzyka volumio[3012]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 12:43:24 muzyka volumio[3012]: info: CoreStateMachine::stop Sep 14 12:43:24 muzyka volumio[3012]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 12:43:24 muzyka volumio[3012]: info: CoreStateMachine::play index undefined Sep 14 12:43:24 muzyka volumio[3012]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 12:43:24 muzyka volumio[3012]: info: CorePlayQueue::getTrack 7 Sep 14 12:43:24 muzyka volumio[3012]: info: CoreStateMachine::startPlaybackTimer Sep 14 12:43:24 muzyka volumio[3012]: info: CorePlayQueue::getTrack 7 Sep 14 12:43:24 muzyka volumio[3012]: info: Initializing connection to go-librespot Websocket Sep 14 12:43:24 muzyka volumio[3012]: error: Cannot start Volumio Streaming Daemon Sep 14 12:43:24 muzyka volumio[3012]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 14 12:43:24 muzyka volumio[3012]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 14 12:43:24 muzyka systemd[1]: systemd-timedated.service: Deactivated successfully. Sep 14 12:43:24 muzyka go-librespot[3371]: time="2025-09-14T12:43:24+02: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]" Sep 14 12:43:24 muzyka go-librespot[3371]: time="2025-09-14T12:43:24+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 14 12:43:24 muzyka go-librespot[3371]: time="2025-09-14T12:43:24+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 14 12:43:24 muzyka go-librespot[3371]: time="2025-09-14T12:43:24+02:00" level=info msg="zeroconf server listening on port 34679" Sep 14 12:43:24 muzyka go-librespot[3371]: time="2025-09-14T12:43:24+02:00" level=debug msg="new websocket client" Sep 14 12:43:24 muzyka volumio[3012]: info: Connection to go-librespot Websocket established Sep 14 12:43:25 muzyka go-librespot[3371]: time="2025-09-14T12:43:25+02:00" level=debug msg="obtained new client token: AAB6bfYi+5NDaSqymG/dXcvtAGaKcq7jO5yt4KTHiPINk7W32sratJfwoXB9O8fjeOzmrbspFZv/UwXPZp/cBTEZlad0ghPkxp6nEUwaGjEmipcjkWjU8PeA8ogxac7pLGF+LC2vemDXJ0Z1qsg2T2SALM+x/h8iPCK/UVBrgm0QL4yju3TyAVOF1MXiVYx5Nc4Qnlq0wnnyUBcBOAUhSr5PIBgL7QBDgYGZRPUkBSQp4XXco2USCgA=" Sep 14 12:43:25 muzyka go-librespot[3371]: time="2025-09-14T12:43:25+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 14 12:43:25 muzyka go-librespot[3371]: time="2025-09-14T12:43:25+02:00" level=debug msg="completed keyexchange" Sep 14 12:43:25 muzyka go-librespot[3371]: time="2025-09-14T12:43:25+02:00" level=debug msg="completed challenge" Sep 14 12:43:25 muzyka go-librespot[3371]: time="2025-09-14T12:43:25+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Sep 14 12:43:25 muzyka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 14 12:43:25 muzyka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 14 12:43:25 muzyka volumio[3012]: info: Connection to go-librespot Websocket closed Sep 14 12:43:26 muzyka volumio[3012]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Sep 14 12:43:26 muzyka volumio[3012]: info: MyVolumio token set successfully Sep 14 12:43:26 muzyka volumio[3012]: info: MYVOLUMIO: Adding device Sep 14 12:43:26 muzyka volumio[3012]: info: MYVOLUMIO: Evaluating Server Sep 14 12:43:27 muzyka volumio[3012]: info: MyVolumio Plan changed: premium Sep 14 12:43:27 muzyka volumio[3012]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Sep 14 12:43:27 muzyka volumio[3012]: info: Removing browser output: myVolumio user plan is not superstar Sep 14 12:43:27 muzyka volumio[3012]: info: Removing audio output: Sep 14 12:43:27 muzyka volumio[3012]: info: MYVOLUMIO: Adding device Sep 14 12:43:27 muzyka volumio[3012]: info: MYVOLUMIO: Evaluating Server Sep 14 12:43:27 muzyka volumio[3012]: info: Remote config written successfully Sep 14 12:43:27 muzyka volumio[3012]: info: Starting Tunnel 1 Sep 14 12:43:27 muzyka volumio[3012]: info: Starting Tunnel Connection Checker Sep 14 12:43:27 muzyka volumio[3012]: info: Completed starting MyVolumio Plugin Sep 14 12:43:27 muzyka volumio[3012]: info: MYVolumio Device enabled Sep 14 12:43:27 muzyka volumio[3012]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Sep 14 12:43:27 muzyka volumio[3012]: info: MyVolumio status changed Sep 14 12:43:27 muzyka volumio[3012]: info: Streaming services startup Sep 14 12:43:27 muzyka volumio[3012]: info: Starting Streaming Daemon Sep 14 12:43:27 muzyka sudo[3421]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 14 12:43:27 muzyka sudo[3421]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:43:27 muzyka sudo[3421]: pam_unix(sudo:session): session closed for user root Sep 14 12:43:27 muzyka volumio[3012]: info: Setting Geolocation for MyVolumio to eu7 Sep 14 12:43:27 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 12:43:27 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 12:43:27 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 12:43:27 muzyka volumio[3012]: error: Cannot start Volumio Streaming Daemon Sep 14 12:43:27 muzyka volumio[3012]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 14 12:43:27 muzyka volumio[3012]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 14 12:43:27 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Sep 14 12:43:27 muzyka volumio[3012]: info: [MyVolumio PluginManager] Plugin music_service/qobuzconnect is enabled for this plan, but could not be found on the local filesystem! Sep 14 12:43:27 muzyka volumio[3012]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Sep 14 12:43:28 muzyka volumio[3012]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Sep 14 12:43:28 muzyka volumio[3012]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Sep 14 12:43:28 muzyka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20. Sep 14 12:43:28 muzyka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 14 12:43:28 muzyka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 14 12:43:28 muzyka go-librespot[3437]: go-librespot daemon starting... Sep 14 12:43:28 muzyka go-librespot[3438]: time="2025-09-14T12:43:28+02:00" level=info msg="running go-librespot 0.3.2" Sep 14 12:43:28 muzyka go-librespot[3438]: time="2025-09-14T12:43:28+02:00" level=debug msg="app state loaded" Sep 14 12:43:28 muzyka go-librespot[3438]: time="2025-09-14T12:43:28+02:00" level=debug msg="stored credentials not found" Sep 14 12:43:28 muzyka go-librespot[3438]: time="2025-09-14T12:43:28+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 14 12:43:28 muzyka go-librespot[3438]: time="2025-09-14T12:43:28+02: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]" Sep 14 12:43:28 muzyka go-librespot[3438]: time="2025-09-14T12:43:28+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Sep 14 12:43:28 muzyka go-librespot[3438]: time="2025-09-14T12:43:28+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Sep 14 12:43:28 muzyka go-librespot[3438]: time="2025-09-14T12:43:28+02:00" level=info msg="zeroconf server listening on port 35005" Sep 14 12:43:29 muzyka go-librespot[3438]: time="2025-09-14T12:43:29+02:00" level=debug msg="obtained new client token: AADJ/1U4jAXPD5qVGaAw2qVC96Y1o6wZKBTf3CfFmAcfWl3IB3VXxQatg1t9rD4d/W9JfqCDkVkjemCLluLqq9q2FZbvJk5SL8o69brhECFfL8ZB91U6jG5hXSW40ba+moxQpN1Wa4lP0fg5jiPsF4nvnyOba7nX3R66Yn5D6C+tsus/XAWz5apQ5hBr7pAo0BF0pgxwS+IFyJeOmgABLsVgkik6rDvieXZ65rkHtFy7pX5TeSgfDj8=" Sep 14 12:43:29 muzyka go-librespot[3438]: time="2025-09-14T12:43:29+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 14 12:43:29 muzyka go-librespot[3438]: time="2025-09-14T12:43:29+02:00" level=debug msg="completed keyexchange" Sep 14 12:43:29 muzyka go-librespot[3438]: time="2025-09-14T12:43:29+02:00" level=debug msg="completed challenge" Sep 14 12:43:29 muzyka go-librespot[3438]: time="2025-09-14T12:43:29+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Sep 14 12:43:29 muzyka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 14 12:43:29 muzyka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 14 12:43:30 muzyka volumio[3012]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Sep 14 12:43:30 muzyka sudo[3449]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Sep 14 12:43:30 muzyka sudo[3449]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:43:30 muzyka sudo[3449]: pam_unix(sudo:session): session closed for user root Sep 14 12:43:30 muzyka volumio[3012]: /usr/bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Sep 14 12:43:30 muzyka volumio[3012]: info: MRS: MultiRoom plugin initialized Sep 14 12:43:30 muzyka volumio[3012]: info: MRS: STOPPING SNAPCLIENT Sep 14 12:43:30 muzyka volumio[3012]: info: MRS: Snap server stop Sep 14 12:43:30 muzyka volumio[3012]: info: MRS: STOPPING volumioStreaming Sep 14 12:43:30 muzyka sudo[3466]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Sep 14 12:43:30 muzyka sudo[3466]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:43:30 muzyka sudo[3468]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Sep 14 12:43:30 muzyka volumio[3012]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Sep 14 12:43:30 muzyka sudo[3471]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Sep 14 12:43:30 muzyka sudo[3471]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:43:30 muzyka sudo[3468]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:43:30 muzyka sudo[3473]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Sep 14 12:43:30 muzyka sudo[3473]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:43:30 muzyka sudo[3473]: pam_unix(sudo:session): session closed for user root Sep 14 12:43:30 muzyka sudo[3466]: pam_unix(sudo:session): session closed for user root Sep 14 12:43:30 muzyka sudo[3468]: pam_unix(sudo:session): session closed for user root Sep 14 12:43:30 muzyka volumio[3012]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Sep 14 12:43:30 muzyka sudo[3471]: pam_unix(sudo:session): session closed for user root Sep 14 12:43:30 muzyka volumio[3012]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Sep 14 12:43:30 muzyka volumio[3012]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Sep 14 12:43:30 muzyka volumio[3012]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Sep 14 12:43:30 muzyka volumio[3012]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Sep 14 12:43:30 muzyka volumio[3012]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Sep 14 12:43:30 muzyka volumio[3012]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Sep 14 12:43:30 muzyka volumio[3012]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Sep 14 12:43:31 muzyka volumio[3012]: info: Preparing to generate the ALSA configuration file Sep 14 12:43:31 muzyka volumio[3012]: info: Getting Spotify volume Sep 14 12:43:31 muzyka volumio[3012]: info: Initializing connection to go-librespot Websocket Sep 14 12:43:31 muzyka volumio[3012]: info: Updating MyVolumio device info Sep 14 12:43:31 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 12:43:31 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 12:43:31 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 12:43:31 muzyka volumio[3012]: info: Successfully Added MyVolumio device Sep 14 12:43:31 muzyka volumio[3012]: info: Setting Geolocation for MyVolumio to eu6 Sep 14 12:43:31 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 12:43:31 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 12:43:31 muzyka volumio[3012]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 12:43:31 muzyka volumio[3012]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Sep 14 12:43:31 muzyka volumio[3012]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 14 12:43:31 muzyka volumio[3012]: info: Reading ALSA contributions from plugins. Sep 14 12:43:31 muzyka volumio[3012]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 12:43:31 muzyka volumio[3012]: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 14 12:43:31 muzyka volumio[3012]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Sep 14 12:43:31 muzyka volumio[3012]: errno: -111, Sep 14 12:43:31 muzyka volumio[3012]: code: 'ECONNREFUSED', Sep 14 12:43:31 muzyka volumio[3012]: syscall: 'connect', Sep 14 12:43:31 muzyka volumio[3012]: address: '127.0.0.1', Sep 14 12:43:31 muzyka volumio[3012]: port: 9879, Sep 14 12:43:31 muzyka volumio[3012]: response: undefined Sep 14 12:43:31 muzyka volumio[3012]: } Sep 14 12:43:31 muzyka volumio[3012]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 12:43:32 muzyka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Sep 14 12:43:32 muzyka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 14 12:43:32 muzyka sudo[3492]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-09-14 12:42' Sep 14 12:43:32 muzyka sudo[3492]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 14 12:43:32 muzyka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 14 12:43:32 muzyka go-librespot[3491]: go-librespot daemon starting... Sep 14 12:43:32 muzyka go-librespot[3494]: time="2025-09-14T12:43:32+02:00" level=info msg="running go-librespot 0.3.2" Sep 14 12:43:32 muzyka go-librespot[3494]: time="2025-09-14T12:43:32+02:00" level=debug msg="app state loaded" Sep 14 12:43:32 muzyka go-librespot[3494]: time="2025-09-14T12:43:32+02:00" level=debug msg="stored credentials not found" Sep 14 12:43:32 muzyka go-librespot[3494]: time="2025-09-14T12:43:32+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 14 12:43:33 muzyka go-librespot[3494]: time="2025-09-14T12:43:33+02: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]" Sep 14 12:43:33 muzyka go-librespot[3494]: time="2025-09-14T12:43:33+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 14 12:43:33 muzyka go-librespot[3494]: time="2025-09-14T12:43:33+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 14 12:43:33 muzyka go-librespot[3494]: time="2025-09-14T12:43:33+02:00" level=info msg="zeroconf server listening on port 35835" PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="4a70cb031e64a8e498efc3e29470650871ff7d54" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat May 31 04:09:10 UTC 2025" VOLUMIO_VERSION="4.005" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="f9bd4447a30d4b2c74b3880e7a50e48b"