-- Logs begin at Tue 2025-09-02 15:15:40 CEST, end at Tue 2025-09-02 15:44:58 CEST. -- Sep 02 15:43:00 volumio sudo[14093]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-02 15:42 Sep 02 15:43:00 volumio sudo[14093]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:00 volumio mpd[14069]: Sep 02 15:43 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 02 15:43:00 volumio systemd[1]: Started Music Player Daemon. Sep 02 15:43:00 volumio sudo[13964]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:00 volumio sudo[14057]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:00 volumio sudo[13983]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:00 volumio sudo[14093]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:00 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 02 15:43:00 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 02 15:43:00 volumio systemd[1]: Started dynamicswap service. Sep 02 15:43:00 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 02 15:43:00 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 02 15:43:00 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3789. Sep 02 15:43:00 volumio systemd[1]: Stopped Volumio Backend Module. Sep 02 15:43:00 volumio systemd[1]: Started Volumio Backend Module. Sep 02 15:43:00 volumio systemd[1]: Started dynamicswap service. Sep 02 15:43:00 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 02 15:43:01 volumio volumio-remote-updater[504]: [2025-09-02 15:43:01] [info] asio async_connect error: system:111 (Connection refused) Sep 02 15:43:01 volumio volumio-remote-updater[504]: [2025-09-02 15:43:01] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 02 15:43:01 volumio volumio-remote-updater[504]: [2025-09-02 15:43:01] [error] handle_connect error: Underlying Transport Error Sep 02 15:43:02 volumio volumio[14105]: info: ------------------------------------------- Sep 02 15:43:02 volumio volumio[14105]: info: ----- Volumio3 ---- Sep 02 15:43:02 volumio volumio[14105]: info: ------------------------------------------- Sep 02 15:43:02 volumio volumio[14105]: info: ----- System startup ---- Sep 02 15:43:02 volumio volumio[14105]: info: ------------------------------------------- Sep 02 15:43:03 volumio volumio[14105]: info: MYVOLUMIO Environment detected Sep 02 15:43:03 volumio volumio[14105]: info: Plugin folders cleanup Sep 02 15:43:03 volumio volumio[14105]: info: Scanning into folder /volumio/app/plugins/ Sep 02 15:43:03 volumio volumio[14105]: info: Scanning category audio_interface Sep 02 15:43:03 volumio volumio[14105]: info: Scanning category miscellanea Sep 02 15:43:03 volumio volumio[14105]: info: Scanning category music_service Sep 02 15:43:03 volumio volumio[14105]: info: Scanning category plugins.json Sep 02 15:43:03 volumio volumio[14105]: info: Scanning category system_controller Sep 02 15:43:03 volumio volumio[14105]: info: Scanning category user_interface Sep 02 15:43:03 volumio volumio[14105]: info: Scanning into folder /data/plugins/ Sep 02 15:43:03 volumio volumio[14105]: info: Scanning category audio_interface Sep 02 15:43:03 volumio volumio[14105]: info: Scanning category music_service Sep 02 15:43:03 volumio volumio[14105]: info: Scanning category user_interface Sep 02 15:43:03 volumio volumio[14105]: info: Plugin folders cleanup completed Sep 02 15:43:03 volumio volumio[14105]: info: ------------------------------------------- Sep 02 15:43:03 volumio volumio[14105]: info: ----- Core plugins startup ---- Sep 02 15:43:03 volumio volumio[14105]: info: ------------------------------------------- Sep 02 15:43:03 volumio volumio[14105]: info: Loading plugins from folder /volumio/app/plugins/ Sep 02 15:43:03 volumio volumio[14105]: info: Adding plugin upnp to MyMusic Plugins Sep 02 15:43:03 volumio volumio[14105]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 02 15:43:03 volumio volumio[14105]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 02 15:43:03 volumio volumio[14105]: info: Loading plugins from folder /data/plugins/ Sep 02 15:43:03 volumio volumio[14105]: info: Loading plugin "system"... Sep 02 15:43:03 volumio volumio[14105]: info: Loading plugin "appearance"... Sep 02 15:43:04 volumio volumio[14105]: info: Loading plugin "network"... Sep 02 15:43:04 volumio volumio[14105]: info: Refreshing Cached IP Addresses Sep 02 15:43:04 volumio sudo[14143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 02 15:43:04 volumio sudo[14143]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:04 volumio sudo[14143]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:04 volumio sudo[14145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 02 15:43:04 volumio volumio[14105]: info: Loading plugin "services"... Sep 02 15:43:04 volumio sudo[14145]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:04 volumio volumio[14105]: info: Loading plugin "alsa_controller"... Sep 02 15:43:04 volumio sudo[14145]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:04 volumio volumio[14105]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 15:43:04 volumio volumio[14105]: info: Loading plugin "wizard"... Sep 02 15:43:04 volumio volumio[14105]: info: Loading plugin "networkfs"... Sep 02 15:43:04 volumio volumio[14105]: info: Starting Udev Watcher for removable devices Sep 02 15:43:04 volumio volumio[14105]: info: Ignoring mount for partition: boot Sep 02 15:43:04 volumio volumio[14105]: info: Ignoring mount for partition: volumio Sep 02 15:43:04 volumio volumio[14105]: info: Ignoring mount for partition: volumio_data Sep 02 15:43:04 volumio volumio[14105]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 15:43:04 volumio volumio[14105]: info: Loading plugin "volumio_command_line_client"... Sep 02 15:43:04 volumio volumio[14105]: info: Loading plugin "upnp"... Sep 02 15:43:04 volumio volumio[14105]: info: [1756820584509] Starting Upmpd Daemon Sep 02 15:43:04 volumio volumio[14105]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 15:43:04 volumio volumio[14105]: info: Loading plugin "my_music"... Sep 02 15:43:04 volumio volumio[14105]: info: Loading plugin "mpd"... Sep 02 15:43:04 volumio volumio[14105]: info: Plugin upnp_browser is not enabled Sep 02 15:43:04 volumio volumio[14105]: info: Loading plugin "alarm-clock"... Sep 02 15:43:05 volumio volumio[14105]: info: Plugin airplay_emulation is not enabled Sep 02 15:43:05 volumio volumio[14105]: info: Loading plugin "last_100"... Sep 02 15:43:05 volumio volumio[14105]: info: Loading plugin "webradio"... Sep 02 15:43:05 volumio volumio[14105]: info: Loading plugin "i2s_dacs"... Sep 02 15:43:05 volumio volumio[14105]: info: Loading plugin "volumiodiscovery"... Sep 02 15:43:05 volumio volumio[14105]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 02 15:43:05 volumio volumio[14105]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 15:43:05 volumio volumio[14105]: *** WARNING *** For more information see Sep 02 15:43:05 volumio node[14105]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 02 15:43:05 volumio volumio[14105]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 02 15:43:05 volumio volumio[14105]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 15:43:05 volumio volumio[14105]: *** WARNING *** For more information see Sep 02 15:43:05 volumio node[14105]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 15:43:05 volumio node[14105]: *** WARNING *** For more information see Sep 02 15:43:05 volumio node[14105]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 02 15:43:05 volumio node[14105]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 15:43:05 volumio node[14105]: *** WARNING *** For more information see Sep 02 15:43:05 volumio volumio[14105]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 02 15:43:05 volumio volumio[14105]: info: Discovery: Started advertising with name: Volumio Sep 02 15:43:05 volumio volumio[14105]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 15:43:05 volumio volumio[14105]: info: Loading plugin "jellyfin"... Sep 02 15:43:06 volumio volumio[14105]: info: Loading plugin "spop"... Sep 02 15:43:07 volumio volumio[14105]: info: Loading plugin "ytcr"... Sep 02 15:43:11 volumio volumio-remote-updater[504]: [2025-09-02 15:43:11] [connect] Successful connection Sep 02 15:43:11 volumio volumio[14105]: info: Loading plugin "outputs"... Sep 02 15:43:11 volumio volumio[14105]: info: Loading plugin "albumart"... Sep 02 15:43:11 volumio volumio[14105]: info: Plugin example_plugin is not enabled Sep 02 15:43:11 volumio volumio[14105]: info: Loading plugin "inputs"... Sep 02 15:43:11 volumio volumio[14105]: info: Loading plugin "updater_comm"... Sep 02 15:43:11 volumio volumio[14105]: info: Plugin mpdemulation is not enabled Sep 02 15:43:11 volumio volumio[14105]: info: Loading plugin "rest_api"... Sep 02 15:43:11 volumio volumio[14105]: info: Loading plugin "websocket"... Sep 02 15:43:11 volumio volumio[14105]: info: Starting Socket.io Server version 2.3.0 Sep 02 15:43:11 volumio volumio[14105]: info: Loading plugin "fusiondsp"... Sep 02 15:43:11 volumio volumio[14105]: Forking 3 albumart workers Sep 02 15:43:11 volumio volumio[14105]: info: Applying required configuration parameters for plugin fusiondsp Sep 02 15:43:12 volumio volumio[14105]: info: Loading plugin "Systeminfo"... Sep 02 15:43:12 volumio volumio[14105]: info: Loading i18n strings for locale hu Sep 02 15:43:12 volumio volumio[14105]: Updating browse sources language Sep 02 15:43:12 volumio volumio[14105]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 15:43:12 volumio volumio[14105]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 15:43:12 volumio volumio[14105]: info: CoreCommandRouter::initPlayerControls Sep 02 15:43:12 volumio volumio[14105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:12 volumio volumio[14105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:12 volumio volumio[14105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:12 volumio volumio[14105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:12 volumio volumio[14105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:12 volumio volumio[14105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:12 volumio volumio[14105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:12 volumio volumio[14105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:12 volumio volumio[14105]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 15:43:12 volumio volumio[14105]: Express server listening on port 3000 Sep 02 15:43:12 volumio volumio[14105]: [Metrics] WebUI: 11s 3.36ms Sep 02 15:43:12 volumio volumio[14105]: info: CoreStateMachine::resetVolumioState Sep 02 15:43:12 volumio volumio[14105]: info: CoreStateMachine::getcurrentVolume Sep 02 15:43:12 volumio volumio[14105]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 15:43:12 volumio volumio[14105]: Starting albumart workers Sep 02 15:43:12 volumio volumio[14105]: Starting albumart workers Sep 02 15:43:12 volumio volumio[14105]: Starting albumart workers Sep 02 15:43:12 volumio volumio[14105]: info: Volumio Network Manager: Network status updated: 1 Sep 02 15:43:13 volumio volumio-remote-updater[504]: [2025-09-02 15:43:13] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1756820591 101 Sep 02 15:43:13 volumio volumio[14105]: 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 02 15:43:13 volumio volumio[14105]: info: CoreCommandRouter::volumioGetState Sep 02 15:43:13 volumio volumio[14105]: info: CorePlayQueue::getTrack 0 Sep 02 15:43:13 volumio volumio[14105]: info: CoreCommandRouter::volumioGetState Sep 02 15:43:13 volumio volumio[14105]: info: CorePlayQueue::getTrack 0 Sep 02 15:43:13 volumio volumio[14105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 15:43:13 volumio volumio[14105]: info: VolumeController:: Volume=37 Mute =false Sep 02 15:43:13 volumio volumio[14105]: info: CoreStateMachine::pushState Sep 02 15:43:13 volumio volumio[14105]: info: CorePlayQueue::getTrack 0 Sep 02 15:43:13 volumio volumio[14105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 15:43:13 volumio nmbd[721]: [2025/09/02 15:43:13.361547, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Sep 02 15:43:13 volumio nmbd[721]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.171 for name WORKGROUP<1d>. Sep 02 15:43:13 volumio nmbd[721]: This response was from IP 192.168.0.185, reporting an IP address of 192.168.0.185. Sep 02 15:43:13 volumio nmbd[721]: [2025/09/02 15:43:13.361994, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Sep 02 15:43:13 volumio nmbd[721]: query_name_response: Multiple (3) responses received for a query on subnet 192.168.0.171 for name WORKGROUP<1d>. Sep 02 15:43:13 volumio nmbd[721]: This response was from IP 192.168.0.185, reporting an IP address of 192.168.0.185. Sep 02 15:43:13 volumio nmbd[721]: [2025/09/02 15:43:13.362306, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Sep 02 15:43:13 volumio volumio[14105]: info: CoreCommandRouter::volumioPushState Sep 02 15:43:13 volumio nmbd[721]: query_name_response: Multiple (4) responses received for a query on subnet 192.168.0.171 for name WORKGROUP<1d>. Sep 02 15:43:13 volumio nmbd[721]: This response was from IP 192.168.0.185, reporting an IP address of 192.168.0.185. Sep 02 15:43:13 volumio volumio[14105]: info: CoreStateMachine::updateTrackBlock Sep 02 15:43:13 volumio volumio[14105]: info: CorePlayQueue::getTrackBlock Sep 02 15:43:13 volumio volumio[14105]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 15:43:13 volumio volumio[14105]: info: CoreStateMachine::setRepeat false single undefined Sep 02 15:43:13 volumio volumio[14105]: info: CoreStateMachine::pushState Sep 02 15:43:13 volumio volumio[14105]: info: CorePlayQueue::getTrack 0 Sep 02 15:43:13 volumio volumio[14105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 15:43:13 volumio volumio[14105]: info: CoreCommandRouter::volumioPushState Sep 02 15:43:13 volumio volumio[14105]: info: CoreStateMachine::setRandom true Sep 02 15:43:13 volumio volumio[14105]: info: CoreStateMachine::pushState Sep 02 15:43:13 volumio volumio[14105]: info: CorePlayQueue::getTrack 0 Sep 02 15:43:13 volumio volumio[14105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 15:43:13 volumio volumio[14105]: info: CoreCommandRouter::volumioPushState Sep 02 15:43:13 volumio volumio[14105]: info: Setting Device type: Raspberry PI Sep 02 15:43:13 volumio volumio[14105]: info: Listing playlists Sep 02 15:43:13 volumio volumio[14105]: info: Listing playlists Sep 02 15:43:13 volumio volumio[14105]: info: Listing playlists Sep 02 15:43:13 volumio volumio[14105]: info: Listing playlists Sep 02 15:43:13 volumio volumio[14105]: info: Completed loading Core Plugins Sep 02 15:43:13 volumio volumio[14105]: info: Preparing to generate the ALSA configuration file Sep 02 15:43:13 volumio volumio[14105]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227 Sep 02 15:43:13 volumio volumio[14105]: info: Discovery: Found device Volumio Sep 02 15:43:13 volumio volumio[14105]: info: CoreCommandRouter::volumioGetState Sep 02 15:43:13 volumio volumio[14105]: info: CorePlayQueue::getTrack 0 Sep 02 15:43:13 volumio volumio[14105]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 02 15:43:13 volumio volumio[14105]: info: Reading ALSA contributions from plugins. Sep 02 15:43:13 volumio volumio[14105]: info: VolumeController:: Volume=37 Mute =false Sep 02 15:43:13 volumio volumio[14105]: info: CoreStateMachine::pushState Sep 02 15:43:13 volumio volumio[14105]: info: CorePlayQueue::getTrack 0 Sep 02 15:43:13 volumio volumio[14105]: info: CoreCommandRouter::volumioPushState Sep 02 15:43:13 volumio volumio[14105]: info: Asound.conf file unchanged, so no further update is needed Sep 02 15:43:13 volumio volumio[14105]: info: Output device has changed, restarting MPD Sep 02 15:43:13 volumio sudo[14250]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 15:43:13 volumio sudo[14250]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:13 volumio volumio[14105]: info: ___________ START PLUGINS ___________ Sep 02 15:43:13 volumio sudo[14250]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:13 volumio volumio[14105]: info: ControllerMpd::onStart: Initializing MPD Sep 02 15:43:13 volumio volumio[14105]: info: Creating MPD Configuration file Sep 02 15:43:13 volumio sudo[14253]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 15:43:13 volumio sudo[14253]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:13 volumio systemd[1]: Stopping Music Player Daemon... Sep 02 15:43:13 volumio sudo[14256]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 15:43:13 volumio sudo[14256]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:13 volumio systemd[1]: mpd.service: Succeeded. Sep 02 15:43:13 volumio systemd[1]: Stopped Music Player Daemon. Sep 02 15:43:13 volumio sudo[14256]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:13 volumio volumio[14105]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 15:43:13 volumio volumio[14105]: info: [1756820593850] CoreMusicLibrary::Adding element Last_100 Sep 02 15:43:13 volumio volumio[14105]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 15:43:13 volumio volumio[14105]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 15:43:13 volumio volumio[14105]: info: [1756820593858] CoreMusicLibrary::Adding element Webradio Sep 02 15:43:13 volumio volumio[14105]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 15:43:13 volumio volumio[14105]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 15:43:13 volumio sudo[14259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 15:43:13 volumio sudo[14259]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:13 volumio volumio[14105]: info: Initializing BBC Radios Sep 02 15:43:13 volumio systemd[1]: Starting Music Player Daemon... Sep 02 15:43:13 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 02 15:43:13 volumio systemd[1]: mpd.service: Succeeded. Sep 02 15:43:13 volumio systemd[1]: Stopped Music Player Daemon. Sep 02 15:43:13 volumio volumio[14105]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 15:43:13 volumio systemd[1]: Starting Music Player Daemon... Sep 02 15:43:13 volumio volumio[14105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 15:43:13 volumio volumio[14105]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 15:43:13 volumio volumio[14105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 02 15:43:13 volumio volumio[14105]: info: Discovery: Getting this device information Sep 02 15:43:13 volumio volumio[14105]: info: CoreCommandRouter::volumioGetState Sep 02 15:43:13 volumio volumio[14105]: info: CorePlayQueue::getTrack 0 Sep 02 15:43:13 volumio volumio[14105]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 02 15:43:13 volumio volumio[14105]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 15:43:13 volumio volumio[14105]: info: [1756820593986] CoreMusicLibrary::Adding element Jellyfin Sep 02 15:43:13 volumio volumio[14105]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 15:43:13 volumio volumio[14105]: Cannot find translation for source Jellyfin Sep 02 15:43:13 volumio volumio[14105]: 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 02 15:43:14 volumio volumio[14105]: info: Creating Spotify config file Sep 02 15:43:14 volumio volumio[14105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:14 volumio sudo[14272]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 02 15:43:14 volumio sudo[14272]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:14 volumio sudo[14272]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:14 volumio volumio[14105]: info: Loading i18n strings for locale hu Sep 02 15:43:14 volumio volumio[14105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 02 15:43:14 volumio volumio[14105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:14 volumio volumio[14105]: info: FusionDsp - mixtype--------------------- Hardware Sep 02 15:43:14 volumio volumio[14105]: info: Volumio Calling Home Sep 02 15:43:14 volumio volumio[14105]: info: Preparing to generate the ALSA configuration file Sep 02 15:43:14 volumio volumio[14105]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 02 15:43:14 volumio volumio[14105]: info: Reading ALSA contributions from plugins. Sep 02 15:43:14 volumio volumio[14105]: info: MPD Permissions set Sep 02 15:43:14 volumio volumio[14105]: info: MPD Permissions set Sep 02 15:43:14 volumio volumio[14105]: info: Spotify config file written Sep 02 15:43:14 volumio sudo[14293]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 02 15:43:14 volumio sudo[14293]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:14 volumio volumio[14105]: 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 02 15:43:14 volumio volumio[14105]: info: Volumio called home Sep 02 15:43:14 volumio volumio[14105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:14 volumio systemd[1]: Stopping go-librespot Daemon... Sep 02 15:43:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 02 15:43:14 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 02 15:43:14 volumio systemd[1]: Stopped go-librespot Daemon. Sep 02 15:43:14 volumio volumio[14105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 15:43:14 volumio volumio[14105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:14 volumio volumio[14105]: info: No need to fix Spotify hosts Sep 02 15:43:14 volumio volumio[14105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:14 volumio volumio[14105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:14 volumio volumio[14105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:14 volumio volumio[14105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 15:43:14 volumio volumio[14105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:14 volumio systemd[1]: Started go-librespot Daemon. Sep 02 15:43:14 volumio go-librespot[14297]: go-librespot daemon starting... Sep 02 15:43:14 volumio sudo[14293]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:14 volumio go-librespot[14297]: time="2025-09-02T15:43:14+02:00" level=info msg="running go-librespot 0.3.2" Sep 02 15:43:14 volumio go-librespot[14297]: time="2025-09-02T15:43:14+02:00" level=debug msg="app state loaded" Sep 02 15:43:14 volumio go-librespot[14297]: time="2025-09-02T15:43:14+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 02 15:43:14 volumio sudo[14313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 02 15:43:14 volumio sudo[14313]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:14 volumio sudo[14313]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:14 volumio sudo[14316]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 02 15:43:14 volumio sudo[14316]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:14 volumio sudo[14316]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:14 volumio go-librespot[14297]: time="2025-09-02T15:43:14+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 02 15:43:14 volumio go-librespot[14297]: time="2025-09-02T15:43:14+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 02 15:43:14 volumio go-librespot[14297]: time="2025-09-02T15:43:14+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 02 15:43:14 volumio go-librespot[14297]: time="2025-09-02T15:43:14+02:00" level=info msg="zeroconf server listening on port 42091" Sep 02 15:43:14 volumio volumio[14105]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 02 15:43:14 volumio volumio[14105]: SPOTIFY: BQCCTQFgJfR3wO9B8GizmsMfIW2qGLNdjV4QOzLd1pZj5aQG2I6-vAtMUjydwDsd_zE8wU9VIaXgtQKbEYCJ-unH569QrjSmo7sLlUIngbh2fOweuBasPlFzMh238DU5k-g6ANbz-2tR_F7htez6AzPX_Bx4DUhuqw1v1rXZkF4TOMmYQAK_-41axu0hVE9W4pozo5WfzuR0tgm3lArLp-sERsP-L4SOOK83ksEfKhtvc06CmgdiRg1_7w0xU8ZUpZ4ZONCcxRypZsinRO9nuDbc64LJ3wKCgqqjNr2iapCENZ0 Sep 02 15:43:14 volumio volumio[14105]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 02 15:43:14 volumio volumio[14105]: info: New Spotify access token = BQCCTQFgJfR3wO9B8GizmsMfIW2qGLNdjV4QOzLd1pZj5aQG2I6-vAtMUjydwDsd_zE8wU9VIaXgtQKbEYCJ-unH569QrjSmo7sLlUIngbh2fOweuBasPlFzMh238DU5k-g6ANbz-2tR_F7htez6AzPX_Bx4DUhuqw1v1rXZkF4TOMmYQAK_-41axu0hVE9W4pozo5WfzuR0tgm3lArLp-sERsP-L4SOOK83ksEfKhtvc06CmgdiRg1_7w0xU8ZUpZ4ZONCcxRypZsinRO9nuDbc64LJ3wKCgqqjNr2iapCENZ0 Sep 02 15:43:14 volumio go-librespot[14297]: time="2025-09-02T15:43:14+02:00" level=debug msg="obtained new client token: AAAYQAOK2w5QXkpIkrPw5NxhiIB/ZktkiG7/HmgQer5w9TEgC4UJkO+cE1xYTrpFeKgO0ioKPyOjGSGl08K0MjW/Jl7+hU4TxfBMhPD3wd93cvtNgSa5G8XC7M8rKjlhCv1uduVXezHlH3ys8rzTR01SEUbgcYqk5BnXFXapllcAE1RexFyytDZ4dcnmamwnKHtYVm7SiwKZ1XEqC38/eZHOdkV2e4QtblUPp+ktjtj5wxsw/fJosp/0M00=" Sep 02 15:43:14 volumio volumio[14105]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 02 15:43:14 volumio volumio[14105]: info: CoreCommandRouter::volumioGetState Sep 02 15:43:14 volumio volumio[14105]: info: CorePlayQueue::getTrack 0 Sep 02 15:43:15 volumio go-librespot[14297]: time="2025-09-02T15:43:15+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 02 15:43:15 volumio go-librespot[14297]: time="2025-09-02T15:43:15+02:00" level=debug msg="completed keyexchange" Sep 02 15:43:15 volumio go-librespot[14297]: time="2025-09-02T15:43:15+02:00" level=debug msg="completed challenge" Sep 02 15:43:15 volumio volumio[14105]: info: Asound.conf file unchanged, so no further update is needed Sep 02 15:43:15 volumio volumio[14105]: info: Output device has changed, restarting MPD Sep 02 15:43:15 volumio go-librespot[14297]: time="2025-09-02T15:43:15+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 02 15:43:15 volumio sudo[14320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 15:43:15 volumio sudo[14320]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:15 volumio sudo[14320]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:15 volumio sudo[14322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 15:43:15 volumio sudo[14322]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:15 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 02 15:43:15 volumio systemd[1]: mpd.service: Succeeded. Sep 02 15:43:15 volumio systemd[1]: Stopped Music Player Daemon. Sep 02 15:43:15 volumio volumio[14105]: info: MPD Permissions set Sep 02 15:43:15 volumio go-librespot[14297]: time="2025-09-02T15:43:15+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 02 15:43:15 volumio go-librespot[14297]: time="2025-09-02T15:43:15+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 02 15:43:15 volumio volumio[14105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:15 volumio sudo[14326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 02 15:43:15 volumio sudo[14326]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:15 volumio volumio[14105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:15 volumio volumio[14105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 15:43:15 volumio volumio[14105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:15 volumio systemd[1]: Starting Music Player Daemon... Sep 02 15:43:15 volumio go-librespot[14297]: time="2025-09-02T15:43:15+02:00" level=debug msg="dealer connection opened" Sep 02 15:43:15 volumio go-librespot[14297]: time="2025-09-02T15:43:15+02:00" level=trace msg="starting accesspoint recv loop" Sep 02 15:43:15 volumio go-librespot[14297]: time="2025-09-02T15:43:15+02:00" level=trace msg="received accesspoint ping" Sep 02 15:43:15 volumio go-librespot[14297]: time="2025-09-02T15:43:15+02:00" level=trace msg="starting dealer recv loop" Sep 02 15:43:15 volumio go-librespot[14297]: time="2025-09-02T15:43:15+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 02 15:43:15 volumio go-librespot[14297]: time="2025-09-02T15:43:15+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 02 15:43:15 volumio go-librespot[14297]: time="2025-09-02T15:43:15+02:00" level=debug msg="received connection id: NzcxMGQ4YTEtM2Uw...NjdGQzcwMEU4RA==" Sep 02 15:43:15 volumio sudo[14326]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:15 volumio volumio[14105]: info: Upmpdcli Daemon Started Sep 02 15:43:15 volumio volumio[14105]: 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 02 15:43:15 volumio volumio[14105]: info: Spotify Successfully logged in Sep 02 15:43:15 volumio volumio[14105]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 15:43:15 volumio volumio[14105]: info: [1756820595349] CoreMusicLibrary::Adding element Spotify Sep 02 15:43:15 volumio volumio[14105]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 15:43:15 volumio volumio[14105]: Cannot find translation for source Jellyfin Sep 02 15:43:15 volumio volumio[14105]: Cannot find translation for source Spotify Sep 02 15:43:15 volumio sudo[14327]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 02 15:43:15 volumio sudo[14327]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:15 volumio go-librespot[14297]: time="2025-09-02T15:43:15+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 02 15:43:15 volumio go-librespot[14297]: time="2025-09-02T15:43:15+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 02 15:43:15 volumio go-librespot[14297]: time="2025-09-02T15:43:15+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 02 15:43:15 volumio go-librespot[14297]: time="2025-09-02T15:43:15+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 02 15:43:15 volumio go-librespot[14297]: time="2025-09-02T15:43:15+02:00" level=trace msg="received accesspoint pong ack" Sep 02 15:43:15 volumio sudo[14327]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:15 volumio volumio[14105]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 02 15:43:15 volumio volumio[14105]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 15:43:15 volumio volumio[14105]: info: VolumeController:: Volume=37 Mute =false Sep 02 15:43:15 volumio volumio[14105]: info: CoreCommandRouter::volumioGetState Sep 02 15:43:15 volumio volumio[14105]: info: CorePlayQueue::getTrack 0 Sep 02 15:43:15 volumio volumio[14105]: info: CoreStateMachine::pushState Sep 02 15:43:15 volumio volumio[14105]: info: CorePlayQueue::getTrack 0 Sep 02 15:43:15 volumio volumio[14105]: info: CoreCommandRouter::volumioPushState Sep 02 15:43:16 volumio volumio[14105]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 02 15:43:16 volumio volumio[14105]: 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 02 15:43:16 volumio volumio[14105]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 02 15:43:16 volumio volumio[14105]: info: camilladsp service started and running in background, instance 1 Sep 02 15:43:16 volumio volumio[14105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:16 volumio volumio[14105]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 02 15:43:16 volumio volumio[14105]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 02 15:43:16 volumio volumio[14105]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 02 15:43:16 volumio volumio[14105]: info: FusionDsp loaded Sep 02 15:43:16 volumio volumio[14105]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 02 15:43:16 volumio sudo[14347]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 02 15:43:16 volumio sudo[14347]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:16 volumio sudo[14347]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:16 volumio volumio[14105]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 02 15:43:16 volumio volumio[14105]: info: Adding Signal Path Element [object Object] Sep 02 15:43:16 volumio volumio[14105]: info: Adding fusiondspeq DSP Signal Path Element Sep 02 15:43:16 volumio volumio[14105]: info: FusionDsp - ---- installed callbackRead Sep 02 15:43:16 volumio volumio[14105]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 15:43:16 volumio volumio[14105]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 02 15:43:16 volumio volumio[14105]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 02 15:43:16 volumio volumio[14105]: at onErrorNT (internal/child_process.js:465:16) Sep 02 15:43:16 volumio volumio[14105]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Sep 02 15:43:16 volumio volumio[14105]: errno: -2, Sep 02 15:43:16 volumio volumio[14105]: code: 'ENOENT', Sep 02 15:43:16 volumio volumio[14105]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 02 15:43:16 volumio volumio[14105]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 02 15:43:16 volumio volumio[14105]: spawnargs: [ Sep 02 15:43:16 volumio volumio[14105]: '-p', Sep 02 15:43:16 volumio volumio[14105]: 9876, Sep 02 15:43:16 volumio volumio[14105]: '-o', Sep 02 15:43:16 volumio volumio[14105]: '/tmp/camilladsp.log', Sep 02 15:43:16 volumio volumio[14105]: '-l', Sep 02 15:43:16 volumio volumio[14105]: 'warn', Sep 02 15:43:16 volumio volumio[14105]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 02 15:43:16 volumio volumio[14105]: ] Sep 02 15:43:16 volumio volumio[14105]: } Sep 02 15:43:16 volumio volumio[14105]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 15:43:16 volumio mpd[14334]: Sep 02 15:43 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 02 15:43:16 volumio systemd[1]: Started Music Player Daemon. Sep 02 15:43:16 volumio sudo[14253]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:16 volumio sudo[14259]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:16 volumio sudo[14322]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:17 volumio sudo[14361]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-02 15:42 Sep 02 15:43:17 volumio sudo[14361]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:17 volumio sudo[14361]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:17 volumio volumio-remote-updater[504]: [2025-09-02 15:43:17] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 02 15:43:17 volumio volumio-remote-updater[504]: [2025-09-02 15:43:17] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 02 15:43:17 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 02 15:43:17 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 02 15:43:17 volumio systemd[1]: Started dynamicswap service. Sep 02 15:43:17 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 02 15:43:17 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 02 15:43:17 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3790. Sep 02 15:43:17 volumio systemd[1]: Started dynamicswap service. Sep 02 15:43:17 volumio systemd[1]: Stopped Volumio Backend Module. Sep 02 15:43:17 volumio systemd[1]: Started Volumio Backend Module. Sep 02 15:43:17 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 02 15:43:19 volumio volumio[14410]: info: ------------------------------------------- Sep 02 15:43:19 volumio volumio[14410]: info: ----- Volumio3 ---- Sep 02 15:43:19 volumio volumio[14410]: info: ------------------------------------------- Sep 02 15:43:19 volumio volumio[14410]: info: ----- System startup ---- Sep 02 15:43:19 volumio volumio[14410]: info: ------------------------------------------- Sep 02 15:43:20 volumio volumio[14410]: info: MYVOLUMIO Environment detected Sep 02 15:43:20 volumio volumio[14410]: info: Plugin folders cleanup Sep 02 15:43:20 volumio volumio[14410]: info: Scanning into folder /volumio/app/plugins/ Sep 02 15:43:20 volumio volumio[14410]: info: Scanning category audio_interface Sep 02 15:43:20 volumio volumio[14410]: info: Scanning category miscellanea Sep 02 15:43:20 volumio volumio[14410]: info: Scanning category music_service Sep 02 15:43:20 volumio volumio[14410]: info: Scanning category plugins.json Sep 02 15:43:20 volumio volumio[14410]: info: Scanning category system_controller Sep 02 15:43:20 volumio volumio[14410]: info: Scanning category user_interface Sep 02 15:43:20 volumio volumio[14410]: info: Scanning into folder /data/plugins/ Sep 02 15:43:20 volumio volumio[14410]: info: Scanning category audio_interface Sep 02 15:43:20 volumio volumio[14410]: info: Scanning category music_service Sep 02 15:43:20 volumio volumio[14410]: info: Scanning category user_interface Sep 02 15:43:20 volumio volumio[14410]: info: Plugin folders cleanup completed Sep 02 15:43:20 volumio volumio[14410]: info: ------------------------------------------- Sep 02 15:43:20 volumio volumio[14410]: info: ----- Core plugins startup ---- Sep 02 15:43:20 volumio volumio[14410]: info: ------------------------------------------- Sep 02 15:43:20 volumio volumio[14410]: info: Loading plugins from folder /volumio/app/plugins/ Sep 02 15:43:20 volumio volumio[14410]: info: Adding plugin upnp to MyMusic Plugins Sep 02 15:43:20 volumio volumio[14410]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 02 15:43:20 volumio volumio[14410]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 02 15:43:20 volumio volumio[14410]: info: Loading plugins from folder /data/plugins/ Sep 02 15:43:20 volumio volumio[14410]: info: Loading plugin "system"... Sep 02 15:43:20 volumio volumio[14410]: info: Loading plugin "appearance"... Sep 02 15:43:21 volumio volumio[14410]: info: Loading plugin "network"... Sep 02 15:43:21 volumio volumio[14410]: info: Refreshing Cached IP Addresses Sep 02 15:43:21 volumio sudo[14436]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 02 15:43:21 volumio sudo[14436]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:21 volumio sudo[14436]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:21 volumio sudo[14438]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 02 15:43:21 volumio volumio[14410]: info: Loading plugin "services"... Sep 02 15:43:21 volumio sudo[14438]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:21 volumio volumio[14410]: info: Loading plugin "alsa_controller"... Sep 02 15:43:21 volumio sudo[14438]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:21 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 15:43:21 volumio volumio[14410]: info: Loading plugin "wizard"... Sep 02 15:43:21 volumio volumio[14410]: info: Loading plugin "networkfs"... Sep 02 15:43:21 volumio volumio[14410]: info: Starting Udev Watcher for removable devices Sep 02 15:43:21 volumio volumio[14410]: info: Ignoring mount for partition: boot Sep 02 15:43:21 volumio volumio[14410]: info: Ignoring mount for partition: volumio Sep 02 15:43:21 volumio volumio[14410]: info: Ignoring mount for partition: volumio_data Sep 02 15:43:21 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 15:43:21 volumio volumio[14410]: info: Loading plugin "volumio_command_line_client"... Sep 02 15:43:21 volumio volumio[14410]: info: Loading plugin "upnp"... Sep 02 15:43:21 volumio volumio[14410]: info: [1756820601555] Starting Upmpd Daemon Sep 02 15:43:21 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 15:43:21 volumio volumio[14410]: info: Loading plugin "my_music"... Sep 02 15:43:21 volumio volumio[14410]: info: Loading plugin "mpd"... Sep 02 15:43:21 volumio volumio[14410]: info: Plugin upnp_browser is not enabled Sep 02 15:43:21 volumio volumio[14410]: info: Loading plugin "alarm-clock"... Sep 02 15:43:22 volumio volumio[14410]: info: Plugin airplay_emulation is not enabled Sep 02 15:43:22 volumio volumio[14410]: info: Loading plugin "last_100"... Sep 02 15:43:22 volumio volumio[14410]: info: Loading plugin "webradio"... Sep 02 15:43:22 volumio volumio[14410]: info: Loading plugin "i2s_dacs"... Sep 02 15:43:22 volumio volumio[14410]: info: Loading plugin "volumiodiscovery"... Sep 02 15:43:22 volumio volumio[14410]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 02 15:43:22 volumio volumio[14410]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 15:43:22 volumio volumio[14410]: *** WARNING *** For more information see Sep 02 15:43:22 volumio node[14410]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 02 15:43:22 volumio volumio[14410]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 02 15:43:22 volumio volumio[14410]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 15:43:22 volumio volumio[14410]: *** WARNING *** For more information see Sep 02 15:43:22 volumio node[14410]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 15:43:22 volumio node[14410]: *** WARNING *** For more information see Sep 02 15:43:22 volumio node[14410]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 02 15:43:22 volumio node[14410]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 15:43:22 volumio node[14410]: *** WARNING *** For more information see Sep 02 15:43:22 volumio volumio-remote-updater[504]: [2025-09-02 15:43:22] [connect] Successful connection Sep 02 15:43:22 volumio volumio[14410]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 02 15:43:22 volumio volumio[14410]: info: Discovery: Started advertising with name: Volumio Sep 02 15:43:22 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 15:43:22 volumio volumio[14410]: info: Loading plugin "jellyfin"... Sep 02 15:43:23 volumio volumio[14410]: info: Loading plugin "spop"... Sep 02 15:43:24 volumio volumio[14410]: info: Loading plugin "ytcr"... Sep 02 15:43:28 volumio volumio[14410]: info: Loading plugin "outputs"... Sep 02 15:43:28 volumio volumio[14410]: info: Loading plugin "albumart"... Sep 02 15:43:28 volumio volumio[14410]: info: Plugin example_plugin is not enabled Sep 02 15:43:28 volumio volumio[14410]: info: Loading plugin "inputs"... Sep 02 15:43:28 volumio volumio[14410]: info: Loading plugin "updater_comm"... Sep 02 15:43:28 volumio volumio[14410]: info: Plugin mpdemulation is not enabled Sep 02 15:43:28 volumio volumio[14410]: info: Loading plugin "rest_api"... Sep 02 15:43:28 volumio volumio[14410]: info: Loading plugin "websocket"... Sep 02 15:43:28 volumio volumio[14410]: info: Starting Socket.io Server version 2.3.0 Sep 02 15:43:28 volumio volumio[14410]: info: Loading plugin "fusiondsp"... Sep 02 15:43:28 volumio volumio[14410]: Forking 3 albumart workers Sep 02 15:43:29 volumio volumio[14410]: info: Applying required configuration parameters for plugin fusiondsp Sep 02 15:43:29 volumio volumio[14410]: info: Loading plugin "Systeminfo"... Sep 02 15:43:29 volumio volumio[14410]: info: Loading i18n strings for locale hu Sep 02 15:43:29 volumio volumio[14410]: Updating browse sources language Sep 02 15:43:29 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 15:43:29 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 15:43:29 volumio volumio[14410]: info: CoreCommandRouter::initPlayerControls Sep 02 15:43:29 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:29 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:29 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:29 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:29 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:29 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:29 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:29 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:29 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 15:43:29 volumio volumio[14410]: Express server listening on port 3000 Sep 02 15:43:29 volumio volumio[14410]: [Metrics] WebUI: 10s 945.72ms Sep 02 15:43:29 volumio volumio[14410]: info: CoreStateMachine::resetVolumioState Sep 02 15:43:29 volumio volumio[14410]: info: CoreStateMachine::getcurrentVolume Sep 02 15:43:29 volumio volumio[14410]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 15:43:29 volumio volumio[14410]: info: Volumio Network Manager: Network status updated: 1 Sep 02 15:43:29 volumio volumio[14410]: Starting albumart workers Sep 02 15:43:29 volumio volumio[14410]: Starting albumart workers Sep 02 15:43:30 volumio volumio[14410]: Starting albumart workers Sep 02 15:43:30 volumio volumio[14410]: info: CoreCommandRouter::volumioGetState Sep 02 15:43:30 volumio volumio[14410]: info: CorePlayQueue::getTrack 0 Sep 02 15:43:30 volumio volumio[14410]: info: CoreCommandRouter::volumioGetState Sep 02 15:43:30 volumio volumio[14410]: info: CorePlayQueue::getTrack 0 Sep 02 15:43:30 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 15:43:30 volumio volumio[14410]: info: VolumeController:: Volume=37 Mute =false Sep 02 15:43:30 volumio volumio[14410]: info: CoreStateMachine::pushState Sep 02 15:43:30 volumio volumio[14410]: info: CorePlayQueue::getTrack 0 Sep 02 15:43:30 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 15:43:30 volumio volumio[14410]: info: CoreCommandRouter::volumioPushState Sep 02 15:43:30 volumio volumio[14410]: info: CoreStateMachine::updateTrackBlock Sep 02 15:43:30 volumio volumio[14410]: info: CorePlayQueue::getTrackBlock Sep 02 15:43:30 volumio volumio[14410]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 15:43:30 volumio volumio[14410]: info: CoreStateMachine::setRepeat false single undefined Sep 02 15:43:30 volumio volumio[14410]: info: CoreStateMachine::pushState Sep 02 15:43:30 volumio volumio[14410]: info: CorePlayQueue::getTrack 0 Sep 02 15:43:30 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 15:43:30 volumio volumio[14410]: info: CoreCommandRouter::volumioPushState Sep 02 15:43:30 volumio volumio[14410]: info: CoreStateMachine::setRandom true Sep 02 15:43:30 volumio volumio[14410]: info: CoreStateMachine::pushState Sep 02 15:43:30 volumio volumio[14410]: info: CorePlayQueue::getTrack 0 Sep 02 15:43:30 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 15:43:30 volumio volumio[14410]: info: CoreCommandRouter::volumioPushState Sep 02 15:43:30 volumio volumio[14410]: info: Setting Device type: Raspberry PI Sep 02 15:43:30 volumio volumio[14410]: info: Listing playlists Sep 02 15:43:30 volumio volumio[14410]: info: Listing playlists Sep 02 15:43:30 volumio volumio[14410]: info: Listing playlists Sep 02 15:43:30 volumio volumio[14410]: info: Listing playlists Sep 02 15:43:30 volumio volumio[14410]: info: Completed loading Core Plugins Sep 02 15:43:30 volumio volumio[14410]: info: Preparing to generate the ALSA configuration file Sep 02 15:43:30 volumio volumio[14410]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227 Sep 02 15:43:30 volumio volumio[14410]: info: Discovery: Found device Volumio Sep 02 15:43:30 volumio volumio[14410]: info: CoreCommandRouter::volumioGetState Sep 02 15:43:30 volumio volumio[14410]: info: CorePlayQueue::getTrack 0 Sep 02 15:43:30 volumio volumio[14410]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 02 15:43:30 volumio volumio[14410]: info: Reading ALSA contributions from plugins. Sep 02 15:43:30 volumio volumio[14410]: info: VolumeController:: Volume=37 Mute =false Sep 02 15:43:30 volumio volumio[14410]: info: CoreStateMachine::pushState Sep 02 15:43:30 volumio volumio[14410]: info: CorePlayQueue::getTrack 0 Sep 02 15:43:30 volumio volumio[14410]: info: CoreCommandRouter::volumioPushState Sep 02 15:43:30 volumio volumio[14410]: info: Asound.conf file unchanged, so no further update is needed Sep 02 15:43:30 volumio volumio[14410]: info: Output device has changed, restarting MPD Sep 02 15:43:30 volumio sudo[14531]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 15:43:30 volumio sudo[14531]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:30 volumio sudo[14531]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:30 volumio volumio[14410]: info: ___________ START PLUGINS ___________ Sep 02 15:43:30 volumio sudo[14534]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 15:43:30 volumio volumio[14410]: info: ControllerMpd::onStart: Initializing MPD Sep 02 15:43:30 volumio volumio[14410]: info: Creating MPD Configuration file Sep 02 15:43:30 volumio sudo[14534]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:30 volumio systemd[1]: Stopping Music Player Daemon... Sep 02 15:43:30 volumio sudo[14541]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 15:43:30 volumio sudo[14541]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:30 volumio sudo[14541]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:30 volumio systemd[1]: mpd.service: Succeeded. Sep 02 15:43:30 volumio systemd[1]: Stopped Music Player Daemon. Sep 02 15:43:30 volumio volumio[14410]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 15:43:30 volumio volumio[14410]: info: [1756820610861] CoreMusicLibrary::Adding element Last_100 Sep 02 15:43:30 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 15:43:30 volumio volumio[14410]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 15:43:30 volumio volumio[14410]: info: [1756820610870] CoreMusicLibrary::Adding element Webradio Sep 02 15:43:30 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 15:43:30 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 15:43:30 volumio volumio[14410]: info: Initializing BBC Radios Sep 02 15:43:30 volumio sudo[14548]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 15:43:30 volumio sudo[14548]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:30 volumio systemd[1]: Starting Music Player Daemon... Sep 02 15:43:30 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 02 15:43:30 volumio systemd[1]: mpd.service: Succeeded. Sep 02 15:43:30 volumio systemd[1]: Stopped Music Player Daemon. Sep 02 15:43:30 volumio systemd[1]: Starting Music Player Daemon... Sep 02 15:43:30 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 15:43:30 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 15:43:30 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 15:43:30 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 02 15:43:31 volumio volumio[14410]: info: Discovery: Getting this device information Sep 02 15:43:31 volumio volumio[14410]: info: CoreCommandRouter::volumioGetState Sep 02 15:43:31 volumio volumio[14410]: info: CorePlayQueue::getTrack 0 Sep 02 15:43:31 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 02 15:43:31 volumio volumio[14410]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 15:43:31 volumio volumio[14410]: info: [1756820611011] CoreMusicLibrary::Adding element Jellyfin Sep 02 15:43:31 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 15:43:31 volumio volumio[14410]: Cannot find translation for source Jellyfin Sep 02 15:43:31 volumio volumio[14410]: 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 02 15:43:31 volumio volumio[14410]: info: Creating Spotify config file Sep 02 15:43:31 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:31 volumio sudo[14554]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 02 15:43:31 volumio sudo[14554]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:31 volumio sudo[14554]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:31 volumio volumio[14410]: info: Loading i18n strings for locale hu Sep 02 15:43:31 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 02 15:43:31 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:31 volumio volumio[14410]: info: FusionDsp - mixtype--------------------- Hardware Sep 02 15:43:31 volumio volumio[14410]: info: Volumio Calling Home Sep 02 15:43:31 volumio volumio[14410]: info: Preparing to generate the ALSA configuration file Sep 02 15:43:31 volumio volumio[14410]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 02 15:43:31 volumio volumio[14410]: info: Reading ALSA contributions from plugins. Sep 02 15:43:31 volumio volumio[14410]: info: MPD Permissions set Sep 02 15:43:31 volumio volumio[14410]: info: MPD Permissions set Sep 02 15:43:31 volumio volumio[14410]: info: Spotify config file written Sep 02 15:43:31 volumio sudo[14581]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 02 15:43:31 volumio sudo[14581]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:31 volumio volumio[14410]: 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 02 15:43:31 volumio systemd[1]: Stopping go-librespot Daemon... Sep 02 15:43:31 volumio volumio[14410]: info: Volumio called home Sep 02 15:43:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 02 15:43:31 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 02 15:43:31 volumio systemd[1]: Stopped go-librespot Daemon. Sep 02 15:43:31 volumio sudo[14586]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 02 15:43:31 volumio sudo[14586]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:31 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:31 volumio systemd[1]: Started go-librespot Daemon. Sep 02 15:43:31 volumio sudo[14586]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:31 volumio sudo[14581]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:31 volumio go-librespot[14584]: go-librespot daemon starting... Sep 02 15:43:31 volumio sudo[14589]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 02 15:43:31 volumio sudo[14589]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:31 volumio sudo[14589]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:31 volumio go-librespot[14584]: time="2025-09-02T15:43:31+02:00" level=info msg="running go-librespot 0.3.2" Sep 02 15:43:31 volumio go-librespot[14584]: time="2025-09-02T15:43:31+02:00" level=debug msg="app state loaded" Sep 02 15:43:31 volumio go-librespot[14584]: time="2025-09-02T15:43:31+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 02 15:43:31 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 15:43:31 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:31 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:31 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:31 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 15:43:31 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:31 volumio volumio[14410]: info: No need to fix Spotify hosts Sep 02 15:43:32 volumio go-librespot[14584]: time="2025-09-02T15:43:32+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 02 15:43:32 volumio go-librespot[14584]: time="2025-09-02T15:43:32+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 02 15:43:32 volumio go-librespot[14584]: time="2025-09-02T15:43:32+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 02 15:43:32 volumio go-librespot[14584]: time="2025-09-02T15:43:32+02:00" level=info msg="zeroconf server listening on port 38517" Sep 02 15:43:32 volumio go-librespot[14584]: time="2025-09-02T15:43:32+02:00" level=debug msg="obtained new client token: AACqG7pLQ1ZpCSaJlpADmzpg62YY2zBLMGkY7hefojnT8fS6xBs2QNaQUB47OIwox0R4umEBgXgUDIzOJ8T5i4NiSaFJ1L9i9kz1ipK+mQHs4NUd/cBX4wJV6eVL/3wG6ctE1wAJuj/cHAl8Wwz3RO6H+7CTfntcYRiFxivy3TIwafXfTJz0mJ2jwWGWeoUM5KY0MywhZ7cpM4x+6IJ7h00Dox+ub4WGR1e6whGQrMaKqIrCubKd8DRP" Sep 02 15:43:32 volumio go-librespot[14584]: time="2025-09-02T15:43:32+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 02 15:43:32 volumio go-librespot[14584]: time="2025-09-02T15:43:32+02:00" level=debug msg="completed keyexchange" Sep 02 15:43:32 volumio go-librespot[14584]: time="2025-09-02T15:43:32+02:00" level=debug msg="completed challenge" Sep 02 15:43:32 volumio volumio[14410]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 02 15:43:32 volumio volumio[14410]: SPOTIFY: BQB8aQ2NbeyCbWC8aDn7A2u9wUhQNnNlXidpD8TcKeXKQ6nZM3gYLppVE5QjV7j7Gis0GzXDWX8aoX9YJIqXVnl_CbB_WmiPYo9Leg9zxw0i6JDBRC2xiLFFLm39zPp_05_EaF-5hutRTro30r0bvr5baJE5BnnQpIaNJGCFswEYkYQMgBm4u-9q3srK6Nf5rHjEeKzQeYB5iWO8SNWiPm1Z0rss8QEvbuarT2fcbunT_QgwXw_PZzXBh1PvvK0AovBf9CsuCDR1VJ2QyyN_mUQvZu-_79IW7613jOemrRhyP7U Sep 02 15:43:32 volumio volumio[14410]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 02 15:43:32 volumio volumio[14410]: info: New Spotify access token = BQB8aQ2NbeyCbWC8aDn7A2u9wUhQNnNlXidpD8TcKeXKQ6nZM3gYLppVE5QjV7j7Gis0GzXDWX8aoX9YJIqXVnl_CbB_WmiPYo9Leg9zxw0i6JDBRC2xiLFFLm39zPp_05_EaF-5hutRTro30r0bvr5baJE5BnnQpIaNJGCFswEYkYQMgBm4u-9q3srK6Nf5rHjEeKzQeYB5iWO8SNWiPm1Z0rss8QEvbuarT2fcbunT_QgwXw_PZzXBh1PvvK0AovBf9CsuCDR1VJ2QyyN_mUQvZu-_79IW7613jOemrRhyP7U Sep 02 15:43:32 volumio volumio[14410]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 02 15:43:32 volumio go-librespot[14584]: time="2025-09-02T15:43:32+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 02 15:43:32 volumio volumio[14410]: info: CoreCommandRouter::volumioGetState Sep 02 15:43:32 volumio volumio[14410]: info: CorePlayQueue::getTrack 0 Sep 02 15:43:32 volumio volumio[14410]: info: Asound.conf file unchanged, so no further update is needed Sep 02 15:43:32 volumio volumio[14410]: info: Output device has changed, restarting MPD Sep 02 15:43:32 volumio sudo[14618]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 15:43:32 volumio sudo[14618]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:32 volumio go-librespot[14584]: time="2025-09-02T15:43:32+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 02 15:43:32 volumio go-librespot[14584]: time="2025-09-02T15:43:32+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 02 15:43:32 volumio sudo[14618]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:32 volumio sudo[14622]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 15:43:32 volumio sudo[14622]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:32 volumio go-librespot[14584]: time="2025-09-02T15:43:32+02:00" level=debug msg="dealer connection opened" Sep 02 15:43:32 volumio go-librespot[14584]: time="2025-09-02T15:43:32+02:00" level=trace msg="starting accesspoint recv loop" Sep 02 15:43:32 volumio go-librespot[14584]: time="2025-09-02T15:43:32+02:00" level=trace msg="received accesspoint ping" Sep 02 15:43:32 volumio go-librespot[14584]: time="2025-09-02T15:43:32+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 02 15:43:32 volumio go-librespot[14584]: time="2025-09-02T15:43:32+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 02 15:43:32 volumio go-librespot[14584]: time="2025-09-02T15:43:32+02:00" level=trace msg="starting dealer recv loop" Sep 02 15:43:32 volumio go-librespot[14584]: time="2025-09-02T15:43:32+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 02 15:43:32 volumio go-librespot[14584]: time="2025-09-02T15:43:32+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 02 15:43:32 volumio go-librespot[14584]: time="2025-09-02T15:43:32+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 02 15:43:32 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 02 15:43:32 volumio systemd[1]: mpd.service: Succeeded. Sep 02 15:43:32 volumio go-librespot[14584]: time="2025-09-02T15:43:32+02:00" level=debug msg="received connection id: MmQ5NmViMTgtMThi...NEYxQUE3RDBFOQ==" Sep 02 15:43:32 volumio systemd[1]: Stopped Music Player Daemon. Sep 02 15:43:32 volumio volumio[14410]: info: MPD Permissions set Sep 02 15:43:32 volumio go-librespot[14584]: time="2025-09-02T15:43:32+02:00" level=trace msg="received accesspoint pong ack" Sep 02 15:43:32 volumio sudo[14627]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 02 15:43:32 volumio sudo[14627]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:32 volumio systemd[1]: Starting Music Player Daemon... Sep 02 15:43:32 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:32 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:32 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:32 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:32 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:32 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:32 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:32 volumio sudo[14627]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:32 volumio go-librespot[14584]: time="2025-09-02T15:43:32+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 02 15:43:32 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 15:43:32 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:32 volumio volumio[14410]: info: Upmpdcli Daemon Started Sep 02 15:43:32 volumio volumio[14410]: 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 02 15:43:32 volumio volumio[14410]: info: Spotify Successfully logged in Sep 02 15:43:32 volumio volumio[14410]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 15:43:32 volumio volumio[14410]: info: [1756820612640] CoreMusicLibrary::Adding element Spotify Sep 02 15:43:32 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 15:43:32 volumio volumio[14410]: Cannot find translation for source Jellyfin Sep 02 15:43:32 volumio volumio[14410]: Cannot find translation for source Spotify Sep 02 15:43:32 volumio sudo[14625]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 02 15:43:32 volumio sudo[14625]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:32 volumio sudo[14625]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:33 volumio volumio[14410]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 02 15:43:33 volumio volumio[14410]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 15:43:33 volumio volumio[14410]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 02 15:43:33 volumio volumio[14410]: 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 02 15:43:33 volumio volumio[14410]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 02 15:43:33 volumio volumio[14410]: info: camilladsp service started and running in background, instance 1 Sep 02 15:43:33 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:33 volumio volumio[14410]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 02 15:43:33 volumio volumio[14410]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 02 15:43:33 volumio volumio[14410]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 02 15:43:33 volumio volumio[14410]: info: FusionDsp loaded Sep 02 15:43:33 volumio volumio[14410]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 02 15:43:33 volumio sudo[14649]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 02 15:43:33 volumio sudo[14649]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:33 volumio sudo[14649]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:33 volumio volumio[14410]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 02 15:43:33 volumio volumio[14410]: info: Adding Signal Path Element [object Object] Sep 02 15:43:33 volumio volumio[14410]: info: Adding fusiondspeq DSP Signal Path Element Sep 02 15:43:33 volumio volumio[14410]: info: FusionDsp - ---- installed callbackRead Sep 02 15:43:33 volumio volumio[14410]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 15:43:33 volumio volumio[14410]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 02 15:43:33 volumio volumio[14410]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 02 15:43:33 volumio volumio[14410]: at onErrorNT (internal/child_process.js:465:16) Sep 02 15:43:33 volumio volumio[14410]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Sep 02 15:43:33 volumio volumio[14410]: at runNextTicks (internal/process/task_queues.js:62:3) Sep 02 15:43:33 volumio volumio[14410]: at listOnTimeout (internal/timers.js:523:9) Sep 02 15:43:33 volumio volumio[14410]: at processTimers (internal/timers.js:497:7) { Sep 02 15:43:33 volumio volumio[14410]: errno: -2, Sep 02 15:43:33 volumio volumio[14410]: code: 'ENOENT', Sep 02 15:43:33 volumio volumio[14410]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 02 15:43:33 volumio volumio[14410]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 02 15:43:33 volumio volumio[14410]: spawnargs: [ Sep 02 15:43:33 volumio volumio[14410]: '-p', Sep 02 15:43:33 volumio volumio[14410]: 9876, Sep 02 15:43:33 volumio volumio[14410]: '-o', Sep 02 15:43:33 volumio volumio[14410]: '/tmp/camilladsp.log', Sep 02 15:43:33 volumio volumio[14410]: '-l', Sep 02 15:43:33 volumio volumio[14410]: 'warn', Sep 02 15:43:33 volumio volumio[14410]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 02 15:43:33 volumio volumio[14410]: ] Sep 02 15:43:33 volumio volumio[14410]: } Sep 02 15:43:33 volumio volumio[14410]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 15:43:34 volumio sudo[14660]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-02 15:42 Sep 02 15:43:34 volumio sudo[14660]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:34 volumio mpd[14636]: Sep 02 15:43 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 02 15:43:34 volumio systemd[1]: Started Music Player Daemon. Sep 02 15:43:34 volumio sudo[14534]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:34 volumio sudo[14622]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:34 volumio sudo[14548]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:34 volumio sudo[14660]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:34 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 02 15:43:34 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 02 15:43:34 volumio systemd[1]: Started dynamicswap service. Sep 02 15:43:34 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 02 15:43:34 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 02 15:43:34 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3791. Sep 02 15:43:34 volumio systemd[1]: Stopped Volumio Backend Module. Sep 02 15:43:34 volumio systemd[1]: Started Volumio Backend Module. Sep 02 15:43:34 volumio systemd[1]: Started dynamicswap service. Sep 02 15:43:34 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 02 15:43:34 volumio volumio-remote-updater[504]: [2025-09-02 15:43:34] [info] asio async_connect error: system:111 (Connection refused) Sep 02 15:43:34 volumio volumio-remote-updater[504]: [2025-09-02 15:43:34] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 02 15:43:34 volumio volumio-remote-updater[504]: [2025-09-02 15:43:34] [error] handle_connect error: Underlying Transport Error Sep 02 15:43:36 volumio volumio[14673]: info: ------------------------------------------- Sep 02 15:43:36 volumio volumio[14673]: info: ----- Volumio3 ---- Sep 02 15:43:36 volumio volumio[14673]: info: ------------------------------------------- Sep 02 15:43:36 volumio volumio[14673]: info: ----- System startup ---- Sep 02 15:43:36 volumio volumio[14673]: info: ------------------------------------------- Sep 02 15:43:37 volumio volumio[14673]: info: MYVOLUMIO Environment detected Sep 02 15:43:37 volumio volumio[14673]: info: Plugin folders cleanup Sep 02 15:43:37 volumio volumio[14673]: info: Scanning into folder /volumio/app/plugins/ Sep 02 15:43:37 volumio volumio[14673]: info: Scanning category audio_interface Sep 02 15:43:37 volumio volumio[14673]: info: Scanning category miscellanea Sep 02 15:43:37 volumio volumio[14673]: info: Scanning category music_service Sep 02 15:43:37 volumio volumio[14673]: info: Scanning category plugins.json Sep 02 15:43:37 volumio volumio[14673]: info: Scanning category system_controller Sep 02 15:43:37 volumio volumio[14673]: info: Scanning category user_interface Sep 02 15:43:37 volumio volumio[14673]: info: Scanning into folder /data/plugins/ Sep 02 15:43:37 volumio volumio[14673]: info: Scanning category audio_interface Sep 02 15:43:37 volumio volumio[14673]: info: Scanning category music_service Sep 02 15:43:37 volumio volumio[14673]: info: Scanning category user_interface Sep 02 15:43:37 volumio volumio[14673]: info: Plugin folders cleanup completed Sep 02 15:43:37 volumio volumio[14673]: info: ------------------------------------------- Sep 02 15:43:37 volumio volumio[14673]: info: ----- Core plugins startup ---- Sep 02 15:43:37 volumio volumio[14673]: info: ------------------------------------------- Sep 02 15:43:37 volumio volumio[14673]: info: Loading plugins from folder /volumio/app/plugins/ Sep 02 15:43:37 volumio volumio[14673]: info: Adding plugin upnp to MyMusic Plugins Sep 02 15:43:37 volumio volumio[14673]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 02 15:43:37 volumio volumio[14673]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 02 15:43:37 volumio volumio[14673]: info: Loading plugins from folder /data/plugins/ Sep 02 15:43:37 volumio volumio[14673]: info: Loading plugin "system"... Sep 02 15:43:37 volumio volumio[14673]: info: Loading plugin "appearance"... Sep 02 15:43:38 volumio volumio[14673]: info: Loading plugin "network"... Sep 02 15:43:38 volumio volumio[14673]: info: Refreshing Cached IP Addresses Sep 02 15:43:38 volumio sudo[14701]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 02 15:43:38 volumio sudo[14701]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:38 volumio sudo[14701]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:38 volumio sudo[14703]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 02 15:43:38 volumio volumio[14673]: info: Loading plugin "services"... Sep 02 15:43:38 volumio sudo[14703]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:38 volumio volumio[14673]: info: Loading plugin "alsa_controller"... Sep 02 15:43:38 volumio sudo[14703]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:38 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 15:43:38 volumio volumio[14673]: info: Loading plugin "wizard"... Sep 02 15:43:38 volumio volumio[14673]: info: Loading plugin "networkfs"... Sep 02 15:43:38 volumio volumio[14673]: info: Starting Udev Watcher for removable devices Sep 02 15:43:38 volumio volumio[14673]: info: Ignoring mount for partition: boot Sep 02 15:43:38 volumio volumio[14673]: info: Ignoring mount for partition: volumio Sep 02 15:43:38 volumio volumio[14673]: info: Ignoring mount for partition: volumio_data Sep 02 15:43:38 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 15:43:38 volumio volumio[14673]: info: Loading plugin "volumio_command_line_client"... Sep 02 15:43:38 volumio volumio[14673]: info: Loading plugin "upnp"... Sep 02 15:43:38 volumio volumio[14673]: info: [1756820618586] Starting Upmpd Daemon Sep 02 15:43:38 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 15:43:38 volumio volumio[14673]: info: Loading plugin "my_music"... Sep 02 15:43:38 volumio volumio[14673]: info: Loading plugin "mpd"... Sep 02 15:43:38 volumio volumio[14673]: info: Plugin upnp_browser is not enabled Sep 02 15:43:38 volumio volumio[14673]: info: Loading plugin "alarm-clock"... Sep 02 15:43:39 volumio volumio[14673]: info: Plugin airplay_emulation is not enabled Sep 02 15:43:39 volumio volumio[14673]: info: Loading plugin "last_100"... Sep 02 15:43:39 volumio volumio[14673]: info: Loading plugin "webradio"... Sep 02 15:43:39 volumio volumio[14673]: info: Loading plugin "i2s_dacs"... Sep 02 15:43:39 volumio volumio[14673]: info: Loading plugin "volumiodiscovery"... Sep 02 15:43:39 volumio volumio[14673]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 02 15:43:39 volumio volumio[14673]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 15:43:39 volumio volumio[14673]: *** WARNING *** For more information see Sep 02 15:43:39 volumio node[14673]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 02 15:43:39 volumio volumio[14673]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 02 15:43:39 volumio volumio[14673]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 15:43:39 volumio volumio[14673]: *** WARNING *** For more information see Sep 02 15:43:39 volumio node[14673]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 15:43:39 volumio node[14673]: *** WARNING *** For more information see Sep 02 15:43:39 volumio node[14673]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 02 15:43:39 volumio node[14673]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 15:43:39 volumio node[14673]: *** WARNING *** For more information see Sep 02 15:43:39 volumio volumio[14673]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 02 15:43:39 volumio volumio[14673]: info: Discovery: Started advertising with name: Volumio Sep 02 15:43:39 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 15:43:39 volumio volumio[14673]: info: Loading plugin "jellyfin"... Sep 02 15:43:40 volumio volumio[14673]: info: Loading plugin "spop"... Sep 02 15:43:41 volumio volumio[14673]: info: Loading plugin "ytcr"... Sep 02 15:43:44 volumio volumio-remote-updater[504]: [2025-09-02 15:43:44] [connect] Successful connection Sep 02 15:43:45 volumio volumio[14673]: info: Loading plugin "outputs"... Sep 02 15:43:45 volumio volumio[14673]: info: Loading plugin "albumart"... Sep 02 15:43:45 volumio volumio[14673]: info: Plugin example_plugin is not enabled Sep 02 15:43:45 volumio volumio[14673]: info: Loading plugin "inputs"... Sep 02 15:43:45 volumio volumio[14673]: info: Loading plugin "updater_comm"... Sep 02 15:43:45 volumio volumio[14673]: info: Plugin mpdemulation is not enabled Sep 02 15:43:45 volumio volumio[14673]: info: Loading plugin "rest_api"... Sep 02 15:43:45 volumio volumio[14673]: info: Loading plugin "websocket"... Sep 02 15:43:45 volumio volumio[14673]: info: Starting Socket.io Server version 2.3.0 Sep 02 15:43:45 volumio volumio[14673]: info: Loading plugin "fusiondsp"... Sep 02 15:43:46 volumio volumio[14673]: Forking 3 albumart workers Sep 02 15:43:46 volumio volumio[14673]: info: Applying required configuration parameters for plugin fusiondsp Sep 02 15:43:46 volumio volumio[14673]: info: Loading plugin "Systeminfo"... Sep 02 15:43:46 volumio volumio[14673]: info: Loading i18n strings for locale hu Sep 02 15:43:46 volumio volumio[14673]: Updating browse sources language Sep 02 15:43:46 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 15:43:46 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 15:43:46 volumio volumio[14673]: info: CoreCommandRouter::initPlayerControls Sep 02 15:43:46 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:46 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:46 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:46 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:46 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:46 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:46 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:46 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:46 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 15:43:46 volumio volumio[14673]: Express server listening on port 3000 Sep 02 15:43:46 volumio volumio[14673]: [Metrics] WebUI: 11s 121.17ms Sep 02 15:43:47 volumio volumio[14673]: info: CoreStateMachine::resetVolumioState Sep 02 15:43:47 volumio volumio[14673]: info: CoreStateMachine::getcurrentVolume Sep 02 15:43:47 volumio volumio[14673]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 15:43:47 volumio volumio[14673]: Starting albumart workers Sep 02 15:43:47 volumio volumio[14673]: Starting albumart workers Sep 02 15:43:47 volumio volumio[14673]: info: Volumio Network Manager: Network status updated: 1 Sep 02 15:43:47 volumio volumio[14673]: Starting albumart workers Sep 02 15:43:47 volumio volumio-remote-updater[504]: [2025-09-02 15:43:47] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1756820624 101 Sep 02 15:43:47 volumio volumio[14673]: 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 02 15:43:47 volumio volumio[14673]: info: CoreCommandRouter::volumioGetState Sep 02 15:43:47 volumio volumio[14673]: info: CorePlayQueue::getTrack 0 Sep 02 15:43:47 volumio volumio[14673]: info: CoreCommandRouter::volumioGetState Sep 02 15:43:47 volumio volumio[14673]: info: CorePlayQueue::getTrack 0 Sep 02 15:43:47 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 15:43:47 volumio volumio[14673]: info: VolumeController:: Volume=37 Mute =false Sep 02 15:43:47 volumio volumio[14673]: info: CoreStateMachine::pushState Sep 02 15:43:47 volumio volumio[14673]: info: CorePlayQueue::getTrack 0 Sep 02 15:43:47 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 15:43:47 volumio volumio[14673]: info: CoreCommandRouter::volumioPushState Sep 02 15:43:47 volumio volumio[14673]: info: CoreStateMachine::updateTrackBlock Sep 02 15:43:47 volumio volumio[14673]: info: CorePlayQueue::getTrackBlock Sep 02 15:43:47 volumio volumio[14673]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 15:43:47 volumio volumio[14673]: info: CoreStateMachine::setRepeat false single undefined Sep 02 15:43:47 volumio volumio[14673]: info: CoreStateMachine::pushState Sep 02 15:43:47 volumio volumio[14673]: info: CorePlayQueue::getTrack 0 Sep 02 15:43:47 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 15:43:47 volumio volumio[14673]: info: CoreCommandRouter::volumioPushState Sep 02 15:43:47 volumio volumio[14673]: info: CoreStateMachine::setRandom true Sep 02 15:43:47 volumio volumio[14673]: info: CoreStateMachine::pushState Sep 02 15:43:47 volumio volumio[14673]: info: CorePlayQueue::getTrack 0 Sep 02 15:43:47 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 15:43:47 volumio volumio[14673]: info: CoreCommandRouter::volumioPushState Sep 02 15:43:47 volumio volumio[14673]: info: Setting Device type: Raspberry PI Sep 02 15:43:47 volumio volumio[14673]: info: Listing playlists Sep 02 15:43:47 volumio volumio[14673]: info: Listing playlists Sep 02 15:43:47 volumio volumio[14673]: info: Listing playlists Sep 02 15:43:47 volumio volumio[14673]: info: Listing playlists Sep 02 15:43:47 volumio volumio[14673]: info: Completed loading Core Plugins Sep 02 15:43:47 volumio volumio[14673]: info: Preparing to generate the ALSA configuration file Sep 02 15:43:47 volumio volumio[14673]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227 Sep 02 15:43:47 volumio volumio[14673]: info: Discovery: Found device Volumio Sep 02 15:43:47 volumio volumio[14673]: info: CoreCommandRouter::volumioGetState Sep 02 15:43:47 volumio volumio[14673]: info: CorePlayQueue::getTrack 0 Sep 02 15:43:47 volumio volumio[14673]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 02 15:43:47 volumio volumio[14673]: info: Reading ALSA contributions from plugins. Sep 02 15:43:47 volumio volumio[14673]: info: VolumeController:: Volume=37 Mute =false Sep 02 15:43:47 volumio volumio[14673]: info: CoreStateMachine::pushState Sep 02 15:43:47 volumio volumio[14673]: info: CorePlayQueue::getTrack 0 Sep 02 15:43:47 volumio volumio[14673]: info: CoreCommandRouter::volumioPushState Sep 02 15:43:47 volumio volumio[14673]: info: Asound.conf file unchanged, so no further update is needed Sep 02 15:43:47 volumio volumio[14673]: info: Output device has changed, restarting MPD Sep 02 15:43:47 volumio sudo[14800]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 15:43:47 volumio sudo[14800]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:47 volumio sudo[14800]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:47 volumio volumio[14673]: info: ___________ START PLUGINS ___________ Sep 02 15:43:47 volumio volumio[14673]: info: ControllerMpd::onStart: Initializing MPD Sep 02 15:43:47 volumio volumio[14673]: info: Creating MPD Configuration file Sep 02 15:43:47 volumio sudo[14807]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 15:43:47 volumio sudo[14807]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:47 volumio systemd[1]: Stopping Music Player Daemon... Sep 02 15:43:47 volumio sudo[14810]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 15:43:47 volumio sudo[14810]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:48 volumio volumio[14673]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 15:43:48 volumio volumio[14673]: info: [1756820628008] CoreMusicLibrary::Adding element Last_100 Sep 02 15:43:48 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 15:43:48 volumio sudo[14810]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:48 volumio volumio[14673]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 15:43:48 volumio volumio[14673]: info: [1756820628016] CoreMusicLibrary::Adding element Webradio Sep 02 15:43:48 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 15:43:48 volumio systemd[1]: mpd.service: Succeeded. Sep 02 15:43:48 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 15:43:48 volumio systemd[1]: Stopped Music Player Daemon. Sep 02 15:43:48 volumio sudo[14813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 15:43:48 volumio sudo[14813]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:48 volumio systemd[1]: Starting Music Player Daemon... Sep 02 15:43:48 volumio volumio[14673]: info: Initializing BBC Radios Sep 02 15:43:48 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 02 15:43:48 volumio systemd[1]: mpd.service: Succeeded. Sep 02 15:43:48 volumio systemd[1]: Stopped Music Player Daemon. Sep 02 15:43:48 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 15:43:48 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 15:43:48 volumio systemd[1]: Starting Music Player Daemon... Sep 02 15:43:48 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 15:43:48 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 02 15:43:48 volumio volumio[14673]: info: Discovery: Getting this device information Sep 02 15:43:48 volumio volumio[14673]: info: CoreCommandRouter::volumioGetState Sep 02 15:43:48 volumio volumio[14673]: info: CorePlayQueue::getTrack 0 Sep 02 15:43:48 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 02 15:43:48 volumio volumio[14673]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 15:43:48 volumio volumio[14673]: info: [1756820628152] CoreMusicLibrary::Adding element Jellyfin Sep 02 15:43:48 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 15:43:48 volumio volumio[14673]: Cannot find translation for source Jellyfin Sep 02 15:43:48 volumio volumio[14673]: 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 02 15:43:48 volumio volumio[14673]: info: Creating Spotify config file Sep 02 15:43:48 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:48 volumio sudo[14826]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 02 15:43:48 volumio sudo[14826]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:48 volumio sudo[14826]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:48 volumio volumio[14673]: info: Loading i18n strings for locale hu Sep 02 15:43:48 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 02 15:43:48 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:48 volumio volumio[14673]: info: FusionDsp - mixtype--------------------- Hardware Sep 02 15:43:48 volumio volumio[14673]: info: Volumio Calling Home Sep 02 15:43:48 volumio volumio[14673]: info: Preparing to generate the ALSA configuration file Sep 02 15:43:48 volumio volumio[14673]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 02 15:43:48 volumio volumio[14673]: info: Reading ALSA contributions from plugins. Sep 02 15:43:48 volumio volumio[14673]: info: MPD Permissions set Sep 02 15:43:48 volumio volumio[14673]: info: MPD Permissions set Sep 02 15:43:48 volumio sudo[14847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 02 15:43:48 volumio sudo[14847]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:48 volumio sudo[14847]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:48 volumio sudo[14850]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 02 15:43:48 volumio sudo[14850]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:48 volumio sudo[14850]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:48 volumio volumio[14673]: info: Volumio called home Sep 02 15:43:48 volumio volumio[14673]: info: Spotify config file written Sep 02 15:43:48 volumio sudo[14853]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 02 15:43:48 volumio sudo[14853]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:48 volumio systemd[1]: Stopping go-librespot Daemon... Sep 02 15:43:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 02 15:43:48 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 02 15:43:48 volumio systemd[1]: Stopped go-librespot Daemon. Sep 02 15:43:48 volumio volumio[14673]: 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 02 15:43:48 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:48 volumio systemd[1]: Started go-librespot Daemon. Sep 02 15:43:48 volumio go-librespot[14855]: go-librespot daemon starting... Sep 02 15:43:48 volumio sudo[14853]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:48 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:48 volumio go-librespot[14855]: time="2025-09-02T15:43:48+02:00" level=info msg="running go-librespot 0.3.2" Sep 02 15:43:48 volumio go-librespot[14855]: time="2025-09-02T15:43:48+02:00" level=debug msg="app state loaded" Sep 02 15:43:49 volumio go-librespot[14855]: time="2025-09-02T15:43:49+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 02 15:43:49 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 15:43:49 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:49 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:49 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:49 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:49 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:49 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:49 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:49 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:49 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:49 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 15:43:49 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:49 volumio volumio[14673]: info: No need to fix Spotify hosts Sep 02 15:43:49 volumio go-librespot[14855]: time="2025-09-02T15:43:49+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 02 15:43:49 volumio go-librespot[14855]: time="2025-09-02T15:43:49+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 02 15:43:49 volumio go-librespot[14855]: time="2025-09-02T15:43:49+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 02 15:43:49 volumio go-librespot[14855]: time="2025-09-02T15:43:49+02:00" level=info msg="zeroconf server listening on port 45507" Sep 02 15:43:49 volumio volumio[14673]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 02 15:43:49 volumio volumio[14673]: SPOTIFY: BQBUCh7GPDjMlY8-uXp2w9_63f3w5Z1y5U8hP5u01uAsyDr4y-7Q3wRX1JCTcBvU8MeA52KDryHRAIqrwmbA6SPDZItguSQsVYp--uJ5MtMMnE4xlusur8Qvepj4KQd1kx7cJR555BT-6oIJiylKS0vuSZk8m4LbdGbpqeOaRdduwZlYsaRrgYRSzPXBBN931RZ-negaEghpCgakGCag4v1LHm9qNTUZN0EPjuzImq0Aw18-x4rtJDDeM_9cRcgkChXskFeJcCPxveGJkR-h4O19UQflaC9LuFm1AiKFpYwRiew Sep 02 15:43:49 volumio volumio[14673]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 02 15:43:49 volumio volumio[14673]: info: New Spotify access token = BQBUCh7GPDjMlY8-uXp2w9_63f3w5Z1y5U8hP5u01uAsyDr4y-7Q3wRX1JCTcBvU8MeA52KDryHRAIqrwmbA6SPDZItguSQsVYp--uJ5MtMMnE4xlusur8Qvepj4KQd1kx7cJR555BT-6oIJiylKS0vuSZk8m4LbdGbpqeOaRdduwZlYsaRrgYRSzPXBBN931RZ-negaEghpCgakGCag4v1LHm9qNTUZN0EPjuzImq0Aw18-x4rtJDDeM_9cRcgkChXskFeJcCPxveGJkR-h4O19UQflaC9LuFm1AiKFpYwRiew Sep 02 15:43:49 volumio volumio[14673]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 02 15:43:49 volumio volumio[14673]: info: CoreCommandRouter::volumioGetState Sep 02 15:43:49 volumio volumio[14673]: info: CorePlayQueue::getTrack 0 Sep 02 15:43:49 volumio go-librespot[14855]: time="2025-09-02T15:43:49+02:00" level=debug msg="obtained new client token: AAATuXBpYCcPJFKg1uL76c5M5Nl6ROuTNH5Cx5+nJ23Unskm2weXS4o9arXJRWFOHQPIYztNBQeB2XPVMGSthrqBDOQHPOKbn6GB4icsTlFGAAikOXY+jZ5b4rfymZPC/semVX1o0IEF/Lbmxhcu/Zg7L0xnY/eZ02OnWUUsGximEM5rLJAQxiGht8YaI3QIcyviq3W4WJLpPar0aLAGRmMXl90oNXsauwm5n2N0uCqfdVEefx1JBWKtQt0=" Sep 02 15:43:49 volumio volumio[14673]: info: Asound.conf file unchanged, so no further update is needed Sep 02 15:43:49 volumio volumio[14673]: info: Output device has changed, restarting MPD Sep 02 15:43:49 volumio go-librespot[14855]: time="2025-09-02T15:43:49+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 02 15:43:49 volumio sudo[14872]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 02 15:43:49 volumio sudo[14872]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:49 volumio go-librespot[14855]: time="2025-09-02T15:43:49+02:00" level=debug msg="completed keyexchange" Sep 02 15:43:49 volumio sudo[14872]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:49 volumio go-librespot[14855]: time="2025-09-02T15:43:49+02:00" level=debug msg="completed challenge" Sep 02 15:43:49 volumio sudo[14875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 15:43:49 volumio sudo[14875]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:49 volumio sudo[14875]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:49 volumio volumio[14673]: info: Upmpdcli Daemon Started Sep 02 15:43:49 volumio sudo[14877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 15:43:49 volumio sudo[14877]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:49 volumio volumio[14673]: info: MPD Permissions set Sep 02 15:43:49 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:49 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:49 volumio go-librespot[14855]: time="2025-09-02T15:43:49+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 02 15:43:49 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:49 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 02 15:43:49 volumio systemd[1]: mpd.service: Succeeded. Sep 02 15:43:49 volumio systemd[1]: Stopped Music Player Daemon. Sep 02 15:43:49 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 15:43:49 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:49 volumio systemd[1]: Starting Music Player Daemon... Sep 02 15:43:49 volumio go-librespot[14855]: time="2025-09-02T15:43:49+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 02 15:43:49 volumio go-librespot[14855]: time="2025-09-02T15:43:49+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 02 15:43:49 volumio volumio[14673]: 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 02 15:43:49 volumio volumio[14673]: info: Spotify Successfully logged in Sep 02 15:43:49 volumio volumio[14673]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 15:43:49 volumio volumio[14673]: info: [1756820629705] CoreMusicLibrary::Adding element Spotify Sep 02 15:43:49 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 15:43:49 volumio volumio[14673]: Cannot find translation for source Jellyfin Sep 02 15:43:49 volumio volumio[14673]: Cannot find translation for source Spotify Sep 02 15:43:49 volumio sudo[14884]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 02 15:43:49 volumio sudo[14884]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:49 volumio sudo[14884]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:49 volumio go-librespot[14855]: time="2025-09-02T15:43:49+02:00" level=debug msg="dealer connection opened" Sep 02 15:43:49 volumio go-librespot[14855]: time="2025-09-02T15:43:49+02:00" level=trace msg="starting accesspoint recv loop" Sep 02 15:43:49 volumio go-librespot[14855]: time="2025-09-02T15:43:49+02:00" level=trace msg="starting dealer recv loop" Sep 02 15:43:49 volumio go-librespot[14855]: time="2025-09-02T15:43:49+02:00" level=trace msg="received accesspoint ping" Sep 02 15:43:49 volumio go-librespot[14855]: time="2025-09-02T15:43:49+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 02 15:43:49 volumio go-librespot[14855]: time="2025-09-02T15:43:49+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 02 15:43:49 volumio go-librespot[14855]: time="2025-09-02T15:43:49+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 02 15:43:49 volumio go-librespot[14855]: time="2025-09-02T15:43:49+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 02 15:43:49 volumio go-librespot[14855]: time="2025-09-02T15:43:49+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 02 15:43:49 volumio go-librespot[14855]: time="2025-09-02T15:43:49+02:00" level=debug msg="received connection id: NmVkYWU2YjgtZmNk...OEUyQzZBOTMxQQ==" Sep 02 15:43:49 volumio go-librespot[14855]: time="2025-09-02T15:43:49+02:00" level=trace msg="received accesspoint pong ack" Sep 02 15:43:50 volumio go-librespot[14855]: time="2025-09-02T15:43:50+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 02 15:43:50 volumio volumio[14673]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 02 15:43:50 volumio volumio[14673]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 15:43:50 volumio volumio[14673]: info: VolumeController:: Volume=37 Mute =false Sep 02 15:43:50 volumio volumio[14673]: info: CoreCommandRouter::volumioGetState Sep 02 15:43:50 volumio volumio[14673]: info: CorePlayQueue::getTrack 0 Sep 02 15:43:50 volumio volumio[14673]: info: CoreStateMachine::pushState Sep 02 15:43:50 volumio volumio[14673]: info: CorePlayQueue::getTrack 0 Sep 02 15:43:50 volumio volumio[14673]: info: CoreCommandRouter::volumioPushState Sep 02 15:43:50 volumio volumio[14673]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 02 15:43:50 volumio volumio[14673]: 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 02 15:43:50 volumio volumio[14673]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 02 15:43:50 volumio volumio[14673]: info: camilladsp service started and running in background, instance 1 Sep 02 15:43:50 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:43:50 volumio volumio[14673]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 02 15:43:50 volumio volumio[14673]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 02 15:43:50 volumio volumio[14673]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 02 15:43:50 volumio volumio[14673]: info: FusionDsp loaded Sep 02 15:43:50 volumio volumio[14673]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 02 15:43:50 volumio sudo[14901]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 02 15:43:50 volumio sudo[14901]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:50 volumio sudo[14901]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:50 volumio volumio[14673]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 02 15:43:50 volumio volumio[14673]: info: Adding Signal Path Element [object Object] Sep 02 15:43:50 volumio volumio[14673]: info: Adding fusiondspeq DSP Signal Path Element Sep 02 15:43:50 volumio volumio[14673]: info: FusionDsp - ---- installed callbackRead Sep 02 15:43:50 volumio volumio[14673]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 15:43:50 volumio volumio[14673]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 02 15:43:50 volumio volumio[14673]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 02 15:43:50 volumio volumio[14673]: at onErrorNT (internal/child_process.js:465:16) Sep 02 15:43:50 volumio volumio[14673]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Sep 02 15:43:50 volumio volumio[14673]: at runNextTicks (internal/process/task_queues.js:62:3) Sep 02 15:43:50 volumio volumio[14673]: at listOnTimeout (internal/timers.js:523:9) Sep 02 15:43:50 volumio volumio[14673]: at processTimers (internal/timers.js:497:7) { Sep 02 15:43:50 volumio volumio[14673]: errno: -2, Sep 02 15:43:50 volumio volumio[14673]: code: 'ENOENT', Sep 02 15:43:50 volumio volumio[14673]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 02 15:43:50 volumio volumio[14673]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 02 15:43:50 volumio volumio[14673]: spawnargs: [ Sep 02 15:43:50 volumio volumio[14673]: '-p', Sep 02 15:43:50 volumio volumio[14673]: 9876, Sep 02 15:43:50 volumio volumio[14673]: '-o', Sep 02 15:43:50 volumio volumio[14673]: '/tmp/camilladsp.log', Sep 02 15:43:50 volumio volumio[14673]: '-l', Sep 02 15:43:50 volumio volumio[14673]: 'warn', Sep 02 15:43:50 volumio volumio[14673]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 02 15:43:50 volumio volumio[14673]: ] Sep 02 15:43:50 volumio volumio[14673]: } Sep 02 15:43:50 volumio volumio[14673]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 15:43:51 volumio sudo[14912]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-02 15:42 Sep 02 15:43:51 volumio sudo[14912]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:51 volumio sudo[14912]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:51 volumio mpd[14887]: Sep 02 15:43 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 02 15:43:51 volumio systemd[1]: Started Music Player Daemon. Sep 02 15:43:51 volumio sudo[14807]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:51 volumio sudo[14877]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:51 volumio sudo[14813]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:51 volumio volumio-remote-updater[504]: [2025-09-02 15:43:51] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 02 15:43:51 volumio volumio-remote-updater[504]: [2025-09-02 15:43:51] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 02 15:43:51 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 02 15:43:51 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 02 15:43:51 volumio systemd[1]: Started dynamicswap service. Sep 02 15:43:51 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 02 15:43:51 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 02 15:43:51 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3792. Sep 02 15:43:51 volumio systemd[1]: Started dynamicswap service. Sep 02 15:43:51 volumio systemd[1]: Stopped Volumio Backend Module. Sep 02 15:43:51 volumio systemd[1]: Started Volumio Backend Module. Sep 02 15:43:51 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 02 15:43:53 volumio volumio[14925]: info: ------------------------------------------- Sep 02 15:43:53 volumio volumio[14925]: info: ----- Volumio3 ---- Sep 02 15:43:53 volumio volumio[14925]: info: ------------------------------------------- Sep 02 15:43:53 volumio volumio[14925]: info: ----- System startup ---- Sep 02 15:43:53 volumio volumio[14925]: info: ------------------------------------------- Sep 02 15:43:54 volumio volumio[14925]: info: MYVOLUMIO Environment detected Sep 02 15:43:54 volumio volumio[14925]: info: Plugin folders cleanup Sep 02 15:43:54 volumio volumio[14925]: info: Scanning into folder /volumio/app/plugins/ Sep 02 15:43:54 volumio volumio[14925]: info: Scanning category audio_interface Sep 02 15:43:54 volumio volumio[14925]: info: Scanning category miscellanea Sep 02 15:43:54 volumio volumio[14925]: info: Scanning category music_service Sep 02 15:43:54 volumio volumio[14925]: info: Scanning category plugins.json Sep 02 15:43:54 volumio volumio[14925]: info: Scanning category system_controller Sep 02 15:43:54 volumio volumio[14925]: info: Scanning category user_interface Sep 02 15:43:54 volumio volumio[14925]: info: Scanning into folder /data/plugins/ Sep 02 15:43:54 volumio volumio[14925]: info: Scanning category audio_interface Sep 02 15:43:54 volumio volumio[14925]: info: Scanning category music_service Sep 02 15:43:54 volumio volumio[14925]: info: Scanning category user_interface Sep 02 15:43:54 volumio volumio[14925]: info: Plugin folders cleanup completed Sep 02 15:43:54 volumio volumio[14925]: info: ------------------------------------------- Sep 02 15:43:54 volumio volumio[14925]: info: ----- Core plugins startup ---- Sep 02 15:43:54 volumio volumio[14925]: info: ------------------------------------------- Sep 02 15:43:54 volumio volumio[14925]: info: Loading plugins from folder /volumio/app/plugins/ Sep 02 15:43:54 volumio volumio[14925]: info: Adding plugin upnp to MyMusic Plugins Sep 02 15:43:54 volumio volumio[14925]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 02 15:43:54 volumio volumio[14925]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 02 15:43:54 volumio volumio[14925]: info: Loading plugins from folder /data/plugins/ Sep 02 15:43:54 volumio volumio[14925]: info: Loading plugin "system"... Sep 02 15:43:54 volumio volumio[14925]: info: Loading plugin "appearance"... Sep 02 15:43:55 volumio volumio[14925]: info: Loading plugin "network"... Sep 02 15:43:55 volumio volumio[14925]: info: Refreshing Cached IP Addresses Sep 02 15:43:55 volumio sudo[14965]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 02 15:43:55 volumio sudo[14965]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:55 volumio sudo[14965]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:55 volumio sudo[14967]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 02 15:43:55 volumio sudo[14967]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:43:55 volumio volumio[14925]: info: Loading plugin "services"... Sep 02 15:43:55 volumio sudo[14967]: pam_unix(sudo:session): session closed for user root Sep 02 15:43:55 volumio volumio[14925]: info: Loading plugin "alsa_controller"... Sep 02 15:43:55 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 15:43:55 volumio volumio[14925]: info: Loading plugin "wizard"... Sep 02 15:43:55 volumio volumio[14925]: info: Loading plugin "networkfs"... Sep 02 15:43:55 volumio volumio[14925]: info: Starting Udev Watcher for removable devices Sep 02 15:43:55 volumio volumio[14925]: info: Ignoring mount for partition: boot Sep 02 15:43:55 volumio volumio[14925]: info: Ignoring mount for partition: volumio Sep 02 15:43:55 volumio volumio[14925]: info: Ignoring mount for partition: volumio_data Sep 02 15:43:55 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 15:43:55 volumio volumio[14925]: info: Loading plugin "volumio_command_line_client"... Sep 02 15:43:55 volumio volumio[14925]: info: Loading plugin "upnp"... Sep 02 15:43:55 volumio volumio[14925]: info: [1756820635547] Starting Upmpd Daemon Sep 02 15:43:55 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 15:43:55 volumio volumio[14925]: info: Loading plugin "my_music"... Sep 02 15:43:55 volumio volumio[14925]: info: Loading plugin "mpd"... Sep 02 15:43:55 volumio volumio[14925]: info: Plugin upnp_browser is not enabled Sep 02 15:43:55 volumio volumio[14925]: info: Loading plugin "alarm-clock"... Sep 02 15:43:56 volumio volumio[14925]: info: Plugin airplay_emulation is not enabled Sep 02 15:43:56 volumio volumio[14925]: info: Loading plugin "last_100"... Sep 02 15:43:56 volumio volumio[14925]: info: Loading plugin "webradio"... Sep 02 15:43:56 volumio volumio[14925]: info: Loading plugin "i2s_dacs"... Sep 02 15:43:56 volumio volumio[14925]: info: Loading plugin "volumiodiscovery"... Sep 02 15:43:56 volumio volumio[14925]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 02 15:43:56 volumio volumio[14925]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 15:43:56 volumio volumio[14925]: *** WARNING *** For more information see Sep 02 15:43:56 volumio node[14925]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 02 15:43:56 volumio volumio[14925]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 02 15:43:56 volumio volumio[14925]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 15:43:56 volumio volumio[14925]: *** WARNING *** For more information see Sep 02 15:43:56 volumio node[14925]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 15:43:56 volumio node[14925]: *** WARNING *** For more information see Sep 02 15:43:56 volumio node[14925]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 02 15:43:56 volumio node[14925]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 15:43:56 volumio node[14925]: *** WARNING *** For more information see Sep 02 15:43:56 volumio volumio[14925]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 02 15:43:56 volumio volumio[14925]: info: Discovery: Started advertising with name: Volumio Sep 02 15:43:56 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 15:43:56 volumio volumio[14925]: info: Loading plugin "jellyfin"... Sep 02 15:43:56 volumio volumio-remote-updater[504]: [2025-09-02 15:43:56] [connect] Successful connection Sep 02 15:43:57 volumio volumio[14925]: info: Loading plugin "spop"... Sep 02 15:43:58 volumio volumio[14925]: info: Loading plugin "ytcr"... Sep 02 15:44:02 volumio volumio[14925]: info: Loading plugin "outputs"... Sep 02 15:44:02 volumio volumio[14925]: info: Loading plugin "albumart"... Sep 02 15:44:02 volumio volumio[14925]: info: Plugin example_plugin is not enabled Sep 02 15:44:02 volumio volumio[14925]: info: Loading plugin "inputs"... Sep 02 15:44:02 volumio volumio[14925]: info: Loading plugin "updater_comm"... Sep 02 15:44:02 volumio volumio[14925]: info: Plugin mpdemulation is not enabled Sep 02 15:44:02 volumio volumio[14925]: info: Loading plugin "rest_api"... Sep 02 15:44:02 volumio volumio[14925]: info: Loading plugin "websocket"... Sep 02 15:44:02 volumio volumio[14925]: info: Starting Socket.io Server version 2.3.0 Sep 02 15:44:02 volumio volumio[14925]: info: Loading plugin "fusiondsp"... Sep 02 15:44:02 volumio volumio[14925]: Forking 3 albumart workers Sep 02 15:44:03 volumio volumio[14925]: info: Applying required configuration parameters for plugin fusiondsp Sep 02 15:44:03 volumio volumio[14925]: info: Loading plugin "Systeminfo"... Sep 02 15:44:03 volumio volumio[14925]: info: Loading i18n strings for locale hu Sep 02 15:44:03 volumio volumio[14925]: Updating browse sources language Sep 02 15:44:03 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 15:44:03 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 15:44:03 volumio volumio[14925]: info: CoreCommandRouter::initPlayerControls Sep 02 15:44:03 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:03 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:03 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:03 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:03 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:03 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:03 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:03 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:03 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 15:44:03 volumio volumio[14925]: Express server listening on port 3000 Sep 02 15:44:03 volumio volumio[14925]: [Metrics] WebUI: 10s 982.29ms Sep 02 15:44:03 volumio volumio[14925]: info: CoreStateMachine::resetVolumioState Sep 02 15:44:03 volumio volumio[14925]: info: CoreStateMachine::getcurrentVolume Sep 02 15:44:03 volumio volumio[14925]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 15:44:03 volumio volumio[14925]: Starting albumart workers Sep 02 15:44:03 volumio volumio[14925]: Starting albumart workers Sep 02 15:44:03 volumio volumio[14925]: Starting albumart workers Sep 02 15:44:03 volumio volumio[14925]: info: Volumio Network Manager: Network status updated: 1 Sep 02 15:44:04 volumio volumio[14925]: info: CoreCommandRouter::volumioGetState Sep 02 15:44:04 volumio volumio[14925]: info: CorePlayQueue::getTrack 0 Sep 02 15:44:04 volumio volumio[14925]: info: CoreCommandRouter::volumioGetState Sep 02 15:44:04 volumio volumio[14925]: info: CorePlayQueue::getTrack 0 Sep 02 15:44:04 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 15:44:04 volumio volumio[14925]: info: VolumeController:: Volume=37 Mute =false Sep 02 15:44:04 volumio volumio[14925]: info: CoreStateMachine::pushState Sep 02 15:44:04 volumio volumio[14925]: info: CorePlayQueue::getTrack 0 Sep 02 15:44:04 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 15:44:04 volumio volumio[14925]: info: CoreCommandRouter::volumioPushState Sep 02 15:44:04 volumio volumio[14925]: info: CoreStateMachine::updateTrackBlock Sep 02 15:44:04 volumio volumio[14925]: info: CorePlayQueue::getTrackBlock Sep 02 15:44:04 volumio volumio[14925]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 15:44:04 volumio volumio[14925]: info: CoreStateMachine::setRepeat false single undefined Sep 02 15:44:04 volumio volumio[14925]: info: CoreStateMachine::pushState Sep 02 15:44:04 volumio volumio[14925]: info: CorePlayQueue::getTrack 0 Sep 02 15:44:04 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 15:44:04 volumio volumio[14925]: info: CoreCommandRouter::volumioPushState Sep 02 15:44:04 volumio volumio[14925]: info: CoreStateMachine::setRandom true Sep 02 15:44:04 volumio volumio[14925]: info: CoreStateMachine::pushState Sep 02 15:44:04 volumio volumio[14925]: info: CorePlayQueue::getTrack 0 Sep 02 15:44:04 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 15:44:04 volumio volumio[14925]: info: CoreCommandRouter::volumioPushState Sep 02 15:44:04 volumio volumio[14925]: info: Setting Device type: Raspberry PI Sep 02 15:44:04 volumio volumio[14925]: info: Listing playlists Sep 02 15:44:04 volumio volumio[14925]: info: Listing playlists Sep 02 15:44:04 volumio volumio[14925]: info: Listing playlists Sep 02 15:44:04 volumio volumio[14925]: info: Listing playlists Sep 02 15:44:04 volumio volumio[14925]: info: Completed loading Core Plugins Sep 02 15:44:04 volumio volumio[14925]: info: Preparing to generate the ALSA configuration file Sep 02 15:44:04 volumio volumio[14925]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227 Sep 02 15:44:04 volumio volumio[14925]: info: Discovery: Found device Volumio Sep 02 15:44:04 volumio volumio[14925]: info: CoreCommandRouter::volumioGetState Sep 02 15:44:04 volumio volumio[14925]: info: CorePlayQueue::getTrack 0 Sep 02 15:44:04 volumio volumio[14925]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 02 15:44:04 volumio volumio[14925]: info: Reading ALSA contributions from plugins. Sep 02 15:44:04 volumio volumio[14925]: info: VolumeController:: Volume=37 Mute =false Sep 02 15:44:04 volumio volumio[14925]: info: CoreStateMachine::pushState Sep 02 15:44:04 volumio volumio[14925]: info: CorePlayQueue::getTrack 0 Sep 02 15:44:04 volumio volumio[14925]: info: CoreCommandRouter::volumioPushState Sep 02 15:44:04 volumio volumio[14925]: info: Asound.conf file unchanged, so no further update is needed Sep 02 15:44:04 volumio volumio[14925]: info: Output device has changed, restarting MPD Sep 02 15:44:04 volumio sudo[15049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 15:44:04 volumio sudo[15049]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:04 volumio volumio[14925]: info: ___________ START PLUGINS ___________ Sep 02 15:44:04 volumio sudo[15049]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:04 volumio volumio[14925]: info: ControllerMpd::onStart: Initializing MPD Sep 02 15:44:04 volumio volumio[14925]: info: Creating MPD Configuration file Sep 02 15:44:04 volumio sudo[15072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 15:44:04 volumio sudo[15072]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:04 volumio sudo[15074]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 15:44:04 volumio sudo[15074]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:04 volumio sudo[15074]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:04 volumio systemd[1]: Stopping Music Player Daemon... Sep 02 15:44:04 volumio volumio[14925]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 15:44:04 volumio volumio[14925]: info: [1756820644814] CoreMusicLibrary::Adding element Last_100 Sep 02 15:44:04 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 15:44:04 volumio volumio[14925]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 15:44:04 volumio volumio[14925]: info: [1756820644820] CoreMusicLibrary::Adding element Webradio Sep 02 15:44:04 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 15:44:04 volumio sudo[15078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 15:44:04 volumio sudo[15078]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:04 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 15:44:04 volumio volumio[14925]: info: Initializing BBC Radios Sep 02 15:44:04 volumio systemd[1]: mpd.service: Succeeded. Sep 02 15:44:04 volumio systemd[1]: Stopped Music Player Daemon. Sep 02 15:44:04 volumio systemd[1]: Starting Music Player Daemon... Sep 02 15:44:04 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 02 15:44:04 volumio systemd[1]: mpd.service: Succeeded. Sep 02 15:44:04 volumio systemd[1]: Stopped Music Player Daemon. Sep 02 15:44:04 volumio systemd[1]: Starting Music Player Daemon... Sep 02 15:44:04 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 15:44:04 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 15:44:04 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 15:44:04 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 02 15:44:04 volumio volumio[14925]: info: Discovery: Getting this device information Sep 02 15:44:04 volumio volumio[14925]: info: CoreCommandRouter::volumioGetState Sep 02 15:44:04 volumio volumio[14925]: info: CorePlayQueue::getTrack 0 Sep 02 15:44:04 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 02 15:44:04 volumio volumio[14925]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 15:44:04 volumio volumio[14925]: info: [1756820644957] CoreMusicLibrary::Adding element Jellyfin Sep 02 15:44:04 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 15:44:04 volumio volumio[14925]: Cannot find translation for source Jellyfin Sep 02 15:44:04 volumio volumio[14925]: 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 02 15:44:04 volumio volumio[14925]: info: Creating Spotify config file Sep 02 15:44:04 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:05 volumio sudo[15090]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 02 15:44:05 volumio sudo[15090]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:05 volumio sudo[15090]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:05 volumio volumio[14925]: info: Loading i18n strings for locale hu Sep 02 15:44:05 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 02 15:44:05 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:05 volumio volumio[14925]: info: FusionDsp - mixtype--------------------- Hardware Sep 02 15:44:05 volumio volumio[14925]: info: Volumio Calling Home Sep 02 15:44:05 volumio volumio[14925]: info: Preparing to generate the ALSA configuration file Sep 02 15:44:05 volumio volumio[14925]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 02 15:44:05 volumio volumio[14925]: info: Reading ALSA contributions from plugins. Sep 02 15:44:05 volumio volumio[14925]: info: MPD Permissions set Sep 02 15:44:05 volumio volumio[14925]: info: MPD Permissions set Sep 02 15:44:05 volumio volumio[14925]: info: Volumio called home Sep 02 15:44:05 volumio sudo[15112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 02 15:44:05 volumio sudo[15112]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:05 volumio sudo[15112]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:05 volumio volumio[14925]: info: Spotify config file written Sep 02 15:44:05 volumio sudo[15115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 02 15:44:05 volumio sudo[15115]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:05 volumio sudo[15115]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:05 volumio sudo[15118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 02 15:44:05 volumio sudo[15118]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:05 volumio systemd[1]: Stopping go-librespot Daemon... Sep 02 15:44:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 02 15:44:05 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 02 15:44:05 volumio systemd[1]: Stopped go-librespot Daemon. Sep 02 15:44:05 volumio systemd[1]: Started go-librespot Daemon. Sep 02 15:44:05 volumio go-librespot[15120]: go-librespot daemon starting... Sep 02 15:44:05 volumio sudo[15118]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:05 volumio go-librespot[15120]: time="2025-09-02T15:44:05+02:00" level=info msg="running go-librespot 0.3.2" Sep 02 15:44:05 volumio go-librespot[15120]: time="2025-09-02T15:44:05+02:00" level=debug msg="app state loaded" Sep 02 15:44:05 volumio go-librespot[15120]: time="2025-09-02T15:44:05+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 02 15:44:05 volumio volumio[14925]: 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 02 15:44:05 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:05 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:05 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 15:44:05 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:05 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:05 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:05 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:05 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:05 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:05 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:05 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:05 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:05 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 15:44:05 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:05 volumio volumio[14925]: info: No need to fix Spotify hosts Sep 02 15:44:06 volumio go-librespot[15120]: time="2025-09-02T15:44:06+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 02 15:44:06 volumio go-librespot[15120]: time="2025-09-02T15:44:06+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 02 15:44:06 volumio go-librespot[15120]: time="2025-09-02T15:44:06+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 02 15:44:06 volumio go-librespot[15120]: time="2025-09-02T15:44:06+02:00" level=info msg="zeroconf server listening on port 33327" Sep 02 15:44:06 volumio go-librespot[15120]: time="2025-09-02T15:44:06+02:00" level=debug msg="obtained new client token: AACiTZdoQ0JCoR/33m07bQOqRr4WtG1mJU8kfYqsi/kpx/IKI4Gr8inYRJIY/5F8L0ZAcbS+n2HNwTlJu+0bhEjWD8NLhjaENkfPDgoWhcr5Ovr8XYp52S0Xa4qxRvvjRIYyKqvJRcYrQ6cNq2+d5L2FjhKMT/wM53XAkHrkhuCnY7n5l1qiNJFQ2c+Lsav9iOb42zDFmd6YD1ztfbH+JQ+taUYqwfZ4y3LTNjL4Y5wGf+3LPkhM5Yc/" Sep 02 15:44:06 volumio go-librespot[15120]: time="2025-09-02T15:44:06+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 02 15:44:06 volumio volumio[14925]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 02 15:44:06 volumio volumio[14925]: SPOTIFY: BQDppRUyKK_Uyd9aWYosryf80EMg6ut1M6Y5ZA2S0vkM9rQy4iBeJaaIPCLz_WhIB9aOb1Dfy4DvWOf2FRoRjw7iWKet3QE11NFV8ZsoL_j5Nc5e24QCXzXQ4rU5Hm-14x4RXioei-j3KyFflXVmZaB87wiwC-1sUJ1ulasTmqn9mssXOhUWJgW_Y4crV3wKxK5MCJJs4GLdsxEtUBdiYhM_Zn1pGZhiJ2XJ8HCZGtLlpwqBzTTy1B4yiOR-qGOB94_BmPuaZWx35F6cZcyFbJZm4La0pBnWlIdrEubZ8nV8gJM Sep 02 15:44:06 volumio volumio[14925]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 02 15:44:06 volumio volumio[14925]: info: New Spotify access token = BQDppRUyKK_Uyd9aWYosryf80EMg6ut1M6Y5ZA2S0vkM9rQy4iBeJaaIPCLz_WhIB9aOb1Dfy4DvWOf2FRoRjw7iWKet3QE11NFV8ZsoL_j5Nc5e24QCXzXQ4rU5Hm-14x4RXioei-j3KyFflXVmZaB87wiwC-1sUJ1ulasTmqn9mssXOhUWJgW_Y4crV3wKxK5MCJJs4GLdsxEtUBdiYhM_Zn1pGZhiJ2XJ8HCZGtLlpwqBzTTy1B4yiOR-qGOB94_BmPuaZWx35F6cZcyFbJZm4La0pBnWlIdrEubZ8nV8gJM Sep 02 15:44:06 volumio volumio[14925]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 02 15:44:06 volumio go-librespot[15120]: time="2025-09-02T15:44:06+02:00" level=debug msg="completed keyexchange" Sep 02 15:44:06 volumio go-librespot[15120]: time="2025-09-02T15:44:06+02:00" level=debug msg="completed challenge" Sep 02 15:44:06 volumio volumio[14925]: info: CoreCommandRouter::volumioGetState Sep 02 15:44:06 volumio volumio[14925]: info: CorePlayQueue::getTrack 0 Sep 02 15:44:06 volumio volumio[14925]: info: Asound.conf file unchanged, so no further update is needed Sep 02 15:44:06 volumio volumio[14925]: info: Output device has changed, restarting MPD Sep 02 15:44:06 volumio go-librespot[15120]: time="2025-09-02T15:44:06+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 02 15:44:06 volumio sudo[15138]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 15:44:06 volumio sudo[15138]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:06 volumio sudo[15138]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:06 volumio sudo[15140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 15:44:06 volumio sudo[15140]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:06 volumio sudo[15144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 02 15:44:06 volumio sudo[15144]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:06 volumio go-librespot[15120]: time="2025-09-02T15:44:06+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 02 15:44:06 volumio go-librespot[15120]: time="2025-09-02T15:44:06+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 02 15:44:06 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 02 15:44:06 volumio systemd[1]: mpd.service: Succeeded. Sep 02 15:44:06 volumio systemd[1]: Stopped Music Player Daemon. Sep 02 15:44:06 volumio go-librespot[15120]: time="2025-09-02T15:44:06+02:00" level=debug msg="dealer connection opened" Sep 02 15:44:06 volumio go-librespot[15120]: time="2025-09-02T15:44:06+02:00" level=trace msg="starting accesspoint recv loop" Sep 02 15:44:06 volumio go-librespot[15120]: time="2025-09-02T15:44:06+02:00" level=trace msg="starting dealer recv loop" Sep 02 15:44:06 volumio go-librespot[15120]: time="2025-09-02T15:44:06+02:00" level=trace msg="received accesspoint ping" Sep 02 15:44:06 volumio go-librespot[15120]: time="2025-09-02T15:44:06+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 02 15:44:06 volumio go-librespot[15120]: time="2025-09-02T15:44:06+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 02 15:44:06 volumio go-librespot[15120]: time="2025-09-02T15:44:06+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 02 15:44:06 volumio go-librespot[15120]: time="2025-09-02T15:44:06+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 02 15:44:06 volumio go-librespot[15120]: time="2025-09-02T15:44:06+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 02 15:44:06 volumio go-librespot[15120]: time="2025-09-02T15:44:06+02:00" level=debug msg="received connection id: ZTMzZmYzNzUtZWIx...MzQ3MjZCNDNDRQ==" Sep 02 15:44:06 volumio volumio[14925]: info: MPD Permissions set Sep 02 15:44:06 volumio go-librespot[15120]: time="2025-09-02T15:44:06+02:00" level=trace msg="received accesspoint pong ack" Sep 02 15:44:06 volumio systemd[1]: Starting Music Player Daemon... Sep 02 15:44:06 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:06 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:06 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:06 volumio sudo[15144]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:06 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 15:44:06 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:06 volumio go-librespot[15120]: time="2025-09-02T15:44:06+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 02 15:44:06 volumio volumio[14925]: info: Upmpdcli Daemon Started Sep 02 15:44:06 volumio volumio[14925]: 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 02 15:44:06 volumio volumio[14925]: info: Spotify Successfully logged in Sep 02 15:44:06 volumio volumio[14925]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 15:44:06 volumio volumio[14925]: info: [1756820646576] CoreMusicLibrary::Adding element Spotify Sep 02 15:44:06 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 15:44:06 volumio volumio[14925]: Cannot find translation for source Jellyfin Sep 02 15:44:06 volumio volumio[14925]: Cannot find translation for source Spotify Sep 02 15:44:06 volumio sudo[15146]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 02 15:44:06 volumio sudo[15146]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:06 volumio sudo[15146]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:06 volumio volumio[14925]: info: CoreCommandRouter::volumioGetState Sep 02 15:44:06 volumio volumio[14925]: info: CorePlayQueue::getTrack 0 Sep 02 15:44:07 volumio volumio[14925]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 02 15:44:07 volumio volumio[14925]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 15:44:07 volumio volumio[14925]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 02 15:44:07 volumio volumio[14925]: 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 02 15:44:07 volumio volumio[14925]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 02 15:44:07 volumio volumio[14925]: info: camilladsp service started and running in background, instance 1 Sep 02 15:44:07 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:07 volumio volumio[14925]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 02 15:44:07 volumio volumio[14925]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 02 15:44:07 volumio volumio[14925]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 02 15:44:07 volumio volumio[14925]: info: FusionDsp loaded Sep 02 15:44:07 volumio volumio[14925]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 02 15:44:07 volumio sudo[15166]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 02 15:44:07 volumio sudo[15166]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:07 volumio sudo[15166]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:07 volumio volumio[14925]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 02 15:44:07 volumio volumio[14925]: info: Adding Signal Path Element [object Object] Sep 02 15:44:07 volumio volumio[14925]: info: Adding fusiondspeq DSP Signal Path Element Sep 02 15:44:07 volumio volumio[14925]: info: FusionDsp - ---- installed callbackRead Sep 02 15:44:07 volumio volumio[14925]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 15:44:07 volumio volumio[14925]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 02 15:44:07 volumio volumio[14925]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 02 15:44:07 volumio volumio[14925]: at onErrorNT (internal/child_process.js:465:16) Sep 02 15:44:07 volumio volumio[14925]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Sep 02 15:44:07 volumio volumio[14925]: errno: -2, Sep 02 15:44:07 volumio volumio[14925]: code: 'ENOENT', Sep 02 15:44:07 volumio volumio[14925]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 02 15:44:07 volumio volumio[14925]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 02 15:44:07 volumio volumio[14925]: spawnargs: [ Sep 02 15:44:07 volumio volumio[14925]: '-p', Sep 02 15:44:07 volumio volumio[14925]: 9876, Sep 02 15:44:07 volumio volumio[14925]: '-o', Sep 02 15:44:07 volumio volumio[14925]: '/tmp/camilladsp.log', Sep 02 15:44:07 volumio volumio[14925]: '-l', Sep 02 15:44:07 volumio volumio[14925]: 'warn', Sep 02 15:44:07 volumio volumio[14925]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 02 15:44:07 volumio volumio[14925]: ] Sep 02 15:44:07 volumio volumio[14925]: } Sep 02 15:44:07 volumio volumio[14925]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 15:44:08 volumio sudo[15177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-02 15:43 Sep 02 15:44:08 volumio sudo[15177]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:08 volumio sudo[15177]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:08 volumio mpd[15153]: Sep 02 15:44 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 02 15:44:08 volumio systemd[1]: Started Music Player Daemon. Sep 02 15:44:08 volumio sudo[15140]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:08 volumio sudo[15078]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:08 volumio sudo[15072]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:08 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 02 15:44:08 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 02 15:44:08 volumio systemd[1]: Started dynamicswap service. Sep 02 15:44:08 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 02 15:44:08 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3793. Sep 02 15:44:08 volumio systemd[1]: Stopped Volumio Backend Module. Sep 02 15:44:08 volumio systemd[1]: Started Volumio Backend Module. Sep 02 15:44:08 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 02 15:44:09 volumio volumio-remote-updater[504]: [2025-09-02 15:44:09] [info] asio async_connect error: system:111 (Connection refused) Sep 02 15:44:09 volumio volumio-remote-updater[504]: [2025-09-02 15:44:09] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 02 15:44:09 volumio volumio-remote-updater[504]: [2025-09-02 15:44:09] [error] handle_connect error: Underlying Transport Error Sep 02 15:44:10 volumio volumio[15184]: info: ------------------------------------------- Sep 02 15:44:10 volumio volumio[15184]: info: ----- Volumio3 ---- Sep 02 15:44:10 volumio volumio[15184]: info: ------------------------------------------- Sep 02 15:44:10 volumio volumio[15184]: info: ----- System startup ---- Sep 02 15:44:10 volumio volumio[15184]: info: ------------------------------------------- Sep 02 15:44:10 volumio volumio[15184]: info: MYVOLUMIO Environment detected Sep 02 15:44:10 volumio volumio[15184]: info: Plugin folders cleanup Sep 02 15:44:10 volumio volumio[15184]: info: Scanning into folder /volumio/app/plugins/ Sep 02 15:44:10 volumio volumio[15184]: info: Scanning category audio_interface Sep 02 15:44:10 volumio volumio[15184]: info: Scanning category miscellanea Sep 02 15:44:10 volumio volumio[15184]: info: Scanning category music_service Sep 02 15:44:10 volumio volumio[15184]: info: Scanning category plugins.json Sep 02 15:44:10 volumio volumio[15184]: info: Scanning category system_controller Sep 02 15:44:10 volumio volumio[15184]: info: Scanning category user_interface Sep 02 15:44:10 volumio volumio[15184]: info: Scanning into folder /data/plugins/ Sep 02 15:44:10 volumio volumio[15184]: info: Scanning category audio_interface Sep 02 15:44:10 volumio volumio[15184]: info: Scanning category music_service Sep 02 15:44:10 volumio volumio[15184]: info: Scanning category user_interface Sep 02 15:44:10 volumio volumio[15184]: info: Plugin folders cleanup completed Sep 02 15:44:10 volumio volumio[15184]: info: ------------------------------------------- Sep 02 15:44:10 volumio volumio[15184]: info: ----- Core plugins startup ---- Sep 02 15:44:10 volumio volumio[15184]: info: ------------------------------------------- Sep 02 15:44:10 volumio volumio[15184]: info: Loading plugins from folder /volumio/app/plugins/ Sep 02 15:44:10 volumio volumio[15184]: info: Adding plugin upnp to MyMusic Plugins Sep 02 15:44:10 volumio volumio[15184]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 02 15:44:10 volumio volumio[15184]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 02 15:44:10 volumio volumio[15184]: info: Loading plugins from folder /data/plugins/ Sep 02 15:44:10 volumio volumio[15184]: info: Loading plugin "system"... Sep 02 15:44:10 volumio volumio[15184]: info: Loading plugin "appearance"... Sep 02 15:44:11 volumio volumio[15184]: info: Loading plugin "network"... Sep 02 15:44:11 volumio volumio[15184]: info: Refreshing Cached IP Addresses Sep 02 15:44:12 volumio sudo[15210]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 02 15:44:12 volumio sudo[15210]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:12 volumio sudo[15210]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:12 volumio sudo[15212]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 02 15:44:12 volumio volumio[15184]: info: Loading plugin "services"... Sep 02 15:44:12 volumio sudo[15212]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:12 volumio volumio[15184]: info: Loading plugin "alsa_controller"... Sep 02 15:44:12 volumio sudo[15212]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:12 volumio volumio[15184]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 15:44:12 volumio volumio[15184]: info: Loading plugin "wizard"... Sep 02 15:44:12 volumio volumio[15184]: info: Loading plugin "networkfs"... Sep 02 15:44:12 volumio volumio[15184]: info: Starting Udev Watcher for removable devices Sep 02 15:44:12 volumio volumio[15184]: info: Ignoring mount for partition: boot Sep 02 15:44:12 volumio volumio[15184]: info: Ignoring mount for partition: volumio Sep 02 15:44:12 volumio volumio[15184]: info: Ignoring mount for partition: volumio_data Sep 02 15:44:12 volumio volumio[15184]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 15:44:12 volumio volumio[15184]: info: Loading plugin "volumio_command_line_client"... Sep 02 15:44:12 volumio volumio[15184]: info: Loading plugin "upnp"... Sep 02 15:44:12 volumio volumio[15184]: info: [1756820652234] Starting Upmpd Daemon Sep 02 15:44:12 volumio volumio[15184]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 15:44:12 volumio volumio[15184]: info: Loading plugin "my_music"... Sep 02 15:44:12 volumio volumio[15184]: info: Loading plugin "mpd"... Sep 02 15:44:12 volumio volumio[15184]: info: Plugin upnp_browser is not enabled Sep 02 15:44:12 volumio volumio[15184]: info: Loading plugin "alarm-clock"... Sep 02 15:44:12 volumio volumio[15184]: info: Plugin airplay_emulation is not enabled Sep 02 15:44:12 volumio volumio[15184]: info: Loading plugin "last_100"... Sep 02 15:44:12 volumio volumio[15184]: info: Loading plugin "webradio"... Sep 02 15:44:12 volumio volumio[15184]: info: Loading plugin "i2s_dacs"... Sep 02 15:44:12 volumio volumio[15184]: info: Loading plugin "volumiodiscovery"... Sep 02 15:44:12 volumio volumio[15184]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 02 15:44:12 volumio volumio[15184]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 15:44:12 volumio volumio[15184]: *** WARNING *** For more information see Sep 02 15:44:12 volumio node[15184]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 02 15:44:12 volumio volumio[15184]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 02 15:44:12 volumio volumio[15184]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 15:44:12 volumio volumio[15184]: *** WARNING *** For more information see Sep 02 15:44:12 volumio node[15184]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 15:44:12 volumio node[15184]: *** WARNING *** For more information see Sep 02 15:44:12 volumio node[15184]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 02 15:44:12 volumio node[15184]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 15:44:12 volumio node[15184]: *** WARNING *** For more information see Sep 02 15:44:12 volumio volumio[15184]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 02 15:44:12 volumio volumio[15184]: info: Discovery: Started advertising with name: Volumio Sep 02 15:44:12 volumio volumio[15184]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 15:44:12 volumio volumio[15184]: info: Loading plugin "jellyfin"... Sep 02 15:44:13 volumio volumio[15184]: info: Loading plugin "spop"... Sep 02 15:44:14 volumio volumio[15184]: info: Loading plugin "ytcr"... Sep 02 15:44:19 volumio volumio-remote-updater[504]: [2025-09-02 15:44:19] [connect] Successful connection Sep 02 15:44:19 volumio volumio[15184]: info: Loading plugin "outputs"... Sep 02 15:44:19 volumio volumio[15184]: info: Loading plugin "albumart"... Sep 02 15:44:19 volumio volumio[15184]: info: Plugin example_plugin is not enabled Sep 02 15:44:19 volumio volumio[15184]: info: Loading plugin "inputs"... Sep 02 15:44:19 volumio volumio[15184]: info: Loading plugin "updater_comm"... Sep 02 15:44:19 volumio volumio[15184]: info: Plugin mpdemulation is not enabled Sep 02 15:44:19 volumio volumio[15184]: info: Loading plugin "rest_api"... Sep 02 15:44:19 volumio volumio[15184]: info: Loading plugin "websocket"... Sep 02 15:44:19 volumio volumio[15184]: info: Starting Socket.io Server version 2.3.0 Sep 02 15:44:19 volumio volumio[15184]: info: Loading plugin "fusiondsp"... Sep 02 15:44:19 volumio volumio[15184]: Forking 3 albumart workers Sep 02 15:44:19 volumio volumio[15184]: info: Applying required configuration parameters for plugin fusiondsp Sep 02 15:44:19 volumio volumio[15184]: info: Loading plugin "Systeminfo"... Sep 02 15:44:20 volumio volumio[15184]: info: Loading i18n strings for locale hu Sep 02 15:44:20 volumio volumio[15184]: Updating browse sources language Sep 02 15:44:20 volumio volumio[15184]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 15:44:20 volumio volumio[15184]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 15:44:20 volumio volumio[15184]: info: CoreCommandRouter::initPlayerControls Sep 02 15:44:20 volumio volumio[15184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:20 volumio volumio[15184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:20 volumio volumio[15184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:20 volumio volumio[15184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:20 volumio volumio[15184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:20 volumio volumio[15184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:20 volumio volumio[15184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:20 volumio volumio[15184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:20 volumio volumio[15184]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 15:44:20 volumio volumio[15184]: Express server listening on port 3000 Sep 02 15:44:20 volumio volumio[15184]: [Metrics] WebUI: 10s 982.22ms Sep 02 15:44:20 volumio volumio[15184]: info: CoreStateMachine::resetVolumioState Sep 02 15:44:20 volumio volumio[15184]: info: CoreStateMachine::getcurrentVolume Sep 02 15:44:20 volumio volumio[15184]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 15:44:20 volumio volumio[15184]: Starting albumart workers Sep 02 15:44:20 volumio volumio[15184]: info: Volumio Network Manager: Network status updated: 1 Sep 02 15:44:20 volumio volumio[15184]: Starting albumart workers Sep 02 15:44:20 volumio volumio[15184]: Starting albumart workers Sep 02 15:44:20 volumio volumio-remote-updater[504]: [2025-09-02 15:44:20] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1756820659 101 Sep 02 15:44:20 volumio volumio[15184]: 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 02 15:44:20 volumio volumio[15184]: info: CoreCommandRouter::volumioGetState Sep 02 15:44:20 volumio volumio[15184]: info: CorePlayQueue::getTrack 0 Sep 02 15:44:20 volumio volumio[15184]: info: CoreCommandRouter::volumioGetState Sep 02 15:44:20 volumio volumio[15184]: info: CorePlayQueue::getTrack 0 Sep 02 15:44:20 volumio volumio[15184]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 15:44:21 volumio volumio[15184]: info: VolumeController:: Volume=37 Mute =false Sep 02 15:44:21 volumio volumio[15184]: info: CoreStateMachine::pushState Sep 02 15:44:21 volumio volumio[15184]: info: CorePlayQueue::getTrack 0 Sep 02 15:44:21 volumio volumio[15184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 15:44:21 volumio volumio[15184]: info: CoreCommandRouter::volumioPushState Sep 02 15:44:21 volumio volumio[15184]: info: CoreStateMachine::updateTrackBlock Sep 02 15:44:21 volumio volumio[15184]: info: CorePlayQueue::getTrackBlock Sep 02 15:44:21 volumio volumio[15184]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 15:44:21 volumio volumio[15184]: info: CoreStateMachine::setRepeat false single undefined Sep 02 15:44:21 volumio volumio[15184]: info: CoreStateMachine::pushState Sep 02 15:44:21 volumio volumio[15184]: info: CorePlayQueue::getTrack 0 Sep 02 15:44:21 volumio volumio[15184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 15:44:21 volumio volumio[15184]: info: CoreCommandRouter::volumioPushState Sep 02 15:44:21 volumio volumio[15184]: info: CoreStateMachine::setRandom true Sep 02 15:44:21 volumio volumio[15184]: info: CoreStateMachine::pushState Sep 02 15:44:21 volumio volumio[15184]: info: CorePlayQueue::getTrack 0 Sep 02 15:44:21 volumio volumio[15184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 15:44:21 volumio volumio[15184]: info: CoreCommandRouter::volumioPushState Sep 02 15:44:21 volumio volumio[15184]: info: Setting Device type: Raspberry PI Sep 02 15:44:21 volumio volumio[15184]: info: Listing playlists Sep 02 15:44:21 volumio volumio[15184]: info: Listing playlists Sep 02 15:44:21 volumio volumio[15184]: info: Listing playlists Sep 02 15:44:21 volumio volumio[15184]: info: Listing playlists Sep 02 15:44:21 volumio volumio[15184]: info: Completed loading Core Plugins Sep 02 15:44:21 volumio volumio[15184]: info: Preparing to generate the ALSA configuration file Sep 02 15:44:21 volumio volumio[15184]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227 Sep 02 15:44:21 volumio volumio[15184]: info: Discovery: Found device Volumio Sep 02 15:44:21 volumio volumio[15184]: info: CoreCommandRouter::volumioGetState Sep 02 15:44:21 volumio volumio[15184]: info: CorePlayQueue::getTrack 0 Sep 02 15:44:21 volumio volumio[15184]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 02 15:44:21 volumio volumio[15184]: info: Reading ALSA contributions from plugins. Sep 02 15:44:21 volumio volumio[15184]: info: VolumeController:: Volume=37 Mute =false Sep 02 15:44:21 volumio volumio[15184]: info: CoreStateMachine::pushState Sep 02 15:44:21 volumio volumio[15184]: info: CorePlayQueue::getTrack 0 Sep 02 15:44:21 volumio volumio[15184]: info: CoreCommandRouter::volumioPushState Sep 02 15:44:21 volumio volumio[15184]: info: Asound.conf file unchanged, so no further update is needed Sep 02 15:44:21 volumio volumio[15184]: info: Output device has changed, restarting MPD Sep 02 15:44:21 volumio sudo[15331]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 15:44:21 volumio sudo[15331]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:21 volumio volumio[15184]: info: ___________ START PLUGINS ___________ Sep 02 15:44:21 volumio sudo[15331]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:21 volumio volumio[15184]: info: ControllerMpd::onStart: Initializing MPD Sep 02 15:44:21 volumio volumio[15184]: info: Creating MPD Configuration file Sep 02 15:44:21 volumio sudo[15334]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 15:44:21 volumio sudo[15334]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:21 volumio volumio[15184]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 15:44:21 volumio volumio[15184]: info: [1756820661462] CoreMusicLibrary::Adding element Last_100 Sep 02 15:44:21 volumio volumio[15184]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 15:44:21 volumio volumio[15184]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 15:44:21 volumio systemd[1]: Stopping Music Player Daemon... Sep 02 15:44:21 volumio volumio[15184]: info: [1756820661471] CoreMusicLibrary::Adding element Webradio Sep 02 15:44:21 volumio volumio[15184]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 15:44:21 volumio sudo[15350]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 15:44:21 volumio volumio[15184]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 15:44:21 volumio sudo[15350]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:21 volumio volumio[15184]: info: Initializing BBC Radios Sep 02 15:44:21 volumio sudo[15350]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:21 volumio systemd[1]: mpd.service: Succeeded. Sep 02 15:44:21 volumio sudo[15355]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 15:44:21 volumio sudo[15355]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:21 volumio systemd[1]: Stopped Music Player Daemon. Sep 02 15:44:21 volumio systemd[1]: Starting Music Player Daemon... Sep 02 15:44:21 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 02 15:44:21 volumio systemd[1]: mpd.service: Succeeded. Sep 02 15:44:21 volumio volumio[15184]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 15:44:21 volumio systemd[1]: Stopped Music Player Daemon. Sep 02 15:44:21 volumio volumio[15184]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 15:44:21 volumio systemd[1]: Starting Music Player Daemon... Sep 02 15:44:21 volumio volumio[15184]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 15:44:21 volumio volumio[15184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 02 15:44:21 volumio volumio[15184]: info: Discovery: Getting this device information Sep 02 15:44:21 volumio volumio[15184]: info: CoreCommandRouter::volumioGetState Sep 02 15:44:21 volumio volumio[15184]: info: CorePlayQueue::getTrack 0 Sep 02 15:44:21 volumio volumio[15184]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 02 15:44:21 volumio volumio[15184]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 15:44:21 volumio volumio[15184]: info: [1756820661652] CoreMusicLibrary::Adding element Jellyfin Sep 02 15:44:21 volumio volumio[15184]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 15:44:21 volumio volumio[15184]: Cannot find translation for source Jellyfin Sep 02 15:44:21 volumio volumio[15184]: 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 02 15:44:21 volumio volumio[15184]: info: Creating Spotify config file Sep 02 15:44:21 volumio volumio[15184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:21 volumio sudo[15377]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 02 15:44:21 volumio sudo[15377]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:21 volumio sudo[15377]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:21 volumio volumio[15184]: info: Loading i18n strings for locale hu Sep 02 15:44:21 volumio volumio[15184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 02 15:44:21 volumio volumio[15184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:21 volumio volumio[15184]: info: FusionDsp - mixtype--------------------- Hardware Sep 02 15:44:21 volumio volumio[15184]: info: Volumio Calling Home Sep 02 15:44:21 volumio volumio[15184]: info: Preparing to generate the ALSA configuration file Sep 02 15:44:22 volumio volumio[15184]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 02 15:44:22 volumio volumio[15184]: info: Reading ALSA contributions from plugins. Sep 02 15:44:22 volumio volumio[15184]: info: MPD Permissions set Sep 02 15:44:22 volumio volumio[15184]: info: Spotify config file written Sep 02 15:44:22 volumio sudo[15397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 02 15:44:22 volumio volumio[15184]: 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 02 15:44:22 volumio sudo[15397]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:22 volumio volumio[15184]: info: Volumio called home Sep 02 15:44:22 volumio volumio[15184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:22 volumio volumio[15184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:22 volumio volumio[15184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:22 volumio systemd[1]: Stopping go-librespot Daemon... Sep 02 15:44:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 02 15:44:22 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 02 15:44:22 volumio systemd[1]: Stopped go-librespot Daemon. Sep 02 15:44:22 volumio volumio[15184]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 15:44:22 volumio volumio[15184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:22 volumio volumio[15184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:22 volumio volumio[15184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:22 volumio volumio[15184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:22 volumio volumio[15184]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 15:44:22 volumio volumio[15184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:22 volumio volumio[15184]: info: No need to fix Spotify hosts Sep 02 15:44:22 volumio systemd[1]: Started go-librespot Daemon. Sep 02 15:44:22 volumio go-librespot[15404]: go-librespot daemon starting... Sep 02 15:44:22 volumio sudo[15397]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:22 volumio go-librespot[15404]: time="2025-09-02T15:44:22+02:00" level=info msg="running go-librespot 0.3.2" Sep 02 15:44:22 volumio go-librespot[15404]: time="2025-09-02T15:44:22+02:00" level=debug msg="app state loaded" Sep 02 15:44:22 volumio go-librespot[15404]: time="2025-09-02T15:44:22+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 02 15:44:22 volumio sudo[15429]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 02 15:44:22 volumio sudo[15429]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:22 volumio sudo[15429]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:22 volumio sudo[15432]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 02 15:44:22 volumio sudo[15432]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:22 volumio sudo[15432]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:22 volumio go-librespot[15404]: time="2025-09-02T15:44:22+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 02 15:44:22 volumio go-librespot[15404]: time="2025-09-02T15:44:22+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 02 15:44:22 volumio go-librespot[15404]: time="2025-09-02T15:44:22+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 02 15:44:22 volumio go-librespot[15404]: time="2025-09-02T15:44:22+02:00" level=info msg="zeroconf server listening on port 39769" Sep 02 15:44:22 volumio go-librespot[15404]: time="2025-09-02T15:44:22+02:00" level=debug msg="obtained new client token: AACHdIEqtK5w7QM6w+zBSYo5ILJbugirEmj66Ypfo36b43eb6ClnqlrVZ8BDB3Tpi84JDHZ4pLIkQzFwEyenG6eGpVcVn14ABnN+hSDvg3AazA3DmXteDt7dYDWIbVUWGRJLynxHtQHosX7iO8xGcLMwrrMZOvMAbPwWldZpZK3DgoE3D4RhQCguIBdQjIWb1xMN6LZFJLZWgOt97vPYmAs8sf85FZ1dJyyQNBPdYMCAb9JSSWPpYMoP7/Y=" Sep 02 15:44:22 volumio go-librespot[15404]: time="2025-09-02T15:44:22+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 02 15:44:22 volumio go-librespot[15404]: time="2025-09-02T15:44:22+02:00" level=debug msg="completed keyexchange" Sep 02 15:44:22 volumio go-librespot[15404]: time="2025-09-02T15:44:22+02:00" level=debug msg="completed challenge" Sep 02 15:44:22 volumio go-librespot[15404]: time="2025-09-02T15:44:22+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 02 15:44:22 volumio volumio[15184]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 02 15:44:22 volumio volumio[15184]: SPOTIFY: BQA1KpO79HPa7BXyqLAftufWqPRVBgNgAbVJeN6hYexYWO6crWlc1vyaoTHZrRJ4WFpqXN6UStNyuxY1KCpVDShD98RNqczEtdUh9FuohHktuF4aSOMoh4lIl4rqaYc-IvAXYlJikMh9KQFAZuZnFekqCVaCSD5IgL8JsnDRfMUWDO-avJH3TBDLkYG-LqRywLiz-Bqj253AHbil63abTTkEmJnyWIdSWyw88XMBeR3CgbBTZpxKCRoJs18AUl86tUJobdeI7axy_SIqvwQ7zPT-Y2GDgx_YzQCtP1Hgq7dJ-po Sep 02 15:44:22 volumio volumio[15184]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 02 15:44:22 volumio volumio[15184]: info: New Spotify access token = BQA1KpO79HPa7BXyqLAftufWqPRVBgNgAbVJeN6hYexYWO6crWlc1vyaoTHZrRJ4WFpqXN6UStNyuxY1KCpVDShD98RNqczEtdUh9FuohHktuF4aSOMoh4lIl4rqaYc-IvAXYlJikMh9KQFAZuZnFekqCVaCSD5IgL8JsnDRfMUWDO-avJH3TBDLkYG-LqRywLiz-Bqj253AHbil63abTTkEmJnyWIdSWyw88XMBeR3CgbBTZpxKCRoJs18AUl86tUJobdeI7axy_SIqvwQ7zPT-Y2GDgx_YzQCtP1Hgq7dJ-po Sep 02 15:44:22 volumio volumio[15184]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 02 15:44:22 volumio volumio[15184]: info: CoreCommandRouter::volumioGetState Sep 02 15:44:22 volumio volumio[15184]: info: CorePlayQueue::getTrack 0 Sep 02 15:44:22 volumio go-librespot[15404]: time="2025-09-02T15:44:22+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 02 15:44:22 volumio go-librespot[15404]: time="2025-09-02T15:44:22+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 02 15:44:22 volumio volumio[15184]: info: Asound.conf file unchanged, so no further update is needed Sep 02 15:44:22 volumio volumio[15184]: info: Output device has changed, restarting MPD Sep 02 15:44:22 volumio go-librespot[15404]: time="2025-09-02T15:44:22+02:00" level=debug msg="dealer connection opened" Sep 02 15:44:22 volumio go-librespot[15404]: time="2025-09-02T15:44:22+02:00" level=trace msg="starting accesspoint recv loop" Sep 02 15:44:22 volumio go-librespot[15404]: time="2025-09-02T15:44:22+02:00" level=trace msg="starting dealer recv loop" Sep 02 15:44:22 volumio go-librespot[15404]: time="2025-09-02T15:44:22+02:00" level=trace msg="received accesspoint ping" Sep 02 15:44:22 volumio go-librespot[15404]: time="2025-09-02T15:44:22+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 02 15:44:22 volumio go-librespot[15404]: time="2025-09-02T15:44:22+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 02 15:44:22 volumio go-librespot[15404]: time="2025-09-02T15:44:22+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 02 15:44:22 volumio go-librespot[15404]: time="2025-09-02T15:44:22+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 02 15:44:22 volumio go-librespot[15404]: time="2025-09-02T15:44:22+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 02 15:44:22 volumio go-librespot[15404]: time="2025-09-02T15:44:22+02:00" level=debug msg="received connection id: NTgzZTM2NWUtNmU2...MTBDODQwMTM4MA==" Sep 02 15:44:23 volumio go-librespot[15404]: time="2025-09-02T15:44:23+02:00" level=trace msg="received accesspoint pong ack" Sep 02 15:44:23 volumio sudo[15435]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 15:44:23 volumio sudo[15435]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:23 volumio sudo[15437]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 15:44:23 volumio sudo[15435]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:23 volumio sudo[15437]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:23 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 02 15:44:23 volumio systemd[1]: mpd.service: Succeeded. Sep 02 15:44:23 volumio systemd[1]: Stopped Music Player Daemon. Sep 02 15:44:23 volumio go-librespot[15404]: time="2025-09-02T15:44:23+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 02 15:44:23 volumio volumio[15184]: info: MPD Permissions set Sep 02 15:44:23 volumio volumio[15184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:23 volumio systemd[1]: Starting Music Player Daemon... Sep 02 15:44:23 volumio sudo[15443]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 02 15:44:23 volumio sudo[15443]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:23 volumio volumio[15184]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 15:44:23 volumio volumio[15184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:23 volumio sudo[15443]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:23 volumio volumio[15184]: info: Upmpdcli Daemon Started Sep 02 15:44:23 volumio volumio[15184]: 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 02 15:44:23 volumio volumio[15184]: info: Spotify Successfully logged in Sep 02 15:44:23 volumio volumio[15184]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 15:44:23 volumio volumio[15184]: info: [1756820663250] CoreMusicLibrary::Adding element Spotify Sep 02 15:44:23 volumio volumio[15184]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 15:44:23 volumio volumio[15184]: Cannot find translation for source Jellyfin Sep 02 15:44:23 volumio volumio[15184]: Cannot find translation for source Spotify Sep 02 15:44:23 volumio sudo[15440]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 02 15:44:23 volumio sudo[15440]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:23 volumio sudo[15440]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:23 volumio volumio[15184]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 02 15:44:23 volumio volumio[15184]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 15:44:23 volumio volumio[15184]: info: VolumeController:: Volume=37 Mute =false Sep 02 15:44:23 volumio volumio[15184]: info: CoreCommandRouter::volumioGetState Sep 02 15:44:23 volumio volumio[15184]: info: CorePlayQueue::getTrack 0 Sep 02 15:44:23 volumio volumio[15184]: info: CoreStateMachine::pushState Sep 02 15:44:23 volumio volumio[15184]: info: CorePlayQueue::getTrack 0 Sep 02 15:44:23 volumio volumio[15184]: info: CoreCommandRouter::volumioPushState Sep 02 15:44:23 volumio volumio[15184]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 02 15:44:23 volumio volumio[15184]: 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 02 15:44:23 volumio volumio[15184]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 02 15:44:23 volumio volumio[15184]: info: camilladsp service started and running in background, instance 1 Sep 02 15:44:23 volumio volumio[15184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:23 volumio volumio[15184]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 02 15:44:23 volumio volumio[15184]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 02 15:44:23 volumio volumio[15184]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 02 15:44:24 volumio volumio[15184]: info: FusionDsp loaded Sep 02 15:44:24 volumio volumio[15184]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 02 15:44:24 volumio sudo[15463]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 02 15:44:24 volumio sudo[15463]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:24 volumio sudo[15463]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:24 volumio volumio[15184]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 02 15:44:24 volumio volumio[15184]: info: Adding Signal Path Element [object Object] Sep 02 15:44:24 volumio volumio[15184]: info: Adding fusiondspeq DSP Signal Path Element Sep 02 15:44:24 volumio volumio[15184]: info: FusionDsp - ---- installed callbackRead Sep 02 15:44:24 volumio volumio[15184]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 15:44:24 volumio volumio[15184]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 02 15:44:24 volumio volumio[15184]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 02 15:44:24 volumio volumio[15184]: at onErrorNT (internal/child_process.js:465:16) Sep 02 15:44:24 volumio volumio[15184]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Sep 02 15:44:24 volumio volumio[15184]: at runNextTicks (internal/process/task_queues.js:62:3) Sep 02 15:44:24 volumio volumio[15184]: at listOnTimeout (internal/timers.js:523:9) Sep 02 15:44:24 volumio volumio[15184]: at processTimers (internal/timers.js:497:7) { Sep 02 15:44:24 volumio volumio[15184]: errno: -2, Sep 02 15:44:24 volumio volumio[15184]: code: 'ENOENT', Sep 02 15:44:24 volumio volumio[15184]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 02 15:44:24 volumio volumio[15184]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 02 15:44:24 volumio volumio[15184]: spawnargs: [ Sep 02 15:44:24 volumio volumio[15184]: '-p', Sep 02 15:44:24 volumio volumio[15184]: 9876, Sep 02 15:44:24 volumio volumio[15184]: '-o', Sep 02 15:44:24 volumio volumio[15184]: '/tmp/camilladsp.log', Sep 02 15:44:24 volumio volumio[15184]: '-l', Sep 02 15:44:24 volumio volumio[15184]: 'warn', Sep 02 15:44:24 volumio volumio[15184]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 02 15:44:24 volumio volumio[15184]: ] Sep 02 15:44:24 volumio volumio[15184]: } Sep 02 15:44:24 volumio volumio[15184]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 15:44:24 volumio sudo[15474]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-02 15:43 Sep 02 15:44:24 volumio sudo[15474]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:24 volumio mpd[15450]: Sep 02 15:44 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 02 15:44:24 volumio systemd[1]: Started Music Player Daemon. Sep 02 15:44:24 volumio sudo[15437]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:24 volumio sudo[15355]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:24 volumio sudo[15334]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:24 volumio sudo[15474]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:24 volumio volumio-remote-updater[504]: [2025-09-02 15:44:24] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 02 15:44:24 volumio volumio-remote-updater[504]: [2025-09-02 15:44:24] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 02 15:44:24 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 02 15:44:25 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 02 15:44:25 volumio systemd[1]: Started dynamicswap service. Sep 02 15:44:25 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 02 15:44:25 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 02 15:44:25 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3794. Sep 02 15:44:25 volumio systemd[1]: Started dynamicswap service. Sep 02 15:44:25 volumio systemd[1]: Stopped Volumio Backend Module. Sep 02 15:44:25 volumio systemd[1]: Started Volumio Backend Module. Sep 02 15:44:25 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 02 15:44:26 volumio volumio[15487]: info: ------------------------------------------- Sep 02 15:44:26 volumio volumio[15487]: info: ----- Volumio3 ---- Sep 02 15:44:26 volumio volumio[15487]: info: ------------------------------------------- Sep 02 15:44:26 volumio volumio[15487]: info: ----- System startup ---- Sep 02 15:44:26 volumio volumio[15487]: info: ------------------------------------------- Sep 02 15:44:27 volumio volumio[15487]: info: MYVOLUMIO Environment detected Sep 02 15:44:27 volumio volumio[15487]: info: Plugin folders cleanup Sep 02 15:44:27 volumio volumio[15487]: info: Scanning into folder /volumio/app/plugins/ Sep 02 15:44:27 volumio volumio[15487]: info: Scanning category audio_interface Sep 02 15:44:27 volumio volumio[15487]: info: Scanning category miscellanea Sep 02 15:44:27 volumio volumio[15487]: info: Scanning category music_service Sep 02 15:44:27 volumio volumio[15487]: info: Scanning category plugins.json Sep 02 15:44:27 volumio volumio[15487]: info: Scanning category system_controller Sep 02 15:44:27 volumio volumio[15487]: info: Scanning category user_interface Sep 02 15:44:27 volumio volumio[15487]: info: Scanning into folder /data/plugins/ Sep 02 15:44:27 volumio volumio[15487]: info: Scanning category audio_interface Sep 02 15:44:27 volumio volumio[15487]: info: Scanning category music_service Sep 02 15:44:27 volumio volumio[15487]: info: Scanning category user_interface Sep 02 15:44:27 volumio volumio[15487]: info: Plugin folders cleanup completed Sep 02 15:44:27 volumio volumio[15487]: info: ------------------------------------------- Sep 02 15:44:27 volumio volumio[15487]: info: ----- Core plugins startup ---- Sep 02 15:44:27 volumio volumio[15487]: info: ------------------------------------------- Sep 02 15:44:27 volumio volumio[15487]: info: Loading plugins from folder /volumio/app/plugins/ Sep 02 15:44:27 volumio volumio[15487]: info: Adding plugin upnp to MyMusic Plugins Sep 02 15:44:27 volumio volumio[15487]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 02 15:44:27 volumio volumio[15487]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 02 15:44:27 volumio volumio[15487]: info: Loading plugins from folder /data/plugins/ Sep 02 15:44:27 volumio volumio[15487]: info: Loading plugin "system"... Sep 02 15:44:27 volumio volumio[15487]: info: Loading plugin "appearance"... Sep 02 15:44:28 volumio volumio[15487]: info: Loading plugin "network"... Sep 02 15:44:28 volumio volumio[15487]: info: Refreshing Cached IP Addresses Sep 02 15:44:28 volumio sudo[15513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 02 15:44:28 volumio sudo[15513]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:28 volumio sudo[15513]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:28 volumio volumio[15487]: info: Loading plugin "services"... Sep 02 15:44:28 volumio sudo[15515]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 02 15:44:28 volumio sudo[15515]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:28 volumio volumio[15487]: info: Loading plugin "alsa_controller"... Sep 02 15:44:28 volumio sudo[15515]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:28 volumio volumio[15487]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 15:44:28 volumio volumio[15487]: info: Loading plugin "wizard"... Sep 02 15:44:28 volumio volumio[15487]: info: Loading plugin "networkfs"... Sep 02 15:44:29 volumio volumio[15487]: info: Starting Udev Watcher for removable devices Sep 02 15:44:29 volumio volumio[15487]: info: Ignoring mount for partition: boot Sep 02 15:44:29 volumio volumio[15487]: info: Ignoring mount for partition: volumio Sep 02 15:44:29 volumio volumio[15487]: info: Ignoring mount for partition: volumio_data Sep 02 15:44:29 volumio volumio[15487]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 15:44:29 volumio volumio[15487]: info: Loading plugin "volumio_command_line_client"... Sep 02 15:44:29 volumio volumio[15487]: info: Loading plugin "upnp"... Sep 02 15:44:29 volumio volumio[15487]: info: [1756820669065] Starting Upmpd Daemon Sep 02 15:44:29 volumio volumio[15487]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 15:44:29 volumio volumio[15487]: info: Loading plugin "my_music"... Sep 02 15:44:29 volumio volumio[15487]: info: Loading plugin "mpd"... Sep 02 15:44:29 volumio volumio[15487]: info: Plugin upnp_browser is not enabled Sep 02 15:44:29 volumio volumio[15487]: info: Loading plugin "alarm-clock"... Sep 02 15:44:29 volumio volumio[15487]: info: Plugin airplay_emulation is not enabled Sep 02 15:44:29 volumio volumio[15487]: info: Loading plugin "last_100"... Sep 02 15:44:29 volumio volumio[15487]: info: Loading plugin "webradio"... Sep 02 15:44:29 volumio volumio[15487]: info: Loading plugin "i2s_dacs"... Sep 02 15:44:29 volumio volumio[15487]: info: Loading plugin "volumiodiscovery"... Sep 02 15:44:29 volumio volumio[15487]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 02 15:44:29 volumio volumio[15487]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 15:44:29 volumio volumio[15487]: *** WARNING *** For more information see Sep 02 15:44:29 volumio node[15487]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 02 15:44:29 volumio volumio[15487]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 02 15:44:29 volumio volumio[15487]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 15:44:29 volumio volumio[15487]: *** WARNING *** For more information see Sep 02 15:44:29 volumio node[15487]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 15:44:29 volumio node[15487]: *** WARNING *** For more information see Sep 02 15:44:29 volumio node[15487]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 02 15:44:29 volumio node[15487]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 15:44:29 volumio node[15487]: *** WARNING *** For more information see Sep 02 15:44:29 volumio volumio[15487]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 02 15:44:29 volumio volumio[15487]: info: Discovery: Started advertising with name: Volumio Sep 02 15:44:29 volumio volumio[15487]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 15:44:29 volumio volumio[15487]: info: Loading plugin "jellyfin"... Sep 02 15:44:29 volumio volumio-remote-updater[504]: [2025-09-02 15:44:29] [connect] Successful connection Sep 02 15:44:30 volumio volumio[15487]: info: Loading plugin "spop"... Sep 02 15:44:31 volumio volumio[15487]: info: Loading plugin "ytcr"... Sep 02 15:44:36 volumio volumio[15487]: info: Loading plugin "outputs"... Sep 02 15:44:36 volumio volumio[15487]: info: Loading plugin "albumart"... Sep 02 15:44:36 volumio volumio[15487]: info: Plugin example_plugin is not enabled Sep 02 15:44:36 volumio volumio[15487]: info: Loading plugin "inputs"... Sep 02 15:44:36 volumio volumio[15487]: info: Loading plugin "updater_comm"... Sep 02 15:44:36 volumio volumio[15487]: info: Plugin mpdemulation is not enabled Sep 02 15:44:36 volumio volumio[15487]: info: Loading plugin "rest_api"... Sep 02 15:44:36 volumio volumio[15487]: info: Loading plugin "websocket"... Sep 02 15:44:36 volumio volumio[15487]: info: Starting Socket.io Server version 2.3.0 Sep 02 15:44:36 volumio volumio[15487]: info: Loading plugin "fusiondsp"... Sep 02 15:44:36 volumio volumio[15487]: Forking 3 albumart workers Sep 02 15:44:36 volumio volumio[15487]: info: Applying required configuration parameters for plugin fusiondsp Sep 02 15:44:36 volumio volumio[15487]: info: Loading plugin "Systeminfo"... Sep 02 15:44:37 volumio volumio[15487]: info: Loading i18n strings for locale hu Sep 02 15:44:37 volumio volumio[15487]: Updating browse sources language Sep 02 15:44:37 volumio volumio[15487]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 15:44:37 volumio volumio[15487]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 15:44:37 volumio volumio[15487]: info: CoreCommandRouter::initPlayerControls Sep 02 15:44:37 volumio volumio[15487]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:37 volumio volumio[15487]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:37 volumio volumio[15487]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:37 volumio volumio[15487]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:37 volumio volumio[15487]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:37 volumio volumio[15487]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:37 volumio volumio[15487]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:37 volumio volumio[15487]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:37 volumio volumio[15487]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 15:44:37 volumio volumio[15487]: Express server listening on port 3000 Sep 02 15:44:37 volumio volumio[15487]: [Metrics] WebUI: 10s 985.78ms Sep 02 15:44:37 volumio volumio[15487]: info: CoreStateMachine::resetVolumioState Sep 02 15:44:37 volumio volumio[15487]: info: CoreStateMachine::getcurrentVolume Sep 02 15:44:37 volumio volumio[15487]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 15:44:37 volumio volumio[15487]: info: Volumio Network Manager: Network status updated: 1 Sep 02 15:44:37 volumio volumio[15487]: Starting albumart workers Sep 02 15:44:37 volumio volumio[15487]: Starting albumart workers Sep 02 15:44:37 volumio volumio[15487]: Starting albumart workers Sep 02 15:44:37 volumio volumio[15487]: info: CoreCommandRouter::volumioGetState Sep 02 15:44:37 volumio volumio[15487]: info: CorePlayQueue::getTrack 0 Sep 02 15:44:37 volumio volumio[15487]: info: CoreCommandRouter::volumioGetState Sep 02 15:44:37 volumio volumio[15487]: info: CorePlayQueue::getTrack 0 Sep 02 15:44:37 volumio volumio[15487]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 15:44:37 volumio volumio[15487]: info: VolumeController:: Volume=37 Mute =false Sep 02 15:44:37 volumio volumio[15487]: info: CoreStateMachine::pushState Sep 02 15:44:37 volumio volumio[15487]: info: CorePlayQueue::getTrack 0 Sep 02 15:44:37 volumio volumio[15487]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 15:44:37 volumio volumio[15487]: info: CoreCommandRouter::volumioPushState Sep 02 15:44:37 volumio volumio[15487]: info: CoreStateMachine::updateTrackBlock Sep 02 15:44:37 volumio volumio[15487]: info: CorePlayQueue::getTrackBlock Sep 02 15:44:37 volumio volumio[15487]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 15:44:37 volumio volumio[15487]: info: CoreStateMachine::setRepeat false single undefined Sep 02 15:44:37 volumio volumio[15487]: info: CoreStateMachine::pushState Sep 02 15:44:37 volumio volumio[15487]: info: CorePlayQueue::getTrack 0 Sep 02 15:44:37 volumio volumio[15487]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 15:44:37 volumio volumio[15487]: info: CoreCommandRouter::volumioPushState Sep 02 15:44:37 volumio volumio[15487]: info: CoreStateMachine::setRandom true Sep 02 15:44:37 volumio volumio[15487]: info: CoreStateMachine::pushState Sep 02 15:44:37 volumio volumio[15487]: info: CorePlayQueue::getTrack 0 Sep 02 15:44:37 volumio volumio[15487]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 15:44:37 volumio volumio[15487]: info: CoreCommandRouter::volumioPushState Sep 02 15:44:37 volumio volumio[15487]: info: Setting Device type: Raspberry PI Sep 02 15:44:37 volumio volumio[15487]: info: Listing playlists Sep 02 15:44:37 volumio volumio[15487]: info: Listing playlists Sep 02 15:44:37 volumio volumio[15487]: info: Listing playlists Sep 02 15:44:37 volumio volumio[15487]: info: Listing playlists Sep 02 15:44:38 volumio volumio[15487]: info: Completed loading Core Plugins Sep 02 15:44:38 volumio volumio[15487]: info: Preparing to generate the ALSA configuration file Sep 02 15:44:38 volumio volumio[15487]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 02 15:44:38 volumio volumio[15487]: info: Reading ALSA contributions from plugins. Sep 02 15:44:38 volumio volumio[15487]: info: VolumeController:: Volume=37 Mute =false Sep 02 15:44:38 volumio volumio[15487]: info: CoreStateMachine::pushState Sep 02 15:44:38 volumio volumio[15487]: info: CorePlayQueue::getTrack 0 Sep 02 15:44:38 volumio volumio[15487]: info: CoreCommandRouter::volumioPushState Sep 02 15:44:38 volumio volumio[15487]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227 Sep 02 15:44:38 volumio volumio[15487]: info: Discovery: Found device Volumio Sep 02 15:44:38 volumio volumio[15487]: info: CoreCommandRouter::volumioGetState Sep 02 15:44:38 volumio volumio[15487]: info: CorePlayQueue::getTrack 0 Sep 02 15:44:38 volumio volumio[15487]: info: Asound.conf file unchanged, so no further update is needed Sep 02 15:44:38 volumio volumio[15487]: info: Output device has changed, restarting MPD Sep 02 15:44:38 volumio sudo[15596]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 15:44:38 volumio sudo[15596]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:38 volumio sudo[15596]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:38 volumio volumio[15487]: info: ___________ START PLUGINS ___________ Sep 02 15:44:38 volumio volumio[15487]: info: ControllerMpd::onStart: Initializing MPD Sep 02 15:44:38 volumio volumio[15487]: info: Creating MPD Configuration file Sep 02 15:44:38 volumio sudo[15599]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 15:44:38 volumio sudo[15599]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:38 volumio systemd[1]: Stopping Music Player Daemon... Sep 02 15:44:38 volumio sudo[15614]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 15:44:38 volumio sudo[15614]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:38 volumio sudo[15614]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:38 volumio systemd[1]: mpd.service: Succeeded. Sep 02 15:44:38 volumio systemd[1]: Stopped Music Player Daemon. Sep 02 15:44:38 volumio volumio[15487]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 15:44:38 volumio volumio[15487]: info: [1756820678404] CoreMusicLibrary::Adding element Last_100 Sep 02 15:44:38 volumio volumio[15487]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 15:44:38 volumio volumio[15487]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 15:44:38 volumio volumio[15487]: info: [1756820678409] CoreMusicLibrary::Adding element Webradio Sep 02 15:44:38 volumio volumio[15487]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 15:44:38 volumio volumio[15487]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 15:44:38 volumio volumio[15487]: info: Initializing BBC Radios Sep 02 15:44:38 volumio systemd[1]: Starting Music Player Daemon... Sep 02 15:44:38 volumio sudo[15630]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 15:44:38 volumio sudo[15630]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:38 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 02 15:44:38 volumio systemd[1]: mpd.service: Succeeded. Sep 02 15:44:38 volumio systemd[1]: Stopped Music Player Daemon. Sep 02 15:44:38 volumio systemd[1]: Starting Music Player Daemon... Sep 02 15:44:38 volumio volumio[15487]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 15:44:38 volumio volumio[15487]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 15:44:38 volumio volumio[15487]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 15:44:38 volumio volumio[15487]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 02 15:44:38 volumio volumio[15487]: info: Discovery: Getting this device information Sep 02 15:44:38 volumio volumio[15487]: info: CoreCommandRouter::volumioGetState Sep 02 15:44:38 volumio volumio[15487]: info: CorePlayQueue::getTrack 0 Sep 02 15:44:38 volumio volumio[15487]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 02 15:44:38 volumio volumio[15487]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 15:44:38 volumio volumio[15487]: info: [1756820678560] CoreMusicLibrary::Adding element Jellyfin Sep 02 15:44:38 volumio volumio[15487]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 15:44:38 volumio volumio[15487]: Cannot find translation for source Jellyfin Sep 02 15:44:38 volumio volumio[15487]: 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 02 15:44:38 volumio volumio[15487]: info: Creating Spotify config file Sep 02 15:44:38 volumio volumio[15487]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:38 volumio sudo[15635]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 02 15:44:38 volumio sudo[15635]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:38 volumio sudo[15635]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:38 volumio volumio[15487]: info: Loading i18n strings for locale hu Sep 02 15:44:38 volumio volumio[15487]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 02 15:44:38 volumio volumio[15487]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:38 volumio volumio[15487]: info: FusionDsp - mixtype--------------------- Hardware Sep 02 15:44:38 volumio volumio[15487]: info: Volumio Calling Home Sep 02 15:44:38 volumio volumio[15487]: info: Preparing to generate the ALSA configuration file Sep 02 15:44:38 volumio volumio[15487]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 02 15:44:38 volumio volumio[15487]: info: Reading ALSA contributions from plugins. Sep 02 15:44:38 volumio volumio[15487]: info: MPD Permissions set Sep 02 15:44:39 volumio volumio[15487]: info: Spotify config file written Sep 02 15:44:39 volumio sudo[15663]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 02 15:44:39 volumio sudo[15663]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:39 volumio volumio[15487]: 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 02 15:44:39 volumio volumio[15487]: info: Volumio called home Sep 02 15:44:39 volumio systemd[1]: Stopping go-librespot Daemon... Sep 02 15:44:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 02 15:44:39 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 02 15:44:39 volumio systemd[1]: Stopped go-librespot Daemon. Sep 02 15:44:39 volumio sudo[15667]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 02 15:44:39 volumio sudo[15667]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:39 volumio systemd[1]: Started go-librespot Daemon. Sep 02 15:44:39 volumio volumio[15487]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:39 volumio volumio[15487]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 15:44:39 volumio volumio[15487]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:39 volumio sudo[15667]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:39 volumio sudo[15670]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 02 15:44:39 volumio go-librespot[15666]: go-librespot daemon starting... Sep 02 15:44:39 volumio sudo[15670]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:39 volumio sudo[15670]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:39 volumio sudo[15663]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:39 volumio volumio[15487]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 15:44:39 volumio volumio[15487]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:39 volumio volumio[15487]: info: No need to fix Spotify hosts Sep 02 15:44:39 volumio go-librespot[15666]: time="2025-09-02T15:44:39+02:00" level=info msg="running go-librespot 0.3.2" Sep 02 15:44:39 volumio go-librespot[15666]: time="2025-09-02T15:44:39+02:00" level=debug msg="app state loaded" Sep 02 15:44:39 volumio go-librespot[15666]: time="2025-09-02T15:44:39+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 02 15:44:39 volumio go-librespot[15666]: time="2025-09-02T15:44:39+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 02 15:44:39 volumio go-librespot[15666]: time="2025-09-02T15:44:39+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 02 15:44:39 volumio go-librespot[15666]: time="2025-09-02T15:44:39+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 02 15:44:39 volumio go-librespot[15666]: time="2025-09-02T15:44:39+02:00" level=info msg="zeroconf server listening on port 45217" Sep 02 15:44:39 volumio go-librespot[15666]: time="2025-09-02T15:44:39+02:00" level=debug msg="obtained new client token: AADNqCzQvM6GROK3sC4bKFHJzYCdG8XQOYJ9gLHMEMGUgbS8WTwKHFfSS7FqfPzJmmTaqI5c/zBD8ZWbZHawuraMsrE9pfwedenDhyv+76Gb8w6QBt8Fm4Pa5KY01wlZEghVrOB6JI/fetT4ID7ocalb7vM7KrGGe1rIm/6d9nixjD4fGl+nQaTyLlCPCnwObk/thy9UgFUBjG7gw4qaAkL+5OMVm8OtQZ7i0HI2mdmHjsW8XcpuQO1f7jM=" Sep 02 15:44:39 volumio go-librespot[15666]: time="2025-09-02T15:44:39+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 02 15:44:39 volumio volumio[15487]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 02 15:44:39 volumio volumio[15487]: SPOTIFY: BQAouNZ1PMVUnhPX2rlAamKSoB5kiQX5NuxvNG16GiEWiuHnpGGN6D5U-Y-Br7p90k3MqRfrpS_aD63U4m7mhdOBv74e39Sbnv0eUHLJSfaopmXo2hWeui1TmW1gRpy2rUyDn2NxOw-Dm1lN_tdzmTnC5Gf9VspDqFwkS1tMviUC3zIn73C0-_lgkJVgtEcB0mBiySpBxjnb1TCuRK4lwdjRva9j9cuHov1dRNOaeVWzBlNSVvSPeSoK7t836nwCjE8j1omdnKt_TZverB8ofPjrVD0sPvjOIKhySMy5ABvEqMA Sep 02 15:44:39 volumio volumio[15487]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 02 15:44:39 volumio volumio[15487]: info: New Spotify access token = BQAouNZ1PMVUnhPX2rlAamKSoB5kiQX5NuxvNG16GiEWiuHnpGGN6D5U-Y-Br7p90k3MqRfrpS_aD63U4m7mhdOBv74e39Sbnv0eUHLJSfaopmXo2hWeui1TmW1gRpy2rUyDn2NxOw-Dm1lN_tdzmTnC5Gf9VspDqFwkS1tMviUC3zIn73C0-_lgkJVgtEcB0mBiySpBxjnb1TCuRK4lwdjRva9j9cuHov1dRNOaeVWzBlNSVvSPeSoK7t836nwCjE8j1omdnKt_TZverB8ofPjrVD0sPvjOIKhySMy5ABvEqMA Sep 02 15:44:39 volumio volumio[15487]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 02 15:44:39 volumio go-librespot[15666]: time="2025-09-02T15:44:39+02:00" level=debug msg="completed keyexchange" Sep 02 15:44:39 volumio go-librespot[15666]: time="2025-09-02T15:44:39+02:00" level=debug msg="completed challenge" Sep 02 15:44:39 volumio go-librespot[15666]: time="2025-09-02T15:44:39+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 02 15:44:39 volumio volumio[15487]: info: CoreCommandRouter::volumioGetState Sep 02 15:44:39 volumio volumio[15487]: info: CorePlayQueue::getTrack 0 Sep 02 15:44:39 volumio volumio[15487]: info: Asound.conf file unchanged, so no further update is needed Sep 02 15:44:39 volumio volumio[15487]: info: Output device has changed, restarting MPD Sep 02 15:44:39 volumio sudo[15690]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 15:44:39 volumio sudo[15690]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:39 volumio go-librespot[15666]: time="2025-09-02T15:44:39+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 02 15:44:39 volumio go-librespot[15666]: time="2025-09-02T15:44:39+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 02 15:44:39 volumio sudo[15690]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:39 volumio sudo[15693]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 15:44:39 volumio sudo[15693]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:39 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 02 15:44:39 volumio systemd[1]: mpd.service: Succeeded. Sep 02 15:44:39 volumio systemd[1]: Stopped Music Player Daemon. Sep 02 15:44:39 volumio go-librespot[15666]: time="2025-09-02T15:44:39+02:00" level=debug msg="dealer connection opened" Sep 02 15:44:39 volumio go-librespot[15666]: time="2025-09-02T15:44:39+02:00" level=trace msg="starting accesspoint recv loop" Sep 02 15:44:39 volumio go-librespot[15666]: time="2025-09-02T15:44:39+02:00" level=trace msg="starting dealer recv loop" Sep 02 15:44:39 volumio go-librespot[15666]: time="2025-09-02T15:44:39+02:00" level=trace msg="received accesspoint ping" Sep 02 15:44:39 volumio go-librespot[15666]: time="2025-09-02T15:44:39+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 02 15:44:39 volumio go-librespot[15666]: time="2025-09-02T15:44:39+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 02 15:44:39 volumio go-librespot[15666]: time="2025-09-02T15:44:39+02:00" level=debug msg="received connection id: Yjk4ZGViNDAtNDk3...NDI1MzVDQzEzNw==" Sep 02 15:44:39 volumio sudo[15696]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 02 15:44:39 volumio sudo[15696]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:40 volumio volumio[15487]: info: MPD Permissions set Sep 02 15:44:40 volumio systemd[1]: Starting Music Player Daemon... Sep 02 15:44:40 volumio sudo[15696]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:40 volumio volumio[15487]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:40 volumio volumio[15487]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:40 volumio volumio[15487]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:40 volumio go-librespot[15666]: time="2025-09-02T15:44:40+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 02 15:44:40 volumio go-librespot[15666]: time="2025-09-02T15:44:40+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 02 15:44:40 volumio go-librespot[15666]: time="2025-09-02T15:44:40+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 02 15:44:40 volumio go-librespot[15666]: time="2025-09-02T15:44:40+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 02 15:44:40 volumio go-librespot[15666]: time="2025-09-02T15:44:40+02:00" level=trace msg="received accesspoint pong ack" Sep 02 15:44:40 volumio volumio[15487]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 15:44:40 volumio volumio[15487]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:40 volumio volumio[15487]: info: Upmpdcli Daemon Started Sep 02 15:44:40 volumio volumio[15487]: 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 02 15:44:40 volumio volumio[15487]: info: Spotify Successfully logged in Sep 02 15:44:40 volumio sudo[15697]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 02 15:44:40 volumio sudo[15697]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:40 volumio volumio[15487]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 15:44:40 volumio volumio[15487]: info: [1756820680139] CoreMusicLibrary::Adding element Spotify Sep 02 15:44:40 volumio volumio[15487]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 15:44:40 volumio volumio[15487]: Cannot find translation for source Jellyfin Sep 02 15:44:40 volumio volumio[15487]: Cannot find translation for source Spotify Sep 02 15:44:40 volumio sudo[15697]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:40 volumio volumio[15487]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 02 15:44:40 volumio volumio[15487]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 15:44:40 volumio volumio[15487]: Error: bind EADDRINUSE 192.168.0.171:50386 Sep 02 15:44:40 volumio volumio[15487]: at dgram.js:337:20 Sep 02 15:44:40 volumio volumio[15487]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Sep 02 15:44:40 volumio volumio[15487]: errno: -98, Sep 02 15:44:40 volumio volumio[15487]: code: 'EADDRINUSE', Sep 02 15:44:40 volumio volumio[15487]: syscall: 'bind', Sep 02 15:44:40 volumio volumio[15487]: address: '192.168.0.171', Sep 02 15:44:40 volumio volumio[15487]: port: 50386 Sep 02 15:44:40 volumio volumio[15487]: } Sep 02 15:44:40 volumio volumio[15487]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 15:44:41 volumio sudo[15715]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-02 15:43 Sep 02 15:44:41 volumio sudo[15715]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:41 volumio sudo[15715]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:41 volumio mpd[15705]: Sep 02 15:44 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 02 15:44:41 volumio systemd[1]: Started Music Player Daemon. Sep 02 15:44:41 volumio sudo[15630]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:41 volumio sudo[15599]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:41 volumio sudo[15693]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:41 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 02 15:44:41 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 02 15:44:41 volumio systemd[1]: Started dynamicswap service. Sep 02 15:44:41 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 02 15:44:42 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 02 15:44:42 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3795. Sep 02 15:44:42 volumio systemd[1]: Started dynamicswap service. Sep 02 15:44:42 volumio systemd[1]: Stopped Volumio Backend Module. Sep 02 15:44:42 volumio systemd[1]: Started Volumio Backend Module. Sep 02 15:44:42 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 02 15:44:42 volumio volumio-remote-updater[504]: [2025-09-02 15:44:42] [info] asio async_connect error: system:111 (Connection refused) Sep 02 15:44:42 volumio volumio-remote-updater[504]: [2025-09-02 15:44:42] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 02 15:44:42 volumio volumio-remote-updater[504]: [2025-09-02 15:44:42] [error] handle_connect error: Underlying Transport Error Sep 02 15:44:43 volumio volumio[15732]: info: ------------------------------------------- Sep 02 15:44:44 volumio volumio[15732]: info: ----- Volumio3 ---- Sep 02 15:44:44 volumio volumio[15732]: info: ------------------------------------------- Sep 02 15:44:44 volumio volumio[15732]: info: ----- System startup ---- Sep 02 15:44:44 volumio volumio[15732]: info: ------------------------------------------- Sep 02 15:44:44 volumio volumio[15732]: info: MYVOLUMIO Environment detected Sep 02 15:44:44 volumio volumio[15732]: info: Plugin folders cleanup Sep 02 15:44:44 volumio volumio[15732]: info: Scanning into folder /volumio/app/plugins/ Sep 02 15:44:44 volumio volumio[15732]: info: Scanning category audio_interface Sep 02 15:44:44 volumio volumio[15732]: info: Scanning category miscellanea Sep 02 15:44:44 volumio volumio[15732]: info: Scanning category music_service Sep 02 15:44:44 volumio volumio[15732]: info: Scanning category plugins.json Sep 02 15:44:44 volumio volumio[15732]: info: Scanning category system_controller Sep 02 15:44:44 volumio volumio[15732]: info: Scanning category user_interface Sep 02 15:44:44 volumio volumio[15732]: info: Scanning into folder /data/plugins/ Sep 02 15:44:44 volumio volumio[15732]: info: Scanning category audio_interface Sep 02 15:44:44 volumio volumio[15732]: info: Scanning category music_service Sep 02 15:44:44 volumio volumio[15732]: info: Scanning category user_interface Sep 02 15:44:44 volumio volumio[15732]: info: Plugin folders cleanup completed Sep 02 15:44:44 volumio volumio[15732]: info: ------------------------------------------- Sep 02 15:44:44 volumio volumio[15732]: info: ----- Core plugins startup ---- Sep 02 15:44:44 volumio volumio[15732]: info: ------------------------------------------- Sep 02 15:44:44 volumio volumio[15732]: info: Loading plugins from folder /volumio/app/plugins/ Sep 02 15:44:44 volumio volumio[15732]: info: Adding plugin upnp to MyMusic Plugins Sep 02 15:44:44 volumio volumio[15732]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 02 15:44:44 volumio volumio[15732]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 02 15:44:44 volumio volumio[15732]: info: Loading plugins from folder /data/plugins/ Sep 02 15:44:44 volumio volumio[15732]: info: Loading plugin "system"... Sep 02 15:44:44 volumio volumio[15732]: info: Loading plugin "appearance"... Sep 02 15:44:45 volumio volumio[15732]: info: Loading plugin "network"... Sep 02 15:44:45 volumio volumio[15732]: info: Refreshing Cached IP Addresses Sep 02 15:44:45 volumio sudo[15769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 02 15:44:45 volumio sudo[15769]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:45 volumio sudo[15769]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:45 volumio sudo[15771]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 02 15:44:45 volumio volumio[15732]: info: Loading plugin "services"... Sep 02 15:44:45 volumio sudo[15771]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:45 volumio sudo[15771]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:45 volumio volumio[15732]: info: Loading plugin "alsa_controller"... Sep 02 15:44:46 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 15:44:46 volumio volumio[15732]: info: Loading plugin "wizard"... Sep 02 15:44:46 volumio volumio[15732]: info: Loading plugin "networkfs"... Sep 02 15:44:46 volumio volumio[15732]: info: Starting Udev Watcher for removable devices Sep 02 15:44:46 volumio volumio[15732]: info: Ignoring mount for partition: boot Sep 02 15:44:46 volumio volumio[15732]: info: Ignoring mount for partition: volumio Sep 02 15:44:46 volumio volumio[15732]: info: Ignoring mount for partition: volumio_data Sep 02 15:44:46 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 15:44:46 volumio volumio[15732]: info: Loading plugin "volumio_command_line_client"... Sep 02 15:44:46 volumio volumio[15732]: info: Loading plugin "upnp"... Sep 02 15:44:46 volumio volumio[15732]: info: [1756820686144] Starting Upmpd Daemon Sep 02 15:44:46 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 15:44:46 volumio volumio[15732]: info: Loading plugin "my_music"... Sep 02 15:44:46 volumio volumio[15732]: info: Loading plugin "mpd"... Sep 02 15:44:46 volumio volumio[15732]: info: Plugin upnp_browser is not enabled Sep 02 15:44:46 volumio volumio[15732]: info: Loading plugin "alarm-clock"... Sep 02 15:44:46 volumio volumio[15732]: info: Plugin airplay_emulation is not enabled Sep 02 15:44:46 volumio volumio[15732]: info: Loading plugin "last_100"... Sep 02 15:44:46 volumio volumio[15732]: info: Loading plugin "webradio"... Sep 02 15:44:46 volumio volumio[15732]: info: Loading plugin "i2s_dacs"... Sep 02 15:44:46 volumio volumio[15732]: info: Loading plugin "volumiodiscovery"... Sep 02 15:44:46 volumio volumio[15732]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 02 15:44:46 volumio volumio[15732]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 15:44:46 volumio volumio[15732]: *** WARNING *** For more information see Sep 02 15:44:46 volumio node[15732]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 02 15:44:46 volumio volumio[15732]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 02 15:44:46 volumio volumio[15732]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 15:44:46 volumio volumio[15732]: *** WARNING *** For more information see Sep 02 15:44:46 volumio node[15732]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 15:44:46 volumio node[15732]: *** WARNING *** For more information see Sep 02 15:44:46 volumio node[15732]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 02 15:44:46 volumio node[15732]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 15:44:46 volumio node[15732]: *** WARNING *** For more information see Sep 02 15:44:46 volumio volumio[15732]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 02 15:44:46 volumio volumio[15732]: info: Discovery: Started advertising with name: Volumio Sep 02 15:44:46 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 15:44:46 volumio volumio[15732]: info: Loading plugin "jellyfin"... Sep 02 15:44:47 volumio volumio[15732]: info: Loading plugin "spop"... Sep 02 15:44:48 volumio volumio[15732]: info: Loading plugin "ytcr"... Sep 02 15:44:52 volumio volumio-remote-updater[504]: [2025-09-02 15:44:52] [connect] Successful connection Sep 02 15:44:53 volumio volumio[15732]: info: Loading plugin "outputs"... Sep 02 15:44:53 volumio volumio[15732]: info: Loading plugin "albumart"... Sep 02 15:44:53 volumio volumio[15732]: info: Plugin example_plugin is not enabled Sep 02 15:44:53 volumio volumio[15732]: info: Loading plugin "inputs"... Sep 02 15:44:53 volumio volumio[15732]: info: Loading plugin "updater_comm"... Sep 02 15:44:53 volumio volumio[15732]: info: Plugin mpdemulation is not enabled Sep 02 15:44:53 volumio volumio[15732]: info: Loading plugin "rest_api"... Sep 02 15:44:53 volumio volumio[15732]: info: Loading plugin "websocket"... Sep 02 15:44:53 volumio volumio[15732]: info: Starting Socket.io Server version 2.3.0 Sep 02 15:44:53 volumio volumio[15732]: info: Loading plugin "fusiondsp"... Sep 02 15:44:53 volumio volumio[15732]: Forking 3 albumart workers Sep 02 15:44:53 volumio volumio[15732]: info: Applying required configuration parameters for plugin fusiondsp Sep 02 15:44:53 volumio volumio[15732]: info: Loading plugin "Systeminfo"... Sep 02 15:44:54 volumio volumio[15732]: info: Loading i18n strings for locale hu Sep 02 15:44:54 volumio volumio[15732]: Updating browse sources language Sep 02 15:44:54 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 15:44:54 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 15:44:54 volumio volumio[15732]: info: CoreCommandRouter::initPlayerControls Sep 02 15:44:54 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:54 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:54 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:54 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:54 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:54 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:54 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:54 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:54 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 15:44:54 volumio volumio[15732]: Express server listening on port 3000 Sep 02 15:44:54 volumio volumio[15732]: [Metrics] WebUI: 11s 10.38ms Sep 02 15:44:54 volumio volumio[15732]: info: CoreStateMachine::resetVolumioState Sep 02 15:44:54 volumio volumio[15732]: info: CoreStateMachine::getcurrentVolume Sep 02 15:44:54 volumio volumio[15732]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 15:44:54 volumio volumio[15732]: Starting albumart workers Sep 02 15:44:54 volumio volumio[15732]: info: Volumio Network Manager: Network status updated: 1 Sep 02 15:44:54 volumio volumio[15732]: Starting albumart workers Sep 02 15:44:54 volumio volumio[15732]: Starting albumart workers Sep 02 15:44:54 volumio volumio-remote-updater[504]: [2025-09-02 15:44:54] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1756820692 101 Sep 02 15:44:54 volumio volumio[15732]: 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 02 15:44:54 volumio volumio[15732]: info: CoreCommandRouter::volumioGetState Sep 02 15:44:54 volumio volumio[15732]: info: CorePlayQueue::getTrack 0 Sep 02 15:44:54 volumio volumio[15732]: info: CoreCommandRouter::volumioGetState Sep 02 15:44:54 volumio volumio[15732]: info: CorePlayQueue::getTrack 0 Sep 02 15:44:54 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 15:44:54 volumio volumio[15732]: info: VolumeController:: Volume=37 Mute =false Sep 02 15:44:54 volumio volumio[15732]: info: CoreStateMachine::pushState Sep 02 15:44:54 volumio volumio[15732]: info: CorePlayQueue::getTrack 0 Sep 02 15:44:54 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 15:44:54 volumio volumio[15732]: info: CoreCommandRouter::volumioPushState Sep 02 15:44:54 volumio volumio[15732]: info: CoreStateMachine::updateTrackBlock Sep 02 15:44:54 volumio volumio[15732]: info: CorePlayQueue::getTrackBlock Sep 02 15:44:54 volumio volumio[15732]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 15:44:55 volumio volumio[15732]: info: CoreStateMachine::setRepeat false single undefined Sep 02 15:44:55 volumio volumio[15732]: info: CoreStateMachine::pushState Sep 02 15:44:55 volumio volumio[15732]: info: CorePlayQueue::getTrack 0 Sep 02 15:44:55 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 15:44:55 volumio volumio[15732]: info: CoreCommandRouter::volumioPushState Sep 02 15:44:55 volumio volumio[15732]: info: CoreStateMachine::setRandom true Sep 02 15:44:55 volumio volumio[15732]: info: CoreStateMachine::pushState Sep 02 15:44:55 volumio volumio[15732]: info: CorePlayQueue::getTrack 0 Sep 02 15:44:55 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 15:44:55 volumio volumio[15732]: info: CoreCommandRouter::volumioPushState Sep 02 15:44:55 volumio volumio[15732]: info: Setting Device type: Raspberry PI Sep 02 15:44:55 volumio volumio[15732]: info: Listing playlists Sep 02 15:44:55 volumio volumio[15732]: info: Listing playlists Sep 02 15:44:55 volumio volumio[15732]: info: Listing playlists Sep 02 15:44:55 volumio volumio[15732]: info: Listing playlists Sep 02 15:44:55 volumio volumio[15732]: info: Completed loading Core Plugins Sep 02 15:44:55 volumio volumio[15732]: info: Preparing to generate the ALSA configuration file Sep 02 15:44:55 volumio volumio[15732]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227 Sep 02 15:44:55 volumio volumio[15732]: info: Discovery: Found device Volumio Sep 02 15:44:55 volumio volumio[15732]: info: CoreCommandRouter::volumioGetState Sep 02 15:44:55 volumio volumio[15732]: info: CorePlayQueue::getTrack 0 Sep 02 15:44:55 volumio volumio[15732]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 02 15:44:55 volumio volumio[15732]: info: Reading ALSA contributions from plugins. Sep 02 15:44:55 volumio volumio[15732]: info: VolumeController:: Volume=37 Mute =false Sep 02 15:44:55 volumio volumio[15732]: info: CoreStateMachine::pushState Sep 02 15:44:55 volumio volumio[15732]: info: CorePlayQueue::getTrack 0 Sep 02 15:44:55 volumio volumio[15732]: info: CoreCommandRouter::volumioPushState Sep 02 15:44:55 volumio volumio[15732]: info: Asound.conf file unchanged, so no further update is needed Sep 02 15:44:55 volumio volumio[15732]: info: Output device has changed, restarting MPD Sep 02 15:44:55 volumio volumio[15732]: info: ___________ START PLUGINS ___________ Sep 02 15:44:55 volumio sudo[15859]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 15:44:55 volumio volumio[15732]: info: ControllerMpd::onStart: Initializing MPD Sep 02 15:44:55 volumio volumio[15732]: info: Creating MPD Configuration file Sep 02 15:44:55 volumio sudo[15859]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:55 volumio sudo[15859]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:55 volumio sudo[15865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 15:44:55 volumio sudo[15865]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:55 volumio systemd[1]: Stopping Music Player Daemon... Sep 02 15:44:55 volumio sudo[15873]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 15:44:55 volumio volumio[15732]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 15:44:55 volumio volumio[15732]: info: [1756820695461] CoreMusicLibrary::Adding element Last_100 Sep 02 15:44:55 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 15:44:55 volumio sudo[15873]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:55 volumio volumio[15732]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 15:44:55 volumio volumio[15732]: info: [1756820695471] CoreMusicLibrary::Adding element Webradio Sep 02 15:44:55 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 15:44:55 volumio systemd[1]: mpd.service: Succeeded. Sep 02 15:44:55 volumio systemd[1]: Stopped Music Player Daemon. Sep 02 15:44:55 volumio sudo[15873]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:55 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 15:44:55 volumio sudo[15879]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 15:44:55 volumio sudo[15879]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:55 volumio systemd[1]: Starting Music Player Daemon... Sep 02 15:44:55 volumio volumio[15732]: info: Initializing BBC Radios Sep 02 15:44:55 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 02 15:44:55 volumio systemd[1]: mpd.service: Succeeded. Sep 02 15:44:55 volumio systemd[1]: Stopped Music Player Daemon. Sep 02 15:44:55 volumio systemd[1]: Starting Music Player Daemon... Sep 02 15:44:55 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 15:44:55 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 15:44:55 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 15:44:55 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 02 15:44:55 volumio volumio[15732]: info: Discovery: Getting this device information Sep 02 15:44:55 volumio volumio[15732]: info: CoreCommandRouter::volumioGetState Sep 02 15:44:55 volumio volumio[15732]: info: CorePlayQueue::getTrack 0 Sep 02 15:44:55 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 02 15:44:55 volumio volumio[15732]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 15:44:55 volumio volumio[15732]: info: [1756820695631] CoreMusicLibrary::Adding element Jellyfin Sep 02 15:44:55 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 15:44:55 volumio volumio[15732]: Cannot find translation for source Jellyfin Sep 02 15:44:55 volumio volumio[15732]: 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 02 15:44:55 volumio volumio[15732]: info: Creating Spotify config file Sep 02 15:44:55 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:55 volumio sudo[15894]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 02 15:44:55 volumio sudo[15894]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:55 volumio sudo[15894]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:55 volumio volumio[15732]: info: Loading i18n strings for locale hu Sep 02 15:44:55 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 02 15:44:55 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:55 volumio volumio[15732]: info: FusionDsp - mixtype--------------------- Hardware Sep 02 15:44:55 volumio volumio[15732]: info: Volumio Calling Home Sep 02 15:44:55 volumio volumio[15732]: info: Preparing to generate the ALSA configuration file Sep 02 15:44:56 volumio volumio[15732]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 02 15:44:56 volumio volumio[15732]: info: Reading ALSA contributions from plugins. Sep 02 15:44:56 volumio volumio[15732]: info: MPD Permissions set Sep 02 15:44:56 volumio volumio[15732]: info: MPD Permissions set Sep 02 15:44:56 volumio volumio[15732]: info: Volumio called home Sep 02 15:44:56 volumio volumio[15732]: info: Spotify config file written Sep 02 15:44:56 volumio sudo[15919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 02 15:44:56 volumio sudo[15919]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:56 volumio volumio[15732]: 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 02 15:44:56 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:56 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:56 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:56 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:56 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:56 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:56 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:56 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:56 volumio systemd[1]: Stopping go-librespot Daemon... Sep 02 15:44:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 02 15:44:56 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 02 15:44:56 volumio systemd[1]: Stopped go-librespot Daemon. Sep 02 15:44:56 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 15:44:56 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:56 volumio volumio[15732]: info: No need to fix Spotify hosts Sep 02 15:44:56 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:56 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 15:44:56 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:56 volumio systemd[1]: Started go-librespot Daemon. Sep 02 15:44:56 volumio go-librespot[15925]: go-librespot daemon starting... Sep 02 15:44:56 volumio sudo[15919]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:56 volumio go-librespot[15925]: time="2025-09-02T15:44:56+02:00" level=info msg="running go-librespot 0.3.2" Sep 02 15:44:56 volumio go-librespot[15925]: time="2025-09-02T15:44:56+02:00" level=debug msg="app state loaded" Sep 02 15:44:56 volumio go-librespot[15925]: time="2025-09-02T15:44:56+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 02 15:44:56 volumio sudo[15938]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 02 15:44:56 volumio sudo[15938]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:56 volumio sudo[15938]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:56 volumio go-librespot[15925]: time="2025-09-02T15:44:56+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Sep 02 15:44:56 volumio go-librespot[15925]: time="2025-09-02T15:44:56+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Sep 02 15:44:56 volumio go-librespot[15925]: time="2025-09-02T15:44:56+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Sep 02 15:44:56 volumio sudo[15941]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 02 15:44:56 volumio sudo[15941]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:56 volumio go-librespot[15925]: time="2025-09-02T15:44:56+02:00" level=info msg="zeroconf server listening on port 42423" Sep 02 15:44:56 volumio sudo[15941]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:56 volumio go-librespot[15925]: time="2025-09-02T15:44:56+02:00" level=debug msg="obtained new client token: AAAEW+LtsvKQ69/aGakMHylEQN1qofxpqa0bNBs0gXdNm+kRbXgWd+o3/d5cMHlkoSOV66UHe5gwjiHHYxSb9Q36k8xEVTo1l69tW6qFc526TBeaiJnp+5JIqmMWBvZ9qq8FA7/lq5uGlfL0AcNqEm6rTRr87pZK/qS+IUcvY/1eTu5c2Zf79TQUdiRIvmgh7f+ux0xQgr3K0GkF7rISRB3vhnAZCqVyudC2p+Y/qp0dAcgWTVxG6YVbldc=" Sep 02 15:44:56 volumio go-librespot[15925]: time="2025-09-02T15:44:56+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 02 15:44:56 volumio go-librespot[15925]: time="2025-09-02T15:44:56+02:00" level=debug msg="completed keyexchange" Sep 02 15:44:56 volumio go-librespot[15925]: time="2025-09-02T15:44:56+02:00" level=debug msg="completed challenge" Sep 02 15:44:56 volumio volumio[15732]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 02 15:44:56 volumio volumio[15732]: SPOTIFY: BQDjMl2Nz-ZxQi8yyGCDpx1zO1RTicxUqciuLo3zuTmj9qgqcx_lzga7ro2JyJcz7RCQPVObcxAjcn8hrpL74O0efMgR7MNRKAzpG9C0T3zCr_VlsgtDCHj8rF_zUvUGguwM3J5QvIpoRJVY-_kSzNd0nmOfUxeQxVmqPuQuyMYuwrImxeprQTGZvqCj77JpAMpnuZnHPsGY6HZX87n9eMrjls7ZsZxJzDm9jruQ90uxYSxgCglAq-Z9EGzclbyPemuPDaYczocFMFC3Nq-Os0FeuvRelD70i7ZmVD18zqnwJ6Y Sep 02 15:44:56 volumio volumio[15732]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 02 15:44:56 volumio volumio[15732]: info: New Spotify access token = BQDjMl2Nz-ZxQi8yyGCDpx1zO1RTicxUqciuLo3zuTmj9qgqcx_lzga7ro2JyJcz7RCQPVObcxAjcn8hrpL74O0efMgR7MNRKAzpG9C0T3zCr_VlsgtDCHj8rF_zUvUGguwM3J5QvIpoRJVY-_kSzNd0nmOfUxeQxVmqPuQuyMYuwrImxeprQTGZvqCj77JpAMpnuZnHPsGY6HZX87n9eMrjls7ZsZxJzDm9jruQ90uxYSxgCglAq-Z9EGzclbyPemuPDaYczocFMFC3Nq-Os0FeuvRelD70i7ZmVD18zqnwJ6Y Sep 02 15:44:56 volumio volumio[15732]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 02 15:44:56 volumio go-librespot[15925]: time="2025-09-02T15:44:56+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 02 15:44:56 volumio volumio[15732]: info: CoreCommandRouter::volumioGetState Sep 02 15:44:56 volumio volumio[15732]: info: CorePlayQueue::getTrack 0 Sep 02 15:44:56 volumio volumio[15732]: info: Asound.conf file unchanged, so no further update is needed Sep 02 15:44:56 volumio volumio[15732]: info: Output device has changed, restarting MPD Sep 02 15:44:56 volumio go-librespot[15925]: time="2025-09-02T15:44:56+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 02 15:44:56 volumio go-librespot[15925]: time="2025-09-02T15:44:56+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 02 15:44:56 volumio sudo[15945]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 15:44:56 volumio sudo[15945]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:56 volumio sudo[15945]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:56 volumio sudo[15948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 15:44:56 volumio sudo[15948]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:56 volumio volumio[15732]: info: CoreCommandRouter::volumioGetState Sep 02 15:44:56 volumio volumio[15732]: info: CorePlayQueue::getTrack 0 Sep 02 15:44:57 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 02 15:44:57 volumio systemd[1]: mpd.service: Succeeded. Sep 02 15:44:57 volumio systemd[1]: Stopped Music Player Daemon. Sep 02 15:44:57 volumio volumio[15732]: info: MPD Permissions set Sep 02 15:44:57 volumio go-librespot[15925]: time="2025-09-02T15:44:57+02:00" level=debug msg="dealer connection opened" Sep 02 15:44:57 volumio go-librespot[15925]: time="2025-09-02T15:44:57+02:00" level=trace msg="starting accesspoint recv loop" Sep 02 15:44:57 volumio go-librespot[15925]: time="2025-09-02T15:44:57+02:00" level=trace msg="starting dealer recv loop" Sep 02 15:44:57 volumio go-librespot[15925]: time="2025-09-02T15:44:57+02:00" level=trace msg="received accesspoint ping" Sep 02 15:44:57 volumio go-librespot[15925]: time="2025-09-02T15:44:57+02:00" level=debug msg="received connection id: Y2ZmYzYxMmItZmQw...MkQ5MDQzNzEzOQ==" Sep 02 15:44:57 volumio go-librespot[15925]: time="2025-09-02T15:44:57+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 02 15:44:57 volumio go-librespot[15925]: time="2025-09-02T15:44:57+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 02 15:44:57 volumio sudo[15952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 02 15:44:57 volumio sudo[15952]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:57 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:57 volumio systemd[1]: Starting Music Player Daemon... Sep 02 15:44:57 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 15:44:57 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:57 volumio sudo[15952]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:57 volumio volumio[15732]: info: Upmpdcli Daemon Started Sep 02 15:44:57 volumio go-librespot[15925]: time="2025-09-02T15:44:57+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 02 15:44:57 volumio go-librespot[15925]: time="2025-09-02T15:44:57+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 02 15:44:57 volumio go-librespot[15925]: time="2025-09-02T15:44:57+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 02 15:44:57 volumio go-librespot[15925]: time="2025-09-02T15:44:57+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 02 15:44:57 volumio go-librespot[15925]: time="2025-09-02T15:44:57+02:00" level=trace msg="received accesspoint pong ack" Sep 02 15:44:57 volumio volumio[15732]: 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 02 15:44:57 volumio volumio[15732]: info: Spotify Successfully logged in Sep 02 15:44:57 volumio volumio[15732]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 15:44:57 volumio volumio[15732]: info: [1756820697169] CoreMusicLibrary::Adding element Spotify Sep 02 15:44:57 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 15:44:57 volumio volumio[15732]: Cannot find translation for source Jellyfin Sep 02 15:44:57 volumio volumio[15732]: Cannot find translation for source Spotify Sep 02 15:44:57 volumio sudo[15950]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 02 15:44:57 volumio sudo[15950]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:57 volumio sudo[15950]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:57 volumio volumio[15732]: info: CoreCommandRouter::volumioGetState Sep 02 15:44:57 volumio volumio[15732]: info: CorePlayQueue::getTrack 0 Sep 02 15:44:57 volumio volumio[15732]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 02 15:44:57 volumio volumio[15732]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 15:44:57 volumio volumio[15732]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 02 15:44:57 volumio volumio[15732]: 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 02 15:44:57 volumio volumio[15732]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 02 15:44:57 volumio volumio[15732]: info: camilladsp service started and running in background, instance 1 Sep 02 15:44:57 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 15:44:57 volumio volumio[15732]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 02 15:44:57 volumio volumio[15732]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 02 15:44:57 volumio volumio[15732]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 02 15:44:57 volumio volumio[15732]: info: FusionDsp loaded Sep 02 15:44:57 volumio volumio[15732]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 02 15:44:57 volumio sudo[15971]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 02 15:44:57 volumio sudo[15971]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:58 volumio sudo[15971]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:58 volumio volumio[15732]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 02 15:44:58 volumio volumio[15732]: info: Adding Signal Path Element [object Object] Sep 02 15:44:58 volumio volumio[15732]: info: Adding fusiondspeq DSP Signal Path Element Sep 02 15:44:58 volumio volumio[15732]: info: FusionDsp - ---- installed callbackRead Sep 02 15:44:58 volumio volumio[15732]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 15:44:58 volumio volumio[15732]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 02 15:44:58 volumio volumio[15732]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 02 15:44:58 volumio volumio[15732]: at onErrorNT (internal/child_process.js:465:16) Sep 02 15:44:58 volumio volumio[15732]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Sep 02 15:44:58 volumio volumio[15732]: at runNextTicks (internal/process/task_queues.js:62:3) Sep 02 15:44:58 volumio volumio[15732]: at listOnTimeout (internal/timers.js:523:9) Sep 02 15:44:58 volumio volumio[15732]: at processTimers (internal/timers.js:497:7) { Sep 02 15:44:58 volumio volumio[15732]: errno: -2, Sep 02 15:44:58 volumio volumio[15732]: code: 'ENOENT', Sep 02 15:44:58 volumio volumio[15732]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 02 15:44:58 volumio volumio[15732]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 02 15:44:58 volumio volumio[15732]: spawnargs: [ Sep 02 15:44:58 volumio volumio[15732]: '-p', Sep 02 15:44:58 volumio volumio[15732]: 9876, Sep 02 15:44:58 volumio volumio[15732]: '-o', Sep 02 15:44:58 volumio volumio[15732]: '/tmp/camilladsp.log', Sep 02 15:44:58 volumio volumio[15732]: '-l', Sep 02 15:44:58 volumio volumio[15732]: 'warn', Sep 02 15:44:58 volumio volumio[15732]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 02 15:44:58 volumio volumio[15732]: ] Sep 02 15:44:58 volumio volumio[15732]: } Sep 02 15:44:58 volumio volumio[15732]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 15:44:58 volumio sudo[15982]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-02 15:43 Sep 02 15:44:58 volumio sudo[15982]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:44:58 volumio mpd[15959]: Sep 02 15:44 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 02 15:44:58 volumio systemd[1]: Started Music Player Daemon. Sep 02 15:44:58 volumio sudo[15879]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:58 volumio sudo[15948]: pam_unix(sudo:session): session closed for user root Sep 02 15:44:58 volumio sudo[15865]: 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"