-- Logs begin at Fri 2025-09-05 15:46:42 CEST, end at Fri 2025-09-05 16:14:55 CEST. -- Sep 05 16:13:00 volumio volumio[16120]: info: Plugin folders cleanup Sep 05 16:13:00 volumio volumio[16120]: info: Scanning into folder /volumio/app/plugins/ Sep 05 16:13:00 volumio volumio[16120]: info: Scanning category audio_interface Sep 05 16:13:00 volumio volumio[16120]: info: Scanning category miscellanea Sep 05 16:13:00 volumio volumio[16120]: info: Scanning category music_service Sep 05 16:13:00 volumio volumio[16120]: info: Scanning category plugins.json Sep 05 16:13:00 volumio volumio[16120]: info: Scanning category system_controller Sep 05 16:13:00 volumio volumio[16120]: info: Scanning category user_interface Sep 05 16:13:00 volumio volumio[16120]: info: Scanning into folder /data/plugins/ Sep 05 16:13:00 volumio volumio[16120]: info: Scanning category audio_interface Sep 05 16:13:00 volumio volumio[16120]: info: Scanning category music_service Sep 05 16:13:00 volumio volumio[16120]: info: Scanning category user_interface Sep 05 16:13:00 volumio volumio[16120]: info: Plugin folders cleanup completed Sep 05 16:13:00 volumio volumio[16120]: info: ------------------------------------------- Sep 05 16:13:00 volumio volumio[16120]: info: ----- Core plugins startup ---- Sep 05 16:13:00 volumio volumio[16120]: info: ------------------------------------------- Sep 05 16:13:00 volumio volumio[16120]: info: Loading plugins from folder /volumio/app/plugins/ Sep 05 16:13:00 volumio volumio[16120]: info: Adding plugin upnp to MyMusic Plugins Sep 05 16:13:00 volumio volumio[16120]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 05 16:13:00 volumio volumio[16120]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 05 16:13:00 volumio volumio[16120]: info: Loading plugins from folder /data/plugins/ Sep 05 16:13:00 volumio volumio[16120]: info: Loading plugin "system"... Sep 05 16:13:00 volumio volumio[16120]: info: Loading plugin "appearance"... Sep 05 16:13:01 volumio volumio[16120]: info: Loading plugin "network"... Sep 05 16:13:01 volumio volumio[16120]: info: Refreshing Cached IP Addresses Sep 05 16:13:01 volumio sudo[16146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 05 16:13:01 volumio sudo[16146]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:01 volumio sudo[16146]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:01 volumio sudo[16148]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 05 16:13:01 volumio volumio[16120]: info: Loading plugin "services"... Sep 05 16:13:01 volumio sudo[16148]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:01 volumio volumio[16120]: info: Loading plugin "alsa_controller"... Sep 05 16:13:01 volumio sudo[16148]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:01 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 16:13:01 volumio volumio[16120]: info: Loading plugin "wizard"... Sep 05 16:13:01 volumio volumio[16120]: info: Loading plugin "networkfs"... Sep 05 16:13:01 volumio volumio[16120]: info: Starting Udev Watcher for removable devices Sep 05 16:13:01 volumio volumio[16120]: info: Ignoring mount for partition: boot Sep 05 16:13:01 volumio volumio[16120]: info: Ignoring mount for partition: volumio Sep 05 16:13:01 volumio volumio[16120]: info: Ignoring mount for partition: volumio_data Sep 05 16:13:01 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 16:13:01 volumio volumio[16120]: info: Loading plugin "volumio_command_line_client"... Sep 05 16:13:01 volumio volumio[16120]: info: Loading plugin "upnp"... Sep 05 16:13:01 volumio volumio[16120]: info: [1757081581372] Starting Upmpd Daemon Sep 05 16:13:01 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 16:13:01 volumio volumio[16120]: info: Loading plugin "my_music"... Sep 05 16:13:01 volumio volumio[16120]: info: Loading plugin "mpd"... Sep 05 16:13:01 volumio volumio[16120]: info: Plugin upnp_browser is not enabled Sep 05 16:13:01 volumio volumio[16120]: info: Loading plugin "alarm-clock"... Sep 05 16:13:01 volumio volumio[16120]: info: Plugin airplay_emulation is not enabled Sep 05 16:13:01 volumio volumio[16120]: info: Loading plugin "last_100"... Sep 05 16:13:01 volumio volumio[16120]: info: Loading plugin "webradio"... Sep 05 16:13:02 volumio volumio[16120]: info: Loading plugin "i2s_dacs"... Sep 05 16:13:02 volumio volumio[16120]: info: Loading plugin "volumiodiscovery"... Sep 05 16:13:02 volumio volumio[16120]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 05 16:13:02 volumio volumio[16120]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 16:13:02 volumio volumio[16120]: *** WARNING *** For more information see Sep 05 16:13:02 volumio node[16120]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 05 16:13:02 volumio volumio[16120]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 05 16:13:02 volumio volumio[16120]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 16:13:02 volumio volumio[16120]: *** WARNING *** For more information see Sep 05 16:13:02 volumio node[16120]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 16:13:02 volumio node[16120]: *** WARNING *** For more information see Sep 05 16:13:02 volumio node[16120]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 05 16:13:02 volumio node[16120]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 16:13:02 volumio node[16120]: *** WARNING *** For more information see Sep 05 16:13:02 volumio volumio[16120]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 05 16:13:02 volumio volumio[16120]: info: Discovery: Started advertising with name: Volumio Sep 05 16:13:02 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 16:13:02 volumio volumio[16120]: info: Loading plugin "jellyfin"... Sep 05 16:13:03 volumio volumio[16120]: info: Loading plugin "spop"... Sep 05 16:13:04 volumio volumio[16120]: info: Loading plugin "ytcr"... Sep 05 16:13:07 volumio volumio-remote-updater[504]: [2025-09-05 16:13:07] [connect] Successful connection Sep 05 16:13:08 volumio volumio[16120]: info: Loading plugin "outputs"... Sep 05 16:13:08 volumio volumio[16120]: info: Loading plugin "albumart"... Sep 05 16:13:08 volumio volumio[16120]: info: Plugin example_plugin is not enabled Sep 05 16:13:08 volumio volumio[16120]: info: Loading plugin "inputs"... Sep 05 16:13:08 volumio volumio[16120]: info: Loading plugin "updater_comm"... Sep 05 16:13:08 volumio volumio[16120]: info: Plugin mpdemulation is not enabled Sep 05 16:13:08 volumio volumio[16120]: info: Loading plugin "rest_api"... Sep 05 16:13:08 volumio volumio[16120]: info: Loading plugin "websocket"... Sep 05 16:13:08 volumio volumio[16120]: info: Starting Socket.io Server version 2.3.0 Sep 05 16:13:08 volumio volumio[16120]: info: Loading plugin "fusiondsp"... Sep 05 16:13:08 volumio volumio[16120]: Forking 3 albumart workers Sep 05 16:13:08 volumio volumio[16120]: info: Applying required configuration parameters for plugin fusiondsp Sep 05 16:13:09 volumio volumio[16120]: info: Loading plugin "Systeminfo"... Sep 05 16:13:09 volumio volumio[16120]: info: Loading i18n strings for locale hu Sep 05 16:13:09 volumio volumio[16120]: Updating browse sources language Sep 05 16:13:09 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:13:09 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 16:13:09 volumio volumio[16120]: info: CoreCommandRouter::initPlayerControls Sep 05 16:13:09 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:09 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:09 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:09 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:09 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:09 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:09 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:09 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:09 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 16:13:09 volumio volumio[16120]: Express server listening on port 3000 Sep 05 16:13:09 volumio volumio[16120]: [Metrics] WebUI: 11s 108.77ms Sep 05 16:13:09 volumio volumio[16120]: info: CoreStateMachine::resetVolumioState Sep 05 16:13:09 volumio volumio[16120]: info: CoreStateMachine::getcurrentVolume Sep 05 16:13:09 volumio volumio[16120]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 16:13:09 volumio volumio[16120]: Starting albumart workers Sep 05 16:13:09 volumio volumio[16120]: Starting albumart workers Sep 05 16:13:09 volumio volumio[16120]: info: Volumio Network Manager: Network status updated: 1 Sep 05 16:13:09 volumio volumio[16120]: Starting albumart workers Sep 05 16:13:10 volumio volumio-remote-updater[504]: [2025-09-05 16:13:10] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1757081587 101 Sep 05 16:13:10 volumio volumio[16120]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Sep 05 16:13:10 volumio volumio[16120]: info: CoreCommandRouter::volumioGetState Sep 05 16:13:10 volumio volumio[16120]: info: CorePlayQueue::getTrack 0 Sep 05 16:13:10 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:13:10 volumio volumio[16120]: info: VolumeController:: Volume=37 Mute =false Sep 05 16:13:10 volumio volumio[16120]: info: CoreStateMachine::pushState Sep 05 16:13:10 volumio volumio[16120]: info: CorePlayQueue::getTrack 0 Sep 05 16:13:10 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 16:13:10 volumio volumio[16120]: info: CoreCommandRouter::volumioPushState Sep 05 16:13:10 volumio volumio[16120]: info: CoreStateMachine::updateTrackBlock Sep 05 16:13:10 volumio volumio[16120]: info: CorePlayQueue::getTrackBlock Sep 05 16:13:10 volumio volumio[16120]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 16:13:10 volumio volumio[16120]: info: CoreStateMachine::setRepeat false single undefined Sep 05 16:13:10 volumio volumio[16120]: info: CoreStateMachine::pushState Sep 05 16:13:10 volumio volumio[16120]: info: CorePlayQueue::getTrack 0 Sep 05 16:13:10 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 16:13:10 volumio volumio[16120]: info: CoreCommandRouter::volumioPushState Sep 05 16:13:10 volumio volumio[16120]: info: CoreStateMachine::setRandom true Sep 05 16:13:10 volumio volumio[16120]: info: CoreStateMachine::pushState Sep 05 16:13:10 volumio volumio[16120]: info: CorePlayQueue::getTrack 0 Sep 05 16:13:10 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 16:13:10 volumio volumio[16120]: info: CoreCommandRouter::volumioPushState Sep 05 16:13:10 volumio volumio[16120]: info: Setting Device type: Raspberry PI Sep 05 16:13:10 volumio volumio[16120]: info: Listing playlists Sep 05 16:13:10 volumio volumio[16120]: info: Listing playlists Sep 05 16:13:10 volumio volumio[16120]: info: Completed loading Core Plugins Sep 05 16:13:10 volumio volumio[16120]: info: Preparing to generate the ALSA configuration file Sep 05 16:13:10 volumio volumio[16120]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227 Sep 05 16:13:10 volumio volumio[16120]: info: Discovery: Found device Volumio Sep 05 16:13:10 volumio volumio[16120]: info: CoreCommandRouter::volumioGetState Sep 05 16:13:10 volumio volumio[16120]: info: CorePlayQueue::getTrack 0 Sep 05 16:13:10 volumio volumio[16120]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 05 16:13:10 volumio volumio[16120]: info: Reading ALSA contributions from plugins. Sep 05 16:13:10 volumio volumio[16120]: info: VolumeController:: Volume=37 Mute =false Sep 05 16:13:10 volumio volumio[16120]: info: CoreStateMachine::pushState Sep 05 16:13:10 volumio volumio[16120]: info: CorePlayQueue::getTrack 0 Sep 05 16:13:10 volumio volumio[16120]: info: CoreCommandRouter::volumioPushState Sep 05 16:13:10 volumio volumio[16120]: info: Asound.conf file unchanged, so no further update is needed Sep 05 16:13:10 volumio volumio[16120]: info: Output device has changed, restarting MPD Sep 05 16:13:10 volumio sudo[16230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 16:13:10 volumio sudo[16230]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:10 volumio volumio[16120]: info: ___________ START PLUGINS ___________ Sep 05 16:13:10 volumio volumio[16120]: info: ControllerMpd::onStart: Initializing MPD Sep 05 16:13:10 volumio volumio[16120]: info: Creating MPD Configuration file Sep 05 16:13:10 volumio sudo[16230]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:10 volumio sudo[16233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 16:13:10 volumio sudo[16233]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:10 volumio systemd[1]: Stopping Music Player Daemon... Sep 05 16:13:10 volumio sudo[16244]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 16:13:10 volumio volumio[16120]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 16:13:10 volumio volumio[16120]: info: [1757081590780] CoreMusicLibrary::Adding element Last_100 Sep 05 16:13:10 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:13:10 volumio sudo[16244]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:10 volumio systemd[1]: mpd.service: Succeeded. Sep 05 16:13:10 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 16:13:10 volumio volumio[16120]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 16:13:10 volumio volumio[16120]: info: [1757081590796] CoreMusicLibrary::Adding element Webradio Sep 05 16:13:10 volumio sudo[16244]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:10 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:13:10 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 16:13:10 volumio sudo[16246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 16:13:10 volumio sudo[16246]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:10 volumio systemd[1]: Starting Music Player Daemon... Sep 05 16:13:10 volumio volumio[16120]: info: Initializing BBC Radios Sep 05 16:13:10 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 05 16:13:10 volumio systemd[1]: mpd.service: Succeeded. Sep 05 16:13:10 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 16:13:10 volumio systemd[1]: Starting Music Player Daemon... Sep 05 16:13:10 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 16:13:10 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:13:10 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 16:13:10 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 05 16:13:10 volumio volumio[16120]: info: Discovery: Getting this device information Sep 05 16:13:10 volumio volumio[16120]: info: CoreCommandRouter::volumioGetState Sep 05 16:13:10 volumio volumio[16120]: info: CorePlayQueue::getTrack 0 Sep 05 16:13:10 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 16:13:10 volumio volumio[16120]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 16:13:10 volumio volumio[16120]: info: [1757081590935] CoreMusicLibrary::Adding element Jellyfin Sep 05 16:13:10 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:13:10 volumio volumio[16120]: Cannot find translation for source Jellyfin Sep 05 16:13:10 volumio volumio[16120]: info: [jellyfin] Initialized plugin with device info: {"id":"efb65fd7-7648-487c-b3ba-ebfb5cc10227","host":"http://192.168.0.171","name":"Volumio","type":"device","serviceName":"Volumio","state":{"status":"stop","volume":37,"mute":false,"artist":"","track":"","albumart":"http://192.168.0.171/albumart"}} Sep 05 16:13:10 volumio volumio[16120]: info: Creating Spotify config file Sep 05 16:13:10 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:11 volumio sudo[16257]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 16:13:11 volumio sudo[16257]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:11 volumio sudo[16257]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:11 volumio volumio[16120]: info: Loading i18n strings for locale hu Sep 05 16:13:11 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 05 16:13:11 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:11 volumio volumio[16120]: info: FusionDsp - mixtype--------------------- Hardware Sep 05 16:13:11 volumio volumio[16120]: info: Volumio Calling Home Sep 05 16:13:11 volumio volumio[16120]: info: Preparing to generate the ALSA configuration file Sep 05 16:13:11 volumio volumio[16120]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 05 16:13:11 volumio volumio[16120]: info: Reading ALSA contributions from plugins. Sep 05 16:13:11 volumio volumio[16120]: info: MPD Permissions set Sep 05 16:13:11 volumio volumio[16120]: info: MPD Permissions set Sep 05 16:13:11 volumio sudo[16282]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 05 16:13:11 volumio sudo[16282]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:11 volumio sudo[16284]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 05 16:13:11 volumio sudo[16282]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:11 volumio sudo[16284]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:11 volumio volumio[16120]: info: Volumio called home Sep 05 16:13:11 volumio volumio[16120]: info: Spotify config file written Sep 05 16:13:11 volumio sudo[16284]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:11 volumio sudo[16288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 05 16:13:11 volumio sudo[16288]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:11 volumio volumio[16120]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Sep 05 16:13:11 volumio systemd[1]: Stopping go-librespot Daemon... Sep 05 16:13:11 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:11 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:11 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 05 16:13:11 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:11 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:11 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:11 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:11 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 05 16:13:11 volumio systemd[1]: Stopped go-librespot Daemon. Sep 05 16:13:11 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:13:11 volumio systemd[1]: Started go-librespot Daemon. Sep 05 16:13:11 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:11 volumio go-librespot[16290]: go-librespot daemon starting... Sep 05 16:13:11 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:11 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:11 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:11 volumio sudo[16288]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:11 volumio go-librespot[16290]: time="2025-09-05T16:13:11+02:00" level=info msg="running go-librespot 0.3.2" Sep 05 16:13:11 volumio go-librespot[16290]: time="2025-09-05T16:13:11+02:00" level=debug msg="app state loaded" Sep 05 16:13:11 volumio go-librespot[16290]: time="2025-09-05T16:13:11+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 05 16:13:11 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:13:11 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:11 volumio volumio[16120]: info: No need to fix Spotify hosts Sep 05 16:13:12 volumio go-librespot[16290]: time="2025-09-05T16:13:12+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 16:13:12 volumio go-librespot[16290]: time="2025-09-05T16:13:12+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 16:13:12 volumio go-librespot[16290]: time="2025-09-05T16:13:12+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 16:13:12 volumio go-librespot[16290]: time="2025-09-05T16:13:12+02:00" level=info msg="zeroconf server listening on port 35097" Sep 05 16:13:12 volumio volumio[16120]: info: CoreCommandRouter::volumioGetState Sep 05 16:13:12 volumio volumio[16120]: info: CorePlayQueue::getTrack 0 Sep 05 16:13:12 volumio go-librespot[16290]: time="2025-09-05T16:13:12+02:00" level=debug msg="obtained new client token: AAAOcowB9F8NYzCXi3Pu8dqGWtR8BmdHKzX3qvTqLIMsslCRzv5DX7VAphEFz3+wU9y86X/vILCHZY8ppuq2NGJeqJJo0L9fqxzmC9Bm6GVyy/vpQxXcU3UKVs6S4xUdy8MWVRV1DsOQgeCkwiKAcEWwF07qyT+eKgxCg0S6YUoJ0/D8n4sQfzVHijnzpG7vli2xkq0QOPOD/YiWW8QGnfXr6T2c1z3dCo4AvT8mjqb9SAUiWBhEIR8f" Sep 05 16:13:12 volumio volumio[16120]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 05 16:13:12 volumio volumio[16120]: SPOTIFY: BQD-sYRyp0QdL_XGWAOKxKxHG-nfDOVW3Op5bnBEc40cfLjbkb7X0S15zc-Rc23Qb8c3Fz662NIOjEUpu3uyeNNkEXj3GfSIz6G01ce_WsWxW8nMOp1Rv3CztCnqkjtioVaJKufGfegfQrOrfxccsAa9bZWNib2RRHH8OCDLEOmixPXDSb0bD-c76xfZx0w3hRMFa3u5bua1KeBmUmfpQcqHDyW_gWYI5wsxDe25GCgsgvZD4sEpbSt8owybFMaBukCbCCxmqBQrLSaCsg1MQ7wzJ94lhNeKCFTLXWVepnm87PU Sep 05 16:13:12 volumio volumio[16120]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 05 16:13:12 volumio volumio[16120]: info: New Spotify access token = BQD-sYRyp0QdL_XGWAOKxKxHG-nfDOVW3Op5bnBEc40cfLjbkb7X0S15zc-Rc23Qb8c3Fz662NIOjEUpu3uyeNNkEXj3GfSIz6G01ce_WsWxW8nMOp1Rv3CztCnqkjtioVaJKufGfegfQrOrfxccsAa9bZWNib2RRHH8OCDLEOmixPXDSb0bD-c76xfZx0w3hRMFa3u5bua1KeBmUmfpQcqHDyW_gWYI5wsxDe25GCgsgvZD4sEpbSt8owybFMaBukCbCCxmqBQrLSaCsg1MQ7wzJ94lhNeKCFTLXWVepnm87PU Sep 05 16:13:12 volumio volumio[16120]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 05 16:13:12 volumio go-librespot[16290]: time="2025-09-05T16:13:12+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 05 16:13:12 volumio volumio[16120]: info: CoreCommandRouter::volumioGetState Sep 05 16:13:12 volumio volumio[16120]: info: CorePlayQueue::getTrack 0 Sep 05 16:13:12 volumio go-librespot[16290]: time="2025-09-05T16:13:12+02:00" level=debug msg="completed keyexchange" Sep 05 16:13:12 volumio go-librespot[16290]: time="2025-09-05T16:13:12+02:00" level=debug msg="completed challenge" Sep 05 16:13:12 volumio volumio[16120]: info: Asound.conf file unchanged, so no further update is needed Sep 05 16:13:12 volumio volumio[16120]: info: Output device has changed, restarting MPD Sep 05 16:13:12 volumio go-librespot[16290]: time="2025-09-05T16:13:12+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 05 16:13:12 volumio sudo[16307]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 05 16:13:12 volumio sudo[16307]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:12 volumio sudo[16309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 16:13:12 volumio sudo[16309]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:12 volumio sudo[16309]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:12 volumio sudo[16307]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:12 volumio sudo[16313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 16:13:12 volumio sudo[16313]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:12 volumio volumio[16120]: info: MPD Permissions set Sep 05 16:13:12 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 05 16:13:12 volumio systemd[1]: mpd.service: Succeeded. Sep 05 16:13:12 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 16:13:12 volumio go-librespot[16290]: time="2025-09-05T16:13:12+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 05 16:13:12 volumio go-librespot[16290]: time="2025-09-05T16:13:12+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 05 16:13:12 volumio volumio[16120]: info: Upmpdcli Daemon Started Sep 05 16:13:12 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:12 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:12 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:12 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:12 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:12 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:12 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:12 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:12 volumio go-librespot[16290]: time="2025-09-05T16:13:12+02:00" level=debug msg="dealer connection opened" Sep 05 16:13:12 volumio go-librespot[16290]: time="2025-09-05T16:13:12+02:00" level=trace msg="starting accesspoint recv loop" Sep 05 16:13:12 volumio go-librespot[16290]: time="2025-09-05T16:13:12+02:00" level=trace msg="starting dealer recv loop" Sep 05 16:13:12 volumio go-librespot[16290]: time="2025-09-05T16:13:12+02:00" level=trace msg="received accesspoint ping" Sep 05 16:13:12 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:13:12 volumio go-librespot[16290]: time="2025-09-05T16:13:12+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 05 16:13:12 volumio go-librespot[16290]: time="2025-09-05T16:13:12+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 05 16:13:12 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:12 volumio go-librespot[16290]: time="2025-09-05T16:13:12+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 05 16:13:12 volumio go-librespot[16290]: time="2025-09-05T16:13:12+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 05 16:13:12 volumio go-librespot[16290]: time="2025-09-05T16:13:12+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 05 16:13:12 volumio go-librespot[16290]: time="2025-09-05T16:13:12+02:00" level=debug msg="received connection id: N2Y3NjQxZTgtMjAy...MkJBNkZBRUYyQw==" Sep 05 16:13:12 volumio systemd[1]: Starting Music Player Daemon... Sep 05 16:13:12 volumio go-librespot[16290]: time="2025-09-05T16:13:12+02:00" level=trace msg="received accesspoint pong ack" Sep 05 16:13:12 volumio volumio[16120]: SPOTIFY: User informations: {"country":"HU","display_name":"4Misi","email":"sztako.mihaly@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/lo8jr34h0xh1vbcv2lgmaqkvv"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/lo8jr34h0xh1vbcv2lgmaqkvv","id":"lo8jr34h0xh1vbcv2lgmaqkvv","images":[],"product":"premium","type":"user","uri":"spotify:user:lo8jr34h0xh1vbcv2lgmaqkvv"} Sep 05 16:13:12 volumio volumio[16120]: info: Spotify Successfully logged in Sep 05 16:13:12 volumio volumio[16120]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 16:13:12 volumio volumio[16120]: info: [1757081592528] CoreMusicLibrary::Adding element Spotify Sep 05 16:13:12 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:13:12 volumio volumio[16120]: Cannot find translation for source Jellyfin Sep 05 16:13:12 volumio volumio[16120]: Cannot find translation for source Spotify Sep 05 16:13:12 volumio go-librespot[16290]: time="2025-09-05T16:13:12+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 05 16:13:12 volumio sudo[16316]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 16:13:12 volumio sudo[16316]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:12 volumio sudo[16316]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:12 volumio volumio[16120]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 05 16:13:12 volumio volumio[16120]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 16:13:13 volumio volumio[16120]: info: VolumeController:: Volume=37 Mute =false Sep 05 16:13:13 volumio volumio[16120]: info: CoreCommandRouter::volumioGetState Sep 05 16:13:13 volumio volumio[16120]: info: CorePlayQueue::getTrack 0 Sep 05 16:13:13 volumio volumio[16120]: info: CoreStateMachine::pushState Sep 05 16:13:13 volumio volumio[16120]: info: CorePlayQueue::getTrack 0 Sep 05 16:13:13 volumio volumio[16120]: info: CoreCommandRouter::volumioPushState Sep 05 16:13:13 volumio volumio[16120]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 05 16:13:13 volumio volumio[16120]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Sep 05 16:13:13 volumio volumio[16120]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 05 16:13:13 volumio volumio[16120]: info: camilladsp service started and running in background, instance 1 Sep 05 16:13:13 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:13 volumio volumio[16120]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 05 16:13:13 volumio volumio[16120]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 05 16:13:13 volumio volumio[16120]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 05 16:13:13 volumio volumio[16120]: info: FusionDsp loaded Sep 05 16:13:13 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 16:13:13 volumio sudo[16337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 05 16:13:13 volumio sudo[16337]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:13 volumio sudo[16337]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:13 volumio volumio[16120]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 05 16:13:13 volumio volumio[16120]: info: Adding Signal Path Element [object Object] Sep 05 16:13:13 volumio volumio[16120]: info: Adding fusiondspeq DSP Signal Path Element Sep 05 16:13:13 volumio volumio[16120]: info: FusionDsp - ---- installed callbackRead Sep 05 16:13:13 volumio volumio[16120]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 16:13:13 volumio volumio[16120]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 05 16:13:13 volumio volumio[16120]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 05 16:13:13 volumio volumio[16120]: at onErrorNT (internal/child_process.js:465:16) Sep 05 16:13:13 volumio volumio[16120]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Sep 05 16:13:13 volumio volumio[16120]: errno: -2, Sep 05 16:13:13 volumio volumio[16120]: code: 'ENOENT', Sep 05 16:13:13 volumio volumio[16120]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 05 16:13:13 volumio volumio[16120]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 05 16:13:13 volumio volumio[16120]: spawnargs: [ Sep 05 16:13:13 volumio volumio[16120]: '-p', Sep 05 16:13:13 volumio volumio[16120]: 9876, Sep 05 16:13:13 volumio volumio[16120]: '-o', Sep 05 16:13:13 volumio volumio[16120]: '/tmp/camilladsp.log', Sep 05 16:13:13 volumio volumio[16120]: '-l', Sep 05 16:13:13 volumio volumio[16120]: 'warn', Sep 05 16:13:13 volumio volumio[16120]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 05 16:13:13 volumio volumio[16120]: ] Sep 05 16:13:13 volumio volumio[16120]: } Sep 05 16:13:13 volumio volumio[16120]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 16:13:14 volumio sudo[16348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-05 16:12 Sep 05 16:13:14 volumio sudo[16348]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:14 volumio sudo[16348]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:14 volumio mpd[16324]: Sep 05 16:13 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 05 16:13:14 volumio systemd[1]: Started Music Player Daemon. Sep 05 16:13:14 volumio sudo[16233]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:14 volumio sudo[16246]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:14 volumio sudo[16313]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:14 volumio volumio-remote-updater[504]: [2025-09-05 16:13:14] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 05 16:13:14 volumio volumio-remote-updater[504]: [2025-09-05 16:13:14] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 05 16:13:14 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 05 16:13:14 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 05 16:13:14 volumio systemd[1]: Started dynamicswap service. Sep 05 16:13:14 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 05 16:13:14 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 05 16:13:14 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 18968. Sep 05 16:13:14 volumio systemd[1]: Stopped Volumio Backend Module. Sep 05 16:13:14 volumio systemd[1]: Started Volumio Backend Module. Sep 05 16:13:14 volumio systemd[1]: Started dynamicswap service. Sep 05 16:13:14 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 05 16:13:16 volumio volumio[16361]: info: ------------------------------------------- Sep 05 16:13:16 volumio volumio[16361]: info: ----- Volumio3 ---- Sep 05 16:13:16 volumio volumio[16361]: info: ------------------------------------------- Sep 05 16:13:16 volumio volumio[16361]: info: ----- System startup ---- Sep 05 16:13:16 volumio volumio[16361]: info: ------------------------------------------- Sep 05 16:13:16 volumio volumio[16361]: info: MYVOLUMIO Environment detected Sep 05 16:13:16 volumio volumio[16361]: info: Plugin folders cleanup Sep 05 16:13:16 volumio volumio[16361]: info: Scanning into folder /volumio/app/plugins/ Sep 05 16:13:16 volumio volumio[16361]: info: Scanning category audio_interface Sep 05 16:13:16 volumio volumio[16361]: info: Scanning category miscellanea Sep 05 16:13:16 volumio volumio[16361]: info: Scanning category music_service Sep 05 16:13:16 volumio volumio[16361]: info: Scanning category plugins.json Sep 05 16:13:16 volumio volumio[16361]: info: Scanning category system_controller Sep 05 16:13:16 volumio volumio[16361]: info: Scanning category user_interface Sep 05 16:13:16 volumio volumio[16361]: info: Scanning into folder /data/plugins/ Sep 05 16:13:16 volumio volumio[16361]: info: Scanning category audio_interface Sep 05 16:13:16 volumio volumio[16361]: info: Scanning category music_service Sep 05 16:13:16 volumio volumio[16361]: info: Scanning category user_interface Sep 05 16:13:16 volumio volumio[16361]: info: Plugin folders cleanup completed Sep 05 16:13:16 volumio volumio[16361]: info: ------------------------------------------- Sep 05 16:13:16 volumio volumio[16361]: info: ----- Core plugins startup ---- Sep 05 16:13:16 volumio volumio[16361]: info: ------------------------------------------- Sep 05 16:13:16 volumio volumio[16361]: info: Loading plugins from folder /volumio/app/plugins/ Sep 05 16:13:16 volumio volumio[16361]: info: Adding plugin upnp to MyMusic Plugins Sep 05 16:13:16 volumio volumio[16361]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 05 16:13:16 volumio volumio[16361]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 05 16:13:16 volumio volumio[16361]: info: Loading plugins from folder /data/plugins/ Sep 05 16:13:16 volumio volumio[16361]: info: Loading plugin "system"... Sep 05 16:13:17 volumio volumio[16361]: info: Loading plugin "appearance"... Sep 05 16:13:18 volumio volumio[16361]: info: Loading plugin "network"... Sep 05 16:13:18 volumio volumio[16361]: info: Refreshing Cached IP Addresses Sep 05 16:13:18 volumio sudo[16389]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 05 16:13:18 volumio sudo[16389]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:18 volumio sudo[16389]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:18 volumio sudo[16391]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 05 16:13:18 volumio volumio[16361]: info: Loading plugin "services"... Sep 05 16:13:18 volumio sudo[16391]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:18 volumio volumio[16361]: info: Loading plugin "alsa_controller"... Sep 05 16:13:18 volumio sudo[16391]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:18 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 16:13:18 volumio volumio[16361]: info: Loading plugin "wizard"... Sep 05 16:13:18 volumio volumio[16361]: info: Loading plugin "networkfs"... Sep 05 16:13:18 volumio volumio[16361]: info: Starting Udev Watcher for removable devices Sep 05 16:13:18 volumio volumio[16361]: info: Ignoring mount for partition: boot Sep 05 16:13:18 volumio volumio[16361]: info: Ignoring mount for partition: volumio Sep 05 16:13:18 volumio volumio[16361]: info: Ignoring mount for partition: volumio_data Sep 05 16:13:18 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 16:13:18 volumio volumio[16361]: info: Loading plugin "volumio_command_line_client"... Sep 05 16:13:18 volumio volumio[16361]: info: Loading plugin "upnp"... Sep 05 16:13:18 volumio volumio[16361]: info: [1757081598266] Starting Upmpd Daemon Sep 05 16:13:18 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 16:13:18 volumio volumio[16361]: info: Loading plugin "my_music"... Sep 05 16:13:18 volumio volumio[16361]: info: Loading plugin "mpd"... Sep 05 16:13:18 volumio volumio[16361]: info: Plugin upnp_browser is not enabled Sep 05 16:13:18 volumio volumio[16361]: info: Loading plugin "alarm-clock"... Sep 05 16:13:18 volumio volumio[16361]: info: Plugin airplay_emulation is not enabled Sep 05 16:13:18 volumio volumio[16361]: info: Loading plugin "last_100"... Sep 05 16:13:18 volumio volumio[16361]: info: Loading plugin "webradio"... Sep 05 16:13:18 volumio volumio[16361]: info: Loading plugin "i2s_dacs"... Sep 05 16:13:18 volumio volumio[16361]: info: Loading plugin "volumiodiscovery"... Sep 05 16:13:18 volumio volumio[16361]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 05 16:13:18 volumio volumio[16361]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 16:13:18 volumio volumio[16361]: *** WARNING *** For more information see Sep 05 16:13:18 volumio node[16361]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 05 16:13:18 volumio volumio[16361]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 05 16:13:18 volumio volumio[16361]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 16:13:18 volumio volumio[16361]: *** WARNING *** For more information see Sep 05 16:13:18 volumio node[16361]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 16:13:18 volumio node[16361]: *** WARNING *** For more information see Sep 05 16:13:18 volumio node[16361]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 05 16:13:18 volumio node[16361]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 16:13:18 volumio node[16361]: *** WARNING *** For more information see Sep 05 16:13:18 volumio volumio[16361]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 05 16:13:18 volumio volumio[16361]: info: Discovery: Started advertising with name: Volumio Sep 05 16:13:18 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 16:13:18 volumio volumio[16361]: info: Loading plugin "jellyfin"... Sep 05 16:13:19 volumio volumio-remote-updater[504]: [2025-09-05 16:13:19] [connect] Successful connection Sep 05 16:13:19 volumio volumio[16361]: info: Loading plugin "spop"... Sep 05 16:13:20 volumio volumio[16361]: info: Loading plugin "ytcr"... Sep 05 16:13:25 volumio volumio[16361]: info: Loading plugin "outputs"... Sep 05 16:13:25 volumio volumio[16361]: info: Loading plugin "albumart"... Sep 05 16:13:25 volumio volumio[16361]: info: Plugin example_plugin is not enabled Sep 05 16:13:25 volumio volumio[16361]: info: Loading plugin "inputs"... Sep 05 16:13:25 volumio volumio[16361]: info: Loading plugin "updater_comm"... Sep 05 16:13:25 volumio volumio[16361]: info: Plugin mpdemulation is not enabled Sep 05 16:13:25 volumio volumio[16361]: info: Loading plugin "rest_api"... Sep 05 16:13:25 volumio volumio[16361]: info: Loading plugin "websocket"... Sep 05 16:13:25 volumio volumio[16361]: info: Starting Socket.io Server version 2.3.0 Sep 05 16:13:25 volumio volumio[16361]: info: Loading plugin "fusiondsp"... Sep 05 16:13:25 volumio volumio[16361]: Forking 3 albumart workers Sep 05 16:13:25 volumio volumio[16361]: info: Applying required configuration parameters for plugin fusiondsp Sep 05 16:13:25 volumio volumio[16361]: info: Loading plugin "Systeminfo"... Sep 05 16:13:26 volumio volumio[16361]: info: Loading i18n strings for locale hu Sep 05 16:13:26 volumio volumio[16361]: Updating browse sources language Sep 05 16:13:26 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:13:26 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 16:13:26 volumio volumio[16361]: info: CoreCommandRouter::initPlayerControls Sep 05 16:13:26 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:26 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:26 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:26 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:26 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:26 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:26 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:26 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:26 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 16:13:26 volumio volumio[16361]: Express server listening on port 3000 Sep 05 16:13:26 volumio volumio[16361]: [Metrics] WebUI: 11s 22.58ms Sep 05 16:13:26 volumio volumio[16361]: info: CoreStateMachine::resetVolumioState Sep 05 16:13:26 volumio volumio[16361]: info: CoreStateMachine::getcurrentVolume Sep 05 16:13:26 volumio volumio[16361]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 16:13:26 volumio volumio[16361]: Starting albumart workers Sep 05 16:13:26 volumio volumio[16361]: info: Volumio Network Manager: Network status updated: 1 Sep 05 16:13:26 volumio volumio[16361]: Starting albumart workers Sep 05 16:13:26 volumio volumio[16361]: Starting albumart workers Sep 05 16:13:26 volumio volumio[16361]: info: CoreCommandRouter::volumioGetState Sep 05 16:13:26 volumio volumio[16361]: info: CorePlayQueue::getTrack 0 Sep 05 16:13:27 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:13:27 volumio volumio[16361]: info: VolumeController:: Volume=37 Mute =false Sep 05 16:13:27 volumio volumio[16361]: info: CoreStateMachine::pushState Sep 05 16:13:27 volumio volumio[16361]: info: CorePlayQueue::getTrack 0 Sep 05 16:13:27 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 16:13:27 volumio volumio[16361]: info: CoreCommandRouter::volumioPushState Sep 05 16:13:27 volumio volumio[16361]: info: CoreStateMachine::updateTrackBlock Sep 05 16:13:27 volumio volumio[16361]: info: CorePlayQueue::getTrackBlock Sep 05 16:13:27 volumio volumio[16361]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 16:13:27 volumio volumio[16361]: info: CoreStateMachine::setRepeat false single undefined Sep 05 16:13:27 volumio volumio[16361]: info: CoreStateMachine::pushState Sep 05 16:13:27 volumio volumio[16361]: info: CorePlayQueue::getTrack 0 Sep 05 16:13:27 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 16:13:27 volumio volumio[16361]: info: CoreCommandRouter::volumioPushState Sep 05 16:13:27 volumio volumio[16361]: info: CoreStateMachine::setRandom true Sep 05 16:13:27 volumio volumio[16361]: info: CoreStateMachine::pushState Sep 05 16:13:27 volumio volumio[16361]: info: CorePlayQueue::getTrack 0 Sep 05 16:13:27 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 16:13:27 volumio volumio[16361]: info: CoreCommandRouter::volumioPushState Sep 05 16:13:27 volumio volumio[16361]: info: Setting Device type: Raspberry PI Sep 05 16:13:27 volumio volumio[16361]: info: Listing playlists Sep 05 16:13:27 volumio volumio[16361]: info: Listing playlists Sep 05 16:13:27 volumio volumio[16361]: info: Completed loading Core Plugins Sep 05 16:13:27 volumio volumio[16361]: info: Preparing to generate the ALSA configuration file Sep 05 16:13:27 volumio volumio[16361]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227 Sep 05 16:13:27 volumio volumio[16361]: info: Discovery: Found device Volumio Sep 05 16:13:27 volumio volumio[16361]: info: CoreCommandRouter::volumioGetState Sep 05 16:13:27 volumio volumio[16361]: info: CorePlayQueue::getTrack 0 Sep 05 16:13:27 volumio volumio[16361]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 05 16:13:27 volumio volumio[16361]: info: Reading ALSA contributions from plugins. Sep 05 16:13:27 volumio volumio[16361]: info: VolumeController:: Volume=37 Mute =false Sep 05 16:13:27 volumio volumio[16361]: info: CoreStateMachine::pushState Sep 05 16:13:27 volumio volumio[16361]: info: CorePlayQueue::getTrack 0 Sep 05 16:13:27 volumio volumio[16361]: info: CoreCommandRouter::volumioPushState Sep 05 16:13:27 volumio volumio[16361]: info: Asound.conf file unchanged, so no further update is needed Sep 05 16:13:27 volumio volumio[16361]: info: Output device has changed, restarting MPD Sep 05 16:13:27 volumio volumio[16361]: info: ___________ START PLUGINS ___________ Sep 05 16:13:27 volumio volumio[16361]: info: ControllerMpd::onStart: Initializing MPD Sep 05 16:13:27 volumio volumio[16361]: info: Creating MPD Configuration file Sep 05 16:13:27 volumio sudo[16465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 16:13:27 volumio sudo[16465]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:27 volumio sudo[16465]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:27 volumio sudo[16467]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 16:13:27 volumio sudo[16467]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:27 volumio sudo[16470]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 16:13:27 volumio sudo[16470]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:27 volumio sudo[16470]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:27 volumio systemd[1]: Stopping Music Player Daemon... Sep 05 16:13:27 volumio volumio[16361]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 16:13:27 volumio volumio[16361]: info: [1757081607547] CoreMusicLibrary::Adding element Last_100 Sep 05 16:13:27 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:13:27 volumio volumio[16361]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 16:13:27 volumio volumio[16361]: info: [1757081607552] CoreMusicLibrary::Adding element Webradio Sep 05 16:13:27 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:13:27 volumio sudo[16474]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 16:13:27 volumio sudo[16474]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:27 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 16:13:27 volumio systemd[1]: mpd.service: Succeeded. Sep 05 16:13:27 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 16:13:27 volumio volumio[16361]: info: Initializing BBC Radios Sep 05 16:13:27 volumio systemd[1]: Starting Music Player Daemon... Sep 05 16:13:27 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 05 16:13:27 volumio systemd[1]: mpd.service: Succeeded. Sep 05 16:13:27 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 16:13:27 volumio systemd[1]: Starting Music Player Daemon... Sep 05 16:13:27 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 16:13:27 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:13:27 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 16:13:27 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 05 16:13:27 volumio volumio[16361]: info: Discovery: Getting this device information Sep 05 16:13:27 volumio volumio[16361]: info: CoreCommandRouter::volumioGetState Sep 05 16:13:27 volumio volumio[16361]: info: CorePlayQueue::getTrack 0 Sep 05 16:13:27 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 16:13:27 volumio volumio[16361]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 16:13:27 volumio volumio[16361]: info: [1757081607740] CoreMusicLibrary::Adding element Jellyfin Sep 05 16:13:27 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:13:27 volumio volumio[16361]: Cannot find translation for source Jellyfin Sep 05 16:13:27 volumio volumio[16361]: info: [jellyfin] Initialized plugin with device info: {"id":"efb65fd7-7648-487c-b3ba-ebfb5cc10227","host":"http://192.168.0.171","name":"Volumio","type":"device","serviceName":"Volumio","state":{"status":"stop","volume":37,"mute":false,"artist":"","track":"","albumart":"http://192.168.0.171/albumart"}} Sep 05 16:13:27 volumio volumio[16361]: info: Creating Spotify config file Sep 05 16:13:27 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:27 volumio sudo[16499]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 16:13:27 volumio sudo[16499]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:27 volumio sudo[16499]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:27 volumio volumio[16361]: info: Loading i18n strings for locale hu Sep 05 16:13:27 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 05 16:13:27 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:27 volumio volumio[16361]: info: FusionDsp - mixtype--------------------- Hardware Sep 05 16:13:27 volumio volumio[16361]: info: Volumio Calling Home Sep 05 16:13:28 volumio volumio[16361]: info: Preparing to generate the ALSA configuration file Sep 05 16:13:28 volumio volumio[16361]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 05 16:13:28 volumio volumio[16361]: info: Reading ALSA contributions from plugins. Sep 05 16:13:28 volumio volumio[16361]: info: MPD Permissions set Sep 05 16:13:28 volumio volumio[16361]: info: MPD Permissions set Sep 05 16:13:28 volumio volumio[16361]: info: Spotify config file written Sep 05 16:13:28 volumio sudo[16524]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 05 16:13:28 volumio sudo[16524]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:28 volumio volumio[16361]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Sep 05 16:13:28 volumio volumio[16361]: info: Volumio called home Sep 05 16:13:28 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:28 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:28 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:28 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:28 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:28 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:28 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:28 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:28 volumio systemd[1]: Stopping go-librespot Daemon... Sep 05 16:13:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 05 16:13:28 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 05 16:13:28 volumio systemd[1]: Stopped go-librespot Daemon. Sep 05 16:13:28 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:13:28 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:28 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:28 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:28 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:28 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:28 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:28 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:28 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:28 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:28 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:13:28 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:28 volumio systemd[1]: Started go-librespot Daemon. Sep 05 16:13:28 volumio go-librespot[16527]: go-librespot daemon starting... Sep 05 16:13:28 volumio volumio[16361]: info: No need to fix Spotify hosts Sep 05 16:13:28 volumio sudo[16524]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:28 volumio go-librespot[16527]: time="2025-09-05T16:13:28+02:00" level=info msg="running go-librespot 0.3.2" Sep 05 16:13:28 volumio go-librespot[16527]: time="2025-09-05T16:13:28+02:00" level=debug msg="app state loaded" Sep 05 16:13:28 volumio go-librespot[16527]: time="2025-09-05T16:13:28+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 05 16:13:28 volumio sudo[16544]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 05 16:13:28 volumio sudo[16544]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:28 volumio sudo[16544]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:28 volumio sudo[16547]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 05 16:13:28 volumio sudo[16547]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:28 volumio go-librespot[16527]: time="2025-09-05T16:13:28+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 16:13:28 volumio go-librespot[16527]: time="2025-09-05T16:13:28+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 16:13:28 volumio go-librespot[16527]: time="2025-09-05T16:13:28+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 16:13:28 volumio go-librespot[16527]: time="2025-09-05T16:13:28+02:00" level=info msg="zeroconf server listening on port 43669" Sep 05 16:13:28 volumio sudo[16547]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:28 volumio go-librespot[16527]: time="2025-09-05T16:13:28+02:00" level=debug msg="obtained new client token: AAC1b00pLuc6w/ZOdKdVcCQynnqDhtkWSfhI18uC9bG3hLyZUCa2aYZXFKIyDL6rd/NO+58UzACm3T8TSq7HUzmnhw5uMrIHqYsedw/wpglddvRtbPPI2K49AhRFntLHNSo7BFAyEKIf5E5KxYQGwzoARNIfavCaEnBu+brfgZE1kGxzBIr3dUDwwSBeVgtCerpCcHxfSLDLMJRKjv3pwGBOPILsVfB6Q46ip1oGWfxoD56bGLVI6z7gwNc=" Sep 05 16:13:28 volumio go-librespot[16527]: time="2025-09-05T16:13:28+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 05 16:13:28 volumio go-librespot[16527]: time="2025-09-05T16:13:28+02:00" level=debug msg="completed keyexchange" Sep 05 16:13:28 volumio go-librespot[16527]: time="2025-09-05T16:13:28+02:00" level=debug msg="completed challenge" Sep 05 16:13:28 volumio go-librespot[16527]: time="2025-09-05T16:13:28+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 05 16:13:28 volumio volumio[16361]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 05 16:13:28 volumio volumio[16361]: SPOTIFY: BQALb5fz9aEph1LktBOaPwhB3zqhqWAjc1ZP5kTq_NWWgoFmcxz1dMp7bQcESz4VbhmJKJhQOpq0AY7poSqfYuq5CPnMqz2-A82tag7sY5zIqm1s5v9WDqHU1iYup0ECQBKzFq1zNLKEUDs7848wDy9v1jn--TOVqqzizKNlbC2qccS4ye3rZmiCMzyvpLcV-YTs9LLflvNHCnvfyVAEeip0quUqQfIPwmvVWZ6idjd5-zU6z4sGFOWrUS96v62jT8IRRmsD_CxAIEQgqKuHYoCA2ONH0rhMjQ8HF4N6aHlpeYs Sep 05 16:13:28 volumio volumio[16361]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 05 16:13:28 volumio volumio[16361]: info: New Spotify access token = BQALb5fz9aEph1LktBOaPwhB3zqhqWAjc1ZP5kTq_NWWgoFmcxz1dMp7bQcESz4VbhmJKJhQOpq0AY7poSqfYuq5CPnMqz2-A82tag7sY5zIqm1s5v9WDqHU1iYup0ECQBKzFq1zNLKEUDs7848wDy9v1jn--TOVqqzizKNlbC2qccS4ye3rZmiCMzyvpLcV-YTs9LLflvNHCnvfyVAEeip0quUqQfIPwmvVWZ6idjd5-zU6z4sGFOWrUS96v62jT8IRRmsD_CxAIEQgqKuHYoCA2ONH0rhMjQ8HF4N6aHlpeYs Sep 05 16:13:28 volumio volumio[16361]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 05 16:13:28 volumio volumio[16361]: info: CoreCommandRouter::volumioGetState Sep 05 16:13:28 volumio volumio[16361]: info: CorePlayQueue::getTrack 0 Sep 05 16:13:29 volumio volumio[16361]: info: Asound.conf file unchanged, so no further update is needed Sep 05 16:13:29 volumio go-librespot[16527]: time="2025-09-05T16:13:29+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 05 16:13:29 volumio volumio[16361]: info: Output device has changed, restarting MPD Sep 05 16:13:29 volumio go-librespot[16527]: time="2025-09-05T16:13:29+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 05 16:13:29 volumio sudo[16550]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 16:13:29 volumio sudo[16550]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:29 volumio go-librespot[16527]: time="2025-09-05T16:13:29+02:00" level=debug msg="dealer connection opened" Sep 05 16:13:29 volumio go-librespot[16527]: time="2025-09-05T16:13:29+02:00" level=trace msg="starting accesspoint recv loop" Sep 05 16:13:29 volumio go-librespot[16527]: time="2025-09-05T16:13:29+02:00" level=trace msg="starting dealer recv loop" Sep 05 16:13:29 volumio go-librespot[16527]: time="2025-09-05T16:13:29+02:00" level=trace msg="received accesspoint ping" Sep 05 16:13:29 volumio go-librespot[16527]: time="2025-09-05T16:13:29+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 05 16:13:29 volumio go-librespot[16527]: time="2025-09-05T16:13:29+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 05 16:13:29 volumio go-librespot[16527]: time="2025-09-05T16:13:29+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 05 16:13:29 volumio go-librespot[16527]: time="2025-09-05T16:13:29+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 05 16:13:29 volumio go-librespot[16527]: time="2025-09-05T16:13:29+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 05 16:13:29 volumio go-librespot[16527]: time="2025-09-05T16:13:29+02:00" level=debug msg="received connection id: M2IxNWQ2NzAtYjdm...QkQ3RkZDQUJDOQ==" Sep 05 16:13:29 volumio sudo[16550]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:29 volumio go-librespot[16527]: time="2025-09-05T16:13:29+02:00" level=trace msg="received accesspoint pong ack" Sep 05 16:13:29 volumio sudo[16553]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 16:13:29 volumio sudo[16553]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:29 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 05 16:13:29 volumio volumio[16361]: info: MPD Permissions set Sep 05 16:13:29 volumio systemd[1]: mpd.service: Succeeded. Sep 05 16:13:29 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 16:13:29 volumio sudo[16557]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 05 16:13:29 volumio go-librespot[16527]: time="2025-09-05T16:13:29+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 05 16:13:29 volumio sudo[16557]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:29 volumio systemd[1]: Starting Music Player Daemon... Sep 05 16:13:29 volumio sudo[16557]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:29 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:29 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:29 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:29 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:29 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:29 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:29 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:29 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:29 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:13:29 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:29 volumio volumio[16361]: info: Upmpdcli Daemon Started Sep 05 16:13:29 volumio sudo[16555]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 16:13:29 volumio sudo[16555]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:29 volumio volumio[16361]: SPOTIFY: User informations: {"country":"HU","display_name":"4Misi","email":"sztako.mihaly@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/lo8jr34h0xh1vbcv2lgmaqkvv"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/lo8jr34h0xh1vbcv2lgmaqkvv","id":"lo8jr34h0xh1vbcv2lgmaqkvv","images":[],"product":"premium","type":"user","uri":"spotify:user:lo8jr34h0xh1vbcv2lgmaqkvv"} Sep 05 16:13:29 volumio volumio[16361]: info: Spotify Successfully logged in Sep 05 16:13:29 volumio sudo[16555]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:29 volumio volumio[16361]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 16:13:29 volumio volumio[16361]: info: [1757081609341] CoreMusicLibrary::Adding element Spotify Sep 05 16:13:29 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:13:29 volumio volumio[16361]: Cannot find translation for source Jellyfin Sep 05 16:13:29 volumio volumio[16361]: Cannot find translation for source Spotify Sep 05 16:13:29 volumio volumio[16361]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 05 16:13:29 volumio volumio[16361]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 16:13:29 volumio volumio[16361]: info: VolumeController:: Volume=37 Mute =false Sep 05 16:13:29 volumio volumio[16361]: info: CoreCommandRouter::volumioGetState Sep 05 16:13:29 volumio volumio[16361]: info: CorePlayQueue::getTrack 0 Sep 05 16:13:29 volumio volumio[16361]: info: CoreStateMachine::pushState Sep 05 16:13:29 volumio volumio[16361]: info: CorePlayQueue::getTrack 0 Sep 05 16:13:29 volumio volumio[16361]: info: CoreCommandRouter::volumioPushState Sep 05 16:13:29 volumio volumio[16361]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 05 16:13:29 volumio volumio[16361]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Sep 05 16:13:29 volumio volumio[16361]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 05 16:13:30 volumio volumio[16361]: info: camilladsp service started and running in background, instance 1 Sep 05 16:13:30 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:30 volumio volumio[16361]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 05 16:13:30 volumio volumio[16361]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 05 16:13:30 volumio volumio[16361]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 05 16:13:30 volumio volumio[16361]: info: FusionDsp loaded Sep 05 16:13:30 volumio volumio[16361]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 16:13:30 volumio sudo[16578]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 05 16:13:30 volumio sudo[16578]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:30 volumio volumio[16361]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 05 16:13:30 volumio volumio[16361]: info: Adding Signal Path Element [object Object] Sep 05 16:13:30 volumio volumio[16361]: info: Adding fusiondspeq DSP Signal Path Element Sep 05 16:13:30 volumio volumio[16361]: info: FusionDsp - ---- installed callbackRead Sep 05 16:13:30 volumio sudo[16578]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:30 volumio volumio[16361]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 16:13:30 volumio volumio[16361]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 05 16:13:30 volumio volumio[16361]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 05 16:13:30 volumio volumio[16361]: at onErrorNT (internal/child_process.js:465:16) Sep 05 16:13:30 volumio volumio[16361]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Sep 05 16:13:30 volumio volumio[16361]: errno: -2, Sep 05 16:13:30 volumio volumio[16361]: code: 'ENOENT', Sep 05 16:13:30 volumio volumio[16361]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 05 16:13:30 volumio volumio[16361]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 05 16:13:30 volumio volumio[16361]: spawnargs: [ Sep 05 16:13:30 volumio volumio[16361]: '-p', Sep 05 16:13:30 volumio volumio[16361]: 9876, Sep 05 16:13:30 volumio volumio[16361]: '-o', Sep 05 16:13:30 volumio volumio[16361]: '/tmp/camilladsp.log', Sep 05 16:13:30 volumio volumio[16361]: '-l', Sep 05 16:13:30 volumio volumio[16361]: 'warn', Sep 05 16:13:30 volumio volumio[16361]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 05 16:13:30 volumio volumio[16361]: ] Sep 05 16:13:30 volumio volumio[16361]: } Sep 05 16:13:30 volumio volumio[16361]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 16:13:30 volumio sudo[16589]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-05 16:12 Sep 05 16:13:30 volumio sudo[16589]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:30 volumio mpd[16565]: Sep 05 16:13 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 05 16:13:30 volumio systemd[1]: Started Music Player Daemon. Sep 05 16:13:30 volumio sudo[16553]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:30 volumio sudo[16474]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:30 volumio sudo[16467]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:30 volumio sudo[16589]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:31 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 05 16:13:31 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 05 16:13:31 volumio systemd[1]: Started dynamicswap service. Sep 05 16:13:31 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 05 16:13:31 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 05 16:13:31 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 18969. Sep 05 16:13:31 volumio systemd[1]: Stopped Volumio Backend Module. Sep 05 16:13:31 volumio systemd[1]: Started Volumio Backend Module. Sep 05 16:13:31 volumio systemd[1]: Started dynamicswap service. Sep 05 16:13:31 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 05 16:13:31 volumio volumio-remote-updater[504]: [2025-09-05 16:13:31] [info] asio async_connect error: system:111 (Connection refused) Sep 05 16:13:31 volumio volumio-remote-updater[504]: [2025-09-05 16:13:31] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 05 16:13:31 volumio volumio-remote-updater[504]: [2025-09-05 16:13:31] [error] handle_connect error: Underlying Transport Error Sep 05 16:13:33 volumio volumio[16601]: info: ------------------------------------------- Sep 05 16:13:33 volumio volumio[16601]: info: ----- Volumio3 ---- Sep 05 16:13:33 volumio volumio[16601]: info: ------------------------------------------- Sep 05 16:13:33 volumio volumio[16601]: info: ----- System startup ---- Sep 05 16:13:33 volumio volumio[16601]: info: ------------------------------------------- Sep 05 16:13:33 volumio volumio[16601]: info: MYVOLUMIO Environment detected Sep 05 16:13:34 volumio volumio[16601]: info: Plugin folders cleanup Sep 05 16:13:34 volumio volumio[16601]: info: Scanning into folder /volumio/app/plugins/ Sep 05 16:13:34 volumio volumio[16601]: info: Scanning category audio_interface Sep 05 16:13:34 volumio volumio[16601]: info: Scanning category miscellanea Sep 05 16:13:34 volumio volumio[16601]: info: Scanning category music_service Sep 05 16:13:34 volumio volumio[16601]: info: Scanning category plugins.json Sep 05 16:13:34 volumio volumio[16601]: info: Scanning category system_controller Sep 05 16:13:34 volumio volumio[16601]: info: Scanning category user_interface Sep 05 16:13:34 volumio volumio[16601]: info: Scanning into folder /data/plugins/ Sep 05 16:13:34 volumio volumio[16601]: info: Scanning category audio_interface Sep 05 16:13:34 volumio volumio[16601]: info: Scanning category music_service Sep 05 16:13:34 volumio volumio[16601]: info: Scanning category user_interface Sep 05 16:13:34 volumio volumio[16601]: info: Plugin folders cleanup completed Sep 05 16:13:34 volumio volumio[16601]: info: ------------------------------------------- Sep 05 16:13:34 volumio volumio[16601]: info: ----- Core plugins startup ---- Sep 05 16:13:34 volumio volumio[16601]: info: ------------------------------------------- Sep 05 16:13:34 volumio volumio[16601]: info: Loading plugins from folder /volumio/app/plugins/ Sep 05 16:13:34 volumio volumio[16601]: info: Adding plugin upnp to MyMusic Plugins Sep 05 16:13:34 volumio volumio[16601]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 05 16:13:34 volumio volumio[16601]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 05 16:13:34 volumio volumio[16601]: info: Loading plugins from folder /data/plugins/ Sep 05 16:13:34 volumio volumio[16601]: info: Loading plugin "system"... Sep 05 16:13:34 volumio volumio[16601]: info: Loading plugin "appearance"... Sep 05 16:13:35 volumio volumio[16601]: info: Loading plugin "network"... Sep 05 16:13:35 volumio volumio[16601]: info: Refreshing Cached IP Addresses Sep 05 16:13:35 volumio sudo[16631]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 05 16:13:35 volumio sudo[16631]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:35 volumio sudo[16631]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:35 volumio sudo[16633]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 05 16:13:35 volumio volumio[16601]: info: Loading plugin "services"... Sep 05 16:13:35 volumio sudo[16633]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:35 volumio volumio[16601]: info: Loading plugin "alsa_controller"... Sep 05 16:13:35 volumio sudo[16633]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:35 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 16:13:35 volumio volumio[16601]: info: Loading plugin "wizard"... Sep 05 16:13:35 volumio volumio[16601]: info: Loading plugin "networkfs"... Sep 05 16:13:35 volumio volumio[16601]: info: Starting Udev Watcher for removable devices Sep 05 16:13:35 volumio volumio[16601]: info: Ignoring mount for partition: boot Sep 05 16:13:35 volumio volumio[16601]: info: Ignoring mount for partition: volumio Sep 05 16:13:35 volumio volumio[16601]: info: Ignoring mount for partition: volumio_data Sep 05 16:13:35 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 16:13:35 volumio volumio[16601]: info: Loading plugin "volumio_command_line_client"... Sep 05 16:13:35 volumio volumio[16601]: info: Loading plugin "upnp"... Sep 05 16:13:35 volumio volumio[16601]: info: [1757081615344] Starting Upmpd Daemon Sep 05 16:13:35 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 16:13:35 volumio volumio[16601]: info: Loading plugin "my_music"... Sep 05 16:13:35 volumio volumio[16601]: info: Loading plugin "mpd"... Sep 05 16:13:35 volumio volumio[16601]: info: Plugin upnp_browser is not enabled Sep 05 16:13:35 volumio volumio[16601]: info: Loading plugin "alarm-clock"... Sep 05 16:13:35 volumio volumio[16601]: info: Plugin airplay_emulation is not enabled Sep 05 16:13:35 volumio volumio[16601]: info: Loading plugin "last_100"... Sep 05 16:13:35 volumio volumio[16601]: info: Loading plugin "webradio"... Sep 05 16:13:35 volumio volumio[16601]: info: Loading plugin "i2s_dacs"... Sep 05 16:13:36 volumio volumio[16601]: info: Loading plugin "volumiodiscovery"... Sep 05 16:13:36 volumio volumio[16601]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 05 16:13:36 volumio volumio[16601]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 16:13:36 volumio volumio[16601]: *** WARNING *** For more information see Sep 05 16:13:36 volumio volumio[16601]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 05 16:13:36 volumio node[16601]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 05 16:13:36 volumio volumio[16601]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 16:13:36 volumio volumio[16601]: *** WARNING *** For more information see Sep 05 16:13:36 volumio node[16601]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 16:13:36 volumio node[16601]: *** WARNING *** For more information see Sep 05 16:13:36 volumio node[16601]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 05 16:13:36 volumio node[16601]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 16:13:36 volumio node[16601]: *** WARNING *** For more information see Sep 05 16:13:36 volumio volumio[16601]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 05 16:13:36 volumio volumio[16601]: info: Discovery: Started advertising with name: Volumio Sep 05 16:13:36 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 16:13:36 volumio volumio[16601]: info: Loading plugin "jellyfin"... Sep 05 16:13:36 volumio volumio[16601]: info: Loading plugin "spop"... Sep 05 16:13:38 volumio volumio[16601]: info: Loading plugin "ytcr"... Sep 05 16:13:41 volumio volumio-remote-updater[504]: [2025-09-05 16:13:41] [connect] Successful connection Sep 05 16:13:42 volumio volumio[16601]: info: Loading plugin "outputs"... Sep 05 16:13:42 volumio volumio[16601]: info: Loading plugin "albumart"... Sep 05 16:13:42 volumio volumio[16601]: info: Plugin example_plugin is not enabled Sep 05 16:13:42 volumio volumio[16601]: info: Loading plugin "inputs"... Sep 05 16:13:42 volumio volumio[16601]: info: Loading plugin "updater_comm"... Sep 05 16:13:42 volumio volumio[16601]: info: Plugin mpdemulation is not enabled Sep 05 16:13:42 volumio volumio[16601]: info: Loading plugin "rest_api"... Sep 05 16:13:42 volumio volumio[16601]: info: Loading plugin "websocket"... Sep 05 16:13:42 volumio volumio[16601]: info: Starting Socket.io Server version 2.3.0 Sep 05 16:13:42 volumio volumio[16601]: info: Loading plugin "fusiondsp"... Sep 05 16:13:42 volumio volumio[16601]: Forking 3 albumart workers Sep 05 16:13:42 volumio volumio[16601]: info: Applying required configuration parameters for plugin fusiondsp Sep 05 16:13:42 volumio volumio[16601]: info: Loading plugin "Systeminfo"... Sep 05 16:13:43 volumio volumio[16601]: info: Loading i18n strings for locale hu Sep 05 16:13:43 volumio volumio[16601]: Updating browse sources language Sep 05 16:13:43 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:13:43 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 16:13:43 volumio volumio[16601]: info: CoreCommandRouter::initPlayerControls Sep 05 16:13:43 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:43 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:43 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:43 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:43 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:43 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:43 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:43 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:43 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 16:13:43 volumio volumio[16601]: Express server listening on port 3000 Sep 05 16:13:43 volumio volumio[16601]: [Metrics] WebUI: 11s 23.77ms Sep 05 16:13:43 volumio volumio[16601]: info: CoreStateMachine::resetVolumioState Sep 05 16:13:43 volumio volumio[16601]: info: CoreStateMachine::getcurrentVolume Sep 05 16:13:43 volumio volumio[16601]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 16:13:43 volumio volumio[16601]: Starting albumart workers Sep 05 16:13:43 volumio volumio[16601]: Starting albumart workers Sep 05 16:13:43 volumio volumio[16601]: info: Volumio Network Manager: Network status updated: 1 Sep 05 16:13:43 volumio volumio[16601]: Starting albumart workers Sep 05 16:13:43 volumio volumio-remote-updater[504]: [2025-09-05 16:13:43] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1757081621 101 Sep 05 16:13:43 volumio volumio[16601]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Sep 05 16:13:43 volumio volumio[16601]: info: CoreCommandRouter::volumioGetState Sep 05 16:13:44 volumio volumio[16601]: info: CorePlayQueue::getTrack 0 Sep 05 16:13:44 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:13:44 volumio volumio[16601]: info: VolumeController:: Volume=37 Mute =false Sep 05 16:13:44 volumio volumio[16601]: info: CoreStateMachine::pushState Sep 05 16:13:44 volumio volumio[16601]: info: CorePlayQueue::getTrack 0 Sep 05 16:13:44 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 16:13:44 volumio volumio[16601]: info: CoreCommandRouter::volumioPushState Sep 05 16:13:44 volumio volumio[16601]: info: CoreStateMachine::updateTrackBlock Sep 05 16:13:44 volumio volumio[16601]: info: CorePlayQueue::getTrackBlock Sep 05 16:13:44 volumio volumio[16601]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 16:13:44 volumio volumio[16601]: info: CoreStateMachine::setRepeat false single undefined Sep 05 16:13:44 volumio volumio[16601]: info: CoreStateMachine::pushState Sep 05 16:13:44 volumio volumio[16601]: info: CorePlayQueue::getTrack 0 Sep 05 16:13:44 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 16:13:44 volumio volumio[16601]: info: CoreCommandRouter::volumioPushState Sep 05 16:13:44 volumio volumio[16601]: info: CoreStateMachine::setRandom true Sep 05 16:13:44 volumio volumio[16601]: info: CoreStateMachine::pushState Sep 05 16:13:44 volumio volumio[16601]: info: CorePlayQueue::getTrack 0 Sep 05 16:13:44 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 16:13:44 volumio volumio[16601]: info: CoreCommandRouter::volumioPushState Sep 05 16:13:44 volumio volumio[16601]: info: Listing playlists Sep 05 16:13:44 volumio volumio[16601]: info: Listing playlists Sep 05 16:13:44 volumio volumio[16601]: info: Setting Device type: Raspberry PI Sep 05 16:13:44 volumio volumio[16601]: info: Completed loading Core Plugins Sep 05 16:13:44 volumio volumio[16601]: info: Preparing to generate the ALSA configuration file Sep 05 16:13:44 volumio volumio[16601]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227 Sep 05 16:13:44 volumio volumio[16601]: info: Discovery: Found device Volumio Sep 05 16:13:44 volumio volumio[16601]: info: CoreCommandRouter::volumioGetState Sep 05 16:13:44 volumio volumio[16601]: info: CorePlayQueue::getTrack 0 Sep 05 16:13:44 volumio volumio[16601]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 05 16:13:44 volumio volumio[16601]: info: Reading ALSA contributions from plugins. Sep 05 16:13:44 volumio volumio[16601]: info: VolumeController:: Volume=37 Mute =false Sep 05 16:13:44 volumio volumio[16601]: info: CoreStateMachine::pushState Sep 05 16:13:44 volumio volumio[16601]: info: CorePlayQueue::getTrack 0 Sep 05 16:13:44 volumio volumio[16601]: info: CoreCommandRouter::volumioPushState Sep 05 16:13:44 volumio volumio[16601]: info: Asound.conf file unchanged, so no further update is needed Sep 05 16:13:44 volumio volumio[16601]: info: Output device has changed, restarting MPD Sep 05 16:13:44 volumio sudo[16750]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 16:13:44 volumio sudo[16750]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:44 volumio sudo[16750]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:44 volumio volumio[16601]: info: ___________ START PLUGINS ___________ Sep 05 16:13:44 volumio volumio[16601]: info: ControllerMpd::onStart: Initializing MPD Sep 05 16:13:44 volumio volumio[16601]: info: Creating MPD Configuration file Sep 05 16:13:44 volumio sudo[16757]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 16:13:44 volumio sudo[16757]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:44 volumio systemd[1]: Stopping Music Player Daemon... Sep 05 16:13:44 volumio volumio[16601]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 16:13:44 volumio volumio[16601]: info: [1757081624664] CoreMusicLibrary::Adding element Last_100 Sep 05 16:13:44 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:13:44 volumio volumio[16601]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 16:13:44 volumio volumio[16601]: info: [1757081624672] CoreMusicLibrary::Adding element Webradio Sep 05 16:13:44 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:13:44 volumio sudo[16765]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 16:13:44 volumio sudo[16765]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:44 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 16:13:44 volumio volumio[16601]: info: Initializing BBC Radios Sep 05 16:13:44 volumio sudo[16770]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 16:13:44 volumio systemd[1]: mpd.service: Succeeded. Sep 05 16:13:44 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 16:13:44 volumio sudo[16770]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:44 volumio sudo[16765]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:44 volumio systemd[1]: Starting Music Player Daemon... Sep 05 16:13:44 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 05 16:13:44 volumio systemd[1]: mpd.service: Succeeded. Sep 05 16:13:44 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 16:13:44 volumio systemd[1]: Starting Music Player Daemon... Sep 05 16:13:44 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 16:13:44 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:13:44 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 16:13:44 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 05 16:13:44 volumio volumio[16601]: info: Discovery: Getting this device information Sep 05 16:13:44 volumio volumio[16601]: info: CoreCommandRouter::volumioGetState Sep 05 16:13:44 volumio volumio[16601]: info: CorePlayQueue::getTrack 0 Sep 05 16:13:44 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 16:13:44 volumio volumio[16601]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 16:13:44 volumio volumio[16601]: info: [1757081624831] CoreMusicLibrary::Adding element Jellyfin Sep 05 16:13:44 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:13:44 volumio volumio[16601]: Cannot find translation for source Jellyfin Sep 05 16:13:44 volumio volumio[16601]: info: [jellyfin] Initialized plugin with device info: {"id":"efb65fd7-7648-487c-b3ba-ebfb5cc10227","host":"http://192.168.0.171","name":"Volumio","type":"device","serviceName":"Volumio","state":{"status":"stop","volume":37,"mute":false,"artist":"","track":"","albumart":"http://192.168.0.171/albumart"}} Sep 05 16:13:44 volumio volumio[16601]: info: Creating Spotify config file Sep 05 16:13:44 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:44 volumio sudo[16781]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 16:13:44 volumio sudo[16781]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:44 volumio sudo[16781]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:45 volumio volumio[16601]: info: Loading i18n strings for locale hu Sep 05 16:13:45 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 05 16:13:45 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:45 volumio volumio[16601]: info: FusionDsp - mixtype--------------------- Hardware Sep 05 16:13:45 volumio volumio[16601]: info: Volumio Calling Home Sep 05 16:13:45 volumio volumio[16601]: info: Preparing to generate the ALSA configuration file Sep 05 16:13:45 volumio volumio[16601]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 05 16:13:45 volumio volumio[16601]: info: Reading ALSA contributions from plugins. Sep 05 16:13:45 volumio volumio[16601]: info: MPD Permissions set Sep 05 16:13:45 volumio volumio[16601]: info: MPD Permissions set Sep 05 16:13:45 volumio sudo[16805]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 05 16:13:45 volumio sudo[16805]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:45 volumio sudo[16805]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:45 volumio volumio[16601]: info: Spotify config file written Sep 05 16:13:45 volumio sudo[16808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 05 16:13:45 volumio sudo[16808]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:45 volumio sudo[16808]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:45 volumio sudo[16811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 05 16:13:45 volumio sudo[16811]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:45 volumio systemd[1]: Stopping go-librespot Daemon... Sep 05 16:13:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 05 16:13:45 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 05 16:13:45 volumio systemd[1]: Stopped go-librespot Daemon. Sep 05 16:13:45 volumio systemd[1]: Started go-librespot Daemon. Sep 05 16:13:45 volumio go-librespot[16813]: go-librespot daemon starting... Sep 05 16:13:45 volumio sudo[16811]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:45 volumio go-librespot[16813]: time="2025-09-05T16:13:45+02:00" level=info msg="running go-librespot 0.3.2" Sep 05 16:13:45 volumio go-librespot[16813]: time="2025-09-05T16:13:45+02:00" level=debug msg="app state loaded" Sep 05 16:13:45 volumio go-librespot[16813]: time="2025-09-05T16:13:45+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 05 16:13:45 volumio volumio[16601]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Sep 05 16:13:45 volumio volumio[16601]: info: Volumio called home Sep 05 16:13:45 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:45 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:45 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:13:45 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:45 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:45 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:45 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:45 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:45 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:45 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:45 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:45 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:45 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:13:45 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:45 volumio volumio[16601]: info: No need to fix Spotify hosts Sep 05 16:13:45 volumio go-librespot[16813]: time="2025-09-05T16:13:45+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 16:13:45 volumio go-librespot[16813]: time="2025-09-05T16:13:45+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 16:13:45 volumio go-librespot[16813]: time="2025-09-05T16:13:45+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 16:13:45 volumio go-librespot[16813]: time="2025-09-05T16:13:45+02:00" level=info msg="zeroconf server listening on port 46467" Sep 05 16:13:45 volumio volumio[16601]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 05 16:13:45 volumio volumio[16601]: SPOTIFY: BQA3Pa-RGSyBIEV3i1eBaTYWbKxuuHLICJ1qadbWvCfFM9qS9GbbbAXuZVyhdEusBVG6feLdbpUqvB19UwWibXl7jkLP9SU9BfV2XSuXiwDQFuZZk4q0xq6qvXl_e4sLweCBzC9vF1vQsor-ZPzHsb2zIydRDgMUtsPswig7hUedrvktDjMdNfXm2jflhbzhlGcv5MExXlYKVN6QkLAvW6zLAbzkGYjRWcmwulyQWWzRiMJnLO0YAwJs7p4AzRJx-M2mCfCXZ2inWfNWdc0NrIKDmKVIg53YEN7OioCx01oQKsQ Sep 05 16:13:45 volumio volumio[16601]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 05 16:13:45 volumio volumio[16601]: info: New Spotify access token = BQA3Pa-RGSyBIEV3i1eBaTYWbKxuuHLICJ1qadbWvCfFM9qS9GbbbAXuZVyhdEusBVG6feLdbpUqvB19UwWibXl7jkLP9SU9BfV2XSuXiwDQFuZZk4q0xq6qvXl_e4sLweCBzC9vF1vQsor-ZPzHsb2zIydRDgMUtsPswig7hUedrvktDjMdNfXm2jflhbzhlGcv5MExXlYKVN6QkLAvW6zLAbzkGYjRWcmwulyQWWzRiMJnLO0YAwJs7p4AzRJx-M2mCfCXZ2inWfNWdc0NrIKDmKVIg53YEN7OioCx01oQKsQ Sep 05 16:13:45 volumio volumio[16601]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 05 16:13:46 volumio go-librespot[16813]: time="2025-09-05T16:13:46+02:00" level=debug msg="obtained new client token: AAC9lsryn22i9uibXCsaiKqSa96wWdHS+rthlCewaUUeXC/zkOKMIiUs0zifV4r4YvpNsB3fGjOSZjGcjIFGGv+djzofKvlx7fVW0QgbA9uUJUC+hyoWZuKnb/tM0lfISXTIW0RP/fp4eJLixvKIoh/3q11hUtuyIFJM80R6qTOIy1kXlo/xZWqirTXSUuDYQZSCRgJYNGJ2tCz+Jh3M8P2BFQc4ZRaoPj0l8z+hWP3G0q8B339Dx4L3E70=" Sep 05 16:13:46 volumio volumio[16601]: info: CoreCommandRouter::volumioGetState Sep 05 16:13:46 volumio volumio[16601]: info: CorePlayQueue::getTrack 0 Sep 05 16:13:46 volumio go-librespot[16813]: time="2025-09-05T16:13:46+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 05 16:13:46 volumio volumio[16601]: info: Asound.conf file unchanged, so no further update is needed Sep 05 16:13:46 volumio volumio[16601]: info: Output device has changed, restarting MPD Sep 05 16:13:46 volumio go-librespot[16813]: time="2025-09-05T16:13:46+02:00" level=debug msg="completed keyexchange" Sep 05 16:13:46 volumio go-librespot[16813]: time="2025-09-05T16:13:46+02:00" level=debug msg="completed challenge" Sep 05 16:13:46 volumio sudo[16830]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 16:13:46 volumio sudo[16830]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:46 volumio sudo[16830]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:46 volumio sudo[16833]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 16:13:46 volumio go-librespot[16813]: time="2025-09-05T16:13:46+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 05 16:13:46 volumio sudo[16833]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:46 volumio sudo[16836]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 05 16:13:46 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 05 16:13:46 volumio systemd[1]: mpd.service: Succeeded. Sep 05 16:13:46 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 16:13:46 volumio sudo[16836]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:46 volumio volumio[16601]: info: MPD Permissions set Sep 05 16:13:46 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:46 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:46 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:46 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:46 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:46 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:46 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:46 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:46 volumio systemd[1]: Starting Music Player Daemon... Sep 05 16:13:46 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:13:46 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:46 volumio sudo[16836]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:46 volumio go-librespot[16813]: time="2025-09-05T16:13:46+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 05 16:13:46 volumio go-librespot[16813]: time="2025-09-05T16:13:46+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 05 16:13:46 volumio volumio[16601]: info: Upmpdcli Daemon Started Sep 05 16:13:46 volumio volumio[16601]: SPOTIFY: User informations: {"country":"HU","display_name":"4Misi","email":"sztako.mihaly@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/lo8jr34h0xh1vbcv2lgmaqkvv"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/lo8jr34h0xh1vbcv2lgmaqkvv","id":"lo8jr34h0xh1vbcv2lgmaqkvv","images":[],"product":"premium","type":"user","uri":"spotify:user:lo8jr34h0xh1vbcv2lgmaqkvv"} Sep 05 16:13:46 volumio volumio[16601]: info: Spotify Successfully logged in Sep 05 16:13:46 volumio volumio[16601]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 16:13:46 volumio volumio[16601]: info: [1757081626380] CoreMusicLibrary::Adding element Spotify Sep 05 16:13:46 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:13:46 volumio volumio[16601]: Cannot find translation for source Jellyfin Sep 05 16:13:46 volumio volumio[16601]: Cannot find translation for source Spotify Sep 05 16:13:46 volumio sudo[16838]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 16:13:46 volumio sudo[16838]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:46 volumio sudo[16838]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:46 volumio go-librespot[16813]: time="2025-09-05T16:13:46+02:00" level=debug msg="dealer connection opened" Sep 05 16:13:46 volumio go-librespot[16813]: time="2025-09-05T16:13:46+02:00" level=trace msg="starting accesspoint recv loop" Sep 05 16:13:46 volumio go-librespot[16813]: time="2025-09-05T16:13:46+02:00" level=trace msg="starting dealer recv loop" Sep 05 16:13:46 volumio go-librespot[16813]: time="2025-09-05T16:13:46+02:00" level=trace msg="received accesspoint ping" Sep 05 16:13:46 volumio go-librespot[16813]: time="2025-09-05T16:13:46+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 05 16:13:46 volumio go-librespot[16813]: time="2025-09-05T16:13:46+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 05 16:13:46 volumio go-librespot[16813]: time="2025-09-05T16:13:46+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 05 16:13:46 volumio go-librespot[16813]: time="2025-09-05T16:13:46+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 05 16:13:46 volumio go-librespot[16813]: time="2025-09-05T16:13:46+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 05 16:13:46 volumio go-librespot[16813]: time="2025-09-05T16:13:46+02:00" level=debug msg="received connection id: ZDNlZGUxMjQtMjc0...NzBCMzhCNjMyMw==" Sep 05 16:13:46 volumio go-librespot[16813]: time="2025-09-05T16:13:46+02:00" level=trace msg="received accesspoint pong ack" Sep 05 16:13:46 volumio go-librespot[16813]: time="2025-09-05T16:13:46+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 05 16:13:46 volumio volumio[16601]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 05 16:13:46 volumio volumio[16601]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 16:13:46 volumio volumio[16601]: info: VolumeController:: Volume=37 Mute =false Sep 05 16:13:46 volumio volumio[16601]: info: CoreCommandRouter::volumioGetState Sep 05 16:13:46 volumio volumio[16601]: info: CorePlayQueue::getTrack 0 Sep 05 16:13:46 volumio volumio[16601]: info: CoreStateMachine::pushState Sep 05 16:13:46 volumio volumio[16601]: info: CorePlayQueue::getTrack 0 Sep 05 16:13:46 volumio volumio[16601]: info: CoreCommandRouter::volumioPushState Sep 05 16:13:47 volumio volumio[16601]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 05 16:13:47 volumio volumio[16601]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Sep 05 16:13:47 volumio volumio[16601]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 05 16:13:47 volumio volumio[16601]: info: camilladsp service started and running in background, instance 1 Sep 05 16:13:47 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:13:47 volumio volumio[16601]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 05 16:13:47 volumio volumio[16601]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 05 16:13:47 volumio volumio[16601]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 05 16:13:47 volumio volumio[16601]: info: FusionDsp loaded Sep 05 16:13:47 volumio volumio[16601]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 16:13:47 volumio sudo[16858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 05 16:13:47 volumio sudo[16858]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:47 volumio volumio[16601]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 05 16:13:47 volumio volumio[16601]: info: Adding Signal Path Element [object Object] Sep 05 16:13:47 volumio volumio[16601]: info: Adding fusiondspeq DSP Signal Path Element Sep 05 16:13:47 volumio volumio[16601]: info: FusionDsp - ---- installed callbackRead Sep 05 16:13:47 volumio sudo[16858]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:47 volumio volumio[16601]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 16:13:47 volumio volumio[16601]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 05 16:13:47 volumio volumio[16601]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 05 16:13:47 volumio volumio[16601]: at onErrorNT (internal/child_process.js:465:16) Sep 05 16:13:47 volumio volumio[16601]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Sep 05 16:13:47 volumio volumio[16601]: errno: -2, Sep 05 16:13:47 volumio volumio[16601]: code: 'ENOENT', Sep 05 16:13:47 volumio volumio[16601]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 05 16:13:47 volumio volumio[16601]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 05 16:13:47 volumio volumio[16601]: spawnargs: [ Sep 05 16:13:47 volumio volumio[16601]: '-p', Sep 05 16:13:47 volumio volumio[16601]: 9876, Sep 05 16:13:47 volumio volumio[16601]: '-o', Sep 05 16:13:47 volumio volumio[16601]: '/tmp/camilladsp.log', Sep 05 16:13:47 volumio volumio[16601]: '-l', Sep 05 16:13:47 volumio volumio[16601]: 'warn', Sep 05 16:13:47 volumio volumio[16601]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 05 16:13:47 volumio volumio[16601]: ] Sep 05 16:13:47 volumio volumio[16601]: } Sep 05 16:13:47 volumio volumio[16601]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 16:13:47 volumio sudo[16869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-05 16:12 Sep 05 16:13:47 volumio sudo[16869]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:48 volumio mpd[16845]: Sep 05 16:13 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 05 16:13:48 volumio systemd[1]: Started Music Player Daemon. Sep 05 16:13:48 volumio sudo[16833]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:48 volumio sudo[16770]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:48 volumio sudo[16757]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:48 volumio sudo[16869]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:48 volumio volumio-remote-updater[504]: [2025-09-05 16:13:48] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 05 16:13:48 volumio volumio-remote-updater[504]: [2025-09-05 16:13:48] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 05 16:13:48 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 05 16:13:48 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 05 16:13:48 volumio systemd[1]: Started dynamicswap service. Sep 05 16:13:48 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 05 16:13:48 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 05 16:13:48 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 18970. Sep 05 16:13:48 volumio systemd[1]: Started dynamicswap service. Sep 05 16:13:48 volumio systemd[1]: Stopped Volumio Backend Module. Sep 05 16:13:48 volumio systemd[1]: Started Volumio Backend Module. Sep 05 16:13:48 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 05 16:13:50 volumio volumio[16882]: info: ------------------------------------------- Sep 05 16:13:50 volumio volumio[16882]: info: ----- Volumio3 ---- Sep 05 16:13:50 volumio volumio[16882]: info: ------------------------------------------- Sep 05 16:13:50 volumio volumio[16882]: info: ----- System startup ---- Sep 05 16:13:50 volumio volumio[16882]: info: ------------------------------------------- Sep 05 16:13:50 volumio volumio[16882]: info: MYVOLUMIO Environment detected Sep 05 16:13:50 volumio volumio[16882]: info: Plugin folders cleanup Sep 05 16:13:50 volumio volumio[16882]: info: Scanning into folder /volumio/app/plugins/ Sep 05 16:13:50 volumio volumio[16882]: info: Scanning category audio_interface Sep 05 16:13:50 volumio volumio[16882]: info: Scanning category miscellanea Sep 05 16:13:50 volumio volumio[16882]: info: Scanning category music_service Sep 05 16:13:50 volumio volumio[16882]: info: Scanning category plugins.json Sep 05 16:13:50 volumio volumio[16882]: info: Scanning category system_controller Sep 05 16:13:50 volumio volumio[16882]: info: Scanning category user_interface Sep 05 16:13:50 volumio volumio[16882]: info: Scanning into folder /data/plugins/ Sep 05 16:13:50 volumio volumio[16882]: info: Scanning category audio_interface Sep 05 16:13:50 volumio volumio[16882]: info: Scanning category music_service Sep 05 16:13:50 volumio volumio[16882]: info: Scanning category user_interface Sep 05 16:13:50 volumio volumio[16882]: info: Plugin folders cleanup completed Sep 05 16:13:50 volumio volumio[16882]: info: ------------------------------------------- Sep 05 16:13:50 volumio volumio[16882]: info: ----- Core plugins startup ---- Sep 05 16:13:50 volumio volumio[16882]: info: ------------------------------------------- Sep 05 16:13:50 volumio volumio[16882]: info: Loading plugins from folder /volumio/app/plugins/ Sep 05 16:13:50 volumio volumio[16882]: info: Adding plugin upnp to MyMusic Plugins Sep 05 16:13:50 volumio volumio[16882]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 05 16:13:51 volumio volumio[16882]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 05 16:13:51 volumio volumio[16882]: info: Loading plugins from folder /data/plugins/ Sep 05 16:13:51 volumio volumio[16882]: info: Loading plugin "system"... Sep 05 16:13:51 volumio volumio[16882]: info: Loading plugin "appearance"... Sep 05 16:13:52 volumio volumio[16882]: info: Loading plugin "network"... Sep 05 16:13:52 volumio volumio[16882]: info: Refreshing Cached IP Addresses Sep 05 16:13:52 volumio sudo[16908]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 05 16:13:52 volumio sudo[16908]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:52 volumio sudo[16908]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:52 volumio sudo[16910]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 05 16:13:52 volumio volumio[16882]: info: Loading plugin "services"... Sep 05 16:13:52 volumio volumio[16882]: info: Loading plugin "alsa_controller"... Sep 05 16:13:52 volumio sudo[16910]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:13:52 volumio sudo[16910]: pam_unix(sudo:session): session closed for user root Sep 05 16:13:52 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 16:13:52 volumio volumio[16882]: info: Loading plugin "wizard"... Sep 05 16:13:52 volumio volumio[16882]: info: Loading plugin "networkfs"... Sep 05 16:13:52 volumio volumio[16882]: info: Starting Udev Watcher for removable devices Sep 05 16:13:52 volumio volumio[16882]: info: Ignoring mount for partition: boot Sep 05 16:13:52 volumio volumio[16882]: info: Ignoring mount for partition: volumio Sep 05 16:13:52 volumio volumio[16882]: info: Ignoring mount for partition: volumio_data Sep 05 16:13:52 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 16:13:52 volumio volumio[16882]: info: Loading plugin "volumio_command_line_client"... Sep 05 16:13:52 volumio volumio[16882]: info: Loading plugin "upnp"... Sep 05 16:13:52 volumio volumio[16882]: info: [1757081632298] Starting Upmpd Daemon Sep 05 16:13:52 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 16:13:52 volumio volumio[16882]: info: Loading plugin "my_music"... Sep 05 16:13:52 volumio volumio[16882]: info: Loading plugin "mpd"... Sep 05 16:13:52 volumio volumio[16882]: info: Plugin upnp_browser is not enabled Sep 05 16:13:52 volumio volumio[16882]: info: Loading plugin "alarm-clock"... Sep 05 16:13:52 volumio volumio[16882]: info: Plugin airplay_emulation is not enabled Sep 05 16:13:52 volumio volumio[16882]: info: Loading plugin "last_100"... Sep 05 16:13:52 volumio volumio[16882]: info: Loading plugin "webradio"... Sep 05 16:13:52 volumio volumio[16882]: info: Loading plugin "i2s_dacs"... Sep 05 16:13:52 volumio volumio[16882]: info: Loading plugin "volumiodiscovery"... Sep 05 16:13:52 volumio volumio[16882]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 05 16:13:52 volumio volumio[16882]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 16:13:52 volumio volumio[16882]: *** WARNING *** For more information see Sep 05 16:13:52 volumio node[16882]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 05 16:13:52 volumio volumio[16882]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 05 16:13:52 volumio volumio[16882]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 16:13:52 volumio volumio[16882]: *** WARNING *** For more information see Sep 05 16:13:52 volumio node[16882]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 16:13:52 volumio node[16882]: *** WARNING *** For more information see Sep 05 16:13:52 volumio node[16882]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 05 16:13:52 volumio node[16882]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 16:13:52 volumio node[16882]: *** WARNING *** For more information see Sep 05 16:13:52 volumio volumio[16882]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 05 16:13:52 volumio volumio[16882]: info: Discovery: Started advertising with name: Volumio Sep 05 16:13:53 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 16:13:53 volumio volumio[16882]: info: Loading plugin "jellyfin"... Sep 05 16:13:53 volumio volumio-remote-updater[504]: [2025-09-05 16:13:53] [connect] Successful connection Sep 05 16:13:53 volumio volumio[16882]: info: Loading plugin "spop"... Sep 05 16:13:54 volumio volumio[16882]: info: Loading plugin "ytcr"... Sep 05 16:13:59 volumio volumio[16882]: info: Loading plugin "outputs"... Sep 05 16:13:59 volumio volumio[16882]: info: Loading plugin "albumart"... Sep 05 16:13:59 volumio volumio[16882]: info: Plugin example_plugin is not enabled Sep 05 16:13:59 volumio volumio[16882]: info: Loading plugin "inputs"... Sep 05 16:13:59 volumio volumio[16882]: info: Loading plugin "updater_comm"... Sep 05 16:13:59 volumio volumio[16882]: info: Plugin mpdemulation is not enabled Sep 05 16:13:59 volumio volumio[16882]: info: Loading plugin "rest_api"... Sep 05 16:13:59 volumio volumio[16882]: info: Loading plugin "websocket"... Sep 05 16:13:59 volumio volumio[16882]: info: Starting Socket.io Server version 2.3.0 Sep 05 16:13:59 volumio volumio[16882]: info: Loading plugin "fusiondsp"... Sep 05 16:13:59 volumio volumio[16882]: Forking 3 albumart workers Sep 05 16:13:59 volumio volumio[16882]: info: Applying required configuration parameters for plugin fusiondsp Sep 05 16:13:59 volumio volumio[16882]: info: Loading plugin "Systeminfo"... Sep 05 16:14:00 volumio volumio[16882]: info: Loading i18n strings for locale hu Sep 05 16:14:00 volumio volumio[16882]: Updating browse sources language Sep 05 16:14:00 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:14:00 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 16:14:00 volumio volumio[16882]: info: CoreCommandRouter::initPlayerControls Sep 05 16:14:00 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:00 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:00 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:00 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:00 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:00 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:00 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:00 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:00 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 16:14:00 volumio volumio[16882]: Express server listening on port 3000 Sep 05 16:14:00 volumio volumio[16882]: [Metrics] WebUI: 10s 927.10ms Sep 05 16:14:00 volumio volumio[16882]: info: CoreStateMachine::resetVolumioState Sep 05 16:14:00 volumio volumio[16882]: info: CoreStateMachine::getcurrentVolume Sep 05 16:14:00 volumio volumio[16882]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 16:14:00 volumio volumio[16882]: Starting albumart workers Sep 05 16:14:00 volumio volumio[16882]: Starting albumart workers Sep 05 16:14:00 volumio volumio[16882]: Starting albumart workers Sep 05 16:14:00 volumio volumio[16882]: info: Volumio Network Manager: Network status updated: 1 Sep 05 16:14:00 volumio volumio[16882]: info: CoreCommandRouter::volumioGetState Sep 05 16:14:00 volumio volumio[16882]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:00 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:14:01 volumio volumio[16882]: info: VolumeController:: Volume=37 Mute =false Sep 05 16:14:01 volumio volumio[16882]: info: CoreStateMachine::pushState Sep 05 16:14:01 volumio volumio[16882]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:01 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 16:14:01 volumio volumio[16882]: info: CoreCommandRouter::volumioPushState Sep 05 16:14:01 volumio volumio[16882]: info: CoreStateMachine::updateTrackBlock Sep 05 16:14:01 volumio volumio[16882]: info: CorePlayQueue::getTrackBlock Sep 05 16:14:01 volumio volumio[16882]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 16:14:01 volumio volumio[16882]: info: CoreStateMachine::setRepeat false single undefined Sep 05 16:14:01 volumio volumio[16882]: info: CoreStateMachine::pushState Sep 05 16:14:01 volumio volumio[16882]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:01 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 16:14:01 volumio volumio[16882]: info: CoreCommandRouter::volumioPushState Sep 05 16:14:01 volumio volumio[16882]: info: CoreStateMachine::setRandom true Sep 05 16:14:01 volumio volumio[16882]: info: CoreStateMachine::pushState Sep 05 16:14:01 volumio volumio[16882]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:01 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 16:14:01 volumio volumio[16882]: info: CoreCommandRouter::volumioPushState Sep 05 16:14:01 volumio volumio[16882]: info: Setting Device type: Raspberry PI Sep 05 16:14:01 volumio volumio[16882]: info: Listing playlists Sep 05 16:14:01 volumio volumio[16882]: info: Listing playlists Sep 05 16:14:01 volumio volumio[16882]: info: Completed loading Core Plugins Sep 05 16:14:01 volumio volumio[16882]: info: Preparing to generate the ALSA configuration file Sep 05 16:14:01 volumio volumio[16882]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227 Sep 05 16:14:01 volumio volumio[16882]: info: Discovery: Found device Volumio Sep 05 16:14:01 volumio volumio[16882]: info: CoreCommandRouter::volumioGetState Sep 05 16:14:01 volumio volumio[16882]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:01 volumio volumio[16882]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 05 16:14:01 volumio volumio[16882]: info: Reading ALSA contributions from plugins. Sep 05 16:14:01 volumio volumio[16882]: info: Asound.conf file unchanged, so no further update is needed Sep 05 16:14:01 volumio volumio[16882]: info: Output device has changed, restarting MPD Sep 05 16:14:01 volumio volumio[16882]: info: ___________ START PLUGINS ___________ Sep 05 16:14:01 volumio sudo[16984]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 16:14:01 volumio sudo[16984]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:01 volumio volumio[16882]: info: ControllerMpd::onStart: Initializing MPD Sep 05 16:14:01 volumio sudo[16984]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:01 volumio volumio[16882]: info: Creating MPD Configuration file Sep 05 16:14:01 volumio sudo[16990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 16:14:01 volumio sudo[16990]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:01 volumio systemd[1]: Stopping Music Player Daemon... Sep 05 16:14:01 volumio sudo[17003]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 16:14:01 volumio sudo[17003]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:01 volumio sudo[17003]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:01 volumio volumio[16882]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 16:14:01 volumio volumio[16882]: info: [1757081641517] CoreMusicLibrary::Adding element Last_100 Sep 05 16:14:01 volumio systemd[1]: mpd.service: Succeeded. Sep 05 16:14:01 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:14:01 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 16:14:01 volumio volumio[16882]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 16:14:01 volumio volumio[16882]: info: [1757081641525] CoreMusicLibrary::Adding element Webradio Sep 05 16:14:01 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:14:01 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 16:14:01 volumio volumio[16882]: info: Initializing BBC Radios Sep 05 16:14:01 volumio sudo[17012]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 16:14:01 volumio systemd[1]: Starting Music Player Daemon... Sep 05 16:14:01 volumio sudo[17012]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:01 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 05 16:14:01 volumio systemd[1]: mpd.service: Succeeded. Sep 05 16:14:01 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 16:14:01 volumio systemd[1]: Starting Music Player Daemon... Sep 05 16:14:01 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 16:14:01 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:14:01 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 16:14:01 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 05 16:14:01 volumio volumio[16882]: info: Discovery: Getting this device information Sep 05 16:14:01 volumio volumio[16882]: info: CoreCommandRouter::volumioGetState Sep 05 16:14:01 volumio volumio[16882]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:01 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 16:14:01 volumio volumio[16882]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 16:14:01 volumio volumio[16882]: info: [1757081641680] CoreMusicLibrary::Adding element Jellyfin Sep 05 16:14:01 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:14:01 volumio volumio[16882]: Cannot find translation for source Jellyfin Sep 05 16:14:01 volumio volumio[16882]: info: [jellyfin] Initialized plugin with device info: {"id":"efb65fd7-7648-487c-b3ba-ebfb5cc10227","host":"http://192.168.0.171","name":"Volumio","type":"device","serviceName":"Volumio","state":{"status":"stop","volume":37,"mute":false,"artist":"","track":"","albumart":"http://192.168.0.171/albumart"}} Sep 05 16:14:01 volumio volumio[16882]: info: Creating Spotify config file Sep 05 16:14:01 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:01 volumio sudo[17022]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 16:14:01 volumio sudo[17022]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:01 volumio sudo[17022]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:01 volumio volumio[16882]: info: Loading i18n strings for locale hu Sep 05 16:14:01 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 05 16:14:01 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:01 volumio volumio[16882]: info: FusionDsp - mixtype--------------------- Hardware Sep 05 16:14:01 volumio volumio[16882]: info: Volumio Calling Home Sep 05 16:14:01 volumio volumio[16882]: info: Preparing to generate the ALSA configuration file Sep 05 16:14:02 volumio volumio[16882]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 05 16:14:02 volumio volumio[16882]: info: Reading ALSA contributions from plugins. Sep 05 16:14:02 volumio volumio[16882]: info: MPD Permissions set Sep 05 16:14:02 volumio volumio[16882]: info: MPD Permissions set Sep 05 16:14:02 volumio volumio[16882]: info: VolumeController:: Volume=37 Mute =false Sep 05 16:14:02 volumio volumio[16882]: info: CoreCommandRouter::volumioGetState Sep 05 16:14:02 volumio volumio[16882]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:02 volumio volumio[16882]: info: CoreStateMachine::pushState Sep 05 16:14:02 volumio volumio[16882]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:02 volumio volumio[16882]: info: CoreCommandRouter::volumioPushState Sep 05 16:14:02 volumio volumio[16882]: info: CoreCommandRouter::volumioGetState Sep 05 16:14:02 volumio volumio[16882]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:02 volumio volumio[16882]: info: Volumio called home Sep 05 16:14:02 volumio volumio[16882]: info: Spotify config file written Sep 05 16:14:02 volumio volumio[16882]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Sep 05 16:14:02 volumio sudo[17033]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 05 16:14:02 volumio sudo[17033]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:02 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:02 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:02 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:02 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:02 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:02 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:02 volumio systemd[1]: Stopping go-librespot Daemon... Sep 05 16:14:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 05 16:14:02 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:14:02 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:02 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 05 16:14:02 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:02 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:02 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:02 volumio systemd[1]: Stopped go-librespot Daemon. Sep 05 16:14:02 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:02 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:02 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:14:02 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:02 volumio volumio[16882]: info: No need to fix Spotify hosts Sep 05 16:14:02 volumio systemd[1]: Started go-librespot Daemon. Sep 05 16:14:02 volumio go-librespot[17053]: go-librespot daemon starting... Sep 05 16:14:02 volumio sudo[17033]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:02 volumio go-librespot[17053]: time="2025-09-05T16:14:02+02:00" level=info msg="running go-librespot 0.3.2" Sep 05 16:14:02 volumio go-librespot[17053]: time="2025-09-05T16:14:02+02:00" level=debug msg="app state loaded" Sep 05 16:14:02 volumio go-librespot[17053]: time="2025-09-05T16:14:02+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 05 16:14:02 volumio sudo[17066]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 05 16:14:02 volumio sudo[17066]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:02 volumio sudo[17066]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:02 volumio sudo[17069]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 05 16:14:02 volumio sudo[17069]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:02 volumio sudo[17069]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:02 volumio go-librespot[17053]: time="2025-09-05T16:14:02+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 05 16:14:02 volumio go-librespot[17053]: time="2025-09-05T16:14:02+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 05 16:14:02 volumio go-librespot[17053]: time="2025-09-05T16:14:02+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 05 16:14:02 volumio go-librespot[17053]: time="2025-09-05T16:14:02+02:00" level=info msg="zeroconf server listening on port 39043" Sep 05 16:14:02 volumio volumio[16882]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 05 16:14:02 volumio volumio[16882]: SPOTIFY: BQA2ZTbwoX9Y_TU3Z8KlvfIqgZpAsj7bDJ4BbYDbWT6JwCjlX76yTQkIRyZZmMEtec813peebSqCWPm5LbVLSf5UjyWjZ8gyrYlcnw4dDTwyeA6MqnJJQv_wfrRivQ598lX14lG3WFuu4x8GcCdEL4ATkeDfCdV4EFOq_siGcglCiPODWFyIVXv6_1lwWTaDRWSHMJuhdUQSwW4aHGXu-zo4NeyKMxYS0aWn1q9dO3UJyesnfSxWcWKLsTLSLHVL1OD5IgGKTKajdOhrlKukrHfWrwFiFe7RgIZEuwxjN12aKIM Sep 05 16:14:02 volumio volumio[16882]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 05 16:14:02 volumio volumio[16882]: info: New Spotify access token = BQA2ZTbwoX9Y_TU3Z8KlvfIqgZpAsj7bDJ4BbYDbWT6JwCjlX76yTQkIRyZZmMEtec813peebSqCWPm5LbVLSf5UjyWjZ8gyrYlcnw4dDTwyeA6MqnJJQv_wfrRivQ598lX14lG3WFuu4x8GcCdEL4ATkeDfCdV4EFOq_siGcglCiPODWFyIVXv6_1lwWTaDRWSHMJuhdUQSwW4aHGXu-zo4NeyKMxYS0aWn1q9dO3UJyesnfSxWcWKLsTLSLHVL1OD5IgGKTKajdOhrlKukrHfWrwFiFe7RgIZEuwxjN12aKIM Sep 05 16:14:02 volumio volumio[16882]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 05 16:14:02 volumio go-librespot[17053]: time="2025-09-05T16:14:02+02:00" level=debug msg="obtained new client token: AADCWgCg9sXJ7RfZQCjkIDcWdzBBEJfWpOeo/B//CJEM9B5E6mUs6cTrigzIrgsFqo5e5WJuJrojrQ+bH9YUKZF1139RH6r7dMnm4q7cKzBeYk3QrSx2WktN4m8ajOFsnHXzni1zx9zMQyVL+54P1MNuixk9Wzu4Zx5/oy6WMq0TKN9vlW5OIjmW+Z02wGNVybuSFpxtY9sNjsWnHO2JuGfSLmR68eJeRR2Yj8Ir63M0xX3kKArP3rqMcf4=" Sep 05 16:14:02 volumio volumio[16882]: info: CoreCommandRouter::volumioGetState Sep 05 16:14:02 volumio volumio[16882]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:02 volumio go-librespot[17053]: time="2025-09-05T16:14:02+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 05 16:14:02 volumio volumio[16882]: info: Asound.conf file unchanged, so no further update is needed Sep 05 16:14:02 volumio volumio[16882]: info: Output device has changed, restarting MPD Sep 05 16:14:02 volumio go-librespot[17053]: time="2025-09-05T16:14:02+02:00" level=debug msg="completed keyexchange" Sep 05 16:14:02 volumio go-librespot[17053]: time="2025-09-05T16:14:02+02:00" level=debug msg="completed challenge" Sep 05 16:14:02 volumio sudo[17072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 16:14:02 volumio sudo[17072]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:02 volumio sudo[17072]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:02 volumio go-librespot[17053]: time="2025-09-05T16:14:02+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 05 16:14:02 volumio sudo[17074]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 16:14:02 volumio sudo[17074]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:02 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 05 16:14:02 volumio systemd[1]: mpd.service: Succeeded. Sep 05 16:14:02 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 16:14:02 volumio volumio[16882]: info: MPD Permissions set Sep 05 16:14:02 volumio go-librespot[17053]: time="2025-09-05T16:14:02+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 05 16:14:02 volumio go-librespot[17053]: time="2025-09-05T16:14:02+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 05 16:14:02 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:02 volumio systemd[1]: Starting Music Player Daemon... Sep 05 16:14:02 volumio sudo[17080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 05 16:14:02 volumio sudo[17080]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:02 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:14:02 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:03 volumio sudo[17080]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:03 volumio volumio[16882]: info: Upmpdcli Daemon Started Sep 05 16:14:03 volumio volumio[16882]: SPOTIFY: User informations: {"country":"HU","display_name":"4Misi","email":"sztako.mihaly@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/lo8jr34h0xh1vbcv2lgmaqkvv"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/lo8jr34h0xh1vbcv2lgmaqkvv","id":"lo8jr34h0xh1vbcv2lgmaqkvv","images":[],"product":"premium","type":"user","uri":"spotify:user:lo8jr34h0xh1vbcv2lgmaqkvv"} Sep 05 16:14:03 volumio volumio[16882]: info: Spotify Successfully logged in Sep 05 16:14:03 volumio volumio[16882]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 16:14:03 volumio volumio[16882]: info: [1757081643036] CoreMusicLibrary::Adding element Spotify Sep 05 16:14:03 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:14:03 volumio volumio[16882]: Cannot find translation for source Jellyfin Sep 05 16:14:03 volumio volumio[16882]: Cannot find translation for source Spotify Sep 05 16:14:03 volumio go-librespot[17053]: time="2025-09-05T16:14:03+02:00" level=debug msg="dealer connection opened" Sep 05 16:14:03 volumio go-librespot[17053]: time="2025-09-05T16:14:03+02:00" level=trace msg="starting accesspoint recv loop" Sep 05 16:14:03 volumio go-librespot[17053]: time="2025-09-05T16:14:03+02:00" level=trace msg="starting dealer recv loop" Sep 05 16:14:03 volumio go-librespot[17053]: time="2025-09-05T16:14:03+02:00" level=trace msg="received accesspoint ping" Sep 05 16:14:03 volumio go-librespot[17053]: time="2025-09-05T16:14:03+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 05 16:14:03 volumio go-librespot[17053]: time="2025-09-05T16:14:03+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 05 16:14:03 volumio go-librespot[17053]: time="2025-09-05T16:14:03+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 05 16:14:03 volumio go-librespot[17053]: time="2025-09-05T16:14:03+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 05 16:14:03 volumio go-librespot[17053]: time="2025-09-05T16:14:03+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 05 16:14:03 volumio go-librespot[17053]: time="2025-09-05T16:14:03+02:00" level=debug msg="received connection id: YzMwZTExNDMtZWJk...NjY5ODcwNTY2OQ==" Sep 05 16:14:03 volumio go-librespot[17053]: time="2025-09-05T16:14:03+02:00" level=trace msg="received accesspoint pong ack" Sep 05 16:14:03 volumio sudo[17078]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 16:14:03 volumio sudo[17078]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:03 volumio sudo[17078]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:03 volumio go-librespot[17053]: time="2025-09-05T16:14:03+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 05 16:14:03 volumio volumio[16882]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 05 16:14:03 volumio volumio[16882]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 05 16:14:03 volumio volumio[16882]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Sep 05 16:14:03 volumio volumio[16882]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 05 16:14:03 volumio volumio[16882]: info: camilladsp service started and running in background, instance 1 Sep 05 16:14:03 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:03 volumio volumio[16882]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 05 16:14:03 volumio volumio[16882]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 05 16:14:03 volumio volumio[16882]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 05 16:14:03 volumio volumio[16882]: info: FusionDsp loaded Sep 05 16:14:03 volumio volumio[16882]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 16:14:04 volumio sudo[17098]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 05 16:14:04 volumio sudo[17098]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:04 volumio volumio[16882]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 05 16:14:04 volumio volumio[16882]: info: Adding Signal Path Element [object Object] Sep 05 16:14:04 volumio volumio[16882]: info: Adding fusiondspeq DSP Signal Path Element Sep 05 16:14:04 volumio volumio[16882]: info: FusionDsp - ---- installed callbackRead Sep 05 16:14:04 volumio sudo[17098]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:04 volumio volumio[16882]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 16:14:04 volumio volumio[16882]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 05 16:14:04 volumio volumio[16882]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 05 16:14:04 volumio volumio[16882]: at onErrorNT (internal/child_process.js:465:16) Sep 05 16:14:04 volumio volumio[16882]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Sep 05 16:14:04 volumio volumio[16882]: errno: -2, Sep 05 16:14:04 volumio volumio[16882]: code: 'ENOENT', Sep 05 16:14:04 volumio volumio[16882]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 05 16:14:04 volumio volumio[16882]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 05 16:14:04 volumio volumio[16882]: spawnargs: [ Sep 05 16:14:04 volumio volumio[16882]: '-p', Sep 05 16:14:04 volumio volumio[16882]: 9876, Sep 05 16:14:04 volumio volumio[16882]: '-o', Sep 05 16:14:04 volumio volumio[16882]: '/tmp/camilladsp.log', Sep 05 16:14:04 volumio volumio[16882]: '-l', Sep 05 16:14:04 volumio volumio[16882]: 'warn', Sep 05 16:14:04 volumio volumio[16882]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 05 16:14:04 volumio volumio[16882]: ] Sep 05 16:14:04 volumio volumio[16882]: } Sep 05 16:14:04 volumio volumio[16882]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 16:14:04 volumio sudo[17109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-05 16:13 Sep 05 16:14:04 volumio sudo[17109]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:04 volumio mpd[17087]: Sep 05 16:14 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 05 16:14:04 volumio systemd[1]: Started Music Player Daemon. Sep 05 16:14:04 volumio sudo[17012]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:04 volumio sudo[16990]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:04 volumio sudo[17074]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:04 volumio sudo[17109]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:04 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 05 16:14:04 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 05 16:14:05 volumio systemd[1]: Started dynamicswap service. Sep 05 16:14:05 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 05 16:14:05 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 05 16:14:05 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 18971. Sep 05 16:14:05 volumio systemd[1]: Stopped Volumio Backend Module. Sep 05 16:14:05 volumio systemd[1]: Started Volumio Backend Module. Sep 05 16:14:05 volumio systemd[1]: Started dynamicswap service. Sep 05 16:14:05 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 05 16:14:05 volumio volumio-remote-updater[504]: [2025-09-05 16:14:05] [info] asio async_connect error: system:111 (Connection refused) Sep 05 16:14:05 volumio volumio-remote-updater[504]: [2025-09-05 16:14:05] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 05 16:14:05 volumio volumio-remote-updater[504]: [2025-09-05 16:14:05] [error] handle_connect error: Underlying Transport Error Sep 05 16:14:06 volumio volumio[17121]: info: ------------------------------------------- Sep 05 16:14:06 volumio volumio[17121]: info: ----- Volumio3 ---- Sep 05 16:14:06 volumio volumio[17121]: info: ------------------------------------------- Sep 05 16:14:06 volumio volumio[17121]: info: ----- System startup ---- Sep 05 16:14:06 volumio volumio[17121]: info: ------------------------------------------- Sep 05 16:14:07 volumio volumio[17121]: info: MYVOLUMIO Environment detected Sep 05 16:14:07 volumio volumio[17121]: info: Plugin folders cleanup Sep 05 16:14:07 volumio volumio[17121]: info: Scanning into folder /volumio/app/plugins/ Sep 05 16:14:07 volumio volumio[17121]: info: Scanning category audio_interface Sep 05 16:14:07 volumio volumio[17121]: info: Scanning category miscellanea Sep 05 16:14:07 volumio volumio[17121]: info: Scanning category music_service Sep 05 16:14:07 volumio volumio[17121]: info: Scanning category plugins.json Sep 05 16:14:07 volumio volumio[17121]: info: Scanning category system_controller Sep 05 16:14:07 volumio volumio[17121]: info: Scanning category user_interface Sep 05 16:14:07 volumio volumio[17121]: info: Scanning into folder /data/plugins/ Sep 05 16:14:07 volumio volumio[17121]: info: Scanning category audio_interface Sep 05 16:14:07 volumio volumio[17121]: info: Scanning category music_service Sep 05 16:14:07 volumio volumio[17121]: info: Scanning category user_interface Sep 05 16:14:07 volumio volumio[17121]: info: Plugin folders cleanup completed Sep 05 16:14:07 volumio volumio[17121]: info: ------------------------------------------- Sep 05 16:14:07 volumio volumio[17121]: info: ----- Core plugins startup ---- Sep 05 16:14:07 volumio volumio[17121]: info: ------------------------------------------- Sep 05 16:14:07 volumio volumio[17121]: info: Loading plugins from folder /volumio/app/plugins/ Sep 05 16:14:07 volumio volumio[17121]: info: Adding plugin upnp to MyMusic Plugins Sep 05 16:14:07 volumio volumio[17121]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 05 16:14:07 volumio volumio[17121]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 05 16:14:07 volumio volumio[17121]: info: Loading plugins from folder /data/plugins/ Sep 05 16:14:07 volumio volumio[17121]: info: Loading plugin "system"... Sep 05 16:14:07 volumio volumio[17121]: info: Loading plugin "appearance"... Sep 05 16:14:08 volumio volumio[17121]: info: Loading plugin "network"... Sep 05 16:14:08 volumio volumio[17121]: info: Refreshing Cached IP Addresses Sep 05 16:14:08 volumio sudo[17149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 05 16:14:08 volumio sudo[17149]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:08 volumio sudo[17149]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:08 volumio sudo[17151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 05 16:14:08 volumio volumio[17121]: info: Loading plugin "services"... Sep 05 16:14:08 volumio sudo[17151]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:08 volumio volumio[17121]: info: Loading plugin "alsa_controller"... Sep 05 16:14:08 volumio sudo[17151]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:09 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 16:14:09 volumio volumio[17121]: info: Loading plugin "wizard"... Sep 05 16:14:09 volumio volumio[17121]: info: Loading plugin "networkfs"... Sep 05 16:14:09 volumio volumio[17121]: info: Starting Udev Watcher for removable devices Sep 05 16:14:09 volumio volumio[17121]: info: Ignoring mount for partition: boot Sep 05 16:14:09 volumio volumio[17121]: info: Ignoring mount for partition: volumio Sep 05 16:14:09 volumio volumio[17121]: info: Ignoring mount for partition: volumio_data Sep 05 16:14:09 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 16:14:09 volumio volumio[17121]: info: Loading plugin "volumio_command_line_client"... Sep 05 16:14:09 volumio volumio[17121]: info: Loading plugin "upnp"... Sep 05 16:14:09 volumio volumio[17121]: info: [1757081649079] Starting Upmpd Daemon Sep 05 16:14:09 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 16:14:09 volumio volumio[17121]: info: Loading plugin "my_music"... Sep 05 16:14:09 volumio volumio[17121]: info: Loading plugin "mpd"... Sep 05 16:14:09 volumio volumio[17121]: info: Plugin upnp_browser is not enabled Sep 05 16:14:09 volumio volumio[17121]: info: Loading plugin "alarm-clock"... Sep 05 16:14:09 volumio volumio[17121]: info: Plugin airplay_emulation is not enabled Sep 05 16:14:09 volumio volumio[17121]: info: Loading plugin "last_100"... Sep 05 16:14:09 volumio volumio[17121]: info: Loading plugin "webradio"... Sep 05 16:14:09 volumio volumio[17121]: info: Loading plugin "i2s_dacs"... Sep 05 16:14:09 volumio volumio[17121]: info: Loading plugin "volumiodiscovery"... Sep 05 16:14:09 volumio volumio[17121]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 05 16:14:09 volumio volumio[17121]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 16:14:09 volumio volumio[17121]: *** WARNING *** For more information see Sep 05 16:14:09 volumio volumio[17121]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 05 16:14:09 volumio node[17121]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 05 16:14:09 volumio volumio[17121]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 16:14:09 volumio volumio[17121]: *** WARNING *** For more information see Sep 05 16:14:09 volumio node[17121]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 16:14:09 volumio node[17121]: *** WARNING *** For more information see Sep 05 16:14:09 volumio node[17121]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 05 16:14:09 volumio node[17121]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 16:14:09 volumio node[17121]: *** WARNING *** For more information see Sep 05 16:14:09 volumio volumio[17121]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 05 16:14:09 volumio volumio[17121]: info: Discovery: Started advertising with name: Volumio Sep 05 16:14:09 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 16:14:09 volumio volumio[17121]: info: Loading plugin "jellyfin"... Sep 05 16:14:10 volumio volumio[17121]: info: Loading plugin "spop"... Sep 05 16:14:11 volumio volumio[17121]: info: Loading plugin "ytcr"... Sep 05 16:14:15 volumio volumio-remote-updater[504]: [2025-09-05 16:14:15] [connect] Successful connection Sep 05 16:14:16 volumio volumio[17121]: info: Loading plugin "outputs"... Sep 05 16:14:16 volumio volumio[17121]: info: Loading plugin "albumart"... Sep 05 16:14:16 volumio volumio[17121]: info: Plugin example_plugin is not enabled Sep 05 16:14:16 volumio volumio[17121]: info: Loading plugin "inputs"... Sep 05 16:14:16 volumio volumio[17121]: info: Loading plugin "updater_comm"... Sep 05 16:14:16 volumio volumio[17121]: info: Plugin mpdemulation is not enabled Sep 05 16:14:16 volumio volumio[17121]: info: Loading plugin "rest_api"... Sep 05 16:14:16 volumio volumio[17121]: info: Loading plugin "websocket"... Sep 05 16:14:16 volumio volumio[17121]: info: Starting Socket.io Server version 2.3.0 Sep 05 16:14:16 volumio volumio[17121]: info: Loading plugin "fusiondsp"... Sep 05 16:14:16 volumio volumio[17121]: Forking 3 albumart workers Sep 05 16:14:16 volumio volumio[17121]: info: Applying required configuration parameters for plugin fusiondsp Sep 05 16:14:16 volumio volumio[17121]: info: Loading plugin "Systeminfo"... Sep 05 16:14:17 volumio volumio[17121]: info: Loading i18n strings for locale hu Sep 05 16:14:17 volumio volumio[17121]: Updating browse sources language Sep 05 16:14:17 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:14:17 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 16:14:17 volumio volumio[17121]: info: CoreCommandRouter::initPlayerControls Sep 05 16:14:17 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:17 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:17 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:17 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:17 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:17 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:17 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:17 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:17 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 16:14:17 volumio volumio[17121]: Express server listening on port 3000 Sep 05 16:14:17 volumio volumio[17121]: [Metrics] WebUI: 11s 15.49ms Sep 05 16:14:17 volumio volumio[17121]: info: CoreStateMachine::resetVolumioState Sep 05 16:14:17 volumio volumio[17121]: info: CoreStateMachine::getcurrentVolume Sep 05 16:14:17 volumio volumio[17121]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 16:14:17 volumio volumio[17121]: Starting albumart workers Sep 05 16:14:17 volumio volumio[17121]: Starting albumart workers Sep 05 16:14:17 volumio volumio[17121]: info: Volumio Network Manager: Network status updated: 1 Sep 05 16:14:17 volumio volumio[17121]: Starting albumart workers Sep 05 16:14:17 volumio volumio-remote-updater[504]: [2025-09-05 16:14:17] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1757081655 101 Sep 05 16:14:17 volumio volumio[17121]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Sep 05 16:14:17 volumio volumio[17121]: info: CoreCommandRouter::volumioGetState Sep 05 16:14:17 volumio volumio[17121]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:17 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:14:17 volumio volumio[17121]: info: VolumeController:: Volume=37 Mute =false Sep 05 16:14:17 volumio volumio[17121]: info: CoreStateMachine::pushState Sep 05 16:14:17 volumio volumio[17121]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:17 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 16:14:17 volumio volumio[17121]: info: CoreCommandRouter::volumioPushState Sep 05 16:14:17 volumio volumio[17121]: info: CoreStateMachine::updateTrackBlock Sep 05 16:14:17 volumio volumio[17121]: info: CorePlayQueue::getTrackBlock Sep 05 16:14:17 volumio volumio[17121]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 16:14:17 volumio volumio[17121]: info: CoreStateMachine::setRepeat false single undefined Sep 05 16:14:17 volumio volumio[17121]: info: CoreStateMachine::pushState Sep 05 16:14:17 volumio volumio[17121]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:17 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 16:14:17 volumio volumio[17121]: info: CoreCommandRouter::volumioPushState Sep 05 16:14:17 volumio volumio[17121]: info: CoreStateMachine::setRandom true Sep 05 16:14:17 volumio volumio[17121]: info: CoreStateMachine::pushState Sep 05 16:14:17 volumio volumio[17121]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:17 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 16:14:17 volumio volumio[17121]: info: CoreCommandRouter::volumioPushState Sep 05 16:14:17 volumio volumio[17121]: info: Setting Device type: Raspberry PI Sep 05 16:14:18 volumio volumio[17121]: info: Listing playlists Sep 05 16:14:18 volumio volumio[17121]: info: Listing playlists Sep 05 16:14:18 volumio volumio[17121]: info: Completed loading Core Plugins Sep 05 16:14:18 volumio volumio[17121]: info: Preparing to generate the ALSA configuration file Sep 05 16:14:18 volumio volumio[17121]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227 Sep 05 16:14:18 volumio volumio[17121]: info: Discovery: Found device Volumio Sep 05 16:14:18 volumio volumio[17121]: info: CoreCommandRouter::volumioGetState Sep 05 16:14:18 volumio volumio[17121]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:18 volumio volumio[17121]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 05 16:14:18 volumio volumio[17121]: info: Reading ALSA contributions from plugins. Sep 05 16:14:18 volumio volumio[17121]: info: VolumeController:: Volume=37 Mute =false Sep 05 16:14:18 volumio volumio[17121]: info: CoreStateMachine::pushState Sep 05 16:14:18 volumio volumio[17121]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:18 volumio volumio[17121]: info: CoreCommandRouter::volumioPushState Sep 05 16:14:18 volumio volumio[17121]: info: Asound.conf file unchanged, so no further update is needed Sep 05 16:14:18 volumio volumio[17121]: info: Output device has changed, restarting MPD Sep 05 16:14:18 volumio sudo[17236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 16:14:18 volumio volumio[17121]: info: ___________ START PLUGINS ___________ Sep 05 16:14:18 volumio sudo[17236]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:18 volumio sudo[17236]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:18 volumio volumio[17121]: info: ControllerMpd::onStart: Initializing MPD Sep 05 16:14:18 volumio volumio[17121]: info: Creating MPD Configuration file Sep 05 16:14:18 volumio sudo[17238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 16:14:18 volumio sudo[17238]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:18 volumio systemd[1]: Stopping Music Player Daemon... Sep 05 16:14:18 volumio sudo[17242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 16:14:18 volumio sudo[17242]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:18 volumio volumio[17121]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 16:14:18 volumio volumio[17121]: info: [1757081658374] CoreMusicLibrary::Adding element Last_100 Sep 05 16:14:18 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:14:18 volumio volumio[17121]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 16:14:18 volumio volumio[17121]: info: [1757081658382] CoreMusicLibrary::Adding element Webradio Sep 05 16:14:18 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:14:18 volumio sudo[17242]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:18 volumio systemd[1]: mpd.service: Succeeded. Sep 05 16:14:18 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 16:14:18 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 16:14:18 volumio systemd[1]: Starting Music Player Daemon... Sep 05 16:14:18 volumio volumio[17121]: info: Initializing BBC Radios Sep 05 16:14:18 volumio sudo[17244]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 16:14:18 volumio sudo[17244]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:18 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 05 16:14:18 volumio systemd[1]: mpd.service: Succeeded. Sep 05 16:14:18 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 16:14:18 volumio systemd[1]: Starting Music Player Daemon... Sep 05 16:14:18 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 16:14:18 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:14:18 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 16:14:18 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 05 16:14:18 volumio volumio[17121]: info: Discovery: Getting this device information Sep 05 16:14:18 volumio volumio[17121]: info: CoreCommandRouter::volumioGetState Sep 05 16:14:18 volumio volumio[17121]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:18 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 16:14:18 volumio volumio[17121]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 16:14:18 volumio volumio[17121]: info: [1757081658556] CoreMusicLibrary::Adding element Jellyfin Sep 05 16:14:18 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:14:18 volumio volumio[17121]: Cannot find translation for source Jellyfin Sep 05 16:14:18 volumio volumio[17121]: info: [jellyfin] Initialized plugin with device info: {"id":"efb65fd7-7648-487c-b3ba-ebfb5cc10227","host":"http://192.168.0.171","name":"Volumio","type":"device","serviceName":"Volumio","state":{"status":"stop","volume":37,"mute":false,"artist":"","track":"","albumart":"http://192.168.0.171/albumart"}} Sep 05 16:14:18 volumio volumio[17121]: info: Creating Spotify config file Sep 05 16:14:18 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:18 volumio sudo[17262]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 16:14:18 volumio sudo[17262]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:18 volumio sudo[17262]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:18 volumio volumio[17121]: info: Loading i18n strings for locale hu Sep 05 16:14:18 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 05 16:14:18 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:18 volumio volumio[17121]: info: FusionDsp - mixtype--------------------- Hardware Sep 05 16:14:18 volumio volumio[17121]: info: Volumio Calling Home Sep 05 16:14:18 volumio volumio[17121]: info: Preparing to generate the ALSA configuration file Sep 05 16:14:18 volumio volumio[17121]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 05 16:14:18 volumio volumio[17121]: info: Reading ALSA contributions from plugins. Sep 05 16:14:18 volumio volumio[17121]: info: MPD Permissions set Sep 05 16:14:18 volumio volumio[17121]: info: MPD Permissions set Sep 05 16:14:19 volumio volumio[17121]: info: Volumio called home Sep 05 16:14:19 volumio volumio[17121]: info: Spotify config file written Sep 05 16:14:19 volumio sudo[17287]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 05 16:14:19 volumio sudo[17287]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:19 volumio volumio[17121]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Sep 05 16:14:19 volumio systemd[1]: Stopping go-librespot Daemon... Sep 05 16:14:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 05 16:14:19 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 05 16:14:19 volumio systemd[1]: Stopped go-librespot Daemon. Sep 05 16:14:19 volumio sudo[17291]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 05 16:14:19 volumio sudo[17291]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:19 volumio systemd[1]: Started go-librespot Daemon. Sep 05 16:14:19 volumio go-librespot[17289]: go-librespot daemon starting... Sep 05 16:14:19 volumio sudo[17291]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:19 volumio sudo[17287]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=info msg="running go-librespot 0.3.2" Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=debug msg="app state loaded" Sep 05 16:14:19 volumio sudo[17294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 05 16:14:19 volumio sudo[17294]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:19 volumio sudo[17294]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:19 volumio volumio[17121]: info: No need to fix Spotify hosts Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=info msg="zeroconf server listening on port 33153" Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=debug msg="obtained new client token: AABMYmlHPxyxgUKBMpuWw4X21DjpOvy62KUK6JcRsCsykpOTU896N1NO+Ee8OVKOABalWsrF1iIv1Mhp4ansT30zlX5XzSBDA399F3wbmVJe6oAHYEje+9OffPQrgI3t3P1xxld0lqfuHrmrgmZxVFwUg1z0Zz3a9nzyv0hrWW3F0hpUYmDeQ7Tnb6w2MbZJmKmJe1oHA4e8t3UGpPvCk9fcUcgquL9ucDeMWhy3gbDeQIOrOiuEPwRabys=" Sep 05 16:14:19 volumio volumio[17121]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 05 16:14:19 volumio volumio[17121]: SPOTIFY: BQBnAD4bwWQK_MLe3ntsKdW6maxPKovjAF41ddTWcI6Hn5MX3Ux0Vs8vyfzQzrkRci2rV6SRI0q6oOjfzgbVzJflz7FSUA0ICFLJZBowygK8y4EMMVDGFI_dOXtVmegtkWBlbLJSNDycpmL9TzrbE83_KufkgVkWWCX4Grlpy1G1PIvizjITSjVQKfeKTlZCTqlWOXL7soxEmE8RMEuKMvZplmtr6UcdpUQO8zgh7nzuuIDwcxrZaTuGkFjygytTdO35X2QJs_OqGZkkQ7-PgUjOWYqfRwrv_It49vWM7mZCFyk Sep 05 16:14:19 volumio volumio[17121]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 05 16:14:19 volumio volumio[17121]: info: New Spotify access token = BQBnAD4bwWQK_MLe3ntsKdW6maxPKovjAF41ddTWcI6Hn5MX3Ux0Vs8vyfzQzrkRci2rV6SRI0q6oOjfzgbVzJflz7FSUA0ICFLJZBowygK8y4EMMVDGFI_dOXtVmegtkWBlbLJSNDycpmL9TzrbE83_KufkgVkWWCX4Grlpy1G1PIvizjITSjVQKfeKTlZCTqlWOXL7soxEmE8RMEuKMvZplmtr6UcdpUQO8zgh7nzuuIDwcxrZaTuGkFjygytTdO35X2QJs_OqGZkkQ7-PgUjOWYqfRwrv_It49vWM7mZCFyk Sep 05 16:14:19 volumio volumio[17121]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::volumioGetState Sep 05 16:14:19 volumio volumio[17121]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:19 volumio volumio[17121]: info: Asound.conf file unchanged, so no further update is needed Sep 05 16:14:19 volumio volumio[17121]: info: Output device has changed, restarting MPD Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=debug msg="completed keyexchange" Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=debug msg="completed challenge" Sep 05 16:14:19 volumio sudo[17314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 16:14:19 volumio sudo[17314]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 05 16:14:19 volumio sudo[17314]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:19 volumio sudo[17317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 16:14:19 volumio sudo[17317]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:19 volumio volumio[17121]: info: MPD Permissions set Sep 05 16:14:19 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 05 16:14:19 volumio systemd[1]: mpd.service: Succeeded. Sep 05 16:14:19 volumio sudo[17320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 05 16:14:19 volumio sudo[17320]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:19 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=debug msg="dealer connection opened" Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=trace msg="starting accesspoint recv loop" Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=trace msg="starting dealer recv loop" Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=trace msg="received accesspoint ping" Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 05 16:14:19 volumio systemd[1]: Starting Music Player Daemon... Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=debug msg="received connection id: YzlmYTEyOTItMGJi...ODlCMEY0MDFGMA==" Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=trace msg="received accesspoint pong ack" Sep 05 16:14:19 volumio sudo[17320]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:19 volumio volumio[17121]: info: Upmpdcli Daemon Started Sep 05 16:14:19 volumio volumio[17121]: SPOTIFY: User informations: {"country":"HU","display_name":"4Misi","email":"sztako.mihaly@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/lo8jr34h0xh1vbcv2lgmaqkvv"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/lo8jr34h0xh1vbcv2lgmaqkvv","id":"lo8jr34h0xh1vbcv2lgmaqkvv","images":[],"product":"premium","type":"user","uri":"spotify:user:lo8jr34h0xh1vbcv2lgmaqkvv"} Sep 05 16:14:19 volumio volumio[17121]: info: Spotify Successfully logged in Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 16:14:19 volumio volumio[17121]: info: [1757081659947] CoreMusicLibrary::Adding element Spotify Sep 05 16:14:19 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:14:19 volumio volumio[17121]: Cannot find translation for source Jellyfin Sep 05 16:14:19 volumio volumio[17121]: Cannot find translation for source Spotify Sep 05 16:14:19 volumio go-librespot[17289]: time="2025-09-05T16:14:19+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 05 16:14:19 volumio sudo[17322]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 16:14:19 volumio sudo[17322]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:19 volumio sudo[17322]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:20 volumio volumio[17121]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 05 16:14:20 volumio volumio[17121]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 16:14:20 volumio volumio[17121]: info: VolumeController:: Volume=37 Mute =false Sep 05 16:14:20 volumio volumio[17121]: info: CoreCommandRouter::volumioGetState Sep 05 16:14:20 volumio volumio[17121]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:20 volumio volumio[17121]: info: CoreStateMachine::pushState Sep 05 16:14:20 volumio volumio[17121]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:20 volumio volumio[17121]: info: CoreCommandRouter::volumioPushState Sep 05 16:14:20 volumio volumio[17121]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 05 16:14:20 volumio volumio[17121]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Sep 05 16:14:20 volumio volumio[17121]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 05 16:14:20 volumio volumio[17121]: info: camilladsp service started and running in background, instance 1 Sep 05 16:14:20 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:20 volumio volumio[17121]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 05 16:14:20 volumio volumio[17121]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 05 16:14:20 volumio volumio[17121]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 05 16:14:20 volumio volumio[17121]: info: FusionDsp loaded Sep 05 16:14:20 volumio volumio[17121]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 16:14:20 volumio sudo[17342]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 05 16:14:20 volumio sudo[17342]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:20 volumio volumio[17121]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 05 16:14:20 volumio volumio[17121]: info: Adding Signal Path Element [object Object] Sep 05 16:14:20 volumio volumio[17121]: info: Adding fusiondspeq DSP Signal Path Element Sep 05 16:14:20 volumio volumio[17121]: info: FusionDsp - ---- installed callbackRead Sep 05 16:14:20 volumio volumio[17121]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 16:14:20 volumio sudo[17342]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:20 volumio volumio[17121]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 05 16:14:20 volumio volumio[17121]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 05 16:14:20 volumio volumio[17121]: at onErrorNT (internal/child_process.js:465:16) Sep 05 16:14:20 volumio volumio[17121]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Sep 05 16:14:20 volumio volumio[17121]: errno: -2, Sep 05 16:14:20 volumio volumio[17121]: code: 'ENOENT', Sep 05 16:14:20 volumio volumio[17121]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 05 16:14:20 volumio volumio[17121]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 05 16:14:20 volumio volumio[17121]: spawnargs: [ Sep 05 16:14:20 volumio volumio[17121]: '-p', Sep 05 16:14:20 volumio volumio[17121]: 9876, Sep 05 16:14:20 volumio volumio[17121]: '-o', Sep 05 16:14:20 volumio volumio[17121]: '/tmp/camilladsp.log', Sep 05 16:14:20 volumio volumio[17121]: '-l', Sep 05 16:14:20 volumio volumio[17121]: 'warn', Sep 05 16:14:20 volumio volumio[17121]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 05 16:14:20 volumio volumio[17121]: ] Sep 05 16:14:20 volumio volumio[17121]: } Sep 05 16:14:20 volumio volumio[17121]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 16:14:21 volumio mpd[17329]: Sep 05 16:14 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 05 16:14:21 volumio systemd[1]: Started Music Player Daemon. Sep 05 16:14:21 volumio sudo[17353]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-05 16:13 Sep 05 16:14:21 volumio sudo[17353]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:21 volumio sudo[17244]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:21 volumio sudo[17238]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:21 volumio sudo[17317]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:21 volumio sudo[17353]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:21 volumio volumio-remote-updater[504]: [2025-09-05 16:14:21] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 05 16:14:21 volumio volumio-remote-updater[504]: [2025-09-05 16:14:21] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 05 16:14:21 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 05 16:14:21 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 05 16:14:21 volumio systemd[1]: Started dynamicswap service. Sep 05 16:14:21 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 05 16:14:22 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 05 16:14:22 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 18972. Sep 05 16:14:22 volumio systemd[1]: Stopped Volumio Backend Module. Sep 05 16:14:22 volumio systemd[1]: Started Volumio Backend Module. Sep 05 16:14:22 volumio systemd[1]: Started dynamicswap service. Sep 05 16:14:22 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 05 16:14:23 volumio volumio[17365]: info: ------------------------------------------- Sep 05 16:14:23 volumio volumio[17365]: info: ----- Volumio3 ---- Sep 05 16:14:23 volumio volumio[17365]: info: ------------------------------------------- Sep 05 16:14:23 volumio volumio[17365]: info: ----- System startup ---- Sep 05 16:14:23 volumio volumio[17365]: info: ------------------------------------------- Sep 05 16:14:24 volumio volumio[17365]: info: MYVOLUMIO Environment detected Sep 05 16:14:24 volumio volumio[17365]: info: Plugin folders cleanup Sep 05 16:14:24 volumio volumio[17365]: info: Scanning into folder /volumio/app/plugins/ Sep 05 16:14:24 volumio volumio[17365]: info: Scanning category audio_interface Sep 05 16:14:24 volumio volumio[17365]: info: Scanning category miscellanea Sep 05 16:14:24 volumio volumio[17365]: info: Scanning category music_service Sep 05 16:14:24 volumio volumio[17365]: info: Scanning category plugins.json Sep 05 16:14:24 volumio volumio[17365]: info: Scanning category system_controller Sep 05 16:14:24 volumio volumio[17365]: info: Scanning category user_interface Sep 05 16:14:24 volumio volumio[17365]: info: Scanning into folder /data/plugins/ Sep 05 16:14:24 volumio volumio[17365]: info: Scanning category audio_interface Sep 05 16:14:24 volumio volumio[17365]: info: Scanning category music_service Sep 05 16:14:24 volumio volumio[17365]: info: Scanning category user_interface Sep 05 16:14:24 volumio volumio[17365]: info: Plugin folders cleanup completed Sep 05 16:14:24 volumio volumio[17365]: info: ------------------------------------------- Sep 05 16:14:24 volumio volumio[17365]: info: ----- Core plugins startup ---- Sep 05 16:14:24 volumio volumio[17365]: info: ------------------------------------------- Sep 05 16:14:24 volumio volumio[17365]: info: Loading plugins from folder /volumio/app/plugins/ Sep 05 16:14:24 volumio volumio[17365]: info: Adding plugin upnp to MyMusic Plugins Sep 05 16:14:24 volumio volumio[17365]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 05 16:14:24 volumio volumio[17365]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 05 16:14:24 volumio volumio[17365]: info: Loading plugins from folder /data/plugins/ Sep 05 16:14:24 volumio volumio[17365]: info: Loading plugin "system"... Sep 05 16:14:24 volumio volumio[17365]: info: Loading plugin "appearance"... Sep 05 16:14:25 volumio volumio[17365]: info: Loading plugin "network"... Sep 05 16:14:25 volumio volumio[17365]: info: Refreshing Cached IP Addresses Sep 05 16:14:25 volumio sudo[17392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 05 16:14:25 volumio sudo[17392]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:25 volumio sudo[17392]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:25 volumio volumio[17365]: info: Loading plugin "services"... Sep 05 16:14:25 volumio sudo[17394]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 05 16:14:25 volumio sudo[17394]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:25 volumio volumio[17365]: info: Loading plugin "alsa_controller"... Sep 05 16:14:25 volumio sudo[17394]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:25 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 16:14:25 volumio volumio[17365]: info: Loading plugin "wizard"... Sep 05 16:14:25 volumio volumio[17365]: info: Loading plugin "networkfs"... Sep 05 16:14:26 volumio volumio[17365]: info: Starting Udev Watcher for removable devices Sep 05 16:14:26 volumio volumio[17365]: info: Ignoring mount for partition: boot Sep 05 16:14:26 volumio volumio[17365]: info: Ignoring mount for partition: volumio Sep 05 16:14:26 volumio volumio[17365]: info: Ignoring mount for partition: volumio_data Sep 05 16:14:26 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 16:14:26 volumio volumio[17365]: info: Loading plugin "volumio_command_line_client"... Sep 05 16:14:26 volumio volumio[17365]: info: Loading plugin "upnp"... Sep 05 16:14:26 volumio volumio[17365]: info: [1757081666048] Starting Upmpd Daemon Sep 05 16:14:26 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 16:14:26 volumio volumio[17365]: info: Loading plugin "my_music"... Sep 05 16:14:26 volumio volumio[17365]: info: Loading plugin "mpd"... Sep 05 16:14:26 volumio volumio[17365]: info: Plugin upnp_browser is not enabled Sep 05 16:14:26 volumio volumio[17365]: info: Loading plugin "alarm-clock"... Sep 05 16:14:26 volumio volumio[17365]: info: Plugin airplay_emulation is not enabled Sep 05 16:14:26 volumio volumio[17365]: info: Loading plugin "last_100"... Sep 05 16:14:26 volumio volumio[17365]: info: Loading plugin "webradio"... Sep 05 16:14:26 volumio volumio[17365]: info: Loading plugin "i2s_dacs"... Sep 05 16:14:26 volumio volumio[17365]: info: Loading plugin "volumiodiscovery"... Sep 05 16:14:26 volumio volumio[17365]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 05 16:14:26 volumio volumio[17365]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 16:14:26 volumio volumio[17365]: *** WARNING *** For more information see Sep 05 16:14:26 volumio node[17365]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 05 16:14:26 volumio volumio[17365]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 05 16:14:26 volumio volumio[17365]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 16:14:26 volumio volumio[17365]: *** WARNING *** For more information see Sep 05 16:14:26 volumio node[17365]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 16:14:26 volumio node[17365]: *** WARNING *** For more information see Sep 05 16:14:26 volumio node[17365]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 05 16:14:26 volumio node[17365]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 16:14:26 volumio node[17365]: *** WARNING *** For more information see Sep 05 16:14:26 volumio volumio[17365]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 05 16:14:26 volumio volumio[17365]: info: Discovery: Started advertising with name: Volumio Sep 05 16:14:26 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 16:14:26 volumio volumio[17365]: info: Loading plugin "jellyfin"... Sep 05 16:14:26 volumio volumio-remote-updater[504]: [2025-09-05 16:14:26] [connect] Successful connection Sep 05 16:14:27 volumio volumio[17365]: info: Loading plugin "spop"... Sep 05 16:14:28 volumio volumio[17365]: info: Loading plugin "ytcr"... Sep 05 16:14:33 volumio volumio[17365]: info: Loading plugin "outputs"... Sep 05 16:14:33 volumio volumio[17365]: info: Loading plugin "albumart"... Sep 05 16:14:33 volumio volumio[17365]: info: Plugin example_plugin is not enabled Sep 05 16:14:33 volumio volumio[17365]: info: Loading plugin "inputs"... Sep 05 16:14:33 volumio volumio[17365]: info: Loading plugin "updater_comm"... Sep 05 16:14:33 volumio volumio[17365]: info: Plugin mpdemulation is not enabled Sep 05 16:14:33 volumio volumio[17365]: info: Loading plugin "rest_api"... Sep 05 16:14:33 volumio volumio[17365]: info: Loading plugin "websocket"... Sep 05 16:14:33 volumio volumio[17365]: info: Starting Socket.io Server version 2.3.0 Sep 05 16:14:33 volumio volumio[17365]: info: Loading plugin "fusiondsp"... Sep 05 16:14:33 volumio volumio[17365]: Forking 3 albumart workers Sep 05 16:14:33 volumio volumio[17365]: info: Applying required configuration parameters for plugin fusiondsp Sep 05 16:14:33 volumio volumio[17365]: info: Loading plugin "Systeminfo"... Sep 05 16:14:34 volumio volumio[17365]: info: Loading i18n strings for locale hu Sep 05 16:14:34 volumio volumio[17365]: Updating browse sources language Sep 05 16:14:34 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:14:34 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 16:14:34 volumio volumio[17365]: info: CoreCommandRouter::initPlayerControls Sep 05 16:14:34 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:34 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:34 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:34 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:34 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:34 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:34 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:34 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:34 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 16:14:34 volumio volumio[17365]: Express server listening on port 3000 Sep 05 16:14:34 volumio volumio[17365]: [Metrics] WebUI: 11s 2.21ms Sep 05 16:14:34 volumio volumio[17365]: info: CoreStateMachine::resetVolumioState Sep 05 16:14:34 volumio volumio[17365]: info: CoreStateMachine::getcurrentVolume Sep 05 16:14:34 volumio volumio[17365]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 16:14:34 volumio volumio[17365]: Starting albumart workers Sep 05 16:14:34 volumio volumio[17365]: Starting albumart workers Sep 05 16:14:34 volumio volumio[17365]: info: Volumio Network Manager: Network status updated: 1 Sep 05 16:14:34 volumio volumio[17365]: Starting albumart workers Sep 05 16:14:34 volumio volumio[17365]: info: CoreCommandRouter::volumioGetState Sep 05 16:14:34 volumio volumio[17365]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:34 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:14:34 volumio volumio[17365]: info: VolumeController:: Volume=37 Mute =false Sep 05 16:14:34 volumio volumio[17365]: info: CoreStateMachine::pushState Sep 05 16:14:34 volumio volumio[17365]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:34 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 16:14:34 volumio volumio[17365]: info: CoreCommandRouter::volumioPushState Sep 05 16:14:34 volumio volumio[17365]: info: CoreStateMachine::updateTrackBlock Sep 05 16:14:34 volumio volumio[17365]: info: CorePlayQueue::getTrackBlock Sep 05 16:14:34 volumio volumio[17365]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 16:14:34 volumio volumio[17365]: info: CoreStateMachine::setRepeat false single undefined Sep 05 16:14:34 volumio volumio[17365]: info: CoreStateMachine::pushState Sep 05 16:14:34 volumio volumio[17365]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:34 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 16:14:34 volumio volumio[17365]: info: CoreCommandRouter::volumioPushState Sep 05 16:14:34 volumio volumio[17365]: info: CoreStateMachine::setRandom true Sep 05 16:14:34 volumio volumio[17365]: info: CoreStateMachine::pushState Sep 05 16:14:34 volumio volumio[17365]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:34 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 16:14:34 volumio volumio[17365]: info: CoreCommandRouter::volumioPushState Sep 05 16:14:34 volumio volumio[17365]: info: Setting Device type: Raspberry PI Sep 05 16:14:34 volumio volumio[17365]: info: Listing playlists Sep 05 16:14:34 volumio volumio[17365]: info: Listing playlists Sep 05 16:14:35 volumio volumio[17365]: info: Completed loading Core Plugins Sep 05 16:14:35 volumio volumio[17365]: info: Preparing to generate the ALSA configuration file Sep 05 16:14:35 volumio volumio[17365]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227 Sep 05 16:14:35 volumio volumio[17365]: info: Discovery: Found device Volumio Sep 05 16:14:35 volumio volumio[17365]: info: CoreCommandRouter::volumioGetState Sep 05 16:14:35 volumio volumio[17365]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:35 volumio volumio[17365]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 05 16:14:35 volumio volumio[17365]: info: Reading ALSA contributions from plugins. Sep 05 16:14:35 volumio volumio[17365]: info: VolumeController:: Volume=37 Mute =false Sep 05 16:14:35 volumio volumio[17365]: info: CoreStateMachine::pushState Sep 05 16:14:35 volumio volumio[17365]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:35 volumio volumio[17365]: info: CoreCommandRouter::volumioPushState Sep 05 16:14:35 volumio volumio[17365]: info: Asound.conf file unchanged, so no further update is needed Sep 05 16:14:35 volumio volumio[17365]: info: Output device has changed, restarting MPD Sep 05 16:14:35 volumio sudo[17479]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 16:14:35 volumio sudo[17479]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:35 volumio volumio[17365]: info: ___________ START PLUGINS ___________ Sep 05 16:14:35 volumio sudo[17479]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:35 volumio volumio[17365]: info: ControllerMpd::onStart: Initializing MPD Sep 05 16:14:35 volumio volumio[17365]: info: Creating MPD Configuration file Sep 05 16:14:35 volumio sudo[17490]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 16:14:35 volumio sudo[17490]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:35 volumio volumio[17365]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 16:14:35 volumio volumio[17365]: info: [1757081675354] CoreMusicLibrary::Adding element Last_100 Sep 05 16:14:35 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:14:35 volumio volumio[17365]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 16:14:35 volumio volumio[17365]: info: [1757081675360] CoreMusicLibrary::Adding element Webradio Sep 05 16:14:35 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:14:35 volumio sudo[17492]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 16:14:35 volumio sudo[17492]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:35 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 16:14:35 volumio volumio[17365]: info: Initializing BBC Radios Sep 05 16:14:35 volumio systemd[1]: Stopping Music Player Daemon... Sep 05 16:14:35 volumio sudo[17495]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 16:14:35 volumio sudo[17495]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:35 volumio sudo[17492]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:35 volumio systemd[1]: mpd.service: Succeeded. Sep 05 16:14:35 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 16:14:35 volumio systemd[1]: Starting Music Player Daemon... Sep 05 16:14:35 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 05 16:14:35 volumio systemd[1]: mpd.service: Succeeded. Sep 05 16:14:35 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 16:14:35 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 16:14:35 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:14:35 volumio systemd[1]: Starting Music Player Daemon... Sep 05 16:14:35 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 16:14:35 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 05 16:14:35 volumio volumio[17365]: info: Discovery: Getting this device information Sep 05 16:14:35 volumio volumio[17365]: info: CoreCommandRouter::volumioGetState Sep 05 16:14:35 volumio volumio[17365]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:35 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 16:14:35 volumio volumio[17365]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 16:14:35 volumio volumio[17365]: info: [1757081675508] CoreMusicLibrary::Adding element Jellyfin Sep 05 16:14:35 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:14:35 volumio volumio[17365]: Cannot find translation for source Jellyfin Sep 05 16:14:35 volumio volumio[17365]: info: [jellyfin] Initialized plugin with device info: {"id":"efb65fd7-7648-487c-b3ba-ebfb5cc10227","host":"http://192.168.0.171","name":"Volumio","type":"device","serviceName":"Volumio","state":{"status":"stop","volume":37,"mute":false,"artist":"","track":"","albumart":"http://192.168.0.171/albumart"}} Sep 05 16:14:35 volumio volumio[17365]: info: Creating Spotify config file Sep 05 16:14:35 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:35 volumio sudo[17509]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 16:14:35 volumio sudo[17509]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:35 volumio sudo[17509]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:35 volumio volumio[17365]: info: Loading i18n strings for locale hu Sep 05 16:14:35 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 05 16:14:35 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:35 volumio volumio[17365]: info: FusionDsp - mixtype--------------------- Hardware Sep 05 16:14:35 volumio volumio[17365]: info: Volumio Calling Home Sep 05 16:14:35 volumio volumio[17365]: info: Preparing to generate the ALSA configuration file Sep 05 16:14:35 volumio volumio[17365]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 05 16:14:35 volumio volumio[17365]: info: Reading ALSA contributions from plugins. Sep 05 16:14:35 volumio volumio[17365]: info: MPD Permissions set Sep 05 16:14:35 volumio volumio[17365]: info: MPD Permissions set Sep 05 16:14:35 volumio volumio[17365]: info: Spotify config file written Sep 05 16:14:36 volumio sudo[17530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 05 16:14:36 volumio sudo[17530]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:36 volumio volumio[17365]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Sep 05 16:14:36 volumio systemd[1]: Stopping go-librespot Daemon... Sep 05 16:14:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 05 16:14:36 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 05 16:14:36 volumio systemd[1]: Stopped go-librespot Daemon. Sep 05 16:14:36 volumio sudo[17534]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 05 16:14:36 volumio sudo[17534]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:36 volumio systemd[1]: Started go-librespot Daemon. Sep 05 16:14:36 volumio sudo[17534]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:36 volumio go-librespot[17533]: go-librespot daemon starting... Sep 05 16:14:36 volumio sudo[17530]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:36 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=info msg="running go-librespot 0.3.2" Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=debug msg="app state loaded" Sep 05 16:14:36 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 05 16:14:36 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:36 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:36 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:36 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:36 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:36 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:36 volumio sudo[17553]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 05 16:14:36 volumio sudo[17553]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:36 volumio sudo[17553]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:36 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:14:36 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:36 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:36 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:36 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:36 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:36 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:36 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:36 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:36 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:36 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:14:36 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:36 volumio volumio[17365]: info: No need to fix Spotify hosts Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=info msg="zeroconf server listening on port 38095" Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=debug msg="obtained new client token: AAA02iTxhQ7TXoaeDLE0prayE0N+Fi9my8wliy5T13VHAWn+3gG2eUm70mEmB2nrcsEnej8pJ0YYAyyD36/lkYW4PaCWPgbP6D7s4GZ8vJ3CqLY9FUFPLkmkZN7TGtCSe/8c2AErfiKTVAobgSjio6cDzc9EX9116qQXJSEOUp+3WeL8olYDh0+69DV7HoZBqCWEConIK2Rz2t/aUnBmm+VXRZOk9AAuRJcyzbcPJ/GYyYkZuSOd91WH1lw=" Sep 05 16:14:36 volumio volumio[17365]: info: Volumio called home Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=debug msg="completed keyexchange" Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=debug msg="completed challenge" Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 05 16:14:36 volumio volumio[17365]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 05 16:14:36 volumio volumio[17365]: SPOTIFY: BQCyUM5b4tg0fq3cWk7ma-xsHIkqo6s_6NrseKCQACZpU4V6S2rnyxneN1qO67350fuegrCIfI3PPT0DwiMTzhxDaNtrqLdTxSk3CD21coQntq1jada_eueGvHLutuLq83Twoe9mwW9UsaP1Cn4r-2FnYN4XIKdrrBv46Vlbk2eXFUOj-iKFh0P5lFxapaSRsa81D66A4l9Eoy5t1bweGM9RtTambjdeFLBCgz6xQk2TX1cwyI5LDq0mlfw_3otwgjmKp24wXf2m7NtQaiYG5zGwRft-59DMW5Ia1sbtR51tPuk Sep 05 16:14:36 volumio volumio[17365]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 05 16:14:36 volumio volumio[17365]: info: New Spotify access token = BQCyUM5b4tg0fq3cWk7ma-xsHIkqo6s_6NrseKCQACZpU4V6S2rnyxneN1qO67350fuegrCIfI3PPT0DwiMTzhxDaNtrqLdTxSk3CD21coQntq1jada_eueGvHLutuLq83Twoe9mwW9UsaP1Cn4r-2FnYN4XIKdrrBv46Vlbk2eXFUOj-iKFh0P5lFxapaSRsa81D66A4l9Eoy5t1bweGM9RtTambjdeFLBCgz6xQk2TX1cwyI5LDq0mlfw_3otwgjmKp24wXf2m7NtQaiYG5zGwRft-59DMW5Ia1sbtR51tPuk Sep 05 16:14:36 volumio volumio[17365]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 05 16:14:36 volumio volumio[17365]: info: CoreCommandRouter::volumioGetState Sep 05 16:14:36 volumio volumio[17365]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:36 volumio volumio[17365]: info: Asound.conf file unchanged, so no further update is needed Sep 05 16:14:36 volumio volumio[17365]: info: Output device has changed, restarting MPD Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 05 16:14:36 volumio sudo[17594]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 16:14:36 volumio sudo[17594]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:36 volumio sudo[17596]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 16:14:36 volumio sudo[17596]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=debug msg="dealer connection opened" Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=trace msg="starting accesspoint recv loop" Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=trace msg="starting dealer recv loop" Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=trace msg="received accesspoint ping" Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=debug msg="received connection id: Mzk3Y2JmNDMtMjAy...MjBBQTQ1OTg5Mw==" Sep 05 16:14:36 volumio sudo[17594]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:36 volumio go-librespot[17533]: time="2025-09-05T16:14:36+02:00" level=trace msg="received accesspoint pong ack" Sep 05 16:14:36 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 05 16:14:36 volumio systemd[1]: mpd.service: Succeeded. Sep 05 16:14:36 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 16:14:37 volumio volumio[17365]: info: MPD Permissions set Sep 05 16:14:37 volumio sudo[17601]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 05 16:14:37 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:37 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:14:37 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:37 volumio go-librespot[17533]: time="2025-09-05T16:14:37+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 05 16:14:37 volumio sudo[17601]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:37 volumio systemd[1]: Starting Music Player Daemon... Sep 05 16:14:37 volumio sudo[17601]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:37 volumio volumio[17365]: SPOTIFY: User informations: {"country":"HU","display_name":"4Misi","email":"sztako.mihaly@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/lo8jr34h0xh1vbcv2lgmaqkvv"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/lo8jr34h0xh1vbcv2lgmaqkvv","id":"lo8jr34h0xh1vbcv2lgmaqkvv","images":[],"product":"premium","type":"user","uri":"spotify:user:lo8jr34h0xh1vbcv2lgmaqkvv"} Sep 05 16:14:37 volumio volumio[17365]: info: Spotify Successfully logged in Sep 05 16:14:37 volumio volumio[17365]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 16:14:37 volumio volumio[17365]: info: [1757081677142] CoreMusicLibrary::Adding element Spotify Sep 05 16:14:37 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:14:37 volumio volumio[17365]: Cannot find translation for source Jellyfin Sep 05 16:14:37 volumio volumio[17365]: Cannot find translation for source Spotify Sep 05 16:14:37 volumio volumio[17365]: info: Upmpdcli Daemon Started Sep 05 16:14:37 volumio sudo[17602]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 16:14:37 volumio sudo[17602]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:37 volumio sudo[17602]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:37 volumio volumio[17365]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 05 16:14:37 volumio volumio[17365]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 16:14:37 volumio volumio[17365]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 05 16:14:37 volumio volumio[17365]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Sep 05 16:14:37 volumio volumio[17365]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 05 16:14:37 volumio volumio[17365]: info: camilladsp service started and running in background, instance 1 Sep 05 16:14:37 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:37 volumio volumio[17365]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 05 16:14:37 volumio volumio[17365]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 05 16:14:37 volumio volumio[17365]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 05 16:14:37 volumio volumio[17365]: info: FusionDsp loaded Sep 05 16:14:37 volumio volumio[17365]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 16:14:37 volumio sudo[17624]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 05 16:14:37 volumio sudo[17624]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:37 volumio volumio[17365]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 05 16:14:37 volumio volumio[17365]: info: Adding Signal Path Element [object Object] Sep 05 16:14:37 volumio volumio[17365]: info: Adding fusiondspeq DSP Signal Path Element Sep 05 16:14:37 volumio volumio[17365]: info: FusionDsp - ---- installed callbackRead Sep 05 16:14:37 volumio volumio[17365]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 16:14:37 volumio sudo[17624]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:37 volumio volumio[17365]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 05 16:14:37 volumio volumio[17365]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 05 16:14:37 volumio volumio[17365]: at onErrorNT (internal/child_process.js:465:16) Sep 05 16:14:37 volumio volumio[17365]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Sep 05 16:14:37 volumio volumio[17365]: at runNextTicks (internal/process/task_queues.js:62:3) Sep 05 16:14:37 volumio volumio[17365]: at listOnTimeout (internal/timers.js:523:9) Sep 05 16:14:37 volumio volumio[17365]: at processTimers (internal/timers.js:497:7) { Sep 05 16:14:37 volumio volumio[17365]: errno: -2, Sep 05 16:14:37 volumio volumio[17365]: code: 'ENOENT', Sep 05 16:14:37 volumio volumio[17365]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 05 16:14:37 volumio volumio[17365]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 05 16:14:37 volumio volumio[17365]: spawnargs: [ Sep 05 16:14:37 volumio volumio[17365]: '-p', Sep 05 16:14:37 volumio volumio[17365]: 9876, Sep 05 16:14:37 volumio volumio[17365]: '-o', Sep 05 16:14:37 volumio volumio[17365]: '/tmp/camilladsp.log', Sep 05 16:14:37 volumio volumio[17365]: '-l', Sep 05 16:14:37 volumio volumio[17365]: 'warn', Sep 05 16:14:37 volumio volumio[17365]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 05 16:14:37 volumio volumio[17365]: ] Sep 05 16:14:37 volumio volumio[17365]: } Sep 05 16:14:37 volumio volumio[17365]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 16:14:38 volumio sudo[17635]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-05 16:13 Sep 05 16:14:38 volumio sudo[17635]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:38 volumio sudo[17635]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:38 volumio mpd[17611]: Sep 05 16:14 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 05 16:14:38 volumio systemd[1]: Started Music Player Daemon. Sep 05 16:14:38 volumio sudo[17490]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:38 volumio sudo[17596]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:38 volumio sudo[17495]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:38 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 05 16:14:38 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 05 16:14:38 volumio systemd[1]: Started dynamicswap service. Sep 05 16:14:38 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 05 16:14:39 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 05 16:14:39 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 18973. Sep 05 16:14:39 volumio systemd[1]: Stopped Volumio Backend Module. Sep 05 16:14:39 volumio systemd[1]: Started Volumio Backend Module. Sep 05 16:14:39 volumio systemd[1]: Started dynamicswap service. Sep 05 16:14:39 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 05 16:14:39 volumio volumio-remote-updater[504]: [2025-09-05 16:14:39] [info] asio async_connect error: system:111 (Connection refused) Sep 05 16:14:39 volumio volumio-remote-updater[504]: [2025-09-05 16:14:39] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 05 16:14:39 volumio volumio-remote-updater[504]: [2025-09-05 16:14:39] [error] handle_connect error: Underlying Transport Error Sep 05 16:14:40 volumio volumio[17647]: info: ------------------------------------------- Sep 05 16:14:40 volumio volumio[17647]: info: ----- Volumio3 ---- Sep 05 16:14:40 volumio volumio[17647]: info: ------------------------------------------- Sep 05 16:14:40 volumio volumio[17647]: info: ----- System startup ---- Sep 05 16:14:40 volumio volumio[17647]: info: ------------------------------------------- Sep 05 16:14:41 volumio volumio[17647]: info: MYVOLUMIO Environment detected Sep 05 16:14:41 volumio volumio[17647]: info: Plugin folders cleanup Sep 05 16:14:41 volumio volumio[17647]: info: Scanning into folder /volumio/app/plugins/ Sep 05 16:14:41 volumio volumio[17647]: info: Scanning category audio_interface Sep 05 16:14:41 volumio volumio[17647]: info: Scanning category miscellanea Sep 05 16:14:41 volumio volumio[17647]: info: Scanning category music_service Sep 05 16:14:41 volumio volumio[17647]: info: Scanning category plugins.json Sep 05 16:14:41 volumio volumio[17647]: info: Scanning category system_controller Sep 05 16:14:41 volumio volumio[17647]: info: Scanning category user_interface Sep 05 16:14:41 volumio volumio[17647]: info: Scanning into folder /data/plugins/ Sep 05 16:14:41 volumio volumio[17647]: info: Scanning category audio_interface Sep 05 16:14:41 volumio volumio[17647]: info: Scanning category music_service Sep 05 16:14:41 volumio volumio[17647]: info: Scanning category user_interface Sep 05 16:14:41 volumio volumio[17647]: info: Plugin folders cleanup completed Sep 05 16:14:41 volumio volumio[17647]: info: ------------------------------------------- Sep 05 16:14:41 volumio volumio[17647]: info: ----- Core plugins startup ---- Sep 05 16:14:41 volumio volumio[17647]: info: ------------------------------------------- Sep 05 16:14:41 volumio volumio[17647]: info: Loading plugins from folder /volumio/app/plugins/ Sep 05 16:14:41 volumio volumio[17647]: info: Adding plugin upnp to MyMusic Plugins Sep 05 16:14:41 volumio volumio[17647]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 05 16:14:41 volumio volumio[17647]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 05 16:14:41 volumio volumio[17647]: info: Loading plugins from folder /data/plugins/ Sep 05 16:14:41 volumio volumio[17647]: info: Loading plugin "system"... Sep 05 16:14:41 volumio volumio[17647]: info: Loading plugin "appearance"... Sep 05 16:14:42 volumio volumio[17647]: info: Loading plugin "network"... Sep 05 16:14:42 volumio volumio[17647]: info: Refreshing Cached IP Addresses Sep 05 16:14:42 volumio sudo[17674]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 05 16:14:42 volumio sudo[17674]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:42 volumio sudo[17674]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:42 volumio sudo[17676]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 05 16:14:42 volumio volumio[17647]: info: Loading plugin "services"... Sep 05 16:14:42 volumio sudo[17676]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:42 volumio volumio[17647]: info: Loading plugin "alsa_controller"... Sep 05 16:14:42 volumio sudo[17676]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:43 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 16:14:43 volumio volumio[17647]: info: Loading plugin "wizard"... Sep 05 16:14:43 volumio volumio[17647]: info: Loading plugin "networkfs"... Sep 05 16:14:43 volumio volumio[17647]: info: Starting Udev Watcher for removable devices Sep 05 16:14:43 volumio volumio[17647]: info: Ignoring mount for partition: boot Sep 05 16:14:43 volumio volumio[17647]: info: Ignoring mount for partition: volumio Sep 05 16:14:43 volumio volumio[17647]: info: Ignoring mount for partition: volumio_data Sep 05 16:14:43 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 16:14:43 volumio volumio[17647]: info: Loading plugin "volumio_command_line_client"... Sep 05 16:14:43 volumio volumio[17647]: info: Loading plugin "upnp"... Sep 05 16:14:43 volumio volumio[17647]: info: [1757081683090] Starting Upmpd Daemon Sep 05 16:14:43 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 16:14:43 volumio volumio[17647]: info: Loading plugin "my_music"... Sep 05 16:14:43 volumio volumio[17647]: info: Loading plugin "mpd"... Sep 05 16:14:43 volumio volumio[17647]: info: Plugin upnp_browser is not enabled Sep 05 16:14:43 volumio volumio[17647]: info: Loading plugin "alarm-clock"... Sep 05 16:14:43 volumio volumio[17647]: info: Plugin airplay_emulation is not enabled Sep 05 16:14:43 volumio volumio[17647]: info: Loading plugin "last_100"... Sep 05 16:14:43 volumio volumio[17647]: info: Loading plugin "webradio"... Sep 05 16:14:43 volumio volumio[17647]: info: Loading plugin "i2s_dacs"... Sep 05 16:14:43 volumio volumio[17647]: info: Loading plugin "volumiodiscovery"... Sep 05 16:14:43 volumio volumio[17647]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 05 16:14:43 volumio volumio[17647]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 16:14:43 volumio volumio[17647]: *** WARNING *** For more information see Sep 05 16:14:43 volumio node[17647]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 05 16:14:43 volumio volumio[17647]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 05 16:14:43 volumio volumio[17647]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 16:14:43 volumio volumio[17647]: *** WARNING *** For more information see Sep 05 16:14:43 volumio node[17647]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 16:14:43 volumio node[17647]: *** WARNING *** For more information see Sep 05 16:14:43 volumio node[17647]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 05 16:14:43 volumio node[17647]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 05 16:14:43 volumio node[17647]: *** WARNING *** For more information see Sep 05 16:14:43 volumio volumio[17647]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 05 16:14:43 volumio volumio[17647]: info: Discovery: Started advertising with name: Volumio Sep 05 16:14:43 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 16:14:43 volumio volumio[17647]: info: Loading plugin "jellyfin"... Sep 05 16:14:44 volumio volumio[17647]: info: Loading plugin "spop"... Sep 05 16:14:45 volumio volumio[17647]: info: Loading plugin "ytcr"... Sep 05 16:14:49 volumio volumio-remote-updater[504]: [2025-09-05 16:14:49] [connect] Successful connection Sep 05 16:14:50 volumio volumio[17647]: info: Loading plugin "outputs"... Sep 05 16:14:50 volumio volumio[17647]: info: Loading plugin "albumart"... Sep 05 16:14:50 volumio volumio[17647]: info: Plugin example_plugin is not enabled Sep 05 16:14:50 volumio volumio[17647]: info: Loading plugin "inputs"... Sep 05 16:14:50 volumio volumio[17647]: info: Loading plugin "updater_comm"... Sep 05 16:14:50 volumio volumio[17647]: info: Plugin mpdemulation is not enabled Sep 05 16:14:50 volumio volumio[17647]: info: Loading plugin "rest_api"... Sep 05 16:14:50 volumio volumio[17647]: info: Loading plugin "websocket"... Sep 05 16:14:50 volumio volumio[17647]: info: Starting Socket.io Server version 2.3.0 Sep 05 16:14:50 volumio volumio[17647]: info: Loading plugin "fusiondsp"... Sep 05 16:14:50 volumio volumio[17647]: Forking 3 albumart workers Sep 05 16:14:50 volumio volumio[17647]: info: Applying required configuration parameters for plugin fusiondsp Sep 05 16:14:50 volumio volumio[17647]: info: Loading plugin "Systeminfo"... Sep 05 16:14:51 volumio volumio[17647]: info: Loading i18n strings for locale hu Sep 05 16:14:51 volumio volumio[17647]: Updating browse sources language Sep 05 16:14:51 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:14:51 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 16:14:51 volumio volumio[17647]: info: CoreCommandRouter::initPlayerControls Sep 05 16:14:51 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:51 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:51 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:51 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:51 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:51 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:51 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:51 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:51 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 16:14:51 volumio volumio[17647]: Express server listening on port 3000 Sep 05 16:14:51 volumio volumio[17647]: [Metrics] WebUI: 10s 997.66ms Sep 05 16:14:51 volumio volumio[17647]: info: CoreStateMachine::resetVolumioState Sep 05 16:14:51 volumio volumio[17647]: info: CoreStateMachine::getcurrentVolume Sep 05 16:14:51 volumio volumio[17647]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 16:14:51 volumio volumio[17647]: Starting albumart workers Sep 05 16:14:51 volumio volumio[17647]: Starting albumart workers Sep 05 16:14:51 volumio volumio[17647]: info: Volumio Network Manager: Network status updated: 1 Sep 05 16:14:51 volumio volumio[17647]: Starting albumart workers Sep 05 16:14:51 volumio volumio-remote-updater[504]: [2025-09-05 16:14:51] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1757081689 101 Sep 05 16:14:51 volumio volumio[17647]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Sep 05 16:14:51 volumio volumio[17647]: info: CoreCommandRouter::volumioGetState Sep 05 16:14:51 volumio volumio[17647]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:51 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:14:51 volumio volumio[17647]: info: VolumeController:: Volume=37 Mute =false Sep 05 16:14:51 volumio volumio[17647]: info: CoreStateMachine::pushState Sep 05 16:14:51 volumio volumio[17647]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:51 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 16:14:51 volumio volumio[17647]: info: CoreCommandRouter::volumioPushState Sep 05 16:14:51 volumio volumio[17647]: info: CoreStateMachine::updateTrackBlock Sep 05 16:14:51 volumio volumio[17647]: info: CorePlayQueue::getTrackBlock Sep 05 16:14:51 volumio volumio[17647]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 16:14:51 volumio volumio[17647]: info: CoreStateMachine::setRepeat false single undefined Sep 05 16:14:51 volumio volumio[17647]: info: CoreStateMachine::pushState Sep 05 16:14:51 volumio volumio[17647]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:51 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 16:14:51 volumio volumio[17647]: info: CoreCommandRouter::volumioPushState Sep 05 16:14:51 volumio volumio[17647]: info: CoreStateMachine::setRandom true Sep 05 16:14:51 volumio volumio[17647]: info: CoreStateMachine::pushState Sep 05 16:14:51 volumio volumio[17647]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:51 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 16:14:51 volumio volumio[17647]: info: CoreCommandRouter::volumioPushState Sep 05 16:14:51 volumio volumio[17647]: info: Setting Device type: Raspberry PI Sep 05 16:14:51 volumio volumio[17647]: info: Listing playlists Sep 05 16:14:51 volumio volumio[17647]: info: Listing playlists Sep 05 16:14:52 volumio volumio[17647]: info: Completed loading Core Plugins Sep 05 16:14:52 volumio volumio[17647]: info: Preparing to generate the ALSA configuration file Sep 05 16:14:52 volumio volumio[17647]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227 Sep 05 16:14:52 volumio volumio[17647]: info: Discovery: Found device Volumio Sep 05 16:14:52 volumio volumio[17647]: info: CoreCommandRouter::volumioGetState Sep 05 16:14:52 volumio volumio[17647]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:52 volumio volumio[17647]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 05 16:14:52 volumio volumio[17647]: info: Reading ALSA contributions from plugins. Sep 05 16:14:52 volumio volumio[17647]: info: VolumeController:: Volume=37 Mute =false Sep 05 16:14:52 volumio volumio[17647]: info: CoreStateMachine::pushState Sep 05 16:14:52 volumio volumio[17647]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:52 volumio volumio[17647]: info: CoreCommandRouter::volumioPushState Sep 05 16:14:52 volumio volumio[17647]: info: Asound.conf file unchanged, so no further update is needed Sep 05 16:14:52 volumio volumio[17647]: info: Output device has changed, restarting MPD Sep 05 16:14:52 volumio sudo[17754]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 16:14:52 volumio sudo[17754]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:52 volumio volumio[17647]: info: ___________ START PLUGINS ___________ Sep 05 16:14:52 volumio sudo[17754]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:52 volumio volumio[17647]: info: ControllerMpd::onStart: Initializing MPD Sep 05 16:14:52 volumio volumio[17647]: info: Creating MPD Configuration file Sep 05 16:14:52 volumio sudo[17758]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 16:14:52 volumio sudo[17758]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:52 volumio sudo[17764]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 16:14:52 volumio sudo[17764]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:52 volumio systemd[1]: Stopping Music Player Daemon... Sep 05 16:14:52 volumio sudo[17764]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:52 volumio volumio[17647]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 16:14:52 volumio volumio[17647]: info: [1757081692365] CoreMusicLibrary::Adding element Last_100 Sep 05 16:14:52 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:14:52 volumio volumio[17647]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 16:14:52 volumio volumio[17647]: info: [1757081692374] CoreMusicLibrary::Adding element Webradio Sep 05 16:14:52 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:14:52 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 16:14:52 volumio systemd[1]: mpd.service: Succeeded. Sep 05 16:14:52 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 16:14:52 volumio sudo[17771]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 16:14:52 volumio sudo[17771]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:52 volumio volumio[17647]: info: Initializing BBC Radios Sep 05 16:14:52 volumio systemd[1]: Starting Music Player Daemon... Sep 05 16:14:52 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 05 16:14:52 volumio systemd[1]: mpd.service: Succeeded. Sep 05 16:14:52 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 16:14:52 volumio systemd[1]: Starting Music Player Daemon... Sep 05 16:14:52 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 16:14:52 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:14:52 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 16:14:52 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 05 16:14:52 volumio volumio[17647]: info: Discovery: Getting this device information Sep 05 16:14:52 volumio volumio[17647]: info: CoreCommandRouter::volumioGetState Sep 05 16:14:52 volumio volumio[17647]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:52 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 16:14:52 volumio volumio[17647]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 16:14:52 volumio volumio[17647]: info: [1757081692529] CoreMusicLibrary::Adding element Jellyfin Sep 05 16:14:52 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:14:52 volumio volumio[17647]: Cannot find translation for source Jellyfin Sep 05 16:14:52 volumio volumio[17647]: info: [jellyfin] Initialized plugin with device info: {"id":"efb65fd7-7648-487c-b3ba-ebfb5cc10227","host":"http://192.168.0.171","name":"Volumio","type":"device","serviceName":"Volumio","state":{"status":"stop","volume":37,"mute":false,"artist":"","track":"","albumart":"http://192.168.0.171/albumart"}} Sep 05 16:14:52 volumio volumio[17647]: info: Creating Spotify config file Sep 05 16:14:52 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:52 volumio sudo[17784]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 16:14:52 volumio sudo[17784]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:52 volumio sudo[17784]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:52 volumio volumio[17647]: info: Loading i18n strings for locale hu Sep 05 16:14:52 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 05 16:14:52 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:52 volumio volumio[17647]: info: FusionDsp - mixtype--------------------- Hardware Sep 05 16:14:52 volumio volumio[17647]: info: Volumio Calling Home Sep 05 16:14:52 volumio volumio[17647]: info: Preparing to generate the ALSA configuration file Sep 05 16:14:52 volumio volumio[17647]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 05 16:14:52 volumio volumio[17647]: info: Reading ALSA contributions from plugins. Sep 05 16:14:52 volumio volumio[17647]: info: MPD Permissions set Sep 05 16:14:52 volumio volumio[17647]: info: MPD Permissions set Sep 05 16:14:52 volumio volumio[17647]: info: Spotify config file written Sep 05 16:14:53 volumio sudo[17809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 05 16:14:53 volumio sudo[17809]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:53 volumio volumio[17647]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Sep 05 16:14:53 volumio volumio[17647]: info: Volumio called home Sep 05 16:14:53 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:53 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:53 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:53 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:53 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:53 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:53 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:53 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:53 volumio systemd[1]: Stopping go-librespot Daemon... Sep 05 16:14:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 05 16:14:53 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 05 16:14:53 volumio systemd[1]: Stopped go-librespot Daemon. Sep 05 16:14:53 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:14:53 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:53 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:53 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:53 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:53 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:53 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:53 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:53 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:53 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:53 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:14:53 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:53 volumio volumio[17647]: info: No need to fix Spotify hosts Sep 05 16:14:53 volumio systemd[1]: Started go-librespot Daemon. Sep 05 16:14:53 volumio go-librespot[17812]: go-librespot daemon starting... Sep 05 16:14:53 volumio sudo[17809]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=info msg="running go-librespot 0.3.2" Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=debug msg="app state loaded" Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 05 16:14:53 volumio sudo[17827]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 05 16:14:53 volumio sudo[17827]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:53 volumio sudo[17830]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 05 16:14:53 volumio sudo[17830]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:53 volumio sudo[17827]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:53 volumio sudo[17830]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=info msg="zeroconf server listening on port 39013" Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=debug msg="obtained new client token: AABb8Nvzkinuywmix8VtTBcZq1KOuTsNFV/Ksr4HGSrbI9uEWSCeqDCL8IVyTfhBU3iCdNEmHv5YrJV44CnQKqrH/6b19qp4QW9XgQr1x178Xx8ShuO/AYg6PSQcNvbQkBVbXAPweXtceoEpAt6qnsvRtk9dOoETdSoIxoSWVo8MFKp/JwoMQWo180PMWLNAnDdCOQ3WRkXaEg/FrAG0VGeuESWc9QxEU+pPOCLBpksqKkaTmYumPRd3RJQ=" Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=debug msg="completed keyexchange" Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=debug msg="completed challenge" Sep 05 16:14:53 volumio volumio[17647]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 05 16:14:53 volumio volumio[17647]: SPOTIFY: BQDxvRm3Lz4EWF1MZQ_D8X_Fva4jdb3NlgM96zZZfEAwJN27oGNcogj75BvQ8M-bTBFryGVktY1N-8nbKdRAhLdL76G-JaqKIMCDuhI-u5qETC4o8i4SKeQqLl3eemCuCedO1rLG7OwkIsuq-_Mj1Bci3W0vnQCmimNTz63H_L1_yHEGVdxv0lbPu-bm_vq4ghiB5UGVlvm5GfOl0ImyDUOEqx0_gIyKGiATVQylrK3NXyIHIUuHJKaT-S6bOi_BPJfplUyZxzMIefSgVbH3bBdQ8yg35Vj-fhc8O-IpwCkDvhA Sep 05 16:14:53 volumio volumio[17647]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 05 16:14:53 volumio volumio[17647]: info: New Spotify access token = BQDxvRm3Lz4EWF1MZQ_D8X_Fva4jdb3NlgM96zZZfEAwJN27oGNcogj75BvQ8M-bTBFryGVktY1N-8nbKdRAhLdL76G-JaqKIMCDuhI-u5qETC4o8i4SKeQqLl3eemCuCedO1rLG7OwkIsuq-_Mj1Bci3W0vnQCmimNTz63H_L1_yHEGVdxv0lbPu-bm_vq4ghiB5UGVlvm5GfOl0ImyDUOEqx0_gIyKGiATVQylrK3NXyIHIUuHJKaT-S6bOi_BPJfplUyZxzMIefSgVbH3bBdQ8yg35Vj-fhc8O-IpwCkDvhA Sep 05 16:14:53 volumio volumio[17647]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 05 16:14:53 volumio volumio[17647]: info: CoreCommandRouter::volumioGetState Sep 05 16:14:53 volumio volumio[17647]: info: CorePlayQueue::getTrack 0 Sep 05 16:14:53 volumio volumio[17647]: info: Asound.conf file unchanged, so no further update is needed Sep 05 16:14:53 volumio volumio[17647]: info: Output device has changed, restarting MPD Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 05 16:14:53 volumio sudo[17834]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 16:14:53 volumio sudo[17834]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:53 volumio sudo[17834]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:53 volumio sudo[17836]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 16:14:53 volumio sudo[17836]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=debug msg="dealer connection opened" Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=trace msg="starting accesspoint recv loop" Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=trace msg="starting dealer recv loop" Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=trace msg="received accesspoint ping" Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=debug msg="received connection id: MDI0YWNjYzgtMGE5...MEU4NUIxMzRDOQ==" Sep 05 16:14:53 volumio volumio[17647]: info: MPD Permissions set Sep 05 16:14:53 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 05 16:14:53 volumio systemd[1]: mpd.service: Succeeded. Sep 05 16:14:53 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 16:14:53 volumio go-librespot[17812]: time="2025-09-05T16:14:53+02:00" level=trace msg="received accesspoint pong ack" Sep 05 16:14:53 volumio sudo[17842]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 05 16:14:53 volumio sudo[17842]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:53 volumio systemd[1]: Starting Music Player Daemon... Sep 05 16:14:54 volumio sudo[17842]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:54 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:54 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:54 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:54 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:54 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:54 volumio go-librespot[17812]: time="2025-09-05T16:14:54+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 05 16:14:54 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:54 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:54 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:54 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 16:14:54 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 16:14:54 volumio volumio[17647]: info: Upmpdcli Daemon Started Sep 05 16:14:54 volumio sudo[17841]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 16:14:54 volumio sudo[17841]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 16:14:54 volumio volumio[17647]: SPOTIFY: User informations: {"country":"HU","display_name":"4Misi","email":"sztako.mihaly@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/lo8jr34h0xh1vbcv2lgmaqkvv"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/lo8jr34h0xh1vbcv2lgmaqkvv","id":"lo8jr34h0xh1vbcv2lgmaqkvv","images":[],"product":"premium","type":"user","uri":"spotify:user:lo8jr34h0xh1vbcv2lgmaqkvv"} Sep 05 16:14:54 volumio volumio[17647]: info: Spotify Successfully logged in Sep 05 16:14:54 volumio sudo[17841]: pam_unix(sudo:session): session closed for user root Sep 05 16:14:54 volumio volumio[17647]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 05 16:14:54 volumio volumio[17647]: info: [1757081694116] CoreMusicLibrary::Adding element Spotify Sep 05 16:14:54 volumio volumio[17647]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 16:14:54 volumio volumio[17647]: Cannot find translation for source Jellyfin Sep 05 16:14:54 volumio volumio[17647]: Cannot find translation for source Spotify Sep 05 16:14:54 volumio volumio[17647]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 05 16:14:54 volumio volumio[17647]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 16:14:54 volumio volumio[17647]: Error: bind EADDRINUSE 192.168.0.171:50386 Sep 05 16:14:54 volumio volumio[17647]: at dgram.js:337:20 Sep 05 16:14:54 volumio volumio[17647]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Sep 05 16:14:54 volumio volumio[17647]: errno: -98, Sep 05 16:14:54 volumio volumio[17647]: code: 'EADDRINUSE', Sep 05 16:14:54 volumio volumio[17647]: syscall: 'bind', Sep 05 16:14:54 volumio volumio[17647]: address: '192.168.0.171', Sep 05 16:14:54 volumio volumio[17647]: port: 50386 Sep 05 16:14:54 volumio volumio[17647]: } Sep 05 16:14:54 volumio volumio[17647]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 16:14:55 volumio sudo[17862]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-05 16:13 Sep 05 16:14:55 volumio sudo[17862]: pam_unix(sudo:session): session opened for user root by (uid=0) 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"