-- Logs begin at Wed 2025-09-03 14:20:19 CEST, end at Wed 2025-09-03 14:50:30 CEST. -- Sep 03 14:49:00 volumio volumio[26313]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 03 14:49:00 volumio volumio[26313]: 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 03 14:49:00 volumio volumio[26313]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 03 14:49:00 volumio volumio[26313]: info: camilladsp service started and running in background, instance 1 Sep 03 14:49:00 volumio volumio[26313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:00 volumio volumio[26313]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 03 14:49:00 volumio volumio[26313]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 03 14:49:00 volumio volumio[26313]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 03 14:49:00 volumio volumio[26313]: info: FusionDsp loaded Sep 03 14:49:00 volumio volumio[26313]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 14:49:00 volumio sudo[26528]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 03 14:49:00 volumio sudo[26528]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:00 volumio volumio[26313]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 03 14:49:00 volumio volumio[26313]: info: Adding Signal Path Element [object Object] Sep 03 14:49:00 volumio volumio[26313]: info: Adding fusiondspeq DSP Signal Path Element Sep 03 14:49:00 volumio volumio[26313]: info: FusionDsp - ---- installed callbackRead Sep 03 14:49:00 volumio sudo[26528]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:00 volumio volumio[26313]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 14:49:00 volumio volumio[26313]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 03 14:49:00 volumio volumio[26313]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 03 14:49:00 volumio volumio[26313]: at onErrorNT (internal/child_process.js:465:16) Sep 03 14:49:00 volumio volumio[26313]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Sep 03 14:49:00 volumio volumio[26313]: errno: -2, Sep 03 14:49:00 volumio volumio[26313]: code: 'ENOENT', Sep 03 14:49:00 volumio volumio[26313]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 03 14:49:00 volumio volumio[26313]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 03 14:49:00 volumio volumio[26313]: spawnargs: [ Sep 03 14:49:00 volumio volumio[26313]: '-p', Sep 03 14:49:00 volumio volumio[26313]: 9876, Sep 03 14:49:00 volumio volumio[26313]: '-o', Sep 03 14:49:00 volumio volumio[26313]: '/tmp/camilladsp.log', Sep 03 14:49:00 volumio volumio[26313]: '-l', Sep 03 14:49:00 volumio volumio[26313]: 'warn', Sep 03 14:49:00 volumio volumio[26313]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 03 14:49:00 volumio volumio[26313]: ] Sep 03 14:49:00 volumio volumio[26313]: } Sep 03 14:49:00 volumio volumio[26313]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 14:49:00 volumio mpd[26517]: Sep 03 14:49 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 03 14:49:00 volumio systemd[1]: Started Music Player Daemon. Sep 03 14:49:00 volumio sudo[26442]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:00 volumio sudo[26437]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:00 volumio sudo[26506]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:00 volumio sudo[26541]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-03 14:48 Sep 03 14:49:00 volumio sudo[26541]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:00 volumio sudo[26541]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:01 volumio volumio-remote-updater[504]: [2025-09-03 14:49:01] [connect] Successful connection Sep 03 14:49:04 volumio volumio-remote-updater[504]: [2025-09-03 14:49:04] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Sep 03 14:49:04 volumio volumio-remote-updater[504]: [2025-09-03 14:49:04] [error] handle_read_http_response error: websocketpp.transport:2 (Underlying Transport Error) Sep 03 14:49:04 volumio volumio-remote-updater[504]: [2025-09-03 14:49:04] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 03 14:49:04 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 03 14:49:04 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 03 14:49:04 volumio systemd[1]: Started dynamicswap service. Sep 03 14:49:04 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 03 14:49:04 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 03 14:49:04 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 8637. Sep 03 14:49:04 volumio systemd[1]: Stopped Volumio Backend Module. Sep 03 14:49:04 volumio systemd[1]: Started Volumio Backend Module. Sep 03 14:49:04 volumio systemd[1]: Started dynamicswap service. Sep 03 14:49:04 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 03 14:49:06 volumio volumio[26552]: info: ------------------------------------------- Sep 03 14:49:06 volumio volumio[26552]: info: ----- Volumio3 ---- Sep 03 14:49:06 volumio volumio[26552]: info: ------------------------------------------- Sep 03 14:49:06 volumio volumio[26552]: info: ----- System startup ---- Sep 03 14:49:06 volumio volumio[26552]: info: ------------------------------------------- Sep 03 14:49:06 volumio volumio[26552]: info: MYVOLUMIO Environment detected Sep 03 14:49:06 volumio volumio[26552]: info: Plugin folders cleanup Sep 03 14:49:06 volumio volumio[26552]: info: Scanning into folder /volumio/app/plugins/ Sep 03 14:49:06 volumio volumio[26552]: info: Scanning category audio_interface Sep 03 14:49:06 volumio volumio[26552]: info: Scanning category miscellanea Sep 03 14:49:06 volumio volumio[26552]: info: Scanning category music_service Sep 03 14:49:06 volumio volumio[26552]: info: Scanning category plugins.json Sep 03 14:49:06 volumio volumio[26552]: info: Scanning category system_controller Sep 03 14:49:06 volumio volumio[26552]: info: Scanning category user_interface Sep 03 14:49:06 volumio volumio[26552]: info: Scanning into folder /data/plugins/ Sep 03 14:49:06 volumio volumio[26552]: info: Scanning category audio_interface Sep 03 14:49:06 volumio volumio[26552]: info: Scanning category music_service Sep 03 14:49:06 volumio volumio[26552]: info: Scanning category user_interface Sep 03 14:49:06 volumio volumio[26552]: info: Plugin folders cleanup completed Sep 03 14:49:06 volumio volumio[26552]: info: ------------------------------------------- Sep 03 14:49:06 volumio volumio[26552]: info: ----- Core plugins startup ---- Sep 03 14:49:06 volumio volumio[26552]: info: ------------------------------------------- Sep 03 14:49:06 volumio volumio[26552]: info: Loading plugins from folder /volumio/app/plugins/ Sep 03 14:49:06 volumio volumio[26552]: info: Adding plugin upnp to MyMusic Plugins Sep 03 14:49:06 volumio volumio[26552]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 03 14:49:06 volumio volumio[26552]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 03 14:49:06 volumio volumio[26552]: info: Loading plugins from folder /data/plugins/ Sep 03 14:49:06 volumio volumio[26552]: info: Loading plugin "system"... Sep 03 14:49:07 volumio volumio[26552]: info: Loading plugin "appearance"... Sep 03 14:49:08 volumio volumio[26552]: info: Loading plugin "network"... Sep 03 14:49:08 volumio volumio[26552]: info: Refreshing Cached IP Addresses Sep 03 14:49:08 volumio sudo[26580]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 03 14:49:08 volumio sudo[26580]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:08 volumio sudo[26580]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:08 volumio sudo[26582]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 14:49:08 volumio sudo[26582]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:08 volumio volumio[26552]: info: Loading plugin "services"... Sep 03 14:49:08 volumio sudo[26582]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:08 volumio volumio[26552]: info: Loading plugin "alsa_controller"... Sep 03 14:49:08 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 14:49:08 volumio volumio[26552]: info: Loading plugin "wizard"... Sep 03 14:49:08 volumio volumio[26552]: info: Loading plugin "networkfs"... Sep 03 14:49:08 volumio volumio[26552]: info: Starting Udev Watcher for removable devices Sep 03 14:49:08 volumio volumio[26552]: info: Ignoring mount for partition: boot Sep 03 14:49:08 volumio volumio[26552]: info: Ignoring mount for partition: volumio Sep 03 14:49:08 volumio volumio[26552]: info: Ignoring mount for partition: volumio_data Sep 03 14:49:08 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 14:49:08 volumio volumio[26552]: info: Loading plugin "volumio_command_line_client"... Sep 03 14:49:08 volumio volumio[26552]: info: Loading plugin "upnp"... Sep 03 14:49:08 volumio volumio[26552]: info: [1756903748289] Starting Upmpd Daemon Sep 03 14:49:08 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 14:49:08 volumio volumio[26552]: info: Loading plugin "my_music"... Sep 03 14:49:08 volumio volumio[26552]: info: Loading plugin "mpd"... Sep 03 14:49:08 volumio volumio[26552]: info: Plugin upnp_browser is not enabled Sep 03 14:49:08 volumio volumio[26552]: info: Loading plugin "alarm-clock"... Sep 03 14:49:08 volumio volumio[26552]: info: Plugin airplay_emulation is not enabled Sep 03 14:49:08 volumio volumio[26552]: info: Loading plugin "last_100"... Sep 03 14:49:08 volumio volumio[26552]: info: Loading plugin "webradio"... Sep 03 14:49:08 volumio volumio[26552]: info: Loading plugin "i2s_dacs"... Sep 03 14:49:08 volumio volumio[26552]: info: Loading plugin "volumiodiscovery"... Sep 03 14:49:08 volumio volumio[26552]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 03 14:49:08 volumio volumio[26552]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 14:49:08 volumio volumio[26552]: *** WARNING *** For more information see Sep 03 14:49:08 volumio node[26552]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 03 14:49:08 volumio volumio[26552]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 03 14:49:08 volumio volumio[26552]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 14:49:08 volumio volumio[26552]: *** WARNING *** For more information see Sep 03 14:49:08 volumio node[26552]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 14:49:08 volumio node[26552]: *** WARNING *** For more information see Sep 03 14:49:08 volumio node[26552]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 03 14:49:08 volumio node[26552]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 14:49:08 volumio node[26552]: *** WARNING *** For more information see Sep 03 14:49:08 volumio volumio[26552]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 03 14:49:08 volumio volumio[26552]: info: Discovery: Started advertising with name: Volumio Sep 03 14:49:09 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 14:49:09 volumio volumio[26552]: info: Loading plugin "jellyfin"... Sep 03 14:49:09 volumio volumio[26552]: info: Loading plugin "spop"... Sep 03 14:49:10 volumio volumio[26552]: info: Loading plugin "ytcr"... Sep 03 14:49:14 volumio volumio-remote-updater[504]: [2025-09-03 14:49:14] [connect] Successful connection Sep 03 14:49:15 volumio volumio[26552]: info: Loading plugin "outputs"... Sep 03 14:49:15 volumio volumio[26552]: info: Loading plugin "albumart"... Sep 03 14:49:15 volumio volumio[26552]: info: Plugin example_plugin is not enabled Sep 03 14:49:15 volumio volumio[26552]: info: Loading plugin "inputs"... Sep 03 14:49:15 volumio volumio[26552]: info: Loading plugin "updater_comm"... Sep 03 14:49:15 volumio volumio[26552]: info: Plugin mpdemulation is not enabled Sep 03 14:49:15 volumio volumio[26552]: info: Loading plugin "rest_api"... Sep 03 14:49:15 volumio volumio[26552]: info: Loading plugin "websocket"... Sep 03 14:49:15 volumio volumio[26552]: info: Starting Socket.io Server version 2.3.0 Sep 03 14:49:15 volumio volumio[26552]: info: Loading plugin "fusiondsp"... Sep 03 14:49:15 volumio volumio[26552]: Forking 3 albumart workers Sep 03 14:49:15 volumio volumio[26552]: info: Applying required configuration parameters for plugin fusiondsp Sep 03 14:49:15 volumio volumio[26552]: info: Loading plugin "Systeminfo"... Sep 03 14:49:16 volumio volumio[26552]: info: Loading i18n strings for locale hu Sep 03 14:49:16 volumio volumio[26552]: Updating browse sources language Sep 03 14:49:16 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 14:49:16 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 03 14:49:16 volumio volumio[26552]: info: CoreCommandRouter::initPlayerControls Sep 03 14:49:16 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:16 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:16 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:16 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:16 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 03 14:49:16 volumio volumio[26552]: Express server listening on port 3000 Sep 03 14:49:16 volumio volumio[26552]: [Metrics] WebUI: 11s 15.07ms Sep 03 14:49:16 volumio volumio[26552]: info: CoreStateMachine::resetVolumioState Sep 03 14:49:16 volumio volumio[26552]: info: CoreStateMachine::getcurrentVolume Sep 03 14:49:16 volumio volumio[26552]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 14:49:16 volumio volumio[26552]: Starting albumart workers Sep 03 14:49:16 volumio volumio[26552]: Starting albumart workers Sep 03 14:49:16 volumio volumio[26552]: info: Volumio Network Manager: Network status updated: 1 Sep 03 14:49:16 volumio volumio[26552]: Starting albumart workers Sep 03 14:49:16 volumio volumio-remote-updater[504]: [2025-09-03 14:49:16] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1756903754 101 Sep 03 14:49:16 volumio volumio[26552]: 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 03 14:49:16 volumio volumio[26552]: info: CoreCommandRouter::volumioGetState Sep 03 14:49:16 volumio volumio[26552]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:17 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 14:49:17 volumio volumio[26552]: info: VolumeController:: Volume=37 Mute =false Sep 03 14:49:17 volumio volumio[26552]: info: CoreStateMachine::pushState Sep 03 14:49:17 volumio volumio[26552]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:17 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 14:49:17 volumio volumio[26552]: info: CoreCommandRouter::volumioPushState Sep 03 14:49:17 volumio volumio[26552]: info: CoreStateMachine::updateTrackBlock Sep 03 14:49:17 volumio volumio[26552]: info: CorePlayQueue::getTrackBlock Sep 03 14:49:17 volumio volumio[26552]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 14:49:17 volumio volumio[26552]: info: CoreStateMachine::setRepeat false single undefined Sep 03 14:49:17 volumio volumio[26552]: info: CoreStateMachine::pushState Sep 03 14:49:17 volumio volumio[26552]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:17 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 14:49:17 volumio volumio[26552]: info: CoreCommandRouter::volumioPushState Sep 03 14:49:17 volumio volumio[26552]: info: CoreStateMachine::setRandom true Sep 03 14:49:17 volumio volumio[26552]: info: CoreStateMachine::pushState Sep 03 14:49:17 volumio volumio[26552]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:17 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 14:49:17 volumio volumio[26552]: info: CoreCommandRouter::volumioPushState Sep 03 14:49:17 volumio volumio[26552]: info: Setting Device type: Raspberry PI Sep 03 14:49:17 volumio volumio[26552]: info: Listing playlists Sep 03 14:49:17 volumio volumio[26552]: info: Listing playlists Sep 03 14:49:17 volumio volumio[26552]: info: Completed loading Core Plugins Sep 03 14:49:17 volumio volumio[26552]: info: Preparing to generate the ALSA configuration file Sep 03 14:49:17 volumio volumio[26552]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227 Sep 03 14:49:17 volumio volumio[26552]: info: Discovery: Found device Volumio Sep 03 14:49:17 volumio volumio[26552]: info: CoreCommandRouter::volumioGetState Sep 03 14:49:17 volumio volumio[26552]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:17 volumio volumio[26552]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 03 14:49:17 volumio volumio[26552]: info: Reading ALSA contributions from plugins. Sep 03 14:49:17 volumio volumio[26552]: info: VolumeController:: Volume=37 Mute =false Sep 03 14:49:17 volumio volumio[26552]: info: CoreStateMachine::pushState Sep 03 14:49:17 volumio volumio[26552]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:17 volumio volumio[26552]: info: CoreCommandRouter::volumioPushState Sep 03 14:49:17 volumio volumio[26552]: info: Asound.conf file unchanged, so no further update is needed Sep 03 14:49:17 volumio volumio[26552]: info: Output device has changed, restarting MPD Sep 03 14:49:17 volumio sudo[26705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 14:49:17 volumio sudo[26705]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:17 volumio sudo[26705]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:17 volumio volumio[26552]: info: ___________ START PLUGINS ___________ Sep 03 14:49:17 volumio volumio[26552]: info: ControllerMpd::onStart: Initializing MPD Sep 03 14:49:17 volumio volumio[26552]: info: Creating MPD Configuration file Sep 03 14:49:17 volumio sudo[26708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 14:49:17 volumio sudo[26708]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:17 volumio systemd[1]: Stopping Music Player Daemon... Sep 03 14:49:17 volumio sudo[26711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 14:49:17 volumio sudo[26711]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:17 volumio volumio[26552]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 14:49:17 volumio sudo[26711]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:17 volumio volumio[26552]: info: [1756903757625] CoreMusicLibrary::Adding element Last_100 Sep 03 14:49:17 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 14:49:17 volumio systemd[1]: mpd.service: Succeeded. Sep 03 14:49:17 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 14:49:17 volumio volumio[26552]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 14:49:17 volumio volumio[26552]: info: [1756903757644] CoreMusicLibrary::Adding element Webradio Sep 03 14:49:17 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 14:49:17 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 14:49:17 volumio sudo[26713]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 14:49:17 volumio sudo[26713]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:17 volumio volumio[26552]: info: Initializing BBC Radios Sep 03 14:49:17 volumio systemd[1]: Starting Music Player Daemon... Sep 03 14:49:17 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 03 14:49:17 volumio systemd[1]: mpd.service: Succeeded. Sep 03 14:49:17 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 14:49:17 volumio systemd[1]: Starting Music Player Daemon... Sep 03 14:49:17 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 14:49:17 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 14:49:17 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 14:49:17 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 03 14:49:17 volumio volumio[26552]: info: Discovery: Getting this device information Sep 03 14:49:17 volumio volumio[26552]: info: CoreCommandRouter::volumioGetState Sep 03 14:49:17 volumio volumio[26552]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:17 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 14:49:17 volumio volumio[26552]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 14:49:17 volumio volumio[26552]: info: [1756903757784] CoreMusicLibrary::Adding element Jellyfin Sep 03 14:49:17 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 14:49:17 volumio volumio[26552]: Cannot find translation for source Jellyfin Sep 03 14:49:17 volumio volumio[26552]: 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 03 14:49:17 volumio volumio[26552]: info: Creating Spotify config file Sep 03 14:49:17 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:17 volumio sudo[26731]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 03 14:49:17 volumio sudo[26731]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:17 volumio sudo[26731]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:17 volumio volumio[26552]: info: Loading i18n strings for locale hu Sep 03 14:49:17 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 03 14:49:17 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:17 volumio volumio[26552]: info: FusionDsp - mixtype--------------------- Hardware Sep 03 14:49:17 volumio volumio[26552]: info: Volumio Calling Home Sep 03 14:49:18 volumio volumio[26552]: info: Preparing to generate the ALSA configuration file Sep 03 14:49:18 volumio volumio[26552]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 03 14:49:18 volumio volumio[26552]: info: Reading ALSA contributions from plugins. Sep 03 14:49:18 volumio volumio[26552]: info: MPD Permissions set Sep 03 14:49:18 volumio volumio[26552]: info: MPD Permissions set Sep 03 14:49:18 volumio volumio[26552]: info: Spotify config file written Sep 03 14:49:18 volumio sudo[26756]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 03 14:49:18 volumio sudo[26756]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:18 volumio volumio[26552]: 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 03 14:49:18 volumio volumio[26552]: info: Volumio called home Sep 03 14:49:18 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:18 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:18 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:18 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:18 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:18 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:18 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:18 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:18 volumio systemd[1]: Stopping go-librespot Daemon... Sep 03 14:49:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 03 14:49:18 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 03 14:49:18 volumio systemd[1]: Stopped go-librespot Daemon. Sep 03 14:49:18 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 14:49:18 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:18 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:18 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:18 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:18 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:18 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:18 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:18 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 14:49:18 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:18 volumio volumio[26552]: info: No need to fix Spotify hosts Sep 03 14:49:18 volumio systemd[1]: Started go-librespot Daemon. Sep 03 14:49:18 volumio go-librespot[26759]: go-librespot daemon starting... Sep 03 14:49:18 volumio sudo[26756]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:18 volumio go-librespot[26759]: time="2025-09-03T14:49:18+02:00" level=info msg="running go-librespot 0.3.2" Sep 03 14:49:18 volumio go-librespot[26759]: time="2025-09-03T14:49:18+02:00" level=debug msg="app state loaded" Sep 03 14:49:18 volumio go-librespot[26759]: time="2025-09-03T14:49:18+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 03 14:49:18 volumio sudo[26774]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 03 14:49:18 volumio sudo[26774]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:18 volumio sudo[26774]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:18 volumio sudo[26777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 14:49:18 volumio sudo[26777]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:18 volumio sudo[26777]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:18 volumio volumio[26552]: info: CoreCommandRouter::volumioGetState Sep 03 14:49:18 volumio volumio[26552]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:18 volumio go-librespot[26759]: time="2025-09-03T14:49:18+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 03 14:49:18 volumio go-librespot[26759]: time="2025-09-03T14:49:18+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 03 14:49:18 volumio go-librespot[26759]: time="2025-09-03T14:49:18+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 03 14:49:18 volumio go-librespot[26759]: time="2025-09-03T14:49:18+02:00" level=info msg="zeroconf server listening on port 32779" Sep 03 14:49:18 volumio volumio[26552]: info: Asound.conf file unchanged, so no further update is needed Sep 03 14:49:18 volumio volumio[26552]: info: Output device has changed, restarting MPD Sep 03 14:49:18 volumio sudo[26782]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 14:49:18 volumio sudo[26782]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:18 volumio sudo[26782]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:18 volumio go-librespot[26759]: time="2025-09-03T14:49:18+02:00" level=debug msg="obtained new client token: AACgDNDCwS5yDGqvwd9ou+rTGYUzdhq8xQ60vUsTliKNpV+RPStQ5ZzyV+Jbp/iI+LnY5mnsX7/zgpoXp5HRyoffrySwnieyT/mwk2CpWZm/1HLA7Of9Q/U7Mg4HMfPgRKpT7fGPZviPnD8dOqMlIM0CbmxmzY5JbmisQT3FyR9xwDSea8H/ALdRBg9LE/a9ZppK2I4HMl5R9nv96gT+3+ytj5TQC/M+yeFIwSCh+rsh5XSylPp8TuyoqJI=" Sep 03 14:49:18 volumio sudo[26785]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 14:49:18 volumio sudo[26785]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:18 volumio go-librespot[26759]: time="2025-09-03T14:49:18+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 03 14:49:18 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 03 14:49:18 volumio systemd[1]: mpd.service: Succeeded. Sep 03 14:49:18 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 14:49:19 volumio go-librespot[26759]: time="2025-09-03T14:49:19+02:00" level=debug msg="completed keyexchange" Sep 03 14:49:19 volumio go-librespot[26759]: time="2025-09-03T14:49:19+02:00" level=debug msg="completed challenge" Sep 03 14:49:19 volumio volumio[26552]: info: MPD Permissions set Sep 03 14:49:19 volumio systemd[1]: Starting Music Player Daemon... Sep 03 14:49:19 volumio go-librespot[26759]: time="2025-09-03T14:49:19+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 03 14:49:19 volumio volumio[26552]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 03 14:49:19 volumio volumio[26552]: SPOTIFY: BQD88K2JGubAzANmeNSTDbHI8T30kp9fuWsqaSEM9bIdEdVObLuSJCUqcjfdJgIRxuv_8g1Nyc9HMiER15atR_m8TM2MXWmLhWLiJPhRY4mVhQX42c7VEAxyRkBM7oAj8KGc-xgOnjP2SxmMhajL0dlt5W2EZhY-hIQxTsmPIrZ9kB_dLHNTZYZ-UFarC7-Jhc9ul_jCuXMPYSRDL9a0iIHF9z8CZ8r4G2DCFO_wewvhTorJI9RdEXoxxVkgCqJdvlWl8GyzFt_oFkK7r6c_Z4syZmttDIoJ5t3NqytTs-hguGY Sep 03 14:49:19 volumio volumio[26552]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 03 14:49:19 volumio volumio[26552]: info: New Spotify access token = BQD88K2JGubAzANmeNSTDbHI8T30kp9fuWsqaSEM9bIdEdVObLuSJCUqcjfdJgIRxuv_8g1Nyc9HMiER15atR_m8TM2MXWmLhWLiJPhRY4mVhQX42c7VEAxyRkBM7oAj8KGc-xgOnjP2SxmMhajL0dlt5W2EZhY-hIQxTsmPIrZ9kB_dLHNTZYZ-UFarC7-Jhc9ul_jCuXMPYSRDL9a0iIHF9z8CZ8r4G2DCFO_wewvhTorJI9RdEXoxxVkgCqJdvlWl8GyzFt_oFkK7r6c_Z4syZmttDIoJ5t3NqytTs-hguGY Sep 03 14:49:19 volumio volumio[26552]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 03 14:49:19 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:19 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:19 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:19 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:19 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:19 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:19 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:19 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:19 volumio sudo[26790]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 03 14:49:19 volumio sudo[26790]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:19 volumio sudo[26787]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 03 14:49:19 volumio go-librespot[26759]: time="2025-09-03T14:49:19+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 03 14:49:19 volumio go-librespot[26759]: time="2025-09-03T14:49:19+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 03 14:49:19 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 14:49:19 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:19 volumio sudo[26787]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:19 volumio sudo[26787]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:19 volumio sudo[26790]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:19 volumio volumio[26552]: info: Upmpdcli Daemon Started Sep 03 14:49:19 volumio go-librespot[26759]: time="2025-09-03T14:49:19+02:00" level=debug msg="dealer connection opened" Sep 03 14:49:19 volumio go-librespot[26759]: time="2025-09-03T14:49:19+02:00" level=trace msg="starting accesspoint recv loop" Sep 03 14:49:19 volumio go-librespot[26759]: time="2025-09-03T14:49:19+02:00" level=trace msg="starting dealer recv loop" Sep 03 14:49:19 volumio go-librespot[26759]: time="2025-09-03T14:49:19+02:00" level=trace msg="received accesspoint ping" Sep 03 14:49:19 volumio go-librespot[26759]: time="2025-09-03T14:49:19+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 03 14:49:19 volumio go-librespot[26759]: time="2025-09-03T14:49:19+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 03 14:49:19 volumio go-librespot[26759]: time="2025-09-03T14:49:19+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 03 14:49:19 volumio go-librespot[26759]: time="2025-09-03T14:49:19+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 03 14:49:19 volumio go-librespot[26759]: time="2025-09-03T14:49:19+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 03 14:49:19 volumio go-librespot[26759]: time="2025-09-03T14:49:19+02:00" level=debug msg="received connection id: ODk4ZjM4ZDctZDZm...MzJCOEJGMzI1MA==" Sep 03 14:49:19 volumio go-librespot[26759]: time="2025-09-03T14:49:19+02:00" level=trace msg="received accesspoint pong ack" Sep 03 14:49:19 volumio volumio[26552]: 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 03 14:49:19 volumio volumio[26552]: info: Spotify Successfully logged in Sep 03 14:49:19 volumio volumio[26552]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 14:49:19 volumio volumio[26552]: info: [1756903759310] CoreMusicLibrary::Adding element Spotify Sep 03 14:49:19 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 14:49:19 volumio volumio[26552]: Cannot find translation for source Jellyfin Sep 03 14:49:19 volumio volumio[26552]: Cannot find translation for source Spotify Sep 03 14:49:19 volumio go-librespot[26759]: time="2025-09-03T14:49:19+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 03 14:49:19 volumio volumio[26552]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 03 14:49:19 volumio volumio[26552]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 14:49:19 volumio volumio[26552]: info: VolumeController:: Volume=37 Mute =false Sep 03 14:49:19 volumio volumio[26552]: info: CoreCommandRouter::volumioGetState Sep 03 14:49:19 volumio volumio[26552]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:19 volumio volumio[26552]: info: CoreStateMachine::pushState Sep 03 14:49:19 volumio volumio[26552]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:19 volumio volumio[26552]: info: CoreCommandRouter::volumioPushState Sep 03 14:49:20 volumio volumio[26552]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 03 14:49:20 volumio volumio[26552]: 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 03 14:49:20 volumio volumio[26552]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 03 14:49:20 volumio volumio[26552]: info: camilladsp service started and running in background, instance 1 Sep 03 14:49:20 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:20 volumio volumio[26552]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 03 14:49:20 volumio volumio[26552]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 03 14:49:20 volumio volumio[26552]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 03 14:49:20 volumio volumio[26552]: info: FusionDsp loaded Sep 03 14:49:20 volumio volumio[26552]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 14:49:20 volumio sudo[26810]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 03 14:49:20 volumio sudo[26810]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:20 volumio volumio[26552]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 03 14:49:20 volumio volumio[26552]: info: Adding Signal Path Element [object Object] Sep 03 14:49:20 volumio volumio[26552]: info: Adding fusiondspeq DSP Signal Path Element Sep 03 14:49:20 volumio volumio[26552]: info: FusionDsp - ---- installed callbackRead Sep 03 14:49:20 volumio volumio[26552]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 14:49:20 volumio sudo[26810]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:20 volumio volumio[26552]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 03 14:49:20 volumio volumio[26552]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 03 14:49:20 volumio volumio[26552]: at onErrorNT (internal/child_process.js:465:16) Sep 03 14:49:20 volumio volumio[26552]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Sep 03 14:49:20 volumio volumio[26552]: errno: -2, Sep 03 14:49:20 volumio volumio[26552]: code: 'ENOENT', Sep 03 14:49:20 volumio volumio[26552]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 03 14:49:20 volumio volumio[26552]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 03 14:49:20 volumio volumio[26552]: spawnargs: [ Sep 03 14:49:20 volumio volumio[26552]: '-p', Sep 03 14:49:20 volumio volumio[26552]: 9876, Sep 03 14:49:20 volumio volumio[26552]: '-o', Sep 03 14:49:20 volumio volumio[26552]: '/tmp/camilladsp.log', Sep 03 14:49:20 volumio volumio[26552]: '-l', Sep 03 14:49:20 volumio volumio[26552]: 'warn', Sep 03 14:49:20 volumio volumio[26552]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 03 14:49:20 volumio volumio[26552]: ] Sep 03 14:49:20 volumio volumio[26552]: } Sep 03 14:49:20 volumio volumio[26552]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 14:49:20 volumio sudo[26821]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-03 14:48 Sep 03 14:49:20 volumio mpd[26797]: Sep 03 14:49 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 03 14:49:20 volumio systemd[1]: Started Music Player Daemon. Sep 03 14:49:20 volumio sudo[26821]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:20 volumio sudo[26785]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:20 volumio sudo[26713]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:20 volumio sudo[26708]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:20 volumio sudo[26821]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:21 volumio volumio-remote-updater[504]: [2025-09-03 14:49:21] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 03 14:49:21 volumio volumio-remote-updater[504]: [2025-09-03 14:49:21] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 03 14:49:21 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 03 14:49:21 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 03 14:49:21 volumio systemd[1]: Started dynamicswap service. Sep 03 14:49:21 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 03 14:49:21 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 03 14:49:21 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 8638. Sep 03 14:49:21 volumio systemd[1]: Started dynamicswap service. Sep 03 14:49:21 volumio systemd[1]: Stopped Volumio Backend Module. Sep 03 14:49:21 volumio systemd[1]: Started Volumio Backend Module. Sep 03 14:49:21 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 03 14:49:22 volumio volumio[26834]: info: ------------------------------------------- Sep 03 14:49:22 volumio volumio[26834]: info: ----- Volumio3 ---- Sep 03 14:49:22 volumio volumio[26834]: info: ------------------------------------------- Sep 03 14:49:22 volumio volumio[26834]: info: ----- System startup ---- Sep 03 14:49:22 volumio volumio[26834]: info: ------------------------------------------- Sep 03 14:49:23 volumio volumio[26834]: info: MYVOLUMIO Environment detected Sep 03 14:49:23 volumio volumio[26834]: info: Plugin folders cleanup Sep 03 14:49:23 volumio volumio[26834]: info: Scanning into folder /volumio/app/plugins/ Sep 03 14:49:23 volumio volumio[26834]: info: Scanning category audio_interface Sep 03 14:49:23 volumio volumio[26834]: info: Scanning category miscellanea Sep 03 14:49:23 volumio volumio[26834]: info: Scanning category music_service Sep 03 14:49:23 volumio volumio[26834]: info: Scanning category plugins.json Sep 03 14:49:23 volumio volumio[26834]: info: Scanning category system_controller Sep 03 14:49:23 volumio volumio[26834]: info: Scanning category user_interface Sep 03 14:49:23 volumio volumio[26834]: info: Scanning into folder /data/plugins/ Sep 03 14:49:23 volumio volumio[26834]: info: Scanning category audio_interface Sep 03 14:49:23 volumio volumio[26834]: info: Scanning category music_service Sep 03 14:49:23 volumio volumio[26834]: info: Scanning category user_interface Sep 03 14:49:23 volumio volumio[26834]: info: Plugin folders cleanup completed Sep 03 14:49:23 volumio volumio[26834]: info: ------------------------------------------- Sep 03 14:49:23 volumio volumio[26834]: info: ----- Core plugins startup ---- Sep 03 14:49:23 volumio volumio[26834]: info: ------------------------------------------- Sep 03 14:49:23 volumio volumio[26834]: info: Loading plugins from folder /volumio/app/plugins/ Sep 03 14:49:23 volumio volumio[26834]: info: Adding plugin upnp to MyMusic Plugins Sep 03 14:49:23 volumio volumio[26834]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 03 14:49:23 volumio volumio[26834]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 03 14:49:23 volumio volumio[26834]: info: Loading plugins from folder /data/plugins/ Sep 03 14:49:23 volumio volumio[26834]: info: Loading plugin "system"... Sep 03 14:49:23 volumio volumio[26834]: info: Loading plugin "appearance"... Sep 03 14:49:24 volumio volumio[26834]: info: Loading plugin "network"... Sep 03 14:49:24 volumio volumio[26834]: info: Refreshing Cached IP Addresses Sep 03 14:49:24 volumio sudo[26860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 03 14:49:24 volumio sudo[26860]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:24 volumio sudo[26860]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:24 volumio volumio[26834]: info: Loading plugin "services"... Sep 03 14:49:24 volumio sudo[26862]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 14:49:24 volumio sudo[26862]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:24 volumio volumio[26834]: info: Loading plugin "alsa_controller"... Sep 03 14:49:24 volumio sudo[26862]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:24 volumio volumio[26834]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 14:49:24 volumio volumio[26834]: info: Loading plugin "wizard"... Sep 03 14:49:24 volumio volumio[26834]: info: Loading plugin "networkfs"... Sep 03 14:49:24 volumio volumio[26834]: info: Starting Udev Watcher for removable devices Sep 03 14:49:25 volumio volumio[26834]: info: Ignoring mount for partition: boot Sep 03 14:49:25 volumio volumio[26834]: info: Ignoring mount for partition: volumio Sep 03 14:49:25 volumio volumio[26834]: info: Ignoring mount for partition: volumio_data Sep 03 14:49:25 volumio volumio[26834]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 14:49:25 volumio volumio[26834]: info: Loading plugin "volumio_command_line_client"... Sep 03 14:49:25 volumio volumio[26834]: info: Loading plugin "upnp"... Sep 03 14:49:25 volumio volumio[26834]: info: [1756903765017] Starting Upmpd Daemon Sep 03 14:49:25 volumio volumio[26834]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 14:49:25 volumio volumio[26834]: info: Loading plugin "my_music"... Sep 03 14:49:25 volumio volumio[26834]: info: Loading plugin "mpd"... Sep 03 14:49:25 volumio volumio[26834]: info: Plugin upnp_browser is not enabled Sep 03 14:49:25 volumio volumio[26834]: info: Loading plugin "alarm-clock"... Sep 03 14:49:25 volumio volumio[26834]: info: Plugin airplay_emulation is not enabled Sep 03 14:49:25 volumio volumio[26834]: info: Loading plugin "last_100"... Sep 03 14:49:25 volumio volumio[26834]: info: Loading plugin "webradio"... Sep 03 14:49:25 volumio volumio[26834]: info: Loading plugin "i2s_dacs"... Sep 03 14:49:25 volumio volumio[26834]: info: Loading plugin "volumiodiscovery"... Sep 03 14:49:25 volumio volumio[26834]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 03 14:49:25 volumio volumio[26834]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 14:49:25 volumio volumio[26834]: *** WARNING *** For more information see Sep 03 14:49:25 volumio node[26834]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 03 14:49:25 volumio volumio[26834]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 03 14:49:25 volumio volumio[26834]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 14:49:25 volumio volumio[26834]: *** WARNING *** For more information see Sep 03 14:49:25 volumio node[26834]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 14:49:25 volumio node[26834]: *** WARNING *** For more information see Sep 03 14:49:25 volumio node[26834]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 03 14:49:25 volumio node[26834]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 14:49:25 volumio node[26834]: *** WARNING *** For more information see Sep 03 14:49:25 volumio volumio[26834]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 03 14:49:25 volumio volumio[26834]: info: Discovery: Started advertising with name: Volumio Sep 03 14:49:25 volumio volumio[26834]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 14:49:25 volumio volumio[26834]: info: Loading plugin "jellyfin"... Sep 03 14:49:26 volumio volumio-remote-updater[504]: [2025-09-03 14:49:26] [connect] Successful connection Sep 03 14:49:26 volumio volumio[26834]: info: Loading plugin "spop"... Sep 03 14:49:27 volumio volumio[26834]: info: Loading plugin "ytcr"... Sep 03 14:49:32 volumio volumio[26834]: info: Loading plugin "outputs"... Sep 03 14:49:32 volumio volumio[26834]: info: Loading plugin "albumart"... Sep 03 14:49:32 volumio volumio[26834]: info: Plugin example_plugin is not enabled Sep 03 14:49:32 volumio volumio[26834]: info: Loading plugin "inputs"... Sep 03 14:49:32 volumio volumio[26834]: info: Loading plugin "updater_comm"... Sep 03 14:49:32 volumio volumio[26834]: info: Plugin mpdemulation is not enabled Sep 03 14:49:32 volumio volumio[26834]: info: Loading plugin "rest_api"... Sep 03 14:49:32 volumio volumio[26834]: info: Loading plugin "websocket"... Sep 03 14:49:32 volumio volumio[26834]: info: Starting Socket.io Server version 2.3.0 Sep 03 14:49:32 volumio volumio[26834]: info: Loading plugin "fusiondsp"... Sep 03 14:49:32 volumio volumio[26834]: Forking 3 albumart workers Sep 03 14:49:32 volumio volumio[26834]: info: Applying required configuration parameters for plugin fusiondsp Sep 03 14:49:32 volumio volumio[26834]: info: Loading plugin "Systeminfo"... Sep 03 14:49:33 volumio volumio[26834]: info: Loading i18n strings for locale hu Sep 03 14:49:33 volumio volumio[26834]: Updating browse sources language Sep 03 14:49:33 volumio volumio[26834]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 14:49:33 volumio volumio[26834]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 03 14:49:33 volumio volumio[26834]: info: CoreCommandRouter::initPlayerControls Sep 03 14:49:33 volumio volumio[26834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:33 volumio volumio[26834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:33 volumio volumio[26834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:33 volumio volumio[26834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:33 volumio volumio[26834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:33 volumio volumio[26834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:33 volumio volumio[26834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:33 volumio volumio[26834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:33 volumio volumio[26834]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 03 14:49:33 volumio volumio[26834]: Express server listening on port 3000 Sep 03 14:49:33 volumio volumio[26834]: [Metrics] WebUI: 11s 17.42ms Sep 03 14:49:33 volumio volumio[26834]: info: CoreStateMachine::resetVolumioState Sep 03 14:49:33 volumio volumio[26834]: info: CoreStateMachine::getcurrentVolume Sep 03 14:49:33 volumio volumio[26834]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 14:49:33 volumio volumio[26834]: Starting albumart workers Sep 03 14:49:33 volumio volumio[26834]: Starting albumart workers Sep 03 14:49:33 volumio volumio[26834]: info: Volumio Network Manager: Network status updated: 1 Sep 03 14:49:33 volumio volumio[26834]: Starting albumart workers Sep 03 14:49:33 volumio volumio[26834]: info: CoreCommandRouter::volumioGetState Sep 03 14:49:33 volumio volumio[26834]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:33 volumio volumio[26834]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 14:49:33 volumio volumio[26834]: info: VolumeController:: Volume=37 Mute =false Sep 03 14:49:33 volumio volumio[26834]: info: CoreStateMachine::pushState Sep 03 14:49:33 volumio volumio[26834]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:33 volumio volumio[26834]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 14:49:33 volumio volumio[26834]: info: CoreCommandRouter::volumioPushState Sep 03 14:49:33 volumio volumio[26834]: info: CoreStateMachine::updateTrackBlock Sep 03 14:49:33 volumio volumio[26834]: info: CorePlayQueue::getTrackBlock Sep 03 14:49:33 volumio volumio[26834]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 14:49:33 volumio volumio[26834]: info: CoreStateMachine::setRepeat false single undefined Sep 03 14:49:33 volumio volumio[26834]: info: CoreStateMachine::pushState Sep 03 14:49:33 volumio volumio[26834]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:33 volumio volumio[26834]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 14:49:33 volumio volumio[26834]: info: CoreCommandRouter::volumioPushState Sep 03 14:49:33 volumio volumio[26834]: info: CoreStateMachine::setRandom true Sep 03 14:49:33 volumio volumio[26834]: info: CoreStateMachine::pushState Sep 03 14:49:33 volumio volumio[26834]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:33 volumio volumio[26834]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 14:49:33 volumio volumio[26834]: info: CoreCommandRouter::volumioPushState Sep 03 14:49:33 volumio volumio[26834]: info: Setting Device type: Raspberry PI Sep 03 14:49:33 volumio volumio[26834]: info: Listing playlists Sep 03 14:49:33 volumio volumio[26834]: info: Listing playlists Sep 03 14:49:34 volumio volumio[26834]: info: Completed loading Core Plugins Sep 03 14:49:34 volumio volumio[26834]: info: Preparing to generate the ALSA configuration file Sep 03 14:49:34 volumio volumio[26834]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 03 14:49:34 volumio volumio[26834]: info: Reading ALSA contributions from plugins. Sep 03 14:49:34 volumio volumio[26834]: info: VolumeController:: Volume=37 Mute =false Sep 03 14:49:34 volumio volumio[26834]: info: CoreStateMachine::pushState Sep 03 14:49:34 volumio volumio[26834]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:34 volumio volumio[26834]: info: CoreCommandRouter::volumioPushState Sep 03 14:49:34 volumio volumio[26834]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227 Sep 03 14:49:34 volumio volumio[26834]: info: Discovery: Found device Volumio Sep 03 14:49:34 volumio volumio[26834]: info: CoreCommandRouter::volumioGetState Sep 03 14:49:34 volumio volumio[26834]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:34 volumio volumio[26834]: info: Asound.conf file unchanged, so no further update is needed Sep 03 14:49:34 volumio volumio[26834]: info: Output device has changed, restarting MPD Sep 03 14:49:34 volumio volumio[26834]: info: ___________ START PLUGINS ___________ Sep 03 14:49:34 volumio sudo[26937]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 14:49:34 volumio sudo[26937]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:34 volumio sudo[26937]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:34 volumio volumio[26834]: info: ControllerMpd::onStart: Initializing MPD Sep 03 14:49:34 volumio volumio[26834]: info: Creating MPD Configuration file Sep 03 14:49:34 volumio sudo[26943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 14:49:34 volumio sudo[26943]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:34 volumio systemd[1]: Stopping Music Player Daemon... Sep 03 14:49:34 volumio sudo[26955]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 14:49:34 volumio sudo[26955]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:34 volumio sudo[26955]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:34 volumio systemd[1]: mpd.service: Succeeded. Sep 03 14:49:34 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 14:49:34 volumio volumio[26834]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 14:49:34 volumio volumio[26834]: info: [1756903774350] CoreMusicLibrary::Adding element Last_100 Sep 03 14:49:34 volumio volumio[26834]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 14:49:34 volumio volumio[26834]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 14:49:34 volumio volumio[26834]: info: [1756903774355] CoreMusicLibrary::Adding element Webradio Sep 03 14:49:34 volumio sudo[26963]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 14:49:34 volumio sudo[26963]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:34 volumio systemd[1]: Starting Music Player Daemon... Sep 03 14:49:34 volumio volumio[26834]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 14:49:34 volumio volumio[26834]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 14:49:34 volumio volumio[26834]: info: Initializing BBC Radios Sep 03 14:49:34 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 03 14:49:34 volumio systemd[1]: mpd.service: Succeeded. Sep 03 14:49:34 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 14:49:34 volumio systemd[1]: Starting Music Player Daemon... Sep 03 14:49:34 volumio volumio[26834]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 14:49:34 volumio volumio[26834]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 14:49:34 volumio volumio[26834]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 14:49:34 volumio volumio[26834]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 03 14:49:34 volumio volumio[26834]: info: Discovery: Getting this device information Sep 03 14:49:34 volumio volumio[26834]: info: CoreCommandRouter::volumioGetState Sep 03 14:49:34 volumio volumio[26834]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:34 volumio volumio[26834]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 14:49:34 volumio volumio[26834]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 14:49:34 volumio volumio[26834]: info: [1756903774499] CoreMusicLibrary::Adding element Jellyfin Sep 03 14:49:34 volumio volumio[26834]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 14:49:34 volumio volumio[26834]: Cannot find translation for source Jellyfin Sep 03 14:49:34 volumio volumio[26834]: 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 03 14:49:34 volumio volumio[26834]: info: Creating Spotify config file Sep 03 14:49:34 volumio volumio[26834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:34 volumio sudo[26967]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 03 14:49:34 volumio sudo[26967]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:34 volumio sudo[26967]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:34 volumio volumio[26834]: info: Loading i18n strings for locale hu Sep 03 14:49:34 volumio volumio[26834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 03 14:49:34 volumio volumio[26834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:34 volumio volumio[26834]: info: FusionDsp - mixtype--------------------- Hardware Sep 03 14:49:34 volumio volumio[26834]: info: Volumio Calling Home Sep 03 14:49:34 volumio volumio[26834]: info: Preparing to generate the ALSA configuration file Sep 03 14:49:34 volumio volumio[26834]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 03 14:49:34 volumio volumio[26834]: info: Reading ALSA contributions from plugins. Sep 03 14:49:34 volumio volumio[26834]: info: MPD Permissions set Sep 03 14:49:34 volumio volumio[26834]: info: MPD Permissions set Sep 03 14:49:34 volumio volumio[26834]: info: Spotify config file written Sep 03 14:49:35 volumio sudo[26996]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 03 14:49:35 volumio sudo[26996]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:35 volumio volumio[26834]: 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 03 14:49:35 volumio volumio[26834]: info: Volumio called home Sep 03 14:49:35 volumio systemd[1]: Stopping go-librespot Daemon... Sep 03 14:49:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 03 14:49:35 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 03 14:49:35 volumio systemd[1]: Stopped go-librespot Daemon. Sep 03 14:49:35 volumio sudo[26999]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 03 14:49:35 volumio sudo[26999]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:35 volumio volumio[26834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:35 volumio volumio[26834]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 14:49:35 volumio volumio[26834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:35 volumio sudo[26999]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:35 volumio systemd[1]: Started go-librespot Daemon. Sep 03 14:49:35 volumio volumio[26834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:35 volumio sudo[27002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 14:49:35 volumio go-librespot[27000]: go-librespot daemon starting... Sep 03 14:49:35 volumio sudo[27002]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:35 volumio sudo[27002]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:35 volumio sudo[26996]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:35 volumio go-librespot[27000]: time="2025-09-03T14:49:35+02:00" level=info msg="running go-librespot 0.3.2" Sep 03 14:49:35 volumio go-librespot[27000]: time="2025-09-03T14:49:35+02:00" level=debug msg="app state loaded" Sep 03 14:49:35 volumio go-librespot[27000]: time="2025-09-03T14:49:35+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 03 14:49:35 volumio volumio[26834]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 14:49:35 volumio volumio[26834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:35 volumio volumio[26834]: info: No need to fix Spotify hosts Sep 03 14:49:35 volumio go-librespot[27000]: time="2025-09-03T14:49:35+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 03 14:49:35 volumio go-librespot[27000]: time="2025-09-03T14:49:35+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 03 14:49:35 volumio go-librespot[27000]: time="2025-09-03T14:49:35+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 03 14:49:35 volumio go-librespot[27000]: time="2025-09-03T14:49:35+02:00" level=info msg="zeroconf server listening on port 39419" Sep 03 14:49:35 volumio go-librespot[27000]: time="2025-09-03T14:49:35+02:00" level=debug msg="obtained new client token: AACpVbrTuTinlKF1cZzBnnHH7g4FwjAF4E6398SU5Y87NkxTv3SRaoNL5JZXP5Ql2DPAC3B48Vr7Xp13JMe4Z1LKF8u7+lllirQsqB85Fh8cvv3J63bl3Skd/E6KEQk0xq0h14RUni2PO2pMEZq2TJOvWprzoWMaUEDtcfJJizSkNGF0hpXR43BHY0gKGvFcVeNxwNZyLy/ukDK9xyMKQNUe0FKn30n+i+AUo88jXblCcAGb1qi3MzQZrDI=" Sep 03 14:49:35 volumio volumio[26834]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 03 14:49:35 volumio volumio[26834]: SPOTIFY: BQAilpctdfpCnTSXmXiP5HyU1H48irNcp0x_4KvRQ14EpxjMBXzMv7vHgg-b43PRHTEHabTbsVnplK-_zziHy5aCpTTQ8R9Mxj7hcIkHW48VxtQmAjWVfinhICoeSjmdqNoMv6YC5OxSKdbFIN4Ph_o6M1PQo2Gqn4VZ2IIhVQgniGTHWHk7hvCLPVH7nRnGwWjjGaHf34s2bS6Bu6Xq237iaYvShtIuiFUXVkuNX9HHurgUGHbMzz_5UM1wHm1IMMZIpmMYbjtK6SW7wDa-ypX2ZLUCcWs0vQGcEuihwbSVb0c Sep 03 14:49:35 volumio volumio[26834]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 03 14:49:35 volumio volumio[26834]: info: New Spotify access token = BQAilpctdfpCnTSXmXiP5HyU1H48irNcp0x_4KvRQ14EpxjMBXzMv7vHgg-b43PRHTEHabTbsVnplK-_zziHy5aCpTTQ8R9Mxj7hcIkHW48VxtQmAjWVfinhICoeSjmdqNoMv6YC5OxSKdbFIN4Ph_o6M1PQo2Gqn4VZ2IIhVQgniGTHWHk7hvCLPVH7nRnGwWjjGaHf34s2bS6Bu6Xq237iaYvShtIuiFUXVkuNX9HHurgUGHbMzz_5UM1wHm1IMMZIpmMYbjtK6SW7wDa-ypX2ZLUCcWs0vQGcEuihwbSVb0c Sep 03 14:49:35 volumio volumio[26834]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 03 14:49:35 volumio volumio[26834]: info: CoreCommandRouter::volumioGetState Sep 03 14:49:35 volumio volumio[26834]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:35 volumio go-librespot[27000]: time="2025-09-03T14:49:35+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 03 14:49:35 volumio volumio[26834]: info: Asound.conf file unchanged, so no further update is needed Sep 03 14:49:35 volumio volumio[26834]: info: Output device has changed, restarting MPD Sep 03 14:49:35 volumio go-librespot[27000]: time="2025-09-03T14:49:35+02:00" level=debug msg="completed keyexchange" Sep 03 14:49:35 volumio go-librespot[27000]: time="2025-09-03T14:49:35+02:00" level=debug msg="completed challenge" Sep 03 14:49:35 volumio sudo[27021]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 14:49:35 volumio sudo[27021]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:35 volumio sudo[27021]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:35 volumio go-librespot[27000]: time="2025-09-03T14:49:35+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 03 14:49:35 volumio sudo[27024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 14:49:35 volumio sudo[27024]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:35 volumio volumio[26834]: info: MPD Permissions set Sep 03 14:49:35 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 03 14:49:35 volumio systemd[1]: mpd.service: Succeeded. Sep 03 14:49:35 volumio go-librespot[27000]: time="2025-09-03T14:49:35+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 03 14:49:35 volumio go-librespot[27000]: time="2025-09-03T14:49:35+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 03 14:49:35 volumio sudo[27027]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 03 14:49:35 volumio volumio[26834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:35 volumio volumio[26834]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 14:49:35 volumio volumio[26834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:35 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 14:49:35 volumio sudo[27027]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:35 volumio systemd[1]: Starting Music Player Daemon... Sep 03 14:49:35 volumio go-librespot[27000]: time="2025-09-03T14:49:35+02:00" level=debug msg="dealer connection opened" Sep 03 14:49:35 volumio go-librespot[27000]: time="2025-09-03T14:49:35+02:00" level=trace msg="starting accesspoint recv loop" Sep 03 14:49:35 volumio go-librespot[27000]: time="2025-09-03T14:49:35+02:00" level=trace msg="starting dealer recv loop" Sep 03 14:49:35 volumio go-librespot[27000]: time="2025-09-03T14:49:35+02:00" level=trace msg="received accesspoint ping" Sep 03 14:49:35 volumio go-librespot[27000]: time="2025-09-03T14:49:35+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 03 14:49:35 volumio go-librespot[27000]: time="2025-09-03T14:49:35+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 03 14:49:35 volumio go-librespot[27000]: time="2025-09-03T14:49:35+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 03 14:49:35 volumio go-librespot[27000]: time="2025-09-03T14:49:35+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 03 14:49:35 volumio go-librespot[27000]: time="2025-09-03T14:49:35+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 03 14:49:35 volumio go-librespot[27000]: time="2025-09-03T14:49:35+02:00" level=debug msg="received connection id: OWQ0Njk2OGMtZGFm...MDgyQ0FFNjQ4Mg==" Sep 03 14:49:35 volumio sudo[27027]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:35 volumio go-librespot[27000]: time="2025-09-03T14:49:35+02:00" level=trace msg="received accesspoint pong ack" Sep 03 14:49:35 volumio volumio[26834]: info: Upmpdcli Daemon Started Sep 03 14:49:35 volumio volumio[26834]: 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 03 14:49:35 volumio volumio[26834]: info: Spotify Successfully logged in Sep 03 14:49:35 volumio volumio[26834]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 14:49:35 volumio volumio[26834]: info: [1756903775880] CoreMusicLibrary::Adding element Spotify Sep 03 14:49:35 volumio volumio[26834]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 14:49:35 volumio volumio[26834]: Cannot find translation for source Jellyfin Sep 03 14:49:35 volumio volumio[26834]: Cannot find translation for source Spotify Sep 03 14:49:35 volumio sudo[27028]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 03 14:49:35 volumio sudo[27028]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:35 volumio sudo[27028]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:35 volumio go-librespot[27000]: time="2025-09-03T14:49:35+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 03 14:49:36 volumio volumio[26834]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 03 14:49:36 volumio volumio[26834]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 14:49:36 volumio volumio[26834]: info: VolumeController:: Volume=37 Mute =false Sep 03 14:49:36 volumio volumio[26834]: info: CoreCommandRouter::volumioGetState Sep 03 14:49:36 volumio volumio[26834]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:36 volumio volumio[26834]: info: CoreStateMachine::pushState Sep 03 14:49:36 volumio volumio[26834]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:36 volumio volumio[26834]: info: CoreCommandRouter::volumioPushState Sep 03 14:49:36 volumio volumio[26834]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 03 14:49:36 volumio volumio[26834]: 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 03 14:49:36 volumio volumio[26834]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 03 14:49:36 volumio volumio[26834]: info: camilladsp service started and running in background, instance 1 Sep 03 14:49:36 volumio volumio[26834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:36 volumio volumio[26834]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 03 14:49:36 volumio volumio[26834]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 03 14:49:36 volumio volumio[26834]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 03 14:49:36 volumio volumio[26834]: info: FusionDsp loaded Sep 03 14:49:36 volumio volumio[26834]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 14:49:36 volumio sudo[27051]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 03 14:49:36 volumio sudo[27051]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:36 volumio volumio[26834]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 03 14:49:36 volumio volumio[26834]: info: Adding Signal Path Element [object Object] Sep 03 14:49:36 volumio volumio[26834]: info: Adding fusiondspeq DSP Signal Path Element Sep 03 14:49:36 volumio volumio[26834]: info: FusionDsp - ---- installed callbackRead Sep 03 14:49:36 volumio volumio[26834]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 14:49:36 volumio sudo[27051]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:36 volumio volumio[26834]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 03 14:49:36 volumio volumio[26834]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 03 14:49:36 volumio volumio[26834]: at onErrorNT (internal/child_process.js:465:16) Sep 03 14:49:36 volumio volumio[26834]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Sep 03 14:49:36 volumio volumio[26834]: errno: -2, Sep 03 14:49:36 volumio volumio[26834]: code: 'ENOENT', Sep 03 14:49:36 volumio volumio[26834]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 03 14:49:36 volumio volumio[26834]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 03 14:49:36 volumio volumio[26834]: spawnargs: [ Sep 03 14:49:36 volumio volumio[26834]: '-p', Sep 03 14:49:36 volumio volumio[26834]: 9876, Sep 03 14:49:36 volumio volumio[26834]: '-o', Sep 03 14:49:36 volumio volumio[26834]: '/tmp/camilladsp.log', Sep 03 14:49:36 volumio volumio[26834]: '-l', Sep 03 14:49:36 volumio volumio[26834]: 'warn', Sep 03 14:49:36 volumio volumio[26834]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 03 14:49:36 volumio volumio[26834]: ] Sep 03 14:49:36 volumio volumio[26834]: } Sep 03 14:49:36 volumio volumio[26834]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 14:49:37 volumio mpd[27036]: Sep 03 14:49 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 03 14:49:37 volumio systemd[1]: Started Music Player Daemon. Sep 03 14:49:37 volumio sudo[26963]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:37 volumio sudo[27024]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:37 volumio sudo[27062]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-03 14:48 Sep 03 14:49:37 volumio sudo[26943]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:37 volumio sudo[27062]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:37 volumio sudo[27062]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:38 volumio volumio-remote-updater[504]: [2025-09-03 14:49:38] [connect] Successful connection Sep 03 14:49:40 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 03 14:49:40 volumio volumio-remote-updater[504]: [2025-09-03 14:49:40] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Sep 03 14:49:40 volumio volumio-remote-updater[504]: [2025-09-03 14:49:40] [error] handle_read_http_response error: websocketpp.transport:2 (Underlying Transport Error) Sep 03 14:49:41 volumio volumio-remote-updater[504]: [2025-09-03 14:49:41] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 03 14:49:41 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 03 14:49:41 volumio systemd[1]: Started dynamicswap service. Sep 03 14:49:41 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 03 14:49:41 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 03 14:49:41 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 8639. Sep 03 14:49:41 volumio systemd[1]: Started dynamicswap service. Sep 03 14:49:41 volumio systemd[1]: Stopped Volumio Backend Module. Sep 03 14:49:41 volumio systemd[1]: Started Volumio Backend Module. Sep 03 14:49:41 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 03 14:49:42 volumio volumio[27076]: info: ------------------------------------------- Sep 03 14:49:42 volumio volumio[27076]: info: ----- Volumio3 ---- Sep 03 14:49:42 volumio volumio[27076]: info: ------------------------------------------- Sep 03 14:49:42 volumio volumio[27076]: info: ----- System startup ---- Sep 03 14:49:42 volumio volumio[27076]: info: ------------------------------------------- Sep 03 14:49:43 volumio volumio[27076]: info: MYVOLUMIO Environment detected Sep 03 14:49:43 volumio volumio[27076]: info: Plugin folders cleanup Sep 03 14:49:43 volumio volumio[27076]: info: Scanning into folder /volumio/app/plugins/ Sep 03 14:49:43 volumio volumio[27076]: info: Scanning category audio_interface Sep 03 14:49:43 volumio volumio[27076]: info: Scanning category miscellanea Sep 03 14:49:43 volumio volumio[27076]: info: Scanning category music_service Sep 03 14:49:43 volumio volumio[27076]: info: Scanning category plugins.json Sep 03 14:49:43 volumio volumio[27076]: info: Scanning category system_controller Sep 03 14:49:43 volumio volumio[27076]: info: Scanning category user_interface Sep 03 14:49:43 volumio volumio[27076]: info: Scanning into folder /data/plugins/ Sep 03 14:49:43 volumio volumio[27076]: info: Scanning category audio_interface Sep 03 14:49:43 volumio volumio[27076]: info: Scanning category music_service Sep 03 14:49:43 volumio volumio[27076]: info: Scanning category user_interface Sep 03 14:49:43 volumio volumio[27076]: info: Plugin folders cleanup completed Sep 03 14:49:43 volumio volumio[27076]: info: ------------------------------------------- Sep 03 14:49:43 volumio volumio[27076]: info: ----- Core plugins startup ---- Sep 03 14:49:43 volumio volumio[27076]: info: ------------------------------------------- Sep 03 14:49:43 volumio volumio[27076]: info: Loading plugins from folder /volumio/app/plugins/ Sep 03 14:49:43 volumio volumio[27076]: info: Adding plugin upnp to MyMusic Plugins Sep 03 14:49:43 volumio volumio[27076]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 03 14:49:43 volumio volumio[27076]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 03 14:49:43 volumio volumio[27076]: info: Loading plugins from folder /data/plugins/ Sep 03 14:49:43 volumio volumio[27076]: info: Loading plugin "system"... Sep 03 14:49:43 volumio volumio[27076]: info: Loading plugin "appearance"... Sep 03 14:49:44 volumio volumio[27076]: info: Loading plugin "network"... Sep 03 14:49:44 volumio volumio[27076]: info: Refreshing Cached IP Addresses Sep 03 14:49:44 volumio sudo[27102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 03 14:49:44 volumio sudo[27102]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:44 volumio sudo[27102]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:44 volumio sudo[27104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 14:49:44 volumio sudo[27104]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:44 volumio volumio[27076]: info: Loading plugin "services"... Sep 03 14:49:44 volumio sudo[27104]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:44 volumio volumio[27076]: info: Loading plugin "alsa_controller"... Sep 03 14:49:44 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 14:49:44 volumio volumio[27076]: info: Loading plugin "wizard"... Sep 03 14:49:44 volumio volumio[27076]: info: Loading plugin "networkfs"... Sep 03 14:49:45 volumio volumio[27076]: info: Starting Udev Watcher for removable devices Sep 03 14:49:45 volumio volumio[27076]: info: Ignoring mount for partition: boot Sep 03 14:49:45 volumio volumio[27076]: info: Ignoring mount for partition: volumio Sep 03 14:49:45 volumio volumio[27076]: info: Ignoring mount for partition: volumio_data Sep 03 14:49:45 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 14:49:45 volumio volumio[27076]: info: Loading plugin "volumio_command_line_client"... Sep 03 14:49:45 volumio volumio[27076]: info: Loading plugin "upnp"... Sep 03 14:49:45 volumio volumio[27076]: info: [1756903785042] Starting Upmpd Daemon Sep 03 14:49:45 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 14:49:45 volumio volumio[27076]: info: Loading plugin "my_music"... Sep 03 14:49:45 volumio volumio[27076]: info: Loading plugin "mpd"... Sep 03 14:49:45 volumio volumio[27076]: info: Plugin upnp_browser is not enabled Sep 03 14:49:45 volumio volumio[27076]: info: Loading plugin "alarm-clock"... Sep 03 14:49:45 volumio volumio[27076]: info: Plugin airplay_emulation is not enabled Sep 03 14:49:45 volumio volumio[27076]: info: Loading plugin "last_100"... Sep 03 14:49:45 volumio volumio[27076]: info: Loading plugin "webradio"... Sep 03 14:49:45 volumio volumio[27076]: info: Loading plugin "i2s_dacs"... Sep 03 14:49:45 volumio volumio[27076]: info: Loading plugin "volumiodiscovery"... Sep 03 14:49:45 volumio volumio[27076]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 03 14:49:45 volumio volumio[27076]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 14:49:45 volumio volumio[27076]: *** WARNING *** For more information see Sep 03 14:49:45 volumio node[27076]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 03 14:49:45 volumio volumio[27076]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 03 14:49:45 volumio volumio[27076]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 14:49:45 volumio volumio[27076]: *** WARNING *** For more information see Sep 03 14:49:45 volumio node[27076]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 14:49:45 volumio node[27076]: *** WARNING *** For more information see Sep 03 14:49:45 volumio node[27076]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 03 14:49:45 volumio node[27076]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 14:49:45 volumio node[27076]: *** WARNING *** For more information see Sep 03 14:49:45 volumio volumio[27076]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 03 14:49:45 volumio volumio[27076]: info: Discovery: Started advertising with name: Volumio Sep 03 14:49:45 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 14:49:45 volumio volumio[27076]: info: Loading plugin "jellyfin"... Sep 03 14:49:46 volumio volumio[27076]: info: Loading plugin "spop"... Sep 03 14:49:47 volumio volumio[27076]: info: Loading plugin "ytcr"... Sep 03 14:49:51 volumio volumio-remote-updater[504]: [2025-09-03 14:49:51] [connect] Successful connection Sep 03 14:49:51 volumio volumio[27076]: info: Loading plugin "outputs"... Sep 03 14:49:51 volumio volumio[27076]: info: Loading plugin "albumart"... Sep 03 14:49:52 volumio volumio[27076]: info: Plugin example_plugin is not enabled Sep 03 14:49:52 volumio volumio[27076]: info: Loading plugin "inputs"... Sep 03 14:49:52 volumio volumio[27076]: info: Loading plugin "updater_comm"... Sep 03 14:49:52 volumio volumio[27076]: info: Plugin mpdemulation is not enabled Sep 03 14:49:52 volumio volumio[27076]: info: Loading plugin "rest_api"... Sep 03 14:49:52 volumio volumio[27076]: info: Loading plugin "websocket"... Sep 03 14:49:52 volumio volumio[27076]: info: Starting Socket.io Server version 2.3.0 Sep 03 14:49:52 volumio volumio[27076]: info: Loading plugin "fusiondsp"... Sep 03 14:49:52 volumio volumio[27076]: Forking 3 albumart workers Sep 03 14:49:52 volumio volumio[27076]: info: Applying required configuration parameters for plugin fusiondsp Sep 03 14:49:52 volumio volumio[27076]: info: Loading plugin "Systeminfo"... Sep 03 14:49:52 volumio volumio[27076]: info: Loading i18n strings for locale hu Sep 03 14:49:53 volumio volumio[27076]: Updating browse sources language Sep 03 14:49:53 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 14:49:53 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 03 14:49:53 volumio volumio[27076]: info: CoreCommandRouter::initPlayerControls Sep 03 14:49:53 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:53 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:53 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:53 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:53 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:53 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:53 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:53 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:53 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 03 14:49:53 volumio volumio[27076]: Express server listening on port 3000 Sep 03 14:49:53 volumio volumio[27076]: [Metrics] WebUI: 10s 942.63ms Sep 03 14:49:53 volumio volumio[27076]: info: CoreStateMachine::resetVolumioState Sep 03 14:49:53 volumio volumio[27076]: info: CoreStateMachine::getcurrentVolume Sep 03 14:49:53 volumio volumio[27076]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 14:49:53 volumio volumio[27076]: Starting albumart workers Sep 03 14:49:53 volumio volumio[27076]: info: Volumio Network Manager: Network status updated: 1 Sep 03 14:49:53 volumio volumio[27076]: Starting albumart workers Sep 03 14:49:53 volumio volumio-remote-updater[504]: [2025-09-03 14:49:53] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1756903791 101 Sep 03 14:49:53 volumio volumio[27076]: Starting albumart workers Sep 03 14:49:53 volumio volumio[27076]: 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 03 14:49:53 volumio volumio[27076]: info: CoreCommandRouter::volumioGetState Sep 03 14:49:53 volumio volumio[27076]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:53 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 14:49:53 volumio volumio[27076]: info: VolumeController:: Volume=37 Mute =false Sep 03 14:49:53 volumio volumio[27076]: info: CoreStateMachine::pushState Sep 03 14:49:53 volumio volumio[27076]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:53 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 14:49:53 volumio volumio[27076]: info: CoreCommandRouter::volumioPushState Sep 03 14:49:53 volumio volumio[27076]: info: CoreStateMachine::updateTrackBlock Sep 03 14:49:53 volumio volumio[27076]: info: CorePlayQueue::getTrackBlock Sep 03 14:49:53 volumio volumio[27076]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 14:49:53 volumio volumio[27076]: info: Setting Device type: Raspberry PI Sep 03 14:49:53 volumio volumio[27076]: info: CoreStateMachine::setRepeat false single undefined Sep 03 14:49:53 volumio volumio[27076]: info: CoreStateMachine::pushState Sep 03 14:49:53 volumio volumio[27076]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:53 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 14:49:53 volumio volumio[27076]: info: CoreCommandRouter::volumioPushState Sep 03 14:49:53 volumio volumio[27076]: info: CoreStateMachine::setRandom true Sep 03 14:49:53 volumio volumio[27076]: info: CoreStateMachine::pushState Sep 03 14:49:53 volumio volumio[27076]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:53 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 14:49:53 volumio volumio[27076]: info: CoreCommandRouter::volumioPushState Sep 03 14:49:53 volumio volumio[27076]: info: Listing playlists Sep 03 14:49:53 volumio volumio[27076]: info: Listing playlists Sep 03 14:49:53 volumio volumio[27076]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227 Sep 03 14:49:53 volumio volumio[27076]: info: Discovery: Found device Volumio Sep 03 14:49:53 volumio volumio[27076]: info: CoreCommandRouter::volumioGetState Sep 03 14:49:53 volumio volumio[27076]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:53 volumio volumio[27076]: info: Completed loading Core Plugins Sep 03 14:49:53 volumio volumio[27076]: info: Preparing to generate the ALSA configuration file Sep 03 14:49:53 volumio volumio[27076]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 03 14:49:53 volumio volumio[27076]: info: Reading ALSA contributions from plugins. Sep 03 14:49:53 volumio volumio[27076]: info: VolumeController:: Volume=37 Mute =false Sep 03 14:49:53 volumio volumio[27076]: info: CoreStateMachine::pushState Sep 03 14:49:53 volumio volumio[27076]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:53 volumio volumio[27076]: info: CoreCommandRouter::volumioPushState Sep 03 14:49:53 volumio volumio[27076]: info: Asound.conf file unchanged, so no further update is needed Sep 03 14:49:53 volumio volumio[27076]: info: Output device has changed, restarting MPD Sep 03 14:49:54 volumio volumio[27076]: info: ___________ START PLUGINS ___________ Sep 03 14:49:54 volumio volumio[27076]: info: ControllerMpd::onStart: Initializing MPD Sep 03 14:49:54 volumio volumio[27076]: info: Creating MPD Configuration file Sep 03 14:49:54 volumio sudo[27171]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 14:49:54 volumio sudo[27171]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:54 volumio sudo[27171]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:54 volumio sudo[27173]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 14:49:54 volumio sudo[27173]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:54 volumio systemd[1]: Stopping Music Player Daemon... Sep 03 14:49:54 volumio sudo[27181]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 14:49:54 volumio sudo[27181]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:54 volumio sudo[27181]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:54 volumio volumio[27076]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 14:49:54 volumio volumio[27076]: info: [1756903794179] CoreMusicLibrary::Adding element Last_100 Sep 03 14:49:54 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 14:49:54 volumio volumio[27076]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 14:49:54 volumio volumio[27076]: info: [1756903794187] CoreMusicLibrary::Adding element Webradio Sep 03 14:49:54 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 14:49:54 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 14:49:54 volumio systemd[1]: mpd.service: Succeeded. Sep 03 14:49:54 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 14:49:54 volumio volumio[27076]: info: Initializing BBC Radios Sep 03 14:49:54 volumio sudo[27188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 14:49:54 volumio sudo[27188]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:54 volumio systemd[1]: Starting Music Player Daemon... Sep 03 14:49:54 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 03 14:49:54 volumio systemd[1]: mpd.service: Succeeded. Sep 03 14:49:54 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 14:49:54 volumio systemd[1]: Starting Music Player Daemon... Sep 03 14:49:54 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 14:49:54 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 14:49:54 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 14:49:54 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 03 14:49:54 volumio volumio[27076]: info: Discovery: Getting this device information Sep 03 14:49:54 volumio volumio[27076]: info: CoreCommandRouter::volumioGetState Sep 03 14:49:54 volumio volumio[27076]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:54 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 14:49:54 volumio volumio[27076]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 14:49:54 volumio volumio[27076]: info: [1756903794406] CoreMusicLibrary::Adding element Jellyfin Sep 03 14:49:54 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 14:49:54 volumio volumio[27076]: Cannot find translation for source Jellyfin Sep 03 14:49:54 volumio volumio[27076]: 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 03 14:49:54 volumio volumio[27076]: info: Creating Spotify config file Sep 03 14:49:54 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:54 volumio sudo[27205]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 03 14:49:54 volumio sudo[27205]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:54 volumio sudo[27205]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:54 volumio volumio[27076]: info: Loading i18n strings for locale hu Sep 03 14:49:54 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 03 14:49:54 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:54 volumio volumio[27076]: info: FusionDsp - mixtype--------------------- Hardware Sep 03 14:49:54 volumio volumio[27076]: info: Volumio Calling Home Sep 03 14:49:54 volumio volumio[27076]: info: Preparing to generate the ALSA configuration file Sep 03 14:49:54 volumio volumio[27076]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 03 14:49:54 volumio volumio[27076]: info: Reading ALSA contributions from plugins. Sep 03 14:49:54 volumio volumio[27076]: info: MPD Permissions set Sep 03 14:49:54 volumio volumio[27076]: info: MPD Permissions set Sep 03 14:49:54 volumio volumio[27076]: info: Spotify config file written Sep 03 14:49:55 volumio sudo[27237]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 03 14:49:55 volumio sudo[27237]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:55 volumio systemd[1]: Stopping go-librespot Daemon... Sep 03 14:49:55 volumio volumio[27076]: 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 03 14:49:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 03 14:49:55 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 03 14:49:55 volumio systemd[1]: Stopped go-librespot Daemon. Sep 03 14:49:55 volumio volumio[27076]: info: Volumio called home Sep 03 14:49:55 volumio sudo[27242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 03 14:49:55 volumio sudo[27242]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:55 volumio systemd[1]: Started go-librespot Daemon. Sep 03 14:49:55 volumio go-librespot[27240]: go-librespot daemon starting... Sep 03 14:49:55 volumio sudo[27242]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:55 volumio sudo[27237]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:55 volumio sudo[27246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 14:49:55 volumio sudo[27246]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:55 volumio go-librespot[27240]: time="2025-09-03T14:49:55+02:00" level=info msg="running go-librespot 0.3.2" Sep 03 14:49:55 volumio go-librespot[27240]: time="2025-09-03T14:49:55+02:00" level=debug msg="app state loaded" Sep 03 14:49:55 volumio go-librespot[27240]: time="2025-09-03T14:49:55+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 03 14:49:55 volumio sudo[27246]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:55 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:55 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:55 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:55 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:55 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:55 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:55 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:55 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:55 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 14:49:55 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:55 volumio volumio[27076]: info: No need to fix Spotify hosts Sep 03 14:49:55 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:55 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:55 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:55 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:55 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 14:49:55 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:55 volumio go-librespot[27240]: time="2025-09-03T14:49:55+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 03 14:49:55 volumio go-librespot[27240]: time="2025-09-03T14:49:55+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 03 14:49:55 volumio go-librespot[27240]: time="2025-09-03T14:49:55+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 03 14:49:55 volumio go-librespot[27240]: time="2025-09-03T14:49:55+02:00" level=info msg="zeroconf server listening on port 35815" Sep 03 14:49:55 volumio go-librespot[27240]: time="2025-09-03T14:49:55+02:00" level=debug msg="obtained new client token: AAA6q0Jnvy1Yr1kY26xj9I2S8OI5JRSeYZNI6pbajtVSxyYOY8+R2Kx1Prneuh+z/pBTwenV3BkuHuIhjH6+VcIukx1Pcu9R57mEoBr8yTayXwVRWhBmIIoLGKvSFVY9W02Kpgkl9l7BtdZwSgRAcKH49VGWjHZgEwOLw34qDHptrf/zIkHaufnvc+VEvtvX1QyHgFLhmi4xEz84YGeFayB9N6CZ0AAe/oAGt19ZzVW6pSaMDVK8OhJTL5E=" Sep 03 14:49:55 volumio go-librespot[27240]: time="2025-09-03T14:49:55+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 03 14:49:55 volumio volumio[27076]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 03 14:49:55 volumio volumio[27076]: SPOTIFY: BQC8ndTmuMw5NzOcetozZQjG8jb4Mj-kSFrMYyfrx3vSGuuz6H49VutnP6kQ947nU68rbRl2t6AqF1EBbWvQd2z40ta5ughzuUn3U7sguIcQKw6RWVRJUVytobREeiyj7KwWUlSPRIlRb9Dn7tuIGsQeo8I2AC8_cuhFcXJkuOlwSHrQOj9sYLMCIMAMEa8lOyXj1LhL29m-hQfuTrM0UMu4AAaATgWT-yilD7zwlGhIe_oWs-iW667d38NM_LmjVADU042HMZ1KqDC-asFehhF-evzt7SqsIjbnXrqpuKKMTnE Sep 03 14:49:55 volumio volumio[27076]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 03 14:49:55 volumio volumio[27076]: info: New Spotify access token = BQC8ndTmuMw5NzOcetozZQjG8jb4Mj-kSFrMYyfrx3vSGuuz6H49VutnP6kQ947nU68rbRl2t6AqF1EBbWvQd2z40ta5ughzuUn3U7sguIcQKw6RWVRJUVytobREeiyj7KwWUlSPRIlRb9Dn7tuIGsQeo8I2AC8_cuhFcXJkuOlwSHrQOj9sYLMCIMAMEa8lOyXj1LhL29m-hQfuTrM0UMu4AAaATgWT-yilD7zwlGhIe_oWs-iW667d38NM_LmjVADU042HMZ1KqDC-asFehhF-evzt7SqsIjbnXrqpuKKMTnE Sep 03 14:49:55 volumio volumio[27076]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 03 14:49:55 volumio go-librespot[27240]: time="2025-09-03T14:49:55+02:00" level=debug msg="completed keyexchange" Sep 03 14:49:55 volumio go-librespot[27240]: time="2025-09-03T14:49:55+02:00" level=debug msg="completed challenge" Sep 03 14:49:55 volumio volumio[27076]: info: CoreCommandRouter::volumioGetState Sep 03 14:49:55 volumio volumio[27076]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:55 volumio go-librespot[27240]: time="2025-09-03T14:49:55+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 03 14:49:55 volumio volumio[27076]: info: Asound.conf file unchanged, so no further update is needed Sep 03 14:49:55 volumio volumio[27076]: info: Output device has changed, restarting MPD Sep 03 14:49:55 volumio sudo[27263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 14:49:55 volumio sudo[27263]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:55 volumio sudo[27263]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:55 volumio sudo[27265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 14:49:55 volumio sudo[27265]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:55 volumio go-librespot[27240]: time="2025-09-03T14:49:55+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 03 14:49:55 volumio go-librespot[27240]: time="2025-09-03T14:49:55+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 03 14:49:55 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 03 14:49:55 volumio systemd[1]: mpd.service: Succeeded. Sep 03 14:49:55 volumio volumio[27076]: info: MPD Permissions set Sep 03 14:49:55 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 14:49:55 volumio sudo[27269]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 03 14:49:55 volumio sudo[27269]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:55 volumio systemd[1]: Starting Music Player Daemon... Sep 03 14:49:55 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:55 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:55 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:55 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:55 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:55 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:55 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:55 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:55 volumio go-librespot[27240]: time="2025-09-03T14:49:55+02:00" level=debug msg="dealer connection opened" Sep 03 14:49:55 volumio go-librespot[27240]: time="2025-09-03T14:49:55+02:00" level=trace msg="starting accesspoint recv loop" Sep 03 14:49:55 volumio go-librespot[27240]: time="2025-09-03T14:49:55+02:00" level=trace msg="starting dealer recv loop" Sep 03 14:49:55 volumio go-librespot[27240]: time="2025-09-03T14:49:55+02:00" level=trace msg="received accesspoint ping" Sep 03 14:49:55 volumio go-librespot[27240]: time="2025-09-03T14:49:55+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 03 14:49:55 volumio go-librespot[27240]: time="2025-09-03T14:49:55+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 03 14:49:55 volumio go-librespot[27240]: time="2025-09-03T14:49:55+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 03 14:49:55 volumio go-librespot[27240]: time="2025-09-03T14:49:55+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 03 14:49:55 volumio go-librespot[27240]: time="2025-09-03T14:49:55+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 03 14:49:55 volumio go-librespot[27240]: time="2025-09-03T14:49:55+02:00" level=debug msg="received connection id: M2M0Y2FhYmItY2Qy...RTIwNjI1NzlBMw==" Sep 03 14:49:55 volumio sudo[27269]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:55 volumio go-librespot[27240]: time="2025-09-03T14:49:55+02:00" level=trace msg="received accesspoint pong ack" Sep 03 14:49:55 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 14:49:55 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:55 volumio volumio[27076]: info: Upmpdcli Daemon Started Sep 03 14:49:56 volumio volumio[27076]: 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 03 14:49:56 volumio volumio[27076]: info: Spotify Successfully logged in Sep 03 14:49:56 volumio volumio[27076]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 14:49:56 volumio volumio[27076]: info: [1756903796017] CoreMusicLibrary::Adding element Spotify Sep 03 14:49:56 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 14:49:56 volumio volumio[27076]: Cannot find translation for source Jellyfin Sep 03 14:49:56 volumio volumio[27076]: Cannot find translation for source Spotify Sep 03 14:49:56 volumio sudo[27270]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 03 14:49:56 volumio sudo[27270]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:56 volumio sudo[27270]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:56 volumio go-librespot[27240]: time="2025-09-03T14:49:56+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 03 14:49:56 volumio volumio[27076]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 03 14:49:56 volumio volumio[27076]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 14:49:56 volumio volumio[27076]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 03 14:49:56 volumio volumio[27076]: 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 03 14:49:56 volumio volumio[27076]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 03 14:49:56 volumio volumio[27076]: info: camilladsp service started and running in background, instance 1 Sep 03 14:49:56 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:49:56 volumio volumio[27076]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 03 14:49:56 volumio volumio[27076]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 03 14:49:56 volumio volumio[27076]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 03 14:49:56 volumio volumio[27076]: info: FusionDsp loaded Sep 03 14:49:56 volumio volumio[27076]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 14:49:56 volumio sudo[27291]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 03 14:49:56 volumio sudo[27291]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:56 volumio sudo[27291]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:56 volumio volumio[27076]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 03 14:49:56 volumio volumio[27076]: info: Adding Signal Path Element [object Object] Sep 03 14:49:56 volumio volumio[27076]: info: Adding fusiondspeq DSP Signal Path Element Sep 03 14:49:56 volumio volumio[27076]: info: FusionDsp - ---- installed callbackRead Sep 03 14:49:56 volumio volumio[27076]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 14:49:56 volumio volumio[27076]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 03 14:49:56 volumio volumio[27076]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 03 14:49:56 volumio volumio[27076]: at onErrorNT (internal/child_process.js:465:16) Sep 03 14:49:56 volumio volumio[27076]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Sep 03 14:49:56 volumio volumio[27076]: at runNextTicks (internal/process/task_queues.js:62:3) Sep 03 14:49:56 volumio volumio[27076]: at listOnTimeout (internal/timers.js:523:9) Sep 03 14:49:56 volumio volumio[27076]: at processTimers (internal/timers.js:497:7) { Sep 03 14:49:56 volumio volumio[27076]: errno: -2, Sep 03 14:49:56 volumio volumio[27076]: code: 'ENOENT', Sep 03 14:49:56 volumio volumio[27076]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 03 14:49:56 volumio volumio[27076]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 03 14:49:56 volumio volumio[27076]: spawnargs: [ Sep 03 14:49:56 volumio volumio[27076]: '-p', Sep 03 14:49:56 volumio volumio[27076]: 9876, Sep 03 14:49:56 volumio volumio[27076]: '-o', Sep 03 14:49:56 volumio volumio[27076]: '/tmp/camilladsp.log', Sep 03 14:49:56 volumio volumio[27076]: '-l', Sep 03 14:49:56 volumio volumio[27076]: 'warn', Sep 03 14:49:56 volumio volumio[27076]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 03 14:49:56 volumio volumio[27076]: ] Sep 03 14:49:56 volumio volumio[27076]: } Sep 03 14:49:56 volumio volumio[27076]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 14:49:57 volumio sudo[27302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-03 14:48 Sep 03 14:49:57 volumio sudo[27302]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:49:57 volumio mpd[27278]: Sep 03 14:49 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 03 14:49:57 volumio systemd[1]: Started Music Player Daemon. Sep 03 14:49:57 volumio sudo[27188]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:57 volumio sudo[27173]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:57 volumio sudo[27265]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:57 volumio sudo[27302]: pam_unix(sudo:session): session closed for user root Sep 03 14:49:57 volumio volumio-remote-updater[504]: [2025-09-03 14:49:57] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 03 14:49:57 volumio volumio-remote-updater[504]: [2025-09-03 14:49:57] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 03 14:49:57 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 03 14:49:57 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 03 14:49:57 volumio systemd[1]: Started dynamicswap service. Sep 03 14:49:57 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 03 14:49:57 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 03 14:49:57 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 8640. Sep 03 14:49:57 volumio systemd[1]: Started dynamicswap service. Sep 03 14:49:57 volumio systemd[1]: Stopped Volumio Backend Module. Sep 03 14:49:57 volumio systemd[1]: Started Volumio Backend Module. Sep 03 14:49:57 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 03 14:49:59 volumio volumio[27318]: info: ------------------------------------------- Sep 03 14:49:59 volumio volumio[27318]: info: ----- Volumio3 ---- Sep 03 14:49:59 volumio volumio[27318]: info: ------------------------------------------- Sep 03 14:49:59 volumio volumio[27318]: info: ----- System startup ---- Sep 03 14:49:59 volumio volumio[27318]: info: ------------------------------------------- Sep 03 14:50:00 volumio volumio[27318]: info: MYVOLUMIO Environment detected Sep 03 14:50:00 volumio volumio[27318]: info: Plugin folders cleanup Sep 03 14:50:00 volumio volumio[27318]: info: Scanning into folder /volumio/app/plugins/ Sep 03 14:50:00 volumio volumio[27318]: info: Scanning category audio_interface Sep 03 14:50:00 volumio volumio[27318]: info: Scanning category miscellanea Sep 03 14:50:00 volumio volumio[27318]: info: Scanning category music_service Sep 03 14:50:00 volumio volumio[27318]: info: Scanning category plugins.json Sep 03 14:50:00 volumio volumio[27318]: info: Scanning category system_controller Sep 03 14:50:00 volumio volumio[27318]: info: Scanning category user_interface Sep 03 14:50:00 volumio volumio[27318]: info: Scanning into folder /data/plugins/ Sep 03 14:50:00 volumio volumio[27318]: info: Scanning category audio_interface Sep 03 14:50:00 volumio volumio[27318]: info: Scanning category music_service Sep 03 14:50:00 volumio volumio[27318]: info: Scanning category user_interface Sep 03 14:50:00 volumio volumio[27318]: info: Plugin folders cleanup completed Sep 03 14:50:00 volumio volumio[27318]: info: ------------------------------------------- Sep 03 14:50:00 volumio volumio[27318]: info: ----- Core plugins startup ---- Sep 03 14:50:00 volumio volumio[27318]: info: ------------------------------------------- Sep 03 14:50:00 volumio volumio[27318]: info: Loading plugins from folder /volumio/app/plugins/ Sep 03 14:50:00 volumio volumio[27318]: info: Adding plugin upnp to MyMusic Plugins Sep 03 14:50:00 volumio volumio[27318]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 03 14:50:00 volumio volumio[27318]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 03 14:50:00 volumio volumio[27318]: info: Loading plugins from folder /data/plugins/ Sep 03 14:50:00 volumio volumio[27318]: info: Loading plugin "system"... Sep 03 14:50:00 volumio volumio[27318]: info: Loading plugin "appearance"... Sep 03 14:50:01 volumio volumio[27318]: info: Loading plugin "network"... Sep 03 14:50:01 volumio volumio[27318]: info: Refreshing Cached IP Addresses Sep 03 14:50:01 volumio sudo[27345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 03 14:50:01 volumio sudo[27345]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:50:01 volumio sudo[27345]: pam_unix(sudo:session): session closed for user root Sep 03 14:50:01 volumio sudo[27347]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 14:50:01 volumio volumio[27318]: info: Loading plugin "services"... Sep 03 14:50:01 volumio sudo[27347]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:50:01 volumio volumio[27318]: info: Loading plugin "alsa_controller"... Sep 03 14:50:01 volumio sudo[27347]: pam_unix(sudo:session): session closed for user root Sep 03 14:50:01 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 14:50:01 volumio volumio[27318]: info: Loading plugin "wizard"... Sep 03 14:50:01 volumio volumio[27318]: info: Loading plugin "networkfs"... Sep 03 14:50:01 volumio volumio[27318]: info: Starting Udev Watcher for removable devices Sep 03 14:50:01 volumio volumio[27318]: info: Ignoring mount for partition: boot Sep 03 14:50:01 volumio volumio[27318]: info: Ignoring mount for partition: volumio Sep 03 14:50:01 volumio volumio[27318]: info: Ignoring mount for partition: volumio_data Sep 03 14:50:01 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 14:50:01 volumio volumio[27318]: info: Loading plugin "volumio_command_line_client"... Sep 03 14:50:01 volumio volumio[27318]: info: Loading plugin "upnp"... Sep 03 14:50:01 volumio volumio[27318]: info: [1756903801774] Starting Upmpd Daemon Sep 03 14:50:01 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 14:50:01 volumio volumio[27318]: info: Loading plugin "my_music"... Sep 03 14:50:01 volumio volumio[27318]: info: Loading plugin "mpd"... Sep 03 14:50:02 volumio volumio[27318]: info: Plugin upnp_browser is not enabled Sep 03 14:50:02 volumio volumio[27318]: info: Loading plugin "alarm-clock"... Sep 03 14:50:02 volumio volumio[27318]: info: Plugin airplay_emulation is not enabled Sep 03 14:50:02 volumio volumio[27318]: info: Loading plugin "last_100"... Sep 03 14:50:02 volumio volumio[27318]: info: Loading plugin "webradio"... Sep 03 14:50:02 volumio volumio[27318]: info: Loading plugin "i2s_dacs"... Sep 03 14:50:02 volumio volumio[27318]: info: Loading plugin "volumiodiscovery"... Sep 03 14:50:02 volumio volumio[27318]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 03 14:50:02 volumio volumio[27318]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 14:50:02 volumio volumio[27318]: *** WARNING *** For more information see Sep 03 14:50:02 volumio node[27318]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 03 14:50:02 volumio volumio[27318]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 03 14:50:02 volumio volumio[27318]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 14:50:02 volumio volumio[27318]: *** WARNING *** For more information see Sep 03 14:50:02 volumio node[27318]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 14:50:02 volumio node[27318]: *** WARNING *** For more information see Sep 03 14:50:02 volumio node[27318]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 03 14:50:02 volumio node[27318]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 14:50:02 volumio node[27318]: *** WARNING *** For more information see Sep 03 14:50:02 volumio volumio[27318]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 03 14:50:02 volumio volumio[27318]: info: Discovery: Started advertising with name: Volumio Sep 03 14:50:02 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 14:50:02 volumio volumio[27318]: info: Loading plugin "jellyfin"... Sep 03 14:50:02 volumio volumio-remote-updater[504]: [2025-09-03 14:50:02] [connect] Successful connection Sep 03 14:50:03 volumio volumio[27318]: info: Loading plugin "spop"... Sep 03 14:50:04 volumio volumio[27318]: info: Loading plugin "ytcr"... Sep 03 14:50:08 volumio volumio[27318]: info: Loading plugin "outputs"... Sep 03 14:50:08 volumio volumio[27318]: info: Loading plugin "albumart"... Sep 03 14:50:08 volumio volumio[27318]: info: Plugin example_plugin is not enabled Sep 03 14:50:08 volumio volumio[27318]: info: Loading plugin "inputs"... Sep 03 14:50:08 volumio volumio[27318]: info: Loading plugin "updater_comm"... Sep 03 14:50:08 volumio volumio[27318]: info: Plugin mpdemulation is not enabled Sep 03 14:50:08 volumio volumio[27318]: info: Loading plugin "rest_api"... Sep 03 14:50:09 volumio volumio[27318]: info: Loading plugin "websocket"... Sep 03 14:50:09 volumio volumio[27318]: info: Starting Socket.io Server version 2.3.0 Sep 03 14:50:09 volumio volumio[27318]: info: Loading plugin "fusiondsp"... Sep 03 14:50:09 volumio volumio[27318]: Forking 3 albumart workers Sep 03 14:50:09 volumio volumio[27318]: info: Applying required configuration parameters for plugin fusiondsp Sep 03 14:50:09 volumio volumio[27318]: info: Loading plugin "Systeminfo"... Sep 03 14:50:09 volumio volumio[27318]: info: Loading i18n strings for locale hu Sep 03 14:50:09 volumio volumio[27318]: Updating browse sources language Sep 03 14:50:09 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 14:50:10 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 03 14:50:10 volumio volumio[27318]: info: CoreCommandRouter::initPlayerControls Sep 03 14:50:10 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:10 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:10 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:10 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:10 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:10 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:10 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:10 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:10 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 03 14:50:10 volumio volumio[27318]: Express server listening on port 3000 Sep 03 14:50:10 volumio volumio[27318]: [Metrics] WebUI: 11s 9.32ms Sep 03 14:50:10 volumio volumio[27318]: info: CoreStateMachine::resetVolumioState Sep 03 14:50:10 volumio volumio[27318]: info: CoreStateMachine::getcurrentVolume Sep 03 14:50:10 volumio volumio[27318]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 14:50:10 volumio volumio[27318]: Starting albumart workers Sep 03 14:50:10 volumio volumio[27318]: Starting albumart workers Sep 03 14:50:10 volumio volumio[27318]: info: Volumio Network Manager: Network status updated: 1 Sep 03 14:50:10 volumio volumio[27318]: Starting albumart workers Sep 03 14:50:10 volumio volumio[27318]: info: CoreCommandRouter::volumioGetState Sep 03 14:50:10 volumio volumio[27318]: info: CorePlayQueue::getTrack 0 Sep 03 14:50:10 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 14:50:10 volumio volumio[27318]: info: VolumeController:: Volume=37 Mute =false Sep 03 14:50:10 volumio volumio[27318]: info: CoreStateMachine::pushState Sep 03 14:50:10 volumio volumio[27318]: info: CorePlayQueue::getTrack 0 Sep 03 14:50:10 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 14:50:10 volumio volumio[27318]: info: CoreCommandRouter::volumioPushState Sep 03 14:50:10 volumio volumio[27318]: info: CoreStateMachine::updateTrackBlock Sep 03 14:50:10 volumio volumio[27318]: info: CorePlayQueue::getTrackBlock Sep 03 14:50:10 volumio volumio[27318]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 14:50:10 volumio volumio[27318]: info: CoreStateMachine::setRepeat false single undefined Sep 03 14:50:10 volumio volumio[27318]: info: CoreStateMachine::pushState Sep 03 14:50:10 volumio volumio[27318]: info: CorePlayQueue::getTrack 0 Sep 03 14:50:10 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 14:50:10 volumio volumio[27318]: info: CoreCommandRouter::volumioPushState Sep 03 14:50:10 volumio volumio[27318]: info: CoreStateMachine::setRandom true Sep 03 14:50:10 volumio volumio[27318]: info: CoreStateMachine::pushState Sep 03 14:50:10 volumio volumio[27318]: info: CorePlayQueue::getTrack 0 Sep 03 14:50:10 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 14:50:10 volumio volumio[27318]: info: CoreCommandRouter::volumioPushState Sep 03 14:50:10 volumio volumio[27318]: info: Setting Device type: Raspberry PI Sep 03 14:50:10 volumio volumio[27318]: info: Listing playlists Sep 03 14:50:10 volumio volumio[27318]: info: Listing playlists Sep 03 14:50:10 volumio volumio[27318]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227 Sep 03 14:50:10 volumio volumio[27318]: info: Discovery: Found device Volumio Sep 03 14:50:10 volumio volumio[27318]: info: CoreCommandRouter::volumioGetState Sep 03 14:50:10 volumio volumio[27318]: info: CorePlayQueue::getTrack 0 Sep 03 14:50:10 volumio volumio[27318]: info: Completed loading Core Plugins Sep 03 14:50:10 volumio volumio[27318]: info: Preparing to generate the ALSA configuration file Sep 03 14:50:10 volumio volumio[27318]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 03 14:50:10 volumio volumio[27318]: info: Reading ALSA contributions from plugins. Sep 03 14:50:10 volumio volumio[27318]: info: VolumeController:: Volume=37 Mute =false Sep 03 14:50:10 volumio volumio[27318]: info: CoreStateMachine::pushState Sep 03 14:50:10 volumio volumio[27318]: info: CorePlayQueue::getTrack 0 Sep 03 14:50:10 volumio volumio[27318]: info: CoreCommandRouter::volumioPushState Sep 03 14:50:10 volumio volumio[27318]: info: Asound.conf file unchanged, so no further update is needed Sep 03 14:50:10 volumio volumio[27318]: info: Output device has changed, restarting MPD Sep 03 14:50:10 volumio volumio[27318]: info: ___________ START PLUGINS ___________ Sep 03 14:50:10 volumio volumio[27318]: info: ControllerMpd::onStart: Initializing MPD Sep 03 14:50:10 volumio sudo[27421]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 14:50:10 volumio volumio[27318]: info: Creating MPD Configuration file Sep 03 14:50:10 volumio sudo[27421]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:50:10 volumio sudo[27421]: pam_unix(sudo:session): session closed for user root Sep 03 14:50:11 volumio sudo[27423]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 14:50:11 volumio sudo[27423]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:50:11 volumio systemd[1]: Stopping Music Player Daemon... Sep 03 14:50:11 volumio sudo[27437]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 14:50:11 volumio sudo[27437]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:50:11 volumio volumio[27318]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 14:50:11 volumio volumio[27318]: info: [1756903811061] CoreMusicLibrary::Adding element Last_100 Sep 03 14:50:11 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 14:50:11 volumio sudo[27437]: pam_unix(sudo:session): session closed for user root Sep 03 14:50:11 volumio systemd[1]: mpd.service: Succeeded. Sep 03 14:50:11 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 14:50:11 volumio volumio[27318]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 14:50:11 volumio volumio[27318]: info: [1756903811089] CoreMusicLibrary::Adding element Webradio Sep 03 14:50:11 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 14:50:11 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 14:50:11 volumio sudo[27445]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 14:50:11 volumio sudo[27445]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:50:11 volumio volumio[27318]: info: Initializing BBC Radios Sep 03 14:50:11 volumio systemd[1]: Starting Music Player Daemon... Sep 03 14:50:11 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 03 14:50:11 volumio systemd[1]: mpd.service: Succeeded. Sep 03 14:50:11 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 14:50:11 volumio systemd[1]: Starting Music Player Daemon... Sep 03 14:50:11 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 14:50:11 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 14:50:11 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 14:50:11 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 03 14:50:11 volumio volumio[27318]: info: Discovery: Getting this device information Sep 03 14:50:11 volumio volumio[27318]: info: CoreCommandRouter::volumioGetState Sep 03 14:50:11 volumio volumio[27318]: info: CorePlayQueue::getTrack 0 Sep 03 14:50:11 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 14:50:11 volumio volumio[27318]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 14:50:11 volumio volumio[27318]: info: [1756903811265] CoreMusicLibrary::Adding element Jellyfin Sep 03 14:50:11 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 14:50:11 volumio volumio[27318]: Cannot find translation for source Jellyfin Sep 03 14:50:11 volumio volumio[27318]: 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 03 14:50:11 volumio volumio[27318]: info: Creating Spotify config file Sep 03 14:50:11 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:11 volumio sudo[27451]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 03 14:50:11 volumio sudo[27451]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:50:11 volumio sudo[27451]: pam_unix(sudo:session): session closed for user root Sep 03 14:50:11 volumio volumio[27318]: info: Loading i18n strings for locale hu Sep 03 14:50:11 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 03 14:50:11 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:11 volumio volumio[27318]: info: FusionDsp - mixtype--------------------- Hardware Sep 03 14:50:11 volumio volumio[27318]: info: Volumio Calling Home Sep 03 14:50:11 volumio volumio[27318]: info: Preparing to generate the ALSA configuration file Sep 03 14:50:11 volumio volumio[27318]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 03 14:50:11 volumio volumio[27318]: info: Reading ALSA contributions from plugins. Sep 03 14:50:11 volumio volumio[27318]: info: MPD Permissions set Sep 03 14:50:11 volumio volumio[27318]: info: MPD Permissions set Sep 03 14:50:11 volumio volumio[27318]: info: Spotify config file written Sep 03 14:50:11 volumio sudo[27481]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 03 14:50:11 volumio sudo[27481]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:50:11 volumio sudo[27483]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 14:50:11 volumio sudo[27483]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:50:11 volumio sudo[27481]: pam_unix(sudo:session): session closed for user root Sep 03 14:50:11 volumio sudo[27483]: pam_unix(sudo:session): session closed for user root Sep 03 14:50:11 volumio sudo[27486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 03 14:50:11 volumio sudo[27486]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:50:11 volumio systemd[1]: Stopping go-librespot Daemon... Sep 03 14:50:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 03 14:50:11 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 03 14:50:11 volumio systemd[1]: Stopped go-librespot Daemon. Sep 03 14:50:12 volumio systemd[1]: Started go-librespot Daemon. Sep 03 14:50:12 volumio go-librespot[27489]: go-librespot daemon starting... Sep 03 14:50:12 volumio sudo[27486]: pam_unix(sudo:session): session closed for user root Sep 03 14:50:12 volumio go-librespot[27489]: time="2025-09-03T14:50:12+02:00" level=info msg="running go-librespot 0.3.2" Sep 03 14:50:12 volumio go-librespot[27489]: time="2025-09-03T14:50:12+02:00" level=debug msg="app state loaded" Sep 03 14:50:12 volumio go-librespot[27489]: time="2025-09-03T14:50:12+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 03 14:50:12 volumio volumio[27318]: 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 03 14:50:12 volumio volumio[27318]: info: Volumio called home Sep 03 14:50:12 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:12 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:12 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:12 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:12 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:12 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:12 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:12 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:12 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 14:50:12 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:12 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:12 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:12 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 14:50:12 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:12 volumio volumio[27318]: info: No need to fix Spotify hosts Sep 03 14:50:12 volumio go-librespot[27489]: time="2025-09-03T14:50: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-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Sep 03 14:50:12 volumio go-librespot[27489]: time="2025-09-03T14:50:12+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 03 14:50:12 volumio go-librespot[27489]: time="2025-09-03T14:50:12+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 03 14:50:12 volumio go-librespot[27489]: time="2025-09-03T14:50:12+02:00" level=info msg="zeroconf server listening on port 34537" Sep 03 14:50:12 volumio go-librespot[27489]: time="2025-09-03T14:50:12+02:00" level=debug msg="obtained new client token: AABpgFTM/S9T14JkEihbBsFPdzdkqXyfYuuN8SrrpChfVzPI2MybLLZWh6WNlT/LV0x7ePNP1PJaaLSdsdx2sw9zF57Yt//uznVVX1Br/xN0BtrtLPb3Q1dxVC4olodE044/emoCwLVhn1B28X7/fJVw9/Hm/q+s5x6GUuL2yQQyDG1/omCHQfD7w+cZD0vPjJndDW1Orjrnv3Sm1jXjzwoXoxt2RoU7lTrkyghCG6Y2Lpqr2XmXaguIhKs=" Sep 03 14:50:12 volumio volumio[27318]: info: CoreCommandRouter::volumioGetState Sep 03 14:50:12 volumio volumio[27318]: info: CorePlayQueue::getTrack 0 Sep 03 14:50:12 volumio go-librespot[27489]: time="2025-09-03T14:50:12+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 03 14:50:12 volumio volumio[27318]: info: CoreCommandRouter::volumioGetState Sep 03 14:50:12 volumio volumio[27318]: info: CorePlayQueue::getTrack 0 Sep 03 14:50:12 volumio go-librespot[27489]: time="2025-09-03T14:50:12+02:00" level=debug msg="completed keyexchange" Sep 03 14:50:12 volumio go-librespot[27489]: time="2025-09-03T14:50:12+02:00" level=debug msg="completed challenge" Sep 03 14:50:12 volumio volumio[27318]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 03 14:50:12 volumio volumio[27318]: SPOTIFY: BQCblAZc8IxWVoCFEUetsm4jaG-s31C7EjvwMJzJ7lkOA-LVXjUNV8IU92P2rTuX_sWPF8EFJTYEmVHopF-aAqxgJiKqljECRotK0VTMPH802pxz7-Mbm8XSFb1bDYsxFCTUKJbHlXRNyTh5WMyNPENEISipEznN0cluXMfphaynvjkl7Aq2UwOI7luHh3LKVn4YND_7_b6BuXvZWydo0iFHJ-0VkMnR7QIKpqy6gDy4nxhhsmRBuA6yIn6aeJ6H3FM9vqcSG3zuTC7A0fNnVvbn4lVNAhSnscPeXPFmnTESq5Y Sep 03 14:50:12 volumio volumio[27318]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 03 14:50:12 volumio volumio[27318]: info: New Spotify access token = BQCblAZc8IxWVoCFEUetsm4jaG-s31C7EjvwMJzJ7lkOA-LVXjUNV8IU92P2rTuX_sWPF8EFJTYEmVHopF-aAqxgJiKqljECRotK0VTMPH802pxz7-Mbm8XSFb1bDYsxFCTUKJbHlXRNyTh5WMyNPENEISipEznN0cluXMfphaynvjkl7Aq2UwOI7luHh3LKVn4YND_7_b6BuXvZWydo0iFHJ-0VkMnR7QIKpqy6gDy4nxhhsmRBuA6yIn6aeJ6H3FM9vqcSG3zuTC7A0fNnVvbn4lVNAhSnscPeXPFmnTESq5Y Sep 03 14:50:12 volumio volumio[27318]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 03 14:50:12 volumio go-librespot[27489]: time="2025-09-03T14:50:12+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 03 14:50:12 volumio volumio[27318]: info: Asound.conf file unchanged, so no further update is needed Sep 03 14:50:12 volumio volumio[27318]: info: Output device has changed, restarting MPD Sep 03 14:50:12 volumio sudo[27507]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 14:50:12 volumio sudo[27507]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:50:12 volumio sudo[27507]: pam_unix(sudo:session): session closed for user root Sep 03 14:50:12 volumio sudo[27510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 14:50:12 volumio sudo[27510]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:50:12 volumio go-librespot[27489]: time="2025-09-03T14:50:12+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 03 14:50:12 volumio go-librespot[27489]: time="2025-09-03T14:50:12+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 03 14:50:12 volumio sudo[27513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 03 14:50:12 volumio sudo[27513]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:50:12 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 03 14:50:12 volumio systemd[1]: mpd.service: Succeeded. Sep 03 14:50:12 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 14:50:12 volumio volumio[27318]: info: MPD Permissions set Sep 03 14:50:12 volumio go-librespot[27489]: time="2025-09-03T14:50:12+02:00" level=debug msg="dealer connection opened" Sep 03 14:50:12 volumio go-librespot[27489]: time="2025-09-03T14:50:12+02:00" level=trace msg="starting accesspoint recv loop" Sep 03 14:50:12 volumio go-librespot[27489]: time="2025-09-03T14:50:12+02:00" level=trace msg="starting dealer recv loop" Sep 03 14:50:12 volumio go-librespot[27489]: time="2025-09-03T14:50:12+02:00" level=trace msg="received accesspoint ping" Sep 03 14:50:12 volumio go-librespot[27489]: time="2025-09-03T14:50:12+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 03 14:50:12 volumio go-librespot[27489]: time="2025-09-03T14:50:12+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 03 14:50:12 volumio go-librespot[27489]: time="2025-09-03T14:50:12+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 03 14:50:12 volumio go-librespot[27489]: time="2025-09-03T14:50:12+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 03 14:50:12 volumio go-librespot[27489]: time="2025-09-03T14:50:12+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 03 14:50:12 volumio go-librespot[27489]: time="2025-09-03T14:50:12+02:00" level=debug msg="received connection id: ZWViMTQ2YzktZTVl...RDA2ODE4MzZDNQ==" Sep 03 14:50:12 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:12 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:12 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:12 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:12 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:12 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:12 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:12 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:12 volumio go-librespot[27489]: time="2025-09-03T14:50:12+02:00" level=trace msg="received accesspoint pong ack" Sep 03 14:50:12 volumio systemd[1]: Starting Music Player Daemon... Sep 03 14:50:12 volumio sudo[27513]: pam_unix(sudo:session): session closed for user root Sep 03 14:50:12 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 14:50:12 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:12 volumio volumio[27318]: info: Upmpdcli Daemon Started Sep 03 14:50:12 volumio go-librespot[27489]: time="2025-09-03T14:50:12+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 03 14:50:12 volumio volumio[27318]: 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 03 14:50:12 volumio volumio[27318]: info: Spotify Successfully logged in Sep 03 14:50:12 volumio volumio[27318]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 14:50:12 volumio volumio[27318]: info: [1756903812884] CoreMusicLibrary::Adding element Spotify Sep 03 14:50:12 volumio volumio[27318]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 14:50:12 volumio volumio[27318]: Cannot find translation for source Jellyfin Sep 03 14:50:12 volumio volumio[27318]: Cannot find translation for source Spotify Sep 03 14:50:12 volumio sudo[27515]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 03 14:50:12 volumio sudo[27515]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:50:12 volumio sudo[27515]: pam_unix(sudo:session): session closed for user root Sep 03 14:50:13 volumio volumio[27318]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 03 14:50:13 volumio volumio[27318]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 14:50:13 volumio volumio[27318]: Error: bind EADDRINUSE 192.168.0.171:50386 Sep 03 14:50:13 volumio volumio[27318]: at dgram.js:337:20 Sep 03 14:50:13 volumio volumio[27318]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Sep 03 14:50:13 volumio volumio[27318]: errno: -98, Sep 03 14:50:13 volumio volumio[27318]: code: 'EADDRINUSE', Sep 03 14:50:13 volumio volumio[27318]: syscall: 'bind', Sep 03 14:50:13 volumio volumio[27318]: address: '192.168.0.171', Sep 03 14:50:13 volumio volumio[27318]: port: 50386 Sep 03 14:50:13 volumio volumio[27318]: } Sep 03 14:50:13 volumio volumio[27318]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 14:50:14 volumio sudo[27570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-03 14:49 Sep 03 14:50:14 volumio sudo[27570]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:50:14 volumio sudo[27570]: pam_unix(sudo:session): session closed for user root Sep 03 14:50:14 volumio mpd[27522]: Sep 03 14:50 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 03 14:50:14 volumio systemd[1]: Started Music Player Daemon. Sep 03 14:50:14 volumio sudo[27423]: pam_unix(sudo:session): session closed for user root Sep 03 14:50:14 volumio sudo[27445]: pam_unix(sudo:session): session closed for user root Sep 03 14:50:14 volumio sudo[27510]: pam_unix(sudo:session): session closed for user root Sep 03 14:50:14 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 03 14:50:14 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 03 14:50:14 volumio systemd[1]: Started dynamicswap service. Sep 03 14:50:14 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 03 14:50:14 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 03 14:50:14 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 8641. Sep 03 14:50:14 volumio systemd[1]: Stopped Volumio Backend Module. Sep 03 14:50:14 volumio systemd[1]: Started Volumio Backend Module. Sep 03 14:50:14 volumio systemd[1]: Started dynamicswap service. Sep 03 14:50:14 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 03 14:50:15 volumio volumio-remote-updater[504]: [2025-09-03 14:50:15] [info] asio async_connect error: system:111 (Connection refused) Sep 03 14:50:15 volumio volumio-remote-updater[504]: [2025-09-03 14:50:15] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 03 14:50:15 volumio volumio-remote-updater[504]: [2025-09-03 14:50:15] [error] handle_connect error: Underlying Transport Error Sep 03 14:50:16 volumio volumio[27585]: info: ------------------------------------------- Sep 03 14:50:16 volumio volumio[27585]: info: ----- Volumio3 ---- Sep 03 14:50:16 volumio volumio[27585]: info: ------------------------------------------- Sep 03 14:50:16 volumio volumio[27585]: info: ----- System startup ---- Sep 03 14:50:16 volumio volumio[27585]: info: ------------------------------------------- Sep 03 14:50:17 volumio volumio[27585]: info: MYVOLUMIO Environment detected Sep 03 14:50:17 volumio volumio[27585]: info: Plugin folders cleanup Sep 03 14:50:17 volumio volumio[27585]: info: Scanning into folder /volumio/app/plugins/ Sep 03 14:50:17 volumio volumio[27585]: info: Scanning category audio_interface Sep 03 14:50:17 volumio volumio[27585]: info: Scanning category miscellanea Sep 03 14:50:17 volumio volumio[27585]: info: Scanning category music_service Sep 03 14:50:17 volumio volumio[27585]: info: Scanning category plugins.json Sep 03 14:50:17 volumio volumio[27585]: info: Scanning category system_controller Sep 03 14:50:17 volumio volumio[27585]: info: Scanning category user_interface Sep 03 14:50:17 volumio volumio[27585]: info: Scanning into folder /data/plugins/ Sep 03 14:50:17 volumio volumio[27585]: info: Scanning category audio_interface Sep 03 14:50:17 volumio volumio[27585]: info: Scanning category music_service Sep 03 14:50:17 volumio volumio[27585]: info: Scanning category user_interface Sep 03 14:50:17 volumio volumio[27585]: info: Plugin folders cleanup completed Sep 03 14:50:17 volumio volumio[27585]: info: ------------------------------------------- Sep 03 14:50:17 volumio volumio[27585]: info: ----- Core plugins startup ---- Sep 03 14:50:17 volumio volumio[27585]: info: ------------------------------------------- Sep 03 14:50:17 volumio volumio[27585]: info: Loading plugins from folder /volumio/app/plugins/ Sep 03 14:50:17 volumio volumio[27585]: info: Adding plugin upnp to MyMusic Plugins Sep 03 14:50:17 volumio volumio[27585]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 03 14:50:17 volumio volumio[27585]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 03 14:50:17 volumio volumio[27585]: info: Loading plugins from folder /data/plugins/ Sep 03 14:50:17 volumio volumio[27585]: info: Loading plugin "system"... Sep 03 14:50:17 volumio volumio[27585]: info: Loading plugin "appearance"... Sep 03 14:50:18 volumio volumio[27585]: info: Loading plugin "network"... Sep 03 14:50:18 volumio volumio[27585]: info: Refreshing Cached IP Addresses Sep 03 14:50:18 volumio sudo[27615]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 03 14:50:18 volumio sudo[27615]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:50:18 volumio sudo[27615]: pam_unix(sudo:session): session closed for user root Sep 03 14:50:18 volumio volumio[27585]: info: Loading plugin "services"... Sep 03 14:50:18 volumio sudo[27617]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 14:50:18 volumio sudo[27617]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:50:18 volumio volumio[27585]: info: Loading plugin "alsa_controller"... Sep 03 14:50:18 volumio sudo[27617]: pam_unix(sudo:session): session closed for user root Sep 03 14:50:18 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 14:50:18 volumio volumio[27585]: info: Loading plugin "wizard"... Sep 03 14:50:18 volumio volumio[27585]: info: Loading plugin "networkfs"... Sep 03 14:50:18 volumio volumio[27585]: info: Starting Udev Watcher for removable devices Sep 03 14:50:18 volumio volumio[27585]: info: Ignoring mount for partition: boot Sep 03 14:50:18 volumio volumio[27585]: info: Ignoring mount for partition: volumio Sep 03 14:50:18 volumio volumio[27585]: info: Ignoring mount for partition: volumio_data Sep 03 14:50:18 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 14:50:18 volumio volumio[27585]: info: Loading plugin "volumio_command_line_client"... Sep 03 14:50:18 volumio volumio[27585]: info: Loading plugin "upnp"... Sep 03 14:50:18 volumio volumio[27585]: info: [1756903818515] Starting Upmpd Daemon Sep 03 14:50:18 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 14:50:18 volumio volumio[27585]: info: Loading plugin "my_music"... Sep 03 14:50:18 volumio volumio[27585]: info: Loading plugin "mpd"... Sep 03 14:50:18 volumio volumio[27585]: info: Plugin upnp_browser is not enabled Sep 03 14:50:18 volumio volumio[27585]: info: Loading plugin "alarm-clock"... Sep 03 14:50:19 volumio volumio[27585]: info: Plugin airplay_emulation is not enabled Sep 03 14:50:19 volumio volumio[27585]: info: Loading plugin "last_100"... Sep 03 14:50:19 volumio volumio[27585]: info: Loading plugin "webradio"... Sep 03 14:50:19 volumio volumio[27585]: info: Loading plugin "i2s_dacs"... Sep 03 14:50:19 volumio volumio[27585]: info: Loading plugin "volumiodiscovery"... Sep 03 14:50:19 volumio volumio[27585]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 03 14:50:19 volumio volumio[27585]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 14:50:19 volumio volumio[27585]: *** WARNING *** For more information see Sep 03 14:50:19 volumio node[27585]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 03 14:50:19 volumio volumio[27585]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 03 14:50:19 volumio volumio[27585]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 14:50:19 volumio volumio[27585]: *** WARNING *** For more information see Sep 03 14:50:19 volumio node[27585]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 14:50:19 volumio node[27585]: *** WARNING *** For more information see Sep 03 14:50:19 volumio node[27585]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 03 14:50:19 volumio node[27585]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 14:50:19 volumio node[27585]: *** WARNING *** For more information see Sep 03 14:50:19 volumio volumio[27585]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 03 14:50:19 volumio volumio[27585]: info: Discovery: Started advertising with name: Volumio Sep 03 14:50:19 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 14:50:19 volumio volumio[27585]: info: Loading plugin "jellyfin"... Sep 03 14:50:20 volumio volumio[27585]: info: Loading plugin "spop"... Sep 03 14:50:21 volumio volumio[27585]: info: Loading plugin "ytcr"... Sep 03 14:50:25 volumio volumio-remote-updater[504]: [2025-09-03 14:50:25] [connect] Successful connection Sep 03 14:50:25 volumio volumio[27585]: info: Loading plugin "outputs"... Sep 03 14:50:25 volumio volumio[27585]: info: Loading plugin "albumart"... Sep 03 14:50:25 volumio volumio[27585]: info: Plugin example_plugin is not enabled Sep 03 14:50:25 volumio volumio[27585]: info: Loading plugin "inputs"... Sep 03 14:50:25 volumio volumio[27585]: info: Loading plugin "updater_comm"... Sep 03 14:50:25 volumio volumio[27585]: info: Plugin mpdemulation is not enabled Sep 03 14:50:25 volumio volumio[27585]: info: Loading plugin "rest_api"... Sep 03 14:50:25 volumio volumio[27585]: info: Loading plugin "websocket"... Sep 03 14:50:25 volumio volumio[27585]: info: Starting Socket.io Server version 2.3.0 Sep 03 14:50:25 volumio volumio[27585]: info: Loading plugin "fusiondsp"... Sep 03 14:50:25 volumio volumio[27585]: Forking 3 albumart workers Sep 03 14:50:25 volumio volumio[27585]: info: Applying required configuration parameters for plugin fusiondsp Sep 03 14:50:26 volumio volumio[27585]: info: Loading plugin "Systeminfo"... Sep 03 14:50:26 volumio volumio[27585]: info: Loading i18n strings for locale hu Sep 03 14:50:26 volumio volumio[27585]: Updating browse sources language Sep 03 14:50:26 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 14:50:26 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 03 14:50:26 volumio volumio[27585]: info: CoreCommandRouter::initPlayerControls Sep 03 14:50:26 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:26 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:26 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:26 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:26 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:26 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:26 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:26 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:26 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 03 14:50:26 volumio volumio[27585]: Express server listening on port 3000 Sep 03 14:50:26 volumio volumio[27585]: [Metrics] WebUI: 10s 902.19ms Sep 03 14:50:26 volumio volumio[27585]: info: CoreStateMachine::resetVolumioState Sep 03 14:50:26 volumio volumio[27585]: info: CoreStateMachine::getcurrentVolume Sep 03 14:50:26 volumio volumio[27585]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 14:50:26 volumio volumio[27585]: info: Volumio Network Manager: Network status updated: 1 Sep 03 14:50:26 volumio volumio[27585]: Starting albumart workers Sep 03 14:50:26 volumio volumio[27585]: Starting albumart workers Sep 03 14:50:26 volumio volumio[27585]: Starting albumart workers Sep 03 14:50:26 volumio volumio-remote-updater[504]: [2025-09-03 14:50:26] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1756903825 101 Sep 03 14:50:27 volumio volumio[27585]: 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 03 14:50:27 volumio volumio[27585]: info: CoreCommandRouter::volumioGetState Sep 03 14:50:27 volumio volumio[27585]: info: CorePlayQueue::getTrack 0 Sep 03 14:50:27 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 14:50:27 volumio volumio[27585]: info: VolumeController:: Volume=37 Mute =false Sep 03 14:50:27 volumio volumio[27585]: info: CoreStateMachine::pushState Sep 03 14:50:27 volumio volumio[27585]: info: CorePlayQueue::getTrack 0 Sep 03 14:50:27 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 14:50:27 volumio volumio[27585]: info: CoreCommandRouter::volumioPushState Sep 03 14:50:27 volumio volumio[27585]: info: CoreStateMachine::updateTrackBlock Sep 03 14:50:27 volumio volumio[27585]: info: CorePlayQueue::getTrackBlock Sep 03 14:50:27 volumio volumio[27585]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 14:50:27 volumio volumio[27585]: info: CoreStateMachine::setRepeat false single undefined Sep 03 14:50:27 volumio volumio[27585]: info: CoreStateMachine::pushState Sep 03 14:50:27 volumio volumio[27585]: info: CorePlayQueue::getTrack 0 Sep 03 14:50:27 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 14:50:27 volumio volumio[27585]: info: CoreCommandRouter::volumioPushState Sep 03 14:50:27 volumio volumio[27585]: info: CoreStateMachine::setRandom true Sep 03 14:50:27 volumio volumio[27585]: info: CoreStateMachine::pushState Sep 03 14:50:27 volumio volumio[27585]: info: CorePlayQueue::getTrack 0 Sep 03 14:50:27 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 14:50:27 volumio volumio[27585]: info: CoreCommandRouter::volumioPushState Sep 03 14:50:27 volumio volumio[27585]: info: Setting Device type: Raspberry PI Sep 03 14:50:27 volumio volumio[27585]: info: Listing playlists Sep 03 14:50:27 volumio volumio[27585]: info: Listing playlists Sep 03 14:50:27 volumio volumio[27585]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227 Sep 03 14:50:27 volumio volumio[27585]: info: Discovery: Found device Volumio Sep 03 14:50:27 volumio volumio[27585]: info: CoreCommandRouter::volumioGetState Sep 03 14:50:27 volumio volumio[27585]: info: CorePlayQueue::getTrack 0 Sep 03 14:50:27 volumio volumio[27585]: info: Completed loading Core Plugins Sep 03 14:50:27 volumio volumio[27585]: info: Preparing to generate the ALSA configuration file Sep 03 14:50:27 volumio volumio[27585]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 03 14:50:27 volumio volumio[27585]: info: Reading ALSA contributions from plugins. Sep 03 14:50:27 volumio volumio[27585]: info: VolumeController:: Volume=37 Mute =false Sep 03 14:50:27 volumio volumio[27585]: info: CoreStateMachine::pushState Sep 03 14:50:27 volumio volumio[27585]: info: CorePlayQueue::getTrack 0 Sep 03 14:50:27 volumio volumio[27585]: info: CoreCommandRouter::volumioPushState Sep 03 14:50:27 volumio volumio[27585]: info: Asound.conf file unchanged, so no further update is needed Sep 03 14:50:27 volumio volumio[27585]: info: Output device has changed, restarting MPD Sep 03 14:50:27 volumio sudo[27699]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 14:50:27 volumio sudo[27699]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:50:27 volumio sudo[27699]: pam_unix(sudo:session): session closed for user root Sep 03 14:50:27 volumio volumio[27585]: info: ___________ START PLUGINS ___________ Sep 03 14:50:27 volumio volumio[27585]: info: ControllerMpd::onStart: Initializing MPD Sep 03 14:50:27 volumio volumio[27585]: info: Creating MPD Configuration file Sep 03 14:50:27 volumio sudo[27702]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 14:50:27 volumio sudo[27702]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:50:27 volumio systemd[1]: Stopping Music Player Daemon... Sep 03 14:50:27 volumio sudo[27713]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 14:50:27 volumio volumio[27585]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 14:50:27 volumio volumio[27585]: info: [1756903827763] CoreMusicLibrary::Adding element Last_100 Sep 03 14:50:27 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 14:50:27 volumio sudo[27713]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:50:27 volumio volumio[27585]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 14:50:27 volumio volumio[27585]: info: [1756903827771] CoreMusicLibrary::Adding element Webradio Sep 03 14:50:27 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 14:50:27 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 14:50:27 volumio systemd[1]: mpd.service: Succeeded. Sep 03 14:50:27 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 14:50:27 volumio sudo[27713]: pam_unix(sudo:session): session closed for user root Sep 03 14:50:27 volumio sudo[27715]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 14:50:27 volumio sudo[27715]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:50:27 volumio volumio[27585]: info: Initializing BBC Radios Sep 03 14:50:27 volumio systemd[1]: Starting Music Player Daemon... Sep 03 14:50:27 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 03 14:50:27 volumio systemd[1]: mpd.service: Succeeded. Sep 03 14:50:27 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 14:50:27 volumio systemd[1]: Starting Music Player Daemon... Sep 03 14:50:27 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 14:50:27 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 14:50:27 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 14:50:27 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 03 14:50:27 volumio volumio[27585]: info: Discovery: Getting this device information Sep 03 14:50:27 volumio volumio[27585]: info: CoreCommandRouter::volumioGetState Sep 03 14:50:27 volumio volumio[27585]: info: CorePlayQueue::getTrack 0 Sep 03 14:50:27 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 14:50:27 volumio volumio[27585]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 14:50:27 volumio volumio[27585]: info: [1756903827912] CoreMusicLibrary::Adding element Jellyfin Sep 03 14:50:27 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 14:50:27 volumio volumio[27585]: Cannot find translation for source Jellyfin Sep 03 14:50:27 volumio volumio[27585]: 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 03 14:50:27 volumio volumio[27585]: info: Creating Spotify config file Sep 03 14:50:27 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:27 volumio sudo[27726]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 03 14:50:27 volumio sudo[27726]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:50:28 volumio sudo[27726]: pam_unix(sudo:session): session closed for user root Sep 03 14:50:28 volumio volumio[27585]: info: Loading i18n strings for locale hu Sep 03 14:50:28 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 03 14:50:28 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:28 volumio volumio[27585]: info: FusionDsp - mixtype--------------------- Hardware Sep 03 14:50:28 volumio volumio[27585]: info: Volumio Calling Home Sep 03 14:50:28 volumio volumio[27585]: info: Preparing to generate the ALSA configuration file Sep 03 14:50:28 volumio volumio[27585]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 03 14:50:28 volumio volumio[27585]: info: Reading ALSA contributions from plugins. Sep 03 14:50:28 volumio volumio[27585]: info: MPD Permissions set Sep 03 14:50:28 volumio volumio[27585]: info: MPD Permissions set Sep 03 14:50:28 volumio volumio[27585]: info: Spotify config file written Sep 03 14:50:28 volumio sudo[27736]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 03 14:50:28 volumio volumio[27585]: 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 03 14:50:28 volumio sudo[27736]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:50:28 volumio volumio[27585]: info: Volumio called home Sep 03 14:50:28 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:28 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:28 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:28 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:28 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:28 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:28 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:28 volumio systemd[1]: Stopping go-librespot Daemon... Sep 03 14:50:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 03 14:50:28 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 03 14:50:28 volumio systemd[1]: Stopped go-librespot Daemon. Sep 03 14:50:28 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 14:50:28 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:28 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:28 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:28 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:28 volumio systemd[1]: Started go-librespot Daemon. Sep 03 14:50:28 volumio go-librespot[27755]: go-librespot daemon starting... Sep 03 14:50:28 volumio sudo[27736]: pam_unix(sudo:session): session closed for user root Sep 03 14:50:28 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 14:50:28 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:28 volumio volumio[27585]: info: No need to fix Spotify hosts Sep 03 14:50:28 volumio go-librespot[27755]: time="2025-09-03T14:50:28+02:00" level=info msg="running go-librespot 0.3.2" Sep 03 14:50:28 volumio go-librespot[27755]: time="2025-09-03T14:50:28+02:00" level=debug msg="app state loaded" Sep 03 14:50:28 volumio go-librespot[27755]: time="2025-09-03T14:50:28+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 03 14:50:28 volumio sudo[27769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 03 14:50:28 volumio sudo[27769]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:50:28 volumio sudo[27769]: pam_unix(sudo:session): session closed for user root Sep 03 14:50:28 volumio sudo[27772]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 14:50:28 volumio sudo[27772]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:50:28 volumio sudo[27772]: pam_unix(sudo:session): session closed for user root Sep 03 14:50:28 volumio go-librespot[27755]: time="2025-09-03T14:50: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 03 14:50:28 volumio go-librespot[27755]: time="2025-09-03T14:50: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 03 14:50:28 volumio go-librespot[27755]: time="2025-09-03T14:50: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 03 14:50:28 volumio go-librespot[27755]: time="2025-09-03T14:50:28+02:00" level=info msg="zeroconf server listening on port 39293" Sep 03 14:50:28 volumio go-librespot[27755]: time="2025-09-03T14:50:28+02:00" level=debug msg="obtained new client token: AADr03Xu+7qhKfAWglzx8lxhlKm/gPJD4llYRmWSEKMEG8PEeNwWLINbk+LSh7tmIxyjyZgW2EdyYQHf2lLnLomY9jIP/hLLOHybrLZE2F9RZueRC6zGnxPgqxVUtDNqMbjBw5BP6gK2j5zqg6T4bxb+C00OQb8xNy8tV++mHHQ3/VC6/HKgjYid7r/qLwI2wLjnCwKnmB+ceBSX7NZMVazFnbVjmJSXBTstF8iA3noGgwFPWIhYABkw9Is=" Sep 03 14:50:28 volumio go-librespot[27755]: time="2025-09-03T14:50:28+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 03 14:50:29 volumio go-librespot[27755]: time="2025-09-03T14:50:29+02:00" level=debug msg="completed keyexchange" Sep 03 14:50:29 volumio go-librespot[27755]: time="2025-09-03T14:50:29+02:00" level=debug msg="completed challenge" Sep 03 14:50:29 volumio go-librespot[27755]: time="2025-09-03T14:50:29+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 03 14:50:29 volumio volumio[27585]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 03 14:50:29 volumio volumio[27585]: SPOTIFY: BQCCtHhdrqsJ2fqlN-TGfWqtI8SJnZo_PVSOX9il77xb0cyyuinuTMjz-sSsavXEufJsurHY-aiG9QWF_u80ZOVQsNfjfSZS8ZiAAJgFOPYS3EC4J2pCHPzSkyycHh6RoxbQajPnX-mIdwcOf58_OtF1AO4OCKoXpvSmyaEtKpcH5zv2Lpx7zp0yVnrD-Yg79uOsINAflMzQzZub034azb9Tpy6GGCT4rFKNhInXsDQly3Jj3uPnyR0qbvCnyzJzoEcWQw59oCrv6Zm3JwybAfgWdGRYVAO8uRKPiS64wrpDutQ Sep 03 14:50:29 volumio volumio[27585]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 03 14:50:29 volumio volumio[27585]: info: New Spotify access token = BQCCtHhdrqsJ2fqlN-TGfWqtI8SJnZo_PVSOX9il77xb0cyyuinuTMjz-sSsavXEufJsurHY-aiG9QWF_u80ZOVQsNfjfSZS8ZiAAJgFOPYS3EC4J2pCHPzSkyycHh6RoxbQajPnX-mIdwcOf58_OtF1AO4OCKoXpvSmyaEtKpcH5zv2Lpx7zp0yVnrD-Yg79uOsINAflMzQzZub034azb9Tpy6GGCT4rFKNhInXsDQly3Jj3uPnyR0qbvCnyzJzoEcWQw59oCrv6Zm3JwybAfgWdGRYVAO8uRKPiS64wrpDutQ Sep 03 14:50:29 volumio volumio[27585]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 03 14:50:29 volumio volumio[27585]: info: CoreCommandRouter::volumioGetState Sep 03 14:50:29 volumio volumio[27585]: info: CorePlayQueue::getTrack 0 Sep 03 14:50:29 volumio go-librespot[27755]: time="2025-09-03T14:50:29+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 03 14:50:29 volumio go-librespot[27755]: time="2025-09-03T14:50:29+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 03 14:50:29 volumio volumio[27585]: info: Asound.conf file unchanged, so no further update is needed Sep 03 14:50:29 volumio volumio[27585]: info: Output device has changed, restarting MPD Sep 03 14:50:29 volumio go-librespot[27755]: time="2025-09-03T14:50:29+02:00" level=debug msg="dealer connection opened" Sep 03 14:50:29 volumio go-librespot[27755]: time="2025-09-03T14:50:29+02:00" level=trace msg="starting accesspoint recv loop" Sep 03 14:50:29 volumio go-librespot[27755]: time="2025-09-03T14:50:29+02:00" level=trace msg="received accesspoint ping" Sep 03 14:50:29 volumio go-librespot[27755]: time="2025-09-03T14:50:29+02:00" level=trace msg="starting dealer recv loop" Sep 03 14:50:29 volumio go-librespot[27755]: time="2025-09-03T14:50:29+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 03 14:50:29 volumio go-librespot[27755]: time="2025-09-03T14:50:29+02:00" level=debug msg="received connection id: MWVmM2QxY2EtMTM0...OTUwQTlBMDVBOA==" Sep 03 14:50:29 volumio go-librespot[27755]: time="2025-09-03T14:50:29+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 03 14:50:29 volumio sudo[27776]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 14:50:29 volumio sudo[27776]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:50:29 volumio sudo[27776]: pam_unix(sudo:session): session closed for user root Sep 03 14:50:29 volumio sudo[27778]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 14:50:29 volumio sudo[27778]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:50:29 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 03 14:50:29 volumio systemd[1]: mpd.service: Succeeded. Sep 03 14:50:29 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 14:50:29 volumio volumio[27585]: info: MPD Permissions set Sep 03 14:50:29 volumio go-librespot[27755]: time="2025-09-03T14:50:29+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 03 14:50:29 volumio go-librespot[27755]: time="2025-09-03T14:50:29+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 03 14:50:29 volumio go-librespot[27755]: time="2025-09-03T14:50:29+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 03 14:50:29 volumio go-librespot[27755]: time="2025-09-03T14:50:29+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 03 14:50:29 volumio go-librespot[27755]: time="2025-09-03T14:50:29+02:00" level=trace msg="received accesspoint pong ack" Sep 03 14:50:29 volumio sudo[27784]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 03 14:50:29 volumio sudo[27784]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:50:29 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:29 volumio systemd[1]: Starting Music Player Daemon... Sep 03 14:50:29 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:29 volumio sudo[27784]: pam_unix(sudo:session): session closed for user root Sep 03 14:50:29 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 14:50:29 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:29 volumio volumio[27585]: info: Upmpdcli Daemon Started Sep 03 14:50:29 volumio volumio[27585]: 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 03 14:50:29 volumio volumio[27585]: info: Spotify Successfully logged in Sep 03 14:50:29 volumio volumio[27585]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 14:50:29 volumio volumio[27585]: info: [1756903829468] CoreMusicLibrary::Adding element Spotify Sep 03 14:50:29 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 14:50:29 volumio volumio[27585]: Cannot find translation for source Jellyfin Sep 03 14:50:29 volumio volumio[27585]: Cannot find translation for source Spotify Sep 03 14:50:29 volumio sudo[27782]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 03 14:50:29 volumio sudo[27782]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:50:29 volumio sudo[27782]: pam_unix(sudo:session): session closed for user root Sep 03 14:50:30 volumio volumio[27585]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 03 14:50:30 volumio volumio[27585]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 14:50:30 volumio volumio[27585]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 03 14:50:30 volumio volumio[27585]: 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 03 14:50:30 volumio volumio[27585]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 03 14:50:30 volumio volumio[27585]: info: camilladsp service started and running in background, instance 1 Sep 03 14:50:30 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 14:50:30 volumio volumio[27585]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 03 14:50:30 volumio volumio[27585]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 03 14:50:30 volumio volumio[27585]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 03 14:50:30 volumio volumio[27585]: info: FusionDsp loaded Sep 03 14:50:30 volumio volumio[27585]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 14:50:30 volumio sudo[27805]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 03 14:50:30 volumio sudo[27805]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:50:30 volumio sudo[27805]: pam_unix(sudo:session): session closed for user root Sep 03 14:50:30 volumio volumio[27585]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 03 14:50:30 volumio volumio[27585]: info: Adding Signal Path Element [object Object] Sep 03 14:50:30 volumio volumio[27585]: info: Adding fusiondspeq DSP Signal Path Element Sep 03 14:50:30 volumio volumio[27585]: info: FusionDsp - ---- installed callbackRead Sep 03 14:50:30 volumio volumio[27585]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 14:50:30 volumio volumio[27585]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 03 14:50:30 volumio volumio[27585]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 03 14:50:30 volumio volumio[27585]: at onErrorNT (internal/child_process.js:465:16) Sep 03 14:50:30 volumio volumio[27585]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Sep 03 14:50:30 volumio volumio[27585]: errno: -2, Sep 03 14:50:30 volumio volumio[27585]: code: 'ENOENT', Sep 03 14:50:30 volumio volumio[27585]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 03 14:50:30 volumio volumio[27585]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 03 14:50:30 volumio volumio[27585]: spawnargs: [ Sep 03 14:50:30 volumio volumio[27585]: '-p', Sep 03 14:50:30 volumio volumio[27585]: 9876, Sep 03 14:50:30 volumio volumio[27585]: '-o', Sep 03 14:50:30 volumio volumio[27585]: '/tmp/camilladsp.log', Sep 03 14:50:30 volumio volumio[27585]: '-l', Sep 03 14:50:30 volumio volumio[27585]: 'warn', Sep 03 14:50:30 volumio volumio[27585]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 03 14:50:30 volumio volumio[27585]: ] Sep 03 14:50:30 volumio volumio[27585]: } Sep 03 14:50:30 volumio volumio[27585]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 14:50:30 volumio sudo[27816]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-03 14:49 Sep 03 14:50:30 volumio sudo[27816]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 14:50:31 volumio mpd[27792]: Sep 03 14:50 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 03 14:50:31 volumio systemd[1]: Started Music Player Daemon. Sep 03 14:50:31 volumio sudo[27702]: pam_unix(sudo:session): session closed for user root Sep 03 14:50:31 volumio sudo[27715]: pam_unix(sudo:session): session closed for user root Sep 03 14:50:31 volumio sudo[27778]: pam_unix(sudo:session): session closed for user root PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"