-- Logs begin at Fri 2025-09-05 15:46:42 CEST, end at Fri 2025-09-05 16:15:12 CEST. -- Sep 05 16:14:00 volumio volumio[16882]: info: Loading i18n strings for locale hu Sep 05 16:14:00 volumio volumio[16882]: Updating browse sources language Sep 05 16:14:00 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:14:00 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 16:14:00 volumio volumio[16882]: info: CoreCommandRouter::initPlayerControls Sep 05 16:14:00 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:00 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:00 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:00 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:00 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:00 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:00 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:00 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:00 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 16:14:00 volumio volumio[16882]: Express server listening on port 3000 Sep 05 16:14:00 volumio volumio[16882]: [Metrics] WebUI: 10s 927.10ms Sep 05 16:14:00 volumio volumio[16882]: info: CoreStateMachine::resetVolumioState Sep 05 16:14:00 volumio volumio[16882]: info: CoreStateMachine::getcurrentVolume Sep 05 16:14:00 volumio volumio[16882]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 16:14:00 volumio volumio[16882]: Starting albumart workers Sep 05 16:14:00 volumio volumio[16882]: Starting albumart workers Sep 05 16:14:00 volumio volumio[16882]: Starting albumart workers Sep 05 16:14:00 volumio volumio[16882]: info: Volumio Network Manager: Network status updated: 1 Sep 05 16:14:00 volumio volumio[16882]: info: CoreCommandRouter::volumioGetState Sep 05 16:14:00 volumio volumio[16882]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:00 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:14:01 volumio volumio[16882]: info: VolumeController:: Volume=37 Mute =false Sep 05 16:14:01 volumio volumio[16882]: info: CoreStateMachine::pushState Sep 05 16:14:01 volumio volumio[16882]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:01 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 16:14:01 volumio volumio[16882]: info: CoreCommandRouter::volumioPushState Sep 05 16:14:01 volumio volumio[16882]: info: CoreStateMachine::updateTrackBlock Sep 05 16:14:01 volumio volumio[16882]: info: CorePlayQueue::getTrackBlock Sep 05 16:14:01 volumio volumio[16882]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 16:14:01 volumio volumio[16882]: info: CoreStateMachine::setRepeat false single undefined Sep 05 16:14:01 volumio volumio[16882]: info: CoreStateMachine::pushState Sep 05 16:14:01 volumio volumio[16882]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:01 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 16:14:01 volumio volumio[16882]: info: CoreCommandRouter::volumioPushState Sep 05 16:14:01 volumio volumio[16882]: info: CoreStateMachine::setRandom true Sep 05 16:14:01 volumio volumio[16882]: info: CoreStateMachine::pushState Sep 05 16:14:01 volumio volumio[16882]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:01 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 16:14:01 volumio volumio[16882]: info: CoreCommandRouter::volumioPushState Sep 05 16:14:01 volumio volumio[16882]: info: Setting Device type: Raspberry PI Sep 05 16:14:01 volumio volumio[16882]: info: Listing playlists Sep 05 16:14:01 volumio volumio[16882]: info: Listing playlists Sep 05 16:14:01 volumio volumio[16882]: info: Completed loading Core Plugins Sep 05 16:14:01 volumio volumio[16882]: info: Preparing to generate the ALSA configuration file Sep 05 16:14:01 volumio volumio[16882]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227 Sep 05 16:14:01 volumio volumio[16882]: info: Discovery: Found device Volumio Sep 05 16:14:01 volumio volumio[16882]: info: CoreCommandRouter::volumioGetState Sep 05 16:14:01 volumio volumio[16882]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:01 volumio volumio[16882]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 05 16:14:01 volumio volumio[16882]: info: Reading ALSA contributions from plugins. Sep 05 16:14:01 volumio volumio[16882]: info: Asound.conf file unchanged, so no further update is needed Sep 05 16:14:01 volumio volumio[16882]: info: Output device has changed, restarting MPD Sep 05 16:14:01 volumio volumio[16882]: info: ___________ START PLUGINS ___________ Sep 05 16:14:01 volumio sudo[16984]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 16:14:01 volumio sudo[16984]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:01 volumio volumio[16882]: info: ControllerMpd::onStart: Initializing MPD Sep 05 16:14:01 volumio sudo[16984]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:01 volumio volumio[16882]: info: Creating MPD Configuration file Sep 05 16:14:01 volumio sudo[16990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 16:14:01 volumio sudo[16990]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:01 volumio systemd[1]: Stopping Music Player Daemon... Sep 05 16:14:01 volumio sudo[17003]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 16:14:01 volumio sudo[17003]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:01 volumio sudo[17003]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:01 volumio volumio[16882]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 16:14:01 volumio volumio[16882]: info: [1757081641517] CoreMusicLibrary::Adding element Last_100 Sep 05 16:14:01 volumio systemd[1]: mpd.service: Succeeded. Sep 05 16:14:01 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:14:01 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 16:14:01 volumio volumio[16882]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 16:14:01 volumio volumio[16882]: info: [1757081641525] CoreMusicLibrary::Adding element Webradio Sep 05 16:14:01 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:14:01 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 16:14:01 volumio volumio[16882]: info: Initializing BBC Radios Sep 05 16:14:01 volumio sudo[17012]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 16:14:01 volumio systemd[1]: Starting Music Player Daemon... Sep 05 16:14:01 volumio sudo[17012]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:01 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 05 16:14:01 volumio systemd[1]: mpd.service: Succeeded. Sep 05 16:14:01 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 16:14:01 volumio systemd[1]: Starting Music Player Daemon... Sep 05 16:14:01 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 16:14:01 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:14:01 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 16:14:01 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 05 16:14:01 volumio volumio[16882]: info: Discovery: Getting this device information Sep 05 16:14:01 volumio volumio[16882]: info: CoreCommandRouter::volumioGetState Sep 05 16:14:01 volumio volumio[16882]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:01 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 16:14:01 volumio volumio[16882]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 16:14:01 volumio volumio[16882]: info: [1757081641680] CoreMusicLibrary::Adding element Jellyfin Sep 05 16:14:01 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:14:01 volumio volumio[16882]: Cannot find translation for source Jellyfin Sep 05 16:14:01 volumio volumio[16882]: info: [jellyfin] Initialized plugin with device info: {"id":"efb65fd7-7648-487c-b3ba-ebfb5cc10227","host":"http://192.168.0.171","name":"Volumio","type":"device","serviceName":"Volumio","state":{"status":"stop","volume":37,"mute":false,"artist":"","track":"","albumart":"http://192.168.0.171/albumart"}} Sep 05 16:14:01 volumio volumio[16882]: info: Creating Spotify config file Sep 05 16:14:01 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:01 volumio sudo[17022]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 16:14:01 volumio sudo[17022]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:01 volumio sudo[17022]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:01 volumio volumio[16882]: info: Loading i18n strings for locale hu Sep 05 16:14:01 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 05 16:14:01 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:01 volumio volumio[16882]: info: FusionDsp - mixtype--------------------- Hardware Sep 05 16:14:01 volumio volumio[16882]: info: Volumio Calling Home Sep 05 16:14:01 volumio volumio[16882]: info: Preparing to generate the ALSA configuration file Sep 05 16:14:02 volumio volumio[16882]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 05 16:14:02 volumio volumio[16882]: info: Reading ALSA contributions from plugins. Sep 05 16:14:02 volumio volumio[16882]: info: MPD Permissions set Sep 05 16:14:02 volumio volumio[16882]: info: MPD Permissions set Sep 05 16:14:02 volumio volumio[16882]: info: VolumeController:: Volume=37 Mute =false Sep 05 16:14:02 volumio volumio[16882]: info: CoreCommandRouter::volumioGetState Sep 05 16:14:02 volumio volumio[16882]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:02 volumio volumio[16882]: info: CoreStateMachine::pushState Sep 05 16:14:02 volumio volumio[16882]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:02 volumio volumio[16882]: info: CoreCommandRouter::volumioPushState Sep 05 16:14:02 volumio volumio[16882]: info: CoreCommandRouter::volumioGetState Sep 05 16:14:02 volumio volumio[16882]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:02 volumio volumio[16882]: info: Volumio called home Sep 05 16:14:02 volumio volumio[16882]: info: Spotify config file written Sep 05 16:14:02 volumio volumio[16882]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Sep 05 16:14:02 volumio sudo[17033]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 05 16:14:02 volumio sudo[17033]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:02 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:02 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:02 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:02 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:02 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:02 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:02 volumio systemd[1]: Stopping go-librespot Daemon... Sep 05 16:14:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 05 16:14:02 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:14:02 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:02 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 05 16:14:02 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:02 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:02 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:02 volumio systemd[1]: Stopped go-librespot Daemon. Sep 05 16:14:02 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:02 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:02 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:14:02 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:02 volumio volumio[16882]: info: No need to fix Spotify hosts Sep 05 16:14:02 volumio systemd[1]: Started go-librespot Daemon. Sep 05 16:14:02 volumio go-librespot[17053]: go-librespot daemon starting... Sep 05 16:14:02 volumio sudo[17033]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:02 volumio go-librespot[17053]: time="2025-09-05T16:14:02+02:00" level=info msg="running go-librespot 0.3.2" Sep 05 16:14:02 volumio go-librespot[17053]: time="2025-09-05T16:14:02+02:00" level=debug msg="app state loaded" Sep 05 16:14:02 volumio go-librespot[17053]: time="2025-09-05T16:14:02+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 05 16:14:02 volumio sudo[17066]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 05 16:14:02 volumio sudo[17066]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:02 volumio sudo[17066]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:02 volumio sudo[17069]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 05 16:14:02 volumio sudo[17069]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:02 volumio sudo[17069]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:02 volumio go-librespot[17053]: time="2025-09-05T16:14:02+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 05 16:14:02 volumio go-librespot[17053]: time="2025-09-05T16:14:02+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 05 16:14:02 volumio go-librespot[17053]: time="2025-09-05T16:14:02+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 05 16:14:02 volumio go-librespot[17053]: time="2025-09-05T16:14:02+02:00" level=info msg="zeroconf server listening on port 39043" Sep 05 16:14:02 volumio volumio[16882]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 05 16:14:02 volumio volumio[16882]: SPOTIFY: BQA2ZTbwoX9Y_TU3Z8KlvfIqgZpAsj7bDJ4BbYDbWT6JwCjlX76yTQkIRyZZmMEtec813peebSqCWPm5LbVLSf5UjyWjZ8gyrYlcnw4dDTwyeA6MqnJJQv_wfrRivQ598lX14lG3WFuu4x8GcCdEL4ATkeDfCdV4EFOq_siGcglCiPODWFyIVXv6_1lwWTaDRWSHMJuhdUQSwW4aHGXu-zo4NeyKMxYS0aWn1q9dO3UJyesnfSxWcWKLsTLSLHVL1OD5IgGKTKajdOhrlKukrHfWrwFiFe7RgIZEuwxjN12aKIM Sep 05 16:14:02 volumio volumio[16882]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 05 16:14:02 volumio volumio[16882]: info: New Spotify access token = BQA2ZTbwoX9Y_TU3Z8KlvfIqgZpAsj7bDJ4BbYDbWT6JwCjlX76yTQkIRyZZmMEtec813peebSqCWPm5LbVLSf5UjyWjZ8gyrYlcnw4dDTwyeA6MqnJJQv_wfrRivQ598lX14lG3WFuu4x8GcCdEL4ATkeDfCdV4EFOq_siGcglCiPODWFyIVXv6_1lwWTaDRWSHMJuhdUQSwW4aHGXu-zo4NeyKMxYS0aWn1q9dO3UJyesnfSxWcWKLsTLSLHVL1OD5IgGKTKajdOhrlKukrHfWrwFiFe7RgIZEuwxjN12aKIM Sep 05 16:14:02 volumio volumio[16882]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 05 16:14:02 volumio go-librespot[17053]: time="2025-09-05T16:14:02+02:00" level=debug msg="obtained new client token: AADCWgCg9sXJ7RfZQCjkIDcWdzBBEJfWpOeo/B//CJEM9B5E6mUs6cTrigzIrgsFqo5e5WJuJrojrQ+bH9YUKZF1139RH6r7dMnm4q7cKzBeYk3QrSx2WktN4m8ajOFsnHXzni1zx9zMQyVL+54P1MNuixk9Wzu4Zx5/oy6WMq0TKN9vlW5OIjmW+Z02wGNVybuSFpxtY9sNjsWnHO2JuGfSLmR68eJeRR2Yj8Ir63M0xX3kKArP3rqMcf4=" Sep 05 16:14:02 volumio volumio[16882]: info: CoreCommandRouter::volumioGetState Sep 05 16:14:02 volumio volumio[16882]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:02 volumio go-librespot[17053]: time="2025-09-05T16:14:02+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 05 16:14:02 volumio volumio[16882]: info: Asound.conf file unchanged, so no further update is needed Sep 05 16:14:02 volumio volumio[16882]: info: Output device has changed, restarting MPD Sep 05 16:14:02 volumio go-librespot[17053]: time="2025-09-05T16:14:02+02:00" level=debug msg="completed keyexchange" Sep 05 16:14:02 volumio go-librespot[17053]: time="2025-09-05T16:14:02+02:00" level=debug msg="completed challenge" Sep 05 16:14:02 volumio sudo[17072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 16:14:02 volumio sudo[17072]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:02 volumio sudo[17072]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:02 volumio go-librespot[17053]: time="2025-09-05T16:14:02+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 05 16:14:02 volumio sudo[17074]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 16:14:02 volumio sudo[17074]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:02 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 05 16:14:02 volumio systemd[1]: mpd.service: Succeeded. Sep 05 16:14:02 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 16:14:02 volumio volumio[16882]: info: MPD Permissions set Sep 05 16:14:02 volumio go-librespot[17053]: time="2025-09-05T16:14:02+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 05 16:14:02 volumio go-librespot[17053]: time="2025-09-05T16:14:02+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 05 16:14:02 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:02 volumio systemd[1]: Starting Music Player Daemon... Sep 05 16:14:02 volumio sudo[17080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 05 16:14:02 volumio sudo[17080]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:02 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:14:02 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:03 volumio sudo[17080]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:03 volumio volumio[16882]: info: Upmpdcli Daemon Started Sep 05 16:14:03 volumio volumio[16882]: SPOTIFY: User informations: {"country":"HU","display_name":"4Misi","email":"sztako.mihaly@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/lo8jr34h0xh1vbcv2lgmaqkvv"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/lo8jr34h0xh1vbcv2lgmaqkvv","id":"lo8jr34h0xh1vbcv2lgmaqkvv","images":[],"product":"premium","type":"user","uri":"spotify:user:lo8jr34h0xh1vbcv2lgmaqkvv"} Sep 05 16:14:03 volumio volumio[16882]: info: Spotify Successfully logged in Sep 05 16:14:03 volumio volumio[16882]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 16:14:03 volumio volumio[16882]: info: [1757081643036] CoreMusicLibrary::Adding element Spotify Sep 05 16:14:03 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:14:03 volumio volumio[16882]: Cannot find translation for source Jellyfin Sep 05 16:14:03 volumio volumio[16882]: Cannot find translation for source Spotify Sep 05 16:14:03 volumio go-librespot[17053]: time="2025-09-05T16:14:03+02:00" level=debug msg="dealer connection opened" Sep 05 16:14:03 volumio go-librespot[17053]: time="2025-09-05T16:14:03+02:00" level=trace msg="starting accesspoint recv loop" Sep 05 16:14:03 volumio go-librespot[17053]: time="2025-09-05T16:14:03+02:00" level=trace msg="starting dealer recv loop" Sep 05 16:14:03 volumio go-librespot[17053]: time="2025-09-05T16:14:03+02:00" level=trace msg="received accesspoint ping" Sep 05 16:14:03 volumio go-librespot[17053]: time="2025-09-05T16:14:03+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 05 16:14:03 volumio go-librespot[17053]: time="2025-09-05T16:14:03+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 05 16:14:03 volumio go-librespot[17053]: time="2025-09-05T16:14:03+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 05 16:14:03 volumio go-librespot[17053]: time="2025-09-05T16:14:03+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 05 16:14:03 volumio go-librespot[17053]: time="2025-09-05T16:14:03+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 05 16:14:03 volumio go-librespot[17053]: time="2025-09-05T16:14:03+02:00" level=debug msg="received connection id: YzMwZTExNDMtZWJk...NjY5ODcwNTY2OQ==" Sep 05 16:14:03 volumio go-librespot[17053]: time="2025-09-05T16:14:03+02:00" level=trace msg="received accesspoint pong ack" Sep 05 16:14:03 volumio sudo[17078]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 16:14:03 volumio sudo[17078]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:03 volumio sudo[17078]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:03 volumio go-librespot[17053]: time="2025-09-05T16:14:03+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 05 16:14:03 volumio volumio[16882]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 05 16:14:03 volumio volumio[16882]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 05 16:14:03 volumio volumio[16882]: 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 05 16:14:03 volumio volumio[16882]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 05 16:14:03 volumio volumio[16882]: info: camilladsp service started and running in background, instance 1 Sep 05 16:14:03 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:03 volumio volumio[16882]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 05 16:14:03 volumio volumio[16882]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 05 16:14:03 volumio volumio[16882]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 05 16:14:03 volumio volumio[16882]: info: FusionDsp loaded Sep 05 16:14:03 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 16:14:04 volumio sudo[17098]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 05 16:14:04 volumio sudo[17098]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:04 volumio volumio[16882]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 05 16:14:04 volumio volumio[16882]: info: Adding Signal Path Element [object Object] Sep 05 16:14:04 volumio volumio[16882]: info: Adding fusiondspeq DSP Signal Path Element Sep 05 16:14:04 volumio volumio[16882]: info: FusionDsp - ---- installed callbackRead Sep 05 16:14:04 volumio sudo[17098]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:04 volumio volumio[16882]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 16:14:04 volumio volumio[16882]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 05 16:14:04 volumio volumio[16882]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 05 16:14:04 volumio volumio[16882]: at onErrorNT (internal/child_process.js:465:16) Sep 05 16:14:04 volumio volumio[16882]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Sep 05 16:14:04 volumio volumio[16882]: errno: -2, Sep 05 16:14:04 volumio volumio[16882]: code: 'ENOENT', Sep 05 16:14:04 volumio volumio[16882]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 05 16:14:04 volumio volumio[16882]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 05 16:14:04 volumio volumio[16882]: spawnargs: [ Sep 05 16:14:04 volumio volumio[16882]: '-p', Sep 05 16:14:04 volumio volumio[16882]: 9876, Sep 05 16:14:04 volumio volumio[16882]: '-o', Sep 05 16:14:04 volumio volumio[16882]: '/tmp/camilladsp.log', Sep 05 16:14:04 volumio volumio[16882]: '-l', Sep 05 16:14:04 volumio volumio[16882]: 'warn', Sep 05 16:14:04 volumio volumio[16882]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 05 16:14:04 volumio volumio[16882]: ] Sep 05 16:14:04 volumio volumio[16882]: } Sep 05 16:14:04 volumio volumio[16882]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 16:14:04 volumio sudo[17109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-05 16:13 Sep 05 16:14:04 volumio sudo[17109]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:04 volumio mpd[17087]: Sep 05 16:14 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 05 16:14:04 volumio systemd[1]: Started Music Player Daemon. Sep 05 16:14:04 volumio sudo[17012]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:04 volumio sudo[16990]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:04 volumio sudo[17074]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:04 volumio sudo[17109]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:04 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 05 16:14:04 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 05 16:14:05 volumio systemd[1]: Started dynamicswap service. Sep 05 16:14:05 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 05 16:14:05 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 05 16:14:05 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 18971. Sep 05 16:14:05 volumio systemd[1]: Stopped Volumio Backend Module. Sep 05 16:14:05 volumio systemd[1]: Started Volumio Backend Module. Sep 05 16:14:05 volumio systemd[1]: Started dynamicswap service. Sep 05 16:14:05 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 05 16:14:05 volumio volumio-remote-updater[504]: [2025-09-05 16:14:05] [info] asio async_connect error: system:111 (Connection refused) Sep 05 16:14:05 volumio volumio-remote-updater[504]: [2025-09-05 16:14:05] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 05 16:14:05 volumio volumio-remote-updater[504]: [2025-09-05 16:14:05] [error] handle_connect error: Underlying Transport Error Sep 05 16:14:06 volumio volumio[17121]: info: ------------------------------------------- Sep 05 16:14:06 volumio volumio[17121]: info: ----- Volumio3 ---- Sep 05 16:14:06 volumio volumio[17121]: info: ------------------------------------------- Sep 05 16:14:06 volumio volumio[17121]: info: ----- System startup ---- Sep 05 16:14:06 volumio volumio[17121]: info: ------------------------------------------- Sep 05 16:14:07 volumio volumio[17121]: info: MYVOLUMIO Environment detected Sep 05 16:14:07 volumio volumio[17121]: info: Plugin folders cleanup Sep 05 16:14:07 volumio volumio[17121]: info: Scanning into folder /volumio/app/plugins/ Sep 05 16:14:07 volumio volumio[17121]: info: Scanning category audio_interface Sep 05 16:14:07 volumio volumio[17121]: info: Scanning category miscellanea Sep 05 16:14:07 volumio volumio[17121]: info: Scanning category music_service Sep 05 16:14:07 volumio volumio[17121]: info: Scanning category plugins.json Sep 05 16:14:07 volumio volumio[17121]: info: Scanning category system_controller Sep 05 16:14:07 volumio volumio[17121]: info: Scanning category user_interface Sep 05 16:14:07 volumio volumio[17121]: info: Scanning into folder /data/plugins/ Sep 05 16:14:07 volumio volumio[17121]: info: Scanning category audio_interface Sep 05 16:14:07 volumio volumio[17121]: info: Scanning category music_service Sep 05 16:14:07 volumio volumio[17121]: info: Scanning category user_interface Sep 05 16:14:07 volumio volumio[17121]: info: Plugin folders cleanup completed Sep 05 16:14:07 volumio volumio[17121]: info: ------------------------------------------- Sep 05 16:14:07 volumio volumio[17121]: info: ----- Core plugins startup ---- Sep 05 16:14:07 volumio volumio[17121]: info: ------------------------------------------- Sep 05 16:14:07 volumio volumio[17121]: info: Loading plugins from folder /volumio/app/plugins/ Sep 05 16:14:07 volumio volumio[17121]: info: Adding plugin upnp to MyMusic Plugins Sep 05 16:14:07 volumio volumio[17121]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 05 16:14:07 volumio volumio[17121]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 05 16:14:07 volumio volumio[17121]: info: Loading plugins from folder /data/plugins/ Sep 05 16:14:07 volumio volumio[17121]: info: Loading plugin "system"... Sep 05 16:14:07 volumio volumio[17121]: info: Loading plugin "appearance"... Sep 05 16:14:08 volumio volumio[17121]: info: Loading plugin "network"... Sep 05 16:14:08 volumio volumio[17121]: info: Refreshing Cached IP Addresses Sep 05 16:14:08 volumio sudo[17149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 05 16:14:08 volumio sudo[17149]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:08 volumio sudo[17149]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:08 volumio sudo[17151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 05 16:14:08 volumio volumio[17121]: info: Loading plugin "services"... Sep 05 16:14:08 volumio sudo[17151]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:08 volumio volumio[17121]: info: Loading plugin "alsa_controller"... Sep 05 16:14:08 volumio sudo[17151]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:09 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 16:14:09 volumio volumio[17121]: info: Loading plugin "wizard"... Sep 05 16:14:09 volumio volumio[17121]: info: Loading plugin "networkfs"... Sep 05 16:14:09 volumio volumio[17121]: info: Starting Udev Watcher for removable devices Sep 05 16:14:09 volumio volumio[17121]: info: Ignoring mount for partition: boot Sep 05 16:14:09 volumio volumio[17121]: info: Ignoring mount for partition: volumio Sep 05 16:14:09 volumio volumio[17121]: info: Ignoring mount for partition: volumio_data Sep 05 16:14:09 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 16:14:09 volumio volumio[17121]: info: Loading plugin "volumio_command_line_client"... Sep 05 16:14:09 volumio volumio[17121]: info: Loading plugin "upnp"... Sep 05 16:14:09 volumio volumio[17121]: info: [1757081649079] Starting Upmpd Daemon Sep 05 16:14:09 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 16:14:09 volumio volumio[17121]: info: Loading plugin "my_music"... Sep 05 16:14:09 volumio volumio[17121]: info: Loading plugin "mpd"... Sep 05 16:14:09 volumio volumio[17121]: info: Plugin upnp_browser is not enabled Sep 05 16:14:09 volumio volumio[17121]: info: Loading plugin "alarm-clock"... Sep 05 16:14:09 volumio volumio[17121]: info: Plugin airplay_emulation is not enabled Sep 05 16:14:09 volumio volumio[17121]: info: Loading plugin "last_100"... Sep 05 16:14:09 volumio volumio[17121]: info: Loading plugin "webradio"... Sep 05 16:14:09 volumio volumio[17121]: info: Loading plugin "i2s_dacs"... Sep 05 16:14:09 volumio volumio[17121]: info: Loading plugin "volumiodiscovery"... Sep 05 16:14:09 volumio volumio[17121]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 05 16:14:09 volumio volumio[17121]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 16:14:09 volumio volumio[17121]: *** WARNING *** For more information see Sep 05 16:14:09 volumio volumio[17121]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 05 16:14:09 volumio node[17121]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 05 16:14:09 volumio volumio[17121]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 16:14:09 volumio volumio[17121]: *** WARNING *** For more information see Sep 05 16:14:09 volumio node[17121]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 16:14:09 volumio node[17121]: *** WARNING *** For more information see Sep 05 16:14:09 volumio node[17121]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 05 16:14:09 volumio node[17121]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 16:14:09 volumio node[17121]: *** WARNING *** For more information see Sep 05 16:14:09 volumio volumio[17121]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 05 16:14:09 volumio volumio[17121]: info: Discovery: Started advertising with name: Volumio Sep 05 16:14:09 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 16:14:09 volumio volumio[17121]: info: Loading plugin "jellyfin"... Sep 05 16:14:10 volumio volumio[17121]: info: Loading plugin "spop"... Sep 05 16:14:11 volumio volumio[17121]: info: Loading plugin "ytcr"... Sep 05 16:14:15 volumio volumio-remote-updater[504]: [2025-09-05 16:14:15] [connect] Successful connection Sep 05 16:14:16 volumio volumio[17121]: info: Loading plugin "outputs"... Sep 05 16:14:16 volumio volumio[17121]: info: Loading plugin "albumart"... Sep 05 16:14:16 volumio volumio[17121]: info: Plugin example_plugin is not enabled Sep 05 16:14:16 volumio volumio[17121]: info: Loading plugin "inputs"... Sep 05 16:14:16 volumio volumio[17121]: info: Loading plugin "updater_comm"... Sep 05 16:14:16 volumio volumio[17121]: info: Plugin mpdemulation is not enabled Sep 05 16:14:16 volumio volumio[17121]: info: Loading plugin "rest_api"... Sep 05 16:14:16 volumio volumio[17121]: info: Loading plugin "websocket"... Sep 05 16:14:16 volumio volumio[17121]: info: Starting Socket.io Server version 2.3.0 Sep 05 16:14:16 volumio volumio[17121]: info: Loading plugin "fusiondsp"... Sep 05 16:14:16 volumio volumio[17121]: Forking 3 albumart workers Sep 05 16:14:16 volumio volumio[17121]: info: Applying required configuration parameters for plugin fusiondsp Sep 05 16:14:16 volumio volumio[17121]: info: Loading plugin "Systeminfo"... Sep 05 16:14:17 volumio volumio[17121]: info: Loading i18n strings for locale hu Sep 05 16:14:17 volumio volumio[17121]: Updating browse sources language Sep 05 16:14:17 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:14:17 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 16:14:17 volumio volumio[17121]: info: CoreCommandRouter::initPlayerControls Sep 05 16:14:17 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:17 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:17 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:17 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:17 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:17 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:17 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:17 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:17 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 16:14:17 volumio volumio[17121]: Express server listening on port 3000 Sep 05 16:14:17 volumio volumio[17121]: [Metrics] WebUI: 11s 15.49ms Sep 05 16:14:17 volumio volumio[17121]: info: CoreStateMachine::resetVolumioState Sep 05 16:14:17 volumio volumio[17121]: info: CoreStateMachine::getcurrentVolume Sep 05 16:14:17 volumio volumio[17121]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 16:14:17 volumio volumio[17121]: Starting albumart workers Sep 05 16:14:17 volumio volumio[17121]: Starting albumart workers Sep 05 16:14:17 volumio volumio[17121]: info: Volumio Network Manager: Network status updated: 1 Sep 05 16:14:17 volumio volumio[17121]: Starting albumart workers Sep 05 16:14:17 volumio volumio-remote-updater[504]: [2025-09-05 16:14:17] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1757081655 101 Sep 05 16:14:17 volumio volumio[17121]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Sep 05 16:14:17 volumio volumio[17121]: info: CoreCommandRouter::volumioGetState Sep 05 16:14:17 volumio volumio[17121]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:17 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:14:17 volumio volumio[17121]: info: VolumeController:: Volume=37 Mute =false Sep 05 16:14:17 volumio volumio[17121]: info: CoreStateMachine::pushState Sep 05 16:14:17 volumio volumio[17121]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:17 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 16:14:17 volumio volumio[17121]: info: CoreCommandRouter::volumioPushState Sep 05 16:14:17 volumio volumio[17121]: info: CoreStateMachine::updateTrackBlock Sep 05 16:14:17 volumio volumio[17121]: info: CorePlayQueue::getTrackBlock Sep 05 16:14:17 volumio volumio[17121]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 16:14:17 volumio volumio[17121]: info: CoreStateMachine::setRepeat false single undefined Sep 05 16:14:17 volumio volumio[17121]: info: CoreStateMachine::pushState Sep 05 16:14:17 volumio volumio[17121]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:17 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 16:14:17 volumio volumio[17121]: info: CoreCommandRouter::volumioPushState Sep 05 16:14:17 volumio volumio[17121]: info: CoreStateMachine::setRandom true Sep 05 16:14:17 volumio volumio[17121]: info: CoreStateMachine::pushState Sep 05 16:14:17 volumio volumio[17121]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:17 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 16:14:17 volumio volumio[17121]: info: CoreCommandRouter::volumioPushState Sep 05 16:14:17 volumio volumio[17121]: info: Setting Device type: Raspberry PI Sep 05 16:14:18 volumio volumio[17121]: info: Listing playlists Sep 05 16:14:18 volumio volumio[17121]: info: Listing playlists Sep 05 16:14:18 volumio volumio[17121]: info: Completed loading Core Plugins Sep 05 16:14:18 volumio volumio[17121]: info: Preparing to generate the ALSA configuration file Sep 05 16:14:18 volumio volumio[17121]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227 Sep 05 16:14:18 volumio volumio[17121]: info: Discovery: Found device Volumio Sep 05 16:14:18 volumio volumio[17121]: info: CoreCommandRouter::volumioGetState Sep 05 16:14:18 volumio volumio[17121]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:18 volumio volumio[17121]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 05 16:14:18 volumio volumio[17121]: info: Reading ALSA contributions from plugins. Sep 05 16:14:18 volumio volumio[17121]: info: VolumeController:: Volume=37 Mute =false Sep 05 16:14:18 volumio volumio[17121]: info: CoreStateMachine::pushState Sep 05 16:14:18 volumio volumio[17121]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:18 volumio volumio[17121]: info: CoreCommandRouter::volumioPushState Sep 05 16:14:18 volumio volumio[17121]: info: Asound.conf file unchanged, so no further update is needed Sep 05 16:14:18 volumio volumio[17121]: info: Output device has changed, restarting MPD Sep 05 16:14:18 volumio sudo[17236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 16:14:18 volumio volumio[17121]: info: ___________ START PLUGINS ___________ Sep 05 16:14:18 volumio sudo[17236]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:18 volumio sudo[17236]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:18 volumio volumio[17121]: info: ControllerMpd::onStart: Initializing MPD Sep 05 16:14:18 volumio volumio[17121]: info: Creating MPD Configuration file Sep 05 16:14:18 volumio sudo[17238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 16:14:18 volumio sudo[17238]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:18 volumio systemd[1]: Stopping Music Player Daemon... Sep 05 16:14:18 volumio sudo[17242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 16:14:18 volumio sudo[17242]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:18 volumio volumio[17121]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 16:14:18 volumio volumio[17121]: info: [1757081658374] CoreMusicLibrary::Adding element Last_100 Sep 05 16:14:18 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:14:18 volumio volumio[17121]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 16:14:18 volumio volumio[17121]: info: [1757081658382] CoreMusicLibrary::Adding element Webradio Sep 05 16:14:18 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:14:18 volumio sudo[17242]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:18 volumio systemd[1]: mpd.service: Succeeded. Sep 05 16:14:18 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 16:14:18 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 16:14:18 volumio systemd[1]: Starting Music Player Daemon... Sep 05 16:14:18 volumio volumio[17121]: info: Initializing BBC Radios Sep 05 16:14:18 volumio sudo[17244]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 16:14:18 volumio sudo[17244]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:18 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 05 16:14:18 volumio systemd[1]: mpd.service: Succeeded. Sep 05 16:14:18 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 16:14:18 volumio systemd[1]: Starting Music Player Daemon... Sep 05 16:14:18 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 16:14:18 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:14:18 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 16:14:18 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 05 16:14:18 volumio volumio[17121]: info: Discovery: Getting this device information Sep 05 16:14:18 volumio volumio[17121]: info: CoreCommandRouter::volumioGetState Sep 05 16:14:18 volumio volumio[17121]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:18 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 16:14:18 volumio volumio[17121]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 16:14:18 volumio volumio[17121]: info: [1757081658556] CoreMusicLibrary::Adding element Jellyfin Sep 05 16:14:18 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:14:18 volumio volumio[17121]: Cannot find translation for source Jellyfin Sep 05 16:14:18 volumio volumio[17121]: info: [jellyfin] Initialized plugin with device info: {"id":"efb65fd7-7648-487c-b3ba-ebfb5cc10227","host":"http://192.168.0.171","name":"Volumio","type":"device","serviceName":"Volumio","state":{"status":"stop","volume":37,"mute":false,"artist":"","track":"","albumart":"http://192.168.0.171/albumart"}} Sep 05 16:14:18 volumio volumio[17121]: info: Creating Spotify config file Sep 05 16:14:18 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:18 volumio sudo[17262]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 16:14:18 volumio sudo[17262]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:18 volumio sudo[17262]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:18 volumio volumio[17121]: info: Loading i18n strings for locale hu Sep 05 16:14:18 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 05 16:14:18 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:18 volumio volumio[17121]: info: FusionDsp - mixtype--------------------- Hardware Sep 05 16:14:18 volumio volumio[17121]: info: Volumio Calling Home Sep 05 16:14:18 volumio volumio[17121]: info: Preparing to generate the ALSA configuration file Sep 05 16:14:18 volumio volumio[17121]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 05 16:14:18 volumio volumio[17121]: info: Reading ALSA contributions from plugins. Sep 05 16:14:18 volumio volumio[17121]: info: MPD Permissions set Sep 05 16:14:18 volumio volumio[17121]: info: MPD Permissions set Sep 05 16:14:19 volumio volumio[17121]: info: Volumio called home Sep 05 16:14:19 volumio volumio[17121]: info: Spotify config file written Sep 05 16:14:19 volumio sudo[17287]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 05 16:14:19 volumio sudo[17287]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:19 volumio volumio[17121]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Sep 05 16:14:19 volumio systemd[1]: Stopping go-librespot Daemon... Sep 05 16:14:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 05 16:14:19 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 05 16:14:19 volumio systemd[1]: Stopped go-librespot Daemon. Sep 05 16:14:19 volumio sudo[17291]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 05 16:14:19 volumio sudo[17291]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:19 volumio systemd[1]: Started go-librespot Daemon. Sep 05 16:14:19 volumio go-librespot[17289]: go-librespot daemon starting... Sep 05 16:14:19 volumio sudo[17291]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:19 volumio sudo[17287]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=info msg="running go-librespot 0.3.2" Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=debug msg="app state loaded" Sep 05 16:14:19 volumio sudo[17294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 05 16:14:19 volumio sudo[17294]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:19 volumio sudo[17294]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:19 volumio volumio[17121]: info: No need to fix Spotify hosts Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=info msg="zeroconf server listening on port 33153" Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=debug msg="obtained new client token: AABMYmlHPxyxgUKBMpuWw4X21DjpOvy62KUK6JcRsCsykpOTU896N1NO+Ee8OVKOABalWsrF1iIv1Mhp4ansT30zlX5XzSBDA399F3wbmVJe6oAHYEje+9OffPQrgI3t3P1xxld0lqfuHrmrgmZxVFwUg1z0Zz3a9nzyv0hrWW3F0hpUYmDeQ7Tnb6w2MbZJmKmJe1oHA4e8t3UGpPvCk9fcUcgquL9ucDeMWhy3gbDeQIOrOiuEPwRabys=" Sep 05 16:14:19 volumio volumio[17121]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 05 16:14:19 volumio volumio[17121]: SPOTIFY: BQBnAD4bwWQK_MLe3ntsKdW6maxPKovjAF41ddTWcI6Hn5MX3Ux0Vs8vyfzQzrkRci2rV6SRI0q6oOjfzgbVzJflz7FSUA0ICFLJZBowygK8y4EMMVDGFI_dOXtVmegtkWBlbLJSNDycpmL9TzrbE83_KufkgVkWWCX4Grlpy1G1PIvizjITSjVQKfeKTlZCTqlWOXL7soxEmE8RMEuKMvZplmtr6UcdpUQO8zgh7nzuuIDwcxrZaTuGkFjygytTdO35X2QJs_OqGZkkQ7-PgUjOWYqfRwrv_It49vWM7mZCFyk Sep 05 16:14:19 volumio volumio[17121]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 05 16:14:19 volumio volumio[17121]: info: New Spotify access token = BQBnAD4bwWQK_MLe3ntsKdW6maxPKovjAF41ddTWcI6Hn5MX3Ux0Vs8vyfzQzrkRci2rV6SRI0q6oOjfzgbVzJflz7FSUA0ICFLJZBowygK8y4EMMVDGFI_dOXtVmegtkWBlbLJSNDycpmL9TzrbE83_KufkgVkWWCX4Grlpy1G1PIvizjITSjVQKfeKTlZCTqlWOXL7soxEmE8RMEuKMvZplmtr6UcdpUQO8zgh7nzuuIDwcxrZaTuGkFjygytTdO35X2QJs_OqGZkkQ7-PgUjOWYqfRwrv_It49vWM7mZCFyk Sep 05 16:14:19 volumio volumio[17121]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::volumioGetState Sep 05 16:14:19 volumio volumio[17121]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:19 volumio volumio[17121]: info: Asound.conf file unchanged, so no further update is needed Sep 05 16:14:19 volumio volumio[17121]: info: Output device has changed, restarting MPD Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=debug msg="completed keyexchange" Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=debug msg="completed challenge" Sep 05 16:14:19 volumio sudo[17314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 16:14:19 volumio sudo[17314]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 05 16:14:19 volumio sudo[17314]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:19 volumio sudo[17317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 16:14:19 volumio sudo[17317]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:19 volumio volumio[17121]: info: MPD Permissions set Sep 05 16:14:19 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 05 16:14:19 volumio systemd[1]: mpd.service: Succeeded. Sep 05 16:14:19 volumio sudo[17320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 05 16:14:19 volumio sudo[17320]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:19 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=debug msg="dealer connection opened" Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=trace msg="starting accesspoint recv loop" Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=trace msg="starting dealer recv loop" Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=trace msg="received accesspoint ping" Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 05 16:14:19 volumio systemd[1]: Starting Music Player Daemon... Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=debug msg="received connection id: YzlmYTEyOTItMGJi...ODlCMEY0MDFGMA==" Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=trace msg="received accesspoint pong ack" Sep 05 16:14:19 volumio sudo[17320]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:19 volumio volumio[17121]: info: Upmpdcli Daemon Started Sep 05 16:14:19 volumio volumio[17121]: SPOTIFY: User informations: {"country":"HU","display_name":"4Misi","email":"sztako.mihaly@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/lo8jr34h0xh1vbcv2lgmaqkvv"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/lo8jr34h0xh1vbcv2lgmaqkvv","id":"lo8jr34h0xh1vbcv2lgmaqkvv","images":[],"product":"premium","type":"user","uri":"spotify:user:lo8jr34h0xh1vbcv2lgmaqkvv"} Sep 05 16:14:19 volumio volumio[17121]: info: Spotify Successfully logged in Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 16:14:19 volumio volumio[17121]: info: [1757081659947] CoreMusicLibrary::Adding element Spotify Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:14:19 volumio volumio[17121]: Cannot find translation for source Jellyfin Sep 05 16:14:19 volumio volumio[17121]: Cannot find translation for source Spotify Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 05 16:14:19 volumio sudo[17322]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 16:14:19 volumio sudo[17322]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:19 volumio sudo[17322]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:20 volumio volumio[17121]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 05 16:14:20 volumio volumio[17121]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 16:14:20 volumio volumio[17121]: info: VolumeController:: Volume=37 Mute =false Sep 05 16:14:20 volumio volumio[17121]: info: CoreCommandRouter::volumioGetState Sep 05 16:14:20 volumio volumio[17121]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:20 volumio volumio[17121]: info: CoreStateMachine::pushState Sep 05 16:14:20 volumio volumio[17121]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:20 volumio volumio[17121]: info: CoreCommandRouter::volumioPushState Sep 05 16:14:20 volumio volumio[17121]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 05 16:14:20 volumio volumio[17121]: 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 05 16:14:20 volumio volumio[17121]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 05 16:14:20 volumio volumio[17121]: info: camilladsp service started and running in background, instance 1 Sep 05 16:14:20 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:20 volumio volumio[17121]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 05 16:14:20 volumio volumio[17121]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 05 16:14:20 volumio volumio[17121]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 05 16:14:20 volumio volumio[17121]: info: FusionDsp loaded Sep 05 16:14:20 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 16:14:20 volumio sudo[17342]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 05 16:14:20 volumio sudo[17342]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:20 volumio volumio[17121]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 05 16:14:20 volumio volumio[17121]: info: Adding Signal Path Element [object Object] Sep 05 16:14:20 volumio volumio[17121]: info: Adding fusiondspeq DSP Signal Path Element Sep 05 16:14:20 volumio volumio[17121]: info: FusionDsp - ---- installed callbackRead Sep 05 16:14:20 volumio volumio[17121]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 16:14:20 volumio sudo[17342]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:20 volumio volumio[17121]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 05 16:14:20 volumio volumio[17121]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 05 16:14:20 volumio volumio[17121]: at onErrorNT (internal/child_process.js:465:16) Sep 05 16:14:20 volumio volumio[17121]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Sep 05 16:14:20 volumio volumio[17121]: errno: -2, Sep 05 16:14:20 volumio volumio[17121]: code: 'ENOENT', Sep 05 16:14:20 volumio volumio[17121]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 05 16:14:20 volumio volumio[17121]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 05 16:14:20 volumio volumio[17121]: spawnargs: [ Sep 05 16:14:20 volumio volumio[17121]: '-p', Sep 05 16:14:20 volumio volumio[17121]: 9876, Sep 05 16:14:20 volumio volumio[17121]: '-o', Sep 05 16:14:20 volumio volumio[17121]: '/tmp/camilladsp.log', Sep 05 16:14:20 volumio volumio[17121]: '-l', Sep 05 16:14:20 volumio volumio[17121]: 'warn', Sep 05 16:14:20 volumio volumio[17121]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 05 16:14:20 volumio volumio[17121]: ] Sep 05 16:14:20 volumio volumio[17121]: } Sep 05 16:14:20 volumio volumio[17121]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 16:14:21 volumio mpd[17329]: Sep 05 16:14 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 05 16:14:21 volumio systemd[1]: Started Music Player Daemon. Sep 05 16:14:21 volumio sudo[17353]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-05 16:13 Sep 05 16:14:21 volumio sudo[17353]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:21 volumio sudo[17244]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:21 volumio sudo[17238]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:21 volumio sudo[17317]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:21 volumio sudo[17353]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:21 volumio volumio-remote-updater[504]: [2025-09-05 16:14:21] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 05 16:14:21 volumio volumio-remote-updater[504]: [2025-09-05 16:14:21] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 05 16:14:21 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 05 16:14:21 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 05 16:14:21 volumio systemd[1]: Started dynamicswap service. Sep 05 16:14:21 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 05 16:14:22 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 05 16:14:22 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 18972. Sep 05 16:14:22 volumio systemd[1]: Stopped Volumio Backend Module. Sep 05 16:14:22 volumio systemd[1]: Started Volumio Backend Module. Sep 05 16:14:22 volumio systemd[1]: Started dynamicswap service. Sep 05 16:14:22 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 05 16:14:23 volumio volumio[17365]: info: ------------------------------------------- Sep 05 16:14:23 volumio volumio[17365]: info: ----- Volumio3 ---- Sep 05 16:14:23 volumio volumio[17365]: info: ------------------------------------------- Sep 05 16:14:23 volumio volumio[17365]: info: ----- System startup ---- Sep 05 16:14:23 volumio volumio[17365]: info: ------------------------------------------- Sep 05 16:14:24 volumio volumio[17365]: info: MYVOLUMIO Environment detected Sep 05 16:14:24 volumio volumio[17365]: info: Plugin folders cleanup Sep 05 16:14:24 volumio volumio[17365]: info: Scanning into folder /volumio/app/plugins/ Sep 05 16:14:24 volumio volumio[17365]: info: Scanning category audio_interface Sep 05 16:14:24 volumio volumio[17365]: info: Scanning category miscellanea Sep 05 16:14:24 volumio volumio[17365]: info: Scanning category music_service Sep 05 16:14:24 volumio volumio[17365]: info: Scanning category plugins.json Sep 05 16:14:24 volumio volumio[17365]: info: Scanning category system_controller Sep 05 16:14:24 volumio volumio[17365]: info: Scanning category user_interface Sep 05 16:14:24 volumio volumio[17365]: info: Scanning into folder /data/plugins/ Sep 05 16:14:24 volumio volumio[17365]: info: Scanning category audio_interface Sep 05 16:14:24 volumio volumio[17365]: info: Scanning category music_service Sep 05 16:14:24 volumio volumio[17365]: info: Scanning category user_interface Sep 05 16:14:24 volumio volumio[17365]: info: Plugin folders cleanup completed Sep 05 16:14:24 volumio volumio[17365]: info: ------------------------------------------- Sep 05 16:14:24 volumio volumio[17365]: info: ----- Core plugins startup ---- Sep 05 16:14:24 volumio volumio[17365]: info: ------------------------------------------- Sep 05 16:14:24 volumio volumio[17365]: info: Loading plugins from folder /volumio/app/plugins/ Sep 05 16:14:24 volumio volumio[17365]: info: Adding plugin upnp to MyMusic Plugins Sep 05 16:14:24 volumio volumio[17365]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 05 16:14:24 volumio volumio[17365]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 05 16:14:24 volumio volumio[17365]: info: Loading plugins from folder /data/plugins/ Sep 05 16:14:24 volumio volumio[17365]: info: Loading plugin "system"... Sep 05 16:14:24 volumio volumio[17365]: info: Loading plugin "appearance"... Sep 05 16:14:25 volumio volumio[17365]: info: Loading plugin "network"... Sep 05 16:14:25 volumio volumio[17365]: info: Refreshing Cached IP Addresses Sep 05 16:14:25 volumio sudo[17392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 05 16:14:25 volumio sudo[17392]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:25 volumio sudo[17392]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:25 volumio volumio[17365]: info: Loading plugin "services"... Sep 05 16:14:25 volumio sudo[17394]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 05 16:14:25 volumio sudo[17394]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:25 volumio volumio[17365]: info: Loading plugin "alsa_controller"... Sep 05 16:14:25 volumio sudo[17394]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:25 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 16:14:25 volumio volumio[17365]: info: Loading plugin "wizard"... Sep 05 16:14:25 volumio volumio[17365]: info: Loading plugin "networkfs"... Sep 05 16:14:26 volumio volumio[17365]: info: Starting Udev Watcher for removable devices Sep 05 16:14:26 volumio volumio[17365]: info: Ignoring mount for partition: boot Sep 05 16:14:26 volumio volumio[17365]: info: Ignoring mount for partition: volumio Sep 05 16:14:26 volumio volumio[17365]: info: Ignoring mount for partition: volumio_data Sep 05 16:14:26 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 16:14:26 volumio volumio[17365]: info: Loading plugin "volumio_command_line_client"... Sep 05 16:14:26 volumio volumio[17365]: info: Loading plugin "upnp"... Sep 05 16:14:26 volumio volumio[17365]: info: [1757081666048] Starting Upmpd Daemon Sep 05 16:14:26 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 16:14:26 volumio volumio[17365]: info: Loading plugin "my_music"... Sep 05 16:14:26 volumio volumio[17365]: info: Loading plugin "mpd"... Sep 05 16:14:26 volumio volumio[17365]: info: Plugin upnp_browser is not enabled Sep 05 16:14:26 volumio volumio[17365]: info: Loading plugin "alarm-clock"... Sep 05 16:14:26 volumio volumio[17365]: info: Plugin airplay_emulation is not enabled Sep 05 16:14:26 volumio volumio[17365]: info: Loading plugin "last_100"... Sep 05 16:14:26 volumio volumio[17365]: info: Loading plugin "webradio"... Sep 05 16:14:26 volumio volumio[17365]: info: Loading plugin "i2s_dacs"... Sep 05 16:14:26 volumio volumio[17365]: info: Loading plugin "volumiodiscovery"... Sep 05 16:14:26 volumio volumio[17365]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 05 16:14:26 volumio volumio[17365]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 16:14:26 volumio volumio[17365]: *** WARNING *** For more information see Sep 05 16:14:26 volumio node[17365]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 05 16:14:26 volumio volumio[17365]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 05 16:14:26 volumio volumio[17365]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 16:14:26 volumio volumio[17365]: *** WARNING *** For more information see Sep 05 16:14:26 volumio node[17365]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 16:14:26 volumio node[17365]: *** WARNING *** For more information see Sep 05 16:14:26 volumio node[17365]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 05 16:14:26 volumio node[17365]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 16:14:26 volumio node[17365]: *** WARNING *** For more information see Sep 05 16:14:26 volumio volumio[17365]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 05 16:14:26 volumio volumio[17365]: info: Discovery: Started advertising with name: Volumio Sep 05 16:14:26 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 16:14:26 volumio volumio[17365]: info: Loading plugin "jellyfin"... Sep 05 16:14:26 volumio volumio-remote-updater[504]: [2025-09-05 16:14:26] [connect] Successful connection Sep 05 16:14:27 volumio volumio[17365]: info: Loading plugin "spop"... Sep 05 16:14:28 volumio volumio[17365]: info: Loading plugin "ytcr"... Sep 05 16:14:33 volumio volumio[17365]: info: Loading plugin "outputs"... Sep 05 16:14:33 volumio volumio[17365]: info: Loading plugin "albumart"... Sep 05 16:14:33 volumio volumio[17365]: info: Plugin example_plugin is not enabled Sep 05 16:14:33 volumio volumio[17365]: info: Loading plugin "inputs"... Sep 05 16:14:33 volumio volumio[17365]: info: Loading plugin "updater_comm"... Sep 05 16:14:33 volumio volumio[17365]: info: Plugin mpdemulation is not enabled Sep 05 16:14:33 volumio volumio[17365]: info: Loading plugin "rest_api"... Sep 05 16:14:33 volumio volumio[17365]: info: Loading plugin "websocket"... Sep 05 16:14:33 volumio volumio[17365]: info: Starting Socket.io Server version 2.3.0 Sep 05 16:14:33 volumio volumio[17365]: info: Loading plugin "fusiondsp"... Sep 05 16:14:33 volumio volumio[17365]: Forking 3 albumart workers Sep 05 16:14:33 volumio volumio[17365]: info: Applying required configuration parameters for plugin fusiondsp Sep 05 16:14:33 volumio volumio[17365]: info: Loading plugin "Systeminfo"... Sep 05 16:14:34 volumio volumio[17365]: info: Loading i18n strings for locale hu Sep 05 16:14:34 volumio volumio[17365]: Updating browse sources language Sep 05 16:14:34 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:14:34 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 16:14:34 volumio volumio[17365]: info: CoreCommandRouter::initPlayerControls Sep 05 16:14:34 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:34 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:34 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:34 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:34 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:34 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:34 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:34 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:34 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 16:14:34 volumio volumio[17365]: Express server listening on port 3000 Sep 05 16:14:34 volumio volumio[17365]: [Metrics] WebUI: 11s 2.21ms Sep 05 16:14:34 volumio volumio[17365]: info: CoreStateMachine::resetVolumioState Sep 05 16:14:34 volumio volumio[17365]: info: CoreStateMachine::getcurrentVolume Sep 05 16:14:34 volumio volumio[17365]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 16:14:34 volumio volumio[17365]: Starting albumart workers Sep 05 16:14:34 volumio volumio[17365]: Starting albumart workers Sep 05 16:14:34 volumio volumio[17365]: info: Volumio Network Manager: Network status updated: 1 Sep 05 16:14:34 volumio volumio[17365]: Starting albumart workers Sep 05 16:14:34 volumio volumio[17365]: info: CoreCommandRouter::volumioGetState Sep 05 16:14:34 volumio volumio[17365]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:34 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:14:34 volumio volumio[17365]: info: VolumeController:: Volume=37 Mute =false Sep 05 16:14:34 volumio volumio[17365]: info: CoreStateMachine::pushState Sep 05 16:14:34 volumio volumio[17365]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:34 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 16:14:34 volumio volumio[17365]: info: CoreCommandRouter::volumioPushState Sep 05 16:14:34 volumio volumio[17365]: info: CoreStateMachine::updateTrackBlock Sep 05 16:14:34 volumio volumio[17365]: info: CorePlayQueue::getTrackBlock Sep 05 16:14:34 volumio volumio[17365]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 16:14:34 volumio volumio[17365]: info: CoreStateMachine::setRepeat false single undefined Sep 05 16:14:34 volumio volumio[17365]: info: CoreStateMachine::pushState Sep 05 16:14:34 volumio volumio[17365]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:34 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 16:14:34 volumio volumio[17365]: info: CoreCommandRouter::volumioPushState Sep 05 16:14:34 volumio volumio[17365]: info: CoreStateMachine::setRandom true Sep 05 16:14:34 volumio volumio[17365]: info: CoreStateMachine::pushState Sep 05 16:14:34 volumio volumio[17365]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:34 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 16:14:34 volumio volumio[17365]: info: CoreCommandRouter::volumioPushState Sep 05 16:14:34 volumio volumio[17365]: info: Setting Device type: Raspberry PI Sep 05 16:14:34 volumio volumio[17365]: info: Listing playlists Sep 05 16:14:34 volumio volumio[17365]: info: Listing playlists Sep 05 16:14:35 volumio volumio[17365]: info: Completed loading Core Plugins Sep 05 16:14:35 volumio volumio[17365]: info: Preparing to generate the ALSA configuration file Sep 05 16:14:35 volumio volumio[17365]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227 Sep 05 16:14:35 volumio volumio[17365]: info: Discovery: Found device Volumio Sep 05 16:14:35 volumio volumio[17365]: info: CoreCommandRouter::volumioGetState Sep 05 16:14:35 volumio volumio[17365]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:35 volumio volumio[17365]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 05 16:14:35 volumio volumio[17365]: info: Reading ALSA contributions from plugins. Sep 05 16:14:35 volumio volumio[17365]: info: VolumeController:: Volume=37 Mute =false Sep 05 16:14:35 volumio volumio[17365]: info: CoreStateMachine::pushState Sep 05 16:14:35 volumio volumio[17365]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:35 volumio volumio[17365]: info: CoreCommandRouter::volumioPushState Sep 05 16:14:35 volumio volumio[17365]: info: Asound.conf file unchanged, so no further update is needed Sep 05 16:14:35 volumio volumio[17365]: info: Output device has changed, restarting MPD Sep 05 16:14:35 volumio sudo[17479]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 16:14:35 volumio sudo[17479]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:35 volumio volumio[17365]: info: ___________ START PLUGINS ___________ Sep 05 16:14:35 volumio sudo[17479]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:35 volumio volumio[17365]: info: ControllerMpd::onStart: Initializing MPD Sep 05 16:14:35 volumio volumio[17365]: info: Creating MPD Configuration file Sep 05 16:14:35 volumio sudo[17490]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 16:14:35 volumio sudo[17490]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:35 volumio volumio[17365]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 16:14:35 volumio volumio[17365]: info: [1757081675354] CoreMusicLibrary::Adding element Last_100 Sep 05 16:14:35 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:14:35 volumio volumio[17365]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 16:14:35 volumio volumio[17365]: info: [1757081675360] CoreMusicLibrary::Adding element Webradio Sep 05 16:14:35 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:14:35 volumio sudo[17492]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 16:14:35 volumio sudo[17492]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:35 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 16:14:35 volumio volumio[17365]: info: Initializing BBC Radios Sep 05 16:14:35 volumio systemd[1]: Stopping Music Player Daemon... Sep 05 16:14:35 volumio sudo[17495]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 16:14:35 volumio sudo[17495]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:35 volumio sudo[17492]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:35 volumio systemd[1]: mpd.service: Succeeded. Sep 05 16:14:35 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 16:14:35 volumio systemd[1]: Starting Music Player Daemon... Sep 05 16:14:35 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 05 16:14:35 volumio systemd[1]: mpd.service: Succeeded. Sep 05 16:14:35 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 16:14:35 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 16:14:35 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:14:35 volumio systemd[1]: Starting Music Player Daemon... Sep 05 16:14:35 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 16:14:35 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 05 16:14:35 volumio volumio[17365]: info: Discovery: Getting this device information Sep 05 16:14:35 volumio volumio[17365]: info: CoreCommandRouter::volumioGetState Sep 05 16:14:35 volumio volumio[17365]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:35 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 16:14:35 volumio volumio[17365]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 16:14:35 volumio volumio[17365]: info: [1757081675508] CoreMusicLibrary::Adding element Jellyfin Sep 05 16:14:35 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:14:35 volumio volumio[17365]: Cannot find translation for source Jellyfin Sep 05 16:14:35 volumio volumio[17365]: info: [jellyfin] Initialized plugin with device info: {"id":"efb65fd7-7648-487c-b3ba-ebfb5cc10227","host":"http://192.168.0.171","name":"Volumio","type":"device","serviceName":"Volumio","state":{"status":"stop","volume":37,"mute":false,"artist":"","track":"","albumart":"http://192.168.0.171/albumart"}} Sep 05 16:14:35 volumio volumio[17365]: info: Creating Spotify config file Sep 05 16:14:35 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:35 volumio sudo[17509]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 16:14:35 volumio sudo[17509]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:35 volumio sudo[17509]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:35 volumio volumio[17365]: info: Loading i18n strings for locale hu Sep 05 16:14:35 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 05 16:14:35 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:35 volumio volumio[17365]: info: FusionDsp - mixtype--------------------- Hardware Sep 05 16:14:35 volumio volumio[17365]: info: Volumio Calling Home Sep 05 16:14:35 volumio volumio[17365]: info: Preparing to generate the ALSA configuration file Sep 05 16:14:35 volumio volumio[17365]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 05 16:14:35 volumio volumio[17365]: info: Reading ALSA contributions from plugins. Sep 05 16:14:35 volumio volumio[17365]: info: MPD Permissions set Sep 05 16:14:35 volumio volumio[17365]: info: MPD Permissions set Sep 05 16:14:35 volumio volumio[17365]: info: Spotify config file written Sep 05 16:14:36 volumio sudo[17530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 05 16:14:36 volumio sudo[17530]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:36 volumio volumio[17365]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Sep 05 16:14:36 volumio systemd[1]: Stopping go-librespot Daemon... Sep 05 16:14:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 05 16:14:36 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 05 16:14:36 volumio systemd[1]: Stopped go-librespot Daemon. Sep 05 16:14:36 volumio sudo[17534]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 05 16:14:36 volumio sudo[17534]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:36 volumio systemd[1]: Started go-librespot Daemon. Sep 05 16:14:36 volumio sudo[17534]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:36 volumio go-librespot[17533]: go-librespot daemon starting... Sep 05 16:14:36 volumio sudo[17530]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:36 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=info msg="running go-librespot 0.3.2" Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=debug msg="app state loaded" Sep 05 16:14:36 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 05 16:14:36 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:36 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:36 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:36 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:36 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:36 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:36 volumio sudo[17553]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 05 16:14:36 volumio sudo[17553]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:36 volumio sudo[17553]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:36 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:14:36 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:36 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:36 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:36 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:36 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:36 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:36 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:36 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:36 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:36 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:14:36 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:36 volumio volumio[17365]: info: No need to fix Spotify hosts Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=info msg="zeroconf server listening on port 38095" Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=debug msg="obtained new client token: AAA02iTxhQ7TXoaeDLE0prayE0N+Fi9my8wliy5T13VHAWn+3gG2eUm70mEmB2nrcsEnej8pJ0YYAyyD36/lkYW4PaCWPgbP6D7s4GZ8vJ3CqLY9FUFPLkmkZN7TGtCSe/8c2AErfiKTVAobgSjio6cDzc9EX9116qQXJSEOUp+3WeL8olYDh0+69DV7HoZBqCWEConIK2Rz2t/aUnBmm+VXRZOk9AAuRJcyzbcPJ/GYyYkZuSOd91WH1lw=" Sep 05 16:14:36 volumio volumio[17365]: info: Volumio called home Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=debug msg="completed keyexchange" Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=debug msg="completed challenge" Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 05 16:14:36 volumio volumio[17365]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 05 16:14:36 volumio volumio[17365]: SPOTIFY: BQCyUM5b4tg0fq3cWk7ma-xsHIkqo6s_6NrseKCQACZpU4V6S2rnyxneN1qO67350fuegrCIfI3PPT0DwiMTzhxDaNtrqLdTxSk3CD21coQntq1jada_eueGvHLutuLq83Twoe9mwW9UsaP1Cn4r-2FnYN4XIKdrrBv46Vlbk2eXFUOj-iKFh0P5lFxapaSRsa81D66A4l9Eoy5t1bweGM9RtTambjdeFLBCgz6xQk2TX1cwyI5LDq0mlfw_3otwgjmKp24wXf2m7NtQaiYG5zGwRft-59DMW5Ia1sbtR51tPuk Sep 05 16:14:36 volumio volumio[17365]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 05 16:14:36 volumio volumio[17365]: info: New Spotify access token = BQCyUM5b4tg0fq3cWk7ma-xsHIkqo6s_6NrseKCQACZpU4V6S2rnyxneN1qO67350fuegrCIfI3PPT0DwiMTzhxDaNtrqLdTxSk3CD21coQntq1jada_eueGvHLutuLq83Twoe9mwW9UsaP1Cn4r-2FnYN4XIKdrrBv46Vlbk2eXFUOj-iKFh0P5lFxapaSRsa81D66A4l9Eoy5t1bweGM9RtTambjdeFLBCgz6xQk2TX1cwyI5LDq0mlfw_3otwgjmKp24wXf2m7NtQaiYG5zGwRft-59DMW5Ia1sbtR51tPuk Sep 05 16:14:36 volumio volumio[17365]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 05 16:14:36 volumio volumio[17365]: info: CoreCommandRouter::volumioGetState Sep 05 16:14:36 volumio volumio[17365]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:36 volumio volumio[17365]: info: Asound.conf file unchanged, so no further update is needed Sep 05 16:14:36 volumio volumio[17365]: info: Output device has changed, restarting MPD Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 05 16:14:36 volumio sudo[17594]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 16:14:36 volumio sudo[17594]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:36 volumio sudo[17596]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 16:14:36 volumio sudo[17596]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=debug msg="dealer connection opened" Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=trace msg="starting accesspoint recv loop" Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=trace msg="starting dealer recv loop" Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=trace msg="received accesspoint ping" Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=debug msg="received connection id: Mzk3Y2JmNDMtMjAy...MjBBQTQ1OTg5Mw==" Sep 05 16:14:36 volumio sudo[17594]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=trace msg="received accesspoint pong ack" Sep 05 16:14:36 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 05 16:14:36 volumio systemd[1]: mpd.service: Succeeded. Sep 05 16:14:36 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 16:14:37 volumio volumio[17365]: info: MPD Permissions set Sep 05 16:14:37 volumio sudo[17601]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 05 16:14:37 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:37 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:14:37 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:37 volumio go-librespot[17533]: time="2025-09-05T16:14:37+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 05 16:14:37 volumio sudo[17601]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:37 volumio systemd[1]: Starting Music Player Daemon... Sep 05 16:14:37 volumio sudo[17601]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:37 volumio volumio[17365]: SPOTIFY: User informations: {"country":"HU","display_name":"4Misi","email":"sztako.mihaly@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/lo8jr34h0xh1vbcv2lgmaqkvv"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/lo8jr34h0xh1vbcv2lgmaqkvv","id":"lo8jr34h0xh1vbcv2lgmaqkvv","images":[],"product":"premium","type":"user","uri":"spotify:user:lo8jr34h0xh1vbcv2lgmaqkvv"} Sep 05 16:14:37 volumio volumio[17365]: info: Spotify Successfully logged in Sep 05 16:14:37 volumio volumio[17365]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 16:14:37 volumio volumio[17365]: info: [1757081677142] CoreMusicLibrary::Adding element Spotify Sep 05 16:14:37 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:14:37 volumio volumio[17365]: Cannot find translation for source Jellyfin Sep 05 16:14:37 volumio volumio[17365]: Cannot find translation for source Spotify Sep 05 16:14:37 volumio volumio[17365]: info: Upmpdcli Daemon Started Sep 05 16:14:37 volumio sudo[17602]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 16:14:37 volumio sudo[17602]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:37 volumio sudo[17602]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:37 volumio volumio[17365]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 05 16:14:37 volumio volumio[17365]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 16:14:37 volumio volumio[17365]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 05 16:14:37 volumio volumio[17365]: 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 05 16:14:37 volumio volumio[17365]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 05 16:14:37 volumio volumio[17365]: info: camilladsp service started and running in background, instance 1 Sep 05 16:14:37 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:37 volumio volumio[17365]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 05 16:14:37 volumio volumio[17365]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 05 16:14:37 volumio volumio[17365]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 05 16:14:37 volumio volumio[17365]: info: FusionDsp loaded Sep 05 16:14:37 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 16:14:37 volumio sudo[17624]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 05 16:14:37 volumio sudo[17624]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:37 volumio volumio[17365]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 05 16:14:37 volumio volumio[17365]: info: Adding Signal Path Element [object Object] Sep 05 16:14:37 volumio volumio[17365]: info: Adding fusiondspeq DSP Signal Path Element Sep 05 16:14:37 volumio volumio[17365]: info: FusionDsp - ---- installed callbackRead Sep 05 16:14:37 volumio volumio[17365]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 16:14:37 volumio sudo[17624]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:37 volumio volumio[17365]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 05 16:14:37 volumio volumio[17365]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 05 16:14:37 volumio volumio[17365]: at onErrorNT (internal/child_process.js:465:16) Sep 05 16:14:37 volumio volumio[17365]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Sep 05 16:14:37 volumio volumio[17365]: at runNextTicks (internal/process/task_queues.js:62:3) Sep 05 16:14:37 volumio volumio[17365]: at listOnTimeout (internal/timers.js:523:9) Sep 05 16:14:37 volumio volumio[17365]: at processTimers (internal/timers.js:497:7) { Sep 05 16:14:37 volumio volumio[17365]: errno: -2, Sep 05 16:14:37 volumio volumio[17365]: code: 'ENOENT', Sep 05 16:14:37 volumio volumio[17365]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 05 16:14:37 volumio volumio[17365]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 05 16:14:37 volumio volumio[17365]: spawnargs: [ Sep 05 16:14:37 volumio volumio[17365]: '-p', Sep 05 16:14:37 volumio volumio[17365]: 9876, Sep 05 16:14:37 volumio volumio[17365]: '-o', Sep 05 16:14:37 volumio volumio[17365]: '/tmp/camilladsp.log', Sep 05 16:14:37 volumio volumio[17365]: '-l', Sep 05 16:14:37 volumio volumio[17365]: 'warn', Sep 05 16:14:37 volumio volumio[17365]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 05 16:14:37 volumio volumio[17365]: ] Sep 05 16:14:37 volumio volumio[17365]: } Sep 05 16:14:37 volumio volumio[17365]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 16:14:38 volumio sudo[17635]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-05 16:13 Sep 05 16:14:38 volumio sudo[17635]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:38 volumio sudo[17635]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:38 volumio mpd[17611]: Sep 05 16:14 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 05 16:14:38 volumio systemd[1]: Started Music Player Daemon. Sep 05 16:14:38 volumio sudo[17490]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:38 volumio sudo[17596]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:38 volumio sudo[17495]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:38 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 05 16:14:38 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 05 16:14:38 volumio systemd[1]: Started dynamicswap service. Sep 05 16:14:38 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 05 16:14:39 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 05 16:14:39 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 18973. Sep 05 16:14:39 volumio systemd[1]: Stopped Volumio Backend Module. Sep 05 16:14:39 volumio systemd[1]: Started Volumio Backend Module. Sep 05 16:14:39 volumio systemd[1]: Started dynamicswap service. Sep 05 16:14:39 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 05 16:14:39 volumio volumio-remote-updater[504]: [2025-09-05 16:14:39] [info] asio async_connect error: system:111 (Connection refused) Sep 05 16:14:39 volumio volumio-remote-updater[504]: [2025-09-05 16:14:39] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 05 16:14:39 volumio volumio-remote-updater[504]: [2025-09-05 16:14:39] [error] handle_connect error: Underlying Transport Error Sep 05 16:14:40 volumio volumio[17647]: info: ------------------------------------------- Sep 05 16:14:40 volumio volumio[17647]: info: ----- Volumio3 ---- Sep 05 16:14:40 volumio volumio[17647]: info: ------------------------------------------- Sep 05 16:14:40 volumio volumio[17647]: info: ----- System startup ---- Sep 05 16:14:40 volumio volumio[17647]: info: ------------------------------------------- Sep 05 16:14:41 volumio volumio[17647]: info: MYVOLUMIO Environment detected Sep 05 16:14:41 volumio volumio[17647]: info: Plugin folders cleanup Sep 05 16:14:41 volumio volumio[17647]: info: Scanning into folder /volumio/app/plugins/ Sep 05 16:14:41 volumio volumio[17647]: info: Scanning category audio_interface Sep 05 16:14:41 volumio volumio[17647]: info: Scanning category miscellanea Sep 05 16:14:41 volumio volumio[17647]: info: Scanning category music_service Sep 05 16:14:41 volumio volumio[17647]: info: Scanning category plugins.json Sep 05 16:14:41 volumio volumio[17647]: info: Scanning category system_controller Sep 05 16:14:41 volumio volumio[17647]: info: Scanning category user_interface Sep 05 16:14:41 volumio volumio[17647]: info: Scanning into folder /data/plugins/ Sep 05 16:14:41 volumio volumio[17647]: info: Scanning category audio_interface Sep 05 16:14:41 volumio volumio[17647]: info: Scanning category music_service Sep 05 16:14:41 volumio volumio[17647]: info: Scanning category user_interface Sep 05 16:14:41 volumio volumio[17647]: info: Plugin folders cleanup completed Sep 05 16:14:41 volumio volumio[17647]: info: ------------------------------------------- Sep 05 16:14:41 volumio volumio[17647]: info: ----- Core plugins startup ---- Sep 05 16:14:41 volumio volumio[17647]: info: ------------------------------------------- Sep 05 16:14:41 volumio volumio[17647]: info: Loading plugins from folder /volumio/app/plugins/ Sep 05 16:14:41 volumio volumio[17647]: info: Adding plugin upnp to MyMusic Plugins Sep 05 16:14:41 volumio volumio[17647]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 05 16:14:41 volumio volumio[17647]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 05 16:14:41 volumio volumio[17647]: info: Loading plugins from folder /data/plugins/ Sep 05 16:14:41 volumio volumio[17647]: info: Loading plugin "system"... Sep 05 16:14:41 volumio volumio[17647]: info: Loading plugin "appearance"... Sep 05 16:14:42 volumio volumio[17647]: info: Loading plugin "network"... Sep 05 16:14:42 volumio volumio[17647]: info: Refreshing Cached IP Addresses Sep 05 16:14:42 volumio sudo[17674]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 05 16:14:42 volumio sudo[17674]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:42 volumio sudo[17674]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:42 volumio sudo[17676]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 05 16:14:42 volumio volumio[17647]: info: Loading plugin "services"... Sep 05 16:14:42 volumio sudo[17676]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:42 volumio volumio[17647]: info: Loading plugin "alsa_controller"... Sep 05 16:14:42 volumio sudo[17676]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:43 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 16:14:43 volumio volumio[17647]: info: Loading plugin "wizard"... Sep 05 16:14:43 volumio volumio[17647]: info: Loading plugin "networkfs"... Sep 05 16:14:43 volumio volumio[17647]: info: Starting Udev Watcher for removable devices Sep 05 16:14:43 volumio volumio[17647]: info: Ignoring mount for partition: boot Sep 05 16:14:43 volumio volumio[17647]: info: Ignoring mount for partition: volumio Sep 05 16:14:43 volumio volumio[17647]: info: Ignoring mount for partition: volumio_data Sep 05 16:14:43 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 16:14:43 volumio volumio[17647]: info: Loading plugin "volumio_command_line_client"... Sep 05 16:14:43 volumio volumio[17647]: info: Loading plugin "upnp"... Sep 05 16:14:43 volumio volumio[17647]: info: [1757081683090] Starting Upmpd Daemon Sep 05 16:14:43 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 16:14:43 volumio volumio[17647]: info: Loading plugin "my_music"... Sep 05 16:14:43 volumio volumio[17647]: info: Loading plugin "mpd"... Sep 05 16:14:43 volumio volumio[17647]: info: Plugin upnp_browser is not enabled Sep 05 16:14:43 volumio volumio[17647]: info: Loading plugin "alarm-clock"... Sep 05 16:14:43 volumio volumio[17647]: info: Plugin airplay_emulation is not enabled Sep 05 16:14:43 volumio volumio[17647]: info: Loading plugin "last_100"... Sep 05 16:14:43 volumio volumio[17647]: info: Loading plugin "webradio"... Sep 05 16:14:43 volumio volumio[17647]: info: Loading plugin "i2s_dacs"... Sep 05 16:14:43 volumio volumio[17647]: info: Loading plugin "volumiodiscovery"... Sep 05 16:14:43 volumio volumio[17647]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 05 16:14:43 volumio volumio[17647]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 16:14:43 volumio volumio[17647]: *** WARNING *** For more information see Sep 05 16:14:43 volumio node[17647]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 05 16:14:43 volumio volumio[17647]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 05 16:14:43 volumio volumio[17647]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 16:14:43 volumio volumio[17647]: *** WARNING *** For more information see Sep 05 16:14:43 volumio node[17647]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 16:14:43 volumio node[17647]: *** WARNING *** For more information see Sep 05 16:14:43 volumio node[17647]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 05 16:14:43 volumio node[17647]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 16:14:43 volumio node[17647]: *** WARNING *** For more information see Sep 05 16:14:43 volumio volumio[17647]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 05 16:14:43 volumio volumio[17647]: info: Discovery: Started advertising with name: Volumio Sep 05 16:14:43 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 16:14:43 volumio volumio[17647]: info: Loading plugin "jellyfin"... Sep 05 16:14:44 volumio volumio[17647]: info: Loading plugin "spop"... Sep 05 16:14:45 volumio volumio[17647]: info: Loading plugin "ytcr"... Sep 05 16:14:49 volumio volumio-remote-updater[504]: [2025-09-05 16:14:49] [connect] Successful connection Sep 05 16:14:50 volumio volumio[17647]: info: Loading plugin "outputs"... Sep 05 16:14:50 volumio volumio[17647]: info: Loading plugin "albumart"... Sep 05 16:14:50 volumio volumio[17647]: info: Plugin example_plugin is not enabled Sep 05 16:14:50 volumio volumio[17647]: info: Loading plugin "inputs"... Sep 05 16:14:50 volumio volumio[17647]: info: Loading plugin "updater_comm"... Sep 05 16:14:50 volumio volumio[17647]: info: Plugin mpdemulation is not enabled Sep 05 16:14:50 volumio volumio[17647]: info: Loading plugin "rest_api"... Sep 05 16:14:50 volumio volumio[17647]: info: Loading plugin "websocket"... Sep 05 16:14:50 volumio volumio[17647]: info: Starting Socket.io Server version 2.3.0 Sep 05 16:14:50 volumio volumio[17647]: info: Loading plugin "fusiondsp"... Sep 05 16:14:50 volumio volumio[17647]: Forking 3 albumart workers Sep 05 16:14:50 volumio volumio[17647]: info: Applying required configuration parameters for plugin fusiondsp Sep 05 16:14:50 volumio volumio[17647]: info: Loading plugin "Systeminfo"... Sep 05 16:14:51 volumio volumio[17647]: info: Loading i18n strings for locale hu Sep 05 16:14:51 volumio volumio[17647]: Updating browse sources language Sep 05 16:14:51 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:14:51 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 16:14:51 volumio volumio[17647]: info: CoreCommandRouter::initPlayerControls Sep 05 16:14:51 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:51 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:51 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:51 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:51 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:51 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:51 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:51 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:51 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 16:14:51 volumio volumio[17647]: Express server listening on port 3000 Sep 05 16:14:51 volumio volumio[17647]: [Metrics] WebUI: 10s 997.66ms Sep 05 16:14:51 volumio volumio[17647]: info: CoreStateMachine::resetVolumioState Sep 05 16:14:51 volumio volumio[17647]: info: CoreStateMachine::getcurrentVolume Sep 05 16:14:51 volumio volumio[17647]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 16:14:51 volumio volumio[17647]: Starting albumart workers Sep 05 16:14:51 volumio volumio[17647]: Starting albumart workers Sep 05 16:14:51 volumio volumio[17647]: info: Volumio Network Manager: Network status updated: 1 Sep 05 16:14:51 volumio volumio[17647]: Starting albumart workers Sep 05 16:14:51 volumio volumio-remote-updater[504]: [2025-09-05 16:14:51] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1757081689 101 Sep 05 16:14:51 volumio volumio[17647]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Sep 05 16:14:51 volumio volumio[17647]: info: CoreCommandRouter::volumioGetState Sep 05 16:14:51 volumio volumio[17647]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:51 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:14:51 volumio volumio[17647]: info: VolumeController:: Volume=37 Mute =false Sep 05 16:14:51 volumio volumio[17647]: info: CoreStateMachine::pushState Sep 05 16:14:51 volumio volumio[17647]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:51 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 16:14:51 volumio volumio[17647]: info: CoreCommandRouter::volumioPushState Sep 05 16:14:51 volumio volumio[17647]: info: CoreStateMachine::updateTrackBlock Sep 05 16:14:51 volumio volumio[17647]: info: CorePlayQueue::getTrackBlock Sep 05 16:14:51 volumio volumio[17647]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 16:14:51 volumio volumio[17647]: info: CoreStateMachine::setRepeat false single undefined Sep 05 16:14:51 volumio volumio[17647]: info: CoreStateMachine::pushState Sep 05 16:14:51 volumio volumio[17647]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:51 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 16:14:51 volumio volumio[17647]: info: CoreCommandRouter::volumioPushState Sep 05 16:14:51 volumio volumio[17647]: info: CoreStateMachine::setRandom true Sep 05 16:14:51 volumio volumio[17647]: info: CoreStateMachine::pushState Sep 05 16:14:51 volumio volumio[17647]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:51 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 16:14:51 volumio volumio[17647]: info: CoreCommandRouter::volumioPushState Sep 05 16:14:51 volumio volumio[17647]: info: Setting Device type: Raspberry PI Sep 05 16:14:51 volumio volumio[17647]: info: Listing playlists Sep 05 16:14:51 volumio volumio[17647]: info: Listing playlists Sep 05 16:14:52 volumio volumio[17647]: info: Completed loading Core Plugins Sep 05 16:14:52 volumio volumio[17647]: info: Preparing to generate the ALSA configuration file Sep 05 16:14:52 volumio volumio[17647]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227 Sep 05 16:14:52 volumio volumio[17647]: info: Discovery: Found device Volumio Sep 05 16:14:52 volumio volumio[17647]: info: CoreCommandRouter::volumioGetState Sep 05 16:14:52 volumio volumio[17647]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:52 volumio volumio[17647]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 05 16:14:52 volumio volumio[17647]: info: Reading ALSA contributions from plugins. Sep 05 16:14:52 volumio volumio[17647]: info: VolumeController:: Volume=37 Mute =false Sep 05 16:14:52 volumio volumio[17647]: info: CoreStateMachine::pushState Sep 05 16:14:52 volumio volumio[17647]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:52 volumio volumio[17647]: info: CoreCommandRouter::volumioPushState Sep 05 16:14:52 volumio volumio[17647]: info: Asound.conf file unchanged, so no further update is needed Sep 05 16:14:52 volumio volumio[17647]: info: Output device has changed, restarting MPD Sep 05 16:14:52 volumio sudo[17754]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 16:14:52 volumio sudo[17754]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:52 volumio volumio[17647]: info: ___________ START PLUGINS ___________ Sep 05 16:14:52 volumio sudo[17754]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:52 volumio volumio[17647]: info: ControllerMpd::onStart: Initializing MPD Sep 05 16:14:52 volumio volumio[17647]: info: Creating MPD Configuration file Sep 05 16:14:52 volumio sudo[17758]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 16:14:52 volumio sudo[17758]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:52 volumio sudo[17764]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 16:14:52 volumio sudo[17764]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:52 volumio systemd[1]: Stopping Music Player Daemon... Sep 05 16:14:52 volumio sudo[17764]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:52 volumio volumio[17647]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 16:14:52 volumio volumio[17647]: info: [1757081692365] CoreMusicLibrary::Adding element Last_100 Sep 05 16:14:52 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:14:52 volumio volumio[17647]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 16:14:52 volumio volumio[17647]: info: [1757081692374] CoreMusicLibrary::Adding element Webradio Sep 05 16:14:52 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:14:52 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 16:14:52 volumio systemd[1]: mpd.service: Succeeded. Sep 05 16:14:52 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 16:14:52 volumio sudo[17771]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 16:14:52 volumio sudo[17771]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:52 volumio volumio[17647]: info: Initializing BBC Radios Sep 05 16:14:52 volumio systemd[1]: Starting Music Player Daemon... Sep 05 16:14:52 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 05 16:14:52 volumio systemd[1]: mpd.service: Succeeded. Sep 05 16:14:52 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 16:14:52 volumio systemd[1]: Starting Music Player Daemon... Sep 05 16:14:52 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 16:14:52 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:14:52 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 16:14:52 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 05 16:14:52 volumio volumio[17647]: info: Discovery: Getting this device information Sep 05 16:14:52 volumio volumio[17647]: info: CoreCommandRouter::volumioGetState Sep 05 16:14:52 volumio volumio[17647]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:52 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 16:14:52 volumio volumio[17647]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 16:14:52 volumio volumio[17647]: info: [1757081692529] CoreMusicLibrary::Adding element Jellyfin Sep 05 16:14:52 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:14:52 volumio volumio[17647]: Cannot find translation for source Jellyfin Sep 05 16:14:52 volumio volumio[17647]: info: [jellyfin] Initialized plugin with device info: {"id":"efb65fd7-7648-487c-b3ba-ebfb5cc10227","host":"http://192.168.0.171","name":"Volumio","type":"device","serviceName":"Volumio","state":{"status":"stop","volume":37,"mute":false,"artist":"","track":"","albumart":"http://192.168.0.171/albumart"}} Sep 05 16:14:52 volumio volumio[17647]: info: Creating Spotify config file Sep 05 16:14:52 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:52 volumio sudo[17784]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 16:14:52 volumio sudo[17784]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:52 volumio sudo[17784]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:52 volumio volumio[17647]: info: Loading i18n strings for locale hu Sep 05 16:14:52 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 05 16:14:52 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:52 volumio volumio[17647]: info: FusionDsp - mixtype--------------------- Hardware Sep 05 16:14:52 volumio volumio[17647]: info: Volumio Calling Home Sep 05 16:14:52 volumio volumio[17647]: info: Preparing to generate the ALSA configuration file Sep 05 16:14:52 volumio volumio[17647]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 05 16:14:52 volumio volumio[17647]: info: Reading ALSA contributions from plugins. Sep 05 16:14:52 volumio volumio[17647]: info: MPD Permissions set Sep 05 16:14:52 volumio volumio[17647]: info: MPD Permissions set Sep 05 16:14:52 volumio volumio[17647]: info: Spotify config file written Sep 05 16:14:53 volumio sudo[17809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 05 16:14:53 volumio sudo[17809]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:53 volumio volumio[17647]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Sep 05 16:14:53 volumio volumio[17647]: info: Volumio called home Sep 05 16:14:53 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:53 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:53 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:53 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:53 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:53 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:53 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:53 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:53 volumio systemd[1]: Stopping go-librespot Daemon... Sep 05 16:14:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 05 16:14:53 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 05 16:14:53 volumio systemd[1]: Stopped go-librespot Daemon. Sep 05 16:14:53 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:14:53 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:53 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:53 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:53 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:53 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:53 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:53 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:53 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:53 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:53 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:14:53 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:53 volumio volumio[17647]: info: No need to fix Spotify hosts Sep 05 16:14:53 volumio systemd[1]: Started go-librespot Daemon. Sep 05 16:14:53 volumio go-librespot[17812]: go-librespot daemon starting... Sep 05 16:14:53 volumio sudo[17809]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=info msg="running go-librespot 0.3.2" Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=debug msg="app state loaded" Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 05 16:14:53 volumio sudo[17827]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 05 16:14:53 volumio sudo[17827]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:53 volumio sudo[17830]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 05 16:14:53 volumio sudo[17830]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:53 volumio sudo[17827]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:53 volumio sudo[17830]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=info msg="zeroconf server listening on port 39013" Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=debug msg="obtained new client token: AABb8Nvzkinuywmix8VtTBcZq1KOuTsNFV/Ksr4HGSrbI9uEWSCeqDCL8IVyTfhBU3iCdNEmHv5YrJV44CnQKqrH/6b19qp4QW9XgQr1x178Xx8ShuO/AYg6PSQcNvbQkBVbXAPweXtceoEpAt6qnsvRtk9dOoETdSoIxoSWVo8MFKp/JwoMQWo180PMWLNAnDdCOQ3WRkXaEg/FrAG0VGeuESWc9QxEU+pPOCLBpksqKkaTmYumPRd3RJQ=" Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=debug msg="completed keyexchange" Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=debug msg="completed challenge" Sep 05 16:14:53 volumio volumio[17647]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 05 16:14:53 volumio volumio[17647]: SPOTIFY: BQDxvRm3Lz4EWF1MZQ_D8X_Fva4jdb3NlgM96zZZfEAwJN27oGNcogj75BvQ8M-bTBFryGVktY1N-8nbKdRAhLdL76G-JaqKIMCDuhI-u5qETC4o8i4SKeQqLl3eemCuCedO1rLG7OwkIsuq-_Mj1Bci3W0vnQCmimNTz63H_L1_yHEGVdxv0lbPu-bm_vq4ghiB5UGVlvm5GfOl0ImyDUOEqx0_gIyKGiATVQylrK3NXyIHIUuHJKaT-S6bOi_BPJfplUyZxzMIefSgVbH3bBdQ8yg35Vj-fhc8O-IpwCkDvhA Sep 05 16:14:53 volumio volumio[17647]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 05 16:14:53 volumio volumio[17647]: info: New Spotify access token = BQDxvRm3Lz4EWF1MZQ_D8X_Fva4jdb3NlgM96zZZfEAwJN27oGNcogj75BvQ8M-bTBFryGVktY1N-8nbKdRAhLdL76G-JaqKIMCDuhI-u5qETC4o8i4SKeQqLl3eemCuCedO1rLG7OwkIsuq-_Mj1Bci3W0vnQCmimNTz63H_L1_yHEGVdxv0lbPu-bm_vq4ghiB5UGVlvm5GfOl0ImyDUOEqx0_gIyKGiATVQylrK3NXyIHIUuHJKaT-S6bOi_BPJfplUyZxzMIefSgVbH3bBdQ8yg35Vj-fhc8O-IpwCkDvhA Sep 05 16:14:53 volumio volumio[17647]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 05 16:14:53 volumio volumio[17647]: info: CoreCommandRouter::volumioGetState Sep 05 16:14:53 volumio volumio[17647]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:53 volumio volumio[17647]: info: Asound.conf file unchanged, so no further update is needed Sep 05 16:14:53 volumio volumio[17647]: info: Output device has changed, restarting MPD Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 05 16:14:53 volumio sudo[17834]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 16:14:53 volumio sudo[17834]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:53 volumio sudo[17834]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:53 volumio sudo[17836]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 16:14:53 volumio sudo[17836]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=debug msg="dealer connection opened" Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=trace msg="starting accesspoint recv loop" Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=trace msg="starting dealer recv loop" Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=trace msg="received accesspoint ping" Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=debug msg="received connection id: MDI0YWNjYzgtMGE5...MEU4NUIxMzRDOQ==" Sep 05 16:14:53 volumio volumio[17647]: info: MPD Permissions set Sep 05 16:14:53 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 05 16:14:53 volumio systemd[1]: mpd.service: Succeeded. Sep 05 16:14:53 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=trace msg="received accesspoint pong ack" Sep 05 16:14:53 volumio sudo[17842]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 05 16:14:53 volumio sudo[17842]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:53 volumio systemd[1]: Starting Music Player Daemon... Sep 05 16:14:54 volumio sudo[17842]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:54 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:54 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:54 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:54 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:54 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:54 volumio go-librespot[17812]: time="2025-09-05T16:14:54+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 05 16:14:54 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:54 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:54 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:54 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:14:54 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:54 volumio volumio[17647]: info: Upmpdcli Daemon Started Sep 05 16:14:54 volumio sudo[17841]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 16:14:54 volumio sudo[17841]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:54 volumio volumio[17647]: SPOTIFY: User informations: {"country":"HU","display_name":"4Misi","email":"sztako.mihaly@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/lo8jr34h0xh1vbcv2lgmaqkvv"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/lo8jr34h0xh1vbcv2lgmaqkvv","id":"lo8jr34h0xh1vbcv2lgmaqkvv","images":[],"product":"premium","type":"user","uri":"spotify:user:lo8jr34h0xh1vbcv2lgmaqkvv"} Sep 05 16:14:54 volumio volumio[17647]: info: Spotify Successfully logged in Sep 05 16:14:54 volumio sudo[17841]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:54 volumio volumio[17647]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 16:14:54 volumio volumio[17647]: info: [1757081694116] CoreMusicLibrary::Adding element Spotify Sep 05 16:14:54 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:14:54 volumio volumio[17647]: Cannot find translation for source Jellyfin Sep 05 16:14:54 volumio volumio[17647]: Cannot find translation for source Spotify Sep 05 16:14:54 volumio volumio[17647]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 05 16:14:54 volumio volumio[17647]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 16:14:54 volumio volumio[17647]: Error: bind EADDRINUSE 192.168.0.171:50386 Sep 05 16:14:54 volumio volumio[17647]: at dgram.js:337:20 Sep 05 16:14:54 volumio volumio[17647]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Sep 05 16:14:54 volumio volumio[17647]: errno: -98, Sep 05 16:14:54 volumio volumio[17647]: code: 'EADDRINUSE', Sep 05 16:14:54 volumio volumio[17647]: syscall: 'bind', Sep 05 16:14:54 volumio volumio[17647]: address: '192.168.0.171', Sep 05 16:14:54 volumio volumio[17647]: port: 50386 Sep 05 16:14:54 volumio volumio[17647]: } Sep 05 16:14:54 volumio volumio[17647]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 16:14:55 volumio sudo[17862]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-05 16:13 Sep 05 16:14:55 volumio sudo[17862]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:55 volumio sudo[17862]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:55 volumio mpd[17850]: Sep 05 16:14 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 05 16:14:55 volumio systemd[1]: Started Music Player Daemon. Sep 05 16:14:55 volumio sudo[17758]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:55 volumio sudo[17836]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:55 volumio sudo[17771]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:55 volumio volumio-remote-updater[504]: [2025-09-05 16:14:55] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 05 16:14:55 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 05 16:14:55 volumio volumio-remote-updater[504]: [2025-09-05 16:14:55] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 05 16:14:55 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 05 16:14:55 volumio systemd[1]: Started dynamicswap service. Sep 05 16:14:55 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 05 16:14:56 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 05 16:14:56 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 18974. Sep 05 16:14:56 volumio systemd[1]: Started dynamicswap service. Sep 05 16:14:56 volumio systemd[1]: Stopped Volumio Backend Module. Sep 05 16:14:56 volumio systemd[1]: Started Volumio Backend Module. Sep 05 16:14:56 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 05 16:14:57 volumio volumio[17879]: info: ------------------------------------------- Sep 05 16:14:57 volumio volumio[17879]: info: ----- Volumio3 ---- Sep 05 16:14:57 volumio volumio[17879]: info: ------------------------------------------- Sep 05 16:14:57 volumio volumio[17879]: info: ----- System startup ---- Sep 05 16:14:57 volumio volumio[17879]: info: ------------------------------------------- Sep 05 16:14:58 volumio volumio[17879]: info: MYVOLUMIO Environment detected Sep 05 16:14:58 volumio volumio[17879]: info: Plugin folders cleanup Sep 05 16:14:58 volumio volumio[17879]: info: Scanning into folder /volumio/app/plugins/ Sep 05 16:14:58 volumio volumio[17879]: info: Scanning category audio_interface Sep 05 16:14:58 volumio volumio[17879]: info: Scanning category miscellanea Sep 05 16:14:58 volumio volumio[17879]: info: Scanning category music_service Sep 05 16:14:58 volumio volumio[17879]: info: Scanning category plugins.json Sep 05 16:14:58 volumio volumio[17879]: info: Scanning category system_controller Sep 05 16:14:58 volumio volumio[17879]: info: Scanning category user_interface Sep 05 16:14:58 volumio volumio[17879]: info: Scanning into folder /data/plugins/ Sep 05 16:14:58 volumio volumio[17879]: info: Scanning category audio_interface Sep 05 16:14:58 volumio volumio[17879]: info: Scanning category music_service Sep 05 16:14:58 volumio volumio[17879]: info: Scanning category user_interface Sep 05 16:14:58 volumio volumio[17879]: info: Plugin folders cleanup completed Sep 05 16:14:58 volumio volumio[17879]: info: ------------------------------------------- Sep 05 16:14:58 volumio volumio[17879]: info: ----- Core plugins startup ---- Sep 05 16:14:58 volumio volumio[17879]: info: ------------------------------------------- Sep 05 16:14:58 volumio volumio[17879]: info: Loading plugins from folder /volumio/app/plugins/ Sep 05 16:14:58 volumio volumio[17879]: info: Adding plugin upnp to MyMusic Plugins Sep 05 16:14:58 volumio volumio[17879]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 05 16:14:58 volumio volumio[17879]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 05 16:14:58 volumio volumio[17879]: info: Loading plugins from folder /data/plugins/ Sep 05 16:14:58 volumio volumio[17879]: info: Loading plugin "system"... Sep 05 16:14:58 volumio volumio[17879]: info: Loading plugin "appearance"... Sep 05 16:14:59 volumio volumio[17879]: info: Loading plugin "network"... Sep 05 16:14:59 volumio volumio[17879]: info: Refreshing Cached IP Addresses Sep 05 16:14:59 volumio sudo[17906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 05 16:14:59 volumio sudo[17906]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:59 volumio sudo[17906]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:59 volumio sudo[17908]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 05 16:14:59 volumio volumio[17879]: info: Loading plugin "services"... Sep 05 16:14:59 volumio sudo[17908]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:59 volumio volumio[17879]: info: Loading plugin "alsa_controller"... Sep 05 16:14:59 volumio sudo[17908]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:59 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 16:14:59 volumio volumio[17879]: info: Loading plugin "wizard"... Sep 05 16:14:59 volumio volumio[17879]: info: Loading plugin "networkfs"... Sep 05 16:15:00 volumio volumio[17879]: info: Starting Udev Watcher for removable devices Sep 05 16:15:00 volumio volumio[17879]: info: Ignoring mount for partition: boot Sep 05 16:15:00 volumio volumio[17879]: info: Ignoring mount for partition: volumio Sep 05 16:15:00 volumio volumio[17879]: info: Ignoring mount for partition: volumio_data Sep 05 16:15:00 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 16:15:00 volumio volumio[17879]: info: Loading plugin "volumio_command_line_client"... Sep 05 16:15:00 volumio volumio[17879]: info: Loading plugin "upnp"... Sep 05 16:15:00 volumio volumio[17879]: info: [1757081700054] Starting Upmpd Daemon Sep 05 16:15:00 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 16:15:00 volumio volumio[17879]: info: Loading plugin "my_music"... Sep 05 16:15:00 volumio volumio[17879]: info: Loading plugin "mpd"... Sep 05 16:15:00 volumio volumio[17879]: info: Plugin upnp_browser is not enabled Sep 05 16:15:00 volumio volumio[17879]: info: Loading plugin "alarm-clock"... Sep 05 16:15:00 volumio volumio[17879]: info: Plugin airplay_emulation is not enabled Sep 05 16:15:00 volumio volumio[17879]: info: Loading plugin "last_100"... Sep 05 16:15:00 volumio volumio[17879]: info: Loading plugin "webradio"... Sep 05 16:15:00 volumio volumio[17879]: info: Loading plugin "i2s_dacs"... Sep 05 16:15:00 volumio volumio[17879]: info: Loading plugin "volumiodiscovery"... Sep 05 16:15:00 volumio volumio[17879]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 05 16:15:00 volumio volumio[17879]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 16:15:00 volumio volumio[17879]: *** WARNING *** For more information see Sep 05 16:15:00 volumio node[17879]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 05 16:15:00 volumio volumio[17879]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 05 16:15:00 volumio volumio[17879]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 16:15:00 volumio volumio[17879]: *** WARNING *** For more information see Sep 05 16:15:00 volumio node[17879]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 16:15:00 volumio node[17879]: *** WARNING *** For more information see Sep 05 16:15:00 volumio node[17879]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 05 16:15:00 volumio node[17879]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 16:15:00 volumio node[17879]: *** WARNING *** For more information see Sep 05 16:15:00 volumio volumio[17879]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 05 16:15:00 volumio volumio[17879]: info: Discovery: Started advertising with name: Volumio Sep 05 16:15:00 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 16:15:00 volumio volumio[17879]: info: Loading plugin "jellyfin"... Sep 05 16:15:00 volumio volumio-remote-updater[504]: [2025-09-05 16:15:00] [connect] Successful connection Sep 05 16:15:01 volumio volumio[17879]: info: Loading plugin "spop"... Sep 05 16:15:02 volumio volumio[17879]: info: Loading plugin "ytcr"... Sep 05 16:15:07 volumio volumio[17879]: info: Loading plugin "outputs"... Sep 05 16:15:07 volumio volumio[17879]: info: Loading plugin "albumart"... Sep 05 16:15:07 volumio volumio[17879]: info: Plugin example_plugin is not enabled Sep 05 16:15:07 volumio volumio[17879]: info: Loading plugin "inputs"... Sep 05 16:15:07 volumio volumio[17879]: info: Loading plugin "updater_comm"... Sep 05 16:15:07 volumio volumio[17879]: info: Plugin mpdemulation is not enabled Sep 05 16:15:07 volumio volumio[17879]: info: Loading plugin "rest_api"... Sep 05 16:15:07 volumio volumio[17879]: info: Loading plugin "websocket"... Sep 05 16:15:07 volumio volumio[17879]: info: Starting Socket.io Server version 2.3.0 Sep 05 16:15:07 volumio volumio[17879]: info: Loading plugin "fusiondsp"... Sep 05 16:15:07 volumio volumio[17879]: Forking 3 albumart workers Sep 05 16:15:07 volumio volumio[17879]: info: Applying required configuration parameters for plugin fusiondsp Sep 05 16:15:07 volumio volumio[17879]: info: Loading plugin "Systeminfo"... Sep 05 16:15:08 volumio volumio[17879]: info: Loading i18n strings for locale hu Sep 05 16:15:08 volumio volumio[17879]: Updating browse sources language Sep 05 16:15:08 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:15:08 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 16:15:08 volumio volumio[17879]: info: CoreCommandRouter::initPlayerControls Sep 05 16:15:08 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:15:08 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:15:08 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:15:08 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:15:08 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:15:08 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:15:08 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:15:08 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:15:08 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 16:15:08 volumio volumio[17879]: Express server listening on port 3000 Sep 05 16:15:08 volumio volumio[17879]: [Metrics] WebUI: 11s 8.32ms Sep 05 16:15:08 volumio volumio[17879]: info: CoreStateMachine::resetVolumioState Sep 05 16:15:08 volumio volumio[17879]: info: CoreStateMachine::getcurrentVolume Sep 05 16:15:08 volumio volumio[17879]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 16:15:08 volumio volumio[17879]: Starting albumart workers Sep 05 16:15:08 volumio volumio[17879]: Starting albumart workers Sep 05 16:15:08 volumio volumio[17879]: info: Volumio Network Manager: Network status updated: 1 Sep 05 16:15:08 volumio volumio[17879]: Starting albumart workers Sep 05 16:15:08 volumio volumio[17879]: info: CoreCommandRouter::volumioGetState Sep 05 16:15:08 volumio volumio[17879]: info: CorePlayQueue::getTrack 0 Sep 05 16:15:08 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:15:08 volumio volumio[17879]: info: CoreStateMachine::setRepeat false single undefined Sep 05 16:15:08 volumio volumio[17879]: info: CoreStateMachine::pushState Sep 05 16:15:08 volumio volumio[17879]: info: CorePlayQueue::getTrack 0 Sep 05 16:15:08 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 16:15:08 volumio volumio[17879]: info: CoreCommandRouter::volumioPushState Sep 05 16:15:08 volumio volumio[17879]: info: CoreStateMachine::setRandom true Sep 05 16:15:08 volumio volumio[17879]: info: CoreStateMachine::pushState Sep 05 16:15:08 volumio volumio[17879]: info: CorePlayQueue::getTrack 0 Sep 05 16:15:08 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 16:15:08 volumio volumio[17879]: info: CoreCommandRouter::volumioPushState Sep 05 16:15:08 volumio volumio[17879]: info: Setting Device type: Raspberry PI Sep 05 16:15:08 volumio volumio[17879]: info: Listing playlists Sep 05 16:15:08 volumio volumio[17879]: info: Listing playlists Sep 05 16:15:08 volumio volumio[17879]: info: VolumeController:: Volume=37 Mute =false Sep 05 16:15:08 volumio volumio[17879]: info: CoreStateMachine::pushState Sep 05 16:15:08 volumio volumio[17879]: info: CorePlayQueue::getTrack 0 Sep 05 16:15:08 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 16:15:08 volumio volumio[17879]: info: CoreCommandRouter::volumioPushState Sep 05 16:15:08 volumio volumio[17879]: info: CoreStateMachine::updateTrackBlock Sep 05 16:15:08 volumio volumio[17879]: info: CorePlayQueue::getTrackBlock Sep 05 16:15:08 volumio volumio[17879]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 16:15:09 volumio volumio[17879]: info: Completed loading Core Plugins Sep 05 16:15:09 volumio volumio[17879]: info: Preparing to generate the ALSA configuration file Sep 05 16:15:09 volumio volumio[17879]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227 Sep 05 16:15:09 volumio volumio[17879]: info: Discovery: Found device Volumio Sep 05 16:15:09 volumio volumio[17879]: info: CoreCommandRouter::volumioGetState Sep 05 16:15:09 volumio volumio[17879]: info: CorePlayQueue::getTrack 0 Sep 05 16:15:09 volumio volumio[17879]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 05 16:15:09 volumio volumio[17879]: info: Reading ALSA contributions from plugins. Sep 05 16:15:09 volumio volumio[17879]: info: Asound.conf file unchanged, so no further update is needed Sep 05 16:15:09 volumio volumio[17879]: info: Output device has changed, restarting MPD Sep 05 16:15:09 volumio sudo[17990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 16:15:09 volumio sudo[17990]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:15:09 volumio volumio[17879]: info: ___________ START PLUGINS ___________ Sep 05 16:15:09 volumio sudo[17990]: pam_unix(sudo:session): session closed for user root Sep 05 16:15:09 volumio volumio[17879]: info: ControllerMpd::onStart: Initializing MPD Sep 05 16:15:09 volumio volumio[17879]: info: Creating MPD Configuration file Sep 05 16:15:09 volumio sudo[17993]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 16:15:09 volumio sudo[17993]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:15:09 volumio systemd[1]: Stopping Music Player Daemon... Sep 05 16:15:09 volumio volumio[17879]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 16:15:09 volumio volumio[17879]: info: [1757081709350] CoreMusicLibrary::Adding element Last_100 Sep 05 16:15:09 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:15:09 volumio sudo[18004]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 16:15:09 volumio sudo[18004]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:15:09 volumio volumio[17879]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 16:15:09 volumio volumio[17879]: info: [1757081709362] CoreMusicLibrary::Adding element Webradio Sep 05 16:15:09 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:15:09 volumio sudo[18004]: pam_unix(sudo:session): session closed for user root Sep 05 16:15:09 volumio systemd[1]: mpd.service: Succeeded. Sep 05 16:15:09 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 16:15:09 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 16:15:09 volumio volumio[17879]: info: Initializing BBC Radios Sep 05 16:15:09 volumio sudo[18006]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 16:15:09 volumio sudo[18006]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:15:09 volumio systemd[1]: Starting Music Player Daemon... Sep 05 16:15:09 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 05 16:15:09 volumio systemd[1]: mpd.service: Succeeded. Sep 05 16:15:09 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 16:15:09 volumio systemd[1]: Starting Music Player Daemon... Sep 05 16:15:09 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 16:15:09 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:15:09 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 16:15:09 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 05 16:15:09 volumio volumio[17879]: info: Discovery: Getting this device information Sep 05 16:15:09 volumio volumio[17879]: info: CoreCommandRouter::volumioGetState Sep 05 16:15:09 volumio volumio[17879]: info: CorePlayQueue::getTrack 0 Sep 05 16:15:09 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 16:15:09 volumio volumio[17879]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 16:15:09 volumio volumio[17879]: info: [1757081709506] CoreMusicLibrary::Adding element Jellyfin Sep 05 16:15:09 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:15:09 volumio volumio[17879]: Cannot find translation for source Jellyfin Sep 05 16:15:09 volumio volumio[17879]: info: [jellyfin] Initialized plugin with device info: {"id":"efb65fd7-7648-487c-b3ba-ebfb5cc10227","host":"http://192.168.0.171","name":"Volumio","type":"device","serviceName":"Volumio","state":{"status":"stop","volume":37,"mute":false,"artist":"","track":"","albumart":"http://192.168.0.171/albumart"}} Sep 05 16:15:09 volumio volumio[17879]: info: Creating Spotify config file Sep 05 16:15:09 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:15:09 volumio sudo[18017]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 16:15:09 volumio sudo[18017]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:15:09 volumio sudo[18017]: pam_unix(sudo:session): session closed for user root Sep 05 16:15:09 volumio volumio[17879]: info: Loading i18n strings for locale hu Sep 05 16:15:09 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 05 16:15:09 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:15:09 volumio volumio[17879]: info: FusionDsp - mixtype--------------------- Hardware Sep 05 16:15:09 volumio volumio[17879]: info: Volumio Calling Home Sep 05 16:15:09 volumio volumio[17879]: info: Preparing to generate the ALSA configuration file Sep 05 16:15:09 volumio volumio[17879]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 05 16:15:09 volumio volumio[17879]: info: Reading ALSA contributions from plugins. Sep 05 16:15:09 volumio volumio[17879]: info: MPD Permissions set Sep 05 16:15:09 volumio volumio[17879]: info: MPD Permissions set Sep 05 16:15:09 volumio volumio[17879]: info: VolumeController:: Volume=37 Mute =false Sep 05 16:15:09 volumio volumio[17879]: info: CoreCommandRouter::volumioGetState Sep 05 16:15:09 volumio volumio[17879]: info: CorePlayQueue::getTrack 0 Sep 05 16:15:09 volumio volumio[17879]: info: CoreStateMachine::pushState Sep 05 16:15:09 volumio volumio[17879]: info: CorePlayQueue::getTrack 0 Sep 05 16:15:09 volumio volumio[17879]: info: CoreCommandRouter::volumioPushState Sep 05 16:15:10 volumio volumio[17879]: info: Volumio called home Sep 05 16:15:10 volumio volumio[17879]: info: Spotify config file written Sep 05 16:15:10 volumio sudo[18041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 05 16:15:10 volumio sudo[18041]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:15:10 volumio systemd[1]: Stopping go-librespot Daemon... Sep 05 16:15:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 05 16:15:10 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 05 16:15:10 volumio systemd[1]: Stopped go-librespot Daemon. Sep 05 16:15:10 volumio systemd[1]: Started go-librespot Daemon. Sep 05 16:15:10 volumio volumio[17879]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Sep 05 16:15:10 volumio sudo[18041]: pam_unix(sudo:session): session closed for user root Sep 05 16:15:10 volumio go-librespot[18043]: go-librespot daemon starting... Sep 05 16:15:10 volumio go-librespot[18043]: time="2025-09-05T16:15:10+02:00" level=info msg="running go-librespot 0.3.2" Sep 05 16:15:10 volumio go-librespot[18043]: time="2025-09-05T16:15:10+02:00" level=debug msg="app state loaded" Sep 05 16:15:10 volumio go-librespot[18043]: time="2025-09-05T16:15:10+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 05 16:15:10 volumio sudo[18052]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 05 16:15:10 volumio sudo[18052]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:15:10 volumio sudo[18052]: pam_unix(sudo:session): session closed for user root Sep 05 16:15:10 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:15:10 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:15:10 volumio sudo[18055]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 05 16:15:10 volumio sudo[18055]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:15:10 volumio sudo[18055]: pam_unix(sudo:session): session closed for user root Sep 05 16:15:10 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:15:10 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:15:10 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:15:10 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:15:10 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:15:10 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:15:10 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:15:10 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:15:10 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:15:10 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:15:10 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:15:10 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:15:10 volumio volumio[17879]: info: No need to fix Spotify hosts Sep 05 16:15:10 volumio go-librespot[18043]: time="2025-09-05T16:15:10+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Sep 05 16:15:10 volumio go-librespot[18043]: time="2025-09-05T16:15:10+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 05 16:15:10 volumio go-librespot[18043]: time="2025-09-05T16:15:10+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 05 16:15:10 volumio go-librespot[18043]: time="2025-09-05T16:15:10+02:00" level=info msg="zeroconf server listening on port 42249" Sep 05 16:15:10 volumio go-librespot[18043]: time="2025-09-05T16:15:10+02:00" level=debug msg="obtained new client token: AACHnCELLgEqxX1Z5Wo6LMm5dXixkG89IQMnt2SPksFQmKn+xLlq4k0QlePXSgj8nExO1zrNbXnujNrp38k0qtWbCKT1L2lzA2AI/ODGf08CK9TGbU9s4D9bBUZKuwWDMYDzIviLjjrFXVphrZSn3xxnIOzVncU7fUYBajarmEiCFxV7nU9bk6nFd05vVFiTsCJkJvVg/t/02q/T6WmJd5/xrTQFw611a4Qk54Rg8Sq1rvIXB9tr5BtAOlE=" Sep 05 16:15:10 volumio go-librespot[18043]: time="2025-09-05T16:15:10+02:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Sep 05 16:15:10 volumio go-librespot[18043]: time="2025-09-05T16:15:10+02:00" level=debug msg="connected to ap-gew1.spotify.com:443" Sep 05 16:15:10 volumio volumio[17879]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 05 16:15:10 volumio volumio[17879]: SPOTIFY: BQCCdAff2FI4UWRrZ6m19FyLFh5fOXHSmONp5DxKK2pRFRqEM3d35z_IG9s-ZafsQvb3mLH6L_hevPLajERvZd2XUo8DXzxnxlE0v3lK7F5hpD79TO6BZaeKmUlQ9_Uyln7rBs4GQkQy3x4WklhkExE9_UCq35YFfCDhSDB2xFILBzym-lJfjdynhpRAdHulEJs7HommKnxw7YmHY14rr5VknzS3k_0Bb2Mx_0NI7lL8PYmed5xz5pwwk9Xn0b9jF0uvCdd2Mi3cCh2ObwUVLifd7xZcI6XNNPhw7svxItSbUkA Sep 05 16:15:10 volumio volumio[17879]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 05 16:15:10 volumio volumio[17879]: info: New Spotify access token = BQCCdAff2FI4UWRrZ6m19FyLFh5fOXHSmONp5DxKK2pRFRqEM3d35z_IG9s-ZafsQvb3mLH6L_hevPLajERvZd2XUo8DXzxnxlE0v3lK7F5hpD79TO6BZaeKmUlQ9_Uyln7rBs4GQkQy3x4WklhkExE9_UCq35YFfCDhSDB2xFILBzym-lJfjdynhpRAdHulEJs7HommKnxw7YmHY14rr5VknzS3k_0Bb2Mx_0NI7lL8PYmed5xz5pwwk9Xn0b9jF0uvCdd2Mi3cCh2ObwUVLifd7xZcI6XNNPhw7svxItSbUkA Sep 05 16:15:10 volumio volumio[17879]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 05 16:15:10 volumio go-librespot[18043]: time="2025-09-05T16:15:10+02:00" level=debug msg="completed keyexchange" Sep 05 16:15:10 volumio go-librespot[18043]: time="2025-09-05T16:15:10+02:00" level=debug msg="completed challenge" Sep 05 16:15:10 volumio volumio[17879]: info: CoreCommandRouter::volumioGetState Sep 05 16:15:10 volumio volumio[17879]: info: CorePlayQueue::getTrack 0 Sep 05 16:15:10 volumio go-librespot[18043]: time="2025-09-05T16:15:10+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 05 16:15:10 volumio volumio[17879]: info: Asound.conf file unchanged, so no further update is needed Sep 05 16:15:10 volumio volumio[17879]: info: Output device has changed, restarting MPD Sep 05 16:15:10 volumio sudo[18068]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 16:15:10 volumio sudo[18068]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:15:10 volumio sudo[18068]: pam_unix(sudo:session): session closed for user root Sep 05 16:15:10 volumio sudo[18071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 16:15:10 volumio go-librespot[18043]: time="2025-09-05T16:15:10+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 05 16:15:10 volumio go-librespot[18043]: time="2025-09-05T16:15:10+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 05 16:15:10 volumio sudo[18071]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:15:10 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 05 16:15:10 volumio systemd[1]: mpd.service: Succeeded. Sep 05 16:15:10 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 16:15:10 volumio sudo[18074]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 05 16:15:10 volumio sudo[18074]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:15:11 volumio volumio[17879]: info: MPD Permissions set Sep 05 16:15:11 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:15:11 volumio go-librespot[18043]: time="2025-09-05T16:15:10+02:00" level=debug msg="dealer connection opened" Sep 05 16:15:11 volumio go-librespot[18043]: time="2025-09-05T16:15:10+02:00" level=trace msg="starting accesspoint recv loop" Sep 05 16:15:11 volumio go-librespot[18043]: time="2025-09-05T16:15:10+02:00" level=trace msg="starting dealer recv loop" Sep 05 16:15:11 volumio go-librespot[18043]: time="2025-09-05T16:15:10+02:00" level=trace msg="received accesspoint ping" Sep 05 16:15:11 volumio go-librespot[18043]: time="2025-09-05T16:15:10+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 05 16:15:11 volumio go-librespot[18043]: time="2025-09-05T16:15:10+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 05 16:15:11 volumio go-librespot[18043]: time="2025-09-05T16:15:10+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 05 16:15:11 volumio go-librespot[18043]: time="2025-09-05T16:15:10+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 05 16:15:11 volumio go-librespot[18043]: time="2025-09-05T16:15:10+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 05 16:15:11 volumio go-librespot[18043]: time="2025-09-05T16:15:10+02:00" level=debug msg="received connection id: NDc1YjZmZGEtNzcy...QTE1MUY1NDkwOA==" Sep 05 16:15:11 volumio go-librespot[18043]: time="2025-09-05T16:15:10+02:00" level=trace msg="received accesspoint pong ack" Sep 05 16:15:11 volumio systemd[1]: Starting Music Player Daemon... Sep 05 16:15:11 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:15:11 volumio sudo[18074]: pam_unix(sudo:session): session closed for user root Sep 05 16:15:11 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:15:11 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:15:11 volumio volumio[17879]: info: Upmpdcli Daemon Started Sep 05 16:15:11 volumio go-librespot[18043]: time="2025-09-05T16:15:11+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 05 16:15:11 volumio volumio[17879]: SPOTIFY: User informations: {"country":"HU","display_name":"4Misi","email":"sztako.mihaly@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/lo8jr34h0xh1vbcv2lgmaqkvv"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/lo8jr34h0xh1vbcv2lgmaqkvv","id":"lo8jr34h0xh1vbcv2lgmaqkvv","images":[],"product":"premium","type":"user","uri":"spotify:user:lo8jr34h0xh1vbcv2lgmaqkvv"} Sep 05 16:15:11 volumio volumio[17879]: info: Spotify Successfully logged in Sep 05 16:15:11 volumio volumio[17879]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 16:15:11 volumio volumio[17879]: info: [1757081711098] CoreMusicLibrary::Adding element Spotify Sep 05 16:15:11 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:15:11 volumio volumio[17879]: Cannot find translation for source Jellyfin Sep 05 16:15:11 volumio volumio[17879]: Cannot find translation for source Spotify Sep 05 16:15:11 volumio sudo[18075]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 16:15:11 volumio sudo[18075]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:15:11 volumio sudo[18075]: pam_unix(sudo:session): session closed for user root Sep 05 16:15:11 volumio volumio[17879]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 05 16:15:11 volumio volumio[17879]: info: CoreCommandRouter::volumioGetState Sep 05 16:15:11 volumio volumio[17879]: info: CorePlayQueue::getTrack 0 Sep 05 16:15:11 volumio volumio[17879]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 05 16:15:11 volumio volumio[17879]: 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 05 16:15:11 volumio volumio[17879]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 05 16:15:11 volumio volumio[17879]: info: camilladsp service started and running in background, instance 1 Sep 05 16:15:11 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:15:11 volumio volumio[17879]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 05 16:15:11 volumio volumio[17879]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 05 16:15:11 volumio volumio[17879]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 05 16:15:11 volumio volumio[17879]: info: FusionDsp loaded Sep 05 16:15:11 volumio volumio[17879]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 16:15:11 volumio sudo[18094]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 05 16:15:11 volumio sudo[18094]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:15:11 volumio volumio[17879]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 05 16:15:11 volumio volumio[17879]: info: Adding Signal Path Element [object Object] Sep 05 16:15:11 volumio volumio[17879]: info: Adding fusiondspeq DSP Signal Path Element Sep 05 16:15:11 volumio volumio[17879]: info: FusionDsp - ---- installed callbackRead Sep 05 16:15:11 volumio volumio[17879]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 16:15:11 volumio sudo[18094]: pam_unix(sudo:session): session closed for user root Sep 05 16:15:11 volumio volumio[17879]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 05 16:15:11 volumio volumio[17879]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 05 16:15:11 volumio volumio[17879]: at onErrorNT (internal/child_process.js:465:16) Sep 05 16:15:11 volumio volumio[17879]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Sep 05 16:15:11 volumio volumio[17879]: errno: -2, Sep 05 16:15:11 volumio volumio[17879]: code: 'ENOENT', Sep 05 16:15:11 volumio volumio[17879]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 05 16:15:11 volumio volumio[17879]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 05 16:15:11 volumio volumio[17879]: spawnargs: [ Sep 05 16:15:11 volumio volumio[17879]: '-p', Sep 05 16:15:11 volumio volumio[17879]: 9876, Sep 05 16:15:11 volumio volumio[17879]: '-o', Sep 05 16:15:11 volumio volumio[17879]: '/tmp/camilladsp.log', Sep 05 16:15:11 volumio volumio[17879]: '-l', Sep 05 16:15:11 volumio volumio[17879]: 'warn', Sep 05 16:15:11 volumio volumio[17879]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 05 16:15:11 volumio volumio[17879]: ] Sep 05 16:15:11 volumio volumio[17879]: } Sep 05 16:15:11 volumio volumio[17879]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 16:15:12 volumio sudo[18105]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-05 16:14 Sep 05 16:15:12 volumio sudo[18105]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"