-- Logs begin at Wed 2025-09-03 10:32:56 CEST, end at Wed 2025-09-03 11:01:47 CEST. -- Sep 03 11:00:00 volumio sudo[17792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 11:00:00 volumio sudo[17792]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:00 volumio volumio[17681]: info: ___________ START PLUGINS ___________ Sep 03 11:00:00 volumio sudo[17792]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:00 volumio volumio[17681]: info: ControllerMpd::onStart: Initializing MPD Sep 03 11:00:00 volumio volumio[17681]: info: Creating MPD Configuration file Sep 03 11:00:00 volumio sudo[17798]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 11:00:00 volumio sudo[17798]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:00 volumio systemd[1]: Stopping Music Player Daemon... Sep 03 11:00:00 volumio volumio[17681]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 11:00:00 volumio volumio[17681]: info: [1756890000100] CoreMusicLibrary::Adding element Last_100 Sep 03 11:00:00 volumio volumio[17681]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 11:00:00 volumio volumio[17681]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 11:00:00 volumio sudo[17802]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 11:00:00 volumio volumio[17681]: info: [1756890000105] CoreMusicLibrary::Adding element Webradio Sep 03 11:00:00 volumio volumio[17681]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 11:00:00 volumio sudo[17802]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:00 volumio sudo[17802]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:00 volumio volumio[17681]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 11:00:00 volumio systemd[1]: mpd.service: Succeeded. Sep 03 11:00:00 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 11:00:00 volumio sudo[17804]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 11:00:00 volumio volumio[17681]: info: Initializing BBC Radios Sep 03 11:00:00 volumio sudo[17804]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:00 volumio systemd[1]: Starting Music Player Daemon... Sep 03 11:00:00 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 03 11:00:00 volumio systemd[1]: mpd.service: Succeeded. Sep 03 11:00:00 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 11:00:00 volumio systemd[1]: Starting Music Player Daemon... Sep 03 11:00:00 volumio volumio[17681]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 11:00:00 volumio volumio[17681]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 11:00:00 volumio volumio[17681]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 11:00:00 volumio volumio[17681]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 03 11:00:00 volumio volumio[17681]: info: Discovery: Getting this device information Sep 03 11:00:00 volumio volumio[17681]: info: CoreCommandRouter::volumioGetState Sep 03 11:00:00 volumio volumio[17681]: info: CorePlayQueue::getTrack 0 Sep 03 11:00:00 volumio volumio[17681]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 11:00:00 volumio volumio[17681]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 11:00:00 volumio volumio[17681]: info: [1756890000270] CoreMusicLibrary::Adding element Jellyfin Sep 03 11:00:00 volumio volumio[17681]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 11:00:00 volumio volumio[17681]: Cannot find translation for source Jellyfin Sep 03 11:00:00 volumio volumio[17681]: 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 11:00:00 volumio volumio[17681]: info: Creating Spotify config file Sep 03 11:00:00 volumio volumio[17681]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:00 volumio sudo[17823]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 03 11:00:00 volumio sudo[17823]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:00 volumio sudo[17823]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:00 volumio volumio[17681]: info: Loading i18n strings for locale hu Sep 03 11:00:00 volumio volumio[17681]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 03 11:00:00 volumio volumio[17681]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:00 volumio volumio[17681]: info: FusionDsp - mixtype--------------------- Hardware Sep 03 11:00:00 volumio volumio[17681]: info: Volumio Calling Home Sep 03 11:00:00 volumio volumio[17681]: info: Preparing to generate the ALSA configuration file Sep 03 11:00:00 volumio volumio[17681]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 03 11:00:00 volumio volumio[17681]: info: Reading ALSA contributions from plugins. Sep 03 11:00:00 volumio volumio[17681]: info: MPD Permissions set Sep 03 11:00:00 volumio volumio[17681]: info: MPD Permissions set Sep 03 11:00:00 volumio volumio[17681]: info: Spotify config file written Sep 03 11:00:00 volumio volumio[17681]: info: CoreCommandRouter::volumioGetState Sep 03 11:00:00 volumio volumio[17681]: info: CorePlayQueue::getTrack 0 Sep 03 11:00:00 volumio sudo[17847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 03 11:00:00 volumio sudo[17847]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:00 volumio volumio[17681]: 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 11:00:00 volumio volumio[17681]: info: Volumio called home Sep 03 11:00:00 volumio volumio[17681]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:00 volumio volumio[17681]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:00 volumio systemd[1]: Stopping go-librespot Daemon... Sep 03 11:00:00 volumio volumio[17681]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:00 volumio volumio[17681]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:00 volumio volumio[17681]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:00 volumio volumio[17681]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 03 11:00:00 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 03 11:00:00 volumio volumio[17681]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:00 volumio systemd[1]: Stopped go-librespot Daemon. Sep 03 11:00:00 volumio volumio[17681]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 11:00:00 volumio volumio[17681]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:00 volumio volumio[17681]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:00 volumio volumio[17681]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:00 volumio volumio[17681]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:00 volumio volumio[17681]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:00 volumio volumio[17681]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:00 volumio volumio[17681]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:00 volumio volumio[17681]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:00 volumio volumio[17681]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:00 volumio volumio[17681]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 11:00:00 volumio volumio[17681]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:00 volumio volumio[17681]: info: No need to fix Spotify hosts Sep 03 11:00:00 volumio systemd[1]: Started go-librespot Daemon. Sep 03 11:00:00 volumio go-librespot[17849]: go-librespot daemon starting... Sep 03 11:00:00 volumio sudo[17847]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:00 volumio go-librespot[17849]: time="2025-09-03T11:00:00+02:00" level=info msg="running go-librespot 0.3.2" Sep 03 11:00:00 volumio go-librespot[17849]: time="2025-09-03T11:00:00+02:00" level=debug msg="app state loaded" Sep 03 11:00:00 volumio go-librespot[17849]: time="2025-09-03T11:00:00+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 03 11:00:01 volumio sudo[17866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 03 11:00:01 volumio sudo[17866]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:01 volumio sudo[17866]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:01 volumio sudo[17869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 11:00:01 volumio sudo[17869]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:01 volumio go-librespot[17849]: time="2025-09-03T11:00:01+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 11:00:01 volumio go-librespot[17849]: time="2025-09-03T11:00:01+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 11:00:01 volumio go-librespot[17849]: time="2025-09-03T11:00:01+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 11:00:01 volumio sudo[17869]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:01 volumio go-librespot[17849]: time="2025-09-03T11:00:01+02:00" level=info msg="zeroconf server listening on port 38347" Sep 03 11:00:01 volumio go-librespot[17849]: time="2025-09-03T11:00:01+02:00" level=debug msg="obtained new client token: AAC9HUHE7MX+mtuy0KW3rJYYjDSvRDYrxYXkPNlgOmF4cVduj7qDeprO5cbdu1HOuK+92gueJ/iC7/cvoQYJpw7jHyzWQz/JbQkz+FDhoyQowTeeNYDBxw1eOiXxsQYSeNnzxuq7I9s3jbqxZjYeFIz7KLlHVooH68hJa5mym3g5luwbz4/ljmhLkiWtldhhwUtJVT4x4rRdpm4HudkPX9mxiQgKQuVvbbxuRSp98OcRkXNpD2A+5gpu" Sep 03 11:00:01 volumio go-librespot[17849]: time="2025-09-03T11:00:01+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 03 11:00:01 volumio go-librespot[17849]: time="2025-09-03T11:00:01+02:00" level=debug msg="completed keyexchange" Sep 03 11:00:01 volumio go-librespot[17849]: time="2025-09-03T11:00:01+02:00" level=debug msg="completed challenge" Sep 03 11:00:01 volumio go-librespot[17849]: time="2025-09-03T11:00:01+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 03 11:00:01 volumio volumio[17681]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 03 11:00:01 volumio volumio[17681]: SPOTIFY: BQAWp2WIt2-XDE6LU4dgSWWSNWziS82ls-ZBc1bdQgO_N_tdXmPtUTQRXLGYVCztvl_mP8zePOiONNivZkBa0N9mXSpdiDNvMv--b-59otaiPdWlwqIer6BRaIksGE8mzzOseI2GfdsFdPNMCsu0rwmuola5_Veu_ylZp191phYitVVpy_GQdijmWssN_w85zSOcRMR39ILIJtHjq95qrrty_KOjZv-lwhsiRnFj5BkXKbRgN3jgMTKay68eBCv1YcbbBn44lnaodO386bZvXV6ucu21FAv0uBtcZGrzLdVyodA Sep 03 11:00:01 volumio volumio[17681]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 03 11:00:01 volumio volumio[17681]: info: New Spotify access token = BQAWp2WIt2-XDE6LU4dgSWWSNWziS82ls-ZBc1bdQgO_N_tdXmPtUTQRXLGYVCztvl_mP8zePOiONNivZkBa0N9mXSpdiDNvMv--b-59otaiPdWlwqIer6BRaIksGE8mzzOseI2GfdsFdPNMCsu0rwmuola5_Veu_ylZp191phYitVVpy_GQdijmWssN_w85zSOcRMR39ILIJtHjq95qrrty_KOjZv-lwhsiRnFj5BkXKbRgN3jgMTKay68eBCv1YcbbBn44lnaodO386bZvXV6ucu21FAv0uBtcZGrzLdVyodA Sep 03 11:00:01 volumio volumio[17681]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 03 11:00:01 volumio volumio[17681]: info: CoreCommandRouter::volumioGetState Sep 03 11:00:01 volumio volumio[17681]: info: CorePlayQueue::getTrack 0 Sep 03 11:00:01 volumio volumio[17681]: info: Asound.conf file unchanged, so no further update is needed Sep 03 11:00:01 volumio volumio[17681]: info: Output device has changed, restarting MPD Sep 03 11:00:01 volumio go-librespot[17849]: time="2025-09-03T11:00:01+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 03 11:00:01 volumio go-librespot[17849]: time="2025-09-03T11:00:01+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 03 11:00:01 volumio sudo[17873]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 11:00:01 volumio sudo[17873]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:01 volumio sudo[17873]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:01 volumio go-librespot[17849]: time="2025-09-03T11:00:01+02:00" level=debug msg="dealer connection opened" Sep 03 11:00:01 volumio go-librespot[17849]: time="2025-09-03T11:00:01+02:00" level=trace msg="starting accesspoint recv loop" Sep 03 11:00:01 volumio go-librespot[17849]: time="2025-09-03T11:00:01+02:00" level=trace msg="received accesspoint ping" Sep 03 11:00:01 volumio go-librespot[17849]: time="2025-09-03T11:00:01+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 03 11:00:01 volumio go-librespot[17849]: time="2025-09-03T11:00:01+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 03 11:00:01 volumio go-librespot[17849]: time="2025-09-03T11:00:01+02:00" level=trace msg="starting dealer recv loop" Sep 03 11:00:01 volumio go-librespot[17849]: time="2025-09-03T11:00:01+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 03 11:00:01 volumio go-librespot[17849]: time="2025-09-03T11:00:01+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 03 11:00:01 volumio go-librespot[17849]: time="2025-09-03T11:00:01+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 03 11:00:01 volumio go-librespot[17849]: time="2025-09-03T11:00:01+02:00" level=debug msg="received connection id: MTg3YjA3MmItN2Y4...QUUxRTAyRThDMA==" Sep 03 11:00:01 volumio sudo[17876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 11:00:01 volumio sudo[17876]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:01 volumio go-librespot[17849]: time="2025-09-03T11:00:01+02:00" level=trace msg="received accesspoint pong ack" Sep 03 11:00:01 volumio volumio[17681]: info: MPD Permissions set Sep 03 11:00:01 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 03 11:00:01 volumio systemd[1]: mpd.service: Succeeded. Sep 03 11:00:01 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 11:00:01 volumio volumio[17681]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:01 volumio sudo[17880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 03 11:00:01 volumio volumio[17681]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:01 volumio sudo[17880]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:01 volumio systemd[1]: Starting Music Player Daemon... Sep 03 11:00:01 volumio sudo[17880]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:01 volumio go-librespot[17849]: time="2025-09-03T11:00:01+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 03 11:00:01 volumio volumio[17681]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 11:00:01 volumio volumio[17681]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:01 volumio volumio[17681]: info: Upmpdcli Daemon Started Sep 03 11:00:01 volumio volumio[17681]: 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 11:00:01 volumio volumio[17681]: info: Spotify Successfully logged in Sep 03 11:00:01 volumio volumio[17681]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 11:00:01 volumio volumio[17681]: info: [1756890001854] CoreMusicLibrary::Adding element Spotify Sep 03 11:00:01 volumio volumio[17681]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 11:00:01 volumio volumio[17681]: Cannot find translation for source Jellyfin Sep 03 11:00:01 volumio volumio[17681]: Cannot find translation for source Spotify Sep 03 11:00:01 volumio sudo[17879]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 03 11:00:01 volumio sudo[17879]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:01 volumio sudo[17879]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:02 volumio volumio[17681]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 03 11:00:02 volumio volumio[17681]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 11:00:02 volumio volumio[17681]: info: VolumeController:: Volume=37 Mute =false Sep 03 11:00:02 volumio volumio[17681]: info: CoreCommandRouter::volumioGetState Sep 03 11:00:02 volumio volumio[17681]: info: CorePlayQueue::getTrack 0 Sep 03 11:00:02 volumio volumio[17681]: info: CoreStateMachine::pushState Sep 03 11:00:02 volumio volumio[17681]: info: CorePlayQueue::getTrack 0 Sep 03 11:00:02 volumio volumio[17681]: info: CoreCommandRouter::volumioPushState Sep 03 11:00:02 volumio volumio[17681]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 03 11:00:02 volumio volumio[17681]: 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 11:00:02 volumio volumio[17681]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 03 11:00:02 volumio volumio[17681]: info: camilladsp service started and running in background, instance 1 Sep 03 11:00:02 volumio volumio[17681]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:02 volumio volumio[17681]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 03 11:00:02 volumio volumio[17681]: 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 11:00:02 volumio volumio[17681]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 03 11:00:02 volumio volumio[17681]: info: FusionDsp loaded Sep 03 11:00:02 volumio volumio[17681]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 11:00:02 volumio sudo[17901]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 03 11:00:02 volumio sudo[17901]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:02 volumio sudo[17901]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:02 volumio volumio[17681]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 03 11:00:02 volumio volumio[17681]: info: Adding Signal Path Element [object Object] Sep 03 11:00:02 volumio volumio[17681]: info: Adding fusiondspeq DSP Signal Path Element Sep 03 11:00:02 volumio volumio[17681]: info: FusionDsp - ---- installed callbackRead Sep 03 11:00:02 volumio volumio[17681]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 11:00:02 volumio volumio[17681]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 03 11:00:02 volumio volumio[17681]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 03 11:00:02 volumio volumio[17681]: at onErrorNT (internal/child_process.js:465:16) Sep 03 11:00:02 volumio volumio[17681]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Sep 03 11:00:02 volumio volumio[17681]: errno: -2, Sep 03 11:00:02 volumio volumio[17681]: code: 'ENOENT', Sep 03 11:00:02 volumio volumio[17681]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 03 11:00:02 volumio volumio[17681]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 03 11:00:02 volumio volumio[17681]: spawnargs: [ Sep 03 11:00:02 volumio volumio[17681]: '-p', Sep 03 11:00:02 volumio volumio[17681]: 9876, Sep 03 11:00:02 volumio volumio[17681]: '-o', Sep 03 11:00:02 volumio volumio[17681]: '/tmp/camilladsp.log', Sep 03 11:00:02 volumio volumio[17681]: '-l', Sep 03 11:00:02 volumio volumio[17681]: 'warn', Sep 03 11:00:02 volumio volumio[17681]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 03 11:00:02 volumio volumio[17681]: ] Sep 03 11:00:02 volumio volumio[17681]: } Sep 03 11:00:02 volumio volumio[17681]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 11:00:03 volumio sudo[17912]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-03 10:59 Sep 03 11:00:03 volumio sudo[17912]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:03 volumio sudo[17912]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:03 volumio mpd[17888]: Sep 03 11:00 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 03 11:00:03 volumio systemd[1]: Started Music Player Daemon. Sep 03 11:00:03 volumio sudo[17798]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:03 volumio sudo[17876]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:03 volumio sudo[17804]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:03 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 03 11:00:03 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 03 11:00:03 volumio systemd[1]: Started dynamicswap service. Sep 03 11:00:03 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 03 11:00:03 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 03 11:00:03 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 7835. Sep 03 11:00:03 volumio systemd[1]: Started dynamicswap service. Sep 03 11:00:03 volumio systemd[1]: Stopped Volumio Backend Module. Sep 03 11:00:03 volumio systemd[1]: Started Volumio Backend Module. Sep 03 11:00:03 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 03 11:00:04 volumio volumio-remote-updater[504]: [2025-09-03 11:00:04] [info] asio async_connect error: system:111 (Connection refused) Sep 03 11:00:04 volumio volumio-remote-updater[504]: [2025-09-03 11:00:04] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 03 11:00:04 volumio volumio-remote-updater[504]: [2025-09-03 11:00:04] [error] handle_connect error: Underlying Transport Error Sep 03 11:00:05 volumio volumio[17925]: info: ------------------------------------------- Sep 03 11:00:05 volumio volumio[17925]: info: ----- Volumio3 ---- Sep 03 11:00:05 volumio volumio[17925]: info: ------------------------------------------- Sep 03 11:00:05 volumio volumio[17925]: info: ----- System startup ---- Sep 03 11:00:05 volumio volumio[17925]: info: ------------------------------------------- Sep 03 11:00:06 volumio volumio[17925]: info: MYVOLUMIO Environment detected Sep 03 11:00:06 volumio volumio[17925]: info: Plugin folders cleanup Sep 03 11:00:06 volumio volumio[17925]: info: Scanning into folder /volumio/app/plugins/ Sep 03 11:00:06 volumio volumio[17925]: info: Scanning category audio_interface Sep 03 11:00:06 volumio volumio[17925]: info: Scanning category miscellanea Sep 03 11:00:06 volumio volumio[17925]: info: Scanning category music_service Sep 03 11:00:06 volumio volumio[17925]: info: Scanning category plugins.json Sep 03 11:00:06 volumio volumio[17925]: info: Scanning category system_controller Sep 03 11:00:06 volumio volumio[17925]: info: Scanning category user_interface Sep 03 11:00:06 volumio volumio[17925]: info: Scanning into folder /data/plugins/ Sep 03 11:00:06 volumio volumio[17925]: info: Scanning category audio_interface Sep 03 11:00:06 volumio volumio[17925]: info: Scanning category music_service Sep 03 11:00:06 volumio volumio[17925]: info: Scanning category user_interface Sep 03 11:00:06 volumio volumio[17925]: info: Plugin folders cleanup completed Sep 03 11:00:06 volumio volumio[17925]: info: ------------------------------------------- Sep 03 11:00:06 volumio volumio[17925]: info: ----- Core plugins startup ---- Sep 03 11:00:06 volumio volumio[17925]: info: ------------------------------------------- Sep 03 11:00:06 volumio volumio[17925]: info: Loading plugins from folder /volumio/app/plugins/ Sep 03 11:00:06 volumio volumio[17925]: info: Adding plugin upnp to MyMusic Plugins Sep 03 11:00:06 volumio volumio[17925]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 03 11:00:06 volumio volumio[17925]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 03 11:00:06 volumio volumio[17925]: info: Loading plugins from folder /data/plugins/ Sep 03 11:00:06 volumio volumio[17925]: info: Loading plugin "system"... Sep 03 11:00:06 volumio volumio[17925]: info: Loading plugin "appearance"... Sep 03 11:00:07 volumio volumio[17925]: info: Loading plugin "network"... Sep 03 11:00:07 volumio volumio[17925]: info: Refreshing Cached IP Addresses Sep 03 11:00:07 volumio sudo[17951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 03 11:00:07 volumio sudo[17951]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:07 volumio sudo[17951]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:07 volumio sudo[17953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 11:00:07 volumio volumio[17925]: info: Loading plugin "services"... Sep 03 11:00:07 volumio sudo[17953]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:07 volumio sudo[17953]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:07 volumio volumio[17925]: info: Loading plugin "alsa_controller"... Sep 03 11:00:07 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 11:00:07 volumio volumio[17925]: info: Loading plugin "wizard"... Sep 03 11:00:07 volumio volumio[17925]: info: Loading plugin "networkfs"... Sep 03 11:00:07 volumio volumio[17925]: info: Starting Udev Watcher for removable devices Sep 03 11:00:07 volumio volumio[17925]: info: Ignoring mount for partition: boot Sep 03 11:00:07 volumio volumio[17925]: info: Ignoring mount for partition: volumio Sep 03 11:00:07 volumio volumio[17925]: info: Ignoring mount for partition: volumio_data Sep 03 11:00:07 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 11:00:07 volumio volumio[17925]: info: Loading plugin "volumio_command_line_client"... Sep 03 11:00:07 volumio volumio[17925]: info: Loading plugin "upnp"... Sep 03 11:00:07 volumio volumio[17925]: info: [1756890007816] Starting Upmpd Daemon Sep 03 11:00:07 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 11:00:07 volumio volumio[17925]: info: Loading plugin "my_music"... Sep 03 11:00:07 volumio volumio[17925]: info: Loading plugin "mpd"... Sep 03 11:00:08 volumio volumio[17925]: info: Plugin upnp_browser is not enabled Sep 03 11:00:08 volumio volumio[17925]: info: Loading plugin "alarm-clock"... Sep 03 11:00:08 volumio volumio[17925]: info: Plugin airplay_emulation is not enabled Sep 03 11:00:08 volumio volumio[17925]: info: Loading plugin "last_100"... Sep 03 11:00:08 volumio volumio[17925]: info: Loading plugin "webradio"... Sep 03 11:00:08 volumio volumio[17925]: info: Loading plugin "i2s_dacs"... Sep 03 11:00:08 volumio volumio[17925]: info: Loading plugin "volumiodiscovery"... Sep 03 11:00:08 volumio volumio[17925]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 03 11:00:08 volumio volumio[17925]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 11:00:08 volumio volumio[17925]: *** WARNING *** For more information see Sep 03 11:00:08 volumio node[17925]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 03 11:00:08 volumio volumio[17925]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 03 11:00:08 volumio volumio[17925]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 11:00:08 volumio volumio[17925]: *** WARNING *** For more information see Sep 03 11:00:08 volumio node[17925]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 11:00:08 volumio node[17925]: *** WARNING *** For more information see Sep 03 11:00:08 volumio node[17925]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 03 11:00:08 volumio node[17925]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 11:00:08 volumio node[17925]: *** WARNING *** For more information see Sep 03 11:00:08 volumio volumio[17925]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 03 11:00:08 volumio volumio[17925]: info: Discovery: Started advertising with name: Volumio Sep 03 11:00:08 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 11:00:08 volumio volumio[17925]: info: Loading plugin "jellyfin"... Sep 03 11:00:09 volumio volumio[17925]: info: Loading plugin "spop"... Sep 03 11:00:10 volumio volumio[17925]: info: Loading plugin "ytcr"... Sep 03 11:00:14 volumio volumio-remote-updater[504]: [2025-09-03 11:00:14] [connect] Successful connection Sep 03 11:00:14 volumio volumio[17925]: info: Loading plugin "outputs"... Sep 03 11:00:14 volumio volumio[17925]: info: Loading plugin "albumart"... Sep 03 11:00:14 volumio volumio[17925]: info: Plugin example_plugin is not enabled Sep 03 11:00:14 volumio volumio[17925]: info: Loading plugin "inputs"... Sep 03 11:00:14 volumio volumio[17925]: info: Loading plugin "updater_comm"... Sep 03 11:00:15 volumio volumio[17925]: info: Plugin mpdemulation is not enabled Sep 03 11:00:15 volumio volumio[17925]: info: Loading plugin "rest_api"... Sep 03 11:00:15 volumio volumio[17925]: info: Loading plugin "websocket"... Sep 03 11:00:15 volumio volumio[17925]: info: Starting Socket.io Server version 2.3.0 Sep 03 11:00:15 volumio volumio[17925]: info: Loading plugin "fusiondsp"... Sep 03 11:00:15 volumio volumio[17925]: Forking 3 albumart workers Sep 03 11:00:15 volumio volumio[17925]: info: Applying required configuration parameters for plugin fusiondsp Sep 03 11:00:15 volumio volumio[17925]: info: Loading plugin "Systeminfo"... Sep 03 11:00:15 volumio volumio[17925]: info: Loading i18n strings for locale hu Sep 03 11:00:15 volumio volumio[17925]: Updating browse sources language Sep 03 11:00:15 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 11:00:16 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 03 11:00:16 volumio volumio[17925]: info: CoreCommandRouter::initPlayerControls Sep 03 11:00:16 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:16 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:16 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:16 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:16 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:16 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:16 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:16 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:16 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 03 11:00:16 volumio volumio[17925]: Express server listening on port 3000 Sep 03 11:00:16 volumio volumio[17925]: [Metrics] WebUI: 10s 964.01ms Sep 03 11:00:16 volumio volumio[17925]: info: CoreStateMachine::resetVolumioState Sep 03 11:00:16 volumio volumio[17925]: info: CoreStateMachine::getcurrentVolume Sep 03 11:00:16 volumio volumio[17925]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 11:00:16 volumio volumio[17925]: Starting albumart workers Sep 03 11:00:16 volumio volumio[17925]: info: Volumio Network Manager: Network status updated: 1 Sep 03 11:00:16 volumio volumio[17925]: Starting albumart workers Sep 03 11:00:16 volumio volumio[17925]: Starting albumart workers Sep 03 11:00:16 volumio volumio-remote-updater[504]: [2025-09-03 11:00:16] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1756890014 101 Sep 03 11:00:16 volumio volumio[17925]: 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 11:00:16 volumio volumio[17925]: info: CoreCommandRouter::volumioGetState Sep 03 11:00:16 volumio volumio[17925]: info: CorePlayQueue::getTrack 0 Sep 03 11:00:16 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 11:00:16 volumio volumio[17925]: info: VolumeController:: Volume=37 Mute =false Sep 03 11:00:16 volumio volumio[17925]: info: CoreStateMachine::pushState Sep 03 11:00:16 volumio volumio[17925]: info: CorePlayQueue::getTrack 0 Sep 03 11:00:16 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 11:00:16 volumio volumio[17925]: info: CoreCommandRouter::volumioPushState Sep 03 11:00:16 volumio volumio[17925]: info: CoreStateMachine::updateTrackBlock Sep 03 11:00:16 volumio volumio[17925]: info: CorePlayQueue::getTrackBlock Sep 03 11:00:16 volumio volumio[17925]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 11:00:16 volumio volumio[17925]: info: CoreStateMachine::setRepeat false single undefined Sep 03 11:00:16 volumio volumio[17925]: info: CoreStateMachine::pushState Sep 03 11:00:16 volumio volumio[17925]: info: CorePlayQueue::getTrack 0 Sep 03 11:00:16 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 11:00:16 volumio volumio[17925]: info: CoreCommandRouter::volumioPushState Sep 03 11:00:16 volumio volumio[17925]: info: CoreStateMachine::setRandom true Sep 03 11:00:16 volumio volumio[17925]: info: CoreStateMachine::pushState Sep 03 11:00:16 volumio volumio[17925]: info: CorePlayQueue::getTrack 0 Sep 03 11:00:16 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 11:00:16 volumio volumio[17925]: info: CoreCommandRouter::volumioPushState Sep 03 11:00:16 volumio volumio[17925]: info: Setting Device type: Raspberry PI Sep 03 11:00:16 volumio volumio[17925]: info: Listing playlists Sep 03 11:00:16 volumio volumio[17925]: info: Listing playlists Sep 03 11:00:16 volumio volumio[17925]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227 Sep 03 11:00:16 volumio volumio[17925]: info: Discovery: Found device Volumio Sep 03 11:00:16 volumio volumio[17925]: info: CoreCommandRouter::volumioGetState Sep 03 11:00:16 volumio volumio[17925]: info: CorePlayQueue::getTrack 0 Sep 03 11:00:16 volumio volumio[17925]: info: Completed loading Core Plugins Sep 03 11:00:16 volumio volumio[17925]: info: Preparing to generate the ALSA configuration file Sep 03 11:00:16 volumio volumio[17925]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 03 11:00:16 volumio volumio[17925]: info: Reading ALSA contributions from plugins. Sep 03 11:00:16 volumio volumio[17925]: info: VolumeController:: Volume=37 Mute =false Sep 03 11:00:16 volumio volumio[17925]: info: CoreStateMachine::pushState Sep 03 11:00:16 volumio volumio[17925]: info: CorePlayQueue::getTrack 0 Sep 03 11:00:16 volumio volumio[17925]: info: CoreCommandRouter::volumioPushState Sep 03 11:00:16 volumio volumio[17925]: info: Asound.conf file unchanged, so no further update is needed Sep 03 11:00:16 volumio volumio[17925]: info: Output device has changed, restarting MPD Sep 03 11:00:16 volumio sudo[18031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 11:00:16 volumio sudo[18031]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:16 volumio sudo[18031]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:16 volumio volumio[17925]: info: ___________ START PLUGINS ___________ Sep 03 11:00:16 volumio volumio[17925]: info: ControllerMpd::onStart: Initializing MPD Sep 03 11:00:17 volumio volumio[17925]: info: Creating MPD Configuration file Sep 03 11:00:17 volumio sudo[18034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 11:00:17 volumio sudo[18034]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:17 volumio sudo[18046]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 11:00:17 volumio systemd[1]: Stopping Music Player Daemon... Sep 03 11:00:17 volumio sudo[18046]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:17 volumio volumio[17925]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 11:00:17 volumio sudo[18046]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:17 volumio volumio[17925]: info: [1756890017096] CoreMusicLibrary::Adding element Last_100 Sep 03 11:00:17 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 11:00:17 volumio sudo[18055]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 11:00:17 volumio sudo[18055]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:17 volumio systemd[1]: mpd.service: Succeeded. Sep 03 11:00:17 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 11:00:17 volumio volumio[17925]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 11:00:17 volumio volumio[17925]: info: [1756890017123] CoreMusicLibrary::Adding element Webradio Sep 03 11:00:17 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 11:00:17 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 11:00:17 volumio volumio[17925]: info: Initializing BBC Radios Sep 03 11:00:17 volumio systemd[1]: Starting Music Player Daemon... Sep 03 11:00:17 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 03 11:00:17 volumio systemd[1]: mpd.service: Succeeded. Sep 03 11:00:17 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 11:00:17 volumio systemd[1]: Starting Music Player Daemon... Sep 03 11:00:17 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 11:00:17 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 11:00:17 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 11:00:17 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 03 11:00:17 volumio volumio[17925]: info: Discovery: Getting this device information Sep 03 11:00:17 volumio volumio[17925]: info: CoreCommandRouter::volumioGetState Sep 03 11:00:17 volumio volumio[17925]: info: CorePlayQueue::getTrack 0 Sep 03 11:00:17 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 11:00:17 volumio volumio[17925]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 11:00:17 volumio volumio[17925]: info: [1756890017269] CoreMusicLibrary::Adding element Jellyfin Sep 03 11:00:17 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 11:00:17 volumio volumio[17925]: Cannot find translation for source Jellyfin Sep 03 11:00:17 volumio volumio[17925]: 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 11:00:17 volumio volumio[17925]: info: Creating Spotify config file Sep 03 11:00:17 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:17 volumio sudo[18061]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 03 11:00:17 volumio sudo[18061]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:17 volumio sudo[18061]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:17 volumio volumio[17925]: info: Loading i18n strings for locale hu Sep 03 11:00:17 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 03 11:00:17 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:17 volumio volumio[17925]: info: FusionDsp - mixtype--------------------- Hardware Sep 03 11:00:17 volumio volumio[17925]: info: Volumio Calling Home Sep 03 11:00:17 volumio volumio[17925]: info: Preparing to generate the ALSA configuration file Sep 03 11:00:17 volumio volumio[17925]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 03 11:00:17 volumio volumio[17925]: info: Reading ALSA contributions from plugins. Sep 03 11:00:17 volumio volumio[17925]: info: MPD Permissions set Sep 03 11:00:17 volumio volumio[17925]: info: MPD Permissions set Sep 03 11:00:17 volumio volumio[17925]: info: Spotify config file written Sep 03 11:00:17 volumio sudo[18091]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 03 11:00:17 volumio sudo[18091]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:17 volumio volumio[17925]: 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 11:00:17 volumio volumio[17925]: info: Volumio called home Sep 03 11:00:17 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:17 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:17 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:17 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:17 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:17 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:17 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:17 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:17 volumio systemd[1]: Stopping go-librespot Daemon... Sep 03 11:00:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 03 11:00:17 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 03 11:00:17 volumio systemd[1]: Stopped go-librespot Daemon. Sep 03 11:00:17 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 11:00:17 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:17 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:17 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:17 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:17 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:17 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:17 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:17 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:17 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:17 volumio systemd[1]: Started go-librespot Daemon. Sep 03 11:00:17 volumio go-librespot[18097]: go-librespot daemon starting... Sep 03 11:00:17 volumio sudo[18091]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:17 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 11:00:17 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:17 volumio volumio[17925]: info: No need to fix Spotify hosts Sep 03 11:00:17 volumio go-librespot[18097]: time="2025-09-03T11:00:17+02:00" level=info msg="running go-librespot 0.3.2" Sep 03 11:00:17 volumio go-librespot[18097]: time="2025-09-03T11:00:17+02:00" level=debug msg="app state loaded" Sep 03 11:00:17 volumio go-librespot[18097]: time="2025-09-03T11:00:17+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 03 11:00:18 volumio sudo[18110]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 03 11:00:18 volumio sudo[18110]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:18 volumio sudo[18110]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:18 volumio sudo[18112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 11:00:18 volumio sudo[18112]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:18 volumio sudo[18112]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:18 volumio go-librespot[18097]: time="2025-09-03T11:00:18+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 03 11:00:18 volumio go-librespot[18097]: time="2025-09-03T11:00:18+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 03 11:00:18 volumio go-librespot[18097]: time="2025-09-03T11:00:18+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 03 11:00:18 volumio go-librespot[18097]: time="2025-09-03T11:00:18+02:00" level=info msg="zeroconf server listening on port 38213" Sep 03 11:00:18 volumio go-librespot[18097]: time="2025-09-03T11:00:18+02:00" level=debug msg="obtained new client token: AAAFdze8B76daT/r/OUCokTVPMTloEtoX+WIJ7vsRUe4qBltyxbZgnc9M832+gwlnUL1WYz/6A0qI8AqSBFXzz+Mcy7XBXAJ/rWyBhJAVjYHYOIC11lHHoh4G+3MwyQSJIVDLYt/7BXWp5hYjHF47njPPBLin6l1lTJiSCuLH0QOdn/szrlO9XtQgq9JysQQnwOlhHQP740BjEnvZfyyxA4HSp6KhL0MoF4H9fsxQc00SmzW9qhgTQY+" Sep 03 11:00:18 volumio volumio[17925]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 03 11:00:18 volumio volumio[17925]: SPOTIFY: BQC9lfxXaDGkeDah4MsOmhsP0TResqf7PDXKYjh0HHOAeDXGUs63ifDgKqMRvCW6DkmBpZjYJCnsOzzlMwJbn9JCq0BF1GDyk3quKVoX9sqMzv__8lziQ6ltzj95_dBDyznZaUF0Rt-a5rba-w2N6OhJ7VB8Hj4oSzC6KqntiGPer29laaxYeDZEKr61f_7dfvcn4y-1_26pyhBXvJhAkt_ZJH_hcvkkj41PjcjfVZjjxTlNhIVgcAgtmck6SURaFxaOSPo0AgiEwdKJyQ_eSCOoLwER1bxaLgst2mEG-Jr-ZsY Sep 03 11:00:18 volumio volumio[17925]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 03 11:00:18 volumio volumio[17925]: info: New Spotify access token = BQC9lfxXaDGkeDah4MsOmhsP0TResqf7PDXKYjh0HHOAeDXGUs63ifDgKqMRvCW6DkmBpZjYJCnsOzzlMwJbn9JCq0BF1GDyk3quKVoX9sqMzv__8lziQ6ltzj95_dBDyznZaUF0Rt-a5rba-w2N6OhJ7VB8Hj4oSzC6KqntiGPer29laaxYeDZEKr61f_7dfvcn4y-1_26pyhBXvJhAkt_ZJH_hcvkkj41PjcjfVZjjxTlNhIVgcAgtmck6SURaFxaOSPo0AgiEwdKJyQ_eSCOoLwER1bxaLgst2mEG-Jr-ZsY Sep 03 11:00:18 volumio volumio[17925]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 03 11:00:18 volumio go-librespot[18097]: time="2025-09-03T11:00:18+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 03 11:00:18 volumio volumio[17925]: info: CoreCommandRouter::volumioGetState Sep 03 11:00:18 volumio volumio[17925]: info: CorePlayQueue::getTrack 0 Sep 03 11:00:18 volumio go-librespot[18097]: time="2025-09-03T11:00:18+02:00" level=debug msg="completed keyexchange" Sep 03 11:00:18 volumio volumio[17925]: info: Asound.conf file unchanged, so no further update is needed Sep 03 11:00:18 volumio volumio[17925]: info: Output device has changed, restarting MPD Sep 03 11:00:18 volumio go-librespot[18097]: time="2025-09-03T11:00:18+02:00" level=debug msg="completed challenge" Sep 03 11:00:18 volumio sudo[18116]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 11:00:18 volumio go-librespot[18097]: time="2025-09-03T11:00:18+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 03 11:00:18 volumio sudo[18116]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:18 volumio sudo[18118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 11:00:18 volumio sudo[18118]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:18 volumio sudo[18116]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:18 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 03 11:00:18 volumio systemd[1]: mpd.service: Succeeded. Sep 03 11:00:18 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 11:00:18 volumio sudo[18122]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 03 11:00:18 volumio sudo[18122]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:18 volumio systemd[1]: Starting Music Player Daemon... Sep 03 11:00:18 volumio go-librespot[18097]: time="2025-09-03T11:00:18+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 03 11:00:18 volumio go-librespot[18097]: time="2025-09-03T11:00:18+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 03 11:00:18 volumio volumio[17925]: info: MPD Permissions set Sep 03 11:00:18 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:18 volumio sudo[18122]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:18 volumio go-librespot[18097]: time="2025-09-03T11:00:18+02:00" level=debug msg="dealer connection opened" Sep 03 11:00:18 volumio go-librespot[18097]: time="2025-09-03T11:00:18+02:00" level=trace msg="starting accesspoint recv loop" Sep 03 11:00:18 volumio go-librespot[18097]: time="2025-09-03T11:00:18+02:00" level=trace msg="starting dealer recv loop" Sep 03 11:00:18 volumio go-librespot[18097]: time="2025-09-03T11:00:18+02:00" level=trace msg="received accesspoint ping" Sep 03 11:00:18 volumio go-librespot[18097]: time="2025-09-03T11:00:18+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 03 11:00:18 volumio go-librespot[18097]: time="2025-09-03T11:00:18+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 03 11:00:18 volumio go-librespot[18097]: time="2025-09-03T11:00:18+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 03 11:00:18 volumio go-librespot[18097]: time="2025-09-03T11:00:18+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 03 11:00:18 volumio go-librespot[18097]: time="2025-09-03T11:00:18+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 03 11:00:18 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 11:00:18 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:18 volumio go-librespot[18097]: time="2025-09-03T11:00:18+02:00" level=debug msg="received connection id: ZjI1YjIxNjAtYzVj...Mjg2QjFFRjAzRQ==" Sep 03 11:00:18 volumio go-librespot[18097]: time="2025-09-03T11:00:18+02:00" level=trace msg="received accesspoint pong ack" Sep 03 11:00:18 volumio volumio[17925]: info: Upmpdcli Daemon Started Sep 03 11:00:18 volumio sudo[18123]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 03 11:00:18 volumio sudo[18123]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:18 volumio volumio[17925]: 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 11:00:18 volumio volumio[17925]: info: Spotify Successfully logged in Sep 03 11:00:18 volumio sudo[18123]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:18 volumio volumio[17925]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 11:00:18 volumio volumio[17925]: info: [1756890018663] CoreMusicLibrary::Adding element Spotify Sep 03 11:00:18 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 11:00:18 volumio volumio[17925]: Cannot find translation for source Jellyfin Sep 03 11:00:18 volumio volumio[17925]: Cannot find translation for source Spotify Sep 03 11:00:18 volumio go-librespot[18097]: time="2025-09-03T11:00:18+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 03 11:00:19 volumio volumio[17925]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 03 11:00:19 volumio volumio[17925]: 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 11:00:19 volumio volumio[17925]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 03 11:00:19 volumio volumio[17925]: info: camilladsp service started and running in background, instance 1 Sep 03 11:00:19 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:19 volumio volumio[17925]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 03 11:00:19 volumio volumio[17925]: 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 11:00:19 volumio volumio[17925]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 03 11:00:19 volumio volumio[17925]: info: FusionDsp loaded Sep 03 11:00:19 volumio volumio[17925]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 11:00:19 volumio sudo[18142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 03 11:00:19 volumio sudo[18142]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:19 volumio sudo[18142]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:19 volumio volumio[17925]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 03 11:00:19 volumio volumio[17925]: info: Adding Signal Path Element [object Object] Sep 03 11:00:19 volumio volumio[17925]: info: Adding fusiondspeq DSP Signal Path Element Sep 03 11:00:19 volumio volumio[17925]: info: FusionDsp - ---- installed callbackRead Sep 03 11:00:19 volumio volumio[17925]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 11:00:19 volumio volumio[17925]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 03 11:00:19 volumio volumio[17925]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 03 11:00:19 volumio volumio[17925]: at onErrorNT (internal/child_process.js:465:16) Sep 03 11:00:19 volumio volumio[17925]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Sep 03 11:00:19 volumio volumio[17925]: errno: -2, Sep 03 11:00:19 volumio volumio[17925]: code: 'ENOENT', Sep 03 11:00:19 volumio volumio[17925]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 03 11:00:19 volumio volumio[17925]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 03 11:00:19 volumio volumio[17925]: spawnargs: [ Sep 03 11:00:19 volumio volumio[17925]: '-p', Sep 03 11:00:19 volumio volumio[17925]: 9876, Sep 03 11:00:19 volumio volumio[17925]: '-o', Sep 03 11:00:19 volumio volumio[17925]: '/tmp/camilladsp.log', Sep 03 11:00:19 volumio volumio[17925]: '-l', Sep 03 11:00:19 volumio volumio[17925]: 'warn', Sep 03 11:00:19 volumio volumio[17925]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 03 11:00:19 volumio volumio[17925]: ] Sep 03 11:00:19 volumio volumio[17925]: } Sep 03 11:00:19 volumio volumio[17925]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 11:00:20 volumio mpd[18131]: Sep 03 11:00 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 03 11:00:20 volumio systemd[1]: Started Music Player Daemon. Sep 03 11:00:20 volumio sudo[18055]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:20 volumio sudo[18034]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:20 volumio sudo[18118]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:20 volumio sudo[18155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-03 10:59 Sep 03 11:00:20 volumio sudo[18155]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:20 volumio sudo[18155]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:23 volumio volumio-remote-updater[504]: [2025-09-03 11:00:23] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 03 11:00:23 volumio volumio-remote-updater[504]: [2025-09-03 11:00:23] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 03 11:00:23 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 03 11:00:23 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 03 11:00:23 volumio systemd[1]: Started dynamicswap service. Sep 03 11:00:23 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 03 11:00:23 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 03 11:00:23 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 7836. Sep 03 11:00:23 volumio systemd[1]: Started dynamicswap service. Sep 03 11:00:23 volumio systemd[1]: Stopped Volumio Backend Module. Sep 03 11:00:23 volumio systemd[1]: Started Volumio Backend Module. Sep 03 11:00:23 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 03 11:00:25 volumio volumio[18166]: info: ------------------------------------------- Sep 03 11:00:25 volumio volumio[18166]: info: ----- Volumio3 ---- Sep 03 11:00:25 volumio volumio[18166]: info: ------------------------------------------- Sep 03 11:00:25 volumio volumio[18166]: info: ----- System startup ---- Sep 03 11:00:25 volumio volumio[18166]: info: ------------------------------------------- Sep 03 11:00:26 volumio volumio[18166]: info: MYVOLUMIO Environment detected Sep 03 11:00:26 volumio volumio[18166]: info: Plugin folders cleanup Sep 03 11:00:26 volumio volumio[18166]: info: Scanning into folder /volumio/app/plugins/ Sep 03 11:00:26 volumio volumio[18166]: info: Scanning category audio_interface Sep 03 11:00:26 volumio volumio[18166]: info: Scanning category miscellanea Sep 03 11:00:26 volumio volumio[18166]: info: Scanning category music_service Sep 03 11:00:26 volumio volumio[18166]: info: Scanning category plugins.json Sep 03 11:00:26 volumio volumio[18166]: info: Scanning category system_controller Sep 03 11:00:26 volumio volumio[18166]: info: Scanning category user_interface Sep 03 11:00:26 volumio volumio[18166]: info: Scanning into folder /data/plugins/ Sep 03 11:00:26 volumio volumio[18166]: info: Scanning category audio_interface Sep 03 11:00:26 volumio volumio[18166]: info: Scanning category music_service Sep 03 11:00:26 volumio volumio[18166]: info: Scanning category user_interface Sep 03 11:00:26 volumio volumio[18166]: info: Plugin folders cleanup completed Sep 03 11:00:26 volumio volumio[18166]: info: ------------------------------------------- Sep 03 11:00:26 volumio volumio[18166]: info: ----- Core plugins startup ---- Sep 03 11:00:26 volumio volumio[18166]: info: ------------------------------------------- Sep 03 11:00:26 volumio volumio[18166]: info: Loading plugins from folder /volumio/app/plugins/ Sep 03 11:00:26 volumio volumio[18166]: info: Adding plugin upnp to MyMusic Plugins Sep 03 11:00:26 volumio volumio[18166]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 03 11:00:26 volumio volumio[18166]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 03 11:00:26 volumio volumio[18166]: info: Loading plugins from folder /data/plugins/ Sep 03 11:00:26 volumio volumio[18166]: info: Loading plugin "system"... Sep 03 11:00:26 volumio volumio[18166]: info: Loading plugin "appearance"... Sep 03 11:00:27 volumio volumio[18166]: info: Loading plugin "network"... Sep 03 11:00:27 volumio volumio[18166]: info: Refreshing Cached IP Addresses Sep 03 11:00:27 volumio sudo[18192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 03 11:00:27 volumio sudo[18192]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:27 volumio sudo[18192]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:27 volumio sudo[18194]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 11:00:27 volumio volumio[18166]: info: Loading plugin "services"... Sep 03 11:00:27 volumio sudo[18194]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:27 volumio volumio[18166]: info: Loading plugin "alsa_controller"... Sep 03 11:00:27 volumio sudo[18194]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:27 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 11:00:27 volumio volumio[18166]: info: Loading plugin "wizard"... Sep 03 11:00:27 volumio volumio[18166]: info: Loading plugin "networkfs"... Sep 03 11:00:27 volumio volumio[18166]: info: Starting Udev Watcher for removable devices Sep 03 11:00:27 volumio volumio[18166]: info: Ignoring mount for partition: boot Sep 03 11:00:27 volumio volumio[18166]: info: Ignoring mount for partition: volumio Sep 03 11:00:27 volumio volumio[18166]: info: Ignoring mount for partition: volumio_data Sep 03 11:00:27 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 11:00:27 volumio volumio[18166]: info: Loading plugin "volumio_command_line_client"... Sep 03 11:00:27 volumio volumio[18166]: info: Loading plugin "upnp"... Sep 03 11:00:27 volumio volumio[18166]: info: [1756890027834] Starting Upmpd Daemon Sep 03 11:00:27 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 11:00:27 volumio volumio[18166]: info: Loading plugin "my_music"... Sep 03 11:00:27 volumio volumio[18166]: info: Loading plugin "mpd"... Sep 03 11:00:28 volumio volumio[18166]: info: Plugin upnp_browser is not enabled Sep 03 11:00:28 volumio volumio[18166]: info: Loading plugin "alarm-clock"... Sep 03 11:00:28 volumio volumio[18166]: info: Plugin airplay_emulation is not enabled Sep 03 11:00:28 volumio volumio[18166]: info: Loading plugin "last_100"... Sep 03 11:00:28 volumio volumio[18166]: info: Loading plugin "webradio"... Sep 03 11:00:28 volumio volumio[18166]: info: Loading plugin "i2s_dacs"... Sep 03 11:00:28 volumio volumio[18166]: info: Loading plugin "volumiodiscovery"... Sep 03 11:00:28 volumio volumio[18166]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 03 11:00:28 volumio volumio[18166]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 11:00:28 volumio node[18166]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 03 11:00:28 volumio volumio[18166]: *** WARNING *** For more information see Sep 03 11:00:28 volumio volumio[18166]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 03 11:00:28 volumio volumio[18166]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 11:00:28 volumio volumio[18166]: *** WARNING *** For more information see Sep 03 11:00:28 volumio node[18166]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 11:00:28 volumio node[18166]: *** WARNING *** For more information see Sep 03 11:00:28 volumio node[18166]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 03 11:00:28 volumio node[18166]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 11:00:28 volumio node[18166]: *** WARNING *** For more information see Sep 03 11:00:28 volumio volumio[18166]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 03 11:00:28 volumio volumio[18166]: info: Discovery: Started advertising with name: Volumio Sep 03 11:00:28 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 11:00:28 volumio volumio[18166]: info: Loading plugin "jellyfin"... Sep 03 11:00:28 volumio volumio-remote-updater[504]: [2025-09-03 11:00:28] [connect] Successful connection Sep 03 11:00:29 volumio volumio[18166]: info: Loading plugin "spop"... Sep 03 11:00:30 volumio volumio[18166]: info: Loading plugin "ytcr"... Sep 03 11:00:33 volumio nmbd[721]: [2025/09/03 11:00:33.013363, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Sep 03 11:00:33 volumio nmbd[721]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.171 for name WORKGROUP<1d>. Sep 03 11:00:33 volumio nmbd[721]: This response was from IP 192.168.0.185, reporting an IP address of 192.168.0.185. Sep 03 11:00:34 volumio volumio[18166]: info: Loading plugin "outputs"... Sep 03 11:00:34 volumio volumio[18166]: info: Loading plugin "albumart"... Sep 03 11:00:34 volumio volumio[18166]: info: Plugin example_plugin is not enabled Sep 03 11:00:34 volumio volumio[18166]: info: Loading plugin "inputs"... Sep 03 11:00:34 volumio volumio[18166]: info: Loading plugin "updater_comm"... Sep 03 11:00:35 volumio volumio[18166]: info: Plugin mpdemulation is not enabled Sep 03 11:00:35 volumio volumio[18166]: info: Loading plugin "rest_api"... Sep 03 11:00:35 volumio volumio[18166]: info: Loading plugin "websocket"... Sep 03 11:00:35 volumio volumio[18166]: info: Starting Socket.io Server version 2.3.0 Sep 03 11:00:35 volumio volumio[18166]: info: Loading plugin "fusiondsp"... Sep 03 11:00:35 volumio volumio[18166]: Forking 3 albumart workers Sep 03 11:00:35 volumio volumio[18166]: info: Applying required configuration parameters for plugin fusiondsp Sep 03 11:00:35 volumio volumio[18166]: info: Loading plugin "Systeminfo"... Sep 03 11:00:35 volumio volumio[18166]: info: Loading i18n strings for locale hu Sep 03 11:00:35 volumio volumio[18166]: Updating browse sources language Sep 03 11:00:35 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 11:00:36 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 03 11:00:36 volumio volumio[18166]: info: CoreCommandRouter::initPlayerControls Sep 03 11:00:36 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:36 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:36 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:36 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:36 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:36 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:36 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:36 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:36 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 03 11:00:36 volumio volumio[18166]: Express server listening on port 3000 Sep 03 11:00:36 volumio volumio[18166]: [Metrics] WebUI: 11s 39.07ms Sep 03 11:00:36 volumio volumio[18166]: info: CoreStateMachine::resetVolumioState Sep 03 11:00:36 volumio volumio[18166]: info: CoreStateMachine::getcurrentVolume Sep 03 11:00:36 volumio volumio[18166]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 11:00:36 volumio volumio[18166]: Starting albumart workers Sep 03 11:00:36 volumio volumio[18166]: Starting albumart workers Sep 03 11:00:36 volumio volumio[18166]: info: Volumio Network Manager: Network status updated: 1 Sep 03 11:00:36 volumio volumio[18166]: Starting albumart workers Sep 03 11:00:36 volumio volumio[18166]: info: CoreCommandRouter::volumioGetState Sep 03 11:00:36 volumio volumio[18166]: info: CorePlayQueue::getTrack 0 Sep 03 11:00:36 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 11:00:36 volumio volumio[18166]: info: VolumeController:: Volume=37 Mute =false Sep 03 11:00:36 volumio volumio[18166]: info: CoreStateMachine::pushState Sep 03 11:00:36 volumio volumio[18166]: info: CorePlayQueue::getTrack 0 Sep 03 11:00:36 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 11:00:36 volumio volumio[18166]: info: CoreCommandRouter::volumioPushState Sep 03 11:00:36 volumio volumio[18166]: info: CoreStateMachine::updateTrackBlock Sep 03 11:00:36 volumio volumio[18166]: info: CorePlayQueue::getTrackBlock Sep 03 11:00:36 volumio volumio[18166]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 11:00:36 volumio volumio[18166]: info: CoreStateMachine::setRepeat false single undefined Sep 03 11:00:36 volumio volumio[18166]: info: CoreStateMachine::pushState Sep 03 11:00:36 volumio volumio[18166]: info: CorePlayQueue::getTrack 0 Sep 03 11:00:36 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 11:00:36 volumio volumio[18166]: info: CoreCommandRouter::volumioPushState Sep 03 11:00:36 volumio volumio[18166]: info: CoreStateMachine::setRandom true Sep 03 11:00:36 volumio volumio[18166]: info: CoreStateMachine::pushState Sep 03 11:00:36 volumio volumio[18166]: info: CorePlayQueue::getTrack 0 Sep 03 11:00:36 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 11:00:36 volumio volumio[18166]: info: CoreCommandRouter::volumioPushState Sep 03 11:00:36 volumio volumio[18166]: info: Setting Device type: Raspberry PI Sep 03 11:00:36 volumio volumio[18166]: info: Listing playlists Sep 03 11:00:36 volumio volumio[18166]: info: Listing playlists Sep 03 11:00:36 volumio volumio[18166]: info: Completed loading Core Plugins Sep 03 11:00:36 volumio volumio[18166]: info: Preparing to generate the ALSA configuration file Sep 03 11:00:36 volumio volumio[18166]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227 Sep 03 11:00:36 volumio volumio[18166]: info: Discovery: Found device Volumio Sep 03 11:00:36 volumio volumio[18166]: info: CoreCommandRouter::volumioGetState Sep 03 11:00:36 volumio volumio[18166]: info: CorePlayQueue::getTrack 0 Sep 03 11:00:36 volumio volumio[18166]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 03 11:00:36 volumio volumio[18166]: info: Reading ALSA contributions from plugins. Sep 03 11:00:36 volumio volumio[18166]: info: VolumeController:: Volume=37 Mute =false Sep 03 11:00:36 volumio volumio[18166]: info: CoreStateMachine::pushState Sep 03 11:00:36 volumio volumio[18166]: info: CorePlayQueue::getTrack 0 Sep 03 11:00:36 volumio volumio[18166]: info: CoreCommandRouter::volumioPushState Sep 03 11:00:36 volumio volumio[18166]: info: Asound.conf file unchanged, so no further update is needed Sep 03 11:00:36 volumio volumio[18166]: info: Output device has changed, restarting MPD Sep 03 11:00:36 volumio volumio[18166]: info: ___________ START PLUGINS ___________ Sep 03 11:00:37 volumio sudo[18303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 11:00:37 volumio volumio[18166]: info: ControllerMpd::onStart: Initializing MPD Sep 03 11:00:37 volumio volumio[18166]: info: Creating MPD Configuration file Sep 03 11:00:37 volumio sudo[18303]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:37 volumio sudo[18303]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:37 volumio sudo[18305]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 11:00:37 volumio sudo[18305]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:37 volumio sudo[18312]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 11:00:37 volumio volumio[18166]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 11:00:37 volumio sudo[18312]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:37 volumio volumio[18166]: info: [1756890037084] CoreMusicLibrary::Adding element Last_100 Sep 03 11:00:37 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 11:00:37 volumio systemd[1]: Stopping Music Player Daemon... Sep 03 11:00:37 volumio volumio[18166]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 11:00:37 volumio volumio[18166]: info: [1756890037092] CoreMusicLibrary::Adding element Webradio Sep 03 11:00:37 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 11:00:37 volumio sudo[18312]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:37 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 11:00:37 volumio volumio[18166]: info: Initializing BBC Radios Sep 03 11:00:37 volumio sudo[18319]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 11:00:37 volumio sudo[18319]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:37 volumio systemd[1]: mpd.service: Succeeded. Sep 03 11:00:37 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 11:00:37 volumio systemd[1]: Starting Music Player Daemon... Sep 03 11:00:37 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 03 11:00:37 volumio systemd[1]: mpd.service: Succeeded. Sep 03 11:00:37 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 11:00:37 volumio systemd[1]: Starting Music Player Daemon... Sep 03 11:00:37 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 11:00:37 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 11:00:37 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 11:00:37 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 03 11:00:37 volumio volumio[18166]: info: Discovery: Getting this device information Sep 03 11:00:37 volumio volumio[18166]: info: CoreCommandRouter::volumioGetState Sep 03 11:00:37 volumio volumio[18166]: info: CorePlayQueue::getTrack 0 Sep 03 11:00:37 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 11:00:37 volumio volumio[18166]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 11:00:37 volumio volumio[18166]: info: [1756890037294] CoreMusicLibrary::Adding element Jellyfin Sep 03 11:00:37 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 11:00:37 volumio volumio[18166]: Cannot find translation for source Jellyfin Sep 03 11:00:37 volumio volumio[18166]: 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 11:00:37 volumio volumio[18166]: info: Creating Spotify config file Sep 03 11:00:37 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:37 volumio sudo[18346]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 03 11:00:37 volumio sudo[18346]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:37 volumio sudo[18346]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:37 volumio volumio[18166]: info: Loading i18n strings for locale hu Sep 03 11:00:37 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 03 11:00:37 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:37 volumio volumio[18166]: info: FusionDsp - mixtype--------------------- Hardware Sep 03 11:00:37 volumio volumio[18166]: info: Volumio Calling Home Sep 03 11:00:37 volumio volumio[18166]: info: Preparing to generate the ALSA configuration file Sep 03 11:00:37 volumio volumio[18166]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 03 11:00:37 volumio volumio[18166]: info: Reading ALSA contributions from plugins. Sep 03 11:00:37 volumio volumio[18166]: info: MPD Permissions set Sep 03 11:00:37 volumio volumio[18166]: info: MPD Permissions set Sep 03 11:00:37 volumio volumio[18166]: info: Spotify config file written Sep 03 11:00:37 volumio sudo[18370]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 03 11:00:37 volumio sudo[18370]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:37 volumio systemd[1]: Stopping go-librespot Daemon... Sep 03 11:00:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 03 11:00:37 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 03 11:00:37 volumio systemd[1]: Stopped go-librespot Daemon. Sep 03 11:00:37 volumio volumio[18166]: 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 11:00:37 volumio volumio[18166]: info: Volumio called home Sep 03 11:00:37 volumio systemd[1]: Started go-librespot Daemon. Sep 03 11:00:37 volumio go-librespot[18372]: go-librespot daemon starting... Sep 03 11:00:37 volumio sudo[18370]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:37 volumio sudo[18374]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 03 11:00:37 volumio sudo[18374]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:38 volumio sudo[18374]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:38 volumio go-librespot[18372]: time="2025-09-03T11:00:38+02:00" level=info msg="running go-librespot 0.3.2" Sep 03 11:00:38 volumio go-librespot[18372]: time="2025-09-03T11:00:38+02:00" level=debug msg="app state loaded" Sep 03 11:00:38 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:38 volumio go-librespot[18372]: time="2025-09-03T11:00:38+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 03 11:00:38 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:38 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:38 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:38 volumio sudo[18382]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 11:00:38 volumio sudo[18382]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:38 volumio sudo[18382]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:38 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 11:00:38 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:38 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:38 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:38 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:38 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:38 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:38 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:38 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:38 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:38 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 11:00:38 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:38 volumio volumio[18166]: info: No need to fix Spotify hosts Sep 03 11:00:38 volumio go-librespot[18372]: time="2025-09-03T11:00:38+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 03 11:00:38 volumio go-librespot[18372]: time="2025-09-03T11:00:38+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 03 11:00:38 volumio go-librespot[18372]: time="2025-09-03T11:00:38+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 03 11:00:38 volumio go-librespot[18372]: time="2025-09-03T11:00:38+02:00" level=info msg="zeroconf server listening on port 36587" Sep 03 11:00:38 volumio go-librespot[18372]: time="2025-09-03T11:00:38+02:00" level=debug msg="obtained new client token: AACVVWO60G6MNKyBro+d5pG83xWtOLHaF0TsPBM9lKJRl0hFiI/LisQEcVUw9YkSrhxIlEhBGg//xlZ8GVlztkPpzkgWSoc0G5Gmg1uxWYkmT1OZ6U7VVcUpQB3EDFo/ih/MYV5LlFHzC8WjnznPE9hv7qicgJyVa+WZLQIiO/dpE2qLUh9SMVLJHTolRHD4icPxIVU4dmOsyC+u++fU15yusG3uMtbmSa1vmLsLsErceB6lDrqq5BQpQIQ=" Sep 03 11:00:38 volumio go-librespot[18372]: time="2025-09-03T11:00:38+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 03 11:00:38 volumio go-librespot[18372]: time="2025-09-03T11:00:38+02:00" level=debug msg="completed keyexchange" Sep 03 11:00:38 volumio go-librespot[18372]: time="2025-09-03T11:00:38+02:00" level=debug msg="completed challenge" Sep 03 11:00:38 volumio volumio[18166]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 03 11:00:38 volumio volumio[18166]: SPOTIFY: BQBnCqPmg43ybDNYcbCVY2x3Aw0vVuXSCUIZIzNahd_npj-3icb-cVbUdoKeBEG9MxDDzusR6SI_4JLtyzSUOIJhKq7EpoSGzF26SDuhgRd6RflB0l4zYgP-fOBvwDBXoRlToKsjrFB_dfkC11nyEM_0eJRmyYONrqXpi3BIKKo1hKD9tdbBTS_lGZq0zJ-91nG59WtI81klyUf8kRW9WJZ-SrX0OWpM2EhrsVWUBHDuVcIz56Qvi20YtwxO_ZgPwBTRPRIBdtjOyluWP_4YWSMCCDgTdcgcWlNy8nVTCOS7B8E Sep 03 11:00:38 volumio volumio[18166]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 03 11:00:38 volumio volumio[18166]: info: New Spotify access token = BQBnCqPmg43ybDNYcbCVY2x3Aw0vVuXSCUIZIzNahd_npj-3icb-cVbUdoKeBEG9MxDDzusR6SI_4JLtyzSUOIJhKq7EpoSGzF26SDuhgRd6RflB0l4zYgP-fOBvwDBXoRlToKsjrFB_dfkC11nyEM_0eJRmyYONrqXpi3BIKKo1hKD9tdbBTS_lGZq0zJ-91nG59WtI81klyUf8kRW9WJZ-SrX0OWpM2EhrsVWUBHDuVcIz56Qvi20YtwxO_ZgPwBTRPRIBdtjOyluWP_4YWSMCCDgTdcgcWlNy8nVTCOS7B8E Sep 03 11:00:38 volumio volumio[18166]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 03 11:00:38 volumio go-librespot[18372]: time="2025-09-03T11:00:38+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 03 11:00:38 volumio volumio[18166]: info: CoreCommandRouter::volumioGetState Sep 03 11:00:38 volumio volumio[18166]: info: CorePlayQueue::getTrack 0 Sep 03 11:00:38 volumio volumio[18166]: info: Asound.conf file unchanged, so no further update is needed Sep 03 11:00:38 volumio volumio[18166]: info: Output device has changed, restarting MPD Sep 03 11:00:38 volumio sudo[18396]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 11:00:38 volumio sudo[18396]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:38 volumio sudo[18396]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:38 volumio go-librespot[18372]: time="2025-09-03T11:00:38+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 03 11:00:38 volumio go-librespot[18372]: time="2025-09-03T11:00:38+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 03 11:00:38 volumio sudo[18399]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 11:00:38 volumio sudo[18399]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:38 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 03 11:00:38 volumio systemd[1]: mpd.service: Succeeded. Sep 03 11:00:38 volumio volumio[18166]: info: MPD Permissions set Sep 03 11:00:38 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 11:00:38 volumio go-librespot[18372]: time="2025-09-03T11:00:38+02:00" level=debug msg="dealer connection opened" Sep 03 11:00:38 volumio go-librespot[18372]: time="2025-09-03T11:00:38+02:00" level=trace msg="starting accesspoint recv loop" Sep 03 11:00:38 volumio go-librespot[18372]: time="2025-09-03T11:00:38+02:00" level=trace msg="starting dealer recv loop" Sep 03 11:00:38 volumio go-librespot[18372]: time="2025-09-03T11:00:38+02:00" level=trace msg="received accesspoint ping" Sep 03 11:00:38 volumio go-librespot[18372]: time="2025-09-03T11:00:38+02:00" level=debug msg="received connection id: NzQ1MGRjNjktZmI2...MkExNUE5RUFCMw==" Sep 03 11:00:38 volumio go-librespot[18372]: time="2025-09-03T11:00:38+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 03 11:00:38 volumio go-librespot[18372]: time="2025-09-03T11:00:38+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 03 11:00:38 volumio sudo[18402]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 03 11:00:38 volumio sudo[18402]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:38 volumio systemd[1]: Starting Music Player Daemon... Sep 03 11:00:38 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:38 volumio sudo[18402]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:38 volumio go-librespot[18372]: time="2025-09-03T11:00:38+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 03 11:00:38 volumio go-librespot[18372]: time="2025-09-03T11:00:38+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 03 11:00:38 volumio go-librespot[18372]: time="2025-09-03T11:00:38+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 03 11:00:38 volumio go-librespot[18372]: time="2025-09-03T11:00:38+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 03 11:00:38 volumio go-librespot[18372]: time="2025-09-03T11:00:38+02:00" level=trace msg="received accesspoint pong ack" Sep 03 11:00:38 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 11:00:38 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:38 volumio volumio[18166]: info: Upmpdcli Daemon Started Sep 03 11:00:38 volumio volumio[18166]: 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 11:00:38 volumio volumio[18166]: info: Spotify Successfully logged in Sep 03 11:00:38 volumio volumio[18166]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 11:00:38 volumio volumio[18166]: info: [1756890038849] CoreMusicLibrary::Adding element Spotify Sep 03 11:00:38 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 11:00:38 volumio volumio[18166]: Cannot find translation for source Jellyfin Sep 03 11:00:38 volumio volumio[18166]: Cannot find translation for source Spotify Sep 03 11:00:38 volumio sudo[18403]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 03 11:00:38 volumio sudo[18403]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:38 volumio sudo[18403]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:39 volumio volumio[18166]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 03 11:00:39 volumio volumio[18166]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 11:00:39 volumio volumio[18166]: info: VolumeController:: Volume=37 Mute =false Sep 03 11:00:39 volumio volumio[18166]: info: CoreCommandRouter::volumioGetState Sep 03 11:00:39 volumio volumio[18166]: info: CorePlayQueue::getTrack 0 Sep 03 11:00:39 volumio volumio[18166]: info: CoreStateMachine::pushState Sep 03 11:00:39 volumio volumio[18166]: info: CorePlayQueue::getTrack 0 Sep 03 11:00:39 volumio volumio[18166]: info: CoreCommandRouter::volumioPushState Sep 03 11:00:39 volumio volumio[18166]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 03 11:00:39 volumio volumio[18166]: 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 11:00:39 volumio volumio[18166]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 03 11:00:39 volumio volumio[18166]: info: camilladsp service started and running in background, instance 1 Sep 03 11:00:39 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:39 volumio volumio[18166]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 03 11:00:39 volumio volumio[18166]: 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 11:00:39 volumio volumio[18166]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 03 11:00:39 volumio volumio[18166]: info: FusionDsp loaded Sep 03 11:00:39 volumio volumio[18166]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 11:00:39 volumio sudo[18424]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 03 11:00:39 volumio sudo[18424]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:39 volumio volumio[18166]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 03 11:00:39 volumio volumio[18166]: info: Adding Signal Path Element [object Object] Sep 03 11:00:39 volumio volumio[18166]: info: Adding fusiondspeq DSP Signal Path Element Sep 03 11:00:39 volumio volumio[18166]: info: FusionDsp - ---- installed callbackRead Sep 03 11:00:39 volumio sudo[18424]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:39 volumio volumio[18166]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 11:00:39 volumio volumio[18166]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 03 11:00:39 volumio volumio[18166]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 03 11:00:39 volumio volumio[18166]: at onErrorNT (internal/child_process.js:465:16) Sep 03 11:00:39 volumio volumio[18166]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Sep 03 11:00:39 volumio volumio[18166]: errno: -2, Sep 03 11:00:39 volumio volumio[18166]: code: 'ENOENT', Sep 03 11:00:39 volumio volumio[18166]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 03 11:00:39 volumio volumio[18166]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 03 11:00:39 volumio volumio[18166]: spawnargs: [ Sep 03 11:00:39 volumio volumio[18166]: '-p', Sep 03 11:00:39 volumio volumio[18166]: 9876, Sep 03 11:00:39 volumio volumio[18166]: '-o', Sep 03 11:00:39 volumio volumio[18166]: '/tmp/camilladsp.log', Sep 03 11:00:39 volumio volumio[18166]: '-l', Sep 03 11:00:39 volumio volumio[18166]: 'warn', Sep 03 11:00:39 volumio volumio[18166]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 03 11:00:39 volumio volumio[18166]: ] Sep 03 11:00:39 volumio volumio[18166]: } Sep 03 11:00:39 volumio volumio[18166]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 11:00:40 volumio sudo[18435]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-03 10:59 Sep 03 11:00:40 volumio sudo[18435]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:40 volumio sudo[18435]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:40 volumio mpd[18411]: Sep 03 11:00 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 03 11:00:40 volumio systemd[1]: Started Music Player Daemon. Sep 03 11:00:40 volumio sudo[18319]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:40 volumio sudo[18305]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:40 volumio sudo[18399]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:40 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 03 11:00:40 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 03 11:00:40 volumio systemd[1]: Started dynamicswap service. Sep 03 11:00:40 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 03 11:00:40 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 03 11:00:40 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 7837. Sep 03 11:00:40 volumio systemd[1]: Stopped Volumio Backend Module. Sep 03 11:00:40 volumio systemd[1]: Started Volumio Backend Module. Sep 03 11:00:40 volumio systemd[1]: Started dynamicswap service. Sep 03 11:00:40 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 03 11:00:41 volumio volumio-remote-updater[504]: [2025-09-03 11:00:41] [info] asio async_connect error: system:111 (Connection refused) Sep 03 11:00:41 volumio volumio-remote-updater[504]: [2025-09-03 11:00:41] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 03 11:00:41 volumio volumio-remote-updater[504]: [2025-09-03 11:00:41] [error] handle_connect error: Underlying Transport Error Sep 03 11:00:42 volumio volumio[18447]: info: ------------------------------------------- Sep 03 11:00:42 volumio volumio[18447]: info: ----- Volumio3 ---- Sep 03 11:00:42 volumio volumio[18447]: info: ------------------------------------------- Sep 03 11:00:42 volumio volumio[18447]: info: ----- System startup ---- Sep 03 11:00:42 volumio volumio[18447]: info: ------------------------------------------- Sep 03 11:00:43 volumio volumio[18447]: info: MYVOLUMIO Environment detected Sep 03 11:00:43 volumio volumio[18447]: info: Plugin folders cleanup Sep 03 11:00:43 volumio volumio[18447]: info: Scanning into folder /volumio/app/plugins/ Sep 03 11:00:43 volumio volumio[18447]: info: Scanning category audio_interface Sep 03 11:00:43 volumio volumio[18447]: info: Scanning category miscellanea Sep 03 11:00:43 volumio volumio[18447]: info: Scanning category music_service Sep 03 11:00:43 volumio volumio[18447]: info: Scanning category plugins.json Sep 03 11:00:43 volumio volumio[18447]: info: Scanning category system_controller Sep 03 11:00:43 volumio volumio[18447]: info: Scanning category user_interface Sep 03 11:00:43 volumio volumio[18447]: info: Scanning into folder /data/plugins/ Sep 03 11:00:43 volumio volumio[18447]: info: Scanning category audio_interface Sep 03 11:00:43 volumio volumio[18447]: info: Scanning category music_service Sep 03 11:00:43 volumio volumio[18447]: info: Scanning category user_interface Sep 03 11:00:43 volumio volumio[18447]: info: Plugin folders cleanup completed Sep 03 11:00:43 volumio volumio[18447]: info: ------------------------------------------- Sep 03 11:00:43 volumio volumio[18447]: info: ----- Core plugins startup ---- Sep 03 11:00:43 volumio volumio[18447]: info: ------------------------------------------- Sep 03 11:00:43 volumio volumio[18447]: info: Loading plugins from folder /volumio/app/plugins/ Sep 03 11:00:43 volumio volumio[18447]: info: Adding plugin upnp to MyMusic Plugins Sep 03 11:00:43 volumio volumio[18447]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 03 11:00:43 volumio volumio[18447]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 03 11:00:43 volumio volumio[18447]: info: Loading plugins from folder /data/plugins/ Sep 03 11:00:43 volumio volumio[18447]: info: Loading plugin "system"... Sep 03 11:00:43 volumio volumio[18447]: info: Loading plugin "appearance"... Sep 03 11:00:44 volumio volumio[18447]: info: Loading plugin "network"... Sep 03 11:00:44 volumio volumio[18447]: info: Refreshing Cached IP Addresses Sep 03 11:00:44 volumio sudo[18474]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 03 11:00:44 volumio sudo[18474]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:44 volumio sudo[18474]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:44 volumio sudo[18476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 11:00:44 volumio volumio[18447]: info: Loading plugin "services"... Sep 03 11:00:44 volumio sudo[18476]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:44 volumio volumio[18447]: info: Loading plugin "alsa_controller"... Sep 03 11:00:44 volumio sudo[18476]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:44 volumio volumio[18447]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 11:00:44 volumio volumio[18447]: info: Loading plugin "wizard"... Sep 03 11:00:44 volumio volumio[18447]: info: Loading plugin "networkfs"... Sep 03 11:00:44 volumio volumio[18447]: info: Starting Udev Watcher for removable devices Sep 03 11:00:44 volumio volumio[18447]: info: Ignoring mount for partition: boot Sep 03 11:00:44 volumio volumio[18447]: info: Ignoring mount for partition: volumio Sep 03 11:00:44 volumio volumio[18447]: info: Ignoring mount for partition: volumio_data Sep 03 11:00:44 volumio volumio[18447]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 11:00:44 volumio volumio[18447]: info: Loading plugin "volumio_command_line_client"... Sep 03 11:00:44 volumio volumio[18447]: info: Loading plugin "upnp"... Sep 03 11:00:44 volumio volumio[18447]: info: [1756890044594] Starting Upmpd Daemon Sep 03 11:00:44 volumio volumio[18447]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 11:00:44 volumio volumio[18447]: info: Loading plugin "my_music"... Sep 03 11:00:44 volumio volumio[18447]: info: Loading plugin "mpd"... Sep 03 11:00:44 volumio volumio[18447]: info: Plugin upnp_browser is not enabled Sep 03 11:00:44 volumio volumio[18447]: info: Loading plugin "alarm-clock"... Sep 03 11:00:45 volumio volumio[18447]: info: Plugin airplay_emulation is not enabled Sep 03 11:00:45 volumio volumio[18447]: info: Loading plugin "last_100"... Sep 03 11:00:45 volumio volumio[18447]: info: Loading plugin "webradio"... Sep 03 11:00:45 volumio volumio[18447]: info: Loading plugin "i2s_dacs"... Sep 03 11:00:45 volumio volumio[18447]: info: Loading plugin "volumiodiscovery"... Sep 03 11:00:45 volumio volumio[18447]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 03 11:00:45 volumio volumio[18447]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 11:00:45 volumio volumio[18447]: *** WARNING *** For more information see Sep 03 11:00:45 volumio node[18447]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 03 11:00:45 volumio volumio[18447]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 03 11:00:45 volumio volumio[18447]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 11:00:45 volumio volumio[18447]: *** WARNING *** For more information see Sep 03 11:00:45 volumio node[18447]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 11:00:45 volumio node[18447]: *** WARNING *** For more information see Sep 03 11:00:45 volumio node[18447]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 03 11:00:45 volumio node[18447]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 11:00:45 volumio node[18447]: *** WARNING *** For more information see Sep 03 11:00:45 volumio volumio[18447]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 03 11:00:45 volumio volumio[18447]: info: Discovery: Started advertising with name: Volumio Sep 03 11:00:45 volumio volumio[18447]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 11:00:45 volumio volumio[18447]: info: Loading plugin "jellyfin"... Sep 03 11:00:46 volumio volumio[18447]: info: Loading plugin "spop"... Sep 03 11:00:47 volumio volumio[18447]: info: Loading plugin "ytcr"... Sep 03 11:00:51 volumio volumio-remote-updater[504]: [2025-09-03 11:00:51] [connect] Successful connection Sep 03 11:00:51 volumio volumio[18447]: info: Loading plugin "outputs"... Sep 03 11:00:51 volumio volumio[18447]: info: Loading plugin "albumart"... Sep 03 11:00:51 volumio volumio[18447]: info: Plugin example_plugin is not enabled Sep 03 11:00:51 volumio volumio[18447]: info: Loading plugin "inputs"... Sep 03 11:00:51 volumio volumio[18447]: info: Loading plugin "updater_comm"... Sep 03 11:00:51 volumio volumio[18447]: info: Plugin mpdemulation is not enabled Sep 03 11:00:51 volumio volumio[18447]: info: Loading plugin "rest_api"... Sep 03 11:00:51 volumio volumio[18447]: info: Loading plugin "websocket"... Sep 03 11:00:51 volumio volumio[18447]: info: Starting Socket.io Server version 2.3.0 Sep 03 11:00:51 volumio volumio[18447]: info: Loading plugin "fusiondsp"... Sep 03 11:00:52 volumio volumio[18447]: Forking 3 albumart workers Sep 03 11:00:52 volumio volumio[18447]: info: Applying required configuration parameters for plugin fusiondsp Sep 03 11:00:52 volumio volumio[18447]: info: Loading plugin "Systeminfo"... Sep 03 11:00:52 volumio volumio[18447]: info: Loading i18n strings for locale hu Sep 03 11:00:52 volumio volumio[18447]: Updating browse sources language Sep 03 11:00:52 volumio volumio[18447]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 11:00:52 volumio volumio[18447]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 03 11:00:52 volumio volumio[18447]: info: CoreCommandRouter::initPlayerControls Sep 03 11:00:52 volumio volumio[18447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:52 volumio volumio[18447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:52 volumio volumio[18447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:52 volumio volumio[18447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:52 volumio volumio[18447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:52 volumio volumio[18447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:52 volumio volumio[18447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:52 volumio volumio[18447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:52 volumio volumio[18447]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 03 11:00:52 volumio volumio[18447]: Express server listening on port 3000 Sep 03 11:00:52 volumio volumio[18447]: [Metrics] WebUI: 11s 56.92ms Sep 03 11:00:53 volumio volumio[18447]: info: CoreStateMachine::resetVolumioState Sep 03 11:00:53 volumio volumio[18447]: info: CoreStateMachine::getcurrentVolume Sep 03 11:00:53 volumio volumio[18447]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 11:00:53 volumio volumio[18447]: Starting albumart workers Sep 03 11:00:53 volumio volumio[18447]: Starting albumart workers Sep 03 11:00:53 volumio volumio[18447]: Starting albumart workers Sep 03 11:00:53 volumio volumio[18447]: info: Volumio Network Manager: Network status updated: 1 Sep 03 11:00:53 volumio volumio-remote-updater[504]: [2025-09-03 11:00:53] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1756890051 101 Sep 03 11:00:53 volumio volumio[18447]: 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 11:00:53 volumio volumio[18447]: info: CoreCommandRouter::volumioGetState Sep 03 11:00:53 volumio volumio[18447]: info: CorePlayQueue::getTrack 0 Sep 03 11:00:53 volumio volumio[18447]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 11:00:53 volumio volumio[18447]: info: VolumeController:: Volume=37 Mute =false Sep 03 11:00:53 volumio volumio[18447]: info: CoreStateMachine::pushState Sep 03 11:00:53 volumio volumio[18447]: info: CorePlayQueue::getTrack 0 Sep 03 11:00:53 volumio volumio[18447]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 11:00:53 volumio volumio[18447]: info: CoreCommandRouter::volumioPushState Sep 03 11:00:53 volumio volumio[18447]: info: CoreStateMachine::updateTrackBlock Sep 03 11:00:53 volumio volumio[18447]: info: CorePlayQueue::getTrackBlock Sep 03 11:00:53 volumio volumio[18447]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 11:00:53 volumio volumio[18447]: info: CoreStateMachine::setRepeat false single undefined Sep 03 11:00:53 volumio volumio[18447]: info: CoreStateMachine::pushState Sep 03 11:00:53 volumio volumio[18447]: info: CorePlayQueue::getTrack 0 Sep 03 11:00:53 volumio volumio[18447]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 11:00:53 volumio volumio[18447]: info: CoreCommandRouter::volumioPushState Sep 03 11:00:53 volumio volumio[18447]: info: CoreStateMachine::setRandom true Sep 03 11:00:53 volumio volumio[18447]: info: CoreStateMachine::pushState Sep 03 11:00:53 volumio volumio[18447]: info: CorePlayQueue::getTrack 0 Sep 03 11:00:53 volumio volumio[18447]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 11:00:53 volumio volumio[18447]: info: CoreCommandRouter::volumioPushState Sep 03 11:00:53 volumio volumio[18447]: info: Setting Device type: Raspberry PI Sep 03 11:00:53 volumio volumio[18447]: info: Listing playlists Sep 03 11:00:53 volumio volumio[18447]: info: Listing playlists Sep 03 11:00:53 volumio volumio[18447]: info: Completed loading Core Plugins Sep 03 11:00:53 volumio volumio[18447]: info: Preparing to generate the ALSA configuration file Sep 03 11:00:53 volumio volumio[18447]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227 Sep 03 11:00:53 volumio volumio[18447]: info: Discovery: Found device Volumio Sep 03 11:00:53 volumio volumio[18447]: info: CoreCommandRouter::volumioGetState Sep 03 11:00:53 volumio volumio[18447]: info: CorePlayQueue::getTrack 0 Sep 03 11:00:53 volumio volumio[18447]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 03 11:00:53 volumio volumio[18447]: info: Reading ALSA contributions from plugins. Sep 03 11:00:53 volumio volumio[18447]: info: VolumeController:: Volume=37 Mute =false Sep 03 11:00:53 volumio volumio[18447]: info: CoreStateMachine::pushState Sep 03 11:00:53 volumio volumio[18447]: info: CorePlayQueue::getTrack 0 Sep 03 11:00:53 volumio volumio[18447]: info: CoreCommandRouter::volumioPushState Sep 03 11:00:53 volumio volumio[18447]: info: Asound.conf file unchanged, so no further update is needed Sep 03 11:00:53 volumio volumio[18447]: info: Output device has changed, restarting MPD Sep 03 11:00:53 volumio sudo[18554]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 11:00:53 volumio sudo[18554]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:53 volumio sudo[18554]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:53 volumio volumio[18447]: info: ___________ START PLUGINS ___________ Sep 03 11:00:53 volumio volumio[18447]: info: ControllerMpd::onStart: Initializing MPD Sep 03 11:00:53 volumio volumio[18447]: info: Creating MPD Configuration file Sep 03 11:00:53 volumio sudo[18562]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 11:00:53 volumio sudo[18562]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:53 volumio systemd[1]: Stopping Music Player Daemon... Sep 03 11:00:53 volumio sudo[18572]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 11:00:53 volumio systemd[1]: mpd.service: Succeeded. Sep 03 11:00:53 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 11:00:53 volumio sudo[18572]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:53 volumio volumio[18447]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 11:00:53 volumio volumio[18447]: info: [1756890053955] CoreMusicLibrary::Adding element Last_100 Sep 03 11:00:53 volumio volumio[18447]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 11:00:53 volumio sudo[18572]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:53 volumio volumio[18447]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 11:00:53 volumio volumio[18447]: info: [1756890053970] CoreMusicLibrary::Adding element Webradio Sep 03 11:00:53 volumio volumio[18447]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 11:00:53 volumio volumio[18447]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 11:00:53 volumio sudo[18578]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 11:00:53 volumio sudo[18578]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:54 volumio volumio[18447]: info: Initializing BBC Radios Sep 03 11:00:54 volumio systemd[1]: Starting Music Player Daemon... Sep 03 11:00:54 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 03 11:00:54 volumio systemd[1]: mpd.service: Succeeded. Sep 03 11:00:54 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 11:00:54 volumio systemd[1]: Starting Music Player Daemon... Sep 03 11:00:54 volumio volumio[18447]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 11:00:54 volumio volumio[18447]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 11:00:54 volumio volumio[18447]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 11:00:54 volumio volumio[18447]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 03 11:00:54 volumio volumio[18447]: info: Discovery: Getting this device information Sep 03 11:00:54 volumio volumio[18447]: info: CoreCommandRouter::volumioGetState Sep 03 11:00:54 volumio volumio[18447]: info: CorePlayQueue::getTrack 0 Sep 03 11:00:54 volumio volumio[18447]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 11:00:54 volumio volumio[18447]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 11:00:54 volumio volumio[18447]: info: [1756890054099] CoreMusicLibrary::Adding element Jellyfin Sep 03 11:00:54 volumio volumio[18447]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 11:00:54 volumio volumio[18447]: Cannot find translation for source Jellyfin Sep 03 11:00:54 volumio volumio[18447]: 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 11:00:54 volumio volumio[18447]: info: Creating Spotify config file Sep 03 11:00:54 volumio volumio[18447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:54 volumio sudo[18585]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 03 11:00:54 volumio sudo[18585]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:54 volumio sudo[18585]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:54 volumio volumio[18447]: info: Loading i18n strings for locale hu Sep 03 11:00:54 volumio volumio[18447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 03 11:00:54 volumio volumio[18447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:54 volumio volumio[18447]: info: FusionDsp - mixtype--------------------- Hardware Sep 03 11:00:54 volumio volumio[18447]: info: Volumio Calling Home Sep 03 11:00:54 volumio volumio[18447]: info: Preparing to generate the ALSA configuration file Sep 03 11:00:54 volumio volumio[18447]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 03 11:00:54 volumio volumio[18447]: info: Reading ALSA contributions from plugins. Sep 03 11:00:54 volumio volumio[18447]: info: MPD Permissions set Sep 03 11:00:54 volumio volumio[18447]: info: MPD Permissions set Sep 03 11:00:54 volumio volumio[18447]: info: Spotify config file written Sep 03 11:00:54 volumio volumio[18447]: 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 11:00:54 volumio sudo[18613]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 03 11:00:54 volumio sudo[18613]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:54 volumio volumio[18447]: info: Volumio called home Sep 03 11:00:54 volumio systemd[1]: Stopping go-librespot Daemon... Sep 03 11:00:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 03 11:00:54 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 03 11:00:54 volumio systemd[1]: Stopped go-librespot Daemon. Sep 03 11:00:54 volumio systemd[1]: Started go-librespot Daemon. Sep 03 11:00:54 volumio go-librespot[18616]: go-librespot daemon starting... Sep 03 11:00:54 volumio sudo[18613]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:54 volumio go-librespot[18616]: time="2025-09-03T11:00:54+02:00" level=info msg="running go-librespot 0.3.2" Sep 03 11:00:54 volumio go-librespot[18616]: time="2025-09-03T11:00:54+02:00" level=debug msg="app state loaded" Sep 03 11:00:54 volumio go-librespot[18616]: time="2025-09-03T11:00:54+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 03 11:00:54 volumio volumio[18447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:54 volumio sudo[18619]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 03 11:00:54 volumio volumio[18447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:54 volumio volumio[18447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:54 volumio volumio[18447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:54 volumio sudo[18619]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:54 volumio sudo[18625]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 11:00:54 volumio sudo[18619]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:54 volumio sudo[18625]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:54 volumio sudo[18625]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:54 volumio volumio[18447]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 11:00:54 volumio volumio[18447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:54 volumio volumio[18447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:54 volumio volumio[18447]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 11:00:54 volumio volumio[18447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:54 volumio volumio[18447]: info: No need to fix Spotify hosts Sep 03 11:00:55 volumio go-librespot[18616]: time="2025-09-03T11:00:55+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Sep 03 11:00:55 volumio go-librespot[18616]: time="2025-09-03T11:00:55+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 03 11:00:55 volumio go-librespot[18616]: time="2025-09-03T11:00:55+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 03 11:00:55 volumio go-librespot[18616]: time="2025-09-03T11:00:55+02:00" level=info msg="zeroconf server listening on port 43251" Sep 03 11:00:55 volumio go-librespot[18616]: time="2025-09-03T11:00:55+02:00" level=debug msg="obtained new client token: AACkydruC2sWrq4SPcjPhZNITR7ZCBuDHG+mk8/8qT1BwooTpMJP05Ge2sjIAcQm680udBuSI/ggPIdb8hqdrK9TGu3y6xLsbFH4Qx19MqKFd26MsVKTrHZGiPq4E0cSugc94vDik9orOn4l2MVBnobZuHSiR4ZKa+Y/9LjDN3kvXVh5zi5W3obpy7bA2ZsQaHC83PV5AF3eQajlYhNExG/1wCAkVtzoke0Bl4knjZjPAFBf0j4gtqoB" Sep 03 11:00:55 volumio go-librespot[18616]: time="2025-09-03T11:00:55+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 03 11:00:55 volumio go-librespot[18616]: time="2025-09-03T11:00:55+02:00" level=debug msg="completed keyexchange" Sep 03 11:00:55 volumio go-librespot[18616]: time="2025-09-03T11:00:55+02:00" level=debug msg="completed challenge" Sep 03 11:00:55 volumio go-librespot[18616]: time="2025-09-03T11:00:55+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 03 11:00:55 volumio volumio[18447]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 03 11:00:55 volumio volumio[18447]: SPOTIFY: BQABoM4nncbB4jgRDeeofEE9zQ_Av9mlUP0vLrwL9y3ca27z7Eo2btSsiBs6dgKd8IV-_djUp-V4JADlYoQ5Qsh88EJifqjrh6wcjjgTLEDsnPbcjLQ6jqy7qkY1DGLx39c-hEcsxBmXWqw8qQlP6TaqKke6SVy94_739HubspdrQh79QSEyGEdWm4XJaOSrM8vsZIQKLEUE8jZyNQGPE_keVlyHXsD4J4dAAuMgVkUquy9FHd2pLgAuj8h6r5ENVUInMDv9P7dhKpD9IR5kVeSFMMsQV0a9bbbwVuaiF-f7tiQ Sep 03 11:00:55 volumio volumio[18447]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 03 11:00:55 volumio volumio[18447]: info: New Spotify access token = BQABoM4nncbB4jgRDeeofEE9zQ_Av9mlUP0vLrwL9y3ca27z7Eo2btSsiBs6dgKd8IV-_djUp-V4JADlYoQ5Qsh88EJifqjrh6wcjjgTLEDsnPbcjLQ6jqy7qkY1DGLx39c-hEcsxBmXWqw8qQlP6TaqKke6SVy94_739HubspdrQh79QSEyGEdWm4XJaOSrM8vsZIQKLEUE8jZyNQGPE_keVlyHXsD4J4dAAuMgVkUquy9FHd2pLgAuj8h6r5ENVUInMDv9P7dhKpD9IR5kVeSFMMsQV0a9bbbwVuaiF-f7tiQ Sep 03 11:00:55 volumio volumio[18447]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 03 11:00:55 volumio volumio[18447]: info: CoreCommandRouter::volumioGetState Sep 03 11:00:55 volumio volumio[18447]: info: CorePlayQueue::getTrack 0 Sep 03 11:00:55 volumio volumio[18447]: info: Asound.conf file unchanged, so no further update is needed Sep 03 11:00:55 volumio volumio[18447]: info: Output device has changed, restarting MPD Sep 03 11:00:55 volumio go-librespot[18616]: time="2025-09-03T11:00:55+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 03 11:00:55 volumio go-librespot[18616]: time="2025-09-03T11:00:55+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 03 11:00:55 volumio sudo[18639]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 11:00:55 volumio sudo[18639]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:55 volumio sudo[18639]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:55 volumio sudo[18641]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 11:00:55 volumio sudo[18641]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:55 volumio go-librespot[18616]: time="2025-09-03T11:00:55+02:00" level=debug msg="dealer connection opened" Sep 03 11:00:55 volumio go-librespot[18616]: time="2025-09-03T11:00:55+02:00" level=trace msg="starting accesspoint recv loop" Sep 03 11:00:55 volumio go-librespot[18616]: time="2025-09-03T11:00:55+02:00" level=trace msg="starting dealer recv loop" Sep 03 11:00:55 volumio go-librespot[18616]: time="2025-09-03T11:00:55+02:00" level=trace msg="received accesspoint ping" Sep 03 11:00:55 volumio go-librespot[18616]: time="2025-09-03T11:00:55+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 03 11:00:55 volumio go-librespot[18616]: time="2025-09-03T11:00:55+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 03 11:00:55 volumio go-librespot[18616]: time="2025-09-03T11:00:55+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 03 11:00:55 volumio go-librespot[18616]: time="2025-09-03T11:00:55+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 03 11:00:55 volumio go-librespot[18616]: time="2025-09-03T11:00:55+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 03 11:00:55 volumio go-librespot[18616]: time="2025-09-03T11:00:55+02:00" level=debug msg="received connection id: MzA1MjZiZDctNTZm...OTYyQTg5QzU1RA==" Sep 03 11:00:55 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 03 11:00:55 volumio systemd[1]: mpd.service: Succeeded. Sep 03 11:00:55 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 11:00:55 volumio go-librespot[18616]: time="2025-09-03T11:00:55+02:00" level=trace msg="received accesspoint pong ack" Sep 03 11:00:55 volumio sudo[18645]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 03 11:00:55 volumio sudo[18645]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:55 volumio volumio[18447]: info: MPD Permissions set Sep 03 11:00:55 volumio volumio[18447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:55 volumio volumio[18447]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 11:00:55 volumio systemd[1]: Starting Music Player Daemon... Sep 03 11:00:55 volumio volumio[18447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:55 volumio sudo[18645]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:55 volumio go-librespot[18616]: time="2025-09-03T11:00:55+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 03 11:00:55 volumio volumio[18447]: info: Upmpdcli Daemon Started Sep 03 11:00:55 volumio volumio[18447]: 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 11:00:55 volumio volumio[18447]: info: Spotify Successfully logged in Sep 03 11:00:55 volumio volumio[18447]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 11:00:55 volumio volumio[18447]: info: [1756890055679] CoreMusicLibrary::Adding element Spotify Sep 03 11:00:55 volumio volumio[18447]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 11:00:55 volumio volumio[18447]: Cannot find translation for source Jellyfin Sep 03 11:00:55 volumio volumio[18447]: Cannot find translation for source Spotify Sep 03 11:00:55 volumio sudo[18646]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 03 11:00:55 volumio sudo[18646]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:55 volumio sudo[18646]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:56 volumio volumio[18447]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 03 11:00:56 volumio volumio[18447]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 11:00:56 volumio volumio[18447]: info: VolumeController:: Volume=37 Mute =false Sep 03 11:00:56 volumio volumio[18447]: info: CoreCommandRouter::volumioGetState Sep 03 11:00:56 volumio volumio[18447]: info: CorePlayQueue::getTrack 0 Sep 03 11:00:56 volumio volumio[18447]: info: CoreStateMachine::pushState Sep 03 11:00:56 volumio volumio[18447]: info: CorePlayQueue::getTrack 0 Sep 03 11:00:56 volumio volumio[18447]: info: CoreCommandRouter::volumioPushState Sep 03 11:00:56 volumio volumio[18447]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 03 11:00:56 volumio volumio[18447]: 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 11:00:56 volumio volumio[18447]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 03 11:00:56 volumio volumio[18447]: info: camilladsp service started and running in background, instance 1 Sep 03 11:00:56 volumio volumio[18447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:00:56 volumio volumio[18447]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 03 11:00:56 volumio volumio[18447]: 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 11:00:56 volumio volumio[18447]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 03 11:00:56 volumio volumio[18447]: info: FusionDsp loaded Sep 03 11:00:56 volumio volumio[18447]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 11:00:56 volumio sudo[18667]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 03 11:00:56 volumio sudo[18667]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:56 volumio sudo[18667]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:56 volumio volumio[18447]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 03 11:00:56 volumio volumio[18447]: info: Adding Signal Path Element [object Object] Sep 03 11:00:56 volumio volumio[18447]: info: Adding fusiondspeq DSP Signal Path Element Sep 03 11:00:56 volumio volumio[18447]: info: FusionDsp - ---- installed callbackRead Sep 03 11:00:56 volumio volumio[18447]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 11:00:56 volumio volumio[18447]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 03 11:00:56 volumio volumio[18447]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 03 11:00:56 volumio volumio[18447]: at onErrorNT (internal/child_process.js:465:16) Sep 03 11:00:56 volumio volumio[18447]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Sep 03 11:00:56 volumio volumio[18447]: errno: -2, Sep 03 11:00:56 volumio volumio[18447]: code: 'ENOENT', Sep 03 11:00:56 volumio volumio[18447]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 03 11:00:56 volumio volumio[18447]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 03 11:00:56 volumio volumio[18447]: spawnargs: [ Sep 03 11:00:56 volumio volumio[18447]: '-p', Sep 03 11:00:56 volumio volumio[18447]: 9876, Sep 03 11:00:56 volumio volumio[18447]: '-o', Sep 03 11:00:56 volumio volumio[18447]: '/tmp/camilladsp.log', Sep 03 11:00:56 volumio volumio[18447]: '-l', Sep 03 11:00:56 volumio volumio[18447]: 'warn', Sep 03 11:00:56 volumio volumio[18447]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 03 11:00:56 volumio volumio[18447]: ] Sep 03 11:00:56 volumio volumio[18447]: } Sep 03 11:00:56 volumio volumio[18447]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 11:00:57 volumio sudo[18678]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-03 10:59 Sep 03 11:00:57 volumio sudo[18678]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:00:57 volumio mpd[18654]: Sep 03 11:00 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 03 11:00:57 volumio systemd[1]: Started Music Player Daemon. Sep 03 11:00:57 volumio sudo[18641]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:57 volumio sudo[18578]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:57 volumio sudo[18562]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:57 volumio sudo[18678]: pam_unix(sudo:session): session closed for user root Sep 03 11:00:57 volumio volumio-remote-updater[504]: [2025-09-03 11:00:57] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 03 11:00:57 volumio volumio-remote-updater[504]: [2025-09-03 11:00:57] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 03 11:00:57 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 03 11:00:57 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 03 11:00:57 volumio systemd[1]: Started dynamicswap service. Sep 03 11:00:57 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 03 11:00:57 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 03 11:00:57 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 7838. Sep 03 11:00:57 volumio systemd[1]: Started dynamicswap service. Sep 03 11:00:57 volumio systemd[1]: Stopped Volumio Backend Module. Sep 03 11:00:57 volumio systemd[1]: Started Volumio Backend Module. Sep 03 11:00:57 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 03 11:00:59 volumio volumio[18691]: info: ------------------------------------------- Sep 03 11:00:59 volumio volumio[18691]: info: ----- Volumio3 ---- Sep 03 11:00:59 volumio volumio[18691]: info: ------------------------------------------- Sep 03 11:00:59 volumio volumio[18691]: info: ----- System startup ---- Sep 03 11:00:59 volumio volumio[18691]: info: ------------------------------------------- Sep 03 11:01:00 volumio volumio[18691]: info: MYVOLUMIO Environment detected Sep 03 11:01:00 volumio volumio[18691]: info: Plugin folders cleanup Sep 03 11:01:00 volumio volumio[18691]: info: Scanning into folder /volumio/app/plugins/ Sep 03 11:01:00 volumio volumio[18691]: info: Scanning category audio_interface Sep 03 11:01:00 volumio volumio[18691]: info: Scanning category miscellanea Sep 03 11:01:00 volumio volumio[18691]: info: Scanning category music_service Sep 03 11:01:00 volumio volumio[18691]: info: Scanning category plugins.json Sep 03 11:01:00 volumio volumio[18691]: info: Scanning category system_controller Sep 03 11:01:00 volumio volumio[18691]: info: Scanning category user_interface Sep 03 11:01:00 volumio volumio[18691]: info: Scanning into folder /data/plugins/ Sep 03 11:01:00 volumio volumio[18691]: info: Scanning category audio_interface Sep 03 11:01:00 volumio volumio[18691]: info: Scanning category music_service Sep 03 11:01:00 volumio volumio[18691]: info: Scanning category user_interface Sep 03 11:01:00 volumio volumio[18691]: info: Plugin folders cleanup completed Sep 03 11:01:00 volumio volumio[18691]: info: ------------------------------------------- Sep 03 11:01:00 volumio volumio[18691]: info: ----- Core plugins startup ---- Sep 03 11:01:00 volumio volumio[18691]: info: ------------------------------------------- Sep 03 11:01:00 volumio volumio[18691]: info: Loading plugins from folder /volumio/app/plugins/ Sep 03 11:01:00 volumio volumio[18691]: info: Adding plugin upnp to MyMusic Plugins Sep 03 11:01:00 volumio volumio[18691]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 03 11:01:00 volumio volumio[18691]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 03 11:01:00 volumio volumio[18691]: info: Loading plugins from folder /data/plugins/ Sep 03 11:01:00 volumio volumio[18691]: info: Loading plugin "system"... Sep 03 11:01:00 volumio volumio[18691]: info: Loading plugin "appearance"... Sep 03 11:01:01 volumio volumio[18691]: info: Loading plugin "network"... Sep 03 11:01:01 volumio volumio[18691]: info: Refreshing Cached IP Addresses Sep 03 11:01:01 volumio sudo[18717]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 03 11:01:01 volumio sudo[18717]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:01:01 volumio sudo[18717]: pam_unix(sudo:session): session closed for user root Sep 03 11:01:01 volumio volumio[18691]: info: Loading plugin "services"... Sep 03 11:01:01 volumio sudo[18719]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 11:01:01 volumio volumio[18691]: info: Loading plugin "alsa_controller"... Sep 03 11:01:01 volumio sudo[18719]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:01:01 volumio sudo[18719]: pam_unix(sudo:session): session closed for user root Sep 03 11:01:01 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 11:01:01 volumio volumio[18691]: info: Loading plugin "wizard"... Sep 03 11:01:01 volumio volumio[18691]: info: Loading plugin "networkfs"... Sep 03 11:01:01 volumio volumio[18691]: info: Starting Udev Watcher for removable devices Sep 03 11:01:01 volumio volumio[18691]: info: Ignoring mount for partition: boot Sep 03 11:01:01 volumio volumio[18691]: info: Ignoring mount for partition: volumio Sep 03 11:01:01 volumio volumio[18691]: info: Ignoring mount for partition: volumio_data Sep 03 11:01:01 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 11:01:01 volumio volumio[18691]: info: Loading plugin "volumio_command_line_client"... Sep 03 11:01:01 volumio volumio[18691]: info: Loading plugin "upnp"... Sep 03 11:01:01 volumio volumio[18691]: info: [1756890061514] Starting Upmpd Daemon Sep 03 11:01:01 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 11:01:01 volumio volumio[18691]: info: Loading plugin "my_music"... Sep 03 11:01:01 volumio volumio[18691]: info: Loading plugin "mpd"... Sep 03 11:01:01 volumio volumio[18691]: info: Plugin upnp_browser is not enabled Sep 03 11:01:01 volumio volumio[18691]: info: Loading plugin "alarm-clock"... Sep 03 11:01:02 volumio volumio[18691]: info: Plugin airplay_emulation is not enabled Sep 03 11:01:02 volumio volumio[18691]: info: Loading plugin "last_100"... Sep 03 11:01:02 volumio volumio[18691]: info: Loading plugin "webradio"... Sep 03 11:01:02 volumio volumio[18691]: info: Loading plugin "i2s_dacs"... Sep 03 11:01:02 volumio volumio[18691]: info: Loading plugin "volumiodiscovery"... Sep 03 11:01:02 volumio volumio[18691]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 03 11:01:02 volumio volumio[18691]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 11:01:02 volumio volumio[18691]: *** WARNING *** For more information see Sep 03 11:01:02 volumio node[18691]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 03 11:01:02 volumio volumio[18691]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 03 11:01:02 volumio volumio[18691]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 11:01:02 volumio volumio[18691]: *** WARNING *** For more information see Sep 03 11:01:02 volumio node[18691]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 11:01:02 volumio node[18691]: *** WARNING *** For more information see Sep 03 11:01:02 volumio node[18691]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 03 11:01:02 volumio node[18691]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 11:01:02 volumio node[18691]: *** WARNING *** For more information see Sep 03 11:01:02 volumio volumio[18691]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 03 11:01:02 volumio volumio[18691]: info: Discovery: Started advertising with name: Volumio Sep 03 11:01:02 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 11:01:02 volumio volumio[18691]: info: Loading plugin "jellyfin"... Sep 03 11:01:02 volumio volumio-remote-updater[504]: [2025-09-03 11:01:02] [connect] Successful connection Sep 03 11:01:03 volumio volumio[18691]: info: Loading plugin "spop"... Sep 03 11:01:04 volumio volumio[18691]: info: Loading plugin "ytcr"... Sep 03 11:01:08 volumio volumio[18691]: info: Loading plugin "outputs"... Sep 03 11:01:08 volumio volumio[18691]: info: Loading plugin "albumart"... Sep 03 11:01:08 volumio volumio[18691]: info: Plugin example_plugin is not enabled Sep 03 11:01:08 volumio volumio[18691]: info: Loading plugin "inputs"... Sep 03 11:01:08 volumio volumio[18691]: info: Loading plugin "updater_comm"... Sep 03 11:01:08 volumio volumio[18691]: info: Plugin mpdemulation is not enabled Sep 03 11:01:08 volumio volumio[18691]: info: Loading plugin "rest_api"... Sep 03 11:01:08 volumio volumio[18691]: info: Loading plugin "websocket"... Sep 03 11:01:08 volumio volumio[18691]: info: Starting Socket.io Server version 2.3.0 Sep 03 11:01:08 volumio volumio[18691]: info: Loading plugin "fusiondsp"... Sep 03 11:01:08 volumio volumio[18691]: Forking 3 albumart workers Sep 03 11:01:08 volumio volumio[18691]: info: Applying required configuration parameters for plugin fusiondsp Sep 03 11:01:09 volumio volumio[18691]: info: Loading plugin "Systeminfo"... Sep 03 11:01:09 volumio volumio[18691]: info: Loading i18n strings for locale hu Sep 03 11:01:09 volumio volumio[18691]: Updating browse sources language Sep 03 11:01:09 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 11:01:09 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 03 11:01:09 volumio volumio[18691]: info: CoreCommandRouter::initPlayerControls Sep 03 11:01:09 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:09 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:09 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:09 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:09 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:09 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:09 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:09 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:09 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 03 11:01:09 volumio volumio[18691]: Express server listening on port 3000 Sep 03 11:01:09 volumio volumio[18691]: [Metrics] WebUI: 10s 953.74ms Sep 03 11:01:09 volumio volumio[18691]: info: CoreStateMachine::resetVolumioState Sep 03 11:01:09 volumio volumio[18691]: info: CoreStateMachine::getcurrentVolume Sep 03 11:01:09 volumio volumio[18691]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 11:01:09 volumio volumio[18691]: Starting albumart workers Sep 03 11:01:09 volumio volumio[18691]: info: Volumio Network Manager: Network status updated: 1 Sep 03 11:01:09 volumio volumio[18691]: Starting albumart workers Sep 03 11:01:09 volumio volumio[18691]: Starting albumart workers Sep 03 11:01:10 volumio volumio[18691]: info: CoreCommandRouter::volumioGetState Sep 03 11:01:10 volumio volumio[18691]: info: CorePlayQueue::getTrack 0 Sep 03 11:01:10 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 11:01:10 volumio volumio[18691]: info: VolumeController:: Volume=37 Mute =false Sep 03 11:01:10 volumio volumio[18691]: info: CoreStateMachine::pushState Sep 03 11:01:10 volumio volumio[18691]: info: CorePlayQueue::getTrack 0 Sep 03 11:01:10 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 11:01:10 volumio volumio[18691]: info: CoreCommandRouter::volumioPushState Sep 03 11:01:10 volumio volumio[18691]: info: CoreStateMachine::updateTrackBlock Sep 03 11:01:10 volumio volumio[18691]: info: CorePlayQueue::getTrackBlock Sep 03 11:01:10 volumio volumio[18691]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 11:01:10 volumio volumio[18691]: info: CoreStateMachine::setRepeat false single undefined Sep 03 11:01:10 volumio volumio[18691]: info: CoreStateMachine::pushState Sep 03 11:01:10 volumio volumio[18691]: info: CorePlayQueue::getTrack 0 Sep 03 11:01:10 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 11:01:10 volumio volumio[18691]: info: CoreCommandRouter::volumioPushState Sep 03 11:01:10 volumio volumio[18691]: info: CoreStateMachine::setRandom true Sep 03 11:01:10 volumio volumio[18691]: info: CoreStateMachine::pushState Sep 03 11:01:10 volumio volumio[18691]: info: CorePlayQueue::getTrack 0 Sep 03 11:01:10 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 11:01:10 volumio volumio[18691]: info: CoreCommandRouter::volumioPushState Sep 03 11:01:10 volumio volumio[18691]: info: Setting Device type: Raspberry PI Sep 03 11:01:10 volumio volumio[18691]: info: Listing playlists Sep 03 11:01:10 volumio volumio[18691]: info: Listing playlists Sep 03 11:01:10 volumio volumio[18691]: info: Completed loading Core Plugins Sep 03 11:01:10 volumio volumio[18691]: info: Preparing to generate the ALSA configuration file Sep 03 11:01:10 volumio volumio[18691]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227 Sep 03 11:01:10 volumio volumio[18691]: info: Discovery: Found device Volumio Sep 03 11:01:10 volumio volumio[18691]: info: CoreCommandRouter::volumioGetState Sep 03 11:01:10 volumio volumio[18691]: info: CorePlayQueue::getTrack 0 Sep 03 11:01:10 volumio volumio[18691]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 03 11:01:10 volumio volumio[18691]: info: Reading ALSA contributions from plugins. Sep 03 11:01:10 volumio volumio[18691]: info: VolumeController:: Volume=37 Mute =false Sep 03 11:01:10 volumio volumio[18691]: info: CoreStateMachine::pushState Sep 03 11:01:10 volumio volumio[18691]: info: CorePlayQueue::getTrack 0 Sep 03 11:01:10 volumio volumio[18691]: info: CoreCommandRouter::volumioPushState Sep 03 11:01:10 volumio volumio[18691]: info: Asound.conf file unchanged, so no further update is needed Sep 03 11:01:10 volumio volumio[18691]: info: Output device has changed, restarting MPD Sep 03 11:01:10 volumio volumio[18691]: info: ___________ START PLUGINS ___________ Sep 03 11:01:10 volumio volumio[18691]: info: ControllerMpd::onStart: Initializing MPD Sep 03 11:01:10 volumio volumio[18691]: info: Creating MPD Configuration file Sep 03 11:01:10 volumio sudo[18788]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 11:01:10 volumio sudo[18788]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:01:10 volumio sudo[18788]: pam_unix(sudo:session): session closed for user root Sep 03 11:01:10 volumio sudo[18790]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 11:01:10 volumio sudo[18790]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:01:10 volumio volumio[18691]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 11:01:10 volumio volumio[18691]: info: [1756890070710] CoreMusicLibrary::Adding element Last_100 Sep 03 11:01:10 volumio systemd[1]: Stopping Music Player Daemon... Sep 03 11:01:10 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 11:01:10 volumio volumio[18691]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 11:01:10 volumio volumio[18691]: info: [1756890070731] CoreMusicLibrary::Adding element Webradio Sep 03 11:01:10 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 11:01:10 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 11:01:10 volumio systemd[1]: mpd.service: Succeeded. Sep 03 11:01:10 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 11:01:10 volumio sudo[18802]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 11:01:10 volumio sudo[18811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 11:01:10 volumio sudo[18802]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:01:10 volumio sudo[18811]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:01:10 volumio volumio[18691]: info: Initializing BBC Radios Sep 03 11:01:10 volumio systemd[1]: Starting Music Player Daemon... Sep 03 11:01:10 volumio sudo[18802]: pam_unix(sudo:session): session closed for user root Sep 03 11:01:10 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 03 11:01:10 volumio systemd[1]: mpd.service: Succeeded. Sep 03 11:01:10 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 11:01:10 volumio systemd[1]: Starting Music Player Daemon... Sep 03 11:01:10 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 11:01:10 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 11:01:10 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 11:01:10 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 03 11:01:10 volumio volumio[18691]: info: Discovery: Getting this device information Sep 03 11:01:10 volumio volumio[18691]: info: CoreCommandRouter::volumioGetState Sep 03 11:01:10 volumio volumio[18691]: info: CorePlayQueue::getTrack 0 Sep 03 11:01:10 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 11:01:10 volumio volumio[18691]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 11:01:10 volumio volumio[18691]: info: [1756890070918] CoreMusicLibrary::Adding element Jellyfin Sep 03 11:01:10 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 11:01:10 volumio volumio[18691]: Cannot find translation for source Jellyfin Sep 03 11:01:10 volumio volumio[18691]: 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 11:01:10 volumio volumio[18691]: info: Creating Spotify config file Sep 03 11:01:10 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:11 volumio sudo[18823]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 03 11:01:11 volumio sudo[18823]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:01:11 volumio sudo[18823]: pam_unix(sudo:session): session closed for user root Sep 03 11:01:11 volumio volumio[18691]: info: Loading i18n strings for locale hu Sep 03 11:01:11 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 03 11:01:11 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:11 volumio volumio[18691]: info: FusionDsp - mixtype--------------------- Hardware Sep 03 11:01:11 volumio volumio[18691]: info: Volumio Calling Home Sep 03 11:01:11 volumio volumio[18691]: info: Preparing to generate the ALSA configuration file Sep 03 11:01:11 volumio volumio[18691]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 03 11:01:11 volumio volumio[18691]: info: Reading ALSA contributions from plugins. Sep 03 11:01:11 volumio volumio[18691]: info: MPD Permissions set Sep 03 11:01:11 volumio volumio[18691]: info: MPD Permissions set Sep 03 11:01:11 volumio volumio[18691]: info: CoreCommandRouter::volumioGetState Sep 03 11:01:11 volumio volumio[18691]: info: CorePlayQueue::getTrack 0 Sep 03 11:01:11 volumio volumio[18691]: info: Spotify config file written Sep 03 11:01:11 volumio volumio[18691]: 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 11:01:11 volumio volumio[18691]: info: Volumio called home Sep 03 11:01:11 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:11 volumio sudo[18846]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 03 11:01:11 volumio sudo[18846]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:01:11 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 11:01:11 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:11 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:11 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:11 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:11 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:11 volumio systemd[1]: Stopping go-librespot Daemon... Sep 03 11:01:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 03 11:01:11 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 03 11:01:11 volumio systemd[1]: Stopped go-librespot Daemon. Sep 03 11:01:11 volumio systemd[1]: Started go-librespot Daemon. Sep 03 11:01:11 volumio go-librespot[18857]: go-librespot daemon starting... Sep 03 11:01:11 volumio sudo[18846]: pam_unix(sudo:session): session closed for user root Sep 03 11:01:11 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 11:01:11 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:11 volumio volumio[18691]: info: No need to fix Spotify hosts Sep 03 11:01:11 volumio go-librespot[18857]: time="2025-09-03T11:01:11+02:00" level=info msg="running go-librespot 0.3.2" Sep 03 11:01:11 volumio go-librespot[18857]: time="2025-09-03T11:01:11+02:00" level=debug msg="app state loaded" Sep 03 11:01:11 volumio go-librespot[18857]: time="2025-09-03T11:01:11+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 03 11:01:11 volumio go-librespot[18857]: time="2025-09-03T11:01:11+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 03 11:01:11 volumio go-librespot[18857]: time="2025-09-03T11:01:11+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 03 11:01:11 volumio go-librespot[18857]: time="2025-09-03T11:01:11+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 03 11:01:11 volumio go-librespot[18857]: time="2025-09-03T11:01:11+02:00" level=info msg="zeroconf server listening on port 40809" Sep 03 11:01:11 volumio sudo[18875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 03 11:01:11 volumio sudo[18875]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:01:11 volumio go-librespot[18857]: time="2025-09-03T11:01:11+02:00" level=debug msg="obtained new client token: AAC0L3+Ag4tArhpPeZ4QYNFIH/GlOj3Vayo15C7OORYhDND+KFIt64++DI79cUi6SRIoZUu9xbp7Xq39aiV2KyjYhc3R2x/aOIPJ3yO8c8OPfmIOGzeCGoXJm5us/rfSrH9TMGYbxv6hiTon+se2b5LYdPcQ8CzMtV9fiXP5fq5n4XjDXL2tTLSsP+quW6hVNK/6vB9CcDYkVXpHboLudT3gTo7VtsrTrWmj4ZkTAD5+948rc71BjeMYsFU=" Sep 03 11:01:11 volumio sudo[18875]: pam_unix(sudo:session): session closed for user root Sep 03 11:01:11 volumio sudo[18877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 11:01:11 volumio sudo[18877]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:01:12 volumio sudo[18877]: pam_unix(sudo:session): session closed for user root Sep 03 11:01:12 volumio go-librespot[18857]: time="2025-09-03T11:01:12+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 03 11:01:12 volumio go-librespot[18857]: time="2025-09-03T11:01:12+02:00" level=debug msg="completed keyexchange" Sep 03 11:01:12 volumio go-librespot[18857]: time="2025-09-03T11:01:12+02:00" level=debug msg="completed challenge" Sep 03 11:01:12 volumio volumio[18691]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 03 11:01:12 volumio volumio[18691]: SPOTIFY: BQDmqauYvzRhwXKkUEdbyOwQLbRAC_nSv5dnisYjhilj3iQAqD_1izGGDKxl5FiutnB0ULW2NtXwwTQeiGTTq6WA0dNjoQrOZSps0WWaeisKQG-bdND9_nW1VUB1O-VMvdPFGX2wb9wCubxv3fs3_6F71iVfXMm-fj-gB99bTSjeTDgzPJxxpjKuLsJUbSZH4lUqQIl7OucLSwEbR6FpR9IiYB-rx3rDyuK0oBnRaLW9-wC-pXkRIMyoLbRbJU4N-jWRpnDYcRhPOIP9GPJO50K3KFdX2_mVLQ-SUAjutN3svbE Sep 03 11:01:12 volumio volumio[18691]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 03 11:01:12 volumio volumio[18691]: info: New Spotify access token = BQDmqauYvzRhwXKkUEdbyOwQLbRAC_nSv5dnisYjhilj3iQAqD_1izGGDKxl5FiutnB0ULW2NtXwwTQeiGTTq6WA0dNjoQrOZSps0WWaeisKQG-bdND9_nW1VUB1O-VMvdPFGX2wb9wCubxv3fs3_6F71iVfXMm-fj-gB99bTSjeTDgzPJxxpjKuLsJUbSZH4lUqQIl7OucLSwEbR6FpR9IiYB-rx3rDyuK0oBnRaLW9-wC-pXkRIMyoLbRbJU4N-jWRpnDYcRhPOIP9GPJO50K3KFdX2_mVLQ-SUAjutN3svbE Sep 03 11:01:12 volumio volumio[18691]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 03 11:01:12 volumio go-librespot[18857]: time="2025-09-03T11:01:12+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 03 11:01:12 volumio volumio[18691]: info: CoreCommandRouter::volumioGetState Sep 03 11:01:12 volumio volumio[18691]: info: CorePlayQueue::getTrack 0 Sep 03 11:01:12 volumio volumio[18691]: info: Asound.conf file unchanged, so no further update is needed Sep 03 11:01:12 volumio volumio[18691]: info: Output device has changed, restarting MPD Sep 03 11:01:12 volumio go-librespot[18857]: time="2025-09-03T11:01:12+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 03 11:01:12 volumio go-librespot[18857]: time="2025-09-03T11:01:12+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 03 11:01:12 volumio sudo[18882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 11:01:12 volumio sudo[18882]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:01:12 volumio sudo[18882]: pam_unix(sudo:session): session closed for user root Sep 03 11:01:12 volumio go-librespot[18857]: time="2025-09-03T11:01:12+02:00" level=debug msg="dealer connection opened" Sep 03 11:01:12 volumio go-librespot[18857]: time="2025-09-03T11:01:12+02:00" level=trace msg="starting accesspoint recv loop" Sep 03 11:01:12 volumio go-librespot[18857]: time="2025-09-03T11:01:12+02:00" level=trace msg="starting dealer recv loop" Sep 03 11:01:12 volumio go-librespot[18857]: time="2025-09-03T11:01:12+02:00" level=trace msg="received accesspoint ping" Sep 03 11:01:12 volumio go-librespot[18857]: time="2025-09-03T11:01:12+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 03 11:01:12 volumio go-librespot[18857]: time="2025-09-03T11:01:12+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 03 11:01:12 volumio sudo[18885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 11:01:12 volumio go-librespot[18857]: time="2025-09-03T11:01:12+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 03 11:01:12 volumio go-librespot[18857]: time="2025-09-03T11:01:12+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 03 11:01:12 volumio go-librespot[18857]: time="2025-09-03T11:01:12+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 03 11:01:12 volumio sudo[18885]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:01:12 volumio go-librespot[18857]: time="2025-09-03T11:01:12+02:00" level=debug msg="received connection id: YzlmMDU4MDMtYzBj...RjY4OTJDNTNENQ==" Sep 03 11:01:12 volumio go-librespot[18857]: time="2025-09-03T11:01:12+02:00" level=trace msg="received accesspoint pong ack" Sep 03 11:01:12 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 03 11:01:12 volumio systemd[1]: mpd.service: Succeeded. Sep 03 11:01:12 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 11:01:12 volumio volumio[18691]: info: MPD Permissions set Sep 03 11:01:12 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:12 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:12 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:12 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:12 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:12 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:12 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:12 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:12 volumio sudo[18890]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 03 11:01:12 volumio sudo[18890]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:01:12 volumio systemd[1]: Starting Music Player Daemon... Sep 03 11:01:12 volumio go-librespot[18857]: time="2025-09-03T11:01:12+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 03 11:01:12 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 11:01:12 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:12 volumio sudo[18890]: pam_unix(sudo:session): session closed for user root Sep 03 11:01:12 volumio volumio[18691]: info: Upmpdcli Daemon Started Sep 03 11:01:12 volumio volumio[18691]: 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 11:01:12 volumio volumio[18691]: info: Spotify Successfully logged in Sep 03 11:01:12 volumio volumio[18691]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 11:01:12 volumio volumio[18691]: info: [1756890072487] CoreMusicLibrary::Adding element Spotify Sep 03 11:01:12 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 11:01:12 volumio volumio[18691]: Cannot find translation for source Jellyfin Sep 03 11:01:12 volumio volumio[18691]: Cannot find translation for source Spotify Sep 03 11:01:12 volumio sudo[18888]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 03 11:01:12 volumio sudo[18888]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:01:12 volumio sudo[18888]: pam_unix(sudo:session): session closed for user root Sep 03 11:01:12 volumio volumio[18691]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 03 11:01:13 volumio volumio[18691]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 11:01:13 volumio volumio[18691]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 03 11:01:13 volumio volumio[18691]: 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 11:01:13 volumio volumio[18691]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 03 11:01:13 volumio volumio[18691]: info: camilladsp service started and running in background, instance 1 Sep 03 11:01:13 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:13 volumio volumio[18691]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 03 11:01:13 volumio volumio[18691]: 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 11:01:13 volumio volumio[18691]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 03 11:01:13 volumio volumio[18691]: info: FusionDsp loaded Sep 03 11:01:13 volumio volumio[18691]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 11:01:13 volumio sudo[18911]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 03 11:01:13 volumio sudo[18911]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:01:13 volumio sudo[18911]: pam_unix(sudo:session): session closed for user root Sep 03 11:01:13 volumio volumio[18691]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 03 11:01:13 volumio volumio[18691]: info: Adding Signal Path Element [object Object] Sep 03 11:01:13 volumio volumio[18691]: info: Adding fusiondspeq DSP Signal Path Element Sep 03 11:01:13 volumio volumio[18691]: info: FusionDsp - ---- installed callbackRead Sep 03 11:01:13 volumio volumio[18691]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 11:01:13 volumio volumio[18691]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 03 11:01:13 volumio volumio[18691]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 03 11:01:13 volumio volumio[18691]: at onErrorNT (internal/child_process.js:465:16) Sep 03 11:01:13 volumio volumio[18691]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Sep 03 11:01:13 volumio volumio[18691]: at runNextTicks (internal/process/task_queues.js:62:3) Sep 03 11:01:13 volumio volumio[18691]: at listOnTimeout (internal/timers.js:523:9) Sep 03 11:01:13 volumio volumio[18691]: at processTimers (internal/timers.js:497:7) { Sep 03 11:01:13 volumio volumio[18691]: errno: -2, Sep 03 11:01:13 volumio volumio[18691]: code: 'ENOENT', Sep 03 11:01:13 volumio volumio[18691]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 03 11:01:13 volumio volumio[18691]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 03 11:01:13 volumio volumio[18691]: spawnargs: [ Sep 03 11:01:13 volumio volumio[18691]: '-p', Sep 03 11:01:13 volumio volumio[18691]: 9876, Sep 03 11:01:13 volumio volumio[18691]: '-o', Sep 03 11:01:13 volumio volumio[18691]: '/tmp/camilladsp.log', Sep 03 11:01:13 volumio volumio[18691]: '-l', Sep 03 11:01:13 volumio volumio[18691]: 'warn', Sep 03 11:01:13 volumio volumio[18691]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 03 11:01:13 volumio volumio[18691]: ] Sep 03 11:01:13 volumio volumio[18691]: } Sep 03 11:01:13 volumio volumio[18691]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 11:01:14 volumio sudo[18922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-03 11:00 Sep 03 11:01:14 volumio sudo[18922]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:01:14 volumio sudo[18922]: pam_unix(sudo:session): session closed for user root Sep 03 11:01:14 volumio mpd[18897]: Sep 03 11:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 03 11:01:14 volumio systemd[1]: Started Music Player Daemon. Sep 03 11:01:14 volumio sudo[18811]: pam_unix(sudo:session): session closed for user root Sep 03 11:01:14 volumio sudo[18790]: pam_unix(sudo:session): session closed for user root Sep 03 11:01:14 volumio sudo[18885]: pam_unix(sudo:session): session closed for user root Sep 03 11:01:14 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 03 11:01:14 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 03 11:01:14 volumio systemd[1]: Started dynamicswap service. Sep 03 11:01:14 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 03 11:01:14 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 03 11:01:14 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 7839. Sep 03 11:01:14 volumio systemd[1]: Stopped Volumio Backend Module. Sep 03 11:01:14 volumio systemd[1]: Started Volumio Backend Module. Sep 03 11:01:14 volumio systemd[1]: Started dynamicswap service. Sep 03 11:01:14 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 03 11:01:14 volumio volumio-remote-updater[504]: [2025-09-03 11:01:14] [info] asio async_connect error: system:111 (Connection refused) Sep 03 11:01:14 volumio volumio-remote-updater[504]: [2025-09-03 11:01:14] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 03 11:01:14 volumio volumio-remote-updater[504]: [2025-09-03 11:01:14] [error] handle_connect error: Underlying Transport Error Sep 03 11:01:16 volumio volumio[18934]: info: ------------------------------------------- Sep 03 11:01:16 volumio volumio[18934]: info: ----- Volumio3 ---- Sep 03 11:01:16 volumio volumio[18934]: info: ------------------------------------------- Sep 03 11:01:16 volumio volumio[18934]: info: ----- System startup ---- Sep 03 11:01:16 volumio volumio[18934]: info: ------------------------------------------- Sep 03 11:01:16 volumio volumio[18934]: info: MYVOLUMIO Environment detected Sep 03 11:01:17 volumio volumio[18934]: info: Plugin folders cleanup Sep 03 11:01:17 volumio volumio[18934]: info: Scanning into folder /volumio/app/plugins/ Sep 03 11:01:17 volumio volumio[18934]: info: Scanning category audio_interface Sep 03 11:01:17 volumio volumio[18934]: info: Scanning category miscellanea Sep 03 11:01:17 volumio volumio[18934]: info: Scanning category music_service Sep 03 11:01:17 volumio volumio[18934]: info: Scanning category plugins.json Sep 03 11:01:17 volumio volumio[18934]: info: Scanning category system_controller Sep 03 11:01:17 volumio volumio[18934]: info: Scanning category user_interface Sep 03 11:01:17 volumio volumio[18934]: info: Scanning into folder /data/plugins/ Sep 03 11:01:17 volumio volumio[18934]: info: Scanning category audio_interface Sep 03 11:01:17 volumio volumio[18934]: info: Scanning category music_service Sep 03 11:01:17 volumio volumio[18934]: info: Scanning category user_interface Sep 03 11:01:17 volumio volumio[18934]: info: Plugin folders cleanup completed Sep 03 11:01:17 volumio volumio[18934]: info: ------------------------------------------- Sep 03 11:01:17 volumio volumio[18934]: info: ----- Core plugins startup ---- Sep 03 11:01:17 volumio volumio[18934]: info: ------------------------------------------- Sep 03 11:01:17 volumio volumio[18934]: info: Loading plugins from folder /volumio/app/plugins/ Sep 03 11:01:17 volumio volumio[18934]: info: Adding plugin upnp to MyMusic Plugins Sep 03 11:01:17 volumio volumio[18934]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 03 11:01:17 volumio volumio[18934]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 03 11:01:17 volumio volumio[18934]: info: Loading plugins from folder /data/plugins/ Sep 03 11:01:17 volumio volumio[18934]: info: Loading plugin "system"... Sep 03 11:01:17 volumio volumio[18934]: info: Loading plugin "appearance"... Sep 03 11:01:18 volumio volumio[18934]: info: Loading plugin "network"... Sep 03 11:01:18 volumio volumio[18934]: info: Refreshing Cached IP Addresses Sep 03 11:01:18 volumio sudo[18961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 03 11:01:18 volumio sudo[18961]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:01:18 volumio sudo[18961]: pam_unix(sudo:session): session closed for user root Sep 03 11:01:18 volumio volumio[18934]: info: Loading plugin "services"... Sep 03 11:01:18 volumio sudo[18963]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 11:01:18 volumio sudo[18963]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:01:18 volumio volumio[18934]: info: Loading plugin "alsa_controller"... Sep 03 11:01:18 volumio sudo[18963]: pam_unix(sudo:session): session closed for user root Sep 03 11:01:18 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 11:01:18 volumio volumio[18934]: info: Loading plugin "wizard"... Sep 03 11:01:18 volumio volumio[18934]: info: Loading plugin "networkfs"... Sep 03 11:01:18 volumio volumio[18934]: info: Starting Udev Watcher for removable devices Sep 03 11:01:18 volumio volumio[18934]: info: Ignoring mount for partition: boot Sep 03 11:01:18 volumio volumio[18934]: info: Ignoring mount for partition: volumio Sep 03 11:01:18 volumio volumio[18934]: info: Ignoring mount for partition: volumio_data Sep 03 11:01:18 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 11:01:18 volumio volumio[18934]: info: Loading plugin "volumio_command_line_client"... Sep 03 11:01:18 volumio volumio[18934]: info: Loading plugin "upnp"... Sep 03 11:01:18 volumio volumio[18934]: info: [1756890078327] Starting Upmpd Daemon Sep 03 11:01:18 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 11:01:18 volumio volumio[18934]: info: Loading plugin "my_music"... Sep 03 11:01:18 volumio volumio[18934]: info: Loading plugin "mpd"... Sep 03 11:01:18 volumio volumio[18934]: info: Plugin upnp_browser is not enabled Sep 03 11:01:18 volumio volumio[18934]: info: Loading plugin "alarm-clock"... Sep 03 11:01:18 volumio volumio[18934]: info: Plugin airplay_emulation is not enabled Sep 03 11:01:18 volumio volumio[18934]: info: Loading plugin "last_100"... Sep 03 11:01:18 volumio volumio[18934]: info: Loading plugin "webradio"... Sep 03 11:01:18 volumio volumio[18934]: info: Loading plugin "i2s_dacs"... Sep 03 11:01:18 volumio volumio[18934]: info: Loading plugin "volumiodiscovery"... Sep 03 11:01:19 volumio volumio[18934]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 03 11:01:19 volumio volumio[18934]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 11:01:19 volumio volumio[18934]: *** WARNING *** For more information see Sep 03 11:01:19 volumio node[18934]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 03 11:01:19 volumio volumio[18934]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 03 11:01:19 volumio volumio[18934]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 11:01:19 volumio volumio[18934]: *** WARNING *** For more information see Sep 03 11:01:19 volumio node[18934]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 11:01:19 volumio node[18934]: *** WARNING *** For more information see Sep 03 11:01:19 volumio node[18934]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 03 11:01:19 volumio node[18934]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 11:01:19 volumio node[18934]: *** WARNING *** For more information see Sep 03 11:01:19 volumio volumio[18934]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 03 11:01:19 volumio volumio[18934]: info: Discovery: Started advertising with name: Volumio Sep 03 11:01:19 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 11:01:19 volumio volumio[18934]: info: Loading plugin "jellyfin"... Sep 03 11:01:19 volumio volumio[18934]: info: Loading plugin "spop"... Sep 03 11:01:20 volumio volumio[18934]: info: Loading plugin "ytcr"... Sep 03 11:01:24 volumio volumio-remote-updater[504]: [2025-09-03 11:01:24] [connect] Successful connection Sep 03 11:01:25 volumio volumio[18934]: info: Loading plugin "outputs"... Sep 03 11:01:25 volumio volumio[18934]: info: Loading plugin "albumart"... Sep 03 11:01:25 volumio volumio[18934]: info: Plugin example_plugin is not enabled Sep 03 11:01:25 volumio volumio[18934]: info: Loading plugin "inputs"... Sep 03 11:01:25 volumio volumio[18934]: info: Loading plugin "updater_comm"... Sep 03 11:01:25 volumio volumio[18934]: info: Plugin mpdemulation is not enabled Sep 03 11:01:25 volumio volumio[18934]: info: Loading plugin "rest_api"... Sep 03 11:01:25 volumio volumio[18934]: info: Loading plugin "websocket"... Sep 03 11:01:25 volumio volumio[18934]: info: Starting Socket.io Server version 2.3.0 Sep 03 11:01:25 volumio volumio[18934]: info: Loading plugin "fusiondsp"... Sep 03 11:01:25 volumio volumio[18934]: Forking 3 albumart workers Sep 03 11:01:25 volumio volumio[18934]: info: Applying required configuration parameters for plugin fusiondsp Sep 03 11:01:25 volumio volumio[18934]: info: Loading plugin "Systeminfo"... Sep 03 11:01:26 volumio volumio[18934]: info: Loading i18n strings for locale hu Sep 03 11:01:26 volumio volumio[18934]: Updating browse sources language Sep 03 11:01:26 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 11:01:26 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 03 11:01:26 volumio volumio[18934]: info: CoreCommandRouter::initPlayerControls Sep 03 11:01:26 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:26 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:26 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:26 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:26 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:26 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:26 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:26 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 03 11:01:26 volumio volumio[18934]: Express server listening on port 3000 Sep 03 11:01:26 volumio volumio[18934]: [Metrics] WebUI: 11s 42.19ms Sep 03 11:01:26 volumio volumio[18934]: info: CoreStateMachine::resetVolumioState Sep 03 11:01:26 volumio volumio[18934]: info: CoreStateMachine::getcurrentVolume Sep 03 11:01:26 volumio volumio[18934]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 11:01:26 volumio volumio[18934]: Starting albumart workers Sep 03 11:01:26 volumio volumio[18934]: Starting albumart workers Sep 03 11:01:26 volumio volumio[18934]: info: Volumio Network Manager: Network status updated: 1 Sep 03 11:01:26 volumio volumio[18934]: Starting albumart workers Sep 03 11:01:26 volumio volumio-remote-updater[504]: [2025-09-03 11:01:26] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1756890084 101 Sep 03 11:01:26 volumio volumio[18934]: 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 11:01:26 volumio volumio[18934]: info: CoreCommandRouter::volumioGetState Sep 03 11:01:26 volumio volumio[18934]: info: CorePlayQueue::getTrack 0 Sep 03 11:01:27 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 11:01:27 volumio volumio[18934]: info: VolumeController:: Volume=37 Mute =false Sep 03 11:01:27 volumio volumio[18934]: info: CoreStateMachine::pushState Sep 03 11:01:27 volumio volumio[18934]: info: CorePlayQueue::getTrack 0 Sep 03 11:01:27 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 11:01:27 volumio volumio[18934]: info: CoreCommandRouter::volumioPushState Sep 03 11:01:27 volumio volumio[18934]: info: CoreStateMachine::updateTrackBlock Sep 03 11:01:27 volumio volumio[18934]: info: CorePlayQueue::getTrackBlock Sep 03 11:01:27 volumio volumio[18934]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 11:01:27 volumio volumio[18934]: info: CoreStateMachine::setRepeat false single undefined Sep 03 11:01:27 volumio volumio[18934]: info: CoreStateMachine::pushState Sep 03 11:01:27 volumio volumio[18934]: info: CorePlayQueue::getTrack 0 Sep 03 11:01:27 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 11:01:27 volumio volumio[18934]: info: CoreCommandRouter::volumioPushState Sep 03 11:01:27 volumio volumio[18934]: info: CoreStateMachine::setRandom true Sep 03 11:01:27 volumio volumio[18934]: info: CoreStateMachine::pushState Sep 03 11:01:27 volumio volumio[18934]: info: CorePlayQueue::getTrack 0 Sep 03 11:01:27 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 11:01:27 volumio volumio[18934]: info: CoreCommandRouter::volumioPushState Sep 03 11:01:27 volumio volumio[18934]: info: Setting Device type: Raspberry PI Sep 03 11:01:27 volumio volumio[18934]: info: Listing playlists Sep 03 11:01:27 volumio volumio[18934]: info: Listing playlists Sep 03 11:01:27 volumio volumio[18934]: info: Completed loading Core Plugins Sep 03 11:01:27 volumio volumio[18934]: info: Preparing to generate the ALSA configuration file Sep 03 11:01:27 volumio volumio[18934]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227 Sep 03 11:01:27 volumio volumio[18934]: info: Discovery: Found device Volumio Sep 03 11:01:27 volumio volumio[18934]: info: CoreCommandRouter::volumioGetState Sep 03 11:01:27 volumio volumio[18934]: info: CorePlayQueue::getTrack 0 Sep 03 11:01:27 volumio volumio[18934]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 03 11:01:27 volumio volumio[18934]: info: Reading ALSA contributions from plugins. Sep 03 11:01:27 volumio volumio[18934]: info: VolumeController:: Volume=37 Mute =false Sep 03 11:01:27 volumio volumio[18934]: info: CoreStateMachine::pushState Sep 03 11:01:27 volumio volumio[18934]: info: CorePlayQueue::getTrack 0 Sep 03 11:01:27 volumio volumio[18934]: info: CoreCommandRouter::volumioPushState Sep 03 11:01:27 volumio volumio[18934]: info: Asound.conf file unchanged, so no further update is needed Sep 03 11:01:27 volumio volumio[18934]: info: Output device has changed, restarting MPD Sep 03 11:01:27 volumio volumio[18934]: info: ___________ START PLUGINS ___________ Sep 03 11:01:27 volumio sudo[19029]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 11:01:27 volumio sudo[19029]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:01:27 volumio volumio[18934]: info: ControllerMpd::onStart: Initializing MPD Sep 03 11:01:27 volumio volumio[18934]: info: Creating MPD Configuration file Sep 03 11:01:27 volumio sudo[19029]: pam_unix(sudo:session): session closed for user root Sep 03 11:01:27 volumio sudo[19031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 11:01:27 volumio sudo[19031]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:01:27 volumio sudo[19039]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 11:01:27 volumio sudo[19039]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:01:27 volumio systemd[1]: Stopping Music Player Daemon... Sep 03 11:01:27 volumio sudo[19039]: pam_unix(sudo:session): session closed for user root Sep 03 11:01:27 volumio systemd[1]: mpd.service: Succeeded. Sep 03 11:01:27 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 11:01:27 volumio volumio[18934]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 11:01:27 volumio volumio[18934]: info: [1756890087603] CoreMusicLibrary::Adding element Last_100 Sep 03 11:01:27 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 11:01:27 volumio volumio[18934]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 11:01:27 volumio volumio[18934]: info: [1756890087611] CoreMusicLibrary::Adding element Webradio Sep 03 11:01:27 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 11:01:27 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 11:01:27 volumio volumio[18934]: info: Initializing BBC Radios Sep 03 11:01:27 volumio sudo[19046]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 11:01:27 volumio sudo[19046]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:01:27 volumio systemd[1]: Starting Music Player Daemon... Sep 03 11:01:27 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 03 11:01:27 volumio systemd[1]: mpd.service: Succeeded. Sep 03 11:01:27 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 11:01:27 volumio systemd[1]: Starting Music Player Daemon... Sep 03 11:01:27 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 11:01:27 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 11:01:27 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 11:01:27 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 03 11:01:27 volumio volumio[18934]: info: Discovery: Getting this device information Sep 03 11:01:27 volumio volumio[18934]: info: CoreCommandRouter::volumioGetState Sep 03 11:01:27 volumio volumio[18934]: info: CorePlayQueue::getTrack 0 Sep 03 11:01:27 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 11:01:27 volumio volumio[18934]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 11:01:27 volumio volumio[18934]: info: [1756890087818] CoreMusicLibrary::Adding element Jellyfin Sep 03 11:01:27 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 11:01:27 volumio volumio[18934]: Cannot find translation for source Jellyfin Sep 03 11:01:27 volumio volumio[18934]: 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 11:01:27 volumio sudo[19055]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 03 11:01:27 volumio sudo[19055]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:01:27 volumio sudo[19055]: pam_unix(sudo:session): session closed for user root Sep 03 11:01:27 volumio volumio[18934]: info: Creating Spotify config file Sep 03 11:01:27 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:28 volumio volumio[18934]: info: Loading i18n strings for locale hu Sep 03 11:01:28 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 03 11:01:28 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:28 volumio volumio[18934]: info: FusionDsp - mixtype--------------------- Hardware Sep 03 11:01:28 volumio volumio[18934]: info: Volumio Calling Home Sep 03 11:01:28 volumio volumio[18934]: info: Preparing to generate the ALSA configuration file Sep 03 11:01:28 volumio volumio[18934]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 03 11:01:28 volumio volumio[18934]: info: Reading ALSA contributions from plugins. Sep 03 11:01:28 volumio volumio[18934]: info: MPD Permissions set Sep 03 11:01:28 volumio volumio[18934]: info: MPD Permissions set Sep 03 11:01:28 volumio sudo[19096]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 03 11:01:28 volumio sudo[19096]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:01:28 volumio sudo[19098]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 11:01:28 volumio sudo[19098]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:01:28 volumio sudo[19096]: pam_unix(sudo:session): session closed for user root Sep 03 11:01:28 volumio sudo[19098]: pam_unix(sudo:session): session closed for user root Sep 03 11:01:28 volumio volumio[18934]: info: Volumio called home Sep 03 11:01:28 volumio volumio[18934]: info: Spotify config file written Sep 03 11:01:28 volumio sudo[19102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 03 11:01:28 volumio sudo[19102]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:01:28 volumio systemd[1]: Stopping go-librespot Daemon... Sep 03 11:01:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 03 11:01:28 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 03 11:01:28 volumio systemd[1]: Stopped go-librespot Daemon. Sep 03 11:01:28 volumio volumio[18934]: 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 11:01:28 volumio systemd[1]: Started go-librespot Daemon. Sep 03 11:01:28 volumio go-librespot[19104]: go-librespot daemon starting... Sep 03 11:01:28 volumio sudo[19102]: pam_unix(sudo:session): session closed for user root Sep 03 11:01:28 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:28 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:28 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:28 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:28 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:28 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:28 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:28 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:28 volumio go-librespot[19104]: time="2025-09-03T11:01:28+02:00" level=info msg="running go-librespot 0.3.2" Sep 03 11:01:28 volumio go-librespot[19104]: time="2025-09-03T11:01:28+02:00" level=debug msg="app state loaded" Sep 03 11:01:28 volumio go-librespot[19104]: time="2025-09-03T11:01:28+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 03 11:01:28 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 11:01:28 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:28 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:28 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:28 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:28 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:28 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:28 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:28 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:28 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:28 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 11:01:28 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:28 volumio volumio[18934]: info: No need to fix Spotify hosts Sep 03 11:01:28 volumio go-librespot[19104]: time="2025-09-03T11:01:28+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Sep 03 11:01:28 volumio go-librespot[19104]: time="2025-09-03T11:01:28+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 03 11:01:28 volumio go-librespot[19104]: time="2025-09-03T11:01:28+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 03 11:01:28 volumio go-librespot[19104]: time="2025-09-03T11:01:28+02:00" level=info msg="zeroconf server listening on port 32977" Sep 03 11:01:29 volumio go-librespot[19104]: time="2025-09-03T11:01:29+02:00" level=debug msg="obtained new client token: AAAcs969dMP87Jde2EAgBncrwYfBJ1uMVY1qMu1FqYhXUtNF7v6QUo4J9IjoCEeleGvGAFoT34Rgv7PR19JKoRSj4KZcsdgihjQMcVKQ5azCgPcZ4Im4kPpD6ypAzKkCDpj/Xv3OxEDIVgGWBNDWAKsn9J3XD7+sZJDakVOfR1Vbhn8hChxeWwtzMDMx4z9op6dyCtsjkJuoPFZc4ey42szJp3X5yRdeD9aWiQO3j0/iNSSqf0VqFZb2io0=" Sep 03 11:01:29 volumio go-librespot[19104]: time="2025-09-03T11:01:29+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 03 11:01:29 volumio volumio[18934]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 03 11:01:29 volumio volumio[18934]: SPOTIFY: BQCEh3d5Ldb8bIkww3Y_Q3AGVdY1jZdWaGsG5rZVXHxsS4tZXF75Homg5H9v1vW-Ita3UAEM2VVU69BxAY28nRlOo4LADQJeYyeVacaYu5oE1QFX2GNrfo4F9SFtU6pzrUKoJF-8W2umFltJzcfFD4H0-sDd9fVBQbuvQeAg660s5EiJB-S1BsfBaS3QAy6ij2wZSeL4_dmzzhaccmoS9QSqizvS2iDtxF48_NFw884lnEr4MJ13DGO4KZx6ABhp3vD33B44L1gwYs2TOtiliPcnFLTiShleE_JY4kK8F7Y_4pI Sep 03 11:01:29 volumio volumio[18934]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 03 11:01:29 volumio volumio[18934]: info: New Spotify access token = BQCEh3d5Ldb8bIkww3Y_Q3AGVdY1jZdWaGsG5rZVXHxsS4tZXF75Homg5H9v1vW-Ita3UAEM2VVU69BxAY28nRlOo4LADQJeYyeVacaYu5oE1QFX2GNrfo4F9SFtU6pzrUKoJF-8W2umFltJzcfFD4H0-sDd9fVBQbuvQeAg660s5EiJB-S1BsfBaS3QAy6ij2wZSeL4_dmzzhaccmoS9QSqizvS2iDtxF48_NFw884lnEr4MJ13DGO4KZx6ABhp3vD33B44L1gwYs2TOtiliPcnFLTiShleE_JY4kK8F7Y_4pI Sep 03 11:01:29 volumio volumio[18934]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 03 11:01:29 volumio volumio[18934]: info: CoreCommandRouter::volumioGetState Sep 03 11:01:29 volumio volumio[18934]: info: CorePlayQueue::getTrack 0 Sep 03 11:01:29 volumio go-librespot[19104]: time="2025-09-03T11:01:29+02:00" level=debug msg="completed keyexchange" Sep 03 11:01:29 volumio go-librespot[19104]: time="2025-09-03T11:01:29+02:00" level=debug msg="completed challenge" Sep 03 11:01:29 volumio go-librespot[19104]: time="2025-09-03T11:01:29+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 03 11:01:29 volumio volumio[18934]: info: Asound.conf file unchanged, so no further update is needed Sep 03 11:01:29 volumio volumio[18934]: info: Output device has changed, restarting MPD Sep 03 11:01:29 volumio sudo[19122]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 03 11:01:29 volumio sudo[19122]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:01:29 volumio sudo[19124]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 11:01:29 volumio sudo[19124]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:01:29 volumio sudo[19124]: pam_unix(sudo:session): session closed for user root Sep 03 11:01:29 volumio sudo[19127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 11:01:29 volumio sudo[19127]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:01:29 volumio sudo[19122]: pam_unix(sudo:session): session closed for user root Sep 03 11:01:29 volumio go-librespot[19104]: time="2025-09-03T11:01:29+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 03 11:01:29 volumio go-librespot[19104]: time="2025-09-03T11:01:29+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 03 11:01:29 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 03 11:01:29 volumio systemd[1]: mpd.service: Succeeded. Sep 03 11:01:29 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 11:01:29 volumio volumio[18934]: info: MPD Permissions set Sep 03 11:01:29 volumio volumio[18934]: info: Upmpdcli Daemon Started Sep 03 11:01:29 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:29 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:29 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:29 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:29 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:29 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:29 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:29 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:29 volumio systemd[1]: Starting Music Player Daemon... Sep 03 11:01:29 volumio go-librespot[19104]: time="2025-09-03T11:01:29+02:00" level=debug msg="dealer connection opened" Sep 03 11:01:29 volumio go-librespot[19104]: time="2025-09-03T11:01:29+02:00" level=trace msg="starting accesspoint recv loop" Sep 03 11:01:29 volumio go-librespot[19104]: time="2025-09-03T11:01:29+02:00" level=trace msg="starting dealer recv loop" Sep 03 11:01:29 volumio go-librespot[19104]: time="2025-09-03T11:01:29+02:00" level=trace msg="received accesspoint ping" Sep 03 11:01:29 volumio go-librespot[19104]: time="2025-09-03T11:01:29+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 03 11:01:29 volumio go-librespot[19104]: time="2025-09-03T11:01:29+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 03 11:01:29 volumio go-librespot[19104]: time="2025-09-03T11:01:29+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 03 11:01:29 volumio go-librespot[19104]: time="2025-09-03T11:01:29+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 03 11:01:29 volumio go-librespot[19104]: time="2025-09-03T11:01:29+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 03 11:01:29 volumio go-librespot[19104]: time="2025-09-03T11:01:29+02:00" level=debug msg="received connection id: NjM5MTU5YjctNThj...N0U5QkE4RDg1Ng==" Sep 03 11:01:29 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 11:01:29 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:29 volumio go-librespot[19104]: time="2025-09-03T11:01:29+02:00" level=trace msg="received accesspoint pong ack" Sep 03 11:01:29 volumio volumio[18934]: 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 11:01:29 volumio volumio[18934]: info: Spotify Successfully logged in Sep 03 11:01:29 volumio volumio[18934]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 11:01:29 volumio volumio[18934]: info: [1756890089454] CoreMusicLibrary::Adding element Spotify Sep 03 11:01:29 volumio volumio[18934]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 11:01:29 volumio volumio[18934]: Cannot find translation for source Jellyfin Sep 03 11:01:29 volumio volumio[18934]: Cannot find translation for source Spotify Sep 03 11:01:29 volumio go-librespot[19104]: time="2025-09-03T11:01:29+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 03 11:01:29 volumio sudo[19130]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 03 11:01:29 volumio sudo[19130]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:01:29 volumio sudo[19130]: pam_unix(sudo:session): session closed for user root Sep 03 11:01:29 volumio volumio[18934]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 03 11:01:29 volumio volumio[18934]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 11:01:29 volumio volumio[18934]: Error: bind EADDRINUSE 192.168.0.171:50386 Sep 03 11:01:29 volumio volumio[18934]: at dgram.js:337:20 Sep 03 11:01:29 volumio volumio[18934]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Sep 03 11:01:29 volumio volumio[18934]: errno: -98, Sep 03 11:01:29 volumio volumio[18934]: code: 'EADDRINUSE', Sep 03 11:01:29 volumio volumio[18934]: syscall: 'bind', Sep 03 11:01:29 volumio volumio[18934]: address: '192.168.0.171', Sep 03 11:01:29 volumio volumio[18934]: port: 50386 Sep 03 11:01:29 volumio volumio[18934]: } Sep 03 11:01:29 volumio volumio[18934]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 11:01:30 volumio sudo[19149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-03 11:00 Sep 03 11:01:30 volumio sudo[19149]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:01:30 volumio sudo[19149]: pam_unix(sudo:session): session closed for user root Sep 03 11:01:31 volumio mpd[19137]: Sep 03 11:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 03 11:01:31 volumio systemd[1]: Started Music Player Daemon. Sep 03 11:01:31 volumio sudo[19127]: pam_unix(sudo:session): session closed for user root Sep 03 11:01:31 volumio sudo[19046]: pam_unix(sudo:session): session closed for user root Sep 03 11:01:31 volumio sudo[19031]: pam_unix(sudo:session): session closed for user root Sep 03 11:01:31 volumio volumio-remote-updater[504]: [2025-09-03 11:01:31] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 03 11:01:31 volumio volumio-remote-updater[504]: [2025-09-03 11:01:31] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 03 11:01:31 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 03 11:01:31 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 03 11:01:31 volumio systemd[1]: Started dynamicswap service. Sep 03 11:01:31 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 03 11:01:31 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 03 11:01:31 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 7840. Sep 03 11:01:31 volumio systemd[1]: Started dynamicswap service. Sep 03 11:01:31 volumio systemd[1]: Stopped Volumio Backend Module. Sep 03 11:01:31 volumio systemd[1]: Started Volumio Backend Module. Sep 03 11:01:31 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 03 11:01:32 volumio volumio[19166]: info: ------------------------------------------- Sep 03 11:01:32 volumio volumio[19166]: info: ----- Volumio3 ---- Sep 03 11:01:32 volumio volumio[19166]: info: ------------------------------------------- Sep 03 11:01:32 volumio volumio[19166]: info: ----- System startup ---- Sep 03 11:01:32 volumio volumio[19166]: info: ------------------------------------------- Sep 03 11:01:33 volumio volumio[19166]: info: MYVOLUMIO Environment detected Sep 03 11:01:33 volumio volumio[19166]: info: Plugin folders cleanup Sep 03 11:01:33 volumio volumio[19166]: info: Scanning into folder /volumio/app/plugins/ Sep 03 11:01:33 volumio volumio[19166]: info: Scanning category audio_interface Sep 03 11:01:33 volumio volumio[19166]: info: Scanning category miscellanea Sep 03 11:01:33 volumio volumio[19166]: info: Scanning category music_service Sep 03 11:01:33 volumio volumio[19166]: info: Scanning category plugins.json Sep 03 11:01:33 volumio volumio[19166]: info: Scanning category system_controller Sep 03 11:01:33 volumio volumio[19166]: info: Scanning category user_interface Sep 03 11:01:33 volumio volumio[19166]: info: Scanning into folder /data/plugins/ Sep 03 11:01:33 volumio volumio[19166]: info: Scanning category audio_interface Sep 03 11:01:33 volumio volumio[19166]: info: Scanning category music_service Sep 03 11:01:33 volumio volumio[19166]: info: Scanning category user_interface Sep 03 11:01:33 volumio volumio[19166]: info: Plugin folders cleanup completed Sep 03 11:01:33 volumio volumio[19166]: info: ------------------------------------------- Sep 03 11:01:33 volumio volumio[19166]: info: ----- Core plugins startup ---- Sep 03 11:01:33 volumio volumio[19166]: info: ------------------------------------------- Sep 03 11:01:33 volumio volumio[19166]: info: Loading plugins from folder /volumio/app/plugins/ Sep 03 11:01:33 volumio volumio[19166]: info: Adding plugin upnp to MyMusic Plugins Sep 03 11:01:33 volumio volumio[19166]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 03 11:01:33 volumio volumio[19166]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 03 11:01:33 volumio volumio[19166]: info: Loading plugins from folder /data/plugins/ Sep 03 11:01:33 volumio volumio[19166]: info: Loading plugin "system"... Sep 03 11:01:33 volumio volumio[19166]: info: Loading plugin "appearance"... Sep 03 11:01:34 volumio volumio[19166]: info: Loading plugin "network"... Sep 03 11:01:34 volumio volumio[19166]: info: Refreshing Cached IP Addresses Sep 03 11:01:34 volumio sudo[19193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 03 11:01:34 volumio sudo[19193]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:01:34 volumio sudo[19193]: pam_unix(sudo:session): session closed for user root Sep 03 11:01:34 volumio volumio[19166]: info: Loading plugin "services"... Sep 03 11:01:34 volumio sudo[19195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 11:01:34 volumio sudo[19195]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:01:34 volumio volumio[19166]: info: Loading plugin "alsa_controller"... Sep 03 11:01:34 volumio sudo[19195]: pam_unix(sudo:session): session closed for user root Sep 03 11:01:35 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 11:01:35 volumio volumio[19166]: info: Loading plugin "wizard"... Sep 03 11:01:35 volumio volumio[19166]: info: Loading plugin "networkfs"... Sep 03 11:01:35 volumio volumio[19166]: info: Starting Udev Watcher for removable devices Sep 03 11:01:35 volumio volumio[19166]: info: Ignoring mount for partition: boot Sep 03 11:01:35 volumio volumio[19166]: info: Ignoring mount for partition: volumio Sep 03 11:01:35 volumio volumio[19166]: info: Ignoring mount for partition: volumio_data Sep 03 11:01:35 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 11:01:35 volumio volumio[19166]: info: Loading plugin "volumio_command_line_client"... Sep 03 11:01:35 volumio volumio[19166]: info: Loading plugin "upnp"... Sep 03 11:01:35 volumio volumio[19166]: info: [1756890095089] Starting Upmpd Daemon Sep 03 11:01:35 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 11:01:35 volumio volumio[19166]: info: Loading plugin "my_music"... Sep 03 11:01:35 volumio volumio[19166]: info: Loading plugin "mpd"... Sep 03 11:01:35 volumio volumio[19166]: info: Plugin upnp_browser is not enabled Sep 03 11:01:35 volumio volumio[19166]: info: Loading plugin "alarm-clock"... Sep 03 11:01:35 volumio volumio[19166]: info: Plugin airplay_emulation is not enabled Sep 03 11:01:35 volumio volumio[19166]: info: Loading plugin "last_100"... Sep 03 11:01:35 volumio volumio[19166]: info: Loading plugin "webradio"... Sep 03 11:01:35 volumio volumio[19166]: info: Loading plugin "i2s_dacs"... Sep 03 11:01:35 volumio volumio[19166]: info: Loading plugin "volumiodiscovery"... Sep 03 11:01:35 volumio volumio[19166]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 03 11:01:35 volumio volumio[19166]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 11:01:35 volumio volumio[19166]: *** WARNING *** For more information see Sep 03 11:01:35 volumio node[19166]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 03 11:01:35 volumio volumio[19166]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 03 11:01:35 volumio volumio[19166]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 11:01:35 volumio volumio[19166]: *** WARNING *** For more information see Sep 03 11:01:35 volumio node[19166]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 11:01:35 volumio node[19166]: *** WARNING *** For more information see Sep 03 11:01:35 volumio node[19166]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 03 11:01:35 volumio node[19166]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 11:01:35 volumio node[19166]: *** WARNING *** For more information see Sep 03 11:01:35 volumio volumio[19166]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 03 11:01:35 volumio volumio[19166]: info: Discovery: Started advertising with name: Volumio Sep 03 11:01:35 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 11:01:35 volumio volumio[19166]: info: Loading plugin "jellyfin"... Sep 03 11:01:36 volumio volumio-remote-updater[504]: [2025-09-03 11:01:36] [connect] Successful connection Sep 03 11:01:36 volumio volumio[19166]: info: Loading plugin "spop"... Sep 03 11:01:37 volumio volumio[19166]: info: Loading plugin "ytcr"... Sep 03 11:01:42 volumio volumio[19166]: info: Loading plugin "outputs"... Sep 03 11:01:42 volumio volumio[19166]: info: Loading plugin "albumart"... Sep 03 11:01:42 volumio volumio[19166]: info: Plugin example_plugin is not enabled Sep 03 11:01:42 volumio volumio[19166]: info: Loading plugin "inputs"... Sep 03 11:01:42 volumio volumio[19166]: info: Loading plugin "updater_comm"... Sep 03 11:01:42 volumio volumio[19166]: info: Plugin mpdemulation is not enabled Sep 03 11:01:42 volumio volumio[19166]: info: Loading plugin "rest_api"... Sep 03 11:01:42 volumio volumio[19166]: info: Loading plugin "websocket"... Sep 03 11:01:42 volumio volumio[19166]: info: Starting Socket.io Server version 2.3.0 Sep 03 11:01:42 volumio volumio[19166]: info: Loading plugin "fusiondsp"... Sep 03 11:01:42 volumio volumio[19166]: Forking 3 albumart workers Sep 03 11:01:42 volumio volumio[19166]: info: Applying required configuration parameters for plugin fusiondsp Sep 03 11:01:42 volumio volumio[19166]: info: Loading plugin "Systeminfo"... Sep 03 11:01:43 volumio volumio[19166]: info: Loading i18n strings for locale hu Sep 03 11:01:43 volumio volumio[19166]: Updating browse sources language Sep 03 11:01:43 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 11:01:43 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 03 11:01:43 volumio volumio[19166]: info: CoreCommandRouter::initPlayerControls Sep 03 11:01:43 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:43 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:43 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:43 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:43 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:43 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:43 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:43 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:43 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 03 11:01:43 volumio volumio[19166]: Express server listening on port 3000 Sep 03 11:01:43 volumio volumio[19166]: [Metrics] WebUI: 11s 70.01ms Sep 03 11:01:43 volumio volumio[19166]: info: CoreStateMachine::resetVolumioState Sep 03 11:01:43 volumio volumio[19166]: info: CoreStateMachine::getcurrentVolume Sep 03 11:01:43 volumio volumio[19166]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 11:01:43 volumio volumio[19166]: Starting albumart workers Sep 03 11:01:43 volumio volumio[19166]: info: Volumio Network Manager: Network status updated: 1 Sep 03 11:01:43 volumio volumio[19166]: Starting albumart workers Sep 03 11:01:43 volumio volumio[19166]: Starting albumart workers Sep 03 11:01:43 volumio volumio[19166]: info: CoreCommandRouter::volumioGetState Sep 03 11:01:43 volumio volumio[19166]: info: CorePlayQueue::getTrack 0 Sep 03 11:01:43 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 11:01:43 volumio volumio[19166]: info: VolumeController:: Volume=37 Mute =false Sep 03 11:01:43 volumio volumio[19166]: info: CoreStateMachine::pushState Sep 03 11:01:43 volumio volumio[19166]: info: CorePlayQueue::getTrack 0 Sep 03 11:01:43 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 11:01:43 volumio volumio[19166]: info: CoreCommandRouter::volumioPushState Sep 03 11:01:43 volumio volumio[19166]: info: CoreStateMachine::updateTrackBlock Sep 03 11:01:43 volumio volumio[19166]: info: CorePlayQueue::getTrackBlock Sep 03 11:01:43 volumio volumio[19166]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 11:01:43 volumio volumio[19166]: info: Setting Device type: Raspberry PI Sep 03 11:01:43 volumio volumio[19166]: info: CoreStateMachine::setRepeat false single undefined Sep 03 11:01:43 volumio volumio[19166]: info: CoreStateMachine::pushState Sep 03 11:01:43 volumio volumio[19166]: info: CorePlayQueue::getTrack 0 Sep 03 11:01:43 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 11:01:43 volumio volumio[19166]: info: CoreCommandRouter::volumioPushState Sep 03 11:01:43 volumio volumio[19166]: info: CoreStateMachine::setRandom true Sep 03 11:01:43 volumio volumio[19166]: info: CoreStateMachine::pushState Sep 03 11:01:43 volumio volumio[19166]: info: CorePlayQueue::getTrack 0 Sep 03 11:01:43 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 11:01:44 volumio volumio[19166]: info: CoreCommandRouter::volumioPushState Sep 03 11:01:44 volumio volumio[19166]: info: Listing playlists Sep 03 11:01:44 volumio volumio[19166]: info: Listing playlists Sep 03 11:01:44 volumio volumio[19166]: info: Discovery: adding efb65fd7-7648-487c-b3ba-ebfb5cc10227 Sep 03 11:01:44 volumio volumio[19166]: info: Discovery: Found device Volumio Sep 03 11:01:44 volumio volumio[19166]: info: CoreCommandRouter::volumioGetState Sep 03 11:01:44 volumio volumio[19166]: info: CorePlayQueue::getTrack 0 Sep 03 11:01:44 volumio volumio[19166]: info: Completed loading Core Plugins Sep 03 11:01:44 volumio volumio[19166]: info: Preparing to generate the ALSA configuration file Sep 03 11:01:44 volumio volumio[19166]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 03 11:01:44 volumio volumio[19166]: info: Reading ALSA contributions from plugins. Sep 03 11:01:44 volumio volumio[19166]: info: VolumeController:: Volume=37 Mute =false Sep 03 11:01:44 volumio volumio[19166]: info: CoreStateMachine::pushState Sep 03 11:01:44 volumio volumio[19166]: info: CorePlayQueue::getTrack 0 Sep 03 11:01:44 volumio volumio[19166]: info: CoreCommandRouter::volumioPushState Sep 03 11:01:44 volumio volumio[19166]: info: Asound.conf file unchanged, so no further update is needed Sep 03 11:01:44 volumio volumio[19166]: info: Output device has changed, restarting MPD Sep 03 11:01:44 volumio volumio[19166]: info: ___________ START PLUGINS ___________ Sep 03 11:01:44 volumio sudo[19307]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 11:01:44 volumio sudo[19307]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:01:44 volumio volumio[19166]: info: ControllerMpd::onStart: Initializing MPD Sep 03 11:01:44 volumio sudo[19307]: pam_unix(sudo:session): session closed for user root Sep 03 11:01:44 volumio volumio[19166]: info: Creating MPD Configuration file Sep 03 11:01:44 volumio sudo[19309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 11:01:44 volumio sudo[19309]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:01:44 volumio sudo[19317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 11:01:44 volumio sudo[19317]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:01:44 volumio systemd[1]: Stopping Music Player Daemon... Sep 03 11:01:44 volumio sudo[19317]: pam_unix(sudo:session): session closed for user root Sep 03 11:01:44 volumio volumio[19166]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 11:01:44 volumio systemd[1]: mpd.service: Succeeded. Sep 03 11:01:44 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 11:01:44 volumio volumio[19166]: info: [1756890104448] CoreMusicLibrary::Adding element Last_100 Sep 03 11:01:44 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 11:01:44 volumio volumio[19166]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 11:01:44 volumio volumio[19166]: info: [1756890104467] CoreMusicLibrary::Adding element Webradio Sep 03 11:01:44 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 11:01:44 volumio sudo[19328]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 11:01:44 volumio sudo[19328]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:01:44 volumio systemd[1]: Starting Music Player Daemon... Sep 03 11:01:44 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 11:01:44 volumio volumio[19166]: info: Initializing BBC Radios Sep 03 11:01:44 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 03 11:01:44 volumio systemd[1]: mpd.service: Succeeded. Sep 03 11:01:44 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 11:01:44 volumio systemd[1]: Starting Music Player Daemon... Sep 03 11:01:44 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 11:01:44 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 11:01:44 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 11:01:44 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 03 11:01:44 volumio volumio[19166]: info: Discovery: Getting this device information Sep 03 11:01:44 volumio volumio[19166]: info: CoreCommandRouter::volumioGetState Sep 03 11:01:44 volumio volumio[19166]: info: CorePlayQueue::getTrack 0 Sep 03 11:01:44 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 11:01:44 volumio volumio[19166]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 11:01:44 volumio volumio[19166]: info: [1756890104609] CoreMusicLibrary::Adding element Jellyfin Sep 03 11:01:44 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 11:01:44 volumio volumio[19166]: Cannot find translation for source Jellyfin Sep 03 11:01:44 volumio volumio[19166]: 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 11:01:44 volumio volumio[19166]: info: Creating Spotify config file Sep 03 11:01:44 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:44 volumio sudo[19337]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 03 11:01:44 volumio sudo[19337]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:01:44 volumio sudo[19337]: pam_unix(sudo:session): session closed for user root Sep 03 11:01:44 volumio volumio[19166]: info: Loading i18n strings for locale hu Sep 03 11:01:44 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 03 11:01:44 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:44 volumio volumio[19166]: info: FusionDsp - mixtype--------------------- Hardware Sep 03 11:01:44 volumio volumio[19166]: info: Volumio Calling Home Sep 03 11:01:44 volumio volumio[19166]: info: Preparing to generate the ALSA configuration file Sep 03 11:01:45 volumio volumio[19166]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 03 11:01:45 volumio volumio[19166]: info: Reading ALSA contributions from plugins. Sep 03 11:01:45 volumio volumio[19166]: info: MPD Permissions set Sep 03 11:01:45 volumio volumio[19166]: info: MPD Permissions set Sep 03 11:01:45 volumio volumio[19166]: info: Spotify config file written Sep 03 11:01:45 volumio sudo[19366]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 03 11:01:45 volumio sudo[19366]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:01:45 volumio volumio[19166]: 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 11:01:45 volumio volumio[19166]: info: Volumio called home Sep 03 11:01:45 volumio systemd[1]: Stopping go-librespot Daemon... Sep 03 11:01:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 03 11:01:45 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 03 11:01:45 volumio systemd[1]: Stopped go-librespot Daemon. Sep 03 11:01:45 volumio sudo[19370]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 03 11:01:45 volumio sudo[19370]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:01:45 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:45 volumio systemd[1]: Started go-librespot Daemon. Sep 03 11:01:45 volumio sudo[19370]: pam_unix(sudo:session): session closed for user root Sep 03 11:01:45 volumio go-librespot[19369]: go-librespot daemon starting... Sep 03 11:01:45 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:45 volumio sudo[19373]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 11:01:45 volumio sudo[19373]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:01:45 volumio sudo[19366]: pam_unix(sudo:session): session closed for user root Sep 03 11:01:45 volumio sudo[19373]: pam_unix(sudo:session): session closed for user root Sep 03 11:01:45 volumio go-librespot[19369]: time="2025-09-03T11:01:45+02:00" level=info msg="running go-librespot 0.3.2" Sep 03 11:01:45 volumio go-librespot[19369]: time="2025-09-03T11:01:45+02:00" level=debug msg="app state loaded" Sep 03 11:01:45 volumio go-librespot[19369]: time="2025-09-03T11:01:45+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 03 11:01:45 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 11:01:45 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:45 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:45 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:45 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:45 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:45 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:45 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:45 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:45 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:45 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 11:01:45 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:45 volumio volumio[19166]: info: No need to fix Spotify hosts Sep 03 11:01:45 volumio go-librespot[19369]: time="2025-09-03T11:01:45+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Sep 03 11:01:45 volumio go-librespot[19369]: time="2025-09-03T11:01:45+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 03 11:01:45 volumio go-librespot[19369]: time="2025-09-03T11:01:45+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 03 11:01:45 volumio go-librespot[19369]: time="2025-09-03T11:01:45+02:00" level=info msg="zeroconf server listening on port 33023" Sep 03 11:01:45 volumio go-librespot[19369]: time="2025-09-03T11:01:45+02:00" level=debug msg="obtained new client token: AAC5+oIF0fLAL57vaz8qUlzoKutH8dB81YdPI789bHZVwiYJ532nnUYuryz0BSJA7nVINVVJu/kxcaXWVCZIBsqhlgZDA55ZkfsY2XWJ+EvU2KFLV2X71fqOZ1ERRK59eZPWGrh2NBQvTPspOvrRHOurJdYC24u5CPvX+YZjN91QBHDMA4R234+Rvs2YOtsu2YHCEjjlCOeruRg71f/Yn+V2bQTJNUQGKKpySHWvlHML/fSFKMd12uKq3wI=" Sep 03 11:01:45 volumio go-librespot[19369]: time="2025-09-03T11:01:45+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Sep 03 11:01:45 volumio go-librespot[19369]: time="2025-09-03T11:01:45+02:00" level=debug msg="completed keyexchange" Sep 03 11:01:45 volumio go-librespot[19369]: time="2025-09-03T11:01:45+02:00" level=debug msg="completed challenge" Sep 03 11:01:45 volumio volumio[19166]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 03 11:01:45 volumio volumio[19166]: SPOTIFY: BQBw3MmpNq-Tq5OivHMAHo92wedCh1NdQP3R77cLRMtdw0dbrxcIXBQePfR76U7vug_NDWbC1qFU8DsOD6Qwg1Sc3AvKVFHQ4DcMVKrkWxHjJWTi9NZLvTUFtWY1M2gewoZGpSi-4vaU9IYrGq0iwELNfX6Vhu3PnayUSF3c6vgPcJr3jH3RlOf7tH1D44qeWtWzBMA45YXFGMW0hnHl6AX7-f0JoMHSr1DrsUZB7v8waRokcYtTTTGpTkQeM1IykhpCFHVv3sQRXMlI_rbaKDcKoF12x8WomF-uYnZ5P8cSCUY Sep 03 11:01:45 volumio volumio[19166]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 03 11:01:45 volumio volumio[19166]: info: New Spotify access token = BQBw3MmpNq-Tq5OivHMAHo92wedCh1NdQP3R77cLRMtdw0dbrxcIXBQePfR76U7vug_NDWbC1qFU8DsOD6Qwg1Sc3AvKVFHQ4DcMVKrkWxHjJWTi9NZLvTUFtWY1M2gewoZGpSi-4vaU9IYrGq0iwELNfX6Vhu3PnayUSF3c6vgPcJr3jH3RlOf7tH1D44qeWtWzBMA45YXFGMW0hnHl6AX7-f0JoMHSr1DrsUZB7v8waRokcYtTTTGpTkQeM1IykhpCFHVv3sQRXMlI_rbaKDcKoF12x8WomF-uYnZ5P8cSCUY Sep 03 11:01:45 volumio volumio[19166]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 03 11:01:45 volumio go-librespot[19369]: time="2025-09-03T11:01:45+02:00" level=info msg="authenticated AP" username="lo*********************vv" Sep 03 11:01:45 volumio volumio[19166]: info: CoreCommandRouter::volumioGetState Sep 03 11:01:45 volumio volumio[19166]: info: CorePlayQueue::getTrack 0 Sep 03 11:01:45 volumio volumio[19166]: info: Asound.conf file unchanged, so no further update is needed Sep 03 11:01:45 volumio volumio[19166]: info: Output device has changed, restarting MPD Sep 03 11:01:45 volumio go-librespot[19369]: time="2025-09-03T11:01:45+02:00" level=info msg="authenticated Login5" username="lo*********************vv" Sep 03 11:01:45 volumio go-librespot[19369]: time="2025-09-03T11:01:45+02:00" level=debug msg="initializing zeroconf session" username="lo*********************vv" Sep 03 11:01:45 volumio sudo[19391]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 11:01:45 volumio sudo[19391]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:01:45 volumio sudo[19391]: pam_unix(sudo:session): session closed for user root Sep 03 11:01:45 volumio sudo[19393]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 11:01:45 volumio sudo[19393]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:01:45 volumio go-librespot[19369]: time="2025-09-03T11:01:45+02:00" level=debug msg="dealer connection opened" Sep 03 11:01:45 volumio go-librespot[19369]: time="2025-09-03T11:01:45+02:00" level=trace msg="starting accesspoint recv loop" Sep 03 11:01:45 volumio go-librespot[19369]: time="2025-09-03T11:01:45+02:00" level=trace msg="starting dealer recv loop" Sep 03 11:01:45 volumio go-librespot[19369]: time="2025-09-03T11:01:45+02:00" level=trace msg="received accesspoint ping" Sep 03 11:01:45 volumio go-librespot[19369]: time="2025-09-03T11:01:45+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 03 11:01:45 volumio go-librespot[19369]: time="2025-09-03T11:01:45+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 03 11:01:45 volumio go-librespot[19369]: time="2025-09-03T11:01:45+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 03 11:01:45 volumio go-librespot[19369]: time="2025-09-03T11:01:45+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 03 11:01:45 volumio go-librespot[19369]: time="2025-09-03T11:01:45+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 03 11:01:45 volumio go-librespot[19369]: time="2025-09-03T11:01:45+02:00" level=debug msg="received connection id: YTI5NDI1ZGYtOGNi...QzU3N0RDMEFERg==" Sep 03 11:01:45 volumio go-librespot[19369]: time="2025-09-03T11:01:45+02:00" level=trace msg="received accesspoint pong ack" Sep 03 11:01:45 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 03 11:01:45 volumio systemd[1]: mpd.service: Succeeded. Sep 03 11:01:45 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 11:01:46 volumio sudo[19397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 03 11:01:46 volumio sudo[19397]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:01:46 volumio systemd[1]: Starting Music Player Daemon... Sep 03 11:01:46 volumio volumio[19166]: info: MPD Permissions set Sep 03 11:01:46 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:46 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:46 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:46 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:46 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:46 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:46 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:46 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:46 volumio sudo[19397]: pam_unix(sudo:session): session closed for user root Sep 03 11:01:46 volumio go-librespot[19369]: time="2025-09-03T11:01:46+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 03 11:01:46 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 11:01:46 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:46 volumio volumio[19166]: info: Upmpdcli Daemon Started Sep 03 11:01:46 volumio volumio[19166]: 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 11:01:46 volumio volumio[19166]: info: Spotify Successfully logged in Sep 03 11:01:46 volumio volumio[19166]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 11:01:46 volumio volumio[19166]: info: [1756890106171] CoreMusicLibrary::Adding element Spotify Sep 03 11:01:46 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 11:01:46 volumio volumio[19166]: Cannot find translation for source Jellyfin Sep 03 11:01:46 volumio volumio[19166]: Cannot find translation for source Spotify Sep 03 11:01:46 volumio sudo[19398]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 03 11:01:46 volumio sudo[19398]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:01:46 volumio sudo[19398]: pam_unix(sudo:session): session closed for user root Sep 03 11:01:46 volumio volumio[19166]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 03 11:01:46 volumio volumio[19166]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 11:01:46 volumio volumio[19166]: info: VolumeController:: Volume=37 Mute =false Sep 03 11:01:46 volumio volumio[19166]: info: CoreCommandRouter::volumioGetState Sep 03 11:01:46 volumio volumio[19166]: info: CorePlayQueue::getTrack 0 Sep 03 11:01:46 volumio volumio[19166]: info: CoreStateMachine::pushState Sep 03 11:01:46 volumio volumio[19166]: info: CorePlayQueue::getTrack 0 Sep 03 11:01:46 volumio volumio[19166]: info: CoreCommandRouter::volumioPushState Sep 03 11:01:46 volumio volumio[19166]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 03 11:01:46 volumio volumio[19166]: 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 11:01:46 volumio volumio[19166]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 03 11:01:46 volumio volumio[19166]: info: camilladsp service started and running in background, instance 1 Sep 03 11:01:46 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 11:01:46 volumio volumio[19166]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 03 11:01:46 volumio volumio[19166]: 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 11:01:46 volumio volumio[19166]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 03 11:01:46 volumio volumio[19166]: info: FusionDsp loaded Sep 03 11:01:46 volumio volumio[19166]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 11:01:46 volumio sudo[19419]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 03 11:01:46 volumio sudo[19419]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:01:46 volumio sudo[19419]: pam_unix(sudo:session): session closed for user root Sep 03 11:01:46 volumio volumio[19166]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 03 11:01:46 volumio volumio[19166]: info: Adding Signal Path Element [object Object] Sep 03 11:01:46 volumio volumio[19166]: info: Adding fusiondspeq DSP Signal Path Element Sep 03 11:01:46 volumio volumio[19166]: info: FusionDsp - ---- installed callbackRead Sep 03 11:01:47 volumio volumio[19166]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 11:01:47 volumio volumio[19166]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 03 11:01:47 volumio volumio[19166]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 03 11:01:47 volumio volumio[19166]: at onErrorNT (internal/child_process.js:465:16) Sep 03 11:01:47 volumio volumio[19166]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Sep 03 11:01:47 volumio volumio[19166]: errno: -2, Sep 03 11:01:47 volumio volumio[19166]: code: 'ENOENT', Sep 03 11:01:47 volumio volumio[19166]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 03 11:01:47 volumio volumio[19166]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 03 11:01:47 volumio volumio[19166]: spawnargs: [ Sep 03 11:01:47 volumio volumio[19166]: '-p', Sep 03 11:01:47 volumio volumio[19166]: 9876, Sep 03 11:01:47 volumio volumio[19166]: '-o', Sep 03 11:01:47 volumio volumio[19166]: '/tmp/camilladsp.log', Sep 03 11:01:47 volumio volumio[19166]: '-l', Sep 03 11:01:47 volumio volumio[19166]: 'warn', Sep 03 11:01:47 volumio volumio[19166]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 03 11:01:47 volumio volumio[19166]: ] Sep 03 11:01:47 volumio volumio[19166]: } Sep 03 11:01:47 volumio volumio[19166]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 11:01:47 volumio sudo[19430]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-03 11:00 Sep 03 11:01:47 volumio sudo[19430]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 11:01:47 volumio mpd[19406]: Sep 03 11:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 03 11:01:47 volumio systemd[1]: Started Music Player Daemon. Sep 03 11:01:47 volumio sudo[19393]: pam_unix(sudo:session): session closed for user root Sep 03 11:01:47 volumio sudo[19309]: pam_unix(sudo:session): session closed for user root Sep 03 11:01:47 volumio sudo[19328]: pam_unix(sudo:session): session closed for user root PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"