-- Logs begin at Fri 2025-09-05 18:11:38 CEST, end at Fri 2025-09-05 18:42:53 CEST. -- Sep 05 18:41:00 volumio volumio[17543]: info: Loading plugin "volumiodiscovery"... Sep 05 18:41:00 volumio volumio[17543]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 05 18:41:00 volumio node[17543]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 05 18:41:00 volumio volumio[17543]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 18:41:00 volumio node[17543]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 18:41:00 volumio volumio[17543]: *** WARNING *** For more information see Sep 05 18:41:00 volumio node[17543]: *** WARNING *** For more information see Sep 05 18:41:00 volumio volumio[17543]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 05 18:41:00 volumio node[17543]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 05 18:41:00 volumio volumio[17543]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 18:41:00 volumio node[17543]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 18:41:00 volumio volumio[17543]: *** WARNING *** For more information see Sep 05 18:41:00 volumio node[17543]: *** WARNING *** For more information see Sep 05 18:41:00 volumio volumio[17543]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 05 18:41:00 volumio volumio[17543]: info: Discovery: Started advertising with name: Volumio Sep 05 18:41:00 volumio volumio[17543]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 18:41:00 volumio volumio[17543]: info: Loading plugin "jellyfin"... Sep 05 18:41:00 volumio volumio[17543]: info: Loading plugin "spop"... Sep 05 18:41:02 volumio volumio[17543]: info: Loading plugin "ytcr"... Sep 05 18:41:05 volumio volumio-remote-updater[504]: [2025-09-05 18:41:05] [connect] Successful connection Sep 05 18:41:06 volumio volumio[17543]: info: Loading plugin "outputs"... Sep 05 18:41:06 volumio volumio[17543]: info: Loading plugin "albumart"... Sep 05 18:41:06 volumio volumio[17543]: info: Plugin example_plugin is not enabled Sep 05 18:41:06 volumio volumio[17543]: info: Loading plugin "inputs"... Sep 05 18:41:06 volumio volumio[17543]: info: Loading plugin "updater_comm"... Sep 05 18:41:06 volumio volumio[17543]: info: Plugin mpdemulation is not enabled Sep 05 18:41:06 volumio volumio[17543]: info: Loading plugin "rest_api"... Sep 05 18:41:06 volumio volumio[17543]: info: Loading plugin "websocket"... Sep 05 18:41:06 volumio volumio[17543]: info: Starting Socket.io Server version 2.3.0 Sep 05 18:41:06 volumio volumio[17543]: info: Loading plugin "fusiondsp"... Sep 05 18:41:06 volumio volumio[17543]: Forking 3 albumart workers Sep 05 18:41:06 volumio volumio[17543]: info: Applying required configuration parameters for plugin fusiondsp Sep 05 18:41:06 volumio volumio[17543]: info: Loading plugin "Systeminfo"... Sep 05 18:41:07 volumio volumio[17543]: info: Loading i18n strings for locale hu Sep 05 18:41:07 volumio volumio[17543]: Updating browse sources language Sep 05 18:41:07 volumio volumio[17543]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 18:41:07 volumio volumio[17543]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 18:41:07 volumio volumio[17543]: info: CoreCommandRouter::initPlayerControls Sep 05 18:41:07 volumio volumio[17543]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:07 volumio volumio[17543]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:07 volumio volumio[17543]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 18:41:07 volumio volumio[17543]: Express server listening on port 3000 Sep 05 18:41:07 volumio volumio[17543]: [Metrics] WebUI: 10s 997.25ms Sep 05 18:41:07 volumio volumio[17543]: info: CoreStateMachine::resetVolumioState Sep 05 18:41:07 volumio volumio[17543]: info: CoreStateMachine::getcurrentVolume Sep 05 18:41:07 volumio volumio[17543]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 18:41:07 volumio volumio[17543]: Starting albumart workers Sep 05 18:41:07 volumio volumio[17543]: Starting albumart workers Sep 05 18:41:07 volumio volumio[17543]: info: Volumio Network Manager: Network status updated: 1 Sep 05 18:41:07 volumio volumio[17543]: Starting albumart workers Sep 05 18:41:07 volumio volumio-remote-updater[504]: [2025-09-05 18:41:07] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1757090465 101 Sep 05 18:41:07 volumio volumio[17543]: 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 18:41:07 volumio volumio[17543]: info: CoreCommandRouter::volumioGetState Sep 05 18:41:07 volumio volumio[17543]: info: CorePlayQueue::getTrack 0 Sep 05 18:41:08 volumio volumio[17543]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 18:41:08 volumio volumio[17543]: info: VolumeController:: Volume=37 Mute =false Sep 05 18:41:08 volumio volumio[17543]: info: CoreStateMachine::pushState Sep 05 18:41:08 volumio volumio[17543]: info: CorePlayQueue::getTrack 0 Sep 05 18:41:08 volumio volumio[17543]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 18:41:08 volumio volumio[17543]: info: CoreCommandRouter::volumioPushState Sep 05 18:41:08 volumio volumio[17543]: info: CoreStateMachine::updateTrackBlock Sep 05 18:41:08 volumio volumio[17543]: info: CorePlayQueue::getTrackBlock Sep 05 18:41:08 volumio volumio[17543]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 18:41:08 volumio volumio[17543]: info: Setting Device type: Raspberry PI Sep 05 18:41:08 volumio volumio[17543]: info: CoreStateMachine::setRepeat false single undefined Sep 05 18:41:08 volumio volumio[17543]: info: CoreStateMachine::pushState Sep 05 18:41:08 volumio volumio[17543]: info: CorePlayQueue::getTrack 0 Sep 05 18:41:08 volumio volumio[17543]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 18:41:08 volumio volumio[17543]: info: CoreCommandRouter::volumioPushState Sep 05 18:41:08 volumio volumio[17543]: info: CoreStateMachine::setRandom true Sep 05 18:41:08 volumio volumio[17543]: info: CoreStateMachine::pushState Sep 05 18:41:08 volumio volumio[17543]: info: CorePlayQueue::getTrack 0 Sep 05 18:41:08 volumio volumio[17543]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 18:41:08 volumio volumio[17543]: info: CoreCommandRouter::volumioPushState Sep 05 18:41:08 volumio volumio[17543]: info: Listing playlists Sep 05 18:41:08 volumio volumio[17543]: info: Listing playlists Sep 05 18:41:08 volumio volumio[17543]: info: Completed loading Core Plugins Sep 05 18:41:08 volumio volumio[17543]: info: Preparing to generate the ALSA configuration file Sep 05 18:41:08 volumio volumio[17543]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227 Sep 05 18:41:08 volumio volumio[17543]: info: Discovery: Found device Volumio Sep 05 18:41:08 volumio volumio[17543]: info: CoreCommandRouter::volumioGetState Sep 05 18:41:08 volumio volumio[17543]: info: CorePlayQueue::getTrack 0 Sep 05 18:41:08 volumio volumio[17543]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 05 18:41:08 volumio volumio[17543]: info: Reading ALSA contributions from plugins. Sep 05 18:41:08 volumio volumio[17543]: info: VolumeController:: Volume=37 Mute =false Sep 05 18:41:08 volumio volumio[17543]: info: CoreStateMachine::pushState Sep 05 18:41:08 volumio volumio[17543]: info: CorePlayQueue::getTrack 0 Sep 05 18:41:08 volumio volumio[17543]: info: CoreCommandRouter::volumioPushState Sep 05 18:41:08 volumio volumio[17543]: info: Asound.conf file unchanged, so no further update is needed Sep 05 18:41:08 volumio volumio[17543]: info: Output device has changed, restarting MPD Sep 05 18:41:08 volumio sudo[17679]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 18:41:08 volumio sudo[17679]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:08 volumio sudo[17679]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:08 volumio volumio[17543]: info: ___________ START PLUGINS ___________ Sep 05 18:41:08 volumio sudo[17690]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 18:41:08 volumio volumio[17543]: info: ControllerMpd::onStart: Initializing MPD Sep 05 18:41:08 volumio sudo[17690]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:08 volumio volumio[17543]: info: Creating MPD Configuration file Sep 05 18:41:08 volumio systemd[1]: Stopping Music Player Daemon... Sep 05 18:41:08 volumio volumio[17543]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 18:41:08 volumio volumio[17543]: info: [1757090468613] CoreMusicLibrary::Adding element Last_100 Sep 05 18:41:08 volumio volumio[17543]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 18:41:08 volumio systemd[1]: mpd.service: Succeeded. Sep 05 18:41:08 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 18:41:08 volumio sudo[17693]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 18:41:08 volumio sudo[17693]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:08 volumio sudo[17693]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:08 volumio volumio[17543]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 18:41:08 volumio volumio[17543]: info: [1757090468647] CoreMusicLibrary::Adding element Webradio Sep 05 18:41:08 volumio volumio[17543]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 18:41:08 volumio volumio[17543]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 18:41:08 volumio volumio[17543]: info: Initializing BBC Radios Sep 05 18:41:08 volumio systemd[1]: Starting Music Player Daemon... Sep 05 18:41:08 volumio sudo[17703]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 18:41:08 volumio sudo[17703]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:08 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 05 18:41:08 volumio systemd[1]: mpd.service: Succeeded. Sep 05 18:41:08 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 18:41:08 volumio systemd[1]: Starting Music Player Daemon... Sep 05 18:41:08 volumio volumio[17543]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 18:41:08 volumio volumio[17543]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 18:41:08 volumio volumio[17543]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 18:41:08 volumio volumio[17543]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 05 18:41:08 volumio volumio[17543]: info: Discovery: Getting this device information Sep 05 18:41:08 volumio volumio[17543]: info: CoreCommandRouter::volumioGetState Sep 05 18:41:08 volumio volumio[17543]: info: CorePlayQueue::getTrack 0 Sep 05 18:41:08 volumio volumio[17543]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 18:41:08 volumio volumio[17543]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 18:41:08 volumio volumio[17543]: info: [1757090468818] CoreMusicLibrary::Adding element Jellyfin Sep 05 18:41:08 volumio volumio[17543]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 18:41:08 volumio volumio[17543]: Cannot find translation for source Jellyfin Sep 05 18:41:08 volumio volumio[17543]: 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 18:41:08 volumio volumio[17543]: info: Creating Spotify config file Sep 05 18:41:08 volumio volumio[17543]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:08 volumio sudo[17717]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 18:41:08 volumio sudo[17717]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:08 volumio sudo[17717]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:09 volumio volumio[17543]: info: Loading i18n strings for locale hu Sep 05 18:41:09 volumio volumio[17543]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 05 18:41:09 volumio volumio[17543]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:09 volumio volumio[17543]: info: FusionDsp - mixtype--------------------- Hardware Sep 05 18:41:09 volumio volumio[17543]: info: Volumio Calling Home Sep 05 18:41:09 volumio volumio[17543]: info: Preparing to generate the ALSA configuration file Sep 05 18:41:09 volumio volumio[17543]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 05 18:41:09 volumio volumio[17543]: info: Reading ALSA contributions from plugins. Sep 05 18:41:09 volumio volumio[17543]: info: MPD Permissions set Sep 05 18:41:09 volumio volumio[17543]: info: MPD Permissions set Sep 05 18:41:09 volumio volumio[17543]: info: Spotify config file written Sep 05 18:41:09 volumio sudo[17746]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 05 18:41:09 volumio volumio[17543]: 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 18:41:09 volumio sudo[17746]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:09 volumio volumio[17543]: info: Volumio called home Sep 05 18:41:09 volumio systemd[1]: Stopping go-librespot Daemon... Sep 05 18:41:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 05 18:41:09 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 05 18:41:09 volumio systemd[1]: Stopped go-librespot Daemon. Sep 05 18:41:09 volumio sudo[17749]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 05 18:41:09 volumio sudo[17749]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:09 volumio sudo[17749]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:09 volumio go-librespot[17751]: go-librespot daemon starting... Sep 05 18:41:09 volumio systemd[1]: Started go-librespot Daemon. Sep 05 18:41:09 volumio volumio[17543]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:09 volumio sudo[17746]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:09 volumio volumio[17543]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:09 volumio volumio[17543]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:09 volumio sudo[17753]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 05 18:41:09 volumio volumio[17543]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:09 volumio go-librespot[17751]: time="2025-09-05T18:41:09+02:00" level=info msg="running go-librespot 0.3.2" Sep 05 18:41:09 volumio go-librespot[17751]: time="2025-09-05T18:41:09+02:00" level=debug msg="app state loaded" Sep 05 18:41:09 volumio go-librespot[17751]: time="2025-09-05T18:41:09+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 05 18:41:09 volumio volumio[17543]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:09 volumio sudo[17753]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:09 volumio sudo[17753]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:09 volumio volumio[17543]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 18:41:09 volumio volumio[17543]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:09 volumio volumio[17543]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:09 volumio volumio[17543]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 18:41:09 volumio volumio[17543]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:09 volumio volumio[17543]: info: No need to fix Spotify hosts Sep 05 18:41:09 volumio go-librespot[17751]: time="2025-09-05T18:41:09+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 18:41:09 volumio go-librespot[17751]: time="2025-09-05T18:41:09+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 18:41:09 volumio go-librespot[17751]: time="2025-09-05T18:41:09+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 18:41:09 volumio go-librespot[17751]: time="2025-09-05T18:41:09+02:00" level=info msg="zeroconf server listening on port 44929" Sep 05 18:41:09 volumio go-librespot[17751]: time="2025-09-05T18:41:09+02:00" level=debug msg="obtained new client token: AAAlhNxguccH7TYDFUZojEgwKR+U7QNd9vvF1xiDt55tsr48iUKGVUDL9jsaKhlv+APuIPPBc3zv5zliKT7eg5ptngAMoTWNL/mBR2OoHb5zOyHu8yxL8PyjpsGKmdXX51o4erqdQbOtXk+g8zuJxWP3H16+LU06ZbQpqWtg+bV9VonZd+bhXOYuuHQxM5E8YC81sHuW7JbNpn1nEMNKxzc8hL/lJtgV3OIxPujl0aQmkPb+uyPhnyzmwGI=" Sep 05 18:41:09 volumio go-librespot[17751]: time="2025-09-05T18:41:09+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 05 18:41:09 volumio go-librespot[17751]: time="2025-09-05T18:41:09+02:00" level=debug msg="completed keyexchange" Sep 05 18:41:09 volumio go-librespot[17751]: time="2025-09-05T18:41:09+02:00" level=debug msg="completed challenge" Sep 05 18:41:10 volumio volumio[17543]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 05 18:41:10 volumio volumio[17543]: SPOTIFY: BQBiawr3e0jJ-FqgqVW-9qIpLIeGYEaB2urqjjUoFx1Eue7JnqPWQIc_TntnRyAKwAu0DObX0TDjjiCGvXuBvnK3gYSNBknMcVp6bZg_yN27SbKbXzS7zp_oyqaqiMPaQ9-KL8tG6IJYsfuxG2Y3snymxcHyfk2rf1gBMqjsGrA6lI_Nh34wNAtFf3hJHhUaO6IqeDzmjjgsQL9nl4IkmJAFxpBQEfVNwFV7jF9LdSidk8zBhfB4mjL5LZKopxAkSd8JCSC0V0CB3U99V2Ym9lLktGuS_sIn6RX7cQG4HKeKSu0 Sep 05 18:41:10 volumio volumio[17543]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 05 18:41:10 volumio volumio[17543]: info: New Spotify access token = BQBiawr3e0jJ-FqgqVW-9qIpLIeGYEaB2urqjjUoFx1Eue7JnqPWQIc_TntnRyAKwAu0DObX0TDjjiCGvXuBvnK3gYSNBknMcVp6bZg_yN27SbKbXzS7zp_oyqaqiMPaQ9-KL8tG6IJYsfuxG2Y3snymxcHyfk2rf1gBMqjsGrA6lI_Nh34wNAtFf3hJHhUaO6IqeDzmjjgsQL9nl4IkmJAFxpBQEfVNwFV7jF9LdSidk8zBhfB4mjL5LZKopxAkSd8JCSC0V0CB3U99V2Ym9lLktGuS_sIn6RX7cQG4HKeKSu0 Sep 05 18:41:10 volumio volumio[17543]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 05 18:41:10 volumio go-librespot[17751]: time="2025-09-05T18:41:10+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 05 18:41:10 volumio volumio[17543]: info: CoreCommandRouter::volumioGetState Sep 05 18:41:10 volumio volumio[17543]: info: CorePlayQueue::getTrack 0 Sep 05 18:41:10 volumio volumio[17543]: info: Asound.conf file unchanged, so no further update is needed Sep 05 18:41:10 volumio volumio[17543]: info: Output device has changed, restarting MPD Sep 05 18:41:10 volumio go-librespot[17751]: time="2025-09-05T18:41:10+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 05 18:41:10 volumio go-librespot[17751]: time="2025-09-05T18:41:10+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 05 18:41:10 volumio sudo[17773]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 18:41:10 volumio sudo[17773]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:10 volumio sudo[17773]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:10 volumio sudo[17775]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 18:41:10 volumio sudo[17775]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:10 volumio go-librespot[17751]: time="2025-09-05T18:41:10+02:00" level=debug msg="dealer connection opened" Sep 05 18:41:10 volumio go-librespot[17751]: time="2025-09-05T18:41:10+02:00" level=trace msg="starting accesspoint recv loop" Sep 05 18:41:10 volumio go-librespot[17751]: time="2025-09-05T18:41:10+02:00" level=trace msg="starting dealer recv loop" Sep 05 18:41:10 volumio go-librespot[17751]: time="2025-09-05T18:41:10+02:00" level=trace msg="received accesspoint ping" Sep 05 18:41:10 volumio go-librespot[17751]: time="2025-09-05T18:41:10+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 05 18:41:10 volumio go-librespot[17751]: time="2025-09-05T18:41:10+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 05 18:41:10 volumio go-librespot[17751]: time="2025-09-05T18:41:10+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 05 18:41:10 volumio go-librespot[17751]: time="2025-09-05T18:41:10+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 05 18:41:10 volumio go-librespot[17751]: time="2025-09-05T18:41:10+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 05 18:41:10 volumio go-librespot[17751]: time="2025-09-05T18:41:10+02:00" level=debug msg="received connection id: MzVmOTViZjUtNWVj...NTU2RDk1QTFFOA==" Sep 05 18:41:10 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 05 18:41:10 volumio systemd[1]: mpd.service: Succeeded. Sep 05 18:41:10 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 18:41:10 volumio go-librespot[17751]: time="2025-09-05T18:41:10+02:00" level=trace msg="received accesspoint pong ack" Sep 05 18:41:10 volumio volumio[17543]: info: MPD Permissions set Sep 05 18:41:10 volumio systemd[1]: Starting Music Player Daemon... Sep 05 18:41:10 volumio volumio[17543]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:10 volumio volumio[17543]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:10 volumio volumio[17543]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:10 volumio volumio[17543]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:10 volumio volumio[17543]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:10 volumio volumio[17543]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:10 volumio volumio[17543]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:10 volumio volumio[17543]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:10 volumio sudo[17780]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 05 18:41:10 volumio sudo[17780]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:10 volumio go-librespot[17751]: time="2025-09-05T18:41:10+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 05 18:41:10 volumio volumio[17543]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 18:41:10 volumio volumio[17543]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:10 volumio sudo[17780]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:10 volumio volumio[17543]: info: Upmpdcli Daemon Started Sep 05 18:41:10 volumio volumio[17543]: 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 18:41:10 volumio volumio[17543]: info: Spotify Successfully logged in Sep 05 18:41:10 volumio volumio[17543]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 18:41:10 volumio volumio[17543]: info: [1757090470402] CoreMusicLibrary::Adding element Spotify Sep 05 18:41:10 volumio volumio[17543]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 18:41:10 volumio volumio[17543]: Cannot find translation for source Jellyfin Sep 05 18:41:10 volumio volumio[17543]: Cannot find translation for source Spotify Sep 05 18:41:10 volumio sudo[17778]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 18:41:10 volumio sudo[17778]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:10 volumio sudo[17778]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:10 volumio volumio[17543]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 05 18:41:10 volumio volumio[17543]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 18:41:11 volumio volumio[17543]: info: VolumeController:: Volume=37 Mute =false Sep 05 18:41:11 volumio volumio[17543]: info: CoreCommandRouter::volumioGetState Sep 05 18:41:11 volumio volumio[17543]: info: CorePlayQueue::getTrack 0 Sep 05 18:41:11 volumio volumio[17543]: info: CoreStateMachine::pushState Sep 05 18:41:11 volumio volumio[17543]: info: CorePlayQueue::getTrack 0 Sep 05 18:41:11 volumio volumio[17543]: info: CoreCommandRouter::volumioPushState Sep 05 18:41:11 volumio volumio[17543]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 05 18:41:11 volumio volumio[17543]: 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 18:41:11 volumio volumio[17543]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 05 18:41:11 volumio volumio[17543]: info: camilladsp service started and running in background, instance 1 Sep 05 18:41:11 volumio volumio[17543]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:11 volumio volumio[17543]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 05 18:41:11 volumio volumio[17543]: 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 18:41:11 volumio volumio[17543]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 05 18:41:11 volumio volumio[17543]: info: FusionDsp loaded Sep 05 18:41:11 volumio volumio[17543]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 18:41:11 volumio sudo[17801]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 05 18:41:11 volumio sudo[17801]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:11 volumio sudo[17801]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:11 volumio volumio[17543]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 05 18:41:11 volumio volumio[17543]: info: Adding Signal Path Element [object Object] Sep 05 18:41:11 volumio volumio[17543]: info: Adding fusiondspeq DSP Signal Path Element Sep 05 18:41:11 volumio volumio[17543]: info: FusionDsp - ---- installed callbackRead Sep 05 18:41:11 volumio volumio[17543]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 18:41:11 volumio volumio[17543]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 05 18:41:11 volumio volumio[17543]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 05 18:41:11 volumio volumio[17543]: at onErrorNT (internal/child_process.js:465:16) Sep 05 18:41:11 volumio volumio[17543]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Sep 05 18:41:11 volumio volumio[17543]: at runNextTicks (internal/process/task_queues.js:62:3) Sep 05 18:41:11 volumio volumio[17543]: at listOnTimeout (internal/timers.js:523:9) Sep 05 18:41:11 volumio volumio[17543]: at processTimers (internal/timers.js:497:7) { Sep 05 18:41:11 volumio volumio[17543]: errno: -2, Sep 05 18:41:11 volumio volumio[17543]: code: 'ENOENT', Sep 05 18:41:11 volumio volumio[17543]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 05 18:41:11 volumio volumio[17543]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 05 18:41:11 volumio volumio[17543]: spawnargs: [ Sep 05 18:41:11 volumio volumio[17543]: '-p', Sep 05 18:41:11 volumio volumio[17543]: 9876, Sep 05 18:41:11 volumio volumio[17543]: '-o', Sep 05 18:41:11 volumio volumio[17543]: '/tmp/camilladsp.log', Sep 05 18:41:11 volumio volumio[17543]: '-l', Sep 05 18:41:11 volumio volumio[17543]: 'warn', Sep 05 18:41:11 volumio volumio[17543]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 05 18:41:11 volumio volumio[17543]: ] Sep 05 18:41:11 volumio volumio[17543]: } Sep 05 18:41:11 volumio volumio[17543]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 18:41:11 volumio sudo[17812]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-05 18:40 Sep 05 18:41:11 volumio sudo[17812]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:12 volumio mpd[17787]: Sep 05 18:41 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 05 18:41:12 volumio systemd[1]: Started Music Player Daemon. Sep 05 18:41:12 volumio sudo[17703]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:12 volumio sudo[17690]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:12 volumio sudo[17775]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:12 volumio sudo[17812]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:12 volumio volumio-remote-updater[504]: [2025-09-05 18:41:12] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 05 18:41:12 volumio volumio-remote-updater[504]: [2025-09-05 18:41:12] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 05 18:41:12 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 05 18:41:12 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 05 18:41:12 volumio systemd[1]: Started dynamicswap service. Sep 05 18:41:12 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 05 18:41:12 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 05 18:41:12 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 19486. Sep 05 18:41:12 volumio systemd[1]: Started dynamicswap service. Sep 05 18:41:12 volumio systemd[1]: Stopped Volumio Backend Module. Sep 05 18:41:12 volumio systemd[1]: Started Volumio Backend Module. Sep 05 18:41:12 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 05 18:41:14 volumio volumio[17825]: info: ------------------------------------------- Sep 05 18:41:14 volumio volumio[17825]: info: ----- Volumio3 ---- Sep 05 18:41:14 volumio volumio[17825]: info: ------------------------------------------- Sep 05 18:41:14 volumio volumio[17825]: info: ----- System startup ---- Sep 05 18:41:14 volumio volumio[17825]: info: ------------------------------------------- Sep 05 18:41:14 volumio volumio[17825]: info: MYVOLUMIO Environment detected Sep 05 18:41:15 volumio volumio[17825]: info: Plugin folders cleanup Sep 05 18:41:15 volumio volumio[17825]: info: Scanning into folder /volumio/app/plugins/ Sep 05 18:41:15 volumio volumio[17825]: info: Scanning category audio_interface Sep 05 18:41:15 volumio volumio[17825]: info: Scanning category miscellanea Sep 05 18:41:15 volumio volumio[17825]: info: Scanning category music_service Sep 05 18:41:15 volumio volumio[17825]: info: Scanning category plugins.json Sep 05 18:41:15 volumio volumio[17825]: info: Scanning category system_controller Sep 05 18:41:15 volumio volumio[17825]: info: Scanning category user_interface Sep 05 18:41:15 volumio volumio[17825]: info: Scanning into folder /data/plugins/ Sep 05 18:41:15 volumio volumio[17825]: info: Scanning category audio_interface Sep 05 18:41:15 volumio volumio[17825]: info: Scanning category music_service Sep 05 18:41:15 volumio volumio[17825]: info: Scanning category user_interface Sep 05 18:41:15 volumio volumio[17825]: info: Plugin folders cleanup completed Sep 05 18:41:15 volumio volumio[17825]: info: ------------------------------------------- Sep 05 18:41:15 volumio volumio[17825]: info: ----- Core plugins startup ---- Sep 05 18:41:15 volumio volumio[17825]: info: ------------------------------------------- Sep 05 18:41:15 volumio volumio[17825]: info: Loading plugins from folder /volumio/app/plugins/ Sep 05 18:41:15 volumio volumio[17825]: info: Adding plugin upnp to MyMusic Plugins Sep 05 18:41:15 volumio volumio[17825]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 05 18:41:15 volumio volumio[17825]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 05 18:41:15 volumio volumio[17825]: info: Loading plugins from folder /data/plugins/ Sep 05 18:41:15 volumio volumio[17825]: info: Loading plugin "system"... Sep 05 18:41:15 volumio volumio[17825]: info: Loading plugin "appearance"... Sep 05 18:41:16 volumio volumio[17825]: info: Loading plugin "network"... Sep 05 18:41:16 volumio volumio[17825]: info: Refreshing Cached IP Addresses Sep 05 18:41:16 volumio sudo[17851]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 05 18:41:16 volumio sudo[17851]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:16 volumio sudo[17851]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:16 volumio sudo[17853]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 05 18:41:16 volumio volumio[17825]: info: Loading plugin "services"... Sep 05 18:41:16 volumio sudo[17853]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:16 volumio volumio[17825]: info: Loading plugin "alsa_controller"... Sep 05 18:41:16 volumio sudo[17853]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:16 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 18:41:16 volumio volumio[17825]: info: Loading plugin "wizard"... Sep 05 18:41:16 volumio volumio[17825]: info: Loading plugin "networkfs"... Sep 05 18:41:16 volumio volumio[17825]: info: Starting Udev Watcher for removable devices Sep 05 18:41:16 volumio volumio[17825]: info: Ignoring mount for partition: boot Sep 05 18:41:16 volumio volumio[17825]: info: Ignoring mount for partition: volumio Sep 05 18:41:16 volumio volumio[17825]: info: Ignoring mount for partition: volumio_data Sep 05 18:41:16 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 18:41:16 volumio volumio[17825]: info: Loading plugin "volumio_command_line_client"... Sep 05 18:41:16 volumio volumio[17825]: info: Loading plugin "upnp"... Sep 05 18:41:16 volumio volumio[17825]: info: [1757090476369] Starting Upmpd Daemon Sep 05 18:41:16 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 18:41:16 volumio volumio[17825]: info: Loading plugin "my_music"... Sep 05 18:41:16 volumio volumio[17825]: info: Loading plugin "mpd"... Sep 05 18:41:16 volumio volumio[17825]: info: Plugin upnp_browser is not enabled Sep 05 18:41:16 volumio volumio[17825]: info: Loading plugin "alarm-clock"... Sep 05 18:41:16 volumio volumio[17825]: info: Plugin airplay_emulation is not enabled Sep 05 18:41:16 volumio volumio[17825]: info: Loading plugin "last_100"... Sep 05 18:41:16 volumio volumio[17825]: info: Loading plugin "webradio"... Sep 05 18:41:17 volumio volumio[17825]: info: Loading plugin "i2s_dacs"... Sep 05 18:41:17 volumio volumio[17825]: info: Loading plugin "volumiodiscovery"... Sep 05 18:41:17 volumio volumio[17825]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 05 18:41:17 volumio volumio[17825]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 18:41:17 volumio volumio[17825]: *** WARNING *** For more information see Sep 05 18:41:17 volumio node[17825]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 05 18:41:17 volumio volumio[17825]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 05 18:41:17 volumio volumio[17825]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 18:41:17 volumio volumio[17825]: *** WARNING *** For more information see Sep 05 18:41:17 volumio node[17825]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 18:41:17 volumio node[17825]: *** WARNING *** For more information see Sep 05 18:41:17 volumio node[17825]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 05 18:41:17 volumio node[17825]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 18:41:17 volumio node[17825]: *** WARNING *** For more information see Sep 05 18:41:17 volumio volumio[17825]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 05 18:41:17 volumio volumio[17825]: info: Discovery: Started advertising with name: Volumio Sep 05 18:41:17 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 18:41:17 volumio volumio[17825]: info: Loading plugin "jellyfin"... Sep 05 18:41:17 volumio volumio-remote-updater[504]: [2025-09-05 18:41:17] [connect] Successful connection Sep 05 18:41:18 volumio volumio[17825]: info: Loading plugin "spop"... Sep 05 18:41:19 volumio volumio[17825]: info: Loading plugin "ytcr"... Sep 05 18:41:23 volumio volumio[17825]: info: Loading plugin "outputs"... Sep 05 18:41:23 volumio volumio[17825]: info: Loading plugin "albumart"... Sep 05 18:41:23 volumio volumio[17825]: info: Plugin example_plugin is not enabled Sep 05 18:41:23 volumio volumio[17825]: info: Loading plugin "inputs"... Sep 05 18:41:23 volumio volumio[17825]: info: Loading plugin "updater_comm"... Sep 05 18:41:23 volumio volumio[17825]: info: Plugin mpdemulation is not enabled Sep 05 18:41:23 volumio volumio[17825]: info: Loading plugin "rest_api"... Sep 05 18:41:23 volumio volumio[17825]: info: Loading plugin "websocket"... Sep 05 18:41:23 volumio volumio[17825]: info: Starting Socket.io Server version 2.3.0 Sep 05 18:41:23 volumio volumio[17825]: info: Loading plugin "fusiondsp"... Sep 05 18:41:23 volumio volumio[17825]: Forking 3 albumart workers Sep 05 18:41:23 volumio volumio[17825]: info: Applying required configuration parameters for plugin fusiondsp Sep 05 18:41:24 volumio volumio[17825]: info: Loading plugin "Systeminfo"... Sep 05 18:41:24 volumio volumio[17825]: info: Loading i18n strings for locale hu Sep 05 18:41:24 volumio volumio[17825]: Updating browse sources language Sep 05 18:41:24 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 18:41:24 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 18:41:24 volumio volumio[17825]: info: CoreCommandRouter::initPlayerControls Sep 05 18:41:24 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:24 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:24 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:24 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:24 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:24 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:24 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:24 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:24 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 18:41:24 volumio volumio[17825]: Express server listening on port 3000 Sep 05 18:41:24 volumio volumio[17825]: [Metrics] WebUI: 11s 44.37ms Sep 05 18:41:24 volumio volumio[17825]: info: CoreStateMachine::resetVolumioState Sep 05 18:41:24 volumio volumio[17825]: info: CoreStateMachine::getcurrentVolume Sep 05 18:41:24 volumio volumio[17825]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 18:41:24 volumio volumio[17825]: Starting albumart workers Sep 05 18:41:24 volumio volumio[17825]: Starting albumart workers Sep 05 18:41:24 volumio volumio[17825]: info: Volumio Network Manager: Network status updated: 1 Sep 05 18:41:24 volumio volumio[17825]: Starting albumart workers Sep 05 18:41:25 volumio volumio[17825]: info: CoreCommandRouter::volumioGetState Sep 05 18:41:25 volumio volumio[17825]: info: CorePlayQueue::getTrack 0 Sep 05 18:41:25 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 18:41:25 volumio volumio[17825]: info: VolumeController:: Volume=37 Mute =false Sep 05 18:41:25 volumio volumio[17825]: info: CoreStateMachine::pushState Sep 05 18:41:25 volumio volumio[17825]: info: CorePlayQueue::getTrack 0 Sep 05 18:41:25 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 18:41:25 volumio volumio[17825]: info: CoreCommandRouter::volumioPushState Sep 05 18:41:25 volumio volumio[17825]: info: CoreStateMachine::updateTrackBlock Sep 05 18:41:25 volumio volumio[17825]: info: CorePlayQueue::getTrackBlock Sep 05 18:41:25 volumio volumio[17825]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 18:41:25 volumio volumio[17825]: info: Setting Device type: Raspberry PI Sep 05 18:41:25 volumio volumio[17825]: info: CoreStateMachine::setRepeat false single undefined Sep 05 18:41:25 volumio volumio[17825]: info: CoreStateMachine::pushState Sep 05 18:41:25 volumio volumio[17825]: info: CorePlayQueue::getTrack 0 Sep 05 18:41:25 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 18:41:25 volumio volumio[17825]: info: CoreCommandRouter::volumioPushState Sep 05 18:41:25 volumio volumio[17825]: info: CoreStateMachine::setRandom true Sep 05 18:41:25 volumio volumio[17825]: info: CoreStateMachine::pushState Sep 05 18:41:25 volumio volumio[17825]: info: CorePlayQueue::getTrack 0 Sep 05 18:41:25 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 18:41:25 volumio volumio[17825]: info: CoreCommandRouter::volumioPushState Sep 05 18:41:25 volumio volumio[17825]: info: Listing playlists Sep 05 18:41:25 volumio volumio[17825]: info: Listing playlists Sep 05 18:41:25 volumio volumio[17825]: info: Completed loading Core Plugins Sep 05 18:41:25 volumio volumio[17825]: info: Preparing to generate the ALSA configuration file Sep 05 18:41:25 volumio volumio[17825]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227 Sep 05 18:41:25 volumio volumio[17825]: info: Discovery: Found device Volumio Sep 05 18:41:25 volumio volumio[17825]: info: CoreCommandRouter::volumioGetState Sep 05 18:41:25 volumio volumio[17825]: info: CorePlayQueue::getTrack 0 Sep 05 18:41:25 volumio volumio[17825]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 05 18:41:25 volumio volumio[17825]: info: Reading ALSA contributions from plugins. Sep 05 18:41:25 volumio volumio[17825]: info: VolumeController:: Volume=37 Mute =false Sep 05 18:41:25 volumio volumio[17825]: info: CoreStateMachine::pushState Sep 05 18:41:25 volumio volumio[17825]: info: CorePlayQueue::getTrack 0 Sep 05 18:41:25 volumio volumio[17825]: info: CoreCommandRouter::volumioPushState Sep 05 18:41:25 volumio volumio[17825]: info: Asound.conf file unchanged, so no further update is needed Sep 05 18:41:25 volumio volumio[17825]: info: Output device has changed, restarting MPD Sep 05 18:41:25 volumio volumio[17825]: info: ___________ START PLUGINS ___________ Sep 05 18:41:25 volumio volumio[17825]: info: ControllerMpd::onStart: Initializing MPD Sep 05 18:41:25 volumio volumio[17825]: info: Creating MPD Configuration file Sep 05 18:41:25 volumio sudo[17922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 18:41:25 volumio sudo[17924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 18:41:25 volumio sudo[17924]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:25 volumio sudo[17922]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:25 volumio sudo[17922]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:25 volumio systemd[1]: Stopping Music Player Daemon... Sep 05 18:41:25 volumio sudo[17933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 18:41:25 volumio sudo[17933]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:25 volumio systemd[1]: mpd.service: Succeeded. Sep 05 18:41:25 volumio sudo[17933]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:25 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 18:41:25 volumio volumio[17825]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 18:41:25 volumio volumio[17825]: info: [1757090485662] CoreMusicLibrary::Adding element Last_100 Sep 05 18:41:25 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 18:41:25 volumio volumio[17825]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 18:41:25 volumio volumio[17825]: info: [1757090485677] CoreMusicLibrary::Adding element Webradio Sep 05 18:41:25 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 18:41:25 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 18:41:25 volumio systemd[1]: Starting Music Player Daemon... Sep 05 18:41:25 volumio volumio[17825]: info: Initializing BBC Radios Sep 05 18:41:25 volumio sudo[17948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 18:41:25 volumio sudo[17948]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:25 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 05 18:41:25 volumio systemd[1]: mpd.service: Succeeded. Sep 05 18:41:25 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 18:41:25 volumio systemd[1]: Starting Music Player Daemon... Sep 05 18:41:25 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 18:41:25 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 18:41:25 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 18:41:25 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 05 18:41:25 volumio volumio[17825]: info: Discovery: Getting this device information Sep 05 18:41:25 volumio volumio[17825]: info: CoreCommandRouter::volumioGetState Sep 05 18:41:25 volumio volumio[17825]: info: CorePlayQueue::getTrack 0 Sep 05 18:41:25 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 18:41:25 volumio volumio[17825]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 18:41:25 volumio volumio[17825]: info: [1757090485850] CoreMusicLibrary::Adding element Jellyfin Sep 05 18:41:25 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 18:41:25 volumio volumio[17825]: Cannot find translation for source Jellyfin Sep 05 18:41:25 volumio volumio[17825]: 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 18:41:25 volumio volumio[17825]: info: Creating Spotify config file Sep 05 18:41:25 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:25 volumio sudo[17956]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 18:41:25 volumio sudo[17956]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:25 volumio sudo[17956]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:26 volumio volumio[17825]: info: Loading i18n strings for locale hu Sep 05 18:41:26 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 05 18:41:26 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:26 volumio volumio[17825]: info: FusionDsp - mixtype--------------------- Hardware Sep 05 18:41:26 volumio volumio[17825]: info: Volumio Calling Home Sep 05 18:41:26 volumio volumio[17825]: info: Preparing to generate the ALSA configuration file Sep 05 18:41:26 volumio volumio[17825]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 05 18:41:26 volumio volumio[17825]: info: Reading ALSA contributions from plugins. Sep 05 18:41:26 volumio volumio[17825]: info: MPD Permissions set Sep 05 18:41:26 volumio volumio[17825]: info: MPD Permissions set Sep 05 18:41:26 volumio volumio[17825]: info: Spotify config file written Sep 05 18:41:26 volumio sudo[17989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 05 18:41:26 volumio sudo[17989]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:26 volumio volumio[17825]: 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 18:41:26 volumio volumio[17825]: info: Volumio called home Sep 05 18:41:26 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:26 volumio systemd[1]: Stopping go-librespot Daemon... Sep 05 18:41:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 05 18:41:26 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 05 18:41:26 volumio systemd[1]: Stopped go-librespot Daemon. Sep 05 18:41:26 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 18:41:26 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:26 volumio volumio[17825]: info: No need to fix Spotify hosts Sep 05 18:41:26 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:26 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:26 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:26 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:26 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:26 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:26 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:26 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:26 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 18:41:26 volumio systemd[1]: Started go-librespot Daemon. Sep 05 18:41:26 volumio go-librespot[17995]: go-librespot daemon starting... Sep 05 18:41:26 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:26 volumio sudo[17989]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:26 volumio go-librespot[17995]: time="2025-09-05T18:41:26+02:00" level=info msg="running go-librespot 0.3.2" Sep 05 18:41:26 volumio go-librespot[17995]: time="2025-09-05T18:41:26+02:00" level=debug msg="app state loaded" Sep 05 18:41:26 volumio go-librespot[17995]: time="2025-09-05T18:41:26+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 05 18:41:26 volumio sudo[18008]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 05 18:41:26 volumio sudo[18008]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:26 volumio sudo[18008]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:26 volumio go-librespot[17995]: time="2025-09-05T18:41:26+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Sep 05 18:41:26 volumio go-librespot[17995]: time="2025-09-05T18:41:26+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 05 18:41:26 volumio go-librespot[17995]: time="2025-09-05T18:41:26+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 05 18:41:26 volumio sudo[18010]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 05 18:41:26 volumio sudo[18010]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:26 volumio go-librespot[17995]: time="2025-09-05T18:41:26+02:00" level=info msg="zeroconf server listening on port 46817" Sep 05 18:41:26 volumio sudo[18010]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:26 volumio go-librespot[17995]: time="2025-09-05T18:41:26+02:00" level=debug msg="obtained new client token: AADKwkFFN+v0zGOJIVSx8xIf4zAYpIyhsUEpukOCUhWkt0DjYE12tN1qm6d9v0C4qt6MHb26pNjtQw56MIIxwDMRM71q0Rm0+xJ6vx9S6J1V5z5LWu9q0kRwKhS0BP18kxNPa1CjF1YIa2WS75+R2MwG8pd0YUpcYgdYbFL+RNW9wRaru98eV1kRpI3ZBQrsIJuIzMpyd6hb661k8/pqc/8w/+jTYBWchjOzow2ewnr2GS36ffpV44R1fZM=" Sep 05 18:41:26 volumio go-librespot[17995]: time="2025-09-05T18:41:26+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 05 18:41:26 volumio go-librespot[17995]: time="2025-09-05T18:41:26+02:00" level=debug msg="completed keyexchange" Sep 05 18:41:26 volumio go-librespot[17995]: time="2025-09-05T18:41:26+02:00" level=debug msg="completed challenge" Sep 05 18:41:26 volumio go-librespot[17995]: time="2025-09-05T18:41:26+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 05 18:41:27 volumio volumio[17825]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 05 18:41:27 volumio volumio[17825]: SPOTIFY: BQCB79UK_GbU_Kmcib_9XJ2mLFRtjVsVdVrDFJCRVoLMHwJ4NDzXQTG9jVJDYNPaM1hb86J-zTdrbGDGIyKPBghhBooB3faoTk9XMJKJZQYgSLeQ9RHOwvj79kgNmkaVCyciMOw802LkXpl3kazZX6t5lyb88exD6_UsiXXWFeWLkl--wzxRD4Bnt6Q6BjIYxPTEB3UrnSmGEunwzBak8BlcifoSznRF3xR_B9_NA9MQVbaaYNMWxllQuitzzdFMwyCqlW9HNKuOA1gX1EXpmEDffgGIh3FZe4t97CmBoL_dK8w Sep 05 18:41:27 volumio volumio[17825]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 05 18:41:27 volumio volumio[17825]: info: New Spotify access token = BQCB79UK_GbU_Kmcib_9XJ2mLFRtjVsVdVrDFJCRVoLMHwJ4NDzXQTG9jVJDYNPaM1hb86J-zTdrbGDGIyKPBghhBooB3faoTk9XMJKJZQYgSLeQ9RHOwvj79kgNmkaVCyciMOw802LkXpl3kazZX6t5lyb88exD6_UsiXXWFeWLkl--wzxRD4Bnt6Q6BjIYxPTEB3UrnSmGEunwzBak8BlcifoSznRF3xR_B9_NA9MQVbaaYNMWxllQuitzzdFMwyCqlW9HNKuOA1gX1EXpmEDffgGIh3FZe4t97CmBoL_dK8w Sep 05 18:41:27 volumio volumio[17825]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 05 18:41:27 volumio volumio[17825]: info: CoreCommandRouter::volumioGetState Sep 05 18:41:27 volumio volumio[17825]: info: CorePlayQueue::getTrack 0 Sep 05 18:41:27 volumio go-librespot[17995]: time="2025-09-05T18:41:27+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 05 18:41:27 volumio go-librespot[17995]: time="2025-09-05T18:41:27+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 05 18:41:27 volumio volumio[17825]: info: Asound.conf file unchanged, so no further update is needed Sep 05 18:41:27 volumio volumio[17825]: info: Output device has changed, restarting MPD Sep 05 18:41:27 volumio go-librespot[17995]: time="2025-09-05T18:41:27+02:00" level=debug msg="dealer connection opened" Sep 05 18:41:27 volumio go-librespot[17995]: time="2025-09-05T18:41:27+02:00" level=trace msg="starting accesspoint recv loop" Sep 05 18:41:27 volumio go-librespot[17995]: time="2025-09-05T18:41:27+02:00" level=trace msg="starting dealer recv loop" Sep 05 18:41:27 volumio go-librespot[17995]: time="2025-09-05T18:41:27+02:00" level=trace msg="received accesspoint ping" Sep 05 18:41:27 volumio go-librespot[17995]: time="2025-09-05T18:41:27+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 05 18:41:27 volumio go-librespot[17995]: time="2025-09-05T18:41:27+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 05 18:41:27 volumio go-librespot[17995]: time="2025-09-05T18:41:27+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 05 18:41:27 volumio go-librespot[17995]: time="2025-09-05T18:41:27+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 05 18:41:27 volumio go-librespot[17995]: time="2025-09-05T18:41:27+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 05 18:41:27 volumio sudo[18014]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 18:41:27 volumio go-librespot[17995]: time="2025-09-05T18:41:27+02:00" level=debug msg="received connection id: MjE0OTAxZDktZDYy...NkU0NzY0N0Q0NQ==" Sep 05 18:41:27 volumio sudo[18014]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:27 volumio sudo[18014]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:27 volumio go-librespot[17995]: time="2025-09-05T18:41:27+02:00" level=trace msg="received accesspoint pong ack" Sep 05 18:41:27 volumio sudo[18017]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 18:41:27 volumio sudo[18017]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:27 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 05 18:41:27 volumio systemd[1]: mpd.service: Succeeded. Sep 05 18:41:27 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 18:41:27 volumio go-librespot[17995]: time="2025-09-05T18:41:27+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 05 18:41:27 volumio volumio[17825]: info: MPD Permissions set Sep 05 18:41:27 volumio systemd[1]: Starting Music Player Daemon... Sep 05 18:41:27 volumio sudo[18022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 05 18:41:27 volumio sudo[18022]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:27 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:27 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:27 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:27 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:27 volumio sudo[18022]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:27 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 18:41:27 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:27 volumio volumio[17825]: info: Upmpdcli Daemon Started Sep 05 18:41:27 volumio volumio[17825]: 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 18:41:27 volumio volumio[17825]: info: Spotify Successfully logged in Sep 05 18:41:27 volumio sudo[18020]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 18:41:27 volumio sudo[18020]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:27 volumio volumio[17825]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 18:41:27 volumio volumio[17825]: info: [1757090487460] CoreMusicLibrary::Adding element Spotify Sep 05 18:41:27 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 18:41:27 volumio volumio[17825]: Cannot find translation for source Jellyfin Sep 05 18:41:27 volumio volumio[17825]: Cannot find translation for source Spotify Sep 05 18:41:27 volumio sudo[18020]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:27 volumio volumio[17825]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 05 18:41:27 volumio volumio[17825]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 18:41:28 volumio volumio[17825]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 05 18:41:28 volumio volumio[17825]: 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 18:41:28 volumio volumio[17825]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 05 18:41:28 volumio volumio[17825]: info: camilladsp service started and running in background, instance 1 Sep 05 18:41:28 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:28 volumio volumio[17825]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 05 18:41:28 volumio volumio[17825]: 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 18:41:28 volumio volumio[17825]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 05 18:41:28 volumio volumio[17825]: info: FusionDsp loaded Sep 05 18:41:28 volumio volumio[17825]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 18:41:28 volumio sudo[18042]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 05 18:41:28 volumio sudo[18042]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:28 volumio volumio[17825]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 05 18:41:28 volumio volumio[17825]: info: Adding Signal Path Element [object Object] Sep 05 18:41:28 volumio volumio[17825]: info: Adding fusiondspeq DSP Signal Path Element Sep 05 18:41:28 volumio volumio[17825]: info: FusionDsp - ---- installed callbackRead Sep 05 18:41:28 volumio volumio[17825]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 18:41:28 volumio sudo[18042]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:28 volumio volumio[17825]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 05 18:41:28 volumio volumio[17825]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 05 18:41:28 volumio volumio[17825]: at onErrorNT (internal/child_process.js:465:16) Sep 05 18:41:28 volumio volumio[17825]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Sep 05 18:41:28 volumio volumio[17825]: at runNextTicks (internal/process/task_queues.js:62:3) Sep 05 18:41:28 volumio volumio[17825]: at listOnTimeout (internal/timers.js:523:9) Sep 05 18:41:28 volumio volumio[17825]: at processTimers (internal/timers.js:497:7) { Sep 05 18:41:28 volumio volumio[17825]: errno: -2, Sep 05 18:41:28 volumio volumio[17825]: code: 'ENOENT', Sep 05 18:41:28 volumio volumio[17825]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 05 18:41:28 volumio volumio[17825]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 05 18:41:28 volumio volumio[17825]: spawnargs: [ Sep 05 18:41:28 volumio volumio[17825]: '-p', Sep 05 18:41:28 volumio volumio[17825]: 9876, Sep 05 18:41:28 volumio volumio[17825]: '-o', Sep 05 18:41:28 volumio volumio[17825]: '/tmp/camilladsp.log', Sep 05 18:41:28 volumio volumio[17825]: '-l', Sep 05 18:41:28 volumio volumio[17825]: 'warn', Sep 05 18:41:28 volumio volumio[17825]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 05 18:41:28 volumio volumio[17825]: ] Sep 05 18:41:28 volumio volumio[17825]: } Sep 05 18:41:28 volumio volumio[17825]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 18:41:28 volumio sudo[18053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-05 18:40 Sep 05 18:41:28 volumio sudo[18053]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:29 volumio mpd[18029]: Sep 05 18:41 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 05 18:41:29 volumio systemd[1]: Started Music Player Daemon. Sep 05 18:41:29 volumio sudo[18017]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:29 volumio sudo[17948]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:29 volumio sudo[17924]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:29 volumio sudo[18053]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:29 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 05 18:41:29 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 05 18:41:29 volumio systemd[1]: Started dynamicswap service. Sep 05 18:41:29 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 05 18:41:29 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 05 18:41:29 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 19487. Sep 05 18:41:29 volumio systemd[1]: Started dynamicswap service. Sep 05 18:41:29 volumio systemd[1]: Stopped Volumio Backend Module. Sep 05 18:41:29 volumio systemd[1]: Started Volumio Backend Module. Sep 05 18:41:29 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 05 18:41:29 volumio volumio-remote-updater[504]: [2025-09-05 18:41:29] [info] asio async_connect error: system:111 (Connection refused) Sep 05 18:41:29 volumio volumio-remote-updater[504]: [2025-09-05 18:41:29] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 05 18:41:29 volumio volumio-remote-updater[504]: [2025-09-05 18:41:29] [error] handle_connect error: Underlying Transport Error Sep 05 18:41:31 volumio volumio[18066]: info: ------------------------------------------- Sep 05 18:41:31 volumio volumio[18066]: info: ----- Volumio3 ---- Sep 05 18:41:31 volumio volumio[18066]: info: ------------------------------------------- Sep 05 18:41:31 volumio volumio[18066]: info: ----- System startup ---- Sep 05 18:41:31 volumio volumio[18066]: info: ------------------------------------------- Sep 05 18:41:31 volumio volumio[18066]: info: MYVOLUMIO Environment detected Sep 05 18:41:32 volumio volumio[18066]: info: Plugin folders cleanup Sep 05 18:41:32 volumio volumio[18066]: info: Scanning into folder /volumio/app/plugins/ Sep 05 18:41:32 volumio volumio[18066]: info: Scanning category audio_interface Sep 05 18:41:32 volumio volumio[18066]: info: Scanning category miscellanea Sep 05 18:41:32 volumio volumio[18066]: info: Scanning category music_service Sep 05 18:41:32 volumio volumio[18066]: info: Scanning category plugins.json Sep 05 18:41:32 volumio volumio[18066]: info: Scanning category system_controller Sep 05 18:41:32 volumio volumio[18066]: info: Scanning category user_interface Sep 05 18:41:32 volumio volumio[18066]: info: Scanning into folder /data/plugins/ Sep 05 18:41:32 volumio volumio[18066]: info: Scanning category audio_interface Sep 05 18:41:32 volumio volumio[18066]: info: Scanning category music_service Sep 05 18:41:32 volumio volumio[18066]: info: Scanning category user_interface Sep 05 18:41:32 volumio volumio[18066]: info: Plugin folders cleanup completed Sep 05 18:41:32 volumio volumio[18066]: info: ------------------------------------------- Sep 05 18:41:32 volumio volumio[18066]: info: ----- Core plugins startup ---- Sep 05 18:41:32 volumio volumio[18066]: info: ------------------------------------------- Sep 05 18:41:32 volumio volumio[18066]: info: Loading plugins from folder /volumio/app/plugins/ Sep 05 18:41:32 volumio volumio[18066]: info: Adding plugin upnp to MyMusic Plugins Sep 05 18:41:32 volumio volumio[18066]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 05 18:41:32 volumio volumio[18066]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 05 18:41:32 volumio volumio[18066]: info: Loading plugins from folder /data/plugins/ Sep 05 18:41:32 volumio volumio[18066]: info: Loading plugin "system"... Sep 05 18:41:32 volumio volumio[18066]: info: Loading plugin "appearance"... Sep 05 18:41:33 volumio volumio[18066]: info: Loading plugin "network"... Sep 05 18:41:33 volumio volumio[18066]: info: Refreshing Cached IP Addresses Sep 05 18:41:33 volumio sudo[18093]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 05 18:41:33 volumio sudo[18093]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:33 volumio sudo[18093]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:33 volumio sudo[18095]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 05 18:41:33 volumio sudo[18095]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:33 volumio volumio[18066]: info: Loading plugin "services"... Sep 05 18:41:33 volumio sudo[18095]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:33 volumio volumio[18066]: info: Loading plugin "alsa_controller"... Sep 05 18:41:33 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 18:41:33 volumio volumio[18066]: info: Loading plugin "wizard"... Sep 05 18:41:33 volumio volumio[18066]: info: Loading plugin "networkfs"... Sep 05 18:41:33 volumio volumio[18066]: info: Starting Udev Watcher for removable devices Sep 05 18:41:33 volumio volumio[18066]: info: Ignoring mount for partition: boot Sep 05 18:41:33 volumio volumio[18066]: info: Ignoring mount for partition: volumio Sep 05 18:41:33 volumio volumio[18066]: info: Ignoring mount for partition: volumio_data Sep 05 18:41:33 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 18:41:33 volumio volumio[18066]: info: Loading plugin "volumio_command_line_client"... Sep 05 18:41:33 volumio volumio[18066]: info: Loading plugin "upnp"... Sep 05 18:41:33 volumio volumio[18066]: info: [1757090493341] Starting Upmpd Daemon Sep 05 18:41:33 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 18:41:33 volumio volumio[18066]: info: Loading plugin "my_music"... Sep 05 18:41:33 volumio volumio[18066]: info: Loading plugin "mpd"... Sep 05 18:41:33 volumio volumio[18066]: info: Plugin upnp_browser is not enabled Sep 05 18:41:33 volumio volumio[18066]: info: Loading plugin "alarm-clock"... Sep 05 18:41:33 volumio volumio[18066]: info: Plugin airplay_emulation is not enabled Sep 05 18:41:33 volumio volumio[18066]: info: Loading plugin "last_100"... Sep 05 18:41:33 volumio volumio[18066]: info: Loading plugin "webradio"... Sep 05 18:41:33 volumio volumio[18066]: info: Loading plugin "i2s_dacs"... Sep 05 18:41:34 volumio volumio[18066]: info: Loading plugin "volumiodiscovery"... Sep 05 18:41:34 volumio volumio[18066]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 05 18:41:34 volumio volumio[18066]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 18:41:34 volumio volumio[18066]: *** WARNING *** For more information see Sep 05 18:41:34 volumio node[18066]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 05 18:41:34 volumio volumio[18066]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 05 18:41:34 volumio volumio[18066]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 18:41:34 volumio volumio[18066]: *** WARNING *** For more information see Sep 05 18:41:34 volumio node[18066]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 18:41:34 volumio node[18066]: *** WARNING *** For more information see Sep 05 18:41:34 volumio node[18066]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 05 18:41:34 volumio node[18066]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 18:41:34 volumio node[18066]: *** WARNING *** For more information see Sep 05 18:41:34 volumio volumio[18066]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 05 18:41:34 volumio volumio[18066]: info: Discovery: Started advertising with name: Volumio Sep 05 18:41:34 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 18:41:34 volumio volumio[18066]: info: Loading plugin "jellyfin"... Sep 05 18:41:34 volumio volumio[18066]: info: Loading plugin "spop"... Sep 05 18:41:36 volumio volumio[18066]: info: Loading plugin "ytcr"... Sep 05 18:41:39 volumio volumio-remote-updater[504]: [2025-09-05 18:41:39] [connect] Successful connection Sep 05 18:41:40 volumio volumio[18066]: info: Loading plugin "outputs"... Sep 05 18:41:40 volumio volumio[18066]: info: Loading plugin "albumart"... Sep 05 18:41:40 volumio volumio[18066]: info: Plugin example_plugin is not enabled Sep 05 18:41:40 volumio volumio[18066]: info: Loading plugin "inputs"... Sep 05 18:41:40 volumio volumio[18066]: info: Loading plugin "updater_comm"... Sep 05 18:41:40 volumio volumio[18066]: info: Plugin mpdemulation is not enabled Sep 05 18:41:40 volumio volumio[18066]: info: Loading plugin "rest_api"... Sep 05 18:41:40 volumio volumio[18066]: info: Loading plugin "websocket"... Sep 05 18:41:40 volumio volumio[18066]: info: Starting Socket.io Server version 2.3.0 Sep 05 18:41:40 volumio volumio[18066]: info: Loading plugin "fusiondsp"... Sep 05 18:41:40 volumio volumio[18066]: Forking 3 albumart workers Sep 05 18:41:40 volumio volumio[18066]: info: Applying required configuration parameters for plugin fusiondsp Sep 05 18:41:40 volumio volumio[18066]: info: Loading plugin "Systeminfo"... Sep 05 18:41:41 volumio volumio[18066]: info: Loading i18n strings for locale hu Sep 05 18:41:41 volumio volumio[18066]: Updating browse sources language Sep 05 18:41:41 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 18:41:41 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 18:41:41 volumio volumio[18066]: info: CoreCommandRouter::initPlayerControls Sep 05 18:41:41 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:41 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:41 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:41 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:41 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:41 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:41 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:41 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:41 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 18:41:41 volumio volumio[18066]: Express server listening on port 3000 Sep 05 18:41:41 volumio volumio[18066]: [Metrics] WebUI: 10s 970.79ms Sep 05 18:41:41 volumio volumio[18066]: info: CoreStateMachine::resetVolumioState Sep 05 18:41:41 volumio volumio[18066]: info: CoreStateMachine::getcurrentVolume Sep 05 18:41:41 volumio volumio[18066]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 18:41:41 volumio volumio[18066]: Starting albumart workers Sep 05 18:41:41 volumio volumio[18066]: Starting albumart workers Sep 05 18:41:41 volumio volumio[18066]: info: Volumio Network Manager: Network status updated: 1 Sep 05 18:41:41 volumio volumio[18066]: Starting albumart workers Sep 05 18:41:41 volumio volumio-remote-updater[504]: [2025-09-05 18:41:41] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1757090499 101 Sep 05 18:41:41 volumio volumio[18066]: 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 18:41:41 volumio volumio[18066]: info: CoreCommandRouter::volumioGetState Sep 05 18:41:41 volumio volumio[18066]: info: CorePlayQueue::getTrack 0 Sep 05 18:41:42 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 18:41:42 volumio volumio[18066]: info: VolumeController:: Volume=37 Mute =false Sep 05 18:41:42 volumio volumio[18066]: info: CoreStateMachine::pushState Sep 05 18:41:42 volumio volumio[18066]: info: CorePlayQueue::getTrack 0 Sep 05 18:41:42 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 18:41:42 volumio volumio[18066]: info: CoreCommandRouter::volumioPushState Sep 05 18:41:42 volumio volumio[18066]: info: CoreStateMachine::updateTrackBlock Sep 05 18:41:42 volumio volumio[18066]: info: CorePlayQueue::getTrackBlock Sep 05 18:41:42 volumio volumio[18066]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 18:41:42 volumio volumio[18066]: info: Setting Device type: Raspberry PI Sep 05 18:41:42 volumio volumio[18066]: info: CoreStateMachine::setRepeat false single undefined Sep 05 18:41:42 volumio volumio[18066]: info: CoreStateMachine::pushState Sep 05 18:41:42 volumio volumio[18066]: info: CorePlayQueue::getTrack 0 Sep 05 18:41:42 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 18:41:42 volumio volumio[18066]: info: CoreCommandRouter::volumioPushState Sep 05 18:41:42 volumio volumio[18066]: info: CoreStateMachine::setRandom true Sep 05 18:41:42 volumio volumio[18066]: info: CoreStateMachine::pushState Sep 05 18:41:42 volumio volumio[18066]: info: CorePlayQueue::getTrack 0 Sep 05 18:41:42 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 18:41:42 volumio volumio[18066]: info: CoreCommandRouter::volumioPushState Sep 05 18:41:42 volumio volumio[18066]: info: Listing playlists Sep 05 18:41:42 volumio volumio[18066]: info: Listing playlists Sep 05 18:41:42 volumio volumio[18066]: info: Completed loading Core Plugins Sep 05 18:41:42 volumio volumio[18066]: info: Preparing to generate the ALSA configuration file Sep 05 18:41:42 volumio volumio[18066]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227 Sep 05 18:41:42 volumio volumio[18066]: info: Discovery: Found device Volumio Sep 05 18:41:42 volumio volumio[18066]: info: CoreCommandRouter::volumioGetState Sep 05 18:41:42 volumio volumio[18066]: info: CorePlayQueue::getTrack 0 Sep 05 18:41:42 volumio volumio[18066]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 05 18:41:42 volumio volumio[18066]: info: Reading ALSA contributions from plugins. Sep 05 18:41:42 volumio volumio[18066]: info: VolumeController:: Volume=37 Mute =false Sep 05 18:41:42 volumio volumio[18066]: info: CoreStateMachine::pushState Sep 05 18:41:42 volumio volumio[18066]: info: CorePlayQueue::getTrack 0 Sep 05 18:41:42 volumio volumio[18066]: info: CoreCommandRouter::volumioPushState Sep 05 18:41:42 volumio volumio[18066]: info: Asound.conf file unchanged, so no further update is needed Sep 05 18:41:42 volumio volumio[18066]: info: Output device has changed, restarting MPD Sep 05 18:41:42 volumio sudo[18180]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 18:41:42 volumio sudo[18180]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:42 volumio sudo[18180]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:42 volumio volumio[18066]: info: ___________ START PLUGINS ___________ Sep 05 18:41:42 volumio volumio[18066]: info: ControllerMpd::onStart: Initializing MPD Sep 05 18:41:42 volumio volumio[18066]: info: Creating MPD Configuration file Sep 05 18:41:42 volumio sudo[18182]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 18:41:42 volumio sudo[18182]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:42 volumio systemd[1]: Stopping Music Player Daemon... Sep 05 18:41:42 volumio sudo[18186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 18:41:42 volumio sudo[18186]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:42 volumio sudo[18186]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:42 volumio volumio[18066]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 18:41:42 volumio volumio[18066]: info: [1757090502620] CoreMusicLibrary::Adding element Last_100 Sep 05 18:41:42 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 18:41:42 volumio systemd[1]: mpd.service: Succeeded. Sep 05 18:41:42 volumio volumio[18066]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 18:41:42 volumio volumio[18066]: info: [1757090502629] CoreMusicLibrary::Adding element Webradio Sep 05 18:41:42 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 18:41:42 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 18:41:42 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 18:41:42 volumio volumio[18066]: info: Initializing BBC Radios Sep 05 18:41:42 volumio sudo[18194]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 18:41:42 volumio sudo[18194]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:42 volumio systemd[1]: Starting Music Player Daemon... Sep 05 18:41:42 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 05 18:41:42 volumio systemd[1]: mpd.service: Succeeded. Sep 05 18:41:42 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 18:41:42 volumio systemd[1]: Starting Music Player Daemon... Sep 05 18:41:42 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 18:41:42 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 18:41:42 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 18:41:42 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 05 18:41:42 volumio volumio[18066]: info: Discovery: Getting this device information Sep 05 18:41:42 volumio volumio[18066]: info: CoreCommandRouter::volumioGetState Sep 05 18:41:42 volumio volumio[18066]: info: CorePlayQueue::getTrack 0 Sep 05 18:41:42 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 18:41:42 volumio volumio[18066]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 18:41:42 volumio volumio[18066]: info: [1757090502775] CoreMusicLibrary::Adding element Jellyfin Sep 05 18:41:42 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 18:41:42 volumio volumio[18066]: Cannot find translation for source Jellyfin Sep 05 18:41:42 volumio volumio[18066]: 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 18:41:42 volumio volumio[18066]: info: Creating Spotify config file Sep 05 18:41:42 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:42 volumio sudo[18206]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 18:41:42 volumio sudo[18206]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:42 volumio sudo[18206]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:42 volumio volumio[18066]: info: Loading i18n strings for locale hu Sep 05 18:41:42 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 05 18:41:42 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:42 volumio volumio[18066]: info: FusionDsp - mixtype--------------------- Hardware Sep 05 18:41:42 volumio volumio[18066]: info: Volumio Calling Home Sep 05 18:41:43 volumio volumio[18066]: info: Preparing to generate the ALSA configuration file Sep 05 18:41:43 volumio volumio[18066]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 05 18:41:43 volumio volumio[18066]: info: Reading ALSA contributions from plugins. Sep 05 18:41:43 volumio volumio[18066]: info: MPD Permissions set Sep 05 18:41:43 volumio volumio[18066]: info: MPD Permissions set Sep 05 18:41:43 volumio volumio[18066]: info: Spotify config file written Sep 05 18:41:43 volumio sudo[18231]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 05 18:41:43 volumio sudo[18231]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:43 volumio volumio[18066]: 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 18:41:43 volumio volumio[18066]: info: Volumio called home Sep 05 18:41:43 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:43 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:43 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:43 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:43 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:43 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:43 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:43 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:43 volumio systemd[1]: Stopping go-librespot Daemon... Sep 05 18:41:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 05 18:41:43 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 05 18:41:43 volumio systemd[1]: Stopped go-librespot Daemon. Sep 05 18:41:43 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 18:41:43 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:43 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:43 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:43 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:43 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:43 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:43 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:43 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:43 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:43 volumio systemd[1]: Started go-librespot Daemon. Sep 05 18:41:43 volumio go-librespot[18237]: go-librespot daemon starting... Sep 05 18:41:43 volumio sudo[18231]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:43 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 18:41:43 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:43 volumio volumio[18066]: info: No need to fix Spotify hosts Sep 05 18:41:43 volumio go-librespot[18237]: time="2025-09-05T18:41:43+02:00" level=info msg="running go-librespot 0.3.2" Sep 05 18:41:43 volumio go-librespot[18237]: time="2025-09-05T18:41:43+02:00" level=debug msg="app state loaded" Sep 05 18:41:43 volumio go-librespot[18237]: time="2025-09-05T18:41:43+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 05 18:41:43 volumio sudo[18250]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 05 18:41:43 volumio sudo[18250]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:43 volumio sudo[18250]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:43 volumio volumio[18066]: info: CoreCommandRouter::volumioGetState Sep 05 18:41:43 volumio volumio[18066]: info: CorePlayQueue::getTrack 0 Sep 05 18:41:43 volumio sudo[18253]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 05 18:41:43 volumio go-librespot[18237]: time="2025-09-05T18:41:43+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 18:41:43 volumio go-librespot[18237]: time="2025-09-05T18:41:43+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 18:41:43 volumio go-librespot[18237]: time="2025-09-05T18:41:43+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 18:41:43 volumio sudo[18253]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:43 volumio sudo[18253]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:43 volumio go-librespot[18237]: time="2025-09-05T18:41:43+02:00" level=info msg="zeroconf server listening on port 39155" Sep 05 18:41:43 volumio go-librespot[18237]: time="2025-09-05T18:41:43+02:00" level=debug msg="obtained new client token: AAAo4N+UEzZ+V61SOLwfyc1yQXEtl88fcRZElsZS2jUBisjCvVMRKU8m93z59C7vAjeo7T6+dEWI7uzpG5bI2jBFt3IrYDEw7F4jNG57H2w8b84nvSwxByfXzA01/LDCyQ4ylWkU62bpkbw/dmxbKZnsvh6Pk1Ug6BQLzexV7D1/OQwQxwp9Lw5LwAZ7gTRasUyOIFVUgceFgdEqYrqda8tYl6Qealr2SCYEMKtDfrzKJr7v6QMu+IaJLZs=" Sep 05 18:41:43 volumio go-librespot[18237]: time="2025-09-05T18:41:43+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 05 18:41:43 volumio go-librespot[18237]: time="2025-09-05T18:41:43+02:00" level=debug msg="completed keyexchange" Sep 05 18:41:43 volumio go-librespot[18237]: time="2025-09-05T18:41:43+02:00" level=debug msg="completed challenge" Sep 05 18:41:43 volumio volumio[18066]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 05 18:41:43 volumio volumio[18066]: SPOTIFY: BQCaadZWdMHcvlB9G3FiI2gLL7WPW0P2SlgToLr78QPs6vlmcZGxcaRactxHC2--0Jw1MZjlUsmkwzIpntj6U3FQ8X24BCRyPuSRh7FaIHPFCjptZpQpoMc6184yXR9o0lHK1o1d8hEtSFidVMlBBUcM_YSAiljobfohqByIqp3OYKKbrjxqECMcVZdOEUlTDhfdKy8dpGT74spxU_SymukArdA_gLhpjJYnPRCnHN0L_U12p2HFU3adZhvA_RaSYJjK5mWySKDQyW_SEVdEcNlDgKfrgycYwEem9ssJG_3WOZI Sep 05 18:41:43 volumio volumio[18066]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 05 18:41:43 volumio volumio[18066]: info: New Spotify access token = BQCaadZWdMHcvlB9G3FiI2gLL7WPW0P2SlgToLr78QPs6vlmcZGxcaRactxHC2--0Jw1MZjlUsmkwzIpntj6U3FQ8X24BCRyPuSRh7FaIHPFCjptZpQpoMc6184yXR9o0lHK1o1d8hEtSFidVMlBBUcM_YSAiljobfohqByIqp3OYKKbrjxqECMcVZdOEUlTDhfdKy8dpGT74spxU_SymukArdA_gLhpjJYnPRCnHN0L_U12p2HFU3adZhvA_RaSYJjK5mWySKDQyW_SEVdEcNlDgKfrgycYwEem9ssJG_3WOZI Sep 05 18:41:43 volumio volumio[18066]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 05 18:41:43 volumio go-librespot[18237]: time="2025-09-05T18:41:43+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 05 18:41:44 volumio volumio[18066]: info: CoreCommandRouter::volumioGetState Sep 05 18:41:44 volumio volumio[18066]: info: CorePlayQueue::getTrack 0 Sep 05 18:41:44 volumio go-librespot[18237]: time="2025-09-05T18:41:44+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 05 18:41:44 volumio go-librespot[18237]: time="2025-09-05T18:41:44+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 05 18:41:44 volumio volumio[18066]: info: Asound.conf file unchanged, so no further update is needed Sep 05 18:41:44 volumio volumio[18066]: info: Output device has changed, restarting MPD Sep 05 18:41:44 volumio go-librespot[18237]: time="2025-09-05T18:41:44+02:00" level=debug msg="dealer connection opened" Sep 05 18:41:44 volumio go-librespot[18237]: time="2025-09-05T18:41:44+02:00" level=trace msg="starting accesspoint recv loop" Sep 05 18:41:44 volumio go-librespot[18237]: time="2025-09-05T18:41:44+02:00" level=trace msg="starting dealer recv loop" Sep 05 18:41:44 volumio go-librespot[18237]: time="2025-09-05T18:41:44+02:00" level=trace msg="received accesspoint ping" Sep 05 18:41:44 volumio go-librespot[18237]: time="2025-09-05T18:41:44+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 05 18:41:44 volumio go-librespot[18237]: time="2025-09-05T18:41:44+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 05 18:41:44 volumio go-librespot[18237]: time="2025-09-05T18:41:44+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 05 18:41:44 volumio go-librespot[18237]: time="2025-09-05T18:41:44+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 05 18:41:44 volumio go-librespot[18237]: time="2025-09-05T18:41:44+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 05 18:41:44 volumio go-librespot[18237]: time="2025-09-05T18:41:44+02:00" level=debug msg="received connection id: ZmEyNmVmMmMtOTg1...QjVBOTU4MzQwRg==" Sep 05 18:41:44 volumio go-librespot[18237]: time="2025-09-05T18:41:44+02:00" level=trace msg="received accesspoint pong ack" Sep 05 18:41:44 volumio sudo[18257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 18:41:44 volumio sudo[18257]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:44 volumio sudo[18257]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:44 volumio sudo[18261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 18:41:44 volumio sudo[18261]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:44 volumio volumio[18066]: info: MPD Permissions set Sep 05 18:41:44 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 05 18:41:44 volumio systemd[1]: mpd.service: Succeeded. Sep 05 18:41:44 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 18:41:44 volumio go-librespot[18237]: time="2025-09-05T18:41:44+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 05 18:41:44 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:44 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:44 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:44 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:44 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:44 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:44 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:44 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:44 volumio sudo[18265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 05 18:41:44 volumio sudo[18265]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:44 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 18:41:44 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:44 volumio systemd[1]: Starting Music Player Daemon... Sep 05 18:41:44 volumio volumio[18066]: 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 18:41:44 volumio volumio[18066]: info: Spotify Successfully logged in Sep 05 18:41:44 volumio sudo[18265]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:44 volumio volumio[18066]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 18:41:44 volumio volumio[18066]: info: [1757090504349] CoreMusicLibrary::Adding element Spotify Sep 05 18:41:44 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 18:41:44 volumio volumio[18066]: Cannot find translation for source Jellyfin Sep 05 18:41:44 volumio volumio[18066]: Cannot find translation for source Spotify Sep 05 18:41:44 volumio volumio[18066]: info: Upmpdcli Daemon Started Sep 05 18:41:44 volumio sudo[18263]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 18:41:44 volumio sudo[18263]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:44 volumio sudo[18263]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:44 volumio volumio[18066]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 05 18:41:44 volumio volumio[18066]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 18:41:44 volumio volumio[18066]: info: VolumeController:: Volume=37 Mute =false Sep 05 18:41:44 volumio volumio[18066]: info: CoreCommandRouter::volumioGetState Sep 05 18:41:44 volumio volumio[18066]: info: CorePlayQueue::getTrack 0 Sep 05 18:41:44 volumio volumio[18066]: info: CoreStateMachine::pushState Sep 05 18:41:44 volumio volumio[18066]: info: CorePlayQueue::getTrack 0 Sep 05 18:41:44 volumio volumio[18066]: info: CoreCommandRouter::volumioPushState Sep 05 18:41:45 volumio volumio[18066]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 05 18:41:45 volumio volumio[18066]: 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 18:41:45 volumio volumio[18066]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 05 18:41:45 volumio volumio[18066]: info: camilladsp service started and running in background, instance 1 Sep 05 18:41:45 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:45 volumio volumio[18066]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 05 18:41:45 volumio volumio[18066]: 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 18:41:45 volumio volumio[18066]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 05 18:41:45 volumio volumio[18066]: info: FusionDsp loaded Sep 05 18:41:45 volumio volumio[18066]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 18:41:45 volumio sudo[18285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 05 18:41:45 volumio sudo[18285]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:45 volumio sudo[18285]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:45 volumio volumio[18066]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 05 18:41:45 volumio volumio[18066]: info: Adding Signal Path Element [object Object] Sep 05 18:41:45 volumio volumio[18066]: info: Adding fusiondspeq DSP Signal Path Element Sep 05 18:41:45 volumio volumio[18066]: info: FusionDsp - ---- installed callbackRead Sep 05 18:41:45 volumio volumio[18066]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 18:41:45 volumio volumio[18066]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 05 18:41:45 volumio volumio[18066]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 05 18:41:45 volumio volumio[18066]: at onErrorNT (internal/child_process.js:465:16) Sep 05 18:41:45 volumio volumio[18066]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Sep 05 18:41:45 volumio volumio[18066]: at runNextTicks (internal/process/task_queues.js:62:3) Sep 05 18:41:45 volumio volumio[18066]: at listOnTimeout (internal/timers.js:523:9) Sep 05 18:41:45 volumio volumio[18066]: at processTimers (internal/timers.js:497:7) { Sep 05 18:41:45 volumio volumio[18066]: errno: -2, Sep 05 18:41:45 volumio volumio[18066]: code: 'ENOENT', Sep 05 18:41:45 volumio volumio[18066]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 05 18:41:45 volumio volumio[18066]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 05 18:41:45 volumio volumio[18066]: spawnargs: [ Sep 05 18:41:45 volumio volumio[18066]: '-p', Sep 05 18:41:45 volumio volumio[18066]: 9876, Sep 05 18:41:45 volumio volumio[18066]: '-o', Sep 05 18:41:45 volumio volumio[18066]: '/tmp/camilladsp.log', Sep 05 18:41:45 volumio volumio[18066]: '-l', Sep 05 18:41:45 volumio volumio[18066]: 'warn', Sep 05 18:41:45 volumio volumio[18066]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 05 18:41:45 volumio volumio[18066]: ] Sep 05 18:41:45 volumio volumio[18066]: } Sep 05 18:41:45 volumio volumio[18066]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 18:41:45 volumio sudo[18296]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-05 18:40 Sep 05 18:41:45 volumio sudo[18296]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:46 volumio mpd[18272]: Sep 05 18:41 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 05 18:41:46 volumio systemd[1]: Started Music Player Daemon. Sep 05 18:41:46 volumio sudo[18194]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:46 volumio sudo[18261]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:46 volumio sudo[18182]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:46 volumio sudo[18296]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:46 volumio volumio-remote-updater[504]: [2025-09-05 18:41:46] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 05 18:41:46 volumio volumio-remote-updater[504]: [2025-09-05 18:41:46] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 05 18:41:46 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 05 18:41:46 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 05 18:41:46 volumio systemd[1]: Started dynamicswap service. Sep 05 18:41:46 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 05 18:41:46 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 05 18:41:46 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 19488. Sep 05 18:41:46 volumio systemd[1]: Stopped Volumio Backend Module. Sep 05 18:41:46 volumio systemd[1]: Started Volumio Backend Module. Sep 05 18:41:46 volumio systemd[1]: Started dynamicswap service. Sep 05 18:41:46 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 05 18:41:48 volumio volumio[18308]: info: ------------------------------------------- Sep 05 18:41:48 volumio volumio[18308]: info: ----- Volumio3 ---- Sep 05 18:41:48 volumio volumio[18308]: info: ------------------------------------------- Sep 05 18:41:48 volumio volumio[18308]: info: ----- System startup ---- Sep 05 18:41:48 volumio volumio[18308]: info: ------------------------------------------- Sep 05 18:41:48 volumio volumio[18308]: info: MYVOLUMIO Environment detected Sep 05 18:41:49 volumio volumio[18308]: info: Plugin folders cleanup Sep 05 18:41:49 volumio volumio[18308]: info: Scanning into folder /volumio/app/plugins/ Sep 05 18:41:49 volumio volumio[18308]: info: Scanning category audio_interface Sep 05 18:41:49 volumio volumio[18308]: info: Scanning category miscellanea Sep 05 18:41:49 volumio volumio[18308]: info: Scanning category music_service Sep 05 18:41:49 volumio volumio[18308]: info: Scanning category plugins.json Sep 05 18:41:49 volumio volumio[18308]: info: Scanning category system_controller Sep 05 18:41:49 volumio volumio[18308]: info: Scanning category user_interface Sep 05 18:41:49 volumio volumio[18308]: info: Scanning into folder /data/plugins/ Sep 05 18:41:49 volumio volumio[18308]: info: Scanning category audio_interface Sep 05 18:41:49 volumio volumio[18308]: info: Scanning category music_service Sep 05 18:41:49 volumio volumio[18308]: info: Scanning category user_interface Sep 05 18:41:49 volumio volumio[18308]: info: Plugin folders cleanup completed Sep 05 18:41:49 volumio volumio[18308]: info: ------------------------------------------- Sep 05 18:41:49 volumio volumio[18308]: info: ----- Core plugins startup ---- Sep 05 18:41:49 volumio volumio[18308]: info: ------------------------------------------- Sep 05 18:41:49 volumio volumio[18308]: info: Loading plugins from folder /volumio/app/plugins/ Sep 05 18:41:49 volumio volumio[18308]: info: Adding plugin upnp to MyMusic Plugins Sep 05 18:41:49 volumio volumio[18308]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 05 18:41:49 volumio volumio[18308]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 05 18:41:49 volumio volumio[18308]: info: Loading plugins from folder /data/plugins/ Sep 05 18:41:49 volumio volumio[18308]: info: Loading plugin "system"... Sep 05 18:41:49 volumio volumio[18308]: info: Loading plugin "appearance"... Sep 05 18:41:50 volumio volumio[18308]: info: Loading plugin "network"... Sep 05 18:41:50 volumio volumio[18308]: info: Refreshing Cached IP Addresses Sep 05 18:41:50 volumio sudo[18336]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 05 18:41:50 volumio sudo[18336]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:50 volumio sudo[18336]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:50 volumio sudo[18338]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 05 18:41:50 volumio volumio[18308]: info: Loading plugin "services"... Sep 05 18:41:50 volumio sudo[18338]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:50 volumio volumio[18308]: info: Loading plugin "alsa_controller"... Sep 05 18:41:50 volumio sudo[18338]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:50 volumio volumio[18308]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 18:41:50 volumio volumio[18308]: info: Loading plugin "wizard"... Sep 05 18:41:50 volumio volumio[18308]: info: Loading plugin "networkfs"... Sep 05 18:41:50 volumio volumio[18308]: info: Starting Udev Watcher for removable devices Sep 05 18:41:50 volumio volumio[18308]: info: Ignoring mount for partition: boot Sep 05 18:41:50 volumio volumio[18308]: info: Ignoring mount for partition: volumio Sep 05 18:41:50 volumio volumio[18308]: info: Ignoring mount for partition: volumio_data Sep 05 18:41:50 volumio volumio[18308]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 18:41:50 volumio volumio[18308]: info: Loading plugin "volumio_command_line_client"... Sep 05 18:41:50 volumio volumio[18308]: info: Loading plugin "upnp"... Sep 05 18:41:50 volumio volumio[18308]: info: [1757090510341] Starting Upmpd Daemon Sep 05 18:41:50 volumio volumio[18308]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 18:41:50 volumio volumio[18308]: info: Loading plugin "my_music"... Sep 05 18:41:50 volumio volumio[18308]: info: Loading plugin "mpd"... Sep 05 18:41:50 volumio volumio[18308]: info: Plugin upnp_browser is not enabled Sep 05 18:41:50 volumio volumio[18308]: info: Loading plugin "alarm-clock"... Sep 05 18:41:50 volumio volumio[18308]: info: Plugin airplay_emulation is not enabled Sep 05 18:41:50 volumio volumio[18308]: info: Loading plugin "last_100"... Sep 05 18:41:50 volumio volumio[18308]: info: Loading plugin "webradio"... Sep 05 18:41:50 volumio volumio[18308]: info: Loading plugin "i2s_dacs"... Sep 05 18:41:50 volumio volumio[18308]: info: Loading plugin "volumiodiscovery"... Sep 05 18:41:51 volumio volumio[18308]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 05 18:41:51 volumio volumio[18308]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 18:41:51 volumio volumio[18308]: *** WARNING *** For more information see Sep 05 18:41:51 volumio node[18308]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 05 18:41:51 volumio volumio[18308]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 05 18:41:51 volumio volumio[18308]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 18:41:51 volumio volumio[18308]: *** WARNING *** For more information see Sep 05 18:41:51 volumio node[18308]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 18:41:51 volumio node[18308]: *** WARNING *** For more information see Sep 05 18:41:51 volumio node[18308]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 05 18:41:51 volumio node[18308]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 18:41:51 volumio node[18308]: *** WARNING *** For more information see Sep 05 18:41:51 volumio volumio[18308]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 05 18:41:51 volumio volumio[18308]: info: Discovery: Started advertising with name: Volumio Sep 05 18:41:51 volumio volumio[18308]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 18:41:51 volumio volumio[18308]: info: Loading plugin "jellyfin"... Sep 05 18:41:51 volumio volumio-remote-updater[504]: [2025-09-05 18:41:51] [connect] Successful connection Sep 05 18:41:51 volumio volumio[18308]: info: Loading plugin "spop"... Sep 05 18:41:53 volumio volumio[18308]: info: Loading plugin "ytcr"... Sep 05 18:41:57 volumio volumio[18308]: info: Loading plugin "outputs"... Sep 05 18:41:57 volumio volumio[18308]: info: Loading plugin "albumart"... Sep 05 18:41:57 volumio volumio[18308]: info: Plugin example_plugin is not enabled Sep 05 18:41:57 volumio volumio[18308]: info: Loading plugin "inputs"... Sep 05 18:41:57 volumio volumio[18308]: info: Loading plugin "updater_comm"... Sep 05 18:41:57 volumio volumio[18308]: info: Plugin mpdemulation is not enabled Sep 05 18:41:57 volumio volumio[18308]: info: Loading plugin "rest_api"... Sep 05 18:41:57 volumio volumio[18308]: info: Loading plugin "websocket"... Sep 05 18:41:57 volumio volumio[18308]: info: Starting Socket.io Server version 2.3.0 Sep 05 18:41:57 volumio volumio[18308]: info: Loading plugin "fusiondsp"... Sep 05 18:41:57 volumio volumio[18308]: Forking 3 albumart workers Sep 05 18:41:57 volumio volumio[18308]: info: Applying required configuration parameters for plugin fusiondsp Sep 05 18:41:57 volumio volumio[18308]: info: Loading plugin "Systeminfo"... Sep 05 18:41:58 volumio volumio[18308]: info: Loading i18n strings for locale hu Sep 05 18:41:58 volumio volumio[18308]: Updating browse sources language Sep 05 18:41:58 volumio volumio[18308]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 18:41:58 volumio volumio[18308]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 18:41:58 volumio volumio[18308]: info: CoreCommandRouter::initPlayerControls Sep 05 18:41:58 volumio volumio[18308]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:58 volumio volumio[18308]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:58 volumio volumio[18308]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 18:41:58 volumio volumio[18308]: Express server listening on port 3000 Sep 05 18:41:58 volumio volumio[18308]: [Metrics] WebUI: 10s 993.64ms Sep 05 18:41:58 volumio volumio[18308]: info: CoreStateMachine::resetVolumioState Sep 05 18:41:58 volumio volumio[18308]: info: CoreStateMachine::getcurrentVolume Sep 05 18:41:58 volumio volumio[18308]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 18:41:58 volumio volumio[18308]: Starting albumart workers Sep 05 18:41:58 volumio volumio[18308]: Starting albumart workers Sep 05 18:41:58 volumio volumio[18308]: info: Volumio Network Manager: Network status updated: 1 Sep 05 18:41:58 volumio volumio[18308]: Starting albumart workers Sep 05 18:41:58 volumio volumio[18308]: info: CoreCommandRouter::volumioGetState Sep 05 18:41:58 volumio volumio[18308]: info: CorePlayQueue::getTrack 0 Sep 05 18:41:59 volumio volumio[18308]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 18:41:59 volumio volumio[18308]: info: VolumeController:: Volume=37 Mute =false Sep 05 18:41:59 volumio volumio[18308]: info: CoreStateMachine::pushState Sep 05 18:41:59 volumio volumio[18308]: info: CorePlayQueue::getTrack 0 Sep 05 18:41:59 volumio volumio[18308]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 18:41:59 volumio volumio[18308]: info: CoreCommandRouter::volumioPushState Sep 05 18:41:59 volumio volumio[18308]: info: CoreStateMachine::updateTrackBlock Sep 05 18:41:59 volumio volumio[18308]: info: CorePlayQueue::getTrackBlock Sep 05 18:41:59 volumio volumio[18308]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 18:41:59 volumio volumio[18308]: info: Setting Device type: Raspberry PI Sep 05 18:41:59 volumio volumio[18308]: info: CoreStateMachine::setRepeat false single undefined Sep 05 18:41:59 volumio volumio[18308]: info: CoreStateMachine::pushState Sep 05 18:41:59 volumio volumio[18308]: info: CorePlayQueue::getTrack 0 Sep 05 18:41:59 volumio volumio[18308]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 18:41:59 volumio volumio[18308]: info: CoreCommandRouter::volumioPushState Sep 05 18:41:59 volumio volumio[18308]: info: CoreStateMachine::setRandom true Sep 05 18:41:59 volumio volumio[18308]: info: CoreStateMachine::pushState Sep 05 18:41:59 volumio volumio[18308]: info: CorePlayQueue::getTrack 0 Sep 05 18:41:59 volumio volumio[18308]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 18:41:59 volumio volumio[18308]: info: CoreCommandRouter::volumioPushState Sep 05 18:41:59 volumio volumio[18308]: info: Listing playlists Sep 05 18:41:59 volumio volumio[18308]: info: Listing playlists Sep 05 18:41:59 volumio volumio[18308]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227 Sep 05 18:41:59 volumio volumio[18308]: info: Discovery: Found device Volumio Sep 05 18:41:59 volumio volumio[18308]: info: CoreCommandRouter::volumioGetState Sep 05 18:41:59 volumio volumio[18308]: info: CorePlayQueue::getTrack 0 Sep 05 18:41:59 volumio volumio[18308]: info: Completed loading Core Plugins Sep 05 18:41:59 volumio volumio[18308]: info: Preparing to generate the ALSA configuration file Sep 05 18:41:59 volumio volumio[18308]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 05 18:41:59 volumio volumio[18308]: info: Reading ALSA contributions from plugins. Sep 05 18:41:59 volumio volumio[18308]: info: VolumeController:: Volume=37 Mute =false Sep 05 18:41:59 volumio volumio[18308]: info: CoreStateMachine::pushState Sep 05 18:41:59 volumio volumio[18308]: info: CorePlayQueue::getTrack 0 Sep 05 18:41:59 volumio volumio[18308]: info: CoreCommandRouter::volumioPushState Sep 05 18:41:59 volumio volumio[18308]: info: Asound.conf file unchanged, so no further update is needed Sep 05 18:41:59 volumio volumio[18308]: info: Output device has changed, restarting MPD Sep 05 18:41:59 volumio sudo[18411]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 18:41:59 volumio sudo[18411]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:59 volumio sudo[18411]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:59 volumio volumio[18308]: info: ___________ START PLUGINS ___________ Sep 05 18:41:59 volumio volumio[18308]: info: ControllerMpd::onStart: Initializing MPD Sep 05 18:41:59 volumio volumio[18308]: info: Creating MPD Configuration file Sep 05 18:41:59 volumio sudo[18418]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 18:41:59 volumio sudo[18418]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:59 volumio systemd[1]: Stopping Music Player Daemon... Sep 05 18:41:59 volumio sudo[18437]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 18:41:59 volumio sudo[18437]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:59 volumio volumio[18308]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 18:41:59 volumio volumio[18308]: info: [1757090519603] CoreMusicLibrary::Adding element Last_100 Sep 05 18:41:59 volumio volumio[18308]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 18:41:59 volumio sudo[18437]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:59 volumio systemd[1]: mpd.service: Succeeded. Sep 05 18:41:59 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 18:41:59 volumio volumio[18308]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 18:41:59 volumio volumio[18308]: info: [1757090519621] CoreMusicLibrary::Adding element Webradio Sep 05 18:41:59 volumio volumio[18308]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 18:41:59 volumio volumio[18308]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 18:41:59 volumio sudo[18440]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 18:41:59 volumio sudo[18440]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:59 volumio volumio[18308]: info: Initializing BBC Radios Sep 05 18:41:59 volumio systemd[1]: Starting Music Player Daemon... Sep 05 18:41:59 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 05 18:41:59 volumio systemd[1]: mpd.service: Succeeded. Sep 05 18:41:59 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 18:41:59 volumio systemd[1]: Starting Music Player Daemon... Sep 05 18:41:59 volumio volumio[18308]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 18:41:59 volumio volumio[18308]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 18:41:59 volumio volumio[18308]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 18:41:59 volumio volumio[18308]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 05 18:41:59 volumio volumio[18308]: info: Discovery: Getting this device information Sep 05 18:41:59 volumio volumio[18308]: info: CoreCommandRouter::volumioGetState Sep 05 18:41:59 volumio volumio[18308]: info: CorePlayQueue::getTrack 0 Sep 05 18:41:59 volumio volumio[18308]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 18:41:59 volumio volumio[18308]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 18:41:59 volumio volumio[18308]: info: [1757090519773] CoreMusicLibrary::Adding element Jellyfin Sep 05 18:41:59 volumio volumio[18308]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 18:41:59 volumio volumio[18308]: Cannot find translation for source Jellyfin Sep 05 18:41:59 volumio volumio[18308]: 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 18:41:59 volumio volumio[18308]: info: Creating Spotify config file Sep 05 18:41:59 volumio volumio[18308]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:59 volumio sudo[18446]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 18:41:59 volumio sudo[18446]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:41:59 volumio sudo[18446]: pam_unix(sudo:session): session closed for user root Sep 05 18:41:59 volumio volumio[18308]: info: Loading i18n strings for locale hu Sep 05 18:41:59 volumio volumio[18308]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 05 18:41:59 volumio volumio[18308]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:41:59 volumio volumio[18308]: info: FusionDsp - mixtype--------------------- Hardware Sep 05 18:41:59 volumio volumio[18308]: info: Volumio Calling Home Sep 05 18:42:00 volumio volumio[18308]: info: Preparing to generate the ALSA configuration file Sep 05 18:42:00 volumio volumio[18308]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 05 18:42:00 volumio volumio[18308]: info: Reading ALSA contributions from plugins. Sep 05 18:42:00 volumio volumio[18308]: info: MPD Permissions set Sep 05 18:42:00 volumio volumio[18308]: info: MPD Permissions set Sep 05 18:42:00 volumio volumio[18308]: info: Spotify config file written Sep 05 18:42:00 volumio sudo[18474]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 05 18:42:00 volumio sudo[18474]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:00 volumio volumio[18308]: 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 18:42:00 volumio volumio[18308]: info: Volumio called home Sep 05 18:42:00 volumio volumio[18308]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:00 volumio volumio[18308]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:00 volumio volumio[18308]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:00 volumio volumio[18308]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:00 volumio volumio[18308]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:00 volumio volumio[18308]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:00 volumio volumio[18308]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:00 volumio systemd[1]: Stopping go-librespot Daemon... Sep 05 18:42:00 volumio volumio[18308]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 05 18:42:00 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 05 18:42:00 volumio systemd[1]: Stopped go-librespot Daemon. Sep 05 18:42:00 volumio volumio[18308]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 18:42:00 volumio volumio[18308]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:00 volumio systemd[1]: Started go-librespot Daemon. Sep 05 18:42:00 volumio volumio[18308]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 18:42:00 volumio volumio[18308]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:00 volumio go-librespot[18477]: go-librespot daemon starting... Sep 05 18:42:00 volumio volumio[18308]: info: No need to fix Spotify hosts Sep 05 18:42:00 volumio sudo[18474]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:00 volumio go-librespot[18477]: time="2025-09-05T18:42:00+02:00" level=info msg="running go-librespot 0.3.2" Sep 05 18:42:00 volumio go-librespot[18477]: time="2025-09-05T18:42:00+02:00" level=debug msg="app state loaded" Sep 05 18:42:00 volumio go-librespot[18477]: time="2025-09-05T18:42:00+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 05 18:42:00 volumio sudo[18495]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 05 18:42:00 volumio sudo[18493]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 05 18:42:00 volumio sudo[18495]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:00 volumio sudo[18493]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:00 volumio sudo[18495]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:00 volumio sudo[18493]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:00 volumio go-librespot[18477]: time="2025-09-05T18:42:00+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Sep 05 18:42:00 volumio go-librespot[18477]: time="2025-09-05T18:42:00+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 05 18:42:00 volumio go-librespot[18477]: time="2025-09-05T18:42:00+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 05 18:42:00 volumio go-librespot[18477]: time="2025-09-05T18:42:00+02:00" level=info msg="zeroconf server listening on port 44527" Sep 05 18:42:00 volumio go-librespot[18477]: time="2025-09-05T18:42:00+02:00" level=debug msg="obtained new client token: AACJ3ly63hrP0IErDCkJ2thga79exIpqIQY+woL6m9ibvd975mT20FscYcmcMXka5N/tGJXbp2HdiGOuG2bT4l37DNq4zvtyWErmnh6x6Eaxmn7nmMdMyXdcdvOkrkH2MO3WXQsVaxn8V1YMCuF9qwiMdvBm+0jayAqf2ltCUbE88HMUh6SvJA/BVwsbZ6n/cFaWTbdLSeduiVNm8F1bcr1cnYdmRIiV589cROUevQ7qEZGQ6epR2hSipLQ=" Sep 05 18:42:00 volumio volumio[18308]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 05 18:42:00 volumio volumio[18308]: SPOTIFY: BQCpFKqVz_dPSPBH02AXlszsXqfXM-4BuFoWwIHk1LhqaZ66ryPbFPvu1Jg28yCa_uiQ-DPTl790brRCcyW0WFZa4xu3UZ3UnGg_rUZK9oP3T1yXVML0lRqIw0CwbIJj70E6b9-ljRMAnMrzzkd9dLxExra0p1xD_AvuAo9EBwi6YHRHG4hGnA5Ik85LDGcqTlMoLTvNoR-FiLqe0M5oYOuwm-0IsiaCrQEdHWzX9XPUgi8_G-xnv87dravbLSD4mAb-5KqDl_g1XirKo_oZq4TzOUbG34XdBxjUp2AYG0iUaWU Sep 05 18:42:00 volumio volumio[18308]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 05 18:42:00 volumio volumio[18308]: info: New Spotify access token = BQCpFKqVz_dPSPBH02AXlszsXqfXM-4BuFoWwIHk1LhqaZ66ryPbFPvu1Jg28yCa_uiQ-DPTl790brRCcyW0WFZa4xu3UZ3UnGg_rUZK9oP3T1yXVML0lRqIw0CwbIJj70E6b9-ljRMAnMrzzkd9dLxExra0p1xD_AvuAo9EBwi6YHRHG4hGnA5Ik85LDGcqTlMoLTvNoR-FiLqe0M5oYOuwm-0IsiaCrQEdHWzX9XPUgi8_G-xnv87dravbLSD4mAb-5KqDl_g1XirKo_oZq4TzOUbG34XdBxjUp2AYG0iUaWU Sep 05 18:42:00 volumio volumio[18308]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 05 18:42:00 volumio go-librespot[18477]: time="2025-09-05T18:42:00+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 05 18:42:00 volumio volumio[18308]: info: CoreCommandRouter::volumioGetState Sep 05 18:42:00 volumio volumio[18308]: info: CorePlayQueue::getTrack 0 Sep 05 18:42:00 volumio go-librespot[18477]: time="2025-09-05T18:42:00+02:00" level=debug msg="completed keyexchange" Sep 05 18:42:00 volumio go-librespot[18477]: time="2025-09-05T18:42:00+02:00" level=debug msg="completed challenge" Sep 05 18:42:00 volumio volumio[18308]: info: Asound.conf file unchanged, so no further update is needed Sep 05 18:42:00 volumio volumio[18308]: info: Output device has changed, restarting MPD Sep 05 18:42:00 volumio go-librespot[18477]: time="2025-09-05T18:42:00+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 05 18:42:00 volumio sudo[18499]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 18:42:00 volumio sudo[18499]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:00 volumio sudo[18499]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:00 volumio sudo[18501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 18:42:00 volumio sudo[18501]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:00 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 05 18:42:00 volumio systemd[1]: mpd.service: Succeeded. Sep 05 18:42:00 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 18:42:00 volumio go-librespot[18477]: time="2025-09-05T18:42:00+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 05 18:42:00 volumio go-librespot[18477]: time="2025-09-05T18:42:00+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 05 18:42:01 volumio volumio[18308]: info: MPD Permissions set Sep 05 18:42:01 volumio volumio[18308]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:01 volumio go-librespot[18477]: time="2025-09-05T18:42:01+02:00" level=debug msg="dealer connection opened" Sep 05 18:42:01 volumio go-librespot[18477]: time="2025-09-05T18:42:01+02:00" level=trace msg="starting accesspoint recv loop" Sep 05 18:42:01 volumio go-librespot[18477]: time="2025-09-05T18:42:01+02:00" level=trace msg="starting dealer recv loop" Sep 05 18:42:01 volumio go-librespot[18477]: time="2025-09-05T18:42:01+02:00" level=trace msg="received accesspoint ping" Sep 05 18:42:01 volumio go-librespot[18477]: time="2025-09-05T18:42:01+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 05 18:42:01 volumio go-librespot[18477]: time="2025-09-05T18:42:01+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 05 18:42:01 volumio volumio[18308]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:01 volumio volumio[18308]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:01 volumio go-librespot[18477]: time="2025-09-05T18:42:01+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 05 18:42:01 volumio go-librespot[18477]: time="2025-09-05T18:42:01+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 05 18:42:01 volumio go-librespot[18477]: time="2025-09-05T18:42:01+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 05 18:42:01 volumio systemd[1]: Starting Music Player Daemon... Sep 05 18:42:01 volumio volumio[18308]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:01 volumio go-librespot[18477]: time="2025-09-05T18:42:01+02:00" level=debug msg="received connection id: MjEwZmQwMDctZGNk...MUU0RTkxMzE5NQ==" Sep 05 18:42:01 volumio sudo[18507]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 05 18:42:01 volumio go-librespot[18477]: time="2025-09-05T18:42:01+02:00" level=trace msg="received accesspoint pong ack" Sep 05 18:42:01 volumio sudo[18507]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:01 volumio volumio[18308]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 18:42:01 volumio volumio[18308]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:01 volumio sudo[18507]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:01 volumio volumio[18308]: info: Upmpdcli Daemon Started Sep 05 18:42:01 volumio volumio[18308]: 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 18:42:01 volumio volumio[18308]: info: Spotify Successfully logged in Sep 05 18:42:01 volumio volumio[18308]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 18:42:01 volumio volumio[18308]: info: [1757090521142] CoreMusicLibrary::Adding element Spotify Sep 05 18:42:01 volumio volumio[18308]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 18:42:01 volumio volumio[18308]: Cannot find translation for source Jellyfin Sep 05 18:42:01 volumio volumio[18308]: Cannot find translation for source Spotify Sep 05 18:42:01 volumio go-librespot[18477]: time="2025-09-05T18:42:01+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 05 18:42:01 volumio sudo[18505]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 18:42:01 volumio sudo[18505]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:01 volumio sudo[18505]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:01 volumio volumio[18308]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 05 18:42:01 volumio volumio[18308]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 18:42:01 volumio volumio[18308]: info: VolumeController:: Volume=37 Mute =false Sep 05 18:42:01 volumio volumio[18308]: info: CoreCommandRouter::volumioGetState Sep 05 18:42:01 volumio volumio[18308]: info: CorePlayQueue::getTrack 0 Sep 05 18:42:01 volumio volumio[18308]: info: CoreStateMachine::pushState Sep 05 18:42:01 volumio volumio[18308]: info: CorePlayQueue::getTrack 0 Sep 05 18:42:01 volumio volumio[18308]: info: CoreCommandRouter::volumioPushState Sep 05 18:42:02 volumio volumio[18308]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 05 18:42:02 volumio volumio[18308]: 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 18:42:02 volumio volumio[18308]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 05 18:42:02 volumio volumio[18308]: info: camilladsp service started and running in background, instance 1 Sep 05 18:42:02 volumio volumio[18308]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:02 volumio volumio[18308]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 05 18:42:02 volumio volumio[18308]: 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 18:42:02 volumio volumio[18308]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 05 18:42:02 volumio volumio[18308]: info: FusionDsp loaded Sep 05 18:42:02 volumio volumio[18308]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 18:42:02 volumio sudo[18565]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 05 18:42:02 volumio sudo[18565]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:02 volumio sudo[18565]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:02 volumio volumio[18308]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 05 18:42:02 volumio volumio[18308]: info: Adding Signal Path Element [object Object] Sep 05 18:42:02 volumio volumio[18308]: info: Adding fusiondspeq DSP Signal Path Element Sep 05 18:42:02 volumio volumio[18308]: info: FusionDsp - ---- installed callbackRead Sep 05 18:42:02 volumio volumio[18308]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 18:42:02 volumio volumio[18308]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 05 18:42:02 volumio volumio[18308]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 05 18:42:02 volumio volumio[18308]: at onErrorNT (internal/child_process.js:465:16) Sep 05 18:42:02 volumio volumio[18308]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Sep 05 18:42:02 volumio volumio[18308]: errno: -2, Sep 05 18:42:02 volumio volumio[18308]: code: 'ENOENT', Sep 05 18:42:02 volumio volumio[18308]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 05 18:42:02 volumio volumio[18308]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 05 18:42:02 volumio volumio[18308]: spawnargs: [ Sep 05 18:42:02 volumio volumio[18308]: '-p', Sep 05 18:42:02 volumio volumio[18308]: 9876, Sep 05 18:42:02 volumio volumio[18308]: '-o', Sep 05 18:42:02 volumio volumio[18308]: '/tmp/camilladsp.log', Sep 05 18:42:02 volumio volumio[18308]: '-l', Sep 05 18:42:02 volumio volumio[18308]: 'warn', Sep 05 18:42:02 volumio volumio[18308]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 05 18:42:02 volumio volumio[18308]: ] Sep 05 18:42:02 volumio volumio[18308]: } Sep 05 18:42:02 volumio volumio[18308]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 18:42:02 volumio mpd[18530]: Sep 05 18:42 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 05 18:42:02 volumio systemd[1]: Started Music Player Daemon. Sep 05 18:42:02 volumio sudo[18418]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:02 volumio sudo[18501]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:02 volumio sudo[18440]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:02 volumio sudo[18578]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-05 18:41 Sep 05 18:42:02 volumio sudo[18578]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:02 volumio sudo[18578]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:03 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 05 18:42:03 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 05 18:42:03 volumio systemd[1]: Started dynamicswap service. Sep 05 18:42:03 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 05 18:42:03 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 05 18:42:03 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 19489. Sep 05 18:42:03 volumio systemd[1]: Stopped Volumio Backend Module. Sep 05 18:42:03 volumio systemd[1]: Started Volumio Backend Module. Sep 05 18:42:03 volumio systemd[1]: Started dynamicswap service. Sep 05 18:42:03 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 05 18:42:03 volumio volumio-remote-updater[504]: [2025-09-05 18:42:03] [info] asio async_connect error: system:111 (Connection refused) Sep 05 18:42:03 volumio volumio-remote-updater[504]: [2025-09-05 18:42:03] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 05 18:42:03 volumio volumio-remote-updater[504]: [2025-09-05 18:42:03] [error] handle_connect error: Underlying Transport Error Sep 05 18:42:05 volumio volumio[18588]: info: ------------------------------------------- Sep 05 18:42:05 volumio volumio[18588]: info: ----- Volumio3 ---- Sep 05 18:42:05 volumio volumio[18588]: info: ------------------------------------------- Sep 05 18:42:05 volumio volumio[18588]: info: ----- System startup ---- Sep 05 18:42:05 volumio volumio[18588]: info: ------------------------------------------- Sep 05 18:42:05 volumio volumio[18588]: info: MYVOLUMIO Environment detected Sep 05 18:42:06 volumio volumio[18588]: info: Plugin folders cleanup Sep 05 18:42:06 volumio volumio[18588]: info: Scanning into folder /volumio/app/plugins/ Sep 05 18:42:06 volumio volumio[18588]: info: Scanning category audio_interface Sep 05 18:42:06 volumio volumio[18588]: info: Scanning category miscellanea Sep 05 18:42:06 volumio volumio[18588]: info: Scanning category music_service Sep 05 18:42:06 volumio volumio[18588]: info: Scanning category plugins.json Sep 05 18:42:06 volumio volumio[18588]: info: Scanning category system_controller Sep 05 18:42:06 volumio volumio[18588]: info: Scanning category user_interface Sep 05 18:42:06 volumio volumio[18588]: info: Scanning into folder /data/plugins/ Sep 05 18:42:06 volumio volumio[18588]: info: Scanning category audio_interface Sep 05 18:42:06 volumio volumio[18588]: info: Scanning category music_service Sep 05 18:42:06 volumio volumio[18588]: info: Scanning category user_interface Sep 05 18:42:06 volumio volumio[18588]: info: Plugin folders cleanup completed Sep 05 18:42:06 volumio volumio[18588]: info: ------------------------------------------- Sep 05 18:42:06 volumio volumio[18588]: info: ----- Core plugins startup ---- Sep 05 18:42:06 volumio volumio[18588]: info: ------------------------------------------- Sep 05 18:42:06 volumio volumio[18588]: info: Loading plugins from folder /volumio/app/plugins/ Sep 05 18:42:06 volumio volumio[18588]: info: Adding plugin upnp to MyMusic Plugins Sep 05 18:42:06 volumio volumio[18588]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 05 18:42:06 volumio volumio[18588]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 05 18:42:06 volumio volumio[18588]: info: Loading plugins from folder /data/plugins/ Sep 05 18:42:06 volumio volumio[18588]: info: Loading plugin "system"... Sep 05 18:42:06 volumio volumio[18588]: info: Loading plugin "appearance"... Sep 05 18:42:07 volumio volumio[18588]: info: Loading plugin "network"... Sep 05 18:42:07 volumio volumio[18588]: info: Refreshing Cached IP Addresses Sep 05 18:42:07 volumio sudo[18615]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 05 18:42:07 volumio sudo[18615]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:07 volumio sudo[18615]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:07 volumio sudo[18617]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 05 18:42:07 volumio sudo[18617]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:07 volumio volumio[18588]: info: Loading plugin "services"... Sep 05 18:42:07 volumio volumio[18588]: info: Loading plugin "alsa_controller"... Sep 05 18:42:07 volumio sudo[18617]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:07 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 18:42:07 volumio volumio[18588]: info: Loading plugin "wizard"... Sep 05 18:42:07 volumio volumio[18588]: info: Loading plugin "networkfs"... Sep 05 18:42:07 volumio volumio[18588]: info: Starting Udev Watcher for removable devices Sep 05 18:42:07 volumio volumio[18588]: info: Ignoring mount for partition: boot Sep 05 18:42:07 volumio volumio[18588]: info: Ignoring mount for partition: volumio Sep 05 18:42:07 volumio volumio[18588]: info: Ignoring mount for partition: volumio_data Sep 05 18:42:07 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 18:42:07 volumio volumio[18588]: info: Loading plugin "volumio_command_line_client"... Sep 05 18:42:07 volumio volumio[18588]: info: Loading plugin "upnp"... Sep 05 18:42:07 volumio volumio[18588]: info: [1757090527344] Starting Upmpd Daemon Sep 05 18:42:07 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 18:42:07 volumio volumio[18588]: info: Loading plugin "my_music"... Sep 05 18:42:07 volumio volumio[18588]: info: Loading plugin "mpd"... Sep 05 18:42:07 volumio volumio[18588]: info: Plugin upnp_browser is not enabled Sep 05 18:42:07 volumio volumio[18588]: info: Loading plugin "alarm-clock"... Sep 05 18:42:07 volumio volumio[18588]: info: Plugin airplay_emulation is not enabled Sep 05 18:42:07 volumio volumio[18588]: info: Loading plugin "last_100"... Sep 05 18:42:07 volumio volumio[18588]: info: Loading plugin "webradio"... Sep 05 18:42:07 volumio volumio[18588]: info: Loading plugin "i2s_dacs"... Sep 05 18:42:08 volumio volumio[18588]: info: Loading plugin "volumiodiscovery"... Sep 05 18:42:08 volumio volumio[18588]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 05 18:42:08 volumio volumio[18588]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 18:42:08 volumio volumio[18588]: *** WARNING *** For more information see Sep 05 18:42:08 volumio node[18588]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 05 18:42:08 volumio volumio[18588]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 05 18:42:08 volumio volumio[18588]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 18:42:08 volumio volumio[18588]: *** WARNING *** For more information see Sep 05 18:42:08 volumio node[18588]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 18:42:08 volumio node[18588]: *** WARNING *** For more information see Sep 05 18:42:08 volumio node[18588]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 05 18:42:08 volumio node[18588]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 18:42:08 volumio node[18588]: *** WARNING *** For more information see Sep 05 18:42:08 volumio volumio[18588]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 05 18:42:08 volumio volumio[18588]: info: Discovery: Started advertising with name: Volumio Sep 05 18:42:08 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 18:42:08 volumio volumio[18588]: info: Loading plugin "jellyfin"... Sep 05 18:42:08 volumio volumio[18588]: info: Loading plugin "spop"... Sep 05 18:42:10 volumio volumio[18588]: info: Loading plugin "ytcr"... Sep 05 18:42:13 volumio volumio-remote-updater[504]: [2025-09-05 18:42:13] [connect] Successful connection Sep 05 18:42:14 volumio volumio[18588]: info: Loading plugin "outputs"... Sep 05 18:42:14 volumio volumio[18588]: info: Loading plugin "albumart"... Sep 05 18:42:14 volumio volumio[18588]: info: Plugin example_plugin is not enabled Sep 05 18:42:14 volumio volumio[18588]: info: Loading plugin "inputs"... Sep 05 18:42:14 volumio volumio[18588]: info: Loading plugin "updater_comm"... Sep 05 18:42:14 volumio volumio[18588]: info: Plugin mpdemulation is not enabled Sep 05 18:42:14 volumio volumio[18588]: info: Loading plugin "rest_api"... Sep 05 18:42:14 volumio volumio[18588]: info: Loading plugin "websocket"... Sep 05 18:42:14 volumio volumio[18588]: info: Starting Socket.io Server version 2.3.0 Sep 05 18:42:14 volumio volumio[18588]: info: Loading plugin "fusiondsp"... Sep 05 18:42:14 volumio volumio[18588]: Forking 3 albumart workers Sep 05 18:42:14 volumio volumio[18588]: info: Applying required configuration parameters for plugin fusiondsp Sep 05 18:42:15 volumio volumio[18588]: info: Loading plugin "Systeminfo"... Sep 05 18:42:15 volumio volumio[18588]: info: Loading i18n strings for locale hu Sep 05 18:42:15 volumio volumio[18588]: Updating browse sources language Sep 05 18:42:15 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 18:42:15 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 18:42:15 volumio volumio[18588]: info: CoreCommandRouter::initPlayerControls Sep 05 18:42:15 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:15 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:15 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:15 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:15 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:15 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:15 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:15 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:15 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 18:42:15 volumio volumio[18588]: Express server listening on port 3000 Sep 05 18:42:15 volumio volumio[18588]: [Metrics] WebUI: 11s 80.26ms Sep 05 18:42:15 volumio volumio[18588]: info: CoreStateMachine::resetVolumioState Sep 05 18:42:15 volumio volumio[18588]: info: CoreStateMachine::getcurrentVolume Sep 05 18:42:15 volumio volumio[18588]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 18:42:15 volumio volumio[18588]: Starting albumart workers Sep 05 18:42:15 volumio volumio[18588]: info: Volumio Network Manager: Network status updated: 1 Sep 05 18:42:15 volumio volumio[18588]: Starting albumart workers Sep 05 18:42:15 volumio volumio-remote-updater[504]: [2025-09-05 18:42:15] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1757090533 101 Sep 05 18:42:15 volumio volumio[18588]: Starting albumart workers Sep 05 18:42:15 volumio volumio[18588]: 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 18:42:16 volumio volumio[18588]: info: CoreCommandRouter::volumioGetState Sep 05 18:42:16 volumio volumio[18588]: info: CorePlayQueue::getTrack 0 Sep 05 18:42:16 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 18:42:16 volumio volumio[18588]: info: VolumeController:: Volume=37 Mute =false Sep 05 18:42:16 volumio volumio[18588]: info: CoreStateMachine::pushState Sep 05 18:42:16 volumio volumio[18588]: info: CorePlayQueue::getTrack 0 Sep 05 18:42:16 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 18:42:16 volumio volumio[18588]: info: CoreCommandRouter::volumioPushState Sep 05 18:42:16 volumio volumio[18588]: info: CoreStateMachine::updateTrackBlock Sep 05 18:42:16 volumio volumio[18588]: info: CorePlayQueue::getTrackBlock Sep 05 18:42:16 volumio volumio[18588]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 18:42:16 volumio volumio[18588]: info: CoreStateMachine::setRepeat false single undefined Sep 05 18:42:16 volumio volumio[18588]: info: CoreStateMachine::pushState Sep 05 18:42:16 volumio volumio[18588]: info: CorePlayQueue::getTrack 0 Sep 05 18:42:16 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 18:42:16 volumio volumio[18588]: info: CoreCommandRouter::volumioPushState Sep 05 18:42:16 volumio volumio[18588]: info: CoreStateMachine::setRandom true Sep 05 18:42:16 volumio volumio[18588]: info: CoreStateMachine::pushState Sep 05 18:42:16 volumio volumio[18588]: info: CorePlayQueue::getTrack 0 Sep 05 18:42:16 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 18:42:16 volumio volumio[18588]: info: CoreCommandRouter::volumioPushState Sep 05 18:42:16 volumio volumio[18588]: info: Setting Device type: Raspberry PI Sep 05 18:42:16 volumio volumio[18588]: info: Listing playlists Sep 05 18:42:16 volumio volumio[18588]: info: Listing playlists Sep 05 18:42:16 volumio volumio[18588]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227 Sep 05 18:42:16 volumio volumio[18588]: info: Discovery: Found device Volumio Sep 05 18:42:16 volumio volumio[18588]: info: CoreCommandRouter::volumioGetState Sep 05 18:42:16 volumio volumio[18588]: info: CorePlayQueue::getTrack 0 Sep 05 18:42:16 volumio volumio[18588]: info: Completed loading Core Plugins Sep 05 18:42:16 volumio volumio[18588]: info: Preparing to generate the ALSA configuration file Sep 05 18:42:16 volumio volumio[18588]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 05 18:42:16 volumio volumio[18588]: info: Reading ALSA contributions from plugins. Sep 05 18:42:16 volumio volumio[18588]: info: VolumeController:: Volume=37 Mute =false Sep 05 18:42:16 volumio volumio[18588]: info: CoreStateMachine::pushState Sep 05 18:42:16 volumio volumio[18588]: info: CorePlayQueue::getTrack 0 Sep 05 18:42:16 volumio volumio[18588]: info: CoreCommandRouter::volumioPushState Sep 05 18:42:16 volumio volumio[18588]: info: Asound.conf file unchanged, so no further update is needed Sep 05 18:42:16 volumio volumio[18588]: info: Output device has changed, restarting MPD Sep 05 18:42:16 volumio sudo[18692]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 18:42:16 volumio sudo[18692]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:16 volumio volumio[18588]: info: ___________ START PLUGINS ___________ Sep 05 18:42:16 volumio sudo[18692]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:16 volumio volumio[18588]: info: ControllerMpd::onStart: Initializing MPD Sep 05 18:42:16 volumio volumio[18588]: info: Creating MPD Configuration file Sep 05 18:42:16 volumio sudo[18695]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 18:42:16 volumio sudo[18695]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:16 volumio systemd[1]: Stopping Music Player Daemon... Sep 05 18:42:16 volumio volumio[18588]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 18:42:16 volumio volumio[18588]: info: [1757090536676] CoreMusicLibrary::Adding element Last_100 Sep 05 18:42:16 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 18:42:16 volumio volumio[18588]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 18:42:16 volumio volumio[18588]: info: [1757090536684] CoreMusicLibrary::Adding element Webradio Sep 05 18:42:16 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 18:42:16 volumio sudo[18702]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 18:42:16 volumio sudo[18702]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:16 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 18:42:16 volumio sudo[18708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 18:42:16 volumio sudo[18708]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:16 volumio systemd[1]: mpd.service: Succeeded. Sep 05 18:42:16 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 18:42:16 volumio volumio[18588]: info: Initializing BBC Radios Sep 05 18:42:16 volumio sudo[18702]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:16 volumio systemd[1]: Starting Music Player Daemon... Sep 05 18:42:16 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 05 18:42:16 volumio systemd[1]: mpd.service: Succeeded. Sep 05 18:42:16 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 18:42:16 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 18:42:16 volumio systemd[1]: Starting Music Player Daemon... Sep 05 18:42:16 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 18:42:16 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 18:42:16 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 05 18:42:16 volumio volumio[18588]: info: Discovery: Getting this device information Sep 05 18:42:16 volumio volumio[18588]: info: CoreCommandRouter::volumioGetState Sep 05 18:42:16 volumio volumio[18588]: info: CorePlayQueue::getTrack 0 Sep 05 18:42:16 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 18:42:16 volumio volumio[18588]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 18:42:16 volumio volumio[18588]: info: [1757090536854] CoreMusicLibrary::Adding element Jellyfin Sep 05 18:42:16 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 18:42:16 volumio volumio[18588]: Cannot find translation for source Jellyfin Sep 05 18:42:16 volumio volumio[18588]: 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 18:42:16 volumio volumio[18588]: info: Creating Spotify config file Sep 05 18:42:16 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:16 volumio sudo[18730]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 18:42:16 volumio sudo[18730]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:16 volumio sudo[18730]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:17 volumio volumio[18588]: info: Loading i18n strings for locale hu Sep 05 18:42:17 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 05 18:42:17 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:17 volumio volumio[18588]: info: FusionDsp - mixtype--------------------- Hardware Sep 05 18:42:17 volumio volumio[18588]: info: Volumio Calling Home Sep 05 18:42:17 volumio volumio[18588]: info: Preparing to generate the ALSA configuration file Sep 05 18:42:17 volumio volumio[18588]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 05 18:42:17 volumio volumio[18588]: info: Reading ALSA contributions from plugins. Sep 05 18:42:17 volumio volumio[18588]: info: MPD Permissions set Sep 05 18:42:17 volumio volumio[18588]: info: MPD Permissions set Sep 05 18:42:17 volumio sudo[18751]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 05 18:42:17 volumio sudo[18751]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:17 volumio sudo[18751]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:17 volumio sudo[18754]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 05 18:42:17 volumio volumio[18588]: info: Volumio called home Sep 05 18:42:17 volumio sudo[18754]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:17 volumio volumio[18588]: info: Spotify config file written Sep 05 18:42:17 volumio sudo[18754]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:17 volumio sudo[18757]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 05 18:42:17 volumio sudo[18757]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:17 volumio volumio[18588]: 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 18:42:17 volumio systemd[1]: Stopping go-librespot Daemon... Sep 05 18:42:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 05 18:42:17 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 05 18:42:17 volumio systemd[1]: Stopped go-librespot Daemon. Sep 05 18:42:17 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:17 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:17 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:17 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:17 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:17 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:17 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:17 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:17 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 18:42:17 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:17 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:17 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:17 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:17 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:17 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:17 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:17 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:17 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:17 volumio systemd[1]: Started go-librespot Daemon. Sep 05 18:42:17 volumio go-librespot[18759]: go-librespot daemon starting... Sep 05 18:42:17 volumio sudo[18757]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:17 volumio go-librespot[18759]: time="2025-09-05T18:42:17+02:00" level=info msg="running go-librespot 0.3.2" Sep 05 18:42:17 volumio go-librespot[18759]: time="2025-09-05T18:42:17+02:00" level=debug msg="app state loaded" Sep 05 18:42:17 volumio go-librespot[18759]: time="2025-09-05T18:42:17+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 05 18:42:17 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 18:42:17 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:17 volumio volumio[18588]: info: No need to fix Spotify hosts Sep 05 18:42:17 volumio go-librespot[18759]: time="2025-09-05T18:42:17+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 18:42:17 volumio go-librespot[18759]: time="2025-09-05T18:42:17+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 18:42:17 volumio go-librespot[18759]: time="2025-09-05T18:42:17+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 18:42:17 volumio go-librespot[18759]: time="2025-09-05T18:42:17+02:00" level=info msg="zeroconf server listening on port 42321" Sep 05 18:42:18 volumio volumio[18588]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 05 18:42:18 volumio volumio[18588]: SPOTIFY: BQC4BNyT9-GPlGQDWeZpbnYZPu6wggbZoOBem9JlxlSHNqaBNUGSDPorqdwvuoyauGME8YDkmzjOhkKVpR8UOmIu2KHIvOFS-aIQZMIBRxhhpFrhqffrYelSAeEPBUWA5MWMuJs9qppYfKlO9f8HatrxbXAV2qH1NuX4WU3cUTkDJ3mIN8fJjJK1ulEQSB8IKjr0oi-ktArX12BXBaRdU7LNm3yGn5iRgyZHB9yejpMRZiDO2iC4LG2F4EG6fLH4vOV_3RCswydL6MdugN0I-X7X9ohCDbbdagRVN4Uj_IUfS4I Sep 05 18:42:18 volumio volumio[18588]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 05 18:42:18 volumio volumio[18588]: info: New Spotify access token = BQC4BNyT9-GPlGQDWeZpbnYZPu6wggbZoOBem9JlxlSHNqaBNUGSDPorqdwvuoyauGME8YDkmzjOhkKVpR8UOmIu2KHIvOFS-aIQZMIBRxhhpFrhqffrYelSAeEPBUWA5MWMuJs9qppYfKlO9f8HatrxbXAV2qH1NuX4WU3cUTkDJ3mIN8fJjJK1ulEQSB8IKjr0oi-ktArX12BXBaRdU7LNm3yGn5iRgyZHB9yejpMRZiDO2iC4LG2F4EG6fLH4vOV_3RCswydL6MdugN0I-X7X9ohCDbbdagRVN4Uj_IUfS4I Sep 05 18:42:18 volumio volumio[18588]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 05 18:42:18 volumio go-librespot[18759]: time="2025-09-05T18:42:18+02:00" level=debug msg="obtained new client token: AADMaQEURDeTmiSySYrXU/MpXMoKv6llhXmgPfxtoaRZhJOxQeEfOYR4EvRJZvA8AnjJYHqhSJZYgNNqeGbIO5F7qIx18DOc5TlvTOgN3iJtkM+szRfdSaW/tx0nORnjYKkwedW2DFSX4Pn1X+YER8AO0rHXoLwRXctTxjiAg1Xp0aOh1h67CW6awzKDFJV0nEqWegCHToj1Q3anZy13NUfv1Arpgl2yqcbxpmYWLrGXTOkcL8oFtJV/" Sep 05 18:42:18 volumio volumio[18588]: info: CoreCommandRouter::volumioGetState Sep 05 18:42:18 volumio volumio[18588]: info: CorePlayQueue::getTrack 0 Sep 05 18:42:18 volumio go-librespot[18759]: time="2025-09-05T18:42:18+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 05 18:42:18 volumio volumio[18588]: info: Asound.conf file unchanged, so no further update is needed Sep 05 18:42:18 volumio volumio[18588]: info: Output device has changed, restarting MPD Sep 05 18:42:18 volumio go-librespot[18759]: time="2025-09-05T18:42:18+02:00" level=debug msg="completed keyexchange" Sep 05 18:42:18 volumio go-librespot[18759]: time="2025-09-05T18:42:18+02:00" level=debug msg="completed challenge" Sep 05 18:42:18 volumio sudo[18777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 05 18:42:18 volumio sudo[18777]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:18 volumio sudo[18779]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 18:42:18 volumio sudo[18779]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:18 volumio go-librespot[18759]: time="2025-09-05T18:42:18+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 05 18:42:18 volumio sudo[18779]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:18 volumio sudo[18777]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:18 volumio sudo[18782]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 18:42:18 volumio sudo[18782]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:18 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 05 18:42:18 volumio systemd[1]: mpd.service: Succeeded. Sep 05 18:42:18 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 18:42:18 volumio volumio[18588]: info: MPD Permissions set Sep 05 18:42:18 volumio go-librespot[18759]: time="2025-09-05T18:42:18+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 05 18:42:18 volumio go-librespot[18759]: time="2025-09-05T18:42:18+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 05 18:42:18 volumio volumio[18588]: info: Upmpdcli Daemon Started Sep 05 18:42:18 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:18 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:18 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:18 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:18 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:18 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:18 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:18 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:18 volumio systemd[1]: Starting Music Player Daemon... Sep 05 18:42:18 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 18:42:18 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:18 volumio go-librespot[18759]: time="2025-09-05T18:42:18+02:00" level=debug msg="dealer connection opened" Sep 05 18:42:18 volumio go-librespot[18759]: time="2025-09-05T18:42:18+02:00" level=trace msg="starting accesspoint recv loop" Sep 05 18:42:18 volumio go-librespot[18759]: time="2025-09-05T18:42:18+02:00" level=trace msg="starting dealer recv loop" Sep 05 18:42:18 volumio go-librespot[18759]: time="2025-09-05T18:42:18+02:00" level=trace msg="received accesspoint ping" Sep 05 18:42:18 volumio go-librespot[18759]: time="2025-09-05T18:42:18+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 05 18:42:18 volumio go-librespot[18759]: time="2025-09-05T18:42:18+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 05 18:42:18 volumio go-librespot[18759]: time="2025-09-05T18:42:18+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 05 18:42:18 volumio go-librespot[18759]: time="2025-09-05T18:42:18+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 05 18:42:18 volumio go-librespot[18759]: time="2025-09-05T18:42:18+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 05 18:42:18 volumio go-librespot[18759]: time="2025-09-05T18:42:18+02:00" level=debug msg="received connection id: YzYzNTI0NTItMTQx...MTc4MDVCRjlCQQ==" Sep 05 18:42:18 volumio go-librespot[18759]: time="2025-09-05T18:42:18+02:00" level=trace msg="received accesspoint pong ack" Sep 05 18:42:18 volumio volumio[18588]: 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 18:42:18 volumio volumio[18588]: info: Spotify Successfully logged in Sep 05 18:42:18 volumio volumio[18588]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 18:42:18 volumio volumio[18588]: info: [1757090538442] CoreMusicLibrary::Adding element Spotify Sep 05 18:42:18 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 18:42:18 volumio volumio[18588]: Cannot find translation for source Jellyfin Sep 05 18:42:18 volumio volumio[18588]: Cannot find translation for source Spotify Sep 05 18:42:18 volumio sudo[18788]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 18:42:18 volumio sudo[18788]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:18 volumio sudo[18788]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:18 volumio go-librespot[18759]: time="2025-09-05T18:42:18+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 05 18:42:18 volumio volumio[18588]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 05 18:42:18 volumio volumio[18588]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 18:42:18 volumio volumio[18588]: info: VolumeController:: Volume=37 Mute =false Sep 05 18:42:18 volumio volumio[18588]: info: CoreCommandRouter::volumioGetState Sep 05 18:42:18 volumio volumio[18588]: info: CorePlayQueue::getTrack 0 Sep 05 18:42:18 volumio volumio[18588]: info: CoreStateMachine::pushState Sep 05 18:42:18 volumio volumio[18588]: info: CorePlayQueue::getTrack 0 Sep 05 18:42:18 volumio volumio[18588]: info: CoreCommandRouter::volumioPushState Sep 05 18:42:19 volumio volumio[18588]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 05 18:42:19 volumio volumio[18588]: 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 18:42:19 volumio volumio[18588]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 05 18:42:19 volumio volumio[18588]: info: camilladsp service started and running in background, instance 1 Sep 05 18:42:19 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:19 volumio volumio[18588]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 05 18:42:19 volumio volumio[18588]: 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 18:42:19 volumio volumio[18588]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 05 18:42:19 volumio volumio[18588]: info: FusionDsp loaded Sep 05 18:42:19 volumio volumio[18588]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 18:42:19 volumio sudo[18807]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 05 18:42:19 volumio sudo[18807]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:19 volumio sudo[18807]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:19 volumio volumio[18588]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 05 18:42:19 volumio volumio[18588]: info: Adding Signal Path Element [object Object] Sep 05 18:42:19 volumio volumio[18588]: info: Adding fusiondspeq DSP Signal Path Element Sep 05 18:42:19 volumio volumio[18588]: info: FusionDsp - ---- installed callbackRead Sep 05 18:42:19 volumio volumio[18588]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 18:42:19 volumio volumio[18588]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 05 18:42:19 volumio volumio[18588]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 05 18:42:19 volumio volumio[18588]: at onErrorNT (internal/child_process.js:465:16) Sep 05 18:42:19 volumio volumio[18588]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Sep 05 18:42:19 volumio volumio[18588]: errno: -2, Sep 05 18:42:19 volumio volumio[18588]: code: 'ENOENT', Sep 05 18:42:19 volumio volumio[18588]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 05 18:42:19 volumio volumio[18588]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 05 18:42:19 volumio volumio[18588]: spawnargs: [ Sep 05 18:42:19 volumio volumio[18588]: '-p', Sep 05 18:42:19 volumio volumio[18588]: 9876, Sep 05 18:42:19 volumio volumio[18588]: '-o', Sep 05 18:42:19 volumio volumio[18588]: '/tmp/camilladsp.log', Sep 05 18:42:19 volumio volumio[18588]: '-l', Sep 05 18:42:19 volumio volumio[18588]: 'warn', Sep 05 18:42:19 volumio volumio[18588]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 05 18:42:19 volumio volumio[18588]: ] Sep 05 18:42:19 volumio volumio[18588]: } Sep 05 18:42:19 volumio volumio[18588]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 18:42:19 volumio sudo[18818]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-05 18:41 Sep 05 18:42:19 volumio sudo[18818]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:20 volumio mpd[18794]: Sep 05 18:42 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 05 18:42:20 volumio systemd[1]: Started Music Player Daemon. Sep 05 18:42:20 volumio sudo[18695]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:20 volumio sudo[18708]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:20 volumio sudo[18782]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:20 volumio sudo[18818]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:20 volumio volumio-remote-updater[504]: [2025-09-05 18:42:20] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 05 18:42:20 volumio volumio-remote-updater[504]: [2025-09-05 18:42:20] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 05 18:42:20 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 05 18:42:20 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 05 18:42:20 volumio systemd[1]: Started dynamicswap service. Sep 05 18:42:20 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 05 18:42:20 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 05 18:42:20 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 19490. Sep 05 18:42:20 volumio systemd[1]: Stopped Volumio Backend Module. Sep 05 18:42:20 volumio systemd[1]: Started Volumio Backend Module. Sep 05 18:42:20 volumio systemd[1]: Started dynamicswap service. Sep 05 18:42:20 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 05 18:42:22 volumio volumio[18831]: info: ------------------------------------------- Sep 05 18:42:22 volumio volumio[18831]: info: ----- Volumio3 ---- Sep 05 18:42:22 volumio volumio[18831]: info: ------------------------------------------- Sep 05 18:42:22 volumio volumio[18831]: info: ----- System startup ---- Sep 05 18:42:22 volumio volumio[18831]: info: ------------------------------------------- Sep 05 18:42:22 volumio volumio[18831]: info: MYVOLUMIO Environment detected Sep 05 18:42:23 volumio volumio[18831]: info: Plugin folders cleanup Sep 05 18:42:23 volumio volumio[18831]: info: Scanning into folder /volumio/app/plugins/ Sep 05 18:42:23 volumio volumio[18831]: info: Scanning category audio_interface Sep 05 18:42:23 volumio volumio[18831]: info: Scanning category miscellanea Sep 05 18:42:23 volumio volumio[18831]: info: Scanning category music_service Sep 05 18:42:23 volumio volumio[18831]: info: Scanning category plugins.json Sep 05 18:42:23 volumio volumio[18831]: info: Scanning category system_controller Sep 05 18:42:23 volumio volumio[18831]: info: Scanning category user_interface Sep 05 18:42:23 volumio volumio[18831]: info: Scanning into folder /data/plugins/ Sep 05 18:42:23 volumio volumio[18831]: info: Scanning category audio_interface Sep 05 18:42:23 volumio volumio[18831]: info: Scanning category music_service Sep 05 18:42:23 volumio volumio[18831]: info: Scanning category user_interface Sep 05 18:42:23 volumio volumio[18831]: info: Plugin folders cleanup completed Sep 05 18:42:23 volumio volumio[18831]: info: ------------------------------------------- Sep 05 18:42:23 volumio volumio[18831]: info: ----- Core plugins startup ---- Sep 05 18:42:23 volumio volumio[18831]: info: ------------------------------------------- Sep 05 18:42:23 volumio volumio[18831]: info: Loading plugins from folder /volumio/app/plugins/ Sep 05 18:42:23 volumio volumio[18831]: info: Adding plugin upnp to MyMusic Plugins Sep 05 18:42:23 volumio volumio[18831]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 05 18:42:23 volumio volumio[18831]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 05 18:42:23 volumio volumio[18831]: info: Loading plugins from folder /data/plugins/ Sep 05 18:42:23 volumio volumio[18831]: info: Loading plugin "system"... Sep 05 18:42:23 volumio volumio[18831]: info: Loading plugin "appearance"... Sep 05 18:42:24 volumio volumio[18831]: info: Loading plugin "network"... Sep 05 18:42:24 volumio volumio[18831]: info: Refreshing Cached IP Addresses Sep 05 18:42:24 volumio sudo[18858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 05 18:42:24 volumio sudo[18858]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:24 volumio sudo[18858]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:24 volumio sudo[18860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 05 18:42:24 volumio volumio[18831]: info: Loading plugin "services"... Sep 05 18:42:24 volumio sudo[18860]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:24 volumio volumio[18831]: info: Loading plugin "alsa_controller"... Sep 05 18:42:24 volumio sudo[18860]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:24 volumio volumio[18831]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 18:42:24 volumio volumio[18831]: info: Loading plugin "wizard"... Sep 05 18:42:24 volumio volumio[18831]: info: Loading plugin "networkfs"... Sep 05 18:42:24 volumio volumio[18831]: info: Starting Udev Watcher for removable devices Sep 05 18:42:24 volumio volumio[18831]: info: Ignoring mount for partition: boot Sep 05 18:42:24 volumio volumio[18831]: info: Ignoring mount for partition: volumio Sep 05 18:42:24 volumio volumio[18831]: info: Ignoring mount for partition: volumio_data Sep 05 18:42:24 volumio volumio[18831]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 18:42:24 volumio volumio[18831]: info: Loading plugin "volumio_command_line_client"... Sep 05 18:42:24 volumio volumio[18831]: info: Loading plugin "upnp"... Sep 05 18:42:24 volumio volumio[18831]: info: [1757090544340] Starting Upmpd Daemon Sep 05 18:42:24 volumio volumio[18831]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 18:42:24 volumio volumio[18831]: info: Loading plugin "my_music"... Sep 05 18:42:24 volumio volumio[18831]: info: Loading plugin "mpd"... Sep 05 18:42:24 volumio volumio[18831]: info: Plugin upnp_browser is not enabled Sep 05 18:42:24 volumio volumio[18831]: info: Loading plugin "alarm-clock"... Sep 05 18:42:24 volumio volumio[18831]: info: Plugin airplay_emulation is not enabled Sep 05 18:42:24 volumio volumio[18831]: info: Loading plugin "last_100"... Sep 05 18:42:24 volumio volumio[18831]: info: Loading plugin "webradio"... Sep 05 18:42:24 volumio volumio[18831]: info: Loading plugin "i2s_dacs"... Sep 05 18:42:25 volumio volumio[18831]: info: Loading plugin "volumiodiscovery"... Sep 05 18:42:25 volumio volumio[18831]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 05 18:42:25 volumio volumio[18831]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 18:42:25 volumio volumio[18831]: *** WARNING *** For more information see Sep 05 18:42:25 volumio node[18831]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 05 18:42:25 volumio volumio[18831]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 05 18:42:25 volumio volumio[18831]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 18:42:25 volumio volumio[18831]: *** WARNING *** For more information see Sep 05 18:42:25 volumio node[18831]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 18:42:25 volumio node[18831]: *** WARNING *** For more information see Sep 05 18:42:25 volumio node[18831]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 05 18:42:25 volumio node[18831]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 18:42:25 volumio node[18831]: *** WARNING *** For more information see Sep 05 18:42:25 volumio volumio[18831]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 05 18:42:25 volumio volumio[18831]: info: Discovery: Started advertising with name: Volumio Sep 05 18:42:25 volumio volumio[18831]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 18:42:25 volumio volumio[18831]: info: Loading plugin "jellyfin"... Sep 05 18:42:25 volumio volumio-remote-updater[504]: [2025-09-05 18:42:25] [connect] Successful connection Sep 05 18:42:25 volumio volumio[18831]: info: Loading plugin "spop"... Sep 05 18:42:26 volumio volumio[18831]: info: Loading plugin "ytcr"... Sep 05 18:42:31 volumio volumio[18831]: info: Loading plugin "outputs"... Sep 05 18:42:31 volumio volumio[18831]: info: Loading plugin "albumart"... Sep 05 18:42:31 volumio volumio[18831]: info: Plugin example_plugin is not enabled Sep 05 18:42:31 volumio volumio[18831]: info: Loading plugin "inputs"... Sep 05 18:42:31 volumio volumio[18831]: info: Loading plugin "updater_comm"... Sep 05 18:42:31 volumio volumio[18831]: info: Plugin mpdemulation is not enabled Sep 05 18:42:31 volumio volumio[18831]: info: Loading plugin "rest_api"... Sep 05 18:42:31 volumio volumio[18831]: info: Loading plugin "websocket"... Sep 05 18:42:31 volumio volumio[18831]: info: Starting Socket.io Server version 2.3.0 Sep 05 18:42:31 volumio volumio[18831]: info: Loading plugin "fusiondsp"... Sep 05 18:42:31 volumio volumio[18831]: Forking 3 albumart workers Sep 05 18:42:31 volumio volumio[18831]: info: Applying required configuration parameters for plugin fusiondsp Sep 05 18:42:31 volumio volumio[18831]: info: Loading plugin "Systeminfo"... Sep 05 18:42:32 volumio volumio[18831]: info: Loading i18n strings for locale hu Sep 05 18:42:32 volumio volumio[18831]: Updating browse sources language Sep 05 18:42:32 volumio volumio[18831]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 18:42:32 volumio volumio[18831]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 18:42:32 volumio volumio[18831]: info: CoreCommandRouter::initPlayerControls Sep 05 18:42:32 volumio volumio[18831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:32 volumio volumio[18831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:32 volumio volumio[18831]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 18:42:32 volumio volumio[18831]: Express server listening on port 3000 Sep 05 18:42:32 volumio volumio[18831]: [Metrics] WebUI: 11s 10.91ms Sep 05 18:42:32 volumio volumio[18831]: info: CoreStateMachine::resetVolumioState Sep 05 18:42:32 volumio volumio[18831]: info: CoreStateMachine::getcurrentVolume Sep 05 18:42:32 volumio volumio[18831]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 18:42:32 volumio volumio[18831]: Starting albumart workers Sep 05 18:42:32 volumio volumio[18831]: Starting albumart workers Sep 05 18:42:32 volumio volumio[18831]: info: Volumio Network Manager: Network status updated: 1 Sep 05 18:42:32 volumio volumio[18831]: Starting albumart workers Sep 05 18:42:33 volumio volumio[18831]: info: CoreCommandRouter::volumioGetState Sep 05 18:42:33 volumio volumio[18831]: info: CorePlayQueue::getTrack 0 Sep 05 18:42:33 volumio volumio[18831]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 18:42:33 volumio volumio[18831]: info: VolumeController:: Volume=37 Mute =false Sep 05 18:42:33 volumio volumio[18831]: info: CoreStateMachine::pushState Sep 05 18:42:33 volumio volumio[18831]: info: CorePlayQueue::getTrack 0 Sep 05 18:42:33 volumio volumio[18831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 18:42:33 volumio volumio[18831]: info: CoreCommandRouter::volumioPushState Sep 05 18:42:33 volumio volumio[18831]: info: CoreStateMachine::updateTrackBlock Sep 05 18:42:33 volumio volumio[18831]: info: CorePlayQueue::getTrackBlock Sep 05 18:42:33 volumio volumio[18831]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 18:42:33 volumio volumio[18831]: info: CoreStateMachine::setRepeat false single undefined Sep 05 18:42:33 volumio volumio[18831]: info: CoreStateMachine::pushState Sep 05 18:42:33 volumio volumio[18831]: info: CorePlayQueue::getTrack 0 Sep 05 18:42:33 volumio volumio[18831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 18:42:33 volumio volumio[18831]: info: CoreCommandRouter::volumioPushState Sep 05 18:42:33 volumio volumio[18831]: info: CoreStateMachine::setRandom true Sep 05 18:42:33 volumio volumio[18831]: info: CoreStateMachine::pushState Sep 05 18:42:33 volumio volumio[18831]: info: CorePlayQueue::getTrack 0 Sep 05 18:42:33 volumio volumio[18831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 18:42:33 volumio volumio[18831]: info: CoreCommandRouter::volumioPushState Sep 05 18:42:33 volumio volumio[18831]: info: Setting Device type: Raspberry PI Sep 05 18:42:33 volumio volumio[18831]: info: Listing playlists Sep 05 18:42:33 volumio volumio[18831]: info: Listing playlists Sep 05 18:42:33 volumio volumio[18831]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227 Sep 05 18:42:33 volumio volumio[18831]: info: Discovery: Found device Volumio Sep 05 18:42:33 volumio volumio[18831]: info: CoreCommandRouter::volumioGetState Sep 05 18:42:33 volumio volumio[18831]: info: CorePlayQueue::getTrack 0 Sep 05 18:42:33 volumio volumio[18831]: info: Completed loading Core Plugins Sep 05 18:42:33 volumio volumio[18831]: info: Preparing to generate the ALSA configuration file Sep 05 18:42:33 volumio volumio[18831]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 05 18:42:33 volumio volumio[18831]: info: Reading ALSA contributions from plugins. Sep 05 18:42:33 volumio volumio[18831]: info: VolumeController:: Volume=37 Mute =false Sep 05 18:42:33 volumio volumio[18831]: info: CoreStateMachine::pushState Sep 05 18:42:33 volumio volumio[18831]: info: CorePlayQueue::getTrack 0 Sep 05 18:42:33 volumio volumio[18831]: info: CoreCommandRouter::volumioPushState Sep 05 18:42:33 volumio volumio[18831]: info: Asound.conf file unchanged, so no further update is needed Sep 05 18:42:33 volumio volumio[18831]: info: Output device has changed, restarting MPD Sep 05 18:42:33 volumio volumio[18831]: info: ___________ START PLUGINS ___________ Sep 05 18:42:33 volumio sudo[18943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 18:42:33 volumio volumio[18831]: info: ControllerMpd::onStart: Initializing MPD Sep 05 18:42:33 volumio volumio[18831]: info: Creating MPD Configuration file Sep 05 18:42:33 volumio sudo[18943]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:33 volumio sudo[18943]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:33 volumio sudo[18945]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 18:42:33 volumio sudo[18945]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:33 volumio volumio[18831]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 18:42:33 volumio volumio[18831]: info: [1757090553615] CoreMusicLibrary::Adding element Last_100 Sep 05 18:42:33 volumio volumio[18831]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 18:42:33 volumio volumio[18831]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 18:42:33 volumio volumio[18831]: info: [1757090553622] CoreMusicLibrary::Adding element Webradio Sep 05 18:42:33 volumio volumio[18831]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 18:42:33 volumio systemd[1]: Stopping Music Player Daemon... Sep 05 18:42:33 volumio sudo[18948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 18:42:33 volumio volumio[18831]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 18:42:33 volumio sudo[18948]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:33 volumio volumio[18831]: info: Initializing BBC Radios Sep 05 18:42:33 volumio sudo[18948]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:33 volumio sudo[18955]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 18:42:33 volumio sudo[18955]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:33 volumio systemd[1]: mpd.service: Succeeded. Sep 05 18:42:33 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 18:42:33 volumio systemd[1]: Starting Music Player Daemon... Sep 05 18:42:33 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 05 18:42:33 volumio systemd[1]: mpd.service: Succeeded. Sep 05 18:42:33 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 18:42:33 volumio volumio[18831]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 18:42:33 volumio volumio[18831]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 18:42:33 volumio systemd[1]: Starting Music Player Daemon... Sep 05 18:42:33 volumio volumio[18831]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 18:42:33 volumio volumio[18831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 05 18:42:33 volumio volumio[18831]: info: Discovery: Getting this device information Sep 05 18:42:33 volumio volumio[18831]: info: CoreCommandRouter::volumioGetState Sep 05 18:42:33 volumio volumio[18831]: info: CorePlayQueue::getTrack 0 Sep 05 18:42:33 volumio volumio[18831]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 18:42:33 volumio volumio[18831]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 18:42:33 volumio volumio[18831]: info: [1757090553788] CoreMusicLibrary::Adding element Jellyfin Sep 05 18:42:33 volumio volumio[18831]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 18:42:33 volumio volumio[18831]: Cannot find translation for source Jellyfin Sep 05 18:42:33 volumio volumio[18831]: 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 18:42:33 volumio volumio[18831]: info: Creating Spotify config file Sep 05 18:42:33 volumio volumio[18831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:33 volumio sudo[18973]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 18:42:33 volumio sudo[18973]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:33 volumio sudo[18973]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:33 volumio volumio[18831]: info: Loading i18n strings for locale hu Sep 05 18:42:33 volumio volumio[18831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 05 18:42:33 volumio volumio[18831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:33 volumio volumio[18831]: info: FusionDsp - mixtype--------------------- Hardware Sep 05 18:42:33 volumio volumio[18831]: info: Volumio Calling Home Sep 05 18:42:34 volumio volumio[18831]: info: Preparing to generate the ALSA configuration file Sep 05 18:42:34 volumio volumio[18831]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 05 18:42:34 volumio volumio[18831]: info: Reading ALSA contributions from plugins. Sep 05 18:42:34 volumio volumio[18831]: info: MPD Permissions set Sep 05 18:42:34 volumio volumio[18831]: info: MPD Permissions set Sep 05 18:42:34 volumio volumio[18831]: info: CoreCommandRouter::volumioGetState Sep 05 18:42:34 volumio volumio[18831]: info: CorePlayQueue::getTrack 0 Sep 05 18:42:34 volumio volumio[18831]: info: Volumio called home Sep 05 18:42:34 volumio sudo[18994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 05 18:42:34 volumio sudo[18994]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:34 volumio sudo[18994]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:34 volumio volumio[18831]: info: Spotify config file written Sep 05 18:42:34 volumio sudo[18997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 05 18:42:34 volumio sudo[18997]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:34 volumio sudo[18997]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:34 volumio sudo[19000]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 05 18:42:34 volumio sudo[19000]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:34 volumio systemd[1]: Stopping go-librespot Daemon... Sep 05 18:42:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 05 18:42:34 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 05 18:42:34 volumio systemd[1]: Stopped go-librespot Daemon. Sep 05 18:42:34 volumio systemd[1]: Started go-librespot Daemon. Sep 05 18:42:34 volumio go-librespot[19002]: go-librespot daemon starting... Sep 05 18:42:34 volumio sudo[19000]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:34 volumio go-librespot[19002]: time="2025-09-05T18:42:34+02:00" level=info msg="running go-librespot 0.3.2" Sep 05 18:42:34 volumio go-librespot[19002]: time="2025-09-05T18:42:34+02:00" level=debug msg="app state loaded" Sep 05 18:42:34 volumio go-librespot[19002]: time="2025-09-05T18:42:34+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 05 18:42:34 volumio volumio[18831]: 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 18:42:34 volumio volumio[18831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:34 volumio volumio[18831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:34 volumio volumio[18831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:34 volumio volumio[18831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:34 volumio volumio[18831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:34 volumio volumio[18831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:34 volumio volumio[18831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:34 volumio volumio[18831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:34 volumio volumio[18831]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 18:42:34 volumio volumio[18831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:34 volumio volumio[18831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:34 volumio volumio[18831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:34 volumio volumio[18831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:34 volumio volumio[18831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:34 volumio volumio[18831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:34 volumio volumio[18831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:34 volumio volumio[18831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:34 volumio volumio[18831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:34 volumio volumio[18831]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 18:42:34 volumio volumio[18831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:34 volumio volumio[18831]: info: No need to fix Spotify hosts Sep 05 18:42:34 volumio go-librespot[19002]: time="2025-09-05T18:42:34+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 18:42:34 volumio go-librespot[19002]: time="2025-09-05T18:42:34+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 18:42:34 volumio go-librespot[19002]: time="2025-09-05T18:42:34+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 18:42:34 volumio go-librespot[19002]: time="2025-09-05T18:42:34+02:00" level=info msg="zeroconf server listening on port 37907" Sep 05 18:42:34 volumio go-librespot[19002]: time="2025-09-05T18:42:34+02:00" level=debug msg="obtained new client token: AABB7UO6BqWaZDICq1n8nkx1JS6FSU76GrVNIWTZqREEgvaQr4oA2snH/nxYCJpjXLN4Y3rzE+zoL2OpUoAUjJJ9hFvpLpXwdCAYqXXHyyoHCwsqFNt4wIdKtdBxaY5tH37l/JGp9tOne8wICK3CUMxpD5y0aVVWpZ0JMrdUB4fb6WnAu9NGgJdt4kyXNaCET+aRozvpjkrczF2xhNGfp7vhrkAtas00Hm+CIkPZWVlQczkEv/HjuPv/YhE=" Sep 05 18:42:34 volumio volumio[18831]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 05 18:42:34 volumio volumio[18831]: SPOTIFY: BQBlnGA83l8ejYQ-ALWLg0v1Tds2uZxFjfbdt3AeRSbFbyzSKLb1ClRqnNTDQeRZe2OliEK2D7_XGqrfV4Av-XpYd2oQk9OlMvTsmwVQujEMKSSJNlYBvxuHO1KQI5P46T_wGIBbac2CcrNh9IQxlEirUhHnNEntK48B5emPBCD58dj84YjqV5p01IS4jY__Np8DITiO9q3IMnCqPcfrA7h4ZuMHEw_PjuDvWdBzbyGQNiFETjLSuU_mLh3FyLD1owrXunkpIQqQNuwvdzKO1RDVo4WyeoF4odExcjPJG-uv0LE Sep 05 18:42:34 volumio volumio[18831]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 05 18:42:34 volumio volumio[18831]: info: New Spotify access token = BQBlnGA83l8ejYQ-ALWLg0v1Tds2uZxFjfbdt3AeRSbFbyzSKLb1ClRqnNTDQeRZe2OliEK2D7_XGqrfV4Av-XpYd2oQk9OlMvTsmwVQujEMKSSJNlYBvxuHO1KQI5P46T_wGIBbac2CcrNh9IQxlEirUhHnNEntK48B5emPBCD58dj84YjqV5p01IS4jY__Np8DITiO9q3IMnCqPcfrA7h4ZuMHEw_PjuDvWdBzbyGQNiFETjLSuU_mLh3FyLD1owrXunkpIQqQNuwvdzKO1RDVo4WyeoF4odExcjPJG-uv0LE Sep 05 18:42:34 volumio volumio[18831]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 05 18:42:35 volumio go-librespot[19002]: time="2025-09-05T18:42:35+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 05 18:42:35 volumio volumio[18831]: info: CoreCommandRouter::volumioGetState Sep 05 18:42:35 volumio volumio[18831]: info: CorePlayQueue::getTrack 0 Sep 05 18:42:35 volumio go-librespot[19002]: time="2025-09-05T18:42:35+02:00" level=debug msg="completed keyexchange" Sep 05 18:42:35 volumio go-librespot[19002]: time="2025-09-05T18:42:35+02:00" level=debug msg="completed challenge" Sep 05 18:42:35 volumio volumio[18831]: info: Asound.conf file unchanged, so no further update is needed Sep 05 18:42:35 volumio volumio[18831]: info: Output device has changed, restarting MPD Sep 05 18:42:35 volumio go-librespot[19002]: time="2025-09-05T18:42:35+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 05 18:42:35 volumio sudo[19019]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 18:42:35 volumio sudo[19019]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:35 volumio sudo[19021]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 18:42:35 volumio sudo[19021]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:35 volumio sudo[19019]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:35 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 05 18:42:35 volumio systemd[1]: mpd.service: Succeeded. Sep 05 18:42:35 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 18:42:35 volumio sudo[19025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 05 18:42:35 volumio go-librespot[19002]: time="2025-09-05T18:42:35+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 05 18:42:35 volumio go-librespot[19002]: time="2025-09-05T18:42:35+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 05 18:42:35 volumio volumio[18831]: info: MPD Permissions set Sep 05 18:42:35 volumio volumio[18831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:35 volumio sudo[19025]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:35 volumio systemd[1]: Starting Music Player Daemon... Sep 05 18:42:35 volumio go-librespot[19002]: time="2025-09-05T18:42:35+02:00" level=debug msg="dealer connection opened" Sep 05 18:42:35 volumio go-librespot[19002]: time="2025-09-05T18:42:35+02:00" level=trace msg="starting accesspoint recv loop" Sep 05 18:42:35 volumio go-librespot[19002]: time="2025-09-05T18:42:35+02:00" level=trace msg="starting dealer recv loop" Sep 05 18:42:35 volumio go-librespot[19002]: time="2025-09-05T18:42:35+02:00" level=trace msg="received accesspoint ping" Sep 05 18:42:35 volumio go-librespot[19002]: time="2025-09-05T18:42:35+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 05 18:42:35 volumio go-librespot[19002]: time="2025-09-05T18:42:35+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 05 18:42:35 volumio volumio[18831]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 18:42:35 volumio volumio[18831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:35 volumio go-librespot[19002]: time="2025-09-05T18:42:35+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 05 18:42:35 volumio go-librespot[19002]: time="2025-09-05T18:42:35+02:00" level=debug msg="received connection id: NTM5NWEzM2UtM2Ri...QTg0QTUxOUIyRQ==" Sep 05 18:42:35 volumio go-librespot[19002]: time="2025-09-05T18:42:35+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 05 18:42:35 volumio go-librespot[19002]: time="2025-09-05T18:42:35+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 05 18:42:35 volumio sudo[19025]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:35 volumio go-librespot[19002]: time="2025-09-05T18:42:35+02:00" level=trace msg="received accesspoint pong ack" Sep 05 18:42:35 volumio volumio[18831]: info: Upmpdcli Daemon Started Sep 05 18:42:35 volumio volumio[18831]: 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 18:42:35 volumio volumio[18831]: info: Spotify Successfully logged in Sep 05 18:42:35 volumio volumio[18831]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 18:42:35 volumio volumio[18831]: info: [1757090555369] CoreMusicLibrary::Adding element Spotify Sep 05 18:42:35 volumio volumio[18831]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 18:42:35 volumio volumio[18831]: Cannot find translation for source Jellyfin Sep 05 18:42:35 volumio volumio[18831]: Cannot find translation for source Spotify Sep 05 18:42:35 volumio go-librespot[19002]: time="2025-09-05T18:42:35+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 05 18:42:35 volumio sudo[19026]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 18:42:35 volumio sudo[19026]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:35 volumio sudo[19026]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:35 volumio volumio[18831]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 05 18:42:35 volumio volumio[18831]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 18:42:35 volumio volumio[18831]: Error: bind EADDRINUSE 192.168.0.171:50386 Sep 05 18:42:35 volumio volumio[18831]: at dgram.js:337:20 Sep 05 18:42:35 volumio volumio[18831]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Sep 05 18:42:35 volumio volumio[18831]: errno: -98, Sep 05 18:42:35 volumio volumio[18831]: code: 'EADDRINUSE', Sep 05 18:42:35 volumio volumio[18831]: syscall: 'bind', Sep 05 18:42:35 volumio volumio[18831]: address: '192.168.0.171', Sep 05 18:42:35 volumio volumio[18831]: port: 50386 Sep 05 18:42:35 volumio volumio[18831]: } Sep 05 18:42:35 volumio volumio[18831]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 18:42:36 volumio sudo[19045]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-05 18:41 Sep 05 18:42:36 volumio sudo[19045]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:36 volumio sudo[19045]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:37 volumio mpd[19035]: Sep 05 18:42 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 05 18:42:37 volumio systemd[1]: Started Music Player Daemon. Sep 05 18:42:37 volumio sudo[18955]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:37 volumio sudo[19021]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:37 volumio sudo[18945]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:37 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 05 18:42:37 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 05 18:42:37 volumio systemd[1]: Started dynamicswap service. Sep 05 18:42:37 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 05 18:42:37 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 05 18:42:37 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 19491. Sep 05 18:42:37 volumio systemd[1]: Started dynamicswap service. Sep 05 18:42:37 volumio systemd[1]: Stopped Volumio Backend Module. Sep 05 18:42:37 volumio systemd[1]: Started Volumio Backend Module. Sep 05 18:42:37 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 05 18:42:37 volumio volumio-remote-updater[504]: [2025-09-05 18:42:37] [info] asio async_connect error: system:111 (Connection refused) Sep 05 18:42:37 volumio volumio-remote-updater[504]: [2025-09-05 18:42:37] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 05 18:42:37 volumio volumio-remote-updater[504]: [2025-09-05 18:42:37] [error] handle_connect error: Underlying Transport Error Sep 05 18:42:39 volumio volumio[19061]: info: ------------------------------------------- Sep 05 18:42:39 volumio volumio[19061]: info: ----- Volumio3 ---- Sep 05 18:42:39 volumio volumio[19061]: info: ------------------------------------------- Sep 05 18:42:39 volumio volumio[19061]: info: ----- System startup ---- Sep 05 18:42:39 volumio volumio[19061]: info: ------------------------------------------- Sep 05 18:42:39 volumio volumio[19061]: info: MYVOLUMIO Environment detected Sep 05 18:42:39 volumio volumio[19061]: info: Plugin folders cleanup Sep 05 18:42:39 volumio volumio[19061]: info: Scanning into folder /volumio/app/plugins/ Sep 05 18:42:39 volumio volumio[19061]: info: Scanning category audio_interface Sep 05 18:42:39 volumio volumio[19061]: info: Scanning category miscellanea Sep 05 18:42:40 volumio volumio[19061]: info: Scanning category music_service Sep 05 18:42:40 volumio volumio[19061]: info: Scanning category plugins.json Sep 05 18:42:40 volumio volumio[19061]: info: Scanning category system_controller Sep 05 18:42:40 volumio volumio[19061]: info: Scanning category user_interface Sep 05 18:42:40 volumio volumio[19061]: info: Scanning into folder /data/plugins/ Sep 05 18:42:40 volumio volumio[19061]: info: Scanning category audio_interface Sep 05 18:42:40 volumio volumio[19061]: info: Scanning category music_service Sep 05 18:42:40 volumio volumio[19061]: info: Scanning category user_interface Sep 05 18:42:40 volumio volumio[19061]: info: Plugin folders cleanup completed Sep 05 18:42:40 volumio volumio[19061]: info: ------------------------------------------- Sep 05 18:42:40 volumio volumio[19061]: info: ----- Core plugins startup ---- Sep 05 18:42:40 volumio volumio[19061]: info: ------------------------------------------- Sep 05 18:42:40 volumio volumio[19061]: info: Loading plugins from folder /volumio/app/plugins/ Sep 05 18:42:40 volumio volumio[19061]: info: Adding plugin upnp to MyMusic Plugins Sep 05 18:42:40 volumio volumio[19061]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 05 18:42:40 volumio volumio[19061]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 05 18:42:40 volumio volumio[19061]: info: Loading plugins from folder /data/plugins/ Sep 05 18:42:40 volumio volumio[19061]: info: Loading plugin "system"... Sep 05 18:42:40 volumio volumio[19061]: info: Loading plugin "appearance"... Sep 05 18:42:41 volumio volumio[19061]: info: Loading plugin "network"... Sep 05 18:42:41 volumio volumio[19061]: info: Refreshing Cached IP Addresses Sep 05 18:42:41 volumio sudo[19090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 05 18:42:41 volumio sudo[19090]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:41 volumio sudo[19090]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:41 volumio sudo[19092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 05 18:42:41 volumio volumio[19061]: info: Loading plugin "services"... Sep 05 18:42:41 volumio sudo[19092]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:41 volumio volumio[19061]: info: Loading plugin "alsa_controller"... Sep 05 18:42:41 volumio sudo[19092]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:41 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 18:42:41 volumio volumio[19061]: info: Loading plugin "wizard"... Sep 05 18:42:41 volumio volumio[19061]: info: Loading plugin "networkfs"... Sep 05 18:42:41 volumio volumio[19061]: info: Starting Udev Watcher for removable devices Sep 05 18:42:41 volumio volumio[19061]: info: Ignoring mount for partition: boot Sep 05 18:42:41 volumio volumio[19061]: info: Ignoring mount for partition: volumio Sep 05 18:42:41 volumio volumio[19061]: info: Ignoring mount for partition: volumio_data Sep 05 18:42:41 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 18:42:41 volumio volumio[19061]: info: Loading plugin "volumio_command_line_client"... Sep 05 18:42:41 volumio volumio[19061]: info: Loading plugin "upnp"... Sep 05 18:42:41 volumio volumio[19061]: info: [1757090561329] Starting Upmpd Daemon Sep 05 18:42:41 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 18:42:41 volumio volumio[19061]: info: Loading plugin "my_music"... Sep 05 18:42:41 volumio volumio[19061]: info: Loading plugin "mpd"... Sep 05 18:42:41 volumio volumio[19061]: info: Plugin upnp_browser is not enabled Sep 05 18:42:41 volumio volumio[19061]: info: Loading plugin "alarm-clock"... Sep 05 18:42:41 volumio volumio[19061]: info: Plugin airplay_emulation is not enabled Sep 05 18:42:41 volumio volumio[19061]: info: Loading plugin "last_100"... Sep 05 18:42:41 volumio volumio[19061]: info: Loading plugin "webradio"... Sep 05 18:42:41 volumio volumio[19061]: info: Loading plugin "i2s_dacs"... Sep 05 18:42:41 volumio volumio[19061]: info: Loading plugin "volumiodiscovery"... Sep 05 18:42:42 volumio volumio[19061]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 05 18:42:42 volumio volumio[19061]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 18:42:42 volumio volumio[19061]: *** WARNING *** For more information see Sep 05 18:42:42 volumio node[19061]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 05 18:42:42 volumio volumio[19061]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 05 18:42:42 volumio volumio[19061]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 18:42:42 volumio volumio[19061]: *** WARNING *** For more information see Sep 05 18:42:42 volumio node[19061]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 18:42:42 volumio node[19061]: *** WARNING *** For more information see Sep 05 18:42:42 volumio node[19061]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 05 18:42:42 volumio node[19061]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 18:42:42 volumio node[19061]: *** WARNING *** For more information see Sep 05 18:42:42 volumio volumio[19061]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 05 18:42:42 volumio volumio[19061]: info: Discovery: Started advertising with name: Volumio Sep 05 18:42:42 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 18:42:42 volumio volumio[19061]: info: Loading plugin "jellyfin"... Sep 05 18:42:42 volumio volumio[19061]: info: Loading plugin "spop"... Sep 05 18:42:44 volumio volumio[19061]: info: Loading plugin "ytcr"... Sep 05 18:42:47 volumio volumio-remote-updater[504]: [2025-09-05 18:42:47] [connect] Successful connection Sep 05 18:42:48 volumio volumio[19061]: info: Loading plugin "outputs"... Sep 05 18:42:48 volumio volumio[19061]: info: Loading plugin "albumart"... Sep 05 18:42:48 volumio volumio[19061]: info: Plugin example_plugin is not enabled Sep 05 18:42:48 volumio volumio[19061]: info: Loading plugin "inputs"... Sep 05 18:42:48 volumio volumio[19061]: info: Loading plugin "updater_comm"... Sep 05 18:42:48 volumio volumio[19061]: info: Plugin mpdemulation is not enabled Sep 05 18:42:48 volumio volumio[19061]: info: Loading plugin "rest_api"... Sep 05 18:42:48 volumio volumio[19061]: info: Loading plugin "websocket"... Sep 05 18:42:48 volumio volumio[19061]: info: Starting Socket.io Server version 2.3.0 Sep 05 18:42:48 volumio volumio[19061]: info: Loading plugin "fusiondsp"... Sep 05 18:42:48 volumio volumio[19061]: Forking 3 albumart workers Sep 05 18:42:48 volumio volumio[19061]: info: Applying required configuration parameters for plugin fusiondsp Sep 05 18:42:48 volumio volumio[19061]: info: Loading plugin "Systeminfo"... Sep 05 18:42:49 volumio volumio[19061]: info: Loading i18n strings for locale hu Sep 05 18:42:49 volumio volumio[19061]: Updating browse sources language Sep 05 18:42:49 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 18:42:49 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 18:42:49 volumio volumio[19061]: info: CoreCommandRouter::initPlayerControls Sep 05 18:42:49 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:49 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:49 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:49 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:49 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:49 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:49 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:49 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:49 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 18:42:49 volumio volumio[19061]: Express server listening on port 3000 Sep 05 18:42:49 volumio volumio[19061]: [Metrics] WebUI: 10s 945.97ms Sep 05 18:42:49 volumio volumio[19061]: info: CoreStateMachine::resetVolumioState Sep 05 18:42:49 volumio volumio[19061]: info: CoreStateMachine::getcurrentVolume Sep 05 18:42:49 volumio volumio[19061]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 18:42:49 volumio volumio[19061]: Starting albumart workers Sep 05 18:42:49 volumio volumio[19061]: info: Volumio Network Manager: Network status updated: 1 Sep 05 18:42:49 volumio volumio[19061]: Starting albumart workers Sep 05 18:42:49 volumio volumio[19061]: Starting albumart workers Sep 05 18:42:49 volumio volumio-remote-updater[504]: [2025-09-05 18:42:49] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1757090567 101 Sep 05 18:42:49 volumio volumio[19061]: 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 18:42:49 volumio volumio[19061]: info: CoreCommandRouter::volumioGetState Sep 05 18:42:49 volumio volumio[19061]: info: CorePlayQueue::getTrack 0 Sep 05 18:42:50 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 18:42:50 volumio volumio[19061]: info: VolumeController:: Volume=37 Mute =false Sep 05 18:42:50 volumio volumio[19061]: info: CoreStateMachine::pushState Sep 05 18:42:50 volumio volumio[19061]: info: CorePlayQueue::getTrack 0 Sep 05 18:42:50 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 18:42:50 volumio volumio[19061]: info: CoreCommandRouter::volumioPushState Sep 05 18:42:50 volumio volumio[19061]: info: CoreStateMachine::updateTrackBlock Sep 05 18:42:50 volumio volumio[19061]: info: CorePlayQueue::getTrackBlock Sep 05 18:42:50 volumio volumio[19061]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 18:42:50 volumio volumio[19061]: info: CoreStateMachine::setRepeat false single undefined Sep 05 18:42:50 volumio volumio[19061]: info: CoreStateMachine::pushState Sep 05 18:42:50 volumio volumio[19061]: info: CorePlayQueue::getTrack 0 Sep 05 18:42:50 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 18:42:50 volumio volumio[19061]: info: CoreCommandRouter::volumioPushState Sep 05 18:42:50 volumio volumio[19061]: info: CoreStateMachine::setRandom true Sep 05 18:42:50 volumio volumio[19061]: info: CoreStateMachine::pushState Sep 05 18:42:50 volumio volumio[19061]: info: CorePlayQueue::getTrack 0 Sep 05 18:42:50 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 18:42:50 volumio volumio[19061]: info: CoreCommandRouter::volumioPushState Sep 05 18:42:50 volumio volumio[19061]: info: Setting Device type: Raspberry PI Sep 05 18:42:50 volumio volumio[19061]: info: Listing playlists Sep 05 18:42:50 volumio volumio[19061]: info: Listing playlists Sep 05 18:42:50 volumio volumio[19061]: info: Completed loading Core Plugins Sep 05 18:42:50 volumio volumio[19061]: info: Preparing to generate the ALSA configuration file Sep 05 18:42:50 volumio volumio[19061]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227 Sep 05 18:42:50 volumio volumio[19061]: info: Discovery: Found device Volumio Sep 05 18:42:50 volumio volumio[19061]: info: CoreCommandRouter::volumioGetState Sep 05 18:42:50 volumio volumio[19061]: info: CorePlayQueue::getTrack 0 Sep 05 18:42:50 volumio volumio[19061]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 05 18:42:50 volumio volumio[19061]: info: Reading ALSA contributions from plugins. Sep 05 18:42:50 volumio volumio[19061]: info: VolumeController:: Volume=37 Mute =false Sep 05 18:42:50 volumio volumio[19061]: info: CoreStateMachine::pushState Sep 05 18:42:50 volumio volumio[19061]: info: CorePlayQueue::getTrack 0 Sep 05 18:42:50 volumio volumio[19061]: info: CoreCommandRouter::volumioPushState Sep 05 18:42:50 volumio volumio[19061]: info: Asound.conf file unchanged, so no further update is needed Sep 05 18:42:50 volumio volumio[19061]: info: Output device has changed, restarting MPD Sep 05 18:42:50 volumio sudo[19183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 18:42:50 volumio sudo[19183]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:50 volumio sudo[19183]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:50 volumio volumio[19061]: info: ___________ START PLUGINS ___________ Sep 05 18:42:50 volumio sudo[19186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 18:42:50 volumio sudo[19186]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:50 volumio volumio[19061]: info: ControllerMpd::onStart: Initializing MPD Sep 05 18:42:50 volumio volumio[19061]: info: Creating MPD Configuration file Sep 05 18:42:50 volumio systemd[1]: Stopping Music Player Daemon... Sep 05 18:42:50 volumio sudo[19189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 18:42:50 volumio sudo[19189]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:50 volumio systemd[1]: mpd.service: Succeeded. Sep 05 18:42:50 volumio sudo[19189]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:50 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 18:42:50 volumio volumio[19061]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 18:42:50 volumio volumio[19061]: info: [1757090570641] CoreMusicLibrary::Adding element Last_100 Sep 05 18:42:50 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 18:42:50 volumio volumio[19061]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 18:42:50 volumio volumio[19061]: info: [1757090570660] CoreMusicLibrary::Adding element Webradio Sep 05 18:42:50 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 18:42:50 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 18:42:50 volumio sudo[19192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 18:42:50 volumio sudo[19192]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:50 volumio volumio[19061]: info: Initializing BBC Radios Sep 05 18:42:50 volumio systemd[1]: Starting Music Player Daemon... Sep 05 18:42:50 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 05 18:42:50 volumio systemd[1]: mpd.service: Succeeded. Sep 05 18:42:50 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 18:42:50 volumio systemd[1]: Starting Music Player Daemon... Sep 05 18:42:50 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 18:42:50 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 18:42:50 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 18:42:50 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 05 18:42:50 volumio volumio[19061]: info: Discovery: Getting this device information Sep 05 18:42:50 volumio volumio[19061]: info: CoreCommandRouter::volumioGetState Sep 05 18:42:50 volumio volumio[19061]: info: CorePlayQueue::getTrack 0 Sep 05 18:42:50 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 18:42:50 volumio volumio[19061]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 18:42:50 volumio volumio[19061]: info: [1757090570785] CoreMusicLibrary::Adding element Jellyfin Sep 05 18:42:50 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 18:42:50 volumio volumio[19061]: Cannot find translation for source Jellyfin Sep 05 18:42:50 volumio volumio[19061]: 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 18:42:50 volumio volumio[19061]: info: Creating Spotify config file Sep 05 18:42:50 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:50 volumio sudo[19202]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 18:42:50 volumio sudo[19202]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:50 volumio sudo[19202]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:50 volumio volumio[19061]: info: Loading i18n strings for locale hu Sep 05 18:42:50 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 05 18:42:50 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:50 volumio volumio[19061]: info: FusionDsp - mixtype--------------------- Hardware Sep 05 18:42:50 volumio volumio[19061]: info: Volumio Calling Home Sep 05 18:42:51 volumio volumio[19061]: info: Preparing to generate the ALSA configuration file Sep 05 18:42:51 volumio volumio[19061]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 05 18:42:51 volumio volumio[19061]: info: Reading ALSA contributions from plugins. Sep 05 18:42:51 volumio volumio[19061]: info: MPD Permissions set Sep 05 18:42:51 volumio volumio[19061]: info: Spotify config file written Sep 05 18:42:51 volumio sudo[19226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 05 18:42:51 volumio sudo[19226]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:51 volumio volumio[19061]: 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 18:42:51 volumio volumio[19061]: info: Volumio called home Sep 05 18:42:51 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:51 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:51 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:51 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:51 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:51 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:51 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:51 volumio systemd[1]: Stopping go-librespot Daemon... Sep 05 18:42:51 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 05 18:42:51 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 05 18:42:51 volumio systemd[1]: Stopped go-librespot Daemon. Sep 05 18:42:51 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 18:42:51 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:51 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:51 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:51 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:51 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:51 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:51 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:51 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:51 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:51 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 18:42:51 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:51 volumio systemd[1]: Started go-librespot Daemon. Sep 05 18:42:51 volumio volumio[19061]: info: No need to fix Spotify hosts Sep 05 18:42:51 volumio go-librespot[19229]: go-librespot daemon starting... Sep 05 18:42:51 volumio sudo[19226]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:51 volumio go-librespot[19229]: time="2025-09-05T18:42:51+02:00" level=info msg="running go-librespot 0.3.2" Sep 05 18:42:51 volumio go-librespot[19229]: time="2025-09-05T18:42:51+02:00" level=debug msg="app state loaded" Sep 05 18:42:51 volumio go-librespot[19229]: time="2025-09-05T18:42:51+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 05 18:42:51 volumio sudo[19245]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 05 18:42:51 volumio sudo[19245]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:51 volumio sudo[19245]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:51 volumio sudo[19248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 05 18:42:51 volumio sudo[19248]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:51 volumio sudo[19248]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:51 volumio go-librespot[19229]: time="2025-09-05T18:42:51+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 18:42:51 volumio go-librespot[19229]: time="2025-09-05T18:42:51+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 18:42:51 volumio go-librespot[19229]: time="2025-09-05T18:42:51+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 18:42:51 volumio go-librespot[19229]: time="2025-09-05T18:42:51+02:00" level=info msg="zeroconf server listening on port 45777" Sep 05 18:42:51 volumio go-librespot[19229]: time="2025-09-05T18:42:51+02:00" level=debug msg="obtained new client token: AACUb9SgXbR1jbLSIaN7J/qQ6tqoy3+VxhNuq3TkcOSZwEITFhAx71QatfGQ1b1iBduyZwUF2upsH2zvoD5mKG8qAI5PE+Q9YUxg3TlUft9H2JcZPQ9Ub5vxomcCjDrjZ4fjT8U0FNL6k/+mSUgbglJWyqxPLv3tkJx5g/A2kxufJsvBz28fbd11SuNq9/Dk4w/7+wvAmN7+1VO2uSe96T4N6nAc+LzN4W64sGyMKj5K0NgFl24sBhso6s4=" Sep 05 18:42:51 volumio go-librespot[19229]: time="2025-09-05T18:42:51+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 05 18:42:51 volumio go-librespot[19229]: time="2025-09-05T18:42:51+02:00" level=debug msg="completed keyexchange" Sep 05 18:42:51 volumio go-librespot[19229]: time="2025-09-05T18:42:51+02:00" level=debug msg="completed challenge" Sep 05 18:42:51 volumio go-librespot[19229]: time="2025-09-05T18:42:51+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 05 18:42:51 volumio volumio[19061]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 05 18:42:51 volumio volumio[19061]: SPOTIFY: BQDngZwJE9G_Dq3gFpvmZr52Q7YpkH-Bs6JdFPYVIEc4LyukFMtvVeNygFsFnOSx_g79Uf6mEGer6vh8M3z1uTTGK2O6TfAz5rBe2cQlwb05TgHHSnc0ubeDl9srML60s8FD9RFCf5agHPbDSSvbj9FFQA7BjRLaoaak2jgJF5IPswKxA1-he9_ZDl2Vm5AG4jTbjdlVBenvdyjEDrAQccK8YeL5jqTI7mn-Wi9tGBZCxKd2WlQsdP8sLV31q4VgYX6jDOFt48asGzz6CeGmSoPw8onrgKGwneCX98-_JiEUPLc Sep 05 18:42:51 volumio volumio[19061]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 05 18:42:51 volumio volumio[19061]: info: New Spotify access token = BQDngZwJE9G_Dq3gFpvmZr52Q7YpkH-Bs6JdFPYVIEc4LyukFMtvVeNygFsFnOSx_g79Uf6mEGer6vh8M3z1uTTGK2O6TfAz5rBe2cQlwb05TgHHSnc0ubeDl9srML60s8FD9RFCf5agHPbDSSvbj9FFQA7BjRLaoaak2jgJF5IPswKxA1-he9_ZDl2Vm5AG4jTbjdlVBenvdyjEDrAQccK8YeL5jqTI7mn-Wi9tGBZCxKd2WlQsdP8sLV31q4VgYX6jDOFt48asGzz6CeGmSoPw8onrgKGwneCX98-_JiEUPLc Sep 05 18:42:51 volumio volumio[19061]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 05 18:42:52 volumio go-librespot[19229]: time="2025-09-05T18:42:52+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 05 18:42:52 volumio go-librespot[19229]: time="2025-09-05T18:42:52+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 05 18:42:52 volumio volumio[19061]: info: CoreCommandRouter::volumioGetState Sep 05 18:42:52 volumio volumio[19061]: info: CorePlayQueue::getTrack 0 Sep 05 18:42:52 volumio go-librespot[19229]: time="2025-09-05T18:42:52+02:00" level=debug msg="dealer connection opened" Sep 05 18:42:52 volumio go-librespot[19229]: time="2025-09-05T18:42:52+02:00" level=trace msg="starting accesspoint recv loop" Sep 05 18:42:52 volumio go-librespot[19229]: time="2025-09-05T18:42:52+02:00" level=trace msg="starting dealer recv loop" Sep 05 18:42:52 volumio go-librespot[19229]: time="2025-09-05T18:42:52+02:00" level=trace msg="received accesspoint ping" Sep 05 18:42:52 volumio go-librespot[19229]: time="2025-09-05T18:42:52+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 05 18:42:52 volumio go-librespot[19229]: time="2025-09-05T18:42:52+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 05 18:42:52 volumio go-librespot[19229]: time="2025-09-05T18:42:52+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 05 18:42:52 volumio go-librespot[19229]: time="2025-09-05T18:42:52+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 05 18:42:52 volumio go-librespot[19229]: time="2025-09-05T18:42:52+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 05 18:42:52 volumio go-librespot[19229]: time="2025-09-05T18:42:52+02:00" level=debug msg="received connection id: M2VjZGU1YzUtYzEw...RjhGNDY4RTFBQQ==" Sep 05 18:42:52 volumio volumio[19061]: info: Asound.conf file unchanged, so no further update is needed Sep 05 18:42:52 volumio volumio[19061]: info: Output device has changed, restarting MPD Sep 05 18:42:52 volumio go-librespot[19229]: time="2025-09-05T18:42:52+02:00" level=trace msg="received accesspoint pong ack" Sep 05 18:42:52 volumio sudo[19252]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 18:42:52 volumio sudo[19252]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:52 volumio sudo[19252]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:52 volumio sudo[19255]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 18:42:52 volumio sudo[19255]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:52 volumio volumio[19061]: info: MPD Permissions set Sep 05 18:42:52 volumio go-librespot[19229]: time="2025-09-05T18:42:52+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 05 18:42:52 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 05 18:42:52 volumio systemd[1]: mpd.service: Succeeded. Sep 05 18:42:52 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 18:42:52 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:52 volumio sudo[19259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 05 18:42:52 volumio sudo[19259]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:52 volumio systemd[1]: Starting Music Player Daemon... Sep 05 18:42:52 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 18:42:52 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:52 volumio sudo[19259]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:52 volumio volumio[19061]: info: Upmpdcli Daemon Started Sep 05 18:42:52 volumio volumio[19061]: 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 18:42:52 volumio volumio[19061]: info: Spotify Successfully logged in Sep 05 18:42:52 volumio volumio[19061]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 18:42:52 volumio volumio[19061]: info: [1757090572362] CoreMusicLibrary::Adding element Spotify Sep 05 18:42:52 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 18:42:52 volumio volumio[19061]: Cannot find translation for source Jellyfin Sep 05 18:42:52 volumio volumio[19061]: Cannot find translation for source Spotify Sep 05 18:42:52 volumio sudo[19258]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 18:42:52 volumio sudo[19258]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:52 volumio sudo[19258]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:52 volumio volumio[19061]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 05 18:42:52 volumio volumio[19061]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 18:42:53 volumio volumio[19061]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 05 18:42:53 volumio volumio[19061]: 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 18:42:53 volumio volumio[19061]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 05 18:42:53 volumio volumio[19061]: info: camilladsp service started and running in background, instance 1 Sep 05 18:42:53 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 18:42:53 volumio volumio[19061]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 05 18:42:53 volumio volumio[19061]: 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 18:42:53 volumio volumio[19061]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 05 18:42:53 volumio volumio[19061]: info: FusionDsp loaded Sep 05 18:42:53 volumio volumio[19061]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 18:42:53 volumio sudo[19279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 05 18:42:53 volumio sudo[19279]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:53 volumio volumio[19061]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 05 18:42:53 volumio volumio[19061]: info: Adding Signal Path Element [object Object] Sep 05 18:42:53 volumio volumio[19061]: info: Adding fusiondspeq DSP Signal Path Element Sep 05 18:42:53 volumio volumio[19061]: info: FusionDsp - ---- installed callbackRead Sep 05 18:42:53 volumio volumio[19061]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 18:42:53 volumio sudo[19279]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:53 volumio volumio[19061]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 05 18:42:53 volumio volumio[19061]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 05 18:42:53 volumio volumio[19061]: at onErrorNT (internal/child_process.js:465:16) Sep 05 18:42:53 volumio volumio[19061]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Sep 05 18:42:53 volumio volumio[19061]: at runNextTicks (internal/process/task_queues.js:62:3) Sep 05 18:42:53 volumio volumio[19061]: at listOnTimeout (internal/timers.js:523:9) Sep 05 18:42:53 volumio volumio[19061]: at processTimers (internal/timers.js:497:7) { Sep 05 18:42:53 volumio volumio[19061]: errno: -2, Sep 05 18:42:53 volumio volumio[19061]: code: 'ENOENT', Sep 05 18:42:53 volumio volumio[19061]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 05 18:42:53 volumio volumio[19061]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 05 18:42:53 volumio volumio[19061]: spawnargs: [ Sep 05 18:42:53 volumio volumio[19061]: '-p', Sep 05 18:42:53 volumio volumio[19061]: 9876, Sep 05 18:42:53 volumio volumio[19061]: '-o', Sep 05 18:42:53 volumio volumio[19061]: '/tmp/camilladsp.log', Sep 05 18:42:53 volumio volumio[19061]: '-l', Sep 05 18:42:53 volumio volumio[19061]: 'warn', Sep 05 18:42:53 volumio volumio[19061]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 05 18:42:53 volumio volumio[19061]: ] Sep 05 18:42:53 volumio volumio[19061]: } Sep 05 18:42:53 volumio volumio[19061]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 18:42:53 volumio sudo[19290]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-05 18:41 Sep 05 18:42:53 volumio sudo[19290]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 18:42:53 volumio mpd[19266]: Sep 05 18:42 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 05 18:42:53 volumio systemd[1]: Started Music Player Daemon. Sep 05 18:42:53 volumio sudo[19186]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:53 volumio sudo[19255]: pam_unix(sudo:session): session closed for user root Sep 05 18:42:53 volumio sudo[19192]: pam_unix(sudo:session): session closed for user root 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"