-- Logs begin at Wed 2025-09-03 08:38:47 CEST, end at Wed 2025-09-03 09:09:36 CEST. -- Sep 03 09:08:00 volumio volumio[15922]: info: Plugin airplay_emulation is not enabled Sep 03 09:08:00 volumio volumio[15922]: info: Loading plugin "last_100"... Sep 03 09:08:00 volumio volumio[15922]: info: Loading plugin "webradio"... Sep 03 09:08:00 volumio volumio[15922]: info: Loading plugin "i2s_dacs"... Sep 03 09:08:00 volumio volumio[15922]: info: Loading plugin "volumiodiscovery"... Sep 03 09:08:00 volumio volumio[15922]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 03 09:08:00 volumio volumio[15922]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 09:08:00 volumio volumio[15922]: *** WARNING *** For more information see Sep 03 09:08:00 volumio node[15922]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 03 09:08:00 volumio volumio[15922]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 03 09:08:00 volumio volumio[15922]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 09:08:00 volumio volumio[15922]: *** WARNING *** For more information see Sep 03 09:08:00 volumio node[15922]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 09:08:00 volumio node[15922]: *** WARNING *** For more information see Sep 03 09:08:00 volumio node[15922]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 03 09:08:00 volumio node[15922]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 09:08:00 volumio node[15922]: *** WARNING *** For more information see Sep 03 09:08:00 volumio volumio[15922]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 03 09:08:00 volumio volumio[15922]: info: Discovery: Started advertising with name: Volumio Sep 03 09:08:00 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 09:08:00 volumio volumio[15922]: info: Loading plugin "jellyfin"... Sep 03 09:08:01 volumio volumio[15922]: info: Loading plugin "spop"... Sep 03 09:08:02 volumio volumio[15922]: info: Loading plugin "ytcr"... Sep 03 09:08:06 volumio volumio-remote-updater[504]: [2025-09-03 09:08:06] [connect] Successful connection Sep 03 09:08:06 volumio volumio[15922]: info: Loading plugin "outputs"... Sep 03 09:08:06 volumio volumio[15922]: info: Loading plugin "albumart"... Sep 03 09:08:06 volumio volumio[15922]: info: Plugin example_plugin is not enabled Sep 03 09:08:06 volumio volumio[15922]: info: Loading plugin "inputs"... Sep 03 09:08:06 volumio volumio[15922]: info: Loading plugin "updater_comm"... Sep 03 09:08:06 volumio volumio[15922]: info: Plugin mpdemulation is not enabled Sep 03 09:08:06 volumio volumio[15922]: info: Loading plugin "rest_api"... Sep 03 09:08:06 volumio volumio[15922]: info: Loading plugin "websocket"... Sep 03 09:08:06 volumio volumio[15922]: info: Starting Socket.io Server version 2.3.0 Sep 03 09:08:06 volumio volumio[15922]: info: Loading plugin "fusiondsp"... Sep 03 09:08:06 volumio volumio[15922]: Forking 3 albumart workers Sep 03 09:08:07 volumio volumio[15922]: info: Applying required configuration parameters for plugin fusiondsp Sep 03 09:08:07 volumio volumio[15922]: info: Loading plugin "Systeminfo"... Sep 03 09:08:07 volumio volumio[15922]: info: Loading i18n strings for locale hu Sep 03 09:08:07 volumio volumio[15922]: Updating browse sources language Sep 03 09:08:07 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 09:08:07 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 03 09:08:07 volumio volumio[15922]: info: CoreCommandRouter::initPlayerControls Sep 03 09:08:07 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:07 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:07 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:07 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:07 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:07 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:07 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:07 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:07 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 03 09:08:07 volumio volumio[15922]: Express server listening on port 3000 Sep 03 09:08:07 volumio volumio[15922]: [Metrics] WebUI: 10s 974.29ms Sep 03 09:08:07 volumio volumio[15922]: info: CoreStateMachine::resetVolumioState Sep 03 09:08:07 volumio volumio[15922]: info: CoreStateMachine::getcurrentVolume Sep 03 09:08:07 volumio volumio[15922]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 09:08:07 volumio volumio[15922]: Starting albumart workers Sep 03 09:08:07 volumio volumio[15922]: Starting albumart workers Sep 03 09:08:07 volumio volumio[15922]: info: Volumio Network Manager: Network status updated: 1 Sep 03 09:08:07 volumio volumio[15922]: Starting albumart workers Sep 03 09:08:08 volumio volumio-remote-updater[504]: [2025-09-03 09:08:08] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1756883286 101 Sep 03 09:08:08 volumio volumio[15922]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Sep 03 09:08:08 volumio volumio[15922]: info: CoreCommandRouter::volumioGetState Sep 03 09:08:08 volumio volumio[15922]: info: CorePlayQueue::getTrack 0 Sep 03 09:08:08 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 09:08:08 volumio volumio[15922]: info: VolumeController:: Volume=37 Mute =false Sep 03 09:08:08 volumio volumio[15922]: info: CoreStateMachine::pushState Sep 03 09:08:08 volumio volumio[15922]: info: CorePlayQueue::getTrack 0 Sep 03 09:08:08 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 09:08:08 volumio volumio[15922]: info: CoreCommandRouter::volumioPushState Sep 03 09:08:08 volumio volumio[15922]: info: CoreStateMachine::updateTrackBlock Sep 03 09:08:08 volumio volumio[15922]: info: CorePlayQueue::getTrackBlock Sep 03 09:08:08 volumio volumio[15922]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 09:08:08 volumio volumio[15922]: info: Setting Device type: Raspberry PI Sep 03 09:08:08 volumio volumio[15922]: info: CoreStateMachine::setRepeat false single undefined Sep 03 09:08:08 volumio volumio[15922]: info: CoreStateMachine::pushState Sep 03 09:08:08 volumio volumio[15922]: info: CorePlayQueue::getTrack 0 Sep 03 09:08:08 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 09:08:08 volumio volumio[15922]: info: CoreCommandRouter::volumioPushState Sep 03 09:08:08 volumio volumio[15922]: info: CoreStateMachine::setRandom true Sep 03 09:08:08 volumio volumio[15922]: info: CoreStateMachine::pushState Sep 03 09:08:08 volumio volumio[15922]: info: CorePlayQueue::getTrack 0 Sep 03 09:08:08 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 09:08:08 volumio volumio[15922]: info: CoreCommandRouter::volumioPushState Sep 03 09:08:08 volumio volumio[15922]: info: Listing playlists Sep 03 09:08:08 volumio volumio[15922]: info: Listing playlists Sep 03 09:08:08 volumio volumio[15922]: info: Completed loading Core Plugins Sep 03 09:08:08 volumio volumio[15922]: info: Preparing to generate the ALSA configuration file Sep 03 09:08:08 volumio volumio[15922]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227 Sep 03 09:08:08 volumio volumio[15922]: info: Discovery: Found device Volumio Sep 03 09:08:08 volumio volumio[15922]: info: CoreCommandRouter::volumioGetState Sep 03 09:08:08 volumio volumio[15922]: info: CorePlayQueue::getTrack 0 Sep 03 09:08:08 volumio volumio[15922]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 03 09:08:08 volumio volumio[15922]: info: Reading ALSA contributions from plugins. Sep 03 09:08:08 volumio volumio[15922]: info: VolumeController:: Volume=37 Mute =false Sep 03 09:08:08 volumio volumio[15922]: info: CoreStateMachine::pushState Sep 03 09:08:08 volumio volumio[15922]: info: CorePlayQueue::getTrack 0 Sep 03 09:08:08 volumio volumio[15922]: info: CoreCommandRouter::volumioPushState Sep 03 09:08:08 volumio volumio[15922]: info: Asound.conf file unchanged, so no further update is needed Sep 03 09:08:08 volumio volumio[15922]: info: Output device has changed, restarting MPD Sep 03 09:08:08 volumio sudo[16034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 09:08:08 volumio sudo[16034]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:08 volumio sudo[16034]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:08 volumio volumio[15922]: info: ___________ START PLUGINS ___________ Sep 03 09:08:08 volumio volumio[15922]: info: ControllerMpd::onStart: Initializing MPD Sep 03 09:08:08 volumio volumio[15922]: info: Creating MPD Configuration file Sep 03 09:08:08 volumio sudo[16045]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 09:08:08 volumio sudo[16045]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:08 volumio sudo[16052]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 09:08:08 volumio sudo[16052]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:08 volumio systemd[1]: Stopping Music Player Daemon... Sep 03 09:08:08 volumio volumio[15922]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 09:08:08 volumio volumio[15922]: info: [1756883288803] CoreMusicLibrary::Adding element Last_100 Sep 03 09:08:08 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 09:08:08 volumio sudo[16052]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:08 volumio volumio[15922]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 09:08:08 volumio volumio[15922]: info: [1756883288812] CoreMusicLibrary::Adding element Webradio Sep 03 09:08:08 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 09:08:08 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 09:08:08 volumio sudo[16054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 09:08:08 volumio sudo[16054]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:08 volumio volumio[15922]: info: Initializing BBC Radios Sep 03 09:08:08 volumio systemd[1]: mpd.service: Succeeded. Sep 03 09:08:08 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 09:08:08 volumio systemd[1]: Starting Music Player Daemon... Sep 03 09:08:08 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 03 09:08:08 volumio systemd[1]: mpd.service: Succeeded. Sep 03 09:08:08 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 09:08:08 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 09:08:08 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 09:08:08 volumio systemd[1]: Starting Music Player Daemon... Sep 03 09:08:08 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 09:08:08 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 03 09:08:08 volumio volumio[15922]: info: Discovery: Getting this device information Sep 03 09:08:08 volumio volumio[15922]: info: CoreCommandRouter::volumioGetState Sep 03 09:08:08 volumio volumio[15922]: info: CorePlayQueue::getTrack 0 Sep 03 09:08:08 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 09:08:08 volumio volumio[15922]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 09:08:08 volumio volumio[15922]: info: [1756883288951] CoreMusicLibrary::Adding element Jellyfin Sep 03 09:08:08 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 09:08:08 volumio volumio[15922]: Cannot find translation for source Jellyfin Sep 03 09:08:08 volumio volumio[15922]: info: [jellyfin] Initialized plugin with device info: {"id":"efb65fd7-7648-487c-b3ba-ebfb5cc10227","host":"http://192.168.0.171","name":"Volumio","type":"device","serviceName":"Volumio","state":{"status":"stop","volume":37,"mute":false,"artist":"","track":"","albumart":"http://192.168.0.171/albumart"}} Sep 03 09:08:08 volumio volumio[15922]: info: Creating Spotify config file Sep 03 09:08:08 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:09 volumio sudo[16068]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 03 09:08:09 volumio sudo[16068]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:09 volumio sudo[16068]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:09 volumio volumio[15922]: info: Loading i18n strings for locale hu Sep 03 09:08:09 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 03 09:08:09 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:09 volumio volumio[15922]: info: FusionDsp - mixtype--------------------- Hardware Sep 03 09:08:09 volumio volumio[15922]: info: Volumio Calling Home Sep 03 09:08:09 volumio volumio[15922]: info: Preparing to generate the ALSA configuration file Sep 03 09:08:09 volumio volumio[15922]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 03 09:08:09 volumio volumio[15922]: info: Reading ALSA contributions from plugins. Sep 03 09:08:09 volumio volumio[15922]: info: MPD Permissions set Sep 03 09:08:09 volumio volumio[15922]: info: Spotify config file written Sep 03 09:08:09 volumio sudo[16089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 03 09:08:09 volumio sudo[16089]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:09 volumio volumio[15922]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Sep 03 09:08:09 volumio volumio[15922]: info: Volumio called home Sep 03 09:08:09 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:09 volumio systemd[1]: Stopping go-librespot Daemon... Sep 03 09:08:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 03 09:08:09 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 03 09:08:09 volumio systemd[1]: Stopped go-librespot Daemon. Sep 03 09:08:09 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 09:08:09 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:09 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:09 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:09 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:09 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:09 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:09 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:09 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:09 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:09 volumio systemd[1]: Started go-librespot Daemon. Sep 03 09:08:09 volumio go-librespot[16095]: go-librespot daemon starting... Sep 03 09:08:09 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 09:08:09 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:09 volumio volumio[15922]: info: No need to fix Spotify hosts Sep 03 09:08:09 volumio sudo[16089]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:09 volumio go-librespot[16095]: time="2025-09-03T09:08:09+02:00" level=info msg="running go-librespot 0.3.2" Sep 03 09:08:09 volumio go-librespot[16095]: time="2025-09-03T09:08:09+02:00" level=debug msg="app state loaded" Sep 03 09:08:09 volumio go-librespot[16095]: time="2025-09-03T09:08:09+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 03 09:08:09 volumio sudo[16107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 03 09:08:09 volumio sudo[16107]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:09 volumio sudo[16107]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:09 volumio sudo[16111]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 09:08:09 volumio sudo[16111]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:09 volumio sudo[16111]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:09 volumio go-librespot[16095]: time="2025-09-03T09:08:09+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Sep 03 09:08:09 volumio go-librespot[16095]: time="2025-09-03T09:08:09+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 03 09:08:09 volumio go-librespot[16095]: time="2025-09-03T09:08:09+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 03 09:08:09 volumio go-librespot[16095]: time="2025-09-03T09:08:09+02:00" level=info msg="zeroconf server listening on port 33987" Sep 03 09:08:09 volumio volumio[15922]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 03 09:08:09 volumio volumio[15922]: SPOTIFY: BQC70z7-m1Gn2XylCqrBLuphQbzitXHmHgHGMfPYM6R1xpbmA8COjbEaqMZ3aHNnu_scvBtc0N5sYsFH9ufpZmzkkJUvpZzPWday4b5EMkTeM-K13m8WDBlLKlccihkTck3eF22tw4Q1cE88LRaCudTgoBTPFQbSD0Ot2aImE2KnQnXCRpndGABXUEPAcVEQVZ21rAIrjo39mGZTQIDzyCVZBq-Pb4uI6e_x-6CMGWjsP9pOmaiC5iO7_CZmSbxXMQhf4hkoitUYUUun-q_kViFqexR0sVdrHlIJk6_szArAn1A Sep 03 09:08:09 volumio volumio[15922]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 03 09:08:09 volumio volumio[15922]: info: New Spotify access token = BQC70z7-m1Gn2XylCqrBLuphQbzitXHmHgHGMfPYM6R1xpbmA8COjbEaqMZ3aHNnu_scvBtc0N5sYsFH9ufpZmzkkJUvpZzPWday4b5EMkTeM-K13m8WDBlLKlccihkTck3eF22tw4Q1cE88LRaCudTgoBTPFQbSD0Ot2aImE2KnQnXCRpndGABXUEPAcVEQVZ21rAIrjo39mGZTQIDzyCVZBq-Pb4uI6e_x-6CMGWjsP9pOmaiC5iO7_CZmSbxXMQhf4hkoitUYUUun-q_kViFqexR0sVdrHlIJk6_szArAn1A Sep 03 09:08:09 volumio volumio[15922]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 03 09:08:09 volumio volumio[15922]: info: CoreCommandRouter::volumioGetState Sep 03 09:08:09 volumio volumio[15922]: info: CorePlayQueue::getTrack 0 Sep 03 09:08:10 volumio go-librespot[16095]: time="2025-09-03T09:08:10+02:00" level=debug msg="obtained new client token: AADfXvDXx5VghxV6f3q50Y9cTzGj41ycTsE0OwE4pB1ehrSSlEmDzcHonjMTad70H5eT1KAisXLjkwzsWu0aVcQ/PmBI2gc/7eKrDvY7ZaiLz2YTo5Ob5tcL2qtZDADjekpdYXl4HhTWS35Gekj3vEyPuXjhgUlH4rVq2iyVNQyd7JvFPj4Eei+iIQtvA1edTV5C3C1ztA6tPvTjTy2oHuGOfX4oY/SB7zjGvxBU9YwbMVPtRAXbZgARwGE=" Sep 03 09:08:10 volumio volumio[15922]: info: CoreCommandRouter::volumioGetState Sep 03 09:08:10 volumio volumio[15922]: info: CorePlayQueue::getTrack 0 Sep 03 09:08:10 volumio go-librespot[16095]: time="2025-09-03T09:08:10+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 03 09:08:10 volumio volumio[15922]: info: Asound.conf file unchanged, so no further update is needed Sep 03 09:08:10 volumio volumio[15922]: info: Output device has changed, restarting MPD Sep 03 09:08:10 volumio go-librespot[16095]: time="2025-09-03T09:08:10+02:00" level=debug msg="completed keyexchange" Sep 03 09:08:10 volumio go-librespot[16095]: time="2025-09-03T09:08:10+02:00" level=debug msg="completed challenge" Sep 03 09:08:10 volumio sudo[16115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 09:08:10 volumio sudo[16115]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:10 volumio sudo[16115]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:10 volumio go-librespot[16095]: time="2025-09-03T09:08:10+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 03 09:08:10 volumio sudo[16118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 09:08:10 volumio sudo[16118]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:10 volumio volumio[15922]: info: MPD Permissions set Sep 03 09:08:10 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 03 09:08:10 volumio systemd[1]: mpd.service: Succeeded. Sep 03 09:08:10 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 09:08:10 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:10 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:10 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:10 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:10 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:10 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:10 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:10 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:10 volumio sudo[16121]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 03 09:08:10 volumio sudo[16121]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:10 volumio go-librespot[16095]: time="2025-09-03T09:08:10+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 03 09:08:10 volumio go-librespot[16095]: time="2025-09-03T09:08:10+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 03 09:08:10 volumio systemd[1]: Starting Music Player Daemon... Sep 03 09:08:10 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 09:08:10 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:10 volumio sudo[16121]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:10 volumio volumio[15922]: info: Upmpdcli Daemon Started Sep 03 09:08:10 volumio go-librespot[16095]: time="2025-09-03T09:08:10+02:00" level=debug msg="dealer connection opened" Sep 03 09:08:10 volumio go-librespot[16095]: time="2025-09-03T09:08:10+02:00" level=trace msg="starting accesspoint recv loop" Sep 03 09:08:10 volumio go-librespot[16095]: time="2025-09-03T09:08:10+02:00" level=trace msg="starting dealer recv loop" Sep 03 09:08:10 volumio go-librespot[16095]: time="2025-09-03T09:08:10+02:00" level=trace msg="received accesspoint ping" Sep 03 09:08:10 volumio go-librespot[16095]: time="2025-09-03T09:08:10+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 03 09:08:10 volumio go-librespot[16095]: time="2025-09-03T09:08:10+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 03 09:08:10 volumio go-librespot[16095]: time="2025-09-03T09:08:10+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 03 09:08:10 volumio go-librespot[16095]: time="2025-09-03T09:08:10+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 03 09:08:10 volumio go-librespot[16095]: time="2025-09-03T09:08:10+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 03 09:08:10 volumio go-librespot[16095]: time="2025-09-03T09:08:10+02:00" level=debug msg="received connection id: OTQyMWNiN2ItOGU0...ODA0QUVBQTNFRQ==" Sep 03 09:08:10 volumio go-librespot[16095]: time="2025-09-03T09:08:10+02:00" level=trace msg="received accesspoint pong ack" Sep 03 09:08:10 volumio volumio[15922]: SPOTIFY: User informations: {"country":"HU","display_name":"4Misi","email":"sztako.mihaly@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/lo8jr34h0xh1vbcv2lgmaqkvv"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/lo8jr34h0xh1vbcv2lgmaqkvv","id":"lo8jr34h0xh1vbcv2lgmaqkvv","images":[],"product":"premium","type":"user","uri":"spotify:user:lo8jr34h0xh1vbcv2lgmaqkvv"} Sep 03 09:08:10 volumio volumio[15922]: info: Spotify Successfully logged in Sep 03 09:08:10 volumio volumio[15922]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 09:08:10 volumio volumio[15922]: info: [1756883290370] CoreMusicLibrary::Adding element Spotify Sep 03 09:08:10 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 09:08:10 volumio volumio[15922]: Cannot find translation for source Jellyfin Sep 03 09:08:10 volumio volumio[15922]: Cannot find translation for source Spotify Sep 03 09:08:10 volumio sudo[16122]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 03 09:08:10 volumio sudo[16122]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:10 volumio sudo[16122]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:10 volumio go-librespot[16095]: time="2025-09-03T09:08:10+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 03 09:08:10 volumio volumio[15922]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 03 09:08:10 volumio volumio[15922]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 09:08:11 volumio volumio[15922]: info: VolumeController:: Volume=37 Mute =false Sep 03 09:08:11 volumio volumio[15922]: info: CoreCommandRouter::volumioGetState Sep 03 09:08:11 volumio volumio[15922]: info: CorePlayQueue::getTrack 0 Sep 03 09:08:11 volumio volumio[15922]: info: CoreStateMachine::pushState Sep 03 09:08:11 volumio volumio[15922]: info: CorePlayQueue::getTrack 0 Sep 03 09:08:11 volumio volumio[15922]: info: CoreCommandRouter::volumioPushState Sep 03 09:08:11 volumio volumio[15922]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 03 09:08:11 volumio volumio[15922]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Sep 03 09:08:11 volumio volumio[15922]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 03 09:08:11 volumio volumio[15922]: info: camilladsp service started and running in background, instance 1 Sep 03 09:08:11 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:11 volumio volumio[15922]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 03 09:08:11 volumio volumio[15922]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 03 09:08:11 volumio volumio[15922]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 03 09:08:11 volumio volumio[15922]: info: FusionDsp loaded Sep 03 09:08:11 volumio volumio[15922]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 09:08:11 volumio sudo[16143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 03 09:08:11 volumio sudo[16143]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:11 volumio sudo[16143]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:11 volumio volumio[15922]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 03 09:08:11 volumio volumio[15922]: info: Adding Signal Path Element [object Object] Sep 03 09:08:11 volumio volumio[15922]: info: Adding fusiondspeq DSP Signal Path Element Sep 03 09:08:11 volumio volumio[15922]: info: FusionDsp - ---- installed callbackRead Sep 03 09:08:11 volumio volumio[15922]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 09:08:11 volumio volumio[15922]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 03 09:08:11 volumio volumio[15922]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 03 09:08:11 volumio volumio[15922]: at onErrorNT (internal/child_process.js:465:16) Sep 03 09:08:11 volumio volumio[15922]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Sep 03 09:08:11 volumio volumio[15922]: errno: -2, Sep 03 09:08:11 volumio volumio[15922]: code: 'ENOENT', Sep 03 09:08:11 volumio volumio[15922]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 03 09:08:11 volumio volumio[15922]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 03 09:08:11 volumio volumio[15922]: spawnargs: [ Sep 03 09:08:11 volumio volumio[15922]: '-p', Sep 03 09:08:11 volumio volumio[15922]: 9876, Sep 03 09:08:11 volumio volumio[15922]: '-o', Sep 03 09:08:11 volumio volumio[15922]: '/tmp/camilladsp.log', Sep 03 09:08:11 volumio volumio[15922]: '-l', Sep 03 09:08:11 volumio volumio[15922]: 'warn', Sep 03 09:08:11 volumio volumio[15922]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 03 09:08:11 volumio volumio[15922]: ] Sep 03 09:08:11 volumio volumio[15922]: } Sep 03 09:08:11 volumio volumio[15922]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 09:08:11 volumio mpd[16130]: Sep 03 09:08 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 03 09:08:11 volumio systemd[1]: Started Music Player Daemon. Sep 03 09:08:11 volumio sudo[16054]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:11 volumio sudo[16118]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:11 volumio sudo[16045]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:12 volumio sudo[16156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-03 09:07 Sep 03 09:08:12 volumio sudo[16156]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:12 volumio sudo[16156]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:12 volumio volumio-remote-updater[504]: [2025-09-03 09:08:12] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 03 09:08:12 volumio volumio-remote-updater[504]: [2025-09-03 09:08:12] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 03 09:08:12 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 03 09:08:12 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 03 09:08:12 volumio systemd[1]: Started dynamicswap service. Sep 03 09:08:12 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 03 09:08:12 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 03 09:08:12 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 7444. Sep 03 09:08:12 volumio systemd[1]: Started dynamicswap service. Sep 03 09:08:12 volumio systemd[1]: Stopped Volumio Backend Module. Sep 03 09:08:12 volumio systemd[1]: Started Volumio Backend Module. Sep 03 09:08:12 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 03 09:08:14 volumio volumio[16167]: info: ------------------------------------------- Sep 03 09:08:14 volumio volumio[16167]: info: ----- Volumio3 ---- Sep 03 09:08:14 volumio volumio[16167]: info: ------------------------------------------- Sep 03 09:08:14 volumio volumio[16167]: info: ----- System startup ---- Sep 03 09:08:14 volumio volumio[16167]: info: ------------------------------------------- Sep 03 09:08:15 volumio volumio[16167]: info: MYVOLUMIO Environment detected Sep 03 09:08:15 volumio volumio[16167]: info: Plugin folders cleanup Sep 03 09:08:15 volumio volumio[16167]: info: Scanning into folder /volumio/app/plugins/ Sep 03 09:08:15 volumio volumio[16167]: info: Scanning category audio_interface Sep 03 09:08:15 volumio volumio[16167]: info: Scanning category miscellanea Sep 03 09:08:15 volumio volumio[16167]: info: Scanning category music_service Sep 03 09:08:15 volumio volumio[16167]: info: Scanning category plugins.json Sep 03 09:08:15 volumio volumio[16167]: info: Scanning category system_controller Sep 03 09:08:15 volumio volumio[16167]: info: Scanning category user_interface Sep 03 09:08:15 volumio volumio[16167]: info: Scanning into folder /data/plugins/ Sep 03 09:08:15 volumio volumio[16167]: info: Scanning category audio_interface Sep 03 09:08:15 volumio volumio[16167]: info: Scanning category music_service Sep 03 09:08:15 volumio volumio[16167]: info: Scanning category user_interface Sep 03 09:08:15 volumio volumio[16167]: info: Plugin folders cleanup completed Sep 03 09:08:15 volumio volumio[16167]: info: ------------------------------------------- Sep 03 09:08:15 volumio volumio[16167]: info: ----- Core plugins startup ---- Sep 03 09:08:15 volumio volumio[16167]: info: ------------------------------------------- Sep 03 09:08:15 volumio volumio[16167]: info: Loading plugins from folder /volumio/app/plugins/ Sep 03 09:08:15 volumio volumio[16167]: info: Adding plugin upnp to MyMusic Plugins Sep 03 09:08:15 volumio volumio[16167]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 03 09:08:15 volumio volumio[16167]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 03 09:08:15 volumio volumio[16167]: info: Loading plugins from folder /data/plugins/ Sep 03 09:08:15 volumio volumio[16167]: info: Loading plugin "system"... Sep 03 09:08:15 volumio volumio[16167]: info: Loading plugin "appearance"... Sep 03 09:08:16 volumio volumio[16167]: info: Loading plugin "network"... Sep 03 09:08:16 volumio volumio[16167]: info: Refreshing Cached IP Addresses Sep 03 09:08:16 volumio sudo[16231]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 03 09:08:16 volumio sudo[16231]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:16 volumio sudo[16231]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:16 volumio volumio[16167]: info: Loading plugin "services"... Sep 03 09:08:16 volumio sudo[16233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 09:08:16 volumio sudo[16233]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:16 volumio volumio[16167]: info: Loading plugin "alsa_controller"... Sep 03 09:08:16 volumio sudo[16233]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:16 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 09:08:16 volumio volumio[16167]: info: Loading plugin "wizard"... Sep 03 09:08:16 volumio volumio[16167]: info: Loading plugin "networkfs"... Sep 03 09:08:16 volumio volumio[16167]: info: Starting Udev Watcher for removable devices Sep 03 09:08:16 volumio volumio[16167]: info: Ignoring mount for partition: boot Sep 03 09:08:16 volumio volumio[16167]: info: Ignoring mount for partition: volumio Sep 03 09:08:16 volumio volumio[16167]: info: Ignoring mount for partition: volumio_data Sep 03 09:08:16 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 09:08:16 volumio volumio[16167]: info: Loading plugin "volumio_command_line_client"... Sep 03 09:08:16 volumio volumio[16167]: info: Loading plugin "upnp"... Sep 03 09:08:16 volumio volumio[16167]: info: [1756883296585] Starting Upmpd Daemon Sep 03 09:08:16 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 09:08:16 volumio volumio[16167]: info: Loading plugin "my_music"... Sep 03 09:08:16 volumio volumio[16167]: info: Loading plugin "mpd"... Sep 03 09:08:16 volumio volumio[16167]: info: Plugin upnp_browser is not enabled Sep 03 09:08:16 volumio volumio[16167]: info: Loading plugin "alarm-clock"... Sep 03 09:08:17 volumio volumio[16167]: info: Plugin airplay_emulation is not enabled Sep 03 09:08:17 volumio volumio[16167]: info: Loading plugin "last_100"... Sep 03 09:08:17 volumio volumio[16167]: info: Loading plugin "webradio"... Sep 03 09:08:17 volumio volumio[16167]: info: Loading plugin "i2s_dacs"... Sep 03 09:08:17 volumio volumio[16167]: info: Loading plugin "volumiodiscovery"... Sep 03 09:08:17 volumio volumio-remote-updater[504]: [2025-09-03 09:08:17] [connect] Successful connection Sep 03 09:08:17 volumio volumio[16167]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 03 09:08:17 volumio volumio[16167]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 09:08:17 volumio volumio[16167]: *** WARNING *** For more information see Sep 03 09:08:17 volumio volumio[16167]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 03 09:08:17 volumio node[16167]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 03 09:08:17 volumio volumio[16167]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 09:08:17 volumio volumio[16167]: *** WARNING *** For more information see Sep 03 09:08:17 volumio node[16167]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 09:08:17 volumio node[16167]: *** WARNING *** For more information see Sep 03 09:08:17 volumio node[16167]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 03 09:08:17 volumio node[16167]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 09:08:17 volumio node[16167]: *** WARNING *** For more information see Sep 03 09:08:17 volumio volumio[16167]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 03 09:08:17 volumio volumio[16167]: info: Discovery: Started advertising with name: Volumio Sep 03 09:08:17 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 09:08:17 volumio volumio[16167]: info: Loading plugin "jellyfin"... Sep 03 09:08:18 volumio volumio[16167]: info: Loading plugin "spop"... Sep 03 09:08:19 volumio volumio[16167]: info: Loading plugin "ytcr"... Sep 03 09:08:23 volumio volumio[16167]: info: Loading plugin "outputs"... Sep 03 09:08:23 volumio volumio[16167]: info: Loading plugin "albumart"... Sep 03 09:08:23 volumio volumio[16167]: info: Plugin example_plugin is not enabled Sep 03 09:08:23 volumio volumio[16167]: info: Loading plugin "inputs"... Sep 03 09:08:23 volumio volumio[16167]: info: Loading plugin "updater_comm"... Sep 03 09:08:23 volumio volumio[16167]: info: Plugin mpdemulation is not enabled Sep 03 09:08:23 volumio volumio[16167]: info: Loading plugin "rest_api"... Sep 03 09:08:23 volumio volumio[16167]: info: Loading plugin "websocket"... Sep 03 09:08:23 volumio volumio[16167]: info: Starting Socket.io Server version 2.3.0 Sep 03 09:08:23 volumio volumio[16167]: info: Loading plugin "fusiondsp"... Sep 03 09:08:23 volumio volumio[16167]: Forking 3 albumart workers Sep 03 09:08:24 volumio volumio[16167]: info: Applying required configuration parameters for plugin fusiondsp Sep 03 09:08:24 volumio volumio[16167]: info: Loading plugin "Systeminfo"... Sep 03 09:08:24 volumio volumio[16167]: info: Loading i18n strings for locale hu Sep 03 09:08:24 volumio volumio[16167]: Updating browse sources language Sep 03 09:08:24 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 09:08:24 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 03 09:08:24 volumio volumio[16167]: info: CoreCommandRouter::initPlayerControls Sep 03 09:08:24 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:24 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:24 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:24 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:24 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:24 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:24 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:24 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:24 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 03 09:08:24 volumio volumio[16167]: Express server listening on port 3000 Sep 03 09:08:24 volumio volumio[16167]: [Metrics] WebUI: 10s 976.48ms Sep 03 09:08:24 volumio volumio[16167]: info: CoreStateMachine::resetVolumioState Sep 03 09:08:24 volumio volumio[16167]: info: CoreStateMachine::getcurrentVolume Sep 03 09:08:24 volumio volumio[16167]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 09:08:24 volumio volumio[16167]: Starting albumart workers Sep 03 09:08:25 volumio volumio[16167]: info: Volumio Network Manager: Network status updated: 1 Sep 03 09:08:25 volumio volumio[16167]: Starting albumart workers Sep 03 09:08:25 volumio volumio[16167]: Starting albumart workers Sep 03 09:08:25 volumio volumio[16167]: info: CoreCommandRouter::volumioGetState Sep 03 09:08:25 volumio volumio[16167]: info: CorePlayQueue::getTrack 0 Sep 03 09:08:25 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 09:08:25 volumio volumio[16167]: info: VolumeController:: Volume=37 Mute =false Sep 03 09:08:25 volumio volumio[16167]: info: CoreStateMachine::pushState Sep 03 09:08:25 volumio volumio[16167]: info: CorePlayQueue::getTrack 0 Sep 03 09:08:25 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 09:08:25 volumio volumio[16167]: info: CoreCommandRouter::volumioPushState Sep 03 09:08:25 volumio volumio[16167]: info: CoreStateMachine::updateTrackBlock Sep 03 09:08:25 volumio volumio[16167]: info: CorePlayQueue::getTrackBlock Sep 03 09:08:25 volumio volumio[16167]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 09:08:25 volumio volumio[16167]: info: CoreStateMachine::setRepeat false single undefined Sep 03 09:08:25 volumio volumio[16167]: info: CoreStateMachine::pushState Sep 03 09:08:25 volumio volumio[16167]: info: CorePlayQueue::getTrack 0 Sep 03 09:08:25 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 09:08:25 volumio volumio[16167]: info: CoreCommandRouter::volumioPushState Sep 03 09:08:25 volumio volumio[16167]: info: CoreStateMachine::setRandom true Sep 03 09:08:25 volumio volumio[16167]: info: CoreStateMachine::pushState Sep 03 09:08:25 volumio volumio[16167]: info: CorePlayQueue::getTrack 0 Sep 03 09:08:25 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 09:08:25 volumio volumio[16167]: info: CoreCommandRouter::volumioPushState Sep 03 09:08:25 volumio volumio[16167]: info: Setting Device type: Raspberry PI Sep 03 09:08:25 volumio volumio[16167]: info: Listing playlists Sep 03 09:08:25 volumio volumio[16167]: info: Listing playlists Sep 03 09:08:25 volumio volumio[16167]: info: Completed loading Core Plugins Sep 03 09:08:25 volumio volumio[16167]: info: Preparing to generate the ALSA configuration file Sep 03 09:08:25 volumio volumio[16167]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227 Sep 03 09:08:25 volumio volumio[16167]: info: Discovery: Found device Volumio Sep 03 09:08:25 volumio volumio[16167]: info: CoreCommandRouter::volumioGetState Sep 03 09:08:25 volumio volumio[16167]: info: CorePlayQueue::getTrack 0 Sep 03 09:08:25 volumio volumio[16167]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 03 09:08:25 volumio volumio[16167]: info: Reading ALSA contributions from plugins. Sep 03 09:08:25 volumio volumio[16167]: info: Asound.conf file unchanged, so no further update is needed Sep 03 09:08:25 volumio volumio[16167]: info: Output device has changed, restarting MPD Sep 03 09:08:25 volumio volumio[16167]: info: ___________ START PLUGINS ___________ Sep 03 09:08:25 volumio volumio[16167]: info: ControllerMpd::onStart: Initializing MPD Sep 03 09:08:25 volumio sudo[16303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 09:08:25 volumio volumio[16167]: info: Creating MPD Configuration file Sep 03 09:08:25 volumio sudo[16303]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:25 volumio sudo[16303]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:25 volumio sudo[16314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 09:08:25 volumio sudo[16314]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:25 volumio sudo[16325]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 09:08:25 volumio sudo[16325]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:25 volumio volumio[16167]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 09:08:25 volumio volumio[16167]: info: [1756883305796] CoreMusicLibrary::Adding element Last_100 Sep 03 09:08:25 volumio sudo[16325]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:25 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 09:08:25 volumio systemd[1]: Stopping Music Player Daemon... Sep 03 09:08:25 volumio volumio[16167]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 09:08:25 volumio volumio[16167]: info: [1756883305804] CoreMusicLibrary::Adding element Webradio Sep 03 09:08:25 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 09:08:25 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 09:08:25 volumio volumio[16167]: info: Initializing BBC Radios Sep 03 09:08:25 volumio systemd[1]: mpd.service: Succeeded. Sep 03 09:08:25 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 09:08:25 volumio sudo[16328]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 09:08:25 volumio sudo[16328]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:25 volumio systemd[1]: Starting Music Player Daemon... Sep 03 09:08:25 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 03 09:08:25 volumio systemd[1]: mpd.service: Succeeded. Sep 03 09:08:25 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 09:08:25 volumio systemd[1]: Starting Music Player Daemon... Sep 03 09:08:25 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 09:08:25 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 09:08:25 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 09:08:25 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 03 09:08:25 volumio volumio[16167]: info: Discovery: Getting this device information Sep 03 09:08:25 volumio volumio[16167]: info: CoreCommandRouter::volumioGetState Sep 03 09:08:25 volumio volumio[16167]: info: CorePlayQueue::getTrack 0 Sep 03 09:08:25 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 09:08:25 volumio volumio[16167]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 09:08:25 volumio volumio[16167]: info: [1756883305999] CoreMusicLibrary::Adding element Jellyfin Sep 03 09:08:26 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 09:08:26 volumio volumio[16167]: Cannot find translation for source Jellyfin Sep 03 09:08:26 volumio volumio[16167]: info: [jellyfin] Initialized plugin with device info: {"id":"efb65fd7-7648-487c-b3ba-ebfb5cc10227","host":"http://192.168.0.171","name":"Volumio","type":"device","serviceName":"Volumio","state":{"status":"stop","volume":37,"mute":false,"artist":"","track":"","albumart":"http://192.168.0.171/albumart"}} Sep 03 09:08:26 volumio volumio[16167]: info: Creating Spotify config file Sep 03 09:08:26 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:26 volumio sudo[16345]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 03 09:08:26 volumio sudo[16345]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:26 volumio sudo[16345]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:26 volumio volumio[16167]: info: Loading i18n strings for locale hu Sep 03 09:08:26 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 03 09:08:26 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:26 volumio volumio[16167]: info: FusionDsp - mixtype--------------------- Hardware Sep 03 09:08:26 volumio volumio[16167]: info: Volumio Calling Home Sep 03 09:08:26 volumio volumio[16167]: info: Preparing to generate the ALSA configuration file Sep 03 09:08:26 volumio volumio[16167]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 03 09:08:26 volumio volumio[16167]: info: Reading ALSA contributions from plugins. Sep 03 09:08:26 volumio volumio[16167]: info: MPD Permissions set Sep 03 09:08:26 volumio volumio[16167]: info: MPD Permissions set Sep 03 09:08:26 volumio volumio[16167]: info: VolumeController:: Volume=37 Mute =false Sep 03 09:08:26 volumio volumio[16167]: info: CoreCommandRouter::volumioGetState Sep 03 09:08:26 volumio volumio[16167]: info: CorePlayQueue::getTrack 0 Sep 03 09:08:26 volumio volumio[16167]: info: CoreStateMachine::pushState Sep 03 09:08:26 volumio volumio[16167]: info: CorePlayQueue::getTrack 0 Sep 03 09:08:26 volumio volumio[16167]: info: CoreCommandRouter::volumioPushState Sep 03 09:08:26 volumio volumio[16167]: info: Volumio called home Sep 03 09:08:26 volumio volumio[16167]: info: Spotify config file written Sep 03 09:08:26 volumio sudo[16369]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 03 09:08:26 volumio sudo[16369]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:26 volumio volumio[16167]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Sep 03 09:08:26 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:26 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:26 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:26 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:26 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:26 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:26 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:26 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:26 volumio systemd[1]: Stopping go-librespot Daemon... Sep 03 09:08:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 03 09:08:26 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 03 09:08:26 volumio systemd[1]: Stopped go-librespot Daemon. Sep 03 09:08:26 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 09:08:26 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:26 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:26 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:26 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:26 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:26 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:26 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:26 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:26 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:26 volumio systemd[1]: Started go-librespot Daemon. Sep 03 09:08:26 volumio go-librespot[16376]: go-librespot daemon starting... Sep 03 09:08:26 volumio sudo[16369]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:26 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 09:08:26 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:26 volumio volumio[16167]: info: No need to fix Spotify hosts Sep 03 09:08:26 volumio go-librespot[16376]: time="2025-09-03T09:08:26+02:00" level=info msg="running go-librespot 0.3.2" Sep 03 09:08:26 volumio go-librespot[16376]: time="2025-09-03T09:08:26+02:00" level=debug msg="app state loaded" Sep 03 09:08:26 volumio go-librespot[16376]: time="2025-09-03T09:08:26+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 03 09:08:26 volumio sudo[16389]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 03 09:08:26 volumio sudo[16389]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:26 volumio go-librespot[16376]: time="2025-09-03T09:08:26+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Sep 03 09:08:26 volumio go-librespot[16376]: time="2025-09-03T09:08:26+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 03 09:08:26 volumio go-librespot[16376]: time="2025-09-03T09:08:26+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 03 09:08:26 volumio sudo[16389]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:26 volumio sudo[16391]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 09:08:26 volumio go-librespot[16376]: time="2025-09-03T09:08:26+02:00" level=info msg="zeroconf server listening on port 39995" Sep 03 09:08:26 volumio sudo[16391]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:26 volumio sudo[16391]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:27 volumio go-librespot[16376]: time="2025-09-03T09:08:27+02:00" level=debug msg="obtained new client token: AABmAEbKwJASSIaK02S/7htnzrK3HCuQ228OFoNxg7ZkOATbLJy+REfJ4VssZQzW3wwNSa74ZoQ9eZPKtxFWyvNDcRE5RF5cJdIpCEMX94LmfU0Lbgi36wMycrKEgHZSVMiZnwTP1rA7mVmKiAT04iDZ6gMwOGW8ic2j69OHDsklKuUZ7eYQqmnPdbG0E63wKvS8aVjnw257HWHjD12Nh4qwb+d/mcXJ+/n5mD/Sxr/XjsZcQaSO4oDmW0U=" Sep 03 09:08:27 volumio go-librespot[16376]: time="2025-09-03T09:08:27+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 03 09:08:27 volumio go-librespot[16376]: time="2025-09-03T09:08:27+02:00" level=debug msg="completed keyexchange" Sep 03 09:08:27 volumio go-librespot[16376]: time="2025-09-03T09:08:27+02:00" level=debug msg="completed challenge" Sep 03 09:08:27 volumio go-librespot[16376]: time="2025-09-03T09:08:27+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 03 09:08:27 volumio volumio[16167]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 03 09:08:27 volumio volumio[16167]: SPOTIFY: BQD-IlvYNgFQ-eYV8vcFbaoFwiPXI7FTRBAqBgR4MDgs8AVQ_YWZQdI8sNskkDWKmqRThtAqUsLCsyQVw_Ih8b2TcrT3W-3nfNiJdeCJG8ijGAA5Nav6u2_x1vQnytECPnSZlNjA-9GDH1DCSIRV-25N_mb6Fex2EQBtbd2E6FUcm2c1v-kd6IGcjNp89HLLNawqoAdhyWjYKSCpUKLdLP-ll6sEXcnJh3gvuS4EpYAzdrvau_rQw1HH6eIgmfilHRU52Fho5NMD2I9LbcstvADkp1FLKT6PljJS1r4RoxqzYoE Sep 03 09:08:27 volumio volumio[16167]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 03 09:08:27 volumio volumio[16167]: info: New Spotify access token = BQD-IlvYNgFQ-eYV8vcFbaoFwiPXI7FTRBAqBgR4MDgs8AVQ_YWZQdI8sNskkDWKmqRThtAqUsLCsyQVw_Ih8b2TcrT3W-3nfNiJdeCJG8ijGAA5Nav6u2_x1vQnytECPnSZlNjA-9GDH1DCSIRV-25N_mb6Fex2EQBtbd2E6FUcm2c1v-kd6IGcjNp89HLLNawqoAdhyWjYKSCpUKLdLP-ll6sEXcnJh3gvuS4EpYAzdrvau_rQw1HH6eIgmfilHRU52Fho5NMD2I9LbcstvADkp1FLKT6PljJS1r4RoxqzYoE Sep 03 09:08:27 volumio volumio[16167]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 03 09:08:27 volumio volumio[16167]: info: CoreCommandRouter::volumioGetState Sep 03 09:08:27 volumio volumio[16167]: info: CorePlayQueue::getTrack 0 Sep 03 09:08:27 volumio go-librespot[16376]: time="2025-09-03T09:08:27+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 03 09:08:27 volumio go-librespot[16376]: time="2025-09-03T09:08:27+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 03 09:08:27 volumio volumio[16167]: info: Asound.conf file unchanged, so no further update is needed Sep 03 09:08:27 volumio volumio[16167]: info: Output device has changed, restarting MPD Sep 03 09:08:27 volumio go-librespot[16376]: time="2025-09-03T09:08:27+02:00" level=debug msg="dealer connection opened" Sep 03 09:08:27 volumio go-librespot[16376]: time="2025-09-03T09:08:27+02:00" level=trace msg="starting accesspoint recv loop" Sep 03 09:08:27 volumio go-librespot[16376]: time="2025-09-03T09:08:27+02:00" level=trace msg="starting dealer recv loop" Sep 03 09:08:27 volumio go-librespot[16376]: time="2025-09-03T09:08:27+02:00" level=trace msg="received accesspoint ping" Sep 03 09:08:27 volumio go-librespot[16376]: time="2025-09-03T09:08:27+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 03 09:08:27 volumio go-librespot[16376]: time="2025-09-03T09:08:27+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 03 09:08:27 volumio go-librespot[16376]: time="2025-09-03T09:08:27+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 03 09:08:27 volumio go-librespot[16376]: time="2025-09-03T09:08:27+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 03 09:08:27 volumio go-librespot[16376]: time="2025-09-03T09:08:27+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 03 09:08:27 volumio go-librespot[16376]: time="2025-09-03T09:08:27+02:00" level=debug msg="received connection id: YzM4ZTkyZGUtMDA3...QzI5MjAzNUM4OA==" Sep 03 09:08:27 volumio go-librespot[16376]: time="2025-09-03T09:08:27+02:00" level=trace msg="received accesspoint pong ack" Sep 03 09:08:27 volumio sudo[16396]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 09:08:27 volumio sudo[16396]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:27 volumio sudo[16396]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:27 volumio sudo[16398]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 09:08:27 volumio sudo[16398]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:27 volumio volumio[16167]: info: MPD Permissions set Sep 03 09:08:27 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 03 09:08:27 volumio systemd[1]: mpd.service: Succeeded. Sep 03 09:08:27 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 09:08:27 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:27 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:27 volumio go-librespot[16376]: time="2025-09-03T09:08:27+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 03 09:08:27 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:27 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:27 volumio sudo[16403]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 03 09:08:27 volumio sudo[16403]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:27 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 09:08:27 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:27 volumio systemd[1]: Starting Music Player Daemon... Sep 03 09:08:27 volumio sudo[16403]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:27 volumio volumio[16167]: info: Upmpdcli Daemon Started Sep 03 09:08:27 volumio volumio[16167]: SPOTIFY: User informations: {"country":"HU","display_name":"4Misi","email":"sztako.mihaly@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/lo8jr34h0xh1vbcv2lgmaqkvv"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/lo8jr34h0xh1vbcv2lgmaqkvv","id":"lo8jr34h0xh1vbcv2lgmaqkvv","images":[],"product":"premium","type":"user","uri":"spotify:user:lo8jr34h0xh1vbcv2lgmaqkvv"} Sep 03 09:08:27 volumio volumio[16167]: info: Spotify Successfully logged in Sep 03 09:08:27 volumio volumio[16167]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 09:08:27 volumio volumio[16167]: info: [1756883307569] CoreMusicLibrary::Adding element Spotify Sep 03 09:08:27 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 09:08:27 volumio volumio[16167]: Cannot find translation for source Jellyfin Sep 03 09:08:27 volumio volumio[16167]: Cannot find translation for source Spotify Sep 03 09:08:27 volumio sudo[16402]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 03 09:08:27 volumio sudo[16402]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:27 volumio sudo[16402]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:28 volumio volumio[16167]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 03 09:08:28 volumio volumio[16167]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 03 09:08:28 volumio volumio[16167]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Sep 03 09:08:28 volumio volumio[16167]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 03 09:08:28 volumio volumio[16167]: info: camilladsp service started and running in background, instance 1 Sep 03 09:08:28 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:28 volumio volumio[16167]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 03 09:08:28 volumio volumio[16167]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 03 09:08:28 volumio volumio[16167]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 03 09:08:28 volumio volumio[16167]: info: FusionDsp loaded Sep 03 09:08:28 volumio volumio[16167]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 09:08:28 volumio sudo[16421]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 03 09:08:28 volumio sudo[16421]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:28 volumio volumio[16167]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 03 09:08:28 volumio volumio[16167]: info: Adding Signal Path Element [object Object] Sep 03 09:08:28 volumio volumio[16167]: info: Adding fusiondspeq DSP Signal Path Element Sep 03 09:08:28 volumio volumio[16167]: info: FusionDsp - ---- installed callbackRead Sep 03 09:08:28 volumio sudo[16421]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:28 volumio volumio[16167]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 09:08:28 volumio volumio[16167]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 03 09:08:28 volumio volumio[16167]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 03 09:08:28 volumio volumio[16167]: at onErrorNT (internal/child_process.js:465:16) Sep 03 09:08:28 volumio volumio[16167]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Sep 03 09:08:28 volumio volumio[16167]: errno: -2, Sep 03 09:08:28 volumio volumio[16167]: code: 'ENOENT', Sep 03 09:08:28 volumio volumio[16167]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 03 09:08:28 volumio volumio[16167]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 03 09:08:28 volumio volumio[16167]: spawnargs: [ Sep 03 09:08:28 volumio volumio[16167]: '-p', Sep 03 09:08:28 volumio volumio[16167]: 9876, Sep 03 09:08:28 volumio volumio[16167]: '-o', Sep 03 09:08:28 volumio volumio[16167]: '/tmp/camilladsp.log', Sep 03 09:08:28 volumio volumio[16167]: '-l', Sep 03 09:08:28 volumio volumio[16167]: 'warn', Sep 03 09:08:28 volumio volumio[16167]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 03 09:08:28 volumio volumio[16167]: ] Sep 03 09:08:28 volumio volumio[16167]: } Sep 03 09:08:28 volumio volumio[16167]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 09:08:29 volumio sudo[16432]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-03 09:07 Sep 03 09:08:29 volumio sudo[16432]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:29 volumio mpd[16410]: Sep 03 09:08 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 03 09:08:29 volumio systemd[1]: Started Music Player Daemon. Sep 03 09:08:29 volumio sudo[16328]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:29 volumio sudo[16314]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:29 volumio sudo[16398]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:29 volumio sudo[16432]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:29 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 03 09:08:29 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 03 09:08:29 volumio systemd[1]: Started dynamicswap service. Sep 03 09:08:29 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 03 09:08:29 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 03 09:08:29 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 7445. Sep 03 09:08:29 volumio systemd[1]: Started dynamicswap service. Sep 03 09:08:29 volumio systemd[1]: Stopped Volumio Backend Module. Sep 03 09:08:29 volumio systemd[1]: Started Volumio Backend Module. Sep 03 09:08:29 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 03 09:08:29 volumio volumio-remote-updater[504]: [2025-09-03 09:08:29] [info] asio async_connect error: system:111 (Connection refused) Sep 03 09:08:29 volumio volumio-remote-updater[504]: [2025-09-03 09:08:29] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 03 09:08:29 volumio volumio-remote-updater[504]: [2025-09-03 09:08:29] [error] handle_connect error: Underlying Transport Error Sep 03 09:08:31 volumio volumio[16445]: info: ------------------------------------------- Sep 03 09:08:31 volumio volumio[16445]: info: ----- Volumio3 ---- Sep 03 09:08:31 volumio volumio[16445]: info: ------------------------------------------- Sep 03 09:08:31 volumio volumio[16445]: info: ----- System startup ---- Sep 03 09:08:31 volumio volumio[16445]: info: ------------------------------------------- Sep 03 09:08:31 volumio volumio[16445]: info: MYVOLUMIO Environment detected Sep 03 09:08:31 volumio volumio[16445]: info: Plugin folders cleanup Sep 03 09:08:31 volumio volumio[16445]: info: Scanning into folder /volumio/app/plugins/ Sep 03 09:08:31 volumio volumio[16445]: info: Scanning category audio_interface Sep 03 09:08:31 volumio volumio[16445]: info: Scanning category miscellanea Sep 03 09:08:31 volumio volumio[16445]: info: Scanning category music_service Sep 03 09:08:31 volumio volumio[16445]: info: Scanning category plugins.json Sep 03 09:08:31 volumio volumio[16445]: info: Scanning category system_controller Sep 03 09:08:31 volumio volumio[16445]: info: Scanning category user_interface Sep 03 09:08:31 volumio volumio[16445]: info: Scanning into folder /data/plugins/ Sep 03 09:08:31 volumio volumio[16445]: info: Scanning category audio_interface Sep 03 09:08:31 volumio volumio[16445]: info: Scanning category music_service Sep 03 09:08:31 volumio volumio[16445]: info: Scanning category user_interface Sep 03 09:08:31 volumio volumio[16445]: info: Plugin folders cleanup completed Sep 03 09:08:31 volumio volumio[16445]: info: ------------------------------------------- Sep 03 09:08:31 volumio volumio[16445]: info: ----- Core plugins startup ---- Sep 03 09:08:31 volumio volumio[16445]: info: ------------------------------------------- Sep 03 09:08:31 volumio volumio[16445]: info: Loading plugins from folder /volumio/app/plugins/ Sep 03 09:08:31 volumio volumio[16445]: info: Adding plugin upnp to MyMusic Plugins Sep 03 09:08:31 volumio volumio[16445]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 03 09:08:31 volumio volumio[16445]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 03 09:08:32 volumio volumio[16445]: info: Loading plugins from folder /data/plugins/ Sep 03 09:08:32 volumio volumio[16445]: info: Loading plugin "system"... Sep 03 09:08:32 volumio volumio[16445]: info: Loading plugin "appearance"... Sep 03 09:08:33 volumio volumio[16445]: info: Loading plugin "network"... Sep 03 09:08:33 volumio volumio[16445]: info: Refreshing Cached IP Addresses Sep 03 09:08:33 volumio sudo[16472]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 03 09:08:33 volumio sudo[16472]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:33 volumio sudo[16472]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:33 volumio sudo[16474]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 09:08:33 volumio volumio[16445]: info: Loading plugin "services"... Sep 03 09:08:33 volumio sudo[16474]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:33 volumio volumio[16445]: info: Loading plugin "alsa_controller"... Sep 03 09:08:33 volumio sudo[16474]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:33 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 09:08:33 volumio volumio[16445]: info: Loading plugin "wizard"... Sep 03 09:08:33 volumio volumio[16445]: info: Loading plugin "networkfs"... Sep 03 09:08:33 volumio volumio[16445]: info: Starting Udev Watcher for removable devices Sep 03 09:08:33 volumio volumio[16445]: info: Ignoring mount for partition: boot Sep 03 09:08:33 volumio volumio[16445]: info: Ignoring mount for partition: volumio Sep 03 09:08:33 volumio volumio[16445]: info: Ignoring mount for partition: volumio_data Sep 03 09:08:33 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 09:08:33 volumio volumio[16445]: info: Loading plugin "volumio_command_line_client"... Sep 03 09:08:33 volumio volumio[16445]: info: Loading plugin "upnp"... Sep 03 09:08:33 volumio volumio[16445]: info: [1756883313308] Starting Upmpd Daemon Sep 03 09:08:33 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 09:08:33 volumio volumio[16445]: info: Loading plugin "my_music"... Sep 03 09:08:33 volumio volumio[16445]: info: Loading plugin "mpd"... Sep 03 09:08:33 volumio volumio[16445]: info: Plugin upnp_browser is not enabled Sep 03 09:08:33 volumio volumio[16445]: info: Loading plugin "alarm-clock"... Sep 03 09:08:33 volumio volumio[16445]: info: Plugin airplay_emulation is not enabled Sep 03 09:08:33 volumio volumio[16445]: info: Loading plugin "last_100"... Sep 03 09:08:33 volumio volumio[16445]: info: Loading plugin "webradio"... Sep 03 09:08:33 volumio volumio[16445]: info: Loading plugin "i2s_dacs"... Sep 03 09:08:33 volumio volumio[16445]: info: Loading plugin "volumiodiscovery"... Sep 03 09:08:34 volumio volumio[16445]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 03 09:08:34 volumio volumio[16445]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 09:08:34 volumio volumio[16445]: *** WARNING *** For more information see Sep 03 09:08:34 volumio node[16445]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 03 09:08:34 volumio volumio[16445]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 03 09:08:34 volumio volumio[16445]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 09:08:34 volumio volumio[16445]: *** WARNING *** For more information see Sep 03 09:08:34 volumio node[16445]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 09:08:34 volumio node[16445]: *** WARNING *** For more information see Sep 03 09:08:34 volumio node[16445]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 03 09:08:34 volumio node[16445]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 09:08:34 volumio node[16445]: *** WARNING *** For more information see Sep 03 09:08:34 volumio volumio[16445]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 03 09:08:34 volumio volumio[16445]: info: Discovery: Started advertising with name: Volumio Sep 03 09:08:34 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 09:08:34 volumio volumio[16445]: info: Loading plugin "jellyfin"... Sep 03 09:08:34 volumio volumio[16445]: info: Loading plugin "spop"... Sep 03 09:08:36 volumio volumio[16445]: info: Loading plugin "ytcr"... Sep 03 09:08:39 volumio volumio-remote-updater[504]: [2025-09-03 09:08:39] [connect] Successful connection Sep 03 09:08:40 volumio volumio[16445]: info: Loading plugin "outputs"... Sep 03 09:08:40 volumio volumio[16445]: info: Loading plugin "albumart"... Sep 03 09:08:40 volumio volumio[16445]: info: Plugin example_plugin is not enabled Sep 03 09:08:40 volumio volumio[16445]: info: Loading plugin "inputs"... Sep 03 09:08:40 volumio volumio[16445]: info: Loading plugin "updater_comm"... Sep 03 09:08:40 volumio volumio[16445]: info: Plugin mpdemulation is not enabled Sep 03 09:08:40 volumio volumio[16445]: info: Loading plugin "rest_api"... Sep 03 09:08:40 volumio volumio[16445]: info: Loading plugin "websocket"... Sep 03 09:08:40 volumio volumio[16445]: info: Starting Socket.io Server version 2.3.0 Sep 03 09:08:40 volumio volumio[16445]: info: Loading plugin "fusiondsp"... Sep 03 09:08:40 volumio volumio[16445]: Forking 3 albumart workers Sep 03 09:08:40 volumio volumio[16445]: info: Applying required configuration parameters for plugin fusiondsp Sep 03 09:08:40 volumio volumio[16445]: info: Loading plugin "Systeminfo"... Sep 03 09:08:41 volumio volumio[16445]: info: Loading i18n strings for locale hu Sep 03 09:08:41 volumio volumio[16445]: Updating browse sources language Sep 03 09:08:41 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 09:08:41 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 03 09:08:41 volumio volumio[16445]: info: CoreCommandRouter::initPlayerControls Sep 03 09:08:41 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:41 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:41 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:41 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:41 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:41 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:41 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:41 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:41 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 03 09:08:41 volumio volumio[16445]: Express server listening on port 3000 Sep 03 09:08:41 volumio volumio[16445]: [Metrics] WebUI: 11s 78.65ms Sep 03 09:08:41 volumio volumio[16445]: Starting albumart workers Sep 03 09:08:41 volumio volumio[16445]: info: CoreStateMachine::resetVolumioState Sep 03 09:08:41 volumio volumio[16445]: info: CoreStateMachine::getcurrentVolume Sep 03 09:08:41 volumio volumio[16445]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 09:08:41 volumio volumio[16445]: Starting albumart workers Sep 03 09:08:41 volumio volumio[16445]: info: Volumio Network Manager: Network status updated: 1 Sep 03 09:08:41 volumio volumio[16445]: Starting albumart workers Sep 03 09:08:41 volumio volumio-remote-updater[504]: [2025-09-03 09:08:41] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1756883319 101 Sep 03 09:08:41 volumio volumio[16445]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Sep 03 09:08:42 volumio volumio[16445]: info: CoreCommandRouter::volumioGetState Sep 03 09:08:42 volumio volumio[16445]: info: CorePlayQueue::getTrack 0 Sep 03 09:08:42 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 09:08:42 volumio volumio[16445]: info: VolumeController:: Volume=37 Mute =false Sep 03 09:08:42 volumio volumio[16445]: info: CoreStateMachine::pushState Sep 03 09:08:42 volumio volumio[16445]: info: CorePlayQueue::getTrack 0 Sep 03 09:08:42 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 09:08:42 volumio volumio[16445]: info: CoreCommandRouter::volumioPushState Sep 03 09:08:42 volumio volumio[16445]: info: CoreStateMachine::updateTrackBlock Sep 03 09:08:42 volumio volumio[16445]: info: CorePlayQueue::getTrackBlock Sep 03 09:08:42 volumio volumio[16445]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 09:08:42 volumio volumio[16445]: info: CoreStateMachine::setRepeat false single undefined Sep 03 09:08:42 volumio volumio[16445]: info: CoreStateMachine::pushState Sep 03 09:08:42 volumio volumio[16445]: info: CorePlayQueue::getTrack 0 Sep 03 09:08:42 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 09:08:42 volumio volumio[16445]: info: CoreCommandRouter::volumioPushState Sep 03 09:08:42 volumio volumio[16445]: info: CoreStateMachine::setRandom true Sep 03 09:08:42 volumio volumio[16445]: info: CoreStateMachine::pushState Sep 03 09:08:42 volumio volumio[16445]: info: CorePlayQueue::getTrack 0 Sep 03 09:08:42 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 09:08:42 volumio volumio[16445]: info: CoreCommandRouter::volumioPushState Sep 03 09:08:42 volumio volumio[16445]: info: Setting Device type: Raspberry PI Sep 03 09:08:42 volumio volumio[16445]: info: Listing playlists Sep 03 09:08:42 volumio volumio[16445]: info: Listing playlists Sep 03 09:08:42 volumio volumio[16445]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227 Sep 03 09:08:42 volumio volumio[16445]: info: Discovery: Found device Volumio Sep 03 09:08:42 volumio volumio[16445]: info: CoreCommandRouter::volumioGetState Sep 03 09:08:42 volumio volumio[16445]: info: CorePlayQueue::getTrack 0 Sep 03 09:08:42 volumio volumio[16445]: info: Completed loading Core Plugins Sep 03 09:08:42 volumio volumio[16445]: info: Preparing to generate the ALSA configuration file Sep 03 09:08:42 volumio volumio[16445]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 03 09:08:42 volumio volumio[16445]: info: Reading ALSA contributions from plugins. Sep 03 09:08:42 volumio volumio[16445]: info: VolumeController:: Volume=37 Mute =false Sep 03 09:08:42 volumio volumio[16445]: info: CoreStateMachine::pushState Sep 03 09:08:42 volumio volumio[16445]: info: CorePlayQueue::getTrack 0 Sep 03 09:08:42 volumio volumio[16445]: info: CoreCommandRouter::volumioPushState Sep 03 09:08:42 volumio volumio[16445]: info: Asound.conf file unchanged, so no further update is needed Sep 03 09:08:42 volumio volumio[16445]: info: Output device has changed, restarting MPD Sep 03 09:08:42 volumio sudo[16567]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 09:08:42 volumio volumio[16445]: info: ___________ START PLUGINS ___________ Sep 03 09:08:42 volumio sudo[16567]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:42 volumio volumio[16445]: info: ControllerMpd::onStart: Initializing MPD Sep 03 09:08:42 volumio volumio[16445]: info: Creating MPD Configuration file Sep 03 09:08:42 volumio sudo[16567]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:42 volumio sudo[16569]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 09:08:42 volumio sudo[16569]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:42 volumio systemd[1]: Stopping Music Player Daemon... Sep 03 09:08:42 volumio sudo[16573]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 09:08:42 volumio sudo[16573]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:42 volumio sudo[16573]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:42 volumio sudo[16575]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 09:08:42 volumio sudo[16575]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:42 volumio volumio[16445]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 09:08:42 volumio systemd[1]: mpd.service: Succeeded. Sep 03 09:08:42 volumio volumio[16445]: info: [1756883322701] CoreMusicLibrary::Adding element Last_100 Sep 03 09:08:42 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 09:08:42 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 09:08:42 volumio volumio[16445]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 09:08:42 volumio volumio[16445]: info: [1756883322715] CoreMusicLibrary::Adding element Webradio Sep 03 09:08:42 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 09:08:42 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 09:08:42 volumio volumio[16445]: info: Initializing BBC Radios Sep 03 09:08:42 volumio systemd[1]: Starting Music Player Daemon... Sep 03 09:08:42 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 03 09:08:42 volumio systemd[1]: mpd.service: Succeeded. Sep 03 09:08:42 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 09:08:42 volumio systemd[1]: Starting Music Player Daemon... Sep 03 09:08:42 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 09:08:42 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 09:08:42 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 09:08:42 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 03 09:08:42 volumio volumio[16445]: info: Discovery: Getting this device information Sep 03 09:08:42 volumio volumio[16445]: info: CoreCommandRouter::volumioGetState Sep 03 09:08:42 volumio volumio[16445]: info: CorePlayQueue::getTrack 0 Sep 03 09:08:42 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 09:08:42 volumio volumio[16445]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 09:08:42 volumio volumio[16445]: info: [1756883322830] CoreMusicLibrary::Adding element Jellyfin Sep 03 09:08:42 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 09:08:42 volumio volumio[16445]: Cannot find translation for source Jellyfin Sep 03 09:08:42 volumio volumio[16445]: info: [jellyfin] Initialized plugin with device info: {"id":"efb65fd7-7648-487c-b3ba-ebfb5cc10227","host":"http://192.168.0.171","name":"Volumio","type":"device","serviceName":"Volumio","state":{"status":"stop","volume":37,"mute":false,"artist":"","track":"","albumart":"http://192.168.0.171/albumart"}} Sep 03 09:08:42 volumio volumio[16445]: info: Creating Spotify config file Sep 03 09:08:42 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:42 volumio sudo[16586]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 03 09:08:42 volumio sudo[16586]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:42 volumio sudo[16586]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:43 volumio volumio[16445]: info: Loading i18n strings for locale hu Sep 03 09:08:43 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 03 09:08:43 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:43 volumio volumio[16445]: info: FusionDsp - mixtype--------------------- Hardware Sep 03 09:08:43 volumio volumio[16445]: info: Volumio Calling Home Sep 03 09:08:43 volumio volumio[16445]: info: Preparing to generate the ALSA configuration file Sep 03 09:08:43 volumio volumio[16445]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 03 09:08:43 volumio volumio[16445]: info: Reading ALSA contributions from plugins. Sep 03 09:08:43 volumio volumio[16445]: info: MPD Permissions set Sep 03 09:08:43 volumio volumio[16445]: info: MPD Permissions set Sep 03 09:08:43 volumio volumio[16445]: info: Spotify config file written Sep 03 09:08:43 volumio sudo[16610]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 03 09:08:43 volumio sudo[16610]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:43 volumio volumio[16445]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Sep 03 09:08:43 volumio volumio[16445]: info: Volumio called home Sep 03 09:08:43 volumio systemd[1]: Stopping go-librespot Daemon... Sep 03 09:08:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 03 09:08:43 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 03 09:08:43 volumio systemd[1]: Stopped go-librespot Daemon. Sep 03 09:08:43 volumio sudo[16613]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 03 09:08:43 volumio sudo[16613]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:43 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:43 volumio sudo[16613]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:43 volumio systemd[1]: Started go-librespot Daemon. Sep 03 09:08:43 volumio sudo[16617]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 09:08:43 volumio sudo[16617]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:43 volumio sudo[16617]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:43 volumio go-librespot[16615]: go-librespot daemon starting... Sep 03 09:08:43 volumio sudo[16610]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:43 volumio go-librespot[16615]: time="2025-09-03T09:08:43+02:00" level=info msg="running go-librespot 0.3.2" Sep 03 09:08:43 volumio go-librespot[16615]: time="2025-09-03T09:08:43+02:00" level=debug msg="app state loaded" Sep 03 09:08:43 volumio go-librespot[16615]: time="2025-09-03T09:08:43+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 03 09:08:43 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 09:08:43 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:43 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:43 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:43 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:43 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:43 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:43 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:43 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:43 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:43 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 09:08:43 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:43 volumio volumio[16445]: info: No need to fix Spotify hosts Sep 03 09:08:43 volumio go-librespot[16615]: time="2025-09-03T09:08:43+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Sep 03 09:08:43 volumio go-librespot[16615]: time="2025-09-03T09:08:43+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 03 09:08:43 volumio go-librespot[16615]: time="2025-09-03T09:08:43+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 03 09:08:43 volumio go-librespot[16615]: time="2025-09-03T09:08:43+02:00" level=info msg="zeroconf server listening on port 44395" Sep 03 09:08:43 volumio go-librespot[16615]: time="2025-09-03T09:08:43+02:00" level=debug msg="obtained new client token: AABipprMt4ij+NF8/pRIifanBXJBU413pMduDoI7J00Co4UeiqVdFMlSBGiwV5a+ecW8qJvkguNl8xYPWgU71u29U7Sv0bLlEnrJTCYITCw5X5lpwZdkTwnxhecoHKbSwWB+G623rILzAGPYR07ncAoJDjPyIWVhqUC8IIAH01mSueH0lYB8W3k5QIyKNrpSBu1y9GHywjYU7iit8arx+TR8K9AwNTjVudIdszLRru/Krg6VdAz4bGEkd6A=" Sep 03 09:08:43 volumio volumio[16445]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 03 09:08:43 volumio volumio[16445]: SPOTIFY: BQC6feE5eaHNnabBV1xeHLkExmsvoNihXkb0bKK9Np1yd3c1IZolFmvUpnem7Ry-NtEG7g0v2bgKMQN8-XtaEfxgZuLeFL5E-JjqgfULJol62bn7FpL1NgAnx0ilQUJpx-M0vg5p_nnYym-w0sfZER2_FO7QKKOoAhQM5XmJeZ5o_8fWnPRWd6qw2IH3ZP0kC6stGzxXGjzJ3XjC-jN5SeJ75GvmOJfWbITJWVrCTc9BMk-6-ox2FEww_d-4S1fZh_0sPAEMjqgAs8HMxJPhdklJFgDO9cr1CyBldTE9pYx6Zp4 Sep 03 09:08:43 volumio volumio[16445]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 03 09:08:43 volumio volumio[16445]: info: New Spotify access token = BQC6feE5eaHNnabBV1xeHLkExmsvoNihXkb0bKK9Np1yd3c1IZolFmvUpnem7Ry-NtEG7g0v2bgKMQN8-XtaEfxgZuLeFL5E-JjqgfULJol62bn7FpL1NgAnx0ilQUJpx-M0vg5p_nnYym-w0sfZER2_FO7QKKOoAhQM5XmJeZ5o_8fWnPRWd6qw2IH3ZP0kC6stGzxXGjzJ3XjC-jN5SeJ75GvmOJfWbITJWVrCTc9BMk-6-ox2FEww_d-4S1fZh_0sPAEMjqgAs8HMxJPhdklJFgDO9cr1CyBldTE9pYx6Zp4 Sep 03 09:08:43 volumio volumio[16445]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 03 09:08:43 volumio go-librespot[16615]: time="2025-09-03T09:08:43+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 03 09:08:43 volumio go-librespot[16615]: time="2025-09-03T09:08:43+02:00" level=debug msg="completed keyexchange" Sep 03 09:08:43 volumio go-librespot[16615]: time="2025-09-03T09:08:43+02:00" level=debug msg="completed challenge" Sep 03 09:08:43 volumio volumio[16445]: info: CoreCommandRouter::volumioGetState Sep 03 09:08:43 volumio volumio[16445]: info: CorePlayQueue::getTrack 0 Sep 03 09:08:43 volumio go-librespot[16615]: time="2025-09-03T09:08:43+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 03 09:08:44 volumio volumio[16445]: info: Asound.conf file unchanged, so no further update is needed Sep 03 09:08:44 volumio volumio[16445]: info: Output device has changed, restarting MPD Sep 03 09:08:44 volumio go-librespot[16615]: time="2025-09-03T09:08:44+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 03 09:08:44 volumio go-librespot[16615]: time="2025-09-03T09:08:44+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 03 09:08:44 volumio sudo[16635]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 09:08:44 volumio sudo[16635]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:44 volumio sudo[16635]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:44 volumio sudo[16637]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 09:08:44 volumio sudo[16637]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:44 volumio go-librespot[16615]: time="2025-09-03T09:08:44+02:00" level=debug msg="dealer connection opened" Sep 03 09:08:44 volumio go-librespot[16615]: time="2025-09-03T09:08:44+02:00" level=trace msg="starting accesspoint recv loop" Sep 03 09:08:44 volumio go-librespot[16615]: time="2025-09-03T09:08:44+02:00" level=trace msg="starting dealer recv loop" Sep 03 09:08:44 volumio go-librespot[16615]: time="2025-09-03T09:08:44+02:00" level=trace msg="received accesspoint ping" Sep 03 09:08:44 volumio go-librespot[16615]: time="2025-09-03T09:08:44+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 03 09:08:44 volumio go-librespot[16615]: time="2025-09-03T09:08:44+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 03 09:08:44 volumio go-librespot[16615]: time="2025-09-03T09:08:44+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 03 09:08:44 volumio go-librespot[16615]: time="2025-09-03T09:08:44+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 03 09:08:44 volumio go-librespot[16615]: time="2025-09-03T09:08:44+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 03 09:08:44 volumio go-librespot[16615]: time="2025-09-03T09:08:44+02:00" level=debug msg="received connection id: MWFmY2I2MzktODZh...NEYzQjM4MzhFNA==" Sep 03 09:08:44 volumio go-librespot[16615]: time="2025-09-03T09:08:44+02:00" level=trace msg="received accesspoint pong ack" Sep 03 09:08:44 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 03 09:08:44 volumio systemd[1]: mpd.service: Succeeded. Sep 03 09:08:44 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 09:08:44 volumio sudo[16642]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 03 09:08:44 volumio sudo[16642]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:44 volumio volumio[16445]: info: MPD Permissions set Sep 03 09:08:44 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:44 volumio systemd[1]: Starting Music Player Daemon... Sep 03 09:08:44 volumio go-librespot[16615]: time="2025-09-03T09:08:44+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 03 09:08:44 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 09:08:44 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:44 volumio sudo[16642]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:44 volumio volumio[16445]: info: Upmpdcli Daemon Started Sep 03 09:08:44 volumio volumio[16445]: SPOTIFY: User informations: {"country":"HU","display_name":"4Misi","email":"sztako.mihaly@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/lo8jr34h0xh1vbcv2lgmaqkvv"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/lo8jr34h0xh1vbcv2lgmaqkvv","id":"lo8jr34h0xh1vbcv2lgmaqkvv","images":[],"product":"premium","type":"user","uri":"spotify:user:lo8jr34h0xh1vbcv2lgmaqkvv"} Sep 03 09:08:44 volumio volumio[16445]: info: Spotify Successfully logged in Sep 03 09:08:44 volumio volumio[16445]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 09:08:44 volumio volumio[16445]: info: [1756883324361] CoreMusicLibrary::Adding element Spotify Sep 03 09:08:44 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 09:08:44 volumio volumio[16445]: Cannot find translation for source Jellyfin Sep 03 09:08:44 volumio volumio[16445]: Cannot find translation for source Spotify Sep 03 09:08:44 volumio sudo[16643]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 03 09:08:44 volumio sudo[16643]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:44 volumio sudo[16643]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:44 volumio volumio[16445]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 03 09:08:44 volumio volumio[16445]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 09:08:45 volumio volumio[16445]: info: VolumeController:: Volume=37 Mute =false Sep 03 09:08:45 volumio volumio[16445]: info: CoreCommandRouter::volumioGetState Sep 03 09:08:45 volumio volumio[16445]: info: CorePlayQueue::getTrack 0 Sep 03 09:08:45 volumio volumio[16445]: info: CoreStateMachine::pushState Sep 03 09:08:45 volumio volumio[16445]: info: CorePlayQueue::getTrack 0 Sep 03 09:08:45 volumio volumio[16445]: info: CoreCommandRouter::volumioPushState Sep 03 09:08:45 volumio volumio[16445]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 03 09:08:45 volumio volumio[16445]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Sep 03 09:08:45 volumio volumio[16445]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 03 09:08:45 volumio volumio[16445]: info: camilladsp service started and running in background, instance 1 Sep 03 09:08:45 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:45 volumio volumio[16445]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 03 09:08:45 volumio volumio[16445]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 03 09:08:45 volumio volumio[16445]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 03 09:08:45 volumio volumio[16445]: info: FusionDsp loaded Sep 03 09:08:45 volumio volumio[16445]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 09:08:45 volumio sudo[16664]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 03 09:08:45 volumio sudo[16664]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:45 volumio sudo[16664]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:45 volumio volumio[16445]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 03 09:08:45 volumio volumio[16445]: info: Adding Signal Path Element [object Object] Sep 03 09:08:45 volumio volumio[16445]: info: Adding fusiondspeq DSP Signal Path Element Sep 03 09:08:45 volumio volumio[16445]: info: FusionDsp - ---- installed callbackRead Sep 03 09:08:45 volumio volumio[16445]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 09:08:45 volumio volumio[16445]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 03 09:08:45 volumio volumio[16445]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 03 09:08:45 volumio volumio[16445]: at onErrorNT (internal/child_process.js:465:16) Sep 03 09:08:45 volumio volumio[16445]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Sep 03 09:08:45 volumio volumio[16445]: at runNextTicks (internal/process/task_queues.js:62:3) Sep 03 09:08:45 volumio volumio[16445]: at listOnTimeout (internal/timers.js:523:9) Sep 03 09:08:45 volumio volumio[16445]: at processTimers (internal/timers.js:497:7) { Sep 03 09:08:45 volumio volumio[16445]: errno: -2, Sep 03 09:08:45 volumio volumio[16445]: code: 'ENOENT', Sep 03 09:08:45 volumio volumio[16445]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 03 09:08:45 volumio volumio[16445]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 03 09:08:45 volumio volumio[16445]: spawnargs: [ Sep 03 09:08:45 volumio volumio[16445]: '-p', Sep 03 09:08:45 volumio volumio[16445]: 9876, Sep 03 09:08:45 volumio volumio[16445]: '-o', Sep 03 09:08:45 volumio volumio[16445]: '/tmp/camilladsp.log', Sep 03 09:08:45 volumio volumio[16445]: '-l', Sep 03 09:08:45 volumio volumio[16445]: 'warn', Sep 03 09:08:45 volumio volumio[16445]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 03 09:08:45 volumio volumio[16445]: ] Sep 03 09:08:45 volumio volumio[16445]: } Sep 03 09:08:45 volumio volumio[16445]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 09:08:45 volumio sudo[16675]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-03 09:07 Sep 03 09:08:45 volumio sudo[16675]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:46 volumio mpd[16651]: Sep 03 09:08 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 03 09:08:46 volumio systemd[1]: Started Music Player Daemon. Sep 03 09:08:46 volumio sudo[16575]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:46 volumio sudo[16569]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:46 volumio sudo[16637]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:46 volumio sudo[16675]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:46 volumio volumio-remote-updater[504]: [2025-09-03 09:08:46] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 03 09:08:46 volumio volumio-remote-updater[504]: [2025-09-03 09:08:46] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 03 09:08:46 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 03 09:08:46 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 03 09:08:46 volumio systemd[1]: Started dynamicswap service. Sep 03 09:08:46 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 03 09:08:46 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 03 09:08:46 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 7446. Sep 03 09:08:46 volumio systemd[1]: Started dynamicswap service. Sep 03 09:08:46 volumio systemd[1]: Stopped Volumio Backend Module. Sep 03 09:08:46 volumio systemd[1]: Started Volumio Backend Module. Sep 03 09:08:46 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 03 09:08:48 volumio volumio[16688]: info: ------------------------------------------- Sep 03 09:08:48 volumio volumio[16688]: info: ----- Volumio3 ---- Sep 03 09:08:48 volumio volumio[16688]: info: ------------------------------------------- Sep 03 09:08:48 volumio volumio[16688]: info: ----- System startup ---- Sep 03 09:08:48 volumio volumio[16688]: info: ------------------------------------------- Sep 03 09:08:48 volumio volumio[16688]: info: MYVOLUMIO Environment detected Sep 03 09:08:48 volumio volumio[16688]: info: Plugin folders cleanup Sep 03 09:08:48 volumio volumio[16688]: info: Scanning into folder /volumio/app/plugins/ Sep 03 09:08:48 volumio volumio[16688]: info: Scanning category audio_interface Sep 03 09:08:48 volumio volumio[16688]: info: Scanning category miscellanea Sep 03 09:08:48 volumio volumio[16688]: info: Scanning category music_service Sep 03 09:08:48 volumio volumio[16688]: info: Scanning category plugins.json Sep 03 09:08:48 volumio volumio[16688]: info: Scanning category system_controller Sep 03 09:08:49 volumio volumio[16688]: info: Scanning category user_interface Sep 03 09:08:49 volumio volumio[16688]: info: Scanning into folder /data/plugins/ Sep 03 09:08:49 volumio volumio[16688]: info: Scanning category audio_interface Sep 03 09:08:49 volumio volumio[16688]: info: Scanning category music_service Sep 03 09:08:49 volumio volumio[16688]: info: Scanning category user_interface Sep 03 09:08:49 volumio volumio[16688]: info: Plugin folders cleanup completed Sep 03 09:08:49 volumio volumio[16688]: info: ------------------------------------------- Sep 03 09:08:49 volumio volumio[16688]: info: ----- Core plugins startup ---- Sep 03 09:08:49 volumio volumio[16688]: info: ------------------------------------------- Sep 03 09:08:49 volumio volumio[16688]: info: Loading plugins from folder /volumio/app/plugins/ Sep 03 09:08:49 volumio volumio[16688]: info: Adding plugin upnp to MyMusic Plugins Sep 03 09:08:49 volumio volumio[16688]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 03 09:08:49 volumio volumio[16688]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 03 09:08:49 volumio volumio[16688]: info: Loading plugins from folder /data/plugins/ Sep 03 09:08:49 volumio volumio[16688]: info: Loading plugin "system"... Sep 03 09:08:49 volumio volumio[16688]: info: Loading plugin "appearance"... Sep 03 09:08:50 volumio volumio[16688]: info: Loading plugin "network"... Sep 03 09:08:50 volumio volumio[16688]: info: Refreshing Cached IP Addresses Sep 03 09:08:50 volumio sudo[16714]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 03 09:08:50 volumio sudo[16714]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:50 volumio sudo[16714]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:50 volumio volumio[16688]: info: Loading plugin "services"... Sep 03 09:08:50 volumio sudo[16716]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 09:08:50 volumio sudo[16716]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:50 volumio volumio[16688]: info: Loading plugin "alsa_controller"... Sep 03 09:08:50 volumio sudo[16716]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:50 volumio volumio[16688]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 09:08:50 volumio volumio[16688]: info: Loading plugin "wizard"... Sep 03 09:08:50 volumio volumio[16688]: info: Loading plugin "networkfs"... Sep 03 09:08:50 volumio volumio[16688]: info: Starting Udev Watcher for removable devices Sep 03 09:08:50 volumio volumio[16688]: info: Ignoring mount for partition: boot Sep 03 09:08:50 volumio volumio[16688]: info: Ignoring mount for partition: volumio Sep 03 09:08:50 volumio volumio[16688]: info: Ignoring mount for partition: volumio_data Sep 03 09:08:50 volumio volumio[16688]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 09:08:50 volumio volumio[16688]: info: Loading plugin "volumio_command_line_client"... Sep 03 09:08:50 volumio volumio[16688]: info: Loading plugin "upnp"... Sep 03 09:08:50 volumio volumio[16688]: info: [1756883330325] Starting Upmpd Daemon Sep 03 09:08:50 volumio volumio[16688]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 09:08:50 volumio volumio[16688]: info: Loading plugin "my_music"... Sep 03 09:08:50 volumio volumio[16688]: info: Loading plugin "mpd"... Sep 03 09:08:50 volumio volumio[16688]: info: Plugin upnp_browser is not enabled Sep 03 09:08:50 volumio volumio[16688]: info: Loading plugin "alarm-clock"... Sep 03 09:08:50 volumio volumio[16688]: info: Plugin airplay_emulation is not enabled Sep 03 09:08:50 volumio volumio[16688]: info: Loading plugin "last_100"... Sep 03 09:08:50 volumio volumio[16688]: info: Loading plugin "webradio"... Sep 03 09:08:50 volumio volumio[16688]: info: Loading plugin "i2s_dacs"... Sep 03 09:08:50 volumio volumio[16688]: info: Loading plugin "volumiodiscovery"... Sep 03 09:08:51 volumio volumio[16688]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 03 09:08:51 volumio volumio[16688]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 09:08:51 volumio volumio[16688]: *** WARNING *** For more information see Sep 03 09:08:51 volumio node[16688]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 03 09:08:51 volumio volumio[16688]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 03 09:08:51 volumio volumio[16688]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 09:08:51 volumio volumio[16688]: *** WARNING *** For more information see Sep 03 09:08:51 volumio node[16688]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 09:08:51 volumio node[16688]: *** WARNING *** For more information see Sep 03 09:08:51 volumio node[16688]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 03 09:08:51 volumio node[16688]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 09:08:51 volumio node[16688]: *** WARNING *** For more information see Sep 03 09:08:51 volumio volumio[16688]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 03 09:08:51 volumio volumio[16688]: info: Discovery: Started advertising with name: Volumio Sep 03 09:08:51 volumio volumio[16688]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 09:08:51 volumio volumio[16688]: info: Loading plugin "jellyfin"... Sep 03 09:08:51 volumio volumio-remote-updater[504]: [2025-09-03 09:08:51] [connect] Successful connection Sep 03 09:08:51 volumio volumio[16688]: info: Loading plugin "spop"... Sep 03 09:08:52 volumio volumio[16688]: info: Loading plugin "ytcr"... Sep 03 09:08:57 volumio volumio[16688]: info: Loading plugin "outputs"... Sep 03 09:08:57 volumio volumio[16688]: info: Loading plugin "albumart"... Sep 03 09:08:57 volumio volumio[16688]: info: Plugin example_plugin is not enabled Sep 03 09:08:57 volumio volumio[16688]: info: Loading plugin "inputs"... Sep 03 09:08:57 volumio volumio[16688]: info: Loading plugin "updater_comm"... Sep 03 09:08:57 volumio volumio[16688]: info: Plugin mpdemulation is not enabled Sep 03 09:08:57 volumio volumio[16688]: info: Loading plugin "rest_api"... Sep 03 09:08:57 volumio volumio[16688]: info: Loading plugin "websocket"... Sep 03 09:08:57 volumio volumio[16688]: info: Starting Socket.io Server version 2.3.0 Sep 03 09:08:57 volumio volumio[16688]: info: Loading plugin "fusiondsp"... Sep 03 09:08:57 volumio volumio[16688]: Forking 3 albumart workers Sep 03 09:08:57 volumio volumio[16688]: info: Applying required configuration parameters for plugin fusiondsp Sep 03 09:08:57 volumio volumio[16688]: info: Loading plugin "Systeminfo"... Sep 03 09:08:58 volumio volumio[16688]: info: Loading i18n strings for locale hu Sep 03 09:08:58 volumio volumio[16688]: Updating browse sources language Sep 03 09:08:58 volumio volumio[16688]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 09:08:58 volumio volumio[16688]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 03 09:08:58 volumio volumio[16688]: info: CoreCommandRouter::initPlayerControls Sep 03 09:08:58 volumio volumio[16688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:58 volumio volumio[16688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:58 volumio volumio[16688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:58 volumio volumio[16688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:58 volumio volumio[16688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:58 volumio volumio[16688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:58 volumio volumio[16688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:58 volumio volumio[16688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:58 volumio volumio[16688]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 03 09:08:58 volumio volumio[16688]: Express server listening on port 3000 Sep 03 09:08:58 volumio volumio[16688]: [Metrics] WebUI: 10s 992.19ms Sep 03 09:08:58 volumio volumio[16688]: info: CoreStateMachine::resetVolumioState Sep 03 09:08:58 volumio volumio[16688]: info: CoreStateMachine::getcurrentVolume Sep 03 09:08:58 volumio volumio[16688]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 09:08:58 volumio volumio[16688]: info: Volumio Network Manager: Network status updated: 1 Sep 03 09:08:58 volumio volumio[16688]: Starting albumart workers Sep 03 09:08:58 volumio volumio[16688]: Starting albumart workers Sep 03 09:08:58 volumio volumio[16688]: Starting albumart workers Sep 03 09:08:58 volumio volumio[16688]: info: CoreCommandRouter::volumioGetState Sep 03 09:08:58 volumio volumio[16688]: info: CorePlayQueue::getTrack 0 Sep 03 09:08:59 volumio volumio[16688]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 09:08:59 volumio volumio[16688]: info: VolumeController:: Volume=37 Mute =false Sep 03 09:08:59 volumio volumio[16688]: info: CoreStateMachine::pushState Sep 03 09:08:59 volumio volumio[16688]: info: CorePlayQueue::getTrack 0 Sep 03 09:08:59 volumio volumio[16688]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 09:08:59 volumio volumio[16688]: info: CoreCommandRouter::volumioPushState Sep 03 09:08:59 volumio volumio[16688]: info: CoreStateMachine::updateTrackBlock Sep 03 09:08:59 volumio volumio[16688]: info: CorePlayQueue::getTrackBlock Sep 03 09:08:59 volumio volumio[16688]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 09:08:59 volumio volumio[16688]: info: Setting Device type: Raspberry PI Sep 03 09:08:59 volumio volumio[16688]: info: CoreStateMachine::setRepeat false single undefined Sep 03 09:08:59 volumio volumio[16688]: info: CoreStateMachine::pushState Sep 03 09:08:59 volumio volumio[16688]: info: CorePlayQueue::getTrack 0 Sep 03 09:08:59 volumio volumio[16688]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 09:08:59 volumio volumio[16688]: info: CoreCommandRouter::volumioPushState Sep 03 09:08:59 volumio volumio[16688]: info: CoreStateMachine::setRandom true Sep 03 09:08:59 volumio volumio[16688]: info: CoreStateMachine::pushState Sep 03 09:08:59 volumio volumio[16688]: info: CorePlayQueue::getTrack 0 Sep 03 09:08:59 volumio volumio[16688]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 09:08:59 volumio volumio[16688]: info: CoreCommandRouter::volumioPushState Sep 03 09:08:59 volumio volumio[16688]: info: Listing playlists Sep 03 09:08:59 volumio volumio[16688]: info: Listing playlists Sep 03 09:08:59 volumio volumio[16688]: info: Completed loading Core Plugins Sep 03 09:08:59 volumio volumio[16688]: info: Preparing to generate the ALSA configuration file Sep 03 09:08:59 volumio volumio[16688]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227 Sep 03 09:08:59 volumio volumio[16688]: info: Discovery: Found device Volumio Sep 03 09:08:59 volumio volumio[16688]: info: CoreCommandRouter::volumioGetState Sep 03 09:08:59 volumio volumio[16688]: info: CorePlayQueue::getTrack 0 Sep 03 09:08:59 volumio volumio[16688]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 03 09:08:59 volumio volumio[16688]: info: Reading ALSA contributions from plugins. Sep 03 09:08:59 volumio volumio[16688]: info: VolumeController:: Volume=37 Mute =false Sep 03 09:08:59 volumio volumio[16688]: info: CoreStateMachine::pushState Sep 03 09:08:59 volumio volumio[16688]: info: CorePlayQueue::getTrack 0 Sep 03 09:08:59 volumio volumio[16688]: info: CoreCommandRouter::volumioPushState Sep 03 09:08:59 volumio volumio[16688]: info: Asound.conf file unchanged, so no further update is needed Sep 03 09:08:59 volumio volumio[16688]: info: Output device has changed, restarting MPD Sep 03 09:08:59 volumio sudo[16782]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 09:08:59 volumio volumio[16688]: info: ___________ START PLUGINS ___________ Sep 03 09:08:59 volumio sudo[16782]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:59 volumio sudo[16782]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:59 volumio sudo[16792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 09:08:59 volumio sudo[16792]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:59 volumio volumio[16688]: info: ControllerMpd::onStart: Initializing MPD Sep 03 09:08:59 volumio volumio[16688]: info: Creating MPD Configuration file Sep 03 09:08:59 volumio systemd[1]: Stopping Music Player Daemon... Sep 03 09:08:59 volumio sudo[16810]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 09:08:59 volumio systemd[1]: mpd.service: Succeeded. Sep 03 09:08:59 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 09:08:59 volumio sudo[16810]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:59 volumio sudo[16810]: pam_unix(sudo:session): session closed for user root Sep 03 09:08:59 volumio volumio[16688]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 09:08:59 volumio volumio[16688]: info: [1756883339668] CoreMusicLibrary::Adding element Last_100 Sep 03 09:08:59 volumio volumio[16688]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 09:08:59 volumio volumio[16688]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 09:08:59 volumio volumio[16688]: info: [1756883339674] CoreMusicLibrary::Adding element Webradio Sep 03 09:08:59 volumio volumio[16688]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 09:08:59 volumio volumio[16688]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 09:08:59 volumio volumio[16688]: info: Initializing BBC Radios Sep 03 09:08:59 volumio sudo[16817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 09:08:59 volumio systemd[1]: Starting Music Player Daemon... Sep 03 09:08:59 volumio sudo[16817]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:59 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 03 09:08:59 volumio systemd[1]: mpd.service: Succeeded. Sep 03 09:08:59 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 09:08:59 volumio systemd[1]: Starting Music Player Daemon... Sep 03 09:08:59 volumio volumio[16688]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 09:08:59 volumio volumio[16688]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 09:08:59 volumio volumio[16688]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 09:08:59 volumio volumio[16688]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 03 09:08:59 volumio volumio[16688]: info: Discovery: Getting this device information Sep 03 09:08:59 volumio volumio[16688]: info: CoreCommandRouter::volumioGetState Sep 03 09:08:59 volumio volumio[16688]: info: CorePlayQueue::getTrack 0 Sep 03 09:08:59 volumio volumio[16688]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 09:08:59 volumio volumio[16688]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 09:08:59 volumio volumio[16688]: info: [1756883339817] CoreMusicLibrary::Adding element Jellyfin Sep 03 09:08:59 volumio volumio[16688]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 09:08:59 volumio volumio[16688]: Cannot find translation for source Jellyfin Sep 03 09:08:59 volumio volumio[16688]: info: [jellyfin] Initialized plugin with device info: {"id":"efb65fd7-7648-487c-b3ba-ebfb5cc10227","host":"http://192.168.0.171","name":"Volumio","type":"device","serviceName":"Volumio","state":{"status":"stop","volume":37,"mute":false,"artist":"","track":"","albumart":"http://192.168.0.171/albumart"}} Sep 03 09:08:59 volumio volumio[16688]: info: Creating Spotify config file Sep 03 09:08:59 volumio volumio[16688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:08:59 volumio sudo[16822]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 03 09:08:59 volumio sudo[16822]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:08:59 volumio sudo[16822]: pam_unix(sudo:session): session closed for user root Sep 03 09:09:00 volumio volumio[16688]: info: Loading i18n strings for locale hu Sep 03 09:09:00 volumio volumio[16688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 03 09:09:00 volumio volumio[16688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:00 volumio volumio[16688]: info: FusionDsp - mixtype--------------------- Hardware Sep 03 09:09:00 volumio volumio[16688]: info: Volumio Calling Home Sep 03 09:09:00 volumio volumio[16688]: info: Preparing to generate the ALSA configuration file Sep 03 09:09:00 volumio volumio[16688]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 03 09:09:00 volumio volumio[16688]: info: Reading ALSA contributions from plugins. Sep 03 09:09:00 volumio volumio[16688]: info: MPD Permissions set Sep 03 09:09:00 volumio volumio[16688]: info: MPD Permissions set Sep 03 09:09:00 volumio volumio[16688]: info: CoreCommandRouter::volumioGetState Sep 03 09:09:00 volumio volumio[16688]: info: CorePlayQueue::getTrack 0 Sep 03 09:09:00 volumio volumio[16688]: info: Spotify config file written Sep 03 09:09:00 volumio sudo[16850]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 03 09:09:00 volumio sudo[16850]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:09:00 volumio volumio[16688]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Sep 03 09:09:00 volumio volumio[16688]: info: Volumio called home Sep 03 09:09:00 volumio systemd[1]: Stopping go-librespot Daemon... Sep 03 09:09:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 03 09:09:00 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 03 09:09:00 volumio systemd[1]: Stopped go-librespot Daemon. Sep 03 09:09:00 volumio sudo[16853]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 03 09:09:00 volumio sudo[16853]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:09:00 volumio systemd[1]: Started go-librespot Daemon. Sep 03 09:09:00 volumio volumio[16688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:00 volumio volumio[16688]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 09:09:00 volumio volumio[16688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:00 volumio sudo[16853]: pam_unix(sudo:session): session closed for user root Sep 03 09:09:00 volumio volumio[16688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:00 volumio sudo[16857]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 09:09:00 volumio sudo[16857]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:09:00 volumio go-librespot[16854]: go-librespot daemon starting... Sep 03 09:09:00 volumio sudo[16857]: pam_unix(sudo:session): session closed for user root Sep 03 09:09:00 volumio sudo[16850]: pam_unix(sudo:session): session closed for user root Sep 03 09:09:00 volumio volumio[16688]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 09:09:00 volumio volumio[16688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:00 volumio go-librespot[16854]: time="2025-09-03T09:09:00+02:00" level=info msg="running go-librespot 0.3.2" Sep 03 09:09:00 volumio go-librespot[16854]: time="2025-09-03T09:09:00+02:00" level=debug msg="app state loaded" Sep 03 09:09:00 volumio volumio[16688]: info: No need to fix Spotify hosts Sep 03 09:09:00 volumio go-librespot[16854]: time="2025-09-03T09:09:00+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 03 09:09:00 volumio go-librespot[16854]: time="2025-09-03T09:09:00+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Sep 03 09:09:00 volumio go-librespot[16854]: time="2025-09-03T09:09:00+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 03 09:09:00 volumio go-librespot[16854]: time="2025-09-03T09:09:00+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 03 09:09:00 volumio go-librespot[16854]: time="2025-09-03T09:09:00+02:00" level=info msg="zeroconf server listening on port 32837" Sep 03 09:09:00 volumio go-librespot[16854]: time="2025-09-03T09:09:00+02:00" level=debug msg="obtained new client token: AACvaHbcbcloexUxCU50tLNce4cmtCjZ7gsMRdN/h+4BgHBXpzpgjk+oIX0ENbrAFbVUMywl5mMnurS8H785ZzR4tAr5ZVCSom+UvhwdQFVvAzJ4WdQQgToJAcH9UBwWIF/at8WYpmcYGJLnyyYMgph9XSh7hB/76/0QWR8NBis2ftm/iPi8P2pMod+ig4TJxuKENF0MoDWLPyD7q1rPXKkIKZL2j+diEHCnqUsMGlRoWyuLuZ8qth8jDf4=" Sep 03 09:09:00 volumio go-librespot[16854]: time="2025-09-03T09:09:00+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 03 09:09:00 volumio go-librespot[16854]: time="2025-09-03T09:09:00+02:00" level=debug msg="completed keyexchange" Sep 03 09:09:00 volumio go-librespot[16854]: time="2025-09-03T09:09:00+02:00" level=debug msg="completed challenge" Sep 03 09:09:00 volumio go-librespot[16854]: time="2025-09-03T09:09:00+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 03 09:09:01 volumio volumio[16688]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 03 09:09:01 volumio volumio[16688]: SPOTIFY: BQC2tY0DwKR4c9Y4YDu3XNWOS9-J6cl1yY4lygFTd2BD6ngaBt9-iG1897ke1cST0xcQYvZiIQek6IaKvbvJyl30zqGTh0-hVH2zzoTEs4_MOedx6e5PS_lLxWwSVQpzV22YSnQq4lgyMXqA8yseQeP6Pg0rXaHYB4QpaagG_dewqzh3ij3Ul0VtNsd5fWgvAxyoGhgaqoiTQ9KzvXre8bTiogzxsoJrd3mqrwwpZC1br4kUXl3HZ9VfxmgVy3OUAR-R-ymPw3DQTF6cddHv-qcOuNzFzOg9UK1SBGKyDsEMs0I Sep 03 09:09:01 volumio volumio[16688]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 03 09:09:01 volumio volumio[16688]: info: New Spotify access token = BQC2tY0DwKR4c9Y4YDu3XNWOS9-J6cl1yY4lygFTd2BD6ngaBt9-iG1897ke1cST0xcQYvZiIQek6IaKvbvJyl30zqGTh0-hVH2zzoTEs4_MOedx6e5PS_lLxWwSVQpzV22YSnQq4lgyMXqA8yseQeP6Pg0rXaHYB4QpaagG_dewqzh3ij3Ul0VtNsd5fWgvAxyoGhgaqoiTQ9KzvXre8bTiogzxsoJrd3mqrwwpZC1br4kUXl3HZ9VfxmgVy3OUAR-R-ymPw3DQTF6cddHv-qcOuNzFzOg9UK1SBGKyDsEMs0I Sep 03 09:09:01 volumio volumio[16688]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 03 09:09:01 volumio go-librespot[16854]: time="2025-09-03T09:09:01+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 03 09:09:01 volumio go-librespot[16854]: time="2025-09-03T09:09:01+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 03 09:09:01 volumio volumio[16688]: info: CoreCommandRouter::volumioGetState Sep 03 09:09:01 volumio volumio[16688]: info: CorePlayQueue::getTrack 0 Sep 03 09:09:01 volumio go-librespot[16854]: time="2025-09-03T09:09:01+02:00" level=debug msg="dealer connection opened" Sep 03 09:09:01 volumio go-librespot[16854]: time="2025-09-03T09:09:01+02:00" level=trace msg="starting accesspoint recv loop" Sep 03 09:09:01 volumio go-librespot[16854]: time="2025-09-03T09:09:01+02:00" level=trace msg="starting dealer recv loop" Sep 03 09:09:01 volumio go-librespot[16854]: time="2025-09-03T09:09:01+02:00" level=trace msg="received accesspoint ping" Sep 03 09:09:01 volumio go-librespot[16854]: time="2025-09-03T09:09:01+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 03 09:09:01 volumio go-librespot[16854]: time="2025-09-03T09:09:01+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 03 09:09:01 volumio go-librespot[16854]: time="2025-09-03T09:09:01+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 03 09:09:01 volumio go-librespot[16854]: time="2025-09-03T09:09:01+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 03 09:09:01 volumio go-librespot[16854]: time="2025-09-03T09:09:01+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 03 09:09:01 volumio volumio[16688]: info: Asound.conf file unchanged, so no further update is needed Sep 03 09:09:01 volumio volumio[16688]: info: Output device has changed, restarting MPD Sep 03 09:09:01 volumio go-librespot[16854]: time="2025-09-03T09:09:01+02:00" level=debug msg="received connection id: ZjkxOWRmNzYtZDI3...QkNFOTc4MDhENw==" Sep 03 09:09:01 volumio go-librespot[16854]: time="2025-09-03T09:09:01+02:00" level=trace msg="received accesspoint pong ack" Sep 03 09:09:01 volumio sudo[16876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 09:09:01 volumio sudo[16876]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:09:01 volumio sudo[16876]: pam_unix(sudo:session): session closed for user root Sep 03 09:09:01 volumio sudo[16879]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 09:09:01 volumio sudo[16879]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:09:01 volumio volumio[16688]: info: MPD Permissions set Sep 03 09:09:01 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 03 09:09:01 volumio systemd[1]: mpd.service: Succeeded. Sep 03 09:09:01 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 09:09:01 volumio go-librespot[16854]: time="2025-09-03T09:09:01+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 03 09:09:01 volumio volumio[16688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:01 volumio volumio[16688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:01 volumio volumio[16688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:01 volumio volumio[16688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:01 volumio volumio[16688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:01 volumio volumio[16688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:01 volumio sudo[16882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 03 09:09:01 volumio sudo[16882]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:09:01 volumio volumio[16688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:01 volumio volumio[16688]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 09:09:01 volumio volumio[16688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:01 volumio systemd[1]: Starting Music Player Daemon... Sep 03 09:09:01 volumio volumio[16688]: SPOTIFY: User informations: {"country":"HU","display_name":"4Misi","email":"sztako.mihaly@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/lo8jr34h0xh1vbcv2lgmaqkvv"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/lo8jr34h0xh1vbcv2lgmaqkvv","id":"lo8jr34h0xh1vbcv2lgmaqkvv","images":[],"product":"premium","type":"user","uri":"spotify:user:lo8jr34h0xh1vbcv2lgmaqkvv"} Sep 03 09:09:01 volumio volumio[16688]: info: Spotify Successfully logged in Sep 03 09:09:01 volumio volumio[16688]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 09:09:01 volumio volumio[16688]: info: [1756883341391] CoreMusicLibrary::Adding element Spotify Sep 03 09:09:01 volumio volumio[16688]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 09:09:01 volumio volumio[16688]: Cannot find translation for source Jellyfin Sep 03 09:09:01 volumio volumio[16688]: Cannot find translation for source Spotify Sep 03 09:09:01 volumio sudo[16882]: pam_unix(sudo:session): session closed for user root Sep 03 09:09:01 volumio volumio[16688]: info: Upmpdcli Daemon Started Sep 03 09:09:01 volumio sudo[16883]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 03 09:09:01 volumio sudo[16883]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:09:01 volumio sudo[16883]: pam_unix(sudo:session): session closed for user root Sep 03 09:09:01 volumio volumio[16688]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 03 09:09:01 volumio volumio[16688]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 09:09:01 volumio volumio[16688]: info: VolumeController:: Volume=37 Mute =false Sep 03 09:09:01 volumio volumio[16688]: info: CoreCommandRouter::volumioGetState Sep 03 09:09:01 volumio volumio[16688]: info: CorePlayQueue::getTrack 0 Sep 03 09:09:01 volumio volumio[16688]: info: CoreStateMachine::pushState Sep 03 09:09:01 volumio volumio[16688]: info: CorePlayQueue::getTrack 0 Sep 03 09:09:01 volumio volumio[16688]: info: CoreCommandRouter::volumioPushState Sep 03 09:09:02 volumio volumio[16688]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 03 09:09:02 volumio volumio[16688]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Sep 03 09:09:02 volumio volumio[16688]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 03 09:09:02 volumio volumio[16688]: info: camilladsp service started and running in background, instance 1 Sep 03 09:09:02 volumio volumio[16688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:02 volumio volumio[16688]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 03 09:09:02 volumio volumio[16688]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 03 09:09:02 volumio volumio[16688]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 03 09:09:02 volumio volumio[16688]: info: FusionDsp loaded Sep 03 09:09:02 volumio volumio[16688]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 09:09:02 volumio sudo[16905]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 03 09:09:02 volumio sudo[16905]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:09:02 volumio volumio[16688]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 03 09:09:02 volumio volumio[16688]: info: Adding Signal Path Element [object Object] Sep 03 09:09:02 volumio volumio[16688]: info: Adding fusiondspeq DSP Signal Path Element Sep 03 09:09:02 volumio volumio[16688]: info: FusionDsp - ---- installed callbackRead Sep 03 09:09:02 volumio sudo[16905]: pam_unix(sudo:session): session closed for user root Sep 03 09:09:02 volumio volumio[16688]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 09:09:02 volumio volumio[16688]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 03 09:09:02 volumio volumio[16688]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 03 09:09:02 volumio volumio[16688]: at onErrorNT (internal/child_process.js:465:16) Sep 03 09:09:02 volumio volumio[16688]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Sep 03 09:09:02 volumio volumio[16688]: errno: -2, Sep 03 09:09:02 volumio volumio[16688]: code: 'ENOENT', Sep 03 09:09:02 volumio volumio[16688]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 03 09:09:02 volumio volumio[16688]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 03 09:09:02 volumio volumio[16688]: spawnargs: [ Sep 03 09:09:02 volumio volumio[16688]: '-p', Sep 03 09:09:02 volumio volumio[16688]: 9876, Sep 03 09:09:02 volumio volumio[16688]: '-o', Sep 03 09:09:02 volumio volumio[16688]: '/tmp/camilladsp.log', Sep 03 09:09:02 volumio volumio[16688]: '-l', Sep 03 09:09:02 volumio volumio[16688]: 'warn', Sep 03 09:09:02 volumio volumio[16688]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 03 09:09:02 volumio volumio[16688]: ] Sep 03 09:09:02 volumio volumio[16688]: } Sep 03 09:09:02 volumio volumio[16688]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 09:09:02 volumio sudo[16916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-03 09:08 Sep 03 09:09:02 volumio sudo[16916]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:09:02 volumio sudo[16916]: pam_unix(sudo:session): session closed for user root Sep 03 09:09:03 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 03 09:09:03 volumio sudo[16879]: pam_unix(sudo:session): session closed for user root Sep 03 09:09:03 volumio sudo[16792]: pam_unix(sudo:session): session closed for user root Sep 03 09:09:03 volumio sudo[16817]: pam_unix(sudo:session): session closed for user root Sep 03 09:09:03 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 03 09:09:03 volumio mpd[16890]: Sep 03 09:09 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 03 09:09:03 volumio systemd[1]: Started dynamicswap service. Sep 03 09:09:03 volumio systemd[1]: Started Music Player Daemon. Sep 03 09:09:03 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 03 09:09:03 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 03 09:09:03 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 7447. Sep 03 09:09:03 volumio systemd[1]: Stopped Volumio Backend Module. Sep 03 09:09:03 volumio systemd[1]: Started Volumio Backend Module. Sep 03 09:09:03 volumio systemd[1]: Started dynamicswap service. Sep 03 09:09:03 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 03 09:09:03 volumio volumio-remote-updater[504]: [2025-09-03 09:09:03] [info] asio async_connect error: system:111 (Connection refused) Sep 03 09:09:03 volumio volumio-remote-updater[504]: [2025-09-03 09:09:03] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 03 09:09:03 volumio volumio-remote-updater[504]: [2025-09-03 09:09:03] [error] handle_connect error: Underlying Transport Error Sep 03 09:09:05 volumio volumio[16929]: info: ------------------------------------------- Sep 03 09:09:05 volumio volumio[16929]: info: ----- Volumio3 ---- Sep 03 09:09:05 volumio volumio[16929]: info: ------------------------------------------- Sep 03 09:09:05 volumio volumio[16929]: info: ----- System startup ---- Sep 03 09:09:05 volumio volumio[16929]: info: ------------------------------------------- Sep 03 09:09:05 volumio volumio[16929]: info: MYVOLUMIO Environment detected Sep 03 09:09:05 volumio volumio[16929]: info: Plugin folders cleanup Sep 03 09:09:05 volumio volumio[16929]: info: Scanning into folder /volumio/app/plugins/ Sep 03 09:09:05 volumio volumio[16929]: info: Scanning category audio_interface Sep 03 09:09:05 volumio volumio[16929]: info: Scanning category miscellanea Sep 03 09:09:05 volumio volumio[16929]: info: Scanning category music_service Sep 03 09:09:05 volumio volumio[16929]: info: Scanning category plugins.json Sep 03 09:09:05 volumio volumio[16929]: info: Scanning category system_controller Sep 03 09:09:05 volumio volumio[16929]: info: Scanning category user_interface Sep 03 09:09:05 volumio volumio[16929]: info: Scanning into folder /data/plugins/ Sep 03 09:09:05 volumio volumio[16929]: info: Scanning category audio_interface Sep 03 09:09:05 volumio volumio[16929]: info: Scanning category music_service Sep 03 09:09:05 volumio volumio[16929]: info: Scanning category user_interface Sep 03 09:09:05 volumio volumio[16929]: info: Plugin folders cleanup completed Sep 03 09:09:05 volumio volumio[16929]: info: ------------------------------------------- Sep 03 09:09:05 volumio volumio[16929]: info: ----- Core plugins startup ---- Sep 03 09:09:05 volumio volumio[16929]: info: ------------------------------------------- Sep 03 09:09:05 volumio volumio[16929]: info: Loading plugins from folder /volumio/app/plugins/ Sep 03 09:09:06 volumio volumio[16929]: info: Adding plugin upnp to MyMusic Plugins Sep 03 09:09:06 volumio volumio[16929]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 03 09:09:06 volumio volumio[16929]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 03 09:09:06 volumio volumio[16929]: info: Loading plugins from folder /data/plugins/ Sep 03 09:09:06 volumio volumio[16929]: info: Loading plugin "system"... Sep 03 09:09:06 volumio volumio[16929]: info: Loading plugin "appearance"... Sep 03 09:09:07 volumio volumio[16929]: info: Loading plugin "network"... Sep 03 09:09:07 volumio volumio[16929]: info: Refreshing Cached IP Addresses Sep 03 09:09:07 volumio sudo[16956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 03 09:09:07 volumio sudo[16956]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:09:07 volumio sudo[16956]: pam_unix(sudo:session): session closed for user root Sep 03 09:09:07 volumio volumio[16929]: info: Loading plugin "services"... Sep 03 09:09:07 volumio sudo[16958]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 09:09:07 volumio sudo[16958]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:09:07 volumio volumio[16929]: info: Loading plugin "alsa_controller"... Sep 03 09:09:07 volumio sudo[16958]: pam_unix(sudo:session): session closed for user root Sep 03 09:09:07 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 09:09:07 volumio volumio[16929]: info: Loading plugin "wizard"... Sep 03 09:09:07 volumio volumio[16929]: info: Loading plugin "networkfs"... Sep 03 09:09:07 volumio volumio[16929]: info: Starting Udev Watcher for removable devices Sep 03 09:09:07 volumio volumio[16929]: info: Ignoring mount for partition: boot Sep 03 09:09:07 volumio volumio[16929]: info: Ignoring mount for partition: volumio Sep 03 09:09:07 volumio volumio[16929]: info: Ignoring mount for partition: volumio_data Sep 03 09:09:07 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 09:09:07 volumio volumio[16929]: info: Loading plugin "volumio_command_line_client"... Sep 03 09:09:07 volumio volumio[16929]: info: Loading plugin "upnp"... Sep 03 09:09:07 volumio volumio[16929]: info: [1756883347313] Starting Upmpd Daemon Sep 03 09:09:07 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 09:09:07 volumio volumio[16929]: info: Loading plugin "my_music"... Sep 03 09:09:07 volumio volumio[16929]: info: Loading plugin "mpd"... Sep 03 09:09:07 volumio volumio[16929]: info: Plugin upnp_browser is not enabled Sep 03 09:09:07 volumio volumio[16929]: info: Loading plugin "alarm-clock"... Sep 03 09:09:07 volumio volumio[16929]: info: Plugin airplay_emulation is not enabled Sep 03 09:09:07 volumio volumio[16929]: info: Loading plugin "last_100"... Sep 03 09:09:07 volumio volumio[16929]: info: Loading plugin "webradio"... Sep 03 09:09:07 volumio volumio[16929]: info: Loading plugin "i2s_dacs"... Sep 03 09:09:07 volumio volumio[16929]: info: Loading plugin "volumiodiscovery"... Sep 03 09:09:08 volumio volumio[16929]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 03 09:09:08 volumio volumio[16929]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 09:09:08 volumio volumio[16929]: *** WARNING *** For more information see Sep 03 09:09:08 volumio node[16929]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 03 09:09:08 volumio volumio[16929]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 03 09:09:08 volumio volumio[16929]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 09:09:08 volumio volumio[16929]: *** WARNING *** For more information see Sep 03 09:09:08 volumio node[16929]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 09:09:08 volumio node[16929]: *** WARNING *** For more information see Sep 03 09:09:08 volumio node[16929]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 03 09:09:08 volumio node[16929]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 09:09:08 volumio node[16929]: *** WARNING *** For more information see Sep 03 09:09:08 volumio volumio[16929]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 03 09:09:08 volumio volumio[16929]: info: Discovery: Started advertising with name: Volumio Sep 03 09:09:08 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 09:09:08 volumio volumio[16929]: info: Loading plugin "jellyfin"... Sep 03 09:09:08 volumio volumio[16929]: info: Loading plugin "spop"... Sep 03 09:09:09 volumio volumio[16929]: info: Loading plugin "ytcr"... Sep 03 09:09:13 volumio volumio-remote-updater[504]: [2025-09-03 09:09:13] [connect] Successful connection Sep 03 09:09:14 volumio volumio[16929]: info: Loading plugin "outputs"... Sep 03 09:09:14 volumio volumio[16929]: info: Loading plugin "albumart"... Sep 03 09:09:14 volumio volumio[16929]: info: Plugin example_plugin is not enabled Sep 03 09:09:14 volumio volumio[16929]: info: Loading plugin "inputs"... Sep 03 09:09:14 volumio volumio[16929]: info: Loading plugin "updater_comm"... Sep 03 09:09:14 volumio volumio[16929]: info: Plugin mpdemulation is not enabled Sep 03 09:09:14 volumio volumio[16929]: info: Loading plugin "rest_api"... Sep 03 09:09:14 volumio volumio[16929]: info: Loading plugin "websocket"... Sep 03 09:09:14 volumio volumio[16929]: info: Starting Socket.io Server version 2.3.0 Sep 03 09:09:14 volumio volumio[16929]: info: Loading plugin "fusiondsp"... Sep 03 09:09:14 volumio volumio[16929]: Forking 3 albumart workers Sep 03 09:09:14 volumio volumio[16929]: info: Applying required configuration parameters for plugin fusiondsp Sep 03 09:09:14 volumio volumio[16929]: info: Loading plugin "Systeminfo"... Sep 03 09:09:15 volumio volumio[16929]: info: Loading i18n strings for locale hu Sep 03 09:09:15 volumio volumio[16929]: Updating browse sources language Sep 03 09:09:15 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 09:09:15 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 03 09:09:15 volumio volumio[16929]: info: CoreCommandRouter::initPlayerControls Sep 03 09:09:15 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:15 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:15 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:15 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:15 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:15 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:15 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:15 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:15 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 03 09:09:15 volumio volumio[16929]: Express server listening on port 3000 Sep 03 09:09:15 volumio volumio[16929]: [Metrics] WebUI: 10s 986.38ms Sep 03 09:09:15 volumio volumio[16929]: info: CoreStateMachine::resetVolumioState Sep 03 09:09:15 volumio volumio[16929]: info: CoreStateMachine::getcurrentVolume Sep 03 09:09:15 volumio volumio[16929]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 09:09:15 volumio volumio[16929]: Starting albumart workers Sep 03 09:09:15 volumio volumio[16929]: info: Volumio Network Manager: Network status updated: 1 Sep 03 09:09:15 volumio volumio[16929]: Starting albumart workers Sep 03 09:09:15 volumio volumio[16929]: Starting albumart workers Sep 03 09:09:15 volumio volumio-remote-updater[504]: [2025-09-03 09:09:15] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1756883353 101 Sep 03 09:09:15 volumio volumio[16929]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Sep 03 09:09:16 volumio volumio[16929]: info: CoreCommandRouter::volumioGetState Sep 03 09:09:16 volumio volumio[16929]: info: CorePlayQueue::getTrack 0 Sep 03 09:09:16 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 09:09:16 volumio volumio[16929]: info: VolumeController:: Volume=37 Mute =false Sep 03 09:09:16 volumio volumio[16929]: info: CoreStateMachine::pushState Sep 03 09:09:16 volumio volumio[16929]: info: CorePlayQueue::getTrack 0 Sep 03 09:09:16 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 09:09:16 volumio volumio[16929]: info: CoreCommandRouter::volumioPushState Sep 03 09:09:16 volumio volumio[16929]: info: CoreStateMachine::updateTrackBlock Sep 03 09:09:16 volumio volumio[16929]: info: CorePlayQueue::getTrackBlock Sep 03 09:09:16 volumio volumio[16929]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 09:09:16 volumio volumio[16929]: info: CoreStateMachine::setRepeat false single undefined Sep 03 09:09:16 volumio volumio[16929]: info: CoreStateMachine::pushState Sep 03 09:09:16 volumio volumio[16929]: info: CorePlayQueue::getTrack 0 Sep 03 09:09:16 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 09:09:16 volumio volumio[16929]: info: CoreCommandRouter::volumioPushState Sep 03 09:09:16 volumio volumio[16929]: info: CoreStateMachine::setRandom true Sep 03 09:09:16 volumio volumio[16929]: info: CoreStateMachine::pushState Sep 03 09:09:16 volumio volumio[16929]: info: CorePlayQueue::getTrack 0 Sep 03 09:09:16 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 09:09:16 volumio volumio[16929]: info: CoreCommandRouter::volumioPushState Sep 03 09:09:16 volumio volumio[16929]: info: Setting Device type: Raspberry PI Sep 03 09:09:16 volumio volumio[16929]: info: Listing playlists Sep 03 09:09:16 volumio volumio[16929]: info: Completed loading Core Plugins Sep 03 09:09:16 volumio volumio[16929]: info: Preparing to generate the ALSA configuration file Sep 03 09:09:16 volumio volumio[16929]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227 Sep 03 09:09:16 volumio volumio[16929]: info: Discovery: Found device Volumio Sep 03 09:09:16 volumio volumio[16929]: info: CoreCommandRouter::volumioGetState Sep 03 09:09:16 volumio volumio[16929]: info: CorePlayQueue::getTrack 0 Sep 03 09:09:16 volumio volumio[16929]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 03 09:09:16 volumio volumio[16929]: info: Reading ALSA contributions from plugins. Sep 03 09:09:16 volumio volumio[16929]: info: VolumeController:: Volume=37 Mute =false Sep 03 09:09:16 volumio volumio[16929]: info: CoreStateMachine::pushState Sep 03 09:09:16 volumio volumio[16929]: info: CorePlayQueue::getTrack 0 Sep 03 09:09:16 volumio volumio[16929]: info: CoreCommandRouter::volumioPushState Sep 03 09:09:16 volumio volumio[16929]: info: Asound.conf file unchanged, so no further update is needed Sep 03 09:09:16 volumio volumio[16929]: info: Output device has changed, restarting MPD Sep 03 09:09:16 volumio sudo[17070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 09:09:16 volumio sudo[17070]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:09:16 volumio volumio[16929]: info: ___________ START PLUGINS ___________ Sep 03 09:09:16 volumio sudo[17070]: pam_unix(sudo:session): session closed for user root Sep 03 09:09:16 volumio volumio[16929]: info: ControllerMpd::onStart: Initializing MPD Sep 03 09:09:16 volumio volumio[16929]: info: Creating MPD Configuration file Sep 03 09:09:16 volumio sudo[17073]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 09:09:16 volumio sudo[17073]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:09:16 volumio systemd[1]: Stopping Music Player Daemon... Sep 03 09:09:16 volumio volumio[16929]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 09:09:16 volumio volumio[16929]: info: [1756883356689] CoreMusicLibrary::Adding element Last_100 Sep 03 09:09:16 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 09:09:16 volumio systemd[1]: mpd.service: Succeeded. Sep 03 09:09:16 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 09:09:16 volumio volumio[16929]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 09:09:16 volumio volumio[16929]: info: [1756883356700] CoreMusicLibrary::Adding element Webradio Sep 03 09:09:16 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 09:09:16 volumio sudo[17091]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 09:09:16 volumio sudo[17091]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:09:16 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 09:09:16 volumio sudo[17088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 09:09:16 volumio sudo[17088]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:09:16 volumio volumio[16929]: info: Initializing BBC Radios Sep 03 09:09:16 volumio sudo[17088]: pam_unix(sudo:session): session closed for user root Sep 03 09:09:16 volumio systemd[1]: Starting Music Player Daemon... Sep 03 09:09:16 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 03 09:09:16 volumio systemd[1]: mpd.service: Succeeded. Sep 03 09:09:16 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 09:09:16 volumio systemd[1]: Starting Music Player Daemon... Sep 03 09:09:16 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 09:09:16 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 09:09:16 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 09:09:16 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 03 09:09:16 volumio volumio[16929]: info: Discovery: Getting this device information Sep 03 09:09:16 volumio volumio[16929]: info: CoreCommandRouter::volumioGetState Sep 03 09:09:16 volumio volumio[16929]: info: CorePlayQueue::getTrack 0 Sep 03 09:09:16 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 09:09:16 volumio volumio[16929]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 09:09:16 volumio volumio[16929]: info: [1756883356875] CoreMusicLibrary::Adding element Jellyfin Sep 03 09:09:16 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 09:09:16 volumio volumio[16929]: Cannot find translation for source Jellyfin Sep 03 09:09:16 volumio volumio[16929]: info: [jellyfin] Initialized plugin with device info: {"id":"efb65fd7-7648-487c-b3ba-ebfb5cc10227","host":"http://192.168.0.171","name":"Volumio","type":"device","serviceName":"Volumio","state":{"status":"stop","volume":37,"mute":false,"artist":"","track":"","albumart":"http://192.168.0.171/albumart"}} Sep 03 09:09:16 volumio volumio[16929]: info: Creating Spotify config file Sep 03 09:09:16 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:16 volumio sudo[17100]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 03 09:09:16 volumio sudo[17100]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:09:16 volumio sudo[17100]: pam_unix(sudo:session): session closed for user root Sep 03 09:09:17 volumio volumio[16929]: info: Loading i18n strings for locale hu Sep 03 09:09:17 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 03 09:09:17 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:17 volumio volumio[16929]: info: FusionDsp - mixtype--------------------- Hardware Sep 03 09:09:17 volumio volumio[16929]: info: Volumio Calling Home Sep 03 09:09:17 volumio volumio[16929]: info: Preparing to generate the ALSA configuration file Sep 03 09:09:17 volumio sudo[17129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 03 09:09:17 volumio sudo[17129]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:09:17 volumio sudo[17129]: pam_unix(sudo:session): session closed for user root Sep 03 09:09:17 volumio volumio[16929]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 03 09:09:17 volumio volumio[16929]: info: Reading ALSA contributions from plugins. Sep 03 09:09:17 volumio volumio[16929]: info: MPD Permissions set Sep 03 09:09:17 volumio sudo[17132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 09:09:17 volumio volumio[16929]: info: MPD Permissions set Sep 03 09:09:17 volumio sudo[17132]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:09:17 volumio sudo[17132]: pam_unix(sudo:session): session closed for user root Sep 03 09:09:17 volumio volumio[16929]: info: Spotify config file written Sep 03 09:09:17 volumio sudo[17135]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 03 09:09:17 volumio sudo[17135]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:09:17 volumio systemd[1]: Stopping go-librespot Daemon... Sep 03 09:09:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 03 09:09:17 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 03 09:09:17 volumio systemd[1]: Stopped go-librespot Daemon. Sep 03 09:09:17 volumio systemd[1]: Started go-librespot Daemon. Sep 03 09:09:17 volumio go-librespot[17137]: go-librespot daemon starting... Sep 03 09:09:17 volumio sudo[17135]: pam_unix(sudo:session): session closed for user root Sep 03 09:09:17 volumio go-librespot[17137]: time="2025-09-03T09:09:17+02:00" level=info msg="running go-librespot 0.3.2" Sep 03 09:09:17 volumio go-librespot[17137]: time="2025-09-03T09:09:17+02:00" level=debug msg="app state loaded" Sep 03 09:09:17 volumio go-librespot[17137]: time="2025-09-03T09:09:17+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 03 09:09:17 volumio volumio[16929]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Sep 03 09:09:17 volumio volumio[16929]: info: Volumio called home Sep 03 09:09:17 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:17 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:17 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:17 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:17 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:17 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:17 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:17 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:17 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 09:09:17 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:17 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:17 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:17 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:17 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:17 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:17 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:17 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:17 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:17 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 09:09:17 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:17 volumio volumio[16929]: info: No need to fix Spotify hosts Sep 03 09:09:17 volumio go-librespot[17137]: time="2025-09-03T09:09:17+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Sep 03 09:09:17 volumio go-librespot[17137]: time="2025-09-03T09:09:17+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 03 09:09:17 volumio go-librespot[17137]: time="2025-09-03T09:09:17+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 03 09:09:17 volumio go-librespot[17137]: time="2025-09-03T09:09:17+02:00" level=info msg="zeroconf server listening on port 36003" Sep 03 09:09:18 volumio go-librespot[17137]: time="2025-09-03T09:09:18+02:00" level=debug msg="obtained new client token: AAB/SmfvyKoTDnquZhW8tx6vVnYTTMfkmSAAd7NanwxH70eseibySkWorQ80+Gxk947kS7/Mxx7ynueYLSuFwSzPowGpHxXj+yMKCCxwWim6ViF6W6UsV/NYN7Hdo78wnlRvE6wa1PBM5Rz0aSHmwAQUedoWy//ht4MguWr+/0sM7gDLx4IkhJ6zKL+RyxRSOyAAVlYXeSWJvExuv2UWb61uZM/zWUavTFE6TvH52hy17VOu+/OeoN4O" Sep 03 09:09:18 volumio volumio[16929]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 03 09:09:18 volumio volumio[16929]: SPOTIFY: BQAC2QK0B-R7ImF9FcNX-mXRWUrhUvhmhY1cm6AOKGSQ6ef5br8qZPFZuEF93Cv387t6zVXOi5evmZqzpj8MqmWaDFfU6gwFy-3UB10lTxRyR5Y3USd_yZeg2S7f4UdNYa7LzSj_Ugvs4E6GaOj_lSdt7VzrU5dVMPLS4EOVWhuA-pnkPtjXIdGKlyHmibccgZsQRfWujxa576nUFF31dfJ2UShNxKJjt6d4wyrDF1lPwI4iEMUUrbJrt8eYbTaii7bafULUc_ZljXDb7xHaf7vaF_Jb6s4tnACx9x1ApsZVAGY Sep 03 09:09:18 volumio volumio[16929]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 03 09:09:18 volumio volumio[16929]: info: New Spotify access token = BQAC2QK0B-R7ImF9FcNX-mXRWUrhUvhmhY1cm6AOKGSQ6ef5br8qZPFZuEF93Cv387t6zVXOi5evmZqzpj8MqmWaDFfU6gwFy-3UB10lTxRyR5Y3USd_yZeg2S7f4UdNYa7LzSj_Ugvs4E6GaOj_lSdt7VzrU5dVMPLS4EOVWhuA-pnkPtjXIdGKlyHmibccgZsQRfWujxa576nUFF31dfJ2UShNxKJjt6d4wyrDF1lPwI4iEMUUrbJrt8eYbTaii7bafULUc_ZljXDb7xHaf7vaF_Jb6s4tnACx9x1ApsZVAGY Sep 03 09:09:18 volumio volumio[16929]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 03 09:09:18 volumio go-librespot[17137]: time="2025-09-03T09:09:18+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 03 09:09:18 volumio volumio[16929]: info: CoreCommandRouter::volumioGetState Sep 03 09:09:18 volumio volumio[16929]: info: CorePlayQueue::getTrack 0 Sep 03 09:09:18 volumio go-librespot[17137]: time="2025-09-03T09:09:18+02:00" level=debug msg="completed keyexchange" Sep 03 09:09:18 volumio go-librespot[17137]: time="2025-09-03T09:09:18+02:00" level=debug msg="completed challenge" Sep 03 09:09:18 volumio sudo[17155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 03 09:09:18 volumio sudo[17155]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:09:18 volumio go-librespot[17137]: time="2025-09-03T09:09:18+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 03 09:09:18 volumio volumio[16929]: info: Asound.conf file unchanged, so no further update is needed Sep 03 09:09:18 volumio volumio[16929]: info: Output device has changed, restarting MPD Sep 03 09:09:18 volumio sudo[17155]: pam_unix(sudo:session): session closed for user root Sep 03 09:09:18 volumio sudo[17158]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 09:09:18 volumio sudo[17158]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:09:18 volumio sudo[17158]: pam_unix(sudo:session): session closed for user root Sep 03 09:09:18 volumio sudo[17160]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 09:09:18 volumio sudo[17160]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:09:18 volumio go-librespot[17137]: time="2025-09-03T09:09:18+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 03 09:09:18 volumio go-librespot[17137]: time="2025-09-03T09:09:18+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 03 09:09:18 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 03 09:09:18 volumio systemd[1]: mpd.service: Succeeded. Sep 03 09:09:18 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 09:09:18 volumio volumio[16929]: info: MPD Permissions set Sep 03 09:09:18 volumio volumio[16929]: info: Upmpdcli Daemon Started Sep 03 09:09:18 volumio systemd[1]: Starting Music Player Daemon... Sep 03 09:09:18 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:18 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:18 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:18 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:18 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:18 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:18 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:18 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:18 volumio go-librespot[17137]: time="2025-09-03T09:09:18+02:00" level=debug msg="dealer connection opened" Sep 03 09:09:18 volumio go-librespot[17137]: time="2025-09-03T09:09:18+02:00" level=trace msg="starting accesspoint recv loop" Sep 03 09:09:18 volumio go-librespot[17137]: time="2025-09-03T09:09:18+02:00" level=trace msg="starting dealer recv loop" Sep 03 09:09:18 volumio go-librespot[17137]: time="2025-09-03T09:09:18+02:00" level=trace msg="received accesspoint ping" Sep 03 09:09:18 volumio go-librespot[17137]: time="2025-09-03T09:09:18+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 03 09:09:18 volumio go-librespot[17137]: time="2025-09-03T09:09:18+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 03 09:09:18 volumio go-librespot[17137]: time="2025-09-03T09:09:18+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 03 09:09:18 volumio go-librespot[17137]: time="2025-09-03T09:09:18+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 03 09:09:18 volumio go-librespot[17137]: time="2025-09-03T09:09:18+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 03 09:09:18 volumio go-librespot[17137]: time="2025-09-03T09:09:18+02:00" level=debug msg="received connection id: ZmNhOTFiOWMtNmM3...MzRDQ0Q3M0M2Mw==" Sep 03 09:09:18 volumio go-librespot[17137]: time="2025-09-03T09:09:18+02:00" level=trace msg="received accesspoint pong ack" Sep 03 09:09:18 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 09:09:18 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:18 volumio volumio[16929]: SPOTIFY: User informations: {"country":"HU","display_name":"4Misi","email":"sztako.mihaly@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/lo8jr34h0xh1vbcv2lgmaqkvv"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/lo8jr34h0xh1vbcv2lgmaqkvv","id":"lo8jr34h0xh1vbcv2lgmaqkvv","images":[],"product":"premium","type":"user","uri":"spotify:user:lo8jr34h0xh1vbcv2lgmaqkvv"} Sep 03 09:09:18 volumio volumio[16929]: info: Spotify Successfully logged in Sep 03 09:09:18 volumio volumio[16929]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 09:09:18 volumio volumio[16929]: info: [1756883358470] CoreMusicLibrary::Adding element Spotify Sep 03 09:09:18 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 09:09:18 volumio volumio[16929]: Cannot find translation for source Jellyfin Sep 03 09:09:18 volumio volumio[16929]: Cannot find translation for source Spotify Sep 03 09:09:18 volumio go-librespot[17137]: time="2025-09-03T09:09:18+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 03 09:09:18 volumio sudo[17164]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 03 09:09:18 volumio sudo[17164]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:09:18 volumio sudo[17164]: pam_unix(sudo:session): session closed for user root Sep 03 09:09:19 volumio volumio[16929]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 03 09:09:19 volumio volumio[16929]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 09:09:19 volumio volumio[16929]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 03 09:09:19 volumio volumio[16929]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Sep 03 09:09:19 volumio volumio[16929]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 03 09:09:19 volumio volumio[16929]: info: camilladsp service started and running in background, instance 1 Sep 03 09:09:19 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:19 volumio volumio[16929]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 03 09:09:19 volumio volumio[16929]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 03 09:09:19 volumio volumio[16929]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 03 09:09:19 volumio volumio[16929]: info: FusionDsp loaded Sep 03 09:09:19 volumio volumio[16929]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 09:09:19 volumio sudo[17183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 03 09:09:19 volumio sudo[17183]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:09:19 volumio volumio[16929]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 03 09:09:19 volumio volumio[16929]: info: Adding Signal Path Element [object Object] Sep 03 09:09:19 volumio volumio[16929]: info: Adding fusiondspeq DSP Signal Path Element Sep 03 09:09:19 volumio volumio[16929]: info: FusionDsp - ---- installed callbackRead Sep 03 09:09:19 volumio sudo[17183]: pam_unix(sudo:session): session closed for user root Sep 03 09:09:19 volumio volumio[16929]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 09:09:19 volumio volumio[16929]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 03 09:09:19 volumio volumio[16929]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 03 09:09:19 volumio volumio[16929]: at onErrorNT (internal/child_process.js:465:16) Sep 03 09:09:19 volumio volumio[16929]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Sep 03 09:09:19 volumio volumio[16929]: errno: -2, Sep 03 09:09:19 volumio volumio[16929]: code: 'ENOENT', Sep 03 09:09:19 volumio volumio[16929]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 03 09:09:19 volumio volumio[16929]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 03 09:09:19 volumio volumio[16929]: spawnargs: [ Sep 03 09:09:19 volumio volumio[16929]: '-p', Sep 03 09:09:19 volumio volumio[16929]: 9876, Sep 03 09:09:19 volumio volumio[16929]: '-o', Sep 03 09:09:19 volumio volumio[16929]: '/tmp/camilladsp.log', Sep 03 09:09:19 volumio volumio[16929]: '-l', Sep 03 09:09:19 volumio volumio[16929]: 'warn', Sep 03 09:09:19 volumio volumio[16929]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 03 09:09:19 volumio volumio[16929]: ] Sep 03 09:09:19 volumio volumio[16929]: } Sep 03 09:09:19 volumio volumio[16929]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 09:09:19 volumio sudo[17195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-03 09:08 Sep 03 09:09:19 volumio sudo[17195]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:09:20 volumio sudo[17195]: pam_unix(sudo:session): session closed for user root Sep 03 09:09:20 volumio mpd[17170]: Sep 03 09:09 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 03 09:09:20 volumio systemd[1]: Started Music Player Daemon. Sep 03 09:09:20 volumio sudo[17160]: pam_unix(sudo:session): session closed for user root Sep 03 09:09:20 volumio sudo[17073]: pam_unix(sudo:session): session closed for user root Sep 03 09:09:20 volumio sudo[17091]: pam_unix(sudo:session): session closed for user root Sep 03 09:09:20 volumio volumio-remote-updater[504]: [2025-09-03 09:09:20] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 03 09:09:20 volumio volumio-remote-updater[504]: [2025-09-03 09:09:20] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 03 09:09:20 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 03 09:09:20 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 03 09:09:20 volumio systemd[1]: Started dynamicswap service. Sep 03 09:09:20 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 03 09:09:20 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 03 09:09:20 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 7448. Sep 03 09:09:20 volumio systemd[1]: Stopped Volumio Backend Module. Sep 03 09:09:20 volumio systemd[1]: Started Volumio Backend Module. Sep 03 09:09:20 volumio systemd[1]: Started dynamicswap service. Sep 03 09:09:20 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 03 09:09:22 volumio volumio[17207]: info: ------------------------------------------- Sep 03 09:09:22 volumio volumio[17207]: info: ----- Volumio3 ---- Sep 03 09:09:22 volumio volumio[17207]: info: ------------------------------------------- Sep 03 09:09:22 volumio volumio[17207]: info: ----- System startup ---- Sep 03 09:09:22 volumio volumio[17207]: info: ------------------------------------------- Sep 03 09:09:22 volumio volumio[17207]: info: MYVOLUMIO Environment detected Sep 03 09:09:23 volumio volumio[17207]: info: Plugin folders cleanup Sep 03 09:09:23 volumio volumio[17207]: info: Scanning into folder /volumio/app/plugins/ Sep 03 09:09:23 volumio volumio[17207]: info: Scanning category audio_interface Sep 03 09:09:23 volumio volumio[17207]: info: Scanning category miscellanea Sep 03 09:09:23 volumio volumio[17207]: info: Scanning category music_service Sep 03 09:09:23 volumio volumio[17207]: info: Scanning category plugins.json Sep 03 09:09:23 volumio volumio[17207]: info: Scanning category system_controller Sep 03 09:09:23 volumio volumio[17207]: info: Scanning category user_interface Sep 03 09:09:23 volumio volumio[17207]: info: Scanning into folder /data/plugins/ Sep 03 09:09:23 volumio volumio[17207]: info: Scanning category audio_interface Sep 03 09:09:23 volumio volumio[17207]: info: Scanning category music_service Sep 03 09:09:23 volumio volumio[17207]: info: Scanning category user_interface Sep 03 09:09:23 volumio volumio[17207]: info: Plugin folders cleanup completed Sep 03 09:09:23 volumio volumio[17207]: info: ------------------------------------------- Sep 03 09:09:23 volumio volumio[17207]: info: ----- Core plugins startup ---- Sep 03 09:09:23 volumio volumio[17207]: info: ------------------------------------------- Sep 03 09:09:23 volumio volumio[17207]: info: Loading plugins from folder /volumio/app/plugins/ Sep 03 09:09:23 volumio volumio[17207]: info: Adding plugin upnp to MyMusic Plugins Sep 03 09:09:23 volumio volumio[17207]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 03 09:09:23 volumio volumio[17207]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 03 09:09:23 volumio volumio[17207]: info: Loading plugins from folder /data/plugins/ Sep 03 09:09:23 volumio volumio[17207]: info: Loading plugin "system"... Sep 03 09:09:23 volumio volumio[17207]: info: Loading plugin "appearance"... Sep 03 09:09:24 volumio volumio[17207]: info: Loading plugin "network"... Sep 03 09:09:24 volumio volumio[17207]: info: Refreshing Cached IP Addresses Sep 03 09:09:24 volumio sudo[17237]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 03 09:09:24 volumio sudo[17237]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:09:24 volumio sudo[17237]: pam_unix(sudo:session): session closed for user root Sep 03 09:09:24 volumio sudo[17239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 09:09:24 volumio volumio[17207]: info: Loading plugin "services"... Sep 03 09:09:24 volumio sudo[17239]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:09:24 volumio volumio[17207]: info: Loading plugin "alsa_controller"... Sep 03 09:09:24 volumio sudo[17239]: pam_unix(sudo:session): session closed for user root Sep 03 09:09:24 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 09:09:24 volumio volumio[17207]: info: Loading plugin "wizard"... Sep 03 09:09:24 volumio volumio[17207]: info: Loading plugin "networkfs"... Sep 03 09:09:24 volumio volumio[17207]: info: Starting Udev Watcher for removable devices Sep 03 09:09:24 volumio volumio[17207]: info: Ignoring mount for partition: boot Sep 03 09:09:24 volumio volumio[17207]: info: Ignoring mount for partition: volumio Sep 03 09:09:24 volumio volumio[17207]: info: Ignoring mount for partition: volumio_data Sep 03 09:09:24 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 09:09:24 volumio volumio[17207]: info: Loading plugin "volumio_command_line_client"... Sep 03 09:09:24 volumio volumio[17207]: info: Loading plugin "upnp"... Sep 03 09:09:24 volumio volumio[17207]: info: [1756883364352] Starting Upmpd Daemon Sep 03 09:09:24 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 09:09:24 volumio volumio[17207]: info: Loading plugin "my_music"... Sep 03 09:09:24 volumio volumio[17207]: info: Loading plugin "mpd"... Sep 03 09:09:24 volumio volumio[17207]: info: Plugin upnp_browser is not enabled Sep 03 09:09:24 volumio volumio[17207]: info: Loading plugin "alarm-clock"... Sep 03 09:09:24 volumio volumio[17207]: info: Plugin airplay_emulation is not enabled Sep 03 09:09:24 volumio volumio[17207]: info: Loading plugin "last_100"... Sep 03 09:09:24 volumio volumio[17207]: info: Loading plugin "webradio"... Sep 03 09:09:25 volumio volumio[17207]: info: Loading plugin "i2s_dacs"... Sep 03 09:09:25 volumio volumio[17207]: info: Loading plugin "volumiodiscovery"... Sep 03 09:09:25 volumio volumio[17207]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 03 09:09:25 volumio volumio[17207]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 09:09:25 volumio volumio[17207]: *** WARNING *** For more information see Sep 03 09:09:25 volumio node[17207]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 03 09:09:25 volumio volumio[17207]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 03 09:09:25 volumio volumio[17207]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 09:09:25 volumio volumio[17207]: *** WARNING *** For more information see Sep 03 09:09:25 volumio node[17207]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 09:09:25 volumio node[17207]: *** WARNING *** For more information see Sep 03 09:09:25 volumio node[17207]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 03 09:09:25 volumio node[17207]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 09:09:25 volumio node[17207]: *** WARNING *** For more information see Sep 03 09:09:25 volumio volumio[17207]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 03 09:09:25 volumio volumio[17207]: info: Discovery: Started advertising with name: Volumio Sep 03 09:09:25 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 09:09:25 volumio volumio[17207]: info: Loading plugin "jellyfin"... Sep 03 09:09:25 volumio volumio-remote-updater[504]: [2025-09-03 09:09:25] [connect] Successful connection Sep 03 09:09:25 volumio volumio[17207]: info: Loading plugin "spop"... Sep 03 09:09:27 volumio volumio[17207]: info: Loading plugin "ytcr"... Sep 03 09:09:31 volumio volumio[17207]: info: Loading plugin "outputs"... Sep 03 09:09:31 volumio volumio[17207]: info: Loading plugin "albumart"... Sep 03 09:09:31 volumio volumio[17207]: info: Plugin example_plugin is not enabled Sep 03 09:09:31 volumio volumio[17207]: info: Loading plugin "inputs"... Sep 03 09:09:31 volumio volumio[17207]: info: Loading plugin "updater_comm"... Sep 03 09:09:31 volumio volumio[17207]: info: Plugin mpdemulation is not enabled Sep 03 09:09:31 volumio volumio[17207]: info: Loading plugin "rest_api"... Sep 03 09:09:31 volumio volumio[17207]: info: Loading plugin "websocket"... Sep 03 09:09:31 volumio volumio[17207]: info: Starting Socket.io Server version 2.3.0 Sep 03 09:09:31 volumio volumio[17207]: info: Loading plugin "fusiondsp"... Sep 03 09:09:31 volumio volumio[17207]: Forking 3 albumart workers Sep 03 09:09:31 volumio volumio[17207]: info: Applying required configuration parameters for plugin fusiondsp Sep 03 09:09:31 volumio volumio[17207]: info: Loading plugin "Systeminfo"... Sep 03 09:09:32 volumio volumio[17207]: info: Loading i18n strings for locale hu Sep 03 09:09:32 volumio volumio[17207]: Updating browse sources language Sep 03 09:09:32 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 09:09:32 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 03 09:09:32 volumio volumio[17207]: info: CoreCommandRouter::initPlayerControls Sep 03 09:09:32 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:32 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:32 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:32 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:32 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:32 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:32 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:32 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:32 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 03 09:09:32 volumio volumio[17207]: Express server listening on port 3000 Sep 03 09:09:32 volumio volumio[17207]: [Metrics] WebUI: 11s 44.04ms Sep 03 09:09:32 volumio volumio[17207]: info: CoreStateMachine::resetVolumioState Sep 03 09:09:32 volumio volumio[17207]: info: CoreStateMachine::getcurrentVolume Sep 03 09:09:32 volumio volumio[17207]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 09:09:32 volumio volumio[17207]: Starting albumart workers Sep 03 09:09:32 volumio volumio[17207]: Starting albumart workers Sep 03 09:09:32 volumio volumio[17207]: info: Volumio Network Manager: Network status updated: 1 Sep 03 09:09:32 volumio volumio[17207]: Starting albumart workers Sep 03 09:09:33 volumio volumio[17207]: info: CoreCommandRouter::volumioGetState Sep 03 09:09:33 volumio volumio[17207]: info: CorePlayQueue::getTrack 0 Sep 03 09:09:33 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 09:09:33 volumio volumio[17207]: info: VolumeController:: Volume=37 Mute =false Sep 03 09:09:33 volumio volumio[17207]: info: CoreStateMachine::pushState Sep 03 09:09:33 volumio volumio[17207]: info: CorePlayQueue::getTrack 0 Sep 03 09:09:33 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 09:09:33 volumio volumio[17207]: info: CoreCommandRouter::volumioPushState Sep 03 09:09:33 volumio volumio[17207]: info: CoreStateMachine::updateTrackBlock Sep 03 09:09:33 volumio volumio[17207]: info: CorePlayQueue::getTrackBlock Sep 03 09:09:33 volumio volumio[17207]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 09:09:33 volumio volumio[17207]: info: CoreStateMachine::setRepeat false single undefined Sep 03 09:09:33 volumio volumio[17207]: info: CoreStateMachine::pushState Sep 03 09:09:33 volumio volumio[17207]: info: CorePlayQueue::getTrack 0 Sep 03 09:09:33 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 09:09:33 volumio volumio[17207]: info: CoreCommandRouter::volumioPushState Sep 03 09:09:33 volumio volumio[17207]: info: CoreStateMachine::setRandom true Sep 03 09:09:33 volumio volumio[17207]: info: CoreStateMachine::pushState Sep 03 09:09:33 volumio volumio[17207]: info: CorePlayQueue::getTrack 0 Sep 03 09:09:33 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 09:09:33 volumio volumio[17207]: info: CoreCommandRouter::volumioPushState Sep 03 09:09:33 volumio volumio[17207]: info: Setting Device type: Raspberry PI Sep 03 09:09:33 volumio volumio[17207]: info: Listing playlists Sep 03 09:09:33 volumio volumio[17207]: info: Listing playlists Sep 03 09:09:33 volumio volumio[17207]: info: Completed loading Core Plugins Sep 03 09:09:33 volumio volumio[17207]: info: Preparing to generate the ALSA configuration file Sep 03 09:09:33 volumio volumio[17207]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227 Sep 03 09:09:33 volumio volumio[17207]: info: Discovery: Found device Volumio Sep 03 09:09:33 volumio volumio[17207]: info: CoreCommandRouter::volumioGetState Sep 03 09:09:33 volumio volumio[17207]: info: CorePlayQueue::getTrack 0 Sep 03 09:09:33 volumio volumio[17207]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 03 09:09:33 volumio volumio[17207]: info: Reading ALSA contributions from plugins. Sep 03 09:09:33 volumio volumio[17207]: info: VolumeController:: Volume=37 Mute =false Sep 03 09:09:33 volumio volumio[17207]: info: CoreStateMachine::pushState Sep 03 09:09:33 volumio volumio[17207]: info: CorePlayQueue::getTrack 0 Sep 03 09:09:33 volumio volumio[17207]: info: CoreCommandRouter::volumioPushState Sep 03 09:09:33 volumio volumio[17207]: info: Asound.conf file unchanged, so no further update is needed Sep 03 09:09:33 volumio volumio[17207]: info: Output device has changed, restarting MPD Sep 03 09:09:33 volumio sudo[17310]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 09:09:33 volumio sudo[17310]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:09:33 volumio sudo[17310]: pam_unix(sudo:session): session closed for user root Sep 03 09:09:33 volumio volumio[17207]: info: ___________ START PLUGINS ___________ Sep 03 09:09:33 volumio volumio[17207]: info: ControllerMpd::onStart: Initializing MPD Sep 03 09:09:33 volumio volumio[17207]: info: Creating MPD Configuration file Sep 03 09:09:33 volumio sudo[17323]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 09:09:33 volumio sudo[17323]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:09:33 volumio systemd[1]: Stopping Music Player Daemon... Sep 03 09:09:33 volumio sudo[17335]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 09:09:33 volumio sudo[17335]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:09:33 volumio volumio[17207]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 09:09:33 volumio volumio[17207]: info: [1756883373633] CoreMusicLibrary::Adding element Last_100 Sep 03 09:09:33 volumio sudo[17335]: pam_unix(sudo:session): session closed for user root Sep 03 09:09:33 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 09:09:33 volumio volumio[17207]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 09:09:33 volumio volumio[17207]: info: [1756883373638] CoreMusicLibrary::Adding element Webradio Sep 03 09:09:33 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 09:09:33 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 09:09:33 volumio systemd[1]: mpd.service: Succeeded. Sep 03 09:09:33 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 09:09:33 volumio sudo[17338]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 09:09:33 volumio sudo[17338]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:09:33 volumio systemd[1]: Starting Music Player Daemon... Sep 03 09:09:33 volumio volumio[17207]: info: Initializing BBC Radios Sep 03 09:09:33 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 03 09:09:33 volumio systemd[1]: mpd.service: Succeeded. Sep 03 09:09:33 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 09:09:33 volumio systemd[1]: Starting Music Player Daemon... Sep 03 09:09:33 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 09:09:33 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 09:09:33 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 09:09:33 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 03 09:09:33 volumio volumio[17207]: info: Discovery: Getting this device information Sep 03 09:09:33 volumio volumio[17207]: info: CoreCommandRouter::volumioGetState Sep 03 09:09:33 volumio volumio[17207]: info: CorePlayQueue::getTrack 0 Sep 03 09:09:33 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 09:09:33 volumio volumio[17207]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 09:09:33 volumio volumio[17207]: info: [1756883373796] CoreMusicLibrary::Adding element Jellyfin Sep 03 09:09:33 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 09:09:33 volumio volumio[17207]: Cannot find translation for source Jellyfin Sep 03 09:09:33 volumio volumio[17207]: info: [jellyfin] Initialized plugin with device info: {"id":"efb65fd7-7648-487c-b3ba-ebfb5cc10227","host":"http://192.168.0.171","name":"Volumio","type":"device","serviceName":"Volumio","state":{"status":"stop","volume":37,"mute":false,"artist":"","track":"","albumart":"http://192.168.0.171/albumart"}} Sep 03 09:09:33 volumio volumio[17207]: info: Creating Spotify config file Sep 03 09:09:33 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:33 volumio sudo[17347]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 03 09:09:33 volumio sudo[17347]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:09:33 volumio sudo[17347]: pam_unix(sudo:session): session closed for user root Sep 03 09:09:33 volumio volumio[17207]: info: Loading i18n strings for locale hu Sep 03 09:09:33 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 03 09:09:33 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:33 volumio volumio[17207]: info: FusionDsp - mixtype--------------------- Hardware Sep 03 09:09:34 volumio volumio[17207]: info: Volumio Calling Home Sep 03 09:09:34 volumio volumio[17207]: info: Preparing to generate the ALSA configuration file Sep 03 09:09:34 volumio volumio[17207]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 03 09:09:34 volumio volumio[17207]: info: Reading ALSA contributions from plugins. Sep 03 09:09:34 volumio volumio[17207]: info: MPD Permissions set Sep 03 09:09:34 volumio volumio[17207]: info: MPD Permissions set Sep 03 09:09:34 volumio volumio[17207]: info: Spotify config file written Sep 03 09:09:34 volumio sudo[17372]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 03 09:09:34 volumio sudo[17372]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:09:34 volumio volumio[17207]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Sep 03 09:09:34 volumio volumio[17207]: info: Volumio called home Sep 03 09:09:34 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:34 volumio systemd[1]: Stopping go-librespot Daemon... Sep 03 09:09:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 03 09:09:34 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 03 09:09:34 volumio systemd[1]: Stopped go-librespot Daemon. Sep 03 09:09:34 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 09:09:34 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:34 volumio go-librespot[17375]: go-librespot daemon starting... Sep 03 09:09:34 volumio systemd[1]: Started go-librespot Daemon. Sep 03 09:09:34 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:34 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:34 volumio sudo[17372]: pam_unix(sudo:session): session closed for user root Sep 03 09:09:34 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:34 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:34 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:34 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:34 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:34 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:34 volumio go-librespot[17375]: time="2025-09-03T09:09:34+02:00" level=info msg="running go-librespot 0.3.2" Sep 03 09:09:34 volumio go-librespot[17375]: time="2025-09-03T09:09:34+02:00" level=debug msg="app state loaded" Sep 03 09:09:34 volumio go-librespot[17375]: time="2025-09-03T09:09:34+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 03 09:09:34 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 09:09:34 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:34 volumio volumio[17207]: info: No need to fix Spotify hosts Sep 03 09:09:34 volumio go-librespot[17375]: time="2025-09-03T09:09:34+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Sep 03 09:09:34 volumio go-librespot[17375]: time="2025-09-03T09:09:34+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 03 09:09:34 volumio go-librespot[17375]: time="2025-09-03T09:09:34+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 03 09:09:34 volumio go-librespot[17375]: time="2025-09-03T09:09:34+02:00" level=info msg="zeroconf server listening on port 41051" Sep 03 09:09:34 volumio go-librespot[17375]: time="2025-09-03T09:09:34+02:00" level=debug msg="obtained new client token: AAD/3SKHaiEfxw2o3UZidiNK13ADyFu17yMTALAS4dYoc8zJjch+nZokqrH8xYsmqB9SBmIbF9skIDgOh5R6eFmEXJ8tIl+Z0I6wIroneYMVsP336VWoqcSUttqX1dJoufsiKDbGv6BenwCYkfHhbXkN5r2AQGvgUgbsKmiHx3yFCtE9UMee9mTnZzcmpsro7ZfiRcvMUj6kWfRDObLgjc3XrtcRackR6mBcI219fD75InTUwJdvwYsK6qU=" Sep 03 09:09:34 volumio sudo[17391]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 03 09:09:34 volumio sudo[17391]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:09:34 volumio go-librespot[17375]: time="2025-09-03T09:09:34+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 03 09:09:34 volumio sudo[17391]: pam_unix(sudo:session): session closed for user root Sep 03 09:09:34 volumio sudo[17394]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 09:09:34 volumio sudo[17394]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:09:34 volumio sudo[17394]: pam_unix(sudo:session): session closed for user root Sep 03 09:09:34 volumio go-librespot[17375]: time="2025-09-03T09:09:34+02:00" level=debug msg="completed keyexchange" Sep 03 09:09:34 volumio go-librespot[17375]: time="2025-09-03T09:09:34+02:00" level=debug msg="completed challenge" Sep 03 09:09:34 volumio go-librespot[17375]: time="2025-09-03T09:09:34+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 03 09:09:35 volumio volumio[17207]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 03 09:09:35 volumio volumio[17207]: SPOTIFY: BQApp-OcWGbi-5DgZkEtgFZGTLCN4gKQWFUqYvofTzqBzeNiDdylVik7m348RuzUrl0oGKFNA2O9ioyTgjkUZcO-wOKYzjvviaVtsEPG1DCDAJ7FYjAfa_QgfOo-W4T-qkYPbrG5jMzIc5ne8_Qc6qzXGOuhS7mB76pQ91EOz3PwSeqDcdkmG7c3s50vZL61zOy_SlucVS863zsOOinP-N-q6IGykEdWOkp33I1PaN2Hjm05ueatzeyCL1UmHdQB3ubP4UMjJDz7NU0MvBbyTHuj3hHaLL7Rw2ngAj_Y5HWqA4o Sep 03 09:09:35 volumio volumio[17207]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 03 09:09:35 volumio volumio[17207]: info: New Spotify access token = BQApp-OcWGbi-5DgZkEtgFZGTLCN4gKQWFUqYvofTzqBzeNiDdylVik7m348RuzUrl0oGKFNA2O9ioyTgjkUZcO-wOKYzjvviaVtsEPG1DCDAJ7FYjAfa_QgfOo-W4T-qkYPbrG5jMzIc5ne8_Qc6qzXGOuhS7mB76pQ91EOz3PwSeqDcdkmG7c3s50vZL61zOy_SlucVS863zsOOinP-N-q6IGykEdWOkp33I1PaN2Hjm05ueatzeyCL1UmHdQB3ubP4UMjJDz7NU0MvBbyTHuj3hHaLL7Rw2ngAj_Y5HWqA4o Sep 03 09:09:35 volumio volumio[17207]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 03 09:09:35 volumio volumio[17207]: info: CoreCommandRouter::volumioGetState Sep 03 09:09:35 volumio volumio[17207]: info: CorePlayQueue::getTrack 0 Sep 03 09:09:35 volumio volumio[17207]: info: Asound.conf file unchanged, so no further update is needed Sep 03 09:09:35 volumio volumio[17207]: info: Output device has changed, restarting MPD Sep 03 09:09:35 volumio go-librespot[17375]: time="2025-09-03T09:09:35+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 03 09:09:35 volumio go-librespot[17375]: time="2025-09-03T09:09:35+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 03 09:09:35 volumio sudo[17397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 09:09:35 volumio sudo[17397]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:09:35 volumio sudo[17397]: pam_unix(sudo:session): session closed for user root Sep 03 09:09:35 volumio sudo[17399]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 09:09:35 volumio sudo[17399]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:09:35 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 03 09:09:35 volumio systemd[1]: mpd.service: Succeeded. Sep 03 09:09:35 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 09:09:35 volumio volumio[17207]: info: MPD Permissions set Sep 03 09:09:35 volumio go-librespot[17375]: time="2025-09-03T09:09:35+02:00" level=debug msg="dealer connection opened" Sep 03 09:09:35 volumio go-librespot[17375]: time="2025-09-03T09:09:35+02:00" level=trace msg="starting accesspoint recv loop" Sep 03 09:09:35 volumio go-librespot[17375]: time="2025-09-03T09:09:35+02:00" level=trace msg="starting dealer recv loop" Sep 03 09:09:35 volumio go-librespot[17375]: time="2025-09-03T09:09:35+02:00" level=trace msg="received accesspoint ping" Sep 03 09:09:35 volumio go-librespot[17375]: time="2025-09-03T09:09:35+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 03 09:09:35 volumio go-librespot[17375]: time="2025-09-03T09:09:35+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 03 09:09:35 volumio go-librespot[17375]: time="2025-09-03T09:09:35+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 03 09:09:35 volumio go-librespot[17375]: time="2025-09-03T09:09:35+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 03 09:09:35 volumio go-librespot[17375]: time="2025-09-03T09:09:35+02:00" level=debug msg="received connection id: Yzc5MTM0NWYtNjg4...MzNFQzA2OTQ4RA==" Sep 03 09:09:35 volumio go-librespot[17375]: time="2025-09-03T09:09:35+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 03 09:09:35 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:35 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:35 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:35 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:35 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:35 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:35 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:35 volumio systemd[1]: Starting Music Player Daemon... Sep 03 09:09:35 volumio sudo[17405]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 03 09:09:35 volumio sudo[17405]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:09:35 volumio go-librespot[17375]: time="2025-09-03T09:09:35+02:00" level=trace msg="received accesspoint pong ack" Sep 03 09:09:35 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 09:09:35 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 09:09:35 volumio sudo[17405]: pam_unix(sudo:session): session closed for user root Sep 03 09:09:35 volumio volumio[17207]: info: Upmpdcli Daemon Started Sep 03 09:09:35 volumio volumio[17207]: SPOTIFY: User informations: {"country":"HU","display_name":"4Misi","email":"sztako.mihaly@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/lo8jr34h0xh1vbcv2lgmaqkvv"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/lo8jr34h0xh1vbcv2lgmaqkvv","id":"lo8jr34h0xh1vbcv2lgmaqkvv","images":[],"product":"premium","type":"user","uri":"spotify:user:lo8jr34h0xh1vbcv2lgmaqkvv"} Sep 03 09:09:35 volumio volumio[17207]: info: Spotify Successfully logged in Sep 03 09:09:35 volumio volumio[17207]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 09:09:35 volumio volumio[17207]: info: [1756883375410] CoreMusicLibrary::Adding element Spotify Sep 03 09:09:35 volumio volumio[17207]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 09:09:35 volumio volumio[17207]: Cannot find translation for source Jellyfin Sep 03 09:09:35 volumio volumio[17207]: Cannot find translation for source Spotify Sep 03 09:09:35 volumio sudo[17403]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 03 09:09:35 volumio sudo[17403]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 09:09:35 volumio go-librespot[17375]: time="2025-09-03T09:09:35+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 03 09:09:35 volumio sudo[17403]: pam_unix(sudo:session): session closed for user root Sep 03 09:09:35 volumio volumio[17207]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 03 09:09:35 volumio volumio[17207]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 09:09:35 volumio volumio[17207]: Error: bind EADDRINUSE 192.168.0.171:50386 Sep 03 09:09:35 volumio volumio[17207]: at dgram.js:337:20 Sep 03 09:09:35 volumio volumio[17207]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Sep 03 09:09:35 volumio volumio[17207]: errno: -98, Sep 03 09:09:35 volumio volumio[17207]: code: 'EADDRINUSE', Sep 03 09:09:35 volumio volumio[17207]: syscall: 'bind', Sep 03 09:09:35 volumio volumio[17207]: address: '192.168.0.171', Sep 03 09:09:35 volumio volumio[17207]: port: 50386 Sep 03 09:09:35 volumio volumio[17207]: } Sep 03 09:09:35 volumio volumio[17207]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 09:09:36 volumio sudo[17422]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-03 09:08 Sep 03 09:09:36 volumio sudo[17422]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"